Odd varnish generated 503 issue
Kevin Pettijohn
kpettijohn at dailyinsightgroup.com
Fri Feb 25 19:55:03 CET 2011
Hello everyone,
I'm having some issues using varnish and pressflow. I am currently running varnish-2.1.5 SVN 0843d7a with pressflow 6.17. The problem is a small corner case and I have not been having much luck figuring it out. If anyone can point me in the right direction that would be much appreciated. The issue is that when a logged in user with administrative privileges tries to view our /forum page it displays the varnish generated error page for a 503 after the 60 second first_byte_timeout.
On the other hand if the user is logged in but does not have administrative privileges the /forum page loads just fine and the same goes for logged out users.
I tried setting a pass in vcl but it didn't seem to help:
// Pass on forum pages
if (req.url ~ "^/forum$") {
return (pass);
}
Here are is the varnishlog output for the failed request (logged in user with admin):
25 SessionOpen c 75.146.54.221 53347 :80
25 ReqStart c 75.146.54.221 53347 366998573
25 RxRequest c GET
25 RxURL c /forum
25 RxProtocol c HTTP/1.1
25 RxHeader c Host: www.example.com<http://www.example.com/>
25 RxHeader c Connection: keep-alive
25 RxHeader c Referer: http://www.example.com/forum
25 RxHeader c Cache-Control: max-age=0
25 RxHeader c Accept: application/xml,application/xhtml+xml,text/html;q=0.9,text/plain;q=0.8,image/png,*/*;q=0.5
25 RxHeader c User-Agent: Mozilla/5.0 (Macintosh; U; Intel Mac OS X 10_6_6; en-US) AppleWebKit/534.10 (KHTML, like Gecko) Chrome/8.0.552.215 Safari/534.10
25 RxHeader c Accept-Encoding: gzip,deflate,sdch
25 RxHeader c Accept-Language: en-US,en;q=0.8
25 RxHeader c Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.3
25 RxHeader c Cookie: __qca=P0-2045062115-1295000757408; PHPSESSID=hrvrdh7k9qva2544rfcrqm4u91; BCKPHPSESSID=hrvrdh7k9qva2544rfcrqm4u91; __utmz=240455574.1298425082.43.4.utmcsr=google|utmccn=(organic)|utmcmd=organic|utmctr=http://example.com/; SESS26a2330bc4f50e2
25 VCL_call c recv pass
25 VCL_call c hash hash
25 VCL_call c pass pass
25 Backend c 21 dh web02
25 FetchError c http first read error: -1 11 (Resource temporarily unavailable)
25 VCL_call c error deliver
25 VCL_call c deliver deliver
25 TxProtocol c HTTP/1.1
25 TxStatus c 503
25 TxResponse c Service Unavailable
25 TxHeader c Server: Varnish
25 TxHeader c Retry-After: 0
25 TxHeader c Content-Type: text/html; charset=UTF-8
25 TxHeader c Content-Length: 2385
25 TxHeader c Date: Thu, 24 Feb 2011 23:34:12 GMT
25 TxHeader c X-Varnish: 366998573
25 TxHeader c Age: 60
25 TxHeader c Via: 1.1 varnish
25 TxHeader c Connection: close
25 TxHeader c X-Cache: MISS
25 Length c 2385
25 ReqEnd c 366998573 1298590392.349307060 1298590452.349412918 0.000030041 60.000077963 0.000027895
0 ExpKill - 366993383 -300
0 ExpKill - 366993387 -300
Here is the log for a successful load (logged out user):
32 SessionOpen c 75.146.54.241 61453 :80
32 ReqStart c 75.146.54.241 61453 367412524
32 RxRequest c GET
32 RxURL c /forum
32 RxProtocol c HTTP/1.1
32 RxHeader c Host: www.example.com<http://www.example.com/>
32 RxHeader c Accept-Encoding: gzip, deflate
32 RxHeader c Accept-Language: en-us
32 RxHeader c User-Agent: Mozilla/5.0 (Macintosh; U; Intel Mac OS X 10_6_6; en-us) AppleWebKit/533.19.4 (KHTML, like Gecko) Version/5.0.3 Safari/533.19.4
32 RxHeader c Accept: application/xml,application/xhtml+xml,text/html;q=0.9,text/plain;q=0.8,image/png,*/*;q=0.5
32 RxHeader c Cookie: __utmc=240455574; __utma=240455574.1048652604.1298005414.1298005414.1298425351.2; __utmz=240455574.1298005414.1.1.utmcsr=(direct)|utmccn=(direct)|utmcmd=(none)
32 RxHeader c Connection: keep-alive
32 VCL_call c recv pass
32 VCL_call c hash hash
32 VCL_call c pass pass
32 Backend c 9 dh web03
32 TTL c 367412524 RFC 600 1298659484 0 0 600 0
32 VCL_call c fetch pass
32 ObjProtocol c HTTP/1.1
32 ObjStatus c 200
32 ObjResponse c OK
32 ObjHeader c Date: Fri, 25 Feb 2011 18:45:29 GMT
32 ObjHeader c Server: Apache/2.2.8 (Unix) PHP/5.2.5 mod_ssl/2.2.8 OpenSSL/0.9.7a
32 ObjHeader c X-Powered-By: PHP/5.2.5
32 ObjHeader c Cache-Control: public, max-age=600
32 ObjHeader c Last-Modified: Fri, 25 Feb 2011 18:45:29 +0000
32 ObjHeader c Expires: Sun, 11 Mar 1984 12:00:00 GMT
32 ObjHeader c Vary: Cookie
32 ObjHeader c ETag: "1298659529"
32 ObjHeader c Content-Type: text/html; charset=utf-8
32 VCL_call c deliver deliver
32 TxProtocol c HTTP/1.1
32 TxStatus c 200
32 TxResponse c OK
32 TxHeader c Server: Apache/2.2.8 (Unix) PHP/5.2.5 mod_ssl/2.2.8 OpenSSL/0.9.7a
32 TxHeader c X-Powered-By: PHP/5.2.5
32 TxHeader c Cache-Control: public, max-age=600
32 TxHeader c Last-Modified: Fri, 25 Feb 2011 18:45:29 +0000
32 TxHeader c Expires: Sun, 11 Mar 1984 12:00:00 GMT
32 TxHeader c Vary: Cookie
32 TxHeader c ETag: "1298659529"
32 TxHeader c Content-Type: text/html; charset=utf-8
32 TxHeader c Content-Length: 83552
32 TxHeader c Date: Fri, 25 Feb 2011 18:44:45 GMT
32 TxHeader c X-Varnish: 367412524
32 TxHeader c Age: 0
32 TxHeader c Via: 1.1 varnish
32 TxHeader c Connection: keep-alive
32 TxHeader c X-Cache: MISS
32 Length c 83552
32 ReqEnd c 367412524 1298659483.560426950 1298659485.242687941 0.000025988 1.473248959 0.209012032
If anyone can give me some insight It would be greatly appreciated
__________________
Kevin Pettijohn
503 927 3585
Operations & IT
The Daily Insight Group
kpettijohn at tarot.com<mailto:kpettijohn at tarot.com>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://www.varnish-cache.org/lists/pipermail/varnish-misc/attachments/20110225/1b34fffd/attachment-0003.html>
More information about the varnish-misc
mailing list