Return-Path: 
 <dev-return-29345-apmail-hc-dev-archive=hc.apache.org@hc.apache.org>
X-Original-To: apmail-hc-dev-archive@www.apache.org
Delivered-To: apmail-hc-dev-archive@www.apache.org
Received: from mail.apache.org (hermes.apache.org [140.211.11.3])
	by minotaur.apache.org (Postfix) with SMTP id 2E0E610C58
	for <apmail-hc-dev-archive@www.apache.org>;
 Sun,  5 Jan 2014 12:12:14 +0000 (UTC)
Received: (qmail 86577 invoked by uid 500); 5 Jan 2014 12:12:04 -0000
Delivered-To: apmail-hc-dev-archive@hc.apache.org
Received: (qmail 86469 invoked by uid 500); 5 Jan 2014 12:12:03 -0000
Mailing-List: contact dev-help@hc.apache.org; run by ezmlm
Precedence: bulk
List-Help: <mailto:dev-help@hc.apache.org>
List-Unsubscribe: <mailto:dev-unsubscribe@hc.apache.org>
List-Post: <mailto:dev@hc.apache.org>
List-Id: <dev.hc.apache.org>
Reply-To: "HttpComponents Project" <dev@hc.apache.org>
Delivered-To: mailing list dev@hc.apache.org
Received: (qmail 86432 invoked by uid 99); 5 Jan 2014 12:11:58 -0000
Received: from arcas.apache.org (HELO arcas.apache.org) (140.211.11.28)
    by apache.org (qpsmtpd/0.29) with ESMTP; Sun, 05 Jan 2014 12:11:58 +0000
Date: Sun, 5 Jan 2014 12:11:57 +0000 (UTC)
From: "Arthur Pilyuk (JIRA)" <jira@apache.org>
To: dev@hc.apache.org
Message-ID: <JIRA.12642262.1365782338752.44323.1388923917796@arcas>
In-Reply-To: <JIRA.12642262.1365782338752@arcas>
References: <JIRA.12642262.1365782338752@arcas>
Subject: [jira] [Comment Edited] (HTTPCLIENT-1340) Connection re-used in a
 inconsistent state despite 'Connection: close' after successful
 authentication
MIME-Version: 1.0
Content-Type: text/plain; charset=utf-8
Content-Transfer-Encoding: quoted-printable
X-JIRA-FingerPrint: 30527f35849b9dde25b450d4833f0394


    [ https://issues.apache.org/jira/browse/HTTPCLIENT-1340?page=3Dcom.atla=
ssian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=
=3D13862540#comment-13862540 ]=20

Arthur Pilyuk edited comment on HTTPCLIENT-1340 at 1/5/14 12:10 PM:
--------------------------------------------------------------------

Hi, guys!
I have same issue on httpclient:4.2.6.

<b>What I send:</b>
BODY [[{"operation":"createComponent","parameters":{"projectName":"Default"=
,"componentName":"CompName 0","groupId":"groupdId","artifactId":"artifactId=
","repositoryType":"ECAM","componentType":"repository","url":"repository","=
artifactVersion":"url"}, "requestId":"0.3667526548400687"}],{}]

What I get:
[[{"operation":"createComponent","parameters":{"projectName":"Default","com=
ponentName":"CompName 0","groupId":"groupdId","artifactId":"artifactId","re=
positoryType":"ECAM","componentType":"repository","url":"repository","artif=
actVersion":"url"}, "requestId":"0.30660520270012304"}],{}]
14:07:42,659 DEBUG BasicClientConnectionManager:159 - Get connection for ro=
ute {}->http://localhost:443
14:07:42,662 DEBUG DefaultClientConnectionOperator:177 - Connecting to loca=
lhost:443
14:07:42,666 DEBUG RequestAddCookies:132 - CookieSpec selected: ignoreCooki=
es
14:07:42,669 DEBUG RequestAuthCache:78 - Auth cache not set in the context
14:07:42,670 DEBUG RequestTargetAuthentication:78 - Target auth state: UNCH=
ALLENGED
14:07:42,671 DEBUG RequestProxyAuthentication:87 - Proxy auth state: UNCHAL=
LENGED
14:07:42,672 DEBUG DefaultHttpClient:713 - Attempt 1 to execute request
14:07:42,672 DEBUG DefaultClientConnection:269 - Sending request: POST /url=
 HTTP/1.1
14:07:42,673 DEBUG wire:63 - >> "POST /url HTTP/1.1[\r][\n]"
14:07:42,673 DEBUG wire:63 - >> "Accept: */*[\r][\n]"
14:07:42,674 DEBUG wire:63 - >> "Cookie: COMMANDER_SESSION_ID=3Ds3dqutba9jp=
kkc6mv8ml42h9u6[\r][\n]"
14:07:42,675 DEBUG wire:63 - >> "Content-Length: 284[\r][\n]"
14:07:42,675 DEBUG wire:63 - >> "Content-Type: application/octet-stream; ch=
arset=3DISO-8859-1[\r][\n]"
14:07:42,677 DEBUG wire:63 - >> "Host: localhost:443[\r][\n]"
14:07:42,678 DEBUG wire:63 - >> "Connection: Keep-Alive[\r][\n]"
14:07:42,679 DEBUG wire:63 - >> "User-Agent: Apache-HttpClient/4.2.6 (java =
1.5)[\r][\n]"
14:07:42,681 DEBUG wire:63 - >> "Accept-Encoding: gzip,deflate[\r][\n]"
14:07:42,682 DEBUG wire:63 - >> "[\r][\n]"
14:07:42,683 DEBUG headers:273 - >> POST /url HTTP/1.1
14:07:42,685 DEBUG headers:276 - >> Accept: */*
14:07:42,686 DEBUG headers:276 - >> Cookie: COMMANDER_SESSION_ID=3Ds3dqutba=
9jpkkc6mv8ml42h9u6
14:07:42,687 DEBUG headers:276 - >> Content-Length: 284
14:07:42,688 DEBUG headers:276 - >> Content-Type: application/octet-stream;=
 charset=3DISO-8859-1
14:07:42,689 DEBUG headers:276 - >> Host: localhost:443
14:07:42,690 DEBUG headers:276 - >> Connection: Keep-Alive
14:07:42,690 DEBUG headers:276 - >> User-Agent: Apache-HttpClient/4.2.6 (ja=
va 1.5)
14:07:42,691 DEBUG headers:276 - >> Accept-Encoding: gzip,deflate
14:07:42,692 DEBUG wire:77 - >> "[[{"operation":"createComponent","paramete=
rs":{"projectName":"Default","componentName":"CompName 0","groupId":"groupd=
Id","artifactId":"artifactId","repositoryType":"ECAM","componentType":"repo=
sitory","url":"repository","artifactVersion":"url"}, "requestId":"0.3066052=
0270012304"}],{}]"
14:07:42,694 DEBUG wire:63 - << "[0x19]??8j????X?[0xb]B[0x4]????|?C?,?@YB\[=
0x2][\r][\n]"
14:07:42,695 DEBUG DefaultHttpResponseParser:107 - Garbage in response: =19=
=C2=A5=C2=998j=C2=8B=C2=9C=C2=98=C3=B5X=C2=9E=0BB=04=C3=80=C2=87=C3=8D=C2=
=83|=C2=92C=C2=83,=C2=94@YB\=02
14:07:42,696 DEBUG wire:63 - << "?_[0xf]??o?u?o[0x14][0x16]???lO?i??d??;?a[=
0x1e]w??[\r][\n]"
14:07:42,698 DEBUG DefaultHttpResponseParser:107 - Garbage in response: =C2=
=BE_=0F=C3=A3=C2=B9o=C2=89u=C3=94o=14=16=C3=BC=C2=9D=C2=9ElO=C2=ADi=C2=86=
=C2=BFd=C2=85=C3=B2;=C2=B0a=1Ew=C2=BC=C3=BD
14:07:42,702 DEBUG wire:63 - << "s?Y?/[0x14]u"?`QN?l?:?8I???eR[0x1b][0x10]A=
~W[0x1c]?jS?9[0x16][0xf]t?(u.????[\r][\n]"
14:07:42,703 DEBUG DefaultHttpResponseParser:107 - Garbage in response: s=
=C2=88Y=C2=B6/=14u"=C2=AB`QN=C3=A7l=C3=AD:=C3=A38I=C3=A6=C2=9F=C3=84eR=1B=
=10A~W=1C=C3=9DjS=C3=A89=16=0Ft=C3=A0(u.=C2=A5=C2=BC=C2=90=C2=96
14:07:42,704 DEBUG DefaultClientConnection:169 - Connection 0.0.0.0:61789<-=
>127.0.0.1:443 closed
14:07:42,705 DEBUG DefaultClientConnection:154 - Connection 0.0.0.0:61789<-=
>127.0.0.1:443 shut down
14:07:42,706 DEBUG BasicClientConnectionManager:201 - Releasing connection =
org.apache.http.impl.conn.ManagedClientConnectionImpl@236e4a57

P.S. I am able to execute properly some other requests, like "create applic=
ation"
[[{"operation":"createApplication","parameters":{"projectName":"Default","a=
pplicationName":"AppName 0"}, "requestId":"0.3164679326105345"}],{}]=20
etc.

I think that problem is here:
- << "f??4[0xc]??????Ay9U?Q???c???????????U?[0x1b]????'????w[0x1d]?[\r]?:?8=
I???eR[0x1b][0x10]A~W[0x1c]?jS?9[0x16][0xf]tU???1???????)F=7F$E??p??el*?V?[=
\r][\n]"
=C3=AD:=C3=A38I=C3=A6=C2=9F=C3=84eR=1B=10A~W=1C=C3=9DjS=C3=A89=16=0FtU=C2=
=82=C2=8B=C3=801=C2=AE=C3=87=C3=8C=C3=8D=C2=9A=C3=83=C2=98)F=7F$E=C2=B2=C3=
=BBp=C2=AC=C3=88el*=C3=87V=C2=B2
But I don`t know how to identify reason of such behaviour.


was (Author: archi):
Hi, guys!
I have same issue on httpclient:4.2.6.

What I send:
BODY [[{"operation":"createComponent","parameters":{"projectName":"Default"=
,"componentName":"CompName 0","groupId":"groupdId","artifactId":"artifactId=
","repositoryType":"ECAM","componentType":"repository","url":"repository","=
artifactVersion":"url"}, "requestId":"0.3667526548400687"}],{}]

What I get:
[[{"operation":"createComponent","parameters":{"projectName":"Default","com=
ponentName":"CompName 0","groupId":"groupdId","artifactId":"artifactId","re=
positoryType":"ECAM","componentType":"repository","url":"repository","artif=
actVersion":"url"}, "requestId":"0.30660520270012304"}],{}]
14:07:42,659 DEBUG BasicClientConnectionManager:159 - Get connection for ro=
ute {}->http://localhost:443
14:07:42,662 DEBUG DefaultClientConnectionOperator:177 - Connecting to loca=
lhost:443
14:07:42,666 DEBUG RequestAddCookies:132 - CookieSpec selected: ignoreCooki=
es
14:07:42,669 DEBUG RequestAuthCache:78 - Auth cache not set in the context
14:07:42,670 DEBUG RequestTargetAuthentication:78 - Target auth state: UNCH=
ALLENGED
14:07:42,671 DEBUG RequestProxyAuthentication:87 - Proxy auth state: UNCHAL=
LENGED
14:07:42,672 DEBUG DefaultHttpClient:713 - Attempt 1 to execute request
14:07:42,672 DEBUG DefaultClientConnection:269 - Sending request: POST /url=
 HTTP/1.1
14:07:42,673 DEBUG wire:63 - >> "POST /url HTTP/1.1[\r][\n]"
14:07:42,673 DEBUG wire:63 - >> "Accept: */*[\r][\n]"
14:07:42,674 DEBUG wire:63 - >> "Cookie: COMMANDER_SESSION_ID=3Ds3dqutba9jp=
kkc6mv8ml42h9u6[\r][\n]"
14:07:42,675 DEBUG wire:63 - >> "Content-Length: 284[\r][\n]"
14:07:42,675 DEBUG wire:63 - >> "Content-Type: application/octet-stream; ch=
arset=3DISO-8859-1[\r][\n]"
14:07:42,677 DEBUG wire:63 - >> "Host: localhost:443[\r][\n]"
14:07:42,678 DEBUG wire:63 - >> "Connection: Keep-Alive[\r][\n]"
14:07:42,679 DEBUG wire:63 - >> "User-Agent: Apache-HttpClient/4.2.6 (java =
1.5)[\r][\n]"
14:07:42,681 DEBUG wire:63 - >> "Accept-Encoding: gzip,deflate[\r][\n]"
14:07:42,682 DEBUG wire:63 - >> "[\r][\n]"
14:07:42,683 DEBUG headers:273 - >> POST /url HTTP/1.1
14:07:42,685 DEBUG headers:276 - >> Accept: */*
14:07:42,686 DEBUG headers:276 - >> Cookie: COMMANDER_SESSION_ID=3Ds3dqutba=
9jpkkc6mv8ml42h9u6
14:07:42,687 DEBUG headers:276 - >> Content-Length: 284
14:07:42,688 DEBUG headers:276 - >> Content-Type: application/octet-stream;=
 charset=3DISO-8859-1
14:07:42,689 DEBUG headers:276 - >> Host: localhost:443
14:07:42,690 DEBUG headers:276 - >> Connection: Keep-Alive
14:07:42,690 DEBUG headers:276 - >> User-Agent: Apache-HttpClient/4.2.6 (ja=
va 1.5)
14:07:42,691 DEBUG headers:276 - >> Accept-Encoding: gzip,deflate
14:07:42,692 DEBUG wire:77 - >> "[[{"operation":"createComponent","paramete=
rs":{"projectName":"Default","componentName":"CompName 0","groupId":"groupd=
Id","artifactId":"artifactId","repositoryType":"ECAM","componentType":"repo=
sitory","url":"repository","artifactVersion":"url"}, "requestId":"0.3066052=
0270012304"}],{}]"
14:07:42,694 DEBUG wire:63 - << "[0x19]??8j????X?[0xb]B[0x4]????|?C?,?@YB\[=
0x2][\r][\n]"
14:07:42,695 DEBUG DefaultHttpResponseParser:107 - Garbage in response: =19=
=C2=A5=C2=998j=C2=8B=C2=9C=C2=98=C3=B5X=C2=9E=0BB=04=C3=80=C2=87=C3=8D=C2=
=83|=C2=92C=C2=83,=C2=94@YB\=02
14:07:42,696 DEBUG wire:63 - << "?_[0xf]??o?u?o[0x14][0x16]???lO?i??d??;?a[=
0x1e]w??[\r][\n]"
14:07:42,698 DEBUG DefaultHttpResponseParser:107 - Garbage in response: =C2=
=BE_=0F=C3=A3=C2=B9o=C2=89u=C3=94o=14=16=C3=BC=C2=9D=C2=9ElO=C2=ADi=C2=86=
=C2=BFd=C2=85=C3=B2;=C2=B0a=1Ew=C2=BC=C3=BD
14:07:42,702 DEBUG wire:63 - << "s?Y?/[0x14]u"?`QN?l?:?8I???eR[0x1b][0x10]A=
~W[0x1c]?jS?9[0x16][0xf]t?(u.????[\r][\n]"
14:07:42,703 DEBUG DefaultHttpResponseParser:107 - Garbage in response: s=
=C2=88Y=C2=B6/=14u"=C2=AB`QN=C3=A7l=C3=AD:=C3=A38I=C3=A6=C2=9F=C3=84eR=1B=
=10A~W=1C=C3=9DjS=C3=A89=16=0Ft=C3=A0(u.=C2=A5=C2=BC=C2=90=C2=96
14:07:42,704 DEBUG DefaultClientConnection:169 - Connection 0.0.0.0:61789<-=
>127.0.0.1:443 closed
14:07:42,705 DEBUG DefaultClientConnection:154 - Connection 0.0.0.0:61789<-=
>127.0.0.1:443 shut down
14:07:42,706 DEBUG BasicClientConnectionManager:201 - Releasing connection =
org.apache.http.impl.conn.ManagedClientConnectionImpl@236e4a57

P.S. I am able to execute properly some other requests, like "create applic=
ation"
[[{"operation":"createApplication","parameters":{"projectName":"Default","a=
pplicationName":"AppName 0"}, "requestId":"0.3164679326105345"}],{}]=20
etc.

I think that problem is here:
- << "f??4[0xc]??????Ay9U?Q???c???????????U?[0x1b]????'????w[0x1d]?[\r]?:?8=
I???eR[0x1b][0x10]A~W[0x1c]?jS?9[0x16][0xf]tU???1???????)F=7F$E??p??el*?V?[=
\r][\n]"
=C3=AD:=C3=A38I=C3=A6=C2=9F=C3=84eR=1B=10A~W=1C=C3=9DjS=C3=A89=16=0FtU=C2=
=82=C2=8B=C3=801=C2=AE=C3=87=C3=8C=C3=8D=C2=9A=C3=83=C2=98)F=7F$E=C2=B2=C3=
=BBp=C2=AC=C3=88el*=C3=87V=C2=B2
But I don`t know how to identify reason of such behaviour.

> Connection re-used in a inconsistent state despite 'Connection: close' af=
ter successful authentication=20
> -------------------------------------------------------------------------=
------------------------------
>
>                 Key: HTTPCLIENT-1340
>                 URL: https://issues.apache.org/jira/browse/HTTPCLIENT-134=
0
>             Project: HttpComponents HttpClient
>          Issue Type: Bug
>    Affects Versions: 4.2.4, 4.3 Beta1
>            Reporter: Igor Fedorenko
>            Priority: Critical
>             Fix For: 4.2.5
>
>         Attachments: HTTPCLIENT1340.java, hc4.log
>
>
> The problem was originally observed when using maven 3.0.5 and newer with=
 maven repositories served by nginx that has username/password authenticati=
on and connection keep-alive enabled.=20
> I was able to reproduce the problem using standalone test application tha=
t executes the same HttpGet request in a loop. Below is wire log of the las=
t two iterations that lead to "ProtocolException: The server failed to resp=
ond with a valid HTTP response". From the log the client apparently stopped=
 processing of the response with Connection:close heaader and then reused t=
he connection for the next request.
> {noformat}
> 2013/04/12 11:48:02:745 EDT [DEBUG] DownloadMain$DefaultHttpClientImpl - =
Stale connection check
> 2013/04/12 11:48:02:746 EDT [DEBUG] DownloadMain$DefaultHttpClientImpl - =
Attempt 1 to execute request
> 2013/04/12 11:48:02:747 EDT [DEBUG] wire - >> "GET /content/groups/sonaty=
pe-grid/.meta/prefixes.txt HTTP/1.1[\r][\n]"
> 2013/04/12 11:48:02:747 EDT [DEBUG] wire - >> "Host: repository.sonatype.=
org[\r][\n]"
> 2013/04/12 11:48:02:747 EDT [DEBUG] wire - >> "Connection: Keep-Alive[\r]=
[\n]"
> 2013/04/12 11:48:02:747 EDT [DEBUG] wire - >> "[\r][\n]"
> 2013/04/12 11:48:02:782 EDT [DEBUG] wire - << "HTTP/1.1 401 Unauthorized[=
\r][\n]"
> 2013/04/12 11:48:02:782 EDT [DEBUG] wire - << "Server: nginx/0.8.54[\r][\=
n]"
> 2013/04/12 11:48:02:782 EDT [DEBUG] wire - << "Date: Fri, 12 Apr 2013 15:=
48:02 GMT[\r][\n]"
> 2013/04/12 11:48:02:782 EDT [DEBUG] wire - << "Connection: keep-alive[\r]=
[\n]"
> 2013/04/12 11:48:02:782 EDT [DEBUG] wire - << "Keep-Alive: timeout=3D5[\r=
][\n]"
> 2013/04/12 11:48:02:783 EDT [DEBUG] wire - << "Set-Cookie: rememberMe=3Dd=
eleteMe; Path=3D/; Max-Age=3D0; Expires=3DThu, 11-Apr-2013 15:48:02 GMT[\r]=
[\n]"
> 2013/04/12 11:48:02:783 EDT [DEBUG] wire - << "WWW-Authenticate: BASIC re=
alm=3D"Sonatype Nexus Repository Manager"[\r][\n]"
> 2013/04/12 11:48:02:783 EDT [DEBUG] wire - << "Content-Length: 0[\r][\n]"
> 2013/04/12 11:48:02:783 EDT [DEBUG] wire - << "[\r][\n]"
> 2013/04/12 11:48:02:783 EDT [DEBUG] DownloadMain$DefaultHttpClientImpl - =
Connection can be kept alive for 5000 MILLISECONDS
> 2013/04/12 11:48:02:783 EDT [DEBUG] DownloadMain$DefaultHttpClientImpl - =
Authentication required
> 2013/04/12 11:48:02:783 EDT [DEBUG] DownloadMain$DefaultHttpClientImpl - =
repository.sonatype.org:443 requested authentication
> 2013/04/12 11:48:02:795 EDT [DEBUG] DownloadMain$DefaultHttpClientImpl - =
Selected authentication options: [BASIC]
> 2013/04/12 11:48:02:796 EDT [DEBUG] DownloadMain$DefaultHttpClientImpl - =
Attempt 2 to execute request
> 2013/04/12 11:48:02:796 EDT [DEBUG] wire - >> "GET /content/groups/sonaty=
pe-grid/.meta/prefixes.txt HTTP/1.1[\r][\n]"
> 2013/04/12 11:48:02:796 EDT [DEBUG] wire - >> "Host: repository.sonatype.=
org[\r][\n]"
> 2013/04/12 11:48:02:796 EDT [DEBUG] wire - >> "Connection: Keep-Alive[\r]=
[\n]"
> 2013/04/12 11:48:02:796 EDT [DEBUG] wire - >> "Authorization: Basic XXX [=
\r][\n]"
> 2013/04/12 11:48:02:796 EDT [DEBUG] wire - >> "[\r][\n]"
> 2013/04/12 11:48:02:840 EDT [DEBUG] wire - << "HTTP/1.1 200 OK[\r][\n]"
> 2013/04/12 11:48:02:840 EDT [DEBUG] wire - << "Server: nginx/0.8.54[\r][\=
n]"
> 2013/04/12 11:48:02:840 EDT [DEBUG] wire - << "Date: Fri, 12 Apr 2013 15:=
48:02 GMT[\r][\n]"
> 2013/04/12 11:48:02:840 EDT [DEBUG] wire - << "Content-Type: text/plain[\=
r][\n]"
> 2013/04/12 11:48:02:840 EDT [DEBUG] wire - << "Connection: keep-alive[\r]=
[\n]"
> 2013/04/12 11:48:02:840 EDT [DEBUG] wire - << "Keep-Alive: timeout=3D5[\r=
][\n]"
> 2013/04/12 11:48:02:840 EDT [DEBUG] wire - << "Set-Cookie: rememberMe=3Dd=
eleteMe; Path=3D/; Max-Age=3D0; Expires=3DThu, 11-Apr-2013 15:48:02 GMT[\r]=
[\n]"
> 2013/04/12 11:48:02:840 EDT [DEBUG] wire - << "Last-Modified: Fri, 12 Apr=
 2013 08:22:54 GMT[\r][\n]"
> 2013/04/12 11:48:02:840 EDT [DEBUG] wire - << "ETag: "{SHA1{3cf8e81cedbd2=
85b8c745289d7184315762ca578}}"[\r][\n]"
> 2013/04/12 11:48:02:841 EDT [DEBUG] wire - << "Vary: Accept-Charset, Acce=
pt-Encoding, Accept-Language, Accept[\r][\n]"
> 2013/04/12 11:48:02:841 EDT [DEBUG] wire - << "X-Content-Type-Options: no=
sniff[\r][\n]"
> 2013/04/12 11:48:02:841 EDT [DEBUG] wire - << "Content-Length: 100[\r][\n=
]"
> 2013/04/12 11:48:02:841 EDT [DEBUG] wire - << "[\r][\n]"
> 2013/04/12 11:48:02:841 EDT [DEBUG] DownloadMain$DefaultHttpClientImpl - =
Connection can be kept alive for 5000 MILLISECONDS
> 2013/04/12 11:48:02:841 EDT [DEBUG] DownloadMain$DefaultHttpClientImpl - =
Authentication succeeded
> 2013/04/12 11:48:02:841 EDT [DEBUG] wire - << "@ unsupported[\n]"
> 2013/04/12 11:48:02:841 EDT [DEBUG] wire - << "# Prefix file generated by=
 Sonatype Nexus[\n]"
> 2013/04/12 11:48:02:841 EDT [DEBUG] wire - << "# Do not edit, changes wil=
l be overwritten![\n]"
> 2013/04/12 11:48:02:841 EDT [DEBUG] DownloadMain$DefaultHttpClientImpl - =
Stale connection check
> 2013/04/12 11:48:02:843 EDT [DEBUG] DownloadMain$DefaultHttpClientImpl - =
Attempt 1 to execute request
> 2013/04/12 11:48:02:843 EDT [DEBUG] wire - >> "GET /content/groups/sonaty=
pe-grid/.meta/prefixes.txt HTTP/1.1[\r][\n]"
> 2013/04/12 11:48:02:843 EDT [DEBUG] wire - >> "Host: repository.sonatype.=
org[\r][\n]"
> 2013/04/12 11:48:02:843 EDT [DEBUG] wire - >> "Connection: Keep-Alive[\r]=
[\n]"
> 2013/04/12 11:48:02:843 EDT [DEBUG] wire - >> "[\r][\n]"
> 2013/04/12 11:48:02:878 EDT [DEBUG] wire - << "HTTP/1.1 401 Unauthorized[=
\r][\n]"
> 2013/04/12 11:48:02:878 EDT [DEBUG] wire - << "Server: nginx/0.8.54[\r][\=
n]"
> 2013/04/12 11:48:02:878 EDT [DEBUG] wire - << "Date: Fri, 12 Apr 2013 15:=
48:02 GMT[\r][\n]"
> 2013/04/12 11:48:02:878 EDT [DEBUG] wire - << "Connection: keep-alive[\r]=
[\n]"
> 2013/04/12 11:48:02:878 EDT [DEBUG] wire - << "Keep-Alive: timeout=3D5[\r=
][\n]"
> 2013/04/12 11:48:02:878 EDT [DEBUG] wire - << "Set-Cookie: rememberMe=3Dd=
eleteMe; Path=3D/; Max-Age=3D0; Expires=3DThu, 11-Apr-2013 15:48:02 GMT[\r]=
[\n]"
> 2013/04/12 11:48:02:878 EDT [DEBUG] wire - << "WWW-Authenticate: BASIC re=
alm=3D"Sonatype Nexus Repository Manager"[\r][\n]"
> 2013/04/12 11:48:02:878 EDT [DEBUG] wire - << "Content-Length: 0[\r][\n]"
> 2013/04/12 11:48:02:878 EDT [DEBUG] wire - << "[\r][\n]"
> 2013/04/12 11:48:02:879 EDT [DEBUG] DownloadMain$DefaultHttpClientImpl - =
Connection can be kept alive for 5000 MILLISECONDS
> 2013/04/12 11:48:02:879 EDT [DEBUG] DownloadMain$DefaultHttpClientImpl - =
Authentication required
> 2013/04/12 11:48:02:879 EDT [DEBUG] DownloadMain$DefaultHttpClientImpl - =
repository.sonatype.org:443 requested authentication
> 2013/04/12 11:48:02:879 EDT [DEBUG] DownloadMain$DefaultHttpClientImpl - =
Selected authentication options: [BASIC]
> 2013/04/12 11:48:02:879 EDT [DEBUG] DownloadMain$DefaultHttpClientImpl - =
Attempt 2 to execute request
> 2013/04/12 11:48:02:879 EDT [DEBUG] wire - >> "GET /content/groups/sonaty=
pe-grid/.meta/prefixes.txt HTTP/1.1[\r][\n]"
> 2013/04/12 11:48:02:879 EDT [DEBUG] wire - >> "Host: repository.sonatype.=
org[\r][\n]"
> 2013/04/12 11:48:02:879 EDT [DEBUG] wire - >> "Connection: Keep-Alive[\r]=
[\n]"
> 2013/04/12 11:48:02:879 EDT [DEBUG] wire - >> "Authorization: Basic XXX [=
\r][\n]"
> 2013/04/12 11:48:02:879 EDT [DEBUG] wire - >> "[\r][\n]"
> 2013/04/12 11:48:02:924 EDT [DEBUG] wire - << "HTTP/1.1 200 OK[\r][\n]"
> 2013/04/12 11:48:02:924 EDT [DEBUG] wire - << "Server: nginx/0.8.54[\r][\=
n]"
> 2013/04/12 11:48:02:924 EDT [DEBUG] wire - << "Date: Fri, 12 Apr 2013 15:=
48:02 GMT[\r][\n]"
> 2013/04/12 11:48:02:924 EDT [DEBUG] wire - << "Content-Type: text/plain[\=
r][\n]"
> 2013/04/12 11:48:02:925 EDT [DEBUG] wire - << "Connection: close[\r][\n]"
> 2013/04/12 11:48:02:925 EDT [DEBUG] wire - << "Set-Cookie: rememberMe=3Dd=
eleteMe; Path=3D/; Max-Age=3D0; Expires=3DThu, 11-Apr-2013 15:48:02 GMT[\r]=
[\n]"
> 2013/04/12 11:48:02:925 EDT [DEBUG] wire - << "Last-Modified: Fri, 12 Apr=
 2013 08:22:54 GMT[\r][\n]"
> 2013/04/12 11:48:02:925 EDT [DEBUG] wire - << "ETag: "{SHA1{3cf8e81cedbd2=
85b8c745289d7184315762ca578}}"[\r][\n]"
> 2013/04/12 11:48:02:925 EDT [DEBUG] wire - << "Vary: Accept-Charset, Acce=
pt-Encoding, Accept-Language, Accept[\r][\n]"
> 2013/04/12 11:48:02:925 EDT [DEBUG] wire - << "X-Content-Type-Options: no=
sniff[\r][\n]"
> 2013/04/12 11:48:02:925 EDT [DEBUG] wire - << "Content-Length: 100[\r][\n=
]"
> 2013/04/12 11:48:02:925 EDT [DEBUG] wire - << "[\r][\n]"
> 2013/04/12 11:48:02:925 EDT [DEBUG] DownloadMain$DefaultHttpClientImpl - =
Authentication succeeded
> 2013/04/12 11:48:02:925 EDT [DEBUG] DownloadMain$DefaultHttpClientImpl - =
Stale connection check
> 2013/04/12 11:48:02:926 EDT [DEBUG] DownloadMain$DefaultHttpClientImpl - =
Attempt 1 to execute request
> 2013/04/12 11:48:02:926 EDT [DEBUG] wire - >> "GET /content/groups/sonaty=
pe-grid/.meta/prefixes.txt HTTP/1.1[\r][\n]"
> 2013/04/12 11:48:02:926 EDT [DEBUG] wire - >> "Host: repository.sonatype.=
org[\r][\n]"
> 2013/04/12 11:48:02:926 EDT [DEBUG] wire - >> "Connection: Keep-Alive[\r]=
[\n]"
> 2013/04/12 11:48:02:926 EDT [DEBUG] wire - >> "[\r][\n]"
> 2013/04/12 11:48:02:926 EDT [DEBUG] wire - << "@ unsupported[\r][\n]"
> 2013/04/12 11:48:02:926 EDT [DEBUG] wire - << "# Prefix file generated by=
 Sonatype Nexus[\r][\n]"
> 2013/04/12 11:48:02:926 EDT [DEBUG] wire - << "# Do not edit, changes wil=
l be overwritten![\r][\n]"
> 50 4975
> Exception in thread "main" org.apache.http.client.ClientProtocolException
> =09at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttp=
Client.java:909)
> =09at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttp=
Client.java:805)
> =09at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttp=
Client.java:784)
> =09at download.httpclient.ssl.DownloadMain.run(DownloadMain.java:80)
> =09at download.httpclient.ssl.DownloadMain.main(DownloadMain.java:31)
> Caused by: org.apache.http.ProtocolException: The server failed to respon=
d with a valid HTTP response
> =09at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(Defau=
ltHttpResponseParser.java:103)
> =09at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(Defau=
ltHttpResponseParser.java:62)
> =09at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessage=
Parser.java:254)
> =09at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHe=
ader(AbstractHttpClientConnection.java:289)
> =09at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHe=
ader(DefaultClientConnection.java:252)
> =09at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveRespon=
seHeader(ManagedClientConnectionImpl.java:191)
> =09at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(Http=
RequestExecutor.java:300)
> =09at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExe=
cutor.java:127)
> =09at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(Defau=
ltRequestDirector.java:716)
> =09at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultR=
equestDirector.java:521)
> =09at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttp=
Client.java:906)
> =09... 4 more
> {noformat}



--
This message was sent by Atlassian JIRA
(v6.1.5#6160)

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