You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@hc.apache.org by "Beg, Mohsin" <mo...@certive.com> on 2003/09/17 19:40:04 UTC

HTTP/1.1 status 100 help needed

Hello,

I have the following code snippet that seems to never return
from httpClient.executeMethod(). [actually another post
has happened that issued a cookie that the code below
is trying to use]

I have also attached a modified/truncated output of the logs that
are are being produced.

Can someone provide some insight ? I don't know what to do.

I am using "2.0-rc1" build available from
http://jakarta.apache.org/site/binindex.cgi

Sincerely,

-Mohsin



Code :-

 // the http client has been set like this
 // somewhere else in the code
 //httpClient = new HttpClient();
 //httpClient.getHostConfiguration().setHost( LOGON_SITE, LOGON_PORT, "http"
);
 //httpClient.getState().setCookiePolicy( CookiePolicy.RFC2109 );
 //httpClient.setConnectionTimeout( 10000 );


 // Log messages at end of this email
 // are shown from this point onwards
 postMethod = new PostMethod( url );
 postMethod.setRequestHeader( "Content-type",
                              PostMethod.FORM_URL_ENCODED_CONTENT_TYPE );

 NameValuePair msgTransaction = new NameValuePair( "XML", request );
 postMethod.addParameter( msgTransaction );

 int statusCode = httpClient.executeMethod( postMethod );



Log output :-

2003/09/17 10:21:46:638 PDT [DEBUG] HttpClient - -Java version: 1.4.1_02
2003/09/17 10:21:46:648 PDT [DEBUG] HttpClient - -Java vendor: Sun
Microsystems Inc.
2003/09/17 10:21:46:660 PDT [DEBUG] HttpClient - -Operating system name:
Linux
2003/09/17 10:21:46:665 PDT [DEBUG] HttpClient - -Operating system
architecture: i386
2003/09/17 10:21:46:666 PDT [DEBUG] HttpClient - -Operating system version:
2.4.18-10smp
....................


2003/09/17 10:22:07:142 PDT [TRACE] GetMethod - -enter GetMethod(String)
2003/09/17 10:22:07:142 PDT [TRACE] PostMethod - -enter
PostMethod.addParameter(NameValuePair)
2003/09/17 10:22:07:143 PDT [TRACE] PostMethod - -enter
PostMethod.addParameter(String, String)
2003/09/17 10:22:07:143 PDT [TRACE] EntityEnclosingMethod - -enter
EntityEnclosingMethod.clearRequestBody()
2003/09/17 10:22:07:144 PDT [TRACE] HttpClient - -enter
HttpClient.executeMethod(HttpMethod)
2003/09/17 10:22:07:144 PDT [TRACE] HttpClient - -enter
HttpClient.executeMethod(HostConfiguration,HttpMethod,HttpState)
2003/09/17 10:22:07:158 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.execute(HttpState, HttpConnection)
2003/09/17 10:22:07:158 PDT [DEBUG] HttpMethodBase - -Execute loop try 1
2003/09/17 10:22:07:159 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.processRequest(HttpState, HttpConnection)
2003/09/17 10:22:07:159 PDT [TRACE] HttpMethodBase - -Attempt number 1 to
process request
2003/09/17 10:22:07:178 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.writeRequest(HttpState, HttpConnection)
2003/09/17 10:22:07:178 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.writeRequestLine(HttpState, HttpConnection)
2003/09/17 10:22:07:179 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.generateRequestLine(HttpConnection, String, String, String,
String)
2003/09/17 10:22:07:180 PDT [DEBUG] wire - ->> "POST /NodeWarrior/xml/
HTTP/1.1[\r][\n]"
2003/09/17 10:22:07:180 PDT [TRACE] HttpConnection - -enter
HttpConnection.print(String)
2003/09/17 10:22:07:181 PDT [TRACE] HttpConnection - -enter
HttpConnection.write(byte[])
2003/09/17 10:22:07:181 PDT [TRACE] HttpConnection - -enter
HttpConnection.write(byte[], int, int)
2003/09/17 10:22:07:182 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.writeRequestHeaders(HttpState,HttpConnection)
2003/09/17 10:22:07:182 PDT [TRACE] ExpectContinueMethod - -enter
ExpectContinueMethod.addRequestHeaders(HttpState, HttpConnection)
2003/09/17 10:22:07:182 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.addRequestHeaders(HttpState, HttpConnection)
2003/09/17 10:22:07:183 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.addUserAgentRequestHeaders(HttpState, HttpConnection)
2003/09/17 10:22:07:183 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.addHostRequestHeader(HttpState, HttpConnection)
2003/09/17 10:22:07:184 PDT [DEBUG] HttpMethodBase - -Adding Host request
header
2003/09/17 10:22:07:184 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.addCookieRequestHeader(HttpState, HttpConnection)
2003/09/17 10:22:07:185 PDT [TRACE] HttpState - -enter
HttpState.getCookies()
2003/09/17 10:22:07:185 PDT [TRACE] CookieSpec - -enter
CookieSpecBase.match(String, int, String, boolean, Cookie[])
2003/09/17 10:22:07:186 PDT [TRACE] CookieSpec - -enter
CookieSpecBase.match(String, int, String, boolean, Cookie
2003/09/17 10:22:07:187 PDT [TRACE] CookieSpec - -enter
CookieSpecBase.formatCookieHeader(Cookie)
2003/09/17 10:22:07:187 PDT [TRACE] CookieSpec - -enter
RFC2109Spec.formatCookie(Cookie)
2003/09/17 10:22:07:188 PDT [TRACE] CookieSpec - -enter
RFC2109Spec.formatCookieAsVer(Cookie)
2003/09/17 10:22:07:188 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.addAuthorizationRequestHeader(HttpState, HttpConnection)
2003/09/17 10:22:07:189 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.addProxyAuthorizationRequestHeader(HttpState, HttpConnection)
2003/09/17 10:22:07:189 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.addProxyConnectionHeader(HttpState, HttpConnection)
2003/09/17 10:22:07:190 PDT [TRACE] EntityEnclosingMethod - -enter
HttpMethodBase.addContentLengthRequestHeader(HttpState, HttpConnection)
2003/09/17 10:22:07:190 PDT [TRACE] EntityEnclosingMethod - -enter
EntityEnclosingMethod.getRequestContentLength()
2003/09/17 10:22:07:191 PDT [TRACE] PostMethod - -enter
PostMethod.hasRequestContent()
2003/09/17 10:22:07:191 PDT [TRACE] PostMethod - -enter
PostMethod.renerateRequestBody()
2003/09/17 10:22:07:191 PDT [TRACE] PostMethod - -enter
PostMethod.getParameters()
2003/09/17 10:22:07:192 PDT [TRACE] HttpMethodBase - -enter
getContentCharSet( Header contentheader )
2003/09/17 10:22:07:192 PDT [TRACE] HeaderElement - -enter
HeaderElement.parse(String)
2003/09/17 10:22:07:193 PDT [TRACE] HeaderElement - -enter
HeaderElement.parsePair(char[], int, int)
2003/09/17 10:22:07:193 PDT [DEBUG] HttpMethodBase - -Default charset used:
ISO-8859-1
2003/09/17 10:22:07:241 PDT [TRACE] HttpMethodBase -
-HttpMethodBase.addRequestHeader(Header)
2003/09/17 10:22:07:242 PDT [DEBUG] wire - ->> "Content-type:
application/x-www-form-urlencoded[\r][\n]"
2003/09/17 10:22:07:242 PDT [TRACE] HttpConnection - -enter
HttpConnection.print(String)
2003/09/17 10:22:07:243 PDT [TRACE] HttpConnection - -enter
HttpConnection.write(byte[])
2003/09/17 10:22:07:243 PDT [TRACE] HttpConnection - -enter
HttpConnection.write(byte[], int, int)
2003/09/17 10:22:07:244 PDT [DEBUG] wire - ->> "User-Agent: Jakarta
Commons-HttpClient/2.0rc1[\r][\n]"
2003/09/17 10:22:07:245 PDT [TRACE] HttpConnection - -enter
HttpConnection.print(String)
2003/09/17 10:22:07:245 PDT [TRACE] HttpConnection - -enter
HttpConnection.write(byte[])
2003/09/17 10:22:07:246 PDT [TRACE] HttpConnection - -enter
HttpConnection.write(byte[], int, int)
2003/09/17 10:22:07:247 PDT [DEBUG] wire - ->> "Host:
dl-mbeg.engr.certive.com:9000[\r][\n]"
2003/09/17 10:22:07:247 PDT [TRACE] HttpConnection - -enter
HttpConnection.print(String)
2003/09/17 10:22:07:248 PDT [TRACE] HttpConnection - -enter
HttpConnection.write(byte[])
2003/09/17 10:22:07:248 PDT [TRACE] HttpConnection - -enter
HttpConnection.write(byte[], int, int)
2003/09/17 10:22:07:249 PDT [DEBUG] wire - ->> "Cookie: $Version=0;
certive=5B4977C33AB075DD915937857B40E3888F24A2123198391F918E6A14D681DDE4[\r]
[\n]"
2003/09/17 10:22:07:250 PDT [TRACE] HttpConnection - -enter
HttpConnection.print(String)
2003/09/17 10:22:07:250 PDT [TRACE] HttpConnection - -enter
HttpConnection.write(byte[])
2003/09/17 10:22:07:251 PDT [TRACE] HttpConnection - -enter
HttpConnection.write(byte[], int, int)
2003/09/17 10:22:07:251 PDT [DEBUG] wire - ->> "Content-Length:
2966[\r][\n]"
2003/09/17 10:22:07:252 PDT [TRACE] HttpConnection - -enter
HttpConnection.print(String)
2003/09/17 10:22:07:253 PDT [TRACE] HttpConnection - -enter
HttpConnection.write(byte[])
2003/09/17 10:22:07:253 PDT [TRACE] HttpConnection - -enter
HttpConnection.write(byte[], int, int)
2003/09/17 10:22:07:253 PDT [TRACE] HttpConnection - -enter
HttpConnection.writeLine()
2003/09/17 10:22:07:262 PDT [TRACE] HttpConnection - -enter
HttpConnection.write(byte[])
2003/09/17 10:22:07:314 PDT [TRACE] HttpConnection - -enter
HttpConnection.write(byte[], int, int)
2003/09/17 10:22:07:315 PDT [TRACE] HttpConnection - -enter
HttpConnection.flushRequestOutputStream()
2003/09/17 10:22:07:316 PDT [DEBUG] wire - ->> "[\r][\n]"
2003/09/17 10:22:07:316 PDT [TRACE] EntityEnclosingMethod - -enter
EntityEnclosingMethod.writeRequestBody(HttpState, HttpConnection)
2003/09/17 10:22:07:317 PDT [TRACE] PostMethod - -enter
PostMethod.hasRequestContent()
2003/09/17 10:22:07:317 PDT [TRACE] EntityEnclosingMethod - -enter
EntityEnclosingMethod.getRequestContentLength()
2003/09/17 10:22:07:328 PDT [TRACE] PostMethod - -enter
PostMethod.hasRequestContent()
2003/09/17 10:22:07:328 PDT [DEBUG] EntityEnclosingMethod - -Using buffered
request body
2003/09/17 10:22:07:329 PDT [TRACE] HttpConnection - -enter
HttpConnection.getRequestOutputStream()
2003/09/17 10:22:07:340 PDT [DEBUG] wire - ->>
"XML=%3CMsgTransaction%3E%0A%3CSerializeMsgs+isSerialized%3D%22true%22%2F%3E
%0A%3C%2FMsgTransaction%3E%0A"
2003/09/17 10:22:07:341 PDT [DEBUG] EntityEnclosingMethod - -Request body
sent
2003/09/17 10:22:07:341 PDT [TRACE] HttpConnection - -enter
HttpConnection.flushRequestOutputStream()
2003/09/17 10:22:07:342 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.readResponse(HttpState, HttpConnection)
2003/09/17 10:22:07:342 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.readStatusLine(HttpState, HttpConnection)
2003/09/17 10:22:07:362 PDT [TRACE] HttpConnection - -enter
HttpConnection.readLine()
2003/09/17 10:22:07:363 PDT [TRACE] HttpParser - -enter
HttpParser.readLine()
2003/09/17 10:22:07:363 PDT [TRACE] HttpParser - -enter
HttpParser.readRawLine()
2003/09/17 10:22:07:378 PDT [DEBUG] wire - -<< "HTTP/1.1 100
Continue[\r][\n]"
2003/09/17 10:22:07:379 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.readResponseHeaders(HttpState,HttpConnection)
2003/09/17 10:22:07:379 PDT [TRACE] HttpConnection - -enter
HttpConnection.getResponseInputStream()
2003/09/17 10:22:07:385 PDT [TRACE] HttpParser - -enter
HeaderParser.parseHeaders(HttpConnection, HeaderGroup)
2003/09/17 10:22:07:385 PDT [TRACE] HttpParser - -enter
HttpParser.readLine()
2003/09/17 10:22:07:386 PDT [TRACE] HttpParser - -enter
HttpParser.readRawLine()
2003/09/17 10:22:07:386 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.processResponseHeaders(HttpState, HttpConnection)
2003/09/17 10:22:07:387 PDT [INFO] HttpMethodBase - -Discarding unexpected
response: HTTP/1.1 100 Continue
2003/09/17 10:22:07:387 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.readStatusLine(HttpState, HttpConnection)
2003/09/17 10:22:07:387 PDT [TRACE] HttpConnection - -enter
HttpConnection.readLine()
2003/09/17 10:22:07:388 PDT [TRACE] HttpParser - -enter
HttpParser.readLine()
2003/09/17 10:22:07:388 PDT [TRACE] HttpParser - -enter
HttpParser.readRawLine()

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


Re: HTTP/1.1 status 100 help needed

Posted by Oleg Kalnichevski <ol...@apache.org>.
Mohsin,

Do you know what kind of HTTP server runs on the server side?

The HTTP server appears to be using the 'expect: 100-continue' handshake
even though the client did not request it.

You may what to try the following measures:
- activating 'expect: 100-continue' on the client side
- using HTTP/1.0

Oleg


On Wed, 2003-09-17 at 19:40, Beg, Mohsin wrote:
> Hello,
> 
> I have the following code snippet that seems to never return
> from httpClient.executeMethod(). [actually another post
> has happened that issued a cookie that the code below
> is trying to use]
> 
> I have also attached a modified/truncated output of the logs that
> are are being produced.
> 
> Can someone provide some insight ? I don't know what to do.
> 
> I am using "2.0-rc1" build available from
> http://jakarta.apache.org/site/binindex.cgi
> 
> Sincerely,
> 
> -Mohsin
> 
> 
> 
> Code :-
> 
>  // the http client has been set like this
>  // somewhere else in the code
>  //httpClient = new HttpClient();
>  //httpClient.getHostConfiguration().setHost( LOGON_SITE, LOGON_PORT, "http"
> );
>  //httpClient.getState().setCookiePolicy( CookiePolicy.RFC2109 );
>  //httpClient.setConnectionTimeout( 10000 );
> 
> 
>  // Log messages at end of this email
>  // are shown from this point onwards
>  postMethod = new PostMethod( url );
>  postMethod.setRequestHeader( "Content-type",
>                               PostMethod.FORM_URL_ENCODED_CONTENT_TYPE );
> 
>  NameValuePair msgTransaction = new NameValuePair( "XML", request );
>  postMethod.addParameter( msgTransaction );
> 
>  int statusCode = httpClient.executeMethod( postMethod );
> 
> 
> 
> Log output :-
> 
> 2003/09/17 10:21:46:638 PDT [DEBUG] HttpClient - -Java version: 1.4.1_02
> 2003/09/17 10:21:46:648 PDT [DEBUG] HttpClient - -Java vendor: Sun
> Microsystems Inc.
> 2003/09/17 10:21:46:660 PDT [DEBUG] HttpClient - -Operating system name:
> Linux
> 2003/09/17 10:21:46:665 PDT [DEBUG] HttpClient - -Operating system
> architecture: i386
> 2003/09/17 10:21:46:666 PDT [DEBUG] HttpClient - -Operating system version:
> 2.4.18-10smp
> ....................
> 
> 
> 2003/09/17 10:22:07:142 PDT [TRACE] GetMethod - -enter GetMethod(String)
> 2003/09/17 10:22:07:142 PDT [TRACE] PostMethod - -enter
> PostMethod.addParameter(NameValuePair)
> 2003/09/17 10:22:07:143 PDT [TRACE] PostMethod - -enter
> PostMethod.addParameter(String, String)
> 2003/09/17 10:22:07:143 PDT [TRACE] EntityEnclosingMethod - -enter
> EntityEnclosingMethod.clearRequestBody()
> 2003/09/17 10:22:07:144 PDT [TRACE] HttpClient - -enter
> HttpClient.executeMethod(HttpMethod)
> 2003/09/17 10:22:07:144 PDT [TRACE] HttpClient - -enter
> HttpClient.executeMethod(HostConfiguration,HttpMethod,HttpState)
> 2003/09/17 10:22:07:158 PDT [TRACE] HttpMethodBase - -enter
> HttpMethodBase.execute(HttpState, HttpConnection)
> 2003/09/17 10:22:07:158 PDT [DEBUG] HttpMethodBase - -Execute loop try 1
> 2003/09/17 10:22:07:159 PDT [TRACE] HttpMethodBase - -enter
> HttpMethodBase.processRequest(HttpState, HttpConnection)
> 2003/09/17 10:22:07:159 PDT [TRACE] HttpMethodBase - -Attempt number 1 to
> process request
> 2003/09/17 10:22:07:178 PDT [TRACE] HttpMethodBase - -enter
> HttpMethodBase.writeRequest(HttpState, HttpConnection)
> 2003/09/17 10:22:07:178 PDT [TRACE] HttpMethodBase - -enter
> HttpMethodBase.writeRequestLine(HttpState, HttpConnection)
> 2003/09/17 10:22:07:179 PDT [TRACE] HttpMethodBase - -enter
> HttpMethodBase.generateRequestLine(HttpConnection, String, String, String,
> String)
> 2003/09/17 10:22:07:180 PDT [DEBUG] wire - ->> "POST /NodeWarrior/xml/
> HTTP/1.1[\r][\n]"
> 2003/09/17 10:22:07:180 PDT [TRACE] HttpConnection - -enter
> HttpConnection.print(String)
> 2003/09/17 10:22:07:181 PDT [TRACE] HttpConnection - -enter
> HttpConnection.write(byte[])
> 2003/09/17 10:22:07:181 PDT [TRACE] HttpConnection - -enter
> HttpConnection.write(byte[], int, int)
> 2003/09/17 10:22:07:182 PDT [TRACE] HttpMethodBase - -enter
> HttpMethodBase.writeRequestHeaders(HttpState,HttpConnection)
> 2003/09/17 10:22:07:182 PDT [TRACE] ExpectContinueMethod - -enter
> ExpectContinueMethod.addRequestHeaders(HttpState, HttpConnection)
> 2003/09/17 10:22:07:182 PDT [TRACE] HttpMethodBase - -enter
> HttpMethodBase.addRequestHeaders(HttpState, HttpConnection)
> 2003/09/17 10:22:07:183 PDT [TRACE] HttpMethodBase - -enter
> HttpMethodBase.addUserAgentRequestHeaders(HttpState, HttpConnection)
> 2003/09/17 10:22:07:183 PDT [TRACE] HttpMethodBase - -enter
> HttpMethodBase.addHostRequestHeader(HttpState, HttpConnection)
> 2003/09/17 10:22:07:184 PDT [DEBUG] HttpMethodBase - -Adding Host request
> header
> 2003/09/17 10:22:07:184 PDT [TRACE] HttpMethodBase - -enter
> HttpMethodBase.addCookieRequestHeader(HttpState, HttpConnection)
> 2003/09/17 10:22:07:185 PDT [TRACE] HttpState - -enter
> HttpState.getCookies()
> 2003/09/17 10:22:07:185 PDT [TRACE] CookieSpec - -enter
> CookieSpecBase.match(String, int, String, boolean, Cookie[])
> 2003/09/17 10:22:07:186 PDT [TRACE] CookieSpec - -enter
> CookieSpecBase.match(String, int, String, boolean, Cookie
> 2003/09/17 10:22:07:187 PDT [TRACE] CookieSpec - -enter
> CookieSpecBase.formatCookieHeader(Cookie)
> 2003/09/17 10:22:07:187 PDT [TRACE] CookieSpec - -enter
> RFC2109Spec.formatCookie(Cookie)
> 2003/09/17 10:22:07:188 PDT [TRACE] CookieSpec - -enter
> RFC2109Spec.formatCookieAsVer(Cookie)
> 2003/09/17 10:22:07:188 PDT [TRACE] HttpMethodBase - -enter
> HttpMethodBase.addAuthorizationRequestHeader(HttpState, HttpConnection)
> 2003/09/17 10:22:07:189 PDT [TRACE] HttpMethodBase - -enter
> HttpMethodBase.addProxyAuthorizationRequestHeader(HttpState, HttpConnection)
> 2003/09/17 10:22:07:189 PDT [TRACE] HttpMethodBase - -enter
> HttpMethodBase.addProxyConnectionHeader(HttpState, HttpConnection)
> 2003/09/17 10:22:07:190 PDT [TRACE] EntityEnclosingMethod - -enter
> HttpMethodBase.addContentLengthRequestHeader(HttpState, HttpConnection)
> 2003/09/17 10:22:07:190 PDT [TRACE] EntityEnclosingMethod - -enter
> EntityEnclosingMethod.getRequestContentLength()
> 2003/09/17 10:22:07:191 PDT [TRACE] PostMethod - -enter
> PostMethod.hasRequestContent()
> 2003/09/17 10:22:07:191 PDT [TRACE] PostMethod - -enter
> PostMethod.renerateRequestBody()
> 2003/09/17 10:22:07:191 PDT [TRACE] PostMethod - -enter
> PostMethod.getParameters()
> 2003/09/17 10:22:07:192 PDT [TRACE] HttpMethodBase - -enter
> getContentCharSet( Header contentheader )
> 2003/09/17 10:22:07:192 PDT [TRACE] HeaderElement - -enter
> HeaderElement.parse(String)
> 2003/09/17 10:22:07:193 PDT [TRACE] HeaderElement - -enter
> HeaderElement.parsePair(char[], int, int)
> 2003/09/17 10:22:07:193 PDT [DEBUG] HttpMethodBase - -Default charset used:
> ISO-8859-1
> 2003/09/17 10:22:07:241 PDT [TRACE] HttpMethodBase -
> -HttpMethodBase.addRequestHeader(Header)
> 2003/09/17 10:22:07:242 PDT [DEBUG] wire - ->> "Content-type:
> application/x-www-form-urlencoded[\r][\n]"
> 2003/09/17 10:22:07:242 PDT [TRACE] HttpConnection - -enter
> HttpConnection.print(String)
> 2003/09/17 10:22:07:243 PDT [TRACE] HttpConnection - -enter
> HttpConnection.write(byte[])
> 2003/09/17 10:22:07:243 PDT [TRACE] HttpConnection - -enter
> HttpConnection.write(byte[], int, int)
> 2003/09/17 10:22:07:244 PDT [DEBUG] wire - ->> "User-Agent: Jakarta
> Commons-HttpClient/2.0rc1[\r][\n]"
> 2003/09/17 10:22:07:245 PDT [TRACE] HttpConnection - -enter
> HttpConnection.print(String)
> 2003/09/17 10:22:07:245 PDT [TRACE] HttpConnection - -enter
> HttpConnection.write(byte[])
> 2003/09/17 10:22:07:246 PDT [TRACE] HttpConnection - -enter
> HttpConnection.write(byte[], int, int)
> 2003/09/17 10:22:07:247 PDT [DEBUG] wire - ->> "Host:
> dl-mbeg.engr.certive.com:9000[\r][\n]"
> 2003/09/17 10:22:07:247 PDT [TRACE] HttpConnection - -enter
> HttpConnection.print(String)
> 2003/09/17 10:22:07:248 PDT [TRACE] HttpConnection - -enter
> HttpConnection.write(byte[])
> 2003/09/17 10:22:07:248 PDT [TRACE] HttpConnection - -enter
> HttpConnection.write(byte[], int, int)
> 2003/09/17 10:22:07:249 PDT [DEBUG] wire - ->> "Cookie: $Version=0;
> certive=5B4977C33AB075DD915937857B40E3888F24A2123198391F918E6A14D681DDE4[\r]
> [\n]"
> 2003/09/17 10:22:07:250 PDT [TRACE] HttpConnection - -enter
> HttpConnection.print(String)
> 2003/09/17 10:22:07:250 PDT [TRACE] HttpConnection - -enter
> HttpConnection.write(byte[])
> 2003/09/17 10:22:07:251 PDT [TRACE] HttpConnection - -enter
> HttpConnection.write(byte[], int, int)
> 2003/09/17 10:22:07:251 PDT [DEBUG] wire - ->> "Content-Length:
> 2966[\r][\n]"
> 2003/09/17 10:22:07:252 PDT [TRACE] HttpConnection - -enter
> HttpConnection.print(String)
> 2003/09/17 10:22:07:253 PDT [TRACE] HttpConnection - -enter
> HttpConnection.write(byte[])
> 2003/09/17 10:22:07:253 PDT [TRACE] HttpConnection - -enter
> HttpConnection.write(byte[], int, int)
> 2003/09/17 10:22:07:253 PDT [TRACE] HttpConnection - -enter
> HttpConnection.writeLine()
> 2003/09/17 10:22:07:262 PDT [TRACE] HttpConnection - -enter
> HttpConnection.write(byte[])
> 2003/09/17 10:22:07:314 PDT [TRACE] HttpConnection - -enter
> HttpConnection.write(byte[], int, int)
> 2003/09/17 10:22:07:315 PDT [TRACE] HttpConnection - -enter
> HttpConnection.flushRequestOutputStream()
> 2003/09/17 10:22:07:316 PDT [DEBUG] wire - ->> "[\r][\n]"
> 2003/09/17 10:22:07:316 PDT [TRACE] EntityEnclosingMethod - -enter
> EntityEnclosingMethod.writeRequestBody(HttpState, HttpConnection)
> 2003/09/17 10:22:07:317 PDT [TRACE] PostMethod - -enter
> PostMethod.hasRequestContent()
> 2003/09/17 10:22:07:317 PDT [TRACE] EntityEnclosingMethod - -enter
> EntityEnclosingMethod.getRequestContentLength()
> 2003/09/17 10:22:07:328 PDT [TRACE] PostMethod - -enter
> PostMethod.hasRequestContent()
> 2003/09/17 10:22:07:328 PDT [DEBUG] EntityEnclosingMethod - -Using buffered
> request body
> 2003/09/17 10:22:07:329 PDT [TRACE] HttpConnection - -enter
> HttpConnection.getRequestOutputStream()
> 2003/09/17 10:22:07:340 PDT [DEBUG] wire - ->>
> "XML=%3CMsgTransaction%3E%0A%3CSerializeMsgs+isSerialized%3D%22true%22%2F%3E
> %0A%3C%2FMsgTransaction%3E%0A"
> 2003/09/17 10:22:07:341 PDT [DEBUG] EntityEnclosingMethod - -Request body
> sent
> 2003/09/17 10:22:07:341 PDT [TRACE] HttpConnection - -enter
> HttpConnection.flushRequestOutputStream()
> 2003/09/17 10:22:07:342 PDT [TRACE] HttpMethodBase - -enter
> HttpMethodBase.readResponse(HttpState, HttpConnection)
> 2003/09/17 10:22:07:342 PDT [TRACE] HttpMethodBase - -enter
> HttpMethodBase.readStatusLine(HttpState, HttpConnection)
> 2003/09/17 10:22:07:362 PDT [TRACE] HttpConnection - -enter
> HttpConnection.readLine()
> 2003/09/17 10:22:07:363 PDT [TRACE] HttpParser - -enter
> HttpParser.readLine()
> 2003/09/17 10:22:07:363 PDT [TRACE] HttpParser - -enter
> HttpParser.readRawLine()
> 2003/09/17 10:22:07:378 PDT [DEBUG] wire - -<< "HTTP/1.1 100
> Continue[\r][\n]"
> 2003/09/17 10:22:07:379 PDT [TRACE] HttpMethodBase - -enter
> HttpMethodBase.readResponseHeaders(HttpState,HttpConnection)
> 2003/09/17 10:22:07:379 PDT [TRACE] HttpConnection - -enter
> HttpConnection.getResponseInputStream()
> 2003/09/17 10:22:07:385 PDT [TRACE] HttpParser - -enter
> HeaderParser.parseHeaders(HttpConnection, HeaderGroup)
> 2003/09/17 10:22:07:385 PDT [TRACE] HttpParser - -enter
> HttpParser.readLine()
> 2003/09/17 10:22:07:386 PDT [TRACE] HttpParser - -enter
> HttpParser.readRawLine()
> 2003/09/17 10:22:07:386 PDT [TRACE] HttpMethodBase - -enter
> HttpMethodBase.processResponseHeaders(HttpState, HttpConnection)
> 2003/09/17 10:22:07:387 PDT [INFO] HttpMethodBase - -Discarding unexpected
> response: HTTP/1.1 100 Continue
> 2003/09/17 10:22:07:387 PDT [TRACE] HttpMethodBase - -enter
> HttpMethodBase.readStatusLine(HttpState, HttpConnection)
> 2003/09/17 10:22:07:387 PDT [TRACE] HttpConnection - -enter
> HttpConnection.readLine()
> 2003/09/17 10:22:07:388 PDT [TRACE] HttpParser - -enter
> HttpParser.readLine()
> 2003/09/17 10:22:07:388 PDT [TRACE] HttpParser - -enter
> HttpParser.readRawLine()
> 
> ---------------------------------------------------------------------
> To unsubscribe, e-mail: commons-httpclient-dev-unsubscribe@jakarta.apache.org
> For additional commands, e-mail: commons-httpclient-dev-help@jakarta.apache.org
> 


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


Re: HTTP/1.1 status 100 help needed

Posted by Roland Weber <RO...@de.ibm.com>.
Hello Mohsin,

Status code 100 is the server's way to tell a client "go on", or
"don't give up yet". If the log you provided is complete, I'd guess
it's the first case. The client sends a content length of 2966,
but only one and a half lines of content are actually sent. The
server is probably waiting for the missing 2800 bytes before
considering to serve the request.

Is 'msgTransaction' a simple string or a wrapper for some
content you generate on the fly?

regards,
  Roland






"Beg, Mohsin" <mo...@certive.com>
17.09.2003 19:40
Please respond to "Commons HttpClient Project"
 
        To:     "'Commons HttpClient Project'" 
<co...@jakarta.apache.org>
        cc: 
        Subject:        HTTP/1.1 status 100 help needed



Hello,

I have the following code snippet that seems to never return
from httpClient.executeMethod(). [actually another post
has happened that issued a cookie that the code below
is trying to use]

I have also attached a modified/truncated output of the logs that
are are being produced.

Can someone provide some insight ? I don't know what to do.

I am using "2.0-rc1" build available from
http://jakarta.apache.org/site/binindex.cgi

Sincerely,

-Mohsin



Code :-

 // the http client has been set like this
 // somewhere else in the code
 //httpClient = new HttpClient();
 //httpClient.getHostConfiguration().setHost( LOGON_SITE, LOGON_PORT, 
"http"
);
 //httpClient.getState().setCookiePolicy( CookiePolicy.RFC2109 );
 //httpClient.setConnectionTimeout( 10000 );


 // Log messages at end of this email
 // are shown from this point onwards
 postMethod = new PostMethod( url );
 postMethod.setRequestHeader( "Content-type",
                              PostMethod.FORM_URL_ENCODED_CONTENT_TYPE );

 NameValuePair msgTransaction = new NameValuePair( "XML", request );
 postMethod.addParameter( msgTransaction );

 int statusCode = httpClient.executeMethod( postMethod );



Log output :-

2003/09/17 10:21:46:638 PDT [DEBUG] HttpClient - -Java version: 1.4.1_02
2003/09/17 10:21:46:648 PDT [DEBUG] HttpClient - -Java vendor: Sun
Microsystems Inc.
2003/09/17 10:21:46:660 PDT [DEBUG] HttpClient - -Operating system name:
Linux
2003/09/17 10:21:46:665 PDT [DEBUG] HttpClient - -Operating system
architecture: i386
2003/09/17 10:21:46:666 PDT [DEBUG] HttpClient - -Operating system 
version:
2.4.18-10smp
....................


2003/09/17 10:22:07:142 PDT [TRACE] GetMethod - -enter GetMethod(String)
2003/09/17 10:22:07:142 PDT [TRACE] PostMethod - -enter
PostMethod.addParameter(NameValuePair)
2003/09/17 10:22:07:143 PDT [TRACE] PostMethod - -enter
PostMethod.addParameter(String, String)
2003/09/17 10:22:07:143 PDT [TRACE] EntityEnclosingMethod - -enter
EntityEnclosingMethod.clearRequestBody()
2003/09/17 10:22:07:144 PDT [TRACE] HttpClient - -enter
HttpClient.executeMethod(HttpMethod)
2003/09/17 10:22:07:144 PDT [TRACE] HttpClient - -enter
HttpClient.executeMethod(HostConfiguration,HttpMethod,HttpState)
2003/09/17 10:22:07:158 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.execute(HttpState, HttpConnection)
2003/09/17 10:22:07:158 PDT [DEBUG] HttpMethodBase - -Execute loop try 1
2003/09/17 10:22:07:159 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.processRequest(HttpState, HttpConnection)
2003/09/17 10:22:07:159 PDT [TRACE] HttpMethodBase - -Attempt number 1 to
process request
2003/09/17 10:22:07:178 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.writeRequest(HttpState, HttpConnection)
2003/09/17 10:22:07:178 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.writeRequestLine(HttpState, HttpConnection)
2003/09/17 10:22:07:179 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.generateRequestLine(HttpConnection, String, String, String,
String)
2003/09/17 10:22:07:180 PDT [DEBUG] wire - ->> "POST /NodeWarrior/xml/
HTTP/1.1[\r][\n]"
2003/09/17 10:22:07:180 PDT [TRACE] HttpConnection - -enter
HttpConnection.print(String)
2003/09/17 10:22:07:181 PDT [TRACE] HttpConnection - -enter
HttpConnection.write(byte[])
2003/09/17 10:22:07:181 PDT [TRACE] HttpConnection - -enter
HttpConnection.write(byte[], int, int)
2003/09/17 10:22:07:182 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.writeRequestHeaders(HttpState,HttpConnection)
2003/09/17 10:22:07:182 PDT [TRACE] ExpectContinueMethod - -enter
ExpectContinueMethod.addRequestHeaders(HttpState, HttpConnection)
2003/09/17 10:22:07:182 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.addRequestHeaders(HttpState, HttpConnection)
2003/09/17 10:22:07:183 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.addUserAgentRequestHeaders(HttpState, HttpConnection)
2003/09/17 10:22:07:183 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.addHostRequestHeader(HttpState, HttpConnection)
2003/09/17 10:22:07:184 PDT [DEBUG] HttpMethodBase - -Adding Host request
header
2003/09/17 10:22:07:184 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.addCookieRequestHeader(HttpState, HttpConnection)
2003/09/17 10:22:07:185 PDT [TRACE] HttpState - -enter
HttpState.getCookies()
2003/09/17 10:22:07:185 PDT [TRACE] CookieSpec - -enter
CookieSpecBase.match(String, int, String, boolean, Cookie[])
2003/09/17 10:22:07:186 PDT [TRACE] CookieSpec - -enter
CookieSpecBase.match(String, int, String, boolean, Cookie
2003/09/17 10:22:07:187 PDT [TRACE] CookieSpec - -enter
CookieSpecBase.formatCookieHeader(Cookie)
2003/09/17 10:22:07:187 PDT [TRACE] CookieSpec - -enter
RFC2109Spec.formatCookie(Cookie)
2003/09/17 10:22:07:188 PDT [TRACE] CookieSpec - -enter
RFC2109Spec.formatCookieAsVer(Cookie)
2003/09/17 10:22:07:188 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.addAuthorizationRequestHeader(HttpState, HttpConnection)
2003/09/17 10:22:07:189 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.addProxyAuthorizationRequestHeader(HttpState, 
HttpConnection)
2003/09/17 10:22:07:189 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.addProxyConnectionHeader(HttpState, HttpConnection)
2003/09/17 10:22:07:190 PDT [TRACE] EntityEnclosingMethod - -enter
HttpMethodBase.addContentLengthRequestHeader(HttpState, HttpConnection)
2003/09/17 10:22:07:190 PDT [TRACE] EntityEnclosingMethod - -enter
EntityEnclosingMethod.getRequestContentLength()
2003/09/17 10:22:07:191 PDT [TRACE] PostMethod - -enter
PostMethod.hasRequestContent()
2003/09/17 10:22:07:191 PDT [TRACE] PostMethod - -enter
PostMethod.renerateRequestBody()
2003/09/17 10:22:07:191 PDT [TRACE] PostMethod - -enter
PostMethod.getParameters()
2003/09/17 10:22:07:192 PDT [TRACE] HttpMethodBase - -enter
getContentCharSet( Header contentheader )
2003/09/17 10:22:07:192 PDT [TRACE] HeaderElement - -enter
HeaderElement.parse(String)
2003/09/17 10:22:07:193 PDT [TRACE] HeaderElement - -enter
HeaderElement.parsePair(char[], int, int)
2003/09/17 10:22:07:193 PDT [DEBUG] HttpMethodBase - -Default charset 
used:
ISO-8859-1
2003/09/17 10:22:07:241 PDT [TRACE] HttpMethodBase -
-HttpMethodBase.addRequestHeader(Header)
2003/09/17 10:22:07:242 PDT [DEBUG] wire - ->> "Content-type:
application/x-www-form-urlencoded[\r][\n]"
2003/09/17 10:22:07:242 PDT [TRACE] HttpConnection - -enter
HttpConnection.print(String)
2003/09/17 10:22:07:243 PDT [TRACE] HttpConnection - -enter
HttpConnection.write(byte[])
2003/09/17 10:22:07:243 PDT [TRACE] HttpConnection - -enter
HttpConnection.write(byte[], int, int)
2003/09/17 10:22:07:244 PDT [DEBUG] wire - ->> "User-Agent: Jakarta
Commons-HttpClient/2.0rc1[\r][\n]"
2003/09/17 10:22:07:245 PDT [TRACE] HttpConnection - -enter
HttpConnection.print(String)
2003/09/17 10:22:07:245 PDT [TRACE] HttpConnection - -enter
HttpConnection.write(byte[])
2003/09/17 10:22:07:246 PDT [TRACE] HttpConnection - -enter
HttpConnection.write(byte[], int, int)
2003/09/17 10:22:07:247 PDT [DEBUG] wire - ->> "Host:
dl-mbeg.engr.certive.com:9000[\r][\n]"
2003/09/17 10:22:07:247 PDT [TRACE] HttpConnection - -enter
HttpConnection.print(String)
2003/09/17 10:22:07:248 PDT [TRACE] HttpConnection - -enter
HttpConnection.write(byte[])
2003/09/17 10:22:07:248 PDT [TRACE] HttpConnection - -enter
HttpConnection.write(byte[], int, int)
2003/09/17 10:22:07:249 PDT [DEBUG] wire - ->> "Cookie: $Version=0;
certive=5B4977C33AB075DD915937857B40E3888F24A2123198391F918E6A14D681DDE4[\r]
[\n]"
2003/09/17 10:22:07:250 PDT [TRACE] HttpConnection - -enter
HttpConnection.print(String)
2003/09/17 10:22:07:250 PDT [TRACE] HttpConnection - -enter
HttpConnection.write(byte[])
2003/09/17 10:22:07:251 PDT [TRACE] HttpConnection - -enter
HttpConnection.write(byte[], int, int)
2003/09/17 10:22:07:251 PDT [DEBUG] wire - ->> "Content-Length:
2966[\r][\n]"
2003/09/17 10:22:07:252 PDT [TRACE] HttpConnection - -enter
HttpConnection.print(String)
2003/09/17 10:22:07:253 PDT [TRACE] HttpConnection - -enter
HttpConnection.write(byte[])
2003/09/17 10:22:07:253 PDT [TRACE] HttpConnection - -enter
HttpConnection.write(byte[], int, int)
2003/09/17 10:22:07:253 PDT [TRACE] HttpConnection - -enter
HttpConnection.writeLine()
2003/09/17 10:22:07:262 PDT [TRACE] HttpConnection - -enter
HttpConnection.write(byte[])
2003/09/17 10:22:07:314 PDT [TRACE] HttpConnection - -enter
HttpConnection.write(byte[], int, int)
2003/09/17 10:22:07:315 PDT [TRACE] HttpConnection - -enter
HttpConnection.flushRequestOutputStream()
2003/09/17 10:22:07:316 PDT [DEBUG] wire - ->> "[\r][\n]"
2003/09/17 10:22:07:316 PDT [TRACE] EntityEnclosingMethod - -enter
EntityEnclosingMethod.writeRequestBody(HttpState, HttpConnection)
2003/09/17 10:22:07:317 PDT [TRACE] PostMethod - -enter
PostMethod.hasRequestContent()
2003/09/17 10:22:07:317 PDT [TRACE] EntityEnclosingMethod - -enter
EntityEnclosingMethod.getRequestContentLength()
2003/09/17 10:22:07:328 PDT [TRACE] PostMethod - -enter
PostMethod.hasRequestContent()
2003/09/17 10:22:07:328 PDT [DEBUG] EntityEnclosingMethod - -Using 
buffered
request body
2003/09/17 10:22:07:329 PDT [TRACE] HttpConnection - -enter
HttpConnection.getRequestOutputStream()
2003/09/17 10:22:07:340 PDT [DEBUG] wire - ->>
"XML=%3CMsgTransaction%3E%0A%3CSerializeMsgs+isSerialized%3D%22true%22%2F%3E
%0A%3C%2FMsgTransaction%3E%0A"
2003/09/17 10:22:07:341 PDT [DEBUG] EntityEnclosingMethod - -Request body
sent
2003/09/17 10:22:07:341 PDT [TRACE] HttpConnection - -enter
HttpConnection.flushRequestOutputStream()
2003/09/17 10:22:07:342 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.readResponse(HttpState, HttpConnection)
2003/09/17 10:22:07:342 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.readStatusLine(HttpState, HttpConnection)
2003/09/17 10:22:07:362 PDT [TRACE] HttpConnection - -enter
HttpConnection.readLine()
2003/09/17 10:22:07:363 PDT [TRACE] HttpParser - -enter
HttpParser.readLine()
2003/09/17 10:22:07:363 PDT [TRACE] HttpParser - -enter
HttpParser.readRawLine()
2003/09/17 10:22:07:378 PDT [DEBUG] wire - -<< "HTTP/1.1 100
Continue[\r][\n]"
2003/09/17 10:22:07:379 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.readResponseHeaders(HttpState,HttpConnection)
2003/09/17 10:22:07:379 PDT [TRACE] HttpConnection - -enter
HttpConnection.getResponseInputStream()
2003/09/17 10:22:07:385 PDT [TRACE] HttpParser - -enter
HeaderParser.parseHeaders(HttpConnection, HeaderGroup)
2003/09/17 10:22:07:385 PDT [TRACE] HttpParser - -enter
HttpParser.readLine()
2003/09/17 10:22:07:386 PDT [TRACE] HttpParser - -enter
HttpParser.readRawLine()
2003/09/17 10:22:07:386 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.processResponseHeaders(HttpState, HttpConnection)
2003/09/17 10:22:07:387 PDT [INFO] HttpMethodBase - -Discarding unexpected
response: HTTP/1.1 100 Continue
2003/09/17 10:22:07:387 PDT [TRACE] HttpMethodBase - -enter
HttpMethodBase.readStatusLine(HttpState, HttpConnection)
2003/09/17 10:22:07:387 PDT [TRACE] HttpConnection - -enter
HttpConnection.readLine()
2003/09/17 10:22:07:388 PDT [TRACE] HttpParser - -enter
HttpParser.readLine()
2003/09/17 10:22:07:388 PDT [TRACE] HttpParser - -enter
HttpParser.readRawLine()

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