v00051

f549ddd

1 @ SunOS 5.11 i86pc 1.04 32_gcc4.7 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/v00051.vtc starting
[...]
*    top   0.0 TEST Test VCL failures
[...]
**** c1    1.1 http[ 1] |503
**** v1    1.1 vsl|       1016 RespHeader      c Content-Length: 251
**** c1    1.1 http[ 2] |VCL failed
**** v1    1.1 vsl|       1016 Storage         c malloc Transient
**** c1    1.1 http[ 3] |Date: Sun, 03 Sep 2017 19:19:51 GMT
**** c1    1.1 http[ 4] |Server: Varnish
**** v1    1.1 vsl|       1016 RespHeader      c Connection: close
**** c1    1.1 http[ 5] |X-Varnish: 1016
**** v1    1.1 vsl|       1016 Timestamp       c Resp: 1504466391.919600 0.000115 0.000059
**** c1    1.1 http[ 6] |Content-Type: text/html; charset=utf-8
**** v1    1.1 vsl|       1016 ReqAcct         c 30 0 30 194 251 445
**** c1    1.1 http[ 7] |Retry-After: 5
**** v1    1.1 vsl|       1016 End             c
**** c1    1.1 http[ 8] |Content-Length: 251
**** v1    1.1 vsl|       1015 SessClose       c VCL_FAILURE 0.000
**** c1    1.1 http[ 9] |Connection: close
**** v1    1.1 vsl|       1015 End             c
**** c1    1.1 body|<!DOCTYPE html>
**** c1    1.1 body|<html>
**** c1    1.1 body|  <head>
**** c1    1.1 body|    <title>503 VCL failed</title>
**** c1    1.1 body|  </head>
**** c1    1.1 body|  <body>
**** c1    1.1 body|    <h1>Error 503 VCL failed</h1>
**** c1    1.1 body|    <p>VCL failed</p>
**** c1    1.1 body|    <h3>Guru Meditation:</h3>
**** c1    1.1 body|    <p>XID: 1016</p>
**** c1    1.1 body|    <hr>
**** c1    1.1 body|    <p>Varnish cache server</p>
**** c1    1.1 body|  </body>
**** c1    1.1 body|</html>
**** c1    1.1 bodylen = 251
**   c1    1.1 === expect resp.status == 503
**** c1    1.1 EXPECT resp.status (503) == "503" match
**   c1    1.1 === expect resp.reason == "VCL failed"
**** c1    1.1 EXPECT resp.reason (VCL failed) == "VCL failed" match
***  c1    1.1 closing fd 17
**   c1    1.1 Ending
**   top   1.1 === varnish v1 -expect vcl_fail == 7
#    top  TEST ../../../bin/varnishtest/tests/v00051.vtc FAILED (1.144) signal=11

Failed to open 127.0.0.1 43587: (null)

1 @ SunOS 5.11 i86pc 1.04 64_sun12.4 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/v00051.vtc starting
[...]
*    top   0.0 TEST Test VCL failures
[...]
**** v1    0.5 CLI RX|Message from VCC-compiler:
**** v1    0.5 CLI RX|Security: Privilege proc_setid missing, will not change uid/gid
**** v1    0.5 CLI RX|Message from C-compiler:
**** v1    0.5 CLI RX|Security: Privilege proc_setid missing, will not change uid/gid
**** v1    0.5 CLI RX|Message from dlopen:
**** v1    0.5 CLI RX|Security: Privilege proc_setid missing, will not change uid/gid
**** v1    0.5 CLI RX|VCL compiled.
**** v1    0.5 CLI TX|vcl.use vcl2
***  v1    0.5 CLI RX  200
**** v1    0.5 CLI RX|VCL 'vcl2' now active
**   top   0.5 === logexpect l1 -v v1 -g raw {
**   l1    0.5 === expect * 1007	VCL_call	"SYNTH"
**   l1    0.5 === expect * 1007	VCL_Error	"Forced failure"
**   l1    0.5 === expect 0 1007	VCL_return	"fail"
**   top   0.5 === client c1 {
**   c1    0.5 Starting client
**** l1    0.5 begin|
***  l1    0.5 expecting| expect * 1007 VCL_call SYNTH
**   c1    0.5 Waiting for client
***  c1    0.5 Connect to 127.0.0.1 43587
---- c1    0.5 Failed to open 127.0.0.1 43587: (null)
*    top   0.5 RESETTING after ../../../bin/varnishtest/tests/v00051.vtc
**   s1    0.5 Waiting for server (3/-1)
**** s1    0.5 macro undef s1_addr
**** s1    0.5 macro undef s1_port
**** s1    0.5 macro undef s1_sock
**   v1    0.5 Wait
***  v1    0.5 debug|Debug: Stopping Child
**** v1    0.5 vsl|          0 CLI             - Rd vcl.load vcl2 vcl_vcl2.1504439820.205262184/vgc.so 1auto
**** v1    0.5 vsl|          0 Debug           - vcl2: VCL_EVENT_WARM
**** v1    0.5 vsl|          0 CLI             - Wr 200 55 Loaded "vcl_vcl2.1504439820.205262184/vgc.so" as "vcl2"
**** v1    0.5 vsl|          0 CLI             - Rd vcl.use vcl2
**** v1    0.5 vsl|          0 CLI             - Wr 200 0
**** v1    0.5 vsl|          0 CLI             - EOF on CLI connection, worker stops
***  v1    1.5 debug|Info: Child (39554) ended
***  v1    1.5 debug|Info: Child (39554) said Child dies
***  v1    1.5 debug|Debug: Child cleanup complete
**** v1    1.5 STDOUT poll 0x10
**   v1    1.5 R 39532 Status: 0000 (u 0.125000 s 0.186000)
**   l1    1.5 Waiting for logexp

1 @ SunOS 5.11 i86pc 1.04 64_gcc4.7 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/v00051.vtc starting
[...]
*    top   0.0 TEST Test VCL failures
[...]
**** v1    3.3 vsl|       1025 BereqAcct       b 0 0 0 0 0 0
**** v1    3.3 vsl|       1025 End             b
**** v1    3.3 vsl|       1024 Begin           c req 1023 rxreq
**** v1    3.3 vsl|       1024 Timestamp       c Start: 1504445418.275029 0.000000 0.000000
**** v1    3.3 vsl|       1024 Timestamp       c Req: 1504445418.275029 0.000000 0.000000
**** v1    3.3 vsl|       1024 ReqStart        c 127.0.0.1 39239
**** v1    3.3 vsl|       1024 ReqMethod       c GET
**** v1    3.3 vsl|       1024 ReqURL          c /backend_fetch
**   c1    3.3 Ending
**** v1    3.3 vsl|       1024 ReqProtocol     c HTTP/1.1
**** v1    3.3 vsl|       1024 ReqHeader       c X-Forwarded-For: 127.0.0.1
**** v1    3.3 vsl|       1024 VCL_call        c RECV
**** v1    3.3 vsl|       1024 VCL_return      c hash
**** v1    3.3 vsl|       1024 VCL_call        c HASH
**** v1    3.3 vsl|       1024 VCL_return      c lookup
**** v1    3.3 vsl|       1024 VCL_call        c MISS
**** v1    3.3 vsl|       1024 VCL_return      c fetch
**** v1    3.3 vsl|       1024 Link            c bereq 1025 fetch
**** v1    3.3 vsl|       1024 Timestamp       c Fetch: 1504445418.275171 0.000142 0.000142
**** v1    3.3 vsl|       1024 Timestamp       c Process: 1504445418.275182 0.000153 0.000011
**** v1    3.3 vsl|       1024 RespHeader      c Date: Sun, 03 Sep 2017 13:30:18 GMT
**** v1    3.3 vsl|       1024 RespHeader      c Server: Varnish
**** v1    3.3 vsl|       1024 RespHeader      c X-Varnish: 1024
**** v1    3.3 vsl|       1024 RespProtocol    c HTTP/1.1
**** v1    3.3 vsl|       1024 RespStatus      c 503
**** v1    3.3 vsl|       1024 RespReason      c Service Unavailable
**** v1    3.3 vsl|       1024 VCL_call        c SYNTH
**** v1    3.3 vsl|       1024 RespHeader      c Content-Type: text/html; charset=utf-8
**** v1    3.3 vsl|       1024 RespHeader      c Retry-After: 5
**** v1    3.3 vsl|       1024 VCL_return      c deliver
**** v1    3.3 vsl|       1024 RespHeader      c Content-Length: 278
**** v1    3.3 vsl|       1024 Storage         c malloc Transient
**** v1    3.3 vsl|       1024 RespHeader      c Connection: keep-alive
**** v1    3.3 vsl|       1024 Timestamp       c Resp: 1504445418.275246 0.000217 0.000064
**** v1    3.3 vsl|       1024 ReqAcct         c 31 0 31 208 278 486
**   top   3.3 === varnish v1 -expect vcl_fail == 11
**** v1    3.3 vsl|       1024 End             c
*    diag  0.0 Assert error in vsm_refresh_set2(), ../../../lib/libvarnishapi/vsm.c line 372:
*    diag  0.0   Condition((close(*(&vs->fd))) == 0) not true.
#    top  TEST ../../../bin/varnishtest/tests/v00051.vtc FAILED (3.271) signal=6

1 @ SunOS 5.11 i86pc 1.04 64_sun12.4 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/v00051.vtc starting
[...]
*    top   0.0 TEST Test VCL failures
[...]
**** c1    0.9 rxhdr|Retry-After: 5\r
**** c1    0.9 rxhdr|Content-Length: 251\r
**** c1    0.9 rxhdr|Connection: close\r
**** c1    0.9 rxhdr|\r
**** c1    0.9 rxhdrlen = 194
**** c1    0.9 http[ 0] |HTTP/1.1
**** c1    0.9 http[ 1] |503
**** c1    0.9 http[ 2] |VCL failed
**** c1    0.9 http[ 3] |Date: Sun, 03 Sep 2017 08:34:03 GMT
**** c1    0.9 http[ 4] |Server: Varnish
**** c1    0.9 http[ 5] |X-Varnish: 1014
**** c1    0.9 http[ 6] |Content-Type: text/html; charset=utf-8
**** c1    0.9 http[ 7] |Retry-After: 5
**** c1    0.9 http[ 8] |Content-Length: 251
**** c1    0.9 http[ 9] |Connection: close
**** c1    0.9 body|<!DOCTYPE html>
**** c1    0.9 body|<html>
**** c1    0.9 body|  <head>
**** c1    0.9 body|    <title>503 VCL failed</title>
**** c1    0.9 body|  </head>
**** c1    0.9 body|  <body>
**** c1    0.9 body|    <h1>Error 503 VCL failed</h1>
**** c1    0.9 body|    <p>VCL failed</p>
**** c1    0.9 body|    <h3>Guru Meditation:</h3>
**** c1    0.9 body|    <p>XID: 1014</p>
**** c1    0.9 body|    <hr>
**** c1    0.9 body|    <p>Varnish cache server</p>
**** c1    0.9 body|  </body>
**** c1    0.9 body|</html>
**** c1    0.9 bodylen = 251
**   c1    0.9 === expect resp.status == 503
**** c1    0.9 EXPECT resp.status (503) == "503" match
**   c1    0.9 === expect resp.reason == "VCL failed"
**** c1    0.9 EXPECT resp.reason (VCL failed) == "VCL failed" match
***  c1    0.9 closing fd 17
**   c1    0.9 Ending
**   top   0.9 === varnish v1 -expect vcl_fail == 6
*    diag  0.0 Assert error in vsm_refresh_set2(), ../../../lib/libvarnishapi/vsm.c line 372:
*    diag  0.0   Condition((close(*(&vs->fd))) == 0) not true.
#    top  TEST ../../../bin/varnishtest/tests/v00051.vtc FAILED (0.862) signal=6

1 @ SunOS 5.11 i86pc 1.04 64_gcc4.7 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/v00051.vtc starting
[...]
*    top   0.0 TEST Test VCL failures
[...]
**** c1    1.8 rxhdr|X-Varnish: 1016\r
**** c1    1.8 rxhdr|Content-Type: text/html; charset=utf-8\r
**** c1    1.8 rxhdr|Retry-After: 5\r
**** c1    1.8 rxhdr|Content-Length: 251\r
**** c1    1.8 rxhdr|Connection: close\r
**** c1    1.8 rxhdr|\r
**** c1    1.8 rxhdrlen = 194
**** c1    1.8 http[ 0] |HTTP/1.1
**** c1    1.8 http[ 1] |503
**** c1    1.8 http[ 2] |VCL failed
**** c1    1.8 http[ 3] |Date: Sun, 03 Sep 2017 12:25:17 GMT
**** c1    1.8 http[ 4] |Server: Varnish
**** c1    1.8 http[ 5] |X-Varnish: 1016
**** c1    1.8 http[ 6] |Content-Type: text/html; charset=utf-8
**** c1    1.8 http[ 7] |Retry-After: 5
**** c1    1.8 http[ 8] |Content-Length: 251
**** c1    1.8 http[ 9] |Connection: close
**** c1    1.8 body|<!DOCTYPE html>
**** c1    1.8 body|<html>
**** c1    1.8 body|  <head>
**** c1    1.8 body|    <title>503 VCL failed</title>
**** c1    1.8 body|  </head>
**** c1    1.8 body|  <body>
**** c1    1.8 body|    <h1>Error 503 VCL failed</h1>
**** c1    1.8 body|    <p>VCL failed</p>
**** c1    1.8 body|    <h3>Guru Meditation:</h3>
**** c1    1.8 body|    <p>XID: 1016</p>
**** c1    1.8 body|    <hr>
**** c1    1.8 body|    <p>Varnish cache server</p>
**** c1    1.8 body|  </body>
**** c1    1.8 body|</html>
**** c1    1.8 bodylen = 251
**   c1    1.8 === expect resp.status == 503
**** c1    1.8 EXPECT resp.status (503) == "503" match
**   c1    1.8 === expect resp.reason == "VCL failed"
**** c1    1.8 EXPECT resp.reason (VCL failed) == "VCL failed" match
***  c1    1.8 closing fd 17
**   c1    1.8 Ending
**   top   1.8 === varnish v1 -expect vcl_fail == 7
#    top  TEST ../../../bin/varnishtest/tests/v00051.vtc FAILED (1.756) signal=6

1 @ SunOS 5.11 i86pc 1.04 32_sun12.4 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/v00051.vtc starting
[...]
*    top   0.0 TEST Test VCL failures
[...]
**** v1    1.3 vsl|       1025 VCL_Error       b Forced failure
**** v1    1.3 vsl|       1025 VCL_return      b fail
**** v1    1.3 vsl|       1025 BereqAcct       b 0 0 0 0 0 0
**** v1    1.3 vsl|       1025 End             b
**** v1    1.3 vsl|       1024 Begin           c req 1023 rxreq
**** v1    1.3 vsl|       1024 Timestamp       c Start: 1504470769.038759 0.000000 0.000000
**** v1    1.3 vsl|       1024 Timestamp       c Req: 1504470769.038759 0.000000 0.000000
**** v1    1.3 vsl|       1024 ReqStart        c 127.0.0.1 57587
**** v1    1.3 vsl|       1024 ReqMethod       c GET
**** v1    1.3 vsl|       1024 ReqURL          c /backend_fetch
**** v1    1.3 vsl|       1024 ReqProtocol     c HTTP/1.1
**** v1    1.3 vsl|       1024 ReqHeader       c X-Forwarded-For: 127.0.0.1
**** v1    1.3 vsl|       1024 VCL_call        c RECV
**** v1    1.3 vsl|       1024 VCL_return      c hash
**** v1    1.3 vsl|       1024 VCL_call        c HASH
**** v1    1.3 vsl|       1024 VCL_return      c lookup
**** v1    1.3 vsl|       1024 VCL_call        c MISS
**** v1    1.3 vsl|       1024 VCL_return      c fetch
**** v1    1.3 vsl|       1024 Link            c bereq 1025 fetch
**** v1    1.3 vsl|       1024 Timestamp       c Fetch: 1504470769.038944 0.000185 0.000185
**** v1    1.3 vsl|       1024 Timestamp       c Process: 1504470769.038959 0.000200 0.000014
**** v1    1.3 vsl|       1024 RespHeader      c Date: Sun, 03 Sep 2017 20:32:49 GMT
**** v1    1.3 vsl|       1024 RespHeader      c Server: Varnish
**** v1    1.3 vsl|       1024 RespHeader      c X-Varnish: 1024
**** v1    1.3 vsl|       1024 RespProtocol    c HTTP/1.1
**** v1    1.3 vsl|       1024 RespStatus      c 503
**** v1    1.3 vsl|       1024 RespReason      c Service Unavailable
**** v1    1.3 vsl|       1024 VCL_call        c SYNTH
**** v1    1.3 vsl|       1024 RespHeader      c Content-Type: text/html; charset=utf-8
**** v1    1.3 vsl|       1024 RespHeader      c Retry-After: 5
**** v1    1.3 vsl|       1024 VCL_return      c deliver
**** v1    1.3 vsl|       1024 RespHeader      c Content-Length: 278
**** v1    1.3 vsl|       1024 Storage         c malloc Transient
**** v1    1.3 vsl|       1024 RespHeader      c Connection: keep-alive
**** v1    1.3 vsl|       1024 Timestamp       c Resp: 1504470769.039037 0.000278 0.000079
**** v1    1.3 vsl|       1024 ReqAcct         c 31 0 31 208 278 486
**** v1    1.3 vsl|       1024 End             c
**** v1    1.3 vsl|       1023 SessClose       c REM_CLOSE 0.001
**** v1    1.3 vsl|       1023 End             c
#    top  TEST ../../../bin/varnishtest/tests/v00051.vtc FAILED (1.347) signal=11

b0339d1

1 @ SunOS 5.11 i86pc 1.04 32_gcc4.7 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/v00051.vtc starting
[...]
*    top   0.0 TEST Test VCL failures
[...]
**** c1    2.2 rxhdr|X-Varnish: 1030\r
**** c1    2.2 rxhdr|Content-Type: text/html; charset=utf-8\r
**** c1    2.2 rxhdr|Retry-After: 5\r
**** c1    2.2 rxhdr|Content-Length: 278\r
**** c1    2.2 rxhdr|Connection: keep-alive\r
**** c1    2.2 rxhdr|\r
**** c1    2.2 rxhdrlen = 208
**** c1    2.2 http[ 0] |HTTP/1.1
**** c1    2.2 http[ 1] |503
**** c1    2.2 http[ 2] |Service Unavailable
**** c1    2.2 http[ 3] |Date: Sat, 02 Sep 2017 02:20:11 GMT
**** c1    2.2 http[ 4] |Server: Varnish
**** c1    2.2 http[ 5] |X-Varnish: 1030
**** c1    2.2 http[ 6] |Content-Type: text/html; charset=utf-8
**** c1    2.2 http[ 7] |Retry-After: 5
**** c1    2.2 http[ 8] |Content-Length: 278
**** c1    2.2 http[ 9] |Connection: keep-alive
**** c1    2.2 body|<!DOCTYPE html>
**** c1    2.2 body|<html>
**** c1    2.2 body|  <head>
**** c1    2.2 body|    <title>503 Service Unavailable</title>
**** c1    2.2 body|  </head>
**** c1    2.2 body|  <body>
**** c1    2.2 body|    <h1>Error 503 Service Unavailable</h1>
**** c1    2.2 body|    <p>Service Unavailable</p>
**** c1    2.2 body|    <h3>Guru Meditation:</h3>
**** c1    2.2 body|    <p>XID: 1030</p>
**** c1    2.2 body|    <hr>
**** c1    2.2 body|    <p>Varnish cache server</p>
**** c1    2.2 body|  </body>
**** c1    2.2 body|</html>
**** c1    2.2 bodylen = 278
**   c1    2.2 === expect resp.status == 503
**** c1    2.2 EXPECT resp.status (503) == "503" match
**   c1    2.2 === expect resp.reason == "Service Unavailable"
**** c1    2.2 EXPECT resp.reason (Service Unavailable) == "Service Unavailable" match
***  c1    2.2 closing fd 18
**   c1    2.2 Ending
**   top   2.2 === varnish v1 -expect vcl_fail == 13
#    top  TEST ../../../bin/varnishtest/tests/v00051.vtc FAILED (2.251) signal=11

1 @ SunOS 5.11 i86pc 1.04 32_gcc4.7 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/v00051.vtc starting
[...]
*    top   0.0 TEST Test VCL failures
[...]
**** v1    2.8 vsl|       1030 RespHeader      c Retry-After: 5
**** c1    2.8 http[ 1] |503
**** v1    2.8 vsl|       1030 VCL_return      c deliver
**** c1    2.8 http[ 2] |Service Unavailable
**** v1    2.8 vsl|       1030 RespHeader      c Content-Length: 278
**** v1    2.8 vsl|       1030 Storage         c malloc Transient
**** c1    2.8 http[ 3] |Date: Sat, 02 Sep 2017 17:16:22 GMT
**** v1    2.8 vsl|       1030 RespHeader      c Connection: keep-alive
**** c1    2.8 http[ 4] |Server: Varnish
**** c1    2.8 http[ 5] |X-Varnish: 1030
**** c1    2.8 http[ 6] |Content-Type: text/html; charset=utf-8
**** v1    2.8 vsl|       1030 Timestamp       c Resp: 1504372582.197453 0.000941 0.000089
**** c1    2.8 http[ 7] |Retry-After: 5
**** v1    2.8 vsl|       1030 ReqAcct         c 34 0 34 208 278 486
**** c1    2.8 http[ 8] |Content-Length: 278
**** v1    2.8 vsl|       1030 End             c
**** c1    2.8 http[ 9] |Connection: keep-alive
**** c1    2.8 body|<!DOCTYPE html>
**** c1    2.8 body|<html>
**** c1    2.8 body|  <head>
**** c1    2.8 body|    <title>503 Service Unavailable</title>
**** c1    2.8 body|  </head>
**** c1    2.8 body|  <body>
**** c1    2.8 body|    <h1>Error 503 Service Unavailable</h1>
**** c1    2.8 body|    <p>Service Unavailable</p>
**** c1    2.8 body|    <h3>Guru Meditation:</h3>
**** c1    2.8 body|    <p>XID: 1030</p>
**** c1    2.8 body|    <hr>
**** c1    2.8 body|    <p>Varnish cache server</p>
**** c1    2.8 body|  </body>
**** c1    2.8 body|</html>
**** c1    2.8 bodylen = 278
**   c1    2.8 === expect resp.status == 503
**** c1    2.8 EXPECT resp.status (503) == "503" match
**   c1    2.8 === expect resp.reason == "Service Unavailable"
**** c1    2.8 EXPECT resp.reason (Service Unavailable) == "Service Unavailable" match
***  c1    2.8 closing fd 18
**   c1    2.8 Ending
**   top   2.8 === varnish v1 -expect vcl_fail == 13
#    top  TEST ../../../bin/varnishtest/tests/v00051.vtc FAILED (2.769) signal=11

1 @ SunOS 5.11 i86pc 1.04 32_sun12.4 uplex

*    top   0.0 TEST ../../../bin/varnishtest/tests/v00051.vtc starting
[...]
*    top   0.0 TEST Test VCL failures
[...]
**** v1    0.9 vsl|       1011 RespHeader      c X-Varnish: 1011
**** v1    0.9 vsl|       1011 RespProtocol    c HTTP/1.1
**** v1    0.9 vsl|       1011 RespStatus      c 503
**** v1    0.9 vsl|       1011 RespReason      c Service Unavailable
**** v1    0.9 vsl|       1011 RespReason      c VCL failed
**** v1    0.9 vsl|       1011 VCL_call        c SYNTH
**** v1    0.9 vsl|       1011 RespHeader      c Content-Type: text/html; charset=utf-8
**** v1    0.9 vsl|       1011 RespHeader      c Retry-After: 5
**** v1    0.9 vsl|       1011 VCL_return      c deliver
**** v1    0.9 vsl|       1011 RespHeader      c Content-Length: 251
**** v1    0.9 vsl|       1011 Storage         c malloc Transient
**** v1    0.9 vsl|       1011 RespHeader      c Connection: close
**** c1    0.9 body|<!DOCTYPE html>
**** c1    0.9 body|<html>
**** c1    0.9 body|  <head>
**** c1    0.9 body|    <title>503 VCL failed</title>
**** c1    0.9 body|  </head>
**** c1    0.9 body|  <body>
**** c1    0.9 body|    <h1>Error 503 VCL failed</h1>
**** c1    0.9 body|    <p>VCL failed</p>
**** c1    0.9 body|    <h3>Guru Meditation:</h3>
**** c1    0.9 body|    <p>XID: 1011</p>
**** c1    0.9 body|    <hr>
**** c1    0.9 body|    <p>Varnish cache server</p>
**** c1    0.9 body|  </body>
**** c1    0.9 body|</html>
**** v1    0.9 vsl|       1011 Timestamp       c Resp: 1504368755.033622 0.000199 0.000102
**** c1    0.9 bodylen = 251
**** v1    0.9 vsl|       1011 ReqAcct         c 29 0 29 194 251 445
**   c1    0.9 === expect resp.status == 503
**** v1    0.9 vsl|       1011 End             c
**** v1    0.9 vsl|       1010 SessClose       c VCL_FAILURE 0.000
**** c1    0.9 EXPECT resp.status (503) == "503" match
**** v1    0.9 vsl|       1010 End             c
**   c1    0.9 === expect resp.reason == "VCL failed"
**** c1    0.9 EXPECT resp.reason (VCL failed) == "VCL failed" match
***  c1    0.9 closing fd 17
**   c1    0.9 Ending
**   top   0.9 === varnish v1 -expect vcl_fail == 5
#    top  TEST ../../../bin/varnishtest/tests/v00051.vtc FAILED (0.864) signal=11