深入理解Java虚拟机（第三版）-- 垃圾收集器日志、垃圾收集器参数

谁借莪１个温暖的怀抱￠ 2021-08-27 22:27 419阅读 0赞

#### 垃圾收集器日志 ####

阅读分析虚拟机和垃圾收集器的日志是处理Java虚拟机内存问题必备的基础技能，垃圾收集器日 志是一系列人为设定的规则，多少有点随开发者编码时的心情而定，没有任何的“业界标准”可言，换 句话说，每个收集器的日志格式都可能不一样。除此以外还有一个麻烦，`在JDK 9以前，HotSpot并没 有提供统一的日志处理框架`，虚拟机各个功能模块的日志开关分布在不同的参数上，日志级别、循环 日志大小、输出格式、重定向等设置在不同功能上都要单独解决。直到JDK 9，这种混乱不堪的局面 才终于消失，HotSpot所有功能的日志都收归到了`“-Xlog”`参数上，这个参数的能力也相应被极大拓展 了:

![在这里插入图片描述][20200404225621187.png]

命令行中最关键的参数是选择器(Selector)，它由标签(Tag)和日志级别(Level)共同组成。 标签可理解为虚拟机中某个功能模块的名字，它告诉日志框架用户希望得到虚拟机哪些功能的日志输 出。垃圾收集器的标签名称为“gc”，由此可见，垃圾收集器日志只是HotSpot众多功能日志的其中一 项，全部支持的功能模块标签名如下所示:  
![在这里插入图片描述][20200404225651939.png]

日志级别从低到高，共有Trace，Debug，Info，Warning，Error，Off六种级别，日志级别决定了输 出 信 息 的 详 细 程 度 ， 默 认 级 别 为 I n f o ， H o t Sp o t 的 日 志 规 则 与 L o g4 j 、 SL F 4 j 这 类 J a v a 日 志 框 架 大 体 上 是 一致的。另外，还可以使用修饰器(Decorator)来要求每行日志输出都附加上额外的内容，支持附加 在日志行上的信息包括:

*  time:当前日期和时间。
 *  up time:虚拟机启动到现在经过的时间，以秒为单位。 ·timemillis:当前时间的毫秒数，相当于System.currentTimeM illis()的输出。
 *  up timemillis:虚拟机启动到现在经过的毫秒数。
 *  timenanos:当前时间的纳秒数，相当于System.nanoTime()的输出。
 *  uptimenanos:虚拟机启动到现在经过的纳秒数。
 *  pid:进程ID。
 *  tid:线程ID。
 *  level:日志级别。
 *  tags : 日 志 输 出 的 标 签 集 。

如 果 不 指 定 ， 默 认 值 是 u p t i m e 、 l e v e l 、 t a gs 这 三 个 ， 此 时 日 志 输 出 类 似 于 以 下 形 式 :  
![在这里插入图片描述][20200404225934732.png]

**示例：**

下面笔者举几个例子，展示在JDK 9统一日志框架前、后是如何获得垃圾收集器过程的相关信 息，以下均以JDK 9的G1收集器(JDK 9下默认收集器就是G1，所以命令行中没有指定收集器)为例。

1.  查看GC基本信息，`在JDK 9之前使用-XX:+PrintGC`，JDK 9后使用-Xlog:gc:  
    ![在这里插入图片描述][20200404230115568.png]
2.  查看GC详细信息，`在JDK 9之前使用-XX:+PrintGCDetails`，在JDK 9之后使用-X-log:gc\*， 用通配符\*将GC标签下所有细分过程都打印出来，如果把日志级别调整到Debug或者Trace(基于版面 篇幅考虑，例子中并没有)，还将获得更多细节信息:  
    ![在这里插入图片描述][watermark_type_ZmFuZ3poZW5naGVpdGk_shadow_10_text_aHR0cHM6Ly9ibG9nLmNzZG4ubmV0L2NvbGRfX19wbGF5_size_16_color_FFFFFF_t_70]
3.  查看GC前后的堆、方法区可用容量变化，`在JDK 9之前使用-XX:+PrintHeapAtGC`，JDK 9之 后使用-Xlog:gc+heap =debug:  
    ![在这里插入图片描述][watermark_type_ZmFuZ3poZW5naGVpdGk_shadow_10_text_aHR0cHM6Ly9ibG9nLmNzZG4ubmV0L2NvbGRfX19wbGF5_size_16_color_FFFFFF_t_70 1]
4.  查看GC过程中用户线程并发时间以及停顿的时间，`在JDK 9之前使用-XX:+Print-GCApplicationConcurrentTime以及-XX:+PrintGCApplicationStoppedTime`，JDK 9之后使用-Xlog: safepoint:

![在这里插入图片描述][watermark_type_ZmFuZ3poZW5naGVpdGk_shadow_10_text_aHR0cHM6Ly9ibG9nLmNzZG4ubmV0L2NvbGRfX19wbGF5_size_16_color_FFFFFF_t_70 2]

1.  查看收集器Ergonomics机制(自动设置堆空间各分代区域大小、收集目标等内容，从Parallel收 集器开始支持)自动调节的相关信息。`在JDK 9之前使用-XX:+PrintAdaptive-SizePolicy`，JDK 9之后 使 用 - X l o g : gc + e r go \* = t r a c e :  
    ![在这里插入图片描述][watermark_type_ZmFuZ3poZW5naGVpdGk_shadow_10_text_aHR0cHM6Ly9ibG9nLmNzZG4ubmV0L2NvbGRfX19wbGF5_size_16_color_FFFFFF_t_70 3]
2.  查看熬过收集后剩余对象的年龄分布信息，在JDK 9前使用-XX:+PrintTenuring-Distribution， JDK 9之后使用-Xlog:gc+age=trace:  
    ![在这里插入图片描述][watermark_type_ZmFuZ3poZW5naGVpdGk_shadow_10_text_aHR0cHM6Ly9ibG9nLmNzZG4ubmV0L2NvbGRfX19wbGF5_size_16_color_FFFFFF_t_70 4]

![在这里插入图片描述][watermark_type_ZmFuZ3poZW5naGVpdGk_shadow_10_text_aHR0cHM6Ly9ibG9nLmNzZG4ubmV0L2NvbGRfX19wbGF5_size_16_color_FFFFFF_t_70 5]  
![在这里插入图片描述][watermark_type_ZmFuZ3poZW5naGVpdGk_shadow_10_text_aHR0cHM6Ly9ibG9nLmNzZG4ubmV0L2NvbGRfX19wbGF5_size_16_color_FFFFFF_t_70 6]

#### 垃圾收集器参数总结 ####

![在这里插入图片描述][watermark_type_ZmFuZ3poZW5naGVpdGk_shadow_10_text_aHR0cHM6Ly9ibG9nLmNzZG4ubmV0L2NvbGRfX19wbGF5_size_16_color_FFFFFF_t_70 7]  
![在这里插入图片描述][watermark_type_ZmFuZ3poZW5naGVpdGk_shadow_10_text_aHR0cHM6Ly9ibG9nLmNzZG4ubmV0L2NvbGRfX19wbGF5_size_16_color_FFFFFF_t_70 8]

[20200404225621187.png]: /images/20210813/6b885185b55f4bf5bd8e1267b2d36257.png
[20200404225651939.png]: /images/20210813/36261182186f4a4699b0711b2d638346.png
[20200404225934732.png]: /images/20210813/894d01428dc14eed842805c87835aaeb.png
[20200404230115568.png]: /images/20210813/6f8f5d6f4e2a409aac13516f1e54ebeb.png
[watermark_type_ZmFuZ3poZW5naGVpdGk_shadow_10_text_aHR0cHM6Ly9ibG9nLmNzZG4ubmV0L2NvbGRfX19wbGF5_size_16_color_FFFFFF_t_70]: /images/20210813/5c6bca5f4dcf4b1db296d6d245e0ead4.png
[watermark_type_ZmFuZ3poZW5naGVpdGk_shadow_10_text_aHR0cHM6Ly9ibG9nLmNzZG4ubmV0L2NvbGRfX19wbGF5_size_16_color_FFFFFF_t_70 1]: /images/20210813/5dc2a95283024c2c9476519178091af3.png
[watermark_type_ZmFuZ3poZW5naGVpdGk_shadow_10_text_aHR0cHM6Ly9ibG9nLmNzZG4ubmV0L2NvbGRfX19wbGF5_size_16_color_FFFFFF_t_70 2]: /images/20210813/e86de3316213482e9758ee95c3003cec.png
[watermark_type_ZmFuZ3poZW5naGVpdGk_shadow_10_text_aHR0cHM6Ly9ibG9nLmNzZG4ubmV0L2NvbGRfX19wbGF5_size_16_color_FFFFFF_t_70 3]: /images/20210813/0096cd78df794952a793a1f1b9340d3a.png
[watermark_type_ZmFuZ3poZW5naGVpdGk_shadow_10_text_aHR0cHM6Ly9ibG9nLmNzZG4ubmV0L2NvbGRfX19wbGF5_size_16_color_FFFFFF_t_70 4]: /images/20210813/9e4106edb3b8416ca671b1990c454609.png
[watermark_type_ZmFuZ3poZW5naGVpdGk_shadow_10_text_aHR0cHM6Ly9ibG9nLmNzZG4ubmV0L2NvbGRfX19wbGF5_size_16_color_FFFFFF_t_70 5]: /images/20210813/dc424fab22d74377a87256f6e4e17c2b.png
[watermark_type_ZmFuZ3poZW5naGVpdGk_shadow_10_text_aHR0cHM6Ly9ibG9nLmNzZG4ubmV0L2NvbGRfX19wbGF5_size_16_color_FFFFFF_t_70 6]: /images/20210813/02b7f1185ee0416a8190cde250530a89.png
[watermark_type_ZmFuZ3poZW5naGVpdGk_shadow_10_text_aHR0cHM6Ly9ibG9nLmNzZG4ubmV0L2NvbGRfX19wbGF5_size_16_color_FFFFFF_t_70 7]: /images/20210813/8bfbdc2f0fa444ad9bf5a1e4aecb88f0.png
[watermark_type_ZmFuZ3poZW5naGVpdGk_shadow_10_text_aHR0cHM6Ly9ibG9nLmNzZG4ubmV0L2NvbGRfX19wbGF5_size_16_color_FFFFFF_t_70 8]: /images/20210813/3c4df170b67c4b47831fcfdce72c20de.png