· 9 years ago · Oct 28, 2016, 11:18 AM
12016-10-28 13:16:48.527 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/css/**']
22016-10-28 13:16:48.534 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/oauth/token'; against '/css/**'
32016-10-28 13:16:48.534 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/js/**']
42016-10-28 13:16:48.534 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/oauth/token'; against '/js/**'
52016-10-28 13:16:48.534 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/images/**']
62016-10-28 13:16:48.534 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/oauth/token'; against '/images/**'
72016-10-28 13:16:48.534 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/webjars/**']
82016-10-28 13:16:48.534 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/oauth/token'; against '/webjars/**'
92016-10-28 13:16:48.534 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/**/favicon.ico']
102016-10-28 13:16:48.534 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/oauth/token'; against '/**/favicon.ico'
112016-10-28 13:16:48.535 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/error']
122016-10-28 13:16:48.535 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/oauth/token'; against '/error'
132016-10-28 13:16:48.535 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.web.util.matcher.OrRequestMatcher : No matches found
142016-10-28 13:16:48.535 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/oauth/token']
152016-10-28 13:16:48.535 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/oauth/token'; against '/oauth/token'
162016-10-28 13:16:48.535 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.web.util.matcher.OrRequestMatcher : matched
172016-10-28 13:16:48.535 DEBUG 89182 --- [nio-8080-exec-2] o.s.security.web.FilterChainProxy : /oauth/token at position 1 of 12 in additional filter chain; firing Filter: 'WebAsyncManagerIntegrationFilter'
182016-10-28 13:16:48.536 DEBUG 89182 --- [nio-8080-exec-2] o.s.security.web.FilterChainProxy : /oauth/token at position 2 of 12 in additional filter chain; firing Filter: 'SecurityContextPersistenceFilter'
192016-10-28 13:16:48.543 DEBUG 89182 --- [nio-8080-exec-2] o.s.security.web.FilterChainProxy : /oauth/token at position 3 of 12 in additional filter chain; firing Filter: 'HeaderWriterFilter'
202016-10-28 13:16:48.554 DEBUG 89182 --- [nio-8080-exec-2] o.s.security.web.FilterChainProxy : /oauth/token at position 4 of 12 in additional filter chain; firing Filter: 'LogoutFilter'
212016-10-28 13:16:48.554 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/logout', GET]
222016-10-28 13:16:48.555 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.w.u.matcher.AntPathRequestMatcher : Request 'POST /oauth/token' doesn't match 'GET /logout
232016-10-28 13:16:48.555 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/logout', POST]
242016-10-28 13:16:48.555 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/oauth/token'; against '/logout'
252016-10-28 13:16:48.555 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/logout', PUT]
262016-10-28 13:16:48.555 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.w.u.matcher.AntPathRequestMatcher : Request 'POST /oauth/token' doesn't match 'PUT /logout
272016-10-28 13:16:48.555 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/logout', DELETE]
282016-10-28 13:16:48.555 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.w.u.matcher.AntPathRequestMatcher : Request 'POST /oauth/token' doesn't match 'DELETE /logout
292016-10-28 13:16:48.555 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.web.util.matcher.OrRequestMatcher : No matches found
302016-10-28 13:16:48.555 DEBUG 89182 --- [nio-8080-exec-2] o.s.security.web.FilterChainProxy : /oauth/token at position 5 of 12 in additional filter chain; firing Filter: 'ClientCredentialsTokenEndpointFilter'
312016-10-28 13:16:48.556 DEBUG 89182 --- [nio-8080-exec-2] p.c.ClientCredentialsTokenEndpointFilter : Request is to process authentication
322016-10-28 13:16:48.557 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.authentication.ProviderManager : Authentication attempt using org.springframework.security.authentication.dao.DaoAuthenticationProvider
332016-10-28 13:16:48.572 DEBUG 89182 --- [nio-8080-exec-2] p.c.ClientCredentialsTokenEndpointFilter : Authentication success. Updating SecurityContextHolder to contain: org.springframework.security.authentication.UsernamePasswordAuthenticationToken@cd68e5d8: Principal: org.springframework.security.core.userdetails.User@8e81ee76: Username: clientapp; Password: [PROTECTED]; Enabled: true; AccountNonExpired: true; credentialsNonExpired: true; AccountNonLocked: true; Granted Authorities: USER_ROLE; Credentials: [PROTECTED]; Authenticated: true; Details: null; Granted Authorities: USER_ROLE
342016-10-28 13:16:48.574 DEBUG 89182 --- [nio-8080-exec-2] o.s.security.web.FilterChainProxy : /oauth/token at position 6 of 12 in additional filter chain; firing Filter: 'BasicAuthenticationFilter'
352016-10-28 13:16:48.575 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.w.a.www.BasicAuthenticationFilter : Basic Authentication Authorization header found for user 'clientapp'
362016-10-28 13:16:48.575 DEBUG 89182 --- [nio-8080-exec-2] o.s.security.web.FilterChainProxy : /oauth/token at position 7 of 12 in additional filter chain; firing Filter: 'RequestCacheAwareFilter'
372016-10-28 13:16:48.576 DEBUG 89182 --- [nio-8080-exec-2] o.s.security.web.FilterChainProxy : /oauth/token at position 8 of 12 in additional filter chain; firing Filter: 'SecurityContextHolderAwareRequestFilter'
382016-10-28 13:16:48.576 DEBUG 89182 --- [nio-8080-exec-2] o.s.security.web.FilterChainProxy : /oauth/token at position 9 of 12 in additional filter chain; firing Filter: 'AnonymousAuthenticationFilter'
392016-10-28 13:16:48.576 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.w.a.AnonymousAuthenticationFilter : SecurityContextHolder not populated with anonymous token, as it already contained: 'org.springframework.security.authentication.UsernamePasswordAuthenticationToken@cd68e5d8: Principal: org.springframework.security.core.userdetails.User@8e81ee76: Username: clientapp; Password: [PROTECTED]; Enabled: true; AccountNonExpired: true; credentialsNonExpired: true; AccountNonLocked: true; Granted Authorities: USER_ROLE; Credentials: [PROTECTED]; Authenticated: true; Details: null; Granted Authorities: USER_ROLE'
402016-10-28 13:16:48.576 DEBUG 89182 --- [nio-8080-exec-2] o.s.security.web.FilterChainProxy : /oauth/token at position 10 of 12 in additional filter chain; firing Filter: 'SessionManagementFilter'
412016-10-28 13:16:48.576 DEBUG 89182 --- [nio-8080-exec-2] s.CompositeSessionAuthenticationStrategy : Delegating to org.springframework.security.web.authentication.session.ChangeSessionIdAuthenticationStrategy@1af82ba8
422016-10-28 13:16:48.577 DEBUG 89182 --- [nio-8080-exec-2] o.s.security.web.FilterChainProxy : /oauth/token at position 11 of 12 in additional filter chain; firing Filter: 'ExceptionTranslationFilter'
432016-10-28 13:16:48.577 DEBUG 89182 --- [nio-8080-exec-2] o.s.security.web.FilterChainProxy : /oauth/token at position 12 of 12 in additional filter chain; firing Filter: 'FilterSecurityInterceptor'
442016-10-28 13:16:48.578 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/oauth/token'; against '/oauth/token'
452016-10-28 13:16:48.578 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.w.a.i.FilterSecurityInterceptor : Secure object: FilterInvocation: URL: /oauth/token; Attributes: [fullyAuthenticated]
462016-10-28 13:16:48.578 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.w.a.i.FilterSecurityInterceptor : Previously Authenticated: org.springframework.security.authentication.UsernamePasswordAuthenticationToken@cd68e5d8: Principal: org.springframework.security.core.userdetails.User@8e81ee76: Username: clientapp; Password: [PROTECTED]; Enabled: true; AccountNonExpired: true; credentialsNonExpired: true; AccountNonLocked: true; Granted Authorities: USER_ROLE; Credentials: [PROTECTED]; Authenticated: true; Details: null; Granted Authorities: USER_ROLE
472016-10-28 13:16:48.580 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.access.vote.AffirmativeBased : Voter: org.springframework.security.web.access.expression.WebExpressionVoter@345c0a12, returned: 1
482016-10-28 13:16:48.580 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.w.a.i.FilterSecurityInterceptor : Authorization successful
492016-10-28 13:16:48.580 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.w.a.i.FilterSecurityInterceptor : RunAsManager did not change Authentication object
502016-10-28 13:16:48.580 DEBUG 89182 --- [nio-8080-exec-2] o.s.security.web.FilterChainProxy : /oauth/token reached end of additional filter chain; proceeding with original chain
512016-10-28 13:16:48.582 DEBUG 89182 --- [nio-8080-exec-2] o.s.b.a.e.mvc.EndpointHandlerMapping : Looking up handler method for path /oauth/token
522016-10-28 13:16:48.586 DEBUG 89182 --- [nio-8080-exec-2] o.s.b.a.e.mvc.EndpointHandlerMapping : Did not find handler method for [/oauth/token]
532016-10-28 13:16:48.588 DEBUG 89182 --- [nio-8080-exec-2] .s.o.p.e.FrameworkEndpointHandlerMapping : Looking up handler method for path /oauth/token
542016-10-28 13:16:48.606 DEBUG 89182 --- [nio-8080-exec-2] .s.o.p.e.FrameworkEndpointHandlerMapping : Returning handler method [public org.springframework.http.ResponseEntity<org.springframework.security.oauth2.common.OAuth2AccessToken> org.springframework.security.oauth2.provider.endpoint.TokenEndpoint.postAccessToken(java.security.Principal,java.util.Map<java.lang.String, java.lang.String>) throws org.springframework.web.HttpRequestMethodNotSupportedException]
552016-10-28 13:16:48.615 DEBUG 89182 --- [nio-8080-exec-2] .o.p.p.ResourceOwnerPasswordTokenGranter : Getting access token for: clientapp
562016-10-28 13:16:48.617 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.authentication.ProviderManager : Authentication attempt using org.springframework.security.authentication.dao.DaoAuthenticationProvider
572016-10-28 13:16:48.618 INFO 89182 --- [nio-8080-exec-2] c.f.b.security.CustomUserDetailsService : 2206510689488224
582016-10-28 13:16:49.122 DEBUG 89182 --- [nio-8080-exec-2] org.hibernate.SQL : select userbo0_.id as id1_3_, userbo0_.created as created2_3_, userbo0_.last_modified as last_mod3_3_, userbo0_.age_range as age_rang4_3_, userbo0_.birthday as birthday5_3_, userbo0_.email as email6_3_, userbo0_.enabled as enabled7_3_, userbo0_.firstname as firstnam8_3_, userbo0_.gender as gender9_3_, userbo0_.language as languag10_3_, userbo0_.last_ip_address as last_ip11_3_, userbo0_.last_login as last_lo12_3_, userbo0_.lastname as lastnam13_3_, userbo0_.logins_failed as logins_14_3_, userbo0_.msisdn as msisdn15_3_, userbo0_.newsletter_activation as newslet16_3_, userbo0_.password as passwor17_3_, userbo0_.sign_in_provider as sign_in18_3_, userbo0_.social_bio as social_19_3_, userbo0_.social_id as social_20_3_, userbo0_.social_link as social_21_3_, userbo0_.token as token22_3_, userbo0_.token_created as token_c23_3_ from users userbo0_ where userbo0_.social_id=?
59Hibernate: select userbo0_.id as id1_3_, userbo0_.created as created2_3_, userbo0_.last_modified as last_mod3_3_, userbo0_.age_range as age_rang4_3_, userbo0_.birthday as birthday5_3_, userbo0_.email as email6_3_, userbo0_.enabled as enabled7_3_, userbo0_.firstname as firstnam8_3_, userbo0_.gender as gender9_3_, userbo0_.language as languag10_3_, userbo0_.last_ip_address as last_ip11_3_, userbo0_.last_login as last_lo12_3_, userbo0_.lastname as lastnam13_3_, userbo0_.logins_failed as logins_14_3_, userbo0_.msisdn as msisdn15_3_, userbo0_.newsletter_activation as newslet16_3_, userbo0_.password as passwor17_3_, userbo0_.sign_in_provider as sign_in18_3_, userbo0_.social_bio as social_19_3_, userbo0_.social_id as social_20_3_, userbo0_.social_link as social_21_3_, userbo0_.token as token22_3_, userbo0_.token_created as token_c23_3_ from users userbo0_ where userbo0_.social_id=?
602016-10-28 13:16:49.233 INFO 89182 --- [nio-8080-exec-2] o.s.s.o.provider.endpoint.TokenEndpoint : Handling error: NestedServletException, Handler dispatch failed; nested exception is java.lang.StackOverflowError
612016-10-28 13:16:49.241 DEBUG 89182 --- [nio-8080-exec-2] 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@44cf89cb
622016-10-28 13:16:49.243 DEBUG 89182 --- [nio-8080-exec-2] o.s.s.w.a.ExceptionTranslationFilter : Chain processed normally
632016-10-28 13:16:49.243 DEBUG 89182 --- [nio-8080-exec-2] s.s.w.c.SecurityContextPersistenceFilter : SecurityContextHolder now cleared, as request processing completed