**** dT 0.000 * top TEST ../../../../bin/vinyltest/tests/r03159.vtc starting **** top extmacro def pkg_version=trunk **** top extmacro def pkg_branch=trunk **** top extmacro def pwd=/root/VT/vinyl-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest **** top extmacro def date(...) **** top extmacro def string(...) **** top extmacro def localhost=127.0.0.1 **** top extmacro def bad_backend=127.0.0.1:35997 **** top extmacro def listen_addr=127.0.0.1:0 **** top extmacro def bad_ip=192.0.2.255 **** top extmacro def topbuild=/root/VT/vinyl-cache/vinyl-cache-trunk/_build/sub **** top extmacro def topsrc=/root/VT/vinyl-cache/vinyl-cache-trunk/_build/sub/../.. **** top macro def testdir=/root/VT/vinyl-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/../../../../bin/vinyltest/tests **** top macro def tmpdir=/root/VT/_vtest_tmp/vtc.10272.43308bb1 **** top macro def vtcid=vtc.10272.43308bb1 ** top === vtest "double sub unref warning / warnings output for -f" * top VTEST double sub unref warning / warnings output for -f ** top === filewrite ${tmpdir}/unref.vcl { ** top === process p1 -log { **** dT 0.006 **** p1 CMD: vinyld -F -n "/root/VT/_vtest_tmp/vtc.10272.43308bb1/t" -a "/root/VT/_vtest_tmp/vtc.10272.43308bb1/sock" \ -p vcc_feature=-err_unref -f "/root/VT/_vtest_tmp/vtc.10272.43308bb1/unref.vcl" \ -l 2m 2>&1 *** p1 PID: 10296 **** p1 macro def p1_pid=10296 **** p1 macro def p1_dir=/root/VT/_vtest_tmp/vtc.10272.43308bb1/p1 **** p1 macro def p1_out=/root/VT/_vtest_tmp/vtc.10272.43308bb1/p1/term **** p1 macro def p1_err=/root/VT/_vtest_tmp/vtc.10272.43308bb1/p1/stderr **** dT 0.007 ** top === shell { **** top shell_cmd|set -e ;exec 2>&1 ; **** top shell_cmd|\t# wait for startup vcl.load to complete **** top shell_cmd|\tvinyladm -n /root/VT/_vtest_tmp/vtc.10272.43308bb1/t ping || **** top shell_cmd|\tvinyladm -n /root/VT/_vtest_tmp/vtc.10272.43308bb1/t ping || **** top shell_cmd|\tvinyladm -n /root/VT/_vtest_tmp/vtc.10272.43308bb1/t ping || **** top shell_cmd|\tvinyladm -n /root/VT/_vtest_tmp/vtc.10272.43308bb1/t ping **** dT 0.011 **** p1 stderr read 0 **** dT 1.146 **** p1 output|Warnings: **** p1 output|Message from VCC-compiler: **** p1 output|Unused sub foo, defined: **** p1 output|('/root/VT/_vtest_tmp/vtc.10272.43308bb1/unref.vcl' Line 4 Pos 13) **** p1 output| sub foo { } **** p1 output|------------###---- **** p1 output|(That was just a warning) **** dT 1.577 **** p1 output|Debug: Version: vinyl-cache-trunk revision 35e0552fa5d594325c06a4ac44b951808442e2d0 **** p1 output|Debug: Platform: FreeBSD,16.0-CURRENT,arm64,-jnone,-sdefault,-sdefault,-sdefault,-hcritbit **** dT 2.995 **** p1 output|Debug: Child (10524) Started **** dT 6.072 **** p1 output|Child launched OK **** dT 6.204 **** p1 output|Info: Child (10524) said Child starts **** dT 6.287 **** top shell_out|No -T in shared memory **** top shell_out|PONG 1778484478 1.0 **** top shell_status = 0x0000 ** top === process p1 -screen_dump *** p1 screen| Warnings: *** p1 screen|Message from VCC-compiler: *** p1 screen|Unused sub foo, defined: *** p1 screen|('/root/VT/_vtest_tmp/vtc.10272.43308bb1/unref.vcl' Line 4 Pos 13) *** p1 screen| sub foo { } *** p1 screen|------------###---- *** p1 screen| *** p1 screen|(That was just a warning) *** p1 screen| *** p1 screen|Debug: Version: vinyl-cache-trunk revision 35e0552fa5d594325c06a4ac44b951808442e *** p1 screen|2d0 *** p1 screen|Debug: Platform: FreeBSD,16.0-CURRENT,arm64,-jnone,-sdefault,-sdefault,-sdefault *** p1 screen|,-hcritbit *** p1 screen|Debug: Child (10524) Started *** p1 screen|Child launched OK *** p1 screen|Info: Child (10524) said Child starts *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 Cursor at line 17 column 1 ** top === process p1 -expect-text 0 1 "Unused sub foo, defined:" **** p1 found expected text at 3,1: 'Unused sub foo, defined:' ** top === process p1 -expect-text 0 1 "(That was just a warning)" **** p1 found expected text at 8,1: '(That was just a warning)' ** top === process p2 -log { **** dT 6.389 **** p2 CMD: set -e vinyladm -n /root/VT/_vtest_tmp/vtc.10272.43308bb1/t "vcl.list" vinyladm -n /root/VT/_vtest_tmp/vtc.10272.43308bb1/t -t 20 "vcl.load unref /root/VT/_vtest_tmp/vtc.10272.43308bb1/unref.vcl" vinyladm -n /root/VT/_vtest_tmp/vtc.10272.43308bb1/t "vcl.list" **** dT 6.390 *** p2 PID: 10657 **** dT 6.440 **** p2 macro def p2_pid=10657 **** p2 macro def p2_dir=/root/VT/_vtest_tmp/vtc.10272.43308bb1/p2 **** p2 macro def p2_out=/root/VT/_vtest_tmp/vtc.10272.43308bb1/p2/term **** p2 macro def p2_err=/root/VT/_vtest_tmp/vtc.10272.43308bb1/p2/stderr **** dT 7.091 **** p2 output|active auto warm 0 boot **** dT 10.853 **** p2 output|Message from VCC-compiler: **** p2 output|Unused sub foo, defined: **** dT 10.854 **** p2 output|('/root/VT/_vtest_tmp/vtc.10272.43308bb1/unref.vcl' Line 4 Pos 13) **** p2 output| sub foo { } **** p2 output|------------###---- **** p2 output|(That was just a warning) **** dT 11.219 **** p2 output|active auto warm 0 boot **** p2 output|available auto warm 0 unref **** dT 11.220 **** p2 stdout read 0 **** p2 stderr read 0 ** p2 WAIT4 pid=10657 status=0x0000 (user 0.018653 sys 0.037306) **** p2 macro undef p2_pid **** dT 11.221 **** p2 stdout 305 bytes, stderr 0 bytes ** top === process p2 -screen_dump *** p2 screen| active auto warm 0 boot *** p2 screen| *** p2 screen|Message from VCC-compiler: *** p2 screen|Unused sub foo, defined: *** p2 screen|('/root/VT/_vtest_tmp/vtc.10272.43308bb1/unref.vcl' Line 4 Pos 13) *** p2 screen| sub foo { } *** p2 screen|------------###---- *** p2 screen| *** p2 screen|(That was just a warning) *** p2 screen| *** p2 screen|active auto warm 0 boot *** p2 screen|available auto warm 0 unref *** p2 screen| *** p2 screen| *** p2 screen| *** p2 screen| *** p2 screen| *** p2 screen| *** p2 screen| *** p2 screen| *** p2 screen| *** p2 screen| *** p2 screen| *** p2 screen| *** p2 Cursor at line 14 column 1 ** top === process p2 -expect-text 0 1 "Unused sub foo, defined:" **** p2 found expected text at 4,1: 'Unused sub foo, defined:' ** top === process p2 -expect-text 0 1 "(That was just a warning)" **** p2 found expected text at 9,1: '(That was just a warning)' ** top === process p1 -kill TERM **** p1 output|Info: Manager got SIGTERM from PID 10292 **** p1 output|Debug: Stopping Child **** dT 11.265 **** p1 Sent signal 15 * top RESETTING after ../../../../bin/vinyltest/tests/r03159.vtc **** p1 Sent signal 15 **** dT 11.307 **** p1 output|Error: Child (10524) died signal=15 **** dT 12.273 **** p1 stdout read 0 ** p1 WAIT4 pid=10296 status=0x0009 (user 0.912023 sys 0.074620) * p1 Expected exit: 0x40 signal: 15 core: 0 ---- p1 Bad exit status: 0x0009 exit 0x0 signal 9 core 0 **** dT 12.274 **** p1 Sent signal 9 **** p1 stdout 573 bytes, stderr 0 bytes * top TEST ../../../../bin/vinyltest/tests/r03159.vtc FAILED