如何理解GC日志

最新推荐文章于 2022-11-08 22:27:57 发布

fengfengchen95

最新推荐文章于 2022-11-08 22:27:57 发布

阅读量230

点赞数

分类专栏： JAVA

13 篇文章 1 订阅

订阅专栏

理解GC日志

217.539: [GC (Allocation Failure) 217.539: [DefNew: 102646K->10770K(102976K), 0.0415902 secs] 239776K->153169K(331528K), 0.0416785 secs] [Times: user=0.03 sys=0.02, real=0.04 secs]
221.383: [Full GC (System.gc()) 221.383: [Tenured: 142398K->92063K(228552K), 0.3029342 secs] 193477K->92063K(331528K), [Metaspace: 65120K->65120K(68992K)], 0.3031417 secs] [Times: user=0.30 sys=0.00, real=0.30 secs]
281.396: [Full GC (System.gc()) 281.396: [Tenured: 92063K->93342K(228552K), 0.2394086 secs] 129487K->93342K(331528K), [Metaspace: 65137K->65137K(68992K)], 0.2395739 secs] [Times: user=0.23 sys=0.00, real=0.24 secs]

1. 最前面的数字217.539:代表GC发生的时间，从虚拟机启动开始算起

2. GC日志开头的[GC和FULL GC]说明这次垃圾收集的停顿类型，而不是区分新生代和老年代的。

FULL GC : 说明这次GC是发生了线程停顿Stop-The-World,如果是System.gc()方法所触发的收集，，那么在这里将显示[Full GC (System)

3. [DefNew,[Tenured,[Perm表示GC发生的区域，这里显示的区域名称和使用的垃圾收集器是密切相关的

1>在Serial收集器中新生代名为Default New Generation ，显示就是DefNew

2>垃圾收集器ParNew中新生代名称就会变为[Parnew,意思为Parallel New Generation

3>如果采用Parrallel Scavenge收集器，那么它配套的新生代名称为PSYongGen

老年代和永久代同理，名称由垃圾收集器决定

4.[DefNew: 102646K->10770K(102976K), 0.0415902 secs] 239776K->153169K(331528K), 0.0416785 secs] [Times: user=0.03 sys=0.02, real=0.04 secs] 就这段而言，

1>方括号内部102646K->10770K(102976K)的含义是GC前改内存区域已使用内存->GC后改内存区域使用容量(该内存区域总容量)

2>方括号外 239776K->153169K(331528K)表示GC前java堆已使用容量->GC后java堆已使用容量(java堆总容量)

3>在往后时间表示该内存区域gc清理用的时间

4>[Times: user=0.03 sys=0.02, real=0.04 secs]

这里面的user、sys和real与linux的time命令所输出的时间含义一致，分别表示用户态消耗的CPU时间、内核态消耗的CPU事件和操作从开始到结束经历的墙钟时间

墙钟时间和CPU时间的区别是，墙钟时间包括各种非运算的等待耗时(i/o,线程阻塞)，而CPU时间不包括这些，但是如果是多核，

多线程会叠加这些CPU时间，此时user或sys时间超过real时间完全是正常的

关注