2026-09-16 21:37:14.320422 | Job console starting 2026-09-16 21:37:14.331306 | Updating git repos 2026-09-16 21:37:16.705316 | Cloning repos into workspace 2026-09-16 21:37:33.527326 | Restoring repo states 2026-09-16 21:37:47.648396 | Merging changes 2026-09-16 21:37:48.431616 | Checking out repos 2026-09-16 21:37:49.978614 | Preparing playbooks 2026-09-16 21:37:52.012227 | Running Ansible setup 2026-09-16 21:37:55.687783 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-16 21:37:56.320770 | 2026-09-16 21:37:56.320950 | PLAY [localhost] 2026-09-16 21:37:56.328845 | 2026-09-16 21:37:56.328953 | TASK [Gathering Facts] 2026-09-16 21:37:57.248378 | localhost | ok 2026-09-16 21:37:57.261125 | 2026-09-16 21:37:57.261296 | TASK [Setup log path fact] 2026-09-16 21:37:57.283301 | localhost | ok 2026-09-16 21:37:57.303872 | 2026-09-16 21:37:57.303998 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-16 21:37:57.354976 | localhost | ok 2026-09-16 21:37:57.367658 | 2026-09-16 21:37:57.367859 | TASK [emit-job-header : Print job information] 2026-09-16 21:37:57.421834 | # Job Information 2026-09-16 21:37:57.422158 | Ansible Version: 2.16.18 2026-09-16 21:37:57.422235 | Job: atmosphere-molecule-csi-rbd 2026-09-16 21:37:57.422279 | Pipeline: check 2026-09-16 21:37:57.422318 | Executor: 2d72f0692154 2026-09-16 21:37:57.422356 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4092 2026-09-16 21:37:57.422403 | Event ID: 1c1b1e80-b216-11f1-92cd-a1774dca4595 2026-09-16 21:37:57.430441 | 2026-09-16 21:37:57.430682 | LOOP [emit-job-header : Print node information] 2026-09-16 21:37:57.524586 | localhost | ok: 2026-09-16 21:37:57.524862 | localhost | # Node Information 2026-09-16 21:37:57.524923 | localhost | Inventory Hostname: instance 2026-09-16 21:37:57.524967 | localhost | Hostname: np0000192939 2026-09-16 21:37:57.525006 | localhost | Username: zuul 2026-09-16 21:37:57.525049 | localhost | Distro: Ubuntu 22.04 2026-09-16 21:37:57.525116 | localhost | Provider: yul1 2026-09-16 21:37:57.525157 | localhost | Region: ca-ymq-1 2026-09-16 21:37:57.525194 | localhost | Label: ubuntu-jammy 2026-09-16 21:37:57.525229 | localhost | Product Name: OpenStack Nova 2026-09-16 21:37:57.525265 | localhost | Interface IP: 162.253.55.126 2026-09-16 21:37:57.542363 | 2026-09-16 21:37:57.542571 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-16 21:37:58.034075 | localhost -> localhost | changed 2026-09-16 21:37:58.041070 | 2026-09-16 21:37:58.041209 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-16 21:37:58.906835 | localhost -> localhost | changed 2026-09-16 21:37:58.915374 | 2026-09-16 21:37:58.915472 | PLAY [all] 2026-09-16 21:37:58.927394 | 2026-09-16 21:37:58.927546 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-16 21:37:59.185129 | instance -> localhost | ok 2026-09-16 21:37:59.201061 | 2026-09-16 21:37:59.201289 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-16 21:37:59.232661 | instance | ok 2026-09-16 21:37:59.249476 | instance | included: /var/lib/zuul/builds/8020aa0f31624db886d2f6521803aa15/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-16 21:37:59.257072 | 2026-09-16 21:37:59.257251 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-16 21:38:00.699997 | instance -> localhost | Generating public/private rsa key pair. 2026-09-16 21:38:00.700293 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/8020aa0f31624db886d2f6521803aa15/work/8020aa0f31624db886d2f6521803aa15_id_rsa 2026-09-16 21:38:00.700356 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/8020aa0f31624db886d2f6521803aa15/work/8020aa0f31624db886d2f6521803aa15_id_rsa.pub 2026-09-16 21:38:00.700402 | instance -> localhost | The key fingerprint is: 2026-09-16 21:38:00.700446 | instance -> localhost | SHA256:TK9lj9c+e+LhSqffX/v67Xn2lmtUhc9nErRpxzPNADI zuul-build-sshkey 2026-09-16 21:38:00.700543 | instance -> localhost | The key's randomart image is: 2026-09-16 21:38:00.700593 | instance -> localhost | +---[RSA 3072]----+ 2026-09-16 21:38:00.700643 | instance -> localhost | | E .oo. | 2026-09-16 21:38:00.700687 | instance -> localhost | | o o*o| 2026-09-16 21:38:00.700727 | instance -> localhost | | . +**| 2026-09-16 21:38:00.700766 | instance -> localhost | | o . ...O| 2026-09-16 21:38:00.700805 | instance -> localhost | | S + oo| 2026-09-16 21:38:00.700844 | instance -> localhost | | + o . . | 2026-09-16 21:38:00.700883 | instance -> localhost | | . . + = o| 2026-09-16 21:38:00.700922 | instance -> localhost | | o =o=X| 2026-09-16 21:38:00.700967 | instance -> localhost | | o+O##| 2026-09-16 21:38:00.701006 | instance -> localhost | +----[SHA256]-----+ 2026-09-16 21:38:00.701114 | instance -> localhost | ok: Runtime: 0:00:01.006302 2026-09-16 21:38:00.712209 | 2026-09-16 21:38:00.712334 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-16 21:38:00.745077 | instance | ok 2026-09-16 21:38:00.760570 | instance | included: /var/lib/zuul/builds/8020aa0f31624db886d2f6521803aa15/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-16 21:38:00.773116 | 2026-09-16 21:38:00.773210 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-16 21:38:00.797380 | instance | skipping: Conditional result was False 2026-09-16 21:38:00.807789 | 2026-09-16 21:38:00.808148 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-16 21:38:01.295296 | instance | changed 2026-09-16 21:38:01.309704 | 2026-09-16 21:38:01.309890 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-16 21:38:01.497291 | instance | ok 2026-09-16 21:38:01.504601 | 2026-09-16 21:38:01.504742 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-16 21:38:02.017874 | instance | changed 2026-09-16 21:38:02.028747 | 2026-09-16 21:38:02.028931 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-16 21:38:02.526496 | instance | changed 2026-09-16 21:38:02.534247 | 2026-09-16 21:38:02.534330 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-16 21:38:02.558165 | instance | skipping: Conditional result was False 2026-09-16 21:38:02.565024 | 2026-09-16 21:38:02.565125 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-16 21:38:02.985469 | instance -> localhost | changed 2026-09-16 21:38:03.001739 | 2026-09-16 21:38:03.001868 | TASK [add-build-sshkey : Add back temp key] 2026-09-16 21:38:03.316680 | instance -> localhost | Identity added: /var/lib/zuul/builds/8020aa0f31624db886d2f6521803aa15/work/8020aa0f31624db886d2f6521803aa15_id_rsa (zuul-build-sshkey) 2026-09-16 21:38:03.316941 | instance -> localhost | ok: Runtime: 0:00:00.015702 2026-09-16 21:38:03.325702 | 2026-09-16 21:38:03.325913 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-16 21:38:03.688010 | instance | ok 2026-09-16 21:38:03.700563 | 2026-09-16 21:38:03.700776 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-16 21:38:03.737220 | instance | skipping: Conditional result was False 2026-09-16 21:38:03.753313 | 2026-09-16 21:38:03.753428 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-16 21:38:04.111619 | instance | ok 2026-09-16 21:38:04.122630 | 2026-09-16 21:38:04.122758 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-16 21:38:07.887913 | instance | Output suppressed because no_log was given 2026-09-16 21:38:07.907346 | 2026-09-16 21:38:07.907558 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-16 21:38:08.130918 | instance | ok: "logs" 2026-09-16 21:38:08.131332 | instance | ok: All items complete 2026-09-16 21:38:08.131393 | 2026-09-16 21:38:08.296172 | instance | ok: "artifacts" 2026-09-16 21:38:08.443875 | instance | ok: "docs" 2026-09-16 21:38:08.459277 | 2026-09-16 21:38:08.459570 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-16 21:38:08.645478 | instance | changed: "logs" 2026-09-16 21:38:08.801024 | instance | changed: "artifacts" 2026-09-16 21:38:08.941820 | instance | changed: "docs" 2026-09-16 21:38:08.964928 | 2026-09-16 21:38:08.965160 | PLAY RECAP 2026-09-16 21:38:08.965272 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-16 21:38:08.965332 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-16 21:38:08.965376 | 2026-09-16 21:38:09.212397 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-16 21:38:09.216032 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-16 21:38:09.843487 | 2026-09-16 21:38:09.843678 | PLAY [all] 2026-09-16 21:38:09.859549 | 2026-09-16 21:38:09.859790 | TASK [setup-uv : Extract archive] 2026-09-16 21:38:12.201987 | instance | changed 2026-09-16 21:38:12.209822 | 2026-09-16 21:38:12.209946 | TASK [setup-uv : Print version] 2026-09-16 21:38:12.618176 | instance | uv 0.8.13 2026-09-16 21:38:12.745775 | instance | ok: Runtime: 0:00:00.010581 2026-09-16 21:38:12.758544 | 2026-09-16 21:38:12.758633 | TASK [Install Ansible collection siblings] 2026-09-16 21:38:23.122227 | instance | changed: 2026-09-16 21:38:23.122489 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-09-16 21:38:23.122545 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-09-16 21:38:23.122588 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-09-16 21:38:23.122625 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto 2026-09-16 21:38:23.122663 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general 2026-09-16 21:38:23.122704 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql 2026-09-16 21:38:23.122744 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-09-16 21:38:23.122786 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-09-16 21:38:23.122823 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-09-16 21:38:23.122860 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-09-16 21:38:23.122896 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-09-16 21:38:23.122931 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-09-16 21:38:23.122967 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0 2026-09-16 21:38:23.123002 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0 2026-09-16 21:38:23.123037 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0 2026-09-16 21:38:23.123074 | instance | Resolved community.crypto to 2.2.3 for 2.2.3 2026-09-16 21:38:23.123130 | instance | Resolved community.general to 7.3.0 for 7.3.0 2026-09-16 21:38:23.123166 | instance | Resolved community.mysql to 3.6.0 for 3.6.0 2026-09-16 21:38:23.123201 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0 2026-09-16 21:38:23.123237 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0 2026-09-16 21:38:23.123272 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0 2026-09-16 21:38:23.123307 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0 2026-09-16 21:38:23.123346 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0 2026-09-16 21:38:23.123402 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0 2026-09-16 21:38:23.123440 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0 2026-09-16 21:38:23.123475 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-16 21:38:23.123510 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-16 21:38:23.123544 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0 2026-09-16 21:38:23.123579 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0 2026-09-16 21:38:23.123614 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3 2026-09-16 21:38:23.123648 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-16 21:38:23.123685 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-16 21:38:23.123721 | instance | Checked out ansible.netcommon to 1.2.0 2026-09-16 21:38:23.123756 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-09-16 21:38:23.123800 | instance | Starting galaxy collection install process 2026-09-16 21:38:23.123835 | instance | Process install dependency map 2026-09-16 21:38:23.123869 | instance | Starting collection install process 2026-09-16 21:38:23.123904 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-09-16 21:38:23.123938 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon 2026-09-16 21:38:23.123972 | instance | ansible.netcommon:* was installed successfully 2026-09-16 21:38:23.124006 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-09-16 21:38:23.124040 | instance | Creating virtual environment at: .venv 2026-09-16 21:38:23.124073 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-16 21:38:23.124127 | instance | Downloading kubernetes (1.9MiB) 2026-09-16 21:38:23.124162 | instance | Downloading setuptools (1.1MiB) 2026-09-16 21:38:23.124200 | instance | Downloading ansible-core (2.1MiB) 2026-09-16 21:38:23.124235 | instance | Downloading netaddr (2.2MiB) 2026-09-16 21:38:23.124349 | instance | Downloading rjsonnet (1.2MiB) 2026-09-16 21:38:23.124396 | instance | Downloading cryptography (4.2MiB) 2026-09-16 21:38:23.124433 | instance | Downloading openstacksdk (1.7MiB) 2026-09-16 21:38:23.124469 | instance | Downloading rjsonnet 2026-09-16 21:38:23.124532 | instance | Building pyperclip==1.9.0 2026-09-16 21:38:23.124570 | instance | Downloading pygments (1.2MiB) 2026-09-16 21:38:23.124609 | instance | Downloading netaddr 2026-09-16 21:38:23.124645 | instance | Downloading cryptography 2026-09-16 21:38:23.124680 | instance | Downloading pygments 2026-09-16 21:38:23.124715 | instance | Downloading setuptools 2026-09-16 21:38:23.124750 | instance | Downloading kubernetes 2026-09-16 21:38:23.124784 | instance | Downloading ansible-core 2026-09-16 21:38:23.124819 | instance | Downloading openstacksdk 2026-09-16 21:38:23.124854 | instance | Built pyperclip==1.9.0 2026-09-16 21:38:23.124890 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-16 21:38:23.124926 | instance | Installed 79 packages in 58ms 2026-09-16 21:38:23.124962 | instance | Checked out ansible.posix to 1.6.0 2026-09-16 21:38:23.124998 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-09-16 21:38:23.125033 | instance | Starting galaxy collection install process 2026-09-16 21:38:23.125068 | instance | Process install dependency map 2026-09-16 21:38:23.125122 | instance | Starting collection install process 2026-09-16 21:38:23.125158 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-09-16 21:38:23.125203 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix 2026-09-16 21:38:23.125239 | instance | ansible.posix:1.6.0 was installed successfully 2026-09-16 21:38:23.125273 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-16 21:38:23.125308 | instance | 2.17.12 2026-09-16 21:38:23.125347 | instance | Checked out ansible.utils to v6.1.0 2026-09-16 21:38:23.125400 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-09-16 21:38:23.125447 | instance | Starting galaxy collection install process 2026-09-16 21:38:23.125484 | instance | Process install dependency map 2026-09-16 21:38:23.125518 | instance | Starting collection install process 2026-09-16 21:38:23.125552 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-09-16 21:38:23.125586 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils 2026-09-16 21:38:23.125620 | instance | ansible.utils:6.1.0 was installed successfully 2026-09-16 21:38:23.125655 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-16 21:38:23.125689 | instance | 2.17.12 2026-09-16 21:38:23.125725 | instance | Checked out atmosphere.common to v0.7.0 2026-09-16 21:38:23.125760 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-09-16 21:38:23.125796 | instance | Starting galaxy collection install process 2026-09-16 21:38:23.125831 | instance | Process install dependency map 2026-09-16 21:38:23.125865 | instance | Starting collection install process 2026-09-16 21:38:23.126166 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common' 2026-09-16 21:38:23.126248 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common 2026-09-16 21:38:23.126290 | instance | atmosphere.common:0.7.0 was installed successfully 2026-09-16 21:38:23.126327 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-16 21:38:23.126363 | instance | 2.17.12 2026-09-16 21:38:23.126400 | instance | Checked out community.crypto to 2.2.3 2026-09-16 21:38:23.126436 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto 2026-09-16 21:38:23.126471 | instance | Starting galaxy collection install process 2026-09-16 21:38:23.126505 | instance | Process install dependency map 2026-09-16 21:38:23.126540 | instance | Starting collection install process 2026-09-16 21:38:23.126574 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-09-16 21:38:23.126609 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto 2026-09-16 21:38:23.126643 | instance | community.crypto:2.2.3 was installed successfully 2026-09-16 21:38:23.126677 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-16 21:38:23.126711 | instance | 2.17.12 2026-09-16 21:38:23.126744 | instance | Checked out community.general to 7.3.0 2026-09-16 21:38:23.126778 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general 2026-09-16 21:38:23.126812 | instance | Starting galaxy collection install process 2026-09-16 21:38:23.126859 | instance | Process install dependency map 2026-09-16 21:38:23.126894 | instance | Starting collection install process 2026-09-16 21:38:23.126927 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-09-16 21:38:23.126961 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general 2026-09-16 21:38:23.126994 | instance | community.general:7.3.0 was installed successfully 2026-09-16 21:38:23.127028 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-16 21:38:23.127062 | instance | 2.17.12 2026-09-16 21:38:23.127114 | instance | Checked out community.mysql to 3.6.0 2026-09-16 21:38:23.127151 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql 2026-09-16 21:38:23.127187 | instance | Starting galaxy collection install process 2026-09-16 21:38:23.127227 | instance | Process install dependency map 2026-09-16 21:38:23.127263 | instance | Starting collection install process 2026-09-16 21:38:23.127298 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-09-16 21:38:23.127334 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql 2026-09-16 21:38:23.127369 | instance | community.mysql:3.6.0 was installed successfully 2026-09-16 21:38:23.127404 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-16 21:38:23.127439 | instance | 2.17.12 2026-09-16 21:38:23.127474 | instance | Checked out kubernetes.core to 2.4.0 2026-09-16 21:38:23.127509 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-09-16 21:38:23.127545 | instance | Starting galaxy collection install process 2026-09-16 21:38:23.127580 | instance | Process install dependency map 2026-09-16 21:38:23.127615 | instance | Starting collection install process 2026-09-16 21:38:23.127650 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-09-16 21:38:23.127685 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core 2026-09-16 21:38:23.127720 | instance | kubernetes.core:2.4.0 was installed successfully 2026-09-16 21:38:23.127755 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-16 21:38:23.127789 | instance | 2.17.12 2026-09-16 21:38:23.127824 | instance | Checked out openstack.cloud to 2.6.0 2026-09-16 21:38:23.127858 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-09-16 21:38:23.127893 | instance | Starting galaxy collection install process 2026-09-16 21:38:23.127928 | instance | Process install dependency map 2026-09-16 21:38:23.127962 | instance | Starting collection install process 2026-09-16 21:38:23.127996 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-09-16 21:38:23.128031 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud 2026-09-16 21:38:23.128065 | instance | openstack.cloud:2.6.0 was installed successfully 2026-09-16 21:38:23.128118 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-16 21:38:23.128153 | instance | 2.17.12 2026-09-16 21:38:23.128187 | instance | Checked out vexxhost.ceph to v4.2.0 2026-09-16 21:38:23.128222 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-09-16 21:38:23.128266 | instance | Starting galaxy collection install process 2026-09-16 21:38:23.128302 | instance | Process install dependency map 2026-09-16 21:38:23.128336 | instance | Starting collection install process 2026-09-16 21:38:23.128370 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph' 2026-09-16 21:38:23.128404 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph 2026-09-16 21:38:23.128438 | instance | vexxhost.ceph:4.2.0 was installed successfully 2026-09-16 21:38:23.128471 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-16 21:38:23.128533 | instance | 2.17.12 2026-09-16 21:38:23.128569 | instance | Checked out vexxhost.containers to v1.6.8 2026-09-16 21:38:23.128604 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-09-16 21:38:23.128638 | instance | Starting galaxy collection install process 2026-09-16 21:38:23.128673 | instance | Process install dependency map 2026-09-16 21:38:23.128707 | instance | Starting collection install process 2026-09-16 21:38:23.128741 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers' 2026-09-16 21:38:23.128775 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers 2026-09-16 21:38:23.128810 | instance | vexxhost.containers:1.6.8 was installed successfully 2026-09-16 21:38:23.128844 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-16 21:38:23.128877 | instance | 2.17.12 2026-09-16 21:38:23.128911 | instance | Checked out vexxhost.kubernetes to v3.4.0 2026-09-16 21:38:23.128946 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-09-16 21:38:23.128981 | instance | Starting galaxy collection install process 2026-09-16 21:38:23.129015 | instance | Process install dependency map 2026-09-16 21:38:23.129049 | instance | Starting collection install process 2026-09-16 21:38:23.129101 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes' 2026-09-16 21:38:23.129138 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes 2026-09-16 21:38:23.129171 | instance | vexxhost.kubernetes:3.4.0 was installed successfully 2026-09-16 21:38:23.129205 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-16 21:38:23.129239 | instance | 2.17.12 2026-09-16 21:38:23.139210 | 2026-09-16 21:38:23.139293 | PLAY RECAP 2026-09-16 21:38:23.139370 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-16 21:38:23.139410 | 2026-09-16 21:38:23.279545 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-16 21:38:23.283620 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-16 21:38:23.841075 | 2026-09-16 21:38:23.841234 | PLAY [all] 2026-09-16 21:38:23.851708 | 2026-09-16 21:38:23.851784 | TASK [Install "jq" for log collection] 2026-09-16 21:38:35.071470 | instance | changed 2026-09-16 21:38:35.073182 | 2026-09-16 21:38:35.073239 | PLAY RECAP 2026-09-16 21:38:35.073286 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-16 21:38:35.073332 | 2026-09-16 21:38:35.236231 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-16 21:38:35.240005 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-16 21:38:35.912344 | 2026-09-16 21:38:35.912611 | PLAY [all] 2026-09-16 21:38:35.937384 | 2026-09-16 21:38:35.937661 | LOOP [Check inventory directories] 2026-09-16 21:38:36.322288 | instance | ok: "src/github.com/vexxhost/atmosphere" 2026-09-16 21:38:36.499041 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory" 2026-09-16 21:38:36.644432 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory" 2026-09-16 21:38:36.655337 | 2026-09-16 21:38:36.655579 | LOOP [Copy inventory file for Zuul] 2026-09-16 21:38:37.562770 | instance | changed: 2026-09-16 21:38:37.563065 | instance | { 2026-09-16 21:38:37.563150 | instance | "ansible_loop_var": "item", 2026-09-16 21:38:37.563202 | instance | "changed": false, 2026-09-16 21:38:37.563245 | instance | "failed": false, 2026-09-16 21:38:37.563294 | instance | "invocation": { 2026-09-16 21:38:37.563335 | instance | "module_args": { 2026-09-16 21:38:37.563375 | instance | "checksum_algorithm": "sha1", 2026-09-16 21:38:37.563411 | instance | "follow": true, 2026-09-16 21:38:37.563447 | instance | "get_attributes": true, 2026-09-16 21:38:37.563488 | instance | "get_checksum": true, 2026-09-16 21:38:37.563525 | instance | "get_mime": true, 2026-09-16 21:38:37.563571 | instance | "path": "src/github.com/vexxhost/atmosphere" 2026-09-16 21:38:37.563627 | instance | } 2026-09-16 21:38:37.563669 | instance | }, 2026-09-16 21:38:37.563707 | instance | "item": "src/github.com/vexxhost/atmosphere", 2026-09-16 21:38:37.563744 | instance | "stat": { 2026-09-16 21:38:37.563781 | instance | "atime": 1789594694.8424785, 2026-09-16 21:38:37.563819 | instance | "attr_flags": "e", 2026-09-16 21:38:37.563856 | instance | "attributes": [ 2026-09-16 21:38:37.563896 | instance | "extents" 2026-09-16 21:38:37.563932 | instance | ], 2026-09-16 21:38:37.564104 | instance | "block_size": 4096, 2026-09-16 21:38:37.564152 | instance | "blocks": 8, 2026-09-16 21:38:37.564192 | instance | "charset": "unknown", 2026-09-16 21:38:37.564233 | instance | "ctime": 1789594693.638389, 2026-09-16 21:38:37.564270 | instance | "dev": 64513, 2026-09-16 21:38:37.564307 | instance | "device_type": 0, 2026-09-16 21:38:37.564343 | instance | "executable": true, 2026-09-16 21:38:37.564381 | instance | "exists": true, 2026-09-16 21:38:37.564416 | instance | "gid": 1000, 2026-09-16 21:38:37.564449 | instance | "gr_name": "zuul", 2026-09-16 21:38:37.564486 | instance | "inode": 1049013, 2026-09-16 21:38:37.564553 | instance | "isblk": false, 2026-09-16 21:38:37.564606 | instance | "ischr": false, 2026-09-16 21:38:37.564645 | instance | "isdir": true, 2026-09-16 21:38:37.564682 | instance | "isfifo": false, 2026-09-16 21:38:37.564717 | instance | "isgid": false, 2026-09-16 21:38:37.564751 | instance | "islnk": false, 2026-09-16 21:38:37.564786 | instance | "isreg": false, 2026-09-16 21:38:37.564821 | instance | "issock": false, 2026-09-16 21:38:37.564855 | instance | "isuid": false, 2026-09-16 21:38:37.564890 | instance | "mimetype": "unknown", 2026-09-16 21:38:37.564925 | instance | "mode": "0755", 2026-09-16 21:38:37.564960 | instance | "mtime": 1789594693.638389, 2026-09-16 21:38:37.564993 | instance | "nlink": 21, 2026-09-16 21:38:37.565028 | instance | "path": "src/github.com/vexxhost/atmosphere", 2026-09-16 21:38:37.565064 | instance | "pw_name": "zuul", 2026-09-16 21:38:37.565125 | instance | "readable": true, 2026-09-16 21:38:37.565166 | instance | "rgrp": true, 2026-09-16 21:38:37.565199 | instance | "roth": true, 2026-09-16 21:38:37.565235 | instance | "rusr": true, 2026-09-16 21:38:37.565270 | instance | "size": 4096, 2026-09-16 21:38:37.565306 | instance | "uid": 1000, 2026-09-16 21:38:37.565358 | instance | "version": "2515401930", 2026-09-16 21:38:37.565398 | instance | "wgrp": false, 2026-09-16 21:38:37.565438 | instance | "woth": false, 2026-09-16 21:38:37.565476 | instance | "writeable": true, 2026-09-16 21:38:37.565510 | instance | "wusr": true, 2026-09-16 21:38:37.565548 | instance | "xgrp": true, 2026-09-16 21:38:37.565599 | instance | "xoth": true, 2026-09-16 21:38:37.565639 | instance | "xusr": true 2026-09-16 21:38:37.565674 | instance | } 2026-09-16 21:38:37.565709 | instance | } 2026-09-16 21:38:37.565778 | instance | ok: All items complete 2026-09-16 21:38:37.565823 | 2026-09-16 21:38:37.568325 | instance | skipping: Conditional result was False 2026-09-16 21:38:37.576123 | instance | skipping: Conditional result was False 2026-09-16 21:38:37.593888 | 2026-09-16 21:38:37.594119 | LOOP [Switch "ansible_host" to private IP] 2026-09-16 21:38:37.989652 | instance | changed: 1 replacements made 2026-09-16 21:38:37.992286 | instance | skipping: Conditional result was False 2026-09-16 21:38:37.996207 | instance | skipping: Conditional result was False 2026-09-16 21:38:38.023134 | 2026-09-16 21:38:38.023328 | TASK [Run Molecule scenario] 2026-09-16 21:38:39.008675 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-09-16 21:38:39.448124 | instance | INFO [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-16 21:38:39.448276 | instance | INFO [csi > prerun] Performing prerun with role_name_check=0... 2026-09-16 21:38:51.019472 | instance | INFO [csi > dependency] Executing 2026-09-16 21:38:51.019915 | instance | WARNING [csi > dependency] Missing roles requirements file: requirements.yml 2026-09-16 21:38:51.020332 | instance | WARNING [csi > dependency] Missing collections requirements file: collections.yml 2026-09-16 21:38:51.020732 | instance | WARNING [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-09-16 21:38:51.037973 | instance | INFO [csi > cleanup] Executing 2026-09-16 21:38:51.038461 | instance | WARNING [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-16 21:38:51.049138 | instance | INFO [csi > destroy] Executing 2026-09-16 21:38:51.049267 | instance | WARNING [csi > destroy] Skipping, '--destroy=never' requested. 2026-09-16 21:38:51.049400 | instance | INFO [csi > destroy] Executed: Successful 2026-09-16 21:38:51.059592 | instance | INFO [csi > syntax] Executing 2026-09-16 21:38:52.646839 | instance | 2026-09-16 21:38:52.647177 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-16 21:38:52.714326 | instance | INFO [csi > syntax] Executed: Successful 2026-09-16 21:38:52.726975 | instance | INFO [csi > create] Executing 2026-09-16 21:38:52.730086 | instance | WARNING [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-16 21:38:52.740652 | instance | INFO [csi > prepare] Executing 2026-09-16 21:38:53.529561 | instance | 2026-09-16 21:38:53.529989 | instance | PLAY [Prepare] ***************************************************************** 2026-09-16 21:38:53.530273 | instance | 2026-09-16 21:38:53.530527 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-16 21:38:53.530777 | instance | Wednesday 16 September 2026 21:38:53 +0000 (0:00:00.028) 0:00:00.028 *** 2026-09-16 21:38:54.818863 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-16 21:38:54.819085 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-16 21:38:54.819325 | instance | interpreter could change the meaning of that path. See 2026-09-16 21:38:54.819561 | instance | https://docs.ansible.com/ansible- 2026-09-16 21:38:54.819798 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-16 21:38:54.832398 | instance | ok: [instance] 2026-09-16 21:38:54.832604 | instance | 2026-09-16 21:38:54.832848 | instance | TASK [Configure short hostname] ************************************************ 2026-09-16 21:38:54.833149 | instance | Wednesday 16 September 2026 21:38:54 +0000 (0:00:01.305) 0:00:01.333 *** 2026-09-16 21:38:55.601068 | instance | changed: [instance] 2026-09-16 21:38:55.601239 | instance | 2026-09-16 21:38:55.601465 | instance | TASK [Ensure hostname inside hosts file] *************************************** 2026-09-16 21:38:55.601731 | instance | Wednesday 16 September 2026 21:38:55 +0000 (0:00:00.768) 0:00:02.101 *** 2026-09-16 21:38:55.911091 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created 2026-09-16 21:38:55.911156 | instance | with a mode of 0700, this may cause issues when running as another user. To 2026-09-16 21:38:55.911272 | instance | avoid this, create the remote_tmp dir with the correct permissions manually 2026-09-16 21:38:55.920603 | instance | changed: [instance] 2026-09-16 21:38:55.920695 | instance | 2026-09-16 21:38:55.920802 | instance | TASK [Purge "snapd" package] *************************************************** 2026-09-16 21:38:55.920925 | instance | Wednesday 16 September 2026 21:38:55 +0000 (0:00:00.320) 0:00:02.421 *** 2026-09-16 21:38:56.837047 | instance | ok: [instance] 2026-09-16 21:38:56.837315 | instance | 2026-09-16 21:38:56.837575 | instance | PLAY [Create devices for Ceph] ************************************************* 2026-09-16 21:38:56.837815 | instance | 2026-09-16 21:38:56.838143 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-16 21:38:56.838457 | instance | Wednesday 16 September 2026 21:38:56 +0000 (0:00:00.914) 0:00:03.336 *** 2026-09-16 21:38:57.539770 | instance | ok: [instance] 2026-09-16 21:38:57.540031 | instance | 2026-09-16 21:38:57.540285 | instance | TASK [Install depedencies] ***************************************************** 2026-09-16 21:38:57.540531 | instance | Wednesday 16 September 2026 21:38:57 +0000 (0:00:00.704) 0:00:04.040 *** 2026-09-16 21:39:21.833062 | instance | changed: [instance] 2026-09-16 21:39:21.833119 | instance | 2026-09-16 21:39:21.833224 | instance | TASK [Start up service] ******************************************************** 2026-09-16 21:39:21.833529 | instance | Wednesday 16 September 2026 21:39:21 +0000 (0:00:24.293) 0:00:28.333 *** 2026-09-16 21:39:22.432867 | instance | ok: [instance] 2026-09-16 21:39:22.433087 | instance | 2026-09-16 21:39:22.433336 | instance | TASK [Generate lvm.conf] ******************************************************* 2026-09-16 21:39:22.433581 | instance | Wednesday 16 September 2026 21:39:22 +0000 (0:00:00.599) 0:00:28.933 *** 2026-09-16 21:39:22.748086 | instance | ok: [instance] 2026-09-16 21:39:22.748234 | instance | 2026-09-16 21:39:22.748408 | instance | TASK [Write /etc/lvm/lvm.conf] ************************************************* 2026-09-16 21:39:22.748577 | instance | Wednesday 16 September 2026 21:39:22 +0000 (0:00:00.314) 0:00:29.248 *** 2026-09-16 21:39:23.397975 | instance | changed: [instance] 2026-09-16 21:39:23.398037 | instance | 2026-09-16 21:39:23.398050 | instance | TASK [Get list of all loopback devices] **************************************** 2026-09-16 21:39:23.398062 | instance | Wednesday 16 September 2026 21:39:23 +0000 (0:00:00.649) 0:00:29.897 *** 2026-09-16 21:39:23.605122 | instance | ok: [instance] 2026-09-16 21:39:23.605497 | instance | 2026-09-16 21:39:23.605980 | instance | TASK [Fail if there is any existing loopback devices] ************************** 2026-09-16 21:39:23.606449 | instance | Wednesday 16 September 2026 21:39:23 +0000 (0:00:00.207) 0:00:30.105 *** 2026-09-16 21:39:23.630640 | instance | skipping: [instance] 2026-09-16 21:39:23.630775 | instance | 2026-09-16 21:39:23.630944 | instance | TASK [Create devices for Ceph] ************************************************* 2026-09-16 21:39:23.631193 | instance | Wednesday 16 September 2026 21:39:23 +0000 (0:00:00.026) 0:00:30.131 *** 2026-09-16 21:39:24.209764 | instance | changed: [instance] => (item=osd0) 2026-09-16 21:39:24.209830 | instance | changed: [instance] => (item=osd1) 2026-09-16 21:39:24.209845 | instance | changed: [instance] => (item=osd2) 2026-09-16 21:39:24.209879 | instance | 2026-09-16 21:39:24.209897 | instance | TASK [Set permissions on loopback devices] ************************************* 2026-09-16 21:39:24.209911 | instance | Wednesday 16 September 2026 21:39:24 +0000 (0:00:00.576) 0:00:30.707 *** 2026-09-16 21:39:24.860718 | instance | changed: [instance] => (item=osd0) 2026-09-16 21:39:24.860767 | instance | changed: [instance] => (item=osd1) 2026-09-16 21:39:24.860778 | instance | changed: [instance] => (item=osd2) 2026-09-16 21:39:24.860788 | instance | 2026-09-16 21:39:24.860798 | instance | TASK [Start loop devices] ****************************************************** 2026-09-16 21:39:24.860807 | instance | Wednesday 16 September 2026 21:39:24 +0000 (0:00:00.652) 0:00:31.360 *** 2026-09-16 21:39:25.605174 | instance | changed: [instance] => (item=osd0) 2026-09-16 21:39:25.606366 | instance | changed: [instance] => (item=osd1) 2026-09-16 21:39:25.606392 | instance | changed: [instance] => (item=osd2) 2026-09-16 21:39:25.606401 | instance | 2026-09-16 21:39:25.606410 | instance | TASK [Create a volume group for each loop device] ****************************** 2026-09-16 21:39:25.606419 | instance | Wednesday 16 September 2026 21:39:25 +0000 (0:00:00.745) 0:00:32.106 *** 2026-09-16 21:39:28.820272 | instance | changed: [instance] => (item=osd0) 2026-09-16 21:39:28.820317 | instance | changed: [instance] => (item=osd1) 2026-09-16 21:39:28.820324 | instance | changed: [instance] => (item=osd2) 2026-09-16 21:39:28.820331 | instance | 2026-09-16 21:39:28.820337 | instance | TASK [Create a logical volume for each loop device] **************************** 2026-09-16 21:39:28.820344 | instance | Wednesday 16 September 2026 21:39:28 +0000 (0:00:03.213) 0:00:35.319 *** 2026-09-16 21:39:30.713182 | instance | changed: [instance] => (item=ceph-instance-osd0) 2026-09-16 21:39:30.713255 | instance | changed: [instance] => (item=ceph-instance-osd1) 2026-09-16 21:39:30.713311 | instance | changed: [instance] => (item=ceph-instance-osd2) 2026-09-16 21:39:30.713322 | instance | 2026-09-16 21:39:30.713332 | instance | PLAY RECAP ********************************************************************* 2026-09-16 21:39:30.713559 | instance | instance : ok=15 changed=9 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-09-16 21:39:30.714181 | instance | 2026-09-16 21:39:30.714211 | instance | Wednesday 16 September 2026 21:39:30 +0000 (0:00:01.893) 0:00:37.213 *** 2026-09-16 21:39:30.714376 | instance | =============================================================================== 2026-09-16 21:39:30.714598 | instance | Install depedencies ---------------------------------------------------- 24.29s 2026-09-16 21:39:30.714885 | instance | Create a volume group for each loop device ------------------------------ 3.21s 2026-09-16 21:39:30.715193 | instance | Create a logical volume for each loop device ---------------------------- 1.89s 2026-09-16 21:39:30.715440 | instance | Gathering Facts --------------------------------------------------------- 1.31s 2026-09-16 21:39:30.715703 | instance | Purge "snapd" package --------------------------------------------------- 0.91s 2026-09-16 21:39:30.715935 | instance | Configure short hostname ------------------------------------------------ 0.77s 2026-09-16 21:39:30.716158 | instance | Start loop devices ------------------------------------------------------ 0.75s 2026-09-16 21:39:30.716381 | instance | Gathering Facts --------------------------------------------------------- 0.70s 2026-09-16 21:39:30.716642 | instance | Set permissions on loopback devices ------------------------------------- 0.65s 2026-09-16 21:39:30.716866 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.65s 2026-09-16 21:39:30.717168 | instance | Start up service -------------------------------------------------------- 0.60s 2026-09-16 21:39:30.717399 | instance | Create devices for Ceph ------------------------------------------------- 0.58s 2026-09-16 21:39:30.717601 | instance | Ensure hostname inside hosts file --------------------------------------- 0.32s 2026-09-16 21:39:30.717885 | instance | Generate lvm.conf ------------------------------------------------------- 0.31s 2026-09-16 21:39:30.718099 | instance | Get list of all loopback devices ---------------------------------------- 0.21s 2026-09-16 21:39:30.718306 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s 2026-09-16 21:39:30.797962 | instance | INFO [csi > prepare] Executed: Successful 2026-09-16 21:39:30.806131 | instance | INFO [csi > converge] Executing 2026-09-16 21:39:32.232565 | instance | 2026-09-16 21:39:32.232724 | instance | PLAY [Debug CSI driver value] ************************************************** 2026-09-16 21:39:32.232868 | instance | 2026-09-16 21:39:32.233047 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-16 21:39:32.233230 | instance | Wednesday 16 September 2026 21:39:32 +0000 (0:00:00.007) 0:00:00.007 *** 2026-09-16 21:39:33.237262 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-16 21:39:33.237345 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-16 21:39:33.237427 | instance | interpreter could change the meaning of that path. See 2026-09-16 21:39:33.237561 | instance | https://docs.ansible.com/ansible- 2026-09-16 21:39:33.237723 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-16 21:39:33.253480 | instance | ok: [instance] 2026-09-16 21:39:33.253570 | instance | 2026-09-16 21:39:33.253706 | instance | TASK [Display CSI driver value and environment variable] *********************** 2026-09-16 21:39:33.253819 | instance | Wednesday 16 September 2026 21:39:33 +0000 (0:00:01.020) 0:00:01.027 *** 2026-09-16 21:39:33.314657 | instance | ok: [instance] => { 2026-09-16 21:39:33.314816 | instance | "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER=" 2026-09-16 21:39:33.314982 | instance | } 2026-09-16 21:39:33.315139 | instance | 2026-09-16 21:39:33.315300 | instance | PLAY [all] ********************************************************************* 2026-09-16 21:39:33.315449 | instance | 2026-09-16 21:39:33.315637 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-16 21:39:33.315793 | instance | Wednesday 16 September 2026 21:39:33 +0000 (0:00:00.061) 0:00:01.089 *** 2026-09-16 21:39:34.238205 | instance | ok: [instance] 2026-09-16 21:39:34.238304 | instance | 2026-09-16 21:39:34.238439 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] ***************** 2026-09-16 21:39:34.238645 | instance | Wednesday 16 September 2026 21:39:34 +0000 (0:00:00.923) 0:00:02.012 *** 2026-09-16 21:39:34.437921 | instance | ok: [instance] 2026-09-16 21:39:34.438019 | instance | 2026-09-16 21:39:34.438189 | instance | PLAY [Deploy Ceph monitors & managers] ***************************************** 2026-09-16 21:39:34.438327 | instance | 2026-09-16 21:39:34.438491 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-16 21:39:34.438647 | instance | Wednesday 16 September 2026 21:39:34 +0000 (0:00:00.199) 0:00:02.212 *** 2026-09-16 21:39:35.287490 | instance | ok: [instance] 2026-09-16 21:39:35.287623 | instance | 2026-09-16 21:39:35.287790 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-16 21:39:35.287955 | instance | Wednesday 16 September 2026 21:39:35 +0000 (0:00:00.849) 0:00:03.061 *** 2026-09-16 21:39:35.573725 | instance | ok: [instance] 2026-09-16 21:39:35.574031 | instance | 2026-09-16 21:39:35.574386 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-16 21:39:35.574784 | instance | Wednesday 16 September 2026 21:39:35 +0000 (0:00:00.285) 0:00:03.347 *** 2026-09-16 21:39:35.613248 | instance | skipping: [instance] 2026-09-16 21:39:35.613292 | instance | 2026-09-16 21:39:35.613303 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-09-16 21:39:35.613313 | instance | Wednesday 16 September 2026 21:39:35 +0000 (0:00:00.039) 0:00:03.387 *** 2026-09-16 21:39:35.939169 | instance | changed: [instance] 2026-09-16 21:39:35.939320 | instance | 2026-09-16 21:39:35.939598 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-16 21:39:35.939852 | instance | Wednesday 16 September 2026 21:39:35 +0000 (0:00:00.326) 0:00:03.713 *** 2026-09-16 21:39:36.030311 | instance | ok: [instance] => { 2026-09-16 21:39:36.030376 | instance | "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64" 2026-09-16 21:39:36.030478 | instance | } 2026-09-16 21:39:36.030617 | instance | 2026-09-16 21:39:36.030773 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-16 21:39:36.030884 | instance | Wednesday 16 September 2026 21:39:36 +0000 (0:00:00.091) 0:00:03.805 *** 2026-09-16 21:39:36.710274 | instance | changed: [instance] 2026-09-16 21:39:36.710664 | instance | 2026-09-16 21:39:36.710924 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-16 21:39:36.711174 | instance | Wednesday 16 September 2026 21:39:36 +0000 (0:00:00.679) 0:00:04.484 *** 2026-09-16 21:39:36.749528 | instance | skipping: [instance] 2026-09-16 21:39:36.749638 | instance | 2026-09-16 21:39:36.749707 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-16 21:39:36.749886 | instance | Wednesday 16 September 2026 21:39:36 +0000 (0:00:00.038) 0:00:04.522 *** 2026-09-16 21:39:36.809316 | instance | skipping: [instance] 2026-09-16 21:39:36.809586 | instance | 2026-09-16 21:39:36.809825 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-16 21:39:36.810063 | instance | Wednesday 16 September 2026 21:39:36 +0000 (0:00:00.059) 0:00:04.581 *** 2026-09-16 21:39:37.062133 | instance | ok: [instance] 2026-09-16 21:39:37.062175 | instance | 2026-09-16 21:39:37.062183 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-16 21:39:37.062190 | instance | Wednesday 16 September 2026 21:39:37 +0000 (0:00:00.254) 0:00:04.836 *** 2026-09-16 21:39:38.347220 | instance | ok: [instance] 2026-09-16 21:39:38.347328 | instance | 2026-09-16 21:39:38.347742 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-16 21:39:38.347804 | instance | Wednesday 16 September 2026 21:39:38 +0000 (0:00:01.285) 0:00:06.121 *** 2026-09-16 21:39:38.434634 | instance | ok: [instance] => { 2026-09-16 21:39:38.436421 | instance | "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz" 2026-09-16 21:39:38.436562 | instance | } 2026-09-16 21:39:38.436579 | instance | 2026-09-16 21:39:38.436592 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-16 21:39:38.436605 | instance | Wednesday 16 September 2026 21:39:38 +0000 (0:00:00.086) 0:00:06.208 *** 2026-09-16 21:39:39.098764 | instance | changed: [instance] 2026-09-16 21:39:39.098975 | instance | 2026-09-16 21:39:39.099386 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-16 21:39:39.099735 | instance | Wednesday 16 September 2026 21:39:39 +0000 (0:00:00.664) 0:00:06.872 *** 2026-09-16 21:39:42.051619 | instance | changed: [instance] 2026-09-16 21:39:42.051828 | instance | 2026-09-16 21:39:42.052205 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] *************** 2026-09-16 21:39:42.052587 | instance | Wednesday 16 September 2026 21:39:42 +0000 (0:00:02.952) 0:00:09.825 *** 2026-09-16 21:39:42.078887 | instance | skipping: [instance] 2026-09-16 21:39:42.079029 | instance | 2026-09-16 21:39:42.079377 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] *** 2026-09-16 21:39:42.079594 | instance | Wednesday 16 September 2026 21:39:42 +0000 (0:00:00.027) 0:00:09.853 *** 2026-09-16 21:39:42.121451 | instance | skipping: [instance] 2026-09-16 21:39:42.122857 | instance | 2026-09-16 21:39:42.123427 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ******** 2026-09-16 21:39:42.123788 | instance | Wednesday 16 September 2026 21:39:42 +0000 (0:00:00.039) 0:00:09.893 *** 2026-09-16 21:39:42.164108 | instance | skipping: [instance] 2026-09-16 21:39:42.164166 | instance | 2026-09-16 21:39:42.164177 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] ************** 2026-09-16 21:39:42.164186 | instance | Wednesday 16 September 2026 21:39:42 +0000 (0:00:00.043) 0:00:09.937 *** 2026-09-16 21:39:48.112604 | instance | changed: [instance] 2026-09-16 21:39:48.112745 | instance | 2026-09-16 21:39:48.113085 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] *** 2026-09-16 21:39:48.113431 | instance | Wednesday 16 September 2026 21:39:48 +0000 (0:00:05.949) 0:00:15.886 *** 2026-09-16 21:39:48.781765 | instance | changed: [instance] 2026-09-16 21:39:48.781885 | instance | 2026-09-16 21:39:48.781900 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] ******* 2026-09-16 21:39:48.782044 | instance | Wednesday 16 September 2026 21:39:48 +0000 (0:00:00.670) 0:00:16.556 *** 2026-09-16 21:39:49.818907 | instance | changed: [instance] => (item={'path': '/etc/containerd'}) 2026-09-16 21:39:49.820164 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'}) 2026-09-16 21:39:49.820211 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'}) 2026-09-16 21:39:49.820219 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'}) 2026-09-16 21:39:49.820226 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'}) 2026-09-16 21:39:49.820232 | instance | 2026-09-16 21:39:49.820243 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] ********** 2026-09-16 21:39:49.820249 | instance | Wednesday 16 September 2026 21:39:49 +0000 (0:00:01.037) 0:00:17.593 *** 2026-09-16 21:39:50.403830 | instance | changed: [instance] 2026-09-16 21:39:50.405125 | instance | 2026-09-16 21:39:50.405197 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ******** 2026-09-16 21:39:50.405205 | instance | Wednesday 16 September 2026 21:39:50 +0000 (0:00:00.559) 0:00:18.153 *** 2026-09-16 21:39:50.405211 | instance | 2026-09-16 21:39:50.405217 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-16 21:39:50.405222 | instance | Wednesday 16 September 2026 21:39:50 +0000 (0:00:00.024) 0:00:18.177 *** 2026-09-16 21:39:51.374010 | instance | ok: [instance] 2026-09-16 21:39:51.374050 | instance | 2026-09-16 21:39:51.374059 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] ********** 2026-09-16 21:39:51.374065 | instance | Wednesday 16 September 2026 21:39:51 +0000 (0:00:00.970) 0:00:19.148 *** 2026-09-16 21:39:51.826439 | instance | changed: [instance] 2026-09-16 21:39:51.826558 | instance | 2026-09-16 21:39:51.826622 | instance | TASK [vexxhost.containers.containerd : Enable and start service] *************** 2026-09-16 21:39:51.826783 | instance | Wednesday 16 September 2026 21:39:51 +0000 (0:00:00.452) 0:00:19.600 *** 2026-09-16 21:39:52.465937 | instance | changed: [instance] 2026-09-16 21:39:52.466058 | instance | 2026-09-16 21:39:52.466071 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-16 21:39:52.466180 | instance | Wednesday 16 September 2026 21:39:52 +0000 (0:00:00.639) 0:00:20.240 *** 2026-09-16 21:39:52.696919 | instance | ok: [instance] 2026-09-16 21:39:52.697680 | instance | 2026-09-16 21:39:52.697709 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-16 21:39:52.697720 | instance | Wednesday 16 September 2026 21:39:52 +0000 (0:00:00.230) 0:00:20.471 *** 2026-09-16 21:39:52.754240 | instance | ok: [instance] => { 2026-09-16 21:39:52.755051 | instance | "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz" 2026-09-16 21:39:52.755072 | instance | } 2026-09-16 21:39:52.755079 | instance | 2026-09-16 21:39:52.755085 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-16 21:39:52.755091 | instance | Wednesday 16 September 2026 21:39:52 +0000 (0:00:00.057) 0:00:20.529 *** 2026-09-16 21:40:07.975232 | instance | FAILED - RETRYING: [instance]: Download item (4 retries left). 2026-09-16 21:40:07.976055 | instance | changed: [instance] 2026-09-16 21:40:07.976114 | instance | 2026-09-16 21:40:07.976123 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-16 21:40:07.976130 | instance | Wednesday 16 September 2026 21:40:07 +0000 (0:00:15.220) 0:00:35.749 *** 2026-09-16 21:40:13.376128 | instance | changed: [instance] 2026-09-16 21:40:13.376208 | instance | 2026-09-16 21:40:13.376613 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ****************** 2026-09-16 21:40:13.376662 | instance | Wednesday 16 September 2026 21:40:13 +0000 (0:00:05.400) 0:00:41.150 *** 2026-09-16 21:40:14.597718 | instance | ok: [instance] 2026-09-16 21:40:14.597785 | instance | 2026-09-16 21:40:14.598047 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] *************** 2026-09-16 21:40:14.598181 | instance | Wednesday 16 September 2026 21:40:14 +0000 (0:00:01.221) 0:00:42.372 *** 2026-09-16 21:40:14.985468 | instance | changed: [instance] 2026-09-16 21:40:14.986369 | instance | 2026-09-16 21:40:14.986429 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] ***** 2026-09-16 21:40:14.986441 | instance | Wednesday 16 September 2026 21:40:14 +0000 (0:00:00.386) 0:00:42.759 *** 2026-09-16 21:40:15.486038 | instance | changed: [instance] 2026-09-16 21:40:15.487038 | instance | 2026-09-16 21:40:15.487075 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] *********** 2026-09-16 21:40:15.487089 | instance | Wednesday 16 September 2026 21:40:15 +0000 (0:00:00.500) 0:00:43.260 *** 2026-09-16 21:40:16.118319 | instance | changed: [instance] => (item={'path': '/etc/docker'}) 2026-09-16 21:40:16.120001 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'}) 2026-09-16 21:40:16.120040 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'}) 2026-09-16 21:40:16.120054 | instance | 2026-09-16 21:40:16.120067 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ****** 2026-09-16 21:40:16.120079 | instance | Wednesday 16 September 2026 21:40:16 +0000 (0:00:00.632) 0:00:43.892 *** 2026-09-16 21:40:16.590064 | instance | changed: [instance] 2026-09-16 21:40:16.590179 | instance | 2026-09-16 21:40:16.590546 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] *********** 2026-09-16 21:40:16.590690 | instance | Wednesday 16 September 2026 21:40:16 +0000 (0:00:00.472) 0:00:44.364 *** 2026-09-16 21:40:17.074088 | instance | changed: [instance] 2026-09-16 21:40:17.074741 | instance | 2026-09-16 21:40:17.074757 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************ 2026-09-16 21:40:17.074763 | instance | Wednesday 16 September 2026 21:40:17 +0000 (0:00:00.467) 0:00:44.832 *** 2026-09-16 21:40:17.074767 | instance | 2026-09-16 21:40:17.074771 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-16 21:40:17.074784 | instance | Wednesday 16 September 2026 21:40:17 +0000 (0:00:00.016) 0:00:44.849 *** 2026-09-16 21:40:17.771277 | instance | ok: [instance] 2026-09-16 21:40:17.771356 | instance | 2026-09-16 21:40:17.771640 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ****************** 2026-09-16 21:40:17.771655 | instance | Wednesday 16 September 2026 21:40:17 +0000 (0:00:00.696) 0:00:45.546 *** 2026-09-16 21:40:18.675782 | instance | changed: [instance] 2026-09-16 21:40:18.676630 | instance | 2026-09-16 21:40:18.676662 | instance | TASK [vexxhost.containers.docker : Enable and start service] ******************* 2026-09-16 21:40:18.676674 | instance | Wednesday 16 September 2026 21:40:18 +0000 (0:00:00.903) 0:00:46.450 *** 2026-09-16 21:40:19.295032 | instance | changed: [instance] 2026-09-16 21:40:19.295452 | instance | 2026-09-16 21:40:19.295470 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ****** 2026-09-16 21:40:19.295477 | instance | Wednesday 16 September 2026 21:40:19 +0000 (0:00:00.619) 0:00:47.069 *** 2026-09-16 21:40:19.347133 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml) 2026-09-16 21:40:19.347834 | instance | 2026-09-16 21:40:19.347887 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ******************************** 2026-09-16 21:40:19.347917 | instance | Wednesday 16 September 2026 21:40:19 +0000 (0:00:00.051) 0:00:47.121 *** 2026-09-16 21:40:24.316855 | instance | changed: [instance] 2026-09-16 21:40:24.316908 | instance | 2026-09-16 21:40:24.316916 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] ********************* 2026-09-16 21:40:24.316922 | instance | Wednesday 16 September 2026 21:40:24 +0000 (0:00:04.969) 0:00:52.090 *** 2026-09-16 21:40:24.947977 | instance | ok: [instance] => (item=chronyd) 2026-09-16 21:40:24.948814 | instance | ok: [instance] => (item=sshd) 2026-09-16 21:40:24.948839 | instance | 2026-09-16 21:40:24.948848 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ****************************** 2026-09-16 21:40:24.948857 | instance | Wednesday 16 September 2026 21:40:24 +0000 (0:00:00.631) 0:00:52.722 *** 2026-09-16 21:40:27.063856 | instance | changed: [instance] 2026-09-16 21:40:27.063971 | instance | 2026-09-16 21:40:27.064319 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ******************** 2026-09-16 21:40:27.064365 | instance | Wednesday 16 September 2026 21:40:27 +0000 (0:00:02.115) 0:00:54.838 *** 2026-09-16 21:40:27.261919 | instance | ok: [instance] 2026-09-16 21:40:27.261979 | instance | 2026-09-16 21:40:27.262237 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] **************** 2026-09-16 21:40:27.262275 | instance | Wednesday 16 September 2026 21:40:27 +0000 (0:00:00.198) 0:00:55.036 *** 2026-09-16 21:40:27.713744 | instance | changed: [instance] 2026-09-16 21:40:27.714001 | instance | 2026-09-16 21:40:27.714275 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] *** 2026-09-16 21:40:27.714547 | instance | Wednesday 16 September 2026 21:40:27 +0000 (0:00:00.450) 0:00:55.486 *** 2026-09-16 21:40:28.073063 | instance | changed: [instance] 2026-09-16 21:40:28.073931 | instance | 2026-09-16 21:40:28.073953 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] ************************* 2026-09-16 21:40:28.073964 | instance | Wednesday 16 September 2026 21:40:28 +0000 (0:00:00.361) 0:00:55.847 *** 2026-09-16 21:40:28.196711 | instance | ok: [instance] 2026-09-16 21:40:28.196934 | instance | 2026-09-16 21:40:28.197222 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] ******************* 2026-09-16 21:40:28.197449 | instance | Wednesday 16 September 2026 21:40:28 +0000 (0:00:00.123) 0:00:55.971 *** 2026-09-16 21:40:28.435936 | instance | ok: [instance] => (item=instance) 2026-09-16 21:40:28.436377 | instance | 2026-09-16 21:40:28.436702 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ******** 2026-09-16 21:40:28.437103 | instance | Wednesday 16 September 2026 21:40:28 +0000 (0:00:00.238) 0:00:56.209 *** 2026-09-16 21:40:28.503529 | instance | ok: [instance] 2026-09-16 21:40:28.503741 | instance | 2026-09-16 21:40:28.504013 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] *********************************** 2026-09-16 21:40:28.504248 | instance | Wednesday 16 September 2026 21:40:28 +0000 (0:00:00.067) 0:00:56.277 *** 2026-09-16 21:40:28.569800 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance 2026-09-16 21:40:28.570106 | instance | 2026-09-16 21:40:28.570436 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] ************* 2026-09-16 21:40:28.570654 | instance | Wednesday 16 September 2026 21:40:28 +0000 (0:00:00.066) 0:00:56.344 *** 2026-09-16 21:40:28.902113 | instance | changed: [instance] 2026-09-16 21:40:28.902194 | instance | 2026-09-16 21:40:28.902295 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ****************** 2026-09-16 21:40:28.902398 | instance | Wednesday 16 September 2026 21:40:28 +0000 (0:00:00.331) 0:00:56.676 *** 2026-09-16 21:40:29.577087 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True}) 2026-09-16 21:40:29.577140 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0}) 2026-09-16 21:40:29.577914 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False}) 2026-09-16 21:40:29.578037 | instance | 2026-09-16 21:40:29.578043 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] ******************************* 2026-09-16 21:40:29.578112 | instance | Wednesday 16 September 2026 21:40:29 +0000 (0:00:00.675) 0:00:57.351 *** 2026-09-16 21:41:51.675910 | instance | ok: [instance] 2026-09-16 21:41:51.675967 | instance | 2026-09-16 21:41:51.675987 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] *************** 2026-09-16 21:41:51.676268 | instance | Wednesday 16 September 2026 21:41:51 +0000 (0:01:22.097) 0:02:19.449 *** 2026-09-16 21:41:51.905835 | instance | changed: [instance] 2026-09-16 21:41:51.905930 | instance | 2026-09-16 21:41:51.906090 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] ********************************** 2026-09-16 21:41:51.906248 | instance | Wednesday 16 September 2026 21:41:51 +0000 (0:00:00.231) 0:02:19.680 *** 2026-09-16 21:41:51.947944 | instance | ok: [instance] 2026-09-16 21:41:51.948019 | instance | 2026-09-16 21:41:51.948167 | instance | TASK [Install Ceph host] ******************************************************* 2026-09-16 21:41:51.948368 | instance | Wednesday 16 September 2026 21:41:51 +0000 (0:00:00.042) 0:02:19.722 *** 2026-09-16 21:41:52.018216 | instance | included: vexxhost.ceph.cephadm_host for instance 2026-09-16 21:41:52.018297 | instance | 2026-09-16 21:41:52.018598 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ****** 2026-09-16 21:41:52.018678 | instance | Wednesday 16 September 2026 21:41:52 +0000 (0:00:00.070) 0:02:19.793 *** 2026-09-16 21:41:53.761773 | instance | ok: [instance] 2026-09-16 21:41:53.761984 | instance | 2026-09-16 21:41:53.762286 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] *** 2026-09-16 21:41:53.762571 | instance | Wednesday 16 September 2026 21:41:53 +0000 (0:00:01.742) 0:02:21.536 *** 2026-09-16 21:41:53.828450 | instance | ok: [instance] => (item=instance) 2026-09-16 21:41:53.828519 | instance | 2026-09-16 21:41:53.828641 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] *********** 2026-09-16 21:41:53.828748 | instance | Wednesday 16 September 2026 21:41:53 +0000 (0:00:00.067) 0:02:21.603 *** 2026-09-16 21:41:54.216045 | instance | ok: [instance] 2026-09-16 21:41:54.216538 | instance | 2026-09-16 21:41:54.216899 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] *********************** 2026-09-16 21:41:54.216954 | instance | Wednesday 16 September 2026 21:41:54 +0000 (0:00:00.386) 0:02:21.990 *** 2026-09-16 21:41:56.625081 | instance | ok: [instance] 2026-09-16 21:41:56.625846 | instance | 2026-09-16 21:41:56.625872 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ****************** 2026-09-16 21:41:56.625883 | instance | Wednesday 16 September 2026 21:41:56 +0000 (0:00:02.408) 0:02:24.399 *** 2026-09-16 21:42:39.014539 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster) 2026-09-16 21:42:39.015405 | instance | fatal: [instance]: FAILED! => {"changed": false, "module_stderr": "Traceback (most recent call last):\n File \"\", line 107, in \n File \"\", line 99, in _ansiballz_main\n File \"\", line 47, in invoke_module\n File \"/usr/lib/python3.10/runpy.py\", line 224, in run_module\n return _run_module_code(code, init_globals, run_name, mod_spec)\n File \"/usr/lib/python3.10/runpy.py\", line 96, in _run_module_code\n _run_code(code, mod_globals, init_globals,\n File \"/usr/lib/python3.10/runpy.py\", line 86, in _run_code\n exec(code, run_globals)\n File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_k6c68wmj/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 202, in \n File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_k6c68wmj/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 198, in main\n File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_k6c68wmj/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 177, in run_module\n File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_k6c68wmj/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 120, in apply_spec\nRuntimeError: Inferring config /var/lib/ceph/4837cbf8-4f90-4300-b3f6-726c9b9f89b4/mon.instance/config\n2026-09-16T21:42:38.833+0000 7f776c326640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgD0DKtqV5P/OCAApsxNfr9m6EOESD5EtJ7ytgvHkZx6XizIFx0Is0ON9rs=: Malformed input [buffer:3]\n2026-09-16T21:42:38.833+0000 7f776c326640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-16T21:42:38.833+0000 7f776c326640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgD0DKtqV5P/OCAApsxNfr9m6EOESD5EtJ7ytgvHkZx6XizIFx0Is0ON9rs=: Malformed input [buffer:3]\n2026-09-16T21:42:38.833+0000 7f776c326640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-16T21:42:38.833+0000 7f776c326640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgD0DKtqV5P/OCAApsxNfr9m6EOESD5EtJ7ytgvHkZx6XizIFx0Is0ON9rs=: Malformed input [buffer:3]\n2026-09-16T21:42:38.833+0000 7f776c326640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-16T21:42:38.833+0000 7f776c326640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1} 2026-09-16 21:42:39.018226 | instance | 2026-09-16 21:42:39.018541 | instance | PLAY RECAP ********************************************************************* 2026-09-16 21:42:39.021053 | instance | instance : ok=55 changed=26 unreachable=0 failed=1 skipped=6 rescued=0 ignored=0 2026-09-16 21:42:39.021078 | instance | 2026-09-16 21:42:39.021085 | instance | Wednesday 16 September 2026 21:42:39 +0000 (0:00:42.394) 0:03:06.793 *** 2026-09-16 21:42:39.021092 | instance | =============================================================================== 2026-09-16 21:42:39.021098 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 82.10s 2026-09-16 21:42:39.021103 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 42.39s 2026-09-16 21:42:39.021109 | instance | vexxhost.containers.download_artifact : Download item ------------------ 15.22s 2026-09-16 21:42:39.021115 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.95s 2026-09-16 21:42:39.021120 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 5.40s 2026-09-16 21:42:39.024905 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 4.97s 2026-09-16 21:42:39.024950 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.95s 2026-09-16 21:42:39.024977 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.41s 2026-09-16 21:42:39.025021 | instance | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 2.12s 2026-09-16 21:42:39.025033 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.74s 2026-09-16 21:42:39.025042 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.67s 2026-09-16 21:42:39.025051 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.29s 2026-09-16 21:42:39.025060 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.22s 2026-09-16 21:42:39.025068 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.04s 2026-09-16 21:42:39.025077 | instance | Gathering Facts --------------------------------------------------------- 1.02s 2026-09-16 21:42:39.025086 | instance | Gathering Facts --------------------------------------------------------- 0.92s 2026-09-16 21:42:39.025094 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.90s 2026-09-16 21:42:39.025103 | instance | Gathering Facts --------------------------------------------------------- 0.85s 2026-09-16 21:42:39.025112 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.68s 2026-09-16 21:42:39.025120 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.68s 2026-09-16 21:42:39.131261 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-16 21:42:39.131323 | instance | ERROR [csi > converge] Executed: Failed 2026-09-16 21:42:39.131397 | instance | ERROR Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-16 21:42:39.388891 | instance | ERROR 2026-09-16 21:42:39.389145 | instance | { 2026-09-16 21:42:39.389185 | instance | "delta": "0:04:00.778587", 2026-09-16 21:42:39.389214 | instance | "end": "2026-09-16 21:42:39.193920", 2026-09-16 21:42:39.389239 | instance | "msg": "non-zero return code", 2026-09-16 21:42:39.389261 | instance | "rc": 2, 2026-09-16 21:42:39.389283 | instance | "start": "2026-09-16 21:38:38.415333" 2026-09-16 21:42:39.389305 | instance | } failure 2026-09-16 21:42:39.398736 | 2026-09-16 21:42:39.399151 | PLAY RECAP 2026-09-16 21:42:39.399242 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-16 21:42:39.399284 | 2026-09-16 21:42:39.589788 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-16 21:42:39.595698 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-16 21:42:40.227177 | 2026-09-16 21:42:40.227670 | PLAY [all] 2026-09-16 21:42:40.242225 | 2026-09-16 21:42:40.242317 | TASK [gather-host-logs : creating directory for system status] 2026-09-16 21:42:40.640171 | instance | changed 2026-09-16 21:42:40.650623 | 2026-09-16 21:42:40.650789 | TASK [gather-host-logs : Get logs for each host] 2026-09-16 21:42:41.074701 | instance | + systemd-cgls --full --all --no-pager 2026-09-16 21:42:41.085136 | instance | + ip addr 2026-09-16 21:42:41.086780 | instance | + ip route 2026-09-16 21:42:41.090167 | instance | + lsblk 2026-09-16 21:42:41.095978 | instance | + mount 2026-09-16 21:42:41.098683 | instance | + docker images 2026-09-16 21:42:41.121793 | instance | + brctl show 2026-09-16 21:42:41.122322 | instance | /bin/bash: line 8: brctl: command not found 2026-09-16 21:42:41.122543 | instance | + ip neigh 2026-09-16 21:42:41.124892 | instance | + bridge fdb show 2026-09-16 21:42:41.130790 | instance | + ps aux --sort=-%mem 2026-09-16 21:42:41.160849 | instance | + dpkg -l 2026-09-16 21:42:41.169259 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-09-16 21:42:41.169766 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-09-16 21:42:41.188808 | instance | + '[' '!' -z '' ']' 2026-09-16 21:42:41.258805 | instance | ok: Runtime: 0:00:00.118842 2026-09-16 21:42:41.273280 | 2026-09-16 21:42:41.273503 | TASK [gather-host-logs : Downloads logs to executor] 2026-09-16 21:42:41.948284 | instance | changed: 2026-09-16 21:42:41.948530 | instance | created directory /var/lib/zuul/builds/8020aa0f31624db886d2f6521803aa15/work/logs/instance 2026-09-16 21:42:41.948601 | instance | cd+++++++++ system/ 2026-09-16 21:42:41.948642 | instance | >f+++++++++ system/brctl-show.txt 2026-09-16 21:42:41.948672 | instance | >f+++++++++ system/bridge-fdb.txt 2026-09-16 21:42:41.948699 | instance | >f+++++++++ system/docker-images.txt 2026-09-16 21:42:41.948732 | instance | >f+++++++++ system/ip-addr.txt 2026-09-16 21:42:41.948776 | instance | >f+++++++++ system/ip-neigh.txt 2026-09-16 21:42:41.948836 | instance | >f+++++++++ system/ip-route.txt 2026-09-16 21:42:41.948872 | instance | >f+++++++++ system/lsblk.txt 2026-09-16 21:42:41.948899 | instance | >f+++++++++ system/mount.txt 2026-09-16 21:42:41.948927 | instance | >f+++++++++ system/packages.txt 2026-09-16 21:42:41.948952 | instance | >f+++++++++ system/ps.txt 2026-09-16 21:42:41.948979 | instance | >f+++++++++ system/systemd-cgls.txt 2026-09-16 21:42:41.961543 | 2026-09-16 21:42:41.961725 | LOOP [helm-release-status : creating directory for helm release status] 2026-09-16 21:42:42.198634 | instance | changed: "values" 2026-09-16 21:42:42.371706 | instance | changed: "releases" 2026-09-16 21:42:42.382765 | 2026-09-16 21:42:42.382959 | TASK [helm-release-status : Gather get release status for helm charts] 2026-09-16 21:42:42.639345 | instance | /bin/bash: line 3: kubectl: command not found 2026-09-16 21:42:42.928058 | instance | ok: Runtime: 0:00:00.006596 2026-09-16 21:42:42.937492 | 2026-09-16 21:42:42.937773 | TASK [helm-release-status : Downloads logs to executor] 2026-09-16 21:42:43.465695 | instance | changed: 2026-09-16 21:42:43.466118 | instance | cd+++++++++ helm/ 2026-09-16 21:42:43.466176 | instance | cd+++++++++ helm/releases/ 2026-09-16 21:42:43.466209 | instance | cd+++++++++ helm/values/ 2026-09-16 21:42:43.477899 | 2026-09-16 21:42:43.478004 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-09-16 21:42:43.680060 | instance | changed 2026-09-16 21:42:43.685987 | 2026-09-16 21:42:43.686118 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-09-16 21:42:43.977268 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-16 21:42:43.977372 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-16 21:42:43.982766 | instance | environment: line 1: kubectl: command not found 2026-09-16 21:42:43.983630 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-16 21:42:43.985959 | instance | environment: line 1: kubectl: command not found 2026-09-16 21:42:43.987425 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-16 21:42:43.988507 | instance | environment: line 1: kubectl: command not found 2026-09-16 21:42:43.989651 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-16 21:42:43.991494 | instance | environment: line 1: kubectl: command not found 2026-09-16 21:42:43.993079 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-16 21:42:43.993470 | instance | environment: line 1: kubectl: command not found 2026-09-16 21:42:43.994905 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-16 21:42:44.221458 | instance | ok: Runtime: 0:00:00.026609 2026-09-16 21:42:44.232156 | 2026-09-16 21:42:44.232287 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-09-16 21:42:44.448898 | instance | changed 2026-09-16 21:42:44.458365 | 2026-09-16 21:42:44.458534 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-09-16 21:42:44.711046 | instance | environment: line 5: kubectl: command not found 2026-09-16 21:42:44.711508 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-16 21:42:44.712374 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-16 21:42:44.712568 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-16 21:42:45.004672 | instance | ok: Runtime: 0:00:00.010073 2026-09-16 21:42:45.011868 | 2026-09-16 21:42:45.012014 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-09-16 21:42:45.589209 | instance | changed: 2026-09-16 21:42:45.589384 | instance | cd+++++++++ objects/ 2026-09-16 21:42:45.589417 | instance | cd+++++++++ objects/cluster/ 2026-09-16 21:42:45.589442 | instance | cd+++++++++ objects/namespaced/ 2026-09-16 21:42:45.598138 | 2026-09-16 21:42:45.598230 | TASK [gather-pod-logs : creating directory for pod logs] 2026-09-16 21:42:45.810953 | instance | changed 2026-09-16 21:42:45.820044 | 2026-09-16 21:42:45.820224 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-09-16 21:42:46.056664 | instance | changed 2026-09-16 21:42:46.061249 | 2026-09-16 21:42:46.061349 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-09-16 21:42:46.304080 | instance | environment: line 3: kubectl: command not found 2026-09-16 21:42:46.596076 | instance | ok: Runtime: 0:00:00.008989 2026-09-16 21:42:46.602231 | 2026-09-16 21:42:46.602316 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-09-16 21:42:47.123562 | instance | changed: 2026-09-16 21:42:47.123846 | instance | cd+++++++++ pod-logs/ 2026-09-16 21:42:47.123901 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-09-16 21:42:47.145672 | 2026-09-16 21:42:47.145768 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-09-16 21:42:47.374759 | instance | changed 2026-09-16 21:42:47.394828 | 2026-09-16 21:42:47.395189 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-09-16 21:42:47.659157 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-16 21:42:47.941308 | instance | ok: Runtime: 0:00:00.055576 2026-09-16 21:42:47.945779 | 2026-09-16 21:42:47.945847 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-09-16 21:42:48.192849 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-16 21:42:48.218120 | instance | ceph-mgr endpoints: 2026-09-16 21:42:48.484225 | instance | ok: Runtime: 0:00:00.030630 2026-09-16 21:42:48.495605 | 2026-09-16 21:42:48.495883 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-09-16 21:42:48.762616 | instance | /bin/bash: line 4: kubectl: command not found 2026-09-16 21:42:49.046190 | instance | ok: Runtime: 0:00:00.054429 2026-09-16 21:42:49.061898 | 2026-09-16 21:42:49.062126 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-09-16 21:42:49.661172 | instance | changed: cd+++++++++ prometheus/ 2026-09-16 21:42:49.671584 | 2026-09-16 21:42:49.671655 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-09-16 21:42:49.921902 | instance | changed 2026-09-16 21:42:49.931667 | 2026-09-16 21:42:49.931802 | TASK [gather-selenium-data : Get selenium data] 2026-09-16 21:42:50.244382 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-09-16 21:42:50.246618 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-09-16 21:42:50.476619 | instance | ERROR 2026-09-16 21:42:50.476760 | instance | { 2026-09-16 21:42:50.476798 | instance | "delta": "0:00:00.009431", 2026-09-16 21:42:50.476824 | instance | "end": "2026-09-16 21:42:50.247104", 2026-09-16 21:42:50.476848 | instance | "msg": "non-zero return code", 2026-09-16 21:42:50.476871 | instance | "rc": 1, 2026-09-16 21:42:50.476894 | instance | "start": "2026-09-16 21:42:50.237673" 2026-09-16 21:42:50.476916 | instance | } 2026-09-16 21:42:50.476945 | instance | ERROR: Ignoring Errors 2026-09-16 21:42:50.482815 | 2026-09-16 21:42:50.482900 | TASK [gather-selenium-data : Downloads logs to executor] 2026-09-16 21:42:51.037377 | instance | changed: cd+++++++++ selenium/ 2026-09-16 21:42:51.049364 | 2026-09-16 21:42:51.049541 | PLAY RECAP 2026-09-16 21:42:51.049645 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-09-16 21:42:51.049688 | 2026-09-16 21:42:51.214955 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-16 21:42:51.217908 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-16 21:42:51.834571 | 2026-09-16 21:42:51.834699 | PLAY [all] 2026-09-16 21:42:51.846902 | 2026-09-16 21:42:51.847017 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-16 21:42:51.911216 | instance | skipping: Conditional result was False 2026-09-16 21:42:51.918287 | 2026-09-16 21:42:51.918404 | TASK [fetch-output : Set log path for single node] 2026-09-16 21:42:51.961249 | instance | ok 2026-09-16 21:42:51.972437 | 2026-09-16 21:42:51.972661 | LOOP [fetch-output : Ensure local output dirs] 2026-09-16 21:42:52.385169 | instance -> localhost | ok: "/var/lib/zuul/builds/8020aa0f31624db886d2f6521803aa15/work/logs" 2026-09-16 21:42:52.593335 | instance -> localhost | changed: "/var/lib/zuul/builds/8020aa0f31624db886d2f6521803aa15/work/artifacts" 2026-09-16 21:42:52.793013 | instance -> localhost | changed: "/var/lib/zuul/builds/8020aa0f31624db886d2f6521803aa15/work/docs" 2026-09-16 21:42:52.812986 | 2026-09-16 21:42:52.813240 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-16 21:42:53.527909 | instance | changed: .d..t...... ./ 2026-09-16 21:42:53.528255 | instance | changed: All items complete 2026-09-16 21:42:53.528311 | 2026-09-16 21:42:54.021371 | instance | changed: .d..t...... ./ 2026-09-16 21:42:54.524982 | instance | changed: .d..t...... ./ 2026-09-16 21:42:54.553273 | 2026-09-16 21:42:54.553441 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-16 21:42:54.982882 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.006780 2026-09-16 21:42:55.252784 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007620 2026-09-16 21:42:55.268134 | 2026-09-16 21:42:55.268288 | PLAY [all] 2026-09-16 21:42:55.280681 | 2026-09-16 21:42:55.280920 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-16 21:42:55.753304 | instance | changed 2026-09-16 21:42:55.765698 | 2026-09-16 21:42:55.765859 | PLAY RECAP 2026-09-16 21:42:55.765946 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-16 21:42:55.765987 | 2026-09-16 21:42:55.995018 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-16 21:42:55.999419 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-16 21:42:56.588875 | 2026-09-16 21:42:56.589049 | PLAY [localhost] 2026-09-16 21:42:56.598794 | 2026-09-16 21:42:56.598910 | TASK [Generate Zuul manifest] 2026-09-16 21:42:56.617104 | localhost | ok 2026-09-16 21:42:56.634712 | 2026-09-16 21:42:56.634887 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-16 21:42:57.072233 | localhost | changed 2026-09-16 21:42:57.092928 | 2026-09-16 21:42:57.093166 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-16 21:42:57.136733 | localhost | ok 2026-09-16 21:42:57.151277 | 2026-09-16 21:42:57.151468 | TASK [Upload logs] 2026-09-16 21:42:57.176356 | localhost | ok 2026-09-16 21:42:57.253036 | 2026-09-16 21:42:57.253195 | TASK [Set zuul-log-path fact] 2026-09-16 21:42:57.271342 | localhost | ok 2026-09-16 21:42:57.282685 | 2026-09-16 21:42:57.282823 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-16 21:42:57.322441 | localhost | ok 2026-09-16 21:42:57.334815 | 2026-09-16 21:42:57.335021 | TASK [upload-logs : Create log directories] 2026-09-16 21:42:57.752078 | localhost | changed 2026-09-16 21:42:57.758340 | 2026-09-16 21:42:57.758446 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-16 21:42:58.228558 | localhost -> localhost | ok: Runtime: 0:00:00.006293 2026-09-16 21:42:58.237458 | 2026-09-16 21:42:58.237584 | TASK [upload-logs : Upload logs to log server] 2026-09-16 21:42:58.773813 | localhost | Output suppressed because no_log was given 2026-09-16 21:42:58.827990 | 2026-09-16 21:42:58.828155 | LOOP [upload-logs : Compress console log and json output] 2026-09-16 21:42:58.884442 | localhost | skipping: Conditional result was False 2026-09-16 21:42:58.891645 | localhost | skipping: Conditional result was False 2026-09-16 21:42:58.908678 | 2026-09-16 21:42:58.908955 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-16 21:42:58.953241 | localhost | skipping: Conditional result was False 2026-09-16 21:42:58.953849 | 2026-09-16 21:42:58.957011 | localhost | skipping: Conditional result was False 2026-09-16 21:42:58.974410 | 2026-09-16 21:42:58.974619 | LOOP [upload-logs : Upload console log and json output]