Can someone please clarify for me
At what exact stage during accepting and responding to an HTTP request
does ZPE write to it's access.log?
When the request is initially received and before it is processed?
When ZPE has finished creating the response and serves the completed
page out?
I'm trying to determine if apparently poor server performance is caused
by a slow web proxy on our network.

Thu, Nov 24, 2005 at 05:15:27PM +0100, Andreas Jung wrote:
24. November 2005 16:06:19 +0000 Eddie Butcher
<ebutcher (AT) roadrunner (DOT) uk.comwrote:
>
>When the request is initially received and before it is processed?

How should that work when the logfile contains the size of the data sent
back to the client?

>
>When ZPE has finished creating the response and serves the completed
>page out?

See above. The answer should be clear.

In case it's not: access log is written to when the response
is sent back.

If you need a more granular log of what happens when,
the trace log can sometimes be useful.
Uncomment this in your zope.conf:

# <logger trace>
# level WARN
# <logfile>
# path $INSTANCE/log/trace.log
# format %(message)s
# </logfile>
# </logger>

There is a script for analyzing its output in your
zope software installation at bin/requestprofiler.py ,
but I have also found it useful to eyeball the log
from time to time.

Paul,
If you need a more granular log of what happens when,
the trace log can sometimes be useful.
Thanks both of you, maybe that's what I'm actually after.

Thu, Nov 24, 2005 at 06:37:36PM +0100, Andreas Jung wrote:
24. November 2005 11:55:25 -0500 Paul Winkler <pw_lists (AT) slinkp (DOT) com
wrote:
>In case it's not: access log is written to when the response
>is sent back.

Which is basically close to the end of the request but never before
processing :-)

I'm actually not sure I know what you mean by "processing".

Thu, Nov 24, 2005 at 07:02:51PM +0100, Tino Wildenhain wrote:
Am Donnerstag, den 24.11.2005, 12:54 -0500 schrieb Paul Winkler:
Thu, Nov 24, 2005 at 06:37:36PM +0100, Andreas Jung wrote:
24. November 2005 11:55:25 -0500 Paul Winkler <pw_lists (AT) slinkp (DOT) com
wrote:
>In case it's not: access log is written to when the response
>is sent back.

Which is basically close to the end of the request but never before
processing :-)

I'm actually not sure I know what you mean by "processing".

All you control from within your Zope-App is processing.
of the processing phase is ZPublisher, which
also handles the logging.

Ah, yes of course. Somehow I mentally read "after" where Andreas wrote
"before", so I was a bit puzzled for no good reason :)