关于jvm:JVM的GC日志解读

39次阅读

共计 3950 个字符，预计需要花费 10 分钟才能阅读完成。

{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
}

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

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 的应用状况。这两个值初始会比拟小，在应用过程中会容量会逐渐扩充。