**** dT    0.000
*    top   TEST ../../../../bin/vinyltest/tests/r03159.vtc starting
**** top   extmacro def pkg_version=trunk
**** top   extmacro def pkg_branch=trunk
**** top   extmacro def pwd=/root/VT/vinyl-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest
**** 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:25894
**** 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/vinyl-cache/vinyl-cache-trunk/_build/sub
**** top   extmacro def topsrc=/root/VT/vinyl-cache/vinyl-cache-trunk/_build/sub/../..
**** top   macro def testdir=/root/VT/vinyl-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/../../../../bin/vinyltest/tests
**** top   macro def tmpdir=/root/VT/_vtest_tmp/vtc.58905.4c0cc8f6
**** top   macro def vtcid=vtc.58905.4c0cc8f6
**   top   === vtest "double sub unref warning / warnings output for -f"
*    top   VTEST double sub unref warning / warnings output for -f
**   top   === filewrite ${tmpdir}/unref.vcl {
**   top   === process p1 -log {
**** dT    0.004
**** p1    CMD: 
	vinyld -F -n "/root/VT/_vtest_tmp/vtc.58905.4c0cc8f6/t" -a "/root/VT/_vtest_tmp/vtc.58905.4c0cc8f6/sock" \
	    -p vcc_feature=-err_unref -f "/root/VT/_vtest_tmp/vtc.58905.4c0cc8f6/unref.vcl" \
	    -l 2m 2>&1

***  p1    PID: 58924
**** p1    macro def p1_pid=58924
**** p1    macro def p1_dir=/root/VT/_vtest_tmp/vtc.58905.4c0cc8f6/p1
**** p1    macro def p1_out=/root/VT/_vtest_tmp/vtc.58905.4c0cc8f6/p1/term
**** p1    macro def p1_err=/root/VT/_vtest_tmp/vtc.58905.4c0cc8f6/p1/stderr
**** dT    0.005
**   top   === shell {
**** top   shell_cmd|set -e ;exec 2>&1 ; 
**** top   shell_cmd|\t# wait for startup vcl.load to complete
**** top   shell_cmd|\tvinyladm -n /root/VT/_vtest_tmp/vtc.58905.4c0cc8f6/t ping ||
**** top   shell_cmd|\tvinyladm -n /root/VT/_vtest_tmp/vtc.58905.4c0cc8f6/t ping ||
**** top   shell_cmd|\tvinyladm -n /root/VT/_vtest_tmp/vtc.58905.4c0cc8f6/t ping ||
**** top   shell_cmd|\tvinyladm -n /root/VT/_vtest_tmp/vtc.58905.4c0cc8f6/t ping
**** dT    0.006
**** p1    stderr read 0
**** dT    0.713
**** p1    output|Warnings:
**** p1    output|Message from VCC-compiler:
**** p1    output|Unused sub foo, defined:
**** p1    output|('/root/VT/_vtest_tmp/vtc.58905.4c0cc8f6/unref.vcl' Line 4 Pos 13)
**** p1    output|        sub foo { }
**** p1    output|------------###----
**** p1    output|(That was just a warning)
**** dT    0.791
**** p1    output|Debug: Version: vinyl-cache-trunk revision 4196617a1817a29ddf54a7a666099aa02fe510f1
**** dT    0.792
**** p1    output|Debug: Platform: FreeBSD,16.0-CURRENT,arm64,-jnone,-sdefault,-sdefault,-hcritbit
**** dT    0.794
**** p1    output|Debug: Child (59035) Started
**** dT    0.820
**** p1    output|Child launched OK
**** dT    0.821
**** p1    output|Info: Child (59035) said Child starts
**** dT    0.822
**** top   shell_out|No -T in shared memory
**** top   shell_out|No -T in shared memory
**** top   shell_out|No -T in shared memory
**** 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/vinyltest/tests/r03159.vtc
**** p1    Sent signal 15
**** p1    output|Info: Manager got SIGTERM from PID 58920
**** p1    output|Debug: Stopping Child
**** dT    0.922
**** p1    output|Error: Child (59035) died signal=15
**** dT    0.925
**** p1    output|Debug: Child cleanup complete
**** p1    output|Info: manager stopping child
**** p1    output|Info: manager dies
**** dT    0.930
**** p1    stdout read 0
**   p1    WAIT4 pid=58924 status=0x4000 (user 0.354358 sys 0.056339)
**** dT    1.822
**** p1    stdout 644 bytes, stderr 0 bytes
*    top   TEST ../../../../bin/vinyltest/tests/r03159.vtc FAILED
#    top  TEST ../../../../bin/vinyltest/tests/r03159.vtc FAILED (1.823) exit=2
FAIL tests/r03159.vtc (exit status: 2)