**** dT 0.000 * top TEST ../../../../bin/varnishtest/tests/r03159.vtc starting **** top extmacro def pkg_version=trunk **** top extmacro def pkg_branch=trunk **** top extmacro def pwd=/Users/bsdphk/VT/varnish-cache/varnish-trunk/_build/sub/bin/varnishtest **** 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:54440 **** top extmacro def listen_addr=127.0.0.1:0 **** top extmacro def bad_ip=192.0.2.255 **** top extmacro def topbuild=/Users/bsdphk/VT/varnish-cache/varnish-trunk/_build/sub **** top extmacro def topsrc=/Users/bsdphk/VT/varnish-cache/varnish-trunk/_build/sub/../.. **** top macro def testdir=/Users/bsdphk/VT/varnish-cache/varnish-trunk/_build/sub/bin/varnishtest/../../../../bin/varnishtest/tests **** top macro def tmpdir=/Users/bsdphk/VT/_vtest_tmp/vtc.84347.22c57603 **** top macro def vtcid=vtc.84347.22c57603 ** top === varnishtest "double sub unref warning / warnings output for ... * top VTEST double sub unref warning / warnings output for -f ** top === filewrite ${tmpdir}/unref.vcl { ** top === process p1 -log { **** dT 0.008 **** p1 CMD: varnishd -F -n "/Users/bsdphk/VT/_vtest_tmp/vtc.84347.22c57603/t" -a "/Users/bsdphk/VT/_vtest_tmp/vtc.84347.22c57603/sock" \ -p vcc_feature=-err_unref -f "/Users/bsdphk/VT/_vtest_tmp/vtc.84347.22c57603/unref.vcl" \ -l 2m 2>&1 *** p1 PID: 84367 **** dT 0.009 **** p1 macro def p1_pid=84367 **** p1 macro def p1_dir=/Users/bsdphk/VT/_vtest_tmp/vtc.84347.22c57603/p1 **** p1 macro def p1_out=/Users/bsdphk/VT/_vtest_tmp/vtc.84347.22c57603/p1/term **** p1 macro def p1_err=/Users/bsdphk/VT/_vtest_tmp/vtc.84347.22c57603/p1/stderr ** top === shell { **** top shell_cmd|set -e ;exec 2>&1 ; **** top shell_cmd|\t# wait for startup vcl.load to complete **** top shell_cmd|\tvarnishadm -n /Users/bsdphk/VT/_vtest_tmp/vtc.84347.22c57603/t ping || **** top shell_cmd|\tvarnishadm -n /Users/bsdphk/VT/_vtest_tmp/vtc.84347.22c57603/t ping **** dT 0.286 **** p1 output|Warnings: **** p1 output|Message from VCC-compiler: **** p1 output|Unused sub foo, defined: **** p1 output|('/Users/bsdphk/VT/_vtest_tmp/vtc.84347.22c57603/unref.vcl' Line 4 Pos 13) **** p1 output| sub foo { } **** p1 output|------------###---- **** p1 output|(That was just a warning) **** dT 0.304 **** p1 output|Debug: Version: varnish-trunk revision ce1d6b2b91b804aabdff3688dd052e755d4afd72 **** p1 output|Debug: Platform: Darwin,24.5.0,arm64,-jnone,-sdefault,-sdefault,-hcritbit **** dT 0.313 **** p1 output|Debug: Child (84393) Started **** dT 0.347 **** p1 output|Child launched OK **** dT 0.348 **** p1 output|Info: Child (84393) said Child starts **** dT 0.558 **** top shell_out|PONG 1757878622 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|('/Users/bsdphk/VT/_vtest_tmp/vtc.84347.22c57603/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: varnish-trunk revision ce1d6b2b91b804aabdff3688dd052e755d4afd72 *** p1 screen|Debug: Platform: Darwin,24.5.0,arm64,-jnone,-sdefault,-sdefault,-hcritbit *** p1 screen|Debug: Child (84393) Started *** p1 screen|Child launched OK *** p1 screen|Info: Child (84393) said Child starts *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 Cursor at line 15 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 0.586 **** p2 CMD: set -e varnishadm -n /Users/bsdphk/VT/_vtest_tmp/vtc.84347.22c57603/t "vcl.list" varnishadm -n /Users/bsdphk/VT/_vtest_tmp/vtc.84347.22c57603/t -t 20 "vcl.load unref /Users/bsdphk/VT/_vtest_tmp/vtc.84347.22c57603/unref.vcl" varnishadm -n /Users/bsdphk/VT/_vtest_tmp/vtc.84347.22c57603/t "vcl.list" **** dT 0.587 *** p2 PID: 84398 **** p2 macro def p2_pid=84398 **** p2 macro def p2_dir=/Users/bsdphk/VT/_vtest_tmp/vtc.84347.22c57603/p2 **** p2 macro def p2_out=/Users/bsdphk/VT/_vtest_tmp/vtc.84347.22c57603/p2/term **** p2 macro def p2_err=/Users/bsdphk/VT/_vtest_tmp/vtc.84347.22c57603/p2/stderr **** dT 0.625 **** p2 output|active auto warm 0 boot **** dT 0.921 **** p2 output|Message from VCC-compiler: **** p2 output|Unused sub foo, defined: **** p2 output|('/Users/bsdphk/VT/_vtest_tmp/vtc.84347.22c57603/unref.vcl' Line 4 Pos 13) **** p2 output| sub foo { } **** p2 output|------------###---- **** p2 output|(That was just a warning) **** dT 0.954 **** p2 output|active auto warm 0 boot **** p2 output|available auto warm 0 unref **** p2 stdout read 0 **** p2 stderr read 0 ** p2 WAIT4 pid=84398 status=0x0000 (user 0.009162 sys 0.011047) **** p2 macro undef p2_pid **** p2 stdout 313 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|('/Users/bsdphk/VT/_vtest_tmp/vtc.84347.22c57603/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 **** dT 0.955 **** p1 Sent signal 15 * top RESETTING after ../../../../bin/varnishtest/tests/r03159.vtc **** p1 output|Error: Manager got SIGTERM from PID 84362 **** p1 output|Debug: Stopping Child **** p1 stdout read 0 **** p1 stderr read 0 ** p1 WAIT4 pid=84367 status=0x000f (user 0.001335 sys 0.001241) ---- p1 Cannot signal a non-running process * top failure during reset # top TEST ../../../../bin/varnishtest/tests/r03159.vtc FAILED (0.956) exit=2 FAIL tests/r03159.vtc (exit status: 2)