Connection to github enterprise oauth callback timeout

Hi!

The oauth autentication process seems to stop at final stage. The request to teamcity times out at this request. And I find that a bit strange, since the communincation with github should be finished at this stage. Maybe irrelevant but I was also looking in the db to see if I could find the accesstoken, but I did not see any matching tables. Should there have been one?

This is the reqest that fails (to teamcity):

Connection timed out: connect while processing request: GET '/oauth/github/accessToken.html?action=completeAuthorization&callbackUrl=%2Foauth%2Fgithub%2Frepositories.html%3FprojectId%3DSmiRoot%26connectionId%3DPROJECT_EXT_2%26tokenObtained%3Dtrue%26showMode%3DcreateProjectMenu&code=5be0809d072b240a564e&connectionId=PROJECT_EXT_2&projectId=SmiRoot&state=tqrDuhyJidkoipfKGFO0w3xcOtHq86Ia

 

Steps to reproduce:
 
1. Created oauth app in github, added keys and callback
2. Click "sign into GitHub"
3. Popup, signs in
4. Authorize access
5. Redirect does not happen (page says if not redirect auto, click her)
6. Timeout
 
Error message: Connection timed out: connect
TeamCity: 10.0.3 (build 42434)
Operating system: Windows Server 2012 R2 (6.3, x86)
Java: 1.8.0_66-b17 (Oracle Corporation)
Servlet container: Apache Tomcat/7.0.68

In the serverlogs i find this:

[2016-11-15 10:56:04,064] console [Info] [2016-11-15 10:56:03,966] ERROR - jetbrains.buildServer.SERVER - Error java.net.ConnectException: Connection timed out: connect while processing request: GET '/oauth/github/accessToken.html?action=completeAuthorization&callbackUrl=%2Foauth%2Fgithub%2Frepositories.html%3FprojectId%3DSmiRoot%26connectionId%3DPROJECT_EXT_2%26tokenObtained%3Dtrue%26showMode%3DcreateProjectMenu&code=5be0809d072b240a564e&connectionId=PROJECT_EXT_2&projectId=SmiRoot&state=tqrDuhyJidkoipfKGFO0w3xcOtHq86Ia', from client [fe80:0:0:0:6503:6fee:9f11:6e7%12]:56604, authenticated as 'admin' (Lars Erik) {id=1}
[2016-11-15 10:56:04,064] console [Info] java.net.ConnectException: Connection timed out: connect
[2016-11-15 10:56:04,064] console [Info] at java.net.DualStackPlainSocketImpl.connect0(Native Method)
[2016-11-15 10:56:04,064] console [Info] at java.net.DualStackPlainSocketImpl.socketConnect(DualStackPlainSocketImpl.java:79)
[2016-11-15 10:56:04,064] console [Info] at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:350)
[2016-11-15 10:56:04,064] console [Info] at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206)
[2016-11-15 10:56:04,064] console [Info] at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188)
[2016-11-15 10:56:04,064] console [Info] at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:172)
[2016-11-15 10:56:04,065] console [Info] at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392)
[2016-11-15 10:56:04,065] console [Info] at java.net.Socket.connect(Socket.java:589)
[2016-11-15 10:56:04,065] console [Info] at sun.security.ssl.SSLSocketImpl.connect(SSLSocketImpl.java:668)
[2016-11-15 10:56:04,065] console [Info] at sun.security.ssl.SSLSocketImpl.<init>(SSLSocketImpl.java:472)
[2016-11-15 10:56:04,065] console [Info] at sun.security.ssl.SSLSocketFactoryImpl.createSocket(SSLSocketFactoryImpl.java:153)
[2016-11-15 10:56:04,065] console [Info] at org.apache.commons.httpclient.protocol.SSLProtocolSocketFactory.createSocket(SSLProtocolSocketFactory.java:82)
[2016-11-15 10:56:04,065] console [Info] at org.apache.commons.httpclient.protocol.SSLProtocolSocketFactory.createSocket(SSLProtocolSocketFactory.java:127)
[2016-11-15 10:56:04,065] console [Info] at org.apache.commons.httpclient.HttpConnection.open(HttpConnection.java:707)
[2016-11-15 10:56:04,065] console [Info] at org.apache.commons.httpclient.HttpMethodDirector.executeWithRetry(HttpMethodDirector.java:387)
[2016-11-15 10:56:04,065] console [Info] at org.apache.commons.httpclient.HttpMethodDirector.executeMethod(HttpMethodDirector.java:171)
[2016-11-15 10:56:04,065] console [Info] at org.apache.commons.httpclient.HttpClient.executeMethod(HttpClient.java:397)
[2016-11-15 10:56:04,065] console [Info] at org.apache.commons.httpclient.HttpClient.executeMethod(HttpClient.java:323)
[2016-11-15 10:56:04,065] console [Info] at jetbrains.buildServer.serverSide.oauth.github.GitHubAccessTokenController.doHandle(GitHubAccessTokenController.java:105)
[2016-11-15 10:56:04,065] console [Info] at jetbrains.buildServer.controllers.BaseController.handleRequestInternal(BaseController.java:75)
[2016-11-15 10:56:04,065] console [Info] at org.springframework.web.servlet.mvc.AbstractController.handleRequest(AbstractController.java:147)
[2016-11-15 10:56:04,066] console [Info] at org.springframework.web.servlet.mvc.SimpleControllerHandlerAdapter.handle(SimpleControllerHandlerAdapter.java:50)
[2016-11-15 10:56:04,066] console [Info] at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:961)
[2016-11-15 10:56:04,066] console [Info] at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:895)
[2016-11-15 10:56:04,066] console [Info] at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:967)
[2016-11-15 10:56:04,066] console [Info] at org.springframework.web.servlet.FrameworkServlet.doGet(FrameworkServlet.java:858)
[2016-11-15 10:56:04,066] console [Info] at javax.servlet.http.HttpServlet.service(HttpServlet.java:624)
[2016-11-15 10:56:04,066] console [Info] at org.springframework.web.servlet.FrameworkServlet.service(FrameworkServlet.java:843)
[2016-11-15 10:56:04,066] console [Info] at javax.servlet.http.HttpServlet.service(HttpServlet.java:731)
[2016-11-15 10:56:04,066] console [Info] at jetbrains.buildServer.maintenance.TeamCityDispatcherServlet.service(TeamCityDispatcherServlet.java:21)
[2016-11-15 10:56:04,066] console [Info] at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:303)
[2016-11-15 10:56:04,066] console [Info] at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
[2016-11-15 10:56:04,067] console [Info] at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52)
[2016-11-15 10:56:04,067] console [Info] at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
[2016-11-15 10:56:04,067] console [Info] at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
[2016-11-15 10:56:04,067] console [Info] at jetbrains.buildServer.web.DisableSessionIdFromUrlFilter.doFilter(DisableSessionIdFromUrlFilter.java:7)
[2016-11-15 10:56:04,067] console [Info] at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
[2016-11-15 10:56:04,067] console [Info] at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
[2016-11-15 10:56:04,067] console [Info] at org.springframework.web.filter.CompositeFilter$VirtualFilterChain.doFilter(CompositeFilter.java:107)
[2016-11-15 10:56:04,067] console [Info] at jetbrains.buildServer.diagnostic.web.DiagnosticFilter.doFilter(DiagnosticFilter.java:23)
[2016-11-15 10:56:04,067] console [Info] at org.springframework.web.filter.CompositeFilter$VirtualFilterChain.doFilter(CompositeFilter.java:112)
[2016-11-15 10:56:04,067] console [Info] at jetbrains.buildServer.web.DependencyParametersCalculationContextFilter.doFilter(DependencyParametersCalculationContextFilter.java:1)
[2016-11-15 10:56:04,067] console [Info] at org.springframework.web.filter.CompositeFilter$VirtualFilterChain.doFilter(CompositeFilter.java:112)
[2016-11-15 10:56:04,067] console [Info] at org.springframework.web.filter.CompositeFilter.doFilter(CompositeFilter.java:73)
[2016-11-15 10:56:04,067] console [Info] at jetbrains.buildServer.web.DelegatingFilter.doFilter(DelegatingFilter.java:11)
[2016-11-15 10:56:04,067] console [Info] at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
[2016-11-15 10:56:04,068] console [Info] at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
[2016-11-15 10:56:04,068] console [Info] at jetbrains.buildServer.web.ResponseFragmentFilter.doFilter(ResponseFragmentFilter.java:10)
[2016-11-15 10:56:04,068] console [Info] at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
[2016-11-15 10:56:04,068] console [Info] at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
[2016-11-15 10:56:04,068] console [Info] at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:220)
[2016-11-15 10:56:04,068] console [Info] at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:122)
[2016-11-15 10:56:04,068] console [Info] at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:169)
[2016-11-15 10:56:04,068] console [Info] at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:103)
[2016-11-15 10:56:04,068] console [Info] at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:116)
[2016-11-15 10:56:04,068] console [Info] at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:436)
[2016-11-15 10:56:04,068] console [Info] at org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:1078)
[2016-11-15 10:56:04,068] console [Info] at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:625)
[2016-11-15 10:56:04,068] console [Info] at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1757)
[2016-11-15 10:56:04,069] console [Info] at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.run(NioEndpoint.java:1716)
[2016-11-15 10:56:04,069] console [Info] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[2016-11-15 10:56:04,069] console [Info] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[2016-11-15 10:56:04,069] console [Info] at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
[2016-11-15 10:56:04,069] console [Info] at java.lang.Thread.run(Thread.java:745)

Thanks for any help

Regards

Lars Erik

0
1 comment

Hello Lars Erik, Do you have correct Server URL configured on Administration > Global settings page? Does your GitHub Enterprise instance use a self-signed certificate? If yes, have you added certificate in keystore on TeamCity server?

0

Please sign in to leave a comment.