无可避免地,我们都需要用到多线程的一天。单纯地使用多线程的参数设置,比如-Xms、-Xmx、-Xss等,还不足够,我们还要学会如何分析JVM里面的线程状况。
在进行java程序问题定位时,内存问题定位是很关键的,jvm自带的命令可以方便的在生产监控和打印堆栈的日志信息帮忙我们来定位问题!虽然jvm调优成熟的工具已经有很多:jconsole、大名鼎鼎的VisualVM,IBM的Memory Analyzer等等,但是在生产环境出现问题的时候,工具的使用会有所限制。所有的工具几乎都是依赖于jdk的接口和底层的这些命令,研究和掌握这些命令的使用也让我们更能了解jvm构成和特性。
Sun JDK监控JVM命令有:
jps JVM Process Status Tool,显示指定系统内所有的HotSpot虚拟机进程;
jstat 提供Java垃圾回收以及类加载信息;
故障排除命令有
jcmd 打印一个Java进程的类、线程以及虚拟机信息
jinfo 访问JVM系统属性,同事可以动态修改这些属性
jhat 帮助分析内存堆存储
jmap 提供JVM内存使用信息
jstack Java堆栈跟踪工具
JVM Process Status Tool,显示指定系统内所有的HotSpot虚拟机进程。
jps [options] [hostid]
-l:输出主类全名或jar路径
-q:抑制类名的输出,JAR文件名和传递给main方法的参数,仅生成本地JVM标识符列表
-m:输出JVM启动时传递给main()的参数
-v:输出JVM启动时显示指定的JVM参数
$ jps -l -m
28920 org.apache.catalina.startup.Bootstrap start
11589 org.apache.catalina.startup.Bootstrap start
25816 sun.tools.jps.Jps -l -m
jstat(JVM statistics Monitoring)是用于监视虚拟机运行时状态信息的命令,它可以显示出虚拟机进程中的类装载、内存、垃圾收集、JIT编译等运行数据。
jstat [option] LVMID [interval] [count]
[option]:操作参数
LVMID:本地虚拟机进程ID
[interval]:连续输出的时间间隔
[count]:连续输出的次数
option参数总览:
option
描述
class
class loader的行为统计
compiler
HotSpot JIT编译器行为统计
gc
垃圾回收堆的行为统计
gccapacity
各个垃圾回收代容量(young,old,perm)和他们相应的空间统计
gcnew
新生代行为统计
gcnewcapacity
新生代与其相应的内存空间的统计
gcold
年老代和永生代行为统计
gcoldcapacity
年老代行为统计
gcpermcapacity
永生代行为统计
printcompilation
HotSpot编译方法统计
监视类装载、卸载数量、总空间以及耗费的时间
[root@cdh1 ~]# jstat -class 348534
Loaded Bytes Unloaded Bytes Time
9099 18284.4 32 46.4 13.85
Loaded:加载class的数量
Bytes:class字节大小
Unloaded:未加载class的数量
Bytes:未加载class的字节大小
Time:加载时间
输出JIT编译过的方法数量耗时等
[root@cdh2 ~]# jstat -compiler 348534
Compiled Failed Invalid Time FailedType FailedMethod
23470 1 0 234.19 1 sun/misc/URLClassPath$JarLoader getResource
Compiled:编译数量
Failed:编译失败数量
Invalid:无效数量
Time:编译耗时
FailedType:失败类型
FailedMethod:失败方法的全限定名
垃圾回收堆的行为统计,常用命令
[root@cdh2 ~]# jstat -gc 348534
S0C S1C S0U S1U EC EU OC OU MC MU CCSC CCSU YGC YGCT FGC FGCT GCT
4096.0 4096.0 3616.1 0.0 3486720.0 2576053.9 6990848.0 6040397.9 62848.0 61320.9 6784.0 6381.8 16264 962.293 3 6.332 968.625
C即Capacity总容量,U即Used已使用的容量
S0C:survivor0区的总容量
S1C:survivor1区的总容量
S0U:survivor0区已使用的容量
S1U:survivor1区已使用的容量
EC:Eden区的总容量
EU:Eden区已使用的容量
OC:Old区的总容量
OU:Old区已使用的容量
MC:方法区的总容量
MU: 方法区已使用的容量
CCSC:压缩类的总容量
CCSU:压缩类已使用的容量
YGC:新生代垃圾回收次数
YGCT:新生代垃圾回收时间
FGC:老年代垃圾回收次数
FGCT:老年代垃圾回收时间
GCT:垃圾回收总消耗时间
注:之前的PC和PU被MC、MU取代了(永久代)
[root@cdh2 ~]# jstat -gc 348534 2000 10
这个命令意思就是每隔2000ms输出348534的gc情况,一共输出10次
同-gc,不过还会输出Java堆各区域使用到的最大、最小空间
[root@hscluster2 yangjianqiu]# jstat -gccapacity 348534
NGCMN NGCMX NGC S0C S1C EC OGCMN OGCMX OGC OC MCMN MCMX MC CCSMN CCSMX CCSC YGC FGC
3494912.0 3494912.0 3494912.0 4608.0 4608.0 3485696.0 6990848.0 6990848.0 6990848.0 6990848.0 0.0 1105920.0 62848.0 0.0 1048576.0 6784.0 16277 3
NGCMN:新生代占用的最小容量
NGCMX:新生代占用的最大容量
NGC:当前新生代容量
S0C:surivivor0区的容量
S1C:surivivor1区的容量
EC:伊甸园区的大小
OGCMN:老年代占用的最小容量
OGCMX:老年代占用的最大容量
OGC:当前老年代的容量(KB)
OC:当前老年代的空间(KB)
MCMN:最小元数据容量
MCMX:最大元数据容量
MC:当前元数据空间大小
CCSMN:最小压缩类空间大小
CCSMX:最大压缩类空间大小
CCSC:当前压缩类空间大小
YGC:年轻代gc次数
FGC:老年代GC次数
同-gc,不过输出的是已使用空间占总空间的百分比
[root@cdh2 ~]# jstat -gcutil 348534
S0 S1 E O M CCS YGC YGCT FGC FGCT GCT
0.00 83.13 39.56 87.70 97.59 94.07 16737 996.073 3 6.332 1002.406
S0:surivivor0区
S1:surivivor1区
E:伊甸园区
O:老年代
M:元数据
CCS:压缩类
YGC:年轻代gc次数
YGCT:新生代垃圾回收时间
FGC:老年代GC次数
FGCT:老年代垃圾回收时间
GCT:垃圾回收总消耗时间
垃圾收集统计概述(同-gcutil),附加最近两次垃圾回收事件的原因。
[root@cdh2 ~]# jstat -gccause 348534
S0 S1 E O M CCS YGC YGCT FGC FGCT GCT LGCC GCC
0.00 60.07 67.49 87.74 97.59 94.07 16753 997.315 3 6.332 1003.647 Allocation Failure No GC
LGCC:最近垃圾回收的原因
GCC:当前垃圾回收的原因
统计新生代的行为
[root@cdh2 ~]# jstat -gcnew 348534
S0C S1C S0U S1U TT MTT DSS EC EU YGC YGCT
5120.0 5120.0 3872.1 0.0 15 15 5120.0 3484672.0 2025220.0 16828 1003.157
TT:Tenuring threshold(提升阀值)
MTT:最大的tenuring threshold
DSS:survivor区域大小(KB)
新生代与其相应的内存空间的统计
[root@cdh2 ~]# jstat -gcnewcapacity 348534
NGCMN NGCMX NGC S0CMX S0C S1CMX S1C ECMX EC YGC FGC
3494912.0 3494912.0 3494912.0 1164800.0 5120.0 1164800.0 5120.0 3493888.0 3484672.0 16837 3
NGC:当前年轻代的容量(KB)
S0CMX:最大的S0空间(KB)
S0C:当前S0空间(KB)
ECMX:最大eden空间(KB)
统计老年代的行为
[root@cdh2 ~]# jstat -gcold 348534
MC MU CCSC CCSU OC OU YGC FGC FGCT GCT
62848.0 61330.8 6784.0 6381.8 6990848.0 6169097.4 16842 3 6.332 1010.295
统计老年代的大小和空间
[root@cdh2 ~]# jstat -gcoldcapacity 348534
OGCMN OGCMX OGC OC YGC FGC FGCT GCT
6990848.0 6990848.0 6990848.0 6990848.0 16844 3 6.332 1010.395
元数据的大小和空间
[root@cdh2 ~]# jstat -gcmetacapacity 348534
MCMN MCMX MC CCSMN CCSMX CCSC YGC FGC FGCT GCT
0.0 1105920.0 62848.0 0.0 1048576.0 6784.0 16846 3 6.332 1010.522
hotspot编译方法统计
[root@cdh2 ~]# jstat -printcompilation 348534
Compiled Size Type Method
23495 366 1 org/apache/hadoop/hdfs/server/namenode/FSImageFormatProtobuf$Saver commitSection
Compiled:被执行的编译任务的数量
Size:方法字节码的字节数
Type:编译类型
Method:编译方法的类名和方法名。类名使用“/”代替“,”作为空间分隔符.方法名是给出类的方法名.格式是一致于HotSpot -XX:+PrintComplation选项
由于调优参数非常繁多,需要借助JVM命令行和JVM调优参数来使用。使用command_line命令可以获得命令行中指定的调优参数,flags命令可以获得通过命令设置的调优参数和JVM设置的调优参数。
通过jcmd命令可以获得一个运行中JVM内生效的调优参数。jcmd是JDK1.7之后出现的,jcmd的常见用法:
jcmd process_id [option]
option
描述
-l
列出所有JVM虚拟机
help
帮助
VM.uptime
查看虚拟机启动时间VM.uptime
Thread.print
打印线程栈信息
GC.class_histogram
查看系统中类统计信息
GC.heap_dump
导出堆栈信息
VM.system_properties
获取系统Properties内容
VM.flags
获取启动参数
PerfCounter.print
获取所有性能相关数据
VM.native_memory
查看原生内存信息 summary/detail/baseline/summary.diff/detail.diff/shutdown
jinfo(JVM Configuration info)这个命令作用是实时查看和调整虚拟机运行参数。jps -v命令只能查看到显示指定的参数,如果想要查看未被显示指定的参数的值就要使用jinfo命令。
jinfo [option] [args] LVMID
-flag:输出指定args参数的值
-flags:不需要args参数,输出所有JVM参数的值
-sysprops:输出系统属性,等同于System.getProperties()
[root@cdh2 ~]# jinfo -flags 21341
Attaching to process ID 21341, please wait…
Debugger attached successfully.
Server compiler detected.
JVM version is 25.112-b15
Non-default VM flags: -XX:CICompilerCount=15 -XX:InitialHeapSize=10737418240 -XX:MaxHeapSize=10737418240 -XX:MaxNewSize=4294967296 -XX:MinHeapDeltaBytes=524288 -XX:NewSize=4294967296 -XX:OldSize=6442450944 -XX:+UseCompressedClassPointers -XX:+UseCompressedOops -XX:+UseFastUnorderedTimeStamps -XX:+UseParallelGC
Command line: -Dproc_rangeradmin -XX:MaxPermSize=256m -Xmx10240m -Xms10240m -Xmn4096m -Duser.timezone=UTC -Duser=root -Dhostname=cdh2 -Dservername=rangeradmin -Dlogdir=/home/hypergalaxy/rangeradmin/ews/logs -Dcatalina.base=/home/hypergalaxy/rangeradmin/ews
jmap(JVM Memory Map)命令用于生成heap dump文件,如果不使用这个命令,还可以使用-XX:+HeapDumpOnOutOfMemoryError参数来让虚拟机出现OOM的时候,自动生成dump文件。jmap不仅能生成dump文件,还可以查询finalize执行队列、Java堆和永久代的详细信息,如当前使用率、当前使用的是哪种收集器等。
jmap [option] LVMID
dump:生成堆转储快照
finalizerinfo:显示在F-Queue队列等待Finalizer线程执行finalizer方法的对象
heap:显示Java堆详细信息
histo:显示堆中对象的统计信息
permstat:to print permanent generation statistic
F:当-dump没有响应时,强制生成dump快照
-dump
常用格式
-dump::live,format=b,file=
dump堆到文件,format指定输出格式,live指明是活着的对象,file指定文件名
$ jmap -dump:live, format=b, file=dump.hprof 28920
Dumping heap to /home/xxx/dump.hprof …
Heap dump file created
dump.hprof这个后缀是为了后续可以直接用MAT(Memory Anlysis Tool)打开。
-finalizerinfo
打印等待回收对象的信息
$ jmap -finalizerinfo 24314
Attaching to process ID 28920, please wait…
Debugger attached successfully.
Server compiler detected.
JVM version is 24.71-b01
Number of objects pending for finalization: 0
可以看到当前F-QUEUE队列中并没有等待Finalizer线程执行finalizer方法的对象。
-heap
打印heap的概要信息,GC使用的算法,heap的配置以及wist heap的使用情况,可以用此来判断内存目前的使用情况以及垃圾回收情况
$ jmap -heap 28920
Attaching to process ID 28920, please wait…
Debugger attached successfully.
Server compiler detected.
JVM version is 24.71-b01
using thread-local object allocation.
Parallel GC with 4 thread(s)//GC 方式
Heap Configuration: //堆内存初始化配置
MinHeapFreeRatio = 0 //对应jvm启动参数-XX:MinHeapFreeRatio设置JVM堆最小空闲比率(default 40)
MaxHeapFreeRatio = 100 //对应jvm启动参数 -XX:MaxHeapFreeRatio设置JVM堆最大空闲比率(default 70)
MaxHeapSize = 2082471936 (1986.0MB) //对应jvm启动参数-XX:MaxHeapSize=设置JVM堆的最大大小
NewSize = 1310720 (1.25MB)//对应jvm启动参数-XX:NewSize=设置JVM堆的‘新生代’的默认大小
MaxNewSize = 17592186044415 MB//对应jvm启动参数-XX:MaxNewSize=设置JVM堆的‘新生代’的最大大小
OldSize = 5439488 (5.1875MB)//对应jvm启动参数-XX:OldSize=
NewRatio = 2 //对应jvm启动参数-XX:NewRatio=:‘新生代’和‘老生代’的大小比率
SurvivorRatio = 8 //对应jvm启动参数-XX:SurvivorRatio=设置年轻代中Eden区与Survivor区的大小比值
PermSize = 21757952 (20.75MB) //对应jvm启动参数-XX:PermSize=
MaxPermSize = 85983232 (82.0MB)//对应jvm启动参数-XX:MaxPermSize=
G1HeapRegionSize = 0 (0.0MB)
Heap Usage://堆内存使用情况
PS Young Generation
Eden Space://Eden区内存分布
capacity = 33030144 (31.5MB)//Eden区总容量
used = 1524040 (1.4534378051757812MB) //Eden区已使用
free = 31506104 (30.04656219482422MB) //Eden区剩余容量
4.614088270399305% used //Eden区使用比率
From Space: //其中一个Survivor区的内存分布
capacity = 5242880 (5.0MB)
used = 0 (0.0MB)
free = 5242880 (5.0MB)
0.0% used
To Space: //另一个Survivor区的内存分布
capacity = 5242880 (5.0MB)
used = 0 (0.0MB)
free = 5242880 (5.0MB)
0.0% used
PS Old Generation //当前的Old区内存分布
capacity = 86507520 (82.5MB)
used = 0 (0.0MB)
free = 86507520 (82.5MB)
0.0% used
PS Perm Generation//当前的 “永生代” 内存分布
capacity = 22020096 (21.0MB)
used = 2496528 (2.3808746337890625MB)
free = 19523568 (18.619125366210938MB)
11.337498256138392% used
670 interned Strings occupying 43720 bytes.
可以很清楚的看到Java堆中各个区域目前的情况
-histo
打印堆的对象统计,包括对象数、内存大小等等(因为在dump:live前会进行full gc,如果带上live则只统计活对象,因此不加live的堆大小要大于加live堆的大小)
$ jmap -histo:live 28920 | more
1: 83613 12012248
2: 23868 11450280 [B
3: 83613 10716064
4: 76287 10412128 [C
5: 8227 9021176
6: 8227 5830256
7: 7031 5156480
8: 73627 1767048 java.lang.String
9: 2260 1348848
10: 8856 849296 java.lang.Class
….
仅仅打印了前10行
xml class name是对象类型,说明如下:
B byte
C char
D double
F float
I int
J long
Z boolean
[ 数组,如[I表示int[]
[L+类名 其他对象
-permstat
打印java堆内存的永久保存区域的类加载器的智能统计信息。对于每个类加载器而言,它的名称、活跃度、地址、父类加载器、它所加载的类的数量和大小都会被打印。此外,包含的字符串数量和大小也会被打印。
$ jmap -permstat 28920
Attaching to process ID 28920, please wait…
Debugger attached successfully.
Server compiler detected.
JVM version is 24.71-b01
finding class loader instances ..done.
computing per loader stat ..done.
please wait.. computing liveness.liveness analysis may be inaccurate …
class_loader classes bytes parent_loader alive? type
0x0000000600905cf8 1 1888 0x0000000600087f08 dead sun/reflect/DelegatingClassLoader@0x00000007800500a0
0x00000006008fcb48 1 1888 0x0000000600087f08 dead sun/reflect/DelegatingClassLoader@0x00000007800500a0
0x00000006016db798 0 0 0x00000006008d3fc0 dead java/util/ResourceBundle$RBClassLoader@0x0000000780626ec0
0x00000006008d6810 1 3056 null dead sun/reflect/DelegatingClassLoader@0x00000007800500a0
-F
强制模式。如果指定的pid没有响应,请使用jmap -dump或jmap -histo选项。此模式下,不支持live子选项。
jhat(JVM Heap Analysis Tool)命令是与jmap搭配使用,用来分析jmap生成的dump,jhat内置了一个微型的HTTP/HTML服务器,生成dump的分析结果后,可以在浏览器中查看。在此要注意,一般不会直接在服务器上进行分析,因为jhat是一个耗时并且耗费硬件资源的过程,一般把服务器生成的dump文件复制到本地或其他机器上进行分析。
jhat [dumpfile]
-stack false | true:关闭对象分配调用栈跟踪(tracking object allocation call stack)。如果分配位置信息在堆栈转储中不可用。则必须将此标志设置为false,默认值为true;
-refs false | true:关闭对象引用跟踪(tracking of references to objects)。默认值为true。默认情况下,返回的指针是指向其他特定对象的对象,如反向链接或输入引用(referrers or incoming references),会统计/计算堆中的所有对象。
-port port-number:设置jhat HTTP server的端口号,默认值 7000;
-exclude exclude-file 指定对象查询时需要排除的数据成员列表文件。例如,如果文件列列出了java.lang.String.value,那么当从某个特定对象Object o 计算可达的对象列表时,引用路径涉及java.lang.String.value的都会被排除。
-baseline exclude-file:指定一个基准堆转储(baseline heap dump)。在两个heap dumps中有相同object ID的对象会被标记为不是新的.其他对象被标记为新的,在比较两个不同的堆转储时很有用。
-debug int:设置debug级别。0表示不输出调试信息。值越大则表示输出更详细的debug信息。
-version:启动后只显示版本信息就退出
-J
$ jhat -J-Xmx512m dump.hprof
eading from dump.hprof…
Dump file created Fri Mar 11 17:13:42 CST 2016
Snapshot read, resolving…
Resolving 271678 objects…
Chasing references, expect 54 dots………………………………………………
Eliminating duplicate references………………………………………………
Snapshot resolved.
Started HTTP server on port 7000
Server is ready.
中间的-J-Xmx512m是在dump快照很大的情况下分配512M内存去启动HTTP服务器,运行完之后就可在浏览器打开http://localhost:7000进行快照分析。堆快照分析主要在最后面的Heap Histogram里,里面根据class列出了dump的时候所有存活对象。
分析同样一个dump快照,MAT需要的额外内存比jhat要小的多的多,所以建议使用MAT来进行分析,当然也看个人偏好。
打开浏览器http://localhost:7000,该页面提供了几个查询功能可供使用:
All classes including platform
Show all members of the rootset
Show instance counts for all classes (including platform)
Show instance counts for all classes (excluding platform)
Show heap histogram
Show finalizer summary
Execute Object Query Language (OQL) query
一般查看堆异常情况主要看你这两个部分:Show instance counts for all classes (excluding platform),平台外的所有对象信息。
Show heap histogram 以树状图形式展示堆情况。
具体排查时需要结合代码,观察是否大量应该被回收的对象在一直被引用或者是否有占有内存特别大的对象无法被回收。一般情况,会down到客户端用工具来分析。
jstack用于生成java虚拟机当前时刻的线程快照。线程快照是当前java虚拟机内每一条线程正在执行的方法堆栈的集合,生成线程快照的主要目的是定位线程出现长时间停顿的原因,如线程间死锁、死循环、请求外部资源导致的长时间等待等。线程出现停顿的时候通过jstack来查看各个线程的调用堆栈,就可以知道没有响应的线程到底在后台做什么事情,或者等待什么资源。如果java程序崩溃生成core文件,jstack工具可以用来获得core文件的java stack和native stack的信息,从而可以轻松地知道java程序是如何崩溃和在程序何处发生问题。另外,jstack工具还可以附属到正在运行的java程序中,看到当时运行的java程序的java stack和native stack的信息,如果现在运行的java程序呈现hung的状态,jstack是非常有用的。
jstack [option] LVMID
-F:当正常输出请求不被响应时,强制输出线程堆栈
-l:除堆栈外,显示关于锁的附加信息
-m:如果调用到本地方法的话,可以显示C/C++的堆栈
$ jstack -l 11494|more
2016-07-28 13:40:04
Full thread dump Java HotSpot(TM) 64-Bit Server VM (24.71-b01 mixed mode):
"Attach Listener" daemon prio=10 tid=0x00007febb0002000 nid=0x6b6f waiting on condition [0x0000000000000000]
java.lang.Thread.State: RUNNABLE
Locked ownable synchronizers:
- None
"http-bio-8005-exec-2" daemon prio=10 tid=0x00007feb94028000 nid=0x7b8c waiting on condition [0x00007fea8f56e000]
java.lang.Thread.State: WAITING (parking)
at sun.misc.Unsafe.park(Native Method)
- parking to wait for <0x00000000cae09b80> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.tomcat.util.threads.TaskQueue.take(TaskQueue.java:104)
at org.apache.tomcat.util.threads.TaskQueue.take(TaskQueue.java:32)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
at java.lang.Thread.run(Thread.java:745)
Locked ownable synchronizers:
- None
…..
参考资料:
https://blog.csdn.net/u012104691/article/details/70136234
https://blog.csdn.net/wangxiaotongfan/article/details/82560739
手机扫一扫
移动阅读更方便
你可能感兴趣的文章