[Varnish] #415: Varnish Hangs

Varnish varnish-bugs at projects.linpro.no
Fri Jan 16 13:47:04 CET 2009


#415: Varnish Hangs
--------------------+-------------------------------------------------------
 Reporter:  plfgoa  |        Owner:       
     Type:  defect  |       Status:  new  
 Priority:  high    |    Milestone:       
Component:  build   |      Version:  trunk
 Severity:  major   |   Resolution:       
 Keywords:          |  
--------------------+-------------------------------------------------------
Comment (by noah):

 I've recently tried to upgrade from 2.0-beta2 to 2.0.2 and run into this
 problem aswell.

 Symptoms:
  * Varnish does not accept() any new connections
  * Backend works OK!
  * CPU usage drops to zero

 There's nothing special in the VCL and it's basically made up of two
 modified sub routines:
  vcl_recv()
    ^-- remove and set a few header and checks and ACL, and the vc
  vcl_fetch()
    ^-- if(obj.status == 200) { set obj.ttl = time depending on req.url }


 Here's output from Varnishstat.
 {{{
        18167         0.00        19.64 Client connections accepted
       184164         0.00       199.10 Client requests received
       170510         0.00       184.34 Cache hits
            1         0.00         0.00 Cache hits for pass
         6493         0.00         7.02 Cache misses
        13654         0.00        14.76 Backend connections success
            0         0.00         0.00 Backend connections not attempted
            0         0.00         0.00 Backend connections too many
            0         0.00         0.00 Backend connections failures
        11972         0.00        12.94 Backend connections reuses
        13355         0.00        14.44 Backend connections recycles
            0         0.00         0.00 Backend connections unused
          953          .            .   N struct srcaddr
            4          .            .   N active struct srcaddr
         1011          .            .   N struct sess_mem
            6          .            .   N struct sess
         2313          .            .   N struct object
         2278          .            .   N struct objecthead
         5252          .            .   N struct smf
          620          .            .   N small free smf
           37          .            .   N large free smf
            1          .            .   N struct vbe_conn
           10          .            .   N struct bereq
           32          .            .   N worker threads
           34         0.00         0.04 N worker threads created
            0         0.00         0.00 N worker threads not created
            0         0.00         0.00 N worker threads limited
            0         0.00         0.00 N queued work requests
          175         0.00         0.19 N overflowed work requests
                          0.00         0.00 N dropped work requests
 }}}

 Here's some lines from varnishlog before killing varnishd:
 {{{
    0 Debug        - "Attempt Prefetch 662879512"
     0 ExpPick      - 662870028 ttl
     0 VCL_call     - timeout
     0 VCL_return   - discard
     0 ExpKill      - 662870028 -10
     0 ExpPick      - 662870039 ttl
     0 VCL_call     - timeout
     0 VCL_return   - discard
     0 ExpKill      - 662870039 -10
     0 ExpPick      - 662870042 ttl
     0 VCL_call     - timeout
     0 VCL_return   - discard
     0 ExpKill      - 662870042 -10
     0 ExpPick      - 662870053 ttl
     0 VCL_call     - timeout
     0 VCL_return   - discard
     0 ExpKill      - 662870053 -10
     0 ExpPick      - 662870209 ttl
     0 VCL_call     - timeout
     0 VCL_return   - discard
     0 ExpKill      - 662870209 -10
     0 ExpPick      - 662870214 ttl
     0 VCL_call     - timeout
     0 VCL_return   - discard
     0 ExpKill      - 662870214 -10
     0 ExpPick      - 662870215 ttl
     0 VCL_call     - timeout
     0 VCL_return   - discard
     0 ExpKill      - 662870215 -10
     0 ExpPick      - 662870220 ttl
     0 VCL_call     - timeout
     0 VCL_return   - discard
     0 ExpKill      - 662870220 -10
     0 ExpPick      - 662870226 ttl
     0 VCL_call     - timeout
     0 VCL_return   - discard
     0 ExpKill      - 662870226 -10
     0 ExpPick      - 662870227 ttl
     0 VCL_call     - timeout
     0 VCL_return   - discard
     0 ExpKill      - 662870227 -10
     0 ExpPick      - 662870234 ttl
     0 VCL_call     - timeout
     0 VCL_return   - discard
     0 ExpKill      - 662870234 -10
     0 ExpPick      - 662870235 ttl
     0 VCL_call     - timeout
     0 VCL_return   - discard
     0 ExpKill      - 662870235 -10
     0 ExpPick      - 662870240 ttl
     0 VCL_call     - timeout
     0 VCL_return   - discard
     0 ExpKill      - 662870240 -10
     0 ExpPick      - 662870241 ttl
     0 VCL_call     - timeout
     0 VCL_return   - discard
     0 ExpKill      - 662870241 -10
     0 ExpPick      - 662870245 ttl
     0 VCL_call     - timeout
     0 VCL_return   - discard
     0 ExpKill      - 662870245 -10
     0 ExpPick      - 662870246 ttl
     0 VCL_call     - timeout
     0 VCL_return   - discard
     0 ExpKill      - 662870246 -10
     0 ExpPick      - 662870250 ttl
     0 VCL_call     - timeout
     0 VCL_return   - discard
     0 ExpKill      - 662870250 -10
     0 ExpPick      - 662870251 ttl
     0 VCL_call     - timeout
     0 VCL_return   - discard
     0 ExpKill      - 662870251 -10
     0 ExpPick      - 662870252 ttl
     0 VCL_call     - timeout
     0 VCL_return   - discard
     0 ExpKill      - 662870252 -10
     0 ExpPick      - 662870256 ttl
     0 VCL_call     - timeout
     0 VCL_return   - discard
     0 ExpKill      - 662870256 -10
     0 ExpPick      - 662870257 ttl
     0 VCL_call     - timeout
     0 VCL_return   - discard
     0 ExpKill      - 662870257 -10
     0 ExpPick      - 662870262 ttl
     0 VCL_call     - timeout
     0 VCL_return   - discard
     0 ExpKill      - 662870262 -10
     0 ExpPick      - 662870263 ttl
     0 VCL_call     - timeout
     0 VCL_return   - discard
     0 ExpKill      - 662870263 -10
     0 ExpPick      - 662870267 ttl
     0 VCL_call     - timeout
     0 VCL_return   - discard
     0 ExpKill      - 662870267 -10
     0 ExpPick      - 662870268 ttl
     0 VCL_call     - timeout
     0 VCL_return   - discard
     0 ExpKill      - 662870268 -10
     0 ExpPick      - 662870275 ttl
     0 VCL_call     - timeout
     0 VCL_return   - discard
     0 ExpKill      - 662870275 -10
     0 ExpPick      - 662870276 ttl
     0 VCL_call     - timeout
     0 VCL_return   - discard
     0 ExpKill      - 662870276 -10
     0 ExpPick      - 662880125 prefetch
     0 VCL_call     - prefetch
     0 VCL_return   - fetch
     0 Debug        - "Attempt Prefetch 662880125"
     0 ExpPick      - 662880218 prefetch
     0 VCL_call     - prefetch
     0 VCL_return   - fetch
     0 Debug        - "Attempt Prefetch 662880218"
     0 CLI          - Rd ping
     0 CLI          - Wr 0 200 PONG 1232109460 1.0
 }}}

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


More information about the varnish-bugs mailing list