hc-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "chowdareddy somu (JIRA)" <j...@apache.org>
Subject [jira] Commented: (HTTPCLIENT-1012) ThreadSafeClientConnManager Connection pool leak issue
Date Wed, 13 Oct 2010 19:37:35 GMT

    [ https://issues.apache.org/jira/browse/HTTPCLIENT-1012?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=12920729#action_12920729 ] 

chowdareddy somu commented on HTTPCLIENT-1012:
----------------------------------------------

Hi Oleg,

Thanks for you reply.. we are releasing the connection in our application when ever any exception happens by calling consumeContent() or abort() method.. though i'm suspecting that when there is any exception happens connection is not  getting to back to pool.. 

My question is there if any socket connection exception happens while executing the Http Request then the  response object is null so there is nothing to consume the content, what happens in this case.. will that connection is going back to the pool? or it just expires? If I close the  expiry connection using the closeExpiredConnections()  method , will it create new connection for that??

And I have small test program where I can get socket connection exception(by giving Socket timeout low).. here is there debug info... I don't understand why it says, connection Closed, connection shut down and released connection is not reusable?

2010/10/13 15:22:38:484 EDT [DEBUG] DefaultClientConnection - Connection closed
2010/10/13 15:22:38:484 EDT [DEBUG] DefaultClientConnection - Connection closed
2010/10/13 15:22:38:484 EDT [DEBUG] DefaultClientConnection - Connection shut down
2010/10/13 15:22:38:484 EDT [DEBUG] ThreadSafeClientConnManager - Released connection is not reusable.



2010/10/13 15:22:38:109 EDT [DEBUG] ThreadSafeClientConnManager - Get connection: HttpRoute[{}->http://nightlyservices.aa1.pqe:80], timeout = 0
2010/10/13 15:22:38:109 EDT [DEBUG] ThreadSafeClientConnManager - Get connection: HttpRoute[{}->http://nightlyservices.aa1.pqe:80], timeout = 0
2010/10/13 15:22:38:109 EDT [DEBUG] ThreadSafeClientConnManager - Get connection: HttpRoute[{}->http://nightlyservices.aa1.pqe:80], timeout = 0
2010/10/13 15:22:38:109 EDT [DEBUG] ThreadSafeClientConnManager - Get connection: HttpRoute[{}->http://nightlyservices.aa1.pqe:80], timeout = 0
2010/10/13 15:22:38:109 EDT [DEBUG] ThreadSafeClientConnManager - Get connection: HttpRoute[{}->http://nightlyservices.aa1.pqe:80], timeout = 0
2010/10/13 15:22:38:109 EDT [DEBUG] ThreadSafeClientConnManager - Get connection: HttpRoute[{}->http://nightlyservices.aa1.pqe:80], timeout = 0
2010/10/13 15:22:38:109 EDT [DEBUG] ThreadSafeClientConnManager - Get connection: HttpRoute[{}->http://nightlyservices.aa1.pqe:80], timeout = 0
2010/10/13 15:22:38:109 EDT [DEBUG] ThreadSafeClientConnManager - Get connection: HttpRoute[{}->http://nightlyservices.aa1.pqe:80], timeout = 0
2010/10/13 15:22:38:109 EDT [DEBUG] ThreadSafeClientConnManager - Get connection: HttpRoute[{}->http://nightlyservices.aa1.pqe:80], timeout = 0
2010/10/13 15:22:38:109 EDT [DEBUG] ThreadSafeClientConnManager - Get connection: HttpRoute[{}->http://nightlyservices.aa1.pqe:80], timeout = 0
2010/10/13 15:22:38:125 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 0, allocated: 0 out of 5
2010/10/13 15:22:38:125 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:125 EDT [DEBUG] ConnPoolByRoute - Available capacity: 5 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:125 EDT [DEBUG] ConnPoolByRoute - Creating new connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 1, allocated: 1 out of 5
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - Available capacity: 4 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - Creating new connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 2, allocated: 2 out of 5
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - Available capacity: 3 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - Creating new connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 3, allocated: 3 out of 5
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - Available capacity: 2 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - Creating new connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 4, allocated: 4 out of 5
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - Available capacity: 1 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - Creating new connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 5, allocated: 5 out of 5
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - Available capacity: 0 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - Need to wait for connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 5, allocated: 5 out of 5
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - Available capacity: 0 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - Need to wait for connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 5, allocated: 5 out of 5
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - Available capacity: 0 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - Need to wait for connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 5, allocated: 5 out of 5
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - Available capacity: 0 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - Need to wait for connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 5, allocated: 5 out of 5
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - Available capacity: 0 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:140 EDT [DEBUG] ConnPoolByRoute - Need to wait for connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:203 EDT [DEBUG] DefaultClientConnection - Sending request: GET /documentfrosting/object/9984462 HTTP/1.1
2010/10/13 15:22:38:203 EDT [DEBUG] DefaultClientConnection - Sending request: GET /documentfrosting/object/9984467 HTTP/1.1
2010/10/13 15:22:38:203 EDT [DEBUG] DefaultClientConnection - Sending request: GET /documentfrosting/object/9984469 HTTP/1.1
2010/10/13 15:22:38:203 EDT [DEBUG] DefaultClientConnection - Sending request: GET /documentfrosting/object/9984468 HTTP/1.1
2010/10/13 15:22:38:203 EDT [DEBUG] DefaultClientConnection - Sending request: GET /documentfrosting/object/9984466 HTTP/1.1
2010/10/13 15:22:38:250 EDT [DEBUG] DefaultClientConnection - Receiving response: HTTP/1.1 200 The request has succeeded
2010/10/13 15:22:38:265 EDT [DEBUG] DefaultClientConnection - Receiving response: HTTP/1.1 200 The request has succeeded
2010/10/13 15:22:38:265 EDT [DEBUG] DefaultClientConnection - Receiving response: HTTP/1.1 200 The request has succeeded
2010/10/13 15:22:38:265 EDT [DEBUG] DefaultClientConnection - Connection shut down
2010/10/13 15:22:38:265 EDT [DEBUG] ThreadSafeClientConnManager - Released connection is not reusable.
2010/10/13 15:22:38:265 EDT [DEBUG] DefaultClientConnection - Connection shut down
2010/10/13 15:22:38:265 EDT [DEBUG] ThreadSafeClientConnManager - Released connection is not reusable.
2010/10/13 15:22:38:265 EDT [DEBUG] ConnPoolByRoute - Releasing connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:265 EDT [DEBUG] DefaultClientConnection - Connection shut down
2010/10/13 15:22:38:265 EDT [DEBUG] ConnPoolByRoute - Releasing connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:265 EDT [DEBUG] ThreadSafeClientConnManager - Released connection is not reusable.
2010/10/13 15:22:38:265 EDT [DEBUG] ConnPoolByRoute - Notifying thread waiting on pool [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
2010/10/13 15:22:38:265 EDT [DEBUG] ConnPoolByRoute - Releasing connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:265 EDT [DEBUG] ConnPoolByRoute - Notifying thread waiting on pool [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]<?xml version="1.0" encoding="UTF-8" standalone="yes"?><DocFrosting><mindex><comp dbid="A29324168" type="ABS"><rep dbid="A30971068" type="ABS"><rights>R1</rights><mimetype>text/xml</mimetype><repattrib name="resides">FAST</repattrib></rep></comp><comp type="CIT"><rep type="CIT"><rights>R1</rights><repattrib name="resides">FAST</repattrib></rep></comp><relatedId rel="dup" idType="44">9984469</relatedId></mindex><rmap><rclass><rights>R1</rights><product>1007813|1007946</product><advancedFmt>false</advancedFmt></rclass></rmap><searchRights><appearsIn><product>1007813|1007946</product></appearsIn></searchRights></DocFrosting>

2010/10/13 15:22:38:265 EDT [DEBUG] ThreadSafeClientConnManager - Get connection: HttpRoute[{}->http://nightlyservices.aa1.pqe:80], timeout = 0
2010/10/13 15:22:38:265 EDT [DEBUG] ConnPoolByRoute - Notifying thread waiting on pool [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
<?xml version="1.0" encoding="UTF-8" standalone="yes"?><DocFrosting><mindex><comp dbid="A29324146" type="ABS"><rep dbid="A30971046" type="ABS"><rights>R1</rights><mimetype>text/xml</mimetype><repattrib name="resides">FAST</repattrib></rep></comp><comp type="CIT"><rep type="CIT"><rights>R1</rights><repattrib name="resides">FAST</repattrib></rep></comp><relatedId rel="dup" idType="44">9984462</relatedId></mindex><rmap><rclass><rights>R1</rights><product>1007813|1007946</product><advancedFmt>false</advancedFmt></rclass></rmap><searchRights><appearsIn><product>1007813|1007946</product></appearsIn></searchRights></DocFrosting>
2010/10/13 15:22:38:265 EDT [DEBUG] ThreadSafeClientConnManager - Get connection: HttpRoute[{}->http://nightlyservices.aa1.pqe:80], timeout = 0
2010/10/13 15:22:38:265 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 2, allocated: 2 out of 5
2010/10/13 15:22:38:265 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:265 EDT [DEBUG] ConnPoolByRoute - Available capacity: 3 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:265 EDT [DEBUG] ConnPoolByRoute - Creating new connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
<?xml version="1.0" encoding="UTF-8" standalone="yes"?><DocFrosting><mindex><comp dbid="A29324164" type="ABS"><rep dbid="A30971064" type="ABS"><rights>R1</rights><mimetype>text/xml</mimetype><repattrib name="resides">FAST</repattrib></rep></comp><comp type="CIT"><rep type="CIT"><rights>R1</rights><repattrib name="resides">FAST</repattrib></rep></comp><relatedId rel="dup" idType="44">9984467</relatedId></mindex><rmap><rclass><rights>R1</rights><product>1007813|1007946</product><advancedFmt>false</advancedFmt></rclass></rmap><searchRights><appearsIn><product>1007813|1007946</product></appearsIn></searchRights></DocFrosting>
2010/10/13 15:22:38:265 EDT [DEBUG] ThreadSafeClientConnManager - Get connection: HttpRoute[{}->http://nightlyservices.aa1.pqe:80], timeout = 0
2010/10/13 15:22:38:265 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 3, allocated: 3 out of 5
2010/10/13 15:22:38:265 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:265 EDT [DEBUG] ConnPoolByRoute - Available capacity: 2 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:265 EDT [DEBUG] ConnPoolByRoute - Creating new connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
2010/10/13 15:22:38:281 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 4, allocated: 4 out of 5
2010/10/13 15:22:38:281 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:281 EDT [DEBUG] DefaultClientConnection - Sending request: GET /documentfrosting/object/9984462 HTTP/1.1
2010/10/13 15:22:38:281 EDT [DEBUG] DefaultClientConnection - Sending request: GET /documentfrosting/object/9984467 HTTP/1.1
2010/10/13 15:22:38:281 EDT [DEBUG] ConnPoolByRoute - Available capacity: 1 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:281 EDT [DEBUG] ConnPoolByRoute - Creating new connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
2010/10/13 15:22:38:281 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 5, allocated: 5 out of 5
2010/10/13 15:22:38:281 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:281 EDT [DEBUG] ConnPoolByRoute - Available capacity: 0 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:281 EDT [DEBUG] ConnPoolByRoute - Need to wait for connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:281 EDT [DEBUG] DefaultClientConnection - Sending request: GET /documentfrosting/object/9984465 HTTP/1.1
2010/10/13 15:22:38:296 EDT [DEBUG] DefaultClientConnection - Connection closed
2010/10/13 15:22:38:296 EDT [DEBUG] DefaultClientConnection - Connection closed
2010/10/13 15:22:38:296 EDT [DEBUG] DefaultClientConnection - Connection shut down
2010/10/13 15:22:38:296 EDT [DEBUG] ThreadSafeClientConnManager - Released connection is not reusable.
2010/10/13 15:22:38:296 EDT [DEBUG] ConnPoolByRoute - Releasing connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:296 EDT [DEBUG] ConnPoolByRoute - Notifying thread waiting on pool [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
java.net.SocketTimeoutException: Read timed out
	at java.net.SocketInputStream.socketRead0(Native Method)
	at java.net.SocketInputStream.read(SocketInputStream.java:129)
	at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:130)
	at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:127)
	at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:233)
	at org.apache.http.impl.conn.DefaultResponseParser.parseHead(DefaultResponseParser.java:98)
	at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:210)
	at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:271)
	at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:233)
	at org.apache.http.impl.conn.AbstractClientConnAdapter.receiveResponseHeader(AbstractClientConnAdapter.java:209)
	at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:292)
	at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:126)
	at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:483)
	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:641)
	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:597)
	at com.proquest.services.ems.ConnectionReuseTest$WorkerThread.run(ConnectionReuseTest.java:129)
2010/10/13 15:22:38:296 EDT [DEBUG] DefaultClientConnection - Connection closed
2010/10/13 15:22:38:296 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 4, allocated: 4 out of 5
2010/10/13 15:22:38:296 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:296 EDT [DEBUG] DefaultClientConnection - Connection closed
2010/10/13 15:22:38:296 EDT [DEBUG] ConnPoolByRoute - Available capacity: 1 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:296 EDT [DEBUG] DefaultClientConnection - Connection shut down
2010/10/13 15:22:38:296 EDT [DEBUG] ThreadSafeClientConnManager - Released connection is not reusable.
2010/10/13 15:22:38:296 EDT [DEBUG] ConnPoolByRoute - Releasing connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:296 EDT [DEBUG] ConnPoolByRoute - Creating new connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
2010/10/13 15:22:38:312 EDT [DEBUG] ConnPoolByRoute - Notifying thread waiting on pool [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
2010/10/13 15:22:38:312 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 4, allocated: 4 out of 5
2010/10/13 15:22:38:312 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:312 EDT [DEBUG] ConnPoolByRoute - Available capacity: 1 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:312 EDT [DEBUG] ConnPoolByRoute - Creating new connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
java.net.SocketTimeoutException: Read timed out
	at java.net.SocketInputStream.socketRead0(Native Method)
	at java.net.SocketInputStream.read(SocketInputStream.java:129)
	at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:130)
	at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:127)
	at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:233)
	at org.apache.http.impl.conn.DefaultResponseParser.parseHead(DefaultResponseParser.java:98)
	at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:210)
	at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:271)
	at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:233)
	at org.apache.http.impl.conn.AbstractClientConnAdapter.receiveResponseHeader(AbstractClientConnAdapter.java:209)
	at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:292)
	at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:126)
	at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:483)
	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:641)
	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:597)
	at com.proquest.services.ems.ConnectionReuseTest$WorkerThread.run(ConnectionReuseTest.java:129)
2010/10/13 15:22:38:312 EDT [DEBUG] DefaultClientConnection - Sending request: GET /documentfrosting/object/9984460 HTTP/1.1
2010/10/13 15:22:38:312 EDT [DEBUG] DefaultClientConnection - Sending request: GET /documentfrosting/object/9984463 HTTP/1.1
2010/10/13 15:22:38:312 EDT [DEBUG] DefaultClientConnection - Receiving response: HTTP/1.1 200 The request has succeeded
2010/10/13 15:22:38:312 EDT [DEBUG] DefaultClientConnection - Connection shut down
2010/10/13 15:22:38:312 EDT [DEBUG] ThreadSafeClientConnManager - Released connection is not reusable.
2010/10/13 15:22:38:312 EDT [DEBUG] ConnPoolByRoute - Releasing connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:312 EDT [DEBUG] ConnPoolByRoute - Notifying thread waiting on pool [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
2010/10/13 15:22:38:312 EDT [DEBUG] ThreadSafeClientConnManager - Get connection: HttpRoute[{}->http://nightlyservices.aa1.pqe:80], timeout = 0
2010/10/13 15:22:38:312 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 4, allocated: 4 out of 5
2010/10/13 15:22:38:312 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:312 EDT [DEBUG] ConnPoolByRoute - Available capacity: 1 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
<?xml version="1.0" encoding="UTF-8" standalone="yes"?><DocFrosting><mindex><comp dbid="A29324146" type="ABS"><rep dbid="A30971046" type="ABS"><rights>R1</rights><mimetype>text/xml</mimetype><repattrib name="resides">FAST</repattrib></rep></comp><comp type="CIT"><rep type="CIT"><rights>R1</rights><repattrib name="resides">FAST</repattrib></rep></comp><relatedId rel="dup" idType="44">9984462</relatedId></mindex><rmap><rclass><rights>R1</rights><product>1007813|1007946</product><advancedFmt>false</advancedFmt></rclass></rmap><searchRights><appearsIn><product>1007813|1007946</product></appearsIn></searchRights></DocFrosting>
2010/10/13 15:22:38:312 EDT [DEBUG] ConnPoolByRoute - Creating new connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
2010/10/13 15:22:38:312 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 5, allocated: 5 out of 5
2010/10/13 15:22:38:312 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:312 EDT [DEBUG] ConnPoolByRoute - Available capacity: 0 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:312 EDT [DEBUG] ConnPoolByRoute - Need to wait for connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:312 EDT [DEBUG] DefaultClientConnection - Sending request: GET /documentfrosting/object/9984464 HTTP/1.1
2010/10/13 15:22:38:312 EDT [DEBUG] DefaultClientConnection - Receiving response: HTTP/1.1 200 The request has succeeded
2010/10/13 15:22:38:312 EDT [DEBUG] DefaultClientConnection - Connection shut down
2010/10/13 15:22:38:312 EDT [DEBUG] ThreadSafeClientConnManager - Released connection is not reusable.
2010/10/13 15:22:38:312 EDT [DEBUG] ConnPoolByRoute - Releasing connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:312 EDT [DEBUG] ConnPoolByRoute - Notifying thread waiting on pool [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
<?xml version="1.0" encoding="UTF-8" standalone="yes"?><DocFrosting><mindex><comp dbid="A29324164" type="ABS"><rep dbid="A30971064" type="ABS"><rights>R1</rights><mimetype>text/xml</mimetype><repattrib name="resides">FAST</repattrib></rep></comp><comp type="CIT"><rep type="CIT"><rights>R1</rights><repattrib name="resides">FAST</repattrib></rep></comp><relatedId rel="dup" idType="44">9984467</relatedId></mindex><rmap><rclass><rights>R1</rights><product>1007813|1007946</product><advancedFmt>false</advancedFmt></rclass></rmap><searchRights><appearsIn><product>1007813|1007946</product></appearsIn></searchRights></DocFrosting>
2010/10/13 15:22:38:312 EDT [DEBUG] ThreadSafeClientConnManager - Get connection: HttpRoute[{}->http://nightlyservices.aa1.pqe:80], timeout = 0
2010/10/13 15:22:38:312 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 4, allocated: 4 out of 5
2010/10/13 15:22:38:312 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:312 EDT [DEBUG] ConnPoolByRoute - Available capacity: 1 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:312 EDT [DEBUG] ConnPoolByRoute - Creating new connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
2010/10/13 15:22:38:312 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 5, allocated: 5 out of 5
2010/10/13 15:22:38:312 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:312 EDT [DEBUG] ConnPoolByRoute - Available capacity: 0 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:328 EDT [DEBUG] ConnPoolByRoute - Need to wait for connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:328 EDT [DEBUG] DefaultClientConnection - Sending request: GET /documentfrosting/object/9984461 HTTP/1.1
2010/10/13 15:22:38:359 EDT [DEBUG] DefaultClientConnection - Receiving response: HTTP/1.1 200 The request has succeeded
2010/10/13 15:22:38:359 EDT [DEBUG] DefaultClientConnection - Connection shut down
2010/10/13 15:22:38:359 EDT [DEBUG] ThreadSafeClientConnManager - Released connection is not reusable.
2010/10/13 15:22:38:359 EDT [DEBUG] ConnPoolByRoute - Releasing connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:359 EDT [DEBUG] ConnPoolByRoute - Notifying thread waiting on pool [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
2010/10/13 15:22:38:359 EDT [DEBUG] ThreadSafeClientConnManager - Get connection: HttpRoute[{}->http://nightlyservices.aa1.pqe:80], timeout = 0
2010/10/13 15:22:38:359 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 4, allocated: 4 out of 5
2010/10/13 15:22:38:359 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
<?xml version="1.0" encoding="UTF-8" standalone="yes"?><DocFrosting><mindex><comp dbid="A29324140" type="ABS"><rep dbid="A30971040" type="ABS"><rights>R1</rights><mimetype>text/xml</mimetype><repattrib name="resides">FAST</repattrib></rep></comp><comp type="CIT"><rep type="CIT"><rights>R1</rights><repattrib name="resides">FAST</repattrib></rep></comp><relatedId rel="dup" idType="44">9984460</relatedId></mindex><rmap><rclass><rights>R1</rights><product>1007813|1007946</product><advancedFmt>false</advancedFmt></rclass></rmap><searchRights><appearsIn><product>1007813|1007946</product></appearsIn></searchRights></DocFrosting>
2010/10/13 15:22:38:359 EDT [DEBUG] ConnPoolByRoute - Available capacity: 1 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:359 EDT [DEBUG] ConnPoolByRoute - Creating new connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
2010/10/13 15:22:38:359 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 5, allocated: 5 out of 5
2010/10/13 15:22:38:359 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:359 EDT [DEBUG] ConnPoolByRoute - Available capacity: 0 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:359 EDT [DEBUG] ConnPoolByRoute - Need to wait for connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:359 EDT [DEBUG] DefaultClientConnection - Sending request: GET /documentfrosting/object/9984460 HTTP/1.1
2010/10/13 15:22:38:375 EDT [DEBUG] DefaultClientConnection - Connection closed
2010/10/13 15:22:38:375 EDT [DEBUG] DefaultClientConnection - Connection closed
2010/10/13 15:22:38:375 EDT [DEBUG] DefaultClientConnection - Connection shut down
2010/10/13 15:22:38:375 EDT [DEBUG] ThreadSafeClientConnManager - Released connection is not reusable.
2010/10/13 15:22:38:375 EDT [DEBUG] ConnPoolByRoute - Releasing connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:375 EDT [DEBUG] ConnPoolByRoute - Notifying thread waiting on pool [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
java.net.SocketTimeoutException: Read timed out
	at java.net.SocketInputStream.socketRead0(Native Method)
	at java.net.SocketInputStream.read(SocketInputStream.java:129)
	at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:130)
	at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:127)
	at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:233)
	at org.apache.http.impl.conn.DefaultResponseParser.parseHead(DefaultResponseParser.java:98)
	at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:210)
	at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:271)
	at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:233)
	at org.apache.http.impl.conn.AbstractClientConnAdapter.receiveResponseHeader(AbstractClientConnAdapter.java:209)
	at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:292)
	at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:126)
	at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:483)
	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:641)
	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:597)
	at com.proquest.services.ems.ConnectionReuseTest$WorkerThread.run(ConnectionReuseTest.java:129)
2010/10/13 15:22:38:375 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 4, allocated: 4 out of 5
2010/10/13 15:22:38:375 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:375 EDT [DEBUG] ConnPoolByRoute - Available capacity: 1 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:375 EDT [DEBUG] ConnPoolByRoute - Creating new connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
2010/10/13 15:22:38:375 EDT [DEBUG] DefaultClientConnection - Sending request: GET /documentfrosting/object/9984462 HTTP/1.1
2010/10/13 15:22:38:390 EDT [DEBUG] DefaultClientConnection - Receiving response: HTTP/1.1 200 The request has succeeded
2010/10/13 15:22:38:390 EDT [DEBUG] DefaultClientConnection - Connection shut down
2010/10/13 15:22:38:390 EDT [DEBUG] ThreadSafeClientConnManager - Released connection is not reusable.
2010/10/13 15:22:38:390 EDT [DEBUG] ConnPoolByRoute - Releasing connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:390 EDT [DEBUG] ConnPoolByRoute - Notifying thread waiting on pool [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
2010/10/13 15:22:38:390 EDT [DEBUG] ThreadSafeClientConnManager - Get connection: HttpRoute[{}->http://nightlyservices.aa1.pqe:80], timeout = 0
2010/10/13 15:22:38:390 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 4, allocated: 4 out of 5
2010/10/13 15:22:38:390 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
<?xml version="1.0" encoding="UTF-8" standalone="yes"?><DocFrosting><mindex><comp dbid="A29324140" type="ABS"><rep dbid="A30971040" type="ABS"><rights>R1</rights><mimetype>text/xml</mimetype><repattrib name="resides">FAST</repattrib></rep></comp><comp type="CIT"><rep type="CIT"><rights>R1</rights><repattrib name="resides">FAST</repattrib></rep></comp><relatedId rel="dup" idType="44">9984460</relatedId></mindex><rmap><rclass><rights>R1</rights><product>1007813|1007946</product><advancedFmt>false</advancedFmt></rclass></rmap><searchRights><appearsIn><product>1007813|1007946</product></appearsIn></searchRights></DocFrosting>
2010/10/13 15:22:38:390 EDT [DEBUG] ConnPoolByRoute - Available capacity: 1 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:390 EDT [DEBUG] ConnPoolByRoute - Creating new connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
2010/10/13 15:22:38:390 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 5, allocated: 5 out of 5
2010/10/13 15:22:38:390 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:390 EDT [DEBUG] ConnPoolByRoute - Available capacity: 0 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:390 EDT [DEBUG] ConnPoolByRoute - Need to wait for connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:390 EDT [DEBUG] DefaultClientConnection - Sending request: GET /documentfrosting/object/9984460 HTTP/1.1
2010/10/13 15:22:38:406 EDT [DEBUG] DefaultClientConnection - Connection closed
2010/10/13 15:22:38:406 EDT [DEBUG] DefaultClientConnection - Connection closed
2010/10/13 15:22:38:406 EDT [DEBUG] DefaultClientConnection - Connection shut down
2010/10/13 15:22:38:406 EDT [DEBUG] ThreadSafeClientConnManager - Released connection is not reusable.
2010/10/13 15:22:38:406 EDT [DEBUG] ConnPoolByRoute - Releasing connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:406 EDT [DEBUG] ConnPoolByRoute - Notifying thread waiting on pool [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
java.net.SocketTimeoutException: Read timed out
	at java.net.SocketInputStream.socketRead0(Native Method)
	at java.net.SocketInputStream.read(SocketInputStream.java:129)
	at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:130)
	at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:127)
	at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:233)
	at org.apache.http.impl.conn.DefaultResponseParser.parseHead(DefaultResponseParser.java:98)
	at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:210)
	at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:271)
	at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:233)
	at org.apache.http.impl.conn.AbstractClientConnAdapter.receiveResponseHeader(AbstractClientConnAdapter.java:209)
	at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:292)
	at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:126)
	at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:483)
	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:641)
	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:597)
	at com.proquest.services.ems.ConnectionReuseTest$WorkerThread.run(ConnectionReuseTest.java:129)
2010/10/13 15:22:38:406 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 4, allocated: 4 out of 5
2010/10/13 15:22:38:406 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:406 EDT [DEBUG] ConnPoolByRoute - Available capacity: 1 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:406 EDT [DEBUG] ConnPoolByRoute - Creating new connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
2010/10/13 15:22:38:406 EDT [DEBUG] DefaultClientConnection - Connection closed
2010/10/13 15:22:38:406 EDT [DEBUG] DefaultClientConnection - Connection closed
2010/10/13 15:22:38:406 EDT [DEBUG] DefaultClientConnection - Connection shut down
2010/10/13 15:22:38:406 EDT [DEBUG] ThreadSafeClientConnManager - Released connection is not reusable.
2010/10/13 15:22:38:406 EDT [DEBUG] ConnPoolByRoute - Releasing connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:406 EDT [DEBUG] ConnPoolByRoute - Notifying thread waiting on pool [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
java.net.SocketTimeoutException: Read timed out
	at java.net.SocketInputStream.socketRead0(Native Method)
	at java.net.SocketInputStream.read(SocketInputStream.java:129)
	at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:130)
	at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:127)
	at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:233)
	at org.apache.http.impl.conn.DefaultResponseParser.parseHead(DefaultResponseParser.java:98)
	at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:210)
	at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:271)
	at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:233)
	at org.apache.http.impl.conn.AbstractClientConnAdapter.receiveResponseHeader(AbstractClientConnAdapter.java:209)
	at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:292)
	at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:126)
	at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:483)
	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:641)
	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:597)
	at com.proquest.services.ems.ConnectionReuseTest$WorkerThread.run(ConnectionReuseTest.java:129)
2010/10/13 15:22:38:406 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 4, allocated: 4 out of 5
2010/10/13 15:22:38:406 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:406 EDT [DEBUG] ConnPoolByRoute - Available capacity: 1 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:406 EDT [DEBUG] ConnPoolByRoute - Creating new connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
2010/10/13 15:22:38:406 EDT [DEBUG] DefaultClientConnection - Sending request: GET /documentfrosting/object/9984469 HTTP/1.1
2010/10/13 15:22:38:421 EDT [DEBUG] DefaultClientConnection - Sending request: GET /documentfrosting/object/9984467 HTTP/1.1
2010/10/13 15:22:38:421 EDT [DEBUG] DefaultClientConnection - Connection closed
2010/10/13 15:22:38:421 EDT [DEBUG] DefaultClientConnection - Connection closed
2010/10/13 15:22:38:421 EDT [DEBUG] DefaultClientConnection - Connection shut down
2010/10/13 15:22:38:421 EDT [DEBUG] ThreadSafeClientConnManager - Released connection is not reusable.
2010/10/13 15:22:38:421 EDT [DEBUG] ConnPoolByRoute - Releasing connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:421 EDT [DEBUG] ConnPoolByRoute - Notifying no-one, there are no waiting threads
java.net.SocketTimeoutException: Read timed out
	at java.net.SocketInputStream.socketRead0(Native Method)
	at java.net.SocketInputStream.read(SocketInputStream.java:129)
	at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:130)
	at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:127)
	at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:233)
	at org.apache.http.impl.conn.DefaultResponseParser.parseHead(DefaultResponseParser.java:98)
	at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:210)
	at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:271)
	at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:233)
	at org.apache.http.impl.conn.AbstractClientConnAdapter.receiveResponseHeader(AbstractClientConnAdapter.java:209)
	at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:292)
	at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:126)
	at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:483)
	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:641)
	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:597)
	at com.proquest.services.ems.ConnectionReuseTest$WorkerThread.run(ConnectionReuseTest.java:129)
2010/10/13 15:22:38:453 EDT [DEBUG] DefaultClientConnection - Receiving response: HTTP/1.1 200 The request has succeeded
2010/10/13 15:22:38:453 EDT [DEBUG] DefaultClientConnection - Connection shut down
2010/10/13 15:22:38:453 EDT [DEBUG] ThreadSafeClientConnManager - Released connection is not reusable.
2010/10/13 15:22:38:453 EDT [DEBUG] ConnPoolByRoute - Releasing connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:453 EDT [DEBUG] ConnPoolByRoute - Notifying no-one, there are no waiting threads
2010/10/13 15:22:38:453 EDT [DEBUG] ThreadSafeClientConnManager - Get connection: HttpRoute[{}->http://nightlyservices.aa1.pqe:80], timeout = 0
2010/10/13 15:22:38:453 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 3, allocated: 3 out of 5
2010/10/13 15:22:38:453 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:453 EDT [DEBUG] ConnPoolByRoute - Available capacity: 2 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:453 EDT [DEBUG] ConnPoolByRoute - Creating new connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
2010/10/13 15:22:38:453 EDT [DEBUG] DefaultClientConnection - Receiving response: HTTP/1.1 200 The request has succeeded
<?xml version="1.0" encoding="UTF-8" standalone="yes"?><DocFrosting><mindex><comp dbid="A29324164" type="ABS"><rep dbid="A30971064" type="ABS"><rights>R1</rights><mimetype>text/xml</mimetype><repattrib name="resides">FAST</repattrib></rep></comp><comp type="CIT"><rep type="CIT"><rights>R1</rights><repattrib name="resides">FAST</repattrib></rep></comp><relatedId rel="dup" idType="44">9984467</relatedId></mindex><rmap><rclass><rights>R1</rights><product>1007813|1007946</product><advancedFmt>false</advancedFmt></rclass></rmap><searchRights><appearsIn><product>1007813|1007946</product></appearsIn></searchRights></DocFrosting>
<?xml version="1.0" encoding="UTF-8" standalone="yes"?><DocFrosting><mindex><comp dbid="A29324168" type="ABS"><rep dbid="A30971068" type="ABS"><rights>R1</rights><mimetype>text/xml</mimetype><repattrib name="resides">FAST</repattrib></rep></comp><comp type="CIT"><rep type="CIT"><rights>R1</rights><repattrib name="resides">FAST</repattrib></rep></comp><relatedId rel="dup" idType="44">9984469</relatedId></mindex><rmap><rclass><rights>R1</rights><product>1007813|1007946</product><advancedFmt>false</advancedFmt></rclass></rmap><searchRights><appearsIn><product>1007813|1007946</product></appearsIn></searchRights></DocFrosting>
2010/10/13 15:22:38:453 EDT [DEBUG] DefaultClientConnection - Connection shut down
2010/10/13 15:22:38:453 EDT [DEBUG] ThreadSafeClientConnManager - Released connection is not reusable.
2010/10/13 15:22:38:453 EDT [DEBUG] ConnPoolByRoute - Releasing connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:453 EDT [DEBUG] ConnPoolByRoute - Notifying no-one, there are no waiting threads
2010/10/13 15:22:38:453 EDT [DEBUG] ThreadSafeClientConnManager - Get connection: HttpRoute[{}->http://nightlyservices.aa1.pqe:80], timeout = 0
2010/10/13 15:22:38:453 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 3, allocated: 3 out of 5
2010/10/13 15:22:38:453 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:453 EDT [DEBUG] ConnPoolByRoute - Available capacity: 2 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:453 EDT [DEBUG] ConnPoolByRoute - Creating new connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]
2010/10/13 15:22:38:453 EDT [DEBUG] DefaultClientConnection - Sending request: GET /documentfrosting/object/9984467 HTTP/1.1
2010/10/13 15:22:38:453 EDT [DEBUG] DefaultClientConnection - Sending request: GET /documentfrosting/object/9984469 HTTP/1.1
2010/10/13 15:22:38:484 EDT [DEBUG] DefaultClientConnection - Connection closed
2010/10/13 15:22:38:484 EDT [DEBUG] DefaultClientConnection - Connection closed
2010/10/13 15:22:38:484 EDT [DEBUG] DefaultClientConnection - Connection shut down
2010/10/13 15:22:38:484 EDT [DEBUG] ThreadSafeClientConnManager - Released connection is not reusable.
2010/10/13 15:22:38:484 EDT [DEBUG] ConnPoolByRoute - Releasing connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:484 EDT [DEBUG] ConnPoolByRoute - Notifying no-one, there are no waiting threads
java.net.SocketTimeoutException: Read timed out
	at java.net.SocketInputStream.socketRead0(Native Method)
	at java.net.SocketInputStream.read(SocketInputStream.java:129)
	at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:130)
	at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:127)
	at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:233)
	at org.apache.http.impl.conn.DefaultResponseParser.parseHead(DefaultResponseParser.java:98)
	at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:210)
	at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:271)
	at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:233)
	at org.apache.http.impl.conn.AbstractClientConnAdapter.receiveResponseHeader(AbstractClientConnAdapter.java:209)
	at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:292)
	at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:126)
	at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:483)
	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:641)
	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:597)
	at com.proquest.services.ems.ConnectionReuseTest$WorkerThread.run(ConnectionReuseTest.java:129)
2010/10/13 15:22:38:484 EDT [DEBUG] DefaultClientConnection - Receiving response: HTTP/1.1 200 The request has succeeded
2010/10/13 15:22:38:484 EDT [DEBUG] DefaultClientConnection - Connection shut down
2010/10/13 15:22:38:484 EDT [DEBUG] ThreadSafeClientConnManager - Released connection is not reusable.
2010/10/13 15:22:38:484 EDT [DEBUG] ConnPoolByRoute - Releasing connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:484 EDT [DEBUG] ConnPoolByRoute - Notifying no-one, there are no waiting threads
2010/10/13 15:22:38:484 EDT [DEBUG] ThreadSafeClientConnManager - Get connection: HttpRoute[{}->http://nightlyservices.aa1.pqe:80], timeout = 0
2010/10/13 15:22:38:484 EDT [DEBUG] ConnPoolByRoute - [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]] kept alive: 0, issued: 2, allocated: 2 out of 5
2010/10/13 15:22:38:484 EDT [DEBUG] ConnPoolByRoute - No free connections [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:484 EDT [DEBUG] ConnPoolByRoute - Available capacity: 3 out of 5 [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]][null]
2010/10/13 15:22:38:484 EDT [DEBUG] ConnPoolByRoute - Creating new connection [HttpRoute[{}->http://nightlyservices.aa1.pqe:80]]

> ThreadSafeClientConnManager Connection pool  leak issue
> -------------------------------------------------------
>
>                 Key: HTTPCLIENT-1012
>                 URL: https://issues.apache.org/jira/browse/HTTPCLIENT-1012
>             Project: HttpComponents HttpClient
>          Issue Type: Bug
>          Components: HttpConn
>    Affects Versions: 4.0.3
>            Reporter: chowdareddy somu
>
> Hi,
> We are using the HttpClient4.0.3(latest) version for our application where we need to serve the http request to our web app by calling the underlying services.
> Application gets an average 50 threads at a time and we are maintaining the connection pool  on per host basis. we configured the following HttpParam values.. And we are calling the abort() and consumeContent() methods if there are any exceptions due to backed service problem, and for success requests we are extracting the response using the get content method( InputStream is = httpResponse.getEntity().getContent())
> Application runs contionusly for 3 to 4 hrs fine, after that its getting the out of connections and it throws below exception..
> Config Values
> http.connection.timeout = 5000
> http.socket.timeout = 300000
> http.conn-manager.timeout = 1000
> http.conn-manager.max-per-route = 100
> http.conn-manager.max-total = 100
> private static HttpConnectionManagerFactory instance = null;
> 	private Map<String, ClientConnectionManager> map = new HashMap<String, ClientConnectionManager>();
> 	private HttpConnectionManagerFactory() {
> 	}
> 	public static synchronized HttpConnectionManagerFactory getInstance() {
> 		if (instance == null) {
> 			instance = new HttpConnectionManagerFactory();
> 		}
> 		return instance;
> 	}
> 	public synchronized ClientConnectionManager getConnectionManager(URI uri) {
> 		if (!map.containsKey(uri.getHost())) {
> 			HttpParams params = HttpProperties.getInstance().getHttpParams(uri);
> 			SchemeRegistry registry = new SchemeRegistry();
> 			Scheme http = new Scheme("http", PlainSocketFactory.getSocketFactory(), 80);
> 			registry.register(http);
> 			map.put(uri.getHost(), new ThreadSafeClientConnManager(params, registry));
> 		}
> 		return map.get(uri.getHost());
> 	}
> public HttpClient getClient(HttpUriRequest request) {
> 		ClientConnectionManager connectionManager = HttpConnectionManagerFactory.getInstance().getConnectionManager(request.getURI());
> 		HttpParams params = HttpProperties.getInstance().getHttpParams(request.getURI());
>           .............
> }
> HttpClient httpClient = HttpClientFactory.getInstance().getClient(httpRequest);
> 			tracker.startTimer();
> 			HttpResponse httpResponse = null;
> 			try {
> 				try {
> 					httpResponse = httpClient.execute(httpRequest);
> 				} finally {
> 					tracker.stopTimer();
> 				}
> 				StatusLine statusLine = httpResponse.getStatusLine();
> 				tracker.setStatusLine(statusLine);
>                         ..........................
>          }
> Caused by: java.io.IOException: org.apache.http.conn.ConnectionPoolTimeoutException: Timeout waiting for connection
>         at com.proquest.services.ems.service.HttpRetrieve.getFollowingRedirection(HttpRetrieve.java:194)
>         at com.proquest.services.ems.service.MorningstarMediaService.resolveResource(MorningstarMediaService.java:73)
>         ... 46 more
> Caused by: org.apache.http.conn.ConnectionPoolTimeoutException: Timeout waiting for connection
>         at org.apache.http.impl.conn.tsccm.ConnPoolByRoute.getEntryBlocking(ConnPoolByRoute.java:345)
>         at org.apache.http.impl.conn.tsccm.ConnPoolByRoute$1.getPoolEntry(ConnPoolByRoute.java:228)
>         at org.apache.http.impl.conn.tsccm.ThreadSafeClientConnManager$1.getConnection(ThreadSafeClientConnManager.java:172)
>         at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:390)
>         at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:641)
>         at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:576)
>         at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:554)
>         at com.proquest.services.ems.service.HttpRetrieve.getFollowingRedirection(HttpRetrieve.java:155)
> any idea???

-- 
This message is automatically generated by JIRA.
-
You can reply to this email to add a comment to the issue online.


---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscribe@hc.apache.org
For additional commands, e-mail: dev-help@hc.apache.org


Mime
View raw message