如何阅读GC日志

一名合格的java程序员应具备基本的使用JVM能力,了解其启动时的配置参数,并且能够理解GC的输出日志。

阅读GC日志是处理Java虚拟机内存问题的基础技能,它只是一些认为确定的规则,没有太多技术含量。
每一种收集器的日志形式都是由它们自身的实现而决定的,换而言之,每个收集器的日志格式都可以不一样。下面为两段典型的GC日志:

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)

[Full GC 283.776: [ParNew: 261559K->261559K(261559K),0.0000288 secs]

接下来的[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.02,real=0.02 secs],这里面的user,sys和real与Linux的time命令所输出的时间含义一致,分别代表用户态消耗的CPU时间,内核消耗的CPU时间和操作从开始到结束的墙钟时间(Wall Clock Time)。CPU时间与墙钟时间的区别是,墙钟时间包括各种非运算的等待耗时,例如等待磁盘I/O,等待线程阻塞,而CPU时间不包括这些耗时,但当系统有多CPU或者多核的话,多线程操作会叠加这些CPU时间,所以读者看到user或sys时间超过real时间是完全正常的。

最后编辑于
©著作权归作者所有,转载或内容合作请联系作者
【社区内容提示】社区部分内容疑似由AI辅助生成,浏览时请结合常识与多方信息审慎甄别。
平台声明:文章内容(如有图片或视频亦包括在内)由作者上传并发布,文章内容仅代表作者本人观点,简书系信息发布平台,仅提供信息存储服务。

相关阅读更多精彩内容

  • 原文阅读 前言 这段时间懈怠了,罪过! 最近看到有同事也开始用上了微信公众号写博客了,挺好的~给他们点赞,这博客我...
    码农戏码阅读 11,238评论 2 31
  • 参数设置 在Java虚拟机的参数中,有3种表示方法用“ps -ef |grep "java"命令,可以得到当前Ja...
    九问阅读 12,999评论 2 52
  • http://www.cnblogs.com/angeldevil/p/3801189.html值得一看 Clas...
    snail_knight阅读 5,416评论 1 0
  • 作者:一字马胡 转载标志 【2017-11-12】 更新日志 日期更新内容备注 2017-11-12新建文章初版 ...
    beneke阅读 6,700评论 0 7
  • 这篇文章是我之前翻阅了不少的书籍以及从网络上收集的一些资料的整理,因此不免有一些不准确的地方,同时不同JDK版本的...
    高广超阅读 15,973评论 3 83

友情链接更多精彩内容