java

关注公众号 jb51net

关闭
首页 > 软件编程 > java > JVM GC日志解读

JVM的GC日志及运行参数解读

作者：jacheut

这篇文章主要为大家介绍了JVM的GC日志及运行参数解读,有需要的朋友可以借鉴参考下,希望能够有所帮助,祝大家多多进步,早日升职加薪

正文 

{Heap before GC invocations=35866 (full 34):
 par new generation   total 290176K, used 287809K [0x00000000d4400000, 0x00000000e7000000, 0x00000000e7000000)
  eden space 273152K, 100% used [0x00000000d4400000, 0x00000000e4ec0000, 0x00000000e4ec0000)
  from space 17024K,  86% used [0x00000000e4ec0000, 0x00000000e5d10770, 0x00000000e5f60000)
  to   space 17024K,   0% used [0x00000000e5f60000, 0x00000000e5f60000, 0x00000000e7000000)
 concurrent mark-sweep generation total 409600K, used 159259K [0x00000000e7000000, 0x0000000100000000, 0x0000000100000000)
 Metaspace       used 137146K, capacity 146452K, committed 147072K, reserved 378880K
  class space    used 15581K, capacity 17238K, committed 17536K, reserved 247808K
2023-09-02T01:18:40.811+0000: 142176.656: [GC (Allocation Failure) 2023-09-02T01:18:40.811+0000: 142176.657: [ParNew: 287809K->13868K(290176K), 0.0142053 secs] 447069K->173146K(699776K), 0.0144550 secs] [Times: user=0.01 sys=0.00, real=0.01 secs]
Heap after GC invocations=35867 (full 34):
 par new generation   total 290176K, used 13868K [0x00000000d4400000, 0x00000000e7000000, 0x00000000e7000000)
  eden space 273152K,   0% used [0x00000000d4400000, 0x00000000d4400000, 0x00000000e4ec0000)
  from space 17024K,  81% used [0x00000000e5f60000, 0x00000000e6ceb260, 0x00000000e7000000)
  to   space 17024K,   0% used [0x00000000e4ec0000, 0x00000000e4ec0000, 0x00000000e5f60000)
 concurrent mark-sweep generation total 409600K, used 159277K [0x00000000e7000000, 0x0000000100000000, 0x0000000100000000)
 Metaspace       used 137146K, capacity 146452K, committed 147072K, reserved 378880K
  class space    used 15581K, capacity 17238K, committed 17536K, reserved 247808K
}

 一段GC日志

 JVM参数为：-Xms700m -Xmx700m -Xmn300m -XX:MetaspaceSize=250m -XX:MaxMetaspaceSize=250m -XX:SurvivorRatio=16

如上是一段GC日志，先来解读这一行：

2023-09-02T01:18:40.811+0000: 142176.656: [GC (Allocation Failure) 2023-09-02T01:18:40.811+0000: 142176.657: [ParNew: 287809K->13868K(290176K), 0.0142053 secs] 447069K->173146K(699776K), 0.0144550 secs] [Times: user=0.01 sys=0.00, real=0.01 secs]

2023-09-02T01:18:40.811+0000：表示的是一个日期格式

142176.656：系统运行142176.656秒的时候发生。

GC：表明进行一次垃圾回收，前面没有Full修饰，表明这是一次Minor GC。注意它不是表示只GC新生代。

(Allocation Failure)：表示本次引起GC的原因是因为在年轻代中没有足够的空间能够存储新的数据了。

ParNew：表明本次GC发生在年轻代并且使用的是ParNew垃圾收集器。ParNew是一个Serial收集器的多线程版本，会使用多个CPU和线程完成垃圾收集工作（默认使用的线程数和CPU数相同，可以使用-XX: ParallelGCThreads参数限制）。该收集器采用复制算法回收内存，期间会停止其他工作线程，即Stop The World。

287809K->13868K(290176K)：单位是KB
三个参数分别为：GC前该内存区域（这里是年轻代）使用容量，GC后该内存区域使用容量，该内存区域的总容量（Eden区 + 1个Survivor区）。

0.0142053 secs：该内存区域GC耗时，单位是秒。

447069K->173146K(699776K)：三个参数分别为：堆区垃圾回收前的大小，堆区垃圾回收后的大小，堆区总大小。

0.0144550 secs：该内存区域GC耗时，单位是秒。

[Times: user=0.01 sys=0.00, real=0.01 secs]：分别表示用户态耗时，内核态耗时和总耗时。

其他日志解读

如果设置了 PrintHeapAtGC 参数，则 HotSpot 在 GC 前后都会将 GC 堆的概要信息输出出来。
Heap before GC 和 Heap after GC 分别表示 GC 前后堆的信息的开始，invocations 表示 GC 的次数，可以看到 后面跟了个 invocations，这里 invocations 表示总的 GC 次数，可以发现在 after 之后，invocations 自增了，而 full 表示第几次 Full GC。
invocations 会随着系统运行一直自增下去，通过这些信息可以很轻松的统计出一段时间的 GC 次数。

Heap before GC invocations=35866 (full 34):
 par new generation   total 290176K, used 287809K [0x00000000d4400000, 0x00000000e7000000, 0x00000000e7000000)
  eden space 273152K, 100% used [0x00000000d4400000, 0x00000000e4ec0000, 0x00000000e4ec0000)
  from space 17024K,  86% used [0x00000000e4ec0000, 0x00000000e5d10770, 0x00000000e5f60000)
  to   space 17024K,   0% used [0x00000000e5f60000, 0x00000000e5f60000, 0x00000000e7000000)
Heap after GC invocations=35867 (full 34):
par new generation   total 290176K, used 13868K [0x00000000d4400000, 0x00000000e7000000, 0x00000000e7000000)
  eden space 273152K,   0% used [0x00000000d4400000, 0x00000000d4400000, 0x00000000e4ec0000)
  from space 17024K,  81% used [0x00000000e5f60000, 0x00000000e6ceb260, 0x00000000e7000000)
  to   space 17024K,   0% used [0x00000000e4ec0000, 0x00000000e4ec0000, 0x00000000e5f60000)

再看下面的日志，可以看到年轻代和老年代所使用的垃圾回收器，以及各自的情况。

新生代 par new generation 表示使用 ParNew 作为垃圾回收器，一共 290176 K 大小，使用了 287809 K 大小。其中 eden 区已经满了，from survivor 用了 86%，to survivor 用了 0%，每个后面都跟了内存地址，头一个表示起始地址，第二个表示当前用到的最大地址，第三个表示终止地址。
观察 before 和 after，细心点可以观察到 from 和 to 的地址对调了。

紧跟着 par new generation 后面的是 concurrent mark-sweep generation，总共的量409600K，使用的量159277K，地址可以清楚的看到，后面跟着的三个参数同样是起止地址，而第二个和第三个是相同的。
此外还给出了 Metaspace 的使用情况，以及 class space 的使用情况。这两个值初始会比较小，在使用过程中会容量会逐步扩大。

以上就是JVM的GC日志及运行参数解读的详细内容，更多关于JVM GC日志解读的资料请关注脚本之家其它相关文章！

您可能感兴趣的文章:
阅读全文