Rare very big pause

[Roman Kennke]

Am 19.12.2017 um 16:10 schrieb Kirill A. Korinsky:
> Hi Roman,
>
> I mean this pice of log
>
>> Concurrent marking triggered. Free: 245M, Free Threshold: 245M; Allocated: 245M, Alloc Threshold: 0M
>> 2017-12-19T12:11:03.960+0000: 12221.481: [Pause Init Mark, 5.081 ms]
>> 2017-12-19T12:11:03.965+0000: 12221.485: [Concurrent markingCancelling concurrent GC: Allocation Failure
>>   5887M->6130M(6144M), 653.310 ms]
>> Adjusting free threshold to: 14% (860M)
>> 2017-12-19T12:11:04.620+0000: 12222.139: [Pause Final MarkTotal Garbage: 4889M
>> Immediate Garbage: 2018M, 1009 regions (44% of total)
>> Garbage to be collected: 2435M (49% of total), 1264 regions
>> Live objects to be evacuated: 87M
>> Live/garbage ratio in collected regions: 3%
>>   6130M->4114M(6144M), 446.259 ms]
>> 2017-12-19T12:11:05.069+0000: 12222.594: [Concurrent evacuation 4118M->4251M(6144M), 116.424 ms]
>> 2017-12-19T12:11:05.187+0000: 12222.706: [Pause Init Update Refs, 0.105 ms]
>> 2017-12-19T12:11:05.187+0000: 12222.706: [Concurrent update references  4251M->4614M(6144M), 992.439 ms]
>> 2017-12-19T12:11:06.180+0000: 12223.699: [Pause Final Update Refs 4614M->2092M(6144M), 2.380 ms]
>> 2017-12-19T12:11:06.183+0000: 12223.702: [Concurrent reset bitmaps 2092M->2092M(6144M), 4.246 ms]
>> Capacity: 6144M, Peak Occupancy: 6130M, Lowest Free: 13M, Free Threshold: 184M
>> Adjusting free threshold to: 17% (1044M)
> If I right understand it means that pause was 446.259 ms.
>
> Am I wrong?
>
> Thanks for links. I'm reading it.
>
Ah right. Then what Aleksey said is correct.

Roman