**** dT 0.001 * 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:13700 **** 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.002 **** top macro def testdir=/root/VT/varnish-cache/varnish-trunk/_build/sub/bin/varnishtest/../../../../bin/varnishtest/tests **** top macro def tmpdir=/root/VT/_vtest_tmp/vtc.25407.6abee47b **** dT 0.003 ** top === varnishtest "double sub unref warning / warnings output for ... * top VTEST double sub unref warning / warnings output for -f ** top === shell { **** dT 0.004 **** 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.047 **** top shell_status = 0x0000 ** top === process p1 -winsz 100 80 -log { **** dT 0.122 **** p1 TIOCWINSZ -1 Bad file descriptor **** dT 0.123 **** p1 CMD: varnishd -F -n "/root/VT/_vtest_tmp/vtc.25407.6abee47b/t" -a "/root/VT/_vtest_tmp/vtc.25407.6abee47b/sock" \ -p vcc_err_unref=off -f "/root/VT/_vtest_tmp/vtc.25407.6abee47b/unref.vcl" \ -l 2m 2>&1 **** dT 0.129 *** p1 PID: 25425 **** dT 0.136 **** p1 macro def p1_pid=25425 **** p1 macro def p1_dir=/root/VT/_vtest_tmp/vtc.25407.6abee47b/p1 **** p1 macro def p1_out=/root/VT/_vtest_tmp/vtc.25407.6abee47b/p1/term **** p1 macro def p1_err=/root/VT/_vtest_tmp/vtc.25407.6abee47b/p1/stderr **** dT 0.137 ** top === process p1 -expect-text 0 1 "Unused sub foo, defined:" **** dT 0.175 **** p1 stderr read 0 **** dT 6.443 **** p1 output|Warnings: **** p1 output|Message from VCC-compiler: **** p1 output|Unused sub foo, defined: **** p1 output|('/root/VT/_vtest_tmp/vtc.25407.6abee47b/unref.vcl' Line 3 Pos 5) **** p1 output|sub foo { } **** p1 output|----###---- **** p1 output|(That was just a warning) **** dT 6.563 **** p1 output|Debug: Version: varnish-trunk revision 7b4e1a34670ba1360c701f7612b4f73165c9ea99 **** dT 6.572 **** p1 output|Debug: Platform: FreeBSD,13.0-RELEASE,arm,-jnone,-sdefault,-sdefault,-hcritbit **** dT 6.651 **** p1 output|Debug: Child (25435) Started **** dT 7.202 **** p1 output|Child launched OK **** dT 7.297 **** p1 output|Info: Child (25435) said Child starts **** dT 10.411 **** 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 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.25407.6abee47b/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 7b4e1a34670ba1360c701f7612b4f73165c9ea99 *** p1 screen|Debug: Platform: FreeBSD,13.0-RELEASE,arm,-jnone,-sdefault,-sdefault,-hcritbit **** dT 10.412 *** p1 screen|Debug: Child (25435) Started *** p1 screen|Child launched OK *** p1 screen|Info: Child (25435) said Child starts *** 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 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 10.413 *** 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 15 column 1 ** top === process p2 -log {varnishadm -n ${tmpdir}/t "vcl.load unref $... **** dT 10.494 **** p2 CMD: varnishadm -n /root/VT/_vtest_tmp/vtc.25407.6abee47b/t "vcl.load unref /root/VT/_vtest_tmp/vtc.25407.6abee47b/unref.vcl" **** dT 10.499 *** p2 PID: 25473 **** dT 10.505 **** p2 macro def p2_pid=25473 **** dT 10.506 **** p2 macro def p2_dir=/root/VT/_vtest_tmp/vtc.25407.6abee47b/p2 **** p2 macro def p2_out=/root/VT/_vtest_tmp/vtc.25407.6abee47b/p2/term **** p2 macro def p2_err=/root/VT/_vtest_tmp/vtc.25407.6abee47b/p2/stderr **** dT 16.818 **** p2 stdout read 0 **** p2 stderr read 0 **** dT 16.821 ** p2 WAIT4 pid=25473 status=0x0000 (user 0.035563 sys 0.128030) **** p2 macro undef p2_pid **** dT 16.822 **** 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.016) signal=9 FAIL tests/r03159.vtc (exit status: 2)