#8776 Nightly test failure in test_integration/test_installation.py/TestInstallCA/test_replica_ca_install_with_skip_schema_check
Closed: duplicate by frenaud. Opened by mpolovka.

test_replica_ca_install_with_skip_schema_check is failing in [testing_ipa-4.9_latest_selinux] Nightly PR #785 with an error

E           subprocess.CalledProcessError: Command '['ipa-ca-install', '-U', '-p', 'Secret.123', '-P', 'admin', '-w', 'Secret.123', '--skip-schema-check']' returned non-zero exit status 1.
pytest_ipa/integration/host.py:202: CalledProcessError
 ------------------------------Captured stderr call------------------------------ 
ipa: WARNING: ipa: ERROR: DNS is not configured
ipa: ERROR: stderr: ipaserver.install.dogtaginstance: CRITICAL Failed to configure CA instance
ipaserver.install.dogtaginstance: CRITICAL See the installation logs and the following files/directories for more information:
ipaserver.install.dogtaginstance: CRITICAL   /var/log/pki/pki-tomcat
CA configuration failed.

All logs.


@mpolovka the test is failing while installing a CA clone on replica0. ipareplica-ca-install.log shows that pkispawn timed out waiting for CA subsystem to start:

INFO: Waiting for CA subsystem to start (60s)
ERROR: Exception: CA subsystem did not start after 60s
  File "/usr/lib/python3.9/site-packages/pki/server/pkispawn.py", line 575, in main
    scriptlet.spawn(deployer)
  File "/usr/lib/python3.9/site-packages/pki/server/deployment/scriptlets/configuration.py", line 962, in spawn
    deployer.instance.wait_for_startup(
  File "/usr/lib/python3.9/site-packages/pki/server/deployment/pkihelper.py", line 890, in wait_for_startup
    raise Exception('%s subsystem did not start after %ds' %
2021-03-19T16:36:26Z CRITICAL Failed to configure CA instance

This message is similar to the issue reported in #8748 [Tracker] Random nightly failure in replica install: timeout Waiting for CA subsystem to start

The journal shows exactly the same symptoms:

Mar 19 16:36:27 replica0.ipa.test server[20720]: WARNING: Match [Server/Service/Connector] failed to set property [address] to [localhost6]
Mar 19 16:36:27 replica0.ipa.test java[20720]: usr/lib/api/apiutil.c Could not open /run/lock/opencryptoki/LCK..APIlock
Mar 19 16:36:28 replica0.ipa.test server[20720]: WARNING: Some of the specified [protocols] are not supported by the SSL engine and have been skipped: [[TLSv1, TLSv1.1]]
Mar 19 16:36:28 replica0.ipa.test server[20720]: SEVERE: Failed to initialize component [Connector[AJP/1.3-8009]]
Mar 19 16:36:28 replica0.ipa.test server[20720]: org.apache.catalina.LifecycleException: Protocol handler initialization failed
Mar 19 16:36:28 replica0.ipa.test server[20720]:         at org.apache.catalina.connector.Connector.initInternal(Connector.java:1049)
Mar 19 16:36:28 replica0.ipa.test server[20720]:         at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:136)
Mar 19 16:36:28 replica0.ipa.test server[20720]:         at org.apache.catalina.core.StandardService.initInternal(StandardService.java:558)
Mar 19 16:36:28 replica0.ipa.test server[20720]:         at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:136)
Mar 19 16:36:28 replica0.ipa.test server[20720]:         at org.apache.catalina.core.StandardServer.initInternal(StandardServer.java:1045)
Mar 19 16:36:28 replica0.ipa.test server[20720]:         at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:136)
Mar 19 16:36:28 replica0.ipa.test server[20720]:         at org.apache.catalina.startup.Catalina.load(Catalina.java:724)
Mar 19 16:36:28 replica0.ipa.test server[20720]:         at org.apache.catalina.startup.Catalina.load(Catalina.java:746)
Mar 19 16:36:28 replica0.ipa.test server[20720]:         at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
Mar 19 16:36:28 replica0.ipa.test server[20720]:         at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
Mar 19 16:36:28 replica0.ipa.test server[20720]:         at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
Mar 19 16:36:28 replica0.ipa.test server[20720]:         at java.base/java.lang.reflect.Method.invoke(Method.java:566)
Mar 19 16:36:28 replica0.ipa.test server[20720]:         at org.apache.catalina.startup.Bootstrap.load(Bootstrap.java:302)
Mar 19 16:36:28 replica0.ipa.test server[20720]:         at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:472)
Mar 19 16:36:28 replica0.ipa.test server[20720]: Caused by: java.net.BindException: Address already in use

=> Closing this issue as a duplicate.

Metadata Update from @frenaud:
- Issue close_status updated to: duplicate
- Issue status updated to: Closed (was: Open)

Metadata