**** 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:62505 **** 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.63975.556991f7 **** top macro def vtcid=vtc.63975.556991f7 ** 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.63975.556991f7/t" -a "/Users/bsdphk/VT/_vtest_tmp/vtc.63975.556991f7/sock" \ -p vcc_feature=-err_unref -f "/Users/bsdphk/VT/_vtest_tmp/vtc.63975.556991f7/unref.vcl" \ -l 2m 2>&1 *** p1 PID: 63994 **** p1 macro def p1_pid=63994 **** p1 macro def p1_dir=/Users/bsdphk/VT/_vtest_tmp/vtc.63975.556991f7/p1 **** p1 macro def p1_out=/Users/bsdphk/VT/_vtest_tmp/vtc.63975.556991f7/p1/term **** p1 macro def p1_err=/Users/bsdphk/VT/_vtest_tmp/vtc.63975.556991f7/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.63975.556991f7/t ping || **** top shell_cmd|\tvarnishadm -n /Users/bsdphk/VT/_vtest_tmp/vtc.63975.556991f7/t ping **** dT 0.276 **** p1 output|Warnings: **** p1 output|Message from VCC-compiler: **** p1 output|Unused sub foo, defined: **** p1 output|('/Users/bsdphk/VT/_vtest_tmp/vtc.63975.556991f7/unref.vcl' Line 4 Pos 13) **** p1 output| sub foo { } **** p1 output|------------###---- **** p1 output|(That was just a warning) **** dT 0.285 **** p1 output|Debug: Version: varnish-trunk revision 886c592608848a29d6e25a8a94060e4f76409ed7 **** p1 output|Debug: Platform: Darwin,24.5.0,arm64,-jnone,-sdefault,-sdefault,-hcritbit **** dT 0.290 **** p1 output|Debug: Child (64022) Started **** dT 0.319 **** p1 output|Child launched OK **** dT 0.320 **** p1 output|Info: Child (64022) said Child starts **** dT 0.322 **** top shell_out|PONG 1752713636 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.63975.556991f7/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 886c592608848a29d6e25a8a94060e4f76409ed7 *** p1 screen|Debug: Platform: Darwin,24.5.0,arm64,-jnone,-sdefault,-sdefault,-hcritbit *** p1 screen|Debug: Child (64022) Started *** p1 screen|Child launched OK *** p1 screen|Info: Child (64022) 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.330 **** p2 CMD: set -e varnishadm -n /Users/bsdphk/VT/_vtest_tmp/vtc.63975.556991f7/t "vcl.list" varnishadm -n /Users/bsdphk/VT/_vtest_tmp/vtc.63975.556991f7/t -t 20 "vcl.load unref /Users/bsdphk/VT/_vtest_tmp/vtc.63975.556991f7/unref.vcl" varnishadm -n /Users/bsdphk/VT/_vtest_tmp/vtc.63975.556991f7/t "vcl.list" *** p2 PID: 64027 **** p2 macro def p2_pid=64027 **** p2 macro def p2_dir=/Users/bsdphk/VT/_vtest_tmp/vtc.63975.556991f7/p2 **** p2 macro def p2_out=/Users/bsdphk/VT/_vtest_tmp/vtc.63975.556991f7/p2/term **** p2 macro def p2_err=/Users/bsdphk/VT/_vtest_tmp/vtc.63975.556991f7/p2/stderr **** dT 0.351 **** p2 output|active auto warm 0 boot **** dT 0.592 **** p2 output|Message from VCC-compiler: **** p2 output|Unused sub foo, defined: **** p2 output|('/Users/bsdphk/VT/_vtest_tmp/vtc.63975.556991f7/unref.vcl' Line 4 Pos 13) **** p2 output| sub foo { } **** p2 output|------------###---- **** p2 output|(That was just a warning) **** dT 0.618 **** 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=64027 status=0x0000 (user 0.006206 sys 0.007510) **** 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.63975.556991f7/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 Sent signal 15 * top RESETTING after ../../../../bin/varnishtest/tests/r03159.vtc **** p1 output|Error: Manager got SIGTERM from PID 63989 **** p1 output|Debug: Stopping Child **** p1 stdout read 0 **** p1 stderr read 0 ** p1 WAIT4 pid=63994 status=0x000f (user 0.001360 sys 0.001325) **** dT 0.619 ---- p1 Cannot signal a non-running process * top failure during reset # top TEST ../../../../bin/varnishtest/tests/r03159.vtc FAILED (0.619) exit=2 FAIL tests/r03159.vtc (exit status: 2)