https://issues.apache.org/bugzilla/show_bug.cgi?id=54044
--- Comment #2 from Casey Lucas <clu...@e-miles.com> ---
I restarted earlier today with an updated pattern trying to get some more
useful information. The format was not the one you recommended (since it was
before your request) but I added:
%I - thread - in case the problem is thread related
%t
%{sec}t
For a final pattern of:
%{X-Tomcat-Session}i,%S,%{X-Forwarded-For}i,%h,%u,%I,%t,%{sec}t,%{begin:yyyy-MM-dd
HH:mm:ss.SSSZ}t,"%r",%s,%b,%p,%{Referer}i,"%{User-Agent}i",%T
The actual time of capture was 23/Oct/2012:14:49:59. I left on the %T data
which is small. These were not long duration requests. Note the 11:18:18
entries. 11:18:18 is a few seconds after the jvm was started. I thought this
might be relavent because of the child/parent caching logic in AccessLogValve.
... ,tomcat-exec-20,[23/Oct/2012:14:49:59 -0500],1351021799,2012-10-23
14:49:59.341-0500,"GET ... ,0.000
... ,tomcat-exec-5,[23/Oct/2012:14:49:59 -0500],1351021799,2012-10-23
14:49:59.337-0500,"GET ... ,0.005
... ,tomcat-exec-18,[23/Oct/2012:14:49:59 -0500],1351021799,2012-10-23
14:49:58.833-0500,"GET ... ,0.590
... ,tomcat-exec-20,[23/Oct/2012:14:49:59 -0500],1351021799,2012-10-23
14:49:59.424-0500,"GET ... ,0.002
... ,tomcat-exec-5,[23/Oct/2012:14:49:59 -0500],1351021799,2012-10-23
14:49:59.424-0500,"GET ... ,0.009
... ,tomcat-exec-18,[23/Oct/2012:14:49:59 -0500],1351021799,2012-10-23
11:18:18.433-0500,"GET ... ,0.001
... ,tomcat-exec-20,[23/Oct/2012:14:49:59 -0500],1351021799,2012-10-23
14:49:59.431-0500,"GET ... ,0.008
... ,tomcat-exec-25,[23/Oct/2012:14:49:59 -0500],1351021799,2012-10-23
11:18:18.444-0500,"GET ... ,0.007
... ,tomcat-exec-25,[23/Oct/2012:14:49:59 -0500],1351021799,2012-10-23
11:18:18.545-0500,"GET ... ,0.007
... ,tomcat-exec-25,[23/Oct/2012:14:49:59 -0500],1351021799,2012-10-23
11:18:18.555-0500,"GET ... ,0.009
... ,tomcat-exec-25,[23/Oct/2012:14:49:59 -0500],1351021799,2012-10-23
11:18:18.645-0500,"GET ... ,0.004
... ,tomcat-exec-18,[23/Oct/2012:14:49:59 -0500],1351021799,2012-10-23
11:18:18.645-0500,"GET ... ,0.006
... ,tomcat-exec-25,[23/Oct/2012:14:49:59 -0500],1351021799,2012-10-23
11:18:18.654-0500,"GET ... ,0.001
... ,tomcat-exec-18,[23/Oct/2012:14:49:59 -0500],1351021799,2012-10-23
11:18:18.653-0500,"GET ... ,0.012
... ,tomcat-exec-25,[23/Oct/2012:14:49:59 -0500],1351021799,2012-10-23
11:18:18.665-0500,"GET ... ,0.012
... ,tomcat-exec-25,[23/Oct/2012:14:49:59 -0500],1351021799,2012-10-23
11:18:18.765-0500,"GET ... ,0.006
... ,tomcat-exec-25,[23/Oct/2012:14:49:59 -0500],1351021799,2012-10-23
11:18:18.855-0500,"GET ... ,0.006
... ,tomcat-exec-25,[23/Oct/2012:14:49:59 -0500],1351021799,2012-10-23
11:18:18.865-0500,"GET ... ,0.008
... ,tomcat-exec-25,[23/Oct/2012:14:49:59 -0500],1351021799,2012-10-23
11:18:18.885-0500,"GET ... ,0.012
... ,tomcat-exec-25,[23/Oct/2012:14:49:59 -0500],1351021799,2012-10-23
11:18:18.901-0500,"GET ... ,0.007
... ,tomcat-exec-25,[23/Oct/2012:14:49:59 -0500],1351021799,2012-10-23
11:18:18.911-0500,"GET ... ,0.010
... ,tomcat-exec-25,[23/Oct/2012:14:49:59 -0500],1351021799,2012-10-23
11:18:18.925-0500,"GET ... ,0.010
... ,tomcat-exec-25,[23/Oct/2012:14:50:00 -0500],1351021800,2012-10-23
11:18:18.976-0500,"GET ... ,0.082
... ,tomcat-exec-5,[23/Oct/2012:14:50:00 -0500],1351021800,2012-10-23
14:50:00.075-0500,"GET ... ,0.010
... ,tomcat-exec-5,[23/Oct/2012:14:50:00 -0500],1351021800,2012-10-23
14:50:00.146-0500,"GET ... ,0.011
... ,tomcat-exec-5,[23/Oct/2012:14:50:00 -0500],1351021800,2012-10-23
14:50:00.161-0500,"GET ... ,0.012
I can update the pattern to be the one you requested but it may take some time
to see the issue crop up again. In the mean time the above data may be useful.
--
You are receiving this mail because:
You are the assignee for the bug.
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@tomcat.apache.org
For additional commands, e-mail: dev-h...@tomcat.apache.org
