Skip to content

Instantly share code, notes, and snippets.

@brettrann
Created April 11, 2018 08:48
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save brettrann/facab8ab92cae05142e5667c9a378728 to your computer and use it in GitHub Desktop.
Save brettrann/facab8ab92cae05142e5667c9a378728 to your computer and use it in GitHub Desktop.
mixed pauses with STW on 16GB. reduced by moving heap to 4GB. but that's added a new concern of 3-6 20ms youngs per second
2018-04-11T08:14:28.741+0000: 529814.699: [GC pause (G1 Evacuation Pause) (mixed), 0.2134632 secs]
[Parallel Time: 200.0 ms, GC Workers: 38]
[GC Worker Start (ms): Min: 529814699.6, Avg: 529814701.6, Max: 529814706.9, Diff: 7.3]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.5, Max: 1.1, Diff: 1.0, Sum: 19.0]
[Update RS (ms): Min: 0.0, Avg: 1.8, Max: 3.1, Diff: 3.1, Sum: 70.0]
[Processed Buffers: Min: 0, Avg: 2.8, Max: 12, Diff: 12, Sum: 106]
[Scan RS (ms): Min: 76.1, Avg: 122.7, Max: 192.8, Diff: 116.7, Sum: 4661.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: 1.3, Max: 10.0, Diff: 10.0, Sum: 48.7]
[Termination (ms): Min: 0.0, Avg: 69.2, Max: 104.6, Diff: 104.6, Sum: 2631.3]
[Termination Attempts: Min: 1, Avg: 4.7, Max: 24, Diff: 23, Sum: 179]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.7]
[GC Worker Total (ms): Min: 190.2, Avg: 195.6, Max: 197.9, Diff: 7.7, Sum: 7431.6]
[GC Worker End (ms): Min: 529814897.1, Avg: 529814897.2, Max: 529814897.5, Diff: 0.4]
[Code Root Fixup: 0.1 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 4.1 ms]
[Other: 9.2 ms]
[Choose CSet: 0.5 ms]
[Ref Proc: 3.9 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 2.6 ms]
[Humongous Register: 0.1 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 1.6 ms]
[Eden: 808.0M(808.0M)->0.0B(808.0M) Survivors: 8192.0K->8192.0K Heap: 5815.6M(16.0G)->4398.1M(16.0G)]
[Times: user=1.97 sys=0.95, real=0.21 secs]
2018-04-11T08:14:29.806+0000: 529815.763: [GC pause (G1 Evacuation Pause) (mixed), 0.0760621 secs]
[Parallel Time: 63.5 ms, GC Workers: 38]
[GC Worker Start (ms): Min: 529815764.1, Avg: 529815765.2, Max: 529815769.7, Diff: 5.6]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.5, Max: 1.1, Diff: 1.1, Sum: 19.5]
[Update RS (ms): Min: 0.0, Avg: 3.2, Max: 5.8, Diff: 5.8, Sum: 120.5]
[Processed Buffers: Min: 0, Avg: 2.9, Max: 8, Diff: 8, Sum: 109]
[Scan RS (ms): Min: 6.2, Avg: 20.9, Max: 57.0, Diff: 50.8, Sum: 793.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: 2.1, Max: 6.3, Diff: 6.3, Sum: 81.0]
[Termination (ms): Min: 0.0, Avg: 34.1, Max: 44.9, Diff: 44.9, Sum: 1296.0]
[Termination Attempts: Min: 1, Avg: 9.8, Max: 33, Diff: 32, Sum: 372]
[GC Worker Other (ms): Min: 0.0, Avg: 0.1, Max: 0.4, Diff: 0.4, Sum: 2.6]
[GC Worker Total (ms): Min: 56.2, Avg: 60.9, Max: 62.3, Diff: 6.1, Sum: 2313.5]
[GC Worker End (ms): Min: 529815825.9, Avg: 529815826.0, Max: 529815826.7, Diff: 0.9]
[Code Root Fixup: 0.3 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 2.9 ms]
[Other: 9.4 ms]
[Choose CSet: 0.6 ms]
[Ref Proc: 3.8 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 2.7 ms]
[Humongous Register: 0.1 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 1.8 ms]
[Eden: 808.0M(808.0M)->0.0B(808.0M) Survivors: 8192.0K->8192.0K Heap: 5206.1M(16.0G)->3791.1M(16.0G)]
[Times: user=1.16 sys=0.56, real=0.08 secs]
2018-04-11T08:14:30.724+0000: 529816.682: [GC pause (G1 Evacuation Pause) (mixed), 0.0493432 secs]
[Parallel Time: 37.0 ms, GC Workers: 38]
[GC Worker Start (ms): Min: 529816682.6, Avg: 529816685.2, Max: 529816691.6, Diff: 9.0]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.5, Max: 1.2, Diff: 1.2, Sum: 18.0]
[Update RS (ms): Min: 0.0, Avg: 1.6, Max: 3.1, Diff: 3.1, Sum: 62.3]
[Processed Buffers: Min: 0, Avg: 2.8, Max: 8, Diff: 8, Sum: 105]
[Scan RS (ms): Min: 1.7, Avg: 7.8, Max: 30.7, Diff: 29.0, Sum: 295.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: 2.3, Max: 3.3, Diff: 3.3, Sum: 87.6]
[Termination (ms): Min: 0.0, Avg: 19.8, Max: 21.5, Diff: 21.5, Sum: 752.6]
[Termination Attempts: Min: 1, Avg: 2.3, Max: 4, Diff: 3, Sum: 86]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 1.5]
[GC Worker Total (ms): Min: 25.6, Avg: 32.0, Max: 34.8, Diff: 9.1, Sum: 1217.2]
[GC Worker End (ms): Min: 529816717.2, Avg: 529816717.2, Max: 529816717.4, Diff: 0.2]
[Code Root Fixup: 0.3 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 2.7 ms]
[Other: 9.4 ms]
[Choose CSet: 0.6 ms]
[Ref Proc: 3.7 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 2.6 ms]
[Humongous Register: 0.1 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 1.9 ms]
[Eden: 808.0M(808.0M)->0.0B(808.0M) Survivors: 8192.0K->8192.0K Heap: 4599.1M(16.0G)->3182.5M(16.0G)]
[Times: user=0.77 sys=0.36, real=0.05 secs]
2018-04-11T08:14:31.604+0000: 529817.561: [GC pause (G1 Evacuation Pause) (mixed), 0.0450896 secs]
[Parallel Time: 32.5 ms, GC Workers: 38]
[GC Worker Start (ms): Min: 529817562.1, Avg: 529817570.2, Max: 529817584.1, Diff: 22.0]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.4, Max: 1.1, Diff: 1.1, Sum: 14.5]
[Update RS (ms): Min: 0.0, Avg: 1.1, Max: 2.5, Diff: 2.5, Sum: 43.5]
[Processed Buffers: Min: 0, Avg: 2.9, Max: 11, Diff: 11, Sum: 109]
[Scan RS (ms): Min: 0.2, Avg: 5.7, Max: 26.6, Diff: 26.5, Sum: 216.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: 2.5, Max: 10.6, Diff: 10.6, Sum: 95.6]
[Termination (ms): Min: 0.0, Avg: 12.2, Max: 14.1, Diff: 14.1, Sum: 463.2]
[Termination Attempts: Min: 1, Avg: 2.8, Max: 8, Diff: 7, Sum: 105]
[GC Worker Other (ms): Min: 0.0, Avg: 0.1, Max: 0.7, Diff: 0.6, Sum: 5.1]
[GC Worker Total (ms): Min: 8.1, Avg: 22.1, Max: 30.2, Diff: 22.1, Sum: 838.4]
[GC Worker End (ms): Min: 529817592.1, Avg: 529817592.3, Max: 529817592.8, Diff: 0.7]
[Code Root Fixup: 0.2 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 2.5 ms]
[Other: 9.9 ms]
[Choose CSet: 0.6 ms]
[Ref Proc: 4.7 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 2.3 ms]
[Humongous Register: 0.1 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 1.9 ms]
[Eden: 808.0M(808.0M)->0.0B(808.0M) Survivors: 8192.0K->8192.0K Heap: 3990.5M(16.0G)->2576.4M(16.0G)]
[Times: user=0.49 sys=0.24, real=0.05 secs]
2018-04-11T08:14:32.455+0000: 529818.412: [GC pause (G1 Evacuation Pause) (mixed), 0.0379609 secs]
[Parallel Time: 24.8 ms, GC Workers: 38]
[GC Worker Start (ms): Min: 529818412.8, Avg: 529818417.1, Max: 529818427.8, Diff: 15.0]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.5, Max: 1.2, Diff: 1.2, Sum: 20.8]
[Update RS (ms): Min: 0.0, Avg: 2.4, Max: 3.6, Diff: 3.6, Sum: 91.6]
[Processed Buffers: Min: 0, Avg: 3.2, Max: 8, Diff: 8, Sum: 120]
[Scan RS (ms): Min: 0.2, Avg: 4.1, Max: 18.1, Diff: 18.0, Sum: 154.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: 3.8, Max: 5.9, Diff: 5.9, Sum: 145.3]
[Termination (ms): Min: 0.0, Avg: 7.4, Max: 7.8, Diff: 7.8, Sum: 283.1]
[Termination Attempts: Min: 1, Avg: 6.9, Max: 12, Diff: 11, Sum: 264]
[GC Worker Other (ms): Min: 0.0, Avg: 0.2, Max: 0.7, Diff: 0.7, Sum: 6.4]
[GC Worker Total (ms): Min: 7.6, Avg: 18.5, Max: 22.8, Diff: 15.2, Sum: 701.2]
[GC Worker End (ms): Min: 529818435.3, Avg: 529818435.5, Max: 529818436.1, Diff: 0.7]
[Code Root Fixup: 0.2 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 2.7 ms]
[Other: 10.3 ms]
[Choose CSet: 0.7 ms]
[Ref Proc: 3.7 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 3.8 ms]
[Humongous Register: 0.1 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 1.7 ms]
[Eden: 808.0M(808.0M)->0.0B(808.0M) Survivors: 8192.0K->8192.0K Heap: 3384.4M(16.0G)->1968.3M(16.0G)]
[Times: user=0.47 sys=0.23, real=0.04 secs]
2018-04-11T08:14:33.366+0000: 529819.323: [GC pause (G1 Evacuation Pause) (mixed), 0.0658806 secs]
[Parallel Time: 52.7 ms, GC Workers: 38]
[GC Worker Start (ms): Min: 529819324.0, Avg: 529819324.9, Max: 529819327.7, Diff: 3.7]
[Ext Root Scanning (ms): Min: 0.0, Avg: 0.5, Max: 1.1, Diff: 1.1, Sum: 20.3]
[Update RS (ms): Min: 0.0, Avg: 1.8, Max: 8.2, Diff: 8.2, Sum: 69.9]
[Processed Buffers: Min: 0, Avg: 2.4, Max: 8, Diff: 8, Sum: 90]
[Scan RS (ms): Min: 0.5, Avg: 7.8, Max: 47.2, Diff: 46.7, Sum: 297.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: 3.2, Max: 20.1, Diff: 20.1, Sum: 121.3]
[Termination (ms): Min: 0.0, Avg: 35.9, Max: 41.1, Diff: 41.1, Sum: 1365.5]
[Termination Attempts: Min: 1, Avg: 2.8, Max: 6, Diff: 5, Sum: 105]
[GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 1.6]
[GC Worker Total (ms): Min: 46.6, Avg: 49.4, Max: 50.3, Diff: 3.7, Sum: 1876.3]
[GC Worker End (ms): Min: 529819374.2, Avg: 529819374.3, Max: 529819374.5, Diff: 0.2]
[Code Root Fixup: 0.2 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 2.6 ms]
[Other: 10.4 ms]
[Choose CSet: 0.7 ms]
[Ref Proc: 3.7 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 2.9 ms]
[Humongous Register: 0.1 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 2.5 ms]
[Eden: 808.0M(808.0M)->0.0B(808.0M) Survivors: 8192.0K->8192.0K Heap: 2776.3M(16.0G)->1360.4M(16.0G)]
[Times: user=1.13 sys=0.52, real=0.07 secs]
2018-04-11T08:14:34.290+0000: 529820.248: [GC pause (G1 Evacuation Pause) (mixed), 0.1133639 secs]
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment