2026-09-12 03:53:26.250943 | Job console starting 2026-09-12 03:53:26.259472 | Updating git repos 2026-09-12 03:53:33.200194 | Cloning repos into workspace 2026-09-12 03:53:50.021871 | Restoring repo states 2026-09-12 03:53:53.173542 | Merging changes 2026-09-12 03:53:54.329532 | Checking out repos 2026-09-12 03:53:56.944711 | Preparing playbooks 2026-09-12 03:54:00.675008 | Running Ansible setup 2026-09-12 03:54:04.155169 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-12 03:54:04.716558 | 2026-09-12 03:54:04.716680 | PLAY [localhost] 2026-09-12 03:54:04.723434 | 2026-09-12 03:54:04.723497 | TASK [Gathering Facts] 2026-09-12 03:54:05.713893 | localhost | ok 2026-09-12 03:54:05.722657 | 2026-09-12 03:54:05.722870 | TASK [Setup log path fact] 2026-09-12 03:54:05.741402 | localhost | ok 2026-09-12 03:54:05.751512 | 2026-09-12 03:54:05.751639 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-12 03:54:05.780983 | localhost | ok 2026-09-12 03:54:05.789417 | 2026-09-12 03:54:05.789506 | TASK [emit-job-header : Print job information] 2026-09-12 03:54:05.830663 | # Job Information 2026-09-12 03:54:05.830918 | Ansible Version: 2.16.18 2026-09-12 03:54:05.830998 | Job: atmosphere-molecule-csi-rbd 2026-09-12 03:54:05.831055 | Pipeline: check 2026-09-12 03:54:05.831109 | Executor: 2d72f0692154 2026-09-12 03:54:05.831159 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4364 2026-09-12 03:54:05.831217 | Event ID: d0b9d560-ae5c-11f1-8573-1bc18484dfc4 2026-09-12 03:54:05.837418 | 2026-09-12 03:54:05.837549 | LOOP [emit-job-header : Print node information] 2026-09-12 03:54:05.920217 | localhost | ok: 2026-09-12 03:54:05.920448 | localhost | # Node Information 2026-09-12 03:54:05.920486 | localhost | Inventory Hostname: instance 2026-09-12 03:54:05.920536 | localhost | Hostname: np0000192674 2026-09-12 03:54:05.920562 | localhost | Username: zuul 2026-09-12 03:54:05.920593 | localhost | Distro: Ubuntu 22.04 2026-09-12 03:54:05.920618 | localhost | Provider: yul1 2026-09-12 03:54:05.920643 | localhost | Region: ca-ymq-1 2026-09-12 03:54:05.920667 | localhost | Label: ubuntu-jammy 2026-09-12 03:54:05.920690 | localhost | Product Name: OpenStack Nova 2026-09-12 03:54:05.920714 | localhost | Interface IP: 162.253.55.217 2026-09-12 03:54:05.949070 | 2026-09-12 03:54:05.949512 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-12 03:54:06.359848 | localhost -> localhost | changed 2026-09-12 03:54:06.370463 | 2026-09-12 03:54:06.370598 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-12 03:54:07.342890 | localhost -> localhost | changed 2026-09-12 03:54:07.442127 | 2026-09-12 03:54:07.442257 | PLAY [all] 2026-09-12 03:54:07.471914 | 2026-09-12 03:54:07.472021 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-12 03:54:07.695132 | instance -> localhost | ok 2026-09-12 03:54:07.701370 | 2026-09-12 03:54:07.789515 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-12 03:54:07.826213 | instance | ok 2026-09-12 03:54:07.844044 | instance | included: /var/lib/zuul/builds/fe109d91ecaf4d55ace9e687ac0f778c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-12 03:54:07.850296 | 2026-09-12 03:54:07.850352 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-12 03:54:09.364766 | instance -> localhost | Generating public/private rsa key pair. 2026-09-12 03:54:09.365046 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/fe109d91ecaf4d55ace9e687ac0f778c/work/fe109d91ecaf4d55ace9e687ac0f778c_id_rsa 2026-09-12 03:54:09.365125 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/fe109d91ecaf4d55ace9e687ac0f778c/work/fe109d91ecaf4d55ace9e687ac0f778c_id_rsa.pub 2026-09-12 03:54:09.365193 | instance -> localhost | The key fingerprint is: 2026-09-12 03:54:09.365247 | instance -> localhost | SHA256:yxozSq4h7AT4aGBrfWsAXFiqUXB7444oBs3vXQ2roag zuul-build-sshkey 2026-09-12 03:54:09.365304 | instance -> localhost | The key's randomart image is: 2026-09-12 03:54:09.365345 | instance -> localhost | +---[RSA 3072]----+ 2026-09-12 03:54:09.365391 | instance -> localhost | |..=. | 2026-09-12 03:54:09.365432 | instance -> localhost | | +.o | 2026-09-12 03:54:09.365471 | instance -> localhost | |o.o o | 2026-09-12 03:54:09.365518 | instance -> localhost | |o* o . | 2026-09-12 03:54:09.365568 | instance -> localhost | |*.+ . S | 2026-09-12 03:54:09.365608 | instance -> localhost | |*+o= . = | 2026-09-12 03:54:09.365645 | instance -> localhost | |=B+.=.= = . | 2026-09-12 03:54:09.365684 | instance -> localhost | |B. *.=.O | 2026-09-12 03:54:09.365752 | instance -> localhost | |Eoo.*.+ | 2026-09-12 03:54:09.365803 | instance -> localhost | +----[SHA256]-----+ 2026-09-12 03:54:09.365895 | instance -> localhost | ok: Runtime: 0:00:01.130732 2026-09-12 03:54:09.381195 | 2026-09-12 03:54:09.381331 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-12 03:54:09.417447 | instance | ok 2026-09-12 03:54:09.433409 | instance | included: /var/lib/zuul/builds/fe109d91ecaf4d55ace9e687ac0f778c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-12 03:54:09.446323 | 2026-09-12 03:54:09.446411 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-12 03:54:09.473562 | instance | skipping: Conditional result was False 2026-09-12 03:54:09.486510 | 2026-09-12 03:54:09.488608 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-12 03:54:09.967825 | instance | changed 2026-09-12 03:54:09.978910 | 2026-09-12 03:54:09.979374 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-12 03:54:10.197816 | instance | ok 2026-09-12 03:54:10.204077 | 2026-09-12 03:54:10.204159 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-12 03:54:10.702100 | instance | changed 2026-09-12 03:54:10.708669 | 2026-09-12 03:54:10.708796 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-12 03:54:11.216092 | instance | changed 2026-09-12 03:54:11.222314 | 2026-09-12 03:54:11.222376 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-12 03:54:11.245553 | instance | skipping: Conditional result was False 2026-09-12 03:54:11.250633 | 2026-09-12 03:54:11.250694 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-12 03:54:11.711104 | instance -> localhost | changed 2026-09-12 03:54:11.725377 | 2026-09-12 03:54:11.725527 | TASK [add-build-sshkey : Add back temp key] 2026-09-12 03:54:12.027465 | instance -> localhost | Identity added: /var/lib/zuul/builds/fe109d91ecaf4d55ace9e687ac0f778c/work/fe109d91ecaf4d55ace9e687ac0f778c_id_rsa (zuul-build-sshkey) 2026-09-12 03:54:12.027639 | instance -> localhost | ok: Runtime: 0:00:00.012790 2026-09-12 03:54:12.031880 | 2026-09-12 03:54:12.031935 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-12 03:54:12.393567 | instance | ok 2026-09-12 03:54:12.397673 | 2026-09-12 03:54:12.397753 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-12 03:54:12.431710 | instance | skipping: Conditional result was False 2026-09-12 03:54:12.442774 | 2026-09-12 03:54:12.442852 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-12 03:54:12.819915 | instance | ok 2026-09-12 03:54:12.830885 | 2026-09-12 03:54:12.831009 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-12 03:54:20.673715 | instance | Output suppressed because no_log was given 2026-09-12 03:54:20.696026 | 2026-09-12 03:54:20.696964 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-12 03:54:20.909781 | instance | ok: "logs" 2026-09-12 03:54:20.910496 | instance | ok: All items complete 2026-09-12 03:54:20.910559 | 2026-09-12 03:54:21.083823 | instance | ok: "artifacts" 2026-09-12 03:54:21.266637 | instance | ok: "docs" 2026-09-12 03:54:21.295688 | 2026-09-12 03:54:21.296761 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-12 03:54:21.550625 | instance | changed: "logs" 2026-09-12 03:54:21.708601 | instance | changed: "artifacts" 2026-09-12 03:54:21.868985 | instance | changed: "docs" 2026-09-12 03:54:21.880145 | 2026-09-12 03:54:21.880253 | PLAY RECAP 2026-09-12 03:54:21.880300 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-12 03:54:21.880324 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-12 03:54:21.880343 | 2026-09-12 03:54:22.085439 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-12 03:54:22.088311 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-12 03:54:22.702194 | 2026-09-12 03:54:22.702323 | PLAY [all] 2026-09-12 03:54:22.712302 | 2026-09-12 03:54:22.712380 | TASK [setup-uv : Extract archive] 2026-09-12 03:54:24.963019 | instance | changed 2026-09-12 03:54:24.968242 | 2026-09-12 03:54:24.968347 | TASK [setup-uv : Print version] 2026-09-12 03:54:25.395220 | instance | uv 0.8.13 2026-09-12 03:54:25.499485 | instance | ok: Runtime: 0:00:00.010637 2026-09-12 03:54:25.517229 | 2026-09-12 03:54:25.517437 | TASK [Install Ansible collection siblings] 2026-09-12 03:54:34.739496 | instance | changed: 2026-09-12 03:54:34.739684 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-09-12 03:54:34.739714 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-09-12 03:54:34.739733 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-09-12 03:54:34.739770 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto 2026-09-12 03:54:34.739789 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general 2026-09-12 03:54:34.739810 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql 2026-09-12 03:54:34.739830 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-09-12 03:54:34.739848 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-09-12 03:54:34.739866 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-09-12 03:54:34.739882 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-09-12 03:54:34.739905 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-09-12 03:54:34.739945 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-09-12 03:54:34.739981 | instance | Resolved ansible.netcommon to v8.6.2 for >=1.2.0 2026-09-12 03:54:34.740001 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0 2026-09-12 03:54:34.740019 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0 2026-09-12 03:54:34.740036 | instance | Resolved community.crypto to 2.2.3 for 2.2.3 2026-09-12 03:54:34.740052 | instance | Resolved community.general to 7.3.0 for 7.3.0 2026-09-12 03:54:34.740068 | instance | Resolved community.mysql to 3.6.0 for 3.6.0 2026-09-12 03:54:34.740084 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0 2026-09-12 03:54:34.740099 | instance | Resolved openstack.cloud to 1.7.0 for 1.7.0 2026-09-12 03:54:34.740115 | instance | Resolved atmosphere.common to v0.6.0 for >=0.6.0,<0.7.0 2026-09-12 03:54:34.740131 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.1.2 2026-09-12 03:54:34.740148 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0 2026-09-12 03:54:34.740179 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0 2026-09-12 03:54:34.740195 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-12 03:54:34.740211 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0 2026-09-12 03:54:34.740226 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0 2026-09-12 03:54:34.740241 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0 2026-09-12 03:54:34.740257 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-12 03:54:34.740274 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0 2026-09-12 03:54:34.740291 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3 2026-09-12 03:54:34.740307 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-12 03:54:34.740323 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-12 03:54:34.740339 | instance | Checked out ansible.netcommon to v8.6.2 2026-09-12 03:54:34.740354 | 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-12 03:54:34.740376 | instance | Starting galaxy collection install process 2026-09-12 03:54:34.740393 | instance | Process install dependency map 2026-09-12 03:54:34.740409 | instance | Starting collection install process 2026-09-12 03:54:34.740424 | instance | Installing 'ansible.netcommon:8.6.2' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-09-12 03:54:34.740440 | instance | Created collection for ansible.netcommon:8.6.2 at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon 2026-09-12 03:54:34.740455 | instance | ansible.netcommon:8.6.2 was installed successfully 2026-09-12 03:54:34.740470 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-09-12 03:54:34.740485 | instance | Creating virtual environment at: .venv 2026-09-12 03:54:34.740526 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-12 03:54:34.740545 | instance | Downloading netaddr (2.2MiB) 2026-09-12 03:54:34.740565 | instance | Downloading cryptography (4.2MiB) 2026-09-12 03:54:34.740581 | instance | Downloading setuptools (1.1MiB) 2026-09-12 03:54:34.740597 | instance | Downloading pygments (1.2MiB) 2026-09-12 03:54:34.740612 | instance | Downloading kubernetes (1.9MiB) 2026-09-12 03:54:34.740627 | instance | Downloading openstacksdk (1.7MiB) 2026-09-12 03:54:34.740643 | instance | Downloading rjsonnet (1.2MiB) 2026-09-12 03:54:34.740658 | instance | Downloading ansible-core (2.1MiB) 2026-09-12 03:54:34.740673 | instance | Building pyperclip==1.9.0 2026-09-12 03:54:34.740690 | instance | Downloading rjsonnet 2026-09-12 03:54:34.740706 | instance | Downloading netaddr 2026-09-12 03:54:34.740722 | instance | Downloading pygments 2026-09-12 03:54:34.740751 | instance | Downloading cryptography 2026-09-12 03:54:34.740768 | instance | Downloading setuptools 2026-09-12 03:54:34.740784 | instance | Downloading kubernetes 2026-09-12 03:54:34.740798 | instance | Downloading ansible-core 2026-09-12 03:54:34.740814 | instance | Downloading openstacksdk 2026-09-12 03:54:34.740829 | instance | Built pyperclip==1.9.0 2026-09-12 03:54:34.740844 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-12 03:54:34.740860 | instance | Installed 79 packages in 49ms 2026-09-12 03:54:34.740875 | instance | Checked out ansible.posix to 1.6.0 2026-09-12 03:54:34.740890 | 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-12 03:54:34.740906 | instance | Starting galaxy collection install process 2026-09-12 03:54:34.740921 | instance | Process install dependency map 2026-09-12 03:54:34.740942 | instance | Starting collection install process 2026-09-12 03:54:34.740958 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-09-12 03:54:34.740973 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix 2026-09-12 03:54:34.740989 | instance | ansible.posix:1.6.0 was installed successfully 2026-09-12 03:54:34.741004 | instance | Checked out ansible.utils to v6.1.0 2026-09-12 03:54:34.741019 | 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-12 03:54:34.741035 | instance | Starting galaxy collection install process 2026-09-12 03:54:34.741050 | instance | Process install dependency map 2026-09-12 03:54:34.741065 | instance | Starting collection install process 2026-09-12 03:54:34.741080 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-09-12 03:54:34.741096 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils 2026-09-12 03:54:34.741111 | instance | ansible.utils:6.1.0 was installed successfully 2026-09-12 03:54:34.741126 | instance | Checked out atmosphere.common to v0.6.0 2026-09-12 03:54:34.741142 | 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-12 03:54:34.741158 | instance | Starting galaxy collection install process 2026-09-12 03:54:34.741174 | instance | Process install dependency map 2026-09-12 03:54:34.741190 | instance | Starting collection install process 2026-09-12 03:54:34.741205 | instance | Installing 'atmosphere.common:0.6.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common' 2026-09-12 03:54:34.741221 | instance | Created collection for atmosphere.common:0.6.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common 2026-09-12 03:54:34.741236 | instance | atmosphere.common:0.6.0 was installed successfully 2026-09-12 03:54:34.741251 | instance | Checked out community.crypto to 2.2.3 2026-09-12 03:54:34.741267 | 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-12 03:54:34.741282 | instance | Starting galaxy collection install process 2026-09-12 03:54:34.741297 | instance | Process install dependency map 2026-09-12 03:54:34.741312 | instance | Starting collection install process 2026-09-12 03:54:34.741328 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-09-12 03:54:34.741343 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto 2026-09-12 03:54:34.741357 | instance | community.crypto:2.2.3 was installed successfully 2026-09-12 03:54:34.741373 | instance | Checked out community.general to 7.3.0 2026-09-12 03:54:34.741389 | 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-12 03:54:34.741405 | instance | Starting galaxy collection install process 2026-09-12 03:54:34.741419 | instance | Process install dependency map 2026-09-12 03:54:34.741435 | instance | Starting collection install process 2026-09-12 03:54:34.741450 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-09-12 03:54:34.741465 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general 2026-09-12 03:54:34.741480 | instance | community.general:7.3.0 was installed successfully 2026-09-12 03:54:34.741499 | instance | Checked out community.mysql to 3.6.0 2026-09-12 03:54:34.741516 | 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-12 03:54:34.741531 | instance | Starting galaxy collection install process 2026-09-12 03:54:34.741546 | instance | Process install dependency map 2026-09-12 03:54:34.741561 | instance | Starting collection install process 2026-09-12 03:54:34.741576 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-09-12 03:54:34.741592 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql 2026-09-12 03:54:34.741606 | instance | community.mysql:3.6.0 was installed successfully 2026-09-12 03:54:34.741622 | instance | Checked out kubernetes.core to 2.4.0 2026-09-12 03:54:34.741637 | 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-12 03:54:34.741653 | instance | Starting galaxy collection install process 2026-09-12 03:54:34.741669 | instance | Process install dependency map 2026-09-12 03:54:34.741686 | instance | Starting collection install process 2026-09-12 03:54:34.741702 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-09-12 03:54:34.741718 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core 2026-09-12 03:54:34.741733 | instance | kubernetes.core:2.4.0 was installed successfully 2026-09-12 03:54:34.741764 | instance | Checked out openstack.cloud to 1.7.0 2026-09-12 03:54:34.741781 | 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-12 03:54:34.741797 | instance | Starting galaxy collection install process 2026-09-12 03:54:34.741812 | instance | Process install dependency map 2026-09-12 03:54:34.742160 | instance | Starting collection install process 2026-09-12 03:54:34.742188 | instance | Installing 'openstack.cloud:1.7.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-09-12 03:54:34.742206 | instance | Created collection for openstack.cloud:1.7.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud 2026-09-12 03:54:34.742332 | instance | openstack.cloud:1.7.0 was installed successfully 2026-09-12 03:54:34.742402 | instance | Checked out vexxhost.ceph to v4.2.0 2026-09-12 03:54:34.742426 | 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-12 03:54:34.742444 | instance | Starting galaxy collection install process 2026-09-12 03:54:34.742460 | instance | Process install dependency map 2026-09-12 03:54:34.742476 | instance | Starting collection install process 2026-09-12 03:54:34.742492 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph' 2026-09-12 03:54:34.742507 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph 2026-09-12 03:54:34.742522 | instance | vexxhost.ceph:4.2.0 was installed successfully 2026-09-12 03:54:34.742538 | instance | Checked out vexxhost.containers to v1.6.8 2026-09-12 03:54:34.742553 | 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-12 03:54:34.742569 | instance | Starting galaxy collection install process 2026-09-12 03:54:34.742590 | instance | Process install dependency map 2026-09-12 03:54:34.742607 | instance | Starting collection install process 2026-09-12 03:54:34.742622 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers' 2026-09-12 03:54:34.742638 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers 2026-09-12 03:54:34.742653 | instance | vexxhost.containers:1.6.8 was installed successfully 2026-09-12 03:54:34.742669 | instance | Checked out vexxhost.kubernetes to v3.4.0 2026-09-12 03:54:34.742684 | 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-12 03:54:34.742699 | instance | Starting galaxy collection install process 2026-09-12 03:54:34.742715 | instance | Process install dependency map 2026-09-12 03:54:34.742730 | instance | Starting collection install process 2026-09-12 03:54:34.742763 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes' 2026-09-12 03:54:34.742779 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes 2026-09-12 03:54:34.742795 | instance | vexxhost.kubernetes:3.4.0 was installed successfully 2026-09-12 03:54:34.747517 | 2026-09-12 03:54:34.747561 | PLAY RECAP 2026-09-12 03:54:34.747601 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-12 03:54:34.747619 | 2026-09-12 03:54:34.880608 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-12 03:54:34.929961 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-12 03:54:35.493476 | 2026-09-12 03:54:35.493606 | PLAY [all] 2026-09-12 03:54:35.503622 | 2026-09-12 03:54:35.503691 | TASK [Install "jq" for log collection] 2026-09-12 03:54:44.730110 | instance | changed 2026-09-12 03:54:44.803379 | 2026-09-12 03:54:44.803567 | PLAY RECAP 2026-09-12 03:54:44.803890 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-12 03:54:44.804000 | 2026-09-12 03:54:44.953375 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-12 03:54:44.956045 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-12 03:54:45.506825 | 2026-09-12 03:54:45.506964 | PLAY [all] 2026-09-12 03:54:45.517129 | 2026-09-12 03:54:45.517226 | LOOP [Check inventory directories] 2026-09-12 03:54:45.878062 | instance | ok: "src/github.com/vexxhost/atmosphere" 2026-09-12 03:54:46.029883 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory" 2026-09-12 03:54:46.208958 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory" 2026-09-12 03:54:46.234495 | 2026-09-12 03:54:46.235080 | LOOP [Copy inventory file for Zuul] 2026-09-12 03:54:47.110892 | instance | changed: 2026-09-12 03:54:47.111174 | instance | { 2026-09-12 03:54:47.111234 | instance | "ansible_loop_var": "item", 2026-09-12 03:54:47.111278 | instance | "changed": false, 2026-09-12 03:54:47.111316 | instance | "failed": false, 2026-09-12 03:54:47.111359 | instance | "invocation": { 2026-09-12 03:54:47.111395 | instance | "module_args": { 2026-09-12 03:54:47.111430 | instance | "checksum_algorithm": "sha1", 2026-09-12 03:54:47.111464 | instance | "follow": true, 2026-09-12 03:54:47.111498 | instance | "get_attributes": true, 2026-09-12 03:54:47.111534 | instance | "get_checksum": true, 2026-09-12 03:54:47.111568 | instance | "get_mime": true, 2026-09-12 03:54:47.111688 | instance | "path": "src/github.com/vexxhost/atmosphere" 2026-09-12 03:54:47.111852 | instance | } 2026-09-12 03:54:47.111917 | instance | }, 2026-09-12 03:54:47.111956 | instance | "item": "src/github.com/vexxhost/atmosphere", 2026-09-12 03:54:47.111991 | instance | "stat": { 2026-09-12 03:54:47.112023 | instance | "atime": 1789185267.6063087, 2026-09-12 03:54:47.112058 | instance | "attr_flags": "e", 2026-09-12 03:54:47.112092 | instance | "attributes": [ 2026-09-12 03:54:47.112124 | instance | "extents" 2026-09-12 03:54:47.112157 | instance | ], 2026-09-12 03:54:47.112189 | instance | "block_size": 4096, 2026-09-12 03:54:47.112220 | instance | "blocks": 8, 2026-09-12 03:54:47.112252 | instance | "charset": "unknown", 2026-09-12 03:54:47.112288 | instance | "ctime": 1789185266.4142928, 2026-09-12 03:54:47.112321 | instance | "dev": 64513, 2026-09-12 03:54:47.112354 | instance | "device_type": 0, 2026-09-12 03:54:47.112387 | instance | "executable": true, 2026-09-12 03:54:47.112419 | instance | "exists": true, 2026-09-12 03:54:47.112451 | instance | "gid": 1000, 2026-09-12 03:54:47.112483 | instance | "gr_name": "zuul", 2026-09-12 03:54:47.112548 | instance | "inode": 1049000, 2026-09-12 03:54:47.112581 | instance | "isblk": false, 2026-09-12 03:54:47.112614 | instance | "ischr": false, 2026-09-12 03:54:47.112647 | instance | "isdir": true, 2026-09-12 03:54:47.112680 | instance | "isfifo": false, 2026-09-12 03:54:47.112712 | instance | "isgid": false, 2026-09-12 03:54:47.112768 | instance | "islnk": false, 2026-09-12 03:54:47.112806 | instance | "isreg": false, 2026-09-12 03:54:47.112839 | instance | "issock": false, 2026-09-12 03:54:47.112872 | instance | "isuid": false, 2026-09-12 03:54:47.112904 | instance | "mimetype": "unknown", 2026-09-12 03:54:47.112936 | instance | "mode": "0755", 2026-09-12 03:54:47.112967 | instance | "mtime": 1789185266.4142928, 2026-09-12 03:54:47.112999 | instance | "nlink": 21, 2026-09-12 03:54:47.113030 | instance | "path": "src/github.com/vexxhost/atmosphere", 2026-09-12 03:54:47.113061 | instance | "pw_name": "zuul", 2026-09-12 03:54:47.113102 | instance | "readable": true, 2026-09-12 03:54:47.113207 | instance | "rgrp": true, 2026-09-12 03:54:47.113267 | instance | "roth": true, 2026-09-12 03:54:47.113305 | instance | "rusr": true, 2026-09-12 03:54:47.113340 | instance | "size": 4096, 2026-09-12 03:54:47.113373 | instance | "uid": 1000, 2026-09-12 03:54:47.113406 | instance | "version": "2682620178", 2026-09-12 03:54:47.113439 | instance | "wgrp": false, 2026-09-12 03:54:47.113476 | instance | "woth": false, 2026-09-12 03:54:47.113510 | instance | "writeable": true, 2026-09-12 03:54:47.113543 | instance | "wusr": true, 2026-09-12 03:54:47.113575 | instance | "xgrp": true, 2026-09-12 03:54:47.113608 | instance | "xoth": true, 2026-09-12 03:54:47.113641 | instance | "xusr": true 2026-09-12 03:54:47.113674 | instance | } 2026-09-12 03:54:47.113706 | instance | } 2026-09-12 03:54:47.113803 | instance | ok: All items complete 2026-09-12 03:54:47.113847 | 2026-09-12 03:54:47.116370 | instance | skipping: Conditional result was False 2026-09-12 03:54:47.117618 | instance | skipping: Conditional result was False 2026-09-12 03:54:47.141521 | 2026-09-12 03:54:47.141675 | LOOP [Switch "ansible_host" to private IP] 2026-09-12 03:54:47.502093 | instance | changed: 1 replacements made 2026-09-12 03:54:47.508288 | instance | skipping: Conditional result was False 2026-09-12 03:54:47.514184 | instance | skipping: Conditional result was False 2026-09-12 03:54:47.536476 | 2026-09-12 03:54:47.536664 | TASK [Run Molecule scenario] 2026-09-12 03:54:48.541342 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-09-12 03:54:48.981717 | instance | INFO [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-12 03:54:48.981818 | instance | INFO [csi > prerun] Performing prerun with role_name_check=0... 2026-09-12 03:54:59.575359 | instance | INFO [csi > dependency] Executing 2026-09-12 03:54:59.575870 | instance | WARNING [csi > dependency] Missing roles requirements file: requirements.yml 2026-09-12 03:54:59.576307 | instance | WARNING [csi > dependency] Missing collections requirements file: collections.yml 2026-09-12 03:54:59.576555 | instance | WARNING [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-09-12 03:54:59.592172 | instance | INFO [csi > cleanup] Executing 2026-09-12 03:54:59.592810 | instance | WARNING [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-12 03:54:59.605274 | instance | INFO [csi > destroy] Executing 2026-09-12 03:54:59.605314 | instance | WARNING [csi > destroy] Skipping, '--destroy=never' requested. 2026-09-12 03:54:59.605419 | instance | INFO [csi > destroy] Executed: Successful 2026-09-12 03:54:59.614429 | instance | INFO [csi > syntax] Executing 2026-09-12 03:55:01.235462 | instance | 2026-09-12 03:55:01.235652 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-12 03:55:01.303659 | instance | INFO [csi > syntax] Executed: Successful 2026-09-12 03:55:01.319615 | instance | INFO [csi > create] Executing 2026-09-12 03:55:01.326231 | instance | WARNING [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-12 03:55:01.341157 | instance | INFO [csi > prepare] Executing 2026-09-12 03:55:02.058765 | instance | 2026-09-12 03:55:02.059009 | instance | PLAY [Prepare] ***************************************************************** 2026-09-12 03:55:02.059258 | instance | 2026-09-12 03:55:02.059484 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-12 03:55:02.059759 | instance | Saturday 12 September 2026 03:55:02 +0000 (0:00:00.023) 0:00:00.023 **** 2026-09-12 03:55:03.320185 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-12 03:55:03.320422 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-12 03:55:03.320701 | instance | interpreter could change the meaning of that path. See 2026-09-12 03:55:03.320948 | instance | https://docs.ansible.com/ansible- 2026-09-12 03:55:03.321198 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-12 03:55:03.330192 | instance | ok: [instance] 2026-09-12 03:55:03.330394 | instance | 2026-09-12 03:55:03.330701 | instance | TASK [Configure short hostname] ************************************************ 2026-09-12 03:55:03.330940 | instance | Saturday 12 September 2026 03:55:03 +0000 (0:00:01.272) 0:00:01.295 **** 2026-09-12 03:55:04.109685 | instance | changed: [instance] 2026-09-12 03:55:04.109763 | instance | 2026-09-12 03:55:04.109860 | instance | TASK [Ensure hostname inside hosts file] *************************************** 2026-09-12 03:55:04.110183 | instance | Saturday 12 September 2026 03:55:04 +0000 (0:00:00.779) 0:00:02.075 **** 2026-09-12 03:55:04.430817 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created 2026-09-12 03:55:04.431047 | instance | with a mode of 0700, this may cause issues when running as another user. To 2026-09-12 03:55:04.431288 | instance | avoid this, create the remote_tmp dir with the correct permissions manually 2026-09-12 03:55:04.440738 | instance | changed: [instance] 2026-09-12 03:55:04.441010 | instance | 2026-09-12 03:55:04.441288 | instance | TASK [Purge "snapd" package] *************************************************** 2026-09-12 03:55:04.441590 | instance | Saturday 12 September 2026 03:55:04 +0000 (0:00:00.330) 0:00:02.405 **** 2026-09-12 03:55:05.415224 | instance | ok: [instance] 2026-09-12 03:55:05.415458 | instance | 2026-09-12 03:55:05.415716 | instance | PLAY [Create devices for Ceph] ************************************************* 2026-09-12 03:55:05.415930 | instance | 2026-09-12 03:55:05.416204 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-12 03:55:05.416442 | instance | Saturday 12 September 2026 03:55:05 +0000 (0:00:00.974) 0:00:03.380 **** 2026-09-12 03:55:06.198256 | instance | ok: [instance] 2026-09-12 03:55:06.198446 | instance | 2026-09-12 03:55:06.198750 | instance | TASK [Install depedencies] ***************************************************** 2026-09-12 03:55:06.198983 | instance | Saturday 12 September 2026 03:55:06 +0000 (0:00:00.782) 0:00:04.162 **** 2026-09-12 03:55:30.232516 | instance | changed: [instance] 2026-09-12 03:55:30.232760 | instance | 2026-09-12 03:55:30.233032 | instance | TASK [Start up service] ******************************************************** 2026-09-12 03:55:30.233284 | instance | Saturday 12 September 2026 03:55:30 +0000 (0:00:24.034) 0:00:28.197 **** 2026-09-12 03:55:30.852880 | instance | ok: [instance] 2026-09-12 03:55:30.852953 | instance | 2026-09-12 03:55:30.853128 | instance | TASK [Generate lvm.conf] ******************************************************* 2026-09-12 03:55:30.853411 | instance | Saturday 12 September 2026 03:55:30 +0000 (0:00:00.620) 0:00:28.817 **** 2026-09-12 03:55:31.189004 | instance | ok: [instance] 2026-09-12 03:55:31.189188 | instance | 2026-09-12 03:55:31.189515 | instance | TASK [Write /etc/lvm/lvm.conf] ************************************************* 2026-09-12 03:55:31.189726 | instance | Saturday 12 September 2026 03:55:31 +0000 (0:00:00.336) 0:00:29.153 **** 2026-09-12 03:55:31.972581 | instance | changed: [instance] 2026-09-12 03:55:31.972663 | instance | 2026-09-12 03:55:31.972679 | instance | TASK [Get list of all loopback devices] **************************************** 2026-09-12 03:55:31.972692 | instance | Saturday 12 September 2026 03:55:31 +0000 (0:00:00.782) 0:00:29.936 **** 2026-09-12 03:55:32.184559 | instance | ok: [instance] 2026-09-12 03:55:32.185443 | instance | 2026-09-12 03:55:32.185494 | instance | TASK [Fail if there is any existing loopback devices] ************************** 2026-09-12 03:55:32.185506 | instance | Saturday 12 September 2026 03:55:32 +0000 (0:00:00.212) 0:00:30.149 **** 2026-09-12 03:55:32.213993 | instance | skipping: [instance] 2026-09-12 03:55:32.214486 | instance | 2026-09-12 03:55:32.214535 | instance | TASK [Create devices for Ceph] ************************************************* 2026-09-12 03:55:32.214543 | instance | Saturday 12 September 2026 03:55:32 +0000 (0:00:00.030) 0:00:30.179 **** 2026-09-12 03:55:32.821126 | instance | changed: [instance] => (item=osd0) 2026-09-12 03:55:32.821181 | instance | changed: [instance] => (item=osd1) 2026-09-12 03:55:32.821196 | instance | changed: [instance] => (item=osd2) 2026-09-12 03:55:32.821205 | instance | 2026-09-12 03:55:32.821215 | instance | TASK [Set permissions on loopback devices] ************************************* 2026-09-12 03:55:32.821225 | instance | Saturday 12 September 2026 03:55:32 +0000 (0:00:00.605) 0:00:30.784 **** 2026-09-12 03:55:33.517666 | instance | changed: [instance] => (item=osd0) 2026-09-12 03:55:33.518657 | instance | changed: [instance] => (item=osd1) 2026-09-12 03:55:33.518714 | instance | changed: [instance] => (item=osd2) 2026-09-12 03:55:33.518721 | instance | 2026-09-12 03:55:33.518728 | instance | TASK [Start loop devices] ****************************************************** 2026-09-12 03:55:33.518889 | instance | Saturday 12 September 2026 03:55:33 +0000 (0:00:00.697) 0:00:31.482 **** 2026-09-12 03:55:34.318393 | instance | changed: [instance] => (item=osd0) 2026-09-12 03:55:34.318453 | instance | changed: [instance] => (item=osd1) 2026-09-12 03:55:34.318464 | instance | changed: [instance] => (item=osd2) 2026-09-12 03:55:34.318474 | instance | 2026-09-12 03:55:34.318483 | instance | TASK [Create a volume group for each loop device] ****************************** 2026-09-12 03:55:34.318493 | instance | Saturday 12 September 2026 03:55:34 +0000 (0:00:00.799) 0:00:32.281 **** 2026-09-12 03:55:37.744779 | instance | changed: [instance] => (item=osd0) 2026-09-12 03:55:37.745699 | instance | changed: [instance] => (item=osd1) 2026-09-12 03:55:37.745750 | instance | changed: [instance] => (item=osd2) 2026-09-12 03:55:37.745757 | instance | 2026-09-12 03:55:37.745765 | instance | TASK [Create a logical volume for each loop device] **************************** 2026-09-12 03:55:37.745771 | instance | Saturday 12 September 2026 03:55:37 +0000 (0:00:03.428) 0:00:35.710 **** 2026-09-12 03:55:39.755545 | instance | changed: [instance] => (item=ceph-instance-osd0) 2026-09-12 03:55:39.756635 | instance | changed: [instance] => (item=ceph-instance-osd1) 2026-09-12 03:55:39.756697 | instance | changed: [instance] => (item=ceph-instance-osd2) 2026-09-12 03:55:39.756713 | instance | 2026-09-12 03:55:39.756727 | instance | PLAY RECAP ********************************************************************* 2026-09-12 03:55:39.756746 | instance | instance : ok=15 changed=9 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-09-12 03:55:39.757807 | instance | 2026-09-12 03:55:39.757876 | instance | Saturday 12 September 2026 03:55:39 +0000 (0:00:02.010) 0:00:37.721 **** 2026-09-12 03:55:39.757888 | instance | =============================================================================== 2026-09-12 03:55:39.757897 | instance | Install depedencies ---------------------------------------------------- 24.03s 2026-09-12 03:55:39.757913 | instance | Create a volume group for each loop device ------------------------------ 3.43s 2026-09-12 03:55:39.758630 | instance | Create a logical volume for each loop device ---------------------------- 2.01s 2026-09-12 03:55:39.758648 | instance | Gathering Facts --------------------------------------------------------- 1.27s 2026-09-12 03:55:39.758657 | instance | Purge "snapd" package --------------------------------------------------- 0.97s 2026-09-12 03:55:39.761105 | instance | Start loop devices ------------------------------------------------------ 0.80s 2026-09-12 03:55:39.761165 | instance | Gathering Facts --------------------------------------------------------- 0.78s 2026-09-12 03:55:39.761176 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.78s 2026-09-12 03:55:39.761186 | instance | Configure short hostname ------------------------------------------------ 0.78s 2026-09-12 03:55:39.761194 | instance | Set permissions on loopback devices ------------------------------------- 0.70s 2026-09-12 03:55:39.761203 | instance | Start up service -------------------------------------------------------- 0.62s 2026-09-12 03:55:39.761212 | instance | Create devices for Ceph ------------------------------------------------- 0.61s 2026-09-12 03:55:39.761221 | instance | Generate lvm.conf ------------------------------------------------------- 0.34s 2026-09-12 03:55:39.761231 | instance | Ensure hostname inside hosts file --------------------------------------- 0.33s 2026-09-12 03:55:39.761240 | instance | Get list of all loopback devices ---------------------------------------- 0.21s 2026-09-12 03:55:39.761249 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s 2026-09-12 03:55:39.848864 | instance | INFO [csi > prepare] Executed: Successful 2026-09-12 03:55:39.864154 | instance | INFO [csi > converge] Executing 2026-09-12 03:55:41.251453 | instance | 2026-09-12 03:55:41.251867 | instance | PLAY [Debug CSI driver value] ************************************************** 2026-09-12 03:55:41.252178 | instance | 2026-09-12 03:55:41.252473 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-12 03:55:41.252765 | instance | Saturday 12 September 2026 03:55:41 +0000 (0:00:00.007) 0:00:00.007 **** 2026-09-12 03:55:42.366489 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-12 03:55:42.367107 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-12 03:55:42.367549 | instance | interpreter could change the meaning of that path. See 2026-09-12 03:55:42.367988 | instance | https://docs.ansible.com/ansible- 2026-09-12 03:55:42.368392 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-12 03:55:42.381512 | instance | ok: [instance] 2026-09-12 03:55:42.381817 | instance | 2026-09-12 03:55:42.382152 | instance | TASK [Display CSI driver value and environment variable] *********************** 2026-09-12 03:55:42.382448 | instance | Saturday 12 September 2026 03:55:42 +0000 (0:00:01.130) 0:00:01.137 **** 2026-09-12 03:55:42.447254 | instance | ok: [instance] => { 2026-09-12 03:55:42.447770 | instance | "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER=" 2026-09-12 03:55:42.448136 | instance | } 2026-09-12 03:55:42.448453 | instance | 2026-09-12 03:55:42.448768 | instance | PLAY [all] ********************************************************************* 2026-09-12 03:55:42.449141 | instance | 2026-09-12 03:55:42.449580 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-12 03:55:42.450029 | instance | Saturday 12 September 2026 03:55:42 +0000 (0:00:00.065) 0:00:01.203 **** 2026-09-12 03:55:43.453045 | instance | ok: [instance] 2026-09-12 03:55:43.453109 | instance | 2026-09-12 03:55:43.453121 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] ***************** 2026-09-12 03:55:43.453131 | instance | Saturday 12 September 2026 03:55:43 +0000 (0:00:01.005) 0:00:02.208 **** 2026-09-12 03:55:43.625969 | instance | ok: [instance] 2026-09-12 03:55:43.626061 | instance | 2026-09-12 03:55:43.626098 | instance | PLAY [Deploy Ceph monitors & managers] ***************************************** 2026-09-12 03:55:43.626226 | instance | 2026-09-12 03:55:43.626412 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-12 03:55:43.626549 | instance | Saturday 12 September 2026 03:55:43 +0000 (0:00:00.174) 0:00:02.383 **** 2026-09-12 03:55:44.626664 | instance | ok: [instance] 2026-09-12 03:55:44.628731 | instance | 2026-09-12 03:55:44.628751 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-12 03:55:44.628769 | instance | Saturday 12 September 2026 03:55:44 +0000 (0:00:00.999) 0:00:03.382 **** 2026-09-12 03:55:44.944935 | instance | ok: [instance] 2026-09-12 03:55:44.945049 | instance | 2026-09-12 03:55:44.945417 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-12 03:55:44.945479 | instance | Saturday 12 September 2026 03:55:44 +0000 (0:00:00.318) 0:00:03.701 **** 2026-09-12 03:55:45.005167 | instance | skipping: [instance] 2026-09-12 03:55:45.005931 | instance | 2026-09-12 03:55:45.005988 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-09-12 03:55:45.006002 | instance | Saturday 12 September 2026 03:55:44 +0000 (0:00:00.060) 0:00:03.761 **** 2026-09-12 03:55:45.343781 | instance | changed: [instance] 2026-09-12 03:55:45.344688 | instance | 2026-09-12 03:55:45.344725 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-12 03:55:45.344739 | instance | Saturday 12 September 2026 03:55:45 +0000 (0:00:00.338) 0:00:04.100 **** 2026-09-12 03:55:45.429833 | instance | ok: [instance] => { 2026-09-12 03:55:45.430175 | instance | "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64" 2026-09-12 03:55:45.430570 | instance | } 2026-09-12 03:55:45.431007 | instance | 2026-09-12 03:55:45.431391 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-12 03:55:45.431758 | instance | Saturday 12 September 2026 03:55:45 +0000 (0:00:00.086) 0:00:04.186 **** 2026-09-12 03:55:46.027885 | instance | changed: [instance] 2026-09-12 03:55:46.028166 | instance | 2026-09-12 03:55:46.028588 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-12 03:55:46.029024 | instance | Saturday 12 September 2026 03:55:46 +0000 (0:00:00.597) 0:00:04.784 **** 2026-09-12 03:55:46.095171 | instance | skipping: [instance] 2026-09-12 03:55:46.095233 | instance | 2026-09-12 03:55:46.095376 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-12 03:55:46.095572 | instance | Saturday 12 September 2026 03:55:46 +0000 (0:00:00.066) 0:00:04.850 **** 2026-09-12 03:55:46.158958 | instance | skipping: [instance] 2026-09-12 03:55:46.159082 | instance | 2026-09-12 03:55:46.159216 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-12 03:55:46.159411 | instance | Saturday 12 September 2026 03:55:46 +0000 (0:00:00.063) 0:00:04.914 **** 2026-09-12 03:55:46.411391 | instance | ok: [instance] 2026-09-12 03:55:46.411727 | instance | 2026-09-12 03:55:46.412064 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-12 03:55:46.412363 | instance | Saturday 12 September 2026 03:55:46 +0000 (0:00:00.251) 0:00:05.165 **** 2026-09-12 03:55:47.720087 | instance | ok: [instance] 2026-09-12 03:55:47.720232 | instance | 2026-09-12 03:55:47.720241 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-12 03:55:47.720247 | instance | Saturday 12 September 2026 03:55:47 +0000 (0:00:01.310) 0:00:06.476 **** 2026-09-12 03:55:47.802362 | instance | ok: [instance] => { 2026-09-12 03:55:47.802405 | instance | "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz" 2026-09-12 03:55:47.802422 | instance | } 2026-09-12 03:55:47.802427 | instance | 2026-09-12 03:55:47.802432 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-12 03:55:47.802436 | instance | Saturday 12 September 2026 03:55:47 +0000 (0:00:00.082) 0:00:06.558 **** 2026-09-12 03:55:48.429996 | instance | changed: [instance] 2026-09-12 03:55:48.430648 | instance | 2026-09-12 03:55:48.430697 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-12 03:55:48.430801 | instance | Saturday 12 September 2026 03:55:48 +0000 (0:00:00.627) 0:00:07.186 **** 2026-09-12 03:55:51.355528 | instance | changed: [instance] 2026-09-12 03:55:51.355595 | instance | 2026-09-12 03:55:51.355604 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] *************** 2026-09-12 03:55:51.355611 | instance | Saturday 12 September 2026 03:55:51 +0000 (0:00:02.925) 0:00:10.111 **** 2026-09-12 03:55:51.392566 | instance | skipping: [instance] 2026-09-12 03:55:51.392594 | instance | 2026-09-12 03:55:51.392600 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] *** 2026-09-12 03:55:51.392606 | instance | Saturday 12 September 2026 03:55:51 +0000 (0:00:00.037) 0:00:10.149 **** 2026-09-12 03:55:51.435845 | instance | skipping: [instance] 2026-09-12 03:55:51.436709 | instance | 2026-09-12 03:55:51.436793 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ******** 2026-09-12 03:55:51.436802 | instance | Saturday 12 September 2026 03:55:51 +0000 (0:00:00.042) 0:00:10.192 **** 2026-09-12 03:55:51.478593 | instance | skipping: [instance] 2026-09-12 03:55:51.479245 | instance | 2026-09-12 03:55:51.479300 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] ************** 2026-09-12 03:55:51.479309 | instance | Saturday 12 September 2026 03:55:51 +0000 (0:00:00.042) 0:00:10.234 **** 2026-09-12 03:55:57.386200 | instance | changed: [instance] 2026-09-12 03:55:57.386691 | instance | 2026-09-12 03:55:57.387140 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] *** 2026-09-12 03:55:57.387165 | instance | Saturday 12 September 2026 03:55:57 +0000 (0:00:05.908) 0:00:16.142 **** 2026-09-12 03:55:58.039989 | instance | changed: [instance] 2026-09-12 03:55:58.040043 | instance | 2026-09-12 03:55:58.040054 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] ******* 2026-09-12 03:55:58.040064 | instance | Saturday 12 September 2026 03:55:58 +0000 (0:00:00.653) 0:00:16.795 **** 2026-09-12 03:55:59.050759 | instance | changed: [instance] => (item={'path': '/etc/containerd'}) 2026-09-12 03:55:59.050983 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'}) 2026-09-12 03:55:59.051216 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'}) 2026-09-12 03:55:59.051459 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'}) 2026-09-12 03:55:59.051718 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'}) 2026-09-12 03:55:59.051933 | instance | 2026-09-12 03:55:59.052176 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] ********** 2026-09-12 03:55:59.052409 | instance | Saturday 12 September 2026 03:55:59 +0000 (0:00:01.011) 0:00:17.807 **** 2026-09-12 03:55:59.694333 | instance | changed: [instance] 2026-09-12 03:55:59.694644 | instance | 2026-09-12 03:55:59.694950 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ******** 2026-09-12 03:55:59.695236 | instance | Saturday 12 September 2026 03:55:59 +0000 (0:00:00.615) 0:00:18.422 **** 2026-09-12 03:55:59.695519 | instance | 2026-09-12 03:55:59.695845 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-12 03:55:59.696152 | instance | Saturday 12 September 2026 03:55:59 +0000 (0:00:00.028) 0:00:18.450 **** 2026-09-12 03:56:00.647960 | instance | ok: [instance] 2026-09-12 03:56:00.648019 | instance | 2026-09-12 03:56:00.648251 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] ********** 2026-09-12 03:56:00.648267 | instance | Saturday 12 September 2026 03:56:00 +0000 (0:00:00.954) 0:00:19.404 **** 2026-09-12 03:56:01.150695 | instance | changed: [instance] 2026-09-12 03:56:01.150789 | instance | 2026-09-12 03:56:01.150801 | instance | TASK [vexxhost.containers.containerd : Enable and start service] *************** 2026-09-12 03:56:01.150816 | instance | Saturday 12 September 2026 03:56:01 +0000 (0:00:00.501) 0:00:19.906 **** 2026-09-12 03:56:01.791081 | instance | changed: [instance] 2026-09-12 03:56:01.791154 | instance | 2026-09-12 03:56:01.791512 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-12 03:56:01.791558 | instance | Saturday 12 September 2026 03:56:01 +0000 (0:00:00.641) 0:00:20.547 **** 2026-09-12 03:56:02.011281 | instance | ok: [instance] 2026-09-12 03:56:02.012008 | instance | 2026-09-12 03:56:02.012071 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-12 03:56:02.012083 | instance | Saturday 12 September 2026 03:56:02 +0000 (0:00:00.219) 0:00:20.767 **** 2026-09-12 03:56:02.088394 | instance | ok: [instance] => { 2026-09-12 03:56:02.089627 | instance | "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz" 2026-09-12 03:56:02.089687 | instance | } 2026-09-12 03:56:02.089700 | instance | 2026-09-12 03:56:02.089709 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-12 03:56:02.089719 | instance | Saturday 12 September 2026 03:56:02 +0000 (0:00:00.077) 0:00:20.844 **** 2026-09-12 03:56:03.949158 | instance | changed: [instance] 2026-09-12 03:56:03.949906 | instance | 2026-09-12 03:56:03.949966 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-12 03:56:03.949980 | instance | Saturday 12 September 2026 03:56:03 +0000 (0:00:01.860) 0:00:22.705 **** 2026-09-12 03:56:08.257277 | instance | changed: [instance] 2026-09-12 03:56:08.257373 | instance | 2026-09-12 03:56:08.257919 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ****************** 2026-09-12 03:56:08.257991 | instance | Saturday 12 September 2026 03:56:08 +0000 (0:00:04.307) 0:00:27.013 **** 2026-09-12 03:56:09.755117 | instance | ok: [instance] 2026-09-12 03:56:09.755218 | instance | 2026-09-12 03:56:09.755542 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] *************** 2026-09-12 03:56:09.755740 | instance | Saturday 12 September 2026 03:56:09 +0000 (0:00:01.498) 0:00:28.511 **** 2026-09-12 03:56:10.163526 | instance | changed: [instance] 2026-09-12 03:56:10.163607 | instance | 2026-09-12 03:56:10.163678 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] ***** 2026-09-12 03:56:10.163801 | instance | Saturday 12 September 2026 03:56:10 +0000 (0:00:00.408) 0:00:28.920 **** 2026-09-12 03:56:10.700597 | instance | changed: [instance] 2026-09-12 03:56:10.701396 | instance | 2026-09-12 03:56:10.701466 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] *********** 2026-09-12 03:56:10.701479 | instance | Saturday 12 September 2026 03:56:10 +0000 (0:00:00.536) 0:00:29.456 **** 2026-09-12 03:56:11.367947 | instance | changed: [instance] => (item={'path': '/etc/docker'}) 2026-09-12 03:56:11.368079 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'}) 2026-09-12 03:56:11.369085 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'}) 2026-09-12 03:56:11.369156 | instance | 2026-09-12 03:56:11.369169 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ****** 2026-09-12 03:56:11.369179 | instance | Saturday 12 September 2026 03:56:11 +0000 (0:00:00.667) 0:00:30.124 **** 2026-09-12 03:56:11.852716 | instance | changed: [instance] 2026-09-12 03:56:11.853474 | instance | 2026-09-12 03:56:11.853533 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] *********** 2026-09-12 03:56:11.853544 | instance | Saturday 12 September 2026 03:56:11 +0000 (0:00:00.484) 0:00:30.608 **** 2026-09-12 03:56:12.409320 | instance | changed: [instance] 2026-09-12 03:56:12.409391 | instance | 2026-09-12 03:56:12.410142 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************ 2026-09-12 03:56:12.410191 | instance | Saturday 12 September 2026 03:56:12 +0000 (0:00:00.527) 0:00:31.136 **** 2026-09-12 03:56:12.410198 | instance | 2026-09-12 03:56:12.410204 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-12 03:56:12.410210 | instance | Saturday 12 September 2026 03:56:12 +0000 (0:00:00.029) 0:00:31.165 **** 2026-09-12 03:56:13.219744 | instance | ok: [instance] 2026-09-12 03:56:13.219801 | instance | 2026-09-12 03:56:13.219812 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ****************** 2026-09-12 03:56:13.219830 | instance | Saturday 12 September 2026 03:56:13 +0000 (0:00:00.809) 0:00:31.975 **** 2026-09-12 03:56:14.151248 | instance | changed: [instance] 2026-09-12 03:56:14.151987 | instance | 2026-09-12 03:56:14.152052 | instance | TASK [vexxhost.containers.docker : Enable and start service] ******************* 2026-09-12 03:56:14.152065 | instance | Saturday 12 September 2026 03:56:14 +0000 (0:00:00.932) 0:00:32.907 **** 2026-09-12 03:56:14.785018 | instance | changed: [instance] 2026-09-12 03:56:14.785056 | instance | 2026-09-12 03:56:14.785063 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ****** 2026-09-12 03:56:14.785070 | instance | Saturday 12 September 2026 03:56:14 +0000 (0:00:00.633) 0:00:33.541 **** 2026-09-12 03:56:14.861224 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml) 2026-09-12 03:56:14.861257 | instance | 2026-09-12 03:56:14.861268 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ******************************** 2026-09-12 03:56:14.861278 | instance | Saturday 12 September 2026 03:56:14 +0000 (0:00:00.075) 0:00:33.617 **** 2026-09-12 03:56:20.504575 | instance | changed: [instance] 2026-09-12 03:56:20.505298 | instance | 2026-09-12 03:56:20.505358 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] ********************* 2026-09-12 03:56:20.505370 | instance | Saturday 12 September 2026 03:56:20 +0000 (0:00:05.643) 0:00:39.260 **** 2026-09-12 03:56:21.232553 | instance | ok: [instance] => (item=chronyd) 2026-09-12 03:56:21.233517 | instance | ok: [instance] => (item=sshd) 2026-09-12 03:56:21.233575 | instance | 2026-09-12 03:56:21.233587 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ****************************** 2026-09-12 03:56:21.233597 | instance | Saturday 12 September 2026 03:56:21 +0000 (0:00:00.727) 0:00:39.988 **** 2026-09-12 03:56:33.224191 | instance | changed: [instance] 2026-09-12 03:56:33.225049 | instance | 2026-09-12 03:56:33.225114 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ******************** 2026-09-12 03:56:33.225127 | instance | Saturday 12 September 2026 03:56:33 +0000 (0:00:11.991) 0:00:51.980 **** 2026-09-12 03:56:33.472624 | instance | ok: [instance] 2026-09-12 03:56:33.472689 | instance | 2026-09-12 03:56:33.472936 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] **************** 2026-09-12 03:56:33.472999 | instance | Saturday 12 September 2026 03:56:33 +0000 (0:00:00.248) 0:00:52.229 **** 2026-09-12 03:56:33.971733 | instance | changed: [instance] 2026-09-12 03:56:33.971797 | instance | 2026-09-12 03:56:33.971809 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] *** 2026-09-12 03:56:33.971819 | instance | Saturday 12 September 2026 03:56:33 +0000 (0:00:00.497) 0:00:52.726 **** 2026-09-12 03:56:34.337877 | instance | changed: [instance] 2026-09-12 03:56:34.337971 | instance | 2026-09-12 03:56:34.337990 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] ************************* 2026-09-12 03:56:34.338174 | instance | Saturday 12 September 2026 03:56:34 +0000 (0:00:00.367) 0:00:53.094 **** 2026-09-12 03:56:34.484762 | instance | ok: [instance] 2026-09-12 03:56:34.484906 | instance | 2026-09-12 03:56:34.485495 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] ******************* 2026-09-12 03:56:34.485557 | instance | Saturday 12 September 2026 03:56:34 +0000 (0:00:00.145) 0:00:53.240 **** 2026-09-12 03:56:34.718553 | instance | ok: [instance] => (item=instance) 2026-09-12 03:56:34.719367 | instance | 2026-09-12 03:56:34.719531 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ******** 2026-09-12 03:56:34.719546 | instance | Saturday 12 September 2026 03:56:34 +0000 (0:00:00.233) 0:00:53.474 **** 2026-09-12 03:56:34.780348 | instance | ok: [instance] 2026-09-12 03:56:34.780442 | instance | 2026-09-12 03:56:34.780622 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] *********************************** 2026-09-12 03:56:34.780868 | instance | Saturday 12 September 2026 03:56:34 +0000 (0:00:00.062) 0:00:53.536 **** 2026-09-12 03:56:34.866276 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance 2026-09-12 03:56:34.866360 | instance | 2026-09-12 03:56:34.866508 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] ************* 2026-09-12 03:56:34.866723 | instance | Saturday 12 September 2026 03:56:34 +0000 (0:00:00.085) 0:00:53.622 **** 2026-09-12 03:56:35.214762 | instance | changed: [instance] 2026-09-12 03:56:35.214845 | instance | 2026-09-12 03:56:35.215219 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ****************** 2026-09-12 03:56:35.215323 | instance | Saturday 12 September 2026 03:56:35 +0000 (0:00:00.348) 0:00:53.971 **** 2026-09-12 03:56:35.984946 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True}) 2026-09-12 03:56:35.986229 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0}) 2026-09-12 03:56:35.986298 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False}) 2026-09-12 03:56:35.986312 | instance | 2026-09-12 03:56:35.986323 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] ******************************* 2026-09-12 03:56:35.986333 | instance | Saturday 12 September 2026 03:56:35 +0000 (0:00:00.769) 0:00:54.740 **** 2026-09-12 03:58:13.306248 | instance | ok: [instance] 2026-09-12 03:58:13.306699 | instance | 2026-09-12 03:58:13.307173 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] *************** 2026-09-12 03:58:13.307755 | instance | Saturday 12 September 2026 03:58:13 +0000 (0:01:37.317) 0:02:32.058 **** 2026-09-12 03:58:13.563157 | instance | changed: [instance] 2026-09-12 03:58:13.563286 | instance | 2026-09-12 03:58:13.563448 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] ********************************** 2026-09-12 03:58:13.563624 | instance | Saturday 12 September 2026 03:58:13 +0000 (0:00:00.261) 0:02:32.319 **** 2026-09-12 03:58:13.599502 | instance | ok: [instance] 2026-09-12 03:58:13.599674 | instance | 2026-09-12 03:58:13.599848 | instance | TASK [Install Ceph host] ******************************************************* 2026-09-12 03:58:13.600021 | instance | Saturday 12 September 2026 03:58:13 +0000 (0:00:00.036) 0:02:32.356 **** 2026-09-12 03:58:13.673151 | instance | included: vexxhost.ceph.cephadm_host for instance 2026-09-12 03:58:13.673304 | instance | 2026-09-12 03:58:13.673492 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ****** 2026-09-12 03:58:13.673694 | instance | Saturday 12 September 2026 03:58:13 +0000 (0:00:00.073) 0:02:32.430 **** 2026-09-12 03:58:15.311587 | instance | ok: [instance] 2026-09-12 03:58:15.311838 | instance | 2026-09-12 03:58:15.312046 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] *** 2026-09-12 03:58:15.312207 | instance | Saturday 12 September 2026 03:58:15 +0000 (0:00:01.637) 0:02:34.067 **** 2026-09-12 03:58:15.389825 | instance | ok: [instance] => (item=instance) 2026-09-12 03:58:15.390029 | instance | 2026-09-12 03:58:15.390260 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] *********** 2026-09-12 03:58:15.390673 | instance | Saturday 12 September 2026 03:58:15 +0000 (0:00:00.078) 0:02:34.146 **** 2026-09-12 03:58:15.785432 | instance | ok: [instance] 2026-09-12 03:58:15.785699 | instance | 2026-09-12 03:58:15.786008 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] *********************** 2026-09-12 03:58:15.786250 | instance | Saturday 12 September 2026 03:58:15 +0000 (0:00:00.395) 0:02:34.541 **** 2026-09-12 03:58:18.215691 | instance | ok: [instance] 2026-09-12 03:58:18.215904 | instance | 2026-09-12 03:58:18.216146 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ****************** 2026-09-12 03:58:18.216382 | instance | Saturday 12 September 2026 03:58:18 +0000 (0:00:02.430) 0:02:36.972 **** 2026-09-12 03:59:09.331515 | 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-12 03:59:09.332464 | 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_e1jafhnl/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_e1jafhnl/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_e1jafhnl/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_e1jafhnl/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-12T03:59:09.137+0000 7f399502e640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCqzaRqZWELLSAAnPV2i2Vyu5Tatbj21GpyelyYz+JkG0DpateYDmdpUOg=: Malformed input [buffer:3]\n2026-09-12T03:59:09.137+0000 7f399502e640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-12T03:59:09.141+0000 7f399502e640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCqzaRqZWELLSAAnPV2i2Vyu5Tatbj21GpyelyYz+JkG0DpateYDmdpUOg=: Malformed input [buffer:3]\n2026-09-12T03:59:09.141+0000 7f399502e640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-12T03:59:09.141+0000 7f399502e640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCqzaRqZWELLSAAnPV2i2Vyu5Tatbj21GpyelyYz+JkG0DpateYDmdpUOg=: Malformed input [buffer:3]\n2026-09-12T03:59:09.141+0000 7f399502e640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-12T03:59:09.141+0000 7f399502e640 -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-12 03:59:09.335869 | instance | 2026-09-12 03:59:09.336167 | instance | PLAY RECAP ********************************************************************* 2026-09-12 03:59:09.336543 | instance | instance : ok=55 changed=26 unreachable=0 failed=1 skipped=6 rescued=0 ignored=0 2026-09-12 03:59:09.336773 | instance | 2026-09-12 03:59:09.337052 | instance | Saturday 12 September 2026 03:59:09 +0000 (0:00:51.120) 0:03:28.093 **** 2026-09-12 03:59:09.337288 | instance | =============================================================================== 2026-09-12 03:59:09.337542 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 97.32s 2026-09-12 03:59:09.337792 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 51.12s 2026-09-12 03:59:09.338074 | instance | vexxhost.ceph.cephadm : Download "cephadm" ----------------------------- 11.99s 2026-09-12 03:59:09.338332 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.91s 2026-09-12 03:59:09.338709 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.64s 2026-09-12 03:59:09.338901 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.31s 2026-09-12 03:59:09.339180 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.93s 2026-09-12 03:59:09.339506 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.43s 2026-09-12 03:59:09.339867 | instance | vexxhost.containers.download_artifact : Download item ------------------- 1.86s 2026-09-12 03:59:09.340207 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.76s 2026-09-12 03:59:09.340496 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.64s 2026-09-12 03:59:09.340751 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.50s 2026-09-12 03:59:09.341017 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.31s 2026-09-12 03:59:09.341262 | instance | Gathering Facts --------------------------------------------------------- 1.13s 2026-09-12 03:59:09.341619 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.01s 2026-09-12 03:59:09.341837 | instance | Gathering Facts --------------------------------------------------------- 1.01s 2026-09-12 03:59:09.342221 | instance | Gathering Facts --------------------------------------------------------- 1.00s 2026-09-12 03:59:09.342474 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.93s 2026-09-12 03:59:09.342784 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.77s 2026-09-12 03:59:09.343033 | instance | vexxhost.ceph.cephadm : Ensure services are started --------------------- 0.73s 2026-09-12 03:59:09.438925 | 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-12 03:59:09.439182 | instance | ERROR [csi > converge] Executed: Failed 2026-09-12 03:59:09.439324 | 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-12 03:59:09.924988 | instance | ERROR 2026-09-12 03:59:09.925256 | instance | { 2026-09-12 03:59:09.925311 | instance | "delta": "0:04:21.595083", 2026-09-12 03:59:09.925349 | instance | "end": "2026-09-12 03:59:09.516953", 2026-09-12 03:59:09.925385 | instance | "msg": "non-zero return code", 2026-09-12 03:59:09.925420 | instance | "rc": 2, 2026-09-12 03:59:09.925454 | instance | "start": "2026-09-12 03:54:47.921870" 2026-09-12 03:59:09.925487 | instance | } failure 2026-09-12 03:59:09.934884 | 2026-09-12 03:59:09.934984 | PLAY RECAP 2026-09-12 03:59:09.935067 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-12 03:59:09.935109 | 2026-09-12 03:59:10.117063 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-12 03:59:10.121926 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-12 03:59:10.688811 | 2026-09-12 03:59:10.688952 | PLAY [all] 2026-09-12 03:59:10.702628 | 2026-09-12 03:59:10.702700 | TASK [gather-host-logs : creating directory for system status] 2026-09-12 03:59:11.078457 | instance | changed 2026-09-12 03:59:11.088361 | 2026-09-12 03:59:11.088559 | TASK [gather-host-logs : Get logs for each host] 2026-09-12 03:59:11.484502 | instance | + systemd-cgls --full --all --no-pager 2026-09-12 03:59:11.504099 | instance | + ip addr 2026-09-12 03:59:11.507699 | instance | + ip route 2026-09-12 03:59:11.510333 | instance | + lsblk 2026-09-12 03:59:11.516211 | instance | + mount 2026-09-12 03:59:11.519726 | instance | + docker images 2026-09-12 03:59:11.544464 | instance | + brctl show 2026-09-12 03:59:11.545056 | instance | /bin/bash: line 8: brctl: command not found 2026-09-12 03:59:11.545384 | instance | + ip neigh 2026-09-12 03:59:11.548118 | instance | + bridge fdb show 2026-09-12 03:59:11.556457 | instance | + ps aux --sort=-%mem 2026-09-12 03:59:11.581158 | instance | + dpkg -l 2026-09-12 03:59:11.592618 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-09-12 03:59:11.593313 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-09-12 03:59:11.618857 | instance | + '[' '!' -z '' ']' 2026-09-12 03:59:11.681448 | instance | ok: Runtime: 0:00:00.139823 2026-09-12 03:59:11.693285 | 2026-09-12 03:59:11.693451 | TASK [gather-host-logs : Downloads logs to executor] 2026-09-12 03:59:12.415091 | instance | changed: 2026-09-12 03:59:12.415335 | instance | created directory /var/lib/zuul/builds/fe109d91ecaf4d55ace9e687ac0f778c/work/logs/instance 2026-09-12 03:59:12.415394 | instance | cd+++++++++ system/ 2026-09-12 03:59:12.415437 | instance | >f+++++++++ system/brctl-show.txt 2026-09-12 03:59:12.415475 | instance | >f+++++++++ system/bridge-fdb.txt 2026-09-12 03:59:12.415511 | instance | >f+++++++++ system/docker-images.txt 2026-09-12 03:59:12.415553 | instance | >f+++++++++ system/ip-addr.txt 2026-09-12 03:59:12.415592 | instance | >f+++++++++ system/ip-neigh.txt 2026-09-12 03:59:12.415631 | instance | >f+++++++++ system/ip-route.txt 2026-09-12 03:59:12.415673 | instance | >f+++++++++ system/lsblk.txt 2026-09-12 03:59:12.415710 | instance | >f+++++++++ system/mount.txt 2026-09-12 03:59:12.415746 | instance | >f+++++++++ system/packages.txt 2026-09-12 03:59:12.415782 | instance | >f+++++++++ system/ps.txt 2026-09-12 03:59:12.415840 | instance | >f+++++++++ system/systemd-cgls.txt 2026-09-12 03:59:12.434202 | 2026-09-12 03:59:12.434332 | LOOP [helm-release-status : creating directory for helm release status] 2026-09-12 03:59:12.658365 | instance | changed: "values" 2026-09-12 03:59:12.850691 | instance | changed: "releases" 2026-09-12 03:59:12.869303 | 2026-09-12 03:59:12.869527 | TASK [helm-release-status : Gather get release status for helm charts] 2026-09-12 03:59:13.132973 | instance | /bin/bash: line 3: kubectl: command not found 2026-09-12 03:59:13.421837 | instance | ok: Runtime: 0:00:00.006655 2026-09-12 03:59:13.431601 | 2026-09-12 03:59:13.431839 | TASK [helm-release-status : Downloads logs to executor] 2026-09-12 03:59:13.982753 | instance | changed: 2026-09-12 03:59:13.983016 | instance | cd+++++++++ helm/ 2026-09-12 03:59:13.983071 | instance | cd+++++++++ helm/releases/ 2026-09-12 03:59:13.983113 | instance | cd+++++++++ helm/values/ 2026-09-12 03:59:14.000681 | 2026-09-12 03:59:14.000853 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-09-12 03:59:14.228836 | instance | changed 2026-09-12 03:59:14.240718 | 2026-09-12 03:59:14.240887 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-09-12 03:59:14.547982 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args valuewarning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-12 03:59:14.548112 | instance | 2026-09-12 03:59:14.554175 | instance | environment: line 1: kubectl: command not found 2026-09-12 03:59:14.555755 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-12 03:59:14.557498 | instance | environment: line 1: kubectl: command not found 2026-09-12 03:59:14.559418 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-12 03:59:14.560194 | instance | environment: line 1: kubectl: command not found 2026-09-12 03:59:14.561747 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-12 03:59:14.564110 | instance | environment: line 1: kubectl: command not found 2026-09-12 03:59:14.565395 | instance | environment: line 1: kubectl: command not found 2026-09-12 03:59:14.565748 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-12 03:59:14.566645 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-12 03:59:14.789416 | instance | ok: Runtime: 0:00:00.027972 2026-09-12 03:59:14.799063 | 2026-09-12 03:59:14.799189 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-09-12 03:59:15.026713 | instance | changed 2026-09-12 03:59:15.035806 | 2026-09-12 03:59:15.035979 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-09-12 03:59:15.318791 | instance | environment: line 5: kubectl: command not found 2026-09-12 03:59:15.319505 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-12 03:59:15.319974 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-12 03:59:15.320069 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-12 03:59:15.582769 | instance | ok: Runtime: 0:00:00.008909 2026-09-12 03:59:15.593562 | 2026-09-12 03:59:15.593691 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-09-12 03:59:16.113710 | instance | changed: 2026-09-12 03:59:16.113993 | instance | cd+++++++++ objects/ 2026-09-12 03:59:16.114047 | instance | cd+++++++++ objects/cluster/ 2026-09-12 03:59:16.114088 | instance | cd+++++++++ objects/namespaced/ 2026-09-12 03:59:16.131470 | 2026-09-12 03:59:16.131673 | TASK [gather-pod-logs : creating directory for pod logs] 2026-09-12 03:59:16.368639 | instance | changed 2026-09-12 03:59:16.378326 | 2026-09-12 03:59:16.378523 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-09-12 03:59:16.607693 | instance | changed 2026-09-12 03:59:16.617150 | 2026-09-12 03:59:16.617284 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-09-12 03:59:16.873125 | instance | environment: line 3: kubectl: command not found 2026-09-12 03:59:17.164212 | instance | ok: Runtime: 0:00:00.011068 2026-09-12 03:59:17.174630 | 2026-09-12 03:59:17.174786 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-09-12 03:59:17.716542 | instance | changed: 2026-09-12 03:59:17.716865 | instance | cd+++++++++ pod-logs/ 2026-09-12 03:59:17.716998 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-09-12 03:59:17.738007 | 2026-09-12 03:59:17.738198 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-09-12 03:59:17.976408 | instance | changed 2026-09-12 03:59:17.986376 | 2026-09-12 03:59:17.986516 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-09-12 03:59:18.259548 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-12 03:59:18.536257 | instance | ok: Runtime: 0:00:00.058011 2026-09-12 03:59:18.545986 | 2026-09-12 03:59:18.546227 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-09-12 03:59:18.814168 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-12 03:59:18.864988 | instance | ceph-mgr endpoints: 2026-09-12 03:59:19.095987 | instance | ok: Runtime: 0:00:00.058737 2026-09-12 03:59:19.105919 | 2026-09-12 03:59:19.106057 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-09-12 03:59:19.376169 | instance | /bin/bash: line 4: kubectl: command not found 2026-09-12 03:59:19.655159 | instance | ok: Runtime: 0:00:00.052782 2026-09-12 03:59:19.665064 | 2026-09-12 03:59:19.665195 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-09-12 03:59:20.215939 | instance | changed: cd+++++++++ prometheus/ 2026-09-12 03:59:20.236068 | 2026-09-12 03:59:20.236222 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-09-12 03:59:20.486145 | instance | changed 2026-09-12 03:59:20.495967 | 2026-09-12 03:59:20.496107 | TASK [gather-selenium-data : Get selenium data] 2026-09-12 03:59:20.792930 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-09-12 03:59:20.794446 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-09-12 03:59:21.043927 | instance | ERROR 2026-09-12 03:59:21.044200 | instance | { 2026-09-12 03:59:21.044259 | instance | "delta": "0:00:00.007226", 2026-09-12 03:59:21.044299 | instance | "end": "2026-09-12 03:59:20.794863", 2026-09-12 03:59:21.044335 | instance | "msg": "non-zero return code", 2026-09-12 03:59:21.044370 | instance | "rc": 1, 2026-09-12 03:59:21.044404 | instance | "start": "2026-09-12 03:59:20.787637" 2026-09-12 03:59:21.044438 | instance | } 2026-09-12 03:59:21.044483 | instance | ERROR: Ignoring Errors 2026-09-12 03:59:21.054562 | 2026-09-12 03:59:21.054685 | TASK [gather-selenium-data : Downloads logs to executor] 2026-09-12 03:59:21.575321 | instance | changed: cd+++++++++ selenium/ 2026-09-12 03:59:21.586504 | 2026-09-12 03:59:21.586603 | PLAY RECAP 2026-09-12 03:59:21.586686 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-09-12 03:59:21.586729 | 2026-09-12 03:59:21.734162 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-12 03:59:21.738177 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-12 03:59:22.311923 | 2026-09-12 03:59:22.312097 | PLAY [all] 2026-09-12 03:59:22.323791 | 2026-09-12 03:59:22.323883 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-12 03:59:22.388510 | instance | skipping: Conditional result was False 2026-09-12 03:59:22.396048 | 2026-09-12 03:59:22.396235 | TASK [fetch-output : Set log path for single node] 2026-09-12 03:59:22.429673 | instance | ok 2026-09-12 03:59:22.435741 | 2026-09-12 03:59:22.435844 | LOOP [fetch-output : Ensure local output dirs] 2026-09-12 03:59:22.889410 | instance -> localhost | ok: "/var/lib/zuul/builds/fe109d91ecaf4d55ace9e687ac0f778c/work/logs" 2026-09-12 03:59:23.123137 | instance -> localhost | changed: "/var/lib/zuul/builds/fe109d91ecaf4d55ace9e687ac0f778c/work/artifacts" 2026-09-12 03:59:23.357290 | instance -> localhost | changed: "/var/lib/zuul/builds/fe109d91ecaf4d55ace9e687ac0f778c/work/docs" 2026-09-12 03:59:23.372001 | 2026-09-12 03:59:23.372179 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-12 03:59:24.099987 | instance | changed: .d..t...... ./ 2026-09-12 03:59:24.100326 | instance | changed: All items complete 2026-09-12 03:59:24.100382 | 2026-09-12 03:59:24.592728 | instance | changed: .d..t...... ./ 2026-09-12 03:59:25.083910 | instance | changed: .d..t...... ./ 2026-09-12 03:59:25.101762 | 2026-09-12 03:59:25.101941 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-12 03:59:25.578657 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008768 2026-09-12 03:59:25.831409 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008939 2026-09-12 03:59:25.842229 | 2026-09-12 03:59:25.842356 | PLAY [all] 2026-09-12 03:59:25.850506 | 2026-09-12 03:59:25.850613 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-12 03:59:26.311567 | instance | changed 2026-09-12 03:59:26.325947 | 2026-09-12 03:59:26.326118 | PLAY RECAP 2026-09-12 03:59:26.326245 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-12 03:59:26.326325 | 2026-09-12 03:59:26.494103 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-12 03:59:26.500130 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-12 03:59:27.043922 | 2026-09-12 03:59:27.044057 | PLAY [localhost] 2026-09-12 03:59:27.053168 | 2026-09-12 03:59:27.053247 | TASK [Generate Zuul manifest] 2026-09-12 03:59:27.071923 | localhost | ok 2026-09-12 03:59:27.089485 | 2026-09-12 03:59:27.089648 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-12 03:59:27.454588 | localhost | changed 2026-09-12 03:59:27.476915 | 2026-09-12 03:59:27.477073 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-12 03:59:27.510063 | localhost | ok 2026-09-12 03:59:27.524412 | 2026-09-12 03:59:27.524599 | TASK [Upload logs] 2026-09-12 03:59:27.549401 | localhost | ok 2026-09-12 03:59:27.621006 | 2026-09-12 03:59:27.621154 | TASK [Set zuul-log-path fact] 2026-09-12 03:59:27.638322 | localhost | ok 2026-09-12 03:59:27.649928 | 2026-09-12 03:59:27.649997 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-12 03:59:27.688056 | localhost | ok 2026-09-12 03:59:27.696235 | 2026-09-12 03:59:27.696319 | TASK [upload-logs : Create log directories] 2026-09-12 03:59:28.110872 | localhost | changed 2026-09-12 03:59:28.116751 | 2026-09-12 03:59:28.116857 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-12 03:59:28.541314 | localhost -> localhost | ok: Runtime: 0:00:00.009599 2026-09-12 03:59:28.548061 | 2026-09-12 03:59:28.548147 | TASK [upload-logs : Upload logs to log server] 2026-09-12 03:59:29.169171 | localhost | Output suppressed because no_log was given 2026-09-12 03:59:29.217536 | 2026-09-12 03:59:29.217701 | LOOP [upload-logs : Compress console log and json output] 2026-09-12 03:59:29.278404 | localhost | skipping: Conditional result was False 2026-09-12 03:59:29.285664 | localhost | skipping: Conditional result was False 2026-09-12 03:59:29.300783 | 2026-09-12 03:59:29.301021 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-12 03:59:29.344872 | localhost | skipping: Conditional result was False 2026-09-12 03:59:29.345285 | 2026-09-12 03:59:29.348678 | localhost | skipping: Conditional result was False 2026-09-12 03:59:29.365658 | 2026-09-12 03:59:29.365903 | LOOP [upload-logs : Upload console log and json output]