Page MenuHomePhabricator
Paste P13407

m00035.log
ActivePublic

Authored by ema on Nov 25 2020, 11:27 AM.
**** top 0.0 extmacro def pwd=/home/ema/wmf/varnish-6.0.7/bin/varnishtest
**** top 0.0 extmacro def localhost=127.0.0.1
**** top 0.0 extmacro def bad_backend=127.0.0.1 40849
**** top 0.0 extmacro def bad_ip=192.0.2.255
**** top 0.0 extmacro def topbuild=/home/ema/wmf/varnish-6.0.7
**** top 0.0 macro def testdir=/home/ema/wmf/varnish-6.0.7/bin/varnishtest/./tests
**** top 0.0 macro def tmpdir=/tmp/vtc.483963.5092bc36
* top 0.0 TEST ./tests/m00035.vtc starting
** top 0.0 === varnishtest "VMOD blob hex encode and decode"
* top 0.0 TEST VMOD blob hex encode and decode
** top 0.0 === varnish v1 -arg "-p workspace_client=256k" -vcl {
** v1 0.0 Launch
*** v1 0.0 CMD: cd ${pwd} && exec varnishd -d -n /tmp/vtc.483963.5092bc36/v1 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p sigsegv_handler=on -p thread_pool_min=10 -p debug=+vtc_mode -a '127.0.0.1:0' -M '127.0.0.1 45743' -P /tmp/vtc.483963.5092bc36/v1/varnishd.pid -p vmod_path=/home/ema/wmf/varnish-6.0.7/lib/libvmod_std/.libs:/home/ema/wmf/varnish-6.0.7/lib/libvmod_debug/.libs:/home/ema/wmf/varnish-6.0.7/lib/libvmod_directors/.libs:/home/ema/wmf/varnish-6.0.7/lib/libvmod_purge/.libs:/home/ema/wmf/varnish-6.0.7/lib/libvmod_vtc/.libs:/home/ema/wmf/varnish-6.0.7/lib/libvmod_blob/.libs:/home/ema/wmf/varnish-6.0.7/lib/libvmod_unix/.libs:/home/ema/wmf/varnish-6.0.7/lib/libvmod_proxy/.libs -p workspace_client=256k
*** v1 0.0 CMD: cd /home/ema/wmf/varnish-6.0.7/bin/varnishtest && exec varnishd -d -n /tmp/vtc.483963.5092bc36/v1 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p sigsegv_handler=on -p thread_pool_min=10 -p debug=+vtc_mode -a '127.0.0.1:0' -M '127.0.0.1 45743' -P /tmp/vtc.483963.5092bc36/v1/varnishd.pid -p vmod_path=/home/ema/wmf/varnish-6.0.7/lib/libvmod_std/.libs:/home/ema/wmf/varnish-6.0.7/lib/libvmod_debug/.libs:/home/ema/wmf/varnish-6.0.7/lib/libvmod_directors/.libs:/home/ema/wmf/varnish-6.0.7/lib/libvmod_purge/.libs:/home/ema/wmf/varnish-6.0.7/lib/libvmod_vtc/.libs:/home/ema/wmf/varnish-6.0.7/lib/libvmod_blob/.libs:/home/ema/wmf/varnish-6.0.7/lib/libvmod_unix/.libs:/home/ema/wmf/varnish-6.0.7/lib/libvmod_proxy/.libs -p workspace_client=256k
*** v1 0.0 PID: 483984
**** v1 0.0 macro def v1_pid=483984
**** v1 0.0 macro def v1_name=/tmp/vtc.483963.5092bc36/v1
*** v1 0.0 debug|Debug: Version: varnish-6.0.7 revision 525d371e3ea0e0c38edd7baf0f80dc226560f26e
*** v1 0.0 debug|Debug: Platform: Linux,5.9.0-1-amd64,x86_64,-jnone,-sdefault,-sdefault,-hcritbit
*** v1 0.0 debug|200 314
*** v1 0.0 debug|-----------------------------
*** v1 0.0 debug|Varnish Cache CLI 1.0
*** v1 0.0 debug|-----------------------------
*** v1 0.0 debug|Linux,5.9.0-1-amd64,x86_64,-jnone,-sdefault,-sdefault,-hcritbit
*** v1 0.0 debug|varnish-6.0.7 revision 525d371e3ea0e0c38edd7baf0f80dc226560f26e
*** v1 0.0 debug|
*** v1 0.0 debug|Type 'help' for command list.
*** v1 0.0 debug|Type 'quit' to close CLI session.
*** v1 0.0 debug|Type 'start' to launch worker process.
*** v1 0.0 debug|
**** v1 0.1 CLIPOLL 1 0x1 0x0
*** v1 0.1 CLI connection fd = 5
*** v1 0.1 CLI RX 107
**** v1 0.1 CLI RX|ngiwwrooobyvyjxpasdxoruxtydpzqlj
**** v1 0.1 CLI RX|
**** v1 0.1 CLI RX|Authentication required.
**** v1 0.1 CLI TX|auth d69145b677cd218457f086fc9a60826d22cb3ffa984ed0d4f6456dc940e66919
*** v1 0.1 CLI RX 200
**** v1 0.1 CLI RX|-----------------------------
**** v1 0.1 CLI RX|Varnish Cache CLI 1.0
**** v1 0.1 CLI RX|-----------------------------
**** v1 0.1 CLI RX|Linux,5.9.0-1-amd64,x86_64,-jnone,-sdefault,-sdefault,-hcritbit
**** v1 0.1 CLI RX|varnish-6.0.7 revision 525d371e3ea0e0c38edd7baf0f80dc226560f26e
**** v1 0.1 CLI RX|
**** v1 0.1 CLI RX|Type 'help' for command list.
**** v1 0.1 CLI RX|Type 'quit' to close CLI session.
**** v1 0.1 CLI RX|Type 'start' to launch worker process.
**** v1 0.1 CLI TX|vcl.inline vcl1 << %XJEIFLH|)Xspa8P
**** v1 0.1 CLI TX|vcl 4.1;
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\timport blob;
**** v1 0.1 CLI TX|\timport std;
**** v1 0.1 CLI TX|\tbackend b { .host = "192.0.2.255"; }
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\tsub vcl_recv {
**** v1 0.1 CLI TX|\t return(synth(200));
**** v1 0.1 CLI TX|\t}
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\tsub vcl_synth {
**** v1 0.1 CLI TX|\t set req.http.pangram = "The quick brown fox jumps over the lazy dog";
**** v1 0.1 CLI TX|\t set req.http.hobbes =
**** v1 0.1 CLI TX|{"Man is distinguished, not only by his reason, but by this singular passion from other animals, which is a lust of the mind, that by a perseverance of delight in the continued and indefatigable generation of knowledge, exceeds the short vehemence of any carnal pleasure."};
**** v1 0.1 CLI TX|\t # base64 encoding of all 256 byte values in ascending order
**** v1 0.1 CLI TX|\t set req.http.b64all =
**** v1 0.1 CLI TX|{"AAECAwQFBgcICQoLDA0ODxAREhMUFRYXGBkaGxwdHh8gISIjJCUmJygpKissLS4vMDEyMzQ1Njc4OTo7PD0+P0BBQkNERUZHSElKS0xNTk9QUVJTVFVWV1hZWltcXV5fYGFiY2RlZmdoaWprbG1ub3BxcnN0dXZ3eHl6e3x9fn+AgYKDhIWGh4iJiouMjY6PkJGSk5SVlpeYmZqbnJ2en6ChoqOkpaanqKmqq6ytrq+wsbKztLW2t7i5uru8vb6/wMHCw8TFxsfIycrLzM3Oz9DR0tPU1dbX2Nna29zd3t/g4eLj5OXm5+jp6uvs7e7v8PHy8/T19vf4+fr7/P3+/w=="};
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set resp.http.hex =
**** v1 0.1 CLI TX|\t blob.encode(HEX, blob=blob.decode(IDENTITY,
**** v1 0.1 CLI TX|\t\t\t\t\t\tencoded=req.http.pangram));
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set resp.http.hexlc =
**** v1 0.1 CLI TX|\t blob.encode(HEX, LOWER,
**** v1 0.1 CLI TX|\t\t\t blob.decode(IDENTITY, encoded=req.http.pangram));
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set resp.http.hexuc =
**** v1 0.1 CLI TX|\t blob.encode(HEX, UPPER,
**** v1 0.1 CLI TX|\t\t\t blob.decode(IDENTITY, encoded=req.http.pangram));
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set resp.http.hobbes =
**** v1 0.1 CLI TX|\t blob.encode(HEX, blob=blob.decode(IDENTITY,
**** v1 0.1 CLI TX|\t\t\t\t\t\tencoded=req.http.hobbes));
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set resp.http.hobbeslc =
**** v1 0.1 CLI TX|\t blob.encode(HEX, LOWER, blob.decode(IDENTITY,
**** v1 0.1 CLI TX|\t\t\t\t\t\t encoded=req.http.hobbes));
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set resp.http.hobbesuc =
**** v1 0.1 CLI TX|\t blob.encode(HEX, UPPER, blob.decode(IDENTITY,
**** v1 0.1 CLI TX|\t\t\t\t\t\t encoded=req.http.hobbes));
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set resp.http.all =
**** v1 0.1 CLI TX|\t blob.encode(HEX, blob=blob.decode(BASE64,
**** v1 0.1 CLI TX|\t\t\t\t\t\tencoded=req.http.b64all));
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set resp.http.all-lc =
**** v1 0.1 CLI TX|\t blob.encode(HEX, LOWER, blob.decode(BASE64,
**** v1 0.1 CLI TX|\t\t\t\t\t\t encoded=req.http.b64all));
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set resp.http.all-uc =
**** v1 0.1 CLI TX|\t blob.encode(HEX, UPPER, blob.decode(BASE64,
**** v1 0.1 CLI TX|\t\t\t\t\t\t encoded=req.http.b64all));
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set resp.http.empty =
**** v1 0.1 CLI TX|\t blob.encode(HEX, blob=blob.decode(IDENTITY, encoded=""));
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set resp.http.empty-lc =
**** v1 0.1 CLI TX|\t blob.encode(HEX, LOWER, blob.decode(IDENTITY, encoded=""));
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set resp.http.empty-uc =
**** v1 0.1 CLI TX|\t blob.encode(HEX, UPPER, blob.decode(IDENTITY, encoded=""));
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set resp.http.hexlcparam =
**** v1 0.1 CLI TX|\t blob.encode(blob=blob.decode(IDENTITY, encoded=req.http.pangram),
**** v1 0.1 CLI TX|\t\t\t case=LOWER, encoding=HEX);
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set resp.http.hexucparam =
**** v1 0.1 CLI TX|\t blob.encode(blob=blob.decode(IDENTITY, encoded=req.http.pangram),
**** v1 0.1 CLI TX|\t\t\t case=UPPER, encoding=HEX);
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set req.http.hexucfoobar = "666F6F206261722062617A2071757578";
**** v1 0.1 CLI TX|\t set req.http.hexlcfoobar = std.tolower(req.http.hexucfoobar);
**** v1 0.1 CLI TX|\t # Hobbes quotation in hex
**** v1 0.1 CLI TX|\t set req.http.hexhobbeslc = "4d616e2069732064697374696e677569736865642c206e6f74206f6e6c792062792068697320726561736f6e2c2062757420627920746869732073696e67756c61722070617373696f6e2066726f6d206f7468657220616e696d616c732c2077686963682069732061206c757374206f6620746865206d696e642c20746861742062792061207065727365766572616e6365206f662064656c6967687420696e2074686520636f6e74696e75656420616e6420696e6465666174696761626c652067656e65726174696f6e206f66206b6e6f776c656467652c2065786365656473207468652073686f727420766568656d656e6365206f6620616e79206361726e616c20706c6561737572652e";
**** v1 0.1 CLI TX|\t set req.http.hexhobbesuc = std.toupper(req.http.hexhobbeslc);
**** v1 0.1 CLI TX|\t set req.http.hexalluc = "000102030405060708090A0B0C0D0E0F101112131415161718191A1B1C1D1E1F202122232425262728292A2B2C2D2E2F303132333435363738393A3B3C3D3E3F404142434445464748494A4B4C4D4E4F505152535455565758595A5B5C5D5E5F606162636465666768696A6B6C6D6E6F707172737475767778797A7B7C7D7E7F808182838485868788898A8B8C8D8E8F909192939495969798999A9B9C9D9E9FA0A1A2A3A4A5A6A7A8A9AAABACADAEAFB0B1B2B3B4B5B6B7B8B9BABBBCBDBEBFC0C1C2C3C4C5C6C7C8C9CACBCCCDCECFD0D1D2D3D4D5D6D7D8D9DADBDCDDDEDFE0E1E2E3E4E5E6E7E8E9EAEBECEDEEEFF0F1F2F3F4F5F6F7F8F9FAFBFCFDFEFF";
**** v1 0.1 CLI TX|\t set req.http.hexalllc = std.tolower(req.http.hexalluc);
**** v1 0.1 CLI TX|\t set req.http.hexallucodd = regsub(req.http.hexalluc, "^0", "");
**** v1 0.1 CLI TX|\t set req.http.hexalllcodd = regsub(req.http.hexalllc, "^0", "");
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set resp.http.decuc =
**** v1 0.1 CLI TX|\t blob.encode(IDENTITY,
**** v1 0.1 CLI TX|\t\t\t blob=blob.decode(HEX, encoded=req.http.hexucfoobar));
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set resp.http.declc =
**** v1 0.1 CLI TX|\t blob.encode(IDENTITY,
**** v1 0.1 CLI TX|\t\t\t blob=blob.decode(HEX, encoded=req.http.hexlcfoobar));
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set resp.http.dechobbesuc =
**** v1 0.1 CLI TX|\t blob.encode(IDENTITY,
**** v1 0.1 CLI TX|\t\t\t blob=blob.decode(HEX, encoded=req.http.hexhobbesuc));
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set resp.http.dechobbeslc =
**** v1 0.1 CLI TX|\t blob.encode(IDENTITY,
**** v1 0.1 CLI TX|\t\t\t blob=blob.decode(HEX, encoded=req.http.hexhobbeslc));
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set resp.http.decalluc =
**** v1 0.1 CLI TX|\t blob.encode(BASE64, blob=blob.decode(HEX,
**** v1 0.1 CLI TX|\t\t\t\t\t\t encoded=req.http.hexalluc));
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set resp.http.decalllc =
**** v1 0.1 CLI TX|\t blob.encode(BASE64, blob=blob.decode(HEX,
**** v1 0.1 CLI TX|\t\t\t\t\t\t encoded=req.http.hexalllc));
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set resp.http.decallucodd =
**** v1 0.1 CLI TX|\t blob.encode(BASE64,
**** v1 0.1 CLI TX|\t\t\t blob=blob.decode(HEX, encoded=req.http.hexallucodd));
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set resp.http.decalllcodd =
**** v1 0.1 CLI TX|\t blob.encode(BASE64,
**** v1 0.1 CLI TX|\t\t\t blob=blob.decode(HEX, encoded=req.http.hexalllcodd));
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set resp.http.decempty =
**** v1 0.1 CLI TX|\t blob.encode(IDENTITY, blob=blob.decode(HEX, encoded=""));
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set resp.http.decemptybyte =
**** v1 0.1 CLI TX|\t blob.encode(IDENTITY, blob=blob.decode(HEX, encoded="00"));
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set resp.http.decemptynibble =
**** v1 0.1 CLI TX|\t blob.encode(IDENTITY, blob=blob.decode(HEX, encoded="0"));
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set resp.http.decemptypieces =
**** v1 0.1 CLI TX|\t blob.encode(IDENTITY,
**** v1 0.1 CLI TX|\t\t\t blob=blob.decode(HEX, encoded=req.http.unset + ""
**** v1 0.1 CLI TX|\t\t\t\t\t\t\t+ req.http.unset + ""));
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set req.http.part1 = "666";
**** v1 0.1 CLI TX|\t set resp.http.dec2pieces =
**** v1 0.1 CLI TX|\t blob.encode(IDENTITY, blob=blob.decode(HEX,
**** v1 0.1 CLI TX|\t\t\t encoded=req.http.part1 +
**** v1 0.1 CLI TX|\t\t\t\t "F6F206261722062617A2071757578"));
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set req.http.part2 = "57578";
**** v1 0.1 CLI TX|\t set resp.http.dec3param =
**** v1 0.1 CLI TX|\t blob.encode(blob=blob.decode(encoded=req.http.part1 +
**** v1 0.1 CLI TX|\t\t\t "F6F206261722062617A20717" + req.http.part2,
**** v1 0.1 CLI TX|\t\t\t decoding=HEX),
**** v1 0.1 CLI TX|\t\t\t encoding=IDENTITY);
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set resp.http.dec3pieces =
**** v1 0.1 CLI TX|\t blob.encode(IDENTITY, blob=blob.decode(HEX,
**** v1 0.1 CLI TX|\t\t\t encoded=req.http.part1 +
**** v1 0.1 CLI TX|\t\t\t "F6F206261722062617A20717" + req.http.part2));
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|\t set resp.http.decmanypieces =
**** v1 0.1 CLI TX|\t blob.encode(IDENTITY, blob=blob.decode(HEX, encoded=
**** v1 0.1 CLI TX|\t\t\t "" + req.http.unset
**** v1 0.1 CLI TX|\t\t\t + req.http.part1 + req.http.unset + ""
**** v1 0.1 CLI TX|\t\t\t + req.http.unset + "" + "F6F206261722062617A20717"
**** v1 0.1 CLI TX|\t\t\t + "" + req.http.unset + req.http.part2
**** v1 0.1 CLI TX|\t\t\t + req.http.unset + "" + req.http.unset));
**** v1 0.1 CLI TX|\t}
**** v1 0.1 CLI TX|
**** v1 0.1 CLI TX|%XJEIFLH|)Xspa8P
*** v1 0.2 vsl|No VSL chunk found (child not started ?)
*** v1 0.3 vsl|No VSL chunk found (child not started ?)
*** v1 0.4 CLI RX 200
**** v1 0.4 CLI RX|VCL compiled.
**** v1 0.4 CLI TX|vcl.use vcl1
*** v1 0.4 CLI RX 200
** v1 0.4 Start
**** v1 0.4 CLI TX|start
*** v1 0.4 debug|Debug: Child (484139) Started
*** v1 0.4 CLI RX 200
*** v1 0.4 wait-running
**** v1 0.4 CLI TX|status
*** v1 0.4 debug|Info: Child (484139) said Child starts
*** v1 0.4 CLI RX 200
**** v1 0.4 CLI RX|Child in state running
**** v1 0.4 CLI TX|debug.listen_address
*** v1 0.4 CLI RX 200
**** v1 0.4 CLI RX|127.0.0.1 38673
**** v1 0.4 CLI TX|debug.xid 999
*** v1 0.4 CLI RX 200
**** v1 0.4 CLI RX|XID is 999
**** v1 0.4 CLI TX|debug.listen_address
*** v1 0.4 CLI RX 200
**** v1 0.4 CLI RX|127.0.0.1 38673
** v1 0.4 Listen on 127.0.0.1 38673
**** v1 0.4 macro def v1_addr=127.0.0.1
**** v1 0.4 macro def v1_port=38673
**** v1 0.4 macro def v1_sock=127.0.0.1 38673
**** v1 0.4 vsl| 0 CLI - Rd vcl.load "vcl1" vcl_vcl1.1606303308.685328/vgc.so 1auto
**** v1 0.4 vsl| 0 CLI - Wr 200 52 Loaded "vcl_vcl1.1606303308.685328/vgc.so" as "vcl1"
**** v1 0.4 vsl| 0 CLI - Rd vcl.use "vcl1"
**** v1 0.4 vsl| 0 CLI - Wr 200 0
**** v1 0.4 vsl| 0 CLI - Rd start
**** v1 0.4 vsl| 0 CLI - Wr 200 0
**** v1 0.4 vsl| 0 CLI - Rd debug.listen_address
**** v1 0.4 vsl| 0 CLI - Wr 200 16 127.0.0.1 38673
**** v1 0.4 vsl| 0 CLI - Rd debug.xid 999
**** v1 0.4 vsl| 0 CLI - Wr 200 10 XID is 999
**** v1 0.4 vsl| 0 CLI - Rd debug.listen_address
**** v1 0.4 vsl| 0 CLI - Wr 200 16 127.0.0.1 38673
** top 0.5 === client c1 {
** c1 0.5 Starting client
** c1 0.5 Waiting for client
*** c1 0.5 Connect to 127.0.0.1 38673
*** c1 0.5 connected fd 15 from 127.0.0.1 57998 to 127.0.0.1 38673
** c1 0.5 === txreq -url "/"
**** c1 0.5 txreq|GET / HTTP/1.1\r
**** c1 0.5 txreq|Host: 127.0.0.1\r
**** c1 0.5 txreq|\r
** c1 0.5 === rxresp
---- c1 0.5 HTTP rx EOF (fd:15 read: Success) 1
*** v1 0.5 debug|Error: Child (484139) died signal=6
* top 0.5 RESETTING after ./tests/m00035.vtc
** v1 0.5 Wait
**** v1 0.5 CLI TX|panic.clear
*** v1 0.5 debug|Error: Child (484139) Panic at: Wed, 25 Nov 2020 11:21:49 GMT
*** v1 0.5 debug|Wrong turn at cache/cache_main.c:284:
*** v1 0.5 debug|Signal 11 (Segmentation fault) received at 0x7fbda0623fe8 si_code 2
*** v1 0.5 debug|THIS PROBABLY IS A STACK OVERFLOW - check thread_pool_stack parameter
*** v1 0.5 debug|version = varnish-6.0.7 revision 525d371e3ea0e0c38edd7baf0f80dc226560f26e, vrt api = 7.1
*** v1 0.5 debug|ident = Linux,5.9.0-1-amd64,x86_64,-jnone,-sdefault,-sdefault,-hcritbit,epoll
*** v1 0.5 debug|now = 1364723.999235 (mono), 1606303309.071339 (real)
*** v1 0.5 debug|Backtrace:
*** v1 0.5 debug| 0x559deafe7d23: varnishd(+0x4dd23) [0x559deafe7d23]
*** v1 0.5 debug| 0x559deb053374: varnishd(VAS_Fail+0x14) [0x559deb053374]
*** v1 0.5 debug| 0x559deafe2dc5: varnishd(+0x48dc5) [0x559deafe2dc5]
*** v1 0.5 debug| 0x7fbda0a88140: /lib/x86_64-linux-gnu/libpthread.so.0(+0x14140) [0x7fbda0a88140]
*** v1 0.5 debug| 0x7fbd9e35604c: [0x7fbd9e35604c]
*** v1 0.5 debug|thread = (cache-worker)
*** v1 0.5 debug|thr.req = 0x7fbd95409220 {
*** v1 0.5 debug| vxid = 1001, transport = HTTP/1 {
*** v1 0.5 debug| state = HTTP1::Proc
*** v1 0.5 debug| }
*** v1 0.5 debug| step = R_STP_SYNTH,
*** v1 0.5 debug| req_body = R_BODY_NONE,
*** v1 0.5 debug| err_code = 200, err_reason = OK,
*** v1 0.5 debug| restarts = 0, esi_level = 0,
*** v1 0.5 debug| sp = 0x7fbd95400020 {
*** v1 0.5 debug| fd = 21, vxid = 1000,
*** v1 0.5 debug| t_open = 1606303309.070740,
*** v1 0.5 debug| t_idle = 1606303309.070740,
*** v1 0.5 debug| ws = 0x7fbd95400060 {
*** v1 0.5 debug| id = \"ses\",
*** v1 0.5 debug| {s, f, r, e} = {0x7fbd954000a0, +96, (nil), +344},
*** v1 0.5 debug| },
*** v1 0.5 debug| transport = HTTP/1 {
*** v1 0.5 debug| state = HTTP1::Proc
*** v1 0.5 debug| }
*** v1 0.5 debug| client = 127.0.0.1 57998 127.0.0.1:38673,
*** v1 0.5 debug| },
*** v1 0.5 debug| worker = 0x7fbda062d580 {
*** v1 0.5 debug| ws = 0x7fbda062d628 {
*** v1 0.5 debug| id = \"wrk\",
*** v1 0.5 debug| {s, f, r, e} = {0x7fbda062cb60, +0, (nil), +2040},
*** v1 0.5 debug| },
*** v1 0.5 debug| VCL::method = inside SYNTH,
*** v1 0.5 debug| VCL::return = 0x0,
*** v1 0.5 debug| VCL::methods = {RECV, HASH, SYNTH},
*** v1 0.5 debug| },
*** v1 0.5 debug| ws = 0x7fbd95409370 {
*** v1 0.5 debug| id = \"req\",
*** v1 0.5 debug| {s, f, r, e} = {0x7fbd9540b2a8, +13672, (nil), +253776},
*** v1 0.5 debug| },
*** v1 0.5 debug| http_conn = 0x7fbd9540b248 {
*** v1 0.5 debug| fd = 21 (@0x7fbd95400044),
*** v1 0.5 debug| doclose = NULL,
*** v1 0.5 debug| ws = 0x7fbd95409370 {
*** v1 0.5 debug| [Already dumped, see above]
*** v1 0.5 debug| },
*** v1 0.5 debug| {rxbuf_b, rxbuf_e} = {0x7fbd9540b2a8, 0x7fbd9540b2cb},
*** v1 0.5 debug| {pipeline_b, pipeline_e} = {(nil), (nil)},
*** v1 0.5 debug| content_length = -1,
*** v1 0.5 debug| body_status = none,
*** v1 0.5 debug| first_byte_timeout = 0.000000,
*** v1 0.5 debug| between_bytes_timeout = 0.000000,
*** v1 0.5 debug| },
*** v1 0.5 debug| http[req] = 0x7fbd95409410 {
*** v1 0.5 debug| ws = 0x7fbd95409370 {
*** v1 0.5 debug| [Already dumped, see above]
*** v1 0.5 debug| },
*** v1 0.5 debug| hdrs {
*** v1 0.5 debug| \"GET\",
*** v1 0.5 debug| \"/\",
*** v1 0.5 debug| \"HTTP/1.1\",
*** v1 0.5 debug| \"Host: 127.0.0.1\",
*** v1 0.5 debug| \"X-Forwarded-For: 127.0.0.1\",
*** v1 0.5 debug| \"pangram: The quick brown fox jumps over the lazy dog\",
*** v1 0.5 debug| \"hobbes: Man is distinguished, not only by his reason, but by this singular passion from other animals, which is a lust of the mind, that by a perseverance of delight in the continued and indefatigable generation of knowledge, exceeds the short vehemence of any carnal pleasure.\",
*** v1 0.5 debug| \"b64all: AAECAwQFBgcICQoLDA0ODxAREhMUFRYXGBkaGxwdHh8gISIjJCUmJygpKissLS4vMDEyMzQ1Njc4OTo7PD0+P0BBQkNERUZHSElKS0xNTk9QUVJTVFVWV1hZWltcXV5fYGFiY2RlZmdoaWprbG1ub3BxcnN0dXZ3eHl6e3x9fn+AgYKDhIWGh4iJiouMjY6PkJGSk5SVlpeYmZqbnJ2en6ChoqOkpaanqKmqq6ytrq+wsbKztLW2t7i5uru8vb6/wMHCw8TFxsfIycrLzM3Oz9DR0tPU1dbX2Nna29zd3t/g4eLj5OXm5+jp6uvs7e7v8PHy8/T19vf4+fr7/P3+/w==\",
*** v1 0.5 debug| \"hexucfoobar: 666F6F206261722062617A2071757578\",
*** v1 0.5 debug| \"hexlcfoobar: 666f6f206261722062617a2071757578\",
*** v1 0.5 debug| \"hexhobbeslc: 4d616e2069732064697374696e677569736865642c206e6f74206f6e6c792062792068697320726561736f6e2c2062757420627920746869732073696e67756c61722070617373696f6e2066726f6d206f7468657220616e696d616c732c2077686963682069732061206c757374206f6620746865206d696e642c20746861742062792061207065727365766572616e6365206f662064656c6967687420696e2074686520636f6e74696e75656420616e6420696e6465666174696761626c652067656e65726174696f6e206f66206b6e6f776c656467652c2065786365656473207468652073686f727420766568656d656e6365206f6620616e79206361726e616c20706c6561737572652e\",
*** v1 0.5 debug| \"hexhobbesuc: 4D616E2069732064697374696E677569736865642C206E6F74206F6E6C792062792068697320726561736F6E2C2062757420627920746869732073696E67756C61722070617373696F6E2066726F6D206F7468657220616E696D616C732C2077686963682069732061206C757374206F6620746865206D696E642C20746861742062792061207065727365766572616E6365206F662064656C6967687420696E2074686520636F6E74696E75656420616E6420696E6465666174696761626C652067656E65726174696F6E206F66206B6E6F776C656467652C2065786365656473207468652073686F727420766568656D656E6365206F6620616E79206361726E616C20706C6561737572652E\",
*** v1 0.5 debug| \"hexalluc: 000102030405060708090A0B0C0D0E0F101112131415161718191A1B1C1D1E1F202122232425262728292A2B2C2D2E2F303132333435363738393A3B3C3D3E3F404142434445464748494A4B4C4D4E4F505152535455565758595A5B5C5D5E5F606162636465666768696A6B6C6D6E6F707172737475767778797A7B7C7D7E7F808182838485868788898A8B8C8D8E8F909192939495969798999A9B9C9D9E9FA0A1A2A3A4A5A6A7A8A9AAABACADAEAFB0B1B2B3B4B5B6B7B8B9BABBBCBDBEBFC0C1C2C3C4C5C6C7C8C9CACBCCCDCECFD0D1D2D3D4D5D6D7D8D9DADBDCDDDEDFE0E1E2E3E4E5E6E7E8E9EAEBECEDEEEFF0F1F2F3F4F5F6F7F8F9FAFBFCFDFEFF\",
*** v1 0.5 debug| \"hexalllc: 000102030405060708090a0b0c0d0e0f101112131415161718191a1b1c1d1e1f202122232425262728292a2b2c2d2e2f303132333435363738393a3b3c3d3e3f404142434445464748494a4b4c4d4e4f505152535455565758595a5b5c5d5e5f606162636465666768696a6b6c6d6e6f707172737475767778797a7b7c7d7e7f808182838485868788898a8b8c8d8e8f909192939495969798999a9b9c9d9e9fa0a1a2a3a4a5a6a7a8a9aaabacadaeafb0b1b2b3b4b5b6b7b8b9babbbcbdbebfc0c1c2c3c4c5c6c7c8c9cacbcccdcecfd0d1d2d3d4d5d6d7d8d9dadbdcdddedfe0e1e2e3e4e5e6e7e8e9eaebecedeeeff0f1f2f3f4f5f6f7f8f9fafbfcfdfeff\",
*** v1 0.5 debug| },
*** v1 0.5 debug| },
*** v1 0.5 debug| http[resp] = 0x7fbd95409d00 {
*** v1 0.5 debug| ws = 0x7fbd95409370 {
*** v1 0.5 debug| [Already dumped, see above]
*** v1 0.5 debug| },
*** v1 0.5 debug| hdrs {
*** v1 0.5 debug| \"HTTP/1.1\",
*** v1 0.5 debug| \"200\",
*** v1 0.5 debug| \"OK\",
*** v1 0.5 debug| \"Date: Wed, 25 Nov 2020 11:21:49 GMT\",
*** v1 0.5 debug| \"Server: Varnish\",
*** v1 0.5 debug| \"X-Varnish: 1001\",
*** v1 0.5 debug| \"hex: 54686520717569636b2062726f776e20666f78206a756d7073206f76657220746865206c617a7920646f67\",
*** v1 0.5 debug| \"hexlc: 54686520717569636b2062726f776e20666f78206a756d7073206f76657220746865206c617a7920646f67\",
*** v1 0.5 debug| \"hexuc: 54686520717569636B2062726F776E20666F78206A756D7073206F76657220746865206C617A7920646F67\",
*** v1 0.5 debug| \"hobbes: 4d616e2069732064697374696e677569736865642c206e6f74206f6e6c792062792068697320726561736f6e2c2062757420627920746869732073696e67756c61722070617373696f6e2066726f6d206f7468657220616e696d616c732c2077686963682069732061206c757374206f6620746865206d696e642c20746861742062792061207065727365766572616e6365206f662064656c6967687420696e2074686520636f6e74696e75656420616e6420696e6465666174696761626c652067656e65726174696f6e206f66206b6e6f776c656467652c2065786365656473207468652073686f727420766568656d656e6365206f6620616e79206361726e616c20706c6561737572652e\",
*** v1 0.5 debug| \"hobbeslc: 4d616e2069732064697374696e677569736865642c206e6f74206f6e6c792062792068697320726561736f6e2c2062757420627920746869732073696e67756c61722070617373696f6e2066726f6d206f7468657220616e696d616c732c2077686963682069732061206c757374206f6620746865206d696e642c20746861742062792061207065727365766572616e6365206f662064656c6967687420696e2074686520636f6e74696e75656420616e6420696e6465666174696761626c652067656e65726174696f6e206f66206b6e6f776c656467652c2065786365656473207468652073686f727420766568656d656e6365206f6620616e79206361726e616c20706c6561737572652e\",
*** v1 0.5 debug| \"hobbesuc: 4D616E2069732064697374696E677569736865642C206E6F74206F6E6C792062792068697320726561736F6E2C2062757420627920746869732073696E67756C61722070617373696F6E2066726F6D206F7468657220616E696D616C732C2077686963682069732061206C757374206F6620746865206D696E642C20746861742062792061207065727365766572616E6365206F662064656C6967687420696E2074686520636F6E74696E75656420616E6420696E6465666174696761626C652067656E65726174696F6E206F66206B6E6F776C656467652C2065786365656473207468652073686F727420766568656D656E6365206F6620616E79206361726E616C20706C6561737572652E\",
*** v1 0.5 debug| \"all: 000102030405060708090a0b0c0d0e0f101112131415161718191a1b1c1d1e1f202122232425262728292a2b2c2d2e2f303132333435363738393a3b3c3d3e3f404142434445464748494a4b4c4d4e4f505152535455565758595a5b5c5d5e5f606162636465666768696a6b6c6d6e6f707172737475767778797a7b7c7d7e7f808182838485868788898a8b8c8d8e8f909192939495969798999a9b9c9d9e9fa0a1a2a3a4a5a6a7a8a9aaabacadaeafb0b1b2b3b4b5b6b7b8b9babbbcbdbebfc0c1c2c3c4c5c6c7c8c9cacbcccdcecfd0d1d2d3d4d5d6d7d8d9dadbdcdddedfe0e1e2e3e4e5e6e7e8e9eaebecedeeeff0f1f2f3f4f5f6f7f8f9fafbfcfdfeff\",
*** v1 0.5 debug| \"all-lc: 000102030405060708090a0b0c0d0e0f101112131415161718191a1b1c1d1e1f202122232425262728292a2b2c2d2
*** v1 0.5 debug| [...] (1704)
*** v1 0.5 debug|Debug: Child cleanup complete
*** v1 0.5 CLI RX 200
---- v1 0.5 Unexpected panic
* top 0.5 failure during reset
# top TEST ./tests/m00035.vtc FAILED (0.505) exit=2