2回复
4年前
old gc 耗时十几秒
线上jvm参数如下
-Xms6144m -Xmx6144m -XX:+PrintGCDetails -XX:+PrintGCDateStamps -Xmn3072m -XX:+UseConcMarkSweepGC -XX:+UseParNewGC -XX:+CMSParallelRemarkEnabled -XX:+UseFastAccessorMethods -XX:CMSInitiatingOccupancyFraction=70 -XX:+CMSScavengeBeforeRemark -XX:+ExplicitGCInvokesConcurrent
垃圾回收日志如下:
2020-04-26T17:34:51.587+0800: 5049.594: [GC (CMS Initial Mark) [1 CMS-initial-mark: 2571800K(3145728K)] 3941663K(5976896K), 0.2738342 secs] [Times: user=1.45 sys=0.01, real=0.28 secs]
2020-04-26T17:34:51.861+0800: 5049.868: [CMS-concurrent-mark-start]
2020-04-26T17:34:54.779+0800: 5052.785: [GC (Allocation Failure) 2020-04-26T17:34:54.779+0800: 5052.786: [ParNew: 2516608K->2516608K(2831168K), 0.0000797 secs]2020-04-26T17:34:54.779+0800: 5052.786: [CMS2020-04-26T17:35:04.078+0800: 5062.084: [CMS-concurrent-mark: 12.214/12.216 secs] [Times: user=43.05 sys=5.68, real=12.21 secs]
(concurrent mode failure): 2571800K->2583321K(3145728K), 16.8144312 secs] 5088408K->2583321K(5976896K), [Metaspace: 116236K->116236K(1157120K)], 16.8147731 secs] [Times: user=39.11 sys=5.51, real=16.82 secs]
2020-04-26T17:35:13.594+0800: 5071.601: [GC (CMS Initial Mark) [1 CMS-initial-mark: 2583321K(3145728K)] 4256642K(5976896K), 0.3942825 secs] [Times: user=1.85 sys=0.00, real=0.39 secs]
2020-04-26T17:35:13.989+0800: 5071.996: [CMS-concurrent-mark-start]
2020-04-26T17:35:15.935+0800: 5073.942: [GC (Allocation Failure) 2020-04-26T17:35:15.935+0800: 5073.942: [ParNew: 2516608K->2516608K(2831168K), 0.0000304 secs]2020-04-26T17:35:15.935+0800: 5073.942: [CMS2020-04-26T17:35:26.054+0800: 5084.060: [CMS-concurrent-mark: 12.063/12.065 secs] [Times: user=42.46 sys=5.79, real=12.07 secs]
(concurrent mode failure): 2583321K->2617260K(3145728K), 18.6539789 secs] 5099929K->2617260K(5976896K), [Metaspace: 116301K->116301K(1157120K)], 18.6543568 secs] [Times: user=42.14 sys=6.90, real=18.65 secs]
2020-04-26T17:35:36.590+0800: 5094.597: [GC (CMS Initial Mark) [1 CMS-initial-mark: 2617260K(3145728K)] 4065845K(5976896K), 0.3811776 secs] [Times: user=1.68 sys=0.01, real=0.39 secs]
2020-04-26T17:35:36.971+0800: 5094.978: [CMS-concurrent-mark-start]
2020-04-26T17:35:39.838+0800: 5097.845: [GC (Allocation Failure) 2020-04-26T17:35:39.838+0800: 5097.845: [ParNew: 2516608K->2516608K(2831168K), 0.0000381 secs]2020-04-26T17:35:39.838+0800: 5097.845: [CMS2020-04-26T17:35:49.157+0800: 5107.164: [CMS-concurrent-mark: 12.183/12.186 secs] [Times: user=43.24 sys=5.58, real=12.18 secs]
(concurrent mode failure): 2617260K->2594811K(3145728K), 16.8659467 secs] 5133868K->2594811K(5976896K), [Metaspace: 116301K->116301K(1157120K)], 16.8662359 secs] [Times: user=39.09 sys=5.76, real=16.87 secs]
2020-04-26T17:35:58.705+0800: 5116.712: [GC (CMS Initial Mark) [1 CMS-initial-mark: 2594811K(3145728K)] 4249504K(5976896K), 0.3663796 secs] [Times: user=1.88 sys=0.00, real=0.37 secs]
2020-04-26T17:35:59.072+0800: 5117.079: [CMS-concurrent-mark-start]
2020-04-26T17:36:00.727+0800: 5118.734: [GC (Allocation Failure) 2020-04-26T17:36:00.727+0800: 5118.734: [ParNew: 2516608K->2516608K(2831168K), 0.0000260 secs]2020-04-26T17:36:00.728+0800: 5118.734: [CMS2020-04-26T17:36:10.566+0800: 5128.573: [CMS-concurrent-mark: 11.492/11.494 secs] [Times: user=40.57 sys=5.30, real=11.49 secs]
(concurrent mode failure): 2594811K->2600365K(3145728K), 17.5541615 secs] 5111419K->2600365K(5976896K), [Metaspace: 116301K->116301K(1157120K)], 17.5544694 secs] [Times: user=40.81 sys=6.32, real=17.56 secs]
2020-04-26T17:36:20.282+0800: 5138.289: [GC (CMS Initial Mark) [1 CMS-initial-mark: 2600365K(3145728K)] 4137881K(5976896K), 0.4768422 secs] [Times: user=1.88 sys=0.32, real=0.47 secs]
2020-04-26T17:36:20.759+0800: 5138.766: [CMS-concurrent-mark-start]
2020-04-26T17:36:22.844+0800: 5140.851: [GC (Allocation Failure) 2020-04-26T17:36:22.844+0800: 5140.851: [ParNew: 2516608K->2516608K(2831168K), 0.0000407 secs]2020-04-26T17:36:22.844+0800: 5140.851: [CMS2020-04-26T17:36:32.853+0800: 5150.860: [CMS-concurrent-mark: 12.092/12.094 secs] [Times: user=43.30 sys=5.01, real=12.10 secs]
(concurrent mode failure): 2600365K->2594075K(3145728K), 17.3552511 secs] 5116973K->2594075K(5976896K), [Metaspace: 116362K->116362K(1157120K)], 17.3555648 secs] [Times: user=41.73 sys=5.66, real=17.35 secs]
2020-04-26T17:36:42.200+0800: 5160.207: [GC (CMS Initial Mark) [1 CMS-initial-mark: 2594075K(3145728K)] 4000569K(5976896K), 0.3444758 secs] [Times: user=1.60 sys=0.01, real=0.35 secs]
2020-04-26T17:36:42.545+0800: 5160.552: [CMS-concurrent-mark-start]
2020-04-26T17:36:45.256+0800: 5163.263: [GC (Allocation Failure) 2020-04-26T17:36:45.256+0800: 5163.263: [ParNew: 2516608K->2516608K(2831168K), 0.0000375 secs]2020-04-26T17:36:45.256+0800: 5163.263: [CMS2020-04-26T17:36:54.776+0800: 5172.783: [CMS-concurrent-mark: 12.228/12.231 secs] [Times: user=43.67 sys=5.34, real=12.23 secs]
(concurrent mode failure): 2594075K->2601539K(3145728K), 17.3262535 secs] 5110683K->2601539K(5976896K), [Metaspace: 116371K->116371K(1157120K)], 17.3265620 secs] [Times: user=39.97 sys=5.98, real=17.33 secs]
2020-04-26T17:37:04.583+0800: 5182.590: [GC (CMS Initial Mark) [1 CMS-initial-mark: 2601539K(3145728K)] 3548252K(5976896K), 0.1751572 secs] [Times: user=0.93 sys=0.00, real=0.17 secs]
2020-04-26T17:37:04.758+0800: 5182.765: [CMS-concurrent-mark-start]
2020-04-26T17:37:11.158+0800: 5189.165: [GC (Allocation Failure) 2020-04-26T17:37:11.158+0800: 5189.165: [ParNew: 2516608K->2516608K(2831168K), 0.0000412 secs]2020-04-26T17:37:11.158+0800: 5189.165: [CMS2020-04-26T17:37:18.684+0800: 5196.691: [CMS-concurrent-mark: 13.918/13.926 secs] [Times: user=50.68 sys=5.01, real=13.93 secs]
3222 阅读