Reg: exact field for response time

[Hardik]

Hi Geoff & Team,

Please help with understanding.

Thank you
Hardik

On Tue, 19 Mar 2019 at 11:48, Hardik <hetardik.p at gmail.com> wrote:

> This line is creating confusion for me..
>
> https://github.com/jib/libvmod-timers/blob/master/src/vmod_timers.c
> The sixth and last number (0.000025988) is the time from we start
> delivering the object until the request completes.
>
> Thanks
> Hardik
>
> On Mon, 18 Mar 2019 at 21:20, Hardik <hetardik.p at gmail.com> wrote:
>
>> Hi Geoff,
>>
>> Thanks for reply. I understood meaning of the fields in varnish 6.
>>
>> One answer I got that we should consider 0.048125 as total response time.
>> But my actual question is different.
>> I am not getting difference between varnish 3 and varnish 6.
>>
>> Exact question if I ask..
>>
>> *varnish 3*
>> ReqEnd c 877345549 1233949945.075706005 1233949945.075754881 0.017112017
>> 0.000022888 *0.000025988*
>>
>> *varnish 6.1*
>> Timestamp Resp: 1501601912.806787* 0.048125* *0.000037*
>>
>> *0.000025988 (varnish 3) is equivalent to 0.048125 or 0.000037 (varnish
>> 6) ?*
>>
>> we have upgraded from 3 to 6 so not able to decide exact field which I
>> was reading in case of varnish 3.
>>
>> *Thank you*
>> *Hardik*
>>
>>
>>
>> On Mon, 18 Mar 2019 at 16:52, Geoff Simmons <geoff at uplex.de> wrote:
>>
>>> On 3/18/19 12:09, Hardik wrote:
>>> >
>>> > *varnish 6.1*
>>> > Timestamp Resp: 1501601912.806787 0.048125 *0.000037*
>>> >
>>> >
>>> https://feryn.eu/blog/varnishlog-measure-varnish-cache-performance/#The_Timestamp_tag
>>> >
>>> >
>>> > -> Are both fields give same values, means same meaning or both are
>>> > different? In varnish 3 I was reading last field. And as per
>>> explanation
>>> > its time from delivery to end of request ( and not total time from
>>> start of
>>> > request till end, plz correct if wrong). Based on this explanation I am
>>> > reading resp time only and no total time in resp tag. Do I have to read
>>> > 0.048125 to be compatible with varnish 3 or I am doing correct ?
>>>
>>> man vsl(7) is your friend here:
>>>
>>>               The format is:
>>>
>>>                  %s: %f %f %f
>>>                  |   |  |  |
>>>                  |   |  |  +- Time since last timestamp
>>>                  |   |  +---- Time since start of work unit
>>>                  |   +------- Absolute time of event
>>>                  +----------- Event label
>>>
>>> So the second number is the time since request processing began, and the
>>> third number is the time since the previous timestamp.
>>>
>>> Timestamp:Resp usually comes after Timestamp:Process:
>>>
>>>        Process
>>>               Processing finished, ready to deliver the client response.
>>>
>>>        Resp   Delivery of response to the client finished.
>>>
>>> So in your example, it's 48.125ms since the start of request processing,
>>> and 37µs since the previous timestamp, usually meaning 37µs for response
>>> delivery.
>>>
>>> The second field of Timestamp:Resp (48ms here) is a good measure of the
>>> total time to process the request.
>>>
>>>
>>> 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 --------------
An HTML attachment was scrubbed...
URL: <https://www.varnish-cache.org/lists/pipermail/varnish-misc/attachments/20190319/9599cc66/attachment.html>