[Bug 60699] New: Negative response time in an Apache HTTPD access log

classic Classic list List threaded Threaded
5 messages Options
Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

[Bug 60699] New: Negative response time in an Apache HTTPD access log

Bugzilla from bugzilla@apache.org
https://bz.apache.org/bugzilla/show_bug.cgi?id=60699

            Bug ID: 60699
           Summary: Negative response time in an Apache HTTPD access log
           Product: Apache httpd-2
           Version: 2.4.18
          Hardware: PC
                OS: Linux
            Status: NEW
          Severity: normal
          Priority: P2
         Component: Core
          Assignee: [hidden email]
          Reporter: [hidden email]
  Target Milestone: ---

So below is a log line that was recorded recently by our Apache that serves a
proxy for Tomcat. The third number is %D ("The time taken to serve the request,
in microseconds").

123.123.123.123 0 -656931 [01/Feb/2017:08:33:04 +0100] "GET
/api/reader?_dc=1485934382427&page=1&start=0&limit=25&sort=lastName&filter=...
HTTP/1.1" 200 681 "Mozilla/5.0 (Windows NT 6.3) AppleWebKit/537.36 (KHTML, like
Gecko) Chrome/38.0.2125.111 Safari/537.36"

There is another one from a different browser:

123.123.123.124 0 -192246 - - [01/Feb/2017:07:59:27 +0100] "GET /auth/...
HTTP/2" 302 - "Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36
(KHTML, like Gecko) Chrome/55.0.2883.87 Safari/537.36"

Similar time (date), but communication is to different servers (our proxy
connects with AJP to several Tomcat servers).

P.S.: Obviously I modified IP in the log, I also removed parts of URL.

--
You are receiving this mail because:
You are the assignee for the bug.
---------------------------------------------------------------------
To unsubscribe, e-mail: [hidden email]
For additional commands, e-mail: [hidden email]

Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

[Bug 60699] Negative response time in an Apache HTTPD access log

Bugzilla from bugzilla@apache.org
https://bz.apache.org/bugzilla/show_bug.cgi?id=60699

Szőgyényi Gábor <[hidden email]> changed:

           What    |Removed                     |Added
----------------------------------------------------------------------------
                 CC|                            |[hidden email]

--
You are receiving this mail because:
You are the assignee for the bug.
---------------------------------------------------------------------
To unsubscribe, e-mail: [hidden email]
For additional commands, e-mail: [hidden email]

Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

[Bug 60699] Negative response time in an Apache HTTPD access log

Bugzilla from bugzilla@apache.org
In reply to this post by Bugzilla from bugzilla@apache.org
https://bz.apache.org/bugzilla/show_bug.cgi?id=60699

--- Comment #1 from Luca Toscano <[hidden email]> ---
Hi Maciej,

does it happens all the times for a particular request or once in a while? Have
you found a way to reliable reproduce the issue?

Thanks!

--
You are receiving this mail because:
You are the assignee for the bug.
---------------------------------------------------------------------
To unsubscribe, e-mail: [hidden email]
For additional commands, e-mail: [hidden email]

Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

[Bug 60699] Negative response time in an Apache HTTPD access log

Bugzilla from bugzilla@apache.org
In reply to this post by Bugzilla from bugzilla@apache.org
https://bz.apache.org/bugzilla/show_bug.cgi?id=60699

--- Comment #2 from Maciej Jaros <[hidden email]> ---
No, unfortunately I don't know how to reproduce this. It just happens from time
to time and is rare.

I'm guessing this happens when the request is not completed and is in some
weird state. But that's a pure guess. Would explain why it is rare thought.

--
You are receiving this mail because:
You are the assignee for the bug.
---------------------------------------------------------------------
To unsubscribe, e-mail: [hidden email]
For additional commands, e-mail: [hidden email]

Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

[Bug 60699] Negative response time in an Apache HTTPD access log

Bugzilla from bugzilla@apache.org
In reply to this post by Bugzilla from bugzilla@apache.org
https://bz.apache.org/bugzilla/show_bug.cgi?id=60699

--- Comment #3 from Luca Toscano <[hidden email]> ---
Is there anything relevant in the error log around the time of the log with
negative %D? Can you add the full log format that you are using? I'd like to
check what is the "0" value right after the IP address.

As far as I can see the issue seems present for both HTTP/2 and HTTP1.1, can
you confirm ?

--
You are receiving this mail because:
You are the assignee for the bug.
---------------------------------------------------------------------
To unsubscribe, e-mail: [hidden email]
For additional commands, e-mail: [hidden email]

Loading...