max_new_space_size diff

max_new_space_size.md

尝试调整新生代的内存大小

默认不加任何参数 (16MB)

$ ./bin/node/bin/node --trace_gc dispatch.js
[29594]       31 ms: Scavenge 1.6 (37.1) -> 1.5 (38.1) MB, 1.3 ms [Runtime::PerformGC].
[29594]       63 ms: Scavenge 2.9 (39.1) -> 2.7 (40.1) MB, 1.4 ms [Runtime::PerformGC].
[29594]       87 ms: Scavenge 3.8 (40.1) -> 3.4 (41.1) MB, 0.8 ms [allocation failure].
[29594]      121 ms: Scavenge 5.8 (41.1) -> 4.8 (42.1) MB, 1.6 ms [allocation failure].
[29594]      153 ms: Scavenge 6.7 (43.1) -> 5.7 (43.1) MB, 1.5 ms [allocation failure].
[29594]      170 ms: Scavenge 7.3 (43.1) -> 6.5 (43.1) MB, 1.7 ms [Runtime::PerformGC].
[29594]      214 ms: Scavenge 10.2 (43.1) -> 8.1 (45.1) MB, 2.9 ms [allocation failure].
[29594]      255 ms: Scavenge 11.4 (45.1) -> 9.1 (45.1) MB, 2.5 ms [allocation failure].
[29594]      320 ms: Scavenge 12.9 (46.1) -> 10.7 (46.1) MB, 3.4 ms (+ 6.4 ms in 29 steps since last GC) [allocation failure].
[29594]    54807 ms: Mark-sweep 17.5 (49.1) -> 9.3 (49.1) MB, 9.4 ms (+ 21.3 ms in 85 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[29594]    55009 ms: Scavenge 18.5 (49.1) -> 12.1 (49.1) MB, 3.0 ms [Runtime::PerformGC].
[29594]    55139 ms: Scavenge 19.1 (49.1) -> 13.8 (50.1) MB, 5.0 ms [Runtime::PerformGC].
[29594]    55292 ms: Mark-sweep 21.0 (51.1) -> 10.0 (51.1) MB, 5.6 ms (+ 18.5 ms in 78 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    55441 ms: Scavenge 18.6 (51.1) -> 12.1 (51.1) MB, 2.5 ms [Runtime::PerformGC].
[29594]    55568 ms: Scavenge 19.1 (51.1) -> 13.8 (51.1) MB, 4.9 ms [Runtime::PerformGC].
[29594]    55766 ms: Mark-sweep 23.0 (52.1) -> 10.6 (50.1) MB, 7.2 ms (+ 23.9 ms in 99 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    56079 ms: Scavenge 27.7 (51.1) -> 14.8 (51.1) MB, 5.9 ms [Runtime::PerformGC].
[29594]    56331 ms: Scavenge 28.8 (51.1) -> 18.0 (53.1) MB, 8.4 ms [Runtime::PerformGC].
[29594]    56468 ms: Mark-sweep 24.4 (55.1) -> 9.1 (55.1) MB, 4.1 ms (+ 16.3 ms in 67 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    56764 ms: Scavenge 26.2 (55.1) -> 13.3 (55.1) MB, 6.1 ms [allocation failure].
[29594]    57016 ms: Scavenge 27.3 (55.1) -> 16.7 (55.1) MB, 8.2 ms (+ 1.5 ms in 8 steps since last GC) [Runtime::PerformGC].
[29594]    57126 ms: Mark-sweep 21.8 (55.1) -> 9.8 (55.1) MB, 3.8 ms (+ 14.0 ms in 67 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    57414 ms: Scavenge 26.8 (55.1) -> 13.9 (55.1) MB, 4.8 ms [Runtime::PerformGC].
[29594]    57659 ms: Scavenge 27.9 (55.1) -> 17.3 (55.1) MB, 7.5 ms (+ 1.0 ms in 5 steps since last GC) [Runtime::PerformGC].
[29594]    57890 ms: Mark-sweep 28.7 (55.1) -> 10.1 (55.1) MB, 5.3 ms (+ 31.2 ms in 124 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    58185 ms: Scavenge 27.2 (55.1) -> 14.0 (55.1) MB, 4.4 ms [allocation failure].
[29594]    58433 ms: Scavenge 28.3 (55.1) -> 17.4 (55.1) MB, 7.3 ms [allocation failure].
[29594]    58725 ms: Mark-sweep 31.6 (57.1) -> 10.1 (55.1) MB, 7.8 ms (+ 38.5 ms in 150 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]    59019 ms: Scavenge 27.1 (55.1) -> 14.3 (55.1) MB, 5.5 ms [Runtime::PerformGC].
[29594]    59258 ms: Scavenge 28.2 (55.1) -> 17.1 (55.1) MB, 6.2 ms [Runtime::PerformGC].
[29594]    59452 ms: Mark-sweep 26.6 (56.1) -> 9.3 (54.1) MB, 5.3 ms (+ 25.0 ms in 101 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    59748 ms: Scavenge 26.4 (54.1) -> 13.5 (54.1) MB, 5.0 ms [allocation failure].
[29594]    60011 ms: Scavenge 27.5 (54.1) -> 16.8 (54.1) MB, 7.4 ms [Runtime::PerformGC].
[29594]    60308 ms: Mark-sweep 30.6 (56.1) -> 10.8 (55.1) MB, 6.5 ms (+ 35.6 ms in 137 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]    60640 ms: Scavenge 28.3 (55.1) -> 15.4 (55.1) MB, 5.3 ms [Runtime::PerformGC].
[29594]    60905 ms: Scavenge 29.4 (55.1) -> 18.8 (56.1) MB, 7.6 ms [allocation failure].
[29594]    61162 ms: Mark-sweep 31.0 (57.1) -> 10.8 (56.1) MB, 5.9 ms (+ 26.7 ms in 118 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    61480 ms: Scavenge 28.0 (56.1) -> 15.0 (56.1) MB, 5.2 ms [Runtime::PerformGC].
[29594]    61743 ms: Scavenge 29.1 (56.1) -> 18.4 (56.1) MB, 8.4 ms [allocation failure].
[29594]    62096 ms: Scavenge 34.6 (58.1) -> 24.2 (60.1) MB, 9.0 ms (+ 37.0 ms in 176 steps since last GC) [Runtime::PerformGC].
[29594]    62274 ms: Mark-sweep 32.3 (62.1) -> 11.5 (60.1) MB, 7.6 ms (+ 58.1 ms in 250 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    62592 ms: Scavenge 28.6 (60.1) -> 15.7 (60.1) MB, 4.8 ms [Runtime::PerformGC].
[29594]    62856 ms: Scavenge 29.7 (60.1) -> 19.1 (60.1) MB, 7.4 ms [Runtime::PerformGC].
[29594]    63152 ms: Mark-sweep 32.2 (62.1) -> 10.8 (57.1) MB, 7.9 ms (+ 36.2 ms in 145 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]    63460 ms: Scavenge 28.0 (57.1) -> 15.0 (57.1) MB, 5.0 ms [Runtime::PerformGC].
[29594]    63713 ms: Scavenge 29.1 (57.1) -> 18.4 (57.1) MB, 7.3 ms [Runtime::PerformGC].
[29594]    64054 ms: Scavenge 34.7 (59.1) -> 24.3 (59.1) MB, 8.6 ms (+ 41.1 ms in 163 steps since last GC) [Runtime::PerformGC].
[29594]    64069 ms: Mark-sweep 24.7 (59.1) -> 10.8 (58.1) MB, 5.6 ms (+ 42.6 ms in 168 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    64368 ms: Scavenge 27.9 (58.1) -> 15.0 (58.1) MB, 4.8 ms [Runtime::PerformGC].
[29594]    64615 ms: Scavenge 29.0 (58.1) -> 18.4 (58.1) MB, 7.6 ms [allocation failure].
[29594]    64808 ms: Mark-sweep 27.4 (59.1) -> 10.2 (55.1) MB, 6.6 ms (+ 20.8 ms in 98 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    65112 ms: Scavenge 27.3 (55.1) -> 14.5 (55.1) MB, 5.1 ms [Runtime::PerformGC].
[29594]    65353 ms: Scavenge 28.4 (55.1) -> 17.5 (56.1) MB, 7.0 ms [allocation failure].
[29594]    65500 ms: Mark-sweep 24.8 (57.1) -> 9.6 (57.1) MB, 3.8 ms (+ 17.4 ms in 78 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    65793 ms: Scavenge 26.7 (57.1) -> 13.7 (57.1) MB, 5.4 ms [Runtime::PerformGC].
[29594]    66040 ms: Scavenge 27.7 (57.1) -> 17.1 (57.1) MB, 7.7 ms [Runtime::PerformGC].
[29594]    66258 ms: Mark-sweep 27.7 (58.1) -> 10.7 (52.1) MB, 7.3 ms (+ 26.4 ms in 112 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    66553 ms: Scavenge 27.8 (52.1) -> 14.8 (52.1) MB, 4.9 ms [Runtime::PerformGC].
[29594]    66798 ms: Scavenge 28.8 (52.1) -> 18.1 (54.1) MB, 10.0 ms [allocation failure].
[29594]    67020 ms: Mark-sweep 28.9 (55.1) -> 10.4 (54.1) MB, 6.2 ms (+ 27.4 ms in 113 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    67308 ms: Scavenge 27.5 (54.1) -> 14.3 (54.1) MB, 4.7 ms [allocation failure].
[29594]    67557 ms: Scavenge 28.5 (54.1) -> 17.7 (54.1) MB, 7.4 ms [allocation failure].
[29594]    67890 ms: Scavenge 33.9 (57.1) -> 23.4 (59.1) MB, 9.6 ms (+ 41.2 ms in 154 steps since last GC) [Runtime::PerformGC].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]    68016 ms: Mark-sweep 29.1 (60.1) -> 11.0 (59.1) MB, 7.9 ms (+ 57.5 ms in 208 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    68301 ms: Scavenge 28.0 (59.1) -> 14.9 (59.1) MB, 5.2 ms [allocation failure].
[29594]    68550 ms: Scavenge 29.1 (59.1) -> 18.3 (59.1) MB, 7.6 ms [allocation failure].
[29594]    68686 ms: Mark-sweep 24.9 (60.1) -> 9.7 (55.1) MB, 6.4 ms (+ 14.9 ms in 70 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    68975 ms: Scavenge 26.8 (55.1) -> 13.8 (55.1) MB, 5.1 ms [Runtime::PerformGC].
[29594]    69215 ms: Scavenge 27.8 (56.1) -> 16.5 (56.1) MB, 6.0 ms [Runtime::PerformGC].
[29594]    69343 ms: Mark-sweep 22.5 (56.1) -> 9.2 (53.1) MB, 4.0 ms (+ 12.4 ms in 59 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    69633 ms: Scavenge 26.3 (53.1) -> 13.3 (53.1) MB, 5.0 ms [Runtime::PerformGC].
[29594]    69878 ms: Scavenge 27.3 (53.1) -> 16.6 (53.1) MB, 7.1 ms [allocation failure].
[29594]    70186 ms: Mark-sweep 32.2 (55.1) -> 11.2 (55.1) MB, 6.0 ms (+ 38.4 ms in 157 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    70475 ms: Scavenge 28.3 (55.1) -> 15.4 (55.1) MB, 4.9 ms [Runtime::PerformGC].
[29594]    70720 ms: Scavenge 29.4 (55.1) -> 18.7 (57.1) MB, 8.5 ms [allocation failure].
[29594]    70920 ms: Mark-sweep 28.3 (59.1) -> 10.2 (55.1) MB, 7.4 ms (+ 24.2 ms in 100 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    71208 ms: Scavenge 27.3 (55.1) -> 14.0 (55.1) MB, 4.2 ms [allocation failure].
[29594]    71458 ms: Scavenge 28.3 (55.1) -> 17.4 (55.1) MB, 7.9 ms [Runtime::PerformGC].
[29594]    71789 ms: Scavenge 33.6 (57.1) -> 23.3 (58.1) MB, 8.4 ms (+ 42.5 ms in 157 steps since last GC) [allocation failure].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]    71886 ms: Mark-sweep 27.8 (59.1) -> 10.7 (59.1) MB, 5.0 ms (+ 55.0 ms in 199 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    72175 ms: Scavenge 27.8 (59.1) -> 14.8 (59.1) MB, 5.3 ms [allocation failure].
[29594]    72416 ms: Scavenge 28.8 (59.1) -> 18.0 (59.1) MB, 7.1 ms [Runtime::PerformGC].
[29594]    72533 ms: Mark-sweep 23.5 (59.1) -> 9.4 (54.1) MB, 5.1 ms (+ 12.5 ms in 61 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    72829 ms: Scavenge 26.5 (54.1) -> 13.5 (54.1) MB, 5.3 ms [allocation failure].
[29594]    73077 ms: Scavenge 27.5 (55.1) -> 16.8 (55.1) MB, 7.9 ms [Runtime::PerformGC].
[29594]    73405 ms: Scavenge 33.0 (57.1) -> 22.1 (57.1) MB, 7.4 ms (+ 41.1 ms in 153 steps since last GC) [allocation failure].
[29594]    73452 ms: Mark-sweep 24.2 (57.1) -> 10.9 (57.1) MB, 4.2 ms (+ 46.9 ms in 173 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    73745 ms: Scavenge 27.9 (57.1) -> 15.0 (57.1) MB, 5.5 ms [Runtime::PerformGC].
[29594]    73985 ms: Scavenge 29.0 (57.1) -> 18.3 (57.1) MB, 6.9 ms [allocation failure].
[29594]    74220 ms: Mark-sweep 29.9 (58.1) -> 10.6 (56.1) MB, 6.4 ms (+ 27.5 ms in 125 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]    74515 ms: Scavenge 27.7 (56.1) -> 14.7 (56.1) MB, 5.3 ms [Runtime::PerformGC].
[29594]    74756 ms: Scavenge 28.8 (56.1) -> 18.0 (56.1) MB, 7.2 ms [Runtime::PerformGC].
[29594]    75089 ms: Scavenge 34.2 (58.1) -> 23.8 (59.1) MB, 9.0 ms (+ 42.4 ms in 160 steps since last GC) [Runtime::PerformGC].
[29594]    75107 ms: Mark-sweep 24.4 (59.1) -> 10.7 (57.1) MB, 4.7 ms (+ 44.4 ms in 167 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    75396 ms: Scavenge 27.7 (57.1) -> 14.7 (57.1) MB, 5.5 ms [Runtime::PerformGC].
[29594]    75642 ms: Scavenge 28.8 (57.1) -> 18.1 (57.1) MB, 7.3 ms [Runtime::PerformGC].
[29594]    75971 ms: Scavenge 34.2 (59.1) -> 23.7 (59.1) MB, 7.9 ms (+ 40.2 ms in 164 steps since last GC) [Runtime::PerformGC].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]    76104 ms: Mark-sweep 29.7 (60.1) -> 11.0 (58.1) MB, 6.4 ms (+ 57.7 ms in 223 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    76393 ms: Scavenge 28.1 (58.1) -> 15.0 (58.1) MB, 5.0 ms [Runtime::PerformGC].
[29594]    76643 ms: Scavenge 29.1 (58.1) -> 18.3 (58.1) MB, 7.1 ms [Runtime::PerformGC].
[29594]    76828 ms: Mark-sweep 27.4 (59.1) -> 10.0 (56.1) MB, 5.5 ms (+ 22.6 ms in 101 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    77122 ms: Scavenge 27.1 (56.1) -> 14.1 (56.1) MB, 5.0 ms [Runtime::PerformGC].
[29594]    77362 ms: Scavenge 28.1 (56.1) -> 17.2 (56.1) MB, 6.8 ms [Runtime::PerformGC].
[29594]    77516 ms: Mark-sweep 24.8 (56.1) -> 9.5 (56.1) MB, 3.8 ms (+ 18.1 ms in 82 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    77806 ms: Scavenge 26.6 (56.1) -> 13.6 (56.1) MB, 5.1 ms [allocation failure].
[29594]    78050 ms: Scavenge 27.6 (56.1) -> 16.9 (56.1) MB, 7.1 ms [Runtime::PerformGC].
[29594]    78210 ms: Mark-sweep 24.9 (56.1) -> 10.6 (54.1) MB, 5.2 ms (+ 17.5 ms in 83 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    78505 ms: Scavenge 27.7 (54.1) -> 14.7 (54.1) MB, 5.5 ms [Runtime::PerformGC].
[29594]    78744 ms: Scavenge 28.7 (54.1) -> 18.0 (54.1) MB, 6.6 ms [Runtime::PerformGC].
[29594]    79069 ms: Scavenge 34.1 (56.1) -> 23.6 (58.1) MB, 9.5 ms (+ 38.8 ms in 157 steps since last GC) [allocation failure].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]    79205 ms: Mark-sweep 29.9 (60.1) -> 11.2 (60.1) MB, 5.7 ms (+ 56.2 ms in 218 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    79494 ms: Scavenge 28.3 (60.1) -> 15.3 (60.1) MB, 4.8 ms [Runtime::PerformGC].
[29594]    79739 ms: Scavenge 29.3 (60.1) -> 18.6 (60.1) MB, 7.2 ms [Runtime::PerformGC].
[29594]    79905 ms: Mark-sweep 26.7 (61.1) -> 10.0 (55.1) MB, 6.0 ms (+ 18.6 ms in 90 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]    80194 ms: Scavenge 27.1 (55.1) -> 14.1 (55.1) MB, 5.1 ms [Runtime::PerformGC].
[29594]    80433 ms: Scavenge 28.1 (55.1) -> 17.1 (56.1) MB, 6.6 ms [allocation failure].
[29594]    80553 ms: Mark-sweep 22.8 (56.1) -> 9.4 (53.1) MB, 4.4 ms (+ 13.0 ms in 56 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    80846 ms: Scavenge 26.5 (53.1) -> 13.5 (53.1) MB, 5.0 ms [allocation failure].
[29594]    81091 ms: Scavenge 27.5 (53.1) -> 16.8 (53.1) MB, 9.0 ms [Runtime::PerformGC].
[29594]    81287 ms: Mark-sweep 26.6 (54.1) -> 10.6 (54.1) MB, 4.4 ms (+ 23.8 ms in 98 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[29594]    81580 ms: Scavenge 27.7 (54.1) -> 14.7 (54.1) MB, 5.0 ms [Runtime::PerformGC].
[29594]    81823 ms: Scavenge 28.7 (54.1) -> 18.0 (55.1) MB, 7.8 ms [allocation failure].
[29594]    82011 ms: Mark-sweep 27.2 (56.1) -> 10.2 (54.1) MB, 5.1 ms (+ 21.5 ms in 94 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]    82297 ms: Scavenge 27.3 (54.1) -> 14.1 (54.1) MB, 4.5 ms [Runtime::PerformGC].
[29594]    82542 ms: Scavenge 28.3 (54.1) -> 17.5 (54.1) MB, 7.2 ms [allocation failure].
[29594]    82761 ms: Mark-sweep 28.0 (55.1) -> 10.1 (55.1) MB, 5.2 ms (+ 27.8 ms in 112 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    83056 ms: Scavenge 27.1 (55.1) -> 14.2 (55.1) MB, 5.5 ms [Runtime::PerformGC].
[29594]    83295 ms: Scavenge 28.2 (55.1) -> 16.9 (55.1) MB, 6.6 ms [Runtime::PerformGC].
[29594]    83409 ms: Mark-sweep 22.5 (55.1) -> 9.2 (53.1) MB, 3.7 ms (+ 12.2 ms in 62 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    83699 ms: Scavenge 26.3 (53.1) -> 13.3 (53.1) MB, 4.8 ms [Runtime::PerformGC].
[29594]    83949 ms: Scavenge 27.3 (54.1) -> 16.7 (54.1) MB, 7.7 ms [Runtime::PerformGC].
[29594]    84179 ms: Mark-sweep 27.6 (55.1) -> 10.6 (55.1) MB, 4.7 ms (+ 25.6 ms in 106 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    84486 ms: Scavenge 27.6 (55.1) -> 14.6 (55.1) MB, 4.8 ms [allocation failure].
[29594]    84743 ms: Scavenge 28.7 (55.1) -> 17.9 (55.1) MB, 6.8 ms [allocation failure].
[29594]    84895 ms: Mark-sweep 24.9 (56.1) -> 9.9 (54.1) MB, 4.5 ms (+ 15.9 ms in 74 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    85208 ms: Scavenge 27.0 (54.1) -> 14.0 (54.1) MB, 5.4 ms [Runtime::PerformGC].
[29594]    85466 ms: Scavenge 28.0 (54.1) -> 17.1 (54.1) MB, 7.2 ms [Runtime::PerformGC].
[29594]    85813 ms: Scavenge 33.4 (56.1) -> 22.9 (58.1) MB, 8.9 ms (+ 41.8 ms in 159 steps since last GC) [allocation failure].
[29594]    85893 ms: Mark-sweep 26.4 (59.1) -> 10.6 (59.1) MB, 4.9 ms (+ 51.7 ms in 192 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    86200 ms: Scavenge 27.7 (59.1) -> 14.7 (59.1) MB, 4.8 ms [Runtime::PerformGC].
[29594]    86459 ms: Scavenge 28.7 (59.1) -> 17.9 (59.1) MB, 7.2 ms [Runtime::PerformGC].
[29594]    86655 ms: Mark-sweep 27.0 (60.1) -> 9.7 (55.1) MB, 5.7 ms (+ 22.2 ms in 93 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    86971 ms: Scavenge 26.8 (55.1) -> 13.8 (55.1) MB, 5.5 ms [Runtime::PerformGC].
[29594]    87227 ms: Scavenge 27.8 (55.1) -> 17.1 (55.1) MB, 7.3 ms [Runtime::PerformGC].
[29594]    87413 ms: Mark-sweep 26.3 (56.1) -> 9.5 (56.1) MB, 3.9 ms (+ 22.9 ms in 89 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]    87706 ms: Scavenge 26.6 (56.1) -> 13.6 (56.1) MB, 5.3 ms [allocation failure].
[29594]    87947 ms: Scavenge 27.6 (56.1) -> 16.9 (56.1) MB, 6.9 ms [Runtime::PerformGC].
[29594]    88075 ms: Mark-sweep 23.0 (57.1) -> 10.1 (55.1) MB, 4.5 ms (+ 13.5 ms in 65 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    88363 ms: Scavenge 27.1 (55.1) -> 14.1 (55.1) MB, 5.0 ms [Runtime::PerformGC].
[29594]    88605 ms: Scavenge 28.2 (55.1) -> 17.5 (55.1) MB, 7.3 ms [Runtime::PerformGC].
[29594]    88813 ms: Mark-sweep 27.9 (56.1) -> 10.1 (56.1) MB, 4.7 ms (+ 26.0 ms in 106 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    89101 ms: Scavenge 27.2 (56.1) -> 14.2 (56.1) MB, 5.1 ms [Runtime::PerformGC].
[29594]    89341 ms: Scavenge 28.2 (56.1) -> 17.2 (56.1) MB, 6.7 ms [allocation failure].
[29594]    89573 ms: Mark-sweep 28.6 (57.1) -> 9.8 (55.1) MB, 5.6 ms (+ 29.2 ms in 117 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    89860 ms: Scavenge 26.9 (55.1) -> 13.9 (55.1) MB, 5.4 ms [Runtime::PerformGC].
[29594]    90102 ms: Scavenge 28.0 (55.1) -> 17.3 (55.1) MB, 7.0 ms [allocation failure].
[29594]    90242 ms: Mark-sweep 24.3 (55.1) -> 10.6 (54.1) MB, 5.7 ms (+ 16.1 ms in 76 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    90544 ms: Scavenge 27.8 (54.1) -> 14.9 (54.1) MB, 5.4 ms [allocation failure].
[29594]    90785 ms: Scavenge 28.8 (55.1) -> 18.2 (55.1) MB, 7.4 ms [allocation failure].
[29594]    91013 ms: Mark-sweep 29.4 (56.1) -> 10.4 (55.1) MB, 5.8 ms (+ 26.8 ms in 112 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    91298 ms: Scavenge 27.5 (55.1) -> 14.3 (55.1) MB, 4.6 ms [Runtime::PerformGC].
[29594]    91566 ms: Scavenge 28.8 (55.1) -> 17.9 (56.1) MB, 7.5 ms [Runtime::PerformGC].
[29594]    91911 ms: Scavenge 34.6 (59.1) -> 24.2 (60.1) MB, 9.1 ms (+ 42.4 ms in 166 steps since last GC) [allocation failure].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]    92028 ms: Mark-sweep 29.5 (61.1) -> 11.2 (59.1) MB, 7.8 ms (+ 56.2 ms in 219 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]    92328 ms: Scavenge 28.3 (59.1) -> 15.2 (59.1) MB, 5.3 ms [Runtime::PerformGC].
[29594]    92576 ms: Scavenge 29.3 (59.1) -> 18.6 (59.1) MB, 7.3 ms [Runtime::PerformGC].
[29594]    92920 ms: Scavenge 35.0 (61.1) -> 24.6 (61.1) MB, 8.5 ms (+ 43.2 ms in 168 steps since last GC) [Runtime::PerformGC].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]    93027 ms: Mark-sweep 29.3 (63.1) -> 11.2 (59.1) MB, 7.4 ms (+ 57.1 ms in 213 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]    93318 ms: Scavenge 28.2 (59.1) -> 15.1 (59.1) MB, 4.6 ms [Runtime::PerformGC].
[29594]    93566 ms: Scavenge 29.3 (59.1) -> 18.5 (59.1) MB, 7.3 ms [Runtime::PerformGC].
[29594]    93908 ms: Scavenge 34.8 (61.1) -> 24.4 (62.1) MB, 8.4 ms (+ 42.1 ms in 161 steps since last GC) [Runtime::PerformGC].
[29594]    94010 ms: Mark-sweep 29.0 (63.1) -> 11.0 (59.1) MB, 6.1 ms (+ 54.8 ms in 204 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]    94301 ms: Scavenge 28.1 (59.1) -> 15.0 (59.1) MB, 4.6 ms [Runtime::PerformGC].
[29594]    94543 ms: Scavenge 29.2 (59.1) -> 18.3 (59.1) MB, 6.9 ms [allocation failure].
[29594]    94708 ms: Mark-sweep 26.4 (60.1) -> 9.9 (55.1) MB, 6.1 ms (+ 19.2 ms in 87 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    95007 ms: Scavenge 27.0 (55.1) -> 14.0 (55.1) MB, 5.6 ms [Runtime::PerformGC].
[29594]    95253 ms: Scavenge 28.1 (55.1) -> 17.4 (55.1) MB, 7.2 ms [allocation failure].
[29594]    95434 ms: Mark-sweep 26.4 (57.1) -> 9.6 (57.1) MB, 3.7 ms (+ 21.9 ms in 85 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[29594]    95725 ms: Scavenge 26.7 (57.1) -> 13.8 (57.1) MB, 5.1 ms [Runtime::PerformGC].
[29594]    95968 ms: Scavenge 27.8 (57.1) -> 17.1 (57.1) MB, 7.2 ms [Runtime::PerformGC].
[29594]    96269 ms: Mark-sweep 31.8 (59.1) -> 11.3 (54.1) MB, 7.9 ms (+ 36.8 ms in 146 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    96584 ms: Scavenge 28.4 (54.1) -> 15.4 (54.1) MB, 5.0 ms [allocation failure].
[29594]    96842 ms: Scavenge 29.4 (54.1) -> 18.7 (56.1) MB, 8.6 ms [allocation failure].
[29594]    97148 ms: Mark-sweep 32.8 (59.1) -> 10.8 (56.1) MB, 8.0 ms (+ 36.9 ms in 145 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    97451 ms: Scavenge 27.9 (56.1) -> 14.7 (56.1) MB, 4.7 ms [Runtime::PerformGC].
[29594]    97696 ms: Scavenge 28.9 (56.1) -> 18.1 (56.1) MB, 7.7 ms [allocation failure].
[29594]    98027 ms: Scavenge 34.4 (58.1) -> 24.0 (59.1) MB, 9.5 ms (+ 39.1 ms in 164 steps since last GC) [allocation failure].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]    98168 ms: Mark-sweep 30.6 (60.1) -> 11.2 (59.1) MB, 5.8 ms (+ 57.2 ms in 228 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    98456 ms: Scavenge 28.3 (59.1) -> 15.2 (59.1) MB, 4.5 ms [Runtime::PerformGC].
[29594]    98699 ms: Scavenge 29.4 (59.1) -> 18.6 (59.1) MB, 7.4 ms [Runtime::PerformGC].
[29594]    99034 ms: Scavenge 34.9 (61.1) -> 24.5 (62.1) MB, 8.4 ms (+ 42.7 ms in 168 steps since last GC) [allocation failure].
[29594]    99096 ms: Mark-sweep 27.2 (63.1) -> 10.9 (59.1) MB, 6.6 ms (+ 49.9 ms in 194 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]    99387 ms: Scavenge 28.0 (59.1) -> 14.9 (59.1) MB, 4.7 ms [Runtime::PerformGC].
[29594]    99633 ms: Scavenge 29.1 (59.1) -> 18.2 (59.1) MB, 7.2 ms [Runtime::PerformGC].
[29594]    99784 ms: Mark-sweep 25.5 (60.1) -> 9.9 (53.1) MB, 6.1 ms (+ 16.8 ms in 82 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   100082 ms: Scavenge 26.9 (53.1) -> 14.0 (53.1) MB, 5.0 ms [allocation failure].
[29594]   100324 ms: Scavenge 28.0 (53.1) -> 16.7 (53.1) MB, 6.2 ms [Runtime::PerformGC].
[29594]   100686 ms: Scavenge 33.6 (55.1) -> 23.0 (57.1) MB, 8.8 ms (+ 45.2 ms in 163 steps since last GC) [Runtime::PerformGC].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   100757 ms: Mark-sweep 26.1 (58.1) -> 10.5 (58.1) MB, 4.5 ms (+ 54.1 ms in 192 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   101071 ms: Scavenge 27.6 (58.1) -> 14.6 (58.1) MB, 4.9 ms [Runtime::PerformGC].
[29594]   101333 ms: Scavenge 28.6 (58.1) -> 17.4 (58.1) MB, 5.9 ms [Runtime::PerformGC].
[29594]   101523 ms: Mark-sweep 26.0 (59.1) -> 9.6 (53.1) MB, 6.1 ms (+ 22.6 ms in 89 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   101833 ms: Scavenge 26.7 (53.1) -> 13.7 (53.1) MB, 5.0 ms [Runtime::PerformGC].
[29594]   102089 ms: Scavenge 27.8 (54.1) -> 17.1 (54.1) MB, 7.1 ms [Runtime::PerformGC].
[29594]   102307 ms: Mark-sweep 27.6 (55.1) -> 10.9 (55.1) MB, 4.7 ms (+ 24.5 ms in 100 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   102621 ms: Scavenge 28.0 (55.1) -> 15.0 (55.1) MB, 5.0 ms [Runtime::PerformGC].
[29594]   102877 ms: Scavenge 29.0 (55.1) -> 18.3 (55.1) MB, 6.8 ms [Runtime::PerformGC].
[29594]   103221 ms: Mark-sweep 34.5 (57.1) -> 11.0 (55.1) MB, 7.6 ms (+ 40.7 ms in 163 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   103536 ms: Scavenge 28.1 (55.1) -> 15.1 (55.1) MB, 4.9 ms [allocation failure].
[29594]   103795 ms: Scavenge 29.1 (55.1) -> 18.4 (55.1) MB, 9.6 ms [Runtime::PerformGC].
[29594]   104154 ms: Scavenge 34.8 (58.1) -> 24.4 (59.1) MB, 9.3 ms (+ 45.5 ms in 173 steps since last GC) [Runtime::PerformGC].
[29594]   104167 ms: Mark-sweep 24.7 (59.1) -> 10.8 (58.1) MB, 5.4 ms (+ 46.8 ms in 177 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   104472 ms: Scavenge 27.8 (58.1) -> 14.7 (58.1) MB, 4.6 ms [Runtime::PerformGC].
[29594]   104736 ms: Scavenge 28.9 (58.1) -> 18.1 (58.1) MB, 7.3 ms [Runtime::PerformGC].
[29594]   105092 ms: Scavenge 34.4 (60.1) -> 23.9 (60.1) MB, 8.2 ms (+ 42.1 ms in 161 steps since last GC) [allocation failure].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   105200 ms: Mark-sweep 28.5 (61.1) -> 11.0 (58.1) MB, 6.7 ms (+ 56.1 ms in 207 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   105514 ms: Scavenge 28.1 (58.1) -> 15.1 (58.1) MB, 5.1 ms [Runtime::PerformGC].
[29594]   105778 ms: Scavenge 29.2 (58.1) -> 18.4 (58.1) MB, 7.7 ms [Runtime::PerformGC].
[29594]   106106 ms: Mark-sweep 33.5 (60.1) -> 10.8 (58.1) MB, 6.6 ms (+ 39.7 ms in 157 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   106414 ms: Scavenge 27.8 (58.1) -> 14.7 (58.1) MB, 4.8 ms [Runtime::PerformGC].
[29594]   106673 ms: Scavenge 28.9 (58.1) -> 18.1 (58.1) MB, 7.2 ms [Runtime::PerformGC].
[29594]   106832 ms: Mark-sweep 25.5 (58.1) -> 9.9 (55.1) MB, 4.6 ms (+ 18.0 ms in 83 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   107144 ms: Scavenge 27.0 (55.1) -> 14.0 (55.1) MB, 5.0 ms [Runtime::PerformGC].
[29594]   107398 ms: Scavenge 28.0 (55.1) -> 16.8 (55.1) MB, 6.2 ms [allocation failure].
[29594]   107752 ms: Scavenge 33.6 (57.1) -> 23.1 (57.1) MB, 7.6 ms (+ 43.1 ms in 165 steps since last GC) [Runtime::PerformGC].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   107851 ms: Mark-sweep 27.5 (58.1) -> 10.7 (58.1) MB, 5.1 ms (+ 55.5 ms in 208 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   108159 ms: Scavenge 27.8 (58.1) -> 14.8 (58.1) MB, 4.7 ms [Runtime::PerformGC].
[29594]   108413 ms: Scavenge 28.8 (58.1) -> 17.7 (58.1) MB, 6.2 ms [Runtime::PerformGC].
[29594]   108617 ms: Mark-sweep 27.2 (59.1) -> 9.8 (55.1) MB, 6.3 ms (+ 23.4 ms in 94 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   108929 ms: Scavenge 26.9 (55.1) -> 13.9 (55.1) MB, 4.8 ms [Runtime::PerformGC].
[29594]   109186 ms: Scavenge 27.9 (55.1) -> 17.2 (55.1) MB, 7.0 ms [Runtime::PerformGC].
[29594]   109381 ms: Mark-sweep 26.7 (56.1) -> 10.7 (56.1) MB, 4.5 ms (+ 21.8 ms in 95 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   109675 ms: Scavenge 27.8 (56.1) -> 14.8 (56.1) MB, 5.4 ms [Runtime::PerformGC].
[29594]   109917 ms: Scavenge 28.8 (56.1) -> 18.1 (56.1) MB, 7.0 ms [allocation failure].
[29594]   110214 ms: Mark-sweep 32.7 (57.1) -> 10.9 (56.1) MB, 7.0 ms (+ 37.6 ms in 144 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   110504 ms: Scavenge 28.0 (56.1) -> 15.0 (56.1) MB, 5.3 ms [allocation failure].
[29594]   110748 ms: Scavenge 29.0 (56.1) -> 18.3 (56.1) MB, 7.0 ms [allocation failure].
[29594]   111081 ms: Scavenge 34.8 (59.1) -> 24.3 (60.1) MB, 8.5 ms (+ 41.7 ms in 165 steps since last GC) [Runtime::PerformGC].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   111187 ms: Mark-sweep 29.2 (60.1) -> 11.2 (58.1) MB, 6.1 ms (+ 56.0 ms in 213 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   111472 ms: Scavenge 28.2 (58.1) -> 15.1 (58.1) MB, 4.6 ms [Runtime::PerformGC].
[29594]   111719 ms: Scavenge 29.3 (58.1) -> 18.4 (58.1) MB, 7.3 ms [Runtime::PerformGC].
[29594]   112055 ms: Scavenge 34.9 (61.1) -> 24.5 (61.1) MB, 7.6 ms (+ 42.6 ms in 164 steps since last GC) [allocation failure].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   112158 ms: Mark-sweep 29.1 (62.1) -> 11.1 (59.1) MB, 6.5 ms (+ 56.1 ms in 208 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[29594]   112456 ms: Scavenge 28.1 (59.1) -> 15.0 (59.1) MB, 4.5 ms [Runtime::PerformGC].
[29594]   112699 ms: Scavenge 29.2 (59.1) -> 18.4 (59.1) MB, 6.6 ms [Runtime::PerformGC].
[29594]   112848 ms: Mark-sweep 25.8 (60.1) -> 9.9 (55.1) MB, 5.3 ms (+ 17.1 ms in 81 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   113139 ms: Scavenge 27.0 (55.1) -> 14.0 (55.1) MB, 5.6 ms [Runtime::PerformGC].
[29594]   113378 ms: Scavenge 28.0 (55.1) -> 16.7 (55.1) MB, 6.8 ms [Runtime::PerformGC].
[29594]   113561 ms: Mark-sweep 25.4 (57.1) -> 9.6 (55.1) MB, 4.7 ms (+ 22.6 ms in 86 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[29594]   113856 ms: Scavenge 26.7 (55.1) -> 13.7 (55.1) MB, 5.4 ms [Runtime::PerformGC].
[29594]   114101 ms: Scavenge 27.7 (55.1) -> 17.1 (55.1) MB, 7.4 ms [allocation failure].
[29594]   114232 ms: Mark-sweep 23.4 (55.1) -> 10.3 (53.1) MB, 4.5 ms (+ 14.7 ms in 67 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   114527 ms: Scavenge 27.3 (53.1) -> 14.3 (53.1) MB, 4.9 ms [Runtime::PerformGC].
[29594]   114772 ms: Scavenge 28.4 (54.1) -> 17.7 (54.1) MB, 7.3 ms [Runtime::PerformGC].
[29594]   114944 ms: Mark-sweep 26.1 (55.1) -> 10.1 (55.1) MB, 4.0 ms (+ 19.5 ms in 84 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   115233 ms: Scavenge 27.1 (55.1) -> 14.2 (55.1) MB, 5.4 ms [Runtime::PerformGC].
[29594]   115474 ms: Scavenge 28.2 (55.1) -> 17.2 (55.1) MB, 6.6 ms [allocation failure].
[29594]   115651 ms: Mark-sweep 25.5 (55.1) -> 9.7 (54.1) MB, 5.0 ms (+ 19.9 ms in 82 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   115958 ms: Scavenge 26.8 (54.1) -> 13.8 (54.1) MB, 5.0 ms [allocation failure].
[29594]   116216 ms: Scavenge 27.8 (54.1) -> 17.1 (54.1) MB, 7.2 ms [Runtime::PerformGC].
[29594]   116505 ms: Mark-sweep 31.1 (56.1) -> 11.2 (56.1) MB, 5.6 ms (+ 34.7 ms in 139 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   116816 ms: Scavenge 28.2 (56.1) -> 15.3 (56.1) MB, 5.1 ms [Runtime::PerformGC].
[29594]   117079 ms: Scavenge 29.3 (56.1) -> 18.6 (57.1) MB, 7.2 ms [allocation failure].
[29594]   117299 ms: Mark-sweep 28.9 (58.1) -> 10.6 (56.1) MB, 5.4 ms (+ 25.4 ms in 109 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   117607 ms: Scavenge 27.7 (56.1) -> 14.7 (56.1) MB, 5.6 ms [Runtime::PerformGC].
[29594]   117866 ms: Scavenge 28.7 (56.1) -> 18.0 (56.1) MB, 7.7 ms [Runtime::PerformGC].
[29594]   118154 ms: Mark-sweep 31.4 (58.1) -> 10.6 (56.1) MB, 5.7 ms (+ 34.8 ms in 137 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   118465 ms: Scavenge 27.7 (56.1) -> 14.6 (56.1) MB, 4.8 ms [Runtime::PerformGC].
[29594]   118728 ms: Scavenge 28.7 (56.1) -> 17.9 (56.1) MB, 6.8 ms [Runtime::PerformGC].
[29594]   119006 ms: Mark-sweep 30.8 (57.1) -> 10.4 (56.1) MB, 6.4 ms (+ 32.3 ms in 132 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   119317 ms: Scavenge 27.5 (56.1) -> 14.6 (56.1) MB, 5.0 ms [allocation failure].
[29594]   119563 ms: Scavenge 28.6 (56.1) -> 17.7 (56.1) MB, 7.4 ms [Runtime::PerformGC].
[29594]   119730 ms: Mark-sweep 25.8 (57.1) -> 9.8 (55.1) MB, 4.8 ms (+ 19.3 ms in 82 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   120022 ms: Scavenge 26.9 (55.1) -> 13.9 (55.1) MB, 5.5 ms [Runtime::PerformGC].
[29594]   120260 ms: Scavenge 27.9 (55.1) -> 17.2 (55.1) MB, 7.4 ms [allocation failure].
[29594]   120518 ms: Mark-sweep 29.4 (57.1) -> 11.3 (56.1) MB, 6.5 ms (+ 30.8 ms in 116 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[29594]   120816 ms: Scavenge 28.4 (56.1) -> 15.5 (56.1) MB, 5.1 ms [Runtime::PerformGC].
[29594]   121059 ms: Scavenge 29.5 (56.1) -> 18.8 (56.1) MB, 7.5 ms [Runtime::PerformGC].
[29594]   121384 ms: Mark-sweep 34.4 (58.1) -> 11.4 (57.1) MB, 7.5 ms (+ 41.3 ms in 152 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   121687 ms: Scavenge 28.6 (57.1) -> 15.6 (57.1) MB, 5.3 ms [Runtime::PerformGC].
[29594]   121937 ms: Scavenge 29.7 (57.1) -> 18.9 (57.1) MB, 7.3 ms [Runtime::PerformGC].
[29594]   122077 ms: Mark-sweep 25.6 (58.1) -> 10.4 (55.1) MB, 6.0 ms (+ 15.1 ms in 75 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   122369 ms: Scavenge 27.5 (55.1) -> 14.6 (55.1) MB, 5.0 ms [Runtime::PerformGC].
[29594]   122610 ms: Scavenge 28.5 (55.1) -> 17.6 (55.1) MB, 6.8 ms [Runtime::PerformGC].
[29594]   122807 ms: Mark-sweep 27.4 (56.1) -> 10.3 (56.1) MB, 4.5 ms (+ 24.2 ms in 104 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   123098 ms: Scavenge 27.4 (56.1) -> 14.5 (56.1) MB, 4.9 ms [Runtime::PerformGC].
[29594]   123339 ms: Scavenge 28.5 (56.1) -> 17.8 (56.1) MB, 6.9 ms [Runtime::PerformGC].
[29594]   123512 ms: Mark-sweep 26.4 (56.1) -> 11.2 (55.1) MB, 5.2 ms (+ 19.6 ms in 89 steps since start of marking, biggest step 0.7 ms) [StackGuard GC request] [GC in old space requested].
[29594]   123804 ms: Scavenge 28.2 (55.1) -> 15.3 (55.1) MB, 4.8 ms [Runtime::PerformGC].
[29594]   124046 ms: Scavenge 29.3 (55.1) -> 18.6 (55.1) MB, 7.5 ms [Runtime::PerformGC].
[29594]   124344 ms: Mark-sweep 33.3 (57.1) -> 11.2 (57.1) MB, 6.3 ms (+ 36.9 ms in 146 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   124639 ms: Scavenge 28.3 (57.1) -> 15.3 (57.1) MB, 5.4 ms [Runtime::PerformGC].
[29594]   124882 ms: Scavenge 29.4 (57.1) -> 18.6 (57.1) MB, 7.4 ms [Runtime::PerformGC].
[29594]   125059 ms: Mark-sweep 27.3 (58.1) -> 10.4 (56.1) MB, 5.3 ms (+ 20.2 ms in 92 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   125355 ms: Scavenge 27.5 (56.1) -> 14.5 (56.1) MB, 5.6 ms [allocation failure].
[29594]   125595 ms: Scavenge 28.5 (56.1) -> 17.6 (56.1) MB, 7.1 ms [Runtime::PerformGC].
[29594]   125745 ms: Mark-sweep 24.9 (57.1) -> 9.9 (55.1) MB, 4.5 ms (+ 16.7 ms in 74 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   126038 ms: Scavenge 27.0 (55.1) -> 14.0 (55.1) MB, 5.4 ms [Runtime::PerformGC].
[29594]   126281 ms: Scavenge 28.0 (55.1) -> 17.3 (55.1) MB, 7.7 ms [Runtime::PerformGC].
[29594]   126597 ms: Mark-sweep 32.3 (57.1) -> 11.7 (57.1) MB, 5.8 ms (+ 38.2 ms in 140 steps since start of marking, biggest step 0.7 ms) [StackGuard GC request] [GC in old space requested].
[29594]   126893 ms: Scavenge 28.8 (57.1) -> 15.8 (57.1) MB, 5.5 ms [allocation failure].
[29594]   127151 ms: Scavenge 29.8 (57.1) -> 19.1 (57.1) MB, 7.0 ms [Runtime::PerformGC].
[29594]   127343 ms: Mark-sweep 28.2 (58.1) -> 10.8 (55.1) MB, 6.6 ms (+ 21.5 ms in 95 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   127640 ms: Scavenge 27.9 (55.1) -> 14.9 (55.1) MB, 4.8 ms [Runtime::PerformGC].
[29594]   127884 ms: Scavenge 28.9 (55.1) -> 18.2 (55.1) MB, 7.2 ms [Runtime::PerformGC].
[29594]   128024 ms: Mark-sweep 25.0 (55.1) -> 10.3 (55.1) MB, 3.8 ms (+ 15.5 ms in 71 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   128315 ms: Scavenge 27.4 (55.1) -> 14.5 (55.1) MB, 5.4 ms [allocation failure].
[29594]   128555 ms: Scavenge 28.4 (55.1) -> 17.4 (55.1) MB, 7.0 ms [Runtime::PerformGC].
[29594]   128710 ms: Mark-sweep 25.0 (56.1) -> 9.9 (56.1) MB, 3.7 ms (+ 17.7 ms in 76 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   129005 ms: Scavenge 26.9 (56.1) -> 14.0 (56.1) MB, 5.4 ms [Runtime::PerformGC].
[29594]   129249 ms: Scavenge 28.0 (56.1) -> 17.3 (56.1) MB, 7.4 ms [Runtime::PerformGC].
[29594]   129405 ms: Mark-sweep 24.8 (56.1) -> 10.8 (55.1) MB, 5.0 ms (+ 16.8 ms in 75 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   129693 ms: Scavenge 27.8 (55.1) -> 14.8 (55.1) MB, 5.0 ms [allocation failure].
[29594]   129935 ms: Scavenge 28.9 (55.1) -> 18.1 (55.1) MB, 6.9 ms [allocation failure].
[29594]   130131 ms: Mark-sweep 27.5 (56.1) -> 10.5 (56.1) MB, 5.0 ms (+ 22.0 ms in 95 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   130428 ms: Scavenge 27.6 (56.1) -> 14.7 (56.1) MB, 4.9 ms [Runtime::PerformGC].
[29594]   130662 ms: Scavenge 28.6 (56.1) -> 17.7 (56.1) MB, 6.5 ms [Runtime::PerformGC].
[29594]   130971 ms: Mark-sweep 32.9 (59.1) -> 10.8 (57.1) MB, 6.9 ms (+ 38.4 ms in 154 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   131266 ms: Scavenge 27.9 (57.1) -> 14.9 (57.1) MB, 5.0 ms [Runtime::PerformGC].
[29594]   131504 ms: Scavenge 28.9 (57.1) -> 17.7 (57.1) MB, 5.9 ms [Runtime::PerformGC].
[29594]   131859 ms: Scavenge 34.9 (60.1) -> 24.3 (61.1) MB, 7.7 ms (+ 46.6 ms in 175 steps since last GC) [allocation failure].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   131930 ms: Mark-sweep 27.5 (62.1) -> 10.9 (59.1) MB, 5.9 ms (+ 55.5 ms in 203 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   132221 ms: Scavenge 27.9 (59.1) -> 15.0 (59.1) MB, 4.7 ms [allocation failure].
[29594]   132460 ms: Scavenge 29.0 (59.1) -> 17.9 (59.1) MB, 6.6 ms [Runtime::PerformGC].
[29594]   132666 ms: Mark-sweep 27.6 (60.1) -> 10.0 (56.1) MB, 5.9 ms (+ 25.9 ms in 106 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   132958 ms: Scavenge 27.0 (56.1) -> 14.1 (56.1) MB, 4.9 ms [allocation failure].
[29594]   133198 ms: Scavenge 28.1 (56.1) -> 17.4 (56.1) MB, 7.5 ms [allocation failure].
[29594]   133365 ms: Mark-sweep 25.6 (57.1) -> 10.7 (54.1) MB, 5.6 ms (+ 19.6 ms in 83 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   133653 ms: Scavenge 27.8 (54.1) -> 14.8 (54.1) MB, 4.8 ms [allocation failure].
[29594]   133896 ms: Scavenge 28.8 (54.1) -> 18.1 (55.1) MB, 7.7 ms [Runtime::PerformGC].
[29594]   134126 ms: Mark-sweep 29.2 (56.1) -> 10.6 (55.1) MB, 5.9 ms (+ 27.9 ms in 110 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   134419 ms: Scavenge 27.7 (55.1) -> 14.7 (55.1) MB, 5.4 ms [Runtime::PerformGC].
[29594]   134662 ms: Scavenge 28.7 (55.1) -> 17.8 (55.1) MB, 7.3 ms [Runtime::PerformGC].
[29594]   134879 ms: Mark-sweep 28.3 (57.1) -> 10.3 (55.1) MB, 5.5 ms (+ 27.0 ms in 107 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   135174 ms: Scavenge 27.3 (55.1) -> 14.4 (55.1) MB, 4.7 ms [Runtime::PerformGC].
[29594]   135415 ms: Scavenge 28.4 (55.1) -> 17.7 (55.1) MB, 6.9 ms [Runtime::PerformGC].
[29594]   135684 ms: Mark-sweep 30.9 (57.1) -> 11.5 (55.1) MB, 6.3 ms (+ 32.8 ms in 132 steps since start of marking, biggest step 0.7 ms) [StackGuard GC request] [GC in old space requested].
[29594]   135975 ms: Scavenge 28.6 (55.1) -> 15.6 (55.1) MB, 4.8 ms [Runtime::PerformGC].
[29594]   136217 ms: Scavenge 29.6 (55.1) -> 18.9 (56.1) MB, 7.7 ms [Runtime::PerformGC].
[29594]   136512 ms: Mark-sweep 33.5 (57.1) -> 11.3 (55.1) MB, 8.2 ms (+ 36.0 ms in 144 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   136806 ms: Scavenge 28.4 (55.1) -> 15.4 (55.1) MB, 5.3 ms [Runtime::PerformGC].
[29594]   137054 ms: Scavenge 29.4 (55.1) -> 18.7 (55.1) MB, 7.9 ms [Runtime::PerformGC].
[29594]   137344 ms: Mark-sweep 33.3 (57.1) -> 11.1 (57.1) MB, 5.7 ms (+ 36.8 ms in 141 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   137637 ms: Scavenge 28.2 (57.1) -> 15.2 (57.1) MB, 4.9 ms [Runtime::PerformGC].
[29594]   137879 ms: Scavenge 29.2 (57.1) -> 18.5 (57.1) MB, 7.4 ms [Runtime::PerformGC].
[29594]   138150 ms: Mark-sweep 31.5 (59.1) -> 10.8 (56.1) MB, 7.0 ms (+ 33.8 ms in 141 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   138449 ms: Scavenge 27.9 (56.1) -> 14.9 (56.1) MB, 5.1 ms [Runtime::PerformGC].
[29594]   138692 ms: Scavenge 28.9 (56.1) -> 18.0 (56.1) MB, 7.2 ms [Runtime::PerformGC].
[29594]   138893 ms: Mark-sweep 27.7 (58.1) -> 10.2 (56.1) MB, 5.8 ms (+ 24.5 ms in 103 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   139183 ms: Scavenge 27.3 (56.1) -> 14.3 (56.1) MB, 4.7 ms [Runtime::PerformGC].
[29594]   139425 ms: Scavenge 28.3 (56.1) -> 17.6 (56.1) MB, 7.0 ms [allocation failure].
[29594]   139618 ms: Mark-sweep 27.3 (57.1) -> 11.1 (56.1) MB, 4.6 ms (+ 21.9 ms in 94 steps since start of marking, biggest step 0.8 ms) [StackGuard GC request] [GC in old space requested].
[29594]   139911 ms: Scavenge 28.2 (56.1) -> 15.2 (56.1) MB, 4.9 ms [Runtime::PerformGC].
[29594]   140153 ms: Scavenge 29.2 (56.1) -> 18.5 (56.1) MB, 6.6 ms [Runtime::PerformGC].
[29594]   140485 ms: Scavenge 35.0 (58.1) -> 23.9 (59.1) MB, 7.3 ms (+ 40.0 ms in 158 steps since last GC) [Runtime::PerformGC].
[29594]   140546 ms: Mark-sweep 26.6 (61.1) -> 11.0 (60.1) MB, 4.5 ms (+ 48.5 ms in 186 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   140843 ms: Scavenge 28.0 (60.1) -> 15.1 (60.1) MB, 6.0 ms [Runtime::PerformGC].
[29594]   141084 ms: Scavenge 29.1 (60.1) -> 18.4 (60.1) MB, 6.9 ms [allocation failure].
[29594]   141421 ms: Scavenge 35.1 (63.1) -> 24.7 (63.1) MB, 7.9 ms (+ 44.4 ms in 172 steps since last GC) [Runtime::PerformGC].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   141501 ms: Mark-sweep 28.4 (65.1) -> 11.5 (60.1) MB, 6.6 ms (+ 54.6 ms in 207 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   141792 ms: Scavenge 28.6 (60.1) -> 15.6 (60.1) MB, 4.9 ms [Runtime::PerformGC].
[29594]   142033 ms: Scavenge 29.6 (60.1) -> 18.9 (60.1) MB, 7.0 ms [Runtime::PerformGC].
[29594]   142338 ms: Mark-sweep 33.8 (63.1) -> 11.1 (58.1) MB, 9.1 ms (+ 38.4 ms in 153 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   142630 ms: Scavenge 28.2 (58.1) -> 15.0 (58.1) MB, 4.7 ms [Runtime::PerformGC].
[29594]   142885 ms: Scavenge 29.3 (58.1) -> 18.4 (58.1) MB, 7.2 ms [allocation failure].
[29594]   143185 ms: Mark-sweep 32.3 (60.1) -> 10.9 (57.1) MB, 8.3 ms (+ 36.3 ms in 142 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   143502 ms: Scavenge 27.9 (57.1) -> 14.8 (57.1) MB, 5.2 ms [Runtime::PerformGC].
[29594]   143765 ms: Scavenge 29.0 (57.1) -> 18.1 (57.1) MB, 7.5 ms [allocation failure].
[29594]   144105 ms: Mark-sweep 34.1 (59.1) -> 10.8 (57.1) MB, 6.8 ms (+ 42.0 ms in 160 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   144419 ms: Scavenge 27.9 (57.1) -> 14.9 (57.1) MB, 5.3 ms [Runtime::PerformGC].
[29594]   144678 ms: Scavenge 28.9 (57.1) -> 17.8 (57.1) MB, 6.4 ms [allocation failure].
[29594]   144849 ms: Mark-sweep 25.7 (58.1) -> 10.0 (55.1) MB, 5.3 ms (+ 18.5 ms in 86 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   145161 ms: Scavenge 27.0 (55.1) -> 14.1 (55.1) MB, 5.4 ms [Runtime::PerformGC].
[29594]   145422 ms: Scavenge 28.1 (55.1) -> 17.4 (55.1) MB, 7.2 ms [Runtime::PerformGC].
[29594]   145761 ms: Scavenge 34.0 (57.1) -> 23.3 (57.1) MB, 7.0 ms (+ 44.2 ms in 159 steps since last GC) [Runtime::PerformGC].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   145858 ms: Mark-sweep 27.7 (58.1) -> 11.6 (58.1) MB, 5.0 ms (+ 57.3 ms in 201 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   146151 ms: Scavenge 28.7 (58.1) -> 15.7 (58.1) MB, 5.1 ms [Runtime::PerformGC].
[29594]   146399 ms: Scavenge 29.7 (58.1) -> 19.0 (58.1) MB, 7.7 ms [Runtime::PerformGC].
[29594]   146551 ms: Mark-sweep 26.2 (58.1) -> 10.7 (54.1) MB, 5.9 ms (+ 16.6 ms in 79 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[29594]   146848 ms: Scavenge 27.7 (54.1) -> 14.8 (54.1) MB, 5.2 ms [allocation failure].
[29594]   147092 ms: Scavenge 28.8 (55.1) -> 18.1 (55.1) MB, 7.2 ms [Runtime::PerformGC].
[29594]   147252 ms: Mark-sweep 25.9 (56.1) -> 10.5 (56.1) MB, 4.1 ms (+ 18.2 ms in 81 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   147548 ms: Scavenge 27.5 (56.1) -> 14.3 (56.1) MB, 4.5 ms [Runtime::PerformGC].
[29594]   147794 ms: Scavenge 28.6 (56.1) -> 17.7 (56.1) MB, 7.0 ms [allocation failure].
[29594]   147937 ms: Mark-sweep 24.7 (57.1) -> 10.0 (55.1) MB, 5.1 ms (+ 15.7 ms in 73 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   148233 ms: Scavenge 27.1 (55.1) -> 14.1 (55.1) MB, 5.1 ms [Runtime::PerformGC].
[29594]   148483 ms: Scavenge 28.1 (55.1) -> 17.4 (55.1) MB, 8.5 ms [Runtime::PerformGC].
[29594]   148603 ms: Mark-sweep 23.3 (55.1) -> 9.5 (55.1) MB, 3.0 ms (+ 12.9 ms in 62 steps since start of marking, biggest step 0.7 ms) [StackGuard GC request] [GC in old space requested].
[29594]   148897 ms: Scavenge 26.6 (55.1) -> 13.6 (55.1) MB, 5.3 ms [allocation failure].
[29594]   149142 ms: Scavenge 27.7 (55.1) -> 17.0 (55.1) MB, 7.3 ms [Runtime::PerformGC].
[29594]   149480 ms: Scavenge 33.5 (57.1) -> 23.0 (57.1) MB, 7.9 ms (+ 42.3 ms in 165 steps since last GC) [allocation failure].
[29594]   149518 ms: Mark-sweep 24.9 (58.1) -> 11.5 (58.1) MB, 4.3 ms (+ 47.2 ms in 182 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   149813 ms: Scavenge 28.6 (58.1) -> 15.6 (58.1) MB, 5.0 ms [Runtime::PerformGC].
[29594]   150065 ms: Scavenge 29.7 (58.1) -> 19.0 (58.1) MB, 7.5 ms [Runtime::PerformGC].
[29594]   150265 ms: Mark-sweep 28.7 (59.1) -> 10.6 (55.1) MB, 6.3 ms (+ 25.2 ms in 107 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   150559 ms: Scavenge 27.8 (55.1) -> 14.7 (55.1) MB, 4.1 ms [Runtime::PerformGC].
[29594]   150807 ms: Scavenge 28.9 (55.1) -> 18.0 (55.1) MB, 7.3 ms [Runtime::PerformGC].
[29594]   150969 ms: Mark-sweep 25.9 (56.1) -> 10.2 (56.1) MB, 4.6 ms (+ 18.4 ms in 85 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   151263 ms: Scavenge 27.3 (56.1) -> 14.3 (56.1) MB, 5.4 ms [Runtime::PerformGC].
[29594]   151516 ms: Scavenge 28.4 (56.1) -> 17.8 (56.1) MB, 7.4 ms [Runtime::PerformGC].
[29594]   151877 ms: Scavenge 35.5 (58.1) -> 24.9 (59.1) MB, 7.3 ms (+ 47.2 ms in 165 steps since last GC) [Runtime::PerformGC].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   151930 ms: Mark-sweep 27.2 (60.1) -> 11.0 (60.1) MB, 4.7 ms (+ 53.6 ms in 184 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   152230 ms: Scavenge 28.1 (60.1) -> 15.2 (60.1) MB, 5.4 ms [Runtime::PerformGC].
[29594]   152478 ms: Scavenge 29.2 (60.1) -> 18.5 (60.1) MB, 7.9 ms [allocation failure].
[29594]   152828 ms: Scavenge 35.8 (63.1) -> 25.0 (63.1) MB, 7.4 ms (+ 46.6 ms in 186 steps since last GC) [Runtime::PerformGC].
[29594]   152902 ms: Mark-sweep 28.2 (65.1) -> 11.8 (60.1) MB, 6.8 ms (+ 56.3 ms in 216 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   153204 ms: Scavenge 28.9 (60.1) -> 15.9 (60.1) MB, 5.1 ms [Runtime::PerformGC].
[29594]   153450 ms: Scavenge 29.9 (60.1) -> 19.2 (60.1) MB, 7.5 ms [Runtime::PerformGC].
[29594]   153680 ms: Mark-sweep 30.5 (62.1) -> 11.3 (57.1) MB, 6.8 ms (+ 29.1 ms in 122 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   153974 ms: Scavenge 28.4 (57.1) -> 15.5 (57.1) MB, 5.2 ms [Runtime::PerformGC].
[29594]   154222 ms: Scavenge 29.5 (57.1) -> 18.8 (57.1) MB, 7.5 ms [Runtime::PerformGC].
[29594]   154551 ms: Mark-sweep 34.8 (59.1) -> 11.6 (58.1) MB, 8.8 ms (+ 39.9 ms in 160 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   154851 ms: Scavenge 28.7 (58.1) -> 15.8 (58.1) MB, 4.9 ms [Runtime::PerformGC].
[29594]   155095 ms: Scavenge 29.8 (58.1) -> 19.1 (58.1) MB, 7.7 ms [allocation failure].
[29594]   155297 ms: Mark-sweep 28.8 (59.1) -> 10.7 (57.1) MB, 5.7 ms (+ 24.5 ms in 96 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   155588 ms: Scavenge 27.8 (57.1) -> 14.6 (57.1) MB, 4.2 ms [Runtime::PerformGC].
[29594]   155837 ms: Scavenge 28.8 (57.1) -> 17.9 (57.1) MB, 6.5 ms [Runtime::PerformGC].
[29594]   156101 ms: Mark-sweep 31.2 (58.1) -> 10.7 (58.1) MB, 5.6 ms (+ 31.1 ms in 130 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   156421 ms: Scavenge 27.8 (58.1) -> 14.8 (58.1) MB, 5.6 ms [allocation failure].
[29594]   156679 ms: Scavenge 28.8 (58.1) -> 17.7 (58.1) MB, 6.3 ms [Runtime::PerformGC].
[29594]   156862 ms: Mark-sweep 26.1 (59.1) -> 10.1 (56.1) MB, 5.6 ms (+ 19.7 ms in 88 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   157178 ms: Scavenge 27.1 (56.1) -> 14.2 (56.1) MB, 5.2 ms [Runtime::PerformGC].
[29594]   157442 ms: Scavenge 28.2 (56.1) -> 17.5 (56.1) MB, 7.0 ms [Runtime::PerformGC].
[29594]   157808 ms: Scavenge 34.8 (59.1) -> 23.9 (61.1) MB, 8.3 ms (+ 43.9 ms in 162 steps since last GC) [Runtime::PerformGC].
[29594]   157836 ms: Mark-sweep 24.9 (61.1) -> 11.3 (58.1) MB, 5.6 ms (+ 47.0 ms in 172 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   158153 ms: Scavenge 28.4 (58.1) -> 15.4 (58.1) MB, 4.9 ms [Runtime::PerformGC].
[29594]   158399 ms: Scavenge 29.4 (58.1) -> 18.7 (58.1) MB, 7.8 ms [allocation failure].
[29594]   158570 ms: Mark-sweep 26.8 (59.1) -> 10.7 (55.1) MB, 6.5 ms (+ 19.3 ms in 83 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   158868 ms: Scavenge 27.8 (55.1) -> 14.8 (55.1) MB, 5.0 ms [Runtime::PerformGC].
[29594]   159115 ms: Scavenge 28.9 (55.1) -> 18.1 (55.1) MB, 7.4 ms [Runtime::PerformGC].
[29594]   159470 ms: Scavenge 35.4 (58.1) -> 24.9 (59.1) MB, 8.5 ms (+ 44.0 ms in 169 steps since last GC) [Runtime::PerformGC].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   159559 ms: Mark-sweep 29.0 (61.1) -> 11.6 (61.1) MB, 4.9 ms (+ 56.1 ms in 207 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   159856 ms: Scavenge 28.7 (61.1) -> 15.7 (61.1) MB, 5.3 ms [Runtime::PerformGC].
[29594]   160122 ms: Scavenge 29.7 (61.1) -> 19.0 (61.1) MB, 7.1 ms [allocation failure].
[29594]   160488 ms: Scavenge 36.3 (64.1) -> 25.9 (66.1) MB, 8.5 ms (+ 43.2 ms in 173 steps since last GC) [Runtime::PerformGC].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   160593 ms: Mark-sweep 30.7 (68.1) -> 11.8 (61.1) MB, 7.9 ms (+ 55.8 ms in 219 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   160910 ms: Scavenge 28.9 (61.1) -> 16.0 (61.1) MB, 5.4 ms [Runtime::PerformGC].
[29594]   161173 ms: Scavenge 30.0 (61.1) -> 19.3 (61.1) MB, 7.4 ms [Runtime::PerformGC].
[29594]   161348 ms: Mark-sweep 27.5 (62.1) -> 10.5 (57.1) MB, 5.7 ms (+ 18.7 ms in 88 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   161659 ms: Scavenge 27.6 (57.1) -> 14.5 (57.1) MB, 4.3 ms [allocation failure].
[29594]   161925 ms: Scavenge 28.7 (57.1) -> 17.8 (57.1) MB, 7.2 ms [Runtime::PerformGC].
[29594]   162169 ms: Mark-sweep 29.3 (59.1) -> 10.6 (56.1) MB, 5.9 ms (+ 27.2 ms in 116 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   162482 ms: Scavenge 27.6 (56.1) -> 14.7 (56.1) MB, 5.1 ms [Runtime::PerformGC].
[29594]   162740 ms: Scavenge 28.7 (56.1) -> 17.5 (56.1) MB, 6.5 ms [Runtime::PerformGC].
[29594]   162983 ms: Mark-sweep 28.8 (58.1) -> 10.4 (55.1) MB, 6.1 ms (+ 29.4 ms in 113 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   163302 ms: Scavenge 27.5 (55.1) -> 14.5 (55.1) MB, 5.0 ms [Runtime::PerformGC].
[29594]   163565 ms: Scavenge 28.5 (55.1) -> 17.8 (55.1) MB, 7.0 ms [Runtime::PerformGC].
[29594]   163930 ms: Scavenge 35.5 (57.1) -> 24.8 (59.1) MB, 7.9 ms (+ 47.0 ms in 175 steps since last GC) [allocation failure].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   163961 ms: Mark-sweep 26.1 (60.1) -> 10.7 (60.1) MB, 4.1 ms (+ 51.3 ms in 188 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   164280 ms: Scavenge 27.8 (60.1) -> 14.8 (60.1) MB, 5.9 ms [allocation failure].
[29594]   164542 ms: Scavenge 28.9 (60.1) -> 18.2 (60.1) MB, 7.8 ms [Runtime::PerformGC].
[29594]   164854 ms: Mark-sweep 32.9 (62.1) -> 11.5 (57.1) MB, 7.7 ms (+ 36.2 ms in 148 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   165165 ms: Scavenge 28.6 (57.1) -> 15.6 (57.1) MB, 5.5 ms [Runtime::PerformGC].
[29594]   165430 ms: Scavenge 29.7 (57.1) -> 19.0 (57.1) MB, 8.1 ms [Runtime::PerformGC].
[29594]   165798 ms: Scavenge 36.0 (60.1) -> 25.1 (61.1) MB, 6.9 ms (+ 42.0 ms in 163 steps since last GC) [allocation failure].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   165934 ms: Mark-sweep 31.0 (63.1) -> 11.7 (61.1) MB, 7.4 ms (+ 60.4 ms in 218 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   166247 ms: Scavenge 28.7 (61.1) -> 15.8 (61.1) MB, 4.9 ms [Runtime::PerformGC].
[29594]   166491 ms: Scavenge 29.8 (61.1) -> 19.1 (61.1) MB, 6.8 ms [allocation failure].
[29594]   166714 ms: Mark-sweep 30.0 (63.1) -> 11.1 (56.1) MB, 7.5 ms (+ 26.1 ms in 112 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   167009 ms: Scavenge 28.1 (56.1) -> 15.2 (56.1) MB, 5.2 ms [Runtime::PerformGC].
[29594]   167252 ms: Scavenge 29.2 (56.1) -> 18.5 (57.1) MB, 7.4 ms [allocation failure].
[29594]   167551 ms: Mark-sweep 33.4 (59.1) -> 11.3 (58.1) MB, 6.4 ms (+ 35.9 ms in 150 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   167842 ms: Scavenge 28.3 (58.1) -> 15.3 (58.1) MB, 5.0 ms [Runtime::PerformGC].
[29594]   168085 ms: Scavenge 29.4 (58.1) -> 18.7 (58.1) MB, 7.0 ms [allocation failure].
[29594]   168406 ms: Mark-sweep 34.3 (60.1) -> 11.2 (58.1) MB, 7.6 ms (+ 40.4 ms in 161 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   168699 ms: Scavenge 28.3 (58.1) -> 15.2 (58.1) MB, 4.9 ms [Runtime::PerformGC].
[29594]   168949 ms: Scavenge 29.4 (58.1) -> 18.5 (58.1) MB, 6.7 ms [Runtime::PerformGC].
[29594]   169126 ms: Mark-sweep 27.2 (59.1) -> 10.3 (57.1) MB, 5.6 ms (+ 19.6 ms in 87 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   169420 ms: Scavenge 27.4 (57.1) -> 14.4 (57.1) MB, 4.7 ms [allocation failure].
[29594]   169661 ms: Scavenge 28.4 (57.1) -> 17.2 (57.1) MB, 6.3 ms [Runtime::PerformGC].
[29594]   169980 ms: Mark-sweep 33.1 (59.1) -> 10.7 (56.1) MB, 6.8 ms (+ 39.3 ms in 156 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[29594]   170277 ms: Scavenge 27.8 (56.1) -> 14.8 (56.1) MB, 5.4 ms [Runtime::PerformGC].
[29594]   170522 ms: Scavenge 28.8 (56.1) -> 18.1 (57.1) MB, 8.1 ms [Runtime::PerformGC].
[29594]   170705 ms: Mark-sweep 27.1 (58.1) -> 11.0 (54.1) MB, 6.5 ms (+ 20.3 ms in 90 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   170998 ms: Scavenge 28.1 (54.1) -> 15.1 (54.1) MB, 5.2 ms [allocation failure].
[29594]   171242 ms: Scavenge 29.1 (54.1) -> 18.4 (54.1) MB, 7.7 ms [allocation failure].
[29594]   171548 ms: Mark-sweep 33.9 (56.1) -> 11.3 (56.1) MB, 6.0 ms (+ 38.5 ms in 150 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   171838 ms: Scavenge 28.4 (56.1) -> 15.4 (56.1) MB, 4.9 ms [Runtime::PerformGC].
[29594]   172083 ms: Scavenge 29.4 (56.1) -> 18.7 (56.1) MB, 7.5 ms [Runtime::PerformGC].
[29594]   172225 ms: Mark-sweep 25.4 (57.1) -> 10.3 (54.1) MB, 5.7 ms (+ 15.6 ms in 73 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   172517 ms: Scavenge 27.4 (54.1) -> 14.4 (54.1) MB, 4.7 ms [Runtime::PerformGC].
[29594]   172756 ms: Scavenge 28.4 (54.1) -> 17.4 (54.1) MB, 7.0 ms [allocation failure].
[29594]   173074 ms: Mark-sweep 33.0 (57.1) -> 10.8 (57.1) MB, 5.5 ms (+ 41.4 ms in 151 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[29594]   173369 ms: Scavenge 27.8 (57.1) -> 14.9 (57.1) MB, 6.0 ms [Runtime::PerformGC].
[29594]   173615 ms: Scavenge 28.9 (57.1) -> 18.2 (57.1) MB, 7.3 ms [allocation failure].
[29594]   173969 ms: Scavenge 35.6 (60.1) -> 24.9 (62.1) MB, 8.4 ms (+ 45.4 ms in 165 steps since last GC) [Runtime::PerformGC].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   174040 ms: Mark-sweep 28.0 (64.1) -> 10.8 (60.1) MB, 6.8 ms (+ 54.1 ms in 193 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   174339 ms: Scavenge 27.9 (60.1) -> 15.0 (60.1) MB, 5.2 ms [Runtime::PerformGC].
[29594]   174584 ms: Scavenge 29.0 (60.1) -> 18.3 (60.1) MB, 8.0 ms [Runtime::PerformGC].
[29594]   174901 ms: Mark-sweep 33.9 (63.1) -> 11.7 (57.1) MB, 8.5 ms (+ 41.0 ms in 157 steps since start of marking, biggest step 0.8 ms) [StackGuard GC request] [GC in old space requested].
[29594]   175200 ms: Scavenge 28.8 (57.1) -> 15.8 (57.1) MB, 5.6 ms [allocation failure].
[29594]   175448 ms: Scavenge 29.8 (57.1) -> 19.1 (57.1) MB, 8.8 ms [Runtime::PerformGC].
[29594]   175748 ms: Mark-sweep 33.9 (59.1) -> 11.4 (57.1) MB, 6.3 ms (+ 36.7 ms in 146 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   176038 ms: Scavenge 28.5 (57.1) -> 15.6 (57.1) MB, 4.8 ms [Runtime::PerformGC].
[29594]   176287 ms: Scavenge 29.6 (57.1) -> 18.9 (57.1) MB, 7.5 ms [allocation failure].
[29594]   176640 ms: Scavenge 36.0 (60.1) -> 25.5 (63.1) MB, 9.1 ms (+ 41.8 ms in 164 steps since last GC) [allocation failure].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   176775 ms: Mark-sweep 31.8 (65.1) -> 11.9 (62.1) MB, 7.1 ms (+ 59.1 ms in 222 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   177067 ms: Scavenge 29.0 (62.1) -> 16.0 (62.1) MB, 4.6 ms [Runtime::PerformGC].
[29594]   177312 ms: Scavenge 30.0 (62.1) -> 19.3 (62.1) MB, 7.6 ms [allocation failure].
[29594]   177486 ms: Mark-sweep 27.9 (63.1) -> 10.7 (57.1) MB, 6.7 ms (+ 19.2 ms in 92 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   177780 ms: Scavenge 27.8 (57.1) -> 14.6 (57.1) MB, 5.1 ms [allocation failure].
[29594]   178028 ms: Scavenge 28.8 (57.1) -> 18.0 (57.1) MB, 7.1 ms [Runtime::PerformGC].
[29594]   178283 ms: Mark-sweep 30.5 (59.1) -> 10.8 (56.1) MB, 6.9 ms (+ 31.0 ms in 128 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   178580 ms: Scavenge 27.9 (56.1) -> 14.9 (56.1) MB, 5.3 ms [allocation failure].
[29594]   178828 ms: Scavenge 28.9 (56.1) -> 18.0 (56.1) MB, 7.3 ms [Runtime::PerformGC].
[29594]   178994 ms: Mark-sweep 26.1 (57.1) -> 10.0 (54.1) MB, 5.5 ms (+ 19.1 ms in 90 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   179286 ms: Scavenge 27.1 (54.1) -> 14.2 (54.1) MB, 5.1 ms [allocation failure].
[29594]   179535 ms: Scavenge 28.2 (54.1) -> 17.5 (54.1) MB, 7.7 ms [allocation failure].
[29594]   179684 ms: Mark-sweep 25.0 (55.1) -> 11.1 (55.1) MB, 3.8 ms (+ 16.2 ms in 78 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   179978 ms: Scavenge 28.1 (55.1) -> 15.2 (55.1) MB, 4.9 ms [Runtime::PerformGC].
[29594]   180219 ms: Scavenge 29.2 (55.1) -> 18.5 (55.1) MB, 7.0 ms [Runtime::PerformGC].
[29594]   180501 ms: Mark-sweep 32.5 (56.1) -> 11.4 (56.1) MB, 5.7 ms (+ 31.6 ms in 132 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   180798 ms: Scavenge 28.5 (56.1) -> 15.3 (56.1) MB, 4.3 ms [Runtime::PerformGC].
[29594]   181048 ms: Scavenge 29.6 (56.1) -> 18.7 (56.1) MB, 6.8 ms [allocation failure].
[29594]   181305 ms: Mark-sweep 31.1 (58.1) -> 11.0 (55.1) MB, 7.6 ms (+ 31.5 ms in 123 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   181606 ms: Scavenge 28.2 (55.1) -> 15.2 (55.1) MB, 5.4 ms [Runtime::PerformGC].
[29594]   181849 ms: Scavenge 29.2 (55.1) -> 18.3 (55.1) MB, 6.8 ms [allocation failure].
[29594]   182057 ms: Mark-sweep 28.4 (56.1) -> 10.6 (55.1) MB, 6.0 ms (+ 23.7 ms in 99 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   182353 ms: Scavenge 27.6 (55.1) -> 14.7 (55.1) MB, 4.8 ms [Runtime::PerformGC].
[29594]   182600 ms: Scavenge 28.7 (55.1) -> 18.0 (55.1) MB, 7.0 ms [Runtime::PerformGC].
[29594]   182906 ms: Mark-sweep 33.2 (57.1) -> 11.9 (56.1) MB, 6.3 ms (+ 39.2 ms in 143 steps since start of marking, biggest step 0.7 ms) [StackGuard GC request] [GC in old space requested].
[29594]   183203 ms: Scavenge 29.0 (56.1) -> 16.0 (56.1) MB, 5.0 ms [Runtime::PerformGC].
[29594]   183448 ms: Scavenge 30.1 (56.1) -> 19.4 (57.1) MB, 7.7 ms [Runtime::PerformGC].
[29594]   183793 ms: Mark-sweep 36.4 (60.1) -> 11.9 (57.1) MB, 8.2 ms (+ 43.3 ms in 164 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   184084 ms: Scavenge 29.0 (57.1) -> 16.0 (57.1) MB, 5.0 ms [allocation failure].
[29594]   184328 ms: Scavenge 30.0 (57.1) -> 19.3 (57.1) MB, 7.9 ms [Runtime::PerformGC].
[29594]   184626 ms: Mark-sweep 33.2 (60.1) -> 11.4 (57.1) MB, 7.6 ms (+ 34.1 ms in 138 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   184945 ms: Scavenge 28.4 (57.1) -> 15.4 (57.1) MB, 5.4 ms [Runtime::PerformGC].
[29594]   185192 ms: Scavenge 29.5 (57.1) -> 18.8 (57.1) MB, 7.6 ms [Runtime::PerformGC].
[29594]   185545 ms: Scavenge 36.0 (60.1) -> 25.6 (62.1) MB, 8.5 ms (+ 43.3 ms in 170 steps since last GC) [Runtime::PerformGC].
[29594]   185567 ms: Mark-sweep 26.4 (62.1) -> 11.3 (60.1) MB, 5.1 ms (+ 45.8 ms in 178 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   185860 ms: Scavenge 28.3 (60.1) -> 15.2 (60.1) MB, 4.3 ms [Runtime::PerformGC].
[29594]   186111 ms: Scavenge 29.4 (60.1) -> 18.6 (60.1) MB, 8.6 ms [Runtime::PerformGC].
[29594]   186273 ms: Mark-sweep 26.5 (60.1) -> 10.5 (56.1) MB, 5.6 ms (+ 19.1 ms in 87 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   186574 ms: Scavenge 27.6 (56.1) -> 14.7 (56.1) MB, 5.0 ms [Runtime::PerformGC].
[29594]   186825 ms: Scavenge 28.7 (56.1) -> 17.8 (56.1) MB, 6.5 ms [allocation failure].
[29594]   187192 ms: Scavenge 35.7 (60.1) -> 25.2 (60.1) MB, 7.3 ms (+ 47.7 ms in 176 steps since last GC) [allocation failure].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   187244 ms: Mark-sweep 27.5 (61.1) -> 11.1 (61.1) MB, 4.9 ms (+ 54.4 ms in 196 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   187546 ms: Scavenge 28.2 (61.1) -> 15.3 (61.1) MB, 4.8 ms [Runtime::PerformGC].
[29594]   187791 ms: Scavenge 29.3 (61.1) -> 18.2 (61.1) MB, 6.1 ms [Runtime::PerformGC].
[29594]   187914 ms: Mark-sweep 23.9 (61.1) -> 9.9 (56.1) MB, 4.7 ms (+ 12.6 ms in 67 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   188210 ms: Scavenge 26.9 (56.1) -> 14.0 (56.1) MB, 4.7 ms [allocation failure].
[29594]   188453 ms: Scavenge 28.0 (56.1) -> 17.3 (56.1) MB, 7.0 ms [Runtime::PerformGC].
[29594]   188799 ms: Scavenge 34.4 (59.1) -> 23.4 (61.1) MB, 8.2 ms (+ 41.9 ms in 160 steps since last GC) [Runtime::PerformGC].
[29594]   188846 ms: Mark-sweep 25.4 (61.1) -> 11.3 (58.1) MB, 5.2 ms (+ 48.0 ms in 179 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   189145 ms: Scavenge 28.4 (58.1) -> 15.4 (58.1) MB, 5.5 ms [Runtime::PerformGC].
[29594]   189407 ms: Scavenge 29.6 (58.1) -> 18.9 (58.1) MB, 7.8 ms [Runtime::PerformGC].
[29594]   189763 ms: Scavenge 36.3 (61.1) -> 25.3 (63.1) MB, 7.7 ms (+ 44.1 ms in 170 steps since last GC) [Runtime::PerformGC].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   189861 ms: Mark-sweep 29.8 (64.1) -> 11.5 (61.1) MB, 5.7 ms (+ 57.0 ms in 209 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   190161 ms: Scavenge 28.6 (61.1) -> 15.6 (61.1) MB, 5.2 ms [Runtime::PerformGC].
[29594]   190412 ms: Scavenge 29.6 (61.1) -> 19.0 (61.1) MB, 7.3 ms [Runtime::PerformGC].
[29594]   190757 ms: Mark-sweep 35.7 (65.1) -> 11.7 (59.1) MB, 8.4 ms (+ 41.6 ms in 164 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[29594]   191058 ms: Scavenge 28.8 (59.1) -> 15.8 (59.1) MB, 5.2 ms [Runtime::PerformGC].
[29594]   191309 ms: Scavenge 29.8 (59.1) -> 19.1 (59.1) MB, 7.2 ms [Runtime::PerformGC].
[29594]   191548 ms: Mark-sweep 30.9 (60.1) -> 11.2 (56.1) MB, 6.7 ms (+ 29.5 ms in 120 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   191850 ms: Scavenge 28.2 (56.1) -> 15.1 (56.1) MB, 4.9 ms [Runtime::PerformGC].
[29594]   192099 ms: Scavenge 29.3 (56.1) -> 18.5 (57.1) MB, 7.3 ms [allocation failure].
[29594]   192463 ms: Scavenge 35.9 (60.1) -> 25.5 (61.1) MB, 9.1 ms (+ 45.3 ms in 174 steps since last GC) [Runtime::PerformGC].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   192555 ms: Mark-sweep 29.6 (62.1) -> 11.6 (61.1) MB, 6.9 ms (+ 57.2 ms in 211 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   192853 ms: Scavenge 28.6 (61.1) -> 15.6 (61.1) MB, 5.1 ms [allocation failure].
[29594]   193104 ms: Scavenge 29.7 (61.1) -> 18.9 (61.1) MB, 7.1 ms [Runtime::PerformGC].
[29594]   193297 ms: Mark-sweep 28.4 (62.1) -> 10.5 (56.1) MB, 6.0 ms (+ 22.5 ms in 98 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   193593 ms: Scavenge 27.6 (56.1) -> 14.6 (56.1) MB, 5.6 ms [Runtime::PerformGC].
[29594]   193844 ms: Scavenge 28.6 (56.1) -> 17.4 (56.1) MB, 6.6 ms [Runtime::PerformGC].
[29594]   194214 ms: Scavenge 35.2 (60.1) -> 24.7 (62.1) MB, 8.9 ms (+ 46.6 ms in 172 steps since last GC) [Runtime::PerformGC].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   194287 ms: Mark-sweep 27.9 (63.1) -> 11.1 (59.1) MB, 7.2 ms (+ 55.8 ms in 200 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   194583 ms: Scavenge 28.2 (59.1) -> 15.3 (59.1) MB, 5.8 ms [Runtime::PerformGC].
[29594]   194828 ms: Scavenge 29.3 (59.1) -> 18.2 (59.1) MB, 6.9 ms [Runtime::PerformGC].
[29594]   195014 ms: Mark-sweep 27.0 (61.1) -> 10.2 (55.1) MB, 6.2 ms (+ 22.0 ms in 91 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[29594]   195311 ms: Scavenge 27.3 (55.1) -> 14.3 (55.1) MB, 4.9 ms [allocation failure].
[29594]   195558 ms: Scavenge 28.3 (55.1) -> 17.6 (55.1) MB, 7.6 ms [Runtime::PerformGC].
[29594]   195761 ms: Mark-sweep 27.8 (56.1) -> 11.3 (56.1) MB, 5.1 ms (+ 22.1 ms in 99 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   196061 ms: Scavenge 28.4 (56.1) -> 15.4 (56.1) MB, 5.4 ms [Runtime::PerformGC].
[29594]   196308 ms: Scavenge 29.4 (56.1) -> 18.7 (56.1) MB, 8.0 ms [allocation failure].
[29594]   196631 ms: Mark-sweep 34.4 (58.1) -> 11.4 (57.1) MB, 7.6 ms (+ 40.6 ms in 153 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   196926 ms: Scavenge 28.5 (57.1) -> 15.4 (57.1) MB, 4.3 ms [Runtime::PerformGC].
[29594]   197176 ms: Scavenge 29.6 (57.1) -> 18.7 (57.1) MB, 7.3 ms [allocation failure].
[29594]   197531 ms: Scavenge 36.0 (60.1) -> 25.5 (62.1) MB, 8.8 ms (+ 43.3 ms in 169 steps since last GC) [allocation failure].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   197647 ms: Mark-sweep 30.9 (63.1) -> 11.7 (61.1) MB, 6.2 ms (+ 58.5 ms in 217 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   197935 ms: Scavenge 28.7 (61.1) -> 15.7 (61.1) MB, 5.0 ms [allocation failure].
[29594]   198181 ms: Scavenge 29.8 (61.1) -> 19.0 (61.1) MB, 7.2 ms [Runtime::PerformGC].
[29594]   198351 ms: Mark-sweep 27.4 (61.1) -> 10.5 (55.1) MB, 6.5 ms (+ 19.4 ms in 88 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   198651 ms: Scavenge 27.6 (55.1) -> 14.7 (55.1) MB, 5.6 ms [allocation failure].
[29594]   198896 ms: Scavenge 28.7 (55.1) -> 17.7 (56.1) MB, 7.0 ms [Runtime::PerformGC].
[29594]   199082 ms: Mark-sweep 26.7 (57.1) -> 10.2 (57.1) MB, 4.3 ms (+ 21.9 ms in 91 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[29594]   199380 ms: Scavenge 27.3 (57.1) -> 14.4 (57.1) MB, 5.2 ms [allocation failure].
[29594]   199631 ms: Scavenge 28.4 (57.1) -> 17.7 (57.1) MB, 7.3 ms [Runtime::PerformGC].
[29594]   199786 ms: Mark-sweep 25.2 (57.1) -> 11.2 (56.1) MB, 5.7 ms (+ 16.2 ms in 80 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   200086 ms: Scavenge 28.2 (56.1) -> 15.3 (56.1) MB, 5.5 ms [Runtime::PerformGC].
[29594]   200330 ms: Scavenge 29.3 (56.1) -> 18.6 (56.1) MB, 7.4 ms [allocation failure].
[29594]   200584 ms: Mark-sweep 31.5 (58.1) -> 11.1 (58.1) MB, 5.7 ms (+ 29.9 ms in 131 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   200877 ms: Scavenge 28.2 (58.1) -> 15.0 (58.1) MB, 4.4 ms [Runtime::PerformGC].
[29594]   201126 ms: Scavenge 29.3 (58.1) -> 18.4 (58.1) MB, 6.8 ms [Runtime::PerformGC].
[29594]   201496 ms: Scavenge 35.9 (62.1) -> 25.5 (63.1) MB, 7.8 ms (+ 47.4 ms in 170 steps since last GC) [Runtime::PerformGC].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   201555 ms: Mark-sweep 28.0 (63.1) -> 11.4 (59.1) MB, 8.2 ms (+ 54.8 ms in 194 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   201852 ms: Scavenge 28.5 (59.1) -> 15.4 (59.1) MB, 5.2 ms [Runtime::PerformGC].
[29594]   202107 ms: Scavenge 29.5 (59.1) -> 18.7 (59.1) MB, 7.6 ms [allocation failure].
[29594]   202280 ms: Mark-sweep 27.1 (60.1) -> 10.4 (56.1) MB, 5.1 ms (+ 18.6 ms in 85 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   202579 ms: Scavenge 27.5 (56.1) -> 14.5 (56.1) MB, 5.1 ms [allocation failure].
[29594]   202818 ms: Scavenge 28.5 (56.1) -> 17.3 (56.1) MB, 5.9 ms [Runtime::PerformGC].
[29594]   203198 ms: Scavenge 35.4 (59.1) -> 24.9 (61.1) MB, 9.3 ms (+ 47.4 ms in 176 steps since last GC) [Runtime::PerformGC].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   203262 ms: Mark-sweep 27.5 (62.1) -> 11.1 (59.1) MB, 5.9 ms (+ 56.3 ms in 201 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[29594]   203562 ms: Scavenge 28.2 (59.1) -> 15.2 (59.1) MB, 5.4 ms [Runtime::PerformGC].
[29594]   203807 ms: Scavenge 29.2 (59.1) -> 18.5 (59.1) MB, 7.6 ms [Runtime::PerformGC].
[29594]   203954 ms: Mark-sweep 25.6 (59.1) -> 9.9 (54.1) MB, 6.0 ms (+ 15.8 ms in 77 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   204251 ms: Scavenge 27.0 (54.1) -> 14.0 (54.1) MB, 5.4 ms [Runtime::PerformGC].
[29594]   204502 ms: Scavenge 28.0 (55.1) -> 17.4 (55.1) MB, 7.0 ms [allocation failure].
[29594]   204712 ms: Mark-sweep 27.4 (56.1) -> 11.0 (56.1) MB, 4.3 ms (+ 24.0 ms in 98 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   205008 ms: Scavenge 28.1 (56.1) -> 15.1 (56.1) MB, 4.8 ms [Runtime::PerformGC].
[29594]   205254 ms: Scavenge 29.2 (56.1) -> 18.4 (56.1) MB, 6.7 ms [Runtime::PerformGC].
[29594]   205570 ms: Mark-sweep 34.0 (58.1) -> 11.3 (57.1) MB, 7.9 ms (+ 39.7 ms in 151 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   205869 ms: Scavenge 28.4 (57.1) -> 15.3 (57.1) MB, 4.7 ms [Runtime::PerformGC].
[29594]   206118 ms: Scavenge 29.5 (57.1) -> 18.6 (57.1) MB, 7.4 ms [allocation failure].
[29594]   206476 ms: Mark-sweep 35.8 (60.1) -> 11.3 (58.1) MB, 8.8 ms (+ 47.0 ms in 175 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   206775 ms: Scavenge 28.4 (58.1) -> 15.5 (58.1) MB, 5.3 ms [Runtime::PerformGC].
[29594]   207018 ms: Scavenge 29.4 (58.1) -> 18.6 (58.1) MB, 7.1 ms [Runtime::PerformGC].
[29594]   207379 ms: Mark-sweep 35.9 (61.1) -> 11.2 (57.1) MB, 8.8 ms (+ 47.0 ms in 175 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   207682 ms: Scavenge 28.3 (57.1) -> 15.4 (57.1) MB, 5.0 ms [Runtime::PerformGC].
[29594]   207930 ms: Scavenge 29.4 (57.1) -> 18.2 (57.1) MB, 6.4 ms [Runtime::PerformGC].
[29594]   208106 ms: Mark-sweep 26.8 (58.1) -> 10.2 (55.1) MB, 6.0 ms (+ 19.9 ms in 85 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   208402 ms: Scavenge 27.3 (55.1) -> 14.3 (55.1) MB, 5.3 ms [Runtime::PerformGC].
[29594]   208650 ms: Scavenge 28.3 (55.1) -> 17.6 (55.1) MB, 7.5 ms [Runtime::PerformGC].
[29594]   208833 ms: Mark-sweep 26.8 (56.1) -> 11.3 (56.1) MB, 4.6 ms (+ 20.4 ms in 91 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   209129 ms: Scavenge 28.4 (56.1) -> 15.4 (56.1) MB, 5.0 ms [Runtime::PerformGC].
[29594]   209380 ms: Scavenge 29.4 (56.1) -> 18.7 (56.1) MB, 7.6 ms [Runtime::PerformGC].
[29594]   209744 ms: Scavenge 36.2 (59.1) -> 25.8 (61.1) MB, 9.2 ms (+ 46.2 ms in 171 steps since last GC) [Runtime::PerformGC].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   209822 ms: Mark-sweep 29.2 (62.1) -> 11.7 (61.1) MB, 5.3 ms (+ 57.1 ms in 204 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   210115 ms: Scavenge 28.8 (61.1) -> 15.8 (61.1) MB, 5.2 ms [Runtime::PerformGC].
[29594]   210362 ms: Scavenge 29.9 (61.1) -> 19.3 (61.1) MB, 7.5 ms [Runtime::PerformGC].
[29594]   210544 ms: Mark-sweep 27.9 (62.1) -> 10.7 (54.1) MB, 8.3 ms (+ 20.5 ms in 90 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   210843 ms: Scavenge 27.8 (54.1) -> 14.9 (54.1) MB, 5.3 ms [Runtime::PerformGC].
[29594]   211087 ms: Scavenge 28.8 (55.1) -> 17.9 (56.1) MB, 7.4 ms [allocation failure].
[29594]   211444 ms: Scavenge 35.2 (59.1) -> 24.7 (59.1) MB, 8.7 ms (+ 42.7 ms in 160 steps since last GC) [Runtime::PerformGC].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   211584 ms: Mark-sweep 30.7 (62.1) -> 11.6 (62.1) MB, 5.5 ms (+ 58.9 ms in 211 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[29594]   211903 ms: Scavenge 28.8 (62.1) -> 15.8 (62.1) MB, 5.0 ms [Runtime::PerformGC].
[29594]   212166 ms: Scavenge 29.8 (62.1) -> 19.1 (62.1) MB, 7.2 ms [Runtime::PerformGC].
[29594]   212509 ms: Mark-sweep 35.1 (64.1) -> 11.4 (58.1) MB, 8.0 ms (+ 44.4 ms in 165 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   212826 ms: Scavenge 28.5 (58.1) -> 15.5 (58.1) MB, 4.8 ms [allocation failure].
[29594]   213086 ms: Scavenge 29.5 (58.1) -> 18.7 (58.1) MB, 7.1 ms [Runtime::PerformGC].
[29594]   213440 ms: Mark-sweep 35.3 (61.1) -> 11.3 (59.1) MB, 7.3 ms (+ 43.0 ms in 164 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   213760 ms: Scavenge 28.3 (59.1) -> 15.4 (59.1) MB, 5.2 ms [Runtime::PerformGC].
[29594]   214010 ms: Scavenge 29.4 (59.1) -> 18.3 (59.1) MB, 6.1 ms [Runtime::PerformGC].
[29594]   214207 ms: Mark-sweep 28.1 (60.1) -> 10.5 (57.1) MB, 5.6 ms (+ 23.9 ms in 104 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   214507 ms: Scavenge 27.5 (57.1) -> 14.6 (57.1) MB, 5.5 ms [Runtime::PerformGC].
[29594]   214755 ms: Scavenge 28.6 (57.1) -> 17.9 (57.1) MB, 8.1 ms [Runtime::PerformGC].
[29594]   214953 ms: Mark-sweep 27.7 (57.1) -> 11.3 (56.1) MB, 6.0 ms (+ 22.3 ms in 95 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[29594]   215249 ms: Scavenge 28.3 (56.1) -> 15.4 (56.1) MB, 5.2 ms [Runtime::PerformGC].
[29594]   215493 ms: Scavenge 29.4 (56.1) -> 18.7 (56.1) MB, 6.9 ms [allocation failure].
[29594]   215762 ms: Mark-sweep 31.9 (58.1) -> 11.3 (58.1) MB, 5.3 ms (+ 32.0 ms in 132 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   216057 ms: Scavenge 28.3 (58.1) -> 15.3 (58.1) MB, 5.3 ms [Runtime::PerformGC].
[29594]   216308 ms: Scavenge 29.4 (58.1) -> 18.7 (58.1) MB, 7.6 ms [Runtime::PerformGC].
[29594]   216582 ms: Mark-sweep 31.5 (60.1) -> 11.0 (57.1) MB, 10.1 ms (+ 31.9 ms in 128 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   216898 ms: Scavenge 28.1 (57.1) -> 14.9 (57.1) MB, 4.7 ms [allocation failure].
[29594]   217149 ms: Scavenge 29.1 (57.1) -> 18.3 (57.1) MB, 7.5 ms [allocation failure].
[29594]   217437 ms: Mark-sweep 32.5 (59.1) -> 11.0 (59.1) MB, 5.8 ms (+ 36.3 ms in 137 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   217738 ms: Scavenge 28.1 (59.1) -> 15.1 (59.1) MB, 5.3 ms [Runtime::PerformGC].
[29594]   217980 ms: Scavenge 29.1 (59.1) -> 18.0 (59.1) MB, 6.0 ms [allocation failure].
[29594]   218220 ms: Mark-sweep 29.5 (61.1) -> 10.5 (57.1) MB, 6.6 ms (+ 28.7 ms in 118 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   218521 ms: Scavenge 27.6 (57.1) -> 14.7 (57.1) MB, 5.5 ms [allocation failure].
[29594]   218767 ms: Scavenge 28.7 (57.1) -> 18.0 (57.1) MB, 6.8 ms [Runtime::PerformGC].
[29594]   219011 ms: Mark-sweep 30.2 (58.1) -> 11.6 (58.1) MB, 5.0 ms (+ 30.2 ms in 116 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[29594]   219309 ms: Scavenge 28.6 (58.1) -> 15.7 (58.1) MB, 5.9 ms [Runtime::PerformGC].
[29594]   219555 ms: Scavenge 29.7 (58.1) -> 19.0 (58.1) MB, 7.6 ms [allocation failure].
[29594]   219886 ms: Mark-sweep 35.0 (60.1) -> 11.8 (58.1) MB, 6.7 ms (+ 40.1 ms in 158 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   220186 ms: Scavenge 28.9 (58.1) -> 15.9 (58.1) MB, 5.3 ms [Runtime::PerformGC].
[29594]   220432 ms: Scavenge 29.9 (58.1) -> 19.3 (58.1) MB, 7.3 ms [Runtime::PerformGC].
[29594]   220787 ms: Scavenge 36.5 (61.1) -> 26.0 (63.1) MB, 8.7 ms (+ 43.5 ms in 170 steps since last GC) [Runtime::PerformGC].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   220904 ms: Mark-sweep 31.7 (64.1) -> 12.1 (62.1) MB, 7.1 ms (+ 59.2 ms in 217 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   221201 ms: Scavenge 29.2 (62.1) -> 16.2 (62.1) MB, 5.2 ms [Runtime::PerformGC].
[29594]   221455 ms: Scavenge 30.2 (62.1) -> 19.6 (62.1) MB, 7.1 ms [Runtime::PerformGC].
[29594]   221811 ms: Scavenge 36.9 (65.1) -> 26.4 (65.1) MB, 7.9 ms (+ 44.4 ms in 174 steps since last GC) [Runtime::PerformGC].
[29594]   221898 ms: Mark-sweep 30.2 (66.1) -> 11.8 (60.1) MB, 8.0 ms (+ 55.1 ms in 209 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   222200 ms: Scavenge 28.8 (60.1) -> 15.8 (60.1) MB, 5.2 ms [Runtime::PerformGC].
[29594]   222443 ms: Scavenge 29.9 (60.1) -> 19.2 (60.1) MB, 7.4 ms [allocation failure].
[29594]   222799 ms: Scavenge 36.5 (62.1) -> 26.1 (63.1) MB, 8.2 ms (+ 44.6 ms in 173 steps since last GC) [Runtime::PerformGC].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   222886 ms: Mark-sweep 30.2 (64.1) -> 11.8 (61.1) MB, 6.2 ms (+ 55.5 ms in 213 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   223183 ms: Scavenge 28.8 (61.1) -> 15.8 (61.1) MB, 5.6 ms [Runtime::PerformGC].
[29594]   223432 ms: Scavenge 29.9 (61.1) -> 19.2 (61.1) MB, 7.0 ms [Runtime::PerformGC].
[29594]   223793 ms: Scavenge 36.7 (64.1) -> 26.2 (64.1) MB, 8.0 ms (+ 46.5 ms in 176 steps since last GC) [Runtime::PerformGC].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   223867 ms: Mark-sweep 29.5 (66.1) -> 11.7 (61.1) MB, 7.3 ms (+ 56.6 ms in 208 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   224159 ms: Scavenge 28.8 (61.1) -> 15.8 (61.1) MB, 4.8 ms [Runtime::PerformGC].
[29594]   224409 ms: Scavenge 29.9 (61.1) -> 19.1 (61.1) MB, 7.0 ms [allocation failure].
[29594]   224756 ms: Mark-sweep 35.5 (64.1) -> 11.5 (58.1) MB, 8.4 ms (+ 43.7 ms in 163 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   225048 ms: Scavenge 28.5 (58.1) -> 15.4 (58.1) MB, 4.6 ms [Runtime::PerformGC].
[29594]   225297 ms: Scavenge 29.6 (58.1) -> 18.8 (58.1) MB, 6.9 ms [Runtime::PerformGC].
[29594]   225648 ms: Scavenge 36.1 (61.1) -> 25.7 (62.1) MB, 8.2 ms (+ 43.8 ms in 173 steps since last GC) [Runtime::PerformGC].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   225739 ms: Mark-sweep 29.9 (63.1) -> 11.6 (60.1) MB, 6.6 ms (+ 55.5 ms in 214 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   226033 ms: Scavenge 28.7 (60.1) -> 15.6 (60.1) MB, 4.8 ms [Runtime::PerformGC].
[29594]   226282 ms: Scavenge 29.7 (60.1) -> 18.9 (60.1) MB, 7.0 ms [Runtime::PerformGC].
[29594]   226644 ms: Scavenge 36.4 (63.1) -> 26.0 (63.1) MB, 8.2 ms (+ 44.9 ms in 176 steps since last GC) [Runtime::PerformGC].
[29594]   226690 ms: Mark-sweep 28.0 (63.1) -> 11.4 (60.1) MB, 5.4 ms (+ 50.7 ms in 194 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   226988 ms: Scavenge 28.4 (60.1) -> 15.4 (60.1) MB, 4.6 ms [Runtime::PerformGC].
[29594]   227237 ms: Scavenge 29.5 (60.1) -> 18.7 (60.1) MB, 7.0 ms [allocation failure].
[29594]   227551 ms: Mark-sweep 34.0 (64.1) -> 11.2 (59.1) MB, 7.6 ms (+ 39.5 ms in 152 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   227851 ms: Scavenge 28.3 (59.1) -> 15.3 (59.1) MB, 5.4 ms [Runtime::PerformGC].
[29594]   228099 ms: Scavenge 29.3 (59.1) -> 18.4 (59.1) MB, 7.4 ms [allocation failure].
[29594]   228465 ms: Scavenge 36.1 (63.1) -> 25.6 (63.1) MB, 8.0 ms (+ 45.6 ms in 177 steps since last GC) [allocation failure].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   228542 ms: Mark-sweep 28.9 (64.1) -> 11.3 (61.1) MB, 7.1 ms (+ 55.6 ms in 208 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   228845 ms: Scavenge 28.4 (61.1) -> 15.4 (61.1) MB, 5.6 ms [Runtime::PerformGC].
[29594]   229089 ms: Scavenge 29.4 (61.1) -> 18.3 (61.1) MB, 6.4 ms [allocation failure].
[29594]   229350 ms: Mark-sweep 30.9 (63.1) -> 10.7 (56.1) MB, 7.2 ms (+ 32.4 ms in 129 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[29594]   229652 ms: Scavenge 27.8 (56.1) -> 14.9 (56.1) MB, 5.1 ms [Runtime::PerformGC].
[29594]   229904 ms: Scavenge 28.9 (56.1) -> 18.2 (57.1) MB, 8.3 ms [Runtime::PerformGC].
[29594]   230071 ms: Mark-sweep 26.1 (57.1) -> 11.1 (55.1) MB, 5.8 ms (+ 18.0 ms in 81 steps since start of marking, biggest step 0.7 ms) [StackGuard GC request] [GC in old space requested].
[29594]   230367 ms: Scavenge 28.2 (55.1) -> 15.2 (55.1) MB, 5.0 ms [allocation failure].
[29594]   230616 ms: Scavenge 29.3 (56.1) -> 18.6 (56.1) MB, 7.1 ms [Runtime::PerformGC].
[29594]   230964 ms: Scavenge 35.7 (59.1) -> 24.8 (60.1) MB, 7.2 ms (+ 42.8 ms in 163 steps since last GC) [allocation failure].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   231081 ms: Mark-sweep 30.3 (61.1) -> 11.6 (61.1) MB, 5.2 ms (+ 59.6 ms in 214 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   231378 ms: Scavenge 28.7 (61.1) -> 15.7 (61.1) MB, 4.9 ms [Runtime::PerformGC].
[29594]   231623 ms: Scavenge 29.7 (61.1) -> 19.1 (61.1) MB, 7.3 ms [Runtime::PerformGC].
[29594]   231961 ms: Mark-sweep 35.2 (64.1) -> 11.8 (59.1) MB, 8.4 ms (+ 42.0 ms in 155 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   232267 ms: Scavenge 28.9 (59.1) -> 15.9 (59.1) MB, 6.1 ms [Runtime::PerformGC].
[29594]   232524 ms: Scavenge 29.9 (59.1) -> 19.2 (59.1) MB, 8.0 ms [allocation failure].
[29594]   232880 ms: Scavenge 36.5 (62.1) -> 26.1 (64.1) MB, 8.3 ms (+ 43.3 ms in 177 steps since last GC) [allocation failure].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   232996 ms: Mark-sweep 31.6 (66.1) -> 11.9 (62.1) MB, 6.9 ms (+ 57.7 ms in 224 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   233300 ms: Scavenge 29.0 (62.1) -> 16.0 (62.1) MB, 5.5 ms [Runtime::PerformGC].
[29594]   233547 ms: Scavenge 30.0 (62.1) -> 19.3 (62.1) MB, 7.4 ms [Runtime::PerformGC].
[29594]   233905 ms: Scavenge 36.7 (66.1) -> 26.2 (66.1) MB, 8.8 ms (+ 45.0 ms in 166 steps since last GC) [Runtime::PerformGC].
[29594]   233962 ms: Mark-sweep 28.7 (67.1) -> 11.6 (61.1) MB, 7.4 ms (+ 51.7 ms in 188 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   234262 ms: Scavenge 28.7 (61.1) -> 15.7 (61.1) MB, 5.4 ms [Runtime::PerformGC].
[29594]   234509 ms: Scavenge 29.8 (61.1) -> 19.1 (61.1) MB, 7.4 ms [Runtime::PerformGC].
[29594]   234685 ms: Mark-sweep 27.6 (62.1) -> 10.7 (57.1) MB, 5.9 ms (+ 19.6 ms in 90 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   234983 ms: Scavenge 27.8 (57.1) -> 14.8 (57.1) MB, 5.1 ms [Runtime::PerformGC].
[29594]   235230 ms: Scavenge 28.8 (57.1) -> 17.8 (57.1) MB, 7.1 ms [Runtime::PerformGC].
[29594]   235502 ms: Mark-sweep 30.8 (59.1) -> 10.8 (56.1) MB, 6.4 ms (+ 32.4 ms in 124 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   235819 ms: Scavenge 27.9 (56.1) -> 14.9 (56.1) MB, 5.4 ms [allocation failure].
[29594]   236079 ms: Scavenge 28.9 (56.1) -> 17.8 (56.1) MB, 6.2 ms [Runtime::PerformGC].
[29594]   236267 ms: Mark-sweep 26.4 (57.1) -> 10.2 (55.1) MB, 5.3 ms (+ 20.7 ms in 90 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   236592 ms: Scavenge 27.3 (55.1) -> 14.3 (55.1) MB, 5.4 ms [Runtime::PerformGC].
[29594]   236858 ms: Scavenge 28.3 (55.1) -> 17.6 (55.1) MB, 7.1 ms [allocation failure].
[29594]   237225 ms: Scavenge 34.9 (57.1) -> 24.3 (59.1) MB, 9.1 ms (+ 44.2 ms in 157 steps since last GC) [Runtime::PerformGC].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   237319 ms: Mark-sweep 28.7 (60.1) -> 12.0 (60.1) MB, 4.6 ms (+ 55.5 ms in 192 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   237643 ms: Scavenge 29.1 (60.1) -> 16.1 (60.1) MB, 5.2 ms [Runtime::PerformGC].
[29594]   237905 ms: Scavenge 30.2 (60.1) -> 19.5 (60.1) MB, 7.1 ms [Runtime::PerformGC].
[29594]   238085 ms: Mark-sweep 27.8 (61.1) -> 10.9 (55.1) MB, 6.6 ms (+ 19.0 ms in 82 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   238403 ms: Scavenge 28.0 (55.1) -> 15.1 (55.1) MB, 5.3 ms [Runtime::PerformGC].
[29594]   238669 ms: Scavenge 29.1 (56.1) -> 18.4 (56.1) MB, 7.2 ms [allocation failure].
[29594]   238987 ms: Mark-sweep 33.2 (59.1) -> 11.4 (59.1) MB, 5.6 ms (+ 36.8 ms in 142 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   239287 ms: Scavenge 28.5 (59.1) -> 15.5 (59.1) MB, 5.0 ms [Runtime::PerformGC].
[29594]   239535 ms: Scavenge 29.6 (59.1) -> 18.8 (59.1) MB, 8.2 ms [Runtime::PerformGC].
[29594]   239766 ms: Mark-sweep 30.0 (61.1) -> 11.0 (57.1) MB, 6.9 ms (+ 27.3 ms in 104 steps since start of marking, biggest step 0.7 ms) [StackGuard GC request] [GC in old space requested].
[29594]   240062 ms: Scavenge 28.0 (57.1) -> 14.9 (57.1) MB, 5.0 ms [allocation failure].
[29594]   240316 ms: Scavenge 29.2 (57.1) -> 18.4 (57.1) MB, 7.2 ms [Runtime::PerformGC].
[29594]   240687 ms: Scavenge 35.8 (59.1) -> 25.5 (60.1) MB, 7.7 ms (+ 46.0 ms in 167 steps since last GC) [Runtime::PerformGC].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   240770 ms: Mark-sweep 29.3 (61.1) -> 11.7 (61.1) MB, 6.0 ms (+ 57.1 ms in 202 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   241071 ms: Scavenge 28.7 (61.1) -> 15.7 (61.1) MB, 5.1 ms [Runtime::PerformGC].
[29594]   241320 ms: Scavenge 29.8 (61.1) -> 19.0 (61.1) MB, 7.5 ms [Runtime::PerformGC].
[29594]   241582 ms: Mark-sweep 31.2 (64.1) -> 11.0 (57.1) MB, 8.3 ms (+ 30.9 ms in 124 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   241884 ms: Scavenge 28.2 (57.1) -> 15.3 (57.1) MB, 4.9 ms [Runtime::PerformGC].
[29594]   242126 ms: Scavenge 29.2 (57.1) -> 18.1 (57.1) MB, 6.2 ms [allocation failure].
[29594]   242272 ms: Mark-sweep 25.1 (58.1) -> 10.3 (55.1) MB, 4.5 ms (+ 15.4 ms in 76 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   242572 ms: Scavenge 27.4 (55.1) -> 14.4 (55.1) MB, 5.5 ms [Runtime::PerformGC].
[29594]   242819 ms: Scavenge 28.4 (55.1) -> 17.8 (56.1) MB, 7.8 ms [Runtime::PerformGC].
[29594]   242998 ms: Mark-sweep 26.2 (57.1) -> 11.2 (54.1) MB, 5.8 ms (+ 20.0 ms in 88 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   243292 ms: Scavenge 28.3 (54.1) -> 15.3 (54.1) MB, 5.1 ms [Runtime::PerformGC].
[29594]   243544 ms: Scavenge 29.4 (54.1) -> 18.6 (55.1) MB, 9.1 ms [Runtime::PerformGC].
[29594]   243906 ms: Scavenge 36.1 (58.1) -> 25.7 (61.1) MB, 9.8 ms (+ 45.7 ms in 173 steps since last GC) [Runtime::PerformGC].
[29594]   243966 ms: Mark-sweep 28.3 (61.1) -> 11.8 (60.1) MB, 6.5 ms (+ 53.2 ms in 198 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   244263 ms: Scavenge 28.9 (60.1) -> 15.9 (60.1) MB, 5.2 ms [Runtime::PerformGC].
[29594]   244515 ms: Scavenge 30.0 (60.1) -> 19.2 (60.1) MB, 7.2 ms [allocation failure].
[29594]   244751 ms: Mark-sweep 30.5 (62.1) -> 11.0 (57.1) MB, 6.7 ms (+ 29.2 ms in 118 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   245049 ms: Scavenge 28.1 (57.1) -> 14.9 (57.1) MB, 4.8 ms [Runtime::PerformGC].
[29594]   245299 ms: Scavenge 29.2 (57.1) -> 18.3 (57.1) MB, 7.2 ms [Runtime::PerformGC].
[29594]   245655 ms: Scavenge 35.7 (60.1) -> 25.4 (61.1) MB, 8.3 ms (+ 45.6 ms in 165 steps since last GC) [Runtime::PerformGC].
[29594]   245706 ms: Mark-sweep 27.5 (62.1) -> 11.3 (60.1) MB, 7.4 ms (+ 51.5 ms in 183 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   246009 ms: Scavenge 28.4 (60.1) -> 15.4 (60.1) MB, 5.1 ms [Runtime::PerformGC].
[29594]   246254 ms: Scavenge 29.4 (60.1) -> 18.6 (60.1) MB, 6.8 ms [allocation failure].
[29594]   246506 ms: Mark-sweep 31.0 (63.1) -> 10.8 (58.1) MB, 6.8 ms (+ 30.9 ms in 130 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   246838 ms: Scavenge 27.9 (58.1) -> 14.9 (58.1) MB, 4.9 ms [allocation failure].
[29594]   247098 ms: Scavenge 28.9 (58.1) -> 17.8 (58.1) MB, 5.9 ms [Runtime::PerformGC].
[29594]   247229 ms: Mark-sweep 23.7 (58.1) -> 9.9 (56.1) MB, 4.1 ms (+ 13.5 ms in 67 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   247548 ms: Scavenge 27.0 (56.1) -> 14.1 (56.1) MB, 5.7 ms [Runtime::PerformGC].
[29594]   247819 ms: Scavenge 28.1 (56.1) -> 17.4 (56.1) MB, 7.2 ms [Runtime::PerformGC].
[29594]   248041 ms: Mark-sweep 27.9 (57.1) -> 11.4 (55.1) MB, 5.9 ms (+ 26.2 ms in 105 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   248360 ms: Scavenge 28.5 (55.1) -> 15.6 (55.1) MB, 5.2 ms [allocation failure].
[29594]   248625 ms: Scavenge 29.6 (55.1) -> 18.9 (55.1) MB, 8.0 ms [Runtime::PerformGC].
[29594]   248834 ms: Mark-sweep 28.3 (55.1) -> 10.9 (54.1) MB, 6.4 ms (+ 23.1 ms in 97 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   249146 ms: Scavenge 27.9 (54.1) -> 14.8 (54.1) MB, 4.4 ms [allocation failure].
[29594]   249414 ms: Scavenge 29.1 (54.1) -> 18.2 (54.1) MB, 6.9 ms [Runtime::PerformGC].
[29594]   249556 ms: Mark-sweep 24.9 (55.1) -> 10.4 (55.1) MB, 4.3 ms (+ 14.5 ms in 70 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   249876 ms: Scavenge 27.5 (55.1) -> 14.5 (55.1) MB, 6.0 ms [Runtime::PerformGC].
[29594]   250136 ms: Scavenge 28.5 (55.1) -> 17.3 (55.1) MB, 6.4 ms [Runtime::PerformGC].
[29594]   250498 ms: Mark-sweep 34.8 (57.1) -> 11.2 (57.1) MB, 6.1 ms (+ 46.4 ms in 165 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   250799 ms: Scavenge 28.3 (57.1) -> 15.3 (57.1) MB, 4.8 ms [Runtime::PerformGC].
[29594]   251047 ms: Scavenge 29.3 (57.1) -> 18.6 (57.1) MB, 7.0 ms [Runtime::PerformGC].
[29594]   251211 ms: Mark-sweep 26.7 (58.1) -> 10.1 (54.1) MB, 6.2 ms (+ 19.5 ms in 79 steps since start of marking, biggest step 0.7 ms) [StackGuard GC request] [GC in old space requested].
[29594]   251509 ms: Scavenge 27.2 (54.1) -> 14.2 (54.1) MB, 5.2 ms [allocation failure].
[29594]   251759 ms: Scavenge 28.2 (54.1) -> 17.6 (54.1) MB, 7.4 ms [Runtime::PerformGC].
[29594]   252072 ms: Mark-sweep 33.2 (57.1) -> 11.8 (57.1) MB, 6.0 ms (+ 37.7 ms in 152 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   252383 ms: Scavenge 29.1 (57.1) -> 16.2 (57.1) MB, 5.6 ms [Runtime::PerformGC].
[29594]   252639 ms: Scavenge 30.1 (57.1) -> 19.5 (58.1) MB, 7.2 ms [Runtime::PerformGC].
[29594]   253001 ms: Scavenge 37.0 (62.1) -> 26.5 (64.1) MB, 9.8 ms (+ 45.4 ms in 169 steps since last GC) [allocation failure].
[29594]   253038 ms: Mark-sweep 28.0 (65.1) -> 11.7 (61.1) MB, 6.7 ms (+ 49.7 ms in 184 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   253337 ms: Scavenge 28.8 (61.1) -> 15.8 (61.1) MB, 4.8 ms [Runtime::PerformGC].
[29594]   253588 ms: Scavenge 29.9 (61.1) -> 19.1 (61.1) MB, 7.8 ms [Runtime::PerformGC].
[29594]   253860 ms: Mark-sweep 31.9 (64.1) -> 11.3 (58.1) MB, 7.7 ms (+ 33.1 ms in 134 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   254167 ms: Scavenge 28.3 (58.1) -> 15.2 (58.1) MB, 4.7 ms [allocation failure].
[29594]   254420 ms: Scavenge 29.4 (58.1) -> 18.6 (58.1) MB, 7.1 ms [allocation failure].
[29594]   254589 ms: Mark-sweep 26.7 (59.1) -> 10.6 (55.1) MB, 7.4 ms (+ 18.2 ms in 86 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   254887 ms: Scavenge 27.6 (55.1) -> 14.7 (55.1) MB, 5.1 ms [Runtime::PerformGC].
[29594]   255130 ms: Scavenge 28.7 (55.1) -> 17.4 (56.1) MB, 6.8 ms [Runtime::PerformGC].
[29594]   255252 ms: Mark-sweep 23.1 (57.1) -> 9.9 (54.1) MB, 4.4 ms (+ 12.5 ms in 59 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   255554 ms: Scavenge 26.9 (54.1) -> 14.0 (54.1) MB, 5.8 ms [Runtime::PerformGC].
[29594]   255802 ms: Scavenge 28.0 (54.1) -> 17.3 (54.1) MB, 7.2 ms [Runtime::PerformGC].
[29594]   256150 ms: Scavenge 34.4 (56.1) -> 23.5 (58.1) MB, 8.5 ms (+ 42.7 ms in 162 steps since last GC) [Runtime::PerformGC].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   256255 ms: Mark-sweep 28.5 (59.1) -> 11.6 (59.1) MB, 4.5 ms (+ 56.9 ms in 205 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   256557 ms: Scavenge 28.7 (59.1) -> 15.7 (59.1) MB, 5.3 ms [Runtime::PerformGC].
[29594]   256802 ms: Scavenge 29.7 (59.1) -> 19.1 (59.1) MB, 7.3 ms [Runtime::PerformGC].
[29594]   256938 ms: Mark-sweep 25.6 (59.1) -> 10.7 (54.1) MB, 5.1 ms (+ 14.8 ms in 78 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   257231 ms: Scavenge 27.8 (54.1) -> 14.6 (54.1) MB, 4.6 ms [allocation failure].
[29594]   257480 ms: Scavenge 28.8 (55.1) -> 18.0 (55.1) MB, 7.4 ms [Runtime::PerformGC].
[29594]   257673 ms: Mark-sweep 27.5 (56.1) -> 10.7 (56.1) MB, 4.2 ms (+ 22.3 ms in 93 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   257968 ms: Scavenge 27.7 (56.1) -> 14.8 (56.1) MB, 5.6 ms [Runtime::PerformGC].
[29594]   258211 ms: Scavenge 28.8 (56.1) -> 17.8 (56.1) MB, 6.5 ms [allocation failure].
[29594]   258579 ms: Scavenge 35.8 (58.1) -> 25.3 (59.1) MB, 7.9 ms (+ 48.7 ms in 172 steps since last GC) [allocation failure].
[29594]   258606 ms: Mark-sweep 26.3 (61.1) -> 11.1 (61.1) MB, 4.5 ms (+ 51.9 ms in 183 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   258907 ms: Scavenge 28.1 (61.1) -> 15.2 (61.1) MB, 5.2 ms [allocation failure].
[29594]   259153 ms: Scavenge 29.2 (61.1) -> 18.1 (61.1) MB, 6.4 ms [Runtime::PerformGC].
[29594]   259330 ms: Mark-sweep 26.6 (62.1) -> 10.2 (55.1) MB, 5.6 ms (+ 20.6 ms in 92 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   259628 ms: Scavenge 27.3 (55.1) -> 14.3 (55.1) MB, 5.2 ms [Runtime::PerformGC].
[29594]   259873 ms: Scavenge 28.3 (55.1) -> 17.6 (56.1) MB, 7.7 ms [Runtime::PerformGC].
[29594]   260232 ms: Scavenge 34.9 (59.1) -> 23.9 (60.1) MB, 8.3 ms (+ 43.3 ms in 165 steps since last GC) [allocation failure].
[29594]   260328 ms: Mark-sweep 28.2 (61.1) -> 11.7 (59.1) MB, 5.1 ms (+ 55.1 ms in 203 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   260628 ms: Scavenge 28.7 (59.1) -> 15.8 (59.1) MB, 5.7 ms [Runtime::PerformGC].
[29594]   260879 ms: Scavenge 29.8 (59.1) -> 19.1 (59.1) MB, 8.4 ms [Runtime::PerformGC].
[29594]   261167 ms: Mark-sweep 33.4 (61.1) -> 11.4 (56.1) MB, 7.1 ms (+ 33.9 ms in 138 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   261465 ms: Scavenge 28.5 (56.1) -> 15.5 (56.1) MB, 5.0 ms [Runtime::PerformGC].
[29594]   261708 ms: Scavenge 29.6 (56.1) -> 18.9 (56.1) MB, 7.2 ms [Runtime::PerformGC].
[29594]   261905 ms: Mark-sweep 28.3 (57.1) -> 10.9 (55.1) MB, 8.6 ms (+ 23.7 ms in 94 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[29594]   262198 ms: Scavenge 28.0 (55.1) -> 14.8 (55.1) MB, 4.4 ms [Runtime::PerformGC].
[29594]   262445 ms: Scavenge 29.1 (55.1) -> 18.2 (55.1) MB, 6.9 ms [Runtime::PerformGC].
[29594]   262580 ms: Mark-sweep 24.9 (56.1) -> 10.3 (56.1) MB, 3.5 ms (+ 14.0 ms in 73 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   262878 ms: Scavenge 27.4 (56.1) -> 14.4 (56.1) MB, 4.8 ms [Runtime::PerformGC].
[29594]   263128 ms: Scavenge 28.4 (56.1) -> 17.8 (56.1) MB, 7.9 ms [Runtime::PerformGC].
[29594]   263490 ms: Scavenge 35.5 (58.1) -> 24.9 (58.1) MB, 7.0 ms (+ 47.7 ms in 169 steps since last GC) [Runtime::PerformGC].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   263538 ms: Mark-sweep 27.0 (60.1) -> 11.0 (60.1) MB, 4.4 ms (+ 53.4 ms in 187 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   263843 ms: Scavenge 28.1 (60.1) -> 15.2 (60.1) MB, 5.1 ms [Runtime::PerformGC].
[29594]   264090 ms: Scavenge 29.2 (60.1) -> 18.5 (60.1) MB, 7.7 ms [Runtime::PerformGC].
[29594]   264297 ms: Mark-sweep 28.6 (61.1) -> 11.3 (56.1) MB, 6.8 ms (+ 24.6 ms in 106 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[29594]   264595 ms: Scavenge 28.4 (56.1) -> 15.4 (56.1) MB, 4.9 ms [Runtime::PerformGC].
[29594]   264838 ms: Scavenge 29.5 (56.1) -> 18.8 (56.1) MB, 6.9 ms [Runtime::PerformGC].
[29594]   265141 ms: Mark-sweep 34.1 (59.1) -> 11.5 (59.1) MB, 5.7 ms (+ 34.5 ms in 153 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   265435 ms: Scavenge 28.6 (59.1) -> 15.6 (59.1) MB, 4.8 ms [Runtime::PerformGC].
[29594]   265680 ms: Scavenge 29.7 (59.1) -> 18.9 (59.1) MB, 7.5 ms [allocation failure].
[29594]   265874 ms: Mark-sweep 28.3 (60.1) -> 10.8 (56.1) MB, 6.6 ms (+ 22.6 ms in 94 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   266164 ms: Scavenge 27.9 (56.1) -> 14.6 (56.1) MB, 4.6 ms [Runtime::PerformGC].
[29594]   266415 ms: Scavenge 28.9 (56.1) -> 18.0 (56.1) MB, 7.3 ms [Runtime::PerformGC].
[29594]   266588 ms: Mark-sweep 26.5 (57.1) -> 10.4 (56.1) MB, 4.7 ms (+ 20.2 ms in 84 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   266886 ms: Scavenge 27.5 (56.1) -> 14.5 (56.1) MB, 4.9 ms [allocation failure].
[29594]   267131 ms: Scavenge 28.5 (56.1) -> 17.9 (56.1) MB, 6.9 ms [Runtime::PerformGC].
[29594]   267281 ms: Mark-sweep 25.4 (56.1) -> 10.0 (56.1) MB, 3.4 ms (+ 17.6 ms in 76 steps since start of marking, biggest step 0.7 ms) [StackGuard GC request] [GC in old space requested].
[29594]   267576 ms: Scavenge 27.1 (56.1) -> 14.1 (56.1) MB, 5.0 ms [Runtime::PerformGC].
[29594]   267819 ms: Scavenge 28.1 (56.1) -> 17.4 (56.1) MB, 7.2 ms [Runtime::PerformGC].
[29594]   268061 ms: Mark-sweep 29.6 (57.1) -> 11.3 (56.1) MB, 6.7 ms (+ 27.1 ms in 117 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   268351 ms: Scavenge 28.3 (56.1) -> 15.3 (56.1) MB, 4.8 ms [allocation failure].
[29594]   268598 ms: Scavenge 29.4 (56.1) -> 18.6 (56.1) MB, 7.1 ms [Runtime::PerformGC].
[29594]   268959 ms: Scavenge 36.2 (59.1) -> 25.9 (61.1) MB, 8.6 ms (+ 46.2 ms in 174 steps since last GC) [Runtime::PerformGC].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   269024 ms: Mark-sweep 28.7 (62.1) -> 11.6 (61.1) MB, 5.1 ms (+ 54.2 ms in 199 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   269322 ms: Scavenge 28.7 (61.1) -> 15.6 (61.1) MB, 4.9 ms [Runtime::PerformGC].
[29594]   269570 ms: Scavenge 29.8 (61.1) -> 19.0 (61.1) MB, 7.2 ms [Runtime::PerformGC].
[29594]   269872 ms: Mark-sweep 33.7 (64.1) -> 11.2 (57.1) MB, 9.5 ms (+ 35.8 ms in 155 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   270164 ms: Scavenge 28.2 (57.1) -> 15.3 (57.1) MB, 4.9 ms [Runtime::PerformGC].
[29594]   270407 ms: Scavenge 29.4 (57.1) -> 18.5 (57.1) MB, 7.2 ms [Runtime::PerformGC].
[29594]   270775 ms: Scavenge 36.0 (60.1) -> 25.6 (61.1) MB, 8.0 ms (+ 46.6 ms in 165 steps since last GC) [allocation failure].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   270851 ms: Mark-sweep 29.0 (62.1) -> 11.5 (61.1) MB, 6.0 ms (+ 56.4 ms in 196 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   271148 ms: Scavenge 28.6 (61.1) -> 15.6 (61.1) MB, 5.2 ms [Runtime::PerformGC].
[29594]   271394 ms: Scavenge 29.6 (61.1) -> 18.8 (61.1) MB, 6.9 ms [Runtime::PerformGC].
[29594]   271704 ms: Mark-sweep 33.7 (63.1) -> 11.2 (58.1) MB, 8.1 ms (+ 39.9 ms in 159 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   272004 ms: Scavenge 28.3 (58.1) -> 15.4 (58.1) MB, 5.5 ms [allocation failure].
[29594]   272244 ms: Scavenge 29.4 (58.1) -> 18.2 (58.1) MB, 6.1 ms [Runtime::PerformGC].
[29594]   272450 ms: Mark-sweep 28.1 (59.5) -> 10.5 (57.5) MB, 5.4 ms (+ 24.6 ms in 103 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   272753 ms: Scavenge 27.6 (57.5) -> 14.6 (57.5) MB, 5.8 ms [allocation failure].
[29594]   272997 ms: Scavenge 28.6 (57.5) -> 18.0 (57.5) MB, 7.5 ms [Runtime::PerformGC].
[29594]   273340 ms: Mark-sweep 34.5 (60.5) -> 12.0 (58.5) MB, 8.6 ms (+ 42.9 ms in 161 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   273638 ms: Scavenge 29.1 (58.5) -> 16.2 (58.5) MB, 5.2 ms [Runtime::PerformGC].
[29594]   273883 ms: Scavenge 30.2 (58.5) -> 19.5 (58.5) MB, 7.2 ms [Runtime::PerformGC].
[29594]   274103 ms: Mark-sweep 30.0 (60.5) -> 11.1 (57.1) MB, 6.8 ms (+ 24.6 ms in 108 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[29594]   274397 ms: Scavenge 28.2 (57.1) -> 15.2 (57.1) MB, 4.8 ms [allocation failure].
[29594]   274640 ms: Scavenge 29.2 (57.1) -> 18.5 (57.1) MB, 6.9 ms [Runtime::PerformGC].
[29594]   274993 ms: Scavenge 35.9 (59.1) -> 25.4 (60.1) MB, 8.3 ms (+ 44.5 ms in 167 steps since last GC) [allocation failure].
[29594] Speed up marking because marker was not keeping up
[29594] Marking speed increased to 3
[29594]   275084 ms: Mark-sweep 29.6 (61.1) -> 11.7 (61.1) MB, 5.0 ms (+ 56.2 ms in 204 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[29594]   275376 ms: Scavenge 28.8 (61.1) -> 15.7 (61.1) MB, 4.8 ms [allocation failure].
[29594]   275624 ms: Scavenge 29.9 (61.1) -> 19.1 (61.1) MB, 7.0 ms [Runtime::PerformGC].
[29594]   275920 ms: Mark-sweep 33.3 (64.1) -> 11.2 (57.1) MB, 8.8 ms (+ 37.6 ms in 143 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   276213 ms: Scavenge 28.3 (57.1) -> 15.1 (57.1) MB, 4.5 ms [allocation failure].
[29594]   276468 ms: Scavenge 29.4 (57.1) -> 18.5 (57.1) MB, 8.9 ms [allocation failure].
[29594]   276796 ms: Mark-sweep 33.9 (59.1) -> 11.1 (57.1) MB, 8.2 ms (+ 41.9 ms in 148 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[29594]   277090 ms: Scavenge 28.2 (57.1) -> 15.3 (57.1) MB, 5.0 ms [allocation failure].
[29594]   277331 ms: Scavenge 29.3 (57.1) -> 18.1 (57.1) MB, 6.6 ms [Runtime::PerformGC].

max_new_space_size=65536 (64MB)

$ ./bin/node/bin/node --trace_gc --max_new_space_size=65536 dispatch.js

[30113]       32 ms: Scavenge 1.6 (69.1) -> 1.5 (70.1) MB, 1.4 ms [Runtime::PerformGC].
[30113]       65 ms: Scavenge 2.9 (71.1) -> 2.7 (72.1) MB, 1.3 ms [Runtime::PerformGC].
[30113]       89 ms: Scavenge 3.7 (72.1) -> 3.3 (73.1) MB, 0.8 ms [allocation failure].
[30113]      123 ms: Scavenge 5.7 (73.1) -> 4.7 (74.1) MB, 2.0 ms [Runtime::PerformGC].
[30113]      156 ms: Scavenge 6.6 (75.1) -> 5.6 (75.1) MB, 1.5 ms [Runtime::PerformGC].
[30113]      172 ms: Scavenge 7.2 (75.1) -> 6.4 (75.1) MB, 1.7 ms [Runtime::PerformGC].
[30113]      216 ms: Scavenge 10.0 (75.1) -> 7.9 (77.1) MB, 2.8 ms [allocation failure].
[30113]      258 ms: Scavenge 11.3 (77.1) -> 9.1 (77.1) MB, 2.9 ms [allocation failure].
[30113]      322 ms: Scavenge 12.6 (78.1) -> 10.4 (78.1) MB, 3.5 ms (+ 6.8 ms in 31 steps since last GC) [allocation failure].
[30113]     7079 ms: Mark-sweep 19.4 (83.1) -> 9.5 (83.1) MB, 9.9 ms (+ 29.6 ms in 105 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[30113]     7259 ms: Scavenge 18.4 (83.1) -> 11.9 (83.1) MB, 2.6 ms [Runtime::PerformGC].
[30113]     7392 ms: Scavenge 19.0 (83.1) -> 13.6 (83.1) MB, 4.8 ms [Runtime::PerformGC].
[30113]     7544 ms: Scavenge 20.9 (83.1) -> 15.4 (84.1) MB, 4.5 ms (+ 14.5 ms in 71 steps since last GC) [Runtime::PerformGC].
[30113]     7714 ms: Scavenge 23.6 (85.1) -> 18.6 (87.1) MB, 6.0 ms (+ 23.2 ms in 79 steps since last GC) [Runtime::PerformGC].
[30113]     7818 ms: Mark-sweep 23.4 (88.1) -> 11.2 (88.1) MB, 5.0 ms (+ 52.4 ms in 199 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]     8127 ms: Scavenge 28.1 (88.1) -> 15.2 (88.1) MB, 5.4 ms [Runtime::PerformGC].
[30113]     8378 ms: Scavenge 29.0 (88.1) -> 18.3 (88.1) MB, 7.1 ms [Runtime::PerformGC].
[30113]     8568 ms: Mark-sweep 27.4 (88.1) -> 10.3 (86.1) MB, 5.6 ms (+ 24.7 ms in 98 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]     8861 ms: Scavenge 27.1 (86.1) -> 14.3 (86.1) MB, 5.5 ms [allocation failure].
[30113]     9099 ms: Scavenge 28.0 (86.1) -> 17.4 (86.1) MB, 7.4 ms [allocation failure].
[30113]     9416 ms: Scavenge 33.0 (88.1) -> 22.3 (89.1) MB, 9.3 ms (+ 41.6 ms in 154 steps since last GC) [Runtime::PerformGC].
[30113]     9505 ms: Mark-sweep 26.3 (89.1) -> 10.7 (88.1) MB, 6.7 ms (+ 53.6 ms in 193 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    10124 ms: Scavenge 44.4 (88.1) -> 18.7 (88.1) MB, 11.8 ms (+ 2.5 ms in 12 steps since last GC) [Runtime::PerformGC].
[30113]    10294 ms: Mark-sweep 27.2 (88.1) -> 10.2 (88.1) MB, 6.2 ms (+ 25.1 ms in 101 steps since start of marking, biggest step 0.7 ms) [StackGuard GC request] [GC in old space requested].
[30113]    10877 ms: Scavenge 43.8 (88.1) -> 18.1 (88.1) MB, 11.7 ms (+ 5.7 ms in 30 steps since last GC) [Runtime::PerformGC].
[30113]    10980 ms: Mark-sweep 22.9 (88.1) -> 9.7 (88.1) MB, 5.0 ms (+ 17.7 ms in 81 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    11547 ms: Mark-sweep 42.1 (88.1) -> 9.1 (88.1) MB, 5.4 ms (+ 12.5 ms in 59 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    12170 ms: Scavenge 42.9 (88.1) -> 17.3 (88.1) MB, 11.5 ms (+ 15.0 ms in 77 steps since last GC) [allocation failure].
[30113]    12216 ms: Mark-sweep 19.4 (88.1) -> 10.1 (88.1) MB, 4.5 ms (+ 21.2 ms in 98 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    12802 ms: Scavenge 43.8 (88.1) -> 18.1 (88.1) MB, 11.2 ms (+ 7.1 ms in 35 steps since last GC) [Runtime::PerformGC].
[30113]    12916 ms: Mark-sweep 23.6 (88.1) -> 9.7 (88.1) MB, 5.4 ms (+ 22.1 ms in 94 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    13526 ms: Scavenge 43.6 (88.1) -> 17.8 (88.1) MB, 10.6 ms (+ 16.9 ms in 70 steps since last GC) [Runtime::PerformGC].
[30113]    13580 ms: Mark-sweep 20.1 (88.1) -> 9.3 (88.1) MB, 4.2 ms (+ 24.0 ms in 95 steps since start of marking, biggest step 0.3 ms) [StackGuard GC request] [GC in old space requested].
[30113]    14176 ms: Scavenge 43.0 (88.1) -> 17.3 (88.1) MB, 12.4 ms (+ 13.7 ms in 68 steps since last GC) [Runtime::PerformGC].
[30113]    14188 ms: Mark-sweep 17.8 (88.1) -> 10.0 (88.1) MB, 4.4 ms (+ 14.8 ms in 72 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    14792 ms: Scavenge 43.8 (88.1) -> 18.1 (88.1) MB, 12.2 ms (+ 13.1 ms in 63 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]    14985 ms: Mark-sweep 27.2 (88.1) -> 10.3 (88.1) MB, 6.6 ms (+ 41.5 ms in 159 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    15578 ms: Scavenge 43.9 (88.1) -> 18.0 (88.1) MB, 10.7 ms (+ 10.3 ms in 52 steps since last GC) [Runtime::PerformGC].
[30113]    15752 ms: Mark-sweep 26.1 (88.1) -> 9.9 (88.1) MB, 5.7 ms (+ 37.2 ms in 147 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]    16348 ms: Scavenge 43.7 (88.1) -> 17.6 (88.1) MB, 9.5 ms (+ 15.3 ms in 65 steps since last GC) [allocation failure].
[30113]    16386 ms: Mark-sweep 19.3 (88.1) -> 9.0 (88.1) MB, 3.4 ms (+ 20.9 ms in 84 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    16983 ms: Scavenge 42.9 (88.1) -> 17.3 (88.1) MB, 11.8 ms (+ 17.3 ms in 82 steps since last GC) [allocation failure].
[30113] Hurrying incremental marking because of lack of progress
[30113]    17127 ms: Mark-sweep 24.0 (88.1) -> 10.4 (88.1) MB, 6.5 ms (+ 39.5 ms in 158 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    17721 ms: Scavenge 44.0 (88.1) -> 18.3 (88.1) MB, 11.9 ms (+ 9.6 ms in 53 steps since last GC) [Runtime::PerformGC].
[30113]    17805 ms: Mark-sweep 22.2 (88.1) -> 9.5 (88.1) MB, 4.8 ms (+ 20.7 ms in 97 steps since start of marking, biggest step 0.3 ms) [StackGuard GC request] [GC in old space requested].
[30113]    18411 ms: Scavenge 43.6 (88.1) -> 17.9 (88.1) MB, 10.7 ms (+ 19.6 ms in 86 steps since last GC) [Runtime::PerformGC].
[30113]    18434 ms: Mark-sweep 18.8 (88.1) -> 9.0 (88.1) MB, 3.4 ms (+ 22.2 ms in 95 steps since start of marking, biggest step 0.3 ms) [StackGuard GC request] [GC in old space requested].
[30113]    19036 ms: Scavenge 42.9 (88.1) -> 17.3 (88.1) MB, 11.5 ms (+ 16.8 ms in 77 steps since last GC) [allocation failure].
[30113]    19065 ms: Mark-sweep 18.5 (88.1) -> 9.8 (88.1) MB, 4.5 ms (+ 20.8 ms in 91 steps since start of marking, biggest step 0.3 ms) [StackGuard GC request] [GC in old space requested].
[30113]    19673 ms: Scavenge 43.5 (88.1) -> 17.9 (88.1) MB, 11.8 ms (+ 14.7 ms in 69 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]    19852 ms: Mark-sweep 26.3 (88.1) -> 10.1 (88.1) MB, 6.4 ms (+ 41.7 ms in 160 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    20446 ms: Mark-sweep 43.5 (88.1) -> 8.9 (88.1) MB, 4.7 ms (+ 11.5 ms in 61 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    21053 ms: Scavenge 42.8 (88.1) -> 17.3 (88.1) MB, 11.7 ms (+ 17.0 ms in 81 steps since last GC) [Runtime::PerformGC].
[30113]    21195 ms: Mark-sweep 24.1 (88.1) -> 10.5 (88.1) MB, 5.9 ms (+ 37.8 ms in 155 steps since start of marking, biggest step 0.3 ms) [StackGuard GC request] [GC in old space requested].
[30113]    21789 ms: Scavenge 44.1 (88.1) -> 18.3 (88.1) MB, 11.3 ms (+ 8.6 ms in 45 steps since last GC) [Runtime::PerformGC].
[30113]    21899 ms: Mark-sweep 23.5 (88.1) -> 9.7 (88.1) MB, 5.5 ms (+ 23.1 ms in 99 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    22456 ms: Mark-sweep 41.7 (88.1) -> 8.9 (88.1) MB, 4.9 ms (+ 11.6 ms in 54 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    23059 ms: Scavenge 42.8 (88.1) -> 17.2 (88.1) MB, 11.5 ms (+ 16.3 ms in 86 steps since last GC) [Runtime::PerformGC].
[30113]    23103 ms: Mark-sweep 19.1 (88.1) -> 9.9 (88.1) MB, 4.3 ms (+ 21.5 ms in 105 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    23688 ms: Mark-sweep 43.4 (88.1) -> 9.3 (88.1) MB, 5.7 ms (+ 13.1 ms in 63 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    24283 ms: Scavenge 43.0 (88.1) -> 16.8 (88.1) MB, 9.7 ms (+ 13.3 ms in 57 steps since last GC) [Runtime::PerformGC].
[30113]    24376 ms: Mark-sweep 21.0 (88.1) -> 9.1 (88.1) MB, 3.9 ms (+ 27.2 ms in 102 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]    24981 ms: Scavenge 42.9 (88.1) -> 17.3 (88.1) MB, 11.3 ms (+ 14.9 ms in 77 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]    25138 ms: Mark-sweep 24.7 (88.1) -> 10.4 (88.1) MB, 6.1 ms (+ 39.5 ms in 162 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    25735 ms: Scavenge 44.0 (88.1) -> 18.3 (88.1) MB, 11.6 ms (+ 9.4 ms in 48 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]    25956 ms: Mark-sweep 28.7 (88.1) -> 10.2 (88.1) MB, 6.4 ms (+ 42.8 ms in 164 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]    26547 ms: Scavenge 43.9 (88.1) -> 17.9 (88.1) MB, 10.8 ms (+ 10.4 ms in 48 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]    26759 ms: Mark-sweep 28.0 (88.1) -> 10.0 (88.1) MB, 6.0 ms (+ 42.7 ms in 159 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]    27361 ms: Scavenge 43.7 (88.1) -> 17.6 (88.1) MB, 9.8 ms (+ 13.1 ms in 66 steps since last GC) [allocation failure].
[30113]    27479 ms: Mark-sweep 23.0 (88.1) -> 9.3 (88.1) MB, 4.1 ms (+ 30.6 ms in 125 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    28090 ms: Scavenge 42.9 (88.1) -> 17.3 (88.1) MB, 11.9 ms (+ 12.9 ms in 69 steps since last GC) [allocation failure].
[30113]    28218 ms: Mark-sweep 23.1 (88.1) -> 10.2 (88.1) MB, 5.7 ms (+ 32.5 ms in 134 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    28813 ms: Scavenge 43.9 (88.1) -> 18.1 (88.1) MB, 11.5 ms (+ 9.2 ms in 50 steps since last GC) [Runtime::PerformGC].
[30113]    28951 ms: Mark-sweep 24.8 (88.1) -> 9.9 (88.1) MB, 5.6 ms (+ 29.8 ms in 125 steps since start of marking, biggest step 0.3 ms) [StackGuard GC request] [GC in old space requested].
[30113]    29549 ms: Scavenge 43.6 (88.1) -> 17.7 (88.1) MB, 10.9 ms (+ 12.6 ms in 62 steps since last GC) [allocation failure].
[30113] Hurrying incremental marking because of lack of progress
[30113]    29742 ms: Mark-sweep 26.5 (88.1) -> 9.9 (88.1) MB, 5.8 ms (+ 41.6 ms in 158 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]    30351 ms: Scavenge 43.6 (88.1) -> 17.5 (88.1) MB, 10.1 ms (+ 8.4 ms in 47 steps since last GC) [Runtime::PerformGC].
[30113]    30559 ms: Mark-sweep 27.5 (88.1) -> 9.7 (88.1) MB, 5.4 ms (+ 38.6 ms in 147 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    31167 ms: Scavenge 43.4 (88.1) -> 17.8 (88.1) MB, 11.6 ms (+ 12.6 ms in 74 steps since last GC) [Runtime::PerformGC].
[30113]    31366 ms: Mark-sweep 27.3 (88.1) -> 10.8 (88.1) MB, 7.1 ms (+ 42.9 ms in 180 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    31980 ms: Scavenge 44.7 (88.1) -> 19.0 (88.1) MB, 11.1 ms (+ 7.7 ms in 41 steps since last GC) [Runtime::PerformGC].
[30113]    32145 ms: Mark-sweep 26.9 (88.1) -> 10.5 (88.1) MB, 6.3 ms (+ 30.6 ms in 130 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    32747 ms: Scavenge 44.1 (88.1) -> 18.3 (88.1) MB, 11.5 ms (+ 9.1 ms in 50 steps since last GC) [Runtime::PerformGC].
[30113]    32810 ms: Mark-sweep 21.3 (88.1) -> 9.8 (88.1) MB, 4.5 ms (+ 16.7 ms in 81 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    33419 ms: Scavenge 43.7 (88.1) -> 17.8 (88.1) MB, 10.3 ms (+ 15.5 ms in 83 steps since last GC) [allocation failure].
[30113]    33496 ms: Mark-sweep 21.4 (88.1) -> 9.5 (88.1) MB, 4.1 ms (+ 26.9 ms in 122 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    34093 ms: Scavenge 43.2 (88.1) -> 17.5 (88.1) MB, 11.2 ms (+ 11.1 ms in 66 steps since last GC) [Runtime::PerformGC].
[30113]    34279 ms: Mark-sweep 26.8 (88.1) -> 10.9 (88.1) MB, 6.0 ms (+ 36.0 ms in 166 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    34874 ms: Scavenge 44.5 (88.1) -> 18.9 (88.1) MB, 11.8 ms (+ 7.3 ms in 43 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]    35179 ms: Mark-sweep 33.9 (89.1) -> 11.0 (89.1) MB, 8.3 ms (+ 48.3 ms in 206 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]    35773 ms: Scavenge 44.6 (89.1) -> 18.9 (89.1) MB, 11.1 ms (+ 5.4 ms in 35 steps since last GC) [Runtime::PerformGC].
[30113]    35940 ms: Mark-sweep 27.1 (89.1) -> 10.1 (89.1) MB, 5.6 ms (+ 27.1 ms in 133 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    36532 ms: Scavenge 43.8 (89.1) -> 17.8 (89.1) MB, 10.2 ms (+ 11.6 ms in 65 steps since last GC) [Runtime::PerformGC].
[30113]    36617 ms: Mark-sweep 21.8 (89.1) -> 9.5 (89.1) MB, 4.1 ms (+ 23.0 ms in 109 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    37230 ms: Scavenge 43.1 (89.1) -> 17.5 (89.1) MB, 11.6 ms (+ 12.1 ms in 68 steps since last GC) [Runtime::PerformGC].
[30113]    37250 ms: Mark-sweep 18.2 (89.1) -> 10.0 (89.1) MB, 4.8 ms (+ 13.9 ms in 78 steps since start of marking, biggest step 0.3 ms) [StackGuard GC request] [GC in old space requested].
[30113]    37850 ms: Scavenge 43.7 (89.1) -> 17.9 (89.1) MB, 11.8 ms (+ 11.6 ms in 59 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]    38084 ms: Mark-sweep 29.5 (89.1) -> 10.7 (89.1) MB, 6.9 ms (+ 44.2 ms in 181 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    38680 ms: Scavenge 44.3 (89.1) -> 18.4 (89.1) MB, 10.9 ms (+ 7.8 ms in 44 steps since last GC) [allocation failure].
[30113] Hurrying incremental marking because of lack of progress
[30113]    38969 ms: Mark-sweep 32.7 (89.1) -> 10.7 (89.1) MB, 7.2 ms (+ 46.8 ms in 201 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    39552 ms: Scavenge 44.3 (89.1) -> 18.4 (89.1) MB, 11.0 ms (+ 7.6 ms in 46 steps since last GC) [allocation failure].
[30113]    39628 ms: Mark-sweep 22.0 (89.1) -> 9.4 (89.1) MB, 3.9 ms (+ 17.0 ms in 87 steps since start of marking, biggest step 0.3 ms) [StackGuard GC request] [GC in old space requested].
[30113]    40229 ms: Scavenge 43.0 (89.1) -> 17.4 (89.1) MB, 11.2 ms (+ 12.1 ms in 69 steps since last GC) [Runtime::PerformGC].
[30113]    40385 ms: Mark-sweep 25.2 (89.1) -> 10.7 (89.1) MB, 5.8 ms (+ 32.7 ms in 155 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    40968 ms: Scavenge 44.4 (89.1) -> 18.7 (89.1) MB, 11.6 ms (+ 1.5 ms in 7 steps since last GC) [Runtime::PerformGC].
[30113]    41213 ms: Mark-sweep 31.0 (89.1) -> 10.6 (89.1) MB, 6.5 ms (+ 31.6 ms in 150 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    41811 ms: Scavenge 44.2 (89.1) -> 18.5 (89.1) MB, 11.4 ms (+ 9.1 ms in 51 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]    42092 ms: Mark-sweep 32.5 (89.1) -> 10.7 (89.1) MB, 7.2 ms (+ 47.4 ms in 201 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    42688 ms: Scavenge 44.3 (89.1) -> 18.4 (89.1) MB, 11.7 ms (+ 7.9 ms in 40 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]    42970 ms: Mark-sweep 32.3 (89.1) -> 10.6 (89.1) MB, 7.3 ms (+ 46.5 ms in 188 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    43567 ms: Scavenge 44.2 (89.1) -> 18.3 (89.1) MB, 10.8 ms (+ 8.9 ms in 52 steps since last GC) [Runtime::PerformGC].
[30113]    43714 ms: Mark-sweep 25.5 (89.1) -> 9.8 (89.1) MB, 5.0 ms (+ 29.2 ms in 132 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]    44312 ms: Scavenge 43.4 (89.1) -> 17.7 (89.1) MB, 11.6 ms (+ 10.5 ms in 55 steps since last GC) [allocation failure].
[30113]    44497 ms: Mark-sweep 26.5 (89.1) -> 10.9 (89.1) MB, 6.2 ms (+ 37.1 ms in 152 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[30113]    45082 ms: Scavenge 44.5 (89.1) -> 18.8 (89.1) MB, 11.6 ms (+ 2.0 ms in 13 steps since last GC) [Runtime::PerformGC].
[30113]    45320 ms: Mark-sweep 30.5 (89.1) -> 10.6 (89.1) MB, 6.6 ms (+ 31.2 ms in 142 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    45911 ms: Scavenge 44.2 (89.1) -> 18.5 (89.1) MB, 11.4 ms (+ 8.4 ms in 51 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]    46203 ms: Mark-sweep 32.7 (89.1) -> 10.9 (89.1) MB, 8.1 ms (+ 47.2 ms in 201 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    46796 ms: Scavenge 44.5 (89.1) -> 18.8 (89.1) MB, 11.9 ms (+ 6.4 ms in 36 steps since last GC) [Runtime::PerformGC].
[30113]    46859 ms: Mark-sweep 21.7 (89.1) -> 9.5 (89.1) MB, 4.3 ms (+ 12.7 ms in 67 steps since start of marking, biggest step 0.3 ms) [StackGuard GC request] [GC in old space requested].
[30113]    47456 ms: Scavenge 43.2 (89.1) -> 17.3 (89.1) MB, 10.2 ms (+ 12.3 ms in 67 steps since last GC) [allocation failure].
[30113]    47464 ms: Mark-sweep 17.5 (89.1) -> 9.0 (89.1) MB, 2.9 ms (+ 13.0 ms in 70 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    48073 ms: Scavenge 42.9 (89.1) -> 17.3 (89.1) MB, 11.5 ms (+ 16.2 ms in 93 steps since last GC) [Runtime::PerformGC].
[30113]    48172 ms: Mark-sweep 21.9 (89.1) -> 10.3 (89.1) MB, 5.6 ms (+ 30.3 ms in 146 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    48770 ms: Scavenge 44.0 (89.1) -> 18.2 (89.1) MB, 11.6 ms (+ 10.9 ms in 62 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]    49019 ms: Mark-sweep 30.3 (89.1) -> 10.6 (89.1) MB, 6.9 ms (+ 46.0 ms in 189 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]    49612 ms: Scavenge 44.2 (89.1) -> 18.3 (89.1) MB, 10.6 ms (+ 8.6 ms in 51 steps since last GC) [Runtime::PerformGC].
[30113]    49687 ms: Mark-sweep 22.0 (89.1) -> 9.4 (89.1) MB, 4.1 ms (+ 18.5 ms in 96 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    50285 ms: Scavenge 43.1 (89.1) -> 17.4 (89.1) MB, 11.5 ms (+ 12.1 ms in 67 steps since last GC) [Runtime::PerformGC].
[30113]    50441 ms: Mark-sweep 24.7 (89.1) -> 10.7 (89.1) MB, 6.0 ms (+ 33.4 ms in 149 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    51034 ms: Scavenge 44.4 (89.1) -> 18.7 (89.1) MB, 11.5 ms (+ 3.3 ms in 17 steps since last GC) [allocation failure].
[30113] Hurrying incremental marking because of lack of progress
[30113]    51393 ms: Mark-sweep 36.7 (89.1) -> 11.1 (89.1) MB, 8.0 ms (+ 48.1 ms in 205 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    51979 ms: Scavenge 44.8 (89.1) -> 19.1 (89.1) MB, 11.6 ms (+ 0.3 ms in 2 steps since last GC) [Runtime::PerformGC].
[30113]    52293 ms: Mark-sweep 34.7 (89.1) -> 10.8 (89.1) MB, 7.8 ms (+ 40.6 ms in 179 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]    52882 ms: Scavenge 44.4 (89.1) -> 18.7 (89.1) MB, 11.7 ms (+ 6.9 ms in 42 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]    53163 ms: Mark-sweep 32.7 (89.1) -> 10.8 (89.1) MB, 7.1 ms (+ 45.8 ms in 195 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    53751 ms: Scavenge 44.4 (89.1) -> 18.5 (89.1) MB, 11.2 ms (+ 7.6 ms in 43 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]    54035 ms: Mark-sweep 32.5 (89.1) -> 10.6 (89.1) MB, 6.9 ms (+ 46.2 ms in 199 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    54628 ms: Scavenge 44.2 (89.1) -> 18.3 (89.1) MB, 10.8 ms (+ 8.2 ms in 46 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]    54861 ms: Mark-sweep 29.7 (89.1) -> 10.2 (89.1) MB, 6.3 ms (+ 43.3 ms in 173 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    55454 ms: Scavenge 43.8 (89.1) -> 17.6 (89.1) MB, 10.0 ms (+ 8.4 ms in 50 steps since last GC) [allocation failure].
[30113] Hurrying incremental marking because of lack of progress
[30113]    55693 ms: Mark-sweep 29.3 (89.1) -> 10.1 (89.1) MB, 5.9 ms (+ 44.4 ms in 186 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    56290 ms: Scavenge 43.8 (89.1) -> 18.1 (89.1) MB, 11.7 ms (+ 7.2 ms in 44 steps since last GC) [allocation failure].
[30113]    56379 ms: Mark-sweep 22.3 (89.1) -> 10.5 (89.1) MB, 5.0 ms (+ 17.2 ms in 98 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    56978 ms: Scavenge 44.1 (89.1) -> 18.4 (89.1) MB, 12.0 ms (+ 9.3 ms in 51 steps since last GC) [Runtime::PerformGC].
[30113]    57093 ms: Mark-sweep 24.0 (89.1) -> 10.1 (89.1) MB, 5.3 ms (+ 23.5 ms in 111 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    57689 ms: Scavenge 43.8 (89.1) -> 17.9 (89.1) MB, 11.5 ms (+ 12.8 ms in 69 steps since last GC) [allocation failure].
[30113] Hurrying incremental marking because of lack of progress
[30113]    57908 ms: Mark-sweep 28.6 (89.1) -> 10.4 (89.1) MB, 6.1 ms (+ 43.6 ms in 182 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    58505 ms: Scavenge 44.0 (89.1) -> 17.8 (89.1) MB, 9.9 ms (+ 10.2 ms in 61 steps since last GC) [allocation failure].
[30113] Hurrying incremental marking because of lack of progress
[30113]    58722 ms: Mark-sweep 28.6 (89.1) -> 10.1 (89.1) MB, 5.7 ms (+ 43.6 ms in 182 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    59313 ms: Scavenge 43.7 (89.1) -> 18.1 (89.1) MB, 11.6 ms (+ 7.6 ms in 46 steps since last GC) [Runtime::PerformGC].
[30113]    59467 ms: Mark-sweep 25.7 (89.1) -> 10.6 (89.1) MB, 6.0 ms (+ 26.8 ms in 138 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]    60058 ms: Scavenge 44.3 (89.1) -> 18.6 (89.1) MB, 11.4 ms (+ 4.3 ms in 22 steps since last GC) [Runtime::PerformGC].
[30113]    60409 ms: Mark-sweep 36.1 (89.1) -> 11.7 (89.1) MB, 8.1 ms (+ 47.9 ms in 203 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]    61006 ms: Scavenge 45.5 (89.1) -> 19.7 (89.1) MB, 11.4 ms (+ 2.5 ms in 15 steps since last GC) [allocation failure].
[30113]    61228 ms: Mark-sweep 30.7 (89.1) -> 10.8 (89.1) MB, 6.6 ms (+ 32.1 ms in 150 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    61831 ms: Scavenge 44.6 (89.1) -> 18.9 (89.1) MB, 11.6 ms (+ 10.6 ms in 63 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]    62097 ms: Mark-sweep 31.8 (89.1) -> 11.1 (89.1) MB, 7.2 ms (+ 48.5 ms in 204 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    62676 ms: Scavenge 44.7 (89.1) -> 18.7 (89.1) MB, 10.9 ms (+ 4.5 ms in 28 steps since last GC) [allocation failure].
[30113]    62822 ms: Mark-sweep 25.7 (89.1) -> 10.0 (89.1) MB, 5.1 ms (+ 23.9 ms in 113 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].

[30113]    63426 ms: Scavenge 43.8 (89.1) -> 18.2 (89.1) MB, 12.0 ms (+ 8.3 ms in 49 steps since last GC) [Runtime::PerformGC].
[30113]    63581 ms: Mark-sweep 25.3 (89.1) -> 11.1 (89.1) MB, 5.6 ms (+ 28.7 ms in 125 steps since start of marking, biggest step 0.7 ms) [StackGuard GC request] [GC in old space requested].
[30113]    64213 ms: Scavenge 44.8 (89.1) -> 19.0 (89.1) MB, 11.5 ms (+ 1.5 ms in 9 steps since last GC) [Runtime::PerformGC].
[30113]    64440 ms: Mark-sweep 29.4 (89.1) -> 11.0 (89.1) MB, 7.1 ms (+ 27.6 ms in 136 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]    65074 ms: Scavenge 44.7 (89.1) -> 18.9 (89.1) MB, 11.4 ms (+ 5.5 ms in 32 steps since last GC) [Runtime::PerformGC].
[30113]    65208 ms: Mark-sweep 25.1 (89.1) -> 10.4 (89.1) MB, 5.5 ms (+ 20.6 ms in 105 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    65843 ms: Scavenge 44.1 (89.1) -> 18.1 (89.1) MB, 11.7 ms (+ 9.5 ms in 57 steps since last GC) [Runtime::PerformGC].
[30113]    65978 ms: Mark-sweep 24.2 (89.1) -> 10.2 (89.1) MB, 5.1 ms (+ 26.2 ms in 124 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    66603 ms: Scavenge 43.9 (89.1) -> 17.7 (89.1) MB, 9.9 ms (+ 11.2 ms in 69 steps since last GC) [Runtime::PerformGC].
[30113]    66751 ms: Mark-sweep 24.5 (89.1) -> 10.0 (89.1) MB, 4.9 ms (+ 33.6 ms in 144 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]    67389 ms: Scavenge 43.7 (89.1) -> 17.9 (89.1) MB, 13.0 ms (+ 7.9 ms in 46 steps since last GC) [Runtime::PerformGC].
[30113]    67464 ms: Mark-sweep 21.2 (89.1) -> 10.6 (89.1) MB, 4.6 ms (+ 16.0 ms in 88 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]    68080 ms: Scavenge 44.2 (89.1) -> 18.4 (89.1) MB, 11.2 ms (+ 6.3 ms in 33 steps since last GC) [allocation failure].
[30113]    68243 ms: Mark-sweep 26.1 (89.1) -> 10.5 (89.1) MB, 5.9 ms (+ 25.8 ms in 113 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    68864 ms: Scavenge 44.1 (89.1) -> 18.0 (89.1) MB, 10.4 ms (+ 7.6 ms in 43 steps since last GC) [Runtime::PerformGC].
[30113]    68925 ms: Mark-sweep 20.8 (89.1) -> 9.8 (89.1) MB, 3.9 ms (+ 14.0 ms in 81 steps since start of marking, biggest step 0.3 ms) [StackGuard GC request] [GC in old space requested].
[30113]    69537 ms: Scavenge 43.4 (89.1) -> 17.0 (89.1) MB, 9.6 ms (+ 8.0 ms in 44 steps since last GC) [Runtime::PerformGC].
[30113]    69664 ms: Mark-sweep 23.2 (89.1) -> 9.8 (89.1) MB, 4.0 ms (+ 26.1 ms in 116 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    70281 ms: Scavenge 43.4 (89.1) -> 17.5 (89.1) MB, 11.9 ms (+ 7.5 ms in 47 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]    70561 ms: Mark-sweep 30.7 (89.1) -> 11.4 (89.1) MB, 7.2 ms (+ 46.5 ms in 195 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]    71170 ms: Scavenge 45.0 (89.1) -> 19.0 (89.1) MB, 10.4 ms [Runtime::PerformGC].
[30113]    71688 ms: Mark-sweep 47.3 (89.1) -> 10.1 (89.1) MB, 5.9 ms (+ 33.9 ms in 127 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[30113]    72268 ms: Scavenge 43.7 (89.1) -> 17.8 (89.1) MB, 11.2 ms (+ 5.7 ms in 33 steps since last GC) [Runtime::PerformGC].
[30113]    72502 ms: Mark-sweep 29.5 (89.1) -> 11.3 (90.1) MB, 7.0 ms (+ 38.1 ms in 170 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    73079 ms: Scavenge 44.9 (90.1) -> 19.1 (90.1) MB, 12.2 ms (+ 1.4 ms in 9 steps since last GC) [Runtime::PerformGC].
[30113]    73325 ms: Mark-sweep 31.0 (90.1) -> 10.8 (90.1) MB, 6.8 ms (+ 34.7 ms in 152 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]    73899 ms: Scavenge 44.4 (90.1) -> 18.5 (90.1) MB, 10.8 ms (+ 5.0 ms in 32 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]    74183 ms: Mark-sweep 32.7 (90.1) -> 11.0 (90.1) MB, 7.0 ms (+ 47.1 ms in 196 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    74762 ms: Scavenge 44.6 (90.1) -> 18.5 (90.1) MB, 11.3 ms (+ 4.1 ms in 22 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]    75074 ms: Mark-sweep 34.0 (90.1) -> 10.8 (90.1) MB, 7.1 ms (+ 49.5 ms in 198 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]    75649 ms: Scavenge 44.4 (90.1) -> 18.1 (90.1) MB, 9.8 ms (+ 5.1 ms in 29 steps since last GC) [Runtime::PerformGC].
[30113]    75771 ms: Mark-sweep 24.0 (90.1) -> 9.8 (90.1) MB, 4.5 ms (+ 21.8 ms in 103 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    76356 ms: Scavenge 43.3 (90.1) -> 17.5 (90.1) MB, 11.2 ms (+ 7.9 ms in 51 steps since last GC) [allocation failure].
[30113]    76532 ms: Mark-sweep 26.1 (90.1) -> 11.2 (90.1) MB, 6.6 ms (+ 31.9 ms in 148 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    77107 ms: Scavenge 44.8 (90.1) -> 18.9 (90.1) MB, 11.1 ms (+ 3.0 ms in 17 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]    77454 ms: Mark-sweep 35.9 (90.1) -> 11.3 (90.1) MB, 7.5 ms (+ 51.5 ms in 212 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]    78025 ms: Scavenge 44.9 (90.1) -> 19.0 (90.1) MB, 11.1 ms (+ 1.0 ms in 7 steps since last GC) [Runtime::PerformGC].
[30113]    78216 ms: Mark-sweep 28.4 (90.1) -> 10.4 (90.1) MB, 5.8 ms (+ 25.5 ms in 123 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    78801 ms: Scavenge 44.0 (90.1) -> 17.9 (90.1) MB, 10.2 ms (+ 8.7 ms in 52 steps since last GC) [allocation failure].
[30113] Hurrying incremental marking because of lack of progress
[30113]    79054 ms: Mark-sweep 30.6 (90.1) -> 10.8 (90.1) MB, 6.4 ms (+ 46.3 ms in 189 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]    79629 ms: Scavenge 44.3 (90.1) -> 18.0 (90.1) MB, 10.4 ms (+ 5.4 ms in 33 steps since last GC) [Runtime::PerformGC].
[30113]    79795 ms: Mark-sweep 26.0 (90.1) -> 10.0 (90.1) MB, 4.6 ms (+ 29.4 ms in 130 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    80381 ms: Scavenge 43.6 (90.1) -> 17.7 (90.1) MB, 12.3 ms (+ 7.7 ms in 41 steps since last GC) [allocation failure].
[30113]    80561 ms: Mark-sweep 26.9 (90.1) -> 11.1 (90.1) MB, 5.9 ms (+ 31.7 ms in 144 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    81141 ms: Scavenge 44.7 (90.1) -> 18.8 (90.1) MB, 11.4 ms (+ 2.9 ms in 17 steps since last GC) [allocation failure].
[30113] Hurrying incremental marking because of lack of progress
[30113]    81478 ms: Mark-sweep 35.7 (90.1) -> 11.3 (90.1) MB, 7.7 ms (+ 49.3 ms in 205 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    82044 ms: Scavenge 44.9 (90.1) -> 19.0 (90.1) MB, 11.1 ms (+ 1.5 ms in 8 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]    82386 ms: Mark-sweep 36.1 (90.1) -> 11.3 (90.1) MB, 7.8 ms (+ 51.1 ms in 210 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    82955 ms: Scavenge 44.8 (90.1) -> 18.8 (90.1) MB, 10.7 ms (+ 1.6 ms in 9 steps since last GC) [Runtime::PerformGC].
[30113]    83143 ms: Mark-sweep 28.3 (90.1) -> 10.3 (90.1) MB, 5.5 ms (+ 27.5 ms in 125 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    83727 ms: Scavenge 43.9 (90.1) -> 17.8 (90.1) MB, 10.4 ms (+ 9.1 ms in 55 steps since last GC) [allocation failure].
[30113]    83818 ms: Mark-sweep 22.1 (90.1) -> 9.8 (90.1) MB, 4.1 ms (+ 22.7 ms in 104 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    84408 ms: Scavenge 43.4 (90.1) -> 17.5 (90.1) MB, 11.5 ms (+ 8.2 ms in 49 steps since last GC) [Runtime::PerformGC].
[30113]    84581 ms: Mark-sweep 26.1 (90.1) -> 10.9 (90.1) MB, 6.0 ms (+ 31.2 ms in 149 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    85157 ms: Scavenge 44.5 (90.1) -> 18.6 (90.1) MB, 11.2 ms (+ 3.9 ms in 22 steps since last GC) [allocation failure].
[30113] Hurrying incremental marking because of lack of progress
[30113]    85489 ms: Mark-sweep 35.2 (90.1) -> 11.4 (90.1) MB, 7.7 ms (+ 50.7 ms in 214 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]    86062 ms: Scavenge 44.9 (90.1) -> 19.0 (90.1) MB, 11.0 ms (+ 0.9 ms in 5 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]    86413 ms: Mark-sweep 36.4 (90.1) -> 11.3 (90.1) MB, 7.6 ms (+ 50.9 ms in 205 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]    86983 ms: Scavenge 44.8 (90.1) -> 18.8 (90.1) MB, 10.6 ms (+ 1.4 ms in 8 steps since last GC) [Runtime::PerformGC].
[30113]    87191 ms: Mark-sweep 28.9 (90.1) -> 10.4 (90.1) MB, 5.7 ms (+ 29.1 ms in 129 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]    87774 ms: Scavenge 44.0 (90.1) -> 17.9 (90.1) MB, 10.5 ms (+ 8.6 ms in 53 steps since last GC) [allocation failure].
[30113]    87987 ms: Mark-sweep 28.6 (90.1) -> 10.5 (90.1) MB, 5.8 ms (+ 39.9 ms in 171 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    88588 ms: Scavenge 44.1 (90.1) -> 18.2 (90.1) MB, 11.6 ms (+ 3.4 ms in 20 steps since last GC) [allocation failure].
[30113]    88743 ms: Mark-sweep 25.9 (90.1) -> 10.8 (90.1) MB, 5.6 ms (+ 23.2 ms in 116 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    89320 ms: Scavenge 44.4 (90.1) -> 18.6 (90.1) MB, 11.1 ms (+ 0.6 ms in 4 steps since last GC) [Runtime::PerformGC].
[30113]    89610 ms: Mark-sweep 33.1 (90.1) -> 11.4 (90.1) MB, 7.1 ms (+ 39.3 ms in 162 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    90192 ms: Scavenge 44.9 (90.1) -> 19.1 (90.1) MB, 13.1 ms (+ 2.2 ms in 11 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]    90597 ms: Mark-sweep 38.9 (91.1) -> 11.7 (91.1) MB, 8.5 ms (+ 59.7 ms in 223 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[30113]    91178 ms: Scavenge 45.4 (91.1) -> 19.4 (91.1) MB, 10.3 ms [Runtime::PerformGC].
[30113]    91635 ms: Mark-sweep 44.3 (91.1) -> 11.3 (92.1) MB, 7.8 ms (+ 24.8 ms in 125 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]    92224 ms: Scavenge 44.9 (92.1) -> 19.1 (92.1) MB, 11.8 ms (+ 3.9 ms in 23 steps since last GC) [allocation failure].
[30113]    92473 ms: Mark-sweep 31.3 (92.1) -> 11.2 (92.1) MB, 6.9 ms (+ 37.4 ms in 151 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    93052 ms: Scavenge 44.7 (92.1) -> 18.8 (92.1) MB, 11.4 ms (+ 2.7 ms in 15 steps since last GC) [Runtime::PerformGC].
[30113]    93218 ms: Mark-sweep 26.7 (92.1) -> 10.4 (92.1) MB, 5.1 ms (+ 23.9 ms in 113 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]    93788 ms: Scavenge 44.0 (92.1) -> 17.9 (92.1) MB, 10.3 ms (+ 8.1 ms in 52 steps since last GC) [allocation failure].
[30113] Hurrying incremental marking because of lack of progress
[30113]    94036 ms: Mark-sweep 30.1 (92.1) -> 10.7 (92.1) MB, 6.5 ms (+ 46.3 ms in 187 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    94609 ms: Scavenge 44.3 (92.1) -> 18.4 (92.1) MB, 11.1 ms (+ 1.6 ms in 10 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]    94933 ms: Mark-sweep 34.7 (92.1) -> 11.9 (92.1) MB, 8.2 ms (+ 49.2 ms in 202 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    95508 ms: Scavenge 45.4 (92.1) -> 19.5 (92.1) MB, 10.2 ms [Runtime::PerformGC].
[30113]    95966 ms: Mark-sweep 44.7 (92.1) -> 10.1 (92.1) MB, 6.2 ms (+ 24.5 ms in 100 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    96549 ms: Scavenge 43.7 (92.1) -> 17.9 (92.1) MB, 11.1 ms (+ 6.3 ms in 36 steps since last GC) [Runtime::PerformGC].
[30113]    96668 ms: Mark-sweep 23.7 (92.1) -> 11.1 (92.1) MB, 5.0 ms (+ 21.0 ms in 108 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    97242 ms: Scavenge 44.7 (92.1) -> 18.8 (92.1) MB, 11.2 ms (+ 4.6 ms in 25 steps since last GC) [Runtime::PerformGC].
[30113]    97426 ms: Mark-sweep 27.6 (92.1) -> 10.7 (92.1) MB, 6.0 ms (+ 30.1 ms in 126 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[30113]    98007 ms: Scavenge 44.3 (92.1) -> 18.2 (92.1) MB, 10.5 ms (+ 6.1 ms in 36 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]    98287 ms: Mark-sweep 32.1 (92.1) -> 11.0 (92.1) MB, 7.2 ms (+ 48.1 ms in 194 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    98855 ms: Scavenge 44.6 (92.1) -> 18.5 (92.1) MB, 11.2 ms (+ 3.1 ms in 18 steps since last GC) [Runtime::PerformGC].
[30113]    99077 ms: Mark-sweep 29.6 (92.1) -> 10.5 (92.1) MB, 5.5 ms (+ 35.3 ms in 150 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]    99655 ms: Scavenge 44.1 (92.1) -> 18.2 (92.1) MB, 11.4 ms (+ 3.3 ms in 18 steps since last GC) [Runtime::PerformGC].
[30113]    99900 ms: Mark-sweep 30.3 (92.1) -> 10.4 (92.1) MB, 5.3 ms (+ 40.1 ms in 155 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]   100474 ms: Scavenge 43.9 (92.1) -> 18.1 (92.1) MB, 11.8 ms (+ 4.3 ms in 24 steps since last GC) [allocation failure].
[30113]   100650 ms: Mark-sweep 26.4 (92.1) -> 10.9 (92.1) MB, 6.6 ms (+ 26.8 ms in 132 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[30113]   101230 ms: Scavenge 44.5 (92.1) -> 18.6 (92.1) MB, 11.7 ms (+ 4.3 ms in 26 steps since last GC) [allocation failure].
[30113]   101412 ms: Mark-sweep 27.5 (92.1) -> 10.6 (92.1) MB, 6.0 ms (+ 28.0 ms in 128 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   101992 ms: Scavenge 44.2 (92.1) -> 18.1 (92.1) MB, 10.5 ms (+ 7.0 ms in 41 steps since last GC) [Runtime::PerformGC].
[30113]   102149 ms: Mark-sweep 25.8 (92.1) -> 10.4 (92.1) MB, 5.0 ms (+ 29.4 ms in 132 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   102725 ms: Scavenge 43.9 (92.1) -> 17.5 (92.1) MB, 9.3 ms (+ 3.9 ms in 21 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]   103003 ms: Mark-sweep 31.3 (92.1) -> 10.7 (92.1) MB, 6.0 ms (+ 45.9 ms in 181 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   103578 ms: Scavenge 44.2 (92.1) -> 18.4 (92.1) MB, 11.5 ms (+ 1.9 ms in 12 steps since last GC) [allocation failure].
[30113]   103827 ms: Mark-sweep 31.0 (92.1) -> 11.4 (92.1) MB, 7.5 ms (+ 35.8 ms in 164 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   104400 ms: Scavenge 45.0 (92.1) -> 19.0 (92.1) MB, 10.4 ms [Runtime::PerformGC].
[30113]   104929 ms: Scavenge 48.0 (92.1) -> 26.1 (93.1) MB, 14.6 ms (+ 35.1 ms in 138 steps since last GC) [allocation failure].
[30113] Hurrying incremental marking because of lack of progress
[30113]   105157 ms: Mark-sweep 37.0 (95.1) -> 11.5 (95.1) MB, 6.6 ms (+ 67.6 ms in 244 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]   105730 ms: Scavenge 45.0 (95.1) -> 18.6 (95.1) MB, 9.6 ms [Runtime::PerformGC].
[30113]   105953 ms: Mark-sweep 29.5 (95.1) -> 10.1 (95.1) MB, 5.1 ms (+ 31.3 ms in 134 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   106530 ms: Scavenge 43.7 (95.1) -> 17.9 (95.1) MB, 11.3 ms (+ 6.3 ms in 36 steps since last GC) [Runtime::PerformGC].
[30113]   106724 ms: Mark-sweep 27.6 (95.1) -> 11.3 (94.1) MB, 7.2 ms (+ 31.0 ms in 144 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   107301 ms: Scavenge 44.9 (94.1) -> 19.0 (94.1) MB, 11.2 ms (+ 2.0 ms in 12 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]   107654 ms: Mark-sweep 36.8 (94.1) -> 11.6 (94.1) MB, 7.8 ms (+ 51.0 ms in 209 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   108225 ms: Scavenge 45.1 (94.1) -> 19.1 (94.1) MB, 10.0 ms [Runtime::PerformGC].
[30113]   108765 ms: Scavenge 48.4 (94.1) -> 26.5 (95.1) MB, 13.2 ms (+ 38.1 ms in 157 steps since last GC) [Runtime::PerformGC].
[30113]   108821 ms: Mark-sweep 28.9 (95.1) -> 10.4 (95.1) MB, 4.2 ms (+ 45.3 ms in 182 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   109394 ms: Scavenge 44.0 (95.1) -> 18.2 (95.1) MB, 11.4 ms (+ 3.7 ms in 22 steps since last GC) [allocation failure].
[30113] Hurrying incremental marking because of lack of progress
[30113]   109699 ms: Mark-sweep 33.3 (95.1) -> 11.5 (94.1) MB, 7.8 ms (+ 47.6 ms in 201 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   110267 ms: Scavenge 45.0 (94.1) -> 19.1 (94.1) MB, 10.4 ms [Runtime::PerformGC].
[30113]   110703 ms: Mark-sweep 42.9 (94.1) -> 10.9 (94.1) MB, 7.8 ms (+ 23.4 ms in 106 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   111281 ms: Scavenge 44.5 (94.1) -> 18.6 (94.1) MB, 11.6 ms (+ 5.2 ms in 29 steps since last GC) [allocation failure].
[30113]   111401 ms: Mark-sweep 24.4 (94.1) -> 10.4 (94.1) MB, 5.1 ms (+ 19.4 ms in 104 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   111977 ms: Scavenge 44.0 (94.1) -> 17.8 (94.1) MB, 10.3 ms (+ 8.5 ms in 50 steps since last GC) [Runtime::PerformGC].
[30113]   112104 ms: Mark-sweep 24.0 (94.1) -> 10.1 (94.1) MB, 4.7 ms (+ 26.2 ms in 121 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]   112688 ms: Scavenge 43.7 (94.1) -> 17.8 (94.1) MB, 11.2 ms (+ 5.5 ms in 36 steps since last GC) [allocation failure].
[30113] Hurrying incremental marking because of lack of progress
[30113]   112961 ms: Mark-sweep 31.6 (94.1) -> 11.7 (94.1) MB, 7.3 ms (+ 47.8 ms in 193 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]   113534 ms: Scavenge 45.3 (94.1) -> 19.4 (94.1) MB, 10.7 ms [Runtime::PerformGC].
[30113]   113946 ms: Mark-sweep 42.6 (94.1) -> 9.9 (94.1) MB, 4.7 ms (+ 21.4 ms in 89 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   114530 ms: Scavenge 43.5 (94.1) -> 17.6 (94.1) MB, 11.3 ms (+ 7.6 ms in 45 steps since last GC) [allocation failure].
[30113] Hurrying incremental marking because of lack of progress
[30113]   114783 ms: Mark-sweep 29.9 (94.1) -> 11.5 (94.1) MB, 8.0 ms (+ 46.2 ms in 186 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[30113]   115357 ms: Scavenge 45.0 (94.1) -> 19.1 (94.1) MB, 10.7 ms [Runtime::PerformGC].
[30113]   115882 ms: Scavenge 47.9 (94.1) -> 25.9 (95.1) MB, 13.5 ms (+ 32.9 ms in 131 steps since last GC) [allocation failure].
[30113] Hurrying incremental marking because of lack of progress
[30113]   116146 ms: Mark-sweep 38.9 (95.1) -> 11.3 (95.1) MB, 7.0 ms (+ 69.0 ms in 248 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   116714 ms: Scavenge 44.9 (95.1) -> 19.0 (95.1) MB, 10.5 ms [allocation failure].
[30113]   117275 ms: Scavenge 48.8 (95.1) -> 27.5 (95.1) MB, 15.9 ms (+ 45.5 ms in 180 steps since last GC) [allocation failure].
[30113] Hurrying incremental marking because of lack of progress
[30113]   117413 ms: Mark-sweep 33.9 (95.1) -> 11.3 (95.1) MB, 7.4 ms (+ 66.1 ms in 248 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]   117989 ms: Scavenge 44.9 (95.1) -> 18.6 (95.1) MB, 10.2 ms (+ 0.8 ms in 5 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]   118326 ms: Mark-sweep 35.3 (95.1) -> 11.1 (95.1) MB, 7.3 ms (+ 50.5 ms in 196 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]   118931 ms: Scavenge 44.6 (95.1) -> 18.4 (95.1) MB, 9.9 ms (+ 2.8 ms in 17 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]   119250 ms: Mark-sweep 34.0 (95.1) -> 10.9 (95.1) MB, 6.7 ms (+ 49.1 ms in 195 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   119812 ms: Scavenge 44.5 (95.1) -> 18.1 (95.1) MB, 9.6 ms (+ 1.8 ms in 11 steps since last GC) [allocation failure].
[30113]   120022 ms: Mark-sweep 28.5 (95.1) -> 10.1 (95.1) MB, 4.9 ms (+ 31.4 ms in 136 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   120639 ms: Scavenge 44.2 (95.1) -> 18.5 (95.1) MB, 11.6 ms (+ 14.8 ms in 83 steps since last GC) [allocation failure].
[30113] Hurrying incremental marking because of lack of progress
[30113]   120906 ms: Mark-sweep 31.7 (95.1) -> 12.0 (95.1) MB, 8.3 ms (+ 53.3 ms in 218 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]   121509 ms: Scavenge 45.7 (95.1) -> 19.8 (95.1) MB, 10.6 ms [Runtime::PerformGC].
[30113]   121997 ms: Mark-sweep 45.0 (95.1) -> 10.3 (95.1) MB, 5.6 ms (+ 23.9 ms in 96 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   122619 ms: Scavenge 43.9 (95.1) -> 18.1 (95.1) MB, 11.5 ms (+ 4.6 ms in 27 steps since last GC) [Runtime::PerformGC].
[30113]   122757 ms: Mark-sweep 24.4 (95.1) -> 11.1 (95.1) MB, 5.4 ms (+ 19.1 ms in 104 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   123376 ms: Scavenge 44.7 (95.1) -> 18.8 (95.1) MB, 11.2 ms (+ 3.7 ms in 22 steps since last GC) [Runtime::PerformGC].
[30113]   123497 ms: Mark-sweep 24.4 (95.1) -> 10.6 (95.1) MB, 5.1 ms (+ 16.1 ms in 88 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   124108 ms: Scavenge 44.2 (95.1) -> 18.1 (95.1) MB, 10.3 ms (+ 6.9 ms in 40 steps since last GC) [Runtime::PerformGC].
[30113]   124317 ms: Mark-sweep 28.1 (95.1) -> 10.7 (95.1) MB, 5.7 ms (+ 34.3 ms in 157 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   124945 ms: Scavenge 44.3 (95.1) -> 18.0 (95.1) MB, 9.9 ms (+ 6.1 ms in 36 steps since last GC) [Runtime::PerformGC].
[30113]   125185 ms: Mark-sweep 29.3 (95.1) -> 10.7 (95.1) MB, 5.8 ms (+ 38.9 ms in 167 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   125800 ms: Scavenge 44.2 (95.1) -> 18.4 (95.1) MB, 11.6 ms (+ 2.1 ms in 13 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]   126135 ms: Mark-sweep 34.4 (95.1) -> 10.9 (95.1) MB, 6.7 ms (+ 49.5 ms in 197 steps since start of marking, biggest step 0.7 ms) [StackGuard GC request] [GC in old space requested].
[30113]   126771 ms: Scavenge 44.7 (95.1) -> 18.9 (95.1) MB, 11.0 ms (+ 5.1 ms in 30 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]   127111 ms: Mark-sweep 34.8 (95.1) -> 12.2 (95.1) MB, 8.1 ms (+ 53.4 ms in 211 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   127727 ms: Scavenge 45.7 (95.1) -> 19.8 (95.1) MB, 10.3 ms [allocation failure].
[30113]   128297 ms: Scavenge 49.0 (95.1) -> 27.3 (95.1) MB, 14.2 ms (+ 32.7 ms in 136 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]   128599 ms: Mark-sweep 41.4 (96.1) -> 12.0 (96.1) MB, 7.7 ms (+ 71.5 ms in 267 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]   129218 ms: Scavenge 45.6 (96.1) -> 19.6 (96.1) MB, 10.4 ms [allocation failure].
[30113]   129790 ms: Scavenge 48.6 (96.1) -> 27.6 (96.1) MB, 15.6 ms (+ 31.8 ms in 157 steps since last GC) [Runtime::PerformGC].
[30113]   129830 ms: Mark-sweep 29.5 (96.1) -> 11.6 (96.1) MB, 4.7 ms (+ 36.2 ms in 175 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   130455 ms: Scavenge 45.1 (96.1) -> 19.3 (96.1) MB, 11.2 ms (+ 0.2 ms in 1 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]   130885 ms: Mark-sweep 39.5 (96.1) -> 11.9 (96.1) MB, 8.5 ms (+ 55.3 ms in 232 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   131506 ms: Scavenge 45.5 (96.1) -> 19.6 (96.1) MB, 10.5 ms [Runtime::PerformGC].
[30113]   132067 ms: Scavenge 48.5 (96.1) -> 26.6 (96.1) MB, 14.4 ms (+ 30.7 ms in 121 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]   132344 ms: Mark-sweep 39.4 (96.1) -> 11.5 (96.1) MB, 7.1 ms (+ 69.9 ms in 250 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   132958 ms: Scavenge 45.1 (96.1) -> 18.6 (96.1) MB, 9.0 ms [Runtime::PerformGC].
[30113]   133565 ms: Scavenge 49.2 (96.1) -> 28.1 (96.1) MB, 15.3 ms (+ 44.9 ms in 183 steps since last GC) [Runtime::PerformGC].
[30113]   133639 ms: Mark-sweep 31.3 (96.1) -> 11.4 (96.1) MB, 5.5 ms (+ 55.0 ms in 218 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   134260 ms: Scavenge 45.0 (96.1) -> 19.0 (96.1) MB, 11.0 ms (+ 0.8 ms in 5 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]   134662 ms: Mark-sweep 38.0 (96.1) -> 11.6 (96.1) MB, 7.8 ms (+ 52.6 ms in 222 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   135277 ms: Scavenge 45.2 (96.1) -> 19.2 (96.1) MB, 9.9 ms [Runtime::PerformGC].
[30113]   135865 ms: Scavenge 48.8 (96.1) -> 27.9 (96.1) MB, 15.8 ms (+ 40.3 ms in 162 steps since last GC) [Runtime::PerformGC].
[30113]   135884 ms: Mark-sweep 28.7 (96.1) -> 11.4 (96.1) MB, 5.4 ms (+ 42.4 ms in 169 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   136498 ms: Scavenge 45.0 (96.1) -> 19.1 (96.1) MB, 11.3 ms [Runtime::PerformGC].
[30113]   137058 ms: Mark-sweep 48.0 (96.1) -> 11.6 (96.1) MB, 8.6 ms (+ 34.8 ms in 144 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[30113]   137672 ms: Scavenge 45.2 (96.1) -> 19.3 (96.1) MB, 10.2 ms [Runtime::PerformGC].
[30113]   138151 ms: Mark-sweep 44.5 (96.1) -> 10.4 (96.1) MB, 6.0 ms (+ 27.4 ms in 116 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   138773 ms: Scavenge 44.0 (96.1) -> 18.1 (96.1) MB, 11.9 ms (+ 4.5 ms in 25 steps since last GC) [Runtime::PerformGC].
[30113]   138883 ms: Mark-sweep 23.3 (96.1) -> 11.1 (96.1) MB, 4.7 ms (+ 15.6 ms in 93 steps since start of marking, biggest step 0.3 ms) [StackGuard GC request] [GC in old space requested].
[30113]   139511 ms: Scavenge 44.7 (96.1) -> 18.9 (96.1) MB, 11.5 ms (+ 3.9 ms in 22 steps since last GC) [Runtime::PerformGC].
[30113]   139698 ms: Mark-sweep 28.1 (96.1) -> 10.9 (96.1) MB, 6.1 ms (+ 27.4 ms in 130 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   140272 ms: Scavenge 44.5 (96.1) -> 18.5 (96.1) MB, 10.5 ms (+ 4.4 ms in 24 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]   140606 ms: Mark-sweep 34.8 (96.1) -> 11.5 (96.1) MB, 7.1 ms (+ 51.9 ms in 208 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]   141172 ms: Scavenge 45.0 (96.1) -> 19.0 (96.1) MB, 10.5 ms (+ 0.3 ms in 2 steps since last GC) [Runtime::PerformGC].
[30113] Hurrying incremental marking because of lack of progress
[30113]   141532 ms: Mark-sweep 36.7 (96.1) -> 11.4 (96.1) MB, 7.5 ms (+ 52.8 ms in 211 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]   142112 ms: Scavenge 45.0 (96.1) -> 19.0 (96.1) MB, 10.7 ms (+ 0.6 ms in 3 steps since last GC) [Runtime::PerformGC].
[30113]   142374 ms: Mark-sweep 32.2 (96.1) -> 10.9 (96.1) MB, 5.9 ms (+ 36.5 ms in 158 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   142950 ms: Scavenge 44.4 (96.1) -> 18.1 (96.1) MB, 9.7 ms (+ 4.2 ms in 25 steps since last GC) [allocation failure].
[30113]   143140 ms: Mark-sweep 27.4 (96.1) -> 10.4 (96.1) MB, 4.8 ms (+ 31.3 ms in 138 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30113]   143739 ms: Scavenge 44.0 (96.1) -> 18.1 (96.1) MB, 11.4 ms (+ 4.3 ms in 25 steps since last GC) [Runtime::PerformGC].
[30113]   144012 ms: Mark-sweep 31.9 (96.1) -> 11.7 (96.1) MB, 7.1 ms (+ 41.3 ms in 189 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   144594 ms: Scavenge 45.3 (96.1) -> 19.4 (96.1) MB, 10.3 ms [Runtime::PerformGC].
[30113]   145029 ms: Mark-sweep 43.6 (96.1) -> 10.0 (96.1) MB, 5.1 ms (+ 18.4 ms in 81 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30113]   145623 ms: Scavenge 43.6 (96.1) -> 17.8 (96.1) MB, 11.2 ms (+ 7.6 ms in 44 steps since last GC) [Runtime::PerformGC].
[30113]   145922 ms: Mark-sweep 33.1 (96.1) -> 11.9 (96.1) MB, 7.6 ms (+ 47.0 ms in 221 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].

max_new_space_size=102400 (100MB)

$ ./bin/node/bin/node --trace_gc --max_new_space_size=102400 dispatch.js

[30423]       32 ms: Scavenge 1.6 (133.1) -> 1.5 (134.1) MB, 1.4 ms [Runtime::PerformGC].
[30423]       65 ms: Scavenge 2.9 (135.1) -> 2.7 (136.1) MB, 1.4 ms [Runtime::PerformGC].
[30423]       89 ms: Scavenge 3.7 (136.1) -> 3.3 (137.1) MB, 0.9 ms [allocation failure].
[30423]      124 ms: Scavenge 5.7 (137.1) -> 4.7 (138.1) MB, 1.6 ms [Runtime::PerformGC].
[30423]      159 ms: Scavenge 6.6 (138.1) -> 5.6 (138.1) MB, 1.7 ms [Runtime::PerformGC].
[30423]      176 ms: Scavenge 7.2 (139.1) -> 6.4 (139.1) MB, 2.1 ms [Runtime::PerformGC].
[30423]      222 ms: Scavenge 10.0 (139.1) -> 7.9 (141.1) MB, 3.1 ms [Runtime::PerformGC].
[30423]      267 ms: Scavenge 11.3 (141.1) -> 8.9 (141.1) MB, 2.9 ms [allocation failure].
[30423]      332 ms: Scavenge 12.6 (142.1) -> 10.4 (142.1) MB, 3.9 ms (+ 3.3 ms in 15 steps since last GC) [Runtime::PerformGC].
[30423]    38126 ms: Mark-sweep 19.5 (145.1) -> 9.6 (145.1) MB, 8.8 ms (+ 25.0 ms in 93 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[30423]    38310 ms: Scavenge 18.4 (145.1) -> 11.9 (145.1) MB, 2.9 ms [allocation failure].
[30423]    38445 ms: Scavenge 19.0 (145.1) -> 13.7 (146.1) MB, 5.0 ms [allocation failure].
[30423]    38596 ms: Scavenge 20.9 (147.1) -> 15.5 (148.1) MB, 4.8 ms (+ 14.8 ms in 63 steps since last GC) [allocation failure].
[30423]    38777 ms: Scavenge 24.0 (149.1) -> 19.1 (151.1) MB, 6.1 ms (+ 25.3 ms in 87 steps since last GC) [Runtime::PerformGC].
[30423]    38810 ms: Mark-sweep 20.4 (151.1) -> 11.0 (151.1) MB, 3.8 ms (+ 44.4 ms in 165 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30423]    39120 ms: Scavenge 27.9 (151.1) -> 15.0 (151.1) MB, 5.2 ms [allocation failure].
[30423]    39374 ms: Scavenge 28.7 (151.1) -> 18.0 (151.1) MB, 8.2 ms [Runtime::PerformGC].
[30423]    39685 ms: Mark-sweep 33.0 (153.1) -> 10.8 (151.1) MB, 7.4 ms (+ 38.9 ms in 149 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[30423]    39982 ms: Scavenge 27.7 (151.1) -> 14.8 (151.1) MB, 5.1 ms [allocation failure].
[30423]    40225 ms: Scavenge 28.5 (151.1) -> 17.5 (151.1) MB, 6.3 ms [Runtime::PerformGC].
[30423]    40366 ms: Mark-sweep 24.3 (151.1) -> 8.9 (150.1) MB, 5.3 ms (+ 16.8 ms in 70 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30423]    41007 ms: Scavenge 42.9 (150.1) -> 17.4 (150.1) MB, 12.0 ms (+ 18.9 ms in 88 steps since last GC) [Runtime::PerformGC].
[30423]    41174 ms: Mark-sweep 25.2 (151.1) -> 10.5 (151.1) MB, 7.6 ms (+ 40.4 ms in 168 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30423]    41774 ms: Scavenge 44.2 (151.1) -> 18.5 (151.1) MB, 11.4 ms (+ 8.9 ms in 49 steps since last GC) [Runtime::PerformGC].
[30423] Hurrying incremental marking because of lack of progress
[30423]    42029 ms: Mark-sweep 30.6 (151.1) -> 10.4 (151.1) MB, 7.7 ms (+ 44.4 ms in 179 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30423]    42634 ms: Scavenge 44.0 (151.1) -> 18.3 (151.1) MB, 11.1 ms (+ 11.0 ms in 66 steps since last GC) [allocation failure].
[30423]    42821 ms: Mark-sweep 26.9 (151.1) -> 10.0 (151.1) MB, 5.8 ms (+ 37.6 ms in 161 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30423]    43437 ms: Scavenge 43.8 (151.1) -> 18.0 (151.1) MB, 11.6 ms (+ 15.3 ms in 72 steps since last GC) [allocation failure].
[30423]    43462 ms: Mark-sweep 19.0 (151.1) -> 9.0 (151.1) MB, 3.4 ms (+ 18.3 ms in 83 steps since start of marking, biggest step 0.3 ms) [StackGuard GC request] [GC in old space requested].
[30423]    44079 ms: Scavenge 42.8 (151.1) -> 17.3 (151.1) MB, 11.9 ms (+ 16.8 ms in 83 steps since last GC) [Runtime::PerformGC].
[30423]    44147 ms: Mark-sweep 20.2 (151.1) -> 10.0 (151.1) MB, 6.9 ms (+ 25.3 ms in 115 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30423]    44882 ms: Mark-sweep 49.5 (151.1) -> 9.9 (151.1) MB, 7.6 ms (+ 31.5 ms in 126 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30423] Hurrying incremental marking because of lack of progress
[30423]    45704 ms: Mark-sweep 52.6 (151.1) -> 10.0 (151.1) MB, 7.7 ms (+ 41.4 ms in 161 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30423]    46362 ms: Mark-sweep 47.1 (151.1) -> 9.2 (151.1) MB, 6.0 ms (+ 19.2 ms in 81 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30423]    47029 ms: Mark-sweep 46.5 (151.1) -> 10.0 (151.1) MB, 7.0 ms (+ 24.2 ms in 116 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30423] Hurrying incremental marking because of lack of progress
[30423]    47829 ms: Mark-sweep 53.8 (151.1) -> 10.2 (151.1) MB, 8.5 ms (+ 42.3 ms in 164 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30423]    48522 ms: Mark-sweep 48.5 (151.1) -> 9.4 (151.1) MB, 6.3 ms (+ 26.8 ms in 108 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30423] Hurrying incremental marking because of lack of progress
[30423]    49328 ms: Mark-sweep 53.5 (151.1) -> 10.8 (151.1) MB, 9.3 ms (+ 43.4 ms in 181 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].

[30423] Hurrying incremental marking because of lack of progress
[30423]    50264 ms: Mark-sweep 61.7 (151.1) -> 10.8 (151.1) MB, 10.1 ms (+ 48.0 ms in 191 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30423]    51177 ms: Mark-sweep 60.3 (151.1) -> 10.7 (151.1) MB, 10.0 ms (+ 45.2 ms in 181 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30423] Hurrying incremental marking because of lack of progress
[30423]    52092 ms: Mark-sweep 58.0 (151.1) -> 10.5 (151.1) MB, 12.0 ms (+ 45.6 ms in 178 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30423]    52769 ms: Mark-sweep 46.6 (151.1) -> 9.4 (151.1) MB, 6.1 ms (+ 14.7 ms in 69 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30423]    53365 ms: Mark-sweep 40.9 (151.1) -> 8.8 (151.1) MB, 4.3 ms (+ 13.3 ms in 62 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30423] Hurrying incremental marking because of lack of progress
[30423]    54149 ms: Mark-sweep 49.3 (151.1) -> 10.4 (151.1) MB, 8.3 ms (+ 40.6 ms in 168 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30423] Hurrying incremental marking because of lack of progress
[30423]    55054 ms: Mark-sweep 57.1 (151.1) -> 10.5 (151.1) MB, 9.8 ms (+ 47.0 ms in 187 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30423] Hurrying incremental marking because of lack of progress
[30423]    55928 ms: Mark-sweep 56.0 (151.1) -> 10.3 (151.1) MB, 8.7 ms (+ 45.0 ms in 172 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30423]    56602 ms: Mark-sweep 46.4 (151.1) -> 9.2 (151.1) MB, 5.7 ms (+ 16.8 ms in 83 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30423] Hurrying incremental marking because of lack of progress
[30423]    57437 ms: Mark-sweep 52.6 (151.1) -> 10.9 (151.1) MB, 8.6 ms (+ 42.0 ms in 167 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30423]    58275 ms: Mark-sweep 55.2 (151.1) -> 10.3 (151.1) MB, 8.6 ms (+ 27.0 ms in 115 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30423] Hurrying incremental marking because of lack of progress
[30423]    59142 ms: Mark-sweep 57.0 (151.1) -> 10.6 (151.1) MB, 9.5 ms (+ 44.9 ms in 177 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30423]    59835 ms: Mark-sweep 48.8 (151.1) -> 9.6 (151.1) MB, 7.0 ms (+ 22.0 ms in 99 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30423]    60561 ms: Mark-sweep 48.9 (152.1) -> 9.8 (152.1) MB, 6.4 ms (+ 36.7 ms in 129 steps since start of marking, biggest step 0.7 ms) [StackGuard GC request] [GC in old space requested].
[30423] Hurrying incremental marking because of lack of progress
[30423]    61400 ms: Mark-sweep 54.8 (152.1) -> 11.2 (152.1) MB, 9.2 ms (+ 47.1 ms in 195 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30423]    62103 ms: Mark-sweep 49.7 (152.1) -> 10.2 (152.1) MB, 6.9 ms (+ 15.9 ms in 88 steps since start of marking, biggest step 0.3 ms) [StackGuard GC request] [GC in old space requested].
[30423] Hurrying incremental marking because of lack of progress
[30423]    62966 ms: Mark-sweep 57.6 (152.1) -> 10.9 (152.1) MB, 9.2 ms (+ 47.2 ms in 208 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30423]    63718 ms: Mark-sweep 51.9 (152.1) -> 10.1 (152.1) MB, 7.2 ms (+ 30.9 ms in 129 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30423]    64483 ms: Mark-sweep 52.5 (152.1) -> 9.9 (152.1) MB, 6.4 ms (+ 35.0 ms in 138 steps since start of marking, biggest step 0.7 ms) [StackGuard GC request] [GC in old space requested].
[30423]    65331 ms: Mark-sweep 55.8 (152.1) -> 11.3 (152.1) MB, 9.4 ms (+ 46.9 ms in 199 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30423] Hurrying incremental marking because of lack of progress
[30423]    66318 ms: Mark-sweep 64.6 (152.1) -> 11.3 (152.1) MB, 10.9 ms (+ 50.3 ms in 218 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30423]    67143 ms: Mark-sweep 57.3 (152.1) -> 10.6 (152.1) MB, 12.3 ms (+ 29.0 ms in 136 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[30423]    67907 ms: Mark-sweep 53.2 (152.1) -> 10.5 (152.1) MB, 8.1 ms (+ 35.6 ms in 152 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[30423]    68716 ms: Mark-sweep 55.3 (152.1) -> 10.5 (152.1) MB, 7.6 ms (+ 43.6 ms in 178 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].

使用 trace_gc_verbose 参数直接统计总gc时间来对比会看得更加简单:

测试脚本统一压测 15s

$ ./ab -t 15 -c 2 -k '127.0.0.1:7001/'

max_new_space_size=10240

Requests per second:    1283.58 [#/sec] (mean)
Time per request:       1.558 [ms] (mean)
Time per request:       0.779 [ms] (mean, across all concurrent requests)

[724]    48353 ms: Mark-sweep 23.6 (41.1) -> 11.5 (41.1) MB, 4.9 ms (+ 38.7 ms in 143 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[724] Memory allocator,   used:  42084 KB, available: 1424284 KB
[724] New space,          used:      0 KB, available:   8192 KB, committed:  16384 KB
[724] Old pointers,       used:   5632 KB, available:   3949 KB, committed:   9581 KB
[724] Old data space,     used:   3999 KB, available:   1226 KB, committed:   5230 KB
[724] Code space,         used:   1693 KB, available:   4282 KB, committed:   5976 KB
[724] Map space,          used:    414 KB, available:    721 KB, committed:   1135 KB
[724] Cell space,         used:     24 KB, available:    103 KB, committed:    128 KB
[724] PropertyCell space, used:     17 KB, available:     46 KB, committed:     64 KB
[724] Large object space, used:      0 KB, available: 1423243 KB, committed:      0 KB
[724] All spaces,         used:  11781 KB, available:  18521 KB, committed:  38500 KB
[724] External memory reported:    368 KB
[724] Total time spent in GC  : 567.5 ms

max_new_space_size=20480

Requests per second:    1237.98 [#/sec] (mean)
Time per request:       1.616 [ms] (mean)
Time per request:       0.808 [ms] (mean, across all concurrent requests)

[823]    19229 ms: Scavenge 26.8 (54.1) -> 13.9 (54.1) MB, 5.6 ms [Runtime::PerformGC].
[823] Memory allocator,   used:  55396 KB, available: 1443740 KB
[823] New space,          used:   2856 KB, available:  13527 KB, committed:  32768 KB
[823] Old pointers,       used:   4388 KB, available:   4185 KB, committed:   8574 KB
[823] Old data space,     used:   3703 KB, available:   1524 KB, committed:   5230 KB
[823] Code space,         used:   2709 KB, available:   1267 KB, committed:   3984 KB
[823] Map space,          used:    504 KB, available:    619 KB, committed:   1135 KB
[823] Cell space,         used:     25 KB, available:    102 KB, committed:    128 KB
[823] PropertyCell space, used:     17 KB, available:     46 KB, committed:     64 KB
[823] Large object space, used:      0 KB, available: 1442699 KB, committed:      0 KB
[823] All spaces,         used:  14205 KB, available:  21272 KB, committed:  51884 KB
[823] External memory reported:     97 KB
[823] Total time spent in GC  : 574.7 ms

max_new_space_size=32768

Requests per second:    1236.95 [#/sec] (mean)
Time per request:       1.617 [ms] (mean)
Time per request:       0.808 [ms] (mean, across all concurrent requests)

[982]    20155 ms: Scavenge 28.8 (57.1) -> 18.1 (57.1) MB, 6.8 ms [Runtime::PerformGC].
[982] Memory allocator,   used:  58468 KB, available: 1440668 KB
[982] New space,          used:   2343 KB, available:  14040 KB, committed:  32768 KB
[982] Old pointers,       used:   7580 KB, available:   1897 KB, committed:   9581 KB
[982] Old data space,     used:   4354 KB, available:    840 KB, committed:   5230 KB
[982] Code space,         used:   3549 KB, available:   2349 KB, committed:   5976 KB
[982] Map space,          used:    624 KB, available:    509 KB, committed:   1135 KB
[982] Cell space,         used:     24 KB, available:    103 KB, committed:    128 KB
[982] PropertyCell space, used:     17 KB, available:     46 KB, committed:     64 KB
[982] Large object space, used:      0 KB, available: 1439627 KB, committed:      0 KB
[982] All spaces,         used:  18495 KB, available:  19787 KB, committed:  54884 KB
[982] External memory reported:    203 KB
[982] Total time spent in GC  : 554.6 ms

max_new_space_size=65536

Requests per second:    1291.73 [#/sec] (mean)
Time per request:       1.548 [ms] (mean)
Time per request:       0.774 [ms] (mean, across all concurrent requests)

[1068]    17862 ms: Scavenge 46.6 (92.1) -> 20.6 (92.1) MB, 10.3 ms [allocation failure].
[1068] Memory allocator,   used:  94308 KB, available: 1470364 KB
[1068] New space,          used:   5706 KB, available:  27061 KB, committed:  65536 KB
[1068] Old pointers,       used:   6486 KB, available:   1865 KB, committed:   8574 KB
[1068] Old data space,     used:   4378 KB, available:   1857 KB, committed:   6238 KB
[1068] Code space,         used:   3854 KB, available:   5104 KB, committed:   8964 KB
[1068] Map space,          used:    663 KB, available:    470 KB, committed:   1135 KB
[1068] Cell space,         used:     24 KB, available:    103 KB, committed:    128 KB
[1068] PropertyCell space, used:     17 KB, available:     46 KB, committed:     64 KB
[1068] Large object space, used:      0 KB, available: 1469323 KB, committed:      0 KB
[1068] All spaces,         used:  21131 KB, available:  36508 KB, committed:  90640 KB
[1068] External memory reported:    521 KB
[1068] Total time spent in GC  : 484.3 ms

max_new_space_size=81920

Requests per second:    1341.85 [#/sec] (mean)
Time per request:       1.490 [ms] (mean)
Time per request:       0.745 [ms] (mean, across all concurrent requests)

[1171]    18224 ms: Mark-sweep 59.5 (152.1) -> 12.2 (152.1) MB, 11.2 ms (+ 45.8 ms in 209 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[1171] Memory allocator,   used: 155748 KB, available: 1539996 KB
[1171] New space,          used:      0 KB, available:  65536 KB, committed: 131072 KB
[1171] Old pointers,       used:   6196 KB, available:   1255 KB, committed:   7566 KB
[1171] Old data space,     used:   4190 KB, available:   1040 KB, committed:   5230 KB
[1171] Code space,         used:   1666 KB, available:   5305 KB, committed:   6972 KB
[1171] Map space,          used:    434 KB, available:    701 KB, committed:   1135 KB
[1171] Cell space,         used:     24 KB, available:    103 KB, committed:    128 KB
[1171] PropertyCell space, used:     17 KB, available:     46 KB, committed:     64 KB
[1171] Large object space, used:      0 KB, available: 1538955 KB, committed:      0 KB
[1171] All spaces,         used:  12529 KB, available:  73989 KB, committed: 152168 KB
[1171] External memory reported:    536 KB
[1171] Total time spent in GC  : 342.7 ms

max_new_space_size=92160

Requests per second:    1263.66 [#/sec] (mean)
Time per request:       1.583 [ms] (mean)
Time per request:       0.791 [ms] (mean, across all concurrent requests)

[1264]    17860 ms: Mark-sweep 56.2 (160.1) -> 11.6 (160.1) MB, 11.9 ms (+ 47.6 ms in 189 steps since start of marking, biggest step 0.5 ms) [StackGuard GC request] [GC in old space requested].
[1264] Memory allocator,   used: 163940 KB, available: 1531804 KB
[1264] New space,          used:      0 KB, available:  65536 KB, committed: 131072 KB
[1264] Old pointers,       used:   5705 KB, available:   5881 KB, committed:  11597 KB
[1264] Old data space,     used:   3967 KB, available:   2265 KB, committed:   6238 KB
[1264] Code space,         used:   1729 KB, available:   8230 KB, committed:   9960 KB
[1264] Map space,          used:    430 KB, available:    705 KB, committed:   1135 KB
[1264] Cell space,         used:     24 KB, available:    103 KB, committed:    128 KB
[1264] PropertyCell space, used:     17 KB, available:     46 KB, committed:     64 KB
[1264] Large object space, used:      0 KB, available: 1530763 KB, committed:      0 KB
[1264] All spaces,         used:  11874 KB, available:  82769 KB, committed: 160195 KB
[1264] External memory reported:    275 KB
[1264] Total time spent in GC  : 358.5 ms

max_new_space_size=102400

Requests per second:    1308.52 [#/sec] (mean)
Time per request:       1.528 [ms] (mean)
Time per request:       0.764 [ms] (mean, across all concurrent requests)

[1370]    18890 ms: Mark-sweep 56.5 (153.1) -> 10.5 (153.1) MB, 9.7 ms (+ 23.1 ms in 104 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[1370] Memory allocator,   used: 156772 KB, available: 1538972 KB
[1370] New space,          used:      0 KB, available:  65536 KB, committed: 131072 KB
[1370] Old pointers,       used:   4841 KB, available:   2724 KB, committed:   7566 KB
[1370] Old data space,     used:   3798 KB, available:   1431 KB, committed:   5230 KB
[1370] Code space,         used:   1651 KB, available:   6316 KB, committed:   7968 KB
[1370] Map space,          used:    395 KB, available:    740 KB, committed:   1135 KB
[1370] Cell space,         used:     25 KB, available:    102 KB, committed:    128 KB
[1370] PropertyCell space, used:     17 KB, available:     46 KB, committed:     64 KB
[1370] Large object space, used:      0 KB, available: 1537931 KB, committed:      0 KB
[1370] All spaces,         used:  10729 KB, available:  76898 KB, committed: 153164 KB
[1370] External memory reported:    113 KB
[1370] Total time spent in GC  : 362.7 ms

max_new_space_size=122880

Requests per second:    1301.96 [#/sec] (mean)
Time per request:       1.536 [ms] (mean)
Time per request:       0.768 [ms] (mean, across all concurrent requests)

[1452] Hurrying incremental marking because of lack of progress
[1452]    17557 ms: Mark-sweep 61.7 (154.1) -> 11.4 (154.1) MB, 9.6 ms (+ 52.4 ms in 198 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[1452] Memory allocator,   used: 157796 KB, available: 1537948 KB
[1452] New space,          used:      0 KB, available:  65536 KB, committed: 131072 KB
[1452] Old pointers,       used:   5515 KB, available:   3037 KB, committed:   8574 KB
[1452] Old data space,     used:   3911 KB, available:   1318 KB, committed:   5230 KB
[1452] Code space,         used:   1724 KB, available:   6243 KB, committed:   7968 KB
[1452] Map space,          used:    438 KB, available:    697 KB, committed:   1135 KB
[1452] Cell space,         used:     24 KB, available:    103 KB, committed:    128 KB
[1452] PropertyCell space, used:     17 KB, available:     46 KB, committed:     64 KB
[1452] Large object space, used:      0 KB, available: 1536907 KB, committed:      0 KB
[1452] All spaces,         used:  11632 KB, available:  76983 KB, committed: 154172 KB
[1452] External memory reported:    299 KB
[1452] Total time spent in GC  : 341.9 ms

max_new_space_size=153600

Requests per second:    1256.35 [#/sec] (mean)
Time per request:       1.592 [ms] (mean)
Time per request:       0.796 [ms] (mean, across all concurrent requests)

[1543] Hurrying incremental marking because of lack of progress
[1543]    18278 ms: Mark-sweep 62.3 (283.1) -> 12.5 (283.1) MB, 12.1 ms (+ 54.2 ms in 224 steps since start of marking, biggest step 0.4 ms) [StackGuard GC request] [GC in old space requested].
[1543] Memory allocator,   used: 289892 KB, available: 1667996 KB
[1543] New space,          used:      0 KB, available:  65536 KB, committed: 131072 KB
[1543] Old pointers,       used:   6348 KB, available:   2010 KB, committed:   8574 KB
[1543] Old data space,     used:   4228 KB, available:   2007 KB, committed:   6238 KB
[1543] Code space,         used:   1705 KB, available:   6262 KB, committed:   7968 KB
[1543] Map space,          used:    443 KB, available:    691 KB, committed:   1135 KB
[1543] Cell space,         used:     23 KB, available:    104 KB, committed:    128 KB
[1543] PropertyCell space, used:     17 KB, available:     46 KB, committed:     64 KB
[1543] Large object space, used:      0 KB, available: 1666955 KB, committed:      0 KB
[1543] All spaces,         used:  12768 KB, available:  76658 KB, committed: 155180 KB
[1543] External memory reported:    530 KB
[1543] Total time spent in GC  : 351.2 ms

max_new_space_size=204800

Requests per second:    1256.52 [#/sec] (mean)
Time per request:       1.592 [ms] (mean)
Time per request:       0.796 [ms] (mean, across all concurrent requests)

[1650] Hurrying incremental marking because of lack of progress
[1650]    17865 ms: Mark-sweep 66.5 (289.1) -> 12.0 (289.1) MB, 12.4 ms (+ 56.6 ms in 221 steps since start of marking, biggest step 0.6 ms) [StackGuard GC request] [GC in old space requested].
[1650] Memory allocator,   used: 296036 KB, available: 1661852 KB
[1650] New space,          used:      0 KB, available:  65536 KB, committed: 131072 KB
[1650] Old pointers,       used:   6027 KB, available:   5565 KB, committed:  11597 KB
[1650] Old data space,     used:   4064 KB, available:   2173 KB, committed:   6238 KB
[1650] Code space,         used:   1729 KB, available:   9226 KB, committed:  10956 KB
[1650] Map space,          used:    443 KB, available:    692 KB, committed:   1135 KB
[1650] Cell space,         used:     25 KB, available:    102 KB, committed:    128 KB
[1650] PropertyCell space, used:     17 KB, available:     46 KB, committed:     64 KB
[1650] Large object space, used:      0 KB, available: 1660811 KB, committed:      0 KB
[1650] All spaces,         used:  12308 KB, available:  83341 KB, committed: 161191 KB
[1650] External memory reported:    321 KB
[1650] Total time spent in GC  : 344.8 ms

统计情况

trace_gc_verbose 按总 gc 时间统计

$ ./ab -t 15 -c 2 -k '127.0.0.1:7001/'

size	gc time	qps	rt
10240	567.5	1283.58	1.558
20480	574.7	1237.98	1.616
32768 (默认)	554.6	1236.95	1.617
65536	484.3	1291.73	1.548
81920	342.7	1341.85	1.490
92160	358.5	1263.66	1.583
102400	362.7	1308.52	1.528
122880	341.9	1301.96	1.536
153600	351.2	1256.35	1.592
204800	344.8	1256.52	1.592

并发10, 压测 30s

$ ./ab -t 30 -c 10 -k '127.0.0.1:7001/'

size	gc time	qps	rt
20480	1076.6	1595.43	6.268
25600	1050.2	1523.93	6.562
30720	1054.5	1522.61	6.568
32768 (默认)	1075.7	1586.33	6.304
40960	1025.1	1619.56	6.175
51200	932.2	1562.26	6.401
65536	571.8	1530.07	6.536
81920	534.0	1656.32	6.037
92160	536.4	1560.68	6.407
97280 (95MB)	524.3	1594.59	6.271
102400	527.8	1575.34	6.348
122880	537.4	1586.04	6.305
153600	544.7	1618.33	6.179
204800	520.4	1569.66	6.371
256000	519.6	1552.86	6.440

重点关注 New space 的 available 是否一直在变化, 如果应用超过了这个值, 那么就需要将 max_new_space_size 调大

max_new_space_size 必须按 16MB, 32MB, 64MB, 128MB, 256MB 步长递增才有效.

[8223] New space,          used:      0 KB, available: 131072 KB, committed: 262144 KB

https://gist.github.com/rednaxelafx/e3f998bc45730e281cd4