r01225

3457ded

CLI debug.listen_address command failed: 400 CLI communication error (hdr)

1 @ SunOS 5.11 i86pc 1.04 32_gcc4.7 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/r01225.vtc starting
[...]
*    top   0.0 TEST Test bans_req counter on persistent reload - #1225
[...]
**** v1    2.4 CLI TX|start
**   s1    2.4 Started on 127.0.0.1 62841
***  v1    2.4 debug|Debug: Child (36598) Started
***  v1    2.4 CLI RX  200
***  v1    2.4 wait-running
**** v1    2.4 CLI TX|status
***  v1    2.4 debug|Info: Child (36598) said Security: Privilege proc_setid missing, will not change uid/gid
***  v1    2.4 debug|Info: Child (36598) said Child starts
***  v1    2.4 debug|Info: Child (36598) said Dropped 0 segments to make free_reserve
***  v1    2.4 CLI RX  200
**** v1    2.4 CLI RX|Child in state running
***  v1    2.4 debug|
***  v1    2.4 debug|Info: Child (36598) said Silo completely loaded
**** v1    2.4 CLI TX|debug.xid 999
***  v1    2.4 CLI RX  200
**** v1    2.4 CLI RX|XID is 999
**** v1    2.4 CLI TX|debug.listen_address
***  v1    2.4 debug|Error: Child (36598) not responding to CLI, killed it.
***  v1    2.4 CLI RX  400
**** v1    2.4 CLI RX|CLI communication error (hdr)
---- v1    2.4 CLI debug.listen_address command failed: 400 CLI communication error (hdr)
*    top   2.4 RESETTING after ../../../bin/varnishtest/tests/r01225.vtc
**   s1    2.4 Waiting for server (3/-1)
**** s1    2.4 macro undef s1_addr
**** s1    2.4 macro undef s1_port
**** s1    2.4 macro undef s1_sock
**   v1    2.4 Wait
***  v1    3.4 debug|Error: Child (36598) died signal=6
***  v1    3.4 debug|Error: Child (36598) Panic at: Tue, 25 Jul 2017 00:07:28 GMT
***  v1    3.4 debug|Assert error in vca_acct(), ../../../bin/varnishd/cache/cache_acceptor.c line 530:
***  v1    3.4 debug|  Condition(ls->sock > 0) not true.
***  v1    3.4 debug|version = varnish-trunk revision 3457ded, vrt api = 6.1
***  v1    3.4 debug|ident = -jsolaris,-sdeprecated_persistent,-smalloc,-hcritbit,ports
***  v1    3.4 debug|now = 18679198.557055 (mono), 1500941247.036989 (real)
***  v1    3.4 debug|Backtrace:
***  v1    3.4 debug|  809936a: /tmp/vtest.32_gcc4.7/varnish-cache/varnish-trunk/_build/bin/varnishd/varnishd'pan_backtrace+0x2c [0x809936a]
***  v1    3.4 debug|  809977c: /tmp/vtest.32_gcc4.7/varnish-cache/varnish-trunk/_build/bin/varnishd/varnishd'pan_ic+0x245 [0x809977c]
***  v1    3.4 debug|  806a054: /tmp/vtest.32_gcc4.7/varnish-cache/varnish-trunk/_build/bin/varnishd/varnishd'vca_acct+0xd3 [0x806a054]
***  v1    3.4 debug|  fee0ae0d: /lib/libc.so.1'_thrp_setup+0x88 [0xfee0ae0d]
***  v1    3.4 debug|  fee0afa0: /lib/libc.so.1'_lwp_start+0x0 [0xfee0afa0]

00319c4

1 @ SunOS 5.11 i86pc 1.04 64_sun12.4 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/r01225.vtc starting
[...]
*    top   0.0 TEST Test bans_req counter on persistent reload - #1225
[...]
**** v1    0.5 vsl|       1001 VCL_return      c lookup
**** v1    0.5 vsl|       1001 VCL_call        c MISS
**** v1    0.5 vsl|       1001 VCL_return      c fetch
**** v1    0.5 vsl|       1001 Link            c bereq 1002 fetch
**** v1    0.5 vsl|       1001 Timestamp       c Fetch: 1500422888.385805 0.011187 0.011187
**** v1    0.5 vsl|       1001 RespProtocol    c HTTP/1.1
**** v1    0.5 vsl|       1001 RespStatus      c 200
**** v1    0.5 vsl|       1001 RespReason      c OK
**** v1    0.5 vsl|       1001 RespHeader      c Foo: foo
**** v1    0.5 vsl|       1001 RespHeader      c Content-Length: 0
**** v1    0.5 vsl|       1001 RespHeader      c Date: Wed, 19 Jul 2017 00:08:08 GMT
**** v1    0.5 vsl|       1001 RespHeader      c X-Varnish: 1001
**** v1    0.5 vsl|       1001 RespHeader      c Age: 0
**** v1    0.5 vsl|       1001 RespHeader      c Via: 1.1 varnish (Varnish/5.1)
**** v1    0.5 vsl|       1001 VCL_call        c DELIVER
**** v1    0.5 vsl|       1001 VCL_return      c deliver
**** v1    0.5 vsl|       1001 Timestamp       c Process: 1500422888.385867 0.011249 0.000062
**** v1    0.5 vsl|       1001 RespHeader      c Accept-Ranges: bytes
**** v1    0.5 vsl|       1001 RespHeader      c Connection: keep-alive
**** v1    0.5 vsl|       1001 Timestamp       c Resp: 1500422888.385909 0.011291 0.000042
**** v1    0.5 vsl|       1001 ReqAcct         c 18 0 18 188 0 188
**** v1    0.5 vsl|       1001 End             c
**** v1    0.5 vsl|       1000 SessClose       c REM_CLOSE 0.012
**** v1    0.5 vsl|       1000 End             c
---- v1    1.4 Not true: bans (0) == 1 (1)
*    top   1.4 RESETTING after ../../../bin/varnishtest/tests/r01225.vtc
**   s1    1.4 Waiting for server (3/-1)
**** s1    1.4 macro undef s1_addr
**** s1    1.4 macro undef s1_port
**** s1    1.4 macro undef s1_sock
**   v1    1.4 Wait
***  v1    1.4 debug|Debug: Stopping Child
**** v1    1.5 vsl|          0 CLI             - EOF on CLI connection, worker stops
***  v1    2.4 debug|Info: Child (6623) ended
***  v1    2.4 debug|Info: Child (6623) said Child dies
***  v1    2.4 debug|Debug: Child cleanup complete
**** v1    2.4 STDOUT poll 0x10
**   v1    2.4 R 6500 Status: 0000 (u 0.064000 s 0.117000)
*    top   2.5 TEST ../../../bin/varnishtest/tests/r01225.vtc FAILED
#    top  TEST ../../../bin/varnishtest/tests/r01225.vtc FAILED (2.460) exit=2