[Varnish] #415: Varnish Hangs
Varnish
varnish-bugs at projects.linpro.no
Wed Jan 21 09:38:09 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 kvaade):
We experience the same kind of problem with version 2.0.2...
Our vcl.conf is very simple:
#
# This is a basic VCL configuration file for varnish. See the vcl(7)
# man page for details on VCL syntax and semantics.
#
# $Id: vcl.conf 1200 2006-10-19 09:21:42Z des $
#
backend default {
set backend.host = "127.0.0.1";
set backend.port = "90";
}
sub vcl_recv {
# pass mode can't handle POST (yet)
if (req.request == "POST") {
pipe;
}
# force lookup even when cookies are present
if (req.request == "GET" && req.http.cookie) {
lookup;
}
}
#sub vcl_fetch {
# if (req.url ~ "template") {
# set obj.ttl = 3s;
# } elseif (obj.ttl < 300s) {
# set obj.ttl = 300s;
# }
#}
sub vcl_fetch {
if (obj.ttl < 2s) {
set obj.ttl = 1s;
}
}
Varnishstat:
0+00:44:18
grandis09
Hitrate ratio: 1 1 1
Hitrate avg: 0.0306 0.0306 0.0306
45 0.00 0.02 Client connections accepted
116 0.00 0.04 Client requests received
3 0.00 0.00 Cache hits
0 0.00 0.00 Cache hits for pass
95 0.00 0.04 Cache misses
95 0.00 0.04 Backend connections success
0 0.00 0.00 Backend connections failures
0 0.00 0.00 Backend connections reuses
0 0.00 0.00 Backend connections recycles
8 0.00 0.00 Backend connections unused
2 . . N struct srcaddr
1 . . N active struct srcaddr
25 . . N struct sess_mem
25 . . N struct sess
30 . . N struct object
30 . . N struct objecthead
20 . . N struct smf
5 . . N small free smf
0 . . N large free smf
13 . . N struct vbe_conn
Varnishlog (at the start of the log everything is OK, at the lower part
Varnish hangs):
16 ReqStart c 194.19.36.2 34546 902563468
16 RxRequest c GET
16 RxURL c
/multimedia/dynamic/00069/14NYHHAguddingsmo002_69559p.jpg
16 RxProtocol c HTTP/1.1
16 RxHeader c Host: grandis09.adresseavisen.no
16 RxHeader c User-Agent: Mozilla/5.0 (Windows; U; Windows NT 5.1;
nb-N rv:1.9.0.5) Gecko/2008120122 Firefox/3.0.5
16 RxHeader c Accept: image/png,image/*;q=0.8,*/*;q=0.5
16 RxHeader c Accept-Language: nb,no;q=0.8,nn;q=0.6,en-
us;q=0.4,en;q=0.
16 RxHeader c Accept-Encoding: gzip,deflate
16 RxHeader c Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
16 RxHeader c Keep-Alive: 300
16 RxHeader c Connection: keep-alive
16 RxHeader c Referer: http://grandis09.adresseavisen.no/
16 RxHeader c Cookie:
__utma=112304850.2329896987502384600.1228385752.12459661.1232461691.23;
__utmz=112304850.1232456212.21.9.utmcsr=grandis09|utmcc(referral)|utmcmd=referral|utmcct=/;
JSESSIONID=abcj4fI_VAP_TbuzICZ7r; __utmc=2304850;
__utmb=112304850.17.10.1232
16 VCL_call c recv
16 VCL_return c lookup
16 VCL_call c hash
16 VCL_return c hash
16 VCL_call c miss
16 VCL_return c fetch
24 BackendOpen b default 127.0.0.1 44110 127.0.0.1 90
24 BackendXID b 902563468
16 Backend c 24 default
24 TxRequest b GET
24 TxURL b
/multimedia/dynamic/00069/14NYHHAguddingsmo002_69559p.jpg
24 TxProtocol b HTTP/1.1
24 TxHeader b Host: grandis09.adresseavisen.no
24 TxHeader b User-Agent: Mozilla/5.0 (Windows; U; Windows NT 5.1;
nb-N rv:1.9.0.5) Gecko/2008120122 Firefox/3.0.5
24 TxHeader b Accept: image/png,image/*;q=0.8,*/*;q=0.5
24 TxHeader b Accept-Language: nb,no;q=0.8,nn;q=0.6,en-
us;q=0.4,en;q=0.
24 TxHeader b Accept-Encoding: gzip,deflate
24 TxHeader b Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
24 TxHeader b Referer: http://grandis09.adresseavisen.no/
24 TxHeader b Cookie:
__utma=112304850.2329896987502384600.1228385752.12459661.1232461691.23;
__utmz=112304850.1232456212.21.9.utmcsr=grandis09|utmcc(referral)|utmcmd=referral|utmcct=/;
JSESSIONID=abcj4fI_VAP_TbuzICZ7r; __utmc=2304850;
__utmb=112304850.17.10.1232
24 TxHeader b X-Varnish: 902563468
24 TxHeader b X-Forwarded-for: 194.19.36.2
24 RxProtocol b HTTP/1.1
24 RxStatus b 200
24 RxResponse b OK
24 RxHeader b Date: Tue, 20 Jan 2009 14:30:28 GMT
24 RxHeader b Server: Resin/3.1.8
24 RxHeader b Content-Type: image/jpeg
24 RxHeader b Connection: close
24 RxHeader b Transfer-Encoding: chunked
16 ObjProtocol c HTTP/1.1
16 ObjStatus c 200
16 ObjResponse c OK
16 ObjHeader c Date: Tue, 20 Jan 2009 14:30:28 GMT
16 ObjHeader c Server: Resin/3.1.8
16 ObjHeader c Content-Type: image/jpeg
24 BackendClose b default
16 TTL c 902563468 RFC 120 1232461828 1232461828 0 0 0
16 VCL_call c fetch
16 VCL_return c insert
16 Length c 4299
16 VCL_call c deliver
16 VCL_return c deliver
16 TxProtocol c HTTP/1.1
16 TxStatus c 200
16 TxResponse c OK
16 TxHeader c Date: Tue, 20 Jan 2009 14:30:28 GMT
16 TxHeader c Server: Resin/3.1.8
16 TxHeader c Content-Type: image/jpeg
16 TxHeader c Content-Length: 4299
16 TxHeader c X-Varnish: 902563468
16 TxHeader c Age: 0
16 TxHeader c Via: 1.1 varnish
16 ReqEnd c 902563468 1232461828.629636049 1232461828.749588013
0.0566091 0.119915009 0.000036955
0 StatAddr 194.19.36.2 0 139 112 382 0 0 345 67281 6177207
28 SessionOpen c 194.19.36.2 34557
28 Debug c
"/multimedia/dynamic/00069/DSO_69532h.jpg#grandis09.adresavisen.no#"
37 ReqStart c 194.19.36.2 34552 902563475
37 RxRequest c GET
37 RxURL c
/multimedia/dynamic/00069/15NYHHAgjerstad013_3_69582d.jpg
37 RxProtocol c HTTP/1.1
37 RxHeader c Host: grandis09.adresseavisen.no
37 RxHeader c User-Agent: Mozilla/5.0 (Windows; U; Windows NT 5.1;
nb-N rv:1.9.0.5) Gecko/2008120122 Firefox/3.0.5
37 RxHeader c Accept: image/png,image/*;q=0.8,*/*;q=0.5
37 RxHeader c Accept-Language: nb,no;q=0.8,nn;q=0.6,en-
us;q=0.4,en;q=0.
37 RxHeader c Accept-Encoding: gzip,deflate
37 RxHeader c Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
37 RxHeader c Keep-Alive: 300
37 RxHeader c Connection: keep-alive
37 RxHeader c Referer: http://grandis09.adresseavisen.no/
37 RxHeader c Cookie:
__utma=112304850.2329896987502384600.1228385752.12459661.1232461691.23;
__utmz=112304850.1232456212.21.9.utmcsr=grandis09|utmcc(referral)|utmcmd=referral|utmcct=/;
JSESSIONID=abcj4fI_VAP_TbuzICZ7r; __utmc=2304850;
__utmb=112304850.17.10.1232
37 VCL_call c recv
37 VCL_return c lookup
37 VCL_call c hash
37 VCL_return c hash
37 VCL_call c miss
37 VCL_return c fetch
46 BackendOpen b default 127.0.0.1 44117 127.0.0.1 90
46 BackendXID b 902563475
37 Backend c 46 default
46 TxRequest b GET
46 TxURL b
/multimedia/dynamic/00069/15NYHHAgjerstad013_3_69582d.jpg
46 TxProtocol b HTTP/1.1
46 TxHeader b Host: grandis09.adresseavisen.no
46 TxHeader b User-Agent: Mozilla/5.0 (Windows; U; Windows NT 5.1;
nb-N rv:1.9.0.5) Gecko/2008120122 Firefox/3.0.5
46 TxHeader b Accept: image/png,image/*;q=0.8,*/*;q=0.5
46 TxHeader b Accept-Language: nb,no;q=0.8,nn;q=0.6,en-
us;q=0.4,en;q=0.
46 TxHeader b Accept-Encoding: gzip,deflate
46 TxHeader b Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
46 TxHeader b Referer: http://grandis09.adresseavisen.no/
46 TxHeader b Cookie:
__utma=112304850.2329896987502384600.1228385752.12459661.1232461691.23;
__utmz=112304850.1232456212.21.9.utmcsr=grandis09|utmcc(referral)|utmcmd=referral|utmcct=/;
JSESSIONID=abcj4fI_VAP_TbuzICZ7r; __utmc=2304850;
__utmb=112304850.17.10.1232
46 TxHeader b X-Varnish: 902563475
46 TxHeader b X-Forwarded-for: 194.19.36.2
46 RxProtocol b HTTP/1.1
46 RxStatus b 200
46 RxResponse b OK
46 RxHeader b Date: Tue, 20 Jan 2009 14:30:28 GMT
46 RxHeader b Server: Resin/3.1.8
46 RxHeader b Content-Type: image/jpeg
46 RxHeader b Connection: close
46 RxHeader b Transfer-Encoding: chunked
37 ObjProtocol c HTTP/1.1
37 ObjStatus c 200
37 ObjResponse c OK
37 ObjHeader c Date: Tue, 20 Jan 2009 14:30:28 GMT
37 ObjHeader c Server: Resin/3.1.8
37 ObjHeader c Content-Type: image/jpeg
46 BackendClose b default
37 TTL c 902563475 RFC 120 1232461828 1232461828 0 0 0
37 VCL_call c fetch
37 VCL_return c insert
37 Length c 13979
37 VCL_call c deliver
37 VCL_return c deliver
37 TxProtocol c HTTP/1.1
37 TxStatus c 200
37 TxResponse c OK
37 TxHeader c Date: Tue, 20 Jan 2009 14:30:28 GMT
37 TxHeader c Server: Resin/3.1.8
37 TxHeader c Content-Type: image/jpeg
37 TxHeader c Content-Length: 13979
37 TxHeader c X-Varnish: 902563475
37 TxHeader c Age: 0
37 TxHeader c Via: 1.1 varnish
37 ReqEnd c 902563475 1232461828.633925915 1232461828.750211954
0.0019804 0.116243124 0.000042915
0 StatAddr 194.19.36.2 0 139 113 383 0 0 346 67455 6191186
29 SessionOpen c 194.19.36.2 34558
29 Debug c
"/multimedia/dynamic/00069/090114_-_Bellman_69531i.jpg#grdis09.adresseavisen.no#"
19 Debug c
"/multimedia/dynamic/00068/pondus_front_68819h.jpg#grandi9.adresseavisen.no#"
23 Debug c
"/multimedia/dynamic/00069/splatt_69454i.jpg#grandis09.adsseavisen.no#"
0 WorkThread 0x44004cf0 start
22 Debug c
"/multimedia/dynamic/00069/090114_-_Midnight_Ch_69530i.jpgrandis09.adresseavisen.no#"
0 WorkThread 0x46008cf0 start
0 WorkThread 0x43002cf0 start
0 WorkThread 0x4a010cf0 start
16 Debug c "/multimedia/dynamic/00069/TS-20080317-NYHETER-
_69455h.jpgrandis09.adresseavisen.no#"
13 Debug c
"/multimedia/dynamic/00069/mosviksentrum_69449h.jpg#grand09.adresseavisen.no#"
37 Debug c
"/multimedia/dynamic/00069/31REPkjellrun01_69506h.jpg#grais09.adresseavisen.no#"
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1232461830
14 SessionClose c timeout
14 StatSess c 192.168.101.83 17635 nan 1 4 0 0 2 974 30246
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1232461833
0 WorkThread 0x4a811cf0 start
14 SessionOpen c 194.19.36.2 34833
14 Debug c "/i/t.gif#grandis09.adresseavisen.no#"
0 WorkThread 0x4b012cf0 start
53 SessionOpen c 194.19.36.2 34834
0 WorkThread 0x4b813cf0 start
56 SessionOpen c 194.19.36.2 34836
53 Debug c
"/multimedia/dynamic/00069/19KULmus_og_menn2_69604h.jpg#gndis09.adresseavisen.no#"
56 Debug c "/#grandis09.adresseavisen.no#"
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1232461836
0 WorkThread 0x4c014cf0 start
63 SessionOpen c 194.19.36.2 34910
63 Debug c
"/template/ver1-0/css/none#grandis09.adresseavisen.no#"
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1232461839
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1232461842
0 WorkThread 0x4c815cf0 start
70 SessionOpen c 194.19.36.2 35144
70 Debug c
"/nyheter/article60936.ece#grandis09.adresseavisen.no#"
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1232461845
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1232461848
0 WorkThread 0x4d016cf0 start
73 SessionOpen c 194.19.36.2 35264
73 Debug c
"/nyheter/article60943.ece#grandis09.adresseavisen.no#"
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1232461851
0 WorkThread 0x4d817cf0 start
76 SessionOpen c 194.19.36.2 35466
76 Debug c
"/nyheter/article60946.ece#grandis09.adresseavisen.no#"
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1232461855
0 CLI Rd ping
0 CLI Wr 0 200 PONG 1232461858
--
Ticket URL: <http://varnish.projects.linpro.no/ticket/415#comment:5>
Varnish <http://varnish.projects.linpro.no/>
The Varnish HTTP Accelerator
More information about the varnish-bugs
mailing list