Error on association for the Consumer

[justi...@gmail.com]

Hi,

I have been working on getting a simple Client application
authenticating against an OpenID server (JanRain PHP 1.1.0) and I am
currently getting the below exception.

17:45:01,403 DEBUG net.openid.association.AssociationSessionType:
Session:Association Type: DH-SHA1:HMAC-SHA1:OpenID1
17:45:01,403 DEBUG net.openid.consumer.ConsumerManager: Trying
association type: DH-SHA1:HMAC-SHA1:OpenID1
17:45:01,403 DEBUG net.openid.association.AssociationSessionType:
Session:Association Type: DH-SHA1:HMAC-SHA1:OpenID1
17:45:01,403 DEBUG net.openid.association.AssociationSessionType:
Session:Association Type: DH-SHA1:HMAC-SHA1:OpenID1
17:45:01,403 DEBUG net.openid.message.ParameterList: Created empty
parameter list.
17:45:05,673 DEBUG net.openid.consumer.ConsumerManager: Performing
HTTP POST on http://openid.sydney.mycompany.com/index.php/serve
17:45:06,164 DEBUG httpclient.wire.header: >> "POST /index.php/serve
HTTP/1.1[\r][\n]"
17:45:06,165 DEBUG httpclient.wire.header: >> "User-Agent: Jakarta
Commons-HttpClient/3.0.1[\r][\n]"
17:45:06,166 DEBUG httpclient.wire.header: >> "Host:
openid.sydney.mycompany.com[\r][\n]"
17:45:06,167 DEBUG httpclient.wire.header: >> "Content-Length: 508[\r]
[\n]"
17:45:06,167 DEBUG httpclient.wire.header: >> "Content-Type:
application/x-www-form-urlencoded; charset=UTF-8[\r][\n]"
17:45:06,168 DEBUG httpclient.wire.header: >> "[\r][\n]"
17:45:06,169 DEBUG httpclient.wire.content: >>
"openid.mode=associate&openid.session_type=DH-
SHA1&openid.assoc_type=HMAC-SHA1&openid.dh_consumer_public=ckfP
%2FsAKYlemTkEHLjHNGFf2vcqaEksvexeqmMcZVm9HA9rwg6Fn45FNZm9KlZuvK1fjszjNmp3uksT9VsxFqxlj3GiD7Ew1IyikZmMex3A
%2BveJ5uD%2F918CGy8yZdWsP08fGZ4p8HfbLN54UKlrsqL%2FOxm2iFKDRsNQrvGU
%2FLDo%3D&openid.dh_gen=Ag%3D
%3D&openid.dh_modulus=ANz5OguIOXLsDhmYmsWizjEOHTdxfo2Vcbt2I3MYZuYe91ouJ4mLBX
%2BYkcLiemOcPym2CBRYHNOyyjmG0mg3BVd9RcLn5S3IHHoXGHblzqdLFEi
%2F368Ygo79JRnxTkXjgmY0rxlJ5bU1zIKaSDuKdiI%2BXUkKJX8Fvf8W8vsixYOr"
17:45:24,377 ERROR net.openid.consumer.ConsumerManager: Error talking
to http://openid.sydney.mycompany.com/index.php/serve response code:
-1
java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.read(SocketInputStream.java:129)
at java.io.BufferedInputStream.fill(BufferedInputStream.java:218)
at java.io.BufferedInputStream.read(BufferedInputStream.java:235)
at
org.apache.commons.httpclient.HttpParser.readRawLine(HttpParser.java:
77)
at org.apache.commons.httpclient.HttpParser.readLine(HttpParser.java:
105)
at
org.apache.commons.httpclient.HttpConnection.readLine(HttpConnection.java:
1115)
at
org.apache.commons.httpclient.HttpMethodBase.readStatusLine(HttpMethodBase.java:
1832)
at
org.apache.commons.httpclient.HttpMethodBase.readResponse(HttpMethodBase.java:
1590)
at
org.apache.commons.httpclient.HttpMethodBase.execute(HttpMethodBase.java:
995)
at
org.apache.commons.httpclient.HttpMethodDirector.executeWithRetry(HttpMethodDirector.java:
397)
at
org.apache.commons.httpclient.HttpMethodDirector.executeMethod(HttpMethodDirector.java:
170)
at
org.apache.commons.httpclient.HttpClient.executeMethod(HttpClient.java:
396)
at
org.apache.commons.httpclient.HttpClient.executeMethod(HttpClient.java:
324)
at net.openid.consumer.ConsumerManager.call(ConsumerManager.java:569)
at net.openid.consumer.ConsumerManager.associate(ConsumerManager.java:
725)
at net.openid.consumer.ConsumerManager.associate(ConsumerManager.java:
612)

I know this may be related to the server and have nothing to do with
the client, but I thought I would post here, just in case someone has
seen this type of error occuring from an association call.

If I find out anything interesting I will drop this into the thread.

Cheers,
Justin

[Sutra Zhou]

the url is not exists:
http://openid.sydney.mycompany.com/index.php/serve

2007/4/3, justi...@gmail.com <justi...@gmail.com>:

[Gabe Wachob]

Justin-
I don't know if it makes a difference, but the current php janrain
libs are up to 1.2.2 -- it may be a bug on their side, I'd update that code
first before spending too much time on the consumer side.

-Gabe

[Justin]

Sutra wrote:
> the url is not exists:
> http://openid.sydney.mycompany.com/index.php/serve

Hi Sutra,

Actually this URL does exist :) behind my company firewall, it is not
a publicly available OpenID server, just a test PHP server running the
JanRain OpenID server.

The server responds correctly on all other calls, it is just the
association call that seems to be failing.

I will continue to research this, but just thought I would see if
anyone else has this issue.

Cheers,
Justin

[Johnny Bufu]

Justin,

On 2-Apr-07, at 10:17 PM, justi...@gmail.com wrote:
> java.net.SocketTimeoutException: Read timed out
> at java.net.SocketInputStream.socketRead0(Native Method)

This definitely looks like your RP is having problems reaching the OP.

I would suggest testing that the OP actually responds properly, with
something like:

$ curl -i http://openid.sydney.mycompany.com/index.php/serve -d
openid.mode=associate -d openid.session_type=

(or better yet with the actual values the RP uses in the association
request - you should be able to see those in the debug log)

Hope this helps.

Johnny