GC日志分析

最新推荐文章于 2024-03-21 09:20:50 发布
邵年无知
最新推荐文章于 2024-03-21 09:20:50 发布
阅读量166
点赞数
分类专栏： Java GC回收文章标签： java 后端
本文链接：https://blog.csdn.net/wy_751493238/article/details/108632204
版权
Java 同时被 2 个专栏收录
1 篇文章 0 订阅
订阅专栏
GC回收
1 篇文章 0 订阅
订阅专栏
CMS日志分析

问题：频繁CMS Init Remark
Memory: 4k page, physical 32947120k(17251648k free), swap 0k(0k free)
CommandLine flags: -XX:CMSFullGCsBeforeCompaction=50 -XX:InitialHeapSize=4294967296 -XX:+ManagementServer -XX:MaxHeapSize=16777216000 -XX:MaxNewSize=42949
67296 -XX:MaxTenuringThreshold=15 -XX:NewSize=4294967296 -XX:OldPLABSize=16 -XX:+PrintFlagsFinal -XX:+PrintGC -XX:+PrintGCDateStamps -XX:+PrintGCDetails -
XX:+PrintGCTimeStamps -XX:SurvivorRatio=8 -XX:+UseCMSCompactAtFullCollection -XX:+UseCompressedClassPointers -XX:+UseCompressedOops -XX:+UseConcMarkSweepG
C -XX:+UseParNewGC
2020-09-15T16:32:17.378+0800: 2.160: [Full GC (System.gc()) 2020-09-15T16:32:17.378+0800: 2.160: [CMS: 0K->63K(64K), 0.1129463 secs] 1342183K->29588K(3774
912K), [Metaspace: 19251K->19251K(1067008K)], 0.1130391 secs] [Times: user=0.10 sys=0.04, real=0.11 secs]
2020-09-15T16:32:17.491+0800: 2.273: [GC (CMS Initial Mark) [1 CMS-initial-mark: 63K(64K)] 29588K(3774912K), 0.0171329 secs] [Times: user=0.02 sys=0.00, r
eal=0.02 secs]
2020-09-15T16:32:17.509+0800: 2.291: [CMS-concurrent-mark-start]
2020-09-15T16:32:17.509+0800: 2.291: [CMS-concurrent-mark: 0.001/0.001 secs] [Times: user=0.01 sys=0.00, real=0.00 secs]
2020-09-15T16:32:17.509+0800: 2.291: [CMS-concurrent-preclean-start]
2020-09-15T16:32:17.510+0800: 2.292: [CMS-concurrent-preclean: 0.001/0.001 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
2020-09-15T16:32:17.510+0800: 2.292: [CMS-concurrent-abortable-preclean-start]
2020-09-15T16:32:18.572+0800: 3.354: [CMS-concurrent-abortable-preclean: 0.008/1.062 secs] [Times: user=2.14 sys=0.11, real=1.06 secs]
2020-09-15T16:32:18.573+0800: 3.355: [GC (CMS Final Remark) [YG occupancy: 1707254 K (3774848 K)]2020-09-15T16:32:18.573+0800: 3.355: [Rescan (parallel) ,
 0.0682473 secs]2020-09-15T16:32:18.641+0800: 3.423: [weak refs processing, 0.0000295 secs]2020-09-15T16:32:18.642+0800: 3.423: [class unloading, 0.003262
4 secs]2020-09-15T16:32:18.645+0800: 3.427: [scrub symbol table, 0.0027793 secs]2020-09-15T16:32:18.648+0800: 3.430: [scrub string table, 0.0003789 secs][
1 CMS-remark: 63K(64K)] 1707318K(3774912K), 0.0753012 secs] [Times: user=0.55 sys=0.00, real=0.08 secs]
2020-09-15T16:32:18.649+0800: 3.431: [CMS-concurrent-sweep-start]
2020-09-15T16:32:18.649+0800: 3.431: [CMS-concurrent-sweep: 0.000/0.000 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
2020-09-15T16:32:18.649+0800: 3.431: [CMS-concurrent-reset-start]
2020-09-15T16:32:18.678+0800: 3.460: [CMS-concurrent-reset: 0.029/0.029 secs] [Times: user=0.12 sys=0.00, real=0.03 secs]
2020-09-15T16:32:30.159+0800: 14.941: [GC (Allocation Failure) 2020-09-15T16:32:30.159+0800: 14.941: [ParNew: 3355456K->419391K(3774848K), 0.9074773 secs]
 3355519K->927569K(4283520K), 0.9076087 secs] [Times: user=2.16 sys=0.43, real=0.91 secs]
2020-09-15T16:32:31.067+0800: 15.849: [GC (CMS Initial Mark) [1 CMS-initial-mark: 508177K(508672K)] 965766K(4283520K), 0.0462747 secs] [Times: user=0.19 s
ys=0.00, real=0.04 secs]
2020-09-15T16:32:31.113+0800: 15.895: [CMS-concurrent-mark-start]
2020-09-15T16:32:31.166+0800: 15.948: [CMS-concurrent-mark: 0.053/0.053 secs] [Times: user=0.42 sys=0.01, real=0.06 secs]
2020-09-15T16:32:31.166+0800: 15.948: [CMS-concurrent-preclean-start]
2020-09-15T16:32:31.168+0800: 15.950: [CMS-concurrent-preclean: 0.002/0.002 secs] [Times: user=0.01 sys=0.00, real=0.00 secs]
2020-09-15T16:32:31.168+0800: 15.950: [CMS-concurrent-abortable-preclean-start]
 CMS: abort preclean due to time 2020-09-15T16:32:36.704+0800: 21.486: [CMS-concurrent-abortable-preclean: 5.262/5.536 secs] [Times: user=18.95 sys=0.35,
real=5.54 secs]
2020-09-15T16:32:36.705+0800: 21.487: [GC (CMS Final Remark) [YG occupancy: 1167275 K (3774848 K)]2020-09-15T16:32:36.705+0800: 21.487: [Rescan (parallel)
 , 0.1138124 secs]2020-09-15T16:32:36.819+0800: 21.601: [weak refs processing, 0.0000437 secs]2020-09-15T16:32:36.819+0800: 21.601: [class unloading, 0.00
82313 secs]2020-09-15T16:32:36.828+0800: 21.610: [scrub symbol table, 0.0081117 secs]2020-09-15T16:32:36.836+0800: 21.618: [scrub string table, 0.0005232
secs][1 CMS-remark: 508177K(508672K)] 1675452K(4283520K), 0.1326061 secs] [Times: user=0.88 sys=0.00, real=0.13 secs]
2020-09-15T16:32:36.838+0800: 21.620: [CMS-concurrent-sweep-start]
2020-09-15T16:32:36.879+0800: 21.661: [CMS-concurrent-sweep: 0.038/0.040 secs] [Times: user=0.21 sys=0.00, real=0.04 secs] 
2020-09-15T16:32:36.880+0800: 21.662: [CMS-concurrent-reset-start]
2020-09-15T16:32:36.909+0800: 21.690: [CMS-concurrent-reset: 0.029/0.029 secs] [Times: user=0.18 sys=0.00, real=0.03 secs] 
2020-09-15T16:32:38.909+0800: 23.691: [GC (CMS Initial Mark) [1 CMS-initial-mark: 508177K(846964K)] 2234927K(4621812K), 0.1144852 secs] [Times: user=0.85 
sys=0.00, real=0.11 secs] 
2020-09-15T16:32:39.024+0800: 23.806: [CMS-concurrent-mark-start]
2020-09-15T16:32:39.028+0800: 23.810: [CMS-concurrent-mark: 0.004/0.004 secs] [Times: user=0.03 sys=0.01, real=0.00 secs] 
2020-09-15T16:32:39.028+0800: 23.810: [CMS-concurrent-preclean-start]
2020-09-15T16:32:39.031+0800: 23.813: [CMS-concurrent-preclean: 0.003/0.003 secs] [Times: user=0.02 sys=0.00, real=0.00 secs] 
2020-09-15T16:32:39.031+0800: 23.813: [CMS-concurrent-abortable-preclean-start]
 CMS: abort preclean due to time 2020-09-15T16:32:44.921+0800: 29.703: [CMS-concurrent-abortable-preclean: 5.496/5.890 secs] [Times: user=20.47 sys=0.70, 
real=5.89 secs] 
2020-09-15T16:32:44.921+0800: 29.703: [GC (CMS Final Remark) [YG occupancy: 3440934 K (3774848 K)]2020-09-15T16:32:44.921+0800: 29.703: [Rescan (parallel)
 , 0.1957912 secs]2020-09-15T16:32:45.117+0800: 29.899: [weak refs processing, 0.0000298 secs]2020-09-15T16:32:45.117+0800: 29.899: [class unloading, 0.01
11229 secs]2020-09-15T16:32:45.128+0800: 29.910: [scrub symbol table, 0.0107061 secs]2020-09-15T16:32:45.139+0800: 29.921: [scrub string table, 0.0008613 
secs][1 CMS-remark: 508177K(846964K)] 3949111K(4621812K), 0.2215132 secs] [Times: user=1.55 sys=0.00, real=0.22 secs] 
2020-09-15T16:32:45.143+0800: 29.925: [CMS-concurrent-sweep-start]
2020-09-15T16:32:45.182+0800: 29.964: [CMS-concurrent-sweep: 0.039/0.039 secs] [Times: user=0.04 sys=0.00, real=0.04 secs] 
2020-09-15T16:32:45.182+0800: 29.964: [CMS-concurrent-reset-start]
2020-09-15T16:32:45.209+0800: 29.991: [CMS-concurrent-reset: 0.027/0.027 secs] [Times: user=0.03 sys=0.00, real=0.03 secs] 
2020-09-15T16:40:03.109+0800: 467.891: [GC (Allocation Failure) 2020-09-15T16:40:03.109+0800: 467.891: [ParNew: 3774847K->198529K(3774848K), 0.6126053 sec
s] 3792452K->361305K(4621812K), 0.6127597 secs] [Times: user=2.28 sys=0.11, real=0.61 secs] 
2020-09-15T16:40:03.736+0800: 468.518: [GC (CMS Initial Mark) [1 CMS-initial-mark: 162776K(846964K)] 417570K(4621812K), 0.0181251 secs] [Times: user=0.10 
sys=0.00, real=0.02 secs] 
2020-09-15T16:40:03.756+0800: 468.538: [CMS-concurrent-mark-start]
2020-09-15T16:40:03.990+0800: 468.772: [CMS-concurrent-mark: 0.167/0.234 secs] [Times: user=1.44 sys=0.04, real=0.23 secs] 
2020-09-15T16:40:03.991+0800: 468.772: [CMS-concurrent-preclean-start]
2020-09-15T16:40:03.994+0800: 468.776: [CMS-concurrent-preclean: 0.004/0.004 secs] [Times: user=0.02 sys=0.00, real=0.01 secs] 
2020-09-15T16:40:03.994+0800: 468.776: [CMS-concurrent-abortable-preclean-start]
 CMS: abort preclean due to time 2020-09-15T16:40:09.397+0800: 474.179: [CMS-concurrent-abortable-preclean: 4.439/5.402 secs] [Times: user=10.79 sys=0.27,
 real=5.40 secs] 
2020-09-15T16:40:09.397+0800: 474.179: [GC (CMS Final Remark) [YG occupancy: 692155 K (3774848 K)]2020-09-15T16:40:09.397+0800: 474.179: [Rescan (parallel
) , 0.0461801 secs]2020-09-15T16:40:09.444+0800: 474.226: [weak refs processing, 0.0000728 secs]2020-09-15T16:40:09.444+0800: 474.226: [class unloading, 0
.0139337 secs]2020-09-15T16:40:09.458+0800: 474.240: [scrub symbol table, 0.0114605 secs]2020-09-15T16:40:09.469+0800: 474.251: [scrub string table, 0.001
2048 secs][1 CMS-remark: 162776K(846964K)] 854932K(4621812K), 0.0765972 secs] [Times: user=0.36 sys=0.00, real=0.08 secs] 
2020-09-15T16:40:09.474+0800: 474.256: [CMS-concurrent-sweep-start]
2020-09-15T16:40:09.517+0800: 474.299: [CMS-concurrent-sweep: 0.043/0.043 secs] [Times: user=0.04 sys=0.00, real=0.04 secs] 
2020-09-15T16:40:09.517+0800: 474.299: [CMS-concurrent-reset-start]
2020-09-15T16:40:09.546+0800: 474.328: [CMS-concurrent-reset: 0.028/0.028 secs] [Times: user=0.13 sys=0.00, real=0.03 secs]