Aiming for Varnish 2.0

Wichert Akkerman wichert at wiggy.net
Fri Jun 6 13:20:11 CEST 2008


Poul-Henning Kamp wrote:
> We are putting Varnish 2.0 right in the cross-hairs now, and that means
> that I'll be trying to close as many outstanding issues and tickets as
> possible.
>
> I will strongly urge you all to run -trunk and help flush out the
> remaining bugs.
>    

We had a varnish trunk instance stop working today. All backend servers 
were running correctly but requests going through varnish hung. The 
varnishlog output is below. After restarting the varnish daemon 
everything worked normally again.

    54 SessionOpen  c 10.121.10.125 54873
    54 HttpError    c Received nothing
    54 SessionClose c silent
    54 ReqEnd       c 0 1212750905.378312826 1212750905.378312826 
0.000177622 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83586 82633 0 0 0 0 0 0
    54 StatSess     c 10.121.10.125 54873 0 1 0 0 0 0 0 0
    54 SessionOpen  c 10.121.10.125 54875
    54 HttpError    c Received nothing
    54 SessionClose c silent
    54 ReqEnd       c 0 1212750906.390101671 1212750906.390101671 
0.000184059 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83587 82634 0 0 0 0 0 0
    54 StatSess     c 10.121.10.125 54875 0 1 0 0 0 0 0 0
    54 SessionOpen  c 10.121.10.125 54877
    54 HttpError    c Received nothing
    54 SessionClose c silent
    54 ReqEnd       c 0 1212750907.398921728 1212750907.398921728 
0.000149965 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83588 82635 0 0 0 0 0 0
    54 StatSess     c 10.121.10.125 54877 0 1 0 0 0 0 0 0
     0 CLI            Rd ping
     0 CLI            Wr 0 200 PONG 1212750907
    54 SessionOpen  c 10.121.10.125 54879
    54 HttpError    c Received nothing
    54 SessionClose c silent
    54 ReqEnd       c 0 1212750908.408715248 1212750908.408715248 
0.000169516 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83589 82636 0 0 0 0 0 0
    54 StatSess     c 10.121.10.125 54879 0 1 0 0 0 0 0 0
    54 SessionOpen  c 10.121.10.125 54881
    54 HttpError    c Received nothing
    54 SessionClose c silent
    54 ReqEnd       c 0 1212750909.418306112 1212750909.418306112 
0.000190735 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83591 82637 0 0 0 0 0 0
    54 StatSess     c 10.121.10.125 54881 0 1 0 0 0 0 0 0
    54 SessionOpen  c 10.121.10.125 54883
    54 HttpError    c Received nothing
    54 SessionClose c silent
    54 ReqEnd       c 0 1212750910.429055929 1212750910.429055929 
0.000201941 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83592 82638 0 0 0 0 0 0
    54 StatSess     c 10.121.10.125 54883 0 1 0 0 0 0 0 0
     0 CLI            Rd ping
     0 CLI            Wr 0 200 PONG 1212750910
    54 SessionOpen  c 10.121.10.125 54885
    54 HttpError    c Received nothing
    54 SessionClose c silent
    54 ReqEnd       c 0 1212750911.438663244 1212750911.438663244 
0.000112534 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83593 82639 0 0 0 0 0 0
    54 StatSess     c 10.121.10.125 54885 0 1 0 0 0 0 0 0
    54 SessionOpen  c 10.121.10.125 54887
    54 HttpError    c Received nothing
    54 SessionClose c silent
    54 ReqEnd       c 0 1212750912.448537588 1212750912.448537588 
0.000156164 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83594 82640 0 0 0 0 0 0
    54 StatSess     c 10.121.10.125 54887 0 1 0 0 0 0 0 0
     0 ExpKill        456634866 0
    54 SessionOpen  c 10.121.10.125 54889
    54 HttpError    c Received nothing
    54 SessionClose c silent
    54 ReqEnd       c 0 1212750913.458305836 1212750913.458305836 
0.000211954 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83595 82641 0 0 0 0 0 0
    54 StatSess     c 10.121.10.125 54889 0 1 0 0 0 0 0 0
     0 CLI            Rd ping
     0 CLI            Wr 0 200 PONG 1212750913
    54 SessionOpen  c 10.121.10.125 54891
    54 HttpError    c Received nothing
    54 SessionClose c silent
    54 ReqEnd       c 0 1212750914.468340158 1212750914.468340158 
0.000185251 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83596 82642 0 0 0 0 0 0
    54 StatSess     c 10.121.10.125 54891 0 1 0 0 0 0 0 0
    54 SessionOpen  c 10.121.10.125 54893
    54 HttpError    c Received nothing
    54 SessionClose c silent
    54 ReqEnd       c 0 1212750915.479013205 1212750915.479013205 
0.000136852 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83597 82643 0 0 0 0 0 0
    54 StatSess     c 10.121.10.125 54893 0 1 0 0 0 0 0 0
    54 SessionOpen  c 10.121.10.125 54895
    54 HttpError    c Received nothing
    54 SessionClose c silent
    54 ReqEnd       c 0 1212750916.488635540 1212750916.488635540 
0.000167847 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83598 82644 0 0 0 0 0 0
    54 StatSess     c 10.121.10.125 54895 0 1 0 0 0 0 0 0
     0 CLI            Rd ping
     0 CLI            Wr 0 200 PONG 1212750916
    54 SessionOpen  c 10.121.10.125 54897
    54 HttpError    c Received nothing
    54 SessionClose c silent
    54 ReqEnd       c 0 1212750917.498711586 1212750917.498711586 
0.000220776 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83599 82645 0 0 0 0 0 0
    54 StatSess     c 10.121.10.125 54897 0 1 0 0 0 0 0 0
    54 SessionOpen  c 10.121.10.125 54899
    54 HttpError    c Received nothing
    54 SessionClose c silent
    54 ReqEnd       c 0 1212750918.508799791 1212750918.508799791 
0.000160933 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83600 82646 0 0 0 0 0 0
    54 StatSess     c 10.121.10.125 54899 0 1 0 0 0 0 0 0
    54 SessionOpen  c 10.121.10.125 54901
    54 HttpError    c Received nothing
    54 SessionClose c silent
    54 ReqEnd       c 0 1212750919.518652439 1212750919.518652439 
0.000189543 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83601 82647 0 0 0 0 0 0
    54 StatSess     c 10.121.10.125 54901 0 1 0 0 0 0 0 0
     0 CLI            Rd ping
     0 CLI            Wr 0 200 PONG 1212750919
    54 SessionOpen  c 10.121.10.125 54903
    54 HttpError    c Received nothing
    54 SessionClose c silent
    54 ReqEnd       c 0 1212750920.528769732 1212750920.528769732 
0.000162125 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83602 82648 0 0 0 0 0 0
    54 StatSess     c 10.121.10.125 54903 0 1 0 0 0 0 0 0
    54 SessionOpen  c 10.121.10.125 54905
    54 HttpError    c Received nothing
    54 SessionClose c silent
    54 ReqEnd       c 0 1212750921.538681984 1212750921.538681984 
0.000258446 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83603 82649 0 0 0 0 0 0
    54 StatSess     c 10.121.10.125 54905 0 1 0 0 0 0 0 0
    54 SessionOpen  c 10.121.10.84 41839
    54 ReqStart     c 10.121.10.84 41839 456634871
    54 RxRequest    c GET
    54 RxURL        c /
    54 RxProtocol   c HTTP/1.0
    54 RxHeader     c User-Agent: w3m/0.5.1+cvs-1.946
    54 RxHeader     c Accept: text/*, image/*, audio/*, application/*
    54 RxHeader     c Accept-Encoding: gzip, compress, bzip, bzip2, deflate
    54 RxHeader     c Accept-Language: en;q=1.0
    54 RxHeader     c Host: plone.customer.int
    54 VCL_call     c recv
    54 VCL_return   c lookup
    54 VCL_call     c hash
    54 VCL_return   c hash
    54 Debug        c "on waiting list on obj 456633699"
     0 StatAddr       10.121.10.84 0 nan 22 20 0 0 2 2397 16350
    55 SessionOpen  c 10.121.10.125 54907
    55 HttpError    c Received nothing
    55 SessionClose c silent
    55 ReqEnd       c 0 1212750922.548995256 1212750922.548995256 
0.000127077 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83604 82650 0 0 0 0 0 0
    55 StatSess     c 10.121.10.125 54907 0 1 0 0 0 0 0 0
     0 CLI            Rd ping
     0 CLI            Wr 0 200 PONG 1212750922
    55 SessionOpen  c 10.121.10.125 54909
    55 HttpError    c Received nothing
    55 SessionClose c silent
    55 ReqEnd       c 0 1212750923.558841467 1212750923.558841467 
0.000233412 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83605 82651 0 0 0 0 0 0
    55 StatSess     c 10.121.10.125 54909 0 1 0 0 0 0 0 0
    55 SessionOpen  c 10.121.10.125 54911
    55 HttpError    c Received nothing
    55 SessionClose c silent
    55 ReqEnd       c 0 1212750924.568663836 1212750924.568663836 
0.000154972 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83606 82652 0 0 0 0 0 0
    55 StatSess     c 10.121.10.125 54911 0 1 0 0 0 0 0 0
     0 CLI            Rd ping
     0 CLI            Wr 0 200 PONG 1212750925
    55 SessionOpen  c 10.121.10.125 54913
    55 HttpError    c Received nothing
    55 SessionClose c silent
    55 ReqEnd       c 0 1212750925.578656435 1212750925.578656435 
0.000115395 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83607 82653 0 0 0 0 0 0
    55 StatSess     c 10.121.10.125 54913 0 1 0 0 0 0 0 0
    55 SessionOpen  c 10.121.10.125 54915
    55 HttpError    c Received nothing
    55 SessionClose c silent
    55 ReqEnd       c 0 1212750926.588635683 1212750926.588635683 
0.000172138 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83608 82654 0 0 0 0 0 0
    55 StatSess     c 10.121.10.125 54915 0 1 0 0 0 0 0 0
    55 SessionOpen  c 10.121.10.125 54917
    55 HttpError    c Received nothing
    55 SessionClose c silent
    55 ReqEnd       c 0 1212750927.599018574 1212750927.599018574 
0.000161171 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83609 82655 0 0 0 0 0 0
    55 StatSess     c 10.121.10.125 54917 0 1 0 0 0 0 0 0
     0 CLI            Rd ping
     0 CLI            Wr 0 200 PONG 1212750928
    55 SessionOpen  c 10.121.10.125 54919
    55 HttpError    c Received nothing
    55 SessionClose c silent
    55 ReqEnd       c 0 1212750928.609158754 1212750928.609158754 
0.000053167 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83610 82656 0 0 0 0 0 0
    55 StatSess     c 10.121.10.125 54919 0 1 0 0 0 0 0 0
    55 SessionOpen  c 10.121.10.125 54921
    55 HttpError    c Received nothing
    55 SessionClose c silent
    55 ReqEnd       c 0 1212750929.620168686 1212750929.620168686 
0.000217199 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83611 82657 0 0 0 0 0 0
    55 StatSess     c 10.121.10.125 54921 0 1 0 0 0 0 0 0
    55 SessionOpen  c 10.121.10.125 54923
    55 HttpError    c Received nothing
    55 SessionClose c silent
    55 ReqEnd       c 0 1212750930.629410744 1212750930.629410744 
0.000182867 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83612 82658 0 0 0 0 0 0
    55 StatSess     c 10.121.10.125 54923 0 1 0 0 0 0 0 0
     0 CLI            Rd ping
     0 CLI            Wr 0 200 PONG 1212750931
    55 SessionOpen  c 10.121.10.125 54925
    55 HttpError    c Received nothing
    55 SessionClose c silent
    55 ReqEnd       c 0 1212750931.639029741 1212750931.639029741 
0.000146389 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83613 82659 0 0 0 0 0 0
    55 StatSess     c 10.121.10.125 54925 0 1 0 0 0 0 0 0
    55 SessionOpen  c 10.121.10.125 54927
    55 HttpError    c Received nothing
    55 SessionClose c silent
    55 ReqEnd       c 0 1212750932.649048805 1212750932.649048805 
0.000166893 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83614 82660 0 0 0 0 0 0
    55 StatSess     c 10.121.10.125 54927 0 1 0 0 0 0 0 0
    55 SessionOpen  c 10.121.10.125 54929
    55 HttpError    c Received nothing
    55 SessionClose c silent
    55 ReqEnd       c 0 1212750933.659260511 1212750933.659260511 
0.000162840 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83615 82661 0 0 0 0 0 0
    55 StatSess     c 10.121.10.125 54929 0 1 0 0 0 0 0 0
     0 CLI            Rd ping
     0 CLI            Wr 0 200 PONG 1212750934
    55 SessionOpen  c 10.121.10.125 54931
    55 HttpError    c Received nothing
    55 SessionClose c silent
    55 ReqEnd       c 0 1212750934.671282768 1212750934.671282768 
0.000159502 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83616 82662 0 0 0 0 0 0
    55 StatSess     c 10.121.10.125 54931 0 1 0 0 0 0 0 0
    55 SessionOpen  c 10.121.10.125 54933
    55 HttpError    c Received nothing
    55 SessionClose c silent
    55 ReqEnd       c 0 1212750935.678945065 1212750935.678945065 
0.000104427 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83617 82663 0 0 0 0 0 0
    55 StatSess     c 10.121.10.125 54933 0 1 0 0 0 0 0 0
    55 SessionOpen  c 10.121.10.125 54935
    55 HttpError    c Received nothing
    55 SessionClose c silent
    55 ReqEnd       c 0 1212750936.689173222 1212750936.689173222 
0.000151396 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83618 82664 0 0 0 0 0 0
    55 StatSess     c 10.121.10.125 54935 0 1 0 0 0 0 0 0
     0 CLI            Rd ping
     0 CLI            Wr 0 200 PONG 1212750937
    55 SessionOpen  c 10.121.10.125 54937
    55 HttpError    c Received nothing
    55 SessionClose c silent
    55 ReqEnd       c 0 1212750937.699398041 1212750937.699398041 
0.000175238 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83619 82665 0 0 0 0 0 0
    55 StatSess     c 10.121.10.125 54937 0 1 0 0 0 0 0 0
    55 SessionOpen  c 10.121.10.125 54939
    55 HttpError    c Received nothing
    55 SessionClose c silent
    55 ReqEnd       c 0 1212750938.709848642 1212750938.709848642 
0.000188351 0.000000000 0.000000000
     0 StatAddr       10.121.10.125 0 83620 82666 0 0 0 0 0 0
    55 StatSess     c 10.121.10.125 54939 0 1 0 0 0 0 0 0


-- 
Wichert Akkerman<wichert at wiggy.net>    It is simple to make things.
http://www.wiggy.net/                  It is hard to make things simple.




More information about the varnish-misc mailing list