TRACE [2019-11-15 10:15:16,307] ({qtp89387388-14} OncePerRequestFilter.java[doFilter]:121) - Filter 'org.apache.shiro.web.servlet.ShiroFilter-17baae6e' not yet executed. Executing now.
TRACE [2019-11-15 10:15:16,307] ({qtp89387388-14} DefaultSecurityManager.java[ensureSecurityManager]:417) - Context already contains a SecurityManager instance. Returning.
TRACE [2019-11-15 10:15:16,307] ({qtp89387388-14} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@4219b2f5
DEBUG [2019-11-15 10:15:16,308] ({qtp89387388-14} SimpleCookie.java[readValue]:389) - Found 'JSESSIONID' cookie value [4582bcb1-87cc-47c4-bae5-be6971f56f7e]
TRACE [2019-11-15 10:15:16,308] ({qtp89387388-14} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@55d5a6f3
TRACE [2019-11-15 10:15:16,308] ({qtp89387388-14} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@55d5a6f3
TRACE [2019-11-15 10:15:16,308] ({qtp89387388-14} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@55d5a6f3
TRACE [2019-11-15 10:15:16,308] ({qtp89387388-14} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@55d5a6f3
TRACE [2019-11-15 10:15:16,309] ({qtp89387388-14} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:16,309] ({qtp89387388-14} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:16,309] ({qtp89387388-14} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@55d5a6f3
TRACE [2019-11-15 10:15:16,309] ({qtp89387388-14} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:16,310] ({qtp89387388-14} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@55d5a6f3
TRACE [2019-11-15 10:15:16,310] ({qtp89387388-14} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:16,310] ({qtp89387388-14} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@55d5a6f3
TRACE [2019-11-15 10:15:16,310] ({qtp89387388-14} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:16,311] ({qtp89387388-14} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@55d5a6f3
TRACE [2019-11-15 10:15:16,311] ({qtp89387388-14} ThreadContext.java[put]:169) - Bound value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] to thread [qtp89387388-14]
TRACE [2019-11-15 10:15:16,311] ({qtp89387388-14} ThreadContext.java[put]:169) - Bound value of type [org.apache.shiro.web.mgt.DefaultWebSecurityManager] for key [org.apache.shiro.util.ThreadContext_SECURITY_MANAGER_KEY] to thread [qtp89387388-14]
TRACE [2019-11-15 10:15:16,311] ({qtp89387388-14} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-14]
TRACE [2019-11-15 10:15:16,311] ({qtp89387388-14} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] bound to thread [qtp89387388-14]
TRACE [2019-11-15 10:15:16,311] ({qtp89387388-14} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:16,312] ({qtp89387388-14} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@55d5a6f3
TRACE [2019-11-15 10:15:16,312] ({qtp89387388-14} PathMatchingFilterChainResolver.java[getChain]:108) - Matched path pattern [/**] for requestURI [/api/login/logout]. Utilizing corresponding filter chain...
TRACE [2019-11-15 10:15:16,312] ({qtp89387388-14} AbstractShiroFilter.java[getExecutionChain]:417) - Resolved a configured FilterChain for the current request.
TRACE [2019-11-15 10:15:16,312] ({qtp89387388-14} ProxiedFilterChain.java[doFilter]:64) - Invoking wrapped filter at index [0]
DEBUG [2019-11-15 10:15:16,312] ({qtp89387388-14} DefaultSecurityLogic.java[perform]:66) - === SECURITY ===
DEBUG [2019-11-15 10:15:16,313] ({qtp89387388-14} DefaultSecurityLogic.java[perform]:92) - matchers: null
DEBUG [2019-11-15 10:15:16,313] ({qtp89387388-14} DefaultSecurityLogic.java[perform]:95) - clients: keycloakOidcClient
DEBUG [2019-11-15 10:15:16,313] ({qtp89387388-14} DefaultSecurityClientFinder.java[find]:37) - Provided clientNames: keycloakOidcClient
DEBUG [2019-11-15 10:15:16,313] ({qtp89387388-14} DefaultSecurityClientFinder.java[find]:53) - clientNameOnRequest: null
DEBUG [2019-11-15 10:15:16,314] ({qtp89387388-14} DefaultSecurityClientFinder.java[find]:79) - result: [keycloakOidcClient]
DEBUG [2019-11-15 10:15:16,314] ({qtp89387388-14} DefaultSecurityLogic.java[perform]:97) - currentClients: [#KeycloakOidcClient# | name: keycloakOidcClient | callbackUrl: http://localhost:8080/api/callback | callbackUrlResolver: org.pac4j.core.http.callback.QueryParameterCallbackUrlResolver@266d1b8e | ajaxRequestResolver: org.pac4j.core.http.ajax.DefaultAjaxRequestResolver@44a72b54 | redirectActionBuilder: org.pac4j.oidc.redirect.OidcRedirectActionBuilder@52eb37e7 | credentialsExtractor: org.pac4j.oidc.credentials.extractor.OidcExtractor@7316a6a7 | authenticator: org.pac4j.oidc.credentials.authenticator.OidcAuthenticator@3d697220 | profileCreator: org.pac4j.oidc.profile.creator.OidcProfileCreator@40e91352 | logoutActionBuilder: org.pac4j.oidc.logout.OidcLogoutActionBuilder@4bb650a7 | authorizationGenerators: [org.pac4j.oidc.authorization.generator.KeycloakRolesAuthorizationGenerator@223aa2f7, org.pac4j.oidc.authorization.generator.KeycloakRolesAuthorizationGenerator@d53f4e8] | configuration: #KeycloakOidcConfiguration# | clientId: zeppelin-client | secret: [protected] | discoveryURI: null | scope: openid | customParams: {} | clientAuthenticationMethod: client_secret_basic | useNonce: true | preferredJwsAlgorithm: null | maxAge: null | maxClockSkew: 30 | connectTimeout: 500 | readTimeout: 5000 | resourceRetriever: com.nimbusds.jose.util.DefaultResourceRetriever@7b5d7f10 | responseType: code | responseMode: null | logoutUrl: http://localhost:5000/auth/realms/zeppelin/protocol/openid-connect/logout | withState: false | stateGenerator: org.pac4j.core.state.StaticOrRandomStateGenerator@50ad3bc1 | |] DEBUG [2019-11-15 10:15:16,314] ({qtp89387388-14} DefaultSecurityLogic.java[perform]:100) - loadProfilesFromSession: true
TRACE [2019-11-15 10:15:16,314] ({qtp89387388-14} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-14]
TRACE [2019-11-15 10:15:16,315] ({qtp89387388-14} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] bound to thread [qtp89387388-14]
TRACE [2019-11-15 10:15:16,315] ({qtp89387388-14} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:16,315] ({qtp89387388-14} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@55d5a6f3
DEBUG [2019-11-15 10:15:16,316] ({qtp89387388-14} DefaultSecurityLogic.java[perform]:103) - profiles: [#KeycloakOidcProfile# | id: 23d46dce-ccbb-41c3-8dd8-49ddb29bb74b | attributes: {sub=23d46dce-ccbb-41c3-8dd8-49ddb29bb74b, email_verified=false, id_token=eyJhbGciOiJSUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICJyRG51S0NjVEFoalZUelZSaGtTTnhBaEhaeUtmS2JQQ3pmZUpnbmp3RkxBIn0.eyJqdGkiOiI0OGQ5ZDI2MC1hZDhiLTQ0MWItOWU0OC01MDVlOWZiYzY3ZmUiLCJleHAiOjE1NzM4MDk1ODksIm5iZiI6MCwiaWF0IjoxNTczODA5Mjg5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJ6ZXBwZWxpbi1jbGllbnQiLCJzdWIiOiIyM2Q0NmRjZS1jY2JiLTQxYzMtOGRkOC00OWRkYjI5YmI3NGIiLCJ0eXAiOiJJRCIsImF6cCI6InplcHBlbGluLWNsaWVudCIsIm5vbmNlIjoidnJSckREU0pjU0tYemxZTDlLOHdsb19nS0U2TnNhbE5oTmdOeXJaemR2SSIsImF1dGhfdGltZSI6MTU3MzgwOTI4OSwic2Vzc2lvbl9zdGF0ZSI6IjJkYzhmN2M0LWJjYzYtNDI0NC1iZTA0LThhZWM5Y2I1OWViZSIsImFjciI6IjEiLCJlbWFpbF92ZXJpZmllZCI6ZmFsc2UsInByZWZlcnJlZF91c2VybmFtZSI6ImVzaXJlbSJ9.gFCSWqHUJ-LIIdE4VmpgdXFDHvN7X6YniUdAUyIHaobf3vyOruSZoElBK2knSwEV3Fc7Iu_B5lrsyLbx21xUFH3UzUhAfhxjTn2uyS0AIPYGzI9OthRRpbRp2RbgrnmXIdYVA6F_b2cqWepQorSP4nu1vzHRRMm114dJcUVaMXZg4uyh54Og47BXi0O-1_Rxe0YqlY3EvMZu2Nt6WcebkTX9UEV4SaS8cFUUrsI57HouDNmTrumfR-DpiH15iSsOKQkLjFlz4RUb7xViwomA17qplObuDAOKsbz_3rDRzyxgNx8WjWrlBbXmu4r9ZMbVQAJr8607p1lIFEQshUZChQ, iss=http://localhost:5000/auth/realms/zeppelin, typ=ID, preferred_username=esirem, nonce=vrRrDDSJcSKXzlYL9K8wlo_gKE6NsalNhNgNyrZzdvI, access_token=eyJhbGciOiJSUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICJyRG51S0NjVEFoalZUelZSaGtTTnhBaEhaeUtmS2JQQ3pmZUpnbmp3RkxBIn0.eyJqdGkiOiJiZThjMTY0Yi1lZmNlLTQzYjktYmU3Ny05ZTdhZjc0MjEyZjYiLCJleHAiOjE1NzM4MDk1ODksIm5iZiI6MCwiaWF0IjoxNTczODA5Mjg5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJhY2NvdW50Iiwic3ViIjoiMjNkNDZkY2UtY2NiYi00MWMzLThkZDgtNDlkZGIyOWJiNzRiIiwidHlwIjoiQmVhcmVyIiwiYXpwIjoiemVwcGVsaW4tY2xpZW50Iiwibm9uY2UiOiJ2clJyRERTSmNTS1h6bFlMOUs4d2xvX2dLRTZOc2FsTmhOZ055clp6ZHZJIiwiYXV0aF90aW1lIjoxNTczODA5Mjg5LCJzZXNzaW9uX3N0YXRlIjoiMmRjOGY3YzQtYmNjNi00MjQ0LWJlMDQtOGFlYzljYjU5ZWJlIiwiYWNyIjoiMSIsInJlYWxtX2FjY2VzcyI6eyJyb2xlcyI6WyJvZmZsaW5lX2FjY2VzcyIsInVtYV9hdXRob3JpemF0aW9uIl19LCJyZXNvdXJjZV9hY2Nlc3MiOnsiemVwcGVsaW4tY2xpZW50Ijp7InJvbGVzIjpbInVzZXIiXX0sImFjY291bnQiOnsicm9sZXMiOlsibWFuYWdlLWFjY291bnQiLCJtYW5hZ2UtYWNjb3VudC1saW5rcyIsInZpZXctcHJvZmlsZSJdfX0sInNjb3BlIjoib3BlbmlkIHByb2ZpbGUgZW1haWwiLCJlbWFpbF92ZXJpZmllZCI6ZmFsc2UsInByZWZlcnJlZF91c2VybmFtZSI6ImVzaXJlbSJ9.Gs0PsPdxUJLAhvee_KW_XfjXzzUuAJuuSgO_IrS_0Vw7msep3_jLqirLTT2Vbo172EYvjun5tGwbXyywUAL7nHGhmAgHOpIPrhMRK91gTVkG-H4MpD7w-RRlcNvElAsOIgbJQpqO9vIXCj1bTk6l7zuKblIXCbsNXf5Jwl1q2xyy_fHduiShp7GzN77tw-F5JYIOoTtlM38Z02mo63ZIt7LQKYUELOA_Pr5jsvqGUdTerQ0OkxmgJjZiboTJ4zuXyf1F1yecIDBp0G_60iM49zmA1Txm5QdbVGZp3tMUjkcOHPS4w7Pk_V6N18lVjBhNTfUv33l3A3NfiwcHd-tsHQ, token_expiration_advance=-1, refresh_token=eyJhbGciOiJIUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICIwMmQ3ZGIxZi1iOTNjLTRlOGMtYTY5MC0zOWE5OGM0NjM0ZWMifQ.eyJqdGkiOiI1Y2VhYmFmOS1kMzNjLTRjMGItODk4Mi0zMGE2ODdkMTRhNmYiLCJleHAiOjE1NzM4MTEwODksIm5iZiI6MCwiaWF0IjoxNTczODA5Mjg5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJzdWIiOiIyM2Q0NmRjZS1jY2JiLTQxYzMtOGRkOC00OWRkYjI5YmI3NGIiLCJ0eXAiOiJSZWZyZXNoIiwiYXpwIjoiemVwcGVsaW4tY2xpZW50Iiwibm9uY2UiOiJ2clJyRERTSmNTS1h6bFlMOUs4d2xvX2dLRTZOc2FsTmhOZ055clp6ZHZJIiwiYXV0aF90aW1lIjowLCJzZXNzaW9uX3N0YXRlIjoiMmRjOGY3YzQtYmNjNi00MjQ0LWJlMDQtOGFlYzljYjU5ZWJlIiwicmVhbG1fYWNjZXNzIjp7InJvbGVzIjpbIm9mZmxpbmVfYWNjZXNzIiwidW1hX2F1dGhvcml6YXRpb24iXX0sInJlc291cmNlX2FjY2VzcyI6eyJ6ZXBwZWxpbi1jbGllbnQiOnsicm9sZXMiOlsidXNlciJdfSwiYWNjb3VudCI6eyJyb2xlcyI6WyJtYW5hZ2UtYWNjb3VudCIsIm1hbmFnZS1hY2NvdW50LWxpbmtzIiwidmlldy1wcm9maWxlIl19fSwic2NvcGUiOiJvcGVuaWQgcHJvZmlsZSBlbWFpbCJ9.2FqksV6dS_1zrC24g07XAPj0Ut8OCnm044h3tVLgZR0, aud=[zeppelin-client], acr=1, nbf=Thu Jan 01 01:00:00 CET 1970, azp=zeppelin-client, auth_time=Fri Nov 15 10:14:49 CET 2019, exp=Fri Nov 15 10:19:49 CET 2019, session_state=2dc8f7c4-bcc6-4244-be04-8aec9cb59ebe, iat=Fri Nov 15 10:14:49 CET 2019, jti=48d9d260-ad8b-441b-9e48-505e9fbc67fe} | roles: [offline_access, uma_authorization, user] | permissions: [] | isRemembered: false | clientName: keycloakOidcClient | linkedId: null |] DEBUG [2019-11-15 10:15:16,316] ({qtp89387388-14} DefaultSecurityLogic.java[perform]:137) - authorizers: null
DEBUG [2019-11-15 10:15:16,316] ({qtp89387388-14} DefaultSecurityLogic.java[perform]:139) - authenticated and authorized -> grant access
TRACE [2019-11-15 10:15:16,316] ({qtp89387388-14} ProxiedFilterChain.java[doFilter]:59) - Invoking original filter chain.
TRACE [2019-11-15 10:15:16,319] ({qtp89387388-14} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-14]
TRACE [2019-11-15 10:15:16,319] ({qtp89387388-14} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] bound to thread [qtp89387388-14]
TRACE [2019-11-15 10:15:16,320] ({qtp89387388-14} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-14]
TRACE [2019-11-15 10:15:16,320] ({qtp89387388-14} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] bound to thread [qtp89387388-14]
TRACE [2019-11-15 10:15:16,320] ({qtp89387388-14} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:16,321] ({qtp89387388-14} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@55d5a6f3
TRACE [2019-11-15 10:15:16,321] ({qtp89387388-14} DelegatingSubject.java[getSession]:321) - attempting to get session; create = true; session is null = false; session has id = true
TRACE [2019-11-15 10:15:16,322] ({qtp89387388-14} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@55d5a6f3
DEBUG [2019-11-15 10:15:16,322] ({qtp89387388-14} AbstractNativeSessionManager.java[stop]:284) - Stopping session with id [4582bcb1-87cc-47c4-bae5-be6971f56f7e]
DEBUG [2019-11-15 10:15:16,322] ({qtp89387388-14} DefaultWebSessionManager.java[onStop]:314) - Session has been stopped (subject logout or explicit stop). Removing session ID cookie.
TRACE [2019-11-15 10:15:16,323] ({qtp89387388-14} SimpleCookie.java[calculatePath]:199) - calculated path: /
DEBUG [2019-11-15 10:15:16,324] ({qtp89387388-14} SimpleCookie.java[addCookieHeader]:226) - Added HttpServletResponse Cookie [JSESSIONID=deleteMe; Path=/; Max-Age=0; Expires=Thu, 14-Nov-2019 09:15:16 GMT]
TRACE [2019-11-15 10:15:16,324] ({qtp89387388-14} SimpleCookie.java[removeFrom]:375) - Removed 'JSESSIONID' cookie by setting maxAge=0
TRACE [2019-11-15 10:15:16,326] ({qtp89387388-14} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = true; session has id = false
TRACE [2019-11-15 10:15:16,326] ({qtp89387388-14} SimpleCookie.java[calculatePath]:199) - calculated path: /
DEBUG [2019-11-15 10:15:16,326] ({qtp89387388-14} SimpleCookie.java[addCookieHeader]:226) - Added HttpServletResponse Cookie [rememberMe=deleteMe; Path=/; Max-Age=0; Expires=Thu, 14-Nov-2019 09:15:16 GMT]
TRACE [2019-11-15 10:15:16,327] ({qtp89387388-14} SimpleCookie.java[removeFrom]:375) - Removed 'rememberMe' cookie by setting maxAge=0
TRACE [2019-11-15 10:15:16,327] ({qtp89387388-14} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = true; session has id = false
DEBUG [2019-11-15 10:15:16,328] ({qtp89387388-14} DefaultSecurityManager.java[logout]:559) - Logging out subject with primary principal #Pac4jPrincipal# | profiles: [#KeycloakOidcProfile# | id: 23d46dce-ccbb-41c3-8dd8-49ddb29bb74b | attributes: {sub=23d46dce-ccbb-41c3-8dd8-49ddb29bb74b, email_verified=false, id_token=eyJhbGciOiJSUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICJyRG51S0NjVEFoalZUelZSaGtTTnhBaEhaeUtmS2JQQ3pmZUpnbmp3RkxBIn0.eyJqdGkiOiI0OGQ5ZDI2MC1hZDhiLTQ0MWItOWU0OC01MDVlOWZiYzY3ZmUiLCJleHAiOjE1NzM4MDk1ODksIm5iZiI6MCwiaWF0IjoxNTczODA5Mjg5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJ6ZXBwZWxpbi1jbGllbnQiLCJzdWIiOiIyM2Q0NmRjZS1jY2JiLTQxYzMtOGRkOC00OWRkYjI5YmI3NGIiLCJ0eXAiOiJJRCIsImF6cCI6InplcHBlbGluLWNsaWVudCIsIm5vbmNlIjoidnJSckREU0pjU0tYemxZTDlLOHdsb19nS0U2TnNhbE5oTmdOeXJaemR2SSIsImF1dGhfdGltZSI6MTU3MzgwOTI4OSwic2Vzc2lvbl9zdGF0ZSI6IjJkYzhmN2M0LWJjYzYtNDI0NC1iZTA0LThhZWM5Y2I1OWViZSIsImFjciI6IjEiLCJlbWFpbF92ZXJpZmllZCI6ZmFsc2UsInByZWZlcnJlZF91c2VybmFtZSI6ImVzaXJlbSJ9.gFCSWqHUJ-LIIdE4VmpgdXFDHvN7X6YniUdAUyIHaobf3vyOruSZoElBK2knSwEV3Fc7Iu_B5lrsyLbx21xUFH3UzUhAfhxjTn2uyS0AIPYGzI9OthRRpbRp2RbgrnmXIdYVA6F_b2cqWepQorSP4nu1vzHRRMm114dJcUVaMXZg4uyh54Og47BXi0O-1_Rxe0YqlY3EvMZu2Nt6WcebkTX9UEV4SaS8cFUUrsI57HouDNmTrumfR-DpiH15iSsOKQkLjFlz4RUb7xViwomA17qplObuDAOKsbz_3rDRzyxgNx8WjWrlBbXmu4r9ZMbVQAJr8607p1lIFEQshUZChQ, iss=http://localhost:5000/auth/realms/zeppelin, typ=ID, preferred_username=esirem, nonce=vrRrDDSJcSKXzlYL9K8wlo_gKE6NsalNhNgNyrZzdvI, access_token=eyJhbGciOiJSUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICJyRG51S0NjVEFoalZUelZSaGtTTnhBaEhaeUtmS2JQQ3pmZUpnbmp3RkxBIn0.eyJqdGkiOiJiZThjMTY0Yi1lZmNlLTQzYjktYmU3Ny05ZTdhZjc0MjEyZjYiLCJleHAiOjE1NzM4MDk1ODksIm5iZiI6MCwiaWF0IjoxNTczODA5Mjg5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJhY2NvdW50Iiwic3ViIjoiMjNkNDZkY2UtY2NiYi00MWMzLThkZDgtNDlkZGIyOWJiNzRiIiwidHlwIjoiQmVhcmVyIiwiYXpwIjoiemVwcGVsaW4tY2xpZW50Iiwibm9uY2UiOiJ2clJyRERTSmNTS1h6bFlMOUs4d2xvX2dLRTZOc2FsTmhOZ055clp6ZHZJIiwiYXV0aF90aW1lIjoxNTczODA5Mjg5LCJzZXNzaW9uX3N0YXRlIjoiMmRjOGY3YzQtYmNjNi00MjQ0LWJlMDQtOGFlYzljYjU5ZWJlIiwiYWNyIjoiMSIsInJlYWxtX2FjY2VzcyI6eyJyb2xlcyI6WyJvZmZsaW5lX2FjY2VzcyIsInVtYV9hdXRob3JpemF0aW9uIl19LCJyZXNvdXJjZV9hY2Nlc3MiOnsiemVwcGVsaW4tY2xpZW50Ijp7InJvbGVzIjpbInVzZXIiXX0sImFjY291bnQiOnsicm9sZXMiOlsibWFuYWdlLWFjY291bnQiLCJtYW5hZ2UtYWNjb3VudC1saW5rcyIsInZpZXctcHJvZmlsZSJdfX0sInNjb3BlIjoib3BlbmlkIHByb2ZpbGUgZW1haWwiLCJlbWFpbF92ZXJpZmllZCI6ZmFsc2UsInByZWZlcnJlZF91c2VybmFtZSI6ImVzaXJlbSJ9.Gs0PsPdxUJLAhvee_KW_XfjXzzUuAJuuSgO_IrS_0Vw7msep3_jLqirLTT2Vbo172EYvjun5tGwbXyywUAL7nHGhmAgHOpIPrhMRK91gTVkG-H4MpD7w-RRlcNvElAsOIgbJQpqO9vIXCj1bTk6l7zuKblIXCbsNXf5Jwl1q2xyy_fHduiShp7GzN77tw-F5JYIOoTtlM38Z02mo63ZIt7LQKYUELOA_Pr5jsvqGUdTerQ0OkxmgJjZiboTJ4zuXyf1F1yecIDBp0G_60iM49zmA1Txm5QdbVGZp3tMUjkcOHPS4w7Pk_V6N18lVjBhNTfUv33l3A3NfiwcHd-tsHQ, token_expiration_advance=-1, refresh_token=eyJhbGciOiJIUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICIwMmQ3ZGIxZi1iOTNjLTRlOGMtYTY5MC0zOWE5OGM0NjM0ZWMifQ.eyJqdGkiOiI1Y2VhYmFmOS1kMzNjLTRjMGItODk4Mi0zMGE2ODdkMTRhNmYiLCJleHAiOjE1NzM4MTEwODksIm5iZiI6MCwiaWF0IjoxNTczODA5Mjg5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJzdWIiOiIyM2Q0NmRjZS1jY2JiLTQxYzMtOGRkOC00OWRkYjI5YmI3NGIiLCJ0eXAiOiJSZWZyZXNoIiwiYXpwIjoiemVwcGVsaW4tY2xpZW50Iiwibm9uY2UiOiJ2clJyRERTSmNTS1h6bFlMOUs4d2xvX2dLRTZOc2FsTmhOZ055clp6ZHZJIiwiYXV0aF90aW1lIjowLCJzZXNzaW9uX3N0YXRlIjoiMmRjOGY3YzQtYmNjNi00MjQ0LWJlMDQtOGFlYzljYjU5ZWJlIiwicmVhbG1fYWNjZXNzIjp7InJvbGVzIjpbIm9mZmxpbmVfYWNjZXNzIiwidW1hX2F1dGhvcml6YXRpb24iXX0sInJlc291cmNlX2FjY2VzcyI6eyJ6ZXBwZWxpbi1jbGllbnQiOnsicm9sZXMiOlsidXNlciJdfSwiYWNjb3VudCI6eyJyb2xlcyI6WyJtYW5hZ2UtYWNjb3VudCIsIm1hbmFnZS1hY2NvdW50LWxpbmtzIiwidmlldy1wcm9maWxlIl19fSwic2NvcGUiOiJvcGVuaWQgcHJvZmlsZSBlbWFpbCJ9.2FqksV6dS_1zrC24g07XAPj0Ut8OCnm044h3tVLgZR0, aud=[zeppelin-client], acr=1, nbf=Thu Jan 01 01:00:00 CET 1970, azp=zeppelin-client, auth_time=Fri Nov 15 10:14:49 CET 2019, exp=Fri Nov 15 10:19:49 CET 2019, session_state=2dc8f7c4-bcc6-4244-be04-8aec9cb59ebe, iat=Fri Nov 15 10:14:49 CET 2019, jti=48d9d260-ad8b-441b-9e48-505e9fbc67fe} | roles: [offline_access, uma_authorization, user] | permissions: [] | isRemembered: false | clientName: keycloakOidcClient | linkedId: null |] | DEBUG [2019-11-15 10:15:16,328] ({qtp89387388-14} AuthorizingRealm.java[getAuthorizationCacheLazy]:234) - No authorizationCache instance set. Checking for a cacheManager...
DEBUG [2019-11-15 10:15:16,330] ({qtp89387388-14} AuthorizingRealm.java[getAuthorizationCacheLazy]:248) - No cache or cacheManager properties have been set. Authorization cache cannot be obtained.
TRACE [2019-11-15 10:15:16,330] ({qtp89387388-14} CachingRealm.java[clearCache]:171) - Cleared cache entries for account with principals [#Pac4jPrincipal# | profiles: [#KeycloakOidcProfile# | id: 23d46dce-ccbb-41c3-8dd8-49ddb29bb74b | attributes: {sub=23d46dce-ccbb-41c3-8dd8-49ddb29bb74b, email_verified=false, id_token=eyJhbGciOiJSUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICJyRG51S0NjVEFoalZUelZSaGtTTnhBaEhaeUtmS2JQQ3pmZUpnbmp3RkxBIn0.eyJqdGkiOiI0OGQ5ZDI2MC1hZDhiLTQ0MWItOWU0OC01MDVlOWZiYzY3ZmUiLCJleHAiOjE1NzM4MDk1ODksIm5iZiI6MCwiaWF0IjoxNTczODA5Mjg5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJ6ZXBwZWxpbi1jbGllbnQiLCJzdWIiOiIyM2Q0NmRjZS1jY2JiLTQxYzMtOGRkOC00OWRkYjI5YmI3NGIiLCJ0eXAiOiJJRCIsImF6cCI6InplcHBlbGluLWNsaWVudCIsIm5vbmNlIjoidnJSckREU0pjU0tYemxZTDlLOHdsb19nS0U2TnNhbE5oTmdOeXJaemR2SSIsImF1dGhfdGltZSI6MTU3MzgwOTI4OSwic2Vzc2lvbl9zdGF0ZSI6IjJkYzhmN2M0LWJjYzYtNDI0NC1iZTA0LThhZWM5Y2I1OWViZSIsImFjciI6IjEiLCJlbWFpbF92ZXJpZmllZCI6ZmFsc2UsInByZWZlcnJlZF91c2VybmFtZSI6ImVzaXJlbSJ9.gFCSWqHUJ-LIIdE4VmpgdXFDHvN7X6YniUdAUyIHaobf3vyOruSZoElBK2knSwEV3Fc7Iu_B5lrsyLbx21xUFH3UzUhAfhxjTn2uyS0AIPYGzI9OthRRpbRp2RbgrnmXIdYVA6F_b2cqWepQorSP4nu1vzHRRMm114dJcUVaMXZg4uyh54Og47BXi0O-1_Rxe0YqlY3EvMZu2Nt6WcebkTX9UEV4SaS8cFUUrsI57HouDNmTrumfR-DpiH15iSsOKQkLjFlz4RUb7xViwomA17qplObuDAOKsbz_3rDRzyxgNx8WjWrlBbXmu4r9ZMbVQAJr8607p1lIFEQshUZChQ, iss=http://localhost:5000/auth/realms/zeppelin, typ=ID, preferred_username=esirem, nonce=vrRrDDSJcSKXzlYL9K8wlo_gKE6NsalNhNgNyrZzdvI, access_token=eyJhbGciOiJSUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICJyRG51S0NjVEFoalZUelZSaGtTTnhBaEhaeUtmS2JQQ3pmZUpnbmp3RkxBIn0.eyJqdGkiOiJiZThjMTY0Yi1lZmNlLTQzYjktYmU3Ny05ZTdhZjc0MjEyZjYiLCJleHAiOjE1NzM4MDk1ODksIm5iZiI6MCwiaWF0IjoxNTczODA5Mjg5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJhY2NvdW50Iiwic3ViIjoiMjNkNDZkY2UtY2NiYi00MWMzLThkZDgtNDlkZGIyOWJiNzRiIiwidHlwIjoiQmVhcmVyIiwiYXpwIjoiemVwcGVsaW4tY2xpZW50Iiwibm9uY2UiOiJ2clJyRERTSmNTS1h6bFlMOUs4d2xvX2dLRTZOc2FsTmhOZ055clp6ZHZJIiwiYXV0aF90aW1lIjoxNTczODA5Mjg5LCJzZXNzaW9uX3N0YXRlIjoiMmRjOGY3YzQtYmNjNi00MjQ0LWJlMDQtOGFlYzljYjU5ZWJlIiwiYWNyIjoiMSIsInJlYWxtX2FjY2VzcyI6eyJyb2xlcyI6WyJvZmZsaW5lX2FjY2VzcyIsInVtYV9hdXRob3JpemF0aW9uIl19LCJyZXNvdXJjZV9hY2Nlc3MiOnsiemVwcGVsaW4tY2xpZW50Ijp7InJvbGVzIjpbInVzZXIiXX0sImFjY291bnQiOnsicm9sZXMiOlsibWFuYWdlLWFjY291bnQiLCJtYW5hZ2UtYWNjb3VudC1saW5rcyIsInZpZXctcHJvZmlsZSJdfX0sInNjb3BlIjoib3BlbmlkIHByb2ZpbGUgZW1haWwiLCJlbWFpbF92ZXJpZmllZCI6ZmFsc2UsInByZWZlcnJlZF91c2VybmFtZSI6ImVzaXJlbSJ9.Gs0PsPdxUJLAhvee_KW_XfjXzzUuAJuuSgO_IrS_0Vw7msep3_jLqirLTT2Vbo172EYvjun5tGwbXyywUAL7nHGhmAgHOpIPrhMRK91gTVkG-H4MpD7w-RRlcNvElAsOIgbJQpqO9vIXCj1bTk6l7zuKblIXCbsNXf5Jwl1q2xyy_fHduiShp7GzN77tw-F5JYIOoTtlM38Z02mo63ZIt7LQKYUELOA_Pr5jsvqGUdTerQ0OkxmgJjZiboTJ4zuXyf1F1yecIDBp0G_60iM49zmA1Txm5QdbVGZp3tMUjkcOHPS4w7Pk_V6N18lVjBhNTfUv33l3A3NfiwcHd-tsHQ, token_expiration_advance=-1, refresh_token=eyJhbGciOiJIUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICIwMmQ3ZGIxZi1iOTNjLTRlOGMtYTY5MC0zOWE5OGM0NjM0ZWMifQ.eyJqdGkiOiI1Y2VhYmFmOS1kMzNjLTRjMGItODk4Mi0zMGE2ODdkMTRhNmYiLCJleHAiOjE1NzM4MTEwODksIm5iZiI6MCwiaWF0IjoxNTczODA5Mjg5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJzdWIiOiIyM2Q0NmRjZS1jY2JiLTQxYzMtOGRkOC00OWRkYjI5YmI3NGIiLCJ0eXAiOiJSZWZyZXNoIiwiYXpwIjoiemVwcGVsaW4tY2xpZW50Iiwibm9uY2UiOiJ2clJyRERTSmNTS1h6bFlMOUs4d2xvX2dLRTZOc2FsTmhOZ055clp6ZHZJIiwiYXV0aF90aW1lIjowLCJzZXNzaW9uX3N0YXRlIjoiMmRjOGY3YzQtYmNjNi00MjQ0LWJlMDQtOGFlYzljYjU5ZWJlIiwicmVhbG1fYWNjZXNzIjp7InJvbGVzIjpbIm9mZmxpbmVfYWNjZXNzIiwidW1hX2F1dGhvcml6YXRpb24iXX0sInJlc291cmNlX2FjY2VzcyI6eyJ6ZXBwZWxpbi1jbGllbnQiOnsicm9sZXMiOlsidXNlciJdfSwiYWNjb3VudCI6eyJyb2xlcyI6WyJtYW5hZ2UtYWNjb3VudCIsIm1hbmFnZS1hY2NvdW50LWxpbmtzIiwidmlldy1wcm9maWxlIl19fSwic2NvcGUiOiJvcGVuaWQgcHJvZmlsZSBlbWFpbCJ9.2FqksV6dS_1zrC24g07XAPj0Ut8OCnm044h3tVLgZR0, aud=[zeppelin-client], acr=1, nbf=Thu Jan 01 01:00:00 CET 1970, azp=zeppelin-client, auth_time=Fri Nov 15 10:14:49 CET 2019, exp=Fri Nov 15 10:19:49 CET 2019, session_state=2dc8f7c4-bcc6-4244-be04-8aec9cb59ebe, iat=Fri Nov 15 10:14:49 CET 2019, jti=48d9d260-ad8b-441b-9e48-505e9fbc67fe} | roles: [offline_access, uma_authorization, user] | permissions: [] | isRemembered: false | clientName: keycloakOidcClient | linkedId: null |] |] TRACE [2019-11-15 10:15:16,330] ({qtp89387388-14} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = true; session has id = false
TRACE [2019-11-15 10:15:16,331] ({qtp89387388-14} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = true; session has id = false
TRACE [2019-11-15 10:15:16,331] ({qtp89387388-14} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-14]
TRACE [2019-11-15 10:15:16,331] ({qtp89387388-14} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.mgt.DefaultWebSecurityManager] for key [org.apache.shiro.util.ThreadContext_SECURITY_MANAGER_KEY] bound to thread [qtp89387388-14]
TRACE [2019-11-15 10:15:16,367] ({qtp89387388-13} OncePerRequestFilter.java[doFilter]:121) - Filter 'org.apache.shiro.web.servlet.ShiroFilter-17baae6e' not yet executed. Executing now.
TRACE [2019-11-15 10:15:16,367] ({qtp89387388-13} DefaultSecurityManager.java[ensureSecurityManager]:417) - Context already contains a SecurityManager instance. Returning.
TRACE [2019-11-15 10:15:16,368] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@2c4d8503
TRACE [2019-11-15 10:15:16,368] ({qtp89387388-13} SimpleCookie.java[readValue]:392) - No 'JSESSIONID' cookie value
DEBUG [2019-11-15 10:15:16,368] ({qtp89387388-13} DefaultSessionManager.java[retrieveSession]:218) - Unable to resolve session ID from SessionKey [org.apache.shiro.web.session.mgt.WebSessionKey@2c4d8503]. Returning null to indicate a session could not be found.
TRACE [2019-11-15 10:15:16,368] ({qtp89387388-13} DefaultSecurityManager.java[resolvePrincipals]:499) - No identity (PrincipalCollection) found in the context. Looking for a remembered identity.
TRACE [2019-11-15 10:15:16,369] ({qtp89387388-13} SimpleCookie.java[readValue]:392) - No 'rememberMe' cookie value
TRACE [2019-11-15 10:15:16,369] ({qtp89387388-13} DefaultSecurityManager.java[resolvePrincipals]:525) - No remembered identity found. Returning original context.
TRACE [2019-11-15 10:15:16,369] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = true; session has id = false
TRACE [2019-11-15 10:15:16,369] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = true; session has id = false
TRACE [2019-11-15 10:15:16,369] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = true; session has id = false
TRACE [2019-11-15 10:15:16,369] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = true; session has id = false
TRACE [2019-11-15 10:15:16,370] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = true; session has id = false
TRACE [2019-11-15 10:15:16,370] ({qtp89387388-13} ThreadContext.java[put]:169) - Bound value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:16,370] ({qtp89387388-13} ThreadContext.java[put]:169) - Bound value of type [org.apache.shiro.web.mgt.DefaultWebSecurityManager] for key [org.apache.shiro.util.ThreadContext_SECURITY_MANAGER_KEY] to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:16,370] ({qtp89387388-13} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-13]
TRACE [2019-11-15 10:15:16,370] ({qtp89387388-13} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] bound to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:16,370] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = true; session has id = false
TRACE [2019-11-15 10:15:16,370] ({qtp89387388-13} PathMatchingFilterChainResolver.java[getChain]:108) - Matched path pattern [/**] for requestURI [/api/login/logout]. Utilizing corresponding filter chain...
TRACE [2019-11-15 10:15:16,371] ({qtp89387388-13} AbstractShiroFilter.java[getExecutionChain]:417) - Resolved a configured FilterChain for the current request.
TRACE [2019-11-15 10:15:16,371] ({qtp89387388-13} ProxiedFilterChain.java[doFilter]:64) - Invoking wrapped filter at index [0]
DEBUG [2019-11-15 10:15:16,371] ({qtp89387388-13} DefaultSecurityLogic.java[perform]:66) - === SECURITY ===
DEBUG [2019-11-15 10:15:16,371] ({qtp89387388-13} DefaultSecurityLogic.java[perform]:92) - matchers: null
DEBUG [2019-11-15 10:15:16,371] ({qtp89387388-13} DefaultSecurityLogic.java[perform]:95) - clients: keycloakOidcClient
DEBUG [2019-11-15 10:15:16,371] ({qtp89387388-13} DefaultSecurityClientFinder.java[find]:37) - Provided clientNames: keycloakOidcClient
DEBUG [2019-11-15 10:15:16,371] ({qtp89387388-13} DefaultSecurityClientFinder.java[find]:53) - clientNameOnRequest: null
DEBUG [2019-11-15 10:15:16,372] ({qtp89387388-13} DefaultSecurityClientFinder.java[find]:79) - result: [keycloakOidcClient]
DEBUG [2019-11-15 10:15:16,372] ({qtp89387388-13} DefaultSecurityLogic.java[perform]:97) - currentClients: [#KeycloakOidcClient# | name: keycloakOidcClient | callbackUrl: http://localhost:8080/api/callback | callbackUrlResolver: org.pac4j.core.http.callback.QueryParameterCallbackUrlResolver@266d1b8e | ajaxRequestResolver: org.pac4j.core.http.ajax.DefaultAjaxRequestResolver@44a72b54 | redirectActionBuilder: org.pac4j.oidc.redirect.OidcRedirectActionBuilder@52eb37e7 | credentialsExtractor: org.pac4j.oidc.credentials.extractor.OidcExtractor@7316a6a7 | authenticator: org.pac4j.oidc.credentials.authenticator.OidcAuthenticator@3d697220 | profileCreator: org.pac4j.oidc.profile.creator.OidcProfileCreator@40e91352 | logoutActionBuilder: org.pac4j.oidc.logout.OidcLogoutActionBuilder@4bb650a7 | authorizationGenerators: [org.pac4j.oidc.authorization.generator.KeycloakRolesAuthorizationGenerator@223aa2f7, org.pac4j.oidc.authorization.generator.KeycloakRolesAuthorizationGenerator@d53f4e8] | configuration: #KeycloakOidcConfiguration# | clientId: zeppelin-client | secret: [protected] | discoveryURI: null | scope: openid | customParams: {} | clientAuthenticationMethod: client_secret_basic | useNonce: true | preferredJwsAlgorithm: null | maxAge: null | maxClockSkew: 30 | connectTimeout: 500 | readTimeout: 5000 | resourceRetriever: com.nimbusds.jose.util.DefaultResourceRetriever@7b5d7f10 | responseType: code | responseMode: null | logoutUrl: http://localhost:5000/auth/realms/zeppelin/protocol/openid-connect/logout | withState: false | stateGenerator: org.pac4j.core.state.StaticOrRandomStateGenerator@50ad3bc1 | |] DEBUG [2019-11-15 10:15:16,372] ({qtp89387388-13} DefaultSecurityLogic.java[perform]:100) - loadProfilesFromSession: true
TRACE [2019-11-15 10:15:16,372] ({qtp89387388-13} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-13]
TRACE [2019-11-15 10:15:16,372] ({qtp89387388-13} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] bound to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:16,372] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = true; session has id = false
DEBUG [2019-11-15 10:15:16,373] ({qtp89387388-13} DefaultSecurityLogic.java[perform]:103) - profiles: []
DEBUG [2019-11-15 10:15:16,373] ({qtp89387388-13} DefaultSecurityLogic.java[perform]:147) - Starting authentication
INFO [2019-11-15 10:15:16,373] ({qtp89387388-13} IndirectClient.java[getRedirectAction]:96) - AJAX request detected -> returning the appropriate action
TRACE [2019-11-15 10:15:16,373] ({qtp89387388-13} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-13]
TRACE [2019-11-15 10:15:16,374] ({qtp89387388-13} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] bound to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:16,374] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = true; session is null = true; session has id = false
TRACE [2019-11-15 10:15:16,374] ({qtp89387388-13} DelegatingSubject.java[getSession]:338) - Starting session for host 127.0.0.1
TRACE [2019-11-15 10:15:16,374] ({qtp89387388-13} DefaultSessionManager.java[doCreateSession]:156) - Creating session for host 127.0.0.1
DEBUG [2019-11-15 10:15:16,374] ({qtp89387388-13} DefaultSessionManager.java[create]:175) - Creating new EIS record for new session instance [org.apache.shiro.session.mgt.SimpleSession,id=null]
TRACE [2019-11-15 10:15:16,374] ({qtp89387388-13} SimpleCookie.java[calculatePath]:199) - calculated path: /
DEBUG [2019-11-15 10:15:16,375] ({qtp89387388-13} SimpleCookie.java[addCookieHeader]:226) - Added HttpServletResponse Cookie [JSESSIONID=eccd494d-6f7e-47f6-8bab-18f44f9d55a7; Path=/; HttpOnly]
TRACE [2019-11-15 10:15:16,375] ({qtp89387388-13} DefaultWebSessionManager.java[storeSessionId]:101) - Set session ID cookie for session with id eccd494d-6f7e-47f6-8bab-18f44f9d55a7
TRACE [2019-11-15 10:15:16,375] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@5b275155
TRACE [2019-11-15 10:15:16,375] ({qtp89387388-13} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-13]
TRACE [2019-11-15 10:15:16,375] ({qtp89387388-13} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] bound to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:16,376] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = true; session is null = false; session has id = true
TRACE [2019-11-15 10:15:16,376] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@5b275155
TRACE [2019-11-15 10:15:16,377] ({qtp89387388-13} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-13]
TRACE [2019-11-15 10:15:16,377] ({qtp89387388-13} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] bound to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:16,377] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:16,378] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@5b275155
DEBUG [2019-11-15 10:15:16,379] ({qtp89387388-13} AbstractExceptionAwareLogic.java[handleException]:41) - extra HTTP action required in security: 401
TRACE [2019-11-15 10:15:18,456] ({qtp89387388-13} OncePerRequestFilter.java[doFilter]:121) - Filter 'org.apache.shiro.web.servlet.ShiroFilter-17baae6e' not yet executed. Executing now.
TRACE [2019-11-15 10:15:18,457] ({qtp89387388-13} DefaultSecurityManager.java[ensureSecurityManager]:417) - Context already contains a SecurityManager instance. Returning.
TRACE [2019-11-15 10:15:18,457] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@395d654b
DEBUG [2019-11-15 10:15:18,457] ({qtp89387388-13} SimpleCookie.java[readValue]:389) - Found 'JSESSIONID' cookie value [eccd494d-6f7e-47f6-8bab-18f44f9d55a7]
TRACE [2019-11-15 10:15:18,457] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@67981f6a
TRACE [2019-11-15 10:15:18,457] ({qtp89387388-13} DefaultSecurityManager.java[resolvePrincipals]:499) - No identity (PrincipalCollection) found in the context. Looking for a remembered identity.
TRACE [2019-11-15 10:15:18,457] ({qtp89387388-13} SimpleCookie.java[readValue]:392) - No 'rememberMe' cookie value
TRACE [2019-11-15 10:15:18,458] ({qtp89387388-13} DefaultSecurityManager.java[resolvePrincipals]:525) - No remembered identity found. Returning original context.
TRACE [2019-11-15 10:15:18,458] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@67981f6a
TRACE [2019-11-15 10:15:18,458] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@67981f6a
TRACE [2019-11-15 10:15:18,458] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@67981f6a
TRACE [2019-11-15 10:15:18,458] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:18,459] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:18,459] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@67981f6a
TRACE [2019-11-15 10:15:18,459] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:18,459] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@67981f6a
TRACE [2019-11-15 10:15:18,459] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:18,459] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@67981f6a
TRACE [2019-11-15 10:15:18,459] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:18,460] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@67981f6a
TRACE [2019-11-15 10:15:18,460] ({qtp89387388-13} ThreadContext.java[put]:169) - Bound value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:18,460] ({qtp89387388-13} ThreadContext.java[put]:169) - Bound value of type [org.apache.shiro.web.mgt.DefaultWebSecurityManager] for key [org.apache.shiro.util.ThreadContext_SECURITY_MANAGER_KEY] to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:18,460] ({qtp89387388-13} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-13]
TRACE [2019-11-15 10:15:18,460] ({qtp89387388-13} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] bound to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:18,460] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:18,460] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@67981f6a
TRACE [2019-11-15 10:15:18,461] ({qtp89387388-13} PathMatchingFilterChainResolver.java[getChain]:108) - Matched path pattern [/**] for requestURI [/api/security/ticket]. Utilizing corresponding filter chain...
TRACE [2019-11-15 10:15:18,461] ({qtp89387388-13} AbstractShiroFilter.java[getExecutionChain]:417) - Resolved a configured FilterChain for the current request.
TRACE [2019-11-15 10:15:18,461] ({qtp89387388-13} ProxiedFilterChain.java[doFilter]:64) - Invoking wrapped filter at index [0]
DEBUG [2019-11-15 10:15:18,461] ({qtp89387388-13} DefaultSecurityLogic.java[perform]:66) - === SECURITY ===
DEBUG [2019-11-15 10:15:18,462] ({qtp89387388-13} DefaultSecurityLogic.java[perform]:92) - matchers: null
DEBUG [2019-11-15 10:15:18,462] ({qtp89387388-13} DefaultSecurityLogic.java[perform]:95) - clients: keycloakOidcClient
DEBUG [2019-11-15 10:15:18,462] ({qtp89387388-13} DefaultSecurityClientFinder.java[find]:37) - Provided clientNames: keycloakOidcClient
DEBUG [2019-11-15 10:15:18,462] ({qtp89387388-13} DefaultSecurityClientFinder.java[find]:53) - clientNameOnRequest: null
DEBUG [2019-11-15 10:15:18,463] ({qtp89387388-13} DefaultSecurityClientFinder.java[find]:79) - result: [keycloakOidcClient]
DEBUG [2019-11-15 10:15:18,463] ({qtp89387388-13} DefaultSecurityLogic.java[perform]:97) - currentClients: [#KeycloakOidcClient# | name: keycloakOidcClient | callbackUrl: http://localhost:8080/api/callback | callbackUrlResolver: org.pac4j.core.http.callback.QueryParameterCallbackUrlResolver@266d1b8e | ajaxRequestResolver: org.pac4j.core.http.ajax.DefaultAjaxRequestResolver@44a72b54 | redirectActionBuilder: org.pac4j.oidc.redirect.OidcRedirectActionBuilder@52eb37e7 | credentialsExtractor: org.pac4j.oidc.credentials.extractor.OidcExtractor@7316a6a7 | authenticator: org.pac4j.oidc.credentials.authenticator.OidcAuthenticator@3d697220 | profileCreator: org.pac4j.oidc.profile.creator.OidcProfileCreator@40e91352 | logoutActionBuilder: org.pac4j.oidc.logout.OidcLogoutActionBuilder@4bb650a7 | authorizationGenerators: [org.pac4j.oidc.authorization.generator.KeycloakRolesAuthorizationGenerator@223aa2f7, org.pac4j.oidc.authorization.generator.KeycloakRolesAuthorizationGenerator@d53f4e8] | configuration: #KeycloakOidcConfiguration# | clientId: zeppelin-client | secret: [protected] | discoveryURI: null | scope: openid | customParams: {} | clientAuthenticationMethod: client_secret_basic | useNonce: true | preferredJwsAlgorithm: null | maxAge: null | maxClockSkew: 30 | connectTimeout: 500 | readTimeout: 5000 | resourceRetriever: com.nimbusds.jose.util.DefaultResourceRetriever@7b5d7f10 | responseType: code | responseMode: null | logoutUrl: http://localhost:5000/auth/realms/zeppelin/protocol/openid-connect/logout | withState: false | stateGenerator: org.pac4j.core.state.StaticOrRandomStateGenerator@50ad3bc1 | |] DEBUG [2019-11-15 10:15:18,463] ({qtp89387388-13} DefaultSecurityLogic.java[perform]:100) - loadProfilesFromSession: true
TRACE [2019-11-15 10:15:18,464] ({qtp89387388-13} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-13]
TRACE [2019-11-15 10:15:18,464] ({qtp89387388-13} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] bound to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:18,464] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:18,464] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@67981f6a
DEBUG [2019-11-15 10:15:18,464] ({qtp89387388-13} DefaultSecurityLogic.java[perform]:103) - profiles: []
DEBUG [2019-11-15 10:15:18,465] ({qtp89387388-13} DefaultSecurityLogic.java[perform]:147) - Starting authentication
INFO [2019-11-15 10:15:18,465] ({qtp89387388-13} IndirectClient.java[getRedirectAction]:96) - AJAX request detected -> returning the appropriate action
TRACE [2019-11-15 10:15:18,465] ({qtp89387388-13} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-13]
TRACE [2019-11-15 10:15:18,465] ({qtp89387388-13} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] bound to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:18,466] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = true; session is null = false; session has id = true
TRACE [2019-11-15 10:15:18,466] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@67981f6a
TRACE [2019-11-15 10:15:18,466] ({qtp89387388-13} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-13]
TRACE [2019-11-15 10:15:18,466] ({qtp89387388-13} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] bound to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:18,466] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = true; session is null = false; session has id = true
TRACE [2019-11-15 10:15:18,466] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@67981f6a
TRACE [2019-11-15 10:15:18,467] ({qtp89387388-13} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-13]
TRACE [2019-11-15 10:15:18,468] ({qtp89387388-13} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] bound to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:18,468] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:18,468] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@67981f6a
DEBUG [2019-11-15 10:15:18,469] ({qtp89387388-13} AbstractExceptionAwareLogic.java[handleException]:41) - extra HTTP action required in security: 401
TRACE [2019-11-15 10:15:18,765] ({qtp89387388-13} OncePerRequestFilter.java[doFilter]:121) - Filter 'org.apache.shiro.web.servlet.ShiroFilter-17baae6e' not yet executed. Executing now.
TRACE [2019-11-15 10:15:18,766] ({qtp89387388-13} DefaultSecurityManager.java[ensureSecurityManager]:417) - Context already contains a SecurityManager instance. Returning.
TRACE [2019-11-15 10:15:18,766] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@27bdeb07
DEBUG [2019-11-15 10:15:18,766] ({qtp89387388-13} SimpleCookie.java[readValue]:389) - Found 'JSESSIONID' cookie value [eccd494d-6f7e-47f6-8bab-18f44f9d55a7]
TRACE [2019-11-15 10:15:18,766] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@46f1bb5c
TRACE [2019-11-15 10:15:18,767] ({qtp89387388-13} DefaultSecurityManager.java[resolvePrincipals]:499) - No identity (PrincipalCollection) found in the context. Looking for a remembered identity.
TRACE [2019-11-15 10:15:18,767] ({qtp89387388-13} SimpleCookie.java[readValue]:392) - No 'rememberMe' cookie value
TRACE [2019-11-15 10:15:18,767] ({qtp89387388-13} DefaultSecurityManager.java[resolvePrincipals]:525) - No remembered identity found. Returning original context.
TRACE [2019-11-15 10:15:18,767] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@46f1bb5c
TRACE [2019-11-15 10:15:18,767] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@46f1bb5c
TRACE [2019-11-15 10:15:18,767] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@46f1bb5c
TRACE [2019-11-15 10:15:18,768] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:18,768] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:18,768] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@46f1bb5c
TRACE [2019-11-15 10:15:18,768] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:18,768] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@46f1bb5c
TRACE [2019-11-15 10:15:18,768] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:18,768] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@46f1bb5c
TRACE [2019-11-15 10:15:18,773] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:18,773] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@46f1bb5c
TRACE [2019-11-15 10:15:18,774] ({qtp89387388-13} ThreadContext.java[put]:169) - Bound value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:18,774] ({qtp89387388-13} ThreadContext.java[put]:169) - Bound value of type [org.apache.shiro.web.mgt.DefaultWebSecurityManager] for key [org.apache.shiro.util.ThreadContext_SECURITY_MANAGER_KEY] to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:18,774] ({qtp89387388-13} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-13]
TRACE [2019-11-15 10:15:18,774] ({qtp89387388-13} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] bound to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:18,774] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:18,785] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@46f1bb5c
TRACE [2019-11-15 10:15:18,786] ({qtp89387388-13} PathMatchingFilterChainResolver.java[getChain]:108) - Matched path pattern [/api/version] for requestURI [/api/version]. Utilizing corresponding filter chain...
TRACE [2019-11-15 10:15:18,786] ({qtp89387388-13} AbstractShiroFilter.java[getExecutionChain]:417) - Resolved a configured FilterChain for the current request.
TRACE [2019-11-15 10:15:18,786] ({qtp89387388-13} ProxiedFilterChain.java[doFilter]:64) - Invoking wrapped filter at index [0]
TRACE [2019-11-15 10:15:18,786] ({qtp89387388-13} OncePerRequestFilter.java[doFilter]:121) - Filter 'anon' not yet executed. Executing now.
TRACE [2019-11-15 10:15:18,786] ({qtp89387388-13} PathMatchingFilter.java[pathsMatch]:123) - Attempting to match pattern '/api/version' with current requestURI '/api/version'...
TRACE [2019-11-15 10:15:18,787] ({qtp89387388-13} PathMatchingFilter.java[preHandle]:176) - Current requestURI matches pattern '/api/version'. Determining filter chain execution...
TRACE [2019-11-15 10:15:18,788] ({qtp89387388-13} PathMatchingFilter.java[isFilterChainContinued]:197) - Filter 'anon' is enabled for the current request under path '/api/version' with config [null]. Delegating to subclass implementation for 'onPreHandle' check.
TRACE [2019-11-15 10:15:18,788] ({qtp89387388-13} AdviceFilter.java[doFilterInternal]:133) - Invoked preHandle method. Continuing chain?: [true]
TRACE [2019-11-15 10:15:18,790] ({qtp89387388-13} ProxiedFilterChain.java[doFilter]:59) - Invoking original filter chain.
TRACE [2019-11-15 10:15:18,807] ({qtp89387388-13} AdviceFilter.java[doFilterInternal]:142) - Successfully invoked postHandle method
TRACE [2019-11-15 10:15:18,807] ({qtp89387388-13} AdviceFilter.java[cleanup]:174) - Successfully invoked afterCompletion method.
TRACE [2019-11-15 10:15:19,113] ({qtp89387388-13} OncePerRequestFilter.java[doFilter]:121) - Filter 'org.apache.shiro.web.servlet.ShiroFilter-17baae6e' not yet executed. Executing now.
TRACE [2019-11-15 10:15:19,113] ({qtp89387388-13} DefaultSecurityManager.java[ensureSecurityManager]:417) - Context already contains a SecurityManager instance. Returning.
TRACE [2019-11-15 10:15:19,114] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@1454e9f4
DEBUG [2019-11-15 10:15:19,114] ({qtp89387388-13} SimpleCookie.java[readValue]:389) - Found 'JSESSIONID' cookie value [eccd494d-6f7e-47f6-8bab-18f44f9d55a7]
TRACE [2019-11-15 10:15:19,114] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@3390c75b
TRACE [2019-11-15 10:15:19,115] ({qtp89387388-13} DefaultSecurityManager.java[resolvePrincipals]:499) - No identity (PrincipalCollection) found in the context. Looking for a remembered identity.
TRACE [2019-11-15 10:15:19,115] ({qtp89387388-13} SimpleCookie.java[readValue]:392) - No 'rememberMe' cookie value
TRACE [2019-11-15 10:15:19,115] ({qtp89387388-13} DefaultSecurityManager.java[resolvePrincipals]:525) - No remembered identity found. Returning original context.
TRACE [2019-11-15 10:15:19,115] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@3390c75b
TRACE [2019-11-15 10:15:19,115] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@3390c75b
TRACE [2019-11-15 10:15:19,116] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@3390c75b
TRACE [2019-11-15 10:15:19,116] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:19,116] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:19,117] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@3390c75b
TRACE [2019-11-15 10:15:19,117] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:19,117] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@3390c75b
TRACE [2019-11-15 10:15:19,117] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:19,118] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@3390c75b
TRACE [2019-11-15 10:15:19,118] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:19,118] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@3390c75b
TRACE [2019-11-15 10:15:19,118] ({qtp89387388-13} ThreadContext.java[put]:169) - Bound value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:19,118] ({qtp89387388-13} ThreadContext.java[put]:169) - Bound value of type [org.apache.shiro.web.mgt.DefaultWebSecurityManager] for key [org.apache.shiro.util.ThreadContext_SECURITY_MANAGER_KEY] to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:19,119] ({qtp89387388-13} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-13]
TRACE [2019-11-15 10:15:19,119] ({qtp89387388-13} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] bound to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:19,119] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:19,119] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@3390c75b
TRACE [2019-11-15 10:15:19,119] ({qtp89387388-13} PathMatchingFilterChainResolver.java[getChain]:108) - Matched path pattern [/api/callback] for requestURI [/api/callback]. Utilizing corresponding filter chain...
TRACE [2019-11-15 10:15:19,120] ({qtp89387388-13} AbstractShiroFilter.java[getExecutionChain]:417) - Resolved a configured FilterChain for the current request.
TRACE [2019-11-15 10:15:19,120] ({qtp89387388-13} ProxiedFilterChain.java[doFilter]:64) - Invoking wrapped filter at index [0]
DEBUG [2019-11-15 10:15:19,120] ({qtp89387388-13} DefaultCallbackLogic.java[perform]:40) - === CALLBACK ===
DEBUG [2019-11-15 10:15:19,122] ({qtp89387388-13} DefaultCallbackClientFinder.java[find]:43) - result: [keycloakOidcClient]
DEBUG [2019-11-15 10:15:19,124] ({qtp89387388-13} DefaultCallbackLogic.java[perform]:86) - foundClient: #KeycloakOidcClient# | name: keycloakOidcClient | callbackUrl: http://localhost:8080/api/callback | callbackUrlResolver: org.pac4j.core.http.callback.QueryParameterCallbackUrlResolver@266d1b8e | ajaxRequestResolver: org.pac4j.core.http.ajax.DefaultAjaxRequestResolver@44a72b54 | redirectActionBuilder: org.pac4j.oidc.redirect.OidcRedirectActionBuilder@52eb37e7 | credentialsExtractor: org.pac4j.oidc.credentials.extractor.OidcExtractor@7316a6a7 | authenticator: org.pac4j.oidc.credentials.authenticator.OidcAuthenticator@3d697220 | profileCreator: org.pac4j.oidc.profile.creator.OidcProfileCreator@40e91352 | logoutActionBuilder: org.pac4j.oidc.logout.OidcLogoutActionBuilder@4bb650a7 | authorizationGenerators: [org.pac4j.oidc.authorization.generator.KeycloakRolesAuthorizationGenerator@223aa2f7, org.pac4j.oidc.authorization.generator.KeycloakRolesAuthorizationGenerator@d53f4e8] | configuration: #KeycloakOidcConfiguration# | clientId: zeppelin-client | secret: [protected] | discoveryURI: null | scope: openid | customParams: {} | clientAuthenticationMethod: client_secret_basic | useNonce: true | preferredJwsAlgorithm: null | maxAge: null | maxClockSkew: 30 | connectTimeout: 500 | readTimeout: 5000 | resourceRetriever: com.nimbusds.jose.util.DefaultResourceRetriever@7b5d7f10 | responseType: code | responseMode: null | logoutUrl: http://localhost:5000/auth/realms/zeppelin/protocol/openid-connect/logout | withState: false | stateGenerator: org.pac4j.core.state.StaticOrRandomStateGenerator@50ad3bc1 | | DEBUG [2019-11-15 10:15:19,124] ({qtp89387388-13} OidcExtractor.java[extract]:66) - Authentication response successful
TRACE [2019-11-15 10:15:19,125] ({qtp89387388-13} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-13]
TRACE [2019-11-15 10:15:19,125] ({qtp89387388-13} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] bound to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:19,125] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:19,126] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@3390c75b
DEBUG [2019-11-15 10:15:19,140] ({qtp89387388-13} OidcAuthenticator.java[validate]:142) - Token response: status=200, content={"access_token":"eyJhbGciOiJSUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICJyRG51S0NjVEFoalZUelZSaGtTTnhBaEhaeUtmS2JQQ3pmZUpnbmp3RkxBIn0.eyJqdGkiOiIwM2I0NTVjMS0wZmFlLTQxYmUtODEwOS03YzIyYmQwMjQwM2EiLCJleHAiOjE1NzM4MDk2MTksIm5iZiI6MCwiaWF0IjoxNTczODA5MzE5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJhY2NvdW50Iiwic3ViIjoiMjNkNDZkY2UtY2NiYi00MWMzLThkZDgtNDlkZGIyOWJiNzRiIiwidHlwIjoiQmVhcmVyIiwiYXpwIjoiemVwcGVsaW4tY2xpZW50Iiwibm9uY2UiOiI2WXVvdFVjS2xNQTUxaWhSYVZ4aldrMGVQcXRrWjRTVmdwX0VLUDE3U21NIiwiYXV0aF90aW1lIjoxNTczODA5Mjg5LCJzZXNzaW9uX3N0YXRlIjoiMmRjOGY3YzQtYmNjNi00MjQ0LWJlMDQtOGFlYzljYjU5ZWJlIiwiYWNyIjoiMCIsInJlYWxtX2FjY2VzcyI6eyJyb2xlcyI6WyJvZmZsaW5lX2FjY2VzcyIsInVtYV9hdXRob3JpemF0aW9uIl19LCJyZXNvdXJjZV9hY2Nlc3MiOnsiemVwcGVsaW4tY2xpZW50Ijp7InJvbGVzIjpbInVzZXIiXX0sImFjY291bnQiOnsicm9sZXMiOlsibWFuYWdlLWFjY291bnQiLCJtYW5hZ2UtYWNjb3VudC1saW5rcyIsInZpZXctcHJvZmlsZSJdfX0sInNjb3BlIjoib3BlbmlkIHByb2ZpbGUgZW1haWwiLCJlbWFpbF92ZXJpZmllZCI6ZmFsc2UsInByZWZlcnJlZF91c2VybmFtZSI6ImVzaXJlbSJ9.RFGwCiT1JVQo5bM6gcCMw0xy10dcMMfhELDXMEmkvc3ENDDDXfDXCh4SBgwqVRppZAyq5Xyz4Kz1uPyXe5JfufGFL-ooXF5GQWOtG4UW6Xj-DTCv0d8pwldHZuFYVaG9bnQ6cCKWhcWhyzgqK1D0i0hNfNHHmb6dRaksLDxvJorwPAKH7AeKrJPk5-MQO0dn2sxaROJY9FeA300K0XFOclm7Xi1E2870QvqMUSTneykIvIzjmgclGc4NoCdSgz_g6g0WwVOuTDraYRqi3dfLW9OIXuYj47TvuxjdbmG-y7JYoQpUth1HJWK-g5bKKodZ6NQartUTCAiml--hsjdL7w","expires_in":300,"refresh_expires_in":1800,"refresh_token":"eyJhbGciOiJIUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICIwMmQ3ZGIxZi1iOTNjLTRlOGMtYTY5MC0zOWE5OGM0NjM0ZWMifQ.eyJqdGkiOiJmZTZjYTM3Yy1mMDg3LTQyYTAtYTZjMy0yZjk3OWIzOWJjNDIiLCJleHAiOjE1NzM4MTExMTksIm5iZiI6MCwiaWF0IjoxNTczODA5MzE5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJzdWIiOiIyM2Q0NmRjZS1jY2JiLTQxYzMtOGRkOC00OWRkYjI5YmI3NGIiLCJ0eXAiOiJSZWZyZXNoIiwiYXpwIjoiemVwcGVsaW4tY2xpZW50Iiwibm9uY2UiOiI2WXVvdFVjS2xNQTUxaWhSYVZ4aldrMGVQcXRrWjRTVmdwX0VLUDE3U21NIiwiYXV0aF90aW1lIjowLCJzZXNzaW9uX3N0YXRlIjoiMmRjOGY3YzQtYmNjNi00MjQ0LWJlMDQtOGFlYzljYjU5ZWJlIiwicmVhbG1fYWNjZXNzIjp7InJvbGVzIjpbIm9mZmxpbmVfYWNjZXNzIiwidW1hX2F1dGhvcml6YXRpb24iXX0sInJlc291cmNlX2FjY2VzcyI6eyJ6ZXBwZWxpbi1jbGllbnQiOnsicm9sZXMiOlsidXNlciJdfSwiYWNjb3VudCI6eyJyb2xlcyI6WyJtYW5hZ2UtYWNjb3VudCIsIm1hbmFnZS1hY2NvdW50LWxpbmtzIiwidmlldy1wcm9maWxlIl19fSwic2NvcGUiOiJvcGVuaWQgcHJvZmlsZSBlbWFpbCJ9.zJejJ0cmVBEzuBMHhJDS2EeiXyENArwEv8MeIeXSrHQ","token_type":"bearer","id_token":"eyJhbGciOiJSUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICJyRG51S0NjVEFoalZUelZSaGtTTnhBaEhaeUtmS2JQQ3pmZUpnbmp3RkxBIn0.eyJqdGkiOiI1MGI1NWYxYi1lOWRjLTRhNjUtODJlZS1mMDE2MGZiNzJiODEiLCJleHAiOjE1NzM4MDk2MTksIm5iZiI6MCwiaWF0IjoxNTczODA5MzE5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJ6ZXBwZWxpbi1jbGllbnQiLCJzdWIiOiIyM2Q0NmRjZS1jY2JiLTQxYzMtOGRkOC00OWRkYjI5YmI3NGIiLCJ0eXAiOiJJRCIsImF6cCI6InplcHBlbGluLWNsaWVudCIsIm5vbmNlIjoiNll1b3RVY0tsTUE1MWloUmFWeGpXazBlUHF0a1o0U1ZncF9FS1AxN1NtTSIsImF1dGhfdGltZSI6MTU3MzgwOTI4OSwic2Vzc2lvbl9zdGF0ZSI6IjJkYzhmN2M0LWJjYzYtNDI0NC1iZTA0LThhZWM5Y2I1OWViZSIsImFjciI6IjAiLCJlbWFpbF92ZXJpZmllZCI6ZmFsc2UsInByZWZlcnJlZF91c2VybmFtZSI6ImVzaXJlbSJ9.UWKMOyAIaDMY9yOym6_09celHj2slMrzgeZ3vIXQfZ07fqdJi36Kz_uzGXHsDlM_UJaNH0EtMPKelWBxmAs6L_Ts7MRqinhCFpyh-IpOCYrli719gb1U4lXW4_hbUBCErAhJARcHE29u-GVu6BEOcBYD2FZP5c5c-YSgBZ6Apq9jX5PQEg_zy32ZgRRxal67Su5BHCzMg8o0DT_SyOk-cFtnY_ctBQIinW_O1lBGYJpAv9i8agzO7MYsho-zN16Me8hIOzPki0xi1UyFiErMOoHKwWTvLcGmWTuQLtCB9rt_0zmUr9LeOhbwHAgkThsWghGsRfzQBCHbmnI9fnthjA","not-before-policy":0,"session_state":"2dc8f7c4-bcc6-4244-be04-8aec9cb59ebe","scope":"openid profile email"}
DEBUG [2019-11-15 10:15:19,141] ({qtp89387388-13} OidcAuthenticator.java[validate]:149) - Token response successful
DEBUG [2019-11-15 10:15:19,141] ({qtp89387388-13} BaseClient.java[retrieveCredentials]:74) - Credentials validation took: 15 ms
TRACE [2019-11-15 10:15:19,141] ({qtp89387388-13} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-13]
TRACE [2019-11-15 10:15:19,141] ({qtp89387388-13} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] bound to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:19,141] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:19,142] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@3390c75b
DEBUG [2019-11-15 10:15:19,142] ({qtp89387388-13} DefaultCallbackLogic.java[perform]:90) - credentials: #OidcCredentials# | code: 3191d2bb-e726-4767-bb7f-2457ddd67f79.2dc8f7c4-bcc6-4244-be04-8aec9cb59ebe.fbcd71d0-e0e2-453a-bcd0-9e3dc8307362 | accessToken: eyJhbGciOiJSUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICJyRG51S0NjVEFoalZUelZSaGtTTnhBaEhaeUtmS2JQQ3pmZUpnbmp3RkxBIn0.eyJqdGkiOiIwM2I0NTVjMS0wZmFlLTQxYmUtODEwOS03YzIyYmQwMjQwM2EiLCJleHAiOjE1NzM4MDk2MTksIm5iZiI6MCwiaWF0IjoxNTczODA5MzE5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJhY2NvdW50Iiwic3ViIjoiMjNkNDZkY2UtY2NiYi00MWMzLThkZDgtNDlkZGIyOWJiNzRiIiwidHlwIjoiQmVhcmVyIiwiYXpwIjoiemVwcGVsaW4tY2xpZW50Iiwibm9uY2UiOiI2WXVvdFVjS2xNQTUxaWhSYVZ4aldrMGVQcXRrWjRTVmdwX0VLUDE3U21NIiwiYXV0aF90aW1lIjoxNTczODA5Mjg5LCJzZXNzaW9uX3N0YXRlIjoiMmRjOGY3YzQtYmNjNi00MjQ0LWJlMDQtOGFlYzljYjU5ZWJlIiwiYWNyIjoiMCIsInJlYWxtX2FjY2VzcyI6eyJyb2xlcyI6WyJvZmZsaW5lX2FjY2VzcyIsInVtYV9hdXRob3JpemF0aW9uIl19LCJyZXNvdXJjZV9hY2Nlc3MiOnsiemVwcGVsaW4tY2xpZW50Ijp7InJvbGVzIjpbInVzZXIiXX0sImFjY291bnQiOnsicm9sZXMiOlsibWFuYWdlLWFjY291bnQiLCJtYW5hZ2UtYWNjb3VudC1saW5rcyIsInZpZXctcHJvZmlsZSJdfX0sInNjb3BlIjoib3BlbmlkIHByb2ZpbGUgZW1haWwiLCJlbWFpbF92ZXJpZmllZCI6ZmFsc2UsInByZWZlcnJlZF91c2VybmFtZSI6ImVzaXJlbSJ9.RFGwCiT1JVQo5bM6gcCMw0xy10dcMMfhELDXMEmkvc3ENDDDXfDXCh4SBgwqVRppZAyq5Xyz4Kz1uPyXe5JfufGFL-ooXF5GQWOtG4UW6Xj-DTCv0d8pwldHZuFYVaG9bnQ6cCKWhcWhyzgqK1D0i0hNfNHHmb6dRaksLDxvJorwPAKH7AeKrJPk5-MQO0dn2sxaROJY9FeA300K0XFOclm7Xi1E2870QvqMUSTneykIvIzjmgclGc4NoCdSgz_g6g0WwVOuTDraYRqi3dfLW9OIXuYj47TvuxjdbmG-y7JYoQpUth1HJWK-g5bKKodZ6NQartUTCAiml--hsjdL7w | refreshToken: eyJhbGciOiJIUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICIwMmQ3ZGIxZi1iOTNjLTRlOGMtYTY5MC0zOWE5OGM0NjM0ZWMifQ.eyJqdGkiOiJmZTZjYTM3Yy1mMDg3LTQyYTAtYTZjMy0yZjk3OWIzOWJjNDIiLCJleHAiOjE1NzM4MTExMTksIm5iZiI6MCwiaWF0IjoxNTczODA5MzE5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJzdWIiOiIyM2Q0NmRjZS1jY2JiLTQxYzMtOGRkOC00OWRkYjI5YmI3NGIiLCJ0eXAiOiJSZWZyZXNoIiwiYXpwIjoiemVwcGVsaW4tY2xpZW50Iiwibm9uY2UiOiI2WXVvdFVjS2xNQTUxaWhSYVZ4aldrMGVQcXRrWjRTVmdwX0VLUDE3U21NIiwiYXV0aF90aW1lIjowLCJzZXNzaW9uX3N0YXRlIjoiMmRjOGY3YzQtYmNjNi00MjQ0LWJlMDQtOGFlYzljYjU5ZWJlIiwicmVhbG1fYWNjZXNzIjp7InJvbGVzIjpbIm9mZmxpbmVfYWNjZXNzIiwidW1hX2F1dGhvcml6YXRpb24iXX0sInJlc291cmNlX2FjY2VzcyI6eyJ6ZXBwZWxpbi1jbGllbnQiOnsicm9sZXMiOlsidXNlciJdfSwiYWNjb3VudCI6eyJyb2xlcyI6WyJtYW5hZ2UtYWNjb3VudCIsIm1hbmFnZS1hY2NvdW50LWxpbmtzIiwidmlldy1wcm9maWxlIl19fSwic2NvcGUiOiJvcGVuaWQgcHJvZmlsZSBlbWFpbCJ9.zJejJ0cmVBEzuBMHhJDS2EeiXyENArwEv8MeIeXSrHQ | idToken: com.nimbusds.jwt.SignedJWT@7bbde7fd |
DEBUG [2019-11-15 10:15:19,143] ({qtp89387388-13} BaseClient.java[getUserProfile]:88) - credentials : #OidcCredentials# | code: 3191d2bb-e726-4767-bb7f-2457ddd67f79.2dc8f7c4-bcc6-4244-be04-8aec9cb59ebe.fbcd71d0-e0e2-453a-bcd0-9e3dc8307362 | accessToken: eyJhbGciOiJSUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICJyRG51S0NjVEFoalZUelZSaGtTTnhBaEhaeUtmS2JQQ3pmZUpnbmp3RkxBIn0.eyJqdGkiOiIwM2I0NTVjMS0wZmFlLTQxYmUtODEwOS03YzIyYmQwMjQwM2EiLCJleHAiOjE1NzM4MDk2MTksIm5iZiI6MCwiaWF0IjoxNTczODA5MzE5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJhY2NvdW50Iiwic3ViIjoiMjNkNDZkY2UtY2NiYi00MWMzLThkZDgtNDlkZGIyOWJiNzRiIiwidHlwIjoiQmVhcmVyIiwiYXpwIjoiemVwcGVsaW4tY2xpZW50Iiwibm9uY2UiOiI2WXVvdFVjS2xNQTUxaWhSYVZ4aldrMGVQcXRrWjRTVmdwX0VLUDE3U21NIiwiYXV0aF90aW1lIjoxNTczODA5Mjg5LCJzZXNzaW9uX3N0YXRlIjoiMmRjOGY3YzQtYmNjNi00MjQ0LWJlMDQtOGFlYzljYjU5ZWJlIiwiYWNyIjoiMCIsInJlYWxtX2FjY2VzcyI6eyJyb2xlcyI6WyJvZmZsaW5lX2FjY2VzcyIsInVtYV9hdXRob3JpemF0aW9uIl19LCJyZXNvdXJjZV9hY2Nlc3MiOnsiemVwcGVsaW4tY2xpZW50Ijp7InJvbGVzIjpbInVzZXIiXX0sImFjY291bnQiOnsicm9sZXMiOlsibWFuYWdlLWFjY291bnQiLCJtYW5hZ2UtYWNjb3VudC1saW5rcyIsInZpZXctcHJvZmlsZSJdfX0sInNjb3BlIjoib3BlbmlkIHByb2ZpbGUgZW1haWwiLCJlbWFpbF92ZXJpZmllZCI6ZmFsc2UsInByZWZlcnJlZF91c2VybmFtZSI6ImVzaXJlbSJ9.RFGwCiT1JVQo5bM6gcCMw0xy10dcMMfhELDXMEmkvc3ENDDDXfDXCh4SBgwqVRppZAyq5Xyz4Kz1uPyXe5JfufGFL-ooXF5GQWOtG4UW6Xj-DTCv0d8pwldHZuFYVaG9bnQ6cCKWhcWhyzgqK1D0i0hNfNHHmb6dRaksLDxvJorwPAKH7AeKrJPk5-MQO0dn2sxaROJY9FeA300K0XFOclm7Xi1E2870QvqMUSTneykIvIzjmgclGc4NoCdSgz_g6g0WwVOuTDraYRqi3dfLW9OIXuYj47TvuxjdbmG-y7JYoQpUth1HJWK-g5bKKodZ6NQartUTCAiml--hsjdL7w | refreshToken: eyJhbGciOiJIUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICIwMmQ3ZGIxZi1iOTNjLTRlOGMtYTY5MC0zOWE5OGM0NjM0ZWMifQ.eyJqdGkiOiJmZTZjYTM3Yy1mMDg3LTQyYTAtYTZjMy0yZjk3OWIzOWJjNDIiLCJleHAiOjE1NzM4MTExMTksIm5iZiI6MCwiaWF0IjoxNTczODA5MzE5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJzdWIiOiIyM2Q0NmRjZS1jY2JiLTQxYzMtOGRkOC00OWRkYjI5YmI3NGIiLCJ0eXAiOiJSZWZyZXNoIiwiYXpwIjoiemVwcGVsaW4tY2xpZW50Iiwibm9uY2UiOiI2WXVvdFVjS2xNQTUxaWhSYVZ4aldrMGVQcXRrWjRTVmdwX0VLUDE3U21NIiwiYXV0aF90aW1lIjowLCJzZXNzaW9uX3N0YXRlIjoiMmRjOGY3YzQtYmNjNi00MjQ0LWJlMDQtOGFlYzljYjU5ZWJlIiwicmVhbG1fYWNjZXNzIjp7InJvbGVzIjpbIm9mZmxpbmVfYWNjZXNzIiwidW1hX2F1dGhvcml6YXRpb24iXX0sInJlc291cmNlX2FjY2VzcyI6eyJ6ZXBwZWxpbi1jbGllbnQiOnsicm9sZXMiOlsidXNlciJdfSwiYWNjb3VudCI6eyJyb2xlcyI6WyJtYW5hZ2UtYWNjb3VudCIsIm1hbmFnZS1hY2NvdW50LWxpbmtzIiwidmlldy1wcm9maWxlIl19fSwic2NvcGUiOiJvcGVuaWQgcHJvZmlsZSBlbWFpbCJ9.zJejJ0cmVBEzuBMHhJDS2EeiXyENArwEv8MeIeXSrHQ | idToken: com.nimbusds.jwt.SignedJWT@7bbde7fd |
DEBUG [2019-11-15 10:15:19,143] ({qtp89387388-13} UserProfile.java[addAttributeToMap]:86) - adding => key: access_token / value: eyJhbGciOiJSUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICJyRG51S0NjVEFoalZUelZSaGtTTnhBaEhaeUtmS2JQQ3pmZUpnbmp3RkxBIn0.eyJqdGkiOiIwM2I0NTVjMS0wZmFlLTQxYmUtODEwOS03YzIyYmQwMjQwM2EiLCJleHAiOjE1NzM4MDk2MTksIm5iZiI6MCwiaWF0IjoxNTczODA5MzE5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJhY2NvdW50Iiwic3ViIjoiMjNkNDZkY2UtY2NiYi00MWMzLThkZDgtNDlkZGIyOWJiNzRiIiwidHlwIjoiQmVhcmVyIiwiYXpwIjoiemVwcGVsaW4tY2xpZW50Iiwibm9uY2UiOiI2WXVvdFVjS2xNQTUxaWhSYVZ4aldrMGVQcXRrWjRTVmdwX0VLUDE3U21NIiwiYXV0aF90aW1lIjoxNTczODA5Mjg5LCJzZXNzaW9uX3N0YXRlIjoiMmRjOGY3YzQtYmNjNi00MjQ0LWJlMDQtOGFlYzljYjU5ZWJlIiwiYWNyIjoiMCIsInJlYWxtX2FjY2VzcyI6eyJyb2xlcyI6WyJvZmZsaW5lX2FjY2VzcyIsInVtYV9hdXRob3JpemF0aW9uIl19LCJyZXNvdXJjZV9hY2Nlc3MiOnsiemVwcGVsaW4tY2xpZW50Ijp7InJvbGVzIjpbInVzZXIiXX0sImFjY291bnQiOnsicm9sZXMiOlsibWFuYWdlLWFjY291bnQiLCJtYW5hZ2UtYWNjb3VudC1saW5rcyIsInZpZXctcHJvZmlsZSJdfX0sInNjb3BlIjoib3BlbmlkIHByb2ZpbGUgZW1haWwiLCJlbWFpbF92ZXJpZmllZCI6ZmFsc2UsInByZWZlcnJlZF91c2VybmFtZSI6ImVzaXJlbSJ9.RFGwCiT1JVQo5bM6gcCMw0xy10dcMMfhELDXMEmkvc3ENDDDXfDXCh4SBgwqVRppZAyq5Xyz4Kz1uPyXe5JfufGFL-ooXF5GQWOtG4UW6Xj-DTCv0d8pwldHZuFYVaG9bnQ6cCKWhcWhyzgqK1D0i0hNfNHHmb6dRaksLDxvJorwPAKH7AeKrJPk5-MQO0dn2sxaROJY9FeA300K0XFOclm7Xi1E2870QvqMUSTneykIvIzjmgclGc4NoCdSgz_g6g0WwVOuTDraYRqi3dfLW9OIXuYj47TvuxjdbmG-y7JYoQpUth1HJWK-g5bKKodZ6NQartUTCAiml--hsjdL7w / class com.nimbusds.oauth2.sdk.token.BearerAccessToken
DEBUG [2019-11-15 10:15:19,143] ({qtp89387388-13} UserProfile.java[addAttributeToMap]:86) - adding => key: id_token / value: eyJhbGciOiJSUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICJyRG51S0NjVEFoalZUelZSaGtTTnhBaEhaeUtmS2JQQ3pmZUpnbmp3RkxBIn0.eyJqdGkiOiI1MGI1NWYxYi1lOWRjLTRhNjUtODJlZS1mMDE2MGZiNzJiODEiLCJleHAiOjE1NzM4MDk2MTksIm5iZiI6MCwiaWF0IjoxNTczODA5MzE5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJ6ZXBwZWxpbi1jbGllbnQiLCJzdWIiOiIyM2Q0NmRjZS1jY2JiLTQxYzMtOGRkOC00OWRkYjI5YmI3NGIiLCJ0eXAiOiJJRCIsImF6cCI6InplcHBlbGluLWNsaWVudCIsIm5vbmNlIjoiNll1b3RVY0tsTUE1MWloUmFWeGpXazBlUHF0a1o0U1ZncF9FS1AxN1NtTSIsImF1dGhfdGltZSI6MTU3MzgwOTI4OSwic2Vzc2lvbl9zdGF0ZSI6IjJkYzhmN2M0LWJjYzYtNDI0NC1iZTA0LThhZWM5Y2I1OWViZSIsImFjciI6IjAiLCJlbWFpbF92ZXJpZmllZCI6ZmFsc2UsInByZWZlcnJlZF91c2VybmFtZSI6ImVzaXJlbSJ9.UWKMOyAIaDMY9yOym6_09celHj2slMrzgeZ3vIXQfZ07fqdJi36Kz_uzGXHsDlM_UJaNH0EtMPKelWBxmAs6L_Ts7MRqinhCFpyh-IpOCYrli719gb1U4lXW4_hbUBCErAhJARcHE29u-GVu6BEOcBYD2FZP5c5c-YSgBZ6Apq9jX5PQEg_zy32ZgRRxal67Su5BHCzMg8o0DT_SyOk-cFtnY_ctBQIinW_O1lBGYJpAv9i8agzO7MYsho-zN16Me8hIOzPki0xi1UyFiErMOoHKwWTvLcGmWTuQLtCB9rt_0zmUr9LeOhbwHAgkThsWghGsRfzQBCHbmnI9fnthjA / class java.lang.String
DEBUG [2019-11-15 10:15:19,143] ({qtp89387388-13} UserProfile.java[addAttributeToMap]:86) - adding => key: refresh_token / value: eyJhbGciOiJIUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICIwMmQ3ZGIxZi1iOTNjLTRlOGMtYTY5MC0zOWE5OGM0NjM0ZWMifQ.eyJqdGkiOiJmZTZjYTM3Yy1mMDg3LTQyYTAtYTZjMy0yZjk3OWIzOWJjNDIiLCJleHAiOjE1NzM4MTExMTksIm5iZiI6MCwiaWF0IjoxNTczODA5MzE5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJzdWIiOiIyM2Q0NmRjZS1jY2JiLTQxYzMtOGRkOC00OWRkYjI5YmI3NGIiLCJ0eXAiOiJSZWZyZXNoIiwiYXpwIjoiemVwcGVsaW4tY2xpZW50Iiwibm9uY2UiOiI2WXVvdFVjS2xNQTUxaWhSYVZ4aldrMGVQcXRrWjRTVmdwX0VLUDE3U21NIiwiYXV0aF90aW1lIjowLCJzZXNzaW9uX3N0YXRlIjoiMmRjOGY3YzQtYmNjNi00MjQ0LWJlMDQtOGFlYzljYjU5ZWJlIiwicmVhbG1fYWNjZXNzIjp7InJvbGVzIjpbIm9mZmxpbmVfYWNjZXNzIiwidW1hX2F1dGhvcml6YXRpb24iXX0sInJlc291cmNlX2FjY2VzcyI6eyJ6ZXBwZWxpbi1jbGllbnQiOnsicm9sZXMiOlsidXNlciJdfSwiYWNjb3VudCI6eyJyb2xlcyI6WyJtYW5hZ2UtYWNjb3VudCIsIm1hbmFnZS1hY2NvdW50LWxpbmtzIiwidmlldy1wcm9maWxlIl19fSwic2NvcGUiOiJvcGVuaWQgcHJvZmlsZSBlbWFpbCJ9.zJejJ0cmVBEzuBMHhJDS2EeiXyENArwEv8MeIeXSrHQ / class com.nimbusds.oauth2.sdk.token.RefreshToken
DEBUG [2019-11-15 10:15:19,144] ({qtp89387388-13} OidcProfileCreator.java[create]:79) - Refresh Token successful retrieved
TRACE [2019-11-15 10:15:19,144] ({qtp89387388-13} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-13]
TRACE [2019-11-15 10:15:19,144] ({qtp89387388-13} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] bound to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:19,144] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:19,144] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@3390c75b
DEBUG [2019-11-15 10:15:19,162] ({qtp89387388-13} OidcProfileCreator.java[create]:103) - Token response: status=200, content={"sub":"23d46dce-ccbb-41c3-8dd8-49ddb29bb74b","email_verified":false,"preferred_username":"esirem"}
DEBUG [2019-11-15 10:15:19,162] ({qtp89387388-13} ProfileDefinition.java[convertAndAdd]:65) - converted to => key: sub / value: 23d46dce-ccbb-41c3-8dd8-49ddb29bb74b / class java.lang.String
DEBUG [2019-11-15 10:15:19,163] ({qtp89387388-13} UserProfile.java[addAttributeToMap]:86) - adding => key: sub / value: 23d46dce-ccbb-41c3-8dd8-49ddb29bb74b / class java.lang.String
DEBUG [2019-11-15 10:15:19,165] ({qtp89387388-13} ProfileDefinition.java[convertAndAdd]:65) - converted to => key: email_verified / value: false / class java.lang.Boolean
DEBUG [2019-11-15 10:15:19,165] ({qtp89387388-13} UserProfile.java[addAttributeToMap]:86) - adding => key: email_verified / value: false / class java.lang.Boolean
DEBUG [2019-11-15 10:15:19,165] ({qtp89387388-13} ProfileDefinition.java[convertAndAdd]:65) - converted to => key: preferred_username / value: esirem / class java.lang.String
DEBUG [2019-11-15 10:15:19,166] ({qtp89387388-13} UserProfile.java[addAttributeToMap]:86) - adding => key: preferred_username / value: esirem / class java.lang.String
DEBUG [2019-11-15 10:15:19,167] ({qtp89387388-13} ProfileDefinition.java[convertAndAdd]:69) - no conversion => key: typ / value: ID / class java.lang.String
DEBUG [2019-11-15 10:15:19,167] ({qtp89387388-13} UserProfile.java[addAttributeToMap]:86) - adding => key: typ / value: ID / class java.lang.String
DEBUG [2019-11-15 10:15:19,167] ({qtp89387388-13} ProfileDefinition.java[convertAndAdd]:65) - converted to => key: nonce / value: 6YuotUcKlMA51ihRaVxjWk0ePqtkZ4SVgp_EKP17SmM / class java.lang.String
DEBUG [2019-11-15 10:15:19,167] ({qtp89387388-13} UserProfile.java[addAttributeToMap]:86) - adding => key: nonce / value: 6YuotUcKlMA51ihRaVxjWk0ePqtkZ4SVgp_EKP17SmM / class java.lang.String
DEBUG [2019-11-15 10:15:19,167] ({qtp89387388-13} ProfileDefinition.java[convertAndAdd]:69) - no conversion => key: aud / value: [zeppelin-client] / class java.util.ArrayList
DEBUG [2019-11-15 10:15:19,168] ({qtp89387388-13} UserProfile.java[addAttributeToMap]:86) - adding => key: aud / value: [zeppelin-client] / class java.util.ArrayList
DEBUG [2019-11-15 10:15:19,168] ({qtp89387388-13} ProfileDefinition.java[convertAndAdd]:65) - converted to => key: acr / value: 0 / class java.lang.String
DEBUG [2019-11-15 10:15:19,168] ({qtp89387388-13} UserProfile.java[addAttributeToMap]:86) - adding => key: acr / value: 0 / class java.lang.String
DEBUG [2019-11-15 10:15:19,168] ({qtp89387388-13} ProfileDefinition.java[convertAndAdd]:65) - converted to => key: nbf / value: Thu Jan 01 01:00:00 CET 1970 / class java.util.Date
DEBUG [2019-11-15 10:15:19,169] ({qtp89387388-13} UserProfile.java[addAttributeToMap]:86) - adding => key: nbf / value: Thu Jan 01 01:00:00 CET 1970 / class java.util.Date
DEBUG [2019-11-15 10:15:19,169] ({qtp89387388-13} ProfileDefinition.java[convertAndAdd]:65) - converted to => key: azp / value: zeppelin-client / class java.lang.String
DEBUG [2019-11-15 10:15:19,169] ({qtp89387388-13} UserProfile.java[addAttributeToMap]:86) - adding => key: azp / value: zeppelin-client / class java.lang.String
DEBUG [2019-11-15 10:15:19,169] ({qtp89387388-13} ProfileDefinition.java[convertAndAdd]:65) - converted to => key: auth_time / value: Fri Nov 15 10:14:49 CET 2019 / class java.util.Date
DEBUG [2019-11-15 10:15:19,169] ({qtp89387388-13} UserProfile.java[addAttributeToMap]:86) - adding => key: auth_time / value: Fri Nov 15 10:14:49 CET 2019 / class java.util.Date
DEBUG [2019-11-15 10:15:19,170] ({qtp89387388-13} ProfileDefinition.java[convertAndAdd]:65) - converted to => key: exp / value: Fri Nov 15 10:20:19 CET 2019 / class java.util.Date
DEBUG [2019-11-15 10:15:19,170] ({qtp89387388-13} UserProfile.java[addAttributeToMap]:86) - adding => key: exp / value: Fri Nov 15 10:20:19 CET 2019 / class java.util.Date
DEBUG [2019-11-15 10:15:19,170] ({qtp89387388-13} ProfileDefinition.java[convertAndAdd]:69) - no conversion => key: session_state / value: 2dc8f7c4-bcc6-4244-be04-8aec9cb59ebe / class java.lang.String
DEBUG [2019-11-15 10:15:19,170] ({qtp89387388-13} UserProfile.java[addAttributeToMap]:86) - adding => key: session_state / value: 2dc8f7c4-bcc6-4244-be04-8aec9cb59ebe / class java.lang.String
DEBUG [2019-11-15 10:15:19,170] ({qtp89387388-13} ProfileDefinition.java[convertAndAdd]:65) - converted to => key: iat / value: Fri Nov 15 10:15:19 CET 2019 / class java.util.Date
DEBUG [2019-11-15 10:15:19,171] ({qtp89387388-13} UserProfile.java[addAttributeToMap]:86) - adding => key: iat / value: Fri Nov 15 10:15:19 CET 2019 / class java.util.Date
DEBUG [2019-11-15 10:15:19,171] ({qtp89387388-13} ProfileDefinition.java[convertAndAdd]:69) - no conversion => key: jti / value: 50b55f1b-e9dc-4a65-82ee-f0160fb72b81 / class java.lang.String
DEBUG [2019-11-15 10:15:19,171] ({qtp89387388-13} UserProfile.java[addAttributeToMap]:86) - adding => key: jti / value: 50b55f1b-e9dc-4a65-82ee-f0160fb72b81 / class java.lang.String
DEBUG [2019-11-15 10:15:19,171] ({qtp89387388-13} UserProfile.java[addAttributeToMap]:86) - adding => key: token_expiration_advance / value: -1 / class java.lang.Integer
DEBUG [2019-11-15 10:15:19,172] ({qtp89387388-13} BaseClient.java[retrieveUserProfile]:114) - profile: #KeycloakOidcProfile# | id: 23d46dce-ccbb-41c3-8dd8-49ddb29bb74b | attributes: {sub=23d46dce-ccbb-41c3-8dd8-49ddb29bb74b, email_verified=false, id_token=eyJhbGciOiJSUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICJyRG51S0NjVEFoalZUelZSaGtTTnhBaEhaeUtmS2JQQ3pmZUpnbmp3RkxBIn0.eyJqdGkiOiI1MGI1NWYxYi1lOWRjLTRhNjUtODJlZS1mMDE2MGZiNzJiODEiLCJleHAiOjE1NzM4MDk2MTksIm5iZiI6MCwiaWF0IjoxNTczODA5MzE5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJ6ZXBwZWxpbi1jbGllbnQiLCJzdWIiOiIyM2Q0NmRjZS1jY2JiLTQxYzMtOGRkOC00OWRkYjI5YmI3NGIiLCJ0eXAiOiJJRCIsImF6cCI6InplcHBlbGluLWNsaWVudCIsIm5vbmNlIjoiNll1b3RVY0tsTUE1MWloUmFWeGpXazBlUHF0a1o0U1ZncF9FS1AxN1NtTSIsImF1dGhfdGltZSI6MTU3MzgwOTI4OSwic2Vzc2lvbl9zdGF0ZSI6IjJkYzhmN2M0LWJjYzYtNDI0NC1iZTA0LThhZWM5Y2I1OWViZSIsImFjciI6IjAiLCJlbWFpbF92ZXJpZmllZCI6ZmFsc2UsInByZWZlcnJlZF91c2VybmFtZSI6ImVzaXJlbSJ9.UWKMOyAIaDMY9yOym6_09celHj2slMrzgeZ3vIXQfZ07fqdJi36Kz_uzGXHsDlM_UJaNH0EtMPKelWBxmAs6L_Ts7MRqinhCFpyh-IpOCYrli719gb1U4lXW4_hbUBCErAhJARcHE29u-GVu6BEOcBYD2FZP5c5c-YSgBZ6Apq9jX5PQEg_zy32ZgRRxal67Su5BHCzMg8o0DT_SyOk-cFtnY_ctBQIinW_O1lBGYJpAv9i8agzO7MYsho-zN16Me8hIOzPki0xi1UyFiErMOoHKwWTvLcGmWTuQLtCB9rt_0zmUr9LeOhbwHAgkThsWghGsRfzQBCHbmnI9fnthjA, iss=http://localhost:5000/auth/realms/zeppelin, typ=ID, preferred_username=esirem, nonce=6YuotUcKlMA51ihRaVxjWk0ePqtkZ4SVgp_EKP17SmM, access_token=eyJhbGciOiJSUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICJyRG51S0NjVEFoalZUelZSaGtTTnhBaEhaeUtmS2JQQ3pmZUpnbmp3RkxBIn0.eyJqdGkiOiIwM2I0NTVjMS0wZmFlLTQxYmUtODEwOS03YzIyYmQwMjQwM2EiLCJleHAiOjE1NzM4MDk2MTksIm5iZiI6MCwiaWF0IjoxNTczODA5MzE5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJhY2NvdW50Iiwic3ViIjoiMjNkNDZkY2UtY2NiYi00MWMzLThkZDgtNDlkZGIyOWJiNzRiIiwidHlwIjoiQmVhcmVyIiwiYXpwIjoiemVwcGVsaW4tY2xpZW50Iiwibm9uY2UiOiI2WXVvdFVjS2xNQTUxaWhSYVZ4aldrMGVQcXRrWjRTVmdwX0VLUDE3U21NIiwiYXV0aF90aW1lIjoxNTczODA5Mjg5LCJzZXNzaW9uX3N0YXRlIjoiMmRjOGY3YzQtYmNjNi00MjQ0LWJlMDQtOGFlYzljYjU5ZWJlIiwiYWNyIjoiMCIsInJlYWxtX2FjY2VzcyI6eyJyb2xlcyI6WyJvZmZsaW5lX2FjY2VzcyIsInVtYV9hdXRob3JpemF0aW9uIl19LCJyZXNvdXJjZV9hY2Nlc3MiOnsiemVwcGVsaW4tY2xpZW50Ijp7InJvbGVzIjpbInVzZXIiXX0sImFjY291bnQiOnsicm9sZXMiOlsibWFuYWdlLWFjY291bnQiLCJtYW5hZ2UtYWNjb3VudC1saW5rcyIsInZpZXctcHJvZmlsZSJdfX0sInNjb3BlIjoib3BlbmlkIHByb2ZpbGUgZW1haWwiLCJlbWFpbF92ZXJpZmllZCI6ZmFsc2UsInByZWZlcnJlZF91c2VybmFtZSI6ImVzaXJlbSJ9.RFGwCiT1JVQo5bM6gcCMw0xy10dcMMfhELDXMEmkvc3ENDDDXfDXCh4SBgwqVRppZAyq5Xyz4Kz1uPyXe5JfufGFL-ooXF5GQWOtG4UW6Xj-DTCv0d8pwldHZuFYVaG9bnQ6cCKWhcWhyzgqK1D0i0hNfNHHmb6dRaksLDxvJorwPAKH7AeKrJPk5-MQO0dn2sxaROJY9FeA300K0XFOclm7Xi1E2870QvqMUSTneykIvIzjmgclGc4NoCdSgz_g6g0WwVOuTDraYRqi3dfLW9OIXuYj47TvuxjdbmG-y7JYoQpUth1HJWK-g5bKKodZ6NQartUTCAiml--hsjdL7w, token_expiration_advance=-1, refresh_token=eyJhbGciOiJIUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICIwMmQ3ZGIxZi1iOTNjLTRlOGMtYTY5MC0zOWE5OGM0NjM0ZWMifQ.eyJqdGkiOiJmZTZjYTM3Yy1mMDg3LTQyYTAtYTZjMy0yZjk3OWIzOWJjNDIiLCJleHAiOjE1NzM4MTExMTksIm5iZiI6MCwiaWF0IjoxNTczODA5MzE5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJzdWIiOiIyM2Q0NmRjZS1jY2JiLTQxYzMtOGRkOC00OWRkYjI5YmI3NGIiLCJ0eXAiOiJSZWZyZXNoIiwiYXpwIjoiemVwcGVsaW4tY2xpZW50Iiwibm9uY2UiOiI2WXVvdFVjS2xNQTUxaWhSYVZ4aldrMGVQcXRrWjRTVmdwX0VLUDE3U21NIiwiYXV0aF90aW1lIjowLCJzZXNzaW9uX3N0YXRlIjoiMmRjOGY3YzQtYmNjNi00MjQ0LWJlMDQtOGFlYzljYjU5ZWJlIiwicmVhbG1fYWNjZXNzIjp7InJvbGVzIjpbIm9mZmxpbmVfYWNjZXNzIiwidW1hX2F1dGhvcml6YXRpb24iXX0sInJlc291cmNlX2FjY2VzcyI6eyJ6ZXBwZWxpbi1jbGllbnQiOnsicm9sZXMiOlsidXNlciJdfSwiYWNjb3VudCI6eyJyb2xlcyI6WyJtYW5hZ2UtYWNjb3VudCIsIm1hbmFnZS1hY2NvdW50LWxpbmtzIiwidmlldy1wcm9maWxlIl19fSwic2NvcGUiOiJvcGVuaWQgcHJvZmlsZSBlbWFpbCJ9.zJejJ0cmVBEzuBMHhJDS2EeiXyENArwEv8MeIeXSrHQ, aud=[zeppelin-client], acr=0, nbf=Thu Jan 01 01:00:00 CET 1970, azp=zeppelin-client, auth_time=Fri Nov 15 10:14:49 CET 2019, exp=Fri Nov 15 10:20:19 CET 2019, session_state=2dc8f7c4-bcc6-4244-be04-8aec9cb59ebe, iat=Fri Nov 15 10:15:19 CET 2019, jti=50b55f1b-e9dc-4a65-82ee-f0160fb72b81} | roles: [] | permissions: [] | isRemembered: false | clientName: null | linkedId: null | DEBUG [2019-11-15 10:15:19,175] ({qtp89387388-13} DefaultCallbackLogic.java[perform]:93) - profile: #KeycloakOidcProfile# | id: 23d46dce-ccbb-41c3-8dd8-49ddb29bb74b | attributes: {sub=23d46dce-ccbb-41c3-8dd8-49ddb29bb74b, email_verified=false, id_token=eyJhbGciOiJSUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICJyRG51S0NjVEFoalZUelZSaGtTTnhBaEhaeUtmS2JQQ3pmZUpnbmp3RkxBIn0.eyJqdGkiOiI1MGI1NWYxYi1lOWRjLTRhNjUtODJlZS1mMDE2MGZiNzJiODEiLCJleHAiOjE1NzM4MDk2MTksIm5iZiI6MCwiaWF0IjoxNTczODA5MzE5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJ6ZXBwZWxpbi1jbGllbnQiLCJzdWIiOiIyM2Q0NmRjZS1jY2JiLTQxYzMtOGRkOC00OWRkYjI5YmI3NGIiLCJ0eXAiOiJJRCIsImF6cCI6InplcHBlbGluLWNsaWVudCIsIm5vbmNlIjoiNll1b3RVY0tsTUE1MWloUmFWeGpXazBlUHF0a1o0U1ZncF9FS1AxN1NtTSIsImF1dGhfdGltZSI6MTU3MzgwOTI4OSwic2Vzc2lvbl9zdGF0ZSI6IjJkYzhmN2M0LWJjYzYtNDI0NC1iZTA0LThhZWM5Y2I1OWViZSIsImFjciI6IjAiLCJlbWFpbF92ZXJpZmllZCI6ZmFsc2UsInByZWZlcnJlZF91c2VybmFtZSI6ImVzaXJlbSJ9.UWKMOyAIaDMY9yOym6_09celHj2slMrzgeZ3vIXQfZ07fqdJi36Kz_uzGXHsDlM_UJaNH0EtMPKelWBxmAs6L_Ts7MRqinhCFpyh-IpOCYrli719gb1U4lXW4_hbUBCErAhJARcHE29u-GVu6BEOcBYD2FZP5c5c-YSgBZ6Apq9jX5PQEg_zy32ZgRRxal67Su5BHCzMg8o0DT_SyOk-cFtnY_ctBQIinW_O1lBGYJpAv9i8agzO7MYsho-zN16Me8hIOzPki0xi1UyFiErMOoHKwWTvLcGmWTuQLtCB9rt_0zmUr9LeOhbwHAgkThsWghGsRfzQBCHbmnI9fnthjA, iss=http://localhost:5000/auth/realms/zeppelin, typ=ID, preferred_username=esirem, nonce=6YuotUcKlMA51ihRaVxjWk0ePqtkZ4SVgp_EKP17SmM, access_token=eyJhbGciOiJSUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICJyRG51S0NjVEFoalZUelZSaGtTTnhBaEhaeUtmS2JQQ3pmZUpnbmp3RkxBIn0.eyJqdGkiOiIwM2I0NTVjMS0wZmFlLTQxYmUtODEwOS03YzIyYmQwMjQwM2EiLCJleHAiOjE1NzM4MDk2MTksIm5iZiI6MCwiaWF0IjoxNTczODA5MzE5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJhY2NvdW50Iiwic3ViIjoiMjNkNDZkY2UtY2NiYi00MWMzLThkZDgtNDlkZGIyOWJiNzRiIiwidHlwIjoiQmVhcmVyIiwiYXpwIjoiemVwcGVsaW4tY2xpZW50Iiwibm9uY2UiOiI2WXVvdFVjS2xNQTUxaWhSYVZ4aldrMGVQcXRrWjRTVmdwX0VLUDE3U21NIiwiYXV0aF90aW1lIjoxNTczODA5Mjg5LCJzZXNzaW9uX3N0YXRlIjoiMmRjOGY3YzQtYmNjNi00MjQ0LWJlMDQtOGFlYzljYjU5ZWJlIiwiYWNyIjoiMCIsInJlYWxtX2FjY2VzcyI6eyJyb2xlcyI6WyJvZmZsaW5lX2FjY2VzcyIsInVtYV9hdXRob3JpemF0aW9uIl19LCJyZXNvdXJjZV9hY2Nlc3MiOnsiemVwcGVsaW4tY2xpZW50Ijp7InJvbGVzIjpbInVzZXIiXX0sImFjY291bnQiOnsicm9sZXMiOlsibWFuYWdlLWFjY291bnQiLCJtYW5hZ2UtYWNjb3VudC1saW5rcyIsInZpZXctcHJvZmlsZSJdfX0sInNjb3BlIjoib3BlbmlkIHByb2ZpbGUgZW1haWwiLCJlbWFpbF92ZXJpZmllZCI6ZmFsc2UsInByZWZlcnJlZF91c2VybmFtZSI6ImVzaXJlbSJ9.RFGwCiT1JVQo5bM6gcCMw0xy10dcMMfhELDXMEmkvc3ENDDDXfDXCh4SBgwqVRppZAyq5Xyz4Kz1uPyXe5JfufGFL-ooXF5GQWOtG4UW6Xj-DTCv0d8pwldHZuFYVaG9bnQ6cCKWhcWhyzgqK1D0i0hNfNHHmb6dRaksLDxvJorwPAKH7AeKrJPk5-MQO0dn2sxaROJY9FeA300K0XFOclm7Xi1E2870QvqMUSTneykIvIzjmgclGc4NoCdSgz_g6g0WwVOuTDraYRqi3dfLW9OIXuYj47TvuxjdbmG-y7JYoQpUth1HJWK-g5bKKodZ6NQartUTCAiml--hsjdL7w, token_expiration_advance=-1, refresh_token=eyJhbGciOiJIUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICIwMmQ3ZGIxZi1iOTNjLTRlOGMtYTY5MC0zOWE5OGM0NjM0ZWMifQ.eyJqdGkiOiJmZTZjYTM3Yy1mMDg3LTQyYTAtYTZjMy0yZjk3OWIzOWJjNDIiLCJleHAiOjE1NzM4MTExMTksIm5iZiI6MCwiaWF0IjoxNTczODA5MzE5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJzdWIiOiIyM2Q0NmRjZS1jY2JiLTQxYzMtOGRkOC00OWRkYjI5YmI3NGIiLCJ0eXAiOiJSZWZyZXNoIiwiYXpwIjoiemVwcGVsaW4tY2xpZW50Iiwibm9uY2UiOiI2WXVvdFVjS2xNQTUxaWhSYVZ4aldrMGVQcXRrWjRTVmdwX0VLUDE3U21NIiwiYXV0aF90aW1lIjowLCJzZXNzaW9uX3N0YXRlIjoiMmRjOGY3YzQtYmNjNi00MjQ0LWJlMDQtOGFlYzljYjU5ZWJlIiwicmVhbG1fYWNjZXNzIjp7InJvbGVzIjpbIm9mZmxpbmVfYWNjZXNzIiwidW1hX2F1dGhvcml6YXRpb24iXX0sInJlc291cmNlX2FjY2VzcyI6eyJ6ZXBwZWxpbi1jbGllbnQiOnsicm9sZXMiOlsidXNlciJdfSwiYWNjb3VudCI6eyJyb2xlcyI6WyJtYW5hZ2UtYWNjb3VudCIsIm1hbmFnZS1hY2NvdW50LWxpbmtzIiwidmlldy1wcm9maWxlIl19fSwic2NvcGUiOiJvcGVuaWQgcHJvZmlsZSBlbWFpbCJ9.zJejJ0cmVBEzuBMHhJDS2EeiXyENArwEv8MeIeXSrHQ, aud=[zeppelin-client], acr=0, nbf=Thu Jan 01 01:00:00 CET 1970, azp=zeppelin-client, auth_time=Fri Nov 15 10:14:49 CET 2019, exp=Fri Nov 15 10:20:19 CET 2019, session_state=2dc8f7c4-bcc6-4244-be04-8aec9cb59ebe, iat=Fri Nov 15 10:15:19 CET 2019, jti=50b55f1b-e9dc-4a65-82ee-f0160fb72b81} | roles: [offline_access, uma_authorization, user] | permissions: [] | isRemembered: false | clientName: keycloakOidcClient | linkedId: null | TRACE [2019-11-15 10:15:19,175] ({qtp89387388-13} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-13]
TRACE [2019-11-15 10:15:19,175] ({qtp89387388-13} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] bound to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:19,175] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = true; session is null = false; session has id = true
TRACE [2019-11-15 10:15:19,176] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@3390c75b
TRACE [2019-11-15 10:15:19,176] ({qtp89387388-13} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-13]
TRACE [2019-11-15 10:15:19,176] ({qtp89387388-13} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] bound to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:19,176] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:19,176] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@3390c75b
TRACE [2019-11-15 10:15:19,177] ({qtp89387388-13} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-13]
TRACE [2019-11-15 10:15:19,177] ({qtp89387388-13} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] bound to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:19,177] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:19,177] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@3390c75b
TRACE [2019-11-15 10:15:19,178] ({qtp89387388-13} AbstractAuthenticator.java[authenticate]:194) - Authentication attempt received for token [io.buji.pac4j.token.Pac4jToken@30dd988d]
DEBUG [2019-11-15 10:15:19,178] ({qtp89387388-13} AuthenticatingRealm.java[getAuthenticationInfo]:572) - Looked up AuthenticationInfo [#Pac4jPrincipal# | profiles: [#KeycloakOidcProfile# | id: 23d46dce-ccbb-41c3-8dd8-49ddb29bb74b | attributes: {sub=23d46dce-ccbb-41c3-8dd8-49ddb29bb74b, email_verified=false, id_token=eyJhbGciOiJSUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICJyRG51S0NjVEFoalZUelZSaGtTTnhBaEhaeUtmS2JQQ3pmZUpnbmp3RkxBIn0.eyJqdGkiOiI1MGI1NWYxYi1lOWRjLTRhNjUtODJlZS1mMDE2MGZiNzJiODEiLCJleHAiOjE1NzM4MDk2MTksIm5iZiI6MCwiaWF0IjoxNTczODA5MzE5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJ6ZXBwZWxpbi1jbGllbnQiLCJzdWIiOiIyM2Q0NmRjZS1jY2JiLTQxYzMtOGRkOC00OWRkYjI5YmI3NGIiLCJ0eXAiOiJJRCIsImF6cCI6InplcHBlbGluLWNsaWVudCIsIm5vbmNlIjoiNll1b3RVY0tsTUE1MWloUmFWeGpXazBlUHF0a1o0U1ZncF9FS1AxN1NtTSIsImF1dGhfdGltZSI6MTU3MzgwOTI4OSwic2Vzc2lvbl9zdGF0ZSI6IjJkYzhmN2M0LWJjYzYtNDI0NC1iZTA0LThhZWM5Y2I1OWViZSIsImFjciI6IjAiLCJlbWFpbF92ZXJpZmllZCI6ZmFsc2UsInByZWZlcnJlZF91c2VybmFtZSI6ImVzaXJlbSJ9.UWKMOyAIaDMY9yOym6_09celHj2slMrzgeZ3vIXQfZ07fqdJi36Kz_uzGXHsDlM_UJaNH0EtMPKelWBxmAs6L_Ts7MRqinhCFpyh-IpOCYrli719gb1U4lXW4_hbUBCErAhJARcHE29u-GVu6BEOcBYD2FZP5c5c-YSgBZ6Apq9jX5PQEg_zy32ZgRRxal67Su5BHCzMg8o0DT_SyOk-cFtnY_ctBQIinW_O1lBGYJpAv9i8agzO7MYsho-zN16Me8hIOzPki0xi1UyFiErMOoHKwWTvLcGmWTuQLtCB9rt_0zmUr9LeOhbwHAgkThsWghGsRfzQBCHbmnI9fnthjA, iss=http://localhost:5000/auth/realms/zeppelin, typ=ID, preferred_username=esirem, nonce=6YuotUcKlMA51ihRaVxjWk0ePqtkZ4SVgp_EKP17SmM, access_token=eyJhbGciOiJSUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICJyRG51S0NjVEFoalZUelZSaGtTTnhBaEhaeUtmS2JQQ3pmZUpnbmp3RkxBIn0.eyJqdGkiOiIwM2I0NTVjMS0wZmFlLTQxYmUtODEwOS03YzIyYmQwMjQwM2EiLCJleHAiOjE1NzM4MDk2MTksIm5iZiI6MCwiaWF0IjoxNTczODA5MzE5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJhY2NvdW50Iiwic3ViIjoiMjNkNDZkY2UtY2NiYi00MWMzLThkZDgtNDlkZGIyOWJiNzRiIiwidHlwIjoiQmVhcmVyIiwiYXpwIjoiemVwcGVsaW4tY2xpZW50Iiwibm9uY2UiOiI2WXVvdFVjS2xNQTUxaWhSYVZ4aldrMGVQcXRrWjRTVmdwX0VLUDE3U21NIiwiYXV0aF90aW1lIjoxNTczODA5Mjg5LCJzZXNzaW9uX3N0YXRlIjoiMmRjOGY3YzQtYmNjNi00MjQ0LWJlMDQtOGFlYzljYjU5ZWJlIiwiYWNyIjoiMCIsInJlYWxtX2FjY2VzcyI6eyJyb2xlcyI6WyJvZmZsaW5lX2FjY2VzcyIsInVtYV9hdXRob3JpemF0aW9uIl19LCJyZXNvdXJjZV9hY2Nlc3MiOnsiemVwcGVsaW4tY2xpZW50Ijp7InJvbGVzIjpbInVzZXIiXX0sImFjY291bnQiOnsicm9sZXMiOlsibWFuYWdlLWFjY291bnQiLCJtYW5hZ2UtYWNjb3VudC1saW5rcyIsInZpZXctcHJvZmlsZSJdfX0sInNjb3BlIjoib3BlbmlkIHByb2ZpbGUgZW1haWwiLCJlbWFpbF92ZXJpZmllZCI6ZmFsc2UsInByZWZlcnJlZF91c2VybmFtZSI6ImVzaXJlbSJ9.RFGwCiT1JVQo5bM6gcCMw0xy10dcMMfhELDXMEmkvc3ENDDDXfDXCh4SBgwqVRppZAyq5Xyz4Kz1uPyXe5JfufGFL-ooXF5GQWOtG4UW6Xj-DTCv0d8pwldHZuFYVaG9bnQ6cCKWhcWhyzgqK1D0i0hNfNHHmb6dRaksLDxvJorwPAKH7AeKrJPk5-MQO0dn2sxaROJY9FeA300K0XFOclm7Xi1E2870QvqMUSTneykIvIzjmgclGc4NoCdSgz_g6g0WwVOuTDraYRqi3dfLW9OIXuYj47TvuxjdbmG-y7JYoQpUth1HJWK-g5bKKodZ6NQartUTCAiml--hsjdL7w, token_expiration_advance=-1, refresh_token=eyJhbGciOiJIUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICIwMmQ3ZGIxZi1iOTNjLTRlOGMtYTY5MC0zOWE5OGM0NjM0ZWMifQ.eyJqdGkiOiJmZTZjYTM3Yy1mMDg3LTQyYTAtYTZjMy0yZjk3OWIzOWJjNDIiLCJleHAiOjE1NzM4MTExMTksIm5iZiI6MCwiaWF0IjoxNTczODA5MzE5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJzdWIiOiIyM2Q0NmRjZS1jY2JiLTQxYzMtOGRkOC00OWRkYjI5YmI3NGIiLCJ0eXAiOiJSZWZyZXNoIiwiYXpwIjoiemVwcGVsaW4tY2xpZW50Iiwibm9uY2UiOiI2WXVvdFVjS2xNQTUxaWhSYVZ4aldrMGVQcXRrWjRTVmdwX0VLUDE3U21NIiwiYXV0aF90aW1lIjowLCJzZXNzaW9uX3N0YXRlIjoiMmRjOGY3YzQtYmNjNi00MjQ0LWJlMDQtOGFlYzljYjU5ZWJlIiwicmVhbG1fYWNjZXNzIjp7InJvbGVzIjpbIm9mZmxpbmVfYWNjZXNzIiwidW1hX2F1dGhvcml6YXRpb24iXX0sInJlc291cmNlX2FjY2VzcyI6eyJ6ZXBwZWxpbi1jbGllbnQiOnsicm9sZXMiOlsidXNlciJdfSwiYWNjb3VudCI6eyJyb2xlcyI6WyJtYW5hZ2UtYWNjb3VudCIsIm1hbmFnZS1hY2NvdW50LWxpbmtzIiwidmlldy1wcm9maWxlIl19fSwic2NvcGUiOiJvcGVuaWQgcHJvZmlsZSBlbWFpbCJ9.zJejJ0cmVBEzuBMHhJDS2EeiXyENArwEv8MeIeXSrHQ, aud=[zeppelin-client], acr=0, nbf=Thu Jan 01 01:00:00 CET 1970, azp=zeppelin-client, auth_time=Fri Nov 15 10:14:49 CET 2019, exp=Fri Nov 15 10:20:19 CET 2019, session_state=2dc8f7c4-bcc6-4244-be04-8aec9cb59ebe, iat=Fri Nov 15 10:15:19 CET 2019, jti=50b55f1b-e9dc-4a65-82ee-f0160fb72b81} | roles: [offline_access, uma_authorization, user] | permissions: [] | isRemembered: false | clientName: keycloakOidcClient | linkedId: null |] |] from doGetAuthenticationInfo DEBUG [2019-11-15 10:15:19,179] ({qtp89387388-13} AuthenticatingRealm.java[cacheAuthenticationInfoIfPossible]:510) - AuthenticationInfo caching is disabled for info [#Pac4jPrincipal# | profiles: [#KeycloakOidcProfile# | id: 23d46dce-ccbb-41c3-8dd8-49ddb29bb74b | attributes: {sub=23d46dce-ccbb-41c3-8dd8-49ddb29bb74b, email_verified=false, id_token=eyJhbGciOiJSUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICJyRG51S0NjVEFoalZUelZSaGtTTnhBaEhaeUtmS2JQQ3pmZUpnbmp3RkxBIn0.eyJqdGkiOiI1MGI1NWYxYi1lOWRjLTRhNjUtODJlZS1mMDE2MGZiNzJiODEiLCJleHAiOjE1NzM4MDk2MTksIm5iZiI6MCwiaWF0IjoxNTczODA5MzE5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJ6ZXBwZWxpbi1jbGllbnQiLCJzdWIiOiIyM2Q0NmRjZS1jY2JiLTQxYzMtOGRkOC00OWRkYjI5YmI3NGIiLCJ0eXAiOiJJRCIsImF6cCI6InplcHBlbGluLWNsaWVudCIsIm5vbmNlIjoiNll1b3RVY0tsTUE1MWloUmFWeGpXazBlUHF0a1o0U1ZncF9FS1AxN1NtTSIsImF1dGhfdGltZSI6MTU3MzgwOTI4OSwic2Vzc2lvbl9zdGF0ZSI6IjJkYzhmN2M0LWJjYzYtNDI0NC1iZTA0LThhZWM5Y2I1OWViZSIsImFjciI6IjAiLCJlbWFpbF92ZXJpZmllZCI6ZmFsc2UsInByZWZlcnJlZF91c2VybmFtZSI6ImVzaXJlbSJ9.UWKMOyAIaDMY9yOym6_09celHj2slMrzgeZ3vIXQfZ07fqdJi36Kz_uzGXHsDlM_UJaNH0EtMPKelWBxmAs6L_Ts7MRqinhCFpyh-IpOCYrli719gb1U4lXW4_hbUBCErAhJARcHE29u-GVu6BEOcBYD2FZP5c5c-YSgBZ6Apq9jX5PQEg_zy32ZgRRxal67Su5BHCzMg8o0DT_SyOk-cFtnY_ctBQIinW_O1lBGYJpAv9i8agzO7MYsho-zN16Me8hIOzPki0xi1UyFiErMOoHKwWTvLcGmWTuQLtCB9rt_0zmUr9LeOhbwHAgkThsWghGsRfzQBCHbmnI9fnthjA, iss=http://localhost:5000/auth/realms/zeppelin, typ=ID, preferred_username=esirem, nonce=6YuotUcKlMA51ihRaVxjWk0ePqtkZ4SVgp_EKP17SmM, access_token=eyJhbGciOiJSUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICJyRG51S0NjVEFoalZUelZSaGtTTnhBaEhaeUtmS2JQQ3pmZUpnbmp3RkxBIn0.eyJqdGkiOiIwM2I0NTVjMS0wZmFlLTQxYmUtODEwOS03YzIyYmQwMjQwM2EiLCJleHAiOjE1NzM4MDk2MTksIm5iZiI6MCwiaWF0IjoxNTczODA5MzE5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJhY2NvdW50Iiwic3ViIjoiMjNkNDZkY2UtY2NiYi00MWMzLThkZDgtNDlkZGIyOWJiNzRiIiwidHlwIjoiQmVhcmVyIiwiYXpwIjoiemVwcGVsaW4tY2xpZW50Iiwibm9uY2UiOiI2WXVvdFVjS2xNQTUxaWhSYVZ4aldrMGVQcXRrWjRTVmdwX0VLUDE3U21NIiwiYXV0aF90aW1lIjoxNTczODA5Mjg5LCJzZXNzaW9uX3N0YXRlIjoiMmRjOGY3YzQtYmNjNi00MjQ0LWJlMDQtOGFlYzljYjU5ZWJlIiwiYWNyIjoiMCIsInJlYWxtX2FjY2VzcyI6eyJyb2xlcyI6WyJvZmZsaW5lX2FjY2VzcyIsInVtYV9hdXRob3JpemF0aW9uIl19LCJyZXNvdXJjZV9hY2Nlc3MiOnsiemVwcGVsaW4tY2xpZW50Ijp7InJvbGVzIjpbInVzZXIiXX0sImFjY291bnQiOnsicm9sZXMiOlsibWFuYWdlLWFjY291bnQiLCJtYW5hZ2UtYWNjb3VudC1saW5rcyIsInZpZXctcHJvZmlsZSJdfX0sInNjb3BlIjoib3BlbmlkIHByb2ZpbGUgZW1haWwiLCJlbWFpbF92ZXJpZmllZCI6ZmFsc2UsInByZWZlcnJlZF91c2VybmFtZSI6ImVzaXJlbSJ9.RFGwCiT1JVQo5bM6gcCMw0xy10dcMMfhELDXMEmkvc3ENDDDXfDXCh4SBgwqVRppZAyq5Xyz4Kz1uPyXe5JfufGFL-ooXF5GQWOtG4UW6Xj-DTCv0d8pwldHZuFYVaG9bnQ6cCKWhcWhyzgqK1D0i0hNfNHHmb6dRaksLDxvJorwPAKH7AeKrJPk5-MQO0dn2sxaROJY9FeA300K0XFOclm7Xi1E2870QvqMUSTneykIvIzjmgclGc4NoCdSgz_g6g0WwVOuTDraYRqi3dfLW9OIXuYj47TvuxjdbmG-y7JYoQpUth1HJWK-g5bKKodZ6NQartUTCAiml--hsjdL7w, token_expiration_advance=-1, refresh_token=eyJhbGciOiJIUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICIwMmQ3ZGIxZi1iOTNjLTRlOGMtYTY5MC0zOWE5OGM0NjM0ZWMifQ.eyJqdGkiOiJmZTZjYTM3Yy1mMDg3LTQyYTAtYTZjMy0yZjk3OWIzOWJjNDIiLCJleHAiOjE1NzM4MTExMTksIm5iZiI6MCwiaWF0IjoxNTczODA5MzE5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJzdWIiOiIyM2Q0NmRjZS1jY2JiLTQxYzMtOGRkOC00OWRkYjI5YmI3NGIiLCJ0eXAiOiJSZWZyZXNoIiwiYXpwIjoiemVwcGVsaW4tY2xpZW50Iiwibm9uY2UiOiI2WXVvdFVjS2xNQTUxaWhSYVZ4aldrMGVQcXRrWjRTVmdwX0VLUDE3U21NIiwiYXV0aF90aW1lIjowLCJzZXNzaW9uX3N0YXRlIjoiMmRjOGY3YzQtYmNjNi00MjQ0LWJlMDQtOGFlYzljYjU5ZWJlIiwicmVhbG1fYWNjZXNzIjp7InJvbGVzIjpbIm9mZmxpbmVfYWNjZXNzIiwidW1hX2F1dGhvcml6YXRpb24iXX0sInJlc291cmNlX2FjY2VzcyI6eyJ6ZXBwZWxpbi1jbGllbnQiOnsicm9sZXMiOlsidXNlciJdfSwiYWNjb3VudCI6eyJyb2xlcyI6WyJtYW5hZ2UtYWNjb3VudCIsIm1hbmFnZS1hY2NvdW50LWxpbmtzIiwidmlldy1wcm9maWxlIl19fSwic2NvcGUiOiJvcGVuaWQgcHJvZmlsZSBlbWFpbCJ9.zJejJ0cmVBEzuBMHhJDS2EeiXyENArwEv8MeIeXSrHQ, aud=[zeppelin-client], acr=0, nbf=Thu Jan 01 01:00:00 CET 1970, azp=zeppelin-client, auth_time=Fri Nov 15 10:14:49 CET 2019, exp=Fri Nov 15 10:20:19 CET 2019, session_state=2dc8f7c4-bcc6-4244-be04-8aec9cb59ebe, iat=Fri Nov 15 10:15:19 CET 2019, jti=50b55f1b-e9dc-4a65-82ee-f0160fb72b81} | roles: [offline_access, uma_authorization, user] | permissions: [] | isRemembered: false | clientName: keycloakOidcClient | linkedId: null |] |]. Submitted token: [io.buji.pac4j.token.Pac4jToken@30dd988d]. DEBUG [2019-11-15 10:15:19,182] ({qtp89387388-13} SimpleCredentialsMatcher.java[equals]:96) - Performing credentials equality check for tokenCredentials of type [java.lang.Integer and accountCredentials of type [java.lang.Integer]
DEBUG [2019-11-15 10:15:19,183] ({qtp89387388-13} AbstractAuthenticator.java[authenticate]:233) - Authentication successful for token [io.buji.pac4j.token.Pac4jToken@30dd988d]. Returned account [#Pac4jPrincipal# | profiles: [#KeycloakOidcProfile# | id: 23d46dce-ccbb-41c3-8dd8-49ddb29bb74b | attributes: {sub=23d46dce-ccbb-41c3-8dd8-49ddb29bb74b, email_verified=false, id_token=eyJhbGciOiJSUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICJyRG51S0NjVEFoalZUelZSaGtTTnhBaEhaeUtmS2JQQ3pmZUpnbmp3RkxBIn0.eyJqdGkiOiI1MGI1NWYxYi1lOWRjLTRhNjUtODJlZS1mMDE2MGZiNzJiODEiLCJleHAiOjE1NzM4MDk2MTksIm5iZiI6MCwiaWF0IjoxNTczODA5MzE5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJ6ZXBwZWxpbi1jbGllbnQiLCJzdWIiOiIyM2Q0NmRjZS1jY2JiLTQxYzMtOGRkOC00OWRkYjI5YmI3NGIiLCJ0eXAiOiJJRCIsImF6cCI6InplcHBlbGluLWNsaWVudCIsIm5vbmNlIjoiNll1b3RVY0tsTUE1MWloUmFWeGpXazBlUHF0a1o0U1ZncF9FS1AxN1NtTSIsImF1dGhfdGltZSI6MTU3MzgwOTI4OSwic2Vzc2lvbl9zdGF0ZSI6IjJkYzhmN2M0LWJjYzYtNDI0NC1iZTA0LThhZWM5Y2I1OWViZSIsImFjciI6IjAiLCJlbWFpbF92ZXJpZmllZCI6ZmFsc2UsInByZWZlcnJlZF91c2VybmFtZSI6ImVzaXJlbSJ9.UWKMOyAIaDMY9yOym6_09celHj2slMrzgeZ3vIXQfZ07fqdJi36Kz_uzGXHsDlM_UJaNH0EtMPKelWBxmAs6L_Ts7MRqinhCFpyh-IpOCYrli719gb1U4lXW4_hbUBCErAhJARcHE29u-GVu6BEOcBYD2FZP5c5c-YSgBZ6Apq9jX5PQEg_zy32ZgRRxal67Su5BHCzMg8o0DT_SyOk-cFtnY_ctBQIinW_O1lBGYJpAv9i8agzO7MYsho-zN16Me8hIOzPki0xi1UyFiErMOoHKwWTvLcGmWTuQLtCB9rt_0zmUr9LeOhbwHAgkThsWghGsRfzQBCHbmnI9fnthjA, iss=http://localhost:5000/auth/realms/zeppelin, typ=ID, preferred_username=esirem, nonce=6YuotUcKlMA51ihRaVxjWk0ePqtkZ4SVgp_EKP17SmM, access_token=eyJhbGciOiJSUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICJyRG51S0NjVEFoalZUelZSaGtTTnhBaEhaeUtmS2JQQ3pmZUpnbmp3RkxBIn0.eyJqdGkiOiIwM2I0NTVjMS0wZmFlLTQxYmUtODEwOS03YzIyYmQwMjQwM2EiLCJleHAiOjE1NzM4MDk2MTksIm5iZiI6MCwiaWF0IjoxNTczODA5MzE5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJhY2NvdW50Iiwic3ViIjoiMjNkNDZkY2UtY2NiYi00MWMzLThkZDgtNDlkZGIyOWJiNzRiIiwidHlwIjoiQmVhcmVyIiwiYXpwIjoiemVwcGVsaW4tY2xpZW50Iiwibm9uY2UiOiI2WXVvdFVjS2xNQTUxaWhSYVZ4aldrMGVQcXRrWjRTVmdwX0VLUDE3U21NIiwiYXV0aF90aW1lIjoxNTczODA5Mjg5LCJzZXNzaW9uX3N0YXRlIjoiMmRjOGY3YzQtYmNjNi00MjQ0LWJlMDQtOGFlYzljYjU5ZWJlIiwiYWNyIjoiMCIsInJlYWxtX2FjY2VzcyI6eyJyb2xlcyI6WyJvZmZsaW5lX2FjY2VzcyIsInVtYV9hdXRob3JpemF0aW9uIl19LCJyZXNvdXJjZV9hY2Nlc3MiOnsiemVwcGVsaW4tY2xpZW50Ijp7InJvbGVzIjpbInVzZXIiXX0sImFjY291bnQiOnsicm9sZXMiOlsibWFuYWdlLWFjY291bnQiLCJtYW5hZ2UtYWNjb3VudC1saW5rcyIsInZpZXctcHJvZmlsZSJdfX0sInNjb3BlIjoib3BlbmlkIHByb2ZpbGUgZW1haWwiLCJlbWFpbF92ZXJpZmllZCI6ZmFsc2UsInByZWZlcnJlZF91c2VybmFtZSI6ImVzaXJlbSJ9.RFGwCiT1JVQo5bM6gcCMw0xy10dcMMfhELDXMEmkvc3ENDDDXfDXCh4SBgwqVRppZAyq5Xyz4Kz1uPyXe5JfufGFL-ooXF5GQWOtG4UW6Xj-DTCv0d8pwldHZuFYVaG9bnQ6cCKWhcWhyzgqK1D0i0hNfNHHmb6dRaksLDxvJorwPAKH7AeKrJPk5-MQO0dn2sxaROJY9FeA300K0XFOclm7Xi1E2870QvqMUSTneykIvIzjmgclGc4NoCdSgz_g6g0WwVOuTDraYRqi3dfLW9OIXuYj47TvuxjdbmG-y7JYoQpUth1HJWK-g5bKKodZ6NQartUTCAiml--hsjdL7w, token_expiration_advance=-1, refresh_token=eyJhbGciOiJIUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICIwMmQ3ZGIxZi1iOTNjLTRlOGMtYTY5MC0zOWE5OGM0NjM0ZWMifQ.eyJqdGkiOiJmZTZjYTM3Yy1mMDg3LTQyYTAtYTZjMy0yZjk3OWIzOWJjNDIiLCJleHAiOjE1NzM4MTExMTksIm5iZiI6MCwiaWF0IjoxNTczODA5MzE5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJzdWIiOiIyM2Q0NmRjZS1jY2JiLTQxYzMtOGRkOC00OWRkYjI5YmI3NGIiLCJ0eXAiOiJSZWZyZXNoIiwiYXpwIjoiemVwcGVsaW4tY2xpZW50Iiwibm9uY2UiOiI2WXVvdFVjS2xNQTUxaWhSYVZ4aldrMGVQcXRrWjRTVmdwX0VLUDE3U21NIiwiYXV0aF90aW1lIjowLCJzZXNzaW9uX3N0YXRlIjoiMmRjOGY3YzQtYmNjNi00MjQ0LWJlMDQtOGFlYzljYjU5ZWJlIiwicmVhbG1fYWNjZXNzIjp7InJvbGVzIjpbIm9mZmxpbmVfYWNjZXNzIiwidW1hX2F1dGhvcml6YXRpb24iXX0sInJlc291cmNlX2FjY2VzcyI6eyJ6ZXBwZWxpbi1jbGllbnQiOnsicm9sZXMiOlsidXNlciJdfSwiYWNjb3VudCI6eyJyb2xlcyI6WyJtYW5hZ2UtYWNjb3VudCIsIm1hbmFnZS1hY2NvdW50LWxpbmtzIiwidmlldy1wcm9maWxlIl19fSwic2NvcGUiOiJvcGVuaWQgcHJvZmlsZSBlbWFpbCJ9.zJejJ0cmVBEzuBMHhJDS2EeiXyENArwEv8MeIeXSrHQ, aud=[zeppelin-client], acr=0, nbf=Thu Jan 01 01:00:00 CET 1970, azp=zeppelin-client, auth_time=Fri Nov 15 10:14:49 CET 2019, exp=Fri Nov 15 10:20:19 CET 2019, session_state=2dc8f7c4-bcc6-4244-be04-8aec9cb59ebe, iat=Fri Nov 15 10:15:19 CET 2019, jti=50b55f1b-e9dc-4a65-82ee-f0160fb72b81} | roles: [offline_access, uma_authorization, user] | permissions: [] | isRemembered: false | clientName: keycloakOidcClient | linkedId: null |] |] TRACE [2019-11-15 10:15:19,183] ({qtp89387388-13} DefaultSecurityManager.java[ensureSecurityManager]:417) - Context already contains a SecurityManager instance. Returning.
TRACE [2019-11-15 10:15:19,183] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
DEBUG [2019-11-15 10:15:19,184] ({qtp89387388-13} DefaultSecurityManager.java[resolveSession]:441) - Context already contains a session. Returning.
TRACE [2019-11-15 10:15:19,184] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:19,184] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:19,184] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:19,185] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:19,185] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@3390c75b
TRACE [2019-11-15 10:15:19,185] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:19,186] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@3390c75b
TRACE [2019-11-15 10:15:19,186] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:19,186] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@3390c75b
TRACE [2019-11-15 10:15:19,187] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@3390c75b
TRACE [2019-11-15 10:15:19,187] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:19,187] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@3390c75b
TRACE [2019-11-15 10:15:19,188] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@3390c75b
TRACE [2019-11-15 10:15:19,188] ({qtp89387388-13} SimpleCookie.java[calculatePath]:199) - calculated path: /
DEBUG [2019-11-15 10:15:19,188] ({qtp89387388-13} SimpleCookie.java[addCookieHeader]:226) - Added HttpServletResponse Cookie [rememberMe=deleteMe; Path=/; Max-Age=0; Expires=Thu, 14-Nov-2019 09:15:19 GMT]
TRACE [2019-11-15 10:15:19,188] ({qtp89387388-13} SimpleCookie.java[removeFrom]:375) - Removed 'rememberMe' cookie by setting maxAge=0
DEBUG [2019-11-15 10:15:19,189] ({qtp89387388-13} AbstractRememberMeManager.java[onSuccessfulLogin]:290) - AuthenticationToken did not indicate RememberMe is requested. RememberMe functionality will not be executed for corresponding account.
TRACE [2019-11-15 10:15:19,189] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:19,189] ({qtp89387388-13} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-13]
TRACE [2019-11-15 10:15:19,190] ({qtp89387388-13} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] bound to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:19,191] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:19,191] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@3390c75b
DEBUG [2019-11-15 10:15:19,191] ({qtp89387388-13} DefaultCallbackLogic.java[redirectToOriginallyRequestedUrl]:146) - redirectUrl: http://localhost:8080 TRACE [2019-11-15 10:15:20,373] ({qtp89387388-15} OncePerRequestFilter.java[doFilter]:121) - Filter 'org.apache.shiro.web.servlet.ShiroFilter-17baae6e' not yet executed. Executing now.
TRACE [2019-11-15 10:15:20,374] ({qtp89387388-15} DefaultSecurityManager.java[ensureSecurityManager]:417) - Context already contains a SecurityManager instance. Returning.
TRACE [2019-11-15 10:15:20,375] ({qtp89387388-15} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@7e6b1fd3
DEBUG [2019-11-15 10:15:20,375] ({qtp89387388-15} SimpleCookie.java[readValue]:389) - Found 'JSESSIONID' cookie value [eccd494d-6f7e-47f6-8bab-18f44f9d55a7]
TRACE [2019-11-15 10:15:20,375] ({qtp89387388-15} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@83ac0df
TRACE [2019-11-15 10:15:20,375] ({qtp89387388-15} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@83ac0df
TRACE [2019-11-15 10:15:20,375] ({qtp89387388-15} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@83ac0df
TRACE [2019-11-15 10:15:20,376] ({qtp89387388-15} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@83ac0df
TRACE [2019-11-15 10:15:20,376] ({qtp89387388-15} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:20,376] ({qtp89387388-15} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:20,376] ({qtp89387388-15} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@83ac0df
TRACE [2019-11-15 10:15:20,377] ({qtp89387388-15} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:20,377] ({qtp89387388-15} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@83ac0df
TRACE [2019-11-15 10:15:20,377] ({qtp89387388-15} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:20,377] ({qtp89387388-15} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@83ac0df
TRACE [2019-11-15 10:15:20,377] ({qtp89387388-15} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:20,389] ({qtp89387388-15} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@83ac0df
TRACE [2019-11-15 10:15:20,393] ({qtp89387388-15} ThreadContext.java[put]:169) - Bound value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] to thread [qtp89387388-15]
TRACE [2019-11-15 10:15:20,393] ({qtp89387388-15} ThreadContext.java[put]:169) - Bound value of type [org.apache.shiro.web.mgt.DefaultWebSecurityManager] for key [org.apache.shiro.util.ThreadContext_SECURITY_MANAGER_KEY] to thread [qtp89387388-15]
TRACE [2019-11-15 10:15:20,394] ({qtp89387388-15} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-15]
TRACE [2019-11-15 10:15:20,394] ({qtp89387388-15} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] bound to thread [qtp89387388-15]
TRACE [2019-11-15 10:15:20,394] ({qtp89387388-15} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:20,394] ({qtp89387388-15} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@83ac0df
TRACE [2019-11-15 10:15:20,394] ({qtp89387388-15} PathMatchingFilterChainResolver.java[getChain]:108) - Matched path pattern [/**] for requestURI [/api/security/ticket]. Utilizing corresponding filter chain...
TRACE [2019-11-15 10:15:20,394] ({qtp89387388-15} AbstractShiroFilter.java[getExecutionChain]:417) - Resolved a configured FilterChain for the current request.
TRACE [2019-11-15 10:15:20,395] ({qtp89387388-15} ProxiedFilterChain.java[doFilter]:64) - Invoking wrapped filter at index [0]
DEBUG [2019-11-15 10:15:20,395] ({qtp89387388-15} DefaultSecurityLogic.java[perform]:66) - === SECURITY ===
DEBUG [2019-11-15 10:15:20,395] ({qtp89387388-15} DefaultSecurityLogic.java[perform]:92) - matchers: null
DEBUG [2019-11-15 10:15:20,395] ({qtp89387388-15} DefaultSecurityLogic.java[perform]:95) - clients: keycloakOidcClient
DEBUG [2019-11-15 10:15:20,396] ({qtp89387388-15} DefaultSecurityClientFinder.java[find]:37) - Provided clientNames: keycloakOidcClient
DEBUG [2019-11-15 10:15:20,396] ({qtp89387388-15} DefaultSecurityClientFinder.java[find]:53) - clientNameOnRequest: null
DEBUG [2019-11-15 10:15:20,396] ({qtp89387388-15} DefaultSecurityClientFinder.java[find]:79) - result: [keycloakOidcClient]
DEBUG [2019-11-15 10:15:20,400] ({qtp89387388-15} DefaultSecurityLogic.java[perform]:97) - currentClients: [#KeycloakOidcClient# | name: keycloakOidcClient | callbackUrl: http://localhost:8080/api/callback | callbackUrlResolver: org.pac4j.core.http.callback.QueryParameterCallbackUrlResolver@266d1b8e | ajaxRequestResolver: org.pac4j.core.http.ajax.DefaultAjaxRequestResolver@44a72b54 | redirectActionBuilder: org.pac4j.oidc.redirect.OidcRedirectActionBuilder@52eb37e7 | credentialsExtractor: org.pac4j.oidc.credentials.extractor.OidcExtractor@7316a6a7 | authenticator: org.pac4j.oidc.credentials.authenticator.OidcAuthenticator@3d697220 | profileCreator: org.pac4j.oidc.profile.creator.OidcProfileCreator@40e91352 | logoutActionBuilder: org.pac4j.oidc.logout.OidcLogoutActionBuilder@4bb650a7 | authorizationGenerators: [org.pac4j.oidc.authorization.generator.KeycloakRolesAuthorizationGenerator@223aa2f7, org.pac4j.oidc.authorization.generator.KeycloakRolesAuthorizationGenerator@d53f4e8] | configuration: #KeycloakOidcConfiguration# | clientId: zeppelin-client | secret: [protected] | discoveryURI: null | scope: openid | customParams: {} | clientAuthenticationMethod: client_secret_basic | useNonce: true | preferredJwsAlgorithm: null | maxAge: null | maxClockSkew: 30 | connectTimeout: 500 | readTimeout: 5000 | resourceRetriever: com.nimbusds.jose.util.DefaultResourceRetriever@7b5d7f10 | responseType: code | responseMode: null | logoutUrl: http://localhost:5000/auth/realms/zeppelin/protocol/openid-connect/logout | withState: false | stateGenerator: org.pac4j.core.state.StaticOrRandomStateGenerator@50ad3bc1 | |] DEBUG [2019-11-15 10:15:20,401] ({qtp89387388-15} DefaultSecurityLogic.java[perform]:100) - loadProfilesFromSession: true
TRACE [2019-11-15 10:15:20,401] ({qtp89387388-15} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-15]
TRACE [2019-11-15 10:15:20,401] ({qtp89387388-15} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] bound to thread [qtp89387388-15]
TRACE [2019-11-15 10:15:20,401] ({qtp89387388-15} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:20,401] ({qtp89387388-15} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@83ac0df
DEBUG [2019-11-15 10:15:20,402] ({qtp89387388-15} DefaultSecurityLogic.java[perform]:103) - profiles: [#KeycloakOidcProfile# | id: 23d46dce-ccbb-41c3-8dd8-49ddb29bb74b | attributes: {sub=23d46dce-ccbb-41c3-8dd8-49ddb29bb74b, email_verified=false, id_token=eyJhbGciOiJSUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICJyRG51S0NjVEFoalZUelZSaGtTTnhBaEhaeUtmS2JQQ3pmZUpnbmp3RkxBIn0.eyJqdGkiOiI1MGI1NWYxYi1lOWRjLTRhNjUtODJlZS1mMDE2MGZiNzJiODEiLCJleHAiOjE1NzM4MDk2MTksIm5iZiI6MCwiaWF0IjoxNTczODA5MzE5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJ6ZXBwZWxpbi1jbGllbnQiLCJzdWIiOiIyM2Q0NmRjZS1jY2JiLTQxYzMtOGRkOC00OWRkYjI5YmI3NGIiLCJ0eXAiOiJJRCIsImF6cCI6InplcHBlbGluLWNsaWVudCIsIm5vbmNlIjoiNll1b3RVY0tsTUE1MWloUmFWeGpXazBlUHF0a1o0U1ZncF9FS1AxN1NtTSIsImF1dGhfdGltZSI6MTU3MzgwOTI4OSwic2Vzc2lvbl9zdGF0ZSI6IjJkYzhmN2M0LWJjYzYtNDI0NC1iZTA0LThhZWM5Y2I1OWViZSIsImFjciI6IjAiLCJlbWFpbF92ZXJpZmllZCI6ZmFsc2UsInByZWZlcnJlZF91c2VybmFtZSI6ImVzaXJlbSJ9.UWKMOyAIaDMY9yOym6_09celHj2slMrzgeZ3vIXQfZ07fqdJi36Kz_uzGXHsDlM_UJaNH0EtMPKelWBxmAs6L_Ts7MRqinhCFpyh-IpOCYrli719gb1U4lXW4_hbUBCErAhJARcHE29u-GVu6BEOcBYD2FZP5c5c-YSgBZ6Apq9jX5PQEg_zy32ZgRRxal67Su5BHCzMg8o0DT_SyOk-cFtnY_ctBQIinW_O1lBGYJpAv9i8agzO7MYsho-zN16Me8hIOzPki0xi1UyFiErMOoHKwWTvLcGmWTuQLtCB9rt_0zmUr9LeOhbwHAgkThsWghGsRfzQBCHbmnI9fnthjA, iss=http://localhost:5000/auth/realms/zeppelin, typ=ID, preferred_username=esirem, nonce=6YuotUcKlMA51ihRaVxjWk0ePqtkZ4SVgp_EKP17SmM, access_token=eyJhbGciOiJSUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICJyRG51S0NjVEFoalZUelZSaGtTTnhBaEhaeUtmS2JQQ3pmZUpnbmp3RkxBIn0.eyJqdGkiOiIwM2I0NTVjMS0wZmFlLTQxYmUtODEwOS03YzIyYmQwMjQwM2EiLCJleHAiOjE1NzM4MDk2MTksIm5iZiI6MCwiaWF0IjoxNTczODA5MzE5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJhY2NvdW50Iiwic3ViIjoiMjNkNDZkY2UtY2NiYi00MWMzLThkZDgtNDlkZGIyOWJiNzRiIiwidHlwIjoiQmVhcmVyIiwiYXpwIjoiemVwcGVsaW4tY2xpZW50Iiwibm9uY2UiOiI2WXVvdFVjS2xNQTUxaWhSYVZ4aldrMGVQcXRrWjRTVmdwX0VLUDE3U21NIiwiYXV0aF90aW1lIjoxNTczODA5Mjg5LCJzZXNzaW9uX3N0YXRlIjoiMmRjOGY3YzQtYmNjNi00MjQ0LWJlMDQtOGFlYzljYjU5ZWJlIiwiYWNyIjoiMCIsInJlYWxtX2FjY2VzcyI6eyJyb2xlcyI6WyJvZmZsaW5lX2FjY2VzcyIsInVtYV9hdXRob3JpemF0aW9uIl19LCJyZXNvdXJjZV9hY2Nlc3MiOnsiemVwcGVsaW4tY2xpZW50Ijp7InJvbGVzIjpbInVzZXIiXX0sImFjY291bnQiOnsicm9sZXMiOlsibWFuYWdlLWFjY291bnQiLCJtYW5hZ2UtYWNjb3VudC1saW5rcyIsInZpZXctcHJvZmlsZSJdfX0sInNjb3BlIjoib3BlbmlkIHByb2ZpbGUgZW1haWwiLCJlbWFpbF92ZXJpZmllZCI6ZmFsc2UsInByZWZlcnJlZF91c2VybmFtZSI6ImVzaXJlbSJ9.RFGwCiT1JVQo5bM6gcCMw0xy10dcMMfhELDXMEmkvc3ENDDDXfDXCh4SBgwqVRppZAyq5Xyz4Kz1uPyXe5JfufGFL-ooXF5GQWOtG4UW6Xj-DTCv0d8pwldHZuFYVaG9bnQ6cCKWhcWhyzgqK1D0i0hNfNHHmb6dRaksLDxvJorwPAKH7AeKrJPk5-MQO0dn2sxaROJY9FeA300K0XFOclm7Xi1E2870QvqMUSTneykIvIzjmgclGc4NoCdSgz_g6g0WwVOuTDraYRqi3dfLW9OIXuYj47TvuxjdbmG-y7JYoQpUth1HJWK-g5bKKodZ6NQartUTCAiml--hsjdL7w, token_expiration_advance=-1, refresh_token=eyJhbGciOiJIUzI1NiIsInR5cCIgOiAiSldUIiwia2lkIiA6ICIwMmQ3ZGIxZi1iOTNjLTRlOGMtYTY5MC0zOWE5OGM0NjM0ZWMifQ.eyJqdGkiOiJmZTZjYTM3Yy1mMDg3LTQyYTAtYTZjMy0yZjk3OWIzOWJjNDIiLCJleHAiOjE1NzM4MTExMTksIm5iZiI6MCwiaWF0IjoxNTczODA5MzE5LCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJhdWQiOiJodHRwOi8vbG9jYWxob3N0OjUwMDAvYXV0aC9yZWFsbXMvemVwcGVsaW4iLCJzdWIiOiIyM2Q0NmRjZS1jY2JiLTQxYzMtOGRkOC00OWRkYjI5YmI3NGIiLCJ0eXAiOiJSZWZyZXNoIiwiYXpwIjoiemVwcGVsaW4tY2xpZW50Iiwibm9uY2UiOiI2WXVvdFVjS2xNQTUxaWhSYVZ4aldrMGVQcXRrWjRTVmdwX0VLUDE3U21NIiwiYXV0aF90aW1lIjowLCJzZXNzaW9uX3N0YXRlIjoiMmRjOGY3YzQtYmNjNi00MjQ0LWJlMDQtOGFlYzljYjU5ZWJlIiwicmVhbG1fYWNjZXNzIjp7InJvbGVzIjpbIm9mZmxpbmVfYWNjZXNzIiwidW1hX2F1dGhvcml6YXRpb24iXX0sInJlc291cmNlX2FjY2VzcyI6eyJ6ZXBwZWxpbi1jbGllbnQiOnsicm9sZXMiOlsidXNlciJdfSwiYWNjb3VudCI6eyJyb2xlcyI6WyJtYW5hZ2UtYWNjb3VudCIsIm1hbmFnZS1hY2NvdW50LWxpbmtzIiwidmlldy1wcm9maWxlIl19fSwic2NvcGUiOiJvcGVuaWQgcHJvZmlsZSBlbWFpbCJ9.zJejJ0cmVBEzuBMHhJDS2EeiXyENArwEv8MeIeXSrHQ, aud=[zeppelin-client], acr=0, nbf=Thu Jan 01 01:00:00 CET 1970, azp=zeppelin-client, auth_time=Fri Nov 15 10:14:49 CET 2019, exp=Fri Nov 15 10:20:19 CET 2019, session_state=2dc8f7c4-bcc6-4244-be04-8aec9cb59ebe, iat=Fri Nov 15 10:15:19 CET 2019, jti=50b55f1b-e9dc-4a65-82ee-f0160fb72b81} | roles: [offline_access, uma_authorization, user] | permissions: [] | isRemembered: false | clientName: keycloakOidcClient | linkedId: null |] DEBUG [2019-11-15 10:15:20,402] ({qtp89387388-15} DefaultSecurityLogic.java[perform]:137) - authorizers: null
DEBUG [2019-11-15 10:15:20,402] ({qtp89387388-15} DefaultSecurityLogic.java[perform]:139) - authenticated and authorized -> grant access
TRACE [2019-11-15 10:15:20,403] ({qtp89387388-15} ProxiedFilterChain.java[doFilter]:59) - Invoking original filter chain.
TRACE [2019-11-15 10:15:20,405] ({qtp89387388-15} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-15]
TRACE [2019-11-15 10:15:20,406] ({qtp89387388-15} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] bound to thread [qtp89387388-15]
TRACE [2019-11-15 10:15:20,406] ({qtp89387388-15} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:20,406] ({qtp89387388-15} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@83ac0df
TRACE [2019-11-15 10:15:20,407] ({qtp89387388-15} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-15]
TRACE [2019-11-15 10:15:20,407] ({qtp89387388-15} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] bound to thread [qtp89387388-15]
TRACE [2019-11-15 10:15:20,407] ({qtp89387388-15} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-15]
TRACE [2019-11-15 10:15:20,408] ({qtp89387388-15} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.mgt.DefaultWebSecurityManager] for key [org.apache.shiro.util.ThreadContext_SECURITY_MANAGER_KEY] bound to thread [qtp89387388-15]
TRACE [2019-11-15 10:15:20,838] ({qtp89387388-13} OncePerRequestFilter.java[doFilter]:121) - Filter 'org.apache.shiro.web.servlet.ShiroFilter-17baae6e' not yet executed. Executing now.
TRACE [2019-11-15 10:15:20,838] ({qtp89387388-13} DefaultSecurityManager.java[ensureSecurityManager]:417) - Context already contains a SecurityManager instance. Returning.
TRACE [2019-11-15 10:15:20,839] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@36ea6901
DEBUG [2019-11-15 10:15:20,839] ({qtp89387388-13} SimpleCookie.java[readValue]:389) - Found 'JSESSIONID' cookie value [eccd494d-6f7e-47f6-8bab-18f44f9d55a7]
TRACE [2019-11-15 10:15:20,839] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@22fcf22e
TRACE [2019-11-15 10:15:20,839] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@22fcf22e
TRACE [2019-11-15 10:15:20,839] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@22fcf22e
TRACE [2019-11-15 10:15:20,840] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@22fcf22e
TRACE [2019-11-15 10:15:20,840] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:20,840] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:20,840] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@22fcf22e
TRACE [2019-11-15 10:15:20,840] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:20,840] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@22fcf22e
TRACE [2019-11-15 10:15:20,841] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:20,841] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@22fcf22e
TRACE [2019-11-15 10:15:20,841] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:20,841] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@22fcf22e
TRACE [2019-11-15 10:15:20,841] ({qtp89387388-13} ThreadContext.java[put]:169) - Bound value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:20,841] ({qtp89387388-13} ThreadContext.java[put]:169) - Bound value of type [org.apache.shiro.web.mgt.DefaultWebSecurityManager] for key [org.apache.shiro.util.ThreadContext_SECURITY_MANAGER_KEY] to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:20,841] ({qtp89387388-13} ThreadContext.java[get]:126) - get() - in thread [qtp89387388-13]
TRACE [2019-11-15 10:15:20,842] ({qtp89387388-13} ThreadContext.java[get]:133) - Retrieved value of type [org.apache.shiro.web.subject.support.WebDelegatingSubject] for key [org.apache.shiro.util.ThreadContext_SUBJECT_KEY] bound to thread [qtp89387388-13]
TRACE [2019-11-15 10:15:20,842] ({qtp89387388-13} DelegatingSubject.java[getSession]:321) - attempting to get session; create = false; session is null = false; session has id = true
TRACE [2019-11-15 10:15:20,842] ({qtp89387388-13} AbstractValidatingSessionManager.java[doGetSession]:116) - Attempting to retrieve session with key org.apache.shiro.web.session.mgt.WebSessionKey@22fcf22e
TRACE [2019-11-15 10:15:20,842] ({qtp89387388-13} PathMatchingFilterChainResolver.java[getChain]:108) - Matched path pattern [/api/version] for requestURI [/api/version]. Utilizing corresponding filter chain...
TRACE [2019-11-15 10:15:20,842] ({qtp89387388-13} AbstractShiroFilter.java[getExecutionChain]:417) - Resolved a configured FilterChain for the current request.
TRACE [2019-11-15 10:15:20,843] ({qtp89387388-13} ProxiedFilterChain.java[doFilter]:64) - Invoking wrapped filter at index [0]
TRACE [2019-11-15 10:15:20,843] ({qtp89387388-13} OncePerRequestFilter.java[doFilter]:121) - Filter 'anon' not yet executed. Executing now.
TRACE [2019-11-15 10:15:20,843] ({qtp89387388-13} PathMatchingFilter.java[pathsMatch]:123) - Attempting to match pattern '/api/version' with current requestURI '/api/version'...
TRACE [2019-11-15 10:15:20,843] ({qtp89387388-13} PathMatchingFilter.java[preHandle]:176) - Current requestURI matches pattern '/api/version'. Determining filter chain execution...
TRACE [2019-11-15 10:15:20,843] ({qtp89387388-13} PathMatchingFilter.java[isFilterChainContinued]:197) - Filter 'anon' is enabled for the current request under path '/api/version' with config [null]. Delegating to subclass implementation for 'onPreHandle' check.
TRACE [2019-11-15 10:15:20,843] ({qtp89387388-13} AdviceFilter.java[doFilterInternal]:133) - Invoked preHandle method. Continuing chain?: [true]
TRACE [2019-11-15 10:15:20,844] ({qtp89387388-13} ProxiedFilterChain.java[doFilter]:59) - Invoking original filter chain.
TRACE [2019-11-15 10:15:20,848] ({qtp89387388-13} AdviceFilter.java[doFilterInternal]:142) - Successfully invoked postHandle method
TRACE [2019-11-15 10:15:20,848] ({qtp89387388-13} AdviceFilter.java[cleanup]:174) - Successfully invoked afterCompletion method.