Log slow backend requests. VSL-query?

[Geoff Simmons]

On 03/21/2017 07:11 PM, Danila Vershinin wrote:
> 
> Trying to log only the slow backend requests:
> 
> varnishncsa -b -q 'Timestamp:Process[2] > 0.1’

Timestamp:Process only appears in the client-side logs, but with -b
you're filtering for the backend logs.

The "Backend fetch timestamps" section at the bottom of vsl(7) tells
you the names of the timestamps for backends:

http://www.varnish-cache.org/docs/5.1/reference/vsl.html#backend-fetch-timestamps

The one you're probably looking for is Timestamp:Beresp[3]. Beresp is
the timestamp set after receiving backend response headers (the first
timestamp recorded after the backend starts sending a response). Field
3 is the time elapsed since the most recent timestamp, which would be
Bereq (backend request sent), so this is the best measurement of how
fast or slow a backend responds after receiving a request.

One thing to watch out for: if there is a fetch error such as a
timeout that results in no backend response being received at all,
then Timestamp:Beresp isn't recorded in the log. Instead of that you
get Timestamp:Error, so you might want to query for Timestamp:Error[3]
as well.


HTH,
Geoff
-- 
** * * UPLEX - Nils Goroll Systemoptimierung

Scheffelstraße 32
22301 Hamburg

Tel +49 40 2880 5731
Mob +49 176 636 90917
Fax +49 40 42949753

http://uplex.de

-------------- next part --------------
A non-text attachment was scrubbed...
Name: signature.asc
Type: application/pgp-signature
Size: 819 bytes
Desc: OpenPGP digital signature
URL: <https://www.varnish-cache.org/lists/pipermail/varnish-misc/attachments/20170321/98aa0a6a/attachment.pgp>