we are trying to consume a web service using axis2 (1.5.1) client. We are getting so many read timed out. Socket time-out to the server is more than 5 minutes, and it is timing out after that period only.
One thing I am not very clear about is log (httpclient logs in debug mode) -------------(EntityEnclosingMethod.java)(writeRequestBody)(508)--Request body sent This line above has come after 3 read timed out.....and after this response was received. Why this line was not visible during the read timed out exception.....does this means request wasn't sent successfully? Is this because server socket is too busy to accept new data or this means some client side code/connection handling not proper. Below are detailed logs. Key doubt is on "Request body sent". (2010-05-04 08:01:10,427)(pool-4-thread-476)(DEBUG)(DefaultHttpParams.java)(setParameter)(151)--Set parameter http.connection.timeout = 301000 (2010-05-04 08:01:10,428)(pool-4-thread-476)(DEBUG)(DefaultHttpParams.java)(setParameter)(151)--Set parameter http.socket.timeout = 301000 (2010-05-04 08:01:10,428)(pool-4-thread-476)(DEBUG)(DefaultHttpParams.java)(setParameter)(151)--Set parameter http.socket.timeout = 301000 (2010-05-04 08:01:10,428)(pool-4-thread-476)(DEBUG)(DefaultHttpParams.java)(setParameter)(151)--Set parameter http.protocol.version = HTTP/1.0 (2010-05-04 08:01:10,428)(pool-4-thread-476)(DEBUG)(DefaultHttpParams.java)(setParameter)(151)--Set parameter http.authentication.preemptive = true (2010-05-04 08:01:10,428)(pool-4-thread-476)(DEBUG)(DefaultHttpParams.java)(setParameter)(151)--Set parameter http.auth.scheme-priority = [Basic] (2010-05-04 08:01:10,429)(pool-4-thread-476)(DEBUG)(MultiThreadedHttpConnectionManager.java)(getConnectionWithTimeout)(412)--HttpConnectionManager.getConnection: config = HostConfiguration[host=https://www.irctc.biz], timeout = 0 (2010-05-04 08:01:10,429)(pool-4-thread-476)(DEBUG)(MultiThreadedHttpConnectionManager.java)(createConnection)(760)--Allocating new connection, hostConfig=HostConfiguration[host=https://www.irctc.biz] (2010-05-04 08:01:10,429)(pool-4-thread-476)(DEBUG)(HttpMethodDirector.java)(executeMethod)(161)--Preemptively sending default basic credentials (2010-05-04 08:01:10,429)(pool-4-thread-476)(DEBUG)(HttpMethodDirector.java)(authenticateHost)(278)--Authenticating with BASIC <any realm>@www.irctc.biz:443 (2010-05-04 08:01:10,429)(pool-4-thread-476)(DEBUG)(HttpMethodParams.java)(getCredentialCharset)(384)--Credential charset not configured, using HTTP element charset (2010-05-04 08:01:10,429)(pool-4-thread-476)(DEBUG)(HttpConnection.java)(open)(692)--Open connection to www.irctc.biz:443 (2010-05-04 08:01:10,483)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)-->> "POST /ws2.0/services/IRCTCBookingService HTTP/1.0[\r][\n]" (2010-05-04 08:01:10,484)(pool-4-thread-476)(DEBUG)(HttpMethodBase.java)(addHostRequestHeader)(1352)--Adding Host request header (2010-05-04 08:01:10,484)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)-->> "Content-Type: text/xml; charset=UTF-8[\r][\n]" (2010-05-04 08:01:10,484)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)-->> "SOAPAction: ""[\r][\n]" (2010-05-04 08:01:10,484)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)-->> "User-Agent: Axis2[\r][\n]" (2010-05-04 08:01:10,485)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)-->> "Content-Encoding: gzip[\r][\n]" (2010-05-04 08:01:10,485)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)-->> "Authorization: BasicSOMEJUNKHERE[\r][\n]" (2010-05-04 08:01:10,485)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)-->> "Host: www.irctc.biz[\r][\n]" (2010-05-04 08:01:10,485)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)-->> "Content-Length: 1744[\r][\n]" (2010-05-04 08:01:10,485)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)-->> "[\r][\n]" (2010-05-04 08:06:11,485)(pool-4-thread-476)(DEBUG)(HttpMethodDirector.java)(executeWithRetry)(404)--Closing the connection. (2010-05-04 08:06:44,316)(pool-4-thread-476)(INFO)(HttpMethodDirector.java)(executeWithRetry)(439)--I/O exception (org.apache.axis2.AxisFault) caught when processing request: Read timed out (2010-05-04 08:06:44,316)(pool-4-thread-476)(DEBUG)(HttpMethodDirector.java)(executeWithRetry)(443)--Read timed out (2010-05-04 08:06:44,316)(pool-4-thread-476)(INFO)(HttpMethodDirector.java)(executeWithRetry)(445)--Retrying request (2010-05-04 08:06:44,316)(pool-4-thread-476)(DEBUG)(HttpConnection.java)(open)(692)--Open connection to www.irctc.biz:443 (2010-05-04 08:06:44,398)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)-->> "POST /ws2.0/services/IRCTCBookingService HTTP/1.0[\r][\n]" (2010-05-04 08:06:44,399)(pool-4-thread-476)(DEBUG)(HttpMethodBase.java)(addHostRequestHeader)(1352)--Adding Host request header (2010-05-04 08:06:44,399)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)-->> "Content-Type: text/xml; charset=UTF-8[\r][\n]" (2010-05-04 08:06:44,399)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)-->> "SOAPAction: ""[\r][\n]" (2010-05-04 08:06:44,399)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)-->> "User-Agent: Axis2[\r][\n]" (2010-05-04 08:06:44,399)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)-->> "Content-Encoding: gzip[\r][\n]" (2010-05-04 08:06:44,399)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)-->> "Authorization: Basic SOMEJUNKHERE[\r][\n]" (2010-05-04 08:06:44,400)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)-->> "Content-Length: 1744[\r][\n]" (2010-05-04 08:06:44,400)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)-->> "Host: www.irctc.biz[\r][\n]" (2010-05-04 08:06:44,400)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)-->> "[\r][\n]" (2010-05-04 08:11:45,400)(pool-4-thread-476)(DEBUG)(HttpMethodDirector.java)(executeWithRetry)(404)--Closing the connection. (2010-05-04 08:12:09,948)(pool-4-thread-476)(INFO)(HttpMethodDirector.java)(executeWithRetry)(439)--I/O exception (org.apache.axis2.AxisFault) caught when processing request: Read timed out (2010-05-04 08:12:09,949)(pool-4-thread-476)(DEBUG)(HttpMethodDirector.java)(executeWithRetry)(443)--Read timed out (2010-05-04 08:12:09,949)(pool-4-thread-476)(INFO)(HttpMethodDirector.java)(executeWithRetry)(445)--Retrying request (2010-05-04 08:12:09,949)(pool-4-thread-476)(DEBUG)(HttpConnection.java)(open)(692)--Open connection to www.irctc.biz:443 (2010-05-04 08:12:10,029)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)-->> "POST /ws2.0/services/IRCTCBookingService HTTP/1.0[\r][\n]" (2010-05-04 08:12:10,029)(pool-4-thread-476)(DEBUG)(HttpMethodBase.java)(addHostRequestHeader)(1352)--Adding Host request header (2010-05-04 08:12:10,029)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)-->> "Content-Type: text/xml; charset=UTF-8[\r][\n]" (2010-05-04 08:12:10,029)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)-->> "SOAPAction: ""[\r][\n]" (2010-05-04 08:12:10,030)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)-->> "User-Agent: Axis2[\r][\n]" (2010-05-04 08:12:10,030)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)-->> "Content-Encoding: gzip[\r][\n]" (2010-05-04 08:12:10,030)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)-->> "Authorization: Basic SOMEJUNKHERE[\r][\n]" (2010-05-04 08:12:10,030)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)-->> "Content-Length: 1744[\r][\n]" (2010-05-04 08:12:10,030)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)-->> "Host: www.irctc.biz[\r][\n]" (2010-05-04 08:12:10,030)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)-->> "[\r][\n]" (2010-05-04 08:15:07,213)(pool-4-thread-476)(DEBUG)(EntityEnclosingMethod.java)(writeRequestBody)(508)--Request body sent-----after this response was received------- (2010-05-04 08:17:39,910)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)--<< "HTTP/1.1 200 OK[\r][\n]" (2010-05-04 08:17:39,910)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)--<< "HTTP/1.1 200 OK[\r][\n]" (2010-05-04 08:17:39,910)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)--<< "Date: Tue, 04 May 2010 02:44:59 GMT[\r][\n]" (2010-05-04 08:17:39,910)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)--<< "Date: Tue, 04 May 2010 02:44:59 GMT[\r][\n]" (2010-05-04 08:17:39,910)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)--<< "Server: Apache/2.2.13 (Win32) mod_ssl/2.2.13 OpenSSL/0.9.8k mod_jk/1.2.27[\r][\n]" (2010-05-04 08:17:39,911)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)--<< "Content-Length: 1660[\r][\n]" (2010-05-04 08:17:39,911)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)--<< "Connection: close[\r][\n]" (2010-05-04 08:17:39,911)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)--<< "Content-Type: text/xml;charset=utf-8[\r][\n]" (2010-05-04 08:17:39,911)(pool-4-thread-476)(DEBUG)(Wire.java)(wire)(70)--<< "[\r][\n]" (2010-05-04 08:17:39,913)(pool-4-thread-476)(DEBUG)(HttpMethodBase.java)(shouldCloseConnection)(1008)--Should close connection in response to directive: close (2010-05-04 08:17:39,913)(pool-4-thread-476)(DEBUG)(HttpConnection.java)(releaseConnection)(1178)--Releasing connection back to connection manager. (2010-05-04 08:17:39,913)(pool-4-thread-476)(DEBUG)(MultiThreadedHttpConnectionManager.java)(freeConnection)(979)--Freeing connection, hostConfig=HostConfiguration[host=https://www.irctc.biz] -- View this message in context: http://old.nabble.com/Server-clogged-up-or-httpclient-issue---tp28443129p28443129.html Sent from the Axis - User mailing list archive at Nabble.com. --------------------------------------------------------------------- To unsubscribe, e-mail: java-user-unsubscr...@axis.apache.org For additional commands, e-mail: java-user-h...@axis.apache.org
