不同的JVM及其選項會輸出不同的日志。
GC 日志
生成下面日志使用的選項: -XX:+PrintGCTimeStamps -XX:+PrintGCDetails -Xloggc:d:/GClogs/tomcat6-gc.log
。
4.231: [GC 4.231: [DefNew: 4928K->512K(4928K), 0.0044047 secs] 6835K->3468K(15872K), 0.0045291 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
4.445: [Full GC (System) 4.445: [Tenured: 2956K->3043K(10944K), 0.1869806 secs] 4034K->3043K(15872K), [Perm : 3400K->3400K(12288K)], 0.1870847 secs] [Times: user=0.05 sys=0.00, real=0.19 secs]
最前面的數(shù)字 4.231
和 4.445
代表虛擬機(jī)啟動以來的秒數(shù)。
[GC
和 [Full GC
是垃圾回收的停頓類型,而不是區(qū)分是新生代還是年老代,如果有 Full
說明發(fā)生了 Stop-The-World
。如果是調(diào)用 System.gc()
觸發(fā)的,那么將顯示的是 [Full GC (System)
。
接下來的 [DefNew
, [Tenured
, [Perm
表示 GC 發(fā)生的區(qū)域,區(qū)域的名稱與使用的 GC 收集器相關(guān)。 Serial 收集器中新生代名為 "Default New Generation",顯示的名字為 "[DefNew"。對于ParNew收集器,顯示的是 "[ParNew",表示 “Parallel New Generation”。 對于 Parallel Scavenge 收集器,新生代名為 "PSYoungGen"。年老代和永久代也相同,名稱都由收集器決定。
方括號內(nèi)部顯示的 “4928K->512K(4928K)” 表示 “GC 前該區(qū)域已使用容量 -> GC 后該區(qū)域已使用容量 (該區(qū)域內(nèi)存總?cè)萘? ”。
再往后的 “0.0044047 secs” 表示該區(qū)域GC所用時間,單位是秒。
再往后的 “6835K->3468K(15872K)” 表示 “GC 前Java堆已使用容量 -> GC后Java堆已使用容量 (Java堆總?cè)萘浚?#8221;。
再往后的 “0.0045291 secs” 是Java堆GC所用的總時間。
最后的 “[Times: user=0.00 sys=0.00, real=0.00 secs]” 分別代表 用戶態(tài)消耗的CPU時間、內(nèi)核態(tài)消耗的CPU時間 和 操作從開始到結(jié)束所經(jīng)過的墻鐘時間。墻鐘時間包括各種非運(yùn)算的等待耗時,如IO等待、線程阻塞。CPU時間不包括等待時間,當(dāng)系統(tǒng)有多核時,多線程操作會疊加這些CPU時間,所以user或sys時間會超過real時間。
堆的分代

在上圖中:
- young區(qū)域就是新生代,存放新創(chuàng)建對象;
- tenured是年老代,存放在新生代經(jīng)歷多次垃圾回收后仍存活的對象;
- perm是永生代,存放類定義信息、元數(shù)據(jù)等信息。
當(dāng)GC發(fā)生在新生代時,稱為Minor GC,次收集;當(dāng)GC發(fā)生在年老代時,稱為Major GC,主收集。 一般的,Minor GC的發(fā)生頻率要比Major GC高很多。