cURL / Mailing Lists / curl-library / Single Mail

curl-library

multi interface proxy issue with NTLM authentication

From: Krishna S <skrishna.blr_at_gmail.com>
Date: Wed, 13 Aug 2014 16:00:19 +0530

Similar to issue reported :
https://www.mail-archive.com/curl-library@cool.haxx.se/msg19575.html

I am facing similar CONNECT timeout issue with multi interface while using
proxy with NTLM authentication.

I have tested it with 7.35 as well as 7.37.

Following are trace:

=====================================================================

Here go trace (some are specific to my code, but very straight foreword.)
NOTE: As mention by Thomas, this issue is only with multi interface,
working perfectly with easy interface.

Trace:

2014-08-04 16:51:30.469 masvc(2896.6344) proxy_detect.Debug: setting proxy
addr<10.213.254.71:90>
2014-08-04 16:51:30.471 masvc(2896.6344) ahclient.Info: Initiating spipe
connection to site
https://10.213.5.78:443/spipe/pkg?AgentGuid={41cdd4a6-1976-11e4-044a-d4bed9862f5a}&Source=Agent_3.0.0
via proxy.
2014-08-04 16:51:30.479 masvc(2896.6344) network.Trace: info 45 Found
bundle for host 10.213.5.78: 0x1a5a7d0
2014-08-04 16:51:30.480 masvc(2896.6344) network.Trace: info 32 Hostname
was found in DNS cache
2014-08-04 16:51:30.481 masvc(2896.6344) network.Trace: curl context addr =
0000000001B2FDF0
2014-08-04 16:51:30.482 masvc(2896.6344) network.Trace: curl open socket
callback, socket 1032
2014-08-04 16:51:30.483 masvc(2896.6344) network.Trace: info 26 Trying
10.213.254.71...
2014-08-04 16:51:30.484 masvc(2896.6344) network.Debug: URL(
https://10.213.5.78:443/spipe/pkg?AgentGuid={41cdd4a6-1976-11e4-044a-d4bed9862f5a}&Source=Agent_3.0.0)
request, socket(1032) cb with action OUT [curl handle = 00000000020D60F0].
2014-08-04 16:51:30.485 masvc(2896.6344) network.Debug: URL(
https://10.213.5.78:443/spipe/pkg?AgentGuid={41cdd4a6-1976-11e4-044a-d4bed9862f5a}&Source=Agent_3.0.0)
request, setting watcher
2014-08-04 16:51:30.487 masvc(2896.6344) ahclient.Info: connection
initiated to site
https://10.213.5.78:443/spipe/pkg?AgentGuid={41cdd4a6-1976-11e4-044a-d4bed9862f5a}&Source=Agent_3.0.0
.
2014-08-04 16:51:30.488 masvc(2896.6344) network.Debug: URL(
https://10.213.5.78:443/spipe/pkg?AgentGuid={41cdd4a6-1976-11e4-044a-d4bed9862f5a}&Source=Agent_3.0.0)
request, connection established
2014-08-04 16:51:30.489 masvc(2896.6344) network.Debug: curl perform cb
called with event 2
2014-08-04 16:51:30.490 masvc(2896.6344) network.Trace: info 57 Connected
to 10.213.254.71 (10.213.254.71) port 90 (#91)
2014-08-04 16:51:30.491 masvc(2896.6344) network.Trace: info 47 Establish
HTTP proxy tunnel to 10.213.5.78:443
2014-08-04 16:51:30.492 masvc(2896.6344) network.Trace: info 49 Proxy auth
using Basic with user 'Administrator'
2014-08-04 16:51:30.493 masvc(2896.6344) network.Trace: snd head 333
CONNECT 10.213.5.78:443 HTTP/1.1 Proxy-Authorization: Basic
QWRtaW5pc3RyYXRvcjoxMjNBc2I= User-Agent: Mozilla/4.0 (compatible;
SPIPE/3.0; Windows) xxxxxx Agent Proxy-Connection: Keep-Alive Accept:
application/octet-stream Accept-Language: en-us Host: 10.213.5.78
Content-Type: application/octet-stream Content-Length: 1942
2014-08-04 16:51:30.496 masvc(2896.6344) network.Trace: rcv head 44
HTTP/1.0 407 Proxy Authentication Required
2014-08-04 16:51:30.497 masvc(2896.6344) network.Trace: rcv head 27 Server:
squid/2.7.STABLE7
2014-08-04 16:51:30.498 masvc(2896.6344) network.Trace: rcv head 37 Date:
Mon, 04 Aug 2014 11:21:29 GMT
2014-08-04 16:51:30.498 masvc(2896.6344) network.Trace: rcv head 25
Content-Type: text/html
2014-08-04 16:51:30.499 masvc(2896.6344) network.Trace: rcv head 22
Content-Length: 1319
2014-08-04 16:51:30.500 masvc(2896.6344) network.Trace: rcv head 42
X-Squid-Error: ERR_CACHE_ACCESS_DENIED 0
2014-08-04 16:51:30.501 masvc(2896.6344) network.Trace: rcv head 26
Proxy-Authenticate: NTLM
2014-08-04 16:51:30.502 masvc(2896.6344) network.Trace: rcv head 36
X-Cache: MISS from ASB-WIN2K8-HYPV
2014-08-04 16:51:30.502 masvc(2896.6344) network.Trace: rcv head 46
X-Cache-Lookup: NONE from ASB-WIN2K8-HYPV:90
2014-08-04 16:51:30.503 masvc(2896.6344) network.Trace: rcv head 49 Via:
1.0 ASB-WIN2K8-HYPV:90 (squid/2.7.STABLE7)
2014-08-04 16:51:30.504 masvc(2896.6344) network.Trace: rcv head 19
Connection: close
2014-08-04 16:51:30.505 masvc(2896.6344) network.Trace: rcv head 2
2014-08-04 16:51:30.506 masvc(2896.6344) network.Trace: info 35 Ignore 1319
bytes of response-body
2014-08-04 16:51:30.506 masvc(2896.6344) network.Trace: info 25 Rewind
stream after send
2014-08-04 16:51:30.507 masvc(2896.6344) network.Trace: curl close socket
callback, socket 1032
2014-08-04 16:51:30.508 masvc(2896.6344) network.Trace: info 48 Received
HTTP code 407 from proxy after CONNECT
2014-08-04 16:51:30.508 masvc(2896.6344) network.Trace: info 45 Found
bundle for host 10.213.5.78: 0x1a5a7d0
2014-08-04 16:51:30.509 masvc(2896.6344) network.Trace: info 32 Hostname
was found in DNS cache
2014-08-04 16:51:30.509 masvc(2896.6344) network.Trace: curl context addr =
0000000001B30430
2014-08-04 16:51:30.510 masvc(2896.6344) network.Trace: curl open socket
callback, socket 868
2014-08-04 16:51:30.510 masvc(2896.6344) network.Trace: info 26 Trying
10.213.254.71...
2014-08-04 16:51:30.511 masvc(2896.6344) network.Debug: URL(
https://10.213.5.78:443/spipe/pkg?AgentGuid={41cdd4a6-1976-11e4-044a-d4bed9862f5a}&Source=Agent_3.0.0)
request, socket(868) cb with action OUT [curl handle = 00000000020D60F0].
2014-08-04 16:51:30.512 masvc(2896.6344) network.Debug: URL(
https://10.213.5.78:443/spipe/pkg?AgentGuid={41cdd4a6-1976-11e4-044a-d4bed9862f5a}&Source=Agent_3.0.0)
request, setting watcher
2014-08-04 16:51:30.513 masvc(2896.6344) network.Debug: URL(
https://10.213.5.78:443/spipe/pkg?AgentGuid={41cdd4a6-1976-11e4-044a-d4bed9862f5a}&Source=Agent_3.0.0)
request, socket(1032) cb with action REMOVE [curl handle =
00000000020D60F0].
2014-08-04 16:51:30.514 masvc(2896.6344) network.Trace: info 57 Connected
to 10.213.254.71 (10.213.254.71) port 90 (#92)
2014-08-04 16:51:30.515 masvc(2896.6344) network.Trace: info 47 Establish
HTTP proxy tunnel to 10.213.5.78:443
2014-08-04 16:51:30.516 masvc(2896.6344) network.Trace: info 48 Proxy auth
using NTLM with user 'Administrator'
2014-08-04 16:51:30.516 masvc(2896.6344) network.Trace: snd head 360
CONNECT 10.213.5.78:443 HTTP/1.1 Proxy-Authorization: NTLM
TlRMTVNTUAABAAAAt4II4gAAAAAAAAAAAAAAAAAAAAAGAbEdAAAADw== User-Agent:
Mozilla/4.0 (compatible; SPIPE/3.0; Windows) xxxxxx Agent
Proxy-Connection: Keep-Alive Accept: application/octet-stream
Accept-Language: en-us Host: 10.213.5.78 Content-Type:
application/octet-stream Content-Length: 1942
2014-08-04 16:51:30.550 masvc(2896.6344) network.Trace: rcv head 44
HTTP/1.0 407 Proxy Authentication Required
2014-08-04 16:51:30.550 masvc(2896.6344) network.Trace: rcv head 27 Server:
squid/2.7.STABLE7
2014-08-04 16:51:30.551 masvc(2896.6344) network.Trace: rcv head 37 Date:
Mon, 04 Aug 2014 11:21:30 GMT
2014-08-04 16:51:30.551 masvc(2896.6344) network.Trace: rcv head 25
Content-Type: text/html
2014-08-04 16:51:30.552 masvc(2896.6344) network.Trace: rcv head 22
Content-Length: 1319
2014-08-04 16:51:30.552 masvc(2896.6344) network.Trace: rcv head 42
X-Squid-Error: ERR_CACHE_ACCESS_DENIED 0
2014-08-04 16:51:30.553 masvc(2896.6344) network.Trace: rcv head 347
Proxy-Authenticate: NTLM
TlRMTVNTUAACAAAAHgAeADgAAAA1goriz0v6eA2f5xAAAAAAAAAAAJgAmABWAAAABgGxHQAAAA9BAFMAQgAtAFcASQBOADIASwA4AC0ASABZAFAAVgACAB4AQQBTAEIALQBXAEkATgAyAEsAOAAtAEgAWQBQAFYAAQAeAEEAUwBCAC0AVwBJAE4AMgBLADgALQBIAFkAUABWAAQAHgBBAFMAQgAtAFcASQBOADIASwA4AC0ASABZAFAAVgADAB4AQQBTAEIALQBXAEkATgAyAEsAOAAtAEgAWQBQAFYABwAIAI53IT3Wr88BAAAAAA==

2014-08-04 16:51:30.555 masvc(2896.6344) network.Trace: rcv head 36
X-Cache: MISS from ASB-WIN2K8-HYPV
2014-08-04 16:51:30.555 masvc(2896.6344) network.Trace: rcv head 46
X-Cache-Lookup: NONE from ASB-WIN2K8-HYPV:90
2014-08-04 16:51:30.556 masvc(2896.6344) network.Trace: rcv head 49 Via:
1.0 ASB-WIN2K8-HYPV:90 (squid/2.7.STABLE7)
2014-08-04 16:51:30.556 masvc(2896.6344) network.Trace: rcv head 24
Connection: keep-alive
2014-08-04 16:51:30.557 masvc(2896.6344) network.Trace: rcv head 30
Proxy-Connection: keep-alive
2014-08-04 16:51:30.557 masvc(2896.6344) network.Trace: rcv head 2
2014-08-04 16:51:30.558 masvc(2896.6344) network.Trace: info 35 Ignore 1319
bytes of response-body
2014-08-04 16:51:30.558 masvc(2896.6344) network.Trace: info 25 Rewind
stream after send
2014-08-04 16:51:30.559 masvc(2896.6344) network.Trace: info 28
TUNNEL_STATE switched to: 0
2014-08-04 16:51:30.559 masvc(2896.6344) network.Trace: info 47 Establish
HTTP proxy tunnel to 10.213.5.78:443
2014-08-04 16:51:30.560 masvc(2896.6344) network.Trace: info 48 Proxy auth
using NTLM with user 'Administrator'
2014-08-04 16:51:30.561 masvc(2896.6344) network.Trace: snd head 928
CONNECT 10.213.5.78:443 HTTP/1.1 Proxy-Authorization: NTLM
TlRMTVNTUAADAAAAGAAYAIwAAAAgASABpAAAAAAAAABYAAAAGgAaAFgAAAAaABoAcgAAABAAEADEAQAANYKI4gYBsR0AAAAPS5YCB67C1res449ml+gp30EAZABtAGkAbgBpAHMAdAByAGEAdABvAHIASwBTAFcAQQBNAEkALQBMAEEAUABUAE8AUAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAD8moYGsY8CJcdomyKJOsygAQEAAAAAAACOdyE91q/PAcc7wuIloJKmAAAAAAIAHgBBAFMAQgAtAFcASQBOADIASwA4AC0ASABZAFAAVgABAB4AQQBTAEIALQBXAEkATgAyAEsAOAAtAEgAWQBQAFYABAAeAEEAUwBCAC0AVwBJAE4AMgBLADgALQBIAFkAUABWAAMAHgBBAFMAQgAtAFcASQBOADIASwA4AC0ASABZAFAAVgAHAAgAjnchPdavzwEGAAQAAgAAAAgAMAAwAAAAAAAAAAAAAAAAQAAA7BNG8VlMI5MqTqCCpaQx4VaeDo/4gNRaO7vZcXMy69gKABAAAAAAAAAAAAAAAAAAAAAAAAkAAAAAAAAAAAAAAAAAAAAvreu1hIx945cJvC91c45e
User-Agent: Mozilla/4.0 (compatible; SPIPE/3.0; Windows) xxxxxx Agent
Proxy-Connection: Keep-Alive Accept: application/octet-stream
Accept-Language: en-us Host: 10.213.5.78 Content-Type:
application/octet-stream Content-Length: 1942
2014-08-04 16:51:30.565 masvc(2896.6344) network.Debug: URL(
https://10.213.5.78:443/spipe/pkg?AgentGuid={41cdd4a6-1976-11e4-044a-d4bed9862f5a}&Source=Agent_3.0.0)
request, socket(868) cb with action IN [curl handle = 00000000020D60F0].
2014-08-04 16:51:30.566 masvc(2896.6344) network.Debug: URL(
https://10.213.5.78:443/spipe/pkg?AgentGuid={41cdd4a6-1976-11e4-044a-d4bed9862f5a}&Source=Agent_3.0.0)
request, setting watcher
2014-08-04 16:51:30.567 masvc(2896.6344) network.Trace: poller closed 1032
2014-08-04 16:51:30.567 masvc(2896.6344) network.Trace: poller closed 916
2014-08-04 16:52:30.523 masvc(2896.6344) network.Trace: info 37 *Proxy
CONNECT aborted due to timeout*

========================================================================

Does it known issue?

Thanks

--Krishna

-------------------------------------------------------------------
List admin: http://cool.haxx.se/list/listinfo/curl-library
Etiquette: http://curl.haxx.se/mail/etiquette.html
Received on 2014-08-13