Hi, I try restore an EJBCA with backup database, p12 and conf file. But fail every time.
Configuration:
EJBCA_7_9_0_2 (Upgrade from EJBCA_7_4_3_2)
MariaDB database (new installation) - MYSQL (old installation)
Hsm Safenet Luna (with keys of the CA and SubCA)
Steps I followed for the restore:
1. restore the database Mysql in new MariaDB, create de user ejbca with new password
2. Installed mariadb-java-client.
3. Followed the steps to install Wildfly 18.0 and configurate the credentials for elytron for the database and keystore.
4. copied the files of EJBCA-7_9_0_2 and restore the /conf/ and /p12/ files of my old installation
5. Modified the file database.properties with the conection to new database
6. Installed the LunaClient to have comunication with the hsm
7. Verified that i have access to the hsm partition where have created the keys of CA y SubCA of my old installation
8. Modified the file Chrystoki.conf for conection with hsm and added user wildfly to hsm group
9. Verify that library paths to Safenet luna are activated in web.properties file.
10. In path /ejbca_7_9_0_2 execute sudo ant clean deployear and sudo ant deploy-keystore
11._ Execute the command sudo systemctl restart wildfly.
2022-12-06 19:41:33,806 DEBUG [org.jboss.as.config] (MSC service thread 1-3) VM Arguments: -D[Standalone] -Xms64m -Xmx512m -XX:MetaspaceSize=96M -XX:MaxMetaspaceSize=256m -Djava.net.preferIPv4Stack=true -Djboss.modules.system.pkgs=org.jboss.byteman -Djava.awt.headless=true -Dorg.jboss.boot.log.file=/opt/wildfly/standalone/log/server.log -Dlogging.configuration=file:/opt/wildfly/standalone/configuration/logging.properties
2022-12-06 19:41:37,013 INFO [org.xnio] (MSC service thread 1-1) XNIO version 3.7.3.Final
2022-12-06 19:41:37,022 INFO [org.xnio.nio] (MSC service thread 1-1) XNIO NIO Implementation Version 3.7.3.Final
2022-12-06 19:41:37,225 WARN [org.jboss.as.txn] (ServerService Thread Pool -- 72) WFLYTX0013: The node-identifier attribute on the /subsystem=transactions is set to the default value. This is a danger for environments running multiple servers. Please make sure the attribute value is unique.
2022-12-06 19:41:41,387 INFO [org.infinispan.factories.GlobalComponentRegistry] (MSC service thread 1-3) ISPN000128: Infinispan version: Infinispan 'Infinity Minus ONE +2' 9.4.16.Final
2022-12-06 19:41:51,908 INFO [org.hibernate.jpa.internal.util.LogHelper] (ServerService Thread Pool -- 76) HHH000204: Processing PersistenceUnitInfo
name: ejbca
...
2022-12-06 19:41:52,076 INFO [org.hibernate.Version] (ServerService Thread Pool -- 76) HHH000412: Hibernate Core {5.3.12.Final}
2022-12-06 19:41:52,079 INFO [org.hibernate.cfg.Environment] (ServerService Thread Pool -- 76) HHH000206: hibernate.properties not found
2022-12-06 19:41:52,246 INFO [org.hibernate.validator.internal.util.Version] (MSC service thread 1-4) HV000001: Hibernate Validator 6.0.17.Final
2022-12-06 19:41:52,461 INFO [org.hibernate.annotations.common.Version] (ServerService Thread Pool -- 76) HCANN000001: Hibernate Commons Annotations {5.0.5.Final}
2022-12-06 19:41:52,652 ERROR [org.jboss.as.jsf] (MSC service thread 1-1) WFLYJSF0002: Could not load JSF managed bean class: org.ejbca.ui.web.admin.peerconnector.PeerConnectorsMBean
2022-12-06 19:41:52,803 ERROR [org.jboss.as.jsf] (MSC service thread 1-1) WFLYJSF0002: Could not load JSF managed bean class: org.ejbca.ui.web.admin.peerconnector.PeerConnectorMBean
2022-12-06 19:41:55,677 WARN [org.jboss.as.jaxrs] (MSC service thread 1-4) WFLYRS0018: Explicit usage of Jackson annotation in a JAX-RS deployment; the system will disable JSON-B processing for the current deployment. Consider setting the 'resteasy.preferJacksonOverJsonB' property to 'false' to restore JSON-B.
2022-12-06 19:41:57,700 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-1) Creating Service {http://ws.protocol.core.ejbca.org/}EjbcaWSService from class org.ejbca.core.protocol.ws.EjbcaWS
2022-12-06 19:41:58,017 INFO [org.hibernate.dialect.Dialect] (ServerService Thread Pool -- 76) HHH000400: Using dialect: org.hibernate.dialect.MySQL5InnoDBDialect
2022-12-06 19:41:58,119 INFO [org.hibernate.envers.boot.internal.EnversServiceImpl] (ServerService Thread Pool -- 76) Envers integration enabled? : true
2022-12-06 19:41:58,259 INFO [org.cesecore.configuration.GlobalConfigurationData] (ServerService Thread Pool -- 76) Class 'org.widget.Bar' was not found on classpath.
2022-12-06 19:41:58,267 INFO [org.cesecore.configuration.GlobalConfigurationData] (ServerService Thread Pool -- 76) Class 'org.widget.Foo' was not found on classpath.
2022-12-06 19:41:59,311 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-1) Setting the server's publish address to be http://jbossws.undefined.host:4447/ejbca/ejbcaws/ejbcaws
2022-12-06 19:42:01,303 INFO [org.hibernate.hql.internal.QueryTranslatorFactoryInitiator] (ServerService Thread Pool -- 76) HHH000397: Using ASTQueryTranslatorFactory
2022-12-06 19:42:01,824 WARN [org.jboss.weld.Bootstrap] (MSC service thread 1-2) WELD-000146: BeforeBeanDiscovery.addAnnotatedType(AnnotatedType<?>) used for class com.sun.faces.flow.FlowDiscoveryCDIHelper is deprecated from CDI 1.1!
2022-12-06 19:42:01,847 INFO [io.smallrye.metrics] (MSC service thread 1-2) MicroProfile: Metrics activated
2022-12-06 19:42:04,142 SEVERE [javax.enterprise.resource.webcontainer.jsf.flow] (MSC service thread 1-2) Unable to obtain CDI 1.1 utilities for Mojarra
2022-12-06 19:42:04,151 SEVERE [javax.enterprise.resource.webcontainer.jsf.application.view] (MSC service thread 1-2) Unable to obtain CDI 1.1 utilities for Mojarra
2022-12-06 19:42:05,190 DEBUG [org.ejbca.core.ejb.ra.raadmin.RaStyleCacheBean] (ServerService Thread Pool -- 93) org.ejbca.core.ejb.ra.raadmin.RaStyleCacheBean initialized
2022-12-06 19:42:05,206 DEBUG [org.cesecore.certificates.ca.internal.CaIDCacheBean] (ServerService Thread Pool -- 88) Loading CA ID cache from database
2022-12-06 19:42:05,245 DEBUG [org.ejbca.config.EjbcaConfigurationHolder] (ServerService Thread Pool -- 79) Added url to configuration source: vfs:/content/ejbca.ear/lib/ejbca-properties.jar/conf/ejbca.properties
2022-12-06 19:42:05,247 DEBUG [org.ejbca.config.EjbcaConfigurationHolder] (ServerService Thread Pool -- 79) Added url to configuration source: vfs:/content/ejbca.ear/lib/ejbca-properties.jar/conf/web.properties
2022-12-06 19:42:05,247 DEBUG [org.ejbca.config.EjbcaConfigurationHolder] (ServerService Thread Pool -- 79) Added url to configuration source: vfs:/content/ejbca.ear/lib/ejbca-properties.jar/conf/database.properties
2022-12-06 19:42:05,250 DEBUG [org.ejbca.config.EjbcaConfigurationHolder] (ServerService Thread Pool -- 79) Added url to configuration source: vfs:/content/ejbca.ear/lib/ejbca-properties.jar/internal.properties
2022-12-06 19:42:05,255 INFO [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) Init, EJBCA 7.9.0.2 Community (e9a5aa4700aaffc5468fbbd06780a6c100b18cb8) startup.
2022-12-06 19:42:05,259 DEBUG [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) >startup re-installing BC-provider
2022-12-06 19:42:05,719 WARN [io.undertow.servlet] (ServerService Thread Pool -- 98) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, GET]
2022-12-06 19:42:05,724 WARN [io.undertow.servlet] (ServerService Thread Pool -- 90) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET]
2022-12-06 19:42:05,725 WARN [io.undertow.servlet] (ServerService Thread Pool -- 92) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET]
2022-12-06 19:42:05,725 WARN [io.undertow.servlet] (ServerService Thread Pool -- 81) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, GET]
2022-12-06 19:42:05,725 WARN [io.undertow.servlet] (ServerService Thread Pool -- 109) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET]
2022-12-06 19:42:05,729 WARN [io.undertow.servlet] (ServerService Thread Pool -- 104) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET]
2022-12-06 19:42:05,725 WARN [io.undertow.servlet] (ServerService Thread Pool -- 105) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET]
2022-12-06 19:42:05,730 WARN [io.undertow.servlet] (ServerService Thread Pool -- 94) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET]
2022-12-06 19:42:05,731 WARN [io.undertow.servlet] (ServerService Thread Pool -- 102) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET]
2022-12-06 19:42:05,732 WARN [io.undertow.servlet] (ServerService Thread Pool -- 99) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, GET]
2022-12-06 19:42:05,751 WARN [io.undertow.servlet] (ServerService Thread Pool -- 83) UT015020: Path /* is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET, PUT]
2022-12-06 19:42:05,799 DEBUG [org.ejbca.core.model.util.EjbLocalHelper] (ServerService Thread Pool -- 81) Failed JEE5 version of EjbBridgeSessionLocal JNDI lookup. All future lookups will JEE6 version lookups.
2022-12-06 19:42:05,799 DEBUG [org.ejbca.core.model.util.EjbLocalHelper] (ServerService Thread Pool -- 99) Failed JEE5 version of EjbBridgeSessionLocal JNDI lookup. All future lookups will JEE6 version lookups.
2022-12-06 19:42:05,843 INFO [javax.enterprise.resource.webcontainer.jsf.config] (ServerService Thread Pool -- 84) Initializing Mojarra 2.3.9.SP03 for context '/ejbca/ra'
2022-12-06 19:42:06,116 DEBUG [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) >startup initializing random seed, can take a little time...
2022-12-06 19:42:06,117 DEBUG [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) >startup finished initializing random seed
2022-12-06 19:42:06,117 DEBUG [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) >startup checking classloader of BouncyCastle provider
2022-12-06 19:42:06,175 DEBUG [org.cesecore.certificates.util.DnComponents] (ServerService Thread Pool -- 76) Using default values for DN components
2022-12-06 19:42:06,179 DEBUG [org.cesecore.certificates.util.DnComponents] (ServerService Thread Pool -- 76) Read profile maps with 49 lines.
2022-12-06 19:42:06,180 DEBUG [org.cesecore.certificates.util.DnComponents] (ServerService Thread Pool -- 76) Properties file /profilemappings_enterprise.properties was not found.
2022-12-06 19:42:06,357 INFO [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) BouncyCastle provider is from our ejbca.ear classloader.
2022-12-06 19:42:06,364 DEBUG [org.cesecore.authorization.user.matchvalues.AccessMatchValueReverseLookupRegistry] (ServerService Thread Pool -- 76) Registered AuthenticationToken of type PublicAccessAuthenticationToken with match keys [TRANSPORT_ANY, TRANSPORT_PLAIN, TRANSPORT_CONFIDENTIAL]
2022-12-06 19:42:06,370 DEBUG [org.cesecore.authorization.user.matchvalues.AccessMatchValueReverseLookupRegistry] (ServerService Thread Pool -- 76) Registered AuthenticationToken of type CertificateAuthenticationToken with match keys [NONE, WITH_COUNTRY, WITH_DOMAINCOMPONENT, WITH_STATEORPROVINCE, WITH_LOCALITY, WITH_ORGANIZATION, WITH_ORGANIZATIONALUNIT, WITH_TITLE, WITH_COMMONNAME, WITH_UID, WITH_DNSERIALNUMBER, WITH_SERIALNUMBER, WITH_DNEMAILADDRESS, WITH_RFC822NAME, WITH_UPN, WITH_FULLDN]
2022-12-06 19:42:06,372 DEBUG [org.cesecore.authorization.user.matchvalues.AccessMatchValueReverseLookupRegistry] (ServerService Thread Pool -- 76) Registered AuthenticationToken of type AlwaysAllowLocalAuthenticationToken with match keys [INSTANCE, DEFAULT]
2022-12-06 19:42:06,374 DEBUG [org.cesecore.authorization.user.matchvalues.AccessMatchValueReverseLookupRegistry] (ServerService Thread Pool -- 76) Registered AuthenticationToken of type OAuth2AuthenticationToken with match keys [CLAIM_SUBJECT, CLAIM_ISSUER, CLAIM_AUDIENCE, CLAIM_OBJECTID, CLAIM_ROLE]
2022-12-06 19:42:06,382 DEBUG [org.cesecore.authorization.user.matchvalues.AccessMatchValueReverseLookupRegistry] (ServerService Thread Pool -- 76) Registered AuthenticationToken of type CliAuthenticationToken with match keys [USERNAME]
2022-12-06 19:42:06,382 INFO [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) Registered AuthenticationTokens [OAuth2AuthenticationToken, PublicAccessAuthenticationToken, CertificateAuthenticationToken, CliAuthenticationToken, AlwaysAllowLocalAuthenticationToken]
2022-12-06 19:42:06,382 DEBUG [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) >startup CryptoTokenFactory just to load those classes that are available
2022-12-06 19:42:06,385 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) CryptoTokenFactory adding available crypto token se.primeKey.caToken.card.PrimeCAToken
2022-12-06 19:42:06,393 INFO [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Class not found: se.primeKey.caToken.card.PrimeCAToken.
2022-12-06 19:42:06,394 INFO [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Can not register se.primeKey.caToken.card.PrimeCAToken. This is normally not an error.
2022-12-06 19:42:06,403 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) CryptoTokenFactory adding available crypto token org.cesecore.keys.token.PKCS11CryptoToken
2022-12-06 19:42:06,423 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Registered org.cesecore.keys.token.PKCS11CryptoToken successfully.
2022-12-06 19:42:06,424 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) CryptoTokenFactory adding available crypto token org.cesecore.keys.token.SoftCryptoToken
2022-12-06 19:42:06,425 DEBUG [org.cesecore.keys.token.SoftCryptoToken] (ServerService Thread Pool -- 76) Creating SoftCryptoToken
2022-12-06 19:42:06,425 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Registered org.cesecore.keys.token.SoftCryptoToken successfully.
2022-12-06 19:42:06,426 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) CryptoTokenFactory adding available crypto token org.cesecore.keys.token.NullCryptoToken
2022-12-06 19:42:06,426 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Registered org.cesecore.keys.token.NullCryptoToken successfully.
And it stops there, without up the wildfly and therefore the EJBCA. I don't understand what I could be doing wrong or what could be happening.
If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
There is no errors at all in the log snippet you paste there. Are you sure it's all that WildFly prints? I see that you enabled DEBUG logging for EJBCA.. Did you configure EjbcaDS in WildFly? Typically that should be logged by WildFly during startup.
If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
2022-12-12 16:29:51,716 DEBUG [org.jboss.as.config] (MSC service thread 1-1) VM Arguments: -D[Standalone] -Xms64m -Xmx512m -XX:MetaspaceSize=96M -XX:MaxMetaspaceSize=256m -Djava.net.preferIPv4Stack=true -Djboss.modules.system.pkgs=org.jboss.byteman -Djava.awt.headless=true -Dorg.jboss.boot.log.file=/opt/wildfly/standalone/log/server.log -Dlogging.configuration=file:/opt/wildfly/standalone/configuration/logging.properties
2022-12-12 16:29:54,701 INFO [org.xnio] (MSC service thread 1-3) XNIO version 3.7.3.Final
2022-12-12 16:29:54,726 INFO [org.xnio.nio] (MSC service thread 1-3) XNIO NIO Implementation Version 3.7.3.Final
2022-12-12 16:29:54,998 WARN [org.jboss.as.txn] (ServerService Thread Pool -- 72) WFLYTX0013: The node-identifier attribute on the /subsystem=transactions is set to the default value. This is a danger for environments running multiple servers. Please make sure the attribute value is unique.
2022-12-12 16:29:59,359 INFO [org.infinispan.factories.GlobalComponentRegistry] (MSC service thread 1-3) ISPN000128: Infinispan version: Infinispan 'Infinity Minus ONE +2' 9.4.16.Final
2022-12-12 16:30:11,383 INFO [org.hibernate.jpa.internal.util.LogHelper] (ServerService Thread Pool -- 76) HHH000204: Processing PersistenceUnitInfo
name: ejbca
...
2022-12-12 16:30:11,553 INFO [org.hibernate.Version] (ServerService Thread Pool -- 76) HHH000412: Hibernate Core {5.3.12.Final}
2022-12-12 16:30:11,557 INFO [org.hibernate.cfg.Environment] (ServerService Thread Pool -- 76) HHH000206: hibernate.properties not found
2022-12-12 16:30:11,745 INFO [org.hibernate.validator.internal.util.Version] (MSC service thread 1-4) HV000001: Hibernate Validator 6.0.17.Final
2022-12-12 16:30:11,924 INFO [org.hibernate.annotations.common.Version] (ServerService Thread Pool -- 76) HCANN000001: Hibernate Commons Annotations {5.0.5.Final}
2022-12-12 16:30:12,434 ERROR [org.jboss.as.jsf] (MSC service thread 1-3) WFLYJSF0002: Could not load JSF managed bean class: org.ejbca.ui.web.admin.peerconnector.PeerConnectorsMBean
2022-12-12 16:30:12,634 ERROR [org.jboss.as.jsf] (MSC service thread 1-3) WFLYJSF0002: Could not load JSF managed bean class: org.ejbca.ui.web.admin.peerconnector.PeerConnectorMBean
2022-12-12 16:30:14,956 WARN [org.jboss.as.jaxrs] (MSC service thread 1-4) WFLYRS0018: Explicit usage of Jackson annotation in a JAX-RS deployment; the system will disable JSON-B processing for the current deployment. Consider setting the 'resteasy.preferJacksonOverJsonB' property to 'false' to restore JSON-B.
2022-12-12 16:30:16,998 INFO [org.hibernate.dialect.Dialect] (ServerService Thread Pool -- 76) HHH000400: Using dialect: org.hibernate.dialect.MySQL5InnoDBDialect
2022-12-12 16:30:17,016 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-2) Creating Service {http://ws.protocol.core.ejbca.org/}EjbcaWSService from class org.ejbca.core.protocol.ws.EjbcaWS
2022-12-12 16:30:17,185 INFO [org.hibernate.envers.boot.internal.EnversServiceImpl] (ServerService Thread Pool -- 76) Envers integration enabled? : true
2022-12-12 16:30:17,366 INFO [org.cesecore.configuration.GlobalConfigurationData] (ServerService Thread Pool -- 76) Class 'org.widget.Bar' was not found on classpath.
2022-12-12 16:30:17,367 INFO [org.cesecore.configuration.GlobalConfigurationData] (ServerService Thread Pool -- 76) Class 'org.widget.Foo' was not found on classpath.
2022-12-12 16:30:18,623 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-2) Setting the server's publish address to be http://jbossws.undefined.host:4447/ejbca/ejbcaws/ejbcaws
2022-12-12 16:30:20,629 INFO [org.hibernate.hql.internal.QueryTranslatorFactoryInitiator] (ServerService Thread Pool -- 76) HHH000397: Using ASTQueryTranslatorFactory
2022-12-12 16:30:21,112 WARN [org.jboss.weld.Bootstrap] (MSC service thread 1-4) WELD-000146: BeforeBeanDiscovery.addAnnotatedType(AnnotatedType<?>) used for class com.sun.faces.flow.FlowDiscoveryCDIHelper is deprecated from CDI 1.1!
2022-12-12 16:30:21,151 INFO [io.smallrye.metrics] (MSC service thread 1-4) MicroProfile: Metrics activated
2022-12-12 16:30:22,686 SEVERE [javax.enterprise.resource.webcontainer.jsf.flow] (MSC service thread 1-4) Unable to obtain CDI 1.1 utilities for Mojarra
2022-12-12 16:30:22,693 SEVERE [javax.enterprise.resource.webcontainer.jsf.application.view] (MSC service thread 1-4) Unable to obtain CDI 1.1 utilities for Mojarra
2022-12-12 16:30:23,738 DEBUG [org.cesecore.certificates.ca.internal.CaIDCacheBean] (ServerService Thread Pool -- 106) Loading CA ID cache from database
2022-12-12 16:30:23,737 DEBUG [org.ejbca.core.ejb.ra.raadmin.RaStyleCacheBean] (ServerService Thread Pool -- 82) org.ejbca.core.ejb.ra.raadmin.RaStyleCacheBean initialized
2022-12-12 16:30:23,754 DEBUG [org.ejbca.config.EjbcaConfigurationHolder] (ServerService Thread Pool -- 76) Added url to configuration source: vfs:/content/ejbca.ear/lib/ejbca-properties.jar/conf/ejbca.properties
2022-12-12 16:30:23,770 DEBUG [org.ejbca.config.EjbcaConfigurationHolder] (ServerService Thread Pool -- 76) Added url to configuration source: vfs:/content/ejbca.ear/lib/ejbca-properties.jar/conf/web.properties
2022-12-12 16:30:23,776 DEBUG [org.ejbca.config.EjbcaConfigurationHolder] (ServerService Thread Pool -- 76) Added url to configuration source: vfs:/content/ejbca.ear/lib/ejbca-properties.jar/conf/database.properties
2022-12-12 16:30:23,796 DEBUG [org.ejbca.config.EjbcaConfigurationHolder] (ServerService Thread Pool -- 76) Added url to configuration source: vfs:/content/ejbca.ear/lib/ejbca-properties.jar/internal.properties
2022-12-12 16:30:23,806 INFO [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) Init, EJBCA 7.9.0.2 Community (e9a5aa4700aaffc5468fbbd06780a6c100b18cb8) startup.
2022-12-12 16:30:23,814 DEBUG [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) >startup re-installing BC-provider
2022-12-12 16:30:24,241 WARN [io.undertow.servlet] (ServerService Thread Pool -- 91) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET]
2022-12-12 16:30:24,241 WARN [io.undertow.servlet] (ServerService Thread Pool -- 102) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET]
2022-12-12 16:30:24,241 WARN [io.undertow.servlet] (ServerService Thread Pool -- 89) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET]
2022-12-12 16:30:24,241 WARN [io.undertow.servlet] (ServerService Thread Pool -- 97) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET]
2022-12-12 16:30:24,241 WARN [io.undertow.servlet] (ServerService Thread Pool -- 98) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, GET]
2022-12-12 16:30:24,241 WARN [io.undertow.servlet] (ServerService Thread Pool -- 103) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, GET]
2022-12-12 16:30:24,241 WARN [io.undertow.servlet] (ServerService Thread Pool -- 94) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, GET]
2022-12-12 16:30:24,242 WARN [io.undertow.servlet] (ServerService Thread Pool -- 101) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET, PUT]
2022-12-12 16:30:24,241 WARN [io.undertow.servlet] (ServerService Thread Pool -- 88) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET]
2022-12-12 16:30:24,242 WARN [io.undertow.servlet] (ServerService Thread Pool -- 80) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET]
2022-12-12 16:30:24,241 WARN [io.undertow.servlet] (ServerService Thread Pool -- 90) UT015020: Path /* is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET]
2022-12-12 16:30:24,310 DEBUG [org.ejbca.core.model.util.EjbLocalHelper] (ServerService Thread Pool -- 94) Failed JEE5 version of EjbBridgeSessionLocal JNDI lookup. All future lookups will JEE6 version lookups.
2022-12-12 16:30:24,311 DEBUG [org.ejbca.core.model.util.EjbLocalHelper] (ServerService Thread Pool -- 103) Failed JEE5 version of EjbBridgeSessionLocal JNDI lookup. All future lookups will JEE6 version lookups.
2022-12-12 16:30:24,343 INFO [javax.enterprise.resource.webcontainer.jsf.config] (ServerService Thread Pool -- 100) Initializing Mojarra 2.3.9.SP03 for context '/ejbca/ra'
2022-12-12 16:30:24,492 DEBUG [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) >startup initializing random seed, can take a little time...
2022-12-12 16:30:24,492 DEBUG [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) >startup finished initializing random seed
2022-12-12 16:30:24,492 DEBUG [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) >startup checking classloader of BouncyCastle provider
2022-12-12 16:30:24,557 DEBUG [org.cesecore.certificates.util.DnComponents] (ServerService Thread Pool -- 76) Using default values for DN components
2022-12-12 16:30:24,560 DEBUG [org.cesecore.certificates.util.DnComponents] (ServerService Thread Pool -- 76) Read profile maps with 49 lines.
2022-12-12 16:30:24,585 DEBUG [org.cesecore.certificates.util.DnComponents] (ServerService Thread Pool -- 76) Properties file /profilemappings_enterprise.properties was not found.
2022-12-12 16:31:02,727 INFO [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) BouncyCastle provider is from our ejbca.ear classloader.
2022-12-12 16:31:05,328 DEBUG [org.cesecore.authorization.user.matchvalues.AccessMatchValueReverseLookupRegistry] (ServerService Thread Pool -- 76) Registered AuthenticationToken of type PublicAccessAuthenticationToken with match keys [TRANSPORT_ANY, TRANSPORT_PLAIN, TRANSPORT_CONFIDENTIAL]
2022-12-12 16:31:05,337 DEBUG [org.cesecore.authorization.user.matchvalues.AccessMatchValueReverseLookupRegistry] (ServerService Thread Pool -- 76) Registered AuthenticationToken of type CertificateAuthenticationToken with match keys [NONE, WITH_COUNTRY, WITH_DOMAINCOMPONENT, WITH_STATEORPROVINCE, WITH_LOCALITY, WITH_ORGANIZATION, WITH_ORGANIZATIONALUNIT, WITH_TITLE, WITH_COMMONNAME, WITH_UID, WITH_DNSERIALNUMBER, WITH_SERIALNUMBER, WITH_DNEMAILADDRESS, WITH_RFC822NAME, WITH_UPN, WITH_FULLDN]
2022-12-12 16:31:05,337 DEBUG [org.cesecore.authorization.user.matchvalues.AccessMatchValueReverseLookupRegistry] (ServerService Thread Pool -- 76) Registered AuthenticationToken of type AlwaysAllowLocalAuthenticationToken with match keys [INSTANCE, DEFAULT]
2022-12-12 16:31:05,339 DEBUG [org.cesecore.authorization.user.matchvalues.AccessMatchValueReverseLookupRegistry] (ServerService Thread Pool -- 76) Registered AuthenticationToken of type OAuth2AuthenticationToken with match keys [CLAIM_SUBJECT, CLAIM_ISSUER, CLAIM_AUDIENCE, CLAIM_OBJECTID, CLAIM_ROLE]
2022-12-12 16:31:05,343 DEBUG [org.cesecore.authorization.user.matchvalues.AccessMatchValueReverseLookupRegistry] (ServerService Thread Pool -- 76) Registered AuthenticationToken of type CliAuthenticationToken with match keys [USERNAME]
2022-12-12 16:31:05,344 INFO [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) Registered AuthenticationTokens [OAuth2AuthenticationToken, PublicAccessAuthenticationToken, CertificateAuthenticationToken, CliAuthenticationToken, AlwaysAllowLocalAuthenticationToken]
2022-12-12 16:31:05,344 DEBUG [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) >startup CryptoTokenFactory just to load those classes that are available
2022-12-12 16:31:05,415 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) CryptoTokenFactory adding available crypto token se.primeKey.caToken.card.PrimeCAToken
2022-12-12 16:31:05,418 INFO [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Class not found: se.primeKey.caToken.card.PrimeCAToken.
2022-12-12 16:31:05,418 INFO [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Can not register se.primeKey.caToken.card.PrimeCAToken. This is normally not an error.
2022-12-12 16:31:05,445 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) CryptoTokenFactory adding available crypto token org.cesecore.keys.token.PKCS11CryptoToken
2022-12-12 16:31:15,394 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Registered org.cesecore.keys.token.PKCS11CryptoToken successfully.
2022-12-12 16:31:15,416 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) CryptoTokenFactory adding available crypto token org.cesecore.keys.token.SoftCryptoToken
2022-12-12 16:31:15,426 DEBUG [org.cesecore.keys.token.SoftCryptoToken] (ServerService Thread Pool -- 76) Creating SoftCryptoToken
2022-12-12 16:31:15,426 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Registered org.cesecore.keys.token.SoftCryptoToken successfully.
2022-12-12 16:31:15,430 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) CryptoTokenFactory adding available crypto token org.cesecore.keys.token.NullCryptoToken
2022-12-12 16:31:15,430 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Registered org.cesecore.keys.token.NullCryptoToken successfully.
2022-12-12 16:31:15,443 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) CryptoTokenFactory adding available crypto token org.cesecore.keys.token.AzureCryptoToken
2022-12-12 16:31:22,154 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Registered org.cesecore.keys.token.AzureCryptoToken successfully.
2022-12-12 16:31:22,155 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) CryptoTokenFactory adding available crypto token org.ejbca.keys.token.AWSKMSCryptoToken
2022-12-12 16:31:22,155 INFO [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Class not found: org.ejbca.keys.token.AWSKMSCryptoToken.
2022-12-12 16:31:22,156 INFO [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Can not register org.ejbca.keys.token.AWSKMSCryptoToken. This is normally not an error.
2022-12-12 16:31:22,156 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) CryptoTokenFactory adding available crypto token org.cesecore.keys.token.p11ng.cryptotoken.Pkcs11NgCryptoToken
2022-12-12 16:31:22,156 INFO [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Class not found: org.cesecore.keys.token.p11ng.cryptotoken.Pkcs11NgCryptoToken.
2022-12-12 16:31:22,156 INFO [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Can not register org.cesecore.keys.token.p11ng.cryptotoken.Pkcs11NgCryptoToken. This is normally not an error.
2022-12-12 16:31:22,365 DEBUG [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) >startup loading CAs into cache
2022-12-12 16:31:52,124 INFO [org.cesecore.dbprotection.ProtectedData] (ServerService Thread Pool -- 76) No database integrity protection available in this version of EJBCA.
2022-12-12 16:33:06,075 DEBUG [org.cesecore.certificates.ca.CABaseCommon] (ServerService Thread Pool -- 76) implementation classname for extended service type: 3 is org.ejbca.core.model.ca.caadmin.extendedcaservices.CmsCAService
2022-12-12 16:33:58,383 DEBUG [org.ejbca.core.model.ca.caadmin.extendedcaservices.CmsCAService] (ServerService Thread Pool -- 76) Not trying to load KEYSTORE for inactive CmsCAService, subjectDN:'CN=CMSCertificate, E=contacto@signapis.com,CN=Autoridad Certificadora Raiz Signapis,OU=77423125-0,O=E-Digital PKI,L=Santiago,C=CL'.
2022-12-12 16:34:15,760 DEBUG [org.cesecore.certificates.ca.CABaseCommon] (ServerService Thread Pool -- 76) implementation classname for extended service type: 5 is org.ejbca.core.model.ca.caadmin.extendedcaservices.KeyRecoveryCAService
2022-12-12 16:35:01,553 ERROR [org.jboss.as.controller.management-operation] (Controller Boot Thread) WFLYCTL0348: Timeout after [300] seconds waiting for service container stability. Operation will roll back. Step that first updated the service container was 'add' at address '
("core-service" => "management"),
("management-interface" => "http-interface") '
Thanks for the help.
If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
Hi Tomas, I had a problem with the capacity of the server where I was trying to restore the ejbca, this stopped the wildfly service without errors and that's why we didn't see errors in the log. I increased the capacity of the server and now I see errors in the log that I can't identify. Could you help me with this problem? attach the log record with the errors. Please help me...
Hi, I try restore an EJBCA with backup database, p12 and conf file. But fail every time.
Configuration:
EJBCA_7_9_0_2 (Upgrade from EJBCA_7_4_3_2)
MariaDB database (new installation) - MYSQL (old installation)
Hsm Safenet Luna (with keys of the CA and SubCA)
Steps I followed for the restore:
1. restore the database Mysql in new MariaDB, create de user ejbca with new password
2. Installed mariadb-java-client.
3. Followed the steps to install Wildfly 18.0 and configurate the credentials for elytron for the database and keystore.
4. copied the files of EJBCA-7_9_0_2 and restore the /conf/ and /p12/ files of my old installation
5. Modified the file database.properties with the conection to new database
6. Installed the LunaClient to have comunication with the hsm
7. Verified that i have access to the hsm partition where have created the keys of CA y SubCA of my old installation
8. Modified the file Chrystoki.conf for conection with hsm and added user wildfly to hsm group
9. Verify that library paths to Safenet luna are activated in web.properties file.
10. In path /ejbca_7_9_0_2 execute sudo ant clean deployear and sudo ant deploy-keystore
11._ Execute the command sudo systemctl restart wildfly.
I see the next Error in Wildfly log:
2022-12-06 19:41:33,802 DEBUG [org.jboss.as.config] (MSC service thread 1-3) Configured system properties:
[Standalone] =
awt.toolkit = sun.awt.X11.XToolkit
file.encoding = UTF-8
file.encoding.pkg = sun.io
file.separator = /
java.awt.graphicsenv = sun.awt.X11GraphicsEnvironment
java.awt.headless = true
java.awt.printerjob = sun.print.PSPrinterJob
java.class.path = /opt/wildfly/jboss-modules.jar
java.class.version = 52.0
java.endorsed.dirs = /usr/lib/jvm/java-8-openjdk-amd64/jre/lib/endorsed
java.ext.dirs = /usr/lib/jvm/java-8-openjdk-amd64/jre/lib/ext:/usr/java/packages/lib/ext
java.home = /usr/lib/jvm/java-8-openjdk-amd64/jre
java.io.tmpdir = /tmp
java.library.path = /usr/java/packages/lib/amd64:/usr/lib/x86_64-linux-gnu/jni:/lib/x86_64-linux-gnu:/usr/lib/x86_64-linux-gnu:/usr/lib/jni:/lib:/usr/lib
java.net.preferIPv4Stack = true
java.runtime.name = OpenJDK Runtime Environment
java.runtime.version = 1.8.0_352-8u352-ga-1~22.04-b08
java.specification.maintenance.version = 4
java.specification.name = Java Platform API Specification
java.specification.vendor = Oracle Corporation
java.specification.version = 1.8
java.util.logging.manager = org.jboss.logmanager.LogManager
java.vendor = Private Build
java.vendor.url = http://java.oracle.com/
java.vendor.url.bug = http://bugreport.sun.com/bugreport/
java.version = 1.8.0_352
java.vm.info = mixed mode
java.vm.name = OpenJDK 64-Bit Server VM
java.vm.specification.name = Java Virtual Machine Specification
java.vm.specification.vendor = Oracle Corporation
java.vm.specification.version = 1.8
java.vm.vendor = Private Build
java.vm.version = 25.352-b08
javax.management.builder.initial = org.jboss.as.jmx.PluggableMBeanServerBuilder
jboss.bind.address = 0.0.0.0
jboss.home.dir = /opt/wildfly
jboss.host.name = ip-172-31-40-135
jboss.modules.dir = /opt/wildfly/modules
jboss.modules.system.pkgs = org.jboss.byteman
jboss.node.name = ip-172-31-40-135
jboss.qualified.host.name = ip-172-31-40-135.us-east-2.compute.internal
jboss.server.base.dir = /opt/wildfly/standalone
jboss.server.config.dir = /opt/wildfly/standalone/configuration
jboss.server.data.dir = /opt/wildfly/standalone/data
jboss.server.deploy.dir = /opt/wildfly/standalone/data/content
jboss.server.log.dir = /opt/wildfly/standalone/log
jboss.server.name = <omited>
jboss.server.persist.config = true
jboss.server.temp.dir = /opt/wildfly/standalone/tmp
line.separator =</omited>
2022-12-06 19:41:33,806 DEBUG [org.jboss.as.config] (MSC service thread 1-3) VM Arguments: -D[Standalone] -Xms64m -Xmx512m -XX:MetaspaceSize=96M -XX:MaxMetaspaceSize=256m -Djava.net.preferIPv4Stack=true -Djboss.modules.system.pkgs=org.jboss.byteman -Djava.awt.headless=true -Dorg.jboss.boot.log.file=/opt/wildfly/standalone/log/server.log -Dlogging.configuration=file:/opt/wildfly/standalone/configuration/logging.properties
2022-12-06 19:41:37,013 INFO [org.xnio] (MSC service thread 1-1) XNIO version 3.7.3.Final
2022-12-06 19:41:37,022 INFO [org.xnio.nio] (MSC service thread 1-1) XNIO NIO Implementation Version 3.7.3.Final
2022-12-06 19:41:37,225 WARN [org.jboss.as.txn] (ServerService Thread Pool -- 72) WFLYTX0013: The node-identifier attribute on the /subsystem=transactions is set to the default value. This is a danger for environments running multiple servers. Please make sure the attribute value is unique.
2022-12-06 19:41:41,387 INFO [org.infinispan.factories.GlobalComponentRegistry] (MSC service thread 1-3) ISPN000128: Infinispan version: Infinispan 'Infinity Minus ONE +2' 9.4.16.Final
2022-12-06 19:41:51,908 INFO [org.hibernate.jpa.internal.util.LogHelper] (ServerService Thread Pool -- 76) HHH000204: Processing PersistenceUnitInfo
name: ejbca
...
2022-12-06 19:41:52,076 INFO [org.hibernate.Version] (ServerService Thread Pool -- 76) HHH000412: Hibernate Core {5.3.12.Final}
2022-12-06 19:41:52,079 INFO [org.hibernate.cfg.Environment] (ServerService Thread Pool -- 76) HHH000206: hibernate.properties not found
2022-12-06 19:41:52,246 INFO [org.hibernate.validator.internal.util.Version] (MSC service thread 1-4) HV000001: Hibernate Validator 6.0.17.Final
2022-12-06 19:41:52,461 INFO [org.hibernate.annotations.common.Version] (ServerService Thread Pool -- 76) HCANN000001: Hibernate Commons Annotations {5.0.5.Final}
2022-12-06 19:41:52,652 ERROR [org.jboss.as.jsf] (MSC service thread 1-1) WFLYJSF0002: Could not load JSF managed bean class: org.ejbca.ui.web.admin.peerconnector.PeerConnectorsMBean
2022-12-06 19:41:52,803 ERROR [org.jboss.as.jsf] (MSC service thread 1-1) WFLYJSF0002: Could not load JSF managed bean class: org.ejbca.ui.web.admin.peerconnector.PeerConnectorMBean
2022-12-06 19:41:55,677 WARN [org.jboss.as.jaxrs] (MSC service thread 1-4) WFLYRS0018: Explicit usage of Jackson annotation in a JAX-RS deployment; the system will disable JSON-B processing for the current deployment. Consider setting the 'resteasy.preferJacksonOverJsonB' property to 'false' to restore JSON-B.
2022-12-06 19:41:57,700 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-1) Creating Service {http://ws.protocol.core.ejbca.org/}EjbcaWSService from class org.ejbca.core.protocol.ws.EjbcaWS
2022-12-06 19:41:58,017 INFO [org.hibernate.dialect.Dialect] (ServerService Thread Pool -- 76) HHH000400: Using dialect: org.hibernate.dialect.MySQL5InnoDBDialect
2022-12-06 19:41:58,119 INFO [org.hibernate.envers.boot.internal.EnversServiceImpl] (ServerService Thread Pool -- 76) Envers integration enabled? : true
2022-12-06 19:41:58,259 INFO [org.cesecore.configuration.GlobalConfigurationData] (ServerService Thread Pool -- 76) Class 'org.widget.Bar' was not found on classpath.
2022-12-06 19:41:58,267 INFO [org.cesecore.configuration.GlobalConfigurationData] (ServerService Thread Pool -- 76) Class 'org.widget.Foo' was not found on classpath.
2022-12-06 19:41:59,311 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-1) Setting the server's publish address to be http://jbossws.undefined.host:4447/ejbca/ejbcaws/ejbcaws
2022-12-06 19:42:01,303 INFO [org.hibernate.hql.internal.QueryTranslatorFactoryInitiator] (ServerService Thread Pool -- 76) HHH000397: Using ASTQueryTranslatorFactory
2022-12-06 19:42:01,824 WARN [org.jboss.weld.Bootstrap] (MSC service thread 1-2) WELD-000146: BeforeBeanDiscovery.addAnnotatedType(AnnotatedType<?>) used for class com.sun.faces.flow.FlowDiscoveryCDIHelper is deprecated from CDI 1.1!
2022-12-06 19:42:01,847 INFO [io.smallrye.metrics] (MSC service thread 1-2) MicroProfile: Metrics activated
2022-12-06 19:42:04,142 SEVERE [javax.enterprise.resource.webcontainer.jsf.flow] (MSC service thread 1-2) Unable to obtain CDI 1.1 utilities for Mojarra
2022-12-06 19:42:04,151 SEVERE [javax.enterprise.resource.webcontainer.jsf.application.view] (MSC service thread 1-2) Unable to obtain CDI 1.1 utilities for Mojarra
2022-12-06 19:42:05,190 DEBUG [org.ejbca.core.ejb.ra.raadmin.RaStyleCacheBean] (ServerService Thread Pool -- 93) org.ejbca.core.ejb.ra.raadmin.RaStyleCacheBean initialized
2022-12-06 19:42:05,206 DEBUG [org.cesecore.certificates.ca.internal.CaIDCacheBean] (ServerService Thread Pool -- 88) Loading CA ID cache from database
2022-12-06 19:42:05,245 DEBUG [org.ejbca.config.EjbcaConfigurationHolder] (ServerService Thread Pool -- 79) Added url to configuration source: vfs:/content/ejbca.ear/lib/ejbca-properties.jar/conf/ejbca.properties
2022-12-06 19:42:05,247 DEBUG [org.ejbca.config.EjbcaConfigurationHolder] (ServerService Thread Pool -- 79) Added url to configuration source: vfs:/content/ejbca.ear/lib/ejbca-properties.jar/conf/web.properties
2022-12-06 19:42:05,247 DEBUG [org.ejbca.config.EjbcaConfigurationHolder] (ServerService Thread Pool -- 79) Added url to configuration source: vfs:/content/ejbca.ear/lib/ejbca-properties.jar/conf/database.properties
2022-12-06 19:42:05,250 DEBUG [org.ejbca.config.EjbcaConfigurationHolder] (ServerService Thread Pool -- 79) Added url to configuration source: vfs:/content/ejbca.ear/lib/ejbca-properties.jar/internal.properties
2022-12-06 19:42:05,255 INFO [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) Init, EJBCA 7.9.0.2 Community (e9a5aa4700aaffc5468fbbd06780a6c100b18cb8) startup.
2022-12-06 19:42:05,259 DEBUG [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) >startup re-installing BC-provider
2022-12-06 19:42:05,719 WARN [io.undertow.servlet] (ServerService Thread Pool -- 98) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, GET]
2022-12-06 19:42:05,724 WARN [io.undertow.servlet] (ServerService Thread Pool -- 90) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET]
2022-12-06 19:42:05,725 WARN [io.undertow.servlet] (ServerService Thread Pool -- 92) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET]
2022-12-06 19:42:05,725 WARN [io.undertow.servlet] (ServerService Thread Pool -- 81) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, GET]
2022-12-06 19:42:05,725 WARN [io.undertow.servlet] (ServerService Thread Pool -- 109) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET]
2022-12-06 19:42:05,729 WARN [io.undertow.servlet] (ServerService Thread Pool -- 104) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET]
2022-12-06 19:42:05,725 WARN [io.undertow.servlet] (ServerService Thread Pool -- 105) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET]
2022-12-06 19:42:05,730 WARN [io.undertow.servlet] (ServerService Thread Pool -- 94) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET]
2022-12-06 19:42:05,731 WARN [io.undertow.servlet] (ServerService Thread Pool -- 102) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET]
2022-12-06 19:42:05,732 WARN [io.undertow.servlet] (ServerService Thread Pool -- 99) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, GET]
2022-12-06 19:42:05,751 WARN [io.undertow.servlet] (ServerService Thread Pool -- 83) UT015020: Path /* is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET, PUT]
2022-12-06 19:42:05,799 DEBUG [org.ejbca.core.model.util.EjbLocalHelper] (ServerService Thread Pool -- 81) Failed JEE5 version of EjbBridgeSessionLocal JNDI lookup. All future lookups will JEE6 version lookups.
2022-12-06 19:42:05,799 DEBUG [org.ejbca.core.model.util.EjbLocalHelper] (ServerService Thread Pool -- 99) Failed JEE5 version of EjbBridgeSessionLocal JNDI lookup. All future lookups will JEE6 version lookups.
2022-12-06 19:42:05,843 INFO [javax.enterprise.resource.webcontainer.jsf.config] (ServerService Thread Pool -- 84) Initializing Mojarra 2.3.9.SP03 for context '/ejbca/ra'
2022-12-06 19:42:06,116 DEBUG [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) >startup initializing random seed, can take a little time...
2022-12-06 19:42:06,117 DEBUG [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) >startup finished initializing random seed
2022-12-06 19:42:06,117 DEBUG [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) >startup checking classloader of BouncyCastle provider
2022-12-06 19:42:06,175 DEBUG [org.cesecore.certificates.util.DnComponents] (ServerService Thread Pool -- 76) Using default values for DN components
2022-12-06 19:42:06,179 DEBUG [org.cesecore.certificates.util.DnComponents] (ServerService Thread Pool -- 76) Read profile maps with 49 lines.
2022-12-06 19:42:06,180 DEBUG [org.cesecore.certificates.util.DnComponents] (ServerService Thread Pool -- 76) Properties file /profilemappings_enterprise.properties was not found.
2022-12-06 19:42:06,357 INFO [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) BouncyCastle provider is from our ejbca.ear classloader.
2022-12-06 19:42:06,364 DEBUG [org.cesecore.authorization.user.matchvalues.AccessMatchValueReverseLookupRegistry] (ServerService Thread Pool -- 76) Registered AuthenticationToken of type PublicAccessAuthenticationToken with match keys [TRANSPORT_ANY, TRANSPORT_PLAIN, TRANSPORT_CONFIDENTIAL]
2022-12-06 19:42:06,370 DEBUG [org.cesecore.authorization.user.matchvalues.AccessMatchValueReverseLookupRegistry] (ServerService Thread Pool -- 76) Registered AuthenticationToken of type CertificateAuthenticationToken with match keys [NONE, WITH_COUNTRY, WITH_DOMAINCOMPONENT, WITH_STATEORPROVINCE, WITH_LOCALITY, WITH_ORGANIZATION, WITH_ORGANIZATIONALUNIT, WITH_TITLE, WITH_COMMONNAME, WITH_UID, WITH_DNSERIALNUMBER, WITH_SERIALNUMBER, WITH_DNEMAILADDRESS, WITH_RFC822NAME, WITH_UPN, WITH_FULLDN]
2022-12-06 19:42:06,372 DEBUG [org.cesecore.authorization.user.matchvalues.AccessMatchValueReverseLookupRegistry] (ServerService Thread Pool -- 76) Registered AuthenticationToken of type AlwaysAllowLocalAuthenticationToken with match keys [INSTANCE, DEFAULT]
2022-12-06 19:42:06,374 DEBUG [org.cesecore.authorization.user.matchvalues.AccessMatchValueReverseLookupRegistry] (ServerService Thread Pool -- 76) Registered AuthenticationToken of type OAuth2AuthenticationToken with match keys [CLAIM_SUBJECT, CLAIM_ISSUER, CLAIM_AUDIENCE, CLAIM_OBJECTID, CLAIM_ROLE]
2022-12-06 19:42:06,382 DEBUG [org.cesecore.authorization.user.matchvalues.AccessMatchValueReverseLookupRegistry] (ServerService Thread Pool -- 76) Registered AuthenticationToken of type CliAuthenticationToken with match keys [USERNAME]
2022-12-06 19:42:06,382 INFO [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) Registered AuthenticationTokens [OAuth2AuthenticationToken, PublicAccessAuthenticationToken, CertificateAuthenticationToken, CliAuthenticationToken, AlwaysAllowLocalAuthenticationToken]
2022-12-06 19:42:06,382 DEBUG [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) >startup CryptoTokenFactory just to load those classes that are available
2022-12-06 19:42:06,385 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) CryptoTokenFactory adding available crypto token se.primeKey.caToken.card.PrimeCAToken
2022-12-06 19:42:06,393 INFO [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Class not found: se.primeKey.caToken.card.PrimeCAToken.
2022-12-06 19:42:06,394 INFO [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Can not register se.primeKey.caToken.card.PrimeCAToken. This is normally not an error.
2022-12-06 19:42:06,403 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) CryptoTokenFactory adding available crypto token org.cesecore.keys.token.PKCS11CryptoToken
2022-12-06 19:42:06,423 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Registered org.cesecore.keys.token.PKCS11CryptoToken successfully.
2022-12-06 19:42:06,424 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) CryptoTokenFactory adding available crypto token org.cesecore.keys.token.SoftCryptoToken
2022-12-06 19:42:06,425 DEBUG [org.cesecore.keys.token.SoftCryptoToken] (ServerService Thread Pool -- 76) Creating SoftCryptoToken
2022-12-06 19:42:06,425 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Registered org.cesecore.keys.token.SoftCryptoToken successfully.
2022-12-06 19:42:06,426 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) CryptoTokenFactory adding available crypto token org.cesecore.keys.token.NullCryptoToken
2022-12-06 19:42:06,426 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Registered org.cesecore.keys.token.NullCryptoToken successfully.
And it stops there, without up the wildfly and therefore the EJBCA. I don't understand what I could be doing wrong or what could be happening.
There is no errors at all in the log snippet you paste there. Are you sure it's all that WildFly prints? I see that you enabled DEBUG logging for EJBCA.. Did you configure EjbcaDS in WildFly? Typically that should be logged by WildFly during startup.
Hello Tomas, thanks for the answer, configure the ejbcaDS with the following command: /opt/wildfly/bin/jboss-cli.sh --user=admin --password=<password> --connect 'data-source add --name=ejbcads --driver-name="mariadb-java-client.jar" --connection-url="jdbc:mysql://<url database="">:3306/ejbca" --jndi-name="java:/EjbcaDS" --use-ccm=true --driver-class="org.mariadb.jdbc.Driver" --user-name="ejbca" --credential-reference={store=defaultCS, alias=dbPassword} --validate-on-match=true --background-validation=false --prepared-statements-cache-size=50 --share-prepared-statements=true --min-pool-size=5 --max-pool-size=150 --pool-prefill=true --transaction-isolation=TRANSACTION_READ_COMMITTED --check-valid-connection-sql="select 1;"'</url></password>
Take another try and this is the wildfly log (/opt/wildfly/standalone/log/server.log):
2022-12-12 16:29:51,712 DEBUG [org.jboss.as.config] (MSC service thread 1-1) Configured system properties:
[Standalone] =
awt.toolkit = sun.awt.X11.XToolkit
file.encoding = UTF-8
file.encoding.pkg = sun.io
file.separator = /
java.awt.graphicsenv = sun.awt.X11GraphicsEnvironment
java.awt.headless = true
java.awt.printerjob = sun.print.PSPrinterJob
java.class.path = /opt/wildfly/jboss-modules.jar
java.class.version = 52.0
java.endorsed.dirs = /usr/lib/jvm/java-8-openjdk-amd64/jre/lib/endorsed
java.ext.dirs = /usr/lib/jvm/java-8-openjdk-amd64/jre/lib/ext:/usr/java/packages/lib/ext
java.home = /usr/lib/jvm/java-8-openjdk-amd64/jre
java.io.tmpdir = /tmp
java.library.path = /usr/java/packages/lib/amd64:/usr/lib/x86_64-linux-gnu/jni:/lib/x86_64-linux-gnu:/usr/lib/x86_64-linux-gnu:/usr/lib/jni:/lib:/usr/lib
java.net.preferIPv4Stack = true
java.runtime.name = OpenJDK Runtime Environment
java.runtime.version = 1.8.0_352-8u352-ga-1~22.04-b08
java.specification.maintenance.version = 4
java.specification.name = Java Platform API Specification
java.specification.vendor = Oracle Corporation
java.specification.version = 1.8
java.util.logging.manager = org.jboss.logmanager.LogManager
java.vendor = Private Build
java.vendor.url = http://java.oracle.com/
java.vendor.url.bug = http://bugreport.sun.com/bugreport/
java.version = 1.8.0_352
java.vm.info = mixed mode
java.vm.name = OpenJDK 64-Bit Server VM
java.vm.specification.name = Java Virtual Machine Specification
java.vm.specification.vendor = Oracle Corporation
java.vm.specification.version = 1.8
java.vm.vendor = Private Build
java.vm.version = 25.352-b08
javax.management.builder.initial = org.jboss.as.jmx.PluggableMBeanServerBuilder
jboss.bind.address = 0.0.0.0
jboss.home.dir = /opt/wildfly
jboss.host.name = ip-172-31-40-135
jboss.modules.dir = /opt/wildfly/modules
jboss.modules.system.pkgs = org.jboss.byteman
jboss.node.name = ip-172-31-40-135
jboss.qualified.host.name = ip-172-31-40-135.us-east-2.compute.internal
jboss.server.base.dir = /opt/wildfly/standalone
jboss.server.config.dir = /opt/wildfly/standalone/configuration
jboss.server.data.dir = /opt/wildfly/standalone/data
jboss.server.deploy.dir = /opt/wildfly/standalone/data/content
jboss.server.log.dir = /opt/wildfly/standalone/log
jboss.server.name = ip-172-31-40-135
jboss.server.persist.config = true
jboss.server.temp.dir = /opt/wildfly/standalone/tmp
line.separator =
2022-12-12 16:29:51,716 DEBUG [org.jboss.as.config] (MSC service thread 1-1) VM Arguments: -D[Standalone] -Xms64m -Xmx512m -XX:MetaspaceSize=96M -XX:MaxMetaspaceSize=256m -Djava.net.preferIPv4Stack=true -Djboss.modules.system.pkgs=org.jboss.byteman -Djava.awt.headless=true -Dorg.jboss.boot.log.file=/opt/wildfly/standalone/log/server.log -Dlogging.configuration=file:/opt/wildfly/standalone/configuration/logging.properties
2022-12-12 16:29:54,701 INFO [org.xnio] (MSC service thread 1-3) XNIO version 3.7.3.Final
2022-12-12 16:29:54,726 INFO [org.xnio.nio] (MSC service thread 1-3) XNIO NIO Implementation Version 3.7.3.Final
2022-12-12 16:29:54,998 WARN [org.jboss.as.txn] (ServerService Thread Pool -- 72) WFLYTX0013: The node-identifier attribute on the /subsystem=transactions is set to the default value. This is a danger for environments running multiple servers. Please make sure the attribute value is unique.
2022-12-12 16:29:59,359 INFO [org.infinispan.factories.GlobalComponentRegistry] (MSC service thread 1-3) ISPN000128: Infinispan version: Infinispan 'Infinity Minus ONE +2' 9.4.16.Final
2022-12-12 16:30:11,383 INFO [org.hibernate.jpa.internal.util.LogHelper] (ServerService Thread Pool -- 76) HHH000204: Processing PersistenceUnitInfo
name: ejbca
...
2022-12-12 16:30:11,553 INFO [org.hibernate.Version] (ServerService Thread Pool -- 76) HHH000412: Hibernate Core {5.3.12.Final}
2022-12-12 16:30:11,557 INFO [org.hibernate.cfg.Environment] (ServerService Thread Pool -- 76) HHH000206: hibernate.properties not found
2022-12-12 16:30:11,745 INFO [org.hibernate.validator.internal.util.Version] (MSC service thread 1-4) HV000001: Hibernate Validator 6.0.17.Final
2022-12-12 16:30:11,924 INFO [org.hibernate.annotations.common.Version] (ServerService Thread Pool -- 76) HCANN000001: Hibernate Commons Annotations {5.0.5.Final}
2022-12-12 16:30:12,434 ERROR [org.jboss.as.jsf] (MSC service thread 1-3) WFLYJSF0002: Could not load JSF managed bean class: org.ejbca.ui.web.admin.peerconnector.PeerConnectorsMBean
2022-12-12 16:30:12,634 ERROR [org.jboss.as.jsf] (MSC service thread 1-3) WFLYJSF0002: Could not load JSF managed bean class: org.ejbca.ui.web.admin.peerconnector.PeerConnectorMBean
2022-12-12 16:30:14,956 WARN [org.jboss.as.jaxrs] (MSC service thread 1-4) WFLYRS0018: Explicit usage of Jackson annotation in a JAX-RS deployment; the system will disable JSON-B processing for the current deployment. Consider setting the 'resteasy.preferJacksonOverJsonB' property to 'false' to restore JSON-B.
2022-12-12 16:30:16,998 INFO [org.hibernate.dialect.Dialect] (ServerService Thread Pool -- 76) HHH000400: Using dialect: org.hibernate.dialect.MySQL5InnoDBDialect
2022-12-12 16:30:17,016 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-2) Creating Service {http://ws.protocol.core.ejbca.org/}EjbcaWSService from class org.ejbca.core.protocol.ws.EjbcaWS
2022-12-12 16:30:17,185 INFO [org.hibernate.envers.boot.internal.EnversServiceImpl] (ServerService Thread Pool -- 76) Envers integration enabled? : true
2022-12-12 16:30:17,366 INFO [org.cesecore.configuration.GlobalConfigurationData] (ServerService Thread Pool -- 76) Class 'org.widget.Bar' was not found on classpath.
2022-12-12 16:30:17,367 INFO [org.cesecore.configuration.GlobalConfigurationData] (ServerService Thread Pool -- 76) Class 'org.widget.Foo' was not found on classpath.
2022-12-12 16:30:18,623 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-2) Setting the server's publish address to be http://jbossws.undefined.host:4447/ejbca/ejbcaws/ejbcaws
2022-12-12 16:30:20,629 INFO [org.hibernate.hql.internal.QueryTranslatorFactoryInitiator] (ServerService Thread Pool -- 76) HHH000397: Using ASTQueryTranslatorFactory
2022-12-12 16:30:21,112 WARN [org.jboss.weld.Bootstrap] (MSC service thread 1-4) WELD-000146: BeforeBeanDiscovery.addAnnotatedType(AnnotatedType<?>) used for class com.sun.faces.flow.FlowDiscoveryCDIHelper is deprecated from CDI 1.1!
2022-12-12 16:30:21,151 INFO [io.smallrye.metrics] (MSC service thread 1-4) MicroProfile: Metrics activated
2022-12-12 16:30:22,686 SEVERE [javax.enterprise.resource.webcontainer.jsf.flow] (MSC service thread 1-4) Unable to obtain CDI 1.1 utilities for Mojarra
2022-12-12 16:30:22,693 SEVERE [javax.enterprise.resource.webcontainer.jsf.application.view] (MSC service thread 1-4) Unable to obtain CDI 1.1 utilities for Mojarra
2022-12-12 16:30:23,738 DEBUG [org.cesecore.certificates.ca.internal.CaIDCacheBean] (ServerService Thread Pool -- 106) Loading CA ID cache from database
2022-12-12 16:30:23,737 DEBUG [org.ejbca.core.ejb.ra.raadmin.RaStyleCacheBean] (ServerService Thread Pool -- 82) org.ejbca.core.ejb.ra.raadmin.RaStyleCacheBean initialized
2022-12-12 16:30:23,754 DEBUG [org.ejbca.config.EjbcaConfigurationHolder] (ServerService Thread Pool -- 76) Added url to configuration source: vfs:/content/ejbca.ear/lib/ejbca-properties.jar/conf/ejbca.properties
2022-12-12 16:30:23,770 DEBUG [org.ejbca.config.EjbcaConfigurationHolder] (ServerService Thread Pool -- 76) Added url to configuration source: vfs:/content/ejbca.ear/lib/ejbca-properties.jar/conf/web.properties
2022-12-12 16:30:23,776 DEBUG [org.ejbca.config.EjbcaConfigurationHolder] (ServerService Thread Pool -- 76) Added url to configuration source: vfs:/content/ejbca.ear/lib/ejbca-properties.jar/conf/database.properties
2022-12-12 16:30:23,796 DEBUG [org.ejbca.config.EjbcaConfigurationHolder] (ServerService Thread Pool -- 76) Added url to configuration source: vfs:/content/ejbca.ear/lib/ejbca-properties.jar/internal.properties
2022-12-12 16:30:23,806 INFO [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) Init, EJBCA 7.9.0.2 Community (e9a5aa4700aaffc5468fbbd06780a6c100b18cb8) startup.
2022-12-12 16:30:23,814 DEBUG [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) >startup re-installing BC-provider
2022-12-12 16:30:24,241 WARN [io.undertow.servlet] (ServerService Thread Pool -- 91) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET]
2022-12-12 16:30:24,241 WARN [io.undertow.servlet] (ServerService Thread Pool -- 102) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET]
2022-12-12 16:30:24,241 WARN [io.undertow.servlet] (ServerService Thread Pool -- 89) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET]
2022-12-12 16:30:24,241 WARN [io.undertow.servlet] (ServerService Thread Pool -- 97) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET]
2022-12-12 16:30:24,241 WARN [io.undertow.servlet] (ServerService Thread Pool -- 98) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, GET]
2022-12-12 16:30:24,241 WARN [io.undertow.servlet] (ServerService Thread Pool -- 103) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, GET]
2022-12-12 16:30:24,241 WARN [io.undertow.servlet] (ServerService Thread Pool -- 94) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, GET]
2022-12-12 16:30:24,242 WARN [io.undertow.servlet] (ServerService Thread Pool -- 101) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET, PUT]
2022-12-12 16:30:24,241 WARN [io.undertow.servlet] (ServerService Thread Pool -- 88) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET]
2022-12-12 16:30:24,242 WARN [io.undertow.servlet] (ServerService Thread Pool -- 80) UT015020: Path / is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET]
2022-12-12 16:30:24,241 WARN [io.undertow.servlet] (ServerService Thread Pool -- 90) UT015020: Path /* is secured for some HTTP methods, however it is not secured for [HEAD, POST, GET]
2022-12-12 16:30:24,310 DEBUG [org.ejbca.core.model.util.EjbLocalHelper] (ServerService Thread Pool -- 94) Failed JEE5 version of EjbBridgeSessionLocal JNDI lookup. All future lookups will JEE6 version lookups.
2022-12-12 16:30:24,311 DEBUG [org.ejbca.core.model.util.EjbLocalHelper] (ServerService Thread Pool -- 103) Failed JEE5 version of EjbBridgeSessionLocal JNDI lookup. All future lookups will JEE6 version lookups.
2022-12-12 16:30:24,343 INFO [javax.enterprise.resource.webcontainer.jsf.config] (ServerService Thread Pool -- 100) Initializing Mojarra 2.3.9.SP03 for context '/ejbca/ra'
2022-12-12 16:30:24,492 DEBUG [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) >startup initializing random seed, can take a little time...
2022-12-12 16:30:24,492 DEBUG [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) >startup finished initializing random seed
2022-12-12 16:30:24,492 DEBUG [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) >startup checking classloader of BouncyCastle provider
2022-12-12 16:30:24,557 DEBUG [org.cesecore.certificates.util.DnComponents] (ServerService Thread Pool -- 76) Using default values for DN components
2022-12-12 16:30:24,560 DEBUG [org.cesecore.certificates.util.DnComponents] (ServerService Thread Pool -- 76) Read profile maps with 49 lines.
2022-12-12 16:30:24,585 DEBUG [org.cesecore.certificates.util.DnComponents] (ServerService Thread Pool -- 76) Properties file /profilemappings_enterprise.properties was not found.
2022-12-12 16:31:02,727 INFO [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) BouncyCastle provider is from our ejbca.ear classloader.
2022-12-12 16:31:05,328 DEBUG [org.cesecore.authorization.user.matchvalues.AccessMatchValueReverseLookupRegistry] (ServerService Thread Pool -- 76) Registered AuthenticationToken of type PublicAccessAuthenticationToken with match keys [TRANSPORT_ANY, TRANSPORT_PLAIN, TRANSPORT_CONFIDENTIAL]
2022-12-12 16:31:05,337 DEBUG [org.cesecore.authorization.user.matchvalues.AccessMatchValueReverseLookupRegistry] (ServerService Thread Pool -- 76) Registered AuthenticationToken of type CertificateAuthenticationToken with match keys [NONE, WITH_COUNTRY, WITH_DOMAINCOMPONENT, WITH_STATEORPROVINCE, WITH_LOCALITY, WITH_ORGANIZATION, WITH_ORGANIZATIONALUNIT, WITH_TITLE, WITH_COMMONNAME, WITH_UID, WITH_DNSERIALNUMBER, WITH_SERIALNUMBER, WITH_DNEMAILADDRESS, WITH_RFC822NAME, WITH_UPN, WITH_FULLDN]
2022-12-12 16:31:05,337 DEBUG [org.cesecore.authorization.user.matchvalues.AccessMatchValueReverseLookupRegistry] (ServerService Thread Pool -- 76) Registered AuthenticationToken of type AlwaysAllowLocalAuthenticationToken with match keys [INSTANCE, DEFAULT]
2022-12-12 16:31:05,339 DEBUG [org.cesecore.authorization.user.matchvalues.AccessMatchValueReverseLookupRegistry] (ServerService Thread Pool -- 76) Registered AuthenticationToken of type OAuth2AuthenticationToken with match keys [CLAIM_SUBJECT, CLAIM_ISSUER, CLAIM_AUDIENCE, CLAIM_OBJECTID, CLAIM_ROLE]
2022-12-12 16:31:05,343 DEBUG [org.cesecore.authorization.user.matchvalues.AccessMatchValueReverseLookupRegistry] (ServerService Thread Pool -- 76) Registered AuthenticationToken of type CliAuthenticationToken with match keys [USERNAME]
2022-12-12 16:31:05,344 INFO [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) Registered AuthenticationTokens [OAuth2AuthenticationToken, PublicAccessAuthenticationToken, CertificateAuthenticationToken, CliAuthenticationToken, AlwaysAllowLocalAuthenticationToken]
2022-12-12 16:31:05,344 DEBUG [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) >startup CryptoTokenFactory just to load those classes that are available
2022-12-12 16:31:05,415 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) CryptoTokenFactory adding available crypto token se.primeKey.caToken.card.PrimeCAToken
2022-12-12 16:31:05,418 INFO [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Class not found: se.primeKey.caToken.card.PrimeCAToken.
2022-12-12 16:31:05,418 INFO [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Can not register se.primeKey.caToken.card.PrimeCAToken. This is normally not an error.
2022-12-12 16:31:05,445 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) CryptoTokenFactory adding available crypto token org.cesecore.keys.token.PKCS11CryptoToken
2022-12-12 16:31:15,394 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Registered org.cesecore.keys.token.PKCS11CryptoToken successfully.
2022-12-12 16:31:15,416 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) CryptoTokenFactory adding available crypto token org.cesecore.keys.token.SoftCryptoToken
2022-12-12 16:31:15,426 DEBUG [org.cesecore.keys.token.SoftCryptoToken] (ServerService Thread Pool -- 76) Creating SoftCryptoToken
2022-12-12 16:31:15,426 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Registered org.cesecore.keys.token.SoftCryptoToken successfully.
2022-12-12 16:31:15,430 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) CryptoTokenFactory adding available crypto token org.cesecore.keys.token.NullCryptoToken
2022-12-12 16:31:15,430 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Registered org.cesecore.keys.token.NullCryptoToken successfully.
2022-12-12 16:31:15,443 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) CryptoTokenFactory adding available crypto token org.cesecore.keys.token.AzureCryptoToken
2022-12-12 16:31:22,154 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Registered org.cesecore.keys.token.AzureCryptoToken successfully.
2022-12-12 16:31:22,155 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) CryptoTokenFactory adding available crypto token org.ejbca.keys.token.AWSKMSCryptoToken
2022-12-12 16:31:22,155 INFO [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Class not found: org.ejbca.keys.token.AWSKMSCryptoToken.
2022-12-12 16:31:22,156 INFO [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Can not register org.ejbca.keys.token.AWSKMSCryptoToken. This is normally not an error.
2022-12-12 16:31:22,156 DEBUG [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) CryptoTokenFactory adding available crypto token org.cesecore.keys.token.p11ng.cryptotoken.Pkcs11NgCryptoToken
2022-12-12 16:31:22,156 INFO [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Class not found: org.cesecore.keys.token.p11ng.cryptotoken.Pkcs11NgCryptoToken.
2022-12-12 16:31:22,156 INFO [org.cesecore.keys.token.CryptoTokenFactory] (ServerService Thread Pool -- 76) Can not register org.cesecore.keys.token.p11ng.cryptotoken.Pkcs11NgCryptoToken. This is normally not an error.
2022-12-12 16:31:22,365 DEBUG [org.ejbca.core.ejb.StartupSingletonBean] (ServerService Thread Pool -- 76) >startup loading CAs into cache
2022-12-12 16:31:52,124 INFO [org.cesecore.dbprotection.ProtectedData] (ServerService Thread Pool -- 76) No database integrity protection available in this version of EJBCA.
2022-12-12 16:33:06,075 DEBUG [org.cesecore.certificates.ca.CABaseCommon] (ServerService Thread Pool -- 76) implementation classname for extended service type: 3 is org.ejbca.core.model.ca.caadmin.extendedcaservices.CmsCAService
2022-12-12 16:33:58,383 DEBUG [org.ejbca.core.model.ca.caadmin.extendedcaservices.CmsCAService] (ServerService Thread Pool -- 76) Not trying to load KEYSTORE for inactive CmsCAService, subjectDN:'CN=CMSCertificate, E=contacto@signapis.com,CN=Autoridad Certificadora Raiz Signapis,OU=77423125-0,O=E-Digital PKI,L=Santiago,C=CL'.
2022-12-12 16:34:15,760 DEBUG [org.cesecore.certificates.ca.CABaseCommon] (ServerService Thread Pool -- 76) implementation classname for extended service type: 5 is org.ejbca.core.model.ca.caadmin.extendedcaservices.KeyRecoveryCAService
2022-12-12 16:35:01,553 ERROR [org.jboss.as.controller.management-operation] (Controller Boot Thread) WFLYCTL0348: Timeout after [300] seconds waiting for service container stability. Operation will roll back. Step that first updated the service container was 'add' at address '
("core-service" => "management"),
("management-interface" => "http-interface")
'
Thanks for the help.
Hi Tomas, I had a problem with the capacity of the server where I was trying to restore the ejbca, this stopped the wildfly service without errors and that's why we didn't see errors in the log. I increased the capacity of the server and now I see errors in the log that I can't identify. Could you help me with this problem? attach the log record with the errors. Please help me...
Last edit: Josibeth Cedeño 2023-01-09