Attachment 918516 Details for Bug 1120402 – build.log

build.log

file_1120402.txt (text/plain), 13.70 KB, created by Zenon Panoussis on 2014-07-16 20:28:42 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Zenon Panoussis

Created: 2014-07-16 20:28:42 UTC

Size: 13.70 KB

patch

obsolete

>===================================================
>   Varnish 4.0.0: bin/varnishtest/test-suite.log   
>===================================================
>1 of 365 tests failed.  
>.. contents:: :depth: 2
>FAIL: tests/m00011.vtc (exit: 2)
>================================
>**** 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 from "/builddir/build/BUILD/varnish-4.0.0/lib/libvmod_std/.libs/libvmod_std.so"
>**** top   0.0 macro def vmod_debug=debug from "/builddir/build/BUILD/varnish-4.0.0/lib/libvmod_debug/.libs/libvmod_debug.so"
>**** top   0.0 macro def vmod_directors=directors from "/builddir/build/BUILD/varnish-4.0.0/lib/libvmod_directors/.libs/libvmod_directors.so"
>**** top   0.0 macro def pwd=/builddir/build/BUILD/varnish-4.0.0/bin/varnishtest
>**** top   0.0 macro def topbuild=/builddir/build/BUILD/varnish-4.0.0
>**** top   0.0 macro def bad_ip=192.0.2.255
>**** top   0.0 macro def tmpdir=/tmp/vtc.20190.3cf1309f
>*    top   0.0 TEST ./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=34150
>**** s1    0.0 macro def s1_sock=127.0.0.1 34150
>*    s1    0.0 Listen on 127.0.0.1 34150
>***  top   0.0 varnish
>**   s1    0.0 Started on 127.0.0.1 34150
>**   v1    0.0 Launch
>***  v1    0.0 CMD: cd ${pwd} && ${varnishd} -d -d -n /tmp/vtc.20190.3cf1309f/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 34482' -P /tmp/vtc.20190.3cf1309f/v1/varnishd.pid 
>***  v1    0.0 CMD: cd /builddir/build/BUILD/varnish-4.0.0/bin/varnishtest && varnishd -d -d -n /tmp/vtc.20190.3cf1309f/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 34482' -P /tmp/vtc.20190.3cf1309f/v1/varnishd.pid 
>***  v1    0.0 PID: 20209
>***  v1    0.1 debug| Platform: Linux,2.6.32-431.20.3.el6.x86_64,i686,-smalloc,-smalloc,-hcritbit\n
>***  v1    0.1 debug| 200 283     \n
>***  v1    0.1 debug| -----------------------------\n
>***  v1    0.1 debug| Varnish Cache CLI 1.0\n
>***  v1    0.1 debug| -----------------------------\n
>***  v1    0.1 debug| Linux,2.6.32-431.20.3.el6.x86_64,i686,-smalloc,-smalloc,-hcritbit\n
>***  v1    0.1 debug| varnish-4.0.0 revision 2acedeb\n
>***  v1    0.1 debug| \n
>***  v1    0.1 debug| Type 'help' for command list.\n
>***  v1    0.1 debug| Type 'quit' to close CLI session.\n
>***  v1    0.1 debug| Type 'start' to launch worker process.\n
>***  v1    0.1 debug| \n
>**** v1    0.2 CLIPOLL 1 0x1 0x0
>***  v1    0.2 CLI connection fd = 9
>***  v1    0.2 CLI RX  107
>**** v1    0.2 CLI RX| qvvvjhbczuofecoffkppaculgkecaszs\n
>**** v1    0.2 CLI RX| \n
>**** v1    0.2 CLI RX| Authentication required.\n
>**** v1    0.2 CLI TX| auth 8a502f4c4b1c454917bee9d08262bd6101ec6cab0b058b96f9ce4b3fc1d3a1cb\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,2.6.32-431.20.3.el6.x86_64,i686,-smalloc,-smalloc,-hcritbit\n
>**** v1    0.2 CLI RX| varnish-4.0.0 revision 2acedeb\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| vcl 4.0;\n
>**** v1    0.2 CLI TX| backend s1 { .host = "127.0.0.1"; .port = "34150"; }\n
>**** v1    0.2 CLI TX| \n
>**** v1    0.2 CLI TX| \n
>**** v1    0.2 CLI TX| \n
>**** v1    0.2 CLI TX| \timport std from "/builddir/build/BUILD/varnish-4.0.0/lib/libvmod_std/.libs/libvmod_std.so";\n
>**** v1    0.2 CLI TX| \tsub vcl_deliver {\n
>**** v1    0.2 CLI TX| \t\tset resp.http.foo0 = std.ip("8.8.8.*", client.ip);\n
>**** v1    0.2 CLI TX| \t\tset resp.http.foo1 = std.ip("9.9.9.*", server.ip);\n
>**** v1    0.2 CLI TX| \t\tset resp.http.foo2 = std.ip("1.2.3.*", "127.0.0.2");\n
>**** v1    0.2 CLI TX| \t\tset resp.http.foo3 = std.ip("1.2.3.5", "127.0.0.3");\n
>**** v1    0.2 CLI TX| \t}\n
>**** v1    0.2 CLI TX| \n
>**** v1    0.2 CLI TX| %XJEIFLH|)Xspa8P\n
>***  v1    0.3 CLI RX  200
>**** v1    0.3 CLI RX| Message from VCC-compiler:\n
>**** v1    0.3 CLI RX| Not running as root, no priv-sep\n
>**** v1    0.3 CLI RX| Message from C-compiler:\n
>**** v1    0.3 CLI RX| Not running as root, no priv-sep\n
>**** v1    0.3 CLI RX| Message from dlopen:\n
>**** v1    0.3 CLI RX| Not running as root, no priv-sep\n
>**** v1    0.3 CLI RX| \n
>**** v1    0.3 CLI RX| VCL compiled.
>**** v1    0.3 CLI TX| vcl.use vcl1
>***  v1    0.3 CLI RX  200
>**** v1    0.3 CLI RX| VCL 'vcl1' now active
>**   v1    0.3 Start
>**** v1    0.3 CLI TX| start
>***  v1    0.4 debug| child (20235) Started\n
>***  v1    0.4 CLI RX  200
>***  v1    0.4 wait-running
>**** v1    0.4 CLI TX| status
>***  v1    0.4 debug| Child (20235) said Not running as root, no priv-sep\n
>***  v1    0.4 debug| Child (20235) said Child starts\n
>***  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.4 CLI RX  200
>**** v1    0.4 CLI RX| XID is 999
>**** v1    0.4 CLI TX| debug.listen_address
>**** v1    0.4 vsl|          0 CLI             - Rd debug.xid 999 
>**** v1    0.4 vsl|          0 CLI             - Wr 200 10 XID is 999
>***  v1    0.5 CLI RX  200
>**** v1    0.5 CLI RX| 127.0.0.1 51806\n
>**   v1    0.5 Listen on 127.0.0.1 51806
>**** v1    0.5 macro def v1_addr=127.0.0.1
>**** v1    0.5 macro def v1_port=51806
>**** v1    0.5 macro def v1_sock=127.0.0.1 51806
>***  top   0.5 client
>**   c1    0.5 Starting client
>**   c1    0.5 Waiting for client
>***  c1    0.5 Connect to 127.0.0.1 51806
>***  c1    0.5 connected fd 10 from 127.0.0.1 43951 to 127.0.0.1 51806
>***  c1    0.5 txreq
>**** c1    0.5 txreq| GET /foo1 HTTP/1.1\r\n
>**** c1    0.5 txreq| \r\n
>***  c1    0.5 rxresp
>***  s1    0.5 accepted fd 4
>***  s1    0.5 rxreq
>**** s1    0.5 rxhdr| GET /foo1 HTTP/1.1\r\n
>**** s1    0.5 rxhdr| X-Forwarded-For: 127.0.0.1\r\n
>**** s1    0.5 rxhdr| Accept-Encoding: gzip\r\n
>**** s1    0.5 rxhdr| X-Varnish: 1002\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] | /foo1
>**** 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] | Accept-Encoding: gzip
>**** 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: 1\r\n
>**** s1    0.5 txresp| \r\n
>**** s1    0.5 txresp| 1
>***  s1    0.5 shutting fd 4
>**   s1    0.5 Ending
>**** v1    0.5 vsl|          0 CLI             - Rd debug.listen_address 
>**** v1    0.5 vsl|          0 CLI             - Wr 200 16 127.0.0.1 51806
>**** v1    0.5 vsl|       1000 Begin           c sess 0 HTTP/1
>**** v1    0.5 vsl|       1000 SessOpen        c 127.0.0.1 43951 127.0.0.1:0 127.0.0.1 51806 1405539781.540535 13
>**** v1    0.5 vsl|       1000 Link            c req 1001 rxreq
>**** v1    0.5 vsl|       1002 Begin           b bereq 1001 fetch 
>**** v1    0.5 vsl|       1002 Timestamp       b Start: 1405539781.540816 0.000000 0.000000
>**** v1    0.5 vsl|       1002 BereqMethod     b GET
>**** v1    0.5 vsl|       1002 BereqURL        b /foo1
>**** v1    0.5 vsl|       1002 BereqProtocol   b HTTP/1.1
>**** v1    0.5 vsl|       1002 BereqHeader     b X-Forwarded-For: 127.0.0.1
>**** v1    0.5 vsl|       1002 BereqHeader     b Accept-Encoding: gzip
>**** v1    0.5 vsl|       1002 BereqHeader     b X-Varnish: 1002
>**** v1    0.5 vsl|       1002 VCL_call        b BACKEND_FETCH
>**** v1    0.5 vsl|       1002 VCL_return      b fetch
>**** v1    0.5 vsl|       1002 BackendOpen     b 15 s1(127.0.0.1,,34150) 127.0.0.1 53093 
>**** v1    0.5 vsl|       1002 Backend         b 15 s1 s1(127.0.0.1,,34150)
>**** v1    0.5 vsl|       1002 BereqHeader     b Host: 127.0.0.1
>**** v1    0.5 vsl|       1002 Timestamp       b Bereq: 1405539781.541019 0.000203 0.000203
>**** v1    0.5 vsl|       1002 Timestamp       b Beresp: 1405539781.542168 0.001352 0.001149
>**** v1    0.5 vsl|       1002 BerespProtocol  b HTTP/1.1
>**** v1    0.5 vsl|       1002 BerespStatus    b 200
>**** v1    0.5 vsl|       1002 BerespResponse  b Ok
>**** v1    0.5 vsl|       1002 BerespHeader    b Content-Length: 1
>**** v1    0.5 vsl|       1002 TTL             b RFC 120 -1 -1 1405539782 1405539782 0 0 0
>**** v1    0.5 vsl|       1002 VCL_call        b BACKEND_RESPONSE
>**** v1    0.5 vsl|       1002 VCL_return      b deliver
>**** v1    0.5 vsl|       1002 Storage         b malloc s0
>**** v1    0.5 vsl|       1002 ObjProtocol     b HTTP/1.1
>**** v1    0.5 vsl|       1002 ObjStatus       b 200
>**** v1    0.5 vsl|       1002 ObjResponse     b Ok
>**** v1    0.5 vsl|       1002 Fetch_Body      b 3 length stream
>**** v1    0.5 vsl|       1002 Timestamp       b BerespBody: 1405539781.542260 0.001445 0.000093
>**** v1    0.5 vsl|       1002 BackendReuse    b 15 s1(127.0.0.1,,34150)
>**** v1    0.5 vsl|       1002 Length          b 1
>**** v1    0.5 vsl|       1002 BereqAcct       b 107 0 107 38 1 39
>**** v1    0.5 vsl|       1002 End             b 
>**** v1    0.5 vsl|          0 ExpKill         - EXP_Inbox p=0xe9c17060 e=0.000000000 f=0x0
>**** v1    0.5 vsl|          0 ExpKill         - EXP_When p=0xe9c17060 e=1405539911.540639400 f=0x1c10
>**** v1    3.4 vsl|          0 CLI             - Rd ping
>**** v1    3.4 vsl|          0 CLI             - Wr 200 19 PONG 1405539784 1.0
>**** v1    6.4 vsl|          0 CLI             - Rd ping
>**** v1    6.4 vsl|          0 CLI             - Wr 200 19 PONG 1405539787 1.0
>**** v1    9.4 vsl|          0 CLI             - Rd ping
>**** v1    9.4 vsl|          0 CLI             - Wr 200 19 PONG 1405539790 1.0
>**** v1   12.4 vsl|          0 CLI             - Rd ping
>**** v1   12.4 vsl|          0 CLI             - Wr 200 19 PONG 1405539793 1.0
>**** v1   15.4 vsl|          0 CLI             - Rd ping
>**** v1   15.4 vsl|          0 CLI             - Wr 200 19 PONG 1405539796 1.0
>---- c1   15.5 HTTP rx timeout (fd:10 15000 ms)
>*    top  15.5 RESETTING after ./tests/m00011.vtc
>**   s1   15.5 Waiting for server
>**** s1   15.5 macro undef s1_addr
>**** s1   15.5 macro undef s1_port
>**** s1   15.5 macro undef s1_sock
>**** v1   16.2 vsl|       1001 Begin           c req 1000 rxreq
>**** v1   16.2 vsl|       1001 Timestamp       c Start: 1405539781.540639 0.000000 0.000000
>**** v1   16.2 vsl|       1001 Timestamp       c Req: 1405539781.540639 0.000000 0.000000
>**** v1   16.2 vsl|       1001 ReqStart        c 127.0.0.1 43951
>**** v1   16.2 vsl|       1001 ReqMethod       c GET
>**** v1   16.2 vsl|       1001 ReqURL          c /foo1
>**** v1   16.2 vsl|       1001 ReqProtocol     c HTTP/1.1
>**** v1   16.2 vsl|       1001 ReqHeader       c X-Forwarded-For: 127.0.0.1
>**** v1   16.2 vsl|       1001 VCL_call        c RECV
>**** v1   16.2 vsl|       1001 VCL_return      c hash
>**** v1   16.2 vsl|       1001 VCL_call        c HASH
>**** v1   16.2 vsl|       1001 VCL_return      c lookup
>**** v1   16.2 vsl|       1001 Debug           c XXXX MISS
>**** v1   16.2 vsl|       1001 VCL_call        c MISS
>**** v1   16.2 vsl|       1001 VCL_return      c fetch
>**** v1   16.2 vsl|       1001 Link            c bereq 1002 fetch 
>**** v1   16.2 vsl|       1001 Timestamp       c Fetch: 1405539781.542264 0.001625 0.001625
>**** v1   16.2 vsl|       1001 Timestamp       c Process: 1405539781.542300 0.001661 0.000036
>**** v1   16.2 vsl|       1001 RespProtocol    c HTTP/1.1
>**** v1   16.2 vsl|       1001 RespStatus      c 200
>**** v1   16.2 vsl|       1001 RespResponse    c Ok
>**** v1   16.2 vsl|       1001 RespHeader      c Date: Wed, 16 Jul 2014 19:43:01 GMT
>**** v1   16.2 vsl|       1001 RespHeader      c X-Varnish: 1001
>**** v1   16.2 vsl|       1001 RespHeader      c Age: 0
>**** v1   16.2 vsl|       1001 RespHeader      c Via: 1.1 varnish (v4)
>**** v1   16.2 vsl|       1001 VCL_call        c DELIVER
>**** v1   16.2 vsl|       1001 RespHeader      c foo0: 127.0.0.1
>**** v1   16.2 vsl|       1001 RespHeader      c foo1: 127.0.0.1
>**** v1   16.2 vsl|       1001 RespHeader      c foo2: 127.0.0.2
>**** v1   16.2 vsl|       1001 RespHeader      c foo3: 1.2.3.5
>**** v1   16.2 vsl|       1001 VCL_return      c deliver
>**** v1   16.2 vsl|       1001 RespHeader      c Content-Length: 1
>**** v1   16.2 vsl|       1001 Debug           c RES_MODE 2
>**** v1   16.2 vsl|       1001 RespHeader      c Connection: keep-alive
>**** v1   16.2 vsl|       1001 RespHeader      c Accept-Ranges: bytes
>**** v1   16.2 vsl|       1001 Timestamp       c Resp: 1405539797.276002 15.735363 15.733702
>**** v1   16.2 vsl|       1001 Debug           c XXX REF 2
>**** v1   16.2 vsl|       1001 ReqAcct         c 22 0 22 235 1 236
>**** v1   16.2 vsl|       1001 End             c 
>**   v1   16.5 Wait
>**** v1   16.5 vsl|          0 CLI             - EOF on CLI connection, worker stops
>**** v1   17.5 STDOUT poll 0x10
>**   v1   17.5 R 20209 Status: 0000 (u 0.164974 s 0.122981)
>*    top  17.5 TEST ./tests/m00011.vtc FAILED
>#     top  TEST ./tests/m00011.vtc FAILED (17.498) exit=1
>==============================================
>1 of 365 tests failed
>See bin/varnishtest/test-suite.log
>Please report to varnish-dev@varnish-cache.org
>==============================================

Actions: View

Attachments on bug 1120402: 918516