**** dT 0.000 * top TEST ../../../../bin/varnishtest/tests/b00023.vtc starting **** top extmacro def pkg_version=trunk **** top extmacro def pkg_branch=trunk **** top extmacro def pwd=/tmp/vtest.gcc/varnish-cache/varnish-trunk/_build/sub/bin/varnishtest **** top extmacro def date(...) **** top extmacro def string(...) **** top extmacro def localhost=127.0.0.1 **** top extmacro def bad_backend=127.0.0.1:33137 **** 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.gcc/varnish-cache/varnish-trunk/_build/sub **** top extmacro def topsrc=/tmp/vtest.gcc/varnish-cache/varnish-trunk/_build/sub/../.. **** top macro def testdir=/tmp/vtest.gcc/varnish-cache/varnish-trunk/_build/sub/bin/varnishtest/../../../../bin/varnishtest/tests **** top macro def tmpdir=/tmp/vtest.gcc/_vtest_tmp/vtc.2396.1cb99f27 **** top macro def vtcid=vtc.2396.1cb99f27 ** top === varnishtest "Check that the first_byte_timeout works" * top VTEST Check that the first_byte_timeout works ** top === server s1 { ** s1 Starting server **** s1 macro def s1_addr=127.0.0.1 **** s1 macro def s1_port=43235 **** s1 macro def s1_sock=127.0.0.1:43235 * s1 Listen on 127.0.0.1:43235 ** top === varnish v1 -vcl+backend { **** dT 0.001 ** s1 Started on 127.0.0.1:43235 (1 iterations) **** dT 0.050 ** v1 Launch *** v1 CMD: cd ${pwd} && exec varnishd -d -n /tmp/vtest.gcc/_vtest_tmp/vtc.2396.1cb99f27/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 39089' -P /tmp/vtest.gcc/_vtest_tmp/vtc.2396.1cb99f27/v1/varnishd.pid -p vmod_path=/tmp/vtest.gcc/varnish-cache/varnish-trunk/_build/sub/vmod/.libs *** v1 CMD: cd /tmp/vtest.gcc/varnish-cache/varnish-trunk/_build/sub/bin/varnishtest && exec varnishd -d -n /tmp/vtest.gcc/_vtest_tmp/vtc.2396.1cb99f27/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 39089' -P /tmp/vtest.gcc/_vtest_tmp/vtc.2396.1cb99f27/v1/varnishd.pid -p vmod_path=/tmp/vtest.gcc/varnish-cache/varnish-trunk/_build/sub/vmod/.libs *** v1 PID: 2468 **** v1 macro def v1_pid=2468 **** v1 macro def v1_name=/tmp/vtest.gcc/_vtest_tmp/vtc.2396.1cb99f27/v1 **** dT 0.088 *** v1 debug|Debug: Version: varnish-trunk revision 4c51aec0faa4c301014796512c0a0f1d025637c7 *** 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 4c51aec0faa4c301014796512c0a0f1d025637c7 *** 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.186 **** v1 CLIPOLL 1 0x1 0x0 0x0 *** v1 CLI connection fd = 7 **** dT 0.187 *** v1 CLI RX 107 **** v1 CLI RX|bbsosprifovqugonefqsdjahaomohaxx **** v1 CLI RX| **** v1 CLI RX|Authentication required. **** dT 0.188 **** v1 CLI TX|auth ca0b1d51572ec84308180b9afce43f290f8d330926fddb70b3caad2983b6fd9f **** dT 0.190 *** 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 4c51aec0faa4c301014796512c0a0f1d025637c7 **** 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 = "43235"; } **** v1 CLI TX| **** v1 CLI TX| **** v1 CLI TX|\tsub vcl_recv { **** v1 CLI TX|\t\treturn (pass); **** v1 CLI TX|\t} **** v1 CLI TX|\tsub vcl_backend_fetch { **** v1 CLI TX|\t\tset bereq.first_byte_timeout = 0.5s; **** v1 CLI TX|\t} **** v1 CLI TX| **** v1 CLI TX|%XJEIFLH|)Xspa8P **** dT 0.296 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.400 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.503 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.606 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.715 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.784 *** v1 CLI RX 200 **** v1 CLI RX|VCL compiled. **** v1 CLI TX|vcl.use vcl1 **** dT 0.788 *** v1 CLI RX 200 **** v1 CLI RX|VCL 'vcl1' now active ** v1 Start **** v1 CLI TX|start **** dT 0.820 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.833 *** v1 debug|Debug: Child (2708) Started **** dT 0.858 *** v1 debug|Child launched OK **** dT 0.859 *** v1 CLI RX 200 *** v1 wait-running **** v1 CLI TX|status *** v1 debug|Info: Child (2708) said Child starts **** dT 0.906 *** v1 CLI RX 200 **** v1 CLI RX|Child in state running **** v1 CLI TX|debug.listen_address **** dT 0.920 **** v1 vsl| 0 CLI - Rd vcl.load "vcl1" vcl_vcl1.1759088643.781969/vgc.so 1auto **** v1 vsl| 0 CLI - Wr 200 52 Loaded "vcl_vcl1.1759088643.781969/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:43299 **** v1 vsl| 0 Debug - sockopt: Setting SO_KEEPALIVE for a0=127.0.0.1:43299 **** v1 vsl| 0 Debug - sockopt: Setting SO_SNDTIMEO for a0=127.0.0.1:43299 **** v1 vsl| 0 Debug - sockopt: Setting SO_RCVTIMEO for a0=127.0.0.1:43299 **** v1 vsl| 0 Debug - sockopt: Setting TCP_NODELAY for a0=127.0.0.1:43299 **** v1 vsl| 0 Debug - sockopt: Setting TCP_KEEPIDLE for a0=127.0.0.1:43299 **** v1 vsl| 0 Debug - sockopt: Setting TCP_KEEPCNT for a0=127.0.0.1:43299 **** v1 vsl| 0 Debug - sockopt: Setting TCP_KEEPINTVL for a0=127.0.0.1:43299 **** v1 vsl| 0 CLI - Wr 200 0 **** dT 0.952 *** v1 CLI RX 200 **** v1 CLI RX|a0 127.0.0.1 43299 **** v1 CLI TX|debug.xid 1000 **** dT 0.994 *** v1 CLI RX 200 **** v1 CLI RX|XID is 1000 chunk 1 **** v1 CLI TX|debug.listen_address **** dT 1.021 **** v1 vsl| 0 CLI - Rd debug.listen_address **** v1 vsl| 0 CLI - Wr 200 19 a0 127.0.0.1 43299 **** v1 vsl| 0 CLI - Rd debug.xid 1000 **** v1 vsl| 0 CLI - Wr 200 19 XID is 1000 chunk 1 **** dT 1.042 *** v1 CLI RX 200 **** v1 CLI RX|a0 127.0.0.1 43299 ** v1 Listen on 127.0.0.1 43299 **** v1 macro def v1_addr=127.0.0.1 **** v1 macro def v1_port=43299 **** v1 macro def v1_sock=127.0.0.1:43299 **** v1 macro def v1_a0_addr=127.0.0.1 **** v1 macro def v1_a0_port=43299 **** v1 macro def v1_a0_sock=127.0.0.1:43299 ** top === logexpect l1 -v v1 { ** l1 === expect * 1002 FetchError "first byte timeout" **** dT 1.043 ** top === client c1 { ** c1 Starting client ** c1 Waiting for client ** c1 Started on 127.0.0.1:43299 (1 iterations) *** c1 Connect to 127.0.0.1:43299 *** c1 connected fd 22 from 127.0.0.1 57532 to 127.0.0.1:43299 ** c1 === txreq **** c1 txreq|GET / HTTP/1.1\r **** c1 txreq|Host: 127.0.0.1\r **** c1 txreq|User-Agent: c1\r **** c1 txreq|\r ** c1 === rxresp **** l1 begin| *** l1 test | expect * 1002 FetchError first byte timeout **** dT 1.044 *** s1 accepted fd 5 127.0.0.1 57360 ** s1 === rxreq **** s1 rxhdr|GET / 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|X-Varnish: 1002\r **** s1 rxhdr|\r **** s1 rxhdrlen = 125 **** s1 http[ 0] |GET **** s1 http[ 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] |X-Varnish: 1002 **** s1 bodylen = 0 ** s1 === delay 1 *** s1 delaying 1 second(s) **** dT 1.125 **** v1 vsl| 0 CLI - Rd debug.listen_address **** v1 vsl| 0 CLI - Wr 200 19 a0 127.0.0.1 43299 **** v1 vsl| 1000 Begin c sess 0 HTTP/1 **** v1 vsl| 1000 SessOpen c 127.0.0.1 57532 a0 127.0.0.1 43299 1759088644.631692 21 **** v1 vsl| 1000 Debug c sockopt: SO_LINGER may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1000 Debug c sockopt: SO_KEEPALIVE may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1000 Debug c sockopt: SO_SNDTIMEO may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1000 Debug c sockopt: SO_RCVTIMEO may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1000 Debug c sockopt: TCP_NODELAY may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1000 Debug c sockopt: TCP_KEEPIDLE may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1000 Debug c sockopt: TCP_KEEPCNT may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1000 Debug c sockopt: TCP_KEEPINTVL may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1000 Link c req 1001 rxreq **** dT 1.546 **** c1 rxhdr|HTTP/1.1 503 Backend fetch failed\r **** c1 rxhdr|Date: Sun, 28 Sep 2025 19:44:05 GMT\r **** c1 rxhdr|Server: Varnish\r **** c1 rxhdr|Content-Type: text/html; charset=utf-8\r **** c1 rxhdr|Retry-After: 5\r **** c1 rxhdr|X-Varnish: 1001\r **** c1 rxhdr|Age: 0\r **** c1 rxhdr|Via: 1.1 v1 (Varnish/trunk)\r **** c1 rxhdr|Content-Length: 281\r **** c1 rxhdr|Connection: keep-alive\r **** c1 rxhdr|\r **** c1 rxhdrlen = 246 **** c1 http[ 0] |HTTP/1.1 **** c1 http[ 1] |503 **** c1 http[ 2] |Backend fetch failed **** c1 http[ 3] |Date: Sun, 28 Sep 2025 19:44:05 GMT **** c1 http[ 4] |Server: Varnish **** c1 http[ 5] |Content-Type: text/html; charset=utf-8 **** c1 http[ 6] |Retry-After: 5 **** c1 http[ 7] |X-Varnish: 1001 **** c1 http[ 8] |Age: 0 **** c1 http[ 9] |Via: 1.1 v1 (Varnish/trunk) **** c1 http[10] |Content-Length: 281 **** c1 http[11] |Connection: keep-alive **** c1 c-l| **** c1 c-l| **** c1 c-l|
**** c1 c-l|Backend fetch failed
**** c1 c-l|XID: 1002
**** c1 c-l|Varnish cache server
**** c1 c-l| **** c1 bodylen = 281 ** c1 === expect resp.status == 503 **** c1 EXPECT resp.status (503) == "503" match *** c1 closing fd 22 **** dT 1.547 ** c1 Ending ** top === logexpect l1 -wait ** l1 Waiting for logexp **** dT 1.559 **** l1 match| 1002 FetchError b first byte timeout **** l1 done | ** top === server s1 { ** s1 Waiting for server (4/-1) **** dT 1.635 **** v1 vsl| 1002 Begin b bereq 1001 pass **** v1 vsl| 1002 VCL_use b vcl1 **** v1 vsl| 1002 Timestamp b Start: 1759088644.631929 0.000000 0.000000 **** v1 vsl| 1002 BereqMethod b GET **** v1 vsl| 1002 BereqURL b / **** 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 X-Varnish: 1002 **** v1 vsl| 1002 VCL_call b BACKEND_FETCH **** v1 vsl| 1002 VCL_return b fetch **** v1 vsl| 1002 Timestamp b Fetch: 1759088644.631952 0.000022 0.000022 **** v1 vsl| 1002 Timestamp b Connected: 1759088644.632060 0.000130 0.000107 **** v1 vsl| 1002 BackendOpen b 24 s1 127.0.0.1 43235 127.0.0.1 57360 connect **** v1 vsl| 1002 Timestamp b Bereq: 1759088644.632101 0.000171 0.000041 **** v1 vsl| 1002 FetchError b first byte timeout **** v1 vsl| 1002 BackendClose b 24 s1 close RX_TIMEOUT **** v1 vsl| 1002 Timestamp b Beresp: 1759088645.132563 0.500633 0.500461 **** v1 vsl| 1002 Timestamp b Error: 1759088645.132568 0.500638 0.000004 **** v1 vsl| 1002 BerespProtocol b HTTP/1.1 **** v1 vsl| 1002 BerespStatus b 503 **** v1 vsl| 1002 BerespReason b Backend fetch failed **** v1 vsl| 1002 BerespHeader b Date: Sun, 28 Sep 2025 19:44:05 GMT **** v1 vsl| 1002 BerespHeader b Server: Varnish **** v1 vsl| 1002 VCL_call b BACKEND_ERROR **** v1 vsl| 1002 BerespHeader b Content-Type: text/html; charset=utf-8 **** v1 vsl| 1002 BerespHeader b Retry-After: 5 **** v1 vsl| 1002 VCL_return b deliver **** v1 vsl| 1002 Storage b malloc Transient **** v1 vsl| 1002 Length b 281 **** v1 vsl| 1002 BereqAcct b 125 0 125 0 0 0 **** v1 vsl| 1002 End b **** v1 vsl| 1001 Begin c req 1000 rxreq **** v1 vsl| 1001 Timestamp c Start: 1759088644.631759 0.000000 0.000000 **** v1 vsl| 1001 Timestamp c Req: 1759088644.631759 0.000000 0.000000 **** v1 vsl| 1001 VCL_use c vcl1 **** v1 vsl| 1001 ReqStart c 127.0.0.1 57532 a0 **** v1 vsl| 1001 ReqMethod c GET **** v1 vsl| 1001 ReqURL c / **** v1 vsl| 1001 ReqProtocol c HTTP/1.1 **** 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 VCL_return c pass **** v1 vsl| 1001 VCL_call c HASH **** v1 vsl| 1001 VCL_return c lookup **** v1 vsl| 1001 VCL_call c PASS **** v1 vsl| 1001 VCL_return c fetch **** v1 vsl| 1001 Link c bereq 1002 pass **** v1 vsl| 1001 Timestamp c Fetch: 1759088645.133961 0.502201 0.502201 **** v1 vsl| 1001 RespProtocol c HTTP/1.1 **** v1 vsl| 1001 RespStatus c 503 **** v1 vsl| 1001 RespReason c Backend fetch failed **** v1 vsl| 1001 RespHeader c Date: Sun, 28 Sep 2025 19:44:05 GMT **** v1 vsl| 1001 RespHeader c Server: Varnish **** v1 vsl| 1001 RespHeader c Content-Type: text/html; charset=utf-8 **** v1 vsl| 1001 RespHeader c Retry-After: 5 **** 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 VCL_return c deliver **** v1 vsl| 1001 Timestamp c Process: 1759088645.133990 0.502231 0.000029 **** v1 vsl| 1001 Filters c **** v1 vsl| 1001 RespHeader c Content-Length: 281 **** v1 vsl| 1001 RespHeader c Connection: keep-alive **** v1 vsl| 1001 Timestamp c Resp: 1759088645.134079 0.502320 0.000088 **** v1 vsl| 1001 ReqAcct c 51 0 51 246 281 527 **** v1 vsl| 1001 End c **** v1 vsl| 1000 SessClose c REM_CLOSE 0.506 **** v1 vsl| 1000 End c **** dT 2.047 ** s1 === txresp **** s1 txresp|HTTP/1.1 200 OK\r **** s1 txresp|Date: Sun, 28 Sep 2025 19:44:05 GMT\r **** s1 txresp|Server: s1\r **** s1 txresp|Content-Length: 0\r **** s1 txresp|\r *** s1 shutting fd 5 (server run) ** s1 Ending ** s1 Starting server **** s1 macro def s1_addr=127.0.0.1 **** s1 macro def s1_port=43235 **** s1 macro def s1_sock=127.0.0.1:43235 * s1 Listen on 127.0.0.1:43235 ** top === client c2 { ** c2 Starting client ** c2 Waiting for client **** dT 2.052 ** s1 Started on 127.0.0.1:43235 (1 iterations) ** c2 Started on 127.0.0.1:43299 (1 iterations) *** c2 Connect to 127.0.0.1:43299 *** c2 connected fd 22 from 127.0.0.1 57546 to 127.0.0.1:43299 ** c2 === txreq **** c2 txreq|GET / HTTP/1.1\r **** c2 txreq|Host: 127.0.0.1\r **** c2 txreq|User-Agent: c2\r **** c2 txreq|\r ** c2 === rxresp **** dT 2.057 *** s1 accepted fd 5 127.0.0.1 57374 ** s1 === rxreq **** s1 rxhdr|GET / 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|X-Varnish: 1005\r **** s1 rxhdr|\r **** s1 rxhdrlen = 125 **** s1 http[ 0] |GET **** s1 http[ 1] |/ **** 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] |X-Varnish: 1005 **** s1 bodylen = 0 ** s1 === delay 0.2 *** s1 delaying 0.2 second(s) **** dT 2.157 **** v1 vsl| 1003 Begin c sess 0 HTTP/1 **** v1 vsl| 1003 SessOpen c 127.0.0.1 57546 a0 127.0.0.1 43299 1759088645.640802 22 **** v1 vsl| 1003 Debug c sockopt: SO_LINGER may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1003 Debug c sockopt: SO_KEEPALIVE may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1003 Debug c sockopt: SO_SNDTIMEO may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1003 Debug c sockopt: SO_RCVTIMEO may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1003 Debug c sockopt: TCP_NODELAY may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1003 Debug c sockopt: TCP_KEEPIDLE may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1003 Debug c sockopt: TCP_KEEPCNT may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1003 Debug c sockopt: TCP_KEEPINTVL may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1003 Link c req 1004 rxreq **** dT 2.262 ** s1 === txresp **** s1 txresp|HTTP/1.1 200 OK\r **** s1 txresp|Date: Sun, 28 Sep 2025 19:44:05 GMT\r **** s1 txresp|Server: s1\r **** s1 txresp|Content-Length: 0\r **** s1 txresp|\r *** s1 shutting fd 5 (server run) ** s1 Ending **** dT 2.283 **** c2 rxhdr|HTTP/1.1 200 OK\r **** c2 rxhdr|Date: Sun, 28 Sep 2025 19:44:05 GMT\r **** c2 rxhdr|Server: s1\r **** c2 rxhdr|Content-Length: 0\r **** c2 rxhdr|X-Varnish: 1004\r **** c2 rxhdr|Age: 0\r **** c2 rxhdr|Via: 1.1 v1 (Varnish/trunk)\r **** c2 rxhdr|Connection: keep-alive\r **** c2 rxhdr|\r **** c2 rxhdrlen = 165 **** c2 http[ 0] |HTTP/1.1 **** c2 http[ 1] |200 **** c2 http[ 2] |OK **** c2 http[ 3] |Date: Sun, 28 Sep 2025 19:44:05 GMT **** c2 http[ 4] |Server: s1 **** c2 http[ 5] |Content-Length: 0 **** c2 http[ 6] |X-Varnish: 1004 **** c2 http[ 7] |Age: 0 **** c2 http[ 8] |Via: 1.1 v1 (Varnish/trunk) **** c2 http[ 9] |Connection: keep-alive **** c2 bodylen = 0 ** c2 === expect resp.status == 200 **** c2 EXPECT resp.status (200) == "200" match *** c2 closing fd 22 ** c2 Ending **** dT 2.285 ** 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 = "43235"; } **** v1 CLI TX| **** v1 CLI TX| **** v1 CLI TX|\tsub vcl_recv { **** v1 CLI TX|\t\treturn (pass); **** v1 CLI TX|\t} **** v1 CLI TX| **** v1 CLI TX|%XJEIFLH|)Xspa8P **** dT 2.360 **** v1 vsl| 1005 Begin b bereq 1004 pass **** v1 vsl| 1005 VCL_use b vcl1 **** v1 vsl| 1005 Timestamp b Start: 1759088645.640967 0.000000 0.000000 **** v1 vsl| 1005 BereqMethod b GET **** v1 vsl| 1005 BereqURL b / **** v1 vsl| 1005 BereqProtocol b HTTP/1.1 **** v1 vsl| 1005 BereqHeader b Host: 127.0.0.1 **** v1 vsl| 1005 BereqHeader b User-Agent: c2 **** v1 vsl| 1005 BereqHeader b X-Forwarded-For: 127.0.0.1 **** v1 vsl| 1005 BereqHeader b Via: 1.1 v1 (Varnish/trunk) **** v1 vsl| 1005 BereqHeader b X-Varnish: 1005 **** v1 vsl| 1005 VCL_call b BACKEND_FETCH **** v1 vsl| 1005 VCL_return b fetch **** v1 vsl| 1005 Timestamp b Fetch: 1759088645.640983 0.000016 0.000016 **** v1 vsl| 1005 Timestamp b Connected: 1759088645.641094 0.000127 0.000110 **** v1 vsl| 1005 BackendOpen b 24 s1 127.0.0.1 43235 127.0.0.1 57374 connect **** v1 vsl| 1005 Timestamp b Bereq: 1759088645.641134 0.000167 0.000040 **** v1 vsl| 1005 BerespProtocol b HTTP/1.1 **** v1 vsl| 1005 BerespStatus b 200 **** v1 vsl| 1005 BerespReason b OK **** v1 vsl| 1005 BerespHeader b Date: Sun, 28 Sep 2025 19:44:05 GMT **** v1 vsl| 1005 BerespHeader b Server: s1 **** v1 vsl| 1005 BerespHeader b Content-Length: 0 **** v1 vsl| 1005 Timestamp b Beresp: 1759088645.856959 0.215992 0.215825 **** v1 vsl| 1005 VCL_call b BACKEND_RESPONSE **** v1 vsl| 1005 VCL_return b deliver **** v1 vsl| 1005 Debug b Missing content-range header or unknown range unit **** v1 vsl| 1005 Timestamp b Process: 1759088645.856982 0.216015 0.000022 **** v1 vsl| 1005 Filters b **** v1 vsl| 1005 Storage b malloc Transient **** v1 vsl| 1005 Fetch_Body b 0 none - **** v1 vsl| 1005 BackendClose b 24 s1 recycle **** v1 vsl| 1005 Timestamp b BerespBody: 1759088645.870614 0.229647 0.013631 **** v1 vsl| 1005 Length b 0 **** v1 vsl| 1005 BereqAcct b 125 0 125 87 0 87 **** v1 vsl| 1005 End b **** v1 vsl| 1004 Begin c req 1003 rxreq **** v1 vsl| 1004 Timestamp c Start: 1759088645.640870 0.000000 0.000000 **** v1 vsl| 1004 Timestamp c Req: 1759088645.640870 0.000000 0.000000 **** v1 vsl| 1004 VCL_use c vcl1 **** v1 vsl| 1004 ReqStart c 127.0.0.1 57546 a0 **** v1 vsl| 1004 ReqMethod c GET **** v1 vsl| 1004 ReqURL c / **** v1 vsl| 1004 ReqProtocol c HTTP/1.1 **** v1 vsl| 1004 ReqHeader c Host: 127.0.0.1 **** v1 vsl| 1004 ReqHeader c User-Agent: c2 **** v1 vsl| 1004 ReqHeader c X-Forwarded-For: 127.0.0.1 **** v1 vsl| 1004 ReqHeader c Via: 1.1 v1 (Varnish/trunk) **** v1 vsl| 1004 VCL_call c RECV **** v1 vsl| 1004 VCL_return c pass **** v1 vsl| 1004 VCL_call c HASH **** v1 vsl| 1004 VCL_return c lookup **** v1 vsl| 1004 VCL_call c PASS **** v1 vsl| 1004 VCL_return c fetch **** v1 vsl| 1004 Link c bereq 1005 pass **** v1 vsl| 1004 Timestamp c Fetch: 1759088645.870670 0.229799 0.229799 **** v1 vsl| 1004 RespProtocol c HTTP/1.1 **** v1 vsl| 1004 RespStatus c 200 **** v1 vsl| 1004 RespReason c OK **** v1 vsl| 1004 RespHeader c Date: Sun, 28 Sep 2025 19:44:05 GMT **** v1 vsl| 1004 RespHeader c Server: s1 **** v1 vsl| 1004 RespHeader c Content-Length: 0 **** v1 vsl| 1004 RespHeader c X-Varnish: 1004 **** v1 vsl| 1004 RespHeader c Age: 0 **** v1 vsl| 1004 RespHeader c Via: 1.1 v1 (Varnish/trunk) **** v1 vsl| 1004 VCL_call c DELIVER **** v1 vsl| 1004 VCL_return c deliver **** v1 vsl| 1004 Timestamp c Process: 1759088645.870708 0.229837 0.000037 **** v1 vsl| 1004 Filters c **** v1 vsl| 1004 RespHeader c Connection: keep-alive **** v1 vsl| 1004 Timestamp c Resp: 1759088645.870796 0.229925 0.000087 **** v1 vsl| 1004 ReqAcct c 51 0 51 165 0 165 **** v1 vsl| 1004 End c **** v1 vsl| 1003 SessClose c REM_CLOSE 0.231 **** v1 vsl| 1003 End c **** dT 3.160 *** v1 CLI RX 200 **** v1 CLI TX|vcl.use vcl2 *** v1 CLI RX 200 **** v1 CLI RX|VCL 'vcl2' now active ** top === varnish v1 -cliok "param.set first_byte_timeout 0.5" **** v1 CLI TX|param.set first_byte_timeout 0.5 **** dT 3.180 **** v1 vsl| 0 CLI - Rd vcl.load vcl2 vcl_vcl2.1759088645.874156/vgc.so 1auto **** v1 vsl| 0 CLI - Wr 200 52 Loaded "vcl_vcl2.1759088645.874156/vgc.so" as "vcl2" **** v1 vsl| 0 CLI - Rd vcl.use vcl2 **** v1 vsl| 0 CLI - Wr 200 0 **** dT 3.203 *** v1 CLI RX 200 ** v1 CLI 200Backend fetch failed
**** c1 c-l|XID: 1008
**** c1 c-l|Varnish cache server
**** c1 c-l| **** c1 bodylen = 281 ** c1 === expect resp.status == 503 **** c1 EXPECT resp.status (503) == "503" match *** c1 closing fd 5 ** c1 Ending ** top === server s1 { ** s1 Waiting for server (4/-1) **** dT 3.807 **** v1 vsl| 1008 Begin b bereq 1007 pass **** v1 vsl| 1008 VCL_use b vcl2 **** v1 vsl| 1008 Timestamp b Start: 1759088646.805160 0.000000 0.000000 **** v1 vsl| 1008 BereqMethod b GET **** v1 vsl| 1008 BereqURL b / **** v1 vsl| 1008 BereqProtocol b HTTP/1.1 **** 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 X-Varnish: 1008 **** v1 vsl| 1008 VCL_call b BACKEND_FETCH **** v1 vsl| 1008 VCL_return b fetch **** v1 vsl| 1008 Timestamp b Fetch: 1759088646.805185 0.000024 0.000024 **** v1 vsl| 1008 Timestamp b Connected: 1759088646.805264 0.000103 0.000079 **** v1 vsl| 1008 BackendOpen b 24 s1 127.0.0.1 43235 127.0.0.1 57384 connect **** v1 vsl| 1008 Timestamp b Bereq: 1759088646.805302 0.000141 0.000037 **** v1 vsl| 1008 FetchError b first byte timeout **** v1 vsl| 1008 BackendClose b 24 s1 close RX_TIMEOUT **** v1 vsl| 1008 Timestamp b Beresp: 1759088647.307072 0.501912 0.501770 **** v1 vsl| 1008 Timestamp b Error: 1759088647.307079 0.501919 0.000006 **** v1 vsl| 1008 BerespProtocol b HTTP/1.1 **** v1 vsl| 1008 BerespStatus b 503 **** v1 vsl| 1008 BerespReason b Backend fetch failed **** v1 vsl| 1008 BerespHeader b Date: Sun, 28 Sep 2025 19:44:07 GMT **** v1 vsl| 1008 BerespHeader b Server: Varnish **** v1 vsl| 1008 VCL_call b BACKEND_ERROR **** v1 vsl| 1008 BerespHeader b Content-Type: text/html; charset=utf-8 **** v1 vsl| 1008 BerespHeader b Retry-After: 5 **** v1 vsl| 1008 VCL_return b deliver **** v1 vsl| 1008 Storage b malloc Transient **** v1 vsl| 1008 Length b 281 **** v1 vsl| 1008 BereqAcct b 125 0 125 0 0 0 **** v1 vsl| 1008 End b **** v1 vsl| 1007 Begin c req 1006 rxreq **** v1 vsl| 1007 Timestamp c Start: 1759088646.802068 0.000000 0.000000 **** v1 vsl| 1007 Timestamp c Req: 1759088646.802068 0.000000 0.000000 **** v1 vsl| 1007 VCL_use c vcl2 **** v1 vsl| 1007 ReqStart c 127.0.0.1 57556 a0 **** v1 vsl| 1007 ReqMethod c GET **** v1 vsl| 1007 ReqURL c / **** v1 vsl| 1007 ReqProtocol c HTTP/1.1 **** v1 vsl| 1007 ReqHeader c Host: 127.0.0.1 **** v1 vsl| 1007 ReqHeader c User-Agent: c1 **** v1 vsl| 1007 ReqHeader c X-Forwarded-For: 127.0.0.1 **** v1 vsl| 1007 ReqHeader c Via: 1.1 v1 (Varnish/trunk) **** v1 vsl| 1007 VCL_call c RECV **** v1 vsl| 1007 VCL_return c pass **** v1 vsl| 1007 VCL_call c HASH **** v1 vsl| 1007 VCL_return c lookup **** v1 vsl| 1007 VCL_call c PASS **** v1 vsl| 1007 VCL_return c fetch **** v1 vsl| 1007 Link c bereq 1008 pass **** v1 vsl| 1007 Timestamp c Fetch: 1759088647.311324 0.509256 0.509256 **** v1 vsl| 1007 RespProtocol c HTTP/1.1 **** v1 vsl| 1007 RespStatus c 503 **** v1 vsl| 1007 RespReason c Backend fetch failed **** v1 vsl| 1007 RespHeader c Date: Sun, 28 Sep 2025 19:44:07 GMT **** v1 vsl| 1007 RespHeader c Server: Varnish **** v1 vsl| 1007 RespHeader c Content-Type: text/html; charset=utf-8 **** v1 vsl| 1007 RespHeader c Retry-After: 5 **** v1 vsl| 1007 RespHeader c X-Varnish: 1007 **** v1 vsl| 1007 RespHeader c Age: 0 **** v1 vsl| 1007 RespHeader c Via: 1.1 v1 (Varnish/trunk) **** v1 vsl| 1007 VCL_call c DELIVER **** v1 vsl| 1007 VCL_return c deliver **** v1 vsl| 1007 Timestamp c Process: 1759088647.311358 0.509290 0.000034 **** v1 vsl| 1007 Filters c **** v1 vsl| 1007 RespHeader c Content-Length: 281 **** v1 vsl| 1007 RespHeader c Connection: keep-alive **** v1 vsl| 1007 Timestamp c Resp: 1759088647.311442 0.509374 0.000084 **** v1 vsl| 1007 ReqAcct c 51 0 51 246 281 527 **** v1 vsl| 1007 End c **** v1 vsl| 1006 SessClose c REM_CLOSE 0.516 **** v1 vsl| 1006 End c **** dT 3.907 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1759088647 1.0 **** dT 4.219 ** s1 === txresp **** s1 txresp|HTTP/1.1 200 OK\r **** s1 txresp|Date: Sun, 28 Sep 2025 19:44:07 GMT\r **** s1 txresp|Server: s1\r **** s1 txresp|Content-Length: 0\r **** s1 txresp|\r *** s1 shutting fd 22 (server run) ** s1 Ending ** s1 Starting server **** s1 macro def s1_addr=127.0.0.1 **** s1 macro def s1_port=43235 **** s1 macro def s1_sock=127.0.0.1:43235 * s1 Listen on 127.0.0.1:43235 ** top === client c2 -run ** c2 Starting client ** c2 Waiting for client ** s1 Started on 127.0.0.1:43235 (1 iterations) **** dT 4.222 ** c2 Started on 127.0.0.1:43299 (1 iterations) *** c2 Connect to 127.0.0.1:43299 *** c2 connected fd 22 from 127.0.0.1 57560 to 127.0.0.1:43299 ** c2 === txreq **** c2 txreq|GET / 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.226 *** s1 accepted fd 5 127.0.0.1 57388 ** s1 === rxreq **** s1 rxhdr|GET / 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|X-Varnish: 1011\r **** s1 rxhdr|\r **** s1 rxhdrlen = 125 **** s1 http[ 0] |GET **** s1 http[ 1] |/ **** 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] |X-Varnish: 1011 **** s1 bodylen = 0 ** s1 === delay 0.2 *** s1 delaying 0.2 second(s) **** dT 4.321 **** v1 vsl| 1009 Begin c sess 0 HTTP/1 **** v1 vsl| 1009 SessOpen c 127.0.0.1 57560 a0 127.0.0.1 43299 1759088647.810412 21 **** v1 vsl| 1009 Debug c sockopt: SO_LINGER may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1009 Debug c sockopt: SO_KEEPALIVE may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1009 Debug c sockopt: SO_SNDTIMEO may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1009 Debug c sockopt: SO_RCVTIMEO may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1009 Debug c sockopt: TCP_NODELAY may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1009 Debug c sockopt: TCP_KEEPIDLE may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1009 Debug c sockopt: TCP_KEEPCNT may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1009 Debug c sockopt: TCP_KEEPINTVL may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1009 Link c req 1010 rxreq **** dT 4.430 ** s1 === txresp **** s1 txresp|HTTP/1.1 200 OK\r **** s1 txresp|Date: Sun, 28 Sep 2025 19:44:08 GMT\r **** s1 txresp|Server: s1\r **** s1 txresp|Content-Length: 0\r **** s1 txresp|\r *** s1 shutting fd 5 (server run) ** s1 Ending **** dT 4.442 **** c2 rxhdr|HTTP/1.1 200 OK\r **** c2 rxhdr|Date: Sun, 28 Sep 2025 19:44:08 GMT\r **** c2 rxhdr|Server: s1\r **** c2 rxhdr|Content-Length: 0\r **** c2 rxhdr|X-Varnish: 1010\r **** c2 rxhdr|Age: 0\r **** c2 rxhdr|Via: 1.1 v1 (Varnish/trunk)\r **** c2 rxhdr|Connection: keep-alive\r **** c2 rxhdr|\r **** c2 rxhdrlen = 165 **** c2 http[ 0] |HTTP/1.1 **** c2 http[ 1] |200 **** c2 http[ 2] |OK **** c2 http[ 3] |Date: Sun, 28 Sep 2025 19:44:08 GMT **** c2 http[ 4] |Server: s1 **** c2 http[ 5] |Content-Length: 0 **** c2 http[ 6] |X-Varnish: 1010 **** c2 http[ 7] |Age: 0 **** c2 http[ 8] |Via: 1.1 v1 (Varnish/trunk) **** c2 http[ 9] |Connection: keep-alive **** c2 bodylen = 0 ** c2 === expect resp.status == 200 **** c2 EXPECT resp.status (200) == "200" match *** c2 closing fd 22 ** c2 Ending **** dT 4.447 ** top === server s1 { ** s1 Waiting for server (4/-1) ** s1 Starting server **** s1 macro def s1_addr=127.0.0.1 **** s1 macro def s1_port=43235 **** s1 macro def s1_sock=127.0.0.1:43235 * s1 Listen on 127.0.0.1:43235 ** top === varnish v1 -vcl { **** v1 CLI TX|vcl.inline vcl3 << %XJEIFLH|)Xspa8P **** v1 CLI TX|vcl 4.1; **** v1 CLI TX| **** v1 CLI TX|\tbackend b1 { **** v1 CLI TX|\t\t.host = "127.0.0.1"; **** v1 CLI TX|\t\t.port = "43235"; **** v1 CLI TX|\t\t.first_byte_timeout = 0.5s; **** v1 CLI TX|\t} **** v1 CLI TX| **** v1 CLI TX|%XJEIFLH|)Xspa8P **** dT 4.449 ** s1 Started on 127.0.0.1:43235 (1 iterations) **** dT 4.526 **** v1 vsl| 1011 Begin b bereq 1010 pass **** v1 vsl| 1011 VCL_use b vcl2 **** v1 vsl| 1011 Timestamp b Start: 1759088647.810535 0.000000 0.000000 **** v1 vsl| 1011 BereqMethod b GET **** v1 vsl| 1011 BereqURL b / **** 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: c2 **** 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 X-Varnish: 1011 **** v1 vsl| 1011 VCL_call b BACKEND_FETCH **** v1 vsl| 1011 VCL_return b fetch **** v1 vsl| 1011 Timestamp b Fetch: 1759088647.810552 0.000017 0.000017 **** v1 vsl| 1011 Timestamp b Connected: 1759088647.810626 0.000091 0.000073 **** v1 vsl| 1011 BackendOpen b 24 s1 127.0.0.1 43235 127.0.0.1 57388 connect **** v1 vsl| 1011 Timestamp b Bereq: 1759088647.810662 0.000126 0.000035 **** v1 vsl| 1011 BerespProtocol b HTTP/1.1 **** v1 vsl| 1011 BerespStatus b 200 **** v1 vsl| 1011 BerespReason b OK **** v1 vsl| 1011 BerespHeader b Date: Sun, 28 Sep 2025 19:44:08 GMT **** v1 vsl| 1011 BerespHeader b Server: s1 **** v1 vsl| 1011 BerespHeader b Content-Length: 0 **** v1 vsl| 1011 Timestamp b Beresp: 1759088648.018925 0.208389 0.208263 **** v1 vsl| 1011 VCL_call b BACKEND_RESPONSE **** 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: 1759088648.018948 0.208412 0.000022 **** v1 vsl| 1011 Filters b **** v1 vsl| 1011 Storage b malloc Transient **** v1 vsl| 1011 Fetch_Body b 0 none - **** v1 vsl| 1011 BackendClose b 24 s1 recycle **** v1 vsl| 1011 Timestamp b BerespBody: 1759088648.029923 0.219388 0.010975 **** v1 vsl| 1011 Length b 0 **** v1 vsl| 1011 BereqAcct b 125 0 125 87 0 87 **** v1 vsl| 1011 End b **** v1 vsl| 1010 Begin c req 1009 rxreq **** v1 vsl| 1010 Timestamp c Start: 1759088647.810463 0.000000 0.000000 **** v1 vsl| 1010 Timestamp c Req: 1759088647.810463 0.000000 0.000000 **** v1 vsl| 1010 VCL_use c vcl2 **** v1 vsl| 1010 ReqStart c 127.0.0.1 57560 a0 **** v1 vsl| 1010 ReqMethod c GET **** v1 vsl| 1010 ReqURL c / **** 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: c2 **** 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 VCL_return c pass **** v1 vsl| 1010 VCL_call c HASH **** v1 vsl| 1010 VCL_return c lookup **** v1 vsl| 1010 VCL_call c PASS **** v1 vsl| 1010 VCL_return c fetch **** v1 vsl| 1010 Link c bereq 1011 pass **** v1 vsl| 1010 Timestamp c Fetch: 1759088648.029997 0.219534 0.219534 **** v1 vsl| 1010 RespProtocol c HTTP/1.1 **** v1 vsl| 1010 RespStatus c 200 **** v1 vsl| 1010 RespReason c OK **** v1 vsl| 1010 RespHeader c Date: Sun, 28 Sep 2025 19:44:08 GMT **** v1 vsl| 1010 RespHeader c Server: s1 **** v1 vsl| 1010 RespHeader c Content-Length: 0 **** 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: 1759088648.030033 0.219569 0.000035 **** v1 vsl| 1010 Filters c **** v1 vsl| 1010 RespHeader c Connection: keep-alive **** v1 vsl| 1010 Timestamp c Resp: 1759088648.030087 0.219623 0.000053 **** v1 vsl| 1010 ReqAcct c 51 0 51 165 0 165 **** v1 vsl| 1010 End c **** v1 vsl| 1009 SessClose c REM_CLOSE 0.221 **** v1 vsl| 1009 End c **** dT 5.597 *** v1 CLI RX 200 **** dT 5.598 **** v1 CLI TX|vcl.use vcl3 *** v1 CLI RX 200 **** v1 CLI RX|VCL 'vcl3' now active ** top === client c1 -run ** c1 Starting client ** c1 Waiting for client **** dT 5.607 ** c1 Started on 127.0.0.1:43299 (1 iterations) *** c1 Connect to 127.0.0.1:43299 *** c1 connected fd 22 from 127.0.0.1 57576 to 127.0.0.1:43299 ** c1 === txreq **** c1 txreq|GET / HTTP/1.1\r **** c1 txreq|Host: 127.0.0.1\r **** c1 txreq|User-Agent: c1\r **** c1 txreq|\r ** c1 === rxresp **** dT 5.613 *** s1 accepted fd 5 127.0.0.1 57404 ** s1 === rxreq **** s1 rxhdr|GET / 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: 1014\r **** s1 rxhdr|\r **** s1 rxhdrlen = 148 **** s1 http[ 0] |GET **** s1 http[ 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: 1014 **** s1 bodylen = 0 ** s1 === delay 1 *** s1 delaying 1 second(s) **** dT 5.674 **** v1 vsl| 0 CLI - Rd vcl.load vcl3 vcl_vcl3.1759088648.036018/vgc.so 1auto **** v1 vsl| 0 CLI - Wr 200 52 Loaded "vcl_vcl3.1759088648.036018/vgc.so" as "vcl3" **** v1 vsl| 0 CLI - Rd vcl.use vcl3 **** 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 57576 a0 127.0.0.1 43299 1759088649.195621 22 **** v1 vsl| 1012 Debug c sockopt: SO_LINGER may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1012 Debug c sockopt: SO_KEEPALIVE may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1012 Debug c sockopt: SO_SNDTIMEO may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1012 Debug c sockopt: SO_RCVTIMEO may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1012 Debug c sockopt: TCP_NODELAY may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1012 Debug c sockopt: TCP_KEEPIDLE may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1012 Debug c sockopt: TCP_KEEPCNT may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1012 Debug c sockopt: TCP_KEEPINTVL may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1012 Link c req 1013 rxreq **** dT 6.122 **** c1 rxhdr|HTTP/1.1 503 Backend fetch failed\r **** c1 rxhdr|Date: Sun, 28 Sep 2025 19:44:09 GMT\r **** c1 rxhdr|Server: Varnish\r **** c1 rxhdr|Content-Type: text/html; charset=utf-8\r **** c1 rxhdr|Retry-After: 5\r **** c1 rxhdr|X-Varnish: 1013\r **** c1 rxhdr|Age: 0\r **** c1 rxhdr|Via: 1.1 v1 (Varnish/trunk)\r **** c1 rxhdr|Content-Length: 281\r **** c1 rxhdr|Connection: keep-alive\r **** c1 rxhdr|\r **** c1 rxhdrlen = 246 **** c1 http[ 0] |HTTP/1.1 **** c1 http[ 1] |503 **** c1 http[ 2] |Backend fetch failed **** c1 http[ 3] |Date: Sun, 28 Sep 2025 19:44:09 GMT **** c1 http[ 4] |Server: Varnish **** c1 http[ 5] |Content-Type: text/html; charset=utf-8 **** c1 http[ 6] |Retry-After: 5 **** c1 http[ 7] |X-Varnish: 1013 **** c1 http[ 8] |Age: 0 **** c1 http[ 9] |Via: 1.1 v1 (Varnish/trunk) **** c1 http[10] |Content-Length: 281 **** c1 http[11] |Connection: keep-alive **** c1 c-l| **** c1 c-l| **** c1 c-l| **** c1 c-l|Backend fetch failed
**** c1 c-l|XID: 1014
**** c1 c-l|Varnish cache server
**** c1 c-l| **** c1 bodylen = 281 ** c1 === expect resp.status == 503 **** c1 EXPECT resp.status (503) == "503" match *** c1 closing fd 22 ** c1 Ending **** dT 6.123 ** top === server s1 { ** s1 Waiting for server (4/-1) **** dT 6.188 **** v1 vsl| 1014 Begin b bereq 1013 fetch **** v1 vsl| 1014 VCL_use b vcl3 **** v1 vsl| 1014 Timestamp b Start: 1759088649.201106 0.000000 0.000000 **** v1 vsl| 1014 BereqMethod b GET **** v1 vsl| 1014 BereqURL b / **** 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: c1 **** 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: 1759088649.201132 0.000025 0.000025 **** v1 vsl| 1014 Timestamp b Connected: 1759088649.201201 0.000094 0.000068 **** v1 vsl| 1014 BackendOpen b 24 b1 127.0.0.1 43235 127.0.0.1 57404 connect **** v1 vsl| 1014 Timestamp b Bereq: 1759088649.201239 0.000133 0.000038 **** v1 vsl| 1014 FetchError b first byte timeout **** v1 vsl| 1014 BackendClose b 24 b1 close RX_TIMEOUT **** v1 vsl| 1014 Timestamp b Beresp: 1759088649.708147 0.507041 0.506907 **** v1 vsl| 1014 Timestamp b Error: 1759088649.708153 0.507047 0.000005 **** v1 vsl| 1014 BerespProtocol b HTTP/1.1 **** v1 vsl| 1014 BerespStatus b 503 **** v1 vsl| 1014 BerespReason b Backend fetch failed **** v1 vsl| 1014 BerespHeader b Date: Sun, 28 Sep 2025 19:44:09 GMT **** v1 vsl| 1014 BerespHeader b Server: Varnish **** v1 vsl| 1014 VCL_call b BACKEND_ERROR **** v1 vsl| 1014 BerespHeader b Content-Type: text/html; charset=utf-8 **** v1 vsl| 1014 BerespHeader b Retry-After: 5 **** v1 vsl| 1014 VCL_return b deliver **** v1 vsl| 1014 Storage b malloc Transient **** v1 vsl| 1014 Length b 281 **** v1 vsl| 1014 BereqAcct b 148 0 148 0 0 0 **** v1 vsl| 1014 End b **** v1 vsl| 1013 Begin c req 1012 rxreq **** v1 vsl| 1013 Timestamp c Start: 1759088649.195698 0.000000 0.000000 **** v1 vsl| 1013 Timestamp c Req: 1759088649.195698 0.000000 0.000000 **** v1 vsl| 1013 VCL_use c vcl3 **** v1 vsl| 1013 ReqStart c 127.0.0.1 57576 a0 **** v1 vsl| 1013 ReqMethod c GET **** v1 vsl| 1013 ReqURL c / **** 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: c1 **** 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: 1759088649.709956 0.514258 0.514258 **** v1 vsl| 1013 RespProtocol c HTTP/1.1 **** v1 vsl| 1013 RespStatus c 503 **** v1 vsl| 1013 RespReason c Backend fetch failed **** v1 vsl| 1013 RespHeader c Date: Sun, 28 Sep 2025 19:44:09 GMT **** v1 vsl| 1013 RespHeader c Server: Varnish **** v1 vsl| 1013 RespHeader c Content-Type: text/html; charset=utf-8 **** v1 vsl| 1013 RespHeader c Retry-After: 5 **** 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 VCL_call c DELIVER **** v1 vsl| 1013 VCL_return c deliver **** v1 vsl| 1013 Timestamp c Process: 1759088649.709991 0.514292 0.000034 **** v1 vsl| 1013 Filters c **** v1 vsl| 1013 RespHeader c Content-Length: 281 **** v1 vsl| 1013 RespHeader c Connection: keep-alive **** v1 vsl| 1013 Timestamp c Resp: 1759088649.710069 0.514370 0.000077 **** v1 vsl| 1013 ReqAcct c 51 0 51 246 281 527 **** v1 vsl| 1013 End c **** v1 vsl| 1012 SessClose c REM_CLOSE 0.518 **** v1 vsl| 1012 End c **** dT 6.617 ** s1 === txresp **** s1 txresp|HTTP/1.1 200 OK\r **** s1 txresp|Date: Sun, 28 Sep 2025 19:44:10 GMT\r **** s1 txresp|Server: s1\r **** s1 txresp|Content-Length: 0\r **** s1 txresp|\r *** s1 shutting fd 5 (server run) ** s1 Ending ** s1 Starting server **** s1 macro def s1_addr=127.0.0.1 **** s1 macro def s1_port=43235 **** s1 macro def s1_sock=127.0.0.1:43235 * s1 Listen on 127.0.0.1:43235 ** top === client c2 -run ** c2 Starting client ** c2 Waiting for client ** c2 Started on 127.0.0.1:43299 (1 iterations) *** c2 Connect to 127.0.0.1:43299 *** c2 connected fd 5 from 127.0.0.1 57586 to 127.0.0.1:43299 ** c2 === txreq **** c2 txreq|GET / HTTP/1.1\r **** c2 txreq|Host: 127.0.0.1\r **** c2 txreq|User-Agent: c2\r **** c2 txreq|\r ** c2 === rxresp **** dT 6.639 ** s1 Started on 127.0.0.1:43235 (1 iterations) *** s1 accepted fd 22 127.0.0.1 57414 ** s1 === rxreq **** dT 6.640 **** s1 rxhdr|GET / 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: 1017\r **** s1 rxhdr|\r **** s1 rxhdrlen = 148 **** s1 http[ 0] |GET **** s1 http[ 1] |/ **** 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: 1017 **** s1 bodylen = 0 ** s1 === delay 0.2 *** s1 delaying 0.2 second(s) **** dT 7.215 **** v1 vsl| 1015 Begin c sess 0 HTTP/1 **** v1 vsl| 1015 SessOpen c 127.0.0.1 57586 a0 127.0.0.1 43299 1759088650.227273 23 **** v1 vsl| 1015 Debug c sockopt: SO_LINGER may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1015 Debug c sockopt: SO_KEEPALIVE may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1015 Debug c sockopt: SO_SNDTIMEO may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1015 Debug c sockopt: SO_RCVTIMEO may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1015 Debug c sockopt: TCP_NODELAY may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1015 Debug c sockopt: TCP_KEEPIDLE may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1015 Debug c sockopt: TCP_KEEPCNT may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1015 Debug c sockopt: TCP_KEEPINTVL may be inherited for a0=127.0.0.1:43299 **** v1 vsl| 1015 Link c req 1016 rxreq **** dT 7.375 ** s1 === txresp **** s1 txresp|HTTP/1.1 200 OK\r **** s1 txresp|Date: Sun, 28 Sep 2025 19:44:10 GMT\r **** s1 txresp|Server: s1\r **** s1 txresp|Content-Length: 0\r **** s1 txresp|\r *** s1 shutting fd 22 (server run) ** s1 Ending **** dT 7.376 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1759088650 1.0 **** v1 vsl| 1017 Begin b bereq 1016 fetch **** v1 vsl| 1017 VCL_use b vcl3 **** v1 vsl| 1017 Timestamp b Start: 1759088650.227530 0.000000 0.000000 **** v1 vsl| 1017 BereqMethod b GET **** v1 vsl| 1017 BereqURL b / **** v1 vsl| 1017 BereqProtocol b HTTP/1.1 **** v1 vsl| 1017 BereqHeader b Host: 127.0.0.1 **** v1 vsl| 1017 BereqHeader b User-Agent: c2 **** v1 vsl| 1017 BereqHeader b X-Forwarded-For: 127.0.0.1 **** v1 vsl| 1017 BereqHeader b Via: 1.1 v1 (Varnish/trunk) **** v1 vsl| 1017 BereqHeader b Accept-Encoding: gzip **** v1 vsl| 1017 BereqHeader b X-Varnish: 1017 **** v1 vsl| 1017 VCL_call b BACKEND_FETCH **** v1 vsl| 1017 VCL_return b fetch **** v1 vsl| 1017 Timestamp b Fetch: 1759088650.227550 0.000020 0.000020 **** v1 vsl| 1017 Timestamp b Connected: 1759088650.227632 0.000102 0.000082 **** v1 vsl| 1017 BackendOpen b 24 b1 127.0.0.1 43235 127.0.0.1 57414 connect **** v1 vsl| 1017 Timestamp b Bereq: 1759088650.227671 0.000141 0.000039 **** v1 vsl| 1017 FetchError b first byte timeout **** v1 vsl| 1017 BackendClose b 24 b1 close RX_TIMEOUT **** v1 vsl| 1017 Timestamp b Beresp: 1759088650.963745 0.736214 0.736073 **** v1 vsl| 1017 Timestamp b Error: 1759088650.963750 0.736220 0.000005 **** v1 vsl| 1017 BerespProtocol b HTTP/1.1 **** v1 vsl| 1017 BerespStatus b 503 **** v1 vsl| 1017 BerespReason b Backend fetch failed **** v1 vsl| 1017 BerespHeader b Date: Sun, 28 Sep 2025 19:44:10 GMT **** v1 vsl| 1017 BerespHeader b Server: Varnish **** v1 vsl| 1017 VCL_call b BACKEND_ERROR **** v1 vsl| 1017 BerespHeader b Content-Type: text/html; charset=utf-8 **** v1 vsl| 1017 BerespHeader b Retry-After: 5 **** v1 vsl| 1017 VCL_return b deliver **** v1 vsl| 1017 Storage b malloc Transient **** v1 vsl| 1017 Length b 281 **** v1 vsl| 1017 BereqAcct b 148 0 148 0 0 0 **** v1 vsl| 1017 End b **** v1 vsl| 1016 Begin c req 1015 rxreq **** v1 vsl| 1016 Timestamp c Start: 1759088650.227358 0.000000 0.000000 **** v1 vsl| 1016 Timestamp c Req: 1759088650.227358 0.000000 0.000000 **** v1 vsl| 1016 VCL_use c vcl3 **** v1 vsl| 1016 ReqStart c 127.0.0.1 57586 a0 **** v1 vsl| 1016 ReqMethod c GET **** v1 vsl| 1016 ReqURL c / **** v1 vsl| 1016 ReqProtocol c HTTP/1.1 **** v1 vsl| 1016 ReqHeader c Host: 127.0.0.1 **** v1 vsl| 1016 ReqHeader c User-Agent: c2 **** v1 vsl| 1016 ReqHeader c X-Forwarded-For: 127.0.0.1 **** v1 vsl| 1016 ReqHeader c Via: 1.1 v1 (Varnish/trunk) **** v1 vsl| 1016 VCL_call c RECV **** v1 vsl| 1016 VCL_return c hash **** v1 vsl| 1016 VCL_call c HASH **** v1 vsl| 1016 VCL_return c lookup **** v1 vsl| 1016 VCL_call c MISS **** v1 vsl| 1016 VCL_return c fetch **** v1 vsl| 1016 Link c bereq 1017 fetch **** v1 vsl| 1016 Timestamp c Fetch: 1759088650.964121 0.736763 0.736763 **** v1 vsl| 1016 RespProtocol c HTTP/1.1 **** v1 vsl| 1016 RespStatus c 503 **** v1 vsl| 1016 RespReason c Backend fetch failed **** v1 vsl| 1016 RespHeader c Date: Sun, 28 Sep 2025 19:44:10 GMT **** v1 vsl| 1016 RespHeader c Server: Varnish **** v1 vsl| 1016 RespHeader c Content-Type: text/html; charset=utf-8 **** v1 vsl| 1016 RespHeader c Retry-After: 5 **** v1 vsl| 1016 RespHeader c X-Varnish: 1016 **** v1 vsl| 1016 RespHeader c Age: 0 **** v1 vsl| 1016 RespHeader c Via: 1.1 v1 (Varnish/trunk) **** v1 vsl| 1016 VCL_call c DELIVER **** v1 vsl| 1016 VCL_return c deliver **** v1 vsl| 1016 Timestamp c Process: 1759088650.964152 0.736794 0.000031 **** v1 vsl| 1016 Filters c **** v1 vsl| 1016 RespHeader c Content-Length: 281 **** v1 vsl| 1016 RespHeader c Connection: keep-alive **** v1 vsl| 1016 Timestamp c Resp: 1759088650.964227 0.736869 0.000074 **** v1 vsl| 1016 ReqAcct c 51 0 51 246 281 527 **** v1 vsl| 1016 End c **** dT 7.386 **** c2 rxhdr|HTTP/1.1 503 Backend fetch failed\r **** c2 rxhdr|Date: Sun, 28 Sep 2025 19:44:10 GMT\r **** c2 rxhdr|Server: Varnish\r **** c2 rxhdr|Content-Type: text/html; charset=utf-8\r **** c2 rxhdr|Retry-After: 5\r **** c2 rxhdr|X-Varnish: 1016\r **** c2 rxhdr|Age: 0\r **** c2 rxhdr|Via: 1.1 v1 (Varnish/trunk)\r **** c2 rxhdr|Content-Length: 281\r **** c2 rxhdr|Connection: keep-alive\r **** c2 rxhdr|\r **** c2 rxhdrlen = 246 **** c2 http[ 0] |HTTP/1.1 **** c2 http[ 1] |503 **** c2 http[ 2] |Backend fetch failed **** c2 http[ 3] |Date: Sun, 28 Sep 2025 19:44:10 GMT **** c2 http[ 4] |Server: Varnish **** c2 http[ 5] |Content-Type: text/html; charset=utf-8 **** c2 http[ 6] |Retry-After: 5 **** c2 http[ 7] |X-Varnish: 1016 **** c2 http[ 8] |Age: 0 **** c2 http[ 9] |Via: 1.1 v1 (Varnish/trunk) **** c2 http[10] |Content-Length: 281 **** c2 http[11] |Connection: keep-alive **** c2 c-l| **** c2 c-l| **** c2 c-l| **** c2 c-l|Backend fetch failed
**** c2 c-l|XID: 1017
**** c2 c-l|Varnish cache server
**** c2 c-l| **** c2 bodylen = 281 ** c2 === expect resp.status == 200 ---- c2 EXPECT resp.status (503) == "200" failed **** dT 7.387 * top Aborting execution, test failed * top RESETTING after ../../../../bin/varnishtest/tests/b00023.vtc ** s1 Waiting for server (4/-1) ** v1 Wait **** v1 CLI TX|panic.show **** dT 7.430 *** v1 CLI RX 300 **** v1 CLI RX|Child has not panicked or panic has been cleared **** dT 7.435 *** v1 debug|Info: manager stopping child *** v1 debug|Debug: Stopping Child **** dT 7.477 **** v1 vsl| 0 CLI - EOF on CLI connection, worker stops **** dT 8.494 *** v1 debug|Info: Child (2708) said Child dies *** v1 debug|Info: Child (2708) ended *** v1 debug|Debug: Child cleanup complete *** v1 debug|Info: manager dies **** v1 STDOUT EOF **** dT 8.518 ** v1 WAIT4 pid=2468 status=0x0000 (user 1.337971 sys 0.176926) * top TEST ../../../../bin/varnishtest/tests/b00023.vtc FAILED # top TEST ../../../../bin/varnishtest/tests/b00023.vtc FAILED (8.530) exit=2 FAIL tests/b00023.vtc (exit status: 2) Henceforth, whatever our philosopher says about Matter will apply to extension and to extension alone. It cannot be apprehended by sight, nor by hearing, nor by smell, nor by taste, for it is neither colour, nor sound, nor odour, nor juice. Neither can it be touched, for it is not a body, but it becomes corporeal on being blended with sensible qualities. And, in a later essay, he describes it as receiving all things and letting them depart again without retaining the slightest trace of their presence.483 Why then, it may be asked, if Plotinus meant extension, could he not say so at once, and save us all this trouble in hunting out his meaning? There were very good reasons why he should not. In the first place, he wished to express himself, so far as possible, in Aristotelian phraseology, and this was incompatible with the reduction of Matter to extension. In the next place, the idea of an infinite void had been already appropriated by the Epicureans, to whose system he was bitterly opposed. And, finally, the extension of ordinary327 experience had not the absolute generality which was needed in order to bring Matter into relation with that ultimate abstraction whence, like everything else, it has now to be derived. That the millionaire was genuine, “in person and not a caricature,” as Dick put it, was evident. Both the nurse, his relative, and his wife, were chatting with him as Jeff delivered the heavy packed ball made up of the gum. 233 "I guess not," said Landor, tolerantly, as he turned[Pg 106] his horse over to his orderly; "but, anyway," he added to Ellton, "we had a picnic—of a sort." Si, unable to think of anything better, went with him. The train had stopped on a switch, and seemed likely to rust fast to the rails, from the way other trains were going by in both directions. The bridge gang, under charge of a burly, red-faced young Englishman, was in the rear car, with their tools, equipments, bedding and cooking utensils. THE DEACON HAS SOME EXPERIENCES WITH THE QUADRUPED. "You are not within a mile of the truth. I know it. Look here: I believe that is Gen. Rosecrans's own cow. She's gone, and I got an order to look around for her. I've never seen her, but from the description given me I believe that's she. Who brought her here?" "Deacon, these brothers and sisters who have come here with me to-night are, like myself, deeply interested in the moral condition of the army, where we all have sons or kinsmen. Now, can't you sit right there and tell us of your observations and experiences, as a Christian man and father, from day to day, of every day that you were down there? Tell us everything, just as it happened each day, that we may be able to judge for ourselves." HAS AN ENCOUNTER WITH THE PROVOST-MARSHAL. "Wonder which one o' them is the 200th Injianny's?" said Si to Shorty. "And your mother, and Harry?" The daughter must be the girl who was talking to him now. She sat on a little stool by the fire, and had brought out some sewing. "Over at Grandturzel—can't see wot's burning from here. Git buckets and come!" These things, however, gave little concern to the worthy who commanded the Kentish division. Tyler, though an excellent blacksmith, possessed few of the qualities requisite for forming a good general. Provided there was no very sensible diminution in the number of his followers, he cared not a straw for the score or two who, after quarrelling, or perhaps fighting, withdrew in such disgust that they vowed rather to pay the full tax for ever than submit to the insolence of the rebels. One man could fight as well as another, reasoned he; and, provided he was obeyed, what mattered it by whom. Dick went and Tom came—it was sure to be all one in the end. But this burst of indignation soon passed away, and upon the suggestion of the prudent Sir Robert Hailes, he sent an evasive answer, with a command that the Commons should attend him at Windsor on the Sunday following. That it was a stratagem to gain entrance to the Tower, was the opinion of several, but, after much discussion, it was decided that the man should be admitted, and that the monk should be exhibited merely to intimidate the rebels, until the result of this promised communication should be known. HoME女同性恋cbcb ENTER NUMBET 0017