理解Java虚拟机（十）虚拟机和垃圾收集器的日志

  本系列均是周志明老师《深入理解Java虚拟机》第三版的学习笔记。

  阅读分析虚拟机和垃圾收集器的日志是处理Java虚拟机内存问题必备的基础技能。

  在JDK 9以前，HotSpot并没有提供统一的日志处理框架，虚拟机各个功能模块的日志开关分布在不同的参数上，日志级别、循环 日志大小、输出格式、重定向等设置在不同功能上都要单独解决。

  直到JDK 9，这种混乱不堪的局面才终于消失，HotSpot所有功能的日志都收归到了“-Xlog”参数上，这个参数的能力也相应被极大拓展 了

一、日志级别

  日志级别从低到高，共有Trace，Debug，Info，Warning，Error，Off六种级别，日志级别决定了输 出信息的详细程度，默认级别为Info，HotSpot的日志规则与Log4j、SLF4j这类Java日志框架大体上是 一致的。

二、日志附加信息

  可以使用修饰器（Decorator）来要求每行日志输出都附加上额外的内容，支持附加在日志行上的信息包括：

• time：当前日期和时间。

• uptime：虚拟机启动到现在经过的时间，以秒为单位。

• timemillis：当前时间的毫秒数，相当于System.currentTimeMillis()的输出。

• uptimemillis：虚拟机启动到现在经过的毫秒数。

• timenanos：当前时间的纳秒数，相当于System.nanoTime()的输出。

• uptimenanos：虚拟机启动到现在经过的纳秒数。

• pid：进程ID。

• tid：线程ID。

• level：日志级别。

• tags：日志输出的标签集。

三、日志实例

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

  1、查看GC基本信息，在JDK 9之前使用-XX：+PrintGC，JDK 9后使用-Xlog：gc。

  2、查看GC详细信息，在JDK 9之前使用-XX：+PrintGCDetails，在JDK 9之后使用-X-log：gc*， 用星号通配符将GC标签下所有细分过程都打印出来，如果把日志级别调整到Debug或者Trace，还将获得更多细节信息。

  3、查看GC前后的堆、方法区可用容量变化，在JDK 9之前使用-XX：+PrintHeapAtGC，JDK 9之后使用-Xlog：gc+heap=debug。

  4、查看GC过程中用户线程并发时间以及停顿的时间，在JDK 9之前使用-XX：+Print-GCApplicationConcurrentTime以及-XX：+PrintGCApplicationStoppedTime，JDK 9之后使用-Xlog：safepoint。

  5、查看收集器Ergonomics机制（自动设置堆空间各分代区域大小、收集目标等内容，从Parallel收集器开始支持）自动调节的相关信息。在JDK 9之前使用-XX：+PrintAdaptive-SizePolicy，JDK 9之后使用-Xlog：gc+ergo*=trace。

  6、查看熬过收集后剩余对象的年龄分布信息，在JDK 9前使用-XX：+PrintTenuring-Distribution，JDK 9之后使用-Xlog：gc+age=trace。