This log is too long to show here, 142 KB has been skipped โ click to see the complete log
<xrds:XRDS
xmlns:xrds="xri://$xrds"
xmlns="xri://$xrd*($v*2.0)">
<XRD>
<Service priority="0">
<Type>http://example.com/</Type>
<URI>http://www.openidenabled.com/</URI>
</Service>
</XRD>
</xrds:XRDS>
[DEBUG] 20:19:21,767 [XrdsParserImpl.java : 90] : Found 1 services for the requested types.
[DEBUG] 20:19:21,767 [XrdsParserImpl.java : 115] : Discovered endpoint:
Service priority: 0
Type: [http://example.com/]
URI: http://www.openidenabled.com/
URI Priority: -1
LocalID: null
[INFO ] 20:19:21,767 [YadisResolver.java : 245] : Yadis discovered 1 endpoints from: http://localhost:8989/?headers=redir_simplehtml
[INFO ] 20:19:21,768 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: destroy
[INFO ] 20:19:21,771 [JCLLoggerAdapter.java : 216] : jetty-6.0.2
[INFO ] 20:19:21,772 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: init
[INFO ] 20:19:21,773 [JCLLoggerAdapter.java : 216] : Started SocketConnector @ 0.0.0.0:8989
[DEBUG] 20:19:21,773 [YadisResolver.java : 351] : Performing HTTP HEAD on: http://localhost:8989/?headers=redir_simplexrds ...
[DEBUG] 20:19:21,776 [YadisResolver.java : 351] : Performing HTTP GET on: http://localhost:8989/?headers=redir_simplexrds ...
[DEBUG] 20:19:21,779 [HttpCache.java : 202] : Read 308 bytes.
[DEBUG] 20:19:21,780 [XrdsParserImpl.java : 48] : Parsing XRDS input for service types: [http://example.com/]
[DEBUG] 20:19:21,780 [XrdsParserImpl.java : 161] : Parsing XRDS input: <?xml version="1.0" encoding="UTF-8"?>
<!-- Sample XRDS file at: xrds -->
<xrds:XRDS
xmlns:xrds="xri://$xrds"
xmlns="xri://$xrd*($v*2.0)">
<XRD>
<Service priority="0">
<Type>http://example.com/</Type>
<URI>http://www.openidenabled.com/</URI>
</Service>
</XRD>
</xrds:XRDS>
[DEBUG] 20:19:21,786 [XrdsParserImpl.java : 90] : Found 1 services for the requested types.
[DEBUG] 20:19:21,786 [XrdsParserImpl.java : 115] : Discovered endpoint:
Service priority: 0
Type: [http://example.com/]
URI: http://www.openidenabled.com/
URI Priority: -1
LocalID: null
[DEBUG] 20:19:21,786 [YadisResult.java : 119] : Setting X-XRDS-Location for yadis result: http://localhost:8989/?headers=redir_simplexrds
[INFO ] 20:19:21,787 [YadisResolver.java : 245] : Yadis discovered 1 endpoints from: http://localhost:8989/?headers=redir_simplexrds
[INFO ] 20:19:21,788 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: destroy
[INFO ] 20:19:21,791 [JCLLoggerAdapter.java : 216] : jetty-6.0.2
[INFO ] 20:19:21,794 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: init
[INFO ] 20:19:21,795 [JCLLoggerAdapter.java : 216] : Started SocketConnector @ 0.0.0.0:8989
[DEBUG] 20:19:21,795 [YadisResolver.java : 351] : Performing HTTP HEAD on: http://localhost:8989/?html=simplehtml ...
[WARN ] 20:19:21,797 [JCLLoggerAdapter.java : 346] : /
java.io.FileNotFoundException: /home/javastream.de/jenkins/jobs/com.googlecode.openid4java/workspace/target/test-data/headers/null (No such file or directory)
at java.io.FileInputStream.open0(Native Method)
at java.io.FileInputStream.open(FileInputStream.java:195)
at java.io.FileInputStream.<init>(FileInputStream.java:138)
at java.io.FileInputStream.<init>(FileInputStream.java:93)
at java.io.FileReader.<init>(FileReader.java:58)
at org.openid4java.discovery.yadis.YadisTestServlet.setHeadersFromFile(YadisTestServlet.java:118)
at org.openid4java.discovery.yadis.YadisTestServlet.doHead(YadisTestServlet.java:37)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:757)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:853)
at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:459)
at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:360)
at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:231)
at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:629)
at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:141)
at org.mortbay.jetty.Server.handle(Server.java:303)
at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:452)
at org.mortbay.jetty.HttpConnection$RequestHandler.headerComplete(HttpConnection.java:721)
at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:509)
at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:209)
at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:349)
at org.mortbay.jetty.bio.SocketConnector$Connection.run(SocketConnector.java:217)
at org.mortbay.thread.BoundedThreadPool$PoolThread.run(BoundedThreadPool.java:475)
[DEBUG] 20:19:21,799 [YadisResolver.java : 377] : Cannot retrieve X-XRDS-Location using HEAD from http://localhost:8989/?html=simplehtml; status=HTTP/1.1 500 homejavastreamdejenkinsjobscomgooglecodeopenid4javaworkspacetargettestdataheadersnull_No_such_file_or_directory
[DEBUG] 20:19:21,799 [YadisResolver.java : 351] : Performing HTTP GET on: http://localhost:8989/?html=simplehtml ...
[DEBUG] 20:19:21,802 [HttpCache.java : 202] : Read 549 bytes.
[DEBUG] 20:19:21,804 [CyberNekoDOMYadisHtmlParser.java : 47] : document:
<HTML>
<HEAD>
<META http-equiv="Content-Type" content="text/html; charset=UTF-8">
<META content="http://localhost:8989/?headers=simplexrds&xrds=simplexrds" http-equiv="X-XRDS-Location">
<TITLE>Joe Schmoe's Homepage</TITLE>
</HEAD>
<BODY>
<H1>Joe Schmoe's larger than 350 bytes Homepage</H1>
<P>Blah blah blah blah blah blah blah</P>
<P>Blah blah blah blah blah blah blah</P>
<P>Blah blah blah blah blah blah blah</P>
<P>Blah blah blah blah blah blah blah</P>
<P>Blah blah blah blah blah blah blah</P>
<P>Blah blah blah blah blah blah blah</P>
<P>Blah blah blah blah blah blah blah</P>
</BODY>
</HTML>
[DEBUG] 20:19:21,805 [CyberNekoDOMYadisHtmlParser.java : 89] : Found X-XRDS-Location META tags.
[DEBUG] 20:19:21,805 [YadisResolver.java : 309] : input:
<html>
<head>
<meta http-equiv="X-XRDS-Location" content="http://localhost:8989/?headers=simplexrds&xrds=simplexrds">
<title>Joe Schmoe's Homepage</title>
</head>
<body>
<h1>Joe Schmoe's larger than 350 bytes Homepage</h1>
<p>Blah blah blah blah blah blah blah</p>
<p>Blah blah blah blah blah blah blah</p>
<p>Blah blah blah blah blah blah blah</p>
<p>Blah blah blah blah blah blah blah</p>
<p>Blah blah blah blah blah blah blah</p>
<p>Blah blah blah blah blah blah blah</p>
<p>Blah blah blah blah blah blah blah</p>
</body>
</html>
[DEBUG] 20:19:21,805 [YadisResolver.java : 310] : xrdsLocation: http://localhost:8989/?headers=simplexrds&xrds=simplexrds
[DEBUG] 20:19:21,805 [YadisResult.java : 119] : Setting X-XRDS-Location for yadis result: http://localhost:8989/?headers=simplexrds&xrds=simplexrds
[DEBUG] 20:19:21,808 [HttpCache.java : 202] : Read 308 bytes.
[DEBUG] 20:19:21,808 [YadisResult.java : 119] : Setting X-XRDS-Location for yadis result: http://localhost:8989/?headers=simplexrds&xrds=simplexrds
[DEBUG] 20:19:21,809 [XrdsParserImpl.java : 48] : Parsing XRDS input for service types: [http://example.com/]
[DEBUG] 20:19:21,809 [XrdsParserImpl.java : 161] : Parsing XRDS input: <?xml version="1.0" encoding="UTF-8"?>
<!-- Sample XRDS file at: xrds -->
<xrds:XRDS
xmlns:xrds="xri://$xrds"
xmlns="xri://$xrd*($v*2.0)">
<XRD>
<Service priority="0">
<Type>http://example.com/</Type>
<URI>http://www.openidenabled.com/</URI>
</Service>
</XRD>
</xrds:XRDS>
[DEBUG] 20:19:21,817 [XrdsParserImpl.java : 90] : Found 1 services for the requested types.
[DEBUG] 20:19:21,818 [XrdsParserImpl.java : 115] : Discovered endpoint:
Service priority: 0
Type: [http://example.com/]
URI: http://www.openidenabled.com/
URI Priority: -1
LocalID: null
[INFO ] 20:19:21,818 [YadisResolver.java : 245] : Yadis discovered 1 endpoints from: http://localhost:8989/?html=simplehtml
[INFO ] 20:19:21,819 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: destroy
[INFO ] 20:19:21,920 [JCLLoggerAdapter.java : 216] : jetty-6.0.2
[INFO ] 20:19:21,921 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: init
[INFO ] 20:19:21,921 [JCLLoggerAdapter.java : 216] : Started SocketConnector @ 0.0.0.0:8989
[INFO ] 20:19:21,922 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: destroy
[INFO ] 20:19:22,022 [JCLLoggerAdapter.java : 216] : jetty-6.0.2
[INFO ] 20:19:22,023 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: init
[INFO ] 20:19:22,023 [JCLLoggerAdapter.java : 216] : Started SocketConnector @ 0.0.0.0:8989
[INFO ] 20:19:22,024 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: destroy
[DEBUG] 20:19:22,124 [YadisResolver.java : 351] : Performing HTTP HEAD on: http://localhost:8989/?servertopped ...
[INFO ] 20:19:22,126 [HttpMethodDirector.java : 438] : I/O exception (java.net.ConnectException) caught when processing request: Connection refused
[INFO ] 20:19:22,126 [HttpMethodDirector.java : 444] : Retrying request
[INFO ] 20:19:22,126 [HttpMethodDirector.java : 438] : I/O exception (java.net.ConnectException) caught when processing request: Connection refused
[INFO ] 20:19:22,126 [HttpMethodDirector.java : 444] : Retrying request
[INFO ] 20:19:22,127 [HttpMethodDirector.java : 438] : I/O exception (java.net.ConnectException) caught when processing request: Connection refused
[INFO ] 20:19:22,127 [HttpMethodDirector.java : 444] : Retrying request
[INFO ] 20:19:22,128 [JCLLoggerAdapter.java : 216] : jetty-6.0.2
[INFO ] 20:19:22,128 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: init
[INFO ] 20:19:22,129 [JCLLoggerAdapter.java : 216] : Started SocketConnector @ 0.0.0.0:8989
[DEBUG] 20:19:22,129 [YadisResolver.java : 351] : Performing HTTP HEAD on: http://localhost:8989/?headers=invalidxrdslocation1 ...
[DEBUG] 20:19:22,134 [YadisResolver.java : 351] : Performing HTTP HEAD on: http://localhost:8989/?headers=invalidxrdslocation2 ...
[INFO ] 20:19:22,136 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: destroy
[INFO ] 20:19:22,237 [JCLLoggerAdapter.java : 216] : jetty-6.0.2
[INFO ] 20:19:22,238 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: init
[INFO ] 20:19:22,238 [JCLLoggerAdapter.java : 216] : Started SocketConnector @ 0.0.0.0:8989
[DEBUG] 20:19:22,238 [YadisResolver.java : 351] : Performing HTTP HEAD on: http://localhost:8989/?headers=simplehtml&getheaders=invalidxrdslocation1 ...
[DEBUG] 20:19:22,240 [YadisResolver.java : 351] : Performing HTTP GET on: http://localhost:8989/?headers=simplehtml&getheaders=invalidxrdslocation1 ...
[DEBUG] 20:19:22,242 [HttpCache.java : 202] : Read 0 bytes.
[DEBUG] 20:19:22,243 [YadisResolver.java : 351] : Performing HTTP HEAD on: http://localhost:8989/?headers=simplehtml&getheaders=invalidxrdslocation2 ...
[DEBUG] 20:19:22,244 [YadisResolver.java : 351] : Performing HTTP GET on: http://localhost:8989/?headers=simplehtml&getheaders=invalidxrdslocation2 ...
[DEBUG] 20:19:22,245 [HttpCache.java : 202] : Read 0 bytes.
[INFO ] 20:19:22,246 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: destroy
[INFO ] 20:19:22,346 [JCLLoggerAdapter.java : 216] : jetty-6.0.2
[INFO ] 20:19:22,347 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: init
[INFO ] 20:19:22,347 [JCLLoggerAdapter.java : 216] : Started SocketConnector @ 0.0.0.0:8989
[DEBUG] 20:19:22,348 [YadisResolver.java : 351] : Performing HTTP HEAD on: http://localhost:8989/?html=multiplexrdslocation ...
[WARN ] 20:19:22,350 [JCLLoggerAdapter.java : 346] : /
java.io.FileNotFoundException: /home/javastream.de/jenkins/jobs/com.googlecode.openid4java/workspace/target/test-data/headers/null (No such file or directory)
at java.io.FileInputStream.open0(Native Method)
at java.io.FileInputStream.open(FileInputStream.java:195)
at java.io.FileInputStream.<init>(FileInputStream.java:138)
at java.io.FileInputStream.<init>(FileInputStream.java:93)
at java.io.FileReader.<init>(FileReader.java:58)
at org.openid4java.discovery.yadis.YadisTestServlet.setHeadersFromFile(YadisTestServlet.java:118)
at org.openid4java.discovery.yadis.YadisTestServlet.doHead(YadisTestServlet.java:37)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:757)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:853)
at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:459)
at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:360)
at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:231)
at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:629)
at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:141)
at org.mortbay.jetty.Server.handle(Server.java:303)
at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:452)
at org.mortbay.jetty.HttpConnection$RequestHandler.headerComplete(HttpConnection.java:721)
at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:509)
at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:209)
at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:349)
at org.mortbay.jetty.bio.SocketConnector$Connection.run(SocketConnector.java:217)
at org.mortbay.thread.BoundedThreadPool$PoolThread.run(BoundedThreadPool.java:475)
[DEBUG] 20:19:22,351 [YadisResolver.java : 377] : Cannot retrieve X-XRDS-Location using HEAD from http://localhost:8989/?html=multiplexrdslocation; status=HTTP/1.1 500 homejavastreamdejenkinsjobscomgooglecodeopenid4javaworkspacetargettestdataheadersnull_No_such_file_or_directory
[DEBUG] 20:19:22,351 [YadisResolver.java : 351] : Performing HTTP GET on: http://localhost:8989/?html=multiplexrdslocation ...
[DEBUG] 20:19:22,353 [HttpCache.java : 202] : Read 355 bytes.
[DEBUG] 20:19:22,354 [CyberNekoDOMYadisHtmlParser.java : 47] : document:
<HTML>
<HEAD>
<META http-equiv="Content-Type" content="text/html; charset=UTF-8">
<META content="http://localhost:8989/?headers=simplexrds&xrds=simplexrds" http-equiv="X-XRDS-Location">
<META content="http://localhost:8989/?xrds=simplexrds" http-equiv="X-XRDS-Location">
<TITLE>Joe Schmoe's Homepage</TITLE>
</HEAD>
<BODY>
<H1>Joe Schmoe's Homepage</H1>
<P>Blah blah blah blah blah blah blah</P>
</BODY>
</HTML>
[DEBUG] 20:19:22,354 [CyberNekoDOMYadisHtmlParser.java : 89] : Found X-XRDS-Location META tags.
[INFO ] 20:19:22,355 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: destroy
[INFO ] 20:19:22,455 [JCLLoggerAdapter.java : 216] : jetty-6.0.2
[INFO ] 20:19:22,456 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: init
[INFO ] 20:19:22,456 [JCLLoggerAdapter.java : 216] : Started SocketConnector @ 0.0.0.0:8989
[DEBUG] 20:19:22,457 [YadisResolver.java : 351] : Performing HTTP HEAD on: http://localhost:8989/?html=nohead ...
[WARN ] 20:19:22,459 [JCLLoggerAdapter.java : 346] : /
java.io.FileNotFoundException: /home/javastream.de/jenkins/jobs/com.googlecode.openid4java/workspace/target/test-data/headers/null (No such file or directory)
at java.io.FileInputStream.open0(Native Method)
at java.io.FileInputStream.open(FileInputStream.java:195)
at java.io.FileInputStream.<init>(FileInputStream.java:138)
at java.io.FileInputStream.<init>(FileInputStream.java:93)
at java.io.FileReader.<init>(FileReader.java:58)
at org.openid4java.discovery.yadis.YadisTestServlet.setHeadersFromFile(YadisTestServlet.java:118)
at org.openid4java.discovery.yadis.YadisTestServlet.doHead(YadisTestServlet.java:37)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:757)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:853)
at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:459)
at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:360)
at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:231)
at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:629)
at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:141)
at org.mortbay.jetty.Server.handle(Server.java:303)
at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:452)
at org.mortbay.jetty.HttpConnection$RequestHandler.headerComplete(HttpConnection.java:721)
at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:509)
at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:209)
at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:349)
at org.mortbay.jetty.bio.SocketConnector$Connection.run(SocketConnector.java:217)
at org.mortbay.thread.BoundedThreadPool$PoolThread.run(BoundedThreadPool.java:475)
[DEBUG] 20:19:22,461 [YadisResolver.java : 377] : Cannot retrieve X-XRDS-Location using HEAD from http://localhost:8989/?html=nohead; status=HTTP/1.1 500 homejavastreamdejenkinsjobscomgooglecodeopenid4javaworkspacetargettestdataheadersnull_No_such_file_or_directory
[DEBUG] 20:19:22,461 [YadisResolver.java : 351] : Performing HTTP GET on: http://localhost:8989/?html=nohead ...
[DEBUG] 20:19:22,464 [HttpCache.java : 202] : Read 109 bytes.
[DEBUG] 20:19:22,465 [CyberNekoDOMYadisHtmlParser.java : 47] : document:
<HTML>
<BODY>
<H1>Joe Schmoe's Homepage</H1>
<P>Blah blah blah blah blah blah blah</P>
</BODY>
</HTML>
[DEBUG] 20:19:22,466 [YadisResolver.java : 351] : Performing HTTP HEAD on: http://localhost:8989/?html=twoheads ...
[WARN ] 20:19:22,467 [JCLLoggerAdapter.java : 346] : /
java.io.FileNotFoundException: /home/javastream.de/jenkins/jobs/com.googlecode.openid4java/workspace/target/test-data/headers/null (No such file or directory)
at java.io.FileInputStream.open0(Native Method)
at java.io.FileInputStream.open(FileInputStream.java:195)
at java.io.FileInputStream.<init>(FileInputStream.java:138)
at java.io.FileInputStream.<init>(FileInputStream.java:93)
at java.io.FileReader.<init>(FileReader.java:58)
at org.openid4java.discovery.yadis.YadisTestServlet.setHeadersFromFile(YadisTestServlet.java:118)
at org.openid4java.discovery.yadis.YadisTestServlet.doHead(YadisTestServlet.java:37)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:757)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:853)
at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:459)
at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:360)
at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:231)
at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:629)
at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:141)
at org.mortbay.jetty.Server.handle(Server.java:303)
at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:452)
at org.mortbay.jetty.HttpConnection$RequestHandler.headerComplete(HttpConnection.java:721)
at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:509)
at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:209)
at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:349)
at org.mortbay.jetty.bio.SocketConnector$Connection.run(SocketConnector.java:217)
at org.mortbay.thread.BoundedThreadPool$PoolThread.run(BoundedThreadPool.java:475)
[DEBUG] 20:19:22,468 [YadisResolver.java : 377] : Cannot retrieve X-XRDS-Location using HEAD from http://localhost:8989/?html=twoheads; status=HTTP/1.1 500 homejavastreamdejenkinsjobscomgooglecodeopenid4javaworkspacetargettestdataheadersnull_No_such_file_or_directory
[DEBUG] 20:19:22,468 [YadisResolver.java : 351] : Performing HTTP GET on: http://localhost:8989/?html=twoheads ...
[DEBUG] 20:19:22,471 [HttpCache.java : 202] : Read 301 bytes.
[DEBUG] 20:19:22,472 [YadisResolver.java : 351] : Performing HTTP HEAD on: http://localhost:8989/?html=extraheadinbody ...
[WARN ] 20:19:22,474 [JCLLoggerAdapter.java : 346] : /
java.io.FileNotFoundException: /home/javastream.de/jenkins/jobs/com.googlecode.openid4java/workspace/target/test-data/headers/null (No such file or directory)
at java.io.FileInputStream.open0(Native Method)
at java.io.FileInputStream.open(FileInputStream.java:195)
at java.io.FileInputStream.<init>(FileInputStream.java:138)
at java.io.FileInputStream.<init>(FileInputStream.java:93)
at java.io.FileReader.<init>(FileReader.java:58)
at org.openid4java.discovery.yadis.YadisTestServlet.setHeadersFromFile(YadisTestServlet.java:118)
at org.openid4java.discovery.yadis.YadisTestServlet.doHead(YadisTestServlet.java:37)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:757)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:853)
at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:459)
at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:360)
at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:231)
at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:629)
at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:141)
at org.mortbay.jetty.Server.handle(Server.java:303)
at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:452)
at org.mortbay.jetty.HttpConnection$RequestHandler.headerComplete(HttpConnection.java:721)
at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:509)
at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:209)
at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:349)
at org.mortbay.jetty.bio.SocketConnector$Connection.run(SocketConnector.java:217)
at org.mortbay.thread.BoundedThreadPool$PoolThread.run(BoundedThreadPool.java:475)
[DEBUG] 20:19:22,475 [YadisResolver.java : 377] : Cannot retrieve X-XRDS-Location using HEAD from http://localhost:8989/?html=extraheadinbody; status=HTTP/1.1 500 homejavastreamdejenkinsjobscomgooglecodeopenid4javaworkspacetargettestdataheadersnull_No_such_file_or_directory
[DEBUG] 20:19:22,475 [YadisResolver.java : 351] : Performing HTTP GET on: http://localhost:8989/?html=extraheadinbody ...
[DEBUG] 20:19:22,482 [HttpCache.java : 202] : Read 321 bytes.
[DEBUG] 20:19:22,483 [CyberNekoDOMYadisHtmlParser.java : 47] : document:
<HTML>
<HEAD>
<META http-equiv="Content-Type" content="text/html; charset=UTF-8">
<META content="http://localhost:8989/?headers=simplexrds&xrds=simplexrds" http-equiv="X-XRDS-Location">
<TITLE>Joe Schmoe's Homepage</TITLE>
</HEAD>
<BODY>
<H1>Joe Schmoe's Homepage</H1>
<P>Blah blah blah blah blah blah blah</P>
some other head, inside the html body
</BODY>
</HTML>
[DEBUG] 20:19:22,483 [CyberNekoDOMYadisHtmlParser.java : 89] : Found X-XRDS-Location META tags.
[DEBUG] 20:19:22,484 [YadisResolver.java : 309] : input:
<html>
<head>
<meta http-equiv="X-XRDS-Location" content="http://localhost:8989/?headers=simplexrds&xrds=simplexrds">
<title>Joe Schmoe's Homepage</title>
</head>
<body>
<h1>Joe Schmoe's Homepage</h1>
<p>Blah blah blah blah blah blah blah</p>
<head>some other head, inside the html body</head>
</body>
</html>
[DEBUG] 20:19:22,484 [YadisResolver.java : 310] : xrdsLocation: http://localhost:8989/?headers=simplexrds&xrds=simplexrds
[DEBUG] 20:19:22,484 [YadisResult.java : 119] : Setting X-XRDS-Location for yadis result: http://localhost:8989/?headers=simplexrds&xrds=simplexrds
[DEBUG] 20:19:22,486 [HttpCache.java : 202] : Read 308 bytes.
[DEBUG] 20:19:22,486 [YadisResult.java : 119] : Setting X-XRDS-Location for yadis result: http://localhost:8989/?headers=simplexrds&xrds=simplexrds
[DEBUG] 20:19:22,486 [XrdsParserImpl.java : 48] : Parsing XRDS input for service types: [http://example.com/]
[DEBUG] 20:19:22,486 [XrdsParserImpl.java : 161] : Parsing XRDS input: <?xml version="1.0" encoding="UTF-8"?>
<!-- Sample XRDS file at: xrds -->
<xrds:XRDS
xmlns:xrds="xri://$xrds"
xmlns="xri://$xrd*($v*2.0)">
<XRD>
<Service priority="0">
<Type>http://example.com/</Type>
<URI>http://www.openidenabled.com/</URI>
</Service>
</XRD>
</xrds:XRDS>
[DEBUG] 20:19:22,493 [XrdsParserImpl.java : 90] : Found 1 services for the requested types.
[DEBUG] 20:19:22,498 [XrdsParserImpl.java : 115] : Discovered endpoint:
Service priority: 0
Type: [http://example.com/]
URI: http://www.openidenabled.com/
URI Priority: -1
LocalID: null
[INFO ] 20:19:22,498 [YadisResolver.java : 245] : Yadis discovered 1 endpoints from: http://localhost:8989/?html=extraheadinbody
[INFO ] 20:19:22,498 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: destroy
[INFO ] 20:19:22,499 [JCLLoggerAdapter.java : 216] : jetty-6.0.2
[INFO ] 20:19:22,505 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: init
[INFO ] 20:19:22,506 [JCLLoggerAdapter.java : 216] : Started SocketConnector @ 0.0.0.0:8989
[DEBUG] 20:19:22,506 [YadisResolver.java : 351] : Performing HTTP HEAD on: http://localhost:8989/?html=headnometa ...
[WARN ] 20:19:22,512 [JCLLoggerAdapter.java : 346] : /
java.io.FileNotFoundException: /home/javastream.de/jenkins/jobs/com.googlecode.openid4java/workspace/target/test-data/headers/null (No such file or directory)
at java.io.FileInputStream.open0(Native Method)
at java.io.FileInputStream.open(FileInputStream.java:195)
at java.io.FileInputStream.<init>(FileInputStream.java:138)
at java.io.FileInputStream.<init>(FileInputStream.java:93)
at java.io.FileReader.<init>(FileReader.java:58)
at org.openid4java.discovery.yadis.YadisTestServlet.setHeadersFromFile(YadisTestServlet.java:118)
at org.openid4java.discovery.yadis.YadisTestServlet.doHead(YadisTestServlet.java:37)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:757)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:853)
at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:459)
at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:360)
at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:231)
at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:629)
at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:141)
at org.mortbay.jetty.Server.handle(Server.java:303)
at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:452)
at org.mortbay.jetty.HttpConnection$RequestHandler.headerComplete(HttpConnection.java:721)
at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:509)
at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:209)
at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:349)
at org.mortbay.jetty.bio.SocketConnector$Connection.run(SocketConnector.java:217)
at org.mortbay.thread.BoundedThreadPool$PoolThread.run(BoundedThreadPool.java:475)
[DEBUG] 20:19:22,514 [YadisResolver.java : 377] : Cannot retrieve X-XRDS-Location using HEAD from http://localhost:8989/?html=headnometa; status=HTTP/1.1 500 homejavastreamdejenkinsjobscomgooglecodeopenid4javaworkspacetargettestdataheadersnull_No_such_file_or_directory
[DEBUG] 20:19:22,519 [YadisResolver.java : 351] : Performing HTTP GET on: http://localhost:8989/?html=headnometa ...
[DEBUG] 20:19:22,522 [HttpCache.java : 202] : Read 444 bytes.
[DEBUG] 20:19:22,523 [CyberNekoDOMYadisHtmlParser.java : 47] : document:
<HTML>
<HEAD>
<META http-equiv="Content-Type" content="text/html; charset=UTF-8">
<TITLE>Joe Schmoe's Homepage</TITLE>
</HEAD>
<BODY>
<H1>Joe Schmoe's larger than 350 bytes Homepage</H1>
<P>Blah blah blah blah blah blah blah</P>
<P>Blah blah blah blah blah blah blah</P>
<P>Blah blah blah blah blah blah blah</P>
<P>Blah blah blah blah blah blah blah</P>
<P>Blah blah blah blah blah blah blah</P>
<P>Blah blah blah blah blah blah blah</P>
<P>Blah blah blah blah blah blah blah</P>
</BODY>
</HTML>
[DEBUG] 20:19:22,523 [CyberNekoDOMYadisHtmlParser.java : 67] : No <meta> element found under <html><head>. See Yadis specification, section 6.2.5/1.
[DEBUG] 20:19:22,523 [YadisResolver.java : 309] : input:
<html>
<head>
<title>Joe Schmoe's Homepage</title>
</head>
<body>
<h1>Joe Schmoe's larger than 350 bytes Homepage</h1>
<p>Blah blah blah blah blah blah blah</p>
<p>Blah blah blah blah blah blah blah</p>
<p>Blah blah blah blah blah blah blah</p>
<p>Blah blah blah blah blah blah blah</p>
<p>Blah blah blah blah blah blah blah</p>
<p>Blah blah blah blah blah blah blah</p>
<p>Blah blah blah blah blah blah blah</p>
</body>
</html>
[DEBUG] 20:19:22,524 [YadisResolver.java : 310] : xrdsLocation: null
[INFO ] 20:19:22,524 [YadisResolver.java : 245] : Yadis discovered 0 endpoints from: http://localhost:8989/?html=headnometa
[INFO ] 20:19:22,524 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: destroy
[INFO ] 20:19:22,625 [JCLLoggerAdapter.java : 216] : jetty-6.0.2
[INFO ] 20:19:22,626 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: init
[INFO ] 20:19:22,626 [JCLLoggerAdapter.java : 216] : Started SocketConnector @ 0.0.0.0:8989
[DEBUG] 20:19:22,626 [YadisResolver.java : 351] : Performing HTTP HEAD on: http://localhost:8989/?html=empty ...
[WARN ] 20:19:22,627 [JCLLoggerAdapter.java : 346] : /
java.io.FileNotFoundException: /home/javastream.de/jenkins/jobs/com.googlecode.openid4java/workspace/target/test-data/headers/null (No such file or directory)
at java.io.FileInputStream.open0(Native Method)
at java.io.FileInputStream.open(FileInputStream.java:195)
at java.io.FileInputStream.<init>(FileInputStream.java:138)
at java.io.FileInputStream.<init>(FileInputStream.java:93)
at java.io.FileReader.<init>(FileReader.java:58)
at org.openid4java.discovery.yadis.YadisTestServlet.setHeadersFromFile(YadisTestServlet.java:118)
at org.openid4java.discovery.yadis.YadisTestServlet.doHead(YadisTestServlet.java:37)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:757)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:853)
at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:459)
at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:360)
at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:231)
at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:629)
at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:141)
at org.mortbay.jetty.Server.handle(Server.java:303)
at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:452)
at org.mortbay.jetty.HttpConnection$RequestHandler.headerComplete(HttpConnection.java:721)
at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:509)
at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:209)
at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:349)
at org.mortbay.jetty.bio.SocketConnector$Connection.run(SocketConnector.java:217)
at org.mortbay.thread.BoundedThreadPool$PoolThread.run(BoundedThreadPool.java:475)
[DEBUG] 20:19:22,628 [YadisResolver.java : 377] : Cannot retrieve X-XRDS-Location using HEAD from http://localhost:8989/?html=empty; status=HTTP/1.1 500 homejavastreamdejenkinsjobscomgooglecodeopenid4javaworkspacetargettestdataheadersnull_No_such_file_or_directory
[DEBUG] 20:19:22,628 [YadisResolver.java : 351] : Performing HTTP GET on: http://localhost:8989/?html=empty ...
[DEBUG] 20:19:22,630 [HttpCache.java : 202] : Read 0 bytes.
[DEBUG] 20:19:22,631 [CyberNekoDOMYadisHtmlParser.java : 47] : document:
<HTML></HTML>
[INFO ] 20:19:22,631 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: destroy
[INFO ] 20:19:22,732 [JCLLoggerAdapter.java : 216] : jetty-6.0.2
[INFO ] 20:19:22,732 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: init
[INFO ] 20:19:22,733 [JCLLoggerAdapter.java : 216] : Started SocketConnector @ 0.0.0.0:8989
[DEBUG] 20:19:22,733 [YadisResolver.java : 351] : Performing HTTP HEAD on: http://localhost:8989/?html=nonexistantfile ...
[WARN ] 20:19:22,735 [JCLLoggerAdapter.java : 346] : /
java.io.FileNotFoundException: /home/javastream.de/jenkins/jobs/com.googlecode.openid4java/workspace/target/test-data/headers/null (No such file or directory)
at java.io.FileInputStream.open0(Native Method)
at java.io.FileInputStream.open(FileInputStream.java:195)
at java.io.FileInputStream.<init>(FileInputStream.java:138)
at java.io.FileInputStream.<init>(FileInputStream.java:93)
at java.io.FileReader.<init>(FileReader.java:58)
at org.openid4java.discovery.yadis.YadisTestServlet.setHeadersFromFile(YadisTestServlet.java:118)
at org.openid4java.discovery.yadis.YadisTestServlet.doHead(YadisTestServlet.java:37)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:757)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:853)
at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:459)
at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:360)
at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:231)
at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:629)
at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:141)
at org.mortbay.jetty.Server.handle(Server.java:303)
at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:452)
at org.mortbay.jetty.HttpConnection$RequestHandler.headerComplete(HttpConnection.java:721)
at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:509)
at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:209)
at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:349)
at org.mortbay.jetty.bio.SocketConnector$Connection.run(SocketConnector.java:217)
at org.mortbay.thread.BoundedThreadPool$PoolThread.run(BoundedThreadPool.java:475)
[DEBUG] 20:19:22,737 [YadisResolver.java : 377] : Cannot retrieve X-XRDS-Location using HEAD from http://localhost:8989/?html=nonexistantfile; status=HTTP/1.1 500 homejavastreamdejenkinsjobscomgooglecodeopenid4javaworkspacetargettestdataheadersnull_No_such_file_or_directory
[DEBUG] 20:19:22,737 [YadisResolver.java : 351] : Performing HTTP GET on: http://localhost:8989/?html=nonexistantfile ...
[WARN ] 20:19:22,740 [JCLLoggerAdapter.java : 346] : /
java.io.FileNotFoundException: /home/javastream.de/jenkins/jobs/com.googlecode.openid4java/workspace/target/test-data/html/nonexistantfile (No such file or directory)
at java.io.FileInputStream.open0(Native Method)
at java.io.FileInputStream.open(FileInputStream.java:195)
at java.io.FileInputStream.<init>(FileInputStream.java:138)
at java.io.FileInputStream.<init>(FileInputStream.java:93)
at java.io.FileReader.<init>(FileReader.java:58)
at org.openid4java.discovery.yadis.YadisTestServlet.doGet(YadisTestServlet.java:95)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:740)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:853)
at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:459)
at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:360)
at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:231)
at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:629)
at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:141)
at org.mortbay.jetty.Server.handle(Server.java:303)
at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:452)
at org.mortbay.jetty.HttpConnection$RequestHandler.headerComplete(HttpConnection.java:721)
at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:509)
at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:209)
at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:349)
at org.mortbay.jetty.bio.SocketConnector$Connection.run(SocketConnector.java:217)
at org.mortbay.thread.BoundedThreadPool$PoolThread.run(BoundedThreadPool.java:475)
[DEBUG] 20:19:22,741 [HttpCache.java : 202] : Read 3168 bytes.
[INFO ] 20:19:22,742 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: destroy
[INFO ] 20:19:22,842 [JCLLoggerAdapter.java : 216] : jetty-6.0.2
[INFO ] 20:19:22,843 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: init
[INFO ] 20:19:22,843 [JCLLoggerAdapter.java : 216] : Started SocketConnector @ 0.0.0.0:8989
[DEBUG] 20:19:22,843 [YadisResolver.java : 351] : Performing HTTP HEAD on: http://localhost:8989/?headers=simpleheaders ...
[DEBUG] 20:19:22,845 [YadisResult.java : 119] : Setting X-XRDS-Location for yadis result: http://localhost:8989/?xrds=simplexrds&headers=simplexrds
[DEBUG] 20:19:22,847 [HttpCache.java : 202] : Read 10 bytes.
[DEBUG] 20:19:22,847 [YadisResult.java : 119] : Setting X-XRDS-Location for yadis result: http://localhost:8989/?xrds=simplexrds&headers=simplexrds
[INFO ] 20:19:22,847 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: destroy
[INFO ] 20:19:22,948 [JCLLoggerAdapter.java : 216] : jetty-6.0.2
[INFO ] 20:19:22,949 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: init
[INFO ] 20:19:22,949 [JCLLoggerAdapter.java : 216] : Started SocketConnector @ 0.0.0.0:8989
[DEBUG] 20:19:22,949 [YadisResolver.java : 351] : Performing HTTP HEAD on: http://localhost:8989/?headers=simplexrds&xrds=malformedxrds1 ...
[DEBUG] 20:19:22,950 [YadisResolver.java : 351] : Performing HTTP GET on: http://localhost:8989/?headers=simplexrds&xrds=malformedxrds1 ...
[DEBUG] 20:19:22,952 [HttpCache.java : 202] : Read 279 bytes.
[DEBUG] 20:19:22,953 [XrdsParserImpl.java : 48] : Parsing XRDS input for service types: [http://openid.net/signon/1.1, http://specs.openid.net/auth/2.0/signon, http://openid.net/signon/1.0, http://specs.openid.net/auth/2.0/server]
[DEBUG] 20:19:22,953 [XrdsParserImpl.java : 161] : Parsing XRDS input: <?xml version="1.0" encoding="UTF-8"?>
<!-- Sample XRDS file at: xrds -->
<xrds:XRDS
xmlns="xri://$xrd*($v*2.0)">
<XRD>
<Service priority="0">
<Type>http://example.com/</Type>
<URI>http://www.openidenabled.com/</URI>
</Service>
</XRD>
</xrds:XRDS>
[DEBUG] 20:19:22,959 [YadisResolver.java : 351] : Performing HTTP HEAD on: http://localhost:8989/?headers=simplexrds&xrds=malformedxrds2 ...
[DEBUG] 20:19:22,961 [YadisResolver.java : 351] : Performing HTTP GET on: http://localhost:8989/?headers=simplexrds&xrds=malformedxrds2 ...
[DEBUG] 20:19:22,964 [HttpCache.java : 202] : Read 293 bytes.
[DEBUG] 20:19:22,964 [XrdsParserImpl.java : 48] : Parsing XRDS input for service types: [http://openid.net/signon/1.1, http://specs.openid.net/auth/2.0/signon, http://openid.net/signon/1.0, http://specs.openid.net/auth/2.0/server]
[DEBUG] 20:19:22,964 [XrdsParserImpl.java : 161] : Parsing XRDS input: <?xml version="1.0" encoding="UTF-8"?>
<!-- Sample XRDS file at: xrds -->
<xrds:XRDS
xmlns:xrds="xri://$xrds"
xmlns="xri://$xrd*($v*2.0)">
<XRD>
<Service priority="0">
<Type>http://example.com/</Type>
<URI>http://www.openidenabled.com/</URI>
</XRD>
</xrds:XRDS>
[INFO ] 20:19:22,982 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: destroy
[INFO ] 20:19:22,983 [JCLLoggerAdapter.java : 216] : jetty-6.0.2
[INFO ] 20:19:22,984 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: init
[INFO ] 20:19:22,986 [JCLLoggerAdapter.java : 216] : Started SocketConnector @ 0.0.0.0:8989
[DEBUG] 20:19:22,986 [YadisResolver.java : 351] : Performing HTTP HEAD on: http://localhost:8989/?headers=simplexrds&xrds=malformedxrds3 ...
[DEBUG] 20:19:22,988 [YadisResolver.java : 351] : Performing HTTP GET on: http://localhost:8989/?headers=simplexrds&xrds=malformedxrds3 ...
[DEBUG] 20:19:22,990 [HttpCache.java : 202] : Read 280 bytes.
[DEBUG] 20:19:22,990 [XrdsParserImpl.java : 48] : Parsing XRDS input for service types: [http://openid.net/signon/1.1, http://specs.openid.net/auth/2.0/signon, http://openid.net/signon/1.0, http://specs.openid.net/auth/2.0/server]
[DEBUG] 20:19:22,990 [XrdsParserImpl.java : 161] : Parsing XRDS input: <?xml version="1.0" encoding="UTF-8"?>
<!-- Sample XRDS file at: xrds -->
<xrds:XRDS
xmlns:xrds="xri://$xrds"
xmlns="xri://$xrd*($v*2.0)">
<XRD>
<Service priority="0">
<Type>http://example.com/</Type>
<URI>:</URI>
</Service>
</XRD>
</xrds:XRDS>
[DEBUG] 20:19:23,000 [YadisResolver.java : 351] : Performing HTTP HEAD on: http://localhost:8989/?headers=simplexrds&xrds=malformedxrds4 ...
[DEBUG] 20:19:23,010 [YadisResolver.java : 351] : Performing HTTP GET on: http://localhost:8989/?headers=simplexrds&xrds=malformedxrds4 ...
[DEBUG] 20:19:23,013 [HttpCache.java : 202] : Read 284 bytes.
[DEBUG] 20:19:23,013 [XrdsParserImpl.java : 48] : Parsing XRDS input for service types: [http://openid.net/signon/1.1, http://specs.openid.net/auth/2.0/signon, http://openid.net/signon/1.0, http://specs.openid.net/auth/2.0/server]
[DEBUG] 20:19:23,013 [XrdsParserImpl.java : 161] : Parsing XRDS input: <?xml version="1.0" encoding="UTF-8"?>
<!-- Sample XRDS file at: xrds -->
<xrds:XRDS
xmlns:xrds="xri://$xrds"
xmlns="xri://$xrd*($v*2.0)">
<XRD>
<Service priority="0">
<Type>http://example.com/</Type>
<URI>http:</URI>
</Service>
</XRD>
</xrds:XRDS>
[DEBUG] 20:19:23,026 [YadisResolver.java : 351] : Performing HTTP HEAD on: http://localhost:8989/?headers=simplexrds&xrds=malformedxrds5 ...
[DEBUG] 20:19:23,032 [YadisResolver.java : 351] : Performing HTTP GET on: http://localhost:8989/?headers=simplexrds&xrds=malformedxrds5 ...
[DEBUG] 20:19:23,035 [HttpCache.java : 202] : Read 306 bytes.
[DEBUG] 20:19:23,035 [XrdsParserImpl.java : 48] : Parsing XRDS input for service types: [http://openid.net/signon/1.1, http://specs.openid.net/auth/2.0/signon, http://openid.net/signon/1.0, http://specs.openid.net/auth/2.0/server]
[DEBUG] 20:19:23,035 [XrdsParserImpl.java : 161] : Parsing XRDS input: <?xml version="1.0" encoding="UTF-8"?>
<!-- Sample XRDS file at: xrds -->
<xrds:XRDS
xmlns:xrds="xri://$xrds"
xmlns="xri://$xrd*($v*2.0)">
<XRD>
<Service priority="0">
<Type>http://specs.openid.net/auth/2.0/signon</Type>
<URI>bla bla</URI>
</Service>
</XRD>
</xrds:XRDS>
[DEBUG] 20:19:23,042 [XrdsParserImpl.java : 90] : Found 1 services for the requested types.
[DEBUG] 20:19:23,045 [XrdsParserImpl.java : 115] : Discovered endpoint:
Service priority: 0
Type: [http://specs.openid.net/auth/2.0/signon]
URI: bla bla
URI Priority: -1
LocalID: null
[DEBUG] 20:19:23,045 [YadisResult.java : 119] : Setting X-XRDS-Location for yadis result: http://localhost:8989/?headers=simplexrds&xrds=malformedxrds5
[INFO ] 20:19:23,045 [YadisResolver.java : 245] : Yadis discovered 1 endpoints from: http://localhost:8989/?headers=simplexrds&xrds=malformedxrds5
[INFO ] 20:19:23,048 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: destroy
[INFO ] 20:19:23,049 [JCLLoggerAdapter.java : 216] : jetty-6.0.2
[INFO ] 20:19:23,050 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: init
[INFO ] 20:19:23,050 [JCLLoggerAdapter.java : 216] : Started SocketConnector @ 0.0.0.0:8989
[DEBUG] 20:19:23,050 [YadisResolver.java : 351] : Performing HTTP HEAD on: http://localhost:8989/?headers=simplexrds&xrds=xrdsdelegate ...
[DEBUG] 20:19:23,053 [YadisResolver.java : 351] : Performing HTTP GET on: http://localhost:8989/?headers=simplexrds&xrds=xrdsdelegate ...
[DEBUG] 20:19:23,056 [HttpCache.java : 202] : Read 435 bytes.
[DEBUG] 20:19:23,056 [XrdsParserImpl.java : 48] : Parsing XRDS input for service types: [http://openid.net/signon/1.1, http://specs.openid.net/auth/2.0/signon, http://openid.net/signon/1.0, http://specs.openid.net/auth/2.0/server]
[DEBUG] 20:19:23,056 [XrdsParserImpl.java : 161] : Parsing XRDS input: <?xml version="1.0" encoding="UTF-8"?>
<!-- Sample XRDS file at: xrds -->
<xrds:XRDS
xmlns:xrds="xri://$xrds"
xmlns:openid="http://openid.net/xmlns/1.0"
xmlns="xri://$xrd*($v*2.0)">
<XRD>
<Service priority="0">
<Type>http://openid.net/signon/1.1</Type>
<URI>http://www.openidenabled.com/</URI>
<openid:Delegate>http://example.com/delegate/</openid:Delegate>
</Service>
</XRD>
</xrds:XRDS>
[DEBUG] 20:19:23,066 [XrdsParserImpl.java : 90] : Found 1 services for the requested types.
[DEBUG] 20:19:23,069 [XrdsParserImpl.java : 115] : Discovered endpoint:
Service priority: 0
Type: [http://openid.net/signon/1.1]
URI: http://www.openidenabled.com/
URI Priority: -1
LocalID: null
[DEBUG] 20:19:23,069 [YadisResult.java : 119] : Setting X-XRDS-Location for yadis result: http://localhost:8989/?headers=simplexrds&xrds=xrdsdelegate
[INFO ] 20:19:23,070 [YadisResolver.java : 245] : Yadis discovered 1 endpoints from: http://localhost:8989/?headers=simplexrds&xrds=xrdsdelegate
[DEBUG] 20:19:23,070 [UrlIdentifier.java : 125] : Normalized: http://localhost:8989/?headers=simplexrds&xrds=xrdsdelegate to: http://localhost:8989/?headers=simplexrds&xrds=xrdsdelegate
[INFO ] 20:19:23,071 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: destroy
[INFO ] 20:19:23,072 [JCLLoggerAdapter.java : 216] : jetty-6.0.2
[INFO ] 20:19:23,072 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: init
[INFO ] 20:19:23,073 [JCLLoggerAdapter.java : 216] : Started SocketConnector @ 0.0.0.0:8989
[DEBUG] 20:19:23,073 [YadisResolver.java : 351] : Performing HTTP HEAD on: http://localhost:8989/?headers=simplexrds&xrds=malformedxrds6 ...
[DEBUG] 20:19:23,075 [YadisResolver.java : 351] : Performing HTTP GET on: http://localhost:8989/?headers=simplexrds&xrds=malformedxrds6 ...
[DEBUG] 20:19:23,079 [HttpCache.java : 202] : Read 279 bytes.
[DEBUG] 20:19:23,079 [XrdsParserImpl.java : 48] : Parsing XRDS input for service types: [http://example.com/]
[DEBUG] 20:19:23,080 [XrdsParserImpl.java : 161] : Parsing XRDS input: <?xml version="1.0" encoding="UTF-8"?>
<!-- Sample XRDS file at: xrds -->
<xrds:XRDS
xmlns:xrds="xri://$xrds"
xmlns="xri://$xrd*($v*2.0)">
<XRD>
<Service priority="0">
<Type>http://example.com/</Type>
<URI></URI>
</Service>
</XRD>
</xrds:XRDS>
[DEBUG] 20:19:23,093 [XrdsParserImpl.java : 90] : Found 1 services for the requested types.
[DEBUG] 20:19:23,094 [XrdsParserImpl.java : 115] : Discovered endpoint:
Service priority: 0
Type: [http://example.com/]
URI: null
URI Priority: -1
LocalID: null
[DEBUG] 20:19:23,094 [YadisResult.java : 119] : Setting X-XRDS-Location for yadis result: http://localhost:8989/?headers=simplexrds&xrds=malformedxrds6
[INFO ] 20:19:23,094 [YadisResolver.java : 245] : Yadis discovered 1 endpoints from: http://localhost:8989/?headers=simplexrds&xrds=malformedxrds6
[WARN ] 20:19:23,106 [RealmVerifier.java : 107] : RP discovery / realm validation disabled;
[DEBUG] 20:19:23,107 [Discovery.java : 89] : Creating URL identifier for: http://localhost:8989/?headers=simplexrds&xrds=malformedxrds6
[DEBUG] 20:19:23,108 [UrlIdentifier.java : 125] : Normalized: http://localhost:8989/?headers=simplexrds&xrds=malformedxrds6 to: http://localhost:8989/?headers=simplexrds&xrds=malformedxrds6
[INFO ] 20:19:23,108 [Discovery.java : 128] : Starting discovery on URL identifier: http://localhost:8989/?headers=simplexrds&xrds=malformedxrds6
[DEBUG] 20:19:23,108 [YadisResolver.java : 351] : Performing HTTP HEAD on: http://localhost:8989/?headers=simplexrds&xrds=malformedxrds6 ...
[DEBUG] 20:19:23,112 [YadisResolver.java : 351] : Performing HTTP GET on: http://localhost:8989/?headers=simplexrds&xrds=malformedxrds6 ...
[DEBUG] 20:19:23,114 [HttpCache.java : 202] : Read 279 bytes.
[DEBUG] 20:19:23,114 [XrdsParserImpl.java : 48] : Parsing XRDS input for service types: [http://openid.net/signon/1.1, http://specs.openid.net/auth/2.0/signon, http://openid.net/signon/1.0, http://specs.openid.net/auth/2.0/server]
[DEBUG] 20:19:23,114 [XrdsParserImpl.java : 161] : Parsing XRDS input: <?xml version="1.0" encoding="UTF-8"?>
<!-- Sample XRDS file at: xrds -->
<xrds:XRDS
xmlns:xrds="xri://$xrds"
xmlns="xri://$xrd*($v*2.0)">
<XRD>
<Service priority="0">
<Type>http://example.com/</Type>
<URI></URI>
</Service>
</XRD>
</xrds:XRDS>
[DEBUG] 20:19:23,126 [XrdsParserImpl.java : 90] : Found 1 services for the requested types.
[INFO ] 20:19:23,127 [YadisResolver.java : 245] : Yadis discovered 0 endpoints from: http://localhost:8989/?headers=simplexrds&xrds=malformedxrds6
[INFO ] 20:19:23,127 [Discovery.java : 139] : No OpenID service endpoints discovered through Yadis; attempting HTML discovery...
[INFO ] 20:19:23,128 [HttpCache.java : 242] : Cached GET response does not match the required content type, removing.
[INFO ] 20:19:23,128 [HttpCache.java : 144] : Removing cached GET for http://localhost:8989/?headers=simplexrds&xrds=malformedxrds6
[INFO ] 20:19:23,128 [HttpCache.java : 102] : Removing cached GET response for http://localhost:8989/?headers=simplexrds&xrds=malformedxrds6
[DEBUG] 20:19:23,130 [HttpCache.java : 202] : Read 279 bytes.
[DEBUG] 20:19:23,130 [UrlIdentifier.java : 125] : Normalized: http://localhost:8989/?headers=simplexrds&xrds=malformedxrds6 to: http://localhost:8989/?headers=simplexrds&xrds=malformedxrds6
[DEBUG] 20:19:23,131 [CyberNekoDOMHtmlParser.java : 44] : Parsing HTML data:
<?xml version="1.0" encoding="UTF-8"?>
<!-- Sample XRDS file at: xrds -->
<xrds:XRDS
xmlns:xrds="xri://$xrds"
xmlns="xri://$xrd*($v*2.0)">
<XRD>
<Service priority="0">
<Type>http://example.com/</Type>
<URI></URI>
</Service>
</XRD>
</xrds:XRDS>
[DEBUG] 20:19:23,133 [CyberNekoDOMHtmlParser.java : 65] : HTML discovery result:
ClaimedID:http://localhost:8989/?headers=simplexrds&xrds=malformedxrds6
[INFO ] 20:19:23,134 [HtmlResolver.java : 127] : HTML discovery completed on: http://localhost:8989/?headers=simplexrds&xrds=malformedxrds6
[INFO ] 20:19:23,134 [Discovery.java : 151] : Discovered 0 OpenID endpoints.
[INFO ] 20:19:23,135 [JCLLoggerAdapter.java : 216] : org.openid4java.discovery.yadis.YadisTestServlet: destroy
Tests run: 21, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.08 sec
Running org.openid4java.samples.ConsumerAndProviderTest
[WARN ] 20:19:23,140 [RealmVerifier.java : 107] : RP discovery / realm validation disabled;
[WARN ] 20:19:23,141 [RealmVerifier.java : 107] : RP discovery / realm validation disabled;
[WARN ] 20:19:23,147 [RealmVerifier.java : 107] : RP discovery / realm validation disabled;
[WARN ] 20:19:23,148 [RealmVerifier.java : 107] : RP discovery / realm validation disabled;
[WARN ] 20:19:23,150 [RealmVerifier.java : 107] : RP discovery / realm validation disabled;
[WARN ] 20:19:23,151 [RealmVerifier.java : 107] : RP discovery / realm validation disabled;
[WARN ] 20:19:23,151 [RealmVerifier.java : 107] : RP discovery / realm validation disabled;
[WARN ] 20:19:23,151 [RealmVerifier.java : 107] : RP discovery / realm validation disabled;
[INFO ] 20:19:23,153 [JCLLoggerAdapter.java : 216] : jetty-6.0.2
[INFO ] 20:19:23,153 [JCLLoggerAdapter.java : 216] : org.openid4java.samples.LoginCallbackServlet: init
[INFO ] 20:19:23,153 [JCLLoggerAdapter.java : 216] : org.openid4java.samples.LoginServlet: init
[INFO ] 20:19:23,154 [JCLLoggerAdapter.java : 216] : org.openid4java.samples.ProviderServlet: init
[INFO ] 20:19:23,154 [JCLLoggerAdapter.java : 216] : org.openid4java.samples.UserInfoServlet: init
[INFO ] 20:19:23,156 [JCLLoggerAdapter.java : 216] : Started SocketConnector @ 0.0.0.0:8989
[INFO ] 20:19:23,230 [HttpServletSupport.java : 37] LoginServlet call-1 : begin onService
[INFO ] 20:19:23,230 [LoginServlet.java : 33] LoginServlet call-1 : display form
[INFO ] 20:19:23,230 [HttpServletSupport.java : 49] LoginServlet call-1 : end onService
[INFO ] 20:19:23,333 [HtmlUnitDialog.java : 692] : Window "" changed : "no_html" became ""
[INFO ] 20:19:23,356 [HttpServletSupport.java : 37] LoginServlet call-2 : begin onService
[INFO ] 20:19:23,357 [LoginServlet.java : 28] LoginServlet call-2 : openind_identifier set => try to consume
[DEBUG] 20:19:23,358 [Discovery.java : 89] LoginServlet call-2 : Creating URL identifier for: http://localhost:8989/user
[DEBUG] 20:19:23,358 [UrlIdentifier.java : 125] LoginServlet call-2 : Normalized: http://localhost:8989/user to: http://localhost:8989/user
[INFO ] 20:19:23,358 [Discovery.java : 128] LoginServlet call-2 : Starting discovery on URL identifier: http://localhost:8989/user
[DEBUG] 20:19:23,358 [YadisResolver.java : 351] LoginServlet call-2 : Performing HTTP HEAD on: http://localhost:8989/user ...
[INFO ] 20:19:23,359 [HttpServletSupport.java : 37] UserInfoServlet call-3 : begin onService
[INFO ] 20:19:23,360 [HttpServletSupport.java : 49] UserInfoServlet call-3 : end onService
[DEBUG] 20:19:23,360 [YadisResolver.java : 351] LoginServlet call-2 : Performing HTTP GET on: http://localhost:8989/user ...
[INFO ] 20:19:23,362 [HttpServletSupport.java : 37] UserInfoServlet call-4 : begin onService
[INFO ] 20:19:23,362 [HttpServletSupport.java : 49] UserInfoServlet call-4 : end onService
[DEBUG] 20:19:23,362 [HttpCache.java : 202] LoginServlet call-2 : Read 320 bytes.
[DEBUG] 20:19:23,362 [XrdsParserImpl.java : 48] LoginServlet call-2 : Parsing XRDS input for service types: [http://openid.net/signon/1.1, http://specs.openid.net/auth/2.0/signon, http://openid.net/signon/1.0, http://specs.openid.net/auth/2.0/server]
[DEBUG] 20:19:23,362 [XrdsParserImpl.java : 161] LoginServlet call-2 : Parsing XRDS input: <?xml version="1.0" encoding="UTF-8"?>
<xrds:XRDS
xmlns:xrds="xri://$xrds"
xmlns:openid="http://openid.net/xmlns/1.0"
xmlns="xri://$xrd*($v*2.0)">
<XRD>
<Service priority="0">
<Type>http://openid.net/signon/1.0</Type>
<URI>http://localhost:8989/provider</URI>
</Service>
</XRD>
</xrds:XRDS>
[DEBUG] 20:19:23,367 [XrdsParserImpl.java : 90] LoginServlet call-2 : Found 1 services for the requested types.
[DEBUG] 20:19:23,368 [XrdsParserImpl.java : 115] LoginServlet call-2 : Discovered endpoint:
Service priority: 0
Type: [http://openid.net/signon/1.0]
URI: http://localhost:8989/provider
URI Priority: -1
LocalID: null
[DEBUG] 20:19:23,368 [YadisResult.java : 119] LoginServlet call-2 : Setting X-XRDS-Location for yadis result: http://localhost:8989/user
[INFO ] 20:19:23,368 [YadisResolver.java : 245] LoginServlet call-2 : Yadis discovered 1 endpoints from: http://localhost:8989/user
[DEBUG] 20:19:23,368 [UrlIdentifier.java : 125] LoginServlet call-2 : Normalized: http://localhost:8989/user to: http://localhost:8989/user
[INFO ] 20:19:23,368 [Discovery.java : 151] LoginServlet call-2 : Discovered 1 OpenID endpoints.
[INFO ] 20:19:23,368 [ConsumerManager.java : 707] LoginServlet call-2 : Trying to associate with http://localhost:8989/provider attempts left: 4
[WARN ] 20:19:23,369 [ConsumerManager.java : 894] LoginServlet call-2 : Could not create association of type: :HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,375 [DiffieHellmanSession.java : 96] LoginServlet call-2 : Created DH session: DH-SHA1:HMAC-SHA1:OpenID1 base: 2 modulus: 155172898181473697471232257763715539915724801966915404479707795314057629378541917580651227423698188993727816152646631438561595825688188889951272158842675419950341258706556549803580104870537681476726513255747040765857479291291572334510643245094715007229621094194349783925984760375594985848253359305585439638443
[DEBUG] 20:19:23,376 [ParameterList.java : 33] LoginServlet call-2 : Created empty parameter list.
[DEBUG] 20:19:23,383 [AssociationRequest.java : 80] LoginServlet call-2 : Creating association request, type: DH-SHA1:HMAC-SHA1:OpenID1DH session: DH-SHA1:HMAC-SHA1:OpenID1 base: 2 modulus: 155172898181473697471232257763715539915724801966915404479707795314057629378541917580651227423698188993727816152646631438561595825688188889951272158842675419950341258706556549803580104870537681476726513255747040765857479291291572334510643245094715007229621094194349783925984760375594985848253359305585439638443
[DEBUG] 20:19:23,384 [AssociationSessionType.java : 187] LoginServlet call-2 : Session:Association Type: DH-SHA1:HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,384 [AssociationRequest.java : 140] LoginServlet call-2 : Created association request:
openid.mode:associate
openid.session_type:DH-SHA1
openid.assoc_type:HMAC-SHA1
openid.dh_consumer_public:bNv37xf2x3CWWZRt7tAilyPnDJdTglvLIPB59Ic7D3SECgA+qICQbs+7uhr6Xv7ZWjjSLYeXfmkPWviFUnTcln9cqAeY9Or3FHogWgk3tIX44x+y17McUBqvS3Jo8BCQfINor63vqMdBc2Iap0YBdzZWXAjx3BNrQTAJN4MW8gM=
[DEBUG] 20:19:23,384 [AssociationSessionType.java : 187] LoginServlet call-2 : Session:Association Type: DH-SHA1:HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,384 [ConsumerManager.java : 767] LoginServlet call-2 : Trying association type: DH-SHA1:HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,384 [AssociationSessionType.java : 187] LoginServlet call-2 : Session:Association Type: DH-SHA1:HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,384 [AssociationSessionType.java : 187] LoginServlet call-2 : Session:Association Type: DH-SHA1:HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,384 [ParameterList.java : 33] LoginServlet call-2 : Created empty parameter list.
[DEBUG] 20:19:23,387 [ConsumerManager.java : 616] LoginServlet call-2 : Performing HTTP POST on http://localhost:8989/provider
[INFO ] 20:19:23,388 [HttpServletSupport.java : 37] ProviderServlet call-5 : begin onService
[INFO ] 20:19:23,389 [ProviderServlet.java : 26] ProviderServlet call-5 : start processing...
[DEBUG] 20:19:23,389 [ParameterList.java : 84] ProviderServlet call-5 : Creating parameter list:
openid.assoc_type:HMAC-SHA1
openid.mode:associate
openid.session_type:DH-SHA1
openid.dh_consumer_public:bNv37xf2x3CWWZRt7tAilyPnDJdTglvLIPB59Ic7D3SECgA+qICQbs+7uhr6Xv7ZWjjSLYeXfmkPWviFUnTcln9cqAeY9Or3FHogWgk3tIX44x+y17McUBqvS3Jo8BCQfINor63vqMdBc2Iap0YBdzZWXAjx3BNrQTAJN4MW8gM=
[INFO ] 20:19:23,389 [ServerManager.java : 382] ProviderServlet call-5 : Processing association request...
[DEBUG] 20:19:23,390 [ParameterList.java : 33] ProviderServlet call-5 : Created empty parameter list.
[DEBUG] 20:19:23,390 [AssociationSessionType.java : 187] ProviderServlet call-5 : Session:Association Type: DH-SHA1:HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,390 [AssociationRequest.java : 153] ProviderServlet call-5 : Created association request from message parameters:
openid.assoc_type:HMAC-SHA1
openid.mode:associate
openid.session_type:DH-SHA1
openid.dh_consumer_public:bNv37xf2x3CWWZRt7tAilyPnDJdTglvLIPB59Ic7D3SECgA+qICQbs+7uhr6Xv7ZWjjSLYeXfmkPWviFUnTcln9cqAeY9Or3FHogWgk3tIX44x+y17McUBqvS3Jo8BCQfINor63vqMdBc2Iap0YBdzZWXAjx3BNrQTAJN4MW8gM=
[DEBUG] 20:19:23,390 [AssociationSessionType.java : 187] ProviderServlet call-5 : Session:Association Type: DH-SHA1:HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,390 [Association.java : 88] ProviderServlet call-5 : Generated SHA1 MAC key: javax.crypto.spec.SecretKeySpec@9b4f92d7
[DEBUG] 20:19:23,391 [Association.java : 45] ProviderServlet call-5 : Creating association, type: HMAC-SHA1 handle: 1455391163147-0 expires: Sat Feb 13 20:49:23 CET 2016
[DEBUG] 20:19:23,391 [InMemoryServerAssociationStore.java : 45] ProviderServlet call-5 : Generated association, handle: 1455391163147-0 type: HMAC-SHA1 expires in: 1800 seconds.
[INFO ] 20:19:23,391 [ServerManager.java : 408] ProviderServlet call-5 : Returning shared association; handle: 1455391163147-0
[DEBUG] 20:19:23,391 [ParameterList.java : 33] ProviderServlet call-5 : Created empty parameter list.
[DEBUG] 20:19:23,391 [AssociationSessionType.java : 187] ProviderServlet call-5 : Session:Association Type: DH-SHA1:HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,392 [AssociationResponse.java : 57] ProviderServlet call-5 : Creating association response, type: DH-SHA1:HMAC-SHA1:OpenID1 association handle: 1455391163147-0
[DEBUG] 20:19:23,392 [AssociationSessionType.java : 187] ProviderServlet call-5 : Session:Association Type: DH-SHA1:HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,398 [DiffieHellmanSession.java : 96] ProviderServlet call-5 : Created DH session: DH-SHA1:HMAC-SHA1:OpenID1 base: 2 modulus: 155172898181473697471232257763715539915724801966915404479707795314057629378541917580651227423698188993727816152646631438561595825688188889951272158842675419950341258706556549803580104870537681476726513255747040765857479291291572334510643245094715007229621094194349783925984760375594985848253359305585439638443
[DEBUG] 20:19:23,401 [DiffieHellmanSession.java : 253] ProviderServlet call-5 : Encrypted MAC key Base64: 79xwJBxt6qD2uCSyjA517K3HgOk=
[DEBUG] 20:19:23,401 [AssociationSessionType.java : 187] ProviderServlet call-5 : Session:Association Type: DH-SHA1:HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,402 [AssociationResponse.java : 107] ProviderServlet call-5 : Created association response:
session_type:DH-SHA1
assoc_type:HMAC-SHA1
assoc_handle:1455391163147-0
expires_in:1799
dh_server_public:MMrAHUMpM4DmIkmsOY4Y2OVaRRffXlbbFFV2ubFJd1Sg3ceYFAvh9Ec75HI6xh3K4FPvi+/0lTaBww1ZKJh9LEN4R5ascfGuPiZtJMmRwDMJUucehEWHVAImZ0t7lt3LXh8rOPsvVWJ8SbqDwi54EJNqRSFK8LrjoRC2gdYEF2Y=
enc_mac_key:79xwJBxt6qD2uCSyjA517K3HgOk=
[INFO ] 20:19:23,402 [ProviderServlet.java : 30] ProviderServlet call-5 : processing not null :session_type:DH-SHA1
assoc_type:HMAC-SHA1
assoc_handle:1455391163147-0
expires_in:1799
dh_server_public:MMrAHUMpM4DmIkmsOY4Y2OVaRRffXlbbFFV2ubFJd1Sg3ceYFAvh9Ec75HI6xh3K4FPvi+/0lTaBww1ZKJh9LEN4R5ascfGuPiZtJMmRwDMJUucehEWHVAImZ0t7lt3LXh8rOPsvVWJ8SbqDwi54EJNqRSFK8LrjoRC2gdYEF2Y=
enc_mac_key:79xwJBxt6qD2uCSyjA517K3HgOk=
[INFO ] 20:19:23,402 [HttpServletSupport.java : 49] ProviderServlet call-5 : end onService
[DEBUG] 20:19:23,404 [ParameterList.java : 188] LoginServlet call-2 : Creating parameter list from key-value form:
session_type:DH-SHA1
assoc_type:HMAC-SHA1
assoc_handle:1455391163147-0
expires_in:1799
dh_server_public:MMrAHUMpM4DmIkmsOY4Y2OVaRRffXlbbFFV2ubFJd1Sg3ceYFAvh9Ec75HI6xh3K4FPvi+/0lTaBww1ZKJh9LEN4R5ascfGuPiZtJMmRwDMJUucehEWHVAImZ0t7lt3LXh8rOPsvVWJ8SbqDwi54EJNqRSFK8LrjoRC2gdYEF2Y=
enc_mac_key:79xwJBxt6qD2uCSyjA517K3HgOk=
[DEBUG] 20:19:23,404 [ParameterList.java : 33] LoginServlet call-2 : Created empty parameter list.
[DEBUG] 20:19:23,404 [ParameterList.java : 89] LoginServlet call-2 : Copying parameter list:
session_type:DH-SHA1
assoc_type:HMAC-SHA1
assoc_handle:1455391163147-0
expires_in:1799
dh_server_public:MMrAHUMpM4DmIkmsOY4Y2OVaRRffXlbbFFV2ubFJd1Sg3ceYFAvh9Ec75HI6xh3K4FPvi+/0lTaBww1ZKJh9LEN4R5ascfGuPiZtJMmRwDMJUucehEWHVAImZ0t7lt3LXh8rOPsvVWJ8SbqDwi54EJNqRSFK8LrjoRC2gdYEF2Y=
enc_mac_key:79xwJBxt6qD2uCSyjA517K3HgOk=
[DEBUG] 20:19:23,404 [ConsumerManager.java : 622] LoginServlet call-2 : Retrived response:
session_type:DH-SHA1
assoc_type:HMAC-SHA1
assoc_handle:1455391163147-0
expires_in:1799
dh_server_public:MMrAHUMpM4DmIkmsOY4Y2OVaRRffXlbbFFV2ubFJd1Sg3ceYFAvh9Ec75HI6xh3K4FPvi+/0lTaBww1ZKJh9LEN4R5ascfGuPiZtJMmRwDMJUucehEWHVAImZ0t7lt3LXh8rOPsvVWJ8SbqDwi54EJNqRSFK8LrjoRC2gdYEF2Y=
enc_mac_key:79xwJBxt6qD2uCSyjA517K3HgOk=
[DEBUG] 20:19:23,404 [ParameterList.java : 33] LoginServlet call-2 : Created empty parameter list.
[DEBUG] 20:19:23,404 [AssociationSessionType.java : 187] LoginServlet call-2 : Session:Association Type: DH-SHA1:HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,404 [AssociationResponse.java : 121] LoginServlet call-2 : Created association response from message parameters:
session_type:DH-SHA1
assoc_type:HMAC-SHA1
assoc_handle:1455391163147-0
expires_in:1799
dh_server_public:MMrAHUMpM4DmIkmsOY4Y2OVaRRffXlbbFFV2ubFJd1Sg3ceYFAvh9Ec75HI6xh3K4FPvi+/0lTaBww1ZKJh9LEN4R5ascfGuPiZtJMmRwDMJUucehEWHVAImZ0t7lt3LXh8rOPsvVWJ8SbqDwi54EJNqRSFK8LrjoRC2gdYEF2Y=
enc_mac_key:79xwJBxt6qD2uCSyjA517K3HgOk=
[DEBUG] 20:19:23,405 [AssociationResponse.java : 315] LoginServlet call-2 : Retrieving MAC key from association response...
[DEBUG] 20:19:23,405 [AssociationSessionType.java : 187] LoginServlet call-2 : Session:Association Type: DH-SHA1:HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,408 [DiffieHellmanSession.java : 298] LoginServlet call-2 : Decrypted MAC key Base64: SQbdXyk1yqMz0PCrsEyfwln80Zc=
[DEBUG] 20:19:23,408 [AssociationResponse.java : 331] LoginServlet call-2 : Decrypted MAC key (base64): SQbdXyk1yqMz0PCrsEyfwln80Zc=
[DEBUG] 20:19:23,408 [Association.java : 45] LoginServlet call-2 : Creating association, type: HMAC-SHA1 handle: 1455391163147-0 expires: Sat Feb 13 20:49:22 CET 2016
[DEBUG] 20:19:23,408 [AssociationResponse.java : 354] LoginServlet call-2 : Created association for handle: 1455391163147-0
[DEBUG] 20:19:23,408 [AssociationSessionType.java : 187] LoginServlet call-2 : Session:Association Type: DH-SHA1:HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,408 [AssociationSessionType.java : 187] LoginServlet call-2 : Session:Association Type: DH-SHA1:HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,408 [InMemoryConsumerAssociationStore.java : 41] LoginServlet call-2 : Adding association to the in-memory store: 1455391163147-0 with OP: http://localhost:8989/provider
[INFO ] 20:19:23,408 [ConsumerManager.java : 804] LoginServlet call-2 : Associated with http://localhost:8989/provider handle: 1455391163147-0
[INFO ] 20:19:23,410 [ConsumerManager.java :1065] LoginServlet call-2 : Creating authentication request for OP-endpoint: http://localhost:8989/provider claimedID: http://localhost:8989/user OP-specific ID: http://localhost:8989/user
[DEBUG] 20:19:23,410 [IncrementalNonceGenerator.java : 43] LoginServlet call-2 : Generated nonce: 2016-02-13T19:19:23Z0
[DEBUG] 20:19:23,411 [ConsumerManager.java :1367] LoginServlet call-2 : Creating private association for opUrl http://localhost:8989/provider
[DEBUG] 20:19:23,411 [Association.java : 111] LoginServlet call-2 : Generated SHA256 MAC key: javax.crypto.spec.SecretKeySpec@5883c70
[DEBUG] 20:19:23,411 [Association.java : 45] LoginServlet call-2 : Creating association, type: HMAC-SHA256 handle: expires: Sat Feb 13 20:24:23 CET 2016
[DEBUG] 20:19:23,411 [InMemoryConsumerAssociationStore.java : 41] LoginServlet call-2 : Adding association to the in-memory store: with OP: http://localhost:8989/provider
[DEBUG] 20:19:23,411 [Association.java : 261] LoginServlet call-2 : Computing signature for input data:
http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0
[DEBUG] 20:19:23,411 [Association.java : 267] LoginServlet call-2 : Calculated signature: RCA3zhQuwObN+tb9KPo+oWPaV/RCFs26dc0rm9LGY7o=
[INFO ] 20:19:23,412 [ConsumerManager.java :1387] LoginServlet call-2 : Inserted consumer nonce: 2016-02-13T19:19:23Z0
[DEBUG] 20:19:23,412 [ConsumerManager.java :1389] LoginServlet call-2 : return_to:http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=RCA3zhQuwObN%2Btb9KPo%2BoWPaV%2FRCFs26dc0rm9LGY7o%3D
[DEBUG] 20:19:23,413 [ParameterList.java : 33] LoginServlet call-2 : Created empty parameter list.
[DEBUG] 20:19:23,413 [RealmVerifier.java : 210] LoginServlet call-2 : Verifying realm: http://localhost:8989/loginCallback on return URL: http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=RCA3zhQuwObN%2Btb9KPo%2BoWPaV%2FRCFs26dc0rm9LGY7o%3D
[INFO ] 20:19:23,413 [RealmVerifier.java : 278] LoginServlet call-2 : Return URL: http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=RCA3zhQuwObN%2Btb9KPo%2BoWPaV%2FRCFs26dc0rm9LGY7o%3D matches realm: http://localhost:8989/loginCallback
[DEBUG] 20:19:23,413 [AuthRequest.java : 103] LoginServlet call-2 : Created auth request:
openid.identity:http://localhost:8989/user
openid.return_to:http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=RCA3zhQuwObN%2Btb9KPo%2BoWPaV%2FRCFs26dc0rm9LGY7o%3D
openid.trust_root:http://localhost:8989/loginCallback
openid.assoc_handle:1455391163147-0
openid.mode:checkid_setup
[DEBUG] 20:19:23,414 [ParameterList.java : 33] LoginServlet call-2 : Created empty parameter list.
[DEBUG] 20:19:23,414 [AxMessage.java : 50] LoginServlet call-2 : Created empty AXMessage.
[DEBUG] 20:19:23,414 [FetchRequest.java : 41] LoginServlet call-2 : Created empty fetch request.
[DEBUG] 20:19:23,415 [FetchRequest.java : 127] LoginServlet call-2 : Added new attribute to fetch request; type: http://schema.openid.net/contact/email alias: email count: 1 required: true
[DEBUG] 20:19:23,415 [Message.java : 396] LoginServlet call-2 : Adding extension; type URI: http://openid.net/srv/ax/1.0 alias: ext1
[DEBUG] 20:19:23,415 [ParameterList.java : 33] LoginServlet call-2 : Created empty parameter list.
[DEBUG] 20:19:23,416 [SRegMessage.java : 73] LoginServlet call-2 : Created empty SRegMessage.
[DEBUG] 20:19:23,416 [SRegRequest.java : 34] LoginServlet call-2 : Created empty SReg request.
[DEBUG] 20:19:23,416 [SRegRequest.java : 105] LoginServlet call-2 : Added new attribute to SReg request: email required: true
[DEBUG] 20:19:23,416 [Message.java : 396] LoginServlet call-2 : Adding extension; type URI: http://openid.net/sreg/1.0 alias: sreg
[INFO ] 20:19:23,432 [HttpServletSupport.java : 49] LoginServlet call-2 : end onService
[INFO ] 20:19:23,446 [HttpMethodDirector.java : 817] : Redirect requested but followRedirects is disabled
[INFO ] 20:19:23,449 [HttpServletSupport.java : 37] ProviderServlet call-6 : begin onService
[INFO ] 20:19:23,449 [ProviderServlet.java : 26] ProviderServlet call-6 : start processing...
[DEBUG] 20:19:23,450 [ParameterList.java : 84] ProviderServlet call-6 : Creating parameter list:
openid.ext1.mode:fetch_request
openid.return_to:http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=RCA3zhQuwObN%2Btb9KPo%2BoWPaV%2FRCFs26dc0rm9LGY7o%3D
openid.sreg.required:email
openid.ns.sreg:http://openid.net/sreg/1.0
openid.identity:http://localhost:8989/user
openid.assoc_handle:1455391163147-0
openid.mode:checkid_setup
openid.trust_root:http://localhost:8989/loginCallback
openid.ns.ext1:http://openid.net/srv/ax/1.0
openid.ext1.type.email:http://schema.openid.net/contact/email
openid.ext1.required:email
[DEBUG] 20:19:23,450 [ParameterList.java : 33] ProviderServlet call-6 : Created empty parameter list.
[DEBUG] 20:19:23,450 [RealmVerifier.java : 210] ProviderServlet call-6 : Verifying realm: http://localhost:8989/loginCallback on return URL: http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=RCA3zhQuwObN%2Btb9KPo%2BoWPaV%2FRCFs26dc0rm9LGY7o%3D
[INFO ] 20:19:23,450 [RealmVerifier.java : 278] ProviderServlet call-6 : Return URL: http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=RCA3zhQuwObN%2Btb9KPo%2BoWPaV%2FRCFs26dc0rm9LGY7o%3D matches realm: http://localhost:8989/loginCallback
[DEBUG] 20:19:23,450 [AuthRequest.java : 118] ProviderServlet call-6 : Created auth request:
openid.ext1.mode:fetch_request
openid.return_to:http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=RCA3zhQuwObN%2Btb9KPo%2BoWPaV%2FRCFs26dc0rm9LGY7o%3D
openid.sreg.required:email
openid.ns.sreg:http://openid.net/sreg/1.0
openid.identity:http://localhost:8989/user
openid.assoc_handle:1455391163147-0
openid.mode:checkid_setup
openid.trust_root:http://localhost:8989/loginCallback
openid.ns.ext1:http://openid.net/srv/ax/1.0
openid.ext1.type.email:http://schema.openid.net/contact/email
openid.ext1.required:email
[INFO ] 20:19:23,450 [ServerManager.java : 592] ProviderServlet call-6 : Parsing authentication request...
[DEBUG] 20:19:23,451 [ParameterList.java : 33] ProviderServlet call-6 : Created empty parameter list.
[DEBUG] 20:19:23,451 [RealmVerifier.java : 210] ProviderServlet call-6 : Verifying realm: http://localhost:8989/loginCallback on return URL: http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=RCA3zhQuwObN%2Btb9KPo%2BoWPaV%2FRCFs26dc0rm9LGY7o%3D
[INFO ] 20:19:23,451 [RealmVerifier.java : 278] ProviderServlet call-6 : Return URL: http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=RCA3zhQuwObN%2Btb9KPo%2BoWPaV%2FRCFs26dc0rm9LGY7o%3D matches realm: http://localhost:8989/loginCallback
[DEBUG] 20:19:23,451 [AuthRequest.java : 118] ProviderServlet call-6 : Created auth request:
openid.ext1.mode:fetch_request
openid.return_to:http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=RCA3zhQuwObN%2Btb9KPo%2BoWPaV%2FRCFs26dc0rm9LGY7o%3D
openid.sreg.required:email
openid.ns.sreg:http://openid.net/sreg/1.0
openid.identity:http://localhost:8989/user
openid.assoc_handle:1455391163147-0
openid.mode:checkid_setup
openid.trust_root:http://localhost:8989/loginCallback
openid.ns.ext1:http://openid.net/srv/ax/1.0
openid.ext1.type.email:http://schema.openid.net/contact/email
openid.ext1.required:email
[INFO ] 20:19:23,451 [ServerManager.java : 665] ProviderServlet call-6 : Processing authentication request...
[DEBUG] 20:19:23,451 [ServerManager.java : 713] ProviderServlet call-6 : Using ClaimedID: userSelectedClaimedId OP-specific ID: http://localhost:8989/user
[INFO ] 20:19:23,452 [ServerManager.java : 731] ProviderServlet call-6 : Loaded shared association; handle: 1455391163147-0
[DEBUG] 20:19:23,452 [ParameterList.java : 33] ProviderServlet call-6 : Created empty parameter list.
[DEBUG] 20:19:23,453 [AuthSuccess.java : 286] ProviderServlet call-6 : Setting fields to be signed: return_to,identity
[DEBUG] 20:19:23,453 [AuthSuccess.java : 364] ProviderServlet call-6 : Added signature:
[DEBUG] 20:19:23,453 [AuthSuccess.java : 106] ProviderServlet call-6 : Created positive auth response:
openid.mode:id_res
openid.identity:http://localhost:8989/user
openid.return_to:http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=RCA3zhQuwObN%2Btb9KPo%2BoWPaV%2FRCFs26dc0rm9LGY7o%3D
openid.assoc_handle:1455391163147-0
openid.signed:return_to,identity
openid.sig:
[INFO ] 20:19:23,453 [ServerManager.java : 759] ProviderServlet call-6 : Returning positive assertion for http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=RCA3zhQuwObN%2Btb9KPo%2BoWPaV%2FRCFs26dc0rm9LGY7o%3D
[DEBUG] 20:19:23,453 [ParameterList.java : 33] ProviderServlet call-6 : Created empty parameter list.
[DEBUG] 20:19:23,453 [AxMessage.java : 50] ProviderServlet call-6 : Created empty AXMessage.
[DEBUG] 20:19:23,453 [ParameterList.java : 33] ProviderServlet call-6 : Created empty parameter list.
[DEBUG] 20:19:23,453 [ParameterList.java : 33] ProviderServlet call-6 : Created empty parameter list.
[DEBUG] 20:19:23,453 [AxMessage.java : 50] ProviderServlet call-6 : Created empty AXMessage.
[DEBUG] 20:19:23,454 [FetchRequest.java : 80] ProviderServlet call-6 : Created fetch request from parameter list:
mode:fetch_request
type.email:http://schema.openid.net/contact/email
required:email
[DEBUG] 20:19:23,454 [Message.java : 513] ProviderServlet call-6 : Extracting http://openid.net/srv/ax/1.0 extension from message...
[DEBUG] 20:19:23,455 [ParameterList.java : 33] ProviderServlet call-6 : Created empty parameter list.
[DEBUG] 20:19:23,455 [AxMessage.java : 50] ProviderServlet call-6 : Created empty AXMessage.
[DEBUG] 20:19:23,456 [FetchResponse.java : 35] ProviderServlet call-6 : Created empty fetch response.
[DEBUG] 20:19:23,456 [AxPayload.java : 67] ProviderServlet call-6 : Added new attribute to AX payload; type: http://schema.openid.net/contact/email alias: email count: 1
[DEBUG] 20:19:23,456 [Message.java : 396] ProviderServlet call-6 : Adding extension; type URI: http://openid.net/srv/ax/1.0 alias: ext1
[DEBUG] 20:19:23,456 [ParameterList.java : 33] ProviderServlet call-6 : Created empty parameter list.
[DEBUG] 20:19:23,456 [SRegMessage.java : 73] ProviderServlet call-6 : Created empty SRegMessage.
[DEBUG] 20:19:23,456 [ParameterList.java : 33] ProviderServlet call-6 : Created empty parameter list.
[DEBUG] 20:19:23,456 [ParameterList.java : 33] ProviderServlet call-6 : Created empty parameter list.
[DEBUG] 20:19:23,456 [SRegMessage.java : 73] ProviderServlet call-6 : Created empty SRegMessage.
[DEBUG] 20:19:23,457 [SRegRequest.java : 73] ProviderServlet call-6 : Created SReg request from parameter list:
required:email
[DEBUG] 20:19:23,457 [Message.java : 513] ProviderServlet call-6 : Extracting http://openid.net/sreg/1.0 extension from message...
[DEBUG] 20:19:23,457 [ParameterList.java : 33] ProviderServlet call-6 : Created empty parameter list.
[DEBUG] 20:19:23,457 [SRegMessage.java : 73] ProviderServlet call-6 : Created empty SRegMessage.
[DEBUG] 20:19:23,458 [SRegResponse.java : 36] ProviderServlet call-6 : Created empty fetch response.
[DEBUG] 20:19:23,458 [SRegResponse.java : 119] ProviderServlet call-6 : Added new attribute to SReg response: email value: user@example.com
[DEBUG] 20:19:23,458 [Message.java : 396] ProviderServlet call-6 : Adding extension; type URI: http://openid.net/sreg/1.0 alias: sreg
[DEBUG] 20:19:23,458 [AuthSuccess.java : 286] ProviderServlet call-6 : Setting fields to be signed: return_to,identity,sreg.email
[DEBUG] 20:19:23,458 [AuthSuccess.java : 286] ProviderServlet call-6 : Setting fields to be signed: return_to,identity,sreg.email
[DEBUG] 20:19:23,458 [Association.java : 261] ProviderServlet call-6 : Computing signature for input data:
return_to:http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=RCA3zhQuwObN%2Btb9KPo%2BoWPaV%2FRCFs26dc0rm9LGY7o%3D
identity:http://localhost:8989/user
sreg.email:user@example.com
[DEBUG] 20:19:23,459 [Association.java : 267] ProviderServlet call-6 : Calculated signature: QY0znV+wl1QCpKPXJMulCO/DcQE=
[DEBUG] 20:19:23,459 [AuthSuccess.java : 364] ProviderServlet call-6 : Added signature: QY0znV+wl1QCpKPXJMulCO/DcQE=
[INFO ] 20:19:23,459 [ProviderServlet.java : 30] ProviderServlet call-6 : processing not null :http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=RCA3zhQuwObN%2Btb9KPo%2BoWPaV%2FRCFs26dc0rm9LGY7o%3D&openid.mode=id_res&openid.identity=http%3A%2F%2Flocalhost%3A8989%2Fuser&openid.return_to=http%3A%2F%2Flocalhost%3A8989%2FloginCallback%3Fopenid.rpnonce%3D2016-02-13T19%253A19%253A23Z0%26openid.rpsig%3DRCA3zhQuwObN%252Btb9KPo%252BoWPaV%252FRCFs26dc0rm9LGY7o%253D&openid.assoc_handle=1455391163147-0&openid.signed=return_to%2Cidentity%2Csreg.email&openid.sig=QY0znV%2Bwl1QCpKPXJMulCO%2FDcQE%3D&openid.ns.ext1=http%3A%2F%2Fopenid.net%2Fsrv%2Fax%2F1.0&openid.ext1.mode=fetch_response&openid.ext1.type.email=http%3A%2F%2Fschema.openid.net%2Fcontact%2Femail&openid.ext1.value.email=user%40example.com&openid.ns.sreg=http%3A%2F%2Fopenid.net%2Fsreg%2F1.0&openid.sreg.email=user%40example.com
[INFO ] 20:19:23,459 [HttpServletSupport.java : 49] ProviderServlet call-6 : end onService
[INFO ] 20:19:23,464 [HtmlUnitDialog.java : 692] : Window "" changed : "" became ""
[INFO ] 20:19:23,466 [HttpServletSupport.java : 37] LoginCallbackServlet call-7 : begin onService
[INFO ] 20:19:23,467 [LoginCallbackServlet.java : 35] LoginCallbackServlet call-7 : verify response
[DEBUG] 20:19:23,467 [ParameterList.java : 84] LoginCallbackServlet call-7 : Creating parameter list:
openid.identity:http://localhost:8989/user
openid.ext1.value.email:user@example.com
openid.signed:return_to,identity,sreg.email
openid.ext1.type.email:http://schema.openid.net/contact/email
openid.rpsig:RCA3zhQuwObN+tb9KPo+oWPaV/RCFs26dc0rm9LGY7o=
openid.ext1.mode:fetch_response
openid.return_to:http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=RCA3zhQuwObN%2Btb9KPo%2BoWPaV%2FRCFs26dc0rm9LGY7o%3D
openid.ns.sreg:http://openid.net/sreg/1.0
openid.assoc_handle:1455391163147-0
openid.mode:id_res
openid.rpnonce:2016-02-13T19:19:23Z0
openid.sig:QY0znV+wl1QCpKPXJMulCO/DcQE=
openid.sreg.email:user@example.com
openid.ns.ext1:http://openid.net/srv/ax/1.0
[INFO ] 20:19:23,467 [ConsumerManager.java :1123] LoginCallbackServlet call-7 : Verifying authentication response...
[DEBUG] 20:19:23,468 [ParameterList.java : 33] LoginCallbackServlet call-7 : Created empty parameter list.
[DEBUG] 20:19:23,468 [AuthSuccess.java : 119] LoginCallbackServlet call-7 : Created positive auth response:
openid.identity:http://localhost:8989/user
openid.ext1.value.email:user@example.com
openid.signed:return_to,identity,sreg.email
openid.ext1.type.email:http://schema.openid.net/contact/email
openid.rpsig:RCA3zhQuwObN+tb9KPo+oWPaV/RCFs26dc0rm9LGY7o=
openid.ext1.mode:fetch_response
openid.return_to:http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=RCA3zhQuwObN%2Btb9KPo%2BoWPaV%2FRCFs26dc0rm9LGY7o%3D
openid.ns.sreg:http://openid.net/sreg/1.0
openid.assoc_handle:1455391163147-0
openid.mode:id_res
openid.rpnonce:2016-02-13T19:19:23Z0
openid.sig:QY0znV+wl1QCpKPXJMulCO/DcQE=
openid.sreg.email:user@example.com
openid.ns.ext1:http://openid.net/srv/ax/1.0
[INFO ] 20:19:23,468 [ConsumerManager.java :1147] LoginCallbackServlet call-7 : Received positive auth response.
[DEBUG] 20:19:23,468 [ConsumerManager.java :1195] LoginCallbackServlet call-7 : Verifying return URL; receiving: http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=RCA3zhQuwObN%2Btb9KPo%2BoWPaV%2FRCFs26dc0rm9LGY7o%3D&openid.mode=id_res&openid.identity=http%3A%2F%2Flocalhost%3A8989%2Fuser&openid.return_to=http%3A%2F%2Flocalhost%3A8989%2FloginCallback%3Fopenid.rpnonce%3D2016-02-13T19%253A19%253A23Z0%26openid.rpsig%3DRCA3zhQuwObN%252Btb9KPo%252BoWPaV%252FRCFs26dc0rm9LGY7o%253D&openid.assoc_handle=1455391163147-0&openid.signed=return_to%2Cidentity%2Csreg.email&openid.sig=QY0znV%2Bwl1QCpKPXJMulCO%2FDcQE%3D&openid.ns.ext1=http%3A%2F%2Fopenid.net%2Fsrv%2Fax%2F1.0&openid.ext1.mode=fetch_response&openid.ext1.type.email=http%3A%2F%2Fschema.openid.net%2Fcontact%2Femail&openid.ext1.value.email=user%40example.com&openid.ns.sreg=http%3A%2F%2Fopenid.net%2Fsreg%2F1.0&openid.sreg.email=user%40example.com
message: http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=RCA3zhQuwObN%2Btb9KPo%2BoWPaV%2FRCFs26dc0rm9LGY7o%3D
[DEBUG] 20:19:23,469 [ConsumerManager.java :1564] LoginCallbackServlet call-7 : Verifying discovered information for OpenID1 assertion about ClaimedID: http://localhost:8989/user
[DEBUG] 20:19:23,469 [ConsumerManager.java :1412] LoginCallbackServlet call-7 : Extracting consumer nonce...
[DEBUG] 20:19:23,469 [ConsumerManager.java :1441] LoginCallbackServlet call-7 : Extracted consumer nonce: 2016-02-13T19:19:23Z0
[DEBUG] 20:19:23,469 [ConsumerManager.java :1447] LoginCallbackServlet call-7 : Extracted consumer nonce signature: RCA3zhQuwObN+tb9KPo+oWPaV/RCFs26dc0rm9LGY7o=
[DEBUG] 20:19:23,469 [ConsumerManager.java :1466] LoginCallbackServlet call-7 : Consumer signed text:
http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0
[DEBUG] 20:19:23,469 [ConsumerManager.java :1470] LoginCallbackServlet call-7 : Loading private association for opUrl http://localhost:8989/provider
[DEBUG] 20:19:23,469 [Association.java : 278] LoginCallbackServlet call-7 : Verifying signature: RCA3zhQuwObN+tb9KPo+oWPaV/RCFs26dc0rm9LGY7o=
[DEBUG] 20:19:23,469 [Association.java : 261] LoginCallbackServlet call-7 : Computing signature for input data:
http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0
[DEBUG] 20:19:23,470 [Association.java : 267] LoginCallbackServlet call-7 : Calculated signature: RCA3zhQuwObN+tb9KPo+oWPaV/RCFs26dc0rm9LGY7o=
[INFO ] 20:19:23,470 [ConsumerManager.java :1480] LoginCallbackServlet call-7 : Consumer nonce signature verified.
[DEBUG] 20:19:23,470 [AbstractNonceVerifier.java : 50] LoginCallbackServlet call-7 : Verifying nonce: 2016-02-13T19:19:23Z0
[DEBUG] 20:19:23,470 [InternetDateFormat.java : 43] LoginCallbackServlet call-7 : Parsed 2016-02-13T19:19:23Z0 into Data object: Sat Feb 13 20:19:23 CET 2016
[DEBUG] 20:19:23,470 [InMemoryNonceVerifier.java : 49] LoginCallbackServlet call-7 : Nonce verified: 2016-02-13T19:19:23Z0
[INFO ] 20:19:23,470 [ConsumerManager.java :1782] LoginCallbackServlet call-7 : Found association: 1455391163147-0 verifying signature locally...
[DEBUG] 20:19:23,471 [Association.java : 278] LoginCallbackServlet call-7 : Verifying signature: QY0znV+wl1QCpKPXJMulCO/DcQE=
[DEBUG] 20:19:23,471 [Association.java : 261] LoginCallbackServlet call-7 : Computing signature for input data:
return_to:http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=RCA3zhQuwObN%2Btb9KPo%2BoWPaV%2FRCFs26dc0rm9LGY7o%3D
identity:http://localhost:8989/user
sreg.email:user@example.com
[DEBUG] 20:19:23,471 [Association.java : 267] LoginCallbackServlet call-7 : Calculated signature: QY0znV+wl1QCpKPXJMulCO/DcQE=
[DEBUG] 20:19:23,471 [ConsumerManager.java :1790] LoginCallbackServlet call-7 : Local signature verification succeeded.
[INFO ] 20:19:23,471 [ConsumerManager.java :1850] LoginCallbackServlet call-7 : Verification succeeded for: http://localhost:8989/user
[DEBUG] 20:19:23,471 [ParameterList.java : 33] LoginCallbackServlet call-7 : Created empty parameter list.
[DEBUG] 20:19:23,471 [AxMessage.java : 50] LoginCallbackServlet call-7 : Created empty AXMessage.
[DEBUG] 20:19:23,472 [ParameterList.java : 33] LoginCallbackServlet call-7 : Created empty parameter list.
[DEBUG] 20:19:23,472 [ParameterList.java : 33] LoginCallbackServlet call-7 : Created empty parameter list.
[DEBUG] 20:19:23,472 [AxMessage.java : 50] LoginCallbackServlet call-7 : Created empty AXMessage.
[DEBUG] 20:19:23,472 [FetchResponse.java : 67] LoginCallbackServlet call-7 : Created fetch response from parameter list:
value.email:user@example.com
type.email:http://schema.openid.net/contact/email
mode:fetch_response
[DEBUG] 20:19:23,472 [Message.java : 513] LoginCallbackServlet call-7 : Extracting http://openid.net/srv/ax/1.0 extension from message...
[DEBUG] 20:19:23,472 [ParameterList.java : 33] LoginCallbackServlet call-7 : Created empty parameter list.
[DEBUG] 20:19:23,472 [SRegMessage.java : 73] LoginCallbackServlet call-7 : Created empty SRegMessage.
[DEBUG] 20:19:23,473 [ParameterList.java : 33] LoginCallbackServlet call-7 : Created empty parameter list.
[DEBUG] 20:19:23,473 [ParameterList.java : 33] LoginCallbackServlet call-7 : Created empty parameter list.
[DEBUG] 20:19:23,473 [SRegMessage.java : 73] LoginCallbackServlet call-7 : Created empty SRegMessage.
[DEBUG] 20:19:23,473 [SRegResponse.java : 68] LoginCallbackServlet call-7 : Created SReg response from parameter list:
email:user@example.com
[DEBUG] 20:19:23,473 [Message.java : 513] LoginCallbackServlet call-7 : Extracting http://openid.net/sreg/1.0 extension from message...
[INFO ] 20:19:23,473 [LoginCallbackServlet.java : 40] LoginCallbackServlet call-7 : success
[INFO ] 20:19:23,474 [HttpServletSupport.java : 49] LoginCallbackServlet call-7 : end onService
[INFO ] 20:19:23,477 [HtmlUnitDialog.java : 692] : Window "" changed : "" became "no_html"
[INFO ] 20:19:23,481 [JCLLoggerAdapter.java : 216] : org.openid4java.samples.ProviderServlet: destroy
[INFO ] 20:19:23,482 [JCLLoggerAdapter.java : 216] : org.openid4java.samples.UserInfoServlet: destroy
[INFO ] 20:19:23,482 [JCLLoggerAdapter.java : 216] : org.openid4java.samples.LoginCallbackServlet: destroy
[INFO ] 20:19:23,482 [JCLLoggerAdapter.java : 216] : org.openid4java.samples.LoginServlet: destroy
[INFO ] 20:19:23,483 [JCLLoggerAdapter.java : 216] : jetty-6.0.2
[INFO ] 20:19:23,484 [JCLLoggerAdapter.java : 216] : org.openid4java.samples.LoginCallbackServlet: init
[INFO ] 20:19:23,484 [JCLLoggerAdapter.java : 216] : org.openid4java.samples.LoginServlet: init
[INFO ] 20:19:23,484 [JCLLoggerAdapter.java : 216] : org.openid4java.samples.ProviderServlet: init
[INFO ] 20:19:23,484 [JCLLoggerAdapter.java : 216] : org.openid4java.samples.UserInfoServlet: init
[INFO ] 20:19:23,484 [JCLLoggerAdapter.java : 216] : Started SocketConnector @ 0.0.0.0:8989
[INFO ] 20:19:23,485 [HttpServletSupport.java : 37] LoginServlet call-1 : begin onService
[INFO ] 20:19:23,486 [LoginServlet.java : 33] LoginServlet call-1 : display form
[INFO ] 20:19:23,486 [HttpServletSupport.java : 49] LoginServlet call-1 : end onService
[INFO ] 20:19:23,492 [HtmlUnitDialog.java : 692] : Window "" changed : "no_html" became ""
[INFO ] 20:19:23,494 [HttpServletSupport.java : 37] LoginServlet call-2 : begin onService
[INFO ] 20:19:23,494 [LoginServlet.java : 28] LoginServlet call-2 : openind_identifier set => try to consume
[DEBUG] 20:19:23,494 [Discovery.java : 89] LoginServlet call-2 : Creating URL identifier for: http://localhost:8989/user?format=html
[DEBUG] 20:19:23,495 [UrlIdentifier.java : 125] LoginServlet call-2 : Normalized: http://localhost:8989/user?format=html to: http://localhost:8989/user?format=html
[INFO ] 20:19:23,495 [Discovery.java : 128] LoginServlet call-2 : Starting discovery on URL identifier: http://localhost:8989/user?format=html
[DEBUG] 20:19:23,495 [YadisResolver.java : 351] LoginServlet call-2 : Performing HTTP HEAD on: http://localhost:8989/user?format=html ...
[INFO ] 20:19:23,503 [HttpServletSupport.java : 37] UserInfoServlet call-3 : begin onService
[INFO ] 20:19:23,503 [HttpServletSupport.java : 49] UserInfoServlet call-3 : end onService
[DEBUG] 20:19:23,503 [YadisResolver.java : 351] LoginServlet call-2 : Performing HTTP GET on: http://localhost:8989/user?format=html ...
[INFO ] 20:19:23,505 [HttpServletSupport.java : 37] UserInfoServlet call-4 : begin onService
[INFO ] 20:19:23,505 [HttpServletSupport.java : 49] UserInfoServlet call-4 : end onService
[DEBUG] 20:19:23,505 [HttpCache.java : 202] LoginServlet call-2 : Read 113 bytes.
[DEBUG] 20:19:23,508 [CyberNekoDOMYadisHtmlParser.java : 47] LoginServlet call-2 : document:
<HTML>
<HEAD>
<META http-equiv="Content-Type" content="text/html; charset=UTF-8">
<LINK href="http://localhost:8989/provider" rel="openid.server">
</HEAD>
<BODY>in html</BODY>
</HTML>
[DEBUG] 20:19:23,508 [CyberNekoDOMYadisHtmlParser.java : 67] LoginServlet call-2 : No <meta> element found under <html><head>. See Yadis specification, section 6.2.5/1.
[DEBUG] 20:19:23,508 [YadisResolver.java : 309] LoginServlet call-2 : input:
<html><head>
<link rel='openid.server' href='http://localhost:8989/provider'/>
</head><body>in html</body></html>
[DEBUG] 20:19:23,508 [YadisResolver.java : 310] LoginServlet call-2 : xrdsLocation: null
[INFO ] 20:19:23,508 [YadisResolver.java : 245] LoginServlet call-2 : Yadis discovered 0 endpoints from: http://localhost:8989/user?format=html
[INFO ] 20:19:23,508 [Discovery.java : 139] LoginServlet call-2 : No OpenID service endpoints discovered through Yadis; attempting HTML discovery...
[INFO ] 20:19:23,508 [HttpCache.java : 140] LoginServlet call-2 : Returning cached GET response for http://localhost:8989/user?format=html
[DEBUG] 20:19:23,509 [UrlIdentifier.java : 125] LoginServlet call-2 : Normalized: http://localhost:8989/user?format=html to: http://localhost:8989/user?format=html
[DEBUG] 20:19:23,509 [CyberNekoDOMHtmlParser.java : 44] LoginServlet call-2 : Parsing HTML data:
<html><head>
<link rel='openid.server' href='http://localhost:8989/provider'/>
</head><body>in html</body></html>
[DEBUG] 20:19:23,509 [CyberNekoDOMHtmlParser.java : 120] LoginServlet call-2 : Found OpenID1 endpoint: http://localhost:8989/provider
[DEBUG] 20:19:23,509 [CyberNekoDOMHtmlParser.java : 65] LoginServlet call-2 : HTML discovery result:
ClaimedID:http://localhost:8989/user?format=html
OpenID1-endpoint:http://localhost:8989/provider
[INFO ] 20:19:23,509 [HtmlResolver.java : 127] LoginServlet call-2 : HTML discovery completed on: http://localhost:8989/user?format=html
[DEBUG] 20:19:23,510 [HtmlResolver.java : 167] LoginServlet call-2 : OpenID1-signon HTML discovery endpoint: OpenID1
OP-endpoint:http://localhost:8989/provider
ClaimedID:http://localhost:8989/user?format=html
Delegate:null
[INFO ] 20:19:23,510 [Discovery.java : 151] LoginServlet call-2 : Discovered 1 OpenID endpoints.
[INFO ] 20:19:23,510 [ConsumerManager.java : 707] LoginServlet call-2 : Trying to associate with http://localhost:8989/provider attempts left: 4
[WARN ] 20:19:23,510 [ConsumerManager.java : 894] LoginServlet call-2 : Could not create association of type: :HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,514 [DiffieHellmanSession.java : 96] LoginServlet call-2 : Created DH session: DH-SHA1:HMAC-SHA1:OpenID1 base: 2 modulus: 155172898181473697471232257763715539915724801966915404479707795314057629378541917580651227423698188993727816152646631438561595825688188889951272158842675419950341258706556549803580104870537681476726513255747040765857479291291572334510643245094715007229621094194349783925984760375594985848253359305585439638443
[DEBUG] 20:19:23,514 [ParameterList.java : 33] LoginServlet call-2 : Created empty parameter list.
[DEBUG] 20:19:23,514 [AssociationRequest.java : 80] LoginServlet call-2 : Creating association request, type: DH-SHA1:HMAC-SHA1:OpenID1DH session: DH-SHA1:HMAC-SHA1:OpenID1 base: 2 modulus: 155172898181473697471232257763715539915724801966915404479707795314057629378541917580651227423698188993727816152646631438561595825688188889951272158842675419950341258706556549803580104870537681476726513255747040765857479291291572334510643245094715007229621094194349783925984760375594985848253359305585439638443
[DEBUG] 20:19:23,521 [AssociationSessionType.java : 187] LoginServlet call-2 : Session:Association Type: DH-SHA1:HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,521 [AssociationRequest.java : 140] LoginServlet call-2 : Created association request:
openid.mode:associate
openid.session_type:DH-SHA1
openid.assoc_type:HMAC-SHA1
openid.dh_consumer_public:AKH05tEUMzuaBDBbRhZC8FfQeIt8oWDjxs1kK7NRRt72tKmTNBgoMyom6ksYgfcj3YyVkPJwZLhDtHX+9WSxFkao7qIihq/0Lt6zAJyPC9bC3IzSPA85BF6yxFDWC0CB1vSf+5gFO7QK6NrXVIIDb8wT8m1f5H27ce1EGo2ptgcX
[DEBUG] 20:19:23,521 [AssociationSessionType.java : 187] LoginServlet call-2 : Session:Association Type: DH-SHA1:HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,521 [ConsumerManager.java : 767] LoginServlet call-2 : Trying association type: DH-SHA1:HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,521 [AssociationSessionType.java : 187] LoginServlet call-2 : Session:Association Type: DH-SHA1:HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,521 [AssociationSessionType.java : 187] LoginServlet call-2 : Session:Association Type: DH-SHA1:HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,522 [ParameterList.java : 33] LoginServlet call-2 : Created empty parameter list.
[DEBUG] 20:19:23,522 [ConsumerManager.java : 616] LoginServlet call-2 : Performing HTTP POST on http://localhost:8989/provider
[INFO ] 20:19:23,523 [HttpServletSupport.java : 37] ProviderServlet call-5 : begin onService
[INFO ] 20:19:23,523 [ProviderServlet.java : 26] ProviderServlet call-5 : start processing...
[DEBUG] 20:19:23,523 [ParameterList.java : 84] ProviderServlet call-5 : Creating parameter list:
openid.assoc_type:HMAC-SHA1
openid.mode:associate
openid.session_type:DH-SHA1
openid.dh_consumer_public:AKH05tEUMzuaBDBbRhZC8FfQeIt8oWDjxs1kK7NRRt72tKmTNBgoMyom6ksYgfcj3YyVkPJwZLhDtHX+9WSxFkao7qIihq/0Lt6zAJyPC9bC3IzSPA85BF6yxFDWC0CB1vSf+5gFO7QK6NrXVIIDb8wT8m1f5H27ce1EGo2ptgcX
[INFO ] 20:19:23,523 [ServerManager.java : 382] ProviderServlet call-5 : Processing association request...
[DEBUG] 20:19:23,523 [ParameterList.java : 33] ProviderServlet call-5 : Created empty parameter list.
[DEBUG] 20:19:23,524 [AssociationSessionType.java : 187] ProviderServlet call-5 : Session:Association Type: DH-SHA1:HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,524 [AssociationRequest.java : 153] ProviderServlet call-5 : Created association request from message parameters:
openid.assoc_type:HMAC-SHA1
openid.mode:associate
openid.session_type:DH-SHA1
openid.dh_consumer_public:AKH05tEUMzuaBDBbRhZC8FfQeIt8oWDjxs1kK7NRRt72tKmTNBgoMyom6ksYgfcj3YyVkPJwZLhDtHX+9WSxFkao7qIihq/0Lt6zAJyPC9bC3IzSPA85BF6yxFDWC0CB1vSf+5gFO7QK6NrXVIIDb8wT8m1f5H27ce1EGo2ptgcX
[DEBUG] 20:19:23,524 [AssociationSessionType.java : 187] ProviderServlet call-5 : Session:Association Type: DH-SHA1:HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,524 [Association.java : 88] ProviderServlet call-5 : Generated SHA1 MAC key: javax.crypto.spec.SecretKeySpec@64b07f05
[DEBUG] 20:19:23,524 [Association.java : 45] ProviderServlet call-5 : Creating association, type: HMAC-SHA1 handle: 1455391163151-0 expires: Sat Feb 13 20:49:23 CET 2016
[DEBUG] 20:19:23,524 [InMemoryServerAssociationStore.java : 45] ProviderServlet call-5 : Generated association, handle: 1455391163151-0 type: HMAC-SHA1 expires in: 1800 seconds.
[INFO ] 20:19:23,524 [ServerManager.java : 408] ProviderServlet call-5 : Returning shared association; handle: 1455391163151-0
[DEBUG] 20:19:23,525 [ParameterList.java : 33] ProviderServlet call-5 : Created empty parameter list.
[DEBUG] 20:19:23,525 [AssociationSessionType.java : 187] ProviderServlet call-5 : Session:Association Type: DH-SHA1:HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,525 [AssociationResponse.java : 57] ProviderServlet call-5 : Creating association response, type: DH-SHA1:HMAC-SHA1:OpenID1 association handle: 1455391163151-0
[DEBUG] 20:19:23,525 [AssociationSessionType.java : 187] ProviderServlet call-5 : Session:Association Type: DH-SHA1:HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,528 [DiffieHellmanSession.java : 96] ProviderServlet call-5 : Created DH session: DH-SHA1:HMAC-SHA1:OpenID1 base: 2 modulus: 155172898181473697471232257763715539915724801966915404479707795314057629378541917580651227423698188993727816152646631438561595825688188889951272158842675419950341258706556549803580104870537681476726513255747040765857479291291572334510643245094715007229621094194349783925984760375594985848253359305585439638443
[DEBUG] 20:19:23,531 [DiffieHellmanSession.java : 253] ProviderServlet call-5 : Encrypted MAC key Base64: TfdwzKaag7TViKzzGfFJ2jx+Ul8=
[DEBUG] 20:19:23,532 [AssociationSessionType.java : 187] ProviderServlet call-5 : Session:Association Type: DH-SHA1:HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,532 [AssociationResponse.java : 107] ProviderServlet call-5 : Created association response:
session_type:DH-SHA1
assoc_type:HMAC-SHA1
assoc_handle:1455391163151-0
expires_in:1799
dh_server_public:AL8cJdFv3w6bUgEQvmB6H/jMi+a4A8Jq/qkUV42YRza0RXzPgfh4m5hNwNdNjWRPx6gjp24OFFY8ASg+dXJvZo3d9WXSC9A9fkbwDSqJ/F/EnZY2hU1Z5qgJ4twBB6zvfve3b9dNzu/hbVC5KJLkkd3KsXXGbNXQpQO8iEaTUCLz
enc_mac_key:TfdwzKaag7TViKzzGfFJ2jx+Ul8=
[INFO ] 20:19:23,532 [ProviderServlet.java : 30] ProviderServlet call-5 : processing not null :session_type:DH-SHA1
assoc_type:HMAC-SHA1
assoc_handle:1455391163151-0
expires_in:1799
dh_server_public:AL8cJdFv3w6bUgEQvmB6H/jMi+a4A8Jq/qkUV42YRza0RXzPgfh4m5hNwNdNjWRPx6gjp24OFFY8ASg+dXJvZo3d9WXSC9A9fkbwDSqJ/F/EnZY2hU1Z5qgJ4twBB6zvfve3b9dNzu/hbVC5KJLkkd3KsXXGbNXQpQO8iEaTUCLz
enc_mac_key:TfdwzKaag7TViKzzGfFJ2jx+Ul8=
[INFO ] 20:19:23,532 [HttpServletSupport.java : 49] ProviderServlet call-5 : end onService
[DEBUG] 20:19:23,532 [ParameterList.java : 188] LoginServlet call-2 : Creating parameter list from key-value form:
session_type:DH-SHA1
assoc_type:HMAC-SHA1
assoc_handle:1455391163151-0
expires_in:1799
dh_server_public:AL8cJdFv3w6bUgEQvmB6H/jMi+a4A8Jq/qkUV42YRza0RXzPgfh4m5hNwNdNjWRPx6gjp24OFFY8ASg+dXJvZo3d9WXSC9A9fkbwDSqJ/F/EnZY2hU1Z5qgJ4twBB6zvfve3b9dNzu/hbVC5KJLkkd3KsXXGbNXQpQO8iEaTUCLz
enc_mac_key:TfdwzKaag7TViKzzGfFJ2jx+Ul8=
[DEBUG] 20:19:23,533 [ParameterList.java : 33] LoginServlet call-2 : Created empty parameter list.
[DEBUG] 20:19:23,533 [ParameterList.java : 89] LoginServlet call-2 : Copying parameter list:
session_type:DH-SHA1
assoc_type:HMAC-SHA1
assoc_handle:1455391163151-0
expires_in:1799
dh_server_public:AL8cJdFv3w6bUgEQvmB6H/jMi+a4A8Jq/qkUV42YRza0RXzPgfh4m5hNwNdNjWRPx6gjp24OFFY8ASg+dXJvZo3d9WXSC9A9fkbwDSqJ/F/EnZY2hU1Z5qgJ4twBB6zvfve3b9dNzu/hbVC5KJLkkd3KsXXGbNXQpQO8iEaTUCLz
enc_mac_key:TfdwzKaag7TViKzzGfFJ2jx+Ul8=
[DEBUG] 20:19:23,533 [ConsumerManager.java : 622] LoginServlet call-2 : Retrived response:
session_type:DH-SHA1
assoc_type:HMAC-SHA1
assoc_handle:1455391163151-0
expires_in:1799
dh_server_public:AL8cJdFv3w6bUgEQvmB6H/jMi+a4A8Jq/qkUV42YRza0RXzPgfh4m5hNwNdNjWRPx6gjp24OFFY8ASg+dXJvZo3d9WXSC9A9fkbwDSqJ/F/EnZY2hU1Z5qgJ4twBB6zvfve3b9dNzu/hbVC5KJLkkd3KsXXGbNXQpQO8iEaTUCLz
enc_mac_key:TfdwzKaag7TViKzzGfFJ2jx+Ul8=
[DEBUG] 20:19:23,533 [ParameterList.java : 33] LoginServlet call-2 : Created empty parameter list.
[DEBUG] 20:19:23,533 [AssociationSessionType.java : 187] LoginServlet call-2 : Session:Association Type: DH-SHA1:HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,533 [AssociationResponse.java : 121] LoginServlet call-2 : Created association response from message parameters:
session_type:DH-SHA1
assoc_type:HMAC-SHA1
assoc_handle:1455391163151-0
expires_in:1799
dh_server_public:AL8cJdFv3w6bUgEQvmB6H/jMi+a4A8Jq/qkUV42YRza0RXzPgfh4m5hNwNdNjWRPx6gjp24OFFY8ASg+dXJvZo3d9WXSC9A9fkbwDSqJ/F/EnZY2hU1Z5qgJ4twBB6zvfve3b9dNzu/hbVC5KJLkkd3KsXXGbNXQpQO8iEaTUCLz
enc_mac_key:TfdwzKaag7TViKzzGfFJ2jx+Ul8=
[DEBUG] 20:19:23,533 [AssociationResponse.java : 315] LoginServlet call-2 : Retrieving MAC key from association response...
[DEBUG] 20:19:23,534 [AssociationSessionType.java : 187] LoginServlet call-2 : Session:Association Type: DH-SHA1:HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,537 [DiffieHellmanSession.java : 298] LoginServlet call-2 : Decrypted MAC key Base64: uu9s6SuLTn5K6nEch+vSn3LqPgA=
[DEBUG] 20:19:23,537 [AssociationResponse.java : 331] LoginServlet call-2 : Decrypted MAC key (base64): uu9s6SuLTn5K6nEch+vSn3LqPgA=
[DEBUG] 20:19:23,537 [Association.java : 45] LoginServlet call-2 : Creating association, type: HMAC-SHA1 handle: 1455391163151-0 expires: Sat Feb 13 20:49:22 CET 2016
[DEBUG] 20:19:23,537 [AssociationResponse.java : 354] LoginServlet call-2 : Created association for handle: 1455391163151-0
[DEBUG] 20:19:23,537 [AssociationSessionType.java : 187] LoginServlet call-2 : Session:Association Type: DH-SHA1:HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,537 [AssociationSessionType.java : 187] LoginServlet call-2 : Session:Association Type: DH-SHA1:HMAC-SHA1:OpenID1
[DEBUG] 20:19:23,537 [InMemoryConsumerAssociationStore.java : 41] LoginServlet call-2 : Adding association to the in-memory store: 1455391163151-0 with OP: http://localhost:8989/provider
[INFO ] 20:19:23,538 [ConsumerManager.java : 804] LoginServlet call-2 : Associated with http://localhost:8989/provider handle: 1455391163151-0
[INFO ] 20:19:23,538 [ConsumerManager.java :1065] LoginServlet call-2 : Creating authentication request for OP-endpoint: http://localhost:8989/provider claimedID: http://localhost:8989/user?format=html OP-specific ID: http://localhost:8989/user?format=html
[DEBUG] 20:19:23,538 [IncrementalNonceGenerator.java : 43] LoginServlet call-2 : Generated nonce: 2016-02-13T19:19:23Z0
[DEBUG] 20:19:23,538 [ConsumerManager.java :1367] LoginServlet call-2 : Creating private association for opUrl http://localhost:8989/provider
[DEBUG] 20:19:23,538 [Association.java : 111] LoginServlet call-2 : Generated SHA256 MAC key: javax.crypto.spec.SecretKeySpec@fa77d0f5
[DEBUG] 20:19:23,539 [Association.java : 45] LoginServlet call-2 : Creating association, type: HMAC-SHA256 handle: expires: Sat Feb 13 20:24:23 CET 2016
[DEBUG] 20:19:23,539 [InMemoryConsumerAssociationStore.java : 41] LoginServlet call-2 : Adding association to the in-memory store: with OP: http://localhost:8989/provider
[DEBUG] 20:19:23,539 [Association.java : 261] LoginServlet call-2 : Computing signature for input data:
http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0
[DEBUG] 20:19:23,539 [Association.java : 267] LoginServlet call-2 : Calculated signature: Oh8WvgZJKvHFMXBGIFCn0jWobpziSRnlkcpt+/Xl2Dg=
[INFO ] 20:19:23,539 [ConsumerManager.java :1387] LoginServlet call-2 : Inserted consumer nonce: 2016-02-13T19:19:23Z0
[DEBUG] 20:19:23,539 [ConsumerManager.java :1389] LoginServlet call-2 : return_to:http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=Oh8WvgZJKvHFMXBGIFCn0jWobpziSRnlkcpt%2B%2FXl2Dg%3D
[DEBUG] 20:19:23,539 [ParameterList.java : 33] LoginServlet call-2 : Created empty parameter list.
[DEBUG] 20:19:23,539 [RealmVerifier.java : 210] LoginServlet call-2 : Verifying realm: http://localhost:8989/loginCallback on return URL: http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=Oh8WvgZJKvHFMXBGIFCn0jWobpziSRnlkcpt%2B%2FXl2Dg%3D
[INFO ] 20:19:23,540 [RealmVerifier.java : 278] LoginServlet call-2 : Return URL: http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=Oh8WvgZJKvHFMXBGIFCn0jWobpziSRnlkcpt%2B%2FXl2Dg%3D matches realm: http://localhost:8989/loginCallback
[DEBUG] 20:19:23,540 [AuthRequest.java : 103] LoginServlet call-2 : Created auth request:
openid.identity:http://localhost:8989/user?format=html
openid.return_to:http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=Oh8WvgZJKvHFMXBGIFCn0jWobpziSRnlkcpt%2B%2FXl2Dg%3D
openid.trust_root:http://localhost:8989/loginCallback
openid.assoc_handle:1455391163151-0
openid.mode:checkid_setup
[DEBUG] 20:19:23,540 [ParameterList.java : 33] LoginServlet call-2 : Created empty parameter list.
[DEBUG] 20:19:23,540 [AxMessage.java : 50] LoginServlet call-2 : Created empty AXMessage.
[DEBUG] 20:19:23,540 [FetchRequest.java : 41] LoginServlet call-2 : Created empty fetch request.
[DEBUG] 20:19:23,540 [FetchRequest.java : 127] LoginServlet call-2 : Added new attribute to fetch request; type: http://schema.openid.net/contact/email alias: email count: 1 required: true
[DEBUG] 20:19:23,540 [Message.java : 396] LoginServlet call-2 : Adding extension; type URI: http://openid.net/srv/ax/1.0 alias: ext1
[DEBUG] 20:19:23,541 [ParameterList.java : 33] LoginServlet call-2 : Created empty parameter list.
[DEBUG] 20:19:23,541 [SRegMessage.java : 73] LoginServlet call-2 : Created empty SRegMessage.
[DEBUG] 20:19:23,541 [SRegRequest.java : 34] LoginServlet call-2 : Created empty SReg request.
[DEBUG] 20:19:23,541 [SRegRequest.java : 105] LoginServlet call-2 : Added new attribute to SReg request: email required: true
[DEBUG] 20:19:23,541 [Message.java : 396] LoginServlet call-2 : Adding extension; type URI: http://openid.net/sreg/1.0 alias: sreg
[INFO ] 20:19:23,542 [HttpMethodDirector.java : 817] : Redirect requested but followRedirects is disabled
[INFO ] 20:19:23,542 [HttpServletSupport.java : 49] LoginServlet call-2 : end onService
[INFO ] 20:19:23,544 [HttpServletSupport.java : 37] ProviderServlet call-6 : begin onService
[INFO ] 20:19:23,544 [ProviderServlet.java : 26] ProviderServlet call-6 : start processing...
[DEBUG] 20:19:23,544 [ParameterList.java : 84] ProviderServlet call-6 : Creating parameter list:
openid.ext1.mode:fetch_request
openid.return_to:http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=Oh8WvgZJKvHFMXBGIFCn0jWobpziSRnlkcpt%2B%2FXl2Dg%3D
openid.sreg.required:email
openid.ns.sreg:http://openid.net/sreg/1.0
openid.identity:http://localhost:8989/user?format=html
openid.assoc_handle:1455391163151-0
openid.mode:checkid_setup
openid.trust_root:http://localhost:8989/loginCallback
openid.ns.ext1:http://openid.net/srv/ax/1.0
openid.ext1.type.email:http://schema.openid.net/contact/email
openid.ext1.required:email
[DEBUG] 20:19:23,544 [ParameterList.java : 33] ProviderServlet call-6 : Created empty parameter list.
[DEBUG] 20:19:23,544 [RealmVerifier.java : 210] ProviderServlet call-6 : Verifying realm: http://localhost:8989/loginCallback on return URL: http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=Oh8WvgZJKvHFMXBGIFCn0jWobpziSRnlkcpt%2B%2FXl2Dg%3D
[INFO ] 20:19:23,545 [RealmVerifier.java : 278] ProviderServlet call-6 : Return URL: http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=Oh8WvgZJKvHFMXBGIFCn0jWobpziSRnlkcpt%2B%2FXl2Dg%3D matches realm: http://localhost:8989/loginCallback
[DEBUG] 20:19:23,545 [AuthRequest.java : 118] ProviderServlet call-6 : Created auth request:
openid.ext1.mode:fetch_request
openid.return_to:http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=Oh8WvgZJKvHFMXBGIFCn0jWobpziSRnlkcpt%2B%2FXl2Dg%3D
openid.sreg.required:email
openid.ns.sreg:http://openid.net/sreg/1.0
openid.identity:http://localhost:8989/user?format=html
openid.assoc_handle:1455391163151-0
openid.mode:checkid_setup
openid.trust_root:http://localhost:8989/loginCallback
openid.ns.ext1:http://openid.net/srv/ax/1.0
openid.ext1.type.email:http://schema.openid.net/contact/email
openid.ext1.required:email
[INFO ] 20:19:23,545 [ServerManager.java : 592] ProviderServlet call-6 : Parsing authentication request...
[DEBUG] 20:19:23,545 [ParameterList.java : 33] ProviderServlet call-6 : Created empty parameter list.
[DEBUG] 20:19:23,545 [RealmVerifier.java : 210] ProviderServlet call-6 : Verifying realm: http://localhost:8989/loginCallback on return URL: http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=Oh8WvgZJKvHFMXBGIFCn0jWobpziSRnlkcpt%2B%2FXl2Dg%3D
[INFO ] 20:19:23,545 [RealmVerifier.java : 278] ProviderServlet call-6 : Return URL: http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=Oh8WvgZJKvHFMXBGIFCn0jWobpziSRnlkcpt%2B%2FXl2Dg%3D matches realm: http://localhost:8989/loginCallback
[DEBUG] 20:19:23,545 [AuthRequest.java : 118] ProviderServlet call-6 : Created auth request:
openid.ext1.mode:fetch_request
openid.return_to:http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=Oh8WvgZJKvHFMXBGIFCn0jWobpziSRnlkcpt%2B%2FXl2Dg%3D
openid.sreg.required:email
openid.ns.sreg:http://openid.net/sreg/1.0
openid.identity:http://localhost:8989/user?format=html
openid.assoc_handle:1455391163151-0
openid.mode:checkid_setup
openid.trust_root:http://localhost:8989/loginCallback
openid.ns.ext1:http://openid.net/srv/ax/1.0
openid.ext1.type.email:http://schema.openid.net/contact/email
openid.ext1.required:email
[INFO ] 20:19:23,546 [ServerManager.java : 665] ProviderServlet call-6 : Processing authentication request...
[DEBUG] 20:19:23,546 [ServerManager.java : 713] ProviderServlet call-6 : Using ClaimedID: userSelectedClaimedId OP-specific ID: http://localhost:8989/user?format=html
[INFO ] 20:19:23,546 [ServerManager.java : 731] ProviderServlet call-6 : Loaded shared association; handle: 1455391163151-0
[DEBUG] 20:19:23,546 [ParameterList.java : 33] ProviderServlet call-6 : Created empty parameter list.
[DEBUG] 20:19:23,546 [AuthSuccess.java : 286] ProviderServlet call-6 : Setting fields to be signed: return_to,identity
[DEBUG] 20:19:23,546 [AuthSuccess.java : 364] ProviderServlet call-6 : Added signature:
[DEBUG] 20:19:23,546 [AuthSuccess.java : 106] ProviderServlet call-6 : Created positive auth response:
openid.mode:id_res
openid.identity:http://localhost:8989/user?format=html
openid.return_to:http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=Oh8WvgZJKvHFMXBGIFCn0jWobpziSRnlkcpt%2B%2FXl2Dg%3D
openid.assoc_handle:1455391163151-0
openid.signed:return_to,identity
openid.sig:
[INFO ] 20:19:23,547 [ServerManager.java : 759] ProviderServlet call-6 : Returning positive assertion for http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=Oh8WvgZJKvHFMXBGIFCn0jWobpziSRnlkcpt%2B%2FXl2Dg%3D
[DEBUG] 20:19:23,547 [ParameterList.java : 33] ProviderServlet call-6 : Created empty parameter list.
[DEBUG] 20:19:23,547 [AxMessage.java : 50] ProviderServlet call-6 : Created empty AXMessage.
[DEBUG] 20:19:23,547 [ParameterList.java : 33] ProviderServlet call-6 : Created empty parameter list.
[DEBUG] 20:19:23,547 [ParameterList.java : 33] ProviderServlet call-6 : Created empty parameter list.
[DEBUG] 20:19:23,547 [AxMessage.java : 50] ProviderServlet call-6 : Created empty AXMessage.
[DEBUG] 20:19:23,547 [FetchRequest.java : 80] ProviderServlet call-6 : Created fetch request from parameter list:
mode:fetch_request
type.email:http://schema.openid.net/contact/email
required:email
[DEBUG] 20:19:23,547 [Message.java : 513] ProviderServlet call-6 : Extracting http://openid.net/srv/ax/1.0 extension from message...
[DEBUG] 20:19:23,548 [ParameterList.java : 33] ProviderServlet call-6 : Created empty parameter list.
[DEBUG] 20:19:23,548 [AxMessage.java : 50] ProviderServlet call-6 : Created empty AXMessage.
[DEBUG] 20:19:23,548 [FetchResponse.java : 35] ProviderServlet call-6 : Created empty fetch response.
[DEBUG] 20:19:23,548 [AxPayload.java : 67] ProviderServlet call-6 : Added new attribute to AX payload; type: http://schema.openid.net/contact/email alias: email count: 1
[DEBUG] 20:19:23,548 [Message.java : 396] ProviderServlet call-6 : Adding extension; type URI: http://openid.net/srv/ax/1.0 alias: ext1
[DEBUG] 20:19:23,548 [ParameterList.java : 33] ProviderServlet call-6 : Created empty parameter list.
[DEBUG] 20:19:23,548 [SRegMessage.java : 73] ProviderServlet call-6 : Created empty SRegMessage.
[DEBUG] 20:19:23,548 [ParameterList.java : 33] ProviderServlet call-6 : Created empty parameter list.
[DEBUG] 20:19:23,549 [ParameterList.java : 33] ProviderServlet call-6 : Created empty parameter list.
[DEBUG] 20:19:23,549 [SRegMessage.java : 73] ProviderServlet call-6 : Created empty SRegMessage.
[DEBUG] 20:19:23,549 [SRegRequest.java : 73] ProviderServlet call-6 : Created SReg request from parameter list:
required:email
[DEBUG] 20:19:23,549 [Message.java : 513] ProviderServlet call-6 : Extracting http://openid.net/sreg/1.0 extension from message...
[DEBUG] 20:19:23,549 [ParameterList.java : 33] ProviderServlet call-6 : Created empty parameter list.
[DEBUG] 20:19:23,549 [SRegMessage.java : 73] ProviderServlet call-6 : Created empty SRegMessage.
[DEBUG] 20:19:23,549 [SRegResponse.java : 36] ProviderServlet call-6 : Created empty fetch response.
[DEBUG] 20:19:23,549 [SRegResponse.java : 119] ProviderServlet call-6 : Added new attribute to SReg response: email value: user@example.com
[DEBUG] 20:19:23,550 [Message.java : 396] ProviderServlet call-6 : Adding extension; type URI: http://openid.net/sreg/1.0 alias: sreg
[DEBUG] 20:19:23,550 [AuthSuccess.java : 286] ProviderServlet call-6 : Setting fields to be signed: return_to,identity,sreg.email
[DEBUG] 20:19:23,550 [AuthSuccess.java : 286] ProviderServlet call-6 : Setting fields to be signed: return_to,identity,sreg.email
[DEBUG] 20:19:23,550 [Association.java : 261] ProviderServlet call-6 : Computing signature for input data:
return_to:http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=Oh8WvgZJKvHFMXBGIFCn0jWobpziSRnlkcpt%2B%2FXl2Dg%3D
identity:http://localhost:8989/user?format=html
sreg.email:user@example.com
[DEBUG] 20:19:23,550 [Association.java : 267] ProviderServlet call-6 : Calculated signature: ru/QtiftlmCdfNJuNXBW1qqCvGE=
[DEBUG] 20:19:23,550 [AuthSuccess.java : 364] ProviderServlet call-6 : Added signature: ru/QtiftlmCdfNJuNXBW1qqCvGE=
[INFO ] 20:19:23,551 [ProviderServlet.java : 30] ProviderServlet call-6 : processing not null :http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=Oh8WvgZJKvHFMXBGIFCn0jWobpziSRnlkcpt%2B%2FXl2Dg%3D&openid.mode=id_res&openid.identity=http%3A%2F%2Flocalhost%3A8989%2Fuser%3Fformat%3Dhtml&openid.return_to=http%3A%2F%2Flocalhost%3A8989%2FloginCallback%3Fopenid.rpnonce%3D2016-02-13T19%253A19%253A23Z0%26openid.rpsig%3DOh8WvgZJKvHFMXBGIFCn0jWobpziSRnlkcpt%252B%252FXl2Dg%253D&openid.assoc_handle=1455391163151-0&openid.signed=return_to%2Cidentity%2Csreg.email&openid.sig=ru%2FQtiftlmCdfNJuNXBW1qqCvGE%3D&openid.ns.ext1=http%3A%2F%2Fopenid.net%2Fsrv%2Fax%2F1.0&openid.ext1.mode=fetch_response&openid.ext1.type.email=http%3A%2F%2Fschema.openid.net%2Fcontact%2Femail&openid.ext1.value.email=user%40example.com&openid.ns.sreg=http%3A%2F%2Fopenid.net%2Fsreg%2F1.0&openid.sreg.email=user%40example.com
[INFO ] 20:19:23,551 [HttpServletSupport.java : 49] ProviderServlet call-6 : end onService
[INFO ] 20:19:23,553 [HtmlUnitDialog.java : 692] : Window "" changed : "" became ""
[INFO ] 20:19:23,556 [HttpServletSupport.java : 37] LoginCallbackServlet call-7 : begin onService
[INFO ] 20:19:23,556 [LoginCallbackServlet.java : 35] LoginCallbackServlet call-7 : verify response
[DEBUG] 20:19:23,556 [ParameterList.java : 84] LoginCallbackServlet call-7 : Creating parameter list:
openid.identity:http://localhost:8989/user?format=html
openid.ext1.value.email:user@example.com
openid.signed:return_to,identity,sreg.email
openid.ext1.type.email:http://schema.openid.net/contact/email
openid.rpsig:Oh8WvgZJKvHFMXBGIFCn0jWobpziSRnlkcpt+/Xl2Dg=
openid.ext1.mode:fetch_response
openid.return_to:http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=Oh8WvgZJKvHFMXBGIFCn0jWobpziSRnlkcpt%2B%2FXl2Dg%3D
openid.ns.sreg:http://openid.net/sreg/1.0
openid.assoc_handle:1455391163151-0
openid.mode:id_res
openid.rpnonce:2016-02-13T19:19:23Z0
openid.sig:ru/QtiftlmCdfNJuNXBW1qqCvGE=
openid.sreg.email:user@example.com
openid.ns.ext1:http://openid.net/srv/ax/1.0
[INFO ] 20:19:23,556 [ConsumerManager.java :1123] LoginCallbackServlet call-7 : Verifying authentication response...
[DEBUG] 20:19:23,557 [ParameterList.java : 33] LoginCallbackServlet call-7 : Created empty parameter list.
[DEBUG] 20:19:23,557 [AuthSuccess.java : 119] LoginCallbackServlet call-7 : Created positive auth response:
openid.identity:http://localhost:8989/user?format=html
openid.ext1.value.email:user@example.com
openid.signed:return_to,identity,sreg.email
openid.ext1.type.email:http://schema.openid.net/contact/email
openid.rpsig:Oh8WvgZJKvHFMXBGIFCn0jWobpziSRnlkcpt+/Xl2Dg=
openid.ext1.mode:fetch_response
openid.return_to:http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=Oh8WvgZJKvHFMXBGIFCn0jWobpziSRnlkcpt%2B%2FXl2Dg%3D
openid.ns.sreg:http://openid.net/sreg/1.0
openid.assoc_handle:1455391163151-0
openid.mode:id_res
openid.rpnonce:2016-02-13T19:19:23Z0
openid.sig:ru/QtiftlmCdfNJuNXBW1qqCvGE=
openid.sreg.email:user@example.com
openid.ns.ext1:http://openid.net/srv/ax/1.0
[INFO ] 20:19:23,557 [ConsumerManager.java :1147] LoginCallbackServlet call-7 : Received positive auth response.
[DEBUG] 20:19:23,566 [ConsumerManager.java :1195] LoginCallbackServlet call-7 : Verifying return URL; receiving: http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=Oh8WvgZJKvHFMXBGIFCn0jWobpziSRnlkcpt%2B%2FXl2Dg%3D&openid.mode=id_res&openid.identity=http%3A%2F%2Flocalhost%3A8989%2Fuser%3Fformat%3Dhtml&openid.return_to=http%3A%2F%2Flocalhost%3A8989%2FloginCallback%3Fopenid.rpnonce%3D2016-02-13T19%253A19%253A23Z0%26openid.rpsig%3DOh8WvgZJKvHFMXBGIFCn0jWobpziSRnlkcpt%252B%252FXl2Dg%253D&openid.assoc_handle=1455391163151-0&openid.signed=return_to%2Cidentity%2Csreg.email&openid.sig=ru%2FQtiftlmCdfNJuNXBW1qqCvGE%3D&openid.ns.ext1=http%3A%2F%2Fopenid.net%2Fsrv%2Fax%2F1.0&openid.ext1.mode=fetch_response&openid.ext1.type.email=http%3A%2F%2Fschema.openid.net%2Fcontact%2Femail&openid.ext1.value.email=user%40example.com&openid.ns.sreg=http%3A%2F%2Fopenid.net%2Fsreg%2F1.0&openid.sreg.email=user%40example.com
message: http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=Oh8WvgZJKvHFMXBGIFCn0jWobpziSRnlkcpt%2B%2FXl2Dg%3D
[DEBUG] 20:19:23,567 [ConsumerManager.java :1564] LoginCallbackServlet call-7 : Verifying discovered information for OpenID1 assertion about ClaimedID: http://localhost:8989/user?format=html
[DEBUG] 20:19:23,567 [ConsumerManager.java :1412] LoginCallbackServlet call-7 : Extracting consumer nonce...
[DEBUG] 20:19:23,567 [ConsumerManager.java :1441] LoginCallbackServlet call-7 : Extracted consumer nonce: 2016-02-13T19:19:23Z0
[DEBUG] 20:19:23,567 [ConsumerManager.java :1447] LoginCallbackServlet call-7 : Extracted consumer nonce signature: Oh8WvgZJKvHFMXBGIFCn0jWobpziSRnlkcpt+/Xl2Dg=
[DEBUG] 20:19:23,567 [ConsumerManager.java :1466] LoginCallbackServlet call-7 : Consumer signed text:
http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0
[DEBUG] 20:19:23,567 [ConsumerManager.java :1470] LoginCallbackServlet call-7 : Loading private association for opUrl http://localhost:8989/provider
[DEBUG] 20:19:23,568 [Association.java : 278] LoginCallbackServlet call-7 : Verifying signature: Oh8WvgZJKvHFMXBGIFCn0jWobpziSRnlkcpt+/Xl2Dg=
[DEBUG] 20:19:23,568 [Association.java : 261] LoginCallbackServlet call-7 : Computing signature for input data:
http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0
[DEBUG] 20:19:23,568 [Association.java : 267] LoginCallbackServlet call-7 : Calculated signature: Oh8WvgZJKvHFMXBGIFCn0jWobpziSRnlkcpt+/Xl2Dg=
[INFO ] 20:19:23,568 [ConsumerManager.java :1480] LoginCallbackServlet call-7 : Consumer nonce signature verified.
[DEBUG] 20:19:23,568 [AbstractNonceVerifier.java : 50] LoginCallbackServlet call-7 : Verifying nonce: 2016-02-13T19:19:23Z0
[DEBUG] 20:19:23,568 [InternetDateFormat.java : 43] LoginCallbackServlet call-7 : Parsed 2016-02-13T19:19:23Z0 into Data object: Sat Feb 13 20:19:23 CET 2016
[DEBUG] 20:19:23,569 [InMemoryNonceVerifier.java : 49] LoginCallbackServlet call-7 : Nonce verified: 2016-02-13T19:19:23Z0
[INFO ] 20:19:23,569 [ConsumerManager.java :1782] LoginCallbackServlet call-7 : Found association: 1455391163151-0 verifying signature locally...
[DEBUG] 20:19:23,569 [Association.java : 278] LoginCallbackServlet call-7 : Verifying signature: ru/QtiftlmCdfNJuNXBW1qqCvGE=
[DEBUG] 20:19:23,569 [Association.java : 261] LoginCallbackServlet call-7 : Computing signature for input data:
return_to:http://localhost:8989/loginCallback?openid.rpnonce=2016-02-13T19%3A19%3A23Z0&openid.rpsig=Oh8WvgZJKvHFMXBGIFCn0jWobpziSRnlkcpt%2B%2FXl2Dg%3D
identity:http://localhost:8989/user?format=html
sreg.email:user@example.com
[DEBUG] 20:19:23,569 [Association.java : 267] LoginCallbackServlet call-7 : Calculated signature: ru/QtiftlmCdfNJuNXBW1qqCvGE=
[DEBUG] 20:19:23,569 [ConsumerManager.java :1790] LoginCallbackServlet call-7 : Local signature verification succeeded.
[INFO ] 20:19:23,570 [ConsumerManager.java :1850] LoginCallbackServlet call-7 : Verification succeeded for: http://localhost:8989/user?format=html
[DEBUG] 20:19:23,570 [ParameterList.java : 33] LoginCallbackServlet call-7 : Created empty parameter list.
[DEBUG] 20:19:23,570 [AxMessage.java : 50] LoginCallbackServlet call-7 : Created empty AXMessage.
[DEBUG] 20:19:23,570 [ParameterList.java : 33] LoginCallbackServlet call-7 : Created empty parameter list.
[DEBUG] 20:19:23,570 [ParameterList.java : 33] LoginCallbackServlet call-7 : Created empty parameter list.
[DEBUG] 20:19:23,570 [AxMessage.java : 50] LoginCallbackServlet call-7 : Created empty AXMessage.
[DEBUG] 20:19:23,570 [FetchResponse.java : 67] LoginCallbackServlet call-7 : Created fetch response from parameter list:
value.email:user@example.com
type.email:http://schema.openid.net/contact/email
mode:fetch_response
[DEBUG] 20:19:23,570 [Message.java : 513] LoginCallbackServlet call-7 : Extracting http://openid.net/srv/ax/1.0 extension from message...
[DEBUG] 20:19:23,571 [ParameterList.java : 33] LoginCallbackServlet call-7 : Created empty parameter list.
[DEBUG] 20:19:23,571 [SRegMessage.java : 73] LoginCallbackServlet call-7 : Created empty SRegMessage.
[DEBUG] 20:19:23,571 [ParameterList.java : 33] LoginCallbackServlet call-7 : Created empty parameter list.
[DEBUG] 20:19:23,571 [ParameterList.java : 33] LoginCallbackServlet call-7 : Created empty parameter list.
[DEBUG] 20:19:23,571 [SRegMessage.java : 73] LoginCallbackServlet call-7 : Created empty SRegMessage.
[DEBUG] 20:19:23,571 [SRegResponse.java : 68] LoginCallbackServlet call-7 : Created SReg response from parameter list:
email:user@example.com
[DEBUG] 20:19:23,571 [Message.java : 513] LoginCallbackServlet call-7 : Extracting http://openid.net/sreg/1.0 extension from message...
[INFO ] 20:19:23,571 [LoginCallbackServlet.java : 40] LoginCallbackServlet call-7 : success
[INFO ] 20:19:23,573 [HttpServletSupport.java : 49] LoginCallbackServlet call-7 : end onService
[INFO ] 20:19:23,574 [HtmlUnitDialog.java : 692] : Window "" changed : "" became "no_html"
[INFO ] 20:19:23,575 [JCLLoggerAdapter.java : 216] : org.openid4java.samples.ProviderServlet: destroy
[INFO ] 20:19:23,575 [JCLLoggerAdapter.java : 216] : org.openid4java.samples.UserInfoServlet: destroy
[INFO ] 20:19:23,575 [JCLLoggerAdapter.java : 216] : org.openid4java.samples.LoginCallbackServlet: destroy
[INFO ] 20:19:23,576 [JCLLoggerAdapter.java : 216] : org.openid4java.samples.LoginServlet: destroy
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.539 sec
Running org.openid4java.association.DiffieHellmanSessionTest
[DEBUG] 20:19:23,725 [DiffieHellmanSession.java : 126] : Generated random DHParameterSpec, base: 2745670069643280498427802520825912869194995376941005677102148901931458386667424080334792818664218026515992145742995613328035229780476230707738782465276187, modulus: 11618849782870208570150009011125704850477088829457326623624769329682084471292575547531577350910948677559660071640216607953594526205899553804493894757172599
[DEBUG] 20:19:23,836 [DiffieHellmanSession.java : 126] : Generated random DHParameterSpec, base: 1343759455925624321071218223646956094644378259528199924060746613554735301676940608788719877989530083918145225532854439758065457028788513530681151598844812, modulus: 8203363287449750300080180388066290616929122017126098085707561265712845321841402022542270520738081805002372480968892992552881035358903898479210912346836021
[DEBUG] 20:19:23,996 [DiffieHellmanSession.java : 126] : Generated random DHParameterSpec, base: 1208374474679441308534937274698121533480846822509945243467909344379448496512026210229899056805265386613940377680386641201308029406163278633900648627913865, modulus: 9832945263894007701882178630212325807049761861972074888121043208738262995554865619029918431594786979463161951432615428987034710128495698612624714830647559
[DEBUG] 20:19:24,236 [DiffieHellmanSession.java : 126] : Generated random DHParameterSpec, base: 1536587252930818323017733699784485071098839969601260518073447274210390444174216433101067404812676423270861156222691647772084439688444388591069601401806915, modulus: 9186071337865117382358041801777577483859011507899073918418468266880660717460504894592685470418892273171640603021307665236126599585382508847276064087746211
[DEBUG] 20:19:24,238 [DiffieHellmanSession.java : 96] : Created DH session: DH-SHA1:HMAC-SHA1:OpenID2 base: 2 modulus: 155172898181473697471232257763715539915724801966915404479707795314057629378541917580651227423698188993727816152646631438561595825688188889951272158842675419950341258706556549803580104870537681476726513255747040765857479291291572334510643245094715007229621094194349783925984760375594985848253359305585439638443
[DEBUG] 20:19:24,240 [DiffieHellmanSession.java : 96] : Created DH session: DH-SHA1:HMAC-SHA1:OpenID2 base: 2 modulus: 155172898181473697471232257763715539915724801966915404479707795314057629378541917580651227423698188993727816152646631438561595825688188889951272158842675419950341258706556549803580104870537681476726513255747040765857479291291572334510643245094715007229621094194349783925984760375594985848253359305585439638443
[DEBUG] 20:19:24,242 [DiffieHellmanSession.java : 96] : Created DH session: DH-SHA1:HMAC-SHA1:OpenID2 base: 2 modulus: 155172898181473697471232257763715539915724801966915404479707795314057629378541917580651227423698188993727816152646631438561595825688188889951272158842675419950341258706556549803580104870537681476726513255747040765857479291291572334510643245094715007229621094194349783925984760375594985848253359305585439638443
[DEBUG] 20:19:24,244 [DiffieHellmanSession.java : 253] : Encrypted MAC key Base64: T+ZpLaAplWvBm4MEftxuRlzAVZQ=
[DEBUG] 20:19:24,245 [DiffieHellmanSession.java : 298] : Decrypted MAC key Base64: PDuYdy+RGJ8sGRDM8seuJjKb8wM=
[DEBUG] 20:19:24,304 [DiffieHellmanSession.java : 126] : Generated random DHParameterSpec, base: 2389492734880013253467454192298144844464222607906241217859602563420624092278728988703775050875015901860499537983710461906120877564967811796764298989404964, modulus: 12810487012898375328941611158568443941449895593086366207150356914115834435865773568279183322517195671626956708901455392071114566794851001969268136228601007
[DEBUG] 20:19:24,305 [DiffieHellmanSession.java : 96] : Created DH session: DH-SHA1:HMAC-SHA1:OpenID2 base: 2389492734880013253467454192298144844464222607906241217859602563420624092278728988703775050875015901860499537983710461906120877564967811796764298989404964 modulus: 12810487012898375328941611158568443941449895593086366207150356914115834435865773568279183322517195671626956708901455392071114566794851001969268136228601007
[DEBUG] 20:19:24,306 [DiffieHellmanSession.java : 96] : Created DH session: DH-SHA1:HMAC-SHA1:OpenID2 base: 2389492734880013253467454192298144844464222607906241217859602563420624092278728988703775050875015901860499537983710461906120877564967811796764298989404964 modulus: 12810487012898375328941611158568443941449895593086366207150356914115834435865773568279183322517195671626956708901455392071114566794851001969268136228601007
[DEBUG] 20:19:24,306 [DiffieHellmanSession.java : 253] : Encrypted MAC key Base64: yBTUvG/ZSPsE+kCiMuNRdxs1V8g=
[DEBUG] 20:19:24,307 [DiffieHellmanSession.java : 298] : Decrypted MAC key Base64: Dm80jTvvltZ0rSpbcoULhv6w09k=
[DEBUG] 20:19:24,309 [DiffieHellmanSession.java : 96] : Created DH session: DH-SHA256:HMAC-SHA256:OpenID2 base: 2 modulus: 155172898181473697471232257763715539915724801966915404479707795314057629378541917580651227423698188993727816152646631438561595825688188889951272158842675419950341258706556549803580104870537681476726513255747040765857479291291572334510643245094715007229621094194349783925984760375594985848253359305585439638443
[DEBUG] 20:19:24,311 [DiffieHellmanSession.java : 96] : Created DH session: DH-SHA256:HMAC-SHA256:OpenID2 base: 2 modulus: 155172898181473697471232257763715539915724801966915404479707795314057629378541917580651227423698188993727816152646631438561595825688188889951272158842675419950341258706556549803580104870537681476726513255747040765857479291291572334510643245094715007229621094194349783925984760375594985848253359305585439638443
[DEBUG] 20:19:24,313 [DiffieHellmanSession.java : 253] : Encrypted MAC key Base64: 1pwSSX+AbqrM87wwS28ElA35Zs6QxhqdfgpAWmvwZ7Y=
[DEBUG] 20:19:24,315 [DiffieHellmanSession.java : 298] : Decrypted MAC key Base64: wBhSYAgn5YE4w8Uijh8mBF/WA0fFoLqePu+LIZ9sJVE=
[DEBUG] 20:19:24,362 [DiffieHellmanSession.java : 126] : Generated random DHParameterSpec, base: 1837534223656204401911121692254389243606755912282537115226618298148998738389259818894639198671595394969118784462151776969620738389280948816595020287333741, modulus: 12970004457181004742128250014102529783885045258017629393583648102628832185445391100159123888756168171381070026967145973721691172411131188177027618205595523
[DEBUG] 20:19:24,363 [DiffieHellmanSession.java : 96] : Created DH session: DH-SHA256:HMAC-SHA256:OpenID2 base: 1837534223656204401911121692254389243606755912282537115226618298148998738389259818894639198671595394969118784462151776969620738389280948816595020287333741 modulus: 12970004457181004742128250014102529783885045258017629393583648102628832185445391100159123888756168171381070026967145973721691172411131188177027618205595523
[DEBUG] 20:19:24,364 [DiffieHellmanSession.java : 96] : Created DH session: DH-SHA256:HMAC-SHA256:OpenID2 base: 1837534223656204401911121692254389243606755912282537115226618298148998738389259818894639198671595394969118784462151776969620738389280948816595020287333741 modulus: 12970004457181004742128250014102529783885045258017629393583648102628832185445391100159123888756168171381070026967145973721691172411131188177027618205595523
[DEBUG] 20:19:24,365 [DiffieHellmanSession.java : 253] : Encrypted MAC key Base64: gEzVrK6j0xgznGcB42EDav7LWbyGRSMJaTNP6BoikhY=
[DEBUG] 20:19:24,365 [DiffieHellmanSession.java : 298] : Decrypted MAC key Base64: f3zSRanRhlraFWyhJ5+abLuH+b1MSMCGngy2olqLRWk=
[DEBUG] 20:19:24,367 [DiffieHellmanSession.java : 96] : Created DH session: DH-SHA1:HMAC-SHA1:OpenID2 base: 2 modulus: 155172898181473697471232257763715539915724801966915404479707795314057629378541917580651227423698188993727816152646631438561595825688188889951272158842675419950341258706556549803580104870537681476726513255747040765857479291291572334510643245094715007229621094194349783925984760375594985848253359305585439638443
Tests run: 14, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.691 sec
Running org.openid4java.discovery.yadis.YadisUrlTest
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 sec
Results :
Tests run: 102, Failures: 0, Errors: 0, Skipped: 0
[JENKINS] Recording test results
[INFO] [jar:jar {execution: default-jar}]
[INFO] Building jar: /home/javastream.de/jenkins/jobs/com.googlecode.openid4java/workspace/target/openid4java-nodeps-0.9.5.jar
[INFO] [install:install {execution: default-install}]
[INFO] Installing /home/javastream.de/jenkins/jobs/com.googlecode.openid4java/workspace/target/openid4java-nodeps-0.9.5.jar to /home/javastream.de/www/maven/org/openid4java/openid4java-nodeps/0.9.5/openid4java-nodeps-0.9.5.jar
Started calculate disk usage of build
Finished Calculation of disk usage of build in 0 seconds
Started calculate disk usage of workspace
Finished Calculation of disk usage of workspace in 0 seconds
[INFO] ------------------------------------------------------------------------
[INFO] BUILD SUCCESSFUL
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 21 seconds
[INFO] Finished at: Sat Feb 13 20:19:26 CET 2016
[INFO] Final Memory: 36M/221M
[INFO] ------------------------------------------------------------------------
![[Jenkins]](/jenkins/static/01607e06/images/svgs/logo.svg){kind=logo}
