By: Tony Mann user 15 Jun 2020 at 4:38 p.m. CDT

2 Responses
Tony Mann gravatar
Hello, I have installed Gluu server 4.1.1 and everything works as expected apart from a very strange log error which keeps filling up the logs. I am not using fido, the idp is setup in a very basic fashion and has ldap_authentication method as its default method and the basic_lock script enabled thats all. Below is the error which keeps occuring i have restarted the box and all services. The oxauth.log has the following after i restarted the service. ``` 2020-06-15 19:39:28,750 INFO [Thread-1] [org.gluu.oxauth.service.AppInitializer] (AppInitializer.java:640) - Stopping services and closing DB connections at server shutdown... 2020-06-15 19:39:28,760 INFO [Thread-1] [org.quartz.core.QuartzScheduler] (QuartzScheduler.java:666) - Scheduler oxAuthScheduler_$_NON_CLUSTERED shutting down. 2020-06-15 19:39:28,760 INFO [Thread-1] [org.quartz.core.QuartzScheduler] (QuartzScheduler.java:585) - Scheduler oxAuthScheduler_$_NON_CLUSTERED paused. 2020-06-15 19:39:28,761 INFO [Thread-1] [org.quartz.core.QuartzScheduler] (QuartzScheduler.java:740) - Scheduler oxAuthScheduler_$_NON_CLUSTERED shutdown complete. 2020-06-15 19:39:28,761 INFO [Thread-1] [org.gluu.service.timer.QuartzSchedulerManager] (QuartzSchedulerManager.java:150) - Quartz scheduler manager destroyed 2020-06-15 19:39:35,709 INFO [main] [org.jboss.weld.environment.servlet.EnhancedListener] (EnhancedListener.java:58) - WELD-ENV-001008: Initialize Weld using ServletContainerInitializer 2020-06-15 19:39:35,736 INFO [main] [org.jboss.weld.bootstrap.WeldStartup] (WeldStartup.java:149) - WELD-000900: 3.1.2 (Final) 2020-06-15 19:39:36,107 INFO [main] [jboss.weld.environment.deployment.discovery.ReflectionDiscoveryStrategy] (ReflectionDiscoveryStrategy.java:49) - WELD-ENV-000014: Falling back to Java Reflection for bean-discovery-mode="annotated" discovery. Add org.jboss:jandex to the classpath to speed-up startup. 2020-06-15 19:39:36,494 INFO [main] [org.jboss.weld.bootstrap.WeldStartup] (WeldStartup.java:231) - WELD-000101: Transactional services not available. Injection of @Inject UserTransaction not available. Transactional observers will be invoked synchronously. 2020-06-15 19:39:36,720 INFO [main] [org.jboss.weld.event.ExtensionObserverMethodImpl] (ExtensionObserverMethodImpl.java:86) - WELD-000411: Observer method [BackedAnnotatedMethod] public org.gluu.service.exception.ExceptionHandlerExtension.processAnnotatedType(@Observes ProcessAnnotatedType<X>) receives events for all annotated types. Consider restricting events using @WithAnnotations or a generic type with bounds. 2020-06-15 19:39:36,725 INFO [main] [org.jboss.weld.event.ExtensionObserverMethodImpl] (ExtensionObserverMethodImpl.java:86) - WELD-000411: Observer method [BackedAnnotatedMethod] public org.gluu.service.security.SecurityExtension.processAnnotatedType(@Observes ProcessAnnotatedType<X>) receives events for all annotated types. Consider restricting events using @WithAnnotations or a generic type with bounds. 2020-06-15 19:39:36,796 WARN [main] [org.jboss.weld.bootstrap.events.BeforeBeanDiscoveryImpl] (BeforeBeanDiscoveryImpl.java:118) - WELD-000146: BeforeBeanDiscovery.addAnnotatedType(AnnotatedType) used for class org.hibernate.validator.internal.cdi.interceptor.ValidationInterceptor is deprecated from CDI 1.1! 2020-06-15 19:39:38,750 INFO [main] [org.jboss.weld.environment.jetty.JettyLegacyContainer] (JettyLegacyContainer.java:59) - WELD-ENV-001200: Jetty 7.2+ detected, CDI injection will be available in Servlets and Filters. Injection into Listeners should work on Jetty 9.1.1 and newer. 2020-06-15 19:39:38,763 WARN [weld-worker-3] [org.jboss.weld.bootstrap.BeanDeployer] (BeanDeployer.java:229) - WELD-000167: Class org.gluu.persist.couchbase.impl.CouchbaseFilterConverter is annotated with @ApplicationScoped but it does not declare an appropriate constructor therefore is not registered as a bean! 2020-06-15 19:39:40,127 INFO [main] [org.gluu.oxauth.model.util.SecurityProviderUtility] (SecurityProviderUtility.java:24) - Adding Bouncy Castle Provider 2020-06-15 19:39:40,219 INFO [main] [org.gluu.oxauth.model.config.ConfigurationFactory] (ConfigurationFactory.java:386) - Loading configuration from 'ldap' DB... 2020-06-15 19:39:41,062 INFO [main] [org.gluu.oxauth.service.AppInitializer] (AppInitializer.java:348) - Created persistenceEntryManager: org.gluu.persist.ldap.impl.LdapEntryManager@76f65a8b with operation service: org.gluu.persist.ldap.operation.impl.LdapOperationsServiceImpl@1b250e51 2020-06-15 19:39:41,262 INFO [main] [org.gluu.service.logger.LoggerService] (LoggerService.java:179) - External log configuration: 2020-06-15 19:39:41,263 INFO [main] [org.gluu.service.logger.LoggerService] (LoggerService.java:122) - Setting loggers level to: 'INFO' 2020-06-15 19:39:41,273 INFO [main] [org.gluu.oxauth.model.config.ConfigurationFactory] (ConfigurationFactory.java:191) - Configuration loaded successfully. 2020-06-15 19:39:42,008 INFO [main] [org.quartz.impl.StdSchedulerFactory] (StdSchedulerFactory.java:1220) - Using default implementation for ThreadExecutor 2020-06-15 19:39:42,034 INFO [main] [org.quartz.core.SchedulerSignalerImpl] (SchedulerSignalerImpl.java:61) - Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl 2020-06-15 19:39:42,034 INFO [main] [org.quartz.core.QuartzScheduler] (QuartzScheduler.java:229) - Quartz Scheduler v.2.3.2 created. 2020-06-15 19:39:42,035 INFO [main] [org.quartz.simpl.RAMJobStore] (RAMJobStore.java:155) - RAMJobStore initialized. 2020-06-15 19:39:42,036 INFO [main] [org.quartz.core.QuartzScheduler] (QuartzScheduler.java:294) - Scheduler meta-data: Quartz Scheduler (v2.3.2) 'oxAuthScheduler' with instanceId 'NON_CLUSTERED' Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally. NOT STARTED. Currently in standby mode. Number of jobs executed: 0 Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 5 threads. Using job-store 'org.quartz.simpl.RAMJobStore' - which does not support persistence. and is not clustered. 2020-06-15 19:39:42,037 INFO [main] [org.quartz.impl.StdSchedulerFactory] (StdSchedulerFactory.java:1374) - Quartz scheduler 'oxAuthScheduler' initialized from the specified file : 'quartz.properties' from the class resource path. 2020-06-15 19:39:42,037 INFO [main] [org.quartz.impl.StdSchedulerFactory] (StdSchedulerFactory.java:1378) - Quartz scheduler version: 2.3.2 2020-06-15 19:39:42,042 INFO [main] [org.quartz.core.QuartzScheduler] (QuartzScheduler.java:2293) - JobFactory set to: org.gluu.service.timer.JobExecutionFactory@4ffff10c 2020-06-15 19:39:42,043 INFO [main] [org.gluu.service.timer.QuartzSchedulerManager] (QuartzSchedulerManager.java:62) - Quartz scheduler manager initialized 2020-06-15 19:39:42,044 INFO [main] [org.quartz.core.QuartzScheduler] (QuartzScheduler.java:547) - Scheduler oxAuthScheduler_$_NON_CLUSTERED started. 2020-06-15 19:39:42,044 INFO [main] [org.gluu.service.timer.QuartzSchedulerManager] (QuartzSchedulerManager.java:115) - Quartz scheduler started 2020-06-15 19:39:42,080 INFO [main] [org.gluu.service.logger.LoggerService] (LoggerService.java:47) - Initializing Logger Update Timer 2020-06-15 19:39:42,152 WARN [main] [gluu.oxauth.fido2.service.mds.MdsTocService] (MdsTocService.java:101) - Fido2 configuration not exists 2020-06-15 19:39:42,154 WARN [main] [org.gluu.oxauth.fido2.service.CertificateSelector] (CertificateSelector.java:102) - Fido2 authenticator folder with certificates is not specified 2020-06-15 19:39:42,244 INFO [main] [org.jboss.weld.environment.servlet.Listener] (Listener.java:112) - WELD-ENV-001006: org.jboss.weld.environment.servlet.EnhancedListener used to initialize Weld 2020-06-15 19:39:43,756 INFO [main] [org.jboss.weld.environment.servlet.EnhancedListener] (EnhancedListener.java:75) - WELD-ENV-001009: org.jboss.weld.environment.servlet.Listener used for ServletRequest and HttpSession notifications 2020-06-15 19:39:44,387 INFO [main] [org.jboss.resteasy.spi.ResteasyDeployment] (ResteasyDeployment.java:546) - RESTEASY002225: Deploying javax.ws.rs.core.Application: class org.gluu.oxauth.service.ResteasyInitializer$Proxy$_$$_WeldClientProxy 2020-06-15 19:39:44,388 WARN [main] [org.jboss.resteasy.spi.ResteasyProviderFactory] (ResteasyProviderFactory.java:1675) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.FormUrlEncodedProvider is already registered. 2nd registration is being ignored. 2020-06-15 19:39:44,389 WARN [main] [org.jboss.resteasy.spi.ResteasyProviderFactory] (ResteasyProviderFactory.java:1675) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.DataSourceProvider is already registered. 2nd registration is being ignored. 2020-06-15 19:39:44,389 WARN [main] [org.jboss.resteasy.spi.ResteasyProviderFactory] (ResteasyProviderFactory.java:1675) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.interceptors.encoding.MessageSanitizerContainerResponseFilter is already registered. 2nd registration is being ignored. 2020-06-15 19:39:44,389 WARN [main] [org.jboss.resteasy.spi.ResteasyProviderFactory] (ResteasyProviderFactory.java:1675) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.SourceProvider is already registered. 2nd registration is being ignored. 2020-06-15 19:39:44,390 WARN [main] [org.jboss.resteasy.spi.ResteasyProviderFactory] (ResteasyProviderFactory.java:1675) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.IIOImageProvider is already registered. 2nd registration is being ignored. 2020-06-15 19:39:44,390 WARN [main] [org.jboss.resteasy.spi.ResteasyProviderFactory] (ResteasyProviderFactory.java:1675) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.sse.SseEventProvider is already registered. 2nd registration is being ignored. 2020-06-15 19:39:44,390 WARN [main] [org.jboss.resteasy.spi.ResteasyProviderFactory] (ResteasyProviderFactory.java:1675) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.jaxb.MapProvider is already registered. 2nd registration is being ignored. 2020-06-15 19:39:44,391 WARN [main] [org.jboss.resteasy.spi.ResteasyProviderFactory] (ResteasyProviderFactory.java:1675) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.FileProvider is already registered. 2nd registration is being ignored. 2020-06-15 19:39:44,392 WARN [main] [org.jboss.resteasy.spi.ResteasyProviderFactory] (ResteasyProviderFactory.java:1675) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.ReaderProvider is already registered. 2nd registration is being ignored. 2020-06-15 19:39:44,392 WARN [main] [org.jboss.resteasy.spi.ResteasyProviderFactory] (ResteasyProviderFactory.java:1675) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.DocumentProvider is already registered. 2nd registration is being ignored. 2020-06-15 19:39:44,403 WARN [main] [org.jboss.resteasy.spi.ResteasyProviderFactory] (ResteasyProviderFactory.java:1675) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.jaxb.JAXBXmlTypeProvider is already registered. 2nd registration is being ignored. 2020-06-15 19:39:44,404 WARN [main] [org.jboss.resteasy.spi.ResteasyProviderFactory] (ResteasyProviderFactory.java:1675) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.jackson.PatchMethodFilter is already registered. 2nd registration is being ignored. 2020-06-15 19:39:44,404 WARN [main] [org.jboss.resteasy.spi.ResteasyProviderFactory] (ResteasyProviderFactory.java:1675) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.DefaultNumberWriter is already registered. 2nd registration is being ignored. 2020-06-15 19:39:44,405 WARN [main] [org.jboss.resteasy.spi.ResteasyProviderFactory] (ResteasyProviderFactory.java:1675) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.InputStreamProvider is already registered. 2nd registration is being ignored. 2020-06-15 19:39:44,405 WARN [main] [org.jboss.resteasy.spi.ResteasyProviderFactory] (ResteasyProviderFactory.java:1675) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.StringTextStar is already registered. 2nd registration is being ignored. 2020-06-15 19:39:44,405 WARN [main] [org.jboss.resteasy.spi.ResteasyProviderFactory] (ResteasyProviderFactory.java:1675) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.jaxb.CollectionProvider is already registered. 2nd registration is being ignored. 2020-06-15 19:39:44,407 WARN [main] [org.jboss.resteasy.spi.ResteasyProviderFactory] (ResteasyProviderFactory.java:1675) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.jaxb.JAXBXmlSeeAlsoProvider is already registered. 2nd registration is being ignored. 2020-06-15 19:39:44,407 WARN [main] [org.jboss.resteasy.spi.ResteasyProviderFactory] (ResteasyProviderFactory.java:1675) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.jackson.UnrecognizedPropertyExceptionHandler is already registered. 2nd registration is being ignored. 2020-06-15 19:39:44,409 WARN [main] [org.jboss.resteasy.spi.ResteasyProviderFactory] (ResteasyProviderFactory.java:1675) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.DefaultTextPlain is already registered. 2nd registration is being ignored. 2020-06-15 19:39:44,411 WARN [main] [org.jboss.resteasy.spi.ResteasyProviderFactory] (ResteasyProviderFactory.java:1675) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.sse.SseEventSinkInterceptor is already registered. 2nd registration is being ignored. 2020-06-15 19:39:44,411 WARN [main] [org.jboss.resteasy.spi.ResteasyProviderFactory] (ResteasyProviderFactory.java:1675) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.sse.SseEventOutputProvider is already registered. 2nd registration is being ignored. 2020-06-15 19:39:44,416 WARN [main] [org.jboss.resteasy.spi.ResteasyProviderFactory] (ResteasyProviderFactory.java:1675) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.jaxb.JAXBElementProvider is already registered. 2nd registration is being ignored. 2020-06-15 19:39:44,416 WARN [main] [org.jboss.resteasy.spi.ResteasyProviderFactory] (ResteasyProviderFactory.java:1675) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.DefaultBooleanWriter is already registered. 2nd registration is being ignored. 2020-06-15 19:39:44,417 WARN [main] [org.jboss.resteasy.spi.ResteasyProviderFactory] (ResteasyProviderFactory.java:1675) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.jaxb.JAXBXmlRootElementProvider is already registered. 2nd registration is being ignored. 2020-06-15 19:39:44,417 WARN [main] [org.jboss.resteasy.spi.ResteasyProviderFactory] (ResteasyProviderFactory.java:1675) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.jaxb.XmlJAXBContextFinder is already registered. 2nd registration is being ignored. 2020-06-15 19:39:44,417 WARN [main] [org.jboss.resteasy.spi.ResteasyProviderFactory] (ResteasyProviderFactory.java:1675) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.JaxrsFormProvider is already registered. 2nd registration is being ignored. 2020-06-15 19:39:44,429 WARN [main] [org.jboss.resteasy.spi.ResteasyProviderFactory] (ResteasyProviderFactory.java:1675) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.jackson.ResteasyJackson2Provider is already registered. 2nd registration is being ignored. 2020-06-15 19:40:42,201 INFO [ForkJoinPool.commonPool-worker-3] [org.gluu.oxauth.service.ApplicationFactory] (ApplicationFactory.java:84) - Cache configuration: CacheConfiguration{cacheProviderType=NATIVE_PERSISTENCE, memcachedConfiguration=MemcachedConfiguration{servers='localhost:11211', maxOperationQueueLength=100000, bufferSize=32768, defaultPutExpiration=60, connectionFactoryType=DEFAULT}, redisConfiguration=RedisConfiguration{servers='localhost:6379', defaultPutExpiration=60, redisProviderType=STANDALONE, useSSL=false, sslTrustStoreFilePath=, sentinelMasterGroupName=, maxIdleConnections=10, maxTotalConnections=500, connectionTimeout=3000, soTimeout=3000, maxRetryAttempts=5}, inMemoryConfiguration=InMemoryConfiguration{defaultPutExpiration=60}, nativePersistenceConfiguration=NativePersistenceConfiguration [defaultPutExpiration=60, defaultCleanupBatchSize=10000, deleteExpiredOnGetRequest=false, baseDn=o=gluu]} 2020-06-15 19:40:42,209 INFO [ForkJoinPool.commonPool-worker-3] [org.gluu.service.cache.NativePersistenceCacheProvider] (NativePersistenceCacheProvider.java:75) - Created NATIVE_PERSISTENCE cache provider. `baseDn`: ou=cache,o=gluu 2020-06-15 19:40:42,228 ERROR [ForkJoinPool.commonPool-worker-3] [org.gluu.oxauth.service.CleanerTimer] (CleanerTimer.java:171) - Failed to process clean up. java.lang.NullPointerException: null at org.gluu.oxauth.fido2.persist.RegistrationPersistenceService.getExpiredRegistrationFilter(RegistrationPersistenceService.java:256) ~[oxauth-fido2-server-4.1.1.Final.jar:?] at org.gluu.oxauth.fido2.persist.RegistrationPersistenceService.cleanup(RegistrationPersistenceService.java:234) ~[oxauth-fido2-server-4.1.1.Final.jar:?] at org.gluu.oxauth.fido2.persist.RegistrationPersistenceService$Proxy$_$$_WeldClientProxy.cleanup(Unknown Source) ~[oxauth-fido2-server-4.1.1.Final.jar:?] at org.gluu.oxauth.service.CleanerTimer.processImpl(CleanerTimer.java:166) [classes/:?] at org.gluu.oxauth.service.CleanerTimer$Proxy$_$$_WeldSubclass.processImpl(Unknown Source) [classes/:?] at org.gluu.oxauth.service.CleanerTimer.process(CleanerTimer.java:113) [classes/:?] at org.gluu.oxauth.service.CleanerTimer$Proxy$_$$_WeldSubclass.process$$super(Unknown Source) [classes/:?] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_222] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_222] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_222] at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_222] at org.jboss.weld.interceptor.proxy.TerminalAroundInvokeInvocationContext.proceedInternal(TerminalAroundInvokeInvocationContext.java:51) [weld-core-impl-3.1.2.Final.jar:3.1.2.Final] at org.jboss.weld.interceptor.proxy.AroundInvokeInvocationContext.proceed(AroundInvokeInvocationContext.java:78) [weld-core-impl-3.1.2.Final.jar:3.1.2.Final] at org.gluu.service.cdi.async.AsynchronousInterceptor$1.get(AsynchronousInterceptor.java:36) [oxcore-service-4.1.1.Final.jar:?] at java.util.concurrent.CompletableFuture$AsyncSupply.run(CompletableFuture.java:1590) [?:1.8.0_222] at java.util.concurrent.CompletableFuture$AsyncSupply.exec(CompletableFuture.java:1582) [?:1.8.0_222] at java.util.concurrent.ForkJoinTask.doExec(ForkJoinTask.java:289) [?:1.8.0_222] at java.util.concurrent.ForkJoinPool$WorkQueue.runTask(ForkJoinPool.java:1056) [?:1.8.0_222] at java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1692) [?:1.8.0_222] at java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:157) [?:1.8.0_222] ```

By Aliaksandr Samuseu staff 15 Jun 2020 at 4:45 p.m. CDT

Aliaksandr Samuseu gravatar
Hi, Tony. Thanks for reporting it, we'll look into it.

By Aliaksandr Samuseu staff 19 Jun 2020 at 11:10 a.m. CDT

Aliaksandr Samuseu gravatar
Hi, Tony. Sorry for the delayed answer, could you let us know what components you selected when you were running `setup.py`? In particular, did you choose Casa and Passport?