503 Errors on POST

Torrance torrance123 at gmail.com
Sun Feb 7 10:56:00 CET 2010


I've no response to this on list, and the problem is ongoing. Should I
file this as a bug?

Torrance



On 30/01/10 12:32 PM, Torrance wrote:
> Hi Tollef,
>
> I've pasted the logs of two failed requests below. As you can see,
> they're both in response to POST requests, though I was overstating the
> frequency at which these errors are occurring: they're occurring about
> 10% of the time.
>
> To be honest, I don't entirely understand the logs or their format, but
> I hope I've captured the important details. (Session IDs have been
> deleted, btw).
>
> Many thanks,
> Torrance
>
>
>    15 ReqStart     c 125.236.128.219 51361 561006524
>    15 RxRequest    c POST
>    15 RxURL        c /node/78063/edit
>    15 RxProtocol   c HTTP/1.1
>    15 RxHeader     c Host: indymedia.org.nz
>    15 RxHeader     c User-Agent: Mozilla/5.0 (Macintosh; U; Intel Mac OS
> X 10.6; en-GB; rv:1.9.2) Gecko/20100115 Firefox/3.6
>    15 RxHeader     c Accept:
> text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
>    15 RxHeader     c Accept-Language: en-gb,en;q=0.5
>    15 RxHeader     c Accept-Encoding: gzip,deflate
>    15 RxHeader     c Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
>    15 RxHeader     c Keep-Alive: 115
>    15 RxHeader     c Connection: keep-alive
>    15 RxHeader     c Referer: http://indymedia.org.nz/node/78063/edit
>    15 RxHeader     c Cookie: comment_info_name=Tester;
> SESSxxxxxxxxxxxxxxxxxxxxxxxxxxxxx=xxxxxxxxxxxxxxxxxxxxxxxxxxxxx;
> SESSxxxxxxxxxxxxxxxxxxxxxxxxxxxxx=xxxxxxxxxxxxxxxxxxxxxxxxxxxxx; has_js=1
>    15 RxHeader     c Content-Type: multipart/form-data;
> boundary=---------------------------1850078892860212931738819713
>    15 RxHeader     c Content-Length: 16978
>    15 VCL_call     c recv
>    15 VCL_return   c pass
>    15 VCL_call     c pass
>    15 VCL_return   c pass
>    15 Backend      c 10 default default
>    10 TxRequest    b POST
>    10 TxURL        b /node/78063/edit
>    10 TxProtocol   b HTTP/1.1
>    10 TxHeader     b Host: indymedia.org.nz
>    10 TxHeader     b User-Agent: Mozilla/5.0 (Macintosh; U; Intel Mac OS
> X 10.6; en-GB; rv:1.9.2) Gecko/20100115 Firefox/3.6
>    10 TxHeader     b Accept:
> text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
>    10 TxHeader     b Accept-Language: en-gb,en;q=0.5
>    10 TxHeader     b Accept-Encoding: gzip,deflate
>    10 TxHeader     b Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
>    10 TxHeader     b Referer: http://indymedia.org.nz/node/78063/edit
>    10 TxHeader     b Cookie: comment_info_name=Tester;
> SESSxxxxxxxxxxxxxxxxxxxxxxxxxxxxx=xxxxxxxxxxxxxxxxxxxxxxxxxxxxx;
> SESSxxxxxxxxxxxxxxxxxxxxxxxxxxxxx=xxxxxxxxxxxxxxxxxxxxxxxxxxxxx; has_js=1
>    10 TxHeader     b Content-Type: multipart/form-data;
> boundary=---------------------------1850078892860212931738819713
>    10 TxHeader     b Content-Length: 16978
>    10 TxHeader     b X-Forwarded-For: 125.236.128.219
>    10 TxHeader     b X-Varnish: 561006524
>    10 TxHeader     b X-Forwarded-For: 125.236.128.219
>    10 BackendClose b default
>    15 VCL_call     c error
>    15 VCL_return   c deliver
>    15 Length       c 465
>    15 VCL_call     c deliver
>    15 VCL_return   c deliver
>    15 TxProtocol   c HTTP/1.1
>    15 TxStatus     c 503
>    15 TxResponse   c Service Unavailable
>    15 TxHeader     c Server: Varnish
>    15 TxHeader     c Retry-After: 0
>    15 TxHeader     c Content-Type: text/html; charset=utf-8
>    15 TxHeader     c Content-Length: 465
>    15 TxHeader     c Date: Fri, 29 Jan 2010 23:00:42 GMT
>    15 TxHeader     c X-Varnish: 561006524
>    15 TxHeader     c Age: 1
>    15 TxHeader     c Via: 1.1 varnish
>    15 TxHeader     c Connection: close
>    15 ReqEnd       c 561006524 1264806040.957435846 1264806042.241542339
> 4.125935793 1.284075260 0.000031233
>    15 SessionClose c error
>    15 StatSess     c 125.236.128.219 51361 14 1 3 0 3 2 1410 49426
>     0 StatAddr     - 125.236.128.219 0 1102 34 74 0 32 42 38287 1054700
>
>
>    21 ReqStart     c 125.236.128.219 53669 561007510
>    21 RxRequest    c POST
>    21 RxURL        c /node/78063/edit
>    21 RxProtocol   c HTTP/1.1
>    21 RxHeader     c Host: indymedia.org.nz
>    21 RxHeader     c User-Agent: Mozilla/5.0 (Macintosh; U; Intel Mac OS
> X 10.6; en-GB; rv:1.9.2) Gecko/20100115 Firefox/3.6
>    21 RxHeader     c Accept:
> text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
>    21 RxHeader     c Accept-Language: en-gb,en;q=0.5
>    21 RxHeader     c Accept-Encoding: gzip,deflate
>    21 RxHeader     c Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
>    21 RxHeader     c Keep-Alive: 115
>    21 RxHeader     c Connection: keep-alive
>    21 RxHeader     c Referer: http://indymedia.org.nz/node/78063/edit
>    21 RxHeader     c Cookie: comment_info_name=Tester;
> SESSxxxxxxxxxxxxxxxxxxxxxxxxxxxxx=xxxxxxxxxxxxxxxxxxxxxxxxxxxxx;
> SESSxxxxxxxxxxxxxxxxxxxxxxxxxxxxx=xxxxxxxxxxxxxxxxxxxxxxxxxxxxx; has_js=1
>    21 RxHeader     c Content-Type: multipart/form-data;
> boundary=---------------------------84863282515329900481602423677
>    21 RxHeader     c Content-Length: 17019
>    21 VCL_call     c recv
>    21 VCL_return   c pass
>    21 VCL_call     c pass
>    21 VCL_return   c pass
>    21 Backend      c 15 default default
>    15 TxRequest    b POST
>    15 TxURL        b /node/78063/edit
>    15 TxProtocol   b HTTP/1.1
>    15 TxHeader     b Host: indymedia.org.nz
>    15 TxHeader     b User-Agent: Mozilla/5.0 (Macintosh; U; Intel Mac OS
> X 10.6; en-GB; rv:1.9.2) Gecko/20100115 Firefox/3.6
>    15 TxHeader     b Accept:
> text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
>    15 TxHeader     b Accept-Language: en-gb,en;q=0.5
>    15 TxHeader     b Accept-Encoding: gzip,deflate
>    15 TxHeader     b Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
>    15 TxHeader     b Referer: http://indymedia.org.nz/node/78063/edit
>    15 TxHeader     b Cookie: comment_info_name=Tester;
> SESSxxxxxxxxxxxxxxxxxxxxxxxxxxxxx=xxxxxxxxxxxxxxxxxxxxxxxxxxxxx;
> SESSxxxxxxxxxxxxxxxxxxxxxxxxxxxxx=xxxxxxxxxxxxxxxxxxxxxxxxxxxxx; has_js=1
>    15 TxHeader     b Content-Type: multipart/form-data;
> boundary=---------------------------84863282515329900481602423677
>    15 TxHeader     b Content-Length: 17019
>    15 TxHeader     b X-Forwarded-For: 125.236.128.219
>    15 TxHeader     b X-Varnish: 561007510
>    15 TxHeader     b X-Forwarded-For: 125.236.128.219
>    15 BackendClose b default
>    21 VCL_call     c error
>    21 VCL_return   c deliver
>    21 Length       c 465
>    21 VCL_call     c deliver
>    21 VCL_return   c deliver
>    21 TxProtocol   c HTTP/1.1
>    21 TxStatus     c 503
>    21 TxResponse   c Service Unavailable
>    21 TxHeader     c Server: Varnish
>    21 TxHeader     c Retry-After: 0
>    21 TxHeader     c Content-Type: text/html; charset=utf-8
>    21 TxHeader     c Content-Length: 465
>    21 TxHeader     c Date: Fri, 29 Jan 2010 23:22:15 GMT
>    21 TxHeader     c X-Varnish: 561007510
>    21 TxHeader     c Age: 2
>    21 TxHeader     c Via: 1.1 varnish
>    21 TxHeader     c Connection: close
>    21 ReqEnd       c 561007510 1264807333.799563885 1264807335.335580826
> 3.151196718 1.535988092 0.000028849
>    21 SessionClose c error
>    21 StatSess     c 125.236.128.219 53669 35 1 9 0 5 4 4595 137808
>     0 StatAddr     - 125.236.128.219 0 2395 45 114 0 53 61 58675 1768498
>
> On 29/01/10 11:26 PM, Tollef Fog Heen wrote:
>   
>> ]] Torrance 
>>
>> | I am running a drupal site behind Varnish and when posts or comments are
>> | submitted there's about a 50/50 chance the user will get one of
>> | Varnish's 503 error pages. These errors aren't after waiting a little
>> | while or even a few seconds - they are returned with no delay
>> | whatsoever. I can only presume that this is somehow linked to the fact
>> | that these are POST requests, as the errors do not come up at any other
>> | time.
>>
>> If you can capture a varnishlog from a good and a failing request, that
>> might shed some light on what's going on.
>>
>>   
>>     
>   


-------------- next part --------------
A non-text attachment was scrubbed...
Name: signature.asc
Type: application/pgp-signature
Size: 905 bytes
Desc: OpenPGP digital signature
Url : http://projects.linpro.no/pipermail/varnish-misc/attachments/20100207/d9201c88/attachment.pgp 


More information about the varnish-misc mailing list