Skip to content

Instantly share code, notes, and snippets.

@brettrann

brettrann/gclog

Created Apr 11, 2018
Embed
What would you like to do?
4gb heap many 20ms YGC / sec
2018-04-11T08:53:59.951+0000: 177829.970: [GC pause (G1 Evacuation Pause) (young), 0.0425543 secs]
[Parallel Time: 32.2 ms, GC Workers: 38]
[GC Worker Start (ms): Min: 177829970.6, Avg: 177829977.8, Max: 177830000.6, Diff: 30.0]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.2, Max: 0.8, Diff: 0.8, Sum: 8.5]
[Update RS (ms): Min: 0.0, Avg: 1.3, Max: 3.0, Diff: 3.0, Sum: 48.7]
[Processed Buffers: Min: 0, Avg: 2.7, Max: 9, Diff: 9, Sum: 104]
[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.5, Max: 1.2, Diff: 1.2, Sum: 17.6]
[Termination (ms): Min: 0.0, Avg: 20.9, Max: 25.6, Diff: 25.6, Sum: 792.6]
[Termination Attempts: Min: 1, Avg: 1.1, Max: 2, Diff: 1, Sum: 43]
[GC Worker Other (ms): Min: 0.0, Avg: 0.1, Max: 0.6, Diff: 0.6, Sum: 5.0]
[GC Worker Total (ms): Min: 0.0, Avg: 23.0, Max: 30.5, Diff: 30.5, Sum: 872.8]
[GC Worker End (ms): Min: 177830000.6, Avg: 177830000.8, Max: 177830001.2, Diff: 0.6]
[Code Root Fixup: 0.1 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 2.6 ms]
[Other: 7.7 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 4.0 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 2.6 ms]
[Humongous Register: 0.1 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.6 ms]
[Eden: 278.0M(278.0M)->0.0B(198.0M) Survivors: 6144.0K->6144.0K Heap: 1638.7M(4096.0M)->1360.0M(4096.0M)]
[Times: user=0.58 sys=0.24, real=0.04 secs]
2018-04-11T08:54:00.189+0000: 177830.208: [GC pause (G1 Evacuation Pause) (young), 0.0310624 secs]
[Parallel Time: 19.8 ms, GC Workers: 38]
[GC Worker Start (ms): Min: 177830208.2, Avg: 177830210.8, Max: 177830226.8, Diff: 18.6]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.5, Max: 1.1, Diff: 1.1, Sum: 18.6]
[Update RS (ms): Min: 0.0, Avg: 1.8, Max: 2.7, Diff: 2.7, Sum: 68.6]
[Processed Buffers: Min: 0, Avg: 2.5, Max: 5, Diff: 5, Sum: 94]
[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.7, Max: 1.1, Diff: 1.1, Sum: 25.6]
[Termination (ms): Min: 0.0, Avg: 13.0, Max: 14.2, Diff: 14.2, Sum: 493.1]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 1, Diff: 0, Sum: 38]
[GC Worker Other (ms): Min: 0.0, Avg: 0.1, Max: 0.5, Diff: 0.5, Sum: 3.7]
[GC Worker Total (ms): Min: 0.0, Avg: 16.1, Max: 18.8, Diff: 18.8, Sum: 610.0]
[GC Worker End (ms): Min: 177830226.8, Avg: 177830226.9, Max: 177830227.3, Diff: 0.5]
[Code Root Fixup: 0.2 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 3.4 ms]
[Other: 7.7 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 4.3 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 2.6 ms]
[Humongous Register: 0.1 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.3 ms]
[Eden: 198.0M(198.0M)->0.0B(198.0M) Survivors: 6144.0K->6144.0K Heap: 1558.0M(4096.0M)->1361.0M(4096.0M)]
[Times: user=0.43 sys=0.17, real=0.03 secs]
2018-04-11T08:54:00.399+0000: 177830.418: [GC pause (G1 Evacuation Pause) (young), 0.0338354 secs]
[Parallel Time: 23.5 ms, GC Workers: 38]
[GC Worker Start (ms): Min: 177830418.6, Avg: 177830421.8, Max: 177830439.5, Diff: 21.0]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.4, Max: 0.9, Diff: 0.9, Sum: 14.0]
[Update RS (ms): Min: 0.0, Avg: 1.5, Max: 2.4, Diff: 2.4, Sum: 56.8]
[Processed Buffers: Min: 0, Avg: 2.6, Max: 7, Diff: 7, Sum: 100]
[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.7, Max: 1.2, Diff: 1.2, Sum: 25.8]
[Termination (ms): Min: 0.0, Avg: 15.2, Max: 17.0, Diff: 17.0, Sum: 577.7]
[Termination Attempts: Min: 1, Avg: 1.4, Max: 2, Diff: 1, Sum: 52]
[GC Worker Other (ms): Min: 0.0, Avg: 0.1, Max: 0.3, Diff: 0.3, Sum: 1.9]
[GC Worker Total (ms): Min: 0.0, Avg: 17.8, Max: 21.1, Diff: 21.1, Sum: 676.6]
[GC Worker End (ms): Min: 177830439.6, Avg: 177830439.6, Max: 177830439.9, Diff: 0.3]
[Code Root Fixup: 0.1 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 2.7 ms]
[Other: 7.5 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 3.9 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 2.7 ms]
[Humongous Register: 0.1 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.4 ms]
[Eden: 198.0M(198.0M)->0.0B(198.0M) Survivors: 6144.0K->6144.0K Heap: 1559.0M(4096.0M)->1360.5M(4096.0M)]
[Times: user=0.47 sys=0.17, real=0.03 secs]
2018-04-11T08:54:00.609+0000: 177830.629: [GC pause (G1 Evacuation Pause) (young), 0.0211485 secs]
[Parallel Time: 11.0 ms, GC Workers: 38]
[GC Worker Start (ms): Min: 177830628.9, Avg: 177830629.6, Max: 177830630.2, Diff: 1.3]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.5, Max: 1.1, Diff: 1.1, Sum: 17.6]
[Update RS (ms): Min: 1.7, Avg: 2.4, Max: 8.1, Diff: 6.4, Sum: 93.1]
[Processed Buffers: Min: 0, Avg: 2.6, Max: 6, Diff: 6, Sum: 100]
[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: 1.3, Max: 1.7, Diff: 1.7, Sum: 48.9]
[Termination (ms): Min: 0.0, Avg: 4.5, Max: 5.2, Diff: 5.2, Sum: 169.4]
[Termination Attempts: Min: 1, Avg: 10.0, Max: 15, Diff: 14, Sum: 381]
[GC Worker Other (ms): Min: 0.0, Avg: 0.2, Max: 0.7, Diff: 0.7, Sum: 7.4]
[GC Worker Total (ms): Min: 8.1, Avg: 8.9, Max: 10.0, Diff: 1.8, Sum: 337.0]
[GC Worker End (ms): Min: 177830638.3, Avg: 177830638.5, Max: 177830639.0, Diff: 0.7]
[Code Root Fixup: 0.2 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 2.6 ms]
[Other: 7.4 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 3.6 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 3.0 ms]
[Humongous Register: 0.1 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.4 ms]
[Eden: 198.0M(198.0M)->0.0B(350.0M) Survivors: 6144.0K->6144.0K Heap: 1558.5M(4096.0M)->1361.7M(4096.0M)]
[Times: user=0.23 sys=0.08, real=0.02 secs]
2018-04-11T08:54:00.956+0000: 177830.975: [GC pause (G1 Evacuation Pause) (young), 0.0159172 secs]
[Parallel Time: 6.2 ms, GC Workers: 38]
[GC Worker Start (ms): Min: 177830975.3, Avg: 177830976.2, Max: 177830980.4, Diff: 5.1]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.5, Max: 1.1, Diff: 1.1, Sum: 18.2]
[Update RS (ms): Min: 0.0, Avg: 2.5, Max: 3.0, Diff: 3.0, Sum: 95.7]
[Processed Buffers: Min: 0, Avg: 3.1, Max: 6, Diff: 6, Sum: 116]
[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.8, Max: 1.1, Diff: 1.1, Sum: 30.3]
[Termination (ms): Min: 0.0, Avg: 0.4, Max: 0.5, Diff: 0.5, Sum: 15.3]
[Termination Attempts: Min: 1, Avg: 1.0, Max: 2, Diff: 1, Sum: 39]
[GC Worker Other (ms): Min: 0.0, Avg: 0.1, Max: 0.6, Diff: 0.6, Sum: 5.6]
[GC Worker Total (ms): Min: 0.0, Avg: 4.4, Max: 5.4, Diff: 5.3, Sum: 166.0]
[GC Worker End (ms): Min: 177830980.4, Avg: 177830980.5, Max: 177830981.0, Diff: 0.6]
[Code Root Fixup: 0.2 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 2.5 ms]
[Other: 7.0 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 3.5 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 2.7 ms]
[Humongous Register: 0.1 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.4 ms]
[Eden: 350.0M(350.0M)->0.0B(694.0M) Survivors: 6144.0K->6144.0K Heap: 1711.7M(4096.0M)->1361.2M(4096.0M)]
[Times: user=0.00 sys=0.00, real=0.01 secs]
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment