[Varnish] #1196: TEST ./tests/b00028.vtc FAILED
Varnish
varnish-bugs at varnish-cache.org
Sat Sep 15 13:25:05 CEST 2012
#1196: TEST ./tests/b00028.vtc FAILED
--------------------------+--------------------
Reporter: plamenpetrov | Type: defect
Status: new | Priority: normal
Milestone: | Component: build
Version: 3.0.3 | Severity: normal
Keywords: |
--------------------------+--------------------
Following the docs install-from-source guide:
Before you install, you may want to run the regression tests, make a cup
of tea while it runs, it takes some minutes:
{{{
make check
}}}
Don't worry of a single or two tests fail, some of the tests are a bit too
timing sensitive (Please tell us which so we can fix it) but if a lot of
them fails, and in particular if the b00000.vtc test fails, something is
horribly wrong, and you will get nowhere without figuring out what.
.......
The "make check" command results in this:
{{{
make[2]: Entering directory `/root/compile/varnish-3.0.3/bin/varnishtest'
./varnishtest -i -j3 ./tests/*.vtc
# top TEST ./tests/a00000.vtc passed (0.021)
# top TEST ./tests/a00002.vtc passed (0.027)
# top TEST ./tests/a00001.vtc passed (0.052)
# top TEST ./tests/a00003.vtc passed (0.043)
# top TEST ./tests/a00005.vtc passed (0.022)
# top TEST ./tests/a00004.vtc passed (0.040)
# top TEST ./tests/a00006.vtc passed (0.038)
# top TEST ./tests/a00007.vtc passed (0.025)
# top TEST ./tests/a00010.vtc passed (0.020)
# top TEST ./tests/a00011.vtc passed (0.011)
# top TEST ./tests/a00012.vtc passed (0.010)
# top TEST ./tests/a00009.vtc passed (0.661)
# top TEST ./tests/b00000.vtc passed (1.098)
# top TEST ./tests/b00001.vtc passed (0.773)
# top TEST ./tests/a00008.vtc passed (1.815)
# top TEST ./tests/b00002.vtc passed (0.956)
# top TEST ./tests/b00003.vtc passed (0.871)
# top TEST ./tests/b00005.vtc passed (0.888)
# top TEST ./tests/b00006.vtc passed (0.761)
# top TEST ./tests/b00004.vtc passed (1.679)
# top TEST ./tests/b00007.vtc passed (0.836)
# top TEST ./tests/b00009.vtc passed (0.889)
# top TEST ./tests/b00008.vtc passed (1.383)
# top TEST ./tests/b00010.vtc passed (0.760)
# top TEST ./tests/b00011.vtc passed (0.850)
# top TEST ./tests/b00012.vtc passed (0.935)
# top TEST ./tests/b00013.vtc passed (0.892)
# top TEST ./tests/b00014.vtc passed (1.065)
# top TEST ./tests/b00016.vtc passed (1.102)
# top TEST ./tests/b00017.vtc passed (0.746)
# top TEST ./tests/b00018.vtc passed (0.739)
# top TEST ./tests/b00015.vtc passed (2.607)
# top TEST ./tests/b00019.vtc passed (0.958)
# top TEST ./tests/b00020.vtc passed (3.355)
# top TEST ./tests/b00022.vtc passed (3.298)
# top TEST ./tests/b00023.vtc passed (2.728)
# top TEST ./tests/b00024.vtc passed (2.717)
# top TEST ./tests/b00025.vtc passed (2.695)
# top TEST ./tests/b00027.vtc passed (0.689)
# top TEST ./tests/b00021.vtc passed (9.777)
**** top 0.0 macro def varnishd=../varnishd/varnishd
**** top 0.0 macro def pwd=/root/compile/varnish-3.0.3/bin/varnishtest
**** top 0.0 macro def
topbuild=/root/compile/varnish-3.0.3/bin/varnishtest/../..
**** top 0.0 macro def bad_ip=10.255.255.255
**** top 0.0 macro def tmpdir=/tmp/vtc.26365.1908605f
* top 0.0 TEST ./tests/b00028.vtc starting
*** top 0.0 varnishtest
* top 0.0 TEST regexp match and no-match
*** 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=44657
**** s1 0.0 macro def s1_sock=127.0.0.1 44657
* s1 0.0 Listen on 127.0.0.1 44657
*** top 0.0 varnish
** s1 0.0 Started on 127.0.0.1 44657
** v1 0.0 Launch
*** v1 0.0 CMD: cd ${pwd} && ${varnishd} -d -d -n
/tmp/vtc.26365.1908605f/v1 -l 10m,1m,- -p auto_restart=off -p
syslog_cli_traffic=off -a '127.0.0.1:0' -S /tmp/vtc.26365.1908605f/v1/_S
-M '127.0.0.1 45289' -P /tmp/vtc.26365.1908605f/v1/varnishd.pid
-sfile,/tmp/vtc.26365.1908605f/v1,10M
*** v1 0.0 CMD: cd /root/compile/varnish-3.0.3/bin/varnishtest &&
../varnishd/varnishd -d -d -n /tmp/vtc.26365.1908605f/v1 -l 10m,1m,- -p
auto_restart=off -p syslog_cli_traffic=off -a '127.0.0.1:0' -S
/tmp/vtc.26365.1908605f/v1/_S -M '127.0.0.1 45289' -P
/tmp/vtc.26365.1908605f/v1/varnishd.pid
-sfile,/tmp/vtc.26365.1908605f/v1,10M
*** v1 0.0 PID: 28082
*** v1 0.2 debug| Platform:
Linux,3.5.2-VMware,i686,-sfile,-smalloc,-hcritbit\n
*** v1 0.2 debug| 200 236 \n
*** v1 0.2 debug| -----------------------------\n
*** v1 0.2 debug| Varnish Cache CLI 1.0\n
*** v1 0.2 debug| -----------------------------\n
*** v1 0.2 debug| Linux,3.5.2-VMware,i686,-sfile,-smalloc,-hcritbit\n
*** v1 0.2 debug| \n
*** v1 0.2 debug| Type 'help' for command list.\n
*** v1 0.2 debug| Type 'quit' to close CLI session.\n
*** v1 0.2 debug| Type 'start' to launch worker process.\n
*** v1 0.2 debug| \n
**** v1 0.3 CLIPOLL 1 0x1 0x0
*** v1 0.3 CLI connection fd = 9
*** v1 0.3 CLI RX 107
**** v1 0.3 CLI RX| mzrgpylmkcvryutwapbnzwocdxeirvbd\n
**** v1 0.3 CLI RX| \n
**** v1 0.3 CLI RX| Authentication required.\n
**** v1 0.3 CLI TX| auth
da18274e07a44b47640737b36e8d080e679e7f8d287b91c68770aaf649ef9093\n
*** v1 0.3 CLI RX 200
**** v1 0.3 CLI RX| -----------------------------\n
**** v1 0.3 CLI RX| Varnish Cache CLI 1.0\n
**** v1 0.3 CLI RX| -----------------------------\n
**** v1 0.3 CLI RX| Linux,3.5.2-VMware,i686,-sfile,-smalloc,-hcritbit\n
**** v1 0.3 CLI RX| \n
**** v1 0.3 CLI RX| Type 'help' for command list.\n
**** v1 0.3 CLI RX| Type 'quit' to close CLI session.\n
**** v1 0.3 CLI RX| Type 'start' to launch worker process.\n
**** v1 0.3 CLI TX| vcl.inline vcl1 << %XJEIFLH|)Xspa8P\n
**** v1 0.3 CLI TX| backend s1 { .host = "127.0.0.1"; .port = "44657";
}\n
**** v1 0.3 CLI TX| \n
**** v1 0.3 CLI TX| \n
**** v1 0.3 CLI TX| \n
**** v1 0.3 CLI TX| \tsub vcl_fetch {\n
**** v1 0.3 CLI TX| \t\tif (beresp.http.foo ~ "bar") {\n
**** v1 0.3 CLI TX| \t\t\tset beresp.http.foo1 = "1";\n
**** v1 0.3 CLI TX| \t\t} else {\n
**** v1 0.3 CLI TX| \t\t\terror 999;\n
**** v1 0.3 CLI TX| \t\t}\n
**** v1 0.3 CLI TX| \t\tif (beresp.http.bar !~ "bar") {\n
**** v1 0.3 CLI TX| \t\t\tset beresp.http.bar1 = "2";\n
**** v1 0.3 CLI TX| \t\t} else {\n
**** v1 0.3 CLI TX| \t\t\terror 999;\n
**** v1 0.3 CLI TX| \t\t}\n
**** v1 0.3 CLI TX| \t}\n
**** v1 0.3 CLI TX| \n
**** v1 0.3 CLI TX| \n
**** v1 0.3 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 Start
**** v1 0.4 CLI TX| start
*** v1 0.5 debug| child (28108) Started\n
**** v1 0.5 vsl| 0 CLI - Rd vcl.load "vcl1"
./vcl.wqSbvggu.so
**** v1 0.5 vsl| 0 CLI - Wr 200 36 Loaded
"./vcl.wqSbvggu.so" as "vcl1"
**** v1 0.5 vsl| 0 WorkThread - 0xb3efb010 start
**** 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 Debug - Acceptor is epoll
**** v1 0.5 vsl| 0 CLI - Wr 200 0
**** v1 0.5 vsl| 0 WorkThread - 0xb22e8010 start
*** v1 0.5 CLI RX 200
*** v1 0.5 wait-running
**** v1 0.5 CLI TX| status
*** v1 0.5 debug| Child (28108) said Child starts\n
*** v1 0.5 debug| Child (28108) said SMF.s0 mmap'ed 10485760 bytes of
10485760\n
**** v1 0.5 vsl| 0 WorkThread - 0xb1ad6010 start
**** v1 0.5 vsl| 0 WorkThread - 0xb1ac5010 start
**** v1 0.5 vsl| 0 WorkThread - 0xb1ab4010 start
**** v1 0.5 vsl| 0 WorkThread - 0xb1aa3010 start
**** v1 0.5 vsl| 0 WorkThread - 0xb1a92010 start
**** v1 0.5 vsl| 0 WorkThread - 0xb1a81010 start
**** v1 0.5 vsl| 0 WorkThread - 0xb1a70010 start
**** v1 0.5 vsl| 0 WorkThread - 0xb1a5f010 start
*** v1 0.5 CLI RX 200
**** v1 0.5 CLI RX| Child in state running
**** v1 0.5 CLI TX| debug.xid 1000
*** v1 0.6 CLI RX 200
**** v1 0.6 CLI RX| XID is 1000
**** v1 0.6 CLI TX| debug.listen_address
*** v1 0.6 CLI RX 200
**** v1 0.6 CLI RX| 127.0.0.1 53607\n
** v1 0.6 Listen on 127.0.0.1 53607
**** v1 0.6 macro def v1_addr=127.0.0.1
**** v1 0.6 macro def v1_port=53607
**** v1 0.6 macro def v1_sock=127.0.0.1 53607
*** top 0.6 client
** c1 0.6 Starting client
** c1 0.6 Waiting for client
*** c1 0.6 Connect to 127.0.0.1 53607
*** c1 0.6 connected fd 10 from 127.0.0.1 48889 to 127.0.0.1 53607
*** c1 0.6 txreq
**** c1 0.6 txreq| GET / HTTP/1.1\r\n
**** c1 0.6 txreq| \r\n
*** s1 0.6 accepted fd 4
*** c1 0.6 rxresp
*** s1 0.6 rxreq
**** s1 0.6 rxhdr| GET / HTTP/1.1\r\n
**** s1 0.6 rxhdr| X-Forwarded-For: 127.0.0.1\r\n
**** s1 0.6 rxhdr| X-Varnish: 1001\r\n
**** s1 0.6 rxhdr| Accept-Encoding: gzip\r\n
**** s1 0.6 rxhdr| Host: 127.0.0.1\r\n
**** s1 0.6 rxhdr| \r\n
**** s1 0.6 http[ 0] | GET
**** s1 0.6 http[ 1] | /
**** s1 0.6 http[ 2] | HTTP/1.1
**** s1 0.6 http[ 3] | X-Forwarded-For: 127.0.0.1
**** s1 0.6 http[ 4] | X-Varnish: 1001
**** s1 0.6 http[ 5] | Accept-Encoding: gzip
**** s1 0.6 http[ 6] | Host: 127.0.0.1
**** s1 0.6 bodylen = 0
*** s1 0.6 txresp
**** s1 0.6 txresp| HTTP/1.1 200 Ok\r\n
**** s1 0.6 txresp| Foo: bar\r\n
**** s1 0.6 txresp| Bar: foo\r\n
**** s1 0.6 txresp| Content-Length: 5\r\n
**** s1 0.6 txresp| \r\n
**** s1 0.6 txresp| 1111\n
*** s1 0.6 shutting fd 4
** s1 0.6 Ending
**** v1 0.6 vsl| 0 CLI - Rd debug.xid 1000
**** v1 0.6 vsl| 0 CLI - Wr 200 11 XID is 1000
**** v1 0.6 vsl| 0 CLI - Rd debug.listen_address
**** v1 0.6 vsl| 0 CLI - Wr 200 16 127.0.0.1 53607
**** v1 0.6 vsl| 11 SessionOpen c 127.0.0.1 48889 127.0.0.1:0
**** v1 0.6 vsl| 11 ReqStart c 127.0.0.1 48889 1001
**** v1 0.6 vsl| 11 RxRequest c GET
**** v1 0.6 vsl| 11 RxURL c /
**** v1 0.6 vsl| 11 RxProtocol c HTTP/1.1
**** v1 0.6 vsl| 11 VCL_call c recv
**** v1 0.6 vsl| 11 VCL_return c lookup
**** v1 0.6 vsl| 11 VCL_call c hash
**** v1 0.6 vsl| 11 Hash c /
**** v1 0.6 vsl| 11 Hash c 127.0.0.1
**** v1 0.6 vsl| 11 VCL_return c hash
**** v1 0.6 vsl| 11 VCL_call c miss
**** v1 0.6 vsl| 11 VCL_return c fetch
**** v1 0.6 vsl| 13 BackendOpen b s1 127.0.0.1 33413 127.0.0.1
44657
**** v1 0.6 vsl| 11 Backend c 13 s1 s1
**** v1 0.6 vsl| 13 TxRequest b GET
**** v1 0.6 vsl| 13 TxURL b /
**** v1 0.6 vsl| 13 TxProtocol b HTTP/1.1
**** v1 0.6 vsl| 13 TxHeader b X-Forwarded-For: 127.0.0.1
**** v1 0.6 vsl| 13 TxHeader b X-Varnish: 1001
**** v1 0.6 vsl| 13 TxHeader b Accept-Encoding: gzip
**** v1 0.6 vsl| 13 TxHeader b Host: 127.0.0.1
---- c1 0.6 HTTP rx EOF (fd:10 read: Success)
*** v1 0.6 debug| Child (28108) died signal=11\n
*** v1 0.6 debug| Child cleanup complete\n
* top 0.6 RESETTING after ./tests/b00028.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 1.6 Wait
** v1 1.6 R 28082 Status: 0000
* top 1.7 TEST ./tests/b00028.vtc FAILED
# top TEST ./tests/b00028.vtc FAILED (1.660) exit=1
make[2]: *** [check] Error 2
make[2]: Leaving directory `/root/compile/varnish-3.0.3/bin/varnishtest'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/root/compile/varnish-3.0.3/bin'
make: *** [check-recursive] Error 1
}}}
The host system is 32-bit CRUX linux 2.7, running on a VMware ESXi server
virtual machine.
--
Ticket URL: <https://www.varnish-cache.org/trac/ticket/1196>
Varnish <https://varnish-cache.org/>
The Varnish HTTP Accelerator
More information about the varnish-bugs
mailing list