2026-09-02 15:50:59.027590 | Job console starting 2026-09-02 15:50:59.037840 | Updating git repos 2026-09-02 15:50:59.820371 | Cloning repos into workspace 2026-09-02 15:51:17.902839 | Restoring repo states 2026-09-02 15:51:31.227193 | Merging changes 2026-09-02 15:51:37.651996 | Checking out repos 2026-09-02 15:51:39.175798 | Preparing playbooks 2026-09-02 15:51:42.791983 | Running Ansible setup 2026-09-02 15:51:46.449191 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-02 15:51:47.084765 | 2026-09-02 15:51:47.084898 | PLAY [localhost] 2026-09-02 15:51:47.092947 | 2026-09-02 15:51:47.093022 | TASK [Gathering Facts] 2026-09-02 15:51:49.082833 | localhost | ok 2026-09-02 15:51:49.095999 | 2026-09-02 15:51:49.096089 | TASK [Setup log path fact] 2026-09-02 15:51:49.119459 | localhost | ok 2026-09-02 15:51:49.131700 | 2026-09-02 15:51:49.131770 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-02 15:51:49.172237 | localhost | ok 2026-09-02 15:51:49.182011 | 2026-09-02 15:51:49.182372 | TASK [emit-job-header : Print job information] 2026-09-02 15:51:49.229256 | # Job Information 2026-09-02 15:51:49.232230 | Ansible Version: 2.16.18 2026-09-02 15:51:49.232288 | Job: atmosphere-molecule-csi-rbd 2026-09-02 15:51:49.232319 | Pipeline: check 2026-09-02 15:51:49.232343 | Executor: 2d72f0692154 2026-09-02 15:51:49.232365 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4359 2026-09-02 15:51:49.232391 | Event ID: 83639860-a6e5-11f1-9cdd-818ee500efb7 2026-09-02 15:51:49.237591 | 2026-09-02 15:51:49.237700 | LOOP [emit-job-header : Print node information] 2026-09-02 15:51:49.338498 | localhost | ok: 2026-09-02 15:51:49.339070 | localhost | # Node Information 2026-09-02 15:51:49.339102 | localhost | Inventory Hostname: instance 2026-09-02 15:51:49.339170 | localhost | Hostname: np0000191849 2026-09-02 15:51:49.339191 | localhost | Username: zuul 2026-09-02 15:51:49.339214 | localhost | Distro: Ubuntu 22.04 2026-09-02 15:51:49.339234 | localhost | Provider: yul1 2026-09-02 15:51:49.339253 | localhost | Region: ca-ymq-1 2026-09-02 15:51:49.339272 | localhost | Label: ubuntu-jammy 2026-09-02 15:51:49.339291 | localhost | Product Name: OpenStack Nova 2026-09-02 15:51:49.339365 | localhost | Interface IP: 162.253.55.124 2026-09-02 15:51:49.357721 | 2026-09-02 15:51:49.357935 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-02 15:51:49.841689 | localhost -> localhost | changed 2026-09-02 15:51:49.849963 | 2026-09-02 15:51:49.850058 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-02 15:51:50.707585 | localhost -> localhost | changed 2026-09-02 15:51:50.720764 | 2026-09-02 15:51:50.720837 | PLAY [all] 2026-09-02 15:51:50.734478 | 2026-09-02 15:51:50.734563 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-02 15:51:50.959768 | instance -> localhost | ok 2026-09-02 15:51:50.968075 | 2026-09-02 15:51:50.968145 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-02 15:51:51.000263 | instance | ok 2026-09-02 15:51:51.015639 | instance | included: /var/lib/zuul/builds/0877a05c4dd3465db0a6e456ef46e5fa/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-02 15:51:51.021496 | 2026-09-02 15:51:51.021554 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-02 15:51:51.817540 | instance -> localhost | Generating public/private rsa key pair. 2026-09-02 15:51:51.817750 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/0877a05c4dd3465db0a6e456ef46e5fa/work/0877a05c4dd3465db0a6e456ef46e5fa_id_rsa 2026-09-02 15:51:51.817793 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/0877a05c4dd3465db0a6e456ef46e5fa/work/0877a05c4dd3465db0a6e456ef46e5fa_id_rsa.pub 2026-09-02 15:51:51.817857 | instance -> localhost | The key fingerprint is: 2026-09-02 15:51:51.817889 | instance -> localhost | SHA256:190aXpZ8fLYHd93YD3jrBzMwFVvEvqvj9mXXMV0RHI8 zuul-build-sshkey 2026-09-02 15:51:51.817936 | instance -> localhost | The key's randomart image is: 2026-09-02 15:51:51.817967 | instance -> localhost | +---[RSA 3072]----+ 2026-09-02 15:51:51.818006 | instance -> localhost | | oB=| 2026-09-02 15:51:51.818037 | instance -> localhost | | .=+| 2026-09-02 15:51:51.818066 | instance -> localhost | | .E.o| 2026-09-02 15:51:51.818095 | instance -> localhost | | .oo.=B| 2026-09-02 15:51:51.818122 | instance -> localhost | | S . ooB=^| 2026-09-02 15:51:51.818149 | instance -> localhost | | . o+%X| 2026-09-02 15:51:51.818177 | instance -> localhost | | +=O| 2026-09-02 15:51:51.818204 | instance -> localhost | | + o=| 2026-09-02 15:51:51.818236 | instance -> localhost | | oo=o | 2026-09-02 15:51:51.818264 | instance -> localhost | +----[SHA256]-----+ 2026-09-02 15:51:51.818335 | instance -> localhost | ok: Runtime: 0:00:00.377490 2026-09-02 15:51:51.826248 | 2026-09-02 15:51:51.826318 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-02 15:51:51.858525 | instance | ok 2026-09-02 15:51:51.868766 | instance | included: /var/lib/zuul/builds/0877a05c4dd3465db0a6e456ef46e5fa/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-02 15:51:51.876188 | 2026-09-02 15:51:51.876254 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-02 15:51:51.901868 | instance | skipping: Conditional result was False 2026-09-02 15:51:51.911216 | 2026-09-02 15:51:51.911322 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-02 15:51:52.370906 | instance | changed 2026-09-02 15:51:52.377273 | 2026-09-02 15:51:52.377340 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-02 15:51:52.584493 | instance | ok 2026-09-02 15:51:52.590040 | 2026-09-02 15:51:52.590110 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-02 15:51:53.083598 | instance | changed 2026-09-02 15:51:53.089989 | 2026-09-02 15:51:53.090057 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-02 15:51:53.570108 | instance | changed 2026-09-02 15:51:53.578001 | 2026-09-02 15:51:53.578072 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-02 15:51:53.603537 | instance | skipping: Conditional result was False 2026-09-02 15:51:53.612080 | 2026-09-02 15:51:53.612170 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-02 15:51:53.973549 | instance -> localhost | changed 2026-09-02 15:51:53.989086 | 2026-09-02 15:51:53.989167 | TASK [add-build-sshkey : Add back temp key] 2026-09-02 15:51:54.250881 | instance -> localhost | Identity added: /var/lib/zuul/builds/0877a05c4dd3465db0a6e456ef46e5fa/work/0877a05c4dd3465db0a6e456ef46e5fa_id_rsa (zuul-build-sshkey) 2026-09-02 15:51:54.251086 | instance -> localhost | ok: Runtime: 0:00:00.013438 2026-09-02 15:51:54.255943 | 2026-09-02 15:51:54.256004 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-02 15:51:54.598348 | instance | ok 2026-09-02 15:51:54.602775 | 2026-09-02 15:51:54.602896 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-02 15:51:54.626395 | instance | skipping: Conditional result was False 2026-09-02 15:51:54.640072 | 2026-09-02 15:51:54.640178 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-02 15:51:55.010039 | instance | ok 2026-09-02 15:51:55.016078 | 2026-09-02 15:51:55.016144 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-02 15:51:58.387054 | instance | Output suppressed because no_log was given 2026-09-02 15:51:58.395572 | 2026-09-02 15:51:58.395635 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-02 15:51:58.599086 | instance | ok: "logs" 2026-09-02 15:51:58.599365 | instance | ok: All items complete 2026-09-02 15:51:58.599400 | 2026-09-02 15:51:58.735737 | instance | ok: "artifacts" 2026-09-02 15:51:58.900281 | instance | ok: "docs" 2026-09-02 15:51:58.914950 | 2026-09-02 15:51:58.915187 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-02 15:51:59.130521 | instance | changed: "logs" 2026-09-02 15:51:59.280643 | instance | changed: "artifacts" 2026-09-02 15:51:59.429280 | instance | changed: "docs" 2026-09-02 15:51:59.449029 | 2026-09-02 15:51:59.449265 | PLAY RECAP 2026-09-02 15:51:59.449400 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-02 15:51:59.449448 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-02 15:51:59.449481 | 2026-09-02 15:51:59.671069 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-02 15:51:59.725283 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-02 15:52:00.380667 | 2026-09-02 15:52:00.380810 | PLAY [all] 2026-09-02 15:52:00.391534 | 2026-09-02 15:52:00.391611 | TASK [setup-uv : Extract archive] 2026-09-02 15:52:02.669308 | instance | changed 2026-09-02 15:52:02.679139 | 2026-09-02 15:52:02.679396 | TASK [setup-uv : Print version] 2026-09-02 15:52:03.062608 | instance | uv 0.8.13 2026-09-02 15:52:03.805927 | instance | ok: Runtime: 0:00:00.011209 2026-09-02 15:52:03.814719 | 2026-09-02 15:52:03.814787 | TASK [Install Ansible collection siblings] 2026-09-02 15:52:13.105437 | instance | changed: 2026-09-02 15:52:13.105625 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-09-02 15:52:13.105655 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-09-02 15:52:13.105677 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-09-02 15:52:13.105697 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto 2026-09-02 15:52:13.105716 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general 2026-09-02 15:52:13.105740 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql 2026-09-02 15:52:13.105761 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-09-02 15:52:13.105782 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-09-02 15:52:13.105802 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-09-02 15:52:13.105821 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-09-02 15:52:13.105840 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-09-02 15:52:13.105859 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-09-02 15:52:13.105878 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0 2026-09-02 15:52:13.105896 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0 2026-09-02 15:52:13.105914 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0 2026-09-02 15:52:13.105933 | instance | Resolved community.crypto to 2.2.3 for 2.2.3 2026-09-02 15:52:13.105951 | instance | Resolved community.general to 7.3.0 for 7.3.0 2026-09-02 15:52:13.105970 | instance | Resolved community.mysql to 3.6.0 for 3.6.0 2026-09-02 15:52:13.105988 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0 2026-09-02 15:52:13.106006 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0 2026-09-02 15:52:13.106024 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0 2026-09-02 15:52:13.106042 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0 2026-09-02 15:52:13.106062 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-09-02 15:52:13.106092 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0 2026-09-02 15:52:13.106112 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0 2026-09-02 15:52:13.106130 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-02 15:52:13.106149 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-02 15:52:13.106168 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-09-02 15:52:13.106188 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0 2026-09-02 15:52:13.106208 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3 2026-09-02 15:52:13.106226 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-02 15:52:13.106244 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-02 15:52:13.106262 | instance | Checked out ansible.netcommon to 1.2.0 2026-09-02 15:52:13.106281 | 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:52:13.106307 | instance | Starting galaxy collection install process 2026-09-02 15:52:13.106325 | instance | Process install dependency map 2026-09-02 15:52:13.106343 | instance | Starting collection install process 2026-09-02 15:52:13.106361 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-09-02 15:52:13.106379 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon 2026-09-02 15:52:13.106397 | instance | ansible.netcommon:* was installed successfully 2026-09-02 15:52:13.106415 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-09-02 15:52:13.106432 | instance | Creating virtual environment at: .venv 2026-09-02 15:52:13.106450 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-02 15:52:13.106468 | instance | Downloading netaddr (2.2MiB) 2026-09-02 15:52:13.106486 | instance | Downloading openstacksdk (1.7MiB) 2026-09-02 15:52:13.106508 | instance | Downloading cryptography (4.2MiB) 2026-09-02 15:52:13.106527 | instance | Downloading ansible-core (2.1MiB) 2026-09-02 15:52:13.106545 | instance | Downloading pygments (1.2MiB) 2026-09-02 15:52:13.106563 | instance | Downloading rjsonnet (1.2MiB) 2026-09-02 15:52:13.106581 | instance | Downloading kubernetes (1.9MiB) 2026-09-02 15:52:13.106599 | instance | Downloading pydantic-core (2.0MiB) 2026-09-02 15:52:13.106618 | instance | Downloading setuptools (1.1MiB) 2026-09-02 15:52:13.106635 | instance | Building pyperclip==1.9.0 2026-09-02 15:52:13.106655 | instance | Downloading rjsonnet 2026-09-02 15:52:13.106674 | instance | Downloading pydantic-core 2026-09-02 15:52:13.106692 | instance | Downloading netaddr 2026-09-02 15:52:13.106711 | instance | Downloading pygments 2026-09-02 15:52:13.106729 | instance | Downloading cryptography 2026-09-02 15:52:13.106747 | instance | Downloading setuptools 2026-09-02 15:52:13.106765 | instance | Downloading kubernetes 2026-09-02 15:52:13.106782 | instance | Downloading ansible-core 2026-09-02 15:52:13.106801 | instance | Downloading openstacksdk 2026-09-02 15:52:13.106819 | instance | Built pyperclip==1.9.0 2026-09-02 15:52:13.106838 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-02 15:52:13.106856 | instance | Installed 83 packages in 174ms 2026-09-02 15:52:13.106875 | instance | Checked out ansible.posix to 1.6.0 2026-09-02 15:52:13.106892 | 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:52:13.106910 | instance | Starting galaxy collection install process 2026-09-02 15:52:13.106928 | instance | Process install dependency map 2026-09-02 15:52:13.106953 | instance | Starting collection install process 2026-09-02 15:52:13.106972 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-09-02 15:52:13.106990 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix 2026-09-02 15:52:13.107008 | instance | ansible.posix:1.6.0 was installed successfully 2026-09-02 15:52:13.107026 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 15:52:13.107044 | instance | 2.17.12 2026-09-02 15:52:13.107062 | instance | Checked out ansible.utils to v6.1.0 2026-09-02 15:52:13.107080 | 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:52:13.107098 | instance | Starting galaxy collection install process 2026-09-02 15:52:13.107115 | instance | Process install dependency map 2026-09-02 15:52:13.107133 | instance | Starting collection install process 2026-09-02 15:52:13.107151 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-09-02 15:52:13.107169 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils 2026-09-02 15:52:13.107187 | instance | ansible.utils:6.1.0 was installed successfully 2026-09-02 15:52:13.107205 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 15:52:13.107222 | instance | 2.17.12 2026-09-02 15:52:13.107240 | instance | Checked out atmosphere.common to v0.7.0 2026-09-02 15:52:13.107257 | 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:52:13.107276 | instance | Starting galaxy collection install process 2026-09-02 15:52:13.107293 | instance | Process install dependency map 2026-09-02 15:52:13.107311 | instance | Starting collection install process 2026-09-02 15:52:13.107328 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common' 2026-09-02 15:52:13.107346 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common 2026-09-02 15:52:13.107364 | instance | atmosphere.common:0.7.0 was installed successfully 2026-09-02 15:52:13.107381 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 15:52:13.107398 | instance | 2.17.12 2026-09-02 15:52:13.107416 | instance | Checked out community.crypto to 2.2.3 2026-09-02 15:52:13.107433 | 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:52:13.107451 | instance | Starting galaxy collection install process 2026-09-02 15:52:13.107468 | instance | Process install dependency map 2026-09-02 15:52:13.107485 | instance | Starting collection install process 2026-09-02 15:52:13.107502 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-09-02 15:52:13.107520 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto 2026-09-02 15:52:13.107537 | instance | community.crypto:2.2.3 was installed successfully 2026-09-02 15:52:13.107554 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 15:52:13.107572 | instance | 2.17.12 2026-09-02 15:52:13.107589 | instance | Checked out community.general to 7.3.0 2026-09-02 15:52:13.107606 | 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:52:13.107630 | instance | Starting galaxy collection install process 2026-09-02 15:52:13.107648 | instance | Process install dependency map 2026-09-02 15:52:13.107665 | instance | Starting collection install process 2026-09-02 15:52:13.107683 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-09-02 15:52:13.107700 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general 2026-09-02 15:52:13.107718 | instance | community.general:7.3.0 was installed successfully 2026-09-02 15:52:13.107736 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 15:52:13.107754 | instance | 2.17.12 2026-09-02 15:52:13.107772 | instance | Checked out community.mysql to 3.6.0 2026-09-02 15:52:13.107792 | 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:52:13.107811 | instance | Starting galaxy collection install process 2026-09-02 15:52:13.107829 | instance | Process install dependency map 2026-09-02 15:52:13.107847 | instance | Starting collection install process 2026-09-02 15:52:13.107865 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-09-02 15:52:13.107883 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql 2026-09-02 15:52:13.107901 | instance | community.mysql:3.6.0 was installed successfully 2026-09-02 15:52:13.107919 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 15:52:13.107937 | instance | 2.17.12 2026-09-02 15:52:13.107954 | instance | Checked out kubernetes.core to 2.4.0 2026-09-02 15:52:13.107973 | 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:52:13.107991 | instance | Starting galaxy collection install process 2026-09-02 15:52:13.108008 | instance | Process install dependency map 2026-09-02 15:52:13.108026 | instance | Starting collection install process 2026-09-02 15:52:13.108044 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-09-02 15:52:13.108062 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core 2026-09-02 15:52:13.108080 | instance | kubernetes.core:2.4.0 was installed successfully 2026-09-02 15:52:13.108098 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 15:52:13.108116 | instance | 2.17.12 2026-09-02 15:52:13.108133 | instance | Checked out openstack.cloud to 2.6.0 2026-09-02 15:52:13.108151 | 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:52:13.108169 | instance | Starting galaxy collection install process 2026-09-02 15:52:13.108187 | instance | Process install dependency map 2026-09-02 15:52:13.108204 | instance | Starting collection install process 2026-09-02 15:52:13.108222 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-09-02 15:52:13.108239 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud 2026-09-02 15:52:13.108256 | instance | openstack.cloud:2.6.0 was installed successfully 2026-09-02 15:52:13.108274 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 15:52:13.108291 | instance | 2.17.12 2026-09-02 15:52:13.108314 | instance | Checked out vexxhost.ceph to v4.2.0 2026-09-02 15:52:13.108333 | 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:52:13.108350 | instance | Starting galaxy collection install process 2026-09-02 15:52:13.108368 | instance | Process install dependency map 2026-09-02 15:52:13.108385 | instance | Starting collection install process 2026-09-02 15:52:13.108403 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph' 2026-09-02 15:52:13.108420 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph 2026-09-02 15:52:13.108437 | instance | vexxhost.ceph:4.2.0 was installed successfully 2026-09-02 15:52:13.108455 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 15:52:13.108472 | instance | 2.17.12 2026-09-02 15:52:13.108489 | instance | Checked out vexxhost.containers to v1.6.8 2026-09-02 15:52:13.108507 | 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:52:13.108525 | instance | Starting galaxy collection install process 2026-09-02 15:52:13.108543 | instance | Process install dependency map 2026-09-02 15:52:13.108560 | instance | Starting collection install process 2026-09-02 15:52:13.108577 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers' 2026-09-02 15:52:13.108595 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers 2026-09-02 15:52:13.108612 | instance | vexxhost.containers:1.6.8 was installed successfully 2026-09-02 15:52:13.108630 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 15:52:13.108648 | instance | 2.17.12 2026-09-02 15:52:13.108665 | instance | Checked out vexxhost.kubernetes to v3.3.0 2026-09-02 15:52:13.108683 | 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:52:13.108701 | instance | Starting galaxy collection install process 2026-09-02 15:52:13.108736 | instance | Process install dependency map 2026-09-02 15:52:13.108755 | instance | Starting collection install process 2026-09-02 15:52:13.108772 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes' 2026-09-02 15:52:13.108790 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes 2026-09-02 15:52:13.108808 | instance | vexxhost.kubernetes:3.3.0 was installed successfully 2026-09-02 15:52:13.108825 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-02 15:52:13.108843 | instance | 2.17.12 2026-09-02 15:52:13.114960 | 2026-09-02 15:52:13.115008 | PLAY RECAP 2026-09-02 15:52:13.115056 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-02 15:52:13.115078 | 2026-09-02 15:52:13.252620 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-02 15:52:13.256884 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-02 15:52:13.852497 | 2026-09-02 15:52:13.852638 | PLAY [all] 2026-09-02 15:52:13.863796 | 2026-09-02 15:52:13.863872 | TASK [Install "jq" for log collection] 2026-09-02 15:52:22.708033 | instance | changed 2026-09-02 15:52:22.710105 | 2026-09-02 15:52:22.710169 | PLAY RECAP 2026-09-02 15:52:22.710227 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-02 15:52:22.710292 | 2026-09-02 15:52:22.841667 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-02 15:52:22.846689 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-02 15:52:23.414717 | 2026-09-02 15:52:23.414842 | PLAY [all] 2026-09-02 15:52:23.425414 | 2026-09-02 15:52:23.425484 | LOOP [Check inventory directories] 2026-09-02 15:52:23.755552 | instance | ok: "src/github.com/vexxhost/atmosphere" 2026-09-02 15:52:23.920904 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory" 2026-09-02 15:52:24.092951 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory" 2026-09-02 15:52:24.105310 | 2026-09-02 15:52:24.105493 | LOOP [Copy inventory file for Zuul] 2026-09-02 15:52:24.961420 | instance | changed: 2026-09-02 15:52:24.961638 | instance | { 2026-09-02 15:52:24.961677 | instance | "ansible_loop_var": "item", 2026-09-02 15:52:24.961709 | instance | "changed": false, 2026-09-02 15:52:24.961737 | instance | "failed": false, 2026-09-02 15:52:24.961769 | instance | "invocation": { 2026-09-02 15:52:24.961796 | instance | "module_args": { 2026-09-02 15:52:24.961822 | instance | "checksum_algorithm": "sha1", 2026-09-02 15:52:24.961848 | instance | "follow": true, 2026-09-02 15:52:24.961873 | instance | "get_attributes": true, 2026-09-02 15:52:24.961899 | instance | "get_checksum": true, 2026-09-02 15:52:24.961925 | instance | "get_mime": true, 2026-09-02 15:52:24.961950 | instance | "path": "src/github.com/vexxhost/atmosphere" 2026-09-02 15:52:24.961975 | instance | } 2026-09-02 15:52:24.962000 | instance | }, 2026-09-02 15:52:24.962025 | instance | "item": "src/github.com/vexxhost/atmosphere", 2026-09-02 15:52:24.962049 | instance | "stat": { 2026-09-02 15:52:24.962074 | instance | "atime": 1788364325.7348456, 2026-09-02 15:52:24.962099 | instance | "attr_flags": "e", 2026-09-02 15:52:24.962124 | instance | "attributes": [ 2026-09-02 15:52:24.962150 | instance | "extents" 2026-09-02 15:52:24.962174 | instance | ], 2026-09-02 15:52:24.962199 | instance | "block_size": 4096, 2026-09-02 15:52:24.962224 | instance | "blocks": 8, 2026-09-02 15:52:24.962249 | instance | "charset": "unknown", 2026-09-02 15:52:24.962275 | instance | "ctime": 1788364324.6508236, 2026-09-02 15:52:24.962301 | instance | "dev": 64513, 2026-09-02 15:52:24.962326 | instance | "device_type": 0, 2026-09-02 15:52:24.962350 | instance | "executable": true, 2026-09-02 15:52:24.962374 | instance | "exists": true, 2026-09-02 15:52:24.962398 | instance | "gid": 1000, 2026-09-02 15:52:24.962422 | instance | "gr_name": "zuul", 2026-09-02 15:52:24.962446 | instance | "inode": 1047503, 2026-09-02 15:52:24.962470 | instance | "isblk": false, 2026-09-02 15:52:24.962494 | instance | "ischr": false, 2026-09-02 15:52:24.962518 | instance | "isdir": true, 2026-09-02 15:52:24.962541 | instance | "isfifo": false, 2026-09-02 15:52:24.962565 | instance | "isgid": false, 2026-09-02 15:52:24.962589 | instance | "islnk": false, 2026-09-02 15:52:24.962612 | instance | "isreg": false, 2026-09-02 15:52:24.962636 | instance | "issock": false, 2026-09-02 15:52:24.962660 | instance | "isuid": false, 2026-09-02 15:52:24.962683 | instance | "mimetype": "unknown", 2026-09-02 15:52:24.962706 | instance | "mode": "0755", 2026-09-02 15:52:24.962729 | instance | "mtime": 1788364324.6508236, 2026-09-02 15:52:24.962753 | instance | "nlink": 23, 2026-09-02 15:52:24.962776 | instance | "path": "src/github.com/vexxhost/atmosphere", 2026-09-02 15:52:24.962800 | instance | "pw_name": "zuul", 2026-09-02 15:52:24.962824 | instance | "readable": true, 2026-09-02 15:52:24.962847 | instance | "rgrp": true, 2026-09-02 15:52:24.962871 | instance | "roth": true, 2026-09-02 15:52:24.962912 | instance | "rusr": true, 2026-09-02 15:52:24.962937 | instance | "size": 4096, 2026-09-02 15:52:24.962960 | instance | "uid": 1000, 2026-09-02 15:52:24.962984 | instance | "version": "1277300020", 2026-09-02 15:52:24.963008 | instance | "wgrp": false, 2026-09-02 15:52:24.963033 | instance | "woth": false, 2026-09-02 15:52:24.963058 | instance | "writeable": true, 2026-09-02 15:52:24.963083 | instance | "wusr": true, 2026-09-02 15:52:24.963106 | instance | "xgrp": true, 2026-09-02 15:52:24.963130 | instance | "xoth": true, 2026-09-02 15:52:24.963154 | instance | "xusr": true 2026-09-02 15:52:24.963177 | instance | } 2026-09-02 15:52:24.963201 | instance | } 2026-09-02 15:52:24.963247 | instance | ok: All items complete 2026-09-02 15:52:24.963277 | 2026-09-02 15:52:24.964940 | instance | skipping: Conditional result was False 2026-09-02 15:52:24.965948 | instance | skipping: Conditional result was False 2026-09-02 15:52:24.982854 | 2026-09-02 15:52:24.983623 | LOOP [Switch "ansible_host" to private IP] 2026-09-02 15:52:25.331841 | instance | changed: 1 replacements made 2026-09-02 15:52:25.333092 | instance | skipping: Conditional result was False 2026-09-02 15:52:25.334086 | instance | skipping: Conditional result was False 2026-09-02 15:52:25.351595 | 2026-09-02 15:52:25.351735 | TASK [Run Molecule scenario] 2026-09-02 15:52:26.258294 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-09-02 15:52:26.607802 | instance | INFO [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-02 15:52:26.607859 | instance | INFO [csi > prerun] Performing prerun with role_name_check=0... 2026-09-02 15:52:37.314442 | instance | INFO [csi > dependency] Executing 2026-09-02 15:52:37.315036 | instance | WARNING [csi > dependency] Missing roles requirements file: requirements.yml 2026-09-02 15:52:37.315552 | instance | WARNING [csi > dependency] Missing collections requirements file: collections.yml 2026-09-02 15:52:37.315857 | instance | WARNING [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-09-02 15:52:37.330341 | instance | INFO [csi > cleanup] Executing 2026-09-02 15:52:37.331053 | instance | WARNING [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-02 15:52:37.345500 | instance | INFO [csi > destroy] Executing 2026-09-02 15:52:37.345796 | instance | WARNING [csi > destroy] Skipping, '--destroy=never' requested. 2026-09-02 15:52:37.346023 | instance | INFO [csi > destroy] Executed: Successful 2026-09-02 15:52:37.357597 | instance | INFO [csi > syntax] Executing 2026-09-02 15:52:38.784430 | instance | 2026-09-02 15:52:38.784916 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-02 15:52:38.855285 | instance | INFO [csi > syntax] Executed: Successful 2026-09-02 15:52:38.870141 | instance | INFO [csi > create] Executing 2026-09-02 15:52:38.873641 | instance | WARNING [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-02 15:52:38.887941 | instance | INFO [csi > prepare] Executing 2026-09-02 15:52:39.689770 | instance | 2026-09-02 15:52:39.690027 | instance | PLAY [Prepare] ***************************************************************** 2026-09-02 15:52:39.690193 | instance | 2026-09-02 15:52:39.690364 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-02 15:52:39.690554 | instance | Wednesday 02 September 2026 15:52:39 +0000 (0:00:00.023) 0:00:00.023 *** 2026-09-02 15:52:40.997673 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-02 15:52:40.997726 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-02 15:52:40.998603 | instance | interpreter could change the meaning of that path. See 2026-09-02 15:52:40.998614 | instance | https://docs.ansible.com/ansible- 2026-09-02 15:52:40.998761 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-02 15:52:41.009112 | instance | ok: [instance] 2026-09-02 15:52:41.009342 | instance | 2026-09-02 15:52:41.009611 | instance | TASK [Configure short hostname] ************************************************ 2026-09-02 15:52:41.009857 | instance | Wednesday 02 September 2026 15:52:40 +0000 (0:00:01.319) 0:00:01.343 *** 2026-09-02 15:52:41.730124 | instance | changed: [instance] 2026-09-02 15:52:41.730355 | instance | 2026-09-02 15:52:41.730706 | instance | TASK [Ensure hostname inside hosts file] *************************************** 2026-09-02 15:52:41.730965 | instance | Wednesday 02 September 2026 15:52:41 +0000 (0:00:00.720) 0:00:02.064 *** 2026-09-02 15:52:42.012163 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created 2026-09-02 15:52:42.012257 | instance | with a mode of 0700, this may cause issues when running as another user. To 2026-09-02 15:52:42.012382 | instance | avoid this, create the remote_tmp dir with the correct permissions manually 2026-09-02 15:52:42.021624 | instance | changed: [instance] 2026-09-02 15:52:42.021710 | instance | 2026-09-02 15:52:42.021821 | instance | TASK [Purge "snapd" package] *************************************************** 2026-09-02 15:52:42.021930 | instance | Wednesday 02 September 2026 15:52:42 +0000 (0:00:00.291) 0:00:02.356 *** 2026-09-02 15:52:42.828424 | instance | ok: [instance] 2026-09-02 15:52:42.828647 | instance | 2026-09-02 15:52:42.828892 | instance | PLAY [Create devices for Ceph] ************************************************* 2026-09-02 15:52:42.829105 | instance | 2026-09-02 15:52:42.829334 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-02 15:52:42.829586 | instance | Wednesday 02 September 2026 15:52:42 +0000 (0:00:00.806) 0:00:03.162 *** 2026-09-02 15:52:43.596197 | instance | ok: [instance] 2026-09-02 15:52:43.596328 | instance | 2026-09-02 15:52:43.596610 | instance | TASK [Install depedencies] ***************************************************** 2026-09-02 15:52:43.596840 | instance | Wednesday 02 September 2026 15:52:43 +0000 (0:00:00.767) 0:00:03.929 *** 2026-09-02 15:53:07.665800 | instance | changed: [instance] 2026-09-02 15:53:07.667059 | instance | 2026-09-02 15:53:07.667127 | instance | TASK [Start up service] ******************************************************** 2026-09-02 15:53:07.667147 | instance | Wednesday 02 September 2026 15:53:07 +0000 (0:00:24.069) 0:00:27.998 *** 2026-09-02 15:53:08.225556 | instance | ok: [instance] 2026-09-02 15:53:08.225645 | instance | 2026-09-02 15:53:08.226027 | instance | TASK [Generate lvm.conf] ******************************************************* 2026-09-02 15:53:08.226082 | instance | Wednesday 02 September 2026 15:53:08 +0000 (0:00:00.560) 0:00:28.559 *** 2026-09-02 15:53:08.582696 | instance | ok: [instance] 2026-09-02 15:53:08.582934 | instance | 2026-09-02 15:53:08.583308 | instance | TASK [Write /etc/lvm/lvm.conf] ************************************************* 2026-09-02 15:53:08.583734 | instance | Wednesday 02 September 2026 15:53:08 +0000 (0:00:00.357) 0:00:28.916 *** 2026-09-02 15:53:09.296271 | instance | changed: [instance] 2026-09-02 15:53:09.296350 | instance | 2026-09-02 15:53:09.296361 | instance | TASK [Get list of all loopback devices] **************************************** 2026-09-02 15:53:09.296369 | instance | Wednesday 02 September 2026 15:53:09 +0000 (0:00:00.713) 0:00:29.629 *** 2026-09-02 15:53:09.525347 | instance | ok: [instance] 2026-09-02 15:53:09.526074 | instance | 2026-09-02 15:53:09.526134 | instance | TASK [Fail if there is any existing loopback devices] ************************** 2026-09-02 15:53:09.526147 | instance | Wednesday 02 September 2026 15:53:09 +0000 (0:00:00.229) 0:00:29.858 *** 2026-09-02 15:53:09.549927 | instance | skipping: [instance] 2026-09-02 15:53:09.550498 | instance | 2026-09-02 15:53:09.550571 | instance | TASK [Create devices for Ceph] ************************************************* 2026-09-02 15:53:09.550581 | instance | Wednesday 02 September 2026 15:53:09 +0000 (0:00:00.024) 0:00:29.883 *** 2026-09-02 15:53:10.202646 | instance | changed: [instance] => (item=osd0) 2026-09-02 15:53:10.202717 | instance | changed: [instance] => (item=osd1) 2026-09-02 15:53:10.202728 | instance | changed: [instance] => (item=osd2) 2026-09-02 15:53:10.202737 | instance | 2026-09-02 15:53:10.202747 | instance | TASK [Set permissions on loopback devices] ************************************* 2026-09-02 15:53:10.202757 | instance | Wednesday 02 September 2026 15:53:10 +0000 (0:00:00.651) 0:00:30.535 *** 2026-09-02 15:53:10.879386 | instance | changed: [instance] => (item=osd0) 2026-09-02 15:53:10.879513 | instance | changed: [instance] => (item=osd1) 2026-09-02 15:53:10.879530 | instance | changed: [instance] => (item=osd2) 2026-09-02 15:53:10.879543 | instance | 2026-09-02 15:53:10.879553 | instance | TASK [Start loop devices] ****************************************************** 2026-09-02 15:53:10.879563 | instance | Wednesday 02 September 2026 15:53:10 +0000 (0:00:00.676) 0:00:31.211 *** 2026-09-02 15:53:11.609411 | instance | changed: [instance] => (item=osd0) 2026-09-02 15:53:11.611244 | instance | changed: [instance] => (item=osd1) 2026-09-02 15:53:11.611328 | instance | changed: [instance] => (item=osd2) 2026-09-02 15:53:11.611341 | instance | 2026-09-02 15:53:11.611352 | instance | TASK [Create a volume group for each loop device] ****************************** 2026-09-02 15:53:11.611362 | instance | Wednesday 02 September 2026 15:53:11 +0000 (0:00:00.730) 0:00:31.942 *** 2026-09-02 15:53:14.852863 | instance | changed: [instance] => (item=osd0) 2026-09-02 15:53:14.852924 | instance | changed: [instance] => (item=osd1) 2026-09-02 15:53:14.852935 | instance | changed: [instance] => (item=osd2) 2026-09-02 15:53:14.852944 | instance | 2026-09-02 15:53:14.852954 | instance | TASK [Create a logical volume for each loop device] **************************** 2026-09-02 15:53:14.852963 | instance | Wednesday 02 September 2026 15:53:14 +0000 (0:00:03.243) 0:00:35.185 *** 2026-09-02 15:53:16.767999 | instance | changed: [instance] => (item=ceph-instance-osd0) 2026-09-02 15:53:16.768847 | instance | changed: [instance] => (item=ceph-instance-osd1) 2026-09-02 15:53:16.768889 | instance | changed: [instance] => (item=ceph-instance-osd2) 2026-09-02 15:53:16.768900 | instance | 2026-09-02 15:53:16.768914 | instance | PLAY RECAP ********************************************************************* 2026-09-02 15:53:16.769583 | instance | instance : ok=15 changed=9 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-09-02 15:53:16.769861 | instance | 2026-09-02 15:53:16.769883 | instance | Wednesday 02 September 2026 15:53:16 +0000 (0:00:01.917) 0:00:37.102 *** 2026-09-02 15:53:16.769896 | instance | =============================================================================== 2026-09-02 15:53:16.770086 | instance | Install depedencies ---------------------------------------------------- 24.07s 2026-09-02 15:53:16.770427 | instance | Create a volume group for each loop device ------------------------------ 3.24s 2026-09-02 15:53:16.770722 | instance | Create a logical volume for each loop device ---------------------------- 1.92s 2026-09-02 15:53:16.771013 | instance | Gathering Facts --------------------------------------------------------- 1.32s 2026-09-02 15:53:16.771334 | instance | Purge "snapd" package --------------------------------------------------- 0.81s 2026-09-02 15:53:16.771686 | instance | Gathering Facts --------------------------------------------------------- 0.77s 2026-09-02 15:53:16.771985 | instance | Start loop devices ------------------------------------------------------ 0.73s 2026-09-02 15:53:16.772300 | instance | Configure short hostname ------------------------------------------------ 0.72s 2026-09-02 15:53:16.772591 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.71s 2026-09-02 15:53:16.772882 | instance | Set permissions on loopback devices ------------------------------------- 0.68s 2026-09-02 15:53:16.773194 | instance | Create devices for Ceph ------------------------------------------------- 0.65s 2026-09-02 15:53:16.773483 | instance | Start up service -------------------------------------------------------- 0.56s 2026-09-02 15:53:16.773771 | instance | Generate lvm.conf ------------------------------------------------------- 0.36s 2026-09-02 15:53:16.774083 | instance | Ensure hostname inside hosts file --------------------------------------- 0.29s 2026-09-02 15:53:16.774373 | instance | Get list of all loopback devices ---------------------------------------- 0.23s 2026-09-02 15:53:16.774661 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s 2026-09-02 15:53:16.857648 | instance | INFO [csi > prepare] Executed: Successful 2026-09-02 15:53:16.872261 | instance | INFO [csi > converge] Executing 2026-09-02 15:53:18.283355 | instance | 2026-09-02 15:53:18.283822 | instance | PLAY [Debug CSI driver value] ************************************************** 2026-09-02 15:53:18.284152 | instance | 2026-09-02 15:53:18.284473 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-02 15:53:18.284806 | instance | Wednesday 02 September 2026 15:53:18 +0000 (0:00:00.007) 0:00:00.007 *** 2026-09-02 15:53:19.330960 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-02 15:53:19.331345 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-02 15:53:19.331685 | instance | interpreter could change the meaning of that path. See 2026-09-02 15:53:19.331978 | instance | https://docs.ansible.com/ansible- 2026-09-02 15:53:19.332295 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-02 15:53:19.347171 | instance | ok: [instance] 2026-09-02 15:53:19.347495 | instance | 2026-09-02 15:53:19.347794 | instance | TASK [Display CSI driver value and environment variable] *********************** 2026-09-02 15:53:19.348111 | instance | Wednesday 02 September 2026 15:53:19 +0000 (0:00:01.064) 0:00:01.071 *** 2026-09-02 15:53:19.412643 | instance | ok: [instance] => { 2026-09-02 15:53:19.412973 | instance | "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER=" 2026-09-02 15:53:19.413381 | instance | } 2026-09-02 15:53:19.413700 | instance | 2026-09-02 15:53:19.413993 | instance | PLAY [all] ********************************************************************* 2026-09-02 15:53:19.414294 | instance | 2026-09-02 15:53:19.414587 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-02 15:53:19.414902 | instance | Wednesday 02 September 2026 15:53:19 +0000 (0:00:00.065) 0:00:01.137 *** 2026-09-02 15:53:20.389414 | instance | ok: [instance] 2026-09-02 15:53:20.389762 | instance | 2026-09-02 15:53:20.390263 | instance | TASK [Fail if atmosphere_ceph_enabled is set] ********************************** 2026-09-02 15:53:20.390563 | instance | Wednesday 02 September 2026 15:53:20 +0000 (0:00:00.975) 0:00:02.112 *** 2026-09-02 15:53:20.449893 | instance | skipping: [instance] 2026-09-02 15:53:20.450468 | instance | 2026-09-02 15:53:20.450859 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] ***************** 2026-09-02 15:53:20.451332 | instance | Wednesday 02 September 2026 15:53:20 +0000 (0:00:00.060) 0:00:02.173 *** 2026-09-02 15:53:20.668355 | instance | ok: [instance] 2026-09-02 15:53:20.668705 | instance | 2026-09-02 15:53:20.669009 | instance | PLAY [Deploy Ceph monitors & managers] ***************************************** 2026-09-02 15:53:20.669309 | instance | 2026-09-02 15:53:20.669601 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-02 15:53:20.669961 | instance | Wednesday 02 September 2026 15:53:20 +0000 (0:00:00.218) 0:00:02.391 *** 2026-09-02 15:53:21.608378 | instance | ok: [instance] 2026-09-02 15:53:21.608546 | instance | 2026-09-02 15:53:21.608787 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-02 15:53:21.609021 | instance | Wednesday 02 September 2026 15:53:21 +0000 (0:00:00.936) 0:00:03.328 *** 2026-09-02 15:53:21.978451 | instance | ok: [instance] 2026-09-02 15:53:21.978551 | instance | 2026-09-02 15:53:21.978733 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-02 15:53:21.978988 | instance | Wednesday 02 September 2026 15:53:21 +0000 (0:00:00.374) 0:00:03.702 *** 2026-09-02 15:53:22.039356 | instance | skipping: [instance] 2026-09-02 15:53:22.039639 | instance | 2026-09-02 15:53:22.040006 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-09-02 15:53:22.040348 | instance | Wednesday 02 September 2026 15:53:22 +0000 (0:00:00.060) 0:00:03.763 *** 2026-09-02 15:53:22.448281 | instance | changed: [instance] 2026-09-02 15:53:22.448487 | instance | 2026-09-02 15:53:22.448851 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-02 15:53:22.449238 | instance | Wednesday 02 September 2026 15:53:22 +0000 (0:00:00.409) 0:00:04.172 *** 2026-09-02 15:53:22.548456 | instance | ok: [instance] => { 2026-09-02 15:53:22.548867 | instance | "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64" 2026-09-02 15:53:22.549227 | instance | } 2026-09-02 15:53:22.549529 | instance | 2026-09-02 15:53:22.549846 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-02 15:53:22.550184 | instance | Wednesday 02 September 2026 15:53:22 +0000 (0:00:00.097) 0:00:04.269 *** 2026-09-02 15:53:23.185655 | instance | changed: [instance] 2026-09-02 15:53:23.185694 | instance | 2026-09-02 15:53:23.185703 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-02 15:53:23.185710 | instance | Wednesday 02 September 2026 15:53:23 +0000 (0:00:00.640) 0:00:04.909 *** 2026-09-02 15:53:23.237116 | instance | skipping: [instance] 2026-09-02 15:53:23.237164 | instance | 2026-09-02 15:53:23.237171 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-02 15:53:23.237178 | instance | Wednesday 02 September 2026 15:53:23 +0000 (0:00:00.051) 0:00:04.961 *** 2026-09-02 15:53:23.286802 | instance | skipping: [instance] 2026-09-02 15:53:23.286822 | instance | 2026-09-02 15:53:23.286827 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-02 15:53:23.286832 | instance | Wednesday 02 September 2026 15:53:23 +0000 (0:00:00.050) 0:00:05.011 *** 2026-09-02 15:53:23.528293 | instance | ok: [instance] 2026-09-02 15:53:23.529023 | instance | 2026-09-02 15:53:23.529086 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-02 15:53:23.529098 | instance | Wednesday 02 September 2026 15:53:23 +0000 (0:00:00.240) 0:00:05.252 *** 2026-09-02 15:53:24.842523 | instance | ok: [instance] 2026-09-02 15:53:24.843307 | instance | 2026-09-02 15:53:24.843540 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-02 15:53:24.843589 | instance | Wednesday 02 September 2026 15:53:24 +0000 (0:00:01.314) 0:00:06.566 *** 2026-09-02 15:53:24.934767 | instance | ok: [instance] => { 2026-09-02 15:53:24.935596 | instance | "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz" 2026-09-02 15:53:24.935694 | instance | } 2026-09-02 15:53:24.935712 | instance | 2026-09-02 15:53:24.935740 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-02 15:53:24.936129 | instance | Wednesday 02 September 2026 15:53:24 +0000 (0:00:00.091) 0:00:06.658 *** 2026-09-02 15:53:25.585728 | instance | changed: [instance] 2026-09-02 15:53:25.586499 | instance | 2026-09-02 15:53:25.586551 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-02 15:53:25.586565 | instance | Wednesday 02 September 2026 15:53:25 +0000 (0:00:00.651) 0:00:07.309 *** 2026-09-02 15:53:28.541717 | instance | changed: [instance] 2026-09-02 15:53:28.541772 | instance | 2026-09-02 15:53:28.541784 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] *************** 2026-09-02 15:53:28.541795 | instance | Wednesday 02 September 2026 15:53:28 +0000 (0:00:02.955) 0:00:10.265 *** 2026-09-02 15:53:28.572841 | instance | skipping: [instance] 2026-09-02 15:53:28.573684 | instance | 2026-09-02 15:53:28.573734 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] *** 2026-09-02 15:53:28.573743 | instance | Wednesday 02 September 2026 15:53:28 +0000 (0:00:00.031) 0:00:10.297 *** 2026-09-02 15:53:28.604649 | instance | skipping: [instance] 2026-09-02 15:53:28.605401 | instance | 2026-09-02 15:53:28.605435 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ******** 2026-09-02 15:53:28.605447 | instance | Wednesday 02 September 2026 15:53:28 +0000 (0:00:00.031) 0:00:10.329 *** 2026-09-02 15:53:28.639970 | instance | skipping: [instance] 2026-09-02 15:53:28.640745 | instance | 2026-09-02 15:53:28.640791 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] ************** 2026-09-02 15:53:28.640803 | instance | Wednesday 02 September 2026 15:53:28 +0000 (0:00:00.035) 0:00:10.364 *** 2026-09-02 15:53:34.960992 | instance | changed: [instance] 2026-09-02 15:53:34.961376 | instance | 2026-09-02 15:53:34.961720 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] *** 2026-09-02 15:53:34.962032 | instance | Wednesday 02 September 2026 15:53:34 +0000 (0:00:06.317) 0:00:16.681 *** 2026-09-02 15:53:35.603501 | instance | changed: [instance] 2026-09-02 15:53:35.603556 | instance | 2026-09-02 15:53:35.603574 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] ******* 2026-09-02 15:53:35.603849 | instance | Wednesday 02 September 2026 15:53:35 +0000 (0:00:00.645) 0:00:17.327 *** 2026-09-02 15:53:36.635003 | instance | changed: [instance] => (item={'path': '/etc/containerd'}) 2026-09-02 15:53:36.635479 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'}) 2026-09-02 15:53:36.636919 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'}) 2026-09-02 15:53:36.636981 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'}) 2026-09-02 15:53:36.636994 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'}) 2026-09-02 15:53:36.637004 | instance | 2026-09-02 15:53:36.637014 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] ********** 2026-09-02 15:53:36.637023 | instance | Wednesday 02 September 2026 15:53:36 +0000 (0:00:01.031) 0:00:18.359 *** 2026-09-02 15:53:37.305207 | instance | changed: [instance] 2026-09-02 15:53:37.306106 | instance | 2026-09-02 15:53:37.306151 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ******** 2026-09-02 15:53:37.306160 | instance | Wednesday 02 September 2026 15:53:37 +0000 (0:00:00.643) 0:00:19.002 *** 2026-09-02 15:53:37.306167 | instance | 2026-09-02 15:53:37.306173 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-02 15:53:37.306179 | instance | Wednesday 02 September 2026 15:53:37 +0000 (0:00:00.027) 0:00:19.029 *** 2026-09-02 15:53:38.276862 | instance | ok: [instance] 2026-09-02 15:53:38.277578 | instance | 2026-09-02 15:53:38.277609 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] ********** 2026-09-02 15:53:38.277621 | instance | Wednesday 02 September 2026 15:53:38 +0000 (0:00:00.971) 0:00:20.001 *** 2026-09-02 15:53:38.770675 | instance | changed: [instance] 2026-09-02 15:53:38.770764 | instance | 2026-09-02 15:53:38.770776 | instance | TASK [vexxhost.containers.containerd : Enable and start service] *************** 2026-09-02 15:53:38.770794 | instance | Wednesday 02 September 2026 15:53:38 +0000 (0:00:00.492) 0:00:20.493 *** 2026-09-02 15:53:40.409667 | instance | changed: [instance] 2026-09-02 15:53:40.410350 | instance | 2026-09-02 15:53:40.410429 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-02 15:53:40.410442 | instance | Wednesday 02 September 2026 15:53:40 +0000 (0:00:01.639) 0:00:22.133 *** 2026-09-02 15:53:40.658782 | instance | ok: [instance] 2026-09-02 15:53:40.659524 | instance | 2026-09-02 15:53:40.659594 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-02 15:53:40.659606 | instance | Wednesday 02 September 2026 15:53:40 +0000 (0:00:00.248) 0:00:22.382 *** 2026-09-02 15:53:40.736472 | instance | ok: [instance] => { 2026-09-02 15:53:40.737697 | instance | "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz" 2026-09-02 15:53:40.737756 | instance | } 2026-09-02 15:53:40.737772 | instance | 2026-09-02 15:53:40.737785 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-02 15:53:40.737800 | instance | Wednesday 02 September 2026 15:53:40 +0000 (0:00:00.077) 0:00:22.460 *** 2026-09-02 15:53:41.463745 | instance | changed: [instance] 2026-09-02 15:53:41.463817 | instance | 2026-09-02 15:53:41.464035 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-02 15:53:41.464077 | instance | Wednesday 02 September 2026 15:53:41 +0000 (0:00:00.727) 0:00:23.188 *** 2026-09-02 15:53:45.823389 | instance | changed: [instance] 2026-09-02 15:53:45.823514 | instance | 2026-09-02 15:53:45.823525 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ****************** 2026-09-02 15:53:45.823535 | instance | Wednesday 02 September 2026 15:53:45 +0000 (0:00:04.358) 0:00:27.546 *** 2026-09-02 15:53:47.023436 | instance | ok: [instance] 2026-09-02 15:53:47.024208 | instance | 2026-09-02 15:53:47.024263 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] *************** 2026-09-02 15:53:47.024276 | instance | Wednesday 02 September 2026 15:53:47 +0000 (0:00:01.200) 0:00:28.747 *** 2026-09-02 15:53:47.407251 | instance | changed: [instance] 2026-09-02 15:53:47.407355 | instance | 2026-09-02 15:53:47.407738 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] ***** 2026-09-02 15:53:47.407781 | instance | Wednesday 02 September 2026 15:53:47 +0000 (0:00:00.384) 0:00:29.132 *** 2026-09-02 15:53:47.897021 | instance | changed: [instance] 2026-09-02 15:53:47.897949 | instance | 2026-09-02 15:53:47.898005 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] *********** 2026-09-02 15:53:47.898017 | instance | Wednesday 02 September 2026 15:53:47 +0000 (0:00:00.489) 0:00:29.621 *** 2026-09-02 15:53:48.493664 | instance | changed: [instance] => (item={'path': '/etc/docker'}) 2026-09-02 15:53:48.494997 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'}) 2026-09-02 15:53:48.495048 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'}) 2026-09-02 15:53:48.495061 | instance | 2026-09-02 15:53:48.495072 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ****** 2026-09-02 15:53:48.495083 | instance | Wednesday 02 September 2026 15:53:48 +0000 (0:00:00.596) 0:00:30.217 *** 2026-09-02 15:53:48.965379 | instance | changed: [instance] 2026-09-02 15:53:48.965847 | instance | 2026-09-02 15:53:48.965891 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] *********** 2026-09-02 15:53:48.965899 | instance | Wednesday 02 September 2026 15:53:48 +0000 (0:00:00.472) 0:00:30.690 *** 2026-09-02 15:53:49.480779 | instance | changed: [instance] 2026-09-02 15:53:49.480874 | instance | 2026-09-02 15:53:49.480939 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************ 2026-09-02 15:53:49.481601 | instance | Wednesday 02 September 2026 15:53:49 +0000 (0:00:00.487) 0:00:31.177 *** 2026-09-02 15:53:49.481778 | instance | 2026-09-02 15:53:49.481790 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-02 15:53:49.481806 | instance | Wednesday 02 September 2026 15:53:49 +0000 (0:00:00.027) 0:00:31.205 *** 2026-09-02 15:53:50.204399 | instance | ok: [instance] 2026-09-02 15:53:50.204498 | instance | 2026-09-02 15:53:50.204862 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ****************** 2026-09-02 15:53:50.204906 | instance | Wednesday 02 September 2026 15:53:50 +0000 (0:00:00.723) 0:00:31.929 *** 2026-09-02 15:53:51.209057 | instance | changed: [instance] 2026-09-02 15:53:51.209139 | instance | 2026-09-02 15:53:51.209481 | instance | TASK [vexxhost.containers.docker : Enable and start service] ******************* 2026-09-02 15:53:51.209528 | instance | Wednesday 02 September 2026 15:53:51 +0000 (0:00:01.004) 0:00:32.933 *** 2026-09-02 15:53:51.854704 | instance | changed: [instance] 2026-09-02 15:53:51.854781 | instance | 2026-09-02 15:53:51.855095 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ****** 2026-09-02 15:53:51.855134 | instance | Wednesday 02 September 2026 15:53:51 +0000 (0:00:00.645) 0:00:33.579 *** 2026-09-02 15:53:51.932226 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml) 2026-09-02 15:53:51.932941 | instance | 2026-09-02 15:53:51.932975 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ******************************** 2026-09-02 15:53:51.932987 | instance | Wednesday 02 September 2026 15:53:51 +0000 (0:00:00.077) 0:00:33.656 *** 2026-09-02 15:53:57.013681 | instance | changed: [instance] 2026-09-02 15:53:57.013768 | instance | 2026-09-02 15:53:57.013831 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] ********************* 2026-09-02 15:53:57.013950 | instance | Wednesday 02 September 2026 15:53:57 +0000 (0:00:05.081) 0:00:38.738 *** 2026-09-02 15:53:57.728864 | instance | ok: [instance] => (item=chronyd) 2026-09-02 15:53:57.729967 | instance | ok: [instance] => (item=sshd) 2026-09-02 15:53:57.730029 | instance | 2026-09-02 15:53:57.730047 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ****************************** 2026-09-02 15:53:57.730064 | instance | Wednesday 02 September 2026 15:53:57 +0000 (0:00:00.714) 0:00:39.453 *** 2026-09-02 15:53:58.069242 | instance | changed: [instance] 2026-09-02 15:53:58.069943 | instance | 2026-09-02 15:53:58.069973 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ******************** 2026-09-02 15:53:58.069985 | instance | Wednesday 02 September 2026 15:53:58 +0000 (0:00:00.340) 0:00:39.793 *** 2026-09-02 15:53:58.293236 | instance | ok: [instance] 2026-09-02 15:53:58.294037 | instance | 2026-09-02 15:53:58.294070 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] **************** 2026-09-02 15:53:58.294083 | instance | Wednesday 02 September 2026 15:53:58 +0000 (0:00:00.223) 0:00:40.017 *** 2026-09-02 15:53:58.764768 | instance | changed: [instance] 2026-09-02 15:53:58.765588 | instance | 2026-09-02 15:53:58.765643 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] *** 2026-09-02 15:53:58.765658 | instance | Wednesday 02 September 2026 15:53:58 +0000 (0:00:00.471) 0:00:40.488 *** 2026-09-02 15:53:59.126135 | instance | changed: [instance] 2026-09-02 15:53:59.126172 | instance | 2026-09-02 15:53:59.126178 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] ************************* 2026-09-02 15:53:59.126183 | instance | Wednesday 02 September 2026 15:53:59 +0000 (0:00:00.361) 0:00:40.850 *** 2026-09-02 15:53:59.332771 | instance | ok: [instance] 2026-09-02 15:53:59.332812 | instance | 2026-09-02 15:53:59.332820 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] ******************* 2026-09-02 15:53:59.332827 | instance | Wednesday 02 September 2026 15:53:59 +0000 (0:00:00.206) 0:00:41.056 *** 2026-09-02 15:53:59.578662 | instance | ok: [instance] => (item=instance) 2026-09-02 15:53:59.578801 | instance | 2026-09-02 15:53:59.578810 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ******** 2026-09-02 15:53:59.578816 | instance | Wednesday 02 September 2026 15:53:59 +0000 (0:00:00.246) 0:00:41.302 *** 2026-09-02 15:53:59.641468 | instance | ok: [instance] 2026-09-02 15:53:59.641492 | instance | 2026-09-02 15:53:59.641798 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] *********************************** 2026-09-02 15:53:59.641815 | instance | Wednesday 02 September 2026 15:53:59 +0000 (0:00:00.063) 0:00:41.366 *** 2026-09-02 15:53:59.723463 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance 2026-09-02 15:53:59.723502 | instance | 2026-09-02 15:53:59.723513 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] ************* 2026-09-02 15:53:59.723677 | instance | Wednesday 02 September 2026 15:53:59 +0000 (0:00:00.081) 0:00:41.447 *** 2026-09-02 15:53:59.954234 | instance | changed: [instance] 2026-09-02 15:53:59.954415 | instance | 2026-09-02 15:53:59.954654 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ****************** 2026-09-02 15:53:59.954888 | instance | Wednesday 02 September 2026 15:53:59 +0000 (0:00:00.231) 0:00:41.678 *** 2026-09-02 15:54:00.654623 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True}) 2026-09-02 15:54:00.654907 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0}) 2026-09-02 15:54:00.655208 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False}) 2026-09-02 15:54:00.655550 | instance | 2026-09-02 15:54:00.655845 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] ******************************* 2026-09-02 15:54:00.656128 | instance | Wednesday 02 September 2026 15:54:00 +0000 (0:00:00.700) 0:00:42.379 *** 2026-09-02 15:55:33.703142 | instance | ok: [instance] 2026-09-02 15:55:33.703861 | instance | 2026-09-02 15:55:33.704660 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] *************** 2026-09-02 15:55:33.705498 | instance | Wednesday 02 September 2026 15:55:33 +0000 (0:01:33.046) 0:02:15.425 *** 2026-09-02 15:55:33.933588 | instance | changed: [instance] 2026-09-02 15:55:33.933647 | instance | 2026-09-02 15:55:33.933774 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] ********************************** 2026-09-02 15:55:33.933877 | instance | Wednesday 02 September 2026 15:55:33 +0000 (0:00:00.232) 0:02:15.658 *** 2026-09-02 15:55:33.977744 | instance | ok: [instance] 2026-09-02 15:55:33.977867 | instance | 2026-09-02 15:55:33.978024 | instance | TASK [Install Ceph host] ******************************************************* 2026-09-02 15:55:33.978180 | instance | Wednesday 02 September 2026 15:55:33 +0000 (0:00:00.043) 0:02:15.701 *** 2026-09-02 15:55:34.058900 | instance | included: vexxhost.ceph.cephadm_host for instance 2026-09-02 15:55:34.059102 | instance | 2026-09-02 15:55:34.059367 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ****** 2026-09-02 15:55:34.059646 | instance | Wednesday 02 September 2026 15:55:34 +0000 (0:00:00.081) 0:02:15.783 *** 2026-09-02 15:55:35.681649 | instance | ok: [instance] 2026-09-02 15:55:35.681854 | instance | 2026-09-02 15:55:35.682123 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] *** 2026-09-02 15:55:35.682358 | instance | Wednesday 02 September 2026 15:55:35 +0000 (0:00:01.622) 0:02:17.405 *** 2026-09-02 15:55:35.758418 | instance | ok: [instance] => (item=instance) 2026-09-02 15:55:35.758690 | instance | 2026-09-02 15:55:35.758986 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] *********** 2026-09-02 15:55:35.759274 | instance | Wednesday 02 September 2026 15:55:35 +0000 (0:00:00.076) 0:02:17.482 *** 2026-09-02 15:55:36.125753 | instance | ok: [instance] 2026-09-02 15:55:36.125983 | instance | 2026-09-02 15:55:36.126365 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] *********************** 2026-09-02 15:55:36.126712 | instance | Wednesday 02 September 2026 15:55:36 +0000 (0:00:00.367) 0:02:17.850 *** 2026-09-02 15:55:38.459968 | instance | ok: [instance] 2026-09-02 15:55:38.460079 | instance | 2026-09-02 15:55:38.460235 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ****************** 2026-09-02 15:55:38.460407 | instance | Wednesday 02 September 2026 15:55:38 +0000 (0:00:02.334) 0:02:20.184 *** 2026-09-02 15:56:30.349519 | 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:56:30.350309 | 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_kykdoc85/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_kykdoc85/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_kykdoc85/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_kykdoc85/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:56:30.180+0000 7f9ebad80640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDKRphqwFHvKCAAKV+f6JiHYkbLU4mQdrSdYCknP4FmS4Qfqxhgvj5cMmk=: Malformed input [buffer:3]\n2026-09-02T15:56:30.180+0000 7f9ebad80640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-02T15:56:30.184+0000 7f9ebad80640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDKRphqwFHvKCAAKV+f6JiHYkbLU4mQdrSdYCknP4FmS4Qfqxhgvj5cMmk=: Malformed input [buffer:3]\n2026-09-02T15:56:30.184+0000 7f9ebad80640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-02T15:56:30.184+0000 7f9ebad80640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDKRphqwFHvKCAAKV+f6JiHYkbLU4mQdrSdYCknP4FmS4Qfqxhgvj5cMmk=: Malformed input [buffer:3]\n2026-09-02T15:56:30.184+0000 7f9ebad80640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-02T15:56:30.184+0000 7f9ebad80640 -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:56:30.352378 | instance | 2026-09-02 15:56:30.352675 | instance | PLAY RECAP ********************************************************************* 2026-09-02 15:56:30.352930 | instance | instance : ok=55 changed=26 unreachable=0 failed=1 skipped=7 rescued=0 ignored=0 2026-09-02 15:56:30.353141 | instance | 2026-09-02 15:56:30.353397 | instance | Wednesday 02 September 2026 15:56:30 +0000 (0:00:51.892) 0:03:12.077 *** 2026-09-02 15:56:30.353610 | instance | =============================================================================== 2026-09-02 15:56:30.353871 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 93.05s 2026-09-02 15:56:30.354099 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 51.89s 2026-09-02 15:56:30.354344 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 6.32s 2026-09-02 15:56:30.354565 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.08s 2026-09-02 15:56:30.354786 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.36s 2026-09-02 15:56:30.355007 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.96s 2026-09-02 15:56:30.355240 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.33s 2026-09-02 15:56:30.355507 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.69s 2026-09-02 15:56:30.355728 | instance | vexxhost.containers.containerd : Enable and start service --------------- 1.64s 2026-09-02 15:56:30.355954 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.62s 2026-09-02 15:56:30.356160 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.31s 2026-09-02 15:56:30.356406 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.20s 2026-09-02 15:56:30.356628 | instance | Gathering Facts --------------------------------------------------------- 1.06s 2026-09-02 15:56:30.356853 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.03s 2026-09-02 15:56:30.357071 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 1.00s 2026-09-02 15:56:30.357305 | instance | Gathering Facts --------------------------------------------------------- 0.98s 2026-09-02 15:56:30.357533 | instance | Gathering Facts --------------------------------------------------------- 0.94s 2026-09-02 15:56:30.357756 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.73s 2026-09-02 15:56:30.357996 | instance | vexxhost.ceph.cephadm : Ensure services are started --------------------- 0.71s 2026-09-02 15:56:30.358276 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.70s 2026-09-02 15:56:30.457963 | 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:56:30.458102 | instance | ERROR [csi > converge] Executed: Failed 2026-09-02 15:56:30.458253 | 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:56:30.699334 | instance | ERROR 2026-09-02 15:56:30.699557 | instance | { 2026-09-02 15:56:30.699606 | instance | "delta": "0:04:04.812141", 2026-09-02 15:56:30.699644 | instance | "end": "2026-09-02 15:56:30.525368", 2026-09-02 15:56:30.699678 | instance | "msg": "non-zero return code", 2026-09-02 15:56:30.699710 | instance | "rc": 2, 2026-09-02 15:56:30.699746 | instance | "start": "2026-09-02 15:52:25.713227" 2026-09-02 15:56:30.699815 | instance | } failure 2026-09-02 15:56:30.706498 | 2026-09-02 15:56:30.706562 | PLAY RECAP 2026-09-02 15:56:30.706625 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-02 15:56:30.706671 | 2026-09-02 15:56:30.847523 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-02 15:56:30.852150 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-02 15:56:31.471871 | 2026-09-02 15:56:31.472367 | PLAY [all] 2026-09-02 15:56:31.486594 | 2026-09-02 15:56:31.486669 | TASK [gather-host-logs : creating directory for system status] 2026-09-02 15:56:31.865409 | instance | changed 2026-09-02 15:56:31.872995 | 2026-09-02 15:56:31.873116 | TASK [gather-host-logs : Get logs for each host] 2026-09-02 15:56:32.241574 | instance | + systemd-cgls --full --all --no-pager 2026-09-02 15:56:32.265729 | instance | + ip addr 2026-09-02 15:56:32.268288 | instance | + ip route 2026-09-02 15:56:32.271367 | instance | + lsblk 2026-09-02 15:56:32.276296 | instance | + mount 2026-09-02 15:56:32.278848 | instance | + docker images 2026-09-02 15:56:32.301390 | instance | + brctl show 2026-09-02 15:56:32.301926 | instance | /bin/bash: line 8: brctl: command not found 2026-09-02 15:56:32.302305 | instance | + ps aux --sort=-%mem 2026-09-02 15:56:32.338681 | instance | + dpkg -l 2026-09-02 15:56:32.345396 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-09-02 15:56:32.345878 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-09-02 15:56:32.365061 | instance | + '[' '!' -z '' ']' 2026-09-02 15:56:32.630039 | instance | ok: Runtime: 0:00:00.127844 2026-09-02 15:56:32.635835 | 2026-09-02 15:56:32.635904 | TASK [gather-host-logs : Downloads logs to executor] 2026-09-02 15:56:33.274448 | instance | changed: 2026-09-02 15:56:33.274699 | instance | created directory /var/lib/zuul/builds/0877a05c4dd3465db0a6e456ef46e5fa/work/logs/instance 2026-09-02 15:56:33.274748 | instance | cd+++++++++ system/ 2026-09-02 15:56:33.274780 | instance | >f+++++++++ system/brctl-show.txt 2026-09-02 15:56:33.274809 | instance | >f+++++++++ system/docker-images.txt 2026-09-02 15:56:33.274836 | instance | >f+++++++++ system/ip-addr.txt 2026-09-02 15:56:33.274868 | instance | >f+++++++++ system/ip-route.txt 2026-09-02 15:56:33.274896 | instance | >f+++++++++ system/lsblk.txt 2026-09-02 15:56:33.274924 | instance | >f+++++++++ system/mount.txt 2026-09-02 15:56:33.274951 | instance | >f+++++++++ system/packages.txt 2026-09-02 15:56:33.274976 | instance | >f+++++++++ system/ps.txt 2026-09-02 15:56:33.275006 | instance | >f+++++++++ system/systemd-cgls.txt 2026-09-02 15:56:33.286634 | 2026-09-02 15:56:33.286703 | LOOP [helm-release-status : creating directory for helm release status] 2026-09-02 15:56:33.503861 | instance | changed: "values" 2026-09-02 15:56:33.682449 | instance | changed: "releases" 2026-09-02 15:56:33.701682 | 2026-09-02 15:56:33.701899 | TASK [helm-release-status : Gather get release status for helm charts] 2026-09-02 15:56:33.926429 | instance | /bin/bash: line 3: kubectl: command not found 2026-09-02 15:56:34.241669 | instance | ok: Runtime: 0:00:00.005461 2026-09-02 15:56:34.249027 | 2026-09-02 15:56:34.249127 | TASK [helm-release-status : Downloads logs to executor] 2026-09-02 15:56:34.749543 | instance | changed: 2026-09-02 15:56:34.749838 | instance | cd+++++++++ helm/ 2026-09-02 15:56:34.749906 | instance | cd+++++++++ helm/releases/ 2026-09-02 15:56:34.749954 | instance | cd+++++++++ helm/values/ 2026-09-02 15:56:34.761427 | 2026-09-02 15:56:34.761498 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-09-02 15:56:34.978656 | instance | changed 2026-09-02 15:56:34.984407 | 2026-09-02 15:56:34.984477 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-09-02 15:56:35.247951 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-02 15:56:35.248415 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-02 15:56:35.252755 | instance | environment: line 1: kubectl: command not found 2026-09-02 15:56:35.254285 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-02 15:56:35.255236 | instance | environment: line 1: kubectl: command not found 2026-09-02 15:56:35.255516 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-02 15:56:35.257652 | instance | environment: line 1: kubectl: command not found 2026-09-02 15:56:35.257783 | instance | environment: line 1: kubectl: command not found 2026-09-02 15:56:35.258983 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-02 15:56:35.259933 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-02 15:56:35.261127 | instance | environment: line 1: kubectl: command not found 2026-09-02 15:56:35.262359 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-02 15:56:35.517898 | instance | ok: Runtime: 0:00:00.021035 2026-09-02 15:56:35.522922 | 2026-09-02 15:56:35.522989 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-09-02 15:56:35.739517 | instance | changed 2026-09-02 15:56:35.745579 | 2026-09-02 15:56:35.745649 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-09-02 15:56:35.967248 | instance | environment: line 5: kubectl: command not found 2026-09-02 15:56:35.968461 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-02 15:56:35.968928 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-02 15:56:35.969440 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-02 15:56:36.283102 | instance | ok: Runtime: 0:00:00.007219 2026-09-02 15:56:36.290331 | 2026-09-02 15:56:36.290419 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-09-02 15:56:36.782310 | instance | changed: 2026-09-02 15:56:36.782439 | instance | cd+++++++++ objects/ 2026-09-02 15:56:36.782466 | instance | cd+++++++++ objects/cluster/ 2026-09-02 15:56:36.782490 | instance | cd+++++++++ objects/namespaced/ 2026-09-02 15:56:36.791116 | 2026-09-02 15:56:36.791181 | TASK [gather-pod-logs : creating directory for pod logs] 2026-09-02 15:56:37.006554 | instance | changed 2026-09-02 15:56:37.011437 | 2026-09-02 15:56:37.011501 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-09-02 15:56:37.229631 | instance | changed 2026-09-02 15:56:37.236582 | 2026-09-02 15:56:37.236654 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-09-02 15:56:37.458431 | instance | environment: line 3: kubectl: command not found 2026-09-02 15:56:37.774108 | instance | ok: Runtime: 0:00:00.006617 2026-09-02 15:56:37.779163 | 2026-09-02 15:56:37.779228 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-09-02 15:56:38.278499 | instance | changed: 2026-09-02 15:56:38.278721 | instance | cd+++++++++ pod-logs/ 2026-09-02 15:56:38.278760 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-09-02 15:56:38.290825 | 2026-09-02 15:56:38.290889 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-09-02 15:56:38.495422 | instance | changed 2026-09-02 15:56:38.502416 | 2026-09-02 15:56:38.502486 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-09-02 15:56:38.716070 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-02 15:56:39.041718 | instance | ok: Runtime: 0:00:00.047735 2026-09-02 15:56:39.049525 | 2026-09-02 15:56:39.049598 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-09-02 15:56:39.268238 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-02 15:56:39.311628 | instance | ceph-mgr endpoints: 2026-09-02 15:56:39.587607 | instance | ok: Runtime: 0:00:00.049175 2026-09-02 15:56:39.594481 | 2026-09-02 15:56:39.594545 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-09-02 15:56:39.817429 | instance | /bin/bash: line 4: kubectl: command not found 2026-09-02 15:56:40.136366 | instance | ok: Runtime: 0:00:00.051079 2026-09-02 15:56:40.143544 | 2026-09-02 15:56:40.143643 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-09-02 15:56:40.644920 | instance | changed: cd+++++++++ prometheus/ 2026-09-02 15:56:40.654795 | 2026-09-02 15:56:40.654858 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-09-02 15:56:40.871923 | instance | changed 2026-09-02 15:56:40.876993 | 2026-09-02 15:56:40.877059 | TASK [gather-selenium-data : Get selenium data] 2026-09-02 15:56:41.143747 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-09-02 15:56:41.145128 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-09-02 15:56:41.412745 | instance | ERROR 2026-09-02 15:56:41.413028 | instance | { 2026-09-02 15:56:41.413075 | instance | "delta": "0:00:00.005019", 2026-09-02 15:56:41.413104 | instance | "end": "2026-09-02 15:56:41.145472", 2026-09-02 15:56:41.413133 | instance | "msg": "non-zero return code", 2026-09-02 15:56:41.413160 | instance | "rc": 1, 2026-09-02 15:56:41.413186 | instance | "start": "2026-09-02 15:56:41.140453" 2026-09-02 15:56:41.413211 | instance | } 2026-09-02 15:56:41.413244 | instance | ERROR: Ignoring Errors 2026-09-02 15:56:41.419988 | 2026-09-02 15:56:41.420064 | TASK [gather-selenium-data : Downloads logs to executor] 2026-09-02 15:56:41.921676 | instance | changed: cd+++++++++ selenium/ 2026-09-02 15:56:41.930576 | 2026-09-02 15:56:41.930639 | PLAY RECAP 2026-09-02 15:56:41.930691 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-09-02 15:56:41.930712 | 2026-09-02 15:56:42.075934 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-02 15:56:42.080144 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-02 15:56:42.688161 | 2026-09-02 15:56:42.688300 | PLAY [all] 2026-09-02 15:56:42.700635 | 2026-09-02 15:56:42.700716 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-02 15:56:42.748002 | instance | skipping: Conditional result was False 2026-09-02 15:56:42.756514 | 2026-09-02 15:56:42.756622 | TASK [fetch-output : Set log path for single node] 2026-09-02 15:56:42.802721 | instance | ok 2026-09-02 15:56:42.809665 | 2026-09-02 15:56:42.809733 | LOOP [fetch-output : Ensure local output dirs] 2026-09-02 15:56:43.218544 | instance -> localhost | ok: "/var/lib/zuul/builds/0877a05c4dd3465db0a6e456ef46e5fa/work/logs" 2026-09-02 15:56:43.420194 | instance -> localhost | changed: "/var/lib/zuul/builds/0877a05c4dd3465db0a6e456ef46e5fa/work/artifacts" 2026-09-02 15:56:43.632537 | instance -> localhost | changed: "/var/lib/zuul/builds/0877a05c4dd3465db0a6e456ef46e5fa/work/docs" 2026-09-02 15:56:43.647714 | 2026-09-02 15:56:43.647947 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-02 15:56:44.312787 | instance | changed: .d..t...... ./ 2026-09-02 15:56:44.313021 | instance | changed: All items complete 2026-09-02 15:56:44.313050 | 2026-09-02 15:56:44.769875 | instance | changed: .d..t...... ./ 2026-09-02 15:56:45.239704 | instance | changed: .d..t...... ./ 2026-09-02 15:56:45.263872 | 2026-09-02 15:56:45.264031 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-02 15:56:45.683286 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.009359 2026-09-02 15:56:45.897465 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006930 2026-09-02 15:56:45.915747 | 2026-09-02 15:56:45.915904 | PLAY [all] 2026-09-02 15:56:45.923664 | 2026-09-02 15:56:45.923736 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-02 15:56:46.339915 | instance | changed 2026-09-02 15:56:46.347661 | 2026-09-02 15:56:46.347727 | PLAY RECAP 2026-09-02 15:56:46.347788 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-02 15:56:46.347819 | 2026-09-02 15:56:46.511881 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-02 15:56:46.514117 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-02 15:56:47.098838 | 2026-09-02 15:56:47.098980 | PLAY [localhost] 2026-09-02 15:56:47.110042 | 2026-09-02 15:56:47.110124 | TASK [Generate Zuul manifest] 2026-09-02 15:56:47.128857 | localhost | ok 2026-09-02 15:56:47.147428 | 2026-09-02 15:56:47.147512 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-02 15:56:47.524394 | localhost | changed 2026-09-02 15:56:47.627375 | 2026-09-02 15:56:47.627591 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-02 15:56:47.660247 | localhost | ok 2026-09-02 15:56:47.669665 | 2026-09-02 15:56:47.669755 | TASK [Upload logs] 2026-09-02 15:56:47.691320 | localhost | ok 2026-09-02 15:56:47.763388 | 2026-09-02 15:56:47.763552 | TASK [Set zuul-log-path fact] 2026-09-02 15:56:47.784376 | localhost | ok 2026-09-02 15:56:47.799919 | 2026-09-02 15:56:47.800000 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-02 15:56:47.832723 | localhost | ok 2026-09-02 15:56:47.842753 | 2026-09-02 15:56:47.842859 | TASK [upload-logs : Create log directories] 2026-09-02 15:56:48.259833 | localhost | changed 2026-09-02 15:56:48.266515 | 2026-09-02 15:56:48.266589 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-02 15:56:48.665880 | localhost -> localhost | ok: Runtime: 0:00:00.003442 2026-09-02 15:56:48.674331 | 2026-09-02 15:56:48.674400 | TASK [upload-logs : Upload logs to log server] 2026-09-02 15:56:49.119920 | localhost | Output suppressed because no_log was given 2026-09-02 15:56:49.169946 | 2026-09-02 15:56:49.170131 | LOOP [upload-logs : Compress console log and json output] 2026-09-02 15:56:49.216609 | localhost | skipping: Conditional result was False 2026-09-02 15:56:49.223436 | localhost | skipping: Conditional result was False 2026-09-02 15:56:49.235333 | 2026-09-02 15:56:49.235531 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-02 15:56:49.278157 | localhost | skipping: Conditional result was False 2026-09-02 15:56:49.278528 | 2026-09-02 15:56:49.282308 | localhost | skipping: Conditional result was False 2026-09-02 15:56:49.300670 | 2026-09-02 15:56:49.300896 | LOOP [upload-logs : Upload console log and json output]