**** dT 0.000 * top TEST ./tests/r01562.vtc starting **** top extmacro def pwd=/root/VT/varnish-cache/bin/varnishtest **** top extmacro def date(...) **** top extmacro def string(...) **** top extmacro def localhost=192.168.60.180 **** top extmacro def bad_backend=192.168.60.180:63098 **** top extmacro def listen_addr=192.168.60.180:0 **** top extmacro def bad_ip=192.0.2.255 **** top extmacro def topbuild=/root/VT/varnish-cache **** top macro def testdir=/root/VT/varnish-cache/bin/varnishtest/./tests **** top macro def tmpdir=/root/VT/_vtest_tmp/vtc.70961.36ea03cd ** top === varnishtest "retrying a short client body read should not pa... * top VTEST retrying a short client body read should not panic varnish ** top === server s1 { ** s1 Starting server **** s1 macro def s1_addr=192.168.60.180 **** s1 macro def s1_port=63099 **** s1 macro def s1_sock=192.168.60.180:63099 * s1 Listen on 192.168.60.180:63099 ** top === server s2 { ** s2 Starting server **** s2 macro def s2_addr=192.168.60.180 **** s2 macro def s2_port=63100 **** s2 macro def s2_sock=192.168.60.180:63100 * s2 Listen on 192.168.60.180:63100 **** dT 0.001 ** top === varnish v1 -cliok "param.set vcc_allow_inline_c true" -vcl+b... ** s1 Started on 192.168.60.180:63099 (1 iterations) ** s2 Started on 192.168.60.180:63100 (1 iterations) **** dT 0.004 ** v1 Launch *** v1 CMD: cd ${pwd} && exec varnishd -d -n /root/VT/_vtest_tmp/vtc.70961.36ea03cd/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 -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -a '192.168.60.180:0' -M '192.168.60.180 63101' -P /root/VT/_vtest_tmp/vtc.70961.36ea03cd/v1/varnishd.pid -p vmod_path=/root/VT/varnish-cache/vmod/.libs *** v1 CMD: cd /root/VT/varnish-cache/bin/varnishtest && exec varnishd -d -n /root/VT/_vtest_tmp/vtc.70961.36ea03cd/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 -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -a '192.168.60.180:0' -M '192.168.60.180 63101' -P /root/VT/_vtest_tmp/vtc.70961.36ea03cd/v1/varnishd.pid -p vmod_path=/root/VT/varnish-cache/vmod/.libs *** v1 PID: 70977 **** v1 macro def v1_pid=70977 **** v1 macro def v1_name=/root/VT/_vtest_tmp/vtc.70961.36ea03cd/v1 **** dT 0.029 *** v1 debug|Debug: Version: varnish-trunk revision 5f0d0db795ddbdfad8a0103c0f48b06f78715004 *** v1 debug|Debug: Platform: FreeBSD,14.0-CURRENT,amd64,-jnone,-sdefault,-sdefault,-hcritbit *** v1 debug|200 314 *** v1 debug|----------------------------- *** v1 debug|Varnish Cache CLI 1.0 *** v1 debug|----------------------------- *** v1 debug|FreeBSD,14.0-CURRENT,amd64,-jnone,-sdefault,-sdefault,-hcritbit *** v1 debug|varnish-trunk revision 5f0d0db795ddbdfad8a0103c0f48b06f78715004 *** 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.132 **** v1 CLIPOLL 1 0x1 0x0 0x0 *** v1 CLI connection fd = 8 *** v1 CLI RX 107 **** v1 CLI RX|abuoceevwxtykeopmmldlljduohfpgxb **** v1 CLI RX| **** v1 CLI RX|Authentication required. **** dT 0.133 **** v1 CLI TX|auth 138ecd8fceae509444d1dffb3e16e806299f1829bcdd96aa059decb5e77cd279 **** dT 0.134 *** v1 CLI RX 200 **** v1 CLI RX|----------------------------- **** v1 CLI RX|Varnish Cache CLI 1.0 **** v1 CLI RX|----------------------------- **** v1 CLI RX|FreeBSD,14.0-CURRENT,amd64,-jnone,-sdefault,-sdefault,-hcritbit **** v1 CLI RX|varnish-trunk revision 5f0d0db795ddbdfad8a0103c0f48b06f78715004 **** 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|param.set vcc_allow_inline_c true **** dT 0.175 *** v1 CLI RX 200 ** v1 CLI 200 **** v1 CLI TX|vcl.inline vcl1 << %XJEIFLH|)Xspa8P **** v1 CLI TX|vcl 4.1; **** v1 CLI TX|backend s1 { .host = "192.168.60.180"; .port = "63099"; } **** v1 CLI TX|backend s2 { .host = "192.168.60.180"; .port = "63100"; } **** 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\tif (bereq.retries >= 1) { **** v1 CLI TX|\t\t\tset bereq.backend = s2; **** v1 CLI TX|\t\t} else { **** v1 CLI TX|\t\t\tset bereq.backend = s1; **** v1 CLI TX|\t\t} **** v1 CLI TX|\t} **** v1 CLI TX|\tsub vcl_backend_error { **** v1 CLI TX|\t\treturn (retry); **** v1 CLI TX|\t} **** v1 CLI TX| **** v1 CLI TX|%XJEIFLH|)Xspa8P **** dT 0.235 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.342 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.449 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.556 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.663 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.748 *** v1 CLI RX 200 **** v1 CLI RX|VCL compiled. **** v1 CLI TX|vcl.use vcl1 **** dT 0.771 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.790 *** v1 CLI RX 200 **** v1 CLI RX|VCL 'vcl1' now active ** v1 Start **** v1 CLI TX|start **** dT 0.835 *** v1 debug|Debug: Child (70987) Started **** dT 0.864 *** v1 debug|Child launched OK **** dT 0.866 *** v1 debug|Info: Child (70987) said Child starts *** v1 CLI RX 200 *** v1 wait-running **** v1 CLI TX|status **** dT 0.878 **** v1 vsl| 0 CLI - Rd vcl.load "vcl1" vcl_vcl1.1650029230.384401/vgc.so 1auto **** v1 vsl| 0 CLI - Wr 200 52 Loaded "vcl_vcl1.1650029230.384401/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=192.168.60.180:63103 **** v1 vsl| 0 Debug - sockopt: Setting SO_KEEPALIVE for a0=192.168.60.180:63103 **** v1 vsl| 0 Debug - sockopt: Setting SO_SNDTIMEO for a0=192.168.60.180:63103 **** v1 vsl| 0 Debug - sockopt: Setting SO_RCVTIMEO for a0=192.168.60.180:63103 **** v1 vsl| 0 Debug - sockopt: Setting TCP_NODELAY for a0=192.168.60.180:63103 **** v1 vsl| 0 Debug - sockopt: Setting TCP_KEEPIDLE for a0=192.168.60.180:63103 **** v1 vsl| 0 Debug - sockopt: Setting TCP_KEEPCNT for a0=192.168.60.180:63103 **** v1 vsl| 0 Debug - sockopt: Setting TCP_KEEPINTVL for a0=192.168.60.180:63103 **** v1 vsl| 0 Error - Kernel filtering: sock=3, errno=2 No such file or directory **** v1 vsl| 0 CLI - Wr 200 0 **** dT 0.908 *** v1 CLI RX 200 **** v1 CLI RX|Child in state running **** v1 CLI TX|debug.listen_address **** dT 0.950 *** v1 CLI RX 200 **** v1 CLI RX|a0 192.168.60.180 63103 **** v1 CLI TX|debug.xid 999 **** dT 0.985 **** v1 vsl| 0 CLI - Rd debug.listen_address **** v1 vsl| 0 CLI - Wr 200 24 a0 192.168.60.180 63103 **** dT 0.992 *** v1 CLI RX 200 **** v1 CLI RX|XID is 999 chunk 1 **** v1 CLI TX|debug.listen_address **** dT 1.034 *** v1 CLI RX 200 **** v1 CLI RX|a0 192.168.60.180 63103 ** v1 Listen on 192.168.60.180 63103 **** v1 macro def v1_addr=192.168.60.180 **** v1 macro def v1_port=63103 **** v1 macro def v1_sock=192.168.60.180:63103 **** v1 macro def v1_a0_addr=192.168.60.180 **** v1 macro def v1_a0_port=63103 **** v1 macro def v1_a0_sock=192.168.60.180:63103 ** top === varnish v1 -cliok "param.set debug +syncvsl" **** v1 CLI TX|param.set debug +syncvsl **** dT 1.076 *** v1 CLI RX 200 ** v1 CLI 200 ** top === client c1 { ** c1 Starting client ** c1 Waiting for client **** dT 1.077 ** c1 Started on 192.168.60.180:63103 (1 iterations) *** c1 Connect to 192.168.60.180:63103 **** dT 1.087 **** v1 vsl| 0 CLI - Rd debug.xid 999 **** v1 vsl| 0 CLI - Wr 200 18 XID is 999 chunk 1 **** v1 vsl| 0 CLI - Rd debug.listen_address **** v1 vsl| 0 CLI - Wr 200 24 a0 192.168.60.180 63103 **** v1 vsl| 1000 Begin c sess 0 HTTP/1 **** v1 vsl| 1000 SessOpen c 192.168.60.180 63107 a0 192.168.60.180 63103 1650029231.285645 22 **** v1 vsl| 1000 Debug c sockopt: Test confirmed SO_KEEPALIVE non heredity for a0=192.168.60.180:63103 **** v1 vsl| 1000 Debug c sockopt: Test confirmed SO_SNDTIMEO non heredity for a0=192.168.60.180:63103 **** v1 vsl| 1000 Debug c sockopt: Test confirmed TCP_NODELAY non heredity for a0=192.168.60.180:63103 **** v1 vsl| 1000 Debug c sockopt: SO_LINGER may be inherited for a0=192.168.60.180:63103 **** v1 vsl| 1000 Debug c sockopt: Setting SO_KEEPALIVE for a0=192.168.60.180:63103 **** v1 vsl| 1000 Debug c sockopt: Setting SO_SNDTIMEO for a0=192.168.60.180:63103 **** v1 vsl| 1000 Debug c sockopt: SO_RCVTIMEO may be inherited for a0=192.168.60.180:63103 **** v1 vsl| 1000 Debug c sockopt: Setting TCP_NODELAY for a0=192.168.60.180:63103 **** v1 vsl| 1000 Debug c sockopt: TCP_KEEPIDLE may be inherited for a0=192.168.60.180:63103 **** v1 vsl| 1000 Debug c sockopt: TCP_KEEPCNT may be inherited for a0=192.168.60.180:63103 **** v1 vsl| 1000 Debug c sockopt: TCP_KEEPINTVL may be inherited for a0=192.168.60.180:63103 **** dT 1.840 *** c1 connected fd 18 from 192.168.60.180 63107 to 192.168.60.180:63103 ** c1 === txreq -req "POST" -nolen -hdr "Content-Length: 10000" -bodyl... **** dT 1.841 **** c1 txreq|POST / HTTP/1.1\r **** c1 txreq|Content-Length: 10000\r **** c1 txreq|Host: 192.168.60.180\r **** c1 txreq|\r **** c1 txreq|!"#$%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_ **** c1 txreq|"#$%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_` **** c1 txreq|#$%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`a **** c1 txreq|$%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`ab **** c1 txreq|%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abc **** c1 txreq|&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcd **** c1 txreq|'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcde **** c1 txreq|()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdef **** c1 txreq|)*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefg **** c1 txreq|*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefgh **** c1 txreq|+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghi **** c1 txreq|,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghij **** c1 txreq|-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijk **** c1 txreq|./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijkl **** c1 txreq|/0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklm **** c1 txreq|0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmn **** c1 txreq|123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmno **** c1 txreq|23456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnop **** c1 txreq|3456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopq **** c1 txreq|456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqr **** c1 txreq|56789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrs **** c1 txreq|6789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrst **** c1 txreq|789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstu **** c1 txreq|89:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuv **** c1 txreq|9:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvw **** c1 txreq|:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwx **** c1 txreq|;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxy **** c1 txreq|<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz **** c1 txreq|=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{ **** c1 txreq|>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{| **** c1 txreq|?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|} **** c1 txreq|@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|}! **** c1 txreq|ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|}!" **** c1 txreq|BCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|}!"# **** c1 txreq|CDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|}!"#$ **** c1 txreq|DEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|}!"#$% **** c1 txreq|EFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|}!"#$%& **** c1 txreq|FGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|}!"#$%&' **** c1 txreq|GHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|}!"#$%&'( **** c1 txreq|HIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|}!"#$%&'() **** c1 txreq|IJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|}!"#$%&'()* **** c1 txreq|JKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|}!"#$%&'()*+ **** c1 txreq|KLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|}!"#$%&'()*+, **** c1 txreq|LMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|}!"#$%&'()*+,- **** c1 txreq|MNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|}!"#$%&'()*+,-. **** c1 txreq|NOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|}!"#$%&'()*+,-./ **** c1 txreq|OPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|}!"#$%&'()*+,-./0 **** c1 txreq|PQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|}!"#$%&'()*+,-./01 **** c1 txreq|QRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|}!"#$%&'()*+,-./012 **** c1 txreq|RSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|}!"#$%&'()*+,-./0123 **** c1 txreq|STUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|}!"#$%&'()*+,-./01234 **** c1 txreq|TUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|}!"#$%&'()*+,-./012345 **** c1 txreq|UVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|}!"#$%&'()*+,-./0123456 **** c1 txreq|VWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|}!"#$%&'()*+,-./01234567 **** c1 txreq|WXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|}!"#$%&'()*+,-./012345678 **** c1 txreq|XYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|}!"#$%&'()*+,-./0123456789 **** c1 txreq|YZ[\]^_`abcdefghijklmnopqrstuvwxyz{|}!"#$%&'()*+,-./0123456789: **** c1 txreq|Z[\]^_`abcdefghijklmnopqrstuvwxyz{|}!"#$%&'()*+,-./0123456789:; **** c1 txreq|[\]^_`abcdefghijklmnopqrstuvwxyz{|}!"#$%&'()*+,-./0123456789:;< **** c1 txreq|\]^_`abcdefghijklmnopqrstuvwxyz{|}!"#$%&'()*+,-./0123456789:;<= **** c1 txreq|]^_`abcdefghijklmnopqrstuvwxyz{|}!"#$%&'()*+,-./0123456789:;<=> **** c1 txreq|^_`abcdefghijklmnopqrstuvwxyz{|}!"#$%&'()*+,-./0123456789:;<=>? **** c1 txreq|_`abcdefghijklmnopqrstuvwxyz{|}!"#$%&'()*+,-./0123456789:;<=>?@ **** c1 txreq|`abcdefghijklmnopqrstuvwxyz{|}!"#$%&'()*+,-./0123456789:;<=>?@A **** c1 txreq|abcdefghijklmnopqrstuvwxyz{|}!"#$%&'()*+,-./0123456789:;<=>?@AB **** c1 txreq|bcdefghijklmnopqrstuvwxyz{|}!"#$%&'()*+,-./0123456789:;<=>?@ABC **** c1 txreq|cdefghijklmnopqrstuvwxyz{|}!"#$%&'()*+,-./0123456789:;<=>?@ABCD **** c1 txreq|defghijklmnopqrstuvwxyz{|}!"#$%&'()*+,-./0123456789:;<=>?@ABCDE **** c1 txreq|efghijklmnopqrstuvwxyz{|}!"#$%&'()*+,-./0123456789:;<=>?@ABCDEF **** c1 txreq|fghijklmnopqrstuvwxyz{|}!"#$%&'()*+,-./0123456789:;<=>?@ABCDEFG **** c1 txreq|ghijklmnopqrstuvwxyz{|}!"#$%&'()*+,-./0123456789:;<=>?@ABCDEFGH **** c1 txreq|hijklmnopqrstuvwxyz{|}!"#$%&'()*+,-./0123456789:;<=>?@ABCDEFGHI **** c1 txreq|ijklmnopqrstuvwxyz{|}!"#$%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJ **** c1 txreq|jklmnopqrstuvwxyz{|}!"#$%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJK **** c1 txreq|klmnopqrstuvwxyz{|}!"#$%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKL **** c1 txreq|lmnopqrstuvwxyz{|}!"#$%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLM **** c1 txreq|mnopqrstuvwxyz{|}!"#$%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMN **** c1 txreq|nopqrstuvwxyz{|}!"#$%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNO **** c1 txreq|opqrstuvwxyz{|}!"#$%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOP **** c1 txreq|pqrstuvwxyz{|}!"#$%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQ **** c1 txreq|qrstuvwxyz{|}!"#$%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQR **** c1 txreq|rstuvwxyz{|}!"#$%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRS **** c1 txreq|stuvwxyz{|}!"#$%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRST **** c1 txreq|tuvwxyz{|}!"#$%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTU **** c1 txreq|uvwxyz{|}!"#$%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUV **** c1 txreq|vwxyz{|}!"#$%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVW **** c1 txreq|wxyz{|}!"#$%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWX **** c1 txreq|xyz{|}!"#$%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXY **** c1 txreq|yz{|}!"#$%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ **** c1 txreq|z{|}!"#$%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[ **** c1 txreq|{|}!"#$%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\ **** c1 txreq||}!"#$%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\] **** c1 txreq|}!"#$%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^ **** c1 txreq|!"#$%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_ **** c1 txreq|"#$%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_` **** c1 txreq|#$%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`a **** c1 txreq|$%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`ab **** c1 txreq|%&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abc **** c1 txreq|&'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcd **** c1 txreq|'()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcde **** c1 txreq|()*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdef **** c1 txreq|)*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefg **** c1 txreq|*+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefgh **** c1 txreq|+,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghi **** c1 txreq|,-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghij **** c1 txreq|-./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijk **** c1 txreq|./0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijkl **** c1 txreq|/0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklm **** c1 txreq|0123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmn **** c1 txreq|123456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmno **** c1 txreq|23456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnop **** c1 txreq|3456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopq **** c1 txreq|456789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqr **** c1 txreq|56789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrs **** c1 txreq|6789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrst **** c1 txreq|789:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstu **** c1 txreq|89:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuv **** c1 txreq|9:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvw **** c1 txreq|:;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwx **** c1 txreq|;<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxy **** c1 txreq|<=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz **** c1 txreq|=>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{ **** c1 txreq|>?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{| **** c1 txreq|?@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|} **** c1 txreq|@ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|}! **** c1 txreq|ABCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|}!" **** c1 txreq|BCDEFGHIJKLMNOPQRSTUVWXYZ[\]^_`abcdefghijklmnopqrstuvwxyz{|}!"# **** c1 txreq| [...] (1871) *** c1 closing fd 18 ** c1 Ending ** top === delay .4 *** top delaying 0.4 second(s) **** dT 1.946 **** v1 vsl| 1000 SessClose c REM_CLOSE 0.764 **** v1 vsl| 1000 End c **** dT 2.267 ** top === server s1 { ** s1 Waiting for server (3/-1) **** dT 3.871 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1650029234 1.0 **** dT 6.974 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1650029237 1.0 **** dT 9.976 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1650029240 1.0 **** dT 12.978 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1650029243 1.0 **** dT 15.973 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1650029246 1.0 **** dT 18.975 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1650029249 1.0 **** dT 22.083 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1650029252 1.0 **** dT 25.079 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1650029255 1.0 **** dT 28.082 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1650029258 1.0 **** dT 31.086 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1650029261 1.0 **** dT 34.089 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1650029264 1.0 **** dT 37.190 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1650029267 1.0 **** dT 40.179 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1650029270 1.0 **** dT 43.288 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1650029273 1.0 **** dT 46.289 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1650029276 1.0 **** dT 49.289 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1650029279 1.0 **** dT 52.394 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1650029282 1.0 **** dT 55.391 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1650029285 1.0 **** dT 58.379 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1650029288 1.0 # top TEST ./tests/r01562.vtc TIMED OUT (kill -9) # top TEST ./tests/r01562.vtc FAILED (60.249) signal=9 FAIL tests/r01562.vtc (exit status: 2)