JVM参数解读

JVM参数解读

串行收集器:
    DefNew:是使用-XX:+UseSerialGC(新生代,老年代都使用串行回收收集器)。
并行收集器:
    ParNew:是使用-XX:+UseParNewGC(新生代使用并行收集器,老年代使用串行回收收集器)或者-XX:+UseConcMarkSweepGC(新生代使用并行收集器,老年代使用CMS)。
    PSYoungGen:是使用-XX:+UseParallelOldGC(新生代,老年代都使用并行回收收集器)或者-XX:+UseParallelGC(新生代使用并行回收收集器,老年代使用串行收集器)
    garbage-first heap:是使用-XX:+UseG1GC(G1收集器)

1.  -XX:+UseSerialGC
配置:
-XX:+PrintGCDetails -XX:+PrintGCDateStamps -XX:+PrintGCTimeStamps -Xmx512m -Xmn400m -XX:+UseSerialGC

日志: DefNew
2018-11-05T15:37:34.844+0800: 2.332: [GC (Allocation Failure) 2.332: [DefNew: 327680K->40960K(368640K), 0.5990026 secs]2.931: [Tenured: 90443K->90547K(90548K), 0.3712554 secs] 327680K->131402K(459188K), [Metaspace: 4902K->4902K(1056768K)], 0.9703541 secs] [Times: user=0.87 sys=0.05, real=0.97 secs]
2018-11-05T15:37:42.247+0800: 9.736: [Full GC (Allocation Failure) 9.736: [Tenured: 114687K->114687K(114688K), 0.9092690 secs] 483327K->284012K(483328K), [Metaspace: 4905K->4905K(1056768K)], 0.9092986 secs] [Times: user=0.89 sys=0.02, real=0.91 secs]
2018-11-05T15:37:48.412+0800: 15.901: [Full GC (Allocation Failure) 15.901: [Tenured: 114687K->114687K(114688K), 0.9573293 secs] 483327K->368289K(483328K), [Metaspace: 4905K->4905K(1056768K)], 0.9573632 secs] [Times: user=0.95 sys=0.00, real=0.96 secs]
2018-11-05T15:37:53.666+0800: 21.155: [Full GC (Allocation Failure) 21.155: [Tenured: 114687K->114687K(114688K), 1.1289622 secs] 483327K->419415K(483328K), [Metaspace: 4905K->4905K(1056768K)], 1.1289911 secs] [Times: user=1.12 sys=0.00, real=1.13 secs]


2.  -XX:+UseParNewGC
配置:
-XX:+PrintGCDetails -XX:+PrintGCDateStamps -XX:+PrintGCTimeStamps -Xmx512m -Xmn400m -XX:+UseParNewGC

日志: ParNew
2018-11-05T15:41:38.505+0800: 2.223: [GC (Allocation Failure) 2.223: [ParNew: 327680K->40959K(368640K), 0.1842580 secs]2.408: [Tenured: 90940K->91122K(91124K), 0.3095685 secs] 327680K->131734K(459764K), [Metaspace: 4906K->4906K(1056768K)], 0.4939490 secs] [Times: user=1.03 sys=0.03, real=0.49 secs]
2018-11-05T15:41:45.385+0800: 9.103: [Full GC (Allocation Failure) 9.103: [Tenured: 114687K->114687K(114688K), 0.8543772 secs] 483327K->284184K(483328K), [Metaspace: 4909K->4909K(1056768K)], 0.8544062 secs] [Times: user=0.86 sys=0.00, real=0.85 secs]
2018-11-05T15:41:51.441+0800: 15.159: [Full GC (Allocation Failure) 15.159: [Tenured: 114687K->114687K(114688K), 0.9155152 secs] 483327K->368385K(483328K), [Metaspace: 4909K->4909K(1056768K)], 0.9155475 secs] [Times: user=0.90 sys=0.00, real=0.91 secs]

 

3.  -XX:+UseConcMarkSweepGC
配置:
-XX:+PrintGCDetails -XX:+PrintGCDateStamps -XX:+PrintGCTimeStamps -Xmx512m -Xmn400m -XX:+UseConcMarkSweepGC

日志: ParNew (执行cms操作)
2018-11-05T15:43:55.699+0800: 2.439: [GC (Allocation Failure) 2.439: [ParNew: 327680K->40960K(368640K), 0.4128518 secs] 327680K->131581K(459956K), 0.4128894 secs] [Times: user=1.43 sys=0.09, real=0.41 secs]
2018-11-05T15:43:56.112+0800: 2.852: [GC (CMS Initial Mark) [1 CMS-initial-mark: 90621K(91316K)] 137938K(459956K), 0.0036495 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
2018-11-05T15:43:56.115+0800: 2.856: [CMS-concurrent-mark-start]
2018-11-05T15:43:56.287+0800: 3.028: [CMS-concurrent-mark: 0.172/0.172 secs] [Times: user=0.34 sys=0.00, real=0.17 secs]
2018-11-05T15:43:56.287+0800: 3.028: [CMS-concurrent-preclean-start]
2018-11-05T15:43:56.288+0800: 3.028: [CMS-concurrent-preclean: 0.000/0.000 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
2018-11-05T15:43:56.288+0800: 3.028: [CMS-concurrent-abortable-preclean-start]
2018-11-05T15:43:56.288+0800: 3.028: [CMS-concurrent-abortable-preclean: 0.000/0.000 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
2018-11-05T15:43:56.288+0800: 3.028: [GC (CMS Final Remark) [YG occupancy: 74711 K (368640 K)]3.028: [Rescan (parallel) , 0.0154526 secs]3.044: [weak refs processing, 0.0000339 secs]3.044: [class unloading, 0.0004709 secs]3.044: [scrub symbol table, 0.0005446 secs]3.045: [scrub string table, 0.0473020 secs][1 CMS-remark: 90621K(91316K)] 165332K(459956K), 0.0640223 secs] [Times: user=0.11 sys=0.00, real=0.06 secs]
2018-11-05T15:43:56.352+0800: 3.092: [CMS-concurrent-sweep-start]
2018-11-05T15:43:56.381+0800: 3.121: [CMS-concurrent-sweep: 0.029/0.029 secs] [Times: user=0.06 sys=0.00, real=0.03 secs]
2018-11-05T15:43:56.381+0800: 3.121: [CMS-concurrent-reset-start]
2018-11-05T15:43:56.381+0800: 3.121: [CMS-concurrent-reset: 0.000/0.000 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
2018-11-05T15:43:58.381+0800: 5.121: [GC (CMS Initial Mark) [1 CMS-initial-mark: 90620K(114688K)] 315932K(483328K), 0.0310535 secs] [Times: user=0.09 sys=0.00, real=0.03 secs]
2018-11-05T15:43:58.412+0800: 5.152: [CMS-concurrent-mark-start]
2018-11-05T15:43:58.475+0800: 5.215: [CMS-concurrent-mark: 0.063/0.063 secs] [Times: user=0.13 sys=0.00, real=0.06 secs]
2018-11-05T15:43:58.475+0800: 5.215: [CMS-concurrent-preclean-start]
2018-11-05T15:43:58.475+0800: 5.215: [CMS-concurrent-preclean: 0.000/0.000 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
2018-11-05T15:43:58.475+0800: 5.215: [CMS-concurrent-abortable-preclean-start]
2018-11-05T15:43:58.475+0800: 5.215: [CMS-concurrent-abortable-preclean: 0.000/0.000 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
2018-11-05T15:43:58.475+0800: 5.215: [GC (CMS Final Remark) [YG occupancy: 225312 K (368640 K)]5.215: [Rescan (parallel) , 0.0335473 secs]5.249: [weak refs processing, 0.0000286 secs]5.249: [class unloading, 0.0004759 secs]5.249: [scrub symbol table, 0.0007779 secs]5.250: [scrub string table, 0.0768823 secs][1 CMS-remark: 90620K(114688K)] 315932K(483328K), 0.1119094 secs] [Times: user=0.19 sys=0.00, real=0.11 secs]
2018-11-05T15:43:58.587+0800: 5.327: [CMS-concurrent-sweep-start]
2018-11-05T15:43:58.616+0800: 5.356: [CMS-concurrent-sweep: 0.029/0.029 secs] [Times: user=0.06 sys=0.00, real=0.03 secs]
2018-11-05T15:43:58.616+0800: 5.356: [CMS-concurrent-reset-start]
2018-11-05T15:43:58.616+0800: 5.356: [CMS-concurrent-reset: 0.000/0.000 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]


4.  -XX:+UseParallelOldGC
配置:
-XX:+PrintGCDetails -XX:+PrintGCDateStamps -XX:+PrintGCTimeStamps -Xmx512m -Xmn400m -XX:+UseParallelOldGC

日志: PSYoungGen
2018-11-05T15:47:48.784+0800: 1.966: [GC (Allocation Failure) [PSYoungGen: 307200K->51184K(358400K)] 307200K->123212K(430592K), 0.1625054 secs] [Times: user=0.42 sys=0.02, real=0.16 secs]
2018-11-05T15:47:48.947+0800: 2.129: [Full GC (Ergonomics) [PSYoungGen: 51184K->51139K(358400K)] [ParOldGen: 72028K->71708K(114688K)] 123212K->122847K(473088K), [Metaspace: 4904K->4904K(1056768K)], 0.9462024 secs] [Times: user=1.84 sys=0.00, real=0.95 secs]
2018-11-05T15:47:54.530+0800: 7.712: [Full GC (Ergonomics) [PSYoungGen: 358339K->141087K(358400K)] [ParOldGen: 71708K->114492K(114688K)] 430047K->255579K(473088K), [Metaspace: 4906K->4906K(1056768K)], 1.1105524 secs] [Times: user=2.48 sys=0.03, real=1.11 secs]
2018-11-05T15:47:59.212+0800: 12.394: [Full GC (Ergonomics) [PSYoungGen: 307200K->210696K(358400K)] [ParOldGen: 114492K->114492K(114688K)] 421692K->325188K(473088K), [Metaspace: 4907K->4907K(1056768K)], 1.0746380 secs] [Times: user=2.53 sys=0.01, real=1.07 secs]
2018-11-05T15:48:03.278+0800: 16.461: [Full GC (Ergonomics) [PSYoungGen: 307200K->253586K(358400K)] [ParOldGen: 114492K->114492K(114688K)] 421692K->368079K(473088K), [Metaspace: 4907K->4907K(1056768K)], 1.1114827 secs] [Times: user=2.92 sys=0.02, real=1.11 secs]
2018-11-05T15:48:06.255+0800: 19.438: [Full GC (Ergonomics) [PSYoungGen: 307200K->277414K(358400K)] [ParOldGen: 114492K->114492K(114688K)] 421692K->391907K(473088K), [Metaspace: 4907K->4907K(1056768K)], 1.1219826 secs] [Times: user=2.95 sys=0.00, real=1.12 secs]
2018-11-05T15:48:08.471+0800: 21.654: [Full GC (Ergonomics) [PSYoungGen: 307200K->290652K(358400K)] [ParOldGen: 114492K->114492K(114688K)] 421692K->405144K(473088K), [Metaspace: 4907K->4907K(1056768K)], 1.1412688 secs] [Times: user=3.14 sys=0.00, real=1.14 secs]


5.  -XX:+UseParallelGC
配置:
-XX:+PrintGCDetails -XX:+PrintGCDateStamps -XX:+PrintGCTimeStamps -Xmx512m -Xmn400m -XX:+UseParallelGC

日志: PSYoungGen
2018-11-05T15:51:09.889+0800: 2.190: [GC (Allocation Failure) [PSYoungGen: 307200K->51184K(358400K)] 307200K->125968K(433664K), 0.1628080 secs] [Times: user=0.42 sys=0.03, real=0.16 secs]
2018-11-05T15:51:10.052+0800: 2.353: [Full GC (Ergonomics) [PSYoungGen: 51184K->51127K(358400K)] [ParOldGen: 74783K->74450K(114688K)] 125968K->125577K(473088K), [Metaspace: 4897K->4897K(1056768K)], 0.9811427 secs] [Times: user=1.97 sys=0.00, real=0.98 secs]
2018-11-05T15:51:15.734+0800: 8.035: [Full GC (Ergonomics) [PSYoungGen: 358327K->143806K(358400K)] [ParOldGen: 74450K->114504K(114688K)] 432777K->258310K(473088K), [Metaspace: 4899K->4899K(1056768K)], 1.1140893 secs] [Times: user=2.48 sys=0.01, real=1.11 secs]
2018-11-05T15:51:20.292+0800: 12.593: [Full GC (Ergonomics) [PSYoungGen: 307200K->210841K(358400K)] [ParOldGen: 114504K->114504K(114688K)] 421704K->325345K(473088K), [Metaspace: 4899K->4899K(1056768K)], 1.0713272 secs] [Times: user=2.59 sys=0.00, real=1.07 secs]
2018-11-05T15:51:24.360+0800: 16.662: [Full GC (Ergonomics) [PSYoungGen: 307200K->253667K(358400K)] [ParOldGen: 114504K->114504K(114688K)] 421704K->368171K(473088K), [Metaspace: 4899K->4899K(1056768K)], 1.1586404 secs] [Times: user=2.85 sys=0.03, real=1.16 secs]
2018-11-05T15:51:27.387+0800: 19.688: [Full GC (Ergonomics) [PSYoungGen: 307200K->277459K(358400K)] [ParOldGen: 114504K->114504K(114688K)] 421704K->391963K(473088K), [Metaspace: 4899K->4899K(1056768K)], 1.1227816 secs] [Times: user=3.01 sys=0.01, real=1.12 secs]
2018-11-05T15:51:29.597+0800: 21.898: [Full GC (Ergonomics) [PSYoungGen: 307200K->290677K(358400K)] [ParOldGen: 114504K->114504K(114688K)] 421704K->405181K(473088K), [Metaspace: 4899K->4899K(1056768K)], 1.1356857 secs] [Times: user=2.82 sys=0.03, real=1.14 secs]


6.  -XX:+UseG1GC
配置:
-XX:+PrintGCDetails -XX:+PrintGCDateStamps -XX:+PrintGCTimeStamps -Xmx512m -Xmn400m -XX:+UseG1GC

日志: PSYoungGen

2018-11-05T15:52:22.419+0800: 3.687: [GC pause (G1 Evacuation Pause) (young) (to-space exhausted), 1.1752590 secs]
   [Parallel Time: 1060.4 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 3687.3, Avg: 3687.5, Max: 3687.9, Diff: 0.6]
      [Ext Root Scanning (ms): Min: 24.9, Avg: 25.1, Max: 25.4, Diff: 0.6, Sum: 100.4]
      [Update RS (ms): Min: 3.3, Avg: 3.3, Max: 3.4, Diff: 0.2, Sum: 13.3]
         [Processed Buffers: Min: 2, Avg: 2.0, Max: 2, Diff: 0, Sum: 8]
      [Scan RS (ms): Min: 25.9, Avg: 26.1, Max: 26.2, Diff: 0.3, Sum: 104.3]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2]
      [Object Copy (ms): Min: 1005.0, Avg: 1005.6, Max: 1006.1, Diff: 1.1, Sum: 4022.5]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 1059.7, Avg: 1060.2, Max: 1060.4, Diff: 0.6, Sum: 4240.8]
      [GC Worker End (ms): Min: 4747.7, Avg: 4747.7, Max: 4747.7, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 114.7 ms]
      [Evacuation Failure: 114.1 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.2 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 370.0M(400.0M)->0.0B(350.0M) Survivors: 0.0B->50.0M Heap: 402.1M(406.0M)->508.1M(512.0M)]
 [Times: user=2.56 sys=0.69, real=1.18 secs]
2018-11-05T15:52:23.595+0800: 4.863: [GC pause (G1 Evacuation Pause) (young) (initial-mark) (to-space exhausted), 1.8679703 secs]
   [Parallel Time: 1824.0 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 4862.7, Avg: 4862.7, Max: 4862.7, Diff: 0.0]
      [Ext Root Scanning (ms): Min: 22.7, Avg: 24.9, Max: 28.3, Diff: 5.6, Sum: 99.5]
      [Update RS (ms): Min: 76.8, Avg: 77.3, Max: 77.7, Diff: 0.9, Sum: 309.1]
         [Processed Buffers: Min: 84, Avg: 86.5, Max: 94, Diff: 10, Sum: 346]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1]
      [Object Copy (ms): Min: 1718.0, Avg: 1721.4, Max: 1723.6, Diff: 5.6, Sum: 6885.7]
      [Termination (ms): Min: 0.0, Avg: 0.4, Max: 0.8, Diff: 0.8, Sum: 1.5]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 1824.0, Avg: 1824.0, Max: 1824.0, Diff: 0.0, Sum: 7295.9]
      [GC Worker End (ms): Min: 6686.7, Avg: 6686.7, Max: 6686.7, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.0 ms]
   [Other: 43.9 ms]
      [Evacuation Failure: 43.5 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.2 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.0 ms]
   [Eden: 0.0B(350.0M)->0.0B(400.0M) Survivors: 50.0M->0.0B Heap: 508.1M(512.0M)->508.1M(512.0M)]
 [Times: user=2.06 sys=1.22, real=1.87 secs]
2018-11-05T15:52:25.463+0800: 6.731: [GC concurrent-root-region-scan-start]
2018-11-05T15:52:25.463+0800: 6.731: [GC concurrent-root-region-scan-end, 0.0000392 secs]
2018-11-05T15:52:25.463+0800: 6.731: [GC concurrent-mark-start]
2018-11-05T15:52:25.532+0800: 6.800: [Full GC (Allocation Failure)  508M->171M(512M), 0.7245971 secs]
   [Eden: 0.0B(400.0M)->0.0B(400.0M) Survivors: 0.0B->0.0B Heap: 508.1M(512.0M)->171.5M(512.0M)], [Metaspace: 4898K->4894K(1056768K)]
 [Times: user=0.94 sys=0.01, real=0.72 secs]
2018-11-05T15:52:26.257+0800: 7.525: [GC concurrent-mark-abort]
2018-11-05T15:52:33.148+0800: 14.417: [GC pause (G1 Evacuation Pause) (young) (to-space exhausted), 3.2263399 secs]
   [Parallel Time: 3105.3 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 14417.0, Avg: 14417.0, Max: 14417.1, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 46.0, Avg: 46.9, Max: 47.6, Diff: 1.6, Sum: 187.7]
      [Update RS (ms): Min: 0.9, Avg: 1.6, Max: 2.2, Diff: 1.3, Sum: 6.4]
         [Processed Buffers: Min: 1, Avg: 2.0, Max: 3, Diff: 2, Sum: 8]
      [Scan RS (ms): Min: 14.7, Avg: 14.8, Max: 15.0, Diff: 0.3, Sum: 59.3]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 3040.9, Avg: 3041.8, Max: 3043.0, Diff: 2.1, Sum: 12167.2]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 3105.1, Avg: 3105.2, Max: 3105.2, Diff: 0.1, Sum: 12420.7]
      [GC Worker End (ms): Min: 17522.2, Avg: 17522.2, Max: 17522.2, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 121.0 ms]
      [Evacuation Failure: 120.7 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 296.0M(400.0M)->0.0B(400.0M) Survivors: 0.0B->0.0B Heap: 509.3M(512.0M)->509.3M(512.0M)]
 [Times: user=3.76 sys=1.53, real=3.23 secs]
2018-11-05T15:52:36.392+0800: 17.661: [GC pause (G1 Evacuation Pause) (young) (initial-mark), 0.0482164 secs]
   [Parallel Time: 47.8 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 17660.7, Avg: 17660.7, Max: 17660.8, Diff: 0.0]
      [Ext Root Scanning (ms): Min: 41.0, Avg: 41.0, Max: 41.2, Diff: 0.2, Sum: 164.2]
      [Update RS (ms): Min: 0.9, Avg: 1.1, Max: 1.7, Diff: 0.8, Sum: 4.5]
         [Processed Buffers: Min: 1, Avg: 2.0, Max: 3, Diff: 2, Sum: 8]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 4.9, Avg: 5.0, Max: 5.0, Diff: 0.2, Sum: 20.0]
      [Termination (ms): Min: 0.0, Avg: 0.6, Max: 0.8, Diff: 0.8, Sum: 2.5]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [GC Worker Total (ms): Min: 47.8, Avg: 47.8, Max: 47.8, Diff: 0.0, Sum: 191.2]
      [GC Worker End (ms): Min: 17708.5, Avg: 17708.5, Max: 17708.6, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.3 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.0 ms]
   [Eden: 0.0B(400.0M)->0.0B(400.0M) Survivors: 0.0B->0.0B Heap: 509.3M(512.0M)->509.3M(512.0M)]
 [Times: user=0.19 sys=0.00, real=0.05 secs]
2018-11-05T15:52:36.440+0800: 17.709: [GC concurrent-root-region-scan-start]
2018-11-05T15:52:36.440+0800: 17.709: [GC concurrent-root-region-scan-end, 0.0000165 secs]
2018-11-05T15:52:36.440+0800: 17.709: [GC concurrent-mark-start]
2018-11-05T15:52:36.440+0800: 17.709: [Full GC (Allocation Failure)  509M->316M(512M), 1.0638391 secs]
   [Eden: 0.0B(400.0M)->0.0B(400.0M) Survivors: 0.0B->0.0B Heap: 509.3M(512.0M)->316.3M(512.0M)], [Metaspace: 4897K->4897K(1056768K)]
 [Times: user=1.39 sys=0.00, real=1.06 secs]
2018-11-05T15:52:37.504+0800: 18.773: [GC concurrent-mark-abort]
2018-11-05T15:52:44.347+0800: 25.616: [GC pause (G1 Evacuation Pause) (young) (to-space exhausted), 2.1570604 secs]
   [Parallel Time: 2080.8 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 25616.3, Avg: 25616.4, Max: 25616.4, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 59.8, Avg: 60.7, Max: 62.9, Diff: 3.1, Sum: 242.6]
      [Update RS (ms): Min: 1.3, Avg: 2.1, Max: 2.4, Diff: 1.1, Sum: 8.2]
         [Processed Buffers: Min: 1, Avg: 2.5, Max: 5, Diff: 4, Sum: 10]
      [Scan RS (ms): Min: 3.4, Avg: 3.8, Max: 4.0, Diff: 0.6, Sum: 15.2]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 2011.4, Avg: 2014.1, Max: 2015.5, Diff: 4.2, Sum: 8056.4]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.2]
      [GC Worker Total (ms): Min: 2080.6, Avg: 2080.7, Max: 2080.8, Diff: 0.2, Sum: 8322.8]
      [GC Worker End (ms): Min: 27697.0, Avg: 27697.0, Max: 27697.1, Diff: 0.1]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 76.2 ms]
      [Evacuation Failure: 75.9 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.0 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.0 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.0 ms]
   [Eden: 194.0M(400.0M)->0.0B(400.0M) Survivors: 0.0B->0.0B Heap: 510.3M(512.0M)->510.3M(512.0M)]
 [Times: user=2.35 sys=1.33, real=2.16 secs]
2018-11-05T15:52:46.511+0800: 27.780: [GC pause (G1 Evacuation Pause) (young) (initial-mark), 0.0624853 secs]
   [Parallel Time: 62.3 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 27780.1, Avg: 27780.1, Max: 27780.1, Diff: 0.0]
      [Ext Root Scanning (ms): Min: 53.6, Avg: 53.7, Max: 53.7, Diff: 0.2, Sum: 214.7]
      [Update RS (ms): Min: 1.0, Avg: 1.6, Max: 1.8, Diff: 0.8, Sum: 6.5]
         [Processed Buffers: Min: 2, Avg: 2.5, Max: 4, Diff: 2, Sum: 10]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 6.5, Avg: 6.6, Max: 6.7, Diff: 0.1, Sum: 26.4]
      [Termination (ms): Min: 0.0, Avg: 0.2, Max: 0.8, Diff: 0.8, Sum: 1.0]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [GC Worker Total (ms): Min: 62.1, Avg: 62.1, Max: 62.3, Diff: 0.2, Sum: 248.6]
      [GC Worker End (ms): Min: 27842.2, Avg: 27842.3, Max: 27842.4, Diff: 0.2]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.0 ms]
   [Other: 0.2 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.0 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.0 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.0 ms]
   [Eden: 0.0B(400.0M)->0.0B(400.0M) Survivors: 0.0B->0.0B Heap: 510.3M(512.0M)->510.3M(512.0M)]
 [Times: user=0.25 sys=0.00, real=0.06 secs]
2018-11-05T15:52:46.574+0800: 27.843: [GC concurrent-root-region-scan-start]
2018-11-05T15:52:46.574+0800: 27.843: [GC concurrent-root-region-scan-end, 0.0000168 secs]
2018-11-05T15:52:46.574+0800: 27.843: [GC concurrent-mark-start]
2018-11-05T15:52:46.574+0800: 27.843: [Full GC (Allocation Failure)  510M->402M(512M), 1.1845914 secs]
   [Eden: 0.0B(400.0M)->0.0B(400.0M) Survivors: 0.0B->0.0B Heap: 510.3M(512.0M)->402.4M(512.0M)], [Metaspace: 4897K->4897K(1056768K)]
 [Times: user=1.59 sys=0.01, real=1.18 secs]
2018-11-05T15:52:47.758+0800: 29.027: [GC concurrent-mark-abort]
2018-11-05T15:52:50.033+0800: 31.302: [GC pause (G1 Humongous Allocation) (young) (initial-mark), 0.1044164 secs]
   [Parallel Time: 104.1 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 31302.0, Avg: 31302.0, Max: 31302.1, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 59.9, Avg: 60.5, Max: 61.8, Diff: 1.9, Sum: 241.9]
      [Update RS (ms): Min: 3.1, Avg: 3.3, Max: 3.9, Diff: 0.8, Sum: 13.3]
         [Processed Buffers: Min: 2, Avg: 2.3, Max: 3, Diff: 1, Sum: 9]
      [Scan RS (ms): Min: 0.0, Avg: 0.4, Max: 0.5, Diff: 0.5, Sum: 1.6]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 38.5, Avg: 39.8, Max: 40.4, Diff: 2.0, Sum: 159.2]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 103.9, Avg: 104.0, Max: 104.1, Diff: 0.1, Sum: 416.1]
      [GC Worker End (ms): Min: 31406.0, Avg: 31406.0, Max: 31406.0, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.3 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.0 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.0 ms]
   [Eden: 57.0M(400.0M)->0.0B(374.0M) Survivors: 0.0B->26.0M Heap: 458.9M(512.0M)->428.0M(512.0M)]
 [Times: user=0.44 sys=0.00, real=0.10 secs]
2018-11-05T15:52:50.137+0800: 31.406: [GC concurrent-root-region-scan-start]
2018-11-05T15:52:50.152+0800: 31.422: [GC concurrent-root-region-scan-end, 0.0152069 secs]
2018-11-05T15:52:50.152+0800: 31.422: [GC concurrent-mark-start]
2018-11-05T15:52:51.292+0800: 32.561: [GC concurrent-mark-end, 1.1396907 secs]
2018-11-05T15:52:51.292+0800: 32.562: [GC remark 32.562: [Finalize Marking, 0.0001269 secs] 32.562: [GC ref-proc, 0.0002435 secs] 32.562: [Unloading, 0.0551409 secs], 0.0564074 secs]
 [Times: user=0.19 sys=0.00, real=0.06 secs]
2018-11-05T15:52:51.349+0800: 32.619: [GC cleanup 493M->493M(512M), 0.0005738 secs]
 [Times: user=0.06 sys=0.00, real=0.00 secs]
2018-11-05T15:52:52.089+0800: 33.358: [GC pause (G1 Evacuation Pause) (young) (to-space exhausted), 1.1349741 secs]
   [Parallel Time: 1108.7 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 33358.1, Avg: 33358.2, Max: 33358.2, Diff: 0.0]
      [Ext Root Scanning (ms): Min: 66.8, Avg: 67.4, Max: 68.3, Diff: 1.5, Sum: 269.6]
      [Update RS (ms): Min: 3.0, Avg: 3.5, Max: 4.0, Diff: 1.1, Sum: 14.0]
         [Processed Buffers: Min: 3, Avg: 3.3, Max: 4, Diff: 1, Sum: 13]
      [Scan RS (ms): Min: 2.1, Avg: 2.3, Max: 2.7, Diff: 0.6, Sum: 9.2]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 1034.8, Avg: 1035.4, Max: 1035.8, Diff: 1.0, Sum: 4141.5]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 1108.6, Avg: 1108.6, Max: 1108.6, Diff: 0.0, Sum: 4434.4]
      [GC Worker End (ms): Min: 34466.8, Avg: 34466.8, Max: 34466.8, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.0 ms]
   [Other: 26.3 ms]
      [Evacuation Failure: 26.0 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.0 ms]
   [Eden: 44.0M(374.0M)->0.0B(400.0M) Survivors: 26.0M->0.0B Heap: 509.6M(512.0M)->509.6M(512.0M)]
 [Times: user=1.56 sys=0.64, real=1.13 secs]
2018-11-05T15:52:53.231+0800: 34.501: [GC pause (G1 Evacuation Pause) (young), 0.0669117 secs]
   [Parallel Time: 66.7 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 34500.8, Avg: 34500.8, Max: 34500.8, Diff: 0.0]
      [Ext Root Scanning (ms): Min: 59.1, Avg: 59.2, Max: 59.2, Diff: 0.1, Sum: 236.6]
      [Update RS (ms): Min: 0.8, Avg: 0.9, Max: 0.9, Diff: 0.1, Sum: 3.4]
         [Processed Buffers: Min: 4, Avg: 4.8, Max: 6, Diff: 2, Sum: 19]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 6.5, Avg: 6.5, Max: 6.6, Diff: 0.1, Sum: 26.1]
      [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.3]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [GC Worker Total (ms): Min: 66.6, Avg: 66.6, Max: 66.7, Diff: 0.1, Sum: 266.5]
      [GC Worker End (ms): Min: 34567.4, Avg: 34567.4, Max: 34567.5, Diff: 0.1]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.0 ms]
   [Other: 0.2 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.0 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.0 ms]
   [Eden: 0.0B(400.0M)->0.0B(400.0M) Survivors: 0.0B->0.0B Heap: 509.6M(512.0M)->509.6M(512.0M)]
 [Times: user=0.25 sys=0.00, real=0.07 secs]
2018-11-05T15:52:53.298+0800: 34.568: [Full GC (Allocation Failure)  509M->459M(512M), 1.2066491 secs]
   [Eden: 0.0B(400.0M)->0.0B(400.0M) Survivors: 0.0B->0.0B Heap: 509.6M(512.0M)->459.5M(512.0M)], [Metaspace: 4897K->4897K(1056768K)]
 [Times: user=1.67 sys=0.02, real=1.21 secs]
2018-11-05T15:52:56.868+0800: 38.138: [GC pause (G1 Evacuation Pause) (young) (to-space exhausted), 0.6525278 secs]
   [Parallel Time: 631.5 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 38137.7, Avg: 38137.7, Max: 38137.7, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 68.3, Avg: 68.8, Max: 69.3, Diff: 1.0, Sum: 275.1]
      [Update RS (ms): Min: 0.0, Avg: 3.2, Max: 4.5, Diff: 4.5, Sum: 12.8]
         [Processed Buffers: Min: 0, Avg: 3.0, Max: 4, Diff: 4, Sum: 12]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 557.7, Avg: 559.2, Max: 562.9, Diff: 5.2, Sum: 2236.8]
      [Termination (ms): Min: 0.0, Avg: 0.2, Max: 0.3, Diff: 0.3, Sum: 0.7]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [GC Worker Total (ms): Min: 631.3, Avg: 631.4, Max: 631.4, Diff: 0.1, Sum: 2525.5]
      [GC Worker End (ms): Min: 38769.0, Avg: 38769.1, Max: 38769.1, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.0 ms]
   [Other: 21.0 ms]
      [Evacuation Failure: 20.8 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.0 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.0 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.0 ms]
   [Eden: 52.0M(400.0M)->0.0B(400.0M) Survivors: 0.0B->0.0B Heap: 511.5M(512.0M)->511.5M(512.0M)]
 [Times: user=1.11 sys=0.34, real=0.65 secs]
2018-11-05T15:52:57.521+0800: 38.790: [GC pause (G1 Evacuation Pause) (young) (initial-mark), 0.0763663 secs]
   [Parallel Time: 76.1 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 38790.4, Avg: 38791.0, Max: 38791.2, Diff: 0.8]
      [Ext Root Scanning (ms): Min: 64.4, Avg: 64.7, Max: 65.2, Diff: 0.7, Sum: 258.7]
      [Update RS (ms): Min: 2.4, Avg: 2.7, Max: 2.9, Diff: 0.5, Sum: 10.7]
         [Processed Buffers: Min: 3, Avg: 4.5, Max: 9, Diff: 6, Sum: 18]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 7.8, Avg: 7.9, Max: 8.1, Diff: 0.2, Sum: 31.7]
      [Termination (ms): Min: 0.0, Avg: 0.2, Max: 0.5, Diff: 0.5, Sum: 1.0]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [GC Worker Total (ms): Min: 75.3, Avg: 75.5, Max: 76.1, Diff: 0.8, Sum: 302.1]
      [GC Worker End (ms): Min: 38866.5, Avg: 38866.5, Max: 38866.5, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.2 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.0 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.0 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.0 ms]
   [Eden: 0.0B(400.0M)->0.0B(400.0M) Survivors: 0.0B->0.0B Heap: 511.5M(512.0M)->511.5M(512.0M)]
 [Times: user=0.31 sys=0.00, real=0.08 secs]
2018-11-05T15:52:57.597+0800: 38.867: [Full GC (Allocation Failure) 2018-11-05T15:52:57.598+0800: 38.867: [GC concurrent-root-region-scan-start]
2018-11-05T15:52:57.598+0800: 38.867: [GC concurrent-root-region-scan-end, 0.0000314 secs]
2018-11-05T15:52:57.598+0800: 38.867: [GC concurrent-mark-start]
 511M->482M(512M), 1.2165836 secs]
   [Eden: 0.0B(400.0M)->0.0B(400.0M) Survivors: 0.0B->0.0B Heap: 511.5M(512.0M)->482.7M(512.0M)], [Metaspace: 4897K->4897K(1056768K)]
 [Times: user=1.65 sys=0.00, real=1.22 secs]
2018-11-05T15:52:58.814+0800: 40.084: [GC concurrent-mark-abort]
2018-11-05T15:53:00.153+0800: 41.423: [GC pause (G1 Evacuation Pause) (young) (to-space exhausted), 0.3636538 secs]
   [Parallel Time: 352.2 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 41423.2, Avg: 41423.3, Max: 41423.3, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 69.2, Avg: 70.3, Max: 72.0, Diff: 2.8, Sum: 281.3]
      [Update RS (ms): Min: 1.3, Avg: 1.9, Max: 2.3, Diff: 1.0, Sum: 7.5]
         [Processed Buffers: Min: 1, Avg: 1.8, Max: 2, Diff: 1, Sum: 7]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 277.7, Avg: 279.5, Max: 280.7, Diff: 3.0, Sum: 1118.0]
      [Termination (ms): Min: 0.0, Avg: 0.4, Max: 0.8, Diff: 0.8, Sum: 1.6]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [GC Worker Total (ms): Min: 352.0, Avg: 352.1, Max: 352.2, Diff: 0.1, Sum: 1408.4]
      [GC Worker End (ms): Min: 41775.4, Avg: 41775.4, Max: 41775.4, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.0 ms]
   [Other: 11.4 ms]
      [Evacuation Failure: 11.2 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.0 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.0 ms]
   [Eden: 28.0M(400.0M)->0.0B(400.0M) Survivors: 0.0B->0.0B Heap: 510.7M(512.0M)->510.7M(512.0M)]
 [Times: user=0.70 sys=0.22, real=0.36 secs]
2018-11-05T15:53:00.517+0800: 41.787: [GC pause (G1 Evacuation Pause) (young) (initial-mark), 0.0786078 secs]
   [Parallel Time: 78.4 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 41787.1, Avg: 41787.2, Max: 41787.7, Diff: 0.7]
      [Ext Root Scanning (ms): Min: 67.7, Avg: 68.3, Max: 70.2, Diff: 2.5, Sum: 273.2]
      [Update RS (ms): Min: 0.0, Avg: 1.4, Max: 2.0, Diff: 2.0, Sum: 5.7]
         [Processed Buffers: Min: 0, Avg: 2.5, Max: 5, Diff: 5, Sum: 10]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 7.4, Avg: 8.3, Max: 8.6, Diff: 1.3, Sum: 33.1]
      [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 0.5]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [GC Worker Total (ms): Min: 77.7, Avg: 78.1, Max: 78.3, Diff: 0.6, Sum: 312.5]
      [GC Worker End (ms): Min: 41865.3, Avg: 41865.4, Max: 41865.4, Diff: 0.1]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.0 ms]
   [Other: 0.2 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.0 ms]
   [Eden: 0.0B(400.0M)->0.0B(400.0M) Survivors: 0.0B->0.0B Heap: 510.7M(512.0M)->510.7M(512.0M)]
 [Times: user=0.31 sys=0.00, real=0.08 secs]
2018-11-05T15:53:00.596+0800: 41.866: [Full GC (Allocation Failure) 2018-11-05T15:53:00.596+0800: 41.866: [GC concurrent-root-region-scan-start]

GC 学习:  https://blog.csdn.net/column/details/14851.html