By: Mursel Koseer user 02 Sep 2024 at 3:11 a.m. CDT

7 Responses
Mursel Koseer gravatar
I'm experiencing an issue with key rotation in Gluu Server. After changing the key rotation interval and restarting the servers, the keys are not refreshing even though they are expired. I've checked the logs and configuration, but the new keys aren't being generated or published to the JWKS endpoint as expected. Has anyone encountered this issue, or can you provide guidance on what might be preventing the keys from rotating properly?

By Michael Schwartz Account Admin 02 Sep 2024 at 10:03 a.m. CDT

Michael Schwartz gravatar
@Mobarak Hosen.Shakil can you help Mursel with this issue.

By Mobarak Hosen Shakil staff 02 Sep 2024 at 6:26 p.m. CDT

Mobarak Hosen Shakil gravatar
Hi, Mursel! I am checking it. I will let you know the update. Regards ~ Shakil

By Mobarak Hosen Shakil staff 02 Sep 2024 at 6:46 p.m. CDT

Mobarak Hosen Shakil gravatar
Hi, Mursel! What is the value you set for `keyRegenerationInterval` currently? Can you please check what are the values of `expired time` that are displayed in jwks? Regards ~ Shakil

By Mursel Koseer user 03 Sep 2024 at 12:21 a.m. CDT

Mursel Koseer gravatar
Hi, Shakil - The value that is set for keyRegenerationInterval is 12 (To see how it works). - At this moment the keys are empty ("keys" : [ ]), I don't know why. Regards Mursel

By Mobarak Hosen Shakil staff 09 Sep 2024 at 12:38 a.m. CDT

Mobarak Hosen Shakil gravatar
Hi, Mursel! Please share `oxauth.log` and `oxtrust.log` in TRACE level. Regards ~ Shakil

By Mursel Koseer user 11 Sep 2024 at 1:10 a.m. CDT

Mursel Koseer gravatar
Hi, Shakil! Here are the logs you asked for - Most of the time the keys are generated and eveyrthing works correctly but some times the keys are empty. - Interval to 1h => Keys are good most of the time. - Interval to 12h => Keys are always empty. - I also found this issue in my cluster manager (maybe this can cause it) => Key Rotation => "Key generator /home/User/.clustermgr5/javalibs/keygen.jar was not found. Key rotation will not work unless the instructions are followed https://gluu.org/docs/cm/installation/#add-key-generator" The following log is from the **oxauth.log** before and after the keys update (everything works for now) ``` 2024-09-11 00:00:51,631 INFO [qtp1199673596-21] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 00:01:51,677 ERROR [qtp1199673596-16] [org.gluu.oxauth.uma.service.Um aRptService] (UmaRptService.java:121) - Failed to find entry: tknCde=3a40b95a398 ba7299cdec2e992c1e4e78d19c444840bb3531b972797249f9418,ou=uma_rpt,ou=tokens,o=glu u org.gluu.persist.exception.EntryPersistenceException: Failed to find entry: tknC de=3a40b95a398ba7299cdec2e992c1e4e78d19c444840bb3531b972797249f9418,ou=uma_rpt,o u=tokens,o=gluu at org.gluu.persist.ldap.impl.LdapEntryManager.find(LdapEntryManager.jav a:395) ~[gluu-orm-ldap-4.5.2.Final.jar:?] at org.gluu.persist.impl.BaseEntryManager.find(BaseEntryManager.java:719 ) ~[gluu-orm-core-4.5.2.Final.jar:?] at org.gluu.persist.impl.BaseEntryManager.find(BaseEntryManager.java:598 ) ~[gluu-orm-core-4.5.2.Final.jar:?] at org.gluu.persist.impl.BaseEntryManager.find(BaseEntryManager.java:585 ) ~[gluu-orm-core-4.5.2.Final.jar:?] at jdk.internal.reflect.GeneratedMethodAccessor69.invoke(Unknown Source) ~[?:?] at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMe thodAccessorImpl.java:43) ~[?:?] at java.lang.reflect.Method.invoke(Method.java:566) ~[?:?] at org.jboss.weld.bean.proxy.AbstractBeanInstance.invoke(AbstractBeanIns tance.java:38) ~[weld-core-impl-3.1.9.Final.jar:3.1.9.Final] at org.jboss.weld.bean.proxy.ProxyMethodHandler.invoke(ProxyMethodHandle r.java:106) ~[weld-core-impl-3.1.9.Final.jar:3.1.9.Final] at org.gluu.persist.PersistenceEntryManager$EntityManager$1948487569$Pro xy$_$$_WeldClientProxy.find(Unknown Source) ~[gluu-orm-core-4.5.2.Final.jar:?] at org.gluu.oxauth.uma.service.UmaRptService.getRPTByCode(UmaRptService. java:114) ~[classes/:?] at org.gluu.oxauth.uma.service.UmaRptService$Proxy$_$$_WeldClientProxy.g etRPTByCode(Unknown Source) ~[classes/:?] at org.gluu.oxauth.uma.ws.rs.UmaRptIntrospectionWS.introspect(UmaRptIntr ospectionWS.java:89) ~[classes/:?] at org.gluu.oxauth.uma.ws.rs.UmaRptIntrospectionWS.introspectPost(UmaRpt IntrospectionWS.java:82) ~[classes/:?] at org.gluu.oxauth.uma.ws.rs.UmaRptIntrospectionWS$Proxy$_$$_WeldClientP roxy.introspectPost(Unknown Source) ~[classes/:?] at jdk.internal.reflect.GeneratedMethodAccessor198.invoke(Unknown Source ) ~[?:?] at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMe thodAccessorImpl.java:43) ~[?:?] at java.lang.reflect.Method.invoke(Method.java:566) ~[?:?] at org.jboss.resteasy.core.MethodInjectorImpl.invoke(MethodInjectorImpl. java:170) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.core.MethodInjectorImpl.invoke(MethodInjectorImpl. java:130) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.core.ResourceMethodInvoker.internalInvokeOnTarget( ResourceMethodInvoker.java:660) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.core.ResourceMethodInvoker.invokeOnTargetAfterFilt er(ResourceMethodInvoker.java:524) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.core.ResourceMethodInvoker.lambda$invokeOnTarget$2 (ResourceMethodInvoker.java:474) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.core.interception.jaxrs.PreMatchContainerRequestCo ntext.filter(PreMatchContainerRequestContext.java:364) ~[resteasy-core-4.7.9.Fin al.jar:4.7.9.Final] at org.jboss.resteasy.core.ResourceMethodInvoker.invokeOnTarget(Resource MethodInvoker.java:476) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.core.ResourceMethodInvoker.invoke(ResourceMethodIn voker.java:434) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.core.ResourceMethodInvoker.invoke(ResourceMethodIn voker.java:408) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.core.ResourceMethodInvoker.invoke(ResourceMethodIn voker.java:69) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.core.SynchronousDispatcher.invoke(SynchronousDispa tcher.java:492) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.core.SynchronousDispatcher.lambda$invoke$4(Synchro nousDispatcher.java:261) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.core.SynchronousDispatcher.lambda$preprocess$0(Syn chronousDispatcher.java:161) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.core.interception.jaxrs.PreMatchContainerRequestCo ntext.filter(PreMatchContainerRequestContext.java:364) ~[resteasy-core-4.7.9.Fin al.jar:4.7.9.Final] at org.jboss.resteasy.core.SynchronousDispatcher.preprocess(SynchronousD ispatcher.java:164) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.core.SynchronousDispatcher.invoke(SynchronousDispa tcher.java:247) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.plugins.server.servlet.ServletContainerDispatcher. service(ServletContainerDispatcher.java:249) ~[resteasy-core-4.7.9.Final.jar:4.7 .9.Final] at org.jboss.resteasy.plugins.server.servlet.HttpServletDispatcher.servi ce(HttpServletDispatcher.java:60) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.plugins.server.servlet.HttpServletDispatcher.servi ce(HttpServletDispatcher.java:55) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at javax.servlet.http.HttpServlet.service(HttpServlet.java:590) ~[jetty- servlet-api-4.0.6.jar:?] at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:764 ) ~[?:?] at org.eclipse.jetty.servlet.ServletHandler$ChainEnd.doFilter(ServletHan dler.java:1630) ~[?:?] at org.gluu.server.filters.AbstractCorsFilter.handleNonCORS(AbstractCors Filter.java:376) ~[oxcore-server-4.5.2.Final.jar:?] at org.gluu.server.filters.AbstractCorsFilter.doFilter(AbstractCorsFilte r.java:142) ~[oxcore-server-4.5.2.Final.jar:?] at org.gluu.oxauth.filter.CorsFilter.doFilter(CorsFilter.java:119) ~[cla sses/:?] at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:202 ) ~[?:?] at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandle r.java:1600) ~[?:?] at org.gluu.oxauth.audit.debug.ServletLoggingFilter.doFilter(ServletLogg ingFilter.java:67) ~[classes/:?] at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:210 ) ~[?:?] at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandle r.java:1600) ~[?:?] at org.eclipse.jetty.websocket.servlet.WebSocketUpgradeFilter.doFilter(W ebSocketUpgradeFilter.java:170) ~[websocket-servlet-10.0.9.jar:10.0.9] at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:202 ) ~[?:?] at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandle r.java:1600) ~[?:?] at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java :506) ~[?:?] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.j ava:131) ~[?:?] at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.jav a:578) ~[?:?] at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper .java:122) ~[?:?] at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandl er.java:223) ~[?:?] at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandl er.java:1571) ~[?:?] at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandl er.java:221) ~[?:?] at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandl er.java:1378) ~[?:?] at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandle r.java:176) ~[?:?] at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java: 463) ~[?:?] at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandle r.java:1544) ~[?:?] at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandle r.java:174) ~[?:?] at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandle r.java:1300) ~[?:?] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.j ava:129) ~[?:?] at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(Cont extHandlerCollection.java:192) ~[?:?] at org.eclipse.jetty.server.handler.HandlerList.handle(HandlerList.java: 51) ~[?:?] at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper .java:122) ~[?:?] at org.eclipse.jetty.server.Server.handle(Server.java:562) ~[?:?] at org.eclipse.jetty.server.HttpChannel.lambda$handle$0(HttpChannel.java :505) ~[?:?] at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:762) ~ [?:?] at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:497) ~[? :?] at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.jav a:282) ~[?:?] at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(Abstra ctConnection.java:319) ~[?:?] at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:100) ~[? :?] at org.eclipse.jetty.io.SelectableChannelEndPoint$1.run(SelectableChanne lEndPoint.java:53) ~[?:?] at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPoo l.java:894) ~[?:?] at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThrea dPool.java:1038) ~[?:?] at java.lang.Thread.run(Thread.java:829) ~[?:?] Caused by: org.gluu.persist.exception.operation.SearchException: Failed to looku p entry by DN: 'tknCde=3a40b95a398ba7299cdec2e992c1e4e78d19c444840bb3531b9727972 49f9418,ou=uma_rpt,ou=tokens,o=gluu' at org.gluu.persist.ldap.operation.impl.LdapOperationServiceImpl.lookupI mpl(LdapOperationServiceImpl.java:604) ~[gluu-orm-ldap-4.5.2.Final.jar:?] at org.gluu.persist.ldap.operation.impl.LdapOperationServiceImpl.lookup( LdapOperationServiceImpl.java:579) ~[gluu-orm-ldap-4.5.2.Final.jar:?] at org.gluu.persist.ldap.impl.LdapEntryManager.find(LdapEntryManager.jav a:390) ~[gluu-orm-ldap-4.5.2.Final.jar:?] ... 78 more 2024-09-11 00:01:51,728 INFO [qtp1199673596-10099] [org.gluu.oxauth.auth.Authen ticator] (Authenticator.java:279) - Authentication success for Client: '1502.45a 76aa1-7ded-40c7-81b7-90f935ce21d3' 2024-09-11 00:05:51,925 INFO [qtp1199673596-16] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 00:10:52,188 INFO [qtp1199673596-18] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 00:15:52,444 INFO [qtp1199673596-14] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 00:20:52,737 INFO [qtp1199673596-17] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 00:25:53,023 INFO [qtp1199673596-20] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 00:30:53,327 INFO [qtp1199673596-18] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 00:33:28,957 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.oxaut h.service.KeyGeneratorTimer] (KeyGeneratorTimer.java:126) - Updating JWKS keys . .. 2024-09-11 00:33:37,920 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.oxaut h.service.KeyGeneratorTimer] (KeyGeneratorTimer.java:139) - Updated JWKS success fully 2024-09-11 00:33:58,927 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.oxaut h.model.config.ConfigurationFactory] (ConfigurationFactory.java:380) - Loading c onfiguration from 'ldap' DB... 2024-09-11 00:33:58,931 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.servi ce.logger.LoggerService] (LoggerService.java:159) - External log configuration: 2024-09-11 00:33:58,931 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.servi ce.logger.LoggerService] (LoggerService.java:178) - Reloading log4j2 configurati on 2024-09-11 00:33:58,944 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.servi ce.logger.LoggerService] (LoggerService.java:142) - Setting layout and loggers l evel to 'TEXT`, `INFO' after configuration update 2024-09-11 00:35:54,286 INFO [qtp1199673596-14] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 00:40:53,884 INFO [qtp1199673596-20] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 00:45:54,201 INFO [qtp1199673596-14] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 00:50:54,480 INFO [qtp1199673596-16] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 00:54:28,929 INFO [ForkJoinPool.commonPool-worker-7] [org.gluu.oxaut h.model.config.ConfigurationFactory] (ConfigurationFactory.java:380) - Loading c onfiguration from 'ldap' DB... 2024-09-11 00:55:55,457 INFO [qtp1199673596-10099] [org.gluu.oxauth.auth.Authen ticator] (Authenticator.java:279) - Authentication success for Client: '1501.91e 63f53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 01:00:55,055 INFO [qtp1199673596-17] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 01:01:55,133 INFO [qtp1199673596-14] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1502.45a76a a1-7ded-40c7-81b7-90f935ce21d3' 2024-09-11 01:05:55,354 INFO [qtp1199673596-16] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 01:10:55,634 INFO [qtp1199673596-19] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 01:15:55,929 INFO [qtp1199673596-18] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 01:20:56,224 INFO [qtp1199673596-16] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 01:25:56,514 INFO [qtp1199673596-21] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 01:30:56,813 INFO [qtp1199673596-10099] [org.gluu.oxauth.auth.Authen ticator] (Authenticator.java:279) - Authentication success for Client: '1501.91e 63f53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 01:34:28,957 INFO [ForkJoinPool.commonPool-worker-7] [org.gluu.oxaut h.service.KeyGeneratorTimer] (KeyGeneratorTimer.java:126) - Updating JWKS keys . .. 2024-09-11 01:34:38,376 INFO [ForkJoinPool.commonPool-worker-7] [org.gluu.oxaut h.service.KeyGeneratorTimer] (KeyGeneratorTimer.java:139) - Updated JWKS success fully 2024-09-11 01:34:58,927 INFO [ForkJoinPool.commonPool-worker-7] [org.gluu.oxaut h.model.config.ConfigurationFactory] (ConfigurationFactory.java:380) - Loading c onfiguration from 'ldap' DB... 2024-09-11 01:35:57,760 INFO [qtp1199673596-18] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 01:40:57,362 INFO [qtp1199673596-16] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 01:45:57,634 INFO [qtp1199673596-10099] [org.gluu.oxauth.auth.Authen ticator] (Authenticator.java:279) - Authentication success for Client: '1501.91e 63f53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 01:50:57,919 INFO [qtp1199673596-18] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 01:55:28,928 INFO [ForkJoinPool.commonPool-worker-7] [org.gluu.oxaut h.model.config.ConfigurationFactory] (ConfigurationFactory.java:380) - Loading c onfiguration from 'ldap' DB... 2024-09-11 01:55:58,905 INFO [qtp1199673596-21] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 02:00:58,492 INFO [qtp1199673596-16] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 02:01:58,572 INFO [qtp1199673596-20] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1502.45a76a a1-7ded-40c7-81b7-90f935ce21d3' 2024-09-11 02:05:58,772 INFO [qtp1199673596-18] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 02:10:59,052 INFO [qtp1199673596-14] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 02:15:59,333 INFO [qtp1199673596-10099] [org.gluu.oxauth.auth.Authen ticator] (Authenticator.java:279) - Authentication success for Client: '1501.91e 63f53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 02:20:59,623 INFO [qtp1199673596-13] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 02:25:59,916 INFO [qtp1199673596-18] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 02:31:00,163 INFO [qtp1199673596-17] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 02:35:28,957 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.oxaut h.service.KeyGeneratorTimer] (KeyGeneratorTimer.java:126) - Updating JWKS keys . .. 2024-09-11 02:35:38,959 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.oxaut h.service.KeyGeneratorTimer] (KeyGeneratorTimer.java:139) - Updated JWKS success fully 2024-09-11 02:35:58,927 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.oxaut h.model.config.ConfigurationFactory] (ConfigurationFactory.java:380) - Loading c onfiguration from 'ldap' DB... 2024-09-11 02:36:01,115 INFO [qtp1199673596-13] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 02:41:00,717 INFO [qtp1199673596-20] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 02:46:00,972 INFO [qtp1199673596-21] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 02:51:01,256 INFO [qtp1199673596-13] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 02:56:01,551 INFO [qtp1199673596-20] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 02:56:28,928 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.oxaut h.model.config.ConfigurationFactory] (ConfigurationFactory.java:380) - Loading c onfiguration from 'ldap' DB... 2024-09-11 03:01:02,540 INFO [qtp1199673596-21] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 03:02:01,893 ERROR [qtp1199673596-10099] [org.gluu.oxauth.uma.service .UmaRptService] (UmaRptService.java:121) - Failed to find entry: tknCde=82695f9d 53f8aa066aaa45e952019acd227e93cef93621a8b34c21dfe6a31150,ou=uma_rpt,ou=tokens,o= gluu org.gluu.persist.exception.EntryPersistenceException: Failed to find entry: tknC de=82695f9d53f8aa066aaa45e952019acd227e93cef93621a8b34c21dfe6a31150,ou=uma_rpt,o u=tokens,o=gluu at org.gluu.persist.ldap.impl.LdapEntryManager.find(LdapEntryManager.jav a:395) ~[gluu-orm-ldap-4.5.2.Final.jar:?] at org.gluu.persist.impl.BaseEntryManager.find(BaseEntryManager.java:719 ) ~[gluu-orm-core-4.5.2.Final.jar:?] at org.gluu.persist.impl.BaseEntryManager.find(BaseEntryManager.java:598 ) ~[gluu-orm-core-4.5.2.Final.jar:?] at org.gluu.persist.impl.BaseEntryManager.find(BaseEntryManager.java:585 ) ~[gluu-orm-core-4.5.2.Final.jar:?] at jdk.internal.reflect.GeneratedMethodAccessor69.invoke(Unknown Source) ~[?:?] at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMe thodAccessorImpl.java:43) ~[?:?] at java.lang.reflect.Method.invoke(Method.java:566) ~[?:?] at org.jboss.weld.bean.proxy.AbstractBeanInstance.invoke(AbstractBeanIns tance.java:38) ~[weld-core-impl-3.1.9.Final.jar:3.1.9.Final] at org.jboss.weld.bean.proxy.ProxyMethodHandler.invoke(ProxyMethodHandle r.java:106) ~[weld-core-impl-3.1.9.Final.jar:3.1.9.Final] at org.gluu.persist.PersistenceEntryManager$EntityManager$1948487569$Pro xy$_$$_WeldClientProxy.find(Unknown Source) ~[gluu-orm-core-4.5.2.Final.jar:?] at org.gluu.oxauth.uma.service.UmaRptService.getRPTByCode(UmaRptService. java:114) ~[classes/:?] at org.gluu.oxauth.uma.service.UmaRptService$Proxy$_$$_WeldClientProxy.g etRPTByCode(Unknown Source) ~[classes/:?] at org.gluu.oxauth.uma.ws.rs.UmaRptIntrospectionWS.introspect(UmaRptIntr ospectionWS.java:89) ~[classes/:?] at org.gluu.oxauth.uma.ws.rs.UmaRptIntrospectionWS.introspectPost(UmaRpt IntrospectionWS.java:82) ~[classes/:?] at org.gluu.oxauth.uma.ws.rs.UmaRptIntrospectionWS$Proxy$_$$_WeldClientP roxy.introspectPost(Unknown Source) ~[classes/:?] at jdk.internal.reflect.GeneratedMethodAccessor198.invoke(Unknown Source ) ~[?:?] at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMe thodAccessorImpl.java:43) ~[?:?] at java.lang.reflect.Method.invoke(Method.java:566) ~[?:?] at org.jboss.resteasy.core.MethodInjectorImpl.invoke(MethodInjectorImpl. java:170) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.core.MethodInjectorImpl.invoke(MethodInjectorImpl. java:130) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.core.ResourceMethodInvoker.internalInvokeOnTarget( ResourceMethodInvoker.java:660) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.core.ResourceMethodInvoker.invokeOnTargetAfterFilt er(ResourceMethodInvoker.java:524) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.core.ResourceMethodInvoker.lambda$invokeOnTarget$2 (ResourceMethodInvoker.java:474) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.core.interception.jaxrs.PreMatchContainerRequestCo ntext.filter(PreMatchContainerRequestContext.java:364) ~[resteasy-core-4.7.9.Fin al.jar:4.7.9.Final] at org.jboss.resteasy.core.ResourceMethodInvoker.invokeOnTarget(Resource MethodInvoker.java:476) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.core.ResourceMethodInvoker.invoke(ResourceMethodIn voker.java:434) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.core.ResourceMethodInvoker.invoke(ResourceMethodIn voker.java:408) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.core.ResourceMethodInvoker.invoke(ResourceMethodIn voker.java:69) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.core.SynchronousDispatcher.invoke(SynchronousDispa tcher.java:492) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.core.SynchronousDispatcher.lambda$invoke$4(Synchro nousDispatcher.java:261) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.core.SynchronousDispatcher.lambda$preprocess$0(Syn chronousDispatcher.java:161) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.core.interception.jaxrs.PreMatchContainerRequestCo ntext.filter(PreMatchContainerRequestContext.java:364) ~[resteasy-core-4.7.9.Fin al.jar:4.7.9.Final] at org.jboss.resteasy.core.SynchronousDispatcher.preprocess(SynchronousD ispatcher.java:164) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.core.SynchronousDispatcher.invoke(SynchronousDispa tcher.java:247) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.plugins.server.servlet.ServletContainerDispatcher. service(ServletContainerDispatcher.java:249) ~[resteasy-core-4.7.9.Final.jar:4.7 .9.Final] at org.jboss.resteasy.plugins.server.servlet.HttpServletDispatcher.servi ce(HttpServletDispatcher.java:60) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at org.jboss.resteasy.plugins.server.servlet.HttpServletDispatcher.servi ce(HttpServletDispatcher.java:55) ~[resteasy-core-4.7.9.Final.jar:4.7.9.Final] at javax.servlet.http.HttpServlet.service(HttpServlet.java:590) ~[jetty- servlet-api-4.0.6.jar:?] at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:764 ) ~[?:?] at org.eclipse.jetty.servlet.ServletHandler$ChainEnd.doFilter(ServletHan dler.java:1630) ~[?:?] at org.gluu.server.filters.AbstractCorsFilter.handleNonCORS(AbstractCors Filter.java:376) ~[oxcore-server-4.5.2.Final.jar:?] at org.gluu.server.filters.AbstractCorsFilter.doFilter(AbstractCorsFilte r.java:142) ~[oxcore-server-4.5.2.Final.jar:?] at org.gluu.oxauth.filter.CorsFilter.doFilter(CorsFilter.java:119) ~[cla sses/:?] at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:202 ) ~[?:?] at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandle r.java:1600) ~[?:?] at org.gluu.oxauth.audit.debug.ServletLoggingFilter.doFilter(ServletLogg ingFilter.java:67) ~[classes/:?] at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:210 ) ~[?:?] at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandle r.java:1600) ~[?:?] at org.eclipse.jetty.websocket.servlet.WebSocketUpgradeFilter.doFilter(W ebSocketUpgradeFilter.java:170) ~[websocket-servlet-10.0.9.jar:10.0.9] at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:202 ) ~[?:?] at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandle r.java:1600) ~[?:?] at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java :506) ~[?:?] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.j ava:131) ~[?:?] at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.jav a:578) ~[?:?] at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper .java:122) ~[?:?] at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandl er.java:223) ~[?:?] at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandl er.java:1571) ~[?:?] at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandl er.java:221) ~[?:?] at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandl er.java:1378) ~[?:?] at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandle r.java:176) ~[?:?] at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java: 463) ~[?:?] at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandle r.java:1544) ~[?:?] at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandle r.java:174) ~[?:?] at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandle r.java:1300) ~[?:?] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.j ava:129) ~[?:?] at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(Cont extHandlerCollection.java:192) ~[?:?] at org.eclipse.jetty.server.handler.HandlerList.handle(HandlerList.java: 51) ~[?:?] at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper .java:122) ~[?:?] at org.eclipse.jetty.server.Server.handle(Server.java:562) ~[?:?] at org.eclipse.jetty.server.HttpChannel.lambda$handle$0(HttpChannel.java :505) ~[?:?] at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:762) ~ [?:?] at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:497) ~[? :?] at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.jav a:282) ~[?:?] at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(Abstra ctConnection.java:319) ~[?:?] at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:100) ~[? :?] at org.eclipse.jetty.io.SelectableChannelEndPoint$1.run(SelectableChanne lEndPoint.java:53) ~[?:?] at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.runT ask(AdaptiveExecutionStrategy.java:412) ~[?:?] at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.cons umeTask(AdaptiveExecutionStrategy.java:381) ~[?:?] at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.tryP roduce(AdaptiveExecutionStrategy.java:268) ~[?:?] at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.lamb da$new$0(AdaptiveExecutionStrategy.java:138) ~[?:?] at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.r un(ReservedThreadExecutor.java:407) ~[?:?] at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPoo l.java:894) ~[?:?] at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThrea dPool.java:1038) ~[?:?] at java.lang.Thread.run(Thread.java:829) ~[?:?] Caused by: org.gluu.persist.exception.operation.SearchException: Failed to looku p entry by DN: 'tknCde=82695f9d53f8aa066aaa45e952019acd227e93cef93621a8b34c21dfe 6a31150,ou=uma_rpt,ou=tokens,o=gluu' at org.gluu.persist.ldap.operation.impl.LdapOperationServiceImpl.lookupI mpl(LdapOperationServiceImpl.java:604) ~[gluu-orm-ldap-4.5.2.Final.jar:?] at org.gluu.persist.ldap.operation.impl.LdapOperationServiceImpl.lookup( LdapOperationServiceImpl.java:579) ~[gluu-orm-ldap-4.5.2.Final.jar:?] at org.gluu.persist.ldap.impl.LdapEntryManager.find(LdapEntryManager.jav a:390) ~[gluu-orm-ldap-4.5.2.Final.jar:?] ... 83 more 2024-09-11 03:02:01,955 INFO [qtp1199673596-10099] [org.gluu.oxauth.auth.Authen ticator] (Authenticator.java:279) - Authentication success for Client: '1502.45a 76aa1-7ded-40c7-81b7-90f935ce21d3' 2024-09-11 03:06:02,138 INFO [qtp1199673596-20] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 03:11:02,429 INFO [qtp1199673596-17] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 03:16:02,695 INFO [qtp1199673596-19] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 03:21:02,988 INFO [qtp1199673596-18] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 03:26:03,282 INFO [qtp1199673596-20] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 03:31:03,578 INFO [qtp1199673596-17] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 03:36:03,873 INFO [qtp1199673596-19] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 03:36:28,957 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.oxaut h.service.KeyGeneratorTimer] (KeyGeneratorTimer.java:126) - Updating JWKS keys . .. 2024-09-11 03:36:38,362 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.oxaut h.service.KeyGeneratorTimer] (KeyGeneratorTimer.java:139) - Updated JWKS success fully 2024-09-11 03:36:58,928 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.oxaut h.model.config.ConfigurationFactory] (ConfigurationFactory.java:380) - Loading c onfiguration from 'ldap' DB... 2024-09-11 03:41:04,855 INFO [qtp1199673596-18] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 03:46:04,445 INFO [qtp1199673596-14] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 03:51:04,726 INFO [qtp1199673596-19] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 03:56:05,011 INFO [qtp1199673596-18] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 03:57:28,928 INFO [ForkJoinPool.commonPool-worker-7] [org.gluu.oxaut h.model.config.ConfigurationFactory] (ConfigurationFactory.java:380) - Loading c onfiguration from 'ldap' DB... 2024-09-11 04:01:05,997 INFO [qtp1199673596-14] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 04:02:05,381 INFO [qtp1199673596-21] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1502.45a76a a1-7ded-40c7-81b7-90f935ce21d3' 2024-09-11 04:06:05,563 INFO [qtp1199673596-13] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 04:11:05,837 INFO [qtp1199673596-16] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 04:16:06,113 INFO [qtp1199673596-18] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 04:21:06,382 INFO [qtp1199673596-20] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 04:26:06,670 INFO [qtp1199673596-10099] [org.gluu.oxauth.auth.Authen ticator] (Authenticator.java:279) - Authentication success for Client: '1501.91e 63f53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 04:31:06,950 INFO [qtp1199673596-13] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 04:36:07,246 INFO [qtp1199673596-18] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 04:37:28,956 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.oxaut h.service.KeyGeneratorTimer] (KeyGeneratorTimer.java:126) - Updating JWKS keys . .. 2024-09-11 04:37:38,890 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.oxaut h.service.KeyGeneratorTimer] (KeyGeneratorTimer.java:139) - Updated JWKS success fully 2024-09-11 04:37:58,927 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.oxaut h.model.config.ConfigurationFactory] (ConfigurationFactory.java:380) - Loading c onfiguration from 'ldap' DB... 2024-09-11 04:37:58,931 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.servi ce.logger.LoggerService] (LoggerService.java:159) - External log configuration: 2024-09-11 04:37:58,931 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.servi ce.logger.LoggerService] (LoggerService.java:178) - Reloading log4j2 configurati on 2024-09-11 04:37:58,943 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.servi ce.logger.LoggerService] (LoggerService.java:142) - Setting layout and loggers l evel to 'TEXT`, `INFO' after configuration update 2024-09-11 04:41:08,227 INFO [qtp1199673596-20] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 04:46:07,833 INFO [qtp1199673596-13] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 04:51:08,122 INFO [qtp1199673596-20] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 04:56:08,390 INFO [qtp1199673596-10099] [org.gluu.oxauth.auth.Authen ticator] (Authenticator.java:279) - Authentication success for Client: '1501.91e 63f53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 04:58:28,928 INFO [ForkJoinPool.commonPool-worker-7] [org.gluu.oxaut h.model.config.ConfigurationFactory] (ConfigurationFactory.java:380) - Loading c onfiguration from 'ldap' DB... 2024-09-11 04:58:28,933 INFO [ForkJoinPool.commonPool-worker-7] [org.gluu.servi ce.logger.LoggerService] (LoggerService.java:159) - External log configuration: 2024-09-11 04:58:28,933 INFO [ForkJoinPool.commonPool-worker-7] [org.gluu.servi ce.logger.LoggerService] (LoggerService.java:178) - Reloading log4j2 configurati on 2024-09-11 04:58:28,983 INFO [ForkJoinPool.commonPool-worker-7] [org.gluu.servi ce.logger.LoggerService] (LoggerService.java:142) - Setting layout and loggers l evel to 'TEXT`, `INFO' after configuration update 2024-09-11 05:01:09,362 INFO [qtp1199673596-13] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 05:02:08,744 INFO [qtp1199673596-18] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1502.45a76a a1-7ded-40c7-81b7-90f935ce21d3' 2024-09-11 05:06:08,963 INFO [qtp1199673596-20] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 05:11:09,248 INFO [qtp1199673596-17] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 05:16:09,511 INFO [qtp1199673596-10099] [org.gluu.oxauth.auth.Authen ticator] (Authenticator.java:279) - Authentication success for Client: '1501.91e 63f53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 05:21:09,793 INFO [qtp1199673596-13] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 05:26:10,073 INFO [qtp1199673596-19] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 05:31:10,345 INFO [qtp1199673596-10099] [org.gluu.oxauth.auth.Authen ticator] (Authenticator.java:279) - Authentication success for Client: '1501.91e 63f53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 05:36:10,622 INFO [qtp1199673596-17] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 05:38:28,957 INFO [ForkJoinPool.commonPool-worker-7] [org.gluu.oxaut h.service.KeyGeneratorTimer] (KeyGeneratorTimer.java:126) - Updating JWKS keys . .. 2024-09-11 05:38:38,385 INFO [ForkJoinPool.commonPool-worker-7] [org.gluu.oxaut h.service.KeyGeneratorTimer] (KeyGeneratorTimer.java:139) - Updated JWKS success fully 2024-09-11 05:38:58,928 INFO [ForkJoinPool.commonPool-worker-7] [org.gluu.oxaut h.model.config.ConfigurationFactory] (ConfigurationFactory.java:380) - Loading c onfiguration from 'ldap' DB... 2024-09-11 05:41:11,595 INFO [qtp1199673596-18] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1501.91e63f 53-27f8-46a7-8c88-010cf82b53c8' 2024-09-11 05:44:02,983 INFO [qtp1199673596-13] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:226) - Authentication failed for 'admin@ide-dev.blueit .be' 2024-09-11 05:44:05,300 INFO [qtp1199673596-20] [org.gluu.oxauth.service.Authen ticationService] (AuthenticationService.java:713) - Attempting to redirect user: SessionUser: 4e134e54-3127-4889-b672-fdc1f940772f 2024-09-11 05:44:05,300 INFO [qtp1199673596-20] [org.gluu.oxauth.service.Authen ticationService] (AuthenticationService.java:721) - Attempting to redirect user: User: BaseEntry [dn=inum=fdfc80d0-7e73-41fd-b93a-ac8dd6b93f7e,ou=people,o=gluu] 2024-09-11 05:44:05,301 INFO [qtp1199673596-20] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:443) - Authentication success for User: 'admin@ide-dev .blueit.be' 2024-09-11 05:44:05,425 INFO [qtp1199673596-19] [org.gluu.oxauth.auth.Authentic ator] (Authenticator.java:279) - Authentication success for Client: '1001.4f053a 7e-7699-411d-8934-4dd02a012a8f' ``` the following log is from the **oxtrust.log** ``` 2024-09-11 05:56:14,178 DEBUG [oxTrustScheduler_Worker-2] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started 2024-09-11 05:56:14,178 TRACE [oxTrustScheduler_Worker-2] [org.gluu.service.timer.TimerJob] (TimerJob.java:35) - Fire timer event [org.gluu.service.cdi.event.LoggerUpdateEvent] with qualifiers [@org.gluu.service.cdi.event.Scheduled()] from instance 1105646191 2024-09-11 05:56:14,178 DEBUG [oxTrustScheduler_Worker-2] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended 2024-09-11 05:56:14,196 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.service.logger.LoggerService] (LoggerService.java:205) - Updated log level of '124' loggers to TRACE 2024-09-11 05:56:29,172 DEBUG [oxTrustScheduler_Worker-1] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started 2024-09-11 05:56:29,173 TRACE [oxTrustScheduler_Worker-1] [org.gluu.service.timer.TimerJob] (TimerJob.java:35) - Fire timer event [org.gluu.service.cdi.event.ConfigurationEvent] with qualifiers [@org.gluu.service.cdi.event.Scheduled()] from instance 1622195671 2024-09-11 05:56:29,173 DEBUG [oxTrustScheduler_Worker-1] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended 2024-09-11 05:56:29,178 DEBUG [oxTrustScheduler_Worker-4] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started 2024-09-11 05:56:29,178 TRACE [oxTrustScheduler_Worker-4] [org.gluu.service.timer.TimerJob] (TimerJob.java:35) - Fire timer event [org.gluu.service.cdi.event.LoggerUpdateEvent] with qualifiers [@org.gluu.service.cdi.event.Scheduled()] from instance 1543240129 2024-09-11 05:56:29,178 DEBUG [oxTrustScheduler_Worker-4] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended 2024-09-11 05:56:29,182 DEBUG [oxTrustScheduler_Worker-5] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started 2024-09-11 05:56:29,182 TRACE [oxTrustScheduler_Worker-5] [org.gluu.service.timer.TimerJob] (TimerJob.java:35) - Fire timer event [org.gluu.service.cdi.event.LdapStatusEvent] with qualifiers [@org.gluu.service.cdi.event.Scheduled()] from instance 1743716148 2024-09-11 05:56:29,182 DEBUG [oxTrustScheduler_Worker-5] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended 2024-09-11 05:56:29,195 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.service.logger.LoggerService] (LoggerService.java:205) - Updated log level of '124' loggers to TRACE 2024-09-11 05:56:29,206 DEBUG [oxTrustScheduler_Worker-3] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started 2024-09-11 05:56:29,206 TRACE [oxTrustScheduler_Worker-3] [org.gluu.service.timer.TimerJob] (TimerJob.java:35) - Fire timer event [org.gluu.oxtrust.service.cdi.event.CacheRefreshEvent] with qualifiers [@org.gluu.service.cdi.event.Scheduled()] from instance 1271816134 2024-09-11 05:56:29,206 DEBUG [oxTrustScheduler_Worker-3] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended 2024-09-11 05:56:29,216 DEBUG [oxTrustScheduler_Worker-2] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started 2024-09-11 05:56:29,216 TRACE [oxTrustScheduler_Worker-2] [org.gluu.service.timer.TimerJob] (TimerJob.java:35) - Fire timer event [org.gluu.service.cdi.event.CleanerEvent] with qualifiers [@org.gluu.service.cdi.event.Scheduled()] from instance 865002835 2024-09-11 05:56:29,216 DEBUG [oxTrustScheduler_Worker-2] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended 2024-09-11 05:56:29,217 TRACE [ForkJoinPool.commonPool-worker-5] [org.gluu.oxtrust.service.CleanerTimer] (CleanerTimer.java:112) - Starting conditions aren't reached 2024-09-11 05:56:29,245 DEBUG [oxTrustScheduler_Worker-1] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started 2024-09-11 05:56:29,245 TRACE [oxTrustScheduler_Worker-1] [org.gluu.service.timer.TimerJob] (TimerJob.java:35) - Fire timer event [org.gluu.service.cdi.event.UpdateScriptEvent] with qualifiers [@org.gluu.service.cdi.event.Scheduled()] from instance 2493662 2024-09-11 05:56:29,248 DEBUG [oxTrustScheduler_Worker-1] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended 2024-09-11 05:56:44,178 DEBUG [oxTrustScheduler_Worker-4] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started 2024-09-11 05:56:44,178 TRACE [oxTrustScheduler_Worker-4] [org.gluu.service.timer.TimerJob] (TimerJob.java:35) - Fire timer event [org.gluu.service.cdi.event.LoggerUpdateEvent] with qualifiers [@org.gluu.service.cdi.event.Scheduled()] from instance 1486350303 2024-09-11 05:56:44,178 DEBUG [oxTrustScheduler_Worker-4] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended 2024-09-11 05:56:44,198 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.service.logger.LoggerService] (LoggerService.java:205) - Updated log level of '124' loggers to TRACE 2024-09-11 05:56:59,172 DEBUG [oxTrustScheduler_Worker-5] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started 2024-09-11 05:56:59,172 TRACE [oxTrustScheduler_Worker-5] [org.gluu.service.timer.TimerJob] (TimerJob.java:35) - Fire timer event [org.gluu.service.cdi.event.ConfigurationEvent] with qualifiers [@org.gluu.service.cdi.event.Scheduled()] from instance 2142751045 2024-09-11 05:56:59,173 DEBUG [oxTrustScheduler_Worker-5] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended 2024-09-11 05:56:59,177 DEBUG [oxTrustScheduler_Worker-3] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started 2024-09-11 05:56:59,177 TRACE [oxTrustScheduler_Worker-3] [org.gluu.service.timer.TimerJob] (TimerJob.java:35) - Fire timer event [org.gluu.service.cdi.event.LoggerUpdateEvent] with qualifiers [@org.gluu.service.cdi.event.Scheduled()] from instance 1228058880 2024-09-11 05:56:59,178 DEBUG [oxTrustScheduler_Worker-3] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended 2024-09-11 05:56:59,182 DEBUG [oxTrustScheduler_Worker-2] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started 2024-09-11 05:56:59,183 TRACE [oxTrustScheduler_Worker-2] [org.gluu.service.timer.TimerJob] (TimerJob.java:35) - Fire timer event [org.gluu.oxtrust.service.cdi.event.MetadataValidationEvent] with qualifiers [@org.gluu.service.cdi.event.Scheduled()] from instance 1425882370 2024-09-11 05:56:59,183 DEBUG [oxTrustScheduler_Worker-2] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended 2024-09-11 05:56:59,183 DEBUG [ForkJoinPool.commonPool-worker-3] [org.gluu.oxtrust.service.MetadataValidationTimer] (MetadataValidationTimer.java:114) - Starting metadata validation 2024-09-11 05:56:59,183 TRACE [ForkJoinPool.commonPool-worker-3] [org.gluu.oxtrust.service.MetadataValidationTimer] (MetadataValidationTimer.java:182) - Starting metadata validation process. 2024-09-11 05:56:59,183 DEBUG [ForkJoinPool.commonPool-worker-3] [org.gluu.oxtrust.service.MetadataValidationTimer] (MetadataValidationTimer.java:117) - Metadata validation finished with result: 'false' 2024-09-11 05:56:59,186 DEBUG [oxTrustScheduler_Worker-1] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started 2024-09-11 05:56:59,186 TRACE [oxTrustScheduler_Worker-1] [org.gluu.service.timer.TimerJob] (TimerJob.java:35) - Fire timer event [org.gluu.oxtrust.service.cdi.event.EntityIdMonitoringEvent] with qualifiers [@org.gluu.service.cdi.event.Scheduled()] from instance 950301781 2024-09-11 05:56:59,186 DEBUG [oxTrustScheduler_Worker-1] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended 2024-09-11 05:56:59,186 TRACE [ForkJoinPool.commonPool-worker-3] [org.gluu.oxtrust.service.EntityIDMonitoringService] (EntityIDMonitoringService.java:111) - Starting entityId monitoring process. 2024-09-11 05:56:59,186 TRACE [ForkJoinPool.commonPool-worker-3] [org.gluu.oxtrust.service.EntityIDMonitoringService] (EntityIDMonitoringService.java:112) - EVENT_METADATA_ENTITY_ID_UPDATE Starting 2024-09-11 05:56:59,195 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.service.logger.LoggerService] (LoggerService.java:205) - Updated log level of '124' loggers to TRACE 2024-09-11 05:56:59,245 DEBUG [oxTrustScheduler_Worker-4] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started 2024-09-11 05:56:59,245 TRACE [oxTrustScheduler_Worker-4] [org.gluu.service.timer.TimerJob] (TimerJob.java:35) - Fire timer event [org.gluu.service.cdi.event.UpdateScriptEvent] with qualifiers [@org.gluu.service.cdi.event.Scheduled()] from instance 1157606227 2024-09-11 05:56:59,248 DEBUG [oxTrustScheduler_Worker-4] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended ``` Best regards Mursel

By Mursel Koseer user 25 Sep 2024 at 8:26 a.m. CDT

Mursel Koseer gravatar
Hi Shakil, We're still encountering issues with the key rotation. I’ve identified some new errors that are causing the JWKS to be empty. Additionally, it appears that restarting the oxauth service affects the timing of key generation. Below are the relevant logs from the oxauth.log: ``` 2024-09-24 00:23:15,118 INFO [ForkJoinPool.commonPool-worker-11] [org.gluu.oxauth.service.KeyGeneratorTimer] (KeyGeneratorTimer.java:126) - Updating JWKS keys ... 2024-09-24 00:23:15,128 DEBUG [oxAuthScheduler_Worker-5] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started 2024-09-24 00:23:15,128 TRACE [oxAuthScheduler_Worker-5] [org.gluu.service.timer.TimerJob] (TimerJob.java:35) - Fire timer event [org.gluu.oxauth.service.cdi.event.AuthConfigurationEvent] with qualifiers [@org.gluu.service.cdi.event.Scheduled()] from instance 1809934916 2024-09-24 00:23:15,128 DEBUG [oxAuthScheduler_Worker-5] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended 2024-09-24 00:23:15,128 TRACE [ForkJoinPool.commonPool-worker-9] [org.gluu.oxauth.service.AppInitializer] (AppInitializer.java:303) - Attempting to use persistenceEntryManager: org.gluu.persist.ldap.operation.impl.LdapOperationServiceImpl@3f2ae973 2024-09-24 00:23:15,349 ERROR [ForkJoinPool.commonPool-worker-11] [org.gluu.oxauth.model.crypto.AbstractCryptoProvider] (AbstractCryptoProvider.java:121) - Algorithm: RS256Uninitialized keystore java.security.KeyStoreException: Uninitialized keystore at java.security.KeyStore.setKeyEntry(KeyStore.java:1166) ~[?:?] at org.gluu.oxauth.model.crypto.OxAuthCryptoProvider.generateKey(OxAuthCryptoProvider.java:220) ~[oxauth-model-4.5.2.Final.jar:?] at org.gluu.oxauth.model.crypto.OxAuthCryptoProvider.generateKey(OxAuthCryptoProvider.java:183) ~[oxauth-model-4.5.2.Final.jar:?] at org.gluu.oxauth.model.crypto.AbstractCryptoProvider$Proxy$_$$_WeldClientProxy.generateKey(Unknown Source) ~[oxauth-model-4.5.2.Final.jar:?] at org.gluu.oxauth.model.crypto.AbstractCryptoProvider.generateJwks(AbstractCryptoProvider.java:119) ~[oxauth-model-4.5.2.Final.jar:?] at org.gluu.oxauth.service.KeyGeneratorTimer.updateKeys(KeyGeneratorTimer.java:145) ~[classes/:?] at org.gluu.oxauth.service.KeyGeneratorTimer.updateKeysImpl(KeyGeneratorTimer.java:131) ~[classes/:?] at org.gluu.oxauth.service.KeyGeneratorTimer.updateKeys(KeyGeneratorTimer.java:108) ~[classes/:?] at org.gluu.oxauth.service.KeyGeneratorTimer.process(KeyGeneratorTimer.java:95) ~[classes/:?] at org.gluu.oxauth.service.KeyGeneratorTimer$Proxy$_$$_WeldSubclass.process$$super(Unknown Source) ~[classes/:?] at jdk.internal.reflect.GeneratedMethodAccessor235.invoke(Unknown Source) ~[?:?] at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?] at java.lang.reflect.Method.invoke(Method.java:566) ~[?:?] at org.jboss.weld.interceptor.proxy.TerminalAroundInvokeInvocationContext.proceedInternal(TerminalAroundInvokeInvocationContext.java:51) ~[weld-core-impl-3.1.9.Final.jar:3.1.9.Final] at org.jboss.weld.interceptor.proxy.AroundInvokeInvocationContext.proceed(AroundInvokeInvocationContext.java:78) ~[weld-core-impl-3.1.9.Final.jar:3.1.9.Final] at org.gluu.service.cdi.async.AsynchronousInterceptor$1.get(AsynchronousInterceptor.java:36) ~[oxcore-service-4.5.2.Final.jar:?] at java.util.concurrent.CompletableFuture$AsyncSupply.run(CompletableFuture.java:1700) ~[?:?] at java.util.concurrent.CompletableFuture$AsyncSupply.exec(CompletableFuture.java:1692) ~[?:?] at java.util.concurrent.ForkJoinTask.doExec(ForkJoinTask.java:290) ~[?:?] at java.util.concurrent.ForkJoinPool$WorkQueue.topLevelExec(ForkJoinPool.java:1020) ~[?:?] at java.util.concurrent.ForkJoinPool.scan(ForkJoinPool.java:1656) ~[?:?] at java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1594) ~[?:?] at java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:183) ~[?:?] 2024-09-24 00:23:15,523 ERROR [ForkJoinPool.commonPool-worker-11] [org.gluu.oxauth.model.crypto.AbstractCryptoProvider] (AbstractCryptoProvider.java:121) - Algorithm: RS384Uninitialized keystore java.security.KeyStoreException: Uninitialized keystore at java.security.KeyStore.setKeyEntry(KeyStore.java:1166) ~[?:?] at org.gluu.oxauth.model.crypto.OxAuthCryptoProvider.generateKey(OxAuthCryptoProvider.java:220) ~[oxauth-model-4.5.2.Final.jar:?] at org.gluu.oxauth.model.crypto.OxAuthCryptoProvider.generateKey(OxAuthCryptoProvider.java:183) ~[oxauth-model-4.5.2.Final.jar:?] at org.gluu.oxauth.model.crypto.AbstractCryptoProvider$Proxy$_$$_WeldClientProxy.generateKey(Unknown Source) ~[oxauth-model-4.5.2.Final.jar:?] at org.gluu.oxauth.model.crypto.AbstractCryptoProvider.generateJwks(AbstractCryptoProvider.java:119) ~[oxauth-model-4.5.2.Final.jar:?] at org.gluu.oxauth.service.KeyGeneratorTimer.updateKeys(KeyGeneratorTimer.java:145) ~[classes/:?] at org.gluu.oxauth.service.KeyGeneratorTimer.updateKeysImpl(KeyGeneratorTimer.java:131) ~[classes/:?] at org.gluu.oxauth.service.KeyGeneratorTimer.updateKeys(KeyGeneratorTimer.java:108) ~[classes/:?] at org.gluu.oxauth.service.KeyGeneratorTimer.process(KeyGeneratorTimer.java:95) ~[classes/:?] at org.gluu.oxauth.service.KeyGeneratorTimer$Proxy$_$$_WeldSubclass.process$$super(Unknown Source) ~[classes/:?] at jdk.internal.reflect.GeneratedMethodAccessor235.invoke(Unknown Source) ~[?:?] at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?] at java.lang.reflect.Method.invoke(Method.java:566) ~[?:?] at org.jboss.weld.interceptor.proxy.TerminalAroundInvokeInvocationContext.proceedInternal(TerminalAroundInvokeInvocationContext.java:51) ~[weld-core-impl-3.1.9.Final.jar:3.1.9.Final] at org.jboss.weld.interceptor.proxy.AroundInvokeInvocationContext.proceed(AroundInvokeInvocationContext.java:78) ~[weld-core-impl-3.1.9.Final.jar:3.1.9.Final] at org.gluu.service.cdi.async.AsynchronousInterceptor$1.get(AsynchronousInterceptor.java:36) ~[oxcore-service-4.5.2.Final.jar:?] at java.util.concurrent.CompletableFuture$AsyncSupply.run(CompletableFuture.java:1700) ~[?:?] at java.util.concurrent.CompletableFuture$AsyncSupply.exec(CompletableFuture.java:1692) ~[?:?] at java.util.concurrent.ForkJoinTask.doExec(ForkJoinTask.java:290) ~[?:?] at java.util.concurrent.ForkJoinPool$WorkQueue.topLevelExec(ForkJoinPool.java:1020) ~[?:?] at java.util.concurrent.ForkJoinPool.scan(ForkJoinPool.java:1656) ~[?:?] at java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1594) ~[?:?] at java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:183) ~[?:?] 2024-09-24 00:23:15,588 ERROR [ForkJoinPool.commonPool-worker-11] [org.gluu.oxauth.model.crypto.AbstractCryptoProvider] (AbstractCryptoProvider.java:121) - Algorithm: RS512Uninitialized keystore java.security.KeyStoreException: Uninitialized keystore at java.security.KeyStore.setKeyEntry(KeyStore.java:1166) ~[?:?] at org.gluu.oxauth.model.crypto.OxAuthCryptoProvider.generateKey(OxAuthCryptoProvider.java:220) ~[oxauth-model-4.5.2.Final.jar:?] at org.gluu.oxauth.model.crypto.OxAuthCryptoProvider.generateKey(OxAuthCryptoProvider.java:183) ~[oxauth-model-4.5.2.Final.jar:?] at org.gluu.oxauth.model.crypto.AbstractCryptoProvider$Proxy$_$$_WeldClientProxy.generateKey(Unknown Source) ~[oxauth-model-4.5.2.Final.jar:?] at org.gluu.oxauth.model.crypto.AbstractCryptoProvider.generateJwks(AbstractCryptoProvider.java:119) ~[oxauth-model-4.5.2.Final.jar:?] at org.gluu.oxauth.service.KeyGeneratorTimer.updateKeys(KeyGeneratorTimer.java:145) ~[classes/:?] at org.gluu.oxauth.service.KeyGeneratorTimer.updateKeysImpl(KeyGeneratorTimer.java:131) ~[classes/:?] at org.gluu.oxauth.service.KeyGeneratorTimer.updateKeys(KeyGeneratorTimer.java:108) ~[classes/:?] at org.gluu.oxauth.service.KeyGeneratorTimer.process(KeyGeneratorTimer.java:95) ~[classes/:?] at org.gluu.oxauth.service.KeyGeneratorTimer$Proxy$_$$_WeldSubclass.process$$super(Unknown Source) ~[classes/:?] at jdk.internal.reflect.GeneratedMethodAccessor235.invoke(Unknown Source) ~[?:?] at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?] at java.lang.reflect.Method.invoke(Method.java:566) ~[?:?] at org.jboss.weld.interceptor.proxy.TerminalAroundInvokeInvocationContext.proceedInternal(TerminalAroundInvokeInvocationContext.java:51) ~[weld-core-impl-3.1.9.Final.jar:3.1.9.Final] at org.jboss.weld.interceptor.proxy.AroundInvokeInvocationContext.proceed(AroundInvokeInvocationContext.java:78) ~[weld-core-impl-3.1.9.Final.jar:3.1.9.Final] at org.gluu.service.cdi.async.AsynchronousInterceptor$1.get(AsynchronousInterceptor.java:36) ~[oxcore-service-4.5.2.Final.jar:?] at java.util.concurrent.CompletableFuture$AsyncSupply.run(CompletableFuture.java:1700) ~[?:?] at java.util.concurrent.CompletableFuture$AsyncSupply.exec(CompletableFuture.java:1692) ~[?:?] at java.util.concurrent.ForkJoinTask.doExec(ForkJoinTask.java:290) ~[?:?] at java.util.concurrent.ForkJoinPool$WorkQueue.topLevelExec(ForkJoinPool.java:1020) ~[?:?] at java.util.concurrent.ForkJoinPool.scan(ForkJoinPool.java:1656) ~[?:?] at java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1594) ~[?:?] at java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:183) ~[?:?] 2024-09-24 00:23:15,593 ERROR [ForkJoinPool.commonPool-worker-11] [org.gluu.oxauth.model.crypto.AbstractCryptoProvider] (AbstractCryptoProvider.java:121) - Algorithm: ES256Uninitialized keystore java.security.KeyStoreException: Uninitialized keystore at java.security.KeyStore.setKeyEntry(KeyStore.java:1166) ~[?:?] at org.gluu.oxauth.model.crypto.OxAuthCryptoProvider.generateKey(OxAuthCryptoProvider.java:220) ~[oxauth-model-4.5.2.Final.jar:?] at org.gluu.oxauth.model.crypto.OxAuthCryptoProvider.generateKey(OxAuthCryptoProvider.java:183) ~[oxauth-model-4.5.2.Final.jar:?] at org.gluu.oxauth.model.crypto.AbstractCryptoProvider$Proxy$_$$_WeldClientProxy.generateKey(Unknown Source) ~[oxauth-model-4.5.2.Final.jar:?] at org.gluu.oxauth.model.crypto.AbstractCryptoProvider.generateJwks(AbstractCryptoProvider.java:119) ~[oxauth-model-4.5.2.Final.jar:?] at org.gluu.oxauth.service.KeyGeneratorTimer.updateKeys(KeyGeneratorTimer.java:145) ~[classes/:?] at org.gluu.oxauth.service.KeyGeneratorTimer.updateKeysImpl(KeyGeneratorTimer.java:131) ~[classes/:?] at org.gluu.oxauth.service.KeyGeneratorTimer.updateKeys(KeyGeneratorTimer.java:108) ~[classes/:?] at org.gluu.oxauth.service.KeyGeneratorTimer.process(KeyGeneratorTimer.java:95) ~[classes/:?] at org.gluu.oxauth.service.KeyGeneratorTimer$Proxy$_$$_WeldSubclass.process$$super(Unknown Source) ~[classes/:?] at jdk.internal.reflect.GeneratedMethodAccessor235.invoke(Unknown Source) ~[?:?] at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?] at java.lang.reflect.Method.invoke(Method.java:566) ~[?:?] at org.jboss.weld.interceptor.proxy.TerminalAroundInvokeInvocationContext.proceedInternal(TerminalAroundInvokeInvocationContext.java:51) ~[weld-core-impl-3.1.9.Final.jar:3.1.9.Final] at org.jboss.weld.interceptor.proxy.AroundInvokeInvocationContext.proceed(AroundInvokeInvocationContext.java:78) ~[weld-core-impl-3.1.9.Final.jar:3.1.9.Final] at org.gluu.service.cdi.async.AsynchronousInterceptor$1.get(AsynchronousInterceptor.java:36) ~[oxcore-service-4.5.2.Final.jar:?] at java.util.concurrent.CompletableFuture$AsyncSupply.run(CompletableFuture.java:1700) ~[?:?] at java.util.concurrent.CompletableFuture$AsyncSupply.exec(CompletableFuture.java:1692) ~[?:?] at java.util.concurrent.ForkJoinTask.doExec(ForkJoinTask.java:290) ~[?:?] at java.util.concurrent.ForkJoinPool$WorkQueue.topLevelExec(ForkJoinPool.java:1020) ~[?:?] at java.util.concurrent.ForkJoinPool.scan(ForkJoinPool.java:1656) ~[?:?] at java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1594) ~[?:?] at java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:183) ~[?:?] 2024-09-24 00:23:16,950 ERROR [ForkJoinPool.commonPool-worker-11] [org.gluu.oxauth.model.crypto.OxAuthCryptoProvider] (OxAuthCryptoProvider.java:283) - Uninitialized keystore java.security.KeyStoreException: Uninitialized keystore at java.security.KeyStore.getKey(KeyStore.java:1055) ~[?:?] at org.gluu.oxauth.model.crypto.OxAuthCryptoProvider.containsKey(OxAuthCryptoProvider.java:281) ~[oxauth-model-4.5.2.Final.jar:?] at org.gluu.oxauth.model.crypto.AbstractCryptoProvider$Proxy$_$$_WeldClientProxy.containsKey(Unknown Source) ~[oxauth-model-4.5.2.Final.jar:?] at org.gluu.oxauth.service.KeyGeneratorTimer.updateKeys(KeyGeneratorTimer.java:161) ~[classes/:?] at org.gluu.oxauth.service.KeyGeneratorTimer.updateKeysImpl(KeyGeneratorTimer.java:131) ~[classes/:?] at org.gluu.oxauth.service.KeyGeneratorTimer.updateKeys(KeyGeneratorTimer.java:108) ~[classes/:?] at org.gluu.oxauth.service.KeyGeneratorTimer.process(KeyGeneratorTimer.java:95) ~[classes/:?] at org.gluu.oxauth.service.KeyGeneratorTimer$Proxy$_$$_WeldSubclass.process$$super(Unknown Source) ~[classes/:?] at jdk.internal.reflect.GeneratedMethodAccessor235.invoke(Unknown Source) ~[?:?] at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?] at java.lang.reflect.Method.invoke(Method.java:566) ~[?:?] at org.jboss.weld.interceptor.proxy.TerminalAroundInvokeInvocationContext.proceedInternal(TerminalAroundInvokeInvocationContext.java:51) ~[weld-core-impl-3.1.9.Final.jar:3.1.9.Final] at org.jboss.weld.interceptor.proxy.AroundInvokeInvocationContext.proceed(AroundInvokeInvocationContext.java:78) ~[weld-core-impl-3.1.9.Final.jar:3.1.9.Final] at org.gluu.service.cdi.async.AsynchronousInterceptor$1.get(AsynchronousInterceptor.java:36) ~[oxcore-service-4.5.2.Final.jar:?] at java.util.concurrent.CompletableFuture$AsyncSupply.run(CompletableFuture.java:1700) ~[?:?] at java.util.concurrent.CompletableFuture$AsyncSupply.exec(CompletableFuture.java:1692) ~[?:?] at java.util.concurrent.ForkJoinTask.doExec(ForkJoinTask.java:290) ~[?:?] at java.util.concurrent.ForkJoinPool$WorkQueue.topLevelExec(ForkJoinPool.java:1020) ~[?:?] at java.util.concurrent.ForkJoinPool.scan(ForkJoinPool.java:1656) ~[?:?] at java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1594) ~[?:?] at java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:183) ~[?:?] 2024-09-24 00:23:16,961 INFO [ForkJoinPool.commonPool-worker-11] [org.gluu.oxauth.service.KeyGeneratorTimer] (KeyGeneratorTimer.java:139) - Updated JWKS successfully 2024-09-24 00:23:16,961 TRACE [ForkJoinPool.commonPool-worker-11] [org.gluu.oxauth.service.KeyGeneratorTimer] (KeyGeneratorTimer.java:140) - JWKS keys: [] 2024-09-24 00:23:16,961 ERROR [ForkJoinPool.commonPool-worker-11] [org.gluu.oxauth.model.crypto.OxAuthCryptoProvider] (OxAuthCryptoProvider.java:509) - Uninitialized keystore java.security.KeyStoreException: Uninitialized keystore at java.security.KeyStore.aliases(KeyStore.java:1267) ~[?:?] at org.gluu.oxauth.model.crypto.OxAuthCryptoProvider.getKeys(OxAuthCryptoProvider.java:507) ~[oxauth-model-4.5.2.Final.jar:?] at org.gluu.oxauth.model.crypto.AbstractCryptoProvider$Proxy$_$$_WeldClientProxy.getKeys(Unknown Source) ~[oxauth-model-4.5.2.Final.jar:?] at org.gluu.oxauth.service.KeyGeneratorTimer.updateKeysImpl(KeyGeneratorTimer.java:141) ~[classes/:?] at org.gluu.oxauth.service.KeyGeneratorTimer.updateKeys(KeyGeneratorTimer.java:108) ~[classes/:?] at org.gluu.oxauth.service.KeyGeneratorTimer.process(KeyGeneratorTimer.java:95) ~[classes/:?] at org.gluu.oxauth.service.KeyGeneratorTimer$Proxy$_$$_WeldSubclass.process$$super(Unknown Source) ~[classes/:?] at jdk.internal.reflect.GeneratedMethodAccessor235.invoke(Unknown Source) ~[?:?] at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?] at java.lang.reflect.Method.invoke(Method.java:566) ~[?:?] at org.jboss.weld.interceptor.proxy.TerminalAroundInvokeInvocationContext.proceedInternal(TerminalAroundInvokeInvocationContext.java:51) ~[weld-core-impl-3.1.9.Final.jar:3.1.9.Final] at org.jboss.weld.interceptor.proxy.AroundInvokeInvocationContext.proceed(AroundInvokeInvocationContext.java:78) ~[weld-core-impl-3.1.9.Final.jar:3.1.9.Final] at org.gluu.service.cdi.async.AsynchronousInterceptor$1.get(AsynchronousInterceptor.java:36) ~[oxcore-service-4.5.2.Final.jar:?] at java.util.concurrent.CompletableFuture$AsyncSupply.run(CompletableFuture.java:1700) ~[?:?] at java.util.concurrent.CompletableFuture$AsyncSupply.exec(CompletableFuture.java:1692) ~[?:?] at java.util.concurrent.ForkJoinTask.doExec(ForkJoinTask.java:290) ~[?:?] at java.util.concurrent.ForkJoinPool$WorkQueue.topLevelExec(ForkJoinPool.java:1020) ~[?:?] at java.util.concurrent.ForkJoinPool.scan(ForkJoinPool.java:1656) ~[?:?] at java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1594) ~[?:?] at java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:183) ~[?:?] 2024-09-24 00:23:16,963 TRACE [ForkJoinPool.commonPool-worker-11] [org.gluu.oxauth.service.KeyGeneratorTimer] (KeyGeneratorTimer.java:141) - KeyStore keys: [] ```