**** 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:34391
**** 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.3514.4d3117de
**** top   macro def vtcid=vtc.3514.4d3117de
**   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=45387
**** s1    macro def s1_sock=127.0.0.1:45387
*    s1    Listen on 127.0.0.1:45387
**   top   === varnish v1 -vcl+backend {
**** dT    0.004
**   s1    Started on 127.0.0.1:45387 (1 iterations)
**** dT    0.024
**   v1    Launch
***  v1    CMD: cd ${pwd} && exec varnishd  -d -n /tmp/vtest.clang/_vtest_tmp/vtc.3514.4d3117de/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 44123' -P /tmp/vtest.clang/_vtest_tmp/vtc.3514.4d3117de/v1/varnishd.pid -p vmod_path=/tmp/vtest.clang/varnish-cache/varnish-trunk/_build/sub/vmod/.libs 
**** dT    0.025
***  v1    CMD: cd /tmp/vtest.clang/varnish-cache/varnish-trunk/_build/sub/vmod && exec varnishd  -d -n /tmp/vtest.clang/_vtest_tmp/vtc.3514.4d3117de/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 44123' -P /tmp/vtest.clang/_vtest_tmp/vtc.3514.4d3117de/v1/varnishd.pid -p vmod_path=/tmp/vtest.clang/varnish-cache/varnish-trunk/_build/sub/vmod/.libs 
***  v1    PID: 3554
**** v1    macro def v1_pid=3554
**** v1    macro def v1_name=/tmp/vtest.clang/_vtest_tmp/vtc.3514.4d3117de/v1
**** dT    0.088
***  v1    debug|Debug: Version: varnish-trunk revision 75aa1a8056c56d9b2c7350bf4e2be44b0a4115e4
**** dT    0.096
***  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 75aa1a8056c56d9b2c7350bf4e2be44b0a4115e4
***  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.192
**** v1    CLIPOLL 1 0x1 0x0 0x0
***  v1    CLI connection fd = 7
***  v1    CLI RX  107
**** v1    CLI RX|yrjgghmfbsmccjlbmazejfquptjbvcdu
**** v1    CLI RX|
**** v1    CLI RX|Authentication required.
**** v1    CLI TX|auth 1a532ff9f8061b56315184bb0c5adc7b7bb5fad178a6e53d7bb213145a069808
**** dT    0.200
***  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 75aa1a8056c56d9b2c7350bf4e2be44b0a4115e4
**** 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 = "45387"; }
**** 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.307
***  v1    vsl|No VSL chunk found (child not started ?)
**** dT    0.408
***  v1    vsl|No VSL chunk found (child not started ?)
**** dT    0.512
***  v1    vsl|No VSL chunk found (child not started ?)
**** dT    0.618
***  v1    vsl|No VSL chunk found (child not started ?)
**** dT    0.719
***  v1    vsl|No VSL chunk found (child not started ?)
**** dT    0.821
***  v1    vsl|No VSL chunk found (child not started ?)
**** dT    0.944
***  v1    vsl|No VSL chunk found (child not started ?)
**** dT    0.972
***  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    1.032
***  v1    debug|Debug: Child (3642) Started
**** dT    1.070
***  v1    debug|Child launched OK
**** dT    1.147
**** v1    vsl|          0 CLI             - Rd vcl.load "vcl1" vcl_vcl1.1742615714.113867/vgc.so 1auto
**** dT    1.221
***  v1    CLI RX  200
***  v1    wait-running
**** v1    CLI TX|status
***  v1    CLI RX  200
**** v1    CLI RX|Child in state running
**** v1    CLI TX|debug.listen_address
**** dT    1.225
***  v1    debug|Info: Child (3642) said Child starts
**** dT    1.249
**** v1    vsl|          0 CLI             - Wr 200 52 Loaded "vcl_vcl1.1742615714.113867/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:43823
**** v1    vsl|          0 Debug           - sockopt: Setting SO_KEEPALIVE for a0=127.0.0.1:43823
**** v1    vsl|          0 Debug           - sockopt: Setting SO_SNDTIMEO for a0=127.0.0.1:43823
**** v1    vsl|          0 Debug           - sockopt: Setting SO_RCVTIMEO for a0=127.0.0.1:43823
**** v1    vsl|          0 Debug           - sockopt: Setting TCP_NODELAY for a0=127.0.0.1:43823
**** v1    vsl|          0 Debug           - sockopt: Setting TCP_KEEPIDLE for a0=127.0.0.1:43823
**** v1    vsl|          0 Debug           - sockopt: Setting TCP_KEEPCNT for a0=127.0.0.1:43823
**** v1    vsl|          0 Debug           - sockopt: Setting TCP_KEEPINTVL for a0=127.0.0.1:43823
**** v1    vsl|          0 CLI             - Wr 200 0 
**** dT    1.272
***  v1    CLI RX  200
**** v1    CLI RX|a0 127.0.0.1 43823
**** v1    CLI TX|debug.xid 1000
**** dT    1.323
***  v1    CLI RX  200
**** v1    CLI RX|XID is 1000 chunk 1
**** v1    CLI TX|debug.listen_address
**** dT    1.352
**** v1    vsl|          0 CLI             - Rd debug.listen_address 
**** v1    vsl|          0 CLI             - Wr 200 19 a0 127.0.0.1 43823

**** v1    vsl|          0 CLI             - Rd debug.xid 1000 
**** v1    vsl|          0 CLI             - Wr 200 19 XID is 1000 chunk 1
**** dT    1.376
***  v1    CLI RX  200
**** v1    CLI RX|a0 127.0.0.1 43823
**   v1    Listen on 127.0.0.1 43823
**** v1    macro def v1_addr=127.0.0.1
**** v1    macro def v1_port=43823
**** v1    macro def v1_sock=127.0.0.1:43823
**** v1    macro def v1_a0_addr=127.0.0.1
**** v1    macro def v1_a0_port=43823
**** v1    macro def v1_a0_sock=127.0.0.1:43823
**   top   === client c1 {
**   c1    Starting client
**   c1    Waiting for client
**** dT    1.379
**   c1    Started on 127.0.0.1:43823 (1 iterations)
***  c1    Connect to 127.0.0.1:43823
***  c1    connected fd 17 from 127.0.0.1 40732 to 127.0.0.1:43823
**   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.388
***  s1    accepted fd 5 127.0.0.1 41520
**   s1    === rxreq
**** 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: Sat, 22 Mar 2025 03:55:15 GMT\r
**** s1    txresp|Server: s1\r
**** s1    txresp|Content-Length: 0\r
**** s1    txresp|\r
**   s1    === accept
**** s1    Accepting
**** dT    1.418
***  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: Sat, 22 Mar 2025 03:55:15 GMT\r
**** s1    txresp|Server: s1\r
**** s1    txresp|Content-Length: 0\r
**** s1    txresp|\r
**   s1    === accept
**** s1    Accepting
**** dT    1.436
**** c1    rxhdr|HTTP/1.1 200 OK\r
**** c1    rxhdr|Date: Sat, 22 Mar 2025 03:55:15 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: Sat, 22 Mar 2025 03:55:15 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
**** dT    1.437
**   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 = "45387"; }
**** 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.453
**** v1    vsl|          0 CLI             - Rd debug.listen_address 
**** v1    vsl|          0 CLI             - Wr 200 19 a0 127.0.0.1 43823

**** v1    vsl|       1000 Begin           c sess 0 HTTP/1
**** v1    vsl|       1000 SessOpen        c 127.0.0.1 40732 a0 127.0.0.1 43823 1742615715.288463 21
**** v1    vsl|       1000 Debug           c sockopt: SO_LINGER may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1000 Debug           c sockopt: SO_KEEPALIVE may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1000 Debug           c sockopt: SO_SNDTIMEO may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1000 Debug           c sockopt: SO_RCVTIMEO may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1000 Debug           c sockopt: TCP_NODELAY may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1000 Debug           c sockopt: TCP_KEEPIDLE may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1000 Debug           c sockopt: TCP_KEEPCNT may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1000 Debug           c sockopt: TCP_KEEPINTVL may be inherited for a0=127.0.0.1:43823
**** 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: 1742615715.294164 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: 1742615715.294218 0.000054 0.000054
**** v1    vsl|       1002 Timestamp       b Connected: 1742615715.294325 0.000161 0.000107
**** v1    vsl|       1002 BackendOpen     b 24 s1 127.0.0.1 45387 127.0.0.1 41520 connect
**** v1    vsl|       1002 Timestamp       b Bereq: 1742615715.294397 0.000232 0.000071
**** 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: Sat, 22 Mar 2025 03:55:15 GMT
**** v1    vsl|       1002 BerespHeader    b Server: s1
**** v1    vsl|       1002 BerespHeader    b Content-Length: 0
**** v1    vsl|       1002 Timestamp       b Beresp: 1742615715.301326 0.007162 0.006929
**** v1    vsl|       1002 TTL             b RFC -1 10 0 1742615715 1742615715 1742615715 0 0 cacheable
**** v1    vsl|       1002 VCL_call        b BACKEND_RESPONSE
**** v1    vsl|       1002 TTL             b VCL 120 10 0 1742615715 cacheable
**** v1    vsl|       1002 TTL             b VCL 120 10 0 1742615715 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: 1742615715.301355 0.007191 0.000028
**** 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: 1742615715.314385 0.020221 0.013030
**** 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: 1742615715.288535 0.000000 0.000000
**** v1    vsl|       1001 Timestamp       c Req: 1742615715.288535 0.000000 0.000000
**** v1    vsl|       1001 VCL_use         c vcl1
**** v1    vsl|       1001 ReqStart        c 127.0.0.1 40732 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: 1742615715.319353 0.030818 0.030818
**** 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: Sat, 22 Mar 2025 03:55:15 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: Sat, 22 Mar 2025 03:55:15 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: 1742615715.319419 0.030884 0.000065
**** 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: 1742615715.322935 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: 1742615715.322978 0.000042 0.000042
**** v1    vsl|       1004 Timestamp       b Connected: 1742615715.323120 0.000185 0.000142
**** v1    vsl|       1004 BackendOpen     b 24 s1 127.0.0.1 45387 127.0.0.1 41522 connect
**** v1    vsl|       1004 Timestamp       b Bereq: 1742615715.323175 0.000239 0.000054
**** 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: Sat, 22 Mar 2025 03:55:15 GMT
**** v1    vsl|       1004 BerespHeader    b Server: s1
**** v1    vsl|       1004 BerespHeader    b Content-Length: 0
**** v1    vsl|       1004 Timestamp       b Beresp: 1742615715.330425 0.007489 0.007250
**** v1    vsl|       1004 TTL             b RFC 120 10 0 1742615715 1742615715 1742615715 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: 1742615715.330494 0.007559 0.000069
**** 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: 1742615715.342571 0.019635 0.012076
**** 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: 1742615715.319419 0.030884 0.000000
**** v1    vsl|       1003 ReqStart        c 127.0.0.1 40732 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: 1742615715.342696 0.054161 0.023277
**** 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: Sat, 22 Mar 2025 03:55:15 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: 1742615715.342734 0.054199 0.000037
**** v1    vsl|       1003 Filters         c 
**** v1    vsl|       1003 RespHeader      c Connection: keep-alive
**** v1    vsl|       1003 Timestamp       c Resp: 1742615715.342793 0.054258 0.000058
**** v1    vsl|       1003 ReqAcct         c 67 0 67 187 0 187
**** v1    vsl|       1003 End             c 
**** v1    vsl|       1000 SessClose       c REM_CLOSE 0.061
**** v1    vsl|       1000 End             c 
**** dT    2.306
***  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
**   c1    Started on 127.0.0.1:43823 (1 iterations)
***  c1    Connect to 127.0.0.1:43823
**** dT    2.307
***  c1    connected fd 17 from 127.0.0.1 40744 to 127.0.0.1:43823
**   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.311
***  s1    Accepted socket fd is 5
**   s1    === rxreq
**** 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: Sat, 22 Mar 2025 03:55:16 GMT\r
**** s1    txresp|Server: s1\r
**** s1    txresp|Content-Length: 0\r
**** s1    txresp|\r
**   s1    === accept
**** s1    Accepting
**** dT    2.326
***  s1    Accepted socket fd is 5
**   s1    === rxreq
**** dT    2.327
**** 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: Sat, 22 Mar 2025 03:55:16 GMT\r
**** s1    txresp|Server: s1\r
**** s1    txresp|Content-Length: 0\r
**** s1    txresp|\r
***  s1    shutting fd 5
**   s1    Ending
**** dT    2.345
**** c1    rxhdr|HTTP/1.1 200 OK\r
**** c1    rxhdr|Date: Sat, 22 Mar 2025 03:55:16 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: Sat, 22 Mar 2025 03:55:16 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
**   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.384
**** v1    vsl|          0 CLI             - Rd vcl.load vcl2 vcl_vcl2.1742615715.346000/vgc.so 1auto
**** v1    vsl|          0 CLI             - Wr 200 52 Loaded "vcl_vcl2.1742615715.346000/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 40744 a0 127.0.0.1 43823 1742615716.216464 22
**** v1    vsl|       1005 Debug           c sockopt: SO_LINGER may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1005 Debug           c sockopt: SO_KEEPALIVE may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1005 Debug           c sockopt: SO_SNDTIMEO may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1005 Debug           c sockopt: SO_RCVTIMEO may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1005 Debug           c sockopt: TCP_NODELAY may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1005 Debug           c sockopt: TCP_KEEPIDLE may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1005 Debug           c sockopt: TCP_KEEPCNT may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1005 Debug           c sockopt: TCP_KEEPINTVL may be inherited for a0=127.0.0.1:43823
**** 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: 1742615716.219681 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: 1742615716.219739 0.000058 0.000058
**** v1    vsl|       1007 Timestamp       b Connected: 1742615716.219883 0.000202 0.000143
**** v1    vsl|       1007 BackendOpen     b 24 s1 127.0.0.1 45387 127.0.0.1 41534 connect
**** v1    vsl|       1007 Timestamp       b Bereq: 1742615716.219928 0.000246 0.000044
**** 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: Sat, 22 Mar 2025 03:55:16 GMT
**** v1    vsl|       1007 BerespHeader    b Server: s1
**** v1    vsl|       1007 BerespHeader    b Content-Length: 0
**** v1    vsl|       1007 Timestamp       b Beresp: 1742615716.220528 0.000847 0.000600
**** v1    vsl|       1007 TTL             b RFC -1 10 0 1742615716 1742615716 1742615716 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: 1742615716.235535 0.015853 0.015006
**** 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: 1742615716.235535 0.015853 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: 1742615716.235561 0.015879 0.000025
**** v1    vsl|       1008 Timestamp       b Connected: 1742615716.235645 0.015964 0.000084
**** v1    vsl|       1008 BackendOpen     b 24 s1 127.0.0.1 45387 127.0.0.1 41538 connect
**** v1    vsl|       1008 Timestamp       b Bereq: 1742615716.235694 0.016012 0.000048
**** 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: Sat, 22 Mar 2025 03:55:16 GMT
**** v1    vsl|       1008 BerespHeader    b Server: s1
**** v1    vsl|       1008 BerespHeader    b Content-Length: 0
**** v1    vsl|       1008 Timestamp       b Beresp: 1742615716.236556 0.016875 0.000862
**** v1    vsl|       1008 TTL             b RFC 120 10 0 1742615716 1742615716 1742615716 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: 1742615716.236629 0.016948 0.000072
**** 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: 1742615716.250075 0.030394 0.013446
**** 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: 1742615716.216527 0.000000 0.000000
**** v1    vsl|       1006 Timestamp       c Req: 1742615716.216527 0.000000 0.000000
**** v1    vsl|       1006 VCL_use         c vcl2
**** v1    vsl|       1006 ReqStart        c 127.0.0.1 40744 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: 1742615716.252917 0.036389 0.036389
**** 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: Sat, 22 Mar 2025 03:55:16 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: 1742615716.252967 0.036439 0.000049
**** v1    vsl|       1006 Filters         c 
**** v1    vsl|       1006 RespHeader      c Connection: keep-alive
**** v1    vsl|       1006 Timestamp       c Resp: 1742615716.253049 0.036521 0.000082
**** v1    vsl|       1006 ReqAcct         c 67 0 67 187 0 187
**** v1    vsl|       1006 End             c 
**** v1    vsl|       1005 SessClose       c REM_CLOSE 0.038
**** v1    vsl|       1005 End             c 
**** dT    2.393
***  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.444
***  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 = "45387"; }
**** 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    3.376
***  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=45387
**** s1    macro def s1_sock=127.0.0.1:45387
*    s1    Listen on 127.0.0.1:45387
**   top   === client c1 {
**   c1    Starting client
**   c1    Waiting for client
**   c1    Started on 127.0.0.1:43823 (1 iterations)
***  c1    Connect to 127.0.0.1:43823
***  c1    connected fd 5 from 127.0.0.1 40764 to 127.0.0.1:43823
**   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
**** dT    3.377
**   c1    === rxresp
**** dT    3.380
**   s1    Started on 127.0.0.1:45387 (1 iterations)
**** dT    3.386
***  s1    accepted fd 17 127.0.0.1 41552
**   s1    === rxreq
**** dT    3.387
**** 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: Sat, 22 Mar 2025 03:55:17 GMT\r
**** s1    txresp|Server: s1\r
**** s1    txresp|Content-Length: 0\r
**** s1    txresp|\r
***  s1    shutting fd 17
**   s1    Ending
**** dT    3.405
**** 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
**** dT    3.406
**   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 = "45387"; }
**** 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    3.413
**** v1    vsl|          0 CLI             - Rd vcl.load vcl3 vcl_vcl3.1742615716.353632/vgc.so 1auto
**** v1    vsl|          0 CLI             - Wr 200 52 Loaded "vcl_vcl3.1742615716.353632/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 40764 a0 127.0.0.1 43823 1742615717.285966 23
**** v1    vsl|       1009 Debug           c sockopt: SO_LINGER may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1009 Debug           c sockopt: SO_KEEPALIVE may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1009 Debug           c sockopt: SO_SNDTIMEO may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1009 Debug           c sockopt: SO_RCVTIMEO may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1009 Debug           c sockopt: TCP_NODELAY may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1009 Debug           c sockopt: TCP_KEEPIDLE may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1009 Debug           c sockopt: TCP_KEEPCNT may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1009 Debug           c sockopt: TCP_KEEPINTVL may be inherited for a0=127.0.0.1:43823
**** 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: 1742615717.288995 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: 1742615717.289042 0.000046 0.000046
**** v1    vsl|       1011 Timestamp       b Connected: 1742615717.289121 0.000125 0.000078
**** v1    vsl|       1011 BackendOpen     b 24 s1 127.0.0.1 45387 127.0.0.1 41552 connect
**** v1    vsl|       1011 Timestamp       b Bereq: 1742615717.289167 0.000171 0.000045
**** 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: Sat, 22 Mar 2025 03:55:17 GMT
**** v1    vsl|       1011 BerespHeader    b Server: s1
**** v1    vsl|       1011 BerespHeader    b Content-Length: 0
**** v1    vsl|       1011 Timestamp       b Beresp: 1742615717.297317 0.008321 0.008149
**** v1    vsl|       1011 TTL             b RFC 120 10 0 1742615717 1742615717 1742615717 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: 1742615717.308604 0.019608 0.011287
**** v1    vsl|       1011 FetchError      b No backend connection (rollback?)
**** v1    vsl|       1011 Timestamp       b Error: 1742615717.308612 0.019616 0.000008
**** 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: 1742615717.286019 0.000000 0.000000
**** v1    vsl|       1010 Timestamp       c Req: 1742615717.286019 0.000000 0.000000
**** v1    vsl|       1010 VCL_use         c vcl3
**** v1    vsl|       1010 ReqStart        c 127.0.0.1 40764 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: 1742615717.308823 0.022804 0.022804
**** 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: 1742615717.308855 0.022835 0.000031
**** 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: 1742615717.308941 0.022922 0.000086
**** v1    vsl|       1010 ReqAcct         c 52 0 52 153 274 427
**** v1    vsl|       1010 End             c 
**** v1    vsl|       1009 SessClose       c REM_CLOSE 0.031
**** v1    vsl|       1009 End             c 
**** dT    4.360
***  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=45387
**** s1    macro def s1_sock=127.0.0.1:45387
*    s1    Listen on 127.0.0.1:45387
**   top   === client c2 {
**   c2    Starting client
**   c2    Waiting for client
**   c2    Started on 127.0.0.1:43823 (1 iterations)
***  c2    Connect to 127.0.0.1:43823
***  c2    connected fd 5 from 127.0.0.1 40772 to 127.0.0.1:43823
**   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    4.365
**   s1    Started on 127.0.0.1:45387 (2 iterations using keepalive)
***  s1    accepted fd 17 127.0.0.1 41560
**   s1    === rxreq
**** 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: Sat, 22 Mar 2025 03:55:18 GMT\r
**** s1    txresp|Server: s1\r
**** s1    txresp|Content-Length: 0\r
**** s1    txresp|\r
**   s1    === rxreq
**** dT    4.388
**** 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: Sat, 22 Mar 2025 03:55:18 GMT\r
**** s1    txresp|Server: s1\r
**** s1    txresp|Content-Length: 0\r
**** s1    txresp|\r
***  s1    shutting fd 17
**   s1    Ending
**** dT    4.401
**** c2    rxhdr|HTTP/1.1 200 OK\r
**** c2    rxhdr|Date: Sat, 22 Mar 2025 03:55:18 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: Sat, 22 Mar 2025 03:55:18 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
**** dT    4.403
**   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 = "45387"; }
**** 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    4.449
**** v1    vsl|          0 CLI             - Rd vcl.load vcl4 vcl_vcl4.1742615717.315881/vgc.so 1auto
**** v1    vsl|          0 CLI             - Wr 200 52 Loaded "vcl_vcl4.1742615717.315881/vgc.so" as "vcl4"
**** v1    vsl|          0 CLI             - Rd ping
**** v1    vsl|          0 CLI             - Wr 200 19 PONG 1742615718 1.0
**** 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 40772 a0 127.0.0.1 43823 1742615718.269525 21
**** v1    vsl|       1012 Debug           c sockopt: SO_LINGER may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1012 Debug           c sockopt: SO_KEEPALIVE may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1012 Debug           c sockopt: SO_SNDTIMEO may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1012 Debug           c sockopt: SO_RCVTIMEO may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1012 Debug           c sockopt: TCP_NODELAY may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1012 Debug           c sockopt: TCP_KEEPIDLE may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1012 Debug           c sockopt: TCP_KEEPCNT may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1012 Debug           c sockopt: TCP_KEEPINTVL may be inherited for a0=127.0.0.1:43823
**** 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: 1742615718.273939 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: 1742615718.273987 0.000047 0.000047
**** v1    vsl|       1014 Timestamp       b Connected: 1742615718.274057 0.000117 0.000069
**** v1    vsl|       1014 BackendOpen     b 24 s1 127.0.0.1 45387 127.0.0.1 41560 connect
**** v1    vsl|       1014 Timestamp       b Bereq: 1742615718.274102 0.000163 0.000045
**** 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: Sat, 22 Mar 2025 03:55:18 GMT
**** v1    vsl|       1014 BerespHeader    b Server: s1
**** v1    vsl|       1014 BerespHeader    b Content-Length: 0
**** v1    vsl|       1014 Timestamp       b Beresp: 1742615718.274680 0.000741 0.000578
**** v1    vsl|       1014 TTL             b RFC 120 10 0 1742615718 1742615718 1742615718 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: 1742615718.296927 0.022987 0.022246
**** 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: 1742615718.296927 0.022987 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: 1742615718.296965 0.023025 0.000037
**** v1    vsl|       1015 Timestamp       b Connected: 1742615718.296973 0.023034 0.000008
**** v1    vsl|       1015 BackendOpen     b 24 s1 127.0.0.1 45387 127.0.0.1 41560 reuse
**** v1    vsl|       1015 Timestamp       b Bereq: 1742615718.297055 0.023116 0.000082
**** 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: Sat, 22 Mar 2025 03:55:18 GMT
**** v1    vsl|       1015 BerespHeader    b Server: s1
**** v1    vsl|       1015 BerespHeader    b Content-Length: 0
**** v1    vsl|       1015 Timestamp       b Beresp: 1742615718.297766 0.023827 0.000711
**** v1    vsl|       1015 TTL             b RFC 120 10 0 1742615718 1742615718 1742615718 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: 1742615718.297804 0.023865 0.000037
**** 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: 1742615718.309747 0.035808 0.011942
**** 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: 1742615718.269573 0.000000 0.000000
**** v1    vsl|       1013 Timestamp       c Req: 1742615718.269573 0.000000 0.000000
**** v1    vsl|       1013 VCL_use         c vcl4
**** v1    vsl|       1013 ReqStart        c 127.0.0.1 40772 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: 1742615718.309787 0.040213 0.040213
**** 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: Sat, 22 Mar 2025 03:55:18 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: 1742615718.309830 0.040256 0.000043
**** v1    vsl|       1013 Filters         c 
**** v1    vsl|       1013 RespHeader      c Connection: keep-alive
**** v1    vsl|       1013 Timestamp       c Resp: 1742615718.309880 0.040307 0.000050
**** v1    vsl|       1013 ReqAcct         c 52 0 52 187 0 187
**** v1    vsl|       1013 End             c 
**** v1    vsl|       1012 SessClose       c REM_CLOSE 0.043
**** v1    vsl|       1012 End             c 
**** dT    4.950
***  v1    CLI RX  200
**** 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=45387
**** s1    macro def s1_sock=127.0.0.1:45387
*    s1    Listen on 127.0.0.1:45387
**   top   === client c3 {
**   c3    Starting client
**   c3    Waiting for client
**** dT    4.951
**   s1    Started on 127.0.0.1:45387 (5 iterations using keepalive)
**   c3    Started on 127.0.0.1:43823 (1 iterations)
***  c3    Connect to 127.0.0.1:43823
***  c3    connected fd 17 from 127.0.0.1 40776 to 127.0.0.1:43823
**   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
**** dT    4.952
***  s1    accepted fd 5 127.0.0.1 41564
**   s1    === rxreq
**** 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: Sat, 22 Mar 2025 03:55:18 GMT\r
**** s1    txresp|Server: s1\r
**** s1    txresp|Content-Length: 0\r
**** s1    txresp|\r
**   s1    === rxreq
**** dT    4.960
**** v1    vsl|          0 CLI             - Rd vcl.load vcl5 vcl_vcl5.1742615718.312212/vgc.so 1auto
**** v1    vsl|          0 CLI             - Wr 200 52 Loaded "vcl_vcl5.1742615718.312212/vgc.so" as "vcl5"
**** 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 40776 a0 127.0.0.1 43823 1742615718.860052 22
**** v1    vsl|       1016 Debug           c sockopt: SO_LINGER may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1016 Debug           c sockopt: SO_KEEPALIVE may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1016 Debug           c sockopt: SO_SNDTIMEO may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1016 Debug           c sockopt: SO_RCVTIMEO may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1016 Debug           c sockopt: TCP_NODELAY may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1016 Debug           c sockopt: TCP_KEEPIDLE may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1016 Debug           c sockopt: TCP_KEEPCNT may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1016 Debug           c sockopt: TCP_KEEPINTVL may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1016 Link            c req 1017 rxreq
**** dT    4.963
**** 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: Sat, 22 Mar 2025 03:55:18 GMT\r
**** s1    txresp|Server: s1\r
**** s1    txresp|Content-Length: 0\r
**** s1    txresp|\r
**   s1    === rxreq
**** dT    4.974
**** c3    rxhdr|HTTP/1.1 200 OK\r
**** c3    rxhdr|Date: Sat, 22 Mar 2025 03:55:18 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: Sat, 22 Mar 2025 03:55:18 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
**** 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.975
**** 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: Sat, 22 Mar 2025 03:55:18 GMT\r
**** s1    txresp|Server: s1\r
**** s1    txresp|Content-Length: 0\r
**** s1    txresp|\r
**   s1    === rxreq
**** dT    4.986
**** 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.987
**** s1    rxhdr|GET /5 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: 1024\r
**** s1    rxhdr|fetch: Fetch value 0\r
**** s1    rxhdr|\r
**** s1    rxhdrlen = 171
**** s1    http[ 0] |GET
**** s1    http[ 1] |/5
**** 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: 1024
**** s1    http[ 9] |fetch: Fetch value 0
**** s1    bodylen = 0
**   s1    === txresp
**** s1    txresp|HTTP/1.1 200 OK\r
**** s1    txresp|Date: Sat, 22 Mar 2025 03:55:18 GMT\r
**** s1    txresp|Server: s1\r
**** s1    txresp|Content-Length: 0\r
**** s1    txresp|\r
**   s1    === rxreq
**** dT    4.998
**** s1    rxhdr|GET /5 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: 1025\r
**** s1    rxhdr|fetch: Fetch value 1\r
**** s1    rxhdr|\r
**** s1    rxhdrlen = 171
**** s1    http[ 0] |GET
**** s1    http[ 1] |/5
**** 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: 1025
**** s1    http[ 9] |fetch: Fetch value 1
**** s1    bodylen = 0
**   s1    === txresp
**** s1    txresp|HTTP/1.1 200 OK\r
**** s1    txresp|Date: Sat, 22 Mar 2025 03:55:18 GMT\r
**** s1    txresp|Server: s1\r
**** s1    txresp|Content-Length: 0\r
**** s1    txresp|\r
***  s1    shutting fd 5
**   s1    Ending
**** dT    5.009
**** c3    rxhdr|HTTP/1.1 200 OK\r
**** c3    rxhdr|Date: Sat, 22 Mar 2025 03:55:18 GMT\r
**** c3    rxhdr|Server: s1\r
**** c3    rxhdr|Content-Length: 0\r
**** c3    rxhdr|fetch: Fetch value 1\r
**** c3    rxhdr|response: \r
**** c3    rxhdr|response2: \r
**** c3    rxhdr|X-Varnish: 1023\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 = 234
**** c3    http[ 0] |HTTP/1.1
**** c3    http[ 1] |200
**** c3    http[ 2] |OK
**** c3    http[ 3] |Date: Sat, 22 Mar 2025 03:55:18 GMT
**** c3    http[ 4] |Server: s1
**** c3    http[ 5] |Content-Length: 0
**** c3    http[ 6] |fetch: Fetch value 1
**** c3    http[ 7] |response: 
**** c3    http[ 8] |response2: 
**** c3    http[ 9] |X-Varnish: 1023
**** 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 == ""
**** c3    EXPECT resp.http.response () == "" match
**   c3    === expect resp.http.response2 == ""
**** c3    EXPECT resp.http.response2 () == "" match
***  c3    closing fd 17
**   c3    Ending
**   top   === server s1 -wait
**   s1    Waiting for server (4/-1)
**   top   === varnish v1 -vcl+backend {
**** v1    CLI TX|vcl.inline vcl6 << %XJEIFLH|)Xspa8P
**** v1    CLI TX|vcl 4.1;
**** v1    CLI TX|backend s1 { .host = "127.0.0.1"; .port = "45387"; }
**** v1    CLI TX|
**** v1    CLI TX|
**** v1    CLI TX|\timport std;
**** v1    CLI TX|\timport debug;
**** v1    CLI TX|\timport vtc;
**** v1    CLI TX|
**** v1    CLI TX|\tsub vcl_recv {
**** v1    CLI TX|\t\tunset req.http.test;
**** v1    CLI TX|\t}
**** v1    CLI TX|
**** v1    CLI TX|\tsub vcl_deliver {
**** v1    CLI TX|\t\tif (req.url ~ "/veto") {
**** v1    CLI TX|\t\t\tdebug.fail_task_fini();
**** v1    CLI TX|\t\t}
**** v1    CLI TX|\t\tif (req.url ~ "/ok") {
**** v1    CLI TX|\t\t\tdebug.ok_task_fini();
**** v1    CLI TX|\t\t}
**** v1    CLI TX|\t\tstd.rollback(req);
**** v1    CLI TX|\t\tset resp.http.test = req.http.test;
**** v1    CLI TX|\t\tvtc.workspace_alloc(client, -200);
**** v1    CLI TX|\t}
**** v1    CLI TX|
**** v1    CLI TX|%XJEIFLH|)Xspa8P
**** dT    5.063
**** v1    vsl|       1018 Begin           b bereq 1017 fetch
**** v1    vsl|       1018 VCL_use         b vcl5
**** v1    vsl|       1018 Timestamp       b Start: 1742615718.860858 0.000000 0.000000
**** 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: 1742615718.860891 0.000032 0.000032
**** v1    vsl|       1018 Timestamp       b Connected: 1742615718.860947 0.000089 0.000056
**** v1    vsl|       1018 BackendOpen     b 24 s1 127.0.0.1 45387 127.0.0.1 41564 connect
**** v1    vsl|       1018 Timestamp       b Bereq: 1742615718.860992 0.000133 0.000044
**** 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: Sat, 22 Mar 2025 03:55:18 GMT
**** v1    vsl|       1018 BerespHeader    b Server: s1
**** v1    vsl|       1018 BerespHeader    b Content-Length: 0
**** v1    vsl|       1018 Timestamp       b Beresp: 1742615718.861642 0.000783 0.000649
**** v1    vsl|       1018 TTL             b RFC 120 10 0 1742615719 1742615719 1742615718 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
**** dT    5.064
**** 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: 1742615718.871795 0.010937 0.010153
**** v1    vsl|       1018 Link            b bereq 1019 retry
**** v1    vsl|       1018 End             b 
**** v1    vsl|       1019 Begin           b bereq 1018 retry
**** v1    vsl|       1019 Timestamp       b Start: 1742615718.871795 0.010937 0.000000
**** v1    vsl|       1019 BereqMethod     b GET
**** v1    vsl|       1019 BereqURL        b /3
**** v1    vsl|       1019 BereqProtocol   b HTTP/1.1
**** v1    vsl|       1019 BereqHeader     b Host: 127.0.0.1
**** v1    vsl|       1019 BereqHeader     b User-Agent: c3
**** v1    vsl|       1019 BereqHeader     b X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1019 BereqHeader     b Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1019 BereqHeader     b Accept-Encoding: gzip
**** v1    vsl|       1019 BereqHeader     b response: 123
**** v1    vsl|       1019 BereqHeader     b response2: Another response
**** v1    vsl|       1019 BereqHeader     b X-Varnish: 1019
**** v1    vsl|       1019 VCL_call        b BACKEND_FETCH
**** v1    vsl|       1019 BereqHeader     b fetch: Fetch value 1
**** v1    vsl|       1019 VCL_return      b fetch
**** v1    vsl|       1019 Timestamp       b Fetch: 1742615718.871817 0.010959 0.000021
**** v1    vsl|       1019 Timestamp       b Connected: 1742615718.871823 0.010964 0.000005
**** v1    vsl|       1019 BackendOpen     b 24 s1 127.0.0.1 45387 127.0.0.1 41564 reuse
**** v1    vsl|       1019 Timestamp       b Bereq: 1742615718.871875 0.011017 0.000052
**** v1    vsl|       1019 BerespProtocol  b HTTP/1.1
**** v1    vsl|       1019 BerespStatus    b 200
**** v1    vsl|       1019 BerespReason    b OK
**** v1    vsl|       1019 BerespHeader    b Date: Sat, 22 Mar 2025 03:55:18 GMT
**** v1    vsl|       1019 BerespHeader    b Server: s1
**** v1    vsl|       1019 BerespHeader    b Content-Length: 0
**** v1    vsl|       1019 Timestamp       b Beresp: 1742615718.872649 0.011790 0.000773
**** v1    vsl|       1019 TTL             b RFC 120 10 0 1742615719 1742615719 1742615718 0 0 cacheable
**** v1    vsl|       1019 VCL_call        b BACKEND_RESPONSE
**** v1    vsl|       1019 BerespHeader    b fetch: Fetch value 1
**** v1    vsl|       1019 BerespHeader    b response: 123
**** v1    vsl|       1019 BerespHeader    b response2: Another response
**** v1    vsl|       1019 VCL_return      b deliver
**** v1    vsl|       1019 Debug           b Missing content-range header or unknown range unit
**** v1    vsl|       1019 Timestamp       b Process: 1742615718.872685 0.011826 0.000036
**** v1    vsl|       1019 Filters         b 
**** v1    vsl|       1019 Storage         b malloc s0
**** v1    vsl|       1019 Fetch_Body      b 0 none -
**** v1    vsl|       1019 BackendClose    b 24 s1 recycle
**** v1    vsl|       1019 Timestamp       b BerespBody: 1742615718.882835 0.021977 0.010150
**** v1    vsl|       1019 Length          b 0
**** v1    vsl|       1019 BereqAcct       b 386 0 386 174 0 174
**** v1    vsl|       1019 End             b 
**** v1    vsl|       1017 Begin           c req 1016 rxreq
**** v1    vsl|       1017 Timestamp       c Start: 1742615718.860103 0.000000 0.000000
**** v1    vsl|       1017 Timestamp       c Req: 1742615718.860103 0.000000 0.000000
**** v1    vsl|       1017 VCL_use         c vcl5
**** v1    vsl|       1017 ReqStart        c 127.0.0.1 40776 a0
**** v1    vsl|       1017 ReqMethod       c GET
**** v1    vsl|       1017 ReqURL          c /3
**** v1    vsl|       1017 ReqProtocol     c HTTP/1.1
**** v1    vsl|       1017 ReqHeader       c Host: 127.0.0.1
**** v1    vsl|       1017 ReqHeader       c User-Agent: c3
**** v1    vsl|       1017 ReqHeader       c X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1017 ReqHeader       c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1017 VCL_call        c RECV
**** v1    vsl|       1017 VCL_return      c hash
**** v1    vsl|       1017 VCL_call        c HASH
**** v1    vsl|       1017 VCL_return      c lookup
**** v1    vsl|       1017 VCL_call        c MISS
**** v1    vsl|       1017 VCL_return      c fetch
**** v1    vsl|       1017 Link            c bereq 1018 fetch
**** v1    vsl|       1017 Timestamp       c Fetch: 1742615718.882888 0.022785 0.022785
**** v1    vsl|       1017 RespProtocol    c HTTP/1.1
**** v1    vsl|       1017 RespStatus      c 200
**** v1    vsl|       1017 RespReason      c OK
**** v1    vsl|       1017 RespHeader      c Date: Sat, 22 Mar 2025 03:55:18 GMT
**** v1    vsl|       1017 RespHeader      c Server: s1
**** v1    vsl|       1017 RespHeader      c Content-Length: 0
**** v1    vsl|       1017 RespHeader      c fetch: Fetch value 1
**** v1    vsl|       1017 RespHeader      c response: 123
**** v1    vsl|       1017 RespHeader      c response2: Another response
**** v1    vsl|       1017 RespHeader      c X-Varnish: 1017
**** v1    vsl|       1017 RespHeader      c Age: 0
**** v1    vsl|       1017 RespHeader      c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1017 RespHeader      c Accept-Ranges: bytes
**** v1    vsl|       1017 VCL_call        c DELIVER
**** v1    vsl|       1017 VCL_return      c deliver
**** v1    vsl|       1017 Timestamp       c Process: 1742615718.882921 0.022818 0.000032
**** v1    vsl|       1017 Filters         c 
**** v1    vsl|       1017 RespHeader      c Connection: keep-alive
**** v1    vsl|       1017 Timestamp       c Resp: 1742615718.882973 0.022870 0.000052
**** v1    vsl|       1017 ReqAcct         c 52 0 52 253 0 253
**** v1    vsl|       1017 End             c 
**** v1    vsl|       1016 Link            c req 1020 rxreq
**** v1    vsl|       1021 Begin           b bereq 1020 fetch
**** v1    vsl|       1021 VCL_use         b vcl5
**** v1    vsl|       1021 Timestamp       b Start: 1742615718.883940 0.000000 0.000000
**** v1    vsl|       1021 BereqMethod     b GET
**** v1    vsl|       1021 BereqURL        b /4
**** v1    vsl|       1021 BereqProtocol   b HTTP/1.1
**** v1    vsl|       1021 BereqHeader     b Host: 127.0.0.1
**** v1    vsl|       1021 BereqHeader     b User-Agent: c3
**** v1    vsl|       1021 BereqHeader     b X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1021 BereqHeader     b Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1021 BereqHeader     b Accept-Encoding: gzip
**** v1    vsl|       1021 BereqHeader     b X-Varnish: 1021
**** v1    vsl|       1021 VCL_call        b BACKEND_FETCH
**** v1    vsl|       1021 BereqHeader     b fetch: Fetch value 0
**** v1    vsl|       1021 VCL_return      b fetch
**** v1    vsl|       1021 Timestamp       b Fetch: 1742615718.883962 0.000021 0.000021
**** v1    vsl|       1021 Timestamp       b Connected: 1742615718.883967 0.000027 0.000005
**** v1    vsl|       1021 BackendOpen     b 24 s1 127.0.0.1 45387 127.0.0.1 41564 reuse
**** v1    vsl|       1021 Timestamp       b Bereq: 1742615718.884007 0.000067 0.000040
**** v1    vsl|       1021 BerespProtocol  b HTTP/1.1
**** v1    vsl|       1021 BerespStatus    b 200
**** v1    vsl|       1021 BerespReason    b OK
**** v1    vsl|       1021 BerespHeader    b Date: Sat, 22 Mar 2025 03:55:18 GMT
**** v1    vsl|       1021 BerespHeader    b Server: s1
**** v1    vsl|       1021 BerespHeader    b Content-Length: 0
**** v1    vsl|       1021 Timestamp       b Beresp: 1742615718.884636 0.000696 0.000628
**** v1    vsl|       1021 TTL             b RFC 120 10 0 1742615719 1742615719 1742615718 0 0 cacheable
**** v1    vsl|       1021 VCL_call        b BACKEND_RESPONSE
**** v1    vsl|       1021 BackendClose    b 24 s1 recycle
**** v1    vsl|       1021 BereqHeader     b response: 123
**** v1    vsl|       1021 BereqHeader     b response2: Another response
**** v1    vsl|       1021 VCL_return      b retry
**** v1    vsl|       1021 Debug           b Missing content-range header or unknown range unit
**** v1    vsl|       1021 Timestamp       b Retry: 1742615718.894787 0.010847 0.010150
**** v1    vsl|       1021 Link            b bereq 1022 retry
**** v1    vsl|       1021 End             b 
**** v1    vsl|       1022 Begin           b bereq 1021 retry
**** v1    vsl|       1022 Timestamp       b Start: 1742615718.894787 0.010847 0.000000
**** v1    vsl|       1022 BereqMethod     b GET
**** v1    vsl|       1022 BereqURL        b /4
**** v1    vsl|       1022 BereqProtocol   b HTTP/1.1
**** v1    vsl|       1022 BereqHeader     b Host: 127.0.0.1
**** v1    vsl|       1022 BereqHeader     b User-Agent: c3
**** v1    vsl|       1022 BereqHeader     b X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1022 BereqHeader     b Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1022 BereqHeader     b Accept-Encoding: gzip
**** v1    vsl|       1022 BereqHeader     b response: 123
**** v1    vsl|       1022 BereqHeader     b response2: Another response
**** v1    vsl|       1022 Error           b out of workspace (bo)
**** v1    vsl|       1022 LostHeader      b X-Varnish: 1022
**** v1    vsl|       1022 VCL_call        b BACKEND_FETCH
**** v1    vsl|       1022 LostHeader      b fetch:
**** v1    vsl|       1022 VCL_return      b fetch
**** v1    vsl|       1022 Timestamp       b Fetch: 1742615718.894808 0.010868 0.000020
**** v1    vsl|       1022 FetchError      b out of workspace
**** v1    vsl|       1022 Timestamp       b Beresp: 1742615718.894813 0.010873 0.000004
**** v1    vsl|       1022 Timestamp       b Error: 1742615718.894814 0.010874 0.000001
**** v1    vsl|       1022 BerespProtocol  b HTTP/1.1
**** v1    vsl|       1022 BerespStatus    b 503
**** v1    vsl|       1022 BerespReason    b Backend fetch failed
**** v1    vsl|       1022 Error           b out of workspace (bo)
**** v1    vsl|       1022 LostHeader      b Date: 
**** v1    vsl|       1022 BerespHeader    b Server: Varnish
**** v1    vsl|       1022 VCL_call        b BACKEND_ERROR
**** v1    vsl|       1022 LostHeader      b Content-Type:
**** v1    vsl|       1022 LostHeader      b Retry-After:
**** v1    vsl|       1022 VCL_return      b deliver
**** v1    vsl|       1022 Storage         b malloc Transient
**** v1    vsl|       1022 Length          b 271
**** v1    vsl|       1022 BereqAcct       b 171 0 171 87 0 87
**** v1    vsl|       1022 End             b 
**** v1    vsl|       1020 Begin           c req 1016 rxreq
**** v1    vsl|       1020 Timestamp       c Start: 1742615718.883860 0.000000 0.000000
**** v1    vsl|       1020 Timestamp       c Req: 1742615718.883860 0.000000 0.000000
**** v1    vsl|       1020 VCL_use         c vcl5
**** v1    vsl|       1020 ReqStart        c 127.0.0.1 40776 a0
**** v1    vsl|       1020 ReqMethod       c GET
**** v1    vsl|       1020 ReqURL          c /4
**** v1    vsl|       1020 ReqProtocol     c HTTP/1.1
**** v1    vsl|       1020 ReqHeader       c Host: 127.0.0.1
**** v1    vsl|       1020 ReqHeader       c User-Agent: c3
**** v1    vsl|       1020 ReqHeader       c X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1020 ReqHeader       c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1020 VCL_call        c RECV
**** v1    vsl|       1020 VCL_return      c hash
**** v1    vsl|       1020 VCL_call        c HASH
**** v1    vsl|       1020 VCL_return      c lookup
**** v1    vsl|       1020 VCL_call        c MISS
**** v1    vsl|       1020 VCL_return      c fetch
**** v1    vsl|       1020 Link            c bereq 1021 fetch
**** v1    vsl|       1020 Timestamp       c Fetch: 1742615718.894889 0.011028 0.011028
**** v1    vsl|       1020 RespProtocol    c HTTP/1.1
**** v1    vsl|       1020 RespStatus      c 503
**** v1    vsl|       1020 RespReason      c Backend fetch failed
**** v1    vsl|       1020 RespHeader      c Server: Varnish
**** v1    vsl|       1020 RespHeader      c X-Varnish: 1020
**** v1    vsl|       1020 RespHeader      c Age: 0
**** v1    vsl|       1020 RespHeader      c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1020 VCL_call        c DELIVER
**** v1    vsl|       1020 VCL_return      c deliver
**** v1    vsl|       1020 Timestamp       c Process: 1742615718.894909 0.011048 0.000020
**** v1    vsl|       1020 Filters         c 
**** v1    vsl|       1020 RespHeader      c Content-Length: 271
**** v1    vsl|       1020 RespHeader      c Connection: keep-alive
**** v1    vsl|       1020 Timestamp       c Resp: 1742615718.894953 0.011092 0.000043
**** v1    vsl|       1020 ReqAcct         c 52 0 52 153 271 424
**** v1    vsl|       1020 End             c 
**** v1    vsl|       1016 Link            c req 1023 rxreq
**** v1    vsl|       1024 Begin           b bereq 1023 fetch
**** v1    vsl|       1024 VCL_use         b vcl5
**** v1    vsl|       1024 Timestamp       b Start: 1742615718.895597 0.000000 0.000000
**** v1    vsl|       1024 BereqMethod     b GET
**** v1    vsl|       1024 BereqURL        b /5
**** v1    vsl|       1024 BereqProtocol   b HTTP/1.1
**** v1    vsl|       1024 BereqHeader     b Host: 127.0.0.1
**** v1    vsl|       1024 BereqHeader     b User-Agent: c3
**** v1    vsl|       1024 BereqHeader     b X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1024 BereqHeader     b Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1024 BereqHeader     b Accept-Encoding: gzip
**** v1    vsl|       1024 BereqHeader     b X-Varnish: 1024
**** v1    vsl|       1024 VCL_call        b BACKEND_FETCH
**** v1    vsl|       1024 BereqHeader     b fetch: Fetch value 0
**** v1    vsl|       1024 VCL_return      b fetch
**** v1    vsl|       1024 Timestamp       b Fetch: 1742615718.895616 0.000019 0.000019
**** v1    vsl|       1024 Timestamp       b Connected: 1742615718.895620 0.000023 0.000004
**** v1    vsl|       1024 BackendOpen     b 24 s1 127.0.0.1 45387 127.0.0.1 41564 reuse
**** v1    vsl|       1024 Timestamp       b Bereq: 1742615718.895656 0.000059 0.000035
**** v1    vsl|       1024 BerespProtocol  b HTTP/1.1
**** v1    vsl|       1024 BerespStatus    b 200
**** v1    vsl|       1024 BerespReason    b OK
**** v1    vsl|       1024 BerespHeader    b Date: Sat, 22 Mar 2025 03:55:18 GMT
**** v1    vsl|       1024 BerespHeader    b Server: s1
**** v1    vsl|       1024 BerespHeader    b Content-Length: 0
**** v1    vsl|       1024 Timestamp       b Beresp: 1742615718.896281 0.000684 0.000625
**** v1    vsl|       1024 TTL             b RFC 120 10 0 1742615719 1742615719 1742615718 0 0 cacheable
**** v1    vsl|       1024 VCL_call        b BACKEND_RESPONSE
**** v1    vsl|       1024 BackendClose    b 24 s1 recycle
**** v1    vsl|       1024 BereqHeader     b response: 123
**** v1    vsl|       1024 BereqHeader     b response2: Another response
**** v1    vsl|       1024 VCL_return      b retry
**** v1    vsl|       1024 Debug           b Missing content-range header or unknown range unit
**** v1    vsl|       1024 Timestamp       b Retry: 1742615718.906455 0.010858 0.010173
**** v1    vsl|       1024 Link            b bereq 1025 retry
**** v1    vsl|       1024 End             b 
**** v1    vsl|       1025 Begin           b bereq 1024 retry
**** v1    vsl|       1025 Timestamp       b Start: 1742615718.906455 0.010858 0.000000
**** v1    vsl|       1025 BereqMethod     b GET
**** v1    vsl|       1025 BereqURL        b /5
**** v1    vsl|       1025 BereqProtocol   b HTTP/1.1
**** v1    vsl|       1025 BereqHeader     b Host: 127.0.0.1
**** v1    vsl|       1025 BereqHeader     b User-Agent: c3
**** v1    vsl|       1025 BereqHeader     b X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1025 BereqHeader     b Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1025 BereqHeader     b Accept-Encoding: gzip
**** v1    vsl|       1025 BereqHeader     b X-Varnish: 1025
**** v1    vsl|       1025 VCL_call        b BACKEND_FETCH
**** v1    vsl|       1025 BereqHeader     b fetch: Fetch value 1
**** v1    vsl|       1025 VCL_return      b fetch
**** v1    vsl|       1025 Timestamp       b Fetch: 1742615718.906479 0.010882 0.000024
**** v1    vsl|       1025 Timestamp       b Connected: 1742615718.906485 0.010888 0.000006
**** v1    vsl|       1025 BackendOpen     b 24 s1 127.0.0.1 45387 127.0.0.1 41564 reuse
**** v1    vsl|       1025 Timestamp       b Bereq: 1742615718.906549 0.010952 0.000064
**** v1    vsl|       1025 BerespProtocol  b HTTP/1.1
**** v1    vsl|       1025 BerespStatus    b 200
**** v1    vsl|       1025 BerespReason    b OK
**** v1    vsl|       1025 BerespHeader    b Date: Sat, 22 Mar 2025 03:55:18 GMT
**** v1    vsl|       1025 BerespHeader    b Server: s1
**** v1    vsl|       1025 BerespHeader    b Content-Length: 0
**** v1    vsl|       1025 Timestamp       b Beresp: 1742615718.907339 0.011742 0.000789
**** v1    vsl|       1025 TTL             b RFC 120 10 0 1742615719 1742615719 1742615718 0 0 cacheable
**** v1    vsl|       1025 VCL_call        b BACKEND_RESPONSE
**** v1    vsl|       1025 BerespHeader    b fetch: Fetch value 1
**** v1    vsl|       1025 BerespHeader    b response: 
**** v1    vsl|       1025 BerespHeader    b response2: 
**** v1    vsl|       1025 VCL_return      b deliver
**** v1    vsl|       1025 Debug           b Missing content-range header or unknown range unit
**** v1    vsl|       1025 Timestamp       b Process: 1742615718.907376 0.011779 0.000036
**** v1    vsl|       1025 Filters         b 
**** v1    vsl|       1025 Storage         b malloc s0
**** v1    vsl|       1025 Fetch_Body      b 0 none -
**** v1    vsl|       1025 BackendClose    b 24 s1 recycle
**** v1    vsl|       1025 Timestamp       b BerespBody: 1742615718.917538 0.021941 0.010162
**** v1    vsl|       1025 Length          b 0
**** v1    vsl|       1025 BereqAcct       b 342 0 342 174 0 174
**** v1    vsl|       1025 End             b 
**** v1    vsl|       1023 Begin           c req 1016 rxreq
**** v1    vsl|       1023 Timestamp       c Start: 1742615718.895531 0.000000 0.000000
**** v1    vsl|       1023 Timestamp       c Req: 1742615718.895531 0.000000 0.000000
**** v1    vsl|       1023 VCL_use         c vcl5
**** v1    vsl|       1023 ReqStart        c 127.0.0.1 40776 a0
**** v1    vsl|       1023 ReqMethod       c GET
**** v1    vsl|       1023 ReqURL          c /5
**** v1    vsl|       1023 ReqProtocol     c HTTP/1.1
**** v1    vsl|       1023 ReqHeader       c Host: 127.0.0.1
**** v1    vsl|       1023 ReqHeader       c User-Agent: c3
**** v1    vsl|       1023 ReqHeader       c X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1023 ReqHeader       c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1023 VCL_call        c RECV
**** v1    vsl|       1023 VCL_return      c hash
**** v1    vsl|       1023 VCL_call        c HASH
**** v1    vsl|       1023 VCL_return      c lookup
**** v1    vsl|       1023 VCL_call        c MISS
**** v1    vsl|       1023 VCL_return      c fetch
**** v1    vsl|       1023 Link            c bereq 1024 fetch
**** v1    vsl|       1023 Timestamp       c Fetch: 1742615718.917594 0.022062 0.022062
**** v1    vsl|       1023 RespProtocol    c HTTP/1.1
**** v1    vsl|       1023 RespStatus      c 200
**** v1    vsl|       1023 RespReason      c OK
**** v1    vsl|       1023 RespHeader      c Date: Sat, 22 Mar 2025 03:55:18 GMT
**** v1    vsl|       1023 RespHeader      c Server: s1
**** v1    vsl|       1023 RespHeader      c Content-Length: 0
**** v1    vsl|       1023 RespHeader      c fetch: Fetch value 1
**** v1    vsl|       1023 RespHeader      c response: 
**** v1    vsl|       1023 RespHeader      c response2: 
**** v1    vsl|       1023 RespHeader      c X-Varnish: 1023
**** v1    vsl|       1023 RespHeader      c Age: 0
**** v1    vsl|       1023 RespHeader      c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1023 RespHeader      c Accept-Ranges: bytes
**** v1    vsl|       1023 VCL_call        c DELIVER
**** v1    vsl|       1023 VCL_return      c deliver
**** v1    vsl|       1023 Timestamp       c Process: 1742615718.917634 0.022103 0.000040
**** v1    vsl|       1023 Filters         c 
**** v1    vsl|       1023 RespHeader      c Connection: keep-alive
**** v1    vsl|       1023 Timestamp       c Resp: 1742615718.917699 0.022167 0.000064
**** v1    vsl|       1023 ReqAcct         c 52 0 52 234 0 234
**** v1    vsl|       1023 End             c 
**** v1    vsl|       1016 SessClose       c REM_CLOSE 0.059
**** v1    vsl|       1016 End             c 
**** dT    5.450
***  v1    CLI RX  200
**** v1    CLI TX|vcl.use vcl6
***  v1    CLI RX  200
**** v1    CLI RX|VCL 'vcl6' now active
**   top   === server s1 -repeat 3 -keepalive {
**   s1    Starting server
**** s1    macro def s1_addr=127.0.0.1
**** s1    macro def s1_port=45387
**** s1    macro def s1_sock=127.0.0.1:45387
*    s1    Listen on 127.0.0.1:45387
**   top   === client c4 {
**   c4    Starting client
**** dT    5.451
**   c4    Waiting for client
**   s1    Started on 127.0.0.1:45387 (3 iterations using keepalive)
**   c4    Started on 127.0.0.1:43823 (1 iterations)
***  c4    Connect to 127.0.0.1:43823
***  c4    connected fd 17 from 127.0.0.1 40780 to 127.0.0.1:43823
**   c4    === txreq -url /6 -hdr "test: 1"
**** c4    txreq|GET /6 HTTP/1.1\r
**** c4    txreq|test: 1\r
**** c4    txreq|Host: 127.0.0.1\r
**** c4    txreq|User-Agent: c4\r
**** c4    txreq|\r
**   c4    === rxresp
***  s1    accepted fd 5 127.0.0.1 41568
**   s1    === rxreq
**** s1    rxhdr|GET /6 HTTP/1.1\r
**** s1    rxhdr|Host: 127.0.0.1\r
**** s1    rxhdr|User-Agent: c4\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: 1028\r
**** s1    rxhdr|\r
**** s1    rxhdrlen = 149
**** s1    http[ 0] |GET
**** s1    http[ 1] |/6
**** s1    http[ 2] |HTTP/1.1
**** s1    http[ 3] |Host: 127.0.0.1
**** s1    http[ 4] |User-Agent: c4
**** 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: 1028
**** s1    bodylen = 0
**   s1    === expect req.url ~ "^/6"
**** dT    5.452
**** s1    EXPECT req.url (/6) ~ "^/6" match
**   s1    === txresp
**** s1    txresp|HTTP/1.1 200 OK\r
**** s1    txresp|Date: Sat, 22 Mar 2025 03:55:19 GMT\r
**** s1    txresp|Server: s1\r
**** s1    txresp|Content-Length: 0\r
**** s1    txresp|\r
**   s1    === rxreq
**** dT    5.463
**** c4    rxhdr|HTTP/1.1 200 OK\r
**** c4    rxhdr|Date: Sat, 22 Mar 2025 03:55:19 GMT\r
**** c4    rxhdr|Server: s1\r
**** c4    rxhdr|Content-Length: 0\r
**** c4    rxhdr|X-Varnish: 1027\r
**** c4    rxhdr|Age: 0\r
**** c4    rxhdr|Via: 1.1 v1 (Varnish/trunk)\r
**** c4    rxhdr|test: 1\r
**** c4    rxhdr|Connection: keep-alive\r
**** c4    rxhdr|\r
**** c4    rxhdrlen = 174
**** c4    http[ 0] |HTTP/1.1
**** c4    http[ 1] |200
**** c4    http[ 2] |OK
**** c4    http[ 3] |Date: Sat, 22 Mar 2025 03:55:19 GMT
**** c4    http[ 4] |Server: s1
**** c4    http[ 5] |Content-Length: 0
**** c4    http[ 6] |X-Varnish: 1027
**** c4    http[ 7] |Age: 0
**** c4    http[ 8] |Via: 1.1 v1 (Varnish/trunk)
**** c4    http[ 9] |test: 1
**** c4    http[10] |Connection: keep-alive
**** c4    bodylen = 0
**   c4    === expect resp.status == 200
**** c4    EXPECT resp.status (200) == "200" match
**   c4    === expect resp.http.test == "1"
**** c4    EXPECT resp.http.test (1) == "1" match
**   c4    === txreq -url /6/veto/ok -hdr "test: 1"
**** c4    txreq|GET /6/veto/ok HTTP/1.1\r
**** c4    txreq|test: 1\r
**** c4    txreq|Host: 127.0.0.1\r
**** c4    txreq|User-Agent: c4\r
**** c4    txreq|\r
**   c4    === rxresp
**** s1    rxhdr|GET /6/veto/ok HTTP/1.1\r
**** s1    rxhdr|Host: 127.0.0.1\r
**** s1    rxhdr|User-Agent: c4\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: 1030\r
**** s1    rxhdr|\r
**** s1    rxhdrlen = 157
**** s1    http[ 0] |GET
**** s1    http[ 1] |/6/veto/ok
**** s1    http[ 2] |HTTP/1.1
**** s1    http[ 3] |Host: 127.0.0.1
**** s1    http[ 4] |User-Agent: c4
**** 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: 1030
**** s1    bodylen = 0
**   s1    === expect req.url ~ "^/6"
**** s1    EXPECT req.url (/6/veto/ok) ~ "^/6" match
**   s1    === txresp
**** s1    txresp|HTTP/1.1 200 OK\r
**** s1    txresp|Date: Sat, 22 Mar 2025 03:55:19 GMT\r
**** s1    txresp|Server: s1\r
**** s1    txresp|Content-Length: 0\r
**** s1    txresp|\r
**   s1    === rxreq
**** dT    5.474
**** c4    rxhdr|HTTP/1.1 200 OK\r
**** c4    rxhdr|Date: Sat, 22 Mar 2025 03:55:19 GMT\r
**** c4    rxhdr|Server: s1\r
**** c4    rxhdr|Content-Length: 0\r
**** c4    rxhdr|X-Varnish: 1029\r
**** c4    rxhdr|Age: 0\r
**** c4    rxhdr|Via: 1.1 v1 (Varnish/trunk)\r
**** c4    rxhdr|test: 1\r
**** c4    rxhdr|Connection: keep-alive\r
**** c4    rxhdr|\r
**** c4    rxhdrlen = 174
**** c4    http[ 0] |HTTP/1.1
**** dT    5.475
**** c4    http[ 1] |200
**** c4    http[ 2] |OK
**** c4    http[ 3] |Date: Sat, 22 Mar 2025 03:55:19 GMT
**** c4    http[ 4] |Server: s1
**** c4    http[ 5] |Content-Length: 0
**** c4    http[ 6] |X-Varnish: 1029
**** c4    http[ 7] |Age: 0
**** c4    http[ 8] |Via: 1.1 v1 (Varnish/trunk)
**** c4    http[ 9] |test: 1
**** c4    http[10] |Connection: keep-alive
**** c4    bodylen = 0
**   c4    === expect resp.status == 200
**** c4    EXPECT resp.status (200) == "200" match
**   c4    === expect resp.http.test == "1"
**** c4    EXPECT resp.http.test (1) == "1" match
**   c4    === txreq -url /6/veto -hdr "test: 1"
**** c4    txreq|GET /6/veto HTTP/1.1\r
**** c4    txreq|test: 1\r
**** c4    txreq|Host: 127.0.0.1\r
**** c4    txreq|User-Agent: c4\r
**** c4    txreq|\r
**   c4    === rxresp
**** s1    rxhdr|GET /6/veto HTTP/1.1\r
**** s1    rxhdr|Host: 127.0.0.1\r
**** s1    rxhdr|User-Agent: c4\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: 1032\r
**** s1    rxhdr|\r
**** s1    rxhdrlen = 154
**** s1    http[ 0] |GET
**** s1    http[ 1] |/6/veto
**** s1    http[ 2] |HTTP/1.1
**** s1    http[ 3] |Host: 127.0.0.1
**** s1    http[ 4] |User-Agent: c4
**** 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: 1032
**** s1    bodylen = 0
**   s1    === expect req.url ~ "^/6"
**** s1    EXPECT req.url (/6/veto) ~ "^/6" match
**   s1    === txresp
**** s1    txresp|HTTP/1.1 200 OK\r
**** s1    txresp|Date: Sat, 22 Mar 2025 03:55:19 GMT\r
**** s1    txresp|Server: s1\r
**** s1    txresp|Content-Length: 0\r
**** s1    txresp|\r
***  s1    shutting fd 5
**   s1    Ending
**** dT    5.485
**** v1    vsl|          0 CLI             - Rd vcl.load vcl6 vcl_vcl6.1742615718.918869/vgc.so 1auto
**** v1    vsl|          0 Debug           - vcl6: VCL_EVENT_WARM
**** v1    vsl|          0 CLI             - Wr 200 52 Loaded "vcl_vcl6.1742615718.918869/vgc.so" as "vcl6"
**** v1    vsl|          0 CLI             - Rd vcl.use vcl6
**** v1    vsl|          0 CLI             - Wr 200 0 
**** v1    vsl|       1026 Begin           c sess 0 HTTP/1
**** v1    vsl|       1026 SessOpen        c 127.0.0.1 40780 a0 127.0.0.1 43823 1742615719.360106 23
**** v1    vsl|       1026 Debug           c sockopt: SO_LINGER may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1026 Debug           c sockopt: SO_KEEPALIVE may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1026 Debug           c sockopt: SO_SNDTIMEO may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1026 Debug           c sockopt: SO_RCVTIMEO may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1026 Debug           c sockopt: TCP_NODELAY may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1026 Debug           c sockopt: TCP_KEEPIDLE may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1026 Debug           c sockopt: TCP_KEEPCNT may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1026 Debug           c sockopt: TCP_KEEPINTVL may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1026 Link            c req 1027 rxreq
**** v1    vsl|       1028 Begin           b bereq 1027 fetch
**** v1    vsl|       1028 VCL_use         b vcl6
**** v1    vsl|       1028 Timestamp       b Start: 1742615719.360253 0.000000 0.000000
**** v1    vsl|       1028 BereqMethod     b GET
**** v1    vsl|       1028 BereqURL        b /6
**** v1    vsl|       1028 BereqProtocol   b HTTP/1.1
**** v1    vsl|       1028 BereqHeader     b Host: 127.0.0.1
**** v1    vsl|       1028 BereqHeader     b User-Agent: c4
**** v1    vsl|       1028 BereqHeader     b X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1028 BereqHeader     b Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1028 BereqHeader     b Accept-Encoding: gzip
**** v1    vsl|       1028 BereqHeader     b X-Varnish: 1028
**** v1    vsl|       1028 VCL_call        b BACKEND_FETCH
**** v1    vsl|       1028 VCL_return      b fetch
**** v1    vsl|       1028 Timestamp       b Fetch: 1742615719.360277 0.000023 0.000023
**** v1    vsl|       1028 Timestamp       b Connected: 1742615719.360333 0.000079 0.000055
**** v1    vsl|       1028 BackendOpen     b 25 s1 127.0.0.1 45387 127.0.0.1 41568 connect
**** v1    vsl|       1028 Timestamp       b Bereq: 1742615719.360375 0.000121 0.000042
**** v1    vsl|       1028 BerespProtocol  b HTTP/1.1
**** v1    vsl|       1028 BerespStatus    b 200
**** v1    vsl|       1028 BerespReason    b OK
**** v1    vsl|       1028 BerespHeader    b Date: Sat, 22 Mar 2025 03:55:19 GMT
**** v1    vsl|       1028 BerespHeader    b Server: s1
**** v1    vsl|       1028 BerespHeader    b Content-Length: 0
**** v1    vsl|       1028 Timestamp       b Beresp: 1742615719.361066 0.000813 0.000691
**** v1    vsl|       1028 TTL             b RFC 120 10 0 1742615719 1742615719 1742615719 0 0 cacheable
**** v1    vsl|       1028 VCL_call        b BACKEND_RESPONSE
**** v1    vsl|       1028 VCL_return      b deliver
**** v1    vsl|       1028 Debug           b Missing content-range header or unknown range unit
**** v1    vsl|       1028 Timestamp       b Process: 1742615719.361094 0.000840 0.000027
**** v1    vsl|       1028 Filters         b 
**** v1    vsl|       1028 Storage         b malloc s0
**** v1    vsl|       1028 Fetch_Body      b 0 none -
**** v1    vsl|       1028 BackendClose    b 25 s1 recycle
**** v1    vsl|       1028 Timestamp       b BerespBody: 1742615719.371300 0.011046 0.010206
**** v1    vsl|       1028 Length          b 0
**** v1    vsl|       1028 BereqAcct       b 149 0 149 87 0 87
**** v1    vsl|       1028 End             b 
**** v1    vsl|       1027 Begin           c req 1026 rxreq
**** v1    vsl|       1027 Timestamp       c Start: 1742615719.360156 0.000000 0.000000
**** v1    vsl|       1027 Timestamp       c Req: 1742615719.360156 0.000000 0.000000
**** v1    vsl|       1027 VCL_use         c vcl6
**** v1    vsl|       1027 ReqStart        c 127.0.0.1 40780 a0
**** v1    vsl|       1027 ReqMethod       c GET
**** v1    vsl|       1027 ReqURL          c /6
**** v1    vsl|       1027 ReqProtocol     c HTTP/1.1
**** v1    vsl|       1027 ReqHeader       c test: 1
**** v1    vsl|       1027 ReqHeader       c Host: 127.0.0.1
**** v1    vsl|       1027 ReqHeader       c User-Agent: c4
**** v1    vsl|       1027 ReqHeader       c X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1027 ReqHeader       c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1027 VCL_call        c RECV
**** v1    vsl|       1027 ReqUnset        c test: 1
**** v1    vsl|       1027 VCL_return      c hash
**** v1    vsl|       1027 VCL_call        c HASH
**** v1    vsl|       1027 VCL_return      c lookup
**** v1    vsl|       1027 VCL_call        c MISS
**** v1    vsl|       1027 VCL_return      c fetch
**** v1    vsl|       1027 Link            c bereq 1028 fetch
**** v1    vsl|       1027 Timestamp       c Fetch: 1742615719.371365 0.011208 0.011208
**** v1    vsl|       1027 RespProtocol    c HTTP/1.1
**** v1    vsl|       1027 RespStatus      c 200
**** v1    vsl|       1027 RespReason      c OK
**** v1    vsl|       1027 RespHeader      c Date: Sat, 22 Mar 2025 03:55:19 GMT
**** v1    vsl|       1027 RespHeader      c Server: s1
**** v1    vsl|       1027 RespHeader      c Content-Length: 0
**** v1    vsl|       1027 RespHeader      c X-Varnish: 1027
**** v1    vsl|       1027 RespHeader      c Age: 0
**** v1    vsl|       1027 RespHeader      c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1027 RespHeader      c Accept-Ranges: bytes
**** v1    vsl|       1027 VCL_call        c DELIVER
**** v1    vsl|       1027 RespProtocol    c HTTP/1.1
**** v1    vsl|       1027 RespStatus      c 200
**** v1    vsl|       1027 RespReason      c OK
**** v1    vsl|       1027 RespHeader      c Date: Sat, 22 Mar 2025 03:55:19 GMT
**** v1    vsl|       1027 RespHeader      c Server: s1
**** v1    vsl|       1027 RespHeader      c Content-Length: 0
**** v1    vsl|       1027 RespHeader      c X-Varnish: 1027
**** v1    vsl|       1027 RespHeader      c Age: 0
**** v1    vsl|       1027 RespHeader      c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1027 RespHeader      c test: 1
**** v1    vsl|       1027 VCL_return      c deliver
**** v1    vsl|       1027 Timestamp       c Process: 1742615719.371427 0.011270 0.000061
**** v1    vsl|       1027 Filters         c 
**** v1    vsl|       1027 RespHeader      c Connection: keep-alive
**** v1    vsl|       1027 Timestamp       c Resp: 1742615719.371479 0.011322 0.000052
**** v1    vsl|       1027 ReqAcct         c 61 0 61 174 0 174
**** v1    vsl|       1027 End             c 
**** v1    vsl|       1026 Link            c req 1029 rxreq
**** v1    vsl|       1030 Begin           b bereq 1029 fetch
**** v1    vsl|       1030 VCL_use         b vcl6
**** v1    vsl|       1030 Timestamp       b Start: 1742615719.372202 0.000000 0.000000
**** v1    vsl|       1030 BereqMethod     b GET
**** v1    vsl|       1030 BereqURL        b /6/veto/ok
**** v1    vsl|       1030 BereqProtocol   b HTTP/1.1
**** v1    vsl|       1030 BereqHeader     b Host: 127.0.0.1
**** v1    vsl|       1030 BereqHeader     b User-Agent: c4
**** v1    vsl|       1030 BereqHeader     b X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1030 BereqHeader     b Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1030 BereqHeader     b Accept-Encoding: gzip
**** v1    vsl|       1030 BereqHeader     b X-Varnish: 1030
**** v1    vsl|       1030 VCL_call        b BACKEND_FETCH
**** v1    vsl|       1030 VCL_return      b fetch
**** v1    vsl|       1030 Timestamp       b Fetch: 1742615719.372221 0.000019 0.000019
**** v1    vsl|       1030 Timestamp       b Connected: 1742615719.372228 0.000025 0.000006
**** v1    vsl|       1030 BackendOpen     b 25 s1 127.0.0.1 45387 127.0.0.1 41568 reuse
**** v1    vsl|       1030 Timestamp       b Bereq: 1742615719.372267 0.000065 0.000039
**** v1    vsl|       1030 BerespProtocol  b HTTP/1.1
**** v1    vsl|       1030 BerespStatus    b 200
**** v1    vsl|       1030 BerespReason    b OK
**** v1    vsl|       1030 BerespHeader    b Date: Sat, 22 Mar 2025 03:55:19 GMT
**** v1    vsl|       1030 BerespHeader    b Server: s1
**** v1    vsl|       1030 BerespHeader    b Content-Length: 0
**** v1    vsl|       1030 Timestamp       b Beresp: 1742615719.372880 0.000678 0.000613
**** v1    vsl|       1030 TTL             b RFC 120 10 0 1742615719 1742615719 1742615719 0 0 cacheable
**** v1    vsl|       1030 VCL_call        b BACKEND_RESPONSE
**** v1    vsl|       1030 VCL_return      b deliver
**** v1    vsl|       1030 Debug           b Missing content-range header or unknown range unit
**** v1    vsl|       1030 Timestamp       b Process: 1742615719.372907 0.000705 0.000026
**** v1    vsl|       1030 Filters         b 
**** v1    vsl|       1030 Storage         b malloc s0
**** v1    vsl|       1030 Fetch_Body      b 0 none -
**** v1    vsl|       1030 BackendClose    b 25 s1 recycle
**** v1    vsl|       1030 Timestamp       b BerespBody: 1742615719.383064 0.010862 0.010157
**** v1    vsl|       1030 Length          b 0
**** v1    vsl|       1030 BereqAcct       b 157 0 157 87 0 87
**** v1    vsl|       1030 End             b 
**** v1    vsl|       1029 Begin           c req 1026 rxreq
**** v1    vsl|       1029 Timestamp       c Start: 1742615719.372125 0.000000 0.000000
**** v1    vsl|       1029 Timestamp       c Req: 1742615719.372125 0.000000 0.000000
**** v1    vsl|       1029 VCL_use         c vcl6
**** v1    vsl|       1029 ReqStart        c 127.0.0.1 40780 a0
**** v1    vsl|       1029 ReqMethod       c GET
**** v1    vsl|       1029 ReqURL          c /6/veto/ok
**** v1    vsl|       1029 ReqProtocol     c HTTP/1.1
**** v1    vsl|       1029 ReqHeader       c test: 1
**** v1    vsl|       1029 ReqHeader       c Host: 127.0.0.1
**** v1    vsl|       1029 ReqHeader       c User-Agent: c4
**** v1    vsl|       1029 ReqHeader       c X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1029 ReqHeader       c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1029 VCL_call        c RECV
**** v1    vsl|       1029 ReqUnset        c test: 1
**** v1    vsl|       1029 VCL_return      c hash
**** v1    vsl|       1029 VCL_call        c HASH
**** v1    vsl|       1029 VCL_return      c lookup
**** v1    vsl|       1029 VCL_call        c MISS
**** v1    vsl|       1029 VCL_return      c fetch
**** v1    vsl|       1029 Link            c bereq 1030 fetch
**** v1    vsl|       1029 Timestamp       c Fetch: 1742615719.383133 0.011008 0.011008
**** v1    vsl|       1029 RespProtocol    c HTTP/1.1
**** v1    vsl|       1029 RespStatus      c 200
**** v1    vsl|       1029 RespReason      c OK
**** v1    vsl|       1029 RespHeader      c Date: Sat, 22 Mar 2025 03:55:19 GMT
**** v1    vsl|       1029 RespHeader      c Server: s1
**** v1    vsl|       1029 RespHeader      c Content-Length: 0
**** v1    vsl|       1029 RespHeader      c X-Varnish: 1029
**** v1    vsl|       1029 RespHeader      c Age: 0
**** v1    vsl|       1029 RespHeader      c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1029 RespHeader      c Accept-Ranges: bytes
**** v1    vsl|       1029 VCL_call        c DELIVER
**** v1    vsl|       1029 RespProtocol    c HTTP/1.1
**** v1    vsl|       1029 RespStatus      c 200
**** v1    vsl|       1029 RespReason      c OK
**** v1    vsl|       1029 RespHeader      c Date: Sat, 22 Mar 2025 03:55:19 GMT
**** v1    vsl|       1029 RespHeader      c Server: s1
**** v1    vsl|       1029 RespHeader      c Content-Length: 0
**** v1    vsl|       1029 RespHeader      c X-Varnish: 1029
**** v1    vsl|       1029 RespHeader      c Age: 0
**** v1    vsl|       1029 RespHeader      c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1029 RespHeader      c test: 1
**** v1    vsl|       1029 VCL_return      c deliver
**** v1    vsl|       1029 Timestamp       c Process: 1742615719.383206 0.011080 0.000072
**** v1    vsl|       1029 Filters         c 
**** v1    vsl|       1029 RespHeader      c Connection: keep-alive
**** v1    vsl|       1029 Timestamp       c Resp: 1742615719.383268 0.011142 0.000061
**** v1    vsl|       1029 ReqAcct         c 69 0 69 174 0 174
**** v1    vsl|       1029 End             c 
**** v1    vsl|       1026 Link            c req 1031 rxreq
**** dT    5.487
**** c4    rxhdr|HTTP/1.1 503 VCL failed\r
**** c4    rxhdr|Date: Sat, 22 Mar 2025 03:55:19 GMT\r
**** c4    rxhdr|Server: Varnish\r
**** c4    rxhdr|X-Varnish: 1031\r
**** c4    rxhdr|Content-Type: text/html; charset=utf-8\r
**** c4    rxhdr|Retry-After: 5\r
**** c4    rxhdr|Content-Length: 251\r
**** c4    rxhdr|Connection: close\r
**** c4    rxhdr|\r
**** c4    rxhdrlen = 194
**** c4    http[ 0] |HTTP/1.1
**** c4    http[ 1] |503
**** c4    http[ 2] |VCL failed
**** c4    http[ 3] |Date: Sat, 22 Mar 2025 03:55:19 GMT
**** c4    http[ 4] |Server: Varnish
**** c4    http[ 5] |X-Varnish: 1031
**** c4    http[ 6] |Content-Type: text/html; charset=utf-8
**** c4    http[ 7] |Retry-After: 5
**** c4    http[ 8] |Content-Length: 251
**** c4    http[ 9] |Connection: close
**** c4    c-l|<!DOCTYPE html>
**** c4    c-l|<html>
**** c4    c-l|  <head>
**** c4    c-l|    <title>503 VCL failed</title>
**** c4    c-l|  </head>
**** c4    c-l|  <body>
**** c4    c-l|    <h1>Error 503 VCL failed</h1>
**** c4    c-l|    <p>VCL failed</p>
**** c4    c-l|    <h3>Guru Meditation:</h3>
**** c4    c-l|    <p>XID: 1031</p>
**** c4    c-l|    <hr>
**** c4    c-l|    <p>Varnish cache server</p>
**** c4    c-l|  </body>
**** c4    c-l|</html>
**** c4    bodylen = 251
**   c4    === expect resp.status == 503
**** c4    EXPECT resp.status (503) == "503" match
***  c4    closing fd 17
**   c4    Ending
**   top   === server s1 -wait
**   s1    Waiting for server (4/-1)
**   top   === varnish v1 -vcl+backend {
**** v1    CLI TX|vcl.inline vcl7 << %XJEIFLH|)Xspa8P
**** v1    CLI TX|vcl 4.1;
**** v1    CLI TX|backend s1 { .host = "127.0.0.1"; .port = "45387"; }
**** 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\tvtc.workspace_alloc(client, 1000);
**** v1    CLI TX|\t}
**** v1    CLI TX|
**** v1    CLI TX|\tsub vcl_deliver {
**** v1    CLI TX|\t\tif (req.restarts == 0) {
**** v1    CLI TX|\t\t\tvtc.workspace_alloc(client, -10);
**** v1    CLI TX|\t\t\tstd.rollback(req);
**** 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    5.587
**** v1    vsl|       1032 Begin           b bereq 1031 fetch
**** v1    vsl|       1032 VCL_use         b vcl6
**** v1    vsl|       1032 Timestamp       b Start: 1742615719.384012 0.000000 0.000000
**** v1    vsl|       1032 BereqMethod     b GET
**** v1    vsl|       1032 BereqURL        b /6/veto
**** v1    vsl|       1032 BereqProtocol   b HTTP/1.1
**** v1    vsl|       1032 BereqHeader     b Host: 127.0.0.1
**** v1    vsl|       1032 BereqHeader     b User-Agent: c4
**** v1    vsl|       1032 BereqHeader     b X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1032 BereqHeader     b Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1032 BereqHeader     b Accept-Encoding: gzip
**** v1    vsl|       1032 BereqHeader     b X-Varnish: 1032
**** v1    vsl|       1032 VCL_call        b BACKEND_FETCH
**** v1    vsl|       1032 VCL_return      b fetch
**** v1    vsl|       1032 Timestamp       b Fetch: 1742615719.384032 0.000020 0.000020
**** v1    vsl|       1032 Timestamp       b Connected: 1742615719.384039 0.000026 0.000006
**** v1    vsl|       1032 BackendOpen     b 25 s1 127.0.0.1 45387 127.0.0.1 41568 reuse
**** v1    vsl|       1032 Timestamp       b Bereq: 1742615719.384083 0.000070 0.000043
**** v1    vsl|       1032 BerespProtocol  b HTTP/1.1
**** v1    vsl|       1032 BerespStatus    b 200
**** v1    vsl|       1032 BerespReason    b OK
**** v1    vsl|       1032 BerespHeader    b Date: Sat, 22 Mar 2025 03:55:19 GMT
**** v1    vsl|       1032 BerespHeader    b Server: s1
**** v1    vsl|       1032 BerespHeader    b Content-Length: 0
**** v1    vsl|       1032 Timestamp       b Beresp: 1742615719.384849 0.000836 0.000766
**** v1    vsl|       1032 TTL             b RFC 120 10 0 1742615719 1742615719 1742615719 0 0 cacheable
**** v1    vsl|       1032 VCL_call        b BACKEND_RESPONSE
**** v1    vsl|       1032 VCL_return      b deliver
**** v1    vsl|       1032 Debug           b Missing content-range header or unknown range unit
**** v1    vsl|       1032 Timestamp       b Process: 1742615719.384878 0.000865 0.000029
**** v1    vsl|       1032 Filters         b 
**** v1    vsl|       1032 Storage         b malloc s0
**** v1    vsl|       1032 Fetch_Body      b 0 none -
**** v1    vsl|       1032 BackendClose    b 25 s1 recycle
**** v1    vsl|       1032 Timestamp       b BerespBody: 1742615719.395047 0.011034 0.010168
**** v1    vsl|       1032 Length          b 0
**** v1    vsl|       1032 BereqAcct       b 154 0 154 87 0 87
**** v1    vsl|       1032 End             b 
**** v1    vsl|       1031 Begin           c req 1026 rxreq
**** v1    vsl|       1031 Timestamp       c Start: 1742615719.383931 0.000000 0.000000
**** v1    vsl|       1031 Timestamp       c Req: 1742615719.383931 0.000000 0.000000
**** v1    vsl|       1031 VCL_use         c vcl6
**** v1    vsl|       1031 ReqStart        c 127.0.0.1 40780 a0
**** v1    vsl|       1031 ReqMethod       c GET
**** v1    vsl|       1031 ReqURL          c /6/veto
**** v1    vsl|       1031 ReqProtocol     c HTTP/1.1
**** v1    vsl|       1031 ReqHeader       c test: 1
**** v1    vsl|       1031 ReqHeader       c Host: 127.0.0.1
**** dT    5.588
**** v1    vsl|       1031 ReqHeader       c User-Agent: c4
**** v1    vsl|       1031 ReqHeader       c X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1031 ReqHeader       c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1031 VCL_call        c RECV
**** v1    vsl|       1031 ReqUnset        c test: 1
**** v1    vsl|       1031 VCL_return      c hash
**** v1    vsl|       1031 VCL_call        c HASH
**** v1    vsl|       1031 VCL_return      c lookup
**** v1    vsl|       1031 VCL_call        c MISS
**** v1    vsl|       1031 VCL_return      c fetch
**** v1    vsl|       1031 Link            c bereq 1032 fetch
**** v1    vsl|       1031 Timestamp       c Fetch: 1742615719.395108 0.011176 0.011176
**** v1    vsl|       1031 RespProtocol    c HTTP/1.1
**** v1    vsl|       1031 RespStatus      c 200
**** v1    vsl|       1031 RespReason      c OK
**** v1    vsl|       1031 RespHeader      c Date: Sat, 22 Mar 2025 03:55:19 GMT
**** v1    vsl|       1031 RespHeader      c Server: s1
**** v1    vsl|       1031 RespHeader      c Content-Length: 0
**** v1    vsl|       1031 RespHeader      c X-Varnish: 1031
**** v1    vsl|       1031 RespHeader      c Age: 0
**** v1    vsl|       1031 RespHeader      c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1031 RespHeader      c Accept-Ranges: bytes
**** v1    vsl|       1031 VCL_call        c DELIVER
**** v1    vsl|       1031 VCL_Error       c thou shalt not fini
**** v1    vsl|       1031 RespProtocol    c HTTP/1.1
**** v1    vsl|       1031 RespStatus      c 200
**** v1    vsl|       1031 RespReason      c OK
**** v1    vsl|       1031 RespHeader      c Date: Sat, 22 Mar 2025 03:55:19 GMT
**** v1    vsl|       1031 RespHeader      c Server: s1
**** v1    vsl|       1031 RespHeader      c Content-Length: 0
**** v1    vsl|       1031 RespHeader      c X-Varnish: 1031
**** v1    vsl|       1031 RespHeader      c Age: 0
**** v1    vsl|       1031 RespHeader      c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1031 VCL_return      c fail
**** v1    vsl|       1031 RespProtocol    c HTTP/1.1
**** v1    vsl|       1031 RespStatus      c 503
**** v1    vsl|       1031 RespReason      c VCL failed
**** v1    vsl|       1031 RespHeader      c Date: Sat, 22 Mar 2025 03:55:19 GMT
**** v1    vsl|       1031 RespHeader      c Server: Varnish
**** v1    vsl|       1031 RespHeader      c X-Varnish: 1031
**** v1    vsl|       1031 VCL_call        c SYNTH
**** v1    vsl|       1031 RespHeader      c Content-Type: text/html; charset=utf-8
**** v1    vsl|       1031 RespHeader      c Retry-After: 5
**** v1    vsl|       1031 VCL_return      c deliver
**** v1    vsl|       1031 Timestamp       c Process: 1742615719.395370 0.011439 0.000262
**** v1    vsl|       1031 RespHeader      c Content-Length: 251
**** v1    vsl|       1031 Storage         c malloc Transient
**** v1    vsl|       1031 Filters         c 
**** v1    vsl|       1031 RespHeader      c Connection: close
**** v1    vsl|       1031 Timestamp       c Resp: 1742615719.395444 0.011512 0.000073
**** v1    vsl|       1031 ReqAcct         c 66 0 66 194 251 445
**** v1    vsl|       1031 End             c 
**** v1    vsl|       1026 SessClose       c VCL_FAILURE 0.035
**** v1    vsl|       1026 End             c 
**** dT    5.928
***  v1    CLI RX  200
**** v1    CLI TX|vcl.use vcl7
***  v1    CLI RX  200
**** v1    CLI RX|VCL 'vcl7' now active
**   top   === server s1 -repeat 1 {
**   s1    Starting server
**** s1    macro def s1_addr=127.0.0.1
**** s1    macro def s1_port=45387
**** s1    macro def s1_sock=127.0.0.1:45387
*    s1    Listen on 127.0.0.1:45387
**   top   === client c5 {
**   c5    Starting client
**   c5    Waiting for client
**   s1    Started on 127.0.0.1:45387 (1 iterations using keepalive)
**   c5    Started on 127.0.0.1:43823 (1 iterations)
***  c5    Connect to 127.0.0.1:43823
***  c5    connected fd 17 from 127.0.0.1 40786 to 127.0.0.1:43823
**   c5    === txreq -url /7
**** c5    txreq|GET /7 HTTP/1.1\r
**** c5    txreq|Host: 127.0.0.1\r
**** c5    txreq|User-Agent: c5\r
**** c5    txreq|\r
**   c5    === rxresp
**** dT    5.929
***  s1    accepted fd 5 127.0.0.1 41574
**   s1    === rxreq
**** s1    rxhdr|GET /7 HTTP/1.1\r
**** s1    rxhdr|Host: 127.0.0.1\r
**** s1    rxhdr|User-Agent: c5\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: 1035\r
**** s1    rxhdr|\r
**** s1    rxhdrlen = 149
**** s1    http[ 0] |GET
**** s1    http[ 1] |/7
**** s1    http[ 2] |HTTP/1.1
**** s1    http[ 3] |Host: 127.0.0.1
**** s1    http[ 4] |User-Agent: c5
**** 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: 1035
**** s1    bodylen = 0
**   s1    === expect req.url == "/7"
**** s1    EXPECT req.url (/7) == "/7" match
**   s1    === txresp
**** s1    txresp|HTTP/1.1 200 OK\r
**** s1    txresp|Date: Sat, 22 Mar 2025 03:55:19 GMT\r
**** s1    txresp|Server: s1\r
**** s1    txresp|Content-Length: 0\r
**** s1    txresp|\r
***  s1    shutting fd 5
**   s1    Ending
**** dT    5.940
**** c5    rxhdr|HTTP/1.1 200 OK\r
**** c5    rxhdr|Date: Sat, 22 Mar 2025 03:55:19 GMT\r
**** c5    rxhdr|Server: s1\r
**** c5    rxhdr|Content-Length: 0\r
**** c5    rxhdr|X-Varnish: 1036 1035\r
**** c5    rxhdr|Age: 0\r
**** c5    rxhdr|Via: 1.1 v1 (Varnish/trunk)\r
**** c5    rxhdr|Accept-Ranges: bytes\r
**** c5    rxhdr|Connection: keep-alive\r
**** c5    rxhdr|\r
**** c5    rxhdrlen = 192
**** c5    http[ 0] |HTTP/1.1
**** c5    http[ 1] |200
**** c5    http[ 2] |OK
**** c5    http[ 3] |Date: Sat, 22 Mar 2025 03:55:19 GMT
**** c5    http[ 4] |Server: s1
**** c5    http[ 5] |Content-Length: 0
**** c5    http[ 6] |X-Varnish: 1036 1035
**** c5    http[ 7] |Age: 0
**** c5    http[ 8] |Via: 1.1 v1 (Varnish/trunk)
**** c5    http[ 9] |Accept-Ranges: bytes
**** c5    http[10] |Connection: keep-alive
**** c5    bodylen = 0
**   c5    === expect resp.status == 200
**** c5    EXPECT resp.status (200) == "200" match
***  c5    closing fd 17
**   c5    Ending
**   top   === server s1 -wait
**   s1    Waiting for server (4/-1)
**   top   === varnish v1 -vcl+backend {
**** dT    5.941
**** v1    CLI TX|vcl.inline vcl8 << %XJEIFLH|)Xspa8P
**** v1    CLI TX|vcl 4.1;
**** v1    CLI TX|backend s1 { .host = "127.0.0.1"; .port = "45387"; }
**** 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.fetch = "Fetch value " + req.restarts;
**** v1    CLI TX|\t}
**** v1    CLI TX|
**** v1    CLI TX|\tsub vcl_deliver {
**** v1    CLI TX|\t\tif (req.restarts == 0) {
**** v1    CLI TX|\t\t\tstd.rollback(req);
**** v1    CLI TX|\t\t\tset req.http.response = "123";
**** v1    CLI TX|\t\t\tset req.http.response2 = "Another response";
**** v1    CLI TX|\t\t\tif (req.url == "/9") {
**** v1    CLI TX|\t\t\t\tvtc.workspace_alloc(client, -200);
**** v1    CLI TX|\t\t\t} else if (req.url == "/10") {
**** v1    CLI TX|\t\t\t\tvtc.workspace_alloc(client, -10);
**** v1    CLI TX|\t\t\t\tstd.rollback(req);
**** v1    CLI TX|\t\t\t}
**** v1    CLI TX|\t\t\treturn (restart);
**** v1    CLI TX|\t\t}
**** v1    CLI TX|\t\tset resp.http.fetch = req.http.fetch;
**** v1    CLI TX|\t\tset resp.http.response = req.http.response;
**** v1    CLI TX|\t\tset resp.http.response2 = req.http.response2;
**** v1    CLI TX|\t}
**** v1    CLI TX|
**** v1    CLI TX|%XJEIFLH|)Xspa8P
**** dT    6.005
**** v1    vsl|          0 CLI             - Rd vcl.load vcl7 vcl_vcl7.1742615719.396395/vgc.so 1auto
**** v1    vsl|          0 CLI             - Wr 200 52 Loaded "vcl_vcl7.1742615719.396395/vgc.so" as "vcl7"
**** v1    vsl|          0 CLI             - Rd vcl.use vcl7
**** v1    vsl|          0 CLI             - Wr 200 0 
**** v1    vsl|       1033 Begin           c sess 0 HTTP/1
**** v1    vsl|       1033 SessOpen        c 127.0.0.1 40786 a0 127.0.0.1 43823 1742615719.837765 21
**** v1    vsl|       1033 Debug           c sockopt: SO_LINGER may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1033 Debug           c sockopt: SO_KEEPALIVE may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1033 Debug           c sockopt: SO_SNDTIMEO may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1033 Debug           c sockopt: SO_RCVTIMEO may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1033 Debug           c sockopt: TCP_NODELAY may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1033 Debug           c sockopt: TCP_KEEPIDLE may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1033 Debug           c sockopt: TCP_KEEPCNT may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1033 Debug           c sockopt: TCP_KEEPINTVL may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1033 Link            c req 1034 rxreq
**** v1    vsl|       1035 Begin           b bereq 1034 fetch
**** v1    vsl|       1035 VCL_use         b vcl7
**** v1    vsl|       1035 Timestamp       b Start: 1742615719.837914 0.000000 0.000000
**** v1    vsl|       1035 BereqMethod     b GET
**** v1    vsl|       1035 BereqURL        b /7
**** v1    vsl|       1035 BereqProtocol   b HTTP/1.1
**** v1    vsl|       1035 BereqHeader     b Host: 127.0.0.1
**** v1    vsl|       1035 BereqHeader     b User-Agent: c5
**** v1    vsl|       1035 BereqHeader     b X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1035 BereqHeader     b Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1035 BereqHeader     b Accept-Encoding: gzip
**** v1    vsl|       1035 BereqHeader     b X-Varnish: 1035
**** v1    vsl|       1035 VCL_call        b BACKEND_FETCH
**** v1    vsl|       1035 VCL_return      b fetch
**** v1    vsl|       1035 Timestamp       b Fetch: 1742615719.837938 0.000024 0.000024
**** v1    vsl|       1035 Timestamp       b Connected: 1742615719.837995 0.000080 0.000056
**** v1    vsl|       1035 BackendOpen     b 25 s1 127.0.0.1 45387 127.0.0.1 41574 connect
**** v1    vsl|       1035 Timestamp       b Bereq: 1742615719.838038 0.000124 0.000043
**** v1    vsl|       1035 BerespProtocol  b HTTP/1.1
**** v1    vsl|       1035 BerespStatus    b 200
**** v1    vsl|       1035 BerespReason    b OK
**** v1    vsl|       1035 BerespHeader    b Date: Sat, 22 Mar 2025 03:55:19 GMT
**** v1    vsl|       1035 BerespHeader    b Server: s1
**** v1    vsl|       1035 BerespHeader    b Content-Length: 0
**** v1    vsl|       1035 Timestamp       b Beresp: 1742615719.838631 0.000716 0.000592
**** v1    vsl|       1035 TTL             b RFC 120 10 0 1742615720 1742615720 1742615719 0 0 cacheable
**** v1    vsl|       1035 VCL_call        b BACKEND_RESPONSE
**** v1    vsl|       1035 VCL_return      b deliver
**** v1    vsl|       1035 Debug           b Missing content-range header or unknown range unit
**** v1    vsl|       1035 Timestamp       b Process: 1742615719.838659 0.000745 0.000028
**** v1    vsl|       1035 Filters         b 
**** v1    vsl|       1035 Storage         b malloc s0
**** v1    vsl|       1035 Fetch_Body      b 0 none -
**** v1    vsl|       1035 BackendClose    b 25 s1 recycle
**** v1    vsl|       1035 Timestamp       b BerespBody: 1742615719.848823 0.010908 0.010163
**** v1    vsl|       1035 Length          b 0
**** v1    vsl|       1035 BereqAcct       b 149 0 149 87 0 87
**** v1    vsl|       1035 End             b 
**** v1    vsl|       1034 Begin           c req 1033 rxreq
**** v1    vsl|       1034 Timestamp       c Start: 1742615719.837816 0.000000 0.000000
**** v1    vsl|       1034 Timestamp       c Req: 1742615719.837816 0.000000 0.000000
**** v1    vsl|       1034 VCL_use         c vcl7
**** v1    vsl|       1034 ReqStart        c 127.0.0.1 40786 a0
**** v1    vsl|       1034 ReqMethod       c GET
**** v1    vsl|       1034 ReqURL          c /7
**** v1    vsl|       1034 ReqProtocol     c HTTP/1.1
**** v1    vsl|       1034 ReqHeader       c Host: 127.0.0.1
**** v1    vsl|       1034 ReqHeader       c User-Agent: c5
**** v1    vsl|       1034 ReqHeader       c X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1034 ReqHeader       c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1034 VCL_call        c RECV
**** v1    vsl|       1034 VCL_return      c hash
**** v1    vsl|       1034 VCL_call        c HASH
**** v1    vsl|       1034 VCL_return      c lookup
**** v1    vsl|       1034 VCL_call        c MISS
**** v1    vsl|       1034 VCL_return      c fetch
**** v1    vsl|       1034 Link            c bereq 1035 fetch
**** v1    vsl|       1034 Timestamp       c Fetch: 1742615719.848892 0.011075 0.011075
**** v1    vsl|       1034 RespProtocol    c HTTP/1.1
**** v1    vsl|       1034 RespStatus      c 200
**** v1    vsl|       1034 RespReason      c OK
**** v1    vsl|       1034 RespHeader      c Date: Sat, 22 Mar 2025 03:55:19 GMT
**** v1    vsl|       1034 RespHeader      c Server: s1
**** v1    vsl|       1034 RespHeader      c Content-Length: 0
**** v1    vsl|       1034 RespHeader      c X-Varnish: 1034
**** v1    vsl|       1034 RespHeader      c Age: 0
**** v1    vsl|       1034 RespHeader      c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1034 RespHeader      c Accept-Ranges: bytes
**** v1    vsl|       1034 VCL_call        c DELIVER
**** v1    vsl|       1034 RespProtocol    c HTTP/1.1
**** v1    vsl|       1034 RespStatus      c 200
**** v1    vsl|       1034 RespReason      c OK
**** v1    vsl|       1034 RespHeader      c Date: Sat, 22 Mar 2025 03:55:19 GMT
**** v1    vsl|       1034 RespHeader      c Server: s1
**** v1    vsl|       1034 RespHeader      c Content-Length: 0
**** v1    vsl|       1034 RespHeader      c X-Varnish: 1034
**** v1    vsl|       1034 RespHeader      c Age: 0
**** v1    vsl|       1034 RespHeader      c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1034 VCL_return      c restart
**** v1    vsl|       1034 Timestamp       c Restart: 1742615719.848955 0.011139 0.000063
**** v1    vsl|       1034 Link            c req 1036 restart
**** v1    vsl|       1034 End             c 
**** v1    vsl|       1036 Begin           c req 1034 restart
**** v1    vsl|       1036 Timestamp       c Start: 1742615719.848955 0.011139 0.000000
**** v1    vsl|       1036 ReqStart        c 127.0.0.1 40786 a0
**** v1    vsl|       1036 ReqMethod       c GET
**** v1    vsl|       1036 ReqURL          c /7
**** v1    vsl|       1036 ReqProtocol     c HTTP/1.1
**** v1    vsl|       1036 ReqHeader       c Host: 127.0.0.1
**** v1    vsl|       1036 ReqHeader       c User-Agent: c5
**** v1    vsl|       1036 VCL_call        c RECV
**** v1    vsl|       1036 VCL_return      c hash
**** v1    vsl|       1036 VCL_call        c HASH
**** v1    vsl|       1036 VCL_return      c lookup
**** v1    vsl|       1036 Hit             c 1035 120.000814 10.000000 0.000000
**** v1    vsl|       1036 VCL_call        c HIT
**** v1    vsl|       1036 VCL_return      c deliver
**** v1    vsl|       1036 RespProtocol    c HTTP/1.1
**** v1    vsl|       1036 RespStatus      c 200
**** v1    vsl|       1036 RespReason      c OK
**** v1    vsl|       1036 RespHeader      c Date: Sat, 22 Mar 2025 03:55:19 GMT
**** v1    vsl|       1036 RespHeader      c Server: s1
**** v1    vsl|       1036 RespHeader      c Content-Length: 0
**** v1    vsl|       1036 RespHeader      c X-Varnish: 1036 1035
**** v1    vsl|       1036 RespHeader      c Age: 0
**** v1    vsl|       1036 RespHeader      c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1036 RespHeader      c Accept-Ranges: bytes
**** v1    vsl|       1036 VCL_call        c DELIVER
**** v1    vsl|       1036 VCL_return      c deliver
**** v1    vsl|       1036 Timestamp       c Process: 1742615719.849017 0.011201 0.000061
**** v1    vsl|       1036 Filters         c 
**** v1    vsl|       1036 RespHeader      c Connection: keep-alive
**** v1    vsl|       1036 Timestamp       c Resp: 1742615719.849082 0.011265 0.000064
**** v1    vsl|       1036 ReqAcct         c 52 0 52 192 0 192
**** v1    vsl|       1036 End             c 
**** v1    vsl|       1033 SessClose       c REM_CLOSE 0.012
**** v1    vsl|       1033 End             c 
**** dT    6.354
***  v1    CLI RX  200
**** v1    CLI TX|vcl.use vcl8
***  v1    CLI RX  200
**** v1    CLI RX|VCL 'vcl8' now active
**   top   === server s1 -repeat 3 -keepalive {
**   s1    Starting server
**** s1    macro def s1_addr=127.0.0.1
**** s1    macro def s1_port=45387
**** s1    macro def s1_sock=127.0.0.1:45387
*    s1    Listen on 127.0.0.1:45387
**   top   === client c6 {
**   c6    Starting client
**   c6    Waiting for client
**   c6    Started on 127.0.0.1:43823 (1 iterations)
***  c6    Connect to 127.0.0.1:43823
***  c6    connected fd 5 from 127.0.0.1 40790 to 127.0.0.1:43823
**   c6    === txreq -url /8 -hdr "Host: 127.0.0.1"
**** c6    txreq|GET /8 HTTP/1.1\r
**** c6    txreq|Host: 127.0.0.1\r
**** c6    txreq|User-Agent: c6\r
**** c6    txreq|\r
**   c6    === rxresp
**   s1    Started on 127.0.0.1:45387 (3 iterations using keepalive)
**** dT    6.355
***  s1    accepted fd 17 127.0.0.1 41578
**   s1    === rxreq
**** s1    rxhdr|GET /8 HTTP/1.1\r
**** s1    rxhdr|Host: 127.0.0.1\r
**** s1    rxhdr|User-Agent: c6\r
**** s1    rxhdr|X-Forwarded-For: 127.0.0.1\r
**** s1    rxhdr|Via: 1.1 v1 (Varnish/trunk)\r
**** s1    rxhdr|fetch: Fetch value 0\r
**** s1    rxhdr|Accept-Encoding: gzip\r
**** s1    rxhdr|X-Varnish: 1039\r
**** s1    rxhdr|\r
**** s1    rxhdrlen = 171
**** s1    http[ 0] |GET
**** s1    http[ 1] |/8
**** s1    http[ 2] |HTTP/1.1
**** s1    http[ 3] |Host: 127.0.0.1
**** s1    http[ 4] |User-Agent: c6
**** s1    http[ 5] |X-Forwarded-For: 127.0.0.1
**** s1    http[ 6] |Via: 1.1 v1 (Varnish/trunk)
**** s1    http[ 7] |fetch: Fetch value 0
**** s1    http[ 8] |Accept-Encoding: gzip
**** s1    http[ 9] |X-Varnish: 1039
**** s1    bodylen = 0
**   s1    === txresp
**** s1    txresp|HTTP/1.1 200 OK\r
**** s1    txresp|Date: Sat, 22 Mar 2025 03:55:20 GMT\r
**** s1    txresp|Server: s1\r
**** s1    txresp|Content-Length: 0\r
**** s1    txresp|\r
**   s1    === rxreq
**** dT    6.376
**** c6    rxhdr|HTTP/1.1 200 OK\r
**** c6    rxhdr|Date: Sat, 22 Mar 2025 03:55:20 GMT\r
**** c6    rxhdr|Server: s1\r
**** c6    rxhdr|Content-Length: 0\r
**** c6    rxhdr|X-Varnish: 1040 1039\r
**** c6    rxhdr|Age: 0\r
**** c6    rxhdr|Via: 1.1 v1 (Varnish/trunk)\r
**** c6    rxhdr|Accept-Ranges: bytes\r
**** c6    rxhdr|fetch: Fetch value 1\r
**** c6    rxhdr|response: 123\r
**** c6    rxhdr|response2: Another response\r
**** c6    rxhdr|Connection: keep-alive\r
**** c6    rxhdr|\r
**** c6    rxhdrlen = 258
**** c6    http[ 0] |HTTP/1.1
**** c6    http[ 1] |200
**** c6    http[ 2] |OK
**** c6    http[ 3] |Date: Sat, 22 Mar 2025 03:55:20 GMT
**** c6    http[ 4] |Server: s1
**** c6    http[ 5] |Content-Length: 0
**** c6    http[ 6] |X-Varnish: 1040 1039
**** c6    http[ 7] |Age: 0
**** c6    http[ 8] |Via: 1.1 v1 (Varnish/trunk)
**** c6    http[ 9] |Accept-Ranges: bytes
**** c6    http[10] |fetch: Fetch value 1
**** c6    http[11] |response: 123
**** c6    http[12] |response2: Another response
**** c6    http[13] |Connection: keep-alive
**** c6    bodylen = 0
**   c6    === expect resp.status == 200
**** c6    EXPECT resp.status (200) == "200" match
**   c6    === expect resp.http.fetch == "Fetch value 1"
**** c6    EXPECT resp.http.fetch (Fetch value 1) == "Fetch value 1" match
**   c6    === expect resp.http.response == "123"
**** c6    EXPECT resp.http.response (123) == "123" match
**   c6    === expect resp.http.response2 == "Another response"
**** c6    EXPECT resp.http.response2 (Another response) == "Another response" match
**   c6    === txreq -url /9 -hdr "Host: 127.0.0.1"
**** c6    txreq|GET /9 HTTP/1.1\r
**** c6    txreq|Host: 127.0.0.1\r
**** c6    txreq|User-Agent: c6\r
**** c6    txreq|\r
**   c6    === rxresp
**** dT    6.379
**** s1    rxhdrlen = 0
---- s1    HTTP header is incomplete
**** c6    rxhdrlen = 0
---- c6    HTTP header is incomplete
**** dT    6.380
*    top   RESETTING after ../../../vmod/tests/std_c00001.vtc
**   s1    Waiting for server (4/-1)
**   v1    Wait
**** v1    CLI TX|panic.show
***  v1    debug|Error: Child (3642) died signal=6
***  v1    debug|Error: Child (3642) Panic at: Sat, 22 Mar 2025 03:55:20 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 75aa1a8056c56d9b2c7350bf4e2be44b0a4115e4, vrt api = 21.0
***  v1    debug|ident = Linux,4.19.0-17-amd64,x86_64,-jnone,-sdefault,-sdefault,-hcritbit,epoll
***  v1    debug|now = 8599418.385369 (mono), 1742615720.285172 (real)
***  v1    debug|Backtrace:
***  v1    debug|  0x548630: varnishd() [0x548630]
***  v1    debug|  0x5485f5: varnishd(VBT_format+0x35) [0x5485f5]
***  v1    debug|  0x46de19: varnishd() [0x46de19]
***  v1    debug|  0x46daf2: varnishd() [0x46daf2]
***  v1    debug|  0x5477d5: varnishd(VAS_Fail+0x55) [0x5477d5]
***  v1    debug|  0x43673e: varnishd(VBO_GetBusyObj+0x3fe) [0x43673e]
***  v1    debug|  0x44c92e: varnishd(VBF_Fetch+0x29e) [0x44c92e]
***  v1    debug|  0x47ce79: varnishd() [0x47ce79]
***  v1    debug|  0x4784d4: varnishd(CNT_Request+0x814) [0x4784d4]
***  v1    debug|  0x4c61b0: varnishd() [0x4c61b0]
***  v1    debug|  0x4c5440: varnishd() [0x4c5440]
***  v1    debug|  0x4b45e4: varnishd() [0x4b45e4]
***  v1    debug|  0x4b3be3: varnishd() [0x4b3be3]
***  v1    debug|  0x4b380b: varnishd() [0x4b380b]
***  v1    debug|  0x7fa97e5f8fa3: /lib/x86_64-linux-gnu/libpthread.so.0(+0x7fa3) [0x7fa97e5f8fa3]
***  v1    debug|  0x7fa97e5274cf: /lib/x86_64-linux-gnu/libc.so.6(clone+0x3f) [0x7fa97e5274cf]
***  v1    debug|argv = {
***  v1    debug|  [0] = \"varnishd\",
***  v1    debug|  [1] = \"-d\",
***  v1    debug|  [2] = \"-n\",
***  v1    debug|  [3] = \"/tmp/vtest.clang/_vtest_tmp/vtc.3514.4d3117de/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 44123\",
***  v1    debug|  [26] = \"-P\",
***  v1    debug|  [27] = \"/tmp/vtest.clang/_vtest_tmp/vtc.3514.4d3117de/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 = 0x7fa97bf7e700
***  v1    debug|pthread.name = (cache-worker)
***  v1    debug|pthread.attr = {
***  v1    debug|  guard = 4096,
***  v1    debug|  stack_bottom = 0x7fa97bf6b000,
***  v1    debug|  stack_top = 0x7fa97bf7f000,
***  v1    debug|  stack_size = 81920,
***  v1    debug|}
***  v1    debug|thr.req = 0x7fa973318fe0 {
***  v1    debug|  vxid = 1041, 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 = 0x7fa97de1e9f0 {
***  v1    debug|    cli_name = vcl8.s1,
***  v1    debug|    admin_health = healthy, changed = 1742615720.262794,
***  v1    debug|    type = backend {
***  v1    debug|      conn_pool = 0x7fa97de473c0 {
***  v1    debug|        ident = 0x39ceb84fe8fc84d9e02ee51fb38b30d0e2631eb785913e719afffb36b977c214,
***  v1    debug|        vrt_endpoint = 0x7fa97de24620 {
***  v1    debug|          ipv4 = 127.0.0.1, port = 45387,
***  v1    debug|        },
***  v1    debug|      },
***  v1    debug|      hosthdr = 127.0.0.1,
***  v1    debug|      n_conn = 0,
***  v1    debug|    },
***  v1    debug|  },
***  v1    debug|  sess = 0x7fa97ac07b20 {
***  v1    debug|    fd = 24, vxid = 1037,
***  v1    debug|    t_open = 1742615720.263542,
***  v1    debug|    t_idle = 1742615720.283943,
***  v1    debug|    ws = 0x7fa97ac07b60 {
***  v1    debug|      id = \"ses\",
***  v1    debug|      {s, f, r, e} = {0x7fa97ac07bb8, +96, (nil), +576},
***  v1    debug|    },
***  v1    debug|    transport = HTTP/1 {
***  v1    debug|      state = HTTP1::Proc
***  v1    debug|    }
***  v1    debug|    client = 127.0.0.1 40790 127.0.0.1:43823,
***  v1    debug|    local.endpoint = 127.0.0.1:43823,
***  v1    debug|    local.socket = a0,
***  v1    debug|    local.ip = 127.0.0.1:43823,
***  v1    debug|    remote.ip = 127.0.0.1:40790,
***  v1    debug|    server.ip = 127.0.0.1:43823,
***  v1    debug|    client.ip = 127.0.0.1:40790,
***  v1    debug|  },
***  v1    debug|  worker = 0x7fa97bf7c5e0 {
***  v1    debug|    ws = 0x7fa97bf7c660 {
***  v1    debug|      id = \"wrk\",
***  v1    debug|      {s, f, r, e} = {0x7fa97bf7bb60, +0, (nil), +2040},
***  v1    debug|    },
***  v1    debug|    VCL::method = MISS,
***  v1    debug|    VCL::methods = {RECV, HASH, MISS},
***  v1    debug|  },
***  v1    debug|  ws = 0x7fa973319138 {
***  v1    debug|    id = \"req\",
***  v1    debug|    {s, f, r, e} = {0x7fa97331e070, +152, (nil), +77640},
***  v1    debug|  },
***  v1    debug|  http_conn = 0x7fa97331dfe8 {
***  v1    debug|    fd = 24 (@0x7fa97ac07b44),
***  v1    debug|    doclose = null(Not Closing)
***  v1    debug|    ws = 0x7fa973319138 {
***  v1    debug|      [Already dumped, see above]
***  v1    debug|    },
***  v1    debug|    {rxbuf_b, rxbuf_e} = {0x7fa97331e070, 0x7fa97331e0a4},
***  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] = 0x7fa9733191e8 {
***  v1    debug|    ws = 0x7fa973319138 {
***  v1    debug|      [Already dumped, see above]
***  v1    debug|    },
***  v1    debug|    hdrs {
***  v1    debug|      \"GET\",
***  v1    debug|      \"/9\",
***  v1    debug|      \"HTTP/1.1\",
***  v1    debug|      \"Host: 127.0.0.1\",
***  v1    debug|      \"User-Agent: c6\",
***  v1    debug|      \"X-Forwarded-For: 127.0.0.1\",
***  v1    debug|      \"Via: 1.1 v1 (Varnish/trunk)\",
***  v1    debug|      \"fetch: Fetch value 0\",
***  v1    debug|    },
***  v1    debug|  },
***  v1    debug|  vdc = 0x7fa97331df98 {
***  v1    debug|    nxt = (nil),
***  v1    debug|    retval = 0,
***  v1    debug|  },
***  v1    debug|  vcl[vcl] = 0x7fa97de2fc00 {
***  v1    debug|    name = \"vcl8\",
***  v1    debug|    busy = 1,
***  v1    debug|    discard = 0,
***  v1    debug|    state = auto,
***  v1    debug|    temp = warm,
***  v1    debug|    conf = 0x7fa97b1a8b70 {
***  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 = 0x7fa97bf7bb50 {
***  v1    debug|    handling (VCL::return) = 0x5 (fetch),
***  v1    debug|    ref = 41,
***  v1    debug|    vpi_ref = 0x7fa97b1a9940 {
***  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] = 0x7fa974021480 {
***  v1    debug|    refcnt = 1,
***  v1    debug|    flags = {busy},
***  v1    debug|    exp_flags = {},
***  v1    debug|    boc = 0x7fa974024000 {
***  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 = 0x7fa974024310,
***  v1    debug|    stevedore = (nil),
***  v1    debug|  },
***  v1    debug|  flags = {
***  v1    debug|  },
***  v1    debug|  privs = 0x7fa9733191d8 {
***  v1    debug|  },
***  v1    debug|  top = 0x7fa97331e050 {
***  v1    debug|    req = 0x7fa973318fe0 {
***  v1    debug|      [Already dumped, see above]
***  v1    debug|    },
***  v1    debug|    privs = 0x7fa97331e068 {
***  v1    debug|    },
***  v1    debug|    vcl[vcl0] = NULL
***  v1    debug|  },
***  v1    debug|},
***  v1    debug|thr.busyobj = NULL
***  v1    debug|thr.worker = 0x7fa97bf7c5e0 {
***  v1    debug|  [Already dumped, see above]
***  v1    debug|},
***  v1    debug|vmods = {
***  v1    debug|  std = {p=0x7fa97de2f700, abi=\"Varnish trunk 75aa1a8056c56d9b2c7350bf4e2be44b0a4115e4\", vrt=0.0,
***  v1    debug|    vcs=\"75aa1a8056c56d9b2c7350bf4e2be44b0a4115e4\", version=\"Varnish trunk\"},
***  v1    debug|  vtc = {p=0x7fa97de2f900, abi=\"Varnish trunk 75aa1a8056c56d9b2c7350bf4e2be44b0a4115e4\", vrt=0.0,
***  v1    debug|    vcs=\"75aa1a8056c56d9b2c7350bf4e2be44b0a4115e4\", version=\"Varnish trunk\"},
***  v1    debug|  debug = {p=0x7fa97de2fb00, abi=\"Varnish trunk 75aa1a8056c56d9b2c7350bf4e2be44b0a4115e4\", vrt=21.0,
***  v1    debug|    vcs=\"75aa1a8056c56d9b2c7350bf4e2be44b0a4115e4\", version=\"Varnish trunk\"},
***  v1    debug|},
***  v1    debug|pools = {
***  v1    debug|  pool = 0x7fa97ba00000 {
***  v1    debug|    nidle = 7,
***  v1    debug|    nthr = 10,
***  v1    debug|    lqueue = 0
***  v1    debug|  },
***  v1    debug|  pool = 0x7fa97ba00640 {
***  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    6.418
**** v1    vsl|          0 CLI             - Rd vcl.load vcl8 vcl_vcl8.1742615719.850048/vgc.so 1auto
**** v1    vsl|          0 CLI             - Wr 200 52 Loaded "vcl_vcl8.1742615719.850048/vgc.so" as "vcl8"
**** v1    vsl|          0 CLI             - Rd vcl.use vcl8
**** v1    vsl|          0 CLI             - Wr 200 0 
**** v1    vsl|       1037 Begin           c sess 0 HTTP/1
**** v1    vsl|       1037 SessOpen        c 127.0.0.1 40790 a0 127.0.0.1 43823 1742615720.263542 24
**** v1    vsl|       1037 Debug           c sockopt: SO_LINGER may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1037 Debug           c sockopt: SO_KEEPALIVE may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1037 Debug           c sockopt: SO_SNDTIMEO may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1037 Debug           c sockopt: SO_RCVTIMEO may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1037 Debug           c sockopt: TCP_NODELAY may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1037 Debug           c sockopt: TCP_KEEPIDLE may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1037 Debug           c sockopt: TCP_KEEPCNT may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1037 Debug           c sockopt: TCP_KEEPINTVL may be inherited for a0=127.0.0.1:43823
**** v1    vsl|       1037 Link            c req 1038 rxreq
**** v1    vsl|       1039 Begin           b bereq 1038 fetch
**** v1    vsl|       1039 VCL_use         b vcl8
**** v1    vsl|       1039 Timestamp       b Start: 1742615720.263695 0.000000 0.000000
**** v1    vsl|       1039 BereqMethod     b GET
**** v1    vsl|       1039 BereqURL        b /8
**** v1    vsl|       1039 BereqProtocol   b HTTP/1.1
**** v1    vsl|       1039 BereqHeader     b Host: 127.0.0.1
**** v1    vsl|       1039 BereqHeader     b User-Agent: c6
**** v1    vsl|       1039 BereqHeader     b X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1039 BereqHeader     b Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1039 BereqHeader     b fetch: Fetch value 0
**** v1    vsl|       1039 BereqHeader     b Accept-Encoding: gzip
**** v1    vsl|       1039 BereqHeader     b X-Varnish: 1039
**** v1    vsl|       1039 VCL_call        b BACKEND_FETCH
**** v1    vsl|       1039 VCL_return      b fetch
**** v1    vsl|       1039 Timestamp       b Fetch: 1742615720.263720 0.000025 0.000025
**** v1    vsl|       1039 Timestamp       b Connected: 1742615720.263774 0.000078 0.000053
**** v1    vsl|       1039 BackendOpen     b 25 s1 127.0.0.1 45387 127.0.0.1 41578 connect
**** v1    vsl|       1039 Timestamp       b Bereq: 1742615720.263817 0.000122 0.000043
**** v1    vsl|       1039 BerespProtocol  b HTTP/1.1
**** v1    vsl|       1039 BerespStatus    b 200
**** v1    vsl|       1039 BerespReason    b OK
**** v1    vsl|       1039 BerespHeader    b Date: Sat, 22 Mar 2025 03:55:20 GMT
**** v1    vsl|       1039 BerespHeader    b Server: s1
**** v1    vsl|       1039 BerespHeader    b Content-Length: 0
**** v1    vsl|       1039 Timestamp       b Beresp: 1742615720.264457 0.000762 0.000639
**** v1    vsl|       1039 TTL             b RFC 120 10 0 1742615720 1742615720 1742615720 0 0 cacheable
**** v1    vsl|       1039 VCL_call        b BACKEND_RESPONSE
**** v1    vsl|       1039 VCL_return      b deliver
**** v1    vsl|       1039 Debug           b Missing content-range header or unknown range unit
**** v1    vsl|       1039 Timestamp       b Process: 1742615720.264483 0.000788 0.000026
**** v1    vsl|       1039 Filters         b 
**** v1    vsl|       1039 Storage         b malloc s0
**** v1    vsl|       1039 Fetch_Body      b 0 none -
**** v1    vsl|       1039 BackendClose    b 25 s1 recycle
**** v1    vsl|       1039 Timestamp       b BerespBody: 1742615720.274656 0.010961 0.010172
**** v1    vsl|       1039 Length          b 0
**** v1    vsl|       1039 BereqAcct       b 171 0 171 87 0 87
**** v1    vsl|       1039 End             b 
**** v1    vsl|       1038 Begin           c req 1037 rxreq
**** v1    vsl|       1038 Timestamp       c Start: 1742615720.263593 0.000000 0.000000
**** v1    vsl|       1038 Timestamp       c Req: 1742615720.263593 0.000000 0.000000
**** v1    vsl|       1038 VCL_use         c vcl8
**** v1    vsl|       1038 ReqStart        c 127.0.0.1 40790 a0
**** v1    vsl|       1038 ReqMethod       c GET
**** v1    vsl|       1038 ReqURL          c /8
**** v1    vsl|       1038 ReqProtocol     c HTTP/1.1
**** v1    vsl|       1038 ReqHeader       c Host: 127.0.0.1
**** v1    vsl|       1038 ReqHeader       c User-Agent: c6
**** v1    vsl|       1038 ReqHeader       c X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1038 ReqHeader       c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1038 VCL_call        c RECV
**** v1    vsl|       1038 ReqHeader       c fetch: Fetch value 0
**** v1    vsl|       1038 VCL_return      c hash
**** v1    vsl|       1038 VCL_call        c HASH
**** v1    vsl|       1038 VCL_return      c lookup
**** v1    vsl|       1038 VCL_call        c MISS
**** v1    vsl|       1038 VCL_return      c fetch
**** v1    vsl|       1038 Link            c bereq 1039 fetch
**** v1    vsl|       1038 Timestamp       c Fetch: 1742615720.283724 0.020131 0.020131
**** v1    vsl|       1038 RespProtocol    c HTTP/1.1
**** v1    vsl|       1038 RespStatus      c 200
**** v1    vsl|       1038 RespReason      c OK
**** v1    vsl|       1038 RespHeader      c Date: Sat, 22 Mar 2025 03:55:20 GMT
**** v1    vsl|       1038 RespHeader      c Server: s1
**** v1    vsl|       1038 RespHeader      c Content-Length: 0
**** v1    vsl|       1038 RespHeader      c X-Varnish: 1038
**** v1    vsl|       1038 RespHeader      c Age: 0
**** v1    vsl|       1038 RespHeader      c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1038 RespHeader      c Accept-Ranges: bytes
**** v1    vsl|       1038 VCL_call        c DELIVER
**** v1    vsl|       1038 RespProtocol    c HTTP/1.1
**** v1    vsl|       1038 RespStatus      c 200
**** v1    vsl|       1038 RespReason      c OK
**** v1    vsl|       1038 RespHeader      c Date: Sat, 22 Mar 2025 03:55:20 GMT
**** v1    vsl|       1038 RespHeader      c Server: s1
**** v1    vsl|       1038 RespHeader      c Content-Length: 0
**** v1    vsl|       1038 RespHeader      c X-Varnish: 1038
**** v1    vsl|       1038 RespHeader      c Age: 0
**** v1    vsl|       1038 RespHeader      c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1038 ReqHeader       c response: 123
**** v1    vsl|       1038 ReqHeader       c response2: Another response
**** v1    vsl|       1038 VCL_return      c restart
**** v1    vsl|       1038 Timestamp       c Restart: 1742615720.283798 0.020205 0.000073
**** v1    vsl|       1038 Link            c req 1040 restart
**** v1    vsl|       1038 End             c 
**** v1    vsl|       1040 Begin           c req 1038 restart
**** v1    vsl|       1040 Timestamp       c Start: 1742615720.283798 0.020205 0.000000
**** v1    vsl|       1040 ReqStart        c 127.0.0.1 40790 a0
**** v1    vsl|       1040 ReqMethod       c GET
**** v1    vsl|       1040 ReqURL          c /8
**** v1    vsl|       1040 ReqProtocol     c HTTP/1.1
**** v1    vsl|       1040 ReqHeader       c Host: 127.0.0.1
**** v1    vsl|       1040 ReqHeader       c User-Agent: c6
**** v1    vsl|       1040 ReqHeader       c response: 123
**** v1    vsl|       1040 ReqHeader       c response2: Another response
**** v1    vsl|       1040 VCL_call        c RECV
**** v1    vsl|       1040 ReqHeader       c fetch: Fetch value 1
**** v1    vsl|       1040 VCL_return      c hash
**** v1    vsl|       1040 VCL_call        c HASH
**** v1    vsl|       1040 VCL_return      c lookup
**** v1    vsl|       1040 Hit             c 1039 120.000864 10.000000 0.000000
**** v1    vsl|       1040 VCL_call        c HIT
**** v1    vsl|       1040 VCL_return      c deliver
**** v1    vsl|       1040 RespProtocol    c HTTP/1.1
**** v1    vsl|       1040 RespStatus      c 200
**** v1    vsl|       1040 RespReason      c OK
**** v1    vsl|       1040 RespHeader      c Date: Sat, 22 Mar 2025 03:55:20 GMT
**** v1    vsl|       1040 RespHeader      c Server: s1
**** v1    vsl|       1040 RespHeader      c Content-Length: 0
**** v1    vsl|       1040 RespHeader      c X-Varnish: 1040 1039
**** v1    vsl|       1040 RespHeader      c Age: 0
**** v1    vsl|       1040 RespHeader      c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1040 RespHeader      c Accept-Ranges: bytes
**** v1    vsl|       1040 VCL_call        c DELIVER
**** v1    vsl|       1040 RespHeader      c fetch: Fetch value 1
**** v1    vsl|       1040 RespHeader      c response: 123
**** v1    vsl|       1040 RespHeader      c response2: Another response
**** v1    vsl|       1040 VCL_return      c deliver
**** v1    vsl|       1040 Timestamp       c Process: 1742615720.283875 0.020282 0.000077
**** v1    vsl|       1040 Filters         c 
**** v1    vsl|       1040 RespHeader      c Connection: keep-alive
**** v1    vsl|       1040 Timestamp       c Resp: 1742615720.283943 0.020349 0.000067
**** v1    vsl|       1040 ReqAcct         c 52 0 52 258 0 258
**** v1    vsl|       1040 End             c 
**** v1    vsl|       1037 Link            c req 1041 rxreq
**** v1    vsl|       1041 Begin           c req 1037 rxreq
**** v1    vsl|       1041 Timestamp       c Start: 1742615720.285094 0.000000 0.000000
**** v1    vsl|       1041 Timestamp       c Req: 1742615720.285094 0.000000 0.000000
**** v1    vsl|       1041 VCL_use         c vcl8
**** v1    vsl|       1041 ReqStart        c 127.0.0.1 40790 a0
**** v1    vsl|       1041 ReqMethod       c GET
**** v1    vsl|       1041 ReqURL          c /9
**** v1    vsl|       1041 ReqProtocol     c HTTP/1.1
**** v1    vsl|       1041 ReqHeader       c Host: 127.0.0.1
**** v1    vsl|       1041 ReqHeader       c User-Agent: c6
**** v1    vsl|       1041 ReqHeader       c X-Forwarded-For: 127.0.0.1
**** v1    vsl|       1041 ReqHeader       c Via: 1.1 v1 (Varnish/trunk)
**** v1    vsl|       1041 VCL_call        c RECV
**** v1    vsl|       1041 ReqHeader       c fetch: Fetch value 0
**** v1    vsl|       1041 VCL_return      c hash
**** v1    vsl|       1041 VCL_call        c HASH
**** v1    vsl|       1041 VCL_return      c lookup
**** v1    vsl|       1041 VCL_call        c MISS
**** v1    vsl|       1041 VCL_return      c fetch
**** dT    6.424
***  v1    CLI RX  200
**** v1    CLI RX|Child (3642) Panic at: Sat, 22 Mar 2025 03:55:20 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 75aa1a8056c56d9b2c7350bf4e2be44b0a4115e4, 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 = 8599418.385369 (mono), 1742615720.285172 (real)
**** v1    CLI RX|Backtrace:
**** v1    CLI RX|  0x548630: varnishd() [0x548630]
**** v1    CLI RX|  0x5485f5: varnishd(VBT_format+0x35) [0x5485f5]
**** v1    CLI RX|  0x46de19: varnishd() [0x46de19]
**** v1    CLI RX|  0x46daf2: varnishd() [0x46daf2]
**** v1    CLI RX|  0x5477d5: varnishd(VAS_Fail+0x55) [0x5477d5]
**** v1    CLI RX|  0x43673e: varnishd(VBO_GetBusyObj+0x3fe) [0x43673e]
**** v1    CLI RX|  0x44c92e: varnishd(VBF_Fetch+0x29e) [0x44c92e]
**** v1    CLI RX|  0x47ce79: varnishd() [0x47ce79]
**** v1    CLI RX|  0x4784d4: varnishd(CNT_Request+0x814) [0x4784d4]
**** v1    CLI RX|  0x4c61b0: varnishd() [0x4c61b0]
**** v1    CLI RX|  0x4c5440: varnishd() [0x4c5440]
**** v1    CLI RX|  0x4b45e4: varnishd() [0x4b45e4]
**** v1    CLI RX|  0x4b3be3: varnishd() [0x4b3be3]
**** v1    CLI RX|  0x4b380b: varnishd() [0x4b380b]
**** v1    CLI RX|  0x7fa97e5f8fa3: /lib/x86_64-linux-gnu/libpthread.so.0(+0x7fa3) [0x7fa97e5f8fa3]
**** v1    CLI RX|  0x7fa97e5274cf: /lib/x86_64-linux-gnu/libc.so.6(clone+0x3f) [0x7fa97e5274cf]
**** 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.3514.4d3117de/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 44123\",
**** v1    CLI RX|  [26] = \"-P\",
**** v1    CLI RX|  [27] = \"/tmp/vtest.clang/_vtest_tmp/vtc.3514.4d3117de/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 = 0x7fa97bf7e700
**** v1    CLI RX|pthread.name = (cache-worker)
**** v1    CLI RX|pthread.attr = {
**** v1    CLI RX|  guard = 4096,
**** v1    CLI RX|  stack_bottom = 0x7fa97bf6b000,
**** v1    CLI RX|  stack_top = 0x7fa97bf7f000,
**** v1    CLI RX|  stack_size = 81920,
**** v1    CLI RX|}
**** v1    CLI RX|thr.req = 0x7fa973318fe0 {
**** v1    CLI RX|  vxid = 1041, 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 = 0x7fa97de1e9f0 {
**** v1    CLI RX|    cli_name = vcl8.s1,
**** v1    CLI RX|    admin_health = healthy, changed = 1742615720.262794,
**** v1    CLI RX|    type = backend {
**** v1    CLI RX|      conn_pool = 0x7fa97de473c0 {
**** v1    CLI RX|        ident = 0x39ceb84fe8fc84d9e02ee51fb38b30d0e2631eb785913e719afffb36b977c214,
**** v1    CLI RX|        vrt_endpoint = 0x7fa97de24620 {
**** v1    CLI RX|          ipv4 = 127.0.0.1, port = 45387,
**** 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 = 0x7fa97ac07b20 {
**** v1    CLI RX|    fd = 24, vxid = 1037,
**** v1    CLI RX|    t_open = 1742615720.263542,
**** v1    CLI RX|    t_idle = 1742615720.283943,
**** v1    CLI RX|    ws = 0x7fa97ac07b60 {
**** v1    CLI RX|      id = \"ses\",
**** v1    CLI RX|      {s, f, r, e} = {0x7fa97ac07bb8, +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 40790 127.0.0.1:43823,
**** v1    CLI RX|    local.endpoint = 127.0.0.1:43823,
**** v1    CLI RX|    local.socket = a0,
**** v1    CLI RX|    local.ip = 127.0.0.1:43823,
**** v1    CLI RX|    remote.ip = 127.0.0.1:40790,
**** v1    CLI RX|    server.ip = 127.0.0.1:43823,
**** v1    CLI RX|    client.ip = 127.0.0.1:40790,
**** v1    CLI RX|  },
**** v1    CLI RX|  worker = 0x7fa97bf7c5e0 {
**** v1    CLI RX|    ws = 0x7fa97bf7c660 {
**** v1    CLI RX|      id = \"wrk\",
**** v1    CLI RX|      {s, f, r, e} = {0x7fa97bf7bb60, +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 = 0x7fa973319138 {
**** v1    CLI RX|    id = \"req\",
**** v1    CLI RX|    {s, f, r, e} = {0x7fa97331e070, +152, (nil), +77640},
**** v1    CLI RX|  },
**** v1    CLI RX|  http_conn = 0x7fa97331dfe8 {
**** v1    CLI RX|    fd = 24 (@0x7fa97ac07b44),
**** v1    CLI RX|    doclose = null(Not Closing)
**** v1    CLI RX|    ws = 0x7fa973319138 {
**** v1    CLI RX|      [Already dumped, see above]
**** v1    CLI RX|    },
**** v1    CLI RX|    {rxbuf_b, rxbuf_e} = {0x7fa97331e070, 0x7fa97331e0a4},
**** 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] = 0x7fa9733191e8 {
**** v1    CLI RX|    ws = 0x7fa973319138 {
**** v1    CLI RX|      [Already dumped, see above]
**** v1    CLI RX|    },
**** v1    CLI RX|    hdrs {
**** v1    CLI RX|      \"GET\",
**** v1    CLI RX|      \"/9\",
**** v1    CLI RX|      \"HTTP/1.1\",
**** v1    CLI RX|      \"Host: 127.0.0.1\",
**** v1    CLI RX|      \"User-Agent: c6\",
**** v1    CLI RX|      \"X-Forwarded-For: 127.0.0.1\",
**** v1    CLI RX|      \"Via: 1.1 v1 (Varnish/trunk)\",
**** v1    CLI RX|      \"fetch: Fetch value 0\",
**** v1    CLI RX|    },
**** v1    CLI RX|  },
**** v1    CLI RX|  vdc = 0x7fa97331df98 {
**** v1    CLI RX|    nxt = (nil),
**** v1    CLI RX|    retval = 0,
**** v1    CLI RX|  },
**** v1    CLI RX|  vcl[vcl] = 0x7fa97de2fc00 {
**** v1    CLI RX|    name = \"vcl8\",
**** v1    CLI RX|    busy = 1,
**** v1    CLI RX|    discard = 0,
**** v1    CLI RX|    state = auto,
**** v1    CLI RX|    temp = warm,
**** v1    CLI RX|    conf = 0x7fa97b1a8b70 {
**** 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 = 0x7fa97bf7bb50 {
**** v1    CLI RX|    handling (VCL::return) = 0x5 (fetch),
**** v1    CLI RX|    ref = 41,
**** v1    CLI RX|    vpi_ref = 0x7fa97b1a9940 {
**** 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] = 0x7fa974021480 {
**** v1    CLI RX|    refcnt = 1,
**** v1    CLI RX|    flags = {busy},
**** v1    CLI RX|    exp_flags = {},
**** v1    CLI RX|    boc = 0x7fa974024000 {
**** 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 = 0x7fa974024310,
**** v1    CLI RX|    stevedore = (nil),
**** v1    CLI RX|  },
**** v1    CLI RX|  flags = {
**** v1    CLI RX|  },
**** v1    CLI RX|  privs = 0x7fa9733191d8 {
**** v1    CLI RX|  },
**** v1    CLI RX|  top = 0x7fa97331e050 {
**** v1    CLI RX|    req = 0x7fa973318fe0 {
**** v1    CLI RX|      [Already dumped, see above]
**** v1    CLI RX|    },
**** v1    CLI RX|    privs = 0x7fa97331e068 {
**** 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 = 0x7fa97bf7c5e0 {
**** v1    CLI RX|  [Already dumped, see above]
**** v1    CLI RX|},
**** v1    CLI RX|vmods = {
**** v1    CLI RX|  std = {p=0x7fa97de2f700, abi=\"Varnish trunk 75aa1a8056c56d9b2c7350bf4e2be44b0a4115e4\", vrt=0.0,
**** v1    CLI RX|    vcs=\"75aa1a8056c56d9b2c7350bf4e2be44b0a4115e4\", version=\"Varnish trunk\"},
**** v1    CLI RX|  vtc = {p=0x7fa97de2f900, abi=\"Varnish trunk 75aa1a8056c56d9b2c7350bf4e2be44b0a4115e4\", vrt=0.0,
**** v1    CLI RX|    vcs=\"75aa1a8056c56d9b2c7350bf4e2be44b0a4115e4\", version=\"Varnish trunk\"},
**** v1    CLI RX|  debug = {p=0x7fa97de2fb00, abi=\"Varnish trunk 75aa1a8056c56d9b2c7350bf4e2be44b0a4115e4\", vrt=21.0,
**** v1    CLI RX|    vcs=\"75aa1a8056c56d9b2c7350bf4e2be44b0a4115e4\", version=\"Varnish trunk\"},
**** v1    CLI RX|},
**** v1    CLI RX|pools = {
**** v1    CLI RX|  pool = 0x7fa97ba00000 {
**** v1    CLI RX|    nidle = 7,
**** v1    CLI RX|    nthr = 10,
**** v1    CLI RX|    lqueue = 0
**** v1    CLI RX|  },
**** v1    CLI RX|  pool = 0x7fa97ba00640 {
**** 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    6.434
---- v1    Unexpected panic
*    top   failure during reset
#    top  TEST ../../../vmod/tests/std_c00001.vtc FAILED (6.443) exit=2
FAIL tests/std_c00001.vtc (exit status: 2)