2026-09-02 15:52:40.475923 | Job console starting 2026-09-02 15:52:40.488514 | Updating git repos 2026-09-02 15:52:48.621323 | Cloning repos into workspace 2026-09-02 15:53:09.440221 | Restoring repo states 2026-09-02 15:53:23.703477 | Merging changes 2026-09-02 15:53:25.539443 | Checking out repos 2026-09-02 15:53:29.929656 | Preparing playbooks 2026-09-02 15:53:32.667373 | Running Ansible setup 2026-09-02 15:53:36.926757 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-02 15:53:37.527360 | 2026-09-02 15:53:37.527488 | PLAY [localhost] 2026-09-02 15:53:37.534793 | 2026-09-02 15:53:37.534866 | TASK [Gathering Facts] 2026-09-02 15:53:38.385016 | localhost | ok 2026-09-02 15:53:38.398223 | 2026-09-02 15:53:38.398333 | TASK [Setup log path fact] 2026-09-02 15:53:38.419296 | localhost | ok 2026-09-02 15:53:38.435243 | 2026-09-02 15:53:38.435315 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-02 15:53:38.466330 | localhost | ok 2026-09-02 15:53:38.475278 | 2026-09-02 15:53:38.475368 | TASK [emit-job-header : Print job information] 2026-09-02 15:53:38.518109 | # Job Information 2026-09-02 15:53:38.518369 | Ansible Version: 2.16.18 2026-09-02 15:53:38.518442 | Job: atmosphere-molecule-csi-rbd 2026-09-02 15:53:38.518491 | Pipeline: check 2026-09-02 15:53:38.518536 | Executor: 2d72f0692154 2026-09-02 15:53:38.518579 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4361 2026-09-02 15:53:38.518629 | Event ID: 85a969b0-a6e5-11f1-9a43-77a85a727eb2 2026-09-02 15:53:38.523213 | 2026-09-02 15:53:38.523302 | LOOP [emit-job-header : Print node information] 2026-09-02 15:53:38.614471 | localhost | ok: 2026-09-02 15:53:38.614687 | localhost | # Node Information 2026-09-02 15:53:38.614733 | localhost | Inventory Hostname: instance 2026-09-02 15:53:38.614769 | localhost | Hostname: np0000191856 2026-09-02 15:53:38.614800 | localhost | Username: zuul 2026-09-02 15:53:38.614837 | localhost | Distro: Ubuntu 22.04 2026-09-02 15:53:38.614873 | localhost | Provider: yul1 2026-09-02 15:53:38.614908 | localhost | Region: ca-ymq-1 2026-09-02 15:53:38.614941 | localhost | Label: ubuntu-jammy 2026-09-02 15:53:38.614966 | localhost | Product Name: OpenStack Nova 2026-09-02 15:53:38.614989 | localhost | Interface IP: 162.253.55.105 2026-09-02 15:53:38.630981 | 2026-09-02 15:53:38.631168 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-02 15:53:39.002779 | localhost -> localhost | changed 2026-09-02 15:53:39.012325 | 2026-09-02 15:53:39.012417 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-02 15:53:39.905273 | localhost -> localhost | changed 2026-09-02 15:53:39.912626 | 2026-09-02 15:53:39.912687 | PLAY [all] 2026-09-02 15:53:39.935661 | 2026-09-02 15:53:39.935805 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-02 15:53:40.153950 | instance -> localhost | ok 2026-09-02 15:53:40.160501 | 2026-09-02 15:53:40.160567 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-02 15:53:40.194621 | instance | ok 2026-09-02 15:53:40.209007 | instance | included: /var/lib/zuul/builds/9d191e3aae8440de8ae94de4b243abb2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-02 15:53:40.214608 | 2026-09-02 15:53:40.214673 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-02 15:53:40.997631 | instance -> localhost | Generating public/private rsa key pair. 2026-09-02 15:53:40.997933 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/9d191e3aae8440de8ae94de4b243abb2/work/9d191e3aae8440de8ae94de4b243abb2_id_rsa 2026-09-02 15:53:40.997997 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/9d191e3aae8440de8ae94de4b243abb2/work/9d191e3aae8440de8ae94de4b243abb2_id_rsa.pub 2026-09-02 15:53:40.998046 | instance -> localhost | The key fingerprint is: 2026-09-02 15:53:40.998093 | instance -> localhost | SHA256:yeF3NHvHjDrtDm0SlKNKJx9tQ/yWEVQ6cgwNrpJMsIs zuul-build-sshkey 2026-09-02 15:53:40.998161 | instance -> localhost | The key's randomart image is: 2026-09-02 15:53:40.998207 | instance -> localhost | +---[RSA 3072]----+ 2026-09-02 15:53:40.998262 | instance -> localhost | | . o+o..| 2026-09-02 15:53:40.998308 | instance -> localhost | | o o +.o | 2026-09-02 15:53:40.998352 | instance -> localhost | | . o @ * | 2026-09-02 15:53:40.998396 | instance -> localhost | | . * + B B B | 2026-09-02 15:53:40.998441 | instance -> localhost | | E . S * B * +| 2026-09-02 15:53:40.998485 | instance -> localhost | | . B + O . | 2026-09-02 15:53:40.998528 | instance -> localhost | | . . = + | 2026-09-02 15:53:40.998571 | instance -> localhost | | * | 2026-09-02 15:53:40.998619 | instance -> localhost | | .o | 2026-09-02 15:53:40.998663 | instance -> localhost | +----[SHA256]-----+ 2026-09-02 15:53:40.998762 | instance -> localhost | ok: Runtime: 0:00:00.355524 2026-09-02 15:53:41.007301 | 2026-09-02 15:53:41.007371 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-02 15:53:41.041616 | instance | ok 2026-09-02 15:53:41.050156 | instance | included: /var/lib/zuul/builds/9d191e3aae8440de8ae94de4b243abb2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-02 15:53:41.057327 | 2026-09-02 15:53:41.057387 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-02 15:53:41.082631 | instance | skipping: Conditional result was False 2026-09-02 15:53:41.093494 | 2026-09-02 15:53:41.093559 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-02 15:53:41.562515 | instance | changed 2026-09-02 15:53:41.570943 | 2026-09-02 15:53:41.571030 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-02 15:53:41.787126 | instance | ok 2026-09-02 15:53:41.802315 | 2026-09-02 15:53:41.802554 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-02 15:53:42.333644 | instance | changed 2026-09-02 15:53:42.338507 | 2026-09-02 15:53:42.338571 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-02 15:53:42.848773 | instance | changed 2026-09-02 15:53:42.856279 | 2026-09-02 15:53:42.856346 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-02 15:53:42.881560 | instance | skipping: Conditional result was False 2026-09-02 15:53:42.892121 | 2026-09-02 15:53:42.892186 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-02 15:53:43.252976 | instance -> localhost | changed 2026-09-02 15:53:43.266815 | 2026-09-02 15:53:43.266898 | TASK [add-build-sshkey : Add back temp key] 2026-09-02 15:53:43.561213 | instance -> localhost | Identity added: /var/lib/zuul/builds/9d191e3aae8440de8ae94de4b243abb2/work/9d191e3aae8440de8ae94de4b243abb2_id_rsa (zuul-build-sshkey) 2026-09-02 15:53:43.561442 | instance -> localhost | ok: Runtime: 0:00:00.011693 2026-09-02 15:53:43.568039 | 2026-09-02 15:53:43.568124 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-02 15:53:43.909441 | instance | ok 2026-09-02 15:53:43.916217 | 2026-09-02 15:53:43.916288 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-02 15:53:43.944465 | instance | skipping: Conditional result was False 2026-09-02 15:53:43.960426 | 2026-09-02 15:53:43.960565 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-02 15:53:44.315947 | instance | ok 2026-09-02 15:53:44.321466 | 2026-09-02 15:53:44.321532 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-02 15:53:47.864951 | instance | Output suppressed because no_log was given 2026-09-02 15:53:47.876667 | 2026-09-02 15:53:47.876732 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-02 15:53:48.080550 | instance | ok: "logs" 2026-09-02 15:53:48.081557 | instance | ok: All items complete 2026-09-02 15:53:48.081608 | 2026-09-02 15:53:48.261872 | instance | ok: "artifacts" 2026-09-02 15:53:48.422190 | instance | ok: "docs" 2026-09-02 15:53:48.438759 | 2026-09-02 15:53:48.618319 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-02 15:53:48.811984 | instance | changed: "logs" 2026-09-02 15:53:48.971642 | instance | changed: "artifacts" 2026-09-02 15:53:49.123019 | instance | changed: "docs" 2026-09-02 15:53:49.135821 | 2026-09-02 15:53:49.135951 | PLAY RECAP 2026-09-02 15:53:49.136011 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-02 15:53:49.136067 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-02 15:53:49.136099 | 2026-09-02 15:53:49.268241 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-02 15:53:49.558401 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-02 15:53:50.154331 | 2026-09-02 15:53:50.154487 | PLAY [all] 2026-09-02 15:53:50.164684 | 2026-09-02 15:53:50.164760 | TASK [setup-uv : Extract archive] 2026-09-02 15:53:53.462377 | instance | changed 2026-09-02 15:53:53.468033 | 2026-09-02 15:53:53.468112 | TASK [setup-uv : Print version] 2026-09-02 15:53:53.832316 | instance | uv 0.8.13 2026-09-02 15:53:54.007336 | instance | ok: Runtime: 0:00:00.012822 2026-09-02 15:53:54.017977 | 2026-09-02 15:53:54.018055 | TASK [Install Ansible collection siblings] 2026-09-02 15:54:03.200673 | instance | changed: 2026-09-02 15:54:03.200893 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-09-02 15:54:03.200937 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-09-02 15:54:03.201001 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-09-02 15:54:03.201040 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto 2026-09-02 15:54:03.201072 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general 2026-09-02 15:54:03.201104 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql 2026-09-02 15:54:03.201135 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-09-02 15:54:03.201168 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-09-02 15:54:03.201197 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-09-02 15:54:03.201227 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-09-02 15:54:03.201256 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-09-02 15:54:03.201284 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-09-02 15:54:03.201313 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0 2026-09-02 15:54:03.201340 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0 2026-09-02 15:54:03.201367 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0 2026-09-02 15:54:03.201394 | instance | Resolved community.crypto to 2.2.3 for 2.2.3 2026-09-02 15:54:03.201422 | instance | Resolved community.general to 7.3.0 for 7.3.0 2026-09-02 15:54:03.201450 | instance | Resolved community.mysql to 3.6.0 for 3.6.0 2026-09-02 15:54:03.201478 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0 2026-09-02 15:54:03.201506 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0 2026-09-02 15:54:03.201539 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0 2026-09-02 15:54:03.201607 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0 2026-09-02 15:54:03.201669 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-09-02 15:54:03.201744 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0 2026-09-02 15:54:03.201799 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0 2026-09-02 15:54:03.201837 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-02 15:54:03.201866 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-02 15:54:03.201894 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-09-02 15:54:03.201921 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0 2026-09-02 15:54:03.201948 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3 2026-09-02 15:54:03.201976 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-02 15:54:03.202004 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-02 15:54:03.202032 | instance | Checked out ansible.netcommon to 1.2.0 2026-09-02 15:54:03.202061 | 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-02 15:54:03.202098 | instance | Starting galaxy collection install process 2026-09-02 15:54:03.202125 | instance | Process install dependency map 2026-09-02 15:54:03.202152 | instance | Starting collection install process 2026-09-02 15:54:03.202183 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-09-02 15:54:03.202240 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon 2026-09-02 15:54:03.202301 | instance | ansible.netcommon:* was installed successfully 2026-09-02 15:54:03.202335 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-09-02 15:54:03.202365 | instance | Creating virtual environment at: .venv 2026-09-02 15:54:03.202393 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-02 15:54:03.202421 | instance | Downloading openstacksdk (1.7MiB) 2026-09-02 15:54:03.202449 | instance | Downloading kubernetes (1.9MiB) 2026-09-02 15:54:03.202484 | instance | Downloading netaddr (2.2MiB) 2026-09-02 15:54:03.202512 | instance | Downloading cryptography (4.2MiB) 2026-09-02 15:54:03.202541 | instance | Downloading pygments (1.2MiB) 2026-09-02 15:54:03.202569 | instance | Downloading pydantic-core (2.0MiB) 2026-09-02 15:54:03.202598 | instance | Downloading rjsonnet (1.2MiB) 2026-09-02 15:54:03.202626 | instance | Downloading ansible-core (2.1MiB) 2026-09-02 15:54:03.202653 | instance | Downloading setuptools (1.1MiB) 2026-09-02 15:54:03.202681 | instance | Building pyperclip==1.9.0 2026-09-02 15:54:03.202711 | instance | Downloading rjsonnet 2026-09-02 15:54:03.202739 | instance | Downloading pydantic-core 2026-09-02 15:54:03.202767 | instance | Downloading netaddr 2026-09-02 15:54:03.202795 | instance | Downloading pygments 2026-09-02 15:54:03.202822 | instance | Downloading cryptography 2026-09-02 15:54:03.202849 | instance | Downloading setuptools 2026-09-02 15:54:03.202876 | instance | Downloading kubernetes 2026-09-02 15:54:03.202904 | instance | Downloading ansible-core 2026-09-02 15:54:03.202931 | instance | Downloading openstacksdk 2026-09-02 15:54:03.203067 | instance | Built pyperclip==1.9.0 2026-09-02 15:54:03.203196 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-02 15:54:03.203244 | instance | Installed 83 packages in 55ms 2026-09-02 15:54:03.203276 | instance | Checked out ansible.posix to 1.6.0 2026-09-02 15:54:03.203305 | 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-02 15:54:03.203335 | instance | Starting galaxy collection install process 2026-09-02 15:54:03.203363 | instance | Process install dependency map 2026-09-02 15:54:03.203400 | instance | Starting collection install process 2026-09-02 15:54:03.203429 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-09-02 15:54:03.203457 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix 2026-09-02 15:54:03.203485 | instance | ansible.posix:1.6.0 was installed successfully 2026-09-02 15:54:03.203514 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 15:54:03.203542 | instance | 2.17.12 2026-09-02 15:54:03.203571 | instance | Checked out ansible.utils to v6.1.0 2026-09-02 15:54:03.203597 | 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-02 15:54:03.203625 | instance | Starting galaxy collection install process 2026-09-02 15:54:03.203660 | instance | Process install dependency map 2026-09-02 15:54:03.203724 | instance | Starting collection install process 2026-09-02 15:54:03.203755 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-09-02 15:54:03.203777 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils 2026-09-02 15:54:03.203798 | instance | ansible.utils:6.1.0 was installed successfully 2026-09-02 15:54:03.203818 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 15:54:03.203837 | instance | 2.17.12 2026-09-02 15:54:03.203857 | instance | Checked out atmosphere.common to v0.7.0 2026-09-02 15:54:03.203877 | 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-02 15:54:03.203897 | instance | Starting galaxy collection install process 2026-09-02 15:54:03.203916 | instance | Process install dependency map 2026-09-02 15:54:03.203936 | instance | Starting collection install process 2026-09-02 15:54:03.203956 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common' 2026-09-02 15:54:03.203976 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common 2026-09-02 15:54:03.203996 | instance | atmosphere.common:0.7.0 was installed successfully 2026-09-02 15:54:03.204016 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 15:54:03.204035 | instance | 2.17.12 2026-09-02 15:54:03.204054 | instance | Checked out community.crypto to 2.2.3 2026-09-02 15:54:03.204074 | 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-02 15:54:03.204094 | instance | Starting galaxy collection install process 2026-09-02 15:54:03.204114 | instance | Process install dependency map 2026-09-02 15:54:03.204133 | instance | Starting collection install process 2026-09-02 15:54:03.204153 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-09-02 15:54:03.204172 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto 2026-09-02 15:54:03.204191 | instance | community.crypto:2.2.3 was installed successfully 2026-09-02 15:54:03.204211 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 15:54:03.204230 | instance | 2.17.12 2026-09-02 15:54:03.204249 | instance | Checked out community.general to 7.3.0 2026-09-02 15:54:03.204269 | 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-02 15:54:03.204295 | instance | Starting galaxy collection install process 2026-09-02 15:54:03.204315 | instance | Process install dependency map 2026-09-02 15:54:03.204334 | instance | Starting collection install process 2026-09-02 15:54:03.204354 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-09-02 15:54:03.204373 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general 2026-09-02 15:54:03.204393 | instance | community.general:7.3.0 was installed successfully 2026-09-02 15:54:03.204413 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 15:54:03.204433 | instance | 2.17.12 2026-09-02 15:54:03.204453 | instance | Checked out community.mysql to 3.6.0 2026-09-02 15:54:03.204476 | 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-02 15:54:03.204497 | instance | Starting galaxy collection install process 2026-09-02 15:54:03.204517 | instance | Process install dependency map 2026-09-02 15:54:03.204537 | instance | Starting collection install process 2026-09-02 15:54:03.204556 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-09-02 15:54:03.204575 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql 2026-09-02 15:54:03.204595 | instance | community.mysql:3.6.0 was installed successfully 2026-09-02 15:54:03.204614 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 15:54:03.204633 | instance | 2.17.12 2026-09-02 15:54:03.204652 | instance | Checked out kubernetes.core to 2.4.0 2026-09-02 15:54:03.204672 | 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-02 15:54:03.204692 | instance | Starting galaxy collection install process 2026-09-02 15:54:03.204712 | instance | Process install dependency map 2026-09-02 15:54:03.204732 | instance | Starting collection install process 2026-09-02 15:54:03.204752 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-09-02 15:54:03.204772 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core 2026-09-02 15:54:03.204792 | instance | kubernetes.core:2.4.0 was installed successfully 2026-09-02 15:54:03.204811 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 15:54:03.204830 | instance | 2.17.12 2026-09-02 15:54:03.204850 | instance | Checked out openstack.cloud to 2.6.0 2026-09-02 15:54:03.204870 | 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-02 15:54:03.204891 | instance | Starting galaxy collection install process 2026-09-02 15:54:03.204911 | instance | Process install dependency map 2026-09-02 15:54:03.204931 | instance | Starting collection install process 2026-09-02 15:54:03.204951 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-09-02 15:54:03.204995 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud 2026-09-02 15:54:03.205017 | instance | openstack.cloud:2.6.0 was installed successfully 2026-09-02 15:54:03.205037 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 15:54:03.205057 | instance | 2.17.12 2026-09-02 15:54:03.205083 | instance | Checked out vexxhost.ceph to v4.2.0 2026-09-02 15:54:03.205103 | 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-02 15:54:03.205124 | instance | Starting galaxy collection install process 2026-09-02 15:54:03.205143 | instance | Process install dependency map 2026-09-02 15:54:03.205163 | instance | Starting collection install process 2026-09-02 15:54:03.205182 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph' 2026-09-02 15:54:03.205202 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph 2026-09-02 15:54:03.205221 | instance | vexxhost.ceph:4.2.0 was installed successfully 2026-09-02 15:54:03.205240 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 15:54:03.205260 | instance | 2.17.12 2026-09-02 15:54:03.205279 | instance | Checked out vexxhost.containers to v1.6.8 2026-09-02 15:54:03.205299 | 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-02 15:54:03.205319 | instance | Starting galaxy collection install process 2026-09-02 15:54:03.205338 | instance | Process install dependency map 2026-09-02 15:54:03.205358 | instance | Starting collection install process 2026-09-02 15:54:03.205378 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers' 2026-09-02 15:54:03.205398 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers 2026-09-02 15:54:03.205418 | instance | vexxhost.containers:1.6.8 was installed successfully 2026-09-02 15:54:03.205438 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 15:54:03.205458 | instance | 2.17.12 2026-09-02 15:54:03.205477 | instance | Checked out vexxhost.kubernetes to v3.3.0 2026-09-02 15:54:03.205497 | 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-02 15:54:03.205517 | instance | Starting galaxy collection install process 2026-09-02 15:54:03.205536 | instance | Process install dependency map 2026-09-02 15:54:03.205562 | instance | Starting collection install process 2026-09-02 15:54:03.205589 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes' 2026-09-02 15:54:03.205610 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes 2026-09-02 15:54:03.205630 | instance | vexxhost.kubernetes:3.3.0 was installed successfully 2026-09-02 15:54:03.205651 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 15:54:03.205671 | instance | 2.17.12 2026-09-02 15:54:03.211781 | 2026-09-02 15:54:03.211830 | PLAY RECAP 2026-09-02 15:54:03.211875 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-02 15:54:03.211898 | 2026-09-02 15:54:03.343058 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-02 15:54:03.347176 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-02 15:54:03.916058 | 2026-09-02 15:54:03.916189 | PLAY [all] 2026-09-02 15:54:03.927076 | 2026-09-02 15:54:03.927148 | TASK [Install "jq" for log collection] 2026-09-02 15:54:13.454674 | instance | changed 2026-09-02 15:54:13.456890 | 2026-09-02 15:54:13.457000 | PLAY RECAP 2026-09-02 15:54:13.457071 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-02 15:54:13.457162 | 2026-09-02 15:54:13.579613 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-02 15:54:13.583461 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-02 15:54:14.173676 | 2026-09-02 15:54:14.173805 | PLAY [all] 2026-09-02 15:54:14.184694 | 2026-09-02 15:54:14.184765 | LOOP [Check inventory directories] 2026-09-02 15:54:14.549728 | instance | ok: "src/github.com/vexxhost/atmosphere" 2026-09-02 15:54:14.721107 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory" 2026-09-02 15:54:14.893966 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory" 2026-09-02 15:54:14.908181 | 2026-09-02 15:54:14.908423 | LOOP [Copy inventory file for Zuul] 2026-09-02 15:54:15.709323 | instance | changed: 2026-09-02 15:54:15.709575 | instance | { 2026-09-02 15:54:15.709617 | instance | "ansible_loop_var": "item", 2026-09-02 15:54:15.709650 | instance | "changed": false, 2026-09-02 15:54:15.709679 | instance | "failed": false, 2026-09-02 15:54:15.709709 | instance | "invocation": { 2026-09-02 15:54:15.709737 | instance | "module_args": { 2026-09-02 15:54:15.709762 | instance | "checksum_algorithm": "sha1", 2026-09-02 15:54:15.709788 | instance | "follow": true, 2026-09-02 15:54:15.709813 | instance | "get_attributes": true, 2026-09-02 15:54:15.709840 | instance | "get_checksum": true, 2026-09-02 15:54:15.709865 | instance | "get_mime": true, 2026-09-02 15:54:15.709890 | instance | "path": "src/github.com/vexxhost/atmosphere" 2026-09-02 15:54:15.709915 | instance | } 2026-09-02 15:54:15.709940 | instance | }, 2026-09-02 15:54:15.709965 | instance | "item": "src/github.com/vexxhost/atmosphere", 2026-09-02 15:54:15.709989 | instance | "stat": { 2026-09-02 15:54:15.710013 | instance | "atime": 1788364436.0218499, 2026-09-02 15:54:15.710038 | instance | "attr_flags": "e", 2026-09-02 15:54:15.710062 | instance | "attributes": [ 2026-09-02 15:54:15.710086 | instance | "extents" 2026-09-02 15:54:15.710111 | instance | ], 2026-09-02 15:54:15.710135 | instance | "block_size": 4096, 2026-09-02 15:54:15.710159 | instance | "blocks": 8, 2026-09-02 15:54:15.710183 | instance | "charset": "unknown", 2026-09-02 15:54:15.710209 | instance | "ctime": 1788364434.905829, 2026-09-02 15:54:15.710234 | instance | "dev": 64513, 2026-09-02 15:54:15.710258 | instance | "device_type": 0, 2026-09-02 15:54:15.710282 | instance | "executable": true, 2026-09-02 15:54:15.710306 | instance | "exists": true, 2026-09-02 15:54:15.710330 | instance | "gid": 1000, 2026-09-02 15:54:15.710355 | instance | "gr_name": "zuul", 2026-09-02 15:54:15.710379 | instance | "inode": 1048381, 2026-09-02 15:54:15.710402 | instance | "isblk": false, 2026-09-02 15:54:15.710428 | instance | "ischr": false, 2026-09-02 15:54:15.710452 | instance | "isdir": true, 2026-09-02 15:54:15.710477 | instance | "isfifo": false, 2026-09-02 15:54:15.710502 | instance | "isgid": false, 2026-09-02 15:54:15.710526 | instance | "islnk": false, 2026-09-02 15:54:15.710550 | instance | "isreg": false, 2026-09-02 15:54:15.710575 | instance | "issock": false, 2026-09-02 15:54:15.710600 | instance | "isuid": false, 2026-09-02 15:54:15.710624 | instance | "mimetype": "unknown", 2026-09-02 15:54:15.710648 | instance | "mode": "0755", 2026-09-02 15:54:15.710673 | instance | "mtime": 1788364434.905829, 2026-09-02 15:54:15.710697 | instance | "nlink": 22, 2026-09-02 15:54:15.710721 | instance | "path": "src/github.com/vexxhost/atmosphere", 2026-09-02 15:54:15.710746 | instance | "pw_name": "zuul", 2026-09-02 15:54:15.710775 | instance | "readable": true, 2026-09-02 15:54:15.710800 | instance | "rgrp": true, 2026-09-02 15:54:15.710825 | instance | "roth": true, 2026-09-02 15:54:15.710865 | instance | "rusr": true, 2026-09-02 15:54:15.710891 | instance | "size": 4096, 2026-09-02 15:54:15.710915 | instance | "uid": 1000, 2026-09-02 15:54:15.710940 | instance | "version": "3919234555", 2026-09-02 15:54:15.710964 | instance | "wgrp": false, 2026-09-02 15:54:15.710991 | instance | "woth": false, 2026-09-02 15:54:15.711016 | instance | "writeable": true, 2026-09-02 15:54:15.711042 | instance | "wusr": true, 2026-09-02 15:54:15.711068 | instance | "xgrp": true, 2026-09-02 15:54:15.711092 | instance | "xoth": true, 2026-09-02 15:54:15.711119 | instance | "xusr": true 2026-09-02 15:54:15.711146 | instance | } 2026-09-02 15:54:15.711171 | instance | } 2026-09-02 15:54:15.711219 | instance | ok: All items complete 2026-09-02 15:54:15.711248 | 2026-09-02 15:54:15.712908 | instance | skipping: Conditional result was False 2026-09-02 15:54:15.713777 | instance | skipping: Conditional result was False 2026-09-02 15:54:15.728528 | 2026-09-02 15:54:15.728695 | LOOP [Switch "ansible_host" to private IP] 2026-09-02 15:54:16.095795 | instance | changed: 1 replacements made 2026-09-02 15:54:16.097111 | instance | skipping: Conditional result was False 2026-09-02 15:54:16.097832 | instance | skipping: Conditional result was False 2026-09-02 15:54:16.116762 | 2026-09-02 15:54:16.116874 | TASK [Run Molecule scenario] 2026-09-02 15:54:17.022885 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-09-02 15:54:17.436867 | instance | INFO [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-02 15:54:17.436955 | instance | INFO [csi > prerun] Performing prerun with role_name_check=0... 2026-09-02 15:54:27.700373 | instance | INFO [csi > dependency] Executing 2026-09-02 15:54:27.700673 | instance | WARNING [csi > dependency] Missing roles requirements file: requirements.yml 2026-09-02 15:54:27.701076 | instance | WARNING [csi > dependency] Missing collections requirements file: collections.yml 2026-09-02 15:54:27.701292 | instance | WARNING [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-09-02 15:54:27.715699 | instance | INFO [csi > cleanup] Executing 2026-09-02 15:54:27.716325 | instance | WARNING [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-02 15:54:27.730053 | instance | INFO [csi > destroy] Executing 2026-09-02 15:54:27.730138 | instance | WARNING [csi > destroy] Skipping, '--destroy=never' requested. 2026-09-02 15:54:27.730234 | instance | INFO [csi > destroy] Executed: Successful 2026-09-02 15:54:27.739581 | instance | INFO [csi > syntax] Executing 2026-09-02 15:54:29.113534 | instance | 2026-09-02 15:54:29.113769 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-02 15:54:29.170880 | instance | INFO [csi > syntax] Executed: Successful 2026-09-02 15:54:29.186535 | instance | INFO [csi > create] Executing 2026-09-02 15:54:29.190272 | instance | WARNING [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-02 15:54:29.204841 | instance | INFO [csi > prepare] Executing 2026-09-02 15:54:29.878009 | instance | 2026-09-02 15:54:29.878121 | instance | PLAY [Prepare] ***************************************************************** 2026-09-02 15:54:29.878319 | instance | 2026-09-02 15:54:29.878550 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-02 15:54:29.878827 | instance | Wednesday 02 September 2026 15:54:29 +0000 (0:00:00.022) 0:00:00.022 *** 2026-09-02 15:54:31.135557 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-02 15:54:31.135817 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-02 15:54:31.136054 | instance | interpreter could change the meaning of that path. See 2026-09-02 15:54:31.136284 | instance | https://docs.ansible.com/ansible- 2026-09-02 15:54:31.136477 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-02 15:54:31.141975 | instance | ok: [instance] 2026-09-02 15:54:31.142147 | instance | 2026-09-02 15:54:31.142371 | instance | TASK [Configure short hostname] ************************************************ 2026-09-02 15:54:31.142602 | instance | Wednesday 02 September 2026 15:54:31 +0000 (0:00:01.264) 0:00:01.287 *** 2026-09-02 15:54:31.891378 | instance | changed: [instance] 2026-09-02 15:54:31.891551 | instance | 2026-09-02 15:54:31.891817 | instance | TASK [Ensure hostname inside hosts file] *************************************** 2026-09-02 15:54:31.892054 | instance | Wednesday 02 September 2026 15:54:31 +0000 (0:00:00.748) 0:00:02.035 *** 2026-09-02 15:54:32.174638 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created 2026-09-02 15:54:32.174899 | instance | with a mode of 0700, this may cause issues when running as another user. To 2026-09-02 15:54:32.175141 | instance | avoid this, create the remote_tmp dir with the correct permissions manually 2026-09-02 15:54:32.183792 | instance | changed: [instance] 2026-09-02 15:54:32.184006 | instance | 2026-09-02 15:54:32.184237 | instance | TASK [Purge "snapd" package] *************************************************** 2026-09-02 15:54:32.184462 | instance | Wednesday 02 September 2026 15:54:32 +0000 (0:00:00.292) 0:00:02.328 *** 2026-09-02 15:54:32.974776 | instance | ok: [instance] 2026-09-02 15:54:32.974988 | instance | 2026-09-02 15:54:32.975225 | instance | PLAY [Create devices for Ceph] ************************************************* 2026-09-02 15:54:32.975436 | instance | 2026-09-02 15:54:32.975683 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-02 15:54:32.975928 | instance | Wednesday 02 September 2026 15:54:32 +0000 (0:00:00.790) 0:00:03.118 *** 2026-09-02 15:54:33.740244 | instance | ok: [instance] 2026-09-02 15:54:33.740409 | instance | 2026-09-02 15:54:33.740703 | instance | TASK [Install depedencies] ***************************************************** 2026-09-02 15:54:33.740951 | instance | Wednesday 02 September 2026 15:54:33 +0000 (0:00:00.765) 0:00:03.884 *** 2026-09-02 15:54:58.671817 | instance | changed: [instance] 2026-09-02 15:54:58.672116 | instance | 2026-09-02 15:54:58.672385 | instance | TASK [Start up service] ******************************************************** 2026-09-02 15:54:58.672664 | instance | Wednesday 02 September 2026 15:54:58 +0000 (0:00:24.930) 0:00:28.815 *** 2026-09-02 15:54:59.253885 | instance | ok: [instance] 2026-09-02 15:54:59.254122 | instance | 2026-09-02 15:54:59.254386 | instance | TASK [Generate lvm.conf] ******************************************************* 2026-09-02 15:54:59.254714 | instance | Wednesday 02 September 2026 15:54:59 +0000 (0:00:00.582) 0:00:29.398 *** 2026-09-02 15:54:59.557372 | instance | ok: [instance] 2026-09-02 15:54:59.557629 | instance | 2026-09-02 15:54:59.557868 | instance | TASK [Write /etc/lvm/lvm.conf] ************************************************* 2026-09-02 15:54:59.558109 | instance | Wednesday 02 September 2026 15:54:59 +0000 (0:00:00.303) 0:00:29.701 *** 2026-09-02 15:55:00.227679 | instance | changed: [instance] 2026-09-02 15:55:00.227898 | instance | 2026-09-02 15:55:00.227912 | instance | TASK [Get list of all loopback devices] **************************************** 2026-09-02 15:55:00.227922 | instance | Wednesday 02 September 2026 15:55:00 +0000 (0:00:00.669) 0:00:30.371 *** 2026-09-02 15:55:00.436098 | instance | ok: [instance] 2026-09-02 15:55:00.436835 | instance | 2026-09-02 15:55:00.436888 | instance | TASK [Fail if there is any existing loopback devices] ************************** 2026-09-02 15:55:00.436900 | instance | Wednesday 02 September 2026 15:55:00 +0000 (0:00:00.208) 0:00:30.580 *** 2026-09-02 15:55:00.461732 | instance | skipping: [instance] 2026-09-02 15:55:00.462477 | instance | 2026-09-02 15:55:00.462531 | instance | TASK [Create devices for Ceph] ************************************************* 2026-09-02 15:55:00.462543 | instance | Wednesday 02 September 2026 15:55:00 +0000 (0:00:00.026) 0:00:30.606 *** 2026-09-02 15:55:01.076493 | instance | changed: [instance] => (item=osd0) 2026-09-02 15:55:01.076780 | instance | changed: [instance] => (item=osd1) 2026-09-02 15:55:01.077183 | instance | changed: [instance] => (item=osd2) 2026-09-02 15:55:01.077510 | instance | 2026-09-02 15:55:01.077876 | instance | TASK [Set permissions on loopback devices] ************************************* 2026-09-02 15:55:01.078256 | instance | Wednesday 02 September 2026 15:55:01 +0000 (0:00:00.614) 0:00:31.220 *** 2026-09-02 15:55:01.740036 | instance | changed: [instance] => (item=osd0) 2026-09-02 15:55:01.740258 | instance | changed: [instance] => (item=osd1) 2026-09-02 15:55:01.740618 | instance | changed: [instance] => (item=osd2) 2026-09-02 15:55:01.740903 | instance | 2026-09-02 15:55:01.741192 | instance | TASK [Start loop devices] ****************************************************** 2026-09-02 15:55:01.741480 | instance | Wednesday 02 September 2026 15:55:01 +0000 (0:00:00.663) 0:00:31.884 *** 2026-09-02 15:55:02.497507 | instance | changed: [instance] => (item=osd0) 2026-09-02 15:55:02.497938 | instance | changed: [instance] => (item=osd1) 2026-09-02 15:55:02.498250 | instance | changed: [instance] => (item=osd2) 2026-09-02 15:55:02.498546 | instance | 2026-09-02 15:55:02.498920 | instance | TASK [Create a volume group for each loop device] ****************************** 2026-09-02 15:55:02.499240 | instance | Wednesday 02 September 2026 15:55:02 +0000 (0:00:00.757) 0:00:32.641 *** 2026-09-02 15:55:05.888710 | instance | changed: [instance] => (item=osd0) 2026-09-02 15:55:05.889084 | instance | changed: [instance] => (item=osd1) 2026-09-02 15:55:05.889377 | instance | changed: [instance] => (item=osd2) 2026-09-02 15:55:05.889694 | instance | 2026-09-02 15:55:05.889987 | instance | TASK [Create a logical volume for each loop device] **************************** 2026-09-02 15:55:05.890277 | instance | Wednesday 02 September 2026 15:55:05 +0000 (0:00:03.391) 0:00:36.033 *** 2026-09-02 15:55:07.815614 | instance | changed: [instance] => (item=ceph-instance-osd0) 2026-09-02 15:55:07.815994 | instance | changed: [instance] => (item=ceph-instance-osd1) 2026-09-02 15:55:07.816314 | instance | changed: [instance] => (item=ceph-instance-osd2) 2026-09-02 15:55:07.816612 | instance | 2026-09-02 15:55:07.816905 | instance | PLAY RECAP ********************************************************************* 2026-09-02 15:55:07.817214 | instance | instance : ok=15 changed=9 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-09-02 15:55:07.817487 | instance | 2026-09-02 15:55:07.817804 | instance | Wednesday 02 September 2026 15:55:07 +0000 (0:00:01.927) 0:00:37.960 *** 2026-09-02 15:55:07.818086 | instance | =============================================================================== 2026-09-02 15:55:07.818372 | instance | Install depedencies ---------------------------------------------------- 24.93s 2026-09-02 15:55:07.818683 | instance | Create a volume group for each loop device ------------------------------ 3.39s 2026-09-02 15:55:07.819023 | instance | Create a logical volume for each loop device ---------------------------- 1.93s 2026-09-02 15:55:07.819310 | instance | Gathering Facts --------------------------------------------------------- 1.26s 2026-09-02 15:55:07.819618 | instance | Purge "snapd" package --------------------------------------------------- 0.79s 2026-09-02 15:55:07.819906 | instance | Gathering Facts --------------------------------------------------------- 0.77s 2026-09-02 15:55:07.820189 | instance | Start loop devices ------------------------------------------------------ 0.76s 2026-09-02 15:55:07.820474 | instance | Configure short hostname ------------------------------------------------ 0.75s 2026-09-02 15:55:07.820785 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.67s 2026-09-02 15:55:07.821073 | instance | Set permissions on loopback devices ------------------------------------- 0.66s 2026-09-02 15:55:07.821357 | instance | Create devices for Ceph ------------------------------------------------- 0.61s 2026-09-02 15:55:07.821665 | instance | Start up service -------------------------------------------------------- 0.58s 2026-09-02 15:55:07.821950 | instance | Generate lvm.conf ------------------------------------------------------- 0.30s 2026-09-02 15:55:07.822235 | instance | Ensure hostname inside hosts file --------------------------------------- 0.29s 2026-09-02 15:55:07.822519 | instance | Get list of all loopback devices ---------------------------------------- 0.21s 2026-09-02 15:55:07.822912 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s 2026-09-02 15:55:07.927318 | instance | INFO [csi > prepare] Executed: Successful 2026-09-02 15:55:07.942037 | instance | INFO [csi > converge] Executing 2026-09-02 15:55:09.312341 | instance | 2026-09-02 15:55:09.312411 | instance | PLAY [Debug CSI driver value] ************************************************** 2026-09-02 15:55:09.312423 | instance | 2026-09-02 15:55:09.312433 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-02 15:55:09.312442 | instance | Wednesday 02 September 2026 15:55:09 +0000 (0:00:00.007) 0:00:00.007 *** 2026-09-02 15:55:10.381768 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-02 15:55:10.382026 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-02 15:55:10.382041 | instance | interpreter could change the meaning of that path. See 2026-09-02 15:55:10.382057 | instance | https://docs.ansible.com/ansible- 2026-09-02 15:55:10.382067 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-02 15:55:10.394613 | instance | ok: [instance] 2026-09-02 15:55:10.394874 | instance | 2026-09-02 15:55:10.395120 | instance | TASK [Display CSI driver value and environment variable] *********************** 2026-09-02 15:55:10.395355 | instance | Wednesday 02 September 2026 15:55:10 +0000 (0:00:01.083) 0:00:01.090 *** 2026-09-02 15:55:10.458711 | instance | ok: [instance] => { 2026-09-02 15:55:10.458969 | instance | "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER=" 2026-09-02 15:55:10.459250 | instance | } 2026-09-02 15:55:10.459523 | instance | 2026-09-02 15:55:10.459851 | instance | PLAY [all] ********************************************************************* 2026-09-02 15:55:10.460141 | instance | 2026-09-02 15:55:10.460408 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-02 15:55:10.460685 | instance | Wednesday 02 September 2026 15:55:10 +0000 (0:00:00.064) 0:00:01.154 *** 2026-09-02 15:55:11.489500 | instance | ok: [instance] 2026-09-02 15:55:11.489583 | instance | 2026-09-02 15:55:11.489595 | instance | TASK [Fail if atmosphere_ceph_enabled is set] ********************************** 2026-09-02 15:55:11.489605 | instance | Wednesday 02 September 2026 15:55:11 +0000 (0:00:01.029) 0:00:02.184 *** 2026-09-02 15:55:11.537483 | instance | skipping: [instance] 2026-09-02 15:55:11.537704 | instance | 2026-09-02 15:55:11.538062 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] ***************** 2026-09-02 15:55:11.538366 | instance | Wednesday 02 September 2026 15:55:11 +0000 (0:00:00.048) 0:00:02.233 *** 2026-09-02 15:55:11.744906 | instance | ok: [instance] 2026-09-02 15:55:11.744971 | instance | 2026-09-02 15:55:11.745075 | instance | PLAY [Deploy Ceph monitors & managers] ***************************************** 2026-09-02 15:55:11.745236 | instance | 2026-09-02 15:55:11.745371 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-02 15:55:11.745511 | instance | Wednesday 02 September 2026 15:55:11 +0000 (0:00:00.208) 0:00:02.441 *** 2026-09-02 15:55:12.731487 | instance | ok: [instance] 2026-09-02 15:55:12.731862 | instance | 2026-09-02 15:55:12.732170 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-02 15:55:12.732469 | instance | Wednesday 02 September 2026 15:55:12 +0000 (0:00:00.984) 0:00:03.425 *** 2026-09-02 15:55:13.092444 | instance | ok: [instance] 2026-09-02 15:55:13.092495 | instance | 2026-09-02 15:55:13.092508 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-02 15:55:13.092519 | instance | Wednesday 02 September 2026 15:55:13 +0000 (0:00:00.361) 0:00:03.787 *** 2026-09-02 15:55:13.148626 | instance | skipping: [instance] 2026-09-02 15:55:13.149829 | instance | 2026-09-02 15:55:13.149908 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-09-02 15:55:13.149925 | instance | Wednesday 02 September 2026 15:55:13 +0000 (0:00:00.056) 0:00:03.843 *** 2026-09-02 15:55:13.516288 | instance | changed: [instance] 2026-09-02 15:55:13.517064 | instance | 2026-09-02 15:55:13.517128 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-02 15:55:13.517140 | instance | Wednesday 02 September 2026 15:55:13 +0000 (0:00:00.368) 0:00:04.211 *** 2026-09-02 15:55:13.616691 | instance | ok: [instance] => { 2026-09-02 15:55:13.616928 | instance | "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64" 2026-09-02 15:55:13.617287 | instance | } 2026-09-02 15:55:13.617593 | instance | 2026-09-02 15:55:13.617940 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-02 15:55:13.618272 | instance | Wednesday 02 September 2026 15:55:13 +0000 (0:00:00.100) 0:00:04.312 *** 2026-09-02 15:55:14.401851 | instance | changed: [instance] 2026-09-02 15:55:14.402126 | instance | 2026-09-02 15:55:14.402602 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-02 15:55:14.402997 | instance | Wednesday 02 September 2026 15:55:14 +0000 (0:00:00.784) 0:00:05.096 *** 2026-09-02 15:55:14.473454 | instance | skipping: [instance] 2026-09-02 15:55:14.473587 | instance | 2026-09-02 15:55:14.473600 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-02 15:55:14.473764 | instance | Wednesday 02 September 2026 15:55:14 +0000 (0:00:00.072) 0:00:05.169 *** 2026-09-02 15:55:14.543530 | instance | skipping: [instance] 2026-09-02 15:55:14.543721 | instance | 2026-09-02 15:55:14.543983 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-02 15:55:14.544181 | instance | Wednesday 02 September 2026 15:55:14 +0000 (0:00:00.066) 0:00:05.236 *** 2026-09-02 15:55:14.783695 | instance | ok: [instance] 2026-09-02 15:55:14.784213 | instance | 2026-09-02 15:55:14.784645 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-02 15:55:14.785146 | instance | Wednesday 02 September 2026 15:55:14 +0000 (0:00:00.241) 0:00:05.478 *** 2026-09-02 15:55:16.034682 | instance | ok: [instance] 2026-09-02 15:55:16.035464 | instance | 2026-09-02 15:55:16.035542 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-02 15:55:16.035595 | instance | Wednesday 02 September 2026 15:55:16 +0000 (0:00:01.251) 0:00:06.729 *** 2026-09-02 15:55:16.124718 | instance | ok: [instance] => { 2026-09-02 15:55:16.124887 | instance | "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz" 2026-09-02 15:55:16.125794 | instance | } 2026-09-02 15:55:16.125860 | instance | 2026-09-02 15:55:16.125872 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-02 15:55:16.125882 | instance | Wednesday 02 September 2026 15:55:16 +0000 (0:00:00.090) 0:00:06.820 *** 2026-09-02 15:55:16.917075 | instance | changed: [instance] 2026-09-02 15:55:16.917769 | instance | 2026-09-02 15:55:16.917833 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-02 15:55:16.917846 | instance | Wednesday 02 September 2026 15:55:16 +0000 (0:00:00.792) 0:00:07.612 *** 2026-09-02 15:55:19.866720 | instance | changed: [instance] 2026-09-02 15:55:19.866819 | instance | 2026-09-02 15:55:19.866831 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] *************** 2026-09-02 15:55:19.866841 | instance | Wednesday 02 September 2026 15:55:19 +0000 (0:00:02.948) 0:00:10.561 *** 2026-09-02 15:55:19.904082 | instance | skipping: [instance] 2026-09-02 15:55:19.905043 | instance | 2026-09-02 15:55:19.905228 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] *** 2026-09-02 15:55:19.905241 | instance | Wednesday 02 September 2026 15:55:19 +0000 (0:00:00.038) 0:00:10.599 *** 2026-09-02 15:55:19.939768 | instance | skipping: [instance] 2026-09-02 15:55:19.939831 | instance | 2026-09-02 15:55:19.939843 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ******** 2026-09-02 15:55:19.939853 | instance | Wednesday 02 September 2026 15:55:19 +0000 (0:00:00.033) 0:00:10.633 *** 2026-09-02 15:55:19.984100 | instance | skipping: [instance] 2026-09-02 15:55:19.984178 | instance | 2026-09-02 15:55:19.984190 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] ************** 2026-09-02 15:55:19.984201 | instance | Wednesday 02 September 2026 15:55:19 +0000 (0:00:00.044) 0:00:10.678 *** 2026-09-02 15:55:26.256595 | instance | changed: [instance] 2026-09-02 15:55:26.256760 | instance | 2026-09-02 15:55:26.257047 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] *** 2026-09-02 15:55:26.257400 | instance | Wednesday 02 September 2026 15:55:26 +0000 (0:00:06.273) 0:00:16.952 *** 2026-09-02 15:55:26.922821 | instance | changed: [instance] 2026-09-02 15:55:26.922890 | instance | 2026-09-02 15:55:26.923299 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] ******* 2026-09-02 15:55:26.923330 | instance | Wednesday 02 September 2026 15:55:26 +0000 (0:00:00.665) 0:00:17.618 *** 2026-09-02 15:55:27.930167 | instance | changed: [instance] => (item={'path': '/etc/containerd'}) 2026-09-02 15:55:27.930780 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'}) 2026-09-02 15:55:27.930837 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'}) 2026-09-02 15:55:27.930863 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'}) 2026-09-02 15:55:27.931582 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'}) 2026-09-02 15:55:27.931612 | instance | 2026-09-02 15:55:27.931622 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] ********** 2026-09-02 15:55:27.931630 | instance | Wednesday 02 September 2026 15:55:27 +0000 (0:00:01.007) 0:00:18.626 *** 2026-09-02 15:55:28.602455 | instance | changed: [instance] 2026-09-02 15:55:28.602548 | instance | 2026-09-02 15:55:28.602843 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ******** 2026-09-02 15:55:28.602910 | instance | Wednesday 02 September 2026 15:55:28 +0000 (0:00:00.645) 0:00:19.271 *** 2026-09-02 15:55:28.603351 | instance | 2026-09-02 15:55:28.603590 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-02 15:55:28.603598 | instance | Wednesday 02 September 2026 15:55:28 +0000 (0:00:00.026) 0:00:19.298 *** 2026-09-02 15:55:29.635895 | instance | ok: [instance] 2026-09-02 15:55:29.635968 | instance | 2026-09-02 15:55:29.635981 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] ********** 2026-09-02 15:55:29.635991 | instance | Wednesday 02 September 2026 15:55:29 +0000 (0:00:01.031) 0:00:20.330 *** 2026-09-02 15:55:30.118815 | instance | changed: [instance] 2026-09-02 15:55:30.119592 | instance | 2026-09-02 15:55:30.119653 | instance | TASK [vexxhost.containers.containerd : Enable and start service] *************** 2026-09-02 15:55:30.119665 | instance | Wednesday 02 September 2026 15:55:30 +0000 (0:00:00.484) 0:00:20.814 *** 2026-09-02 15:55:30.736139 | instance | changed: [instance] 2026-09-02 15:55:30.736852 | instance | 2026-09-02 15:55:30.736910 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-02 15:55:30.736921 | instance | Wednesday 02 September 2026 15:55:30 +0000 (0:00:00.617) 0:00:21.431 *** 2026-09-02 15:55:30.980784 | instance | ok: [instance] 2026-09-02 15:55:30.981537 | instance | 2026-09-02 15:55:30.981596 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-02 15:55:30.981609 | instance | Wednesday 02 September 2026 15:55:30 +0000 (0:00:00.244) 0:00:21.676 *** 2026-09-02 15:55:31.049290 | instance | ok: [instance] => { 2026-09-02 15:55:31.050060 | instance | "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz" 2026-09-02 15:55:31.050091 | instance | } 2026-09-02 15:55:31.050098 | instance | 2026-09-02 15:55:31.050105 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-02 15:55:31.050112 | instance | Wednesday 02 September 2026 15:55:31 +0000 (0:00:00.068) 0:00:21.745 *** 2026-09-02 15:55:31.805834 | instance | changed: [instance] 2026-09-02 15:55:31.806659 | instance | 2026-09-02 15:55:31.806753 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-02 15:55:31.806769 | instance | Wednesday 02 September 2026 15:55:31 +0000 (0:00:00.755) 0:00:22.501 *** 2026-09-02 15:55:36.111381 | instance | changed: [instance] 2026-09-02 15:55:36.111466 | instance | 2026-09-02 15:55:36.111807 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ****************** 2026-09-02 15:55:36.111851 | instance | Wednesday 02 September 2026 15:55:36 +0000 (0:00:04.306) 0:00:26.807 *** 2026-09-02 15:55:37.130595 | instance | ok: [instance] 2026-09-02 15:55:37.131575 | instance | 2026-09-02 15:55:37.131659 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] *************** 2026-09-02 15:55:37.131671 | instance | Wednesday 02 September 2026 15:55:37 +0000 (0:00:01.017) 0:00:27.825 *** 2026-09-02 15:55:37.501590 | instance | changed: [instance] 2026-09-02 15:55:37.501687 | instance | 2026-09-02 15:55:37.502058 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] ***** 2026-09-02 15:55:37.502116 | instance | Wednesday 02 September 2026 15:55:37 +0000 (0:00:00.372) 0:00:28.197 *** 2026-09-02 15:55:38.017540 | instance | changed: [instance] 2026-09-02 15:55:38.017606 | instance | 2026-09-02 15:55:38.017898 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] *********** 2026-09-02 15:55:38.017956 | instance | Wednesday 02 September 2026 15:55:38 +0000 (0:00:00.515) 0:00:28.713 *** 2026-09-02 15:55:38.642052 | instance | changed: [instance] => (item={'path': '/etc/docker'}) 2026-09-02 15:55:38.642788 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'}) 2026-09-02 15:55:38.642862 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'}) 2026-09-02 15:55:38.642874 | instance | 2026-09-02 15:55:38.643249 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ****** 2026-09-02 15:55:38.643285 | instance | Wednesday 02 September 2026 15:55:38 +0000 (0:00:00.624) 0:00:29.337 *** 2026-09-02 15:55:39.157337 | instance | changed: [instance] 2026-09-02 15:55:39.158095 | instance | 2026-09-02 15:55:39.158183 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] *********** 2026-09-02 15:55:39.158195 | instance | Wednesday 02 September 2026 15:55:39 +0000 (0:00:00.514) 0:00:29.852 *** 2026-09-02 15:55:39.634600 | instance | changed: [instance] 2026-09-02 15:55:39.634658 | instance | 2026-09-02 15:55:39.635232 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************ 2026-09-02 15:55:39.635260 | instance | Wednesday 02 September 2026 15:55:39 +0000 (0:00:00.464) 0:00:30.316 *** 2026-09-02 15:55:39.635265 | instance | 2026-09-02 15:55:39.635269 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-02 15:55:39.635277 | instance | Wednesday 02 September 2026 15:55:39 +0000 (0:00:00.013) 0:00:30.330 *** 2026-09-02 15:55:40.373045 | instance | ok: [instance] 2026-09-02 15:55:40.373106 | instance | 2026-09-02 15:55:40.373347 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ****************** 2026-09-02 15:55:40.373389 | instance | Wednesday 02 September 2026 15:55:40 +0000 (0:00:00.738) 0:00:31.069 *** 2026-09-02 15:55:41.672367 | instance | changed: [instance] 2026-09-02 15:55:41.673103 | instance | 2026-09-02 15:55:41.673161 | instance | TASK [vexxhost.containers.docker : Enable and start service] ******************* 2026-09-02 15:55:41.673174 | instance | Wednesday 02 September 2026 15:55:41 +0000 (0:00:01.298) 0:00:32.368 *** 2026-09-02 15:55:42.277561 | instance | changed: [instance] 2026-09-02 15:55:42.277640 | instance | 2026-09-02 15:55:42.277969 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ****** 2026-09-02 15:55:42.278012 | instance | Wednesday 02 September 2026 15:55:42 +0000 (0:00:00.605) 0:00:32.973 *** 2026-09-02 15:55:42.324559 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml) 2026-09-02 15:55:42.324881 | instance | 2026-09-02 15:55:42.324925 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ******************************** 2026-09-02 15:55:42.324931 | instance | Wednesday 02 September 2026 15:55:42 +0000 (0:00:00.047) 0:00:33.020 *** 2026-09-02 15:55:47.666126 | instance | changed: [instance] 2026-09-02 15:55:47.666834 | instance | 2026-09-02 15:55:47.667091 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] ********************* 2026-09-02 15:55:47.667133 | instance | Wednesday 02 September 2026 15:55:47 +0000 (0:00:05.338) 0:00:38.359 *** 2026-09-02 15:55:48.365964 | instance | ok: [instance] => (item=chronyd) 2026-09-02 15:55:48.366495 | instance | ok: [instance] => (item=sshd) 2026-09-02 15:55:48.366561 | instance | 2026-09-02 15:55:48.366831 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ****************************** 2026-09-02 15:55:48.367013 | instance | Wednesday 02 September 2026 15:55:48 +0000 (0:00:00.702) 0:00:39.061 *** 2026-09-02 15:55:48.713610 | instance | changed: [instance] 2026-09-02 15:55:48.714308 | instance | 2026-09-02 15:55:48.714360 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ******************** 2026-09-02 15:55:48.714372 | instance | Wednesday 02 September 2026 15:55:48 +0000 (0:00:00.347) 0:00:39.409 *** 2026-09-02 15:55:48.913751 | instance | ok: [instance] 2026-09-02 15:55:48.913833 | instance | 2026-09-02 15:55:48.913893 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] **************** 2026-09-02 15:55:48.914011 | instance | Wednesday 02 September 2026 15:55:48 +0000 (0:00:00.200) 0:00:39.610 *** 2026-09-02 15:55:49.379850 | instance | changed: [instance] 2026-09-02 15:55:49.379950 | instance | 2026-09-02 15:55:49.380282 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] *** 2026-09-02 15:55:49.380327 | instance | Wednesday 02 September 2026 15:55:49 +0000 (0:00:00.465) 0:00:40.075 *** 2026-09-02 15:55:49.738637 | instance | changed: [instance] 2026-09-02 15:55:49.738726 | instance | 2026-09-02 15:55:49.739042 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] ************************* 2026-09-02 15:55:49.739064 | instance | Wednesday 02 September 2026 15:55:49 +0000 (0:00:00.358) 0:00:40.434 *** 2026-09-02 15:55:49.938063 | instance | ok: [instance] 2026-09-02 15:55:49.938132 | instance | 2026-09-02 15:55:49.938465 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] ******************* 2026-09-02 15:55:49.938514 | instance | Wednesday 02 September 2026 15:55:49 +0000 (0:00:00.199) 0:00:40.634 *** 2026-09-02 15:55:50.193387 | instance | ok: [instance] => (item=instance) 2026-09-02 15:55:50.193487 | instance | 2026-09-02 15:55:50.193829 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ******** 2026-09-02 15:55:50.193876 | instance | Wednesday 02 September 2026 15:55:50 +0000 (0:00:00.255) 0:00:40.889 *** 2026-09-02 15:55:50.264706 | instance | ok: [instance] 2026-09-02 15:55:50.265373 | instance | 2026-09-02 15:55:50.265432 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] *********************************** 2026-09-02 15:55:50.265444 | instance | Wednesday 02 September 2026 15:55:50 +0000 (0:00:00.070) 0:00:40.960 *** 2026-09-02 15:55:50.351475 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance 2026-09-02 15:55:50.351537 | instance | 2026-09-02 15:55:50.351543 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] ************* 2026-09-02 15:55:50.351549 | instance | Wednesday 02 September 2026 15:55:50 +0000 (0:00:00.086) 0:00:41.046 *** 2026-09-02 15:55:50.653164 | instance | changed: [instance] 2026-09-02 15:55:50.653897 | instance | 2026-09-02 15:55:50.653954 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ****************** 2026-09-02 15:55:50.653967 | instance | Wednesday 02 September 2026 15:55:50 +0000 (0:00:00.301) 0:00:41.348 *** 2026-09-02 15:55:51.337052 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True}) 2026-09-02 15:55:51.338177 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0}) 2026-09-02 15:55:51.338241 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False}) 2026-09-02 15:55:51.338255 | instance | 2026-09-02 15:55:51.338265 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] ******************************* 2026-09-02 15:55:51.338275 | instance | Wednesday 02 September 2026 15:55:51 +0000 (0:00:00.684) 0:00:42.032 *** 2026-09-02 15:57:29.757252 | instance | ok: [instance] 2026-09-02 15:57:29.757324 | instance | 2026-09-02 15:57:29.757337 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] *************** 2026-09-02 15:57:29.757347 | instance | Wednesday 02 September 2026 15:57:29 +0000 (0:01:38.418) 0:02:20.451 *** 2026-09-02 15:57:30.002312 | instance | changed: [instance] 2026-09-02 15:57:30.002442 | instance | 2026-09-02 15:57:30.002621 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] ********************************** 2026-09-02 15:57:30.002901 | instance | Wednesday 02 September 2026 15:57:29 +0000 (0:00:00.246) 0:02:20.698 *** 2026-09-02 15:57:30.059262 | instance | ok: [instance] 2026-09-02 15:57:30.059307 | instance | 2026-09-02 15:57:30.059315 | instance | TASK [Install Ceph host] ******************************************************* 2026-09-02 15:57:30.059323 | instance | Wednesday 02 September 2026 15:57:30 +0000 (0:00:00.056) 0:02:20.754 *** 2026-09-02 15:57:30.126833 | instance | included: vexxhost.ceph.cephadm_host for instance 2026-09-02 15:57:30.126889 | instance | 2026-09-02 15:57:30.127076 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ****** 2026-09-02 15:57:30.127092 | instance | Wednesday 02 September 2026 15:57:30 +0000 (0:00:00.068) 0:02:20.823 *** 2026-09-02 15:57:31.830222 | instance | ok: [instance] 2026-09-02 15:57:31.830401 | instance | 2026-09-02 15:57:31.830663 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] *** 2026-09-02 15:57:31.830964 | instance | Wednesday 02 September 2026 15:57:31 +0000 (0:00:01.702) 0:02:22.525 *** 2026-09-02 15:57:31.908640 | instance | ok: [instance] => (item=instance) 2026-09-02 15:57:31.908908 | instance | 2026-09-02 15:57:31.909178 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] *********** 2026-09-02 15:57:31.909461 | instance | Wednesday 02 September 2026 15:57:31 +0000 (0:00:00.078) 0:02:22.603 *** 2026-09-02 15:57:32.289385 | instance | ok: [instance] 2026-09-02 15:57:32.289466 | instance | 2026-09-02 15:57:32.289484 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] *********************** 2026-09-02 15:57:32.289499 | instance | Wednesday 02 September 2026 15:57:32 +0000 (0:00:00.379) 0:02:22.983 *** 2026-09-02 15:57:34.847799 | instance | ok: [instance] 2026-09-02 15:57:34.847845 | instance | 2026-09-02 15:57:34.847853 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ****************** 2026-09-02 15:57:34.847860 | instance | Wednesday 02 September 2026 15:57:34 +0000 (0:00:02.559) 0:02:25.542 *** 2026-09-02 15:58:30.543634 | 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-02 15:58:30.544470 | 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_frbmxsr9/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_frbmxsr9/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_frbmxsr9/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_frbmxsr9/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-02T15:58:30.351+0000 7fb5b18df640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgA/R5hqFhvcOiAALBnzhAMf1JGDcahQ8C7bos3FnAF0CJ+j9lO8xDYll38=: Malformed input [buffer:3]\n2026-09-02T15:58:30.351+0000 7fb5b18df640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-02T15:58:30.355+0000 7fb5b18df640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgA/R5hqFhvcOiAALBnzhAMf1JGDcahQ8C7bos3FnAF0CJ+j9lO8xDYll38=: Malformed input [buffer:3]\n2026-09-02T15:58:30.355+0000 7fb5b18df640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-02T15:58:30.355+0000 7fb5b18df640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgA/R5hqFhvcOiAALBnzhAMf1JGDcahQ8C7bos3FnAF0CJ+j9lO8xDYll38=: Malformed input [buffer:3]\n2026-09-02T15:58:30.355+0000 7fb5b18df640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-02T15:58:30.355+0000 7fb5b18df640 -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-02 15:58:30.547264 | instance | 2026-09-02 15:58:30.547509 | instance | PLAY RECAP ********************************************************************* 2026-09-02 15:58:30.547761 | instance | instance : ok=55 changed=26 unreachable=0 failed=1 skipped=7 rescued=0 ignored=0 2026-09-02 15:58:30.548050 | instance | 2026-09-02 15:58:30.548284 | instance | Wednesday 02 September 2026 15:58:30 +0000 (0:00:55.700) 0:03:21.243 *** 2026-09-02 15:58:30.548495 | instance | =============================================================================== 2026-09-02 15:58:30.548733 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 98.42s 2026-09-02 15:58:30.548975 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 55.70s 2026-09-02 15:58:30.549198 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 6.27s 2026-09-02 15:58:30.549418 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.34s 2026-09-02 15:58:30.549638 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.31s 2026-09-02 15:58:30.549856 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.95s 2026-09-02 15:58:30.550101 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.56s 2026-09-02 15:58:30.550323 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.77s 2026-09-02 15:58:30.550545 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.70s 2026-09-02 15:58:30.550813 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 1.30s 2026-09-02 15:58:30.551045 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.25s 2026-09-02 15:58:30.551258 | instance | Gathering Facts --------------------------------------------------------- 1.08s 2026-09-02 15:58:30.551474 | instance | Gathering Facts --------------------------------------------------------- 1.03s 2026-09-02 15:58:30.551693 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.02s 2026-09-02 15:58:30.551935 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.01s 2026-09-02 15:58:30.552165 | instance | Gathering Facts --------------------------------------------------------- 0.98s 2026-09-02 15:58:30.552408 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.79s 2026-09-02 15:58:30.552637 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.78s 2026-09-02 15:58:30.552865 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.76s 2026-09-02 15:58:30.553113 | instance | vexxhost.ceph.cephadm : Ensure services are started --------------------- 0.70s 2026-09-02 15:58:30.647859 | 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-02 15:58:30.648072 | instance | ERROR [csi > converge] Executed: Failed 2026-09-02 15:58:30.648144 | 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-02 15:58:31.002093 | instance | ERROR 2026-09-02 15:58:31.002675 | instance | { 2026-09-02 15:58:31.002720 | instance | "delta": "0:04:14.246736", 2026-09-02 15:58:31.002744 | instance | "end": "2026-09-02 15:58:30.716957", 2026-09-02 15:58:31.002764 | instance | "msg": "non-zero return code", 2026-09-02 15:58:31.002783 | instance | "rc": 2, 2026-09-02 15:58:31.002800 | instance | "start": "2026-09-02 15:54:16.470221" 2026-09-02 15:58:31.002819 | instance | } failure 2026-09-02 15:58:31.007835 | 2026-09-02 15:58:31.007879 | PLAY RECAP 2026-09-02 15:58:31.007925 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-02 15:58:31.007948 | 2026-09-02 15:58:31.236519 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-02 15:58:31.238686 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-02 15:58:31.851184 | 2026-09-02 15:58:31.851326 | PLAY [all] 2026-09-02 15:58:31.865905 | 2026-09-02 15:58:31.865987 | TASK [gather-host-logs : creating directory for system status] 2026-09-02 15:58:32.254936 | instance | changed 2026-09-02 15:58:32.260220 | 2026-09-02 15:58:32.260300 | TASK [gather-host-logs : Get logs for each host] 2026-09-02 15:58:32.629595 | instance | + systemd-cgls --full --all --no-pager 2026-09-02 15:58:32.647325 | instance | + ip addr 2026-09-02 15:58:32.650104 | instance | + ip route 2026-09-02 15:58:32.652835 | instance | + lsblk 2026-09-02 15:58:32.658253 | instance | + mount 2026-09-02 15:58:32.661490 | instance | + docker images 2026-09-02 15:58:32.684535 | instance | + brctl show 2026-09-02 15:58:32.685089 | instance | /bin/bash: line 8: brctl: command not found 2026-09-02 15:58:32.685383 | instance | + ps aux --sort=-%mem 2026-09-02 15:58:32.705612 | instance | + dpkg -l 2026-09-02 15:58:32.717713 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-09-02 15:58:32.718286 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-09-02 15:58:32.737433 | instance | + '[' '!' -z '' ']' 2026-09-02 15:58:32.799235 | instance | ok: Runtime: 0:00:00.113970 2026-09-02 15:58:32.807571 | 2026-09-02 15:58:32.807645 | TASK [gather-host-logs : Downloads logs to executor] 2026-09-02 15:58:33.459160 | instance | changed: 2026-09-02 15:58:33.459342 | instance | created directory /var/lib/zuul/builds/9d191e3aae8440de8ae94de4b243abb2/work/logs/instance 2026-09-02 15:58:33.459371 | instance | cd+++++++++ system/ 2026-09-02 15:58:33.459393 | instance | >f+++++++++ system/brctl-show.txt 2026-09-02 15:58:33.459413 | instance | >f+++++++++ system/docker-images.txt 2026-09-02 15:58:33.459433 | instance | >f+++++++++ system/ip-addr.txt 2026-09-02 15:58:33.459456 | instance | >f+++++++++ system/ip-route.txt 2026-09-02 15:58:33.459476 | instance | >f+++++++++ system/lsblk.txt 2026-09-02 15:58:33.459496 | instance | >f+++++++++ system/mount.txt 2026-09-02 15:58:33.459516 | instance | >f+++++++++ system/packages.txt 2026-09-02 15:58:33.459535 | instance | >f+++++++++ system/ps.txt 2026-09-02 15:58:33.459556 | instance | >f+++++++++ system/systemd-cgls.txt 2026-09-02 15:58:33.479510 | 2026-09-02 15:58:33.479724 | LOOP [helm-release-status : creating directory for helm release status] 2026-09-02 15:58:33.694264 | instance | changed: "values" 2026-09-02 15:58:33.900786 | instance | changed: "releases" 2026-09-02 15:58:33.916032 | 2026-09-02 15:58:33.916221 | TASK [helm-release-status : Gather get release status for helm charts] 2026-09-02 15:58:34.156980 | instance | /bin/bash: line 3: kubectl: command not found 2026-09-02 15:58:34.454592 | instance | ok: Runtime: 0:00:00.006256 2026-09-02 15:58:34.461609 | 2026-09-02 15:58:34.461706 | TASK [helm-release-status : Downloads logs to executor] 2026-09-02 15:58:34.981197 | instance | changed: 2026-09-02 15:58:34.981398 | instance | cd+++++++++ helm/ 2026-09-02 15:58:34.981436 | instance | cd+++++++++ helm/releases/ 2026-09-02 15:58:34.981467 | instance | cd+++++++++ helm/values/ 2026-09-02 15:58:34.991931 | 2026-09-02 15:58:34.991995 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-09-02 15:58:35.202139 | instance | changed 2026-09-02 15:58:35.208271 | 2026-09-02 15:58:35.208347 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-09-02 15:58:35.510453 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-02 15:58:35.511667 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-02 15:58:35.515848 | instance | environment: line 1: kubectl: command not found 2026-09-02 15:58:35.517175 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-02 15:58:35.518260 | instance | environment: line 1: kubectl: command not found 2026-09-02 15:58:35.520709 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-02 15:58:35.521411 | instance | environment: line 1: kubectl: command not found 2026-09-02 15:58:35.523330 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-02 15:58:35.524569 | instance | environment: line 1: kubectl: command not found 2026-09-02 15:58:35.527131 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-02 15:58:35.527948 | instance | environment: line 1: kubectl: command not found 2026-09-02 15:58:35.528613 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-02 15:58:35.758821 | instance | ok: Runtime: 0:00:00.027174 2026-09-02 15:58:35.766009 | 2026-09-02 15:58:35.766139 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-09-02 15:58:35.997696 | instance | changed 2026-09-02 15:58:36.002537 | 2026-09-02 15:58:36.002605 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-09-02 15:58:36.243461 | instance | environment: line 5: kubectl: command not found 2026-09-02 15:58:36.244698 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-02 15:58:36.245081 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-02 15:58:36.245430 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-02 15:58:36.543976 | instance | ok: Runtime: 0:00:00.009357 2026-09-02 15:58:36.551435 | 2026-09-02 15:58:36.551507 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-09-02 15:58:37.072865 | instance | changed: 2026-09-02 15:58:37.073206 | instance | cd+++++++++ objects/ 2026-09-02 15:58:37.073253 | instance | cd+++++++++ objects/cluster/ 2026-09-02 15:58:37.073287 | instance | cd+++++++++ objects/namespaced/ 2026-09-02 15:58:37.084290 | 2026-09-02 15:58:37.084368 | TASK [gather-pod-logs : creating directory for pod logs] 2026-09-02 15:58:37.304777 | instance | changed 2026-09-02 15:58:37.310878 | 2026-09-02 15:58:37.310975 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-09-02 15:58:37.531481 | instance | changed 2026-09-02 15:58:37.540189 | 2026-09-02 15:58:37.540304 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-09-02 15:58:37.764949 | instance | environment: line 3: kubectl: command not found 2026-09-02 15:58:38.079885 | instance | ok: Runtime: 0:00:00.010501 2026-09-02 15:58:38.087711 | 2026-09-02 15:58:38.087778 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-09-02 15:58:38.634922 | instance | changed: 2026-09-02 15:58:38.635857 | instance | cd+++++++++ pod-logs/ 2026-09-02 15:58:38.635946 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-09-02 15:58:38.649118 | 2026-09-02 15:58:38.649194 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-09-02 15:58:38.868821 | instance | changed 2026-09-02 15:58:38.886131 | 2026-09-02 15:58:38.886588 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-09-02 15:58:39.121477 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-02 15:58:39.428890 | instance | ok: Runtime: 0:00:00.052632 2026-09-02 15:58:39.434674 | 2026-09-02 15:58:39.434747 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-09-02 15:58:39.666228 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-02 15:58:39.714775 | instance | ceph-mgr endpoints: 2026-09-02 15:58:39.972166 | instance | ok: Runtime: 0:00:00.055705 2026-09-02 15:58:39.977340 | 2026-09-02 15:58:39.977408 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-09-02 15:58:40.213392 | instance | /bin/bash: line 4: kubectl: command not found 2026-09-02 15:58:40.516946 | instance | ok: Runtime: 0:00:00.053615 2026-09-02 15:58:40.522880 | 2026-09-02 15:58:40.522946 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-09-02 15:58:41.012572 | instance | changed: cd+++++++++ prometheus/ 2026-09-02 15:58:41.026089 | 2026-09-02 15:58:41.026206 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-09-02 15:58:41.275898 | instance | changed 2026-09-02 15:58:41.283837 | 2026-09-02 15:58:41.283906 | TASK [gather-selenium-data : Get selenium data] 2026-09-02 15:58:41.550291 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-09-02 15:58:41.552071 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-09-02 15:58:41.825575 | instance | ERROR 2026-09-02 15:58:41.825871 | instance | { 2026-09-02 15:58:41.825938 | instance | "delta": "0:00:00.007535", 2026-09-02 15:58:41.825985 | instance | "end": "2026-09-02 15:58:41.552391", 2026-09-02 15:58:41.826033 | instance | "msg": "non-zero return code", 2026-09-02 15:58:41.826076 | instance | "rc": 1, 2026-09-02 15:58:41.826118 | instance | "start": "2026-09-02 15:58:41.544856" 2026-09-02 15:58:41.826158 | instance | } 2026-09-02 15:58:41.826209 | instance | ERROR: Ignoring Errors 2026-09-02 15:58:41.835427 | 2026-09-02 15:58:41.835495 | TASK [gather-selenium-data : Downloads logs to executor] 2026-09-02 15:58:42.331579 | instance | changed: cd+++++++++ selenium/ 2026-09-02 15:58:42.338823 | 2026-09-02 15:58:42.338877 | PLAY RECAP 2026-09-02 15:58:42.338926 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-09-02 15:58:42.338949 | 2026-09-02 15:58:42.478234 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-02 15:58:42.480459 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-02 15:58:43.119800 | 2026-09-02 15:58:43.120237 | PLAY [all] 2026-09-02 15:58:43.132537 | 2026-09-02 15:58:43.132611 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-02 15:58:43.177880 | instance | skipping: Conditional result was False 2026-09-02 15:58:43.185768 | 2026-09-02 15:58:43.185874 | TASK [fetch-output : Set log path for single node] 2026-09-02 15:58:43.233334 | instance | ok 2026-09-02 15:58:43.240478 | 2026-09-02 15:58:43.240549 | LOOP [fetch-output : Ensure local output dirs] 2026-09-02 15:58:43.669199 | instance -> localhost | ok: "/var/lib/zuul/builds/9d191e3aae8440de8ae94de4b243abb2/work/logs" 2026-09-02 15:58:43.909697 | instance -> localhost | changed: "/var/lib/zuul/builds/9d191e3aae8440de8ae94de4b243abb2/work/artifacts" 2026-09-02 15:58:44.139510 | instance -> localhost | changed: "/var/lib/zuul/builds/9d191e3aae8440de8ae94de4b243abb2/work/docs" 2026-09-02 15:58:44.149273 | 2026-09-02 15:58:44.149422 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-02 15:58:44.882025 | instance | changed: .d..t...... ./ 2026-09-02 15:58:44.882354 | instance | changed: All items complete 2026-09-02 15:58:44.882412 | 2026-09-02 15:58:45.349954 | instance | changed: .d..t...... ./ 2026-09-02 15:58:46.573832 | instance | changed: .d..t...... ./ 2026-09-02 15:58:46.600980 | 2026-09-02 15:58:46.601261 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-02 15:58:47.032286 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007751 2026-09-02 15:58:47.258744 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007692 2026-09-02 15:58:47.279695 | 2026-09-02 15:58:47.279831 | PLAY [all] 2026-09-02 15:58:47.286002 | 2026-09-02 15:58:47.286069 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-02 15:58:47.758164 | instance | changed 2026-09-02 15:58:47.766224 | 2026-09-02 15:58:47.766284 | PLAY RECAP 2026-09-02 15:58:47.766331 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-02 15:58:47.766354 | 2026-09-02 15:58:47.957344 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-02 15:58:47.961031 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-02 15:58:48.571683 | 2026-09-02 15:58:48.665328 | PLAY [localhost] 2026-09-02 15:58:48.682534 | 2026-09-02 15:58:48.682635 | TASK [Generate Zuul manifest] 2026-09-02 15:58:48.705522 | localhost | ok 2026-09-02 15:58:48.723495 | 2026-09-02 15:58:48.723572 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-02 15:58:49.120767 | localhost | changed 2026-09-02 15:58:49.134901 | 2026-09-02 15:58:49.134985 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-02 15:58:49.501756 | localhost | ok 2026-09-02 15:58:49.510233 | 2026-09-02 15:58:49.510303 | TASK [Upload logs] 2026-09-02 15:58:49.534803 | localhost | ok 2026-09-02 15:58:49.592314 | 2026-09-02 15:58:49.592443 | TASK [Set zuul-log-path fact] 2026-09-02 15:58:49.615451 | localhost | ok 2026-09-02 15:58:49.632097 | 2026-09-02 15:58:49.632197 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-02 15:58:49.665485 | localhost | ok 2026-09-02 15:58:49.675389 | 2026-09-02 15:58:49.675496 | TASK [upload-logs : Create log directories] 2026-09-02 15:58:50.090365 | localhost | changed 2026-09-02 15:58:50.097771 | 2026-09-02 15:58:50.097868 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-02 15:58:50.520341 | localhost -> localhost | ok: Runtime: 0:00:00.005513 2026-09-02 15:58:50.527713 | 2026-09-02 15:58:50.527777 | TASK [upload-logs : Upload logs to log server] 2026-09-02 15:58:51.280571 | localhost | Output suppressed because no_log was given 2026-09-02 15:58:51.324661 | 2026-09-02 15:58:51.324784 | LOOP [upload-logs : Compress console log and json output] 2026-09-02 15:58:51.369855 | localhost | skipping: Conditional result was False 2026-09-02 15:58:51.376713 | localhost | skipping: Conditional result was False 2026-09-02 15:58:51.388282 | 2026-09-02 15:58:51.388420 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-02 15:58:51.437516 | localhost | skipping: Conditional result was False 2026-09-02 15:58:51.437896 | 2026-09-02 15:58:51.442161 | localhost | skipping: Conditional result was False 2026-09-02 15:58:51.454505 | 2026-09-02 15:58:51.454709 | LOOP [upload-logs : Upload console log and json output]