By: Patryk Su user 28 Jun 2022 at 5:48 a.m. CDT

13 Responses
Patryk Su gravatar
Hi, I installed a new instance of Gluu-server 4.4.0. Everything worked out beautifully. However, today after coming to work and trying to connect to the UI, I get an Oops message, something wrong happened. Yesterday I only started LDAP configuration but had to stop due to other activities. What could be the reason for this error. What logs to share? Regards

By Mobarak Hosen Shakil staff 28 Jun 2022 at 8:28 p.m. CDT

Mobarak Hosen Shakil gravatar
Hi Patryk, *LDAP should be running for gluu server.* > Yesterday I only started LDAP configuration but had to stop due to other activities If possible then, check `oxauth`, `identity` and `opendj` logs. These are the basic services that needs to run to make gluu server working.

By Patryk Su user 29 Jun 2022 at 2:47 a.m. CDT

Patryk Su gravatar
Hi Shakil yes of course all of the services you mentioned are started with a status of 0. Apache is trying to connect to /identity/login.htm, but gets a 302 error and returns an error page.

By Patryk Su user 29 Jun 2022 at 2:50 a.m. CDT

Patryk Su gravatar
What logs should I send you for analysis?

By Mobarak Hosen Shakil staff 29 Jun 2022 at 3:12 a.m. CDT

Mobarak Hosen Shakil gravatar
Please share, `oxauth.log` and `oxtrust.log`. Are `OpenDJ`, `apache`, `oxauth` and `identity` running? Kindly share status each of them.

By Patryk Su user 29 Jun 2022 at 3:28 a.m. CDT

Patryk Su gravatar
``` * opendj.service - OpenDJ Directory Service Loaded: loaded (/etc/systemd/system/opendj.service; enabled; vendor preset: enabled) Drop-In: /etc/systemd/system/opendj.service.d `-override.conf Active: active (running) since Wed 2022-06-29 09:40:29 CEST; 41min ago Process: 7436 ExecStart=/opt/opendj/bin/start-ds (code=exited, status=0/SUCCESS) Main PID: 7483 (java) CGroup: /system.slice/opendj.service `-7483 /opt/jre/bin/java -server -Xms512m -Xmx1280m -XX:+UseCompressedOops -Dorg.opends.server.scriptName=start-ds org.opends.server.core.DirectoryServer --configFile /opt/opendj/config/config.ldif Jun 29 09:40:24 my.gluu.host start-ds[7484]: [29/Jun/2022:07:40:24 +0000] category=JVM severity=NOTICE msgID=19 msg=JVM Arguments: "-Xms512m", "-Xmx1280m", "-XX:+UseCompressedOops", "-Dorg.opends.server.scriptName=start-ds" Jun 29 09:40:25 my.gluu.host start-ds[7484]: [29/Jun/2022:07:40:25 +0000] category=BACKEND severity=NOTICE msgID=513 msg=The database backend site containing 2 entries has started Jun 29 09:40:26 my.gluu.host start-ds[7484]: [29/Jun/2022:07:40:26 +0000] category=BACKEND severity=NOTICE msgID=513 msg=The database backend metric containing 3925 entries has started Jun 29 09:40:27 my.gluu.host start-ds[7484]: [29/Jun/2022:07:40:27 +0000] category=BACKEND severity=NOTICE msgID=513 msg=The database backend userRoot containing 186 entries has started Jun 29 09:40:27 my.gluu.host start-ds[7484]: [29/Jun/2022:07:40:27 +0000] category=EXTENSIONS severity=NOTICE msgID=221 msg=DIGEST-MD5 SASL mechanism using a server fully qualified domain name of: localhost Jun 29 09:40:27 my.gluu.host start-ds[7484]: [29/Jun/2022:07:40:27 +0000] category=CORE severity=NOTICE msgID=135 msg=The Directory Server has started successfully Jun 29 09:40:27 my.gluu.host start-ds[7484]: [29/Jun/2022:07:40:27 +0000] category=CORE severity=NOTICE msgID=139 msg=The Directory Server has sent an alert notification generated by class org.opends.server.core.DirectoryServer (alert type org.opends.server.DirectoryServerStarted, alert ID org.opends.messages.core-135): The Directory Serv>er (alert type org.opends.server.DirectoryServerStarted, alert ID org.opends.messages.core-135): The Directory Server has started successfully Jun 29 09:40:28 my.gluu.host start-ds[7484]: [29/Jun/2022:07:40:28 +0000] category=PROTOCOL severity=NOTICE msgID=276 msg=Started listening for new connections on LDAPS Connection Handler localhost port 1636 Jun 29 09:40:28 my.gluu.host start-ds[7484]: [29/Jun/2022:07:40:28 +0000] category=PROTOCOL severity=NOTICE msgID=276 msg=Started listening for new connections on Administration Connector localhost port 4444 Jun 29 09:40:29 my.gluu.host systemd[1]: Started OpenDJ Directory Service. * identity.service - Identity service Loaded: loaded (/etc/systemd/system/identity.service; enabled; vendor preset: enabled) Drop-In: /etc/systemd/system/identity.service.d `-override.conf Active: active (running) since Wed 2022-06-29 09:38:39 CEST; 46min ago Process: 7158 ExecStart=/opt/dist/scripts/identity start (code=exited, status=0/SUCCESS) Main PID: 7184 (java) CGroup: /system.slice/identity.service `-7184 /opt/jre/bin/java -server -Xms128m -Xmx835m -XX:MaxMetaspaceSize=512m -XX:+DisableExplicitGC -Dgluu.base=/etc/gluu -Dserver.base=/opt/gluu/jetty/identity -Dlog.base=/opt/gluu/jetty/identity -Dpython.home=/opt/jy> Jun 29 09:37:59 my.gluu.host systemd[1]: Stopped Identity service. Jun 29 09:37:59 my.gluu.host systemd[1]: Starting Identity service... Jun 29 09:38:03 my.gluu.host identity[7158]: Starting Jetty: . Jun 29 09:38:03 my.gluu.host identity[7184]: 2022-06-29 07:38:03.982:INFO :oe.jetty:main: Console stderr/stdout captured to| /opt/gluu/jetty/identity/logs/2022_06_29.jetty.log Jun 29 09:38:39 my.gluu.host identity[7158]: . . . . . . . . OK Wed Jun 29 09:38:39 CEST 2022 Jun 29 09:38:39 my.gluu.host systemd[1]: Started Identity service. * apache2.service - The Apache HTTP Server Loaded: loaded (/lib/systemd/system/apache2.service; enabled; vendor preset: enabled) Drop-In: /etc/systemd/system/apache2.service.d `-override.conf Active: active (running) since Wed 2022-06-29 00:01:14 CEST; 10h ago Docs: https://httpd.apache.org/docs/2.4/ Process: 2210 ExecStart=/usr/sbin/apachectl start (code=exited, status=0/SUCCESS) Main PID: 2214 (apache2) CGroup: /system.slice/apache2.service |-2214 /usr/sbin/apache2 -k start |-2215 /usr/sbin/apache2 -k start `-2216 /usr/sbin/apache2 -k start Jun 29 00:01:13 my.gluu.host systemd[1]: Stopped The Apache HTTP Server. Jun 29 00:01:13 my.gluu.host systemd[1]: Starting The Apache HTTP Server... Jun 29 00:01:14 my.gluu.host systemd[1]: Started The Apache HTTP Server. * oxauth.service - Oxauth service Loaded: loaded (/etc/systemd/system/oxauth.service; enabled; vendor preset: enabled) Drop-In: /etc/systemd/system/oxauth.service.d `-override.conf Active: active (running) since Wed 2022-06-29 09:32:15 CEST; 55min ago Process: 6889 ExecStart=/opt/dist/scripts/oxauth start (code=exited, status=0/SUCCESS) Main PID: 6915 (java) CGroup: /system.slice/oxauth.service `-6915 /opt/jre/bin/java -server -Xms128m -Xmx557m -XX:+DisableExplicitGC -Dgluu.base=/etc/gluu -Dserver.base=/opt/gluu/jetty/oxauth -Dlog.base=/opt/gluu/jetty/oxauth -Dpython.home=/opt/jython -Djetty.home=/opt/jetty -> Jun 29 09:31:39 my.gluu.host[1]: oxauth.service: Failed with result 'signal'. Jun 29 09:31:39 my.gluu.host[1]: Stopped Oxauth service. Jun 29 09:31:39 my.gluu.host[1]: Starting Oxauth service... Jun 29 09:31:43 my.gluu.host oxauth[6889]: Starting Jetty: . Jun 29 09:31:43 my.gluu.host oxauth[6915]: 2022-06-29 07:31:43.881:INFO :oe.jetty:main: Console stderr/stdout captured to| /opt/gluu/jetty/oxauth/logs/2022_06_29.jetty.log Jun 29 09:32:15 my.gluu.host oxauth[6889]: . . . . . . . OK Wed Jun 29 09:32:15 CEST 2022 Jun 29 09:32:15 my.gluu.host systemd[1]: Started Oxauth service. ```

By Patryk Su user 29 Jun 2022 at 3:42 a.m. CDT

Patryk Su gravatar
here is oxtrust.log ``` 2022-06-29 10:32:47,943 INFO [main] [org.gluu.util.security.SecurityProviderUtility] (SecurityProviderUtility.java:284) - Adding Bouncy Castle Provider 2022-06-29 10:32:48,107 INFO [main] [org.gluu.util.security.SecurityProviderUtility] (SecurityProviderUtility.java:289) - Provider 'BC' with version 1.7 is added 2022-06-29 10:32:48,113 DEBUG [main] [org.gluu.oxtrust.service.AppInitializer] (AppInitializer.java:184) - Initializing application services 2022-06-29 10:32:48,117 INFO [main] [org.gluu.oxtrust.util.BuildVersionService] (BuildVersionService.java:80) - Root element :beans 2022-06-29 10:32:48,122 INFO [main] [org.gluu.oxtrust.service.AppInitializer] (AppInitializer.java:261) - Build date 2022-04-29 11:15. Code revision 0acd2ccdd2fa2e46ffe927eb06fcf36825fe3543 on 2022-04-29T09:58:52+0000. Build 704 2022-06-29 10:32:48,127 INFO [main] [org.gluu.service.config.ConfigurationFactory] (ConfigurationFactory.java:127) - Creating oxTrustConfiguration 2022-06-29 10:32:48,180 DEBUG [main] [org.gluu.service.config.ConfigurationFactory] (ConfigurationFactory.java:273) - ########## fileName = /etc/gluu/conf/gluu.properties 2022-06-29 10:32:48,188 DEBUG [main] [org.gluu.service.config.ConfigurationFactory] (ConfigurationFactory.java:274) - ########## oxtrust_ConfigurationEntryDN = ou=oxtrust,ou=configuration,o=gluu 2022-06-29 10:32:48,275 DEBUG [main] [org.gluu.service.config.ConfigurationFactory] (ConfigurationFactory.java:273) - ########## fileName = /etc/gluu/conf/salt 2022-06-29 10:32:48,275 DEBUG [main] [org.gluu.service.config.ConfigurationFactory] (ConfigurationFactory.java:274) - ########## oxtrust_ConfigurationEntryDN = null 2022-06-29 10:32:48,276 INFO [main] [org.gluu.service.config.ConfigurationFactory] (ConfigurationFactory.java:289) - Loading configuration from 'ldap' DB... 2022-06-29 10:32:50,168 INFO [main] [org.gluu.oxtrust.service.AppInitializer] (AppInitializer.java:305) - Created persistenceEntryManager: org.gluu.persist.ldap.impl.LdapEntryManager@7021ab0e with operation service: org.gluu.persist.ldap.operation.impl.LdapOperationServiceImpl@6749add0 2022-06-29 10:32:50,573 INFO [main] [org.gluu.service.logger.LoggerService] (LoggerService.java:159) - External log configuration: null 2022-06-29 10:32:50,573 INFO [main] [org.gluu.service.logger.LoggerService] (LoggerService.java:178) - Reloading log4j2 configuration 2022-06-29 10:32:50,686 INFO [main] [org.gluu.service.logger.LoggerService] (LoggerService.java:142) - Setting layout and loggers level to 'TEXT`, `INFO' after configuration update 2022-06-29 10:32:50,775 INFO [main] [org.gluu.service.logger.LoggerService] (LoggerService.java:205) - Updated log level of '31' loggers to INFO 2022-06-29 10:32:50,775 INFO [main] [org.gluu.service.config.ConfigurationFactory] (ConfigurationFactory.java:150) - Configuration loaded successfully. 2022-06-29 10:32:50,790 DEBUG [main] [org.gluu.oxtrust.service.TemplateService] (TemplateService.java:107) - jar path =jar:file:/opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/lib/oxtrust-configuration-4.4.0.Final.jar, 2022-06-29 10:32:50,790 DEBUG [main] [org.gluu.oxtrust.service.TemplateService] (TemplateService.java:124) - class_path.resource.loader.path = META-INF/shibboleth3/idp, META-INF/shibboleth3/sp, META-INF/ldif, META-INF/shibboleth3/idp/MetadataFilter, META-INF/shibboleth3/idp/ProfileConfiguration, META-INF/template/conf, META-INF/template/shibboleth3 2022-06-29 10:32:50,791 DEBUG [main] [org.gluu.oxtrust.service.TemplateService] (TemplateService.java:116) - file.resource.loader.path = /opt/gluu/jetty/identity/conf/shibboleth3/idp, /opt/gluu/jetty/identity/conf/shibboleth3/sp, /opt/gluu/jetty/identity/conf/ldif, /opt/gluu/jetty/identity/conf/shibboleth3/idp/MetadataFilter, /opt/gluu/jetty/identity/conf/shibboleth3/idp/ProfileConfiguration, /opt/gluu/jetty/identity/conf/template/conf, /opt/gluu/jetty/identity/conf/template/shibboleth3 2022-06-29 10:32:52,094 INFO [main] [org.gluu.oxtrust.service.ShibbolethInitializer] (ShibbolethInitializer.java:43) - IDP config generation is set to true 2022-06-29 10:32:52,126 INFO [main] [org.gluu.oxtrust.service.ShibbolethInitializer] (ShibbolethInitializer.java:47) - ########## shibbolethVersion = v3 2022-06-29 10:32:52,575 INFO [main] [org.gluu.oxtrust.service.Shibboleth3ConfService] (Shibboleth3ConfService.java:1233) - >>>>>>>>>> IN Shibboleth3ConfService.generateMetadataFiles()... 2022-06-29 10:32:52,586 DEBUG [main] [gluu.service.document.store.provider.DocumentStoreProviderFactory] (DocumentStoreProviderFactory.java:33) - Started to create document store provider 2022-06-29 10:32:52,594 INFO [main] [org.gluu.oxtrust.service.ApplicationFactory] (ApplicationFactory.java:92) - Document store configuration: DocumentStoreConfiguration [documentStoreType=LOCAL, localConfiguration=LocalConfiguration [baseLocation=/], jcaConfiguration=JcaDocumentStoreConfiguration [serverUrl=http://localhost:8080/rmi, workspaceName=default, connectionTimeout=15, userId=admin], webDavConfiguration=null] 2022-06-29 10:32:52,598 DEBUG [main] [gluu.service.document.store.provider.LocalDocumentStoreProvider] (LocalDocumentStoreProvider.java:50) - Starting LocalDocumentStoreProvider ... 2022-06-29 10:32:52,598 DEBUG [main] [gluu.service.document.store.provider.LocalDocumentStoreProvider] (LocalDocumentStoreProvider.java:139) - Read document: '/etc/certs/idp-signing.crt' 2022-06-29 10:32:52,625 DEBUG [main] [gluu.service.document.store.provider.LocalDocumentStoreProvider] (LocalDocumentStoreProvider.java:139) - Read document: '/etc/certs/idp-encryption.crt' 2022-06-29 10:32:52,653 DEBUG [main] [gluu.service.document.store.provider.LocalDocumentStoreProvider] (LocalDocumentStoreProvider.java:97) - Save document: '/opt/shibboleth-idp/metadata/idp-metadata.xml' 2022-06-29 10:32:52,657 INFO [main] [org.gluu.oxtrust.service.Shibboleth3ConfService] (Shibboleth3ConfService.java:1306) - >>>>>>>>>> LEAVING Shibboleth3ConfService.generateMetadataFiles()... 2022-06-29 10:32:52,658 INFO [main] [org.gluu.oxtrust.service.Shibboleth3ConfService] (Shibboleth3ConfService.java:286) - >>>>>>>>>> IN Shibboleth3ConfService.generateConfigurationFiles()... 2022-06-29 10:32:52,667 DEBUG [main] [org.gluu.service.cache.CacheProviderFactory] (CacheProviderFactory.java:32) - Started to create cache provider 2022-06-29 10:32:52,676 INFO [main] [org.gluu.oxtrust.service.ApplicationFactory] (ApplicationFactory.java:74) - 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=, sslKeyStoreFilePath=, 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]} 2022-06-29 10:32:52,687 INFO [main] [org.gluu.service.cache.NativePersistenceCacheProvider] (NativePersistenceCacheProvider.java:79) - Created NATIVE_PERSISTENCE cache provider. `baseDn`: ou=cache,o=gluu 2022-06-29 10:32:52,898 DEBUG [main] [org.gluu.oxtrust.service.AttributeService] (AttributeService.java:96) - objectClassTypes=[gluuCustomPerson, gluuPerson, eduPerson] 2022-06-29 10:32:53,150 INFO [main] [org.gluu.oxtrust.service.CASService] (CASService.java:51) - loadCASConfiguration() call 2022-06-29 10:32:53,167 INFO [main] [org.gluu.oxtrust.service.Shibboleth3ConfService] (Shibboleth3ConfService.java:639) - add ShibbolethCASProtocolConfiguration parameters 2022-06-29 10:32:53,380 DEBUG [main] [org.gluu.oxtrust.service.Shibboleth3ConfService] (Shibboleth3ConfService.java:677) - >>>>>>>>>> Shibboleth3ConfService.initAttributeResolverParamMap() - Persistance type: 'ldap' 2022-06-29 10:32:53,634 DEBUG [main] [gluu.service.document.store.provider.LocalDocumentStoreProvider] (LocalDocumentStoreProvider.java:97) - Save document: '/opt/shibboleth-idp/conf/metadata-providers.xml' 2022-06-29 10:32:53,635 DEBUG [main] [gluu.service.document.store.provider.LocalDocumentStoreProvider] (LocalDocumentStoreProvider.java:97) - Save document: '/opt/shibboleth-idp/conf/attribute-resolver.xml' 2022-06-29 10:32:53,636 DEBUG [main] [gluu.service.document.store.provider.LocalDocumentStoreProvider] (LocalDocumentStoreProvider.java:97) - Save document: '/opt/shibboleth-idp/conf/attribute-filter.xml' 2022-06-29 10:32:53,636 DEBUG [main] [gluu.service.document.store.provider.LocalDocumentStoreProvider] (LocalDocumentStoreProvider.java:97) - Save document: '/opt/shibboleth-idp/conf/relying-party.xml' 2022-06-29 10:32:53,637 DEBUG [main] [gluu.service.document.store.provider.LocalDocumentStoreProvider] (LocalDocumentStoreProvider.java:97) - Save document: '/opt/shibboleth-idp/conf/cas-protocol.xml' 2022-06-29 10:32:53,638 DEBUG [main] [gluu.service.document.store.provider.LocalDocumentStoreProvider] (LocalDocumentStoreProvider.java:97) - Save document: '/opt/shibboleth-idp/sp/shibboleth2.xml' 2022-06-29 10:32:53,649 DEBUG [main] [gluu.service.document.store.provider.LocalDocumentStoreProvider] (LocalDocumentStoreProvider.java:97) - Save document: '/opt/shibboleth-idp/conf/saml-nameid.xml' 2022-06-29 10:32:53,649 DEBUG [main] [gluu.service.document.store.provider.LocalDocumentStoreProvider] (LocalDocumentStoreProvider.java:97) - Save document: '/opt/shibboleth-idp/conf/saml-nameid.properties' 2022-06-29 10:32:53,650 INFO [main] [org.gluu.oxtrust.service.Shibboleth3ConfService] (Shibboleth3ConfService.java:372) - >>>>>>>>>> LEAVING Shibboleth3ConfService.generateConfigurationFiles()... 2022-06-29 10:32:53,659 INFO [main] [org.gluu.oxtrust.service.Shibboleth3ConfService] (Shibboleth3ConfService.java:196) - >>>>>>>>>> IN generateConfigurationFiles(SamlAcr[] acrs)... 2022-06-29 10:32:53,668 DEBUG [main] [gluu.service.document.store.provider.LocalDocumentStoreProvider] (LocalDocumentStoreProvider.java:97) - Save document: '/opt/shibboleth-idp/conf/authn/oxauth-supported-principals.xml' 2022-06-29 10:32:53,674 INFO [main] [org.gluu.oxtrust.service.Shibboleth3ConfService] (Shibboleth3ConfService.java:214) - >>>>>>>>>> LEAVING generateConfigurationFiles(SamlAcr[] acrs)... 2022-06-29 10:32:53,701 INFO [main] [org.gluu.oxtrust.service.Shibboleth3ConfService] (Shibboleth3ConfService.java:227) - >>>>>>>>>> IN Shibboleth3ConfService.generateGluuAttributeRulesFile() ... 2022-06-29 10:32:53,906 INFO [main] [org.gluu.oxtrust.service.Shibboleth3ConfService] (Shibboleth3ConfService.java:275) - Gluu attributes rules file path is /opt/shibboleth-idp/conf/attributes/gluu-attribute-rules.xml 2022-06-29 10:32:53,906 DEBUG [main] [gluu.service.document.store.provider.LocalDocumentStoreProvider] (LocalDocumentStoreProvider.java:97) - Save document: '/opt/shibboleth-idp/conf/attributes/gluu-attribute-rules.xml' 2022-06-29 10:32:53,907 INFO [main] [org.gluu.oxtrust.service.Shibboleth3ConfService] (Shibboleth3ConfService.java:277) - >>>>>>>>>>> LEAVING Shibboleth3ConfService.generateGluuAttributeRulesFile() ... 2022-06-29 10:32:53,908 INFO [main] [org.gluu.oxtrust.service.ShibbolethReloadService] (ShibbolethReloadService.java:65) - >>>>> Initializing ShibbolethReloadService 2022-06-29 10:32:53,952 INFO [main] [org.gluu.oxtrust.service.ShibbolethReloadService] (ShibbolethReloadService.java:76) - >>>> ShibbolethReloadService Initialization complete 2022-06-29 10:32:54,488 DEBUG [main] [org.gluu.oxtrust.service.ShibbolethReloadService] (ShibbolethReloadService.java:129) - Service reload response for url https://localhost/idp/profile/admin/reload-service?id=shibboleth.AttributeRegistryService is Configuration reloaded for 'shibboleth.AttributeRegistryService' 2022-06-29 10:32:54,489 DEBUG [main] [org.gluu.oxtrust.service.ShibbolethReloadService] (ShibbolethReloadService.java:131) - Service reload operation for url https://localhost/idp/profile/admin/reload-service?id=shibboleth.AttributeRegistryService succeeded 2022-06-29 10:32:54,583 INFO [main] [org.quartz.impl.StdSchedulerFactory] (StdSchedulerFactory.java:1220) - Using default implementation for ThreadExecutor 2022-06-29 10:32:54,614 INFO [main] [org.quartz.core.SchedulerSignalerImpl] (SchedulerSignalerImpl.java:61) - Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl 2022-06-29 10:32:54,614 INFO [main] [org.quartz.core.QuartzScheduler] (QuartzScheduler.java:229) - Quartz Scheduler v.2.3.2 created. 2022-06-29 10:32:54,616 INFO [main] [org.quartz.simpl.RAMJobStore] (RAMJobStore.java:155) - RAMJobStore initialized. 2022-06-29 10:32:54,617 INFO [main] [org.quartz.core.QuartzScheduler] (QuartzScheduler.java:294) - Scheduler meta-data: Quartz Scheduler (v2.3.2) 'oxTrustScheduler' 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. 2022-06-29 10:32:54,618 INFO [main] [org.quartz.impl.StdSchedulerFactory] (StdSchedulerFactory.java:1374) - Quartz scheduler 'oxTrustScheduler' initialized from the specified file : 'quartz.properties' from the class resource path. 2022-06-29 10:32:54,618 INFO [main] [org.quartz.impl.StdSchedulerFactory] (StdSchedulerFactory.java:1378) - Quartz scheduler version: 2.3.2 2022-06-29 10:32:54,620 INFO [main] [org.quartz.core.QuartzScheduler] (QuartzScheduler.java:2293) - JobFactory set to: org.gluu.service.timer.JobExecutionFactory@3f6be18d 2022-06-29 10:32:54,621 INFO [main] [org.gluu.service.timer.QuartzSchedulerManager] (QuartzSchedulerManager.java:62) - Quartz scheduler manager initialized 2022-06-29 10:32:54,621 INFO [main] [org.quartz.core.QuartzScheduler] (QuartzScheduler.java:547) - Scheduler oxTrustScheduler_$_NON_CLUSTERED started. 2022-06-29 10:32:54,622 INFO [main] [org.gluu.service.timer.QuartzSchedulerManager] (QuartzSchedulerManager.java:115) - Quartz scheduler started 2022-06-29 10:32:54,644 INFO [main] [org.gluu.service.logger.LoggerService] (LoggerService.java:59) - Initializing Logger Update Timer 2022-06-29 10:32:54,650 DEBUG [main] [org.gluu.oxtrust.service.MetadataValidationTimer] (MetadataValidationTimer.java:84) - Initializing Metadata Validation Timer 2022-06-29 10:32:54,654 DEBUG [main] [org.gluu.oxtrust.service.EntityIDMonitoringService] (EntityIDMonitoringService.java:73) - Initializing EntityId Monitoring Timer 2022-06-29 10:32:54,692 DEBUG [main] [org.gluu.oxtrust.service.CleanerTimer] (CleanerTimer.java:76) - Initializing Cleaner Timer 2022-06-29 10:32:54,709 DEBUG [main] [org.gluu.oxtrust.service.StatusCheckerDaily] (StatusCheckerDaily.java:57) - Initializing Daily Status Cheker Timer 2022-06-29 10:32:54,710 DEBUG [main] [org.gluu.oxtrust.service.StatusCheckerTimer] (StatusCheckerTimer.java:93) - Initializing Daily Status Cheker Timer 2022-06-29 10:32:54,711 INFO [main] [org.gluu.oxtrust.service.LogFileSizeChecker] (LogFileSizeChecker.java:63) - Initializing Log File Size Checker Timer 2022-06-29 10:32:54,714 DEBUG [main] [org.gluu.oxtrust.service.TranscodingRulesUpdater] (TranscodingRulesUpdater.java:52) - Initializing Shibboleth Transcoding Rules Updater 2022-06-29 10:32:54,721 DEBUG [main] [org.gluu.oxtrust.auth.uma.UmaPermissionService] (UmaPermissionService.java:84) - ##### Initializing custom ClientExecutor... 2022-06-29 10:32:54,771 INFO [main] [org.gluu.oxtrust.auth.uma.UmaPermissionService] (UmaPermissionService.java:99) - ##### Initializing custom ClientExecutor DONE 2022-06-29 10:32:55,025 INFO [main] [org.gluu.oxtrust.auth.uma.UmaPermissionService] (UmaPermissionService.java:125) - ##### Getting UMA metadata ... 2022-06-29 10:32:55,945 INFO [main] [org.gluu.oxtrust.auth.uma.UmaPermissionService] (UmaPermissionService.java:154) - ##### Getting UMA metadata ... DONE 2022-06-29 10:32:57,993 DEBUG [main] [org.gluu.jsf2.customization.FacesLocalizationConfigPopulator] (FacesLocalizationConfigPopulator.java:44) - Starting localization configuration populator 2022-06-29 10:32:57,994 DEBUG [main] [org.gluu.jsf2.customization.FacesLocalizationConfigPopulator] (FacesLocalizationConfigPopulator.java:48) - Adding localizations from custom dir folder: /opt/gluu/jetty/identity/custom/i18n 2022-06-29 10:32:58,017 DEBUG [main] [org.gluu.jsf2.customization.FacesLocalizationConfigPopulator] (FacesLocalizationConfigPopulator.java:57) - Adding localizations from application resurces 2022-06-29 10:32:58,026 DEBUG [main] [org.gluu.jsf2.customization.FacesLocalizationConfigPopulator] (FacesLocalizationConfigPopulator.java:83) - Found '3' language files 2022-06-29 10:32:58,026 INFO [main] [org.gluu.jsf2.customization.FacesLocalizationConfigPopulator] (FacesLocalizationConfigPopulator.java:95) - Adding languages '[fr, ru, en]' from dir folder: /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/ 2022-06-29 10:32:58,027 DEBUG [main] [org.gluu.jsf2.customization.FacesLocalizationConfigPopulator] (FacesLocalizationConfigPopulator.java:63) - Added 3 application localizations from war folder: /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/ 2022-06-29 10:32:58,028 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:47) - Starting configuration populator 2022-06-29 10:32:58,028 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:51) - Adding navigation rules from custom dir folder: /opt/gluu/jetty/identity/custom/pages 2022-06-29 10:32:58,033 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:82) - Found '0' navigation rules files 2022-06-29 10:32:58,035 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:60) - Adding navigation rules from application resurces 2022-06-29 10:32:58,038 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:82) - Found '80' navigation rules files 2022-06-29 10:32:58,042 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/home.navigation.xml 2022-06-29 10:32:58,044 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/status.navigation.xml 2022-06-29 10:32:58,045 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/scopeDescriptionIcon.navigation.xml 2022-06-29 10:32:58,046 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/applianceStatus.navigation.xml 2022-06-29 10:32:58,048 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/inventory.navigation.xml 2022-06-29 10:32:58,049 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/updateConfiguration.navigation.xml 2022-06-29 10:32:58,050 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/resetPasswordSuccess.navigation.xml 2022-06-29 10:32:58,052 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/addProvider.navigation.xml 2022-06-29 10:32:58,053 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/resource.navigation.xml 2022-06-29 10:32:58,054 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/updateOrganization.navigation.xml 2022-06-29 10:32:58,056 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/addAttribute.navigation.xml 2022-06-29 10:32:58,057 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/logout.navigation.xml 2022-06-29 10:32:58,059 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/configureLogViewer.navigation.xml 2022-06-29 10:32:58,061 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/resetPassword.navigation.xml 2022-06-29 10:32:58,062 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/cacheRefresh.navigation.xml 2022-06-29 10:32:58,063 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/scopeDescriptionFile.navigation.xml 2022-06-29 10:32:58,065 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/updateAttribute.navigation.xml 2022-06-29 10:32:58,066 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/addRadiusClient.navigation.xml 2022-06-29 10:32:58,068 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/addUmaScope.navigation.xml 2022-06-29 10:32:58,069 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/modifyScopeDescription.navigation.xml 2022-06-29 10:32:58,071 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/configureNameId.navigation.xml 2022-06-29 10:32:58,072 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/trustRelationships.navigation.xml 2022-06-29 10:32:58,073 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/viewLogFile.navigation.xml 2022-06-29 10:32:58,075 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/addTrustRelationship.navigation.xml 2022-06-29 10:32:58,076 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/viewWhitePages.navigation.xml 2022-06-29 10:32:58,077 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/sectorIdentifierInventory.navigation.xml 2022-06-29 10:32:58,079 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/attributeExport.navigation.xml 2022-06-29 10:32:58,080 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/editSPPool.navigation.xml 2022-06-29 10:32:58,082 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/spPools.navigation.xml 2022-06-29 10:32:58,083 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/radiusClientInventory.navigation.xml 2022-06-29 10:32:58,085 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/editProvider.navigation.xml 2022-06-29 10:32:58,086 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/addSectorIdentifier.navigation.xml 2022-06-29 10:32:58,087 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/error.navigation.xml 2022-06-29 10:32:58,089 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/attributeInventory.navigation.xml 2022-06-29 10:32:58,090 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/updateSectorIdentifier.navigation.xml 2022-06-29 10:32:58,092 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/addSPstep1.navigation.xml 2022-06-29 10:32:58,093 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/personInventory.navigation.xml 2022-06-29 10:32:58,094 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/editSelector.navigation.xml 2022-06-29 10:32:58,096 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/manageRegistration.navigation.xml 2022-06-29 10:32:58,097 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/personImport.navigation.xml 2022-06-29 10:32:58,098 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/register.navigation.xml 2022-06-29 10:32:58,100 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/scopeInventory.navigation.xml 2022-06-29 10:32:58,101 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/updateClient.navigation.xml 2022-06-29 10:32:58,102 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/manageAuthScript.navigation.xml 2022-06-29 10:32:58,104 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/updateTrustRelationship.navigation.xml 2022-06-29 10:32:58,105 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/selectors.navigation.xml 2022-06-29 10:32:58,106 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/updateRadiusServerConfig.navigation.xml 2022-06-29 10:32:58,107 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/trustRelationshipConfigurationFile.navigation.xml 2022-06-29 10:32:58,109 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/login.navigation.xml 2022-06-29 10:32:58,110 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/spRequestors.navigation.xml 2022-06-29 10:32:58,111 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/addGroup.navigation.xml 2022-06-29 10:32:58,112 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/updateGroup.navigation.xml 2022-06-29 10:32:58,114 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/addScope.navigation.xml 2022-06-29 10:32:58,115 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/updateUmaScope.navigation.xml 2022-06-29 10:32:58,116 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/viewProfile.navigation.xml 2022-06-29 10:32:58,118 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/addClient.navigation.xml 2022-06-29 10:32:58,119 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/modifyResource.navigation.xml 2022-06-29 10:32:58,121 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/passportBasicConfig.navigation.xml 2022-06-29 10:32:58,122 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/updateScope.navigation.xml 2022-06-29 10:32:58,123 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/clientInventory.navigation.xml 2022-06-29 10:32:58,125 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/applicationInventory.navigation.xml 2022-06-29 10:32:58,126 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/postRegister.navigation.xml 2022-06-29 10:32:58,127 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/scope.navigation.xml 2022-06-29 10:32:58,128 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/error_service.navigation.xml 2022-06-29 10:32:58,130 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/passportproviders.navigation.xml 2022-06-29 10:32:58,131 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/deviceInventory.navigation.xml 2022-06-29 10:32:58,132 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/attributeImport.navigation.xml 2022-06-29 10:32:58,133 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/addPerson.navigation.xml 2022-06-29 10:32:58,135 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/updatePerson.navigation.xml 2022-06-29 10:32:58,136 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/finishlogout.navigation.xml 2022-06-29 10:32:58,137 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/editSPRequestor.navigation.xml 2022-06-29 10:32:58,138 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/editIDP.navigation.xml 2022-06-29 10:32:58,140 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/updateRadiusClient.navigation.xml 2022-06-29 10:32:58,141 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/certificateManagement.navigation.xml 2022-06-29 10:32:58,142 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/manageCustomAuthentication.navigation.xml 2022-06-29 10:32:58,143 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/idps.navigation.xml 2022-06-29 10:32:58,145 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/authcode.navigation.xml 2022-06-29 10:32:58,146 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/manageOtherScript.navigation.xml 2022-06-29 10:32:58,148 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/addIDPstep1.navigation.xml 2022-06-29 10:32:58,149 DEBUG [main] [org.gluu.jsf2.customization.FacesConfigPopulator] (FacesConfigPopulator.java:91) - Added navigation rules from /opt/jetty-10.0/temp/jetty-localhost-8082-identity_war-_identity-any-9761401308795200060/webapp/WEB-INF/classes/META-INF/navigation/groupInventory.navigation.xml 2022-06-29 10:32:59,820 DEBUG [main] [org.gluu.service.ExternalResourceHandler] (ExternalResourceHandler.java:68) - Found overriden resource: / 2022-06-29 10:33:09,661 DEBUG [oxTrustScheduler_Worker-1] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:53) - Bound request started 2022-06-29 10:33:09,668 DEBUG [oxTrustScheduler_Worker-1] [org.gluu.service.timer.RequestJobListener] (RequestJobListener.java:63) - Bound request ended 2022-06-29 10:33:09,759 INFO [ForkJoinPool.commonPool-worker-7] [org.gluu.service.logger.LoggerService] (LoggerService.java:205) - Updated log level of '73' loggers to INFO 2022-06-29 10:33:24,725 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.service.logger.LoggerService] (LoggerService.java:205) - Updated log level of '73' loggers to INFO 2022-06-29 10:33:39,722 INFO [ForkJoinPool.commonPool-worker-7] [org.gluu.service.logger.LoggerService] (LoggerService.java:205) - Updated log level of '73' loggers to INFO 2022-06-29 10:33:54,702 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.service.logger.LoggerService] (LoggerService.java:205) - Updated log level of '73' loggers to INFO 2022-06-29 10:33:54,809 DEBUG [ForkJoinPool.commonPool-worker-7] [org.gluu.util.process.ProcessHelper] (ProcessHelper.java:66) - Preparing to start process [facter, -j] 2022-06-29 10:33:54,822 DEBUG [ForkJoinPool.commonPool-worker-7] [org.gluu.util.process.ProcessHelper] (ProcessHelper.java:152) - Executing blocking process [facter, -j] 2022-06-29 10:33:55,022 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.oxtrust.service.Shibboleth3ConfService] (Shibboleth3ConfService.java:227) - >>>>>>>>>> IN Shibboleth3ConfService.generateGluuAttributeRulesFile() ... 2022-06-29 10:33:55,348 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.oxtrust.service.Shibboleth3ConfService] (Shibboleth3ConfService.java:275) - Gluu attributes rules file path is /opt/shibboleth-idp/conf/attributes/gluu-attribute-rules.xml 2022-06-29 10:33:55,349 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.oxtrust.service.Shibboleth3ConfService] (Shibboleth3ConfService.java:277) - >>>>>>>>>>> LEAVING Shibboleth3ConfService.generateGluuAttributeRulesFile() ... 2022-06-29 10:33:55,438 DEBUG [ForkJoinPool.commonPool-worker-7] [org.gluu.util.process.ProcessHelper] (ProcessHelper.java:69) - Successfully start process [facter, -j] 2022-06-29 10:33:55,448 DEBUG [ForkJoinPool.commonPool-worker-7] [org.gluu.util.process.ProcessHelper] (ProcessHelper.java:77) - Waiting for the proces [facter, -j] finish 2022-06-29 10:33:55,449 DEBUG [ForkJoinPool.commonPool-worker-7] [org.gluu.util.process.ProcessHelper] (ProcessHelper.java:87) - Process [facter, -j] has finished 2022-06-29 10:34:09,711 INFO [ForkJoinPool.commonPool-worker-7] [org.gluu.service.logger.LoggerService] (LoggerService.java:205) - Updated log level of '75' loggers to INFO 2022-06-29 10:34:24,697 INFO [ForkJoinPool.commonPool-worker-7] [org.gluu.service.logger.LoggerService] (LoggerService.java:205) - Updated log level of '75' loggers to INFO 2022-06-29 10:34:39,697 INFO [ForkJoinPool.commonPool-worker-7] [org.gluu.service.logger.LoggerService] (LoggerService.java:205) - Updated log level of '75' loggers to INFO 2022-06-29 10:34:54,698 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.service.logger.LoggerService] (LoggerService.java:205) - Updated log level of '75' loggers to INFO 2022-06-29 10:35:09,709 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.service.logger.LoggerService] (LoggerService.java:205) - Updated log level of '75' loggers to INFO ```

By Patryk Su user 29 Jun 2022 at 3:45 a.m. CDT

Patryk Su gravatar
here is oxauth.log ``` 2022-06-29 09:57:11,995 INFO [JettyShutdownThread] [org.gluu.oxauth.service.AppInitializer] (AppInitializer.java:680) - Stopping services and closing DB connections at server shutdown... 2022-06-29 09:57:38,497 ERROR [ForkJoinPool.commonPool-worker-3] [org.gluu.oxauth.model.config.ConfigurationFactory] (ConfigurationFactory.java:440) - Failed to find entry: ou=oxauth,ou=configuration,o=gluu 2022-06-29 09:57:38,551 ERROR [ForkJoinPool.commonPool-worker-7] [org.gluu.oxauth.service.AppInitializer] (AppInitializer.java:639) - Failed to load global configuration entry from Ldap org.gluu.persist.exception.EntryPersistenceException: Failed to find entry: ou=configuration,o=gluu at org.gluu.persist.ldap.impl.LdapEntryManager.find(LdapEntryManager.java:405) ~[gluu-orm-ldap-4.4.0.Final.jar:?] at org.gluu.persist.impl.BaseEntryManager.find(BaseEntryManager.java:713) ~[gluu-orm-core-4.4.0.Final.jar:?] at org.gluu.persist.impl.BaseEntryManager.find(BaseEntryManager.java:592) ~[gluu-orm-core-4.4.0.Final.jar:?] at jdk.internal.reflect.GeneratedMethodAccessor84.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.bean.proxy.AbstractBeanInstance.invoke(AbstractBeanInstance.java:38) ~[weld-core-impl-3.1.9.Final.jar:3.1.9.Final] at org.jboss.weld.bean.proxy.ProxyMethodHandler.invoke(ProxyMethodHandler.java:106) ~[weld-core-impl-3.1.9.Final.jar:3.1.9.Final] at org.gluu.persist.PersistenceEntryManager$EntityManager$1948487569$Proxy$_$$_WeldClientProxy.find(Unknown Source) ~[gluu-orm-core-4.4.0.Final.jar:?] at org.gluu.oxauth.service.AppInitializer.loadConfiguration(AppInitializer.java:636) ~[classes/:?] at org.gluu.oxauth.service.AppInitializer.reloadConfiguration(AppInitializer.java:305) ~[classes/:?] at org.gluu.oxauth.service.AppInitializer.reloadConfigurationTimerEvent(AppInitializer.java:292) ~[classes/:?] at org.gluu.oxauth.service.AppInitializer$Proxy$_$$_WeldSubclass.reloadConfigurationTimerEvent$$super(Unknown Source) ~[classes/:?] at jdk.internal.reflect.GeneratedMethodAccessor120.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.4.0.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) ~[?:?] Caused by: org.gluu.persist.exception.operation.ConnectionException: Failed to lookup entry at org.gluu.persist.ldap.operation.impl.LdapOperationServiceImpl.lookupImpl(LdapOperationServiceImpl.java:626) ~[gluu-orm-ldap-4.4.0.Final.jar:?] at org.gluu.persist.ldap.operation.impl.LdapOperationServiceImpl.lookup(LdapOperationServiceImpl.java:605) ~[gluu-orm-ldap-4.4.0.Final.jar:?] at org.gluu.persist.ldap.impl.LdapEntryManager.find(LdapEntryManager.java:399) ~[gluu-orm-ldap-4.4.0.Final.jar:?] ... 25 more Caused by: com.unboundid.ldap.sdk.LDAPException: This connection pool has been closed. at com.unboundid.ldap.sdk.LDAPConnectionPool.getConnection(LDAPConnectionPool.java:1790) ~[unboundid-ldapsdk-6.0.4.jar:6.0.4] at com.unboundid.ldap.sdk.AbstractConnectionPool.getEntry(AbstractConnectionPool.java:642) ~[unboundid-ldapsdk-6.0.4.jar:6.0.4] at org.gluu.persist.ldap.operation.impl.LdapOperationServiceImpl.lookupImpl(LdapOperationServiceImpl.java:619) ~[gluu-orm-ldap-4.4.0.Final.jar:?] at org.gluu.persist.ldap.operation.impl.LdapOperationServiceImpl.lookup(LdapOperationServiceImpl.java:605) ~[gluu-orm-ldap-4.4.0.Final.jar:?] at org.gluu.persist.ldap.impl.LdapEntryManager.find(LdapEntryManager.java:399) ~[gluu-orm-ldap-4.4.0.Final.jar:?] ... 25 more 2022-06-29 09:58:04,171 INFO [main] [org.jboss.weld.environment.servlet.EnhancedListener] (EnhancedListener.java:58) - WELD-ENV-001008: Initialize Weld using ServletContainerInitializer 2022-06-29 09:58:04,250 INFO [main] [org.jboss.weld.bootstrap.WeldStartup] (WeldStartup.java:148) - WELD-000900: 3.1.9 (Final) 2022-06-29 09:58:05,130 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. 2022-06-29 09:58:05,956 INFO [main] [org.jboss.weld.bootstrap.WeldStartup] (WeldStartup.java:236) - WELD-000101: Transactional services not available. Injection of @Inject UserTransaction not available. Transactional observers will be invoked synchronously. 2022-06-29 09:58:06,463 WARN [main] [org.jboss.weld.bootstrap.events.BeforeBeanDiscoveryImpl] (BeforeBeanDiscoveryImpl.java:118) - WELD-000146: BeforeBeanDiscovery.addAnnotatedType(AnnotatedType) used for class com.sun.faces.flow.FlowDiscoveryCDIHelper is deprecated from CDI 1.1! 2022-06-29 09:58:07,537 INFO [main] [org.jboss.weld.environment.jetty.JettyContainer] (JettyContainer.java:88) - WELD-ENV-001212: Jetty CdiDecoratingListener support detected, CDI injection will be available in Listeners, Servlets and Filters. 2022-06-29 09:58:09,387 INFO [main] [org.gluu.util.security.SecurityProviderUtility] (SecurityProviderUtility.java:284) - Adding Bouncy Castle Provider 2022-06-29 09:58:09,696 INFO [main] [org.gluu.util.security.SecurityProviderUtility] (SecurityProviderUtility.java:289) - Provider 'BC' with version 1.7 is added 2022-06-29 09:58:09,698 INFO [main] [org.gluu.oxauth.model.config.ConfigurationFactory] (ConfigurationFactory.java:380) - Loading configuration from 'ldap' DB... 2022-06-29 09:58:12,431 INFO [main] [org.gluu.oxauth.service.AppInitializer] (AppInitializer.java:376) - Created persistenceEntryManager: org.gluu.persist.ldap.impl.LdapEntryManager@18ac25e6 with operation service: org.gluu.persist.ldap.operation.impl.LdapOperationServiceImpl@47ffa248 2022-06-29 09:58:12,693 INFO [main] [org.gluu.service.logger.LoggerService] (LoggerService.java:159) - External log configuration: 2022-06-29 09:58:12,694 INFO [main] [org.gluu.service.logger.LoggerService] (LoggerService.java:178) - Reloading log4j2 configuration 2022-06-29 09:58:12,746 INFO [main] [org.gluu.service.logger.LoggerService] (LoggerService.java:142) - Setting layout and loggers level to 'TEXT`, `INFO' after configuration update 2022-06-29 09:58:12,823 INFO [main] [org.gluu.oxauth.model.config.ConfigurationFactory] (ConfigurationFactory.java:185) - Configuration loaded successfully. 2022-06-29 09:58:14,065 INFO [main] [org.gluu.oxauth.service.stat.StatService] (StatService.java:66) - Initializing Stat Service 2022-06-29 09:58:14,110 INFO [main] [org.gluu.oxauth.service.stat.StatService] (StatService.java:82) - Initialized Stat Service 2022-06-29 09:58:14,232 INFO [main] [org.quartz.impl.StdSchedulerFactory] (StdSchedulerFactory.java:1220) - Using default implementation for ThreadExecutor 2022-06-29 09:58:14,265 INFO [main] [org.quartz.core.SchedulerSignalerImpl] (SchedulerSignalerImpl.java:61) - Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl 2022-06-29 09:58:14,265 INFO [main] [org.quartz.core.QuartzScheduler] (QuartzScheduler.java:229) - Quartz Scheduler v.2.3.2 created. 2022-06-29 09:58:14,268 INFO [main] [org.quartz.simpl.RAMJobStore] (RAMJobStore.java:155) - RAMJobStore initialized. 2022-06-29 09:58:14,269 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. 2022-06-29 09:58:14,269 INFO [main] [org.quartz.impl.StdSchedulerFactory] (StdSchedulerFactory.java:1374) - Quartz scheduler 'oxAuthScheduler' initialized from the specified file : 'quartz.properties' from the class resource path. 2022-06-29 09:58:14,270 INFO [main] [org.quartz.impl.StdSchedulerFactory] (StdSchedulerFactory.java:1378) - Quartz scheduler version: 2.3.2 2022-06-29 09:58:14,272 INFO [main] [org.quartz.core.QuartzScheduler] (QuartzScheduler.java:2293) - JobFactory set to: org.gluu.service.timer.JobExecutionFactory@7388486e 2022-06-29 09:58:14,273 INFO [main] [org.gluu.service.timer.QuartzSchedulerManager] (QuartzSchedulerManager.java:62) - Quartz scheduler manager initialized 2022-06-29 09:58:14,273 INFO [main] [org.quartz.core.QuartzScheduler] (QuartzScheduler.java:547) - Scheduler oxAuthScheduler_$_NON_CLUSTERED started. 2022-06-29 09:58:14,273 INFO [main] [org.gluu.service.timer.QuartzSchedulerManager] (QuartzSchedulerManager.java:115) - Quartz scheduler started 2022-06-29 09:58:14,306 INFO [main] [org.gluu.service.logger.LoggerService] (LoggerService.java:59) - Initializing Logger Update Timer 2022-06-29 09:58:14,411 INFO [main] [org.gluu.oxauth.service.KeyGeneratorTimer] (KeyGeneratorTimer.java:70) - Initializing Key Generator Timer 2022-06-29 09:58:14,413 INFO [main] [org.gluu.oxauth.service.KeyGeneratorTimer] (KeyGeneratorTimer.java:77) - Initialized Key Generator Timer 2022-06-29 09:58:14,428 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.oxauth.service.stat.StatTimer] (StatTimer.java:43) - Initializing Stat Service Timer 2022-06-29 09:58:14,430 INFO [ForkJoinPool.commonPool-worker-5] [org.gluu.oxauth.service.stat.StatTimer] (StatTimer.java:50) - Initialized Stat Service Timer 2022-06-29 09:58:14,431 WARN [main] [org.gluu.oxauth.service.AppInitializer] (AppInitializer.java:710) - Ciba requests processor hasn't been started because the interval is not valid to run or this is disabled, value: 0 2022-06-29 09:58:14,859 INFO [main] [org.jboss.weld.environment.servlet.Listener] (Listener.java:112) - WELD-ENV-001006: org.jboss.weld.environment.servlet.EnhancedListener used to initialize Weld 2022-06-29 09:58:15,364 INFO [main] [org.gluu.jsf2.customization.FacesLocalizationConfigPopulator] (FacesLocalizationConfigPopulator.java:95) - Adding languages '[ru, tr, bg, es, it, en, de, fr]' from dir folder: /opt/jetty-10.0/temp/jetty-localhost-8081-oxauth_war-_oxauth-any-5836744271257925177/webapp/WEB-INF/classes/ 2022-06-29 09:58:16,292 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 2022-06-29 09:58:16,967 INFO [main] [org.jboss.resteasy.core.ResteasyDeploymentImpl] (ResteasyDeploymentImpl.java:656) - RESTEASY002225: Deploying javax.ws.rs.core.Application: class org.gluu.oxauth.service.ResteasyInitializer$Proxy$_$$_WeldClientProxy 2022-06-29 09:58:16,968 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.DocumentProvider is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,969 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.sse.SseEventProvider is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,969 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.sse.SseEventSinkInterceptor is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,970 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.JaxrsServerFormUrlEncodedProvider is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,970 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.jaxb.XmlJAXBContextFinder is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,971 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.AsyncStreamingOutputProvider is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,972 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.StringTextStar is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,972 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.JaxrsFormProvider is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,973 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.DefaultBooleanWriter is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,973 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.interceptors.MessageSanitizerContainerResponseFilter is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,974 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.ByteArrayProvider is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,974 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.jackson.UnrecognizedPropertyExceptionHandler is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,975 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.jackson.PatchMethodFilter is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,975 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.FileRangeWriter is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,975 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.jaxb.JAXBXmlSeeAlsoProvider is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,976 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.DataSourceProvider is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,976 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.DefaultTextPlain is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,977 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.FormUrlEncodedProvider is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,977 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.DefaultNumberWriter is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,978 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.SourceProvider is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,978 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.MultiValuedParamConverterProvider is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,978 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.StreamingOutputProvider is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,979 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.jaxb.JAXBXmlTypeProvider is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,979 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.jaxb.JAXBXmlRootElementProvider is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,983 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.ReaderProvider is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,984 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.IIOImageProvider is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,991 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.jaxb.JAXBElementProvider is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,991 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.jaxb.CollectionProvider is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,992 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.FileProvider is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,992 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.InputStreamProvider is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,993 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.jaxb.MapProvider is already registered. 2nd registration is being ignored. 2022-06-29 09:58:16,993 WARN [main] [org.jboss.resteasy.core.providerfactory.ResteasyProviderFactoryImpl] (ResteasyProviderFactoryImpl.java:825) - RESTEASY002155: Provider class org.jboss.resteasy.plugins.providers.jackson.ResteasyJackson2Provider is already registered. 2nd registration is being ignored. ```

By Patryk Su user 29 Jun 2022 at 8:41 a.m. CDT

Patryk Su gravatar
here is apache2.log ``` [29/Jun/2022:15:39:15 +0200] "GET / HTTP/1.1" 302 884 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:91.0) Gecko/20100101 Firefox/91.0" [29/Jun/2022:15:39:15 +0200] "GET /identity/ HTTP/1.1" 200 555 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:91.0) Gecko/20100101 Firefox/91.0" 29/Jun/2022:15:39:16 +0200] "GET /identity/home.htm HTTP/1.1" 302 564 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:91.0) Gecko/20100101 Firefox/91.0" [29/Jun/2022:15:39:16 +0200] "GET /.well-known/openid-configuration HTTP/1.1" 503 27189 "-" "Apache-HttpClient/4.5.13 (Java/11.0.14.1)" [29/Jun/2022:15:39:16 +0200] "GET /identity/login.htm HTTP/1.1" 302 407 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:91.0) Gecko/20100101 Firefox/91.0" [29/Jun/2022:15:39:16 +0200] "GET /identity/error.htm HTTP/1.1" 200 2355 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:91.0) Gecko/20100101 Firefox/91.0" ```

By Patryk Su user 29 Jun 2022 at 2:37 p.m. CDT

Patryk Su gravatar
Shakil, my server is allocated 4GB of RAM or this could be the reason why Gluu-server is acting like this ?

By Mobarak Hosen Shakil staff 29 Jun 2022 at 6:09 p.m. CDT

Mobarak Hosen Shakil gravatar
Yes, 4GB of RAM might be a reason depending on the services it's using. We recommend at least 8GB of RAM. How many services are running(ex: oxauth, identity, casa, oxd, saml..etc)? I see this issue: >```2022-06-29 09:57:11,995 INFO [JettyShutdownThread] [org.gluu.oxauth.service.AppInitializer] (AppInitializer.java:680) - Stopping services and closing DB connections at server shutdown... 2022-06-29 09:57:38,497 ERROR [ForkJoinPool.commonPool-worker-3] [org.gluu.oxauth.model.config.ConfigurationFactory] (ConfigurationFactory.java:440) - Failed to find entry: ou=oxauth,ou=configuration,o=gluu 2022-06-29 09:57:38,551 ERROR [ForkJoinPool.commonPool-worker-7] [org.gluu.oxauth.service.AppInitializer] (AppInitializer.java:639) - Failed to load global configuration entry from Ldap``` It shows OpenDJ not running. Did you try to restart `opendj`? >`systemctl restart opendj` Or you can restart the gluu-server: >`gluu-serverd restart`

By Patryk Su user 30 Jun 2022 at 1:27 a.m. CDT

Patryk Su gravatar
Hi Shakil yes I restarted services (identity, oxauth, opendj, oxd-server) many times with no effect, the OpenDJ service itself looks fine, here I am sending its status: ``` * opendj.service - OpenDJ Directory Service Loaded: loaded (/etc/systemd/system/opendj.service; enabled; vendor preset: enabled) Drop-In: /etc/systemd/system/opendj.service.d `-override.conf Active: active (running) since Thu 2022-06-30 08:02:45 CEST; 21min ago Process: 4296 ExecStart=/opt/opendj/bin/start-ds (code=exited, status=0/SUCCESS) Main PID: 4343 (java) CGroup: /system.slice/opendj.service `-4343 /opt/jre/bin/java -server -Xms512m -Xmx1280m -XX:+UseCompressedOops -Dorg.opends.server.scriptName=start-ds org.opends.server.core.DirectoryServer --configFile /opt/opendj/config/config.ldif Jun 30 08:02:40 my.gluu.host start-ds[4344]: [30/Jun/2022:06:02:40 +0000] category=JVM severity=NOTICE msgID=19 msg=JVM Arguments: "-Xms512m", "-Xmx1280m", "-XX:+UseCompressedOops", "-Dorg.opends.server.scriptName=start-ds" Jun 30 08:02:42 my.gluu.host start-ds[4344]: [30/Jun/2022:06:02:42 +0000] category=BACKEND severity=NOTICE msgID=513 msg=The database backend site containing 2 entries has started Jun 30 08:02:43 my.gluu.host start-ds[4344]: [30/Jun/2022:06:02:42 +0000] category=BACKEND severity=NOTICE msgID=513 msg=The database backend metric containing 3925 entries has started Jun 30 08:02:43 my.gluu.host start-ds[4344]: [30/Jun/2022:06:02:43 +0000] category=BACKEND severity=NOTICE msgID=513 msg=The database backend userRoot containing 185 entries has started Jun 30 08:02:43 my.gluu.host start-ds[4344]: [30/Jun/2022:06:02:43 +0000] category=EXTENSIONS severity=NOTICE msgID=221 msg=DIGEST-MD5 SASL mechanism using a server fully qualified domain name of: localhost Jun 30 08:02:44 my.gluu.host start-ds[4344]: [30/Jun/2022:06:02:44 +0000] category=CORE severity=NOTICE msgID=135 msg=The Directory Server has started successfully Jun 30 08:02:44 my.gluu.host start-ds[4344]: [30/Jun/2022:06:02:44 +0000] category=CORE severity=NOTICE msgID=139 msg=The Directory Server has sent an alert notification generated by class org.opends.server.core.DirectorySer> Jun 30 08:02:44 my.gluu.host start-ds[4344]: [30/Jun/2022:06:02:44 +0000] category=PROTOCOL severity=NOTICE msgID=276 msg=Started listening for new connections on Administration Connector localhost port 4444 Jun 30 08:02:44 my.gluu.host start-ds[4344]: [30/Jun/2022:06:02:44 +0000] category=PROTOCOL severity=NOTICE msgID=276 msg=Started listening for new connections on LDAPS Connection Handler localhost port 1636 Jun 30 08:02:45 my.gluu.host systemd[1]: Started OpenDJ Directory Service. ``` as for what services are running at the moment, the basic ones are: identity, oxauth, oxd. In the future, we want to introduce 2FA, so there must also be Casa, it is installed, but for now I just wanted to do the configuration with the LDAP backend.

By Mobarak Hosen Shakil staff 01 Jul 2022 at 1:52 a.m. CDT

Mobarak Hosen Shakil gravatar
The log shows, you have installed `SAML IDP` also. *What happens if you try to restart the whole server?* ``` gluu-serverd restart ```

By Patryk Su user 04 Jul 2022 at 7:15 a.m. CDT

Patryk Su gravatar
same but put a new gluu instance. Thx for your time Shakil