mihai-cazacu-adswizz edited a comment on issue #8063: The compaction task fails without errors URL: https://github.com/apache/incubator-druid/issues/8063#issuecomment-511104701 All I can see in the overlord's log is: ``` 2019-07-13T08:49:55,244 INFO [Curator-PathChildrenCache-1] org.apache.druid.indexing.overlord.RemoteTaskRunner - Worker[ec2-34-254-100-113.eu-west-1.compute.amazonaws.com:8091] wrote FAILED status for task [compact_datasource_2019-07-13T07:37:08.322Z] on [TaskLocation{host='ec2-34-254-100-113.eu-west-1.compute.amazonaws.com', port=8106, tlsPort=-1}] 2019-07-13T08:49:55,244 INFO [Curator-PathChildrenCache-1] org.apache.druid.indexing.overlord.RemoteTaskRunner - Worker[ec2-34-254-100-113.eu-west-1.compute.amazonaws.com:8091] completed task[compact_datasource_2019-07-13T07:37:08.322Z] with status[FAILED] 2019-07-13T08:49:55,244 INFO [Curator-PathChildrenCache-1] org.apache.druid.indexing.overlord.TaskQueue - Received FAILED status for task: compact_datasource_2019-07-13T07:37:08.322Z 2019-07-13T08:49:55,244 INFO [Curator-PathChildrenCache-1] org.apache.druid.indexing.overlord.RemoteTaskRunner - Shutdown [compact_datasource_2019-07-13T07:37:08.322Z] because: [notified status change from task] 2019-07-13T08:49:55,244 INFO [Curator-PathChildrenCache-1] org.apache.druid.indexing.overlord.RemoteTaskRunner - Cleaning up task[compact_datasource_2019-07-13T07:37:08.322Z] on worker[ec2-34-254-100-113.eu-west-1.compute.amazonaws.com:8091] 2019-07-13T08:49:55,246 INFO [Curator-PathChildrenCache-1] org.apache.druid.indexing.overlord.TaskLockbox - Removing task[compact_datasource_2019-07-13T07:37:08.322Z] from activeTasks 2019-07-13T08:49:55,246 INFO [Curator-PathChildrenCache-1] org.apache.druid.indexing.overlord.TaskLockbox - Removing task[compact_datasource_2019-07-13T07:37:08.322Z] from TaskLock[compact_datasource_2019-07-13T07:37:08.322Z] 2019-07-13T08:49:55,246 INFO [Curator-PathChildrenCache-1] org.apache.druid.indexing.overlord.TaskLockbox - TaskLock is now empty: TaskLock{type=EXCLUSIVE, groupId=compact_datasource_2019-07-13T07:37:08.322Z, dataSource=datasource, interval=2019-02-18T00:00:00.000Z/2019-03-01T00:00:00.000Z, version=2019-07-13T07:37:08.335Z, priority=25, revoked=false} 2019-07-13T08:49:55,249 INFO [Curator-PathChildrenCache-1] org.apache.druid.indexing.overlord.MetadataTaskStorage - Deleting TaskLock with id[41440]: TaskLock{type=EXCLUSIVE, groupId=compact_datasource_2019-07-13T07:37:08.322Z, dataSource=datasource, interval=2019-02-18T00:00:00.000Z/2019-03-01T00:00:00.000Z, version=2019-07-13T07:37:08.335Z, priority=25, revoked=false} 2019-07-13T08:49:55,257 INFO [Curator-PathChildrenCache-1] org.apache.druid.indexing.overlord.MetadataTaskStorage - Updating task compact_datasource_2019-07-13T07:37:08.322Z to status: TaskStatus{id=compact_datasource_2019-07-13T07:37:08.322Z, status=FAILED, duration=4366588, errorMsg=null} 2019-07-13T08:49:55,264 INFO [Curator-PathChildrenCache-1] org.apache.druid.indexing.overlord.TaskQueue - Task done: AbstractTask{id='compact_datasource_2019-07-13T07:37:08.322Z', groupId='compact_datasource_2019-07-13T07:37:08.322Z', taskResource=TaskResource{availabilityGroup='compact_datasource_2019-07-13T07:37:08.322Z', requiredCapacity=1}, dataSource='datasource', context={}} 2019-07-13T08:49:55,264 INFO [Curator-PathChildrenCache-1] org.apache.druid.indexing.overlord.TaskQueue - Task FAILED: AbstractTask{id='compact_datasource_2019-07-13T07:37:08.322Z', groupId='compact_datasource_2019-07-13T07:37:08.322Z', taskResource=TaskResource{availabilityGroup='compact_datasource_2019-07-13T07:37:08.322Z', requiredCapacity=1}, dataSource='datasource', context={}} (4366588 run duration) 2019-07-13T08:49:55,264 INFO [Curator-PathChildrenCache-1] org.apache.druid.indexing.overlord.TaskRunnerUtils - Task [compact_datasource_2019-07-13T07:37:08.322Z] status changed to [FAILED]. 2019-07-13T08:49:55,264 INFO [Curator-PathChildrenCache-1] org.apache.druid.indexing.overlord.RemoteTaskRunner - Task[compact_datasource_2019-07-13T07:37:08.322Z] went bye bye. ``` and the last lines from peon's log: ``` druid/2019-02-28T23:00:00.000Z_2019-03-01T00:00:00.000Z/2019-07-10T15:27:33.889Z/59/index.zip] to outDir[/mnt/druid/persistent/task/compact_datasource_2019-07-13T07:37:08.322Z/work/datasource/2019-02-28T23:00:00.000Z_2019-03-01T00:00:00.000Z/2019-07-10T15:27:33.889Z/59] 2019-07-13T08:14:08,739 INFO [task-runner-0-priority-0] org.apache.druid.storage.s3.S3DataSegmentPuller - Loaded 1423122 bytes from [s3://host/druid-dev-1/segments/datasource/2019-02-28T23:00:00.000Z_2019-03-01T00:00:00.000Z/2019-07-10T15:27:33.889Z/59/index.zip] to [/mnt/druid/persistent/task/compact_datasource_2019-07-13T07:37:08.322Z/work/datasource/2019-02-28T23:00:00.000Z_2019-03-01T00:00:00.000Z/2019-07-10T15:27:33.889Z/59] 3039.248: [GC pause (G1 Evacuation Pause) (young) (to-space exhausted), 9.4105376 secs] [Parallel Time: 5411.9 ms, GC Workers: 4] [GC Worker Start (ms): Min: 3039248.5, Avg: 3039248.5, Max: 3039248.5, Diff: 0.1] [Ext Root Scanning (ms): Min: 0.9, Avg: 1.1, Max: 1.3, Diff: 0.4, Sum: 4.3] [Update RS (ms): Min: 10.4, Avg: 10.7, Max: 10.9, Diff: 0.4, Sum: 42.7] [Processed Buffers: Min: 105, Avg: 128.8, Max: 156, Diff: 51, Sum: 515] [Scan RS (ms): Min: 1.3, Avg: 1.5, Max: 1.7, Diff: 0.4, Sum: 6.0] [Code Root Scanning (ms): Min: 0.3, Avg: 0.3, Max: 0.4, Diff: 0.1, Sum: 1.2] [Object Copy (ms): Min: 5397.8, Avg: 5398.0, Max: 5398.1, Diff: 0.3, Sum: 21592.0] [Termination (ms): Min: 0.0, Avg: 0.2, Max: 0.3, Diff: 0.3, Sum: 0.7] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.0, Sum: 0.2] [GC Worker Total (ms): Min: 5411.7, Avg: 5411.8, Max: 5411.8, Diff: 0.1, Sum: 21647.1] [GC Worker End (ms): Min: 3044660.3, Avg: 3044660.3, Max: 3044660.3, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 1.0 ms] [Other: 3997.6 ms] [Evacuation Failure: 3899.6 ms] [Choose CSet: 0.0 ms] [Ref Proc: 88.3 ms] [Ref Enq: 0.3 ms] [Redirty Cards: 5.8 ms] [Humongous Register: 0.2 ms] [Humongous Reclaim: 1.6 ms] [Free CSet: 1.0 ms] [Eden: 5522.0M(5522.0M)->0.0B(608.0M) Survivors: 6144.0K->692.0M Heap: 7529.4M(9216.0M)->6992.0M(9216.0M)] [Times: user=21.24 sys=0.14, real=9.41 secs] 3049.295: [GC pause (G1 Evacuation Pause) (young) (initial-mark), 1.4852109 secs] [Parallel Time: 1475.7 ms, GC Workers: 4] [GC Worker Start (ms): Min: 3049295.4, Avg: 3049295.5, Max: 3049295.5, Diff: 0.1] [Ext Root Scanning (ms): Min: 1.5, Avg: 1.6, Max: 1.7, Diff: 0.2, Sum: 6.4] [Update RS (ms): Min: 249.3, Avg: 249.4, Max: 249.4, Diff: 0.1, Sum: 997.4] [Processed Buffers: Min: 1002, Avg: 1047.0, Max: 1071, Diff: 69, Sum: 4188] [Scan RS (ms): Min: 699.7, Avg: 699.8, Max: 699.9, Diff: 0.2, Sum: 2799.3] [Code Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.5] [Object Copy (ms): Min: 524.4, Avg: 524.5, Max: 524.6, Diff: 0.2, Sum: 2098.1] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2] [GC Worker Total (ms): Min: 1475.4, Avg: 1475.5, Max: 1475.5, Diff: 0.1, Sum: 5901.9] [GC Worker End (ms): Min: 3050770.9, Avg: 3050770.9, Max: 3050771.0, Diff: 0.1] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.5 ms] [Other: 9.0 ms] [Choose CSet: 0.0 ms] [Ref Proc: 0.5 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 6.0 ms] [Humongous Register: 0.0 ms] [Humongous Reclaim: 0.2 ms] [Free CSet: 0.6 ms] [Eden: 4096.0K(2048.0K)->0.0B(454.0M) Survivors: 692.0M->6144.0K Heap: 6996.0M(9216.0M)->6815.0M(9216.0M)] [Times: user=5.82 sys=0.00, real=1.48 secs] 3050.780: [GC concurrent-root-region-scan-start] 3050.783: [GC concurrent-root-region-scan-end, 0.0031196 secs] 3050.783: [GC concurrent-mark-start] 3055.081: [GC concurrent-mark-end, 4.2973773 secs] 3055.081: [GC remark 3055.081: [Finalize Marking, 0.0001662 secs] 3055.081: [GC ref-proc, 0.0064131 secs] 3055.088: [Unloading, 0.0141108 secs], 0.0334054 secs] [Times: user=0.09 sys=0.00, real=0.04 secs] 3055.115: [GC cleanup 6837M->3465M(9216M), 0.0073388 secs] [Times: user=0.02 sys=0.00, real=0.00 secs] 3055.123: [GC concurrent-cleanup-start] 3055.130: [GC concurrent-cleanup-end, 0.0074491 secs] 3112.818: [GC pause (G1 Evacuation Pause) (young), 0.1533583 secs] [Parallel Time: 149.6 ms, GC Workers: 4] [GC Worker Start (ms): Min: 3112818.7, Avg: 3112818.7, Max: 3112818.7, Diff: 0.1] [Ext Root Scanning (ms): Min: 0.9, Avg: 1.1, Max: 1.3, Diff: 0.4, Sum: 4.2] [Update RS (ms): Min: 24.9, Avg: 24.9, Max: 25.0, Diff: 0.2, Sum: 99.7] [Processed Buffers: Min: 61, Avg: 81.5, Max: 92, Diff: 31, Sum: 326] [Scan RS (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.3] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [Object Copy (ms): Min: 123.2, Avg: 123.3, Max: 123.4, Diff: 0.1, Sum: 493.2] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2] [GC Worker Total (ms): Min: 149.4, Avg: 149.4, Max: 149.4, Diff: 0.0, Sum: 597.7] [GC Worker End (ms): Min: 3112968.1, Avg: 3112968.1, Max: 3112968.2, Diff: 0.1] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.2 ms] [Other: 3.6 ms] [Choose CSet: 0.0 ms] [Ref Proc: 1.9 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.4 ms] [Humongous Register: 0.1 ms] [Humongous Reclaim: 0.1 ms] [Free CSet: 0.3 ms] [Eden: 454.0M(454.0M)->0.0B(402.0M) Survivors: 6144.0K->58.0M Heap: 3897.0M(9216.0M)->3583.0M(9216.0M)] [Times: user=0.58 sys=0.00, real=0.16 secs] 3170.486: [GC pause (G1 Evacuation Pause) (mixed), 0.2297910 secs] [Parallel Time: 225.3 ms, GC Workers: 4] [GC Worker Start (ms): Min: 3170486.4, Avg: 3170486.4, Max: 3170486.4, Diff: 0.1] [Ext Root Scanning (ms): Min: 0.8, Avg: 1.0, Max: 1.4, Diff: 0.6, Sum: 4.0] [Update RS (ms): Min: 16.7, Avg: 16.9, Max: 17.1, Diff: 0.5, Sum: 67.7] [Processed Buffers: Min: 51, Avg: 72.2, Max: 84, Diff: 33, Sum: 289] [Scan RS (ms): Min: 8.4, Avg: 8.5, Max: 8.7, Diff: 0.2, Sum: 34.2] [Code Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.3] [Object Copy (ms): Min: 198.7, Avg: 198.7, Max: 198.7, Diff: 0.1, Sum: 794.8] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Termination Attempts: Min: 1, Avg: 1.8, Max: 3, Diff: 2, Sum: 7] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 225.2, Avg: 225.2, Max: 225.3, Diff: 0.1, Sum: 901.0] [GC Worker End (ms): Min: 3170711.7, Avg: 3170711.7, Max: 3170711.7, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.4 ms] [Other: 4.1 ms] [Choose CSet: 0.1 ms] [Ref Proc: 2.0 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.6 ms] [Humongous Register: 0.1 ms] [Humongous Reclaim: 0.1 ms] [Free CSet: 0.6 ms] [Eden: 402.0M(402.0M)->0.0B(402.0M) Survivors: 58.0M->58.0M Heap: 3985.0M(9216.0M)->3467.0M(9216.0M)] [Times: user=0.88 sys=0.00, real=0.23 secs] 3230.344: [GC pause (G1 Evacuation Pause) (mixed), 0.2608476 secs] [Parallel Time: 255.2 ms, GC Workers: 4] [GC Worker Start (ms): Min: 3230344.7, Avg: 3230345.5, Max: 3230347.8, Diff: 3.1] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.9, Max: 1.3, Diff: 1.3, Sum: 3.8] [Update RS (ms): Min: 14.3, Avg: 15.6, Max: 16.1, Diff: 1.8, Sum: 62.5] [Processed Buffers: Min: 36, Avg: 69.0, Max: 101, Diff: 65, Sum: 276] [Scan RS (ms): Min: 0.5, Avg: 0.6, Max: 0.6, Diff: 0.2, Sum: 2.3] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [Object Copy (ms): Min: 237.1, Avg: 237.1, Max: 237.2, Diff: 0.2, Sum: 948.5] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Termination Attempts: Min: 1, Avg: 1.2, Max: 2, Diff: 1, Sum: 5] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 252.0, Avg: 254.3, Max: 255.1, Diff: 3.1, Sum: 1017.3] [GC Worker End (ms): Min: 3230599.8, Avg: 3230599.8, Max: 3230599.8, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.5 ms] [Other: 5.1 ms] [Choose CSet: 0.1 ms] [Ref Proc: 2.0 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 1.3 ms] [Humongous Register: 0.2 ms] [Humongous Reclaim: 0.2 ms] [Free CSet: 0.6 ms] [Eden: 402.0M(402.0M)->0.0B(402.0M) Survivors: 58.0M->58.0M Heap: 3869.0M(9216.0M)->3396.0M(9216.0M)] [Times: user=1.00 sys=0.00, real=0.26 secs] 3286.315: [GC pause (G1 Evacuation Pause) (mixed), 0.2685836 secs] [Parallel Time: 264.0 ms, GC Workers: 4] [GC Worker Start (ms): Min: 3286315.9, Avg: 3286316.5, Max: 3286318.4, Diff: 2.5] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.9, Max: 1.3, Diff: 1.2, Sum: 3.4] [Update RS (ms): Min: 12.8, Avg: 13.8, Max: 14.2, Diff: 1.5, Sum: 55.1] [Processed Buffers: Min: 50, Avg: 63.8, Max: 82, Diff: 32, Sum: 255] [Scan RS (ms): Min: 1.3, Avg: 1.4, Max: 1.5, Diff: 0.2, Sum: 5.8] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [Object Copy (ms): Min: 247.0, Avg: 247.1, Max: 247.2, Diff: 0.2, Sum: 988.4] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 261.4, Avg: 263.2, Max: 263.9, Diff: 2.5, Sum: 1053.0] [GC Worker End (ms): Min: 3286579.7, Avg: 3286579.8, Max: 3286579.8, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.3 ms] [Other: 4.3 ms] [Choose CSet: 0.1 ms] [Ref Proc: 1.9 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.7 ms] [Humongous Register: 0.2 ms] [Humongous Reclaim: 0.1 ms] [Free CSet: 0.6 ms] [Eden: 402.0M(402.0M)->0.0B(402.0M) Survivors: 58.0M->58.0M Heap: 3798.0M(9216.0M)->3327.0M(9216.0M)] [Times: user=1.01 sys=0.00, real=0.27 secs] 3341.782: [GC pause (G1 Evacuation Pause) (mixed), 0.2609102 secs] [Parallel Time: 256.3 ms, GC Workers: 4] [GC Worker Start (ms): Min: 3341782.4, Avg: 3341782.7, Max: 3341783.8, Diff: 1.4] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.9, Max: 1.3, Diff: 1.3, Sum: 3.7] [Update RS (ms): Min: 12.3, Avg: 12.4, Max: 12.5, Diff: 0.2, Sum: 49.4] [Processed Buffers: Min: 38, Avg: 61.0, Max: 77, Diff: 39, Sum: 244] [Scan RS (ms): Min: 2.9, Avg: 2.9, Max: 3.1, Diff: 0.2, Sum: 11.7] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [Object Copy (ms): Min: 239.4, Avg: 239.5, Max: 239.6, Diff: 0.2, Sum: 958.1] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.0, Sum: 0.2] [GC Worker Total (ms): Min: 254.8, Avg: 255.8, Max: 256.2, Diff: 1.4, Sum: 1023.2] [GC Worker End (ms): Min: 3342038.5, Avg: 3342038.6, Max: 3342038.6, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.3 ms] [Other: 4.3 ms] [Choose CSet: 0.1 ms] [Ref Proc: 2.0 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.7 ms] [Humongous Register: 0.2 ms] [Humongous Reclaim: 0.1 ms] [Free CSet: 0.6 ms] [Eden: 402.0M(402.0M)->0.0B(402.0M) Survivors: 58.0M->58.0M Heap: 3729.0M(9216.0M)->3258.0M(9216.0M)] [Times: user=1.01 sys=0.00, real=0.26 secs] 3402.742: [GC pause (G1 Evacuation Pause) (mixed), 0.2854773 secs] [Parallel Time: 280.7 ms, GC Workers: 4] [GC Worker Start (ms): Min: 3402743.0, Avg: 3402744.2, Max: 3402747.8, Diff: 4.8] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.9, Max: 1.3, Diff: 1.3, Sum: 3.6] [Update RS (ms): Min: 7.9, Avg: 10.6, Max: 11.6, Diff: 3.7, Sum: 42.6] [Processed Buffers: Min: 33, Avg: 56.0, Max: 97, Diff: 64, Sum: 224] [Scan RS (ms): Min: 2.8, Avg: 3.0, Max: 3.1, Diff: 0.3, Sum: 12.2] [Code Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.2] [Object Copy (ms): Min: 264.6, Avg: 264.7, Max: 264.9, Diff: 0.2, Sum: 1058.9] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 275.8, Avg: 279.4, Max: 280.6, Diff: 4.8, Sum: 1117.6] [GC Worker End (ms): Min: 3403023.6, Avg: 3403023.6, Max: 3403023.6, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.3 ms] [Other: 4.4 ms] [Choose CSet: 0.1 ms] [Ref Proc: 1.9 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.9 ms] [Humongous Register: 0.2 ms] [Humongous Reclaim: 0.1 ms] [Free CSet: 0.6 ms] [Eden: 402.0M(402.0M)->0.0B(402.0M) Survivors: 58.0M->58.0M Heap: 3660.0M(9216.0M)->3189.0M(9216.0M)] [Times: user=1.01 sys=0.00, real=0.29 secs] 3456.812: [GC pause (G1 Evacuation Pause) (mixed), 0.2252140 secs] [Parallel Time: 220.9 ms, GC Workers: 4] [GC Worker Start (ms): Min: 3456812.3, Avg: 3456812.3, Max: 3456812.4, Diff: 0.1] [Ext Root Scanning (ms): Min: 0.8, Avg: 1.0, Max: 1.3, Diff: 0.5, Sum: 3.9] [Update RS (ms): Min: 9.7, Avg: 9.9, Max: 10.1, Diff: 0.4, Sum: 39.7] [Processed Buffers: Min: 30, Avg: 52.0, Max: 89, Diff: 59, Sum: 208] [Scan RS (ms): Min: 2.3, Avg: 2.4, Max: 2.5, Diff: 0.1, Sum: 9.6] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [Object Copy (ms): Min: 207.4, Avg: 207.5, Max: 207.5, Diff: 0.1, Sum: 829.8] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4] [GC Worker Other (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.2] [GC Worker Total (ms): Min: 220.8, Avg: 220.8, Max: 220.8, Diff: 0.1, Sum: 883.2] [GC Worker End (ms): Min: 3457033.1, Avg: 3457033.1, Max: 3457033.2, Diff: 0.1] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.2 ms] [Other: 4.0 ms] [Choose CSet: 0.1 ms] [Ref Proc: 2.0 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.6 ms] [Humongous Register: 0.1 ms] [Humongous Reclaim: 0.1 ms] [Free CSet: 0.5 ms] [Eden: 402.0M(402.0M)->0.0B(402.0M) Survivors: 58.0M->58.0M Heap: 3591.0M(9216.0M)->3199.0M(9216.0M)] [Times: user=0.88 sys=0.00, real=0.22 secs] 3515.150: [GC pause (G1 Evacuation Pause) (young), 0.1757094 secs] [Parallel Time: 171.9 ms, GC Workers: 4] [GC Worker Start (ms): Min: 3515150.7, Avg: 3515151.0, Max: 3515151.8, Diff: 1.1] [Ext Root Scanning (ms): Min: 0.2, Avg: 1.0, Max: 1.3, Diff: 1.1, Sum: 4.0] [Update RS (ms): Min: 11.0, Avg: 11.1, Max: 11.2, Diff: 0.2, Sum: 44.3] [Processed Buffers: Min: 32, Avg: 56.5, Max: 74, Diff: 42, Sum: 226] [Scan RS (ms): Min: 0.2, Avg: 0.3, Max: 0.4, Diff: 0.2, 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: 159.1, Avg: 159.1, Max: 159.2, Diff: 0.1, Sum: 636.5] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4] [GC Worker Other (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.3] [GC Worker Total (ms): Min: 170.7, Avg: 171.6, Max: 171.9, Diff: 1.1, Sum: 686.2] [GC Worker End (ms): Min: 3515322.5, Avg: 3515322.5, Max: 3515322.6, Diff: 0.1] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.2 ms] [Other: 3.6 ms] [Choose CSet: 0.0 ms] [Ref Proc: 2.0 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.4 ms] [Humongous Register: 0.2 ms] [Humongous Reclaim: 0.1 ms] [Free CSet: 0.3 ms] [Eden: 402.0M(402.0M)->0.0B(402.0M) Survivors: 58.0M->58.0M Heap: 3601.0M(9216.0M)->3332.0M(9216.0M)] [Times: user=0.68 sys=0.00, real=0.18 secs] 3567.690: [GC pause (G1 Evacuation Pause) (young), 0.1737026 secs] [Parallel Time: 169.9 ms, GC Workers: 4] [GC Worker Start (ms): Min: 3567689.9, Avg: 3567690.0, Max: 3567690.0, Diff: 0.1] [Ext Root Scanning (ms): Min: 1.3, Avg: 1.4, Max: 1.8, Diff: 0.5, Sum: 5.7] [Update RS (ms): Min: 9.3, Avg: 9.7, Max: 9.9, Diff: 0.6, Sum: 38.8] [Processed Buffers: Min: 30, Avg: 42.0, Max: 57, Diff: 27, Sum: 168] [Scan RS (ms): Min: 0.3, Avg: 0.3, Max: 0.4, Diff: 0.1, Sum: 1.2] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 158.2, Avg: 158.3, Max: 158.4, Diff: 0.2, Sum: 633.2] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 169.7, Avg: 169.8, Max: 169.8, Diff: 0.1, Sum: 679.0] [GC Worker End (ms): Min: 3567859.7, Avg: 3567859.7, Max: 3567859.7, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.2 ms] [Other: 3.6 ms] [Choose CSet: 0.0 ms] [Ref Proc: 2.0 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.5 ms] [Humongous Register: 0.2 ms] [Humongous Reclaim: 0.1 ms] [Free CSet: 0.3 ms] [Eden: 402.0M(402.0M)->0.0B(402.0M) Survivors: 58.0M->58.0M Heap: 3734.0M(9216.0M)->3465.0M(9216.0M)] [Times: user=0.67 sys=0.01, real=0.17 secs] 3626.608: [GC pause (G1 Evacuation Pause) (young), 0.1757278 secs] [Parallel Time: 172.0 ms, GC Workers: 4] [GC Worker Start (ms): Min: 3626608.2, Avg: 3626609.0, Max: 3626611.2, Diff: 3.0] [Ext Root Scanning (ms): Min: 0.0, Avg: 1.0, Max: 1.4, Diff: 1.4, Sum: 3.9] [Update RS (ms): Min: 9.4, Avg: 10.6, Max: 11.1, Diff: 1.7, Sum: 42.4] [Processed Buffers: Min: 34, Avg: 53.5, Max: 71, Diff: 37, Sum: 214] [Scan RS (ms): Min: 0.2, Avg: 0.3, Max: 0.4, Diff: 0.1, Sum: 1.3] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [Object Copy (ms): Min: 159.1, Avg: 159.2, Max: 159.3, Diff: 0.2, Sum: 636.8] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2] [GC Worker Total (ms): Min: 169.0, Avg: 171.1, Max: 171.9, Diff: 2.9, Sum: 684.6] [GC Worker End (ms): Min: 3626780.1, Avg: 3626780.1, Max: 3626780.1, Diff: 0.1] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.1 ms] [Other: 3.6 ms] [Choose CSet: 0.0 ms] [Ref Proc: 2.0 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.4 ms] [Humongous Register: 0.2 ms] [Humongous Reclaim: 0.1 ms] [Free CSet: 0.3 ms] [Eden: 402.0M(402.0M)->0.0B(402.0M) Survivors: 58.0M->58.0M Heap: 3867.0M(9216.0M)->3597.0M(9216.0M)] [Times: user=0.67 sys=0.00, real=0.17 secs] 3687.669: [GC pause (G1 Evacuation Pause) (young), 0.1743503 secs] [Parallel Time: 170.5 ms, GC Workers: 4] [GC Worker Start (ms): Min: 3687669.8, Avg: 3687670.3, Max: 3687671.8, Diff: 2.0] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.9, Max: 1.3, Diff: 1.3, Sum: 3.6] [Update RS (ms): Min: 9.0, Avg: 9.6, Max: 9.8, Diff: 0.9, Sum: 38.3] [Processed Buffers: Min: 28, Avg: 51.0, Max: 69, Diff: 41, Sum: 204] [Scan RS (ms): Min: 0.2, Avg: 0.3, Max: 0.3, 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: 159.0, Avg: 159.1, Max: 159.2, Diff: 0.2, Sum: 636.2] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2] [GC Worker Total (ms): Min: 168.3, Avg: 169.9, Max: 170.4, Diff: 2.1, Sum: 679.4] [GC Worker End (ms): Min: 3687840.1, Avg: 3687840.2, Max: 3687840.2, Diff: 0.1] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.2 ms] [Other: 3.7 ms] [Choose CSet: 0.0 ms] [Ref Proc: 2.0 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.4 ms] [Humongous Register: 0.2 ms] [Humongous Reclaim: 0.1 ms] [Free CSet: 0.3 ms] [Eden: 402.0M(402.0M)->0.0B(402.0M) Survivors: 58.0M->58.0M Heap: 3999.0M(9216.0M)->3730.0M(9216.0M)] [Times: user=0.68 sys=0.00, real=0.18 secs] 3743.872: [GC pause (G1 Evacuation Pause) (young), 0.1742967 secs] [Parallel Time: 170.6 ms, GC Workers: 4] [GC Worker Start (ms): Min: 3743872.3, Avg: 3743872.3, Max: 3743872.3, Diff: 0.1] [Ext Root Scanning (ms): Min: 0.9, Avg: 1.0, Max: 1.2, Diff: 0.3, Sum: 4.1] [Update RS (ms): Min: 10.3, Avg: 10.4, Max: 10.5, Diff: 0.2, Sum: 41.8] [Processed Buffers: Min: 40, Avg: 53.0, Max: 69, Diff: 29, Sum: 212] [Scan RS (ms): Min: 0.1, Avg: 0.3, Max: 0.4, Diff: 0.3, Sum: 1.2] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 158.6, Avg: 158.6, Max: 158.7, Diff: 0.1, Sum: 634.5] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.0, Sum: 0.2] [GC Worker Total (ms): Min: 170.4, Avg: 170.4, Max: 170.5, Diff: 0.1, Sum: 681.8] [GC Worker End (ms): Min: 3744042.7, Avg: 3744042.7, Max: 3744042.7, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.2 ms] [Other: 3.6 ms] [Choose CSet: 0.0 ms] [Ref Proc: 1.9 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.4 ms] [Humongous Register: 0.2 ms] [Humongous Reclaim: 0.1 ms] [Free CSet: 0.3 ms] [Eden: 402.0M(402.0M)->0.0B(402.0M) Survivors: 58.0M->58.0M Heap: 4132.0M(9216.0M)->3862.0M(9216.0M)] [Times: user=0.67 sys=0.00, real=0.17 secs] 3802.585: [GC pause (G1 Evacuation Pause) (young), 0.1746780 secs] [Parallel Time: 170.7 ms, GC Workers: 4] [GC Worker Start (ms): Min: 3802585.5, Avg: 3802586.0, Max: 3802587.2, Diff: 1.7] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.9, Max: 1.3, Diff: 1.3, Sum: 3.6] [Update RS (ms): Min: 9.1, Avg: 9.3, Max: 9.4, Diff: 0.3, Sum: 37.3] [Processed Buffers: Min: 28, Avg: 49.5, Max: 65, Diff: 37, Sum: 198] [Scan RS (ms): Min: 0.2, Avg: 0.3, Max: 0.4, Diff: 0.1, Sum: 1.3] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 159.5, Avg: 159.5, Max: 159.7, Diff: 0.2, Sum: 638.2] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 168.9, Avg: 170.1, Max: 170.6, Diff: 1.7, Sum: 680.6] [GC Worker End (ms): Min: 3802756.1, Avg: 3802756.1, Max: 3802756.1, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.2 ms] [Other: 3.8 ms] [Choose CSet: 0.0 ms] [Ref Proc: 2.0 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.6 ms] [Humongous Register: 0.2 ms] [Humongous Reclaim: 0.2 ms] [Free CSet: 0.3 ms] [Eden: 402.0M(402.0M)->0.0B(402.0M) Survivors: 58.0M->58.0M Heap: 4264.0M(9216.0M)->3994.0M(9216.0M)] [Times: user=0.68 sys=0.00, real=0.17 secs] 3861.819: [GC pause (G1 Evacuation Pause) (young), 0.1753251 secs] [Parallel Time: 171.3 ms, GC Workers: 4] [GC Worker Start (ms): Min: 3861818.9, Avg: 3861818.9, Max: 3861819.0, Diff: 0.1] [Ext Root Scanning (ms): Min: 0.8, Avg: 1.0, Max: 1.2, Diff: 0.5, Sum: 3.9] [Update RS (ms): Min: 10.5, Avg: 10.5, Max: 10.5, Diff: 0.1, Sum: 42.0] [Processed Buffers: Min: 34, Avg: 52.8, Max: 62, Diff: 28, Sum: 211] [Scan RS (ms): Min: 0.2, Avg: 0.3, Max: 0.3, Diff: 0.2, Sum: 1.0] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Object Copy (ms): Min: 159.3, Avg: 159.4, Max: 159.5, Diff: 0.2, Sum: 637.5] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 171.1, Avg: 171.2, Max: 171.2, Diff: 0.0, Sum: 684.6] [GC Worker End (ms): Min: 3861990.1, Avg: 3861990.1, Max: 3861990.1, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.2 ms] [Other: 3.8 ms] [Choose CSet: 0.0 ms] [Ref Proc: 2.2 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.4 ms] [Humongous Register: 0.2 ms] [Humongous Reclaim: 0.1 ms] [Free CSet: 0.3 ms] [Eden: 402.0M(402.0M)->0.0B(402.0M) Survivors: 58.0M->58.0M Heap: 4396.0M(9216.0M)->4126.0M(9216.0M)] [Times: user=0.68 sys=0.00, real=0.18 secs] 3923.421: [GC pause (G1 Evacuation Pause) (young), 0.1733337 secs] [Parallel Time: 169.4 ms, GC Workers: 4] [GC Worker Start (ms): Min: 3923421.3, Avg: 3923421.3, Max: 3923421.4, Diff: 0.1] [Ext Root Scanning (ms): Min: 0.9, Avg: 1.0, Max: 1.2, Diff: 0.4, Sum: 4.0] [Update RS (ms): Min: 9.4, Avg: 9.4, Max: 9.4, Diff: 0.0, Sum: 37.6] [Processed Buffers: Min: 29, Avg: 48.0, Max: 71, Diff: 42, Sum: 192] [Scan RS (ms): Min: 0.2, Avg: 0.3, Max: 0.4, Diff: 0.2, Sum: 1.2] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [Object Copy (ms): Min: 158.4, Avg: 158.5, Max: 158.5, Diff: 0.1, Sum: 633.8] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.2] [GC Worker Total (ms): Min: 169.2, Avg: 169.2, Max: 169.3, Diff: 0.1, Sum: 676.9] [GC Worker End (ms): Min: 3923590.5, Avg: 3923590.6, Max: 3923590.6, Diff: 0.1] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.2 ms] [Other: 3.8 ms] [Choose CSet: 0.0 ms] [Ref Proc: 2.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.6 ms] [Humongous Register: 0.2 ms] [Humongous Reclaim: 0.1 ms] [Free CSet: 0.3 ms] [Eden: 402.0M(402.0M)->0.0B(402.0M) Survivors: 58.0M->58.0M Heap: 4528.0M(9216.0M)->4258.0M(9216.0M)] [Times: user=0.67 sys=0.00, real=0.18 secs] 3980.303: [GC pause (G1 Evacuation Pause) (young) (initial-mark), 0.1752533 secs] [Parallel Time: 170.9 ms, GC Workers: 4] [GC Worker Start (ms): Min: 3980304.1, Avg: 3980304.3, Max: 3980304.8, Diff: 0.7] [Ext Root Scanning (ms): Min: 0.8, Avg: 1.4, Max: 1.6, Diff: 0.8, Sum: 5.5] [Update RS (ms): Min: 10.1, Avg: 10.1, Max: 10.2, Diff: 0.2, Sum: 40.5] [Processed Buffers: Min: 35, Avg: 52.5, Max: 63, Diff: 28, Sum: 210] [Scan RS (ms): Min: 0.2, Avg: 0.3, Max: 0.4, Diff: 0.1, Sum: 1.2] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [Object Copy (ms): Min: 158.5, Avg: 158.7, Max: 158.8, Diff: 0.2, Sum: 634.8] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4] [GC Worker Other (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.2] [GC Worker Total (ms): Min: 170.1, Avg: 170.6, Max: 170.7, Diff: 0.6, Sum: 682.3] [GC Worker End (ms): Min: 3980474.8, Avg: 3980474.8, Max: 3980474.9, Diff: 0.1] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.1 ms] [Other: 4.2 ms] [Choose CSet: 0.0 ms] [Ref Proc: 1.9 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.4 ms] [Humongous Register: 0.1 ms] [Humongous Reclaim: 0.1 ms] [Free CSet: 0.3 ms] [Eden: 402.0M(402.0M)->0.0B(402.0M) Survivors: 58.0M->58.0M Heap: 4660.0M(9216.0M)->4390.0M(9216.0M)] [Times: user=0.68 sys=0.00, real=0.18 secs] 3980.479: [GC concurrent-root-region-scan-start] 3980.550: [GC concurrent-root-region-scan-end, 0.0713719 secs] 3980.550: [GC concurrent-mark-start] 3987.582: [GC concurrent-mark-end, 7.0321664 secs] 3987.686: [GC remark 3987.686: [Finalize Marking, 0.0004148 secs] 3987.686: [GC ref-proc, 0.0056028 secs] 3987.692: [Unloading, 0.0114048 secs], 0.0344139 secs] [Times: user=0.11 sys=0.00, real=0.03 secs] 3987.729: [GC cleanup 4437M->4421M(9216M), 0.0065576 secs] [Times: user=0.02 sys=0.00, real=0.01 secs] 3987.736: [GC concurrent-cleanup-start] 3987.736: [GC concurrent-cleanup-end, 0.0000310 secs] 4043.512: [GC pause (G1 Evacuation Pause) (young), 0.1739712 secs] [Parallel Time: 170.0 ms, GC Workers: 4] [GC Worker Start (ms): Min: 4043512.5, Avg: 4043513.1, Max: 4043514.7, Diff: 2.2] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.9, Max: 1.3, Diff: 1.3, Sum: 3.7] [Update RS (ms): Min: 9.5, Avg: 10.0, Max: 10.1, Diff: 0.7, Sum: 39.8] [Processed Buffers: Min: 28, Avg: 48.8, Max: 80, Diff: 52, Sum: 195] [Scan RS (ms): Min: 0.1, Avg: 0.2, Max: 0.3, Diff: 0.2, 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: 158.1, Avg: 158.2, Max: 158.3, Diff: 0.2, Sum: 632.8] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 167.8, Avg: 169.4, Max: 169.9, Diff: 2.1, Sum: 677.5] [GC Worker End (ms): Min: 4043682.4, Avg: 4043682.5, Max: 4043682.5, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.2 ms] [Other: 3.8 ms] [Choose CSet: 0.0 ms] [Ref Proc: 2.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.5 ms] [Humongous Register: 0.2 ms] [Humongous Reclaim: 0.1 ms] [Free CSet: 0.3 ms] [Eden: 402.0M(402.0M)->0.0B(402.0M) Survivors: 58.0M->58.0M Heap: 4776.0M(9216.0M)->4506.0M(9216.0M)] [Times: user=0.68 sys=0.00, real=0.17 secs] 4104.414: [GC pause (G1 Evacuation Pause) (mixed), 0.1889837 secs] [Parallel Time: 184.8 ms, GC Workers: 4] [GC Worker Start (ms): Min: 4104414.8, Avg: 4104414.8, Max: 4104414.8, Diff: 0.1] [Ext Root Scanning (ms): Min: 0.9, Avg: 1.0, Max: 1.3, Diff: 0.4, Sum: 4.2] [Update RS (ms): Min: 9.9, Avg: 9.9, Max: 10.0, Diff: 0.0, Sum: 39.7] [Processed Buffers: Min: 43, Avg: 49.5, Max: 56, Diff: 13, Sum: 198] [Scan RS (ms): Min: 2.6, Avg: 2.9, Max: 3.1, Diff: 0.5, Sum: 11.4] [Code Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.3] [Object Copy (ms): Min: 170.6, Avg: 170.8, Max: 171.0, Diff: 0.4, Sum: 683.2] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4] [GC Worker Other (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.2] [GC Worker Total (ms): Min: 184.7, Avg: 184.7, Max: 184.8, Diff: 0.1, Sum: 739.0] [GC Worker End (ms): Min: 4104599.5, Avg: 4104599.5, Max: 4104599.6, Diff: 0.1] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.3 ms] [Other: 3.9 ms] [Choose CSet: 0.0 ms] [Ref Proc: 2.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.6 ms] [Humongous Register: 0.2 ms] [Humongous Reclaim: 0.1 ms] [Free CSet: 0.3 ms] [Eden: 402.0M(402.0M)->0.0B(402.0M) Survivors: 58.0M->58.0M Heap: 4908.0M(9216.0M)->4592.0M(9216.0M)] [Times: user=0.74 sys=0.00, real=0.18 secs] 4165.096: [GC pause (G1 Evacuation Pause) (young), 0.1878887 secs] [Parallel Time: 183.8 ms, GC Workers: 4] [GC Worker Start (ms): Min: 4165096.1, Avg: 4165096.5, Max: 4165097.7, Diff: 1.6] [Ext Root Scanning (ms): Min: 0.0, Avg: 0.9, Max: 1.2, Diff: 1.2, Sum: 3.5] [Update RS (ms): Min: 10.8, Avg: 11.1, Max: 11.3, Diff: 0.5, Sum: 44.3] [Processed Buffers: Min: 36, Avg: 54.5, Max: 87, Diff: 51, Sum: 218] [Scan RS (ms): Min: 0.2, Avg: 0.3, Max: 0.3, Diff: 0.2, 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: 164.5, Avg: 166.1, Max: 170.9, Diff: 6.5, Sum: 664.5] [Termination (ms): Min: 0.0, Avg: 4.9, Max: 6.5, Diff: 6.5, Sum: 19.6] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 182.1, Avg: 183.3, Max: 183.7, Diff: 1.6, Sum: 733.0] [GC Worker End (ms): Min: 4165279.8, Avg: 4165279.8, Max: 4165279.8, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.2 ms] [Other: 3.9 ms] [Choose CSet: 0.0 ms] [Ref Proc: 2.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.6 ms] [Humongous Register: 0.1 ms] [Humongous Reclaim: 0.2 ms] [Free CSet: 0.3 ms] [Eden: 402.0M(402.0M)->0.0B(402.0M) Survivors: 58.0M->58.0M Heap: 4994.0M(9216.0M)->4724.0M(9216.0M)] [Times: user=0.70 sys=0.00, real=0.19 secs] 4225.702: [GC pause (G1 Evacuation Pause) (young) (initial-mark), 0.1754066 secs] [Parallel Time: 170.8 ms, GC Workers: 4] [GC Worker Start (ms): Min: 4225703.1, Avg: 4225703.1, Max: 4225703.2, Diff: 0.1] [Ext Root Scanning (ms): Min: 1.5, Avg: 1.6, Max: 1.7, Diff: 0.2, Sum: 6.5] [Update RS (ms): Min: 9.6, Avg: 9.7, Max: 9.8, Diff: 0.1, Sum: 38.7] [Processed Buffers: Min: 34, Avg: 49.5, Max: 69, Diff: 35, Sum: 198] [Scan RS (ms): Min: 0.3, Avg: 0.4, Max: 0.5, Diff: 0.1, Sum: 1.5] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [Object Copy (ms): Min: 158.9, Avg: 158.9, Max: 159.1, Diff: 0.2, Sum: 635.8] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 170.6, Avg: 170.7, Max: 170.7, Diff: 0.1, Sum: 682.7] [GC Worker End (ms): Min: 4225873.8, Avg: 4225873.8, Max: 4225873.8, Diff: 0.0] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.1 ms] [Other: 4.4 ms] [Choose CSet: 0.0 ms] [Ref Proc: 1.9 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.6 ms] [Humongous Register: 0.1 ms] [Humongous Reclaim: 0.1 ms] [Free CSet: 0.3 ms] [Eden: 402.0M(402.0M)->0.0B(402.0M) Survivors: 58.0M->58.0M Heap: 5126.0M(9216.0M)->4856.0M(9216.0M)] [Times: user=0.67 sys=0.00, real=0.18 secs] 4225.878: [GC concurrent-root-region-scan-start] 4225.945: [GC concurrent-root-region-scan-end, 0.0674054 secs] 4225.945: [GC concurrent-mark-start] 4233.995: [GC concurrent-mark-end, 8.0500995 secs] 4234.010: [GC remark 4234.010: [Finalize Marking, 0.0002039 secs] 4234.010: [GC ref-proc, 0.0059652 secs] 4234.016: [Unloading, 0.0110792 secs], 0.0360993 secs] [Times: user=0.11 sys=0.00, real=0.04 secs] 4234.066: [GC cleanup 4918M->4910M(9216M), 0.0073057 secs] [Times: user=0.03 sys=0.00, real=0.01 secs] 4234.073: [GC concurrent-cleanup-start] 4234.073: [GC concurrent-cleanup-end, 0.0000225 secs] 4284.322: [GC pause (G1 Evacuation Pause) (young), 0.4781381 secs] [Parallel Time: 472.6 ms, GC Workers: 4] [GC Worker Start (ms): Min: 4284322.9, Avg: 4284324.8, Max: 4284326.8, Diff: 3.9] [Ext Root Scanning (ms): Min: 0.0, Avg: 1.0, Max: 1.9, Diff: 1.9, Sum: 3.8] [Update RS (ms): Min: 24.1, Avg: 31.5, Max: 43.2, Diff: 19.1, Sum: 125.8] [Processed Buffers: Min: 29, Avg: 53.8, Max: 113, Diff: 84, Sum: 215] [Scan RS (ms): Min: 0.0, Avg: 0.9, Max: 3.4, Diff: 3.4, Sum: 3.4] [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.1] [Object Copy (ms): Min: 412.0, Avg: 427.9, Max: 438.1, Diff: 26.1, Sum: 1711.7] [Termination (ms): Min: 0.0, Avg: 7.1, Max: 15.4, Diff: 15.4, Sum: 28.4] [Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 4] [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] [GC Worker Total (ms): Min: 465.6, Avg: 468.3, Max: 469.5, Diff: 3.9, Sum: 1873.3] [GC Worker End (ms): Min: 4284792.4, Avg: 4284793.2, Max: 4284795.5, Diff: 3.1] [Code Root Fixup: 0.0 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.3 ms] [Other: 5.2 ms] [Choose CSet: 0.0 ms] [Ref Proc: 3.3 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.4 ms] [Humongous Register: 0.2 ms] [Humongous Reclaim: 0.2 ms] [Free CSet: 0.4 ms] [Eden: 402.0M(402.0M)->0.0B(402.0M) Survivors: 58.0M->58.0M Heap: 5250.0M(9216.0M)->4980.0M(9216.0M)] [Times: user=0.68 sys=0.00, real=0.48 secs] ```
---------------------------------------------------------------- This is an automated message from the Apache Git Service. To respond to the message, please log on to GitHub and use the URL above to go to the specific comment. For queries about this service, please contact Infrastructure at: [email protected] With regards, Apache Git Services --------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
