9

How is a varnishhist histogram/graph read? I understand that the x-axis is a log scale.

Specifically:

  • I see 9 "|"s or cache hits occur around y=1e-4, what does each "|" refer to? Page? File?

  • I see fewer hits to the left of these 9 "|"s and some to the right. What are those? Did they take longer (right) and shorter (left) time ... ?

  • In the top, left what are 1:2? and n = 134

  • Also, I've noticed that the cache hits are always to the left of the cache misses, i.e., they are on the 1e-x side of things, while cache misses are under + exponent. What is the significance of that? Is that time, if so, time of what ... ?

Many Thanks!

x-axis

1:2, n = 134                                                              hostname








                            #
                            #
                            #
                            #
                            #
                   |        #
                   |        #
                   |        #
                   |        #
                   |       ###
                   |       ###
                  ||       ###                              ##
                  |||      ###         ## #   #             ##
                  ||||     ####      #### ## ##  #          ###
+---------+---------+---------+---------+---------+---------+---------+---------+---------
|1e-6     |1e-5     |1e-4     |1e-3     |1e-2     |1e-1     |1e0      |1e1  |1e2
KM.
  • 1,746
  • 2
  • 18
  • 31

2 Answers2

17
  • '|' is cache HIT
  • '#' is cache MISS
  • 'n:m' numbers in left top corner is vertical scale
  • 'n = 123' is number of requests that are being displayed
  • X-axis is logarithmic time between request request from kernel to Varnish and response from Varnish to kernel.

The X-axis works like this:

  • 1e1 = 10 sec
  • 1e0 = 1 sec
  • 1e-1 = 0.1 secs or 100 ms (milliseconds)
  • 1e-2 = 0.01 secs or 10 ms
  • 1e-3 = 0.001 secs or 1 ms or 1000 µs (microseconds)
  • 1e-4 = 0.0001 secs or 0.1 ms or 100 µs
  • 1e-5 = 0.00001 secs or 0.01 ms or 10 µs
  • 1e-6 = 0.000001 secs or 0.001 ms or 1 µs or 1000 ns (nanoseconds)

Whole article explaining 'varnishhist' very nicely can be found here: Varnishhist – What Does it Tell Us, and official doc here: Varnish request histogram.

Another useful command is 'varnishstat' - Varnish Cache statistics.

the
  • 468
  • 8
  • 23
David Lukac
  • 271
  • 2
  • 5
8

So the x-axis is the time it takes for the request to come into varnish and get sent back to the client. The |'s are the cache hits and the #'s are the misses. So you should see all the |'s on the left since that is a faster time.

So the left most numbers are faster.. the right most are slower..

now the 1:2 n= number.. Its numbers for the vertical scale and sample size. I wouldn't worry much about those.

But from that output.. you have a pretty fast cache going on.

Mike
  • 21,910
  • 7
  • 55
  • 79