**** 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:65332
**** 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.26827.7be16a1f
**** top   macro def vtcid=vtc.26827.7be16a1f
**** 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 {
**** dT    0.006
**   top   === process p1 -log {
**** dT    0.079
**** p1    CMD: 
	varnishd -F -n "/root/VT/_vtest_tmp/vtc.26827.7be16a1f/t" -a "/root/VT/_vtest_tmp/vtc.26827.7be16a1f/sock" \
	    -p vcc_feature=-err_unref -f "/root/VT/_vtest_tmp/vtc.26827.7be16a1f/unref.vcl" \
	    -l 2m 2>&1

**** dT    0.086
***  p1    PID: 26844
**** dT    0.094
**** p1    macro def p1_pid=26844
**** p1    macro def p1_dir=/root/VT/_vtest_tmp/vtc.26827.7be16a1f/p1
**** p1    macro def p1_out=/root/VT/_vtest_tmp/vtc.26827.7be16a1f/p1/term
**** p1    macro def p1_err=/root/VT/_vtest_tmp/vtc.26827.7be16a1f/p1/stderr
**   top   === shell {
**** dT    0.095
**** 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.26827.7be16a1f/t ping ||
**** top   shell_cmd|\tvarnishadm -n /root/VT/_vtest_tmp/vtc.26827.7be16a1f/t ping
**** dT    0.122
**** p1    stderr read 0
**** dT    6.292
**** p1    output|Warnings:
**** p1    output|Message from VCC-compiler:
**** p1    output|Unused sub foo, defined:
**** p1    output|('/root/VT/_vtest_tmp/vtc.26827.7be16a1f/unref.vcl' Line 4 Pos 13)
**** p1    output|        sub foo { }
**** p1    output|------------###----
**** p1    output|(That was just a warning)
**** dT    6.445
**** p1    output|Debug: Version: varnish-trunk revision 276be1aa161cd0de9cb8be753d21bad494625196
**** dT    6.454
**** p1    output|Debug: Platform: FreeBSD,13.4-RC3,arm,-jnone,-sdefault,-sdefault,-hcritbit
**** dT    6.628
**** p1    output|Debug: Child (26879) Started
**** dT    7.260
**** p1    output|Child launched OK
**** dT    7.361
**** p1    output|Info: Child (26879) said Child starts
**** dT    7.443
**** top   shell_out|.....
**** top   shell_out|Could not get hold of varnishd, is it running?
**** 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    7.444
**** p1    output|Error: Manager got SIGTERM from PID 26840
**** dT    7.561
**** p1    Sent signal 15
**** dT    7.572
**** p1    output|Debug: Stopping Child
**** dT    7.578
**** p1    output|Error: Child (26879) died signal=15
**** dT    7.634
**** p1    output|Debug: Child cleanup complete
**** dT    7.639
**** p1    output|Info: manager stopping child
**** dT    7.643
**** p1    output|Info: manager dies
**** dT    7.719
**** p1    stdout read 0
**** dT    7.722
**   p1    WAIT4 pid=26844 status=0x4000 (user 4.750032 sys 0.775762)
**** dT    8.562
**** p1    stdout 635 bytes, stderr 0 bytes
**** dT    8.563
*    top   TEST ../../../../bin/varnishtest/tests/r03159.vtc FAILED
#    top  TEST ../../../../bin/varnishtest/tests/r03159.vtc FAILED (8.577) exit=2
FAIL tests/r03159.vtc (exit status: 2)