v00051

b2d2b6f

1 @ FreeBSD arm 12.0-BET 1.04 phk

*    top   0.0 TEST ../../../../bin/varnishtest/tests/v00051.vtc starting
[...]
*    top   0.0 VTEST Test VCL failures
[...]
**** v1   55.8 vsl|       1028 End             b
**** v1   55.8 vsl|       1027 Begin           c req 1026 rxreq
**** v1   55.8 vsl|       1027 Timestamp       c Start: 1542257371.448062 0.000000 0.000000
**** v1   55.8 vsl|       1027 Timestamp       c Req: 1542257371.448062 0.000000 0.000000
**** v1   55.8 vsl|       1027 ReqStart        c 127.0.0.1 58957 a0
**** v1   55.8 vsl|       1027 ReqMethod       c GET
**** v1   55.8 vsl|       1027 ReqURL          c /backend_error
**** v1   55.8 vsl|       1027 ReqProtocol     c HTTP/1.1
**** v1   55.8 vsl|       1027 ReqHeader       c Host: 127.0.0.1
**** v1   55.8 vsl|       1027 ReqHeader       c X-Forwarded-For: 127.0.0.1
**** v1   55.8 vsl|       1027 VCL_call        c RECV
**** v1   55.8 vsl|       1027 VCL_return      c hash
**** v1   55.8 vsl|       1027 VCL_call        c HASH
**** v1   55.8 vsl|       1027 VCL_return      c lookup
**** v1   55.8 vsl|       1027 VCL_call        c MISS
**** v1   55.8 vsl|       1027 VCL_return      c fetch
**** v1   55.8 vsl|       1027 Link            c bereq 1028 fetch
**** v1   55.8 vsl|       1027 Timestamp       c Fetch: 1542257371.456523 0.008461 0.008461
**** v1   55.8 vsl|       1027 Timestamp       c Process: 1542257371.456664 0.008602 0.000141
**** v1   55.8 vsl|       1027 RespHeader      c Date: Thu, 15 Nov 2018 04:49:31 GMT
**** v1   55.8 vsl|       1027 RespHeader      c Server: Varnish
**** v1   55.8 vsl|       1027 RespHeader      c X-Varnish: 1027
**** v1   55.8 vsl|       1027 RespProtocol    c HTTP/1.1
**** v1   55.8 vsl|       1027 RespStatus      c 503
**** v1   55.8 vsl|       1027 RespReason      c Service Unavailable
**** v1   55.8 vsl|       1027 VCL_call        c SYNTH
**** v1   55.8 vsl|       1027 RespHeader      c Content-Type: text/html; charset=utf-8
**** v1   55.8 vsl|       1027 RespHeader      c Retry-After: 5
**** v1   55.8 vsl|       1027 VCL_return      c deliver
**** v1   55.8 vsl|       1027 RespHeader      c Content-Length: 278
**** v1   55.8 vsl|       1027 Storage         c malloc Transient
**** v1   55.8 vsl|       1027 RespHeader      c Connection: keep-alive
**** v1   55.8 vsl|       1027 Timestamp       c Resp: 1542257371.458339 0.010277 0.001675
**** v1   55.8 vsl|       1027 ReqAcct         c 48 0 48 208 278 486
**** v1   55.8 vsl|       1027 End             c
**** v1   55.8 vsl|       1026 SessClose       c REM_CLOSE 0.019
**** v1   55.8 vsl|       1026 End             c
#    top  TEST ../../../../bin/varnishtest/tests/v00051.vtc TIMED OUT (kill -9)
#    top  TEST ../../../../bin/varnishtest/tests/v00051.vtc FAILED (60.175) signal=9
FAIL tests/v00051.vtc (exit status: 2)

1 @ FreeBSD arm 12.0-BET 1.04 phk

*    top   0.0 TEST ../../../../bin/varnishtest/tests/v00051.vtc starting
[...]
*    top   0.0 VTEST Test VCL failures
[...]
**** v1   56.2 vsl|       1028 End             b
**** v1   56.2 vsl|       1027 Begin           c req 1026 rxreq
**** v1   56.2 vsl|       1027 Timestamp       c Start: 1542210785.328501 0.000000 0.000000
**** v1   56.2 vsl|       1027 Timestamp       c Req: 1542210785.328501 0.000000 0.000000
**** v1   56.2 vsl|       1027 ReqStart        c 127.0.0.1 55752 a0
**** v1   56.2 vsl|       1027 ReqMethod       c GET
**** v1   56.2 vsl|       1027 ReqURL          c /backend_error
**** v1   56.2 vsl|       1027 ReqProtocol     c HTTP/1.1
**** v1   56.2 vsl|       1027 ReqHeader       c Host: 127.0.0.1
**** v1   56.2 vsl|       1027 ReqHeader       c X-Forwarded-For: 127.0.0.1
**** v1   56.2 vsl|       1027 VCL_call        c RECV
**** v1   56.2 vsl|       1027 VCL_return      c hash
**** v1   56.2 vsl|       1027 VCL_call        c HASH
**** v1   56.2 vsl|       1027 VCL_return      c lookup
**** v1   56.2 vsl|       1027 VCL_call        c MISS
**** v1   56.2 vsl|       1027 VCL_return      c fetch
**** v1   56.2 vsl|       1027 Link            c bereq 1028 fetch
**** v1   56.2 vsl|       1027 Timestamp       c Fetch: 1542210785.336661 0.008161 0.008161
**** v1   56.2 vsl|       1027 Timestamp       c Process: 1542210785.336800 0.008300 0.000139
**** v1   56.2 vsl|       1027 RespHeader      c Date: Wed, 14 Nov 2018 15:53:05 GMT
**** v1   56.2 vsl|       1027 RespHeader      c Server: Varnish
**** v1   56.2 vsl|       1027 RespHeader      c X-Varnish: 1027
**** v1   56.2 vsl|       1027 RespProtocol    c HTTP/1.1
**** v1   56.2 vsl|       1027 RespStatus      c 503
**** v1   56.2 vsl|       1027 RespReason      c Service Unavailable
**** v1   56.2 vsl|       1027 VCL_call        c SYNTH
**** v1   56.2 vsl|       1027 RespHeader      c Content-Type: text/html; charset=utf-8
**** v1   56.2 vsl|       1027 RespHeader      c Retry-After: 5
**** v1   56.2 vsl|       1027 VCL_return      c deliver
**** v1   56.2 vsl|       1027 RespHeader      c Content-Length: 278
**** v1   56.2 vsl|       1027 Storage         c malloc Transient
**** v1   56.2 vsl|       1027 RespHeader      c Connection: keep-alive
**** v1   56.2 vsl|       1027 Timestamp       c Resp: 1542210785.338562 0.010061 0.001762
**** v1   56.2 vsl|       1027 ReqAcct         c 48 0 48 208 278 486
**** v1   56.2 vsl|       1027 End             c
**** v1   56.2 vsl|       1026 SessClose       c REM_CLOSE 0.018
**** v1   56.2 vsl|       1026 End             c
#    top  TEST ../../../../bin/varnishtest/tests/v00051.vtc TIMED OUT (kill -9)
#    top  TEST ../../../../bin/varnishtest/tests/v00051.vtc FAILED (60.157) signal=9
FAIL tests/v00051.vtc (exit status: 2)

1 @ FreeBSD arm 12.0-BET 1.04 phk

*    top   0.0 TEST ../../../../bin/varnishtest/tests/v00051.vtc starting
[...]
*    top   0.0 VTEST Test VCL failures
[...]
**** v1   56.9 vsl|       1028 End             b
**** v1   56.9 vsl|       1027 Begin           c req 1026 rxreq
**** v1   56.9 vsl|       1027 Timestamp       c Start: 1542226206.515527 0.000000 0.000000
**** v1   56.9 vsl|       1027 Timestamp       c Req: 1542226206.515527 0.000000 0.000000
**** v1   56.9 vsl|       1027 ReqStart        c 127.0.0.1 33054 a0
**** v1   56.9 vsl|       1027 ReqMethod       c GET
**** v1   56.9 vsl|       1027 ReqURL          c /backend_error
**** v1   56.9 vsl|       1027 ReqProtocol     c HTTP/1.1
**** v1   56.9 vsl|       1027 ReqHeader       c Host: 127.0.0.1
**** v1   56.9 vsl|       1027 ReqHeader       c X-Forwarded-For: 127.0.0.1
**** v1   56.9 vsl|       1027 VCL_call        c RECV
**** v1   56.9 vsl|       1027 VCL_return      c hash
**** v1   56.9 vsl|       1027 VCL_call        c HASH
**** v1   56.9 vsl|       1027 VCL_return      c lookup
**** v1   56.9 vsl|       1027 VCL_call        c MISS
**** v1   56.9 vsl|       1027 VCL_return      c fetch
**** v1   56.9 vsl|       1027 Link            c bereq 1028 fetch
**** v1   56.9 vsl|       1027 Timestamp       c Fetch: 1542226206.524545 0.009018 0.009018
**** v1   56.9 vsl|       1027 Timestamp       c Process: 1542226206.524689 0.009161 0.000144
**** v1   56.9 vsl|       1027 RespHeader      c Date: Wed, 14 Nov 2018 20:10:06 GMT
**** v1   56.9 vsl|       1027 RespHeader      c Server: Varnish
**** v1   56.9 vsl|       1027 RespHeader      c X-Varnish: 1027
**** v1   56.9 vsl|       1027 RespProtocol    c HTTP/1.1
**** v1   56.9 vsl|       1027 RespStatus      c 503
**** v1   56.9 vsl|       1027 RespReason      c Service Unavailable
**** v1   56.9 vsl|       1027 VCL_call        c SYNTH
**** v1   56.9 vsl|       1027 RespHeader      c Content-Type: text/html; charset=utf-8
**** v1   56.9 vsl|       1027 RespHeader      c Retry-After: 5
**** v1   56.9 vsl|       1027 VCL_return      c deliver
**** v1   56.9 vsl|       1027 RespHeader      c Content-Length: 278
**** v1   56.9 vsl|       1027 Storage         c malloc Transient
**** v1   56.9 vsl|       1027 RespHeader      c Connection: keep-alive
**** v1   56.9 vsl|       1027 Timestamp       c Resp: 1542226206.532767 0.017240 0.008079
**** v1   56.9 vsl|       1027 ReqAcct         c 48 0 48 208 278 486
**** v1   56.9 vsl|       1027 End             c
**** v1   56.9 vsl|       1026 SessClose       c REM_CLOSE 0.019
**** v1   56.9 vsl|       1026 End             c
#    top  TEST ../../../../bin/varnishtest/tests/v00051.vtc TIMED OUT (kill -9)
#    top  TEST ../../../../bin/varnishtest/tests/v00051.vtc FAILED (60.037) signal=9
FAIL tests/v00051.vtc (exit status: 2)

1 @ FreeBSD arm 12.0-BET 1.04 phk

*    top   0.0 TEST ../../../../bin/varnishtest/tests/v00051.vtc starting
[...]
*    top   0.0 VTEST Test VCL failures
[...]
**** v1   59.7 vsl|       1030 VCL_return      c hash
**** v1   59.7 vsl|       1030 VCL_call        c HASH
**** v1   59.7 vsl|       1030 VCL_return      c lookup
**** v1   59.7 vsl|       1030 VCL_call        c MISS
**** v1   59.7 vsl|       1030 VCL_return      c fetch
**** v1   59.7 vsl|       1030 Link            c bereq 1031 fetch
**** v1   59.7 vsl|       1030 Timestamp       c Fetch: 1542233915.647444 0.008036 0.008036
**** v1   59.7 vsl|       1030 Timestamp       c Process: 1542233915.647587 0.008180 0.000143
**** v1   59.7 vsl|       1030 RespHeader      c Date: Wed, 14 Nov 2018 22:18:35 GMT
**** v1   59.7 vsl|       1030 RespHeader      c Server: Varnish
**** v1   59.7 vsl|       1030 RespHeader      c X-Varnish: 1030
**** v1   59.7 vsl|       1030 RespProtocol    c HTTP/1.1
**** v1   59.7 vsl|       1030 RespStatus      c 503
**** v1   59.7 vsl|       1030 RespReason      c Service Unavailable
**** v1   59.7 vsl|       1030 VCL_call        c SYNTH
**** v1   59.7 vsl|       1030 RespHeader      c Content-Type: text/html; charset=utf-8
**** v1   59.7 vsl|       1030 RespHeader      c Retry-After: 5
**** v1   59.7 vsl|       1030 VCL_return      c deliver
**** v1   59.7 vsl|       1030 RespHeader      c Content-Length: 278
**** v1   59.7 vsl|       1030 Storage         c malloc Transient
**** v1   59.7 vsl|       1030 RespHeader      c Connection: keep-alive
**** v1   59.7 vsl|       1030 Timestamp       c Resp: 1542233915.654453 0.015045 0.006866
**** v1   59.7 vsl|       1030 ReqAcct         c 51 0 51 208 278 486
**** v1   59.7 vsl|       1030 End             c
**** v1   59.7 vsl|       1029 SessClose       c REM_CLOSE 0.017
**** v1   59.7 vsl|       1029 End             c
***  v1   59.7 CLI RX  200
**** v1   59.7 CLI RX|Backend name                   Admin   Probe      Last change
**** v1   59.7 CLI RX|vcl12.s1                       probe   healthy    Wed, 14 Nov 2018 22:18:35 GMT
**   v1   59.7 Stop
**** v1   59.7 CLI TX|stop
**** v1   59.8 vsl|          0 CLI             - Rd backend.list
**** v1   59.8 vsl|          0 CLI             - Wr 200 141 Backend name                   Admin   Probe      Last change
vcl12.s1                       probe   healthy    Wed, 14 Nov 2018 22:18:35 GMT
***  v1   59.8 debug|Debug: Stopping Child
***  v1   59.9 debug|Info: Child (4073) ended
**** v1   59.9 vsl|          0 CLI             - EOF on CLI connection, worker stops
#    top  TEST ../../../../bin/varnishtest/tests/v00051.vtc TIMED OUT (kill -9)
#    top  TEST ../../../../bin/varnishtest/tests/v00051.vtc FAILED (60.048) signal=9
FAIL tests/v00051.vtc (exit status: 2)

1 @ FreeBSD arm 12.0-BET 1.04 phk

*    top   0.0 TEST ../../../../bin/varnishtest/tests/v00051.vtc starting
[...]
*    top   0.0 VTEST Test VCL failures
[...]
**   c1   56.6 Ending
**   s1   56.6 Ending
**** l1   56.6 match|       1028 VCL_call        b BACKEND_ERROR
***  l1   56.6 expecting| expect 0 1028 VCL_Error Forced failure
**** l1   56.6 match|       1028 VCL_Error       b Forced failure
***  l1   56.6 expecting| expect 0 1028 VCL_return fail
**** l1   56.6 match|       1028 VCL_return      b fail
**** l1   56.6 end|
**   top  56.6 === varnish v1 -expect vcl_fail == 12
**   v1   56.6 as expected: vcl_fail (12) == 12
**   top  56.6 === varnish v1 -expect sc_vcl_failure == 10
**   v1   56.6 as expected: sc_vcl_failure (10) == 10
**   top  56.6 === logexpect l1 -wait
**   l1   56.6 Waiting for logexp
**   top  56.6 === server s1 {
**   s1   56.6 Waiting for server (3/-1)
**   s1   56.6 Starting server
**** s1   56.6 macro def s1_addr=127.0.0.1
**** s1   56.6 macro def s1_port=40592
**** s1   56.6 macro def s1_sock=127.0.0.1 40592
*    s1   56.6 Listen on 127.0.0.1 40592
**   top  56.6 === varnish v1 -vcl+backend {
**** v1   56.6 CLI TX|vcl.inline vcl12 << %XJEIFLH|)Xspa8P
**** v1   56.6 CLI TX|vcl 4.1;
**** v1   56.6 CLI TX|backend s1 { .host = "127.0.0.1"; .port = "40592"; }
**** v1   56.6 CLI TX|
**** v1   56.6 CLI TX|
**** v1   56.6 CLI TX|\timport debug;
**** v1   56.6 CLI TX|\tsub vcl_backend_response {
**** v1   56.6 CLI TX|\t\tdebug.fail();
**** v1   56.6 CLI TX|\t\tset bereq.http.not = "Should not happen";
**** v1   56.6 CLI TX|\t}
**** v1   56.6 CLI TX|
**** v1   56.6 CLI TX|%XJEIFLH|)Xspa8P
**   s1   56.6 Started on 127.0.0.1 40592 (1 iterations)
**** v1   56.7 vsl|       1026 SessClose       c REM_CLOSE 0.043
**** v1   56.7 vsl|       1026 End             c
#    top  TEST ../../../../bin/varnishtest/tests/v00051.vtc TIMED OUT (kill -9)
#    top  TEST ../../../../bin/varnishtest/tests/v00051.vtc FAILED (60.157) signal=9
FAIL tests/v00051.vtc (exit status: 2)

1 @ FreeBSD arm 12.0-BET 1.04 phk

*    top   0.0 TEST ../../../../bin/varnishtest/tests/v00051.vtc starting
[...]
*    top   0.0 VTEST Test VCL failures
[...]
**** v1   56.9 vsl|       1028 End             b
**** v1   56.9 vsl|       1027 Begin           c req 1026 rxreq
**** v1   56.9 vsl|       1027 Timestamp       c Start: 1542241622.592202 0.000000 0.000000
**** v1   56.9 vsl|       1027 Timestamp       c Req: 1542241622.592202 0.000000 0.000000
**** v1   56.9 vsl|       1027 ReqStart        c 127.0.0.1 23192 a0
**** v1   56.9 vsl|       1027 ReqMethod       c GET
**** v1   56.9 vsl|       1027 ReqURL          c /backend_error
**** v1   56.9 vsl|       1027 ReqProtocol     c HTTP/1.1
**** v1   56.9 vsl|       1027 ReqHeader       c Host: 127.0.0.1
**** v1   56.9 vsl|       1027 ReqHeader       c X-Forwarded-For: 127.0.0.1
**** v1   56.9 vsl|       1027 VCL_call        c RECV
**** v1   56.9 vsl|       1027 VCL_return      c hash
**** v1   56.9 vsl|       1027 VCL_call        c HASH
**** v1   56.9 vsl|       1027 VCL_return      c lookup
**** v1   56.9 vsl|       1027 VCL_call        c MISS
**** v1   56.9 vsl|       1027 VCL_return      c fetch
**** v1   56.9 vsl|       1027 Link            c bereq 1028 fetch
**** v1   56.9 vsl|       1027 Timestamp       c Fetch: 1542241622.601250 0.009049 0.009049
**** v1   56.9 vsl|       1027 Timestamp       c Process: 1542241622.601481 0.009279 0.000231
**** v1   56.9 vsl|       1027 RespHeader      c Date: Thu, 15 Nov 2018 00:27:02 GMT
**** v1   56.9 vsl|       1027 RespHeader      c Server: Varnish
**** v1   56.9 vsl|       1027 RespHeader      c X-Varnish: 1027
**** v1   56.9 vsl|       1027 RespProtocol    c HTTP/1.1
**** v1   56.9 vsl|       1027 RespStatus      c 503
**** v1   56.9 vsl|       1027 RespReason      c Service Unavailable
**** v1   56.9 vsl|       1027 VCL_call        c SYNTH
**** v1   56.9 vsl|       1027 RespHeader      c Content-Type: text/html; charset=utf-8
**** v1   56.9 vsl|       1027 RespHeader      c Retry-After: 5
**** v1   56.9 vsl|       1027 VCL_return      c deliver
**** v1   56.9 vsl|       1027 RespHeader      c Content-Length: 278
**** v1   56.9 vsl|       1027 Storage         c malloc Transient
**** v1   56.9 vsl|       1027 RespHeader      c Connection: keep-alive
**** v1   56.9 vsl|       1027 Timestamp       c Resp: 1542241622.609618 0.017417 0.008137
**** v1   56.9 vsl|       1027 ReqAcct         c 48 0 48 208 278 486
**** v1   56.9 vsl|       1027 End             c
**** v1   56.9 vsl|       1026 SessClose       c REM_CLOSE 0.019
**** v1   56.9 vsl|       1026 End             c
#    top  TEST ../../../../bin/varnishtest/tests/v00051.vtc TIMED OUT (kill -9)
#    top  TEST ../../../../bin/varnishtest/tests/v00051.vtc FAILED (60.030) signal=9
FAIL tests/v00051.vtc (exit status: 2)

77d4c56

1 @ FreeBSD arm 12.0-BET 1.04 phk

*    top   0.0 TEST ../../../../bin/varnishtest/tests/v00051.vtc starting
[...]
*    top   0.0 VTEST Test VCL failures
[...]
**** v1   56.8 vsl|       1027 ReqHeader       c Host: 127.0.0.1
**** v1   56.8 vsl|       1027 ReqHeader       c X-Forwarded-For: 127.0.0.1
**** v1   56.8 vsl|       1027 VCL_call        c RECV
**** v1   56.8 vsl|       1027 VCL_return      c hash
**** v1   56.8 vsl|       1027 VCL_call        c HASH
**** v1   56.8 vsl|       1027 VCL_return      c lookup
**** v1   56.8 vsl|       1027 VCL_call        c MISS
**** v1   56.8 vsl|       1027 VCL_return      c fetch
**** v1   56.8 vsl|       1027 Link            c bereq 1028 fetch
**** v1   56.8 vsl|       1027 Timestamp       c Fetch: 1542203150.155020 0.009047 0.009047
**** v1   56.8 vsl|       1027 Timestamp       c Process: 1542203150.155173 0.009200 0.000153
**** v1   56.8 vsl|       1027 RespHeader      c Date: Wed, 14 Nov 2018 13:45:50 GMT
**** v1   56.8 vsl|       1027 RespHeader      c Server: Varnish
**** v1   56.8 vsl|       1027 RespHeader      c X-Varnish: 1027
**** v1   56.8 vsl|       1027 RespProtocol    c HTTP/1.1
**** v1   56.8 vsl|       1027 RespStatus      c 503
**** v1   56.8 vsl|       1027 RespReason      c Service Unavailable
**** v1   56.8 vsl|       1027 VCL_call        c SYNTH
**** v1   56.8 vsl|       1027 RespHeader      c Content-Type: text/html; charset=utf-8
**** v1   56.8 vsl|       1027 RespHeader      c Retry-After: 5
**** v1   56.8 vsl|       1027 VCL_return      c deliver
**** v1   56.8 vsl|       1027 RespHeader      c Content-Length: 278
**** v1   56.8 vsl|       1027 Storage         c malloc Transient
**** v1   56.8 vsl|       1027 RespHeader      c Connection: keep-alive
**** v1   56.8 vsl|       1027 Timestamp       c Resp: 1542203150.163275 0.017302 0.008102
**** v1   56.8 vsl|       1027 ReqAcct         c 48 0 48 208 278 486
**** v1   56.8 vsl|       1027 End             c
**** v1   56.8 vsl|       1026 SessClose       c REM_CLOSE 0.019
**** v1   56.8 vsl|       1026 End             c
**** v1   59.9 vsl|          0 CLI             - Rd vcl.load vcl12 vcl_vcl12.1542203150.171110/vgc.so 1auto
***  v1   59.9 CLI RX  200
**** v1   59.9 CLI RX|VCL compiled.
**** v1   59.9 CLI TX|vcl.use vcl12
**** v1   60.0 vsl|          0 Debug           - vcl12: VCL_EVENT_WARM
**** v1   60.0 vsl|          0 CLI             - Wr 200 54 Loaded "vcl_vcl12.1542203150.171110/vgc.so" as "vcl12"
**** v1   60.0 vsl|          0 CLI             - Rd ping
**** v1   60.0 vsl|          0 CLI             - Wr 200 19 PONG 1542203153 1.0
#    top  TEST ../../../../bin/varnishtest/tests/v00051.vtc TIMED OUT (kill -9)
#    top  TEST ../../../../bin/varnishtest/tests/v00051.vtc FAILED (60.058) signal=9
FAIL tests/v00051.vtc (exit status: 2)

57cd74c

1 @ FreeBSD arm 12.0-BET 1.04 phk

*    top   0.0 TEST ../../../../bin/varnishtest/tests/v00051.vtc starting
[...]
*    top   0.0 VTEST Test VCL failures
[...]
**** v1   59.9 vsl|       1030 ReqMethod       c GET
**** v1   59.9 vsl|       1030 ReqURL          c /backend_response
**** v1   59.9 vsl|       1030 ReqProtocol     c HTTP/1.1
**** v1   59.9 vsl|       1030 ReqHeader       c Host: 127.0.0.1
**** v1   59.9 vsl|       1030 ReqHeader       c X-Forwarded-For: 127.0.0.1
**** v1   59.9 vsl|       1030 VCL_call        c RECV
**** v1   59.9 vsl|       1030 VCL_return      c hash
**** v1   59.9 vsl|       1030 VCL_call        c HASH
**** v1   59.9 vsl|       1030 VCL_return      c lookup
**** v1   59.9 vsl|       1030 VCL_call        c MISS
**** v1   59.9 vsl|       1030 VCL_return      c fetch
**** v1   59.9 vsl|       1030 Link            c bereq 1031 fetch
**** v1   59.9 vsl|       1030 Timestamp       c Fetch: 1542195498.984191 0.008935 0.008935
**** v1   59.9 vsl|       1030 Timestamp       c Process: 1542195498.984332 0.009076 0.000141
**** v1   59.9 vsl|       1030 RespHeader      c Date: Wed, 14 Nov 2018 11:38:18 GMT
**** v1   59.9 vsl|       1030 RespHeader      c Server: Varnish
**** v1   59.9 vsl|       1030 RespHeader      c X-Varnish: 1030
**** v1   59.9 vsl|       1030 RespProtocol    c HTTP/1.1
**** v1   59.9 vsl|       1030 RespStatus      c 503
**** v1   59.9 vsl|       1030 RespReason      c Service Unavailable
**** v1   59.9 vsl|       1030 VCL_call        c SYNTH
**** v1   59.9 vsl|       1030 RespHeader      c Content-Type: text/html; charset=utf-8
**** v1   59.9 vsl|       1030 RespHeader      c Retry-After: 5
**** v1   59.9 vsl|       1030 VCL_return      c deliver
**** v1   59.9 vsl|       1030 RespHeader      c Content-Length: 278
**** v1   59.9 vsl|       1030 Storage         c malloc Transient
**** v1   59.9 vsl|       1030 RespHeader      c Connection: keep-alive
**** v1   59.9 vsl|       1030 Timestamp       c Resp: 1542195498.991110 0.015854 0.006778
**** v1   59.9 vsl|       1030 ReqAcct         c 51 0 51 208 278 486
**** v1   59.9 vsl|       1030 End             c
**** v1   59.9 vsl|       1029 SessClose       c REM_CLOSE 0.018
**** v1   59.9 vsl|       1029 End             c
***  v1   59.9 CLI RX  200
**** v1   59.9 CLI RX|Backend name                   Admin   Probe      Last change
**** v1   59.9 CLI RX|vcl12.s1                       probe   healthy    Wed, 14 Nov 2018 11:38:18 GMT
**   v1   59.9 Stop
**** v1   59.9 CLI TX|stop
#    top  TEST ../../../../bin/varnishtest/tests/v00051.vtc TIMED OUT (kill -9)
#    top  TEST ../../../../bin/varnishtest/tests/v00051.vtc FAILED (60.372) signal=9
FAIL tests/v00051.vtc (exit status: 2)