On Mon, 2013-12-16 at 20:20 +0000, Boxer, Aaron wrote:
> Hello,
> I am using the sardine java WebDav client (which uses HTTPClient) to send
> files using WebDav protocol to an IIS 7.5 server.
> Authentication is Basic.
>
> I always get the connection closed with a socket connection on my *first* put
> to the server; any subsequent puts are fine.
>
> Below is a debug trace for sending two files.
>
> Thanks!
> Aaron
>
Looks really like a server side issue.
Can you tell me what version of HttpClient is being used?
Oleg
> ////////////////////////////////////////////////////////////////////////////////////////////////
>
> 2013/12/16 15:20:11:971 EST [DEBUG] PoolingClientConnectionManager -
> Connection request: [route: {}->http://scaramouche:80][total kept alive: 0;
> route allocated: 0 of 2; total allocated: 0 of 20]
> 2013/12/16 15:20:11:971 EST [DEBUG] PoolingClientConnectionManager -
> Connection leased: [id: 2][route: {}->http://scaramouche:80][total kept
> alive: 0; route allocated: 1 of 2; total allocated: 1 of 20]
> 2013/12/16 15:20:11:971 EST [DEBUG] DefaultClientConnectionOperator -
> Connecting to scaramouche:80
> 2013/12/16 15:20:11:972 EST [DEBUG] RequestAddCookies - CookieSpec selected:
> best-match
> 2013/12/16 15:20:11:972 EST [DEBUG] RequestAuthCache - Auth cache not set in
> the context
> 2013/12/16 15:20:11:972 EST [DEBUG] RequestTargetAuthentication - Target auth
> state: UNCHALLENGED
> 2013/12/16 15:20:11:972 EST [DEBUG] RequestProxyAuthentication - Proxy auth
> state: UNCHALLENGED
> 2013/12/16 15:20:11:972 EST [DEBUG] DefaultHttpClient - Attempt 1 to execute
> request
> 2013/12/16 15:20:11:972 EST [DEBUG] DefaultClientConnection - Sending
> request: PUT /upload/0ab3aedd-5efe-428c-a791-f8e38885c874.1 HTTP/1.1
> 2013/12/16 15:20:11:972 EST [DEBUG] headers - >> PUT
> /upload/0ab3aedd-5efe-428c-a791-f8e38885c874.1 HTTP/1.1
> 2013/12/16 15:20:11:972 EST [DEBUG] headers - >> Expect: 100-continue
> 2013/12/16 15:20:11:972 EST [DEBUG] headers - >> Content-Type: ISO-8859-1
> 2013/12/16 15:20:11:972 EST [DEBUG] headers - >> Transfer-Encoding: chunked
> 2013/12/16 15:20:11:972 EST [DEBUG] headers - >> Host: scaramouche:80
> 2013/12/16 15:20:11:972 EST [DEBUG] headers - >> Connection: Keep-Alive
> 2013/12/16 15:20:11:972 EST [DEBUG] headers - >> User-Agent:
> Sardine/UNAVAILABLE
> 2013/12/16 15:20:11:974 EST [DEBUG] DefaultClientConnection - Receiving
> response: HTTP/1.1 401 Unauthorized
> 2013/12/16 15:20:11:975 EST [DEBUG] headers - << HTTP/1.1 401 Unauthorized
> 2013/12/16 15:20:11:975 EST [DEBUG] headers - << Cache-Control: private
> 2013/12/16 15:20:11:975 EST [DEBUG] headers - << Content-Type: text/html;
> charset=utf-8
> 2013/12/16 15:20:11:975 EST [DEBUG] headers - << Server: Microsoft-IIS/7.5
> 2013/12/16 15:20:11:975 EST [DEBUG] headers - << WWW-Authenticate: Basic
> realm="scaramouche"
> 2013/12/16 15:20:11:975 EST [DEBUG] headers - << X-Powered-By: ASP.NET
> 2013/12/16 15:20:11:975 EST [DEBUG] headers - << Date: Mon, 16 Dec 2013
> 20:20:11 GMT
> 2013/12/16 15:20:11:975 EST [DEBUG] headers - << Content-Length: 6321
> 2013/12/16 15:20:11:975 EST [DEBUG] DefaultHttpClient - Connection can be
> kept alive indefinitely
> 2013/12/16 15:20:11:975 EST [DEBUG] DefaultHttpClient - Authentication
> required
> 2013/12/16 15:20:11:975 EST [DEBUG] DefaultHttpClient - scaramouche:80
> requested authentication
> 2013/12/16 15:20:11:975 EST [DEBUG] TargetAuthenticationStrategy -
> Authentication schemes in the order of preference: [negotiate, Kerberos,
> NTLM, Digest, Basic]
> 2013/12/16 15:20:11:975 EST [DEBUG] TargetAuthenticationStrategy - Challenge
> for negotiate authentication scheme not available
> 2013/12/16 15:20:11:975 EST [DEBUG] TargetAuthenticationStrategy - Challenge
> for Kerberos authentication scheme not available
> 2013/12/16 15:20:11:975 EST [DEBUG] TargetAuthenticationStrategy - Challenge
> for NTLM authentication scheme not available
> 2013/12/16 15:20:11:975 EST [DEBUG] TargetAuthenticationStrategy - Challenge
> for Digest authentication scheme not available
> 2013/12/16 15:20:11:975 EST [DEBUG] DefaultHttpClient - Selected
> authentication options: [BASIC]
> 2013/12/16 15:20:11:975 EST [DEBUG] RequestAddCookies - CookieSpec selected:
> best-match
> 2013/12/16 15:20:11:975 EST [DEBUG] RequestAuthCache - Auth cache not set in
> the context
> 2013/12/16 15:20:11:975 EST [DEBUG] RequestTargetAuthentication - Target auth
> state: CHALLENGED
> 2013/12/16 15:20:11:975 EST [DEBUG] RequestTargetAuthentication - Generating
> response to an authentication challenge using basic scheme
> 2013/12/16 15:20:11:976 EST [DEBUG] RequestProxyAuthentication - Proxy auth
> state: UNCHALLENGED
> 2013/12/16 15:20:11:976 EST [DEBUG] DefaultHttpClient - Attempt 2 to execute
> request
> 2013/12/16 15:20:11:976 EST [DEBUG] DefaultClientConnection - Sending
> request: PUT /upload/0ab3aedd-5efe-428c-a791-f8e38885c874.1 HTTP/1.1
> 2013/12/16 15:20:11:976 EST [DEBUG] headers - >> PUT
> /upload/0ab3aedd-5efe-428c-a791-f8e38885c874.1 HTTP/1.1
> 2013/12/16 15:20:11:976 EST [DEBUG] headers - >> Expect: 100-continue
> 2013/12/16 15:20:11:976 EST [DEBUG] headers - >> Content-Type: ISO-8859-1
> 2013/12/16 15:20:11:976 EST [DEBUG] headers - >> Transfer-Encoding: chunked
> 2013/12/16 15:20:11:976 EST [DEBUG] headers - >> Host: scaramouche:80
> 2013/12/16 15:20:11:976 EST [DEBUG] headers - >> Connection: Keep-Alive
> 2013/12/16 15:20:11:976 EST [DEBUG] headers - >> User-Agent:
> Sardine/UNAVAILABLE
> 2013/12/16 15:20:11:976 EST [DEBUG] headers - >> Authorization: Basic
> amRtaTpCYW5hbmExMSEhAAAAAAAA
> 2013/12/16 15:20:11:978 EST [DEBUG] DefaultClientConnection - Connection
> 0.0.0.0:55055<->10.7.11.181:80 closed
> 2013/12/16 15:20:11:978 EST [DEBUG] DefaultHttpClient - Closing the
> connection.
> 2013/12/16 15:20:11:978 EST [DEBUG] DefaultClientConnection - Connection
> 0.0.0.0:55055<->10.7.11.181:80 closed
> 2013/12/16 15:20:11:978 EST [INFO] DefaultHttpClient - I/O exception
> (java.net.SocketException) caught when processing request: Connection reset
> 2013/12/16 15:20:11:978 EST [DEBUG] DefaultHttpClient - Connection reset
> <java.net.SocketException: Connection reset>java.net.SocketException:
> Connection reset
> at java.net.SocketInputStream.read(SocketInputStream.java:196)
> at java.net.SocketInputStream.read(SocketInputStream.java:122)
> at
> org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160)
> at
> org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84)
> at
> org.apache.http.impl.io.SocketInputBuffer.isDataAvailable(SocketInputBuffer.java:95)
> at
> org.apache.http.impl.AbstractHttpClientConnection.isResponseAvailable(AbstractHttpClientConnection.java:244)
> at
> org.apache.http.impl.conn.ManagedClientConnectionImpl.isResponseAvailable(ManagedClientConnectionImpl.java:186)
> at
> org.apache.http.protocol.HttpRequestExecutor.doSendRequest(HttpRequestExecutor.java:217)
> at
> org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:121)
> at
> org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682)
> at
> org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486)
> at
> org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863)
> at
> org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:72)
> at
> org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:214)
> at
> org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:160)
> at com.github.sardine.impl.SardineImpl.execute(SardineImpl.java:807)
> at com.github.sardine.impl.SardineImpl.put(SardineImpl.java:739)
> at com.github.sardine.impl.SardineImpl.put(SardineImpl.java:722)
> at com.github.sardine.impl.SardineImpl.put(SardineImpl.java:710)
> at com.github.sardine.impl.SardineImpl.put(SardineImpl.java:680)
> at com.github.sardine.impl.SardineImpl.put(SardineImpl.java:673)
> at com.github.sardine.impl.SardineImpl.put(SardineImpl.java:666)
> at com.github.sardine.impl.SardineImpl.put(SardineImpl.java:660)
> at
> wjhk.jupload2.upload.FileUploadThreadWebDAV.beforeFile(FileUploadThreadWebDAV.java:157)
> at
> wjhk.jupload2.upload.DefaultFileUploadThread.doNonChunkedUpload(DefaultFileUploadThread.java:694)
> at
> wjhk.jupload2.upload.DefaultFileUploadThread.doUpload(DefaultFileUploadThread.java:527)
> at
> wjhk.jupload2.upload.DefaultFileUploadThread.run(DefaultFileUploadThread.java:336)
>
> 2013/12/16 15:20:11:978 EST [INFO] DefaultHttpClient - Retrying request
> 2013/12/16 15:20:11:978 EST [DEBUG] DefaultHttpClient - Reopening the direct
> connection.
> 2013/12/16 15:20:11:978 EST [DEBUG] DefaultClientConnectionOperator -
> Connecting to scaramouche:80
> 2013/12/16 15:20:11:979 EST [DEBUG] DefaultHttpClient - Attempt 3 to execute
> request
> 2013/12/16 15:20:11:979 EST [DEBUG] DefaultClientConnection - Sending
> request: PUT /upload/0ab3aedd-5efe-428c-a791-f8e38885c874.1 HTTP/1.1
> 2013/12/16 15:20:11:979 EST [DEBUG] headers - >> PUT
> /upload/0ab3aedd-5efe-428c-a791-f8e38885c874.1 HTTP/1.1
> 2013/12/16 15:20:11:979 EST [DEBUG] headers - >> Expect: 100-continue
> 2013/12/16 15:20:11:979 EST [DEBUG] headers - >> Content-Type: ISO-8859-1
> 2013/12/16 15:20:11:979 EST [DEBUG] headers - >> Transfer-Encoding: chunked
> 2013/12/16 15:20:11:979 EST [DEBUG] headers - >> Host: scaramouche:80
> 2013/12/16 15:20:11:979 EST [DEBUG] headers - >> Connection: Keep-Alive
> 2013/12/16 15:20:11:979 EST [DEBUG] headers - >> User-Agent:
> Sardine/UNAVAILABLE
> 2013/12/16 15:20:11:979 EST [DEBUG] headers - >> Authorization: Basic
> amRtaTpCYW5hbmExMSEhAAAAAAAA
> 2013/12/16 15:20:11:980 EST [DEBUG] DefaultClientConnection - Receiving
> response: HTTP/1.1 100 Continue
> 2013/12/16 15:20:11:980 EST [DEBUG] headers - << HTTP/1.1 100 Continue
> 2013/12/16 15:20:12:118 EST [DEBUG] DefaultClientConnection - Receiving
> response: HTTP/1.1 201 Created
> 2013/12/16 15:20:12:118 EST [DEBUG] headers - << HTTP/1.1 201 Created
> 2013/12/16 15:20:12:118 EST [DEBUG] headers - << Server: Microsoft-IIS/7.5
> 2013/12/16 15:20:12:118 EST [DEBUG] headers - << X-Powered-By: ASP.NET
> 2013/12/16 15:20:12:118 EST [DEBUG] headers - << Date: Mon, 16 Dec 2013
> 20:20:11 GMT
> 2013/12/16 15:20:12:118 EST [DEBUG] headers - << Content-Length: 0
> 2013/12/16 15:20:12:118 EST [DEBUG] DefaultHttpClient - Connection can be
> kept alive indefinitely
> 2013/12/16 15:20:12:119 EST [DEBUG] DefaultHttpClient - Authentication
> succeeded
> 2013/12/16 15:20:12:119 EST [DEBUG] TargetAuthenticationStrategy - Caching
> 'basic' auth scheme for http://scaramouche:80
> 2013/12/16 15:20:12:119 EST [DEBUG] PoolingClientConnectionManager -
> Connection [id: 2][route: {}->http://scaramouche:80] can be kept alive
> indefinitely
> 2013/12/16 15:20:12:119 EST [DEBUG] PoolingClientConnectionManager -
> Connection released: [id: 2][route: {}->http://scaramouche:80][total kept
> alive: 1; route allocated: 1 of 2; total allocated: 1 of 20]
> 2013/12/16 15:20:12:119 EST [DEBUG] PoolingClientConnectionManager -
> Connection request: [route: {}->http://scaramouche:80][total kept alive: 1;
> route allocated: 1 of 2; total allocated: 1 of 20]
> 2013/12/16 15:20:12:119 EST [DEBUG] PoolingClientConnectionManager -
> Connection leased: [id: 2][route: {}->http://scaramouche:80][total kept
> alive: 0; route allocated: 1 of 2; total allocated: 1 of 20]
> 2013/12/16 15:20:12:119 EST [DEBUG] DefaultHttpClient - Stale connection check
> 2013/12/16 15:20:12:120 EST [DEBUG] RequestAddCookies - CookieSpec selected:
> best-match
> 2013/12/16 15:20:12:120 EST [DEBUG] RequestAuthCache - Re-using cached
> 'basic' auth scheme for http://scaramouche:80
> 2013/12/16 15:20:12:120 EST [DEBUG] RequestTargetAuthentication - Target auth
> state: CHALLENGED
> 2013/12/16 15:20:12:120 EST [DEBUG] RequestProxyAuthentication - Proxy auth
> state: UNCHALLENGED
> 2013/12/16 15:20:12:120 EST [DEBUG] DefaultHttpClient - Attempt 1 to execute
> request
> 2013/12/16 15:20:12:120 EST [DEBUG] DefaultClientConnection - Sending
> request: PUT /upload/0ab3aedd-5efe-428c-a791-f8e38885c874.2 HTTP/1.1
> 2013/12/16 15:20:12:120 EST [DEBUG] headers - >> PUT
> /upload/0ab3aedd-5efe-428c-a791-f8e38885c874.2 HTTP/1.1
> 2013/12/16 15:20:12:120 EST [DEBUG] headers - >> Expect: 100-continue
> 2013/12/16 15:20:12:120 EST [DEBUG] headers - >> Content-Type: ISO-8859-1
> 2013/12/16 15:20:12:120 EST [DEBUG] headers - >> Transfer-Encoding: chunked
> 2013/12/16 15:20:12:120 EST [DEBUG] headers - >> Host: scaramouche:80
> 2013/12/16 15:20:12:120 EST [DEBUG] headers - >> Connection: Keep-Alive
> 2013/12/16 15:20:12:120 EST [DEBUG] headers - >> User-Agent:
> Sardine/UNAVAILABLE
> 2013/12/16 15:20:12:120 EST [DEBUG] headers - >> Authorization: Basic
> amRtaTpCYW5hbmExMSEhAAAAAAAA
> 2013/12/16 15:20:12:121 EST [DEBUG] DefaultClientConnection - Receiving
> response: HTTP/1.1 100 Continue
> 2013/12/16 15:20:12:121 EST [DEBUG] headers - << HTTP/1.1 100 Continue
> 2013/12/16 15:20:12:168 EST [DEBUG] DefaultClientConnection - Receiving
> response: HTTP/1.1 201 Created
> 2013/12/16 15:20:12:168 EST [DEBUG] headers - << HTTP/1.1 201 Created
> 2013/12/16 15:20:12:168 EST [DEBUG] headers - << Server: Microsoft-IIS/7.5
> 2013/12/16 15:20:12:168 EST [DEBUG] headers - << X-Powered-By: ASP.NET
> 2013/12/16 15:20:12:168 EST [DEBUG] headers - << Date: Mon, 16 Dec 2013
> 20:20:11 GMT
> 2013/12/16 15:20:12:168 EST [DEBUG] headers - << Content-Length: 0
> 2013/12/16 15:20:12:168 EST [DEBUG] DefaultHttpClient - Connection can be
> kept alive indefinitely
> 2013/12/16 15:20:12:168 EST [DEBUG] DefaultHttpClient - Authentication
> succeeded
> 2013/12/16 15:20:12:168 EST [DEBUG] TargetAuthenticationStrategy - Caching
> 'basic' auth scheme for http://scaramouche:80
> 2013/12/16 15:20:12:168 EST [DEBUG] PoolingClientConnectionManager -
> Connection [id: 2][route: {}->http://scaramouche:80] can be kept alive
> indefinitely
> 2013/12/16 15:20:12:168 EST [DEBUG] PoolingClientConnectionManager -
> Connection released: [id: 2][route: {}->http://scaramouche:80][total kept
> alive: 1; route allocated: 1 of 2; total allocated: 1 of 20]
> 2013/12/16 15:20:12:169 EST [DEBUG] PoolingClientConnectionManager -
> Connection request: [route: {}->http://scaramouche:80][total kept alive: 1;
> route allocated: 1 of 2; total allocated: 1 of 20]
> 2013/12/16 15:20:12:169 EST [DEBUG] PoolingClientConnectionManager -
> Connection leased: [id: 2][route: {}->http://scaramouche:80][total kept
> alive: 0; route allocated: 1 of 2; total allocated: 1 of 20]
> 2013/12/16 15:20:12:169 EST [DEBUG] DefaultHttpClient - Stale connection check
> 2013/12/16 15:20:12:170 EST [DEBUG] RequestAddCookies - CookieSpec selected:
> best-match
> 2013/12/16 15:20:12:170 EST [DEBUG] RequestAuthCache - Re-using cached
> 'basic' auth scheme for http://scaramouche:80
> 2013/12/16 15:20:12:170 EST [DEBUG] RequestTargetAuthentication - Target auth
> state: CHALLENGED
> 2013/12/16 15:20:12:170 EST [DEBUG] RequestProxyAuthentication - Proxy auth
> state: UNCHALLENGED
> 2013/12/16 15:20:12:170 EST [DEBUG] DefaultHttpClient - Attempt 1 to execute
> request
> 2013/12/16 15:20:12:170 EST [DEBUG] DefaultClientConnection - Sending
> request: PUT /upload/0ab3aedd-5efe-428c-a791-f8e38885c874.info HTTP/1.1
> 2013/12/16 15:20:12:170 EST [DEBUG] headers - >> PUT
> /upload/0ab3aedd-5efe-428c-a791-f8e38885c874.info HTTP/1.1
> 2013/12/16 15:20:12:170 EST [DEBUG] headers - >> Expect: 100-continue
> 2013/12/16 15:20:12:170 EST [DEBUG] headers - >> Content-Type: ISO-8859-1
> 2013/12/16 15:20:12:170 EST [DEBUG] headers - >> Transfer-Encoding: chunked
> 2013/12/16 15:20:12:170 EST [DEBUG] headers - >> Host: scaramouche:80
> 2013/12/16 15:20:12:170 EST [DEBUG] headers - >> Connection: Keep-Alive
> 2013/12/16 15:20:12:170 EST [DEBUG] headers - >> User-Agent:
> Sardine/UNAVAILABLE
> 2013/12/16 15:20:12:170 EST [DEBUG] headers - >> Authorization: Basic
> amRtaTpCYW5hbmExMSEhAAAAAAAA
> 2013/12/16 15:20:12:170 EST [DEBUG] DefaultClientConnection - Receiving
> response: HTTP/1.1 100 Continue
> 2013/12/16 15:20:12:170 EST [DEBUG] headers - << HTTP/1.1 100 Continue
> 2013/12/16 15:20:12:170 EST [DEBUG] DefaultClientConnection - Receiving
> response: HTTP/1.1 201 Created
> 2013/12/16 15:20:12:170 EST [DEBUG] headers - << HTTP/1.1 201 Created
> 2013/12/16 15:20:12:170 EST [DEBUG] headers - << Server: Microsoft-IIS/7.5
> 2013/12/16 15:20:12:171 EST [DEBUG] headers - << X-Powered-By: ASP.NET
> 2013/12/16 15:20:12:171 EST [DEBUG] headers - << Date: Mon, 16 Dec 2013
> 20:20:11 GMT
> 2013/12/16 15:20:12:171 EST [DEBUG] headers - << Content-Length: 0
> 2013/12/16 15:20:12:171 EST [DEBUG] DefaultHttpClient - Connection can be
> kept alive indefinitely
> 2013/12/16 15:20:12:171 EST [DEBUG] DefaultHttpClient - Authentication
> succeeded
> 2013/12/16 15:20:12:171 EST [DEBUG] TargetAuthenticationStrategy - Caching
> 'basic' auth scheme for http://scaramouche:80
> 2013/12/16 15:20:12:171 EST [DEBUG] PoolingClientConnectionManager -
> Connection [id: 2][route: {}->http://scaramouche:80] can be kept alive
> indefinitely
> 2013/12/16 15:20:12:171 EST [DEBUG] PoolingClientConnectionManager -
> Connection released: [id: 2][route: {}->http://scaramouche:80][total kept
> alive: 1; route allocated: 1 of 2; total allocated: 1 of 20]
>
>
>
>
>
>
>
> This e-mail may contain confidential and/or privileged information for the
> sole use of the intended recipient.
> Any review or distribution by anyone other than the person for whom it was
> originally intended is strictly prohibited.
> If you have received this e-mail in error, please contact the sender and
> delete all copies.
> Opinions, conclusions or other information contained in this e-mail may not
> be that of the organization.
---------------------------------------------------------------------
To unsubscribe, e-mail: httpclient-users-unsubscr...@hc.apache.org
For additional commands, e-mail: httpclient-users-h...@hc.apache.org
