JDK 17 benchmarks

README.md

Here are some benchmarks of JDK 17 GCs. I wanted to compare a basic service running on Vert.x fetching and stream (HTTP chuncked transfer) 500 rows, serializing them to JSON (almost 30KB when serialized), send them over wire (buffered). Here are tech specs of this benchmarks:

• All processes including Vegeta, Postgres, and Java were running on same machine
• Host machine is a 2015 Macbook Pro (2.2 GHz Quadcore Intel Core i7), with 16 GB 1600 MHZ DDR3 RAM.
• Each benchmark was warmed up by first running atleast 3 1000 req/s stress tests before the readings posted in these benchmarks.
• RSS of each process was noted towards the end of benchmarks.

g1gc.md

Launch parameters java -server -XX:+UseG1GC -Xmx1g -Xms1g -Xlog:gc* -jar app.jar

RSS: 967M

Vegeta Logs

➜  stress_test git:(master) echo 'GET http://localhost:8888/' | vegeta attack -rate=1000 -duration=30s | vegeta report
Requests      [total, rate, throughput]         30000, 1000.04, 999.95
Duration      [total, attack, wait]             30.002s, 29.999s, 2.69ms
Latencies     [min, mean, 50, 90, 95, 99, max]  2.486ms, 4.232ms, 3.561ms, 4.889ms, 7.53ms, 20.244ms, 72.736ms
Bytes In      [total, mean]                     926760000, 30892.00
Bytes Out     [total, mean]                     0, 0.00
Success       [ratio]                           100.00%
Status Codes  [code:count]                      200:30000
Error Set:
➜  stress_test git:(master) echo 'GET http://localhost:8888/' | vegeta attack -rate=1000 -duration=30s | vegeta report
Requests      [total, rate, throughput]         30000, 1000.04, 999.92
Duration      [total, attack, wait]             30.002s, 29.999s, 3.502ms
Latencies     [min, mean, 50, 90, 95, 99, max]  2.507ms, 3.717ms, 3.532ms, 4.311ms, 4.666ms, 6.076ms, 41.346ms
Bytes In      [total, mean]                     926760000, 30892.00
Bytes Out     [total, mean]                     0, 0.00
Success       [ratio]                           100.00%
Status Codes  [code:count]                      200:30000
Error Set:
➜  stress_test git:(master) echo 'GET http://localhost:8888/' | vegeta attack -rate=1000 -duration=30s | vegeta report
Requests      [total, rate, throughput]         30000, 1000.04, 999.93
Duration      [total, attack, wait]             30.002s, 29.999s, 3.169ms
Latencies     [min, mean, 50, 90, 95, 99, max]  2.504ms, 3.842ms, 3.617ms, 4.485ms, 4.95ms, 7.065ms, 113.221ms
Bytes In      [total, mean]                     926760000, 30892.00
Bytes Out     [total, mean]                     0, 0.00
Success       [ratio]                           100.00%
Status Codes  [code:count]                      200:30000
Error Set:

Sample GC logs

[188.937s][info][gc,metaspace] GC(221) Metaspace: 26901K(27328K)->26901K(27328K) NonClass: 23729K(23936K)->23729K(23936K) Class: 3172K(3392K)->3172K(3392K)
[188.937s][info][gc          ] GC(221) Pause Young (Normal) (G1 Evacuation Pause) 638M->25M(1024M) 1.338ms
[188.937s][info][gc,cpu      ] GC(221) User=0.00s Sys=0.00s Real=0.00s
[189.620s][info][gc,start    ] GC(222) Pause Young (Normal) (G1 Evacuation Pause)
[189.620s][info][gc,task     ] GC(222) Using 8 workers of 8 for evacuation
[189.622s][info][gc,phases   ] GC(222)   Pre Evacuate Collection Set: 0.1ms
[189.622s][info][gc,phases   ] GC(222)   Merge Heap Roots: 0.1ms
[189.622s][info][gc,phases   ] GC(222)   Evacuate Collection Set: 0.4ms
[189.622s][info][gc,phases   ] GC(222)   Post Evacuate Collection Set: 0.5ms
[189.622s][info][gc,phases   ] GC(222)   Other: 0.2ms
[189.622s][info][gc,heap     ] GC(222) Eden regions: 613->0(613)
[189.622s][info][gc,heap     ] GC(222) Survivor regions: 1->1(77)
[189.622s][info][gc,heap     ] GC(222) Old regions: 23->23
[189.622s][info][gc,heap     ] GC(222) Archive regions: 2->2
[189.622s][info][gc,heap     ] GC(222) Humongous regions: 2->2
[189.622s][info][gc,metaspace] GC(222) Metaspace: 26901K(27328K)->26901K(27328K) NonClass: 23729K(23936K)->23729K(23936K) Class: 3172K(3392K)->3172K(3392K)
[189.622s][info][gc          ] GC(222) Pause Young (Normal) (G1 Evacuation Pause) 638M->25M(1024M) 1.225ms
[189.622s][info][gc,cpu      ] GC(222) User=0.00s Sys=0.00s Real=0.00s
[190.306s][info][gc,start    ] GC(223) Pause Young (Normal) (G1 Evacuation Pause)
[190.306s][info][gc,task     ] GC(223) Using 8 workers of 8 for evacuation
[190.307s][info][gc,phases   ] GC(223)   Pre Evacuate Collection Set: 0.1ms
[190.307s][info][gc,phases   ] GC(223)   Merge Heap Roots: 0.1ms
[190.307s][info][gc,phases   ] GC(223)   Evacuate Collection Set: 0.3ms
[190.307s][info][gc,phases   ] GC(223)   Post Evacuate Collection Set: 0.4ms
[190.307s][info][gc,phases   ] GC(223)   Other: 0.1ms
[190.307s][info][gc,heap     ] GC(223) Eden regions: 613->0(613)
[190.307s][info][gc,heap     ] GC(223) Survivor regions: 1->1(77)
[190.307s][info][gc,heap     ] GC(223) Old regions: 23->23
[190.307s][info][gc,heap     ] GC(223) Archive regions: 2->2
[190.307s][info][gc,heap     ] GC(223) Humongous regions: 2->2
[190.307s][info][gc,metaspace] GC(223) Metaspace: 26901K(27328K)->26901K(27328K) NonClass: 23729K(23936K)->23729K(23936K) Class: 3172K(3392K)->3172K(3392K)
[190.307s][info][gc          ] GC(223) Pause Young (Normal) (G1 Evacuation Pause) 638M->25M(1024M) 1.024ms
[190.307s][info][gc,cpu      ] GC(223) User=0.00s Sys=0.00s Real=0.00s
[190.992s][info][gc,start    ] GC(224) Pause Young (Normal) (G1 Evacuation Pause)
[190.992s][info][gc,task     ] GC(224) Using 8 workers of 8 for evacuation
[190.994s][info][gc,phases   ] GC(224)   Pre Evacuate Collection Set: 0.1ms
[190.994s][info][gc,phases   ] GC(224)   Merge Heap Roots: 0.1ms
[190.994s][info][gc,phases   ] GC(224)   Evacuate Collection Set: 0.4ms
[190.994s][info][gc,phases   ] GC(224)   Post Evacuate Collection Set: 0.5ms
[190.994s][info][gc,phases   ] GC(224)   Other: 0.1ms
[190.994s][info][gc,heap     ] GC(224) Eden regions: 613->0(613)
[190.994s][info][gc,heap     ] GC(224) Survivor regions: 1->1(77)
[190.994s][info][gc,heap     ] GC(224) Old regions: 23->23
[190.994s][info][gc,heap     ] GC(224) Archive regions: 2->2
[190.994s][info][gc,heap     ] GC(224) Humongous regions: 2->2
[190.994s][info][gc,metaspace] GC(224) Metaspace: 26901K(27328K)->26901K(27328K) NonClass: 23729K(23936K)->23729K(23936K) Class: 3172K(3392K)->3172K(3392K)
[190.994s][info][gc          ] GC(224) Pause Young (Normal) (G1 Evacuation Pause) 638M->25M(1024M) 1.239ms
[190.994s][info][gc,cpu      ] GC(224) User=0.00s Sys=0.00s Real=0.00s

shenandoahgc.md

Launch parameters java -server -XX:+UseShenandoahGC -Xmx1g -Xms1g -Xlog:gc,ergo,heap -jar app.jar

RSS: 1256M

Vegeta Logs

➜  stress_test git:(master) echo 'GET http://localhost:8888/' | vegeta attack -rate=1000 -duration=30s | vegeta report
Requests      [total, rate, throughput]         30000, 1000.05, 999.93
Duration      [total, attack, wait]             30.002s, 29.999s, 3.421ms
Latencies     [min, mean, 50, 90, 95, 99, max]  2.594ms, 4.24ms, 3.609ms, 4.937ms, 6.74ms, 21.854ms, 49.532ms
Bytes In      [total, mean]                     926760000, 30892.00
Bytes Out     [total, mean]                     0, 0.00
Success       [ratio]                           100.00%
Status Codes  [code:count]                      200:30000
Error Set:
➜  stress_test git:(master) echo 'GET http://localhost:8888/' | vegeta attack -rate=1000 -duration=30s | vegeta report
Requests      [total, rate, throughput]         30000, 1000.05, 999.93
Duration      [total, attack, wait]             30.002s, 29.999s, 3.459ms
Latencies     [min, mean, 50, 90, 95, 99, max]  2.638ms, 3.948ms, 3.584ms, 4.62ms, 5.599ms, 10.219ms, 41.205ms
Bytes In      [total, mean]                     926760000, 30892.00
Bytes Out     [total, mean]                     0, 0.00
Success       [ratio]                           100.00%
Status Codes  [code:count]                      200:30000
Error Set:
➜  stress_test git:(master) echo 'GET http://localhost:8888/' | vegeta attack -rate=1000 -duration=30s | vegeta report
Requests      [total, rate, throughput]         30000, 1000.04, 999.94
Duration      [total, attack, wait]             30.002s, 29.999s, 3.035ms
Latencies     [min, mean, 50, 90, 95, 99, max]  2.521ms, 3.747ms, 3.404ms, 4.341ms, 4.852ms, 11.423ms, 46.493ms
Bytes In      [total, mean]                     926760000, 30892.00
Bytes Out     [total, mean]                     0, 0.00
Success       [ratio]                           100.00%
Status Codes  [code:count]                      200:30000
Error Set:
➜  stress_test git:(master) echo 'GET http://localhost:8888/' | vegeta attack -rate=1000 -duration=30s | vegeta report
Requests      [total, rate, throughput]         30000, 1000.04, 999.93
Duration      [total, attack, wait]             30.002s, 29.999s, 3.123ms
Latencies     [min, mean, 50, 90, 95, 99, max]  2.583ms, 4.244ms, 3.596ms, 4.865ms, 6.364ms, 25.158ms, 44.628ms
Bytes In      [total, mean]                     926760000, 30892.00
Bytes Out     [total, mean]                     0, 0.00
Success       [ratio]                           100.00%
Status Codes  [code:count]                      200:30000
Error Set:

Sample GC logs

[174.577s][info   ][gc     ] GC(158) Pause Init Mark (unload classes) 0.048ms
[174.578s][info   ][gc     ] GC(158) Concurrent marking roots 1.425ms
[174.592s][info   ][gc     ] GC(158) Concurrent marking (unload classes) 14.256ms
[174.593s][info   ][gc     ] GC(158) Pause Final Mark (unload classes) 0.138ms
[174.593s][info   ][gc     ] GC(158) Concurrent weak references 0.089ms
[174.594s][info   ][gc     ] GC(158) Concurrent weak roots 1.238ms
[174.594s][info   ][gc     ] GC(158) Concurrent cleanup 888M->74M(1024M) 0.220ms
[174.598s][info   ][gc     ] GC(158) Concurrent class unloading 3.604ms
[174.598s][info   ][gc     ] GC(158) Pause Final Roots 0.005ms
[175.477s][info   ][gc     ] Trigger: Free (100M) is below minimum threshold (102M)
[175.477s][info   ][gc     ] GC(159) Concurrent reset 0.334ms
[175.477s][info   ][gc     ] GC(159) Pause Init Mark (unload classes) 0.050ms
[175.479s][info   ][gc     ] GC(159) Concurrent marking roots 1.469ms
[175.493s][info   ][gc     ] GC(159) Concurrent marking (unload classes) 14.779ms
[175.494s][info   ][gc     ] GC(159) Pause Final Mark (unload classes) 0.201ms
[175.494s][info   ][gc     ] GC(159) Concurrent weak references 0.117ms
[175.496s][info   ][gc     ] GC(159) Concurrent weak roots 1.547ms
[175.496s][info   ][gc     ] GC(159) Concurrent cleanup 889M->75M(1024M) 0.328ms
[175.500s][info   ][gc     ] GC(159) Concurrent class unloading 4.513ms
[175.501s][info   ][gc     ] GC(159) Pause Final Roots 0.007ms
[176.376s][info   ][gc     ] Trigger: Free (101M) is below minimum threshold (102M)
[176.376s][info   ][gc     ] GC(160) Concurrent reset 0.276ms
[176.376s][info   ][gc     ] GC(160) Pause Init Mark (unload classes) 0.037ms
[176.378s][info   ][gc     ] GC(160) Concurrent marking roots 1.619ms
[176.392s][info   ][gc     ] GC(160) Concurrent marking (unload classes) 14.253ms
[176.393s][info   ][gc     ] GC(160) Pause Final Mark (unload classes) 0.176ms
[176.393s][info   ][gc     ] GC(160) Concurrent weak references 0.160ms
[176.394s][info   ][gc     ] GC(160) Concurrent weak roots 1.279ms
[176.394s][info   ][gc     ] GC(160) Concurrent cleanup 888M->74M(1024M) 0.215ms
[176.398s][info   ][gc     ] GC(160) Concurrent class unloading 3.877ms
[176.398s][info   ][gc     ] GC(160) Pause Final Roots 0.004ms
[177.276s][info   ][gc     ] Trigger: Free (101M) is below minimum threshold (102M)
[177.276s][info   ][gc     ] GC(161) Concurrent reset 0.400ms
[177.276s][info   ][gc     ] GC(161) Pause Init Mark (unload classes) 0.045ms
[177.278s][info   ][gc     ] GC(161) Concurrent marking roots 1.662ms
[177.293s][info   ][gc     ] GC(161) Concurrent marking (unload classes) 14.830ms
[177.293s][info   ][gc     ] GC(161) Pause Final Mark (unload classes) 0.199ms
[177.294s][info   ][gc     ] GC(161) Concurrent weak references 0.198ms
[177.295s][info   ][gc     ] GC(161) Concurrent weak roots 1.361ms
[177.295s][info   ][gc     ] GC(161) Concurrent cleanup 889M->76M(1024M) 0.255ms
[177.330s][info   ][gc     ] GC(161) Concurrent class unloading 34.163ms
[177.330s][info   ][gc     ] GC(161) Pause Final Roots 0.005ms
[178.165s][info   ][gc     ] Trigger: Average GC time (66.44 ms) is above the time for average allocation rate (903 MB/s) to deplete free headroom (61311K) (margin of error = 1.81)
[178.166s][info   ][gc     ] GC(162) Concurrent reset 0.458ms
[178.166s][info   ][gc     ] GC(162) Pause Init Mark (unload classes) 0.045ms
[178.168s][info   ][gc     ] GC(162) Concurrent marking roots 1.713ms
[178.183s][info   ][gc     ] GC(162) Concurrent marking (unload classes) 14.855ms
[178.183s][info   ][gc     ] GC(162) Pause Final Mark (unload classes) 0.151ms
[178.184s][info   ][gc     ] GC(162) Concurrent weak references 0.133ms
[178.185s][info   ][gc     ] GC(162) Concurrent weak roots 1.537ms
[178.186s][info   ][gc     ] GC(162) Concurrent cleanup 879M->73M(1024M) 0.282ms
[178.190s][info   ][gc     ] GC(162) Concurrent class unloading 4.457ms
[178.190s][info   ][gc     ] GC(162) Pause Final Roots 0.005ms
[179.067s][info   ][gc     ] Trigger: Free (102M) is below minimum threshold (102M)
[179.067s][info   ][gc     ] GC(163) Concurrent reset 0.400ms
[179.068s][info   ][gc     ] GC(163) Pause Init Mark (unload classes) 0.074ms
[179.070s][info   ][gc     ] GC(163) Concurrent marking roots 2.032ms
[179.084s][info   ][gc     ] GC(163) Concurrent marking (unload classes) 13.945ms
[179.084s][info   ][gc     ] GC(163) Pause Final Mark (unload classes) 0.229ms
[179.087s][info   ][gc     ] GC(163) Concurrent weak references 0.226ms
[179.088s][info   ][gc     ] GC(163) Concurrent weak roots 1.569ms
[179.089s][info   ][gc     ] GC(163) Concurrent cleanup 889M->74M(1024M) 0.298ms
[179.093s][info   ][gc     ] GC(163) Concurrent class unloading 4.373ms
[179.093s][info   ][gc     ] GC(163) Pause Final Roots 0.006ms
[179.968s][info   ][gc     ] Trigger: Free (101M) is below minimum threshold (102M)
[179.968s][info   ][gc     ] GC(164) Concurrent reset 0.516ms
[179.968s][info   ][gc     ] GC(164) Pause Init Mark (unload classes) 0.055ms
[179.970s][info   ][gc     ] GC(164) Concurrent marking roots 2.021ms
[179.984s][info   ][gc     ] GC(164) Concurrent marking (unload classes) 13.148ms
[179.984s][info   ][gc     ] GC(164) Pause Final Mark (unload classes) 0.156ms
[179.984s][info   ][gc     ] GC(164) Concurrent weak references 0.067ms
[179.986s][info   ][gc     ] GC(164) Concurrent weak roots 1.809ms
[179.986s][info   ][gc     ] GC(164) Concurrent cleanup 888M->72M(1024M) 0.218ms
[179.990s][info   ][gc     ] GC(164) Concurrent class unloading 4.298ms
[179.991s][info   ][gc     ] GC(164) Pause Final Roots 0.006ms
[180.869s][info   ][gc     ] Trigger: Free (101M) is below minimum threshold (102M)
[180.870s][info   ][gc     ] GC(165) Concurrent reset 0.585ms
[180.870s][info   ][gc     ] GC(165) Pause Init Mark (unload classes) 0.063ms
[180.872s][info   ][gc     ] GC(165) Concurrent marking roots 1.974ms
[180.888s][info   ][gc     ] GC(165) Concurrent marking (unload classes) 15.692ms
[180.889s][info   ][gc     ] GC(165) Pause Final Mark (unload classes) 0.158ms
[180.893s][info   ][gc     ] GC(165) Concurrent weak references 0.307ms
[180.895s][info   ][gc     ] GC(165) Concurrent weak roots 1.583ms
[180.895s][info   ][gc     ] GC(165) Concurrent cleanup 893M->79M(1024M) 0.223ms
[180.900s][info   ][gc     ] GC(165) Concurrent class unloading 4.989ms
[180.900s][info   ][gc     ] GC(165) Pause Final Roots 0.006ms
[181.771s][info   ][gc     ] Trigger: Free (101M) is below minimum threshold (102M)
[181.772s][info   ][gc     ] GC(166) Concurrent reset 0.680ms
[181.772s][info   ][gc     ] GC(166) Pause Init Mark (unload classes) 0.063ms
[181.774s][info   ][gc     ] GC(166) Concurrent marking roots 1.506ms
[181.789s][info   ][gc     ] GC(166) Concurrent marking (unload classes) 14.758ms
[181.789s][info   ][gc     ] GC(166) Pause Final Mark (unload classes) 0.322ms
[181.790s][info   ][gc     ] GC(166) Concurrent weak references 0.265ms
[181.792s][info   ][gc     ] GC(166) Concurrent weak roots 2.404ms
[181.793s][info   ][gc     ] GC(166) Concurrent cleanup 890M->77M(1024M) 0.229ms
[181.798s][info   ][gc     ] GC(166) Concurrent class unloading 5.002ms
[181.798s][info   ][gc     ] GC(166) Pause Final Roots 0.006ms
[182.670s][info   ][gc     ] Trigger: Free (101M) is below minimum threshold (102M)
[182.670s][info   ][gc     ] GC(167) Concurrent reset 0.388ms
[182.671s][info   ][gc     ] GC(167) Pause Init Mark (unload classes) 0.081ms
[182.672s][info   ][gc     ] GC(167) Concurrent marking roots 1.664ms
[182.687s][info   ][gc     ] GC(167) Concurrent marking (unload classes) 14.266ms
[182.687s][info   ][gc     ] GC(167) Pause Final Mark (unload classes) 0.242ms
[182.688s][info   ][gc     ] GC(167) Concurrent weak references 0.101ms
[182.689s][info   ][gc     ] GC(167) Concurrent weak roots 1.568ms
[182.690s][info   ][gc     ] GC(167) Concurrent cleanup 888M->74M(1024M) 0.238ms
[182.694s][info   ][gc     ] GC(167) Concurrent class unloading 4.575ms
[182.694s][info   ][gc     ] GC(167) Pause Final Roots 0.005ms
[183.570s][info   ][gc     ] Trigger: Free (102M) is below minimum threshold (102M)
[183.571s][info   ][gc     ] GC(168) Concurrent reset 0.289ms
[183.571s][info   ][gc     ] GC(168) Pause Init Mark (unload classes) 0.044ms
[183.572s][info   ][gc     ] GC(168) Concurrent marking roots 1.408ms
[183.587s][info   ][gc     ] GC(168) Concurrent marking (unload classes) 14.896ms
[183.588s][info   ][gc     ] GC(168) Pause Final Mark (unload classes) 0.210ms
[183.590s][info   ][gc     ] GC(168) Concurrent weak references 0.100ms
[183.592s][info   ][gc     ] GC(168) Concurrent weak roots 1.590ms
[183.592s][info   ][gc     ] GC(168) Concurrent cleanup 889M->75M(1024M) 0.247ms
[183.597s][info   ][gc     ] GC(168) Concurrent class unloading 4.939ms
[183.597s][info   ][gc     ] GC(168) Pause Final Roots 0.007ms
[184.469s][info   ][gc     ] Trigger: Free (101M) is below minimum threshold (102M)
[184.470s][info   ][gc     ] GC(169) Concurrent reset 0.388ms
[184.470s][info   ][gc     ] GC(169) Pause Init Mark (unload classes) 0.057ms
[184.472s][info   ][gc     ] GC(169) Concurrent marking roots 1.762ms
[184.484s][info   ][gc     ] GC(169) Concurrent marking (unload classes) 12.481ms
[184.485s][info   ][gc     ] GC(169) Pause Final Mark (unload classes) 0.157ms
[184.485s][info   ][gc     ] GC(169) Concurrent weak references 0.037ms
[184.486s][info   ][gc     ] GC(169) Concurrent weak roots 1.235ms
[184.487s][info   ][gc     ] GC(169) Concurrent cleanup 886M->71M(1024M) 0.199ms
[184.491s][info   ][gc     ] GC(169) Concurrent class unloading 4.179ms
[184.491s][info   ][gc     ] GC(169) Pause Final Roots 0.007ms

zgc.md

Launch parameters java -server -XX:+UseZGC -Xmx1g -Xms1g -Xlog:gc,ergo,heap,gc+phases -jar app.jar

RSS: 2768M

Vegeta Logs

➜  stress_test git:(master) echo 'GET http://localhost:8888/' | vegeta attack -rate=1000 -duration=30s | vegeta report
Requests      [total, rate, throughput]         30000, 1000.04, 999.92
Duration      [total, attack, wait]             30.002s, 29.999s, 3.5ms
Latencies     [min, mean, 50, 90, 95, 99, max]  2.5ms, 4.423ms, 3.6ms, 5.691ms, 7.838ms, 20.55ms, 70.54ms
Bytes In      [total, mean]                     926760000, 30892.00
Bytes Out     [total, mean]                     0, 0.00
Success       [ratio]                           100.00%
Status Codes  [code:count]                      200:30000
Error Set:
➜  stress_test git:(master) echo 'GET http://localhost:8888/' | vegeta attack -rate=1000 -duration=30s | vegeta report
Requests      [total, rate, throughput]         30000, 1000.03, 999.83
Duration      [total, attack, wait]             30.005s, 29.999s, 6.213ms
Latencies     [min, mean, 50, 90, 95, 99, max]  2.259ms, 5.37ms, 3.772ms, 5.886ms, 10.512ms, 47.786ms, 131.695ms
Bytes In      [total, mean]                     926760000, 30892.00
Bytes Out     [total, mean]                     0, 0.00
Success       [ratio]                           100.00%
Status Codes  [code:count]                      200:30000
Error Set:
➜  stress_test git:(master) echo 'GET http://localhost:8888/' | vegeta attack -rate=1000 -duration=30s | vegeta report
Requests      [total, rate, throughput]         30000, 1000.04, 999.91
Duration      [total, attack, wait]             30.003s, 29.999s, 3.953ms
Latencies     [min, mean, 50, 90, 95, 99, max]  2.378ms, 3.803ms, 3.496ms, 4.531ms, 5.126ms, 9.303ms, 38.423ms
Bytes In      [total, mean]                     926760000, 30892.00
Bytes Out     [total, mean]                     0, 0.00
Success       [ratio]                           100.00%
Status Codes  [code:count]                      200:30000
Error Set:
➜  stress_test git:(master) echo 'GET http://localhost:8888/' | vegeta attack -rate=1000 -duration=30s | vegeta report
Requests      [total, rate, throughput]         30000, 1000.04, 999.92
Duration      [total, attack, wait]             30.002s, 29.999s, 3.622ms
Latencies     [min, mean, 50, 90, 95, 99, max]  2.615ms, 4.692ms, 3.802ms, 6.18ms, 8.549ms, 24.895ms, 60.47ms
Bytes In      [total, mean]                     926760000, 30892.00
Bytes Out     [total, mean]                     0, 0.00
Success       [ratio]                           100.00%
Status Codes  [code:count]                      200:30000
Error Set:

Sample GC logs

[205.890s][info   ][gc       ] GC(247) Garbage Collection (Allocation Rate) 824M(80%)->118M(12%)
[206.530s][info   ][gc,phases] GC(248) Pause Mark Start 0.006ms
[206.568s][info   ][gc,phases] GC(248) Concurrent Mark 37.283ms
[206.568s][info   ][gc,phases] GC(248) Pause Mark End 0.018ms
[206.568s][info   ][gc,phases] GC(248) Concurrent Mark Free 0.002ms
[206.583s][info   ][gc,phases] GC(248) Concurrent Process Non-Strong References 14.989ms
[206.583s][info   ][gc,phases] GC(248) Concurrent Reset Relocation Set 0.003ms
[206.588s][info   ][gc,phases] GC(248) Concurrent Select Relocation Set 5.598ms
[206.589s][info   ][gc,phases] GC(248) Pause Relocate Start 0.009ms
[206.592s][info   ][gc,phases] GC(248) Concurrent Relocate 2.491ms
[206.592s][info   ][gc       ] GC(248) Garbage Collection (Allocation Rate) 830M(81%)->120M(12%)
[207.231s][info   ][gc,phases] GC(249) Pause Mark Start 0.006ms
[207.265s][info   ][gc,phases] GC(249) Concurrent Mark 33.418ms
[207.265s][info   ][gc,phases] GC(249) Pause Mark End 0.010ms
[207.265s][info   ][gc,phases] GC(249) Concurrent Mark Free 0.001ms
[207.282s][info   ][gc,phases] GC(249) Concurrent Process Non-Strong References 16.780ms
[207.282s][info   ][gc,phases] GC(249) Concurrent Reset Relocation Set 0.003ms
[207.289s][info   ][gc,phases] GC(249) Concurrent Select Relocation Set 7.518ms
[207.290s][info   ][gc,phases] GC(249) Pause Relocate Start 0.010ms
[207.294s][info   ][gc,phases] GC(249) Concurrent Relocate 3.756ms
[207.294s][info   ][gc       ] GC(249) Garbage Collection (Allocation Rate) 828M(81%)->118M(12%)
[207.930s][info   ][gc,phases] GC(250) Pause Mark Start 0.018ms
[207.969s][info   ][gc,phases] GC(250) Concurrent Mark 38.545ms
[207.969s][info   ][gc,phases] GC(250) Pause Mark End 0.027ms
[207.969s][info   ][gc,phases] GC(250) Concurrent Mark Free 0.002ms
[207.984s][info   ][gc,phases] GC(250) Concurrent Process Non-Strong References 14.614ms
[207.984s][info   ][gc,phases] GC(250) Concurrent Reset Relocation Set 0.010ms
[207.988s][info   ][gc,phases] GC(250) Concurrent Select Relocation Set 3.916ms
[207.988s][info   ][gc,phases] GC(250) Pause Relocate Start 0.009ms
[207.991s][info   ][gc,phases] GC(250) Concurrent Relocate 2.640ms
[207.991s][info   ][gc       ] GC(250) Garbage Collection (Allocation Rate) 828M(81%)->118M(12%)
[208.630s][info   ][gc,phases] GC(251) Pause Mark Start 0.006ms
[208.672s][info   ][gc,phases] GC(251) Concurrent Mark 41.287ms
[208.672s][info   ][gc,phases] GC(251) Pause Mark End 0.018ms
[208.672s][info   ][gc,phases] GC(251) Concurrent Mark Free 0.001ms
[208.687s][info   ][gc,phases] GC(251) Concurrent Process Non-Strong References 15.217ms
[208.688s][info   ][gc,phases] GC(251) Concurrent Reset Relocation Set 0.004ms
[208.691s][info   ][gc,phases] GC(251) Concurrent Select Relocation Set 3.941ms
[208.692s][info   ][gc,phases] GC(251) Pause Relocate Start 0.007ms
[208.696s][info   ][gc,phases] GC(251) Concurrent Relocate 2.536ms
[208.696s][info   ][gc       ] GC(251) Garbage Collection (Allocation Rate) 830M(81%)->122M(12%)
[209.330s][info   ][gc,phases] GC(252) Pause Mark Start 0.007ms
[209.365s][info   ][gc,phases] GC(252) Concurrent Mark 34.617ms
[209.365s][info   ][gc,phases] GC(252) Pause Mark End 0.012ms
[209.366s][info   ][gc,phases] GC(252) Concurrent Mark Free 0.001ms
[209.381s][info   ][gc,phases] GC(252) Concurrent Process Non-Strong References 15.700ms
[209.381s][info   ][gc,phases] GC(252) Concurrent Reset Relocation Set 0.004ms
[209.386s][info   ][gc,phases] GC(252) Concurrent Select Relocation Set 5.164ms
[209.387s][info   ][gc,phases] GC(252) Pause Relocate Start 0.009ms
[209.391s][info   ][gc,phases] GC(252) Concurrent Relocate 2.749ms
[209.391s][info   ][gc       ] GC(252) Garbage Collection (Allocation Rate) 828M(81%)->120M(12%)
[210.031s][info   ][gc,phases] GC(253) Pause Mark Start 0.006ms
[210.068s][info   ][gc,phases] GC(253) Concurrent Mark 36.789ms
[210.068s][info   ][gc,phases] GC(253) Pause Mark End 0.025ms
[210.068s][info   ][gc,phases] GC(253) Concurrent Mark Free 0.001ms
[210.084s][info   ][gc,phases] GC(253) Concurrent Process Non-Strong References 15.490ms
[210.084s][info   ][gc,phases] GC(253) Concurrent Reset Relocation Set 0.003ms
[210.089s][info   ][gc,phases] GC(253) Concurrent Select Relocation Set 5.445ms
[210.089s][info   ][gc,phases] GC(253) Pause Relocate Start 0.009ms
[210.092s][info   ][gc,phases] GC(253) Concurrent Relocate 2.704ms
[210.092s][info   ][gc       ] GC(253) Garbage Collection (Allocation Rate) 828M(81%)->118M(12%)
[210.731s][info   ][gc,phases] GC(254) Pause Mark Start 0.007ms
[210.768s][info   ][gc,phases] GC(254) Concurrent Mark 37.301ms
[210.768s][info   ][gc,phases] GC(254) Pause Mark End 0.010ms
[210.768s][info   ][gc,phases] GC(254) Concurrent Mark Free 0.001ms
[210.783s][info   ][gc,phases] GC(254) Concurrent Process Non-Strong References 15.003ms
[210.783s][info   ][gc,phases] GC(254) Concurrent Reset Relocation Set 0.004ms
[210.788s][info   ][gc,phases] GC(254) Concurrent Select Relocation Set 4.772ms
[210.788s][info   ][gc,phases] GC(254) Pause Relocate Start 0.019ms
[210.791s][info   ][gc,phases] GC(254) Concurrent Relocate 2.692ms
[210.791s][info   ][gc       ] GC(254) Garbage Collection (Allocation Rate) 826M(81%)->122M(12%)
[211.431s][info   ][gc,phases] GC(255) Pause Mark Start 0.006ms
[211.475s][info   ][gc,phases] GC(255) Concurrent Mark 44.580ms
[211.476s][info   ][gc,phases] GC(255) Pause Mark End 0.020ms
[211.486s][info   ][gc,phases] GC(255) Concurrent Mark Free 0.001ms
[211.500s][info   ][gc,phases] GC(255) Concurrent Process Non-Strong References 13.414ms
[211.500s][info   ][gc,phases] GC(255) Concurrent Reset Relocation Set 0.003ms
[211.507s][info   ][gc,phases] GC(255) Concurrent Select Relocation Set 7.504ms
[211.507s][info   ][gc,phases] GC(255) Pause Relocate Start 0.010ms
[211.523s][info   ][gc,phases] GC(255) Concurrent Relocate 5.372ms
[211.523s][info   ][gc       ] GC(255) Garbage Collection (Allocation Rate) 828M(81%)->148M(14%)
[212.131s][info   ][gc,phases] GC(256) Pause Mark Start 0.006ms
[212.169s][info   ][gc,phases] GC(256) Concurrent Mark 37.815ms
[212.170s][info   ][gc,phases] GC(256) Pause Mark End 0.030ms
[212.170s][info   ][gc,phases] GC(256) Concurrent Mark Free 0.002ms
[212.187s][info   ][gc,phases] GC(256) Concurrent Process Non-Strong References 16.922ms
[212.187s][info   ][gc,phases] GC(256) Concurrent Reset Relocation Set 0.003ms
[212.191s][info   ][gc,phases] GC(256) Concurrent Select Relocation Set 3.724ms
[212.191s][info   ][gc,phases] GC(256) Pause Relocate Start 0.009ms
[212.194s][info   ][gc,phases] GC(256) Concurrent Relocate 2.369ms
[212.194s][info   ][gc       ] GC(256) Garbage Collection (Allocation Rate) 830M(81%)->122M(12%)
[212.831s][info   ][gc,phases] GC(257) Pause Mark Start 0.007ms
[212.893s][info   ][gc,phases] GC(257) Concurrent Mark 62.418ms
[212.900s][info   ][gc,phases] GC(257) Pause Mark End 0.014ms
[212.911s][info   ][gc,phases] GC(257) Concurrent Mark Free 0.001ms
[212.934s][info   ][gc,phases] GC(257) Concurrent Process Non-Strong References 23.748ms
[212.934s][info   ][gc,phases] GC(257) Concurrent Reset Relocation Set 0.005ms
[212.942s][info   ][gc,phases] GC(257) Concurrent Select Relocation Set 7.939ms
[212.943s][info   ][gc,phases] GC(257) Pause Relocate Start 0.011ms
[212.946s][info   ][gc,phases] GC(257) Concurrent Relocate 2.933ms
[212.946s][info   ][gc       ] GC(257) Garbage Collection (Allocation Rate) 828M(81%)->130M(13%)
[213.427s][info   ][gc,phases] GC(258) Pause Mark Start 0.007ms
[213.465s][info   ][gc,phases] GC(258) Concurrent Mark 37.087ms
[213.465s][info   ][gc,phases] GC(258) Pause Mark End 0.020ms
[213.465s][info   ][gc,phases] GC(258) Concurrent Mark Free 0.001ms
[213.483s][info   ][gc,phases] GC(258) Concurrent Process Non-Strong References 18.542ms
[213.483s][info   ][gc,phases] GC(258) Concurrent Reset Relocation Set 0.004ms
[213.487s][info   ][gc,phases] GC(258) Concurrent Select Relocation Set 3.607ms
[213.487s][info   ][gc,phases] GC(258) Pause Relocate Start 0.007ms
[213.491s][info   ][gc,phases] GC(258) Concurrent Relocate 3.544ms
[213.491s][info   ][gc       ] GC(258) Garbage Collection (Allocation Rate) 716M(70%)->118M(12%)
[214.031s][info   ][gc,phases] GC(259) Pause Mark Start 0.006ms
[214.069s][info   ][gc,phases] GC(259) Concurrent Mark 37.798ms
[214.069s][info   ][gc,phases] GC(259) Pause Mark End 0.026ms
[214.070s][info   ][gc,phases] GC(259) Concurrent Mark Free 0.001ms
[214.083s][info   ][gc,phases] GC(259) Concurrent Process Non-Strong References 13.331ms
[214.083s][info   ][gc,phases] GC(259) Concurrent Reset Relocation Set 0.004ms
[214.088s][info   ][gc,phases] GC(259) Concurrent Select Relocation Set 4.752ms
[214.088s][info   ][gc,phases] GC(259) Pause Relocate Start 0.007ms
[214.090s][info   ][gc,phases] GC(259) Concurrent Relocate 2.120ms
[214.090s][info   ][gc       ] GC(259) Garbage Collection (Allocation Rate) 722M(71%)->114M(11%)
[214.731s][info   ][gc,phases] GC(260) Pause Mark Start 0.006ms
[214.766s][info   ][gc,phases] GC(260) Concurrent Mark 34.662ms
[214.766s][info   ][gc,phases] GC(260) Pause Mark End 0.010ms
[214.767s][info   ][gc,phases] GC(260) Concurrent Mark Free 0.001ms
[214.781s][info   ][gc,phases] GC(260) Concurrent Process Non-Strong References 14.112ms
[214.781s][info   ][gc,phases] GC(260) Concurrent Reset Relocation Set 0.003ms
[214.783s][info   ][gc,phases] GC(260) Concurrent Select Relocation Set 2.684ms
[214.784s][info   ][gc,phases] GC(260) Pause Relocate Start 0.009ms
[214.789s][info   ][gc,phases] GC(260) Concurrent Relocate 4.352ms
[214.789s][info   ][gc       ] GC(260) Garbage Collection (Allocation Rate) 828M(81%)->114M(11%)
[215.431s][info   ][gc,phases] GC(261) Pause Mark Start 0.006ms
[215.474s][info   ][gc,phases] GC(261) Concurrent Mark 42.088ms
[215.474s][info   ][gc,phases] GC(261) Pause Mark End 0.023ms
[215.474s][info   ][gc,phases] GC(261) Concurrent Mark Free 0.001ms
[215.487s][info   ][gc,phases] GC(261) Concurrent Process Non-Strong References 13.313ms
[215.487s][info   ][gc,phases] GC(261) Concurrent Reset Relocation Set 0.003ms
[215.491s][info   ][gc,phases] GC(261) Concurrent Select Relocation Set 3.340ms
[215.491s][info   ][gc,phases] GC(261) Pause Relocate Start 0.010ms
[215.493s][info   ][gc,phases] GC(261) Concurrent Relocate 2.375ms
[215.493s][info   ][gc       ] GC(261) Garbage Collection (Allocation Rate) 828M(81%)->106M(10%)
[216.127s][info   ][gc,phases] GC(262) Pause Mark Start 0.008ms
[216.165s][info   ][gc,phases] GC(262) Concurrent Mark 37.861ms
[216.165s][info   ][gc,phases] GC(262) Pause Mark End 0.011ms
[216.165s][info   ][gc,phases] GC(262) Concurrent Mark Free 0.001ms
[216.182s][info   ][gc,phases] GC(262) Concurrent Process Non-Strong References 16.775ms
[216.182s][info   ][gc,phases] GC(262) Concurrent Reset Relocation Set 0.003ms
[216.186s][info   ][gc,phases] GC(262) Concurrent Select Relocation Set 4.014ms
[216.186s][info   ][gc,phases] GC(262) Pause Relocate Start 0.016ms
[216.191s][info   ][gc,phases] GC(262) Concurrent Relocate 4.584ms
[216.191s][info   ][gc       ] GC(262) Garbage Collection (Allocation Rate) 822M(80%)->120M(12%)
[216.832s][info   ][gc,phases] GC(263) Pause Mark Start 0.010ms
[216.871s][info   ][gc,phases] GC(263) Concurrent Mark 39.538ms
[216.872s][info   ][gc,phases] GC(263) Pause Mark End 0.083ms
[216.874s][info   ][gc,phases] GC(263) Concurrent Mark Free 0.001ms
[216.890s][info   ][gc,phases] GC(263) Concurrent Process Non-Strong References 15.458ms
[216.890s][info   ][gc,phases] GC(263) Concurrent Reset Relocation Set 0.002ms
[216.894s][info   ][gc,phases] GC(263) Concurrent Select Relocation Set 3.978ms
[216.894s][info   ][gc,phases] GC(263) Pause Relocate Start 0.007ms
[216.897s][info   ][gc,phases] GC(263) Concurrent Relocate 2.897ms
[216.897s][info   ][gc       ] GC(263) Garbage Collection (Allocation Rate) 834M(81%)->126M(12%)
[217.532s][info   ][gc,phases] GC(264) Pause Mark Start 0.006ms
[217.576s][info   ][gc,phases] GC(264) Concurrent Mark 44.746ms
[217.577s][info   ][gc,phases] GC(264) Pause Mark End 0.023ms
[217.577s][info   ][gc,phases] GC(264) Concurrent Mark Free 0.002ms
[217.591s][info   ][gc,phases] GC(264) Concurrent Process Non-Strong References 14.491ms
[217.591s][info   ][gc,phases] GC(264) Concurrent Reset Relocation Set 0.003ms
[217.595s][info   ][gc,phases] GC(264) Concurrent Select Relocation Set 3.171ms
[217.595s][info   ][gc,phases] GC(264) Pause Relocate Start 0.007ms
[217.598s][info   ][gc,phases] GC(264) Concurrent Relocate 2.774ms
[217.598s][info   ][gc       ] GC(264) Garbage Collection (Allocation Rate) 828M(81%)->126M(12%)
[218.228s][info   ][gc,phases] GC(265) Pause Mark Start 0.007ms
[218.266s][info   ][gc,phases] GC(265) Concurrent Mark 38.011ms
[218.266s][info   ][gc,phases] GC(265) Pause Mark End 0.014ms
[218.267s][info   ][gc,phases] GC(265) Concurrent Mark Free 0.001ms
[218.283s][info   ][gc,phases] GC(265) Concurrent Process Non-Strong References 16.016ms
[218.283s][info   ][gc,phases] GC(265) Concurrent Reset Relocation Set 0.008ms
[218.287s][info   ][gc,phases] GC(265) Concurrent Select Relocation Set 3.704ms
[218.287s][info   ][gc,phases] GC(265) Pause Relocate Start 0.008ms
[218.291s][info   ][gc,phases] GC(265) Concurrent Relocate 4.628ms
[218.291s][info   ][gc       ] GC(265) Garbage Collection (Allocation Rate) 826M(81%)->122M(12%)
[218.931s][info   ][gc,phases] GC(266) Pause Mark Start 0.007ms
[218.971s][info   ][gc,phases] GC(266) Concurrent Mark 40.298ms
[218.971s][info   ][gc,phases] GC(266) Pause Mark End 0.032ms
[218.971s][info   ][gc,phases] GC(266) Concurrent Mark Free 0.002ms
[218.988s][info   ][gc,phases] GC(266) Concurrent Process Non-Strong References 16.839ms
[218.988s][info   ][gc,phases] GC(266) Concurrent Reset Relocation Set 0.003ms
[218.992s][info   ][gc,phases] GC(266) Concurrent Select Relocation Set 3.726ms
[218.993s][info   ][gc,phases] GC(266) Pause Relocate Start 0.009ms
[218.996s][info   ][gc,phases] GC(266) Concurrent Relocate 2.492ms
[218.996s][info   ][gc       ] GC(266) Garbage Collection (Allocation Rate) 832M(81%)->128M(12%)
[219.627s][info   ][gc,phases] GC(267) Pause Mark Start 0.007ms
[219.671s][info   ][gc,phases] GC(267) Concurrent Mark 44.695ms
[219.672s][info   ][gc,phases] GC(267) Pause Mark End 0.053ms
[219.682s][info   ][gc,phases] GC(267) Concurrent Mark Free 0.001ms
[219.698s][info   ][gc,phases] GC(267) Concurrent Process Non-Strong References 15.622ms
[219.698s][info   ][gc,phases] GC(267) Concurrent Reset Relocation Set 0.003ms
[219.701s][info   ][gc,phases] GC(267) Concurrent Select Relocation Set 3.154ms
[219.701s][info   ][gc,phases] GC(267) Pause Relocate Start 0.009ms
[219.716s][info   ][gc,phases] GC(267) Concurrent Relocate 4.196ms
[219.716s][info   ][gc       ] GC(267) Garbage Collection (Allocation Rate) 824M(80%)->150M(15%)
[220.332s][info   ][gc,phases] GC(268) Pause Mark Start 0.006ms
[220.371s][info   ][gc,phases] GC(268) Concurrent Mark 38.543ms
[220.371s][info   ][gc,phases] GC(268) Pause Mark End 0.040ms
[220.371s][info   ][gc,phases] GC(268) Concurrent Mark Free 0.003ms
[220.388s][info   ][gc,phases] GC(268) Concurrent Process Non-Strong References 17.191ms
[220.389s][info   ][gc,phases] GC(268) Concurrent Reset Relocation Set 0.004ms
[220.393s][info   ][gc,phases] GC(268) Concurrent Select Relocation Set 3.996ms
[220.393s][info   ][gc,phases] GC(268) Pause Relocate Start 0.009ms
[220.396s][info   ][gc,phases] GC(268) Concurrent Relocate 2.729ms
[220.397s][info   ][gc       ] GC(268) Garbage Collection (Allocation Rate) 832M(81%)->124M(12%)
[221.027s][info   ][gc,phases] GC(269) Pause Mark Start 0.007ms
[221.069s][info   ][gc,phases] GC(269) Concurrent Mark 42.116ms
[221.070s][info   ][gc,phases] GC(269) Pause Mark End 0.036ms
[221.075s][info   ][gc,phases] GC(269) Concurrent Mark Free 0.002ms
[221.089s][info   ][gc,phases] GC(269) Concurrent Process Non-Strong References 14.637ms
[221.089s][info   ][gc,phases] GC(269) Concurrent Reset Relocation Set 0.013ms
[221.096s][info   ][gc,phases] GC(269) Concurrent Select Relocation Set 6.430ms
[221.096s][info   ][gc,phases] GC(269) Pause Relocate Start 0.011ms
[221.099s][info   ][gc,phases] GC(269) Concurrent Relocate 2.495ms
[221.099s][info   ][gc       ] GC(269) Garbage Collection (Allocation Rate) 824M(80%)->124M(12%)
[221.730s][info   ][gc,phases] GC(270) Pause Mark Start 0.007ms
[221.770s][info   ][gc,phases] GC(270) Concurrent Mark 39.620ms
[221.770s][info   ][gc,phases] GC(270) Pause Mark End 0.030ms
[221.771s][info   ][gc,phases] GC(270) Concurrent Mark Free 0.002ms
[221.788s][info   ][gc,phases] GC(270) Concurrent Process Non-Strong References 17.024ms
[221.788s][info   ][gc,phases] GC(270) Concurrent Reset Relocation Set 0.004ms
[221.792s][info   ][gc,phases] GC(270) Concurrent Select Relocation Set 3.984ms
[221.792s][info   ][gc,phases] GC(270) Pause Relocate Start 0.010ms
[221.795s][info   ][gc,phases] GC(270) Concurrent Relocate 2.590ms
[221.795s][info   ][gc       ] GC(270) Garbage Collection (Allocation Rate) 830M(81%)->122M(12%)
[222.427s][info   ][gc,phases] GC(271) Pause Mark Start 0.008ms
[222.469s][info   ][gc,phases] GC(271) Concurrent Mark 42.388ms
[222.469s][info   ][gc,phases] GC(271) Pause Mark End 0.023ms
[222.469s][info   ][gc,phases] GC(271) Concurrent Mark Free 0.003ms
[222.483s][info   ][gc,phases] GC(271) Concurrent Process Non-Strong References 14.095ms
[222.484s][info   ][gc,phases] GC(271) Concurrent Reset Relocation Set 0.003ms
[222.487s][info   ][gc,phases] GC(271) Concurrent Select Relocation Set 3.569ms
[222.487s][info   ][gc,phases] GC(271) Pause Relocate Start 0.009ms
[222.493s][info   ][gc,phases] GC(271) Concurrent Relocate 5.909ms
[222.493s][info   ][gc       ] GC(271) Garbage Collection (Allocation Rate) 822M(80%)->124M(12%)
[223.128s][info   ][gc,phases] GC(272) Pause Mark Start 0.006ms
[223.170s][info   ][gc,phases] GC(272) Concurrent Mark 41.480ms
[223.170s][info   ][gc,phases] GC(272) Pause Mark End 0.033ms
[223.171s][info   ][gc,phases] GC(272) Concurrent Mark Free 0.003ms
[223.188s][info   ][gc,phases] GC(272) Concurrent Process Non-Strong References 16.328ms
[223.188s][info   ][gc,phases] GC(272) Concurrent Reset Relocation Set 0.003ms
[223.193s][info   ][gc,phases] GC(272) Concurrent Select Relocation Set 5.287ms
[223.193s][info   ][gc,phases] GC(272) Pause Relocate Start 0.009ms
[223.197s][info   ][gc,phases] GC(272) Concurrent Relocate 2.966ms
[223.197s][info   ][gc       ] GC(272) Garbage Collection (Allocation Rate) 828M(81%)->126M(12%)
[223.830s][info   ][gc,phases] GC(273) Pause Mark Start 0.007ms
[223.871s][info   ][gc,phases] GC(273) Concurrent Mark 40.632ms
[223.871s][info   ][gc,phases] GC(273) Pause Mark End 0.037ms
[223.871s][info   ][gc,phases] GC(273) Concurrent Mark Free 0.001ms
[223.887s][info   ][gc,phases] GC(273) Concurrent Process Non-Strong References 15.592ms
[223.887s][info   ][gc,phases] GC(273) Concurrent Reset Relocation Set 0.005ms
[223.890s][info   ][gc,phases] GC(273) Concurrent Select Relocation Set 3.560ms
[223.891s][info   ][gc,phases] GC(273) Pause Relocate Start 0.009ms
[223.895s][info   ][gc,phases] GC(273) Concurrent Relocate 4.397ms
[223.895s][info   ][gc       ] GC(273) Garbage Collection (Allocation Rate) 832M(81%)->122M(12%)
[224.526s][info   ][gc,phases] GC(274) Pause Mark Start 0.010ms
[224.569s][info   ][gc,phases] GC(274) Concurrent Mark 42.816ms
[224.570s][info   ][gc,phases] GC(274) Pause Mark End 0.030ms
[224.570s][info   ][gc,phases] GC(274) Concurrent Mark Free 0.002ms
[224.585s][info   ][gc,phases] GC(274) Concurrent Process Non-Strong References 14.996ms
[224.585s][info   ][gc,phases] GC(274) Concurrent Reset Relocation Set 0.004ms
[224.592s][info   ][gc,phases] GC(274) Concurrent Select Relocation Set 7.374ms
[224.592s][info   ][gc,phases] GC(274) Pause Relocate Start 0.009ms
[224.597s][info   ][gc,phases] GC(274) Concurrent Relocate 3.809ms
[224.597s][info   ][gc       ] GC(274) Garbage Collection (Allocation Rate) 824M(80%)->128M(12%)
[225.329s][info   ][gc,phases] GC(275) Pause Mark Start 0.009ms
[225.354s][info   ][gc,phases] GC(275) Concurrent Mark 25.380ms
[225.355s][info   ][gc,phases] GC(275) Pause Mark End 0.018ms
[225.355s][info   ][gc,phases] GC(275) Concurrent Mark Free 0.001ms
[225.364s][info   ][gc,phases] GC(275) Concurrent Process Non-Strong References 8.891ms
[225.364s][info   ][gc,phases] GC(275) Concurrent Reset Relocation Set 0.003ms
[225.366s][info   ][gc,phases] GC(275) Concurrent Select Relocation Set 1.944ms
[225.366s][info   ][gc,phases] GC(275) Pause Relocate Start 0.006ms
[225.366s][info   ][gc,phases] GC(275) Concurrent Relocate 0.868ms
[225.367s][info   ][gc       ] GC(275) Garbage Collection (Allocation Rate) 750M(73%)->46M(4%)