最近在學習jvm和gc調優,今天總結下cms的一些特點和要點,首先貼上乙個實際的cms gc log,先來解讀下各個元素。
/*從下面的gc日誌可以看出,當前最新版本(jdk1.8)中的cms大致分為6步
1. cms initial mark 初始標記
2. cms-concurrent-mark 併發標記
3. cms-concurrent-preclean 預處理
4. cms-concurrent-abortable-preclean 預處理5.
*///初始標記 這個動作需要stop the world
//這個步驟僅僅是標記一下gc roots能直接關聯到的物件
15887.169: [gc (cms initial mark) [1 cms-initial-mark: 1307523k(1398144k)]
1336024k(2027264k), 0.0046835 secs] [times: user=0.01 sys=0.00, real=0.00 secs] 15887.173: [cms-concurrent-mark-start]15887.443: [cms-concurrent-mark: 0.269/0.269 secs] [times: user=0.90 sys=0.16, real=0.27 secs] 15887.443: [cms-concurrent-preclean-start]15887.462:
[cms-concurrent-preclean: 0.019/0.020 secs] [times: user=0.06 sys=0.02, real=0.02 secs] 15887.463: [cms-concurrent-abortable-preclean-start]15888.433: [cms-concurrent-abortable-preclean: 0.880/0.970 secs] [times: user=3.00 sys=0.60, real=0.97 secs]
[gc (cms final remark) [yg occupancy: 380362 k (629120 k)]15888.435: [rescan (parallel) ,
0.1452201 secs]15888.580: [weak refs processing, 0.0015400 secs]
15888.582: [class unloading, 0.0541155 secs]
15888.636: [scrub symbol table, 0.0049675 secs]
15888.641: [scrub string table, 0.0009185 secs][1 cms-remark: 1307523k(1398144k)]
1687886k(2027264k), 0.2161993 secs] [times: user=0.62 sys=0.01, real=0.22 secs]
15888.651: [cms-concurrent-sweep-start]15889.816: [cms-concurrent-sweep: 0.947/1.165 secs] [times: user=3.74 sys=0.68, real=1.17 secs] 15889.816: [cms-concurrent-reset-start]15889.838: [cms-concurrent-reset: 0.022/0.022 secs] [times: user=0.07 sys=0.02, real=0.02
secs]
GC日誌分析
psyounggen gc型別 使用 xx useparalleloldgc 新生代，老年代都使用並行 收集器 或者 xx useparallelgc 新生代使用並行 收集器，老年代使用序列收集器 6577k younggc前新生代記憶體占用 1016k younggc後新生代記憶體占用 9216k...
GC日誌時間分析
在gc日誌裡，一條完整的gc日誌記錄最後，會帶有本次gc所花費的時間，如下面這一條新生代gc gc defnew 3298k 149k 5504k 0.0053498 secs 3298k 3221k 9600k 0053750 secs times user 0.00 sys 0.00,real ...
GC日誌格式
2017 03 20t04 06 02.824 0800 3319.193 gc allocation failure psyounggen 6970156k 9232k 6 974464k 16059560k 9098683k 20955648k 0.0161336 secs times user...