**** dT 0.002 * top TEST ../../../../bin/varnishtest/tests/r03159.vtc starting **** top extmacro def pwd=/root/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:42080 **** 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/varnish-cache/varnish-trunk/_build/sub **** dT 0.003 **** top macro def testdir=/root/VT/varnish-cache/varnish-trunk/_build/sub/bin/varnishtest/../../../../bin/varnishtest/tests **** dT 0.004 **** top macro def tmpdir=/root/VT/_vtest_tmp/vtc.923.08b24790 **** dT 0.005 ** top === varnishtest "double sub unref warning / warnings output for ... * top VTEST double sub unref warning / warnings output for -f ** top === shell { **** top shell_cmd|exec 2>&1 ; **** top shell_cmd|\tcat >unref.vcl <<-EOF **** top shell_cmd|\tvcl 4.1; **** top shell_cmd|\tbackend be none; **** top shell_cmd|\tsub foo { } **** top shell_cmd|\tEOF **** dT 0.057 **** top shell_status = 0x0000 ** top === process p1 -winsz 100 80 -log { **** dT 0.143 **** p1 TIOCWINSZ -1 Bad file descriptor **** p1 CMD: varnishd -F -n "/root/VT/_vtest_tmp/vtc.923.08b24790/t" -a "/root/VT/_vtest_tmp/vtc.923.08b24790/sock" \ -p vcc_err_unref=off -f "/root/VT/_vtest_tmp/vtc.923.08b24790/unref.vcl" \ -l 2m 2>&1 **** dT 0.164 *** p1 PID: 956 **** dT 0.216 **** p1 macro def p1_pid=956 **** p1 macro def p1_dir=/root/VT/_vtest_tmp/vtc.923.08b24790/p1 **** p1 macro def p1_out=/root/VT/_vtest_tmp/vtc.923.08b24790/p1/term **** p1 macro def p1_err=/root/VT/_vtest_tmp/vtc.923.08b24790/p1/stderr **** dT 0.217 ** top === process p1 -expect-text 0 1 "Unused sub foo, defined:" **** dT 0.254 **** p1 stderr read 0 **** dT 4.890 **** p1 output|Warnings: **** dT 4.891 **** p1 output|Message from VCC-compiler: **** p1 output|Unused sub foo, defined: **** dT 4.892 **** p1 output|('/root/VT/_vtest_tmp/vtc.923.08b24790/unref.vcl' Line 3 Pos 5) **** p1 output|sub foo { } **** p1 output|----###---- **** dT 4.893 **** p1 output|(That was just a warning) **** dT 5.078 **** p1 output|Debug: Version: varnish-trunk revision 271e2b8307ff56942ce05f04b90948027ca60cd8 **** dT 5.090 **** p1 output|Debug: Platform: FreeBSD,13.0-RELEASE,arm,-jnone,-sdefault,-sdefault,-hcritbit **** dT 5.199 **** p1 output|Debug: Child (965) Started **** dT 5.371 **** p1 found expected text at 3,1: 'Unused sub foo, defined:' ** top === process p1 -expect-text 0 1 "(That was just a warning)" **** dT 5.372 **** p1 found expected text at 8,1: '(That was just a warning)' ** 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.923.08b24790/unref.vcl' Line 3 Pos 5) *** p1 screen|sub foo { } *** p1 screen|----###---- *** p1 screen| *** p1 screen|(That was just a warning) *** p1 screen| *** p1 screen|Debug: Version: varnish-trunk revision 271e2b8307ff56942ce05f04b90948027ca60cd8 *** p1 screen|Debug: Platform: FreeBSD,13.0-RELEASE,arm,-jnone,-sdefault,-sdefault,-hcritbit *** p1 screen|Debug: Child (965) Started *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| **** dT 5.373 *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 screen| *** p1 Cursor at line 13 column 1 ** top === process p2 -log {varnishadm -n ${tmpdir}/t "vcl.load unref $... **** dT 5.846 **** p2 CMD: varnishadm -n /root/VT/_vtest_tmp/vtc.923.08b24790/t "vcl.load unref /root/VT/_vtest_tmp/vtc.923.08b24790/unref.vcl" **** dT 5.869 *** p2 PID: 971 **** dT 5.980 **** p2 macro def p2_pid=971 **** p2 macro def p2_dir=/root/VT/_vtest_tmp/vtc.923.08b24790/p2 **** p2 macro def p2_out=/root/VT/_vtest_tmp/vtc.923.08b24790/p2/term **** p2 macro def p2_err=/root/VT/_vtest_tmp/vtc.923.08b24790/p2/stderr **** dT 6.307 **** p1 output|Child launched OK **** dT 6.418 **** p1 output|Info: Child (965) said Child starts **** dT 11.766 **** p2 stdout read 0 **** p2 stderr read 0 **** dT 11.767 ** p2 WAIT4 pid=971 status=0x0000 (user 0.066083 sys 0.099125) **** p2 macro undef p2_pid **** dT 11.768 **** p2 stdout 0 bytes, stderr 0 bytes ** top === process p2 -expect-text 0 1 "Unused sub foo, defined:" # top TEST ../../../../bin/varnishtest/tests/r03159.vtc TIMED OUT (kill -9) # top TEST ../../../../bin/varnishtest/tests/r03159.vtc FAILED (60.083) signal=9 FAIL tests/r03159.vtc (exit status: 2)