report.html

Report generated on 11-Apr-2019 at 08:34:38 by pytest-html v1.19.0

Environment

Packages {'pytest': '3.6.4', 'py': '1.5.4', 'pluggy': '0.6.0'}
Platform Linux-4.19.4-300.fc29.x86_64-x86_64-with-fedora-29-Twenty_Nine
Plugins {'metadata': '1.7.0', 'html': '1.19.0', 'sourceorder': '0.5', 'multihost': '3.0'}
Python 3.7.1

Summary

0 tests ran in 161.21 seconds.

0 passed, 0 skipped, 0 failed, 1 errors, 0 expected failures, 0 unexpected passes

Results

Result Test Duration Links
Error test_hsmsupport.py::TestHSMSupport::()::test_hsm_certutil::setup 136.29
cls = <class 'ipatests.test_integration.test_hsmsupport.TestHSMSupport'>
mh = <pytest_multihost.plugin.MultihostFixture object at 0x7f26b09a2c18>

@classmethod
def install(cls, mh):
cls.pki_ini = prepare_softhsm(cls.master)
extra_args = [
'--pki-config-override', cls.pki_ini,
]
result = tasks.install_master(
cls.master, setup_dns=False, extra_args=extra_args,
raiseonerr=False
)
cls.master.run_command([
'ls', '-laRZ', SOFTHSM_DIR
])
> assert result.returncode == 0
E assert 1 == 0
E + where 1 = <pytest_multihost.transport.SSHCommand object at 0x7f26aecd94a8>.returncode

/usr/lib/python3.7/site-packages/ipatests/test_integration/test_hsmsupport.py:109: AssertionError
------------------------------ Captured log setup ------------------------------
__init__.py 267 INFO Preparing host master.ipa.test transport.py 1746 INFO Connected (version 2.0, client OpenSSH_7.9) transport.py 247 DEBUG Authenticating with private RSA key using user root transport.py 1746 INFO Authentication (publickey) successful! transport.py 318 INFO RUN ['true'] transport.py 513 DEBUG RUN ['true'] transport.py 558 DEBUG -bash: line 1: cd: /ipatests: No such file or directory transport.py 217 DEBUG Exit code: 0 __init__.py 261 INFO Adding master.ipa.test:/ipatests/env.sh to list of logs to collect transport.py 301 DEBUG STAT /ipatests sftp.py 158 INFO [chan 1] Opened sftp connection (server version 3) transport.py 301 DEBUG STAT / transport.py 312 INFO MKDIR /ipatests transport.py 293 INFO WRITE /ipatests/env.sh transport.py 318 INFO RUN ['rm', '-f', '/etc/crypto-policies/local.d/nss-p11-kit.config'] transport.py 513 DEBUG RUN ['rm', '-f', '/etc/crypto-policies/local.d/nss-p11-kit.config'] transport.py 217 DEBUG Exit code: 0 transport.py 318 INFO RUN ['update-crypto-policies'] transport.py 513 DEBUG RUN ['update-crypto-policies'] transport.py 558 DEBUG Setting system policy to DEFAULT transport.py 217 DEBUG Exit code: 0 transport.py 318 INFO RUN ['usermod', '-G', 'ods', '-a', 'pkiuser'] transport.py 513 DEBUG RUN ['usermod', '-G', 'ods', '-a', 'pkiuser'] transport.py 217 DEBUG Exit code: 0 transport.py 318 INFO RUN ['chcon', '--recursive', '--verbose', '-u', 'system_u', '-r', 'object_r', '-t', 'pki_tomcat_t', '/var/lib/softhsm'] transport.py 513 DEBUG RUN ['chcon', '--recursive', '--verbose', '-u', 'system_u', '-r', 'object_r', '-t', 'pki_tomcat_t', '/var/lib/softhsm'] transport.py 558 DEBUG changing security context of '/var/lib/softhsm/tokens/a8a4eafd-38ff-af6f-22e0-bbaec2a28baa/generation' transport.py 558 DEBUG changing security context of '/var/lib/softhsm/tokens/a8a4eafd-38ff-af6f-22e0-bbaec2a28baa/token.lock' transport.py 558 DEBUG changing security context of '/var/lib/softhsm/tokens/a8a4eafd-38ff-af6f-22e0-bbaec2a28baa/token.object' transport.py 558 DEBUG changing security context of '/var/lib/softhsm/tokens/a8a4eafd-38ff-af6f-22e0-bbaec2a28baa' transport.py 558 DEBUG changing security context of '/var/lib/softhsm/tokens' transport.py 558 DEBUG changing security context of '/var/lib/softhsm' transport.py 217 DEBUG Exit code: 0 transport.py 318 INFO RUN ['runuser', '-u', 'pkiuser', '--', '/usr/bin/softhsm2-util', '--init-token', '--free', '--pin', 'TokenSecret123', '--so-pin', 'TokenSOSecret123', '--label', 'softhsm_token'] transport.py 513 DEBUG RUN ['runuser', '-u', 'pkiuser', '--', '/usr/bin/softhsm2-util', '--init-token', '--free', '--pin', 'TokenSecret123', '--so-pin', 'TokenSOSecret123', '--label', 'softhsm_token'] transport.py 558 DEBUG Slot 0 has a free/uninitialized token. transport.py 558 DEBUG The token has been initialized and is reassigned to slot 248113697 transport.py 217 DEBUG Exit code: 0 transport.py 318 INFO RUN ['chcon', '--recursive', '--verbose', '-u', 'system_u', '-r', 'object_r', '-t', 'pki_tomcat_t', '/var/lib/softhsm'] transport.py 513 DEBUG RUN ['chcon', '--recursive', '--verbose', '-u', 'system_u', '-r', 'object_r', '-t', 'pki_tomcat_t', '/var/lib/softhsm'] transport.py 558 DEBUG changing security context of '/var/lib/softhsm/tokens/cbc082ed-809e-a51e-2d4e-d1ea8ec9ea21/generation' transport.py 558 DEBUG changing security context of '/var/lib/softhsm/tokens/cbc082ed-809e-a51e-2d4e-d1ea8ec9ea21/token.lock' transport.py 558 DEBUG changing security context of '/var/lib/softhsm/tokens/cbc082ed-809e-a51e-2d4e-d1ea8ec9ea21/token.object' transport.py 558 DEBUG changing security context of '/var/lib/softhsm/tokens/cbc082ed-809e-a51e-2d4e-d1ea8ec9ea21' transport.py 558 DEBUG changing security context of '/var/lib/softhsm/tokens/a8a4eafd-38ff-af6f-22e0-bbaec2a28baa/generation' transport.py 558 DEBUG changing security context of '/var/lib/softhsm/tokens/a8a4eafd-38ff-af6f-22e0-bbaec2a28baa/token.lock' transport.py 558 DEBUG changing security context of '/var/lib/softhsm/tokens/a8a4eafd-38ff-af6f-22e0-bbaec2a28baa/token.object' transport.py 558 DEBUG changing security context of '/var/lib/softhsm/tokens/a8a4eafd-38ff-af6f-22e0-bbaec2a28baa' transport.py 558 DEBUG changing security context of '/var/lib/softhsm/tokens' transport.py 558 DEBUG changing security context of '/var/lib/softhsm' transport.py 217 DEBUG Exit code: 0 transport.py 318 INFO RUN ['runuser', '-u', 'pkiuser', '--', '/usr/bin/softhsm2-util', '--show-slots'] transport.py 513 DEBUG RUN ['runuser', '-u', 'pkiuser', '--', '/usr/bin/softhsm2-util', '--show-slots'] transport.py 558 DEBUG Available slots: transport.py 558 DEBUG Slot 248113697 transport.py 558 DEBUG Slot info: transport.py 558 DEBUG Description: SoftHSM slot ID 0xec9ea21 transport.py 558 DEBUG Manufacturer ID: SoftHSM project transport.py 558 DEBUG Hardware version: 2.5 transport.py 558 DEBUG Firmware version: 2.5 transport.py 558 DEBUG Token present: yes transport.py 558 DEBUG Token info: transport.py 558 DEBUG Manufacturer ID: SoftHSM project transport.py 558 DEBUG Model: SoftHSM v2 transport.py 558 DEBUG Hardware version: 2.5 transport.py 558 DEBUG Firmware version: 2.5 transport.py 558 DEBUG Serial number: 2d4ed1ea8ec9ea21 transport.py 558 DEBUG Initialized: yes transport.py 558 DEBUG User PIN init.: yes transport.py 558 DEBUG Label: softhsm_token transport.py 558 DEBUG Slot 1 transport.py 558 DEBUG Slot info: transport.py 558 DEBUG Description: SoftHSM slot ID 0x1 transport.py 558 DEBUG Manufacturer ID: SoftHSM project transport.py 558 DEBUG Hardware version: 2.5 transport.py 558 DEBUG Firmware version: 2.5 transport.py 558 DEBUG Token present: yes transport.py 558 DEBUG Token info: transport.py 558 DEBUG Manufacturer ID: SoftHSM project transport.py 558 DEBUG Model: SoftHSM v2 transport.py 558 DEBUG Hardware version: 2.5 transport.py 558 DEBUG Firmware version: 2.5 transport.py 558 DEBUG Serial number: transport.py 558 DEBUG Initialized: no transport.py 558 DEBUG User PIN init.: no transport.py 558 DEBUG Label: transport.py 217 DEBUG Exit code: 0 transport.py 318 INFO RUN ['ls', '-laRZ', '/var/lib/softhsm'] transport.py 513 DEBUG RUN ['ls', '-laRZ', '/var/lib/softhsm'] transport.py 558 DEBUG /var/lib/softhsm: transport.py 558 DEBUG total 12 transport.py 558 DEBUG drwxr-x---. 3 ods ods system_u:object_r:pki_tomcat_t:s0 4096 Nov 28 01:55 . transport.py 558 DEBUG drwxr-xr-x. 44 root root system_u:object_r:var_lib_t:s0 4096 Apr 11 08:30 .. transport.py 558 DEBUG drwxrwx--T. 4 ods ods system_u:object_r:pki_tomcat_t:s0 4096 Apr 11 08:31 tokens transport.py 558 DEBUG transport.py 558 DEBUG /var/lib/softhsm/tokens: transport.py 558 DEBUG total 16 transport.py 558 DEBUG drwxrwx--T. 4 ods ods system_u:object_r:pki_tomcat_t:s0 4096 Apr 11 08:31 . transport.py 558 DEBUG drwxr-x---. 3 ods ods system_u:object_r:pki_tomcat_t:s0 4096 Nov 28 01:55 .. transport.py 558 DEBUG drwx------. 2 ods ods system_u:object_r:pki_tomcat_t:s0 4096 Nov 28 01:55 a8a4eafd-38ff-af6f-22e0-bbaec2a28baa transport.py 558 DEBUG drwx------. 2 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 4096 Apr 11 08:31 cbc082ed-809e-a51e-2d4e-d1ea8ec9ea21 transport.py 558 DEBUG transport.py 558 DEBUG /var/lib/softhsm/tokens/a8a4eafd-38ff-af6f-22e0-bbaec2a28baa: transport.py 558 DEBUG total 16 transport.py 558 DEBUG drwx------. 2 ods ods system_u:object_r:pki_tomcat_t:s0 4096 Nov 28 01:55 . transport.py 558 DEBUG drwxrwx--T. 4 ods ods system_u:object_r:pki_tomcat_t:s0 4096 Apr 11 08:31 .. transport.py 558 DEBUG -rw-------. 1 ods ods system_u:object_r:pki_tomcat_t:s0 8 Nov 28 01:55 generation transport.py 558 DEBUG -rw-------. 1 ods ods system_u:object_r:pki_tomcat_t:s0 0 Nov 28 01:55 token.lock transport.py 558 DEBUG -rw-------. 1 ods ods system_u:object_r:pki_tomcat_t:s0 320 Nov 28 01:55 token.object transport.py 558 DEBUG transport.py 558 DEBUG /var/lib/softhsm/tokens/cbc082ed-809e-a51e-2d4e-d1ea8ec9ea21: transport.py 558 DEBUG total 16 transport.py 558 DEBUG drwx------. 2 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 4096 Apr 11 08:31 . transport.py 558 DEBUG drwxrwx--T. 4 ods ods system_u:object_r:pki_tomcat_t:s0 4096 Apr 11 08:31 .. transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 8 Apr 11 08:31 generation transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 0 Apr 11 08:31 token.lock transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 320 Apr 11 08:31 token.object transport.py 217 DEBUG Exit code: 0 transport.py 318 INFO RUN ['mktemp'] transport.py 513 DEBUG RUN ['mktemp'] transport.py 558 DEBUG /tmp/tmp.K4iwIMbgr9 transport.py 217 DEBUG Exit code: 0 transport.py 293 INFO WRITE /tmp/tmp.K4iwIMbgr9 __init__.py 261 INFO Adding master.ipa.test:/var/log/dirsrv/slapd-IPA-TEST/errors to list of logs to collect __init__.py 261 INFO Adding master.ipa.test:/var/log/dirsrv/slapd-IPA-TEST/access to list of logs to collect __init__.py 261 INFO Adding master.ipa.test:/var/log/ipaserver-install.log to list of logs to collect __init__.py 261 INFO Adding master.ipa.test:/var/log/ipaserver-uninstall.log to list of logs to collect __init__.py 261 INFO Adding master.ipa.test:/var/log/ipaclient-install.log to list of logs to collect __init__.py 261 INFO Adding master.ipa.test:/var/log/ipaclient-uninstall.log to list of logs to collect __init__.py 261 INFO Adding master.ipa.test:/var/log/ipareplica-install.log to list of logs to collect __init__.py 261 INFO Adding master.ipa.test:/var/log/ipareplica-conncheck.log to list of logs to collect __init__.py 261 INFO Adding master.ipa.test:/var/log/ipareplica-ca-install.log to list of logs to collect __init__.py 261 INFO Adding master.ipa.test:/var/log/ipaserver-kra-install.log to list of logs to collect __init__.py 261 INFO Adding master.ipa.test:/var/log/ipa-custodia.audit.log to list of logs to collect __init__.py 261 INFO Adding master.ipa.test:/var/log/ipaclient-uninstall.log to list of logs to collect __init__.py 261 INFO Adding master.ipa.test:/var/log/iparestore.log to list of logs to collect __init__.py 261 INFO Adding master.ipa.test:/var/log/ipabackup.log to list of logs to collect __init__.py 261 INFO Adding master.ipa.test:/var/log/kadmind.log to list of logs to collect __init__.py 261 INFO Adding master.ipa.test:/var/log/krb5kdc.log to list of logs to collect __init__.py 261 INFO Adding master.ipa.test:/var/log/httpd/error_log to list of logs to collect __init__.py 261 INFO Adding master.ipa.test:/var/log/pki/ to list of logs to collect __init__.py 261 INFO Adding master.ipa.test:/var/log/audit/audit.log to list of logs to collect transport.py 318 INFO RUN ['true'] transport.py 513 DEBUG RUN ['true'] transport.py 217 DEBUG Exit code: 0 __init__.py 261 INFO Adding master.ipa.test:/ipatests/env.sh to list of logs to collect transport.py 301 DEBUG STAT /ipatests transport.py 293 INFO WRITE /ipatests/env.sh transport.py 301 DEBUG STAT /etc/hostname transport.py 301 DEBUG STAT /ipatests/file_backup/etc transport.py 301 DEBUG STAT /ipatests/file_backup transport.py 301 DEBUG STAT /ipatests transport.py 312 INFO MKDIR /ipatests/file_backup transport.py 312 INFO MKDIR /ipatests/file_backup/etc transport.py 318 INFO RUN ['cp', '-af', '/etc/hostname', '/ipatests/file_backup/etc/hostname'] transport.py 513 DEBUG RUN ['cp', '-af', '/etc/hostname', '/ipatests/file_backup/etc/hostname'] transport.py 217 DEBUG Exit code: 0 transport.py 293 INFO WRITE /etc/hostname transport.py 318 INFO RUN ['hostname', 'master.ipa.test'] transport.py 513 DEBUG RUN ['hostname', 'master.ipa.test'] transport.py 217 DEBUG Exit code: 0 transport.py 318 INFO RUN hostname > '/ipatests/backup_hostname' transport.py 513 DEBUG RUN hostname > '/ipatests/backup_hostname' transport.py 217 DEBUG Exit code: 0 transport.py 318 INFO RUN ['ss', '--all', '--tcp', '--udp', '--numeric', '--processes'] transport.py 513 DEBUG RUN ['ss', '--all', '--tcp', '--udp', '--numeric', '--processes'] transport.py 558 DEBUG Netid State Recv-Q Send-Q Local Address:Port Peer Address:Port transport.py 558 DEBUG udp UNCONN 0 0 0.0.0.0:68 0.0.0.0:* users:(("dhclient",pid=1584,fd=8)) transport.py 558 DEBUG udp UNCONN 0 0 0.0.0.0:111 0.0.0.0:* users:(("rpcbind",pid=1983,fd=5),("systemd",pid=1,fd=69)) transport.py 558 DEBUG udp UNCONN 0 0 127.0.0.1:323 0.0.0.0:* users:(("chronyd",pid=460,fd=5)) transport.py 558 DEBUG udp UNCONN 0 0 0.0.0.0:55655 0.0.0.0:* transport.py 558 DEBUG udp UNCONN 0 0 0.0.0.0:57842 0.0.0.0:* users:(("rpc.statd",pid=1984,fd=9)) transport.py 558 DEBUG udp UNCONN 0 0 127.0.0.1:889 0.0.0.0:* users:(("rpc.statd",pid=1984,fd=8)) transport.py 558 DEBUG udp UNCONN 0 0 [::]:48485 [::]:* transport.py 558 DEBUG udp UNCONN 0 0 [::]:49108 [::]:* users:(("rpc.statd",pid=1984,fd=11)) transport.py 558 DEBUG udp UNCONN 0 0 [::]:111 [::]:* users:(("rpcbind",pid=1983,fd=7),("systemd",pid=1,fd=71)) transport.py 558 DEBUG udp UNCONN 0 0 [::1]:323 [::]:* users:(("chronyd",pid=460,fd=6)) transport.py 558 DEBUG tcp LISTEN 0 64 0.0.0.0:40841 0.0.0.0:* transport.py 558 DEBUG tcp LISTEN 0 128 0.0.0.0:41039 0.0.0.0:* users:(("rpc.statd",pid=1984,fd=10)) transport.py 558 DEBUG tcp LISTEN 0 128 0.0.0.0:111 0.0.0.0:* users:(("rpcbind",pid=1983,fd=4),("systemd",pid=1,fd=68)) transport.py 558 DEBUG tcp LISTEN 0 128 0.0.0.0:22 0.0.0.0:* users:(("sshd",pid=16418,fd=5)) transport.py 558 DEBUG tcp ESTAB 0 0 192.168.121.89:22 192.168.121.74:49784 users:(("sshd",pid=16239,fd=5),("sshd",pid=16236,fd=5)) transport.py 558 DEBUG tcp ESTAB 0 0 192.168.121.89:890 192.168.121.1:2049 transport.py 558 DEBUG tcp ESTAB 0 0 192.168.121.89:22 192.168.121.1:33486 users:(("sshd",pid=2092,fd=5),("sshd",pid=2089,fd=5)) transport.py 558 DEBUG tcp LISTEN 0 64 [::]:33477 [::]:* transport.py 558 DEBUG tcp LISTEN 0 128 [::]:111 [::]:* users:(("rpcbind",pid=1983,fd=6),("systemd",pid=1,fd=70)) transport.py 558 DEBUG tcp LISTEN 0 128 [::]:36915 [::]:* users:(("rpc.statd",pid=1984,fd=12)) transport.py 558 DEBUG tcp LISTEN 0 128 [::]:22 [::]:* users:(("sshd",pid=16418,fd=7)) transport.py 217 DEBUG Exit code: 0 transport.py 318 INFO RUN ['ss', '--all', '--tcp', '--udp', '--numeric', '--processes', '-o', 'state', 'all', '( sport = :749 or dport = :749 or sport = :464 or dport = :464 )'] transport.py 513 DEBUG RUN ['ss', '--all', '--tcp', '--udp', '--numeric', '--processes', '-o', 'state', 'all', '( sport = :749 or dport = :749 or sport = :464 or dport = :464 )'] transport.py 558 DEBUG Netid State Recv-Q Send-Q Local Address:Port Peer Address:Port transport.py 217 DEBUG Exit code: 0 transport.py 301 DEBUG STAT /bin/systemctl transport.py 318 INFO RUN ['systemctl', 'stop', 'httpd'] transport.py 513 DEBUG RUN ['systemctl', 'stop', 'httpd'] transport.py 217 DEBUG Exit code: 0 transport.py 318 INFO RUN for line in `ipcs -s | grep apache | cut -d " " -f 2`; do ipcrm -s $line; done transport.py 513 DEBUG RUN for line in `ipcs -s | grep apache | cut -d " " -f 2`; do ipcrm -s $line; done transport.py 217 DEBUG Exit code: 0 transport.py 318 INFO RUN ['systemctl', 'unmask', 'firewalld'] transport.py 513 DEBUG RUN ['systemctl', 'unmask', 'firewalld'] transport.py 558 DEBUG Removed /etc/systemd/system/firewalld.service. transport.py 217 DEBUG Exit code: 0 transport.py 318 INFO RUN ['systemctl', 'enable', 'firewalld'] transport.py 513 DEBUG RUN ['systemctl', 'enable', 'firewalld'] transport.py 217 DEBUG Exit code: 0 transport.py 318 INFO RUN ['systemctl', 'start', 'firewalld'] transport.py 513 DEBUG RUN ['systemctl', 'start', 'firewalld'] transport.py 217 DEBUG Exit code: 0 transport.py 318 INFO RUN ['ipa-server-install', '-n', 'ipa.test', '-r', 'IPA.TEST', '-p', 'Secret.123', '-a', 'Secret.123', '--domain-level=1', '-U', '--pki-config-override', '/tmp/tmp.K4iwIMbgr9'] transport.py 513 DEBUG RUN ['ipa-server-install', '-n', 'ipa.test', '-r', 'IPA.TEST', '-p', 'Secret.123', '-a', 'Secret.123', '--domain-level=1', '-U', '--pki-config-override', '/tmp/tmp.K4iwIMbgr9'] transport.py 558 DEBUG Synchronizing time transport.py 558 DEBUG No SRV records of NTP servers found and no NTP server or pool address was provided. transport.py 558 DEBUG Attempting to sync time with chronyc. transport.py 558 DEBUG Process chronyc waitsync failed to sync time! transport.py 558 DEBUG Unable to sync time with chrony server, assuming the time is in sync. Please check that 123 UDP port is opened, and any time server is on network. transport.py 558 DEBUG transport.py 558 DEBUG The log file for this installation can be found in /var/log/ipaserver-install.log transport.py 558 DEBUG ============================================================================== transport.py 558 DEBUG This program will set up the FreeIPA Server. transport.py 558 DEBUG Version 4.7.90test transport.py 558 DEBUG transport.py 558 DEBUG This includes: transport.py 558 DEBUG * Configure a stand-alone CA (dogtag) for certificate management transport.py 558 DEBUG * Configure the NTP client (chronyd) transport.py 558 DEBUG * Create and configure an instance of Directory Server transport.py 558 DEBUG * Create and configure a Kerberos Key Distribution Center (KDC) transport.py 558 DEBUG * Configure Apache (httpd) transport.py 558 DEBUG * Configure the KDC to enable PKINIT transport.py 558 DEBUG transport.py 558 DEBUG transport.py 558 DEBUG The IPA Master Server will be configured with: transport.py 558 DEBUG Hostname: master.ipa.test transport.py 558 DEBUG IP address(es): 192.168.121.89 transport.py 558 DEBUG Domain name: ipa.test transport.py 558 DEBUG Realm name: IPA.TEST transport.py 558 DEBUG transport.py 558 DEBUG The CA will be configured with: transport.py 558 DEBUG Subject DN: CN=Certificate Authority,O=IPA.TEST transport.py 558 DEBUG Subject base: O=IPA.TEST transport.py 558 DEBUG Chaining: self-signed transport.py 558 DEBUG transport.py 558 DEBUG Using default chrony configuration. transport.py 558 DEBUG Warning: IPA was unable to sync time with chrony! transport.py 558 DEBUG Time synchronization is required for IPA to work correctly transport.py 558 DEBUG Configuring directory server (dirsrv). Estimated time: 30 seconds transport.py 558 DEBUG transport.py 558 DEBUG Starting installation... transport.py 558 DEBUG [1/43]: creating directory server instance transport.py 558 DEBUG Created symlink /etc/systemd/system/multi-user.target.wants/dirsrv@IPA-TEST.service → /usr/lib/systemd/system/dirsrv@.service. transport.py 558 DEBUG Opening SELinux policy "//etc/selinux/targeted/policy/policy.31" transport.py 558 DEBUG Successfully opened SELinux policy "//etc/selinux/targeted/policy/policy.31" transport.py 558 DEBUG Allocate local instance <class 'lib389.DirSrv'> with ldapi://%2fvar%2frun%2fslapd-IPA-TEST.socket transport.py 558 DEBUG [2/43]: configure autobind for root transport.py 558 DEBUG [3/43]: stopping directory server transport.py 558 DEBUG [4/43]: updating configuration in dse.ldif transport.py 558 DEBUG [5/43]: starting directory server transport.py 558 DEBUG [6/43]: adding default schema transport.py 558 DEBUG [7/43]: enabling memberof plugin transport.py 558 DEBUG [8/43]: enabling winsync plugin transport.py 558 DEBUG [9/43]: configuring replication version plugin transport.py 558 DEBUG [10/43]: enabling IPA enrollment plugin transport.py 558 DEBUG [11/43]: configuring uniqueness plugin transport.py 558 DEBUG [12/43]: configuring uuid plugin transport.py 558 DEBUG [13/43]: configuring modrdn plugin transport.py 558 DEBUG [14/43]: configuring DNS plugin transport.py 558 DEBUG [15/43]: enabling entryUSN plugin transport.py 558 DEBUG [16/43]: configuring lockout plugin transport.py 558 DEBUG [17/43]: configuring topology plugin transport.py 558 DEBUG [18/43]: creating indices transport.py 558 DEBUG [19/43]: enabling referential integrity plugin transport.py 558 DEBUG [20/43]: configuring certmap.conf transport.py 558 DEBUG [21/43]: configure new location for managed entries transport.py 558 DEBUG [22/43]: configure dirsrv ccache and keytab transport.py 558 DEBUG [23/43]: enabling SASL mapping fallback transport.py 558 DEBUG [24/43]: restarting directory server transport.py 558 DEBUG [25/43]: adding sasl mappings to the directory transport.py 558 DEBUG [26/43]: adding default layout transport.py 558 DEBUG [27/43]: adding delegation layout transport.py 558 DEBUG [28/43]: creating container for managed entries transport.py 558 DEBUG [29/43]: configuring user private groups transport.py 558 DEBUG [30/43]: configuring netgroups from hostgroups transport.py 558 DEBUG [31/43]: creating default Sudo bind user transport.py 558 DEBUG [32/43]: creating default Auto Member layout transport.py 558 DEBUG [33/43]: adding range check plugin transport.py 558 DEBUG [34/43]: creating default HBAC rule allow_all transport.py 558 DEBUG [35/43]: adding entries for topology management transport.py 558 DEBUG [36/43]: initializing group membership transport.py 558 DEBUG [37/43]: adding master entry transport.py 558 DEBUG [38/43]: initializing domain level transport.py 558 DEBUG [39/43]: configuring Posix uid/gid generation transport.py 558 DEBUG [40/43]: adding replication acis transport.py 558 DEBUG [41/43]: activating sidgen plugin transport.py 558 DEBUG [42/43]: activating extdom plugin transport.py 558 DEBUG [43/43]: configuring directory to start on boot transport.py 558 DEBUG Done configuring directory server (dirsrv). transport.py 558 DEBUG Configuring Kerberos KDC (krb5kdc) transport.py 558 DEBUG [1/10]: adding kerberos container to the directory transport.py 558 DEBUG [2/10]: configuring KDC transport.py 558 DEBUG [3/10]: initialize kerberos container transport.py 558 DEBUG [4/10]: adding default ACIs transport.py 558 DEBUG [5/10]: creating a keytab for the directory transport.py 558 DEBUG [6/10]: creating a keytab for the machine transport.py 558 DEBUG [7/10]: adding the password extension to the directory transport.py 558 DEBUG [8/10]: creating anonymous principal transport.py 558 DEBUG [9/10]: starting the KDC transport.py 558 DEBUG [10/10]: configuring KDC to start on boot transport.py 558 DEBUG Done configuring Kerberos KDC (krb5kdc). transport.py 558 DEBUG Configuring kadmin transport.py 558 DEBUG [1/2]: starting kadmin transport.py 558 DEBUG [2/2]: configuring kadmin to start on boot transport.py 558 DEBUG Done configuring kadmin. transport.py 558 DEBUG Configuring ipa-custodia transport.py 558 DEBUG [1/5]: Making sure custodia container exists transport.py 558 DEBUG [2/5]: Generating ipa-custodia config file transport.py 558 DEBUG [3/5]: Generating ipa-custodia keys transport.py 558 DEBUG [4/5]: starting ipa-custodia transport.py 558 DEBUG [5/5]: configuring ipa-custodia to start on boot transport.py 558 DEBUG Done configuring ipa-custodia. transport.py 558 DEBUG Configuring certificate server (pki-tomcatd). Estimated time: 3 minutes transport.py 558 DEBUG [1/29]: configuring certificate server instance transport.py 558 DEBUG Failed to configure CA instance: CalledProcessError(Command ['/usr/sbin/pkispawn', '-s', 'CA', '-f', '/tmp/tmpyyk911ag'] returned non-zero exit status 1: 'Notice: Trust flag u is set automatically if the private key is present.\ncertutil: could not change trust on certificate: SEC_ERROR_TOKEN_NOT_LOGGED_IN: The operation failed because the PKCS#11 token is not logged in.\npki.nssdb : WARNING certutil returned non-zero exit code (bug #1393668)\n') transport.py 558 DEBUG See the installation logs and the following files/directories for more information: transport.py 558 DEBUG /var/log/pki/pki-tomcat transport.py 558 DEBUG [error] RuntimeError: CA configuration failed. transport.py 558 DEBUG CA configuration failed. transport.py 558 DEBUG The ipa-server-install command failed. See /var/log/ipaserver-install.log for more information transport.py 217 DEBUG Exit code: 1 transport.py 318 INFO RUN ['ls', '-laRZ', '/var/lib/softhsm'] transport.py 513 DEBUG RUN ['ls', '-laRZ', '/var/lib/softhsm'] transport.py 558 DEBUG /var/lib/softhsm: transport.py 558 DEBUG total 12 transport.py 558 DEBUG drwxr-x---. 3 ods ods system_u:object_r:pki_tomcat_t:s0 4096 Nov 28 01:55 . transport.py 558 DEBUG drwxr-xr-x. 44 root root system_u:object_r:var_lib_t:s0 4096 Apr 11 08:30 .. transport.py 558 DEBUG drwxrwx--T. 4 ods ods system_u:object_r:pki_tomcat_t:s0 4096 Apr 11 08:31 tokens transport.py 558 DEBUG transport.py 558 DEBUG /var/lib/softhsm/tokens: transport.py 558 DEBUG total 16 transport.py 558 DEBUG drwxrwx--T. 4 ods ods system_u:object_r:pki_tomcat_t:s0 4096 Apr 11 08:31 . transport.py 558 DEBUG drwxr-x---. 3 ods ods system_u:object_r:pki_tomcat_t:s0 4096 Nov 28 01:55 .. transport.py 558 DEBUG drwx------. 2 ods ods system_u:object_r:pki_tomcat_t:s0 4096 Nov 28 01:55 a8a4eafd-38ff-af6f-22e0-bbaec2a28baa transport.py 558 DEBUG drwx------. 2 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 4096 Apr 11 08:33 cbc082ed-809e-a51e-2d4e-d1ea8ec9ea21 transport.py 558 DEBUG transport.py 558 DEBUG /var/lib/softhsm/tokens/a8a4eafd-38ff-af6f-22e0-bbaec2a28baa: transport.py 558 DEBUG total 16 transport.py 558 DEBUG drwx------. 2 ods ods system_u:object_r:pki_tomcat_t:s0 4096 Nov 28 01:55 . transport.py 558 DEBUG drwxrwx--T. 4 ods ods system_u:object_r:pki_tomcat_t:s0 4096 Apr 11 08:31 .. transport.py 558 DEBUG -rw-------. 1 ods ods system_u:object_r:pki_tomcat_t:s0 8 Apr 11 08:33 generation transport.py 558 DEBUG -rw-------. 1 ods ods system_u:object_r:pki_tomcat_t:s0 0 Nov 28 01:55 token.lock transport.py 558 DEBUG -rw-------. 1 ods ods system_u:object_r:pki_tomcat_t:s0 320 Nov 28 01:55 token.object transport.py 558 DEBUG transport.py 558 DEBUG /var/lib/softhsm/tokens/cbc082ed-809e-a51e-2d4e-d1ea8ec9ea21: transport.py 558 DEBUG total 76 transport.py 558 DEBUG drwx------. 2 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 4096 Apr 11 08:33 . transport.py 558 DEBUG drwxrwx--T. 4 ods ods system_u:object_r:pki_tomcat_t:s0 4096 Apr 11 08:31 .. transport.py 558 DEBUG -rw-------. 1 root root unconfined_u:object_r:pki_tomcat_t:s0 0 Apr 11 08:33 31f3e344-0b80-7189-bd7d-f5671a6f9ebc.lock transport.py 558 DEBUG -rw-------. 1 root root unconfined_u:object_r:pki_tomcat_t:s0 1588 Apr 11 08:33 31f3e344-0b80-7189-bd7d-f5671a6f9ebc.object transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 0 Apr 11 08:33 390c53fc-0144-fbd5-ffed-0a10cfc22ea7.lock transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 2321 Apr 11 08:33 390c53fc-0144-fbd5-ffed-0a10cfc22ea7.object transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 0 Apr 11 08:33 5913f5ef-c864-0d41-fb77-724beb803a51.lock transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 1563 Apr 11 08:33 5913f5ef-c864-0d41-fb77-724beb803a51.object transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 0 Apr 11 08:33 69b245f4-1d87-c629-892c-1da5cfbf233a.lock transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 827 Apr 11 08:33 69b245f4-1d87-c629-892c-1da5cfbf233a.object transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 0 Apr 11 08:33 8149bdf1-d003-e379-12e4-b993f6ad072b.lock transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 827 Apr 11 08:33 8149bdf1-d003-e379-12e4-b993f6ad072b.object transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 0 Apr 11 08:33 90ffa393-3b98-1fe4-029d-456876f2ede9.lock transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 827 Apr 11 08:33 90ffa393-3b98-1fe4-029d-456876f2ede9.object transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 0 Apr 11 08:33 9afd246f-336f-86bd-7291-1ebd96038320.lock transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 1556 Apr 11 08:33 9afd246f-336f-86bd-7291-1ebd96038320.object transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 0 Apr 11 08:33 a141e96b-a70c-54cb-4a20-5cb5fc016086.lock transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 1528 Apr 11 08:33 a141e96b-a70c-54cb-4a20-5cb5fc016086.object transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 0 Apr 11 08:33 aab907e6-56c1-c6f3-3d45-937a29cf02b5.lock transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 827 Apr 11 08:33 aab907e6-56c1-c6f3-3d45-937a29cf02b5.object transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 0 Apr 11 08:33 c8be750e-c18c-d61f-b4d5-f15fa16d32f0.lock transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 1601 Apr 11 08:33 c8be750e-c18c-d61f-b4d5-f15fa16d32f0.object transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 0 Apr 11 08:33 cb1ea2b0-192d-592b-8078-9f670aebc36b.lock transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 2209 Apr 11 08:33 cb1ea2b0-192d-592b-8078-9f670aebc36b.object transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 0 Apr 11 08:33 cb725a80-b2ea-f484-0bde-824e2a097190.lock transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 2321 Apr 11 08:33 cb725a80-b2ea-f484-0bde-824e2a097190.object transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 0 Apr 11 08:33 d3424cec-7f4f-900b-feca-f7d787bfc551.lock transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 827 Apr 11 08:33 d3424cec-7f4f-900b-feca-f7d787bfc551.object transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 0 Apr 11 08:33 eaca3247-9411-42f9-cf67-37827ee5949a.lock transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 2321 Apr 11 08:33 eaca3247-9411-42f9-cf67-37827ee5949a.object transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 0 Apr 11 08:33 edbff71f-0ec1-861d-8192-a6166638370d.lock transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 2337 Apr 11 08:33 edbff71f-0ec1-861d-8192-a6166638370d.object transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 8 Apr 11 08:34 generation transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 0 Apr 11 08:34 token.lock transport.py 558 DEBUG -rw-------. 1 pkiuser pkiuser system_u:object_r:pki_tomcat_t:s0 320 Apr 11 08:34 token.object transport.py 217 DEBUG Exit code: 0 __init__.py 134 INFO Collecting logs from: master.ipa.test transport.py 318 INFO RUN ['mktemp'] transport.py 513 DEBUG RUN ['mktemp'] transport.py 558 DEBUG /tmp/tmp.65sqyTcWdm transport.py 217 DEBUG Exit code: 0 transport.py 318 INFO RUN ['tar', 'cJvf', '/tmp/tmp.65sqyTcWdm', '--ignore-failed-read', '/ipatests/env.sh', '/var/log/dirsrv/slapd-IPA-TEST/errors', '/var/log/dirsrv/slapd-IPA-TEST/access', '/var/log/ipaserver-install.log', '/var/log/ipaserver-uninstall.log', '/var/log/ipaclient-install.log', '/var/log/ipaclient-uninstall.log', '/var/log/ipareplica-install.log', '/var/log/ipareplica-conncheck.log', '/var/log/ipareplica-ca-install.log', '/var/log/ipaserver-kra-install.log', '/var/log/ipa-custodia.audit.log', '/var/log/ipaclient-uninstall.log', '/var/log/iparestore.log', '/var/log/ipabackup.log', '/var/log/kadmind.log', '/var/log/krb5kdc.log', '/var/log/httpd/error_log', '/var/log/pki/', '/var/log/audit/audit.log', '/ipatests/env.sh'] transport.py 513 DEBUG RUN ['tar', 'cJvf', '/tmp/tmp.65sqyTcWdm', '--ignore-failed-read', '/ipatests/env.sh', '/var/log/dirsrv/slapd-IPA-TEST/errors', '/var/log/dirsrv/slapd-IPA-TEST/access', '/var/log/ipaserver-install.log', '/var/log/ipaserver-uninstall.log', '/var/log/ipaclient-install.log', '/var/log/ipaclient-uninstall.log', '/var/log/ipareplica-install.log', '/var/log/ipareplica-conncheck.log', '/var/log/ipareplica-ca-install.log', '/var/log/ipaserver-kra-install.log', '/var/log/ipa-custodia.audit.log', '/var/log/ipaclient-uninstall.log', '/var/log/iparestore.log', '/var/log/ipabackup.log', '/var/log/kadmind.log', '/var/log/krb5kdc.log', '/var/log/httpd/error_log', '/var/log/pki/', '/var/log/audit/audit.log', '/ipatests/env.sh'] transport.py 558 DEBUG tar: Removing leading `/' from member names transport.py 558 DEBUG tar: Removing leading `/' from hard link targets transport.py 558 DEBUG tar: /var/log/ipaserver-uninstall.log: Warning: Cannot stat: No such file or directory transport.py 558 DEBUG tar: /var/log/ipaclient-install.log: Warning: Cannot stat: No such file or directory transport.py 558 DEBUG tar: /var/log/ipaclient-uninstall.log: Warning: Cannot stat: No such file or directory transport.py 558 DEBUG tar: /var/log/ipareplica-install.log: Warning: Cannot stat: No such file or directory transport.py 558 DEBUG tar: /var/log/ipareplica-conncheck.log: Warning: Cannot stat: No such file or directory transport.py 558 DEBUG tar: /var/log/ipareplica-ca-install.log: Warning: Cannot stat: No such file or directory transport.py 558 DEBUG tar: /var/log/ipaserver-kra-install.log: Warning: Cannot stat: No such file or directory transport.py 558 DEBUG tar: /var/log/ipaclient-uninstall.log: Warning: Cannot stat: No such file or directory transport.py 558 DEBUG tar: /var/log/iparestore.log: Warning: Cannot stat: No such file or directory transport.py 558 DEBUG tar: /var/log/ipabackup.log: Warning: Cannot stat: No such file or directory transport.py 558 DEBUG tar: /var/log/httpd/error_log: Warning: Cannot stat: No such file or directory transport.py 217 DEBUG Exit code: 0 transport.py 284 DEBUG READ /tmp/tmp.65sqyTcWdm transport.py 318 INFO RUN ['rm', '-f', '/tmp/tmp.65sqyTcWdm'] transport.py 513 DEBUG RUN ['rm', '-f', '/tmp/tmp.65sqyTcWdm'] transport.py 217 DEBUG Exit code: 0 __init__.py 90 INFO Collecting journal from: master.ipa.test transport.py 318 INFO RUN ['journalctl', '--since', '-1h'] transport.py 513 DEBUG RUN ['journalctl', '--since', '-1h'] transport.py 217 DEBUG Exit code: 0