**** dT    0.000
*    top   TEST ../../../vmod/tests/std_c00001.vtc starting
**** top   extmacro def pkg_version=trunk
**** top   extmacro def pkg_branch=trunk
**** top   extmacro def pwd=/tmp/vtest.clang/varnish-cache/varnish-trunk/_build/sub/vmod
**** 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:45691
**** top   extmacro def listen_addr=127.0.0.1:0
**** top   extmacro def bad_ip=192.0.2.255
**** top   extmacro def topbuild=/tmp/vtest.clang/varnish-cache/varnish-trunk/_build/sub
**** top   extmacro def topsrc=/tmp/vtest.clang/varnish-cache/varnish-trunk/_build/sub/../..
**** top   macro def testdir=/tmp/vtest.clang/varnish-cache/varnish-trunk/_build/sub/vmod/../../../vmod/tests
**** top   macro def tmpdir=/tmp/vtest.clang/_vtest_tmp/vtc.1621.218e421a
**** top   macro def vtcid=vtc.1621.218e421a
**   top   === varnishtest "Test std.rollback"
*    top   VTEST Test std.rollback
**   top   === feature !workspace_emulator
**   top   === server s1 {
**   s1    Starting server
**** s1    macro def s1_addr=127.0.0.1
**** s1    macro def s1_port=38375
**** s1    macro def s1_sock=127.0.0.1:38375
*    s1    Listen on 127.0.0.1:38375
**   top   === varnish v1 -vcl+backend {
**** dT    0.004
**   s1    Started on 127.0.0.1:38375 (1 iterations)
**** dT    0.032
**   v1    Launch
***  v1    CMD: cd ${pwd} && exec varnishd  -d -n /tmp/vtest.clang/_vtest_tmp/vtc.1621.218e421a/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 41239' -P /tmp/vtest.clang/_vtest_tmp/vtc.1621.218e421a/v1/varnishd.pid -p vmod_path=/tmp/vtest.clang/varnish-cache/varnish-trunk/_build/sub/vmod/.libs 
***  v1    CMD: cd /tmp/vtest.clang/varnish-cache/varnish-trunk/_build/sub/vmod && exec varnishd  -d -n /tmp/vtest.clang/_vtest_tmp/vtc.1621.218e421a/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 41239' -P /tmp/vtest.clang/_vtest_tmp/vtc.1621.218e421a/v1/varnishd.pid -p vmod_path=/tmp/vtest.clang/varnish-cache/varnish-trunk/_build/sub/vmod/.libs 
***  v1    PID: 1649
**** v1    macro def v1_pid=1649
**** v1    macro def v1_name=/tmp/vtest.clang/_vtest_tmp/vtc.1621.218e421a/v1
**** dT    0.100
***  v1    debug|Debug: Version: varnish-trunk revision 886c592608848a29d6e25a8a94060e4f76409ed7
***  v1    debug|Debug: Platform: Linux,4.19.0-17-amd64,x86_64,-jnone,-sdefault,-sdefault,-hcritbit
***  v1    debug|200 316     
***  v1    debug|-----------------------------
***  v1    debug|Varnish Cache CLI 1.0
***  v1    debug|-----------------------------
***  v1    debug|Linux,4.19.0-17-amd64,x86_64,-jnone,-sdefault,-sdefault,-hcritbit
***  v1    debug|varnish-trunk revision 886c592608848a29d6e25a8a94060e4f76409ed7
***  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.201
**** v1    CLIPOLL 1 0x1 0x0 0x0
***  v1    CLI connection fd = 7
***  v1    CLI RX  107
**** v1    CLI RX|donmdgnmhdmyhlvnbkwyktswptmomsnx
**** v1    CLI RX|
**** v1    CLI RX|Authentication required.
**** v1    CLI TX|auth 994e6d9c5146f2e3e7abf453d1c963893da1d4b35e9f0586177fd89a2fa79af1
**** dT    0.202
***  v1    CLI RX  200
**** v1    CLI RX|-----------------------------
**** v1    CLI RX|Varnish Cache CLI 1.0
**** v1    CLI RX|-----------------------------
**** v1    CLI RX|Linux,4.19.0-17-amd64,x86_64,-jnone,-sdefault,-sdefault,-hcritbit
**** v1    CLI RX|varnish-trunk revision 886c592608848a29d6e25a8a94060e4f76409ed7
**** 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 s1 { .host = "127.0.0.1"; .port = "38375"; }
**** v1    CLI TX|
**** v1    CLI TX|
**** v1    CLI TX|\timport std;
**** v1    CLI TX|
**** v1    CLI TX|\tsub vcl_recv {
**** v1    CLI TX|\t\tif (req.url == "/foo") {
**** v1    CLI TX|\t\t\tset req.http.foobar = "bar";
**** v1    CLI TX|\t\t}
**** v1    CLI TX|\t}
**** v1    CLI TX|
**** v1    CLI TX|\tsub vcl_deliver {
**** v1    CLI TX|\t\tif (resp.status == 400) {
**** v1    CLI TX|\t\t\tstd.rollback(req);
**** v1    CLI TX|\t\t\tset req.url = "/bar";
**** v1    CLI TX|\t\t\treturn (restart);
**** v1    CLI TX|\t\t}
**** v1    CLI TX|\t}
**** v1    CLI TX|
**** v1    CLI TX|%XJEIFLH|)Xspa8P
**** dT    0.308
***  v1    vsl|No VSL chunk found (child not started ?)
**** dT    0.408
***  v1    vsl|No VSL chunk found (child not started ?)
**** dT    0.509
***  v1    vsl|No VSL chunk found (child not started ?)
**** dT    0.611
***  v1    vsl|No VSL chunk found (child not started ?)
**** dT    0.712
***  v1    vsl|No VSL chunk found (child not started ?)
**** dT    0.814
***  v1    vsl|No VSL chunk found (child not started ?)
**** dT    0.866
***  v1    CLI RX  200
**** v1    CLI RX|VCL compiled.
**** v1    CLI TX|vcl.use vcl1
***  v1    CLI RX  200
**** v1    CLI RX|VCL 'vcl1' now active
**   v1    Start
**** v1    CLI TX|start
**** dT    0.924
***  v1    vsl|No VSL chunk found (child not started ?)
**** dT    0.947
***  v1    debug|Debug: Child (1819) Started
**** dT    0.985
***  v1    debug|Child launched OK
**** dT    0.986
***  v1    CLI RX  200
***  v1    wait-running
**** v1    CLI TX|status
***  v1    debug|Info: Child (1819) said Child starts
***  v1    CLI RX  200
**** v1    CLI RX|Child in state running
**** v1    CLI TX|debug.listen_address
**** dT    1.035
**** v1    vsl|          0 CLI             - Rd vcl.load "vcl1" vcl_vcl1.1752996046.092345/vgc.so 1auto
**** v1    vsl|          0 CLI             - Wr 200 52 Loaded "vcl_vcl1.1752996046.092345/vgc.so" as "vcl1"
**** v1    vsl|          0 CLI             - Rd vcl.use "vcl1"
**** v1    vsl|          0 CLI             - Wr 200 0 
**** v1    vsl|          0 CLI             - Rd start
**** v1    vsl|          0 Debug           - sockopt: Setting SO_LINGER for a0=127.0.0.1:41933
**** v1    vsl|          0 Debug           - sockopt: Setting SO_KEEPALIVE for a0=127.0.0.1:41933
**** v1    vsl|          0 Debug           - sockopt: Setting SO_SNDTIMEO for a0=127.0.0.1:41933
**** v1    vsl|          0 Debug           - sockopt: Setting SO_RCVTIMEO for a0=127.0.0.1:41933
**** v1    vsl|          0 Debug           - sockopt: Setting TCP_NODELAY for a0=127.0.0.1:41933
**** v1    vsl|          0 Debug           - sockopt: Setting TCP_KEEPIDLE for a0=127.0.0.1:41933
**** v1    vsl|          0 Debug           - sockopt: Setting TCP_KEEPCNT for a0=127.0.0.1:41933
**** v1    vsl|          0 Debug           - sockopt: Setting TCP_KEEPINTVL for a0=127.0.0.1:41933
**** v1    vsl|          0 CLI             - Wr 200 0 
**** dT    1.049
***  v1    CLI RX  200
**** v1    CLI RX|a0 127.0.0.1 41933
**** v1    CLI TX|debug.xid 1000
**** dT    1.097
***  v1    CLI RX  200
**** v1    CLI RX|XID is 1000 chunk 1
**** v1    CLI TX|debug.listen_address
**** dT    1.136
**** v1    vsl|          0 CLI             - Rd debug.listen_address 
**** v1    vsl|          0 CLI             - Wr 200 19 a0 127.0.0.1 41933

**** v1    vsl|          0 CLI             - Rd debug.xid 1000 
**** v1    vsl|          0 CLI             - Wr 200 19 XID is 1000 chunk 1
**** dT    1.144
***  v1    CLI RX  200
**** v1    CLI RX|a0 127.0.0.1 41933
**   v1    Listen on 127.0.0.1 41933
**** v1    macro def v1_addr=127.0.0.1
**** v1    macro def v1_port=41933
**** v1    macro def v1_sock=127.0.0.1:41933
**** v1    macro def v1_a0_addr=127.0.0.1
**** v1    macro def v1_a0_port=41933
**** v1    macro def v1_a0_sock=127.0.0.1:41933
**   top   === client c1 {
**   c1    Starting client
**   c1    Waiting for client
**** dT    1.146
**   c1    Started on 127.0.0.1:41933 (1 iterations)
***  c1    Connect to 127.0.0.1:41933
**** dT    1.147
***  c1    connected fd 17 from 127.0.0.1 48966 to 127.0.0.1:41933
**   c1    === txreq -url "/foo" -hdr "foobar: foo"
**** c1    txreq|GET /foo HTTP/1.1\r
**** c1    txreq|foobar: foo\r
**** c1    txreq|Host: 127.0.0.1\r
**** c1    txreq|User-Agent: c1\r
**** c1    txreq|\r
**   c1    === rxresp
**** dT    1.150
***  s1    accepted fd 5 127.0.0.1 48228
**   s1    === rxreq
**** dT    1.151
**** s1    rxhdr|GET /foo HTTP/1.1\r
**** s1    rxhdr|Host: 127.0.0.1\r
**** s1    rxhdr|User-Agent: c1\r
**** s1    rxhdr|X-Forwarded-For: 127.0.0.1\r
**** s1    rxhdr|Via: 1.1 v1 (Varnish/trunk)\r
**** s1    rxhdr|foobar: bar\r
**** s1    rxhdr|Accept-Encoding: gzip\r
**** s1    rxhdr|X-Varnish: 1002\r
**** s1    rxhdr|\r
**** s1    rxhdrlen = 164
**** s1    http[ 0] |GET
**** s1    http[ 1] |/foo
**** s1    http[ 2] |HTTP/1.1
**** s1    http[ 3] |Host: 127.0.0.1
**** s1    http[ 4] |User-Agent: c1
**** s1    http[ 5] |X-Forwarded-For: 127.0.0.1
**** s1    http[ 6] |Via: 1.1 v1 (Varnish/trunk)
**** s1    http[ 7] |foobar: bar
**** s1    http[ 8] |Accept-Encoding: gzip
**** s1    http[ 9] |X-Varnish: 1002
**** s1    bodylen = 0
**   s1    === expect req.url == "/foo"
**** s1    EXPECT req.url (/foo) == "/foo" match
**   s1    === expect req.http.foobar == "bar"
**** s1    EXPECT req.http.foobar (bar) == "bar" match
**   s1    === txresp -status 400
**** s1    txresp|HTTP/1.1 400 OK\r
**** s1    txresp|Date: Sun, 20 Jul 2025 07:20:47 GMT\r
**** s1    txresp|Server: s1\r
**** s1    txresp|Content-Length: 0\r
**** s1    txresp|\r
**   s1    === accept
**** s1    Accepting
**** dT    1.165
***  s1    Accepted socket fd is 5
**   s1    === rxreq
**** s1    rxhdr|GET /bar HTTP/1.1\r
**** s1    rxhdr|foobar: foo\r
**** s1    rxhdr|Host: 127.0.0.1\r
**** s1    rxhdr|User-Agent: c1\r
**** s1    rxhdr|Accept-Encoding: gzip\r
**** s1    rxhdr|X-Varnish: 1004\r
**** s1    rxhdr|\r
**** s1    rxhdrlen = 107
**** s1    http[ 0] |GET
**** s1    http[ 1] |/bar
**** s1    http[ 2] |HTTP/1.1
**** s1    http[ 3] |foobar: foo
**** s1    http[ 4] |Host: 127.0.0.1
**** s1    http[ 5] |User-Agent: c1
**** s1    http[ 6] |Accept-Encoding: gzip
**** s1    http[ 7] |X-Varnish: 1004
**** s1    bodylen = 0
**   s1    === expect req.url == "/bar"
**** s1    EXPECT req.url (/bar) == "/bar" match
**   s1    === expect req.http.foobar == "foo"
**** s1    EXPECT req.http.foobar (foo) == "foo" match
**   s1    === txresp
**** s1    txresp|HTTP/1.1 200 OK\r
**** s1    txresp|Date: Sun, 20 Jul 2025 07:20:47 GMT\r
**** s1    txresp|Server: s1\r
**** s1    txresp|Content-Length: 0\r
**** s1    txresp|\r
**   s1    === accept
**** s1    Accepting
**** dT    1.178
**** c1    rxhdr|HTTP/1.1 200 OK\r
**** c1    rxhdr|Date: Sun, 20 Jul 2025 07:20:47 GMT\r
**** c1    rxhdr|Server: s1\r
**** c1    rxhdr|Content-Length: 0\r
**** c1    rxhdr|X-Varnish: 1003\r
**** c1    rxhdr|Age: 0\r
**** c1    rxhdr|Via: 1.1 v1 (Varnish/trunk)\r
**** c1    rxhdr|Accept-Ranges: bytes\r
**** c1    rxhdr|Connection: keep-alive\r
**** c1    rxhdr|\r
**** c1    rxhdrlen = 187
**** c1    http[ 0] |HTTP/1.1
**** c1    http[ 1] |200
**** c1    http[ 2] |OK
**** c1    http[ 3] |Date: Sun, 20 Jul 2025 07:20:47 GMT
**** c1    http[ 4] |Server: s1
**** c1    http[ 5] |Content-Length: 0
**** c1    http[ 6] |X-Varnish: 1003
**** c1    http[ 7] |Age: 0
**** c1    http[ 8] |Via: 1.1 v1 (Varnish/trunk)
**** c1    http[ 9] |Accept-Ranges: bytes
**** c1    http[10] |Connection: keep-alive
**** c1    bodylen = 0
***  c1    closing fd 17
**   c1    Ending
**   top   === varnish v1 -vcl+backend {
**** v1    CLI TX|vcl.inline vcl2 << %XJEIFLH|)Xspa8P
**** v1    CLI TX|vcl 4.1;
**** v1    CLI TX|backend s1 { .host = "127.0.0.1"; .port = "38375"; }
**** v1    CLI TX|
**** v1    CLI TX|
**** v1    CLI TX|\timport std;
**** v1    CLI TX|
**** v1    CLI TX|\tsub vcl_backend_fetch {
**** v1    CLI TX|\t\tif (bereq.url == "/baz") {
**** v1    CLI TX|\t\t\tset bereq.http.foobar = "qux";
**** v1    CLI TX|\t\t}
**** v1    CLI TX|\t}
**** v1    CLI TX|
**** v1    CLI TX|\tsub vcl_backend_response {
**** v1    CLI TX|\t\tif (beresp.status == 400) {
**** v1    CLI TX|\t\t\tstd.rollback(bereq);
**** v1    CLI TX|\t\t\tset bereq.url = "/qux";
**** v1    CLI TX|\t\t\treturn (retry);
**** v1    CLI TX|\t\t}
**** v1    CLI TX|\t}
**** v1    CLI TX|
**** v1    CLI TX|%XJEIFLH|)Xspa8P
**** dT    1.237
**** v1    vsl|          0 CLI             - Rd debug.listen_address 
**** v1    vsl|          0 CLI             - Wr 200 19 a0 127.0.0.1 41933

**** v1    vsl|       1000 Begin           c sess 0 HTTP/1
**** v1    vsl|       1000 SessOpen        c 127.0.0.1 48966 a0 127.0.0.1 41933 1752996047.039629 21
**** v1    vsl|       1000 Debug           c sockopt: SO_LINGER may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1000 Debug           c sockopt: SO_KEEPALIVE may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1000 Debug           c sockopt: SO_SNDTIMEO may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1000 Debug           c sockopt: SO_RCVTIMEO may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1000 Debug           c sockopt: TCP_NODELAY may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1000 Debug           c sockopt: TCP_KEEPIDLE may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1000 Debug           c sockopt: TCP_KEEPCNT may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1000 Debug           c sockopt: TCP_KEEPINTVL may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1000 Link            c req 1001 rxreq
**** v1    vsl|       1002 Begin           b bereq 1001 fetch
**** v1    vsl|       1002 VCL_use         b vcl1
**** v1    vsl|       1002 Timestamp       b Start: 1752996047.039996 0.000000 0.000000
**** v1    vsl|       1002 BereqMethod     b GET
**** v1    vsl|       1002 BereqURL        b /foo
**** v1    vsl|       1002 BereqProtocol   b HTTP/1.1
**** v1    vsl|       1002 BereqHeader     b Host: 127.0.0.1
**** v1    vsl|       1002 BereqHeader     b User-Agent: c1
**** v1    vsl|       1002 BereqHeader     b X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1002 BereqHeader     b Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1002 BereqHeader     b foobar: bar
**** v1    vsl|       1002 BereqHeader     b Accept-Encoding: gzip
**** v1    vsl|       1002 BereqHeader     b X-Varnish: 1002
**** v1    vsl|       1002 VCL_call        b BACKEND_FETCH
**** v1    vsl|       1002 VCL_return      b fetch
**** v1    vsl|       1002 Timestamp       b Fetch: 1752996047.040027 0.000030 0.000030
**** v1    vsl|       1002 Timestamp       b Connected: 1752996047.040164 0.000167 0.000136
**** v1    vsl|       1002 BackendOpen     b 24 s1 127.0.0.1 38375 127.0.0.1 48228 connect
**** v1    vsl|       1002 Timestamp       b Bereq: 1752996047.040244 0.000247 0.000080
**** v1    vsl|       1002 BerespProtocol  b HTTP/1.1
**** v1    vsl|       1002 BerespStatus    b 400
**** v1    vsl|       1002 BerespReason    b OK
**** v1    vsl|       1002 BerespHeader    b Date: Sun, 20 Jul 2025 07:20:47 GMT
**** v1    vsl|       1002 BerespHeader    b Server: s1
**** v1    vsl|       1002 BerespHeader    b Content-Length: 0
**** v1    vsl|       1002 Timestamp       b Beresp: 1752996047.041018 0.001021 0.000774
**** v1    vsl|       1002 TTL             b RFC -1 10 0 1752996047 1752996047 1752996047 0 0 cacheable
**** v1    vsl|       1002 VCL_call        b BACKEND_RESPONSE
**** v1    vsl|       1002 TTL             b VCL 120 10 0 1752996047 cacheable
**** v1    vsl|       1002 TTL             b VCL 120 10 0 1752996047 uncacheable
**** v1    vsl|       1002 VCL_return      b deliver
**** v1    vsl|       1002 Debug           b Missing content-range header or unknown range unit
**** v1    vsl|       1002 Timestamp       b Process: 1752996047.041046 0.001049 0.000027
**** v1    vsl|       1002 Filters         b 
**** v1    vsl|       1002 Storage         b malloc Transient
**** v1    vsl|       1002 Fetch_Body      b 0 none -
**** v1    vsl|       1002 BackendClose    b 24 s1 recycle
**** v1    vsl|       1002 Timestamp       b BerespBody: 1752996047.054120 0.014123 0.013074
**** v1    vsl|       1002 Length          b 0
**** v1    vsl|       1002 BereqAcct       b 164 0 164 87 0 87
**** v1    vsl|       1002 End             b 
**** v1    vsl|       1001 Begin           c req 1000 rxreq
**** v1    vsl|       1001 Timestamp       c Start: 1752996047.039734 0.000000 0.000000
**** v1    vsl|       1001 Timestamp       c Req: 1752996047.039734 0.000000 0.000000
**** v1    vsl|       1001 VCL_use         c vcl1
**** v1    vsl|       1001 ReqStart        c 127.0.0.1 48966 a0
**** v1    vsl|       1001 ReqMethod       c GET
**** v1    vsl|       1001 ReqURL          c /foo
**** v1    vsl|       1001 ReqProtocol     c HTTP/1.1
**** v1    vsl|       1001 ReqHeader       c foobar: foo
**** v1    vsl|       1001 ReqHeader       c Host: 127.0.0.1
**** v1    vsl|       1001 ReqHeader       c User-Agent: c1
**** v1    vsl|       1001 ReqHeader       c X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1001 ReqHeader       c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1001 VCL_call        c RECV
**** v1    vsl|       1001 ReqUnset        c foobar: foo
**** v1    vsl|       1001 ReqHeader       c foobar: bar
**** v1    vsl|       1001 VCL_return      c hash
**** v1    vsl|       1001 VCL_call        c HASH
**** v1    vsl|       1001 VCL_return      c lookup
**** v1    vsl|       1001 VCL_call        c MISS
**** v1    vsl|       1001 VCL_return      c fetch
**** v1    vsl|       1001 Link            c bereq 1002 fetch
**** v1    vsl|       1001 Timestamp       c Fetch: 1752996047.054237 0.014503 0.014503
**** v1    vsl|       1001 RespProtocol    c HTTP/1.1
**** v1    vsl|       1001 RespStatus      c 400
**** v1    vsl|       1001 RespReason      c OK
**** v1    vsl|       1001 RespHeader      c Date: Sun, 20 Jul 2025 07:20:47 GMT
**** v1    vsl|       1001 RespHeader      c Server: s1
**** v1    vsl|       1001 RespHeader      c Content-Length: 0
**** v1    vsl|       1001 RespHeader      c X-Varnish: 1001
**** v1    vsl|       1001 RespHeader      c Age: 0
**** v1    vsl|       1001 RespHeader      c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1001 VCL_call        c DELIVER
**** v1    vsl|       1001 RespProtocol    c HTTP/1.1
**** v1    vsl|       1001 RespStatus      c 400
**** v1    vsl|       1001 RespReason      c OK
**** v1    vsl|       1001 RespHeader      c Date: Sun, 20 Jul 2025 07:20:47 GMT
**** v1    vsl|       1001 RespHeader      c Server: s1
**** v1    vsl|       1001 RespHeader      c Content-Length: 0
**** v1    vsl|       1001 RespHeader      c X-Varnish: 1001
**** v1    vsl|       1001 RespHeader      c Age: 0
**** v1    vsl|       1001 RespHeader      c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1001 ReqURL          c /bar
**** v1    vsl|       1001 VCL_return      c restart
**** v1    vsl|       1001 Timestamp       c Restart: 1752996047.054310 0.014576 0.000072
**** v1    vsl|       1001 Link            c req 1003 restart
**** v1    vsl|       1001 End             c 
**** v1    vsl|       1004 Begin           b bereq 1003 fetch
**** v1    vsl|       1004 VCL_use         b vcl1
**** v1    vsl|       1004 Timestamp       b Start: 1752996047.054382 0.000000 0.000000
**** v1    vsl|       1004 BereqMethod     b GET
**** v1    vsl|       1004 BereqURL        b /bar
**** v1    vsl|       1004 BereqProtocol   b HTTP/1.1
**** v1    vsl|       1004 BereqHeader     b foobar: foo
**** v1    vsl|       1004 BereqHeader     b Host: 127.0.0.1
**** v1    vsl|       1004 BereqHeader     b User-Agent: c1
**** v1    vsl|       1004 BereqHeader     b Accept-Encoding: gzip
**** v1    vsl|       1004 BereqHeader     b X-Varnish: 1004
**** v1    vsl|       1004 VCL_call        b BACKEND_FETCH
**** v1    vsl|       1004 VCL_return      b fetch
**** v1    vsl|       1004 Timestamp       b Fetch: 1752996047.054402 0.000019 0.000019
**** v1    vsl|       1004 Timestamp       b Connected: 1752996047.054483 0.000100 0.000081
**** v1    vsl|       1004 BackendOpen     b 24 s1 127.0.0.1 38375 127.0.0.1 48230 connect
**** v1    vsl|       1004 Timestamp       b Bereq: 1752996047.054549 0.000166 0.000065
**** v1    vsl|       1004 BerespProtocol  b HTTP/1.1
**** v1    vsl|       1004 BerespStatus    b 200
**** v1    vsl|       1004 BerespReason    b OK
**** v1    vsl|       1004 BerespHeader    b Date: Sun, 20 Jul 2025 07:20:47 GMT
**** v1    vsl|       1004 BerespHeader    b Server: s1
**** v1    vsl|       1004 BerespHeader    b Content-Length: 0
**** v1    vsl|       1004 Timestamp       b Beresp: 1752996047.055073 0.000690 0.000524
**** v1    vsl|       1004 TTL             b RFC 120 10 0 1752996047 1752996047 1752996047 0 0 cacheable
**** v1    vsl|       1004 VCL_call        b BACKEND_RESPONSE
**** v1    vsl|       1004 VCL_return      b deliver
**** v1    vsl|       1004 Debug           b Missing content-range header or unknown range unit
**** v1    vsl|       1004 Timestamp       b Process: 1752996047.055137 0.000754 0.000064
**** v1    vsl|       1004 Filters         b 
**** v1    vsl|       1004 Storage         b malloc s0
**** v1    vsl|       1004 Fetch_Body      b 0 none -
**** v1    vsl|       1004 BackendClose    b 24 s1 recycle
**** v1    vsl|       1004 Timestamp       b BerespBody: 1752996047.066955 0.012572 0.011817
**** v1    vsl|       1004 Length          b 0
**** v1    vsl|       1004 BereqAcct       b 107 0 107 87 0 87
**** v1    vsl|       1004 End             b 
**** v1    vsl|       1003 Begin           c req 1001 restart
**** v1    vsl|       1003 Timestamp       c Start: 1752996047.054310 0.014576 0.000000
**** v1    vsl|       1003 ReqStart        c 127.0.0.1 48966 a0
**** v1    vsl|       1003 ReqMethod       c GET
**** v1    vsl|       1003 ReqURL          c /bar
**** v1    vsl|       1003 ReqProtocol     c HTTP/1.1
**** v1    vsl|       1003 ReqHeader       c foobar: foo
**** v1    vsl|       1003 ReqHeader       c Host: 127.0.0.1
**** v1    vsl|       1003 ReqHeader       c User-Agent: c1
**** v1    vsl|       1003 VCL_call        c RECV
**** v1    vsl|       1003 VCL_return      c hash
**** v1    vsl|       1003 VCL_call        c HASH
**** v1    vsl|       1003 VCL_return      c lookup
**** v1    vsl|       1003 VCL_call        c MISS
**** v1    vsl|       1003 VCL_return      c fetch
**** v1    vsl|       1003 Link            c bereq 1004 fetch
**** v1    vsl|       1003 Timestamp       c Fetch: 1752996047.067140 0.027405 0.012829
**** v1    vsl|       1003 RespProtocol    c HTTP/1.1
**** v1    vsl|       1003 RespStatus      c 200
**** v1    vsl|       1003 RespReason      c OK
**** v1    vsl|       1003 RespHeader      c Date: Sun, 20 Jul 2025 07:20:47 GMT
**** v1    vsl|       1003 RespHeader      c Server: s1
**** v1    vsl|       1003 RespHeader      c Content-Length: 0
**** v1    vsl|       1003 RespHeader      c X-Varnish: 1003
**** v1    vsl|       1003 RespHeader      c Age: 0
**** v1    vsl|       1003 RespHeader      c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1003 RespHeader      c Accept-Ranges: bytes
**** v1    vsl|       1003 VCL_call        c DELIVER
**** v1    vsl|       1003 VCL_return      c deliver
**** v1    vsl|       1003 Timestamp       c Process: 1752996047.067198 0.027463 0.000057
**** v1    vsl|       1003 Filters         c 
**** v1    vsl|       1003 RespHeader      c Connection: keep-alive
**** v1    vsl|       1003 Timestamp       c Resp: 1752996047.067253 0.027518 0.000054
**** v1    vsl|       1003 ReqAcct         c 67 0 67 187 0 187
**** v1    vsl|       1003 End             c 
**** v1    vsl|       1000 SessClose       c REM_CLOSE 0.029
**** v1    vsl|       1000 End             c 
**** dT    2.081
***  v1    CLI RX  200
**** v1    CLI TX|vcl.use vcl2
***  v1    CLI RX  200
**** v1    CLI RX|VCL 'vcl2' now active
**   top   === client c1 {
**   c1    Starting client
**   c1    Waiting for client
**** dT    2.084
**   c1    Started on 127.0.0.1:41933 (1 iterations)
***  c1    Connect to 127.0.0.1:41933
**** dT    2.085
***  c1    connected fd 17 from 127.0.0.1 48980 to 127.0.0.1:41933
**   c1    === txreq -url "/baz" -hdr "foobar: baz"
**** c1    txreq|GET /baz HTTP/1.1\r
**** c1    txreq|foobar: baz\r
**** c1    txreq|Host: 127.0.0.1\r
**** c1    txreq|User-Agent: c1\r
**** c1    txreq|\r
**   c1    === rxresp
**** dT    2.088
***  s1    Accepted socket fd is 5
**   s1    === rxreq
**** dT    2.089
**** s1    rxhdr|GET /baz HTTP/1.1\r
**** s1    rxhdr|Host: 127.0.0.1\r
**** s1    rxhdr|User-Agent: c1\r
**** s1    rxhdr|X-Forwarded-For: 127.0.0.1\r
**** s1    rxhdr|Via: 1.1 v1 (Varnish/trunk)\r
**** s1    rxhdr|Accept-Encoding: gzip\r
**** s1    rxhdr|X-Varnish: 1007\r
**** s1    rxhdr|foobar: qux\r
**** s1    rxhdr|\r
**** s1    rxhdrlen = 164
**** s1    http[ 0] |GET
**** s1    http[ 1] |/baz
**** s1    http[ 2] |HTTP/1.1
**** s1    http[ 3] |Host: 127.0.0.1
**** s1    http[ 4] |User-Agent: c1
**** s1    http[ 5] |X-Forwarded-For: 127.0.0.1
**** s1    http[ 6] |Via: 1.1 v1 (Varnish/trunk)
**** s1    http[ 7] |Accept-Encoding: gzip
**** s1    http[ 8] |X-Varnish: 1007
**** s1    http[ 9] |foobar: qux
**** s1    bodylen = 0
**   s1    === expect req.url == "/baz"
**** s1    EXPECT req.url (/baz) == "/baz" match
**   s1    === expect req.http.foobar == "qux"
**** s1    EXPECT req.http.foobar (qux) == "qux" match
**   s1    === txresp -status 400
**** s1    txresp|HTTP/1.1 400 OK\r
**** s1    txresp|Date: Sun, 20 Jul 2025 07:20:47 GMT\r
**** s1    txresp|Server: s1\r
**** s1    txresp|Content-Length: 0\r
**** s1    txresp|\r
**   s1    === accept
**** s1    Accepting
**** dT    2.100
***  s1    Accepted socket fd is 5
**   s1    === rxreq
**** dT    2.101
**** s1    rxhdr|GET /qux HTTP/1.1\r
**** s1    rxhdr|foobar: baz\r
**** s1    rxhdr|Host: 127.0.0.1\r
**** s1    rxhdr|User-Agent: c1\r
**** s1    rxhdr|X-Forwarded-For: 127.0.0.1\r
**** s1    rxhdr|Via: 1.1 v1 (Varnish/trunk)\r
**** s1    rxhdr|Accept-Encoding: gzip\r
**** s1    rxhdr|X-Varnish: 1008\r
**** s1    rxhdr|\r
**** s1    rxhdrlen = 164
**** s1    http[ 0] |GET
**** s1    http[ 1] |/qux
**** s1    http[ 2] |HTTP/1.1
**** s1    http[ 3] |foobar: baz
**** s1    http[ 4] |Host: 127.0.0.1
**** s1    http[ 5] |User-Agent: c1
**** s1    http[ 6] |X-Forwarded-For: 127.0.0.1
**** s1    http[ 7] |Via: 1.1 v1 (Varnish/trunk)
**** s1    http[ 8] |Accept-Encoding: gzip
**** s1    http[ 9] |X-Varnish: 1008
**** s1    bodylen = 0
**   s1    === expect req.url == "/qux"
**** s1    EXPECT req.url (/qux) == "/qux" match
**   s1    === expect req.http.foobar == "baz"
**** s1    EXPECT req.http.foobar (baz) == "baz" match
**   s1    === txresp
**** s1    txresp|HTTP/1.1 200 OK\r
**** s1    txresp|Date: Sun, 20 Jul 2025 07:20:47 GMT\r
**** s1    txresp|Server: s1\r
**** s1    txresp|Content-Length: 0\r
**** s1    txresp|\r
***  s1    shutting fd 5 (server run)
**   s1    Ending
**** dT    2.113
**** c1    rxhdr|HTTP/1.1 200 OK\r
**** c1    rxhdr|Date: Sun, 20 Jul 2025 07:20:47 GMT\r
**** c1    rxhdr|Server: s1\r
**** c1    rxhdr|Content-Length: 0\r
**** c1    rxhdr|X-Varnish: 1006\r
**** c1    rxhdr|Age: 0\r
**** c1    rxhdr|Via: 1.1 v1 (Varnish/trunk)\r
**** c1    rxhdr|Accept-Ranges: bytes\r
**** c1    rxhdr|Connection: keep-alive\r
**** c1    rxhdr|\r
**** c1    rxhdrlen = 187
**** c1    http[ 0] |HTTP/1.1
**** c1    http[ 1] |200
**** c1    http[ 2] |OK
**** c1    http[ 3] |Date: Sun, 20 Jul 2025 07:20:47 GMT
**** c1    http[ 4] |Server: s1
**** c1    http[ 5] |Content-Length: 0
**** c1    http[ 6] |X-Varnish: 1006
**** c1    http[ 7] |Age: 0
**** c1    http[ 8] |Via: 1.1 v1 (Varnish/trunk)
**** c1    http[ 9] |Accept-Ranges: bytes
**** c1    http[10] |Connection: keep-alive
**** c1    bodylen = 0
***  c1    closing fd 17
**   c1    Ending
**** dT    2.116
**   top   === server s1 -wait
**   s1    Waiting for server (4/-1)
**   top   === varnish v1 -cliok "param.set workspace_client 12000"
**** v1    CLI TX|param.set workspace_client 12000
**** dT    2.156
**** v1    vsl|          0 CLI             - Rd vcl.load vcl2 vcl_vcl2.1752996047.068216/vgc.so 1auto
**** v1    vsl|          0 CLI             - Wr 200 52 Loaded "vcl_vcl2.1752996047.068216/vgc.so" as "vcl2"
**** v1    vsl|          0 CLI             - Rd vcl.use vcl2
**** v1    vsl|          0 CLI             - Wr 200 0 
**** v1    vsl|       1005 Begin           c sess 0 HTTP/1
**** v1    vsl|       1005 SessOpen        c 127.0.0.1 48980 a0 127.0.0.1 41933 1752996047.974762 22
**** v1    vsl|       1005 Debug           c sockopt: SO_LINGER may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1005 Debug           c sockopt: SO_KEEPALIVE may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1005 Debug           c sockopt: SO_SNDTIMEO may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1005 Debug           c sockopt: SO_RCVTIMEO may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1005 Debug           c sockopt: TCP_NODELAY may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1005 Debug           c sockopt: TCP_KEEPIDLE may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1005 Debug           c sockopt: TCP_KEEPCNT may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1005 Debug           c sockopt: TCP_KEEPINTVL may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1005 Link            c req 1006 rxreq
**** v1    vsl|       1007 Begin           b bereq 1006 fetch
**** v1    vsl|       1007 VCL_use         b vcl2
**** v1    vsl|       1007 Timestamp       b Start: 1752996047.977938 0.000000 0.000000
**** v1    vsl|       1007 BereqMethod     b GET
**** v1    vsl|       1007 BereqURL        b /baz
**** v1    vsl|       1007 BereqProtocol   b HTTP/1.1
**** v1    vsl|       1007 BereqHeader     b foobar: baz
**** v1    vsl|       1007 BereqHeader     b Host: 127.0.0.1
**** v1    vsl|       1007 BereqHeader     b User-Agent: c1
**** v1    vsl|       1007 BereqHeader     b X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1007 BereqHeader     b Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1007 BereqHeader     b Accept-Encoding: gzip
**** v1    vsl|       1007 BereqHeader     b X-Varnish: 1007
**** v1    vsl|       1007 VCL_call        b BACKEND_FETCH
**** v1    vsl|       1007 BereqUnset      b foobar: baz
**** v1    vsl|       1007 BereqHeader     b foobar: qux
**** v1    vsl|       1007 VCL_return      b fetch
**** v1    vsl|       1007 Timestamp       b Fetch: 1752996047.977997 0.000059 0.000059
**** v1    vsl|       1007 Timestamp       b Connected: 1752996047.978198 0.000260 0.000200
**** v1    vsl|       1007 BackendOpen     b 24 s1 127.0.0.1 38375 127.0.0.1 48242 connect
**** v1    vsl|       1007 Timestamp       b Bereq: 1752996047.978246 0.000308 0.000048
**** v1    vsl|       1007 BerespProtocol  b HTTP/1.1
**** v1    vsl|       1007 BerespStatus    b 400
**** v1    vsl|       1007 BerespReason    b OK
**** v1    vsl|       1007 BerespHeader    b Date: Sun, 20 Jul 2025 07:20:47 GMT
**** v1    vsl|       1007 BerespHeader    b Server: s1
**** v1    vsl|       1007 BerespHeader    b Content-Length: 0
**** v1    vsl|       1007 Timestamp       b Beresp: 1752996047.978850 0.000911 0.000603
**** v1    vsl|       1007 TTL             b RFC -1 10 0 1752996048 1752996048 1752996047 0 0 cacheable
**** v1    vsl|       1007 VCL_call        b BACKEND_RESPONSE
**** v1    vsl|       1007 BackendClose    b 24 s1 recycle
**** v1    vsl|       1007 BereqURL        b /qux
**** v1    vsl|       1007 VCL_return      b retry
**** v1    vsl|       1007 Debug           b Missing content-range header or unknown range unit
**** v1    vsl|       1007 Timestamp       b Retry: 1752996047.989951 0.012012 0.011101
**** v1    vsl|       1007 Link            b bereq 1008 retry
**** v1    vsl|       1007 End             b 
**** v1    vsl|       1008 Begin           b bereq 1007 retry
**** v1    vsl|       1008 Timestamp       b Start: 1752996047.989951 0.012012 0.000000
**** v1    vsl|       1008 BereqMethod     b GET
**** v1    vsl|       1008 BereqURL        b /qux
**** v1    vsl|       1008 BereqProtocol   b HTTP/1.1
**** v1    vsl|       1008 BereqHeader     b foobar: baz
**** v1    vsl|       1008 BereqHeader     b Host: 127.0.0.1
**** v1    vsl|       1008 BereqHeader     b User-Agent: c1
**** v1    vsl|       1008 BereqHeader     b X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1008 BereqHeader     b Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1008 BereqHeader     b Accept-Encoding: gzip
**** v1    vsl|       1008 BereqHeader     b X-Varnish: 1008
**** v1    vsl|       1008 VCL_call        b BACKEND_FETCH
**** v1    vsl|       1008 VCL_return      b fetch
**** v1    vsl|       1008 Timestamp       b Fetch: 1752996047.989971 0.012032 0.000019
**** v1    vsl|       1008 Timestamp       b Connected: 1752996047.990039 0.012101 0.000068
**** v1    vsl|       1008 BackendOpen     b 24 s1 127.0.0.1 38375 127.0.0.1 48244 connect
**** v1    vsl|       1008 Timestamp       b Bereq: 1752996047.990077 0.012139 0.000037
**** v1    vsl|       1008 BerespProtocol  b HTTP/1.1
**** v1    vsl|       1008 BerespStatus    b 200
**** v1    vsl|       1008 BerespReason    b OK
**** v1    vsl|       1008 BerespHeader    b Date: Sun, 20 Jul 2025 07:20:47 GMT
**** v1    vsl|       1008 BerespHeader    b Server: s1
**** v1    vsl|       1008 BerespHeader    b Content-Length: 0
**** v1    vsl|       1008 Timestamp       b Beresp: 1752996047.990765 0.012827 0.000687
**** v1    vsl|       1008 TTL             b RFC 120 10 0 1752996048 1752996048 1752996047 0 0 cacheable
**** v1    vsl|       1008 VCL_call        b BACKEND_RESPONSE
**** v1    vsl|       1008 VCL_return      b deliver
**** v1    vsl|       1008 Debug           b Missing content-range header or unknown range unit
**** v1    vsl|       1008 Timestamp       b Process: 1752996047.990822 0.012884 0.000057
**** v1    vsl|       1008 Filters         b 
**** v1    vsl|       1008 Storage         b malloc s0
**** v1    vsl|       1008 Fetch_Body      b 0 none -
**** v1    vsl|       1008 BackendClose    b 24 s1 recycle
**** v1    vsl|       1008 Timestamp       b BerespBody: 1752996048.001968 0.024029 0.011145
**** v1    vsl|       1008 Length          b 0
**** v1    vsl|       1008 BereqAcct       b 328 0 328 174 0 174
**** v1    vsl|       1008 End             b 
**** v1    vsl|       1006 Begin           c req 1005 rxreq
**** v1    vsl|       1006 Timestamp       c Start: 1752996047.974823 0.000000 0.000000
**** v1    vsl|       1006 Timestamp       c Req: 1752996047.974823 0.000000 0.000000
**** v1    vsl|       1006 VCL_use         c vcl2
**** v1    vsl|       1006 ReqStart        c 127.0.0.1 48980 a0
**** v1    vsl|       1006 ReqMethod       c GET
**** v1    vsl|       1006 ReqURL          c /baz
**** v1    vsl|       1006 ReqProtocol     c HTTP/1.1
**** v1    vsl|       1006 ReqHeader       c foobar: baz
**** v1    vsl|       1006 ReqHeader       c Host: 127.0.0.1
**** v1    vsl|       1006 ReqHeader       c User-Agent: c1
**** v1    vsl|       1006 ReqHeader       c X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1006 ReqHeader       c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1006 VCL_call        c RECV
**** v1    vsl|       1006 VCL_return      c hash
**** v1    vsl|       1006 VCL_call        c HASH
**** v1    vsl|       1006 VCL_return      c lookup
**** v1    vsl|       1006 VCL_call        c MISS
**** v1    vsl|       1006 VCL_return      c fetch
**** v1    vsl|       1006 Link            c bereq 1007 fetch
**** v1    vsl|       1006 Timestamp       c Fetch: 1752996048.002036 0.027212 0.027212
**** v1    vsl|       1006 RespProtocol    c HTTP/1.1
**** v1    vsl|       1006 RespStatus      c 200
**** v1    vsl|       1006 RespReason      c OK
**** v1    vsl|       1006 RespHeader      c Date: Sun, 20 Jul 2025 07:20:47 GMT
**** v1    vsl|       1006 RespHeader      c Server: s1
**** v1    vsl|       1006 RespHeader      c Content-Length: 0
**** v1    vsl|       1006 RespHeader      c X-Varnish: 1006
**** v1    vsl|       1006 RespHeader      c Age: 0
**** v1    vsl|       1006 RespHeader      c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1006 RespHeader      c Accept-Ranges: bytes
**** v1    vsl|       1006 VCL_call        c DELIVER
**** v1    vsl|       1006 VCL_return      c deliver
**** v1    vsl|       1006 Timestamp       c Process: 1752996048.002078 0.027255 0.000042
**** v1    vsl|       1006 Filters         c 
**** v1    vsl|       1006 RespHeader      c Connection: keep-alive
**** v1    vsl|       1006 Timestamp       c Resp: 1752996048.002118 0.027294 0.000039
**** v1    vsl|       1006 ReqAcct         c 67 0 67 187 0 187
**** v1    vsl|       1006 End             c 
**** v1    vsl|       1005 SessClose       c REM_CLOSE 0.028
**** v1    vsl|       1005 End             c 
**** dT    2.160
***  v1    CLI RX  200
**   v1    CLI 200 <param.set workspace_client 12000>
**   top   === varnish v1 -cliok "param.set workspace_backend 12000"
**** v1    CLI TX|param.set workspace_backend 12000
**** dT    2.205
***  v1    CLI RX  200
**   v1    CLI 200 <param.set workspace_backend 12000>
**   top   === varnish v1 -vcl+backend {
**** v1    CLI TX|vcl.inline vcl3 << %XJEIFLH|)Xspa8P
**** v1    CLI TX|vcl 4.1;
**** v1    CLI TX|backend s1 { .host = "127.0.0.1"; .port = "38375"; }
**** v1    CLI TX|
**** v1    CLI TX|
**** v1    CLI TX|\timport std;
**** v1    CLI TX|\timport vtc;
**** v1    CLI TX|
**** v1    CLI TX|\tsub vcl_recv {
**** v1    CLI TX|\t\tset req.http.test = "1";
**** v1    CLI TX|\t}
**** v1    CLI TX|
**** v1    CLI TX|\tsub vcl_backend_fetch {
**** v1    CLI TX|\t\tunset bereq.http.test;
**** v1    CLI TX|\t}
**** v1    CLI TX|
**** v1    CLI TX|\tsub vcl_backend_response {
**** v1    CLI TX|\t\tstd.rollback(bereq);
**** v1    CLI TX|\t\tset beresp.http.test = bereq.http.test;
**** v1    CLI TX|\t\tvtc.workspace_alloc(backend, -10);
**** v1    CLI TX|\t}
**** v1    CLI TX|
**** v1    CLI TX|%XJEIFLH|)Xspa8P
**** dT    2.849
***  v1    CLI RX  200
**** v1    CLI TX|vcl.use vcl3
***  v1    CLI RX  200
**** v1    CLI RX|VCL 'vcl3' now active
**   top   === server s1 {
**   s1    Starting server
**** s1    macro def s1_addr=127.0.0.1
**** s1    macro def s1_port=38375
**** s1    macro def s1_sock=127.0.0.1:38375
*    s1    Listen on 127.0.0.1:38375
**   top   === client c1 {
**   c1    Starting client
**   c1    Waiting for client
**** dT    2.850
**   c1    Started on 127.0.0.1:41933 (1 iterations)
***  c1    Connect to 127.0.0.1:41933
**** dT    2.851
***  c1    connected fd 5 from 127.0.0.1 48994 to 127.0.0.1:41933
**   c1    === txreq -url /1
**** c1    txreq|GET /1 HTTP/1.1\r
**** c1    txreq|Host: 127.0.0.1\r
**** c1    txreq|User-Agent: c1\r
**** c1    txreq|\r
**   c1    === rxresp
**   s1    Started on 127.0.0.1:38375 (1 iterations)
**** dT    2.855
***  s1    accepted fd 17 127.0.0.1 48256
**   s1    === rxreq
**** dT    2.856
**** s1    rxhdr|GET /1 HTTP/1.1\r
**** s1    rxhdr|Host: 127.0.0.1\r
**** s1    rxhdr|User-Agent: c1\r
**** s1    rxhdr|X-Forwarded-For: 127.0.0.1\r
**** s1    rxhdr|Via: 1.1 v1 (Varnish/trunk)\r
**** s1    rxhdr|Accept-Encoding: gzip\r
**** s1    rxhdr|X-Varnish: 1011\r
**** s1    rxhdr|\r
**** s1    rxhdrlen = 149
**** s1    http[ 0] |GET
**** s1    http[ 1] |/1
**** s1    http[ 2] |HTTP/1.1
**** s1    http[ 3] |Host: 127.0.0.1
**** s1    http[ 4] |User-Agent: c1
**** s1    http[ 5] |X-Forwarded-For: 127.0.0.1
**** s1    http[ 6] |Via: 1.1 v1 (Varnish/trunk)
**** s1    http[ 7] |Accept-Encoding: gzip
**** s1    http[ 8] |X-Varnish: 1011
**** s1    bodylen = 0
**   s1    === expect req.url == "/1"
**** s1    EXPECT req.url (/1) == "/1" match
**   s1    === txresp
**** s1    txresp|HTTP/1.1 200 OK\r
**** s1    txresp|Date: Sun, 20 Jul 2025 07:20:48 GMT\r
**** s1    txresp|Server: s1\r
**** s1    txresp|Content-Length: 0\r
**** s1    txresp|\r
***  s1    shutting fd 17 (server run)
**   s1    Ending
**** dT    2.867
**** c1    rxhdr|HTTP/1.1 503 Backend fetch failed\r
**** c1    rxhdr|Server: Varnish\r
**** c1    rxhdr|X-Varnish: 1010\r
**** c1    rxhdr|Age: 0\r
**** c1    rxhdr|Via: 1.1 v1 (Varnish/trunk)\r
**** c1    rxhdr|Content-Length: 274\r
**** c1    rxhdr|Connection: keep-alive\r
**** c1    rxhdr|\r
**** c1    rxhdrlen = 153
**** c1    http[ 0] |HTTP/1.1
**** c1    http[ 1] |503
**** c1    http[ 2] |Backend fetch failed
**** c1    http[ 3] |Server: Varnish
**** c1    http[ 4] |X-Varnish: 1010
**** c1    http[ 5] |Age: 0
**** c1    http[ 6] |Via: 1.1 v1 (Varnish/trunk)
**** c1    http[ 7] |Content-Length: 274
**** c1    http[ 8] |Connection: keep-alive
**** c1    c-l|<!DOCTYPE html>
**** c1    c-l|<html>
**** c1    c-l|  <head>
**** c1    c-l|    <title>503 Backend fetch failed</title>
**** c1    c-l|  </head>
**** c1    c-l|  <body>
**** c1    c-l|    <h1>Error  Backend fetch failed</h1>
**** c1    c-l|    <p>Backend fetch failed</p>
**** c1    c-l|    <h3>Guru Meditation:</h3>
**** c1    c-l|    <p>XID: </p>
**** c1    c-l|    <hr>
**** c1    c-l|    <p>Varnish cache server</p>
**** c1    c-l|  </body>
**** c1    c-l|</html>
**** c1    bodylen = 274
**   c1    === expect resp.status == 503
**** c1    EXPECT resp.status (503) == "503" match
**   c1    === expect resp.http.test == <undef>
**** c1    EXPECT resp.http.test (<undef>) == "<undef>" match
***  c1    closing fd 5
**   c1    Ending
**   top   === server s1 -wait
**   s1    Waiting for server (4/-1)
**   top   === varnish v1 -vcl+backend {
**** v1    CLI TX|vcl.inline vcl4 << %XJEIFLH|)Xspa8P
**** v1    CLI TX|vcl 4.1;
**** v1    CLI TX|backend s1 { .host = "127.0.0.1"; .port = "38375"; }
**** v1    CLI TX|
**** v1    CLI TX|
**** v1    CLI TX|\timport std;
**** v1    CLI TX|\timport vtc;
**** v1    CLI TX|
**** v1    CLI TX|\tsub vcl_backend_fetch {
**** v1    CLI TX|\t\tvtc.workspace_alloc(backend, 1000);
**** v1    CLI TX|\t}
**** v1    CLI TX|
**** v1    CLI TX|\tsub vcl_backend_response {
**** v1    CLI TX|\t\tif (bereq.retries == 0) {
**** v1    CLI TX|\t\t\tvtc.workspace_alloc(backend, -10);
**** v1    CLI TX|\t\t\tstd.rollback(bereq);
**** v1    CLI TX|\t\t\treturn (retry);
**** v1    CLI TX|\t\t}
**** v1    CLI TX|\t}
**** v1    CLI TX|
**** v1    CLI TX|%XJEIFLH|)Xspa8P
**** dT    2.872
**** v1    vsl|          0 CLI             - Rd vcl.load vcl3 vcl_vcl3.1752996048.094702/vgc.so 1auto
**** v1    vsl|          0 CLI             - Wr 200 52 Loaded "vcl_vcl3.1752996048.094702/vgc.so" as "vcl3"
**** v1    vsl|          0 CLI             - Rd vcl.use vcl3
**** v1    vsl|          0 CLI             - Wr 200 0 
**** v1    vsl|       1009 Begin           c sess 0 HTTP/1
**** v1    vsl|       1009 SessOpen        c 127.0.0.1 48994 a0 127.0.0.1 41933 1752996048.741946 23
**** v1    vsl|       1009 Debug           c sockopt: SO_LINGER may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1009 Debug           c sockopt: SO_KEEPALIVE may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1009 Debug           c sockopt: SO_SNDTIMEO may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1009 Debug           c sockopt: SO_RCVTIMEO may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1009 Debug           c sockopt: TCP_NODELAY may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1009 Debug           c sockopt: TCP_KEEPIDLE may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1009 Debug           c sockopt: TCP_KEEPCNT may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1009 Debug           c sockopt: TCP_KEEPINTVL may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1009 Link            c req 1010 rxreq
**** v1    vsl|       1011 Begin           b bereq 1010 fetch
**** v1    vsl|       1011 VCL_use         b vcl3
**** v1    vsl|       1011 Timestamp       b Start: 1752996048.742592 0.000000 0.000000
**** v1    vsl|       1011 BereqMethod     b GET
**** v1    vsl|       1011 BereqURL        b /1
**** v1    vsl|       1011 BereqProtocol   b HTTP/1.1
**** v1    vsl|       1011 BereqHeader     b Host: 127.0.0.1
**** v1    vsl|       1011 BereqHeader     b User-Agent: c1
**** v1    vsl|       1011 BereqHeader     b X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1011 BereqHeader     b Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1011 BereqHeader     b test: 1
**** v1    vsl|       1011 BereqHeader     b Accept-Encoding: gzip
**** v1    vsl|       1011 BereqHeader     b X-Varnish: 1011
**** v1    vsl|       1011 VCL_call        b BACKEND_FETCH
**** v1    vsl|       1011 BereqUnset      b test: 1
**** v1    vsl|       1011 VCL_return      b fetch
**** v1    vsl|       1011 Timestamp       b Fetch: 1752996048.742646 0.000053 0.000053
**** v1    vsl|       1011 Timestamp       b Connected: 1752996048.742726 0.000133 0.000080
**** v1    vsl|       1011 BackendOpen     b 24 s1 127.0.0.1 38375 127.0.0.1 48256 connect
**** v1    vsl|       1011 Timestamp       b Bereq: 1752996048.742767 0.000174 0.000041
**** v1    vsl|       1011 BerespProtocol  b HTTP/1.1
**** v1    vsl|       1011 BerespStatus    b 200
**** v1    vsl|       1011 BerespReason    b OK
**** v1    vsl|       1011 BerespHeader    b Date: Sun, 20 Jul 2025 07:20:48 GMT
**** v1    vsl|       1011 BerespHeader    b Server: s1
**** v1    vsl|       1011 BerespHeader    b Content-Length: 0
**** v1    vsl|       1011 Timestamp       b Beresp: 1752996048.746173 0.003580 0.003406
**** v1    vsl|       1011 TTL             b RFC 120 10 0 1752996049 1752996049 1752996048 0 0 cacheable
**** v1    vsl|       1011 VCL_call        b BACKEND_RESPONSE
**** v1    vsl|       1011 BackendClose    b 24 s1 recycle
**** v1    vsl|       1011 BerespHeader    b test: 1
**** v1    vsl|       1011 VCL_return      b deliver
**** v1    vsl|       1011 Debug           b Missing content-range header or unknown range unit
**** v1    vsl|       1011 Timestamp       b Process: 1752996048.756368 0.013775 0.010194
**** v1    vsl|       1011 FetchError      b No backend connection (rollback?)
**** v1    vsl|       1011 Timestamp       b Error: 1752996048.756375 0.013782 0.000007
**** v1    vsl|       1011 BerespProtocol  b HTTP/1.1
**** v1    vsl|       1011 BerespStatus    b 503
**** v1    vsl|       1011 BerespReason    b Backend fetch failed
**** v1    vsl|       1011 Error           b out of workspace (bo)
**** v1    vsl|       1011 LostHeader      b Date: 
**** v1    vsl|       1011 BerespHeader    b Server: Varnish
**** v1    vsl|       1011 VCL_call        b BACKEND_ERROR
**** v1    vsl|       1011 LostHeader      b Content-Type:
**** v1    vsl|       1011 LostHeader      b Retry-After:
**** v1    vsl|       1011 VCL_return      b deliver
**** v1    vsl|       1011 Storage         b malloc Transient
**** v1    vsl|       1011 Length          b 274
**** v1    vsl|       1011 BereqAcct       b 149 0 149 87 0 87
**** v1    vsl|       1011 End             b 
**** v1    vsl|       1010 Begin           c req 1009 rxreq
**** v1    vsl|       1010 Timestamp       c Start: 1752996048.742002 0.000000 0.000000
**** v1    vsl|       1010 Timestamp       c Req: 1752996048.742002 0.000000 0.000000
**** v1    vsl|       1010 VCL_use         c vcl3
**** v1    vsl|       1010 ReqStart        c 127.0.0.1 48994 a0
**** v1    vsl|       1010 ReqMethod       c GET
**** v1    vsl|       1010 ReqURL          c /1
**** v1    vsl|       1010 ReqProtocol     c HTTP/1.1
**** v1    vsl|       1010 ReqHeader       c Host: 127.0.0.1
**** v1    vsl|       1010 ReqHeader       c User-Agent: c1
**** v1    vsl|       1010 ReqHeader       c X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1010 ReqHeader       c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1010 VCL_call        c RECV
**** v1    vsl|       1010 ReqHeader       c test: 1
**** v1    vsl|       1010 VCL_return      c hash
**** v1    vsl|       1010 VCL_call        c HASH
**** v1    vsl|       1010 VCL_return      c lookup
**** v1    vsl|       1010 VCL_call        c MISS
**** v1    vsl|       1010 VCL_return      c fetch
**** v1    vsl|       1010 Link            c bereq 1011 fetch
**** v1    vsl|       1010 Timestamp       c Fetch: 1752996048.756536 0.014533 0.014533
**** v1    vsl|       1010 RespProtocol    c HTTP/1.1
**** v1    vsl|       1010 RespStatus      c 503
**** v1    vsl|       1010 RespReason      c Backend fetch failed
**** v1    vsl|       1010 RespHeader      c Server: Varnish
**** v1    vsl|       1010 RespHeader      c X-Varnish: 1010
**** v1    vsl|       1010 RespHeader      c Age: 0
**** v1    vsl|       1010 RespHeader      c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1010 VCL_call        c DELIVER
**** v1    vsl|       1010 VCL_return      c deliver
**** v1    vsl|       1010 Timestamp       c Process: 1752996048.756560 0.014557 0.000023
**** v1    vsl|       1010 Filters         c 
**** v1    vsl|       1010 RespHeader      c Content-Length: 274
**** v1    vsl|       1010 RespHeader      c Connection: keep-alive
**** v1    vsl|       1010 Timestamp       c Resp: 1752996048.756620 0.014617 0.000060
**** v1    vsl|       1010 ReqAcct         c 52 0 52 153 274 427
**** v1    vsl|       1010 End             c 
**** v1    vsl|       1009 SessClose       c REM_CLOSE 0.016
**** v1    vsl|       1009 End             c 
**** dT    3.696
***  v1    CLI RX  200
**** v1    CLI TX|vcl.use vcl4
***  v1    CLI RX  200
**** v1    CLI RX|VCL 'vcl4' now active
**   top   === server s1 -repeat 2 -keepalive {
**   s1    Starting server
**** s1    macro def s1_addr=127.0.0.1
**** s1    macro def s1_port=38375
**** s1    macro def s1_sock=127.0.0.1:38375
*    s1    Listen on 127.0.0.1:38375
**   top   === client c2 {
**   c2    Starting client
**   c2    Waiting for client
**** dT    3.697
**   c2    Started on 127.0.0.1:41933 (1 iterations)
***  c2    Connect to 127.0.0.1:41933
***  c2    connected fd 5 from 127.0.0.1 49002 to 127.0.0.1:41933
**   c2    === txreq -url /2
**** c2    txreq|GET /2 HTTP/1.1\r
**** c2    txreq|Host: 127.0.0.1\r
**** c2    txreq|User-Agent: c2\r
**** c2    txreq|\r
**   c2    === rxresp
**** dT    3.704
**   s1    Started on 127.0.0.1:38375 (2 iterations using keepalive)
**** dT    3.716
***  s1    accepted fd 17 127.0.0.1 48264
**   s1    === rxreq
**** dT    3.717
**** s1    rxhdr|GET /2 HTTP/1.1\r
**** s1    rxhdr|Host: 127.0.0.1\r
**** s1    rxhdr|User-Agent: c2\r
**** s1    rxhdr|X-Forwarded-For: 127.0.0.1\r
**** s1    rxhdr|Via: 1.1 v1 (Varnish/trunk)\r
**** s1    rxhdr|Accept-Encoding: gzip\r
**** s1    rxhdr|X-Varnish: 1014\r
**** s1    rxhdr|\r
**** s1    rxhdrlen = 149
**** s1    http[ 0] |GET
**** s1    http[ 1] |/2
**** s1    http[ 2] |HTTP/1.1
**** s1    http[ 3] |Host: 127.0.0.1
**** s1    http[ 4] |User-Agent: c2
**** s1    http[ 5] |X-Forwarded-For: 127.0.0.1
**** s1    http[ 6] |Via: 1.1 v1 (Varnish/trunk)
**** s1    http[ 7] |Accept-Encoding: gzip
**** s1    http[ 8] |X-Varnish: 1014
**** s1    bodylen = 0
**   s1    === expect req.url == "/2"
**** s1    EXPECT req.url (/2) == "/2" match
**   s1    === txresp
**** s1    txresp|HTTP/1.1 200 OK\r
**** s1    txresp|Date: Sun, 20 Jul 2025 07:20:49 GMT\r
**** s1    txresp|Server: s1\r
**** s1    txresp|Content-Length: 0\r
**** s1    txresp|\r
**   s1    === rxreq
**** dT    3.728
**** s1    rxhdr|GET /2 HTTP/1.1\r
**** s1    rxhdr|Host: 127.0.0.1\r
**** s1    rxhdr|User-Agent: c2\r
**** s1    rxhdr|X-Forwarded-For: 127.0.0.1\r
**** s1    rxhdr|Via: 1.1 v1 (Varnish/trunk)\r
**** s1    rxhdr|Accept-Encoding: gzip\r
**** s1    rxhdr|X-Varnish: 1015\r
**** s1    rxhdr|\r
**** s1    rxhdrlen = 149
**** s1    http[ 0] |GET
**** s1    http[ 1] |/2
**** s1    http[ 2] |HTTP/1.1
**** s1    http[ 3] |Host: 127.0.0.1
**** s1    http[ 4] |User-Agent: c2
**** s1    http[ 5] |X-Forwarded-For: 127.0.0.1
**** s1    http[ 6] |Via: 1.1 v1 (Varnish/trunk)
**** s1    http[ 7] |Accept-Encoding: gzip
**** s1    http[ 8] |X-Varnish: 1015
**** s1    bodylen = 0
**   s1    === expect req.url == "/2"
**** s1    EXPECT req.url (/2) == "/2" match
**   s1    === txresp
**** s1    txresp|HTTP/1.1 200 OK\r
**** s1    txresp|Date: Sun, 20 Jul 2025 07:20:49 GMT\r
**** s1    txresp|Server: s1\r
**** s1    txresp|Content-Length: 0\r
**** s1    txresp|\r
**** dT    3.729
***  s1    shutting fd 17 (server run)
**   s1    Ending
**** dT    3.747
**** c2    rxhdr|HTTP/1.1 200 OK\r
**** c2    rxhdr|Date: Sun, 20 Jul 2025 07:20:49 GMT\r
**** c2    rxhdr|Server: s1\r
**** c2    rxhdr|Content-Length: 0\r
**** c2    rxhdr|X-Varnish: 1013\r
**** c2    rxhdr|Age: 0\r
**** c2    rxhdr|Via: 1.1 v1 (Varnish/trunk)\r
**** c2    rxhdr|Accept-Ranges: bytes\r
**** c2    rxhdr|Connection: keep-alive\r
**** c2    rxhdr|\r
**** c2    rxhdrlen = 187
**** c2    http[ 0] |HTTP/1.1
**** c2    http[ 1] |200
**** c2    http[ 2] |OK
**** c2    http[ 3] |Date: Sun, 20 Jul 2025 07:20:49 GMT
**** c2    http[ 4] |Server: s1
**** c2    http[ 5] |Content-Length: 0
**** c2    http[ 6] |X-Varnish: 1013
**** c2    http[ 7] |Age: 0
**** c2    http[ 8] |Via: 1.1 v1 (Varnish/trunk)
**** c2    http[ 9] |Accept-Ranges: bytes
**** c2    http[10] |Connection: keep-alive
**** c2    bodylen = 0
**   c2    === expect resp.status == 200
**** c2    EXPECT resp.status (200) == "200" match
***  c2    closing fd 5
**   c2    Ending
**   top   === server s1 -wait
**   s1    Waiting for server (4/-1)
**   top   === varnish v1 -vcl+backend {
**** v1    CLI TX|vcl.inline vcl5 << %XJEIFLH|)Xspa8P
**** v1    CLI TX|vcl 4.1;
**** v1    CLI TX|backend s1 { .host = "127.0.0.1"; .port = "38375"; }
**** v1    CLI TX|
**** v1    CLI TX|
**** v1    CLI TX|\timport std;
**** v1    CLI TX|\timport vtc;
**** v1    CLI TX|
**** v1    CLI TX|\tsub vcl_backend_fetch {
**** v1    CLI TX|\t\tset bereq.http.fetch = "Fetch value " + bereq.retries;
**** v1    CLI TX|\t}
**** v1    CLI TX|
**** v1    CLI TX|\tsub vcl_backend_response {
**** v1    CLI TX|\t\tif (bereq.retries == 0) {
**** v1    CLI TX|\t\t\tstd.rollback(bereq);
**** v1    CLI TX|\t\t\tset bereq.http.response = "123";
**** v1    CLI TX|\t\t\tset bereq.http.response2 = "Another response";
**** v1    CLI TX|\t\t\tif (bereq.url == "/4") {
**** v1    CLI TX|\t\t\t\tvtc.workspace_alloc(backend, -10);
**** v1    CLI TX|\t\t\t} else if (bereq.url == "/5") {
**** v1    CLI TX|\t\t\t\tvtc.workspace_alloc(backend, -10);
**** v1    CLI TX|\t\t\t\tstd.rollback(bereq);
**** v1    CLI TX|\t\t\t}
**** v1    CLI TX|\t\t\treturn (retry);
**** v1    CLI TX|\t\t}
**** v1    CLI TX|\t\tset beresp.http.fetch = bereq.http.fetch;
**** v1    CLI TX|\t\tset beresp.http.response = bereq.http.response;
**** v1    CLI TX|\t\tset beresp.http.response2 = bereq.http.response2;
**** v1    CLI TX|\t}
**** v1    CLI TX|
**** v1    CLI TX|%XJEIFLH|)Xspa8P
**** dT    3.798
**** v1    vsl|          0 CLI             - Rd vcl.load vcl4 vcl_vcl4.1752996048.757368/vgc.so 1auto
**** v1    vsl|          0 CLI             - Wr 200 52 Loaded "vcl_vcl4.1752996048.757368/vgc.so" as "vcl4"
**** v1    vsl|          0 CLI             - Rd vcl.use vcl4
**** v1    vsl|          0 CLI             - Wr 200 0 
**** v1    vsl|       1012 Begin           c sess 0 HTTP/1
**** v1    vsl|       1012 SessOpen        c 127.0.0.1 49002 a0 127.0.0.1 41933 1752996049.602292 21
**** v1    vsl|       1012 Debug           c sockopt: SO_LINGER may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1012 Debug           c sockopt: SO_KEEPALIVE may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1012 Debug           c sockopt: SO_SNDTIMEO may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1012 Debug           c sockopt: SO_RCVTIMEO may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1012 Debug           c sockopt: TCP_NODELAY may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1012 Debug           c sockopt: TCP_KEEPIDLE may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1012 Debug           c sockopt: TCP_KEEPCNT may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1012 Debug           c sockopt: TCP_KEEPINTVL may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1012 Link            c req 1013 rxreq
**** v1    vsl|       1014 Begin           b bereq 1013 fetch
**** v1    vsl|       1014 VCL_use         b vcl4
**** v1    vsl|       1014 Timestamp       b Start: 1752996049.605940 0.000000 0.000000
**** v1    vsl|       1014 BereqMethod     b GET
**** v1    vsl|       1014 BereqURL        b /2
**** v1    vsl|       1014 BereqProtocol   b HTTP/1.1
**** v1    vsl|       1014 BereqHeader     b Host: 127.0.0.1
**** v1    vsl|       1014 BereqHeader     b User-Agent: c2
**** v1    vsl|       1014 BereqHeader     b X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1014 BereqHeader     b Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1014 BereqHeader     b Accept-Encoding: gzip
**** v1    vsl|       1014 BereqHeader     b X-Varnish: 1014
**** v1    vsl|       1014 VCL_call        b BACKEND_FETCH
**** v1    vsl|       1014 VCL_return      b fetch
**** v1    vsl|       1014 Timestamp       b Fetch: 1752996049.605991 0.000051 0.000051
**** v1    vsl|       1014 Timestamp       b Connected: 1752996049.606258 0.000318 0.000267
**** v1    vsl|       1014 BackendOpen     b 24 s1 127.0.0.1 38375 127.0.0.1 48264 connect
**** v1    vsl|       1014 Timestamp       b Bereq: 1752996049.606309 0.000368 0.000050
**** v1    vsl|       1014 BerespProtocol  b HTTP/1.1
**** v1    vsl|       1014 BerespStatus    b 200
**** v1    vsl|       1014 BerespReason    b OK
**** v1    vsl|       1014 BerespHeader    b Date: Sun, 20 Jul 2025 07:20:49 GMT
**** v1    vsl|       1014 BerespHeader    b Server: s1
**** v1    vsl|       1014 BerespHeader    b Content-Length: 0
**** v1    vsl|       1014 Timestamp       b Beresp: 1752996049.606851 0.000911 0.000542
**** v1    vsl|       1014 TTL             b RFC 120 10 0 1752996050 1752996050 1752996049 0 0 cacheable
**** v1    vsl|       1014 VCL_call        b BACKEND_RESPONSE
**** v1    vsl|       1014 BackendClose    b 24 s1 recycle
**** v1    vsl|       1014 VCL_return      b retry
**** v1    vsl|       1014 Debug           b Missing content-range header or unknown range unit
**** v1    vsl|       1014 Timestamp       b Retry: 1752996049.617943 0.012002 0.011091
**** v1    vsl|       1014 Link            b bereq 1015 retry
**** v1    vsl|       1014 End             b 
**** v1    vsl|       1015 Begin           b bereq 1014 retry
**** v1    vsl|       1015 Timestamp       b Start: 1752996049.617943 0.012002 0.000000
**** v1    vsl|       1015 BereqMethod     b GET
**** v1    vsl|       1015 BereqURL        b /2
**** v1    vsl|       1015 BereqProtocol   b HTTP/1.1
**** v1    vsl|       1015 BereqHeader     b Host: 127.0.0.1
**** v1    vsl|       1015 BereqHeader     b User-Agent: c2
**** v1    vsl|       1015 BereqHeader     b X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1015 BereqHeader     b Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1015 BereqHeader     b Accept-Encoding: gzip
**** v1    vsl|       1015 BereqHeader     b X-Varnish: 1015
**** v1    vsl|       1015 VCL_call        b BACKEND_FETCH
**** v1    vsl|       1015 VCL_return      b fetch
**** v1    vsl|       1015 Timestamp       b Fetch: 1752996049.617968 0.012027 0.000025
**** v1    vsl|       1015 Timestamp       b Connected: 1752996049.617975 0.012035 0.000007
**** v1    vsl|       1015 BackendOpen     b 24 s1 127.0.0.1 38375 127.0.0.1 48264 reuse
**** v1    vsl|       1015 Timestamp       b Bereq: 1752996049.618035 0.012095 0.000060
**** v1    vsl|       1015 BerespProtocol  b HTTP/1.1
**** v1    vsl|       1015 BerespStatus    b 200
**** v1    vsl|       1015 BerespReason    b OK
**** v1    vsl|       1015 BerespHeader    b Date: Sun, 20 Jul 2025 07:20:49 GMT
**** v1    vsl|       1015 BerespHeader    b Server: s1
**** v1    vsl|       1015 BerespHeader    b Content-Length: 0
**** v1    vsl|       1015 Timestamp       b Beresp: 1752996049.621941 0.016001 0.003906
**** v1    vsl|       1015 TTL             b RFC 120 10 0 1752996050 1752996050 1752996049 0 0 cacheable
**** v1    vsl|       1015 VCL_call        b BACKEND_RESPONSE
**** v1    vsl|       1015 VCL_return      b deliver
**** v1    vsl|       1015 Debug           b Missing content-range header or unknown range unit
**** v1    vsl|       1015 Timestamp       b Process: 1752996049.621987 0.016046 0.000045
**** v1    vsl|       1015 Filters         b 
**** v1    vsl|       1015 Storage         b malloc s0
**** v1    vsl|       1015 Fetch_Body      b 0 none -
**** v1    vsl|       1015 BackendClose    b 24 s1 recycle
**** v1    vsl|       1015 Timestamp       b BerespBody: 1752996049.634197 0.028256 0.012209
**** v1    vsl|       1015 Length          b 0
**** v1    vsl|       1015 BereqAcct       b 298 0 298 174 0 174
**** v1    vsl|       1015 End             b 
**** v1    vsl|       1013 Begin           c req 1012 rxreq
**** v1    vsl|       1013 Timestamp       c Start: 1752996049.602355 0.000000 0.000000
**** v1    vsl|       1013 Timestamp       c Req: 1752996049.602355 0.000000 0.000000
**** v1    vsl|       1013 VCL_use         c vcl4
**** v1    vsl|       1013 ReqStart        c 127.0.0.1 49002 a0
**** v1    vsl|       1013 ReqMethod       c GET
**** v1    vsl|       1013 ReqURL          c /2
**** v1    vsl|       1013 ReqProtocol     c HTTP/1.1
**** v1    vsl|       1013 ReqHeader       c Host: 127.0.0.1
**** v1    vsl|       1013 ReqHeader       c User-Agent: c2
**** v1    vsl|       1013 ReqHeader       c X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1013 ReqHeader       c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1013 VCL_call        c RECV
**** v1    vsl|       1013 VCL_return      c hash
**** v1    vsl|       1013 VCL_call        c HASH
**** v1    vsl|       1013 VCL_return      c lookup
**** v1    vsl|       1013 VCL_call        c MISS
**** v1    vsl|       1013 VCL_return      c fetch
**** v1    vsl|       1013 Link            c bereq 1014 fetch
**** v1    vsl|       1013 Timestamp       c Fetch: 1752996049.635730 0.033375 0.033375
**** v1    vsl|       1013 RespProtocol    c HTTP/1.1
**** v1    vsl|       1013 RespStatus      c 200
**** v1    vsl|       1013 RespReason      c OK
**** v1    vsl|       1013 RespHeader      c Date: Sun, 20 Jul 2025 07:20:49 GMT
**** v1    vsl|       1013 RespHeader      c Server: s1
**** v1    vsl|       1013 RespHeader      c Content-Length: 0
**** v1    vsl|       1013 RespHeader      c X-Varnish: 1013
**** v1    vsl|       1013 RespHeader      c Age: 0
**** v1    vsl|       1013 RespHeader      c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1013 RespHeader      c Accept-Ranges: bytes
**** v1    vsl|       1013 VCL_call        c DELIVER
**** v1    vsl|       1013 VCL_return      c deliver
**** v1    vsl|       1013 Timestamp       c Process: 1752996049.635774 0.033419 0.000044
**** v1    vsl|       1013 Filters         c 
**** v1    vsl|       1013 RespHeader      c Connection: keep-alive
**** v1    vsl|       1013 Timestamp       c Resp: 1752996049.635839 0.033484 0.000064
**** v1    vsl|       1013 ReqAcct         c 52 0 52 187 0 187
**** v1    vsl|       1013 End             c 
**** v1    vsl|       1012 SessClose       c REM_CLOSE 0.036
**** v1    vsl|       1012 End             c 
**** dT    4.198
***  v1    CLI RX  200
**** dT    4.199
**** v1    CLI TX|vcl.use vcl5
***  v1    CLI RX  200
**** v1    CLI RX|VCL 'vcl5' now active
**   top   === server s1 -repeat 5 -keepalive {
**   s1    Starting server
**** s1    macro def s1_addr=127.0.0.1
**** s1    macro def s1_port=38375
**** s1    macro def s1_sock=127.0.0.1:38375
*    s1    Listen on 127.0.0.1:38375
**   top   === client c3 {
**   c3    Starting client
**   c3    Waiting for client
**   c3    Started on 127.0.0.1:41933 (1 iterations)
***  c3    Connect to 127.0.0.1:41933
***  c3    connected fd 5 from 127.0.0.1 49008 to 127.0.0.1:41933
**   c3    === txreq -url /3
**** c3    txreq|GET /3 HTTP/1.1\r
**** c3    txreq|Host: 127.0.0.1\r
**** c3    txreq|User-Agent: c3\r
**** c3    txreq|\r
**   c3    === rxresp
**   s1    Started on 127.0.0.1:38375 (5 iterations using keepalive)
**** dT    4.202
***  s1    accepted fd 17 127.0.0.1 48270
**   s1    === rxreq
**** dT    4.203
**** s1    rxhdr|GET /3 HTTP/1.1\r
**** s1    rxhdr|Host: 127.0.0.1\r
**** s1    rxhdr|User-Agent: c3\r
**** s1    rxhdr|X-Forwarded-For: 127.0.0.1\r
**** s1    rxhdr|Via: 1.1 v1 (Varnish/trunk)\r
**** s1    rxhdr|Accept-Encoding: gzip\r
**** s1    rxhdr|X-Varnish: 1018\r
**** s1    rxhdr|fetch: Fetch value 0\r
**** s1    rxhdr|\r
**** s1    rxhdrlen = 171
**** s1    http[ 0] |GET
**** s1    http[ 1] |/3
**** s1    http[ 2] |HTTP/1.1
**** s1    http[ 3] |Host: 127.0.0.1
**** s1    http[ 4] |User-Agent: c3
**** s1    http[ 5] |X-Forwarded-For: 127.0.0.1
**** s1    http[ 6] |Via: 1.1 v1 (Varnish/trunk)
**** s1    http[ 7] |Accept-Encoding: gzip
**** s1    http[ 8] |X-Varnish: 1018
**** s1    http[ 9] |fetch: Fetch value 0
**** s1    bodylen = 0
**   s1    === txresp
**** s1    txresp|HTTP/1.1 200 OK\r
**** s1    txresp|Date: Sun, 20 Jul 2025 07:20:50 GMT\r
**** s1    txresp|Server: s1\r
**** s1    txresp|Content-Length: 0\r
**** s1    txresp|\r
**   s1    === rxreq
**** dT    4.216
**** s1    rxhdr|GET /3 HTTP/1.1\r
**** s1    rxhdr|Host: 127.0.0.1\r
**** s1    rxhdr|User-Agent: c3\r
**** s1    rxhdr|X-Forwarded-For: 127.0.0.1\r
**** s1    rxhdr|Via: 1.1 v1 (Varnish/trunk)\r
**** s1    rxhdr|Accept-Encoding: gzip\r
**** s1    rxhdr|response: 123\r
**** s1    rxhdr|response2: Another response\r
**** s1    rxhdr|X-Varnish: 1019\r
**** s1    rxhdr|fetch: Fetch value 1\r
**** s1    rxhdr|\r
**** s1    rxhdrlen = 215
**** s1    http[ 0] |GET
**** s1    http[ 1] |/3
**** s1    http[ 2] |HTTP/1.1
**** s1    http[ 3] |Host: 127.0.0.1
**** s1    http[ 4] |User-Agent: c3
**** s1    http[ 5] |X-Forwarded-For: 127.0.0.1
**** s1    http[ 6] |Via: 1.1 v1 (Varnish/trunk)
**** s1    http[ 7] |Accept-Encoding: gzip
**** s1    http[ 8] |response: 123
**** s1    http[ 9] |response2: Another response
**** s1    http[10] |X-Varnish: 1019
**** s1    http[11] |fetch: Fetch value 1
**** s1    bodylen = 0
**   s1    === txresp
**** s1    txresp|HTTP/1.1 200 OK\r
**** s1    txresp|Date: Sun, 20 Jul 2025 07:20:50 GMT\r
**** s1    txresp|Server: s1\r
**** s1    txresp|Content-Length: 0\r
**** s1    txresp|\r
**   s1    === rxreq
**** dT    4.220
**** v1    vsl|          0 CLI             - Rd vcl.load vcl5 vcl_vcl5.1752996049.637403/vgc.so 1auto
**** v1    vsl|          0 CLI             - Wr 200 52 Loaded "vcl_vcl5.1752996049.637403/vgc.so" as "vcl5"
**** v1    vsl|          0 CLI             - Rd ping
**** v1    vsl|          0 CLI             - Wr 200 19 PONG 1752996050 1.0
**** v1    vsl|          0 CLI             - Rd vcl.use vcl5
**** v1    vsl|          0 CLI             - Wr 200 0 
**** v1    vsl|       1016 Begin           c sess 0 HTTP/1
**** v1    vsl|       1016 SessOpen        c 127.0.0.1 49008 a0 127.0.0.1 41933 1752996050.089029 22
**** v1    vsl|       1016 Debug           c sockopt: SO_LINGER may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1016 Debug           c sockopt: SO_KEEPALIVE may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1016 Debug           c sockopt: SO_SNDTIMEO may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1016 Debug           c sockopt: SO_RCVTIMEO may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1016 Debug           c sockopt: TCP_NODELAY may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1016 Debug           c sockopt: TCP_KEEPIDLE may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1016 Debug           c sockopt: TCP_KEEPCNT may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1016 Debug           c sockopt: TCP_KEEPINTVL may be inherited for a0=127.0.0.1:41933
**** v1    vsl|       1016 Link            c req 1017 rxreq
**** v1    vsl|       1018 Begin           b bereq 1017 fetch
**** v1    vsl|       1018 VCL_use         b vcl5
**** v1    vsl|       1018 Timestamp       b Start: 1752996050.092032 0.000000 0.000000
**** dT    4.221
**** v1    vsl|       1018 BereqMethod     b GET
**** v1    vsl|       1018 BereqURL        b /3
**** v1    vsl|       1018 BereqProtocol   b HTTP/1.1
**** v1    vsl|       1018 BereqHeader     b Host: 127.0.0.1
**** v1    vsl|       1018 BereqHeader     b User-Agent: c3
**** v1    vsl|       1018 BereqHeader     b X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1018 BereqHeader     b Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1018 BereqHeader     b Accept-Encoding: gzip
**** v1    vsl|       1018 BereqHeader     b X-Varnish: 1018
**** v1    vsl|       1018 VCL_call        b BACKEND_FETCH
**** v1    vsl|       1018 BereqHeader     b fetch: Fetch value 0
**** v1    vsl|       1018 VCL_return      b fetch
**** v1    vsl|       1018 Timestamp       b Fetch: 1752996050.092086 0.000053 0.000053
**** v1    vsl|       1018 Timestamp       b Connected: 1752996050.092175 0.000143 0.000089
**** v1    vsl|       1018 BackendOpen     b 24 s1 127.0.0.1 38375 127.0.0.1 48270 connect
**** v1    vsl|       1018 Timestamp       b Bereq: 1752996050.092254 0.000222 0.000078
**** v1    vsl|       1018 BerespProtocol  b HTTP/1.1
**** v1    vsl|       1018 BerespStatus    b 200
**** v1    vsl|       1018 BerespReason    b OK
**** v1    vsl|       1018 BerespHeader    b Date: Sun, 20 Jul 2025 07:20:50 GMT
**** v1    vsl|       1018 BerespHeader    b Server: s1
**** v1    vsl|       1018 BerespHeader    b Content-Length: 0
**** v1    vsl|       1018 Timestamp       b Beresp: 1752996050.093327 0.001295 0.001072
**** v1    vsl|       1018 TTL             b RFC 120 10 0 1752996050 1752996050 1752996050 0 0 cacheable
**** v1    vsl|       1018 VCL_call        b BACKEND_RESPONSE
**** v1    vsl|       1018 BackendClose    b 24 s1 recycle
**** v1    vsl|       1018 BereqHeader     b response: 123
**** v1    vsl|       1018 BereqHeader     b response2: Another response
**** v1    vsl|       1018 VCL_return      b retry
**** v1    vsl|       1018 Debug           b Missing content-range header or unknown range unit
**** v1    vsl|       1018 Timestamp       b Retry: 1752996050.103854 0.011821 0.010526
**** v1    vsl|       1018 Link            b bereq 1019 retry
**** v1    vsl|       1018 End             b 
**** dT    4.234
**** c3    rxhdr|HTTP/1.1 200 OK\r
**** c3    rxhdr|Date: Sun, 20 Jul 2025 07:20:50 GMT\r
**** c3    rxhdr|Server: s1\r
**** c3    rxhdr|Content-Length: 0\r
**** c3    rxhdr|fetch: Fetch value 1\r
**** c3    rxhdr|response: 123\r
**** c3    rxhdr|response2: Another response\r
**** c3    rxhdr|X-Varnish: 1017\r
**** c3    rxhdr|Age: 0\r
**** c3    rxhdr|Via: 1.1 v1 (Varnish/trunk)\r
**** c3    rxhdr|Accept-Ranges: bytes\r
**** c3    rxhdr|Connection: keep-alive\r
**** c3    rxhdr|\r
**** c3    rxhdrlen = 253
**** c3    http[ 0] |HTTP/1.1
**** c3    http[ 1] |200
**** c3    http[ 2] |OK
**** c3    http[ 3] |Date: Sun, 20 Jul 2025 07:20:50 GMT
**** c3    http[ 4] |Server: s1
**** c3    http[ 5] |Content-Length: 0
**** c3    http[ 6] |fetch: Fetch value 1
**** c3    http[ 7] |response: 123
**** c3    http[ 8] |response2: Another response
**** c3    http[ 9] |X-Varnish: 1017
**** c3    http[10] |Age: 0
**** c3    http[11] |Via: 1.1 v1 (Varnish/trunk)
**** c3    http[12] |Accept-Ranges: bytes
**** c3    http[13] |Connection: keep-alive
**** c3    bodylen = 0
**   c3    === expect resp.status == 200
**** c3    EXPECT resp.status (200) == "200" match
**   c3    === expect resp.http.fetch == "Fetch value 1"
**** c3    EXPECT resp.http.fetch (Fetch value 1) == "Fetch value 1" match
**   c3    === expect resp.http.response == "123"
**** c3    EXPECT resp.http.response (123) == "123" match
**   c3    === expect resp.http.response2 == "Another response"
**** c3    EXPECT resp.http.response2 (Another response) == "Another response" match
**   c3    === txreq -url /4
**** dT    4.237
**** c3    txreq|GET /4 HTTP/1.1\r
**** c3    txreq|Host: 127.0.0.1\r
**** c3    txreq|User-Agent: c3\r
**** c3    txreq|\r
**   c3    === rxresp
**** dT    4.239
**** s1    rxhdr|GET /4 HTTP/1.1\r
**** s1    rxhdr|Host: 127.0.0.1\r
**** s1    rxhdr|User-Agent: c3\r
**** s1    rxhdr|X-Forwarded-For: 127.0.0.1\r
**** s1    rxhdr|Via: 1.1 v1 (Varnish/trunk)\r
**** s1    rxhdr|Accept-Encoding: gzip\r
**** s1    rxhdr|X-Varnish: 1021\r
**** s1    rxhdr|fetch: Fetch value 0\r
**** s1    rxhdr|\r
**** s1    rxhdrlen = 171
**** s1    http[ 0] |GET
**** s1    http[ 1] |/4
**** s1    http[ 2] |HTTP/1.1
**** s1    http[ 3] |Host: 127.0.0.1
**** s1    http[ 4] |User-Agent: c3
**** s1    http[ 5] |X-Forwarded-For: 127.0.0.1
**** s1    http[ 6] |Via: 1.1 v1 (Varnish/trunk)
**** s1    http[ 7] |Accept-Encoding: gzip
**** s1    http[ 8] |X-Varnish: 1021
**** s1    http[ 9] |fetch: Fetch value 0
**** s1    bodylen = 0
**   s1    === txresp
**** s1    txresp|HTTP/1.1 200 OK\r
**** s1    txresp|Date: Sun, 20 Jul 2025 07:20:50 GMT\r
**** s1    txresp|Server: s1\r
**** s1    txresp|Content-Length: 0\r
**** s1    txresp|\r
**   s1    === rxreq
**** dT    4.255
**** c3    rxhdr|HTTP/1.1 503 Backend fetch failed\r
**** c3    rxhdr|Server: Varnish\r
**** c3    rxhdr|X-Varnish: 1020\r
**** c3    rxhdr|Age: 0\r
**** c3    rxhdr|Via: 1.1 v1 (Varnish/trunk)\r
**** c3    rxhdr|Content-Length: 271\r
**** c3    rxhdr|Connection: keep-alive\r
**** c3    rxhdr|\r
**** c3    rxhdrlen = 153
**** c3    http[ 0] |HTTP/1.1
**** c3    http[ 1] |503
**** c3    http[ 2] |Backend fetch failed
**** c3    http[ 3] |Server: Varnish
**** c3    http[ 4] |X-Varnish: 1020
**** c3    http[ 5] |Age: 0
**** c3    http[ 6] |Via: 1.1 v1 (Varnish/trunk)
**** c3    http[ 7] |Content-Length: 271
**** c3    http[ 8] |Connection: keep-alive
**** c3    c-l|<!DOCTYPE html>
**** c3    c-l|<html>
**** c3    c-l|  <head>
**** c3    c-l|    <title> Backend fetch failed</title>
**** c3    c-l|  </head>
**** c3    c-l|  <body>
**** c3    c-l|    <h1>Error  Backend fetch failed</h1>
**** c3    c-l|    <p>Backend fetch failed</p>
**** c3    c-l|    <h3>Guru Meditation:</h3>
**** c3    c-l|    <p>XID: </p>
**** c3    c-l|    <hr>
**** c3    c-l|    <p>Varnish cache server</p>
**** c3    c-l|  </body>
**** c3    c-l|</html>
**** c3    bodylen = 271
**   c3    === expect resp.status == 503
**** c3    EXPECT resp.status (503) == "503" match
**   c3    === txreq -url /5
**** c3    txreq|GET /5 HTTP/1.1\r
**** c3    txreq|Host: 127.0.0.1\r
**** c3    txreq|User-Agent: c3\r
**** c3    txreq|\r
**   c3    === rxresp
**** dT    4.262
**** c3    rxhdrlen = 0
---- c3    HTTP header is incomplete
**** s1    rxhdrlen = 0
---- s1    HTTP header is incomplete
*    top   Aborting execution, test failed
*    top   RESETTING after ../../../vmod/tests/std_c00001.vtc
**   s1    Waiting for server (4/-1)
**   v1    Wait
**** v1    CLI TX|panic.show
**** dT    4.263
***  v1    debug|Error: Child (1819) died signal=6
***  v1    debug|Error: Child (1819) Panic at: Sun, 20 Jul 2025 07:20:50 GMT
***  v1    debug|Assert error in VBO_GetBusyObj(), ../../../../bin/varnishd/cache/cache_busyobj.c line 104:
***  v1    debug|  Condition(p < bo->end) not true.
***  v1    debug|version = varnish-trunk revision 886c592608848a29d6e25a8a94060e4f76409ed7, vrt api = 21.0
***  v1    debug|ident = Linux,4.19.0-17-amd64,x86_64,-jnone,-sdefault,-sdefault,-hcritbit,epoll
***  v1    debug|now = 4642999.653143 (mono), 1752996050.144962 (real)
***  v1    debug|Backtrace:
***  v1    debug|  0x556f10: varnishd() [0x556f10]
***  v1    debug|  0x556ed5: varnishd(VBT_format+0x35) [0x556ed5]
***  v1    debug|  0x4744c9: varnishd() [0x4744c9]
***  v1    debug|  0x4741a2: varnishd() [0x4741a2]
***  v1    debug|  0x555efb: varnishd(VAS_Fail+0x4b) [0x555efb]
***  v1    debug|  0x438c1e: varnishd(VBO_GetBusyObj+0x3fe) [0x438c1e]
***  v1    debug|  0x44fe9e: varnishd(VBF_Fetch+0x29e) [0x44fe9e]
***  v1    debug|  0x4845c9: varnishd() [0x4845c9]
***  v1    debug|  0x47fc34: varnishd(CNT_Request+0x814) [0x47fc34]
***  v1    debug|  0x4cf41e: varnishd() [0x4cf41e]
***  v1    debug|  0x4ce6b0: varnishd() [0x4ce6b0]
***  v1    debug|  0x4bd6d4: varnishd() [0x4bd6d4]
***  v1    debug|  0x4bccd3: varnishd() [0x4bccd3]
***  v1    debug|  0x4bc8fb: varnishd() [0x4bc8fb]
***  v1    debug|  0x7f2cbc8a2fa3: /lib/x86_64-linux-gnu/libpthread.so.0(+0x7fa3) [0x7f2cbc8a2fa3]
***  v1    debug|  0x7f2cbc7d14cf: /lib/x86_64-linux-gnu/libc.so.6(clone+0x3f) [0x7f2cbc7d14cf]
***  v1    debug|argv = {
***  v1    debug|  [0] = \"varnishd\",
***  v1    debug|  [1] = \"-d\",
***  v1    debug|  [2] = \"-n\",
***  v1    debug|  [3] = \"/tmp/vtest.clang/_vtest_tmp/vtc.1621.218e421a/v1\",
***  v1    debug|  [4] = \"-i\",
***  v1    debug|  [5] = \"v1\",
***  v1    debug|  [6] = \"-l\",
***  v1    debug|  [7] = \"2m\",
***  v1    debug|  [8] = \"-p\",
***  v1    debug|  [9] = \"auto_restart=off\",
***  v1    debug|  [10] = \"-p\",
***  v1    debug|  [11] = \"syslog_cli_traffic=off\",
***  v1    debug|  [12] = \"-p\",
***  v1    debug|  [13] = \"thread_pool_min=10\",
***  v1    debug|  [14] = \"-p\",
***  v1    debug|  [15] = \"debug=+vtc_mode\",
***  v1    debug|  [16] = \"-p\",
***  v1    debug|  [17] = \"vsl_mask=+Debug,+H2RxHdr,+H2RxBody\",
***  v1    debug|  [18] = \"-p\",
***  v1    debug|  [19] = \"h2_initial_window_size=1m\",
***  v1    debug|  [20] = \"-p\",
***  v1    debug|  [21] = \"h2_rx_window_low_water=64k\",
***  v1    debug|  [22] = \"-a\",
***  v1    debug|  [23] = \"127.0.0.1:0\",
***  v1    debug|  [24] = \"-M\",
***  v1    debug|  [25] = \"127.0.0.1 41239\",
***  v1    debug|  [26] = \"-P\",
***  v1    debug|  [27] = \"/tmp/vtest.clang/_vtest_tmp/vtc.1621.218e421a/v1/varnishd.pid\",
***  v1    debug|  [28] = \"-p\",
***  v1    debug|  [29] = \"vmod_path=/tmp/vtest.clang/varnish-cache/varnish-trunk/_build/sub/vmod/.libs\",
***  v1    debug|}
***  v1    debug|pthread.self = 0x7f2cbc4a8700
***  v1    debug|pthread.name = (cache-worker)
***  v1    debug|pthread.attr = {
***  v1    debug|  guard = 4096,
***  v1    debug|  stack_bottom = 0x7f2cbc495000,
***  v1    debug|  stack_top = 0x7f2cbc4a9000,
***  v1    debug|  stack_size = 81920,
***  v1    debug|}
***  v1    debug|thr.req = 0x7f2cb1919ca0 {
***  v1    debug|  vxid = 1023, transport = HTTP/1 {
***  v1    debug|    state = HTTP1::Proc
***  v1    debug|  }
***  v1    debug|  step = Req Step miss
***  v1    debug|  req_body = none,
***  v1    debug|  restarts = 0, esi_level = 0,
***  v1    debug|  vary_b = (nil), vary_e = (nil),
***  v1    debug|  d_ttl = -1.000000, d_grace = -1.000000,
***  v1    debug|  storage = (nil),
***  v1    debug|  director_hint = 0x7f2cbc01e870 {
***  v1    debug|    cli_name = vcl5.s1,
***  v1    debug|    admin_health = healthy, changed = 1752996050.088184,
***  v1    debug|    type = backend {
***  v1    debug|      conn_pool = 0x7f2cbc0473c0 {
***  v1    debug|        ident = 0x1e6b6918d1762c4feb76bb3467b532471199cd119b56d65622c98d0bc68a3893,
***  v1    debug|        vrt_endpoint = 0x7f2cbc024850 {
***  v1    debug|          ipv4 = 127.0.0.1, port = 38375,
***  v1    debug|        },
***  v1    debug|      },
***  v1    debug|      hosthdr = 127.0.0.1,
***  v1    debug|      n_conn = 0,
***  v1    debug|    },
***  v1    debug|  },
***  v1    debug|  sess = 0x7f2cb8e07b20 {
***  v1    debug|    fd = 22, vxid = 1016,
***  v1    debug|    t_open = 1752996050.089029,
***  v1    debug|    t_idle = 1752996050.143162,
***  v1    debug|    ws = 0x7f2cb8e07b60 {
***  v1    debug|      id = \"ses\",
***  v1    debug|      {s, f, r, e} = {0x7f2cb8e07bb8, +96, (nil), +576},
***  v1    debug|    },
***  v1    debug|    transport = HTTP/1 {
***  v1    debug|      state = HTTP1::Proc
***  v1    debug|    }
***  v1    debug|    client = 127.0.0.1 49008 127.0.0.1:41933,
***  v1    debug|    local.endpoint = 127.0.0.1:41933,
***  v1    debug|    local.socket = a0,
***  v1    debug|    local.ip = 127.0.0.1:41933,
***  v1    debug|    remote.ip = 127.0.0.1:49008,
***  v1    debug|    server.ip = 127.0.0.1:41933,
***  v1    debug|    client.ip = 127.0.0.1:49008,
***  v1    debug|  },
***  v1    debug|  worker = 0x7f2cbc4a65e0 {
***  v1    debug|    ws = 0x7f2cbc4a6660 {
***  v1    debug|      id = \"wrk\",
***  v1    debug|      {s, f, r, e} = {0x7f2cbc4a5b60, +0, (nil), +2040},
***  v1    debug|    },
***  v1    debug|    VCL::method = MISS,
***  v1    debug|    VCL::methods = {RECV, HASH, MISS},
***  v1    debug|  },
***  v1    debug|  ws = 0x7f2cb1919df8 {
***  v1    debug|    id = \"req\",
***  v1    debug|    {s, f, r, e} = {0x7f2cb191ed40, +120, (nil), +77624},
***  v1    debug|  },
***  v1    debug|  http_conn = 0x7f2cb191ecb8 {
***  v1    debug|    fd = 22 (@0x7f2cb8e07b44),
***  v1    debug|    doclose = null(Not Closing)
***  v1    debug|    ws = 0x7f2cb1919df8 {
***  v1    debug|      [Already dumped, see above]
***  v1    debug|    },
***  v1    debug|    {rxbuf_b, rxbuf_e} = {0x7f2cb191ed40, 0x7f2cb191ed74},
***  v1    debug|    {pipeline_b, pipeline_e} = {(nil), (nil)},
***  v1    debug|    content_length = -1,
***  v1    debug|    body_status = none,
***  v1    debug|    first_byte_timeout = 0.000000,
***  v1    debug|    between_bytes_timeout = 0.000000,
***  v1    debug|  },
***  v1    debug|  http[req] = 0x7f2cb1919ea8 {
***  v1    debug|    ws = 0x7f2cb1919df8 {
***  v1    debug|      [Already dumped, see above]
***  v1    debug|    },
***  v1    debug|    hdrs {
***  v1    debug|      \"GET\",
***  v1    debug|      \"/5\",
***  v1    debug|      \"HTTP/1.1\",
***  v1    debug|      \"Host: 127.0.0.1\",
***  v1    debug|      \"User-Agent: c3\",
***  v1    debug|      \"X-Forwarded-For: 127.0.0.1\",
***  v1    debug|      \"Via: 1.1 v1 (Varnish/trunk)\",
***  v1    debug|    },
***  v1    debug|  },
***  v1    debug|  vdc = 0x7f2cb191ec58 {
***  v1    debug|    nxt = (nil),
***  v1    debug|    retval = 0,
***  v1    debug|  },
***  v1    debug|  vcl[vcl] = 0x7f2cbc02fa00 {
***  v1    debug|    name = \"vcl5\",
***  v1    debug|    busy = 2,
***  v1    debug|    discard = 0,
***  v1    debug|    state = auto,
***  v1    debug|    temp = warm,
***  v1    debug|    conf = 0x7f2cba0b1b70 {
***  v1    debug|      syntax = \"41\",
***  v1    debug|      srcname = {
***  v1    debug|        [0] = \"<vcl.inline>\",
***  v1    debug|        [1] = \"<builtin>\",
***  v1    debug|      },
***  v1    debug|      instances = {
***  v1    debug|      },
***  v1    debug|    },
***  v1    debug|  },
***  v1    debug|  vpi = 0x7f2cbc4a5b50 {
***  v1    debug|    handling (VCL::return) = 0x5 (fetch),
***  v1    debug|    ref = 41,
***  v1    debug|    vpi_ref = 0x7f2cba0b2940 {
***  v1    debug|      source = 1 (\"<builtin>\"),
***  v1    debug|      offset = 3675,
***  v1    debug|      line = 155,
***  v1    debug|      pos = 1,
***  v1    debug|      src = \"}\"
***  v1    debug|    },
***  v1    debug|  },
***  v1    debug|  objcore[REQ] = 0x7f2cb260d3c0 {
***  v1    debug|    refcnt = 1,
***  v1    debug|    flags = {busy},
***  v1    debug|    exp_flags = {},
***  v1    debug|    boc = 0x7f2cb2610080 {
***  v1    debug|      refcnt = 1,
***  v1    debug|      state = invalid,
***  v1    debug|      vary = (nil),
***  v1    debug|      stevedore_priv = (nil),
***  v1    debug|    },
***  v1    debug|    exp = {0.000000, 0.000000, 0.000000, 0.000000},
***  v1    debug|    objhead = 0x7f2cb2611230,
***  v1    debug|    stevedore = (nil),
***  v1    debug|  },
***  v1    debug|  flags = {
***  v1    debug|  },
***  v1    debug|  privs = 0x7f2cb1919e98 {
***  v1    debug|  },
***  v1    debug|  top = 0x7f2cb191ed20 {
***  v1    debug|    req = 0x7f2cb1919ca0 {
***  v1    debug|      [Already dumped, see above]
***  v1    debug|    },
***  v1    debug|    privs = 0x7f2cb191ed38 {
***  v1    debug|    },
***  v1    debug|    vcl[vcl0] = NULL
***  v1    debug|  },
***  v1    debug|},
***  v1    debug|thr.busyobj = NULL
***  v1    debug|thr.worker = 0x7f2cbc4a65e0 {
***  v1    debug|  [Already dumped, see above]
***  v1    debug|},
***  v1    debug|vmods = {
***  v1    debug|  std = {p=0x7f2cbc02f700, abi=\"Varnish trunk 886c592608848a29d6e25a8a94060e4f76409ed7\", vrt=0.0,
***  v1    debug|    vcs=\"886c592608848a29d6e25a8a94060e4f76409ed7\", version=\"Varnish trunk\"},
***  v1    debug|  vtc = {p=0x7f2cbc02f900, abi=\"Varnish trunk 886c592608848a29d6e25a8a94060e4f76409ed7\", vrt=0.0,
***  v1    debug|    vcs=\"886c592608848a29d6e25a8a94060e4f76409ed7\", version=\"Varnish trunk\"},
***  v1    debug|},
***  v1    debug|pools = {
***  v1    debug|  pool = 0x7f2cb9c00000 {
***  v1    debug|    nidle = 7,
***  v1    debug|    nthr = 10,
***  v1    debug|    lqueue = 0
***  v1    debug|  },
***  v1    debug|  pool = 0x7f2cb9c00640 {
***  v1    debug|    nidle = 9,
***  v1    debug|    nthr = 10,
***  v1    debug|    lqueue = 0
***  v1    debug|  },
***  v1    debug|},
***  v1    debug|
***  v1    debug|
***  v1    debug|Debug: Child cleanup complete
**** dT    4.304
***  v1    CLI RX  200
**** dT    4.305
**** v1    CLI RX|Child (1819) Panic at: Sun, 20 Jul 2025 07:20:50 GMT
**** v1    CLI RX|Assert error in VBO_GetBusyObj(), ../../../../bin/varnishd/cache/cache_busyobj.c line 104:
**** v1    CLI RX|  Condition(p < bo->end) not true.
**** v1    CLI RX|version = varnish-trunk revision 886c592608848a29d6e25a8a94060e4f76409ed7, vrt api = 21.0
**** v1    CLI RX|ident = Linux,4.19.0-17-amd64,x86_64,-jnone,-sdefault,-sdefault,-hcritbit,epoll
**** v1    CLI RX|now = 4642999.653143 (mono), 1752996050.144962 (real)
**** v1    CLI RX|Backtrace:
**** v1    CLI RX|  0x556f10: varnishd() [0x556f10]
**** v1    CLI RX|  0x556ed5: varnishd(VBT_format+0x35) [0x556ed5]
**** v1    CLI RX|  0x4744c9: varnishd() [0x4744c9]
**** v1    CLI RX|  0x4741a2: varnishd() [0x4741a2]
**** v1    CLI RX|  0x555efb: varnishd(VAS_Fail+0x4b) [0x555efb]
**** v1    CLI RX|  0x438c1e: varnishd(VBO_GetBusyObj+0x3fe) [0x438c1e]
**** v1    CLI RX|  0x44fe9e: varnishd(VBF_Fetch+0x29e) [0x44fe9e]
**** v1    CLI RX|  0x4845c9: varnishd() [0x4845c9]
**** v1    CLI RX|  0x47fc34: varnishd(CNT_Request+0x814) [0x47fc34]
**** v1    CLI RX|  0x4cf41e: varnishd() [0x4cf41e]
**** v1    CLI RX|  0x4ce6b0: varnishd() [0x4ce6b0]
**** v1    CLI RX|  0x4bd6d4: varnishd() [0x4bd6d4]
**** v1    CLI RX|  0x4bccd3: varnishd() [0x4bccd3]
**** v1    CLI RX|  0x4bc8fb: varnishd() [0x4bc8fb]
**** v1    CLI RX|  0x7f2cbc8a2fa3: /lib/x86_64-linux-gnu/libpthread.so.0(+0x7fa3) [0x7f2cbc8a2fa3]
**** v1    CLI RX|  0x7f2cbc7d14cf: /lib/x86_64-linux-gnu/libc.so.6(clone+0x3f) [0x7f2cbc7d14cf]
**** v1    CLI RX|argv = {
**** v1    CLI RX|  [0] = \"varnishd\",
**** v1    CLI RX|  [1] = \"-d\",
**** v1    CLI RX|  [2] = \"-n\",
**** v1    CLI RX|  [3] = \"/tmp/vtest.clang/_vtest_tmp/vtc.1621.218e421a/v1\",
**** v1    CLI RX|  [4] = \"-i\",
**** v1    CLI RX|  [5] = \"v1\",
**** v1    CLI RX|  [6] = \"-l\",
**** v1    CLI RX|  [7] = \"2m\",
**** v1    CLI RX|  [8] = \"-p\",
**** v1    CLI RX|  [9] = \"auto_restart=off\",
**** v1    CLI RX|  [10] = \"-p\",
**** v1    CLI RX|  [11] = \"syslog_cli_traffic=off\",
**** v1    CLI RX|  [12] = \"-p\",
**** v1    CLI RX|  [13] = \"thread_pool_min=10\",
**** v1    CLI RX|  [14] = \"-p\",
**** v1    CLI RX|  [15] = \"debug=+vtc_mode\",
**** v1    CLI RX|  [16] = \"-p\",
**** v1    CLI RX|  [17] = \"vsl_mask=+Debug,+H2RxHdr,+H2RxBody\",
**** v1    CLI RX|  [18] = \"-p\",
**** v1    CLI RX|  [19] = \"h2_initial_window_size=1m\",
**** v1    CLI RX|  [20] = \"-p\",
**** v1    CLI RX|  [21] = \"h2_rx_window_low_water=64k\",
**** v1    CLI RX|  [22] = \"-a\",
**** v1    CLI RX|  [23] = \"127.0.0.1:0\",
**** v1    CLI RX|  [24] = \"-M\",
**** v1    CLI RX|  [25] = \"127.0.0.1 41239\",
**** v1    CLI RX|  [26] = \"-P\",
**** v1    CLI RX|  [27] = \"/tmp/vtest.clang/_vtest_tmp/vtc.1621.218e421a/v1/varnishd.pid\",
**** v1    CLI RX|  [28] = \"-p\",
**** v1    CLI RX|  [29] = \"vmod_path=/tmp/vtest.clang/varnish-cache/varnish-trunk/_build/sub/vmod/.libs\",
**** v1    CLI RX|}
**** v1    CLI RX|pthread.self = 0x7f2cbc4a8700
**** v1    CLI RX|pthread.name = (cache-worker)
**** v1    CLI RX|pthread.attr = {
**** v1    CLI RX|  guard = 4096,
**** v1    CLI RX|  stack_bottom = 0x7f2cbc495000,
**** v1    CLI RX|  stack_top = 0x7f2cbc4a9000,
**** v1    CLI RX|  stack_size = 81920,
**** v1    CLI RX|}
**** v1    CLI RX|thr.req = 0x7f2cb1919ca0 {
**** v1    CLI RX|  vxid = 1023, transport = HTTP/1 {
**** v1    CLI RX|    state = HTTP1::Proc
**** v1    CLI RX|  }
**** v1    CLI RX|  step = Req Step miss
**** v1    CLI RX|  req_body = none,
**** v1    CLI RX|  restarts = 0, esi_level = 0,
**** v1    CLI RX|  vary_b = (nil), vary_e = (nil),
**** v1    CLI RX|  d_ttl = -1.000000, d_grace = -1.000000,
**** v1    CLI RX|  storage = (nil),
**** v1    CLI RX|  director_hint = 0x7f2cbc01e870 {
**** v1    CLI RX|    cli_name = vcl5.s1,
**** v1    CLI RX|    admin_health = healthy, changed = 1752996050.088184,
**** v1    CLI RX|    type = backend {
**** v1    CLI RX|      conn_pool = 0x7f2cbc0473c0 {
**** v1    CLI RX|        ident = 0x1e6b6918d1762c4feb76bb3467b532471199cd119b56d65622c98d0bc68a3893,
**** v1    CLI RX|        vrt_endpoint = 0x7f2cbc024850 {
**** v1    CLI RX|          ipv4 = 127.0.0.1, port = 38375,
**** v1    CLI RX|        },
**** v1    CLI RX|      },
**** v1    CLI RX|      hosthdr = 127.0.0.1,
**** v1    CLI RX|      n_conn = 0,
**** v1    CLI RX|    },
**** v1    CLI RX|  },
**** v1    CLI RX|  sess = 0x7f2cb8e07b20 {
**** v1    CLI RX|    fd = 22, vxid = 1016,
**** v1    CLI RX|    t_open = 1752996050.089029,
**** v1    CLI RX|    t_idle = 1752996050.143162,
**** v1    CLI RX|    ws = 0x7f2cb8e07b60 {
**** v1    CLI RX|      id = \"ses\",
**** v1    CLI RX|      {s, f, r, e} = {0x7f2cb8e07bb8, +96, (nil), +576},
**** v1    CLI RX|    },
**** v1    CLI RX|    transport = HTTP/1 {
**** v1    CLI RX|      state = HTTP1::Proc
**** v1    CLI RX|    }
**** v1    CLI RX|    client = 127.0.0.1 49008 127.0.0.1:41933,
**** v1    CLI RX|    local.endpoint = 127.0.0.1:41933,
**** v1    CLI RX|    local.socket = a0,
**** v1    CLI RX|    local.ip = 127.0.0.1:41933,
**** v1    CLI RX|    remote.ip = 127.0.0.1:49008,
**** v1    CLI RX|    server.ip = 127.0.0.1:41933,
**** v1    CLI RX|    client.ip = 127.0.0.1:49008,
**** v1    CLI RX|  },
**** v1    CLI RX|  worker = 0x7f2cbc4a65e0 {
**** v1    CLI RX|    ws = 0x7f2cbc4a6660 {
**** v1    CLI RX|      id = \"wrk\",
**** v1    CLI RX|      {s, f, r, e} = {0x7f2cbc4a5b60, +0, (nil), +2040},
**** v1    CLI RX|    },
**** v1    CLI RX|    VCL::method = MISS,
**** v1    CLI RX|    VCL::methods = {RECV, HASH, MISS},
**** v1    CLI RX|  },
**** v1    CLI RX|  ws = 0x7f2cb1919df8 {
**** v1    CLI RX|    id = \"req\",
**** v1    CLI RX|    {s, f, r, e} = {0x7f2cb191ed40, +120, (nil), +77624},
**** v1    CLI RX|  },
**** v1    CLI RX|  http_conn = 0x7f2cb191ecb8 {
**** v1    CLI RX|    fd = 22 (@0x7f2cb8e07b44),
**** v1    CLI RX|    doclose = null(Not Closing)
**** v1    CLI RX|    ws = 0x7f2cb1919df8 {
**** v1    CLI RX|      [Already dumped, see above]
**** v1    CLI RX|    },
**** v1    CLI RX|    {rxbuf_b, rxbuf_e} = {0x7f2cb191ed40, 0x7f2cb191ed74},
**** v1    CLI RX|    {pipeline_b, pipeline_e} = {(nil), (nil)},
**** v1    CLI RX|    content_length = -1,
**** v1    CLI RX|    body_status = none,
**** v1    CLI RX|    first_byte_timeout = 0.000000,
**** v1    CLI RX|    between_bytes_timeout = 0.000000,
**** v1    CLI RX|  },
**** v1    CLI RX|  http[req] = 0x7f2cb1919ea8 {
**** v1    CLI RX|    ws = 0x7f2cb1919df8 {
**** v1    CLI RX|      [Already dumped, see above]
**** v1    CLI RX|    },
**** v1    CLI RX|    hdrs {
**** v1    CLI RX|      \"GET\",
**** v1    CLI RX|      \"/5\",
**** v1    CLI RX|      \"HTTP/1.1\",
**** v1    CLI RX|      \"Host: 127.0.0.1\",
**** v1    CLI RX|      \"User-Agent: c3\",
**** v1    CLI RX|      \"X-Forwarded-For: 127.0.0.1\",
**** v1    CLI RX|      \"Via: 1.1 v1 (Varnish/trunk)\",
**** v1    CLI RX|    },
**** v1    CLI RX|  },
**** v1    CLI RX|  vdc = 0x7f2cb191ec58 {
**** v1    CLI RX|    nxt = (nil),
**** v1    CLI RX|    retval = 0,
**** v1    CLI RX|  },
**** v1    CLI RX|  vcl[vcl] = 0x7f2cbc02fa00 {
**** v1    CLI RX|    name = \"vcl5\",
**** v1    CLI RX|    busy = 2,
**** v1    CLI RX|    discard = 0,
**** v1    CLI RX|    state = auto,
**** v1    CLI RX|    temp = warm,
**** v1    CLI RX|    conf = 0x7f2cba0b1b70 {
**** v1    CLI RX|      syntax = \"41\",
**** v1    CLI RX|      srcname = {
**** v1    CLI RX|        [0] = \"<vcl.inline>\",
**** v1    CLI RX|        [1] = \"<builtin>\",
**** v1    CLI RX|      },
**** v1    CLI RX|      instances = {
**** v1    CLI RX|      },
**** v1    CLI RX|    },
**** v1    CLI RX|  },
**** v1    CLI RX|  vpi = 0x7f2cbc4a5b50 {
**** v1    CLI RX|    handling (VCL::return) = 0x5 (fetch),
**** v1    CLI RX|    ref = 41,
**** v1    CLI RX|    vpi_ref = 0x7f2cba0b2940 {
**** v1    CLI RX|      source = 1 (\"<builtin>\"),
**** v1    CLI RX|      offset = 3675,
**** v1    CLI RX|      line = 155,
**** v1    CLI RX|      pos = 1,
**** v1    CLI RX|      src = \"}\"
**** v1    CLI RX|    },
**** v1    CLI RX|  },
**** v1    CLI RX|  objcore[REQ] = 0x7f2cb260d3c0 {
**** v1    CLI RX|    refcnt = 1,
**** v1    CLI RX|    flags = {busy},
**** v1    CLI RX|    exp_flags = {},
**** v1    CLI RX|    boc = 0x7f2cb2610080 {
**** v1    CLI RX|      refcnt = 1,
**** v1    CLI RX|      state = invalid,
**** v1    CLI RX|      vary = (nil),
**** v1    CLI RX|      stevedore_priv = (nil),
**** v1    CLI RX|    },
**** v1    CLI RX|    exp = {0.000000, 0.000000, 0.000000, 0.000000},
**** v1    CLI RX|    objhead = 0x7f2cb2611230,
**** v1    CLI RX|    stevedore = (nil),
**** v1    CLI RX|  },
**** v1    CLI RX|  flags = {
**** v1    CLI RX|  },
**** v1    CLI RX|  privs = 0x7f2cb1919e98 {
**** v1    CLI RX|  },
**** v1    CLI RX|  top = 0x7f2cb191ed20 {
**** v1    CLI RX|    req = 0x7f2cb1919ca0 {
**** v1    CLI RX|      [Already dumped, see above]
**** v1    CLI RX|    },
**** v1    CLI RX|    privs = 0x7f2cb191ed38 {
**** v1    CLI RX|    },
**** v1    CLI RX|    vcl[vcl0] = NULL
**** v1    CLI RX|  },
**** v1    CLI RX|},
**** v1    CLI RX|thr.busyobj = NULL
**** v1    CLI RX|thr.worker = 0x7f2cbc4a65e0 {
**** v1    CLI RX|  [Already dumped, see above]
**** v1    CLI RX|},
**** v1    CLI RX|vmods = {
**** v1    CLI RX|  std = {p=0x7f2cbc02f700, abi=\"Varnish trunk 886c592608848a29d6e25a8a94060e4f76409ed7\", vrt=0.0,
**** v1    CLI RX|    vcs=\"886c592608848a29d6e25a8a94060e4f76409ed7\", version=\"Varnish trunk\"},
**** v1    CLI RX|  vtc = {p=0x7f2cbc02f900, abi=\"Varnish trunk 886c592608848a29d6e25a8a94060e4f76409ed7\", vrt=0.0,
**** v1    CLI RX|    vcs=\"886c592608848a29d6e25a8a94060e4f76409ed7\", version=\"Varnish trunk\"},
**** v1    CLI RX|},
**** v1    CLI RX|pools = {
**** v1    CLI RX|  pool = 0x7f2cb9c00000 {
**** v1    CLI RX|    nidle = 7,
**** v1    CLI RX|    nthr = 10,
**** v1    CLI RX|    lqueue = 0
**** v1    CLI RX|  },
**** v1    CLI RX|  pool = 0x7f2cb9c00640 {
**** v1    CLI RX|    nidle = 9,
**** v1    CLI RX|    nthr = 10,
**** v1    CLI RX|    lqueue = 0
**** v1    CLI RX|  },
**** v1    CLI RX|},
**** v1    CLI RX|
**** v1    CLI RX|
**** dT    4.315
---- v1    Unexpected panic
*    top   failure during reset
#    top  TEST ../../../vmod/tests/std_c00001.vtc FAILED (4.320) exit=2
FAIL tests/std_c00001.vtc (exit status: 2)