Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

GC 日志分析 - G1 – Garbage First（垃圾优先算法） #186

Open
bingoogolapple opened this issue Jul 27, 2017 · 2 comments
Open

GC 日志分析 - G1 – Garbage First（垃圾优先算法） #186

bingoogolapple opened this issue Jul 27, 2017 · 2 comments
Labels
Java

Comments

@bingoogolapple
Copy link
Owner

bingoogolapple commented Jul 27, 2017 •
edited

  • 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
Copy link
Owner Author

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
Copy link
Owner Author

G1 垃圾收集器概述

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
Java
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
1 participant
@bingoogolapple