{"body":"[iojs@test-digitalocean-freebsd11-x64-2 /usr/home/iojs/build/workspace/node-test-ojs@test-digitalocean-freebsd11-x64-2 /usr/home/iojs/build/workspace/node-test-commit-freebsd/nodes/freebsd11-x64]$ time ./node --trace_gc --trace_gc_verbose --expose-gc test/addons/stringbytes-external-exceed-max/test-stringbytes-external-exceed-max\n\n[85873:0x803e34380] Shrinking page 0x697d3200000: end 0x697d3280000 -> 0x697d3239000\n[85873:0x803e34380] Shrinking page 0xf1bd0800000: end 0xf1bd0880000 -> 0xf1bd0803000\n[85873:0x803e34380] Fast promotion mode: false survival rate: 71%\n[85873:0x803e34380] 109 ms: Scavenge 2.6 (4.2) -> 2.3 (5.2) MB, 2.0 / 0.0 ms (average mu = 1.000, current mu = 1.000) allocation failure\n[85873:0x803e34380] Memory allocator, used: 5360 KB, available: 1486096 KB\n[85873:0x803e34380] Read-only space, used: 34 KB, available: 469 KB, committed: 512 KB\n[85873:0x803e34380] New space, used: 748 KB, available: 258 KB, committed: 2048 KB\n[85873:0x803e34380] Old space, used: 921 KB, available: 0 KB, committed: 1252 KB\n[85873:0x803e34380] Code space, used: 475 KB, available: 0 KB, committed: 1024KB\n[85873:0x803e34380] Map space, used: 213 KB, available: 0 KB, committed: 524 KB\n[85873:0x803e34380] Large object space, used: 0 KB, available: 1485575 KB, committed: 0 KB\n[85873:0x803e34380] All spaces, used: 2392 KB, available: 1486303 KB, committed: 5360KB\n[85873:0x803e34380] External memory reported: 8 KB\n[85873:0x803e34380] External memory global 0 KB\n[85873:0x803e34380] Total time spent in GC : 2.0 ms\n[85873:0x803e34380] Fast promotion mode: false survival rate: 87%\n[85873:0x803e34380] 125 ms: Scavenge 2.8 (5.2) -> 2.7 (6.2) MB, 2.0 / 0.0 ms (average mu = 1.000, current mu = 1.000) allocation failure\n[85873:0x803e34380] Memory allocator, used: 6384 KB, available: 1485072 KB\n[85873:0x803e34380] Read-only space, used: 34 KB, available: 469 KB, committed: 512 KB\n[85873:0x803e34380] New space, used: 192 KB, available: 814 KB, committed: 2048 KB\n[85873:0x803e34380] Old space, used: 1753 KB, available: 289 KB, committed: 2276 KB\n[85873:0x803e34380] Code space, used: 491 KB, available: 0 KB, committed: 1024KB\n[85873:0x803e34380] Map space, used: 266 KB, available: 0 KB, committed: 524 KB\n[85873:0x803e34380] Large object space, used: 0 KB, available: 1484551 KB, committed: 0 KB\n[85873:0x803e34380] All spaces, used: 2737 KB, available: 1486125 KB, committed: 6384KB\n[85873:0x803e34380] External memory reported: 8 KB\n[85873:0x803e34380] External memory global 0 KB\n[85873:0x803e34380] Total time spent in GC : 4.0 ms\n[85873:0x803e34380] Fast promotion mode: false survival rate: 39%\n[85873:0x803e34380] 186 ms: Scavenge 4.2 (6.7) -> 4.0 (9.7) MB, 1.3 / 0.0 ms (average mu = 1.000, current mu = 1.000) allocation failure\n[85873:0x803e34380] Memory allocator, used: 9968 KB, available: 1481488 KB\n[85873:0x803e34380] Read-only space, used: 34 KB, available: 469 KB, committed: 512 KB\n[85873:0x803e34380] New space, used: 673 KB, available: 1340 KB, committed: 4096 KB\n[85873:0x803e34380] Old space, used: 2546 KB, available: 887 KB, committed: 3812 KB\n[85873:0x803e34380] Code space, used: 542 KB, available: 0 KB, committed: 1024KB\n[85873:0x803e34380] Map space, used: 328 KB, available: 0 KB, committed: 524 KB\n[85873:0x803e34380] Large object space, used: 0 KB, available: 1480967 KB, committed: 0 KB\n[85873:0x803e34380] All spaces, used: 4124 KB, available: 1483664 KB, committed: 9968KB\n[85873:0x803e34380] External memory reported: 8 KB\n[85873:0x803e34380] External memory global 0 KB\n[85873:0x803e34380] Total time spent in GC : 5.2 ms\ncould allocate 1073741799\n[85873:0x803e34380] Fast promotion mode: false survival rate: 8%\n[85873:0x803e34380] 209 ms: Heap growing factor 1.7 based on mu=0.970, speed_ratio=0 (gc=0, mutator=21902)\n[85873:0x803e34380] 209 ms: Grow: old size: 3693 KB, new limit: 13899 KB (1.1)\n[85873:0x803e34380] 209 ms: Mark-sweep 4.2 (9.7) -> 3.6 (7.2) MB, 7.7 / 0.0 ms (average mu = 1.000, current mu = 1.000) external memory pressure GC in old space requested\n[85873:0x803e34380] Memory allocator, used: 7408 KB, available: 1484048 KB\n[85873:0x803e34380] Read-only space, used: 34 KB, available: 469 KB, committed: 512 KB\n[85873:0x803e34380] New space, used: 15 KB, available: 991 KB, committed: 1024 KB\n[85873:0x803e34380] Old space, used: 2829 KB, available: 1320 KB, committed: 4324 KB\n[85873:0x803e34380] Code space, used: 557 KB, available: 0 KB, committed: 1024KB\n[85873:0x803e34380] Map space, used: 271 KB, available: 0 KB, committed: 524 KB\n[85873:0x803e34380] Large object space, used: 0 KB, available: 1483527 KB, committed: 0 KB\n[85873:0x803e34380] All spaces, used: 3708 KB, available: 1486308 KB, committed: 7408KB\n[85873:0x803e34380] External memory reported: 1048584 KB\n[85873:0x803e34380] External memory global 0 KB\n[85873:0x803e34380] Total time spent in GC : 13.0 ms\ncould allocate\nin expectsError - before\n[85873:0x803e34380] Fast promotion mode: false survival rate: 1%\n[85873:0x803e34380] 12894 ms: Heap growing factor 1.1 based on mu=0.970, speed_ratio=11578 (gc=567359, mutator=49)\n[85873:0x803e34380] 12897 ms: Grow: old size: 3694 KB, new limit: 13900 KB (1.1)\n[85873:0x803e34380] 12899 ms: Mark-sweep 3.6 (7.2) -> 3.6 (6.7) MB, 628.3 / 1.4 ms (average mu = 0.950, current mu = 0.950) external memory pressure GC in old space requested\n[85873:0x803e34380] Memory allocator, used: 6896 KB, available: 1484560 KB\n[85873:0x803e34380] Read-only space, used: 34 KB, available: 469 KB, committed: 512 KB\n[85873:0x803e34380] New space, used: 4 KB, available: 1002 KB, committed: 1024 KB\n[85873:0x803e34380] Old space, used: 2830 KB, available: 892 KB, committed: 3812 KB\n[85873:0x803e34380] Code space, used: 557 KB, available: 0 KB, committed: 1024KB\n[85873:0x803e34380] Map space, used: 271 KB, available: 0 KB, committed: 524 KB\n[85873:0x803e34380] Large object space, used: 0 KB, available: 1484039 KB, committed: 0 KB\n[85873:0x803e34380] All spaces, used: 3699 KB, available: 1486403 KB, committed: 6896KB\n[85873:0x803e34380] External memory reported: 2097160 KB\n[85873:0x803e34380] External memory global 0 KB\n[85873:0x803e34380] Total time spent in GC : 641.3 ms\n[85873:0x803e34380] Fast promotion mode: false survival rate: 1%\n[85873:0x803e34380] 13049 ms: Heap growing factor 1.1 based on mu=0.970, speed_ratio=251 (gc=12885, mutator=51)\n[85873:0x803e34380] 13049 ms: Grow: old size: 3712 KB, new limit: 12911 KB (1.1)\n[85873:0x803e34380] 13050 ms: Mark-sweep 3.7 (6.7) -> 3.7 (6.7) MB, 5.9 / 0.0 ms (average mu = 0.951, current mu = 0.961) testing GC in old space requested\n[85873:0x803e34380] Memory allocator, used: 6896 KB, available: 1484560 KB\n[85873:0x803e34380] Read-only space, used: 34 KB, available: 469 KB, committed: 512 KB\n[85873:0x803e34380] New space, used: 47 KB, available: 959 KB, committed: 1024 KB\n[85873:0x803e34380] Old space, used: 2847 KB, available: 331 KB, committed: 3812 KB\n[85873:0x803e34380] Code space, used: 558 KB, available: 0 KB, committed: 1024KB\n[85873:0x803e34380] Map space, used: 272 KB, available: 0 KB, committed: 524 KB\n[85873:0x803e34380] Large object space, used: 0 KB, available: 1484039 KB, committed: 0 KB\n[85873:0x803e34380] All spaces, used: 3760 KB, available: 1485799 KB, committed: 6896KB\n[85873:0x803e34380] External memory reported: 1048584 KB\n[85873:0x803e34380] External memory global 0 KB\n[85873:0x803e34380] Total time spent in GC : 647.2 ms\nin global - before\n[85873:0x803e34380] Fast promotion mode: false survival rate: 1%\n[85873:0x803e34380] 19039 ms: Heap growing factor 1.1 based on mu=0.970, speed_ratio=18731 (gc=18731, mutator=1)\n[85873:0x803e34380] 19039 ms: Grow: old size: 3711 KB, new limit: 12910 KB (1.1)\n[85873:0x803e34380] 19039 ms: Mark-sweep 3.7 (6.7) -> 3.6 (6.7) MB, 5.8 / 0.0 ms (average mu = 0.974, current mu = 0.999) external memory pressure GC in old space requested\n[85873:0x803e34380] Memory allocator, used: 6896 KB, available: 1484560 KB\n[85873:0x803e34380] Read-only space, used: 34 KB, available: 469 KB, committed: 512 KB\n[85873:0x803e34380] New space, used: 0 KB, available: 1006 KB, committed: 1024 KB\n[85873:0x803e34380] Old space, used: 2845 KB, available: 313 KB, committed: 3812 KB\n[85873:0x803e34380] Code space, used: 558 KB, available: 0 KB, committed: 1024KB\n[85873:0x803e34380] Map space, used: 271 KB, available: 0 KB, committed: 524 KB\n[85873:0x803e34380] Large object space, used: 0 KB, available: 1484039 KB, committed: 0 KB\n[85873:0x803e34380] All spaces, used: 3711 KB, available: 1485829 KB, committed: 6896KB\n[85873:0x803e34380] External memory reported: 2097160 KB\n[85873:0x803e34380] External memory global 0 KB\n[85873:0x803e34380] Total time spent in GC : 653.0 ms\nin global - after\n[85873:0x803e34380] Fast promotion mode: false survival rate: 0%\n[85873:0x803e34380] 19135 ms: Heap growing factor 1.1 based on mu=0.970, speed_ratio=6815 (gc=24512, mutator=4)\n[85873:0x803e34380] 19135 ms: Grow: old size: 3711 KB, new limit: 12910 KB (1.1)\n[85873:0x803e34380] 19135 ms: Mark-sweep 3.6 (6.7) -> 3.6 (6.7) MB, 94.8 / 0.0 ms (average mu = 0.959, current mu = 0.011) testing GC in old space requested\n[85873:0x803e34380] Memory allocator, used: 6896 KB, available: 1484560 KB\n[85873:0x803e34380] Read-only space, used: 34 KB, available: 469 KB, committed: 512 KB\n[85873:0x803e34380] New space, used: 0 KB, available: 1006 KB, committed: 1024 KB\n[85873:0x803e34380] Old space, used: 2845 KB, available: 313 KB, committed: 3812 KB\n[85873:0x803e34380] Code space, used: 558 KB, available: 0 KB, committed: 1024KB\n[85873:0x803e34380] Map space, used: 271 KB, available: 0 KB, committed: 524 KB\n[85873:0x803e34380] Large object space, used: 0 KB, available: 1484039 KB, committed: 0 KB\n[85873:0x803e34380] All spaces, used: 3711 KB, available: 1485829 KB, committed: 6896KB\n[85873:0x803e34380] External memory reported: 1048584 KB\n[85873:0x803e34380] External memory global 0 KB\n[85873:0x803e34380] Total time spent in GC : 747.8 ms\nin global - before slice\n[85873:0x803e34380] Fast promotion mode: false survival rate: 0%\n[85873:0x803e34380] 27734 ms: Heap growing factor 1.1 based on mu=0.970, speed_ratio=26505 (gc=26505, mutator=1)\n[85873:0x803e34380] 27736 ms: Grow: old size: 3711 KB, new limit: 12910 KB (1.1)\n[85873:0x803e34380] 27738 ms: Mark-sweep 3.6 (6.7) -> 3.6 (6.7) MB, 73.0 / 0.3 ms (average mu = 0.983, current mu = 0.992) external memory pressure GC in old space requested\n[85873:0x803e34380] Memory allocator, used: 6896 KB, available: 1484560 KB\n[85873:0x803e34380] Read-only space, used: 34 KB, available: 469 KB, committed: 512 KB\n[85873:0x803e34380] New space, used: 0 KB, available: 1006 KB, committed: 1024 KB\n[85873:0x803e34380] Old space, used: 2846 KB, available: 313 KB, committed: 3812 KB\n[85873:0x803e34380] Code space, used: 558 KB, available: 0 KB, committed: 1024KB\n[85873:0x803e34380] Map space, used: 271 KB, available: 0 KB, committed: 524 KB\n[85873:0x803e34380] Large object space, used: 0 KB, available: 1484039 KB, committed: 0 KB\n[85873:0x803e34380] All spaces, used: 3711 KB, available: 1485828 KB, committed: 6896KB\n[85873:0x803e34380] External memory reported: 2097160 KB\n[85873:0x803e34380] External memory global 0 KB\n[85873:0x803e34380] Total time spent in GC : 820.8 ms\nin global - after slice\n\nreal 0m28.081s\nuser 0m0.962s\nsys 0m10.379s","name":"","extension":"txt","url":"https://www.irccloud.com/pastebin/zMF1YpIa","modified":1534181972,"id":"zMF1YpIa","size":12394,"lines":144,"own_paste":false,"theme":"","date":1534181972}