Java HotSpot(TM) 64-Bit Server VM (25.60-b23) for windows-amd64 JRE (1.8.0_60-b27), built on Aug 4 2015 11:06:27 by "java_re" with MS VC++ 10.0 (VS2010) Memory: 4k page, physical 16466732k(7798260k free), swap 32931624k(20973180k free) CommandLine flags: -XX:InitialHeapSize=263467712 -XX:MaxHeapSize=4215483392 -XX:+PrintGC -XX:+PrintGCDateStamps -XX:+PrintGCDetails -XX:+PrintGCTimeStamps -XX:+UseCompressedClassPointers -XX:+UseCompressedOops -XX:+UseG1GC -XX:-UseLargePagesIndividualAllocation 2015-09-14T12:32:24.398-0700: 0.356: [GC pause (G1 Evacuation Pause) (young), 0.0215287 secs] [Parallel Time: 20.0 ms, GC Workers: 8] [GC Worker Start (ms): Min: 355.9, Avg: 356.3, Max: 358.4, Diff: 2.4] [Ext Root Scanning (ms): Min: 0.0, Avg: 6.4, Max: 16.7, Diff: 16.7, Sum: 51.4] [Update RS (ms): Min: 0.0, Avg: 1.0, Max: 2.5, Diff: 2.5, Sum: 8.2] [Processed Buffers: Min: 0, Avg: 1.1, Max: 5, Diff: 5, 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.1, Diff: 0.1, Sum: 0.1] [Object Copy (ms): Min: 2.9, Avg: 11.9, Max: 17.5, Diff: 14.6, Sum: 95.3] [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.9] [Termination Attempts: Min: 1, Avg: 2.5, Max: 5, Diff: 4, Sum: 20] [GC Worker Other (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.5] [GC Worker Total (ms): Min: 17.5, Avg: 19.6, Max: 19.9, Diff: 2.4, Sum: 156.5] [GC Worker End (ms): Min: 375.8, Avg: 375.9, Max: 375.9, Diff: 0.1] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.5 ms] [Other: 1.0 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.4 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.4 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 12.0M(12.0M)->0.0B(14.0M) Survivors: 0.0B->2048.0K Heap: 12.6M(252.0M)->7848.3K(252.0M)] [Times: user=0.08 sys=0.00, real=0.02 secs] 2015-09-14T12:32:24.932-0700: 0.889: [GC pause (G1 Evacuation Pause) (young), 0.0469650 secs] [Parallel Time: 46.6 ms, GC Workers: 8] [GC Worker Start (ms): Min: 889.5, Avg: 892.0, Max: 897.3, Diff: 7.8] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.7] [Update RS (ms): Min: 0.0, Avg: 1.8, Max: 4.1, Diff: 4.1, Sum: 14.1] [Processed Buffers: Min: 0, Avg: 2.1, Max: 5, Diff: 5, Sum: 17] [Scan RS (ms): Min: 3.4, Avg: 18.4, Max: 35.6, Diff: 32.2, Sum: 147.5] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1] [Object Copy (ms): Min: 7.1, Avg: 23.4, Max: 42.1, Diff: 35.0, Sum: 187.3] [Termination (ms): Min: 0.0, Avg: 0.4, Max: 0.5, Diff: 0.5, Sum: 3.2] [Termination Attempts: Min: 1, Avg: 1.5, Max: 2, Diff: 1, Sum: 12] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 38.8, Avg: 44.1, Max: 46.6, Diff: 7.8, Sum: 352.8] [GC Worker End (ms): Min: 936.1, Avg: 936.1, Max: 936.1, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.1 ms] [Other: 0.3 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.1 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 10.0M(10.0M)->0.0B(10.0M) Survivors: 2048.0K->2048.0K Heap: 22.0M(252.0M)->24.5M(252.0M)] [Times: user=0.22 sys=0.00, real=0.05 secs] 2015-09-14T12:32:25.489-0700: 1.447: [GC pause (G1 Evacuation Pause) (young), 0.0293914 secs] [Parallel Time: 28.1 ms, GC Workers: 8] [GC Worker Start (ms): Min: 1447.4, Avg: 1456.5, Max: 1475.3, Diff: 27.9] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.2] [Update RS (ms): Min: 0.0, Avg: 2.7, Max: 5.8, Diff: 5.8, Sum: 21.5] [Processed Buffers: Min: 0, Avg: 2.3, Max: 6, Diff: 6, Sum: 18] [Scan RS (ms): Min: 0.0, Avg: 0.8, Max: 1.9, Diff: 1.9, Sum: 6.6] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 0.0, Avg: 15.0, Max: 27.4, Diff: 27.4, Sum: 120.3] [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.6] [Termination Attempts: Min: 1, Avg: 282.6, Max: 581, Diff: 580, Sum: 2261] [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.0, Avg: 18.8, Max: 27.9, Diff: 27.9, Sum: 150.3] [GC Worker End (ms): Min: 1475.3, Avg: 1475.3, Max: 1475.3, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.6 ms] [Other: 0.7 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.4 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 10.0M(10.0M)->0.0B(24.0M) Survivors: 2048.0K->2048.0K Heap: 37.6M(252.0M)->40.1M(252.0M)] [Times: user=0.08 sys=0.00, real=0.03 secs] 2015-09-14T12:32:26.690-0700: 2.648: [GC pause (G1 Evacuation Pause) (young), 0.0660076 secs] [Parallel Time: 65.3 ms, GC Workers: 8] [GC Worker Start (ms): Min: 2648.1, Avg: 2659.7, Max: 2694.5, Diff: 46.3] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.2, Max: 0.3, Diff: 0.3, Sum: 1.2] [Update RS (ms): Min: 0.0, Avg: 2.9, Max: 6.4, Diff: 6.4, Sum: 23.4] [Processed Buffers: Min: 0, Avg: 2.4, Max: 5, Diff: 5, Sum: 19] [Scan RS (ms): Min: 0.0, Avg: 3.3, Max: 7.0, Diff: 7.0, Sum: 26.5] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 18.3, Avg: 46.8, Max: 64.3, Diff: 46.0, Sum: 374.3] [Termination (ms): Min: 0.0, Avg: 0.5, Max: 0.7, Diff: 0.7, Sum: 3.9] [Termination Attempts: Min: 1, Avg: 114.8, Max: 199, Diff: 198, Sum: 918] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 18.9, Avg: 53.7, Max: 65.3, Diff: 46.4, Sum: 429.4] [GC Worker End (ms): Min: 2713.3, Avg: 2713.3, Max: 2713.4, Diff: 0.1] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.0 ms] [Other: 0.6 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.3 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: 22.0M(20.0M)->0.0B(30.0M) Survivors: 2048.0K->3072.0K Heap: 73.6M(252.0M)->75.1M(252.0M)] [Times: user=0.31 sys=0.02, real=0.07 secs] 2015-09-14T12:32:28.192-0700: 4.150: [GC pause (G1 Evacuation Pause) (young), 0.0759699 secs] [Parallel Time: 75.5 ms, GC Workers: 8] [GC Worker Start (ms): Min: 4149.8, Avg: 4155.5, Max: 4165.4, Diff: 15.6] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.2] [Update RS (ms): Min: 0.0, Avg: 4.0, Max: 8.1, Diff: 8.1, Sum: 31.8] [Processed Buffers: Min: 0, Avg: 3.0, Max: 6, Diff: 6, Sum: 24] [Scan RS (ms): Min: 0.0, Avg: 5.6, Max: 25.4, Diff: 25.4, Sum: 45.1] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 34.7, Avg: 53.8, Max: 68.0, Diff: 33.4, Sum: 430.4] [Termination (ms): Min: 0.0, Avg: 6.1, Max: 7.0, Diff: 7.0, Sum: 48.8] [Termination Attempts: Min: 1, Avg: 52.1, Max: 100, Diff: 99, Sum: 417] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2] [GC Worker Total (ms): Min: 59.8, Avg: 69.7, Max: 75.4, Diff: 15.6, Sum: 557.4] [GC Worker End (ms): Min: 4225.2, Avg: 4225.2, Max: 4225.2, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.0 ms] [Other: 0.4 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: 27.0M(27.0M)->0.0B(37.0M) Survivors: 3072.0K->4096.0K Heap: 112.6M(252.0M)->114.1M(252.0M)] [Times: user=0.33 sys=0.01, real=0.08 secs] 2015-09-14T12:32:28.882-0700: 4.840: [GC pause (G1 Humongous Allocation) (young) (initial-mark), 0.0446157 secs] [Parallel Time: 44.0 ms, GC Workers: 8] [GC Worker Start (ms): Min: 4840.3, Avg: 4840.5, Max: 4840.7, Diff: 0.4] [Ext Root Scanning (ms): Min: 0.0, Avg: 16.6, Max: 33.1, Diff: 33.1, Sum: 132.7] [Update RS (ms): Min: 0.0, Avg: 2.6, Max: 5.7, Diff: 5.7, Sum: 20.9] [Processed Buffers: Min: 0, Avg: 2.8, Max: 7, Diff: 7, Sum: 22] [Scan RS (ms): Min: 0.0, Avg: 0.7, Max: 1.8, Diff: 1.8, Sum: 5.9] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 10.5, Avg: 23.6, Max: 36.7, Diff: 26.2, Sum: 188.5] [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.2] [Termination Attempts: Min: 1, Avg: 84.3, Max: 176, Diff: 175, Sum: 674] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 43.4, Avg: 43.7, Max: 43.9, Diff: 0.4, Sum: 349.2] [GC Worker End (ms): Min: 4884.1, Avg: 4884.2, Max: 4884.2, Diff: 0.1] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.0 ms] [Other: 0.6 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.3 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: 13.0M(37.0M)->0.0B(43.0M) Survivors: 4096.0K->6144.0K Heap: 126.3M(252.0M)->128.1M(252.0M)] [Times: user=0.17 sys=0.02, real=0.05 secs] 2015-09-14T12:32:28.927-0700: 4.885: [GC concurrent-root-region-scan-start] 2015-09-14T12:32:28.934-0700: 4.892: [GC concurrent-root-region-scan-end, 0.0071426 secs] 2015-09-14T12:32:28.934-0700: 4.892: [GC concurrent-mark-start] 2015-09-14T12:32:29.102-0700: 5.060: [GC concurrent-mark-end, 0.1678427 secs] 2015-09-14T12:32:29.102-0700: 5.060: [GC remark 2015-09-14T12:32:29.102-0700: 5.060: [Finalize Marking, 0.0001736 secs] 2015-09-14T12:32:29.102-0700: 5.060: [GC ref-proc, 0.0000710 secs] 2015-09-14T12:32:29.102-0700: 5.060: [Unloading, 0.0004097 secs], 0.0010520 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:32:29.103-0700: 5.061: [GC cleanup 148M->128M(252M), 0.0004511 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:32:29.104-0700: 5.062: [GC concurrent-cleanup-start] 2015-09-14T12:32:29.104-0700: 5.062: [GC concurrent-cleanup-end, 0.0000197 secs] 2015-09-14T12:32:31.066-0700: 7.024: [GC pause (G1 Evacuation Pause) (young), 0.0661927 secs] [Parallel Time: 65.7 ms, GC Workers: 8] [GC Worker Start (ms): Min: 7024.2, Avg: 7037.9, Max: 7051.6, Diff: 27.5] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.2, Max: 0.4, Diff: 0.4, Sum: 1.2] [Update RS (ms): Min: 0.0, Avg: 4.8, Max: 10.0, Diff: 10.0, Sum: 38.2] [Processed Buffers: Min: 0, Avg: 3.4, Max: 8, Diff: 8, Sum: 27] [Scan RS (ms): Min: 0.0, Avg: 3.5, Max: 7.2, Diff: 7.2, Sum: 27.7] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 12.5, Avg: 37.3, Max: 48.8, Diff: 36.3, Sum: 298.4] [Termination (ms): Min: 0.0, Avg: 1.2, Max: 1.6, Diff: 1.6, Sum: 9.3] [Termination Attempts: Min: 1, Avg: 1.8, Max: 3, Diff: 2, Sum: 14] [GC Worker Other (ms): Min: 0.0, Avg: 5.1, Max: 24.1, Diff: 24.1, Sum: 40.9] [GC Worker Total (ms): Min: 38.2, Avg: 52.0, Max: 65.7, Diff: 27.4, Sum: 415.7] [GC Worker End (ms): Min: 7089.8, Avg: 7089.8, Max: 7089.8, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.0 ms] [Other: 0.4 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: 40.0M(40.0M)->0.0B(30.0M) Survivors: 6144.0K->6144.0K Heap: 164.1M(252.0M)->165.1M(252.0M)] [Times: user=0.30 sys=0.00, real=0.07 secs] 2015-09-14T12:32:31.260-0700: 7.218: [GC pause (G1 Humongous Allocation) (young) (initial-mark), 0.0238704 secs] [Parallel Time: 22.0 ms, GC Workers: 8] [GC Worker Start (ms): Min: 7217.9, Avg: 7218.1, Max: 7218.3, Diff: 0.4] [Ext Root Scanning (ms): Min: 0.0, Avg: 3.0, Max: 21.8, Diff: 21.8, Sum: 23.7] [Update RS (ms): Min: 0.0, Avg: 3.1, Max: 4.5, Diff: 4.5, Sum: 24.4] [Processed Buffers: Min: 0, Avg: 2.8, Max: 5, Diff: 5, Sum: 22] [Scan RS (ms): Min: 0.0, Avg: 0.8, Max: 1.4, Diff: 1.4, Sum: 6.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.0, Avg: 14.8, Max: 17.1, Diff: 17.1, Sum: 118.8] [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 1.0] [Termination Attempts: Min: 1, Avg: 19.4, Max: 30, Diff: 29, Sum: 155] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.2] [GC Worker Total (ms): Min: 21.5, Avg: 21.8, Max: 21.9, Diff: 0.3, Sum: 174.1] [GC Worker End (ms): Min: 7239.8, Avg: 7239.8, Max: 7239.9, Diff: 0.1] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.7 ms] [Other: 1.2 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.3 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.7 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 3072.0K(30.0M)->0.0B(26.0M) Survivors: 6144.0K->5120.0K Heap: 167.9M(252.0M)->171.6M(252.0M)] [Times: user=0.22 sys=0.00, real=0.02 secs] 2015-09-14T12:32:31.284-0700: 7.242: [GC concurrent-root-region-scan-start] 2015-09-14T12:32:31.296-0700: 7.254: [GC concurrent-root-region-scan-end, 0.0116955 secs] 2015-09-14T12:32:31.296-0700: 7.254: [GC concurrent-mark-start] 2015-09-14T12:32:31.557-0700: 7.515: [GC concurrent-mark-end, 0.2609997 secs] 2015-09-14T12:32:31.557-0700: 7.515: [GC remark 2015-09-14T12:32:31.557-0700: 7.515: [Finalize Marking, 0.0001586 secs] 2015-09-14T12:32:31.557-0700: 7.515: [GC ref-proc, 0.0000671 secs] 2015-09-14T12:32:31.557-0700: 7.515: [Unloading, 0.0004062 secs], 0.0012316 secs] [Times: user=0.13 sys=0.00, real=0.00 secs] 2015-09-14T12:32:31.558-0700: 7.516: [GC cleanup 200M->189M(252M), 0.0004952 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:32:31.559-0700: 7.517: [GC concurrent-cleanup-start] 2015-09-14T12:32:31.559-0700: 7.517: [GC concurrent-cleanup-end, 0.0000150 secs] 2015-09-14T12:32:32.725-0700: 8.683: [GC pause (G1 Evacuation Pause) (young), 0.0618549 secs] [Parallel Time: 61.4 ms, GC Workers: 8] [GC Worker Start (ms): Min: 8683.3, Avg: 8699.1, Max: 8743.8, Diff: 60.5] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.2] [Update RS (ms): Min: 0.0, Avg: 4.9, Max: 10.1, Diff: 10.1, Sum: 39.4] [Processed Buffers: Min: 0, Avg: 3.6, Max: 8, Diff: 8, Sum: 29] [Scan RS (ms): Min: 0.0, Avg: 0.9, Max: 1.9, Diff: 1.9, Sum: 7.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.0, Avg: 38.8, Max: 60.1, Diff: 60.1, Sum: 310.7] [Termination (ms): Min: 0.0, Avg: 0.7, Max: 0.9, Diff: 0.9, Sum: 5.4] [Termination Attempts: Min: 1, Avg: 26.8, Max: 52, Diff: 51, Sum: 214] [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: 45.5, Max: 61.3, Diff: 60.5, Sum: 364.0] [GC Worker End (ms): Min: 8744.6, Avg: 8744.6, Max: 8744.6, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.0 ms] [Other: 0.4 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(26.0M)->0.0B(8192.0K) Survivors: 5120.0K->4096.0K Heap: 210.6M(252.0M)->209.6M(252.0M)] [Times: user=0.27 sys=0.00, real=0.06 secs] 2015-09-14T12:32:33.196-0700: 9.154: [GC pause (G1 Evacuation Pause) (mixed), 0.0262634 secs] [Parallel Time: 24.9 ms, GC Workers: 8] [GC Worker Start (ms): Min: 9154.2, Avg: 9163.6, Max: 9179.0, Diff: 24.9] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.2, Max: 0.4, Diff: 0.4, Sum: 1.3] [Update RS (ms): Min: 0.0, Avg: 3.6, Max: 7.7, Diff: 7.7, Sum: 29.2] [Processed Buffers: Min: 0, Avg: 3.8, Max: 10, Diff: 10, Sum: 30] [Scan RS (ms): Min: 0.0, Avg: 0.3, Max: 0.9, Diff: 0.9, Sum: 2.5] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 0.0, Avg: 11.3, Max: 24.5, Diff: 24.5, Sum: 90.3] [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.6] [Termination Attempts: Min: 1, Avg: 14.3, Max: 42, Diff: 41, Sum: 114] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.2] [GC Worker Total (ms): Min: 0.0, Avg: 15.5, Max: 24.9, Diff: 24.9, Sum: 124.1] [GC Worker End (ms): Min: 9179.1, Avg: 9179.1, Max: 9179.1, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.3 ms] [Other: 1.1 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.3 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.6 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 8192.0K(8192.0K)->0.0B(10.0M) Survivors: 4096.0K->2048.0K Heap: 217.6M(252.0M)->216.6M(252.0M)] [Times: user=0.06 sys=0.00, real=0.03 secs] 2015-09-14T12:32:33.746-0700: 9.704: [GC pause (G1 Evacuation Pause) (young), 0.0324759 secs] [Parallel Time: 30.5 ms, GC Workers: 8] [GC Worker Start (ms): Min: 9704.4, Avg: 9715.8, Max: 9734.5, Diff: 30.0] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.2, Max: 0.7, Diff: 0.7, Sum: 1.7] [Update RS (ms): Min: 0.0, Avg: 4.0, Max: 8.2, Diff: 8.2, Sum: 31.7] [Processed Buffers: Min: 0, Avg: 3.9, Max: 9, Diff: 9, Sum: 31] [Scan RS (ms): Min: 0.0, Avg: 0.2, Max: 0.4, Diff: 0.4, Sum: 1.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.0, Avg: 14.3, Max: 29.7, Diff: 29.7, Sum: 114.5] [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.5, Diff: 0.5, Sum: 1.1] [Termination Attempts: Min: 1, Avg: 14.3, Max: 39, Diff: 38, Sum: 114] [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.0, Avg: 18.8, Max: 30.5, Diff: 30.4, Sum: 150.4] [GC Worker End (ms): Min: 9734.5, Avg: 9734.6, Max: 9734.9, Diff: 0.4] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.9 ms] [Other: 1.1 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.3 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.6 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 10.0M(10.0M)->0.0B(10.0M) Survivors: 2048.0K->2048.0K Heap: 226.6M(252.0M)->227.6M(252.0M)] [Times: user=0.12 sys=0.00, real=0.03 secs] 2015-09-14T12:32:34.295-0700: 10.253: [GC pause (G1 Evacuation Pause) (young) (initial-mark), 0.0330309 secs] [Parallel Time: 32.0 ms, GC Workers: 8] [GC Worker Start (ms): Min: 10253.1, Avg: 10253.3, Max: 10253.5, Diff: 0.4] [Ext Root Scanning (ms): Min: 0.0, Avg: 15.9, Max: 31.5, Diff: 31.5, Sum: 127.4] [Update RS (ms): Min: 0.0, Avg: 4.2, Max: 8.8, Diff: 8.8, Sum: 33.4] [Processed Buffers: Min: 0, Avg: 4.0, Max: 10, Diff: 10, Sum: 32] [Scan RS (ms): Min: 0.0, Avg: 0.2, Max: 1.1, Diff: 1.1, Sum: 1.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.0, Avg: 11.4, Max: 22.7, Diff: 22.7, Sum: 90.9] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4] [Termination Attempts: Min: 1, Avg: 12.0, Max: 27, Diff: 26, Sum: 96] [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.5, Avg: 31.7, Max: 31.9, Diff: 0.4, Sum: 253.5] [GC Worker End (ms): Min: 10285.0, Avg: 10285.0, Max: 10285.0, Diff: 0.1] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.2 ms] [Other: 0.7 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.4 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 10.0M(10.0M)->0.0B(10.0M) Survivors: 2048.0K->2048.0K Heap: 237.6M(252.0M)->239.1M(252.0M)] [Times: user=0.13 sys=0.00, real=0.03 secs] 2015-09-14T12:32:34.328-0700: 10.286: [GC concurrent-root-region-scan-start] 2015-09-14T12:32:34.331-0700: 10.289: [GC concurrent-root-region-scan-end, 0.0028386 secs] 2015-09-14T12:32:34.331-0700: 10.289: [GC concurrent-mark-start] 2015-09-14T12:32:34.701-0700: 10.659: [GC concurrent-mark-end, 0.3702883 secs] 2015-09-14T12:32:34.701-0700: 10.659: [GC remark 2015-09-14T12:32:34.701-0700: 10.660: [Finalize Marking, 0.0001899 secs] 2015-09-14T12:32:34.702-0700: 10.660: [GC ref-proc, 0.0000693 secs] 2015-09-14T12:32:34.702-0700: 10.660: [Unloading, 0.0005020 secs], 0.0015424 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:32:34.703-0700: 10.661: [GC cleanup 246M->230M(252M), 0.0006431 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:32:34.704-0700: 10.662: [GC concurrent-cleanup-start] 2015-09-14T12:32:34.704-0700: 10.662: [GC concurrent-cleanup-end, 0.0000141 secs] 2015-09-14T12:32:34.887-0700: 10.845: [GC pause (G1 Evacuation Pause) (young), 0.0216904 secs] [Parallel Time: 21.3 ms, GC Workers: 8] [GC Worker Start (ms): Min: 10844.7, Avg: 10848.6, Max: 10865.7, Diff: 21.0] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.5] [Update RS (ms): Min: 0.0, Avg: 2.5, Max: 5.3, Diff: 5.3, Sum: 20.3] [Processed Buffers: Min: 0, Avg: 4.6, Max: 10, Diff: 10, Sum: 37] [Scan RS (ms): Min: 0.0, Avg: 0.4, Max: 1.1, Diff: 1.1, Sum: 3.6] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 0.0, Avg: 14.0, Max: 20.8, Diff: 20.8, Sum: 112.2] [Termination (ms): Min: 0.0, Avg: 0.3, Max: 0.3, Diff: 0.3, Sum: 2.2] [Termination Attempts: Min: 1, Avg: 69.3, Max: 132, Diff: 131, Sum: 554] [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.3, Avg: 17.4, Max: 21.3, Diff: 21.0, Sum: 138.9] [GC Worker End (ms): Min: 10866.0, Avg: 10866.0, Max: 10866.0, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.0 ms] [Other: 0.3 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.1 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 10.0M(10.0M)->0.0B(12.0M) Survivors: 2048.0K->2048.0K Heap: 268.7M(288.0M)->270.7M(288.0M)] [Times: user=0.06 sys=0.00, real=0.02 secs] 2015-09-14T12:32:35.525-0700: 11.483: [GC pause (G1 Evacuation Pause) (mixed), 0.0361723 secs] [Parallel Time: 35.8 ms, GC Workers: 8] [GC Worker Start (ms): Min: 11483.4, Avg: 11483.5, Max: 11483.5, Diff: 0.2] [Ext Root Scanning (ms): Min: 0.2, Avg: 0.2, Max: 0.3, Diff: 0.2, Sum: 1.9] [Update RS (ms): Min: 8.6, Avg: 9.5, Max: 10.4, Diff: 1.8, Sum: 76.3] [Processed Buffers: Min: 4, Avg: 4.6, Max: 7, Diff: 3, Sum: 37] [Scan RS (ms): Min: 0.0, Avg: 0.7, Max: 1.5, Diff: 1.5, Sum: 5.9] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 25.0, Avg: 25.1, Max: 25.2, Diff: 0.3, Sum: 200.9] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.2] [Termination Attempts: Min: 1, Avg: 33.9, Max: 47, Diff: 46, Sum: 271] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 35.6, Avg: 35.7, Max: 35.8, Diff: 0.2, Sum: 285.2] [GC Worker End (ms): Min: 11519.1, Avg: 11519.1, Max: 11519.1, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.1 ms] [Other: 0.3 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.1 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 12.0M(12.0M)->0.0B(13.0M) Survivors: 2048.0K->2048.0K Heap: 282.7M(288.0M)->281.2M(301.0M)] [Times: user=0.25 sys=0.00, real=0.04 secs] 2015-09-14T12:32:36.262-0700: 12.220: [GC pause (G1 Evacuation Pause) (young), 0.0527119 secs] [Parallel Time: 52.0 ms, GC Workers: 8] [GC Worker Start (ms): Min: 12220.6, Avg: 12230.9, Max: 12264.6, Diff: 44.0] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.2] [Update RS (ms): Min: 0.0, Avg: 7.2, Max: 18.1, Diff: 18.1, Sum: 57.7] [Processed Buffers: Min: 0, Avg: 5.4, Max: 11, Diff: 11, Sum: 43] [Scan RS (ms): Min: 0.0, Avg: 0.2, Max: 0.5, Diff: 0.5, Sum: 1.5] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 7.4, Avg: 33.7, Max: 51.1, Diff: 43.7, Sum: 269.3] [Termination (ms): Min: 0.0, Avg: 0.3, Max: 0.4, Diff: 0.4, Sum: 2.1] [Termination Attempts: Min: 1, Avg: 145.1, Max: 300, Diff: 299, Sum: 1161] [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.8, Avg: 41.5, Max: 51.8, Diff: 44.0, Sum: 331.9] [GC Worker End (ms): Min: 12272.4, Avg: 12272.4, Max: 12272.5, Diff: 0.1] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.0 ms] [Other: 0.7 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.2 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.4 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 13.0M(13.0M)->0.0B(13.0M) Survivors: 2048.0K->2048.0K Heap: 294.2M(301.0M)->296.2M(313.0M)] [Times: user=0.19 sys=0.00, real=0.05 secs] 2015-09-14T12:32:37.015-0700: 12.973: [GC pause (G1 Evacuation Pause) (young) (initial-mark), 0.0501756 secs] [Parallel Time: 49.4 ms, GC Workers: 8] [GC Worker Start (ms): Min: 12973.6, Avg: 12973.9, Max: 12974.1, Diff: 0.4] [Ext Root Scanning (ms): Min: 0.0, Avg: 14.6, Max: 39.7, Diff: 39.7, Sum: 117.1] [Update RS (ms): Min: 0.0, Avg: 5.6, Max: 11.2, Diff: 11.2, Sum: 44.8] [Processed Buffers: Min: 0, Avg: 5.6, Max: 12, Diff: 12, Sum: 45] [Scan RS (ms): Min: 0.0, Avg: 0.2, Max: 0.4, Diff: 0.4, Sum: 1.5] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 9.0, Avg: 28.4, Max: 37.1, Diff: 28.2, Sum: 227.3] [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.8] [Termination Attempts: Min: 1, Avg: 2.9, Max: 7, Diff: 6, Sum: 23] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 48.7, Avg: 49.0, Max: 49.2, Diff: 0.4, Sum: 391.6] [GC Worker End (ms): Min: 13022.8, Avg: 13022.8, Max: 13022.9, Diff: 0.1] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.2 ms] [Other: 0.6 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.4 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 13.0M(13.0M)->0.0B(14.0M) Survivors: 2048.0K->2048.0K Heap: 309.2M(313.0M)->311.2M(328.0M)] [Times: user=0.25 sys=0.01, real=0.05 secs] 2015-09-14T12:32:37.066-0700: 13.024: [GC concurrent-root-region-scan-start] 2015-09-14T12:32:37.069-0700: 13.027: [GC concurrent-root-region-scan-end, 0.0027984 secs] 2015-09-14T12:32:37.069-0700: 13.027: [GC concurrent-mark-start] 2015-09-14T12:32:37.549-0700: 13.507: [GC concurrent-mark-end, 0.4806417 secs] 2015-09-14T12:32:37.549-0700: 13.507: [GC remark 2015-09-14T12:32:37.549-0700: 13.507: [Finalize Marking, 0.0001758 secs] 2015-09-14T12:32:37.550-0700: 13.508: [GC ref-proc, 0.0000718 secs] 2015-09-14T12:32:37.550-0700: 13.508: [Unloading, 0.0003866 secs], 0.0016737 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:32:37.551-0700: 13.509: [GC cleanup 320M->296M(328M), 0.0006543 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:32:37.552-0700: 13.510: [GC concurrent-cleanup-start] 2015-09-14T12:32:37.552-0700: 13.510: [GC concurrent-cleanup-end, 0.0000171 secs] 2015-09-14T12:32:37.784-0700: 13.742: [GC pause (G1 Evacuation Pause) (young), 0.0304288 secs] [Parallel Time: 30.0 ms, GC Workers: 8] [GC Worker Start (ms): Min: 13742.6, Avg: 13753.8, Max: 13772.4, Diff: 29.8] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.1] [Update RS (ms): Min: 0.0, Avg: 6.0, Max: 12.2, Diff: 12.2, Sum: 48.3] [Processed Buffers: Min: 0, Avg: 5.9, Max: 13, Diff: 13, Sum: 47] [Scan RS (ms): Min: 0.0, Avg: 0.2, Max: 0.6, Diff: 0.6, Sum: 1.6] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 0.0, Avg: 12.1, Max: 29.4, Diff: 29.4, Sum: 96.7] [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.0] [Termination Attempts: Min: 1, Avg: 4.3, Max: 12, Diff: 11, Sum: 34] [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.0, Avg: 18.6, Max: 29.8, Diff: 29.8, Sum: 148.8] [GC Worker End (ms): Min: 13772.4, Avg: 13772.4, Max: 13772.6, Diff: 0.2] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.0 ms] [Other: 0.4 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: 14.0M(14.0M)->0.0B(14.0M) Survivors: 2048.0K->2048.0K Heap: 301.7M(328.0M)->303.2M(328.0M)] [Times: user=0.13 sys=0.00, real=0.03 secs] 2015-09-14T12:32:38.576-0700: 14.534: [GC pause (G1 Evacuation Pause) (mixed), 0.0491010 secs] [Parallel Time: 48.6 ms, GC Workers: 8] [GC Worker Start (ms): Min: 14534.5, Avg: 14547.0, Max: 14567.7, Diff: 33.2] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.2] [Update RS (ms): Min: 0.0, Avg: 6.5, Max: 13.3, Diff: 13.3, Sum: 52.3] [Processed Buffers: Min: 0, Avg: 6.1, Max: 13, Diff: 13, Sum: 49] [Scan RS (ms): Min: 0.0, Avg: 0.6, Max: 1.5, Diff: 1.5, Sum: 5.1] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 14.9, Avg: 28.4, Max: 47.8, Diff: 32.8, Sum: 227.0] [Termination (ms): Min: 0.0, Avg: 0.3, Max: 0.4, Diff: 0.4, Sum: 2.6] [Termination Attempts: Min: 1, Avg: 35.8, Max: 82, Diff: 81, Sum: 286] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 15.4, Avg: 36.0, Max: 48.5, Diff: 33.2, Sum: 288.3] [GC Worker End (ms): Min: 14583.1, Avg: 14583.1, Max: 14583.1, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.0 ms] [Other: 0.4 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: 14.0M(14.0M)->0.0B(14.0M) Survivors: 2048.0K->2048.0K Heap: 317.2M(328.0M)->312.7M(338.0M)] [Times: user=0.25 sys=0.00, real=0.05 secs] 2015-09-14T12:32:39.350-0700: 15.308: [GC pause (G1 Evacuation Pause) (young), 0.0411797 secs] [Parallel Time: 40.6 ms, GC Workers: 8] [GC Worker Start (ms): Min: 15307.8, Avg: 15318.5, Max: 15347.8, Diff: 40.0] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.2] [Update RS (ms): Min: 0.0, Avg: 10.2, Max: 31.2, Diff: 31.2, Sum: 81.7] [Processed Buffers: Min: 0, Avg: 6.5, Max: 16, Diff: 16, Sum: 52] [Scan RS (ms): Min: 0.0, Avg: 0.3, Max: 0.8, Diff: 0.8, Sum: 2.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.0, Avg: 18.7, Max: 39.6, Diff: 39.6, Sum: 149.9] [Termination (ms): Min: 0.0, Avg: 0.5, Max: 0.6, Diff: 0.6, Sum: 3.7] [Termination Attempts: Min: 1, Avg: 8.5, Max: 24, Diff: 23, Sum: 68] [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.5, Avg: 29.8, Max: 40.5, Diff: 40.0, Sum: 238.6] [GC Worker End (ms): Min: 15348.3, Avg: 15348.3, Max: 15348.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.2 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.2 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 14.0M(14.0M)->0.0B(15.0M) Survivors: 2048.0K->2048.0K Heap: 326.7M(338.0M)->328.2M(345.0M)] [Times: user=0.16 sys=0.02, real=0.04 secs] 2015-09-14T12:32:40.140-0700: 16.097: [GC pause (G1 Humongous Allocation) (young) (initial-mark), 0.0475282 secs] [Parallel Time: 47.1 ms, GC Workers: 8] [GC Worker Start (ms): Min: 16097.2, Avg: 16097.4, Max: 16097.7, Diff: 0.5] [Ext Root Scanning (ms): Min: 0.0, Avg: 15.8, Max: 45.9, Diff: 45.9, Sum: 126.8] [Update RS (ms): Min: 0.0, Avg: 6.9, Max: 14.1, Diff: 14.1, Sum: 55.4] [Processed Buffers: Min: 0, Avg: 6.9, Max: 15, Diff: 15, Sum: 55] [Scan RS (ms): Min: 0.0, Avg: 0.1, Max: 0.5, Diff: 0.5, Sum: 1.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.0, Avg: 23.3, Max: 32.2, Diff: 32.2, Sum: 186.3] [Termination (ms): Min: 0.0, Avg: 0.5, Max: 0.6, Diff: 0.6, Sum: 3.9] [Termination Attempts: Min: 1, Avg: 28.5, Max: 60, Diff: 59, Sum: 228] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 46.4, Avg: 46.7, Max: 47.0, Diff: 0.5, Sum: 373.5] [GC Worker End (ms): Min: 16144.1, Avg: 16144.1, Max: 16144.1, 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.1 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 15.0M(15.0M)->0.0B(15.0M) Survivors: 2048.0K->3072.0K Heap: 342.4M(345.0M)->343.7M(362.0M)] [Times: user=0.19 sys=0.02, real=0.06 secs] 2015-09-14T12:32:40.197-0700: 16.154: [GC concurrent-root-region-scan-start] 2015-09-14T12:32:40.202-0700: 16.160: [GC concurrent-root-region-scan-end, 0.0054757 secs] 2015-09-14T12:32:40.202-0700: 16.160: [GC concurrent-mark-start] 2015-09-14T12:32:40.787-0700: 16.744: [GC concurrent-mark-end, 0.5848057 secs] 2015-09-14T12:32:40.787-0700: 16.745: [GC remark 2015-09-14T12:32:40.787-0700: 16.745: [Finalize Marking, 0.0001839 secs] 2015-09-14T12:32:40.788-0700: 16.745: [GC ref-proc, 0.0000671 secs] 2015-09-14T12:32:40.788-0700: 16.745: [Unloading, 0.0005234 secs], 0.0019517 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:32:40.789-0700: 16.747: [GC cleanup 407M->407M(415M), 0.0007436 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:32:41.152-0700: 17.109: [GC pause (G1 Evacuation Pause) (young), 0.0703954 secs] [Parallel Time: 69.8 ms, GC Workers: 8] [GC Worker Start (ms): Min: 17109.6, Avg: 17117.0, Max: 17132.2, Diff: 22.6] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.2] [Update RS (ms): Min: 0.0, Avg: 18.7, Max: 46.5, Diff: 46.5, Sum: 149.7] [Processed Buffers: Min: 0, Avg: 7.4, Max: 15, Diff: 15, Sum: 59] [Scan RS (ms): Min: 0.0, Avg: 0.6, Max: 1.8, Diff: 1.8, Sum: 4.7] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 19.9, Avg: 40.2, Max: 57.7, Diff: 37.8, Sum: 321.3] [Termination (ms): Min: 0.0, Avg: 2.6, Max: 3.1, Diff: 3.1, Sum: 20.8] [Termination Attempts: Min: 1, Avg: 49.0, Max: 111, Diff: 110, Sum: 392] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 47.0, Avg: 62.2, Max: 69.7, Diff: 22.7, Sum: 497.7] [GC Worker End (ms): Min: 17179.2, Avg: 17179.2, Max: 17179.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.3 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: 17.0M(17.0M)->0.0B(18.0M) Survivors: 3072.0K->3072.0K Heap: 413.5M(415.0M)->416.5M(438.0M)] [Times: user=0.22 sys=0.01, real=0.07 secs] 2015-09-14T12:32:42.151-0700: 18.109: [GC pause (G1 Evacuation Pause) (mixed), 0.0547448 secs] [Parallel Time: 54.2 ms, GC Workers: 8] [GC Worker Start (ms): Min: 18108.7, Avg: 18121.5, Max: 18144.5, Diff: 35.8] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.2, Max: 0.4, Diff: 0.4, Sum: 1.2] [Update RS (ms): Min: 0.0, Avg: 12.1, Max: 35.4, Diff: 35.4, Sum: 97.1] [Processed Buffers: Min: 0, Avg: 7.9, Max: 16, Diff: 16, Sum: 63] [Scan RS (ms): Min: 0.0, Avg: 0.2, Max: 1.3, Diff: 1.3, Sum: 1.8] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 18.1, Avg: 28.7, Max: 37.0, Diff: 18.9, Sum: 229.4] [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.6] [Termination Attempts: Min: 1, Avg: 158.8, Max: 298, Diff: 297, Sum: 1270] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 18.2, Avg: 41.3, Max: 54.0, Diff: 35.8, Sum: 330.3] [GC Worker End (ms): Min: 18162.8, Avg: 18162.8, Max: 18162.9, 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.3 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: 18.0M(18.0M)->0.0B(19.0M) Survivors: 3072.0K->3072.0K Heap: 434.5M(438.0M)->435.5M(459.0M)] [Times: user=0.19 sys=0.02, real=0.06 secs] 2015-09-14T12:32:43.224-0700: 19.181: [GC pause (G1 Evacuation Pause) (young), 0.0605468 secs] [Parallel Time: 60.1 ms, GC Workers: 8] [GC Worker Start (ms): Min: 19181.5, Avg: 19192.1, Max: 19207.5, Diff: 26.0] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.2, Max: 0.3, Diff: 0.3, Sum: 1.2] [Update RS (ms): Min: 0.0, Avg: 10.8, Max: 20.8, Diff: 20.8, Sum: 86.3] [Processed Buffers: Min: 0, Avg: 8.9, Max: 18, Diff: 18, Sum: 71] [Scan RS (ms): Min: 0.0, Avg: 0.3, Max: 0.9, Diff: 0.9, Sum: 2.0] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 34.0, Avg: 38.3, Max: 40.8, Diff: 6.9, Sum: 306.2] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3] [Termination Attempts: Min: 1, Avg: 111.4, Max: 214, Diff: 213, Sum: 891] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 34.0, Avg: 49.5, Max: 60.1, Diff: 26.0, Sum: 396.2] [GC Worker End (ms): Min: 19241.6, Avg: 19241.6, Max: 19241.6, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.0 ms] [Other: 0.4 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: 19.0M(19.0M)->0.0B(21.0M) Survivors: 3072.0K->3072.0K Heap: 454.5M(459.0M)->456.5M(480.0M)] [Times: user=0.27 sys=0.00, real=0.06 secs] 2015-09-14T12:32:44.404-0700: 20.362: [GC pause (G1 Evacuation Pause) (young) (initial-mark), 0.0612242 secs] [Parallel Time: 60.7 ms, GC Workers: 8] [GC Worker Start (ms): Min: 20361.8, Avg: 20362.0, Max: 20362.2, Diff: 0.4] [Ext Root Scanning (ms): Min: 0.0, Avg: 15.3, Max: 32.3, Diff: 32.2, Sum: 122.0] [Update RS (ms): Min: 0.0, Avg: 10.7, Max: 21.7, Diff: 21.7, Sum: 85.4] [Processed Buffers: Min: 0, Avg: 9.4, Max: 19, Diff: 19, Sum: 75] [Scan RS (ms): Min: 0.0, Avg: 0.3, Max: 1.2, Diff: 1.2, Sum: 2.7] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 24.8, Avg: 31.4, Max: 38.0, Diff: 13.2, Sum: 251.1] [Termination (ms): Min: 0.0, Avg: 2.7, Max: 3.2, Diff: 3.2, Sum: 21.9] [Termination Attempts: Min: 1, Avg: 103.9, Max: 203, Diff: 202, Sum: 831] [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.4, Max: 60.6, Diff: 0.4, Sum: 483.1] [GC Worker End (ms): Min: 20422.4, Avg: 20422.4, Max: 20422.5, Diff: 0.1] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.0 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.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 21.0M(21.0M)->0.0B(22.0M) Survivors: 3072.0K->3072.0K Heap: 477.5M(480.0M)->480.0M(505.0M)] [Times: user=0.23 sys=0.03, real=0.06 secs] 2015-09-14T12:32:44.466-0700: 20.423: [GC concurrent-root-region-scan-start] 2015-09-14T12:32:44.469-0700: 20.426: [GC concurrent-root-region-scan-end, 0.0031323 secs] 2015-09-14T12:32:44.469-0700: 20.426: [GC concurrent-mark-start] 2015-09-14T12:32:45.198-0700: 21.156: [GC concurrent-mark-end, 0.7294776 secs] 2015-09-14T12:32:45.198-0700: 21.156: [GC remark 2015-09-14T12:32:45.199-0700: 21.156: [Finalize Marking, 0.0001924 secs] 2015-09-14T12:32:45.199-0700: 21.156: [GC ref-proc, 0.0000667 secs] 2015-09-14T12:32:45.199-0700: 21.156: [Unloading, 0.0004387 secs], 0.0020573 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:32:45.201-0700: 21.158: [GC cleanup 494M->459M(505M), 0.0006444 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:32:45.201-0700: 21.159: [GC concurrent-cleanup-start] 2015-09-14T12:32:45.201-0700: 21.159: [GC concurrent-cleanup-end, 0.0000248 secs] 2015-09-14T12:32:45.671-0700: 21.628: [GC pause (G1 Evacuation Pause) (young), 0.0414136 secs] [Parallel Time: 41.0 ms, GC Workers: 8] [GC Worker Start (ms): Min: 21628.5, Avg: 21628.6, Max: 21628.6, Diff: 0.1] [Ext Root Scanning (ms): Min: 0.2, Avg: 0.2, Max: 0.3, Diff: 0.1, Sum: 1.9] [Update RS (ms): Min: 20.4, Avg: 20.9, Max: 21.5, Diff: 1.1, Sum: 167.0] [Processed Buffers: Min: 9, Avg: 10.0, Max: 11, Diff: 2, Sum: 80] [Scan RS (ms): Min: 0.0, Avg: 0.5, Max: 1.0, Diff: 1.0, Sum: 3.9] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 19.1, Avg: 19.3, Max: 19.4, Diff: 0.3, Sum: 154.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: 8] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.2] [GC Worker Total (ms): Min: 40.8, Avg: 40.9, Max: 41.0, Diff: 0.1, Sum: 327.1] [GC Worker End (ms): Min: 21669.5, Avg: 21669.5, Max: 21669.5, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.1 ms] [Other: 0.3 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.1 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 22.0M(22.0M)->0.0B(21.0M) Survivors: 3072.0K->4096.0K Heap: 466.8M(505.0M)->469.8M(505.0M)] [Times: user=0.37 sys=0.00, real=0.04 secs] 2015-09-14T12:32:46.820-0700: 22.777: [GC pause (G1 Evacuation Pause) (mixed), 0.0579280 secs] [Parallel Time: 57.1 ms, GC Workers: 8] [GC Worker Start (ms): Min: 22777.1, Avg: 22792.2, Max: 22834.0, Diff: 56.9] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.2] [Update RS (ms): Min: 0.0, Avg: 9.5, Max: 19.3, Diff: 19.3, Sum: 75.9] [Processed Buffers: Min: 0, Avg: 9.3, Max: 19, Diff: 19, Sum: 74] [Scan RS (ms): Min: 0.0, Avg: 0.5, Max: 1.3, Diff: 1.3, Sum: 4.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.0, Avg: 31.5, Max: 56.4, Diff: 56.4, Sum: 252.3] [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 1.2] [Termination Attempts: Min: 1, Avg: 2.3, Max: 5, Diff: 4, Sum: 18] [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.2, Avg: 41.8, Max: 56.9, Diff: 56.8, Sum: 334.6] [GC Worker End (ms): Min: 22834.1, Avg: 22834.1, Max: 22834.1, Diff: 0.1] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.2 ms] [Other: 0.6 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.3 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.1 ms] [Eden: 21.0M(21.0M)->0.0B(22.0M) Survivors: 4096.0K->4096.0K Heap: 490.8M(505.0M)->485.8M(520.0M)] [Times: user=0.27 sys=0.00, real=0.06 secs] 2015-09-14T12:32:48.093-0700: 24.050: [GC pause (G1 Evacuation Pause) (young), 0.0624493 secs] [Parallel Time: 61.9 ms, GC Workers: 8] [GC Worker Start (ms): Min: 24050.6, Avg: 24070.3, Max: 24094.5, Diff: 43.9] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.2] [Update RS (ms): Min: 0.0, Avg: 11.3, Max: 23.3, Diff: 23.3, Sum: 90.6] [Processed Buffers: Min: 0, Avg: 9.8, Max: 20, Diff: 20, Sum: 78] [Scan RS (ms): Min: 0.0, Avg: 0.4, Max: 1.2, Diff: 1.2, Sum: 2.8] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 14.2, Avg: 27.1, Max: 38.0, Diff: 23.8, Sum: 216.5] [Termination (ms): Min: 0.0, Avg: 3.2, Max: 3.8, Diff: 3.8, Sum: 25.5] [Termination Attempts: Min: 1, Avg: 127.4, Max: 273, Diff: 272, Sum: 1019] [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.8, Avg: 42.1, Max: 61.7, Diff: 43.9, Sum: 336.8] [GC Worker End (ms): Min: 24112.3, Avg: 24112.4, Max: 24112.5, Diff: 0.1] [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.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] [Eden: 22.0M(22.0M)->0.0B(22.0M) Survivors: 4096.0K->4096.0K Heap: 507.8M(520.0M)->510.3M(537.0M)] [Times: user=0.25 sys=0.00, real=0.06 secs] 2015-09-14T12:32:48.265-0700: 24.222: [GC pause (G1 Humongous Allocation) (young) (initial-mark), 0.0220316 secs] [Parallel Time: 20.8 ms, GC Workers: 8] [GC Worker Start (ms): Min: 24222.2, Avg: 24222.3, Max: 24222.5, Diff: 0.3] [Ext Root Scanning (ms): Min: 0.0, Avg: 2.8, Max: 20.6, Diff: 20.6, Sum: 22.5] [Update RS (ms): Min: 0.0, Avg: 11.4, Max: 13.5, Diff: 13.5, Sum: 91.6] [Processed Buffers: Min: 0, Avg: 8.1, Max: 14, Diff: 14, Sum: 65] [Scan RS (ms): Min: 0.0, Avg: 0.2, Max: 0.5, Diff: 0.5, Sum: 1.5] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 0.0, Avg: 6.0, Max: 7.0, Diff: 7.0, Sum: 48.2] [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.7] [Termination Attempts: Min: 1, Avg: 53.8, Max: 81, Diff: 80, Sum: 430] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2] [GC Worker Total (ms): Min: 20.3, Avg: 20.6, Max: 20.7, Diff: 0.4, Sum: 164.8] [GC Worker End (ms): Min: 24242.8, Avg: 24242.9, Max: 24242.9, Diff: 0.1] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.5 ms] [Other: 0.7 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.4 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.2 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 3072.0K(22.0M)->0.0B(22.0M) Survivors: 4096.0K->4096.0K Heap: 512.8M(537.0M)->516.3M(537.0M)] [Times: user=0.11 sys=0.00, real=0.02 secs] 2015-09-14T12:32:48.287-0700: 24.244: [GC concurrent-root-region-scan-start] 2015-09-14T12:32:48.290-0700: 24.247: [GC concurrent-root-region-scan-end, 0.0029378 secs] 2015-09-14T12:32:48.290-0700: 24.247: [GC concurrent-mark-start] 2015-09-14T12:32:49.182-0700: 25.139: [GC concurrent-mark-end, 0.8918005 secs] 2015-09-14T12:32:49.182-0700: 25.139: [GC remark 2015-09-14T12:32:49.182-0700: 25.139: [Finalize Marking, 0.0001514 secs] 2015-09-14T12:32:49.182-0700: 25.139: [GC ref-proc, 0.0000671 secs] 2015-09-14T12:32:49.182-0700: 25.139: [Unloading, 0.0004439 secs], 0.0022822 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:32:49.184-0700: 25.142: [GC cleanup 610M->610M(617M), 0.0006538 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:32:49.457-0700: 25.415: [GC pause (G1 Evacuation Pause) (young), 0.0546358 secs] [Parallel Time: 54.1 ms, GC Workers: 8] [GC Worker Start (ms): Min: 25414.7, Avg: 25421.2, Max: 25440.2, Diff: 25.5] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.2] [Update RS (ms): Min: 0.0, Avg: 12.3, Max: 25.1, Diff: 25.1, Sum: 98.7] [Processed Buffers: Min: 0, Avg: 11.0, Max: 24, Diff: 24, Sum: 88] [Scan RS (ms): Min: 0.0, Avg: 0.1, Max: 0.4, Diff: 0.4, Sum: 0.9] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 28.4, Avg: 34.9, Max: 53.6, Diff: 25.2, Sum: 278.8] [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.6] [Termination Attempts: Min: 1, Avg: 138.0, Max: 168, Diff: 167, Sum: 1104] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 28.5, Avg: 47.5, Max: 54.0, Diff: 25.5, Sum: 380.3] [GC Worker End (ms): Min: 25468.7, Avg: 25468.7, Max: 25468.8, 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.1 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 20.0M(22.0M)->0.0B(28.0M) Survivors: 4096.0K->4096.0K Heap: 615.5M(617.0M)->616.0M(641.0M)] [Times: user=0.19 sys=0.00, real=0.06 secs] 2015-09-14T12:32:50.742-0700: 26.700: [GC pause (G1 Evacuation Pause) (mixed), 0.0640473 secs] [Parallel Time: 63.6 ms, GC Workers: 8] [GC Worker Start (ms): Min: 26699.8, Avg: 26705.0, Max: 26732.0, Diff: 32.2] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.2] [Update RS (ms): Min: 0.0, Avg: 15.4, Max: 27.3, Diff: 27.3, Sum: 123.2] [Processed Buffers: Min: 0, Avg: 11.8, Max: 24, Diff: 24, Sum: 94] [Scan RS (ms): Min: 0.0, Avg: 0.4, Max: 1.0, Diff: 1.0, Sum: 3.3] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 29.5, Avg: 40.8, Max: 61.3, Diff: 31.9, Sum: 326.1] [Termination (ms): Min: 0.0, Avg: 1.6, Max: 1.9, Diff: 1.9, Sum: 12.9] [Termination Attempts: Min: 1, Avg: 92.8, Max: 139, Diff: 138, Sum: 742] [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.3, Avg: 58.3, Max: 63.5, Diff: 32.2, Sum: 466.7] [GC Worker End (ms): Min: 26763.3, Avg: 26763.4, Max: 26763.4, 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.1 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 24.0M(28.0M)->0.0B(29.0M) Survivors: 4096.0K->4096.0K Heap: 640.0M(641.0M)->639.0M(672.0M)] [Times: user=0.25 sys=0.00, real=0.06 secs] 2015-09-14T12:32:52.332-0700: 28.290: [GC pause (G1 Evacuation Pause) (young), 0.0783171 secs] [Parallel Time: 77.9 ms, GC Workers: 8] [GC Worker Start (ms): Min: 28289.7, Avg: 28303.9, Max: 28334.1, Diff: 44.4] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.2] [Update RS (ms): Min: 0.0, Avg: 13.8, Max: 27.9, Diff: 27.9, Sum: 110.7] [Processed Buffers: Min: 0, Avg: 13.3, Max: 27, Diff: 27, Sum: 106] [Scan RS (ms): Min: 0.0, Avg: 0.3, Max: 0.7, Diff: 0.7, Sum: 2.2] [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: 43.8, Max: 71.1, Diff: 44.1, Sum: 350.8] [Termination (ms): Min: 0.0, Avg: 5.5, Max: 6.4, Diff: 6.4, Sum: 44.2] [Termination Attempts: Min: 1, Avg: 2.8, Max: 8, Diff: 7, Sum: 22] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 33.4, Avg: 63.6, Max: 77.9, Diff: 44.4, Sum: 509.1] [GC Worker End (ms): Min: 28367.5, Avg: 28367.5, Max: 28367.6, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.0 ms] [Other: 0.3 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.1 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 29.0M(29.0M)->0.0B(30.0M) Survivors: 4096.0K->5120.0K Heap: 668.0M(672.0M)->670.5M(705.0M)] [Times: user=0.36 sys=0.00, real=0.08 secs] 2015-09-14T12:32:54.017-0700: 29.975: [GC pause (G1 Evacuation Pause) (young) (initial-mark), 0.0771112 secs] [Parallel Time: 76.6 ms, GC Workers: 8] [GC Worker Start (ms): Min: 29974.9, Avg: 29975.2, Max: 29975.4, Diff: 0.4] [Ext Root Scanning (ms): Min: 0.0, Avg: 17.5, Max: 42.6, Diff: 42.6, Sum: 140.0] [Update RS (ms): Min: 0.0, Avg: 13.8, Max: 28.2, Diff: 28.2, Sum: 110.4] [Processed Buffers: Min: 0, Avg: 14.4, Max: 30, Diff: 30, Sum: 115] [Scan RS (ms): Min: 0.0, Avg: 0.3, Max: 1.0, Diff: 1.0, Sum: 2.2] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 30.9, Avg: 43.1, Max: 47.7, Diff: 16.9, Sum: 344.4] [Termination (ms): Min: 0.0, Avg: 1.6, Max: 2.6, Diff: 2.6, Sum: 12.9] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 8] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 76.0, Avg: 76.3, Max: 76.5, Diff: 0.4, Sum: 610.1] [GC Worker End (ms): Min: 30051.4, Avg: 30051.4, Max: 30051.5, 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.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: 30.0M(30.0M)->0.0B(31.0M) Survivors: 5120.0K->5120.0K Heap: 700.5M(705.0M)->701.0M(737.0M)] [Times: user=0.31 sys=0.02, real=0.08 secs] 2015-09-14T12:32:54.094-0700: 30.052: [GC concurrent-root-region-scan-start] 2015-09-14T12:32:54.103-0700: 30.061: [GC concurrent-root-region-scan-end, 0.0087244 secs] 2015-09-14T12:32:54.103-0700: 30.061: [GC concurrent-mark-start] 2015-09-14T12:32:55.187-0700: 31.145: [GC concurrent-mark-end, 1.0842645 secs] 2015-09-14T12:32:55.188-0700: 31.145: [GC remark 2015-09-14T12:32:55.188-0700: 31.145: [Finalize Marking, 0.0002236 secs] 2015-09-14T12:32:55.188-0700: 31.145: [GC ref-proc, 0.0000718 secs] 2015-09-14T12:32:55.188-0700: 31.146: [Unloading, 0.0005131 secs], 0.0028313 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:32:55.191-0700: 31.148: [GC cleanup 721M->668M(737M), 0.0008783 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:32:55.191-0700: 31.149: [GC concurrent-cleanup-start] 2015-09-14T12:32:55.192-0700: 31.149: [GC concurrent-cleanup-end, 0.0000355 secs] 2015-09-14T12:32:55.725-0700: 31.682: [GC pause (G1 Evacuation Pause) (young), 0.0369086 secs] [Parallel Time: 36.4 ms, GC Workers: 8] [GC Worker Start (ms): Min: 31682.3, Avg: 31682.4, Max: 31682.5, Diff: 0.1] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.8] [Update RS (ms): Min: 11.8, Avg: 12.4, Max: 12.7, Diff: 0.8, Sum: 99.3] [Processed Buffers: Min: 15, Avg: 15.6, Max: 18, Diff: 3, Sum: 125] [Scan RS (ms): Min: 0.0, Avg: 0.3, Max: 0.7, Diff: 0.7, Sum: 2.3] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 23.4, Avg: 23.5, Max: 23.6, Diff: 0.3, Sum: 187.7] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [Termination Attempts: Min: 1, Avg: 47.6, Max: 66, Diff: 65, Sum: 381] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 36.2, Avg: 36.3, Max: 36.3, Diff: 0.1, Sum: 290.2] [GC Worker End (ms): Min: 31718.7, Avg: 31718.7, Max: 31718.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.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] [Eden: 31.0M(31.0M)->0.0B(31.0M) Survivors: 5120.0K->5120.0K Heap: 679.2M(737.0M)->679.7M(737.0M)] [Times: user=0.25 sys=0.00, real=0.04 secs] 2015-09-14T12:32:57.422-0700: 33.379: [GC pause (G1 Evacuation Pause) (mixed), 0.0778621 secs] [Parallel Time: 77.4 ms, GC Workers: 8] [GC Worker Start (ms): Min: 33379.1, Avg: 33387.9, Max: 33413.7, Diff: 34.6] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.2] [Update RS (ms): Min: 0.6, Avg: 38.0, Max: 67.4, Diff: 66.8, Sum: 304.2] [Processed Buffers: Min: 1, Avg: 16.9, Max: 32, Diff: 31, Sum: 135] [Scan RS (ms): Min: 0.0, Avg: 0.3, Max: 0.5, Diff: 0.5, Sum: 2.1] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 9.2, Avg: 28.6, Max: 41.4, Diff: 32.3, Sum: 228.7] [Termination (ms): Min: 0.0, Avg: 1.5, Max: 2.0, Diff: 2.0, Sum: 11.8] [Termination Attempts: Min: 1, Avg: 22.0, Max: 50, Diff: 49, Sum: 176] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 42.7, Avg: 68.5, Max: 77.3, Diff: 34.6, Sum: 548.0] [GC Worker End (ms): Min: 33456.4, Avg: 33456.4, Max: 33456.4, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.0 ms] [Other: 0.4 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: 31.0M(31.0M)->0.0B(32.0M) Survivors: 5120.0K->5120.0K Heap: 710.7M(737.0M)->710.2M(751.0M)] [Times: user=0.33 sys=0.02, real=0.08 secs] 2015-09-14T12:32:59.218-0700: 35.175: [GC pause (G1 Evacuation Pause) (young), 0.0793263 secs] [Parallel Time: 78.8 ms, GC Workers: 8] [GC Worker Start (ms): Min: 35175.4, Avg: 35194.5, Max: 35235.6, Diff: 60.2] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.2] [Update RS (ms): Min: 0.0, Avg: 23.7, Max: 73.2, Diff: 73.2, Sum: 189.7] [Processed Buffers: Min: 0, Avg: 15.8, Max: 31, Diff: 31, Sum: 126] [Scan RS (ms): Min: 0.0, Avg: 0.4, Max: 1.2, Diff: 1.2, Sum: 3.5] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 5.2, Avg: 30.8, Max: 68.5, Diff: 63.2, Sum: 246.5] [Termination (ms): Min: 0.0, Avg: 4.5, Max: 5.6, Diff: 5.6, Sum: 36.2] [Termination Attempts: Min: 1, Avg: 420.1, Max: 763, Diff: 762, Sum: 3361] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 18.5, Avg: 59.7, Max: 78.8, Diff: 60.3, Sum: 477.2] [GC Worker End (ms): Min: 35254.1, Avg: 35254.1, Max: 35254.1, 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.1 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 32.0M(32.0M)->0.0B(34.0M) Survivors: 5120.0K->5120.0K Heap: 742.2M(751.0M)->743.7M(782.0M)] [Times: user=0.33 sys=0.02, real=0.08 secs] 2015-09-14T12:33:00.206-0700: 36.163: [GC pause (G1 Humongous Allocation) (young) (initial-mark), 0.0656531 secs] [Parallel Time: 65.0 ms, GC Workers: 8] [GC Worker Start (ms): Min: 36163.3, Avg: 36163.6, Max: 36163.8, Diff: 0.4] [Ext Root Scanning (ms): Min: 0.0, Avg: 18.7, Max: 40.3, Diff: 40.3, Sum: 149.4] [Update RS (ms): Min: 0.0, Avg: 13.3, Max: 27.2, Diff: 27.2, Sum: 106.3] [Processed Buffers: Min: 0, Avg: 12.5, Max: 25, Diff: 25, Sum: 100] [Scan RS (ms): Min: 0.0, Avg: 0.5, Max: 1.5, Diff: 1.5, Sum: 3.9] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 21.9, Avg: 30.3, Max: 37.0, Diff: 15.0, Sum: 242.2] [Termination (ms): Min: 0.0, Avg: 2.0, Max: 2.3, Diff: 2.3, Sum: 15.8] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 8] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.2] [GC Worker Total (ms): Min: 64.5, Avg: 64.7, Max: 64.9, Diff: 0.4, Sum: 517.9] [GC Worker End (ms): Min: 36228.3, Avg: 36228.3, Max: 36228.3, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.2 ms] [Other: 0.4 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: 18.0M(34.0M)->0.0B(34.0M) Survivors: 5120.0K->5120.0K Heap: 760.9M(782.0M)->762.7M(787.0M)] [Times: user=0.26 sys=0.00, real=0.07 secs] 2015-09-14T12:33:00.272-0700: 36.229: [GC concurrent-root-region-scan-start] 2015-09-14T12:33:00.277-0700: 36.234: [GC concurrent-root-region-scan-end, 0.0046602 secs] 2015-09-14T12:33:00.277-0700: 36.234: [GC concurrent-mark-start] 2015-09-14T12:33:01.626-0700: 37.583: [GC concurrent-mark-end, 1.3491607 secs] 2015-09-14T12:33:01.626-0700: 37.583: [GC remark 2015-09-14T12:33:01.626-0700: 37.583: [Finalize Marking, 0.0002660 secs] 2015-09-14T12:33:01.626-0700: 37.583: [GC ref-proc, 0.0000671 secs] 2015-09-14T12:33:01.626-0700: 37.583: [Unloading, 0.0004276 secs], 0.0031751 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:33:01.629-0700: 37.586: [GC cleanup 903M->903M(906M), 0.0009416 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:33:01.635-0700: 37.592: [GC pause (G1 Evacuation Pause) (young), 0.0472490 secs] [Parallel Time: 46.8 ms, GC Workers: 8] [GC Worker Start (ms): Min: 37591.7, Avg: 37594.2, Max: 37610.4, Diff: 18.7] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.5] [Update RS (ms): Min: 0.0, Avg: 10.2, Max: 16.6, Diff: 16.6, Sum: 81.9] [Processed Buffers: Min: 0, Avg: 14.4, Max: 29, Diff: 29, Sum: 115] [Scan RS (ms): Min: 0.0, Avg: 0.4, Max: 0.8, Diff: 0.8, Sum: 2.9] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 28.1, Avg: 33.7, Max: 46.6, Diff: 18.5, Sum: 269.3] [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.0, Sum: 0.4] [Termination Attempts: Min: 1, Avg: 149.0, Max: 227, Diff: 226, Sum: 1192] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 28.1, Avg: 44.4, Max: 46.8, Diff: 18.7, Sum: 355.1] [GC Worker End (ms): Min: 37638.6, Avg: 37638.6, Max: 37638.6, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.1 ms] [Other: 0.3 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.1 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 23.0M(40.0M)->0.0B(40.0M) Survivors: 5120.0K->6144.0K Heap: 904.6M(906.0M)->905.1M(934.0M)] [Times: user=0.22 sys=0.06, real=0.05 secs] 2015-09-14T12:33:03.207-0700: 39.164: [GC pause (G1 Evacuation Pause) (young), 0.0867990 secs] [Parallel Time: 86.3 ms, GC Workers: 8] [GC Worker Start (ms): Min: 39164.5, Avg: 39172.8, Max: 39197.9, Diff: 33.4] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.2] [Update RS (ms): Min: 0.0, Avg: 15.4, Max: 31.2, Diff: 31.2, Sum: 122.8] [Processed Buffers: Min: 0, Avg: 15.9, Max: 33, Diff: 33, Sum: 127] [Scan RS (ms): Min: 0.0, Avg: 5.8, Max: 37.4, Diff: 37.4, Sum: 46.3] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 18.0, Avg: 47.3, Max: 75.1, Diff: 57.1, Sum: 378.2] [Termination (ms): Min: 0.0, Avg: 9.4, Max: 10.7, Diff: 10.7, Sum: 74.8] [Termination Attempts: Min: 1, Avg: 121.6, Max: 189, Diff: 188, Sum: 973] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 52.8, Avg: 77.9, Max: 86.2, Diff: 33.4, Sum: 623.4] [GC Worker End (ms): Min: 39250.7, Avg: 39250.7, Max: 39250.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.1 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 28.0M(40.0M)->0.0B(42.0M) Survivors: 6144.0K->6144.0K Heap: 933.1M(934.0M)->934.6M(970.0M)] [Times: user=0.38 sys=0.02, real=0.09 secs] 2015-09-14T12:33:05.107-0700: 41.064: [GC pause (G1 Evacuation Pause) (young) (initial-mark), 0.0839075 secs] [Parallel Time: 83.4 ms, GC Workers: 8] [GC Worker Start (ms): Min: 41064.1, Avg: 41064.4, Max: 41064.6, Diff: 0.4] [Ext Root Scanning (ms): Min: 0.0, Avg: 8.4, Max: 32.9, Diff: 32.9, Sum: 67.6] [Update RS (ms): Min: 0.0, Avg: 20.9, Max: 59.8, Diff: 59.8, Sum: 167.2] [Processed Buffers: Min: 0, Avg: 17.8, Max: 36, Diff: 36, Sum: 142] [Scan RS (ms): Min: 0.0, Avg: 0.3, Max: 0.8, Diff: 0.8, Sum: 2.3] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 19.2, Avg: 50.4, Max: 81.5, Diff: 62.3, Sum: 403.4] [Termination (ms): Min: 0.0, Avg: 3.0, Max: 3.8, Diff: 3.8, Sum: 24.3] [Termination Attempts: Min: 1, Avg: 100.0, Max: 172, Diff: 171, Sum: 800] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 82.9, Avg: 83.1, Max: 83.3, Diff: 0.4, Sum: 664.9] [GC Worker End (ms): Min: 41147.5, Avg: 41147.5, Max: 41147.5, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.0 ms] [Other: 0.4 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: 34.0M(42.0M)->0.0B(44.0M) Survivors: 6144.0K->6144.0K Heap: 968.6M(970.0M)->970.1M(1011.0M)] [Times: user=0.34 sys=0.00, real=0.08 secs] 2015-09-14T12:33:05.191-0700: 41.148: [GC concurrent-root-region-scan-start] 2015-09-14T12:33:05.203-0700: 41.160: [GC concurrent-root-region-scan-end, 0.0115292 secs] 2015-09-14T12:33:05.203-0700: 41.160: [GC concurrent-mark-start] 2015-09-14T12:33:06.770-0700: 42.727: [GC concurrent-mark-end, 1.5676605 secs] 2015-09-14T12:33:06.770-0700: 42.727: [GC remark 2015-09-14T12:33:06.770-0700: 42.727: [Finalize Marking, 0.0002168 secs] 2015-09-14T12:33:06.771-0700: 42.728: [GC ref-proc, 0.0000650 secs] 2015-09-14T12:33:06.771-0700: 42.728: [Unloading, 0.0003755 secs], 0.0035839 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:33:06.774-0700: 42.731: [GC cleanup 999M->920M(1011M), 0.0011063 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:33:06.775-0700: 42.732: [GC concurrent-cleanup-start] 2015-09-14T12:33:06.775-0700: 42.732: [GC concurrent-cleanup-end, 0.0000406 secs] 2015-09-14T12:33:07.520-0700: 43.477: [GC pause (G1 Evacuation Pause) (young), 0.0994122 secs] [Parallel Time: 98.2 ms, GC Workers: 8] [GC Worker Start (ms): Min: 43477.5, Avg: 43491.4, Max: 43523.5, Diff: 46.1] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.2] [Update RS (ms): Min: 0.0, Avg: 26.0, Max: 76.9, Diff: 76.9, Sum: 207.8] [Processed Buffers: Min: 0, Avg: 17.9, Max: 35, Diff: 35, Sum: 143] [Scan RS (ms): Min: 0.0, Avg: 4.5, Max: 33.4, Diff: 33.4, Sum: 36.1] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 15.0, Avg: 40.5, Max: 76.3, Diff: 61.3, Sum: 323.7] [Termination (ms): Min: 0.0, Avg: 13.0, Max: 21.1, Diff: 21.1, Sum: 104.0] [Termination Attempts: Min: 1, Avg: 10.8, Max: 41, Diff: 40, Sum: 86] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 52.0, Avg: 84.1, Max: 98.1, Diff: 46.1, Sum: 673.0] [GC Worker End (ms): Min: 43575.6, Avg: 43575.6, Max: 43575.6, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.1 ms] [Other: 1.1 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.7 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.2 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 44.0M(44.0M)->0.0B(43.0M) Survivors: 6144.0K->7168.0K Heap: 934.8M(1011.0M)->935.8M(1011.0M)] [Times: user=0.36 sys=0.02, real=0.10 secs] 2015-09-14T12:33:09.947-0700: 45.904: [GC pause (G1 Evacuation Pause) (young), 0.0888640 secs] [Parallel Time: 88.0 ms, GC Workers: 8] [GC Worker Start (ms): Min: 45904.0, Avg: 45916.3, Max: 45936.6, Diff: 32.6] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.1] [Update RS (ms): Min: 0.0, Avg: 30.9, Max: 64.5, Diff: 64.5, Sum: 247.1] [Processed Buffers: Min: 0, Avg: 16.5, Max: 33, Diff: 33, Sum: 132] [Scan RS (ms): Min: 0.0, Avg: 0.5, Max: 1.2, Diff: 1.2, Sum: 3.8] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 22.8, Avg: 43.9, Max: 86.8, Diff: 64.0, Sum: 351.1] [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.1] [Termination Attempts: Min: 1, Avg: 24.4, Max: 50, Diff: 49, Sum: 195] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 55.2, Avg: 75.6, Max: 87.9, Diff: 32.7, Sum: 604.4] [GC Worker End (ms): Min: 45991.8, Avg: 45991.8, Max: 45991.9, Diff: 0.1] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.2 ms] [Other: 0.6 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.3 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: 43.0M(43.0M)->0.0B(44.0M) Survivors: 7168.0K->7168.0K Heap: 978.8M(1011.0M)->980.3M(1031.0M)] [Times: user=0.38 sys=0.02, real=0.09 secs] 2015-09-14T12:33:12.371-0700: 48.329: [GC pause (G1 Evacuation Pause) (young) (initial-mark), 0.0997744 secs] [Parallel Time: 99.2 ms, GC Workers: 8] [GC Worker Start (ms): Min: 48328.7, Avg: 48329.0, Max: 48329.1, Diff: 0.4] [Ext Root Scanning (ms): Min: 0.0, Avg: 17.1, Max: 36.5, Diff: 36.5, Sum: 136.7] [Update RS (ms): Min: 0.0, Avg: 12.9, Max: 26.0, Diff: 26.0, Sum: 103.3] [Processed Buffers: Min: 0, Avg: 10.8, Max: 22, Diff: 22, Sum: 86] [Scan RS (ms): Min: 0.0, Avg: 0.5, Max: 1.0, Diff: 1.0, Sum: 3.6] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 33.2, Avg: 47.3, Max: 72.0, Diff: 38.8, Sum: 378.1] [Termination (ms): Min: 0.0, Avg: 21.2, Max: 29.1, Diff: 29.1, Sum: 170.0] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 8] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 98.8, Avg: 99.0, Max: 99.2, Diff: 0.4, Sum: 791.9] [GC Worker End (ms): Min: 48427.9, Avg: 48427.9, Max: 48427.9, 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.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 44.0M(44.0M)->0.0B(46.0M) Survivors: 7168.0K->7168.0K Heap: 1024.3M(1031.0M)->1025.8M(1077.0M)] [Times: user=0.45 sys=0.06, real=0.10 secs] 2015-09-14T12:33:12.471-0700: 48.428: [GC concurrent-root-region-scan-start] 2015-09-14T12:33:12.490-0700: 48.447: [GC concurrent-root-region-scan-end, 0.0186234 secs] 2015-09-14T12:33:12.490-0700: 48.447: [GC concurrent-mark-start] 2015-09-14T12:33:14.275-0700: 50.231: [GC concurrent-mark-end, 1.7842566 secs] 2015-09-14T12:33:14.275-0700: 50.232: [GC remark 2015-09-14T12:33:14.275-0700: 50.232: [Finalize Marking, 0.0001924 secs] 2015-09-14T12:33:14.276-0700: 50.232: [GC ref-proc, 0.0000723 secs] 2015-09-14T12:33:14.276-0700: 50.232: [Unloading, 0.0004738 secs], 0.0039384 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:33:14.279-0700: 50.236: [GC cleanup 1058M->1058M(1077M), 0.0009579 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:33:14.994-0700: 50.950: [GC pause (G1 Evacuation Pause) (young), 0.1075974 secs] [Parallel Time: 107.1 ms, GC Workers: 8] [GC Worker Start (ms): Min: 50950.4, Avg: 50969.6, Max: 51014.9, Diff: 64.5] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.2] [Update RS (ms): Min: 0.0, Avg: 23.7, Max: 72.7, Diff: 72.7, Sum: 189.4] [Processed Buffers: Min: 0, Avg: 11.3, Max: 23, Diff: 23, Sum: 90] [Scan RS (ms): Min: 0.0, Avg: 1.7, Max: 3.9, Diff: 3.9, Sum: 13.8] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 32.4, Avg: 53.4, Max: 92.6, Diff: 60.3, Sum: 427.1] [Termination (ms): Min: 0.0, Avg: 8.9, Max: 10.1, Diff: 10.1, Sum: 70.9] [Termination Attempts: Min: 1, Avg: 10.4, Max: 22, Diff: 21, Sum: 83] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 42.5, Avg: 87.8, Max: 107.0, Diff: 64.5, Sum: 702.5] [GC Worker End (ms): Min: 51057.4, Avg: 51057.4, Max: 51057.4, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.0 ms] [Other: 0.4 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: 46.0M(46.0M)->0.0B(49.0M) Survivors: 7168.0K->7168.0K Heap: 1071.8M(1077.0M)->1073.3M(1127.0M)] [Times: user=0.50 sys=0.03, real=0.11 secs] 2015-09-14T12:33:17.743-0700: 53.699: [GC pause (G1 Evacuation Pause) (young), 0.0833203 secs] [Parallel Time: 82.8 ms, GC Workers: 8] [GC Worker Start (ms): Min: 53699.1, Avg: 53709.7, Max: 53731.8, Diff: 32.7] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.5] [Update RS (ms): Min: 0.0, Avg: 7.7, Max: 15.7, Diff: 15.7, Sum: 61.9] [Processed Buffers: Min: 0, Avg: 11.1, Max: 30, Diff: 30, Sum: 89] [Scan RS (ms): Min: 0.0, Avg: 0.4, Max: 1.0, Diff: 1.0, Sum: 3.3] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 36.6, Avg: 53.4, Max: 69.2, Diff: 32.6, Sum: 427.1] [Termination (ms): Min: 0.0, Avg: 10.6, Max: 13.4, Diff: 13.4, Sum: 84.8] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 8] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 50.1, Avg: 72.2, Max: 82.8, Diff: 32.7, Sum: 577.8] [GC Worker End (ms): Min: 53781.9, Avg: 53781.9, Max: 53781.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.1 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 49.0M(49.0M)->0.0B(52.0M) Survivors: 7168.0K->7168.0K Heap: 1122.3M(1127.0M)->1123.8M(1180.0M)] [Times: user=0.41 sys=0.00, real=0.08 secs] 2015-09-14T12:33:18.329-0700: 54.285: [GC pause (G1 Humongous Allocation) (young) (initial-mark), 0.0456078 secs] [Parallel Time: 45.1 ms, GC Workers: 8] [GC Worker Start (ms): Min: 54285.4, Avg: 54285.6, Max: 54285.8, Diff: 0.5] [Ext Root Scanning (ms): Min: 0.0, Avg: 18.3, Max: 44.6, Diff: 44.6, Sum: 146.5] [Update RS (ms): Min: 0.0, Avg: 9.7, Max: 19.5, Diff: 19.5, Sum: 77.4] [Processed Buffers: Min: 0, Avg: 11.6, Max: 24, Diff: 24, Sum: 93] [Scan RS (ms): Min: 0.0, Avg: 0.5, Max: 1.0, Diff: 1.0, Sum: 3.7] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 0.0, Avg: 16.3, Max: 24.3, Diff: 24.3, Sum: 130.4] [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.5] [Termination Attempts: Min: 1, Avg: 79.3, Max: 137, Diff: 136, Sum: 634] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 44.6, Avg: 44.8, Max: 45.0, Diff: 0.5, Sum: 358.5] [GC Worker End (ms): Min: 54330.4, Avg: 54330.4, Max: 54330.4, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.0 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.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 10.0M(52.0M)->0.0B(51.0M) Survivors: 7168.0K->8192.0K Heap: 1133.8M(1180.0M)->1135.3M(1180.0M)] [Times: user=0.19 sys=0.00, real=0.05 secs] 2015-09-14T12:33:18.375-0700: 54.331: [GC concurrent-root-region-scan-start] 2015-09-14T12:33:18.382-0700: 54.338: [GC concurrent-root-region-scan-end, 0.0071888 secs] 2015-09-14T12:33:18.382-0700: 54.338: [GC concurrent-mark-start] 2015-09-14T12:33:20.378-0700: 56.334: [GC concurrent-mark-end, 1.9962724 secs] 2015-09-14T12:33:20.378-0700: 56.335: [GC remark 2015-09-14T12:33:20.378-0700: 56.335: [Finalize Marking, 0.0001450 secs] 2015-09-14T12:33:20.378-0700: 56.335: [GC ref-proc, 0.0000731 secs] 2015-09-14T12:33:20.379-0700: 56.335: [Unloading, 0.0004952 secs], 0.0041454 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:33:20.383-0700: 56.339: [GC cleanup 1347M->1347M(1359M), 0.0010011 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:33:20.914-0700: 56.871: [GC pause (G1 Evacuation Pause) (young), 0.1266519 secs] [Parallel Time: 126.2 ms, GC Workers: 8] [GC Worker Start (ms): Min: 56870.8, Avg: 56889.3, Max: 56912.0, Diff: 41.2] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.2] [Update RS (ms): Min: 0.0, Avg: 24.5, Max: 48.7, Diff: 48.7, Sum: 195.9] [Processed Buffers: Min: 0, Avg: 17.1, Max: 35, Diff: 35, Sum: 137] [Scan RS (ms): Min: 0.0, Avg: 0.6, Max: 1.1, Diff: 1.1, Sum: 4.5] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 46.6, Avg: 55.9, Max: 76.8, Diff: 30.2, Sum: 447.5] [Termination (ms): Min: 0.0, Avg: 26.1, Max: 30.4, Diff: 30.4, Sum: 208.6] [Termination Attempts: Min: 1, Avg: 22.3, Max: 48, Diff: 47, Sum: 178] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 84.4, Avg: 107.2, Max: 125.6, Diff: 41.2, Sum: 857.7] [GC Worker End (ms): Min: 56996.4, Avg: 56996.5, Max: 56996.9, Diff: 0.5] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.0 ms] [Other: 0.4 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: 44.0M(59.0M)->0.0B(61.0M) Survivors: 8192.0K->9216.0K Heap: 1357.6M(1359.0M)->1358.1M(1411.0M)] [Times: user=0.50 sys=0.06, real=0.13 secs] 2015-09-14T12:33:23.842-0700: 59.799: [GC pause (G1 Evacuation Pause) (young), 0.1100536 secs] [Parallel Time: 109.5 ms, GC Workers: 8] [GC Worker Start (ms): Min: 59798.7, Avg: 59807.2, Max: 59832.0, Diff: 33.3] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.2] [Update RS (ms): Min: 0.0, Avg: 17.2, Max: 40.0, Diff: 40.0, Sum: 138.0] [Processed Buffers: Min: 0, Avg: 11.6, Max: 24, Diff: 24, Sum: 93] [Scan RS (ms): Min: 0.0, Avg: 3.0, Max: 15.2, Diff: 15.2, Sum: 24.1] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 52.5, Avg: 73.1, Max: 100.0, Diff: 47.4, Sum: 584.5] [Termination (ms): Min: 0.0, Avg: 7.5, Max: 9.1, Diff: 9.1, Sum: 59.9] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 8] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 76.2, Avg: 101.0, Max: 109.4, Diff: 33.3, Sum: 807.8] [GC Worker End (ms): Min: 59908.1, Avg: 59908.1, Max: 59908.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.0 ms] [Eden: 52.0M(61.0M)->0.0B(64.0M) Survivors: 9216.0K->9216.0K Heap: 1410.1M(1411.0M)->1411.6M(1474.0M)] [Times: user=0.47 sys=0.03, real=0.11 secs] 2015-09-14T12:33:27.194-0700: 63.151: [GC pause (G1 Evacuation Pause) (young) (initial-mark), 0.1389969 secs] [Parallel Time: 138.2 ms, GC Workers: 8] [GC Worker Start (ms): Min: 63151.2, Avg: 63151.5, Max: 63151.7, Diff: 0.4] [Ext Root Scanning (ms): Min: 0.0, Avg: 16.4, Max: 32.7, Diff: 32.7, Sum: 131.4] [Update RS (ms): Min: 0.0, Avg: 14.9, Max: 30.0, Diff: 30.0, Sum: 119.3] [Processed Buffers: Min: 0, Avg: 13.0, Max: 28, Diff: 28, Sum: 104] [Scan RS (ms): Min: 0.0, Avg: 0.6, Max: 1.4, Diff: 1.4, Sum: 4.9] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 40.4, Avg: 62.1, Max: 106.6, Diff: 66.2, Sum: 497.0] [Termination (ms): Min: 0.0, Avg: 43.9, Max: 64.9, Diff: 64.9, Sum: 351.0] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 8] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 137.7, Avg: 138.0, Max: 138.2, Diff: 0.4, Sum: 1103.7] [GC Worker End (ms): Min: 63289.4, Avg: 63289.4, Max: 63289.4, 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.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.2 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 61.0M(64.0M)->0.0B(67.0M) Survivors: 9216.0K->10.0M Heap: 1472.6M(1474.0M)->1474.6M(1546.0M)] [Times: user=0.56 sys=0.08, real=0.14 secs] 2015-09-14T12:33:27.334-0700: 63.290: [GC concurrent-root-region-scan-start] 2015-09-14T12:33:27.346-0700: 63.302: [GC concurrent-root-region-scan-end, 0.0121578 secs] 2015-09-14T12:33:27.346-0700: 63.302: [GC concurrent-mark-start] 2015-09-14T12:33:29.622-0700: 65.578: [GC concurrent-mark-end, 2.2758998 secs] 2015-09-14T12:33:29.622-0700: 65.578: [GC remark 2015-09-14T12:33:29.622-0700: 65.578: [Finalize Marking, 0.0002668 secs] 2015-09-14T12:33:29.622-0700: 65.579: [GC ref-proc, 0.0000718 secs] 2015-09-14T12:33:29.622-0700: 65.579: [Unloading, 0.0004075 secs], 0.0052495 secs] [Times: user=0.00 sys=0.00, real=0.01 secs] 2015-09-14T12:33:29.627-0700: 65.584: [GC cleanup 1517M->1398M(1546M), 0.0012345 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:33:29.628-0700: 65.585: [GC concurrent-cleanup-start] 2015-09-14T12:33:29.629-0700: 65.585: [GC concurrent-cleanup-end, 0.0000714 secs] 2015-09-14T12:33:30.955-0700: 66.912: [GC pause (G1 Evacuation Pause) (young), 0.1359826 secs] [Parallel Time: 135.3 ms, GC Workers: 8] [GC Worker Start (ms): Min: 66911.7, Avg: 66928.0, Max: 66976.2, Diff: 64.4] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.2] [Update RS (ms): Min: 0.0, Avg: 33.1, Max: 96.2, Diff: 96.2, Sum: 264.4] [Processed Buffers: Min: 0, Avg: 14.6, Max: 29, Diff: 29, Sum: 117] [Scan RS (ms): Min: 0.0, Avg: 0.7, Max: 1.5, Diff: 1.5, Sum: 5.8] [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: 62.1, Max: 98.2, Diff: 72.1, Sum: 496.9] [Termination (ms): Min: 0.0, Avg: 22.9, Max: 34.7, Diff: 34.7, Sum: 183.5] [Termination Attempts: Min: 1, Avg: 9.0, Max: 24, Diff: 23, Sum: 72] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.2] [GC Worker Total (ms): Min: 70.8, Avg: 119.0, Max: 135.2, Diff: 64.4, Sum: 952.0] [GC Worker End (ms): Min: 67047.0, Avg: 67047.0, Max: 67047.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.2 ms] [Humongous Register: 0.1 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.1 ms] [Eden: 67.0M(67.0M)->0.0B(67.0M) Survivors: 10.0M->10.0M Heap: 1422.8M(1546.0M)->1424.3M(1546.0M)] [Times: user=0.70 sys=0.00, real=0.14 secs] 2015-09-14T12:33:34.732-0700: 70.688: [GC pause (G1 Evacuation Pause) (young), 0.1266211 secs] [Parallel Time: 126.0 ms, GC Workers: 8] [GC Worker Start (ms): Min: 70687.7, Avg: 70713.3, Max: 70770.4, Diff: 82.7] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 0.9] [Update RS (ms): Min: 0.0, Avg: 14.0, Max: 28.8, Diff: 28.8, Sum: 112.3] [Processed Buffers: Min: 0, Avg: 14.0, Max: 29, Diff: 29, Sum: 112] [Scan RS (ms): Min: 0.0, Avg: 0.8, Max: 1.9, Diff: 1.9, Sum: 6.1] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 6.0, Avg: 62.4, Max: 96.0, Diff: 90.0, Sum: 498.8] [Termination (ms): Min: 0.0, Avg: 23.1, Max: 37.3, Diff: 37.3, Sum: 184.4] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 8] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 43.2, Avg: 100.3, Max: 125.9, Diff: 82.7, Sum: 802.6] [GC Worker End (ms): Min: 70813.6, Avg: 70813.6, Max: 70813.6, 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.2 ms] [Humongous Register: 0.1 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.1 ms] [Eden: 67.0M(67.0M)->0.0B(68.0M) Survivors: 10.0M->10.0M Heap: 1491.3M(1546.0M)->1492.8M(1571.0M)] [Times: user=0.58 sys=0.00, real=0.13 secs] 2015-09-14T12:33:38.474-0700: 74.430: [GC pause (G1 Evacuation Pause) (young) (initial-mark), 0.1625628 secs] [Parallel Time: 161.9 ms, GC Workers: 8] [GC Worker Start (ms): Min: 74430.0, Avg: 74430.2, Max: 74430.4, Diff: 0.4] [Ext Root Scanning (ms): Min: 0.0, Avg: 20.3, Max: 52.0, Diff: 52.0, Sum: 162.2] [Update RS (ms): Min: 0.0, Avg: 22.8, Max: 83.2, Diff: 83.2, Sum: 182.1] [Processed Buffers: Min: 0, Avg: 14.6, Max: 30, Diff: 30, Sum: 117] [Scan RS (ms): Min: 0.0, Avg: 4.5, Max: 31.7, Diff: 31.6, Sum: 36.1] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 42.5, Avg: 64.1, Max: 81.5, Diff: 39.0, Sum: 513.1] [Termination (ms): Min: 0.0, Avg: 49.9, Max: 66.9, Diff: 66.9, Sum: 399.6] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 8] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2] [GC Worker Total (ms): Min: 161.4, Avg: 161.7, Max: 161.9, Diff: 0.4, Sum: 1293.2] [GC Worker End (ms): Min: 74591.9, Avg: 74591.9, Max: 74591.9, 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.2 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 68.0M(68.0M)->0.0B(72.0M) Survivors: 10.0M->10.0M Heap: 1560.8M(1571.0M)->1561.8M(1641.0M)] [Times: user=0.70 sys=0.06, real=0.16 secs] 2015-09-14T12:33:38.637-0700: 74.592: [GC concurrent-root-region-scan-start] 2015-09-14T12:33:38.651-0700: 74.607: [GC concurrent-root-region-scan-end, 0.0146949 secs] 2015-09-14T12:33:38.651-0700: 74.607: [GC concurrent-mark-start] 2015-09-14T12:33:41.358-0700: 77.314: [GC concurrent-mark-end, 2.7064655 secs] 2015-09-14T12:33:41.358-0700: 77.314: [GC remark 2015-09-14T12:33:41.358-0700: 77.314: [Finalize Marking, 0.0001929 secs] 2015-09-14T12:33:41.358-0700: 77.314: [GC ref-proc, 0.0000676 secs] 2015-09-14T12:33:41.358-0700: 77.314: [Unloading, 0.0004328 secs], 0.0066654 secs] [Times: user=0.00 sys=0.00, real=0.01 secs] 2015-09-14T12:33:41.365-0700: 77.321: [GC cleanup 1612M->1612M(1641M), 0.0013389 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:33:42.480-0700: 78.436: [GC pause (G1 Evacuation Pause) (young), 0.1441622 secs] [Parallel Time: 143.7 ms, GC Workers: 8] [GC Worker Start (ms): Min: 78436.0, Avg: 78446.7, Max: 78464.9, Diff: 28.9] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.5] [Update RS (ms): Min: 0.0, Avg: 12.8, Max: 32.9, Diff: 32.9, Sum: 102.7] [Processed Buffers: Min: 0, Avg: 14.1, Max: 30, Diff: 30, Sum: 113] [Scan RS (ms): Min: 0.0, Avg: 0.6, Max: 1.6, Diff: 1.6, Sum: 4.5] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 55.6, Avg: 80.3, Max: 119.5, Diff: 63.9, Sum: 642.7] [Termination (ms): Min: 0.0, Avg: 39.1, Max: 59.2, Diff: 59.2, Sum: 312.9] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 8] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.2] [GC Worker Total (ms): Min: 114.8, Avg: 132.9, Max: 143.6, Diff: 28.9, Sum: 1063.4] [GC Worker End (ms): Min: 78579.6, Avg: 78579.7, Max: 78579.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.2 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.1 ms] [Eden: 72.0M(72.0M)->0.0B(74.0M) Survivors: 10.0M->11.0M Heap: 1633.8M(1641.0M)->1635.3M(1718.0M)] [Times: user=0.72 sys=0.11, real=0.14 secs] 2015-09-14T12:33:45.261-0700: 81.217: [GC pause (G1 Humongous Allocation) (young) (initial-mark), 0.1259715 secs] [Parallel Time: 125.4 ms, GC Workers: 8] [GC Worker Start (ms): Min: 81217.4, Avg: 81217.6, Max: 81217.6, Diff: 0.3] [Ext Root Scanning (ms): Min: 0.0, Avg: 12.8, Max: 45.0, Diff: 45.0, Sum: 102.5] [Update RS (ms): Min: 0.0, Avg: 13.8, Max: 44.5, Diff: 44.5, Sum: 110.2] [Processed Buffers: Min: 0, Avg: 16.8, Max: 38, Diff: 38, Sum: 134] [Scan RS (ms): Min: 0.0, Avg: 10.3, Max: 40.5, Diff: 40.5, Sum: 82.4] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 41.8, Avg: 66.6, Max: 91.9, Diff: 50.1, Sum: 532.4] [Termination (ms): Min: 0.0, Avg: 21.7, Max: 27.4, Diff: 27.4, Sum: 173.7] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 8] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 125.1, Avg: 125.2, Max: 125.4, Diff: 0.3, Sum: 1001.3] [GC Worker End (ms): Min: 81342.7, Avg: 81342.7, Max: 81342.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.2 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.1 ms] [Eden: 50.0M(74.0M)->0.0B(76.0M) Survivors: 11.0M->11.0M Heap: 1684.7M(1718.0M)->1685.3M(1747.0M)] [Times: user=0.50 sys=0.08, real=0.13 secs] 2015-09-14T12:33:45.387-0700: 81.343: [GC concurrent-root-region-scan-start] 2015-09-14T12:33:45.412-0700: 81.368: [GC concurrent-root-region-scan-end, 0.0244353 secs] 2015-09-14T12:33:45.412-0700: 81.368: [GC concurrent-mark-start] 2015-09-14T12:33:48.392-0700: 84.348: [GC concurrent-mark-reset-for-overflow] 2015-09-14T12:33:48.988-0700: 84.944: [GC pause (G1 Evacuation Pause) (young), 0.1122157 secs] [Parallel Time: 111.7 ms, GC Workers: 8] [GC Worker Start (ms): Min: 84944.0, Avg: 84949.3, Max: 84965.0, Diff: 21.0] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.6] [Update RS (ms): Min: 0.0, Avg: 12.1, Max: 19.7, Diff: 19.7, Sum: 96.9] [Processed Buffers: Min: 0, Avg: 14.4, Max: 28, Diff: 28, Sum: 115] [Scan RS (ms): Min: 0.0, Avg: 0.9, Max: 1.6, Diff: 1.6, Sum: 6.9] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 62.4, Avg: 73.7, Max: 90.4, Diff: 27.9, Sum: 589.6] [Termination (ms): Min: 0.0, Avg: 19.4, Max: 28.3, Diff: 28.3, Sum: 155.1] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 8] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 90.4, Avg: 106.2, Max: 111.6, Diff: 21.2, Sum: 849.2] [GC Worker End (ms): Min: 85055.4, Avg: 85055.5, Max: 85055.7, Diff: 0.3] [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.2 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 61.0M(76.0M)->0.0B(93.0M) Survivors: 11.0M->11.0M Heap: 2013.6M(2015.0M)->2015.1M(2089.0M)] [Times: user=0.72 sys=0.06, real=0.11 secs] 2015-09-14T12:33:49.950-0700: 85.905: [GC concurrent-mark-end, 4.5373326 secs] 2015-09-14T12:33:49.950-0700: 85.905: [GC remark 2015-09-14T12:33:49.950-0700: 85.905: [Finalize Marking, 0.0001890 secs] 2015-09-14T12:33:49.950-0700: 85.906: [GC ref-proc, 0.0000757 secs] 2015-09-14T12:33:49.950-0700: 85.906: [Unloading, 0.0003990 secs], 0.0057733 secs] [Times: user=0.00 sys=0.00, real=0.01 secs] 2015-09-14T12:33:49.956-0700: 85.911: [GC cleanup 2032M->2032M(2089M), 0.0016592 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:33:53.032-0700: 88.988: [GC pause (G1 Evacuation Pause) (young), 0.1328725 secs] [Parallel Time: 132.2 ms, GC Workers: 8] [GC Worker Start (ms): Min: 88988.0, Avg: 88998.4, Max: 89037.9, Diff: 50.0] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.2] [Update RS (ms): Min: 0.0, Avg: 32.3, Max: 75.3, Diff: 75.3, Sum: 258.1] [Processed Buffers: Min: 0, Avg: 16.0, Max: 31, Diff: 31, Sum: 128] [Scan RS (ms): Min: 0.0, Avg: 2.6, Max: 13.0, Diff: 13.0, Sum: 20.6] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 45.0, Avg: 76.7, Max: 107.0, Diff: 62.0, Sum: 613.2] [Termination (ms): Min: 0.0, Avg: 10.1, Max: 11.5, Diff: 11.5, Sum: 80.4] [Termination Attempts: Min: 1, Avg: 134.8, Max: 223, Diff: 222, Sum: 1078] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.2] [GC Worker Total (ms): Min: 82.2, Avg: 121.7, Max: 132.2, Diff: 50.0, Sum: 973.7] [GC Worker End (ms): Min: 89120.1, Avg: 89120.1, Max: 89120.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.2 ms] [Humongous Register: 0.1 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.1 ms] [Eden: 72.0M(93.0M)->0.0B(95.0M) Survivors: 11.0M->13.0M Heap: 2087.1M(2089.0M)->2088.1M(2173.0M)] [Times: user=0.67 sys=0.05, real=0.13 secs] 2015-09-14T12:33:57.702-0700: 93.658: [GC pause (G1 Evacuation Pause) (young), 0.1768314 secs] [Parallel Time: 176.2 ms, GC Workers: 8] [GC Worker Start (ms): Min: 93657.9, Avg: 93661.0, Max: 93681.7, Diff: 23.9] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.2] [Update RS (ms): Min: 8.1, Avg: 24.6, Max: 37.5, Diff: 29.4, Sum: 196.6] [Processed Buffers: Min: 6, Avg: 16.8, Max: 31, Diff: 25, Sum: 134] [Scan RS (ms): Min: 2.2, Avg: 5.4, Max: 7.3, Diff: 5.2, Sum: 43.0] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 86.9, Avg: 106.8, Max: 131.9, Diff: 45.0, Sum: 854.6] [Termination (ms): Min: 0.0, Avg: 35.4, Max: 44.0, Diff: 44.0, Sum: 283.1] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 8] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.2] [GC Worker Total (ms): Min: 151.4, Avg: 172.3, Max: 176.1, Diff: 24.7, Sum: 1378.6] [GC Worker End (ms): Min: 93833.2, Avg: 93833.4, Max: 93834.0, Diff: 0.8] [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.2 ms] [Humongous Register: 0.1 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.1 ms] [Eden: 83.0M(95.0M)->0.0B(99.0M) Survivors: 13.0M->14.0M Heap: 2171.1M(2173.0M)->2172.6M(2270.0M)] [Times: user=0.87 sys=0.06, real=0.18 secs] 2015-09-14T12:34:03.083-0700: 99.039: [GC pause (G1 Evacuation Pause) (young) (initial-mark), 0.1867975 secs] [Parallel Time: 186.0 ms, GC Workers: 8] [GC Worker Start (ms): Min: 99039.2, Avg: 99039.4, Max: 99039.6, Diff: 0.4] [Ext Root Scanning (ms): Min: 0.0, Avg: 16.1, Max: 39.4, Diff: 39.4, Sum: 129.2] [Update RS (ms): Min: 0.0, Avg: 12.7, Max: 26.2, Diff: 26.2, Sum: 101.4] [Processed Buffers: Min: 0, Avg: 11.8, Max: 24, Diff: 24, Sum: 94] [Scan RS (ms): Min: 0.0, Avg: 1.3, Max: 3.5, Diff: 3.5, Sum: 10.3] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 45.2, Avg: 86.8, Max: 157.7, Diff: 112.5, Sum: 694.1] [Termination (ms): Min: 0.0, Avg: 68.8, Max: 100.9, Diff: 100.9, Sum: 550.4] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 8] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 185.5, Avg: 185.7, Max: 185.9, Diff: 0.4, Sum: 1485.5] [GC Worker End (ms): Min: 99225.1, Avg: 99225.1, Max: 99225.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] [Choose CSet: 0.0 ms] [Ref Proc: 0.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.2 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.1 ms] [Eden: 96.0M(99.0M)->0.0B(104.0M) Survivors: 14.0M->15.0M Heap: 2268.6M(2270.0M)->2269.6M(2381.0M)] [Times: user=1.12 sys=0.08, real=0.19 secs] 2015-09-14T12:34:03.270-0700: 99.226: [GC concurrent-root-region-scan-start] 2015-09-14T12:34:03.296-0700: 99.251: [GC concurrent-root-region-scan-end, 0.0255249 secs] 2015-09-14T12:34:03.296-0700: 99.251: [GC concurrent-mark-start] 2015-09-14T12:34:06.729-0700: 102.684: [GC concurrent-mark-reset-for-overflow] 2015-09-14T12:34:08.474-0700: 104.429: [GC concurrent-mark-end, 5.1774330 secs] 2015-09-14T12:34:08.474-0700: 104.429: [GC remark 2015-09-14T12:34:08.474-0700: 104.429: [Finalize Marking, 0.0002399 secs] 2015-09-14T12:34:08.475-0700: 104.429: [GC ref-proc, 0.0000996 secs] 2015-09-14T12:34:08.475-0700: 104.429: [Unloading, 0.0004139 secs], 0.0071618 secs] [Times: user=0.02 sys=0.00, real=0.01 secs] 2015-09-14T12:34:08.482-0700: 104.436: [GC cleanup 2365M->2187M(2381M), 0.0017900 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:34:08.483-0700: 104.438: [GC concurrent-cleanup-start] 2015-09-14T12:34:08.484-0700: 104.438: [GC concurrent-cleanup-end, 0.0000971 secs] 2015-09-14T12:34:08.900-0700: 104.855: [GC pause (G1 Evacuation Pause) (young), 0.1721608 secs] [Parallel Time: 171.5 ms, GC Workers: 8] [GC Worker Start (ms): Min: 104855.3, Avg: 104870.9, Max: 104901.5, Diff: 46.2] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.1] [Update RS (ms): Min: 0.0, Avg: 14.6, Max: 29.5, Diff: 29.5, Sum: 116.4] [Processed Buffers: Min: 0, Avg: 13.5, Max: 29, Diff: 29, Sum: 108] [Scan RS (ms): Min: 0.0, Avg: 0.9, Max: 1.9, Diff: 1.9, Sum: 7.0] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 28.0, Avg: 80.8, Max: 140.1, Diff: 112.2, Sum: 646.4] [Termination (ms): Min: 0.0, Avg: 59.4, Max: 97.2, Diff: 97.2, Sum: 475.1] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 8] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 125.2, Avg: 155.8, Max: 171.4, Diff: 46.2, Sum: 1246.1] [GC Worker End (ms): Min: 105026.7, Avg: 105026.7, Max: 105026.7, 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.3 ms] [Humongous Register: 0.1 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.1 ms] [Eden: 104.0M(104.0M)->0.0B(104.0M) Survivors: 15.0M->15.0M Heap: 2195.4M(2381.0M)->2196.4M(2381.0M)] [Times: user=1.05 sys=0.01, real=0.17 secs] 2015-09-14T12:34:14.720-0700: 110.675: [GC pause (G1 Evacuation Pause) (young), 0.1580189 secs] [Parallel Time: 157.2 ms, GC Workers: 8] [GC Worker Start (ms): Min: 110675.3, Avg: 110692.3, Max: 110742.4, Diff: 67.2] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.2] [Update RS (ms): Min: 0.0, Avg: 40.2, Max: 99.3, Diff: 99.3, Sum: 321.4] [Processed Buffers: Min: 0, Avg: 20.0, Max: 42, Diff: 42, Sum: 160] [Scan RS (ms): Min: 0.0, Avg: 1.0, Max: 1.8, Diff: 1.8, Sum: 8.1] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 43.4, Avg: 86.4, Max: 121.7, Diff: 78.3, Sum: 691.0] [Termination (ms): Min: 0.0, Avg: 11.6, Max: 14.1, Diff: 14.1, Sum: 92.8] [Termination Attempts: Min: 1, Avg: 386.4, Max: 511, Diff: 510, Sum: 3091] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 89.1, Avg: 139.3, Max: 156.8, Diff: 67.7, Sum: 1114.7] [GC Worker End (ms): Min: 110831.5, Avg: 110831.6, Max: 110832.4, Diff: 0.9] [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.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.3 ms] [Humongous Register: 0.1 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.1 ms] [Eden: 104.0M(104.0M)->0.0B(106.0M) Survivors: 15.0M->15.0M Heap: 2300.4M(2381.0M)->2300.9M(2421.0M)] [Times: user=0.84 sys=0.02, real=0.16 secs] 2015-09-14T12:34:20.634-0700: 116.589: [GC pause (G1 Evacuation Pause) (young) (initial-mark), 0.1821343 secs] [Parallel Time: 181.3 ms, GC Workers: 8] [GC Worker Start (ms): Min: 116589.5, Avg: 116589.7, Max: 116590.0, Diff: 0.5] [Ext Root Scanning (ms): Min: 0.0, Avg: 16.3, Max: 32.7, Diff: 32.7, Sum: 130.5] [Update RS (ms): Min: 10.1, Avg: 31.2, Max: 67.1, Diff: 57.0, Sum: 249.7] [Processed Buffers: Min: 7, Avg: 13.5, Max: 21, Diff: 14, Sum: 108] [Scan RS (ms): Min: 0.0, Avg: 1.0, Max: 1.8, Diff: 1.8, Sum: 8.1] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 84.6, Avg: 98.4, Max: 127.5, Diff: 42.9, Sum: 787.2] [Termination (ms): Min: 0.0, Avg: 34.0, Max: 43.9, Diff: 43.9, Sum: 272.3] [Termination Attempts: Min: 1, Avg: 4.0, Max: 8, Diff: 7, Sum: 32] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.2] [GC Worker Total (ms): Min: 180.8, Avg: 181.0, Max: 181.2, Diff: 0.5, Sum: 1447.9] [GC Worker End (ms): Min: 116770.7, Avg: 116770.7, Max: 116770.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] [Choose CSet: 0.0 ms] [Ref Proc: 0.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.3 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.1 ms] [Eden: 106.0M(106.0M)->0.0B(110.0M) Survivors: 15.0M->16.0M Heap: 2406.9M(2421.0M)->2407.9M(2530.0M)] [Times: user=0.91 sys=0.03, real=0.18 secs] 2015-09-14T12:34:20.816-0700: 116.771: [GC concurrent-root-region-scan-start] 2015-09-14T12:34:20.838-0700: 116.793: [GC concurrent-root-region-scan-end, 0.0218101 secs] 2015-09-14T12:34:20.838-0700: 116.793: [GC concurrent-mark-start] 2015-09-14T12:34:24.742-0700: 120.696: [GC concurrent-mark-reset-for-overflow] 2015-09-14T12:34:26.908-0700: 122.863: [GC concurrent-mark-end, 6.0692502 secs] 2015-09-14T12:34:26.908-0700: 122.863: [GC remark 2015-09-14T12:34:26.908-0700: 122.863: [Finalize Marking, 0.0001723 secs] 2015-09-14T12:34:26.909-0700: 122.863: [GC ref-proc, 0.0000684 secs] 2015-09-14T12:34:26.909-0700: 122.863: [Unloading, 0.0004541 secs], 0.0084588 secs] [Times: user=0.00 sys=0.00, real=0.01 secs] 2015-09-14T12:34:26.917-0700: 122.871: [GC cleanup 2913M->2913M(2932M), 0.0025456 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:34:27.802-0700: 123.756: [GC pause (G1 Evacuation Pause) (young), 0.2076865 secs] [Parallel Time: 206.8 ms, GC Workers: 8] [GC Worker Start (ms): Min: 123756.1, Avg: 123764.4, Max: 123788.8, Diff: 32.7] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.2] [Update RS (ms): Min: 0.0, Avg: 29.8, Max: 53.0, Diff: 53.0, Sum: 238.3] [Processed Buffers: Min: 0, Avg: 14.5, Max: 26, Diff: 26, Sum: 116] [Scan RS (ms): Min: 0.0, Avg: 11.7, Max: 14.7, Diff: 14.7, Sum: 93.6] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 125.2, Avg: 141.4, Max: 187.1, Diff: 61.9, Sum: 1131.4] [Termination (ms): Min: 0.0, Avg: 15.4, Max: 19.3, Diff: 19.3, Sum: 123.1] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 8] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.2] [GC Worker Total (ms): Min: 174.0, Avg: 198.5, Max: 206.7, Diff: 32.7, Sum: 1587.7] [GC Worker End (ms): Min: 123962.8, Avg: 123962.8, Max: 123962.8, 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.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.3 ms] [Humongous Register: 0.1 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.1 ms] [Eden: 121.0M(130.0M)->0.0B(134.0M) Survivors: 16.0M->19.0M Heap: 2929.9M(2932.0M)->2930.4M(3069.0M)] [Times: user=1.01 sys=0.14, real=0.21 secs] 2015-09-14T12:34:35.300-0700: 131.255: [GC pause (G1 Evacuation Pause) (young), 0.2470655 secs] [Parallel Time: 246.2 ms, GC Workers: 8] [GC Worker Start (ms): Min: 131255.0, Avg: 131270.7, Max: 131301.2, Diff: 46.1] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.2] [Update RS (ms): Min: 0.0, Avg: 27.9, Max: 57.6, Diff: 57.6, Sum: 223.4] [Processed Buffers: Min: 0, Avg: 12.1, Max: 24, Diff: 24, Sum: 97] [Scan RS (ms): Min: 0.0, Avg: 7.3, Max: 13.7, Diff: 13.7, Sum: 58.6] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 118.1, Avg: 136.4, Max: 191.9, Diff: 73.8, Sum: 1091.3] [Termination (ms): Min: 0.0, Avg: 58.2, Max: 69.9, Diff: 69.9, Sum: 465.2] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 8] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.2] [GC Worker Total (ms): Min: 199.5, Avg: 230.0, Max: 245.7, Diff: 46.2, Sum: 1839.9] [GC Worker End (ms): Min: 131500.6, Avg: 131500.7, Max: 131501.0, Diff: 0.5] [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.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.3 ms] [Humongous Register: 0.1 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.1 ms] [Eden: 134.0M(134.0M)->0.0B(141.0M) Survivors: 19.0M->20.0M Heap: 3064.4M(3069.0M)->3065.4M(3220.0M)] [Times: user=1.19 sys=0.17, real=0.25 secs] 2015-09-14T12:34:43.222-0700: 139.176: [GC pause (G1 Evacuation Pause) (young) (initial-mark), 0.3139399 secs] [Parallel Time: 312.9 ms, GC Workers: 8] [GC Worker Start (ms): Min: 139176.5, Avg: 139176.8, Max: 139177.0, Diff: 0.5] [Ext Root Scanning (ms): Min: 0.0, Avg: 21.0, Max: 51.3, Diff: 51.3, Sum: 168.3] [Update RS (ms): Min: 0.0, Avg: 17.3, Max: 35.2, Diff: 35.2, Sum: 138.2] [Processed Buffers: Min: 0, Avg: 9.9, Max: 21, Diff: 21, Sum: 79] [Scan RS (ms): Min: 0.0, Avg: 9.7, Max: 17.2, Diff: 17.2, Sum: 77.3] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 58.2, Avg: 128.6, Max: 265.3, Diff: 207.2, Sum: 1028.7] [Termination (ms): Min: 0.0, Avg: 135.4, Max: 202.8, Diff: 202.8, Sum: 1083.5] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 8] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.2] [GC Worker Total (ms): Min: 311.8, Avg: 312.0, Max: 312.3, Diff: 0.5, Sum: 2496.0] [GC Worker End (ms): Min: 139488.7, Avg: 139488.8, Max: 139489.3, Diff: 0.6] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.1 ms] [Other: 1.0 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.3 ms] [Humongous Register: 0.1 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.1 ms] [Eden: 141.0M(141.0M)->0.0B(147.0M) Survivors: 20.0M->21.0M Heap: 3206.4M(3220.0M)->3206.9M(3370.0M)] [Times: user=1.95 sys=0.08, real=0.31 secs] 2015-09-14T12:34:43.536-0700: 139.490: [GC concurrent-root-region-scan-start] 2015-09-14T12:34:43.569-0700: 139.522: [GC concurrent-root-region-scan-end, 0.0320688 secs] 2015-09-14T12:34:43.569-0700: 139.522: [GC concurrent-mark-start] 2015-09-14T12:34:48.358-0700: 144.312: [GC concurrent-mark-reset-for-overflow] 2015-09-14T12:34:50.795-0700: 146.749: [GC concurrent-mark-end, 7.2269108 secs] 2015-09-14T12:34:50.795-0700: 146.749: [GC remark 2015-09-14T12:34:50.795-0700: 146.749: [Finalize Marking, 0.0001261 secs] 2015-09-14T12:34:50.796-0700: 146.749: [GC ref-proc, 0.0000684 secs] 2015-09-14T12:34:50.796-0700: 146.750: [Unloading, 0.0003784 secs], 0.0101137 secs] [Times: user=0.01 sys=0.00, real=0.01 secs] 2015-09-14T12:34:50.806-0700: 146.759: [GC cleanup 3340M->3073M(3370M), 0.0020235 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:34:50.808-0700: 146.762: [GC concurrent-cleanup-start] 2015-09-14T12:34:50.808-0700: 146.762: [GC concurrent-cleanup-end, 0.0001907 secs] 2015-09-14T12:34:51.545-0700: 147.499: [GC pause (G1 Evacuation Pause) (young), 0.2312781 secs] [Parallel Time: 230.4 ms, GC Workers: 8] [GC Worker Start (ms): Min: 147499.5, Avg: 147522.4, Max: 147564.5, Diff: 64.9] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.1] [Update RS (ms): Min: 0.0, Avg: 22.4, Max: 74.9, Diff: 74.9, Sum: 179.0] [Processed Buffers: Min: 0, Avg: 11.1, Max: 26, Diff: 26, Sum: 89] [Scan RS (ms): Min: 0.0, Avg: 9.6, Max: 39.2, Diff: 39.2, Sum: 76.8] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 74.6, Avg: 109.1, Max: 156.8, Diff: 82.2, Sum: 873.0] [Termination (ms): Min: 0.0, Avg: 66.3, Max: 90.7, Diff: 90.7, Sum: 530.1] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 8] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.2] [GC Worker Total (ms): Min: 165.4, Avg: 207.5, Max: 230.3, Diff: 64.9, Sum: 1660.2] [GC Worker End (ms): Min: 147729.9, Avg: 147729.9, Max: 147729.9, 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.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.3 ms] [Humongous Register: 0.1 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.1 ms] [Eden: 147.0M(147.0M)->0.0B(147.0M) Survivors: 21.0M->21.0M Heap: 3086.6M(3370.0M)->3087.6M(3370.0M)] [Times: user=1.37 sys=0.02, real=0.23 secs] 2015-09-14T12:34:59.786-0700: 155.740: [GC pause (G1 Evacuation Pause) (young), 0.2735213 secs] [Parallel Time: 272.7 ms, GC Workers: 8] [GC Worker Start (ms): Min: 155739.8, Avg: 155759.9, Max: 155803.8, Diff: 63.9] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.1] [Update RS (ms): Min: 0.0, Avg: 11.9, Max: 24.0, Diff: 24.0, Sum: 95.3] [Processed Buffers: Min: 0, Avg: 9.8, Max: 20, Diff: 20, Sum: 78] [Scan RS (ms): Min: 0.0, Avg: 16.8, Max: 71.8, Diff: 71.8, Sum: 134.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.9, Avg: 110.8, Max: 233.0, Diff: 170.1, Sum: 886.1] [Termination (ms): Min: 0.0, Avg: 113.0, Max: 145.8, Diff: 145.8, Sum: 903.9] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 8] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 208.7, Avg: 252.6, Max: 272.6, Diff: 63.9, Sum: 2020.8] [GC Worker End (ms): Min: 156012.4, Avg: 156012.5, Max: 156012.5, 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.0 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.3 ms] [Humongous Register: 0.1 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.1 ms] [Eden: 147.0M(147.0M)->0.0B(149.0M) Survivors: 21.0M->21.0M Heap: 3234.6M(3370.0M)->3235.6M(3405.0M)] [Times: user=1.59 sys=0.03, real=0.27 secs] 2015-09-14T12:35:08.164-0700: 164.117: [GC pause (G1 Evacuation Pause) (young) (initial-mark), 0.3374553 secs] [Parallel Time: 336.5 ms, GC Workers: 8] [GC Worker Start (ms): Min: 164117.3, Avg: 164117.4, Max: 164117.4, Diff: 0.2] [Ext Root Scanning (ms): Min: 0.0, Avg: 20.2, Max: 32.5, Diff: 32.5, Sum: 161.3] [Update RS (ms): Min: 0.0, Avg: 7.0, Max: 19.1, Diff: 19.1, Sum: 56.2] [Processed Buffers: Min: 0, Avg: 9.8, Max: 26, Diff: 26, Sum: 78] [Scan RS (ms): Min: 0.0, Avg: 10.2, Max: 53.9, Diff: 53.9, Sum: 81.7] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 28.5, Avg: 122.4, Max: 291.0, Diff: 262.4, Sum: 979.4] [Termination (ms): Min: 0.0, Avg: 176.5, Max: 275.8, Diff: 275.8, Sum: 1412.3] [Termination Attempts: Min: 1, Avg: 21.4, Max: 57, Diff: 56, Sum: 171] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 336.3, Avg: 336.4, Max: 336.5, Diff: 0.2, Sum: 2691.1] [GC Worker End (ms): Min: 164453.7, Avg: 164453.8, Max: 164453.8, 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.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.3 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.1 ms] [Eden: 149.0M(149.0M)->0.0B(155.0M) Survivors: 21.0M->22.0M Heap: 3384.6M(3405.0M)->3385.6M(3557.0M)] [Times: user=2.12 sys=0.05, real=0.34 secs] 2015-09-14T12:35:08.501-0700: 164.455: [GC concurrent-root-region-scan-start] 2015-09-14T12:35:08.531-0700: 164.485: [GC concurrent-root-region-scan-end, 0.0298520 secs] 2015-09-14T12:35:08.531-0700: 164.485: [GC concurrent-mark-start] 2015-09-14T12:35:13.957-0700: 169.911: [GC concurrent-mark-reset-for-overflow] 2015-09-14T12:35:16.759-0700: 172.711: [GC concurrent-mark-end, 8.2266555 secs] 2015-09-14T12:35:16.759-0700: 172.711: [GC remark 2015-09-14T12:35:16.759-0700: 172.711: [Finalize Marking, 0.0003532 secs] 2015-09-14T12:35:16.759-0700: 172.712: [GC ref-proc, 0.0000718 secs] 2015-09-14T12:35:16.759-0700: 172.712: [Unloading, 0.0005055 secs], 0.0110353 secs] [Times: user=0.08 sys=0.00, real=0.01 secs] 2015-09-14T12:35:16.770-0700: 172.723: [GC cleanup 3537M->3537M(3557M), 0.0028441 secs] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:35:16.908-0700: 172.861: [GC pause (G1 Evacuation Pause) (young), 0.2445934 secs] [Parallel Time: 243.7 ms, GC Workers: 8] [GC Worker Start (ms): Min: 172861.2, Avg: 172874.9, Max: 172899.5, Diff: 38.3] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.1] [Update RS (ms): Min: 0.0, Avg: 17.1, Max: 34.4, Diff: 34.4, Sum: 137.1] [Processed Buffers: Min: 0, Avg: 13.6, Max: 27, Diff: 27, Sum: 109] [Scan RS (ms): Min: 0.0, Avg: 10.5, Max: 22.7, Diff: 22.7, Sum: 83.8] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 99.9, Avg: 137.3, Max: 202.9, Diff: 103.0, Sum: 1098.2] [Termination (ms): Min: 0.0, Avg: 63.7, Max: 95.5, Diff: 95.5, Sum: 509.3] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 8] [GC Worker Other (ms): Min: 0.0, Avg: 1.3, Max: 9.9, Diff: 9.9, Sum: 10.1] [GC Worker Total (ms): Min: 205.3, Avg: 229.9, Max: 243.6, Diff: 38.3, Sum: 1839.6] [GC Worker End (ms): Min: 173104.8, Avg: 173104.9, Max: 173104.9, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.2 ms] [Other: 0.8 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.3 ms] [Humongous Register: 0.1 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.1 ms] [Eden: 155.0M(155.0M)->0.0B(162.0M) Survivors: 22.0M->23.0M Heap: 3540.6M(3557.0M)->3541.1M(3719.0M)] [Times: user=1.50 sys=0.22, real=0.25 secs] 2015-09-14T12:35:25.956-0700: 181.909: [GC pause (G1 Evacuation Pause) (young), 0.2652973 secs] [Parallel Time: 264.4 ms, GC Workers: 8] [GC Worker Start (ms): Min: 181909.2, Avg: 181928.9, Max: 181968.5, Diff: 59.3] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.2, Max: 0.4, Diff: 0.4, Sum: 1.3] [Update RS (ms): Min: 0.0, Avg: 35.5, Max: 92.3, Diff: 92.3, Sum: 284.0] [Processed Buffers: Min: 0, Avg: 15.3, Max: 31, Diff: 31, Sum: 122] [Scan RS (ms): Min: 0.0, Avg: 3.5, Max: 7.1, Diff: 7.1, Sum: 27.7] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 99.2, Avg: 136.4, Max: 217.0, Diff: 117.7, Sum: 1091.4] [Termination (ms): Min: 0.0, Avg: 69.1, Max: 84.7, Diff: 84.7, Sum: 552.6] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 8] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 205.0, Avg: 244.6, Max: 264.3, Diff: 59.3, Sum: 1957.2] [GC Worker End (ms): Min: 182173.5, Avg: 182173.5, Max: 182173.5, 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.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.4 ms] [Humongous Register: 0.1 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.1 ms] [Eden: 162.0M(162.0M)->0.0B(170.0M) Survivors: 23.0M->24.0M Heap: 3703.1M(3719.0M)->3704.1M(3890.0M)] [Times: user=1.50 sys=0.13, real=0.26 secs] 2015-09-14T12:35:27.116-0700: 183.069: [GC pause (G1 Humongous Allocation) (young) (initial-mark), 0.0943500 secs] [Parallel Time: 93.5 ms, GC Workers: 8] [GC Worker Start (ms): Min: 183069.6, Avg: 183069.8, Max: 183070.0, Diff: 0.4] [Ext Root Scanning (ms): Min: 0.0, Avg: 19.9, Max: 64.5, Diff: 64.5, Sum: 159.2] [Update RS (ms): Min: 0.0, Avg: 12.3, Max: 25.0, Diff: 25.0, Sum: 98.3] [Processed Buffers: Min: 0, Avg: 11.8, Max: 25, Diff: 25, Sum: 94] [Scan RS (ms): Min: 0.0, Avg: 4.2, Max: 15.6, Diff: 15.6, Sum: 33.6] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 3.7, Avg: 36.5, Max: 63.0, Diff: 59.3, Sum: 291.8] [Termination (ms): Min: 0.0, Avg: 20.4, Max: 24.9, Diff: 24.9, Sum: 163.0] [Termination Attempts: Min: 1, Avg: 2.0, Max: 5, Diff: 4, Sum: 16] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 93.1, Avg: 93.3, Max: 93.5, Diff: 0.4, Sum: 746.1] [GC Worker End (ms): Min: 183163.1, Avg: 183163.1, Max: 183163.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] [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: 18.0M(170.0M)->0.0B(176.0M) Survivors: 24.0M->18.0M Heap: 3721.6M(3890.0M)->3724.6M(3890.0M)] [Times: user=0.42 sys=0.00, real=0.10 secs] 2015-09-14T12:35:27.211-0700: 183.164: [GC concurrent-root-region-scan-start] 2015-09-14T12:35:27.224-0700: 183.177: [GC pause (G1 Humongous Allocation) (young)2015-09-14T12:35:27.232-0700: 183.185: [GC concurrent-root-region-scan-end, 0.0215723 secs] 2015-09-14T12:35:27.232-0700: 183.185: [GC concurrent-mark-start] , 0.0367619 secs] [Root Region Scan Waiting: 8.0 ms] [Parallel Time: 28.3 ms, GC Workers: 8] [GC Worker Start (ms): Min: 183185.4, Avg: 183185.4, Max: 183185.4, Diff: 0.0] [Ext Root Scanning (ms): Min: 0.1, Avg: 5.4, Max: 28.2, Diff: 28.1, Sum: 43.1] [Update RS (ms): Min: 0.0, Avg: 9.2, Max: 14.1, Diff: 14.0, Sum: 74.0] [Processed Buffers: Min: 0, Avg: 14.9, Max: 29, Diff: 29, Sum: 119] [Scan RS (ms): Min: 0.0, Avg: 0.4, Max: 0.7, Diff: 0.7, Sum: 2.9] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 0.0, Avg: 13.2, Max: 15.5, Diff: 15.5, Sum: 105.3] [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.7] [Termination Attempts: Min: 1, Avg: 176.1, Max: 251, Diff: 250, Sum: 1409] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 28.2, Avg: 28.3, Max: 28.3, Diff: 0.0, Sum: 226.1] [GC Worker End (ms): Min: 183213.6, Avg: 183213.6, Max: 183213.7, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.1 ms] [Other: 0.3 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.1 ms] [Humongous Register: 0.1 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 0.0B(176.0M)->0.0B(194.0M) Survivors: 18.0M->0.0B Heap: 3724.6M(3890.0M)->3724.6M(3890.0M)] [Times: user=0.22 sys=0.00, real=0.04 secs] 2015-09-14T12:35:27.263-0700: 183.216: [Full GC (Allocation Failure) 3727M->3612M(4022M), 9.7408549 secs] [Eden: 3072.0K(194.0M)->0.0B(201.0M) Survivors: 0.0B->0.0B Heap: 3727.1M(4022.0M)->3612.0M(4022.0M)], [Metaspace: 2776K->2776K(1056768K)] [Times: user=19.08 sys=0.01, real=9.74 secs] 2015-09-14T12:35:37.005-0700: 192.958: [Full GC (Allocation Failure) 3612M->3611M(4022M), 9.6052636 secs] [Eden: 0.0B(201.0M)->0.0B(201.0M) Survivors: 0.0B->0.0B Heap: 3612.0M(4022.0M)->3612.0M(4022.0M)], [Metaspace: 2776K->2776K(1056768K)] [Times: user=18.91 sys=0.00, real=9.60 secs] 2015-09-14T12:35:46.611-0700: 202.563: [GC concurrent-mark-abort] 2015-09-14T12:35:46.611-0700: 202.563: [GC pause (G1 Humongous Allocation) (young) (initial-mark), 0.0011122 secs] [Parallel Time: 0.3 ms, GC Workers: 8] [GC Worker Start (ms): Min: 202563.4, Avg: 202563.5, Max: 202563.6, Diff: 0.2] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 1.1] [Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Processed Buffers: Min: 0, Avg: 0.3, Max: 1, Diff: 1, Sum: 2] [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.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 8] [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.1, Avg: 0.2, Max: 0.3, Diff: 0.2, Sum: 1.6] [GC Worker End (ms): Min: 202563.7, Avg: 202563.7, Max: 202563.7, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.2 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.1 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 1024.0K(201.0M)->0.0B(200.0M) Survivors: 0.0B->1024.0K Heap: 3612.5M(4022.0M)->3613.0M(4022.0M)] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:35:46.612-0700: 202.564: [GC concurrent-root-region-scan-start] 2015-09-14T12:35:46.612-0700: 202.564: [GC concurrent-root-region-scan-end, 0.0000214 secs] 2015-09-14T12:35:46.612-0700: 202.564: [GC concurrent-mark-start] 2015-09-14T12:35:46.613-0700: 202.565: [GC pause (G1 Humongous Allocation) (young), 0.0004661 secs] [Parallel Time: 0.2 ms, GC Workers: 8] [GC Worker Start (ms): Min: 202565.4, Avg: 202565.4, Max: 202565.6, Diff: 0.1] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.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.1, Max: 1, Diff: 1, Sum: 1] [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.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.3] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 8] [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.0, Avg: 0.1, Max: 0.2, Diff: 0.1, Sum: 1.0] [GC Worker End (ms): Min: 202565.6, Avg: 202565.6, Max: 202565.6, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.0 ms] [Other: 0.2 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.0 ms] [Humongous Register: 0.1 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 0.0B(200.0M)->0.0B(200.0M) Survivors: 1024.0K->1024.0K Heap: 3613.0M(4022.0M)->3612.3M(4022.0M)] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:35:46.614-0700: 202.566: [Full GC (Allocation Failure) 3612M->3611M(4022M), 8.6558823 secs] [Eden: 1024.0K(200.0M)->0.0B(201.0M) Survivors: 1024.0K->0.0B Heap: 3612.8M(4022.0M)->3612.0M(4022.0M)], [Metaspace: 2776K->2776K(1056768K)] [Times: user=18.00 sys=0.00, real=8.66 secs] 2015-09-14T12:35:55.271-0700: 211.222: [Full GC (Allocation Failure) 3611M->3611M(4022M), 8.2331648 secs] [Eden: 0.0B(201.0M)->0.0B(201.0M) Survivors: 0.0B->0.0B Heap: 3612.0M(4022.0M)->3612.0M(4022.0M)], [Metaspace: 2776K->2776K(1056768K)] [Times: user=17.60 sys=0.00, real=8.23 secs] 2015-09-14T12:36:03.504-0700: 219.456: [GC concurrent-mark-abort] 2015-09-14T12:36:03.504-0700: 219.456: [GC pause (G1 Humongous Allocation) (young) (initial-mark), 0.0009857 secs] [Parallel Time: 0.5 ms, GC Workers: 8] [GC Worker Start (ms): Min: 219456.2, Avg: 219456.2, Max: 219456.4, Diff: 0.2] [Ext Root Scanning (ms): Min: 0.2, Avg: 0.4, Max: 0.5, Diff: 0.3, Sum: 2.8] [Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Processed Buffers: Min: 0, Avg: 0.3, Max: 1, Diff: 1, Sum: 2] [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.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.4] [Termination Attempts: Min: 1, Avg: 1.9, Max: 3, Diff: 2, Sum: 15] [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.3, Avg: 0.4, Max: 0.5, Diff: 0.2, Sum: 3.4] [GC Worker End (ms): Min: 219456.6, Avg: 219456.6, Max: 219456.6, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.0 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.0 ms] [Eden: 2048.0K(201.0M)->0.0B(200.0M) Survivors: 0.0B->1024.0K Heap: 3613.0M(4022.0M)->3612.2M(4022.0M)] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:36:03.505-0700: 219.457: [GC concurrent-root-region-scan-start] 2015-09-14T12:36:03.505-0700: 219.457: [GC concurrent-root-region-scan-end, 0.0000949 secs] 2015-09-14T12:36:03.505-0700: 219.457: [GC concurrent-mark-start] 2015-09-14T12:36:03.506-0700: 219.458: [GC pause (G1 Humongous Allocation) (young), 0.0007022 secs] [Parallel Time: 0.3 ms, GC Workers: 8] [GC Worker Start (ms): Min: 219458.5, Avg: 219458.6, Max: 219458.7, Diff: 0.2] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.0] [Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Processed Buffers: Min: 0, Avg: 0.4, Max: 1, Diff: 1, Sum: 3] [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.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.9] [Termination Attempts: Min: 1, Avg: 1.3, Max: 2, Diff: 1, Sum: 10] [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.1, Avg: 0.3, Max: 0.3, Diff: 0.2, Sum: 2.0] [GC Worker End (ms): Min: 219458.8, Avg: 219458.8, Max: 219458.8, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.0 ms] [Other: 0.3 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.0 ms] [Humongous Register: 0.1 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 1024.0K(200.0M)->0.0B(200.0M) Survivors: 1024.0K->1024.0K Heap: 3613.2M(4022.0M)->3612.0M(4022.0M)] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:36:03.508-0700: 219.460: [Full GC (Allocation Failure) 3612M->3611M(4022M), 8.2364699 secs] [Eden: 0.0B(200.0M)->0.0B(201.0M) Survivors: 1024.0K->0.0B Heap: 3612.0M(4022.0M)->3612.0M(4022.0M)], [Metaspace: 2799K->2799K(1056768K)] [Times: user=17.67 sys=0.00, real=8.24 secs] 2015-09-14T12:36:11.745-0700: 227.696: [Full GC (Allocation Failure) 3611M->3611M(4022M), 8.3133357 secs] [Eden: 0.0B(201.0M)->0.0B(201.0M) Survivors: 0.0B->0.0B Heap: 3612.0M(4022.0M)->3612.0M(4022.0M)], [Metaspace: 2799K->2799K(1056768K)] [Times: user=17.86 sys=0.02, real=8.31 secs] 2015-09-14T12:36:20.058-0700: 236.010: [GC concurrent-mark-abort] 2015-09-14T12:36:20.058-0700: 236.010: [GC pause (G1 Humongous Allocation) (young) (initial-mark), 0.0005752 secs] [Parallel Time: 0.2 ms, GC Workers: 8] [GC Worker Start (ms): Min: 236010.1, Avg: 236010.1, Max: 236010.1, Diff: 0.0] [Ext Root Scanning (ms): Min: 0.1, Avg: 0.1, Max: 0.1, Diff: 0.0, Sum: 0.9] [Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Processed Buffers: Min: 0, Avg: 0.3, Max: 1, Diff: 1, Sum: 2] [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.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [Termination Attempts: Min: 1, Avg: 1.4, Max: 2, Diff: 1, Sum: 11] [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.1, Avg: 0.2, Max: 0.2, Diff: 0.0, Sum: 1.2] [GC Worker End (ms): Min: 236010.2, Avg: 236010.2, Max: 236010.2, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.0 ms] [Other: 0.3 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.0 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: 2048.0K(201.0M)->0.0B(200.0M) Survivors: 0.0B->1024.0K Heap: 3613.0M(4022.0M)->3612.0M(4022.0M)] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:36:20.059-0700: 236.011: [GC concurrent-root-region-scan-start] 2015-09-14T12:36:20.059-0700: 236.011: [GC concurrent-root-region-scan-end, 0.0000599 secs] 2015-09-14T12:36:20.059-0700: 236.011: [GC concurrent-mark-start] 2015-09-14T12:36:20.059-0700: 236.011: [GC pause (G1 Humongous Allocation) (young), 0.0003699 secs] [Parallel Time: 0.2 ms, GC Workers: 8] [GC Worker Start (ms): Min: 236010.9, Avg: 236010.9, Max: 236010.9, Diff: 0.0] [Ext Root Scanning (ms): Min: 0.1, Avg: 0.1, Max: 0.1, Diff: 0.0, Sum: 0.8] [Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Processed Buffers: Min: 0, Avg: 0.3, Max: 1, Diff: 1, Sum: 2] [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.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [Termination Attempts: Min: 1, Avg: 1.3, Max: 2, Diff: 1, Sum: 10] [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.1, Avg: 0.1, Max: 0.1, Diff: 0.0, Sum: 0.9] [GC Worker End (ms): Min: 236011.0, Avg: 236011.0, Max: 236011.0, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.0 ms] [Other: 0.2 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.0 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.0 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 0.0B(200.0M)->0.0B(200.0M) Survivors: 1024.0K->1024.0K Heap: 3612.0M(4022.0M)->3612.0M(4022.0M)] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:36:20.060-0700: 236.011: [Full GC (Allocation Failure) 3612M->3611M(4022M), 8.1948189 secs] [Eden: 0.0B(200.0M)->0.0B(201.0M) Survivors: 1024.0K->0.0B Heap: 3612.0M(4022.0M)->3612.0M(4022.0M)], [Metaspace: 2799K->2799K(1056768K)] [Times: user=17.49 sys=0.03, real=8.20 secs] 2015-09-14T12:36:28.256-0700: 244.206: [Full GC (Allocation Failure) 3611M->3611M(4022M), 8.2806631 secs] [Eden: 0.0B(201.0M)->0.0B(201.0M) Survivors: 0.0B->0.0B Heap: 3612.0M(4022.0M)->3612.0M(4022.0M)], [Metaspace: 2799K->2799K(1056768K)] [Times: user=17.41 sys=0.00, real=8.28 secs] 2015-09-14T12:36:36.536-0700: 252.487: [GC concurrent-mark-abort] 2015-09-14T12:36:36.537-0700: 252.488: [GC pause (G1 Humongous Allocation) (young) (initial-mark), 0.0014107 secs] [Parallel Time: 0.5 ms, GC Workers: 8] [GC Worker Start (ms): Min: 252487.8, Avg: 252487.9, Max: 252488.0, Diff: 0.2] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.2, Max: 0.3, Diff: 0.3, Sum: 1.5] [Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Processed Buffers: Min: 0, Avg: 0.1, Max: 1, Diff: 1, Sum: 1] [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.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.7] [Termination Attempts: Min: 1, Avg: 1.1, Max: 2, Diff: 1, Sum: 9] [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.2, Avg: 0.3, Max: 0.4, Diff: 0.2, Sum: 2.2] [GC Worker End (ms): Min: 252488.1, Avg: 252488.2, Max: 252488.2, 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.0 ms] [Ref Proc: 0.3 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: 2048.0K(201.0M)->0.0B(200.0M) Survivors: 0.0B->1024.0K Heap: 3613.0M(4022.0M)->3612.0M(4022.0M)] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:36:36.538-0700: 252.489: [GC concurrent-root-region-scan-start] 2015-09-14T12:36:36.538-0700: 252.489: [GC concurrent-root-region-scan-end, 0.0001086 secs] 2015-09-14T12:36:36.538-0700: 252.489: [GC concurrent-mark-start] 2015-09-14T12:36:36.538-0700: 252.489: [GC pause (G1 Humongous Allocation) (young), 0.0005277 secs] [Parallel Time: 0.2 ms, GC Workers: 8] [GC Worker Start (ms): Min: 252489.6, Avg: 252489.6, Max: 252489.8, Diff: 0.2] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.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.1, Max: 1, Diff: 1, Sum: 1] [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.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.5] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 8] [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.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 1.2] [GC Worker End (ms): Min: 252489.8, Avg: 252489.8, Max: 252489.8, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.0 ms] [Other: 0.3 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.0 ms] [Humongous Register: 0.1 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 0.0B(200.0M)->0.0B(200.0M) Survivors: 1024.0K->1024.0K Heap: 3612.0M(4022.0M)->3612.0M(4022.0M)] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:36:36.539-0700: 252.490: [Full GC (Allocation Failure) 3612M->3611M(4022M), 9.6151469 secs] [Eden: 1024.0K(200.0M)->0.0B(201.0M) Survivors: 1024.0K->0.0B Heap: 3612.5M(4022.0M)->3612.0M(4022.0M)], [Metaspace: 2800K->2800K(1056768K)] [Times: user=18.97 sys=0.02, real=9.62 secs] 2015-09-14T12:36:46.156-0700: 262.106: [Full GC (Allocation Failure) 3611M->3611M(4022M), 8.2284327 secs] [Eden: 0.0B(201.0M)->0.0B(201.0M) Survivors: 0.0B->0.0B Heap: 3612.0M(4022.0M)->3612.0M(4022.0M)], [Metaspace: 2800K->2800K(1056768K)] [Times: user=17.47 sys=0.01, real=8.23 secs] 2015-09-14T12:36:54.384-0700: 270.335: [GC concurrent-mark-abort] 2015-09-14T12:36:54.384-0700: 270.335: [GC pause (G1 Humongous Allocation) (young) (initial-mark), 0.0017815 secs] [Parallel Time: 0.5 ms, GC Workers: 8] [GC Worker Start (ms): Min: 270335.0, Avg: 270335.0, Max: 270335.1, Diff: 0.2] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.2, Max: 0.2, 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.1, Max: 1, Diff: 1, Sum: 1] [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.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4] [Termination Attempts: Min: 1, Avg: 1.6, Max: 3, Diff: 2, Sum: 13] [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.1, Avg: 0.2, Max: 0.3, Diff: 0.2, Sum: 1.7] [GC Worker End (ms): Min: 270335.3, Avg: 270335.3, Max: 270335.3, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.1 ms] [Other: 1.2 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.6 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.3 ms] [Humongous Register: 0.1 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 2048.0K(201.0M)->0.0B(200.0M) Survivors: 0.0B->1024.0K Heap: 3613.0M(4022.0M)->3612.0M(4022.0M)] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:36:54.386-0700: 270.337: [GC concurrent-root-region-scan-start] 2015-09-14T12:36:54.386-0700: 270.337: [GC concurrent-root-region-scan-end, 0.0000821 secs] 2015-09-14T12:36:54.386-0700: 270.337: [GC concurrent-mark-start] 2015-09-14T12:36:54.387-0700: 270.337: [GC pause (G1 Humongous Allocation) (young), 0.0005114 secs] [Parallel Time: 0.2 ms, GC Workers: 8] [GC Worker Start (ms): Min: 270337.1, Avg: 270337.2, Max: 270337.3, Diff: 0.2] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.5] [Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Processed Buffers: Min: 0, Avg: 0.4, Max: 1, Diff: 1, Sum: 3] [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.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2] [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.4] [Termination Attempts: Min: 1, Avg: 1.3, Max: 2, Diff: 1, Sum: 10] [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.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 1.2] [GC Worker End (ms): Min: 270337.3, Avg: 270337.3, Max: 270337.3, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.0 ms] [Other: 0.2 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.0 ms] [Humongous Register: 0.1 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.0 ms] [Eden: 1024.0K(200.0M)->0.0B(200.0M) Survivors: 1024.0K->1024.0K Heap: 3612.5M(4022.0M)->3612.0M(4022.0M)] [Times: user=0.00 sys=0.00, real=0.00 secs] 2015-09-14T12:36:54.387-0700: 270.338: [Full GC (Allocation Failure) 3612M->3611M(4022M), 8.2935174 secs] [Eden: 0.0B(200.0M)->0.0B(201.0M) Survivors: 1024.0K->0.0B Heap: 3612.0M(4022.0M)->3612.0M(4022.0M)], [Metaspace: 2800K->2800K(1056768K)] [Times: user=17.63 sys=0.00, real=8.29 secs] 2015-09-14T12:37:02.682-0700: 278.632: [Full GC (Allocation Failure) 3611M->3611M(4022M), 8.2928910 secs] [Eden: 0.0B(201.0M)->0.0B(201.0M) Survivors: 0.0B->0.0B Heap: 3612.0M(4022.0M)->3612.0M(4022.0M)], [Metaspace: 2800K->2800K(1056768K)] [Times: user=17.60 sys=0.02, real=8.29 secs] 2015-09-14T12:37:10.975-0700: 286.925: [GC concurrent-mark-abort] Heap garbage-first heap total 4118528K, used 3701758K [0x00000006c4a00000, 0x00000006c4b07db0, 0x00000007c0000000) region size 1024K, 4 young (4096K), 0 survivors (0K) Metaspace used 2810K, capacity 4566K, committed 4864K, reserved 1056768K class space used 314K, capacity 390K, committed 512K, reserved 1048576K