**** dT 0.000 * top TEST ../../../../bin/vinyltest/tests/r03940.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:32058 **** 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.90936.5367f449 **** top macro def vtcid=vtc.90936.5367f449 ** top === vtest "test startup_timeout vs. stevedore init / open" * top VTEST test startup_timeout vs. stevedore init / open ** top === vinyl v1 -arg "-sdebug=debug,dinit=5s -pstartup_timeout=3s -... **** dT 0.003 ** v1 Launch *** v1 CMD: cd ${pwd} && exec vinyld -d -n /root/VT/_vtest_tmp/vtc.90936.5367f449/v1 -i v1 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p thread_pool_min=10 -p debug=+vtc_mode -p vsl_mask=+Debug,+H2RxHdr,+H2RxBody -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -a '127.0.0.1:0' -M '127.0.0.1 44310' -P /root/VT/_vtest_tmp/vtc.90936.5367f449/v1/vinyld.pid -p vmod_path=/root/VT/vinyl-cache/vinyl-cache-trunk/_build/sub/vmod/.libs: -sdebug=debug,dinit=5s -pstartup_timeout=3s -pcli_timeout=2s -p feature=+no_coredump *** v1 CMD: cd /root/VT/vinyl-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest && exec vinyld -d -n /root/VT/_vtest_tmp/vtc.90936.5367f449/v1 -i v1 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p thread_pool_min=10 -p debug=+vtc_mode -p vsl_mask=+Debug,+H2RxHdr,+H2RxBody -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -a '127.0.0.1:0' -M '127.0.0.1 44310' -P /root/VT/_vtest_tmp/vtc.90936.5367f449/v1/vinyld.pid -p vmod_path=/root/VT/vinyl-cache/vinyl-cache-trunk/_build/sub/vmod/.libs: -sdebug=debug,dinit=5s -pstartup_timeout=3s -pcli_timeout=2s -p feature=+no_coredump *** v1 PID: 90952 **** v1 macro def v1_pid=90952 **** v1 macro def v1_name=/root/VT/_vtest_tmp/vtc.90936.5367f449/v1 **** dT 0.013 *** v1 debug|Debug: Version: vinyl-cache-trunk revision 35e0552fa5d594325c06a4ac44b951808442e2d0 *** v1 debug|Debug: Platform: FreeBSD,16.0-CURRENT,arm64,-jnone,-sdebug,-sdefault,-sdefault,-hcritbit *** v1 debug|200 324 *** v1 debug|----------------------------- *** v1 debug|Vinyl Cache CLI 1.0 *** v1 debug|----------------------------- *** v1 debug|FreeBSD,16.0-CURRENT,arm64,-jnone,-sdebug,-sdefault,-sdefault,-hcritbit *** v1 debug|vinyl-cache-trunk revision 35e0552fa5d594325c06a4ac44b951808442e2d0 *** v1 debug| *** v1 debug|Type 'help' for command list. *** v1 debug|Type 'quit' to close CLI session. *** v1 debug|Type 'start' to launch worker process. *** v1 debug| **** dT 0.118 **** v1 CLIPOLL 1 0x1 0x0 0x0 *** v1 CLI connection fd = 4 *** v1 CLI RX 107 **** v1 CLI RX|rtxkgsdckarzphpnkdldeciuvtcdcgah **** v1 CLI RX| **** v1 CLI RX|Authentication required. **** v1 CLI TX|auth fc62ea5196de7a41e8132204a1791c5181cdc133d9c07590764ca5d3ad024e88 **** dT 0.119 *** v1 CLI RX 200 **** v1 CLI RX|----------------------------- **** v1 CLI RX|Vinyl Cache CLI 1.0 **** v1 CLI RX|----------------------------- **** v1 CLI RX|FreeBSD,16.0-CURRENT,arm64,-jnone,-sdebug,-sdefault,-sdefault,-hcritbit **** v1 CLI RX|vinyl-cache-trunk revision 35e0552fa5d594325c06a4ac44b951808442e2d0 **** v1 CLI RX| **** v1 CLI RX|Type 'help' for command list. **** v1 CLI RX|Type 'quit' to close CLI session. **** v1 CLI RX|Type 'start' to launch worker process. **** v1 CLI TX|vcl.inline vcl1 << %XJEIFLH|)Xspa8P **** v1 CLI TX|vcl 4.1; **** v1 CLI TX|backend none none; **** v1 CLI TX|%XJEIFLH|)Xspa8P **** dT 0.219 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.319 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.419 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.519 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.612 *** v1 CLI RX 200 **** v1 CLI RX|VCL compiled. **** v1 CLI TX|vcl.use vcl1 **** dT 0.619 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.652 *** v1 CLI RX 200 **** v1 CLI RX|VCL 'vcl1' now active ** top === vinyl v1 -cliexpect \ **** dT 0.653 **** v1 CLI TX|start **** dT 0.698 *** v1 debug|Debug: Child (91030) Started **** dT 0.719 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.819 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.919 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 1.019 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 1.119 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 1.219 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 1.319 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 1.419 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 1.519 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 1.619 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 1.719 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 1.819 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 1.919 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 2.019 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 2.119 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 2.220 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 2.320 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 2.420 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 2.520 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 2.620 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 2.720 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 2.820 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 2.920 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 3.020 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 3.120 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 3.220 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 3.320 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 3.420 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 3.520 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 3.620 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 3.700 *** v1 debug|Error: Child failed on launch within startup_timeout=3.00s *** v1 debug|Error: Child (91030) said Child starts *** v1 debug|Error: Child (91030) said -sdebug init delay 5.000000s *** v1 debug|Error: Child (91030) said -sdebug open delay in init 0.000000s **** dT 3.720 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 3.800 *** v1 debug|Error: Child (91030) died signal=3 *** v1 debug|Debug: Child cleanup complete *** v1 CLI RX 400 **** v1 CLI RX|Child failed on launch within startup_timeout=3.00s ** v1 CLI 400 ** top === shell {vinyladm -n ${v1_name} panic.clear || true } **** top shell_cmd|set -e ;exec 2>&1 ; vinyladm -n /root/VT/_vtest_tmp/vtc.90936.5367f449/v1 panic.clear || true **** dT 3.811 **** top shell_out|No panic to clear **** top shell_out|Command failed with error code 300 **** top shell_status = 0x0000 ** top === vinyl v1 -wait ** v1 Wait **** v1 CLI TX|backend.list **** dT 3.820 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 3.863 *** v1 CLI RX 101 **** v1 CLI RX|Unknown request in manager process (child not running). **** v1 CLI RX|Type 'help' for more info. ** v1 Stop **** v1 CLI TX|stop **** dT 3.920 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 3.927 *** v1 CLI RX 300 **** v1 CLI RX|Child in state stopped *** v1 wait-stopped **** v1 CLI TX|status **** dT 4.020 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 4.029 *** v1 CLI RX 200 **** v1 CLI RX|Child in state stopped **** v1 CLI TX|panic.show **** dT 4.071 *** v1 CLI RX 300 **** v1 CLI RX|Child has not panicked or panic has been cleared *** v1 debug|Info: manager stopping child *** v1 debug|Info: manager dies **** dT 4.072 **** v1 STDOUT EOF **** dT 4.120 *** v1 vsl|No VSL chunk found (child not started ?) ** v1 WAIT4 pid=90952 status=0x4000 (user 0.362784 sys 0.039433) ** top === process p1 { vinyld \ **** dT 4.124 **** p1 CMD: vinyld \ -sdebug=debug,dinit=5s \ -pstartup_timeout=3s -pcli_timeout=2s \ -n /root/VT/_vtest_tmp/vtc.90936.5367f449/p1 -a :0 -b none 2>&1 **** dT 4.125 *** p1 PID: 91660 **** p1 macro def p1_pid=91660 **** p1 macro def p1_dir=/root/VT/_vtest_tmp/vtc.90936.5367f449/p1 **** p1 macro def p1_out=/root/VT/_vtest_tmp/vtc.90936.5367f449/p1/term **** p1 macro def p1_err=/root/VT/_vtest_tmp/vtc.90936.5367f449/p1/stderr **** dT 4.126 **** p1 stderr read 0 **** dT 7.683 **** p1 stdout read 0 ** p1 WAIT4 pid=91660 status=0x0200 (user 0.000000 sys 0.003838) **** p1 macro undef p1_pid **** p1 stdout 270 bytes, stderr 0 bytes ** top === shell {grep -q "Child failed on launch within startup_timeou... **** top shell_cmd|set -e ;exec 2>&1 ; grep -q "Child failed on launch within startup_timeout=3.00s" /root/VT/_vtest_tmp/vtc.90936.5367f449/p1/term **** dT 7.685 **** top shell_status = 0x0000 ** top === vinyl v2 -arg "-sdebug=debug,dopen=5s -pstartup_timeout=2s -... **** dT 7.689 ** v2 Launch *** v2 CMD: cd ${pwd} && exec vinyld -d -n /root/VT/_vtest_tmp/vtc.90936.5367f449/v2 -i v2 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p thread_pool_min=10 -p debug=+vtc_mode -p vsl_mask=+Debug,+H2RxHdr,+H2RxBody -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -a '127.0.0.1:0' -M '127.0.0.1 18368' -P /root/VT/_vtest_tmp/vtc.90936.5367f449/v2/vinyld.pid -p vmod_path=/root/VT/vinyl-cache/vinyl-cache-trunk/_build/sub/vmod/.libs: -sdebug=debug,dopen=5s -pstartup_timeout=2s -pcli_timeout=3s -p feature=+no_coredump *** v2 CMD: cd /root/VT/vinyl-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest && exec vinyld -d -n /root/VT/_vtest_tmp/vtc.90936.5367f449/v2 -i v2 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p thread_pool_min=10 -p debug=+vtc_mode -p vsl_mask=+Debug,+H2RxHdr,+H2RxBody -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -a '127.0.0.1:0' -M '127.0.0.1 18368' -P /root/VT/_vtest_tmp/vtc.90936.5367f449/v2/vinyld.pid -p vmod_path=/root/VT/vinyl-cache/vinyl-cache-trunk/_build/sub/vmod/.libs: -sdebug=debug,dopen=5s -pstartup_timeout=2s -pcli_timeout=3s -p feature=+no_coredump *** v2 PID: 92136 **** v2 macro def v2_pid=92136 **** v2 macro def v2_name=/root/VT/_vtest_tmp/vtc.90936.5367f449/v2 **** dT 7.699 *** v2 debug|Debug: Version: vinyl-cache-trunk revision 35e0552fa5d594325c06a4ac44b951808442e2d0 *** v2 debug|Debug: Platform: FreeBSD,16.0-CURRENT,arm64,-jnone,-sdebug,-sdefault,-sdefault,-hcritbit *** v2 debug|200 324 *** v2 debug|----------------------------- *** v2 debug|Vinyl Cache CLI 1.0 *** v2 debug|----------------------------- *** v2 debug|FreeBSD,16.0-CURRENT,arm64,-jnone,-sdebug,-sdefault,-sdefault,-hcritbit *** v2 debug|vinyl-cache-trunk revision 35e0552fa5d594325c06a4ac44b951808442e2d0 *** v2 debug| *** v2 debug|Type 'help' for command list. *** v2 debug|Type 'quit' to close CLI session. *** v2 debug|Type 'start' to launch worker process. *** v2 debug| **** dT 7.801 **** v2 CLIPOLL 1 0x1 0x0 0x0 *** v2 CLI connection fd = 11 *** v2 CLI RX 107 **** v2 CLI RX|qritunfzyosgnxxsuepntelhfqqrispk **** v2 CLI RX| **** v2 CLI RX|Authentication required. **** v2 CLI TX|auth fb6f3a7c04292328845ba49af3581cca3070e3554ff068f665bfef0f47f19f86 *** v2 CLI RX 200 **** v2 CLI RX|----------------------------- **** v2 CLI RX|Vinyl Cache CLI 1.0 **** v2 CLI RX|----------------------------- **** v2 CLI RX|FreeBSD,16.0-CURRENT,arm64,-jnone,-sdebug,-sdefault,-sdefault,-hcritbit **** v2 CLI RX|vinyl-cache-trunk revision 35e0552fa5d594325c06a4ac44b951808442e2d0 **** v2 CLI RX| **** v2 CLI RX|Type 'help' for command list. **** v2 CLI RX|Type 'quit' to close CLI session. **** v2 CLI RX|Type 'start' to launch worker process. **** v2 CLI TX|vcl.inline vcl1 << %XJEIFLH|)Xspa8P **** v2 CLI TX|vcl 4.1; **** v2 CLI TX|backend none none; **** v2 CLI TX|%XJEIFLH|)Xspa8P **** dT 7.901 *** v2 vsl|No VSL chunk found (child not started ?) **** dT 8.001 *** v2 vsl|No VSL chunk found (child not started ?) **** dT 8.101 *** v2 vsl|No VSL chunk found (child not started ?) **** dT 8.201 *** v2 vsl|No VSL chunk found (child not started ?) **** dT 8.226 *** v2 CLI RX 200 **** v2 CLI RX|VCL compiled. **** v2 CLI TX|vcl.use vcl1 **** dT 8.283 *** v2 CLI RX 200 **** v2 CLI RX|VCL 'vcl1' now active ** top === vinyl v2 -cliexpect \ **** v2 CLI TX|start **** dT 8.301 *** v2 vsl|No VSL chunk found (child not started ?) **** dT 8.329 *** v2 debug|Debug: Child (92149) Started **** dT 11.345 *** v2 debug|Error: Child failed on launch within cli_timeout=3.00s (tip: set startup_timeout) *** v2 debug|Error: Child (92149) said Child starts *** v2 debug|Error: Child (92149) said -sdebug init delay 0.000000s *** v2 debug|Error: Child (92149) said -sdebug open delay in init 5.000000s *** v2 debug|Error: Child (92149) said -sdebug open delay 5.000000s **** dT 11.445 *** v2 debug|Error: Child (92149) died signal=6 *** v2 debug|Error: Child (92149) Panic at: Tue, 12 May 2026 04:48:16 GMT *** v2 debug|Wrong turn at ../../../../bin/vinyld/cache/cache_main.c:388: *** v2 debug|It's time for the big quit *** v2 debug|version = vinyl-cache-trunk revision 35e0552fa5d594325c06a4ac44b951808442e2d0, vrt api = 23.0 *** v2 debug|ident = FreeBSD,16.0-CURRENT,arm64,-jnone,-sdebug,-sdefault,-sdefault,-hcritbit,kqueue *** v2 debug|now = 66314.008548 (mono), 1778561296.018800 (real) *** v2 debug|Backtrace: *** v2 debug| ip=0x3db160 sp=0x8059b430 *** v2 debug| ip=0x304554 sp=0x8059b450 *** v2 debug| ip=0x3042ac sp=0x8059b470 *** v2 debug| ip=0x3da014 sp=0x8059b5a0 *** v2 debug| ip=0x2fd404 sp=0x8059b5e0 *** v2 debug| ip=0x8476e1cc sp=0x8059b600 *** v2 debug| ip=0x8476d964 sp=0x8059b650 *** v2 debug| ip=0x80b4e1a4 sp=0x8059b6c0 *** v2 debug| ip=0xaa8c6ed0 sp=0x8059bae0 <__sys_nanosleep+0x8> *** v2 debug| ip=0x847705b4 sp=0x8059bae0 *** v2 debug| ip=0x3f6774 sp=0x8059bb10 *** v2 debug| ip=0x397064 sp=0x8059bb50 *** v2 debug| ip=0x391454 sp=0x8059bb70 *** v2 debug| ip=0x2fce44 sp=0x8059bfb0 *** v2 debug| ip=0x3743ec sp=0x8059c0e0 ** top === vinyl v2 -cliok "panic.clear" *** v2 debug|4> *** v2 debug| ip=0x375318 sp=0x8059c160 *** v2 debug| ip=0x3df40c sp=0x8059c190 *** v2 debug| ip=0x3deda8 sp=0x8059c1d0 *** v2 debug| ip=0x3ddc00 sp=0x8059c220 *** v2 debug| ip=0x3dd6bc sp=0x8059c290 *** v2 debug| ip=0x376374 sp=0x8059c700 *** v2 debug| ip=0x3e19f0 sp=0x8059c720 *** v2 debug| ip=0x3e1418 sp=0x8059c770 *** v2 debug| ip=0x37b608 sp=0x8059c7a0 *** v2 debug| ip=0x864ac7a0 sp=0x8059c9a0 <__libc_start1+0x1b4> *** v2 debug|argv = { *** v2 debug| [0] = \"vinyld\", *** v2 debug| [1] = \"-d\", *** v2 debug| [2] = \"-n\", *** v2 debug| [3] = \"/root/VT/_vtest_tmp/vtc.90936.5367f449/v2\", *** v2 debug| [4] = \"-i\", *** v2 debug| [5] = \"v2\", *** v2 debug| [6] = \"-l\", *** v2 debug| [7] = \"2m\", *** v2 debug| [8] = \"-p\", *** v2 debug| [9] = \"auto_restart=off\", *** v2 debug| [10] = \"-p\", *** v2 debug| [11] = \"syslog_cli_traffic=off\", *** v2 debug| [12] = \"-p\", *** v2 debug| [13] = \"thread_pool_min=10\", *** v2 debug| [14] = \"-p\", *** v2 debug| [15] = \"debug=+vtc_mode\", *** v2 debug| [16] = \"-p\", *** v2 debug| [17] = \"vsl_mask=+Debug,+H2RxHdr,+H2RxBody\", *** v2 debug| [18] = \"-p\", *** v2 debug| [19] = \"h2_initial_window_size=1m\", *** v2 debug| [20] = \"-p\", *** v2 debug| [21] = \"h2_rx_window_lo *** v2 debug|w_water=64k\", *** v2 debug| [22] = \"-a\", *** v2 debug| [23] = \"127.0.0.1:0\", *** v2 debug| [24] = \"-M\", *** v2 debug| [25] = \"127.0.0.1 18368\", *** v2 debug| [26] = \"-P\", *** v2 debug| [27] = \"/root/VT/_vtest_tmp/vtc.90936.5367f449/v2/vinyld.pid\", *** v2 debug| [28] = \"-p\", *** v2 debug| [29] = \"vmod_path=/root/VT/vinyl-cache/vinyl-cache-trunk/_build/sub/vmod/.libs:\", *** v2 debug| [30] = \"-sdebug=debug,dopen=5s\", *** v2 debug| [31] = \"-pstartup_timeout=2s\", *** v2 debug| [32] = \"-pcli_timeout=3s\", *** v2 debug| [33] = \"-p\", *** v2 debug| [34] = \"feature=+no_coredump\", *** v2 debug|} *** v2 debug|pthread.self = 0x84e0dc4d010 *** v2 debug|pthread.name = (cache-main) *** v2 debug|thr.req = NULL *** v2 debug|thr.busyobj = NULL *** v2 debug|thr.worker = NULL *** v2 debug|vmods = { *** v2 debug|}, *** v2 debug|pools = { *** v2 debug| pool = 0x84e0f001000 { *** v2 debug| nidle = 9, *** v2 debug| nthr = 10, *** v2 debug| lqueue = 0 *** v2 debug| }, *** v2 debug| pool = 0x84e0f001100 { *** v2 debug| nidle = 9, *** v2 debug| nthr = 10, *** v2 debug| lqueue = 0 *** v2 debug| }, *** v2 debug|}, *** v2 debug| *** v2 debug| *** v2 debug|Debug: Child cleanup complete **** v2 CLI TX|panic.clear **** dT 11.488 *** v2 CLI RX 200 ** v2 CLI 200 ** top === vinyl v2 -wait ** v2 Wait **** v2 CLI TX|backend.list **** dT 11.529 *** v2 CLI RX 101 **** v2 CLI RX|Unknown request in manager process (child not running). **** v2 CLI RX|Type 'help' for more info. ** v2 Stop **** v2 CLI TX|stop **** dT 11.570 *** v2 CLI RX 300 **** v2 CLI RX|Child in state stopped *** v2 wait-stopped **** v2 CLI TX|status **** dT 11.611 *** v2 CLI RX 200 **** v2 CLI RX|Child in state stopped **** v2 CLI TX|panic.show **** dT 11.677 *** v2 CLI RX 300 **** v2 CLI RX|Child has not panicked or panic has been cleared *** v2 debug|Info: manager stopping child *** v2 debug|Info: manager dies **** v2 STDOUT EOF **** dT 11.703 ** v2 WAIT4 pid=92136 status=0x4000 (user 0.337779 sys 0.065908) ** top === process p2 { vinyld \ **** dT 11.707 **** p2 CMD: vinyld \ -sdebug=debug,dopen=5s \ -pstartup_timeout=2s -pcli_timeout=3s \ -n /root/VT/_vtest_tmp/vtc.90936.5367f449/p2 -a :0 -b none **** dT 11.708 *** p2 PID: 92396 **** p2 macro def p2_pid=92396 **** p2 macro def p2_dir=/root/VT/_vtest_tmp/vtc.90936.5367f449/p2 **** p2 macro def p2_out=/root/VT/_vtest_tmp/vtc.90936.5367f449/p2/term **** p2 macro def p2_err=/root/VT/_vtest_tmp/vtc.90936.5367f449/p2/stderr **** dT 15.107 **** p2 stderr|Error: Child failed on launch **** p2 stderr|Error: Child (92456) said Child starts **** p2 stderr|Error: Child (92456) said -sdebug init delay 0.000000s **** p2 stderr|Error: Child (92456) said -sdebug open delay in init 5.000000s **** p2 stderr|Error: Child (92456) said -sdebug open delay 5.000000s **** dT 15.608 **** p2 stderr|Error: Child (92456) died signal=6 (core dumped) **** p2 stderr|Error: Child (92456) Panic at: Tue, 12 May 2026 04:48:20 GMT **** p2 stderr|Wrong turn at ../../../../bin/vinyld/cache/cache_main.c:388: **** p2 stderr|It's time for the big quit **** p2 stderr|version = vinyl-cache-trunk revision 35e0552fa5d594325c06a4ac44b951808442e2d0, vrt api = 23.0 **** p2 stderr|ident = FreeBSD,16.0-CURRENT,arm64,-jnone,-sdebug,-sdefault,-sdefault,-hcritbit,kqueue **** p2 stderr|now = 66317.770411 (mono), 1778561299.780663 (real) **** p2 stderr|Backtrace: **** p2 stderr| ip=0x3db160 sp=0x80558710 **** p2 stderr| ip=0x304554 sp=0x80558730 **** p2 stderr| ip=0x3042ac sp=0x80558750 **** p2 stderr| ip=0x3da014 sp=0x80558880 **** p2 stderr| ip=0x2fd404 sp=0x805588c0 **** p2 stderr| ip=0x8399e1cc sp=0x805588e0 **** p2 stderr| ip=0x8399d964 sp=0x80558930 **** p2 stderr| ip=0x80df11a4 sp=0x805589a0 **** p2 stderr| ip=0x98326ed0 sp=0x80558dc0 <__sys_nanosleep+0x8> **** p2 stderr| ip=0x839a05b4 sp=0x80558dc0 **** p2 stderr| ip=0x3f6774 sp=0x80558df0 **** p2 stderr| ip=0x397064 sp=0x80558e30 **** p2 stderr| ip=0x391454 sp=0x80558e50 **** p2 stderr| ip=0x2fce44 sp=0x80559290 **** p2 stderr| ip=0x3743ec sp=0x805593c0 **** p2 stderr| ip=0x373e60 sp=0x80559440 **** p2 stderr| ip=0x37b364 sp=0x80559460 **** p2 stderr| ip=0x860ac7a0 sp=0x80559660 <__libc_start1+0x1b4> **** p2 stderr|argv = { **** p2 stderr| [0] = \"vinyld\", **** p2 stderr| [1] = \"-sdebug=debug,dopen=5s\", **** p2 stderr| [2] = \"-pstartup_timeout=2s\", **** p2 stderr| [3] = \"-pcli_timeout=3s\", **** p2 stderr| [4] = \"-n\", **** p2 stderr| [5] = \"/root/VT/_vtest_tmp/vtc.90936.5367f449/p2\", **** p2 stderr| [6] = \"-a\", **** p2 stderr| [7] = \":0\", **** p2 stderr| [8] = \"-b\", **** p2 stderr| [9] = \"none\", **** p2 stderr|} **** p2 stderr|pthread.self = 0x397c2344d010 **** p2 stderr|pthread.name = (cache-main) **** p2 stderr|thr.req = NULL **** p2 stderr|thr.busyobj = NULL **** p2 stderr|thr.worker = NULL **** p2 stderr|vmods = { **** p2 stderr|}, **** p2 stderr|pools = { **** p2 stderr| pool = 0x397c24801000 { **** p2 stderr| nidle = 98, **** p2 stderr| nthr = 100, **** p2 stderr| lqueue = 0 **** p2 stderr| }, **** p2 stderr| pool = 0x397c24801100 { **** p2 stderr| nidle = 98, **** p2 stderr| nthr = 100, **** p2 stderr| lqueue = 0 **** p2 stderr| }, **** p2 stderr|}, **** p2 stderr| **** p2 stderr| **** dT 15.621 **** p2 stdout read 0 **** p2 stderr read 0 ** p2 WAIT4 pid=92396 status=0x0200 (user 0.000000 sys 0.003868) **** p2 macro undef p2_pid **** p2 stdout 0 bytes, stderr 2025 bytes ** top === shell {grep -q "launch within cli_timeout=3.00s (tip: set st... **** top shell_cmd|set -e ;exec 2>&1 ; grep -q "launch within cli_timeout=3.00s (tip: set startup_" /root/VT/_vtest_tmp/vtc.90936.5367f449/p2/stderr **** dT 15.624 **** top shell_status = 0x0001 ---- top shell_exit not as expected: got 0x0001 wanted 0x0000 * top RESETTING after ../../../../bin/vinyltest/tests/r03940.vtc * top TEST ../../../../bin/vinyltest/tests/r03940.vtc FAILED