**** 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:30558
**** 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.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.59826.141aae12
**** top   macro def vtcid=vtc.59826.141aae12
**** dT    0.004
**   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.073
**** p1    CMD: 
	varnishd -F -n "/root/VT/_vtest_tmp/vtc.59826.141aae12/t" -a "/root/VT/_vtest_tmp/vtc.59826.141aae12/sock" \
	    -p vcc_feature=-err_unref -f "/root/VT/_vtest_tmp/vtc.59826.141aae12/unref.vcl" \
	    -l 2m 2>&1

**** dT    0.106
***  p1    PID: 59846
**** dT    0.108
**** p1    macro def p1_pid=59846
**** dT    0.109
**** p1    macro def p1_dir=/root/VT/_vtest_tmp/vtc.59826.141aae12/p1
**** p1    macro def p1_out=/root/VT/_vtest_tmp/vtc.59826.141aae12/p1/term
**** p1    macro def p1_err=/root/VT/_vtest_tmp/vtc.59826.141aae12/p1/stderr
**   top   === shell {
**** dT    0.110
**** 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.59826.141aae12/t ping ||
**** top   shell_cmd|\tvarnishadm -n /root/VT/_vtest_tmp/vtc.59826.141aae12/t ping
**** dT    0.137
**** p1    stderr read 0
**** dT    9.671
**** p1    output|Warnings:
**** p1    output|Message from VCC-compiler:
**** p1    output|Unused sub foo, defined:
**** p1    output|('/root/VT/_vtest_tmp/vtc.59826.141aae12/unref.vcl' Line 4 Pos 13)
**** p1    output|        sub foo { }
**** p1    output|------------###----
**** p1    output|(That was just a warning)
**** dT    9.850
**** p1    output|Debug: Version: varnish-trunk revision 81c8e58b5e02365734b9d628d24b30a6eff0d88c
**** dT    9.882
**** p1    output|Debug: Platform: FreeBSD,13.5-RELEASE,arm,-jnone,-sdefault,-sdefault,-hcritbit
**** dT    9.995
**** p1    output|Debug: Child (59882) Started
**** dT    10.500
**** p1    output|Child launched OK
**** dT    10.539
**** p1    output|Info: Child (59882) said Child starts
**** dT    10.551
**** 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
**** p1    Sent signal 15
**** dT    10.552
**** p1    output|Error: Manager got SIGTERM from PID 59842
**** dT    10.674
**** p1    output|Debug: Stopping Child
**** dT    10.682
**** p1    output|Error: Child (59882) died signal=15
**** dT    10.879
**** p1    output|Debug: Child cleanup complete
**** dT    11.046
**** p1    output|Info: manager stopping child
**** dT    11.050
**** p1    output|Info: manager dies
**** dT    11.217
**** p1    stdout read 0
**** dT    11.220
**   p1    WAIT4 pid=59846 status=0x4000 (user 4.609877 sys 0.668788)
**** dT    11.561
**** p1    stdout 639 bytes, stderr 0 bytes
*    top   TEST ../../../../bin/varnishtest/tests/r03159.vtc FAILED
#    top  TEST ../../../../bin/varnishtest/tests/r03159.vtc FAILED (11.609) exit=2
FAIL tests/r03159.vtc (exit status: 2)