2026-09-01 03:55:14.923998 | Job console starting 2026-09-01 03:55:14.935548 | Updating git repos 2026-09-01 03:55:20.663576 | Cloning repos into workspace 2026-09-01 03:55:39.322611 | Restoring repo states 2026-09-01 03:55:55.110496 | Merging changes 2026-09-01 03:55:56.443976 | Checking out repos 2026-09-01 03:55:57.947777 | Preparing playbooks 2026-09-01 03:56:00.078994 | Running Ansible setup 2026-09-01 03:56:03.866412 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-01 03:56:04.493275 | 2026-09-01 03:56:04.493437 | PLAY [localhost] 2026-09-01 03:56:04.501435 | 2026-09-01 03:56:04.501510 | TASK [Gathering Facts] 2026-09-01 03:56:05.357869 | localhost | ok 2026-09-01 03:56:05.369500 | 2026-09-01 03:56:05.369570 | TASK [Setup log path fact] 2026-09-01 03:56:05.388397 | localhost | ok 2026-09-01 03:56:05.402026 | 2026-09-01 03:56:05.402091 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-01 03:56:05.431842 | localhost | ok 2026-09-01 03:56:05.439696 | 2026-09-01 03:56:05.439758 | TASK [emit-job-header : Print job information] 2026-09-01 03:56:05.482323 | # Job Information 2026-09-01 03:56:05.483260 | Ansible Version: 2.16.18 2026-09-01 03:56:05.483340 | Job: atmosphere-molecule-csi-rbd 2026-09-01 03:56:05.483390 | Pipeline: check 2026-09-01 03:56:05.483434 | Executor: 2d72f0692154 2026-09-01 03:56:05.483480 | Triggered by: https://github.com/vexxhost/atmosphere/pull/2729 2026-09-01 03:56:05.483529 | Event ID: 57282410-a5b8-11f1-98f3-9b49fbb4a364 2026-09-01 03:56:05.489503 | 2026-09-01 03:56:05.489606 | LOOP [emit-job-header : Print node information] 2026-09-01 03:56:05.612154 | localhost | ok: 2026-09-01 03:56:05.612512 | localhost | # Node Information 2026-09-01 03:56:05.612586 | localhost | Inventory Hostname: instance 2026-09-01 03:56:05.612626 | localhost | Hostname: np0000191665 2026-09-01 03:56:05.612656 | localhost | Username: zuul 2026-09-01 03:56:05.612689 | localhost | Distro: Ubuntu 22.04 2026-09-01 03:56:05.612716 | localhost | Provider: yul1 2026-09-01 03:56:05.612744 | localhost | Region: ca-ymq-1 2026-09-01 03:56:05.612771 | localhost | Label: ubuntu-jammy 2026-09-01 03:56:05.612797 | localhost | Product Name: OpenStack Nova 2026-09-01 03:56:05.612823 | localhost | Interface IP: 162.253.55.119 2026-09-01 03:56:05.631351 | 2026-09-01 03:56:05.631513 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-01 03:56:06.011842 | localhost -> localhost | changed 2026-09-01 03:56:06.021204 | 2026-09-01 03:56:06.021294 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-01 03:56:06.839347 | localhost -> localhost | changed 2026-09-01 03:56:06.850340 | 2026-09-01 03:56:06.850400 | PLAY [all] 2026-09-01 03:56:06.859402 | 2026-09-01 03:56:06.859468 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-01 03:56:07.088269 | instance -> localhost | ok 2026-09-01 03:56:07.099931 | 2026-09-01 03:56:07.100066 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-01 03:56:07.134009 | instance | ok 2026-09-01 03:56:07.148296 | instance | included: /var/lib/zuul/builds/b157548a00eb44e0a74bb1e7c2598c83/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-01 03:56:07.153793 | 2026-09-01 03:56:07.153851 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-01 03:56:09.366511 | instance -> localhost | Generating public/private rsa key pair. 2026-09-01 03:56:09.366732 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/b157548a00eb44e0a74bb1e7c2598c83/work/b157548a00eb44e0a74bb1e7c2598c83_id_rsa 2026-09-01 03:56:09.366766 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/b157548a00eb44e0a74bb1e7c2598c83/work/b157548a00eb44e0a74bb1e7c2598c83_id_rsa.pub 2026-09-01 03:56:09.366793 | instance -> localhost | The key fingerprint is: 2026-09-01 03:56:09.366818 | instance -> localhost | SHA256:/qGUMszWX8de58mKMkXqhhfOkCL3EKDFnkQQZ5EurKI zuul-build-sshkey 2026-09-01 03:56:09.366854 | instance -> localhost | The key's randomart image is: 2026-09-01 03:56:09.366875 | instance -> localhost | +---[RSA 3072]----+ 2026-09-01 03:56:09.366899 | instance -> localhost | | o==o | 2026-09-01 03:56:09.366929 | instance -> localhost | | o* | 2026-09-01 03:56:09.366951 | instance -> localhost | |. * o | 2026-09-01 03:56:09.366972 | instance -> localhost | | + + . . | 2026-09-01 03:56:09.366993 | instance -> localhost | |. . . S o | 2026-09-01 03:56:09.367019 | instance -> localhost | |o . * = + . . | 2026-09-01 03:56:09.367047 | instance -> localhost | |o o X @ + . o o| 2026-09-01 03:56:09.367070 | instance -> localhost | |E . * % o.o.oo| 2026-09-01 03:56:09.367100 | instance -> localhost | | + =. .oo.| 2026-09-01 03:56:09.367121 | instance -> localhost | +----[SHA256]-----+ 2026-09-01 03:56:09.367185 | instance -> localhost | ok: Runtime: 0:00:01.801068 2026-09-01 03:56:09.374748 | 2026-09-01 03:56:09.374908 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-01 03:56:09.410681 | instance | ok 2026-09-01 03:56:09.422627 | instance | included: /var/lib/zuul/builds/b157548a00eb44e0a74bb1e7c2598c83/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-01 03:56:09.430991 | 2026-09-01 03:56:09.431137 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-01 03:56:09.456722 | instance | skipping: Conditional result was False 2026-09-01 03:56:09.468754 | 2026-09-01 03:56:09.469031 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-01 03:56:09.919619 | instance | changed 2026-09-01 03:56:09.927084 | 2026-09-01 03:56:09.927144 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-01 03:56:10.133109 | instance | ok 2026-09-01 03:56:10.138226 | 2026-09-01 03:56:10.138286 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-01 03:56:10.665240 | instance | changed 2026-09-01 03:56:10.671635 | 2026-09-01 03:56:10.671701 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-01 03:56:11.164628 | instance | changed 2026-09-01 03:56:11.172507 | 2026-09-01 03:56:11.172571 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-01 03:56:11.197546 | instance | skipping: Conditional result was False 2026-09-01 03:56:11.206868 | 2026-09-01 03:56:11.206962 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-01 03:56:11.586812 | instance -> localhost | changed 2026-09-01 03:56:11.600666 | 2026-09-01 03:56:11.600754 | TASK [add-build-sshkey : Add back temp key] 2026-09-01 03:56:11.898395 | instance -> localhost | Identity added: /var/lib/zuul/builds/b157548a00eb44e0a74bb1e7c2598c83/work/b157548a00eb44e0a74bb1e7c2598c83_id_rsa (zuul-build-sshkey) 2026-09-01 03:56:11.898678 | instance -> localhost | ok: Runtime: 0:00:00.015077 2026-09-01 03:56:11.903434 | 2026-09-01 03:56:11.903496 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-01 03:56:12.254857 | instance | ok 2026-09-01 03:56:12.261488 | 2026-09-01 03:56:12.261584 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-01 03:56:12.286912 | instance | skipping: Conditional result was False 2026-09-01 03:56:12.300198 | 2026-09-01 03:56:12.300259 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-01 03:56:12.658803 | instance | ok 2026-09-01 03:56:12.664327 | 2026-09-01 03:56:12.664387 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-01 03:56:16.212600 | instance | Output suppressed because no_log was given 2026-09-01 03:56:16.223460 | 2026-09-01 03:56:16.223526 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-01 03:56:16.436162 | instance | ok: "logs" 2026-09-01 03:56:16.436500 | instance | ok: All items complete 2026-09-01 03:56:16.436529 | 2026-09-01 03:56:16.610742 | instance | ok: "artifacts" 2026-09-01 03:56:16.780865 | instance | ok: "docs" 2026-09-01 03:56:16.802451 | 2026-09-01 03:56:16.802640 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-01 03:56:17.003055 | instance | changed: "logs" 2026-09-01 03:56:17.183157 | instance | changed: "artifacts" 2026-09-01 03:56:17.333387 | instance | changed: "docs" 2026-09-01 03:56:17.351719 | 2026-09-01 03:56:17.351781 | PLAY RECAP 2026-09-01 03:56:17.351829 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-01 03:56:17.351857 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-01 03:56:17.351878 | 2026-09-01 03:56:17.480515 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-01 03:56:17.484900 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-01 03:56:18.076899 | 2026-09-01 03:56:18.077119 | PLAY [all] 2026-09-01 03:56:18.087522 | 2026-09-01 03:56:18.087598 | TASK [setup-uv : Extract archive] 2026-09-01 03:56:20.467866 | instance | changed 2026-09-01 03:56:20.473694 | 2026-09-01 03:56:20.473775 | TASK [setup-uv : Print version] 2026-09-01 03:56:20.854350 | instance | uv 0.8.13 2026-09-01 03:56:21.011335 | instance | ok: Runtime: 0:00:00.010728 2026-09-01 03:56:21.022878 | 2026-09-01 03:56:21.022983 | TASK [Install Ansible collection siblings] 2026-09-01 03:56:30.296284 | instance | changed: 2026-09-01 03:56:30.296463 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-09-01 03:56:30.296493 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-09-01 03:56:30.296515 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-09-01 03:56:30.296536 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto 2026-09-01 03:56:30.296556 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general 2026-09-01 03:56:30.296579 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql 2026-09-01 03:56:30.296600 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-09-01 03:56:30.296622 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-09-01 03:56:30.296642 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-09-01 03:56:30.296662 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-09-01 03:56:30.296682 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-09-01 03:56:30.296701 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-09-01 03:56:30.296720 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0 2026-09-01 03:56:30.296738 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0 2026-09-01 03:56:30.296757 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0 2026-09-01 03:56:30.296777 | instance | Resolved community.crypto to 2.2.3 for 2.2.3 2026-09-01 03:56:30.296795 | instance | Resolved community.general to 7.3.0 for 7.3.0 2026-09-01 03:56:30.296814 | instance | Resolved community.mysql to 3.6.0 for 3.6.0 2026-09-01 03:56:30.296832 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0 2026-09-01 03:56:30.296851 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0 2026-09-01 03:56:30.296869 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0 2026-09-01 03:56:30.296887 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0 2026-09-01 03:56:30.296907 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-09-01 03:56:30.296940 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0 2026-09-01 03:56:30.296984 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0 2026-09-01 03:56:30.297004 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-01 03:56:30.297023 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-01 03:56:30.297041 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-09-01 03:56:30.297059 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0 2026-09-01 03:56:30.297077 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3 2026-09-01 03:56:30.297095 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-01 03:56:30.297114 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-01 03:56:30.297132 | instance | Checked out ansible.netcommon to 1.2.0 2026-09-01 03:56:30.297151 | 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-01 03:56:30.297174 | instance | Starting galaxy collection install process 2026-09-01 03:56:30.297192 | instance | Process install dependency map 2026-09-01 03:56:30.297210 | instance | Starting collection install process 2026-09-01 03:56:30.297228 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-09-01 03:56:30.297245 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon 2026-09-01 03:56:30.297264 | instance | ansible.netcommon:* was installed successfully 2026-09-01 03:56:30.297282 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-09-01 03:56:30.297299 | instance | Creating virtual environment at: .venv 2026-09-01 03:56:30.297317 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-01 03:56:30.297335 | instance | Downloading netaddr (2.2MiB) 2026-09-01 03:56:30.297353 | instance | Downloading pydantic-core (2.0MiB) 2026-09-01 03:56:30.297375 | instance | Downloading kubernetes (1.9MiB) 2026-09-01 03:56:30.297393 | instance | Downloading rjsonnet (1.2MiB) 2026-09-01 03:56:30.297411 | instance | Downloading setuptools (1.1MiB) 2026-09-01 03:56:30.297429 | instance | Downloading ansible-core (2.1MiB) 2026-09-01 03:56:30.297448 | instance | Downloading cryptography (4.2MiB) 2026-09-01 03:56:30.297466 | instance | Downloading pygments (1.2MiB) 2026-09-01 03:56:30.297484 | instance | Downloading openstacksdk (1.7MiB) 2026-09-01 03:56:30.297502 | instance | Building pyperclip==1.9.0 2026-09-01 03:56:30.297521 | instance | Downloading rjsonnet 2026-09-01 03:56:30.297540 | instance | Downloading pydantic-core 2026-09-01 03:56:30.297558 | instance | Downloading netaddr 2026-09-01 03:56:30.297575 | instance | Downloading pygments 2026-09-01 03:56:30.297593 | instance | Downloading cryptography 2026-09-01 03:56:30.297611 | instance | Downloading setuptools 2026-09-01 03:56:30.297628 | instance | Downloading kubernetes 2026-09-01 03:56:30.297646 | instance | Downloading ansible-core 2026-09-01 03:56:30.297664 | instance | Downloading openstacksdk 2026-09-01 03:56:30.297681 | instance | Built pyperclip==1.9.0 2026-09-01 03:56:30.297699 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-01 03:56:30.297718 | instance | Installed 83 packages in 51ms 2026-09-01 03:56:30.297735 | instance | Checked out ansible.posix to 1.6.0 2026-09-01 03:56:30.297753 | 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-01 03:56:30.297771 | instance | Starting galaxy collection install process 2026-09-01 03:56:30.297789 | instance | Process install dependency map 2026-09-01 03:56:30.297812 | instance | Starting collection install process 2026-09-01 03:56:30.297831 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-09-01 03:56:30.297849 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix 2026-09-01 03:56:30.297867 | instance | ansible.posix:1.6.0 was installed successfully 2026-09-01 03:56:30.297885 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:56:30.297903 | instance | 2.17.12 2026-09-01 03:56:30.297921 | instance | Checked out ansible.utils to v6.1.0 2026-09-01 03:56:30.297938 | 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-01 03:56:30.297956 | instance | Starting galaxy collection install process 2026-09-01 03:56:30.297974 | instance | Process install dependency map 2026-09-01 03:56:30.297992 | instance | Starting collection install process 2026-09-01 03:56:30.298010 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-09-01 03:56:30.298028 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils 2026-09-01 03:56:30.298047 | instance | ansible.utils:6.1.0 was installed successfully 2026-09-01 03:56:30.298065 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:56:30.298083 | instance | 2.17.12 2026-09-01 03:56:30.298100 | instance | Checked out atmosphere.common to v0.7.0 2026-09-01 03:56:30.298118 | 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-01 03:56:30.298136 | instance | Starting galaxy collection install process 2026-09-01 03:56:30.298154 | instance | Process install dependency map 2026-09-01 03:56:30.298171 | instance | Starting collection install process 2026-09-01 03:56:30.298192 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common' 2026-09-01 03:56:30.298214 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common 2026-09-01 03:56:30.298237 | instance | atmosphere.common:0.7.0 was installed successfully 2026-09-01 03:56:30.298259 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:56:30.298281 | instance | 2.17.12 2026-09-01 03:56:30.298303 | instance | Checked out community.crypto to 2.2.3 2026-09-01 03:56:30.298325 | 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-01 03:56:30.298346 | instance | Starting galaxy collection install process 2026-09-01 03:56:30.298364 | instance | Process install dependency map 2026-09-01 03:56:30.298381 | instance | Starting collection install process 2026-09-01 03:56:30.298398 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-09-01 03:56:30.298416 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto 2026-09-01 03:56:30.298433 | instance | community.crypto:2.2.3 was installed successfully 2026-09-01 03:56:30.298450 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:56:30.298468 | instance | 2.17.12 2026-09-01 03:56:30.298485 | instance | Checked out community.general to 7.3.0 2026-09-01 03:56:30.298503 | 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-01 03:56:30.298525 | instance | Starting galaxy collection install process 2026-09-01 03:56:30.298543 | instance | Process install dependency map 2026-09-01 03:56:30.298561 | instance | Starting collection install process 2026-09-01 03:56:30.298578 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-09-01 03:56:30.298595 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general 2026-09-01 03:56:30.298612 | instance | community.general:7.3.0 was installed successfully 2026-09-01 03:56:30.298643 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:56:30.298662 | instance | 2.17.12 2026-09-01 03:56:30.298680 | instance | Checked out community.mysql to 3.6.0 2026-09-01 03:56:30.298699 | 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-01 03:56:30.298718 | instance | Starting galaxy collection install process 2026-09-01 03:56:30.298736 | instance | Process install dependency map 2026-09-01 03:56:30.298754 | instance | Starting collection install process 2026-09-01 03:56:30.298772 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-09-01 03:56:30.298790 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql 2026-09-01 03:56:30.298808 | instance | community.mysql:3.6.0 was installed successfully 2026-09-01 03:56:30.298826 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:56:30.298844 | instance | 2.17.12 2026-09-01 03:56:30.298862 | instance | Checked out kubernetes.core to 2.4.0 2026-09-01 03:56:30.298880 | 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-01 03:56:30.298898 | instance | Starting galaxy collection install process 2026-09-01 03:56:30.298916 | instance | Process install dependency map 2026-09-01 03:56:30.298934 | instance | Starting collection install process 2026-09-01 03:56:30.298952 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-09-01 03:56:30.298970 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core 2026-09-01 03:56:30.298988 | instance | kubernetes.core:2.4.0 was installed successfully 2026-09-01 03:56:30.299006 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:56:30.299024 | instance | 2.17.12 2026-09-01 03:56:30.299041 | instance | Checked out openstack.cloud to 2.6.0 2026-09-01 03:56:30.299058 | 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-01 03:56:30.299076 | instance | Starting galaxy collection install process 2026-09-01 03:56:30.299093 | instance | Process install dependency map 2026-09-01 03:56:30.299110 | instance | Starting collection install process 2026-09-01 03:56:30.299127 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-09-01 03:56:30.299145 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud 2026-09-01 03:56:30.299162 | instance | openstack.cloud:2.6.0 was installed successfully 2026-09-01 03:56:30.299179 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:56:30.299196 | instance | 2.17.12 2026-09-01 03:56:30.299218 | instance | Checked out vexxhost.ceph to v4.2.0 2026-09-01 03:56:30.299237 | 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-01 03:56:30.299254 | instance | Starting galaxy collection install process 2026-09-01 03:56:30.299272 | instance | Process install dependency map 2026-09-01 03:56:30.299290 | instance | Starting collection install process 2026-09-01 03:56:30.299307 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph' 2026-09-01 03:56:30.299324 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph 2026-09-01 03:56:30.299341 | instance | vexxhost.ceph:4.2.0 was installed successfully 2026-09-01 03:56:30.299358 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:56:30.299376 | instance | 2.17.12 2026-09-01 03:56:30.299393 | instance | Checked out vexxhost.containers to v1.6.8 2026-09-01 03:56:30.299410 | 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-01 03:56:30.299427 | instance | Starting galaxy collection install process 2026-09-01 03:56:30.299444 | instance | Process install dependency map 2026-09-01 03:56:30.299461 | instance | Starting collection install process 2026-09-01 03:56:30.299478 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers' 2026-09-01 03:56:30.299496 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers 2026-09-01 03:56:30.299514 | instance | vexxhost.containers:1.6.8 was installed successfully 2026-09-01 03:56:30.299531 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:56:30.299549 | instance | 2.17.12 2026-09-01 03:56:30.299567 | instance | Checked out vexxhost.kubernetes to v3.3.0 2026-09-01 03:56:30.299585 | 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-01 03:56:30.299602 | instance | Starting galaxy collection install process 2026-09-01 03:56:30.299619 | instance | Process install dependency map 2026-09-01 03:56:30.299636 | instance | Starting collection install process 2026-09-01 03:56:30.299654 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes' 2026-09-01 03:56:30.299671 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes 2026-09-01 03:56:30.299689 | instance | vexxhost.kubernetes:3.3.0 was installed successfully 2026-09-01 03:56:30.299706 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 03:56:30.299724 | instance | 2.17.12 2026-09-01 03:56:30.305275 | 2026-09-01 03:56:30.305326 | PLAY RECAP 2026-09-01 03:56:30.305369 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-01 03:56:30.305390 | 2026-09-01 03:56:30.489980 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-01 03:56:30.492134 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-01 03:56:31.108136 | 2026-09-01 03:56:31.108292 | PLAY [all] 2026-09-01 03:56:31.120057 | 2026-09-01 03:56:31.120130 | TASK [Install "jq" for log collection] 2026-09-01 03:56:41.146017 | instance | changed 2026-09-01 03:56:41.153669 | 2026-09-01 03:56:41.153771 | PLAY RECAP 2026-09-01 03:56:41.153830 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-01 03:56:41.153894 | 2026-09-01 03:56:41.280868 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-01 03:56:41.283049 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-01 03:56:41.890193 | 2026-09-01 03:56:41.890315 | PLAY [all] 2026-09-01 03:56:41.901232 | 2026-09-01 03:56:41.901312 | LOOP [Check inventory directories] 2026-09-01 03:56:42.265826 | instance | ok: "src/github.com/vexxhost/atmosphere" 2026-09-01 03:56:42.423527 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory" 2026-09-01 03:56:42.595193 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory" 2026-09-01 03:56:42.606559 | 2026-09-01 03:56:42.606701 | LOOP [Copy inventory file for Zuul] 2026-09-01 03:56:43.484404 | instance | changed: 2026-09-01 03:56:43.484777 | instance | { 2026-09-01 03:56:43.484847 | instance | "ansible_loop_var": "item", 2026-09-01 03:56:43.484903 | instance | "changed": false, 2026-09-01 03:56:43.484977 | instance | "failed": false, 2026-09-01 03:56:43.485036 | instance | "invocation": { 2026-09-01 03:56:43.485084 | instance | "module_args": { 2026-09-01 03:56:43.485129 | instance | "checksum_algorithm": "sha1", 2026-09-01 03:56:43.485173 | instance | "follow": true, 2026-09-01 03:56:43.485220 | instance | "get_attributes": true, 2026-09-01 03:56:43.485268 | instance | "get_checksum": true, 2026-09-01 03:56:43.485314 | instance | "get_mime": true, 2026-09-01 03:56:43.485359 | instance | "path": "src/github.com/vexxhost/atmosphere" 2026-09-01 03:56:43.485406 | instance | } 2026-09-01 03:56:43.485451 | instance | }, 2026-09-01 03:56:43.485494 | instance | "item": "src/github.com/vexxhost/atmosphere", 2026-09-01 03:56:43.485538 | instance | "stat": { 2026-09-01 03:56:43.485582 | instance | "atime": 1788234982.973573, 2026-09-01 03:56:43.485627 | instance | "attr_flags": "e", 2026-09-01 03:56:43.485672 | instance | "attributes": [ 2026-09-01 03:56:43.485716 | instance | "extents" 2026-09-01 03:56:43.485741 | instance | ], 2026-09-01 03:56:43.485762 | instance | "block_size": 4096, 2026-09-01 03:56:43.485783 | instance | "blocks": 8, 2026-09-01 03:56:43.485804 | instance | "charset": "unknown", 2026-09-01 03:56:43.485826 | instance | "ctime": 1788234981.9055703, 2026-09-01 03:56:43.485847 | instance | "dev": 64513, 2026-09-01 03:56:43.485868 | instance | "device_type": 0, 2026-09-01 03:56:43.485889 | instance | "executable": true, 2026-09-01 03:56:43.485909 | instance | "exists": true, 2026-09-01 03:56:43.485930 | instance | "gid": 1000, 2026-09-01 03:56:43.485950 | instance | "gr_name": "zuul", 2026-09-01 03:56:43.485971 | instance | "inode": 1048199, 2026-09-01 03:56:43.485991 | instance | "isblk": false, 2026-09-01 03:56:43.486012 | instance | "ischr": false, 2026-09-01 03:56:43.486033 | instance | "isdir": true, 2026-09-01 03:56:43.486054 | instance | "isfifo": false, 2026-09-01 03:56:43.486074 | instance | "isgid": false, 2026-09-01 03:56:43.486095 | instance | "islnk": false, 2026-09-01 03:56:43.486115 | instance | "isreg": false, 2026-09-01 03:56:43.486135 | instance | "issock": false, 2026-09-01 03:56:43.486156 | instance | "isuid": false, 2026-09-01 03:56:43.486177 | instance | "mimetype": "unknown", 2026-09-01 03:56:43.486197 | instance | "mode": "0755", 2026-09-01 03:56:43.486218 | instance | "mtime": 1788234981.9055703, 2026-09-01 03:56:43.486239 | instance | "nlink": 23, 2026-09-01 03:56:43.486260 | instance | "path": "src/github.com/vexxhost/atmosphere", 2026-09-01 03:56:43.486280 | instance | "pw_name": "zuul", 2026-09-01 03:56:43.486304 | instance | "readable": true, 2026-09-01 03:56:43.486325 | instance | "rgrp": true, 2026-09-01 03:56:43.486346 | instance | "roth": true, 2026-09-01 03:56:43.486413 | instance | "rusr": true, 2026-09-01 03:56:43.486437 | instance | "size": 4096, 2026-09-01 03:56:43.486455 | instance | "uid": 1000, 2026-09-01 03:56:43.486472 | instance | "version": "3897310170", 2026-09-01 03:56:43.486490 | instance | "wgrp": false, 2026-09-01 03:56:43.486508 | instance | "woth": false, 2026-09-01 03:56:43.486525 | instance | "writeable": true, 2026-09-01 03:56:43.486542 | instance | "wusr": true, 2026-09-01 03:56:43.486559 | instance | "xgrp": true, 2026-09-01 03:56:43.486575 | instance | "xoth": true, 2026-09-01 03:56:43.486592 | instance | "xusr": true 2026-09-01 03:56:43.486608 | instance | } 2026-09-01 03:56:43.486625 | instance | } 2026-09-01 03:56:43.486660 | instance | ok: All items complete 2026-09-01 03:56:43.486681 | 2026-09-01 03:56:43.487915 | instance | skipping: Conditional result was False 2026-09-01 03:56:43.488504 | instance | skipping: Conditional result was False 2026-09-01 03:56:43.504706 | 2026-09-01 03:56:43.504863 | LOOP [Switch "ansible_host" to private IP] 2026-09-01 03:56:43.876099 | instance | changed: 1 replacements made 2026-09-01 03:56:43.877996 | instance | skipping: Conditional result was False 2026-09-01 03:56:43.880240 | instance | skipping: Conditional result was False 2026-09-01 03:56:43.894634 | 2026-09-01 03:56:43.894966 | TASK [Run Molecule scenario] 2026-09-01 03:56:44.817287 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-09-01 03:56:45.217706 | instance | INFO [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-01 03:56:45.217828 | instance | INFO [csi > prerun] Performing prerun with role_name_check=0... 2026-09-01 03:56:56.262955 | instance | INFO [csi > dependency] Executing 2026-09-01 03:56:56.263088 | instance | WARNING [csi > dependency] Missing roles requirements file: requirements.yml 2026-09-01 03:56:56.263282 | instance | WARNING [csi > dependency] Missing collections requirements file: collections.yml 2026-09-01 03:56:56.263392 | instance | WARNING [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-09-01 03:56:56.274946 | instance | INFO [csi > cleanup] Executing 2026-09-01 03:56:56.275528 | instance | WARNING [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-01 03:56:56.287371 | instance | INFO [csi > destroy] Executing 2026-09-01 03:56:56.287424 | instance | WARNING [csi > destroy] Skipping, '--destroy=never' requested. 2026-09-01 03:56:56.287519 | instance | INFO [csi > destroy] Executed: Successful 2026-09-01 03:56:56.298519 | instance | INFO [csi > syntax] Executing 2026-09-01 03:56:57.847653 | instance | 2026-09-01 03:56:57.847736 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-01 03:56:57.909298 | instance | INFO [csi > syntax] Executed: Successful 2026-09-01 03:56:57.925188 | instance | INFO [csi > create] Executing 2026-09-01 03:56:57.928701 | instance | WARNING [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-01 03:56:57.943412 | instance | INFO [csi > prepare] Executing 2026-09-01 03:56:58.717174 | instance | 2026-09-01 03:56:58.717390 | instance | PLAY [Prepare] ***************************************************************** 2026-09-01 03:56:58.717725 | instance | 2026-09-01 03:56:58.717985 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-01 03:56:58.718265 | instance | Tuesday 01 September 2026 03:56:58 +0000 (0:00:00.024) 0:00:00.024 ***** 2026-09-01 03:57:00.029244 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-01 03:57:00.029333 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-01 03:57:00.029421 | instance | interpreter could change the meaning of that path. See 2026-09-01 03:57:00.029574 | instance | https://docs.ansible.com/ansible- 2026-09-01 03:57:00.029681 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-01 03:57:00.040348 | instance | ok: [instance] 2026-09-01 03:57:00.040390 | instance | 2026-09-01 03:57:00.040537 | instance | TASK [Configure short hostname] ************************************************ 2026-09-01 03:57:00.040637 | instance | Tuesday 01 September 2026 03:57:00 +0000 (0:00:01.323) 0:00:01.348 ***** 2026-09-01 03:57:00.795676 | instance | changed: [instance] 2026-09-01 03:57:00.795960 | instance | 2026-09-01 03:57:00.796378 | instance | TASK [Ensure hostname inside hosts file] *************************************** 2026-09-01 03:57:00.796748 | instance | Tuesday 01 September 2026 03:57:00 +0000 (0:00:00.754) 0:00:02.103 ***** 2026-09-01 03:57:01.075433 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created 2026-09-01 03:57:01.075752 | instance | with a mode of 0700, this may cause issues when running as another user. To 2026-09-01 03:57:01.076024 | instance | avoid this, create the remote_tmp dir with the correct permissions manually 2026-09-01 03:57:01.084509 | instance | changed: [instance] 2026-09-01 03:57:01.084762 | instance | 2026-09-01 03:57:01.085021 | instance | TASK [Purge "snapd" package] *************************************************** 2026-09-01 03:57:01.085269 | instance | Tuesday 01 September 2026 03:57:01 +0000 (0:00:00.289) 0:00:02.392 ***** 2026-09-01 03:57:01.983174 | instance | ok: [instance] 2026-09-01 03:57:01.983376 | instance | 2026-09-01 03:57:01.983688 | instance | PLAY [Create devices for Ceph] ************************************************* 2026-09-01 03:57:01.984007 | instance | 2026-09-01 03:57:01.984395 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-01 03:57:01.984775 | instance | Tuesday 01 September 2026 03:57:01 +0000 (0:00:00.898) 0:00:03.291 ***** 2026-09-01 03:57:02.748915 | instance | ok: [instance] 2026-09-01 03:57:02.749033 | instance | 2026-09-01 03:57:02.749298 | instance | TASK [Install depedencies] ***************************************************** 2026-09-01 03:57:02.749575 | instance | Tuesday 01 September 2026 03:57:02 +0000 (0:00:00.765) 0:00:04.056 ***** 2026-09-01 03:57:27.446398 | instance | changed: [instance] 2026-09-01 03:57:27.446459 | instance | 2026-09-01 03:57:27.446467 | instance | TASK [Start up service] ******************************************************** 2026-09-01 03:57:27.446474 | instance | Tuesday 01 September 2026 03:57:27 +0000 (0:00:24.697) 0:00:28.754 ***** 2026-09-01 03:57:28.006427 | instance | ok: [instance] 2026-09-01 03:57:28.007199 | instance | 2026-09-01 03:57:28.007268 | instance | TASK [Generate lvm.conf] ******************************************************* 2026-09-01 03:57:28.007302 | instance | Tuesday 01 September 2026 03:57:28 +0000 (0:00:00.560) 0:00:29.314 ***** 2026-09-01 03:57:28.300712 | instance | ok: [instance] 2026-09-01 03:57:28.300786 | instance | 2026-09-01 03:57:28.300994 | instance | TASK [Write /etc/lvm/lvm.conf] ************************************************* 2026-09-01 03:57:28.301024 | instance | Tuesday 01 September 2026 03:57:28 +0000 (0:00:00.294) 0:00:29.609 ***** 2026-09-01 03:57:28.973831 | instance | changed: [instance] 2026-09-01 03:57:28.974383 | instance | 2026-09-01 03:57:28.974461 | instance | TASK [Get list of all loopback devices] **************************************** 2026-09-01 03:57:28.974471 | instance | Tuesday 01 September 2026 03:57:28 +0000 (0:00:00.672) 0:00:30.281 ***** 2026-09-01 03:57:29.154391 | instance | ok: [instance] 2026-09-01 03:57:29.154510 | instance | 2026-09-01 03:57:29.154522 | instance | TASK [Fail if there is any existing loopback devices] ************************** 2026-09-01 03:57:29.154639 | instance | Tuesday 01 September 2026 03:57:29 +0000 (0:00:00.181) 0:00:30.463 ***** 2026-09-01 03:57:29.184967 | instance | skipping: [instance] 2026-09-01 03:57:29.185326 | instance | 2026-09-01 03:57:29.185693 | instance | TASK [Create devices for Ceph] ************************************************* 2026-09-01 03:57:29.186048 | instance | Tuesday 01 September 2026 03:57:29 +0000 (0:00:00.029) 0:00:30.492 ***** 2026-09-01 03:57:29.769971 | instance | changed: [instance] => (item=osd0) 2026-09-01 03:57:29.770019 | instance | changed: [instance] => (item=osd1) 2026-09-01 03:57:29.770030 | instance | changed: [instance] => (item=osd2) 2026-09-01 03:57:29.770040 | instance | 2026-09-01 03:57:29.770049 | instance | TASK [Set permissions on loopback devices] ************************************* 2026-09-01 03:57:29.770059 | instance | Tuesday 01 September 2026 03:57:29 +0000 (0:00:00.584) 0:00:31.076 ***** 2026-09-01 03:57:30.427807 | instance | changed: [instance] => (item=osd0) 2026-09-01 03:57:30.427926 | instance | changed: [instance] => (item=osd1) 2026-09-01 03:57:30.427944 | instance | changed: [instance] => (item=osd2) 2026-09-01 03:57:30.428238 | instance | 2026-09-01 03:57:30.428359 | instance | TASK [Start loop devices] ****************************************************** 2026-09-01 03:57:30.428381 | instance | Tuesday 01 September 2026 03:57:30 +0000 (0:00:00.659) 0:00:31.736 ***** 2026-09-01 03:57:31.165236 | instance | changed: [instance] => (item=osd0) 2026-09-01 03:57:31.166493 | instance | changed: [instance] => (item=osd1) 2026-09-01 03:57:31.166543 | instance | changed: [instance] => (item=osd2) 2026-09-01 03:57:31.166555 | instance | 2026-09-01 03:57:31.166564 | instance | TASK [Create a volume group for each loop device] ****************************** 2026-09-01 03:57:31.166574 | instance | Tuesday 01 September 2026 03:57:31 +0000 (0:00:00.736) 0:00:32.473 ***** 2026-09-01 03:57:34.648253 | instance | changed: [instance] => (item=osd0) 2026-09-01 03:57:34.648601 | instance | changed: [instance] => (item=osd1) 2026-09-01 03:57:34.648622 | instance | changed: [instance] => (item=osd2) 2026-09-01 03:57:34.649554 | instance | 2026-09-01 03:57:34.649645 | instance | TASK [Create a logical volume for each loop device] **************************** 2026-09-01 03:57:34.649657 | instance | Tuesday 01 September 2026 03:57:34 +0000 (0:00:03.482) 0:00:35.955 ***** 2026-09-01 03:57:36.667382 | instance | changed: [instance] => (item=ceph-instance-osd0) 2026-09-01 03:57:36.668216 | instance | changed: [instance] => (item=ceph-instance-osd1) 2026-09-01 03:57:36.668283 | instance | changed: [instance] => (item=ceph-instance-osd2) 2026-09-01 03:57:36.668294 | instance | 2026-09-01 03:57:36.672136 | instance | PLAY RECAP ********************************************************************* 2026-09-01 03:57:36.672196 | instance | instance : ok=15 changed=9 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-09-01 03:57:36.672208 | instance | 2026-09-01 03:57:36.672218 | instance | Tuesday 01 September 2026 03:57:36 +0000 (0:00:02.020) 0:00:37.976 ***** 2026-09-01 03:57:36.672227 | instance | =============================================================================== 2026-09-01 03:57:36.672236 | instance | Install depedencies ---------------------------------------------------- 24.70s 2026-09-01 03:57:36.672244 | instance | Create a volume group for each loop device ------------------------------ 3.48s 2026-09-01 03:57:36.672253 | instance | Create a logical volume for each loop device ---------------------------- 2.02s 2026-09-01 03:57:36.672262 | instance | Gathering Facts --------------------------------------------------------- 1.32s 2026-09-01 03:57:36.672271 | instance | Purge "snapd" package --------------------------------------------------- 0.90s 2026-09-01 03:57:36.672280 | instance | Gathering Facts --------------------------------------------------------- 0.77s 2026-09-01 03:57:36.672288 | instance | Configure short hostname ------------------------------------------------ 0.75s 2026-09-01 03:57:36.672297 | instance | Start loop devices ------------------------------------------------------ 0.74s 2026-09-01 03:57:36.672306 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.67s 2026-09-01 03:57:36.672314 | instance | Set permissions on loopback devices ------------------------------------- 0.66s 2026-09-01 03:57:36.672323 | instance | Create devices for Ceph ------------------------------------------------- 0.58s 2026-09-01 03:57:36.672337 | instance | Start up service -------------------------------------------------------- 0.56s 2026-09-01 03:57:36.672545 | instance | Generate lvm.conf ------------------------------------------------------- 0.29s 2026-09-01 03:57:36.672911 | instance | Ensure hostname inside hosts file --------------------------------------- 0.29s 2026-09-01 03:57:36.673252 | instance | Get list of all loopback devices ---------------------------------------- 0.18s 2026-09-01 03:57:36.673555 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s 2026-09-01 03:57:36.761035 | instance | INFO [csi > prepare] Executed: Successful 2026-09-01 03:57:36.776746 | instance | INFO [csi > converge] Executing 2026-09-01 03:57:38.167844 | instance | 2026-09-01 03:57:38.168364 | instance | PLAY [Debug CSI driver value] ************************************************** 2026-09-01 03:57:38.168783 | instance | 2026-09-01 03:57:38.169252 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-01 03:57:38.169627 | instance | Tuesday 01 September 2026 03:57:38 +0000 (0:00:00.007) 0:00:00.007 ***** 2026-09-01 03:57:39.223582 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-01 03:57:39.224047 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-01 03:57:39.224530 | instance | interpreter could change the meaning of that path. See 2026-09-01 03:57:39.224956 | instance | https://docs.ansible.com/ansible- 2026-09-01 03:57:39.225363 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-01 03:57:39.239392 | instance | ok: [instance] 2026-09-01 03:57:39.239753 | instance | 2026-09-01 03:57:39.240220 | instance | TASK [Display CSI driver value and environment variable] *********************** 2026-09-01 03:57:39.240562 | instance | Tuesday 01 September 2026 03:57:39 +0000 (0:00:01.071) 0:00:01.078 ***** 2026-09-01 03:57:39.304364 | instance | ok: [instance] => { 2026-09-01 03:57:39.304879 | instance | "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER=" 2026-09-01 03:57:39.305319 | instance | } 2026-09-01 03:57:39.305759 | instance | 2026-09-01 03:57:39.306250 | instance | PLAY [all] ********************************************************************* 2026-09-01 03:57:39.306693 | instance | 2026-09-01 03:57:39.307174 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-01 03:57:39.307677 | instance | Tuesday 01 September 2026 03:57:39 +0000 (0:00:00.064) 0:00:01.143 ***** 2026-09-01 03:57:40.240264 | instance | ok: [instance] 2026-09-01 03:57:40.240629 | instance | 2026-09-01 03:57:40.240955 | instance | TASK [Fail if atmosphere_ceph_enabled is set] ********************************** 2026-09-01 03:57:40.241497 | instance | Tuesday 01 September 2026 03:57:40 +0000 (0:00:00.936) 0:00:02.079 ***** 2026-09-01 03:57:40.304790 | instance | skipping: [instance] 2026-09-01 03:57:40.305039 | instance | 2026-09-01 03:57:40.305267 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] ***************** 2026-09-01 03:57:40.305462 | instance | Tuesday 01 September 2026 03:57:40 +0000 (0:00:00.062) 0:00:02.142 ***** 2026-09-01 03:57:40.494947 | instance | ok: [instance] 2026-09-01 03:57:40.495140 | instance | 2026-09-01 03:57:40.495288 | instance | PLAY [Deploy Ceph monitors & managers] ***************************************** 2026-09-01 03:57:40.495419 | instance | 2026-09-01 03:57:40.495593 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-01 03:57:40.495745 | instance | Tuesday 01 September 2026 03:57:40 +0000 (0:00:00.190) 0:00:02.333 ***** 2026-09-01 03:57:41.478386 | instance | ok: [instance] 2026-09-01 03:57:41.478443 | instance | 2026-09-01 03:57:41.478455 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-01 03:57:41.478465 | instance | Tuesday 01 September 2026 03:57:41 +0000 (0:00:00.983) 0:00:03.316 ***** 2026-09-01 03:57:41.808019 | instance | ok: [instance] 2026-09-01 03:57:41.808063 | instance | 2026-09-01 03:57:41.808068 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-01 03:57:41.808089 | instance | Tuesday 01 September 2026 03:57:41 +0000 (0:00:00.330) 0:00:03.647 ***** 2026-09-01 03:57:41.860296 | instance | skipping: [instance] 2026-09-01 03:57:41.860375 | instance | 2026-09-01 03:57:41.860394 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-09-01 03:57:41.860613 | instance | Tuesday 01 September 2026 03:57:41 +0000 (0:00:00.052) 0:00:03.699 ***** 2026-09-01 03:57:42.221760 | instance | changed: [instance] 2026-09-01 03:57:42.221815 | instance | 2026-09-01 03:57:42.221955 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-01 03:57:42.222064 | instance | Tuesday 01 September 2026 03:57:42 +0000 (0:00:00.361) 0:00:04.061 ***** 2026-09-01 03:57:42.296726 | instance | ok: [instance] => { 2026-09-01 03:57:42.296862 | instance | "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64" 2026-09-01 03:57:42.297014 | instance | } 2026-09-01 03:57:42.297153 | instance | 2026-09-01 03:57:42.297297 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-01 03:57:42.297437 | instance | Tuesday 01 September 2026 03:57:42 +0000 (0:00:00.075) 0:00:04.136 ***** 2026-09-01 03:57:43.036608 | instance | changed: [instance] 2026-09-01 03:57:43.037134 | instance | 2026-09-01 03:57:43.037617 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-01 03:57:43.037979 | instance | Tuesday 01 September 2026 03:57:43 +0000 (0:00:00.739) 0:00:04.876 ***** 2026-09-01 03:57:43.083212 | instance | skipping: [instance] 2026-09-01 03:57:43.083390 | instance | 2026-09-01 03:57:43.083604 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-01 03:57:43.083816 | instance | Tuesday 01 September 2026 03:57:43 +0000 (0:00:00.044) 0:00:04.921 ***** 2026-09-01 03:57:43.124964 | instance | skipping: [instance] 2026-09-01 03:57:43.124999 | instance | 2026-09-01 03:57:43.125004 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-01 03:57:43.125009 | instance | Tuesday 01 September 2026 03:57:43 +0000 (0:00:00.043) 0:00:04.964 ***** 2026-09-01 03:57:43.396731 | instance | ok: [instance] 2026-09-01 03:57:43.396779 | instance | 2026-09-01 03:57:43.396790 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-01 03:57:43.396797 | instance | Tuesday 01 September 2026 03:57:43 +0000 (0:00:00.271) 0:00:05.236 ***** 2026-09-01 03:57:44.665161 | instance | ok: [instance] 2026-09-01 03:57:44.665221 | instance | 2026-09-01 03:57:44.665233 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-01 03:57:44.665269 | instance | Tuesday 01 September 2026 03:57:44 +0000 (0:00:01.267) 0:00:06.503 ***** 2026-09-01 03:57:44.754849 | instance | ok: [instance] => { 2026-09-01 03:57:44.754925 | instance | "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz" 2026-09-01 03:57:44.754938 | instance | } 2026-09-01 03:57:44.754948 | instance | 2026-09-01 03:57:44.754958 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-01 03:57:44.754968 | instance | Tuesday 01 September 2026 03:57:44 +0000 (0:00:00.088) 0:00:06.592 ***** 2026-09-01 03:57:45.561703 | instance | changed: [instance] 2026-09-01 03:57:45.562083 | instance | 2026-09-01 03:57:45.562414 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-01 03:57:45.562728 | instance | Tuesday 01 September 2026 03:57:45 +0000 (0:00:00.808) 0:00:07.401 ***** 2026-09-01 03:57:48.509840 | instance | changed: [instance] 2026-09-01 03:57:48.509928 | instance | 2026-09-01 03:57:48.509940 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] *************** 2026-09-01 03:57:48.509950 | instance | Tuesday 01 September 2026 03:57:48 +0000 (0:00:02.947) 0:00:10.348 ***** 2026-09-01 03:57:48.553549 | instance | skipping: [instance] 2026-09-01 03:57:48.554891 | instance | 2026-09-01 03:57:48.554984 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] *** 2026-09-01 03:57:48.555033 | instance | Tuesday 01 September 2026 03:57:48 +0000 (0:00:00.044) 0:00:10.392 ***** 2026-09-01 03:57:48.598021 | instance | skipping: [instance] 2026-09-01 03:57:48.598295 | instance | 2026-09-01 03:57:48.598693 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ******** 2026-09-01 03:57:48.599092 | instance | Tuesday 01 September 2026 03:57:48 +0000 (0:00:00.044) 0:00:10.437 ***** 2026-09-01 03:57:48.637069 | instance | skipping: [instance] 2026-09-01 03:57:48.637268 | instance | 2026-09-01 03:57:48.637859 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] ************** 2026-09-01 03:57:48.638247 | instance | Tuesday 01 September 2026 03:57:48 +0000 (0:00:00.039) 0:00:10.476 ***** 2026-09-01 03:57:56.972636 | instance | changed: [instance] 2026-09-01 03:57:56.972916 | instance | 2026-09-01 03:57:56.973364 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] *** 2026-09-01 03:57:56.973767 | instance | Tuesday 01 September 2026 03:57:56 +0000 (0:00:08.335) 0:00:18.811 ***** 2026-09-01 03:57:57.632886 | instance | changed: [instance] 2026-09-01 03:57:57.633684 | instance | 2026-09-01 03:57:57.633741 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] ******* 2026-09-01 03:57:57.633754 | instance | Tuesday 01 September 2026 03:57:57 +0000 (0:00:00.660) 0:00:19.472 ***** 2026-09-01 03:57:58.643736 | instance | changed: [instance] => (item={'path': '/etc/containerd'}) 2026-09-01 03:57:58.644214 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'}) 2026-09-01 03:57:58.644304 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'}) 2026-09-01 03:57:58.645501 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'}) 2026-09-01 03:57:58.645748 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'}) 2026-09-01 03:57:58.645762 | instance | 2026-09-01 03:57:58.645773 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] ********** 2026-09-01 03:57:58.645783 | instance | Tuesday 01 September 2026 03:57:58 +0000 (0:00:01.010) 0:00:20.482 ***** 2026-09-01 03:57:59.302490 | instance | changed: [instance] 2026-09-01 03:57:59.302554 | instance | 2026-09-01 03:57:59.303283 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ******** 2026-09-01 03:57:59.303303 | instance | Tuesday 01 September 2026 03:57:59 +0000 (0:00:00.633) 0:00:21.116 ***** 2026-09-01 03:57:59.303310 | instance | 2026-09-01 03:57:59.303316 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-01 03:57:59.303321 | instance | Tuesday 01 September 2026 03:57:59 +0000 (0:00:00.025) 0:00:21.142 ***** 2026-09-01 03:58:00.344010 | instance | ok: [instance] 2026-09-01 03:58:00.344118 | instance | 2026-09-01 03:58:00.344135 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] ********** 2026-09-01 03:58:00.344145 | instance | Tuesday 01 September 2026 03:58:00 +0000 (0:00:01.040) 0:00:22.182 ***** 2026-09-01 03:58:00.826401 | instance | changed: [instance] 2026-09-01 03:58:00.826529 | instance | 2026-09-01 03:58:00.826835 | instance | TASK [vexxhost.containers.containerd : Enable and start service] *************** 2026-09-01 03:58:00.826889 | instance | Tuesday 01 September 2026 03:58:00 +0000 (0:00:00.483) 0:00:22.666 ***** 2026-09-01 03:58:01.481941 | instance | changed: [instance] 2026-09-01 03:58:01.482532 | instance | 2026-09-01 03:58:01.482605 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-01 03:58:01.482615 | instance | Tuesday 01 September 2026 03:58:01 +0000 (0:00:00.654) 0:00:23.320 ***** 2026-09-01 03:58:01.720237 | instance | ok: [instance] 2026-09-01 03:58:01.720574 | instance | 2026-09-01 03:58:01.720613 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-01 03:58:01.720619 | instance | Tuesday 01 September 2026 03:58:01 +0000 (0:00:00.239) 0:00:23.560 ***** 2026-09-01 03:58:01.806896 | instance | ok: [instance] => { 2026-09-01 03:58:01.807649 | instance | "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz" 2026-09-01 03:58:01.807687 | instance | } 2026-09-01 03:58:01.807695 | instance | 2026-09-01 03:58:01.807701 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-01 03:58:01.807708 | instance | Tuesday 01 September 2026 03:58:01 +0000 (0:00:00.085) 0:00:23.646 ***** 2026-09-01 03:58:33.683583 | instance | changed: [instance] 2026-09-01 03:58:33.683719 | instance | 2026-09-01 03:58:33.684170 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-01 03:58:33.684392 | instance | Tuesday 01 September 2026 03:58:33 +0000 (0:00:31.876) 0:00:55.522 ***** 2026-09-01 03:58:38.097055 | instance | changed: [instance] 2026-09-01 03:58:38.097149 | instance | 2026-09-01 03:58:38.097673 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ****************** 2026-09-01 03:58:38.097893 | instance | Tuesday 01 September 2026 03:58:38 +0000 (0:00:04.413) 0:00:59.936 ***** 2026-09-01 03:58:39.254479 | instance | ok: [instance] 2026-09-01 03:58:39.255003 | instance | 2026-09-01 03:58:39.255050 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] *************** 2026-09-01 03:58:39.255057 | instance | Tuesday 01 September 2026 03:58:39 +0000 (0:00:01.157) 0:01:01.094 ***** 2026-09-01 03:58:39.622462 | instance | changed: [instance] 2026-09-01 03:58:39.622579 | instance | 2026-09-01 03:58:39.622655 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] ***** 2026-09-01 03:58:39.622811 | instance | Tuesday 01 September 2026 03:58:39 +0000 (0:00:00.368) 0:01:01.462 ***** 2026-09-01 03:58:40.113011 | instance | changed: [instance] 2026-09-01 03:58:40.113092 | instance | 2026-09-01 03:58:40.113337 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] *********** 2026-09-01 03:58:40.113353 | instance | Tuesday 01 September 2026 03:58:40 +0000 (0:00:00.490) 0:01:01.953 ***** 2026-09-01 03:58:40.743575 | instance | changed: [instance] => (item={'path': '/etc/docker'}) 2026-09-01 03:58:40.744221 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'}) 2026-09-01 03:58:40.744289 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'}) 2026-09-01 03:58:40.745017 | instance | 2026-09-01 03:58:40.745249 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ****** 2026-09-01 03:58:40.745269 | instance | Tuesday 01 September 2026 03:58:40 +0000 (0:00:00.629) 0:01:02.582 ***** 2026-09-01 03:58:41.243021 | instance | changed: [instance] 2026-09-01 03:58:41.244162 | instance | 2026-09-01 03:58:41.244277 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] *********** 2026-09-01 03:58:41.244293 | instance | Tuesday 01 September 2026 03:58:41 +0000 (0:00:00.498) 0:01:03.081 ***** 2026-09-01 03:58:41.791458 | instance | changed: [instance] 2026-09-01 03:58:41.792127 | instance | 2026-09-01 03:58:41.792157 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************ 2026-09-01 03:58:41.792184 | instance | Tuesday 01 September 2026 03:58:41 +0000 (0:00:00.522) 0:01:03.604 ***** 2026-09-01 03:58:41.792772 | instance | 2026-09-01 03:58:41.792790 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-01 03:58:41.792806 | instance | Tuesday 01 September 2026 03:58:41 +0000 (0:00:00.026) 0:01:03.630 ***** 2026-09-01 03:58:42.539272 | instance | ok: [instance] 2026-09-01 03:58:42.539329 | instance | 2026-09-01 03:58:42.539341 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ****************** 2026-09-01 03:58:42.539351 | instance | Tuesday 01 September 2026 03:58:42 +0000 (0:00:00.747) 0:01:04.378 ***** 2026-09-01 03:58:43.522950 | instance | changed: [instance] 2026-09-01 03:58:43.523009 | instance | 2026-09-01 03:58:43.523022 | instance | TASK [vexxhost.containers.docker : Enable and start service] ******************* 2026-09-01 03:58:43.523032 | instance | Tuesday 01 September 2026 03:58:43 +0000 (0:00:00.983) 0:01:05.361 ***** 2026-09-01 03:58:44.182901 | instance | changed: [instance] 2026-09-01 03:58:44.182970 | instance | 2026-09-01 03:58:44.182985 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ****** 2026-09-01 03:58:44.182998 | instance | Tuesday 01 September 2026 03:58:44 +0000 (0:00:00.659) 0:01:06.021 ***** 2026-09-01 03:58:44.260324 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml) 2026-09-01 03:58:44.260470 | instance | 2026-09-01 03:58:44.260787 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ******************************** 2026-09-01 03:58:44.261071 | instance | Tuesday 01 September 2026 03:58:44 +0000 (0:00:00.078) 0:01:06.099 ***** 2026-09-01 03:58:50.924204 | instance | changed: [instance] 2026-09-01 03:58:50.925106 | instance | 2026-09-01 03:58:50.925140 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] ********************* 2026-09-01 03:58:50.925153 | instance | Tuesday 01 September 2026 03:58:50 +0000 (0:00:06.664) 0:01:12.763 ***** 2026-09-01 03:58:51.595677 | instance | ok: [instance] => (item=chronyd) 2026-09-01 03:58:51.595747 | instance | ok: [instance] => (item=sshd) 2026-09-01 03:58:51.596146 | instance | 2026-09-01 03:58:51.596310 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ****************************** 2026-09-01 03:58:51.596324 | instance | Tuesday 01 September 2026 03:58:51 +0000 (0:00:00.672) 0:01:13.435 ***** 2026-09-01 03:58:51.937672 | instance | changed: [instance] 2026-09-01 03:58:51.938440 | instance | 2026-09-01 03:58:51.938669 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ******************** 2026-09-01 03:58:51.938684 | instance | Tuesday 01 September 2026 03:58:51 +0000 (0:00:00.341) 0:01:13.777 ***** 2026-09-01 03:58:52.160616 | instance | ok: [instance] 2026-09-01 03:58:52.161386 | instance | 2026-09-01 03:58:52.161440 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] **************** 2026-09-01 03:58:52.161452 | instance | Tuesday 01 September 2026 03:58:52 +0000 (0:00:00.222) 0:01:14.000 ***** 2026-09-01 03:58:52.745228 | instance | changed: [instance] 2026-09-01 03:58:52.745304 | instance | 2026-09-01 03:58:52.745662 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] *** 2026-09-01 03:58:52.745986 | instance | Tuesday 01 September 2026 03:58:52 +0000 (0:00:00.584) 0:01:14.584 ***** 2026-09-01 03:58:53.185816 | instance | changed: [instance] 2026-09-01 03:58:53.186345 | instance | 2026-09-01 03:58:53.186390 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] ************************* 2026-09-01 03:58:53.186400 | instance | Tuesday 01 September 2026 03:58:53 +0000 (0:00:00.441) 0:01:15.025 ***** 2026-09-01 03:58:53.378309 | instance | ok: [instance] 2026-09-01 03:58:53.378388 | instance | 2026-09-01 03:58:53.378599 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] ******************* 2026-09-01 03:58:53.378639 | instance | Tuesday 01 September 2026 03:58:53 +0000 (0:00:00.192) 0:01:15.218 ***** 2026-09-01 03:58:53.638457 | instance | ok: [instance] => (item=instance) 2026-09-01 03:58:53.639242 | instance | 2026-09-01 03:58:53.639314 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ******** 2026-09-01 03:58:53.639327 | instance | Tuesday 01 September 2026 03:58:53 +0000 (0:00:00.259) 0:01:15.477 ***** 2026-09-01 03:58:53.717538 | instance | ok: [instance] 2026-09-01 03:58:53.717618 | instance | 2026-09-01 03:58:53.717631 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] *********************************** 2026-09-01 03:58:53.717644 | instance | Tuesday 01 September 2026 03:58:53 +0000 (0:00:00.077) 0:01:15.555 ***** 2026-09-01 03:58:53.796917 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance 2026-09-01 03:58:53.796996 | instance | 2026-09-01 03:58:53.797236 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] ************* 2026-09-01 03:58:53.797276 | instance | Tuesday 01 September 2026 03:58:53 +0000 (0:00:00.081) 0:01:15.636 ***** 2026-09-01 03:58:54.103101 | instance | changed: [instance] 2026-09-01 03:58:54.104018 | instance | 2026-09-01 03:58:54.104108 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ****************** 2026-09-01 03:58:54.104128 | instance | Tuesday 01 September 2026 03:58:54 +0000 (0:00:00.305) 0:01:15.942 ***** 2026-09-01 03:58:54.796791 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True}) 2026-09-01 03:58:54.796855 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0}) 2026-09-01 03:58:54.797485 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False}) 2026-09-01 03:58:54.797523 | instance | 2026-09-01 03:58:54.797528 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] ******************************* 2026-09-01 03:58:54.797533 | instance | Tuesday 01 September 2026 03:58:54 +0000 (0:00:00.694) 0:01:16.637 ***** 2026-09-01 04:00:33.387012 | instance | ok: [instance] 2026-09-01 04:00:33.387124 | instance | 2026-09-01 04:00:33.387318 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] *************** 2026-09-01 04:00:33.387544 | instance | Tuesday 01 September 2026 04:00:33 +0000 (0:01:38.589) 0:02:55.226 ***** 2026-09-01 04:00:33.610083 | instance | changed: [instance] 2026-09-01 04:00:33.610157 | instance | 2026-09-01 04:00:33.610165 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] ********************************** 2026-09-01 04:00:33.610172 | instance | Tuesday 01 September 2026 04:00:33 +0000 (0:00:00.222) 0:02:55.449 ***** 2026-09-01 04:00:33.644425 | instance | ok: [instance] 2026-09-01 04:00:33.644481 | instance | 2026-09-01 04:00:33.644487 | instance | TASK [Install Ceph host] ******************************************************* 2026-09-01 04:00:33.644493 | instance | Tuesday 01 September 2026 04:00:33 +0000 (0:00:00.034) 0:02:55.483 ***** 2026-09-01 04:00:33.726654 | instance | included: vexxhost.ceph.cephadm_host for instance 2026-09-01 04:00:33.726697 | instance | 2026-09-01 04:00:33.726705 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ****** 2026-09-01 04:00:33.726711 | instance | Tuesday 01 September 2026 04:00:33 +0000 (0:00:00.082) 0:02:55.566 ***** 2026-09-01 04:00:35.621237 | instance | ok: [instance] 2026-09-01 04:00:35.621454 | instance | 2026-09-01 04:00:35.621727 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] *** 2026-09-01 04:00:35.622051 | instance | Tuesday 01 September 2026 04:00:35 +0000 (0:00:01.894) 0:02:57.460 ***** 2026-09-01 04:00:35.690546 | instance | ok: [instance] => (item=instance) 2026-09-01 04:00:35.690614 | instance | 2026-09-01 04:00:35.690751 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] *********** 2026-09-01 04:00:35.690848 | instance | Tuesday 01 September 2026 04:00:35 +0000 (0:00:00.069) 0:02:57.530 ***** 2026-09-01 04:00:36.066768 | instance | ok: [instance] 2026-09-01 04:00:36.067312 | instance | 2026-09-01 04:00:36.067392 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] *********************** 2026-09-01 04:00:36.067402 | instance | Tuesday 01 September 2026 04:00:36 +0000 (0:00:00.375) 0:02:57.905 ***** 2026-09-01 04:00:38.995622 | instance | ok: [instance] 2026-09-01 04:00:38.995766 | instance | 2026-09-01 04:00:38.995786 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ****************** 2026-09-01 04:00:38.996020 | instance | Tuesday 01 September 2026 04:00:38 +0000 (0:00:02.929) 0:03:00.835 ***** 2026-09-01 04:01:37.084263 | 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-01 04:01:37.085619 | 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_1t0f54x7/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_1t0f54x7/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_1t0f54x7/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_1t0f54x7/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-01T04:01:36.873+0000 7f88aba8c640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgC1TZZq+SpiCCAA4SFyRBSjAH4Q2sx+p54FBlX0zd51v7+WxU6MOlzAQiw=: Malformed input [buffer:3]\n2026-09-01T04:01:36.873+0000 7f88aba8c640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-01T04:01:36.873+0000 7f88aba8c640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgC1TZZq+SpiCCAA4SFyRBSjAH4Q2sx+p54FBlX0zd51v7+WxU6MOlzAQiw=: Malformed input [buffer:3]\n2026-09-01T04:01:36.873+0000 7f88aba8c640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-01T04:01:36.873+0000 7f88aba8c640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgC1TZZq+SpiCCAA4SFyRBSjAH4Q2sx+p54FBlX0zd51v7+WxU6MOlzAQiw=: Malformed input [buffer:3]\n2026-09-01T04:01:36.873+0000 7f88aba8c640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-01T04:01:36.873+0000 7f88aba8c640 -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-01 04:01:37.088859 | instance | 2026-09-01 04:01:37.089240 | instance | PLAY RECAP ********************************************************************* 2026-09-01 04:01:37.092191 | instance | instance : ok=55 changed=26 unreachable=0 failed=1 skipped=7 rescued=0 ignored=0 2026-09-01 04:01:37.092216 | instance | 2026-09-01 04:01:37.092223 | instance | Tuesday 01 September 2026 04:01:37 +0000 (0:00:58.093) 0:03:58.929 ***** 2026-09-01 04:01:37.092229 | instance | =============================================================================== 2026-09-01 04:01:37.092237 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 98.59s 2026-09-01 04:01:37.092244 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 58.09s 2026-09-01 04:01:37.092252 | instance | vexxhost.containers.download_artifact : Download item ------------------ 31.88s 2026-09-01 04:01:37.092260 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 8.34s 2026-09-01 04:01:37.092267 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 6.66s 2026-09-01 04:01:37.092275 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.41s 2026-09-01 04:01:37.092297 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.95s 2026-09-01 04:01:37.095538 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.93s 2026-09-01 04:01:37.095602 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.89s 2026-09-01 04:01:37.095615 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.79s 2026-09-01 04:01:37.095625 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.27s 2026-09-01 04:01:37.095633 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.16s 2026-09-01 04:01:37.095642 | instance | Gathering Facts --------------------------------------------------------- 1.07s 2026-09-01 04:01:37.095651 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.01s 2026-09-01 04:01:37.095660 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.98s 2026-09-01 04:01:37.095668 | instance | Gathering Facts --------------------------------------------------------- 0.98s 2026-09-01 04:01:37.095677 | instance | Gathering Facts --------------------------------------------------------- 0.94s 2026-09-01 04:01:37.095686 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.81s 2026-09-01 04:01:37.095694 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.74s 2026-09-01 04:01:37.095703 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.69s 2026-09-01 04:01:37.205753 | 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-01 04:01:37.206071 | instance | ERROR [csi > converge] Executed: Failed 2026-09-01 04:01:37.206287 | 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-01 04:01:37.323191 | instance | ERROR 2026-09-01 04:01:37.323642 | instance | { 2026-09-01 04:01:37.323673 | instance | "delta": "0:04:53.021603", 2026-09-01 04:01:37.323694 | instance | "end": "2026-09-01 04:01:37.275344", 2026-09-01 04:01:37.323712 | instance | "msg": "non-zero return code", 2026-09-01 04:01:37.323730 | instance | "rc": 2, 2026-09-01 04:01:37.323748 | instance | "start": "2026-09-01 03:56:44.253741" 2026-09-01 04:01:37.323766 | instance | } failure 2026-09-01 04:01:37.329658 | 2026-09-01 04:01:37.329706 | PLAY RECAP 2026-09-01 04:01:37.329754 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-01 04:01:37.329775 | 2026-09-01 04:01:37.457220 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-01 04:01:37.459556 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-01 04:01:38.108768 | 2026-09-01 04:01:38.108918 | PLAY [all] 2026-09-01 04:01:38.123684 | 2026-09-01 04:01:38.123763 | TASK [gather-host-logs : creating directory for system status] 2026-09-01 04:01:38.503635 | instance | changed 2026-09-01 04:01:38.512020 | 2026-09-01 04:01:38.512099 | TASK [gather-host-logs : Get logs for each host] 2026-09-01 04:01:38.883684 | instance | + systemd-cgls --full --all --no-pager 2026-09-01 04:01:38.899570 | instance | + ip addr 2026-09-01 04:01:38.902660 | instance | + ip route 2026-09-01 04:01:38.905230 | instance | + lsblk 2026-09-01 04:01:38.909959 | instance | + mount 2026-09-01 04:01:38.912813 | instance | + docker images 2026-09-01 04:01:38.938671 | instance | + brctl show 2026-09-01 04:01:38.939335 | instance | /bin/bash: line 8: brctl: command not found 2026-09-01 04:01:38.939700 | instance | + ps aux --sort=-%mem 2026-09-01 04:01:38.956190 | instance | + dpkg -l 2026-09-01 04:01:38.970517 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-09-01 04:01:38.971072 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-09-01 04:01:38.991753 | instance | + '[' '!' -z '' ']' 2026-09-01 04:01:39.049904 | instance | ok: Runtime: 0:00:00.112320 2026-09-01 04:01:39.055962 | 2026-09-01 04:01:39.056034 | TASK [gather-host-logs : Downloads logs to executor] 2026-09-01 04:01:39.718156 | instance | changed: 2026-09-01 04:01:39.718393 | instance | created directory /var/lib/zuul/builds/b157548a00eb44e0a74bb1e7c2598c83/work/logs/instance 2026-09-01 04:01:39.718433 | instance | cd+++++++++ system/ 2026-09-01 04:01:39.718465 | instance | >f+++++++++ system/brctl-show.txt 2026-09-01 04:01:39.718493 | instance | >f+++++++++ system/docker-images.txt 2026-09-01 04:01:39.718521 | instance | >f+++++++++ system/ip-addr.txt 2026-09-01 04:01:39.718552 | instance | >f+++++++++ system/ip-route.txt 2026-09-01 04:01:39.718584 | instance | >f+++++++++ system/lsblk.txt 2026-09-01 04:01:39.718612 | instance | >f+++++++++ system/mount.txt 2026-09-01 04:01:39.718639 | instance | >f+++++++++ system/packages.txt 2026-09-01 04:01:39.718669 | instance | >f+++++++++ system/ps.txt 2026-09-01 04:01:39.718701 | instance | >f+++++++++ system/systemd-cgls.txt 2026-09-01 04:01:39.730050 | 2026-09-01 04:01:39.730121 | LOOP [helm-release-status : creating directory for helm release status] 2026-09-01 04:01:39.939686 | instance | changed: "values" 2026-09-01 04:01:40.106328 | instance | changed: "releases" 2026-09-01 04:01:40.124025 | 2026-09-01 04:01:40.124217 | TASK [helm-release-status : Gather get release status for helm charts] 2026-09-01 04:01:40.352971 | instance | /bin/bash: line 3: kubectl: command not found 2026-09-01 04:01:40.661986 | instance | ok: Runtime: 0:00:00.006655 2026-09-01 04:01:40.669209 | 2026-09-01 04:01:40.669337 | TASK [helm-release-status : Downloads logs to executor] 2026-09-01 04:01:41.178489 | instance | changed: 2026-09-01 04:01:41.178661 | instance | cd+++++++++ helm/ 2026-09-01 04:01:41.178691 | instance | cd+++++++++ helm/releases/ 2026-09-01 04:01:41.178717 | instance | cd+++++++++ helm/values/ 2026-09-01 04:01:41.187972 | 2026-09-01 04:01:41.188047 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-09-01 04:01:41.424642 | instance | changed 2026-09-01 04:01:41.431353 | 2026-09-01 04:01:41.431426 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-09-01 04:01:41.717819 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-01 04:01:41.717961 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-01 04:01:41.724352 | instance | environment: line 1: kubectl: command not found 2026-09-01 04:01:41.726239 | instance | xargs: environment: line 1: kubectl: command not found 2026-09-01 04:01:41.726266 | instance | warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-01 04:01:41.727857 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-01 04:01:41.729660 | instance | environment: line 1: kubectl: command not found 2026-09-01 04:01:41.731330 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-01 04:01:41.731900 | instance | environment: line 1: kubectl: command not found 2026-09-01 04:01:41.733305 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-01 04:01:41.734786 | instance | environment: line 1: kubectl: command not found 2026-09-01 04:01:41.735976 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-01 04:01:41.969808 | instance | ok: Runtime: 0:00:00.026825 2026-09-01 04:01:41.974959 | 2026-09-01 04:01:41.975026 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-09-01 04:01:42.192929 | instance | changed 2026-09-01 04:01:42.199294 | 2026-09-01 04:01:42.199367 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-09-01 04:01:42.423565 | instance | environment: line 5: kubectl: command not found 2026-09-01 04:01:42.424002 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-01 04:01:42.424651 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-01 04:01:42.425326 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-01 04:01:42.737412 | instance | ok: Runtime: 0:00:00.008705 2026-09-01 04:01:42.743605 | 2026-09-01 04:01:42.743672 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-09-01 04:01:43.232561 | instance | changed: 2026-09-01 04:01:43.232718 | instance | cd+++++++++ objects/ 2026-09-01 04:01:43.232756 | instance | cd+++++++++ objects/cluster/ 2026-09-01 04:01:43.232788 | instance | cd+++++++++ objects/namespaced/ 2026-09-01 04:01:43.243089 | 2026-09-01 04:01:43.243150 | TASK [gather-pod-logs : creating directory for pod logs] 2026-09-01 04:01:43.790402 | instance | changed 2026-09-01 04:01:43.797109 | 2026-09-01 04:01:43.797192 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-09-01 04:01:44.025084 | instance | changed 2026-09-01 04:01:44.030032 | 2026-09-01 04:01:44.030102 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-09-01 04:01:44.258241 | instance | environment: line 3: kubectl: command not found 2026-09-01 04:01:44.568527 | instance | ok: Runtime: 0:00:00.006871 2026-09-01 04:01:44.575072 | 2026-09-01 04:01:44.575140 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-09-01 04:01:45.066011 | instance | changed: 2026-09-01 04:01:45.066351 | instance | cd+++++++++ pod-logs/ 2026-09-01 04:01:45.066410 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-09-01 04:01:45.076612 | 2026-09-01 04:01:45.076677 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-09-01 04:01:45.274930 | instance | changed 2026-09-01 04:01:45.280149 | 2026-09-01 04:01:45.280220 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-09-01 04:01:45.514380 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-01 04:01:45.818417 | instance | ok: Runtime: 0:00:00.055059 2026-09-01 04:01:45.824764 | 2026-09-01 04:01:45.824835 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-09-01 04:01:46.065079 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-01 04:01:46.109389 | instance | ceph-mgr endpoints: 2026-09-01 04:01:46.364360 | instance | ok: Runtime: 0:00:00.049748 2026-09-01 04:01:46.371909 | 2026-09-01 04:01:46.372022 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-09-01 04:01:46.609683 | instance | /bin/bash: line 4: kubectl: command not found 2026-09-01 04:01:46.911132 | instance | ok: Runtime: 0:00:00.054561 2026-09-01 04:01:46.917951 | 2026-09-01 04:01:46.918039 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-09-01 04:01:47.414445 | instance | changed: cd+++++++++ prometheus/ 2026-09-01 04:01:47.423479 | 2026-09-01 04:01:47.423545 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-09-01 04:01:47.627175 | instance | changed 2026-09-01 04:01:47.631999 | 2026-09-01 04:01:47.632061 | TASK [gather-selenium-data : Get selenium data] 2026-09-01 04:01:47.903609 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-09-01 04:01:47.905145 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-09-01 04:01:48.168231 | instance | ERROR 2026-09-01 04:01:48.168481 | instance | { 2026-09-01 04:01:48.168524 | instance | "delta": "0:00:00.006987", 2026-09-01 04:01:48.168552 | instance | "end": "2026-09-01 04:01:47.905601", 2026-09-01 04:01:48.168581 | instance | "msg": "non-zero return code", 2026-09-01 04:01:48.168609 | instance | "rc": 1, 2026-09-01 04:01:48.168634 | instance | "start": "2026-09-01 04:01:47.898614" 2026-09-01 04:01:48.168659 | instance | } 2026-09-01 04:01:48.168691 | instance | ERROR: Ignoring Errors 2026-09-01 04:01:48.175465 | 2026-09-01 04:01:48.175552 | TASK [gather-selenium-data : Downloads logs to executor] 2026-09-01 04:01:48.676345 | instance | changed: cd+++++++++ selenium/ 2026-09-01 04:01:48.682587 | 2026-09-01 04:01:48.682639 | PLAY RECAP 2026-09-01 04:01:48.682689 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-09-01 04:01:48.682737 | 2026-09-01 04:01:48.854608 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-01 04:01:48.859151 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-01 04:01:49.465134 | 2026-09-01 04:01:49.465434 | PLAY [all] 2026-09-01 04:01:49.484394 | 2026-09-01 04:01:49.484524 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-01 04:01:49.530576 | instance | skipping: Conditional result was False 2026-09-01 04:01:49.540046 | 2026-09-01 04:01:49.540167 | TASK [fetch-output : Set log path for single node] 2026-09-01 04:01:49.583660 | instance | ok 2026-09-01 04:01:49.590464 | 2026-09-01 04:01:49.590567 | LOOP [fetch-output : Ensure local output dirs] 2026-09-01 04:01:49.974738 | instance -> localhost | ok: "/var/lib/zuul/builds/b157548a00eb44e0a74bb1e7c2598c83/work/logs" 2026-09-01 04:01:50.187969 | instance -> localhost | changed: "/var/lib/zuul/builds/b157548a00eb44e0a74bb1e7c2598c83/work/artifacts" 2026-09-01 04:01:50.397723 | instance -> localhost | changed: "/var/lib/zuul/builds/b157548a00eb44e0a74bb1e7c2598c83/work/docs" 2026-09-01 04:01:50.419265 | 2026-09-01 04:01:50.419405 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-01 04:01:51.085847 | instance | changed: .d..t...... ./ 2026-09-01 04:01:51.086088 | instance | changed: All items complete 2026-09-01 04:01:51.086127 | 2026-09-01 04:01:51.560833 | instance | changed: .d..t...... ./ 2026-09-01 04:01:52.034918 | instance | changed: .d..t...... ./ 2026-09-01 04:01:52.058358 | 2026-09-01 04:01:52.058480 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-01 04:01:52.466263 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008513 2026-09-01 04:01:52.688261 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007995 2026-09-01 04:01:52.703991 | 2026-09-01 04:01:52.704136 | PLAY [all] 2026-09-01 04:01:52.710301 | 2026-09-01 04:01:52.710368 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-01 04:01:53.160946 | instance | changed 2026-09-01 04:01:53.166377 | 2026-09-01 04:01:53.166429 | PLAY RECAP 2026-09-01 04:01:53.166475 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-01 04:01:53.166497 | 2026-09-01 04:01:53.313461 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-01 04:01:53.317249 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-01 04:01:53.898252 | 2026-09-01 04:01:53.898393 | PLAY [localhost] 2026-09-01 04:01:53.908950 | 2026-09-01 04:01:53.909056 | TASK [Generate Zuul manifest] 2026-09-01 04:01:53.932496 | localhost | ok 2026-09-01 04:01:53.951391 | 2026-09-01 04:01:53.951474 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-01 04:01:54.339496 | localhost | changed 2026-09-01 04:01:54.350553 | 2026-09-01 04:01:54.350635 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-01 04:01:54.382400 | localhost | ok 2026-09-01 04:01:54.391482 | 2026-09-01 04:01:54.391568 | TASK [Upload logs] 2026-09-01 04:01:54.415962 | localhost | ok 2026-09-01 04:01:54.474405 | 2026-09-01 04:01:54.474537 | TASK [Set zuul-log-path fact] 2026-09-01 04:01:54.498700 | localhost | ok 2026-09-01 04:01:54.514060 | 2026-09-01 04:01:54.514127 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-01 04:01:54.545907 | localhost | ok 2026-09-01 04:01:54.554942 | 2026-09-01 04:01:54.555041 | TASK [upload-logs : Create log directories] 2026-09-01 04:01:54.961403 | localhost | changed 2026-09-01 04:01:54.967907 | 2026-09-01 04:01:54.968003 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-01 04:01:55.364769 | localhost -> localhost | ok: Runtime: 0:00:00.005252 2026-09-01 04:01:55.371516 | 2026-09-01 04:01:55.371610 | TASK [upload-logs : Upload logs to log server] 2026-09-01 04:01:55.862424 | localhost | Output suppressed because no_log was given 2026-09-01 04:01:55.900023 | 2026-09-01 04:01:55.900172 | LOOP [upload-logs : Compress console log and json output] 2026-09-01 04:01:55.949524 | localhost | skipping: Conditional result was False 2026-09-01 04:01:55.956422 | localhost | skipping: Conditional result was False 2026-09-01 04:01:55.975339 | 2026-09-01 04:01:55.975535 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-01 04:01:56.019640 | localhost | skipping: Conditional result was False 2026-09-01 04:01:56.020099 | 2026-09-01 04:01:56.023455 | localhost | skipping: Conditional result was False 2026-09-01 04:01:56.040811 | 2026-09-01 04:01:56.040937 | LOOP [upload-logs : Upload console log and json output]