Skip to content

Instantly share code, notes, and snippets.

@Atalanta

Atalanta/gist:4518207

Created January 12, 2013 14:07
Show Gist options

  • Select an option

    Learn more about clone URLs
  • Save Atalanta/4518207 to your computer and use it in GitHub Desktop.

Select an option

Learn more about clone URLs
Save Atalanta/4518207 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
gistfile1.txt
**** top 0.0 macro def varnishd=varnishd
**** top 0.0 macro def pwd=/opt
**** top 0.0 macro def topbuild=/opt/../..
**** top 0.0 macro def bad_ip=192.0.2.255
**** top 0.0 macro def tmpdir=/tmp/vtc.8307.782e7eb9
* top 0.0 TEST test-which-fails.vtc starting
*** top 0.0 varnishtest
* top 0.0 TEST #1086 junk after gzip from backend and streaming enabled
*** top 0.0 server
** s1 0.0 Starting server
**** s1 0.0 macro def s1_addr=127.0.0.1
**** s1 0.0 macro def s1_port=54761
**** s1 0.0 macro def s1_sock=127.0.0.1 54761
* s1 0.0 Listen on 127.0.0.1 54761
*** top 0.0 varnish
** s1 0.0 Started on 127.0.0.1 54761
** v1 0.0 Launch
*** v1 0.0 CMD: cd ${pwd} && ${varnishd} -d -d -n /tmp/vtc.8307.782e7eb9/v1 -l 2m,1m,- -p auto_restart=off -p syslog_cli_traffic=off -a '127.0.0.1:0' -S /tmp/vtc.8307.782e7eb9/v1/_S -M '127.0.0.1 44746' -P /tmp/vtc.8307.782e7eb9/v1/varnishd.pid -sfile,/tmp/vtc.8307.782e7eb9/v1,10M -p debug=+syncvsl
*** v1 0.0 CMD: cd /opt && varnishd -d -d -n /tmp/vtc.8307.782e7eb9/v1 -l 2m,1m,- -p auto_restart=off -p syslog_cli_traffic=off -a '127.0.0.1:0' -S /tmp/vtc.8307.782e7eb9/v1/_S -M '127.0.0.1 44746' -P /tmp/vtc.8307.782e7eb9/v1/varnishd.pid -sfile,/tmp/vtc.8307.782e7eb9/v1,10M -p debug=+syncvsl
*** v1 0.0 PID: 8314
*** v1 0.0 debug| Platform: Linux,3.2.0-24-virtual,x86_64,-sfile,-smalloc,-hcritbit\n
*** v1 0.0 debug| 200 273 \n
*** v1 0.0 debug| -----------------------------\n
*** v1 0.0 debug| Varnish Cache CLI 1.0\n
*** v1 0.0 debug| -----------------------------\n
*** v1 0.0 debug| Linux,3.2.0-24-virtual,x86_64,-sfile,-smalloc,-hcritbit\n
*** v1 0.0 debug| varnish-trunk revision 8fa24e2\n
*** v1 0.0 debug| \n
*** v1 0.0 debug| Type 'help' for command list.\n
*** v1 0.0 debug| Type 'quit' to close CLI session.\n
*** v1 0.0 debug| Type 'start' to launch worker process.\n
*** v1 0.0 debug| \n
**** v1 0.2 CLIPOLL 1 0x1 0x0
*** v1 0.2 CLI connection fd = 6
*** v1 0.2 CLI RX 107
**** v1 0.2 CLI RX| nggwscmnzfclwkyfdqnhfxzvgvfjzrum\n
**** v1 0.2 CLI RX| \n
**** v1 0.2 CLI RX| Authentication required.\n
**** v1 0.2 CLI TX| auth 24b30087fee757a9293427e7331429fa4707f7f8f1bda40e1283587da6bf30c9\n
*** v1 0.2 CLI RX 200
**** v1 0.2 CLI RX| -----------------------------\n
**** v1 0.2 CLI RX| Varnish Cache CLI 1.0\n
**** v1 0.2 CLI RX| -----------------------------\n
**** v1 0.2 CLI RX| Linux,3.2.0-24-virtual,x86_64,-sfile,-smalloc,-hcritbit\n
**** v1 0.2 CLI RX| varnish-trunk revision 8fa24e2\n
**** v1 0.2 CLI RX| \n
**** v1 0.2 CLI RX| Type 'help' for command list.\n
**** v1 0.2 CLI RX| Type 'quit' to close CLI session.\n
**** v1 0.2 CLI RX| Type 'start' to launch worker process.\n
**** v1 0.2 CLI TX| vcl.inline vcl1 << %XJEIFLH|)Xspa8P\n
**** v1 0.2 CLI TX| backend s1 { .host = "127.0.0.1"; .port = "54761"; }\n
**** v1 0.2 CLI TX| \n
**** v1 0.2 CLI TX| \n
**** v1 0.2 CLI TX| sub vcl_fetch {\n
**** v1 0.2 CLI TX| set beresp.do_stream = true;\n
**** v1 0.2 CLI TX| }\n
**** v1 0.2 CLI TX| \n
**** v1 0.2 CLI TX| %XJEIFLH|)Xspa8P\n
*** 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 CLI RX| VCL 'vcl1' now active
*** top 0.4 varnish
** v1 0.4 Start
**** v1 0.4 CLI TX| start
*** v1 0.4 debug| child (8327) Started\n
*** v1 0.4 debug| Child (8327) said Child starts\n
*** v1 0.4 debug| Child (8327) said SMF.s0 mmap'ed 10485760 bytes of 10485760\n
*** v1 0.4 CLI RX 200
*** v1 0.4 wait-running
**** v1 0.4 CLI TX| status
*** v1 0.5 CLI RX 200
**** v1 0.5 CLI RX| Child in state running
**** v1 0.5 CLI TX| debug.xid 999
*** v1 0.5 CLI RX 200
**** v1 0.5 CLI RX| XID is 999
**** v1 0.5 CLI TX| debug.listen_address
**** v1 0.5 vsl| 0 CLI - Rd vcl.load "vcl1" ./vcl.4qgf6ePq.so
**** v1 0.5 vsl| 0 CLI - Wr 200 36 Loaded "./vcl.4qgf6ePq.so" as "vcl1"
**** v1 0.5 vsl| 0 CLI - Rd vcl.use "vcl1"
**** v1 0.5 vsl| 0 CLI - Wr 200 0
**** v1 0.5 vsl| 0 CLI - Rd start
**** v1 0.5 vsl| 0 CLI - Wr 200 0
**** v1 0.5 vsl| 0 CLI - Rd debug.xid 999
**** v1 0.5 vsl| 0 CLI - Wr 200 10 XID is 999
*** v1 0.5 CLI RX 200
**** v1 0.5 CLI RX| 127.0.0.1 49539\n
** v1 0.5 Listen on 127.0.0.1 49539
**** v1 0.5 macro def v1_addr=127.0.0.1
**** v1 0.5 macro def v1_port=49539
**** v1 0.5 macro def v1_sock=127.0.0.1 49539
*** top 0.5 client
** c1 0.5 Starting client
** c1 0.5 Waiting for client
*** c1 0.5 Connect to 127.0.0.1 49539
*** c1 0.5 connected fd 10 from 127.0.0.1 37858 to 127.0.0.1 49539
*** c1 0.5 txreq
**** c1 0.5 txreq| GET / HTTP/1.1\r\n
**** c1 0.5 txreq| \r\n
*** s1 0.5 accepted fd 4
*** s1 0.5 rxreq
**** s1 0.5 rxhdr| GET / HTTP/1.1\r\n
**** s1 0.5 rxhdr| X-Forwarded-For: 127.0.0.1\r\n
**** s1 0.5 rxhdr| X-Varnish: 1001\r\n
**** s1 0.5 rxhdr| Accept-Encoding: gzip\r\n
**** s1 0.5 rxhdr| Host: 127.0.0.1\r\n
**** s1 0.5 rxhdr| \r\n
**** s1 0.5 http[ 0] | GET
**** s1 0.5 http[ 1] | /
**** s1 0.5 http[ 2] | HTTP/1.1
**** s1 0.5 http[ 3] | X-Forwarded-For: 127.0.0.1
**** s1 0.5 http[ 4] | X-Varnish: 1001
**** s1 0.5 http[ 5] | Accept-Encoding: gzip
**** s1 0.5 http[ 6] | Host: 127.0.0.1
**** s1 0.5 bodylen = 0
*** s1 0.5 txresp
**** s1 0.5 txresp| HTTP/1.1 200 Ok\r\n
**** s1 0.5 txresp| Content-Encoding: gzip\r\n
**** s1 0.5 txresp| Transfer-Encoding: Chunked\r\n
**** s1 0.5 txresp| \r\n
*** s1 0.5 send
**** s1 0.5 send| 14\r\n
*** s1 0.5 sendhex
**** s1 0.5 sendhex| 1f 8b
*** s1 0.5 sendhex
**** s1 0.5 sendhex| 08
*** s1 0.5 sendhex
**** s1 0.5 sendhex| 00
*** s1 0.5 sendhex
**** s1 0.5 sendhex| 00 00 00 00
*** s1 0.5 sendhex
**** s1 0.5 sendhex| 00
*** s1 0.5 sendhex
**** s1 0.5 sendhex| 03
*** s1 0.5 sendhex
**** s1 0.5 sendhex| 03 00
*** s1 0.5 sendhex
**** s1 0.5 sendhex| 00 00 00 00
*** s1 0.5 sendhex
**** s1 0.5 sendhex| 00 00 00 00
*** s1 0.5 send
**** s1 0.5 send| \r\n
*** s1 0.5 chunked
**** s1 0.5 chunked| 6\r\n
**** s1 0.5 chunked| FOOBAR\r\n
*** s1 0.5 chunkedlen
**** s1 0.5 chunked| 0\r\n
**** s1 0.5 chunked| \r\n
*** s1 0.5 shutting fd 4
** s1 0.5 Ending
*** c1 0.5 rxresp
**** c1 0.6 rxhdr| HTTP/1.1 503 Service Unavailable\r\n
**** c1 0.6 rxhdr| Server: Varnish\r\n
**** c1 0.6 rxhdr| Content-Type: text/html; charset=utf-8\r\n
**** c1 0.6 rxhdr| Retry-After: 5\r\n
**** c1 0.6 rxhdr| Content-Length: 413\r\n
**** c1 0.6 rxhdr| Accept-Ranges: bytes\r\n
**** c1 0.6 rxhdr| Date: Sat, 12 Jan 2013 14:06:29 GMT\r\n
**** c1 0.6 rxhdr| X-Varnish: 1001\r\n
**** c1 0.6 rxhdr| Age: 0\r\n
**** c1 0.6 rxhdr| Via: 1.1 varnish\r\n
**** c1 0.6 rxhdr| Connection: close\r\n
**** c1 0.6 rxhdr| \r\n
**** c1 0.6 http[ 0] | HTTP/1.1
**** c1 0.6 http[ 1] | 503
**** c1 0.6 http[ 2] | Service Unavailable
**** c1 0.6 http[ 3] | Server: Varnish
**** c1 0.6 http[ 4] | Content-Type: text/html; charset=utf-8
**** c1 0.6 http[ 5] | Retry-After: 5
**** c1 0.6 http[ 6] | Content-Length: 413
**** c1 0.6 http[ 7] | Accept-Ranges: bytes
**** c1 0.6 http[ 8] | Date: Sat, 12 Jan 2013 14:06:29 GMT
**** c1 0.6 http[ 9] | X-Varnish: 1001
**** c1 0.6 http[10] | Age: 0
**** c1 0.6 http[11] | Via: 1.1 varnish
**** c1 0.6 http[12] | Connection: close
**** c1 0.6 body| \n
**** c1 0.6 body| <?xml version="1.0" encoding="utf-8"?>\n
**** c1 0.6 body| <!DOCTYPE html PUBLIC "-//W3C//DTD XHTML 1.0 Strict//EN"\n
**** c1 0.6 body| "http://www.w3.org/TR/xhtml1/DTD/xhtml1-strict.dtd">\n
**** c1 0.6 body| <html>\n
**** c1 0.6 body| <head>\n
**** c1 0.6 body| <title>503 Service Unavailable</title>\n
**** c1 0.6 body| </head>\n
**** c1 0.6 body| <body>\n
**** c1 0.6 body| <h1>Error 503 Service Unavailable</h1>\n
**** c1 0.6 body| <p>Service Unavailable</p>\n
**** c1 0.6 body| <h3>Guru Meditation:</h3>\n
**** c1 0.6 body| <p>XID: 1001</p>\n
**** c1 0.6 body| <hr>\n
**** c1 0.6 body| <p>Varnish cache server</p>\n
**** c1 0.6 body| </body>\n
**** c1 0.6 body| </html>\n
**** c1 0.6 bodylen = 413
*** c1 0.6 expect
---- c1 0.6 EXPECT resp.status (503) == 200 (200) failed
* top 0.6 RESETTING after test-which-fails.vtc
** s1 0.6 Waiting for server
**** s1 0.6 macro undef s1_addr
**** s1 0.6 macro undef s1_port
**** s1 0.6 macro undef s1_sock
**** v1 0.6 vsl| 0 CLI - Rd debug.listen_address
**** v1 0.6 vsl| 0 CLI - Wr 200 16 127.0.0.1 49539
**** v1 0.6 vsl| 1000 SessOpen c 127.0.0.1 37858 127.0.0.1:0 127.0.0.1 49539 1357999589.774791 12
**** v1 0.6 vsl| 1001 Link c sess 1000
**** v1 0.6 vsl| 1001 ReqMethod c GET
**** v1 0.6 vsl| 1001 ReqURL c /
**** v1 0.6 vsl| 1001 ReqProtocol c HTTP/1.1
**** v1 0.6 vsl| 1001 ReqStart c 127.0.0.1 37858
**** v1 0.6 vsl| 1001 VCL_call c recv
**** v1 0.6 vsl| 1001 VCL_return c lookup
**** v1 0.6 vsl| 1001 VCL_call c hash
**** v1 0.6 vsl| 1001 VCL_return c hash
**** v1 0.6 vsl| 1002 Link b req 1001
**** v1 0.6 vsl| 1001 Link c bereq 1002
**** v1 0.6 vsl| 1001 VCL_call c miss
**** v1 0.6 vsl| 1001 VCL_return c fetch
**** v1 0.6 vsl| 1001 BackendOpen c 15 s1(127.0.0.1,,54761) 127.0.0.1 54733
**** v1 0.6 vsl| 1001 Backend c 15 s1 s1(127.0.0.1,,54761)
**** v1 0.6 vsl| 1002 BereqMethod b GET
**** v1 0.6 vsl| 1002 BereqURL b /
**** v1 0.6 vsl| 1002 BereqProtocol b HTTP/1.1
**** v1 0.6 vsl| 1002 BereqHeader b X-Forwarded-For: 127.0.0.1
**** v1 0.6 vsl| 1002 BereqHeader b X-Varnish: 1001
**** v1 0.6 vsl| 1002 BereqHeader b Accept-Encoding: gzip
**** v1 0.6 vsl| 1002 BereqHeader b Host: 127.0.0.1
**** v1 0.6 vsl| 1002 BerespProtocol b HTTP/1.1
**** v1 0.6 vsl| 1002 BerespStatus b 200
**** v1 0.6 vsl| 1002 BerespResponse b Ok
**** v1 0.6 vsl| 1002 BerespHeader b Content-Encoding: gzip
**** v1 0.6 vsl| 1002 BerespHeader b Transfer-Encoding: Chunked
**** v1 0.6 vsl| 1002 TTL b RFC 120 -1 -1 1357999590 0 0 0 0
**** v1 0.6 vsl| 1001 VCL_call c fetch
**** v1 0.6 vsl| 1001 VCL_return c deliver
**** v1 0.6 vsl| 1002 ObjProtocol b HTTP/1.1
**** v1 0.6 vsl| 1002 ObjResponse b Ok
**** v1 0.6 vsl| 1002 ObjHeader b Content-Encoding: gzip
**** v1 0.6 vsl| 1001 VCL_call c deliver
**** v1 0.6 vsl| 1001 VCL_return c deliver
**** v1 0.6 vsl| 1002 FetchError b Junk after gzip data
**** v1 0.6 vsl| 1002 Gzip b u F - 20 0 80 80 90
**** v1 0.6 vsl| 1002 Fetch_Body b 3(chunked) cls -1 mklen 1
**** v1 0.6 vsl| 1002 BackendClose b s1(127.0.0.1,,54761)
**** v1 0.6 vsl| 1003 Link b req 1001
**** v1 0.6 vsl| 1001 Link c bereq 1003
**** v1 0.6 vsl| 1001 VCL_call c error
**** v1 0.6 vsl| 1001 VCL_return c deliver
**** v1 0.6 vsl| 1001 VCL_call c deliver
**** v1 0.6 vsl| 1002 BerespHeader b Content-Encoding: gzip
**** v1 0.6 vsl| 1002 BerespHeader b Transfer-Encoding: Chunked
**** v1 0.6 vsl| 1002 TTL b RFC 120 -1 -1 1357999590 0 0 0 0
**** v1 0.6 vsl| 1001 VCL_call c fetch
**** v1 0.6 vsl| 1001 VCL_return c deliver
**** v1 0.6 vsl| 1002 ObjProtocol b HTTP/1.1
**** v1 0.6 vsl| 1002 ObjResponse b Ok
**** v1 0.6 vsl| 1002 ObjHeader b Content-Encoding: gzip
**** v1 0.6 vsl| 1001 VCL_call c deliver
**** v1 0.6 vsl| 1001 VCL_return c deliver
**** v1 0.6 vsl| 1002 FetchError b Junk after gzip data
**** v1 0.6 vsl| 1002 Gzip b u F - 20 0 80 80 90
**** v1 0.6 vsl| 1002 Fetch_Body b 3(chunked) cls -1 mklen 1
**** v1 0.6 vsl| 1002 BackendClose b s1(127.0.0.1,,54761)
**** v1 0.6 vsl| 1003 Link b req 1001
**** v1 0.6 vsl| 1001 Link c bereq 1003
**** v1 0.6 vsl| 1001 VCL_call c error
**** v1 0.6 vsl| 1001 VCL_return c deliver
**** v1 0.6 vsl| 1001 VCL_call c deliver
**** v1 0.6 vsl| 1001 VCL_return c deliver
**** v1 0.6 vsl| 1001 RespProtocol c HTTP/1.1
**** v1 0.6 vsl| 1001 RespStatus c 503
**** v1 0.6 vsl| 1001 RespResponse c Service Unavailable
**** v1 0.6 vsl| 1001 RespHeader c Server: Varnish
**** v1 0.6 vsl| 1001 RespHeader c Content-Type: text/html; charset=utf-8
**** v1 0.6 vsl| 1001 RespHeader c Retry-After: 5
**** v1 0.6 vsl| 1001 RespHeader c Content-Length: 413
**** v1 0.6 vsl| 1001 RespHeader c Accept-Ranges: bytes
**** v1 0.6 vsl| 1001 RespHeader c Date: Sat, 12 Jan 2013 14:06:29 GMT
**** v1 0.6 vsl| 1001 RespHeader c X-Varnish: 1001
**** v1 0.6 vsl| 1001 RespHeader c Age: 0
**** v1 0.6 vsl| 1001 RespHeader c Via: 1.1 varnish
**** v1 0.6 vsl| 1001 RespHeader c Connection: close
**** v1 0.6 vsl| 1001 Length c 0
**** v1 0.6 vsl| 1001 ReqEnd c 1357999589.774981022 1357999589.774790764 -0.012964249 0.012773991 -0.012964249
**** v1 0.6 vsl| 1000 SessClose c TX_ERROR 0.013 1 0 0 1 251 413
**** v1 1.4 vsl| 32682 ExpKill - 2147484650 -1357999592
** v1 1.6 Wait
** v1 1.6 R 8314 Status: 0000
* top 1.6 TEST test-which-fails.vtc FAILED
# top TEST test-which-fails.vtc FAILED (1.620) exit=1
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment