Mailing List Archive: varnishlog and latencies

varnishlog and latencies

Oct 24, 2007, 5:53 AM

Post #1 of 3 (190 views)

Hello,
I'm testing varnish and seeing visible long latencies before
objects appear on the browser.
Looking at varnishlog i see for a long object to download :
12 ReqEnd c 894991605 1193228694.621880054 1193228694.6322798730.006652355 0.010343552 0.000056267

if i'm correct that means that it took 12 ms to deliver the object ?
my eyes say that it took longer than that.

Thanks,
PS: is there a doc about the varnish log output explaining the fields
for ReqEnd TTL etc,..

--

~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
Damien WETZEL (ATANAR TECHNOLOGIES) ("`-/")_.-'"``-._
http://www.atanar.com . . `; -._ )-;-,_`)
(v_,)' _ )`-.\ ``-'
Phone:+33 6 62 29 61 77 _.- _..-_/ / ((.'
- So much to do, so little time - ((,.-' ((,/
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~

varnishlog and latencies [ In reply to ]

phk at phk

Oct 24, 2007, 5:56 AM

Post #2 of 3 (189 views)

Permalink

In message <18207.16480.262863.257820 at localhost.localdomain>, Damien Wetzel wri
tes:
>Hello,
>I'm testing varnish and seeing visible long latencies before
>objects appear on the browser.
>Looking at varnishlog i see for a long object to download :
>12 ReqEnd c 894991605 1193228694.621880054 1193228694.6322798730.006652355 0.010343552 0.000056267
>
>if i'm correct that means that it took 12 ms to deliver the object ?
>my eyes say that it took longer than that.

Yes, something like that.

>Thanks,
>PS: is there a doc about the varnish log output explaining the fields
>for ReqEnd TTL etc,..

Not yet, unfortunately.

--
Poul-Henning Kamp | UNIX since Zilog Zeus 3.20
phk at FreeBSD.ORG | TCP/IP since RFC 956
FreeBSD committer | BSD since 4.3-tahoe
Never attribute to malice what can adequately be explained by incompetence.

varnishlog and latencies [ In reply to ]

dwetzel at nerim

Oct 24, 2007, 1:10 PM

Post #3 of 3 (185 views)

Permalink

thank you,
strange is it.

varnishncsa generates lines with ip= 127.0.0.1, i suppose
this represents the requests to the backends ?

I think it could be interresting to arrange varnishncsa to add
at least two fields that would be
1) if the request was a cache or misss
2) the duration of the delivering
it would ease the debugging
is that planned sometimes ?

Damien,

Poul-Henning Kamp writes:
> In message <18207.16480.262863.257820 at localhost.localdomain>, Damien Wetzel wri
> tes:
> >Hello,
> >I'm testing varnish and seeing visible long latencies before
> >objects appear on the browser.
> >Looking at varnishlog i see for a long object to download :
> >12 ReqEnd c 894991605 1193228694.621880054 1193228694.6322798730.006652355 0.010343552 0.000056267
> >
> >if i'm correct that means that it took 12 ms to deliver the object ?
> >my eyes say that it took longer than that.
>
> Yes, something like that.
>
> >Thanks,
> >PS: is there a doc about the varnish log output explaining the fields
> >for ReqEnd TTL etc,..
>
> Not yet, unfortunately.
>
> --
> Poul-Henning Kamp | UNIX since Zilog Zeus 3.20
> phk at FreeBSD.ORG | TCP/IP since RFC 956
> FreeBSD committer | BSD since 4.3-tahoe
> Never attribute to malice what can adequately be explained by incompetence.