authorezation_request_not_found although I'm getting authenticatedby GitHub
MarounMaroun opened this issue · 1 comments
MarounMaroun commented
Summary
I'm keeping redirected to "reauthorization request" (probably due to infinite redirects by Spring) with the following stacktrace:
2021-01-24 14:23:01.882 DEBUG 54030 --- [nio-8080-exec-4] o.a.coyote.http11.Http11InputBuffer : Received [GET /oauth2/authorization/github?redirect_uri=http://localhost:5000/oauth/jwt HTTP/1.1
cookie: JSESSIONID=16A39E54C46DF7A93E3C013A67882C6C; oauth2_auth_request=rO0ABXNyAExvcmcuc3ByaW5nZnJhbWV3b3JrLnNlY3VyaXR5Lm9hdXRoMi5jb3JlLmVuZHBvaW50Lk9BdXRoMkF1dGhvcml6YXRpb25SZXF1ZXN0AAAAAAAAAhICAApMABRhZGRpdGlvbmFsUGFyYW1ldGVyc3QAD0xqYXZhL3V0aWwvTWFwO0wACmF0dHJpYnV0ZXNxAH4AAUwAFmF1dGhvcml6YXRpb25HcmFudFR5cGV0AEFMb3JnL3NwcmluZ2ZyYW1ld29yay9zZWN1cml0eS9vYXV0aDIvY29yZS9BdXRob3JpemF0aW9uR3JhbnRUeXBlO0wAF2F1dGhvcml6YXRpb25SZXF1ZXN0VXJpdAASTGphdmEvbGFuZy9TdHJpbmc7TAAQYXV0aG9yaXphdGlvblVyaXEAfgADTAAIY2xpZW50SWRxAH4AA0wAC3JlZGlyZWN0VXJpcQB-AANMAAxyZXNwb25zZVR5cGV0AFNMb3JnL3NwcmluZ2ZyYW1ld29yay9zZWN1cml0eS9vYXV0aDIvY29yZS9lbmRwb2ludC9PQXV0aDJBdXRob3JpemF0aW9uUmVzcG9uc2VUeXBlO0wABnNjb3Blc3QAD0xqYXZhL3V0aWwvU2V0O0wABXN0YXRlcQB-AAN4cHNyACVqYXZhLnV0aWwuQ29sbGVjdGlvbnMkVW5tb2RpZmlhYmxlTWFw8aWo_nT1B0ICAAFMAAFtcQB-AAF4cHNyABdqYXZhLnV0aWwuTGlua2VkSGFzaE1hcDTATlwQbMD7AgABWgALYWNjZXNzT3JkZXJ4cgARamF2YS51dGlsLkhhc2hNYXAFB9rBwxZg0QMAAkYACmxvYWRGYWN0b3JJAAl0aHJlc2hvbGR4cD9AAAAAAAAAdwgAAAAQAAAAAHgAc3EAfgAHc3EAfgAJP0AAAAAAAAF3CAAAAAIAAAABdAAPcmVnaXN0cmF0aW9uX2lkdAAGZ2l0aHVieABzcgA_b3JnLnNwcmluZ2ZyYW1ld29yay5zZWN1cml0eS5vYXV0aDIuY29yZS5BdXRob3JpemF0aW9uR3JhbnRUeXBlAAAAAAAAAhICAAFMAAV2YWx1ZXEAfgADeHB0ABJhdXRob3JpemF0aW9uX2NvZGV0ANtodHRwczovL2dpdGh1Yi5jb20vbG9naW4vb2F1dGgvYXV0aG9yaXplP3Jlc3BvbnNlX3R5cGU9Y29kZSZjbGllbnRfaWQ9YzRiZjhhNWZjMTg0NDgyZWM3MWEmc2NvcGU9cmVhZDp1c2VyJnN0YXRlPVdxcjNEanEteHFuZ243MFh6UWNJOGluZzNMY2N0MjlsZ2FKZHpjZW1TUEUlM0QmcmVkaXJlY3RfdXJpPWh0dHA6Ly9sb2NhbGhvc3Q6ODA4MC9sb2dpbi9vYXV0aDIvY29kZS9naXRodWJ0AChodHRwczovL2dpdGh1Yi5jb20vbG9naW4vb2F1dGgvYXV0aG9yaXpldAAUYzRiZjhhNWZjMTg0NDgyZWM3MWF0AC5odHRwOi8vbG9jYWxob3N0OjgwODAvbG9naW4vb2F1dGgyL2NvZGUvZ2l0aHVic3IAUW9yZy5zcHJpbmdmcmFtZXdvcmsuc2VjdXJpdHkub2F1dGgyLmNvcmUuZW5kcG9pbnQuT0F1dGgyQXV0aG9yaXphdGlvblJlc3BvbnNlVHlwZQAAAAAAAAISAgABTAAFdmFsdWVxAH4AA3hwdAAEY29kZXNyACVqYXZhLnV0aWwuQ29sbGVjdGlvbnMkVW5tb2RpZmlhYmxlU2V0gB2S0Y-bgFUCAAB4cgAsamF2YS51dGlsLkNvbGxlY3Rpb25zJFVubW9kaWZpYWJsZUNvbGxlY3Rpb24ZQgCAy173HgIAAUwAAWN0ABZMamF2YS91dGlsL0NvbGxlY3Rpb247eHBzcgAXamF2YS51dGlsLkxpbmtlZEhhc2hTZXTYbNdald0qHgIAAHhyABFqYXZhLnV0aWwuSGFzaFNldLpEhZWWuLc0AwAAeHB3DAAAABA_QAAAAAAAAXQACXJlYWQ6dXNlcnh0ACxXcXIzRGpxLXhxbmduNzBYelFjSThpbmczTGNjdDI5bGdhSmR6Y2VtU1BFPQ==
accept-language: en-US,en;q=0.9
accept-encoding: gzip, deflate, br
referer: http://localhost:5000/entry/sign-in
sec-fetch-dest: document
sec-fetch-user: ?1
sec-fetch-mode: navigate
sec-fetch-site: same-origin
accept: text/html,application/xhtml+xml,application/xml;q=0.9,image/avif,image/webp,image/apng,*/*;q=0.8,application/signed-exchange;v=b3;q=0.9
user-agent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36
upgrade-insecure-requests: 1
sec-ch-ua-mobile: ?0
sec-ch-ua: "Google Chrome";v="87", " Not;A Brand";v="99", "Chromium";v="87"
connection: close
host: 127.0.0.1:8080
]
2021-01-24 14:23:01.883 DEBUG 54030 --- [nio-8080-exec-4] o.a.t.util.http.Rfc6265CookieProcessor : Cookies: Parsing b[]: JSESSIONID=16A39E54C46DF7A93E3C013A67882C6C; oauth2_auth_request=rO0ABXNyAExvcmcuc3ByaW5nZnJhbWV3b3JrLnNlY3VyaXR5Lm9hdXRoMi5jb3JlLmVuZHBvaW50Lk9BdXRoMkF1dGhvcml6YXRpb25SZXF1ZXN0AAAAAAAAAhICAApMABRhZGRpdGlvbmFsUGFyYW1ldGVyc3QAD0xqYXZhL3V0aWwvTWFwO0wACmF0dHJpYnV0ZXNxAH4AAUwAFmF1dGhvcml6YXRpb25HcmFudFR5cGV0AEFMb3JnL3NwcmluZ2ZyYW1ld29yay9zZWN1cml0eS9vYXV0aDIvY29yZS9BdXRob3JpemF0aW9uR3JhbnRUeXBlO0wAF2F1dGhvcml6YXRpb25SZXF1ZXN0VXJpdAASTGphdmEvbGFuZy9TdHJpbmc7TAAQYXV0aG9yaXphdGlvblVyaXEAfgADTAAIY2xpZW50SWRxAH4AA0wAC3JlZGlyZWN0VXJpcQB-AANMAAxyZXNwb25zZVR5cGV0AFNMb3JnL3NwcmluZ2ZyYW1ld29yay9zZWN1cml0eS9vYXV0aDIvY29yZS9lbmRwb2ludC9PQXV0aDJBdXRob3JpemF0aW9uUmVzcG9uc2VUeXBlO0wABnNjb3Blc3QAD0xqYXZhL3V0aWwvU2V0O0wABXN0YXRlcQB-AAN4cHNyACVqYXZhLnV0aWwuQ29sbGVjdGlvbnMkVW5tb2RpZmlhYmxlTWFw8aWo_nT1B0ICAAFMAAFtcQB-AAF4cHNyABdqYXZhLnV0aWwuTGlua2VkSGFzaE1hcDTATlwQbMD7AgABWgALYWNjZXNzT3JkZXJ4cgARamF2YS51dGlsLkhhc2hNYXAFB9rBwxZg0QMAAkYACmxvYWRGYWN0b3JJAAl0aHJlc2hvbGR4cD9AAAAAAAAAdwgAAAAQAAAAAHgAc3EAfgAHc3EAfgAJP0AAAAAAAAF3CAAAAAIAAAABdAAPcmVnaXN0cmF0aW9uX2lkdAAGZ2l0aHVieABzcgA_b3JnLnNwcmluZ2ZyYW1ld29yay5zZWN1cml0eS5vYXV0aDIuY29yZS5BdXRob3JpemF0aW9uR3JhbnRUeXBlAAAAAAAAAhICAAFMAAV2YWx1ZXEAfgADeHB0ABJhdXRob3JpemF0aW9uX2NvZGV0ANtodHRwczovL2dpdGh1Yi5jb20vbG9naW4vb2F1dGgvYXV0aG9yaXplP3Jlc3BvbnNlX3R5cGU9Y29kZSZjbGllbnRfaWQ9YzRiZjhhNWZjMTg0NDgyZWM3MWEmc2NvcGU9cmVhZDp1c2VyJnN0YXRlPVdxcjNEanEteHFuZ243MFh6UWNJOGluZzNMY2N0MjlsZ2FKZHpjZW1TUEUlM0QmcmVkaXJlY3RfdXJpPWh0dHA6Ly9sb2NhbGhvc3Q6ODA4MC9sb2dpbi9vYXV0aDIvY29kZS9naXRodWJ0AChodHRwczovL2dpdGh1Yi5jb20vbG9naW4vb2F1dGgvYXV0aG9yaXpldAAUYzRiZjhhNWZjMTg0NDgyZWM3MWF0AC5odHRwOi8vbG9jYWxob3N0OjgwODAvbG9naW4vb2F1dGgyL2NvZGUvZ2l0aHVic3IAUW9yZy5zcHJpbmdmcmFtZXdvcmsuc2VjdXJpdHkub2F1dGgyLmNvcmUuZW5kcG9pbnQuT0F1dGgyQXV0aG9yaXphdGlvblJlc3BvbnNlVHlwZQAAAAAAAAISAgABTAAFdmFsdWVxAH4AA3hwdAAEY29kZXNyACVqYXZhLnV0aWwuQ29sbGVjdGlvbnMkVW5tb2RpZmlhYmxlU2V0gB2S0Y-bgFUCAAB4cgAsamF2YS51dGlsLkNvbGxlY3Rpb25zJFVubW9kaWZpYWJsZUNvbGxlY3Rpb24ZQgCAy173HgIAAUwAAWN0ABZMamF2YS91dGlsL0NvbGxlY3Rpb247eHBzcgAXamF2YS51dGlsLkxpbmtlZEhhc2hTZXTYbNdald0qHgIAAHhyABFqYXZhLnV0aWwuSGFzaFNldLpEhZWWuLc0AwAAeHB3DAAAABA_QAAAAAAAAXQACXJlYWQ6dXNlcnh0ACxXcXIzRGpxLXhxbmduNzBYelFjSThpbmczTGNjdDI5bGdhSmR6Y2VtU1BFPQ==
2021-01-24 14:23:01.883 DEBUG 54030 --- [nio-8080-exec-4] o.a.catalina.connector.CoyoteAdapter : Requested cookie session id is 16A39E54C46DF7A93E3C013A67882C6C
2021-01-24 14:23:01.883 DEBUG 54030 --- [nio-8080-exec-4] o.a.c.authenticator.AuthenticatorBase : Security checking request GET /oauth2/authorization/github
2021-01-24 14:23:01.883 DEBUG 54030 --- [nio-8080-exec-4] org.apache.catalina.realm.RealmBase : No applicable constraints defined
2021-01-24 14:23:01.883 DEBUG 54030 --- [nio-8080-exec-4] o.a.c.authenticator.AuthenticatorBase : Not subject to any constraint
2021-01-24 14:23:01.884 DEBUG 54030 --- [nio-8080-exec-4] o.s.security.web.FilterChainProxy : /oauth2/authorization/github?redirect_uri=http://localhost:5000/oauth/jwt at position 1 of 15 in additional filter chain; firing Filter: 'WebAsyncManagerIntegrationFilter'
2021-01-24 14:23:01.884 DEBUG 54030 --- [nio-8080-exec-4] o.s.security.web.FilterChainProxy : /oauth2/authorization/github?redirect_uri=http://localhost:5000/oauth/jwt at position 2 of 15 in additional filter chain; firing Filter: 'SecurityContextPersistenceFilter'
2021-01-24 14:23:01.884 DEBUG 54030 --- [nio-8080-exec-4] o.s.security.web.FilterChainProxy : /oauth2/authorization/github?redirect_uri=http://localhost:5000/oauth/jwt at position 3 of 15 in additional filter chain; firing Filter: 'HeaderWriterFilter'
2021-01-24 14:23:01.884 DEBUG 54030 --- [nio-8080-exec-4] o.s.security.web.FilterChainProxy : /oauth2/authorization/github?redirect_uri=http://localhost:5000/oauth/jwt at position 4 of 15 in additional filter chain; firing Filter: 'LogoutFilter'
2021-01-24 14:23:01.884 DEBUG 54030 --- [nio-8080-exec-4] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/logout', GET]
2021-01-24 14:23:01.884 DEBUG 54030 --- [nio-8080-exec-4] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/oauth2/authorization/github'; against '/logout'
2021-01-24 14:23:01.884 DEBUG 54030 --- [nio-8080-exec-4] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/logout', POST]
2021-01-24 14:23:01.884 DEBUG 54030 --- [nio-8080-exec-4] o.s.s.w.u.matcher.AntPathRequestMatcher : Request 'GET /oauth2/authorization/github' doesn't match 'POST /logout'
2021-01-24 14:23:01.884 DEBUG 54030 --- [nio-8080-exec-4] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/logout', PUT]
2021-01-24 14:23:01.884 DEBUG 54030 --- [nio-8080-exec-4] o.s.s.w.u.matcher.AntPathRequestMatcher : Request 'GET /oauth2/authorization/github' doesn't match 'PUT /logout'
2021-01-24 14:23:01.884 DEBUG 54030 --- [nio-8080-exec-4] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/logout', DELETE]
2021-01-24 14:23:01.884 DEBUG 54030 --- [nio-8080-exec-4] o.s.s.w.u.matcher.AntPathRequestMatcher : Request 'GET /oauth2/authorization/github' doesn't match 'DELETE /logout'
2021-01-24 14:23:01.884 DEBUG 54030 --- [nio-8080-exec-4] o.s.s.web.util.matcher.OrRequestMatcher : No matches found
2021-01-24 14:23:01.884 DEBUG 54030 --- [nio-8080-exec-4] o.s.security.web.FilterChainProxy : /oauth2/authorization/github?redirect_uri=http://localhost:5000/oauth/jwt at position 5 of 15 in additional filter chain; firing Filter: 'OAuth2AuthorizationRequestRedirectFilter'
2021-01-24 14:23:01.884 DEBUG 54030 --- [nio-8080-exec-4] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/oauth2/authorization/github'; against '/oauth2/authorization/{registrationId}'
2021-01-24 14:23:01.884 DEBUG 54030 --- [nio-8080-exec-4] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/oauth2/authorization/github'; against '/oauth2/authorization/{registrationId}'
2021-01-24 14:23:01.884 DEBUG 54030 --- [nio-8080-exec-4] org.apache.tomcat.util.http.Parameters : Set encoding to UTF-8
2021-01-24 14:23:01.884 DEBUG 54030 --- [nio-8080-exec-4] org.apache.tomcat.util.http.Parameters : Decoding query null UTF-8
2021-01-24 14:23:01.885 DEBUG 54030 --- [nio-8080-exec-4] org.apache.tomcat.util.http.Parameters : Start processing with input [redirect_uri=http://localhost:5000/oauth/jwt]
2021-01-24 14:23:01.886 DEBUG 54030 --- [nio-8080-exec-4] o.s.s.web.DefaultRedirectStrategy : Redirecting to 'https://github.com/login/oauth/authorize?response_type=code&client_id=c4bf8a5fc184482ec71a&scope=read:user&state=sdrsuBQum1n9Q_paJv4lYKQt2dbc1QBeFxiTICwju2Q%3D&redirect_uri=http://127.0.0.1:8080/login/oauth2/code/github'
2021-01-24 14:23:01.886 DEBUG 54030 --- [nio-8080-exec-4] o.s.s.w.header.writers.HstsHeaderWriter : Not injecting HSTS header since it did not match the requestMatcher org.springframework.security.web.header.writers.HstsHeaderWriter$SecureRequestMatcher@46149ef2
2021-01-24 14:23:01.886 DEBUG 54030 --- [nio-8080-exec-4] s.s.w.c.SecurityContextPersistenceFilter : SecurityContextHolder now cleared, as request processing completed
2021-01-24 14:23:01.887 DEBUG 54030 --- [nio-8080-exec-4] o.apache.coyote.http11.Http11Processor : Socket: [org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@4348f636:org.apache.tomcat.util.net.NioChannel@4d8f102f:java.nio.channels.SocketChannel[connected local=/127.0.0.1:8080 remote=/127.0.0.1:57791]], Status in: [OPEN_READ], State out: [CLOSED]
2021-01-24 14:23:01.888 DEBUG 54030 --- [nio-8080-exec-4] o.apache.tomcat.util.threads.LimitLatch : Counting down[http-nio-8080-exec-4] latch=3
2021-01-24 14:23:01.888 DEBUG 54030 --- [nio-8080-exec-4] org.apache.tomcat.util.net.NioEndpoint : Calling [org.apache.tomcat.util.net.NioEndpoint@706b8437].closeSocket([org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@4348f636:org.apache.tomcat.util.net.NioChannel@4d8f102f:java.nio.channels.SocketChannel[connected local=/127.0.0.1:8080 remote=/127.0.0.1:57791]])
2021-01-24 14:23:04.913 DEBUG 54030 --- [o-8080-Acceptor] o.apache.tomcat.util.threads.LimitLatch : Counting up[http-nio-8080-Acceptor] latch=3
2021-01-24 14:23:04.924 DEBUG 54030 --- [nio-8080-exec-5] o.a.tomcat.util.net.SocketWrapperBase : Socket: [org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@30776187:org.apache.tomcat.util.net.NioChannel@3eb099f9:java.nio.channels.SocketChannel[connected local=/127.0.0.1:8080 remote=/127.0.0.1:57773]], Read from buffer: [0]
2021-01-24 14:23:04.925 DEBUG 54030 --- [nio-8080-exec-5] org.apache.tomcat.util.net.NioEndpoint : Socket: [org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@30776187:org.apache.tomcat.util.net.NioChannel@3eb099f9:java.nio.channels.SocketChannel[connected local=/127.0.0.1:8080 remote=/127.0.0.1:57773]], Read direct from socket: [778]
2021-01-24 14:23:04.925 DEBUG 54030 --- [nio-8080-exec-5] o.a.coyote.http11.Http11InputBuffer : Received [GET /login/oauth2/code/github?code=c1f037db1df6d99c41bf&state=sdrsuBQum1n9Q_paJv4lYKQt2dbc1QBeFxiTICwju2Q%3D HTTP/1.1
Host: 127.0.0.1:8080
Connection: keep-alive
sec-ch-ua: "Google Chrome";v="87", " Not;A Brand";v="99", "Chromium";v="87"
sec-ch-ua-mobile: ?0
Upgrade-Insecure-Requests: 1
User-Agent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36
Accept: text/html,application/xhtml+xml,application/xml;q=0.9,image/avif,image/webp,image/apng,*/*;q=0.8,application/signed-exchange;v=b3;q=0.9
Sec-Fetch-Site: cross-site
Sec-Fetch-Mode: navigate
Sec-Fetch-Dest: document
Accept-Encoding: gzip, deflate, br
Accept-Language: en-US,en;q=0.9
Cookie: JSESSIONID=1E6CB48952B6F03D586DC0112BBD2120
]
2021-01-24 14:23:04.926 DEBUG 54030 --- [nio-8080-exec-5] o.a.t.util.http.Rfc6265CookieProcessor : Cookies: Parsing b[]: JSESSIONID=1E6CB48952B6F03D586DC0112BBD2120
2021-01-24 14:23:04.926 DEBUG 54030 --- [nio-8080-exec-5] o.a.catalina.connector.CoyoteAdapter : Requested cookie session id is 1E6CB48952B6F03D586DC0112BBD2120
2021-01-24 14:23:04.926 DEBUG 54030 --- [nio-8080-exec-5] o.a.c.authenticator.AuthenticatorBase : Security checking request GET /login/oauth2/code/github
2021-01-24 14:23:04.926 DEBUG 54030 --- [nio-8080-exec-5] org.apache.catalina.realm.RealmBase : No applicable constraints defined
2021-01-24 14:23:04.926 DEBUG 54030 --- [nio-8080-exec-5] o.a.c.authenticator.AuthenticatorBase : Not subject to any constraint
2021-01-24 14:23:04.926 DEBUG 54030 --- [nio-8080-exec-5] o.s.security.web.FilterChainProxy : /login/oauth2/code/github?code=c1f037db1df6d99c41bf&state=sdrsuBQum1n9Q_paJv4lYKQt2dbc1QBeFxiTICwju2Q%3D at position 1 of 15 in additional filter chain; firing Filter: 'WebAsyncManagerIntegrationFilter'
2021-01-24 14:23:04.927 DEBUG 54030 --- [nio-8080-exec-5] o.s.security.web.FilterChainProxy : /login/oauth2/code/github?code=c1f037db1df6d99c41bf&state=sdrsuBQum1n9Q_paJv4lYKQt2dbc1QBeFxiTICwju2Q%3D at position 2 of 15 in additional filter chain; firing Filter: 'SecurityContextPersistenceFilter'
2021-01-24 14:23:04.927 DEBUG 54030 --- [nio-8080-exec-5] o.s.security.web.FilterChainProxy : /login/oauth2/code/github?code=c1f037db1df6d99c41bf&state=sdrsuBQum1n9Q_paJv4lYKQt2dbc1QBeFxiTICwju2Q%3D at position 3 of 15 in additional filter chain; firing Filter: 'HeaderWriterFilter'
2021-01-24 14:23:04.927 DEBUG 54030 --- [nio-8080-exec-5] o.s.security.web.FilterChainProxy : /login/oauth2/code/github?code=c1f037db1df6d99c41bf&state=sdrsuBQum1n9Q_paJv4lYKQt2dbc1QBeFxiTICwju2Q%3D at position 4 of 15 in additional filter chain; firing Filter: 'LogoutFilter'
2021-01-24 14:23:04.927 DEBUG 54030 --- [nio-8080-exec-5] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/logout', GET]
2021-01-24 14:23:04.927 DEBUG 54030 --- [nio-8080-exec-5] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/login/oauth2/code/github'; against '/logout'
2021-01-24 14:23:04.927 DEBUG 54030 --- [nio-8080-exec-5] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/logout', POST]
2021-01-24 14:23:04.927 DEBUG 54030 --- [nio-8080-exec-5] o.s.s.w.u.matcher.AntPathRequestMatcher : Request 'GET /login/oauth2/code/github' doesn't match 'POST /logout'
2021-01-24 14:23:04.927 DEBUG 54030 --- [nio-8080-exec-5] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/logout', PUT]
2021-01-24 14:23:04.927 DEBUG 54030 --- [nio-8080-exec-5] o.s.s.w.u.matcher.AntPathRequestMatcher : Request 'GET /login/oauth2/code/github' doesn't match 'PUT /logout'
2021-01-24 14:23:04.927 DEBUG 54030 --- [nio-8080-exec-5] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/logout', DELETE]
2021-01-24 14:23:04.927 DEBUG 54030 --- [nio-8080-exec-5] o.s.s.w.u.matcher.AntPathRequestMatcher : Request 'GET /login/oauth2/code/github' doesn't match 'DELETE /logout'
2021-01-24 14:23:04.927 DEBUG 54030 --- [nio-8080-exec-5] o.s.s.web.util.matcher.OrRequestMatcher : No matches found
2021-01-24 14:23:04.927 DEBUG 54030 --- [nio-8080-exec-5] o.s.security.web.FilterChainProxy : /login/oauth2/code/github?code=c1f037db1df6d99c41bf&state=sdrsuBQum1n9Q_paJv4lYKQt2dbc1QBeFxiTICwju2Q%3D at position 5 of 15 in additional filter chain; firing Filter: 'OAuth2AuthorizationRequestRedirectFilter'
2021-01-24 14:23:04.927 DEBUG 54030 --- [nio-8080-exec-5] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/login/oauth2/code/github'; against '/oauth2/authorization/{registrationId}'
2021-01-24 14:23:04.927 DEBUG 54030 --- [nio-8080-exec-5] org.apache.tomcat.util.http.Parameters : Set encoding to UTF-8
2021-01-24 14:23:04.927 DEBUG 54030 --- [nio-8080-exec-5] org.apache.tomcat.util.http.Parameters : Decoding query null UTF-8
2021-01-24 14:23:04.927 DEBUG 54030 --- [nio-8080-exec-5] org.apache.tomcat.util.http.Parameters : Start processing with input [code=c1f037db1df6d99c41bf&state=sdrsuBQum1n9Q_paJv4lYKQt2dbc1QBeFxiTICwju2Q%3D]
2021-01-24 14:23:04.928 DEBUG 54030 --- [nio-8080-exec-5] o.s.security.web.FilterChainProxy : /login/oauth2/code/github?code=c1f037db1df6d99c41bf&state=sdrsuBQum1n9Q_paJv4lYKQt2dbc1QBeFxiTICwju2Q%3D at position 6 of 15 in additional filter chain; firing Filter: 'OAuth2LoginAuthenticationFilter'
2021-01-24 14:23:04.928 DEBUG 54030 --- [nio-8080-exec-5] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/login/oauth2/code/github'; against '/login/oauth2/code/*'
2021-01-24 14:23:04.928 DEBUG 54030 --- [nio-8080-exec-5] .s.o.c.w.OAuth2LoginAuthenticationFilter : Request is to process authentication
2021-01-24 14:23:04.929 DEBUG 54030 --- [nio-8080-exec-5] .s.o.c.w.OAuth2LoginAuthenticationFilter : Authentication request failed: org.springframework.security.oauth2.core.OAuth2AuthenticationException: [authorization_request_not_found]
org.springframework.security.oauth2.core.OAuth2AuthenticationException: [authorization_request_not_found]
2021-01-24 14:23:04.929 DEBUG 54030 --- [nio-8080-exec-5] .s.o.c.w.OAuth2LoginAuthenticationFilter : Updated SecurityContextHolder to contain null Authentication
2021-01-24 14:23:04.929 DEBUG 54030 --- [nio-8080-exec-5] .s.o.c.w.OAuth2LoginAuthenticationFilter : Delegating to authentication failure handler org.springframework.security.web.authentication.SimpleUrlAuthenticationFailureHandler@6f9321d2
2021-01-24 14:23:04.929 DEBUG 54030 --- [nio-8080-exec-5] .a.SimpleUrlAuthenticationFailureHandler : Redirecting to /login?error
2021-01-24 14:23:04.929 DEBUG 54030 --- [nio-8080-exec-5] o.s.s.web.DefaultRedirectStrategy : Redirecting to '/login?error'
2021-01-24 14:23:04.929 DEBUG 54030 --- [nio-8080-exec-5] o.s.s.w.header.writers.HstsHeaderWriter : Not injecting HSTS header since it did not match the requestMatcher org.springframework.security.web.header.writers.HstsHeaderWriter$SecureRequestMatcher@46149ef2
2021-01-24 14:23:04.930 DEBUG 54030 --- [nio-8080-exec-5] s.s.w.c.SecurityContextPersistenceFilter : SecurityContextHolder now cleared, as request processing completed
2021-01-24 14:23:04.931 DEBUG 54030 --- [nio-8080-exec-5] o.a.tomcat.util.net.SocketWrapperBase : Socket: [org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@30776187:org.apache.tomcat.util.net.NioChannel@3eb099f9:java.nio.channels.SocketChannel[connected local=/127.0.0.1:8080 remote=/127.0.0.1:57773]], Read from buffer: [0]
2021-01-24 14:23:04.931 DEBUG 54030 --- [nio-8080-exec-5] org.apache.tomcat.util.net.NioEndpoint : Socket: [org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@30776187:org.apache.tomcat.util.net.NioChannel@3eb099f9:java.nio.channels.SocketChannel[connected local=/127.0.0.1:8080 remote=/127.0.0.1:57773]], Read direct from socket: [0]
2021-01-24 14:23:04.931 DEBUG 54030 --- [nio-8080-exec-5] o.apache.coyote.http11.Http11Processor : Socket: [org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@30776187:org.apache.tomcat.util.net.NioChannel@3eb099f9:java.nio.channels.SocketChannel[connected local=/127.0.0.1:8080 remote=/127.0.0.1:57773]], Status in: [OPEN_READ], State out: [OPEN]
2021-01-24 14:23:04.931 DEBUG 54030 --- [nio-8080-exec-5] org.apache.tomcat.util.net.NioEndpoint : Registered read interest for [org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@30776187:org.apache.tomcat.util.net.NioChannel@3eb099f9:java.nio.channels.SocketChannel[connected local=/127.0.0.1:8080 remote=/127.0.0.1:57773]]
2021-01-24 14:23:04.935 DEBUG 54030 --- [nio-8080-exec-6] o.a.tomcat.util.net.SocketWrapperBase : Socket: [org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@30776187:org.apache.tomcat.util.net.NioChannel@3eb099f9:java.nio.channels.SocketChannel[connected local=/127.0.0.1:8080 remote=/127.0.0.1:57773]], Read from buffer: [0]
2021-01-24 14:23:04.935 DEBUG 54030 --- [nio-8080-exec-6] org.apache.tomcat.util.net.NioEndpoint : Socket: [org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@30776187:org.apache.tomcat.util.net.NioChannel@3eb099f9:java.nio.channels.SocketChannel[connected local=/127.0.0.1:8080 remote=/127.0.0.1:57773]], Read direct from socket: [686]
2021-01-24 14:23:04.935 DEBUG 54030 --- [nio-8080-exec-6] o.a.coyote.http11.Http11InputBuffer : Received [GET /login?error HTTP/1.1
Host: 127.0.0.1:8080
Connection: keep-alive
Upgrade-Insecure-Requests: 1
User-Agent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36
Accept: text/html,application/xhtml+xml,application/xml;q=0.9,image/avif,image/webp,image/apng,*/*;q=0.8,application/signed-exchange;v=b3;q=0.9
Sec-Fetch-Site: cross-site
Sec-Fetch-Mode: navigate
Sec-Fetch-Dest: document
sec-ch-ua: "Google Chrome";v="87", " Not;A Brand";v="99", "Chromium";v="87"
sec-ch-ua-mobile: ?0
Accept-Encoding: gzip, deflate, br
Accept-Language: en-US,en;q=0.9
Cookie: JSESSIONID=1E6CB48952B6F03D586DC0112BBD2120
]
2021-01-24 14:23:04.936 DEBUG 54030 --- [nio-8080-exec-6] o.a.t.util.http.Rfc6265CookieProcessor : Cookies: Parsing b[]: JSESSIONID=1E6CB48952B6F03D586DC0112BBD2120
2021-01-24 14:23:04.936 DEBUG 54030 --- [nio-8080-exec-6] o.a.catalina.connector.CoyoteAdapter : Requested cookie session id is 1E6CB48952B6F03D586DC0112BBD2120
2021-01-24 14:23:04.936 DEBUG 54030 --- [nio-8080-exec-6] o.a.c.authenticator.AuthenticatorBase : Security checking request GET /login
2021-01-24 14:23:04.936 DEBUG 54030 --- [nio-8080-exec-6] org.apache.catalina.realm.RealmBase : No applicable constraints defined
2021-01-24 14:23:04.936 DEBUG 54030 --- [nio-8080-exec-6] o.a.c.authenticator.AuthenticatorBase : Not subject to any constraint
2021-01-24 14:23:04.936 DEBUG 54030 --- [nio-8080-exec-6] o.s.security.web.FilterChainProxy : /login?error at position 1 of 15 in additional filter chain; firing Filter: 'WebAsyncManagerIntegrationFilter'
2021-01-24 14:23:04.936 DEBUG 54030 --- [nio-8080-exec-6] o.s.security.web.FilterChainProxy : /login?error at position 2 of 15 in additional filter chain; firing Filter: 'SecurityContextPersistenceFilter'
2021-01-24 14:23:04.936 DEBUG 54030 --- [nio-8080-exec-6] o.s.security.web.FilterChainProxy : /login?error at position 3 of 15 in additional filter chain; firing Filter: 'HeaderWriterFilter'
2021-01-24 14:23:04.936 DEBUG 54030 --- [nio-8080-exec-6] o.s.security.web.FilterChainProxy : /login?error at position 4 of 15 in additional filter chain; firing Filter: 'LogoutFilter'
2021-01-24 14:23:04.936 DEBUG 54030 --- [nio-8080-exec-6] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/logout', GET]
2021-01-24 14:23:04.936 DEBUG 54030 --- [nio-8080-exec-6] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/login'; against '/logout'
2021-01-24 14:23:04.936 DEBUG 54030 --- [nio-8080-exec-6] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/logout', POST]
2021-01-24 14:23:04.936 DEBUG 54030 --- [nio-8080-exec-6] o.s.s.w.u.matcher.AntPathRequestMatcher : Request 'GET /login' doesn't match 'POST /logout'
2021-01-24 14:23:04.936 DEBUG 54030 --- [nio-8080-exec-6] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/logout', PUT]
2021-01-24 14:23:04.936 DEBUG 54030 --- [nio-8080-exec-6] o.s.s.w.u.matcher.AntPathRequestMatcher : Request 'GET /login' doesn't match 'PUT /logout'
2021-01-24 14:23:04.936 DEBUG 54030 --- [nio-8080-exec-6] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/logout', DELETE]
2021-01-24 14:23:04.936 DEBUG 54030 --- [nio-8080-exec-6] o.s.s.w.u.matcher.AntPathRequestMatcher : Request 'GET /login' doesn't match 'DELETE /logout'
2021-01-24 14:23:04.936 DEBUG 54030 --- [nio-8080-exec-6] o.s.s.web.util.matcher.OrRequestMatcher : No matches found
2021-01-24 14:23:04.936 DEBUG 54030 --- [nio-8080-exec-6] o.s.security.web.FilterChainProxy : /login?error at position 5 of 15 in additional filter chain; firing Filter: 'OAuth2AuthorizationRequestRedirectFilter'
2021-01-24 14:23:04.936 DEBUG 54030 --- [nio-8080-exec-6] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/login'; against '/oauth2/authorization/{registrationId}'
2021-01-24 14:23:04.937 DEBUG 54030 --- [nio-8080-exec-6] org.apache.tomcat.util.http.Parameters : Set encoding to UTF-8
2021-01-24 14:23:04.937 DEBUG 54030 --- [nio-8080-exec-6] org.apache.tomcat.util.http.Parameters : Decoding query null UTF-8
2021-01-24 14:23:04.937 DEBUG 54030 --- [nio-8080-exec-6] org.apache.tomcat.util.http.Parameters : Start processing with input [error]
2021-01-24 14:23:04.937 DEBUG 54030 --- [nio-8080-exec-6] org.apache.tomcat.util.http.Parameters : Parameter starting at position [0] and ending at position [5] with a value of [error] was not followed by an '=' character
2021-01-24 14:23:04.937 DEBUG 54030 --- [nio-8080-exec-6] o.s.security.web.FilterChainProxy : /login?error at position 6 of 15 in additional filter chain; firing Filter: 'OAuth2LoginAuthenticationFilter'
2021-01-24 14:23:04.937 DEBUG 54030 --- [nio-8080-exec-6] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/login'; against '/login/oauth2/code/*'
2021-01-24 14:23:04.937 DEBUG 54030 --- [nio-8080-exec-6] o.s.security.web.FilterChainProxy : /login?error at position 7 of 15 in additional filter chain; firing Filter: 'JwtRequestFilter'
2021-01-24 14:23:04.937 DEBUG 54030 --- [nio-8080-exec-6] o.s.security.web.FilterChainProxy : /login?error at position 8 of 15 in additional filter chain; firing Filter: 'DefaultLoginPageGeneratingFilter'
2021-01-24 14:23:04.937 DEBUG 54030 --- [nio-8080-exec-6] o.s.s.w.header.writers.HstsHeaderWriter : Not injecting HSTS header since it did not match the requestMatcher org.springframework.security.web.header.writers.HstsHeaderWriter$SecureRequestMatcher@46149ef2
2021-01-24 14:23:04.937 DEBUG 54030 --- [nio-8080-exec-6] s.s.w.c.SecurityContextPersistenceFilter : SecurityContextHolder now cleared, as request processing completed
2021-01-24 14:23:04.938 DEBUG 54030 --- [nio-8080-exec-6] o.a.tomcat.util.net.SocketWrapperBase : Socket: [org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@30776187:org.apache.tomcat.util.net.NioChannel@3eb099f9:java.nio.channels.SocketChannel[connected local=/127.0.0.1:8080 remote=/127.0.0.1:57773]], Read from buffer: [0]
2021-01-24 14:23:04.938 DEBUG 54030 --- [nio-8080-exec-6] org.apache.tomcat.util.net.NioEndpoint : Socket: [org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@30776187:org.apache.tomcat.util.net.NioChannel@3eb099f9:java.nio.channels.SocketChannel[connected local=/127.0.0.1:8080 remote=/127.0.0.1:57773]], Read direct from socket: [0]
2021-01-24 14:23:04.938 DEBUG 54030 --- [nio-8080-exec-6] o.apache.coyote.http11.Http11Processor : Socket: [org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@30776187:org.apache.tomcat.util.net.NioChannel@3eb099f9:java.nio.channels.SocketChannel[connected local=/127.0.0.1:8080 remote=/127.0.0.1:57773]], Status in: [OPEN_READ], State out: [OPEN]
2021-01-24 14:23:04.938 DEBUG 54030 --- [nio-8080-exec-6] org.apache.tomcat.util.net.NioEndpoint : Registered read interest for [org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@30776187:org.apache.tomcat.util.net.NioChannel@3eb099f9:java.nio.channels.SocketChannel[connected local=/127.0.0.1:8080 remote=/127.0.0.1:57773]]
2021-01-24 14:23:05.114 DEBUG 54030 --- [nio-8080-exec-7] o.a.tomcat.util.net.SocketWrapperBase : Socket: [org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@30776187:org.apache.tomcat.util.net.NioChannel@3eb099f9:java.nio.channels.SocketChannel[connected local=/127.0.0.1:8080 remote=/127.0.0.1:57773]], Read from buffer: [0]
2021-01-24 14:23:05.114 DEBUG 54030 --- [nio-8080-exec-7] org.apache.tomcat.util.net.NioEndpoint : Socket: [org.apache.tomcat.util.net.NioEndpoint$NioSocketWrapper@30776187:org.apache.tomcat.util.net.NioChannel@3eb099f9:java.nio.channels.SocketChannel[connected local=/127.0.0.1:8080 remote=/127.0.0.1:57773]], Read direct from socket: [612]
2021-01-24 14:23:05.114 DEBUG 54030 --- [nio-8080-exec-7] o.a.coyote.http11.Http11InputBuffer : Received [GET /favicon.ico HTTP/1.1
Host: 127.0.0.1:8080
Connection: keep-alive
sec-ch-ua: "Google Chrome";v="87", " Not;A Brand";v="99", "Chromium";v="87"
sec-ch-ua-mobile: ?0
User-Agent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36
Accept: image/avif,image/webp,image/apng,image/*,*/*;q=0.8
Sec-Fetch-Site: same-origin
Sec-Fetch-Mode: no-cors
Sec-Fetch-Dest: image
Referer: http://127.0.0.1:8080/login?error
Accept-Encoding: gzip, deflate, br
Accept-Language: en-US,en;q=0.9
Cookie: JSESSIONID=1E6CB48952B6F03D586DC0112BBD2120
]
2021-01-24 14:23:05.115 DEBUG 54030 --- [nio-8080-exec-7] o.a.t.util.http.Rfc6265CookieProcessor : Cookies: Parsing b[]: JSESSIONID=1E6CB48952B6F03D586DC0112BBD2120
2021-01-24 14:23:05.115 DEBUG 54030 --- [nio-8080-exec-7] o.a.catalina.connector.CoyoteAdapter : Requested cookie session id is 1E6CB48952B6F03D586DC0112BBD2120
2021-01-24 14:23:05.116 DEBUG 54030 --- [nio-8080-exec-7] o.a.c.authenticator.AuthenticatorBase : Security checking request GET /favicon.ico
2021-01-24 14:23:05.116 DEBUG 54030 --- [nio-8080-exec-7] org.apache.catalina.realm.RealmBase : No applicable constraints defined
2021-01-24 14:23:05.116 DEBUG 54030 --- [nio-8080-exec-7] o.a.c.authenticator.AuthenticatorBase : Not subject to any constraint
2021-01-24 14:23:05.117 DEBUG 54030 --- [nio-8080-exec-7] o.s.security.web.FilterChainProxy : /favicon.ico at position 1 of 15 in additional filter chain; firing Filter: 'WebAsyncManagerIntegrationFilter'
2021-01-24 14:23:05.117 DEBUG 54030 --- [nio-8080-exec-7] o.s.security.web.FilterChainProxy : /favicon.ico at position 2 of 15 in additional filter chain; firing Filter: 'SecurityContextPersistenceFilter'
2021-01-24 14:23:05.117 DEBUG 54030 --- [nio-8080-exec-7] o.s.security.web.FilterChainProxy : /favicon.ico at position 3 of 15 in additional filter chain; firing Filter: 'HeaderWriterFilter'
2021-01-24 14:23:05.117 DEBUG 54030 --- [nio-8080-exec-7] o.s.security.web.FilterChainProxy : /favicon.ico at position 4 of 15 in additional filter chain; firing Filter: 'LogoutFilter'
2021-01-24 14:23:05.117 DEBUG 54030 --- [nio-8080-exec-7] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/logout', GET]
2021-01-24 14:23:05.117 DEBUG 54030 --- [nio-8080-exec-7] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/favicon.ico'; against '/logout'
2021-01-24 14:23:05.117 DEBUG 54030 --- [nio-8080-exec-7] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/logout', POST]
2021-01-24 14:23:05.117 DEBUG 54030 --- [nio-8080-exec-7] o.s.s.w.u.matcher.AntPathRequestMatcher : Request 'GET /favicon.ico' doesn't match 'POST /logout'
2021-01-24 14:23:05.117 DEBUG 54030 --- [nio-8080-exec-7] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/logout', PUT]
2021-01-24 14:23:05.117 DEBUG 54030 --- [nio-8080-exec-7] o.s.s.w.u.matcher.AntPathRequestMatcher : Request 'GET /favicon.ico' doesn't match 'PUT /logout'
2021-01-24 14:23:05.117 DEBUG 54030 --- [nio-8080-exec-7] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/logout', DELETE]
2021-01-24 14:23:05.117 DEBUG 54030 --- [nio-8080-exec-7] o.s.s.w.u.matcher.AntPathRequestMatcher : Request 'GET /favicon.ico' doesn't match 'DELETE /logout'
2021-01-24 14:23:05.117 DEBUG 54030 --- [nio-8080-exec-7] o.s.s.web.util.matcher.OrRequestMatcher : No matches found
2021-01-24 14:23:05.117 DEBUG 54030 --- [nio-8080-exec-7] o.s.security.web.FilterChainProxy : /favicon.ico at position 5 of 15 in additional filter chain; firing Filter: 'OAuth2AuthorizationRequestRedirectFilter'
2021-01-24 14:23:05.117 DEBUG 54030 --- [nio-8080-exec-7] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/favicon.ico'; against '/oauth2/authorization/{registrationId}'
2021-01-24 14:23:05.117 DEBUG 54030 --- [nio-8080-exec-7] org.apache.tomcat.util.http.Parameters : Set encoding to UTF-8
2021-01-24 14:23:05.117 DEBUG 54030 --- [nio-8080-exec-7] o.s.security.web.FilterChainProxy : /favicon.ico at position 6 of 15 in additional filter chain; firing Filter: 'OAuth2LoginAuthenticationFilter'
2021-01-24 14:23:05.117 DEBUG 54030 --- [nio-8080-exec-7] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/favicon.ico'; against '/login/oauth2/code/*'
2021-01-24 14:23:05.118 DEBUG 54030 --- [nio-8080-exec-7] o.s.security.web.FilterChainProxy : /favicon.ico at position 7 of 15 in additional filter chain; firing Filter: 'JwtRequestFilter'
2021-01-24 14:23:05.118 DEBUG 54030 --- [nio-8080-exec-7] o.s.security.web.FilterChainProxy : /favicon.ico at position 8 of 15 in additional filter chain; firing Filter: 'DefaultLoginPageGeneratingFilter'
2021-01-24 14:23:05.118 DEBUG 54030 --- [nio-8080-exec-7] o.s.security.web.FilterChainProxy : /favicon.ico at position 9 of 15 in additional filter chain; firing Filter: 'DefaultLogoutPageGeneratingFilter'
2021-01-24 14:23:05.118 DEBUG 54030 --- [nio-8080-exec-7] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/favicon.ico'; against '/logout'
2021-01-24 14:23:05.118 DEBUG 54030 --- [nio-8080-exec-7] o.s.security.web.FilterChainProxy : /favicon.ico at position 10 of 15 in additional filter chain; firing Filter: 'RequestCacheAwareFilter'
2021-01-24 14:23:05.118 DEBUG 54030 --- [nio-8080-exec-7] o.s.security.web.FilterChainProxy : /favicon.ico at position 11 of 15 in additional filter chain; firing Filter: 'SecurityContextHolderAwareRequestFilter'
2021-01-24 14:23:05.123 DEBUG 54030 --- [nio-8080-exec-7] o.s.security.web.FilterChainProxy : /favicon.ico at position 12 of 15 in additional filter chain; firing Filter: 'AnonymousAuthenticationFilter'
2021-01-24 14:23:05.125 DEBUG 54030 --- [nio-8080-exec-7] o.s.s.w.a.AnonymousAuthenticationFilter : Populated SecurityContextHolder with anonymous token: 'org.springframework.security.authentication.AnonymousAuthenticationToken@75b1e1c4: Principal: anonymousUser; Credentials: [PROTECTED]; Authenticated: true; Details: org.springframework.security.web.authentication.WebAuthenticationDetails@fffed504: RemoteIpAddress: 127.0.0.1; SessionId: 1E6CB48952B6F03D586DC0112BBD2120; Granted Authorities: ROLE_ANONYMOUS'
2021-01-24 14:23:05.125 DEBUG 54030 --- [nio-8080-exec-7] o.s.security.web.FilterChainProxy : /favicon.ico at position 13 of 15 in additional filter chain; firing Filter: 'SessionManagementFilter'
2021-01-24 14:23:05.126 DEBUG 54030 --- [nio-8080-exec-7] o.s.security.web.FilterChainProxy : /favicon.ico at position 14 of 15 in additional filter chain; firing Filter: 'ExceptionTranslationFilter'
2021-01-24 14:23:05.126 DEBUG 54030 --- [nio-8080-exec-7] o.s.security.web.FilterChainProxy : /favicon.ico at position 15 of 15 in additional filter chain; firing Filter: 'FilterSecurityInterceptor'
2021-01-24 14:23:05.127 DEBUG 54030 --- [nio-8080-exec-7] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/logout', GET]
2021-01-24 14:23:05.127 DEBUG 54030 --- [nio-8080-exec-7] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/favicon.ico'; against '/logout'
2021-01-24 14:23:05.127 DEBUG 54030 --- [nio-8080-exec-7] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/logout', POST]
2021-01-24 14:23:05.127 DEBUG 54030 --- [nio-8080-exec-7] o.s.s.w.u.matcher.AntPathRequestMatcher : Request 'GET /favicon.ico' doesn't match 'POST /logout'
2021-01-24 14:23:05.127 DEBUG 54030 --- [nio-8080-exec-7] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/logout', PUT]
2021-01-24 14:23:05.127 DEBUG 54030 --- [nio-8080-exec-7] o.s.s.w.u.matcher.AntPathRequestMatcher : Request 'GET /favicon.ico' doesn't match 'PUT /logout'
2021-01-24 14:23:05.127 DEBUG 54030 --- [nio-8080-exec-7] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/logout', DELETE]
2021-01-24 14:23:05.127 DEBUG 54030 --- [nio-8080-exec-7] o.s.s.w.u.matcher.AntPathRequestMatcher : Request 'GET /favicon.ico' doesn't match 'DELETE /logout'
2021-01-24 14:23:05.127 DEBUG 54030 --- [nio-8080-exec-7] o.s.s.web.util.matcher.OrRequestMatcher : No matches found
2021-01-24 14:23:05.127 DEBUG 54030 --- [nio-8080-exec-7] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/favicon.ico'; against '/oauth2/**'
2021-01-24 14:23:05.127 DEBUG 54030 --- [nio-8080-exec-7] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/favicon.ico'; against '/auth/authenticate'
2021-01-24 14:23:05.127 DEBUG 54030 --- [nio-8080-exec-7] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/favicon.ico'; against '/api/user/register/**'
2021-01-24 14:23:05.127 DEBUG 54030 --- [nio-8080-exec-7] o.s.s.w.a.i.FilterSecurityInterceptor : Secure object: FilterInvocation: URL: /favicon.ico; Attributes: [authenticated]
2021-01-24 14:23:05.127 DEBUG 54030 --- [nio-8080-exec-7] o.s.s.w.a.i.FilterSecurityInterceptor : Previously Authenticated: org.springframework.security.authentication.AnonymousAuthenticationToken@75b1e1c4: Principal: anonymousUser; Credentials: [PROTECTED]; Authenticated: true; Details: org.springframework.security.web.authentication.WebAuthenticationDetails@fffed504: RemoteIpAddress: 127.0.0.1; SessionId: 1E6CB48952B6F03D586DC0112BBD2120; Granted Authorities: ROLE_ANONYMOUS
2021-01-24 14:23:05.136 DEBUG 54030 --- [nio-8080-exec-7] o.s.s.access.vote.AffirmativeBased : Voter: org.springframework.security.web.access.expression.WebExpressionVoter@610468ed, returned: -1
2021-01-24 14:23:05.138 DEBUG 54030 --- [nio-8080-exec-7] o.s.s.w.a.ExceptionTranslationFilter : Access is denied (user is anonymous); redirecting to authentication entry point
org.springframework.security.access.AccessDeniedException: Access is denied
Actual Behavior
Infinite loop with authorization_request_not_found (eventually getting access denied).
Expected Behavior
Successfully authenticated
Version
spring-boot 2.3.3
spring-security-oauth2 2.3.5
MarounMaroun commented
I figured it out. I was accessing localhost:8080, and the redirect URI was configured to 127.0.0.1.