By: Martin Petri user 11 Mar 2021 at 5:32 a.m. CST

3 Responses
Martin Petri gravatar
Hey guys. We have casa and FIDO/OTP in place. When we try to log in using Safari (version >=14.0) we get this: HTTP ERROR 500 java.lang.NullPointerException URI: /oxauth/restv1/authorize STATUS: 500 MESSAGE: java.lang.NullPointerException SERVLET: org.gluu.oxauth.service.ResteasyInitializer CAUSED BY: java.lang.NullPointerException Only when using Safari. Every other browser works perfectly. The problem occurs irrespective of whether or not a user has enabled 2FA. As you can see in the jetty log it happens right after the redirection to /oxauth/restv1/authorize Maybe the warning right before the NPE is a hint(?) WARN:oejs.HttpChannel:qtp790067787-200154 Thx for your help! Cheers, Martin

By Martin Petri user 11 Mar 2021 at 5:33 a.m. CST

Martin Petri gravatar
Jetty log (trace level): ``` 2021-03-11 10:43:25,037 DEBUG [qtp790067787-200154] [org.gluu.service.ExternalResourceHandler] (ExternalResourceHandler.java:68) - Found overriden resource: /casa/login.xhtml 2021-03-11 10:43:25,038 DEBUG [qtp790067787-200154] [org.gluu.service.ExternalResourceHandler] (ExternalResourceHandler.java:68) - Found overriden resource: /casa/login.xhtml 2021-03-11 10:43:25,038 DEBUG [qtp790067787-200154] [org.gluu.service.ExternalResourceHandler] (ExternalResourceHandler.java:68) - Found overriden resource: /casa/login-template.xhtml 2021-03-11 10:43:25,040 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.CookieService] (CookieService.java:141) - Found cookie: '6369f5fd-924c-4f85-95ec-23006d6a5173' 2021-03-11 10:43:25,044 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.SessionIdService] (SessionIdService.java:744) - Try to get session by id: 6369f5fd-924c-4f85-95ec-23006d6a5173 ... 2021-03-11 10:43:25,044 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.SessionIdService] (SessionIdService.java:746) - Session dn: oxId=6369f5fd-924c-4f85-95ec-23006d6a5173,ou=sessions,o=gluu 2021-03-11 10:43:25,044 TRACE [qtp790067787-200154] [org.gluu.oxauth.auth.Authenticator] (Authenticator.java:188) - Authenticating ... (interactive: true, skipPassword: false, credentials.username: testuser123) 2021-03-11 10:43:25,044 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.CookieService] (CookieService.java:141) - Found cookie: '6369f5fd-924c-4f85-95ec-23006d6a5173' 2021-03-11 10:43:25,047 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.SessionIdService] (SessionIdService.java:744) - Try to get session by id: 6369f5fd-924c-4f85-95ec-23006d6a5173 ... 2021-03-11 10:43:25,047 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.SessionIdService] (SessionIdService.java:746) - Session dn: oxId=6369f5fd-924c-4f85-95ec-23006d6a5173,ou=sessions,o=gluu 2021-03-11 10:43:25,049 DEBUG [qtp790067787-200154] [org.gluu.oxauth.service.common.UserService] (UserService.java:172) - Getting user information from LDAP: attributeName = 'uid', attributeValue = 'testuser123' 2021-03-11 10:43:25,051 DEBUG [qtp790067787-200154] [org.gluu.oxauth.service.common.UserService] (UserService.java:187) - Found '1' entries 2021-03-11 10:43:25,052 DEBUG [qtp790067787-200154] [org.gluu.oxauth.service.AuthenticationService] (AuthenticationService.java:123) - Authenticating user with LDAP: username: 'testuser123', credentials: '1669335800' 2021-03-11 10:43:25,052 DEBUG [qtp790067787-200154] [org.gluu.oxauth.service.AuthenticationService] (AuthenticationService.java:393) - Attempting to find userDN by primary key: 'samAccountName' and key value: 'testuser123', credentials: '1669335800' 2021-03-11 10:43:25,052 DEBUG [qtp790067787-200154] [org.gluu.oxauth.service.AuthenticationService] (AuthenticationService.java:496) - Getting user information from LDAP: attributeName = 'samAccountName', attributeValue = 'testuser123' 2021-03-11 10:43:25,052 DEBUG [qtp790067787-200154] [org.gluu.oxauth.service.AuthenticationService] (AuthenticationService.java:511) - Searching user by attributes: '[Attribute [name=samAccountName, values=[testuser123]]]', baseDn: 'OU=People,DC=dmawi,DC=de' 2021-03-11 10:43:25,070 DEBUG [qtp790067787-200154] [org.gluu.oxauth.service.AuthenticationService] (AuthenticationService.java:513) - Found '1' entries 2021-03-11 10:43:25,078 DEBUG [qtp790067787-200154] [org.gluu.oxauth.service.AuthenticationService] (AuthenticationService.java:416) - Attempting to authenticate userDN: CN=testuser123,OU=People,DC=dmawi,DC=de 2021-03-11 10:43:25,081 DEBUG [qtp790067787-200154] [org.gluu.oxauth.service.AuthenticationService] (AuthenticationService.java:418) - User authenticated: CN=testuser123,OU=People,DC=dmawi,DC=de 2021-03-11 10:43:25,081 DEBUG [qtp790067787-200154] [org.gluu.oxauth.service.AuthenticationService] (AuthenticationService.java:420) - Attempting to find userDN by local primary key: uid 2021-03-11 10:43:25,081 DEBUG [qtp790067787-200154] [org.gluu.oxauth.service.common.UserService] (UserService.java:172) - Getting user information from LDAP: attributeName = 'uid', attributeValue = 'testuser123' 2021-03-11 10:43:25,084 DEBUG [qtp790067787-200154] [org.gluu.oxauth.service.common.UserService] (UserService.java:187) - Found '1' entries 2021-03-11 10:43:25,085 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.AuthenticationService] (AuthenticationService.java:430) - authenticate_external: credentials: '1669335800', credentials.userName: 'testuser123', authenticatedUser.userId: 'testuser123' 2021-03-11 10:43:25,085 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.CookieService] (CookieService.java:141) - Found cookie: '6369f5fd-924c-4f85-95ec-23006d6a5173' 2021-03-11 10:43:25,087 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.SessionIdService] (SessionIdService.java:744) - Try to get session by id: 6369f5fd-924c-4f85-95ec-23006d6a5173 ... 2021-03-11 10:43:25,088 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.SessionIdService] (SessionIdService.java:746) - Session dn: oxId=6369f5fd-924c-4f85-95ec-23006d6a5173,ou=sessions,o=gluu 2021-03-11 10:43:25,094 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.CookieService] (CookieService.java:141) - Found cookie: '6369f5fd-924c-4f85-95ec-23006d6a5173' 2021-03-11 10:43:25,096 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.SessionIdService] (SessionIdService.java:744) - Try to get session by id: 6369f5fd-924c-4f85-95ec-23006d6a5173 ... 2021-03-11 10:43:25,096 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.SessionIdService] (SessionIdService.java:746) - Session dn: oxId=6369f5fd-924c-4f85-95ec-23006d6a5173,ou=sessions,o=gluu 2021-03-11 10:43:25,100 TRACE [qtp790067787-200154] [org.gluu.oxauth.auth.Authenticator] (Authenticator.java:336) - ######################################################################### 2021-03-11 10:43:25,100 TRACE [qtp790067787-200154] [org.gluu.oxauth.auth.Authenticator] (Authenticator.java:337) - ++++++++++++++++++++++++++++++++++++++++++CURRENT ACR:casa 2021-03-11 10:43:25,100 TRACE [qtp790067787-200154] [org.gluu.oxauth.auth.Authenticator] (Authenticator.java:338) - ++++++++++++++++++++++++++++++++++++++++++CURRENT STEP:1 2021-03-11 10:43:25,100 TRACE [qtp790067787-200154] [org.gluu.oxauth.auth.Authenticator] (Authenticator.java:341) - According to API version script supports steps overriding 2021-03-11 10:43:25,100 DEBUG [qtp790067787-200154] [org.gluu.oxauth.auth.Authenticator] (Authenticator.java:344) - Get next step from script: '-1' 2021-03-11 10:43:25,101 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.AuthenticationService] (AuthenticationService.java:748) - Attempting to store extraParameters: [ACR, methods, trustedDevicesInfo, casa_contextPath, casa_prefix, casa_faviconUrl, casa_extraCss, casa_logoUrl] 2021-03-11 10:43:25,101 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.AuthenticationService] (AuthenticationService.java:767) - Storing sessionIdAttributes: {auth_external_attributes=[{"casa_logoUrl":"java.lang.String"},{"externalProviders":"java.lang.String"},{"casa_faviconUrl":"java.lang.String"},{"casa_prefix":"java.lang.String"},{"casa_contextPath":"java.lang.String"}], opbs=1ccc70f4-1b05-49b8-8ffe-d8584fff47b9, externalProviders={"helmholtzsaml": {"displayName": "(supports DFN / GEANT / eduGAIN)", "logo_img": "../../ext/resources/img/passport/2017_H_Logo_RGB_EN.png", "saml": true}}, response_type=code, nonce=p1mck1ftbmcos1agfsd1a972gp, client_id=2c71bef7-3035-416c-8649-eccf61138926, auth_step=1, acr=casa, casa_logoUrl=/casa/images/logo.png, remote_ip=172.26.1.220, scope=openid profile user_name clientinfo oxd, acr_values=casa, casa_faviconUrl=/casa/images/favicon.ico, redirect_uri=https://login.mydomain.com/casa, state=2q6rlvj96ucjrfjk85bhgf2uum, casa_prefix=, casa_contextPath=/casa, casa_extraCss=null} 2021-03-11 10:43:25,101 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.AuthenticationService] (AuthenticationService.java:768) - Storing authExternalAttributes: {casa_logoUrl=java.lang.String, externalProviders=java.lang.String, casa_faviconUrl=java.lang.String, casa_prefix=java.lang.String, casa_contextPath=java.lang.String} 2021-03-11 10:43:25,102 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.CookieService] (CookieService.java:141) - Found cookie: '6369f5fd-924c-4f85-95ec-23006d6a5173' 2021-03-11 10:43:25,104 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.SessionIdService] (SessionIdService.java:744) - Try to get session by id: 6369f5fd-924c-4f85-95ec-23006d6a5173 ... 2021-03-11 10:43:25,104 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.SessionIdService] (SessionIdService.java:746) - Session dn: oxId=6369f5fd-924c-4f85-95ec-23006d6a5173,ou=sessions,o=gluu 2021-03-11 10:43:25,104 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.AuthenticationService] (AuthenticationService.java:748) - Attempting to store extraParameters: [ACR, methods, trustedDevicesInfo, casa_contextPath, casa_prefix, casa_faviconUrl, casa_extraCss, casa_logoUrl] 2021-03-11 10:43:25,104 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.AuthenticationService] (AuthenticationService.java:767) - Storing sessionIdAttributes: {auth_external_attributes=[{"casa_logoUrl":"java.lang.String"},{"externalProviders":"java.lang.String"},{"casa_faviconUrl":"java.lang.String"},{"casa_prefix":"java.lang.String"},{"casa_contextPath":"java.lang.String"}], opbs=1ccc70f4-1b05-49b8-8ffe-d8584fff47b9, externalProviders={"helmholtzsaml": {"displayName": "(supports DFN / GEANT / eduGAIN)", "logo_img": "../../ext/resources/img/passport/2017_H_Logo_RGB_EN.png", "saml": true}}, response_type=code, nonce=p1mck1ftbmcos1agfsd1a972gp, client_id=2c71bef7-3035-416c-8649-eccf61138926, auth_step=1, acr=casa, casa_logoUrl=/casa/images/logo.png, remote_ip=172.26.1.220, scope=openid profile user_name clientinfo oxd, acr_values=casa, casa_faviconUrl=/casa/images/favicon.ico, redirect_uri=https://login.mydomain.com/casa, state=2q6rlvj96ucjrfjk85bhgf2uum, casa_prefix=, casa_contextPath=/casa, casa_extraCss=null, auth_user=testuser123} 2021-03-11 10:43:25,104 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.AuthenticationService] (AuthenticationService.java:768) - Storing authExternalAttributes: {casa_logoUrl=java.lang.String, externalProviders=java.lang.String, casa_faviconUrl=java.lang.String, casa_prefix=java.lang.String, casa_contextPath=java.lang.String} 2021-03-11 10:43:25,104 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.AuthenticationService] (AuthenticationService.java:572) - configureSessionUser: credentials: '1669335800', sessionId: 'SessionId {dn='oxId=6369f5fd-924c-4f85-95ec-23006d6a5173,ou=sessions,o=gluu', id='6369f5fd-924c-4f85-95ec-23006d6a5173', lastUsedAt=Thu Mar 11 10:43:25 UTC 2021, userDn='null', authenticationTime=Thu Mar 11 10:43:04 UTC 2021, state=unauthenticated, sessionState='2cf0f721d61a92e1e2678f1416d8b8935be708e2df662dbcca030b3fe50834f2.916c955d-e8b1-47a8-ba00-d147323bb384', permissionGranted=null, isJwt=false, jwt=null, permissionGrantedMap=SessionIdAccessMap{permissionGranted={2c71bef7-3035-416c-8649-eccf61138926=false}}, sessionAttributes={auth_external_attributes=[{"casa_logoUrl":"java.lang.String"},{"externalProviders":"java.lang.String"},{"casa_faviconUrl":"java.lang.String"},{"casa_prefix":"java.lang.String"},{"casa_contextPath":"java.lang.String"}], opbs=1ccc70f4-1b05-49b8-8ffe-d8584fff47b9, externalProviders={"helmholtzsaml": {"displayName": "(supports DFN / GEANT / eduGAIN)", "logo_img": "../../ext/resources/img/passport/2017_H_Logo_RGB_EN.png", "saml": true}}, response_type=code, nonce=p1mck1ftbmcos1agfsd1a972gp, client_id=2c71bef7-3035-416c-8649-eccf61138926, auth_step=1, acr=casa, casa_logoUrl=/casa/images/logo.png, remote_ip=172.26.1.220, scope=openid profile user_name clientinfo oxd, acr_values=casa, casa_faviconUrl=/casa/images/favicon.ico, redirect_uri=https://login.mydomain.com/casa, state=2q6rlvj96ucjrfjk85bhgf2uum, casa_prefix=, casa_contextPath=/casa, casa_extraCss=null, auth_user=testuser123}, persisted=false}', credentials.userName: 'testuser123', authenticatedUser.userId: 'testuser123' 2021-03-11 10:43:25,105 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.AuthenticationService] (AuthenticationService.java:578) - configureSessionUser sessionId: 'SessionId {dn='oxId=6369f5fd-924c-4f85-95ec-23006d6a5173,ou=sessions,o=gluu', id='6369f5fd-924c-4f85-95ec-23006d6a5173', lastUsedAt=Thu Mar 11 10:43:25 UTC 2021, userDn='null', authenticationTime=Thu Mar 11 10:43:04 UTC 2021, state=unauthenticated, sessionState='2cf0f721d61a92e1e2678f1416d8b8935be708e2df662dbcca030b3fe50834f2.916c955d-e8b1-47a8-ba00-d147323bb384', permissionGranted=null, isJwt=false, jwt=null, permissionGrantedMap=SessionIdAccessMap{permissionGranted={2c71bef7-3035-416c-8649-eccf61138926=false}}, sessionAttributes={auth_external_attributes=[{"casa_logoUrl":"java.lang.String"},{"externalProviders":"java.lang.String"},{"casa_faviconUrl":"java.lang.String"},{"casa_prefix":"java.lang.String"},{"casa_contextPath":"java.lang.String"}], opbs=1ccc70f4-1b05-49b8-8ffe-d8584fff47b9, externalProviders={"helmholtzsaml": {"displayName": "(supports DFN / GEANT / eduGAIN)", "logo_img": "../../ext/resources/img/passport/2017_H_Logo_RGB_EN.png", "saml": true}}, response_type=code, nonce=p1mck1ftbmcos1agfsd1a972gp, client_id=2c71bef7-3035-416c-8649-eccf61138926, auth_step=1, acr=casa, casa_logoUrl=/casa/images/logo.png, remote_ip=172.26.1.220, scope=openid profile user_name clientinfo oxd, acr_values=casa, casa_faviconUrl=/casa/images/favicon.ico, redirect_uri=https://login.mydomain.com/casa, state=2q6rlvj96ucjrfjk85bhgf2uum, casa_prefix=, casa_contextPath=/casa, casa_extraCss=null, auth_user=testuser123}, persisted=false}', sessionId.auth_user: 'testuser123' 2021-03-11 10:43:25,105 DEBUG [qtp790067787-200154] [org.gluu.oxauth.service.SessionIdService] (SessionIdService.java:491) - Changing session id from 6369f5fd-924c-4f85-95ec-23006d6a5173 to 989684cf-c26a-47a3-9e50-36e184ec78f9 ... 2021-03-11 10:43:25,107 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.SessionIdService] (SessionIdService.java:541) - sessionIdAttributes: SessionIdAccessMap{permissionGranted={2c71bef7-3035-416c-8649-eccf61138926=false}} 2021-03-11 10:43:25,110 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.CookieService] (CookieService.java:141) - Found cookie: '["6369f5fd-924c-4f85-95ec-23006d6a5173"]' 2021-03-11 10:43:25,111 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.SessionIdService] (SessionIdService.java:744) - Try to get session by id: 6369f5fd-924c-4f85-95ec-23006d6a5173 ... 2021-03-11 10:43:25,112 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.SessionIdService] (SessionIdService.java:758) - Failed to get session by id: 6369f5fd-924c-4f85-95ec-23006d6a5173 2021-03-11 10:43:25,112 DEBUG [qtp790067787-200154] [org.gluu.oxauth.service.SessionIdService] (SessionIdService.java:502) - Session identifier changed from 6369f5fd-924c-4f85-95ec-23006d6a5173 to 989684cf-c26a-47a3-9e50-36e184ec78f9 . 2021-03-11 10:43:25,112 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.SessionIdService] (SessionIdService.java:508) - Authenticated session, id = '989684cf-c26a-47a3-9e50-36e184ec78f9', state = 'authenticated', persisted = 'true' 2021-03-11 10:43:25,112 DEBUG [qtp790067787-200154] [org.gluu.oxauth.auth.Authenticator] (Authenticator.java:429) - Sending event to trigger user redirection: 'testuser123' 2021-03-11 10:43:25,112 INFO [qtp790067787-200154] [org.gluu.oxauth.service.AuthenticationService] (AuthenticationService.java:684) - Attempting to redirect user: SessionUser: SessionId {dn='oxId=989684cf-c26a-47a3-9e50-36e184ec78f9,ou=sessions,o=gluu', id='989684cf-c26a-47a3-9e50-36e184ec78f9', lastUsedAt=Thu Mar 11 10:43:25 UTC 2021, userDn='inum=0000!4084.40DF,ou=people,o=gluu', authenticationTime=Thu Mar 11 10:43:25 UTC 2021, state=authenticated, sessionState='2cf0f721d61a92e1e2678f1416d8b8935be708e2df662dbcca030b3fe50834f2.916c955d-e8b1-47a8-ba00-d147323bb384', permissionGranted=null, isJwt=false, jwt=null, permissionGrantedMap=SessionIdAccessMap{permissionGranted={2c71bef7-3035-416c-8649-eccf61138926=false}}, sessionAttributes={auth_external_attributes=[{"casa_logoUrl":"java.lang.String"},{"externalProviders":"java.lang.String"},{"casa_faviconUrl":"java.lang.String"},{"casa_prefix":"java.lang.String"},{"casa_contextPath":"java.lang.String"}], opbs=1ccc70f4-1b05-49b8-8ffe-d8584fff47b9, externalProviders={"helmholtzsaml": {"displayName": "(supports DFN / GEANT / eduGAIN)", "logo_img": "../../ext/resources/img/passport/2017_H_Logo_RGB_EN.png", "saml": true}}, response_type=code, nonce=p1mck1ftbmcos1agfsd1a972gp, client_id=2c71bef7-3035-416c-8649-eccf61138926, auth_step=1, acr=casa, casa_logoUrl=/casa/images/logo.png, remote_ip=172.26.1.220, scope=openid profile user_name clientinfo oxd, acr_values=casa, casa_faviconUrl=/casa/images/favicon.ico, redirect_uri=https://login.mydomain.com/casa, state=2q6rlvj96ucjrfjk85bhgf2uum, casa_prefix=, casa_contextPath=/casa, casa_extraCss=null, auth_user=testuser123}, persisted=true} 2021-03-11 10:43:25,112 INFO [qtp790067787-200154] [org.gluu.oxauth.service.AuthenticationService] (AuthenticationService.java:692) - Attempting to redirect user: User: org.gluu.oxauth.model.common.User@2960d725 2021-03-11 10:43:25,112 TRACE [qtp790067787-200154] [org.gluu.oxauth.service.AuthenticationService] (AuthenticationService.java:703) - Logged in successfully! User: org.gluu.oxauth.model.common.User@2960d725, page: /authorize.xhtml, map: {scope=openid profile user_name clientinfo oxd, acr_values=casa, response_type=code, redirect_uri=https://login.mydomain.com/casa, state=2q6rlvj96ucjrfjk85bhgf2uum, nonce=p1mck1ftbmcos1agfsd1a972gp, client_id=2c71bef7-3035-416c-8649-eccf61138926} 2021-03-11 10:43:25,112 INFO [qtp790067787-200154] [org.gluu.oxauth.auth.Authenticator] (Authenticator.java:432) - Authentication success for User: 'testuser123' 2021-03-11 10:43:25,113 TRACE [qtp790067787-200154] [org.gluu.oxauth.auth.Authenticator] (Authenticator.java:219) - Authentication successfully for 'testuser123' 2021-03-11 10:43:25,183 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=2c71bef7-3035-416c-8649-eccf61138926,ou=clients,o=gluu' 2021-03-11 10:43:25,183 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=2c71bef7-3035-416c-8649-eccf61138926,ou=clients,o=gluu': 'DeletableEntity{expirationDate=null, deletable=false} BaseEntry [dn=null]' 2021-03-11 10:43:25,183 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=2c71bef7-3035-416c-8649-eccf61138926,ou=clients,o=gluu' 2021-03-11 10:43:25,184 DEBUG [qtp790067787-202520] [org.gluu.oxauth.service.ClientService] (ClientService.java:135) - Found 1 entries for client id = 2c71bef7-3035-416c-8649-eccf61138926 2021-03-11 10:43:25,184 DEBUG [qtp790067787-202520] [org.gluu.oxauth.model.authorize.ScopeChecker] (ScopeChecker.java:58) - Checking scopes policy for: [openid, profile, user_name, clientinfo, oxd] 2021-03-11 10:43:25,184 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=6D90,ou=scopes,o=gluu' 2021-03-11 10:43:25,184 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=6D90,ou=scopes,o=gluu': 'Scope{dn='inum=6D90,ou=scopes,o=gluu', inum='6D90', displayName='oxd', id='oxd', iconUrl='null', description='oxd scope which is required to call oxd API', scopeType=openid, oxAuthClaims=null, defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,184 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=6D90,ou=scopes,o=gluu' 2021-03-11 10:43:25,185 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:84) - Put data, key 'oxd': 'Scope{dn='inum=6D90,ou=scopes,o=gluu', inum='6D90', displayName='oxd', id='oxd', iconUrl='null', description='oxd scope which is required to call oxd API', scopeType=openid, oxAuthClaims=null, defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,185 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=F0C4,ou=scopes,o=gluu' 2021-03-11 10:43:25,185 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=F0C4,ou=scopes,o=gluu': 'Scope{dn='inum=F0C4,ou=scopes,o=gluu', inum='F0C4', displayName='null', id='openid', iconUrl='null', description='Authenticate using OpenID Connect.', scopeType=openid, oxAuthClaims=null, defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,185 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=F0C4,ou=scopes,o=gluu' 2021-03-11 10:43:25,185 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:84) - Put data, key 'openid': 'Scope{dn='inum=F0C4,ou=scopes,o=gluu', inum='F0C4', displayName='null', id='openid', iconUrl='null', description='Authenticate using OpenID Connect.', scopeType=openid, oxAuthClaims=null, defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,185 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=43F1,ou=scopes,o=gluu' 2021-03-11 10:43:25,186 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=43F1,ou=scopes,o=gluu': 'Scope{dn='inum=43F1,ou=scopes,o=gluu', inum='43F1', displayName='profile', id='profile', iconUrl='null', description='View your basic profile info.', scopeType=openid, oxAuthClaims=[inum=2B29,ou=attributes,o=gluu, inum=0C85,ou=attributes,o=gluu, inum=B4B0,ou=attributes,o=gluu, inum=A0E8,ou=attributes,o=gluu, inum=5EC6,ou=attributes,o=gluu, inum=B52A,ou=attributes,o=gluu, inum=64A0,ou=attributes,o=gluu, inum=EC3A,ou=attributes,o=gluu, inum=3B47,ou=attributes,o=gluu, inum=3692,ou=attributes,o=gluu, inum=98FC,ou=attributes,o=gluu, inum=A901,ou=attributes,o=gluu, inum=36D9,ou=attributes,o=gluu, inum=BE64,ou=attributes,o=gluu, inum=6493,ou=attributes,o=gluu], defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=ScopeAttributes{spontaneousClientId='null'spontaneousClientScopes='null'notShowInDiscovery='false'}}' 2021-03-11 10:43:25,186 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=43F1,ou=scopes,o=gluu' 2021-03-11 10:43:25,186 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:84) - Put data, key 'profile': 'Scope{dn='inum=43F1,ou=scopes,o=gluu', inum='43F1', displayName='profile', id='profile', iconUrl='null', description='View your basic profile info.', scopeType=openid, oxAuthClaims=[inum=2B29,ou=attributes,o=gluu, inum=0C85,ou=attributes,o=gluu, inum=B4B0,ou=attributes,o=gluu, inum=A0E8,ou=attributes,o=gluu, inum=5EC6,ou=attributes,o=gluu, inum=B52A,ou=attributes,o=gluu, inum=64A0,ou=attributes,o=gluu, inum=EC3A,ou=attributes,o=gluu, inum=3B47,ou=attributes,o=gluu, inum=3692,ou=attributes,o=gluu, inum=98FC,ou=attributes,o=gluu, inum=A901,ou=attributes,o=gluu, inum=36D9,ou=attributes,o=gluu, inum=BE64,ou=attributes,o=gluu, inum=6493,ou=attributes,o=gluu], defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=ScopeAttributes{spontaneousClientId='null'spontaneousClientScopes='null'notShowInDiscovery='false'}}' 2021-03-11 10:43:25,186 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=10B2,ou=scopes,o=gluu' 2021-03-11 10:43:25,186 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=10B2,ou=scopes,o=gluu': 'Scope{dn='inum=10B2,ou=scopes,o=gluu', inum='10B2', displayName='null', id='user_name', iconUrl='null', description='View your local username in the Gluu Server.', scopeType=openid, oxAuthClaims=[inum=42E0,ou=attributes,o=gluu], defaultScope=false, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,187 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=10B2,ou=scopes,o=gluu' 2021-03-11 10:43:25,187 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:84) - Put data, key 'user_name': 'Scope{dn='inum=10B2,ou=scopes,o=gluu', inum='10B2', displayName='null', id='user_name', iconUrl='null', description='View your local username in the Gluu Server.', scopeType=openid, oxAuthClaims=[inum=42E0,ou=attributes,o=gluu], defaultScope=false, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,187 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=341A,ou=scopes,o=gluu' 2021-03-11 10:43:25,187 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=341A,ou=scopes,o=gluu': 'Scope{dn='inum=341A,ou=scopes,o=gluu', inum='341A', displayName='null', id='clientinfo', iconUrl='null', description='View the client info.', scopeType=openid, oxAuthClaims=[inum=2B29,ou=attributes,o=gluu, inum=29DA,ou=attributes,o=gluu], defaultScope=false, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,187 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=341A,ou=scopes,o=gluu' 2021-03-11 10:43:25,187 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:84) - Put data, key 'clientinfo': 'Scope{dn='inum=341A,ou=scopes,o=gluu', inum='341A', displayName='null', id='clientinfo', iconUrl='null', description='View the client info.', scopeType=openid, oxAuthClaims=[inum=2B29,ou=attributes,o=gluu, inum=29DA,ou=attributes,o=gluu], defaultScope=false, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,188 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=6D90,ou=scopes,o=gluu' 2021-03-11 10:43:25,188 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=6D90,ou=scopes,o=gluu': 'Scope{dn='inum=6D90,ou=scopes,o=gluu', inum='6D90', displayName='oxd', id='oxd', iconUrl='null', description='oxd scope which is required to call oxd API', scopeType=openid, oxAuthClaims=null, defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,188 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=6D90,ou=scopes,o=gluu' 2021-03-11 10:43:25,188 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:84) - Put data, key 'oxd': 'Scope{dn='inum=6D90,ou=scopes,o=gluu', inum='6D90', displayName='oxd', id='oxd', iconUrl='null', description='oxd scope which is required to call oxd API', scopeType=openid, oxAuthClaims=null, defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,188 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=F0C4,ou=scopes,o=gluu' 2021-03-11 10:43:25,188 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=F0C4,ou=scopes,o=gluu': 'Scope{dn='inum=F0C4,ou=scopes,o=gluu', inum='F0C4', displayName='null', id='openid', iconUrl='null', description='Authenticate using OpenID Connect.', scopeType=openid, oxAuthClaims=null, defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,188 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=F0C4,ou=scopes,o=gluu' 2021-03-11 10:43:25,188 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:84) - Put data, key 'openid': 'Scope{dn='inum=F0C4,ou=scopes,o=gluu', inum='F0C4', displayName='null', id='openid', iconUrl='null', description='Authenticate using OpenID Connect.', scopeType=openid, oxAuthClaims=null, defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,188 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=43F1,ou=scopes,o=gluu' 2021-03-11 10:43:25,188 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=43F1,ou=scopes,o=gluu': 'Scope{dn='inum=43F1,ou=scopes,o=gluu', inum='43F1', displayName='profile', id='profile', iconUrl='null', description='View your basic profile info.', scopeType=openid, oxAuthClaims=[inum=2B29,ou=attributes,o=gluu, inum=0C85,ou=attributes,o=gluu, inum=B4B0,ou=attributes,o=gluu, inum=A0E8,ou=attributes,o=gluu, inum=5EC6,ou=attributes,o=gluu, inum=B52A,ou=attributes,o=gluu, inum=64A0,ou=attributes,o=gluu, inum=EC3A,ou=attributes,o=gluu, inum=3B47,ou=attributes,o=gluu, inum=3692,ou=attributes,o=gluu, inum=98FC,ou=attributes,o=gluu, inum=A901,ou=attributes,o=gluu, inum=36D9,ou=attributes,o=gluu, inum=BE64,ou=attributes,o=gluu, inum=6493,ou=attributes,o=gluu], defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=ScopeAttributes{spontaneousClientId='null'spontaneousClientScopes='null'notShowInDiscovery='false'}}' 2021-03-11 10:43:25,188 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=43F1,ou=scopes,o=gluu' 2021-03-11 10:43:25,188 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:84) - Put data, key 'profile': 'Scope{dn='inum=43F1,ou=scopes,o=gluu', inum='43F1', displayName='profile', id='profile', iconUrl='null', description='View your basic profile info.', scopeType=openid, oxAuthClaims=[inum=2B29,ou=attributes,o=gluu, inum=0C85,ou=attributes,o=gluu, inum=B4B0,ou=attributes,o=gluu, inum=A0E8,ou=attributes,o=gluu, inum=5EC6,ou=attributes,o=gluu, inum=B52A,ou=attributes,o=gluu, inum=64A0,ou=attributes,o=gluu, inum=EC3A,ou=attributes,o=gluu, inum=3B47,ou=attributes,o=gluu, inum=3692,ou=attributes,o=gluu, inum=98FC,ou=attributes,o=gluu, inum=A901,ou=attributes,o=gluu, inum=36D9,ou=attributes,o=gluu, inum=BE64,ou=attributes,o=gluu, inum=6493,ou=attributes,o=gluu], defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=ScopeAttributes{spontaneousClientId='null'spontaneousClientScopes='null'notShowInDiscovery='false'}}' 2021-03-11 10:43:25,188 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=10B2,ou=scopes,o=gluu' 2021-03-11 10:43:25,189 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=10B2,ou=scopes,o=gluu': 'Scope{dn='inum=10B2,ou=scopes,o=gluu', inum='10B2', displayName='null', id='user_name', iconUrl='null', description='View your local username in the Gluu Server.', scopeType=openid, oxAuthClaims=[inum=42E0,ou=attributes,o=gluu], defaultScope=false, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,189 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=10B2,ou=scopes,o=gluu' 2021-03-11 10:43:25,189 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:84) - Put data, key 'user_name': 'Scope{dn='inum=10B2,ou=scopes,o=gluu', inum='10B2', displayName='null', id='user_name', iconUrl='null', description='View your local username in the Gluu Server.', scopeType=openid, oxAuthClaims=[inum=42E0,ou=attributes,o=gluu], defaultScope=false, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,189 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=341A,ou=scopes,o=gluu' 2021-03-11 10:43:25,189 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=341A,ou=scopes,o=gluu': 'Scope{dn='inum=341A,ou=scopes,o=gluu', inum='341A', displayName='null', id='clientinfo', iconUrl='null', description='View the client info.', scopeType=openid, oxAuthClaims=[inum=2B29,ou=attributes,o=gluu, inum=29DA,ou=attributes,o=gluu], defaultScope=false, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,189 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=341A,ou=scopes,o=gluu' 2021-03-11 10:43:25,189 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:84) - Put data, key 'clientinfo': 'Scope{dn='inum=341A,ou=scopes,o=gluu', inum='341A', displayName='null', id='clientinfo', iconUrl='null', description='View the client info.', scopeType=openid, oxAuthClaims=[inum=2B29,ou=attributes,o=gluu, inum=29DA,ou=attributes,o=gluu], defaultScope=false, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,189 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=6D90,ou=scopes,o=gluu' 2021-03-11 10:43:25,189 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=6D90,ou=scopes,o=gluu': 'Scope{dn='inum=6D90,ou=scopes,o=gluu', inum='6D90', displayName='oxd', id='oxd', iconUrl='null', description='oxd scope which is required to call oxd API', scopeType=openid, oxAuthClaims=null, defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,189 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=6D90,ou=scopes,o=gluu' 2021-03-11 10:43:25,189 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:84) - Put data, key 'oxd': 'Scope{dn='inum=6D90,ou=scopes,o=gluu', inum='6D90', displayName='oxd', id='oxd', iconUrl='null', description='oxd scope which is required to call oxd API', scopeType=openid, oxAuthClaims=null, defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,189 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=F0C4,ou=scopes,o=gluu' 2021-03-11 10:43:25,189 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=F0C4,ou=scopes,o=gluu': 'Scope{dn='inum=F0C4,ou=scopes,o=gluu', inum='F0C4', displayName='null', id='openid', iconUrl='null', description='Authenticate using OpenID Connect.', scopeType=openid, oxAuthClaims=null, defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,189 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=F0C4,ou=scopes,o=gluu' 2021-03-11 10:43:25,190 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:84) - Put data, key 'openid': 'Scope{dn='inum=F0C4,ou=scopes,o=gluu', inum='F0C4', displayName='null', id='openid', iconUrl='null', description='Authenticate using OpenID Connect.', scopeType=openid, oxAuthClaims=null, defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,190 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=43F1,ou=scopes,o=gluu' 2021-03-11 10:43:25,190 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=43F1,ou=scopes,o=gluu': 'Scope{dn='inum=43F1,ou=scopes,o=gluu', inum='43F1', displayName='profile', id='profile', iconUrl='null', description='View your basic profile info.', scopeType=openid, oxAuthClaims=[inum=2B29,ou=attributes,o=gluu, inum=0C85,ou=attributes,o=gluu, inum=B4B0,ou=attributes,o=gluu, inum=A0E8,ou=attributes,o=gluu, inum=5EC6,ou=attributes,o=gluu, inum=B52A,ou=attributes,o=gluu, inum=64A0,ou=attributes,o=gluu, inum=EC3A,ou=attributes,o=gluu, inum=3B47,ou=attributes,o=gluu, inum=3692,ou=attributes,o=gluu, inum=98FC,ou=attributes,o=gluu, inum=A901,ou=attributes,o=gluu, inum=36D9,ou=attributes,o=gluu, inum=BE64,ou=attributes,o=gluu, inum=6493,ou=attributes,o=gluu], defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=ScopeAttributes{spontaneousClientId='null'spontaneousClientScopes='null'notShowInDiscovery='false'}}' 2021-03-11 10:43:25,190 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=43F1,ou=scopes,o=gluu' 2021-03-11 10:43:25,190 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:84) - Put data, key 'profile': 'Scope{dn='inum=43F1,ou=scopes,o=gluu', inum='43F1', displayName='profile', id='profile', iconUrl='null', description='View your basic profile info.', scopeType=openid, oxAuthClaims=[inum=2B29,ou=attributes,o=gluu, inum=0C85,ou=attributes,o=gluu, inum=B4B0,ou=attributes,o=gluu, inum=A0E8,ou=attributes,o=gluu, inum=5EC6,ou=attributes,o=gluu, inum=B52A,ou=attributes,o=gluu, inum=64A0,ou=attributes,o=gluu, inum=EC3A,ou=attributes,o=gluu, inum=3B47,ou=attributes,o=gluu, inum=3692,ou=attributes,o=gluu, inum=98FC,ou=attributes,o=gluu, inum=A901,ou=attributes,o=gluu, inum=36D9,ou=attributes,o=gluu, inum=BE64,ou=attributes,o=gluu, inum=6493,ou=attributes,o=gluu], defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=ScopeAttributes{spontaneousClientId='null'spontaneousClientScopes='null'notShowInDiscovery='false'}}' 2021-03-11 10:43:25,190 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=10B2,ou=scopes,o=gluu' 2021-03-11 10:43:25,190 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=10B2,ou=scopes,o=gluu': 'Scope{dn='inum=10B2,ou=scopes,o=gluu', inum='10B2', displayName='null', id='user_name', iconUrl='null', description='View your local username in the Gluu Server.', scopeType=openid, oxAuthClaims=[inum=42E0,ou=attributes,o=gluu], defaultScope=false, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,190 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=10B2,ou=scopes,o=gluu' 2021-03-11 10:43:25,190 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:84) - Put data, key 'user_name': 'Scope{dn='inum=10B2,ou=scopes,o=gluu', inum='10B2', displayName='null', id='user_name', iconUrl='null', description='View your local username in the Gluu Server.', scopeType=openid, oxAuthClaims=[inum=42E0,ou=attributes,o=gluu], defaultScope=false, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,190 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=341A,ou=scopes,o=gluu' 2021-03-11 10:43:25,190 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=341A,ou=scopes,o=gluu': 'Scope{dn='inum=341A,ou=scopes,o=gluu', inum='341A', displayName='null', id='clientinfo', iconUrl='null', description='View the client info.', scopeType=openid, oxAuthClaims=[inum=2B29,ou=attributes,o=gluu, inum=29DA,ou=attributes,o=gluu], defaultScope=false, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,190 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=341A,ou=scopes,o=gluu' 2021-03-11 10:43:25,191 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:84) - Put data, key 'clientinfo': 'Scope{dn='inum=341A,ou=scopes,o=gluu', inum='341A', displayName='null', id='clientinfo', iconUrl='null', description='View the client info.', scopeType=openid, oxAuthClaims=[inum=2B29,ou=attributes,o=gluu, inum=29DA,ou=attributes,o=gluu], defaultScope=false, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,191 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=6D90,ou=scopes,o=gluu' 2021-03-11 10:43:25,191 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=6D90,ou=scopes,o=gluu': 'Scope{dn='inum=6D90,ou=scopes,o=gluu', inum='6D90', displayName='oxd', id='oxd', iconUrl='null', description='oxd scope which is required to call oxd API', scopeType=openid, oxAuthClaims=null, defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,191 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=6D90,ou=scopes,o=gluu' 2021-03-11 10:43:25,191 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:84) - Put data, key 'oxd': 'Scope{dn='inum=6D90,ou=scopes,o=gluu', inum='6D90', displayName='oxd', id='oxd', iconUrl='null', description='oxd scope which is required to call oxd API', scopeType=openid, oxAuthClaims=null, defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,191 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=F0C4,ou=scopes,o=gluu' 2021-03-11 10:43:25,191 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=F0C4,ou=scopes,o=gluu': 'Scope{dn='inum=F0C4,ou=scopes,o=gluu', inum='F0C4', displayName='null', id='openid', iconUrl='null', description='Authenticate using OpenID Connect.', scopeType=openid, oxAuthClaims=null, defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,191 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=F0C4,ou=scopes,o=gluu' 2021-03-11 10:43:25,191 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:84) - Put data, key 'openid': 'Scope{dn='inum=F0C4,ou=scopes,o=gluu', inum='F0C4', displayName='null', id='openid', iconUrl='null', description='Authenticate using OpenID Connect.', scopeType=openid, oxAuthClaims=null, defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,191 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=43F1,ou=scopes,o=gluu' 2021-03-11 10:43:25,191 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=43F1,ou=scopes,o=gluu': 'Scope{dn='inum=43F1,ou=scopes,o=gluu', inum='43F1', displayName='profile', id='profile', iconUrl='null', description='View your basic profile info.', scopeType=openid, oxAuthClaims=[inum=2B29,ou=attributes,o=gluu, inum=0C85,ou=attributes,o=gluu, inum=B4B0,ou=attributes,o=gluu, inum=A0E8,ou=attributes,o=gluu, inum=5EC6,ou=attributes,o=gluu, inum=B52A,ou=attributes,o=gluu, inum=64A0,ou=attributes,o=gluu, inum=EC3A,ou=attributes,o=gluu, inum=3B47,ou=attributes,o=gluu, inum=3692,ou=attributes,o=gluu, inum=98FC,ou=attributes,o=gluu, inum=A901,ou=attributes,o=gluu, inum=36D9,ou=attributes,o=gluu, inum=BE64,ou=attributes,o=gluu, inum=6493,ou=attributes,o=gluu], defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=ScopeAttributes{spontaneousClientId='null'spontaneousClientScopes='null'notShowInDiscovery='false'}}' 2021-03-11 10:43:25,191 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=43F1,ou=scopes,o=gluu' 2021-03-11 10:43:25,191 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:84) - Put data, key 'profile': 'Scope{dn='inum=43F1,ou=scopes,o=gluu', inum='43F1', displayName='profile', id='profile', iconUrl='null', description='View your basic profile info.', scopeType=openid, oxAuthClaims=[inum=2B29,ou=attributes,o=gluu, inum=0C85,ou=attributes,o=gluu, inum=B4B0,ou=attributes,o=gluu, inum=A0E8,ou=attributes,o=gluu, inum=5EC6,ou=attributes,o=gluu, inum=B52A,ou=attributes,o=gluu, inum=64A0,ou=attributes,o=gluu, inum=EC3A,ou=attributes,o=gluu, inum=3B47,ou=attributes,o=gluu, inum=3692,ou=attributes,o=gluu, inum=98FC,ou=attributes,o=gluu, inum=A901,ou=attributes,o=gluu, inum=36D9,ou=attributes,o=gluu, inum=BE64,ou=attributes,o=gluu, inum=6493,ou=attributes,o=gluu], defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=ScopeAttributes{spontaneousClientId='null'spontaneousClientScopes='null'notShowInDiscovery='false'}}' 2021-03-11 10:43:25,191 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=10B2,ou=scopes,o=gluu' 2021-03-11 10:43:25,192 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=10B2,ou=scopes,o=gluu': 'Scope{dn='inum=10B2,ou=scopes,o=gluu', inum='10B2', displayName='null', id='user_name', iconUrl='null', description='View your local username in the Gluu Server.', scopeType=openid, oxAuthClaims=[inum=42E0,ou=attributes,o=gluu], defaultScope=false, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,192 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=10B2,ou=scopes,o=gluu' 2021-03-11 10:43:25,192 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:84) - Put data, key 'user_name': 'Scope{dn='inum=10B2,ou=scopes,o=gluu', inum='10B2', displayName='null', id='user_name', iconUrl='null', description='View your local username in the Gluu Server.', scopeType=openid, oxAuthClaims=[inum=42E0,ou=attributes,o=gluu], defaultScope=false, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,192 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=341A,ou=scopes,o=gluu' 2021-03-11 10:43:25,192 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=341A,ou=scopes,o=gluu': 'Scope{dn='inum=341A,ou=scopes,o=gluu', inum='341A', displayName='null', id='clientinfo', iconUrl='null', description='View the client info.', scopeType=openid, oxAuthClaims=[inum=2B29,ou=attributes,o=gluu, inum=29DA,ou=attributes,o=gluu], defaultScope=false, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,192 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=341A,ou=scopes,o=gluu' 2021-03-11 10:43:25,192 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:84) - Put data, key 'clientinfo': 'Scope{dn='inum=341A,ou=scopes,o=gluu', inum='341A', displayName='null', id='clientinfo', iconUrl='null', description='View the client info.', scopeType=openid, oxAuthClaims=[inum=2B29,ou=attributes,o=gluu, inum=29DA,ou=attributes,o=gluu], defaultScope=false, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,192 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=6D90,ou=scopes,o=gluu' 2021-03-11 10:43:25,192 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=6D90,ou=scopes,o=gluu': 'Scope{dn='inum=6D90,ou=scopes,o=gluu', inum='6D90', displayName='oxd', id='oxd', iconUrl='null', description='oxd scope which is required to call oxd API', scopeType=openid, oxAuthClaims=null, defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,192 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=6D90,ou=scopes,o=gluu' 2021-03-11 10:43:25,192 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:84) - Put data, key 'oxd': 'Scope{dn='inum=6D90,ou=scopes,o=gluu', inum='6D90', displayName='oxd', id='oxd', iconUrl='null', description='oxd scope which is required to call oxd API', scopeType=openid, oxAuthClaims=null, defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,192 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=F0C4,ou=scopes,o=gluu' 2021-03-11 10:43:25,192 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=F0C4,ou=scopes,o=gluu': 'Scope{dn='inum=F0C4,ou=scopes,o=gluu', inum='F0C4', displayName='null', id='openid', iconUrl='null', description='Authenticate using OpenID Connect.', scopeType=openid, oxAuthClaims=null, defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,192 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=F0C4,ou=scopes,o=gluu' 2021-03-11 10:43:25,192 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:84) - Put data, key 'openid': 'Scope{dn='inum=F0C4,ou=scopes,o=gluu', inum='F0C4', displayName='null', id='openid', iconUrl='null', description='Authenticate using OpenID Connect.', scopeType=openid, oxAuthClaims=null, defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,193 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=43F1,ou=scopes,o=gluu' 2021-03-11 10:43:25,193 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=43F1,ou=scopes,o=gluu': 'Scope{dn='inum=43F1,ou=scopes,o=gluu', inum='43F1', displayName='profile', id='profile', iconUrl='null', description='View your basic profile info.', scopeType=openid, oxAuthClaims=[inum=2B29,ou=attributes,o=gluu, inum=0C85,ou=attributes,o=gluu, inum=B4B0,ou=attributes,o=gluu, inum=A0E8,ou=attributes,o=gluu, inum=5EC6,ou=attributes,o=gluu, inum=B52A,ou=attributes,o=gluu, inum=64A0,ou=attributes,o=gluu, inum=EC3A,ou=attributes,o=gluu, inum=3B47,ou=attributes,o=gluu, inum=3692,ou=attributes,o=gluu, inum=98FC,ou=attributes,o=gluu, inum=A901,ou=attributes,o=gluu, inum=36D9,ou=attributes,o=gluu, inum=BE64,ou=attributes,o=gluu, inum=6493,ou=attributes,o=gluu], defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=ScopeAttributes{spontaneousClientId='null'spontaneousClientScopes='null'notShowInDiscovery='false'}}' 2021-03-11 10:43:25,193 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=43F1,ou=scopes,o=gluu' 2021-03-11 10:43:25,193 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:84) - Put data, key 'profile': 'Scope{dn='inum=43F1,ou=scopes,o=gluu', inum='43F1', displayName='profile', id='profile', iconUrl='null', description='View your basic profile info.', scopeType=openid, oxAuthClaims=[inum=2B29,ou=attributes,o=gluu, inum=0C85,ou=attributes,o=gluu, inum=B4B0,ou=attributes,o=gluu, inum=A0E8,ou=attributes,o=gluu, inum=5EC6,ou=attributes,o=gluu, inum=B52A,ou=attributes,o=gluu, inum=64A0,ou=attributes,o=gluu, inum=EC3A,ou=attributes,o=gluu, inum=3B47,ou=attributes,o=gluu, inum=3692,ou=attributes,o=gluu, inum=98FC,ou=attributes,o=gluu, inum=A901,ou=attributes,o=gluu, inum=36D9,ou=attributes,o=gluu, inum=BE64,ou=attributes,o=gluu, inum=6493,ou=attributes,o=gluu], defaultScope=true, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=ScopeAttributes{spontaneousClientId='null'spontaneousClientScopes='null'notShowInDiscovery='false'}}' 2021-03-11 10:43:25,193 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=10B2,ou=scopes,o=gluu' 2021-03-11 10:43:25,193 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=10B2,ou=scopes,o=gluu': 'Scope{dn='inum=10B2,ou=scopes,o=gluu', inum='10B2', displayName='null', id='user_name', iconUrl='null', description='View your local username in the Gluu Server.', scopeType=openid, oxAuthClaims=[inum=42E0,ou=attributes,o=gluu], defaultScope=false, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,193 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=10B2,ou=scopes,o=gluu' 2021-03-11 10:43:25,193 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:84) - Put data, key 'user_name': 'Scope{dn='inum=10B2,ou=scopes,o=gluu', inum='10B2', displayName='null', id='user_name', iconUrl='null', description='View your local username in the Gluu Server.', scopeType=openid, oxAuthClaims=[inum=42E0,ou=attributes,o=gluu], defaultScope=false, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,193 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=341A,ou=scopes,o=gluu' 2021-03-11 10:43:25,193 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=341A,ou=scopes,o=gluu': 'Scope{dn='inum=341A,ou=scopes,o=gluu', inum='341A', displayName='null', id='clientinfo', iconUrl='null', description='View the client info.', scopeType=openid, oxAuthClaims=[inum=2B29,ou=attributes,o=gluu, inum=29DA,ou=attributes,o=gluu], defaultScope=false, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,193 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=341A,ou=scopes,o=gluu' 2021-03-11 10:43:25,193 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:84) - Put data, key 'clientinfo': 'Scope{dn='inum=341A,ou=scopes,o=gluu', inum='341A', displayName='null', id='clientinfo', iconUrl='null', description='View the client info.', scopeType=openid, oxAuthClaims=[inum=2B29,ou=attributes,o=gluu, inum=29DA,ou=attributes,o=gluu], defaultScope=false, oxAuthGroupClaims=null, dynamicScopeScripts=null, umaAuthorizationPolicies=null, deletable=null, expirationDate=null, attributes=null}' 2021-03-11 10:43:25,193 DEBUG [qtp790067787-202520] [org.gluu.oxauth.model.authorize.ScopeChecker] (ScopeChecker.java:90) - Granted scopes: [openid, user_name, clientinfo, profile, oxd] 2021-03-11 10:43:25,194 TRACE [qtp790067787-202520] [org.gluu.oxauth.service.CookieService] (CookieService.java:141) - Found cookie: '989684cf-c26a-47a3-9e50-36e184ec78f9' 2021-03-11 10:43:25,197 TRACE [qtp790067787-202520] [org.gluu.oxauth.service.SessionIdService] (SessionIdService.java:744) - Try to get session by id: 989684cf-c26a-47a3-9e50-36e184ec78f9 ... 2021-03-11 10:43:25,197 TRACE [qtp790067787-202520] [org.gluu.oxauth.service.SessionIdService] (SessionIdService.java:746) - Session dn: oxId=989684cf-c26a-47a3-9e50-36e184ec78f9,ou=sessions,o=gluu 2021-03-11 10:43:25,197 TRACE [qtp790067787-202520] [org.gluu.oxauth.auth.Authenticator] (Authenticator.java:704) - authenticateBySessionId, sessionId = '989684cf-c26a-47a3-9e50-36e184ec78f9', session = 'SessionId {dn='oxId=989684cf-c26a-47a3-9e50-36e184ec78f9,ou=sessions,o=gluu', id='989684cf-c26a-47a3-9e50-36e184ec78f9', lastUsedAt=Thu Mar 11 10:43:25 UTC 2021, userDn='inum=0000!4084.40DF,ou=people,o=gluu', authenticationTime=Thu Mar 11 10:43:25 UTC 2021, state=authenticated, sessionState='2cf0f721d61a92e1e2678f1416d8b8935be708e2df662dbcca030b3fe50834f2.916c955d-e8b1-47a8-ba00-d147323bb384', permissionGranted=null, isJwt=false, jwt=null, permissionGrantedMap=SessionIdAccessMap{permissionGranted={2c71bef7-3035-416c-8649-eccf61138926=false}}, sessionAttributes={auth_external_attributes=[{"casa_logoUrl":"java.lang.String"},{"externalProviders":"java.lang.String"},{"casa_faviconUrl":"java.lang.String"},{"casa_prefix":"java.lang.String"},{"casa_contextPath":"java.lang.String"}], opbs=1ccc70f4-1b05-49b8-8ffe-d8584fff47b9, externalProviders={"helmholtzsaml": {"displayName": "(supports DFN / GEANT / eduGAIN)", "logo_img": "../../ext/resources/img/passport/2017_H_Logo_RGB_EN.png", "saml": true}}, response_type=code, nonce=p1mck1ftbmcos1agfsd1a972gp, client_id=2c71bef7-3035-416c-8649-eccf61138926, auth_step=1, acr=casa, casa_logoUrl=/casa/images/logo.png, remote_ip=172.26.1.220, scope=openid profile user_name clientinfo oxd, acr_values=casa, casa_faviconUrl=/casa/images/favicon.ico, redirect_uri=https://login.mydomain.com/casa, state=2q6rlvj96ucjrfjk85bhgf2uum, casa_prefix=, casa_contextPath=/casa, casa_extraCss=null, auth_user=testuser123}, persisted=false}', state= 'authenticated' 2021-03-11 10:43:25,203 TRACE [qtp790067787-202520] [org.gluu.oxauth.service.SessionIdService] (SessionIdService.java:744) - Try to get session by id: 989684cf-c26a-47a3-9e50-36e184ec78f9 ... 2021-03-11 10:43:25,203 TRACE [qtp790067787-202520] [org.gluu.oxauth.service.SessionIdService] (SessionIdService.java:746) - Session dn: oxId=989684cf-c26a-47a3-9e50-36e184ec78f9,ou=sessions,o=gluu 2021-03-11 10:43:25,204 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=2c71bef7-3035-416c-8649-eccf61138926,ou=clients,o=gluu' 2021-03-11 10:43:25,204 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=2c71bef7-3035-416c-8649-eccf61138926,ou=clients,o=gluu': 'DeletableEntity{expirationDate=null, deletable=false} BaseEntry [dn=null]' 2021-03-11 10:43:25,204 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=2c71bef7-3035-416c-8649-eccf61138926,ou=clients,o=gluu' 2021-03-11 10:43:25,204 DEBUG [qtp790067787-202520] [org.gluu.oxauth.service.ClientService] (ClientService.java:135) - Found 1 entries for client id = 2c71bef7-3035-416c-8649-eccf61138926 2021-03-11 10:43:25,204 DEBUG [qtp790067787-202520] [org.gluu.oxauth.service.RedirectionUriService] (RedirectionUriService.java:87) - Validating redirection URI: clientIdentifier = 2c71bef7-3035-416c-8649-eccf61138926, redirectionUri = https://login.mydomain.com/casa, found = 1 2021-03-11 10:43:25,204 DEBUG [qtp790067787-202520] [org.gluu.oxauth.service.RedirectionUriService] (RedirectionUriService.java:109) - Comparing https://login.mydomain.com/casa == https://login.mydomain.com/casa 2021-03-11 10:43:25,205 TRACE [qtp790067787-202520] [gluu.oxauth.authorize.ws.rs.AuthorizeAction] (AuthorizeAction.java:376) - checkPermissionGranted, user = org.gluu.oxauth.model.common.User@2f74d06e 2021-03-11 10:43:25,206 TRACE [qtp790067787-202520] [org.gluu.oxauth.service.AuthorizeService] (AuthorizeService.java:138) - permissionGranted 2021-03-11 10:43:25,206 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=2c71bef7-3035-416c-8649-eccf61138926,ou=clients,o=gluu' 2021-03-11 10:43:25,206 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=2c71bef7-3035-416c-8649-eccf61138926,ou=clients,o=gluu': 'DeletableEntity{expirationDate=null, deletable=false} BaseEntry [dn=null]' 2021-03-11 10:43:25,206 TRACE [qtp790067787-202520] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=2c71bef7-3035-416c-8649-eccf61138926,ou=clients,o=gluu' 2021-03-11 10:43:25,206 DEBUG [qtp790067787-202520] [org.gluu.oxauth.service.ClientService] (ClientService.java:135) - Found 1 entries for client id = 2c71bef7-3035-416c-8649-eccf61138926 2021-03-11 10:43:25,210 TRACE [qtp790067787-202520] [org.gluu.oxauth.service.CookieService] (CookieService.java:141) - Found cookie: '["989684cf-c26a-47a3-9e50-36e184ec78f9"]' 2021-03-11 10:43:25,211 TRACE [qtp790067787-202520] [org.gluu.oxauth.service.SessionIdService] (SessionIdService.java:744) - Try to get session by id: 989684cf-c26a-47a3-9e50-36e184ec78f9 ... 2021-03-11 10:43:25,212 TRACE [qtp790067787-202520] [org.gluu.oxauth.service.SessionIdService] (SessionIdService.java:746) - Session dn: oxId=989684cf-c26a-47a3-9e50-36e184ec78f9,ou=sessions,o=gluu 2021-03-11 10:43:25,212 TRACE [qtp790067787-202520] [org.gluu.oxauth.service.AuthorizeService] (AuthorizeService.java:176) - permissionGranted, redirectTo: /oxauth/restv1/authorize?scope=openid+profile+user_name+clientinfo+oxd&acr_values=casa&response_type=code&redirect_uri=https%3A%2F%2Flogin.mydomain.com%2Fcasa&state=2q6rlvj96ucjrfjk85bhgf2uum&nonce=p1mck1ftbmcos1agfsd1a972gp&client_id=2c71bef7-3035-416c-8649-eccf61138926 2021-03-11 10:43:25,265 TRACE [qtp790067787-200154] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=2c71bef7-3035-416c-8649-eccf61138926,ou=clients,o=gluu' 2021-03-11 10:43:25,265 TRACE [qtp790067787-200154] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=2c71bef7-3035-416c-8649-eccf61138926,ou=clients,o=gluu': 'DeletableEntity{expirationDate=null, deletable=false} BaseEntry [dn=null]' 2021-03-11 10:43:25,265 TRACE [qtp790067787-200154] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=2c71bef7-3035-416c-8649-eccf61138926,ou=clients,o=gluu' 2021-03-11 10:43:25,265 DEBUG [qtp790067787-200154] [org.gluu.oxauth.service.ClientService] (ClientService.java:135) - Found 1 entries for client id = 2c71bef7-3035-416c-8649-eccf61138926 2021-03-11 10:43:25.265:WARN:oejs.HttpChannel:qtp790067787-200154: /oxauth/restv1/authorize java.lang.NullPointerException at org.gluu.server.filters.AbstractCorsFilter.isOriginAllowed(AbstractCorsFilter.java:596) at org.gluu.server.filters.AbstractCorsFilter.handleSimpleCORS(AbstractCorsFilter.java:181) at org.gluu.server.filters.AbstractCorsFilter.doFilter(AbstractCorsFilter.java:127) at org.gluu.oxauth.filter.CorsFilter.doFilter(CorsFilter.java:118) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1638) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:567) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:602) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1610) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1377) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:507) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1580) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1292) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:234) at org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:146) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) at org.eclipse.jetty.server.Server.handle(Server.java:501) at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383) at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:556) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:273) at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:336) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:313) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:171) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:129) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:375) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:806) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:938) at java.base/java.lang.Thread.run(Thread.java:834) 2021-03-11 10:43:31,225 DEBUG [oxAuthScheduler_Worker-1] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started 2021-03-11 10:43:31,225 DEBUG [oxAuthScheduler_Worker-1] [org.gluu.service.timer.TimerJob] (TimerJob.java:36) - Fire timer event [org.gluu.service.cdi.event.LoggerUpdateEvent] with qualifiers [@org.gluu.service.cdi.event.Scheduled()] from instance 2060058117 2021-03-11 10:43:31,226 DEBUG [oxAuthScheduler_Worker-1] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended 2021-03-11 10:43:31,352 INFO [ForkJoinPool.commonPool-worker-11] [org.gluu.service.logger.LoggerService] (LoggerService.java:165) - Updated log level of '138' loggers to TRACE 2021-03-11 10:43:31,788 TRACE [qtp790067787-200652] [org.gluu.oxauth.uma.service.UmaValidationService] (UmaValidationService.java:110) - Validate authorization: Bearer 25ae1033-1558-4e0e-b6ca-396749b4ab53 2021-03-11 10:43:31,789 TRACE [qtp790067787-200652] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key '4d006afa1bbc6b04ae708ae8db6b85069e2db85259b81c82655055b3b1c262af' 2021-03-11 10:43:31,790 TRACE [qtp790067787-200652] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key '4d006afa1bbc6b04ae708ae8db6b85069e2db85259b81c82655055b3b1c262af': 'null' 2021-03-11 10:43:31,794 DEBUG [qtp790067787-200652] [org.gluu.oxauth.service.common.UserService] (UserService.java:78) - Getting user information from LDAP: userId = null 2021-03-11 10:43:31,794 TRACE [qtp790067787-200652] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=1501.313afb3b-c67e-4a2e-8d7b-1b4283c75b1e,ou=clients,o=gluu' 2021-03-11 10:43:31,794 TRACE [qtp790067787-200652] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=1501.313afb3b-c67e-4a2e-8d7b-1b4283c75b1e,ou=clients,o=gluu': 'null' 2021-03-11 10:43:31,794 TRACE [qtp790067787-200652] [org.gluu.service.BaseCacheService] (BaseCacheService.java:61) - Key not in cache. Searching value via load function, key: 'inum=1501.313afb3b-c67e-4a2e-8d7b-1b4283c75b1e,ou=clients,o=gluu' 2021-03-11 10:43:31,798 TRACE [qtp790067787-200652] [org.gluu.service.BaseCacheService] (BaseCacheService.java:84) - Put data, key 'inum=1501.313afb3b-c67e-4a2e-8d7b-1b4283c75b1e,ou=clients,o=gluu': 'DeletableEntity{expirationDate=null, deletable=null} BaseEntry [dn=null]' 2021-03-11 10:43:31,798 DEBUG [qtp790067787-200652] [org.gluu.oxauth.service.ClientService] (ClientService.java:135) - Found 1 entries for client id = 1501.313afb3b-c67e-4a2e-8d7b-1b4283c75b1e 2021-03-11 10:43:31,805 TRACE [qtp790067787-200652] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=1502.1a6dfcf7-603b-43ed-acde-abe579c269f4,ou=clients,o=gluu' 2021-03-11 10:43:31,806 TRACE [qtp790067787-200652] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=1502.1a6dfcf7-603b-43ed-acde-abe579c269f4,ou=clients,o=gluu': 'null' 2021-03-11 10:43:31,806 TRACE [qtp790067787-200652] [org.gluu.service.BaseCacheService] (BaseCacheService.java:61) - Key not in cache. Searching value via load function, key: 'inum=1502.1a6dfcf7-603b-43ed-acde-abe579c269f4,ou=clients,o=gluu' 2021-03-11 10:43:31,807 TRACE [qtp790067787-200652] [org.gluu.service.BaseCacheService] (BaseCacheService.java:84) - Put data, key 'inum=1502.1a6dfcf7-603b-43ed-acde-abe579c269f4,ou=clients,o=gluu': 'DeletableEntity{expirationDate=null, deletable=null} BaseEntry [dn=null]' 2021-03-11 10:43:31,808 DEBUG [qtp790067787-200652] [org.gluu.oxauth.service.ClientService] (ClientService.java:135) - Found 1 entries for client id = 1502.1a6dfcf7-603b-43ed-acde-abe579c269f4 2021-03-11 10:43:31,808 TRACE [qtp790067787-200652] [gluu.oxauth.uma.ws.rs.UmaRptIntrospectionWS] (UmaRptIntrospectionWS.java:133) - Canceled changes made by external RPT Claims script since method returned `false`. ```

By Mohit Mali staff 17 Mar 2021 at 6:40 a.m. CDT

Mohit Mali gravatar
hi Martin Petri, I have just configured casa with otp , my safari version is 14.0.3 and gluu version 4.2.0 , its works for me. Thanks and Regards Mohit Mali

By Martin Petri user 19 Mar 2021 at 2:46 a.m. CDT

Martin Petri gravatar
Hi Mohit. Thank you very much for recreating the issue. We also were not able to reproduce the problem (with Version 4.2.3). I'll close this case. If I can find some useful informations about the cause i'll post it here. Thanks for your support! Cheers, Martin