Open sendaoYan opened 1 year ago
elastic-heap/TestElasticHeapGenerationLimit.java随机失败
https://tone.aliyun-inc.com/ws/xesljfzh/test_result/155163?tab=2
ACTION: build -- Passed. All files up to date
REASON: Named class compiled on demand
TIME: 0.0 seconds
messages:
command: build TestElasticHeapGenerationLimit
reason: Named class compiled on demand
elapsed time (seconds): 0.0
ACTION: main -- Failed. Execution failed: `main' threw exception: java.lang.RuntimeException: 'gc is too frequent' missing from stdout/stderr
REASON: User specified action: run main/othervm/timeout=500 -XX:+UseG1GC -XX:+G1ElasticHeap -Xmx1000m -Xms1000m -XX:+AlwaysPreTouch -XX:MaxNewSize=400m -XX:G1HeapRegionSize=1m -XX:ElasticHeapYGCIntervalMinMillis=500 -verbose:gc -XX:+PrintGCDetails -XX:+PrintGCTimeStamps TestElasticHeapGenerationLimit
TIME: 95.896 seconds
messages:
command: main -XX:+UseG1GC -XX:+G1ElasticHeap -Xmx1000m -Xms1000m -XX:+AlwaysPreTouch -XX:MaxNewSize=400m -XX:G1HeapRegionSize=1m -XX:ElasticHeapYGCIntervalMinMillis=500 -verbose:gc -XX:+PrintGCDetails -XX:+PrintGCTimeStamps TestElasticHeapGenerationLimit
reason: User specified action: run main/othervm/timeout=500 -XX:+UseG1GC -XX:+G1ElasticHeap -Xmx1000m -Xms1000m -XX:+AlwaysPreTouch -XX:MaxNewSize=400m -XX:G1HeapRegionSize=1m -XX:ElasticHeapYGCIntervalMinMillis=500 -verbose:gc -XX:+PrintGCDetails -XX:+PrintGCTimeStamps TestElasticHeapGenerationLimit
Mode: othervm [/othervm specified]
elapsed time (seconds): 95.896
configuration:
STDOUT:
0.788: [GC pause (G1 Evacuation Pause) (young), 0.0395242 secs]
[Parallel Time: 2.7 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 787.9, Avg: 788.8, Max: 789.6, Diff: 1.7]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 1.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.0, Max: 0, Diff: 0, Sum: 0]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 2.0]
[Termination (ms): Min: 0.5, Avg: 1.3, Max: 2.0, Diff: 1.6, Sum: 105.5]
[Termination Attempts: Min: 1, Avg: 1.3, Max: 7, Diff: 6, Sum: 108]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.6]
[GC Worker Total (ms): Min: 0.5, Avg: 1.3, Max: 2.5, Diff: 2.0, Sum: 110.2]
[GC Worker End (ms): Min: 789.9, Avg: 790.2, Max: 790.4, Diff: 0.5]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.8 ms]
[Other: 35.0 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 33.6 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.3 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.1 ms]
[Eden: 51200.0K(51200.0K)->0.0B(72704.0K) Survivors: 0.0B->1024.0K Heap: 51200.0K(1000.0M)->672.1K(1000.0M)]
[Times: user=0.68 sys=0.00, real=0.04 secs]
1.231: [GC pause (G1 Evacuation Pause) (young), 0.0239477 secs]
[Parallel Time: 2.3 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 1231.6, Avg: 1232.4, Max: 1233.0, Diff: 1.4]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.6, Diff: 0.6, Sum: 4.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.0, Max: 0, Diff: 0, Sum: 0]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.3]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 3.4]
[Termination (ms): Min: 0.0, Avg: 0.8, Max: 1.0, Diff: 1.0, Sum: 62.9]
[Termination Attempts: Min: 1, Avg: 1.3, Max: 4, Diff: 3, Sum: 105]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 1.0]
[GC Worker Total (ms): Min: 0.0, Avg: 0.9, Max: 1.5, Diff: 1.5, Sum: 71.8]
[GC Worker End (ms): Min: 1233.0, Avg: 1233.3, Max: 1233.5, Diff: 0.5]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.2 ms]
[Other: 20.4 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 19.1 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 0.9 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.1 ms]
[Eden: 72704.0K(72704.0K)->0.0B(399.0M) Survivors: 1024.0K->1024.0K Heap: 73376.1K(1000.0M)->548.5K(1000.0M)]
[Times: user=0.32 sys=0.00, real=0.03 secs]
3.890: [GC pause (G1 Evacuation Pause) (young), 0.0879503 secs]
[Parallel Time: 4.5 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 3890.8, Avg: 3893.0, Max: 3893.8, Diff: 3.1]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.6, Diff: 0.6, Sum: 1.2]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 1.1]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.7, Diff: 0.7, Sum: 1.4]
[Termination (ms): Min: 0.1, Avg: 1.3, Max: 2.2, Diff: 2.1, Sum: 105.7]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 2, Diff: 1, Sum: 84]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.9]
[GC Worker Total (ms): Min: 0.1, Avg: 1.3, Max: 3.5, Diff: 3.4, Sum: 110.5]
[GC Worker End (ms): Min: 3893.9, Avg: 3894.3, Max: 3894.8, Diff: 0.9]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 6.2 ms]
[Other: 77.3 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 43.9 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 32.5 ms]
[Humongous Register: 0.1 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.6 ms]
[Eden: 399.0M(399.0M)->0.0B(399.0M) Survivors: 1024.0K->1024.0K Heap: 399.5M(1000.0M)->525.6K(1000.0M)]
[Times: user=0.72 sys=0.01, real=0.09 secs]
7.033: [GC pause (G1 Evacuation Pause) (young), 0.0371959 secs]
[Parallel Time: 13.7 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 7033.3, Avg: 7040.9, Max: 7042.9, Diff: 9.6]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, 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.0, Max: 0, Diff: 0, Sum: 0]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.9]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.3, Diff: 0.3, Sum: 1.1]
[Termination (ms): Min: 0.0, Avg: 5.6, Max: 13.0, Diff: 12.9, Sum: 465.8]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 2, Diff: 1, Sum: 84]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.6]
[GC Worker Total (ms): Min: 0.0, Avg: 5.7, Max: 13.6, Diff: 13.5, Sum: 469.4]
[GC Worker End (ms): Min: 7043.0, Avg: 7046.5, Max: 7046.9, Diff: 4.0]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.2 ms]
[Other: 22.4 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 20.3 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.7 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 399.0M(399.0M)->0.0B(399.0M) Survivors: 1024.0K->1024.0K Heap: 399.5M(1000.0M)->526.7K(1000.0M)]
[Times: user=0.38 sys=0.00, real=0.03 secs]
Full rss: 1161232
7.778: [Full GC (System.gc()) 91152K->787K(1000M), 0.0146969 secs]
[Eden: 91136.0K(399.0M)->0.0B(400.0M) Survivors: 1024.0K->0.0B Heap: 91152.7K(1000.0M)->787.5K(1000.0M)], [Metaspace: 4789K->4789K(1056768K)]
[Times: user=0.01 sys=0.00, real=0.02 secs]
8.859: [GC pause (Elastic Heap Initiated GC) (young)(Elastic Heap concurrent cycle starts due to generation limit), 0.0590647 secs]
[Parallel Time: 3.7 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 8859.0, Avg: 8860.6, Max: 8861.3, Diff: 2.3]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.6, Diff: 0.6, Sum: 0.7]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.1, Max: 5, Diff: 5, Sum: 5]
[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.3]
[Termination (ms): Min: 0.1, Avg: 1.1, Max: 2.0, Diff: 1.9, Sum: 92.8]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.3]
[GC Worker Total (ms): Min: 0.2, Avg: 1.1, Max: 2.4, Diff: 2.2, Sum: 94.2]
[GC Worker End (ms): Min: 8861.3, Avg: 8861.8, Max: 8862.1, Diff: 0.8]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 25.9 ms]
[Other: 29.4 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 28.1 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.1 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.0 ms]
[Eden: 3072.0K(400.0M)->0.0B(199.0M) Survivors: 0.0B->1024.0K Heap: 2837.5K(1000.0M)->842.4K(800.0M)]
[Times: user=0.28 sys=0.01, real=0.06 secs]
[Elastic Heap concurrent thread: uncommit 200M spent 0.008s(concurrent workers: 21) ]
291227:
[GC.elastic_heap: in generation limit mode]
[GC.elastic_heap: young generation commit percent 50, uncommitted memory 209715200 B]
10.610: [GC pause (G1 Evacuation Pause) (young)(Elastic Heap concurrent cycle ends)
, 0.0683235 secs]
[Parallel Time: 20.8 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 10610.5, Avg: 10613.4, Max: 10615.3, Diff: 4.8]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.9, Diff: 0.9, Sum: 1.1]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.1, Max: 5, Diff: 5, Sum: 5]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.5]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Termination (ms): Min: 0.0, Avg: 11.6, Max: 18.6, Diff: 18.6, Sum: 962.9]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.7]
[GC Worker Total (ms): Min: 0.0, Avg: 11.6, Max: 18.7, Diff: 18.6, Sum: 965.7]
[GC Worker End (ms): Min: 10615.4, Avg: 10625.0, Max: 10631.2, Diff: 15.8]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.3 ms]
[Other: 46.2 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 43.7 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 2.0 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 199.8M(800.0M)->1025.8K(800.0M)]
[Times: user=0.24 sys=0.00, real=0.07 secs]
12.051: [GC pause (G1 Evacuation Pause) (young), 0.0588696 secs]
[Parallel Time: 4.7 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 12051.5, Avg: 12053.6, Max: 12054.7, Diff: 3.2]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.6, Diff: 0.6, Sum: 0.7]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 3, Diff: 3, Sum: 3]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Termination (ms): Min: 0.0, Avg: 1.7, Max: 3.0, Diff: 3.0, Sum: 144.2]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.6]
[GC Worker Total (ms): Min: 0.0, Avg: 1.8, Max: 3.3, Diff: 3.3, Sum: 146.3]
[GC Worker End (ms): Min: 12054.7, Avg: 12055.4, Max: 12056.0, Diff: 1.3]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 2.5 ms]
[Other: 51.7 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 50.5 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 0.9 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.0M(800.0M)->1021.7K(800.0M)]
[Times: user=0.46 sys=0.00, real=0.06 secs]
13.436: [GC pause (G1 Evacuation Pause) (young), 0.0489420 secs]
[Parallel Time: 2.7 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 13436.5, Avg: 13437.8, Max: 13438.6, Diff: 2.0]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, Sum: 0.8]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 2]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Termination (ms): Min: 0.0, Avg: 1.0, Max: 1.8, Diff: 1.8, Sum: 80.7]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.3]
[GC Worker Total (ms): Min: 0.0, Avg: 1.0, Max: 2.3, Diff: 2.3, Sum: 82.5]
[GC Worker End (ms): Min: 13438.6, Avg: 13438.8, Max: 13439.1, Diff: 0.5]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 10.3 ms]
[Other: 35.9 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 26.0 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 9.4 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.0M(800.0M)->1024.0K(800.0M)]
[Times: user=0.34 sys=0.01, real=0.05 secs]
14.923: [GC pause (G1 Evacuation Pause) (young), 0.0368418 secs]
[Parallel Time: 8.3 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 14923.1, Avg: 14929.1, Max: 14930.0, Diff: 6.9]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, Sum: 0.6]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 2, Diff: 2, Sum: 2]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Termination (ms): Min: 0.0, Avg: 1.3, Max: 6.4, Diff: 6.4, Sum: 106.8]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.4]
[GC Worker Total (ms): Min: 0.0, Avg: 1.3, Max: 7.0, Diff: 7.0, Sum: 108.6]
[GC Worker End (ms): Min: 14930.0, Avg: 14930.4, Max: 14930.9, Diff: 0.8]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 3.4 ms]
[Other: 25.1 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 23.4 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.4 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.0M(800.0M)->1021.7K(800.0M)]
[Times: user=0.32 sys=0.01, real=0.03 secs]
16.306: [GC pause (G1 Evacuation Pause) (young), 0.0610823 secs]
[Parallel Time: 5.6 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 16305.8, Avg: 16307.6, Max: 16308.4, Diff: 2.6]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, 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.0, Max: 2, Diff: 2, Sum: 2]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.0, Sum: 0.3]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Termination (ms): Min: 0.0, Avg: 2.1, Max: 4.2, Diff: 4.2, Sum: 171.7]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.3]
[GC Worker Total (ms): Min: 0.0, Avg: 2.1, Max: 4.2, Diff: 4.2, Sum: 173.2]
[GC Worker End (ms): Min: 16308.4, Avg: 16309.7, Max: 16311.4, Diff: 2.9]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 2.5 ms]
[Other: 52.9 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 26.4 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 26.2 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.0M(800.0M)->1021.7K(800.0M)]
[Times: user=0.22 sys=0.01, real=0.06 secs]
50% rss: 962268
17.555: [GC pause (Elastic Heap Initiated GC) (young)(Elastic Heap concurrent cycle starts due to generation limit), 0.0448495 secs]
[Parallel Time: 14.3 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 17555.0, Avg: 17562.2, Max: 17564.1, Diff: 9.1]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.5, Diff: 0.5, Sum: 0.7]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 4, Diff: 4, Sum: 4]
[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.3]
[Termination (ms): Min: 0.0, Avg: 5.7, Max: 13.2, Diff: 13.1, Sum: 475.4]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.5]
[GC Worker Total (ms): Min: 0.0, Avg: 5.7, Max: 13.8, Diff: 13.8, Sum: 477.0]
[GC Worker End (ms): Min: 17564.1, Avg: 17568.0, Max: 17568.9, Diff: 4.8]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 2.4 ms]
[Other: 28.2 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 26.9 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.0 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.1 ms]
[Eden: 8192.0K(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 8191.7K(800.0M)->1052.4K(800.0M)]
[Times: user=0.42 sys=0.01, real=0.04 secs]
[Elastic Heap concurrent thread: commit 200M spent 0.107s(concurrent workers: 21) ]
291227:
[GC.elastic_heap: in generation limit mode]
[GC.elastic_heap: young generation commit percent 100, uncommitted memory 0 B]
19.474: [GC pause (G1 Evacuation Pause) (young)(Elastic Heap concurrent cycle ends)
, 0.0522849 secs]
[Parallel Time: 5.6 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 19474.2, Avg: 19476.8, Max: 19479.0, Diff: 4.8]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, 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.0, Max: 4, Diff: 4, Sum: 4]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.3]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Termination (ms): Min: 0.0, Avg: 2.6, Max: 4.2, Diff: 4.2, Sum: 212.6]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.3]
[GC Worker Total (ms): Min: 0.0, Avg: 2.6, Max: 4.8, Diff: 4.8, Sum: 214.1]
[GC Worker End (ms): Min: 19479.0, Avg: 19479.3, Max: 19479.5, Diff: 0.5]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 4.8 ms]
[Other: 41.9 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 20.7 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 21.0 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.1 ms]
[Eden: 199.0M(199.0M)->0.0B(399.0M) Survivors: 1024.0K->1024.0K Heap: 200.0M(1000.0M)->1026.0K(1000.0M)]
[Times: user=0.27 sys=0.00, real=0.05 secs]
22.459: [GC pause (G1 Evacuation Pause) (young), 0.0569777 secs]
[Parallel Time: 8.7 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 22460.9, Avg: 22463.8, Max: 22465.5, Diff: 4.6]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.6, Diff: 0.6, Sum: 0.8]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 3, Diff: 3, Sum: 3]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 0.7]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Termination (ms): Min: 0.0, Avg: 2.6, Max: 4.5, Diff: 4.5, Sum: 215.9]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.8]
[GC Worker Total (ms): Min: 0.0, Avg: 2.6, Max: 4.7, Diff: 4.6, Sum: 218.7]
[GC Worker End (ms): Min: 22465.5, Avg: 22466.5, Max: 22467.0, Diff: 1.5]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 18.8 ms]
[Other: 29.5 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 26.5 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 2.4 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.5 ms]
[Eden: 399.0M(399.0M)->0.0B(399.0M) Survivors: 1024.0K->1024.0K Heap: 400.0M(1000.0M)->1024.0K(1000.0M)]
[Times: user=0.41 sys=0.01, real=0.06 secs]
25.327: [GC pause (G1 Evacuation Pause) (young), 0.0706889 secs]
[Parallel Time: 8.3 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 25327.7, Avg: 25331.3, Max: 25332.9, Diff: 5.2]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.6, Diff: 0.6, Sum: 0.9]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 2, Diff: 2, Sum: 2]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, 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: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Termination (ms): Min: 0.0, Avg: 3.2, Max: 5.0, Diff: 5.0, Sum: 266.0]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 1.0]
[GC Worker Total (ms): Min: 0.0, Avg: 3.2, Max: 5.2, Diff: 5.2, Sum: 269.4]
[GC Worker End (ms): Min: 25332.9, Avg: 25334.5, Max: 25335.1, Diff: 2.2]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 19.0 ms]
[Other: 43.4 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 41.6 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.3 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.4 ms]
[Eden: 399.0M(399.0M)->0.0B(399.0M) Survivors: 1024.0K->1024.0K Heap: 400.0M(1000.0M)->1024.0K(1000.0M)]
[Times: user=0.22 sys=0.01, real=0.07 secs]
100% rss: 1162372
25.825: [GC pause (Elastic Heap Initiated GC) (young), 0.0991027 secs]
[Parallel Time: 25.4 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 25825.2, Avg: 25842.2, Max: 25845.7, Diff: 20.5]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, 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.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 3, Diff: 3, Sum: 4]
[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.4]
[Termination (ms): Min: 0.0, Avg: 7.1, Max: 24.7, Diff: 24.7, Sum: 585.3]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 2, Diff: 1, Sum: 84]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.4]
[GC Worker Total (ms): Min: 0.0, Avg: 7.1, Max: 25.1, Diff: 25.1, Sum: 587.1]
[GC Worker End (ms): Min: 25845.7, Avg: 25849.3, Max: 25850.5, Diff: 4.8]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 11.9 ms]
[Other: 61.8 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 56.0 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 5.5 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 6144.0K(399.0M)->0.0B(399.0M) Survivors: 1024.0K->1024.0K Heap: 6658.0K(1000.0M)->1054.1K(1000.0M)]
[Times: user=0.29 sys=0.00, real=0.10 secs]
291227:
[GC.elastic_heap: inactive]
28.905: [GC pause (G1 Evacuation Pause) (young), 0.0428310 secs]
[Parallel Time: 9.3 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 28905.3, Avg: 28907.7, Max: 28908.9, Diff: 3.6]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.5, Diff: 0.5, Sum: 0.6]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.1, Max: 5, Diff: 5, Sum: 5]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.7]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Termination (ms): Min: 0.0, Avg: 3.2, Max: 7.6, Diff: 7.6, Sum: 268.6]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.4]
[GC Worker Total (ms): Min: 0.0, Avg: 3.3, Max: 7.6, Diff: 7.6, Sum: 270.8]
[GC Worker End (ms): Min: 28908.9, Avg: 28910.9, Max: 28914.1, Diff: 5.2]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 3.1 ms]
[Other: 30.4 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 29.0 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.0 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 399.0M(399.0M)->0.0B(399.0M) Survivors: 1024.0K->1024.0K Heap: 400.0M(1000.0M)->1026.0K(1000.0M)]
[Times: user=0.25 sys=0.00, real=0.04 secs]
32.097: [GC pause (G1 Evacuation Pause) (young), 0.0269183 secs]
[Parallel Time: 1.8 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 32097.0, Avg: 32097.6, Max: 32098.0, Diff: 1.0]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 1.5]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 3]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.8]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.0, Sum: 0.4]
[Termination (ms): Min: 0.0, Avg: 0.6, Max: 0.7, Diff: 0.7, Sum: 50.4]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.4]
[GC Worker Total (ms): Min: 0.0, Avg: 0.6, Max: 1.0, Diff: 1.0, Sum: 53.7]
[GC Worker End (ms): Min: 32098.0, Avg: 32098.3, Max: 32098.5, Diff: 0.5]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.6 ms]
[Other: 23.5 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 22.2 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 0.8 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.4 ms]
[Eden: 399.0M(399.0M)->0.0B(399.0M) Survivors: 1024.0K->1024.0K Heap: 400.0M(1000.0M)->1028.8K(1000.0M)]
[Times: user=0.25 sys=0.00, real=0.03 secs]
Recover rss: 1162372
34.257: [GC pause (Elastic Heap Initiated GC) (young)(Elastic Heap concurrent cycle starts due to generation limit), 0.1124606 secs]
[Parallel Time: 9.3 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 34256.7, Avg: 34259.9, Max: 34261.3, Diff: 4.6]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.5, Diff: 0.5, Sum: 0.7]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.1, Max: 6, Diff: 6, Sum: 6]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Termination (ms): Min: 0.0, Avg: 2.2, Max: 4.7, Diff: 4.7, Sum: 182.0]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.4]
[GC Worker Total (ms): Min: 0.0, Avg: 2.2, Max: 4.7, Diff: 4.7, Sum: 184.0]
[GC Worker End (ms): Min: 34261.3, Avg: 34262.1, Max: 34262.5, Diff: 1.2]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 31.1 ms]
[Other: 72.0 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 54.2 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 17.3 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 205.0M(399.0M)->0.0B(119.0M) Survivors: 1024.0K->1024.0K Heap: 205.0M(1000.0M)->1052.7K(720.0M)]
[Times: user=0.78 sys=0.01, real=0.11 secs]
[Elastic Heap concurrent thread: uncommit 280M spent 0.030s(concurrent workers: 21) ]
291227:
[GC.elastic_heap: in generation limit mode]
[GC.elastic_heap: young generation commit percent 30, uncommitted memory 293601280 B]
35.279: [GC pause (G1 Evacuation Pause) (young)(Elastic Heap concurrent cycle ends)
, 0.0583763 secs]
[Parallel Time: 11.6 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 35279.6, Avg: 35281.2, Max: 35281.9, Diff: 2.4]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.3, Diff: 0.3, Sum: 1.3]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 2, Diff: 2, Sum: 4]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.3]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Termination (ms): Min: 0.0, Avg: 6.0, Max: 9.7, Diff: 9.7, Sum: 495.7]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 2, Diff: 1, Sum: 84]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.5]
[GC Worker Total (ms): Min: 0.0, Avg: 6.0, Max: 9.7, Diff: 9.7, Sum: 498.3]
[GC Worker End (ms): Min: 35281.9, Avg: 35287.2, Max: 35290.5, Diff: 8.5]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.6 ms]
[Other: 45.2 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 44.1 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 0.8 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.1 ms]
[Eden: 119.0M(119.0M)->0.0B(119.0M) Survivors: 1024.0K->1024.0K Heap: 120.0M(720.0M)->1030.4K(720.0M)]
[Times: user=0.51 sys=0.01, real=0.06 secs]
36.227: [GC pause (G1 Evacuation Pause) (young), 0.0746173 secs]
[Parallel Time: 22.1 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 36227.0, Avg: 36230.0, Max: 36231.7, Diff: 4.8]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, 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.1]
[Processed Buffers: Min: 0, Avg: 0.1, Max: 2, Diff: 2, Sum: 6]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.3]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Termination (ms): Min: 0.0, Avg: 9.7, Max: 13.6, Diff: 13.6, Sum: 802.4]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.5]
[GC Worker Total (ms): Min: 0.0, Avg: 9.7, Max: 13.6, Diff: 13.6, Sum: 804.8]
[GC Worker End (ms): Min: 36231.8, Avg: 36239.7, Max: 36242.9, Diff: 11.1]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 13.4 ms]
[Other: 39.2 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 36.4 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 2.5 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.1 ms]
[Eden: 119.0M(119.0M)->0.0B(119.0M) Survivors: 1024.0K->1024.0K Heap: 120.0M(720.0M)->1032.6K(720.0M)]
[Times: user=0.33 sys=0.01, real=0.08 secs]
37.143: [GC pause (G1 Evacuation Pause) (young), 0.0641168 secs]
[Parallel Time: 1.7 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 37143.5, Avg: 37144.2, Max: 37144.6, Diff: 1.1]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 1.8]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Processed Buffers: Min: 0, Avg: 0.1, Max: 1, Diff: 1, Sum: 5]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.3]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Termination (ms): Min: 0.0, Avg: 0.7, Max: 0.9, Diff: 0.9, Sum: 58.4]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.5]
[GC Worker Total (ms): Min: 0.0, Avg: 0.7, Max: 1.2, Diff: 1.2, Sum: 61.7]
[GC Worker End (ms): Min: 37144.6, Avg: 37144.9, Max: 37145.2, Diff: 0.5]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 11.4 ms]
[Other: 51.1 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 45.6 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 5.1 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 119.0M(119.0M)->0.0B(119.0M) Survivors: 1024.0K->1024.0K Heap: 120.0M(720.0M)->1057.1K(720.0M)]
[Times: user=0.32 sys=0.00, real=0.06 secs]
38.001: [GC pause (G1 Evacuation Pause) (young), 0.0411945 secs]
[Parallel Time: 3.2 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 38001.3, Avg: 38002.1, Max: 38002.6, Diff: 1.3]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 1.7]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2]
[Processed Buffers: Min: 0, Avg: 0.1, Max: 2, Diff: 2, Sum: 5]
[Scan RS (ms): Min: 0.0, Avg
...
Output overflow:
JT Harness has limited the test output to the text
at the beginning and the end, so that you can see how the
test began, and how it completed.
If you need to see more of the output from the test,
set the system property javatest.maxOutputSize to a higher
value. The current value is 100000
...
0.1]
[Processed Buffers: Min: 0, Avg: 0.1, Max: 5, Diff: 5, Sum: 7]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Termination (ms): Min: 0.0, Avg: 1.1, Max: 1.6, Diff: 1.6, Sum: 90.0]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 2, Diff: 1, Sum: 85]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.4]
[GC Worker Total (ms): Min: 0.0, Avg: 1.1, Max: 2.0, Diff: 2.0, Sum: 92.3]
[GC Worker End (ms): Min: 74268.1, Avg: 74268.3, Max: 74268.5, Diff: 0.4]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 0.6 ms]
[Other: 27.8 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 27.1 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 0.6 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.1 ms]
[Eden: 6144.0K(399.0M)->0.0B(399.0M) Survivors: 1024.0K->1024.0K Heap: 6164.3K(1000.0M)->1060.3K(1000.0M)]
[Times: user=0.28 sys=0.00, real=0.03 secs]
291227:
[GC.elastic_heap: inactive]
76.607: [GC pause (G1 Evacuation Pause) (young), 0.0245317 secs]
[Parallel Time: 2.5 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 76607.0, Avg: 76607.7, Max: 76608.4, Diff: 1.4]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 1.6]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.1, Max: 1, Diff: 1, Sum: 5]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, 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: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.5]
[Termination (ms): Min: 0.1, Avg: 1.0, Max: 1.8, Diff: 1.7, Sum: 81.9]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.8]
[GC Worker Total (ms): Min: 0.1, Avg: 1.0, Max: 2.0, Diff: 1.9, Sum: 86.0]
[GC Worker End (ms): Min: 76608.5, Avg: 76608.8, Max: 76609.0, Diff: 0.6]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.5 ms]
[Other: 20.5 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 18.4 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.7 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 399.0M(399.0M)->0.0B(399.0M) Survivors: 1024.0K->1024.0K Heap: 400.0M(1000.0M)->1044.3K(1000.0M)]
[Times: user=0.36 sys=0.00, real=0.02 secs]
79.201: [GC pause (G1 Evacuation Pause) (young), 0.0287199 secs]
[Parallel Time: 4.4 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 79200.8, Avg: 79202.1, Max: 79202.9, Diff: 2.0]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 3.2, Diff: 3.2, Sum: 10.7]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.5, Diff: 0.5, Sum: 0.9]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 4]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, 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: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, Sum: 3.1]
[Termination (ms): Min: 0.1, Avg: 2.1, Max: 3.0, Diff: 2.9, Sum: 172.3]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 2, Diff: 1, Sum: 86]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 1.2]
[GC Worker Total (ms): Min: 1.2, Avg: 2.3, Max: 3.9, Diff: 2.7, Sum: 190.5]
[GC Worker End (ms): Min: 79204.1, Avg: 79204.4, Max: 79204.8, Diff: 0.7]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 0.9 ms]
[Other: 23.4 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 20.5 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 2.2 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.5 ms]
[Eden: 399.0M(399.0M)->0.0B(399.0M) Survivors: 1024.0K->1024.0K Heap: 400.0M(1000.0M)->1056.4K(1000.0M)]
[Times: user=0.43 sys=0.01, real=0.03 secs]
Recover rss: 1163520
80.686: [GC pause (Elastic Heap Initiated GC) (young)(Elastic Heap concurrent cycle starts due to generation limit), 0.0463703 secs]
[Parallel Time: 4.1 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 80686.3, Avg: 80688.5, Max: 80689.3, Diff: 3.0]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.6, Diff: 0.6, Sum: 1.4]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.1, Max: 7, Diff: 7, Sum: 7]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.5]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Termination (ms): Min: 0.0, Avg: 1.2, Max: 2.4, Diff: 2.4, Sum: 97.3]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.6]
[GC Worker Total (ms): Min: 0.0, Avg: 1.2, Max: 3.2, Diff: 3.2, Sum: 100.3]
[GC Worker End (ms): Min: 80689.4, Avg: 80689.7, Max: 80690.0, Diff: 0.7]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 0.9 ms]
[Other: 41.3 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 36.5 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 4.0 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.6 ms]
[Eden: 205.0M(399.0M)->0.0B(119.0M) Survivors: 1024.0K->1024.0K Heap: 205.0M(1000.0M)->1060.6K(720.0M)]
[Times: user=0.57 sys=0.01, real=0.05 secs]
[Elastic Heap concurrent thread: uncommit 280M spent 0.043s(concurrent workers: 21) ]
291227:
[GC.elastic_heap: in generation limit mode]
[GC.elastic_heap: young generation commit percent 30, uncommitted memory 293601280 B]
81.444: [GC pause (G1 Evacuation Pause) (young)(Elastic Heap concurrent cycle ends)
, 0.0461090 secs]
[Parallel Time: 2.4 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 81443.7, Avg: 81444.7, Max: 81445.2, Diff: 1.4]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, Sum: 2.4]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.2]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 4]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 0.6]
[Termination (ms): Min: 0.0, Avg: 0.9, Max: 1.1, Diff: 1.0, Sum: 72.5]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.6]
[GC Worker Total (ms): Min: 0.1, Avg: 0.9, Max: 1.5, Diff: 1.5, Sum: 76.7]
[GC Worker End (ms): Min: 81445.2, Avg: 81445.6, Max: 81445.9, Diff: 0.7]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 6.3 ms]
[Other: 37.4 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 22.5 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 14.4 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 119.0M(119.0M)->0.0B(119.0M) Survivors: 1024.0K->1024.0K Heap: 120.0M(720.0M)->1040.3K(720.0M)]
[Times: user=0.26 sys=0.01, real=0.04 secs]
82.424: [GC pause (G1 Evacuation Pause) (young), 0.0485663 secs]
[Parallel Time: 12.9 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 82424.3, Avg: 82425.1, Max: 82425.6, Diff: 1.3]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.3, Diff: 0.3, Sum: 2.0]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 3]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.3]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 0.8]
[Termination (ms): Min: 0.0, Avg: 1.0, Max: 12.0, Diff: 12.0, Sum: 82.0]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.6]
[GC Worker Total (ms): Min: 0.0, Avg: 1.0, Max: 12.2, Diff: 12.2, Sum: 85.8]
[GC Worker End (ms): Min: 82425.7, Avg: 82426.1, Max: 82436.7, Diff: 11.0]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.2 ms]
[Other: 34.5 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 32.7 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.3 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 119.0M(119.0M)->0.0B(119.0M) Survivors: 1024.0K->1024.0K Heap: 120.0M(720.0M)->1050.8K(720.0M)]
[Times: user=0.43 sys=0.00, real=0.05 secs]
83.199: [GC pause (G1 Evacuation Pause) (young), 0.0318344 secs]
[Parallel Time: 3.6 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 83199.2, Avg: 83200.1, Max: 83200.8, Diff: 1.7]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, Sum: 3.2]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 3]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.8]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.8]
[Termination (ms): Min: 0.4, Avg: 1.3, Max: 2.3, Diff: 1.9, Sum: 110.0]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.9]
[GC Worker Total (ms): Min: 0.4, Avg: 1.4, Max: 2.7, Diff: 2.3, Sum: 116.0]
[GC Worker End (ms): Min: 83201.1, Avg: 83201.5, Max: 83201.9, Diff: 0.8]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.9 ms]
[Other: 26.3 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 23.6 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.1 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 1.4 ms]
[Eden: 119.0M(119.0M)->0.0B(119.0M) Survivors: 1024.0K->1024.0K Heap: 120.0M(720.0M)->1054.8K(720.0M)]
[Times: user=0.52 sys=0.03, real=0.03 secs]
83.955: [GC pause (G1 Evacuation Pause) (young), 0.0375045 secs]
[Parallel Time: 8.7 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 83955.6, Avg: 83957.9, Max: 83958.7, Diff: 3.1]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.2, Max: 1.6, Diff: 1.6, Sum: 17.9]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 2]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.3]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.5]
[Termination (ms): Min: 0.8, Avg: 4.1, Max: 6.7, Diff: 6.0, Sum: 343.9]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.6]
[GC Worker Total (ms): Min: 0.8, Avg: 4.4, Max: 8.3, Diff: 7.5, Sum: 363.3]
[GC Worker End (ms): Min: 83959.2, Avg: 83962.2, Max: 83964.1, Diff: 4.9]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.0 ms]
[Other: 27.8 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 26.5 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 0.8 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 119.0M(119.0M)->0.0B(119.0M) Survivors: 1024.0K->1024.0K Heap: 120.0M(720.0M)->1052.8K(720.0M)]
[Times: user=0.28 sys=0.02, real=0.04 secs]
84.758: [GC pause (G1 Evacuation Pause) (young), 0.0322396 secs]
[Parallel Time: 4.0 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 84758.2, Avg: 84759.4, Max: 84760.1, Diff: 1.9]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.5, Diff: 0.5, Sum: 2.6]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 2]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 0.8]
[Termination (ms): Min: 0.0, Avg: 1.6, Max: 2.5, Diff: 2.4, Sum: 130.5]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.7]
[GC Worker Total (ms): Min: 0.1, Avg: 1.6, Max: 3.0, Diff: 2.9, Sum: 135.1]
[GC Worker End (ms): Min: 84760.2, Avg: 84761.0, Max: 84761.4, Diff: 1.2]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.2 ms]
[Other: 27.0 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 23.3 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 2.0 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.1 ms]
[Eden: 119.0M(119.0M)->0.0B(119.0M) Survivors: 1024.0K->1024.0K Heap: 120.0M(720.0M)->1046.8K(720.0M)]
[Times: user=0.53 sys=0.03, real=0.03 secs]
85.532: [GC pause (G1 Evacuation Pause) (young), 0.0510231 secs]
[Parallel Time: 21.0 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 85531.6, Avg: 85539.0, Max: 85545.2, Diff: 13.6]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 2.2, Diff: 2.2, Sum: 12.4]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 2]
[Scan RS (ms): Min: 0.0, Avg: 0.3, Max: 2.5, Diff: 2.5, Sum: 25.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: 0.0, Max: 0.2, Diff: 0.2, Sum: 0.9]
[Termination (ms): Min: 0.9, Avg: 11.0, Max: 19.5, Diff: 18.6, Sum: 915.8]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 2, Diff: 1, Sum: 84]
[GC Worker Other (ms): Min: 0.0, Avg: 0.1, Max: 0.5, Diff: 0.5, Sum: 5.5]
[GC Worker Total (ms): Min: 0.9, Avg: 11.6, Max: 20.3, Diff: 19.4, Sum: 960.1]
[GC Worker End (ms): Min: 85546.0, Avg: 85550.6, Max: 85552.1, Diff: 6.1]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.2 ms]
[Other: 28.8 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 27.4 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 0.9 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.1 ms]
[Eden: 119.0M(119.0M)->0.0B(119.0M) Survivors: 1024.0K->1024.0K Heap: 120.0M(720.0M)->1040.7K(720.0M)]
[Times: user=0.40 sys=0.03, real=0.06 secs]
86.263: [GC pause (G1 Evacuation Pause) (young), 0.0331095 secs]
[Parallel Time: 5.6 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 86263.1, Avg: 86265.9, Max: 86267.0, Diff: 4.0]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 1.1, Diff: 1.1, Sum: 7.4]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 2]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.5]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 1.1]
[Termination (ms): Min: 0.0, Avg: 1.5, Max: 3.3, Diff: 3.3, Sum: 121.2]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 1.2]
[GC Worker Total (ms): Min: 0.0, Avg: 1.6, Max: 4.5, Diff: 4.5, Sum: 131.6]
[GC Worker End (ms): Min: 86267.1, Avg: 86267.5, Max: 86267.9, Diff: 0.8]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.1 ms]
[Other: 26.4 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 24.7 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.0 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.4 ms]
[Eden: 119.0M(119.0M)->0.0B(119.0M) Survivors: 1024.0K->1024.0K Heap: 120.0M(720.0M)->1052.8K(720.0M)]
[Times: user=0.45 sys=0.02, real=0.03 secs]
86.981: [GC pause (G1 Evacuation Pause) (young), 0.0395442 secs]
[Parallel Time: 2.4 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 86981.5, Avg: 86982.3, Max: 86982.8, Diff: 1.3]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.4, Diff: 0.4, Sum: 4.4]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 2]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.7]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 1.6]
[Termination (ms): Min: 0.0, Avg: 0.9, Max: 1.0, Diff: 0.9, Sum: 73.2]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 3, Diff: 2, Sum: 86]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.9]
[GC Worker Total (ms): Min: 0.0, Avg: 1.0, Max: 1.4, Diff: 1.4, Sum: 80.9]
[GC Worker End (ms): Min: 86982.8, Avg: 86983.3, Max: 86983.6, Diff: 0.8]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.3 ms]
[Other: 35.9 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 14.5 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 20.9 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 119.0M(119.0M)->0.0B(119.0M) Survivors: 1024.0K->1024.0K Heap: 120.0M(720.0M)->1069.2K(720.0M)]
[Times: user=0.44 sys=0.01, real=0.04 secs]
87.310: [Full GC (System.gc()) 52245K->819K(720M), 0.0285632 secs]
[Eden: 51200.0K(119.0M)->0.0B(120.0M) Survivors: 1024.0K->0.0B Heap: 52245.3K(720.0M)->819.6K(720.0M)], [Metaspace: 5030K->5030K(1056768K)]
[Times: user=0.01 sys=0.04, real=0.03 secs]
rss30: 882376
87.719: [GC pause (G1 Evacuation Pause) (young), 0.0353093 secs]
[Parallel Time: 3.7 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 87718.7, Avg: 87720.4, Max: 87721.2, Diff: 2.4]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, Sum: 3.8]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.2]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 2]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.7]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.6]
[Termination (ms): Min: 0.0, Avg: 1.0, Max: 2.1, Diff: 2.1, Sum: 85.9]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 2, Diff: 1, Sum: 84]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.8]
[GC Worker Total (ms): Min: 0.0, Avg: 1.1, Max: 2.5, Diff: 2.5, Sum: 92.1]
[GC Worker End (ms): Min: 87721.2, Avg: 87721.5, Max: 87721.8, Diff: 0.6]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 11.0 ms]
[Other: 20.6 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 18.4 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.7 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 120.0M(120.0M)->0.0B(119.0M) Survivors: 0.0B->1024.0K Heap: 120.8M(720.0M)->1061.8K(720.0M)]
[Times: user=0.43 sys=0.01, real=0.04 secs]
88.110: [GC pause (G1 Evacuation Pause) (young), 0.1014126 secs]
[Parallel Time: 9.2 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 88109.8, Avg: 88111.5, Max: 88113.7, Diff: 3.9]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, Sum: 3.3]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.2]
[Processed Buffers: Min: 0, Avg: 0.1, Max: 1, Diff: 1, Sum: 5]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.6]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.5]
[Termination (ms): Min: 0.0, Avg: 6.5, Max: 8.5, Diff: 8.5, Sum: 537.3]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 2, Diff: 1, Sum: 84]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 1.1]
[GC Worker Total (ms): Min: 0.0, Avg: 6.5, Max: 8.9, Diff: 8.9, Sum: 543.1]
[GC Worker End (ms): Min: 88113.8, Avg: 88118.1, Max: 88118.9, Diff: 5.1]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 33.4 ms]
[Other: 58.8 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 52.0 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 6.3 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 119.0M(119.0M)->0.0B(119.0M) Survivors: 1024.0K->1024.0K Heap: 120.0M(720.0M)->1051.7K(720.0M)]
[Times: user=0.60 sys=0.01, real=0.10 secs]
88.601: [GC pause (G1 Evacuation Pause) (young), 0.0373358 secs]
[Parallel Time: 5.9 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 88600.8, Avg: 88602.2, Max: 88602.9, Diff: 2.1]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 1.0, Diff: 1.0, Sum: 2.4]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 3, Diff: 3, Sum: 3]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 0.7]
[Termination (ms): Min: 0.3, Avg: 1.8, Max: 3.0, Diff: 2.6, Sum: 146.6]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.0, Sum: 1.0]
[GC Worker Total (ms): Min: 0.4, Avg: 1.8, Max: 3.9, Diff: 3.5, Sum: 151.3]
[GC Worker End (ms): Min: 88603.1, Avg: 88604.0, Max: 88604.7, Diff: 1.5]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.2 ms]
[Other: 30.3 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 28.7 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.1 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 119.0M(119.0M)->0.0B(119.0M) Survivors: 1024.0K->1024.0K Heap: 120.0M(720.0M)->1045.7K(720.0M)]
[Times: user=0.86 sys=0.01, real=0.04 secs]
89.009: [GC pause (G1 Evacuation Pause) (young), 0.0222489 secs]
[Parallel Time: 2.0 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 89008.7, Avg: 89009.4, Max: 89009.8, Diff: 1.1]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.4, Diff: 0.4, Sum: 4.3]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.6]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 2]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.6]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.6]
[Termination (ms): Min: 0.0, Avg: 0.8, Max: 0.9, Diff: 0.9, Sum: 66.4]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.8]
[GC Worker Total (ms): Min: 0.0, Avg: 0.9, Max: 1.3, Diff: 1.3, Sum: 73.4]
[GC Worker End (ms): Min: 89009.9, Avg: 89010.2, Max: 89010.6, Diff: 0.7]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 0.9 ms]
[Other: 19.3 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 18.0 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.1 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.1 ms]
[Eden: 119.0M(119.0M)->0.0B(119.0M) Survivors: 1024.0K->1024.0K Heap: 120.0M(720.0M)->1049.7K(720.0M)]
[Times: user=0.42 sys=0.01, real=0.02 secs]
89.456: [GC pause (G1 Evacuation Pause) (young), 0.0199146 secs]
[Parallel Time: 2.6 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 89456.5, Avg: 89457.5, Max: 89458.3, Diff: 1.8]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.3, Diff: 0.3, Sum: 3.6]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.3]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 3]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.5]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.5]
[Termination (ms): Min: 0.0, Avg: 1.1, Max: 2.0, Diff: 2.0, Sum: 90.9]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 2, Diff: 1, Sum: 84]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.9]
[GC Worker Total (ms): Min: 0.0, Avg: 1.2, Max: 2.3, Diff: 2.3, Sum: 96.6]
[GC Worker End (ms): Min: 89458.3, Avg: 89458.7, Max: 89459.1, Diff: 0.7]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.0 ms]
[Other: 16.3 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 14.7 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.3 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 119.0M(119.0M)->0.0B(119.0M) Survivors: 1024.0K->1024.0K Heap: 120.0M(720.0M)->1055.8K(720.0M)]
[Times: user=0.35 sys=0.00, real=0.02 secs]
89.816: [GC pause (G1 Evacuation Pause) (young), 0.0321549 secs]
[Parallel Time: 9.7 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 89815.8, Avg: 89821.0, Max: 89824.1, Diff: 8.3]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.5, Diff: 0.5, Sum: 3.0]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, Sum: 0.6]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 3]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.5]
[Termination (ms): Min: 0.0, Avg: 3.5, Max: 8.4, Diff: 8.4, Sum: 287.3]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 2, Diff: 1, Sum: 85]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.6]
[GC Worker Total (ms): Min: 0.0, Avg: 3.5, Max: 8.9, Diff: 8.8, Sum: 292.5]
[GC Worker End (ms): Min: 89824.2, Avg: 89824.6, Max: 89824.8, Diff: 0.6]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 0.7 ms]
[Other: 21.8 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 20.6 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 0.8 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 119.0M(119.0M)->0.0B(119.0M) Survivors: 1024.0K->1024.0K Heap: 120.0M(720.0M)->1051.7K(720.0M)]
[Times: user=0.41 sys=0.01, real=0.03 secs]
90.213: [GC pause (G1 Evacuation Pause) (young), 0.0866483 secs]
[Parallel Time: 20.9 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 90213.1, Avg: 90219.9, Max: 90226.8, Diff: 13.7]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, Sum: 2.2]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 3]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.0, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Termination (ms): Min: 0.0, Avg: 8.1, Max: 14.4, Diff: 14.4, Sum: 671.5]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.6]
[GC Worker Total (ms): Min: 0.0, Avg: 8.1, Max: 14.9, Diff: 14.9, Sum: 675.1]
[GC Worker End (ms): Min: 90226.8, Avg: 90228.0, Max: 90228.3, Diff: 1.5]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.8 ms]
[Other: 63.9 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 62.2 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.3 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 119.0M(119.0M)->0.0B(119.0M) Survivors: 1024.0K->1024.0K Heap: 120.0M(720.0M)->1053.8K(720.0M)]
[Times: user=1.09 sys=0.01, real=0.08 secs]
90.633: [GC pause (G1 Evacuation Pause) (young), 0.0309298 secs]
[Parallel Time: 5.6 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 90633.5, Avg: 90634.9, Max: 90637.0, Diff: 3.5]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, Sum: 2.6]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 3]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 0.6]
[Termination (ms): Min: 0.0, Avg: 2.6, Max: 3.5, Diff: 3.5, Sum: 218.3]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 2, Diff: 1, Sum: 85]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.8]
[GC Worker Total (ms): Min: 0.0, Avg: 2.7, Max: 3.9, Diff: 3.9, Sum: 222.7]
[GC Worker End (ms): Min: 90637.1, Avg: 90637.6, Max: 90638.6, Diff: 1.5]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 2.7 ms]
[Other: 22.6 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 19.3 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 2.7 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 119.0M(119.0M)->0.0B(119.0M) Survivors: 1024.0K->1024.0K Heap: 120.0M(720.0M)->1051.7K(720.0M)]
[Times: user=0.28 sys=0.01, real=0.03 secs]
91.055: [GC pause (G1 Evacuation Pause) (young), 0.0281351 secs]
[Parallel Time: 9.7 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 91055.7, Avg: 91063.7, Max: 91064.5, Diff: 8.8]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.6, Diff: 0.6, Sum: 1.3]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 3, Diff: 3, Sum: 3]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Termination (ms): Min: 0.0, Avg: 1.1, Max: 8.6, Diff: 8.5, Sum: 90.1]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 2, Diff: 1, Sum: 84]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.5]
[GC Worker Total (ms): Min: 0.0, Avg: 1.1, Max: 9.3, Diff: 9.3, Sum: 92.6]
[GC Worker End (ms): Min: 91064.5, Avg: 91064.8, Max: 91065.2, Diff: 0.7]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 0.9 ms]
[Other: 17.5 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 16.1 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.1 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 119.0M(119.0M)->0.0B(119.0M) Survivors: 1024.0K->1024.0K Heap: 120.0M(720.0M)->1045.7K(720.0M)]
[Times: user=0.40 sys=0.00, real=0.03 secs]
91.570: [GC pause (G1 Evacuation Pause) (young), 0.0262286 secs]
[Parallel Time: 6.1 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 91570.2, Avg: 91573.2, Max: 91575.2, Diff: 4.9]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, Sum: 2.6]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 3, Diff: 3, Sum: 3]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.0, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 0.5]
[Termination (ms): Min: 0.0, Avg: 2.2, Max: 5.0, Diff: 5.0, Sum: 186.5]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.6]
[GC Worker Total (ms): Min: 0.0, Avg: 2.3, Max: 5.4, Diff: 5.4, Sum: 190.6]
[GC Worker End (ms): Min: 91575.2, Avg: 91575.5, Max: 91575.8, Diff: 0.6]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.4 ms]
[Other: 18.7 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 16.6 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.8 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 119.0M(119.0M)->0.0B(119.0M) Survivors: 1024.0K->1024.0K Heap: 120.0M(720.0M)->1051.7K(720.0M)]
[Times: user=0.50 sys=0.01, real=0.03 secs]
91.954: [GC pause (G1 Evacuation Pause) (young), 0.0361610 secs]
[Parallel Time: 3.6 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 91954.5, Avg: 91955.4, Max: 91956.5, Diff: 2.0]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.5, Diff: 0.5, Sum: 4.2]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 3]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.5]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.0, Sum: 0.4]
[Termination (ms): Min: 0.0, Avg: 2.3, Max: 3.0, Diff: 3.0, Sum: 188.0]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.6]
[GC Worker Total (ms): Min: 0.1, Avg: 2.3, Max: 3.4, Diff: 3.3, Sum: 194.1]
[GC Worker End (ms): Min: 91956.6, Avg: 91957.8, Max: 91958.0, Diff: 1.4]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 3.3 ms]
[Other: 29.3 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 26.0 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 2.0 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 1.1 ms]
[Eden: 119.0M(119.0M)->0.0B(119.0M) Survivors: 1024.0K->1024.0K Heap: 120.0M(720.0M)->1051.7K(720.0M)]
[Times: user=0.43 sys=0.00, real=0.03 secs]
92.399: [GC pause (G1 Evacuation Pause) (young), 0.0645377 secs]
[Parallel Time: 15.9 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 92399.1, Avg: 92403.9, Max: 92406.1, Diff: 7.0]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.9, Diff: 0.9, Sum: 1.2]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 3, Diff: 3, Sum: 3]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.0, Sum: 0.3]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.3]
[Termination (ms): Min: 0.0, Avg: 10.2, Max: 11.9, Diff: 11.9, Sum: 845.4]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 1.3]
[GC Worker Total (ms): Min: 0.1, Avg: 10.2, Max: 12.0, Diff: 12.0, Sum: 848.7]
[GC Worker End (ms): Min: 92406.2, Avg: 92414.1, Max: 92414.8, Diff: 8.6]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 9.0 ms]
[Other: 39.7 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 31.3 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 8.0 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 119.0M(119.0M)->0.0B(119.0M) Survivors: 1024.0K->1024.0K Heap: 120.0M(720.0M)->845.7K(720.0M)]
[Times: user=0.57 sys=0.01, real=0.07 secs]
92.829: [GC pause (G1 Evacuation Pause) (young), 0.0372966 secs]
[Parallel Time: 3.5 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 92829.3, Avg: 92830.7, Max: 92832.2, Diff: 2.8]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.8, Diff: 0.8, Sum: 1.3]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 2]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.3]
[Termination (ms): Min: 0.0, Avg: 1.7, Max: 2.2, Diff: 2.2, Sum: 143.0]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.5]
[GC Worker Total (ms): Min: 0.0, Avg: 1.8, Max: 2.9, Diff: 2.8, Sum: 145.5]
[GC Worker End (ms): Min: 92832.2, Avg: 92832.5, Max: 92832.7, Diff: 0.5]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.2 ms]
[Other: 32.6 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 29.2 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.6 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.1 ms]
[Eden: 119.0M(119.0M)->0.0B(119.0M) Survivors: 1024.0K->1024.0K Heap: 119.8M(720.0M)->847.7K(720.0M)]
[Times: user=0.35 sys=0.01, real=0.04 secs]
93.225: [GC pause (G1 Evacuation Pause) (young), 0.1357295 secs]
[Parallel Time: 46.0 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 93224.9, Avg: 93235.8, Max: 93247.8, Diff: 22.9]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, Sum: 2.2]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 3]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.4]
[Termination (ms): Min: 0.0, Avg: 30.1, Max: 42.4, Diff: 42.3, Sum: 2495.9]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 2, Diff: 1, Sum: 84]
[GC Worker Other (ms): Min: 0.0, Avg: 0.4, Max: 13.0, Diff: 13.0, Sum: 32.6]
[GC Worker Total (ms): Min: 11.0, Avg: 30.5, Max: 42.9, Diff: 31.9, Sum: 2531.6]
[GC Worker End (ms): Min: 93257.7, Avg: 93266.3, Max: 93269.8, Diff: 12.0]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 28.3 ms]
[Other: 61.4 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 58.1 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 3.1 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.1 ms]
[Eden: 119.0M(119.0M)->0.0B(119.0M) Survivors: 1024.0K->1024.0K Heap: 119.8M(720.0M)->851.7K(720.0M)]
[Times: user=0.50 sys=0.01, real=0.13 secs]
93.704: [GC pause (G1 Evacuation Pause) (young), 0.1208236 secs]
[Parallel Time: 8.8 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 93703.7, Avg: 93704.5, Max: 93705.2, Diff: 1.5]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, Sum: 3.6]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 3]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.6]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.4]
[Termination (ms): Min: 0.2, Avg: 3.8, Max: 7.6, Diff: 7.5, Sum: 317.2]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 2, Diff: 1, Sum: 84]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.5]
[GC Worker Total (ms): Min: 0.2, Avg: 3.9, Max: 8.0, Diff: 7.8, Sum: 322.4]
[GC Worker End (ms): Min: 93705.4, Avg: 93708.4, Max: 93711.8, Diff: 6.4]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 8.6 ms]
[Other: 103.4 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 83.5 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 19.7 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.1 ms]
[Eden: 119.0M(119.0M)->0.0B(119.0M) Survivors: 1024.0K->1024.0K Heap: 119.8M(720.0M)->851.7K(720.0M)]
[Times: user=0.44 sys=0.01, real=0.12 secs]
94.715: [GC pause (G1 Evacuation Pause) (young), 0.1532733 secs]
[Parallel Time: 33.5 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 94714.9, Avg: 94723.9, Max: 94737.1, Diff: 22.2]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.8, Diff: 0.8, 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.0, Max: 3, Diff: 3, Sum: 3]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Termination (ms): Min: 0.3, Avg: 20.7, Max: 28.4, Diff: 28.1, Sum: 1719.7]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.5]
[GC Worker Total (ms): Min: 0.3, Avg: 20.8, Max: 28.4, Diff: 28.1, Sum: 1722.3]
[GC Worker End (ms): Min: 94737.2, Avg: 94744.7, Max: 94745.3, Diff: 8.1]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 24.3 ms]
[Other: 95.4 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 67.9 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 27.1 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 119.0M(119.0M)->0.0B(120.0M) Survivors: 1024.0K->0.0B Heap: 119.8M(720.0M)->846.1K(720.0M)]
[Times: user=0.53 sys=0.01, real=0.15 secs]
95.689: [GC pause (Elastic Heap Initiated GC) (young)(Elastic Heap concurrent cycle starts due to generation limit), 0.1589744 secs]
[Parallel Time: 60.3 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 95688.9, Avg: 95697.6, Max: 95702.7, Diff: 13.8]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 6.4, Diff: 6.4, Sum: 8.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.0, Max: 4, Diff: 4, Sum: 4]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.3]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Termination (ms): Min: 0.0, Avg: 28.2, Max: 56.3, Diff: 56.3, Sum: 2343.3]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.5]
[GC Worker Total (ms): Min: 0.0, Avg: 28.3, Max: 56.3, Diff: 56.3, Sum: 2352.6]
[GC Worker End (ms): Min: 95702.7, Avg: 95726.0, Max: 95748.0, Diff: 45.3]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 16.0 ms]
[Other: 82.7 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 66.8 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 13.7 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.1 ms]
[Eden: 101376.0K(120.0M)->0.0B(80896.0K) Survivors: 0.0B->1024.0K Heap: 102222.1K(720.0M)->876.2K(680.0M)]
[Times: user=0.16 sys=0.01, real=0.16 secs]
[Elastic Heap concurrent thread: uncommit 40960K spent 0.002s(concurrent workers: 21) ]
291227:
[GC.elastic_heap: in generation limit mode]
[GC.elastic_heap: young generation commit percent 20, uncommitted memory 335544320 B]
Heap
garbage-first heap total 696320K, used 876K [0x00000000c1800000, 0x00000000c1901540, 0x0000000100000000)
region size 1024K, 2 young (2048K), 1 survivors (1024K)
Metaspace used 5070K, capacity 5204K, committed 5376K, reserved 1056768K
class space used 573K, capacity 644K, committed 768K, reserved 1048576K
STDERR:
stdout: [291227:
[GC.elastic_heap: in generation limit mode]
[GC.elastic_heap: young generation commit percent 20, uncommitted memory 335544320 B]
];
stderr: []
exitValue = 0
java.lang.RuntimeException: 'gc is too frequent' missing from stdout/stderr
at com.oracle.java.testlibrary.OutputAnalyzer.shouldContain(OutputAnalyzer.java:134)
at TestElasticHeapGenerationLimit.test(TestElasticHeapGenerationLimit.java:116)
at TestElasticHeapGenerationLimit.main(TestElasticHeapGenerationLimit.java:42)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:127)
at java.lang.Thread.run(Thread.java:855)
JavaTest Message: Test threw exception: java.lang.RuntimeException: 'gc is too frequent' missing from stdout/stderr
JavaTest Message: shutting down test
STATUS:Failed.`main' threw exception: java.lang.RuntimeException: 'gc is too frequent' missing from stdout/stderr
rerun:
cd /tmp/tone/run/jtreg/jt-work/hotspot_test/elastic-heap/TestElasticHeapGenerationLimit && \
DISPLAY=:7 \
HOME=/root \
LANG=en_US.UTF-8 \
PATH=/bin:/usr/bin:/usr/sbin \
CLASSPATH=/tmp/tone/run/jtreg/jt-work/hotspot_test/classes/32/elastic-heap:/tmp/tone/run/jtreg/jdk-repo/hotspot/test/elastic-heap:/tmp/tone/run/jtreg/jt-work/hotspot_test/classes/32/testlibrary:/tmp/tone/run/jtreg/jdk-repo/hotspot/test/testlibrary:/opt/java/openjdk/lib/tools.jar:/tmp/tone/run/jtreg/jtreg/lib/javatest.jar:/tmp/tone/run/jtreg/jtreg/lib/jtreg.jar \
/opt/java/openjdk/bin/java \
-Dtest.vm.opts='-Xmixed -ea -esa' \
-Dtest.tool.vm.opts='-J-Xmixed -J-ea -J-esa' \
-Dtest.compiler.opts= \
-Dtest.java.opts= \
-Dtest.jdk=/opt/java/openjdk \
-Dcompile.jdk=/opt/java/openjdk \
-Dtest.timeout.factor=4.0 \
-Dtest.root=/tmp/tone/run/jtreg/jdk-repo/hotspot/test \
-Dtest.name=elastic-heap/TestElasticHeapGenerationLimit.java \
-Dtest.file=/tmp/tone/run/jtreg/jdk-repo/hotspot/test/elastic-heap/TestElasticHeapGenerationLimit.java \
-Dtest.src=/tmp/tone/run/jtreg/jdk-repo/hotspot/test/elastic-heap \
-Dtest.src.path=/tmp/tone/run/jtreg/jdk-repo/hotspot/test/elastic-heap:/tmp/tone/run/jtreg/jdk-repo/hotspot/test/testlibrary \
-Dtest.classes=/tmp/tone/run/jtreg/jt-work/hotspot_test/classes/32/elastic-heap \
-Dtest.class.path=/tmp/tone/run/jtreg/jt-work/hotspot_test/classes/32/elastic-heap:/tmp/tone/run/jtreg/jt-work/hotspot_test/classes/32/testlibrary \
-Xmixed \
-ea \
-esa \
-XX:+UseG1GC \
-XX:+G1ElasticHeap \
-Xmx1000m \
-Xms1000m \
-XX:+AlwaysPreTouch \
-XX:MaxNewSize=400m \
-XX:G1HeapRegionSize=1m \
-XX:ElasticHeapYGCIntervalMinMillis=500 \
-verbose:gc \
-XX:+PrintGCDetails \
-XX:+PrintGCTimeStamps \
com.sun.javatest.regtest.agent.MainWrapper /tmp/tone/run/jtreg/jt-work/hotspot_test/elastic-heap/TestElasticHeapGenerationLimit.d/main.0.jta
elastic-heap/TestElasticHeapPeriodic.java
https://tone.aliyun-inc.com/ws/xesljfzh/test_result/156786?tab=1
TEST: elastic-heap/TestElasticHeapPeriodic.java
TEST JDK: /tmp/tone/run/jtreg/binary-download/dragonwell-8.14.15
ACTION: build -- Passed. Build successful
REASON: User specified action: run build TestElasticHeapPeriodic
TIME: 1.577 seconds
messages:
command: build TestElasticHeapPeriodic
reason: User specified action: run build TestElasticHeapPeriodic
Test directory:
compile: TestElasticHeapPeriodic
elapsed time (seconds): 1.577
ACTION: compile -- Passed. Compilation successful
REASON: .class file out of date or does not exist
TIME: 1.577 seconds
messages:
command: compile -XDignore.symbol.file=true /tmp/tone/run/jtreg/jdk-repo/hotspot/test/elastic-heap/TestElasticHeapPeriodic.java
reason: .class file out of date or does not exist
Mode: othervm
elapsed time (seconds): 1.577
configuration:
javac compilation environment
source path: /tmp/tone/run/jtreg/jdk-repo/hotspot/test/elastic-heap
/tmp/tone/run/jtreg/jdk-repo/hotspot/test/testlibrary
class path: /tmp/tone/run/jtreg/jdk-repo/hotspot/test/elastic-heap
/tmp/tone/run/jtreg/jt-work/hotspot_test/classes/5/elastic-heap
/tmp/tone/run/jtreg/jt-work/hotspot_test/classes/5/testlibrary
/tmp/tone/run/jtreg/binary-download/dragonwell-8.14.15/lib/tools.jar
rerun:
cd /tmp/tone/run/jtreg/jt-work/hotspot_test/elastic-heap/TestElasticHeapPeriodic && \
DISPLAY=:7 \
HOME=/home/testUserForTone \
LANG=C \
LC_CTYPE=C.UTF-8 \
PATH=/bin:/usr/bin:/usr/sbin \
/tmp/tone/run/jtreg/binary-download/dragonwell-8.14.15/bin/javac \
-J-Djdk.lang.processReaperUseDefaultStackSize=true \
-J-Xmixed \
-J-ea \
-J-esa \
-J-Dtest.vm.opts='-Djdk.lang.processReaperUseDefaultStackSize=true -Xmixed -ea -esa' \
-J-Dtest.tool.vm.opts='-J-Djdk.lang.processReaperUseDefaultStackSize=true -J-Xmixed -J-ea -J-esa' \
-J-Dtest.compiler.opts= \
-J-Dtest.java.opts= \
-J-Dtest.jdk=/tmp/tone/run/jtreg/binary-download/dragonwell-8.14.15 \
-J-Dcompile.jdk=/tmp/tone/run/jtreg/binary-download/dragonwell-8.14.15 \
-J-Dtest.timeout.factor=4.0 \
-J-Dtest.root=/tmp/tone/run/jtreg/jdk-repo/hotspot/test \
-J-Dtest.name=elastic-heap/TestElasticHeapPeriodic.java \
-J-Dtest.file=/tmp/tone/run/jtreg/jdk-repo/hotspot/test/elastic-heap/TestElasticHeapPeriodic.java \
-J-Dtest.src=/tmp/tone/run/jtreg/jdk-repo/hotspot/test/elastic-heap \
-J-Dtest.src.path=/tmp/tone/run/jtreg/jdk-repo/hotspot/test/elastic-heap:/tmp/tone/run/jtreg/jdk-repo/hotspot/test/testlibrary \
-J-Dtest.classes=/tmp/tone/run/jtreg/jt-work/hotspot_test/classes/5/elastic-heap \
-J-Dtest.class.path=/tmp/tone/run/jtreg/jt-work/hotspot_test/classes/5/elastic-heap:/tmp/tone/run/jtreg/jt-work/hotspot_test/classes/5/testlibrary \
-d /tmp/tone/run/jtreg/jt-work/hotspot_test/classes/5/elastic-heap \
-sourcepath /tmp/tone/run/jtreg/jdk-repo/hotspot/test/elastic-heap:/tmp/tone/run/jtreg/jdk-repo/hotspot/test/testlibrary \
-classpath /tmp/tone/run/jtreg/jdk-repo/hotspot/test/elastic-heap:/tmp/tone/run/jtreg/jt-work/hotspot_test/classes/5/elastic-heap:/tmp/tone/run/jtreg/jt-work/hotspot_test/classes/5/testlibrary:/tmp/tone/run/jtreg/binary-download/dragonwell-8.14.15/lib/tools.jar \
-XDignore.symbol.file=true /tmp/tone/run/jtreg/jdk-repo/hotspot/test/elastic-heap/TestElasticHeapPeriodic.java
STDOUT:
STDERR:
ACTION: build -- Passed. All files up to date
REASON: Named class compiled on demand
TIME: 0.0 seconds
messages:
command: build TestElasticHeapPeriodic
reason: Named class compiled on demand
elapsed time (seconds): 0.0
ACTION: main -- Failed. Execution failed: `main' threw exception: java.lang.RuntimeException: '[GC.elastic_heap: young generation commit percent 100, uncommitted memory 0 B]' missing from stdout/stderr
REASON: User specified action: run main/othervm/timeout=600 -XX:+UseG1GC -XX:+G1ElasticHeap -Xmx1000m -Xms1000m -XX:ElasticHeapPeriodicYGCIntervalMillis=400 -XX:ElasticHeapPeriodicUncommitStartupDelay=0 -Xmn200m -XX:G1HeapRegionSize=1m -XX:+AlwaysPreTouch -XX:ElasticHeapYGCIntervalMinMillis=50 -verbose:gc -XX:+PrintGCDetails -XX:+PrintGCTimeStamps TestElasticHeapPeriodic
TIME: 50.267 seconds
messages:
command: main -XX:+UseG1GC -XX:+G1ElasticHeap -Xmx1000m -Xms1000m -XX:ElasticHeapPeriodicYGCIntervalMillis=400 -XX:ElasticHeapPeriodicUncommitStartupDelay=0 -Xmn200m -XX:G1HeapRegionSize=1m -XX:+AlwaysPreTouch -XX:ElasticHeapYGCIntervalMinMillis=50 -verbose:gc -XX:+PrintGCDetails -XX:+PrintGCTimeStamps TestElasticHeapPeriodic
reason: User specified action: run main/othervm/timeout=600 -XX:+UseG1GC -XX:+G1ElasticHeap -Xmx1000m -Xms1000m -XX:ElasticHeapPeriodicYGCIntervalMillis=400 -XX:ElasticHeapPeriodicUncommitStartupDelay=0 -Xmn200m -XX:G1HeapRegionSize=1m -XX:+AlwaysPreTouch -XX:ElasticHeapYGCIntervalMinMillis=50 -verbose:gc -XX:+PrintGCDetails -XX:+PrintGCTimeStamps TestElasticHeapPeriodic
Mode: othervm [/othervm specified]
elapsed time (seconds): 50.267
configuration:
STDOUT:
1.661: [GC pause (G1 Evacuation Pause) (young), 0.0993993 secs]
[Parallel Time: 40.1 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 1661.4, Avg: 1675.8, Max: 1684.8, Diff: 23.4]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, 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.0, Max: 0, Diff: 0, Sum: 0]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.5]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.3, Diff: 0.3, Sum: 1.2]
[Termination (ms): Min: 0.0, Avg: 19.0, Max: 35.0, Diff: 35.0, Sum: 1580.8]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 3, Diff: 2, Sum: 86]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 1.0]
[GC Worker Total (ms): Min: 0.0, Avg: 19.1, Max: 35.5, Diff: 35.5, Sum: 1585.1]
[GC Worker End (ms): Min: 1684.9, Avg: 1694.9, Max: 1697.6, Diff: 12.7]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 12.0 ms]
[Other: 47.4 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 41.8 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 5.3 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.1 ms]
[Eden: 200.0M(200.0M)->0.0B(199.0M) Survivors: 0.0B->1024.0K Heap: 200.0M(1000.0M)->624.1K(1000.0M)]
[Times: user=0.52 sys=0.00, real=0.10 secs]
2.989: [GC pause (G1 Evacuation Pause) (young), 0.0250467 secs]
[Parallel Time: 3.5 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 2989.7, Avg: 2991.4, Max: 2992.7, Diff: 3.0]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, Sum: 0.7]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.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: 0.0, Max: 0.3, Diff: 0.3, Sum: 1.0]
[Termination (ms): Min: 0.0, Avg: 1.5, Max: 2.6, Diff: 2.6, Sum: 122.4]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.3]
[GC Worker Total (ms): Min: 0.0, Avg: 1.5, Max: 3.3, Diff: 3.2, Sum: 124.7]
[GC Worker End (ms): Min: 2992.7, Avg: 2992.9, Max: 2993.0, Diff: 0.3]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 0.5 ms]
[Other: 21.0 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 19.9 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 0.7 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 199.6M(1000.0M)->533.9K(1000.0M)]
[Times: user=0.26 sys=0.01, real=0.02 secs]
4.131: [GC pause (G1 Evacuation Pause) (young), 0.0647635 secs]
[Parallel Time: 15.2 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 4131.4, Avg: 4137.4, Max: 4138.7, Diff: 7.3]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, 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.0, Max: 0, Diff: 0, Sum: 0]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, Sum: 0.8]
[Termination (ms): Min: 0.2, Avg: 8.4, Max: 14.4, Diff: 14.1, Sum: 693.2]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.5]
[GC Worker Total (ms): Min: 0.3, Avg: 8.4, Max: 14.9, Diff: 14.7, Sum: 695.4]
[GC Worker End (ms): Min: 4138.9, Avg: 4145.8, Max: 4146.5, Diff: 7.6]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 12.5 ms]
[Other: 37.1 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 31.4 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 5.4 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.1 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 199.5M(1000.0M)->531.3K(1000.0M)]
[Times: user=0.33 sys=0.00, real=0.07 secs]
rssFull: 1162228
332038:
[GC.elastic_heap: inactive]
6.217: [GC pause (Elastic Heap Initiated GC) (young), 0.0808913 secs]
[Parallel Time: 8.3 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 6216.7, Avg: 6217.8, Max: 6218.6, Diff: 1.9]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.5, Diff: 0.5, Sum: 3.4]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.5, Diff: 0.5, Sum: 3.3]
[Termination (ms): Min: 0.7, Avg: 2.1, Max: 6.6, Diff: 5.9, Sum: 174.8]
[Termination Attempts: Min: 1, Avg: 1.1, Max: 3, Diff: 2, Sum: 92]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.5]
[GC Worker Total (ms): Min: 0.7, Avg: 2.2, Max: 7.1, Diff: 6.4, Sum: 182.5]
[GC Worker End (ms): Min: 6219.3, Avg: 6220.0, Max: 6223.9, Diff: 4.6]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 12.4 ms]
[Other: 60.2 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 59.1 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 0.8 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.1 ms]
[Eden: 11264.0K(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 11285.3K(1000.0M)->879.4K(1000.0M)]
[Times: user=0.31 sys=0.01, real=0.08 secs]
7.920: [GC pause (G1 Evacuation Pause) (young), 0.0479514 secs]
[Parallel Time: 9.3 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 7920.0, Avg: 7922.8, Max: 7924.4, Diff: 4.4]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.6, Diff: 0.6, Sum: 1.2]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 0.2]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.8, Diff: 0.8, Sum: 1.6]
[Termination (ms): Min: 0.4, Avg: 3.1, Max: 5.2, Diff: 4.8, Sum: 258.2]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.4]
[GC Worker Total (ms): Min: 0.4, Avg: 3.2, Max: 5.2, Diff: 4.8, Sum: 262.0]
[GC Worker End (ms): Min: 7924.7, Avg: 7926.0, Max: 7927.2, Diff: 2.5]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.0 ms]
[Other: 37.7 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 35.6 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.6 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 199.9M(1000.0M)->837.9K(1000.0M)]
[Times: user=0.43 sys=0.01, real=0.05 secs]
9.412: [GC pause (G1 Evacuation Pause) (young), 0.0459376 secs]
[Parallel Time: 6.4 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 9412.3, Avg: 9413.8, Max: 9414.7, Diff: 2.4]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, Sum: 1.6]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 0.2]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, Sum: 2.5]
[Termination (ms): Min: 0.4, Avg: 3.6, Max: 4.7, Diff: 4.3, Sum: 299.0]
[Termination Attempts: Min: 1, Avg: 1.2, Max: 6, Diff: 5, Sum: 101]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.6]
[GC Worker Total (ms): Min: 0.4, Avg: 3.7, Max: 5.3, Diff: 4.9, Sum: 304.4]
[GC Worker End (ms): Min: 9414.9, Avg: 9417.5, Max: 9418.0, Diff: 3.1]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 0.8 ms]
[Other: 38.7 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 37.8 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 0.6 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 199.8M(1000.0M)->839.9K(1000.0M)]
[Times: user=0.33 sys=0.01, real=0.05 secs]
10.912: [GC pause (G1 Evacuation Pause) (young), 0.0361040 secs]
[Parallel Time: 9.1 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 10912.5, Avg: 10917.5, Max: 10918.7, Diff: 6.2]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, 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.0, Max: 0, Diff: 0, Sum: 0]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 0.2]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.8, Diff: 0.8, Sum: 1.5]
[Termination (ms): Min: 0.0, Avg: 2.9, Max: 7.2, Diff: 7.1, Sum: 238.4]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.4]
[GC Worker Total (ms): Min: 0.0, Avg: 2.9, Max: 7.2, Diff: 7.1, Sum: 241.7]
[GC Worker End (ms): Min: 10918.7, Avg: 10920.5, Max: 10921.2, Diff: 2.5]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.5 ms]
[Other: 25.4 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 24.1 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.0 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 199.8M(1000.0M)->830.7K(1000.0M)]
[Times: user=0.20 sys=0.01, real=0.04 secs]
12.238: [GC pause (G1 Evacuation Pause) (young), 0.1557478 secs]
[Parallel Time: 68.6 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 12238.6, Avg: 12255.0, Max: 12262.6, Diff: 23.9]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.6, Diff: 0.6, 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.0, Max: 0, Diff: 0, Sum: 0]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 0.2]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 1.2, Diff: 1.2, Sum: 1.5]
[Termination (ms): Min: 0.0, Avg: 41.1, Max: 58.2, Diff: 58.1, Sum: 3414.8]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.5]
[GC Worker Total (ms): Min: 0.0, Avg: 41.2, Max: 58.2, Diff: 58.1, Sum: 3418.2]
[GC Worker End (ms): Min: 12262.6, Avg: 12296.2, Max: 12301.8, Diff: 39.2]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 24.1 ms]
[Other: 63.0 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 60.1 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 2.5 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 199.8M(1000.0M)->828.9K(1000.0M)]
[Times: user=0.15 sys=0.01, real=0.15 secs]
13.946: [GC pause (G1 Evacuation Pause) (young), 0.0761305 secs]
[Parallel Time: 7.8 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 13946.4, Avg: 13950.8, Max: 13952.0, Diff: 5.6]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 1.3, Diff: 1.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.0, Max: 0, Diff: 0, Sum: 0]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 0.2]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 1.2, Diff: 1.2, Sum: 1.5]
[Termination (ms): Min: 0.0, Avg: 1.8, Max: 4.0, Diff: 4.0, Sum: 146.3]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.6]
[GC Worker Total (ms): Min: 0.0, Avg: 1.8, Max: 5.6, Diff: 5.6, Sum: 150.5]
[GC Worker End (ms): Min: 13952.1, Avg: 13952.6, Max: 13954.2, Diff: 2.1]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 8.6 ms]
[Other: 59.7 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 32.5 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 26.7 ms]
[Humongous Register: 0.1 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 199.8M(1000.0M)->831.9K(1000.0M)]
[Times: user=0.16 sys=0.01, real=0.07 secs]
15.297: [GC pause (G1 Evacuation Pause) (young)(Elastic Heap concurrent cycle starts due to periodic uncommit), 0.0881441 secs]
[Parallel Time: 26.0 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 15296.9, Avg: 15305.1, Max: 15307.0, Diff: 10.1]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.7, Diff: 0.7, Sum: 2.2]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.5]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.3, Diff: 0.3, Sum: 0.3]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.6, Diff: 0.6, Sum: 2.2]
[Termination (ms): Min: 0.1, Avg: 3.3, Max: 12.2, Diff: 12.0, Sum: 277.5]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 1.0]
[GC Worker Total (ms): Min: 0.2, Avg: 3.4, Max: 13.2, Diff: 13.1, Sum: 283.7]
[GC Worker End (ms): Min: 15307.1, Avg: 15308.5, Max: 15310.2, Diff: 3.1]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 2.7 ms]
[Other: 59.3 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 31.5 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 27.1 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.4 ms]
[Eden: 199.0M(199.0M)->0.0B(101376.0K) Survivors: 1024.0K->1024.0K Heap: 199.8M(1000.0M)->839.8K(900.0M)]
[Times: user=0.38 sys=0.02, real=0.08 secs]
[Elastic Heap concurrent thread: uncommit 100M spent 0.008s(concurrent workers: 21) ]
16.290: [GC pause (G1 Evacuation Pause) (young)(Elastic Heap concurrent cycle ends)
, 0.0767598 secs]
[Parallel Time: 48.0 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 16290.5, Avg: 16298.3, Max: 16309.9, Diff: 19.4]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 1.8, Diff: 1.8, Sum: 2.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.0, Max: 0, Diff: 0, Sum: 0]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.3, Diff: 0.3, Sum: 0.3]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 2.6, Diff: 2.6, Sum: 2.9]
[Termination (ms): Min: 0.0, Avg: 32.8, Max: 41.6, Diff: 41.6, Sum: 2724.9]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 1.3]
[GC Worker Total (ms): Min: 0.0, Avg: 32.9, Max: 41.6, Diff: 41.6, Sum: 2732.0]
[GC Worker End (ms): Min: 16309.9, Avg: 16331.2, Max: 16337.8, Diff: 27.9]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.1 ms]
[Other: 27.7 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 25.6 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.6 ms]
[Humongous Register: 0.1 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 101376.0K(101376.0K)->0.0B(101376.0K) Survivors: 1024.0K->1024.0K Heap: 102215.8K(900.0M)->831.6K(900.0M)]
[Times: user=0.26 sys=0.01, real=0.08 secs]
17.165: [GC pause (G1 Evacuation Pause) (young), 0.1048008 secs]
[Parallel Time: 45.0 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 17174.3, Avg: 17194.7, Max: 17198.4, Diff: 24.1]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.9, Diff: 0.9, 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.0, Max: 0, Diff: 0, Sum: 0]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.0, Sum: 0.2]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 0.2]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 1.5, Diff: 1.5, Sum: 1.7]
[Termination (ms): Min: 0.0, Avg: 11.6, Max: 29.4, Diff: 29.4, Sum: 959.0]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.8]
[GC Worker Total (ms): Min: 0.0, Avg: 11.6, Max: 29.4, Diff: 29.4, Sum: 963.1]
[GC Worker End (ms): Min: 17198.4, Avg: 17206.3, Max: 17208.1, Diff: 9.7]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 8.8 ms]
[Other: 51.0 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 35.3 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 15.4 ms]
[Humongous Register: 0.1 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.1 ms]
[Eden: 101376.0K(101376.0K)->0.0B(101376.0K) Survivors: 1024.0K->1024.0K Heap: 102207.6K(900.0M)->831.8K(900.0M)]
[Times: user=0.26 sys=0.01, real=0.11 secs]
18.020: [GC pause (G1 Evacuation Pause) (young), 0.1750178 secs]
[Parallel Time: 9.9 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 18020.6, Avg: 18025.2, Max: 18027.0, Diff: 6.4]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 1.1, Diff: 1.1, Sum: 1.3]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.3, Diff: 0.3, Sum: 0.3]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 1.7, Diff: 1.7, Sum: 1.9]
[Termination (ms): Min: 0.0, Avg: 4.1, Max: 5.9, Diff: 5.9, Sum: 341.3]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.7]
[GC Worker Total (ms): Min: 0.1, Avg: 4.2, Max: 6.5, Diff: 6.4, Sum: 345.7]
[GC Worker End (ms): Min: 18027.1, Avg: 18029.4, Max: 18030.1, Diff: 3.0]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 2.1 ms]
[Other: 163.0 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 161.7 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 0.7 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 101376.0K(101376.0K)->0.0B(101376.0K) Survivors: 1024.0K->1024.0K Heap: 102207.8K(900.0M)->832.1K(900.0M)]
[Times: user=0.75 sys=0.02, real=0.18 secs]
18.818: [GC pause (G1 Evacuation Pause) (young), 0.0667759 secs]
[Parallel Time: 9.2 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 18818.4, Avg: 18822.0, Max: 18823.6, Diff: 5.2]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.7, Diff: 0.7, Sum: 2.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.0, Max: 0, Diff: 0, Sum: 0]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 0.2]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.6, Diff: 0.6, Sum: 2.4]
[Termination (ms): Min: 0.0, Avg: 3.2, Max: 7.1, Diff: 7.1, Sum: 262.2]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.9]
[GC Worker Total (ms): Min: 0.0, Avg: 3.2, Max: 8.0, Diff: 8.0, Sum: 268.2]
[GC Worker End (ms): Min: 18823.6, Avg: 18825.2, Max: 18826.4, Diff: 2.8]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 9.2 ms]
[Other: 48.3 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 46.3 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.9 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.1 ms]
[Eden: 101376.0K(101376.0K)->0.0B(101376.0K) Survivors: 1024.0K->1024.0K Heap: 102208.1K(900.0M)->846.3K(900.0M)]
[Times: user=0.22 sys=0.01, real=0.06 secs]
19.520: [GC pause (G1 Evacuation Pause) (young), 0.0425877 secs]
[Parallel Time: 7.9 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 19520.5, Avg: 19524.1, Max: 19525.2, Diff: 4.8]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.8, Diff: 0.8, 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.0, Max: 0, Diff: 0, Sum: 0]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.2]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 0.2]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 1.4, Diff: 1.4, Sum: 1.6]
[Termination (ms): Min: 0.0, Avg: 2.9, Max: 4.8, Diff: 4.8, Sum: 239.5]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.4]
[GC Worker Total (ms): Min: 0.0, Avg: 2.9, Max: 4.9, Diff: 4.9, Sum: 242.8]
[GC Worker End (ms): Min: 19525.2, Avg: 19527.0, Max: 19528.1, Diff: 2.9]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 0.7 ms]
[Other: 33.9 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 32.7 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 0.8 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 101376.0K(101376.0K)->0.0B(101376.0K) Survivors: 1024.0K->1024.0K Heap: 102222.3K(900.0M)->831.5K(900.0M)]
[Times: user=0.23 sys=0.01, real=0.04 secs]
20.187: [GC pause (G1 Evacuation Pause) (young), 0.0296795 secs]
[Parallel Time: 2.7 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 20187.1, Avg: 20188.3, Max: 20189.0, Diff: 1.9]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.9, Diff: 0.9, Sum: 3.3]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, Sum: 0.4]
[Object Copy (ms): Min: 0.0, Avg: 0.1, Max: 0.5, Diff: 0.5, Sum: 4.7]
[Termination (ms): Min: 0.1, Avg: 1.0, Max: 1.8, Diff: 1.8, Sum: 79.7]
[Termination Attempts: Min: 1, Avg: 1.3, Max: 8, Diff: 7, Sum: 112]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.9]
[GC Worker Total (ms): Min: 0.1, Avg: 1.1, Max: 2.5, Diff: 2.5, Sum: 89.4]
[GC Worker End (ms): Min: 20189.1, Avg: 20189.4, Max: 20189.7, Diff: 0.6]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.0 ms]
[Other: 25.9 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 24.7 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 0.7 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 101376.0K(101376.0K)->0.0B(101376.0K) Survivors: 1024.0K->1024.0K Heap: 102207.5K(900.0M)->904.7K(900.0M)]
[Times: user=0.31 sys=0.01, real=0.03 secs]
20.829: [GC pause (G1 Evacuation Pause) (young), 0.0861648 secs]
[Parallel Time: 8.6 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 20829.3, Avg: 20833.0, Max: 20836.9, Diff: 7.7]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, Sum: 1.9]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Processed Buffers: Min: 0, Avg: 0.2, Max: 4, Diff: 4, Sum: 13]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.3]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 0.2]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, Sum: 1.7]
[Termination (ms): Min: 0.0, Avg: 4.1, Max: 7.3, Diff: 7.3, Sum: 344.3]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 2, Diff: 1, Sum: 84]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.7]
[GC Worker Total (ms): Min: 0.0, Avg: 4.2, Max: 8.1, Diff: 8.1, Sum: 349.4]
[GC Worker End (ms): Min: 20836.9, Avg: 20837.2, Max: 20837.6, Diff: 0.7]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 10.8 ms]
[Other: 66.8 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 66.0 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 0.5 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.1 ms]
[Eden: 101376.0K(101376.0K)->0.0B(101376.0K) Survivors: 1024.0K->1024.0K Heap: 102280.7K(900.0M)->863.3K(900.0M)]
[Times: user=0.72 sys=0.00, real=0.08 secs]
21.503: [GC pause (G1 Evacuation Pause) (young), 0.2581029 secs]
[Parallel Time: 14.8 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 21503.4, Avg: 21506.9, Max: 21507.9, Diff: 4.4]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 1.1]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.1, Max: 3, Diff: 3, Sum: 6]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.2]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.3, Diff: 0.3, Sum: 1.2]
[Termination (ms): Min: 0.0, Avg: 9.0, Max: 13.1, Diff: 13.0, Sum: 742.9]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.6]
[GC Worker Total (ms): Min: 0.0, Avg: 9.0, Max: 13.1, Diff: 13.1, Sum: 746.2]
[GC Worker End (ms): Min: 21507.9, Avg: 21515.8, Max: 21517.8, Diff: 9.8]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 162.3 ms]
[Other: 81.0 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 79.5 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.2 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.1 ms]
[Eden: 101376.0K(101376.0K)->0.0B(101376.0K) Survivors: 1024.0K->1024.0K Heap: 102239.3K(900.0M)->853.4K(900.0M)]
[Times: user=0.48 sys=0.00, real=0.26 secs]
22.604: [GC pause (G1 Evacuation Pause) (young), 0.0674208 secs]
[Parallel Time: 31.5 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 22606.7, Avg: 22609.3, Max: 22613.1, Diff: 6.5]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 1.7]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 3]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.3]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.3, Diff: 0.2, Sum: 1.9]
[Termination (ms): Min: 1.1, Avg: 18.6, Max: 24.2, Diff: 23.1, Sum: 1544.3]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 2, Diff: 1, Sum: 87]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.4]
[GC Worker Total (ms): Min: 1.1, Avg: 18.7, Max: 24.6, Diff: 23.5, Sum: 1548.9]
[GC Worker End (ms): Min: 22614.2, Avg: 22627.9, Max: 22631.3, Diff: 17.1]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 2.9 ms]
[Other: 33.0 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 27.0 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 5.8 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.1 ms]
[Eden: 101376.0K(101376.0K)->0.0B(101376.0K) Survivors: 1024.0K->1024.0K Heap: 102229.4K(900.0M)->890.0K(900.0M)]
[Times: user=0.26 sys=0.01, real=0.07 secs]
23.242: [GC pause (G1 Evacuation Pause) (young), 0.0770053 secs]
[Parallel Time: 20.4 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 23242.2, Avg: 23251.4, Max: 23253.9, Diff: 11.7]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.9, Diff: 0.9, Sum: 1.1]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 4, Diff: 4, Sum: 4]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 0.4]
[Termination (ms): Min: 1.1, Avg: 10.4, Max: 18.5, Diff: 17.4, Sum: 859.6]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.5]
[GC Worker Total (ms): Min: 1.1, Avg: 10.4, Max: 18.5, Diff: 17.4, Sum: 862.1]
[GC Worker End (ms): Min: 23254.8, Avg: 23261.8, Max: 23262.2, Diff: 7.4]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 12.0 ms]
[Other: 44.6 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 35.5 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 8.7 ms]
[Humongous Register: 0.1 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 101376.0K(101376.0K)->0.0B(101376.0K) Survivors: 1024.0K->1024.0K Heap: 102266.0K(900.0M)->866.5K(900.0M)]
[Times: user=0.18 sys=0.00, real=0.08 secs]
23.897: [GC pause (G1 Evacuation Pause) (young), 0.1822002 secs]
[Parallel Time: 15.3 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 23896.7, Avg: 23898.3, Max: 23899.2, Diff: 2.5]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, 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.0, Max: 2, Diff: 2, Sum: 2]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.2]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Termination (ms): Min: 0.2, Avg: 9.5, Max: 13.2, Diff: 13.0, Sum: 791.1]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.4]
[GC Worker Total (ms): Min: 0.2, Avg: 9.6, Max: 13.2, Diff: 13.0, Sum: 792.7]
[GC Worker End (ms): Min: 23899.2, Avg: 23907.9, Max: 23911.0, Diff: 11.8]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 102.2 ms]
[Other: 64.7 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 63.5 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.0 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.1 ms]
[Eden: 101376.0K(101376.0K)->0.0B(101376.0K) Survivors: 1024.0K->1024.0K Heap: 102242.5K(900.0M)->866.5K(900.0M)]
[Times: user=0.36 sys=0.02, real=0.18 secs]
24.685: [GC pause (G1 Evacuation Pause) (young), 0.0772208 secs]
[Parallel Time: 31.9 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 24685.1, Avg: 24704.3, Max: 24709.6, Diff: 24.5]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 1.0, Diff: 1.0, Sum: 1.2]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 2, Diff: 2, Sum: 2]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Termination (ms): Min: 0.0, Avg: 10.3, Max: 29.3, Diff: 29.3, Sum: 857.5]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.5]
[GC Worker Total (ms): Min: 0.0, Avg: 10.4, Max: 29.3, Diff: 29.3, Sum: 860.0]
[GC Worker End (ms): Min: 24709.7, Avg: 24714.6, Max: 24716.2, Diff: 6.5]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.5 ms]
[Other: 43.8 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 37.5 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 5.9 ms]
[Humongous Register: 0.1 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 101376.0K(101376.0K)->0.0B(10137
...
Output overflow:
JT Harness has limited the test output to the text
at the beginning and the end, so that you can see how the
test began, and how it completed.
If you need to see more of the output from the test,
set the system property javatest.maxOutputSize to a higher
value. The current value is 100000
...
Total (ms): Min: 0.1, Avg: 6.5, Max: 8.2, Diff: 8.1, Sum: 539.8]
[GC Worker End (ms): Min: 36635.1, Avg: 36635.8, Max: 36636.1, Diff: 1.0]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 6.0 ms]
[Other: 24.3 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 22.5 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.4 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.41 sys=0.01, real=0.04 secs]
36.835: [GC pause (G1 Evacuation Pause) (young), 0.1038039 secs]
[Parallel Time: 46.6 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 36835.1, Avg: 36840.1, Max: 36847.2, Diff: 12.1]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.3, Diff: 0.3, 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.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.5]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Termination (ms): Min: 0.0, Avg: 13.1, Max: 19.5, Diff: 19.5, Sum: 1086.6]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.6]
[GC Worker Total (ms): Min: 0.0, Avg: 13.1, Max: 19.5, Diff: 19.5, Sum: 1089.0]
[GC Worker End (ms): Min: 36847.2, Avg: 36853.2, Max: 36856.4, Diff: 9.2]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.1 ms]
[Other: 56.1 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 54.2 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.5 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.61 sys=0.00, real=0.11 secs]
37.221: [GC pause (G1 Evacuation Pause) (young), 0.1155420 secs]
[Parallel Time: 38.2 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 37221.4, Avg: 37226.3, Max: 37236.3, Diff: 14.9]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, Sum: 1.3]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.6]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.5]
[Termination (ms): Min: 0.0, Avg: 16.6, Max: 34.9, Diff: 34.9, Sum: 1376.7]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 1.1]
[GC Worker Total (ms): Min: 0.0, Avg: 16.6, Max: 34.9, Diff: 34.9, Sum: 1380.3]
[GC Worker End (ms): Min: 37236.3, Avg: 37242.9, Max: 37257.6, Diff: 21.3]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 17.4 ms]
[Other: 60.0 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 57.6 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 2.1 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.53 sys=0.01, real=0.12 secs]
37.551: [GC pause (G1 Evacuation Pause) (young), 0.0629712 secs]
[Parallel Time: 23.6 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 37551.5, Avg: 37557.4, Max: 37571.0, Diff: 19.5]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.9, Diff: 0.9, Sum: 1.2]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.5]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Termination (ms): Min: 0.1, Avg: 15.5, Max: 21.5, Diff: 21.3, Sum: 1285.5]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.7]
[GC Worker Total (ms): Min: 0.2, Avg: 15.5, Max: 21.5, Diff: 21.3, Sum: 1288.3]
[GC Worker End (ms): Min: 37571.1, Avg: 37572.9, Max: 37574.6, Diff: 3.6]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.2 ms]
[Other: 38.2 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 29.8 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 8.0 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.30 sys=0.00, real=0.06 secs]
37.808: [GC pause (G1 Evacuation Pause) (young), 0.1279265 secs]
[Parallel Time: 14.4 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 37808.3, Avg: 37810.7, Max: 37812.7, Diff: 4.4]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, 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.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Termination (ms): Min: 0.0, Avg: 4.7, Max: 9.0, Diff: 9.0, Sum: 393.4]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.5]
[GC Worker Total (ms): Min: 0.0, Avg: 4.8, Max: 9.0, Diff: 9.0, Sum: 395.7]
[GC Worker End (ms): Min: 37812.7, Avg: 37815.5, Max: 37821.6, Diff: 8.9]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 8.5 ms]
[Other: 105.0 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 54.7 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 49.8 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.42 sys=0.01, real=0.13 secs]
38.157: [GC pause (G1 Evacuation Pause) (young), 0.0936559 secs]
[Parallel Time: 36.7 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 38162.6, Avg: 38169.7, Max: 38173.3, Diff: 10.7]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, 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.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Termination (ms): Min: 0.0, Avg: 19.6, Max: 29.0, Diff: 29.0, Sum: 1627.6]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.6]
[GC Worker Total (ms): Min: 0.0, Avg: 19.6, Max: 29.3, Diff: 29.2, Sum: 1630.1]
[GC Worker End (ms): Min: 38173.3, Avg: 38189.3, Max: 38192.5, Diff: 19.2]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 13.2 ms]
[Other: 43.7 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 39.4 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 4.1 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.1 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.41 sys=0.01, real=0.09 secs]
38.424: [GC pause (G1 Evacuation Pause) (young), 0.0596836 secs]
[Parallel Time: 11.5 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 38423.8, Avg: 38426.4, Max: 38427.7, Diff: 3.9]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, 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.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Termination (ms): Min: 0.0, Avg: 6.2, Max: 8.1, Diff: 8.1, Sum: 516.5]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.5]
[GC Worker Total (ms): Min: 0.0, Avg: 6.3, Max: 8.2, Diff: 8.1, Sum: 518.8]
[GC Worker End (ms): Min: 38427.7, Avg: 38432.7, Max: 38434.8, Diff: 7.1]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 3.7 ms]
[Other: 44.5 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 36.2 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 7.8 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.40 sys=0.01, real=0.06 secs]
38.660: [GC pause (G1 Evacuation Pause) (young), 0.0542543 secs]
[Parallel Time: 15.6 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 38660.3, Avg: 38664.9, Max: 38668.6, Diff: 8.3]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 1.4]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.5]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Termination (ms): Min: 0.0, Avg: 8.4, Max: 14.2, Diff: 14.2, Sum: 700.1]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.6]
[GC Worker Total (ms): Min: 0.0, Avg: 8.5, Max: 14.3, Diff: 14.3, Sum: 703.1]
[GC Worker End (ms): Min: 38668.7, Avg: 38673.4, Max: 38674.8, Diff: 6.2]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.5 ms]
[Other: 37.2 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 35.8 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.0 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.37 sys=0.01, real=0.05 secs]
38.914: [GC pause (G1 Evacuation Pause) (young), 0.0835553 secs]
[Parallel Time: 36.7 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 38913.7, Avg: 38916.7, Max: 38917.6, Diff: 3.8]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.5, Diff: 0.5, Sum: 0.7]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Termination (ms): Min: 0.0, Avg: 16.7, Max: 33.0, Diff: 33.0, Sum: 1383.0]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.7]
[GC Worker Total (ms): Min: 0.1, Avg: 16.7, Max: 33.0, Diff: 33.0, Sum: 1385.2]
[GC Worker End (ms): Min: 38917.7, Avg: 38933.4, Max: 38949.6, Diff: 31.9]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 3.4 ms]
[Other: 43.4 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 41.7 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.5 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.46 sys=0.01, real=0.09 secs]
39.159: [GC pause (G1 Evacuation Pause) (young), 0.0588651 secs]
[Parallel Time: 2.1 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 39158.6, Avg: 39159.1, Max: 39159.5, Diff: 0.9]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 1.5]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.6]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.0, Sum: 0.3]
[Termination (ms): Min: 0.0, Avg: 0.7, Max: 0.8, Diff: 0.8, Sum: 58.7]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.4]
[GC Worker Total (ms): Min: 0.0, Avg: 0.7, Max: 0.9, Diff: 0.9, Sum: 61.6]
[GC Worker End (ms): Min: 39159.5, Avg: 39159.9, Max: 39160.3, Diff: 0.8]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 25.0 ms]
[Other: 31.8 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 29.3 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 2.2 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.44 sys=0.01, real=0.06 secs]
39.408: [GC pause (G1 Evacuation Pause) (young), 0.0670831 secs]
[Parallel Time: 2.7 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 39408.4, Avg: 39409.3, Max: 39409.9, Diff: 1.5]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.3, Diff: 0.3, Sum: 1.2]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Termination (ms): Min: 0.1, Avg: 0.9, Max: 1.1, Diff: 1.1, Sum: 78.6]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.6]
[GC Worker Total (ms): Min: 0.1, Avg: 1.0, Max: 1.6, Diff: 1.5, Sum: 81.2]
[GC Worker End (ms): Min: 39409.9, Avg: 39410.3, Max: 39410.7, Diff: 0.7]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 3.5 ms]
[Other: 60.9 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 58.9 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.8 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.61 sys=0.01, real=0.06 secs]
39.669: [GC pause (G1 Evacuation Pause) (young), 0.0903088 secs]
[Parallel Time: 27.9 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 39668.7, Avg: 39673.7, Max: 39679.7, Diff: 11.0]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, 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.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Termination (ms): Min: 0.0, Avg: 18.6, Max: 26.4, Diff: 26.4, Sum: 1546.6]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.6]
[GC Worker Total (ms): Min: 0.0, Avg: 18.7, Max: 26.4, Diff: 26.4, Sum: 1548.6]
[GC Worker End (ms): Min: 39679.8, Avg: 39692.4, Max: 39696.2, Diff: 16.4]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.9 ms]
[Other: 60.5 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 56.0 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 4.2 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.67 sys=0.01, real=0.09 secs]
39.946: [GC pause (G1 Evacuation Pause) (young), 0.0651994 secs]
[Parallel Time: 18.1 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 39946.5, Avg: 39953.0, Max: 39959.0, Diff: 12.5]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.3, Diff: 0.3, Sum: 1.3]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.5]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Termination (ms): Min: 0.0, Avg: 10.9, Max: 17.2, Diff: 17.1, Sum: 902.6]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.6]
[GC Worker Total (ms): Min: 0.0, Avg: 10.9, Max: 17.5, Diff: 17.5, Sum: 905.5]
[GC Worker End (ms): Min: 39959.0, Avg: 39963.9, Max: 39964.5, Diff: 5.5]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 5.2 ms]
[Other: 41.9 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 37.2 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 4.4 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.31 sys=0.01, real=0.06 secs]
40.243: [GC pause (G1 Evacuation Pause) (young), 0.0670610 secs]
[Parallel Time: 17.8 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 40243.3, Avg: 40257.1, Max: 40259.1, Diff: 15.8]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.6, Diff: 0.6, 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.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Termination (ms): Min: 0.0, Avg: 3.2, Max: 16.1, Diff: 16.0, Sum: 266.4]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.7]
[GC Worker Total (ms): Min: 0.1, Avg: 3.2, Max: 16.1, Diff: 16.0, Sum: 268.9]
[GC Worker End (ms): Min: 40259.1, Avg: 40260.3, Max: 40261.0, Diff: 1.9]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 2.1 ms]
[Other: 47.2 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 42.0 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 4.8 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.41 sys=0.00, real=0.07 secs]
40.502: [GC pause (G1 Evacuation Pause) (young), 0.0935872 secs]
[Parallel Time: 37.4 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 40502.2, Avg: 40519.5, Max: 40526.2, Diff: 24.0]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.3, Sum: 2.1]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.8]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Termination (ms): Min: 0.0, Avg: 8.5, Max: 29.4, Diff: 29.4, Sum: 708.1]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.7]
[GC Worker Total (ms): Min: 0.0, Avg: 8.6, Max: 29.9, Diff: 29.9, Sum: 712.2]
[GC Worker End (ms): Min: 40526.2, Avg: 40528.1, Max: 40534.7, Diff: 8.5]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 5.3 ms]
[Other: 50.8 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 42.4 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 7.9 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.32 sys=0.01, real=0.10 secs]
40.778: [GC pause (G1 Evacuation Pause) (young), 0.0722727 secs]
[Parallel Time: 22.7 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 40777.9, Avg: 40780.8, Max: 40791.8, Diff: 13.9]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.5, Diff: 0.5, Sum: 2.5]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.8]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 0.6]
[Termination (ms): Min: 0.0, Avg: 17.0, Max: 21.3, Diff: 21.3, Sum: 1413.2]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 1.1]
[GC Worker Total (ms): Min: 0.0, Avg: 17.1, Max: 21.4, Diff: 21.3, Sum: 1418.4]
[GC Worker End (ms): Min: 40791.9, Avg: 40797.9, Max: 40800.0, Diff: 8.1]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 8.6 ms]
[Other: 41.0 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 39.4 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.0 ms]
[Humongous Register: 0.1 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.29 sys=0.01, real=0.07 secs]
41.071: [GC pause (G1 Evacuation Pause) (young), 0.1017248 secs]
[Parallel Time: 31.0 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 41070.8, Avg: 41076.0, Max: 41090.1, Diff: 19.3]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.6, Diff: 0.6, 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.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.5]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Termination (ms): Min: 0.0, Avg: 20.3, Max: 26.4, Diff: 26.4, Sum: 1685.1]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.9]
[GC Worker Total (ms): Min: 0.0, Avg: 20.3, Max: 26.5, Diff: 26.4, Sum: 1687.8]
[GC Worker End (ms): Min: 41090.2, Avg: 41096.3, Max: 41098.7, Diff: 8.6]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.5 ms]
[Other: 69.2 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 67.4 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.3 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.39 sys=0.01, real=0.10 secs]
41.369: [GC pause (G1 Evacuation Pause) (young), 0.1025635 secs]
[Parallel Time: 49.7 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 41369.4, Avg: 41376.8, Max: 41385.8, Diff: 16.3]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.5, Diff: 0.5, Sum: 1.2]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Termination (ms): Min: 0.0, Avg: 20.5, Max: 38.8, Diff: 38.8, Sum: 1703.9]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.8]
[GC Worker Total (ms): Min: 0.0, Avg: 20.6, Max: 38.8, Diff: 38.8, Sum: 1706.7]
[GC Worker End (ms): Min: 41385.8, Avg: 41397.3, Max: 41409.6, Diff: 23.8]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 3.4 ms]
[Other: 49.4 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 47.5 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.5 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.36 sys=0.01, real=0.10 secs]
41.681: [GC pause (G1 Evacuation Pause) (young), 0.0354867 secs]
[Parallel Time: 7.6 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 41680.7, Avg: 41686.1, Max: 41687.2, Diff: 6.5]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 2.0, Diff: 2.0, Sum: 4.7]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.5, Diff: 0.5, Sum: 0.8]
[Termination (ms): Min: 0.0, Avg: 1.4, Max: 5.9, Diff: 5.9, Sum: 116.1]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.7]
[GC Worker Total (ms): Min: 0.0, Avg: 1.5, Max: 7.1, Diff: 7.1, Sum: 122.8]
[GC Worker End (ms): Min: 41687.3, Avg: 41687.6, Max: 41687.9, Diff: 0.6]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.2 ms]
[Other: 26.7 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 25.1 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.2 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.39 sys=0.01, real=0.04 secs]
42.009: [GC pause (G1 Evacuation Pause) (young), 0.0623912 secs]
[Parallel Time: 13.6 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 42008.6, Avg: 42011.4, Max: 42013.1, Diff: 4.5]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 1.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.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.0, Sum: 0.6]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Termination (ms): Min: 0.0, Avg: 9.8, Max: 12.5, Diff: 12.5, Sum: 815.9]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.6]
[GC Worker Total (ms): Min: 0.0, Avg: 9.9, Max: 12.8, Diff: 12.7, Sum: 819.4]
[GC Worker End (ms): Min: 42013.1, Avg: 42021.3, Max: 42022.0, Diff: 8.9]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 3.3 ms]
[Other: 45.4 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 40.3 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 4.7 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.57 sys=0.01, real=0.06 secs]
42.243: [GC pause (G1 Evacuation Pause) (young), 0.0854160 secs]
[Parallel Time: 40.8 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 42243.4, Avg: 42257.6, Max: 42273.8, Diff: 30.4]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, 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.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.5]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Termination (ms): Min: 0.0, Avg: 24.5, Max: 38.8, Diff: 38.8, Sum: 2030.9]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.7]
[GC Worker Total (ms): Min: 0.0, Avg: 24.5, Max: 39.3, Diff: 39.3, Sum: 2033.6]
[GC Worker End (ms): Min: 42273.8, Avg: 42282.1, Max: 42282.9, Diff: 9.1]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 12.9 ms]
[Other: 31.7 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 25.1 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 6.1 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.43 sys=0.01, real=0.09 secs]
42.518: [GC pause (G1 Evacuation Pause) (young), 0.0445606 secs]
[Parallel Time: 12.6 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 42518.0, Avg: 42523.2, Max: 42524.2, Diff: 6.2]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 1.0, Diff: 0.9, Sum: 1.2]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 0.4]
[Termination (ms): Min: 0.0, Avg: 5.6, Max: 8.4, Diff: 8.4, Sum: 464.1]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.6]
[GC Worker Total (ms): Min: 0.0, Avg: 5.6, Max: 8.5, Diff: 8.5, Sum: 466.8]
[GC Worker End (ms): Min: 42524.2, Avg: 42528.9, Max: 42530.1, Diff: 5.9]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 5.4 ms]
[Other: 26.6 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 25.4 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 0.9 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.26 sys=0.01, real=0.04 secs]
42.740: [GC pause (G1 Evacuation Pause) (young), 0.0753385 secs]
[Parallel Time: 14.3 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 42740.2, Avg: 42745.0, Max: 42750.5, Diff: 10.2]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.5, Diff: 0.5, 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.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.5]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Termination (ms): Min: 0.0, Avg: 7.9, Max: 11.9, Diff: 11.9, Sum: 651.6]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.5]
[GC Worker Total (ms): Min: 0.0, Avg: 7.9, Max: 12.2, Diff: 12.2, Sum: 654.0]
[GC Worker End (ms): Min: 42750.5, Avg: 42752.9, Max: 42753.3, Diff: 2.7]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 27.0 ms]
[Other: 34.1 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 30.6 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 2.1 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 1.3 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.21 sys=0.01, real=0.07 secs]
43.012: [GC pause (G1 Evacuation Pause) (young), 0.0513863 secs]
[Parallel Time: 10.7 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 43014.6, Avg: 43020.2, Max: 43021.3, Diff: 6.7]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.2, Max: 5.1, Diff: 5.1, Sum: 15.3]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.1, Sum: 0.4]
[Termination (ms): Min: 0.0, Avg: 1.7, Max: 5.7, Diff: 5.7, Sum: 140.3]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.5]
[GC Worker Total (ms): Min: 0.0, Avg: 1.9, Max: 7.4, Diff: 7.4, Sum: 157.0]
[GC Worker End (ms): Min: 43021.3, Avg: 43022.1, Max: 43022.3, Diff: 1.0]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 3.3 ms]
[Other: 37.3 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 36.4 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 0.7 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.1 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.24 sys=0.01, real=0.05 secs]
43.297: [GC pause (G1 Evacuation Pause) (young), 0.1053201 secs]
[Parallel Time: 2.3 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 43297.2, Avg: 43298.3, Max: 43299.0, Diff: 1.7]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.3, Diff: 0.3, 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.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Termination (ms): Min: 0.0, Avg: 0.9, Max: 1.8, Diff: 1.8, Sum: 75.7]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.4]
[GC Worker Total (ms): Min: 0.0, Avg: 0.9, Max: 2.2, Diff: 2.1, Sum: 77.6]
[GC Worker End (ms): Min: 43299.0, Avg: 43299.2, Max: 43299.5, Diff: 0.5]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 43.6 ms]
[Other: 59.4 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 41.4 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 17.7 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.35 sys=0.00, real=0.10 secs]
43.637: [GC pause (G1 Evacuation Pause) (young), 0.0312213 secs]
[Parallel Time: 1.9 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 43636.7, Avg: 43637.2, Max: 43637.5, Diff: 0.8]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 1.5]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.5]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Termination (ms): Min: 0.0, Avg: 0.6, Max: 0.7, Diff: 0.7, Sum: 48.7]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.4]
[GC Worker Total (ms): Min: 0.0, Avg: 0.6, Max: 0.9, Diff: 0.9, Sum: 51.5]
[GC Worker End (ms): Min: 43637.6, Avg: 43637.8, Max: 43638.3, Diff: 0.7]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 0.5 ms]
[Other: 28.8 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 28.0 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 0.6 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.1 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.23 sys=0.00, real=0.03 secs]
43.902: [GC pause (G1 Evacuation Pause) (young), 0.0586922 secs]
[Parallel Time: 12.1 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 43902.1, Avg: 43905.0, Max: 43908.0, Diff: 5.9]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.5, Diff: 0.5, Sum: 3.3]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.7]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.5]
[Termination (ms): Min: 0.5, Avg: 7.3, Max: 10.8, Diff: 10.3, Sum: 608.9]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 4.3]
[GC Worker Total (ms): Min: 0.6, Avg: 7.4, Max: 11.1, Diff: 10.5, Sum: 617.8]
[GC Worker End (ms): Min: 43908.2, Avg: 43912.4, Max: 43913.5, Diff: 5.3]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 0.6 ms]
[Other: 46.0 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 44.8 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 0.7 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.38 sys=0.01, real=0.06 secs]
44.154: [GC pause (G1 Evacuation Pause) (young), 0.1053918 secs]
[Parallel Time: 1.7 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 44154.0, Avg: 44154.6, Max: 44155.0, Diff: 1.0]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.3, Diff: 0.3, 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.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Termination (ms): Min: 0.0, Avg: 0.6, Max: 0.8, Diff: 0.8, Sum: 53.1]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.5]
[GC Worker Total (ms): Min: 0.0, Avg: 0.7, Max: 1.1, Diff: 1.1, Sum: 55.3]
[GC Worker End (ms): Min: 44155.0, Avg: 44155.3, Max: 44155.6, Diff: 0.6]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 22.6 ms]
[Other: 81.1 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 78.3 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 2.4 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.88 sys=0.01, real=0.11 secs]
44.501: [GC pause (G1 Evacuation Pause) (young), 0.1451367 secs]
[Parallel Time: 71.7 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 44501.0, Avg: 44527.5, Max: 44547.2, Diff: 46.2]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.5, Diff: 0.5, 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.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.5]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Termination (ms): Min: 0.0, Avg: 34.8, Max: 60.8, Diff: 60.7, Sum: 2891.5]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.5]
[GC Worker Total (ms): Min: 0.0, Avg: 34.9, Max: 60.8, Diff: 60.8, Sum: 2893.5]
[GC Worker End (ms): Min: 44547.2, Avg: 44562.4, Max: 44563.8, Diff: 16.6]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 4.9 ms]
[Other: 68.5 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 63.0 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.5 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.30 sys=0.11, real=0.15 secs]
44.820: [GC pause (G1 Evacuation Pause) (young), 0.0340878 secs]
[Parallel Time: 2.0 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 44819.8, Avg: 44820.7, Max: 44821.2, Diff: 1.4]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.5, Diff: 0.5, Sum: 3.2]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.6]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Termination (ms): Min: 0.0, Avg: 0.6, Max: 1.0, Diff: 1.0, Sum: 53.3]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.6]
[GC Worker Total (ms): Min: 0.0, Avg: 0.7, Max: 1.4, Diff: 1.4, Sum: 58.2]
[GC Worker End (ms): Min: 44821.2, Avg: 44821.4, Max: 44821.6, Diff: 0.4]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.1 ms]
[Other: 31.0 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 26.5 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 3.4 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.7 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.41 sys=0.01, real=0.03 secs]
45.039: [GC pause (G1 Evacuation Pause) (young), 0.0237598 secs]
[Parallel Time: 2.3 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 45039.2, Avg: 45039.8, Max: 45040.2, Diff: 1.0]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 2.3]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, 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: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.5]
[Termination (ms): Min: 0.0, Avg: 0.8, Max: 0.9, Diff: 0.8, Sum: 64.9]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.7]
[GC Worker Total (ms): Min: 0.0, Avg: 0.8, Max: 1.1, Diff: 1.1, Sum: 69.3]
[GC Worker End (ms): Min: 45040.2, Avg: 45040.6, Max: 45040.9, Diff: 0.7]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 2.0 ms]
[Other: 19.5 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 17.6 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.5 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.40 sys=0.01, real=0.02 secs]
45.232: [GC pause (G1 Evacuation Pause) (young), 0.0209273 secs]
[Parallel Time: 2.4 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 45232.4, Avg: 45233.3, Max: 45233.8, Diff: 1.4]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, Sum: 2.2]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.7]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 0.5]
[Termination (ms): Min: 0.0, Avg: 0.8, Max: 1.0, Diff: 1.0, Sum: 64.1]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.7]
[GC Worker Total (ms): Min: 0.0, Avg: 0.8, Max: 1.5, Diff: 1.4, Sum: 68.2]
[GC Worker End (ms): Min: 45233.8, Avg: 45234.1, Max: 45234.4, Diff: 0.6]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.0 ms]
[Other: 17.6 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 15.9 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.0 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.35 sys=0.00, real=0.02 secs]
45.454: [GC pause (G1 Evacuation Pause) (young), 0.0281340 secs]
[Parallel Time: 2.7 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 45454.3, Avg: 45455.3, Max: 45455.8, Diff: 1.6]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.5, Diff: 0.5, Sum: 2.6]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.6]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 0.5]
[Termination (ms): Min: 0.0, Avg: 0.8, Max: 1.0, Diff: 1.0, Sum: 64.7]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.7]
[GC Worker Total (ms): Min: 0.0, Avg: 0.8, Max: 1.7, Diff: 1.7, Sum: 69.2]
[GC Worker End (ms): Min: 45455.9, Avg: 45456.2, Max: 45456.5, Diff: 0.6]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 0.7 ms]
[Other: 24.8 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 23.7 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 0.8 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.26 sys=0.01, real=0.03 secs]
45.664: [GC pause (G1 Evacuation Pause) (young), 0.0286272 secs]
[Parallel Time: 3.2 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 45663.7, Avg: 45664.7, Max: 45665.4, Diff: 1.7]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, Sum: 2.3]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, 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: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 0.6]
[Termination (ms): Min: 0.1, Avg: 1.1, Max: 2.1, Diff: 2.0, Sum: 91.8]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 1.2]
[GC Worker Total (ms): Min: 0.2, Avg: 1.2, Max: 2.6, Diff: 2.4, Sum: 96.9]
[GC Worker End (ms): Min: 45665.5, Avg: 45665.9, Max: 45666.4, Diff: 0.9]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 0.6 ms]
[Other: 24.9 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 24.0 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 0.6 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 199.0M(199.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.21 sys=0.00, real=0.03 secs]
46.245: [GC pause (G1 Evacuation Pause) (young)(Elastic Heap concurrent cycle starts due to periodic uncommit), 0.1844255 secs]
[Parallel Time: 23.9 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 46244.8, Avg: 46255.9, Max: 46256.8, Diff: 12.0]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.4, Diff: 0.4, Sum: 0.7]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Processed Buffers: Min: 0, Avg: 0.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Termination (ms): Min: 0.0, Avg: 9.9, Max: 12.2, Diff: 12.2, Sum: 825.3]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.7]
[GC Worker Total (ms): Min: 0.0, Avg: 10.0, Max: 12.3, Diff: 12.2, Sum: 827.3]
[GC Worker End (ms): Min: 46256.8, Avg: 46265.9, Max: 46267.1, Diff: 10.3]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 95.6 ms]
[Other: 65.0 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 63.4 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 1.1 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 199.0M(199.0M)->0.0B(144.0M) Survivors: 1024.0K->1024.0K Heap: 200.2M(1000.0M)->1232.7K(945.0M)]
[Times: user=0.42 sys=0.01, real=0.19 secs]
[Elastic Heap concurrent thread: uncommit 56320K spent 0.007s(concurrent workers: 21) ]
46.793: [GC pause (G1 Evacuation Pause) (young)(Elastic Heap concurrent cycle ends)
, 0.4494172 secs]
[Parallel Time: 258.0 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 46793.6, Avg: 46826.3, Max: 46885.6, Diff: 92.0]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.3, Diff: 0.3, 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.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.3]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Termination (ms): Min: 65.4, Avg: 172.7, Max: 223.2, Diff: 157.9, Sum: 14336.5]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.5]
[GC Worker Total (ms): Min: 65.4, Avg: 172.8, Max: 223.2, Diff: 157.9, Sum: 14338.4]
[GC Worker End (ms): Min: 46951.0, Avg: 46999.1, Max: 47017.8, Diff: 66.8]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 1.1 ms]
[Other: 190.3 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 163.3 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 26.5 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 144.0M(144.0M)->0.0B(144.0M) Survivors: 1024.0K->1024.0K Heap: 145.2M(945.0M)->1232.7K(945.0M)]
[Times: user=0.18 sys=0.00, real=0.45 secs]
47.427: [GC pause (G1 Evacuation Pause) (young)(Elastic Heap concurrent cycle starts due to periodic uncommit), 0.0760818 secs]
[Parallel Time: 14.0 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 47426.8, Avg: 47428.9, Max: 47430.0, Diff: 3.1]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.7, Diff: 0.7, 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.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.3]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Termination (ms): Min: 0.0, Avg: 1.4, Max: 2.6, Diff: 2.6, Sum: 116.2]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.4]
[GC Worker Total (ms): Min: 0.0, Avg: 1.4, Max: 3.2, Diff: 3.1, Sum: 118.1]
[GC Worker End (ms): Min: 47430.0, Avg: 47430.3, Max: 47430.7, Diff: 0.7]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 0.8 ms]
[Other: 61.3 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 60.4 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 0.5 ms]
[Humongous Register: 0.1 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.1 ms]
[Eden: 144.0M(144.0M)->0.0B(144.0M) Survivors: 1024.0K->1024.0K Heap: 145.2M(945.0M)->1232.7K(945.0M)]
[Times: user=0.09 sys=0.01, real=0.07 secs]
[Elastic Heap concurrent thread: commit 56320K spent 0.019s(concurrent workers: 21) ]
47.746: [GC pause (G1 Evacuation Pause) (young)(Elastic Heap concurrent cycle ends)
, 0.1390314 secs]
[Parallel Time: 5.3 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 47746.6, Avg: 47748.8, Max: 47751.0, Diff: 4.4]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.5, Diff: 0.5, Sum: 1.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.0, Max: 1, Diff: 1, Sum: 1]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 0.6]
[Termination (ms): Min: 0.0, Avg: 2.4, Max: 4.3, Diff: 4.3, Sum: 201.0]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.5]
[GC Worker Total (ms): Min: 0.0, Avg: 2.5, Max: 4.9, Diff: 4.9, Sum: 204.4]
[GC Worker End (ms): Min: 47751.1, Avg: 47751.3, Max: 47751.5, Diff: 0.5]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 42.6 ms]
[Other: 91.1 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 37.2 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 53.5 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.1 ms]
[Eden: 144.0M(144.0M)->0.0B(199.0M) Survivors: 1024.0K->1024.0K Heap: 145.2M(1000.0M)->1232.7K(1000.0M)]
[Times: user=0.18 sys=0.01, real=0.13 secs]
rss100: 1163628
49.916: [GC pause (Elastic Heap Initiated GC) (young)(Elastic Heap concurrent cycle starts due to periodic uncommit), 0.1400979 secs]
[Parallel Time: 40.9 ms, GC Workers: 83]
[GC Worker Start (ms): Min: 49916.6, Avg: 49920.6, Max: 49922.0, Diff: 5.4]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.6, Diff: 0.6, 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.0, Max: 3, Diff: 3, Sum: 3]
[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.2]
[Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Object Copy (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.3]
[Termination (ms): Min: 0.0, Avg: 16.1, Max: 34.8, Diff: 34.8, Sum: 1336.1]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 83]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.4]
[GC Worker Total (ms): Min: 0.0, Avg: 16.1, Max: 34.8, Diff: 34.8, Sum: 1337.9]
[GC Worker End (ms): Min: 49922.0, Avg: 49936.8, Max: 49952.8, Diff: 30.8]
[Code Root Fixup: 0.0 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 53.0 ms]
[Other: 46.2 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 45.0 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 0.8 ms]
[Humongous Register: 0.0 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.2 ms]
[Eden: 146.0M(199.0M)->0.0B(101376.0K) Survivors: 1024.0K->1024.0K Heap: 146.2M(1000.0M)->1288.9K(900.0M)]
[Times: user=0.22 sys=0.00, real=0.14 secs]
[Elastic Heap concurrent thread: uncommit 100M spent 0.037s(concurrent workers: 21) ]
332038:
[GC.elastic_heap: in periodic uncommit mode]
[GC.elastic_heap: young generation commit percent 50, uncommitted memory 104857600 B]
Heap
garbage-first heap total 921600K, used 1288K [0x00000000c1800000, 0x00000000c1901c20, 0x0000000100000000)
region size 1024K, 2 young (2048K), 1 survivors (1024K)
Metaspace used 4976K, capacity 5134K, committed 5376K, reserved 1056768K
class space used 565K, capacity 643K, committed 768K, reserved 1048576K
STDERR:
stdout: [332038:
[GC.elastic_heap: in periodic uncommit mode]
[GC.elastic_heap: young generation commit percent 50, uncommitted memory 104857600 B]
];
stderr: []
exitValue = 0
java.lang.RuntimeException: '[GC.elastic_heap: young generation commit percent 100, uncommitted memory 0 B]' missing from stdout/stderr
at com.oracle.java.testlibrary.OutputAnalyzer.shouldContain(OutputAnalyzer.java:134)
at TestElasticHeapPeriodic.test(TestElasticHeapPeriodic.java:95)
at TestElasticHeapPeriodic.main(TestElasticHeapPeriodic.java:44)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:127)
at java.lang.Thread.run(Thread.java:855)
JavaTest Message: Test threw exception: java.lang.RuntimeException: '[GC.elastic_heap: young generation commit percent 100, uncommitted memory 0 B]' missing from stdout/stderr
JavaTest Message: shutting down test
STATUS:Failed.`main' threw exception: java.lang.RuntimeException: '[GC.elastic_heap: young generation commit percent 100, uncommitted memory 0 B]' missing from stdout/stderr
rerun:
cd /tmp/tone/run/jtreg/jt-work/hotspot_test/elastic-heap/TestElasticHeapPeriodic && \
DISPLAY=:7 \
HOME=/home/testUserForTone \
LANG=C \
LC_CTYPE=C.UTF-8 \
PATH=/bin:/usr/bin:/usr/sbin \
CLASSPATH=/tmp/tone/run/jtreg/jt-work/hotspot_test/classes/5/elastic-heap:/tmp/tone/run/jtreg/jdk-repo/hotspot/test/elastic-heap:/tmp/tone/run/jtreg/jt-work/hotspot_test/classes/5/testlibrary:/tmp/tone/run/jtreg/jdk-repo/hotspot/test/testlibrary:/tmp/tone/run/jtreg/binary-download/dragonwell-8.14.15/lib/tools.jar:/tmp/tone/run/jtreg/jtreg/lib/javatest.jar:/tmp/tone/run/jtreg/jtreg/lib/jtreg.jar \
/tmp/tone/run/jtreg/binary-download/dragonwell-8.14.15/bin/java \
-Dtest.vm.opts='-Djdk.lang.processReaperUseDefaultStackSize=true -Xmixed -ea -esa' \
-Dtest.tool.vm.opts='-J-Djdk.lang.processReaperUseDefaultStackSize=true -J-Xmixed -J-ea -J-esa' \
-Dtest.compiler.opts= \
-Dtest.java.opts= \
-Dtest.jdk=/tmp/tone/run/jtreg/binary-download/dragonwell-8.14.15 \
-Dcompile.jdk=/tmp/tone/run/jtreg/binary-download/dragonwell-8.14.15 \
-Dtest.timeout.factor=4.0 \
-Dtest.root=/tmp/tone/run/jtreg/jdk-repo/hotspot/test \
-Dtest.name=elastic-heap/TestElasticHeapPeriodic.java \
-Dtest.file=/tmp/tone/run/jtreg/jdk-repo/hotspot/test/elastic-heap/TestElasticHeapPeriodic.java \
-Dtest.src=/tmp/tone/run/jtreg/jdk-repo/hotspot/test/elastic-heap \
-Dtest.src.path=/tmp/tone/run/jtreg/jdk-repo/hotspot/test/elastic-heap:/tmp/tone/run/jtreg/jdk-repo/hotspot/test/testlibrary \
-Dtest.classes=/tmp/tone/run/jtreg/jt-work/hotspot_test/classes/5/elastic-heap \
-Dtest.class.path=/tmp/tone/run/jtreg/jt-work/hotspot_test/classes/5/elastic-heap:/tmp/tone/run/jtreg/jt-work/hotspot_test/classes/5/testlibrary \
-Djdk.lang.processReaperUseDefaultStackSize=true \
-Xmixed \
-ea \
-esa \
-XX:+UseG1GC \
-XX:+G1ElasticHeap \
-Xmx1000m \
-Xms1000m \
-XX:ElasticHeapPeriodicYGCIntervalMillis=400 \
-XX:ElasticHeapPeriodicUncommitStartupDelay=0 \
-Xmn200m \
-XX:G1HeapRegionSize=1m \
-XX:+AlwaysPreTouch \
-XX:ElasticHeapYGCIntervalMinMillis=50 \
-verbose:gc \
-XX:+PrintGCDetails \
-XX:+PrintGCTimeStamps \
com.sun.javatest.regtest.agent.MainWrapper /tmp/tone/run/jtreg/jt-work/hotspot_test/elastic-heap/TestElasticHeapPeriodic.d/main.0.jta
TEST RESULT: Failed. Execution failed: `main' threw exception: java.lang.RuntimeException: '[GC.elastic_heap: young generation commit percent 100, uncommitted memory 0 B]' missing from stdout/stderr
https://tone.aliyun-inc.com/ws/xesljfzh/test_result/152376?tab=2
重跑没有复现: