**** dT 0.001 * top TEST ../../../../bin/varnishtest/tests/r03159.vtc starting **** top extmacro def pkg_version=trunk **** top extmacro def pkg_branch=trunk **** 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:63845 **** 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 **** top extmacro def topsrc=/root/VT/varnish-cache/varnish-trunk/_build/sub/../.. **** dT 0.012 **** top macro def testdir=/root/VT/varnish-cache/varnish-trunk/_build/sub/bin/varnishtest/../../../../bin/varnishtest/tests **** dT 0.013 **** top macro def tmpdir=/root/VT/_vtest_tmp/vtc.94504.79e3bf5c **** top macro def vtcid=vtc.94504.79e3bf5c **** dT 0.014 ** top === varnishtest "double sub unref warning / warnings output for ... * top VTEST double sub unref warning / warnings output for -f ** top === filewrite ${tmpdir}/unref.vcl { **** dT 0.015 ** top === process p1 -log { **** dT 0.146 **** p1 CMD: varnishd -F -n "/root/VT/_vtest_tmp/vtc.94504.79e3bf5c/t" -a "/root/VT/_vtest_tmp/vtc.94504.79e3bf5c/sock" \ -p vcc_feature=-err_unref -f "/root/VT/_vtest_tmp/vtc.94504.79e3bf5c/unref.vcl" \ -l 2m 2>&1 **** dT 0.152 *** p1 PID: 94521 **** dT 0.154 **** p1 macro def p1_pid=94521 **** p1 macro def p1_dir=/root/VT/_vtest_tmp/vtc.94504.79e3bf5c/p1 **** p1 macro def p1_out=/root/VT/_vtest_tmp/vtc.94504.79e3bf5c/p1/term **** p1 macro def p1_err=/root/VT/_vtest_tmp/vtc.94504.79e3bf5c/p1/stderr **** dT 0.155 ** 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 /root/VT/_vtest_tmp/vtc.94504.79e3bf5c/t ping || **** top shell_cmd|\tvarnishadm -n /root/VT/_vtest_tmp/vtc.94504.79e3bf5c/t ping **** dT 0.209 **** p1 stderr read 0 **** dT 9.608 **** p1 output|Warnings: **** p1 output|Message from VCC-compiler: **** p1 output|Unused sub foo, defined: **** p1 output|('/root/VT/_vtest_tmp/vtc.94504.79e3bf5c/unref.vcl' Line 4 Pos 13) **** p1 output| sub foo { } **** p1 output|------------###---- **** p1 output|(That was just a warning) **** dT 9.746 **** p1 output|Debug: Version: varnish-trunk revision a95399f5b9eda1bfdba6ee6406c30a1ed0720167 **** dT 9.801 **** p1 output|Debug: Platform: FreeBSD,13.5-RELEASE,arm,-jnone,-sdefault,-sdefault,-hcritbit **** dT 10.066 **** p1 output|Debug: Child (94560) Started **** dT 10.701 **** p1 output|Child launched OK **** dT 10.726 **** p1 output|Info: Child (94560) said Child starts **** dT 10.734 **** top shell_out|..... **** top shell_out|Could not get hold of varnishd, is it running? **** top shell_out|... **** top shell_out|Authentication required **** top shell_out|Authentication required **** top shell_status = 0x0002 ---- top shell_exit not as expected: got 0x0002 wanted 0x0000 * top RESETTING after ../../../../bin/varnishtest/tests/r03159.vtc **** dT 10.736 **** p1 output|Error: Manager got SIGTERM from PID 94517 **** dT 10.843 **** p1 Sent signal 15 **** dT 10.852 **** p1 output|Debug: Stopping Child **** dT 10.853 **** p1 output|Error: Child (94560) died signal=15 **** dT 11.002 **** p1 output|Debug: Child cleanup complete **** dT 11.011 **** p1 output|Info: manager stopping child **** dT 11.012 **** p1 output|Info: manager dies **** dT 11.154 **** p1 stdout read 0 **** dT 11.156 ** p1 WAIT4 pid=94521 status=0x4000 (user 4.623032 sys 0.675795) **** dT 11.844 **** p1 stdout 639 bytes, stderr 0 bytes * top TEST ../../../../bin/varnishtest/tests/r03159.vtc FAILED # top TEST ../../../../bin/varnishtest/tests/r03159.vtc FAILED (11.852) exit=2 FAIL tests/r03159.vtc (exit status: 2)