解读gc日志文件

june_chao666

于 2024-06-26 10:48:19 发布

阅读量272

点赞数 4

文章标签： jvm java

本文链接：https://blog.csdn.net/weixin_44263023/article/details/139983112

版权

Java HotSpot(TM) 64-Bit Server VM (25.212-b10) for windows-amd64 JRE (1.8.0_212-b10), built on Apr  1 2019 22:50:23 by "java_re" with MS VC++ 10.0 (VS2010)
Memory: 4k page, physical 33272696k(11788180k free), swap 47428472k(14035772k free)
CommandLine flags: -XX:+CMSClassUnloadingEnabled -XX:CMSInitiatingOccupancyFraction=70 -XX:+CMSParallelRemarkEnabled -XX:CompressedClassSpaceSize=327155712 -XX:InitialHeapSize=536870912 -XX:MaxHeapSize=536870912 -XX:MaxMetaspaceSize=335544320 -XX:MaxNewSize=536870912 -XX:MetaspaceSize=134217728 -XX:NewSize=536870912 -XX:OldPLABSize=16 -XX:-OmitStackTraceInFastThrow -XX:+PrintGC -XX:+PrintGCDateStamps -XX:+PrintGCDetails -XX:+PrintGCTimeStamps -XX:SoftRefLRUPolicyMSPerMB=0 -XX:SurvivorRatio=8 -XX:+UseCMSCompactAtFullCollection -XX:+UseCompressedClassPointers -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC -XX:-UseLargePages -XX:-UseLargePagesIndividualAllocation -XX:-UseParNewGC 
2024-06-21T17:42:33.444+0800: 115885.325: [GC (Allocation Failure) 2024-06-21T17:42:33.445+0800: 115885.326: [DefNew: 419392K->12379K(471808K), 0.2913579 secs] 419392K->12379K(471872K), 0.2925794 secs] [Times: user=0.02 sys=0.05, real=0.29 secs]

这段日志描述了一次垃圾收集事件，发生在特定时间点和环境下，下面是详细的解读：

时间戳: 2024-06-21T17:42:33.444+0800: 115885.325: 表示这次垃圾收集操作开始于2024年6月21日17时42分33秒（UTC+8时区），且自JVM启动以来已经过去了115885.325秒。
GC原因: [GC (Allocation Failure) 指出这次垃圾收集是因为新对象分配失败触发的，即新生代空间不足，需要进行垃圾回收以腾出空间。
时间戳（详细）: 2024-06-21T17:42:33.445+0800: 115885.326: 是GC操作内部的一个更精确的时间点。
垃圾收集详情:
- [DefNew: 419392K->12379K(471808K), 0.2913579 secs] 描述了新生代的垃圾收集情况：
  - Before GC: 新生代占用419392K。
  - After GC: 收集后，新生代占用减少到12379K。
  - Total Capacity: 新生代总容量为471808K。
  - Duration: 垃圾收集过程耗时约为0.29秒。
- 419392K->12379K(471872K), 0.2925794 secs] 总结整个垃圾收集周期，包括新生代和老年代的变化，但这里新生代和总容量的数字略有不一致，可能是笔误或日志格式问题，通常应理解为新生代和老年代的总变化。正确的理解应聚焦于新生代的详细变化。
- 0.2925794 secs] 表示整个GC周期的总持续时间为约0.29秒。
时间细分: [Times: user=0.02 sys=0.05, real=0.29 secs] 提供了GC操作的CPU时间消耗详情：
- user: 用户态CPU时间，约为0.02秒。
- sys: 内核态CPU时间，约为0.05秒。
- real: 实际经过的时间，也就是总的GC停顿时间，约为0.29秒。