[Varnish] #968: "Resource temporarily unavailable" error when backend responds with Transfer-Encoding: chunked
Varnish
varnish-bugs at varnish-cache.org
Tue Jul 26 20:44:55 CEST 2011
#968: "Resource temporarily unavailable" error when backend responds with
Transfer-Encoding: chunked
------------------------------------------------+---------------------------
Reporter: sctb | Type: defect
Status: new | Priority: normal
Milestone: | Component: varnishd
Version: 3.0.0 | Severity: normal
Keywords: chunked hang transfer-encoding 503 |
------------------------------------------------+---------------------------
Here the varnishlog output from a session that exhibits this problem:
{{{
0 CLI - Rd ping
0 CLI - Wr 200 19 PONG 1311705005 1.0
14 BackendOpen b default 127.0.0.1 54918 127.0.0.1 8000
14 TxRequest b POST
14 TxURL b /simplest-
calc?bkey=247004fee56c9473094474859361c37c9053264a
14 TxProtocol b HTTP/1.1
14 TxHeader b content-length: 103
14 TxHeader b X-Forwarded-For: 68.145.230.90
14 TxHeader b X-Varnish: 1688028143
14 TxHeader b Host: 127.0.0.1
14 RxProtocol b HTTP/1.1
14 RxStatus b 200
14 RxResponse b OK
14 RxHeader b Connection: keep-alive
14 RxHeader b Transfer-Encoding: chunked
14 Fetch_Body b 3 0 1
14 Length b 40
14 BackendReuse b default
13 SessionOpen c 68.145.230.90 58615 0.0.0.0:8001
13 ReqStart c 68.145.230.90 58615 1688028143
13 RxRequest c POST
13 RxURL c /simplest-
calc?bkey=247004fee56c9473094474859361c37c9053264a
13 RxProtocol c HTTP/1.1
13 RxHeader c content-length: 103
13 RxHeader c Connection: close
13 VCL_call c recv pass
13 VCL_call c hash
13 Hash c /simplest-
calc?bkey=247004fee56c9473094474859361c37c9053264a
13 Hash c 10.97.29.183
13 VCL_return c hash
13 VCL_call c pass pass
13 Backend c 14 default default
13 TTL c 1688028143 RFC 120 1311705007 0 0 0 0
13 VCL_call c fetch hit_for_pass
13 ObjProtocol c HTTP/1.1
13 ObjResponse c OK
13 VCL_call c deliver deliver
13 TxProtocol c HTTP/1.1
13 TxStatus c 200
13 TxResponse c OK
13 TxHeader c Content-Length: 40
13 TxHeader c Accept-Ranges: bytes
13 TxHeader c Date: Tue, 26 Jul 2011 18:30:07 GMT
13 TxHeader c X-Varnish: 1688028143
13 TxHeader c Age: 0
13 TxHeader c Via: 1.1 varnish
13 TxHeader c Connection: close
13 Length c 40
13 ReqEnd c 1688028143 1311705007.461123228 1311705007.468569756
0.000087023 0.007403135 0.000043392
13 SessionClose c Connection: close
13 StatSess c 68.145.230.90 58615 0 1 1 0 1 1 166 40
0 CLI - Rd ping
0 CLI - Wr 200 19 PONG 1311705008 1.0
0 CLI - Rd ping
0 CLI - Wr 200 19 PONG 1311705011 1.0
0 CLI - Rd ping
0 CLI - Wr 200 19 PONG 1311705014 1.0
0 CLI - Rd ping
0 CLI - Wr 200 19 PONG 1311705017 1.0
0 CLI - Rd ping
0 CLI - Wr 200 19 PONG 1311705020 1.0
0 CLI - Rd ping
0 CLI - Wr 200 19 PONG 1311705023 1.0
0 CLI - Rd ping
0 CLI - Wr 200 19 PONG 1311705026 1.0
0 CLI - Rd ping
0 CLI - Wr 200 19 PONG 1311705029 1.0
0 CLI - Rd ping
0 CLI - Wr 200 19 PONG 1311705032 1.0
0 CLI - Rd ping
0 CLI - Wr 200 19 PONG 1311705035 1.0
0 CLI - Rd ping
0 CLI - Wr 200 19 PONG 1311705038 1.0
0 CLI - Rd ping
0 CLI - Wr 200 19 PONG 1311705041 1.0
0 CLI - Rd ping
0 CLI - Wr 200 19 PONG 1311705044 1.0
0 CLI - Rd ping
0 CLI - Wr 200 19 PONG 1311705047 1.0
0 CLI - Rd ping
0 CLI - Wr 200 19 PONG 1311705050 1.0
0 CLI - Rd ping
0 CLI - Wr 200 19 PONG 1311705053 1.0
0 CLI - Rd ping
0 CLI - Wr 200 19 PONG 1311705056 1.0
0 CLI - Rd ping
0 CLI - Wr 200 19 PONG 1311705059 1.0
0 CLI - Rd ping
0 CLI - Wr 200 19 PONG 1311705062 1.0
0 CLI - Rd ping
0 CLI - Wr 200 19 PONG 1311705065 1.0
14 TxRequest b POST
14 TxURL b /simplest-
calc?bkey=247004fee56c9473094474859361c37c9053264a
14 TxProtocol b HTTP/1.1
14 TxHeader b content-length: 66
14 TxHeader b X-Forwarded-For: 68.145.230.90
14 TxHeader b X-Varnish: 1688028144
14 TxHeader b Host: 127.0.0.1
14 BackendClose b default
0 CLI - Rd ping
0 CLI - Wr 200 19 PONG 1311705068 1.0
0 CLI - Rd ping
0 CLI - Wr 200 19 PONG 1311705071 1.0
14 BackendOpen b default 127.0.0.1 57673 127.0.0.1 8000
14 TxRequest b POST
14 TxURL b /simplest-
calc?bkey=247004fee56c9473094474859361c37c9053264a
14 TxProtocol b HTTP/1.1
14 TxHeader b content-length: 66
14 TxHeader b X-Forwarded-For: 68.145.230.90
14 TxHeader b X-Varnish: 1688028144
14 TxHeader b Host: 127.0.0.1
14 BackendClose b default
13 SessionOpen c 68.145.230.90 58616 0.0.0.0:8001
13 ReqStart c 68.145.230.90 58616 1688028144
13 RxRequest c POST
13 RxURL c /simplest-
calc?bkey=247004fee56c9473094474859361c37c9053264a
13 RxProtocol c HTTP/1.1
13 RxHeader c content-length: 66
13 RxHeader c Connection: close
13 VCL_call c recv pass
13 VCL_call c hash
13 Hash c /simplest-
calc?bkey=247004fee56c9473094474859361c37c9053264a
13 Hash c 10.97.29.183
13 VCL_return c hash
13 VCL_call c pass pass
13 Backend c 14 default default
13 FetchError c http first read error: -1 11 (Resource temporarily
unavailable)
13 Backend c 14 default default
13 FetchError c backend write error: 11 (Resource temporarily
unavailable)
13 VCL_call c error deliver
13 VCL_call c deliver deliver
13 TxProtocol c HTTP/1.1
13 TxStatus c 503
13 TxResponse c Service Unavailable
13 TxHeader c Server: Varnish
13 TxHeader c Content-Type: text/html; charset=utf-8
13 TxHeader c Retry-After: 5
13 TxHeader c Content-Length: 419
13 TxHeader c Accept-Ranges: bytes
13 TxHeader c Date: Tue, 26 Jul 2011 18:31:12 GMT
13 TxHeader c X-Varnish: 1688028144
13 TxHeader c Age: 65
13 TxHeader c Via: 1.1 varnish
13 TxHeader c Connection: close
13 Length c 419
13 ReqEnd c 1688028144 1311705007.643313646 1311705072.642966986
0.000086546 64.999594212 0.000059128
13 SessionClose c error
13 StatSess c 68.145.230.90 58616 65 1 1 0 1 0 258 419
0 CLI - Rd ping
0 CLI - Wr 200 19 PONG 1311705074 1.0
0 CLI - Rd ping
}}}
If I configure the backend to set Content-Length rather than
Transfer-Encoding, the request is served as expected.
--
Ticket URL: <http://www.varnish-cache.org/trac/ticket/968>
Varnish <http://varnish-cache.org/>
The Varnish HTTP Accelerator
More information about the varnish-bugs
mailing list