21-04-08 cms日志分析-优快云博客

本文深入探讨了Java CMS（Concurrent Mark Sweep）垃圾收集器的工作原理，通过示例程序展示了CMS的执行过程，包括初始标记、并发标记、重新标记和并发清除阶段。同时，分析了日志中的关键指标，如年轻代和老年代的内存使用情况，以及STW（Stop-The-World）阶段的作用。通过对CMS日志的解析，读者可以更好地理解Java内存管理和垃圾回收机制。

1、执行程序

java -Xms20M -Xmx20M -XX:+PrintGCDetails -XX:+UseConcMarkSweepGC com.mashibing.jvm.gc.T15_FullGC_Problem01

2、查看日志

YGC

ParNew：年轻代收集器

6144->640：收集前后的对比

（6144）：整个年轻代容量

6585 -> 2770：整个堆的情况

（19840）：整个堆大小

初始标记>并发标记>重新标记>并发清除>>

initial-mark>concurrent-mark>Final Remark>concurrent-sweep>>

[GC (CMS Initial Mark) [1 CMS-initial-mark: 8511K(13696K)] 9866K(19840K), 0.0040321 secs] [Times: user=0.01 sys=0.00, real=0.00 secs]
   //8511 (13696) : 老年代使用（最大）
   //9866 (19840) : 整个堆使用（最大）
[CMS-concurrent-mark-start]
[CMS-concurrent-mark: 0.018/0.018 secs] [Times: user=0.01 sys=0.00, real=0.02 secs]
   //这里的时间意义不大，因为是并发执行
[CMS-concurrent-preclean-start]
[CMS-concurrent-preclean: 0.000/0.000 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
   //标记Card为Dirty，也称为Card Marking
[GC (CMS Final Remark) [YG occupancy: 1597 K (6144 K)][Rescan (parallel) , 0.0008396 secs][weak refs processing, 0.0000138 secs][class unloading, 0.0005404 secs][scrub symbol table, 0.0006169 secs][scrub string table, 0.0004903 secs][1 CMS-remark: 8511K(13696K)] 10108K(19840K), 0.0039567 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
   //STW阶段，YG occupancy:年轻代占用及容量
   //[Rescan (parallel)：STW下的存活对象标记
   //weak refs processing: 弱引用处理
   //class unloading: 卸载用不到的class
   //scrub symbol(string) table:
       //cleaning up symbol and string tables which hold class-level metadata and
       //internalized string respectively
   //CMS-remark: 8511K(13696K): 阶段过后的老年代占用及容量
   //10108K(19840K): 阶段过后的堆占用及容量

[CMS-concurrent-sweep-start]
[CMS-concurrent-sweep: 0.005/0.005 secs] [Times: user=0.00 sys=0.00, real=0.01 secs]
//标记已经完成，进行并发清理
[CMS-concurrent-reset-start]
[CMS-concurrent-reset: 0.000/0.000 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
//重置内部结构，为下次GC做准备