Subsequent request takes too long
============================================================================================================================================================================================
2013-07-30 09:56:42,912: DEBUG [tp764546727-193]         NettyAsyncHttpProvider - 
Non cached request 
DefaultHttpRequest(chunked: false)
CONNECT stg.data.nokia.com:443 HTTP/1.0
Host: stg.data.nokia.com
Authorization: Basic NTgzOThhMGYxZTAyZmIyMjU3ZGZkYTFlMDAxZTU4MDI6NUpjdHFNTWJSd25kMGg0Y0k3NjdSTFppUXBRNTNrcHJpMWlRa0RDMGNWNmRBZGZ3cHMxR3V3ajVtUGM2R3VadA==
Connection: keep-alive
Proxy-Connection: keep-alive
Accept: */*
User-Agent: Places-1.1-pc-uc

using Channel 
[id: 0xe6128f1d]

2013-07-30 09:56:43,051: DEBUG [ I/O worker #83]         NettyAsyncHttpProvider - 

Request DefaultHttpRequest(chunked: false)
CONNECT stg.data.nokia.com:443 HTTP/1.0
Host: stg.data.nokia.com
Authorization: Basic NTgzOThhMGYxZTAyZmIyMjU3ZGZkYTFlMDAxZTU4MDI6NUpjdHFNTWJSd25kMGg0Y0k3NjdSTFppUXBRNTNrcHJpMWlRa0RDMGNWNmRBZGZ3cHMxR3V3ajVtUGM2R3VadA==
Connection: keep-alive
Proxy-Connection: keep-alive
Accept: */*
User-Agent: Places-1.1-pc-uc

Response DefaultHttpResponse(chunked: false)
HTTP/1.0 200 Connection established

2013-07-30 09:56:43,051: DEBUG [ I/O worker #83]         NettyAsyncHttpProvider - Connected to corporate.proxy.com:8080
2013-07-30 09:56:43,051: DEBUG [ I/O worker #83]         NettyAsyncHttpProvider - Connecting to proxy http://corporate.proxy.com:8080 for scheme https://target.server.com/review/c425eb5b-3a69-4f45-8e2f-61ebd571fb1d
2013-07-30 09:56:43,052: DEBUG [ I/O worker #83]         NettyAsyncHttpProvider - 
Using cached Channel [id: 0xe6128f1d, /172.25.62.60:21775 => corporate.proxy.com/172.16.99.10:8080]
 for request 
DefaultHttpRequest(chunked: false)
GET https://target.server.com/review/c425eb5b-3a69-4f45-8e2f-61ebd571fb1d HTTP/1.1
Host: stg.data.nokia.com
X-User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/28.0.1500.72 Safari/537.36
NLP.PP: 28923440-2ba3-498d-af22-6018a127aa7c
X-FWD-APP-ID: BDjctzFoFSCLGaDpE82O
X-FWD-Referer: http://localhost:8080/places/v1/places/250u09tu-4561b8da952f4fd79c4e1998c3fcf032?app_id=BDjctzFoFSCLGaDpE82O&app_code=NS9%2FOwu97RZCPj3d38KGsg%3D%3D
X-Forwarded-For: 0:0:0:0:0:0:0:1
X-NLP-Testing: 1
X-NLP-TID: 899a8f18-5ff3-42d6-b175-07ac68d06c86
NLP.PS: 2ea1dcb9-b396-4ddd-a4dd-8a8dbeb3c1d8
Accept-Encoding: gzip
Authorization: Basic NTgzOThhMGYxZTAyZmIyMjU3ZGZkYTFlMDAxZTU4MDI6NUpjdHFNTWJSd25kMGg0Y0k3NjdSTFppUXBRNTNrcHJpMWlRa0RDMGNWNmRBZGZ3cHMxR3V3ajVtUGM2R3VadA==
Connection: keep-alive
Proxy-Connection: keep-alive
Accept: */*
User-Agent: Places-1.1-pc-uc

2013-07-30 09:56:43,495: DEBUG [ I/O worker #83]         NettyAsyncHttpProvider - 

Request DefaultHttpRequest(chunked: false)
GET https://target.server.com/review/c425eb5b-3a69-4f45-8e2f-61ebd571fb1d HTTP/1.1
Host: stg.data.nokia.com
X-User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/28.0.1500.72 Safari/537.36
NLP.PP: 28923440-2ba3-498d-af22-6018a127aa7c
X-FWD-APP-ID: BDjctzFoFSCLGaDpE82O
X-FWD-Referer: http://localhost:8080/places/v1/places/250u09tu-4561b8da952f4fd79c4e1998c3fcf032?app_id=BDjctzFoFSCLGaDpE82O&app_code=NS9%2FOwu97RZCPj3d38KGsg%3D%3D
X-Forwarded-For: 0:0:0:0:0:0:0:1
X-NLP-Testing: 1
X-NLP-TID: 899a8f18-5ff3-42d6-b175-07ac68d06c86
NLP.PS: 2ea1dcb9-b396-4ddd-a4dd-8a8dbeb3c1d8
Accept-Encoding: gzip
Authorization: Basic NTgzOThhMGYxZTAyZmIyMjU3ZGZkYTFlMDAxZTU4MDI6NUpjdHFNTWJSd25kMGg0Y0k3NjdSTFppUXBRNTNrcHJpMWlRa0RDMGNWNmRBZGZ3cHMxR3V3ajVtUGM2R3VadA==
Connection: keep-alive
Proxy-Connection: keep-alive
Accept: */*
User-Agent: Places-1.1-pc-uc

Response DefaultHttpResponse(chunked: false)
HTTP/1.1 200 OK
Content-Language: en-US
Content-Type: application/json;charset=UTF-8
Date: Tue, 30 Jul 2013 07:56:28 GMT
ETag: 6c687a58ddf133fe1c5a08bcb2740a5174935622
Server: Apache-Coyote/1.1
Content-Length: 551
Connection: keep-alive

2013-07-30 09:56:43,495: DEBUG [ I/O worker #83]           NettyConnectionsPool - Adding uri: http://corporate.proxy.com:8080 for channel [id: 0xe6128f1d, /172.25.62.60:21775 => corporate.proxy.com/172.16.99.10:8080]
2013-07-30 09:56:43,496: INFO  [ I/O worker #83]                      upstreams - 899a8f18-5ff3-42d6-b175-07ac68d06c86 scbe GET "https://target.server.com/review/c425eb5b-3a69-4f45-8e2f-61ebd571fb1d" 200 551 632 "X-User-Agent=Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/28.0.1500.72 Safari/537.36;NLP.PP=28923440-2ba3-498d-af22-6018a127aa7c;X-FWD-APP-ID=BDjctzFoFSCLGaDpE82O;X-FWD-Referer=http://localhost:8080/places/v1/places/250u09tu-4561b8da952f4fd79c4e1998c3fcf032?app_id=BDjctzFoFSCLGaDpE82O&app_code=NS9%2FOwu97RZCPj3d38KGsg%3D%3D;X-Forwarded-For=0:0:0:0:0:0:0:1;X-NLP-Testing=1;X-NLP-TID=899a8f18-5ff3-42d6-b175-07ac68d06c86;NLP.PS=2ea1dcb9-b396-4ddd-a4dd-8a8dbeb3c1d8"
2013-07-30 09:56:44,502: DEBUG [        Timer-0]           NettyConnectionsPool - Entry count for : http://corporate.proxy.com:8080 : 0
2013-07-30 09:56:45,529: DEBUG [        Timer-9]           NettyConnectionsPool - Entry count for : http://corporate.proxy.com:8080 : 1
2013-07-30 09:56:46,930: DEBUG [tp764546727-196]         NettyAsyncHttpProvider - Using cached Channel [id: 0xe6128f1d, /172.25.62.60:21775 => corporate.proxy.com/172.16.99.10:8080]
 for uri http://corporate.proxy.com:8080/

2013-07-30 09:56:46,930: DEBUG [tp764546727-196]         NettyAsyncHttpProvider - 
Using cached Channel [id: 0xe6128f1d, /172.25.62.60:21775 => corporate.proxy.com/172.16.99.10:8080]
 for request 
DefaultHttpRequest(chunked: false)
GET https://target.server.com/review/c425eb5b-3a69-4f45-8e2f-61ebd571fb1d HTTP/1.1
Host: stg.data.nokia.com
X-User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/28.0.1500.72 Safari/537.36
NLP.PP: 28923440-2ba3-498d-af22-6018a127aa7c
X-FWD-APP-ID: BDjctzFoFSCLGaDpE82O
X-FWD-Referer: http://localhost:8080/places/v1/places/250u09tu-4561b8da952f4fd79c4e1998c3fcf032?app_id=BDjctzFoFSCLGaDpE82O&app_code=NS9%2FOwu97RZCPj3d38KGsg%3D%3D
X-Forwarded-For: 0:0:0:0:0:0:0:1
X-NLP-Testing: 1
X-NLP-TID: 0f47e0f2-9c7e-4c55-8b96-60d806ae3057
NLP.PS: 2ea1dcb9-b396-4ddd-a4dd-8a8dbeb3c1d8
Accept-Encoding: gzip
Authorization: Basic NTgzOThhMGYxZTAyZmIyMjU3ZGZkYTFlMDAxZTU4MDI6NUpjdHFNTWJSd25kMGg0Y0k3NjdSTFppUXBRNTNrcHJpMWlRa0RDMGNWNmRBZGZ3cHMxR3V3ajVtUGM2R3VadA==
Connection: keep-alive
Proxy-Connection: keep-alive
Accept: */*
User-Agent: Places-1.1-pc-uc

2013-07-30 09:56:51,194: DEBUG [ I/O worker #83]         NettyAsyncHttpProvider - Channel Closed: [id: 0xe6128f1d, /172.25.62.60:21775 :> corporate.proxy.com/172.16.99.10:8080] with attachment NettyResponseFuture{currentRetry=0,
	isDone=false,
	isCancelled=false,
	asyncHandler=pbapi.clients.httpclient.Request$$anon$1@4dab43cb,
	requestTimeoutInMs=15000,
	nettyRequest=DefaultHttpRequest(chunked: false)
GET https://target.server.com/review/c425eb5b-3a69-4f45-8e2f-61ebd571fb1d HTTP/1.1
Host: stg.data.nokia.com
X-User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/28.0.1500.72 Safari/537.36
NLP.PP: 28923440-2ba3-498d-af22-6018a127aa7c
X-FWD-APP-ID: BDjctzFoFSCLGaDpE82O
X-FWD-Referer: http://localhost:8080/places/v1/places/250u09tu-4561b8da952f4fd79c4e1998c3fcf032?app_id=BDjctzFoFSCLGaDpE82O&app_code=NS9%2FOwu97RZCPj3d38KGsg%3D%3D
X-Forwarded-For: 0:0:0:0:0:0:0:1
X-NLP-Testing: 1
X-NLP-TID: 0f47e0f2-9c7e-4c55-8b96-60d806ae3057
NLP.PS: 2ea1dcb9-b396-4ddd-a4dd-8a8dbeb3c1d8
Accept-Encoding: gzip
Authorization: Basic NTgzOThhMGYxZTAyZmIyMjU3ZGZkYTFlMDAxZTU4MDI6NUpjdHFNTWJSd25kMGg0Y0k3NjdSTFppUXBRNTNrcHJpMWlRa0RDMGNWNmRBZGZ3cHMxR3V3ajVtUGM2R3VadA==
Connection: keep-alive
Proxy-Connection: keep-alive
Accept: */*
User-Agent: Places-1.1-pc-uc,
	content=null,
	uri=https://target.server.com/review/c425eb5b-3a69-4f45-8e2f-61ebd571fb1d,
	keepAlive=true,
	httpResponse=null,
	exEx=null,
	redirectCount=0,
	reaperFuture=com.ning.http.client.providers.netty.NettyAsyncHttpProvider$ReaperFuture@518036ad,
	inAuth=false,
	statusReceived=false,
	touch=314387285}
2013-07-30 09:56:51,195: DEBUG [ I/O worker #83]         NettyAsyncHttpProvider - Trying to recover request DefaultHttpRequest(chunked: false)
GET https://target.server.com/review/c425eb5b-3a69-4f45-8e2f-61ebd571fb1d HTTP/1.1
Host: stg.data.nokia.com
X-User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/28.0.1500.72 Safari/537.36
NLP.PP: 28923440-2ba3-498d-af22-6018a127aa7c
X-FWD-APP-ID: BDjctzFoFSCLGaDpE82O
X-FWD-Referer: http://localhost:8080/places/v1/places/250u09tu-4561b8da952f4fd79c4e1998c3fcf032?app_id=BDjctzFoFSCLGaDpE82O&app_code=NS9%2FOwu97RZCPj3d38KGsg%3D%3D
X-Forwarded-For: 0:0:0:0:0:0:0:1
X-NLP-Testing: 1
X-NLP-TID: 0f47e0f2-9c7e-4c55-8b96-60d806ae3057
NLP.PS: 2ea1dcb9-b396-4ddd-a4dd-8a8dbeb3c1d8
Accept-Encoding: gzip
Authorization: Basic NTgzOThhMGYxZTAyZmIyMjU3ZGZkYTFlMDAxZTU4MDI6NUpjdHFNTWJSd25kMGg0Y0k3NjdSTFppUXBRNTNrcHJpMWlRa0RDMGNWNmRBZGZ3cHMxR3V3ajVtUGM2R3VadA==
Connection: keep-alive
Proxy-Connection: keep-alive
Accept: */*
User-Agent: Places-1.1-pc-uc

2013-07-30 09:56:51,195: DEBUG [ I/O worker #83]         NettyAsyncHttpProvider - 
Non cached request 
DefaultHttpRequest(chunked: false)
CONNECT stg.data.nokia.com:443 HTTP/1.0
Host: stg.data.nokia.com
Authorization: Basic NTgzOThhMGYxZTAyZmIyMjU3ZGZkYTFlMDAxZTU4MDI6NUpjdHFNTWJSd25kMGg0Y0k3NjdSTFppUXBRNTNrcHJpMWlRa0RDMGNWNmRBZGZ3cHMxR3V3ajVtUGM2R3VadA==
Connection: keep-alive
Proxy-Connection: keep-alive
Accept: */*
User-Agent: Places-1.1-pc-uc

using Channel 
[id: 0x061fb809]

2013-07-30 09:56:51,335: DEBUG [ I/O worker #84]         NettyAsyncHttpProvider - 

Request DefaultHttpRequest(chunked: false)
CONNECT stg.data.nokia.com:443 HTTP/1.0
Host: stg.data.nokia.com
Authorization: Basic NTgzOThhMGYxZTAyZmIyMjU3ZGZkYTFlMDAxZTU4MDI6NUpjdHFNTWJSd25kMGg0Y0k3NjdSTFppUXBRNTNrcHJpMWlRa0RDMGNWNmRBZGZ3cHMxR3V3ajVtUGM2R3VadA==
Connection: keep-alive
Proxy-Connection: keep-alive
Accept: */*
User-Agent: Places-1.1-pc-uc

Response DefaultHttpResponse(chunked: false)
HTTP/1.0 200 Connection established

2013-07-30 09:56:51,335: DEBUG [ I/O worker #84]         NettyAsyncHttpProvider - Connected to corporate.proxy.com:8080
2013-07-30 09:56:51,335: DEBUG [ I/O worker #84]         NettyAsyncHttpProvider - Connecting to proxy http://corporate.proxy.com:8080 for scheme https://target.server.com/review/c425eb5b-3a69-4f45-8e2f-61ebd571fb1d
2013-07-30 09:56:51,336: DEBUG [ I/O worker #84]         NettyAsyncHttpProvider - 
Using cached Channel [id: 0x061fb809, /172.25.62.60:21777 => corporate.proxy.com/172.16.99.10:8080]
 for request 
DefaultHttpRequest(chunked: false)
GET https://target.server.com/review/c425eb5b-3a69-4f45-8e2f-61ebd571fb1d HTTP/1.1
Host: stg.data.nokia.com
X-User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/28.0.1500.72 Safari/537.36
NLP.PP: 28923440-2ba3-498d-af22-6018a127aa7c
X-FWD-APP-ID: BDjctzFoFSCLGaDpE82O
X-FWD-Referer: http://localhost:8080/places/v1/places/250u09tu-4561b8da952f4fd79c4e1998c3fcf032?app_id=BDjctzFoFSCLGaDpE82O&app_code=NS9%2FOwu97RZCPj3d38KGsg%3D%3D
X-Forwarded-For: 0:0:0:0:0:0:0:1
X-NLP-Testing: 1
X-NLP-TID: 0f47e0f2-9c7e-4c55-8b96-60d806ae3057
NLP.PS: 2ea1dcb9-b396-4ddd-a4dd-8a8dbeb3c1d8
Accept-Encoding: gzip
Authorization: Basic NTgzOThhMGYxZTAyZmIyMjU3ZGZkYTFlMDAxZTU4MDI6NUpjdHFNTWJSd25kMGg0Y0k3NjdSTFppUXBRNTNrcHJpMWlRa0RDMGNWNmRBZGZ3cHMxR3V3ajVtUGM2R3VadA==
Connection: keep-alive
Proxy-Connection: keep-alive
Accept: */*
User-Agent: Places-1.1-pc-uc

2013-07-30 09:56:51,788: DEBUG [ I/O worker #84]         NettyAsyncHttpProvider - 

Request DefaultHttpRequest(chunked: false)
GET https://target.server.com/review/c425eb5b-3a69-4f45-8e2f-61ebd571fb1d HTTP/1.1
Host: stg.data.nokia.com
X-User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/28.0.1500.72 Safari/537.36
NLP.PP: 28923440-2ba3-498d-af22-6018a127aa7c
X-FWD-APP-ID: BDjctzFoFSCLGaDpE82O
X-FWD-Referer: http://localhost:8080/places/v1/places/250u09tu-4561b8da952f4fd79c4e1998c3fcf032?app_id=BDjctzFoFSCLGaDpE82O&app_code=NS9%2FOwu97RZCPj3d38KGsg%3D%3D
X-Forwarded-For: 0:0:0:0:0:0:0:1
X-NLP-Testing: 1
X-NLP-TID: 0f47e0f2-9c7e-4c55-8b96-60d806ae3057
NLP.PS: 2ea1dcb9-b396-4ddd-a4dd-8a8dbeb3c1d8
Accept-Encoding: gzip
Authorization: Basic NTgzOThhMGYxZTAyZmIyMjU3ZGZkYTFlMDAxZTU4MDI6NUpjdHFNTWJSd25kMGg0Y0k3NjdSTFppUXBRNTNrcHJpMWlRa0RDMGNWNmRBZGZ3cHMxR3V3ajVtUGM2R3VadA==
Connection: keep-alive
Proxy-Connection: keep-alive
Accept: */*
User-Agent: Places-1.1-pc-uc

Response DefaultHttpResponse(chunked: false)
HTTP/1.1 200 OK
Content-Language: en-US
Content-Type: application/json;charset=UTF-8
Date: Tue, 30 Jul 2013 07:56:37 GMT
ETag: 6c687a58ddf133fe1c5a08bcb2740a5174935622
Server: Apache-Coyote/1.1
Content-Length: 551
Connection: keep-alive

2013-07-30 09:56:51,788: DEBUG [ I/O worker #84]           NettyConnectionsPool - Adding uri: http://corporate.proxy.com:8080 for channel [id: 0x061fb809, /172.25.62.60:21777 => corporate.proxy.com/172.16.99.10:8080]
2013-07-30 09:56:51,788: INFO  [ I/O worker #84]                      upstreams - 0f47e0f2-9c7e-4c55-8b96-60d806ae3057 scbe GET "https://target.server.com/review/c425eb5b-3a69-4f45-8e2f-61ebd571fb1d" 200 551 4858 "X-User-Agent=Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/28.0.1500.72 Safari/537.36;NLP.PP=28923440-2ba3-498d-af22-6018a127aa7c;X-FWD-APP-ID=BDjctzFoFSCLGaDpE82O;X-FWD-Referer=http://localhost:8080/places/v1/places/250u09tu-4561b8da952f4fd79c4e1998c3fcf032?app_id=BDjctzFoFSCLGaDpE82O&app_code=NS9%2FOwu97RZCPj3d38KGsg%3D%3D;X-Forwarded-For=0:0:0:0:0:0:0:1;X-NLP-Testing=1;X-NLP-TID=0f47e0f2-9c7e-4c55-8b96-60d806ae3057;NLP.PS=2ea1dcb9-b396-4ddd-a4dd-8a8dbeb3c1d8"
============================================================================================================================================================================================