【JVM】【日志】【解读GC日志】

Parallel Scavenge 和Parallel Old 收集器日志

第一次GC

2017-03-21T22:44:13.115+0800: 2.192:
[GC
[PSYoungGen: 98304K->16367K(114688K)]
98304K->27259K(125632K), 0.0327436 secs
] [Times: user=0.08 sys=0.00, real=0.03 secs]

第二次GC

2017-03-21T22:44:13.148+0800: 2.225:
[Full GC
[PSYoungGen: 16367K->16074K(114688K)]
[ParOldGen: 10892K->10940K(31744K)]
27259K->27015K(146432K)
[PSPermGen: 12639K->12635K(262144K)],
0.1702624 secs]
[Times: user=0.44 sys=0.00, real=0.17 secs]

1. GC和FullGC是垃圾收集的停顿类型。而不是用来区分新生代GC还是老年代GC。如果有“Full”说明这次GC发生了stop-the-world的。新生代回收和老年代回收都有会这种情况；
2. [PSYoungGen 表示GC发生的具体区域。这里显示的区域名称和使用的GC收集器密切相关。例如：Serial收集器新生代名称为"DefaultNew Generation"；对于收集器ParNew显示的名称 就是“[parNew”;采用ParallelScanvenge收集器，新生代显示的就是"[PSYounGen"；有时[GC (Allocation Failure)后面会带括号，意思是GC 发生的原因，这里意思是“分配内存失败”而引起的
3. 方括号内部的 98304K->16367K(114688K) 含义是“GC前该内存区域已使用量(该内存区域总容量) -->GC后该内 存区域使用的量”
4. 括号外面的98304K->27259K(125632K)表示“GC前java堆已使用多少”–>GC后java已经使用多少(java堆总容量)；
5. 0.0327436 secs 表示该内存区域GC占用时间，单位是秒；
6. 最后就是用户态、系统态在这此回收占用时间；
7. 延伸分析：16367K->16074K(114688K) 即96MB->16MB(112MB)新生代减少了80MB，98304K->27259K(125632K)即（96MB->27MB(123MB)）减少了69MB，发现新生代回收之后减少了80MB，但是总的堆只是减少了69MB，说明有80-69=11MB数据对象进入了老年代；