Attachment 1837208 Details for Bug 1862163 – GC log from Java 11 10 with the reproduction snippet.

GC log from Java 11 10 with the reproduction snippet.

java11_10_gclog.txt (text/plain), 30.34 KB, created by Simeon Andreev on 2021-10-26 13:50:51 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Simeon Andreev

Created: 2021-10-26 13:50:51 UTC

Size: 30.34 KB

patch

obsolete

>[0.003s][info][gc,heap] Heap region size: 2M
>[0.005s][info][gc     ] Using G1
>[0.005s][info][gc,heap,coops] Heap address: 0x0000000600000000, size: 8192 MB, Compressed Oops mode: Zero based, Oop shift amount: 3
>[0.005s][info][gc,cds       ] Mark closed archive regions in map: [0x00000007ffc00000, 0x00000007ffc6aff8]
>[0.005s][info][gc,cds       ] Mark open archive regions in map: [0x00000007ff800000, 0x00000007ff847ff8]
>[0.157s][info][gc,start     ] GC(0) Pause Young (Concurrent Start) (G1 Humongous Allocation)
>[0.158s][info][gc,task      ] GC(0) Using 12 workers of 13 for evacuation
>[0.339s][info][gc,phases    ] GC(0)   Pre Evacuate Collection Set: 0.0ms
>[0.339s][info][gc,phases    ] GC(0)   Evacuate Collection Set: 180.1ms
>[0.339s][info][gc,phases    ] GC(0)   Post Evacuate Collection Set: 0.5ms
>[0.339s][info][gc,phases    ] GC(0)   Other: 0.6ms
>[0.339s][info][gc,heap      ] GC(0) Eden regions: 1->0(11)
>[0.339s][info][gc,heap      ] GC(0) Survivor regions: 0->1(2)
>[0.339s][info][gc,heap      ] GC(0) Old regions: 2->2
>[0.339s][info][gc,heap      ] GC(0) Humongous regions: 112->112
>[0.339s][info][gc,metaspace ] GC(0) Metaspace: 187K->187K(1056768K)
>[0.339s][info][gc           ] GC(0) Pause Young (Concurrent Start) (G1 Humongous Allocation) 226M->224M(516M) 181.204ms
>[0.339s][info][gc,cpu       ] GC(0) User=2.16s Sys=0.01s Real=0.18s
>[0.339s][info][gc           ] GC(1) Concurrent Cycle
>[0.339s][info][gc,marking   ] GC(1) Concurrent Clear Claimed Marks
>[0.339s][info][gc,marking   ] GC(1) Concurrent Clear Claimed Marks 0.008ms
>[0.339s][info][gc,marking   ] GC(1) Concurrent Scan Root Regions
>[0.339s][info][gc,marking   ] GC(1) Concurrent Scan Root Regions 0.426ms
>[0.339s][info][gc,marking   ] GC(1) Concurrent Mark (0.339s)
>[0.339s][info][gc,marking   ] GC(1) Concurrent Mark From Roots
>[0.339s][info][gc,task      ] GC(1) Using 3 workers of 3 for marking
>[0.348s][info][gc,marking   ] GC(1) Concurrent Mark From Roots 8.687ms
>[0.348s][info][gc,marking   ] GC(1) Concurrent Preclean
>[0.348s][info][gc,marking   ] GC(1) Concurrent Preclean 0.101ms
>[0.348s][info][gc,marking   ] GC(1) Concurrent Mark (0.339s, 0.348s) 8.832ms
>[0.349s][info][gc,start     ] GC(1) Pause Remark
>[0.349s][info][gc,stringtable] GC(1) Cleaned string and symbol table, strings: 10 processed, 0 removed, symbols: 19 processed, 0 removed
>[0.350s][info][gc            ] GC(1) Pause Remark 240M->24M(516M) 0.423ms
>[0.350s][info][gc,cpu        ] GC(1) User=0.00s Sys=0.00s Real=0.00s
>[0.350s][info][gc,marking    ] GC(1) Concurrent Rebuild Remembered Sets
>[0.398s][info][gc,marking    ] GC(1) Concurrent Rebuild Remembered Sets 48.740ms
>[0.399s][info][gc,start      ] GC(1) Pause Cleanup
>[0.399s][info][gc            ] GC(1) Pause Cleanup 232M->232M(516M) 0.109ms
>[0.399s][info][gc,cpu        ] GC(1) User=0.00s Sys=0.00s Real=0.00s
>[0.399s][info][gc,marking    ] GC(1) Concurrent Cleanup for Next Mark
>[0.411s][info][gc,marking    ] GC(1) Concurrent Cleanup for Next Mark 11.772ms
>[0.411s][info][gc            ] GC(1) Concurrent Cycle 72.171ms
>[0.413s][info][gc,start      ] GC(2) Pause Young (Concurrent Start) (G1 Humongous Allocation)
>[0.413s][info][gc,task       ] GC(2) Using 12 workers of 13 for evacuation
>[0.673s][info][gc,mmu        ] GC(2) MMU target violated: 201.0ms (200.0ms/201.0ms)
>[0.673s][info][gc,phases     ] GC(2)   Pre Evacuate Collection Set: 0.0ms
>[0.673s][info][gc,phases     ] GC(2)   Evacuate Collection Set: 259.5ms
>[0.673s][info][gc,phases     ] GC(2)   Post Evacuate Collection Set: 0.5ms
>[0.673s][info][gc,phases     ] GC(2)   Other: 0.2ms
>[0.673s][info][gc,heap       ] GC(2) Eden regions: 0->0(11)
>[0.673s][info][gc,heap       ] GC(2) Survivor regions: 1->1(2)
>[0.673s][info][gc,heap       ] GC(2) Old regions: 2->2
>[0.673s][info][gc,heap       ] GC(2) Humongous regions: 132->132
>[0.673s][info][gc,metaspace  ] GC(2) Metaspace: 187K->187K(1056768K)
>[0.673s][info][gc            ] GC(2) Pause Young (Concurrent Start) (G1 Humongous Allocation) 264M->264M(516M) 260.202ms
>[0.673s][info][gc,cpu        ] GC(2) User=3.11s Sys=0.00s Real=0.26s
>[0.673s][info][gc            ] GC(3) Concurrent Cycle
>[0.673s][info][gc,marking    ] GC(3) Concurrent Clear Claimed Marks
>[0.673s][info][gc,marking    ] GC(3) Concurrent Clear Claimed Marks 0.005ms
>[0.673s][info][gc,marking    ] GC(3) Concurrent Scan Root Regions
>[0.673s][info][gc,marking    ] GC(3) Concurrent Scan Root Regions 0.418ms
>[0.673s][info][gc,marking    ] GC(3) Concurrent Mark (0.673s)
>[0.673s][info][gc,marking    ] GC(3) Concurrent Mark From Roots
>[0.673s][info][gc,task       ] GC(3) Using 3 workers of 3 for marking
>[0.687s][info][gc,marking    ] GC(3) Concurrent Mark From Roots 13.967ms
>[0.687s][info][gc,marking    ] GC(3) Concurrent Preclean
>[0.687s][info][gc,marking    ] GC(3) Concurrent Preclean 0.120ms
>[0.687s][info][gc,marking    ] GC(3) Concurrent Mark (0.673s, 0.687s) 14.153ms
>[0.688s][info][gc,start      ] GC(3) Pause Remark
>[0.688s][info][gc,stringtable] GC(3) Cleaned string and symbol table, strings: 10 processed, 0 removed, symbols: 19 processed, 0 removed
>[0.688s][info][gc            ] GC(3) Pause Remark 288M->32M(516M) 0.383ms
>[0.688s][info][gc,cpu        ] GC(3) User=0.01s Sys=0.00s Real=0.00s
>[0.688s][info][gc,marking    ] GC(3) Concurrent Rebuild Remembered Sets
>[0.748s][info][gc,marking    ] GC(3) Concurrent Rebuild Remembered Sets 59.521ms
>[0.749s][info][gc,start      ] GC(3) Pause Cleanup
>[0.749s][info][gc            ] GC(3) Pause Cleanup 288M->288M(516M) 0.102ms
>[0.749s][info][gc,cpu        ] GC(3) User=0.00s Sys=0.00s Real=0.00s
>[0.749s][info][gc,marking    ] GC(3) Concurrent Cleanup for Next Mark
>[0.755s][info][gc,marking    ] GC(3) Concurrent Cleanup for Next Mark 6.145ms
>[0.755s][info][gc            ] GC(3) Concurrent Cycle 82.394ms
>[0.758s][info][gc,start      ] GC(4) Pause Young (Concurrent Start) (G1 Humongous Allocation)
>[0.758s][info][gc,task       ] GC(4) Using 12 workers of 13 for evacuation
>[1.065s][info][gc,mmu        ] GC(4) MMU target violated: 201.0ms (200.0ms/201.0ms)
>[1.065s][info][gc,phases     ] GC(4)   Pre Evacuate Collection Set: 0.0ms
>[1.065s][info][gc,phases     ] GC(4)   Evacuate Collection Set: 306.2ms
>[1.065s][info][gc,phases     ] GC(4)   Post Evacuate Collection Set: 0.5ms
>[1.065s][info][gc,phases     ] GC(4)   Other: 0.2ms
>[1.065s][info][gc,heap       ] GC(4) Eden regions: 0->0(11)
>[1.065s][info][gc,heap       ] GC(4) Survivor regions: 1->1(2)
>[1.065s][info][gc,heap       ] GC(4) Old regions: 2->2
>[1.065s][info][gc,heap       ] GC(4) Humongous regions: 152->152
>[1.065s][info][gc,metaspace  ] GC(4) Metaspace: 187K->187K(1056768K)
>[1.065s][info][gc            ] GC(4) Pause Young (Concurrent Start) (G1 Humongous Allocation) 304M->304M(516M) 306.889ms
>[1.065s][info][gc,cpu        ] GC(4) User=3.67s Sys=0.00s Real=0.31s
>[1.065s][info][gc            ] GC(5) Concurrent Cycle
>[1.065s][info][gc,marking    ] GC(5) Concurrent Clear Claimed Marks
>[1.065s][info][gc,marking    ] GC(5) Concurrent Clear Claimed Marks 0.005ms
>[1.065s][info][gc,marking    ] GC(5) Concurrent Scan Root Regions
>[1.066s][info][gc,marking    ] GC(5) Concurrent Scan Root Regions 0.780ms
>[1.066s][info][gc,marking    ] GC(5) Concurrent Mark (1.066s)
>[1.066s][info][gc,marking    ] GC(5) Concurrent Mark From Roots
>[1.066s][info][gc,task       ] GC(5) Using 3 workers of 3 for marking
>[1.078s][info][gc,marking    ] GC(5) Concurrent Mark From Roots 12.150ms
>[1.078s][info][gc,marking    ] GC(5) Concurrent Preclean
>[1.078s][info][gc,marking    ] GC(5) Concurrent Preclean 0.106ms
>[1.078s][info][gc,marking    ] GC(5) Concurrent Mark (1.066s, 1.078s) 12.310ms
>[1.079s][info][gc,start      ] GC(5) Pause Remark
>[1.079s][info][gc,stringtable] GC(5) Cleaned string and symbol table, strings: 10 processed, 0 removed, symbols: 19 processed, 0 removed
>[1.079s][info][gc            ] GC(5) Pause Remark 328M->32M(516M) 0.384ms
>[1.079s][info][gc,cpu        ] GC(5) User=0.00s Sys=0.00s Real=0.00s
>[1.079s][info][gc,marking    ] GC(5) Concurrent Rebuild Remembered Sets
>[1.137s][info][gc,marking    ] GC(5) Concurrent Rebuild Remembered Sets 57.573ms
>[1.137s][info][gc,start      ] GC(5) Pause Cleanup
>[1.137s][info][gc            ] GC(5) Pause Cleanup 272M->272M(516M) 0.093ms
>[1.137s][info][gc,cpu        ] GC(5) User=0.00s Sys=0.00s Real=0.00s
>[1.137s][info][gc,marking    ] GC(5) Concurrent Cleanup for Next Mark
>[1.138s][info][gc,marking    ] GC(5) Concurrent Cleanup for Next Mark 0.376ms
>[1.138s][info][gc            ] GC(5) Concurrent Cycle 72.717ms
>[1.139s][info][gc,start      ] GC(6) Pause Young (Concurrent Start) (G1 Humongous Allocation)
>[1.139s][info][gc,task       ] GC(6) Using 12 workers of 13 for evacuation
>[1.392s][info][gc,mmu        ] GC(6) MMU target violated: 201.0ms (200.0ms/201.0ms)
>[1.392s][info][gc,phases     ] GC(6)   Pre Evacuate Collection Set: 0.0ms
>[1.392s][info][gc,phases     ] GC(6)   Evacuate Collection Set: 251.7ms
>[1.392s][info][gc,phases     ] GC(6)   Post Evacuate Collection Set: 0.5ms
>[1.392s][info][gc,phases     ] GC(6)   Other: 0.2ms
>[1.392s][info][gc,heap       ] GC(6) Eden regions: 0->0(11)
>[1.392s][info][gc,heap       ] GC(6) Survivor regions: 1->1(2)
>[1.392s][info][gc,heap       ] GC(6) Old regions: 2->2
>[1.392s][info][gc,heap       ] GC(6) Humongous regions: 140->140
>[1.392s][info][gc,metaspace  ] GC(6) Metaspace: 187K->187K(1056768K)
>[1.392s][info][gc            ] GC(6) Pause Young (Concurrent Start) (G1 Humongous Allocation) 280M->280M(516M) 252.403ms
>[1.392s][info][gc,cpu        ] GC(6) User=3.02s Sys=0.00s Real=0.25s
>[1.392s][info][gc            ] GC(7) Concurrent Cycle
>[1.392s][info][gc,marking    ] GC(7) Concurrent Clear Claimed Marks
>[1.392s][info][gc,marking    ] GC(7) Concurrent Clear Claimed Marks 0.005ms
>[1.392s][info][gc,marking    ] GC(7) Concurrent Scan Root Regions
>[1.392s][info][gc,marking    ] GC(7) Concurrent Scan Root Regions 0.426ms
>[1.392s][info][gc,marking    ] GC(7) Concurrent Mark (1.392s)
>[1.392s][info][gc,marking    ] GC(7) Concurrent Mark From Roots
>[1.392s][info][gc,task       ] GC(7) Using 3 workers of 3 for marking
>[1.405s][info][gc,marking    ] GC(7) Concurrent Mark From Roots 12.692ms
>[1.405s][info][gc,marking    ] GC(7) Concurrent Preclean
>[1.405s][info][gc,marking    ] GC(7) Concurrent Preclean 0.099ms
>[1.405s][info][gc,marking    ] GC(7) Concurrent Mark (1.392s, 1.405s) 12.837ms
>[1.405s][info][gc,start      ] GC(7) Pause Remark
>[1.405s][info][gc,stringtable] GC(7) Cleaned string and symbol table, strings: 10 processed, 0 removed, symbols: 19 processed, 0 removed
>[1.406s][info][gc            ] GC(7) Pause Remark 320M->48M(516M) 0.386ms
>[1.406s][info][gc,cpu        ] GC(7) User=0.01s Sys=0.00s Real=0.00s
>[1.406s][info][gc,marking    ] GC(7) Concurrent Rebuild Remembered Sets
>[1.484s][info][gc,marking    ] GC(7) Concurrent Rebuild Remembered Sets 78.729ms
>[1.486s][info][gc,start      ] GC(7) Pause Cleanup
>[1.486s][info][gc            ] GC(7) Pause Cleanup 352M->352M(516M) 0.106ms
>[1.486s][info][gc,cpu        ] GC(7) User=0.00s Sys=0.00s Real=0.00s
>[1.486s][info][gc,marking    ] GC(7) Concurrent Cleanup for Next Mark
>[1.489s][info][gc,marking    ] GC(7) Concurrent Cleanup for Next Mark 2.831ms
>[1.489s][info][gc            ] GC(7) Concurrent Cycle 97.221ms
>[1.491s][info][gc,start      ] GC(8) Pause Young (Concurrent Start) (G1 Humongous Allocation)
>[1.491s][info][gc,task       ] GC(8) Using 12 workers of 13 for evacuation
>[1.836s][info][gc,mmu        ] GC(8) MMU target violated: 201.0ms (200.0ms/201.0ms)
>[1.837s][info][gc,phases     ] GC(8)   Pre Evacuate Collection Set: 0.0ms
>[1.837s][info][gc,phases     ] GC(8)   Evacuate Collection Set: 341.7ms
>[1.837s][info][gc,phases     ] GC(8)   Post Evacuate Collection Set: 3.6ms
>[1.837s][info][gc,phases     ] GC(8)   Other: 0.2ms
>[1.837s][info][gc,heap       ] GC(8) Eden regions: 0->0(37)
>[1.837s][info][gc,heap       ] GC(8) Survivor regions: 1->1(2)
>[1.837s][info][gc,heap       ] GC(8) Old regions: 2->2
>[1.837s][info][gc,heap       ] GC(8) Humongous regions: 180->180
>[1.837s][info][gc,metaspace  ] GC(8) Metaspace: 187K->187K(1056768K)
>[1.837s][info][gc            ] GC(8) Pause Young (Concurrent Start) (G1 Humongous Allocation) 360M->360M(1548M) 345.611ms
>[1.837s][info][gc,cpu        ] GC(8) User=4.10s Sys=0.00s Real=0.35s
>[1.837s][info][gc            ] GC(9) Concurrent Cycle
>[1.837s][info][gc,marking    ] GC(9) Concurrent Clear Claimed Marks
>[1.837s][info][gc,marking    ] GC(9) Concurrent Clear Claimed Marks 0.006ms
>[1.837s][info][gc,marking    ] GC(9) Concurrent Scan Root Regions
>[1.837s][info][gc,marking    ] GC(9) Concurrent Scan Root Regions 0.420ms
>[1.837s][info][gc,marking    ] GC(9) Concurrent Mark (1.837s)
>[1.837s][info][gc,marking    ] GC(9) Concurrent Mark From Roots
>[1.837s][info][gc,task       ] GC(9) Using 3 workers of 3 for marking
>[1.847s][info][gc,marking    ] GC(9) Concurrent Mark From Roots 9.692ms
>[1.847s][info][gc,marking    ] GC(9) Concurrent Preclean
>[1.847s][info][gc,marking    ] GC(9) Concurrent Preclean 0.099ms
>[1.847s][info][gc,marking    ] GC(9) Concurrent Mark (1.837s, 1.847s) 9.839ms
>[1.847s][info][gc,start      ] GC(9) Pause Remark
>[1.848s][info][gc,stringtable] GC(9) Cleaned string and symbol table, strings: 10 processed, 0 removed, symbols: 19 processed, 0 removed
>[1.848s][info][gc            ] GC(9) Pause Remark 376M->24M(1548M) 0.474ms
>[1.848s][info][gc,cpu        ] GC(9) User=0.00s Sys=0.00s Real=0.00s
>[1.848s][info][gc,marking    ] GC(9) Concurrent Rebuild Remembered Sets
>[1.886s][info][gc,marking    ] GC(9) Concurrent Rebuild Remembered Sets 37.844ms
>[1.886s][info][gc,start      ] GC(9) Pause Cleanup
>[1.886s][info][gc            ] GC(9) Pause Cleanup 192M->192M(1548M) 0.171ms
>[1.886s][info][gc,cpu        ] GC(9) User=0.00s Sys=0.00s Real=0.00s
>[1.886s][info][gc,marking    ] GC(9) Concurrent Cleanup for Next Mark
>[1.890s][info][gc,marking    ] GC(9) Concurrent Cleanup for Next Mark 3.116ms
>[1.890s][info][gc            ] GC(9) Concurrent Cycle 52.970ms
>[2.087s][info][gc,start      ] GC(10) Pause Young (Concurrent Start) (G1 Humongous Allocation)
>[2.087s][info][gc,task       ] GC(10) Using 13 workers of 13 for evacuation
>[2.811s][info][gc,mmu        ] GC(10) MMU target violated: 201.0ms (200.0ms/201.0ms)
>[2.811s][info][gc,phases     ] GC(10)   Pre Evacuate Collection Set: 0.0ms
>[2.811s][info][gc,phases     ] GC(10)   Evacuate Collection Set: 722.5ms
>[2.811s][info][gc,phases     ] GC(10)   Post Evacuate Collection Set: 1.1ms
>[2.811s][info][gc,phases     ] GC(10)   Other: 0.3ms
>[2.811s][info][gc,heap       ] GC(10) Eden regions: 0->0(37)
>[2.811s][info][gc,heap       ] GC(10) Survivor regions: 1->1(5)
>[2.811s][info][gc,heap       ] GC(10) Old regions: 2->2
>[2.811s][info][gc,heap       ] GC(10) Humongous regions: 344->344
>[2.811s][info][gc,metaspace  ] GC(10) Metaspace: 189K->189K(1056768K)
>[2.811s][info][gc            ] GC(10) Pause Young (Concurrent Start) (G1 Humongous Allocation) 688M->688M(1548M) 723.950ms
>[2.811s][info][gc,cpu        ] GC(10) User=9.39s Sys=0.00s Real=0.72s
>[2.811s][info][gc            ] GC(11) Concurrent Cycle
>[2.811s][info][gc,marking    ] GC(11) Concurrent Clear Claimed Marks
>[2.811s][info][gc,marking    ] GC(11) Concurrent Clear Claimed Marks 0.007ms
>[2.811s][info][gc,marking    ] GC(11) Concurrent Scan Root Regions
>[2.811s][info][gc,marking    ] GC(11) Concurrent Scan Root Regions 0.409ms
>[2.811s][info][gc,marking    ] GC(11) Concurrent Mark (2.811s)
>[2.811s][info][gc,marking    ] GC(11) Concurrent Mark From Roots
>[2.811s][info][gc,task       ] GC(11) Using 3 workers of 3 for marking
>[2.825s][info][gc,marking    ] GC(11) Concurrent Mark From Roots 13.340ms
>[2.825s][info][gc,marking    ] GC(11) Concurrent Preclean
>[2.825s][info][gc,marking    ] GC(11) Concurrent Preclean 0.097ms
>[2.825s][info][gc,marking    ] GC(11) Concurrent Mark (2.811s, 2.825s) 13.494ms
>[2.827s][info][gc,start      ] GC(11) Pause Remark
>[2.827s][info][gc,stringtable] GC(11) Cleaned string and symbol table, strings: 10 processed, 0 removed, symbols: 19 processed, 0 removed
>[2.827s][info][gc            ] GC(11) Pause Remark 712M->32M(1548M) 0.602ms
>[2.827s][info][gc,cpu        ] GC(11) User=0.00s Sys=0.00s Real=0.00s
>[2.828s][info][gc,marking    ] GC(11) Concurrent Rebuild Remembered Sets
>[2.874s][info][gc,marking    ] GC(11) Concurrent Rebuild Remembered Sets 45.996ms
>[2.874s][info][gc,start      ] GC(11) Pause Cleanup
>[2.875s][info][gc            ] GC(11) Pause Cleanup 240M->240M(1548M) 0.132ms
>[2.875s][info][gc,cpu        ] GC(11) User=0.00s Sys=0.00s Real=0.00s
>[2.875s][info][gc,marking    ] GC(11) Concurrent Cleanup for Next Mark
>[2.878s][info][gc,marking    ] GC(11) Concurrent Cleanup for Next Mark 2.996ms
>[2.878s][info][gc            ] GC(11) Concurrent Cycle 66.712ms
>[2.981s][info][gc,start      ] GC(12) Pause Young (Concurrent Start) (G1 Humongous Allocation)
>[2.981s][info][gc,task       ] GC(12) Using 13 workers of 13 for evacuation
>[3.760s][info][gc,mmu        ] GC(12) MMU target violated: 201.0ms (200.0ms/201.0ms)
>[3.761s][info][gc,phases     ] GC(12)   Pre Evacuate Collection Set: 0.0ms
>[3.761s][info][gc,phases     ] GC(12)   Evacuate Collection Set: 778.1ms
>[3.761s][info][gc,phases     ] GC(12)   Post Evacuate Collection Set: 1.4ms
>[3.761s][info][gc,phases     ] GC(12)   Other: 0.2ms
>[3.761s][info][gc,heap       ] GC(12) Eden regions: 0->0(37)
>[3.761s][info][gc,heap       ] GC(12) Survivor regions: 1->1(5)
>[3.761s][info][gc,heap       ] GC(12) Old regions: 2->2
>[3.761s][info][gc,heap       ] GC(12) Humongous regions: 344->344
>[3.761s][info][gc,metaspace  ] GC(12) Metaspace: 189K->189K(1056768K)
>[3.761s][info][gc            ] GC(12) Pause Young (Concurrent Start) (G1 Humongous Allocation) 688M->688M(1548M) 779.748ms
>[3.761s][info][gc,cpu        ] GC(12) User=10.11s Sys=0.00s Real=0.78s
>[3.761s][info][gc            ] GC(13) Concurrent Cycle
>[3.761s][info][gc,marking    ] GC(13) Concurrent Clear Claimed Marks
>[3.761s][info][gc,marking    ] GC(13) Concurrent Clear Claimed Marks 0.005ms
>[3.761s][info][gc,marking    ] GC(13) Concurrent Scan Root Regions
>[3.762s][info][gc,marking    ] GC(13) Concurrent Scan Root Regions 1.136ms
>[3.762s][info][gc,marking    ] GC(13) Concurrent Mark (3.762s)
>[3.762s][info][gc,marking    ] GC(13) Concurrent Mark From Roots
>[3.762s][info][gc,task       ] GC(13) Using 3 workers of 3 for marking
>[3.774s][info][gc,marking    ] GC(13) Concurrent Mark From Roots 12.355ms
>[3.774s][info][gc,marking    ] GC(13) Concurrent Preclean
>[3.774s][info][gc,marking    ] GC(13) Concurrent Preclean 0.099ms
>[3.774s][info][gc,marking    ] GC(13) Concurrent Mark (3.762s, 3.774s) 12.508ms
>[3.776s][info][gc,start      ] GC(13) Pause Remark
>[3.777s][info][gc,stringtable] GC(13) Cleaned string and symbol table, strings: 10 processed, 0 removed, symbols: 19 processed, 0 removed
>[3.777s][info][gc            ] GC(13) Pause Remark 720M->40M(1548M) 1.060ms
>[3.777s][info][gc,cpu        ] GC(13) User=0.00s Sys=0.00s Real=0.00s
>[3.778s][info][gc,marking    ] GC(13) Concurrent Rebuild Remembered Sets
>[3.840s][info][gc,marking    ] GC(13) Concurrent Rebuild Remembered Sets 62.602ms
>[3.841s][info][gc,start      ] GC(13) Pause Cleanup
>[3.841s][info][gc            ] GC(13) Pause Cleanup 304M->304M(1548M) 0.162ms
>[3.841s][info][gc,cpu        ] GC(13) User=0.00s Sys=0.00s Real=0.00s
>[3.841s][info][gc,marking    ] GC(13) Concurrent Cleanup for Next Mark
>[3.842s][info][gc,marking    ] GC(13) Concurrent Cleanup for Next Mark 1.127ms
>[3.842s][info][gc            ] GC(13) Concurrent Cycle 81.659ms
>[3.929s][info][gc,start      ] GC(14) Pause Young (Concurrent Start) (G1 Humongous Allocation)
>[3.929s][info][gc,task       ] GC(14) Using 13 workers of 13 for evacuation
>[4.757s][info][gc,mmu        ] GC(14) MMU target violated: 201.0ms (200.0ms/201.0ms)
>[4.757s][info][gc,phases     ] GC(14)   Pre Evacuate Collection Set: 0.0ms
>[4.757s][info][gc,phases     ] GC(14)   Evacuate Collection Set: 826.3ms
>[4.757s][info][gc,phases     ] GC(14)   Post Evacuate Collection Set: 1.2ms
>[4.757s][info][gc,phases     ] GC(14)   Other: 0.2ms
>[4.757s][info][gc,heap       ] GC(14) Eden regions: 0->0(37)
>[4.757s][info][gc,heap       ] GC(14) Survivor regions: 1->1(5)
>[4.757s][info][gc,heap       ] GC(14) Old regions: 2->2
>[4.757s][info][gc,heap       ] GC(14) Humongous regions: 344->344
>[4.757s][info][gc,metaspace  ] GC(14) Metaspace: 189K->189K(1056768K)
>[4.757s][info][gc            ] GC(14) Pause Young (Concurrent Start) (G1 Humongous Allocation) 688M->688M(1548M) 827.780ms
>[4.757s][info][gc,cpu        ] GC(14) User=10.74s Sys=0.00s Real=0.83s
>[4.757s][info][gc            ] GC(15) Concurrent Cycle
>[4.757s][info][gc,marking    ] GC(15) Concurrent Clear Claimed Marks
>[4.757s][info][gc,marking    ] GC(15) Concurrent Clear Claimed Marks 0.005ms
>[4.757s][info][gc,marking    ] GC(15) Concurrent Scan Root Regions
>[4.759s][info][gc,marking    ] GC(15) Concurrent Scan Root Regions 1.898ms
>[4.759s][info][gc,marking    ] GC(15) Concurrent Mark (4.759s)
>[4.759s][info][gc,marking    ] GC(15) Concurrent Mark From Roots
>[4.759s][info][gc,task       ] GC(15) Using 3 workers of 3 for marking
>[4.771s][info][gc,marking    ] GC(15) Concurrent Mark From Roots 11.743ms
>[4.771s][info][gc,marking    ] GC(15) Concurrent Preclean
>[4.771s][info][gc,marking    ] GC(15) Concurrent Preclean 0.107ms
>[4.771s][info][gc,marking    ] GC(15) Concurrent Mark (4.759s, 4.771s) 11.894ms
>[4.775s][info][gc,start      ] GC(15) Pause Remark
>[4.775s][info][gc,stringtable] GC(15) Cleaned string and symbol table, strings: 10 processed, 0 removed, symbols: 19 processed, 0 removed
>[4.775s][info][gc            ] GC(15) Pause Remark 728M->48M(1548M) 0.568ms
>[4.775s][info][gc,cpu        ] GC(15) User=0.00s Sys=0.00s Real=0.00s
>[4.776s][info][gc,marking    ] GC(15) Concurrent Rebuild Remembered Sets
>[4.848s][info][gc,marking    ] GC(15) Concurrent Rebuild Remembered Sets 72.156ms
>[4.848s][info][gc,start      ] GC(15) Pause Cleanup
>[4.848s][info][gc            ] GC(15) Pause Cleanup 368M->368M(1548M) 0.161ms
>[4.848s][info][gc,cpu        ] GC(15) User=0.01s Sys=0.00s Real=0.00s
>[4.848s][info][gc,marking    ] GC(15) Concurrent Cleanup for Next Mark
>[4.850s][info][gc,marking    ] GC(15) Concurrent Cleanup for Next Mark 1.169ms
>[4.850s][info][gc            ] GC(15) Concurrent Cycle 92.821ms
>[4.922s][info][gc,start      ] GC(16) Pause Young (Concurrent Start) (G1 Humongous Allocation)
>[4.922s][info][gc,task       ] GC(16) Using 13 workers of 13 for evacuation
>[5.697s][info][gc,mmu        ] GC(16) MMU target violated: 201.0ms (200.0ms/201.0ms)
>[5.697s][info][gc,phases     ] GC(16)   Pre Evacuate Collection Set: 0.0ms
>[5.697s][info][gc,phases     ] GC(16)   Evacuate Collection Set: 765.9ms
>[5.697s][info][gc,phases     ] GC(16)   Post Evacuate Collection Set: 9.1ms
>[5.697s][info][gc,phases     ] GC(16)   Other: 0.2ms
>[5.697s][info][gc,heap       ] GC(16) Eden regions: 0->0(104)
>[5.697s][info][gc,heap       ] GC(16) Survivor regions: 1->1(5)
>[5.697s][info][gc,heap       ] GC(16) Old regions: 2->2
>[5.697s][info][gc,heap       ] GC(16) Humongous regions: 344->344
>[5.697s][info][gc,metaspace  ] GC(16) Metaspace: 189K->189K(1056768K)
>[5.697s][info][gc            ] GC(16) Pause Young (Concurrent Start) (G1 Humongous Allocation) 688M->688M(4206M) 775.379ms
>[5.697s][info][gc,cpu        ] GC(16) User=9.96s Sys=0.00s Real=0.77s
>[5.697s][info][gc            ] GC(17) Concurrent Cycle
>[5.697s][info][gc,marking    ] GC(17) Concurrent Clear Claimed Marks
>[5.697s][info][gc,marking    ] GC(17) Concurrent Clear Claimed Marks 0.007ms
>[5.697s][info][gc,marking    ] GC(17) Concurrent Scan Root Regions
>[5.698s][info][gc,marking    ] GC(17) Concurrent Scan Root Regions 0.856ms
>[5.698s][info][gc,marking    ] GC(17) Concurrent Mark (5.698s)
>[5.698s][info][gc,marking    ] GC(17) Concurrent Mark From Roots
>[5.698s][info][gc,task       ] GC(17) Using 3 workers of 3 for marking
>[5.707s][info][gc,marking    ] GC(17) Concurrent Mark From Roots 8.421ms
>[5.707s][info][gc,marking    ] GC(17) Concurrent Preclean
>[5.707s][info][gc,marking    ] GC(17) Concurrent Preclean 0.103ms
>[5.707s][info][gc,marking    ] GC(17) Concurrent Mark (5.698s, 5.707s) 8.573ms
>[5.707s][info][gc,start      ] GC(17) Pause Remark
>[5.708s][info][gc,stringtable] GC(17) Cleaned string and symbol table, strings: 10 processed, 0 removed, symbols: 19 processed, 0 removed
>[5.708s][info][gc            ] GC(17) Pause Remark 720M->40M(4206M) 1.076ms
>[5.708s][info][gc,cpu        ] GC(17) User=0.01s Sys=0.00s Real=0.00s
>[5.708s][info][gc,marking    ] GC(17) Concurrent Rebuild Remembered Sets
>[5.778s][info][gc,marking    ] GC(17) Concurrent Rebuild Remembered Sets 69.883ms
>[5.778s][info][gc,start      ] GC(17) Pause Cleanup
>[5.779s][info][gc            ] GC(17) Pause Cleanup 344M->344M(4206M) 0.328ms
>[5.779s][info][gc,cpu        ] GC(17) User=0.00s Sys=0.00s Real=0.00s
>[5.779s][info][gc,marking    ] GC(17) Concurrent Cleanup for Next Mark
>[5.786s][info][gc,marking    ] GC(17) Concurrent Cleanup for Next Mark 7.552ms
>[5.786s][info][gc            ] GC(17) Concurrent Cycle 89.083ms
>[6.450s][info][gc,start      ] GC(18) Pause Young (Concurrent Start) (G1 Humongous Allocation)
>[6.450s][info][gc,task       ] GC(18) Using 13 workers of 13 for evacuation
>[8.420s][info][gc,mmu        ] GC(18) MMU target violated: 201.0ms (200.0ms/201.0ms)
>[8.420s][info][gc,phases     ] GC(18)   Pre Evacuate Collection Set: 0.1ms
>[8.420s][info][gc,phases     ] GC(18)   Evacuate Collection Set: 1966.6ms
>[8.420s][info][gc,phases     ] GC(18)   Post Evacuate Collection Set: 2.6ms
>[8.420s][info][gc,phases     ] GC(18)   Other: 0.4ms
>[8.420s][info][gc,heap       ] GC(18) Eden regions: 0->0(104)
>[8.420s][info][gc,heap       ] GC(18) Survivor regions: 1->1(14)
>[8.420s][info][gc,heap       ] GC(18) Old regions: 2->2
>[8.420s][info][gc,heap       ] GC(18) Humongous regions: 944->944
>[8.420s][info][gc,metaspace  ] GC(18) Metaspace: 189K->189K(1056768K)
>[8.420s][info][gc            ] GC(18) Pause Young (Concurrent Start) (G1 Humongous Allocation) 1888M->1888M(4206M) 1969.771ms
>[8.420s][info][gc,cpu        ] GC(18) User=25.55s Sys=0.01s Real=1.97s
>[8.420s][info][gc            ] GC(19) Concurrent Cycle
>[8.420s][info][gc,marking    ] GC(19) Concurrent Clear Claimed Marks
>[8.420s][info][gc,marking    ] GC(19) Concurrent Clear Claimed Marks 0.005ms
>[8.420s][info][gc,marking    ] GC(19) Concurrent Scan Root Regions
>[8.422s][info][gc,marking    ] GC(19) Concurrent Scan Root Regions 1.501ms
>[8.422s][info][gc,marking    ] GC(19) Concurrent Mark (8.422s)
>[8.422s][info][gc,marking    ] GC(19) Concurrent Mark From Roots
>[8.422s][info][gc,task       ] GC(19) Using 3 workers of 3 for marking
>[8.435s][info][gc,marking    ] GC(19) Concurrent Mark From Roots 13.087ms
>[8.435s][info][gc,marking    ] GC(19) Concurrent Preclean
>[8.435s][info][gc,marking    ] GC(19) Concurrent Preclean 0.099ms
>[8.435s][info][gc,marking    ] GC(19) Concurrent Mark (8.422s, 8.435s) 13.240ms
>[8.437s][info][gc,start      ] GC(19) Pause Remark
>[8.437s][info][gc,stringtable] GC(19) Cleaned string and symbol table, strings: 10 processed, 0 removed, symbols: 19 processed, 0 removed
>[8.438s][info][gc            ] GC(19) Pause Remark 1912M->32M(4206M) 0.880ms
>[8.438s][info][gc,cpu        ] GC(19) User=0.00s Sys=0.00s Real=0.00s
>[8.438s][info][gc,marking    ] GC(19) Concurrent Rebuild Remembered Sets
>[8.485s][info][gc,marking    ] GC(19) Concurrent Rebuild Remembered Sets 47.336ms
>[8.486s][info][gc,start      ] GC(19) Pause Cleanup
>[8.486s][info][gc            ] GC(19) Pause Cleanup 248M->248M(4206M) 0.277ms
>[8.486s][info][gc,cpu        ] GC(19) User=0.00s Sys=0.00s Real=0.00s
>[8.486s][info][gc,marking    ] GC(19) Concurrent Cleanup for Next Mark
>[8.494s][info][gc,marking    ] GC(19) Concurrent Cleanup for Next Mark 8.288ms
>[8.494s][info][gc            ] GC(19) Concurrent Cycle 74.281ms
>[8.871s][info][gc,start      ] GC(20) Pause Young (Concurrent Start) (G1 Humongous Allocation)
>[8.871s][info][gc,task       ] GC(20) Using 13 workers of 13 for evacuation
>[10.771s][info][gc,mmu        ] GC(20) MMU target violated: 201.0ms (200.0ms/201.0ms)
>[10.771s][info][gc,phases     ] GC(20)   Pre Evacuate Collection Set: 0.1ms
>[10.771s][info][gc,phases     ] GC(20)   Evacuate Collection Set: 1896.4ms
>[10.771s][info][gc,phases     ] GC(20)   Post Evacuate Collection Set: 3.2ms
>[10.771s][info][gc,phases     ] GC(20)   Other: 0.4ms
>[10.771s][info][gc,heap       ] GC(20) Eden regions: 0->0(104)
>[10.771s][info][gc,heap       ] GC(20) Survivor regions: 1->1(14)
>[10.771s][info][gc,heap       ] GC(20) Old regions: 2->2
>[10.771s][info][gc,heap       ] GC(20) Humongous regions: 944->944
>[10.771s][info][gc,metaspace  ] GC(20) Metaspace: 189K->189K(1056768K)
>[10.771s][info][gc            ] GC(20) Pause Young (Concurrent Start) (G1 Humongous Allocation) 1888M->1888M(4206M) 1900.158ms
>[10.771s][info][gc,cpu        ] GC(20) User=24.65s Sys=0.00s Real=1.90s
>[10.771s][info][gc            ] GC(21) Concurrent Cycle
>[10.771s][info][gc,marking    ] GC(21) Concurrent Clear Claimed Marks
>[10.771s][info][gc,marking    ] GC(21) Concurrent Clear Claimed Marks 0.006ms
>[10.771s][info][gc,marking    ] GC(21) Concurrent Scan Root Regions
>[10.772s][info][gc,marking    ] GC(21) Concurrent Scan Root Regions 0.691ms
>[10.772s][info][gc,marking    ] GC(21) Concurrent Mark (10.772s)
>[10.772s][info][gc,marking    ] GC(21) Concurrent Mark From Roots
>[10.772s][info][gc,task       ] GC(21) Using 3 workers of 3 for marking
>[10.784s][info][gc,marking    ] GC(21) Concurrent Mark From Roots 12.055ms
>[10.784s][info][gc,marking    ] GC(21) Concurrent Preclean
>[10.784s][info][gc,marking    ] GC(21) Concurrent Preclean 0.099ms
>[10.784s][info][gc,marking    ] GC(21) Concurrent Mark (10.772s, 10.784s) 12.204ms
>[10.786s][info][gc,start      ] GC(21) Pause Remark
>[10.786s][info][gc,stringtable] GC(21) Cleaned string and symbol table, strings: 10 processed, 0 removed, symbols: 19 processed, 0 removed
>[10.787s][info][gc            ] GC(21) Pause Remark 1920M->40M(4206M) 0.859ms
>[10.787s][info][gc,cpu        ] GC(21) User=0.00s Sys=0.00s Real=0.00s
>[10.787s][info][gc,marking    ] GC(21) Concurrent Rebuild Remembered Sets
>[10.850s][info][gc,marking    ] GC(21) Concurrent Rebuild Remembered Sets 63.360ms
>[10.850s][info][gc,start      ] GC(21) Pause Cleanup
>[10.851s][info][gc            ] GC(21) Pause Cleanup 137M->137M(4206M) 0.274ms
>[10.851s][info][gc,cpu        ] GC(21) User=0.00s Sys=0.00s Real=0.00s
>[10.851s][info][gc,marking    ] GC(21) Concurrent Cleanup for Next Mark
>[10.853s][info][gc,marking    ] GC(21) Concurrent Cleanup for Next Mark 2.403ms
>[10.853s][info][gc            ] GC(21) Concurrent Cycle 82.006ms
>[10.880s][info][gc,heap,exit  ] Heap
>[10.880s][info][gc,heap,exit  ]  garbage-first heap   total 4306944K, used 140279K [0x0000000600000000, 0x0000000800000000)
>[10.880s][info][gc,heap,exit  ]   region size 2048K, 2 young (4096K), 1 survivors (2048K)
>[10.880s][info][gc,heap,exit  ]  Metaspace       used 941K, capacity 4626K, committed 4864K, reserved 1056768K
>[10.880s][info][gc,heap,exit  ]   class space    used 100K, capacity 436K, committed 512K, reserved 1048576K

Actions: View

Attachments on bug 1862163: 1710962 | 1710967 | 1710981 | 1710982 | 1710983 | 1710985 | 1733753 | 1733754 | 1733828 | 1734031 | 1837207 | 1837208 | 1838349 | 1847239 | 1847240