# Pastebin 059SqSBt [iojs@test-digitalocean-freebsd11-x64-2 /usr/home/iojs/build/workspace/node-test-commit-freebsd/nodes/freebsd11-x64]$ time ./node --trace_gc --trace_gc_verbose test/addons/stringbytes-external-exceed-max/test-stringbytes-external-exceed-max-by-1-binary.js [85822:0x803e34380] Shrinking page 0x84850080000: end 0x84850100000 -> 0x848500b9000 [85822:0x803e34380] Shrinking page 0x6a0c7080000: end 0x6a0c7100000 -> 0x6a0c7083000 [85822:0x803e34380] Fast promotion mode: false survival rate: 71% [85822:0x803e34380] 91 ms: Scavenge 2.6 (4.2) -> 2.3 (5.2) MB, 2.1 / 0.0 ms (average mu = 1.000, current mu = 1.000) allocation failure [85822:0x803e34380] Memory allocator, used: 5360 KB, available: 1486096 KB [85822:0x803e34380] Read-only space, used: 34 KB, available: 469 KB, committed: 512 KB [85822:0x803e34380] New space, used: 748 KB, available: 258 KB, committed: 2048 KB [85822:0x803e34380] Old space, used: 919 KB, available: 0 KB, committed: 1252 KB [85822:0x803e34380] Code space, used: 475 KB, available: 0 KB, committed: 1024KB [85822:0x803e34380] Map space, used: 213 KB, available: 0 KB, committed: 524 KB [85822:0x803e34380] Large object space, used: 0 KB, available: 1485575 KB, committed: 0 KB [85822:0x803e34380] All spaces, used: 2390 KB, available: 1486303 KB, committed: 5360KB [85822:0x803e34380] External memory reported: 8 KB [85822:0x803e34380] External memory global 0 KB [85822:0x803e34380] Total time spent in GC : 2.1 ms [85822:0x803e34380] Fast promotion mode: false survival rate: 87% [85822:0x803e34380] 106 ms: Scavenge 2.8 (5.2) -> 2.7 (6.2) MB, 1.9 / 0.0 ms (average mu = 1.000, current mu = 1.000) allocation failure [85822:0x803e34380] Memory allocator, used: 6384 KB, available: 1485072 KB [85822:0x803e34380] Read-only space, used: 34 KB, available: 469 KB, committed: 512 KB [85822:0x803e34380] New space, used: 192 KB, available: 814 KB, committed: 2048 KB [85822:0x803e34380] Old space, used: 1751 KB, available: 290 KB, committed: 2276 KB [85822:0x803e34380] Code space, used: 490 KB, available: 0 KB, committed: 1024KB [85822:0x803e34380] Map space, used: 266 KB, available: 0 KB, committed: 524 KB [85822:0x803e34380] Large object space, used: 0 KB, available: 1484551 KB, committed: 0 KB [85822:0x803e34380] All spaces, used: 2735 KB, available: 1486125 KB, committed: 6384KB [85822:0x803e34380] External memory reported: 8 KB [85822:0x803e34380] External memory global 0 KB [85822:0x803e34380] Total time spent in GC : 3.9 ms [85822:0x803e34380] Fast promotion mode: false survival rate: 39% [85822:0x803e34380] 179 ms: Scavenge 4.2 (6.7) -> 4.0 (9.7) MB, 1.4 / 0.0 ms (average mu = 1.000, current mu = 1.000) allocation failure [85822:0x803e34380] Memory allocator, used: 9968 KB, available: 1481488 KB [85822:0x803e34380] Read-only space, used: 34 KB, available: 469 KB, committed: 512 KB [85822:0x803e34380] New space, used: 678 KB, available: 1335 KB, committed: 4096 KB [85822:0x803e34380] Old space, used: 2544 KB, available: 889 KB, committed: 3812 KB [85822:0x803e34380] Code space, used: 541 KB, available: 0 KB, committed: 1024KB [85822:0x803e34380] Map space, used: 328 KB, available: 0 KB, committed: 524 KB [85822:0x803e34380] Large object space, used: 0 KB, available: 1480967 KB, committed: 0 KB [85822:0x803e34380] All spaces, used: 4127 KB, available: 1483661 KB, committed: 9968KB [85822:0x803e34380] External memory reported: 8 KB [85822:0x803e34380] External memory global 0 KB [85822:0x803e34380] Total time spent in GC : 5.3 ms could allocate 1073741799 [85822:0x803e34380] Fast promotion mode: false survival rate: 0% [85822:0x803e34380] 202 ms: Heap growing factor 1.7 based on mu=0.970, speed_ratio=0 (gc=0, mutator=19121) [85822:0x803e34380] 202 ms: Grow: old size: 3691 KB, new limit: 13897 KB (1.1) [85822:0x803e34380] 202 ms: Mark-sweep 4.2 (9.7) -> 3.6 (6.7) MB, 6.9 / 0.0 ms (average mu = 1.000, current mu = 1.000) external memory pressure GC in old space requested [85822:0x803e34380] Memory allocator, used: 6896 KB, available: 1484560 KB [85822:0x803e34380] Read-only space, used: 34 KB, available: 469 KB, committed: 512 KB [85822:0x803e34380] New space, used: 15 KB, available: 991 KB, committed: 1024 KB [85822:0x803e34380] Old space, used: 2828 KB, available: 818 KB, committed: 3812 KB [85822:0x803e34380] Code space, used: 557 KB, available: 0 KB, committed: 1024KB [85822:0x803e34380] Map space, used: 271 KB, available: 0 KB, committed: 524 KB [85822:0x803e34380] Large object space, used: 0 KB, available: 1484039 KB, committed: 0 KB [85822:0x803e34380] All spaces, used: 3706 KB, available: 1486319 KB, committed: 6896KB [85822:0x803e34380] External memory reported: 1048584 KB [85822:0x803e34380] External memory global 0 KB [85822:0x803e34380] Total time spent in GC : 12.3 ms could allocate in expectsError - before [85822:0x803e34380] Fast promotion mode: false survival rate: 0% [85822:0x803e34380] 12170 ms: Heap growing factor 1.1 based on mu=0.970, speed_ratio=12130 (gc=631054, mutator=52) [85822:0x803e34380] 12177 ms: Grow: old size: 3692 KB, new limit: 13898 KB (1.1) [85822:0x803e34380] 12179 ms: Mark-sweep 3.6 (6.7) -> 3.6 (6.7) MB, 616.7 / 1.5 ms (average mu = 0.949, current mu = 0.949) external memory pressure GC in old space requested [85822:0x803e34380] Memory allocator, used: 6896 KB, available: 1484560 KB [85822:0x803e34380] Read-only space, used: 34 KB, available: 469 KB, committed: 512 KB [85822:0x803e34380] New space, used: 4 KB, available: 1002 KB, committed: 1024 KB [85822:0x803e34380] Old space, used: 2828 KB, available: 894 KB, committed: 3812 KB [85822:0x803e34380] Code space, used: 557 KB, available: 0 KB, committed: 1024KB [85822:0x803e34380] Map space, used: 271 KB, available: 0 KB, committed: 524 KB [85822:0x803e34380] Large object space, used: 0 KB, available: 1484039 KB, committed: 0 KB [85822:0x803e34380] All spaces, used: 3697 KB, available: 1486404 KB, committed: 6896KB [85822:0x803e34380] External memory reported: 2097160 KB [85822:0x803e34380] External memory global 0 KB [85822:0x803e34380] Total time spent in GC : 629.0 ms in global - before in global - after in global - before slice [85822:0x803e34380] Fast promotion mode: false survival rate: 2% [85822:0x803e34380] 85705 ms: Heap growing factor 1.1 based on mu=0.970, speed_ratio=13141 (gc=13141, mutator=1) [85822:0x803e34380] 85708 ms: Grow: old size: 3710 KB, new limit: 12909 KB (1.1) [85822:0x803e34380] 85711 ms: Mark-sweep 3.7 (6.7) -> 3.7 (6.7) MB, 662.8 / 5.3 ms (+ 0.0 ms in 0 steps since start of marking, biggest step 0.0 ms, walltime since start of marking 69058 ms) (average mu = 0.985, current mu = 0.991) external memory pressure GC in old space requested [85822:0x803e34380] Memory allocator, used: 6896 KB, available: 1484560 KB [85822:0x803e34380] Read-only space, used: 34 KB, available: 469 KB, committed: 512 KB [85822:0x803e34380] New space, used: 47 KB, available: 959 KB, committed: 1024 KB [85822:0x803e34380] Old space, used: 2845 KB, available: 312 KB, committed: 3812 KB [85822:0x803e34380] Code space, used: 558 KB, available: 0 KB, committed: 1024KB [85822:0x803e34380] Map space, used: 272 KB, available: 0 KB, committed: 524 KB [85822:0x803e34380] Large object space, used: 0 KB, available: 1484039 KB, committed: 0 KB [85822:0x803e34380] All spaces, used: 3758 KB, available: 1485780 KB, committed: 6896KB [85822:0x803e34380] External memory reported: 3145736 KB [85822:0x803e34380] External memory global 0 KB [85822:0x803e34380] Total time spent in GC : 1291.9 ms in global - after slice real 1m26.415s user 0m1.004s sys 0m10.774s