[Varnish] #519: 503 error problem

Varnish varnish-bugs at projects.linpro.no
Thu Jun 11 13:16:52 CEST 2009


#519: 503 error problem
-----------------------+----------------------------------------------------
 Reporter:  silver     |        Type:  defect
   Status:  new        |    Priority:  normal
Milestone:             |   Component:  build 
  Version:  2.0        |    Severity:  normal
 Keywords:  503 error  |  
-----------------------+----------------------------------------------------
 ENV: centos 5.2 64bit, varnish 2.0.4
  The problem has 2 different appearances:

  First, one of my dynamic link alwayse turns 503 error. And varnishlog
 result is as follows:

 {{{
    10 SessionOpen  c 125.230.149.219 1819 :80
    12 SessionOpen  c 124.207.129.40 1601 :80
    12 ReqStart     c 124.207.129.40 1601 1822984100
    12 RxRequest    c GET
    12 RxURL        c /pause/index.php?c=dshj,guzhuang,ndjch
    12 RxProtocol   c HTTP/1.1
    12 RxHeader     c Accept: image/gif, image/x-xbitmap, image/jpeg,
 image/pjpeg, application/x-shockwave-flash, application/vnd.ms-excel,
 application/vnd.ms
 -powerpoint, application/msword, application/x-silverlight, */*
    12 RxHeader     c Accept-Language: zh-cn
    12 RxHeader     c Accept-Encoding: gzip, deflate
    12 RxHeader     c User-Agent: Mozilla/4.0 (compatible; MSIE 6.0;
 Windows NT 5.1; SV1; .NET CLR 2.0.50727)
    12 RxHeader     c Host: fs.funshion.com
    12 RxHeader     c Connection: Keep-Alive
    12 RxHeader     c Cookie: tacarea=1; taczone=z1;
 __utma=227745162.516174632591317950.1244613332.1244615894.1244615928.4;
 __utmz=227745162.1244613332.1.1.u
 tmcsr=(direct)|utmccn=(direct)|utmcmd=(none)
    12 VCL_call     c recv
    12 VCL_return   c lookup
    12 VCL_call     c hash
    12 VCL_return   c hash
    12 VCL_call     c miss
    12 VCL_return   c fetch
    13 BackendOpen  b funshionfs 222.35.250.5 25595 222.35.250.4 80
    12 Backend      c 13 funshionfs funshionfs
    13 TxRequest    b GET
    13 TxURL        b /pause/index.php?c=dshj,guzhuang,ndjch
    13 TxProtocol   b HTTP/1.1
    13 TxHeader     b Accept: image/gif, image/x-xbitmap, image/jpeg,
 image/pjpeg, application/x-shockwave-flash, application/vnd.ms-excel,
 application/vnd.ms
 -powerpoint, application/msword, application/x-silverlight, */*
    13 TxHeader     b Accept-Language: zh-cn
    13 TxHeader     b Accept-Encoding: gzip, deflate
    13 TxHeader     b User-Agent: Mozilla/4.0 (compatible; MSIE 6.0;
 Windows NT 5.1; SV1; .NET CLR 2.0.50727)
    13 TxHeader     b Host: fs.funshion.com
    13 TxHeader     b Cookie: tacarea=1; taczone=z1;
 __utma=227745162.516174632591317950.1244613332.1244615894.1244615928.4;
 __utmz=227745162.1244613332.1.1.u
 tmcsr=(direct)|utmccn=(direct)|utmcmd=(none)
    13 TxHeader     b X-Forwarded-For: 124.207.129.40
    13 TxHeader     b X-Varnish: 1822984100
    13 TxHeader     b X-Forwarded-For: 124.207.129.40
    13 RxProtocol   b HTTP/1.1
    13 RxStatus     b 200
    13 RxResponse   b OK
    13 RxHeader     b X-Powered-By: PHP/5.2.1
    13 RxHeader     b Set-Cookie: PHPSESSID=m3suseosc4a74095r5r7ip9kp6;
 path=/; domain=.funshion.com
    13 RxHeader     b Pragma: no-cache
    13 RxHeader     b Last-Modified: Thu, 11 Jun 2009 02:04:36 GMT
    13 RxHeader     b Expires: Fri, 12 Jun 2009 02:04:36 GMT
    13 RxHeader     b Cache-Control: max-age=86400
    13 RxHeader     b Content-Length: 1506
    13 RxHeader     b Content-Encoding: gzip
    13 RxHeader     b Vary: Accept-Encoding
    13 RxHeader     b Content-type: text/html
    13 RxHeader     b Date: Thu, 11 Jun 2009 02:04:36 GMT
    13 RxHeader     b Server: lighttpd/1.4.22
    12 ObjProtocol  c HTTP/1.1
    12 ObjStatus    c 200
    12 ObjResponse  c OK
    12 ObjHeader    c X-Powered-By: PHP/5.2.1
    12 ObjHeader    c Set-Cookie: PHPSESSID=m3suseosc4a74095r5r7ip9kp6;
 path=/; domain=.funshion.com
    12 ObjHeader    c Pragma: no-cache
    12 ObjHeader    c Last-Modified: Thu, 11 Jun 2009 02:04:36 GMT
    12 ObjHeader    c Expires: Fri, 12 Jun 2009 02:04:36 GMT
    12 ObjHeader    c Cache-Control: max-age=86400
    12 ObjHeader    c Content-Encoding: gzip
    12 ObjHeader    c Vary: Accept-Encoding
    12 ObjHeader    c Content-type: text/html
    12 ObjHeader    c Date: Thu, 11 Jun 2009 02:04:36 GMT
    12 ObjHeader    c Server: lighttpd/1.4.22
    13 BackendClose b funshionfs
 }}}
  '''   12 VCL_call     c error'''
 {{{
    12 VCL_return   c deliver
    12 Length       c 466
    12 VCL_call     c deliver
    12 VCL_return   c deliver
    12 TxProtocol   c HTTP/1.1
    12 TxStatus     c 503
    12 TxResponse   c Service Unavailable
    12 TxHeader     c Server: Varnish
    12 TxHeader     c Retry-After: 0
    12 TxHeader     c Content-Type: text/html; charset=utf-8
    12 TxHeader     c Content-Length: 466
    12 TxHeader     c Date: Thu, 11 Jun 2009 02:04:42 GMT
    12 TxHeader     c X-Varnish: 1822984100
    12 TxHeader     c Age: 1
    12 TxHeader     c Via: 1.1 varnish
    12 TxHeader     c Connection: close
    12 TxHeader     c X-Cache: MISS
    12 ReqEnd       c 1822984100 1244685882.232357979 1244685882.997204065
 0.001631975 0.764765978 0.000080109
    12 SessionClose c error
 }}}

 And i can get the right content via varnish by curl, but 503 by IE.
 The bold line ,varnish should call fetch instead of error. There are some
 similar actions i found here, but no solution.

 The second one:


 {{{
   952 ReqStart     c 118.100.158.74 64065 350606500
   952 RxRequest    c GET
   952 RxURL        c /attachment/fs/521/26/52126.jpg?1241778919
   952 RxProtocol   c HTTP/1.0
   952 RxHeader     c Accept: */*
   952 RxHeader     c Referer:
 http://fs.funshion.com/embed_list/region?r=e6aca7e6b4b2&o=z1&pt=vp&pg=13
   952 RxHeader     c Accept-Language: en-us
   952 RxHeader     c UA-CPU: x86
   952 RxHeader     c Accept-Encoding: gzip, deflate
   952 RxHeader     c User-Agent: Mozilla/4.0 (compatible; MSIE 7.0;
 Windows NT 5.1; GTB6; .NET CLR 2.0.50727; InfoPath.1;
 OfficeLiveConnector.1.3; OfficeLive
 Patch.0.0)
   952 RxHeader     c Host: img.funshion.com
   952 RxHeader     c Cookie:
 __utma=110910354.772200403240562500.1237512648.1237512648.1237512648.1;
 __utmz=110910354.1237512648.1.1.utmcsr=funshion-movie-on
 -demand.software.informer.com|utmccn=(referral)|utmcmd=referral|utmcct=/;
 funshion_setup=1; thide=0; userplay=%u5904%u5
   952 RxHeader     c Via: 1.1 SnapGear:3128 (squid/2.5.STABLE10)
   952 RxHeader     c X-Forwarded-For: 192.168.0.2
   952 RxHeader     c Cache-Control: max-age=259200
   952 RxHeader     c Connection: keep-alive
   952 VCL_call     c recv
   952 VCL_return   c lookup
   952 VCL_call     c hash
   952 VCL_return   c hash
   952 VCL_call     c miss
   952 VCL_return   c fetch
 }}}
  '''  952 VCL_call     c error'''
 {{{
   952 VCL_return   c deliver
   952 Length       c 465
   952 VCL_call     c deliver
   952 VCL_return   c deliver
   952 TxProtocol   c HTTP/1.1
   952 TxStatus     c 503
   952 TxResponse   c Service Unavailable
   952 TxHeader     c Server: Varnish
   952 TxHeader     c Retry-After: 0
   952 TxHeader     c Content-Type: text/html; charset=utf-8
   952 TxHeader     c Content-Length: 465
   952 TxHeader     c Date: Wed, 10 Jun 2009 04:06:14 GMT
   952 TxHeader     c X-Varnish: 350606500
   952 TxHeader     c Age: 5
   952 TxHeader     c Via: 1.1 varnish
   952 TxHeader     c Connection: close
   952 TxHeader     c X-Cache: MISS
   952 ReqEnd       c 350606500 1244606769.210163116 1244606774.209917068
 0.784834146 4.999708891 0.000045061
   952 SessionClose c error
   952 StatSess     c 118.100.158.74 64065 148 1 49 0 0 7 21706 177002
 }}}

 Sometimes non-dynamic links came to 503. But in this situation, a refresh
 will make it right.

 BTW, when i install varnish, make check turns 2 fails, but the check
 result is too long, i can't find the wrong part.

 So if there is any need, just let me know.

 Thanks in advance.

-- 
Ticket URL: <http://varnish.projects.linpro.no/ticket/519>
Varnish <http://varnish.projects.linpro.no/>
The Varnish HTTP Accelerator


More information about the varnish-bugs mailing list