To fix this we are always re-winding our steam at the end, weather curl
wants us to or not.

Spacen Jasset wrote:
> Hello,
>
> We are using curl 7.17.0 and have seemingly discovered a problem:
>
> We try to post 376 bytes to a server, through a squid proxy configured
> for digest authentication. We find that curl does not call the ioctl
> rewind function (CURLIOCMD_RESTARTREAD) when it re-tries the post with
> correct authentication credentials.
>
>
> This problem may be difficult to reproduce; on some machines it happens
> rarely, say 1 in 50, on other machines it will happen constantly. This
> has been seen on Linux and OS X platforms.
>
> We can't as yet see where the problem may lie. Please see the log output
> below (with comments)
>
> When our rewind function is called, "proto_input_stream::rewind" is seen
> in the log output; Similarly "proto_input_stream::read" are calls to our
> read function
>
>
>
> 16:43:14 url '192.168.100.204/Discovery/HTTPsvc/IISXferWanServer.DLL'
> proxy 'test:test_at_vm-fruitbat:3128'
>
> /* Our data of length 376 */
> 16:43:14 proto_input_stream::stream_length - 376
> 16:43:14 proto_input_stream::rewind
>
>
> 16:43:14 CURL: About to connect() to proxy vm-fruitbat port 3128 (#0)
> 16:43:14 CURL: Trying 192.168.100.106...
> 16:43:14 CURL: connected
> 16:43:14 CURL: Connected to vm-fruitbat (192.168.100.106) port 3128 (#0)
> 16:43:14 CURL: POST
> http://192.168.100.204/Discovery/HTTPsvc/IISXferWanServer.DLL HTTP/1.1
> User-Agent: HttpXferwan 10.3.2 (20071123)
> Host: 192.168.100.204
> Pragma: no-cache
> Accept: */*
> Proxy-Connection: Keep-Alive
> Content-Type: application/octet-stream
> Content-Length: 376
> 16:43:14 CURL: HTTP 1.0, assume close after body
> 16:43:14 CURL: HTTP/1.0 407 Proxy Authentication Required
> 16:43:14 CURL: Server: squid/2.6.STABLE14
> 16:43:14 CURL: Date: Wed, 12 Dec 2007 16:27:24 GMT
> 16:43:14 CURL: Content-Type: text/html
> 16:43:14 CURL: Content-Length: 1450
> 16:43:14 CURL: Expires: Wed, 12 Dec 2007 16:27:24 GMT
> 16:43:14 CURL: X-Squid-Error: ERR_CACHE_ACCESS_DENIED 0
> 16:43:14 CURL: Proxy-Authenticate: Digest realm="DIGEST",
> nonce="7AtgR1grWQiRspYH", qop="auth", stale=false
> 16:43:14 CURL: X-Cache: MISS from vm-fruitbat.centennial.co.uk
> 16:43:14 CURL: X-Cache-Lookup: NONE from
> vm-fruitbat.centennial.co.uk:3128
> 16:43:14 CURL: Via: 1.0 vm-fruitbat.centennial.co.uk:3128
> (squid/2.6.STABLE14)
> 16:43:14 CURL: Proxy-Connection: close
> 16:43:14 CURL:
>
> <..html error message...
>
>
> /* Our "stream" is read here. Note that there is no rewind called afer
> these reads. */
> 16:43:14 proto_input_stream::read requested 16384 bytes
> 16:43:14 proto_input_stream::read satisfied 376 bytes
> 16:43:14 proto_input_stream::read requested 16384 bytes
> 16:43:14 proto_input_stream::read satisfied 0 bytes
>
>
> 16:43:14 CURL: Closing connection #0
> 16:43:14 CURL: Issue another request to this URL:
> 'http://192.168.100.204/Discovery/HTTPsvc/IISXferWanServer.DLL'
> 16:43:14 CURL: About to connect() to proxy vm-fruitbat port 3128 (#0)
> 16:43:14 CURL: Trying 192.168.100.106...
> 16:43:14 CURL: connected
> 16:43:15 CURL: Connected to vm-fruitbat (192.168.100.106) port 3128 (#0)
> 16:43:15 CURL: Proxy auth using Digest with user 'test'
> 16:43:15 CURL: POST
> http://192.168.100.204/Discovery/HTTPsvc/IISXferWanServer.DLL HTTP/1.1
> Proxy-Authorization: Digest username="test", realm="DIGEST",
> nonce="7AtgR1grWQiRspYH", uri="/Discovery/HTTPsvc/IISXferWanServer.DLL",
> cnonce="MTE5NzQ3", nc=00000001, qop="auth",
> response="3b848ddfa0b8761bc5a79e03f32a4da9"
> User-Agent: HttpXferwan 10.3.2 (20071123)
> Host: 192.168.100.204
> Pragma: no-cache
> Accept: */*
> Proxy-Connection: Keep-Alive
> Content-Type: application/octet-stream
> Content-Length: 376
>
> /* Another read. Since no rewind has taken place it cannot be fulfilled */
>
> 16:43:15 proto_input_stream::read requested 16384 bytes
> 16:43:15 proto_input_stream::read satisfied 0 bytes
> 16:45:16 CURL: Operation too slow. Less than 1 bytes/sec transfered
> the last 120 seconds
> 16:45:16 CURL: Closing connection #0
> 16:45:16 CURL: Timeout was reached
> 16:45:16 CURL INFO: Version 'libcurl/7.17.0 06/12/2007 patch level 2
> OpenSSL/0.9.7l'
> 16:45:16 CURL INFO: CURLINFO_EFFECTIVE_URL
> http://192.168.100.204/Discovery/HTTPsvc/IISXferWanServer.DLL
> 16:45:16 CURL INFO: CURLINFO_RESPONSE_CODE 407
> 16:45:16 CURL INFO: CURLINFO_TOTAL_TIME 121.405
> 16:45:16 CURL INFO: CURLINFO_NAMELOOKUP_TIME 0.000218
> 16:45:16 CURL INFO: CURLINFO_CONNECT_TIME 0.043462
> 16:45:16 CURL INFO: CURLINFO_STARTTRANSFER_TIME 0.140539
> 16:45:16 CURL INFO: CURLINFO_REDIRECT_TIME 0.204195
> 16:45:16 CURL INFO: CURLINFO_SIZE_UPLOAD 0
> 16:45:16 CURL INFO: CURLINFO_SIZE_DOWNLOAD 0
> 16:45:16 CURL INFO: CURLINFO_SPEED_DOWNLOAD 0 B/s
> 16:45:16 CURL INFO: CURLINFO_SPEED_UPLOAD 0 B/s
> 16:45:16 CURL INFO: CURLINFO_CONTENT_TYPE text/html
> 16:45:16 CURL INFO: CURLINFO_PROXYAUTH_AVAIL 0x2 (DIGEST)
> 16:45:16 CURL INFO: CURLINFO_NUM_CONNECTS 2
> 16:45:16 CURL: transaction result status 28 (Timeout was reached)
>
>
Received on 2007-12-13