bingoogolapple.github.io icon indicating copy to clipboard operation
bingoogolapple.github.io copied to clipboard

GC 日志分析 - G1 – Garbage First(垃圾优先算法)

Open bingoogolapple opened this issue 7 years ago • 2 comments

  • java -jar -Xms2g -Xmx2g -Xss256k -XX:+UseG1GC -Xloggc:./log/G1.log -XX:+PrintGC -XX:+PrintGCTimeStamps -XX:+PrintGCDateStamps -XX:+PrintGCDetails build/libs/tuning-1.0.jar
Java HotSpot(TM) 64-Bit Server VM (25.131-b11) for bsd-amd64 JRE (1.8.0_131-b11), built on Mar 15 2017 01:32:22 by "java_re" with gcc 4.2.1 (Based on Apple Inc. build 5658) (LLVM build 2336.11.00)
Memory: 4k page, physical 8388608k(1985348k free)

/proc/meminfo:

CommandLine flags: -XX:InitialHeapSize=2147483648 -XX:MaxHeapSize=2147483648 -XX:+PrintGC -XX:+PrintGCDateStamps -XX:+PrintGCDetails -XX:+PrintGCTimeStamps -XX:ThreadStackSize=256 -XX:+UseCompressedClassPointers -XX:+UseCompressedOops -XX:+UseG1GC 

2017-07-27T10:49:24.066-0800: 0.480: [GC pause (G1 Evacuation Pause) (young), 0.0694219 secs]
   [Parallel Time: 68.5 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 480.2, Avg: 480.2, Max: 480.3, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.5, Avg: 0.6, Max: 0.9, Diff: 0.4, Sum: 2.6]
      [Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
         [Processed Buffers: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1]
      [Object Copy (ms): Min: 67.5, Avg: 67.6, Max: 67.7, Diff: 0.2, Sum: 270.5]
      [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.3]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 68.4, Avg: 68.4, Max: 68.5, Diff: 0.1, Sum: 273.7]
      [GC Worker End (ms): Min: 548.6, Avg: 548.6, Max: 548.6, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.8 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.2 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 102.0M(102.0M)->0.0B(89.0M) Survivors: 0.0B->13.0M Heap: 102.0M(2048.0M)->97.2M(2048.0M)]
 [Times: user=0.05 sys=0.14, real=0.07 secs] 
2017-07-27T10:49:24.362-0800: 0.775: [GC pause (G1 Evacuation Pause) (young), 0.0616274 secs]
   [Parallel Time: 60.9 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 775.6, Avg: 775.7, Max: 775.8, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.4, Max: 0.6, Diff: 0.3, Sum: 1.4]
      [Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1]
         [Processed Buffers: Min: 0, Avg: 0.2, Max: 1, Diff: 1, Sum: 1]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [Object Copy (ms): Min: 60.1, Avg: 60.2, Max: 60.4, Diff: 0.3, Sum: 240.9]
      [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 0.5]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 60.7, Avg: 60.8, Max: 60.9, Diff: 0.1, Sum: 243.2]
      [GC Worker End (ms): Min: 836.5, Avg: 836.5, Max: 836.5, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.6 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 89.0M(89.0M)->0.0B(89.0M) Survivors: 13.0M->13.0M Heap: 186.2M(2048.0M)->186.8M(2048.0M)]
 [Times: user=0.05 sys=0.14, real=0.06 secs] 
2017-07-27T10:49:24.660-0800: 1.073: [GC pause (G1 Evacuation Pause) (young), 0.0609914 secs]
   [Parallel Time: 60.4 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 1073.3, Avg: 1073.4, Max: 1073.4, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.1, Avg: 0.2, Max: 0.2, Diff: 0.1, Sum: 0.8]
      [Update RS (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 0.4]
         [Processed Buffers: Min: 1, Avg: 1.5, Max: 2, Diff: 1, Sum: 6]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 59.7, Avg: 59.9, Max: 60.0, Diff: 0.2, Sum: 239.6]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2]
         [Termination Attempts: Min: 1, Avg: 1.2, Max: 2, Diff: 1, Sum: 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: 60.2, Avg: 60.3, Max: 60.3, Diff: 0.1, Sum: 241.1]
      [GC Worker End (ms): Min: 1133.6, Avg: 1133.6, Max: 1133.6, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.5 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.0 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 89.0M(89.0M)->0.0B(89.0M) Survivors: 13.0M->13.0M Heap: 275.8M(2048.0M)->273.2M(2048.0M)]
 [Times: user=0.05 sys=0.14, real=0.06 secs] 
2017-07-27T10:49:24.960-0800: 1.373: [GC pause (G1 Evacuation Pause) (young), 0.0613928 secs]
   [Parallel Time: 60.8 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 1373.5, Avg: 1373.6, Max: 1373.6, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.3, Max: 0.4, Diff: 0.1, Sum: 1.2]
      [Update RS (ms): Min: 0.1, Avg: 0.2, Max: 0.2, Diff: 0.1, Sum: 0.7]
         [Processed Buffers: Min: 1, Avg: 1.8, Max: 3, Diff: 2, Sum: 7]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.0, Sum: 0.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 60.0, Avg: 60.1, Max: 60.2, Diff: 0.1, Sum: 240.4]
      [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.2]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 60.6, Avg: 60.7, Max: 60.8, Diff: 0.1, Sum: 242.7]
      [GC Worker End (ms): Min: 1434.3, Avg: 1434.3, Max: 1434.3, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.5 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.0 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 89.0M(89.0M)->0.0B(89.0M) Survivors: 13.0M->13.0M Heap: 362.2M(2048.0M)->360.8M(2048.0M)]
 [Times: user=0.05 sys=0.14, real=0.06 secs] 
2017-07-27T10:49:25.260-0800: 1.674: [GC pause (G1 Evacuation Pause) (young), 0.0692297 secs]
   [Parallel Time: 68.6 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 1673.9, Avg: 1674.0, Max: 1674.0, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.3, Avg: 0.3, Max: 0.3, Diff: 0.1, Sum: 1.2]
      [Update RS (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.3]
         [Processed Buffers: Min: 1, Avg: 1.8, Max: 3, Diff: 2, Sum: 7]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.0, Sum: 0.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 67.8, Avg: 67.9, Max: 68.0, Diff: 0.2, Sum: 271.7]
      [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.3]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 68.4, Avg: 68.5, Max: 68.5, Diff: 0.1, Sum: 273.8]
      [GC Worker End (ms): Min: 1742.4, Avg: 1742.4, Max: 1742.4, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.6 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 89.0M(89.0M)->0.0B(89.0M) Survivors: 13.0M->13.0M Heap: 449.8M(2048.0M)->448.2M(2048.0M)]
 [Times: user=0.06 sys=0.16, real=0.07 secs] 
2017-07-27T10:49:25.560-0800: 1.974: [GC pause (G1 Evacuation Pause) (young), 0.0638776 secs]
   [Parallel Time: 63.1 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 1974.1, Avg: 1974.1, Max: 1974.3, Diff: 0.2]
      [Ext Root Scanning (ms): Min: 0.1, Avg: 0.3, Max: 0.3, Diff: 0.2, Sum: 1.1]
      [Update RS (ms): Min: 0.3, Avg: 1.0, Max: 2.4, Diff: 2.1, Sum: 4.0]
         [Processed Buffers: Min: 1, Avg: 1.8, Max: 3, Diff: 2, Sum: 7]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 60.0, Avg: 61.5, Max: 62.2, Diff: 2.2, Sum: 246.1]
      [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 0.4]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 62.8, Avg: 63.0, Max: 63.1, Diff: 0.2, Sum: 251.9]
      [GC Worker End (ms): Min: 2037.1, Avg: 2037.1, Max: 2037.1, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.6 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 89.0M(89.0M)->0.0B(89.0M) Survivors: 13.0M->13.0M Heap: 537.2M(2048.0M)->537.8M(2048.0M)]
 [Times: user=0.06 sys=0.13, real=0.06 secs] 
2017-07-27T10:49:25.858-0800: 2.271: [GC pause (G1 Evacuation Pause) (young), 0.0623549 secs]
   [Parallel Time: 61.8 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 2271.4, Avg: 2271.5, Max: 2271.5, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.3, Avg: 0.3, Max: 0.3, Diff: 0.1, Sum: 1.2]
      [Update RS (ms): Min: 0.2, Avg: 0.2, Max: 0.2, Diff: 0.0, Sum: 0.7]
         [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.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 60.8, Avg: 61.0, Max: 61.1, Diff: 0.3, Sum: 244.0]
      [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.4, Diff: 0.4, Sum: 0.5]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.3]
      [GC Worker Total (ms): Min: 61.7, Avg: 61.7, Max: 61.7, Diff: 0.1, Sum: 246.7]
      [GC Worker End (ms): Min: 2333.1, Avg: 2333.1, Max: 2333.1, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.5 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.0 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 89.0M(89.0M)->0.0B(89.0M) Survivors: 13.0M->13.0M Heap: 626.8M(2048.0M)->627.6M(2048.0M)]
 [Times: user=0.06 sys=0.16, real=0.07 secs] 
2017-07-27T10:49:26.160-0800: 2.573: [GC pause (G1 Evacuation Pause) (young), 0.0622202 secs]
   [Parallel Time: 61.7 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 2573.4, Avg: 2573.5, Max: 2573.7, Diff: 0.3]
      [Ext Root Scanning (ms): Min: 0.0, Avg: 0.2, Max: 0.2, Diff: 0.2, Sum: 0.7]
      [Update RS (ms): Min: 0.2, Avg: 0.6, Max: 1.2, Diff: 1.0, Sum: 2.4]
         [Processed Buffers: Min: 1, Avg: 1.8, Max: 4, Diff: 3, Sum: 7]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 60.1, Avg: 60.6, Max: 60.9, Diff: 0.8, Sum: 242.4]
      [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.5]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.0, Sum: 0.2]
      [GC Worker Total (ms): Min: 61.4, Avg: 61.6, Max: 61.6, Diff: 0.3, Sum: 246.3]
      [GC Worker End (ms): Min: 2635.1, Avg: 2635.1, Max: 2635.1, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.5 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 89.0M(89.0M)->0.0B(89.0M) Survivors: 13.0M->13.0M Heap: 716.6M(2048.0M)->713.6M(2048.0M)]
 [Times: user=0.05 sys=0.13, real=0.06 secs] 
2017-07-27T10:49:26.457-0800: 2.870: [GC pause (G1 Evacuation Pause) (young), 0.0656710 secs]
   [Parallel Time: 65.1 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 2870.1, Avg: 2870.2, Max: 2870.5, Diff: 0.4]
      [Ext Root Scanning (ms): Min: 0.0, Avg: 0.2, Max: 0.4, Diff: 0.4, Sum: 1.0]
      [Update RS (ms): Min: 0.1, Avg: 0.1, Max: 0.2, Diff: 0.0, Sum: 0.6]
         [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.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 64.2, Avg: 64.4, Max: 64.5, Diff: 0.2, Sum: 257.6]
      [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.3]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 64.7, Avg: 64.9, Max: 65.1, Diff: 0.4, Sum: 259.7]
      [GC Worker End (ms): Min: 2935.1, Avg: 2935.1, Max: 2935.1, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.5 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.0 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 89.0M(89.0M)->0.0B(89.0M) Survivors: 13.0M->13.0M Heap: 802.6M(2048.0M)->801.2M(2048.0M)]
 [Times: user=0.05 sys=0.16, real=0.07 secs] 
2017-07-27T10:49:26.760-0800: 3.174: [GC pause (G1 Evacuation Pause) (young), 0.0665174 secs]
   [Parallel Time: 66.0 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 3173.7, Avg: 3173.7, Max: 3173.8, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.2, Max: 0.3, Diff: 0.1, Sum: 0.9]
      [Update RS (ms): Min: 0.1, Avg: 0.1, Max: 0.2, Diff: 0.1, Sum: 0.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.1, Diff: 0.1, Sum: 0.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 65.1, Avg: 65.3, Max: 65.5, Diff: 0.3, Sum: 261.4]
      [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 0.5]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.0, Sum: 0.2]
      [GC Worker Total (ms): Min: 65.9, Avg: 65.9, Max: 65.9, Diff: 0.1, Sum: 263.6]
      [GC Worker End (ms): Min: 3239.6, Avg: 3239.6, Max: 3239.6, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.5 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.0 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 89.0M(89.0M)->0.0B(95.0M) Survivors: 13.0M->13.0M Heap: 890.2M(2048.0M)->890.6M(2048.0M)]
 [Times: user=0.05 sys=0.14, real=0.06 secs] 
2017-07-27T10:49:27.060-0800: 3.473: [GC pause (G1 Evacuation Pause) (young), 0.0703310 secs]
   [Parallel Time: 69.8 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 3473.3, Avg: 3473.4, Max: 3473.4, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.3, Max: 0.3, Diff: 0.1, Sum: 1.0]
      [Update RS (ms): Min: 0.1, Avg: 0.2, Max: 0.2, Diff: 0.1, Sum: 0.7]
         [Processed Buffers: Min: 1, Avg: 1.8, Max: 2, Diff: 1, Sum: 7]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 68.9, Avg: 69.0, Max: 69.1, Diff: 0.2, Sum: 276.0]
      [Termination (ms): Min: 0.0, Avg: 0.2, Max: 0.3, Diff: 0.3, Sum: 0.7]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 69.6, Avg: 69.6, Max: 69.7, Diff: 0.1, Sum: 278.6]
      [GC Worker End (ms): Min: 3543.0, Avg: 3543.0, Max: 3543.0, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.5 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 95.0M(95.0M)->0.0B(111.0M) Survivors: 13.0M->14.0M Heap: 985.6M(2048.0M)->981.2M(2048.0M)]
 [Times: user=0.05 sys=0.13, real=0.07 secs] 
2017-07-27T10:49:27.365-0800: 3.779: [GC pause (G1 Evacuation Pause) (young) (initial-mark), 0.0800282 secs]
   [Parallel Time: 79.2 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 3778.9, Avg: 3779.0, Max: 3779.0, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.3, Max: 0.4, Diff: 0.1, Sum: 1.2]
      [Update RS (ms): Min: 0.1, Avg: 0.1, Max: 0.2, Diff: 0.1, Sum: 0.6]
         [Processed Buffers: Min: 1, Avg: 1.8, Max: 2, Diff: 1, Sum: 7]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 78.4, Avg: 78.5, Max: 78.6, Diff: 0.2, Sum: 313.9]
      [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.5]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 79.0, Avg: 79.1, Max: 79.2, Diff: 0.1, Sum: 316.3]
      [GC Worker End (ms): Min: 3858.0, Avg: 3858.0, Max: 3858.0, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.7 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.2 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.0 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 111.0M(111.0M)->0.0B(124.0M) Survivors: 14.0M->16.0M Heap: 1092.2M(2048.0M)->1090.4M(2048.0M)]
 [Times: user=0.06 sys=0.17, real=0.08 secs] 
2017-07-27T10:49:27.446-0800: 3.859: [GC concurrent-root-region-scan-start]
2017-07-27T10:49:27.446-0800: 3.859: [GC concurrent-root-region-scan-end, 0.0004284 secs]
2017-07-27T10:49:27.446-0800: 3.859: [GC concurrent-mark-start]
2017-07-27T10:49:27.470-0800: 3.884: [GC concurrent-mark-end, 0.0242591 secs]
2017-07-27T10:49:27.470-0800: 3.884: [GC remark 2017-07-27T10:49:27.470-0800: 3.884: [Finalize Marking, 0.0001205 secs] 2017-07-27T10:49:27.471-0800: 3.884: [GC ref-proc, 0.0000482 secs] 2017-07-27T10:49:27.471-0800: 3.884: [Unloading, 0.0013804 secs], 0.0059468 secs]
 [Times: user=0.01 sys=0.00, real=0.00 secs] 
2017-07-27T10:49:27.477-0800: 3.890: [GC cleanup 1124M->1124M(2048M), 0.0020698 secs]
 [Times: user=0.01 sys=0.01, real=0.00 secs] 
2017-07-27T10:49:27.764-0800: 4.177: [GC pause (G1 Evacuation Pause) (young), 0.0921526 secs]
   [Parallel Time: 91.5 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 4177.6, Avg: 4177.7, Max: 4177.7, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.3, Max: 0.3, Diff: 0.1, Sum: 1.0]
      [Update RS (ms): Min: 0.1, Avg: 0.2, Max: 0.2, Diff: 0.1, Sum: 0.7]
         [Processed Buffers: Min: 1, Avg: 1.8, Max: 2, Diff: 1, Sum: 7]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 90.5, Avg: 90.6, Max: 90.8, Diff: 0.3, Sum: 362.6]
      [Termination (ms): Min: 0.0, Avg: 0.2, Max: 0.3, Diff: 0.3, Sum: 0.7]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 91.3, Avg: 91.3, Max: 91.4, Diff: 0.1, Sum: 365.3]
      [GC Worker End (ms): Min: 4269.0, Avg: 4269.0, Max: 4269.0, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.6 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 124.0M(124.0M)->0.0B(84.0M) Survivors: 16.0M->18.0M Heap: 1214.4M(2048.0M)->1212.4M(2048.0M)]
 [Times: user=0.07 sys=0.23, real=0.09 secs] 
2017-07-27T10:49:28.062-0800: 4.475: [GC pause (G1 Evacuation Pause) (mixed), 0.1208332 secs]
   [Parallel Time: 120.0 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 4475.3, Avg: 4475.3, Max: 4475.4, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.3, Max: 0.3, Diff: 0.1, Sum: 1.1]
      [Update RS (ms): Min: 0.2, Avg: 0.7, Max: 1.2, Diff: 1.0, Sum: 2.7]
         [Processed Buffers: Min: 1, Avg: 1.8, Max: 3, Diff: 2, Sum: 7]
      [Scan RS (ms): Min: 0.0, Avg: 0.2, Max: 0.5, Diff: 0.5, Sum: 0.7]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 118.3, Avg: 118.6, Max: 119.0, Diff: 0.8, Sum: 474.4]
      [Termination (ms): Min: 0.0, Avg: 0.2, Max: 0.4, Diff: 0.4, Sum: 0.6]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 119.9, Avg: 119.9, Max: 119.9, Diff: 0.1, Sum: 479.6]
      [GC Worker End (ms): Min: 4595.2, Avg: 4595.2, Max: 4595.3, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.7 ms]
      [Choose CSet: 0.1 ms]
      [Ref Proc: 0.2 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 84.0M(84.0M)->0.0B(89.0M) Survivors: 18.0M->13.0M Heap: 1296.4M(2048.0M)->1294.4M(2048.0M)]
 [Times: user=0.09 sys=0.29, real=0.12 secs] 
2017-07-27T10:49:28.363-0800: 4.777: [GC pause (G1 Evacuation Pause) (mixed), 0.0645630 secs]
   [Parallel Time: 63.9 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 4776.9, Avg: 4777.0, Max: 4777.0, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.3, Max: 0.3, Diff: 0.1, Sum: 1.0]
      [Update RS (ms): Min: 0.4, Avg: 0.5, Max: 0.7, Diff: 0.3, Sum: 2.0]
         [Processed Buffers: Min: 1, Avg: 1.8, Max: 4, Diff: 3, Sum: 7]
      [Scan RS (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.3]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 62.5, Avg: 62.7, Max: 62.9, Diff: 0.4, Sum: 250.8]
      [Termination (ms): Min: 0.0, Avg: 0.2, Max: 0.4, Diff: 0.4, Sum: 0.8]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 63.7, Avg: 63.7, Max: 63.8, Diff: 0.1, Sum: 255.0]
      [GC Worker End (ms): Min: 4840.7, Avg: 4840.7, Max: 4840.8, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.6 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 89.0M(89.0M)->0.0B(181.0M) Survivors: 13.0M->13.0M Heap: 1383.4M(2048.0M)->1383.2M(2048.0M)]
 [Times: user=0.11 sys=0.09, real=0.06 secs] 
2017-07-27T10:49:28.974-0800: 5.387: [GC pause (G1 Evacuation Pause) (young), 0.1047674 secs]
   [Parallel Time: 104.2 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 5387.6, Avg: 5387.6, Max: 5387.6, Diff: 0.0]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.2, Max: 0.2, Diff: 0.0, Sum: 0.8]
      [Update RS (ms): Min: 0.4, Avg: 0.5, Max: 0.5, Diff: 0.1, Sum: 1.8]
         [Processed Buffers: Min: 1, Avg: 2.0, Max: 3, Diff: 2, Sum: 8]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 103.2, Avg: 103.3, Max: 103.4, Diff: 0.2, Sum: 413.1]
      [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.4]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 104.1, Avg: 104.1, Max: 104.1, Diff: 0.0, Sum: 416.4]
      [GC Worker End (ms): Min: 5491.7, Avg: 5491.7, Max: 5491.7, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.5 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 181.0M(181.0M)->0.0B(150.0M) Survivors: 13.0M->25.0M Heap: 1564.2M(2048.0M)->1557.6M(2048.0M)]
 [Times: user=0.11 sys=0.24, real=0.10 secs] 
2017-07-27T10:49:29.463-0800: 5.876: [GC pause (G1 Evacuation Pause) (young) (initial-mark), 0.1032404 secs]
   [Parallel Time: 102.6 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 5876.6, Avg: 5876.6, Max: 5876.7, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.3, Max: 0.3, Diff: 0.1, Sum: 1.2]
      [Update RS (ms): Min: 0.2, Avg: 0.2, Max: 0.3, Diff: 0.1, Sum: 0.9]
         [Processed Buffers: Min: 1, Avg: 1.8, Max: 2, Diff: 1, Sum: 7]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 101.7, Avg: 101.9, Max: 101.9, Diff: 0.2, Sum: 407.4]
      [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.4]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 102.5, Avg: 102.5, Max: 102.6, Diff: 0.1, Sum: 410.1]
      [GC Worker End (ms): Min: 5979.1, Avg: 5979.2, Max: 5979.2, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.5 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.0 ms]
      [Humongous Register: 0.0 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 150.0M(150.0M)->0.0B(80.0M) Survivors: 25.0M->22.0M Heap: 1707.6M(2048.0M)->1705.8M(2048.0M)]
 [Times: user=0.08 sys=0.24, real=0.10 secs] 
2017-07-27T10:49:29.566-0800: 5.980: [GC concurrent-root-region-scan-start]
2017-07-27T10:49:29.567-0800: 5.980: [GC concurrent-root-region-scan-end, 0.0004796 secs]
2017-07-27T10:49:29.567-0800: 5.980: [GC concurrent-mark-start]
2017-07-27T10:49:29.579-0800: 5.992: [GC concurrent-mark-end, 0.0116904 secs]
2017-07-27T10:49:29.579-0800: 5.992: [GC remark 2017-07-27T10:49:29.579-0800: 5.992: [Finalize Marking, 0.0028278 secs] 2017-07-27T10:49:29.582-0800: 5.995: [GC ref-proc, 0.0000396 secs] 2017-07-27T10:49:29.582-0800: 5.995: [Unloading, 0.0007630 secs], 0.0095199 secs]
 [Times: user=0.03 sys=0.01, real=0.01 secs] 
2017-07-27T10:49:29.588-0800: 6.002: [GC cleanup 1748M->1643M(2048M), 0.0020291 secs]
 [Times: user=0.01 sys=0.00, real=0.01 secs] 
2017-07-27T10:49:29.591-0800: 6.004: [GC concurrent-cleanup-start]
2017-07-27T10:49:29.591-0800: 6.004: [GC concurrent-cleanup-end, 0.0000965 secs]
2017-07-27T10:49:29.760-0800: 6.173: [GC pause (G1 Evacuation Pause) (young), 0.0323715 secs]
   [Parallel Time: 31.8 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 6173.6, Avg: 6173.7, Max: 6173.9, Diff: 0.3]
      [Ext Root Scanning (ms): Min: 0.0, Avg: 0.2, Max: 0.2, Diff: 0.2, Sum: 0.7]
      [Update RS (ms): Min: 0.2, Avg: 0.8, Max: 2.3, Diff: 2.1, Sum: 3.2]
         [Processed Buffers: Min: 0, Avg: 1.8, Max: 4, Diff: 4, Sum: 7]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 28.9, Avg: 30.5, Max: 31.0, Diff: 2.1, Sum: 121.8]
      [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.6]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 31.4, Avg: 31.6, Max: 31.7, Diff: 0.4, Sum: 126.6]
      [GC Worker End (ms): Min: 6205.3, Avg: 6205.3, Max: 6205.4, Diff: 0.1]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.5 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.2 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.0 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 80.0M(80.0M)->0.0B(89.0M) Survivors: 22.0M->13.0M Heap: 1680.8M(2048.0M)->1678.2M(2048.0M)]
 [Times: user=0.06 sys=0.01, real=0.03 secs] 
2017-07-27T10:49:30.061-0800: 6.474: [GC pause (G1 Evacuation Pause) (mixed), 0.1108168 secs]
   [Parallel Time: 110.0 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 6474.4, Avg: 6474.5, Max: 6474.5, Diff: 0.2]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.3, Max: 0.4, Diff: 0.2, Sum: 1.2]
      [Update RS (ms): Min: 0.1, Avg: 0.1, Max: 0.1, Diff: 0.0, Sum: 0.5]
         [Processed Buffers: Min: 1, Avg: 1.8, Max: 3, Diff: 2, Sum: 7]
      [Scan RS (ms): Min: 0.1, Avg: 0.2, Max: 0.2, Diff: 0.1, Sum: 0.7]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [Object Copy (ms): Min: 108.9, Avg: 109.1, Max: 109.2, Diff: 0.3, Sum: 436.2]
      [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 0.6]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 109.8, Avg: 109.8, Max: 109.9, Diff: 0.1, Sum: 439.2]
      [GC Worker End (ms): Min: 6584.3, Avg: 6584.3, Max: 6584.3, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.7 ms]
      [Choose CSet: 0.1 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.0 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.2 ms]
   [Eden: 89.0M(89.0M)->0.0B(89.0M) Survivors: 13.0M->13.0M Heap: 1767.2M(2048.0M)->1668.2M(2048.0M)]
 [Times: user=0.09 sys=0.24, real=0.11 secs] 
2017-07-27T10:49:30.360-0800: 6.773: [GC pause (G1 Evacuation Pause) (mixed), 0.0658799 secs]
   [Parallel Time: 65.0 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 6773.0, Avg: 6773.0, Max: 6773.1, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.2, Max: 0.3, Diff: 0.1, Sum: 0.8]
      [Update RS (ms): Min: 0.3, Avg: 0.6, Max: 0.8, Diff: 0.5, Sum: 2.3]
         [Processed Buffers: Min: 1, Avg: 2.2, Max: 5, Diff: 4, Sum: 9]
      [Scan RS (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.3]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 63.6, Avg: 63.9, Max: 64.1, Diff: 0.5, Sum: 255.5]
      [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 0.6]
         [Termination Attempts: Min: 1, Avg: 1.2, Max: 2, Diff: 1, Sum: 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: 64.8, Avg: 64.9, Max: 64.9, Diff: 0.1, Sum: 259.5]
      [GC Worker End (ms): Min: 6837.9, Avg: 6837.9, Max: 6837.9, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.2 ms]
   [Other: 0.7 ms]
      [Choose CSet: 0.1 ms]
      [Ref Proc: 0.2 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.2 ms]
   [Eden: 89.0M(89.0M)->0.0B(89.0M) Survivors: 13.0M->13.0M Heap: 1757.2M(2048.0M)->1753.8M(2048.0M)]
 [Times: user=0.22 sys=0.02, real=0.06 secs] 
2017-07-27T10:49:30.659-0800: 7.072: [GC pause (G1 Evacuation Pause) (mixed) (to-space exhausted), 0.0531266 secs]
   [Parallel Time: 52.1 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 7072.3, Avg: 7072.3, Max: 7072.4, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.3, Avg: 0.3, Max: 0.4, Diff: 0.1, Sum: 1.2]
      [Update RS (ms): Min: 0.3, Avg: 0.4, Max: 0.6, Diff: 0.3, Sum: 1.7]
         [Processed Buffers: Min: 1, Avg: 1.8, Max: 2, Diff: 1, Sum: 7]
      [Scan RS (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.6]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 50.8, Avg: 50.9, Max: 50.9, Diff: 0.1, Sum: 203.6]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.0, Sum: 0.2]
      [GC Worker Total (ms): Min: 51.8, Avg: 51.9, Max: 51.9, Diff: 0.1, Sum: 207.4]
      [GC Worker End (ms): Min: 7124.2, Avg: 7124.2, Max: 7124.2, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.9 ms]
      [Evacuation Failure: 0.2 ms]
      [Choose CSet: 0.1 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.2 ms]
   [Eden: 89.0M(89.0M)->0.0B(89.0M) Survivors: 13.0M->13.0M Heap: 1842.8M(2048.0M)->1843.0M(2048.0M)]
 [Times: user=0.20 sys=0.00, real=0.06 secs] 
2017-07-27T10:49:30.960-0800: 7.373: [GC pause (G1 Evacuation Pause) (young), 0.0275818 secs]
   [Parallel Time: 27.1 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 7373.6, Avg: 7373.7, Max: 7373.7, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.3, Max: 0.3, Diff: 0.1, Sum: 1.0]
      [Update RS (ms): Min: 0.4, Avg: 0.4, Max: 0.5, Diff: 0.1, Sum: 1.8]
         [Processed Buffers: Min: 2, Avg: 2.8, Max: 5, Diff: 3, Sum: 11]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 26.1, Avg: 26.1, Max: 26.2, Diff: 0.1, Sum: 104.5]
      [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.4]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 26.9, Avg: 27.0, Max: 27.0, Diff: 0.1, Sum: 107.9]
      [GC Worker End (ms): Min: 7400.6, Avg: 7400.7, Max: 7400.7, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.4 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.0 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 89.0M(89.0M)->0.0B(89.0M) Survivors: 13.0M->13.0M Heap: 1932.0M(2048.0M)->1929.2M(2048.0M)]
 [Times: user=0.09 sys=0.01, real=0.02 secs] 
2017-07-27T10:49:31.163-0800: 7.576: [GC pause (G1 Evacuation Pause) (young) (initial-mark) (to-space exhausted), 0.0101238 secs]
   [Parallel Time: 9.3 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 7576.1, Avg: 7576.1, Max: 7576.2, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.3, Max: 0.3, Diff: 0.1, Sum: 1.2]
      [Update RS (ms): Min: 0.1, Avg: 0.2, Max: 0.2, Diff: 0.2, Sum: 0.6]
         [Processed Buffers: Min: 1, Avg: 1.8, Max: 2, Diff: 1, Sum: 7]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 8.6, Avg: 8.7, Max: 8.7, Diff: 0.1, Sum: 34.8]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 9.2, Avg: 9.2, Max: 9.3, Diff: 0.1, Sum: 36.9]
      [GC Worker End (ms): Min: 7585.3, Avg: 7585.3, Max: 7585.3, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.8 ms]
      [Evacuation Failure: 0.3 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.0 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 89.0M(89.0M)->0.0B(89.0M) Survivors: 13.0M->13.0M Heap: 2018.2M(2048.0M)->2026.0M(2048.0M)]
 [Times: user=0.03 sys=0.00, real=0.01 secs] 
2017-07-27T10:49:31.173-0800: 7.586: [GC concurrent-root-region-scan-start]
2017-07-27T10:49:31.173-0800: 7.586: [GC concurrent-root-region-scan-end, 0.0002680 secs]
2017-07-27T10:49:31.173-0800: 7.586: [GC concurrent-mark-start]
2017-07-27T10:49:31.184-0800: 7.598: [GC concurrent-mark-end, 0.0111974 secs]
2017-07-27T10:49:31.185-0800: 7.598: [GC remark 2017-07-27T10:49:31.185-0800: 7.598: [Finalize Marking, 0.0001333 secs] 2017-07-27T10:49:31.185-0800: 7.598: [GC ref-proc, 0.0000359 secs] 2017-07-27T10:49:31.185-0800: 7.598: [Unloading, 0.0007805 secs], 0.0070028 secs]
 [Times: user=0.03 sys=0.00, real=0.01 secs] 
2017-07-27T10:49:31.192-0800: 7.605: [GC cleanup 2028M->1861M(2048M), 0.0022019 secs]
 [Times: user=0.01 sys=0.00, real=0.00 secs] 
2017-07-27T10:49:31.194-0800: 7.607: [GC concurrent-cleanup-start]
2017-07-27T10:49:31.194-0800: 7.607: [GC concurrent-cleanup-end, 0.0001394 secs]
2017-07-27T10:49:31.465-0800: 7.878: [GC pause (G1 Evacuation Pause) (young), 0.0281434 secs]
   [Parallel Time: 27.7 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 7878.3, Avg: 7878.4, Max: 7878.4, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.2, Max: 0.3, Diff: 0.1, Sum: 0.9]
      [Update RS (ms): Min: 0.1, Avg: 0.2, Max: 0.2, Diff: 0.1, Sum: 0.6]
         [Processed Buffers: Min: 1, Avg: 2.8, Max: 4, Diff: 3, Sum: 11]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 27.0, Avg: 27.0, Max: 27.1, Diff: 0.1, Sum: 108.2]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2]
      [GC Worker Total (ms): Min: 27.5, Avg: 27.5, Max: 27.6, Diff: 0.1, Sum: 110.1]
      [GC Worker End (ms): Min: 7905.9, Avg: 7905.9, Max: 7905.9, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.4 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.0 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 89.0M(89.0M)->0.0B(89.0M) Survivors: 13.0M->13.0M Heap: 1948.0M(2048.0M)->1945.4M(2048.0M)]
 [Times: user=0.10 sys=0.01, real=0.03 secs] 
2017-07-27T10:49:31.765-0800: 8.178: [GC pause (G1 Evacuation Pause) (mixed) (to-space exhausted), 0.0075908 secs]
   [Parallel Time: 6.1 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 8178.5, Avg: 8178.5, Max: 8178.6, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.3, Max: 0.3, Diff: 0.1, Sum: 1.1]
      [Update RS (ms): Min: 0.1, Avg: 0.2, Max: 0.2, Diff: 0.1, Sum: 0.6]
         [Processed Buffers: Min: 1, Avg: 1.8, Max: 2, Diff: 1, Sum: 7]
      [Scan RS (ms): Min: 0.2, Avg: 0.2, Max: 0.2, Diff: 0.1, Sum: 0.8]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 5.3, Avg: 5.3, Max: 5.3, Diff: 0.0, Sum: 21.1]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 5.9, Avg: 6.0, Max: 6.0, Diff: 0.1, Sum: 23.9]
      [GC Worker End (ms): Min: 8184.5, Avg: 8184.5, Max: 8184.5, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 1.4 ms]
      [Evacuation Failure: 0.8 ms]
      [Choose CSet: 0.2 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 89.0M(89.0M)->0.0B(101.0M) Survivors: 13.0M->1024.0K Heap: 2034.4M(2048.0M)->2007.0M(2048.0M)]
 [Times: user=0.02 sys=0.01, real=0.01 secs] 
2017-07-27T10:49:31.960-0800: 8.373: [GC pause (G1 Evacuation Pause) (mixed) (to-space exhausted), 0.0045469 secs]
   [Parallel Time: 3.0 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 8373.4, Avg: 8373.5, Max: 8373.5, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.3, Avg: 0.3, Max: 0.4, Diff: 0.1, Sum: 1.3]
      [Update RS (ms): Min: 0.4, Avg: 0.5, Max: 0.6, Diff: 0.2, Sum: 1.9]
         [Processed Buffers: Min: 1, Avg: 2.8, Max: 6, Diff: 5, Sum: 11]
      [Scan RS (ms): Min: 0.1, Avg: 0.2, Max: 0.3, Diff: 0.2, Sum: 0.7]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 1.8, Avg: 1.8, Max: 1.8, Diff: 0.0, Sum: 7.1]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 2.7, Avg: 2.8, Max: 2.9, Diff: 0.1, Sum: 11.2]
      [GC Worker End (ms): Min: 8376.3, Avg: 8376.3, Max: 8376.3, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 1.5 ms]
      [Evacuation Failure: 0.9 ms]
      [Choose CSet: 0.2 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 41.0M(101.0M)->0.0B(102.0M) Survivors: 1024.0K->0.0B Heap: 2048.0M(2048.0M)->2042.0M(2048.0M)]
 [Times: user=0.01 sys=0.00, real=0.00 secs] 
2017-07-27T10:49:31.966-0800: 8.379: [GC pause (G1 Evacuation Pause) (mixed) (to-space exhausted), 0.0048540 secs]
   [Parallel Time: 3.5 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 8379.4, Avg: 8379.5, Max: 8379.5, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.3, Max: 0.3, Diff: 0.1, Sum: 1.1]
      [Update RS (ms): Min: 0.5, Avg: 0.8, Max: 1.7, Diff: 1.2, Sum: 3.4]
         [Processed Buffers: Min: 1, Avg: 3.2, Max: 5, Diff: 4, Sum: 13]
      [Scan RS (ms): Min: 0.0, Avg: 0.2, Max: 0.4, Diff: 0.4, Sum: 1.0]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 1.4, Avg: 2.1, Max: 2.3, Diff: 0.9, Sum: 8.2]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 3.4, Avg: 3.4, Max: 3.5, Diff: 0.1, Sum: 13.8]
      [GC Worker End (ms): Min: 8382.9, Avg: 8382.9, Max: 8382.9, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 1.2 ms]
      [Evacuation Failure: 0.7 ms]
      [Choose CSet: 0.1 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 6144.0K(102.0M)->0.0B(102.0M) Survivors: 0.0B->0.0B Heap: 2048.0M(2048.0M)->2026.0M(2048.0M)]
 [Times: user=0.01 sys=0.00, real=0.01 secs] 
2017-07-27T10:49:32.061-0800: 8.474: [GC pause (G1 Evacuation Pause) (mixed) (to-space exhausted), 0.0027795 secs]
   [Parallel Time: 1.9 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 8473.9, Avg: 8474.0, Max: 8474.0, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.3, Max: 0.3, Diff: 0.1, Sum: 1.1]
      [Update RS (ms): Min: 0.8, Avg: 0.8, Max: 0.8, Diff: 0.0, Sum: 3.3]
         [Processed Buffers: Min: 1, Avg: 3.5, Max: 6, Diff: 5, Sum: 14]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.2]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 0.6, Avg: 0.6, Max: 0.6, Diff: 0.0, Sum: 2.3]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 1.7, Avg: 1.7, Max: 1.8, Diff: 0.1, Sum: 6.9]
      [GC Worker End (ms): Min: 8475.7, Avg: 8475.7, Max: 8475.7, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.8 ms]
      [Evacuation Failure: 0.3 ms]
      [Choose CSet: 0.1 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.0 ms]
   [Eden: 22.0M(102.0M)->0.0B(102.0M) Survivors: 0.0B->0.0B Heap: 2048.0M(2048.0M)->2048.0M(2048.0M)]
 [Times: user=0.00 sys=0.00, real=0.00 secs] 
2017-07-27T10:49:32.064-0800: 8.477: [Full GC (Allocation Failure)  2048M->1417M(2048M), 0.6236285 secs]
   [Eden: 0.0B(102.0M)->0.0B(126.0M) Survivors: 0.0B->0.0B Heap: 2048.0M(2048.0M)->1417.4M(2048.0M)], [Metaspace: 3144K->3144K(1056768K)]
 [Times: user=0.57 sys=0.05, real=0.62 secs] 
2017-07-27T10:49:32.709-0800: 9.122: [GC pause (G1 Evacuation Pause) (young), 0.0347160 secs]
   [Parallel Time: 34.1 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 9122.7, Avg: 9122.7, Max: 9122.7, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.2, Max: 0.2, Diff: 0.1, Sum: 0.8]
      [Update RS (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 0.5]
         [Processed Buffers: Min: 0, Avg: 0.8, Max: 1, Diff: 1, Sum: 3]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 33.4, Avg: 33.6, Max: 33.7, Diff: 0.4, Sum: 134.4]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.0, Sum: 0.2]
      [GC Worker Total (ms): Min: 34.0, Avg: 34.1, Max: 34.1, Diff: 0.1, Sum: 136.2]
      [GC Worker End (ms): Min: 9156.7, Avg: 9156.8, Max: 9156.8, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.5 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.0 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 126.0M(126.0M)->0.0B(86.0M) Survivors: 0.0B->16.0M Heap: 1543.4M(2048.0M)->1538.0M(2048.0M)]
 [Times: user=0.11 sys=0.01, real=0.04 secs] 
2017-07-27T10:49:32.760-0800: 9.173: [GC pause (G1 Evacuation Pause) (young) (initial-mark), 0.0270709 secs]
   [Parallel Time: 26.4 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 9173.2, Avg: 9173.2, Max: 9173.3, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.2, Max: 0.3, Diff: 0.1, Sum: 0.9]
      [Update RS (ms): Min: 0.1, Avg: 0.1, Max: 0.2, Diff: 0.1, Sum: 0.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.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 25.7, Avg: 25.8, Max: 25.9, Diff: 0.2, Sum: 103.3]
      [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.2]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 26.2, Avg: 26.3, Max: 26.3, Diff: 0.1, Sum: 105.1]
      [GC Worker End (ms): Min: 9199.5, Avg: 9199.5, Max: 9199.5, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.6 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.2 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.0 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 86.0M(86.0M)->0.0B(89.0M) Survivors: 16.0M->13.0M Heap: 1624.0M(2048.0M)->1624.8M(2048.0M)]
 [Times: user=0.10 sys=0.00, real=0.02 secs] 
2017-07-27T10:49:32.787-0800: 9.200: [GC concurrent-root-region-scan-start]
2017-07-27T10:49:32.787-0800: 9.200: [GC concurrent-root-region-scan-end, 0.0002178 secs]
2017-07-27T10:49:32.787-0800: 9.200: [GC concurrent-mark-start]
2017-07-27T10:49:32.803-0800: 9.216: [GC concurrent-mark-end, 0.0153044 secs]
2017-07-27T10:49:32.803-0800: 9.216: [GC remark 2017-07-27T10:49:32.803-0800: 9.216: [Finalize Marking, 0.0001841 secs] 2017-07-27T10:49:32.803-0800: 9.216: [GC ref-proc, 0.0000416 secs] 2017-07-27T10:49:32.803-0800: 9.216: [Unloading, 0.0012212 secs], 0.0106623 secs]
 [Times: user=0.03 sys=0.00, real=0.01 secs] 
2017-07-27T10:49:32.814-0800: 9.227: [GC cleanup 1650M->1636M(2048M), 0.0026453 secs]
 [Times: user=0.00 sys=0.00, real=0.00 secs] 
2017-07-27T10:49:32.816-0800: 9.230: [GC concurrent-cleanup-start]
2017-07-27T10:49:32.816-0800: 9.230: [GC concurrent-cleanup-end, 0.0000360 secs]
2017-07-27T10:49:33.058-0800: 9.471: [GC pause (G1 Evacuation Pause) (young), 0.0273954 secs]
   [Parallel Time: 26.6 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 9471.0, Avg: 9471.1, Max: 9471.1, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.3, Avg: 0.3, Max: 0.4, Diff: 0.1, Sum: 1.3]
      [Update RS (ms): Min: 0.1, Avg: 0.2, Max: 0.2, Diff: 0.0, Sum: 0.6]
         [Processed Buffers: Min: 1, Avg: 1.8, Max: 2, Diff: 1, Sum: 7]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.0, Sum: 0.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 25.8, Avg: 25.9, Max: 25.9, Diff: 0.1, Sum: 103.5]
      [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.3]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 26.4, Avg: 26.5, Max: 26.5, Diff: 0.1, Sum: 105.9]
      [GC Worker End (ms): Min: 9497.5, Avg: 9497.5, Max: 9497.5, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.6 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.3 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.0 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 89.0M(89.0M)->0.0B(89.0M) Survivors: 13.0M->13.0M Heap: 1700.2M(2048.0M)->1699.0M(2048.0M)]
 [Times: user=0.10 sys=0.01, real=0.03 secs] 
2017-07-27T10:49:33.359-0800: 9.772: [GC pause (G1 Evacuation Pause) (mixed) (to-space exhausted), 0.0145425 secs]
   [Parallel Time: 13.1 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 9771.8, Avg: 9771.9, Max: 9771.9, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.2, Max: 0.2, Diff: 0.1, Sum: 0.8]
      [Update RS (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.3]
         [Processed Buffers: Min: 1, Avg: 1.8, Max: 3, Diff: 2, Sum: 7]
      [Scan RS (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.4]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 12.6, Avg: 12.6, Max: 12.6, Diff: 0.0, Sum: 50.4]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 13.0, Avg: 13.0, Max: 13.0, Diff: 0.1, Sum: 52.0]
      [GC Worker End (ms): Min: 9784.9, Avg: 9784.9, Max: 9784.9, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 1.3 ms]
      [Evacuation Failure: 0.7 ms]
      [Choose CSet: 0.1 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 89.0M(89.0M)->0.0B(89.0M) Survivors: 13.0M->13.0M Heap: 1788.0M(2048.0M)->1766.5M(2048.0M)]
 [Times: user=0.05 sys=0.00, real=0.02 secs] 
2017-07-27T10:49:33.560-0800: 9.973: [GC pause (G1 Evacuation Pause) (mixed) (to-space exhausted), 0.0041274 secs]
   [Parallel Time: 2.9 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 9972.9, Avg: 9973.0, Max: 9973.1, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.3, Max: 0.3, Diff: 0.1, Sum: 1.0]
      [Update RS (ms): Min: 0.5, Avg: 0.8, Max: 1.1, Diff: 0.5, Sum: 3.4]
         [Processed Buffers: Min: 1, Avg: 3.0, Max: 6, Diff: 5, Sum: 12]
      [Scan RS (ms): Min: 0.0, Avg: 0.1, Max: 0.4, Diff: 0.3, Sum: 0.5]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 1.4, Avg: 1.5, Max: 1.6, Diff: 0.2, Sum: 6.2]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 2.8, Avg: 2.8, Max: 2.9, Diff: 0.1, Sum: 11.3]
      [GC Worker End (ms): Min: 9975.8, Avg: 9975.8, Max: 9975.8, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 1.1 ms]
      [Evacuation Failure: 0.5 ms]
      [Choose CSet: 0.2 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 73.0M(89.0M)->0.0B(102.0M) Survivors: 13.0M->0.0B Heap: 1839.5M(2048.0M)->1830.7M(2048.0M)]
 [Times: user=0.01 sys=0.00, real=0.00 secs] 
2017-07-27T10:49:33.566-0800: 9.979: [GC pause (G1 Evacuation Pause) (mixed) (to-space exhausted), 0.0018067 secs]
   [Parallel Time: 1.1 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 9979.2, Avg: 9979.3, Max: 9979.3, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.2, Max: 0.3, Diff: 0.1, Sum: 0.8]
      [Update RS (ms): Min: 0.4, Avg: 0.5, Max: 0.5, Diff: 0.1, Sum: 1.9]
         [Processed Buffers: Min: 1, Avg: 2.5, Max: 4, Diff: 3, Sum: 10]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 0.2, Avg: 0.2, Max: 0.2, Diff: 0.1, Sum: 0.9]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 0.9, Avg: 1.0, Max: 1.0, Diff: 0.1, Sum: 3.9]
      [GC Worker End (ms): Min: 9980.3, Avg: 9980.3, Max: 9980.3, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.6 ms]
      [Evacuation Failure: 0.2 ms]
      [Choose CSet: 0.1 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.0 ms]
   [Eden: 11.0M(102.0M)->0.0B(102.0M) Survivors: 0.0B->0.0B Heap: 1841.7M(2048.0M)->1816.9M(2048.0M)]
 [Times: user=0.00 sys=0.00, real=0.00 secs] 
2017-07-27T10:49:33.666-0800: 10.079: [GC pause (G1 Evacuation Pause) (young) (to-space exhausted), 0.0016791 secs]
   [Parallel Time: 1.0 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 10078.9, Avg: 10078.9, Max: 10079.0, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.3, Max: 0.3, Diff: 0.1, Sum: 1.1]
      [Update RS (ms): Min: 0.2, Avg: 0.2, Max: 0.2, Diff: 0.0, Sum: 0.8]
         [Processed Buffers: Min: 1, Avg: 2.8, Max: 5, Diff: 4, Sum: 11]
      [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: 0.3, Avg: 0.4, Max: 0.4, Diff: 0.0, Sum: 1.5]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 0.8, Avg: 0.9, Max: 0.9, Diff: 0.1, Sum: 3.6]
      [GC Worker End (ms): Min: 10079.8, Avg: 10079.8, Max: 10079.8, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.6 ms]
      [Evacuation Failure: 0.2 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.0 ms]
   [Eden: 31.0M(102.0M)->0.0B(102.0M) Survivors: 0.0B->0.0B Heap: 1847.9M(2048.0M)->1847.9M(2048.0M)]
 [Times: user=0.00 sys=0.00, real=0.00 secs] 
2017-07-27T10:49:33.668-0800: 10.081: [Full GC (Allocation Failure)  1847M->1500M(2048M), 0.5688421 secs]
   [Eden: 0.0B(102.0M)->0.0B(102.0M) Survivors: 0.0B->0.0B Heap: 1847.9M(2048.0M)->1500.7M(2048.0M)], [Metaspace: 3152K->3152K(1056768K)]
 [Times: user=0.54 sys=0.03, real=0.57 secs] 
2017-07-27T10:49:34.253-0800: 10.666: [GC pause (G1 Evacuation Pause) (young), 0.0267503 secs]
   [Parallel Time: 26.3 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 10666.5, Avg: 10666.5, Max: 10666.6, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.2, Max: 0.2, Diff: 0.1, Sum: 0.8]
      [Update RS (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.4]
         [Processed Buffers: Min: 0, Avg: 0.5, Max: 1, Diff: 1, Sum: 2]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 25.6, Avg: 25.8, Max: 25.9, Diff: 0.3, Sum: 103.0]
      [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.3]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.2]
      [GC Worker Total (ms): Min: 26.1, Avg: 26.2, Max: 26.3, Diff: 0.1, Sum: 104.8]
      [GC Worker End (ms): Min: 10692.7, Avg: 10692.7, Max: 10692.7, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.4 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.0 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 102.0M(102.0M)->0.0B(89.0M) Survivors: 0.0B->13.0M Heap: 1602.7M(2048.0M)->1597.1M(2048.0M)]
 [Times: user=0.09 sys=0.01, real=0.03 secs] 
2017-07-27T10:49:34.358-0800: 10.771: [GC pause (G1 Evacuation Pause) (young) (initial-mark), 0.0278548 secs]
   [Parallel Time: 27.2 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 10771.2, Avg: 10771.3, Max: 10771.5, Diff: 0.3]
      [Ext Root Scanning (ms): Min: 0.0, Avg: 0.3, Max: 0.5, Diff: 0.5, Sum: 1.1]
      [Update RS (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.4]
         [Processed Buffers: Min: 0, Avg: 1.8, Max: 3, Diff: 3, Sum: 7]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.0, Sum: 0.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 26.3, Avg: 26.4, Max: 26.5, Diff: 0.3, Sum: 105.7]
      [Termination (ms): Min: 0.0, Avg: 0.2, Max: 0.3, Diff: 0.3, Sum: 0.6]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 26.8, Avg: 27.0, Max: 27.1, Diff: 0.4, Sum: 108.0]
      [GC Worker End (ms): Min: 10798.3, Avg: 10798.3, Max: 10798.3, Diff: 0.1]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.6 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.2 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.0 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 89.0M(89.0M)->0.0B(89.0M) Survivors: 13.0M->13.0M Heap: 1686.1M(2048.0M)->1685.9M(2048.0M)]
 [Times: user=0.08 sys=0.00, real=0.03 secs] 
2017-07-27T10:49:34.386-0800: 10.799: [GC concurrent-root-region-scan-start]
2017-07-27T10:49:34.386-0800: 10.799: [GC concurrent-root-region-scan-end, 0.0001163 secs]
2017-07-27T10:49:34.386-0800: 10.799: [GC concurrent-mark-start]
2017-07-27T10:49:34.403-0800: 10.816: [GC concurrent-mark-end, 0.0167055 secs]
2017-07-27T10:49:34.403-0800: 10.816: [GC remark 2017-07-27T10:49:34.403-0800: 10.816: [Finalize Marking, 0.0002935 secs] 2017-07-27T10:49:34.403-0800: 10.816: [GC ref-proc, 0.0000448 secs] 2017-07-27T10:49:34.403-0800: 10.816: [Unloading, 0.0015053 secs], 0.0113672 secs]
 [Times: user=0.03 sys=0.00, real=0.01 secs] 
2017-07-27T10:49:34.415-0800: 10.828: [GC cleanup 1711M->1700M(2048M), 0.0036811 secs]
 [Times: user=0.01 sys=0.00, real=0.00 secs] 
2017-07-27T10:49:34.418-0800: 10.831: [GC concurrent-cleanup-start]
2017-07-27T10:49:34.418-0800: 10.831: [GC concurrent-cleanup-end, 0.0000395 secs]
2017-07-27T10:49:34.660-0800: 11.073: [GC pause (G1 Evacuation Pause) (young) (to-space exhausted), 0.0188601 secs]
   [Parallel Time: 17.7 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 11072.8, Avg: 11072.8, Max: 11072.8, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.3, Max: 0.3, Diff: 0.1, Sum: 1.0]
      [Update RS (ms): Min: 0.1, Avg: 0.1, Max: 0.2, Diff: 0.1, Sum: 0.6]
         [Processed Buffers: Min: 1, Avg: 1.8, Max: 2, Diff: 1, Sum: 7]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.0, Sum: 0.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 16.9, Avg: 17.0, Max: 17.0, Diff: 0.1, Sum: 67.9]
      [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.5]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 17.5, Avg: 17.5, Max: 17.6, Diff: 0.1, Sum: 70.2]
      [GC Worker End (ms): Min: 11090.3, Avg: 11090.4, Max: 11090.4, Diff: 0.1]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 1.1 ms]
      [Evacuation Failure: 0.4 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.3 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.0 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 89.0M(89.0M)->0.0B(89.0M) Survivors: 13.0M->13.0M Heap: 1763.7M(2048.0M)->1771.5M(2048.0M)]
 [Times: user=0.05 sys=0.00, real=0.01 secs] 
2017-07-27T10:49:34.762-0800: 11.175: [GC pause (G1 Evacuation Pause) (mixed) (to-space exhausted), 0.0041701 secs]
   [Parallel Time: 2.2 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 11175.0, Avg: 11175.5, Max: 11176.0, Diff: 1.0]
      [Ext Root Scanning (ms): Min: 0.0, Avg: 0.2, Max: 0.3, Diff: 0.3, Sum: 0.7]
      [Update RS (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 0.5]
         [Processed Buffers: Min: 0, Avg: 2.8, Max: 6, Diff: 6, Sum: 11]
      [Scan RS (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 0.6]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 1.0, Avg: 1.2, Max: 1.3, Diff: 0.2, Sum: 4.6]
      [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.2]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1]
      [GC Worker Total (ms): Min: 1.1, Avg: 1.7, Max: 2.2, Diff: 1.0, Sum: 6.8]
      [GC Worker End (ms): Min: 11177.2, Avg: 11177.2, Max: 11177.2, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.2 ms]
   [Other: 1.7 ms]
      [Evacuation Failure: 1.0 ms]
      [Choose CSet: 0.2 ms]
      [Ref Proc: 0.2 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 52.0M(89.0M)->0.0B(102.0M) Survivors: 13.0M->0.0B Heap: 1823.5M(2048.0M)->1811.5M(2048.0M)]
 [Times: user=0.00 sys=0.00, real=0.00 secs] 
2017-07-27T10:49:34.861-0800: 11.274: [GC pause (G1 Evacuation Pause) (mixed) (to-space exhausted), 0.0025046 secs]
   [Parallel Time: 1.5 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 11274.1, Avg: 11274.1, Max: 11274.2, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.2, Max: 0.3, Diff: 0.1, Sum: 0.9]
      [Update RS (ms): Min: 0.3, Avg: 0.4, Max: 0.5, Diff: 0.2, Sum: 1.5]
         [Processed Buffers: Min: 1, Avg: 2.5, Max: 6, Diff: 5, Sum: 10]
      [Scan RS (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.3]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 0.6, Avg: 0.7, Max: 0.7, Diff: 0.1, Sum: 2.6]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 1.4, Avg: 1.4, Max: 1.5, Diff: 0.1, Sum: 5.6]
      [GC Worker End (ms): Min: 11275.5, Avg: 11275.5, Max: 11275.6, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.9 ms]
      [Evacuation Failure: 0.4 ms]
      [Choose CSet: 0.1 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 15.0M(102.0M)->0.0B(102.0M) Survivors: 0.0B->0.0B Heap: 1826.5M(2048.0M)->1796.9M(2048.0M)]
 [Times: user=0.00 sys=0.00, real=0.00 secs] 
2017-07-27T10:49:34.962-0800: 11.375: [GC pause (G1 Evacuation Pause) (mixed) (to-space exhausted), 0.0028101 secs]
   [Parallel Time: 1.9 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 11375.3, Avg: 11375.3, Max: 11375.4, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.3, Max: 0.3, Diff: 0.1, Sum: 1.0]
      [Update RS (ms): Min: 0.4, Avg: 0.5, Max: 0.5, Diff: 0.1, Sum: 1.9]
         [Processed Buffers: Min: 1, Avg: 3.2, Max: 7, Diff: 6, Sum: 13]
      [Scan RS (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.3]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 0.9, Avg: 0.9, Max: 0.9, Diff: 0.0, Sum: 3.6]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 1.7, Avg: 1.8, Max: 1.8, Diff: 0.1, Sum: 7.1]
      [GC Worker End (ms): Min: 11377.1, Avg: 11377.1, Max: 11377.1, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.8 ms]
      [Evacuation Failure: 0.3 ms]
      [Choose CSet: 0.1 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.0 ms]
   [Eden: 37.0M(102.0M)->0.0B(102.0M) Survivors: 0.0B->0.0B Heap: 1833.9M(2048.0M)->1833.9M(2048.0M)]
 [Times: user=0.01 sys=0.00, real=0.00 secs] 
2017-07-27T10:49:34.965-0800: 11.378: [Full GC (Allocation Failure)  1833M->1565M(2048M), 0.5910860 secs]
   [Eden: 0.0B(102.0M)->0.0B(102.0M) Survivors: 0.0B->0.0B Heap: 1833.9M(2048.0M)->1565.7M(2048.0M)], [Metaspace: 3152K->3152K(1056768K)]
 [Times: user=0.56 sys=0.03, real=0.59 secs] 
2017-07-27T10:49:35.574-0800: 11.987: [GC pause (G1 Evacuation Pause) (young), 0.0271906 secs]
   [Parallel Time: 26.7 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 11986.7, Avg: 11986.7, Max: 11986.7, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.2, Max: 0.2, Diff: 0.1, Sum: 0.8]
      [Update RS (ms): Min: 0.0, Avg: 0.1, Max: 0.4, Diff: 0.4, Sum: 0.5]
         [Processed Buffers: Min: 0, Avg: 0.5, Max: 1, Diff: 1, Sum: 2]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 25.9, Avg: 26.2, Max: 26.3, Diff: 0.4, Sum: 104.7]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 26.6, Avg: 26.6, Max: 26.7, Diff: 0.1, Sum: 106.5]
      [GC Worker End (ms): Min: 12013.3, Avg: 12013.3, Max: 12013.3, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.4 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.0 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 102.0M(102.0M)->0.0B(89.0M) Survivors: 0.0B->13.0M Heap: 1667.7M(2048.0M)->1660.9M(2048.0M)]
 [Times: user=0.09 sys=0.01, real=0.03 secs] 
2017-07-27T10:49:35.616-0800: 12.029: [GC pause (G1 Evacuation Pause) (young) (initial-mark) (to-space exhausted), 0.0153940 secs]
   [Parallel Time: 14.6 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 12029.6, Avg: 12029.6, Max: 12029.6, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.2, Max: 0.3, Diff: 0.1, Sum: 0.9]
      [Update RS (ms): Min: 0.1, Avg: 0.1, Max: 0.4, Diff: 0.3, Sum: 0.6]
         [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.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 13.9, Avg: 14.1, Max: 14.1, Diff: 0.3, Sum: 56.2]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 14.5, Avg: 14.5, Max: 14.5, Diff: 0.1, Sum: 58.0]
      [GC Worker End (ms): Min: 12044.1, Avg: 12044.1, Max: 12044.1, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.7 ms]
      [Evacuation Failure: 0.2 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.2 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.0 ms]
      [Humongous Register: 0.0 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.0 ms]
   [Eden: 89.0M(89.0M)->0.0B(89.0M) Survivors: 13.0M->13.0M Heap: 1749.9M(2048.0M)->1751.7M(2048.0M)]
 [Times: user=0.05 sys=0.00, real=0.02 secs] 
2017-07-27T10:49:35.632-0800: 12.045: [GC concurrent-root-region-scan-start]
2017-07-27T10:49:35.632-0800: 12.045: [GC concurrent-root-region-scan-end, 0.0002168 secs]
2017-07-27T10:49:35.632-0800: 12.045: [GC concurrent-mark-start]
2017-07-27T10:49:35.644-0800: 12.057: [GC concurrent-mark-end, 0.0121700 secs]
2017-07-27T10:49:35.645-0800: 12.057: [GC remark 2017-07-27T10:49:35.645-0800: 12.058: [Finalize Marking, 0.0001574 secs] 2017-07-27T10:49:35.645-0800: 12.058: [GC ref-proc, 0.0000356 secs] 2017-07-27T10:49:35.645-0800: 12.058: [Unloading, 0.0009681 secs], 0.0084692 secs]
 [Times: user=0.02 sys=0.00, real=0.01 secs] 
2017-07-27T10:49:35.653-0800: 12.066: [GC cleanup 1758M->1710M(2048M), 0.0022304 secs]
 [Times: user=0.01 sys=0.00, real=0.00 secs] 
2017-07-27T10:49:35.655-0800: 12.068: [GC concurrent-cleanup-start]
2017-07-27T10:49:35.656-0800: 12.068: [GC concurrent-cleanup-end, 0.0000633 secs]
2017-07-27T10:49:35.864-0800: 12.276: [GC pause (G1 Evacuation Pause) (young) (to-space exhausted), 0.0085195 secs]
   [Parallel Time: 7.8 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 12276.6, Avg: 12276.7, Max: 12276.8, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.3, Max: 0.3, Diff: 0.1, Sum: 1.0]
      [Update RS (ms): Min: 0.1, Avg: 0.1, Max: 0.2, Diff: 0.1, Sum: 0.6]
         [Processed Buffers: Min: 1, Avg: 2.8, Max: 5, Diff: 4, Sum: 11]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 7.0, Avg: 7.1, Max: 7.1, Diff: 0.1, Sum: 28.2]
      [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.3]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 7.5, Avg: 7.6, Max: 7.7, Diff: 0.1, Sum: 30.4]
      [GC Worker End (ms): Min: 12284.3, Avg: 12284.3, Max: 12284.3, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.7 ms]
      [Evacuation Failure: 0.3 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.0 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.0 ms]
   [Eden: 89.0M(89.0M)->0.0B(89.0M) Survivors: 13.0M->13.0M Heap: 1792.7M(2048.0M)->1805.7M(2048.0M)]
 [Times: user=0.03 sys=0.00, real=0.01 secs] 
2017-07-27T10:49:35.961-0800: 12.374: [GC pause (G1 Evacuation Pause) (mixed) (to-space exhausted), 0.0024407 secs]
   [Parallel Time: 1.3 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 12374.6, Avg: 12374.6, Max: 12374.6, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.2, Max: 0.2, Diff: 0.1, Sum: 0.8]
      [Update RS (ms): Min: 0.1, Avg: 0.1, Max: 0.1, Diff: 0.0, Sum: 0.4]
         [Processed Buffers: Min: 1, Avg: 2.8, Max: 4, Diff: 3, Sum: 11]
      [Scan RS (ms): Min: 0.1, Avg: 0.1, Max: 0.1, Diff: 0.0, Sum: 0.4]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 0.8, Avg: 0.8, Max: 0.8, Diff: 0.0, Sum: 3.0]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 1.2, Avg: 1.2, Max: 1.2, Diff: 0.1, Sum: 4.8]
      [GC Worker End (ms): Min: 12375.8, Avg: 12375.8, Max: 12375.8, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 1.0 ms]
      [Evacuation Failure: 0.6 ms]
      [Choose CSet: 0.1 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.0 ms]
   [Eden: 13.0M(89.0M)->0.0B(102.0M) Survivors: 13.0M->0.0B Heap: 1818.7M(2048.0M)->1813.9M(2048.0M)]
 [Times: user=0.00 sys=0.00, real=0.00 secs] 
2017-07-27T10:49:35.965-0800: 12.378: [GC pause (G1 Evacuation Pause) (mixed) (to-space exhausted), 0.0020408 secs]
   [Parallel Time: 1.3 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 12378.2, Avg: 12378.3, Max: 12378.3, Diff: 0.0]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.2, Max: 0.2, Diff: 0.0, Sum: 0.9]
      [Update RS (ms): Min: 0.3, Avg: 0.4, Max: 0.4, Diff: 0.2, Sum: 1.4]
         [Processed Buffers: Min: 1, Avg: 2.5, Max: 7, Diff: 6, Sum: 10]
      [Scan RS (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.3]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 0.5, Avg: 0.5, Max: 0.5, Diff: 0.0, Sum: 2.1]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 1.2, Avg: 1.2, Max: 1.2, Diff: 0.0, Sum: 4.7]
      [GC Worker End (ms): Min: 12379.4, Avg: 12379.4, Max: 12379.4, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.6 ms]
      [Evacuation Failure: 0.2 ms]
      [Choose CSet: 0.1 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.0 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.0 ms]
   [Eden: 6144.0K(102.0M)->0.0B(102.0M) Survivors: 0.0B->0.0B Heap: 1819.9M(2048.0M)->1816.7M(2048.0M)]
 [Times: user=0.01 sys=0.00, real=0.00 secs] 
2017-07-27T10:49:35.968-0800: 12.381: [GC pause (G1 Evacuation Pause) (mixed) (to-space exhausted), 0.0012720 secs]
   [Parallel Time: 0.7 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 12381.1, Avg: 12381.1, Max: 12381.1, Diff: 0.0]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.2, Max: 0.2, Diff: 0.0, Sum: 0.8]
      [Update RS (ms): Min: 0.1, Avg: 0.2, Max: 0.3, Diff: 0.1, Sum: 0.7]
         [Processed Buffers: Min: 1, Avg: 2.2, Max: 5, Diff: 4, Sum: 9]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 0.1, Avg: 0.2, Max: 0.2, Diff: 0.1, Sum: 0.7]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 0.6, Avg: 0.6, Max: 0.6, Diff: 0.0, Sum: 2.4]
      [GC Worker End (ms): Min: 12381.7, Avg: 12381.7, Max: 12381.7, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.5 ms]
      [Evacuation Failure: 0.2 ms]
      [Choose CSet: 0.1 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.0 ms]
   [Eden: 4096.0K(102.0M)->0.0B(102.0M) Survivors: 0.0B->0.0B Heap: 1820.7M(2048.0M)->1799.1M(2048.0M)]
 [Times: user=0.00 sys=0.00, real=0.00 secs] 
2017-07-27T10:49:36.059-0800: 12.472: [GC pause (G1 Evacuation Pause) (young) (to-space exhausted), 0.0013835 secs]
   [Parallel Time: 0.7 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 12472.1, Avg: 12472.1, Max: 12472.2, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.2, Max: 0.3, Diff: 0.1, Sum: 0.9]
      [Update RS (ms): Min: 0.1, Avg: 0.1, Max: 0.1, Diff: 0.0, Sum: 0.5]
         [Processed Buffers: Min: 2, Avg: 3.0, Max: 4, Diff: 2, 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: 0.2, Avg: 0.3, Max: 0.3, Diff: 0.0, Sum: 1.0]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 0.6, Avg: 0.6, Max: 0.7, Diff: 0.1, Sum: 2.6]
      [GC Worker End (ms): Min: 12472.8, Avg: 12472.8, Max: 12472.8, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.6 ms]
      [Evacuation Failure: 0.2 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.0 ms]
   [Eden: 27.0M(102.0M)->0.0B(102.0M) Survivors: 0.0B->0.0B Heap: 1826.1M(2048.0M)->1826.1M(2048.0M)]
 [Times: user=0.00 sys=0.01, real=0.01 secs] 
2017-07-27T10:49:36.061-0800: 12.474: [Full GC (Allocation Failure)  1826M->1620M(2048M), 0.5642062 secs]
   [Eden: 0.0B(102.0M)->0.0B(102.0M) Survivors: 0.0B->0.0B Heap: 1826.1M(2048.0M)->1620.4M(2048.0M)], [Metaspace: 3152K->3152K(1056768K)]
 [Times: user=0.55 sys=0.01, real=0.56 secs] 
2017-07-27T10:49:36.643-0800: 13.056: [GC pause (G1 Evacuation Pause) (young) (to-space exhausted), 0.0224550 secs]
   [Parallel Time: 21.8 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 13056.0, Avg: 13056.0, Max: 13056.1, Diff: 0.0]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.2, Max: 0.2, Diff: 0.0, Sum: 0.7]
      [Update RS (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 0.5]
         [Processed Buffers: Min: 0, Avg: 0.5, Max: 1, Diff: 1, Sum: 2]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 21.2, Avg: 21.3, Max: 21.5, Diff: 0.2, Sum: 85.3]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 21.7, Avg: 21.7, Max: 21.7, Diff: 0.0, Sum: 86.9]
      [GC Worker End (ms): Min: 13077.8, Avg: 13077.8, Max: 13077.8, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.6 ms]
      [Evacuation Failure: 0.2 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.0 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 102.0M(102.0M)->0.0B(89.0M) Survivors: 0.0B->13.0M Heap: 1722.4M(2048.0M)->1727.4M(2048.0M)]
 [Times: user=0.08 sys=0.00, real=0.02 secs] 
2017-07-27T10:49:36.679-0800: 13.092: [GC pause (G1 Evacuation Pause) (young) (initial-mark) (to-space exhausted), 0.0015870 secs]
   [Parallel Time: 0.8 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 13091.9, Avg: 13091.9, Max: 13092.0, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.2, Max: 0.2, Diff: 0.0, Sum: 0.9]
      [Update RS (ms): Min: 0.1, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.4]
         [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.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 0.4, Avg: 0.4, Max: 0.4, Diff: 0.0, Sum: 1.7]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 0.8, Avg: 0.8, Max: 0.8, Diff: 0.1, Sum: 3.1]
      [GC Worker End (ms): Min: 13092.7, Avg: 13092.7, Max: 13092.7, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.7 ms]
      [Evacuation Failure: 0.2 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.0 ms]
      [Humongous Register: 0.0 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.0 ms]
   [Eden: 74.0M(89.0M)->0.0B(102.0M) Survivors: 13.0M->0.0B Heap: 1801.4M(2048.0M)->1801.4M(2048.0M)]
 [Times: user=0.01 sys=0.00, real=0.01 secs] 
2017-07-27T10:49:36.680-0800: 13.093: [GC concurrent-root-region-scan-start]
2017-07-27T10:49:36.681-0800: 13.093: [GC concurrent-root-region-scan-end, 0.0000144 secs]
2017-07-27T10:49:36.681-0800: 13.093: [GC concurrent-mark-start]
2017-07-27T10:49:36.681-0800: 13.094: [Full GC (Allocation Failure)  1801M->1650M(2048M), 0.6298997 secs]
   [Eden: 0.0B(102.0M)->0.0B(102.0M) Survivors: 0.0B->0.0B Heap: 1801.4M(2048.0M)->1650.7M(2048.0M)], [Metaspace: 3163K->3163K(1056768K)]
 [Times: user=0.57 sys=0.02, real=0.63 secs] 
2017-07-27T10:49:37.311-0800: 13.724: [GC concurrent-mark-abort]
2017-07-27T10:49:37.329-0800: 13.742: [GC pause (G1 Evacuation Pause) (young) (to-space exhausted), 0.0153004 secs]
   [Parallel Time: 14.3 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 13741.7, Avg: 13741.8, Max: 13741.8, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.2, Max: 0.2, Diff: 0.1, Sum: 0.8]
      [Update RS (ms): Min: 0.0, Avg: 1.0, Max: 3.9, Diff: 3.9, Sum: 4.1]
         [Processed Buffers: Min: 0, Avg: 0.5, Max: 1, Diff: 1, Sum: 2]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 10.0, Avg: 12.9, Max: 14.0, Diff: 3.9, Sum: 51.8]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 14.2, Avg: 14.3, Max: 14.3, Diff: 0.1, Sum: 57.0]
      [GC Worker End (ms): Min: 13756.0, Avg: 13756.0, Max: 13756.0, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.9 ms]
      [Evacuation Failure: 0.4 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.2 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.1 ms]
   [Eden: 102.0M(102.0M)->0.0B(89.0M) Survivors: 0.0B->13.0M Heap: 1752.7M(2048.0M)->1772.7M(2048.0M)]
 [Times: user=0.04 sys=0.00, real=0.02 secs] 
2017-07-27T10:49:37.349-0800: 13.762: [GC pause (G1 Evacuation Pause) (young) (initial-mark) (to-space exhausted), 0.0012358 secs]
   [Parallel Time: 0.6 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 13762.5, Avg: 13762.5, Max: 13762.5, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.2, Max: 0.3, Diff: 0.1, Sum: 0.9]
      [Update RS (ms): Min: 0.1, Avg: 0.1, Max: 0.1, Diff: 0.0, Sum: 0.3]
         [Processed Buffers: Min: 1, Avg: 2.2, Max: 3, Diff: 2, Sum: 9]
      [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: 0.2, Avg: 0.2, Max: 0.2, Diff: 0.0, Sum: 0.7]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [GC Worker Total (ms): Min: 0.5, Avg: 0.5, Max: 0.5, Diff: 0.1, Sum: 2.0]
      [GC Worker End (ms): Min: 13763.0, Avg: 13763.0, Max: 13763.0, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.6 ms]
      [Evacuation Failure: 0.2 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.0 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.0 ms]
   [Eden: 26.0M(89.0M)->0.0B(102.0M) Survivors: 13.0M->0.0B Heap: 1798.7M(2048.0M)->1798.7M(2048.0M)]
 [Times: user=0.00 sys=0.00, real=0.01 secs] 
2017-07-27T10:49:37.351-0800: 13.764: [GC concurrent-root-region-scan-start]
2017-07-27T10:49:37.351-0800: 13.764: [GC concurrent-root-region-scan-end, 0.0000134 secs]
2017-07-27T10:49:37.351-0800: 13.764: [GC concurrent-mark-start]
2017-07-27T10:49:37.351-0800: 13.764: [Full GC (Allocation Failure)  1798M->1680M(2048M), 0.6569420 secs]
   [Eden: 0.0B(102.0M)->0.0B(102.0M) Survivors: 0.0B->0.0B Heap: 1798.7M(2048.0M)->1680.7M(2048.0M)], [Metaspace: 3163K->3163K(1056768K)]
 [Times: user=0.61 sys=0.03, real=0.65 secs] 
2017-07-27T10:49:38.008-0800: 14.421: [GC concurrent-mark-abort]
2017-07-27T10:49:38.026-0800: 14.439: [GC pause (G1 Evacuation Pause) (young) (to-space exhausted), 0.0055419 secs]
   [Parallel Time: 4.7 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 14438.9, Avg: 14438.9, Max: 14439.0, Diff: 0.0]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.2, Max: 0.3, Diff: 0.1, Sum: 1.0]
      [Update RS (ms): Min: 0.0, Avg: 0.1, Max: 0.4, Diff: 0.4, Sum: 0.5]
         [Processed Buffers: Min: 0, Avg: 0.5, Max: 1, Diff: 1, Sum: 2]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 3.9, Avg: 4.2, Max: 4.3, Diff: 0.4, Sum: 16.8]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 4.6, Avg: 4.7, Max: 4.7, Diff: 0.0, Sum: 18.6]
      [GC Worker End (ms): Min: 14443.6, Avg: 14443.6, Max: 14443.6, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.7 ms]
      [Evacuation Failure: 0.3 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.0 ms]
   [Eden: 102.0M(102.0M)->0.0B(89.0M) Survivors: 0.0B->13.0M Heap: 1782.7M(2048.0M)->1791.7M(2048.0M)]
 [Times: user=0.02 sys=0.00, real=0.01 secs] 
2017-07-27T10:49:38.033-0800: 14.445: [GC pause (G1 Evacuation Pause) (young) (initial-mark) (to-space exhausted), 0.0011727 secs]
   [Parallel Time: 0.5 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 14445.6, Avg: 14445.7, Max: 14445.7, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.2, Max: 0.3, Diff: 0.1, Sum: 0.9]
      [Update RS (ms): Min: 0.1, Avg: 0.1, Max: 0.1, Diff: 0.0, Sum: 0.3]
         [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: 0.1, Avg: 0.1, Max: 0.1, Diff: 0.0, Sum: 0.3]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [GC Worker Total (ms): Min: 0.4, Avg: 0.4, Max: 0.5, Diff: 0.1, Sum: 1.6]
      [GC Worker End (ms): Min: 14446.1, Avg: 14446.1, Max: 14446.1, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.6 ms]
      [Evacuation Failure: 0.2 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.0 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.0 ms]
   [Eden: 5120.0K(89.0M)->0.0B(102.0M) Survivors: 13.0M->0.0B Heap: 1796.7M(2048.0M)->1796.7M(2048.0M)]
 [Times: user=0.00 sys=0.00, real=0.00 secs] 
2017-07-27T10:49:38.034-0800: 14.447: [GC concurrent-root-region-scan-start]
2017-07-27T10:49:38.034-0800: 14.447: [GC concurrent-root-region-scan-end, 0.0000133 secs]
2017-07-27T10:49:38.034-0800: 14.447: [GC concurrent-mark-start]
2017-07-27T10:49:38.034-0800: 14.447: [Full GC (Allocation Failure)  1796M->1715M(2048M), 0.5632045 secs]
   [Eden: 0.0B(102.0M)->0.0B(102.0M) Survivors: 0.0B->0.0B Heap: 1796.7M(2048.0M)->1715.8M(2048.0M)], [Metaspace: 3163K->3163K(1056768K)]
 [Times: user=0.54 sys=0.02, real=0.57 secs] 
2017-07-27T10:49:38.600-0800: 15.013: [GC concurrent-mark-abort]
2017-07-27T10:49:38.613-0800: 15.025: [GC pause (G1 Evacuation Pause) (young) (to-space exhausted), 0.0017234 secs]
   [Parallel Time: 1.1 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 15025.5, Avg: 15025.6, Max: 15025.6, Diff: 0.1]
      [Ext Root Scanning (ms): Min: 0.2, Avg: 0.2, Max: 0.2, Diff: 0.1, Sum: 0.8]
      [Update RS (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.3]
         [Processed Buffers: Min: 0, Avg: 0.5, Max: 1, Diff: 1, Sum: 2]
      [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
      [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [Object Copy (ms): Min: 0.6, Avg: 0.7, Max: 0.8, Diff: 0.2, Sum: 2.7]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
         [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4]
      [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
      [GC Worker Total (ms): Min: 1.0, Avg: 1.0, Max: 1.0, Diff: 0.1, Sum: 4.0]
      [GC Worker End (ms): Min: 15026.6, Avg: 15026.6, Max: 15026.6, Diff: 0.0]
   [Code Root Fixup: 0.0 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.1 ms]
   [Other: 0.5 ms]
      [Evacuation Failure: 0.2 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 0.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.1 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.0 ms]
   [Eden: 78.0M(102.0M)->0.0B(102.0M) Survivors: 0.0B->0.0B Heap: 1793.8M(2048.0M)->1793.8M(2048.0M)]
 [Times: user=0.01 sys=0.00, real=0.00 secs] 
2017-07-27T10:49:38.614-0800: 15.027: [Full GC (Allocation Failure)  1793M->1745M(2048M), 0.4479856 secs]
   [Eden: 0.0B(102.0M)->0.0B(102.0M) Survivors: 0.0B->0.0B Heap: 1793.8M(2048.0M)->1745.8M(2048.0M)], [Metaspace: 3163K->3163K(1056768K)]
 [Times: user=0.41 sys=0.02, real=0.45 secs] 

Heap
 garbage-first heap   total 2097152K, used 1804048K [0x0000000740000000, 0x0000000740104000, 0x00000007c0000000)
  region size 1024K, 17 young (17408K), 0 survivors (0K)
 Metaspace       used 3179K, capacity 4486K, committed 4864K, reserved 1056768K
  class space    used 354K, capacity 386K, committed 512K, reserved 1048576K

bingoogolapple avatar Jul 27 '17 03:07 bingoogolapple

G1 – Garbage First(垃圾优先算法)

Evacuation Pause: Fully Young(转移暂停:纯年轻代模式)

  • 在应用程序刚启动时,G1 还未执行过(not-yet-executed)并发阶段,也就没有获得任何额外的信息,处于初始的 fully-young 模式。在年轻代空间用满之后,应用线程被暂停,年轻代堆区中的存活对象被复制到存活区,如果还没有存活区,则选择任意一部分空闲的小堆区用作存活区
  • 复制的过程称为转移(Evacuation),这和前面讲过的年轻代收集器基本上是一样的工作原理
日志 描述
[GC pause (G1 Evacuation Pause) (young), 0.0144119 secs] G1 转移暂停,只清理年轻代空间,持续的系统时间为 0.0144 秒
[Parallel Time: 13.9 ms, GC Workers: 8] 表明后面的活动由 8 个 Worker 线程并行执行,消耗时间为 13.9 毫秒(real time)
[Code Root Fixup: 0.0 ms] 释放用于管理并行活动的内部数据。一般都接近于零。这是串行执行的过程
[Code Root Purge: 0.0 ms] 清理其他部分数据,也是非常快的,但如非必要则几乎等于零。这是串行执行的过程
[Other: 0.4 ms] 其他活动消耗的时间,其中有很多是并行执行的
[Eden: 24.0M(24.0M)->0.0B(13.0M) 暂停之前和暂停之后,Eden 区的使用量与总容量
Survivors: 0.0B->3072.0K 暂停之前和暂停之后,存活区的使用量
Heap: 24.0M(256.0M)->21.9M(256.0M)] 暂停之前和暂停之后,整个堆内存的使用量与总容量
[Times: user=0.78 sys=0.01, real=0.11 secs] GC 事件的持续时间,通过三个部分来衡量「user – 在此次垃圾回收过程中,所有 GC 线程所消耗的 CPU 时间之和」「sys – GC 过程中操作系统调用和系统等待事件所消耗的时间」「real – 应用程序暂停的时间。在 Parallel GC 中, 这个数字约等于:(user + sys) / GC 线程数。这里使用了8个线程。请注意,总有一定比例的处理过程是不能并行进行的
[Parallel Time: 13.9 ms, GC Workers: 8]
[GC Worker Start (ms): Min: 134.0, Avg: 134.1, Max: 134.1, Diff: 0.1]
[Ext Root Scanning (ms): Min: 0.1, Avg: 0.2, Max: 0.3, Diff: 0.2, Sum: 1.2]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0]
[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.2, Diff: 0.2, Sum: 0.2]
[Object Copy (ms): Min: 10.8, Avg: 12.1, Max: 12.6, Diff: 1.9, Sum: 96.5]
[Termination (ms): Min: 0.8, Avg: 1.5, Max: 2.8, Diff: 1.9, Sum: 12.2]
[Termination Attempts: Min: 173, Avg: 293.2, Max: 362, Diff: 189, Sum: 2346]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
GC Worker Total (ms): Min: 13.7, Avg: 13.8, Max: 13.8, Diff: 0.1, Sum: 110.2]
[GC Worker End (ms): Min: 147.8, Avg: 147.8, Max: 147.8, Diff: 0.0]
日志 描述
[Parallel Time: 13.9 ms, GC Workers: 8] 表明后面的活动由 8 个 Worker 线程并行执行,消耗时间为 13.9 毫秒(real time)
GC Worker Start (ms) GC 的 Worker 线程开始启动时,相对于 pause 开始的时间戳。如果 Min 和 Max 差别很大,则表明本机其他进程所使用的线程数量过多,挤占了 GC 的 CPU 时间
Ext Root Scanning (ms) 用了多长时间来扫描堆外(non-heap)的 root,如 Classloaders,JNI 引用,JVM 的系统 root 等。后面显示了运行时间,Sum 指的是 CPU 时间
Code Root Scanning (ms) 用了多长时间来扫描实际代码中的 root。例如局部变量等等
Object Copy (ms) 用了多长时间来拷贝收集区内的存活对象
Termination (ms) GC 的 Worker 线程用了多长时间来确保自身可以安全地停止,这段时间什么也不用做,stop 之后该线程就终止运行了
Termination Attempts GC 的 Worker 线程尝试多少次 try 和 teminate。如果 Worker 发现还有一些任务没处理完,则这一次尝试就是失败的,暂时还不能终止
GC Worker Other (ms) 一些琐碎的小活动,在 GC 日志中不值得单独列出来
GC Worker Total (ms) GC 的 Worker 线程的工作时间总计
GC Worker End (ms) GC 的 Worker 线程完成作业的时间戳。通常来说这部分数字应该大致相等,否则就说明有太多的线程被挂起,很可能是因为坏邻居效应(noisy neighbor)所导致的
  • 转移暂停期间,还有一些琐碎执行的小活动
[Other: 0.4 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 0.2 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 0.1 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.0 ms]
日志 描述
[Other: 0.4 ms] 其他活动消耗的时间,其中有很多是并行执行的
Ref Proc: 0.2 ms 处理非强引用(non-strong)的时间「进行清理或者决定是否需要清理」
Ref Enq: 0.0 ms 用来将剩下的非强引用(non-strong)排列到合适的 ReferenceQueue 中
Free CSet: 0.0 ms 将回收集中被释放的小堆归还所消耗的时间,以便他们能用来分配新的对象

Concurrent Marking(并发标记)

  • G1 收集器的很多概念建立在 CMS 的基础上,也有很多不同的地方。G1的并发标记通过 Snapshot-At-The-Beginning(开始时快照)的方式,在标记阶段开始时记下所有的存活对象
  • 当堆内存的总体使用比例达到一定数值时,就会触发并发标记。默认值为 45%,但也可以通过 JVM 参数 InitiatingHeapOccupancyPercent 来设置。和 CMS 一样,G1 的并发标记也是由多个阶段组成,其中一些是完全并发的,还有一些阶段需要暂停应用线程

阶段 1:Initial Mark(初始标记)。此阶段标记所有从 GC root 直接可达的对象。在 CMS 中需要一次 STW 暂停,但 G1 里面通常是在转移暂停的同时处理这些事情,所以它的开销是很小的

1.631: [GC pause (G1 Evacuation Pause) (young) (initial-mark), 0.0062656 secs]

阶段 2:Root Region Scan(Root 区扫描)。此阶段标记所有从根区域可达的存活对象。根区域包括非空的区域,以及在标记过程中不得不收集的区域。因为在并发标记的过程中迁移对象会造成很多麻烦,所以此阶段必须在下一次转移暂停之前完成。如果必须启动转移暂停,则会先要求根区域扫描中止,等它完成才能继续扫描。在当前版本的实现中,根区域是存活的小堆区,也包括下一次转移暂停中肯定会被清理的那部分年轻代小堆区

1.362: [GC concurrent-root-region-scan-start]
1.364: [GC concurrent-root-region-scan-end, 0.0028513 secs]

阶段 3:Concurrent Mark(并发标记)。此阶段非常类似于 CMS:它只是遍历对象图,并在一个特殊的位图中标记能访问到的对象。为了确保标记开始时的快照准确性,所有应用线程并发对对象图执行的引用更新,G1 要求放弃前面阶段为了标记目的而引用的过时引用

  • 这是通过使用 Pre-Write 屏障来实现的,(不要和之后介绍的 Post-Write 混淆,也不要和多线程开发中的内存屏障(memory barriers)相混淆)。Pre-Write 屏障的作用是 G1 在进行并发标记时,如果程序将对象的某个属性做了变更,就会在 log buffers 中存储之前的引用。由并发标记线程负责处理
1.364: [GC concurrent-mark-start]
1.645: [GC co ncurrent-mark-end, 0.2803470 secs]

阶段 4:Remark(再次标记):和 CMS 类似,这也是一次 STW 停顿,以完成标记过程。对于 G1,它短暂地停止应用线程,停止并发更新日志的写入,处理其中的少量信息,并标记所有在并发标记开始时未被标记的存活对象。这一阶段也执行某些额外的清理,如引用处理(参见 Evacuation Pause log)或者类卸载(class unloading)

1.645: [GC remark 1.645: [Finalize Marking, 0.0009461 secs]
1.646: [GC ref-proc, 0.0000417 secs]
1.646: [Unloading, 0.0011301 secs], 0.0074056 secs]
[Times: user=0.01 sys=0.00, real=0.01 secs]

阶段 5:Cleanup(清理)。最后这个小阶段为即将到来的转移阶段做准备,统计小堆区中所有存活的对象,并将小堆区进行排序,以提升 GC 的效率。所有不包含存活对象的小堆区在此阶段都被回收了

  • 有一部分是并发的:例如空堆区的回收;还有大部分的存活率计算,此阶段也需要一个短暂的 STW 暂停,以不受应用线程的影响来完成作业。这种 STW 停顿的日志如下
1.652: [GC cleanup 1213M->1213M(1885M), 0.0030492 secs]
[Times: user=0.01 sys=0.00, real=0.00 secs]
  • 如果发现某些小堆区中只包含垃圾,则日志格式可能会有点不同
1.872: [GC cleanup 1357M->173M(1996M), 0.0015664 secs]
[Times: user=0.01 sys=0.00, real=0.01 secs]
1.874: [GC concurrent-cleanup-start]
1.876: [GC concurrent-cleanup-end, 0.0014846 secs]

Evacuation Pause: Mixed(转移暂停: 混合模式)

  • 能并发清理老年代中整个整个的小堆区是一种最优情形,但有时候并不是这样。并发标记完成之后,G1 将执行一次混合收集(mixed collection),不只清理年轻代,还将一部分老年代区域也加入到 collection set 中
  • 混合模式的转移暂停(Evacuation pause)不一定紧跟着并发标记阶段。有很多规则和历史数据会影响混合模式的启动时机。比如,假若在老年代中可以并发地腾出很多的小堆区,就没有必要启动混合模式
  • 在并发标记与混合转移暂停之间,很可能会存在多次 fully-young 转移暂停
[Update RS (ms): Min: 0.7, Avg: 0.8, Max: 0.9, Diff: 0.2, Sum: 6.1]
[Processed Buffers: Min: 0, Avg: 2.2, Max: 5, Diff: 5, Sum: 18]
[Scan RS (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.8]
[Clear CT: 0.2 ms]
[Redirty Cards: 0.1 ms]
日志 描述
Update RS (ms) 因为 Remembered Sets 是并发处理的,必须确保在实际的垃圾收集之前,缓冲区中的 card 得到处理。如果 card 数量很多,则 GC 并发线程的负载可能就会很高。可能的原因是,修改的字段过多,或者 CPU 资源受限
Processed Buffers 每个 worker 线程处理了多少个本地缓冲区(local buffer)
Scan RS (ms) 用了多长时间扫描来自 RSet 的引用
Clear CT: 0.2 ms 清理 card table 中 cards 的时间。清理工作只是简单地删除脏状态,此状态用来标识一个字段是否被更新的,供 Remembered Sets 使用
Redirty Cards: 0.1 ms 将 card table 中适当的位置标记为 dirty 所花费的时间。「适当的位置」是由 GC 本身执行的堆内存改变所决定的,例如引用排队等

bingoogolapple avatar Jul 27 '17 09:07 bingoogolapple