r01818

b587956

1 @ SunOS 5.11 i86pc 1.04 64_sun12.4 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/r01818.vtc starting
[...]
*    top   0.0 TEST #1818: verify that grace works for hit_for_pass objects
[...]
**** v1    0.9 vsl|       1006 Timestamp       c Resp: 1506226712.840847 0.011731 0.000066
**** v1    0.9 vsl|       1006 ReqAcct         c 24 0 24 187 0 187
**** v1    0.9 vsl|       1006 End             c
**** v1    0.9 vsl|       1001 SessClose       c REM_CLOSE 0.243
**** v1    0.9 vsl|       1001 End             c
**** v1    3.6 vsl|          0 CLI             - Rd ping
**** v1    3.6 vsl|          0 CLI             - Wr 200 19 PONG 1506226715 1.0
**** v1    6.6 vsl|          0 CLI             - Rd ping
**** v1    6.6 vsl|          0 CLI             - Wr 200 19 PONG 1506226718 1.0
**** v1    9.6 vsl|          0 CLI             - Rd ping
**** v1    9.6 vsl|          0 CLI             - Wr 200 19 PONG 1506226721 1.0
**** v1   12.6 vsl|          0 CLI             - Rd ping
**** v1   12.6 vsl|          0 CLI             - Wr 200 19 PONG 1506226724 1.0
**** v1   15.7 vsl|          0 CLI             - Rd ping
**** v1   15.7 vsl|          0 CLI             - Wr 200 19 PONG 1506226727 1.0
**** v1   18.7 vsl|          0 CLI             - Rd ping
**** v1   18.7 vsl|          0 CLI             - Wr 200 19 PONG 1506226730 1.0
**** v1   21.7 vsl|          0 CLI             - Rd ping
**** v1   21.7 vsl|          0 CLI             - Wr 200 19 PONG 1506226733 1.0
**** v1   24.7 vsl|          0 CLI             - Rd ping
**** v1   24.7 vsl|          0 CLI             - Wr 200 19 PONG 1506226736 1.0
**** v1   27.7 vsl|          0 CLI             - Rd ping
**** v1   27.7 vsl|          0 CLI             - Wr 200 19 PONG 1506226739 1.0
**** v1   30.7 vsl|          0 CLI             - Rd ping
**** v1   30.7 vsl|          0 CLI             - Wr 200 19 PONG 1506226742 1.0
---- c1   30.9 HTTP rx timeout (fd:19 30000 ms)
**   v1   30.9 Wait
**** v1   30.9 CLI TX|panic.clear
***  v1   30.9 CLI RX  300
**** v1   30.9 CLI RX|No panic to clear
***  v1   30.9 debug|Debug: Stopping Child
**** v1   31.0 vsl|          0 CLI             - EOF on CLI connection, worker stops
***  v1   31.9 debug|Info: Child (86504) ended
***  v1   31.9 debug|Info: Child (86504) said Child dies
***  v1   31.9 debug|Debug:
***  v1   31.9 debug|Child cleanup complete
**** v1   31.9 STDOUT poll 0x10
**   v1   31.9 R 86420 Status: 0000 (u 0.124000 s 0.214000)
*    top  32.0 TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED
#    top  TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED (31.981) exit=2

1 @ SunOS 5.11 i86pc 1.04 64_sun12.4 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/r01818.vtc starting
[...]
*    top   0.0 TEST #1818: verify that grace works for hit_for_pass objects
[...]
**** v1    1.2 vsl|       1006 Timestamp       c Resp: 1506210390.699162 0.039673 0.000050
**** v1    1.2 vsl|       1006 ReqAcct         c 24 0 24 187 0 187
**** v1    1.2 vsl|       1006 End             c
**** v1    1.2 vsl|       1003 SessClose       c REM_CLOSE 0.294
**** v1    1.2 vsl|       1003 End             c
**** v1    3.7 vsl|          0 CLI             - Rd ping
**** v1    3.7 vsl|          0 CLI             - Wr 200 19 PONG 1506210393 1.0
**** v1    6.7 vsl|          0 CLI             - Rd ping
**** v1    6.7 vsl|          0 CLI             - Wr 200 19 PONG 1506210396 1.0
**** v1    9.7 vsl|          0 CLI             - Rd ping
**** v1    9.7 vsl|          0 CLI             - Wr 200 19 PONG 1506210399 1.0
**** v1   12.7 vsl|          0 CLI             - Rd ping
**** v1   12.7 vsl|          0 CLI             - Wr 200 19 PONG 1506210402 1.0
**** v1   15.7 vsl|          0 CLI             - Rd ping
**** v1   15.7 vsl|          0 CLI             - Wr 200 19 PONG 1506210405 1.0
**** v1   18.7 vsl|          0 CLI             - Rd ping
**** v1   18.7 vsl|          0 CLI             - Wr 200 19 PONG 1506210408 1.0
**** v1   21.7 vsl|          0 CLI             - Rd ping
**** v1   21.7 vsl|          0 CLI             - Wr 200 19 PONG 1506210411 1.0
**** v1   24.8 vsl|          0 CLI             - Rd ping
**** v1   24.8 vsl|          0 CLI             - Wr 200 19 PONG 1506210414 1.0
**** v1   27.8 vsl|          0 CLI             - Rd ping
**** v1   27.8 vsl|          0 CLI             - Wr 200 19 PONG 1506210417 1.0
**** v1   30.8 vsl|          0 CLI             - Rd ping
**** v1   30.8 vsl|          0 CLI             - Wr 200 19 PONG 1506210420 1.0
---- c1   31.1 HTTP rx timeout (fd:18 30000 ms)
**   v1   31.1 Wait
**** v1   31.1 CLI TX|panic.clear
***  v1   31.1 CLI RX  300
**** v1   31.1 CLI RX|No panic to clear
***  v1   31.1 debug|Debug: Stopping Child
**** v1   31.2 vsl|          0 CLI             - EOF on CLI connection, worker stops
***  v1   32.1 debug|Info: Child (20816) ended
***  v1   32.1 debug|Info: Child (20816) said Child dies
***  v1   32.1 debug|Debug:
***  v1   32.1 debug|Child cleanup complete
**** v1   32.1 STDOUT poll 0x10
**   v1   32.1 R 20648 Status: 0000 (u 0.117000 s 0.201000)
*    top  32.2 TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED
#    top  TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED (32.179) exit=2

1 @ SunOS 5.11 i86pc 1.04 32_gcc4.7 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/r01818.vtc starting
[...]
*    top   0.0 TEST #1818: verify that grace works for hit_for_pass objects
[...]
**** v1    0.9 vsl|       1006 Timestamp       c Resp: 1506227968.250373 0.022709 0.000038
**** v1    0.9 vsl|       1006 ReqAcct         c 24 0 24 187 0 187
**** v1    0.9 vsl|       1006 End             c
**** v1    0.9 vsl|       1000 SessClose       c REM_CLOSE 0.248
**** v1    0.9 vsl|       1000 End             c
**** v1    3.5 vsl|          0 CLI             - Rd ping
**** v1    3.5 vsl|          0 CLI             - Wr 200 19 PONG 1506227970 1.0
**** v1    6.5 vsl|          0 CLI             - Rd ping
**** v1    6.5 vsl|          0 CLI             - Wr 200 19 PONG 1506227973 1.0
**** v1    9.5 vsl|          0 CLI             - Rd ping
**** v1    9.5 vsl|          0 CLI             - Wr 200 19 PONG 1506227976 1.0
**** v1   12.5 vsl|          0 CLI             - Rd ping
**** v1   12.5 vsl|          0 CLI             - Wr 200 19 PONG 1506227979 1.0
**** v1   15.5 vsl|          0 CLI             - Rd ping
**** v1   15.5 vsl|          0 CLI             - Wr 200 19 PONG 1506227982 1.0
**** v1   18.5 vsl|          0 CLI             - Rd ping
**** v1   18.5 vsl|          0 CLI             - Wr 200 19 PONG 1506227985 1.0
**** v1   21.5 vsl|          0 CLI             - Rd ping
**** v1   21.5 vsl|          0 CLI             - Wr 200 19 PONG 1506227988 1.0
**** v1   24.6 vsl|          0 CLI             - Rd ping
**** v1   24.6 vsl|          0 CLI             - Wr 200 19 PONG 1506227991 1.0
**** v1   27.6 vsl|          0 CLI             - Rd ping
**** v1   27.6 vsl|          0 CLI             - Wr 200 19 PONG 1506227994 1.0
**** v1   30.6 vsl|          0 CLI             - Rd ping
**** v1   30.6 vsl|          0 CLI             - Wr 200 19 PONG 1506227997 1.0
---- c1   30.9 HTTP rx timeout (fd:18 30000 ms)
**   v1   30.9 Wait
**** v1   30.9 CLI TX|panic.clear
***  v1   30.9 CLI RX  300
**** v1   30.9 CLI RX|No panic to clear
***  v1   30.9 debug|Debug: Stopping Child
**** v1   31.0 vsl|          0 CLI             - EOF on CLI connection, worker stops
***  v1   31.9 debug|Info: Child (15267) ended
***  v1   31.9 debug|Info: Child (15267) said Child dies
***  v1   31.9 debug|Debug:
***  v1   31.9 debug| Child cleanup complete
**** v1   31.9 STDOUT poll 0x10
**   v1   31.9 R 15223 Status: 0000 (u 0.144000 s 0.171000)
*    top  32.0 TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED
#    top  TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED (31.972) exit=2

1 @ SunOS 5.11 i86pc 1.04 32_gcc4.7 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/r01818.vtc starting
[...]
*    top   0.0 TEST #1818: verify that grace works for hit_for_pass objects
[...]
**** v1    1.1 vsl|       1006 Timestamp       c Resp: 1506233587.057351 0.038905 0.000045
**** v1    1.1 vsl|       1006 ReqAcct         c 24 0 24 187 0 187
**** v1    1.1 vsl|       1006 End             c
**** v1    1.1 vsl|       1000 SessClose       c REM_CLOSE 0.260
**** v1    1.1 vsl|       1000 End             c
**** v1    3.7 vsl|          0 CLI             - Rd ping
**** v1    3.7 vsl|          0 CLI             - Wr 200 19 PONG 1506233589 1.0
**** v1    6.7 vsl|          0 CLI             - Rd ping
**** v1    6.7 vsl|          0 CLI             - Wr 200 19 PONG 1506233592 1.0
**** v1    9.7 vsl|          0 CLI             - Rd ping
**** v1    9.7 vsl|          0 CLI             - Wr 200 19 PONG 1506233595 1.0
**** v1   12.7 vsl|          0 CLI             - Rd ping
**** v1   12.7 vsl|          0 CLI             - Wr 200 19 PONG 1506233598 1.0
**** v1   15.7 vsl|          0 CLI             - Rd ping
**** v1   15.7 vsl|          0 CLI             - Wr 200 19 PONG 1506233601 1.0
**** v1   18.7 vsl|          0 CLI             - Rd ping
**** v1   18.7 vsl|          0 CLI             - Wr 200 19 PONG 1506233604 1.0
**** v1   21.6 vsl|          0 CLI             - Rd ping
**** v1   21.6 vsl|          0 CLI             - Wr 200 19 PONG 1506233607 1.0
**** v1   24.6 vsl|          0 CLI             - Rd ping
**** v1   24.6 vsl|          0 CLI             - Wr 200 19 PONG 1506233610 1.0
**** v1   27.6 vsl|          0 CLI             - Rd ping
**** v1   27.6 vsl|          0 CLI             - Wr 200 19 PONG 1506233613 1.0
**** v1   30.7 vsl|          0 CLI             - Rd ping
**** v1   30.7 vsl|          0 CLI             - Wr 200 19 PONG 1506233616 1.0
---- c1   30.9 HTTP rx timeout (fd:18 30000 ms)
**   v1   30.9 Wait
**** v1   30.9 CLI TX|panic.clear
***  v1   30.9 CLI RX  300
**** v1   30.9 CLI RX|No panic to clear
***  v1   30.9 debug|Debug: Stopping Child
**** v1   31.0 vsl|          0 CLI             - EOF on CLI connection, worker stops
***  v1   31.9 debug|Info: Child (83972) ended
***  v1   31.9 debug|Info: Child (83972) said Child dies
***  v1   31.9 debug|Debug:
***  v1   31.9 debug| Child cleanup complete
**** v1   31.9 STDOUT poll 0x10
**   v1   31.9 R 83744 Status: 0000 (u 0.145000 s 0.178000)
*    top  32.0 TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED
#    top  TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED (32.039) exit=2

1 @ SunOS 5.11 i86pc 1.04 32_gcc4.7 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/r01818.vtc starting
[...]
*    top   0.0 TEST #1818: verify that grace works for hit_for_pass objects
[...]
**** s1    1.7 macro undef s1_sock
**   s2    1.7 Waiting for server (4/-1)
**** s2    1.7 macro undef s2_addr
**** s2    1.7 macro undef s2_port
**** s2    1.7 macro undef s2_sock
**   c1    1.7 Waiting for client
**** v1    3.9 vsl|          0 CLI             - Rd ping
**** v1    3.9 vsl|          0 CLI             - Wr 200 19 PONG 1506217643 1.0
**** v1    6.9 vsl|          0 CLI             - Rd ping
**** v1    6.9 vsl|          0 CLI             - Wr 200 19 PONG 1506217646 1.0
**** v1    9.9 vsl|          0 CLI             - Rd ping
**** v1    9.9 vsl|          0 CLI             - Wr 200 19 PONG 1506217649 1.0
**** v1   12.9 vsl|          0 CLI             - Rd ping
**** v1   12.9 vsl|          0 CLI             - Wr 200 19 PONG 1506217652 1.0
**** v1   15.9 vsl|          0 CLI             - Rd ping
**** v1   15.9 vsl|          0 CLI             - Wr 200 19 PONG 1506217655 1.0
**** v1   18.9 vsl|          0 CLI             - Rd ping
**** v1   18.9 vsl|          0 CLI             - Wr 200 19 PONG 1506217658 1.0
**** v1   21.9 vsl|          0 CLI             - Rd ping
**** v1   21.9 vsl|          0 CLI             - Wr 200 19 PONG 1506217661 1.0
**** v1   24.9 vsl|          0 CLI             - Rd ping
**** v1   24.9 vsl|          0 CLI             - Wr 200 19 PONG 1506217664 1.0
**** v1   27.9 vsl|          0 CLI             - Rd ping
**** v1   27.9 vsl|          0 CLI             - Wr 200 19 PONG 1506217667 1.0
**** v1   30.9 vsl|          0 CLI             - Rd ping
**** v1   30.9 vsl|          0 CLI             - Wr 200 19 PONG 1506217670 1.0
---- c1   31.3 HTTP rx timeout (fd:19 30000 ms)
**   v1   31.3 Wait
**** v1   31.3 CLI TX|panic.clear
***  v1   31.3 CLI RX  300
**** v1   31.3 CLI RX|No panic to clear
***  v1   31.3 debug|Debug: Stopping Child
**** v1   31.4 vsl|          0 CLI             - EOF on CLI connection, worker stops
***  v1   32.3 debug|Info: Child (55536) ended
***  v1   32.3 debug|Info: Child (55536) said Child dies
***  v1   32.3 debug|Debug: Child cleanup complete
**** v1   32.3 STDOUT poll 0x10
**   v1   32.3 R 55245 Status: 0000 (u 0.151000 s 0.180000)
*    top  32.3 TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED
#    top  TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED (32.315) exit=2

1 @ SunOS 5.11 i86pc 1.04 64_gcc4.7 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/r01818.vtc starting
[...]
*    top   0.0 TEST #1818: verify that grace works for hit_for_pass objects
[...]
**** v1    1.1 vsl|       1006 Timestamp       c Resp: 1506230554.938917 0.011290 0.000047
**** v1    1.1 vsl|       1006 ReqAcct         c 24 0 24 187 0 187
**** v1    1.1 vsl|       1006 End             c
**** v1    1.1 vsl|       1002 SessClose       c REM_CLOSE 0.258
**** v1    1.1 vsl|       1002 End             c
**** v1    3.7 vsl|          0 CLI             - Rd ping
**** v1    3.7 vsl|          0 CLI             - Wr 200 19 PONG 1506230557 1.0
**** v1    6.7 vsl|          0 CLI             - Rd ping
**** v1    6.7 vsl|          0 CLI             - Wr 200 19 PONG 1506230560 1.0
**** v1    9.7 vsl|          0 CLI             - Rd ping
**** v1    9.7 vsl|          0 CLI             - Wr 200 19 PONG 1506230563 1.0
**** v1   12.7 vsl|          0 CLI             - Rd ping
**** v1   12.7 vsl|          0 CLI             - Wr 200 19 PONG 1506230566 1.0
**** v1   15.7 vsl|          0 CLI             - Rd ping
**** v1   15.7 vsl|          0 CLI             - Wr 200 19 PONG 1506230569 1.0
**** v1   18.7 vsl|          0 CLI             - Rd ping
**** v1   18.7 vsl|          0 CLI             - Wr 200 19 PONG 1506230572 1.0
**** v1   21.7 vsl|          0 CLI             - Rd ping
**** v1   21.7 vsl|          0 CLI             - Wr 200 19 PONG 1506230575 1.0
**** v1   24.7 vsl|          0 CLI             - Rd ping
**** v1   24.7 vsl|          0 CLI             - Wr 200 19 PONG 1506230578 1.0
**** v1   27.7 vsl|          0 CLI             - Rd ping
**** v1   27.7 vsl|          0 CLI             - Wr 200 19 PONG 1506230581 1.0
**** v1   30.6 vsl|          0 CLI             - Rd ping
**** v1   30.6 vsl|          0 CLI             - Wr 200 19 PONG 1506230584 1.0
---- c1   31.0 HTTP rx timeout (fd:18 30000 ms)
**   v1   31.0 Wait
**** v1   31.0 CLI TX|panic.clear
***  v1   31.0 CLI RX  300
**** v1   31.0 CLI RX|No panic to clear
***  v1   31.0 debug|Debug: Stopping Child
**** v1   31.0 vsl|          0 CLI             - EOF on CLI connection, worker stops
***  v1   32.0 debug|Info: Child (61839) ended
***  v1   32.0 debug|Info: Child (61839) said Child dies
***  v1   32.0 debug|Debug:
***  v1   32.0 debug| Child cleanup complete
**** v1   32.0 STDOUT poll 0x10
**   v1   32.0 R 61659 Status: 0000 (u 0.291000 s 0.210000)
*    top  32.0 TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED
#    top  TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED (32.044) exit=2

1 @ SunOS 5.11 i86pc 1.04 32_sun12.4 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/r01818.vtc starting
[...]
*    top   0.0 TEST #1818: verify that grace works for hit_for_pass objects
[...]
**** v1    1.2 vsl|       1006 RespHeader      c Connection: keep-alive
**** v1    1.2 vsl|       1006 Timestamp       c Resp: 1506215793.152167 0.026136 0.000054
**** v1    1.2 vsl|       1006 ReqAcct         c 24 0 24 187 0 187
**** v1    1.2 vsl|       1006 End             c
**** v1    1.2 vsl|       1000 SessClose       c REM_CLOSE 0.274
**** v1    1.2 vsl|       1000 End             c
**** v1    3.8 vsl|          0 CLI             - Rd ping
**** v1    3.8 vsl|          0 CLI             - Wr 200 19 PONG 1506215795 1.0
**** v1    6.8 vsl|          0 CLI             - Rd ping
**** v1    6.8 vsl|          0 CLI             - Wr 200 19 PONG 1506215798 1.0
**** v1    9.8 vsl|          0 CLI             - Rd ping
**** v1    9.8 vsl|          0 CLI             - Wr 200 19 PONG 1506215801 1.0
**** v1   12.8 vsl|          0 CLI             - Rd ping
**** v1   12.8 vsl|          0 CLI             - Wr 200 19 PONG 1506215804 1.0
**** v1   15.8 vsl|          0 CLI             - Rd ping
**** v1   15.8 vsl|          0 CLI             - Wr 200 19 PONG 1506215807 1.0
**** v1   18.8 vsl|          0 CLI             - Rd ping
**** v1   18.8 vsl|          0 CLI             - Wr 200 19 PONG 1506215810 1.0
**** v1   21.8 vsl|          0 CLI             - Rd ping
**** v1   21.8 vsl|          0 CLI             - Wr 200 19 PONG 1506215813 1.0
**** v1   24.8 vsl|          0 CLI             - Rd ping
**** v1   24.8 vsl|          0 CLI             - Wr 200 19 PONG 1506215816 1.0
**** v1   27.8 vsl|          0 CLI             - Rd ping
**** v1   27.8 vsl|          0 CLI             - Wr 200 19 PONG 1506215819 1.0
**** v1   30.8 vsl|          0 CLI             - Rd ping
**** v1   30.8 vsl|          0 CLI             - Wr 200 19 PONG 1506215822 1.0
---- c1   31.1 HTTP rx timeout (fd:19 30000 ms)
**   v1   31.1 Wait
**** v1   31.1 CLI TX|panic.clear
***  v1   31.1 CLI RX  300
**** v1   31.1 CLI RX|No panic to clear
***  v1   31.1 debug|Debug: Stopping Child
**** v1   31.1 vsl|          0 CLI             - EOF on CLI connection, worker stops
***  v1   32.1 debug|Info: Child (98697) ended
***  v1   32.1 debug|Info: Child (98697) said Child dies
***  v1   32.1 debug|Debug: Child cleanup complete
**** v1   32.1 STDOUT poll 0x10
**   v1   32.1 R 98476 Status: 0000 (u 0.116000 s 0.197000)
*    top  32.1 TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED
#    top  TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED (32.162) exit=2

1 @ SunOS 5.11 i86pc 1.04 32_gcc4.7 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/r01818.vtc starting
[...]
*    top   0.0 TEST #1818: verify that grace works for hit_for_pass objects
[...]
**** v1    1.4 vsl|       1006 RespHeader      c Connection: keep-alive
**** v1    1.4 vsl|       1006 Timestamp       c Resp: 1506226480.287244 0.011260 0.000064
**** v1    1.4 vsl|       1006 ReqAcct         c 24 0 24 187 0 187
**** v1    1.4 vsl|       1006 End             c
**** v1    1.4 vsl|       1001 SessClose       c REM_CLOSE 0.247
**** v1    1.4 vsl|       1001 End             c
**** v1    4.0 vsl|          0 CLI             - Rd ping
**** v1    4.0 vsl|          0 CLI             - Wr 200 19 PONG 1506226482 1.0
**** v1    7.0 vsl|          0 CLI             - Rd ping
**** v1    7.0 vsl|          0 CLI             - Wr 200 19 PONG 1506226485 1.0
**** v1   10.0 vsl|          0 CLI             - Rd ping
**** v1   10.0 vsl|          0 CLI             - Wr 200 19 PONG 1506226488 1.0
**** v1   13.0 vsl|          0 CLI             - Rd ping
**** v1   13.0 vsl|          0 CLI             - Wr 200 19 PONG 1506226491 1.0
**** v1   16.0 vsl|          0 CLI             - Rd ping
**** v1   16.0 vsl|          0 CLI             - Wr 200 19 PONG 1506226494 1.0
**** v1   19.0 vsl|          0 CLI             - Rd ping
**** v1   19.0 vsl|          0 CLI             - Wr 200 19 PONG 1506226497 1.0
**** v1   22.0 vsl|          0 CLI             - Rd ping
**** v1   22.0 vsl|          0 CLI             - Wr 200 19 PONG 1506226500 1.0
**** v1   25.0 vsl|          0 CLI             - Rd ping
**** v1   25.0 vsl|          0 CLI             - Wr 200 19 PONG 1506226503 1.0
**** v1   28.0 vsl|          0 CLI             - Rd ping
**** v1   28.0 vsl|          0 CLI             - Wr 200 19 PONG 1506226506 1.0
**** v1   31.0 vsl|          0 CLI             - Rd ping
**** v1   31.0 vsl|          0 CLI             - Wr 200 19 PONG 1506226509 1.0
---- c1   31.3 HTTP rx timeout (fd:18 30000 ms)
**   v1   31.3 Wait
**** v1   31.3 CLI TX|panic.clear
***  v1   31.3 CLI RX  300
**** v1   31.3 CLI RX|No panic to clear
***  v1   31.3 debug|Debug: Stopping Child
**** v1   31.3 vsl|          0 CLI             - EOF on CLI connection, worker stops
***  v1   32.3 debug|Info: Child (32215) ended
***  v1   32.3 debug|Info: Child (32215) said Child dies
***  v1   32.3 debug|Debug: Child cleanup complete
**** v1   32.3 STDOUT poll 0x10
**   v1   32.3 R 31995 Status: 0000 (u 0.142000 s 0.169000)
*    top  32.3 TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED
#    top  TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED (32.376) exit=2

47649ea

1 @ SunOS 5.11 i86pc 1.04 64_sun12.4 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/r01818.vtc starting
[...]
*    top   0.0 TEST #1818: verify that grace works for hit_for_pass objects
[...]
**** v1    1.4 vsl|       1006 Timestamp       c Resp: 1506204923.652698 0.022337 0.000054
**** v1    1.4 vsl|       1006 ReqAcct         c 24 0 24 187 0 187
**** v1    1.4 vsl|       1006 End             c
**** v1    1.4 vsl|       1000 SessClose       c REM_CLOSE 0.329
**** v1    1.4 vsl|       1000 End             c
**** v1    3.9 vsl|          0 CLI             - Rd ping
**** v1    3.9 vsl|          0 CLI             - Wr 200 19 PONG 1506204926 1.0
**** v1    6.9 vsl|          0 CLI             - Rd ping
**** v1    6.9 vsl|          0 CLI             - Wr 200 19 PONG 1506204929 1.0
**** v1    9.9 vsl|          0 CLI             - Rd ping
**** v1    9.9 vsl|          0 CLI             - Wr 200 19 PONG 1506204932 1.0
**** v1   12.9 vsl|          0 CLI             - Rd ping
**** v1   12.9 vsl|          0 CLI             - Wr 200 19 PONG 1506204935 1.0
**** v1   15.9 vsl|          0 CLI             - Rd ping
**** v1   15.9 vsl|          0 CLI             - Wr 200 19 PONG 1506204938 1.0
**** v1   18.9 vsl|          0 CLI             - Rd ping
**** v1   18.9 vsl|          0 CLI             - Wr 200 19 PONG 1506204941 1.0
**** v1   21.9 vsl|          0 CLI             - Rd ping
**** v1   21.9 vsl|          0 CLI             - Wr 200 19 PONG 1506204944 1.0
**** v1   24.9 vsl|          0 CLI             - Rd ping
**** v1   24.9 vsl|          0 CLI             - Wr 200 19 PONG 1506204947 1.0
**** v1   27.9 vsl|          0 CLI             - Rd ping
**** v1   27.9 vsl|          0 CLI             - Wr 200 19 PONG 1506204950 1.0
**** v1   30.9 vsl|          0 CLI             - Rd ping
**** v1   30.9 vsl|          0 CLI             - Wr 200 19 PONG 1506204953 1.0
---- c1   31.3 HTTP rx timeout (fd:19 30000 ms)
**   v1   31.3 Wait
**** v1   31.3 CLI TX|panic.clear
***  v1   31.3 CLI RX  300
**** v1   31.3 CLI RX|No panic to clear
***  v1   31.3 debug|Debug: Stopping Child
**** v1   31.3 vsl|          0 CLI             - EOF on CLI connection, worker stops
***  v1   32.3 debug|Info: Child (8948) ended
***  v1   32.3 debug|Info: Child (8948) said Child dies
***  v1   32.3 debug|Debug: Child cleanup complete
***  v1   32.3 debug|
**** v1   32.3 STDOUT poll 0x10
**   v1   32.3 R 8758 Status: 0000 (u 0.129000 s 0.225000)
*    top  32.3 TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED
#    top  TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED (32.350) exit=2

6aead7b

1 @ SunOS 5.11 i86pc 1.04 32_sun12.4 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/r01818.vtc starting
[...]
*    top   0.0 TEST #1818: verify that grace works for hit_for_pass objects
[...]
**** v1    1.0 vsl|       1006 Timestamp       c Resp: 1506173672.829655 0.024794 0.000064
**** v1    1.0 vsl|       1006 ReqAcct         c 24 0 24 187 0 187
**** v1    1.0 vsl|       1006 End             c
**** v1    1.0 vsl|       1000 SessClose       c REM_CLOSE 0.256
**** v1    1.0 vsl|       1000 End             c
**** v1    3.6 vsl|          0 CLI             - Rd ping
**** v1    3.6 vsl|          0 CLI             - Wr 200 19 PONG 1506173675 1.0
**** v1    6.6 vsl|          0 CLI             - Rd ping
**** v1    6.6 vsl|          0 CLI             - Wr 200 19 PONG 1506173678 1.0
**** v1    9.6 vsl|          0 CLI             - Rd ping
**** v1    9.6 vsl|          0 CLI             - Wr 200 19 PONG 1506173681 1.0
**** v1   12.5 vsl|          0 CLI             - Rd ping
**** v1   12.5 vsl|          0 CLI             - Wr 200 19 PONG 1506173684 1.0
**** v1   15.5 vsl|          0 CLI             - Rd ping
**** v1   15.5 vsl|          0 CLI             - Wr 200 19 PONG 1506173687 1.0
**** v1   18.5 vsl|          0 CLI             - Rd ping
**** v1   18.5 vsl|          0 CLI             - Wr 200 19 PONG 1506173690 1.0
**** v1   21.5 vsl|          0 CLI             - Rd ping
**** v1   21.5 vsl|          0 CLI             - Wr 200 19 PONG 1506173693 1.0
**** v1   24.5 vsl|          0 CLI             - Rd ping
**** v1   24.5 vsl|          0 CLI             - Wr 200 19 PONG 1506173696 1.0
**** v1   27.5 vsl|          0 CLI             - Rd ping
**** v1   27.5 vsl|          0 CLI             - Wr 200 19 PONG 1506173699 1.0
**** v1   30.5 vsl|          0 CLI             - Rd ping
**** v1   30.5 vsl|          0 CLI             - Wr 200 19 PONG 1506173702 1.0
---- c1   30.9 HTTP rx timeout (fd:18 30000 ms)
**   v1   30.9 Wait
**** v1   30.9 CLI TX|panic.clear
***  v1   30.9 CLI RX  300
**** v1   30.9 CLI RX|No panic to clear
***  v1   30.9 debug|Debug: Stopping Child
**** v1   30.9 vsl|          0 CLI             - EOF on CLI connection, worker stops
***  v1   31.9 debug|Info: Child (28046) ended
***  v1   31.9 debug|Info: Child (28046) said Child dies
***  v1   31.9 debug|Debug: Child cleanup complete
***  v1   31.9 debug|
**** v1   31.9 STDOUT poll 0x10
**   v1   31.9 R 27941 Status: 0000 (u 0.114000 s 0.188000)
*    top  31.9 TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED
#    top  TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED (31.943) exit=2

1 @ SunOS 5.11 i86pc 1.04 32_gcc4.7 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/r01818.vtc starting
[...]
*    top   0.0 TEST #1818: verify that grace works for hit_for_pass objects
[...]
**** v1    1.1 vsl|       1006 Timestamp       c Resp: 1506198139.347921 0.011371 0.000042
**** v1    1.1 vsl|       1006 ReqAcct         c 24 0 24 187 0 187
**** v1    1.1 vsl|       1006 End             c
**** v1    1.1 vsl|       1003 SessClose       c REM_CLOSE 0.266
**** v1    1.1 vsl|       1003 End             c
**** v1    3.7 vsl|          0 CLI             - Rd ping
**** v1    3.7 vsl|          0 CLI             - Wr 200 19 PONG 1506198141 1.0
**** v1    6.6 vsl|          0 CLI             - Rd ping
**** v1    6.6 vsl|          0 CLI             - Wr 200 19 PONG 1506198144 1.0
**** v1    9.6 vsl|          0 CLI             - Rd ping
**** v1    9.6 vsl|          0 CLI             - Wr 200 19 PONG 1506198147 1.0
**** v1   12.6 vsl|          0 CLI             - Rd ping
**** v1   12.6 vsl|          0 CLI             - Wr 200 19 PONG 1506198150 1.0
**** v1   15.6 vsl|          0 CLI             - Rd ping
**** v1   15.6 vsl|          0 CLI             - Wr 200 19 PONG 1506198153 1.0
**** v1   18.6 vsl|          0 CLI             - Rd ping
**** v1   18.6 vsl|          0 CLI             - Wr 200 19 PONG 1506198156 1.0
**** v1   21.7 vsl|          0 CLI             - Rd ping
**** v1   21.7 vsl|          0 CLI             - Wr 200 19 PONG 1506198159 1.0
**** v1   24.7 vsl|          0 CLI             - Rd ping
**** v1   24.7 vsl|          0 CLI             - Wr 200 19 PONG 1506198162 1.0
**** v1   27.7 vsl|          0 CLI             - Rd ping
**** v1   27.7 vsl|          0 CLI             - Wr 200 19 PONG 1506198165 1.0
**** v1   30.7 vsl|          0 CLI             - Rd ping
**** v1   30.7 vsl|          0 CLI             - Wr 200 19 PONG 1506198168 1.0
---- c1   31.0 HTTP rx timeout (fd:18 30000 ms)
**   v1   31.0 Wait
**** v1   31.0 CLI TX|panic.clear
***  v1   31.0 CLI RX  300
**** v1   31.0 CLI RX|No panic to clear
***  v1   31.0 debug|Debug: Stopping Child
**** v1   31.0 vsl|          0 CLI             - EOF on CLI connection, worker stops
***  v1   32.0 debug|Info: Child (83837) ended
***  v1   32.0 debug|Info: Child (83837) said Child dies
***  v1   32.0 debug|Debug: Child cleanup complete
***  v1   32.0 debug|
**** v1   32.0 STDOUT poll 0x10
**   v1   32.0 R 83774 Status: 0000 (u 0.136000 s 0.164000)
*    top  32.0 TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED
#    top  TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED (32.047) exit=2

1 @ SunOS 5.11 i86pc 1.04 64_sun12.4 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/r01818.vtc starting
[...]
*    top   0.0 TEST #1818: verify that grace works for hit_for_pass objects
[...]
**** v1    0.9 vsl|       1006 RespHeader      c Connection: keep-alive
**** v1    0.9 vsl|       1006 Timestamp       c Resp: 1506200226.373069 0.011437 0.000056
**** v1    0.9 vsl|       1006 ReqAcct         c 24 0 24 187 0 187
**** v1    0.9 vsl|       1006 End             c
**** v1    0.9 vsl|       1001 SessClose       c REM_CLOSE 0.233
**** v1    0.9 vsl|       1001 End             c
**** v1    3.5 vsl|          0 CLI             - Rd ping
**** v1    3.5 vsl|          0 CLI             - Wr 200 19 PONG 1506200229 1.0
**** v1    6.5 vsl|          0 CLI             - Rd ping
**** v1    6.5 vsl|          0 CLI             - Wr 200 19 PONG 1506200232 1.0
**** v1    9.5 vsl|          0 CLI             - Rd ping
**** v1    9.5 vsl|          0 CLI             - Wr 200 19 PONG 1506200235 1.0
**** v1   12.5 vsl|          0 CLI             - Rd ping
**** v1   12.5 vsl|          0 CLI             - Wr 200 19 PONG 1506200238 1.0
**** v1   15.5 vsl|          0 CLI             - Rd ping
**** v1   15.5 vsl|          0 CLI             - Wr 200 19 PONG 1506200241 1.0
**** v1   18.5 vsl|          0 CLI             - Rd ping
**** v1   18.5 vsl|          0 CLI             - Wr 200 19 PONG 1506200244 1.0
**** v1   21.5 vsl|          0 CLI             - Rd ping
**** v1   21.5 vsl|          0 CLI             - Wr 200 19 PONG 1506200247 1.0
**** v1   24.5 vsl|          0 CLI             - Rd ping
**** v1   24.5 vsl|          0 CLI             - Wr 200 19 PONG 1506200250 1.0
**** v1   27.5 vsl|          0 CLI             - Rd ping
**** v1   27.5 vsl|          0 CLI             - Wr 200 19 PONG 1506200253 1.0
**** v1   30.6 vsl|          0 CLI             - Rd ping
**** v1   30.6 vsl|          0 CLI             - Wr 200 19 PONG 1506200256 1.0
---- c1   30.8 HTTP rx timeout (fd:18 30000 ms)
**   v1   30.8 Wait
**** v1   30.8 CLI TX|panic.clear
***  v1   30.8 CLI RX  300
**** v1   30.8 CLI RX|No panic to clear
***  v1   30.8 debug|Debug: Stopping Child
**** v1   30.9 vsl|          0 CLI             - EOF on CLI connection, worker stops
***  v1   31.8 debug|Info: Child (29744) ended
***  v1   31.8 debug|Info: Child (29744) said Child dies
***  v1   31.8 debug|Debug: Child cleanup complete
**** v1   31.8 STDOUT poll 0x10
**   v1   31.9 R 29650 Status: 0000 (u 0.124000 s 0.215000)
*    top  31.9 TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED
#    top  TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED (31.862) exit=2

1 @ SunOS 5.11 i86pc 1.04 64_gcc4.7 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/r01818.vtc starting
[...]
*    top   0.0 TEST #1818: verify that grace works for hit_for_pass objects
[...]
**** v1    1.8 vsl|       1000 SessClose       c REM_CLOSE 0.311
**** v1    1.8 vsl|       1000 End             c
**** v1    1.8 vsl|          0 ExpKill         - EXP_expire p=676cd0 e=59.819635630 f=0x4
**** v1    1.8 vsl|          0 ExpKill         - EXP_Inbox flg=1e p=676b50 e=0.000000000 f=0x4
**** v1    1.8 vsl|          0 ExpKill         - EXP_When p=676b50 e=1506187837.498236656 f=0x1e
**** v1    1.8 vsl|          0 ExpKill         - EXP_expire p=676cd0 e=59.819609165 f=0x4
**** v1    4.3 vsl|          0 CLI             - Rd ping
**** v1    4.3 vsl|          0 CLI             - Wr 200 19 PONG 1506187779 1.0
**** v1    7.3 vsl|          0 CLI             - Rd ping
**** v1    7.3 vsl|          0 CLI             - Wr 200 19 PONG 1506187782 1.0
**** v1   10.3 vsl|          0 CLI             - Rd ping
**** v1   10.3 vsl|          0 CLI             - Wr 200 19 PONG 1506187785 1.0
**** v1   13.3 vsl|          0 CLI             - Rd ping
**** v1   13.3 vsl|          0 CLI             - Wr 200 19 PONG 1506187788 1.0
**** v1   16.3 vsl|          0 CLI             - Rd ping
**** v1   16.3 vsl|          0 CLI             - Wr 200 19 PONG 1506187791 1.0
**** v1   19.3 vsl|          0 CLI             - Rd ping
**** v1   19.3 vsl|          0 CLI             - Wr 200 19 PONG 1506187794 1.0
**** v1   22.3 vsl|          0 CLI             - Rd ping
**** v1   22.3 vsl|          0 CLI             - Wr 200 19 PONG 1506187797 1.0
**** v1   25.3 vsl|          0 CLI             - Rd ping
**** v1   25.3 vsl|          0 CLI             - Wr 200 19 PONG 1506187800 1.0
**** v1   28.3 vsl|          0 CLI             - Rd ping
**** v1   28.3 vsl|          0 CLI             - Wr 200 19 PONG 1506187803 1.0
**** v1   31.3 vsl|          0 CLI             - Rd ping
**** v1   31.3 vsl|          0 CLI             - Wr 200 19 PONG 1506187806 1.0
---- c1   31.6 HTTP rx timeout (fd:18 30000 ms)
**   v1   31.6 Wait
**** v1   31.6 CLI TX|panic.clear
***  v1   31.6 CLI RX  300
**** v1   31.6 CLI RX|No panic to clear
***  v1   31.6 debug|Debug: Stopping Child
**** v1   31.7 vsl|          0 CLI             - EOF on CLI connection, worker stops
***  v1   32.6 debug|Info: Child (58722) ended
***  v1   32.6 debug|Info: Child (58722) said Child dies
***  v1   32.6 debug|Debug: Child cleanup complete
**** v1   32.6 STDOUT poll 0x10
**   v1   32.6 R 58455 Status: 0000 (u 0.330000 s 0.218000)
*    top  32.7 TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED
#    top  TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED (32.700) exit=2

1 @ SunOS 5.11 i86pc 1.04 32_sun12.4 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/r01818.vtc starting
[...]
*    top   0.0 TEST #1818: verify that grace works for hit_for_pass objects
[...]
**** v1    1.1 vsl|       1006 RespHeader      c Connection: keep-alive
**** v1    1.1 vsl|       1006 Timestamp       c Resp: 1506169277.159990 0.017370 0.000066
**** v1    1.1 vsl|       1006 ReqAcct         c 24 0 24 187 0 187
**** v1    1.1 vsl|       1006 End             c
**** v1    1.1 vsl|       1000 SessClose       c REM_CLOSE 0.233
**** v1    1.1 vsl|       1000 End             c
**** v1    3.7 vsl|          0 CLI             - Rd ping
**** v1    3.7 vsl|          0 CLI             - Wr 200 19 PONG 1506169279 1.0
**** v1    6.7 vsl|          0 CLI             - Rd ping
**** v1    6.7 vsl|          0 CLI             - Wr 200 19 PONG 1506169282 1.0
**** v1    9.7 vsl|          0 CLI             - Rd ping
**** v1    9.7 vsl|          0 CLI             - Wr 200 19 PONG 1506169285 1.0
**** v1   12.7 vsl|          0 CLI             - Rd ping
**** v1   12.7 vsl|          0 CLI             - Wr 200 19 PONG 1506169288 1.0
**** v1   15.7 vsl|          0 CLI             - Rd ping
**** v1   15.7 vsl|          0 CLI             - Wr 200 19 PONG 1506169291 1.0
**** v1   18.7 vsl|          0 CLI             - Rd ping
**** v1   18.7 vsl|          0 CLI             - Wr 200 19 PONG 1506169294 1.0
**** v1   21.7 vsl|          0 CLI             - Rd ping
**** v1   21.7 vsl|          0 CLI             - Wr 200 19 PONG 1506169297 1.0
**** v1   24.7 vsl|          0 CLI             - Rd ping
**** v1   24.7 vsl|          0 CLI             - Wr 200 19 PONG 1506169300 1.0
**** v1   27.7 vsl|          0 CLI             - Rd ping
**** v1   27.7 vsl|          0 CLI             - Wr 200 19 PONG 1506169303 1.0
**** v1   30.7 vsl|          0 CLI             - Rd ping
**** v1   30.7 vsl|          0 CLI             - Wr 200 19 PONG 1506169306 1.0
---- c1   31.0 HTTP rx timeout (fd:19 30000 ms)
**   v1   31.0 Wait
**** v1   31.0 CLI TX|panic.clear
***  v1   31.0 CLI RX  300
**** v1   31.0 CLI RX|No panic to clear
***  v1   31.0 debug|Debug: Stopping Child
**** v1   31.0 vsl|          0 CLI             - EOF on CLI connection, worker stops
***  v1   32.0 debug|Info: Child (9922) ended
***  v1   32.0 debug|Info: Child (9922) said Child dies
***  v1   32.0 debug|Debug: Child cleanup complete
**** v1   32.0 STDOUT poll 0x10
**   v1   32.0 R 9782 Status: 0000 (u 0.121000 s 0.200000)
*    top  32.0 TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED
#    top  TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED (32.032) exit=2

1 @ SunOS 5.11 i86pc 1.04 64_sun12.4 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/r01818.vtc starting
[...]
*    top   0.0 TEST #1818: verify that grace works for hit_for_pass objects
[...]
**** v1    1.1 vsl|       1006 RespHeader      c Connection: keep-alive
**** v1    1.1 vsl|       1006 Timestamp       c Resp: 1506200945.394190 0.011461 0.000050
**** v1    1.1 vsl|       1006 ReqAcct         c 24 0 24 187 0 187
**** v1    1.1 vsl|       1006 End             c
**** v1    1.1 vsl|       1001 SessClose       c REM_CLOSE 0.244
**** v1    1.1 vsl|       1001 End             c
**** v1    3.6 vsl|          0 CLI             - Rd ping
**** v1    3.6 vsl|          0 CLI             - Wr 200 19 PONG 1506200947 1.0
**** v1    6.6 vsl|          0 CLI             - Rd ping
**** v1    6.6 vsl|          0 CLI             - Wr 200 19 PONG 1506200950 1.0
**** v1    9.6 vsl|          0 CLI             - Rd ping
**** v1    9.6 vsl|          0 CLI             - Wr 200 19 PONG 1506200953 1.0
**** v1   12.6 vsl|          0 CLI             - Rd ping
**** v1   12.6 vsl|          0 CLI             - Wr 200 19 PONG 1506200956 1.0
**** v1   15.6 vsl|          0 CLI             - Rd ping
**** v1   15.6 vsl|          0 CLI             - Wr 200 19 PONG 1506200959 1.0
**** v1   18.6 vsl|          0 CLI             - Rd ping
**** v1   18.6 vsl|          0 CLI             - Wr 200 19 PONG 1506200962 1.0
**** v1   21.6 vsl|          0 CLI             - Rd ping
**** v1   21.6 vsl|          0 CLI             - Wr 200 19 PONG 1506200965 1.0
**** v1   24.6 vsl|          0 CLI             - Rd ping
**** v1   24.6 vsl|          0 CLI             - Wr 200 19 PONG 1506200968 1.0
**** v1   27.6 vsl|          0 CLI             - Rd ping
**** v1   27.6 vsl|          0 CLI             - Wr 200 19 PONG 1506200971 1.0
**** v1   30.7 vsl|          0 CLI             - Rd ping
**** v1   30.7 vsl|          0 CLI             - Wr 200 19 PONG 1506200974 1.0
---- c1   31.0 HTTP rx timeout (fd:18 30000 ms)
**   v1   31.0 Wait
**** v1   31.0 CLI TX|panic.clear
***  v1   31.0 CLI RX  300
**** v1   31.0 CLI RX|No panic to clear
***  v1   31.0 debug|Debug: Stopping Child
**** v1   31.1 vsl|          0 CLI             - EOF on CLI connection, worker stops
***  v1   32.0 debug|Info: Child (97259) ended
***  v1   32.0 debug|Info: Child (97259) said Child dies
***  v1   32.0 debug|Debug: Child cleanup complete
**** v1   32.0 STDOUT poll 0x10
**   v1   32.0 R 97072 Status: 0000 (u 0.129000 s 0.221000)
*    top  32.1 TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED
#    top  TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED (32.084) exit=2

1 @ SunOS 5.11 i86pc 1.04 32_gcc4.7 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/r01818.vtc starting
[...]
*    top   0.0 TEST #1818: verify that grace works for hit_for_pass objects
[...]
**** v1    1.1 vsl|       1006 RespHeader      c Connection: keep-alive
**** v1    1.1 vsl|       1006 Timestamp       c Resp: 1506173342.668565 0.017140 0.000057
**** v1    1.1 vsl|       1006 ReqAcct         c 24 0 24 187 0 187
**** v1    1.1 vsl|       1006 End             c
**** v1    1.1 vsl|       1000 SessClose       c REM_CLOSE 0.243
**** v1    1.1 vsl|       1000 End             c
**** v1    3.8 vsl|          0 CLI             - Rd ping
**** v1    3.8 vsl|          0 CLI             - Wr 200 19 PONG 1506173345 1.0
**** v1    6.8 vsl|          0 CLI             - Rd ping
**** v1    6.8 vsl|          0 CLI             - Wr 200 19 PONG 1506173348 1.0
**** v1    9.7 vsl|          0 CLI             - Rd ping
**** v1    9.7 vsl|          0 CLI             - Wr 200 19 PONG 1506173351 1.0
**** v1   12.7 vsl|          0 CLI             - Rd ping
**** v1   12.7 vsl|          0 CLI             - Wr 200 19 PONG 1506173354 1.0
**** v1   15.7 vsl|          0 CLI             - Rd ping
**** v1   15.7 vsl|          0 CLI             - Wr 200 19 PONG 1506173357 1.0
**** v1   18.7 vsl|          0 CLI             - Rd ping
**** v1   18.7 vsl|          0 CLI             - Wr 200 19 PONG 1506173360 1.0
**** v1   21.7 vsl|          0 CLI             - Rd ping
**** v1   21.7 vsl|          0 CLI             - Wr 200 19 PONG 1506173363 1.0
**** v1   24.7 vsl|          0 CLI             - Rd ping
**** v1   24.7 vsl|          0 CLI             - Wr 200 19 PONG 1506173366 1.0
**** v1   27.7 vsl|          0 CLI             - Rd ping
**** v1   27.7 vsl|          0 CLI             - Wr 200 19 PONG 1506173369 1.0
**** v1   30.8 vsl|          0 CLI             - Rd ping
**** v1   30.8 vsl|          0 CLI             - Wr 200 19 PONG 1506173372 1.0
---- c1   31.1 HTTP rx timeout (fd:18 30000 ms)
**   v1   31.1 Wait
**** v1   31.1 CLI TX|panic.clear
***  v1   31.1 CLI RX  300
**** v1   31.1 CLI RX|No panic to clear
***  v1   31.1 debug|Debug: Stopping Child
**** v1   31.2 vsl|          0 CLI             - EOF on CLI connection, worker stops
***  v1   32.1 debug|Info: Child (57755) ended
***  v1   32.1 debug|Info: Child (57755) said Child dies
***  v1   32.1 debug|Debug: Child cleanup complete
**** v1   32.1 STDOUT poll 0x10
**   v1   32.1 R 57484 Status: 0000 (u 0.145000 s 0.174000)
*    top  32.2 TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED
#    top  TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED (32.173) exit=2

1 @ SunOS 5.11 i86pc 1.04 64_gcc4.7 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/r01818.vtc starting
[...]
*    top   0.0 TEST #1818: verify that grace works for hit_for_pass objects
[...]
**** v1    1.7 vsl|       1000 SessClose       c REM_CLOSE 0.374
**** v1    1.7 vsl|       1000 End             c
**** v1    1.7 vsl|          0 ExpKill         - EXP_expire p=676cd0 e=59.733170748 f=0x4
**** v1    1.7 vsl|          0 ExpKill         - EXP_Inbox flg=1e p=676b50 e=0.000000000 f=0x4
**** v1    1.7 vsl|          0 ExpKill         - EXP_When p=676b50 e=1506188616.657735348 f=0x1e
**** v1    1.7 vsl|          0 ExpKill         - EXP_expire p=676cd0 e=59.733139038 f=0x4
**** v1    4.1 vsl|          0 CLI             - Rd ping
**** v1    4.1 vsl|          0 CLI             - Wr 200 19 PONG 1506188558 1.0
**** v1    7.1 vsl|          0 CLI             - Rd ping
**** v1    7.1 vsl|          0 CLI             - Wr 200 19 PONG 1506188561 1.0
**** v1   10.1 vsl|          0 CLI             - Rd ping
**** v1   10.1 vsl|          0 CLI             - Wr 200 19 PONG 1506188564 1.0
**** v1   13.1 vsl|          0 CLI             - Rd ping
**** v1   13.1 vsl|          0 CLI             - Wr 200 19 PONG 1506188567 1.0
**** v1   16.1 vsl|          0 CLI             - Rd ping
**** v1   16.1 vsl|          0 CLI             - Wr 200 19 PONG 1506188570 1.0
**** v1   19.1 vsl|          0 CLI             - Rd ping
**** v1   19.1 vsl|          0 CLI             - Wr 200 19 PONG 1506188573 1.0
**** v1   22.1 vsl|          0 CLI             - Rd ping
**** v1   22.1 vsl|          0 CLI             - Wr 200 19 PONG 1506188576 1.0
**** v1   25.1 vsl|          0 CLI             - Rd ping
**** v1   25.1 vsl|          0 CLI             - Wr 200 19 PONG 1506188579 1.0
**** v1   28.1 vsl|          0 CLI             - Rd ping
**** v1   28.1 vsl|          0 CLI             - Wr 200 19 PONG 1506188582 1.0
**** v1   31.1 vsl|          0 CLI             - Rd ping
**** v1   31.1 vsl|          0 CLI             - Wr 200 19 PONG 1506188585 1.0
---- c1   31.5 HTTP rx timeout (fd:19 30000 ms)
**   v1   31.5 Wait
**** v1   31.5 CLI TX|panic.clear
***  v1   31.5 CLI RX  300
**** v1   31.5 CLI RX|No panic to clear
***  v1   31.5 debug|Debug: Stopping Child
**** v1   31.6 vsl|          0 CLI             - EOF on CLI connection, worker stops
***  v1   32.5 debug|Info: Child (8796) ended
***  v1   32.5 debug|Info: Child (8796) said Child dies
***  v1   32.5 debug|Debug: Child cleanup complete
**** v1   32.5 STDOUT poll 0x10
**   v1   32.5 R 8664 Status: 0000 (u 0.295000 s 0.190000)
*    top  32.5 TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED
#    top  TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED (32.552) exit=2

1 @ SunOS 5.11 i86pc 1.04 64_gcc4.7 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/r01818.vtc starting
[...]
*    top   0.0 TEST #1818: verify that grace works for hit_for_pass objects
[...]
**** v1    1.8 vsl|       1006 RespHeader      c Connection: keep-alive
**** v1    1.8 vsl|       1006 Timestamp       c Resp: 1506189305.456835 0.011229 0.000041
**** v1    1.8 vsl|       1006 ReqAcct         c 24 0 24 187 0 187
**** v1    1.8 vsl|       1006 End             c
**** v1    1.8 vsl|       1000 SessClose       c REM_CLOSE 0.274
**** v1    1.8 vsl|       1000 End             c
**** v1    4.4 vsl|          0 CLI             - Rd ping
**** v1    4.4 vsl|          0 CLI             - Wr 200 19 PONG 1506189308 1.0
**** v1    7.4 vsl|          0 CLI             - Rd ping
**** v1    7.4 vsl|          0 CLI             - Wr 200 19 PONG 1506189311 1.0
**** v1   10.4 vsl|          0 CLI             - Rd ping
**** v1   10.4 vsl|          0 CLI             - Wr 200 19 PONG 1506189314 1.0
**** v1   13.4 vsl|          0 CLI             - Rd ping
**** v1   13.4 vsl|          0 CLI             - Wr 200 19 PONG 1506189317 1.0
**** v1   16.4 vsl|          0 CLI             - Rd ping
**** v1   16.4 vsl|          0 CLI             - Wr 200 19 PONG 1506189320 1.0
**** v1   19.4 vsl|          0 CLI             - Rd ping
**** v1   19.4 vsl|          0 CLI             - Wr 200 19 PONG 1506189323 1.0
**** v1   22.4 vsl|          0 CLI             - Rd ping
**** v1   22.4 vsl|          0 CLI             - Wr 200 19 PONG 1506189326 1.0
**** v1   25.4 vsl|          0 CLI             - Rd ping
**** v1   25.4 vsl|          0 CLI             - Wr 200 19 PONG 1506189329 1.0
**** v1   28.3 vsl|          0 CLI             - Rd ping
**** v1   28.3 vsl|          0 CLI             - Wr 200 19 PONG 1506189332 1.0
**** v1   31.3 vsl|          0 CLI             - Rd ping
**** v1   31.3 vsl|          0 CLI             - Wr 200 19 PONG 1506189335 1.0
---- c1   31.7 HTTP rx timeout (fd:18 30000 ms)
**   v1   31.7 Wait
**** v1   31.7 CLI TX|panic.clear
***  v1   31.7 CLI RX  300
**** v1   31.7 CLI RX|No panic to clear
***  v1   31.7 debug|Debug: Stopping Child
**** v1   31.7 vsl|          0 CLI             - EOF on CLI connection, worker stops
***  v1   32.7 debug|Info: Child (52646) ended
***  v1   32.7 debug|Info: Child (52646) said Child dies
***  v1   32.7 debug|Debug: Child cleanup complete
**** v1   32.7 STDOUT poll 0x10
**   v1   32.7 R 52226 Status: 0000 (u 0.282000 s 0.191000)
*    top  32.7 TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED
#    top  TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED (32.720) exit=2

1 @ SunOS 5.11 i86pc 1.04 64_sun12.4 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/r01818.vtc starting
[...]
*    top   0.0 TEST #1818: verify that grace works for hit_for_pass objects
[...]
**** v1    1.3 vsl|       1006 Timestamp       c Resp: 1506188839.194949 0.037659 0.000063
**** v1    1.3 vsl|       1006 ReqAcct         c 24 0 24 187 0 187
**** v1    1.3 vsl|       1006 End             c
**** v1    1.3 vsl|       1000 SessClose       c REM_CLOSE 0.332
**** v1    1.3 vsl|       1000 End             c
**** v1    3.8 vsl|          0 CLI             - Rd ping
**** v1    3.8 vsl|          0 CLI             - Wr 200 19 PONG 1506188841 1.0
**** v1    6.8 vsl|          0 CLI             - Rd ping
**** v1    6.8 vsl|          0 CLI             - Wr 200 19 PONG 1506188844 1.0
**** v1    9.8 vsl|          0 CLI             - Rd ping
**** v1    9.8 vsl|          0 CLI             - Wr 200 19 PONG 1506188847 1.0
**** v1   12.8 vsl|          0 CLI             - Rd ping
**** v1   12.8 vsl|          0 CLI             - Wr 200 19 PONG 1506188850 1.0
**** v1   15.8 vsl|          0 CLI             - Rd ping
**** v1   15.8 vsl|          0 CLI             - Wr 200 19 PONG 1506188853 1.0
**** v1   18.8 vsl|          0 CLI             - Rd ping
**** v1   18.8 vsl|          0 CLI             - Wr 200 19 PONG 1506188856 1.0
**** v1   21.8 vsl|          0 CLI             - Rd ping
**** v1   21.8 vsl|          0 CLI             - Wr 200 19 PONG 1506188859 1.0
**** v1   24.8 vsl|          0 CLI             - Rd ping
**** v1   24.8 vsl|          0 CLI             - Wr 200 19 PONG 1506188862 1.0
**** v1   27.8 vsl|          0 CLI             - Rd ping
**** v1   27.8 vsl|          0 CLI             - Wr 200 19 PONG 1506188865 1.0
**** v1   30.8 vsl|          0 CLI             - Rd ping
**** v1   30.8 vsl|          0 CLI             - Wr 200 19 PONG 1506188868 1.0
---- c1   31.1 HTTP rx timeout (fd:18 30000 ms)
**   v1   31.1 Wait
**** v1   31.1 CLI TX|panic.clear
***  v1   31.1 CLI RX  300
**** v1   31.1 CLI RX|No panic to clear
***  v1   31.1 debug|Debug: Stopping Child
**** v1   31.1 vsl|          0 CLI             - EOF on CLI connection, worker stops
***  v1   32.1 debug|Info: Child (90876) ended
***  v1   32.1 debug|Info: Child (90876) said Child dies
***  v1   32.1 debug|Debug: Child cleanup complete
***  v1   32.1 debug|
**** v1   32.1 STDOUT poll 0x10
**   v1   32.1 R 90696 Status: 0000 (u 0.121000 s 0.208000)
*    top  32.1 TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED
#    top  TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED (32.092) exit=2

1 @ SunOS 5.11 i86pc 1.04 64_sun12.4 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/r01818.vtc starting
[...]
*    top   0.0 TEST #1818: verify that grace works for hit_for_pass objects
[...]
**** v1    1.0 vsl|       1006 Timestamp       c Resp: 1506182578.338221 0.011550 0.000060
**** v1    1.0 vsl|       1006 ReqAcct         c 24 0 24 187 0 187
**** v1    1.0 vsl|       1006 End             c
**** v1    1.0 vsl|       1001 SessClose       c REM_CLOSE 0.236
**** v1    1.0 vsl|       1001 End             c
**** v1    3.6 vsl|          0 CLI             - Rd ping
**** v1    3.6 vsl|          0 CLI             - Wr 200 19 PONG 1506182580 1.0
**** v1    6.7 vsl|          0 CLI             - Rd ping
**** v1    6.7 vsl|          0 CLI             - Wr 200 19 PONG 1506182583 1.0
**** v1    9.7 vsl|          0 CLI             - Rd ping
**** v1    9.7 vsl|          0 CLI             - Wr 200 19 PONG 1506182586 1.0
**** v1   12.7 vsl|          0 CLI             - Rd ping
**** v1   12.7 vsl|          0 CLI             - Wr 200 19 PONG 1506182589 1.0
**** v1   15.7 vsl|          0 CLI             - Rd ping
**** v1   15.7 vsl|          0 CLI             - Wr 200 19 PONG 1506182592 1.0
**** v1   18.7 vsl|          0 CLI             - Rd ping
**** v1   18.7 vsl|          0 CLI             - Wr 200 19 PONG 1506182595 1.0
**** v1   21.7 vsl|          0 CLI             - Rd ping
**** v1   21.7 vsl|          0 CLI             - Wr 200 19 PONG 1506182598 1.0
**** v1   24.7 vsl|          0 CLI             - Rd ping
**** v1   24.7 vsl|          0 CLI             - Wr 200 19 PONG 1506182601 1.0
**** v1   27.7 vsl|          0 CLI             - Rd ping
**** v1   27.7 vsl|          0 CLI             - Wr 200 19 PONG 1506182604 1.0
**** v1   30.7 vsl|          0 CLI             - Rd ping
**** v1   30.7 vsl|          0 CLI             - Wr 200 19 PONG 1506182607 1.0
---- c1   31.0 HTTP rx timeout (fd:19 30000 ms)
**   v1   31.0 Wait
**** v1   31.0 CLI TX|panic.clear
***  v1   31.0 CLI RX  300
**** v1   31.0 CLI RX|No panic to clear
***  v1   31.0 debug|Debug: Stopping Child
**** v1   31.1 vsl|          0 CLI             - EOF on CLI connection, worker stops
***  v1   32.0 debug|Info: Child (55625) ended
***  v1   32.0 debug|Info: Child (55625) said Child dies
***  v1   32.0 debug|Debug:
***  v1   32.0 debug| Child cleanup complete
**** v1   32.0 STDOUT poll 0x10
**   v1   32.0 R 55424 Status: 0000 (u 0.123000 s 0.210000)
*    top  32.1 TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED
#    top  TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED (32.104) exit=2

1 @ SunOS 5.11 i86pc 1.04 64_sun12.4 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/r01818.vtc starting
[...]
*    top   0.0 TEST #1818: verify that grace works for hit_for_pass objects
[...]
**** v1    0.8 vsl|       1006 RespHeader      c Connection: keep-alive
**** v1    0.8 vsl|       1006 Timestamp       c Resp: 1506171463.950444 0.011420 0.000053
**** v1    0.8 vsl|       1006 ReqAcct         c 24 0 24 187 0 187
**** v1    0.8 vsl|       1006 End             c
**** v1    0.8 vsl|       1000 SessClose       c REM_CLOSE 0.235
**** v1    0.8 vsl|       1000 End             c
**** v1    3.6 vsl|          0 CLI             - Rd ping
**** v1    3.6 vsl|          0 CLI             - Wr 200 19 PONG 1506171466 1.0
**** v1    6.6 vsl|          0 CLI             - Rd ping
**** v1    6.6 vsl|          0 CLI             - Wr 200 19 PONG 1506171469 1.0
**** v1    9.6 vsl|          0 CLI             - Rd ping
**** v1    9.6 vsl|          0 CLI             - Wr 200 19 PONG 1506171472 1.0
**** v1   12.6 vsl|          0 CLI             - Rd ping
**** v1   12.6 vsl|          0 CLI             - Wr 200 19 PONG 1506171475 1.0
**** v1   15.5 vsl|          0 CLI             - Rd ping
**** v1   15.5 vsl|          0 CLI             - Wr 200 19 PONG 1506171478 1.0
**** v1   18.5 vsl|          0 CLI             - Rd ping
**** v1   18.5 vsl|          0 CLI             - Wr 200 19 PONG 1506171481 1.0
**** v1   21.5 vsl|          0 CLI             - Rd ping
**** v1   21.5 vsl|          0 CLI             - Wr 200 19 PONG 1506171484 1.0
**** v1   24.5 vsl|          0 CLI             - Rd ping
**** v1   24.5 vsl|          0 CLI             - Wr 200 19 PONG 1506171487 1.0
**** v1   27.6 vsl|          0 CLI             - Rd ping
**** v1   27.6 vsl|          0 CLI             - Wr 200 19 PONG 1506171490 1.0
**** v1   30.5 vsl|          0 CLI             - Rd ping
**** v1   30.5 vsl|          0 CLI             - Wr 200 19 PONG 1506171493 1.0
---- c1   30.8 HTTP rx timeout (fd:18 30000 ms)
**   v1   30.8 Wait
**** v1   30.8 CLI TX|panic.clear
***  v1   30.8 CLI RX  300
**** v1   30.8 CLI RX|No panic to clear
***  v1   30.8 debug|Debug: Stopping Child
**** v1   30.9 vsl|          0 CLI             - EOF on CLI connection, worker stops
***  v1   31.8 debug|Info: Child (81158) ended
***  v1   31.8 debug|Info: Child (81158) said Child dies
***  v1   31.8 debug|Debug: Child cleanup complete
**** v1   31.8 STDOUT poll 0x10
**   v1   31.8 R 81049 Status: 0000 (u 0.125000 s 0.214000)
*    top  31.9 TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED
#    top  TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED (31.888) exit=2

0199bde

1 @ SunOS 5.11 i86pc 1.04 64_sun12.4 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/r01818.vtc starting
[...]
*    top   0.0 TEST #1818: verify that grace works for hit_for_pass objects
[...]
**** v1    1.1 vsl|       1006 ReqAcct         c 24 0 24 187 0 187
**** v1    1.1 vsl|       1006 End             c
**** v1    1.1 vsl|       1000 SessClose       c REM_CLOSE 0.248
**** v1    1.1 vsl|       1000 End             c
**** v1    3.7 vsl|          0 CLI             - Rd ping
**** v1    3.7 vsl|          0 CLI             - Wr 200 19 PONG 1506168585 1.0
**** v1    6.7 vsl|          0 CLI             - Rd ping
**** v1    6.7 vsl|          0 CLI             - Wr 200 19 PONG 1506168588 1.0
**** v1    9.7 vsl|          0 CLI             - Rd ping
**** v1    9.7 vsl|          0 CLI             - Wr 200 19 PONG 1506168591 1.0
**** v1   12.7 vsl|          0 CLI             - Rd ping
**** v1   12.7 vsl|          0 CLI             - Wr 200 19 PONG 1506168594 1.0
**** v1   15.7 vsl|          0 CLI             - Rd ping
**** v1   15.7 vsl|          0 CLI             - Wr 200 19 PONG 1506168597 1.0
**** v1   18.6 vsl|          0 CLI             - Rd ping
**** v1   18.6 vsl|          0 CLI             - Wr 200 19 PONG 1506168600 1.0
**** v1   21.6 vsl|          0 CLI             - Rd ping
**** v1   21.6 vsl|          0 CLI             - Wr 200 19 PONG 1506168603 1.0
**** v1   24.6 vsl|          0 CLI             - Rd ping
**** v1   24.6 vsl|          0 CLI             - Wr 200 19 PONG 1506168606 1.0
**** v1   27.6 vsl|          0 CLI             - Rd ping
**** v1   27.6 vsl|          0 CLI             - Wr 200 19 PONG 1506168609 1.0
**** v1   30.6 vsl|          0 CLI             - Rd ping
**** v1   30.6 vsl|          0 CLI             - Wr 200 19 PONG 1506168612 1.0
---- c1   31.0 HTTP rx timeout (fd:18 30000 ms)
**   v1   31.0 Wait
**** v1   31.0 CLI TX|panic.clear
***  v1   31.0 CLI RX  300
**** v1   31.0 CLI RX|No panic to clear
***  v1   31.0 debug|Debug: Stopping Child
**** v1   31.0 vsl|          0 CLI             - EOF on CLI connection, worker stops
***  v1   32.0 debug|Info:
***  v1   32.0 debug| Child (99832) ended
***  v1   32.0 debug|Info: Child (99832) said Child dies
***  v1   32.0 debug|Debug:
***  v1   32.0 debug| Child cleanup complete
**** v1   32.0 STDOUT poll 0x10
**   v1   32.0 R 99656 Status: 0000 (u 0.127000 s 0.222000)
*    top  32.0 TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED
#    top  TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED (32.063) exit=2

1 @ SunOS 5.11 i86pc 1.04 32_gcc4.7 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/r01818.vtc starting
[...]
*    top   0.0 TEST #1818: verify that grace works for hit_for_pass objects
[...]
**** v1    1.3 vsl|       1006 Timestamp       c Resp: 1506168404.856739 0.012192 0.000051
**** v1    1.3 vsl|       1006 ReqAcct         c 24 0 24 187 0 187
**** v1    1.3 vsl|       1006 End             c
**** v1    1.3 vsl|       1001 SessClose       c REM_CLOSE 0.256
**** v1    1.3 vsl|       1001 End             c
**** v1    3.9 vsl|          0 CLI             - Rd ping
**** v1    3.9 vsl|          0 CLI             - Wr 200 19 PONG 1506168407 1.0
**** v1    7.0 vsl|          0 CLI             - Rd ping
**** v1    7.0 vsl|          0 CLI             - Wr 200 19 PONG 1506168410 1.0
**** v1   10.0 vsl|          0 CLI             - Rd ping
**** v1   10.0 vsl|          0 CLI             - Wr 200 19 PONG 1506168413 1.0
**** v1   13.0 vsl|          0 CLI             - Rd ping
**** v1   13.0 vsl|          0 CLI             - Wr 200 19 PONG 1506168416 1.0
**** v1   16.0 vsl|          0 CLI             - Rd ping
**** v1   16.0 vsl|          0 CLI             - Wr 200 19 PONG 1506168419 1.0
**** v1   19.0 vsl|          0 CLI             - Rd ping
**** v1   19.0 vsl|          0 CLI             - Wr 200 19 PONG 1506168422 1.0
**** v1   22.0 vsl|          0 CLI             - Rd ping
**** v1   22.0 vsl|          0 CLI             - Wr 200 19 PONG 1506168425 1.0
**** v1   25.0 vsl|          0 CLI             - Rd ping
**** v1   25.0 vsl|          0 CLI             - Wr 200 19 PONG 1506168428 1.0
**** v1   28.0 vsl|          0 CLI             - Rd ping
**** v1   28.0 vsl|          0 CLI             - Wr 200 19 PONG 1506168431 1.0
**** v1   31.0 vsl|          0 CLI             - Rd ping
**** v1   31.0 vsl|          0 CLI             - Wr 200 19 PONG 1506168434 1.0
---- c1   31.3 HTTP rx timeout (fd:18 30000 ms)
**   v1   31.3 Wait
**** v1   31.3 CLI TX|panic.clear
***  v1   31.3 CLI RX  300
**** v1   31.3 CLI RX|No panic to clear
***  v1   31.3 debug|Debug: Stopping Child
**** v1   31.4 vsl|          0 CLI             - EOF on CLI connection, worker stops
***  v1   32.3 debug|Info: Child (42858) ended
***  v1   32.3 debug|Info: Child (42858) said Child dies
***  v1   32.3 debug|Debug: Child cleanup complete
***  v1   32.3 debug|
**** v1   32.3 STDOUT poll 0x10
**   v1   32.3 R 42559 Status: 0000 (u 0.143000 s 0.171000)
*    top  32.4 TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED
#    top  TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED (32.406) exit=2

9c31eb7

1 @ SunOS 5.11 i86pc 1.04 64_sun12.4 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/r01818.vtc starting
[...]
*    top   0.0 TEST #1818: verify that grace works for hit_for_pass objects
[...]
**** v1    1.2 vsl|       1006 RespHeader      c Connection: keep-alive
**** v1    1.2 vsl|       1006 Timestamp       c Resp: 1506163363.290734 0.011447 0.000057
**** v1    1.2 vsl|       1006 ReqAcct         c 24 0 24 187 0 187
**** v1    1.2 vsl|       1006 End             c
**** v1    1.2 vsl|       1000 SessClose       c REM_CLOSE 0.246
**** v1    1.2 vsl|       1000 End             c
**** v1    3.8 vsl|          0 CLI             - Rd ping
**** v1    3.8 vsl|          0 CLI             - Wr 200 19 PONG 1506163365 1.0
**** v1    6.8 vsl|          0 CLI             - Rd ping
**** v1    6.8 vsl|          0 CLI             - Wr 200 19 PONG 1506163368 1.0
**** v1    9.8 vsl|          0 CLI             - Rd ping
**** v1    9.8 vsl|          0 CLI             - Wr 200 19 PONG 1506163371 1.0
**** v1   12.8 vsl|          0 CLI             - Rd ping
**** v1   12.8 vsl|          0 CLI             - Wr 200 19 PONG 1506163374 1.0
**** v1   15.8 vsl|          0 CLI             - Rd ping
**** v1   15.8 vsl|          0 CLI             - Wr 200 19 PONG 1506163377 1.0
**** v1   18.8 vsl|          0 CLI             - Rd ping
**** v1   18.8 vsl|          0 CLI             - Wr 200 19 PONG 1506163380 1.0
**** v1   21.7 vsl|          0 CLI             - Rd ping
**** v1   21.7 vsl|          0 CLI             - Wr 200 19 PONG 1506163383 1.0
**** v1   24.7 vsl|          0 CLI             - Rd ping
**** v1   24.7 vsl|          0 CLI             - Wr 200 19 PONG 1506163386 1.0
**** v1   27.7 vsl|          0 CLI             - Rd ping
**** v1   27.7 vsl|          0 CLI             - Wr 200 19 PONG 1506163389 1.0
**** v1   30.7 vsl|          0 CLI             - Rd ping
**** v1   30.7 vsl|          0 CLI             - Wr 200 19 PONG 1506163392 1.0
---- c1   31.1 HTTP rx timeout (fd:19 30000 ms)
**   v1   31.1 Wait
**** v1   31.1 CLI TX|panic.clear
***  v1   31.1 CLI RX  300
**** v1   31.1 CLI RX|No panic to clear
***  v1   31.1 debug|Debug: Stopping Child
**** v1   31.1 vsl|          0 CLI             - EOF on CLI connection, worker stops
***  v1   32.1 debug|Info: Child (99266) ended
***  v1   32.1 debug|Info: Child (99266) said Child dies
***  v1   32.1 debug|Debug: Child cleanup complete
**** v1   32.1 STDOUT poll 0x10
**   v1   32.1 R 98965 Status: 0000 (u 0.122000 s 0.220000)
*    top  32.1 TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED
#    top  TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED (32.160) exit=2

1 @ SunOS 5.11 i86pc 1.04 64_sun12.4 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/r01818.vtc starting
[...]
*    top   0.0 TEST #1818: verify that grace works for hit_for_pass objects
[...]
**** v1    1.1 vsl|       1006 RespHeader      c Connection: keep-alive
**** v1    1.1 vsl|       1006 Timestamp       c Resp: 1506166414.143914 0.050979 0.000056
**** v1    1.1 vsl|       1006 ReqAcct         c 24 0 24 187 0 187
**** v1    1.1 vsl|       1006 End             c
**** v1    1.1 vsl|       1000 SessClose       c REM_CLOSE 0.319
**** v1    1.1 vsl|       1000 End             c
**** v1    3.7 vsl|          0 CLI             - Rd ping
**** v1    3.7 vsl|          0 CLI             - Wr 200 19 PONG 1506166416 1.0
**** v1    6.7 vsl|          0 CLI             - Rd ping
**** v1    6.7 vsl|          0 CLI             - Wr 200 19 PONG 1506166419 1.0
**** v1    9.6 vsl|          0 CLI             - Rd ping
**** v1    9.6 vsl|          0 CLI             - Wr 200 19 PONG 1506166422 1.0
**** v1   12.6 vsl|          0 CLI             - Rd ping
**** v1   12.6 vsl|          0 CLI             - Wr 200 19 PONG 1506166425 1.0
**** v1   15.6 vsl|          0 CLI             - Rd ping
**** v1   15.6 vsl|          0 CLI             - Wr 200 19 PONG 1506166428 1.0
**** v1   18.6 vsl|          0 CLI             - Rd ping
**** v1   18.6 vsl|          0 CLI             - Wr 200 19 PONG 1506166431 1.0
**** v1   21.6 vsl|          0 CLI             - Rd ping
**** v1   21.6 vsl|          0 CLI             - Wr 200 19 PONG 1506166434 1.0
**** v1   24.6 vsl|          0 CLI             - Rd ping
**** v1   24.6 vsl|          0 CLI             - Wr 200 19 PONG 1506166437 1.0
**** v1   27.7 vsl|          0 CLI             - Rd ping
**** v1   27.7 vsl|          0 CLI             - Wr 200 19 PONG 1506166440 1.0
**** v1   30.7 vsl|          0 CLI             - Rd ping
**** v1   30.7 vsl|          0 CLI             - Wr 200 19 PONG 1506166443 1.0
---- c1   31.0 HTTP rx timeout (fd:18 30000 ms)
**   v1   31.0 Wait
**** v1   31.0 CLI TX|panic.clear
***  v1   31.0 CLI RX  300
**** v1   31.0 CLI RX|No panic to clear
***  v1   31.0 debug|Debug: Stopping Child
**** v1   31.1 vsl|          0 CLI             - EOF on CLI connection, worker stops
***  v1   32.0 debug|Info: Child (51262) ended
***  v1   32.0 debug|Info: Child (51262) said Child dies
***  v1   32.0 debug|Debug: Child cleanup complete
**** v1   32.0 STDOUT poll 0x10
**   v1   32.0 R 51150 Status: 0000 (u 0.122000 s 0.225000)
*    top  32.1 TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED
#    top  TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED (32.119) exit=2

1 @ SunOS 5.11 i86pc 1.04 32_gcc4.7 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/r01818.vtc starting
[...]
*    top   0.0 TEST #1818: verify that grace works for hit_for_pass objects
[...]
**   s2    1.1 Waiting for server (4/-1)
**** s2    1.1 macro undef s2_addr
**** s2    1.1 macro undef s2_port
**** s2    1.1 macro undef s2_sock
**   c1    1.1 Waiting for client
**** v1    3.8 vsl|          0 CLI             - Rd ping
**** v1    3.8 vsl|          0 CLI             - Wr 200 19 PONG 1506163694 1.0
**** v1    6.8 vsl|          0 CLI             - Rd ping
**** v1    6.8 vsl|          0 CLI             - Wr 200 19 PONG 1506163697 1.0
**** v1    9.8 vsl|          0 CLI             - Rd ping
**** v1    9.8 vsl|          0 CLI             - Wr 200 19 PONG 1506163700 1.0
**** v1   12.8 vsl|          0 CLI             - Rd ping
**** v1   12.8 vsl|          0 CLI             - Wr 200 19 PONG 1506163703 1.0
**** v1   15.8 vsl|          0 CLI             - Rd ping
**** v1   15.8 vsl|          0 CLI             - Wr 200 19 PONG 1506163706 1.0
**** v1   18.8 vsl|          0 CLI             - Rd ping
**** v1   18.8 vsl|          0 CLI             - Wr 200 19 PONG 1506163709 1.0
**** v1   21.7 vsl|          0 CLI             - Rd ping
**** v1   21.7 vsl|          0 CLI             - Wr 200 19 PONG 1506163712 1.0
**** v1   24.7 vsl|          0 CLI             - Rd ping
**** v1   24.7 vsl|          0 CLI             - Wr 200 19 PONG 1506163715 1.0
**** v1   27.7 vsl|          0 CLI             - Rd ping
**** v1   27.7 vsl|          0 CLI             - Wr 200 19 PONG 1506163718 1.0
**** v1   30.7 vsl|          0 CLI             - Rd ping
**** v1   30.7 vsl|          0 CLI             - Wr 200 19 PONG 1506163721 1.0
---- c1   31.0 HTTP rx timeout (fd:18 30000 ms)
**   v1   31.0 Wait
**** v1   31.0 CLI TX|panic.clear
***  v1   31.0 CLI RX  300
**** v1   31.0 CLI RX|No panic to clear
***  v1   31.0 debug|Debug: Stopping Child
**** v1   31.1 vsl|          0 CLI             - EOF on CLI connection, worker stops
***  v1   32.0 debug|Info: Child (84882) ended
***  v1   32.0 debug|Info: Child (84882) said Child dies
***  v1   32.0 debug|
***  v1   32.0 debug|Debug: Child cleanup complete
**** v1   32.0 STDOUT poll 0x10
**   v1   32.0 R 84673 Status: 0000 (u 0.146000 s 0.169000)
*    top  32.1 TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED
#    top  TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED (32.118) exit=2

1daa9be

1 @ SunOS 5.11 i86pc 1.04 32_sun12.4 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/r01818.vtc starting
[...]
*    top   0.0 TEST #1818: verify that grace works for hit_for_pass objects
[...]
**** v1    1.0 vsl|       1006 RespHeader      c Connection: keep-alive
**** v1    1.0 vsl|       1006 Timestamp       c Resp: 1506152579.224465 0.018916 0.000044
**** v1    1.0 vsl|       1006 ReqAcct         c 24 0 24 187 0 187
**** v1    1.0 vsl|       1006 End             c
**** v1    1.0 vsl|       1003 SessClose       c REM_CLOSE 0.242
**** v1    1.0 vsl|       1003 End             c
**** v1    3.6 vsl|          0 CLI             - Rd ping
**** v1    3.6 vsl|          0 CLI             - Wr 200 19 PONG 1506152581 1.0
**** v1    6.6 vsl|          0 CLI             - Rd ping
**** v1    6.6 vsl|          0 CLI             - Wr 200 19 PONG 1506152584 1.0
**** v1    9.6 vsl|          0 CLI             - Rd ping
**** v1    9.6 vsl|          0 CLI             - Wr 200 19 PONG 1506152587 1.0
**** v1   12.6 vsl|          0 CLI             - Rd ping
**** v1   12.6 vsl|          0 CLI             - Wr 200 19 PONG 1506152590 1.0
**** v1   15.6 vsl|          0 CLI             - Rd ping
**** v1   15.6 vsl|          0 CLI             - Wr 200 19 PONG 1506152593 1.0
**** v1   18.6 vsl|          0 CLI             - Rd ping
**** v1   18.6 vsl|          0 CLI             - Wr 200 19 PONG 1506152596 1.0
**** v1   21.6 vsl|          0 CLI             - Rd ping
**** v1   21.6 vsl|          0 CLI             - Wr 200 19 PONG 1506152599 1.0
**** v1   24.6 vsl|          0 CLI             - Rd ping
**** v1   24.6 vsl|          0 CLI             - Wr 200 19 PONG 1506152602 1.0
**** v1   27.6 vsl|          0 CLI             - Rd ping
**** v1   27.6 vsl|          0 CLI             - Wr 200 19 PONG 1506152605 1.0
**** v1   30.6 vsl|          0 CLI             - Rd ping
**** v1   30.6 vsl|          0 CLI             - Wr 200 19 PONG 1506152608 1.0
---- c1   30.9 HTTP rx timeout (fd:19 30000 ms)
**   v1   30.9 Wait
**** v1   30.9 CLI TX|panic.clear
***  v1   30.9 CLI RX  300
**** v1   30.9 CLI RX|No panic to clear
***  v1   30.9 debug|Debug: Stopping Child
**** v1   30.9 vsl|          0 CLI             - EOF on CLI connection, worker stops
***  v1   31.9 debug|Info: Child (11902) ended
***  v1   31.9 debug|Info: Child (11902) said Child dies
***  v1   31.9 debug|Debug: Child cleanup complete
**** v1   31.9 STDOUT poll 0x10
**   v1   31.9 R 11699 Status: 0000 (u 0.114000 s 0.190000)
*    top  31.9 TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED
#    top  TEST ../../../bin/varnishtest/tests/r01818.vtc FAILED (31.963) exit=2