Ok. One thing at a time. :)
More detailed logs, with log4j.xml set to TRACE
```
2016-07-29 07:14:21,325 TRACE [org.xdi.oxauth.service.SessionStateService] Found session_state cookie: '3cdf831b-edb2-4927-bf67-d526d4093363'
2016-07-29 07:14:21,362 DEBUG [org.xdi.oxauth.service.AppInitializer] Created ldapAuthEntryManager1: org.gluu.site.ldap.persistence.LdapEntryManager@7f7e089a
2016-07-29 07:14:21,376 DEBUG [org.xdi.oxauth.service.external.ExternalAuthenticationService] Executing python 'getPageForStep' authenticator method
2016-07-29 07:14:21,380 TRACE [org.xdi.oxauth.service.SessionStateService] Generated new session, id = 'c4a749a2-a324-40e9-a52a-46db30902bcd', state = 'unauthenticated', persisted = 'false'
2016-07-29 07:14:21,463 TRACE [xdi.oxauth.authorize.ws.rs.AuthorizeAction] Session 'c4a749a2-a324-40e9-a52a-46db30902bcd' persisted to LDAP
2016-07-29 07:14:21,577 TRACE [org.xdi.oxauth.service.SessionStateService] Found session_state cookie: 'c4a749a2-a324-40e9-a52a-46db30902bcd'
2016-07-29 07:14:21,602 TRACE [org.xdi.oxauth.service.SessionStateService] Try to get session by id: c4a749a2-a324-40e9-a52a-46db30902bcd ...
2016-07-29 07:14:21,602 TRACE [org.xdi.oxauth.service.SessionStateService] Session dn: uniqueIdentifier=c4a749a2-a324-40e9-a52a-46db30902bcd,ou=session,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu
2016-07-29 07:14:21,609 DEBUG [org.xdi.oxauth.service.external.ExternalAuthenticationService] Validating auth_mode: 'internal'
2016-07-29 07:14:21,610 DEBUG [org.xdi.oxauth.service.external.ExternalAuthenticationService] Executing python 'isValidAuthenticationMethod' authenticator method
2016-07-29 07:14:21,612 DEBUG [org.xdi.oxauth.service.external.ExternalAuthenticationService] Executing python 'prepareForStep' authenticator method
2016-07-29 07:14:21,613 DEBUG [org.xdi.oxauth.service.external.ExternalAuthenticationService] Executing python 'getExtraParametersForStep' authenticator method
2016-07-29 07:14:22,635 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C
2016-07-29 07:14:22,638 TRACE [org.xdi.oxauth.service.ClientService] Get client from cache by Dn 'inum=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C,ou=clients,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu'
2016-07-29 07:14:22,639 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C
2016-07-29 07:14:22,642 TRACE [org.xdi.oxauth.service.ClientService] Get client from cache by Dn 'inum=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C,ou=clients,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu'
2016-07-29 07:14:22,642 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C
2016-07-29 07:14:22,645 TRACE [org.xdi.oxauth.service.ClientService] Get client from cache by Dn 'inum=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C,ou=clients,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu'
2016-07-29 07:14:22,645 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C
2016-07-29 07:14:22,648 TRACE [org.xdi.oxauth.service.ClientService] Get client from cache by Dn 'inum=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C,ou=clients,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu'
2016-07-29 07:14:22,648 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C
2016-07-29 07:14:22,651 TRACE [org.xdi.oxauth.service.ClientService] Get client from cache by Dn 'inum=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C,ou=clients,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu'
2016-07-29 07:14:22,651 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C
2016-07-29 07:14:22,705 TRACE [org.xdi.oxauth.service.ClientService] Get client from cache by Dn 'inum=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C,ou=clients,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu'
2016-07-29 07:14:22,706 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C
2016-07-29 07:14:22,717 TRACE [org.xdi.oxauth.service.ClientService] Get client from cache by Dn 'inum=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C,ou=clients,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu'
2016-07-29 07:14:22,717 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C
2016-07-29 07:14:35,194 TRACE [org.xdi.oxauth.service.SessionStateService] Found session_state cookie: 'c4a749a2-a324-40e9-a52a-46db30902bcd'
2016-07-29 07:14:35,207 TRACE [org.xdi.oxauth.service.SessionStateService] Try to get session by id: c4a749a2-a324-40e9-a52a-46db30902bcd ...
2016-07-29 07:14:35,207 TRACE [org.xdi.oxauth.service.SessionStateService] Session dn: uniqueIdentifier=c4a749a2-a324-40e9-a52a-46db30902bcd,ou=session,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu
2016-07-29 07:14:35,210 DEBUG [org.xdi.oxauth.service.external.ExternalAuthenticationService] Executing python 'authenticate' authenticator method
2016-07-29 07:14:35,218 DEBUG [org.xdi.oxauth.service.AuthenticationService] Authenticating user with LDAP: username: test
2016-07-29 07:14:35,240 DEBUG [org.xdi.oxauth.service.AuthenticationService] Attempting to find userDN by primary key: 'uid' and key value: 'test'
2016-07-29 07:14:35,241 DEBUG [org.xdi.oxauth.service.AuthenticationService] Getting user information from LDAP: attributeName = 'uid', attributeValue = 'test'
2016-07-29 07:14:35,282 DEBUG [org.xdi.oxauth.service.AuthenticationService] Found '1' entries
2016-07-29 07:14:35,291 DEBUG [org.xdi.oxauth.service.AuthenticationService] Attempting to authenticate userDN: inum=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0000!CF07.8FEE,ou=people,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu
2016-07-29 07:14:35,295 DEBUG [org.xdi.oxauth.service.AuthenticationService] User authenticated: inum=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0000!CF07.8FEE,ou=people,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu
2016-07-29 07:14:35,295 DEBUG [org.xdi.oxauth.service.AuthenticationService] Attempting to find userDN by local primary key: uid
2016-07-29 07:14:35,297 DEBUG [org.xdi.oxauth.service.UserService] Getting user information from LDAP: attributeName = 'uid', attributeValue = 'test'
2016-07-29 07:14:35,306 DEBUG [org.xdi.oxauth.service.UserService] Found '1' entries
2016-07-29 07:14:35,331 DEBUG [org.xdi.oxauth.auth.Authenticator] Authentication result for user 'test'. auth_step: '1', result: 'true'
2016-07-29 07:14:35,333 DEBUG [org.xdi.oxauth.service.external.ExternalAuthenticationService] Executing python 'getCountAuthenticationSteps' authenticator method
2016-07-29 07:14:35,371 TRACE [org.xdi.oxauth.service.SessionStateService] Authenticated session, id = 'c4a749a2-a324-40e9-a52a-46db30902bcd', state = 'authenticated', persisted = 'true'
2016-07-29 07:14:35,373 DEBUG [org.xdi.oxauth.auth.Authenticator] Sending event to trigger user redirection: 'test'
2016-07-29 07:14:35,377 INFO [org.xdi.oxauth.service.AuthenticationService] Attempting to redirect user. SessionUser: SessionState, dn='uniqueIdentifier=c4a749a2-a324-40e9-a52a-46db30902bcd,ou=session,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu', id='c4a749a2-a324-40e9-a52a-46db30902bcd', lastUsedAt=Fri Jul 29 07:14:35 UTC 2016, userDn='inum=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0000!CF07.8FEE,ou=people,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu', authenticationTime=Fri Jul 29 07:14:35 UTC 2016, state=authenticated, permissionGranted=null, permissionGrantedMap=null, sessionAttributes={response_type=code id_token token, scope=openid email, redirect_uri=https://360.lobosstudios.com/callback, nonce=n-0S6_WzA2Mj, state=af0ifjsldkj, auth_step=1, client_id=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C, acr=internal, acr_values=}, persisted=true}
2016-07-29 07:14:35,383 INFO [org.xdi.oxauth.service.AuthenticationService] Attempting to redirect user. User: org.xdi.oxauth.model.common.User@2c837b0b
2016-07-29 07:14:35,384 TRACE [org.xdi.oxauth.service.AuthenticationService] Logged in successfully! User: org.xdi.oxauth.model.common.User@2c837b0b, page: /authorize.xhtml, map: {scope=openid email, response_type=code id_token token, nonce=n-0S6_WzA2Mj, redirect_uri=https://360.lobosstudios.com/callback, state=af0ifjsldkj, client_id=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C, acr_values=}
2016-07-29 07:14:35,429 INFO [org.xdi.oxauth.auth.Authenticator] Authentication success for User: 'test'
2016-07-29 07:14:35,430 TRACE [org.xdi.oxauth.auth.Authenticator] Authentication successfully for 'test'
2016-07-29 07:14:35,599 TRACE [org.xdi.oxauth.service.SessionStateService] Found session_state cookie: 'c4a749a2-a324-40e9-a52a-46db30902bcd'
2016-07-29 07:14:35,631 TRACE [org.xdi.oxauth.service.SessionStateService] Try to get session by id: c4a749a2-a324-40e9-a52a-46db30902bcd ...
2016-07-29 07:14:35,632 TRACE [org.xdi.oxauth.service.SessionStateService] Session dn: uniqueIdentifier=c4a749a2-a324-40e9-a52a-46db30902bcd,ou=session,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu
2016-07-29 07:14:35,639 TRACE [org.xdi.oxauth.service.ClientService] Get client from cache by Dn 'inum=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C,ou=clients,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu'
2016-07-29 07:14:35,640 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C
2016-07-29 07:14:35,643 TRACE [org.xdi.oxauth.service.ClientService] Get client from cache by Dn 'inum=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C,ou=clients,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu'
2016-07-29 07:14:35,643 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C
2016-07-29 07:14:35,644 DEBUG [org.xdi.oxauth.service.RedirectionUriService] Validating redirection URI: clientIdentifier = @!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C, redirectionUri = https://360.lobosstudios.com/callback, found = 1
2016-07-29 07:14:35,645 DEBUG [org.xdi.oxauth.service.RedirectionUriService] Comparing https://360.lobosstudios.com/callback == https://360.lobosstudios.com/callback
2016-07-29 07:14:35,660 TRACE [xdi.oxauth.authorize.ws.rs.AuthorizeAction] checkPermissionGranted, user = org.xdi.oxauth.model.common.User@f2b52bd
2016-07-29 07:14:35,703 TRACE [org.xdi.oxauth.service.ClientService] Get client from cache by Dn 'inum=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C,ou=clients,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu'
2016-07-29 07:14:35,704 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C
2016-07-29 07:14:35,869 TRACE [xdi.oxauth.authorize.ws.rs.AuthorizeAction] permissionGranted, redirectTo: seam/resource/restv1/oxauth/authorize?scope=openid+email&response_type=code+id_token+token&nonce=n-0S6_WzA2Mj&redirect_uri=https%3A%2F%2F360.lobosstudios.com%2Fcallback&state=af0ifjsldkj&client_id=%40%212920.FAD2.FB38.9BC5%210001%219227.0E3D%210008%219EC6.7B6C
2016-07-29 07:14:36,020 TRACE [org.xdi.oxauth.service.SessionStateService] Found session_state cookie: 'c4a749a2-a324-40e9-a52a-46db30902bcd'
2016-07-29 07:14:36,059 TRACE [org.xdi.oxauth.service.SessionStateService] Try to get session by id: c4a749a2-a324-40e9-a52a-46db30902bcd ...
2016-07-29 07:14:36,060 TRACE [org.xdi.oxauth.service.SessionStateService] Session dn: uniqueIdentifier=c4a749a2-a324-40e9-a52a-46db30902bcd,ou=session,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu
2016-07-29 07:14:36,091 TRACE [org.xdi.oxauth.service.SessionStateService] Try to get session by id: c4a749a2-a324-40e9-a52a-46db30902bcd ...
2016-07-29 07:14:36,091 TRACE [org.xdi.oxauth.service.SessionStateService] Session dn: uniqueIdentifier=c4a749a2-a324-40e9-a52a-46db30902bcd,ou=session,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu
2016-07-29 07:14:36,093 TRACE [org.xdi.oxauth.auth.Authenticator] authenticateBySessionState, sessionState = 'c4a749a2-a324-40e9-a52a-46db30902bcd', session = 'SessionState, dn='uniqueIdentifier=c4a749a2-a324-40e9-a52a-46db30902bcd,ou=session,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu', id='c4a749a2-a324-40e9-a52a-46db30902bcd', lastUsedAt=Fri Jul 29 07:14:35 UTC 2016, userDn='inum=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0000!CF07.8FEE,ou=people,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu', authenticationTime=Fri Jul 29 07:14:35 UTC 2016, state=authenticated, permissionGranted=null, permissionGrantedMap=org.xdi.oxauth.model.common.SessionIdAccessMap@781798b6, sessionAttributes={response_type=code id_token token, scope=openid email, redirect_uri=https://360.lobosstudios.com/callback, nonce=n-0S6_WzA2Mj, state=af0ifjsldkj, auth_step=1, client_id=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C, acr=internal, acr_values=}, persisted=false}', state= 'authenticated'
2016-07-29 07:14:36,170 TRACE [org.xdi.oxauth.auth.AuthenticationFilter] Process Session Auth, sessionState = c4a749a2-a324-40e9-a52a-46db30902bcd, requireAuth = false
2016-07-29 07:14:36,207 DEBUG [xdi.oxauth.authorize.ws.rs.AuthorizeRestWebServiceImpl] Attempting to request authorization: responseType = code id_token token, clientId = @!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C, scope = openid email, redirectUri = https://360.lobosstudios.com/callback, nonce = n-0S6_WzA2Mj, state = af0ifjsldkj, request = null, isSecure = true, requestSessionState = null, sessionState = null
2016-07-29 07:14:36,213 DEBUG [xdi.oxauth.authorize.ws.rs.AuthorizeRestWebServiceImpl] Attempting to request authorization: acrValues = null, amrValues = null, originHeaders = null, codeChallenge = {5}, codeChallengeMethod = {6}
2016-07-29 07:14:36,222 TRACE [org.xdi.oxauth.service.ClientService] Get client from cache by Dn 'inum=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C,ou=clients,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu'
2016-07-29 07:14:36,229 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C
2016-07-29 07:14:36,237 DEBUG [org.xdi.oxauth.model.authorize.ScopeChecker] Checking scopes policy for: openid email
2016-07-29 07:14:36,293 DEBUG [org.xdi.oxauth.model.authorize.ScopeChecker] Granted scopes: [email, openid]
2016-07-29 07:14:36,295 TRACE [org.xdi.oxauth.service.ClientService] Get client from cache by Dn 'inum=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C,ou=clients,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu'
2016-07-29 07:14:36,296 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C
2016-07-29 07:14:36,296 DEBUG [org.xdi.oxauth.service.RedirectionUriService] Validating redirection URI: clientIdentifier = @!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C, redirectionUri = https://360.lobosstudios.com/callback, found = 1
2016-07-29 07:14:36,297 DEBUG [org.xdi.oxauth.service.RedirectionUriService] Comparing https://360.lobosstudios.com/callback == https://360.lobosstudios.com/callback
2016-07-29 07:14:36,437 TRACE [org.xdi.oxauth.model.common.AuthorizationGrant] Saving grant: 6203d26c-bf9c-498f-806f-9bbaeb5e73d3, code_challenge: null
2016-07-29 07:14:36,824 TRACE [org.xdi.oxauth.model.common.AuthorizationGrant] Saving grant: 6203d26c-bf9c-498f-806f-9bbaeb5e73d3, code_challenge: null
2016-07-29 07:14:36,837 TRACE [org.xdi.oxauth.model.common.AuthorizationGrant] Saving grant: 6203d26c-bf9c-498f-806f-9bbaeb5e73d3, code_challenge: null
2016-07-29 07:14:36,842 TRACE [org.xdi.oxauth.model.common.AuthorizationGrant] Saving grant: 6203d26c-bf9c-498f-806f-9bbaeb5e73d3, code_challenge: null
2016-07-29 07:14:36,854 DEBUG [org.xdi.oxauth.model.authorize.ScopeChecker] Checking scopes policy for: openid email
2016-07-29 07:14:36,868 DEBUG [org.xdi.oxauth.model.authorize.ScopeChecker] Granted scopes: [email, openid]
2016-07-29 07:14:36,884 TRACE [org.xdi.oxauth.model.common.AuthorizationGrant] Saving grant: 6203d26c-bf9c-498f-806f-9bbaeb5e73d3, code_challenge: null
2016-07-29 07:14:36,890 TRACE [org.xdi.oxauth.model.common.AuthorizationGrant] Saving grant: 6203d26c-bf9c-498f-806f-9bbaeb5e73d3, code_challenge: null
2016-07-29 07:14:36,896 TRACE [org.xdi.oxauth.model.common.AuthorizationGrant] Saving grant: 6203d26c-bf9c-498f-806f-9bbaeb5e73d3, code_challenge: null
2016-07-29 07:14:37,991 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C
2016-07-29 07:14:37,995 DEBUG [org.xdi.oxauth.service.ClientService] Authenticating Client with LDAP: clientId = @!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C
2016-07-29 07:14:37,996 TRACE [org.xdi.oxauth.service.ClientService] Get client from cache by Dn 'inum=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C,ou=clients,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu'
2016-07-29 07:14:37,996 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C
2016-07-29 07:14:38,000 TRACE [org.xdi.oxauth.service.ClientService] Get client from cache by Dn 'inum=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C,ou=clients,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu'
2016-07-29 07:14:38,000 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C
2016-07-29 07:14:38,019 INFO [org.xdi.oxauth.auth.Authenticator] Authentication success for Client: '@!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C'
2016-07-29 07:14:38,019 TRACE [org.xdi.oxauth.auth.Authenticator] Authentication successfully for '@!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C'
2016-07-29 07:14:38,028 DEBUG [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] Attempting to request access token: grantType = authorization_code, code = 17cf4a7e-36be-4829-aa4b-e519e1802638, redirectUri = https://360.lobosstudios.com/callback, username = null, refreshToken = null, clientId = null, ExtraParams = {scope=[Ljava.lang.String;@661f76d9, grant_type=[Ljava.lang.String;@1bb3d4f0, redirect_uri=[Ljava.lang.String;@3b60e6c5, code=[Ljava.lang.String;@56ceadf7}, isSecure = true, codeVerifier = null
2016-07-29 07:14:38,041 DEBUG [org.xdi.oxauth.service.UserService] Getting user information from LDAP: userId = test
2016-07-29 07:14:38,046 DEBUG [org.xdi.oxauth.service.UserService] Found 1 entries for user id = test
2016-07-29 07:14:38,061 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C
2016-07-29 07:14:38,068 TRACE [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] PKCE validation, code_verifier: null, code_challenge: null, method: null
2016-07-29 07:14:38,092 DEBUG [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] Issuing access token: 29e1bcb4-f728-4a41-95a1-ac2585bdb29e
2016-07-29 07:14:38,113 DEBUG [org.xdi.oxauth.model.authorize.ScopeChecker] Checking scopes policy for: openid email
2016-07-29 07:14:38,133 DEBUG [org.xdi.oxauth.model.authorize.ScopeChecker] Granted scopes: [email, openid]
2016-07-29 07:14:38,161 TRACE [org.xdi.oxauth.model.common.AuthorizationGrant] Saving grant: 6203d26c-bf9c-498f-806f-9bbaeb5e73d3, code_challenge: null
2016-07-29 07:14:38,175 TRACE [org.xdi.oxauth.model.common.AuthorizationGrant] Saving grant: 6203d26c-bf9c-498f-806f-9bbaeb5e73d3, code_challenge: null
2016-07-29 07:14:38,186 TRACE [org.xdi.oxauth.model.common.AuthorizationGrant] Saving grant: 6203d26c-bf9c-498f-806f-9bbaeb5e73d3, code_challenge: null
2016-07-29 07:14:38,202 TRACE [org.xdi.oxauth.model.common.AuthorizationGrant] Saving grant: 6203d26c-bf9c-498f-806f-9bbaeb5e73d3, code_challenge: null
2016-07-29 07:14:38,219 TRACE [org.xdi.oxauth.model.common.AuthorizationGrant] Saving grant: 6203d26c-bf9c-498f-806f-9bbaeb5e73d3, code_challenge: null
2016-07-29 07:14:38,248 TRACE [org.xdi.oxauth.service.AttributeService] Get attribute from cache by Dn 'inum=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0005!8F88,ou=attributes,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu'
2016-07-29 07:14:38,249 TRACE [org.xdi.oxauth.service.AttributeService] Get attribute from cache by Dn 'inum=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0005!CAE3,ou=attributes,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu'
2016-07-29 07:14:38,274 TRACE [org.xdi.oxauth.service.AttributeService] Get attribute from cache by Dn 'inum=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0005!29DA,ou=attributes,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu'
2016-07-29 07:14:38,417 TRACE [org.xdi.oxauth.model.common.AuthorizationGrant] Saving grant: 6203d26c-bf9c-498f-806f-9bbaeb5e73d3, code_challenge: null
2016-07-29 07:14:38,430 TRACE [org.xdi.oxauth.model.common.AuthorizationGrant] Saving grant: 6203d26c-bf9c-498f-806f-9bbaeb5e73d3, code_challenge: null
2016-07-29 07:14:38,443 TRACE [org.xdi.oxauth.model.common.AuthorizationGrant] Saving grant: 6203d26c-bf9c-498f-806f-9bbaeb5e73d3, code_challenge: null
2016-07-29 07:14:38,456 TRACE [org.xdi.oxauth.model.common.AuthorizationGrant] Saving grant: 6203d26c-bf9c-498f-806f-9bbaeb5e73d3, code_challenge: null
2016-07-29 07:14:38,470 TRACE [org.xdi.oxauth.model.common.AuthorizationGrant] Saving grant: 6203d26c-bf9c-498f-806f-9bbaeb5e73d3, code_challenge: null
2016-07-29 07:14:38,482 TRACE [org.xdi.oxauth.model.common.AuthorizationGrant] Saving grant: 6203d26c-bf9c-498f-806f-9bbaeb5e73d3, code_challenge: null
2016-07-29 07:14:38,559 TRACE [org.xdi.oxauth.service.GrantService] Removed token, code: 17cf4a7e-36be-4829-aa4b-e519e1802638
2016-07-29 07:14:38,619 DEBUG [xdi.oxauth.jwk.ws.rs.JwkRestWebServiceImpl] Attempting to request JWK, Is Secure = true
2016-07-29 07:15:16,046 DEBUG [xdi.oxauth.session.ws.rs.EndSessionRestWebServiceImpl] Attempting to end session, idTokenHint: eyJ0eXAiOiJKV1QiLCJhbGciOiJSUzI1NiIsImtpZCI6IjZjZDBkNzY5LTMxNWQtNDVmOS04Nzk2LTczODI0M2VjOWQ0ZiJ9.eyJpc3MiOiJodHRwczovL3Nzby5sb2Jvc3N0dWRpb3MuY29tIiwiYXVkIjoiQCEyOTIwLkZBRDIuRkIzOC45QkM1ITAwMDEhOTIyNy4wRTNEITAwMDghOUVDNi43QjZDIiwiZXhwIjoxNDY5NzgwMDc4LCJpYXQiOjE0Njk3NzY0NzgsIm5vbmNlIjoibi0wUzZfV3pBMk1qIiwiYXV0aF90aW1lIjoxNDY5Nzc2NDc1LCJhdF9oYXNoIjoiV0ZCSTBFcE5NM19TVzhrRTZOTVR4QSIsIm94VmFsaWRhdGlvblVSSSI6Imh0dHBzOi8vc3NvLmxvYm9zc3R1ZGlvcy5jb20vb3hhdXRoL29waWZyYW1lIiwib3hPcGVuSURDb25uZWN0VmVyc2lvbiI6Im9wZW5pZGNvbm5lY3QtMS4wIiwiZW1haWwiOiJzdGV2ZUBsb2Jvc3N0dWRpb3MuY29tIiwiaW51bSI6IkAhMjkyMC5GQUQyLkZCMzguOUJDNSEwMDAxITkyMjcuMEUzRCEwMDAwIUNGMDcuOEZFRSIsInN1YiI6ImE5NmJkNTczLWI3ODUtNGU3MC05MTViLTkzZTU3YzBiYmE1MCJ9.WOz2bG6PaZwMoFEpL0d73EyuXFsPFkF2ynlUFpykoGlp90KBSn6d9K3F87JJIwy3Z4cHTH8yw3lcOebwx8Pj5aHO1ARyDoi0hwQoBi1mwPkYcyEtkUz2aaZoIXVNVAVqSVlsmJgKMQP-sm3hSOyMiXWahPFVY-60pyGa4T6eXmpy2JIAwEBLgZ9dW8WhAfbcOcegWzktaSkwLUDKfjRsfFTcgofZbLTqAl3whUAyGQ7AmyBfYfNUZ2TUoKao0siVUMA6v9n9umjfx6FjzJ15AZezMkU6LuQ87mWWszy8DmRqvnNjVfefGrkcVL2KWurNg3G0eOEZ4-MB383LVC-Wwg, postLogoutRedirectUri: https://360.lobosstudios.com/end_session, sessionState: null, Is Secure = true
2016-07-29 07:15:16,058 DEBUG [org.xdi.oxauth.service.UserService] Getting user information from LDAP: userId = test
2016-07-29 07:15:16,065 DEBUG [org.xdi.oxauth.service.UserService] Found 1 entries for user id = test
2016-07-29 07:15:16,074 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C
2016-07-29 07:15:16,075 TRACE [org.xdi.oxauth.service.SessionStateService] Found session_state cookie: 'c4a749a2-a324-40e9-a52a-46db30902bcd'
2016-07-29 07:15:16,086 TRACE [org.xdi.oxauth.service.SessionStateService] Try to get session by id: c4a749a2-a324-40e9-a52a-46db30902bcd ...
2016-07-29 07:15:16,087 TRACE [org.xdi.oxauth.service.SessionStateService] Session dn: uniqueIdentifier=c4a749a2-a324-40e9-a52a-46db30902bcd,ou=session,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu
2016-07-29 07:15:16,140 TRACE [org.xdi.oxauth.service.GrantService] Removed token, code: eyJ0eXAiOiJKV1QiLCJhbGciOiJSUzI1NiIsImtpZCI6IjZjZDBkNzY5LTMxNWQtNDVmOS04Nzk2LTczODI0M2VjOWQ0ZiJ9.eyJpc3MiOiJodHRwczovL3Nzby5sb2Jvc3N0dWRpb3MuY29tIiwiYXVkIjoiQCEyOTIwLkZBRDIuRkIzOC45QkM1ITAwMDEhOTIyNy4wRTNEITAwMDghOUVDNi43QjZDIiwiZXhwIjoxNDY5NzgwMDc4LCJpYXQiOjE0Njk3NzY0NzgsIm5vbmNlIjoibi0wUzZfV3pBMk1qIiwiYXV0aF90aW1lIjoxNDY5Nzc2NDc1LCJhdF9oYXNoIjoiV0ZCSTBFcE5NM19TVzhrRTZOTVR4QSIsIm94VmFsaWRhdGlvblVSSSI6Imh0dHBzOi8vc3NvLmxvYm9zc3R1ZGlvcy5jb20vb3hhdXRoL29waWZyYW1lIiwib3hPcGVuSURDb25uZWN0VmVyc2lvbiI6Im9wZW5pZGNvbm5lY3QtMS4wIiwiZW1haWwiOiJzdGV2ZUBsb2Jvc3N0dWRpb3MuY29tIiwiaW51bSI6IkAhMjkyMC5GQUQyLkZCMzguOUJDNSEwMDAxITkyMjcuMEUzRCEwMDAwIUNGMDcuOEZFRSIsInN1YiI6ImE5NmJkNTczLWI3ODUtNGU3MC05MTViLTkzZTU3YzBiYmE1MCJ9.WOz2bG6PaZwMoFEpL0d73EyuXFsPFkF2ynlUFpykoGlp90KBSn6d9K3F87JJIwy3Z4cHTH8yw3lcOebwx8Pj5aHO1ARyDoi0hwQoBi1mwPkYcyEtkUz2aaZoIXVNVAVqSVlsmJgKMQP-sm3hSOyMiXWahPFVY-60pyGa4T6eXmpy2JIAwEBLgZ9dW8WhAfbcOcegWzktaSkwLUDKfjRsfFTcgofZbLTqAl3whUAyGQ7AmyBfYfNUZ2TUoKao0siVUMA6v9n9umjfx6FjzJ15AZezMkU6LuQ87mWWszy8DmRqvnNjVfefGrkcVL2KWurNg3G0eOEZ4-MB383LVC-Wwg
2016-07-29 07:15:16,155 TRACE [org.xdi.oxauth.service.GrantService] Removed token, code: a9c43d0f-c0a2-42a4-b560-9d718a2a66bf
2016-07-29 07:15:16,172 TRACE [org.xdi.oxauth.service.GrantService] Removed token, code: 29e1bcb4-f728-4a41-95a1-ac2585bdb29e
2016-07-29 07:15:16,190 TRACE [org.xdi.oxauth.service.GrantService] Removed token, code: 12016b9c-0a53-4227-9576-0c721eb904b1
2016-07-29 07:15:16,204 TRACE [org.xdi.oxauth.service.GrantService] Removed token, code: eyJ0eXAiOiJKV1QiLCJhbGciOiJSUzI1NiIsImtpZCI6IjZjZDBkNzY5LTMxNWQtNDVmOS04Nzk2LTczODI0M2VjOWQ0ZiJ9.eyJpc3MiOiJodHRwczovL3Nzby5sb2Jvc3N0dWRpb3MuY29tIiwiYXVkIjoiQCEyOTIwLkZBRDIuRkIzOC45QkM1ITAwMDEhOTIyNy4wRTNEITAwMDghOUVDNi43QjZDIiwiZXhwIjoxNDY5NzgwMDc2LCJpYXQiOjE0Njk3NzY0NzYsIm5vbmNlIjoibi0wUzZfV3pBMk1qIiwiYXV0aF90aW1lIjoxNDY5Nzc2NDc1LCJjX2hhc2giOiJjSm4xYjZwbmdtTV9VVWZCWjF4S2pRIiwiYXRfaGFzaCI6ImJVTlNtcTlWeGN6ZGJKb29tT3BDekEiLCJveFZhbGlkYXRpb25VUkkiOiJodHRwczovL3Nzby5sb2Jvc3N0dWRpb3MuY29tL294YXV0aC9vcGlmcmFtZSIsIm94T3BlbklEQ29ubmVjdFZlcnNpb24iOiJvcGVuaWRjb25uZWN0LTEuMCIsImVtYWlsIjoic3RldmVAbG9ib3NzdHVkaW9zLmNvbSIsImludW0iOiJAITI5MjAuRkFEMi5GQjM4LjlCQzUhMDAwMSE5MjI3LjBFM0QhMDAwMCFDRjA3LjhGRUUiLCJzdWIiOiJhOTZiZDU3My1iNzg1LTRlNzAtOTE1Yi05M2U1N2MwYmJhNTAifQ.IJiMpyVGKYPldLmY9pOsL1X2IUh-teanpZwwXBwHvyS5yz55slzBKH5lL2Do1gB1PKAUBL_Xcykbpz0DRDFWjSfAOFrtNEu2UXCUmr0nuNVZNx_b-Q7Wxq3TygzD2HDGLg0cXnFL9qXbxc017jPJ-P95WwmU_w7l9iTmMByvxuQ5_sAgE2DdJoCYWerdmnlalW0UWxLCpDQFrEKQ09nGVFYf1BwH6M1evPzEbw0YPvWt_sfSEwAqiVkutHErEDzqirLe1SQFcwFgfmqmPRmePm_2-UfnRkDa9hxXPCzwkoyLNIMWPo4s7zxpj_OaFplPB7Y6at05EhOnIwQCRI0bSg
2016-07-29 07:15:16,207 TRACE [org.xdi.oxauth.service.ClientService] Get client from cache by Dn 'inum=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C,ou=clients,o=@!2920.FAD2.FB38.9BC5!0001!9227.0E3D,o=gluu'
2016-07-29 07:15:16,207 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!2920.FAD2.FB38.9BC5!0001!9227.0E3D!0008!9EC6.7B6C
2016-07-29 07:15:16,208 DEBUG [org.xdi.oxauth.model.error.ErrorResponseFactory] Looking for the error with id: invalid_request
2016-07-29 07:15:16,208 DEBUG [org.xdi.oxauth.model.error.ErrorResponseFactory] Found error, id: invalid_request
```