Skip to content

Instantly share code, notes, and snippets.

@jbfavre

jbfavre/gist:c753336f4fc3c898ed7c066dd0b7b8a1

Created July 20, 2016 08:55
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save jbfavre/c753336f4fc3c898ed7c066dd0b7b8a1 to your computer and use it in GitHub Desktop.
Save jbfavre/c753336f4fc3c898ed7c066dd0b7b8a1 to your computer and use it in GitHub Desktop.
Download ZIP
varnish-modules test error on i386
Raw
gistfile1.txt
* top 0.0 TEST ../src/tests/cookie/08-overflow.vtc starting
** top 0.0 === varnishtest "Test cookie vmod"
* top 0.0 TEST Test cookie vmod
** top 0.0 === server s1 {
** s1 0.0 Starting server
**** s1 0.0 macro def s1_addr=127.0.0.1
**** s1 0.0 macro def s1_port=60202
**** s1 0.0 macro def s1_sock=127.0.0.1 60202
* s1 0.0 Listen on 127.0.0.1 60202
** top 0.0 === varnish v1 -vcl+backend {
** s1 0.0 Started on 127.0.0.1 60202
** v1 0.0 Launch
*** v1 0.0 CMD: cd ${pwd} && exec ${varnishd} -d -n /tmp/vtc.32535.4a20d0b7/v1 -l 2m,1m,- -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 47534' -P /tmp/vtc.32535.4a20d0b7/v1/varnishd.pid
*** v1 0.0 CMD: cd /tmp/buildd/varnish-modules-0.9.1/src && exec /usr/sbin/varnishd -d -n /tmp/vtc.32535.4a20d0b7/v1 -l 2m,1m,- -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 47534' -P /tmp/vtc.32535.4a20d0b7/v1/varnishd.pid
*** v1 0.0 PID: 32541
**** v1 0.0 macro def v1_pid=32541
**** v1 0.0 macro def v1_name=/tmp/vtc.32535.4a20d0b7/v1
*** v1 0.0 debug| Debug: Platform: Linux,3.16.0-4-amd64,i686,-jnone,-smalloc,-smalloc,-hcritbit\n
*** v1 0.0 debug| \n
*** v1 0.0 debug| 200 278 \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.16.0-4-amd64,i686,-jnone,-smalloc,-smalloc,-hcritbit\n
*** v1 0.0 debug| varnish-4.1.3 revision 5e3b6d2\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.1 CLIPOLL 1 0x1 0x0
*** v1 0.1 CLI connection fd = 10
*** v1 0.1 CLI RX 107
**** v1 0.1 CLI RX| ptcbwhiwfomwdvhwbxsqumufvnynloza\n
**** v1 0.1 CLI RX| \n
**** v1 0.1 CLI RX| Authentication required.\n
**** v1 0.1 CLI TX| auth 7f0871440981716aea851fbcb83d59913527654f02c589b6e90e8b4ae6568c5a\n
*** v1 0.1 CLI RX 200
**** v1 0.1 CLI RX| -----------------------------\n
**** v1 0.1 CLI RX| Varnish Cache CLI 1.0\n
**** v1 0.1 CLI RX| -----------------------------\n
**** v1 0.1 CLI RX| Linux,3.16.0-4-amd64,i686,-jnone,-smalloc,-smalloc,-hcritbit\n
**** v1 0.1 CLI RX| varnish-4.1.3 revision 5e3b6d2\n
**** v1 0.1 CLI RX| \n
**** v1 0.1 CLI RX| Type 'help' for command list.\n
**** v1 0.1 CLI RX| Type 'quit' to close CLI session.\n
**** v1 0.1 CLI RX| Type 'start' to launch worker process.\n
**** v1 0.1 CLI TX| vcl.inline vcl1 << %XJEIFLH|)Xspa8P\n
**** v1 0.1 CLI TX| vcl 4.0;\n
**** v1 0.1 CLI TX| backend s1 { .host = "127.0.0.1"; .port = "60202"; }\n
**** v1 0.1 CLI TX| \n
**** v1 0.1 CLI TX| \n
**** v1 0.1 CLI TX| \timport cookie from "/tmp/buildd/varnish-modules-0.9.1/src/.libs/libvmod_cookie.so";\n
**** v1 0.1 CLI TX| \n
**** v1 0.1 CLI TX| \tsub vcl_recv {\n
**** v1 0.1 CLI TX| \t}\n
**** v1 0.1 CLI TX| \tsub vcl_deliver {\n
**** v1 0.1 CLI TX| \t\tcookie.parse(req.http.cookie);\n
**** v1 0.1 CLI TX| \t\tset resp.http.cookiestring = cookie.get_string();\n
**** v1 0.1 CLI TX| \t\tset resp.http.cookie1 = cookie.isset("cookie1");\n
**** v1 0.1 CLI TX| \t}\n
**** v1 0.1 CLI TX| \n
**** v1 0.1 CLI TX| %XJEIFLH|)Xspa8P\n
*** v1 0.3 CLI RX 200
**** v1 0.3 CLI RX| VCL compiled.\n
**** v1 0.3 CLI TX| vcl.use vcl1
*** v1 0.3 CLI RX 200
** v1 0.3 Start
**** v1 0.3 CLI TX| start
*** v1 0.4 debug| Debug: Child (32553) Started\n
*** v1 0.4 CLI RX 200
*** v1 0.4 wait-running
**** v1 0.4 CLI TX| status
*** v1 0.4 debug| Info: Child (32553) said Child starts\n
**** v1 0.4 vsl| 0 CLI - Rd vcl.load "vcl1" vcl_vcl1.1469003007.413882256/vgc.so 1auto
**** v1 0.4 vsl| 0 CLI - Wr 200 55 Loaded "vcl_vcl1.1469003007.413882256/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 CLI RX 200
**** v1 0.4 CLI RX| Child in state running
**** v1 0.4 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 CLI RX 200
**** v1 0.5 CLI RX| 127.0.0.1 37996\n
** v1 0.5 Listen on 127.0.0.1 37996
**** v1 0.5 macro def v1_addr=127.0.0.1
**** v1 0.5 macro def v1_port=37996
**** v1 0.5 macro def v1_sock=127.0.0.1 37996
** top 0.5 === client c1 {
** top 0.5 === client c1 -run
** c1 0.5 Starting client
** c1 0.5 Waiting for client
*** c1 0.5 Connect to 127.0.0.1 37996
*** c1 0.5 connected fd 11 from 127.0.0.1 51880 to 127.0.0.1 37996
** c1 0.5 === txreq -url "/" -hdr "Cookie: phohx8aingie6Ide7peephie5paip6a...
**** c1 0.5 txreq| GET / HTTP/1.1\r\n
**** c1 0.5 txreq| Cookie: phohx8aingie6Ide7peephie5paip6ang4thooh4ooquai8ohvah7eiqueeki8ooth7viequ0Tha5thewiSheih5jaimaiTahr1wi8WooQuoe7loothieThahweeneichoo8cufeelu3tie5cei1iShiemiezoofox6ahcaevaihocheungai2aeghaichaingee0EiGie3Ees5ujaem5uquahpieFeelei7Ohngei1afaTooph4aiquum1aewaidatheshuh1fohhoor0hoo6aeTeiy9xougahf3jeapooshuhoob5deiwareingahth7ahf2fafeer8Oobiewai3rei8ofejohjeiye4die8Na7ze6eixajauCairoth0lek8vioyuom6eih0egho2aingoo7coh1at3niochu6osahthi0ue1Luchae1eifeupiuwaa0raidiewaijese4oozee4eihie5shaBaoreacooNg8uW9eru9kigh3Feesi8iex2pu7ohfaiBiezael6ifaujiek4nutae1aalohchoteiPuaM2chiefaicaopheKohsh6Ho1wiephieseef1daj3Pahfie2ooch8shaing5baXeiLiep9lahfe9uDaxeehielais2eix3iekiew8aiter9Foo8noo2hae7ohdie1iB7hoop3podeengooSothoojui4AhXu5Nain8ohqu8if1ue5iTheimei5oghie9sheiv4Hejah1veixahcaixie8ahyieT8Phay4daeTei1aRiemae6oicheef2miiNuoxeil1kae2nea1roh9Rei1keiwaT2eoJaiNgie0den6aideif3uechaishaec4cai2eozieb9aeN9sai9ahnielohdaeGh2kaeleiteitai0ietoo7eiCha0baiW7dai0im1jul5OWijaLo2ohh3kooxu2oFah3loob6feiw7pie9eighu8ik4chae...
*** s1 0.5 accepted fd 5
** s1 0.5 === rxreq
**** s1 0.5 rxhdr| GET / HTTP/1.1\r\n
**** s1 0.5 rxhdr| Cookie: phohx8aingie6Ide7peephie5paip6ang4thooh4ooquai8ohvah7eiqueeki8ooth7viequ0Tha5thewiSheih5jaimaiTahr1wi8WooQuoe7loothieThahweeneichoo8cufeelu3tie5cei1iShiemiezoofox6ahcaevaihocheungai2aeghaichaingee0EiGie3Ees5ujaem5uquahpieFeelei7Ohngei1afaTooph4aiquum1aewaidatheshuh1fohhoor0hoo6aeTeiy9xougahf3jeapooshuhoob5deiwareingahth7ahf2fafeer8Oobiewai3rei8ofejohjeiye4die8Na7ze6eixajauCairoth0lek8vioyuom6eih0egho2aingoo7coh1at3niochu6osahthi0ue1Luchae1eifeupiuwaa0raidiewaijese4oozee4eihie5shaBaoreacooNg8uW9eru9kigh3Feesi8iex2pu7ohfaiBiezael6ifaujiek4nutae1aalohchoteiPuaM2chiefaicaopheKohsh6Ho1wiephieseef1daj3Pahfie2ooch8shaing5baXeiLiep9lahfe9uDaxeehielais2eix3iekiew8aiter9Foo8noo2hae7ohdie1iB7hoop3podeengooSothoojui4AhXu5Nain8ohqu8if1ue5iTheimei5oghie9sheiv4Hejah1veixahcaixie8ahyieT8Phay4daeTei1aRiemae6oicheef2miiNuoxeil1kae2nea1roh9Rei1keiwaT2eoJaiNgie0den6aideif3uechaishaec4cai2eozieb9aeN9sai9ahnielohdaeGh2kaeleiteitai0ietoo7eiCha0baiW7dai0im1jul5OWijaLo2ohh3kooxu2oFah3loob6feiw7pie9eighu8ik4chae...
**** s1 0.5 rxhdrlen = 1126
**** s1 0.5 http[ 0] | GET
**** s1 0.5 http[ 1] | /
**** s1 0.5 http[ 2] | HTTP/1.1
**** s1 0.5 http[ 3] | Cookie: phohx8aingie6Ide7peephie5paip6ang4thooh4ooquai8ohvah7eiqueeki8ooth7viequ0Tha5thewiSheih5jaimaiTahr1wi8WooQuoe7loothieThahweeneichoo8cufeelu3tie5cei1iShiemiezoofox6ahcaevaihocheungai2aeghaichaingee0EiGie3Ees5ujaem5uquahpieFeelei7Ohngei1afaTooph4aiquum1aewaidatheshuh1fohhoor0hoo6aeTeiy9xougahf3jeapooshuhoob5deiwareingahth7ahf2fafeer8Oobiewai3rei8ofejohjeiye4die8Na7ze6eixajauCairoth0lek8vioyuom6eih0egho2aingoo7coh1at3niochu6osahthi0ue1Luchae1eifeupiuwaa0raidiewaijese4oozee4eihie5shaBaoreacooNg8uW9eru9kigh3Feesi8iex2pu7ohfaiBiezael6ifaujiek4nutae1aalohchoteiPuaM2chiefaicaopheKohsh6Ho1wiephieseef1daj3Pahfie2ooch8shaing5baXeiLiep9lahfe9uDaxeehielais2eix3iekiew8aiter9Foo8noo2hae7ohdie1iB7hoop3podeengooSothoojui4AhXu5Nain8ohqu8if1ue5iTheimei5oghie9sheiv4Hejah1veixahcaixie8ahyieT8Phay4daeTei1aRiemae6oicheef2miiNuoxeil1kae2nea1roh9Rei1keiwaT2eoJaiNgie0den6aideif3uechaishaec4cai2eozieb9aeN9sai9ahnielohdaeGh2kaeleiteitai0ietoo7eiCha0baiW7dai0im1jul5OWijaLo2ohh3kooxu2oFah3loob6feiw7pie9eighu8ik4chae0Athou2fah5ieQuu...
**** s1 0.5 http[ 4] | X-Forwarded-For: 127.0.0.1
**** s1 0.5 http[ 5] | X-Varnish: 1002
**** 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-Length: 0\r\n
**** s1 0.5 txresp| \r\n
** s1 0.5 === rxreq
** c1 0.5 === rxresp
**** 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 vsl| 0 CLI - Rd debug.listen_address
**** v1 0.5 vsl| 0 CLI - Wr 200 16 127.0.0.1 37996
**** v1 0.5 vsl| 1000 Begin c sess 0 HTTP/1
**** v1 0.5 vsl| 1000 SessOpen c 127.0.0.1 51880 127.0.0.1:37996 127.0.0.1 37996 1469003007.771497 20
**** v1 0.5 vsl| 1000 Link c req 1001 rxreq
**** c1 0.5 rxhdr| HTTP/1.1 200 OK\r\n
**** c1 0.5 rxhdr| Content-Length: 0\r\n
**** c1 0.5 rxhdr| Date: Wed, 20 Jul 2016 08:23:27 GMT\r\n
**** c1 0.5 rxhdr| X-Varnish: 1001\r\n
**** c1 0.5 rxhdr| Age: 0\r\n
**** c1 0.5 rxhdr| Via: 1.1 varnish-v4\r\n
**** c1 0.5 rxhdr| cookiestring: phohx8aingie6Ide7peephie5paip6ang4thooh4ooquai8ohvah7eiqueeki8ooth7viequ0Tha5thewiSheih5jaimaiTahr1wi8WooQuoe7loothieThahweeneichoo8cufeelu3tie5cei1iShiemiezoofox6ahcaevaihocheungai2aeghaichaingee0EiGie3Ees5ujaem5uquahpieFeelei7Ohngei1afaTooph4aiquum1aewaidatheshuh1fohhoor0hoo6aeTeiy9xougahf3jeapooshuhoob5deiwareingahth7ahf2fafeer8Oobiewai3rei8ofejohjeiye4die8Na7ze6eixajauCairoth0lek8vioyuom6eih0egho2aingoo7coh1at3niochu6osahthi0ue1Luchae1eifeupiuwaa0raidiewaijese4oozee4eihie5shaBaoreacooNg8uW9eru9kigh3Feesi8iex2pu7ohfaiBiezael6ifaujiek4nutae1aalohchoteiPuaM2chiefaicaopheKohsh6Ho1wiephieseef1daj3Pahfie2ooch8shaing5baXeiLiep9lahfe9uDaxeehielais2eix3iekiew8aiter9Foo8noo2hae7ohdie1iB7hoop3podeengooSothoojui4AhXu5Nain8ohqu8if1ue5iTheimei5oghie9sheiv4Hejah1veixahcaixie8ahyieT8Phay4daeTei1aRiemae6oicheef2miiNuoxeil1kae2nea1roh9Rei1keiwaT2eoJaiNgie0den6aideif3uechaishaec4cai2eozieb9aeN9...
**** c1 0.5 rxhdrlen = 1236
**** c1 0.5 http[ 0] | HTTP/1.1
**** c1 0.5 http[ 1] | 200
**** c1 0.5 http[ 2] | OK
**** c1 0.5 http[ 3] | Content-Length: 0
**** c1 0.5 http[ 4] | Date: Wed, 20 Jul 2016 08:23:27 GMT
**** c1 0.5 http[ 5] | X-Varnish: 1001
**** c1 0.5 http[ 6] | Age: 0
**** c1 0.5 http[ 7] | Via: 1.1 varnish-v4
**** c1 0.5 http[ 8] | cookiestring: phohx8aingie6Ide7peephie5paip6ang4thooh4ooquai8ohvah7eiqueeki8ooth7viequ0Tha5thewiSheih5jaimaiTahr1wi8WooQuoe7loothieThahweeneichoo8cufeelu3tie5cei1iShiemiezoofox6ahcaevaihocheungai2aeghaichaingee0EiGie3Ees5ujaem5uquahpieFeelei7Ohngei1afaTooph4aiquum1aewaidatheshuh1fohhoor0hoo6aeTeiy9xougahf3jeapooshuhoob5deiwareingahth7ahf2fafeer8Oobiewai3rei8ofejohjeiye4die8Na7ze6eixajauCairoth0lek8vioyuom6eih0egho2aingoo7coh1at3niochu6osahthi0ue1Luchae1eifeupiuwaa0raidiewaijese4oozee4eihie5shaBaoreacooNg8uW9eru9kigh3Feesi8iex2pu7ohfaiBiezael6ifaujiek4nutae1aalohchoteiPuaM2chiefaicaopheKohsh6Ho1wiephieseef1daj3Pahfie2ooch8shaing5baXeiLiep9lahfe9uDaxeehielais2eix3iekiew8aiter9Foo8noo2hae7ohdie1iB7hoop3podeengooSothoojui4AhXu5Nain8ohqu8if1ue5iTheimei5oghie9sheiv4Hejah1veixahcaixie8ahyieT8Phay4daeTei1aRiemae6oicheef2miiNuoxeil1kae2nea1roh9Rei1keiwaT2eoJaiNgie0den6aideif3uechaishaec4cai2eozieb9aeN9sai9ahnielohdaeGh2kaeleiteitai0ietoo7eiCha0baiW7dai0im1jul5OWijaLo2ohh3kooxu2oFah3loob6feiw7pie9eighu8ik4chae0Athou2fah...
**** c1 0.5 http[ 9] | cookie1: false
**** c1 0.5 http[10] | Accept-Ranges: bytes
**** c1 0.5 http[11] | Connection: keep-alive
**** c1 0.5 bodylen = 0
** c1 0.5 === expect resp.http.cookiestring == "phohx8aingie6Ide7peephie5p...
**** c1 0.5 EXPECT resp.http.cookiestring (phohx8aingie6Ide7peephie5paip6ang4thooh4ooquai8ohvah7eiqueeki8ooth7viequ0Tha5thewiSheih5jaimaiTahr1wi8WooQuoe7loothieThahweeneichoo8cufeelu3tie5cei1iShiemiezoofox6ahcaevaihocheungai2aeghaichaingee0EiGie3Ees5ujaem5uquahpieFeelei7Ohngei1afaTooph4aiquum1aewaidatheshuh1fohhoor0hoo6aeTeiy9xougahf3jeapooshuhoob5deiwareingahth7ahf2fafeer8Oobiewai3rei8ofejohjeiye4die8Na7ze6eixajauCairoth0lek8vioyuom6eih0egho2aingoo7coh1at3niochu6osahthi0ue1Luchae1eifeupiuwaa0raidiewaijese4oozee4eihie5shaBaoreacooNg8uW9eru9kigh3Feesi8iex2pu7ohfaiBiezael6ifaujiek4nutae1aalohchoteiPuaM2chiefaicaopheKohsh6Ho1wiephieseef1daj3Pahfie2ooch8shaing5baXeiLiep9lahfe9uDaxeehielais2eix3iekiew8aiter9Foo8noo2hae7ohdie1iB7hoop3podeengooSothoojui4AhXu5Nain8ohqu8if1ue5iTheimei5oghie9sheiv4Hejah1veixahcaixie8ahyieT8Phay4daeTei1aRiemae6oicheef2miiNuoxeil1kae2nea1roh9Rei1keiwaT2eoJaiNgie0den6aideif3uechaishaec4cai2eozieb9aeN9sai9ahnielohdaeGh2kaeleiteitai0ietoo7eiCha0baiW7dai0im1jul5OWijaLo2ohh3kooxu2oFah3loob6feiw7pie9eighu8ik4chae0Athou2fah5ieQuuic0Mu1j=cookievalue;) == "phohx8aingie6Ide7peephie5paip6ang4thooh4ooquai8ohvah7eiqueeki8ooth7viequ0Tha5thewiSheih5jaimaiTahr1wi8WooQuoe7loothieThahweeneichoo8cufeelu3tie5cei1iShiemiezoofox6ahcaevaihocheungai2aeghaichaingee0EiGie3Ees5ujaem5uquahpieFeelei7Ohngei1afaTooph4aiquum1aewaidatheshuh1fohhoor0hoo6aeTeiy9xougahf3jeapooshuhoob5deiwareingahth7ahf2fafeer8Oobiewai3rei8ofejohjeiye4die8Na7ze6eixajauCairoth0lek8vioyuom6eih0egho2aingoo7coh1at3niochu6osahthi0ue1Luchae1eifeupiuwaa0raidiewaijese4oozee4eihie5shaBaoreacooNg8uW9eru9kigh3Feesi8iex2pu7ohfaiBiezael6ifaujiek4nutae1aalohchoteiPuaM2chiefaicaopheKohsh6Ho1wiephieseef1daj3Pahfie2ooch8shaing5baXeiLiep9lahfe9uDaxeehielais2eix3iekiew8aiter9Foo8noo2hae7ohdie1iB7hoop3podeengooSothoojui4AhXu5Nain8ohqu8if1ue5iTheimei5oghie9sheiv4Hejah1veixahcaixie8ahyieT8Phay4daeTei1aRiemae6oicheef2miiNuoxeil1kae2nea1roh9Rei1keiwaT2eoJaiNgie0den6aideif3uechaishaec4cai2eozieb9aeN9sai9ahnielohdaeGh2kaeleiteitai0ietoo7eiCha0baiW7dai0im1jul5OWijaLo2ohh3kooxu2oFah3loob6feiw7pie9eighu8ik4chae0Athou2fah5ieQuuic0Mu1j=cookievalue;" match
** c1 0.5 === txreq -url "/" -hdr "Cookie: cookie1=foobarbazfoobarbazphohx...
**** c1 0.5 txreq| GET / HTTP/1.1\r\n
**** c1 0.5 txreq| Cookie: cookie1=foobarbazfoobarbazphohx8aingie6Ide7peephie5paip6ang4thooh4ooquai8ohvah7eiqueeki8ooth7viequ0Tha5thewiSheih5jaimaiTahr1wi8WooQuoe7loothieThahweeneichoo8cufeelu3tie5cei1iShiemiezoofox6ahcaevaihocheungai2aeghaichaingee0EiGie3Ees5ujaem5uquahpieFeelei7Ohngei1afaTooph4aiquum1aewaidatheshuh1fohhoor0hoo6aeTeiy9xougahf3jeapooshuhoob5deiwareingahth7ahf2fafeer8Oobiewai3rei8ofejohjeiye4die8Na7ze6eixajauCairoth0lek8vioyuom6eih0egho2aingoo7coh1at3niochu6osahthi0ue1Luchae1eifeupiuwaa0raidiewaijese4oozee4eihie5shaBaoreacooNg8uW9eru9kigh3Feesi8iex2pu7ohfaiBiezael6ifaujiek4nutae1aalohchoteiPuaM2chiefaicaopheKohsh6Ho1wiephieseef1daj3Pahfie2ooch8shaing5baXeiLiep9lahfe9uDaxeehielais2eix3iekiew8aiter9Foo8noo2hae7ohdie1iB7hoop3podeengooSothoojui4AhXu5Nain8ohqu8if1ue5iTheimei5oghie9sheiv4Hejah1veixahcaixie8ahyieT8Phay4daeTei1aRiemae6oicheef2miiNuoxeil1kae2nea1roh9Rei1keiwaT2eoJaiNgie0den6aideif3uechaishaec4cai2eozieb9aeN9sai9ahnielohdaeGh2kaeleiteitai0ietoo7eiCha0baiW7dai0im1jul5OWijaLo2ohh3kooxu2oFah3l...
**** s1 0.5 rxhdr| GET / HTTP/1.1\r\n
**** s1 0.5 rxhdr| Cookie: cookie1=foobarbazfoobarbazphohx8aingie6Ide7peephie5paip6ang4thooh4ooquai8ohvah7eiqueeki8ooth7viequ0Tha5thewiSheih5jaimaiTahr1wi8WooQuoe7loothieThahweeneichoo8cufeelu3tie5cei1iShiemiezoofox6ahcaevaihocheungai2aeghaichaingee0EiGie3Ees5ujaem5uquahpieFeelei7Ohngei1afaTooph4aiquum1aewaidatheshuh1fohhoor0hoo6aeTeiy9xougahf3jeapooshuhoob5deiwareingahth7ahf2fafeer8Oobiewai3rei8ofejohjeiye4die8Na7ze6eixajauCairoth0lek8vioyuom6eih0egho2aingoo7coh1at3niochu6osahthi0ue1Luchae1eifeupiuwaa0raidiewaijese4oozee4eihie5shaBaoreacooNg8uW9eru9kigh3Feesi8iex2pu7ohfaiBiezael6ifaujiek4nutae1aalohchoteiPuaM2chiefaicaopheKohsh6Ho1wiephieseef1daj3Pahfie2ooch8shaing5baXeiLiep9lahfe9uDaxeehielais2eix3iekiew8aiter9Foo8noo2hae7ohdie1iB7hoop3podeengooSothoojui4AhXu5Nain8ohqu8if1ue5iTheimei5oghie9sheiv4Hejah1veixahcaixie8ahyieT8Phay4daeTei1aRiemae6oicheef2miiNuoxeil1kae2nea1roh9Rei1keiwaT2eoJaiNgie0den6aideif3uechaishaec4cai2eozieb9aeN9sai9ahnielohdaeGh2kaeleiteitai0ietoo7eiCha0baiW7dai0im1jul5OWijaLo2ohh3kooxu2oFah3l...
**** s1 0.5 rxhdrlen = 6261
**** s1 0.5 http[ 0] | GET
**** s1 0.5 http[ 1] | /
**** s1 0.5 http[ 2] | HTTP/1.1
**** s1 0.5 http[ 3] | Cookie: cookie1=foobarbazfoobarbazphohx8aingie6Ide7peephie5paip6ang4thooh4ooquai8ohvah7eiqueeki8ooth7viequ0Tha5thewiSheih5jaimaiTahr1wi8WooQuoe7loothieThahweeneichoo8cufeelu3tie5cei1iShiemiezoofox6ahcaevaihocheungai2aeghaichaingee0EiGie3Ees5ujaem5uquahpieFeelei7Ohngei1afaTooph4aiquum1aewaidatheshuh1fohhoor0hoo6aeTeiy9xougahf3jeapooshuhoob5deiwareingahth7ahf2fafeer8Oobiewai3rei8ofejohjeiye4die8Na7ze6eixajauCairoth0lek8vioyuom6eih0egho2aingoo7coh1at3niochu6osahthi0ue1Luchae1eifeupiuwaa0raidiewaijese4oozee4eihie5shaBaoreacooNg8uW9eru9kigh3Feesi8iex2pu7ohfaiBiezael6ifaujiek4nutae1aalohchoteiPuaM2chiefaicaopheKohsh6Ho1wiephieseef1daj3Pahfie2ooch8shaing5baXeiLiep9lahfe9uDaxeehielais2eix3iekiew8aiter9Foo8noo2hae7ohdie1iB7hoop3podeengooSothoojui4AhXu5Nain8ohqu8if1ue5iTheimei5oghie9sheiv4Hejah1veixahcaixie8ahyieT8Phay4daeTei1aRiemae6oicheef2miiNuoxeil1kae2nea1roh9Rei1keiwaT2eoJaiNgie0den6aideif3uechaishaec4cai2eozieb9aeN9sai9ahnielohdaeGh2kaeleiteitai0ietoo7eiCha0baiW7dai0im1jul5OWijaLo2ohh3kooxu2oFah3loob6feiw7pie9eig...
**** s1 0.5 http[ 4] | X-Forwarded-For: 127.0.0.1
**** s1 0.5 http[ 5] | X-Varnish: 1004
**** 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-Length: 0\r\n
**** s1 0.5 txresp| \r\n
** c1 0.5 === rxresp
*** s1 0.5 shutting fd 5
** s1 0.5 Ending
**** c1 0.5 rxhdr| HTTP/1.1 500 Internal Server Error\r\n
**** c1 0.5 rxhdr| Server: Varnish\r\n
**** c1 0.5 rxhdr| Connection: close\r\n
**** c1 0.5 rxhdr| \r\n
**** c1 0.5 rxhdrlen = 74
**** c1 0.5 http[ 0] | HTTP/1.1
**** c1 0.5 http[ 1] | 500
**** c1 0.5 http[ 2] | Internal Server Error
**** c1 0.5 http[ 3] | Server: Varnish
**** c1 0.5 http[ 4] | Connection: close
**** c1 0.5 bodylen = 0
** c1 0.5 === expect resp.http.cookie1 == "true"
---- c1 0.5 EXPECT resp.http.cookie1 (<undef>) == "true" failed
* top 0.5 RESETTING after ../src/tests/cookie/08-overflow.vtc
** s1 0.5 Waiting for server (4/-1)
**** s1 0.5 macro undef s1_addr
**** s1 0.5 macro undef s1_port
**** s1 0.5 macro undef s1_sock
** v1 0.5 Wait
**** v1 0.5 CLI TX| backend.list
*** v1 0.6 CLI RX 200
**** v1 0.6 CLI RX| Backend name Admin Probe\n
**** v1 0.6 CLI RX| vcl1.s1 probe Healthy (no probe)
**** v1 0.6 vsl| 1002 Begin b bereq 1001 pass
**** v1 0.6 vsl| 1002 Timestamp b Start: 1469003007.771687 0.000000 0.000000
**** 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 Cookie: phohx8aingie6Ide7peephie5paip6ang4thooh4ooquai8ohvah7eiqueeki8ooth7viequ0Tha5thewiSheih5jaimaiTahr1wi8WooQuoe7loothieThahweeneichoo8cufeelu3tie5cei1iShiemiezoofox6ahcaevaihocheungai2aeghaichaingee0EiGie3Ees5ujaem5uquahpieFeelei7Ohngei1afaTooph4ai
**** v1 0.6 vsl| 1002 BereqHeader b X-Forwarded-For: 127.0.0.1
**** v1 0.6 vsl| 1002 BereqHeader b X-Varnish: 1002
**** v1 0.6 vsl| 1002 VCL_call b BACKEND_FETCH
**** v1 0.6 vsl| 1002 VCL_return b fetch
**** v1 0.6 vsl| 1002 BereqHeader b Host: 127.0.0.1
**** v1 0.6 vsl| 1002 BackendOpen b 23 vcl1.s1 127.0.0.1 60202 127.0.0.1 36673
**** v1 0.6 vsl| 1002 BackendStart b 127.0.0.1 60202
**** v1 0.6 vsl| 1002 Timestamp b Bereq: 1469003007.771766 0.000079 0.000079
**** v1 0.6 vsl| 1002 Timestamp b Beresp: 1469003007.772868 0.001181 0.001102
**** v1 0.6 vsl| 1002 BerespProtocol b HTTP/1.1
**** v1 0.6 vsl| 1002 BerespStatus b 200
**** v1 0.6 vsl| 1002 BerespReason b OK
**** v1 0.6 vsl| 1002 BerespHeader b Content-Length: 0
**** v1 0.6 vsl| 1002 BerespHeader b Date: Wed, 20 Jul 2016 08:23:27 GMT
**** v1 0.6 vsl| 1002 TTL b RFC 120 10 -1 1469003008 1469003008 1469003007 0 0
**** v1 0.6 vsl| 1002 VCL_call b BACKEND_RESPONSE
**** v1 0.6 vsl| 1002 TTL b VCL 120 10 0 1469003008
**** v1 0.6 vsl| 1002 VCL_return b deliver
**** v1 0.6 vsl| 1002 Storage b malloc Transient
**** v1 0.6 vsl| 1002 ObjProtocol b HTTP/1.1
**** v1 0.6 vsl| 1002 ObjStatus b 200
**** v1 0.6 vsl| 1002 ObjReason b OK
**** v1 0.6 vsl| 1002 ObjHeader b Content-Length: 0
**** v1 0.6 vsl| 1002 ObjHeader b Date: Wed, 20 Jul 2016 08:23:27 GMT
**** v1 0.6 vsl| 1002 Fetch_Body b 0 none -
**** v1 0.6 vsl| 1002 BackendReuse b 23 vcl1.s1
**** v1 0.6 vsl| 1002 Timestamp b BerespBody: 1469003007.783119 0.011432 0.010252
**** v1 0.6 vsl| 1002 Length b 0
**** v1 0.6 vsl| 1002 BereqAcct b 1126 0 1126 38 0 38
**** v1 0.6 vsl| 1002 End b
**** v1 0.6 vsl| 1001 Begin c req 1000 rxreq
**** v1 0.6 vsl| 1001 Timestamp c Start: 1469003007.771651 0.000000 0.000000
**** v1 0.6 vsl| 1001 Timestamp c Req: 1469003007.771651 0.000000 0.000000
**** v1 0.6 vsl| 1001 ReqStart c 127.0.0.1 51880
**** 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 ReqHeader c Cookie: phohx8aingie6Ide7peephie5paip6ang4thooh4ooquai8ohvah7eiqueeki8ooth7viequ0Tha5thewiSheih5jaimaiTahr1wi8WooQuoe7loothieThahweeneichoo8cufeelu3tie5cei1iShiemiezoofox6ahcaevaihocheungai2aeghaichaingee0EiGie3Ees5ujaem5uquahpieFeelei7Ohngei1afaTooph4ai
**** v1 0.6 vsl| 1001 ReqHeader c X-Forwarded-For: 127.0.0.1
**** v1 0.6 vsl| 1001 VCL_call c RECV
**** v1 0.6 vsl| 1001 VCL_return c pass
**** v1 0.6 vsl| 1001 VCL_call c HASH
**** v1 0.6 vsl| 1001 VCL_return c lookup
**** v1 0.6 vsl| 1001 VCL_call c PASS
**** v1 0.6 vsl| 1001 VCL_return c fetch
**** v1 0.6 vsl| 1001 Link c bereq 1002 pass
**** v1 0.6 vsl| 1001 Timestamp c Fetch: 1469003007.783175 0.011524 0.011524
**** v1 0.6 vsl| 1001 RespProtocol c HTTP/1.1
**** v1 0.6 vsl| 1001 RespStatus c 200
**** v1 0.6 vsl| 1001 RespReason c OK
**** v1 0.6 vsl| 1001 RespHeader c Content-Length: 0
**** v1 0.6 vsl| 1001 RespHeader c Date: Wed, 20 Jul 2016 08:23:27 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-v4
**** v1 0.6 vsl| 1001 VCL_call c DELIVER
**** v1 0.6 vsl| 1001 VCL_Log c cookie: parsed 1 cookies.
**** v1 0.6 vsl| 1001 RespHeader c cookiestring: phohx8aingie6Ide7peephie5paip6ang4thooh4ooquai8ohvah7eiqueeki8ooth7viequ0Tha5thewiSheih5jaimaiTahr1wi8WooQuoe7loothieThahweeneichoo8cufeelu3tie5cei1iShiemiezoofox6ahcaevaihocheungai2aeghaichaingee0EiGie3Ees5ujaem5uquahpieFeelei7Ohngei1afaTo
**** v1 0.6 vsl| 1001 RespHeader c cookie1: false
**** v1 0.6 vsl| 1001 VCL_return c deliver
**** v1 0.6 vsl| 1001 Timestamp c Process: 1469003007.783304 0.011653 0.000129
**** v1 0.6 vsl| 1001 RespHeader c Accept-Ranges: bytes
**** v1 0.6 vsl| 1001 Debug c RES_MODE 2
**** v1 0.6 vsl| 1001 RespHeader c Connection: keep-alive
**** v1 0.6 vsl| 1001 Timestamp c Resp: 1469003007.783413 0.011762 0.000109
**** v1 0.6 vsl| 1001 ReqAcct c 1064 0 1064 1236 0 1236
**** v1 0.6 vsl| 1001 End c
**** v1 0.6 vsl| 1000 Link c req 1003 rxreq
**** v1 0.6 vsl| 1004 Begin b bereq 1003 pass
**** v1 0.6 vsl| 1004 Timestamp b Start: 1469003007.784953 0.000000 0.000000
**** v1 0.6 vsl| 1004 BereqMethod b GET
**** v1 0.6 vsl| 1004 BereqURL b /
**** v1 0.6 vsl| 1004 BereqProtocol b HTTP/1.1
**** v1 0.6 vsl| 1004 BereqHeader b Cookie: cookie1=foobarbazfoobarbazphohx8aingie6Ide7peephie5paip6ang4thooh4ooquai8ohvah7eiqueeki8ooth7viequ0Tha5thewiSheih5jaimaiTahr1wi8WooQuoe7loothieThahweeneichoo8cufeelu3tie5cei1iShiemiezoofox6ahcaevaihocheungai2aeghaichaingee0EiGie3Ees5ujaem5uquahpi
**** v1 0.6 vsl| 1004 BereqHeader b X-Forwarded-For: 127.0.0.1
**** v1 0.6 vsl| 1004 BereqHeader b X-Varnish: 1004
**** v1 0.6 vsl| 1004 VCL_call b BACKEND_FETCH
**** v1 0.6 vsl| 1004 VCL_return b fetch
**** v1 0.6 vsl| 1004 BereqHeader b Host: 127.0.0.1
**** v1 0.6 vsl| 1004 BackendOpen b 23 vcl1.s1 127.0.0.1 60202 127.0.0.1 36673
**** v1 0.6 vsl| 1004 BackendStart b 127.0.0.1 60202
**** v1 0.6 vsl| 1004 Timestamp b Bereq: 1469003007.784977 0.000024 0.000024
**** v1 0.6 vsl| 1004 Timestamp b Beresp: 1469003007.790236 0.005283 0.005259
**** v1 0.6 vsl| 1004 BerespProtocol b HTTP/1.1
**** v1 0.6 vsl| 1004 BerespStatus b 200
**** v1 0.6 vsl| 1004 BerespReason b OK
**** v1 0.6 vsl| 1004 BerespHeader b Content-Length: 0
**** v1 0.6 vsl| 1004 BerespHeader b Date: Wed, 20 Jul 2016 08:23:27 GMT
**** v1 0.6 vsl| 1004 TTL b RFC 120 10 -1 1469003008 1469003008 1469003007 0 0
**** v1 0.6 vsl| 1004 VCL_call b BACKEND_RESPONSE
**** v1 0.6 vsl| 1004 TTL b VCL 120 10 0 1469003008
**** v1 0.6 vsl| 1004 VCL_return b deliver
**** v1 0.6 vsl| 1004 Storage b malloc Transient
**** v1 0.6 vsl| 1004 ObjProtocol b HTTP/1.1
**** v1 0.6 vsl| 1004 ObjStatus b 200
**** v1 0.6 vsl| 1004 ObjReason b OK
**** v1 0.6 vsl| 1004 ObjHeader b Content-Length: 0
**** v1 0.6 vsl| 1004 ObjHeader b Date: Wed, 20 Jul 2016 08:23:27 GMT
**** v1 0.6 vsl| 1004 Fetch_Body b 0 none -
**** v1 0.6 vsl| 1004 BackendReuse b 23 vcl1.s1
**** v1 0.6 vsl| 1004 Timestamp b BerespBody: 1469003007.800383 0.015430 0.010147
**** v1 0.6 vsl| 1004 Length b 0
**** v1 0.6 vsl| 1004 BereqAcct b 6261 0 6261 38 0 38
**** v1 0.6 vsl| 1004 End b
**** v1 0.6 vsl| 1003 Begin c req 1000 rxreq
**** v1 0.6 vsl| 1003 Timestamp c Start: 1469003007.784911 0.000000 0.000000
**** v1 0.6 vsl| 1003 Timestamp c Req: 1469003007.784911 0.000000 0.000000
**** v1 0.6 vsl| 1003 ReqStart c 127.0.0.1 51880
**** v1 0.6 vsl| 1003 ReqMethod c GET
**** v1 0.6 vsl| 1003 ReqURL c /
**** v1 0.6 vsl| 1003 ReqProtocol c HTTP/1.1
**** v1 0.6 vsl| 1003 ReqHeader c Cookie: cookie1=foobarbazfoobarbazphohx8aingie6Ide7peephie5paip6ang4thooh4ooquai8ohvah7eiqueeki8ooth7viequ0Tha5thewiSheih5jaimaiTahr1wi8WooQuoe7loothieThahweeneichoo8cufeelu3tie5cei1iShiemiezoofox6ahcaevaihocheungai2aeghaichaingee0EiGie3Ees5ujaem5uquahpi
**** v1 0.6 vsl| 1003 ReqHeader c X-Forwarded-For: 127.0.0.1
**** v1 0.6 vsl| 1003 VCL_call c RECV
**** v1 0.6 vsl| 1003 VCL_return c pass
**** v1 0.6 vsl| 1003 VCL_call c HASH
**** v1 0.6 vsl| 1003 VCL_return c lookup
**** v1 0.6 vsl| 1003 VCL_call c PASS
**** v1 0.6 vsl| 1003 VCL_return c fetch
**** v1 0.6 vsl| 1003 Link c bereq 1004 pass
**** v1 0.6 vsl| 1003 Timestamp c Fetch: 1469003007.800401 0.015491 0.015491
**** v1 0.6 vsl| 1003 RespProtocol c HTTP/1.1
**** v1 0.6 vsl| 1003 RespStatus c 200
**** v1 0.6 vsl| 1003 RespReason c OK
**** v1 0.6 vsl| 1003 RespHeader c Content-Length: 0
**** v1 0.6 vsl| 1003 RespHeader c Date: Wed, 20 Jul 2016 08:23:27 GMT
**** v1 0.6 vsl| 1003 RespHeader c X-Varnish: 1003
**** v1 0.6 vsl| 1003 RespHeader c Age: 0
**** v1 0.6 vsl| 1003 RespHeader c Via: 1.1 varnish-v4
**** v1 0.6 vsl| 1003 VCL_call c DELIVER
**** v1 0.6 vsl| 1003 VCL_Log c cookie: parsed 1 cookies.
**** v1 0.6 vsl| 1003 VCL_Log c cookie: Workspace overflow
**** v1 0.6 vsl| 1003 RespHeader c cookiestring:
**** v1 0.6 vsl| 1003 RespHeader c cookie1: true
**** v1 0.6 vsl| 1003 VCL_return c deliver
**** v1 0.6 vsl| 1003 Timestamp c Process: 1469003007.800479 0.015568 0.000077
**** v1 0.6 vsl| 1003 RespHeader c Accept-Ranges: bytes
**** v1 0.6 vsl| 1003 Debug c RES_MODE 2
**** v1 0.6 vsl| 1003 RespHeader c Connection: keep-alive
**** v1 0.6 vsl| 1003 Error c workspace_client overflow
**** v1 0.6 vsl| 1003 RespProtocol c HTTP/1.1
**** v1 0.6 vsl| 1003 RespStatus c 500
**** v1 0.6 vsl| 1003 RespReason c Internal Server Error
**** v1 0.6 vsl| 1003 Timestamp c Resp: 1469003007.800508 0.015597 0.000029
**** v1 0.6 vsl| 1003 ReqAcct c 6199 0 6199 0 0 0
**** v1 0.6 vsl| 1003 End c
**** v1 0.6 vsl| 1000 SessClose c TX_EOF 0.029
**** v1 0.6 vsl| 1000 End c
**** v1 0.6 vsl| 0 CLI - Rd backend.list
**** v1 0.6 vsl| 0 CLI - Wr 200 108 Backend name Admin Probe
vcl1.s1 probe Healthy (no probe)
*** v1 1.6 debug| Debug: Stopping Child\n
**** v1 1.6 vsl| 0 CLI - EOF on CLI connection, worker stops
*** v1 2.6 debug| Info: Child (32553) ended\n
*** v1 2.6 debug| Info: Child (32553) said Child dies\n
*** v1 2.6 debug| Debug: Child cleanup complete\n
**** v1 2.6 STDOUT poll 0x10
** v1 2.6 R 32541 Status: 0000 (u 0.164000 s 0.040000)
* top 2.6 TEST ../src/tests/cookie/08-overflow.vtc FAILED
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment