GC日志含义

最前面的数字“33.125：”和“100.667：”代表 了GC发生的时间，这个数字的含义是从Java虚拟 机启动以来经过的秒数。 GC日志开头的“[GC”和“[Full GC”说明了这 次垃圾收集的停顿类型，而不是用来区分新生代 GC还是老年代GC的。如果有“Full”，说明这次 GC是发生了Stop-The-World的，例如下面这段新 生代收集器ParNew的日志也会出现“[Full GC”（这一般是因为出现了分配担保失败之类的 问题，所以才导致STW）。如果是调用 System.gc（）方法所触发的收集，那么在这里将 显示“[Full GC（System）”。

接下来的“[DefNew”、“[Tenured”、“[Perm”表 示GC发生的区域，这里显示的区域名称与使用的 GC收集器是密切相关的，例如上面样例所使用的 Serial收集器中的新生代名为“Default New Generation”，所以显示的是“[DefNew”。如果是 ParNew收集器，新生代名称就会变 为“[ParNew”，意为“Parallel New Generation”。如 果采用Parallel Scavenge收集器，那它配套的新生 代称为“PSYoungGen”，老年代和永久代同理，名 称也是由收集器决定的。 后面方括号内部的“3324K-＞ 152K（3712K）”含义是“GC前该内存区域已使用 容量-＞GC后该内存区域已使用容量（该内存区 域总容量）”。而在方括号之外的“3324K-＞ 152K（11904K）”表示“GC前Java堆已使用容量- ＞GC后Java堆已使用容量（Java堆总容量）”。 再往后，“0.0025925 secs”表示该内存区域 GC所占用的时间，单位是秒。有的收集器会给出 更具体的时间数据，如“[Times：user=0.01 sys=0.00，real=0.02 secs]”，这里面的user、sys和 real与Linux的time命令所输出的时间含义一致， 分别代表用户态消耗的CPU时间、内核态消耗的 CPU事件和操作从开始到结束所经过的墙钟时间 （Wall Clock Time）。CPU时间与墙钟时间的区 别是，墙钟时间包括各种非运算的等待耗时，例 如等待磁盘I/O、等待线程阻塞，而CPU时间不包 括这些耗时，但当系统有多CPU或者多核的话， 多线程操作会叠加这些CPU时间，所以读者看到 user或sys时间超过real时间是完全正常的。

 

可以通过以下几个参数要求虚拟机生成GC日志：-XX：+PrintGCTimeStamps（打印GC停顿时间）、-XX：+PrintGCDetails（打印GC详细信息）、-verbose：gc（打印GC信息，输出内容已被前一个参数包括，可以不写）、-Xloggc：gc.log