Hi Mobarak,
Here is the oxauth.log in trace mode.
```
2023-12-12 09:03:02,078 DEBUG [qtp934275857-19] [org.gluu.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:142) - Starting endpoint authentication https://idpdev.aaas.org/oxauth/restv1/token
2023-12-12 09:03:02,078 DEBUG [qtp934275857-19] [org.gluu.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:154) - Starting JWT token endpoint authentication
2023-12-12 09:03:02,079 TRACE [qtp934275857-19] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=1501.2051e8de-f3b7-40e4-bebc-5280987cc017,ou=clients,o=gluu'
2023-12-12 09:03:02,079 TRACE [qtp934275857-19] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=1501.2051e8de-f3b7-40e4-bebc-5280987cc017,ou=clients,o=gluu': 'DeletableEntity{expirationDate=null, deletable=false} BaseEntry [dn=inum=1501.2051e8de-f3b7-40e4-bebc-5280987cc017,ou=clients,o=gluu]'
2023-12-12 09:03:02,079 TRACE [qtp934275857-19] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=1501.2051e8de-f3b7-40e4-bebc-5280987cc017,ou=clients,o=gluu'
2023-12-12 09:03:02,079 DEBUG [qtp934275857-19] [org.gluu.oxauth.service.ClientService] (ClientService.java:133) - Found 1 entries for client id = 1501.2051e8de-f3b7-40e4-bebc-5280987cc017
2023-12-12 09:03:02,079 INFO [qtp934275857-19] [org.gluu.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:473) - JWT authentication failed: {}
org.gluu.oxauth.model.exception.InvalidJwtException: Cannot verify the JWT
at org.gluu.oxauth.model.token.ClientAssertion.<init>(ClientAssertion.java:49) ~[classes/:?]
at org.gluu.oxauth.auth.AuthenticationFilter.processJwtAuth(AuthenticationFilter.java:453) ~[classes/:?]
at org.gluu.oxauth.auth.AuthenticationFilter.doFilter(AuthenticationFilter.java:155) ~[classes/:?]
at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:202) ~[?:?]
at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1600) ~[?:?]
at org.gluu.oxauth.audit.debug.ServletLoggingFilter.doFilter(ServletLoggingFilter.java:67) ~[classes/:?]
at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:210) ~[?:?]
at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1600) ~[?:?]
at org.eclipse.jetty.websocket.servlet.WebSocketUpgradeFilter.doFilter(WebSocketUpgradeFilter.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(ServletHandler.java:1600) ~[?:?]
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:506) ~[?:?]
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:131) ~[?:?]
at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:578) ~[?:?]
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:122) ~[?:?]
at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:223) ~[?:?]
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1571) ~[?:?]
at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:221) ~[?:?]
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1378) ~[?:?]
at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:176) ~[?:?]
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:463) ~[?:?]
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1544) ~[?:?]
at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:174) ~[?:?]
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1300) ~[?:?]
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:129) ~[?:?]
at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.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.java:282) ~[?:?]
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:319) ~[?:?]
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:100) ~[?:?]
at org.eclipse.jetty.io.SelectableChannelEndPoint$1.run(SelectableChannelEndPoint.java:53) ~[?:?]
at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.runTask(AdaptiveExecutionStrategy.java:412) ~[?:?]
at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.consumeTask(AdaptiveExecutionStrategy.java:381) ~[?:?]
at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.tryProduce(AdaptiveExecutionStrategy.java:268) ~[?:?]
at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.lambda$new$0(AdaptiveExecutionStrategy.java:138) ~[?:?]
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:407) ~[?:?]
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:894) ~[?:?]
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1038) ~[?:?]
at java.lang.Thread.run(Thread.java:829) ~[?:?]
Caused by: org.json.JSONException: A JSONObject text must begin with '{' at 1 [character 2 line 1]
at org.json.JSONTokener.syntaxError(JSONTokener.java:507) ~[json-20210307.jar:?]
at org.json.JSONObject.<init>(JSONObject.java:222) ~[json-20210307.jar:?]
at org.json.JSONObject.<init>(JSONObject.java:406) ~[json-20210307.jar:?]
at org.gluu.oxauth.util.ServerUtil.getJwks(ServerUtil.java:63) ~[classes/:?]
at org.gluu.oxauth.model.token.ClientAssertion.load(ClientAssertion.java:109) ~[classes/:?]
at org.gluu.oxauth.model.token.ClientAssertion.<init>(ClientAssertion.java:43) ~[classes/:?]
... 43 more
2023-12-12 09:03:02,091 DEBUG [qtp934275857-19] [org.gluu.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:72) - Looking for the error with id: invalid_client
2023-12-12 09:03:02,091 DEBUG [qtp934275857-19] [org.gluu.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:77) - Found error, id: invalid_client
2023-12-12 09:03:08,113 DEBUG [oxAuthScheduler_Worker-1] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started
2023-12-12 09:03:08,113 TRACE [oxAuthScheduler_Worker-1] [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 807664026
2023-12-12 09:03:08,113 DEBUG [oxAuthScheduler_Worker-1] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended
2023-12-12 09:03:08,125 INFO [ForkJoinPool.commonPool-worker-3] [org.gluu.service.logger.LoggerService] (LoggerService.java:205) - Updated log level of '131' loggers to TRACE
2023-12-12 09:03:23,105 DEBUG [oxAuthScheduler_Worker-2] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started
2023-12-12 09:03:23,105 TRACE [oxAuthScheduler_Worker-2] [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 238879497
2023-12-12 09:03:23,105 DEBUG [oxAuthScheduler_Worker-2] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended
2023-12-12 09:03:23,107 TRACE [ForkJoinPool.commonPool-worker-3] [org.gluu.oxauth.model.config.ConfigurationFactory] (ConfigurationFactory.java:258) - LDAP revision: 19, server revision:19
2023-12-12 09:03:23,113 DEBUG [oxAuthScheduler_Worker-4] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started
2023-12-12 09:03:23,113 TRACE [oxAuthScheduler_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 1081419894
2023-12-12 09:03:23,113 DEBUG [oxAuthScheduler_Worker-4] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended
2023-12-12 09:03:23,123 INFO [ForkJoinPool.commonPool-worker-3] [org.gluu.service.logger.LoggerService] (LoggerService.java:205) - Updated log level of '131' loggers to TRACE
2023-12-12 09:03:23,124 DEBUG [oxAuthScheduler_Worker-5] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started
2023-12-12 09:03:23,124 TRACE [oxAuthScheduler_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 936129426
2023-12-12 09:03:23,124 DEBUG [oxAuthScheduler_Worker-5] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended
2023-12-12 09:03:23,139 DEBUG [oxAuthScheduler_Worker-3] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started
2023-12-12 09:03:23,139 TRACE [oxAuthScheduler_Worker-3] [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 1086139453
2023-12-12 09:03:23,139 DEBUG [oxAuthScheduler_Worker-3] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended
2023-12-12 09:03:23,154 DEBUG [oxAuthScheduler_Worker-1] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started
2023-12-12 09:03:23,154 TRACE [oxAuthScheduler_Worker-1] [org.gluu.service.timer.TimerJob] (TimerJob.java:35) - Fire timer event [org.gluu.oxauth.service.cdi.event.KeyGenerationEvent] with qualifiers [@org.gluu.service.cdi.event.Scheduled()] from instance 946577053
2023-12-12 09:03:23,155 DEBUG [oxAuthScheduler_Worker-1] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended
2023-12-12 09:03:23,165 DEBUG [oxAuthScheduler_Worker-2] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started
2023-12-12 09:03:23,166 TRACE [oxAuthScheduler_Worker-2] [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 174720739
2023-12-12 09:03:23,166 DEBUG [oxAuthScheduler_Worker-2] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended
2023-12-12 09:03:23,166 TRACE [ForkJoinPool.commonPool-worker-5] [org.gluu.oxauth.service.AppInitializer] (AppInitializer.java:303) - Attempting to use persistenceEntryManager: org.gluu.persist.ldap.operation.impl.LdapOperationServiceImpl@33a3e5db
2023-12-12 09:03:23,186 DEBUG [oxAuthScheduler_Worker-4] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started
2023-12-12 09:03:23,186 TRACE [oxAuthScheduler_Worker-4] [org.gluu.service.timer.TimerJob] (TimerJob.java:35) - Fire timer event [org.gluu.oxauth.service.cdi.event.StatEvent] with qualifiers [@org.gluu.service.cdi.event.Scheduled()] from instance 2041716474
2023-12-12 09:03:23,186 DEBUG [oxAuthScheduler_Worker-4] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended
2023-12-12 09:03:28,172 DEBUG [oxAuthScheduler_Worker-5] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started
2023-12-12 09:03:28,172 TRACE [oxAuthScheduler_Worker-5] [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 76777891
2023-12-12 09:03:28,184 DEBUG [oxAuthScheduler_Worker-5] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended
2023-12-12 09:03:38,112 DEBUG [oxAuthScheduler_Worker-3] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started
2023-12-12 09:03:38,113 TRACE [oxAuthScheduler_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 569171311
2023-12-12 09:03:38,113 DEBUG [oxAuthScheduler_Worker-3] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended
2023-12-12 09:03:38,123 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.service.logger.LoggerService] (LoggerService.java:205) - Updated log level of '131' loggers to TRACE
2023-12-12 09:03:44,011 TRACE [qtp934275857-19] [gluu.oxauth.introspection.ws.rs.IntrospectionWebService] (IntrospectionWebService.java:146) - Introspect token, authorization: Bearer c1f6520e-6245-48d8-b12e-bf1fd23f1247, token to introspect: c1f6520e-6245-48d8-b12e-bf1fd23f1247, tokenTypeHint: null
2023-12-12 09:03:44,011 TRACE [qtp934275857-19] [gluu.oxauth.introspection.ws.rs.IntrospectionWebService] (IntrospectionWebService.java:111) - skipAuthorization: false
2023-12-12 09:03:44,011 TRACE [qtp934275857-19] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key '128a0fa85e17365c75e7b4f75a749671b7503ecd7c17ef34918636062055a3a8'
2023-12-12 09:03:44,012 TRACE [qtp934275857-19] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key '128a0fa85e17365c75e7b4f75a749671b7503ecd7c17ef34918636062055a3a8': 'null'
2023-12-12 09:03:44,013 DEBUG [qtp934275857-19] [org.gluu.oxauth.service.common.UserService] (UserService.java:81) - Getting user information from LDAP: userId = null
2023-12-12 09:03:44,013 TRACE [qtp934275857-19] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu'
2023-12-12 09:03:44,013 TRACE [qtp934275857-19] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu': 'null'
2023-12-12 09:03:44,013 TRACE [qtp934275857-19] [org.gluu.service.BaseCacheService] (BaseCacheService.java:61) - Key not in cache. Searching value via load function, key: 'inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu'
2023-12-12 09:03:44,014 TRACE [qtp934275857-19] [org.gluu.service.BaseCacheService] (BaseCacheService.java:84) - Put data, key 'inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu': 'DeletableEntity{expirationDate=null, deletable=false} BaseEntry [dn=inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu]'
2023-12-12 09:03:44,015 DEBUG [qtp934275857-19] [org.gluu.oxauth.service.ClientService] (ClientService.java:133) - Found 1 entries for client id = @!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE
2023-12-12 09:03:44,015 TRACE [qtp934275857-19] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key '128a0fa85e17365c75e7b4f75a749671b7503ecd7c17ef34918636062055a3a8'
2023-12-12 09:03:44,016 TRACE [qtp934275857-19] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key '128a0fa85e17365c75e7b4f75a749671b7503ecd7c17ef34918636062055a3a8': 'null'
2023-12-12 09:03:44,017 DEBUG [qtp934275857-19] [org.gluu.oxauth.service.common.UserService] (UserService.java:81) - Getting user information from LDAP: userId = null
2023-12-12 09:03:44,017 TRACE [qtp934275857-19] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu'
2023-12-12 09:03:44,017 TRACE [qtp934275857-19] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu': 'DeletableEntity{expirationDate=null, deletable=false} BaseEntry [dn=inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu]'
2023-12-12 09:03:44,017 TRACE [qtp934275857-19] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu'
2023-12-12 09:03:44,017 DEBUG [qtp934275857-19] [org.gluu.oxauth.service.ClientService] (ClientService.java:133) - Found 1 entries for client id = @!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE
2023-12-12 09:03:44,017 TRACE [qtp934275857-19] [org.gluu.oxauth.service.SectorIdentifierService] (SectorIdentifierService.java:79) - User is null, return blank sub
2023-12-12 09:03:44,018 TRACE [qtp934275857-19] [org.gluu.oxauth.service.external.ExternalIntrospectionService] (ExternalIntrospectionService.java:58) - No introspection scripts associated with client which was used to obtain access_token.
2023-12-12 09:03:44,018 TRACE [qtp934275857-19] [org.gluu.oxauth.service.external.ExternalIntrospectionService] (ExternalIntrospectionService.java:65) - There is no any external interception scripts defined.
2023-12-12 09:03:44,018 TRACE [qtp934275857-19] [gluu.oxauth.introspection.ws.rs.IntrospectionWebService] (IntrospectionWebService.java:189) - Canceled changes made by external introspection script since method returned `false`.
2023-12-12 09:03:53,105 DEBUG [oxAuthScheduler_Worker-1] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started
2023-12-12 09:03:53,106 TRACE [oxAuthScheduler_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 1847439593
2023-12-12 09:03:53,106 DEBUG [oxAuthScheduler_Worker-1] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended
2023-12-12 09:03:53,108 TRACE [ForkJoinPool.commonPool-worker-5] [org.gluu.oxauth.model.config.ConfigurationFactory] (ConfigurationFactory.java:258) - LDAP revision: 19, server revision:19
2023-12-12 09:03:53,113 DEBUG [oxAuthScheduler_Worker-2] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started
2023-12-12 09:03:53,113 TRACE [oxAuthScheduler_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 334783838
2023-12-12 09:03:53,113 DEBUG [oxAuthScheduler_Worker-2] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended
2023-12-12 09:03:53,126 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.service.logger.LoggerService] (LoggerService.java:205) - Updated log level of '131' loggers to TRACE
2023-12-12 09:03:53,139 DEBUG [oxAuthScheduler_Worker-4] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started
2023-12-12 09:03:53,139 TRACE [oxAuthScheduler_Worker-4] [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 1852897914
2023-12-12 09:03:53,139 DEBUG [oxAuthScheduler_Worker-4] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended
2023-12-12 09:03:53,165 DEBUG [oxAuthScheduler_Worker-5] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started
2023-12-12 09:03:53,165 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 833075102
2023-12-12 09:03:53,166 DEBUG [oxAuthScheduler_Worker-5] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended
2023-12-12 09:03:53,166 TRACE [ForkJoinPool.commonPool-worker-3] [org.gluu.oxauth.service.AppInitializer] (AppInitializer.java:303) - Attempting to use persistenceEntryManager: org.gluu.persist.ldap.operation.impl.LdapOperationServiceImpl@33a3e5db
2023-12-12 09:03:54,482 DEBUG [ForkJoinPool.commonPool-worker-5] [org.gluu.service.cache.NativePersistenceCacheProvider] (NativePersistenceCacheProvider.java:282) - Start NATIVE_PERSISTENCE clean up
2023-12-12 09:03:54,484 DEBUG [ForkJoinPool.commonPool-worker-5] [org.gluu.service.cache.NativePersistenceCacheProvider] (NativePersistenceCacheProvider.java:289) - End NATIVE_PERSISTENCE clean up, items removed: 1
2023-12-12 09:03:58,172 DEBUG [oxAuthScheduler_Worker-3] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started
2023-12-12 09:03:58,173 TRACE [oxAuthScheduler_Worker-3] [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 1926762929
2023-12-12 09:03:58,189 DEBUG [oxAuthScheduler_Worker-3] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended
2023-12-12 09:03:58,868 TRACE [qtp934275857-17] [org.gluu.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:121) - Get request to: 'https://idpdev.aaas.org/oxauth/restv1/token'
2023-12-12 09:03:58,868 TRACE [qtp934275857-17] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=1501.2051e8de-f3b7-40e4-bebc-5280987cc017,ou=clients,o=gluu'
2023-12-12 09:03:58,868 TRACE [qtp934275857-17] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=1501.2051e8de-f3b7-40e4-bebc-5280987cc017,ou=clients,o=gluu': 'null'
2023-12-12 09:03:58,868 TRACE [qtp934275857-17] [org.gluu.service.BaseCacheService] (BaseCacheService.java:61) - Key not in cache. Searching value via load function, key: 'inum=1501.2051e8de-f3b7-40e4-bebc-5280987cc017,ou=clients,o=gluu'
2023-12-12 09:03:58,873 TRACE [qtp934275857-17] [org.gluu.service.BaseCacheService] (BaseCacheService.java:84) - Put data, key 'inum=1501.2051e8de-f3b7-40e4-bebc-5280987cc017,ou=clients,o=gluu': 'DeletableEntity{expirationDate=null, deletable=false} BaseEntry [dn=inum=1501.2051e8de-f3b7-40e4-bebc-5280987cc017,ou=clients,o=gluu]'
2023-12-12 09:03:58,873 DEBUG [qtp934275857-17] [org.gluu.oxauth.service.ClientService] (ClientService.java:133) - Found 1 entries for client id = 1501.2051e8de-f3b7-40e4-bebc-5280987cc017
2023-12-12 09:03:58,873 DEBUG [qtp934275857-17] [org.gluu.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:142) - Starting endpoint authentication https://idpdev.aaas.org/oxauth/restv1/token
2023-12-12 09:03:58,873 DEBUG [qtp934275857-17] [org.gluu.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:154) - Starting JWT token endpoint authentication
2023-12-12 09:03:58,873 TRACE [qtp934275857-17] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=1501.2051e8de-f3b7-40e4-bebc-5280987cc017,ou=clients,o=gluu'
2023-12-12 09:03:58,873 TRACE [qtp934275857-17] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=1501.2051e8de-f3b7-40e4-bebc-5280987cc017,ou=clients,o=gluu': 'DeletableEntity{expirationDate=null, deletable=false} BaseEntry [dn=inum=1501.2051e8de-f3b7-40e4-bebc-5280987cc017,ou=clients,o=gluu]'
2023-12-12 09:03:58,873 TRACE [qtp934275857-17] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=1501.2051e8de-f3b7-40e4-bebc-5280987cc017,ou=clients,o=gluu'
2023-12-12 09:03:58,873 DEBUG [qtp934275857-17] [org.gluu.oxauth.service.ClientService] (ClientService.java:133) - Found 1 entries for client id = 1501.2051e8de-f3b7-40e4-bebc-5280987cc017
2023-12-12 09:03:58,873 INFO [qtp934275857-17] [org.gluu.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:473) - JWT authentication failed: {}
org.gluu.oxauth.model.exception.InvalidJwtException: Cannot verify the JWT
at org.gluu.oxauth.model.token.ClientAssertion.<init>(ClientAssertion.java:49) ~[classes/:?]
at org.gluu.oxauth.auth.AuthenticationFilter.processJwtAuth(AuthenticationFilter.java:453) ~[classes/:?]
at org.gluu.oxauth.auth.AuthenticationFilter.doFilter(AuthenticationFilter.java:155) ~[classes/:?]
at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:202) ~[?:?]
at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1600) ~[?:?]
at org.gluu.oxauth.audit.debug.ServletLoggingFilter.doFilter(ServletLoggingFilter.java:67) ~[classes/:?]
at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:210) ~[?:?]
at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1600) ~[?:?]
at org.eclipse.jetty.websocket.servlet.WebSocketUpgradeFilter.doFilter(WebSocketUpgradeFilter.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(ServletHandler.java:1600) ~[?:?]
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:506) ~[?:?]
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:131) ~[?:?]
at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:578) ~[?:?]
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:122) ~[?:?]
at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:223) ~[?:?]
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1571) ~[?:?]
at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:221) ~[?:?]
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1378) ~[?:?]
at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:176) ~[?:?]
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:463) ~[?:?]
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1544) ~[?:?]
at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:174) ~[?:?]
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1300) ~[?:?]
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:129) ~[?:?]
at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.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.java:282) ~[?:?]
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:319) ~[?:?]
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:100) ~[?:?]
at org.eclipse.jetty.io.SelectableChannelEndPoint$1.run(SelectableChannelEndPoint.java:53) ~[?:?]
at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.runTask(AdaptiveExecutionStrategy.java:412) ~[?:?]
at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.consumeTask(AdaptiveExecutionStrategy.java:381) ~[?:?]
at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.tryProduce(AdaptiveExecutionStrategy.java:268) ~[?:?]
at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.lambda$new$0(AdaptiveExecutionStrategy.java:138) ~[?:?]
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:407) ~[?:?]
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:894) ~[?:?]
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1038) ~[?:?]
at java.lang.Thread.run(Thread.java:829) ~[?:?]
Caused by: org.json.JSONException: A JSONObject text must begin with '{' at 1 [character 2 line 1]
at org.json.JSONTokener.syntaxError(JSONTokener.java:507) ~[json-20210307.jar:?]
at org.json.JSONObject.<init>(JSONObject.java:222) ~[json-20210307.jar:?]
at org.json.JSONObject.<init>(JSONObject.java:406) ~[json-20210307.jar:?]
at org.gluu.oxauth.util.ServerUtil.getJwks(ServerUtil.java:63) ~[classes/:?]
at org.gluu.oxauth.model.token.ClientAssertion.load(ClientAssertion.java:109) ~[classes/:?]
at org.gluu.oxauth.model.token.ClientAssertion.<init>(ClientAssertion.java:43) ~[classes/:?]
... 43 more
2023-12-12 09:03:58,889 DEBUG [qtp934275857-17] [org.gluu.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:72) - Looking for the error with id: invalid_client
2023-12-12 09:03:58,889 DEBUG [qtp934275857-17] [org.gluu.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:77) - Found error, id: invalid_client
2023-12-12 09:03:59,162 TRACE [qtp934275857-13] [gluu.oxauth.introspection.ws.rs.IntrospectionWebService] (IntrospectionWebService.java:146) - Introspect token, authorization: Bearer c1f6520e-6245-48d8-b12e-bf1fd23f1247, token to introspect: c1f6520e-6245-48d8-b12e-bf1fd23f1247, tokenTypeHint: null
2023-12-12 09:03:59,162 TRACE [qtp934275857-13] [gluu.oxauth.introspection.ws.rs.IntrospectionWebService] (IntrospectionWebService.java:111) - skipAuthorization: false
2023-12-12 09:03:59,163 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key '128a0fa85e17365c75e7b4f75a749671b7503ecd7c17ef34918636062055a3a8'
2023-12-12 09:03:59,164 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key '128a0fa85e17365c75e7b4f75a749671b7503ecd7c17ef34918636062055a3a8': 'null'
2023-12-12 09:03:59,179 DEBUG [qtp934275857-13] [org.gluu.oxauth.service.common.UserService] (UserService.java:81) - Getting user information from LDAP: userId = null
2023-12-12 09:03:59,185 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu'
2023-12-12 09:03:59,187 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu': 'DeletableEntity{expirationDate=null, deletable=false} BaseEntry [dn=inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu]'
2023-12-12 09:03:59,187 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu'
2023-12-12 09:03:59,187 DEBUG [qtp934275857-13] [org.gluu.oxauth.service.ClientService] (ClientService.java:133) - Found 1 entries for client id = @!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE
2023-12-12 09:03:59,187 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key '128a0fa85e17365c75e7b4f75a749671b7503ecd7c17ef34918636062055a3a8'
2023-12-12 09:03:59,188 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key '128a0fa85e17365c75e7b4f75a749671b7503ecd7c17ef34918636062055a3a8': 'null'
2023-12-12 09:03:59,189 DEBUG [qtp934275857-13] [org.gluu.oxauth.service.common.UserService] (UserService.java:81) - Getting user information from LDAP: userId = null
2023-12-12 09:03:59,189 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu'
2023-12-12 09:03:59,189 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu': 'DeletableEntity{expirationDate=null, deletable=false} BaseEntry [dn=inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu]'
2023-12-12 09:03:59,189 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu'
2023-12-12 09:03:59,189 DEBUG [qtp934275857-13] [org.gluu.oxauth.service.ClientService] (ClientService.java:133) - Found 1 entries for client id = @!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE
2023-12-12 09:03:59,197 TRACE [qtp934275857-13] [org.gluu.oxauth.service.SectorIdentifierService] (SectorIdentifierService.java:79) - User is null, return blank sub
2023-12-12 09:03:59,200 TRACE [qtp934275857-13] [org.gluu.oxauth.service.external.ExternalIntrospectionService] (ExternalIntrospectionService.java:58) - No introspection scripts associated with client which was used to obtain access_token.
2023-12-12 09:03:59,200 TRACE [qtp934275857-13] [org.gluu.oxauth.service.external.ExternalIntrospectionService] (ExternalIntrospectionService.java:65) - There is no any external interception scripts defined.
2023-12-12 09:03:59,210 TRACE [qtp934275857-13] [gluu.oxauth.introspection.ws.rs.IntrospectionWebService] (IntrospectionWebService.java:189) - Canceled changes made by external introspection script since method returned `false`.
2023-12-12 09:04:00,047 TRACE [qtp934275857-17] [org.gluu.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:121) - Get request to: 'https://idpdev.aaas.org/oxauth/restv1/token'
2023-12-12 09:04:00,047 TRACE [qtp934275857-17] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=1501.2051e8de-f3b7-40e4-bebc-5280987cc017,ou=clients,o=gluu'
2023-12-12 09:04:00,047 TRACE [qtp934275857-17] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=1501.2051e8de-f3b7-40e4-bebc-5280987cc017,ou=clients,o=gluu': 'DeletableEntity{expirationDate=null, deletable=false} BaseEntry [dn=inum=1501.2051e8de-f3b7-40e4-bebc-5280987cc017,ou=clients,o=gluu]'
2023-12-12 09:04:00,047 TRACE [qtp934275857-17] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=1501.2051e8de-f3b7-40e4-bebc-5280987cc017,ou=clients,o=gluu'
2023-12-12 09:04:00,047 DEBUG [qtp934275857-17] [org.gluu.oxauth.service.ClientService] (ClientService.java:133) - Found 1 entries for client id = 1501.2051e8de-f3b7-40e4-bebc-5280987cc017
2023-12-12 09:04:00,047 DEBUG [qtp934275857-17] [org.gluu.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:142) - Starting endpoint authentication https://idpdev.aaas.org/oxauth/restv1/token
2023-12-12 09:04:00,047 DEBUG [qtp934275857-17] [org.gluu.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:154) - Starting JWT token endpoint authentication
2023-12-12 09:04:00,048 TRACE [qtp934275857-17] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=1501.2051e8de-f3b7-40e4-bebc-5280987cc017,ou=clients,o=gluu'
2023-12-12 09:04:00,048 TRACE [qtp934275857-17] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=1501.2051e8de-f3b7-40e4-bebc-5280987cc017,ou=clients,o=gluu': 'DeletableEntity{expirationDate=null, deletable=false} BaseEntry [dn=inum=1501.2051e8de-f3b7-40e4-bebc-5280987cc017,ou=clients,o=gluu]'
2023-12-12 09:04:00,048 TRACE [qtp934275857-17] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=1501.2051e8de-f3b7-40e4-bebc-5280987cc017,ou=clients,o=gluu'
2023-12-12 09:04:00,048 DEBUG [qtp934275857-17] [org.gluu.oxauth.service.ClientService] (ClientService.java:133) - Found 1 entries for client id = 1501.2051e8de-f3b7-40e4-bebc-5280987cc017
2023-12-12 09:04:00,048 INFO [qtp934275857-17] [org.gluu.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:473) - JWT authentication failed: {}
org.gluu.oxauth.model.exception.InvalidJwtException: Cannot verify the JWT
at org.gluu.oxauth.model.token.ClientAssertion.<init>(ClientAssertion.java:49) ~[classes/:?]
at org.gluu.oxauth.auth.AuthenticationFilter.processJwtAuth(AuthenticationFilter.java:453) ~[classes/:?]
at org.gluu.oxauth.auth.AuthenticationFilter.doFilter(AuthenticationFilter.java:155) ~[classes/:?]
at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:202) ~[?:?]
at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1600) ~[?:?]
at org.gluu.oxauth.audit.debug.ServletLoggingFilter.doFilter(ServletLoggingFilter.java:67) ~[classes/:?]
at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:210) ~[?:?]
at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1600) ~[?:?]
at org.eclipse.jetty.websocket.servlet.WebSocketUpgradeFilter.doFilter(WebSocketUpgradeFilter.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(ServletHandler.java:1600) ~[?:?]
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:506) ~[?:?]
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:131) ~[?:?]
at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:578) ~[?:?]
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:122) ~[?:?]
at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:223) ~[?:?]
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1571) ~[?:?]
at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:221) ~[?:?]
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1378) ~[?:?]
at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:176) ~[?:?]
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:463) ~[?:?]
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1544) ~[?:?]
at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:174) ~[?:?]
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1300) ~[?:?]
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:129) ~[?:?]
at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.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.java:282) ~[?:?]
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:319) ~[?:?]
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:100) ~[?:?]
at org.eclipse.jetty.io.SelectableChannelEndPoint$1.run(SelectableChannelEndPoint.java:53) ~[?:?]
at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.runTask(AdaptiveExecutionStrategy.java:412) ~[?:?]
at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.consumeTask(AdaptiveExecutionStrategy.java:381) ~[?:?]
at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.tryProduce(AdaptiveExecutionStrategy.java:268) ~[?:?]
at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.lambda$new$0(AdaptiveExecutionStrategy.java:138) ~[?:?]
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:407) ~[?:?]
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:894) ~[?:?]
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1038) ~[?:?]
at java.lang.Thread.run(Thread.java:829) ~[?:?]
Caused by: org.json.JSONException: A JSONObject text must begin with '{' at 1 [character 2 line 1]
at org.json.JSONTokener.syntaxError(JSONTokener.java:507) ~[json-20210307.jar:?]
at org.json.JSONObject.<init>(JSONObject.java:222) ~[json-20210307.jar:?]
at org.json.JSONObject.<init>(JSONObject.java:406) ~[json-20210307.jar:?]
at org.gluu.oxauth.util.ServerUtil.getJwks(ServerUtil.java:63) ~[classes/:?]
at org.gluu.oxauth.model.token.ClientAssertion.load(ClientAssertion.java:109) ~[classes/:?]
at org.gluu.oxauth.model.token.ClientAssertion.<init>(ClientAssertion.java:43) ~[classes/:?]
... 43 more
2023-12-12 09:04:00,065 DEBUG [qtp934275857-17] [org.gluu.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:72) - Looking for the error with id: invalid_client
2023-12-12 09:04:00,065 DEBUG [qtp934275857-17] [org.gluu.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:77) - Found error, id: invalid_client
2023-12-12 09:04:02,261 TRACE [qtp934275857-19] [org.gluu.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:121) - Get request to: 'https://idpdev.aaas.org/oxauth/restv1/token'
2023-12-12 09:04:02,261 TRACE [qtp934275857-19] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=1501.2051e8de-f3b7-40e4-bebc-5280987cc017,ou=clients,o=gluu'
2023-12-12 09:04:02,262 TRACE [qtp934275857-19] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=1501.2051e8de-f3b7-40e4-bebc-5280987cc017,ou=clients,o=gluu': 'DeletableEntity{expirationDate=null, deletable=false} BaseEntry [dn=inum=1501.2051e8de-f3b7-40e4-bebc-5280987cc017,ou=clients,o=gluu]'
2023-12-12 09:04:02,262 TRACE [qtp934275857-19] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=1501.2051e8de-f3b7-40e4-bebc-5280987cc017,ou=clients,o=gluu'
2023-12-12 09:04:02,262 DEBUG [qtp934275857-19] [org.gluu.oxauth.service.ClientService] (ClientService.java:133) - Found 1 entries for client id = 1501.2051e8de-f3b7-40e4-bebc-5280987cc017
2023-12-12 09:04:02,262 DEBUG [qtp934275857-19] [org.gluu.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:142) - Starting endpoint authentication https://idpdev.aaas.org/oxauth/restv1/token
2023-12-12 09:04:02,262 DEBUG [qtp934275857-19] [org.gluu.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:154) - Starting JWT token endpoint authentication
2023-12-12 09:04:02,262 TRACE [qtp934275857-19] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=1501.2051e8de-f3b7-40e4-bebc-5280987cc017,ou=clients,o=gluu'
2023-12-12 09:04:02,262 TRACE [qtp934275857-19] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=1501.2051e8de-f3b7-40e4-bebc-5280987cc017,ou=clients,o=gluu': 'DeletableEntity{expirationDate=null, deletable=false} BaseEntry [dn=inum=1501.2051e8de-f3b7-40e4-bebc-5280987cc017,ou=clients,o=gluu]'
2023-12-12 09:04:02,262 TRACE [qtp934275857-19] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=1501.2051e8de-f3b7-40e4-bebc-5280987cc017,ou=clients,o=gluu'
2023-12-12 09:04:02,262 DEBUG [qtp934275857-19] [org.gluu.oxauth.service.ClientService] (ClientService.java:133) - Found 1 entries for client id = 1501.2051e8de-f3b7-40e4-bebc-5280987cc017
2023-12-12 09:04:02,262 INFO [qtp934275857-19] [org.gluu.oxauth.auth.AuthenticationFilter] (AuthenticationFilter.java:473) - JWT authentication failed: {}
org.gluu.oxauth.model.exception.InvalidJwtException: Cannot verify the JWT
at org.gluu.oxauth.model.token.ClientAssertion.<init>(ClientAssertion.java:49) ~[classes/:?]
at org.gluu.oxauth.auth.AuthenticationFilter.processJwtAuth(AuthenticationFilter.java:453) ~[classes/:?]
at org.gluu.oxauth.auth.AuthenticationFilter.doFilter(AuthenticationFilter.java:155) ~[classes/:?]
at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:202) ~[?:?]
at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1600) ~[?:?]
at org.gluu.oxauth.audit.debug.ServletLoggingFilter.doFilter(ServletLoggingFilter.java:67) ~[classes/:?]
at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:210) ~[?:?]
at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1600) ~[?:?]
at org.eclipse.jetty.websocket.servlet.WebSocketUpgradeFilter.doFilter(WebSocketUpgradeFilter.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(ServletHandler.java:1600) ~[?:?]
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:506) ~[?:?]
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:131) ~[?:?]
at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:578) ~[?:?]
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:122) ~[?:?]
at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:223) ~[?:?]
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1571) ~[?:?]
at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:221) ~[?:?]
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1378) ~[?:?]
at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:176) ~[?:?]
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:463) ~[?:?]
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1544) ~[?:?]
at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:174) ~[?:?]
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1300) ~[?:?]
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:129) ~[?:?]
at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.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.java:282) ~[?:?]
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:319) ~[?:?]
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:100) ~[?:?]
at org.eclipse.jetty.io.SelectableChannelEndPoint$1.run(SelectableChannelEndPoint.java:53) ~[?:?]
at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.runTask(AdaptiveExecutionStrategy.java:412) ~[?:?]
at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.consumeTask(AdaptiveExecutionStrategy.java:381) ~[?:?]
at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.tryProduce(AdaptiveExecutionStrategy.java:268) ~[?:?]
at org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.lambda$new$0(AdaptiveExecutionStrategy.java:138) ~[?:?]
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:407) ~[?:?]
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:894) ~[?:?]
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1038) ~[?:?]
at java.lang.Thread.run(Thread.java:829) ~[?:?]
Caused by: org.json.JSONException: A JSONObject text must begin with '{' at 1 [character 2 line 1]
at org.json.JSONTokener.syntaxError(JSONTokener.java:507) ~[json-20210307.jar:?]
at org.json.JSONObject.<init>(JSONObject.java:222) ~[json-20210307.jar:?]
at org.json.JSONObject.<init>(JSONObject.java:406) ~[json-20210307.jar:?]
at org.gluu.oxauth.util.ServerUtil.getJwks(ServerUtil.java:63) ~[classes/:?]
at org.gluu.oxauth.model.token.ClientAssertion.load(ClientAssertion.java:109) ~[classes/:?]
at org.gluu.oxauth.model.token.ClientAssertion.<init>(ClientAssertion.java:43) ~[classes/:?]
... 43 more
2023-12-12 09:04:02,277 DEBUG [qtp934275857-19] [org.gluu.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:72) - Looking for the error with id: invalid_client
2023-12-12 09:04:02,277 DEBUG [qtp934275857-19] [org.gluu.oxauth.model.error.ErrorResponseFactory] (ErrorResponseFactory.java:77) - Found error, id: invalid_client
2023-12-12 09:04:03,153 TRACE [qtp934275857-13] [gluu.oxauth.introspection.ws.rs.IntrospectionWebService] (IntrospectionWebService.java:146) - Introspect token, authorization: Bearer c1f6520e-6245-48d8-b12e-bf1fd23f1247, token to introspect: c1f6520e-6245-48d8-b12e-bf1fd23f1247, tokenTypeHint: null
2023-12-12 09:04:03,153 TRACE [qtp934275857-13] [gluu.oxauth.introspection.ws.rs.IntrospectionWebService] (IntrospectionWebService.java:111) - skipAuthorization: false
2023-12-12 09:04:03,153 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key '128a0fa85e17365c75e7b4f75a749671b7503ecd7c17ef34918636062055a3a8'
2023-12-12 09:04:03,154 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key '128a0fa85e17365c75e7b4f75a749671b7503ecd7c17ef34918636062055a3a8': 'null'
2023-12-12 09:04:03,155 DEBUG [qtp934275857-13] [org.gluu.oxauth.service.common.UserService] (UserService.java:81) - Getting user information from LDAP: userId = null
2023-12-12 09:04:03,155 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu'
2023-12-12 09:04:03,155 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu': 'DeletableEntity{expirationDate=null, deletable=false} BaseEntry [dn=inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu]'
2023-12-12 09:04:03,155 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu'
2023-12-12 09:04:03,155 DEBUG [qtp934275857-13] [org.gluu.oxauth.service.ClientService] (ClientService.java:133) - Found 1 entries for client id = @!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE
2023-12-12 09:04:03,155 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key '128a0fa85e17365c75e7b4f75a749671b7503ecd7c17ef34918636062055a3a8'
2023-12-12 09:04:03,156 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key '128a0fa85e17365c75e7b4f75a749671b7503ecd7c17ef34918636062055a3a8': 'null'
2023-12-12 09:04:03,157 DEBUG [qtp934275857-13] [org.gluu.oxauth.service.common.UserService] (UserService.java:81) - Getting user information from LDAP: userId = null
2023-12-12 09:04:03,157 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu'
2023-12-12 09:04:03,158 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu': 'DeletableEntity{expirationDate=null, deletable=false} BaseEntry [dn=inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu]'
2023-12-12 09:04:03,158 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu'
2023-12-12 09:04:03,158 DEBUG [qtp934275857-13] [org.gluu.oxauth.service.ClientService] (ClientService.java:133) - Found 1 entries for client id = @!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE
2023-12-12 09:04:03,158 TRACE [qtp934275857-13] [org.gluu.oxauth.service.SectorIdentifierService] (SectorIdentifierService.java:79) - User is null, return blank sub
2023-12-12 09:04:03,158 TRACE [qtp934275857-13] [org.gluu.oxauth.service.external.ExternalIntrospectionService] (ExternalIntrospectionService.java:58) - No introspection scripts associated with client which was used to obtain access_token.
2023-12-12 09:04:03,158 TRACE [qtp934275857-13] [org.gluu.oxauth.service.external.ExternalIntrospectionService] (ExternalIntrospectionService.java:65) - There is no any external interception scripts defined.
2023-12-12 09:04:03,159 TRACE [qtp934275857-13] [gluu.oxauth.introspection.ws.rs.IntrospectionWebService] (IntrospectionWebService.java:189) - Canceled changes made by external introspection script since method returned `false`.
2023-12-12 09:04:03,187 TRACE [qtp934275857-13] [gluu.oxauth.introspection.ws.rs.IntrospectionWebService] (IntrospectionWebService.java:146) - Introspect token, authorization: Bearer c1f6520e-6245-48d8-b12e-bf1fd23f1247, token to introspect: c1f6520e-6245-48d8-b12e-bf1fd23f1247, tokenTypeHint: null
2023-12-12 09:04:03,187 TRACE [qtp934275857-13] [gluu.oxauth.introspection.ws.rs.IntrospectionWebService] (IntrospectionWebService.java:111) - skipAuthorization: false
2023-12-12 09:04:03,187 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key '128a0fa85e17365c75e7b4f75a749671b7503ecd7c17ef34918636062055a3a8'
2023-12-12 09:04:03,188 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key '128a0fa85e17365c75e7b4f75a749671b7503ecd7c17ef34918636062055a3a8': 'null'
2023-12-12 09:04:03,189 DEBUG [qtp934275857-13] [org.gluu.oxauth.service.common.UserService] (UserService.java:81) - Getting user information from LDAP: userId = null
2023-12-12 09:04:03,189 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu'
2023-12-12 09:04:03,189 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu': 'DeletableEntity{expirationDate=null, deletable=false} BaseEntry [dn=inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu]'
2023-12-12 09:04:03,189 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu'
2023-12-12 09:04:03,190 DEBUG [qtp934275857-13] [org.gluu.oxauth.service.ClientService] (ClientService.java:133) - Found 1 entries for client id = @!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE
2023-12-12 09:04:03,190 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key '128a0fa85e17365c75e7b4f75a749671b7503ecd7c17ef34918636062055a3a8'
2023-12-12 09:04:03,192 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key '128a0fa85e17365c75e7b4f75a749671b7503ecd7c17ef34918636062055a3a8': 'null'
2023-12-12 09:04:03,193 DEBUG [qtp934275857-13] [org.gluu.oxauth.service.common.UserService] (UserService.java:81) - Getting user information from LDAP: userId = null
2023-12-12 09:04:03,193 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:37) - Request data, key 'inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu'
2023-12-12 09:04:03,194 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:39) - Loaded data, key 'inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu': 'DeletableEntity{expirationDate=null, deletable=false} BaseEntry [dn=inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu]'
2023-12-12 09:04:03,194 TRACE [qtp934275857-13] [org.gluu.service.BaseCacheService] (BaseCacheService.java:58) - Loaded from cache, key: 'inum=@!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE,ou=clients,o=gluu'
2023-12-12 09:04:03,194 DEBUG [qtp934275857-13] [org.gluu.oxauth.service.ClientService] (ClientService.java:133) - Found 1 entries for client id = @!564A.08D9.2499.0A20!0001!9DCD.1028!0008!A813.FBBE
2023-12-12 09:04:03,194 TRACE [qtp934275857-13] [org.gluu.oxauth.service.SectorIdentifierService] (SectorIdentifierService.java:79) - User is null, return blank sub
2023-12-12 09:04:03,197 TRACE [qtp934275857-13] [org.gluu.oxauth.service.external.ExternalIntrospectionService] (ExternalIntrospectionService.java:58) - No introspection scripts associated with client which was used to obtain access_token.
2023-12-12 09:04:03,197 TRACE [qtp934275857-13] [org.gluu.oxauth.service.external.ExternalIntrospectionService] (ExternalIntrospectionService.java:65) - There is no any external interception scripts defined.
2023-12-12 09:04:03,197 TRACE [qtp934275857-13] [gluu.oxauth.introspection.ws.rs.IntrospectionWebService] (IntrospectionWebService.java:189) - Canceled changes made by external introspection script since method returned `false`.
```