hc-httpclient-users mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Oleg Kalnichevski <ol...@apache.org>
Subject RE: strange timeout behaviour
Date Fri, 30 Nov 2007 09:31:17 GMT

On Thu, 2007-11-29 at 15:14 +0100, Valentijn Scholten wrote:
> >Valentijn,
> >
> >Please post a wire/context log of the session
> >
> >http://jakarta.apache.org/httpcomponents/httpclient-3.x/logging.html
> >
> >Oleg
> Oleg,
> 
> Thanks, good idea. They're rather long though.
> 
> Log without timeouts set:
> 

...

> The only difference are these lines:
> 
> Integer connectionTimeout = 30000;
> pm.getParams().setParameter("http.socket.timeout", connectionTimeout);
> pm.getParams().setParameter("http.connection.timeout",
> connectionTimeout);
> httpClient.getParams().setParameter("http.socket.timeout",
> connectionTimeout);
> httpClient.getParams().setParameter("http.connection.timeout",
> connectionTimeout);
> 				
> They're commented out in log 1 above.
> 
> According to the log, just the sending of the requestentity takes
> longer? Trace level doesn't give me any more hints.
> 
> Little extra thing I found out. With timeout the processing time will be
> around 6000-7000 always, no matter what timeout is used. Withouth
> timeouts it's always about 1000-2000ms.
> 
> Thanks for the help!
> 

Valentijn,

I can tell you roughly where the extra 5 seconds are spent but I cannot
give you a rational explanation why that happens.

In the first case the request body gets sent almost instantaneously

15:00:54:520 CET [DEBUG] header - >> "Host: 193.173.142.184[\r][\n]"
15:00:54:520 CET [DEBUG] header - >> "Content-Length: 1137[\r][\n]"
15:00:54:520 CET [DEBUG] header - >> "[\r][\n]"
15:00:54:879 CET [DEBUG] EntityEnclosingMethod - Request body sent
 
In the second case for some reason it takes almost 5 secs to send the
body

15:03:28:944 CET [DEBUG] header - >> "Host: 193.173.142.184[\r][\n]"
15:03:28:944 CET [DEBUG] header - >> "Content-Length: 1137[\r][\n]"
15:03:28:944 CET [DEBUG] header - >> "[\r][\n]"
15:03:33:835 CET [DEBUG] EntityEnclosingMethod - Request body sent

The only thing I can think of is running HttpClient under a profiler in
order to find out where exactly those 5 seconds are spent.

Sorry.

Oleg 



---------------------------------------------------------------------
To unsubscribe, e-mail: httpclient-user-unsubscribe@jakarta.apache.org
For additional commands, e-mail: httpclient-user-help@jakarta.apache.org


Mime
View raw message