hc-httpclient-users mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Bernadette.Hur...@clients.ie
Subject Re: problem with proxy authentication
Date Mon, 24 Apr 2006 09:18:16 GMT
Here is the full log again.

Thanks
Bernadette

2006-04-24 09:39:00,077 DEBUG [main] .HttpTest.main() -
main(String[]=[Ljava.lang.String;@13bad12) - start
 Trying to get connection details from [XiamCaller.properties].
2006-04-24 09:39:00,138 DEBUG [main] .DefaultHttpParams.setParameter() - Set
parameter http.useragent = Jakarta Commons-HttpClient/3.0
2006-04-24 09:39:00,138 DEBUG [main] .DefaultHttpParams.setParameter() - Set
parameter http.protocol.version = HTTP/1.1
2006-04-24 09:39:00,148 DEBUG [main] .DefaultHttpParams.setParameter() - Set
parameter http.connection-manager.class = class
org.apache.commons.httpclient.SimpleHttpConnectionManager
2006-04-24 09:39:00,148 DEBUG [main] .DefaultHttpParams.setParameter() - Set
parameter http.protocol.cookie-policy = rfc2109
2006-04-24 09:39:00,148 DEBUG [main] .DefaultHttpParams.setParameter() - Set
parameter http.protocol.element-charset = US-ASCII
2006-04-24 09:39:00,158 DEBUG [main] .DefaultHttpParams.setParameter() - Set
parameter http.protocol.content-charset = ISO-8859-1
2006-04-24 09:39:00,158 DEBUG [main] .DefaultHttpParams.setParameter() - Set
parameter http.method.retry-handler =
org.apache.commons.httpclient.DefaultHttpMethodRetryHandler@1e9cb75
2006-04-24 09:39:00,168 DEBUG [main] .DefaultHttpParams.setParameter() - Set
parameter http.dateparser.patterns = [EEE, dd MMM yyyy HH:mm:ss zzz, EEEE,
dd-MMM-yy HH:mm:ss zzz, EEE MMM d HH:mm:ss yyyy, EEE, dd-MMM-yyyy HH:mm:ss z,
EEE, dd-MMM-yyyy HH-mm-ss z, EEE, dd MMM yy HH:mm:ss z, EEE dd-MMM-yyyy HH:mm:ss
z, EEE dd MMM yyyy HH:mm:ss z, EEE dd-MMM-yyyy HH-mm-ss z, EEE dd-MMM-yy
HH:mm:ss z, EEE dd MMM yy HH:mm:ss z, EEE,dd-MMM-yy HH:mm:ss z, EEE,dd-MMM-yyyy
HH:mm:ss z, EEE, dd-MM-yyyy HH:mm:ss z]
2006-04-24 09:39:00,198 DEBUG [main] .GetMethod.<init>() - enter
GetMethod(String)
2006-04-24 09:39:00,198 DEBUG [main] .HttpClient.<clinit>() - Java version:
1.4.2_10
2006-04-24 09:39:00,198 DEBUG [main] .HttpClient.<clinit>() - Java vendor: Sun
Microsystems Inc.
2006-04-24 09:39:00,208 DEBUG [main] .HttpClient.<clinit>() - Java class path:
C:\workspace\xiamcaller\build;C:\DS_Area\CCDD\Source\lib\commons-validator.jar;C:\DS_Area\CCDD\Source\lib\commons-collections.jar;C:\DS_Area\CCDD\Source\lib\commons-digester.jar;C:\DS_Area\CCDD\Source\lib\commons-discovery.jar;C:\DS_Area\CCDD\Source\lib\commons-fileupload.jar;C:\DS_Area\CCDD\Source\lib\commons-lang-2.1.jar;C:\DS_Area\CCDD\Source\lib\commons-logging.jar;C:\DS_Area\CCDD\Source\lib\commons-beanutils.jar;C:\commons-codec-1.3\commons-codec-1.3.jar;C:\caramel-1.0-b1\caramel-1.0-b1.jar;C:\workspace\batch\lib\log4j.jar;C:\jwsdp-1.6\jaxb\lib\jaxb-xjc.jar;C:\jwsdp-1.6\jaxb\lib\jaxb-impl.jar;C:\jwsdp-1.6\jaxb\lib\jaxb-libs.jar;C:\jwsdp-1.6\jaxb\lib\jaxb-api.jar;C:\jwsdp-1.6\jwsdp-shared\lib\activation.jar;C:\jwsdp-1.6\jwsdp-shared\lib\mail.jar;C:\jwsdp-1.6\jwsdp-shared\lib\relaxngDatatype.jar;C:\jwsdp-1.6\jaxp\lib\jaxp-api.jar;C:\DS_Area\Unified_Middleware\Source\lib\jaxb\xsdlib.jar;C:\DS_Area\Unified_Middleware\Source\lib\jaxb\jaxb-impl.jar;C:\DS_Area\Unified_Middlewar
 e\Source\lib\jaxb\jaxb-libs.jar;C:\DS_Area\Unified_Middleware\Source\lib\jaxb\jaxb-xjc.jar;C:\DS_Area\Unified_Middleware\Source\lib\jaxb\jsr173_api.jar;C:\DS_Area\Unified_Middleware\Source\lib\jaxb\namespace.jar;C:\DS_Area\Unified_Middleware\Source\lib\jaxb\relaxngDatatype.jar;C:\DS_Area\Unified_Middleware\Source\lib\jaxb\jaxb-api.jar;C:\camp\httpclient.jar;C:\inetfactory\lib\inetfactory.jar;C:\commons-httpclient\commons-httpclient.jar
2006-04-24 09:39:00,208 DEBUG [main] .HttpClient.<clinit>() - Operating system
name: Windows 2000
2006-04-24 09:39:00,208 DEBUG [main] .HttpClient.<clinit>() - Operating system
architecture: x86
2006-04-24 09:39:00,208 DEBUG [main] .HttpClient.<clinit>() - Operating system
version: 5.0
2006-04-24 09:39:00,318 DEBUG [main] .HttpClient.<clinit>() - SUN 1.42: SUN (DSA
key/parameter generation; DSA signing; SHA-1, MD5 digests; SecureRandom; X.509
certificates; JKS keystore; PKIX CertPathValidator; PKIX CertPathBuilder; LDAP,
Collection CertStores)
2006-04-24 09:39:00,318 DEBUG [main] .HttpClient.<clinit>() - SunJSSE 1.42: Sun
JSSE provider(implements RSA Signatures, PKCS12, SunX509 key/trust factories,
SSLv3, TLSv1)
2006-04-24 09:39:00,318 DEBUG [main] .HttpClient.<clinit>() - SunRsaSign 1.42:
SUN's provider for RSA signatures
2006-04-24 09:39:00,318 DEBUG [main] .HttpClient.<clinit>() - SunJCE 1.42:
SunJCE Provider (implements DES, Triple DES, AES, Blowfish, PBE, Diffie-Hellman,
HMAC-MD5, HMAC-SHA1)
2006-04-24 09:39:00,318 DEBUG [main] .HttpClient.<clinit>() - SunJGSS 1.0: Sun
(Kerberos v5)
2006-04-24 09:39:00,328 DEBUG [main] .HttpState.setProxyCredentials() - enter
HttpState.setProxyCredentials(AuthScope, Credentials)
2006-04-24 09:39:00,338 DEBUG [main] .DefaultHttpParams.setParameter() - Set
parameter NTLM = [NTLM]
2006-04-24 09:39:00,338 DEBUG [main] .DefaultHttpParams.setParameter() - Set
parameter http.protocol.expect-continue = true
2006-04-24 09:39:00,338 DEBUG [main] .DefaultHttpParams.setParameter() - Set
parameter http.connection.timeout = 120000
2006-04-24 09:39:00,338 DEBUG [main] .DefaultHttpParams.setParameter() - Set
parameter http.socket.timeout = 300000
2006-04-24 09:39:00,338 DEBUG [main] .HttpClient.executeMethod() - enter
HttpClient.executeMethod(HttpMethod)
2006-04-24 09:39:00,338 DEBUG [main] .HttpClient.executeMethod() - enter
HttpClient.executeMethod(HostConfiguration,HttpMethod,HttpState)
2006-04-24 09:39:00,358 DEBUG [main] .HttpMethodDirector.executeWithRetry() -
Attempt number 1 to process request
2006-04-24 09:39:00,358 DEBUG [main] .HttpConnection.open() - enter
HttpConnection.open()
2006-04-24 09:39:00,358 DEBUG [main] .HttpConnection.open() - Open connection to
172.26.11.47:80
2006-04-24 09:39:00,398 DEBUG [main] .HttpMethodBase.execute() - enter
HttpMethodBase.execute(HttpState, HttpConnection)
2006-04-24 09:39:00,398 DEBUG [main] .HttpMethodBase.writeRequest() - enter
HttpMethodBase.writeRequest(HttpState, HttpConnection)
2006-04-24 09:39:00,398 DEBUG [main] .HttpMethodBase.writeRequestLine() - enter
HttpMethodBase.writeRequestLine(HttpState, HttpConnection)
2006-04-24 09:39:00,398 DEBUG [main] .HttpMethodBase.generateRequestLine() -
enter HttpMethodBase.generateRequestLine(HttpConnection, String, String, String,
String)
2006-04-24 09:39:00,398 DEBUG [main] .header.wire() - >> "GET
http://www.google.com/ HTTP/1.1[\r][\n]"
2006-04-24 09:39:00,398 DEBUG [main] .HttpConnection.print() - enter
HttpConnection.print(String)
2006-04-24 09:39:00,398 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[])
2006-04-24 09:39:00,398 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[], int, int)
2006-04-24 09:39:00,398 DEBUG [main] .HttpMethodBase.writeRequestHeaders() -
enter HttpMethodBase.writeRequestHeaders(HttpState,HttpConnection)
2006-04-24 09:39:00,398 DEBUG [main] .HttpMethodBase.addRequestHeaders() - enter
HttpMethodBase.addRequestHeaders(HttpState, HttpConnection)
2006-04-24 09:39:00,398 DEBUG [main] .HttpMethodBase.addUserAgentRequestHeader()
- enter HttpMethodBase.addUserAgentRequestHeaders(HttpState, HttpConnection)
2006-04-24 09:39:00,408 DEBUG [main] .HttpMethodBase.addHostRequestHeader() -
enter HttpMethodBase.addHostRequestHeader(HttpState, HttpConnection)
2006-04-24 09:39:00,408 DEBUG [main] .HttpMethodBase.addHostRequestHeader() -
Adding Host request header
2006-04-24 09:39:00,408 DEBUG [main] .HttpMethodBase.addCookieRequestHeader() -
enter HttpMethodBase.addCookieRequestHeader(HttpState, HttpConnection)
2006-04-24 09:39:00,418 DEBUG [main] .HttpState.getCookies() - enter
HttpState.getCookies()
2006-04-24 09:39:00,428 DEBUG [main] .CookieSpec.match() - enter
CookieSpecBase.match(String, int, String, boolean, Cookie[])
2006-04-24 09:39:00,428 DEBUG [main] .HttpMethodBase.addProxyConnectionHeader()
- enter HttpMethodBase.addProxyConnectionHeader(HttpState, HttpConnection)
2006-04-24 09:39:00,428 DEBUG [main] .HttpMethodBase.addRequestHeader() -
HttpMethodBase.addRequestHeader(Header)
2006-04-24 09:39:00,428 DEBUG [main] .header.wire() - >> "User-Agent: Jakarta
Commons-HttpClient/3.0[\r][\n]"
2006-04-24 09:39:00,428 DEBUG [main] .HttpConnection.print() - enter
HttpConnection.print(String)
2006-04-24 09:39:00,428 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[])
2006-04-24 09:39:00,428 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[], int, int)
2006-04-24 09:39:00,428 DEBUG [main] .header.wire() - >> "Host:
www.google.com[\r][\n]"
2006-04-24 09:39:00,428 DEBUG [main] .HttpConnection.print() - enter
HttpConnection.print(String)
2006-04-24 09:39:00,428 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[])
2006-04-24 09:39:00,428 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[], int, int)
2006-04-24 09:39:00,428 DEBUG [main] .header.wire() - >> "Proxy-Connection:
Keep-Alive[\r][\n]"
2006-04-24 09:39:00,428 DEBUG [main] .HttpConnection.print() - enter
HttpConnection.print(String)
2006-04-24 09:39:00,438 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[])
2006-04-24 09:39:00,438 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[], int, int)
2006-04-24 09:39:00,438 DEBUG [main] .HttpConnection.writeLine() - enter
HttpConnection.writeLine()
2006-04-24 09:39:00,438 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[])
2006-04-24 09:39:00,438 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[], int, int)
2006-04-24 09:39:00,438 DEBUG [main] .header.wire() - >> "[\r][\n]"
2006-04-24 09:39:00,438 DEBUG [main] .HttpConnection.flushRequestOutputStream()
- enter HttpConnection.flushRequestOutputStream()
2006-04-24 09:39:00,438 DEBUG [main] .HttpMethodBase.readResponse() - enter
HttpMethodBase.readResponse(HttpState, HttpConnection)
2006-04-24 09:39:00,438 DEBUG [main] .HttpMethodBase.readStatusLine() - enter
HttpMethodBase.readStatusLine(HttpState, HttpConnection)
2006-04-24 09:39:00,448 DEBUG [main] .HttpConnection.readLine() - enter
HttpConnection.readLine()
2006-04-24 09:39:00,448 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:00,448 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:00,458 DEBUG [main] .header.wire() - << "HTTP/1.1 407 Proxy
Authentication Required ( The ISA Server requires authorization to fulfill the
request. Access to the Web Proxy service is denied.  )[\r][\n]"
2006-04-24 09:39:00,458 DEBUG [main] .HttpMethodBase.readResponseHeaders() -
enter HttpMethodBase.readResponseHeaders(HttpState,HttpConnection)
2006-04-24 09:39:00,458 DEBUG [main] .HttpConnection.getResponseInputStream() -
enter HttpConnection.getResponseInputStream()
2006-04-24 09:39:00,458 DEBUG [main] .HttpParser.parseHeaders() - enter
HeaderParser.parseHeaders(InputStream, String)
2006-04-24 09:39:00,458 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:00,458 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:00,458 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:00,458 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:00,458 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:00,458 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:00,468 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:00,468 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:00,468 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:00,468 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:00,468 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:00,468 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:00,468 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:00,468 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:00,468 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:00,468 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:00,468 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:00,528 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:00,528 DEBUG [main] .header.wire() - << "Via: 1.1
IENT1ISA001[\r][\n]"
2006-04-24 09:39:00,528 DEBUG [main] .header.wire() - << "Proxy-Authenticate:
NTLM[\r][\n]"
2006-04-24 09:39:00,528 DEBUG [main] .header.wire() - << "Proxy-Authenticate:
Kerberos[\r][\n]"
2006-04-24 09:39:00,528 DEBUG [main] .header.wire() - << "Proxy-Authenticate:
Negotiate[\r][\n]"
2006-04-24 09:39:00,528 DEBUG [main] .header.wire() - << "Pragma:
no-cache[\r][\n]"
2006-04-24 09:39:00,538 DEBUG [main] .header.wire() - << "Cache-Control:
no-cache[\r][\n]"
2006-04-24 09:39:00,538 DEBUG [main] .header.wire() - << "Content-Type:
text/html[\r][\n]"
2006-04-24 09:39:00,538 DEBUG [main] .header.wire() - << "Content-Length:
3778[\r][\n]"
2006-04-24 09:39:00,538 DEBUG [main] .HttpMethodBase.processResponseHeaders() -
enter HttpMethodBase.processResponseHeaders(HttpState, HttpConnection)
2006-04-24 09:39:00,538 DEBUG [main] .HttpMethodBase.readResponseBody() - enter
HttpMethodBase.readResponseBody(HttpState, HttpConnection)
2006-04-24 09:39:00,538 DEBUG [main] .HttpMethodBase.readResponseBody() - enter
HttpMethodBase.readResponseBody(HttpConnection)
2006-04-24 09:39:00,538 DEBUG [main] .HttpConnection.getResponseInputStream() -
enter HttpConnection.getResponseInputStream()
2006-04-24 09:39:00,538 DEBUG [main] .HttpMethodBase.canResponseHaveBody() -
enter HttpMethodBase.canResponseHaveBody(int)
2006-04-24 09:39:00,538 DEBUG [main]
.HttpMethodDirector.isAuthenticationNeeded() - Authorization required
2006-04-24 09:39:00,538 DEBUG [main]
.HttpMethodDirector.processAuthenticationResponse() - enter
HttpMethodBase.processAuthenticationResponse(HttpState, HttpConnection)
2006-04-24 09:39:00,558 DEBUG [main] .AuthChallengeProcessor.selectAuthScheme()
- Supported authentication schemes in the order of preference: [ntlm, digest,
basic]
2006-04-24 09:39:00,558 INFO  [main] .AuthChallengeProcessor.selectAuthScheme()
- ntlm authentication scheme selected
2006-04-24 09:39:00,558 DEBUG [main] .AuthChallengeProcessor.processChallenge()
- Using authentication scheme: ntlm
2006-04-24 09:39:00,558 DEBUG [main] .AuthChallengeProcessor.processChallenge()
- Authorization challenge processed
2006-04-24 09:39:00,558 DEBUG [main]
.HttpMethodDirector.processProxyAuthChallenge() - Proxy authentication scope:
NTLM <any realm>@172.26.11.47:80
2006-04-24 09:39:00,558 DEBUG [main] .HttpState.getProxyCredentials() - enter
HttpState.getProxyCredentials(AuthScope)
2006-04-24 09:39:00,578 DEBUG [main] .HttpMethodDirector.executeMethod() - Retry
authentication
2006-04-24 09:39:00,578 DEBUG [main] .content.wire() - << "<!DOCTYPE HTML PUBLIC
"-//W3C//DTD HTML 4.0 Transitional//EN">[\r][\n]"
2006-04-24 09:39:00,578 DEBUG [main] .content.wire() - << "<HTML
dir=ltr><HEAD><TITLE>The page cannot be displayed</TITLE>[\r][\n]"
2006-04-24 09:39:00,588 DEBUG [main] .content.wire() - << "<STYLE>A:link
{[\r][\n]"
2006-04-24 09:39:00,588 DEBUG [main] .content.wire() - << "[0x9]FONT: 8pt/11pt
verdana; COLOR: #ff0000[\r][\n]"
2006-04-24 09:39:00,588 DEBUG [main] .content.wire() - << "}[\r][\n]"
2006-04-24 09:39:00,588 DEBUG [main] .content.wire() - << "A:visited {[\r][\n]"
2006-04-24 09:39:00,588 DEBUG [main] .content.wire() - << "[0x9]FONT: 8pt/11pt
verdana; COLOR: #4e4e4e[\r][\n]"
2006-04-24 09:39:00,588 DEBUG [main] .content.wire() - << "}[\r][\n]"
2006-04-24 09:39:00,588 DEBUG [main] .content.wire() - << "</STYLE>[\r][\n]"
2006-04-24 09:39:00,588 DEBUG [main] .content.wire() - << "[\r][\n]"
2006-04-24 09:39:00,588 DEBUG [main] .content.wire() - << "<META content=NOINDEX
name=ROBOTS>[\r][\n]"
2006-04-24 09:39:00,588 DEBUG [main] .content.wire() - << "<META
http-equiv=Content-Type content="text-html; charset=Windows-1252">[\r][\n]"
2006-04-24 09:39:00,588 DEBUG [main] .content.wire() - << "<SCRIPT> [\r][\n]"
2006-04-24 09:39:00,588 DEBUG [main] .content.wire() - << "function
Homepage(){[\r][\n]"
2006-04-24 09:39:00,588 DEBUG [main] .content.wire() - << "<!--[\r][\n]"
2006-04-24 09:39:00,598 DEBUG [main] .content.wire() - << "// in real bits, urls
get returned to our script like this:[\r][\n]"
2006-04-24 09:39:00,598 DEBUG [main] .content.wire() - << "//
res://shdocvw.dll/http_404.htm#http://www.DocURL.com/bar.htm [\r][\n]"
2006-04-24 09:39:00,598 DEBUG [main] .content.wire() - << "[\r][\n]"
2006-04-24 09:39:00,598 DEBUG [main] .content.wire() - << "[0x9]//For testing
use DocURL =
"res://shdocvw.dll/http_404.htm#https://www.microsoft.com/bar.htm"[\r][\n]"
2006-04-24 09:39:00,598 DEBUG [main] .content.wire() - <<
"[0x9]DocURL=document.URL;[\r][\n]"
2006-04-24 09:39:00,598 DEBUG [main] .content.wire() - << "[0x9][\r][\n]"
2006-04-24 09:39:00,598 DEBUG [main] .content.wire() - << "[0x9]//this is where
the http or https will be, as found by searching for :// but skipping the
res://[\r][\n]"
2006-04-24 09:39:00,598 DEBUG [main] .content.wire() - <<
"[0x9]protocolIndex=DocURL.indexOf("://",4);[\r][\n]"
2006-04-24 09:39:00,598 DEBUG [main] .content.wire() - << "[0x9][\r][\n]"
2006-04-24 09:39:00,598 DEBUG [main] .content.wire() - << "[0x9]//this finds the
ending slash for the domain server [\r][\n]"
2006-04-24 09:39:00,598 DEBUG [main] .content.wire() - <<
"[0x9]serverIndex=DocURL.indexOf("/",protocolIndex + 3);[\r][\n]"
2006-04-24 09:39:00,598 DEBUG [main] .content.wire() - << "[\r][\n]"
2006-04-24 09:39:00,598 DEBUG [main] .content.wire() - << "[0x9]//for the href,
we need a valid URL to the domain. We search for the # symbol to find the
begining [\r][\n]"
2006-04-24 09:39:00,598 DEBUG [main] .content.wire() - << "[0x9]//of the "
2006-04-24 09:39:00,608 DEBUG [main] .content.wire() - << "true URL, and add 1
to skip it - this is the BeginURL value. We use serverIndex as the end
marker.[\r][\n]"
2006-04-24 09:39:00,608 DEBUG [main] .content.wire() - <<
"[0x9]//urlresult=DocURL.substring(protocolIndex - 4,serverIndex);[\r][\n]"
2006-04-24 09:39:00,608 DEBUG [main] .content.wire() - <<
"[0x9]BeginURL=DocURL.indexOf("#",1) + 1;[\r][\n]"
2006-04-24 09:39:00,608 DEBUG [main] .content.wire() - <<
"[0x9]urlresult=DocURL.substring(BeginURL,serverIndex);[\r][\n]"
2006-04-24 09:39:00,608 DEBUG [main] .content.wire() - << "[0x9][0x9][\r][\n]"
2006-04-24 09:39:00,618 DEBUG [main] .content.wire() - << "[0x9]//for display,
we need to skip after http://, and go to the next slash[\r][\n]"
2006-04-24 09:39:00,618 DEBUG [main] .content.wire() - <<
"[0x9]displayresult=DocURL.substring(protocolIndex + 3 ,serverIndex);[\r][\n]"
2006-04-24 09:39:00,618 DEBUG [main] .content.wire() - <<
"[0x9]document.write('<A HREF="' + urlresult + '">' + displayresult +
"</a>");[\r][\n]"
2006-04-24 09:39:00,618 DEBUG [main] .content.wire() - << "}[\r][\n]"
2006-04-24 09:39:00,618 DEBUG [main] .content.wire() - << "//-->[\r][\n]"
2006-04-24 09:39:00,618 DEBUG [main] .content.wire() - << "</SCRIPT>[\r][\n]"
2006-04-24 09:39:00,618 DEBUG [main] .content.wire() - << "[\r][\n]"
2006-04-24 09:39:00,618 DEBUG [main] .content.wire() - << "<META content="MSHTML
5.50.4522.1800" name=GENERATOR></HEAD>[\r][\n]"
2006-04-24 09:39:00,618 DEBUG [main] .content.wire() - << "<BODY
bgColor=#ffffff>[\r][\n]"
2006-04-24 09:39:00,618 DEBUG [main] .content.wire() - << "<TABLE cellSpacing=5
cellPadding=3 width=410>[\r][\n]"
2006-04-24 09:39:00,618 DEBUG [main] .content.wire() - << "  <TBODY>[\r][\n]"
2006-04-24 09:39:00,618 DEBUG [main] .content.wire() - << "  <TR>[\r][\n]"
2006-04-24 09:39:00,618 DEBUG [main] .content.wire() - << "    <TD vAlign=center
align=left width=360>[\r][\n]"
2006-04-24 09:39:00,618 DEBUG [main] .content.wire() - << "      <H1
style="FONT: 13pt/15pt verdana; COLOR: #000000"><!--Problem-->The page [\r][\n]"
2006-04-24 09:39:00,618 DEBUG [main] .content.wire() - << "      cannot be
displayed</H1></TD></TR>[\r][\n]"
2006-04-24 09:39:00,628 DEBUG [main] .content.wire() - << "  <TR>[\r][\n]"
2006-04-24 09:39:00,628 DEBUG [main] .content.wire() - << "    <TD width=400
colSpan=2><FONT [\r][\n]"
2006-04-24 09:39:00,628 DEBUG [main] .content.wire() - << "      style="FONT:
8pt/11pt verdana; COLOR: #000000">There is a problem with the [\r][\n]"
2006-04-24 09:39:00,628 DEBUG [main] .content.wire() - << "      page you are
trying to reach and it cannot be displayed.</FONT></TD></TR>[\r][\n]"
2006-04-24 09:39:00,628 DEBUG [main] .content.wire() - << "  <TR>[\r][\n]"
2006-04-24 09:39:00,628 DEBUG [main] .content.wire() - << "    <TD width=400
colSpan=2><FONT [\r][\n]"
2006-04-24 09:39:00,638 DEBUG [main] .content.wire() - << "      style="FONT:
8pt/11pt verdana; COLOR: #000000">[\r][\n]"
2006-04-24 09:39:00,638 DEBUG [main] .content.wire() - << "      <HR
color=#c0c0c0 noShade>[\r][\n]"
2006-04-24 09:39:00,638 DEBUG [main] .content.wire() - << "[\r][\n]"
2006-04-24 09:39:00,638 DEBUG [main] .content.wire() - << "      <P>Please try
the following:</P>[\r][\n]"
2006-04-24 09:39:00,638 DEBUG [main] .content.wire() - << "      <UL>[\r][\n]"
2006-04-24 09:39:00,638 DEBUG [main] .content.wire() - << "        <LI>Click the
<A href="javascript:location.reload()">Refresh</A> button, [\r][\n]"
2006-04-24 09:39:00,638 DEBUG [main] .content.wire() - << "        or try again
later.<BR>[\r][\n]"
2006-04-24 09:39:00,638 DEBUG [main] .content.wire() - << "        <LI>Open
the[\r][\n]"
2006-04-24 09:39:00,638 DEBUG [main] .content.wire() - << "       
<SCRIPT>[\r][\n]"
2006-04-24 09:39:00,648 DEBUG [main] .content.wire() - << "[0x9]  <!--[\r][\n]"
2006-04-24 09:39:00,648 DEBUG [main] .content.wire() - << "[0x9]  if
(!((window.navigator.userAgent.indexOf("MSIE") > 0) &&
(window.navigator.appVersion.charAt(0) == "2")))[\r][\n]"
2006-04-24 09:39:00,648 DEBUG [main] .content.wire() - << "[0x9]  {[\r][\n]"
2006-04-24 09:39:00,648 DEBUG [main] .content.wire() - << "[0x9]  
Homepage();[\r][\n]"
2006-04-24 09:39:00,648 DEBUG [main] .content.wire() - << "[0x9]  }[\r][\n]"
2006-04-24 09:39:00,648 DEBUG [main] .content.wire() - << "[0x9]  //-->[\r][\n]"
2006-04-24 09:39:00,648 DEBUG [main] .content.wire() - << "[0x9] 
</SCRIPT>[\r][\n]"
2006-04-24 09:39:00,648 DEBUG [main] .content.wire() - << "         home page,
and then look for links to the information you want. [\r][\n]"
2006-04-24 09:39:00,648 DEBUG [main] .content.wire() - << "        <LI>If you
typed the page address in the Address bar, make sure that it[\r][\n]"
2006-04-24 09:39:00,648 DEBUG [main] .content.wire() - << "        is spelled
correctly.<BR>[\r][\n]"
2006-04-24 09:39:00,648 DEBUG [main] .content.wire() - << "        <LI>Verify
that the Internet access policy on your network allows you[\r][\n]"
2006-04-24 09:39:00,648 DEBUG [main] .content.wire() - << "        to view this
this page.</LI>[\r][\n]"
2006-04-24 09:39:00,648 DEBUG [main] .content.wire() - << "        <LI>If you
believe you should be able to view this directory or page, [\r][\n]"
2006-04-24 09:39:00,648 DEBUG [main] .content.wire() - << "        please
contact the Web site administrator by using the e-mail address or [\r][\n]"
2006-04-24 09:39:00,658 DEBUG [main] .content.wire() - << "        phone number
listed on the[\r][\n]"
2006-04-24 09:39:00,658 DEBUG [main] .content.wire() - << " "
2006-04-24 09:39:00,658 DEBUG [main] .content.wire() - << "       <SCRIPT>
Homepage();</SCRIPT>[\r][\n]"
2006-04-24 09:39:00,658 DEBUG [main] .content.wire() - << "         home page.
</LI></UL>[\r][\n]"
2006-04-24 09:39:00,658 DEBUG [main] .content.wire() - << "      <H2
style="FONT: 8pt/11pt verdana; COLOR: #000000">HTTP 407 Proxy Authentication
Required - The ISA Server requires authorization to fulfill the request. Access
to the Web Proxy service is denied. (12209)<BR>Internet Security and
Acceleration Server</H2>[\r][\n]"
2006-04-24 09:39:00,658 DEBUG [main] .content.wire() - << "      <HR
color=#c0c0c0 noShade>[\r][\n]"
2006-04-24 09:39:00,658 DEBUG [main] .content.wire() - << "[\r][\n]"
2006-04-24 09:39:00,658 DEBUG [main] .content.wire() - << "      <P>Technical
Information (for support personnel)</P>[\r][\n]"
2006-04-24 09:39:00,658 DEBUG [main] .content.wire() - << "      <UL>[\r][\n]"
2006-04-24 09:39:00,658 DEBUG [main] .content.wire() - << "       
<LI>Background:<BR>The gateway could not retrieve the requested
page.<P></P></LI>[\r][\n]"
2006-04-24 09:39:00,658 DEBUG [main] .content.wire() - << "        <LI>ISA
Server: ient1isa001.ie.pri.o2.com<BR>[\r][\n]"
2006-04-24 09:39:00,658 DEBUG [main] .content.wire() - << "        Via:
<BR><BR>Time: 4/24/2006 8:39:00 AM GMT[\r][\n]"
2006-04-24 09:39:00,658 DEBUG [main] .content.wire() - << "       
</LI></UL></FONT></TD></TR></TBODY></TABLE></BODY></HTML>[\r][\n]"
2006-04-24 09:39:00,658 DEBUG [main] .HttpMethodBase.shouldCloseConnection() -
Resorting to protocol version default close connection policy
2006-04-24 09:39:00,658 DEBUG [main] .HttpMethodBase.shouldCloseConnection() -
Should NOT close connection, using HTTP/1.1
2006-04-24 09:39:00,668 DEBUG [main] .HttpConnection.isResponseAvailable() -
enter HttpConnection.isResponseAvailable()
2006-04-24 09:39:00,668 DEBUG [main] .HttpConnection.releaseConnection() - enter
HttpConnection.releaseConnection()
2006-04-24 09:39:00,668 DEBUG [main] .HttpConnection.releaseConnection() -
Connection is locked.  Call to releaseConnection() ignored.
2006-04-24 09:39:00,668 DEBUG [main] .HttpMethodDirector.authenticateProxy() -
Authenticating with NTLM <any realm>@172.26.11.47:80
2006-04-24 09:39:00,668 DEBUG [main] .HttpState.getProxyCredentials() - enter
HttpState.getProxyCredentials(AuthScope)
2006-04-24 09:39:00,668 DEBUG [main] .NTLMScheme.authenticate() - enter
NTLMScheme.authenticate(Credentials, HttpMethod)
2006-04-24 09:39:00,668 DEBUG [main] .HttpMethodParams.getCredentialCharset() -
Credential charset not configured, using HTTP element charset
2006-04-24 09:39:00,728 DEBUG [main] .HttpMethodBase.addRequestHeader() -
HttpMethodBase.addRequestHeader(Header)
2006-04-24 09:39:00,738 DEBUG [main] .HttpMethodDirector.executeWithRetry() -
Attempt number 1 to process request
2006-04-24 09:39:00,738 DEBUG [main] .HttpMethodBase.execute() - enter
HttpMethodBase.execute(HttpState, HttpConnection)
2006-04-24 09:39:00,738 DEBUG [main] .HttpMethodBase.writeRequest() - enter
HttpMethodBase.writeRequest(HttpState, HttpConnection)
2006-04-24 09:39:00,738 DEBUG [main] .HttpMethodBase.writeRequestLine() - enter
HttpMethodBase.writeRequestLine(HttpState, HttpConnection)
2006-04-24 09:39:00,748 DEBUG [main] .HttpMethodBase.generateRequestLine() -
enter HttpMethodBase.generateRequestLine(HttpConnection, String, String, String,
String)
2006-04-24 09:39:00,748 DEBUG [main] .header.wire() - >> "GET
http://www.google.com/ HTTP/1.1[\r][\n]"
2006-04-24 09:39:00,748 DEBUG [main] .HttpConnection.print() - enter
HttpConnection.print(String)
2006-04-24 09:39:00,748 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[])
2006-04-24 09:39:00,748 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[], int, int)
2006-04-24 09:39:00,748 DEBUG [main] .HttpMethodBase.writeRequestHeaders() -
enter HttpMethodBase.writeRequestHeaders(HttpState,HttpConnection)
2006-04-24 09:39:00,748 DEBUG [main] .HttpMethodBase.addRequestHeaders() - enter
HttpMethodBase.addRequestHeaders(HttpState, HttpConnection)
2006-04-24 09:39:00,748 DEBUG [main] .HttpMethodBase.addUserAgentRequestHeader()
- enter HttpMethodBase.addUserAgentRequestHeaders(HttpState, HttpConnection)
2006-04-24 09:39:00,758 DEBUG [main] .HttpMethodBase.addHostRequestHeader() -
enter HttpMethodBase.addHostRequestHeader(HttpState, HttpConnection)
2006-04-24 09:39:00,758 DEBUG [main] .HttpMethodBase.addHostRequestHeader() -
Adding Host request header
2006-04-24 09:39:00,758 DEBUG [main] .HttpMethodBase.addCookieRequestHeader() -
enter HttpMethodBase.addCookieRequestHeader(HttpState, HttpConnection)
2006-04-24 09:39:00,758 DEBUG [main] .HttpState.getCookies() - enter
HttpState.getCookies()
2006-04-24 09:39:00,758 DEBUG [main] .CookieSpec.match() - enter
CookieSpecBase.match(String, int, String, boolean, Cookie[])
2006-04-24 09:39:00,758 DEBUG [main] .HttpMethodBase.addProxyConnectionHeader()
- enter HttpMethodBase.addProxyConnectionHeader(HttpState, HttpConnection)
2006-04-24 09:39:00,758 DEBUG [main] .header.wire() - >> "User-Agent: Jakarta
Commons-HttpClient/3.0[\r][\n]"
2006-04-24 09:39:00,758 DEBUG [main] .HttpConnection.print() - enter
HttpConnection.print(String)
2006-04-24 09:39:00,758 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[])
2006-04-24 09:39:00,758 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[], int, int)
2006-04-24 09:39:00,758 DEBUG [main] .header.wire() - >> "Proxy-Connection:
Keep-Alive[\r][\n]"
2006-04-24 09:39:00,758 DEBUG [main] .HttpConnection.print() - enter
HttpConnection.print(String)
2006-04-24 09:39:00,758 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[])
2006-04-24 09:39:00,758 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[], int, int)
2006-04-24 09:39:00,758 DEBUG [main] .header.wire() - >> "Proxy-Authorization:
NTLM TlRMTVNTUAABAAAABlIAAAAAAAAgAAAAAAAAACAAAAA=[\r][\n]"
2006-04-24 09:39:00,758 DEBUG [main] .HttpConnection.print() - enter
HttpConnection.print(String)
2006-04-24 09:39:00,758 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[])
2006-04-24 09:39:00,768 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[], int, int)
2006-04-24 09:39:00,768 DEBUG [main] .header.wire() - >> "Host:
www.google.com[\r][\n]"
2006-04-24 09:39:00,768 DEBUG [main] .HttpConnection.print() - enter
HttpConnection.print(String)
2006-04-24 09:39:00,768 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[])
2006-04-24 09:39:00,768 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[], int, int)
2006-04-24 09:39:00,768 DEBUG [main] .HttpConnection.writeLine() - enter
HttpConnection.writeLine()
2006-04-24 09:39:00,768 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[])
2006-04-24 09:39:00,768 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[], int, int)
2006-04-24 09:39:00,768 DEBUG [main] .header.wire() - >> "[\r][\n]"
2006-04-24 09:39:00,768 DEBUG [main] .HttpConnection.flushRequestOutputStream()
- enter HttpConnection.flushRequestOutputStream()
2006-04-24 09:39:00,768 DEBUG [main] .HttpMethodBase.readResponse() - enter
HttpMethodBase.readResponse(HttpState, HttpConnection)
2006-04-24 09:39:00,768 DEBUG [main] .HttpMethodBase.readStatusLine() - enter
HttpMethodBase.readStatusLine(HttpState, HttpConnection)
2006-04-24 09:39:00,768 DEBUG [main] .HttpConnection.readLine() - enter
HttpConnection.readLine()
2006-04-24 09:39:00,768 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:00,778 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:00,778 DEBUG [main] .header.wire() - << "HTTP/1.1 407 Proxy
Authentication Required ( Access is denied.  )[\r][\n]"
2006-04-24 09:39:00,778 DEBUG [main] .HttpMethodBase.readResponseHeaders() -
enter HttpMethodBase.readResponseHeaders(HttpState,HttpConnection)
2006-04-24 09:39:00,778 DEBUG [main] .HttpConnection.getResponseInputStream() -
enter HttpConnection.getResponseInputStream()
2006-04-24 09:39:00,778 DEBUG [main] .HttpParser.parseHeaders() - enter
HeaderParser.parseHeaders(InputStream, String)
2006-04-24 09:39:00,778 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:00,778 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:00,778 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:00,778 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:00,778 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:00,778 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:00,788 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:00,788 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:00,788 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:00,788 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:00,788 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:00,788 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:00,788 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:00,788 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:00,788 DEBUG [main] .header.wire() - << "Via: 1.1
IENT1ISA001[\r][\n]"
2006-04-24 09:39:00,788 DEBUG [main] .header.wire() - << "Proxy-Authenticate:
NTLM
TlRMTVNTUAACAAAAAgACADgAAAAGAoECXTxM97giKVUAAAAAAAAAAHoAegA6AAAABQCTCAAAAA9JRQIABABJAEUAAQAWAEkARQBOAFQAMQBJAFMAQQAwADAAMQAEABoAaQBlAC4AcAByAGkALgBvADIALgBjAG8AbQADADIAaQBlAG4AdAAxAGkAcwBhADAAMAAxAC4AaQBlAC4AcAByAGkALgBvADIALgBjAG8AbQAAAAAA[\r][\n]"
2006-04-24 09:39:00,819 DEBUG [main] .header.wire() - << "Pragma:
no-cache[\r][\n]"
2006-04-24 09:39:00,819 DEBUG [main] .header.wire() - << "Cache-Control:
no-cache[\r][\n]"
2006-04-24 09:39:00,819 DEBUG [main] .header.wire() - << "Content-Type:
text/html[\r][\n]"
2006-04-24 09:39:00,819 DEBUG [main] .header.wire() - << "Content-Length:
0[\r][\n]"
2006-04-24 09:39:00,819 DEBUG [main] .HttpMethodBase.processResponseHeaders() -
enter HttpMethodBase.processResponseHeaders(HttpState, HttpConnection)
2006-04-24 09:39:00,819 DEBUG [main] .HttpMethodBase.readResponseBody() - enter
HttpMethodBase.readResponseBody(HttpState, HttpConnection)
2006-04-24 09:39:00,819 DEBUG [main] .HttpMethodBase.readResponseBody() - enter
HttpMethodBase.readResponseBody(HttpConnection)
2006-04-24 09:39:00,819 DEBUG [main] .HttpConnection.getResponseInputStream() -
enter HttpConnection.getResponseInputStream()
2006-04-24 09:39:00,819 DEBUG [main] .HttpMethodBase.canResponseHaveBody() -
enter HttpMethodBase.canResponseHaveBody(int)
2006-04-24 09:39:00,819 DEBUG [main]
.HttpMethodDirector.isAuthenticationNeeded() - Authorization required
2006-04-24 09:39:00,819 DEBUG [main]
.HttpMethodDirector.processAuthenticationResponse() - enter
HttpMethodBase.processAuthenticationResponse(HttpState, HttpConnection)
2006-04-24 09:39:00,829 DEBUG [main] .AuthChallengeProcessor.processChallenge()
- Using authentication scheme: ntlm
2006-04-24 09:39:00,829 DEBUG [main] .AuthChallengeProcessor.processChallenge()
- Authorization challenge processed
2006-04-24 09:39:00,829 DEBUG [main]
.HttpMethodDirector.processProxyAuthChallenge() - Proxy authentication scope:
NTLM <any realm>@172.26.11.47:80
2006-04-24 09:39:00,829 DEBUG [main] .HttpState.getProxyCredentials() - enter
HttpState.getProxyCredentials(AuthScope)
2006-04-24 09:39:00,829 DEBUG [main] .HttpMethodDirector.executeMethod() - Retry
authentication
2006-04-24 09:39:00,829 DEBUG [main] .HttpMethodBase.shouldCloseConnection() -
Resorting to protocol version default close connection policy
2006-04-24 09:39:00,829 DEBUG [main] .HttpMethodBase.shouldCloseConnection() -
Should NOT close connection, using HTTP/1.1
2006-04-24 09:39:00,829 DEBUG [main] .HttpConnection.isResponseAvailable() -
enter HttpConnection.isResponseAvailable()
2006-04-24 09:39:00,829 DEBUG [main] .HttpConnection.releaseConnection() - enter
HttpConnection.releaseConnection()
2006-04-24 09:39:00,829 DEBUG [main] .HttpConnection.releaseConnection() -
Connection is locked.  Call to releaseConnection() ignored.
2006-04-24 09:39:00,829 DEBUG [main] .HttpMethodDirector.authenticateProxy() -
Authenticating with NTLM <any realm>@172.26.11.47:80
2006-04-24 09:39:00,829 DEBUG [main] .HttpState.getProxyCredentials() - enter
HttpState.getProxyCredentials(AuthScope)
2006-04-24 09:39:00,829 DEBUG [main] .NTLMScheme.authenticate() - enter
NTLMScheme.authenticate(Credentials, HttpMethod)
2006-04-24 09:39:00,829 DEBUG [main] .HttpMethodParams.getCredentialCharset() -
Credential charset not configured, using HTTP element charset
2006-04-24 09:39:03,643 DEBUG [main] .HttpMethodBase.addRequestHeader() -
HttpMethodBase.addRequestHeader(Header)
2006-04-24 09:39:03,643 DEBUG [main] .HttpMethodDirector.executeWithRetry() -
Attempt number 1 to process request
2006-04-24 09:39:03,643 DEBUG [main] .HttpMethodBase.execute() - enter
HttpMethodBase.execute(HttpState, HttpConnection)
2006-04-24 09:39:03,643 DEBUG [main] .HttpMethodBase.writeRequest() - enter
HttpMethodBase.writeRequest(HttpState, HttpConnection)
2006-04-24 09:39:03,643 DEBUG [main] .HttpMethodBase.writeRequestLine() - enter
HttpMethodBase.writeRequestLine(HttpState, HttpConnection)
2006-04-24 09:39:03,643 DEBUG [main] .HttpMethodBase.generateRequestLine() -
enter HttpMethodBase.generateRequestLine(HttpConnection, String, String, String,
String)
2006-04-24 09:39:03,643 DEBUG [main] .header.wire() - >> "GET
http://www.google.com/ HTTP/1.1[\r][\n]"
2006-04-24 09:39:03,643 DEBUG [main] .HttpConnection.print() - enter
HttpConnection.print(String)
2006-04-24 09:39:03,643 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[])
2006-04-24 09:39:03,643 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[], int, int)
2006-04-24 09:39:03,643 DEBUG [main] .HttpMethodBase.writeRequestHeaders() -
enter HttpMethodBase.writeRequestHeaders(HttpState,HttpConnection)
2006-04-24 09:39:03,653 DEBUG [main] .HttpMethodBase.addRequestHeaders() - enter
HttpMethodBase.addRequestHeaders(HttpState, HttpConnection)
2006-04-24 09:39:03,653 DEBUG [main] .HttpMethodBase.addUserAgentRequestHeader()
- enter HttpMethodBase.addUserAgentRequestHeaders(HttpState, HttpConnection)
2006-04-24 09:39:03,653 DEBUG [main] .HttpMethodBase.addHostRequestHeader() -
enter HttpMethodBase.addHostRequestHeader(HttpState, HttpConnection)
2006-04-24 09:39:03,653 DEBUG [main] .HttpMethodBase.addHostRequestHeader() -
Adding Host request header
2006-04-24 09:39:03,653 DEBUG [main] .HttpMethodBase.addCookieRequestHeader() -
enter HttpMethodBase.addCookieRequestHeader(HttpState, HttpConnection)
2006-04-24 09:39:03,653 DEBUG [main] .HttpState.getCookies() - enter
HttpState.getCookies()
2006-04-24 09:39:03,653 DEBUG [main] .CookieSpec.match() - enter
CookieSpecBase.match(String, int, String, boolean, Cookie[])
2006-04-24 09:39:03,653 DEBUG [main] .HttpMethodBase.addProxyConnectionHeader()
- enter HttpMethodBase.addProxyConnectionHeader(HttpState, HttpConnection)
2006-04-24 09:39:03,653 DEBUG [main] .header.wire() - >> "User-Agent: Jakarta
Commons-HttpClient/3.0[\r][\n]"
2006-04-24 09:39:03,653 DEBUG [main] .HttpConnection.print() - enter
HttpConnection.print(String)
2006-04-24 09:39:03,653 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[])
2006-04-24 09:39:03,653 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[], int, int)
2006-04-24 09:39:03,653 DEBUG [main] .header.wire() - >> "Proxy-Connection:
Keep-Alive[\r][\n]"
2006-04-24 09:39:03,653 DEBUG [main] .HttpConnection.print() - enter
HttpConnection.print(String)
2006-04-24 09:39:03,653 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[])
2006-04-24 09:39:03,653 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[], int, int)
2006-04-24 09:39:03,663 DEBUG [main] .header.wire() - >> "Proxy-Authorization:
NTLM
TlRMTVNTUAADAAAAGAAYAEoAAAAAAAAAYgAAAAAAAABAAAAACgAKAEAAAAAAAAAASgAAAAAAAABiAAAABlIAAENfSFVSTEVZX0L2nrlv+gaLKRWSCkXzw5BWEVvoFHeavgE=[\r][\n]"
2006-04-24 09:39:03,663 DEBUG [main] .HttpConnection.print() - enter
HttpConnection.print(String)
2006-04-24 09:39:03,663 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[])
2006-04-24 09:39:03,663 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[], int, int)
2006-04-24 09:39:03,663 DEBUG [main] .header.wire() - >> "Host:
www.google.com[\r][\n]"
2006-04-24 09:39:03,663 DEBUG [main] .HttpConnection.print() - enter
HttpConnection.print(String)
2006-04-24 09:39:03,663 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[])
2006-04-24 09:39:03,663 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[], int, int)
2006-04-24 09:39:03,663 DEBUG [main] .HttpConnection.writeLine() - enter
HttpConnection.writeLine()
2006-04-24 09:39:03,663 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[])
2006-04-24 09:39:03,663 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[], int, int)
2006-04-24 09:39:03,663 DEBUG [main] .header.wire() - >> "[\r][\n]"
2006-04-24 09:39:03,663 DEBUG [main] .HttpConnection.flushRequestOutputStream()
- enter HttpConnection.flushRequestOutputStream()
2006-04-24 09:39:03,663 DEBUG [main] .HttpMethodBase.readResponse() - enter
HttpMethodBase.readResponse(HttpState, HttpConnection)
2006-04-24 09:39:03,663 DEBUG [main] .HttpMethodBase.readStatusLine() - enter
HttpMethodBase.readStatusLine(HttpState, HttpConnection)
2006-04-24 09:39:03,673 DEBUG [main] .HttpConnection.readLine() - enter
HttpConnection.readLine()
2006-04-24 09:39:03,673 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:03,673 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:03,853 DEBUG [main] .header.wire() - << "HTTP/1.1 302
Found[\r][\n]"
2006-04-24 09:39:03,853 DEBUG [main] .HttpMethodBase.readResponseHeaders() -
enter HttpMethodBase.readResponseHeaders(HttpState,HttpConnection)
2006-04-24 09:39:03,853 DEBUG [main] .HttpConnection.getResponseInputStream() -
enter HttpConnection.getResponseInputStream()
2006-04-24 09:39:03,853 DEBUG [main] .HttpParser.parseHeaders() - enter
HeaderParser.parseHeaders(InputStream, String)
2006-04-24 09:39:03,853 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:03,853 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:03,853 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:03,853 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:03,853 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:03,853 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:03,853 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:03,853 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:03,853 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:03,853 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:03,853 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:03,853 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:03,853 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:03,853 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:03,863 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:03,863 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:03,863 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:03,863 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:03,863 DEBUG [main] .header.wire() - << "Via: 1.0
IENT1ISA001[\r][\n]"
2006-04-24 09:39:03,863 DEBUG [main] .header.wire() - << "Content-Length:
218[\r][\n]"
2006-04-24 09:39:03,863 DEBUG [main] .header.wire() - << "Date: Mon, 24 Apr 2006
08:39:03 GMT[\r][\n]"
2006-04-24 09:39:03,863 DEBUG [main] .header.wire() - << "Location:
http://www.google.ie/[\r][\n]"
2006-04-24 09:39:03,863 DEBUG [main] .header.wire() - << "Content-Type:
text/html[\r][\n]"
2006-04-24 09:39:03,863 DEBUG [main] .header.wire() - << "Cache-Control:
private[\r][\n]"
2006-04-24 09:39:03,863 DEBUG [main] .header.wire() - << "Set-Cookie:
PREF=ID=402abfdf488b5626:TM=1145867943:LM=1145867943:S=6dO6yF4X_3knBWZr;
expires=Sun, 17-Jan-2038 19:14:07 GMT; path=/; domain=.google.com[\r][\n]"
2006-04-24 09:39:03,863 DEBUG [main] .header.wire() - << "Server:
GWS/2.1[\r][\n]"
2006-04-24 09:39:03,863 DEBUG [main] .HttpMethodBase.processResponseHeaders() -
enter HttpMethodBase.processResponseHeaders(HttpState, HttpConnection)
2006-04-24 09:39:03,863 DEBUG [main] .CookieSpec.parse() - enter
CookieSpecBase.parse(String, port, path, boolean, String)
2006-04-24 09:39:03,863 DEBUG [main] .CookieSpec.parse() - enter
CookieSpecBase.parse(String, port, path, boolean, Header)
2006-04-24 09:39:03,963 DEBUG [main] .Cookie.<init>() - enter Cookie(String,
String, String, String, Date, boolean)
2006-04-24 09:39:03,963 DEBUG [main] .CookieSpec.validate() - enter
RFC2109Spec.validate(String, int, String, boolean, Cookie)
2006-04-24 09:39:03,973 DEBUG [main] .CookieSpec.validate() - enter
CookieSpecBase.validate(String, port, path, boolean, Cookie)
2006-04-24 09:39:03,973 DEBUG [main] .HttpState.addCookie() - enter
HttpState.addCookie(Cookie)
2006-04-24 09:39:03,973 DEBUG [main] .CookieSpec.formatCookie() - enter
RFC2109Spec.formatCookie(Cookie)
2006-04-24 09:39:03,973 DEBUG [main] .HttpMethodBase.processResponseHeaders() -
Cookie accepted: "$Version=0;
PREF=ID=402abfdf488b5626:TM=1145867943:LM=1145867943:S=6dO6yF4X_3knBWZr;
$Path=/; $Domain=.google.com"
2006-04-24 09:39:03,973 DEBUG [main] .HttpMethodBase.readResponseBody() - enter
HttpMethodBase.readResponseBody(HttpState, HttpConnection)
2006-04-24 09:39:03,973 DEBUG [main] .HttpMethodBase.readResponseBody() - enter
HttpMethodBase.readResponseBody(HttpConnection)
2006-04-24 09:39:03,973 DEBUG [main] .HttpConnection.getResponseInputStream() -
enter HttpConnection.getResponseInputStream()
2006-04-24 09:39:03,973 DEBUG [main] .HttpMethodBase.canResponseHaveBody() -
enter HttpMethodBase.canResponseHaveBody(int)
2006-04-24 09:39:03,973 DEBUG [main] .HttpMethodDirector.isRedirectNeeded() -
Redirect required
2006-04-24 09:39:03,973 DEBUG [main]
.HttpMethodDirector.processRedirectResponse() - Redirect requested to location
'http://www.google.ie/'
2006-04-24 09:39:03,983 DEBUG [main]
.HttpMethodDirector.processRedirectResponse() - Redirecting from
'http://www.google.com:80/' to 'http://www.google.ie/
2006-04-24 09:39:03,983 DEBUG [main] .HttpMethodDirector.executeMethod() -
Execute redirect 1 of 100
2006-04-24 09:39:03,983 DEBUG [main] .content.wire() - << "<HTML><HEAD><meta
http-equiv="content-type" content="text/html;charset=utf-8">[\n]"
2006-04-24 09:39:03,983 DEBUG [main] .content.wire() - << "<TITLE>302
Moved</TITLE></HEAD><BODY>[\n]"
2006-04-24 09:39:03,983 DEBUG [main] .content.wire() - << "<H1>302
Moved</H1>[\n]"
2006-04-24 09:39:03,983 DEBUG [main] .content.wire() - << "The document has
moved[\n]"
2006-04-24 09:39:03,983 DEBUG [main] .content.wire() - << "<A
HREF="http://www.google.ie/">here</A>.[\r][\n]"
2006-04-24 09:39:03,983 DEBUG [main] .content.wire() - <<
"</BODY></HTML>[\r][\n]"
2006-04-24 09:39:03,983 DEBUG [main] .HttpMethodBase.shouldCloseConnection() -
Resorting to protocol version default close connection policy
2006-04-24 09:39:03,983 DEBUG [main] .HttpMethodBase.shouldCloseConnection() -
Should NOT close connection, using HTTP/1.1
2006-04-24 09:39:03,983 DEBUG [main] .HttpConnection.isResponseAvailable() -
enter HttpConnection.isResponseAvailable()
2006-04-24 09:39:03,983 DEBUG [main] .HttpConnection.releaseConnection() - enter
HttpConnection.releaseConnection()
2006-04-24 09:39:03,983 DEBUG [main] .HttpConnection.releaseConnection() -
Connection is locked.  Call to releaseConnection() ignored.
2006-04-24 09:39:03,983 DEBUG [main] .HttpConnection.releaseConnection() - enter
HttpConnection.releaseConnection()
2006-04-24 09:39:03,993 DEBUG [main] .HttpConnection.releaseConnection() -
Releasing connection back to connection manager.
2006-04-24 09:39:03,993 DEBUG [main] .HttpConnection.close() - enter
HttpConnection.close()
2006-04-24 09:39:03,993 DEBUG [main] .HttpConnection.closeSocketAndStreams() -
enter HttpConnection.closeSockedAndStreams()
2006-04-24 09:39:03,993 DEBUG [main] .HttpMethodDirector.executeWithRetry() -
Attempt number 1 to process request
2006-04-24 09:39:03,993 DEBUG [main] .HttpConnection.open() - enter
HttpConnection.open()
2006-04-24 09:39:03,993 DEBUG [main] .HttpConnection.open() - Open connection to
172.26.11.47:80
2006-04-24 09:39:03,993 DEBUG [main] .HttpMethodBase.execute() - enter
HttpMethodBase.execute(HttpState, HttpConnection)
2006-04-24 09:39:04,003 DEBUG [main] .HttpMethodBase.writeRequest() - enter
HttpMethodBase.writeRequest(HttpState, HttpConnection)
2006-04-24 09:39:04,003 DEBUG [main] .HttpMethodBase.writeRequestLine() - enter
HttpMethodBase.writeRequestLine(HttpState, HttpConnection)
2006-04-24 09:39:04,003 DEBUG [main] .HttpMethodBase.generateRequestLine() -
enter HttpMethodBase.generateRequestLine(HttpConnection, String, String, String,
String)
2006-04-24 09:39:04,003 DEBUG [main] .header.wire() - >> "GET
http://www.google.ie/ HTTP/1.1[\r][\n]"
2006-04-24 09:39:04,003 DEBUG [main] .HttpConnection.print() - enter
HttpConnection.print(String)
2006-04-24 09:39:04,003 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[])
2006-04-24 09:39:04,003 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[], int, int)
2006-04-24 09:39:04,003 DEBUG [main] .HttpMethodBase.writeRequestHeaders() -
enter HttpMethodBase.writeRequestHeaders(HttpState,HttpConnection)
2006-04-24 09:39:04,003 DEBUG [main] .HttpMethodBase.addRequestHeaders() - enter
HttpMethodBase.addRequestHeaders(HttpState, HttpConnection)
2006-04-24 09:39:04,003 DEBUG [main] .HttpMethodBase.addUserAgentRequestHeader()
- enter HttpMethodBase.addUserAgentRequestHeaders(HttpState, HttpConnection)
2006-04-24 09:39:04,003 DEBUG [main] .HttpMethodBase.addHostRequestHeader() -
enter HttpMethodBase.addHostRequestHeader(HttpState, HttpConnection)
2006-04-24 09:39:04,003 DEBUG [main] .HttpMethodBase.addHostRequestHeader() -
Adding Host request header
2006-04-24 09:39:04,003 DEBUG [main] .HttpMethodBase.addCookieRequestHeader() -
enter HttpMethodBase.addCookieRequestHeader(HttpState, HttpConnection)
2006-04-24 09:39:04,003 DEBUG [main] .HttpState.getCookies() - enter
HttpState.getCookies()
2006-04-24 09:39:04,003 DEBUG [main] .CookieSpec.match() - enter
CookieSpecBase.match(String, int, String, boolean, Cookie[])
2006-04-24 09:39:04,003 DEBUG [main] .CookieSpec.match() - enter
CookieSpecBase.match(String, int, String, boolean, Cookie
2006-04-24 09:39:04,003 DEBUG [main] .HttpMethodBase.addProxyConnectionHeader()
- enter HttpMethodBase.addProxyConnectionHeader(HttpState, HttpConnection)
2006-04-24 09:39:04,003 DEBUG [main] .header.wire() - >> "User-Agent: Jakarta
Commons-HttpClient/3.0[\r][\n]"
2006-04-24 09:39:04,013 DEBUG [main] .HttpConnection.print() - enter
HttpConnection.print(String)
2006-04-24 09:39:04,013 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[])
2006-04-24 09:39:04,013 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[], int, int)
2006-04-24 09:39:04,013 DEBUG [main] .header.wire() - >> "Proxy-Connection:
Keep-Alive[\r][\n]"
2006-04-24 09:39:04,023 DEBUG [main] .HttpConnection.print() - enter
HttpConnection.print(String)
2006-04-24 09:39:04,023 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[])
2006-04-24 09:39:04,023 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[], int, int)
2006-04-24 09:39:04,023 DEBUG [main] .header.wire() - >> "Host:
www.google.ie[\r][\n]"
2006-04-24 09:39:04,023 DEBUG [main] .HttpConnection.print() - enter
HttpConnection.print(String)
2006-04-24 09:39:04,023 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[])
2006-04-24 09:39:04,023 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[], int, int)
2006-04-24 09:39:04,023 DEBUG [main] .HttpConnection.writeLine() - enter
HttpConnection.writeLine()
2006-04-24 09:39:04,023 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[])
2006-04-24 09:39:04,023 DEBUG [main] .HttpConnection.write() - enter
HttpConnection.write(byte[], int, int)
2006-04-24 09:39:04,033 DEBUG [main] .header.wire() - >> "[\r][\n]"
2006-04-24 09:39:04,033 DEBUG [main] .HttpConnection.flushRequestOutputStream()
- enter HttpConnection.flushRequestOutputStream()
2006-04-24 09:39:04,033 DEBUG [main] .HttpMethodBase.readResponse() - enter
HttpMethodBase.readResponse(HttpState, HttpConnection)
2006-04-24 09:39:04,033 DEBUG [main] .HttpMethodBase.readStatusLine() - enter
HttpMethodBase.readStatusLine(HttpState, HttpConnection)
2006-04-24 09:39:04,033 DEBUG [main] .HttpConnection.readLine() - enter
HttpConnection.readLine()
2006-04-24 09:39:04,063 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:04,063 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:04,063 DEBUG [main] .header.wire() - << "HTTP/1.1 407 Proxy
Authentication Required ( The ISA Server requires authorization to fulfill the
request. Access to the Web Proxy service is denied.  )[\r][\n]"
2006-04-24 09:39:04,073 DEBUG [main] .HttpMethodBase.readResponseHeaders() -
enter HttpMethodBase.readResponseHeaders(HttpState,HttpConnection)
2006-04-24 09:39:04,073 DEBUG [main] .HttpConnection.getResponseInputStream() -
enter HttpConnection.getResponseInputStream()
2006-04-24 09:39:04,073 DEBUG [main] .HttpParser.parseHeaders() - enter
HeaderParser.parseHeaders(InputStream, String)
2006-04-24 09:39:04,073 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:04,073 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:04,083 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:04,083 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:04,083 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:04,083 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:04,083 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:04,083 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:04,083 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:04,083 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:04,083 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:04,083 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:04,083 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:04,083 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:04,083 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:04,093 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:04,093 DEBUG [main] .HttpParser.readLine() - enter
HttpParser.readLine(InputStream, String)
2006-04-24 09:39:04,093 DEBUG [main] .HttpParser.readRawLine() - enter
HttpParser.readRawLine()
2006-04-24 09:39:04,093 DEBUG [main] .header.wire() - << "Via: 1.1
IENT1ISA001[\r][\n]"
2006-04-24 09:39:04,093 DEBUG [main] .header.wire() - << "Proxy-Authenticate:
NTLM[\r][\n]"
2006-04-24 09:39:04,093 DEBUG [main] .header.wire() - << "Proxy-Authenticate:
Kerberos[\r][\n]"
2006-04-24 09:39:04,093 DEBUG [main] .header.wire() - << "Proxy-Authenticate:
Negotiate[\r][\n]"
2006-04-24 09:39:04,093 DEBUG [main] .header.wire() - << "Pragma:
no-cache[\r][\n]"
2006-04-24 09:39:04,093 DEBUG [main] .header.wire() - << "Cache-Control:
no-cache[\r][\n]"
2006-04-24 09:39:04,093 DEBUG [main] .header.wire() - << "Content-Type:
text/html[\r][\n]"
2006-04-24 09:39:04,093 DEBUG [main] .header.wire() - << "Content-Length:
3778[\r][\n]"
2006-04-24 09:39:04,093 DEBUG [main] .HttpMethodBase.processResponseHeaders() -
enter HttpMethodBase.processResponseHeaders(HttpState, HttpConnection)
2006-04-24 09:39:04,093 DEBUG [main] .HttpMethodBase.readResponseBody() - enter
HttpMethodBase.readResponseBody(HttpState, HttpConnection)
2006-04-24 09:39:04,093 DEBUG [main] .HttpMethodBase.readResponseBody() - enter
HttpMethodBase.readResponseBody(HttpConnection)
2006-04-24 09:39:04,103 DEBUG [main] .HttpConnection.getResponseInputStream() -
enter HttpConnection.getResponseInputStream()
2006-04-24 09:39:04,103 DEBUG [main] .HttpMethodBase.canResponseHaveBody() -
enter HttpMethodBase.canResponseHaveBody(int)
2006-04-24 09:39:04,103 DEBUG [main]
.HttpMethodDirector.isAuthenticationNeeded() - Authorization required
2006-04-24 09:39:04,103 DEBUG [main]
.HttpMethodDirector.processAuthenticationResponse() - enter
HttpMethodBase.processAuthenticationResponse(HttpState, HttpConnection)
2006-04-24 09:39:04,103 DEBUG [main] .AuthChallengeProcessor.processChallenge()
- Using authentication scheme: ntlm
2006-04-24 09:39:04,103 DEBUG [main] .AuthChallengeProcessor.processChallenge()
- Authorization challenge processed
2006-04-24 09:39:04,103 DEBUG [main]
.HttpMethodDirector.processProxyAuthChallenge() - Proxy authentication scope:
NTLM <any realm>@172.26.11.47:80
2006-04-24 09:39:04,103 DEBUG [main]
.HttpMethodDirector.promptForProxyCredentials() - Proxy credentials required
2006-04-24 09:39:04,103 DEBUG [main]
.HttpMethodDirector.promptForProxyCredentials() - Proxy credentials provider not
available
2006-04-24 09:39:04,103 INFO  [main]
.HttpMethodDirector.processProxyAuthChallenge() - Failure authenticating with
NTLM <any realm>@172.26.11.47:80
2006-04-24 09:39:04,103 DEBUG [main] .HttpTest.getTest() - status 407
2006-04-24 09:39:04,103 DEBUG [main] .content.wire() - << "<!DOCTYPE HTML PUBLIC
"-//W3C//DTD HTML 4.0 Transitional//EN">[\r][\n]"
2006-04-24 09:39:04,103 DEBUG [main] .content.wire() - << "<HTML
dir=ltr><HEAD><TITLE>The page cannot be displayed</TITLE>[\r][\n]"
2006-04-24 09:39:04,113 DEBUG [main] .content.wire() - << "<STYLE>A:link
{[\r][\n]"
2006-04-24 09:39:04,113 DEBUG [main] .content.wire() - << "[0x9]FONT: 8pt/11pt
verdana; COLOR: #ff0000[\r][\n]"
2006-04-24 09:39:04,113 DEBUG [main] .content.wire() - << "}[\r][\n]"
2006-04-24 09:39:04,113 DEBUG [main] .content.wire() - << "A:visited {[\r][\n]"
2006-04-24 09:39:04,113 DEBUG [main] .content.wire() - << "[0x9]FONT: 8pt/11pt
verdana; COLOR: #4e4e4e[\r][\n]"
2006-04-24 09:39:04,113 DEBUG [main] .content.wire() - << "}[\r][\n]"
2006-04-24 09:39:04,113 DEBUG [main] .content.wire() - << "</STYLE>[\r][\n]"
2006-04-24 09:39:04,113 DEBUG [main] .content.wire() - << "[\r][\n]"
2006-04-24 09:39:04,113 DEBUG [main] .content.wire() - << "<META content=NOINDEX
name=ROBOTS>[\r][\n]"
2006-04-24 09:39:04,113 DEBUG [main] .content.wire() - << "<META
http-equiv=Content-Type content="text-html; charset=Windows-1252">[\r][\n]"
2006-04-24 09:39:04,113 DEBUG [main] .content.wire() - << "<SCRIPT> [\r][\n]"
2006-04-24 09:39:04,113 DEBUG [main] .content.wire() - << "function
Homepage(){[\r][\n]"
2006-04-24 09:39:04,113 DEBUG [main] .content.wire() - << "<!--[\r][\n]"
2006-04-24 09:39:04,113 DEBUG [main] .content.wire() - << "// in real bits, urls
get returned to our script like this:[\r][\n]"
2006-04-24 09:39:04,113 DEBUG [main] .content.wire() - << "//
res://shdocvw.dll/http_404.htm#http://www.DocURL.com/bar.htm [\r][\n]"
2006-04-24 09:39:04,123 DEBUG [main] .content.wire() - << "[\r][\n]"
2006-04-24 09:39:04,123 DEBUG [main] .content.wire() - << "[0x9]//For testing
use DocURL =
"res://shdocvw.dll/http_404.htm#https://www.microsoft.com/bar.htm"[\r][\n]"
2006-04-24 09:39:04,123 DEBUG [main] .content.wire() - <<
"[0x9]DocURL=document.URL;[\r][\n]"
2006-04-24 09:39:04,123 DEBUG [main] .content.wire() - << "[0x9][\r][\n]"
2006-04-24 09:39:04,123 DEBUG [main] .content.wire() - << "[0x9]//this is where
the http or https will be, as found by searching for :// but skipping the
res://[\r][\n]"
2006-04-24 09:39:04,123 DEBUG [main] .content.wire() - <<
"[0x9]protocolIndex=DocURL.indexOf("://",4);[\r][\n]"
2006-04-24 09:39:04,123 DEBUG [main] .content.wire() - << "[0x9][\r][\n]"
2006-04-24 09:39:04,134 DEBUG [main] .content.wire() - << "[0x9]//this finds the
ending slash for the domain server [\r][\n]"
2006-04-24 09:39:04,134 DEBUG [main] .content.wire() - <<
"[0x9]serverIndex=DocURL.indexOf("/",protocolIndex + 3);[\r][\n]"
2006-04-24 09:39:04,134 DEBUG [main] .content.wire() - << "[\r][\n]"
2006-04-24 09:39:04,134 DEBUG [main] .content.wire() - << "[0x9]//for the href,
we need a valid URL to the domain. We search for the # symbol to find the
begining [\r][\n]"
2006-04-24 09:39:04,134 DEBUG [main] .content.wire() - << "[0x9]//of the "
2006-04-24 09:39:04,144 DEBUG [main] .content.wire() - << "true URL, and add 1
to skip it - this is the BeginURL value. We use serverIndex as the end
marker.[\r][\n]"
2006-04-24 09:39:04,144 DEBUG [main] .content.wire() - <<
"[0x9]//urlresult=DocURL.substring(protocolIndex - 4,serverIndex);[\r][\n]"
2006-04-24 09:39:04,144 DEBUG [main] .content.wire() - <<
"[0x9]BeginURL=DocURL.indexOf("#",1) + 1;[\r][\n]"
2006-04-24 09:39:04,144 DEBUG [main] .content.wire() - <<
"[0x9]urlresult=DocURL.substring(BeginURL,serverIndex);[\r][\n]"
2006-04-24 09:39:04,144 DEBUG [main] .content.wire() - << "[0x9][0x9][\r][\n]"
2006-04-24 09:39:04,144 DEBUG [main] .content.wire() - << "[0x9]//for display,
we need to skip after http://, and go to the next slash[\r][\n]"
2006-04-24 09:39:04,144 DEBUG [main] .content.wire() - <<
"[0x9]displayresult=DocURL.substring(protocolIndex + 3 ,serverIndex);[\r][\n]"
2006-04-24 09:39:04,144 DEBUG [main] .content.wire() - <<
"[0x9]document.write('<A HREF="' + urlresult + '">' + displayresult +
"</a>");[\r][\n]"
2006-04-24 09:39:04,144 DEBUG [main] .content.wire() - << "}[\r][\n]"
2006-04-24 09:39:04,144 DEBUG [main] .content.wire() - << "//-->[\r][\n]"
2006-04-24 09:39:04,144 DEBUG [main] .content.wire() - << "</SCRIPT>[\r][\n]"
2006-04-24 09:39:04,144 DEBUG [main] .content.wire() - << "[\r][\n]"
2006-04-24 09:39:04,144 DEBUG [main] .content.wire() - << "<META content="MSHTML
5.50.4522.1800" name=GENERATOR></HEAD>[\r][\n]"
2006-04-24 09:39:04,154 DEBUG [main] .content.wire() - << "<BODY
bgColor=#ffffff>[\r][\n]"
2006-04-24 09:39:04,154 DEBUG [main] .content.wire() - << "<TABLE cellSpacing=5
cellPadding=3 width=410>[\r][\n]"
2006-04-24 09:39:04,154 DEBUG [main] .content.wire() - << "  <TBODY>[\r][\n]"
2006-04-24 09:39:04,154 DEBUG [main] .content.wire() - << "  <TR>[\r][\n]"
2006-04-24 09:39:04,154 DEBUG [main] .content.wire() - << "    <TD vAlign=center
align=left width=360>[\r][\n]"
2006-04-24 09:39:04,154 DEBUG [main] .content.wire() - << "      <H1
style="FONT: 13pt/15pt verdana; COLOR: #000000"><!--Problem-->The page [\r][\n]"
2006-04-24 09:39:04,154 DEBUG [main] .content.wire() - << "      cannot be
displayed</H1></TD></TR>[\r][\n]"
2006-04-24 09:39:04,154 DEBUG [main] .content.wire() - << "  <TR>[\r][\n]"
2006-04-24 09:39:04,154 DEBUG [main] .content.wire() - << "    <TD width=400
colSpan=2><FONT [\r][\n]"
2006-04-24 09:39:04,154 DEBUG [main] .content.wire() - << "      style="FONT:
8pt/11pt verdana; COLOR: #000000">There is a problem with the [\r][\n]"
2006-04-24 09:39:04,154 DEBUG [main] .content.wire() - << "      page you are
trying to reach and it cannot be displayed.</FONT></TD></TR>[\r][\n]"
2006-04-24 09:39:04,154 DEBUG [main] .content.wire() - << "  <TR>[\r][\n]"
2006-04-24 09:39:04,154 DEBUG [main] .content.wire() - << "    <TD width=400
colSpan=2><FONT [\r][\n]"
2006-04-24 09:39:04,154 DEBUG [main] .content.wire() - << "      style="FONT:
8pt/11pt verdana; COLOR: #000000">[\r][\n]"
2006-04-24 09:39:04,154 DEBUG [main] .content.wire() - << "      <HR
color=#c0c0c0 noShade>[\r][\n]"
2006-04-24 09:39:04,164 DEBUG [main] .content.wire() - << "[\r][\n]"
2006-04-24 09:39:04,164 DEBUG [main] .content.wire() - << "      <P>Please try
the following:</P>[\r][\n]"
2006-04-24 09:39:04,164 DEBUG [main] .content.wire() - << "      <UL>[\r][\n]"
2006-04-24 09:39:04,164 DEBUG [main] .content.wire() - << "        <LI>Click the
<A href="javascript:location.reload()">Refresh</A> button, [\r][\n]"
2006-04-24 09:39:04,164 DEBUG [main] .content.wire() - << "        or try again
later.<BR>[\r][\n]"
2006-04-24 09:39:04,164 DEBUG [main] .content.wire() - << "        <LI>Open
the[\r][\n]"
2006-04-24 09:39:04,164 DEBUG [main] .content.wire() - << "       
<SCRIPT>[\r][\n]"
2006-04-24 09:39:04,164 DEBUG [main] .content.wire() - << "[0x9]  <!--[\r][\n]"
2006-04-24 09:39:04,164 DEBUG [main] .content.wire() - << "[0x9]  if
(!((window.navigator.userAgent.indexOf("MSIE") > 0) &&
(window.navigator.appVersion.charAt(0) == "2")))[\r][\n]"
2006-04-24 09:39:04,164 DEBUG [main] .content.wire() - << "[0x9]  {[\r][\n]"
2006-04-24 09:39:04,164 DEBUG [main] .content.wire() - << "[0x9]  
Homepage();[\r][\n]"
2006-04-24 09:39:04,164 DEBUG [main] .content.wire() - << "[0x9]  }[\r][\n]"
2006-04-24 09:39:04,164 DEBUG [main] .content.wire() - << "[0x9]  //-->[\r][\n]"
2006-04-24 09:39:04,164 DEBUG [main] .content.wire() - << "[0x9] 
</SCRIPT>[\r][\n]"
2006-04-24 09:39:04,164 DEBUG [main] .content.wire() - << "         home page,
and then look for links to the information you want. [\r][\n]"
2006-04-24 09:39:04,164 DEBUG [main] .content.wire() - << "        <LI>If you
typed the page address in the Address bar, make sure that it[\r][\n]"
2006-04-24 09:39:04,174 DEBUG [main] .content.wire() - << "        is spelled
correctly.<BR>[\r][\n]"
2006-04-24 09:39:04,174 DEBUG [main] .content.wire() - << "        <LI>Verify
that the Internet access policy on your network allows you[\r][\n]"
2006-04-24 09:39:04,174 DEBUG [main] .content.wire() - << "        to view this
this page.</LI>[\r][\n]"
2006-04-24 09:39:04,174 DEBUG [main] .content.wire() - << "        <LI>If you
believe you should be able to view this directory or page, [\r][\n]"
2006-04-24 09:39:04,174 DEBUG [main] .content.wire() - << "        please
contact the Web site administrator by using the e-mail address or [\r][\n]"
2006-04-24 09:39:04,174 DEBUG [main] .content.wire() - << "        phone number
listed on the[\r][\n]"
2006-04-24 09:39:04,174 DEBUG [main] .content.wire() - << " "
2006-04-24 09:39:04,184 DEBUG [main] .content.wire() - << "       <SCRIPT>
Homepage();</SCRIPT>[\r][\n]"
2006-04-24 09:39:04,184 DEBUG [main] .content.wire() - << "         home page.
</LI></UL>[\r][\n]"
2006-04-24 09:39:04,184 DEBUG [main] .content.wire() - << "      <H2
style="FONT: 8pt/11pt verdana; COLOR: #000000">HTTP 407 Proxy Authentication
Required - The ISA Server requires authorization to fulfill the request. Access
to the Web Proxy service is denied. (12209)<BR>Internet Security and
Acceleration Server</H2>[\r][\n]"
2006-04-24 09:39:04,184 DEBUG [main] .content.wire() - << "      <HR
color=#c0c0c0 noShade>[\r][\n]"
2006-04-24 09:39:04,184 DEBUG [main] .content.wire() - << "[\r][\n]"
2006-04-24 09:39:04,184 DEBUG [main] .content.wire() - << "      <P>Technical
Information (for support personnel)</P>[\r][\n]"
2006-04-24 09:39:04,184 DEBUG [main] .content.wire() - << "      <UL>[\r][\n]"
2006-04-24 09:39:04,184 DEBUG [main] .content.wire() - << "       
<LI>Background:<BR>The gateway could not retrieve the requested
page.<P></P></LI>[\r][\n]"
2006-04-24 09:39:04,184 DEBUG [main] .content.wire() - << "        <LI>ISA
Server: ient1isa001.ie.pri.o2.com<BR>[\r][\n]"
2006-04-24 09:39:04,184 DEBUG [main] .content.wire() - << "        Via:
<BR><BR>Time: 4/24/2006 8:39:04 AM GMT[\r][\n]"
2006-04-24 09:39:04,184 DEBUG [main] .content.wire() - << "       
</LI></UL></FONT></TD></TR></TBODY></TABLE></BODY></HTML>[\r][\n]"
2006-04-24 09:39:04,184 DEBUG [main] .HttpMethodBase.shouldCloseConnection() -
Resorting to protocol version default close connection policy
2006-04-24 09:39:04,184 DEBUG [main] .HttpMethodBase.shouldCloseConnection() -
Should NOT close connection, using HTTP/1.1
2006-04-24 09:39:04,184 DEBUG [main] .HttpConnection.isResponseAvailable() -
enter HttpConnection.isResponseAvailable()
2006-04-24 09:39:04,184 DEBUG [main] .HttpConnection.releaseConnection() - enter
HttpConnection.releaseConnection()
2006-04-24 09:39:04,184 DEBUG [main] .HttpConnection.releaseConnection() -
Releasing connection back to connection manager.
2006-04-24 09:39:04,184 DEBUG [main] .HttpTest.getTest() - getTest() - end -
return value=
2006-04-24 09:39:04,184 DEBUG [main] .HttpTest.main() -
main(String[]=[Ljava.lang.String;@13bad12) - end


Quoting Oleg Kalnichevski <olegk@apache.org>:

> On Mon, 2006-04-24 at 09:51 +0100, Bernadette.Hurley@clients.ie wrote:
> > Thanks Oleg,
> >
> > I have downloaded the latest build, but unfortunalty I am still failing to
> get
> > through the proxy.
> > I noticed, also the problem described wasn't quite the same as I sent the
> > Credentials before i make the first outward call wheras in the example it
> wasn't
> >
> > Attached is the full output.
> > I saw on the website that on ntlm1 is supported
> > -----------
> >  Cannot authenticate with Microsoft IIS using NTLM authentication scheme
> >
> > NT Lan Manager (NTLM) authentication is a proprietary, closed
> challenge/response
> > authentication protocol for Microsoft Windows. Only some details about
> NTLM
> > protocol are available through reverse engineering. HttpClient provides
> limited
> > support for what is known as NTLMv1, the early version of the NTLM
> protocol.
> > HttpClient does not support NTLMv2 at all.
> > -------------
> >
> > Right now I am trying to establish with our sys admins which version we
> have on
> > the firewall.
> > Is this the sort of behaviour I would expect if we had NTLMv2?
> >
> > Thanks
> >
> > Bernadette
> >
>
> Bernadette,
>
> The trouble is that according to what could make out of the wire log
> that you had posted, the NTLM credentials were not sent at all. Now
> after having looked a little more carefully I see that you are not using
> SSL, so the cause of this problem cannot be the bug I was referring to.
>
> Are you absolutely sure you have posted the _complete_ wire log? From
> what I can make out of that log, it appears the session gets cut off
> somewhere in the middle before the NTLM authentication is attempted.
>
> Oleg
>
> >
> > Quoting Oleg Kalnichevski <olegk@apache.org>:
> >
> > > On Fri, 2006-04-21 at 12:16 +0100, Bernadette.Hurley@clients.ie wrote:
> > > >
> > > > I am attempting to use httpclient 3.0 to via a proxy.
> > > >
> > > > However I have not been able to get it work.
> > > > The proxy server is NT.
> > > > I have attempted using both UserPasswordCredentials and NTCredentials.
> > > >
> > > > Here is the code and output.
> > > > And yes i am using the right password.
> > > > Any help is appreciated.
> > > >
> > > > Thanks
> > > > Bernadette
> > >
> > > Bernadette,
> > >
> > > The problem is likely to have been caused by this bug [1] which has been
> > > fixed in the SVN trunk, but the fix has not yet made it into an official
> > > release. Please try one of the latest nightly builds [2] and see if that
> > > solves the problem
> > >
> > > [1]http://issues.apache.org/bugzilla/show_bug.cgi?id=38818
> > >
> [2]http://cvs.apache.org/builds/jakarta-commons/nightly/commons-httpclient/
> > >
> > > Oleg
> > >
> > > > ---
> > > > public String getTest() {
> > > >
> > > > 		String strURL = "http://www.google.com";
> > > > 		String response = "";
> > > >
> > > > 		GetMethod get = new GetMethod(strURL);
> > > > 		try {
> > > >
> > > > 			// Get HTTP client
> > > > 			HttpClient httpclient = new HttpClient();
> > > > 			HostConfiguration config = httpclient.getHostConfiguration();
> > > > 			// add proxy stuff
> > > > 			if (prop.isvia_proxy()) {
> > > >
> > > > 				config.setProxy(prop.getProxy_host(), prop.getProxy_port());
> > > >
> > > > 				AuthScope auth = new AuthScope(null, -1, null, null);
> > > > 				Credentials creds = null;
> > > > 				if (logger.isDebugEnabled()) {
> > > > 					logger.debug("getTest()");
> > > > 				}
> > > > 				// creds = new UsernamePasswordCredentials(prop.getProxy_user(),
> > > > 				// prop.getProxy_pass());
> > > >
> > > > 				creds = new NTCredentials(prop.getProxy_user,
> prop.getProxy_pass(),
> > > > 						prop.getProxy_host(), "IE");
> > > > 				httpclient.getState().setProxyCredentials(auth, creds);
> > > >
> > > >
> > > > 				List authPrefs = new ArrayList(1);
> > > >
> > > > 				authPrefs.add(AuthPolicy.NTLM);
> > > >
> > > > 				httpclient.getParams().setParameter(AuthPolicy.NTLM, authPrefs);
> > > > 				httpclient.getParams().setParameter(
> > > > 						"http.protocol.expect-continue", new Boolean(true));
> > > >
> > > >
> > > > 				get.setDoAuthentication(true);
> > > > 			}
> > > >
> > > > 			HttpConnectionParams param = httpclient.getHttpConnectionManager()
> > > > 					.getParams();
> > > > 			param.setConnectionTimeout(120 * 1000);
> > > > 			param.setSoTimeout(300 * 1000);
> > > >
> > > >
> > > >
> > > > 			int status = httpclient.executeMethod(get);
> > > >
> > > > 	}
> > >
> >
> > ----------------------------------------------------------------
> > This message was sent using IMP, the Internet Messaging Program.
> > ---------------------------------------------------------------------
> > To unsubscribe, e-mail: httpclient-user-unsubscribe@jakarta.apache.org
> > For additional commands, e-mail: httpclient-user-help@jakarta.apache.org
>
>




----------------------------------------------------------------
This message was sent using IMP, the Internet Messaging Program.

---------------------------------------------------------------------
To unsubscribe, e-mail: httpclient-user-unsubscribe@jakarta.apache.org
For additional commands, e-mail: httpclient-user-help@jakarta.apache.org


Mime
View raw message