varnish time it takes for a request

[Dridi Boukelmoune]

On Mon, Nov 7, 2016 at 6:04 PM, Admin Beckspaced <admin at beckspaced.com> wrote:
> hello again dear varnish community,
>
> if i look at the varnishlog I see lots of timing information, e.g.
>
> -   Timestamp      Start: 1478537654.083406 0.000000 0.000000
> ...
> -   Timestamp      Req: 1478537654.083406 0.000000 0.000000
> ...
> -   Timestamp      Process: 1478537654.083552 0.000146 0.000146
> -   Timestamp      Resp: 1478537654.149267 0.065862 0.065716
> -   ReqAcct        456 0 456 297 117701 117998
>
> what would be the best way to find out how long a single request took in
> total?
> start from the client request until the delivery is done.
>
> how would i find that one out?

See `man vsl`, the second decimal field is the "Time since start of
work unit", so in your case it should be 0.065862 seconds or almost
66ms from the last Timestamp record. But there are other details to
consider, like buffering in your TCP stack, or apparently the lack of
Timestamp record for the response body.

Cheers,
Dridi