快捷導(dǎo)航

JVM的GC日志及運行參數(shù)解讀

更新時間：2023年09月03日 14:21:57 作者：jacheut

這篇文章主要為大家介紹了JVM的GC日志及運行參數(shù)解讀,有需要的朋友可以借鑒參考下,希望能夠有所幫助,祝大家多多進步,早日升職加薪

正文

{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參數(shù)為：-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：系統(tǒng)運行142176.656秒的時候發(fā)生。

GC：表明進行一次垃圾回收，前面沒有Full修飾，表明這是一次Minor GC。注意它不是表示只GC新生代。

(Allocation Failure)：表示本次引起GC的原因是因為在年輕代中沒有足夠的空間能夠存儲新的數(shù)據(jù)了。

ParNew：表明本次GC發(fā)生在年輕代并且使用的是ParNew垃圾收集器。ParNew是一個Serial收集器的多線程版本，會使用多個CPU和線程完成垃圾收集工作（默認(rèn)使用的線程數(shù)和CPU數(shù)相同，可以使用-XX: ParallelGCThreads參數(shù)限制）。該收集器采用復(fù)制算法回收內(nèi)存，期間會停止其他工作線程，即Stop The World。

287809K->13868K(290176K)：單位是KB
三個參數(shù)分別為：GC前該內(nèi)存區(qū)域（這里是年輕代）使用容量，GC后該內(nèi)存區(qū)域使用容量，該內(nèi)存區(qū)域的總?cè)萘浚‥den區(qū) + 1個Survivor區(qū)）。

0.0142053 secs：該內(nèi)存區(qū)域GC耗時，單位是秒。

447069K->173146K(699776K)：三個參數(shù)分別為：堆區(qū)垃圾回收前的大小，堆區(qū)垃圾回收后的大小，堆區(qū)總大小。

0.0144550 secs：該內(nèi)存區(qū)域GC耗時，單位是秒。

[Times: user=0.01 sys=0.00, real=0.01 secs]：分別表示用戶態(tài)耗時，內(nèi)核態(tài)耗時和總耗時。

其他日志解讀

如果設(shè)置了 PrintHeapAtGC 參數(shù)，則 HotSpot 在 GC 前后都會將 GC 堆的概要信息輸出出來。
Heap before GC 和 Heap after GC 分別表示 GC 前后堆的信息的開始，invocations 表示 GC 的次數(shù)，可以看到后面跟了個 invocations，這里 invocations 表示總的 GC 次數(shù)，可以發(fā)現(xiàn)在 after 之后，invocations 自增了，而 full 表示第幾次 Full GC。
invocations 會隨著系統(tǒng)運行一直自增下去，通過這些信息可以很輕松的統(tǒng)計出一段時間的 GC 次數(shù)。

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 區(qū)已經(jīng)滿了，from survivor 用了 86%，to survivor 用了 0%，每個后面都跟了內(nèi)存地址，頭一個表示起始地址，第二個表示當(dāng)前用到的最大地址，第三個表示終止地址。
觀察 before 和 after，細(xì)心點可以觀察到 from 和 to 的地址對調(diào)了。

緊跟著 par new generation 后面的是 concurrent mark-sweep generation，總共的量409600K，使用的量159277K，地址可以清楚的看到，后面跟著的三個參數(shù)同樣是起止地址，而第二個和第三個是相同的。
此外還給出了 Metaspace 的使用情況，以及 class space 的使用情況。這兩個值初始會比較小，在使用過程中會容量會逐步擴大。