[Varnish] #149: Can't Purge

Varnish varnish-bugs at projects.linpro.no
Mon Aug 13 11:18:10 CEST 2007


#149: Can't Purge
----------------------+-----------------------------------------------------
 Reporter:  greenad   |        Owner:  phk          
     Type:  defect    |       Status:  reopened     
 Priority:  lowest    |    Milestone:  Varnish 1.0.4
Component:  varnishd  |      Version:  1.0.4        
 Severity:  minor     |   Resolution:               
 Keywords:            |  
----------------------+-----------------------------------------------------
Comment (by greenad):

 Thanks for your fast reply.

 This time I tried - /thumbs/7238558.jpg

 wget  --server-response http://localhost:8080/thumbs/7238558.jpg
  brings me this in varnishlog
 {{{

    11 SessionClose c Connection: close
    11 StatSess     c 10.10.23.46 55307 0 1 1 0 0 0 248 2796
    11 SessionOpen  c 10.10.23.46 55313
    11 ReqStart     c 10.10.23.46 55313 1701172779
    11 RxRequest    c GET
    11 RxURL        c /thumbs/9817923.jpg
    11 RxProtocol   c HTTP/1.0
    11 RxHeader     c Host: mogile
    11 RxHeader     c Connection: close
    11 VCL_call     c recv
    11 VCL_return   c lookup
    11 VCL_call     c hash
    11 VCL_return   c hash
    11 Hit          c 1701077913
    11 VCL_call     c hit
    11 VCL_return   c deliver
    11 Length       c 3797
    11 TxProtocol   c HTTP/1.1
    11 TxStatus     c 200
    11 TxResponse   c OK
    11 TxHeader     c Expires: Thu, 16 Aug 2007 08:04:15 GMT
    11 TxHeader     c Content-Type: image/jpeg
    11 TxHeader     c Date: Mon, 13 Aug 2007 08:04:15 GMT
    11 TxHeader     c Server: image_server
    11 TxHeader     c Content-Length: 3797
    11 TxHeader     c X-Varnish: 1701172779 1701077913
    11 TxHeader     c Age: 3517
    11 TxHeader     c Via: 1.1 varnish
    11 TxHeader     c Connection: close
    11 ReqEnd       c 1701172779 1186995772.802979000 1186995772.802999000
 0.000014000 0.000006000 0.000014000
     0 StatAddr       10.10.23.46 0 3967 106977 106977 0 0 44009 25892324
 1491312716
    11 SessionClose c Connection: close
    11 StatSess     c 10.10.23.46 55313 0 1 1 0 0 0 248 3797
    12 ReqStart     c 127.0.0.1 49985 1701172775
    12 RxRequest    c GET
    12 RxURL        c /thumbs/7238558.jpg
    12 RxProtocol   c HTTP/1.0
    12 RxHeader     c User-Agent: Wget/1.10.2
    12 RxHeader     c Accept: */*
    12 RxHeader     c Host: localhost:8080
    12 RxHeader     c Connection: Keep-Alive
    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
    17 BackendXID     1701172775
    12 Backend      c 17 default
    17 TxRequest      GET
    17 TxURL          /thumbs/7238558.jpg
    17 TxProtocol     HTTP/1.1
    17 TxHeader       User-Agent: Wget/1.10.2
    17 TxHeader       Accept: */*
    17 TxHeader       Host: localhost:8080
    17 TxHeader       X-Varnish: 1701172775
    17 TxHeader       X-Forwarded-for: 127.0.0.1
    17 RxProtocol     HTTP/1.1
    17 RxStatus       200
    17 RxResponse     OK
    17 RxHeader       Expires: Thu, 16 Aug 2007 09:02:52 GMT
    17 RxHeader       Content-Type: image/jpeg
    17 RxHeader       Content-Length: 1657
    17 RxHeader       Date: Mon, 13 Aug 2007 09:02:52 GMT
    17 RxHeader       Server: image_server
    12 ObjProtocol  c HTTP/1.1
    12 ObjStatus    c 200
    12 ObjResponse  c OK
    12 ObjHeader    c Expires: Thu, 16 Aug 2007 09:02:52 GMT
    12 ObjHeader    c Content-Type: image/jpeg
    12 ObjHeader    c Date: Mon, 13 Aug 2007 09:02:52 GMT
    12 ObjHeader    c Server: image_server
    12 ObjHeader    c Content-Length: 1657
    17 BackendReuse   default
    12 TTL          c 1701172775 RFC 259200 1186995772 1186995772
 1187254972 0 0
    12 VCL_call     c fetch
    12 VCL_return   c insert
    12 Length       c 1657
    12 TxProtocol   c HTTP/1.1
    12 TxStatus     c 200
    12 TxResponse   c OK
    12 TxHeader     c Expires: Thu, 16 Aug 2007 09:02:52 GMT
    12 TxHeader     c Content-Type: image/jpeg
    12 TxHeader     c Date: Mon, 13 Aug 2007 09:02:52 GMT
    12 TxHeader     c Server: image_server
    12 TxHeader     c Content-Length: 1657
    12 TxHeader     c X-Varnish: 1701172775
    12 TxHeader     c Age: 0
    12 TxHeader     c Via: 1.1 varnish
    12 ReqEnd       c 1701172775 1186995772.730346000 1186995772.807852000
 0.000095000 0.077485000 0.000021000
     0 StatAddr       127.0.0.1 0 3951 67 67 0 0 2 15430 12266767
    12 HttpError    c Received nothing
    12 SessionClose c no request
    12 StatSess     c 127.0.0.1 49985 0 1 1 0 0 1 215 1657
    11 SessionOpen  c 10.10.23.46 55322
    12 SessionOpen  c 10.10.23.46 55345
    12 ReqStart     c 10.10.23.46 55345 1701172781
    12 RxRequest    c GET
    12 RxURL        c /thumbs/1417983.jpg
    12 RxProtocol   c HTTP/1.0
    12 RxHeader     c Host: mogile
    12 RxHeader     c Connection: close
    12 VCL_call     c recv
    12 VCL_return   c lookup
    12 VCL_call     c hash
    12 VCL_return   c hash
    12 Hit          c 1701077986
    12 VCL_call     c hit
    12 VCL_return   c deliver
    12 Length       c 2371
    12 TxProtocol   c HTTP/1.1
    12 TxStatus     c 200
    12 TxResponse   c OK
    12 TxHeader     c Expires: Thu, 16 Aug 2007 08:04:17 GMT
    12 TxHeader     c Content-Type: image/jpeg
    12 TxHeader     c Date: Mon, 13 Aug 2007 08:04:17 GMT
    12 TxHeader     c Server: image_server
    12 TxHeader     c Content-Length: 2371
    12 TxHeader     c X-Varnish: 1701172781 1701077986
    12 TxHeader     c Age: 3515
    12 TxHeader     c Via: 1.1 varnish
    12 TxHeader     c Connection: close
    12 ReqEnd       c 1701172781 1186995772.846569000 1186995772.846619000
 0.002383000 0.000019000 0.000031000
     0 StatAddr       10.10.23.46 0 3967 106978 106978 0 0 44009 25892572
 1491315087
    12 SessionClose c Connection: close
    12 StatSess     c 10.10.23.46 55345 0 1 1 0 0 0 248 2371
    12 SessionOpen  c 10.10.23.46 55348
    13 SessionOpen  c 10.10.23.46 55358
    13 ReqStart     c 10.10.23.46 55358 1701172783
 }}}



 {{{
 root at ubuntu60664m:/tmp# telnet localhost 8080
 Trying 127.0.0.1...
 Connected to localhost.
 Escape character is '^]'.
 PURGE http://localhost:8080/thumbs/7238558.jpg HTTP/1.1

 HTTP/1.1 404 Not Found
 Date: Mon, 13 Aug 2007 09:06:02 GMT
 Server: Varnish
 Connection: close
 Content-Type: text/html; charset=iso-8859-1

 <!DOCTYPE HTML PUBLIC "-//IETF//DTD HTML 2.0//EN">
 <HTML>
   <HEAD>
     <TITLE>404 Not Found</TITLE>
   </HEAD>
   <BODY>
     <H1>Error 404 Not Found</H1>
     <P>Not in cache.</P>
     <H3>Guru Meditation:</H3>
     <P>XID: 1701177511</P>
     <I><A href="http://www.varnish-cache.org/">Varnish</A></I>
   </BODY>
 </HTML>
 Connection closed by foreign host.
 root at ubuntu60664m:/tmp#

 }}}

 results in this log messages


 {{{
  12 SessionClose c Connection: close
    12 StatSess     c 10.10.23.46 45497 0 1 1 0 0 0 248 2150
    11 VCL_acl      c MATCH purge "127.0.0.1"
    11 SessionClose c Not in cache.
    11 ReqStart     c 127.0.0.1 52982 1701177511
    11 RxRequest    c PURGE
    11 RxURL        c http://localhost:8080/thumbs/7238558.jpg
    11 RxProtocol   c HTTP/1.1
    11 VCL_call     c recv
    11 VCL_return   c lookup
    11 VCL_call     c hash
    11 VCL_return   c hash
    11 VCL_call     c miss
     0 Debug          "VCL_error(404, Not in cache.)"
    11 VCL_return   c error
    11 TxStatus     c 404
    11 TxProtocol   c HTTP/1.1
    11 TxResponse   c Not Found
    11 ReqEnd       c 1701177511 1186995962.226952000 1186995962.227023000
 1.916852000 0.000014000 0.000057000
     0 StatAddr       127.0.0.1 0 4141 72 72 0 0 2 16826 13010107
    11 StatSess     c 127.0.0.1 52982 2 1 1 0 0 0 472 0
    11 SessionOpen  c 10.10.23.46 45504
    11 ReqStart     c 10.10.23.46 45504 1701177512
 }}}

 and finally this call


 {{{
  telnet localhost 8080
 Trying 127.0.0.1...
 Connected to localhost.
 Escape character is '^]'.
 PURGE /thumbs/7238558.jpg HTTP/1.1

 HTTP/1.1 404 Not Found
 Date: Mon, 13 Aug 2007 09:06:31 GMT
 Server: Varnish
 Connection: close
 Content-Type: text/html; charset=iso-8859-1

 <!DOCTYPE HTML PUBLIC "-//IETF//DTD HTML 2.0//EN">
 <HTML>
   <HEAD>
     <TITLE>404 Not Found</TITLE>
   </HEAD>
   <BODY>
     <H1>Error 404 Not Found</H1>
     <P>Not in cache.</P>
     <H3>Guru Meditation:</H3>
     <P>XID: 1701178248</P>
     <I><A href="http://www.varnish-cache.org/">Varnish</A></I>
   </BODY>
 </HTML>
 Connection closed by foreign host.

 }}}

 results in this

 {{{
     0 StatAddr       10.10.23.46 0 4214 113159 113159 0 0 45798 27400262
 1584580150
    17 SessionClose c Connection: close
    17 StatSess     c 10.10.23.46 64445 0 1 1 0 0 0 248 2550
    11 VCL_acl      c MATCH purge "127.0.0.1"
    11 SessionClose c Not in cache.
    11 ReqStart     c 127.0.0.1 53801 1701178972
    11 RxRequest    c PURGE
    11 RxURL        c /thumbs/7238558.jpg
    11 RxProtocol   c HTTP/1.1
    11 VCL_call     c recv
    11 VCL_return   c lookup
    11 VCL_call     c hash
    11 VCL_return   c hash
    11 VCL_call     c miss
     0 Debug          "VCL_error(404, Not in cache.)"
    11 VCL_return   c error
    11 TxStatus     c 404
    11 TxProtocol   c HTTP/1.1
    11 TxResponse   c Not Found
    11 ReqEnd       c 1701178972 1186996019.737754000 1186996019.737835000
 0.598959000 0.000017000 0.000064000
     0 StatAddr       127.0.0.1 0 4198 78 76 0 0 2 18470 13011764
    11 StatSess     c 127.0.0.1 53801 0 1 1 0 0 0 472 0
    11 SessionOpen  c 10.10.23.46 64454
    11 ReqStart     c 10.10.23.46 64454 1701178973
    11 RxRequest    c GET
 }}}

-- 
Ticket URL: <http://varnish.projects.linpro.no/ticket/149#comment:4>
Varnish <http://varnish.projects.linpro.no/>
The Varnish HTTP Accelerator


More information about the varnish-bugs mailing list