agreatfool/node--trace-gc--trace-gc-verbose.sh

## node--trace-gc--trace-gc-verbose.sh
$ node --expose-gc --trace-gc --trace-gc-verbose ./cases/leak-and-gc.js --normal
[44972:0x103000000] Fast promotion mode: false survival rate: 57%
[44972:0x103000000]       60 ms: Scavenge 3.0 (5.5) -> 2.7 (6.5) MB, 1.2 / 0.0 ms  allocation failure
[44972:0x103000000] Memory allocator,   used:   6656 KB, available: 1459712 KB
[44972:0x103000000] New space,          used:    581 KB, available:    425 KB, committed:   2048 KB
[44972:0x103000000] Old space,          used:    778 KB, available:      0 KB, committed:   1024 KB
[44972:0x103000000] Code space,         used:   1180 KB, available:      1 KB, committed:   1536KB
[44972:0x103000000] Map space,          used:    201 KB, available:      0 KB, committed:    512 KB
[44972:0x103000000] Large object space, used:      0 KB, available: 1459191 KB, committed:      0 KB
[44972:0x103000000] All spaces,         used:   2741 KB, available: 1459617 KB, committed:   5120KB
[44972:0x103000000] External memory reported:      8 KB
[44972:0x103000000] Total time spent in GC  : 1.2 ms
[44972:0x103000000] Fast promotion mode: false survival rate: 94%
[44972:0x103000000]       75 ms: Scavenge 3.3 (7.0) -> 3.3 (7.5) MB, 1.1 / 0.0 ms  allocation failure
[44972:0x103000000] Memory allocator,   used:   7680 KB, available: 1458688 KB
[44972:0x103000000] New space,          used:    369 KB, available:    637 KB, committed:   2048 KB
[44972:0x103000000] Old space,          used:   1605 KB, available:      0 KB, committed:   2048 KB
[44972:0x103000000] Code space,         used:   1181 KB, available:      1 KB, committed:   1536KB
[44972:0x103000000] Map space,          used:    230 KB, available:      0 KB, committed:    512 KB
[44972:0x103000000] Large object space, used:      0 KB, available: 1458167 KB, committed:      0 KB
[44972:0x103000000] All spaces,         used:   3387 KB, available: 1458805 KB, committed:   6144KB
[44972:0x103000000] External memory reported:      8 KB
[44972:0x103000000] Total time spent in GC  : 2.4 ms
[44972:0x103000000] Fast promotion mode: false survival rate: 37%
[44972:0x103000000]       98 ms: Scavenge 4.4 (7.5) -> 4.1 (10.0) MB, 0.8 / 0.0 ms  allocation failure
[44972:0x103000000] Memory allocator,   used:  10240 KB, available: 1456128 KB
[44972:0x103000000] New space,          used:    381 KB, available:   1632 KB, committed:   4096 KB
[44972:0x103000000] Old space,          used:   2349 KB, available:     31 KB, committed:   2560 KB
[44972:0x103000000] Code space,         used:   1184 KB, available:      1 KB, committed:   1536KB
[44972:0x103000000] Map space,          used:    308 KB, available:      0 KB, committed:    512 KB
[44972:0x103000000] Large object space, used:      0 KB, available: 1455607 KB, committed:      0 KB
[44972:0x103000000] All spaces,         used:   4222 KB, available: 1457272 KB, committed:   8704KB
[44972:0x103000000] External memory reported:      8 KB
[44972:0x103000000] Total time spent in GC  : 3.2 ms
[44972:0x103000000] Fast promotion mode: false survival rate: 94%
[44972:0x103000000]     1125 ms: Scavenge 5.9 (10.5) -> 5.8 (10.5) MB, 1.1 / 0.0 ms  allocation failure
[44972:0x103000000] Memory allocator,   used:  10752 KB, available: 1455616 KB
[44972:0x103000000] New space,          used:   1579 KB, available:    434 KB, committed:   4096 KB
[44972:0x103000000] Old space,          used:   2848 KB, available:      0 KB, committed:   3072 KB
[44972:0x103000000] Code space,         used:   1191 KB, available:      1 KB, committed:   1536KB
[44972:0x103000000] Map space,          used:    339 KB, available:      0 KB, committed:    512 KB
[44972:0x103000000] Large object space, used:      0 KB, available: 1455095 KB, committed:      0 KB
[44972:0x103000000] All spaces,         used:   5958 KB, available: 1455531 KB, committed:   9216KB
[44972:0x103000000] External memory reported:      8 KB
[44972:0x103000000] Total time spent in GC  : 4.3 ms
[44972:0x103000000] Fast promotion mode: false survival rate: 45%
[44972:0x103000000]     1127 ms: Scavenge 6.2 (10.5) -> 6.1 (16.0) MB, 1.5 / 0.0 ms  allocation failure
[44972:0x103000000] Memory allocator,   used:  16384 KB, available: 1449984 KB
[44972:0x103000000] New space,          used:    384 KB, available:   3643 KB, committed:   8192 KB
[44972:0x103000000] Old space,          used:   4283 KB, available:    134 KB, committed:   4608 KB
[44972:0x103000000] Code space,         used:   1191 KB, available:      1 KB, committed:   1536KB
[44972:0x103000000] Map space,          used:    339 KB, available:      0 KB, committed:    512 KB
[44972:0x103000000] Large object space, used:      0 KB, available: 1449463 KB, committed:      0 KB
[44972:0x103000000] All spaces,         used:   6198 KB, available: 1453243 KB, committed:  14848KB
[44972:0x103000000] External memory reported:      8 KB
[44972:0x103000000] Total time spent in GC  : 5.8 ms
[44972:0x103000000] Fast promotion mode: false survival rate: 98%
[44972:0x103000000]     3189 ms: Scavenge 51.2 (57.8) -> 51.2 (58.3) MB, 3.4 / 0.0 ms  allocation failure
[44972:0x103000000] Memory allocator,   used:  59744 KB, available: 1406624 KB
[44972:0x103000000] New space,          used:   3588 KB, available:    439 KB, committed:   8192 KB
[44972:0x103000000] Old space,          used:   4696 KB, available:    155 KB, committed:   5120 KB
[44972:0x103000000] Code space,         used:   1203 KB, available:      1 KB, committed:   1536KB
[44972:0x103000000] Map space,          used:    339 KB, available:      0 KB, committed:    512 KB
[44972:0x103000000] Large object space, used:  42598 KB, available: 1406103 KB, committed:  42848 KB
[44972:0x103000000] All spaces,         used:  52425 KB, available: 1406699 KB, committed:  58208KB
[44972:0x103000000] External memory reported:      8 KB
[44972:0x103000000] Total time spent in GC  : 9.2 ms
[44972:0x103000000] Fast promotion mode: false survival rate: 44%
[44972:0x103000000]     3193 ms: Scavenge 51.6 (58.3) -> 51.2 (69.8) MB, 3.4 / 0.0 ms  allocation failure
[44972:0x103000000] Memory allocator,   used:  71520 KB, available: 1394848 KB
[44972:0x103000000] New space,          used:    384 KB, available:   7671 KB, committed:  16384 KB
[44972:0x103000000] Old space,          used:   7900 KB, available:    539 KB, committed:   8704 KB
[44972:0x103000000] Code space,         used:   1203 KB, available:      1 KB, committed:   1536KB
[44972:0x103000000] Map space,          used:    339 KB, available:      0 KB, committed:    512 KB
[44972:0x103000000] Large object space, used:  42598 KB, available: 1394327 KB, committed:  42848 KB
[44972:0x103000000] All spaces,         used:  52426 KB, available: 1402539 KB, committed:  69984KB
[44972:0x103000000] External memory reported:      8 KB
[44972:0x103000000] Total time spent in GC  : 12.5 ms
[44972:0x103000000] Fast promotion mode: false survival rate: 99%
[44972:0x103000000]     6261 ms: Scavenge 114.6 (126.2) -> 114.6 (126.7) MB, 4.3 / 0.0 ms  allocation failure
[44972:0x103000000] Memory allocator,   used: 129712 KB, available: 1336656 KB
[44972:0x103000000] New space,          used:   7616 KB, available:    439 KB, committed:  16384 KB
[44972:0x103000000] Old space,          used:   8285 KB, available:    594 KB, committed:   9216 KB
[44972:0x103000000] Code space,         used:   1203 KB, available:      1 KB, committed:   1536KB
[44972:0x103000000] Map space,          used:    339 KB, available:      0 KB, committed:    512 KB
[44972:0x103000000] Large object space, used:  99943 KB, available: 1336135 KB, committed: 100528 KB
[44972:0x103000000] All spaces,         used: 117387 KB, available: 1337170 KB, committed: 128176KB
[44972:0x103000000] External memory reported:      8 KB
[44972:0x103000000] Total time spent in GC  : 16.9 ms
[44972:0x103000000] Fast promotion mode: false survival rate: 44%
[44972:0x103000000]     7301 ms: Scavenge 131.0 (142.8) -> 130.2 (176.3) MB, 9.3 / 0.0 ms  allocation failure
[44972:0x103000000] Memory allocator,   used: 180496 KB, available: 1285872 KB
[44972:0x103000000] New space,          used:    384 KB, available:  15727 KB, committed:  32768 KB
[44972:0x103000000] Old space,          used:  15077 KB, available:  11543 KB, committed:  27136 KB
[44972:0x103000000] Code space,         used:   1203 KB, available:      1 KB, committed:   1536KB
[44972:0x103000000] Map space,          used:    339 KB, available:      0 KB, committed:    512 KB
[44972:0x103000000] Large object space, used: 116327 KB, available: 1285351 KB, committed: 117008 KB
[44972:0x103000000] All spaces,         used: 133332 KB, available: 1312623 KB, committed: 178960KB
[44972:0x103000000] External memory reported:      8 KB
[44972:0x103000000] Total time spent in GC  : 26.2 ms
[44972:0x103000000] Fast promotion mode: false survival rate: 99%
[44972:0x103000000]    12574 ms: Scavenge 265.5 (297.0) -> 265.5 (298.0) MB, 8.8 / 0.0 ms  allocation failure
[44972:0x103000000] Memory allocator,   used: 305120 KB, available: 1161248 KB
[44972:0x103000000] New space,          used:  15672 KB, available:    439 KB, committed:  32768 KB
[44972:0x103000000] Old space,          used:  15462 KB, available:  12165 KB, committed:  28160 KB
[44972:0x103000000] Code space,         used:   1203 KB, available:      1 KB, committed:   1536KB
[44972:0x103000000] Map space,          used:    339 KB, available:      0 KB, committed:    512 KB
[44972:0x103000000] Large object space, used: 239208 KB, available: 1160727 KB, committed: 240608 KB
[44972:0x103000000] All spaces,         used: 271885 KB, available: 1173333 KB, committed: 303584KB
[44972:0x103000000] External memory reported:      8 KB
[44972:0x103000000] Total time spent in GC  : 35.0 ms
[44972:0x103000000] Fast promotion mode: false survival rate: 22%
[44972:0x103000000]    12580 ms: Heap growing factor 4.0 based on mu=0.970, speed_ratio=0 (gc=0, mutator=25291)
[44972:0x103000000]    12580 ms: Grow: old size: 269765 KB, new limit: 851682 KB (4.0)
[44972:0x103000000]    12580 ms: Mark-sweep 265.8 (298.0) -> 263.8 (313.5) MB, 4.9 / 0.0 ms  (+ 100.3 ms in 290 steps since start of marking, biggest step 2.6 ms, walltime since start of marking 6320 ms) finalize incremental marking via stack guard GC in old space requested
[44972:0x103000000] Memory allocator,   used: 320992 KB, available: 1145376 KB
[44972:0x103000000] New space,          used:    324 KB, available:  15787 KB, committed:  32768 KB
[44972:0x103000000] Old space,          used:  29128 KB, available:    311 KB, committed:  44032 KB
[44972:0x103000000] Code space,         used:   1195 KB, available:      0 KB, committed:   1536KB
[44972:0x103000000] Map space,          used:    232 KB, available:      0 KB, committed:    512 KB
[44972:0x103000000] Large object space, used: 239208 KB, available: 1144855 KB, committed: 240608 KB
[44972:0x103000000] All spaces,         used: 270089 KB, available: 1160954 KB, committed: 319456KB
[44972:0x103000000] External memory reported:      8 KB
[44972:0x103000000] Total time spent in GC  : 39.9 ms
[44972:0x103000000] Fast promotion mode: false survival rate: 99%
[44972:0x103000000]    18753 ms: Dampen: old size: 402797 KB, old limit: 851682 KB, new limit: 498841 KB (1.2)
[44972:0x103000000]    18753 ms: Scavenge 408.7 (443.8) -> 408.7 (443.8) MB, 14.4 / 0.0 ms  allocation failure
[44972:0x103000000] Memory allocator,   used: 454480 KB, available: 1011888 KB
[44972:0x103000000] New space,          used:  15736 KB, available:    375 KB, committed:  32768 KB
[44972:0x103000000] Old space,          used:  29449 KB, available:  13795 KB, committed:  44032 KB
[44972:0x103000000] Code space,         used:   1195 KB, available:    280 KB, committed:   1536KB
[44972:0x103000000] Map space,          used:    232 KB, available:    271 KB, committed:    512 KB
[44972:0x103000000] Large object space, used: 371919 KB, available: 1011367 KB, committed: 374096 KB
[44972:0x103000000] All spaces,         used: 418534 KB, available: 1026089 KB, committed: 452944KB
[44972:0x103000000] External memory reported:      8 KB
[44972:0x103000000] Total time spent in GC  : 54.3 ms
[44972:0x103000000] Fast promotion mode: false survival rate: 0%
[44972:0x103000000]    18901 ms: Heap growing factor 1.2 based on mu=0.970, speed_ratio=195 (gc=4763053, mutator=24445)
[44972:0x103000000]    18901 ms: Grow: old size: 376070 KB, new limit: 467004 KB (1.2)
[44972:0x103000000]    18901 ms: Mark-sweep 408.8 (443.8) -> 367.3 (407.3) MB, 148.2 / 0.0 ms  testing GC in old space requested
[44972:0x103000000] Memory allocator,   used: 417104 KB, available: 1049264 KB
[44972:0x103000000] New space,          used:      0 KB, available:  16111 KB, committed:  32768 KB
[44972:0x103000000] Old space,          used:   2726 KB, available:    500 KB, committed:   6656 KB
[44972:0x103000000] Code space,         used:   1192 KB, available:      0 KB, committed:   1536KB
[44972:0x103000000] Map space,          used:    232 KB, available:      0 KB, committed:    512 KB
[44972:0x103000000] Large object space, used: 371919 KB, available: 1048743 KB, committed: 374096 KB
[44972:0x103000000] All spaces,         used: 376070 KB, available: 1065356 KB, committed: 415568KB
[44972:0x103000000] External memory reported:      8 KB
[44972:0x103000000] Total time spent in GC  : 202.5 ms
[44972:0x103000000]    20972 ms: Memory reducer: low alloc, foreground
[44972:0x103000000]    20972 ms: Memory reducer: waiting for 5929 ms
[44972:0x103000000] Fast promotion mode: false survival rate: 0%
[44972:0x103000000]    25257 ms: Heap growing factor 1.2 based on mu=0.970, speed_ratio=212 (gc=4763053, mutator=22477)
[44972:0x103000000]    25257 ms: Grow: old size: 4109 KB, new limit: 28413 KB (1.2)
[44972:0x103000000]    25257 ms: Mark-sweep 517.8 (544.1) -> 18.5 (41.0) MB, 10.0 / 0.0 ms  (+ 168.9 ms in 156 steps since start of marking, biggest step 2.7 ms, walltime since start of marking 3273 ms) testing GC in old space requested
[44972:0x103000000] Memory allocator,   used:  41984 KB, available: 1424384 KB
[44972:0x103000000] New space,          used:  14857 KB, available:   1254 KB, committed:  32768 KB
[44972:0x103000000] Old space,          used:   2684 KB, available:    503 KB, committed:   5632 KB
[44972:0x103000000] Code space,         used:   1192 KB, available:      0 KB, committed:   1536KB
[44972:0x103000000] Map space,          used:    232 KB, available:      0 KB, committed:    512 KB
[44972:0x103000000] Large object space, used:      0 KB, available: 1423863 KB, committed:      0 KB
[44972:0x103000000] All spaces,         used:  18966 KB, available: 1425621 KB, committed:  40448KB
[44972:0x103000000] External memory reported:      8 KB
[44972:0x103000000] Total time spent in GC  : 212.4 ms
[44972:0x103000000] Fast promotion mode: false survival rate: 89%
[44972:0x103000000]    26273 ms: Scavenge 19.7 (41.0) -> 18.1 (53.5) MB, 12.9 / 0.0 ms  allocation failure
[44972:0x103000000] Memory allocator,   used:  54784 KB, available: 1411584 KB
[44972:0x103000000] New space,          used:   1199 KB, available:  14912 KB, committed:  32768 KB
[44972:0x103000000] Old space,          used:  15943 KB, available:   2061 KB, committed:  18432 KB
[44972:0x103000000] Code space,         used:   1192 KB, available:    283 KB, committed:   1536KB
[44972:0x103000000] Map space,          used:    232 KB, available:    271 KB, committed:    512 KB
[44972:0x103000000] Large object space, used:      0 KB, available: 1411063 KB, committed:      0 KB
[44972:0x103000000] All spaces,         used:  18567 KB, available: 1428592 KB, committed:  53248KB
[44972:0x103000000] External memory reported:      8 KB
[44972:0x103000000] Total time spent in GC  : 225.3 ms
[44972:0x103000000]    28371 ms: Memory reducer: low alloc, foreground
[44972:0x103000000]    28371 ms: Memory reducer: waiting for 8000 ms
[44972:0x103000000] Fast promotion mode: false survival rate: 98%
[44972:0x103000000]    32484 ms: Scavenge 120.7 (142.0) -> 120.6 (145.0) MB, 6.4 / 0.0 ms  allocation failure
[44972:0x103000000] Memory allocator,   used: 148496 KB, available: 1317872 KB
[44972:0x103000000] New space,          used:  14857 KB, available:   1254 KB, committed:  32768 KB
[44972:0x103000000] Old space,          used:  17062 KB, available:   4019 KB, committed:  21504 KB
[44972:0x103000000] Code space,         used:   1208 KB, available:     20 KB, committed:   1536KB
[44972:0x103000000] Map space,          used:    232 KB, available:    271 KB, committed:    512 KB
[44972:0x103000000] Large object space, used:  90112 KB, available: 1317351 KB, committed:  90640 KB
[44972:0x103000000] All spaces,         used: 123473 KB, available: 1322917 KB, committed: 146960KB
[44972:0x103000000] External memory reported:      8 KB
[44972:0x103000000] Total time spent in GC  : 231.7 ms
[44972:0x103000000] Fast promotion mode: false survival rate: 89%
[44972:0x103000000]    32499 ms: Scavenge 121.8 (145.0) -> 120.2 (179.5) MB, 12.4 / 0.0 ms  allocation failure
[44972:0x103000000] Memory allocator,   used: 183824 KB, available: 1282544 KB
[44972:0x103000000] New space,          used:   1199 KB, available:  14912 KB, committed:  32768 KB
[44972:0x103000000] Old space,          used:  30326 KB, available:  25496 KB, committed:  56832 KB
[44972:0x103000000] Code space,         used:   1208 KB, available:     20 KB, committed:   1536KB
[44972:0x103000000] Map space,          used:    232 KB, available:    271 KB, committed:    512 KB
[44972:0x103000000] Large object space, used:  90112 KB, available: 1282023 KB, committed:  90640 KB
[44972:0x103000000] All spaces,         used: 123079 KB, available: 1322724 KB, committed: 182288KB
[44972:0x103000000] External memory reported:      8 KB
[44972:0x103000000] Total time spent in GC  : 244.1 ms
[44972:0x103000000] Fast promotion mode: false survival rate: 2%
[44972:0x103000000]    32502 ms: Heap growing factor 1.2 based on mu=0.970, speed_ratio=220 (gc=4265796, mutator=19424)
[44972:0x103000000]    32502 ms: Grow: old size: 122951 KB, new limit: 160289 KB (1.2)
[44972:0x103000000]    32502 ms: Mark-sweep 120.3 (179.5) -> 120.2 (180.5) MB, 0.8 / 0.0 ms  (+ 47.5 ms in 182 steps since start of marking, biggest step 2.2 ms, walltime since start of marking 6205 ms) finalize incremental marking via stack guard GC in old space requested
[44972:0x103000000] Memory allocator,   used: 184848 KB, available: 1281520 KB
[44972:0x103000000] New space,          used:    132 KB, available:  15979 KB, committed:  32768 KB
[44972:0x103000000] Old space,          used:  31403 KB, available:    247 KB, committed:  57856 KB
[44972:0x103000000] Code space,         used:   1202 KB, available:      0 KB, committed:   1536KB
[44972:0x103000000] Map space,          used:    232 KB, available:      0 KB, committed:    512 KB
[44972:0x103000000] Large object space, used:  90112 KB, available: 1280999 KB, committed:  90640 KB
[44972:0x103000000] All spaces,         used: 123083 KB, available: 1297226 KB, committed: 183312KB
[44972:0x103000000] External memory reported:      8 KB
[44972:0x103000000] Total time spent in GC  : 244.9 ms
[44972:0x103000000] Fast promotion mode: false survival rate: 0%
[44972:0x103000000]    36767 ms: Heap growing factor 1.2 based on mu=0.970, speed_ratio=205 (gc=4282851, mutator=20880)
[44972:0x103000000]    36767 ms: Grow: old size: 187626 KB, new limit: 238848 KB (1.2)
[44972:0x103000000]    36767 ms: Mark-sweep 223.8 (272.2) -> 183.2 (223.2) MB, 64.7 / 0.0 ms  (+ 81.4 ms in 199 steps since start of marking, biggest step 2.0 ms, walltime since start of marking 4243 ms) testing GC in old space requested
[44972:0x103000000] Memory allocator,   used: 228608 KB, available: 1237760 KB
[44972:0x103000000] New space,          used:     13 KB, available:  16098 KB, committed:  32768 KB
[44972:0x103000000] Old space,          used:   2694 KB, available:      0 KB, committed:   7680 KB
[44972:0x103000000] Code space,         used:   1196 KB, available:      0 KB, committed:   1536KB
[44972:0x103000000] Map space,          used:    232 KB, available:      0 KB, committed:    512 KB
[44972:0x103000000] Large object space, used: 183502 KB, available: 1237239 KB, committed: 184576 KB
[44972:0x103000000] All spaces,         used: 187639 KB, available: 1253338 KB, committed: 227072KB
[44972:0x103000000] External memory reported:      8 KB
[44972:0x103000000] Total time spent in GC  : 309.6 ms
[44972:0x103000000]    37824 ms: Memory reducer: low alloc, foreground
[44972:0x103000000]    37824 ms: Memory reducer: waiting for 8000 ms
[44972:0x103000000] Fast promotion mode: false survival rate: 99%
[44972:0x103000000]    44079 ms: Scavenge 338.1 (363.3) -> 338.1 (363.3) MB, 5.6 / 0.0 ms  allocation failure
[44972:0x103000000] Memory allocator,   used: 371984 KB, available: 1094384 KB
[44972:0x103000000] New space,          used:  16056 KB, available:     55 KB, committed:  32768 KB
[44972:0x103000000] Old space,          used:   2698 KB, available:    852 KB, committed:   7680 KB
[44972:0x103000000] Code space,         used:   1196 KB, available:    279 KB, committed:   1536KB
[44972:0x103000000] Map space,          used:    232 KB, available:    271 KB, committed:    512 KB
[44972:0x103000000] Large object space, used: 326044 KB, available: 1093863 KB, committed: 327952 KB
[44972:0x103000000] All spaces,         used: 346229 KB, available: 1095321 KB, committed: 370448KB
[44972:0x103000000] External memory reported:      8 KB
[44972:0x103000000] Total time spent in GC  : 315.2 ms
[44972:0x103000000] Fast promotion mode: false survival rate: 89%
[44972:0x103000000]    44093 ms: Scavenge 338.1 (363.3) -> 336.5 (400.3) MB, 14.0 / 0.0 ms  allocation failure
[44972:0x103000000] Memory allocator,   used: 409872 KB, available: 1056496 KB
[44972:0x103000000] New space,          used:      0 KB, available:  16112 KB, committed:  32768 KB
[44972:0x103000000] Old space,          used:  17051 KB, available:  23695 KB, committed:  45568 KB
[44972:0x103000000] Code space,         used:   1196 KB, available:    279 KB, committed:   1536KB
[44972:0x103000000] Map space,          used:    232 KB, available:    271 KB, committed:    512 KB
[44972:0x103000000] Large object space, used: 326044 KB, available: 1055975 KB, committed: 327952 KB
[44972:0x103000000] All spaces,         used: 344525 KB, available: 1096332 KB, committed: 408336KB
[44972:0x103000000] External memory reported:      8 KB
[44972:0x103000000] Total time spent in GC  : 329.2 ms
[44972:0x103000000] Fast promotion mode: false survival rate: 0%
[44972:0x103000000]    44123 ms: Heap growing factor 1.3 based on mu=0.970, speed_ratio=144 (gc=3510382, mutator=24420)
[44972:0x103000000]    44123 ms: Grow: old size: 362548 KB, new limit: 483871 KB (1.3)
[44972:0x103000000]    44123 ms: Mark-sweep 354.7 (418.0) -> 354.7 (414.0) MB, 1.1 / 0.0 ms  (+ 128.2 ms in 231 steps since start of marking, biggest step 2.1 ms, walltime since start of marking 6311 ms) finalize incremental marking via stack guard GC in old space requested
[44972:0x103000000] Memory allocator,   used: 423904 KB, available: 1042464 KB
[44972:0x103000000] New space,          used:    631 KB, available:  15480 KB, committed:  32768 KB
[44972:0x103000000] Old space,          used:  17051 KB, available:    346 KB, committed:  41472 KB
[44972:0x103000000] Code space,         used:   1196 KB, available:      0 KB, committed:   1536KB
[44972:0x103000000] Map space,          used:    232 KB, available:      0 KB, committed:    512 KB
[44972:0x103000000] Large object space, used: 344066 KB, available: 1041943 KB, committed: 346080 KB
[44972:0x103000000] All spaces,         used: 363179 KB, available: 1057770 KB, committed: 422368KB
[44972:0x103000000] External memory reported:      8 KB
[44972:0x103000000] Total time spent in GC  : 330.3 ms
[44972:0x103000000]    46167 ms: Memory reducer: low alloc, foreground
[44972:0x103000000]    46167 ms: Memory reducer: waiting for 5956 ms
[44972:0x103000000] Fast promotion mode: false survival rate: 99%
[44972:0x103000000]    51368 ms: Scavenge 491.3 (536.3) -> 491.3 (536.3) MB, 6.9 / 0.0 ms  allocation failure
[44972:0x103000000] Memory allocator,   used: 549152 KB, available: 917216 KB
[44972:0x103000000] New space,          used:  15425 KB, available:    686 KB, committed:  32768 KB
[44972:0x103000000] Old space,          used:  17629 KB, available:  16548 KB, committed:  41472 KB
[44972:0x103000000] Code space,         used:   1196 KB, available:    279 KB, committed:   1536KB
[44972:0x103000000] Map space,          used:    232 KB, available:    271 KB, committed:    512 KB
[44972:0x103000000] Large object space, used: 468586 KB, available: 916695 KB, committed: 471328 KB
[44972:0x103000000] All spaces,         used: 503069 KB, available: 934481 KB, committed: 547616KB
[44972:0x103000000] External memory reported:      8 KB
[44972:0x103000000] Total time spent in GC  : 337.2 ms
[44972:0x103000000] Fast promotion mode: false survival rate: 89%
[44972:0x103000000]    52444 ms: Scavenge 511.1 (555.6) -> 509.5 (584.1) MB, 13.8 / 0.0 ms  allocation failure
[44972:0x103000000] Memory allocator,   used: 598112 KB, available: 868256 KB
[44972:0x103000000] New space,          used:    631 KB, available:  15480 KB, committed:  32768 KB
[44972:0x103000000] Old space,          used:  31408 KB, available:  31468 KB, committed:  70656 KB
[44972:0x103000000] Code space,         used:   1196 KB, available:    279 KB, committed:   1536KB
[44972:0x103000000] Map space,          used:    232 KB, available:    271 KB, committed:    512 KB
[44972:0x103000000] Large object space, used: 488247 KB, available: 867735 KB, committed: 491104 KB
[44972:0x103000000] All spaces,         used: 521716 KB, available: 915234 KB, committed: 596576KB
[44972:0x103000000] External memory reported:      8 KB
[44972:0x103000000] Total time spent in GC  : 351.1 ms
[44972:0x103000000] Fast promotion mode: false survival rate: 0%
[44972:0x103000000]    52535 ms: Heap growing factor 1.2 based on mu=0.970, speed_ratio=187 (gc=4033404, mutator=21589)
[44972:0x103000000]    52535 ms: Grow: old size: 33398 KB, new limit: 57702 KB (1.2)
[44972:0x103000000]    52535 ms: Mark-sweep 529.4 (601.8) -> 34.9 (94.5) MB, 17.1 / 0.0 ms  (+ 196.7 ms in 134 steps since start of marking, biggest step 6.7 ms, walltime since start of marking 3306 ms) testing GC in old space requested
[44972:0x103000000] Memory allocator,   used:  96768 KB, available: 1369600 KB
[44972:0x103000000] New space,          used:   2338 KB, available:  13773 KB, committed:  32768 KB
[44972:0x103000000] Old space,          used:  31969 KB, available:   5622 KB, committed:  60416 KB
[44972:0x103000000] Code space,         used:   1196 KB, available:      0 KB, committed:   1536KB
[44972:0x103000000] Map space,          used:    232 KB, available:      0 KB, committed:    512 KB
[44972:0x103000000] Large object space, used:      0 KB, available: 1369079 KB, committed:      0 KB
[44972:0x103000000] All spaces,         used:  35736 KB, available: 1388475 KB, committed:  95232KB
[44972:0x103000000] External memory reported:      8 KB
[44972:0x103000000] Total time spent in GC  : 368.1 ms
[44972:0x103000000]    53569 ms: Memory reducer: low alloc, foreground
[44972:0x103000000]    53569 ms: Memory reducer: waiting for 8000 ms

## node--trace-gc-nvp.sh
$ node --expose-gc --trace-gc-nvp ./cases/leak-and-gc.js --normal
[44883:0x104000000]       23 ms: pause=0.8 mutator=261.3 gc=s reduce_memory=0 heap.prologue=0.00 heap.epilogue=0.00 heap.epilogue.reduce_new_space=0.00 heap.external.prologue=0.00 heap.external.epilogue=0.00 heap.external_weak_global_handles=0.00 scavenge=0.72 evacuate=0.00 old_new=0.41 weak=0.00 roots=0.10 code=0.00 semispace=0.21 steps_count=0 steps_took=0.0 scavenge_throughput=1226839 total_size_before=3197280 total_size_after=2807624 holes_size_before=1064 holes_size_after=1064 allocated=3197280 promoted=0 semi_space_copied=595432 nodes_died_in_new=7 nodes_copied_in_new=7 nodes_promoted=0 promotion_ratio=0.0% average_survival_ratio=60.4% promotion_rate=0.0% semi_space_copy_rate=60.4% new_space_allocation_throughput=0.0 context_disposal_rate=0.0
[44883:0x104000000]       34 ms: pause=1.1 mutator=10.6 gc=s reduce_memory=0 heap.prologue=0.00 heap.epilogue=0.00 heap.epilogue.reduce_new_space=0.00 heap.external.prologue=0.00 heap.external.epilogue=0.00 heap.external_weak_global_handles=0.00 scavenge=1.07 evacuate=0.00 old_new=0.62 weak=0.00 roots=0.11 code=0.00 semispace=0.32 steps_count=0 steps_took=0.0 scavenge_throughput=1047087 total_size_before=3511784 total_size_after=3468624 holes_size_before=1048 holes_size_after=1088 allocated=704160 promoted=594088 semi_space_copied=378584 nodes_died_in_new=19 nodes_copied_in_new=6 nodes_promoted=6 promotion_ratio=58.5% average_survival_ratio=78.1% promotion_rate=99.8% semi_space_copy_rate=37.3% new_space_allocation_throughput=39509.0 context_disposal_rate=0.0
[44883:0x104000000]       57 ms: pause=0.9 mutator=21.4 gc=s reduce_memory=0 heap.prologue=0.08 heap.epilogue=0.00 heap.epilogue.reduce_new_space=0.00 heap.external.prologue=0.00 heap.external.epilogue=0.00 heap.external_weak_global_handles=0.01 scavenge=0.76 evacuate=0.00 old_new=0.50 weak=0.00 roots=0.04 code=0.00 semispace=0.21 steps_count=0 steps_took=0.0 scavenge_throughput=1083362 total_size_before=4577192 total_size_after=4323728 holes_size_before=1088 holes_size_after=33176 allocated=1108568 promoted=376576 semi_space_copied=390472 nodes_died_in_new=17 nodes_copied_in_new=4 nodes_promoted=5 promotion_ratio=36.9% average_survival_ratio=77.1% promotion_rate=99.5% semi_space_copy_rate=38.3% new_space_allocation_throughput=33137.8 context_disposal_rate=0.0
[44883:0x104000000]     1077 ms: pause=1.4 mutator=1019.0 gc=s reduce_memory=0 heap.prologue=0.00 heap.epilogue=0.00 heap.epilogue.reduce_new_space=0.00 heap.external.prologue=0.00 heap.external.epilogue=0.00 heap.external_weak_global_handles=0.00 scavenge=1.35 evacuate=0.00 old_new=0.39 weak=0.00 roots=0.03 code=0.00 semispace=0.91 steps_count=0 steps_took=0.0 scavenge_throughput=1200500 total_size_before=6161128 total_size_after=6101504 holes_size_before=1608 holes_size_after=1608 allocated=1837400 promoted=329208 semi_space_copied=1469536 nodes_died_in_new=6 nodes_copied_in_new=3 nodes_promoted=3 promotion_ratio=16.4% average_survival_ratio=80.3% promotion_rate=84.3% semi_space_copy_rate=73.2% new_space_allocation_throughput=2547.9 context_disposal_rate=0.0
[44883:0x104000000]     1079 ms: pause=1.7 mutator=0.4 gc=s reduce_memory=0 heap.prologue=0.16 heap.epilogue=0.00 heap.epilogue.reduce_new_space=0.00 heap.external.prologue=0.00 heap.external.epilogue=0.00 heap.external_weak_global_handles=0.00 scavenge=1.55 evacuate=0.00 old_new=0.16 weak=0.00 roots=0.02 code=0.00 semispace=1.36 steps_count=0 steps_took=0.0 scavenge_throughput=1190355 total_size_before=6495008 total_size_after=6347176 holes_size_before=1608 holes_size_after=139208 allocated=393504 promoted=1469536 semi_space_copied=393504 nodes_died_in_new=0 nodes_copied_in_new=0 nodes_promoted=3 promotion_ratio=73.1% average_survival_ratio=82.7% promotion_rate=100.0% semi_space_copy_rate=19.6% new_space_allocation_throughput=2921.3 context_disposal_rate=0.0
[44883:0x104000000]     2113 ms: pause=2.3 mutator=1031.1 gc=s reduce_memory=0 heap.prologue=0.00 heap.epilogue=0.01 heap.epilogue.reduce_new_space=0.00 heap.external.prologue=0.00 heap.external.epilogue=0.00 heap.external_weak_global_handles=0.00 scavenge=2.30 evacuate=0.00 old_new=0.03 weak=0.00 roots=0.01 code=0.00 semispace=2.24 steps_count=0 steps_took=0.0 scavenge_throughput=1347415 total_size_before=30182856 total_size_after=30182824 holes_size_before=139208 holes_size_after=169432 allocated=23835680 promoted=393504 semi_space_copied=3280584 nodes_died_in_new=0 nodes_copied_in_new=0 nodes_promoted=0 promotion_ratio=9.7% average_survival_ratio=84.0% promotion_rate=100.0% semi_space_copy_rate=80.6% new_space_allocation_throughput=3239.3 context_disposal_rate=0.0
[44883:0x104000000]     2116 ms: pause=3.1 mutator=0.4 gc=s reduce_memory=0 heap.prologue=0.30 heap.epilogue=0.00 heap.epilogue.reduce_new_space=0.00 heap.external.prologue=0.00 heap.external.epilogue=0.00 heap.external_weak_global_handles=0.00 scavenge=2.75 evacuate=0.00 old_new=0.02 weak=0.00 roots=0.01 code=0.00 semispace=2.71 steps_count=0 steps_took=0.0 scavenge_throughput=1340183 total_size_before=30576328 total_size_after=30182272 holes_size_before=169432 holes_size_after=563488 allocated=393504 promoted=3280584 semi_space_copied=393504 nodes_died_in_new=0 nodes_copied_in_new=0 nodes_promoted=0 promotion_ratio=80.6% average_survival_ratio=84.9% promotion_rate=100.0% semi_space_copy_rate=9.7% new_space_allocation_throughput=3427.7 context_disposal_rate=0.0
[44883:0x104000000]     5190 ms: pause=4.5 mutator=3069.3 gc=s reduce_memory=0 heap.prologue=0.00 heap.epilogue=0.01 heap.epilogue.reduce_new_space=0.00 heap.external.prologue=0.00 heap.external.epilogue=0.00 heap.external_weak_global_handles=0.00 scavenge=4.52 evacuate=0.00 old_new=0.03 weak=0.00 roots=0.01 code=0.00 semispace=4.44 steps_count=0 steps_took=0.0 scavenge_throughput=1472354 total_size_before=98392992 total_size_after=98392960 holes_size_before=563488 holes_size_after=610752 allocated=68210720 promoted=393504 semi_space_copied=6955856 nodes_died_in_new=0 nodes_copied_in_new=0 nodes_promoted=0 promotion_ratio=4.8% average_survival_ratio=85.5% promotion_rate=100.0% semi_space_copy_rate=84.9% new_space_allocation_throughput=2899.5 context_disposal_rate=0.0
[44883:0x104000000]     5197 ms: pause=6.7 mutator=0.3 gc=s reduce_memory=0 heap.prologue=0.62 heap.epilogue=0.01 heap.epilogue.reduce_new_space=0.00 heap.external.prologue=0.00 heap.external.epilogue=0.00 heap.external_weak_global_handles=0.00 scavenge=6.01 evacuate=0.00 old_new=0.03 weak=0.00 roots=0.01 code=0.00 semispace=5.94 steps_count=0 steps_took=0.0 scavenge_throughput=1399147 total_size_before=98786464 total_size_after=97943008 holes_size_before=610752 holes_size_after=1454208 allocated=393504 promoted=6955856 semi_space_copied=393504 nodes_died_in_new=0 nodes_copied_in_new=0 nodes_promoted=0 promotion_ratio=84.9% average_survival_ratio=86.0% promotion_rate=100.0% semi_space_copy_rate=4.8% new_space_allocation_throughput=2975.7 context_disposal_rate=0.0
[44883:0x104000000]    11430 ms: pause=7.9 mutator=6225.1 gc=s reduce_memory=0 heap.prologue=0.00 heap.epilogue=0.01 heap.epilogue.reduce_new_space=0.00 heap.external.prologue=0.00 heap.external.epilogue=0.00 heap.external_weak_global_handles=0.00 scavenge=7.82 evacuate=0.00 old_new=0.07 weak=0.00 roots=0.01 code=0.00 semispace=7.68 steps_count=0 steps_took=0.0 scavenge_throughput=1578051 total_size_before=216333720 total_size_after=216333688 holes_size_before=1454208 holes_size_after=2148480 allocated=118390712 promoted=393504 semi_space_copied=14304848 nodes_died_in_new=0 nodes_copied_in_new=0 nodes_promoted=0 promotion_ratio=2.4% average_survival_ratio=86.3% promotion_rate=100.0% semi_space_copy_rate=87.0% new_space_allocation_throughput=2758.2 context_disposal_rate=0.0
[44883:0x104000000]    11445 ms: pause=12.7 mutator=1.8 gc=s reduce_memory=0 heap.prologue=0.00 heap.epilogue=0.02 heap.epilogue.reduce_new_space=0.00 heap.external.prologue=0.00 heap.external.epilogue=0.00 heap.external_weak_global_handles=0.00 scavenge=12.72 evacuate=0.00 old_new=0.05 weak=0.00 roots=0.01 code=0.00 semispace=12.62 steps_count=0 steps_took=0.0 scavenge_throughput=1498070 total_size_before=216727192 total_size_after=214983384 holes_size_before=2148480 holes_size_after=25481264 allocated=393504 promoted=14304848 semi_space_copied=393504 nodes_died_in_new=0 nodes_copied_in_new=0 nodes_promoted=0 promotion_ratio=87.0% average_survival_ratio=89.2% promotion_rate=100.0% semi_space_copy_rate=2.4% new_space_allocation_throughput=2792.3 context_disposal_rate=0.0
[44883:0x104000000]    11447 ms: pause=0.9 mutator=1.7 gc=ms reduce_memory=0 heap.prologue=0.00 heap.epilogue=0.00 heap.epilogue.reduce_new_space=0.00 heap.external.prologue=0.0 heap.external.epilogue=0.0 heap.external.weak_global_handles=0.0 clear=0 clear.code_flush=0.0 clear.dependent_code=0.0 clear.maps=0.0 clear.slots_buffer=0.0 clear.store_buffer=0.0 clear.string_table=0.0 clear.weak_cells=0.0 clear.weak_collections=0.0 clear.weak_lists=0.0 epilogue=0.0 evacuate=0.6 evacuate.candidates=0.0 evacuate.clean_up=0.0 evacuate.copy=0.4 evacuate.prologue=0.0 evacuate.epilogue=0.0 evacuate.rebalance=0.0 evacuate.update_pointers=0.1 evacuate.update_pointers.to_evacuated=0.0 evacuate.update_pointers.to_new=0.1 evacuate.update_pointers.weak=0.0 finish=0.1 mark=0.1 mark.finish_incremental=0.0 mark.prepare_code_flush=0.0 mark.roots=0.1 mark.weak_closure=0.0 mark.weak_closure.ephemeral=0.0 mark.weak_closure.weak_handles=0.0 mark.weak_closure.weak_roots=0.0 mark.weak_closure.harmony=0.0 mark.wrapper_prologue=0.0 mark.wrapper_epilogue=0.0 mark.wrapper_tracing=0.0 prologue=0.0 sweep=0.0 sweep.code=0.0 sweep.map=0.0 sweep.old=0.0 incremental=78.6 incremental.finalize=0.1 incremental.finalize.body=0.1 incremental.finalize.external.prologue=0.0 incremental.finalize.external.epilogue=0.0 incremental.sweeping=0.0 incremental.wrapper_prologue=0.0 incremental.wrapper_tracing=0.0 incremental_wrapper_tracing_longest_step=0.0 incremental_finalize_longest_step=0.1 incremental_finalize_steps_count=1 incremental_longest_step=1.5 incremental_steps_count=239 incremental_marking_throughput=5053423 incremental_walltime_duration=6250 total_size_before=215367800 total_size_after=214890856 holes_size_before=25481264 holes_size_after=56528 allocated=384416 promoted=393504 semi_space_copied=327920 nodes_died_in_new=0 nodes_copied_in_new=0 nodes_promoted=0 promotion_ratio=50.6% average_survival_ratio=88.9% promotion_rate=100.0% semi_space_copy_rate=42.2% new_space_allocation_throughput=2791.3 context_disposal_rate=0.0 compaction_speed=1790134
[44883:0x104000000]    17678 ms: pause=112.8 mutator=6118.2 gc=ms reduce_memory=0 heap.prologue=0.00 heap.epilogue=0.00 heap.epilogue.reduce_new_space=0.00 heap.external.prologue=0.0 heap.external.epilogue=0.0 heap.external.weak_global_handles=0.0 clear=0 clear.code_flush=0.0 clear.dependent_code=0.0 clear.maps=0.0 clear.slots_buffer=0.0 clear.store_buffer=0.0 clear.string_table=0.0 clear.weak_cells=0.1 clear.weak_collections=0.0 clear.weak_lists=0.0 epilogue=0.0 evacuate=0.4 evacuate.candidates=0.0 evacuate.clean_up=0.0 evacuate.copy=0.2 evacuate.prologue=0.0 evacuate.epilogue=0.0 evacuate.rebalance=0.0 evacuate.update_pointers=0.2 evacuate.update_pointers.to_evacuated=0.0 evacuate.update_pointers.to_new=0.1 evacuate.update_pointers.weak=0.0 finish=0.1 mark=111.8 mark.finish_incremental=0.0 mark.prepare_code_flush=0.0 mark.roots=111.8 mark.weak_closure=0.0 mark.weak_closure.ephemeral=0.0 mark.weak_closure.weak_handles=0.0 mark.weak_closure.weak_roots=0.0 mark.weak_closure.harmony=0.0 mark.wrapper_prologue=0.0 mark.wrapper_epilogue=0.0 mark.wrapper_tracing=0.0 prologue=0.0 sweep=0.1 sweep.code=0.0 sweep.map=0.0 sweep.old=0.1 incremental=0.0 incremental.finalize=0.0 incremental.finalize.body=0.0 incremental.finalize.external.prologue=0.0 incremental.finalize.external.epilogue=0.0 incremental.sweeping=0.0 incremental.wrapper_prologue=0.0 incremental.wrapper_tracing=0.0 incremental_wrapper_tracing_longest_step=0.0 incremental_finalize_longest_step=0.0 incremental_finalize_steps_count=0 incremental_longest_step=0.0 incremental_steps_count=0 incremental_marking_throughput=5053423 incremental_walltime_duration=0 total_size_before=337079848 total_size_after=294503312 holes_size_before=1048 holes_size_after=0 allocated=122188992 promoted=0 semi_space_copied=3912 nodes_died_in_new=0 nodes_copied_in_new=0 nodes_promoted=0 promotion_ratio=0.0% average_survival_ratio=81.4% promotion_rate=0.0% semi_space_copy_rate=0.0% new_space_allocation_throughput=2628.3 context_disposal_rate=0.0 compaction_speed=1018730

## node--trace-gc.sh
$ node --expose-gc --trace-gc ./cases/leak-and-gc.js --normal
  [44743:0x103800000]       61 ms: Scavenge 3.0 (5.5) -> 2.7 (6.5) MB, 1.1 / 0.0 ms  allocation failure
  [44743:0x103800000]       74 ms: Scavenge 3.3 (7.0) -> 3.3 (7.5) MB, 1.0 / 0.0 ms  allocation failure
  [44743:0x103800000]       98 ms: Scavenge 4.4 (7.5) -> 4.1 (10.0) MB, 1.2 / 0.0 ms  allocation failure
  [44743:0x103800000]     1124 ms: Scavenge 5.9 (10.5) -> 5.8 (10.5) MB, 1.4 / 0.0 ms  allocation failure
  [44743:0x103800000]     2165 ms: Scavenge 30.2 (34.6) -> 30.1 (40.1) MB, 1.8 / 0.0 ms  allocation failure
  [44743:0x103800000]     3188 ms: Scavenge 51.2 (57.8) -> 51.2 (58.3) MB, 2.5 / 0.0 ms  allocation failure
  [44743:0x103800000]     3191 ms: Scavenge 51.6 (58.3) -> 51.2 (69.8) MB, 3.3 / 0.0 ms  allocation failure
  [44743:0x103800000]     6260 ms: Scavenge 119.4 (131.0) -> 119.4 (131.5) MB, 5.4 / 0.0 ms  allocation failure
  [44743:0x103800000]     6269 ms: Scavenge 119.8 (131.5) -> 119.0 (165.0) MB, 7.8 / 0.0 ms  allocation failure
  [44743:0x103800000]    14619 ms: Scavenge 262.3 (293.8) -> 262.3 (294.8) MB, 8.8 / 0.0 ms  allocation failure
  [44743:0x103800000]    14634 ms: Scavenge 262.7 (294.8) -> 261.0 (331.2) MB, 12.4 / 0.0 ms  allocation failure
  [44743:0x103800000]    14637 ms: Mark-sweep 261.1 (331.2) -> 260.7 (331.2) MB, 3.4 / 0.0 ms  (+ 119.3 ms in 282 steps since start of marking, biggest step 2.7 ms, walltime since start of marking 8377 ms) finalize incremental marking via stack guard GC in old space requested
  [44743:0x103800000]    19869 ms: Mark-sweep 371.2 (429.4) -> 332.1 (371.4) MB, 124.7 / 0.0 ms  testing GC in old space requested
  [44743:0x103800000]    28193 ms: Scavenge 471.0 (495.3) -> 471.0 (495.3) MB, 4.4 / 0.0 ms  allocation failure
  [44743:0x103800000]    28207 ms: Scavenge 471.0 (495.3) -> 469.3 (532.8) MB, 13.9 / 0.0 ms  allocation failure
  [44743:0x103800000]    30300 ms: Mark-sweep 520.4 (577.9) -> 24.4 (78.5) MB, 13.4 / 0.0 ms  (+ 175.4 ms in 243 steps since start of marking, biggest step 6.5 ms, walltime since start of marking 6341 ms) testing GC in old space requested
  [44743:0x103800000]    34403 ms: Scavenge 73.7 (118.7) -> 73.0 (125.2) MB, 12.8 / 0.0 ms  allocation failure
  [44743:0x103800000]    36469 ms: Scavenge 105.0 (151.0) -> 104.0 (173.0) MB, 10.9 / 0.0 ms  allocation failure
  [44743:0x103800000]    37549 ms: Mark-sweep 129.4 (193.9) -> 90.4 (129.9) MB, 34.8 / 0.0 ms  (+ 46.6 ms in 198 steps since start of marking, biggest step 2.4 ms, walltime since start of marking 6216 ms) testing GC in old space requested
  [44743:0x103800000]    44851 ms: Mark-sweep 250.0 (274.8) -> 250.1 (270.8) MB, 3.7 / 0.0 ms  (+ 92.5 ms in 274 steps since start of marking, biggest step 2.1 ms, walltime since start of marking 7278 ms) finalize incremental marking via stack guard GC in old space requested
  [44743:0x103800000]    44862 ms: Scavenge 250.1 (270.8) -> 248.4 (286.2) MB, 11.4 / 0.0 ms  allocation failure
  [44743:0x103800000]    51147 ms: Scavenge 379.3 (402.1) -> 379.3 (402.1) MB, 3.4 / 0.0 ms  allocation failure
  [44743:0x103800000]    51160 ms: Scavenge 379.3 (402.1) -> 377.6 (439.6) MB, 12.9 / 0.0 ms  allocation failure
  [44743:0x103800000]    51177 ms: Mark-sweep 389.1 (450.9) -> 389.1 (450.9) MB, 0.8 / 0.0 ms  (+ 134.8 ms in 239 steps since start of marking, biggest step 2.4 ms, walltime since start of marking 5271 ms) finalize incremental marking via stack guard GC in old space requested
  [44743:0x103800000]    56428 ms: Mark-sweep 480.5 (531.4) -> 440.8 (479.4) MB, 162.3 / 0.0 ms  testing GC in old space requested
  [44743:0x103800000]    59495 ms: Mark-sweep 509.6 (540.5) -> 12.0 (39.0) MB, 7.4 / 0.0 ms  testing GC in old space requested
  [44743:0x103800000]    63588 ms: Scavenge 61.3 (80.8) -> 60.4 (99.3) MB, 14.9 / 0.0 ms  allocation failure
  [44743:0x103800000]    66707 ms: Scavenge 137.2 (168.5) -> 136.4 (187.0) MB, 11.5 / 0.0 ms  allocation failure
	$ node --expose-gc --trace-gc-nvp ./cases/leak-and-gc.js --normal
	[44883:0x104000000] 23 ms: pause=0.8 mutator=261.3 gc=s reduce_memory=0 heap.prologue=0.00 heap.epilogue=0.00 heap.epilogue.reduce_new_space=0.00 heap.external.prologue=0.00 heap.external.epilogue=0.00 heap.external_weak_global_handles=0.00 scavenge=0.72 evacuate=0.00 old_new=0.41 weak=0.00 roots=0.10 code=0.00 semispace=0.21 steps_count=0 steps_took=0.0 scavenge_throughput=1226839 total_size_before=3197280 total_size_after=2807624 holes_size_before=1064 holes_size_after=1064 allocated=3197280 promoted=0 semi_space_copied=595432 nodes_died_in_new=7 nodes_copied_in_new=7 nodes_promoted=0 promotion_ratio=0.0% average_survival_ratio=60.4% promotion_rate=0.0% semi_space_copy_rate=60.4% new_space_allocation_throughput=0.0 context_disposal_rate=0.0
	[44883:0x104000000] 34 ms: pause=1.1 mutator=10.6 gc=s reduce_memory=0 heap.prologue=0.00 heap.epilogue=0.00 heap.epilogue.reduce_new_space=0.00 heap.external.prologue=0.00 heap.external.epilogue=0.00 heap.external_weak_global_handles=0.00 scavenge=1.07 evacuate=0.00 old_new=0.62 weak=0.00 roots=0.11 code=0.00 semispace=0.32 steps_count=0 steps_took=0.0 scavenge_throughput=1047087 total_size_before=3511784 total_size_after=3468624 holes_size_before=1048 holes_size_after=1088 allocated=704160 promoted=594088 semi_space_copied=378584 nodes_died_in_new=19 nodes_copied_in_new=6 nodes_promoted=6 promotion_ratio=58.5% average_survival_ratio=78.1% promotion_rate=99.8% semi_space_copy_rate=37.3% new_space_allocation_throughput=39509.0 context_disposal_rate=0.0
	[44883:0x104000000] 57 ms: pause=0.9 mutator=21.4 gc=s reduce_memory=0 heap.prologue=0.08 heap.epilogue=0.00 heap.epilogue.reduce_new_space=0.00 heap.external.prologue=0.00 heap.external.epilogue=0.00 heap.external_weak_global_handles=0.01 scavenge=0.76 evacuate=0.00 old_new=0.50 weak=0.00 roots=0.04 code=0.00 semispace=0.21 steps_count=0 steps_took=0.0 scavenge_throughput=1083362 total_size_before=4577192 total_size_after=4323728 holes_size_before=1088 holes_size_after=33176 allocated=1108568 promoted=376576 semi_space_copied=390472 nodes_died_in_new=17 nodes_copied_in_new=4 nodes_promoted=5 promotion_ratio=36.9% average_survival_ratio=77.1% promotion_rate=99.5% semi_space_copy_rate=38.3% new_space_allocation_throughput=33137.8 context_disposal_rate=0.0
	[44883:0x104000000] 1077 ms: pause=1.4 mutator=1019.0 gc=s reduce_memory=0 heap.prologue=0.00 heap.epilogue=0.00 heap.epilogue.reduce_new_space=0.00 heap.external.prologue=0.00 heap.external.epilogue=0.00 heap.external_weak_global_handles=0.00 scavenge=1.35 evacuate=0.00 old_new=0.39 weak=0.00 roots=0.03 code=0.00 semispace=0.91 steps_count=0 steps_took=0.0 scavenge_throughput=1200500 total_size_before=6161128 total_size_after=6101504 holes_size_before=1608 holes_size_after=1608 allocated=1837400 promoted=329208 semi_space_copied=1469536 nodes_died_in_new=6 nodes_copied_in_new=3 nodes_promoted=3 promotion_ratio=16.4% average_survival_ratio=80.3% promotion_rate=84.3% semi_space_copy_rate=73.2% new_space_allocation_throughput=2547.9 context_disposal_rate=0.0
	[44883:0x104000000] 1079 ms: pause=1.7 mutator=0.4 gc=s reduce_memory=0 heap.prologue=0.16 heap.epilogue=0.00 heap.epilogue.reduce_new_space=0.00 heap.external.prologue=0.00 heap.external.epilogue=0.00 heap.external_weak_global_handles=0.00 scavenge=1.55 evacuate=0.00 old_new=0.16 weak=0.00 roots=0.02 code=0.00 semispace=1.36 steps_count=0 steps_took=0.0 scavenge_throughput=1190355 total_size_before=6495008 total_size_after=6347176 holes_size_before=1608 holes_size_after=139208 allocated=393504 promoted=1469536 semi_space_copied=393504 nodes_died_in_new=0 nodes_copied_in_new=0 nodes_promoted=3 promotion_ratio=73.1% average_survival_ratio=82.7% promotion_rate=100.0% semi_space_copy_rate=19.6% new_space_allocation_throughput=2921.3 context_disposal_rate=0.0
	[44883:0x104000000] 2113 ms: pause=2.3 mutator=1031.1 gc=s reduce_memory=0 heap.prologue=0.00 heap.epilogue=0.01 heap.epilogue.reduce_new_space=0.00 heap.external.prologue=0.00 heap.external.epilogue=0.00 heap.external_weak_global_handles=0.00 scavenge=2.30 evacuate=0.00 old_new=0.03 weak=0.00 roots=0.01 code=0.00 semispace=2.24 steps_count=0 steps_took=0.0 scavenge_throughput=1347415 total_size_before=30182856 total_size_after=30182824 holes_size_before=139208 holes_size_after=169432 allocated=23835680 promoted=393504 semi_space_copied=3280584 nodes_died_in_new=0 nodes_copied_in_new=0 nodes_promoted=0 promotion_ratio=9.7% average_survival_ratio=84.0% promotion_rate=100.0% semi_space_copy_rate=80.6% new_space_allocation_throughput=3239.3 context_disposal_rate=0.0
	[44883:0x104000000] 2116 ms: pause=3.1 mutator=0.4 gc=s reduce_memory=0 heap.prologue=0.30 heap.epilogue=0.00 heap.epilogue.reduce_new_space=0.00 heap.external.prologue=0.00 heap.external.epilogue=0.00 heap.external_weak_global_handles=0.00 scavenge=2.75 evacuate=0.00 old_new=0.02 weak=0.00 roots=0.01 code=0.00 semispace=2.71 steps_count=0 steps_took=0.0 scavenge_throughput=1340183 total_size_before=30576328 total_size_after=30182272 holes_size_before=169432 holes_size_after=563488 allocated=393504 promoted=3280584 semi_space_copied=393504 nodes_died_in_new=0 nodes_copied_in_new=0 nodes_promoted=0 promotion_ratio=80.6% average_survival_ratio=84.9% promotion_rate=100.0% semi_space_copy_rate=9.7% new_space_allocation_throughput=3427.7 context_disposal_rate=0.0
	[44883:0x104000000] 5190 ms: pause=4.5 mutator=3069.3 gc=s reduce_memory=0 heap.prologue=0.00 heap.epilogue=0.01 heap.epilogue.reduce_new_space=0.00 heap.external.prologue=0.00 heap.external.epilogue=0.00 heap.external_weak_global_handles=0.00 scavenge=4.52 evacuate=0.00 old_new=0.03 weak=0.00 roots=0.01 code=0.00 semispace=4.44 steps_count=0 steps_took=0.0 scavenge_throughput=1472354 total_size_before=98392992 total_size_after=98392960 holes_size_before=563488 holes_size_after=610752 allocated=68210720 promoted=393504 semi_space_copied=6955856 nodes_died_in_new=0 nodes_copied_in_new=0 nodes_promoted=0 promotion_ratio=4.8% average_survival_ratio=85.5% promotion_rate=100.0% semi_space_copy_rate=84.9% new_space_allocation_throughput=2899.5 context_disposal_rate=0.0
	[44883:0x104000000] 5197 ms: pause=6.7 mutator=0.3 gc=s reduce_memory=0 heap.prologue=0.62 heap.epilogue=0.01 heap.epilogue.reduce_new_space=0.00 heap.external.prologue=0.00 heap.external.epilogue=0.00 heap.external_weak_global_handles=0.00 scavenge=6.01 evacuate=0.00 old_new=0.03 weak=0.00 roots=0.01 code=0.00 semispace=5.94 steps_count=0 steps_took=0.0 scavenge_throughput=1399147 total_size_before=98786464 total_size_after=97943008 holes_size_before=610752 holes_size_after=1454208 allocated=393504 promoted=6955856 semi_space_copied=393504 nodes_died_in_new=0 nodes_copied_in_new=0 nodes_promoted=0 promotion_ratio=84.9% average_survival_ratio=86.0% promotion_rate=100.0% semi_space_copy_rate=4.8% new_space_allocation_throughput=2975.7 context_disposal_rate=0.0
	[44883:0x104000000] 11430 ms: pause=7.9 mutator=6225.1 gc=s reduce_memory=0 heap.prologue=0.00 heap.epilogue=0.01 heap.epilogue.reduce_new_space=0.00 heap.external.prologue=0.00 heap.external.epilogue=0.00 heap.external_weak_global_handles=0.00 scavenge=7.82 evacuate=0.00 old_new=0.07 weak=0.00 roots=0.01 code=0.00 semispace=7.68 steps_count=0 steps_took=0.0 scavenge_throughput=1578051 total_size_before=216333720 total_size_after=216333688 holes_size_before=1454208 holes_size_after=2148480 allocated=118390712 promoted=393504 semi_space_copied=14304848 nodes_died_in_new=0 nodes_copied_in_new=0 nodes_promoted=0 promotion_ratio=2.4% average_survival_ratio=86.3% promotion_rate=100.0% semi_space_copy_rate=87.0% new_space_allocation_throughput=2758.2 context_disposal_rate=0.0
	[44883:0x104000000] 11445 ms: pause=12.7 mutator=1.8 gc=s reduce_memory=0 heap.prologue=0.00 heap.epilogue=0.02 heap.epilogue.reduce_new_space=0.00 heap.external.prologue=0.00 heap.external.epilogue=0.00 heap.external_weak_global_handles=0.00 scavenge=12.72 evacuate=0.00 old_new=0.05 weak=0.00 roots=0.01 code=0.00 semispace=12.62 steps_count=0 steps_took=0.0 scavenge_throughput=1498070 total_size_before=216727192 total_size_after=214983384 holes_size_before=2148480 holes_size_after=25481264 allocated=393504 promoted=14304848 semi_space_copied=393504 nodes_died_in_new=0 nodes_copied_in_new=0 nodes_promoted=0 promotion_ratio=87.0% average_survival_ratio=89.2% promotion_rate=100.0% semi_space_copy_rate=2.4% new_space_allocation_throughput=2792.3 context_disposal_rate=0.0
	[44883:0x104000000] 11447 ms: pause=0.9 mutator=1.7 gc=ms reduce_memory=0 heap.prologue=0.00 heap.epilogue=0.00 heap.epilogue.reduce_new_space=0.00 heap.external.prologue=0.0 heap.external.epilogue=0.0 heap.external.weak_global_handles=0.0 clear=0 clear.code_flush=0.0 clear.dependent_code=0.0 clear.maps=0.0 clear.slots_buffer=0.0 clear.store_buffer=0.0 clear.string_table=0.0 clear.weak_cells=0.0 clear.weak_collections=0.0 clear.weak_lists=0.0 epilogue=0.0 evacuate=0.6 evacuate.candidates=0.0 evacuate.clean_up=0.0 evacuate.copy=0.4 evacuate.prologue=0.0 evacuate.epilogue=0.0 evacuate.rebalance=0.0 evacuate.update_pointers=0.1 evacuate.update_pointers.to_evacuated=0.0 evacuate.update_pointers.to_new=0.1 evacuate.update_pointers.weak=0.0 finish=0.1 mark=0.1 mark.finish_incremental=0.0 mark.prepare_code_flush=0.0 mark.roots=0.1 mark.weak_closure=0.0 mark.weak_closure.ephemeral=0.0 mark.weak_closure.weak_handles=0.0 mark.weak_closure.weak_roots=0.0 mark.weak_closure.harmony=0.0 mark.wrapper_prologue=0.0 mark.wrapper_epilogue=0.0 mark.wrapper_tracing=0.0 prologue=0.0 sweep=0.0 sweep.code=0.0 sweep.map=0.0 sweep.old=0.0 incremental=78.6 incremental.finalize=0.1 incremental.finalize.body=0.1 incremental.finalize.external.prologue=0.0 incremental.finalize.external.epilogue=0.0 incremental.sweeping=0.0 incremental.wrapper_prologue=0.0 incremental.wrapper_tracing=0.0 incremental_wrapper_tracing_longest_step=0.0 incremental_finalize_longest_step=0.1 incremental_finalize_steps_count=1 incremental_longest_step=1.5 incremental_steps_count=239 incremental_marking_throughput=5053423 incremental_walltime_duration=6250 total_size_before=215367800 total_size_after=214890856 holes_size_before=25481264 holes_size_after=56528 allocated=384416 promoted=393504 semi_space_copied=327920 nodes_died_in_new=0 nodes_copied_in_new=0 nodes_promoted=0 promotion_ratio=50.6% average_survival_ratio=88.9% promotion_rate=100.0% semi_space_copy_rate=42.2% new_space_allocation_throughput=2791.3 context_disposal_rate=0.0 compaction_speed=1790134
	[44883:0x104000000] 17678 ms: pause=112.8 mutator=6118.2 gc=ms reduce_memory=0 heap.prologue=0.00 heap.epilogue=0.00 heap.epilogue.reduce_new_space=0.00 heap.external.prologue=0.0 heap.external.epilogue=0.0 heap.external.weak_global_handles=0.0 clear=0 clear.code_flush=0.0 clear.dependent_code=0.0 clear.maps=0.0 clear.slots_buffer=0.0 clear.store_buffer=0.0 clear.string_table=0.0 clear.weak_cells=0.1 clear.weak_collections=0.0 clear.weak_lists=0.0 epilogue=0.0 evacuate=0.4 evacuate.candidates=0.0 evacuate.clean_up=0.0 evacuate.copy=0.2 evacuate.prologue=0.0 evacuate.epilogue=0.0 evacuate.rebalance=0.0 evacuate.update_pointers=0.2 evacuate.update_pointers.to_evacuated=0.0 evacuate.update_pointers.to_new=0.1 evacuate.update_pointers.weak=0.0 finish=0.1 mark=111.8 mark.finish_incremental=0.0 mark.prepare_code_flush=0.0 mark.roots=111.8 mark.weak_closure=0.0 mark.weak_closure.ephemeral=0.0 mark.weak_closure.weak_handles=0.0 mark.weak_closure.weak_roots=0.0 mark.weak_closure.harmony=0.0 mark.wrapper_prologue=0.0 mark.wrapper_epilogue=0.0 mark.wrapper_tracing=0.0 prologue=0.0 sweep=0.1 sweep.code=0.0 sweep.map=0.0 sweep.old=0.1 incremental=0.0 incremental.finalize=0.0 incremental.finalize.body=0.0 incremental.finalize.external.prologue=0.0 incremental.finalize.external.epilogue=0.0 incremental.sweeping=0.0 incremental.wrapper_prologue=0.0 incremental.wrapper_tracing=0.0 incremental_wrapper_tracing_longest_step=0.0 incremental_finalize_longest_step=0.0 incremental_finalize_steps_count=0 incremental_longest_step=0.0 incremental_steps_count=0 incremental_marking_throughput=5053423 incremental_walltime_duration=0 total_size_before=337079848 total_size_after=294503312 holes_size_before=1048 holes_size_after=0 allocated=122188992 promoted=0 semi_space_copied=3912 nodes_died_in_new=0 nodes_copied_in_new=0 nodes_promoted=0 promotion_ratio=0.0% average_survival_ratio=81.4% promotion_rate=0.0% semi_space_copy_rate=0.0% new_space_allocation_throughput=2628.3 context_disposal_rate=0.0 compaction_speed=1018730
	$ node --expose-gc --trace-gc ./cases/leak-and-gc.js --normal
	[44743:0x103800000] 61 ms: Scavenge 3.0 (5.5) -> 2.7 (6.5) MB, 1.1 / 0.0 ms allocation failure
	[44743:0x103800000] 74 ms: Scavenge 3.3 (7.0) -> 3.3 (7.5) MB, 1.0 / 0.0 ms allocation failure
	[44743:0x103800000] 98 ms: Scavenge 4.4 (7.5) -> 4.1 (10.0) MB, 1.2 / 0.0 ms allocation failure
	[44743:0x103800000] 1124 ms: Scavenge 5.9 (10.5) -> 5.8 (10.5) MB, 1.4 / 0.0 ms allocation failure
	[44743:0x103800000] 2165 ms: Scavenge 30.2 (34.6) -> 30.1 (40.1) MB, 1.8 / 0.0 ms allocation failure
	[44743:0x103800000] 3188 ms: Scavenge 51.2 (57.8) -> 51.2 (58.3) MB, 2.5 / 0.0 ms allocation failure
	[44743:0x103800000] 3191 ms: Scavenge 51.6 (58.3) -> 51.2 (69.8) MB, 3.3 / 0.0 ms allocation failure
	[44743:0x103800000] 6260 ms: Scavenge 119.4 (131.0) -> 119.4 (131.5) MB, 5.4 / 0.0 ms allocation failure
	[44743:0x103800000] 6269 ms: Scavenge 119.8 (131.5) -> 119.0 (165.0) MB, 7.8 / 0.0 ms allocation failure
	[44743:0x103800000] 14619 ms: Scavenge 262.3 (293.8) -> 262.3 (294.8) MB, 8.8 / 0.0 ms allocation failure
	[44743:0x103800000] 14634 ms: Scavenge 262.7 (294.8) -> 261.0 (331.2) MB, 12.4 / 0.0 ms allocation failure
	[44743:0x103800000] 14637 ms: Mark-sweep 261.1 (331.2) -> 260.7 (331.2) MB, 3.4 / 0.0 ms (+ 119.3 ms in 282 steps since start of marking, biggest step 2.7 ms, walltime since start of marking 8377 ms) finalize incremental marking via stack guard GC in old space requested
	[44743:0x103800000] 19869 ms: Mark-sweep 371.2 (429.4) -> 332.1 (371.4) MB, 124.7 / 0.0 ms testing GC in old space requested
	[44743:0x103800000] 28193 ms: Scavenge 471.0 (495.3) -> 471.0 (495.3) MB, 4.4 / 0.0 ms allocation failure
	[44743:0x103800000] 28207 ms: Scavenge 471.0 (495.3) -> 469.3 (532.8) MB, 13.9 / 0.0 ms allocation failure
	[44743:0x103800000] 30300 ms: Mark-sweep 520.4 (577.9) -> 24.4 (78.5) MB, 13.4 / 0.0 ms (+ 175.4 ms in 243 steps since start of marking, biggest step 6.5 ms, walltime since start of marking 6341 ms) testing GC in old space requested
	[44743:0x103800000] 34403 ms: Scavenge 73.7 (118.7) -> 73.0 (125.2) MB, 12.8 / 0.0 ms allocation failure
	[44743:0x103800000] 36469 ms: Scavenge 105.0 (151.0) -> 104.0 (173.0) MB, 10.9 / 0.0 ms allocation failure
	[44743:0x103800000] 37549 ms: Mark-sweep 129.4 (193.9) -> 90.4 (129.9) MB, 34.8 / 0.0 ms (+ 46.6 ms in 198 steps since start of marking, biggest step 2.4 ms, walltime since start of marking 6216 ms) testing GC in old space requested
	[44743:0x103800000] 44851 ms: Mark-sweep 250.0 (274.8) -> 250.1 (270.8) MB, 3.7 / 0.0 ms (+ 92.5 ms in 274 steps since start of marking, biggest step 2.1 ms, walltime since start of marking 7278 ms) finalize incremental marking via stack guard GC in old space requested
	[44743:0x103800000] 44862 ms: Scavenge 250.1 (270.8) -> 248.4 (286.2) MB, 11.4 / 0.0 ms allocation failure
	[44743:0x103800000] 51147 ms: Scavenge 379.3 (402.1) -> 379.3 (402.1) MB, 3.4 / 0.0 ms allocation failure
	[44743:0x103800000] 51160 ms: Scavenge 379.3 (402.1) -> 377.6 (439.6) MB, 12.9 / 0.0 ms allocation failure
	[44743:0x103800000] 51177 ms: Mark-sweep 389.1 (450.9) -> 389.1 (450.9) MB, 0.8 / 0.0 ms (+ 134.8 ms in 239 steps since start of marking, biggest step 2.4 ms, walltime since start of marking 5271 ms) finalize incremental marking via stack guard GC in old space requested
	[44743:0x103800000] 56428 ms: Mark-sweep 480.5 (531.4) -> 440.8 (479.4) MB, 162.3 / 0.0 ms testing GC in old space requested
	[44743:0x103800000] 59495 ms: Mark-sweep 509.6 (540.5) -> 12.0 (39.0) MB, 7.4 / 0.0 ms testing GC in old space requested
	[44743:0x103800000] 63588 ms: Scavenge 61.3 (80.8) -> 60.4 (99.3) MB, 14.9 / 0.0 ms allocation failure
	[44743:0x103800000] 66707 ms: Scavenge 137.2 (168.5) -> 136.4 (187.0) MB, 11.5 / 0.0 ms allocation failure