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

**** dT    0.172
***  p1    PID: 96392
**** dT    0.174
**** p1    macro def p1_pid=96392
**** p1    macro def p1_dir=/root/VT/_vtest_tmp/vtc.96375.629914d7/p1
**** p1    macro def p1_out=/root/VT/_vtest_tmp/vtc.96375.629914d7/p1/term
**** p1    macro def p1_err=/root/VT/_vtest_tmp/vtc.96375.629914d7/p1/stderr
**** dT    0.175
**   top   === shell {
**** dT    0.176
**** 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.96375.629914d7/t ping ||
**** top   shell_cmd|\tvarnishadm -n /root/VT/_vtest_tmp/vtc.96375.629914d7/t ping
**** dT    0.310
**** p1    stderr read 0
**** dT    10.365
**** p1    output|Warnings:
**** p1    output|Message from VCC-compiler:
**** p1    output|Unused sub foo, defined:
**** p1    output|('/root/VT/_vtest_tmp/vtc.96375.629914d7/unref.vcl' Line 4 Pos 13)
**** p1    output|        sub foo { }
**** p1    output|------------###----
**** p1    output|(That was just a warning)
**** dT    10.598
**** p1    output|Debug: Version: varnish-trunk revision 36fad6905fd4cb6ca01aacbb7625a1966975d2f2
**** dT    10.646
**** p1    output|Debug: Platform: FreeBSD,13.5-RELEASE,arm,-jnone,-sdefault,-sdefault,-hcritbit
**** dT    10.741
**** p1    output|Debug: Child (96465) Started
**** dT    11.252
**** p1    output|Child launched OK
**** dT    11.313
**** p1    output|Info: Child (96465) said Child starts
**** dT    11.327
**** 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    11.328
**** p1    output|Error: Manager got SIGTERM from PID 96388
**** dT    11.356
**** p1    output|Debug: Stopping Child
**** dT    11.702
**** p1    output|Error: Child (96465) died signal=15
**** dT    11.772
**** p1    output|Debug: Child cleanup complete
**** dT    11.803
**** p1    output|Info: manager stopping child
**** dT    11.806
**** p1    output|Info: manager dies
**** dT    11.957
**** p1    stdout read 0
**** dT    11.960
**   p1    WAIT4 pid=96392 status=0x4000 (user 4.520923 sys 0.768257)
**** dT    12.356
**** p1    stdout 639 bytes, stderr 0 bytes
*    top   TEST ../../../../bin/varnishtest/tests/r03159.vtc FAILED
#    top  TEST ../../../../bin/varnishtest/tests/r03159.vtc FAILED (12.367) exit=2
FAIL tests/r03159.vtc (exit status: 2)