JVM之GC回收信息詳解

1、-XX:+PrintGCDetails 打印GC日誌

參數配置：-Xms10M -Xmx10M -XX:+PrintGCDetails -XX:+PrintGCDateStamps -XX:+PrintGCTimeStamps  -Xloggc:F:\gc1.log

-Xloggc：輸出到文件

2020-01-03T14:13:18.866+0800: 0.224: [GC (Allocation Failure) [PSYoungGen: 2048K->496K(2560K)] 2048K->744K(9728K), 0.0128127 secs] [Times: user=0.00 sys=0.00, real=0.01 secs] 
2020-01-03T14:13:18.881+0800: 0.229: [GC (System.gc()) [PSYoungGen: 565K->496K(2560K)] 813K->752K(9728K), 0.0009625 secs] [Times: user=0.02 sys=0.00, real=0.00 secs] 
2020-01-03T14:13:18.882+0800: 0.230: [Full GC (System.gc()) [PSYoungGen: 496K->0K(2560K)] [ParOldGen: 256K->675K(7168K)] 752K->675K(9728K), [Metaspace: 3460K->3460K(1056768K)], 0.0053436 secs] [Times: user=0.00 sys=0.00, real=0.01 secs] 
2020-01-03T14:13:19.889+0800: 1.236: [GC (System.gc()) [PSYoungGen: 117K->128K(2560K)] 793K->803K(9728K), 0.0006856 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 
2020-01-03T14:13:19.889+0800: 1.237: [Full GC (System.gc()) [PSYoungGen: 128K->0K(2560K)] [ParOldGen: 675K->696K(7168K)] 803K->696K(9728K), [Metaspace: 3475K->3475K(1056768K)], 0.0057842 secs] [Times: user=0.00 sys=0.00, real=0.01 secs] 
Heap
 PSYoungGen      total 2560K, used 59K [0x00000000ffd00000, 0x0000000100000000, 0x0000000100000000)
  eden space 2048K, 2% used [0x00000000ffd00000,0x00000000ffd0ecc0,0x00000000fff00000)
  from space 512K, 0% used [0x00000000fff00000,0x00000000fff00000,0x00000000fff80000)
  to   space 512K, 0% used [0x00000000fff80000,0x00000000fff80000,0x0000000100000000)
 ParOldGen       total 7168K, used 696K [0x00000000ff600000, 0x00000000ffd00000, 0x00000000ffd00000)
  object space 7168K, 9% used [0x00000000ff600000,0x00000000ff6ae138,0x00000000ffd00000)
 Metaspace       used 3481K, capacity 4500K, committed 4864K, reserved 1056768K
  class space    used 381K, capacity 388K, committed 512K, reserved 1048576K

第一行：2020-01-03T14:13:18.866+0800: 0.224: [GC (Allocation Failure) [PSYoungGen: 2048K->496K(2560K)] 2048K->744K(9728K), 0.0128127 secs] [Times: user=0.00 sys=0.00, real=0.01 secs]

GC (Allocation Failure)：GC表示YoungGC年輕代垃圾回收；(Allocation Failure)表示內存分配失敗；

[PSYoungGen: 2048K->496K(2560K)]：PSYoungGen表示年輕代使用的垃圾回收器是Paralle Scavenge  ；2048K表示年輕代垃圾回收前內存佔用大小；496K表示年輕代垃圾回收後內存佔用大小；(2560K)表示年輕代總大小；

2048K->744K(9728K)：2048K表示堆內存垃圾回收前內存佔用大小；744K表示堆內存垃圾回收後內存佔用大小；(9728K)表示堆內存總大小；

0.0128127 secs：表示YoungGC耗時，單位秒

[Times: user=0.00 sys=0.00, real=0.01 secs]：user=0.00表示用戶耗時，sys=0.00表示系統耗時，real=0.01表示真實耗時，secs表示時間單位秒
第二行：2020-01-03T14:13:18.881+0800: 0.229: [GC (System.gc()) [PSYoungGen: 565K->496K(2560K)] 813K->752K(9728K), 0.0009625 secs] [Times: user=0.02 sys=0.00, real=0.00 secs] 
 [GC (System.gc())：表示系統顯示調用gc
第三行：2020-01-03T14:13:18.882+0800: 0.230: [Full GC (System.gc()) [PSYoungGen: 496K->0K(2560K)] [ParOldGen: 256K->675K(7168K)] 752K->675K(9728K), [Metaspace: 3460K->3460K(1056768K)], 0.0053436 secs]
 [Full GC (System.gc())：Full GC表示YoungGC+OldGC（年輕代垃圾回收+年老代垃圾回收）即整個JVM內存的回收（除了方法區或者元空間）；System.gc()表示系統顯示調用GC

[PSYoungGen: 496K->0K(2560K)]：PSYoungGen表示年輕代使用的垃圾回收器是Paralle Scavenge  ；496K表示Full GC垃圾回收前內存佔用大小；0K表示Full GC垃圾回收後內存佔用大小（0是由於年輕代使用的是複製算法）；(2560K)表示年輕代總大小；

752K->675K(9728K)：752K表示Full GC垃圾回收前堆內存佔用大小；675K表示Full GC垃圾回收後堆內存佔用大小；(9728K)表示堆內存總大小；

ParOldGen: 256K->675K(7168K)：ParOldGen表示老年代使用的垃圾回收器Parallel Old，256K表示Full GC垃圾回收前內存佔用大小；675K表示Full GC垃圾回收後內存佔用大小；(7168K)表示老年代總大小；

[Metaspace: 3460K->3460K(1056768K)]：Metaspace表示元空間本文使用JDK1.8，3460K：表示Full GC垃圾回收前Metaspace佔用大小，3460K表示Full GC垃圾回收後Metaspace佔用大小；(1056768K)表示Metaspace總大小。

Full GC分析下能夠得出結論：
    新生代變化：496k-0L=496K 新生代減小了496k

    老年代變化：256k-675k=-419k（老年代增長了419k）
    Heap區變化：752k-675k=77K 整個堆減小了 77k
    496k – 77K =419K，說明該次共有419k內存從年輕代移到了老年代，能夠看出來數量很多，說明大部分對象的生命週期較長。因爲Full GC會引起stop  the word ，因此咱們要儘可能避免Full GC的發生，讓對象儘量的在老年代多呆會，因此這裏能夠增長老年輕代的大小。

第五行：

Heap
 PSYoungGen      total 2560K, used 59K [0x00000000ffd00000, 0x0000000100000000, 0x0000000100000000) eden space 2048K, 2% used [0x00000000ffd00000,0x00000000ffd0ecc0,0x00000000fff00000) from space 512K, 0% used [0x00000000fff00000,0x00000000fff00000,0x00000000fff80000) to space 512K, 0% used [0x00000000fff80000,0x00000000fff80000,0x0000000100000000) ParOldGen total 7168K, used 696K [0x00000000ff600000, 0x00000000ffd00000, 0x00000000ffd00000) object space 7168K, 9% used [0x00000000ff600000,0x00000000ff6ae138,0x00000000ffd00000) Metaspace used 3481K, capacity 4500K, committed 4864K, reserved 1056768K class space used 381K, capacity 388K, committed 512K, reserved 1048576K

PSYoungGen total 2560K, used 59K：total 年輕代佔用的總內存大小2560K，used：表示當前使用量
eden space 2048K, 2% used：年輕代的eden區佔用的總內存大小2048K，used：已經使用2%
from space 512K, 0% used：年輕代的 from s1區佔用的總內存大小512K，used：已經使用0%
to space 512K, 0% used：年輕代的 from s2區佔用的總內存大小512K，used：已經使用0%
ParOldGen total 7168K：老年代的總內存大小
object space 7168K, 9% used：老年已使用9%

 -XX:NewRatio=2：年輕代與老年代的比值1:2， 可是根據實際數據顯然年輕代沒有佔到整個堆的三分之一，非常費解？不知這種偏差是否合理，在這裏不吝賜教，求大神指點迷津。