[Varnish] #1520: m00011.vtc fails on x86_64

Varnish varnish-bugs at varnish-cache.org
Mon Jun 9 16:37:41 CEST 2014


#1520: m00011.vtc fails on x86_64
----------------------+-------------------------
 Reporter:  yoloseem  |       Type:  defect
   Status:  new       |   Priority:  normal
Milestone:            |  Component:  varnishtest
  Version:  4.0.0     |   Severity:  normal
 Keywords:            |
----------------------+-------------------------
 Varnish 4.0.0 does not compile on x86_6/OS X 10.9.3, the m00011.vtc test
 fails :

 {{{
 $ varnishtest bin/varnishtest/tests/m00011.vtc
 **** top   0.0 macro def varnishd=varnishd
 **** top   0.0 macro def varnishadm=varnishadm
 **** top   0.0 macro def varnishstat=varnishstat
 **** top   0.0 macro def varnishhist=varnishhist
 **** top   0.0 macro def varnishlog=varnishlog
 **** top   0.0 macro def varnishncsa=varnishncsa
 **** top   0.0 macro def vmod_std=std
 **** top   0.0 macro def vmod_debug=debug
 **** top   0.0 macro def vmod_directors=directors
 **** top   0.0 macro def pwd=/Users/yoloseem/Dropbox/works/varnish-4.0.0
 **** top   0.0 macro def bad_ip=192.0.2.255
 **** top   0.0 macro def
 tmpdir=/var/folders/x_/35t1c_0n12d9xs1t83tp4grw0000gn/T//vtc.87569.2123a94f
 *    top   0.0 TEST bin/varnishtest/tests/m00011.vtc starting
 ***  top   0.0 varnishtest
 *    top   0.0 TEST Test std.ip
 ***  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=58591
 **** s1    0.0 macro def s1_sock=127.0.0.1 58591
 *    s1    0.0 Listen on 127.0.0.1 58591
 ***  top   0.0 varnish
 **   s1    0.0 Started on 127.0.0.1 58591
 **   v1    0.0 Launch
 ***  v1    0.0 CMD: cd ${pwd} && ${varnishd} -d -d -n
 /var/folders/x_/35t1c_0n12d9xs1t83tp4grw0000gn/T//vtc.87569.2123a94f/v1 -l
 2m,1m,- -p auto_restart=off -p syslog_cli_traffic=off -p
 sigsegv_handler=on -a '127.0.0.1:0' -M '127.0.0.1 58592' -P
 /var/folders/x_/35t1c_0n12d9xs1t83tp4grw0000gn/T//vtc.87569.2123a94f/v1/varnishd.pid
 ***  v1    0.0 CMD: cd /Users/yoloseem/Dropbox/works/varnish-4.0.0 &&
 varnishd -d -d -n
 /var/folders/x_/35t1c_0n12d9xs1t83tp4grw0000gn/T//vtc.87569.2123a94f/v1 -l
 2m,1m,- -p auto_restart=off -p syslog_cli_traffic=off -p
 sigsegv_handler=on -a '127.0.0.1:0' -M '127.0.0.1 58592' -P
 /var/folders/x_/35t1c_0n12d9xs1t83tp4grw0000gn/T//vtc.87569.2123a94f/v1/varnishd.pid
 ***  v1    0.0 PID: 87574
 ***  v1    0.0 debug| Platform:
 Darwin,13.2.0,x86_64,-smalloc,-smalloc,-hcritbit\n
 ***  v1    0.0 debug| 200 266     \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| Darwin,13.2.0,x86_64,-smalloc,-smalloc,-hcritbit\n
 ***  v1    0.0 debug| varnish-4.0.0 revision 2acedeb\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 = 8
 ***  v1    0.1 CLI RX  107
 **** v1    0.1 CLI RX| zhlrbwasavvyeeeeymhleoinkzgkxjay\n
 **** v1    0.1 CLI RX| \n
 **** v1    0.1 CLI RX| Authentication required.\n
 **** v1    0.1 CLI TX| auth
 a6f9cb0852c99143ed36b8f2f9fcd611aa5063690564f88c3c10da2fc377fbe2\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| Darwin,13.2.0,x86_64,-smalloc,-smalloc,-hcritbit\n
 **** v1    0.1 CLI RX| varnish-4.0.0 revision 2acedeb\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 = "58591";
 }\n
 **** v1    0.1 CLI TX| \n
 **** v1    0.1 CLI TX| \n
 **** v1    0.1 CLI TX| \n
 **** v1    0.1 CLI TX|   import std;\n
 **** v1    0.1 CLI TX|   sub vcl_deliver {\n
 **** v1    0.1 CLI TX|     set resp.http.foo0 = std.ip("8.8.8.*",
 client.ip);\n
 **** v1    0.1 CLI TX|     set resp.http.foo1 = std.ip("9.9.9.*",
 server.ip);\n
 **** v1    0.1 CLI TX|     set resp.http.foo2 = std.ip("1.2.3.*",
 "127.0.0.2");\n
 **** v1    0.1 CLI TX|     set resp.http.foo3 = std.ip("1.2.3.5",
 "127.0.0.3");\n
 **** v1    0.1 CLI TX|   }\n
 **** v1    0.1 CLI TX| \n
 **** v1    0.1 CLI TX| %XJEIFLH|)Xspa8P\n
 ***  v1    0.2 CLI RX  200
 **** v1    0.2 CLI RX| Message from VCC-compiler:\n
 **** v1    0.2 CLI RX| Not running as root, no priv-sep\n
 **** v1    0.2 CLI RX| Message from C-compiler:\n
 **** v1    0.2 CLI RX| Not running as root, no priv-sep\n
 **** v1    0.2 CLI RX| Message from dlopen:\n
 **** v1    0.2 CLI RX| Not running as root, no priv-sep\n
 **** v1    0.2 CLI RX| \n
 **** v1    0.2 CLI RX| VCL compiled.
 **** v1    0.2 CLI TX| vcl.use vcl1
 ***  v1    0.2 CLI RX  200
 **** v1    0.2 CLI RX| VCL 'vcl1' now active
 **   v1    0.2 Start
 **** v1    0.2 CLI TX| start
 ***  v1    0.2 debug| child (87584) Started\n
 ***  v1    0.2 CLI RX  200
 ***  v1    0.2 debug| Child (87584) said Not running as root, no priv-
 sep\n
 ***  v1    0.2 wait-running
 **** v1    0.2 CLI TX| status
 ***  v1    0.2 debug| Child (87584) said Child starts\n
 ***  v1    0.2 CLI RX  200
 **** v1    0.2 CLI RX| Child in state running
 **** v1    0.2 CLI TX| debug.xid 999
 ***  v1    0.2 CLI RX  200
 **** v1    0.2 CLI RX| XID is 999
 **** v1    0.2 CLI TX| debug.listen_address
 ***  v1    0.2 CLI RX  200
 **** v1    0.2 CLI RX| 127.0.0.1 58597\n
 **   v1    0.2 Listen on 127.0.0.1 58597
 **** v1    0.2 macro def v1_addr=127.0.0.1
 **** v1    0.2 macro def v1_port=58597
 **** v1    0.2 macro def v1_sock=127.0.0.1 58597
 ***  top   0.2 client
 **   c1    0.2 Starting client
 **   c1    0.2 Waiting for client
 ***  c1    0.2 Connect to 127.0.0.1 58597
 ***  c1    0.2 connected fd 9 from 127.0.0.1 58598 to 127.0.0.1 58597
 ***  c1    0.2 txreq
 **** c1    0.2 txreq| GET /foo1 HTTP/1.1\r\n
 **** c1    0.2 txreq| \r\n
 ***  c1    0.2 rxresp
 ***  s1    0.3 accepted fd 10
 ***  s1    0.3 rxreq
 **** s1    0.3 rxhdr| GET /foo1 HTTP/1.1\r\n
 **** s1    0.3 rxhdr| X-Forwarded-For: 127.0.0.1\r\n
 **** s1    0.3 rxhdr| Accept-Encoding: gzip\r\n
 **** s1    0.3 rxhdr| X-Varnish: 1002\r\n
 **** s1    0.3 rxhdr| Host: 127.0.0.1\r\n
 **** s1    0.3 rxhdr| \r\n
 **** s1    0.3 http[ 0] | GET
 **** s1    0.3 http[ 1] | /foo1
 **** s1    0.3 http[ 2] | HTTP/1.1
 **** s1    0.3 http[ 3] | X-Forwarded-For: 127.0.0.1
 **** s1    0.3 http[ 4] | Accept-Encoding: gzip
 **** s1    0.3 http[ 5] | X-Varnish: 1002
 **** s1    0.3 http[ 6] | Host: 127.0.0.1
 **** s1    0.3 bodylen = 0
 ***  s1    0.3 txresp
 **** s1    0.3 txresp| HTTP/1.1 200 Ok\r\n
 **** s1    0.3 txresp| Content-Length: 1\r\n
 **** s1    0.3 txresp| \r\n
 **** s1    0.3 txresp| 1
 ***  s1    0.3 shutting fd 10
 **   s1    0.3 Ending
 ---- c1    0.3 HTTP rx EOF (fd:9 read: Undefined error: 0)
 *    top   0.3 RESETTING after bin/varnishtest/tests/m00011.vtc
 **   s1    0.3 Waiting for server
 **** s1    0.3 macro undef s1_addr
 **** s1    0.3 macro undef s1_port
 **** s1    0.3 macro undef s1_sock
 **** v1    0.3 vsl|       1000 Begin           c sess 0 HTTP/1
 **** v1    0.3 vsl|       1000 SessOpen        c 127.0.0.1 58598
 127.0.0.1:0 127.0.0.1 58597 1402324058.106455 6
 **** v1    0.3 vsl|       1000 Link            c req 1001 rxreq
 **** v1    0.3 vsl|       1002 Begin           b bereq 1001 fetch
 **** v1    0.3 vsl|       1002 Timestamp       b Start: 1402324058.107015
 0.000000 0.000000
 **** v1    0.3 vsl|       1002 BereqMethod     b GET
 **** v1    0.3 vsl|       1002 BereqURL        b /foo1
 **** v1    0.3 vsl|       1002 BereqProtocol   b HTTP/1.1
 **** v1    0.3 vsl|       1002 BereqHeader     b X-Forwarded-For:
 127.0.0.1
 **** v1    0.3 vsl|       1002 BereqHeader     b Accept-Encoding: gzip
 **** v1    0.3 vsl|       1002 BereqHeader     b X-Varnish: 1002
 **** v1    0.3 vsl|       1002 VCL_call        b BACKEND_FETCH
 **** v1    0.3 vsl|       1002 VCL_return      b fetch
 **** v1    0.3 vsl|       1002 BackendOpen     b 7 s1(127.0.0.1,,58591)
 127.0.0.1 58599
 **** v1    0.3 vsl|       1002 Backend         b 7 s1 s1(127.0.0.1,,58591)
 **** v1    0.3 vsl|       1002 BereqHeader     b Host: 127.0.0.1
 **** v1    0.3 vsl|       1002 Timestamp       b Bereq: 1402324058.107317
 0.000302 0.000302
 **** v1    0.3 vsl|       1002 Timestamp       b Beresp: 1402324058.107758
 0.000743 0.000441
 **** v1    0.3 vsl|       1002 BerespProtocol  b HTTP/1.1
 **** v1    0.3 vsl|       1002 BerespStatus    b 200
 **** v1    0.3 vsl|       1002 BerespResponse  b Ok
 **** v1    0.3 vsl|       1002 BerespHeader    b Content-Length: 1
 **** v1    0.3 vsl|       1002 TTL             b RFC 120 -1 -1 1402324058
 1402324058 0 0 0
 **** v1    0.3 vsl|       1002 VCL_call        b BACKEND_RESPONSE
 **** v1    0.3 vsl|       1002 VCL_return      b deliver
 **** v1    0.3 vsl|       1002 Storage         b malloc s0
 **** v1    0.3 vsl|       1002 ObjProtocol     b HTTP/1.1
 **** v1    0.3 vsl|       1002 ObjStatus       b 200
 **** v1    0.3 vsl|       1002 ObjResponse     b Ok
 **** v1    0.3 vsl|       1002 Fetch_Body      b 3 length stream
 **** v1    0.3 vsl|       1002 Timestamp       b BerespBody:
 1402324058.107860 0.000845 0.000102
 **** v1    0.3 vsl|       1002 BackendReuse    b 7 s1(127.0.0.1,,58591)
 **** v1    0.3 vsl|          0 ExpKill         - EXP_Inbox
 p=0x7fea0bf00990 e=0.000000000 f=0x0
 **** v1    0.3 vsl|          0 ExpKill         - EXP_When p=0x7fea0bf00990
 e=1402324188.106722116 f=0x1c10
 **** v1    0.3 vsl|       1002 Length          b 1
 **** v1    0.3 vsl|       1002 BereqAcct       b 107 0 107 38 1 39
 **** v1    0.3 vsl|       1002 End             b
 **** v1    0.3 vsl|       1001 Begin           c req 1000 rxreq
 **** v1    0.3 vsl|       1001 Timestamp       c Start: 1402324058.106722
 0.000000 0.000000
 **** v1    0.3 vsl|       1001 Timestamp       c Req: 1402324058.106722
 0.000000 0.000000
 **** v1    0.3 vsl|       1001 ReqStart        c 127.0.0.1 58598
 **** v1    0.3 vsl|       1001 ReqMethod       c GET
 **** v1    0.3 vsl|       1001 ReqURL          c /foo1
 **** v1    0.3 vsl|       1001 ReqProtocol     c HTTP/1.1
 **** v1    0.3 vsl|       1001 ReqHeader       c X-Forwarded-For:
 127.0.0.1
 **** v1    0.3 vsl|       1001 VCL_call        c RECV
 **** v1    0.3 vsl|       1001 VCL_return      c hash
 **** v1    0.3 vsl|       1001 VCL_call        c HASH
 **** v1    0.3 vsl|       1001 VCL_return      c lookup
 **** v1    0.3 vsl|       1001 Debug           c XXXX MISS
 **** v1    0.3 vsl|       1001 VCL_call        c MISS
 **** v1    0.3 vsl|       1001 VCL_return      c fetch
 **** v1    0.3 vsl|       1001 Link            c bereq 1002 fetch
 **** v1    0.3 vsl|       1001 Timestamp       c Fetch: 1402324058.107850
 0.001128 0.001128
 **** v1    0.3 vsl|       1001 Timestamp       c Process:
 1402324058.107860 0.001138 0.000010
 **** v1    0.3 vsl|       1001 RespProtocol    c HTTP/1.1
 **** v1    0.3 vsl|       1001 RespStatus      c 200
 **** v1    0.3 vsl|       1001 RespResponse    c Ok
 **** v1    0.3 vsl|       1001 RespHeader      c Date: Mon, 09 Jun 2014
 14:27:38 GMT
 **** v1    0.3 vsl|       1001 RespHeader      c X-Varnish: 1001
 **** v1    0.3 vsl|       1001 RespHeader      c Age: 0
 **** v1    0.3 vsl|       1001 RespHeader      c Via: 1.1 varnish (v4)
 **** v1    0.3 vsl|       1001 VCL_call        c DELIVER
 **** v1    0.3 vsl|       1001 RespHeader      c foo0: 127.0.0.1
 **** v1    0.3 vsl|       1001 RespHeader      c foo1: 127.0.0.1
 **** v1    0.3 vsl|       1001 RespHeader      c foo2: 127.0.0.2
 **** v1    0.3 vsl|       1001 RespHeader      c foo3: 1.2.3.5
 **** v1    0.3 vsl|       1001 VCL_return      c deliver
 **** v1    0.3 vsl|       1001 RespHeader      c Content-Length: 1
 **** v1    0.3 vsl|       1001 Debug           c RES_MODE 2
 **** v1    0.3 vsl|       1001 RespHeader      c Connection: keep-alive
 **** v1    0.3 vsl|       1001 RespHeader      c Accept-Ranges: bytes
 **** v1    0.3 vsl|       1001 Timestamp       c Resp: 1402324058.108992
 0.002270 0.001132
 **** v1    0.3 vsl|       1001 Debug           c XXX REF 2
 **** v1    0.3 vsl|       1001 ReqAcct         c 22 0 22 235 1 236
 **** v1    0.3 vsl|       1001 End             c
 **** v1    0.3 vsl|          0 ReqAcct         - 0 0 0 0 0 0
 **** v1    0.3 vsl|       1000 SessClose       c REM_CLOSE 0.003
 **** v1    0.3 vsl|       1000 End             c
 **   v1    1.3 Wait
 **** v1    1.3 vsl|          0 CLI             - EOF on CLI connection,
 worker stops
 **** v1    2.3 STDOUT poll 0x11
 **   v1    2.3 R 87574 Status: 0000 (u 0.062789 s 0.052513)
 *    top   2.3 TEST bin/varnishtest/tests/m00011.vtc FAILED

 #     top  TEST bin/varnishtest/tests/m00011.vtc FAILED (2.313) exit=1
 }}}

-- 
Ticket URL: <https://www.varnish-cache.org/trac/ticket/1520>
Varnish <https://varnish-cache.org/>
The Varnish HTTP Accelerator



More information about the varnish-bugs mailing list