2026-09-12 03:57:47.980636 | Job console starting 2026-09-12 03:57:48.038902 | Updating git repos 2026-09-12 03:57:50.244339 | Cloning repos into workspace 2026-09-12 03:58:06.357566 | Restoring repo states 2026-09-12 03:58:12.341059 | Merging changes 2026-09-12 03:58:13.461102 | Checking out repos 2026-09-12 03:58:15.557939 | Preparing playbooks 2026-09-12 03:58:18.791236 | Running Ansible setup 2026-09-12 03:58:22.421564 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-12 03:58:22.996894 | 2026-09-12 03:58:23.015722 | PLAY [localhost] 2026-09-12 03:58:23.023675 | 2026-09-12 03:58:23.023743 | TASK [Gathering Facts] 2026-09-12 03:58:23.931784 | localhost | ok 2026-09-12 03:58:23.945994 | 2026-09-12 03:58:23.946156 | TASK [Setup log path fact] 2026-09-12 03:58:23.966962 | localhost | ok 2026-09-12 03:58:23.984192 | 2026-09-12 03:58:23.984289 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-12 03:58:24.024173 | localhost | ok 2026-09-12 03:58:24.035625 | 2026-09-12 03:58:24.037576 | TASK [emit-job-header : Print job information] 2026-09-12 03:58:24.089437 | # Job Information 2026-09-12 03:58:24.089886 | Ansible Version: 2.16.18 2026-09-12 03:58:24.089975 | Job: atmosphere-molecule-csi-rbd 2026-09-12 03:58:24.090024 | Pipeline: check 2026-09-12 03:58:24.090067 | Executor: 2d72f0692154 2026-09-12 03:58:24.090108 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4370 2026-09-12 03:58:24.090157 | Event ID: f7d435a0-ae5c-11f1-8246-5a57cbd56350 2026-09-12 03:58:24.095881 | 2026-09-12 03:58:24.101241 | LOOP [emit-job-header : Print node information] 2026-09-12 03:58:24.202839 | localhost | ok: 2026-09-12 03:58:24.217798 | localhost | # Node Information 2026-09-12 03:58:24.218011 | localhost | Inventory Hostname: instance 2026-09-12 03:58:24.218043 | localhost | Hostname: np0000192706 2026-09-12 03:58:24.218068 | localhost | Username: zuul 2026-09-12 03:58:24.218097 | localhost | Distro: Ubuntu 22.04 2026-09-12 03:58:24.218120 | localhost | Provider: yul1 2026-09-12 03:58:24.218144 | localhost | Region: ca-ymq-1 2026-09-12 03:58:24.218177 | localhost | Label: ubuntu-jammy 2026-09-12 03:58:24.218203 | localhost | Product Name: OpenStack Nova 2026-09-12 03:58:24.218235 | localhost | Interface IP: 162.253.55.109 2026-09-12 03:58:24.239618 | 2026-09-12 03:58:24.239851 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-12 03:58:24.730873 | localhost -> localhost | changed 2026-09-12 03:58:24.735581 | 2026-09-12 03:58:24.735657 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-12 03:58:25.559551 | localhost -> localhost | changed 2026-09-12 03:58:25.571432 | 2026-09-12 03:58:25.571537 | PLAY [all] 2026-09-12 03:58:25.587713 | 2026-09-12 03:58:25.587870 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-12 03:58:25.857137 | instance -> localhost | ok 2026-09-12 03:58:25.872523 | 2026-09-12 03:58:25.872698 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-12 03:58:25.908535 | instance | ok 2026-09-12 03:58:25.932345 | instance | included: /var/lib/zuul/builds/a4ea0dc9e94040dc886f537f2cb936be/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-12 03:58:25.939670 | 2026-09-12 03:58:25.939757 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-12 03:58:26.904886 | instance -> localhost | Generating public/private rsa key pair. 2026-09-12 03:58:26.905241 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/a4ea0dc9e94040dc886f537f2cb936be/work/a4ea0dc9e94040dc886f537f2cb936be_id_rsa 2026-09-12 03:58:26.905318 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/a4ea0dc9e94040dc886f537f2cb936be/work/a4ea0dc9e94040dc886f537f2cb936be_id_rsa.pub 2026-09-12 03:58:26.905380 | instance -> localhost | The key fingerprint is: 2026-09-12 03:58:26.906166 | instance -> localhost | SHA256:296K5k1nVRNEdcTIUIPyaXDebkKmo50k/OlZ7uQBtFM zuul-build-sshkey 2026-09-12 03:58:26.906288 | instance -> localhost | The key's randomart image is: 2026-09-12 03:58:26.906352 | instance -> localhost | +---[RSA 3072]----+ 2026-09-12 03:58:26.906398 | instance -> localhost | | .==B=| 2026-09-12 03:58:26.906442 | instance -> localhost | | o o o.+| 2026-09-12 03:58:26.906481 | instance -> localhost | | .*Eo ..| 2026-09-12 03:58:26.906536 | instance -> localhost | | . oB ...| 2026-09-12 03:58:26.906592 | instance -> localhost | | .S += .. | 2026-09-12 03:58:26.906638 | instance -> localhost | | oo+o..o | 2026-09-12 03:58:26.906677 | instance -> localhost | | .*o+*o | 2026-09-12 03:58:26.906716 | instance -> localhost | | o==X . | 2026-09-12 03:58:26.906762 | instance -> localhost | | oo.*o= | 2026-09-12 03:58:26.906827 | instance -> localhost | +----[SHA256]-----+ 2026-09-12 03:58:26.906939 | instance -> localhost | ok: Runtime: 0:00:00.507455 2026-09-12 03:58:26.920469 | 2026-09-12 03:58:26.920691 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-12 03:58:26.954407 | instance | ok 2026-09-12 03:58:26.965577 | instance | included: /var/lib/zuul/builds/a4ea0dc9e94040dc886f537f2cb936be/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-12 03:58:26.975431 | 2026-09-12 03:58:26.975545 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-12 03:58:27.000788 | instance | skipping: Conditional result was False 2026-09-12 03:58:27.008679 | 2026-09-12 03:58:27.008850 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-12 03:58:27.470051 | instance | changed 2026-09-12 03:58:27.482065 | 2026-09-12 03:58:27.482237 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-12 03:58:27.669864 | instance | ok 2026-09-12 03:58:27.680076 | 2026-09-12 03:58:27.680222 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-12 03:58:28.186027 | instance | changed 2026-09-12 03:58:28.197278 | 2026-09-12 03:58:28.197420 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-12 03:58:28.701685 | instance | changed 2026-09-12 03:58:28.710115 | 2026-09-12 03:58:28.710220 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-12 03:58:28.735153 | instance | skipping: Conditional result was False 2026-09-12 03:58:28.743540 | 2026-09-12 03:58:28.743637 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-12 03:58:29.207337 | instance -> localhost | changed 2026-09-12 03:58:29.233585 | 2026-09-12 03:58:29.233778 | TASK [add-build-sshkey : Add back temp key] 2026-09-12 03:58:29.554994 | instance -> localhost | Identity added: /var/lib/zuul/builds/a4ea0dc9e94040dc886f537f2cb936be/work/a4ea0dc9e94040dc886f537f2cb936be_id_rsa (zuul-build-sshkey) 2026-09-12 03:58:29.555233 | instance -> localhost | ok: Runtime: 0:00:00.017504 2026-09-12 03:58:29.567092 | 2026-09-12 03:58:29.567193 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-12 03:58:29.917324 | instance | ok 2026-09-12 03:58:29.935256 | 2026-09-12 03:58:29.935736 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-12 03:58:29.960086 | instance | skipping: Conditional result was False 2026-09-12 03:58:29.977379 | 2026-09-12 03:58:29.977490 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-12 03:58:30.351534 | instance | ok 2026-09-12 03:58:30.357212 | 2026-09-12 03:58:30.357349 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-12 03:58:35.033632 | instance | Output suppressed because no_log was given 2026-09-12 03:58:35.052205 | 2026-09-12 03:58:35.052368 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-12 03:58:35.272557 | instance | ok: "logs" 2026-09-12 03:58:35.272935 | instance | ok: All items complete 2026-09-12 03:58:35.272991 | 2026-09-12 03:58:35.414387 | instance | ok: "artifacts" 2026-09-12 03:58:35.576438 | instance | ok: "docs" 2026-09-12 03:58:35.602389 | 2026-09-12 03:58:35.602651 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-12 03:58:35.811768 | instance | changed: "logs" 2026-09-12 03:58:35.986635 | instance | changed: "artifacts" 2026-09-12 03:58:36.142090 | instance | changed: "docs" 2026-09-12 03:58:36.162024 | 2026-09-12 03:58:36.162224 | PLAY RECAP 2026-09-12 03:58:36.162326 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-12 03:58:36.162384 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-12 03:58:36.162431 | 2026-09-12 03:58:36.327565 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-12 03:58:36.330740 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-12 03:58:36.961063 | 2026-09-12 03:58:36.961313 | PLAY [all] 2026-09-12 03:58:36.980046 | 2026-09-12 03:58:36.980244 | TASK [setup-uv : Extract archive] 2026-09-12 03:58:39.380660 | instance | changed 2026-09-12 03:58:39.391784 | 2026-09-12 03:58:39.391972 | TASK [setup-uv : Print version] 2026-09-12 03:58:39.793726 | instance | uv 0.8.13 2026-09-12 03:58:39.948939 | instance | ok: Runtime: 0:00:00.012221 2026-09-12 03:58:39.963777 | 2026-09-12 03:58:39.963891 | TASK [Install Ansible collection siblings] 2026-09-12 03:58:49.654269 | instance | changed: 2026-09-12 03:58:49.654528 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-09-12 03:58:49.654586 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-09-12 03:58:49.654627 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-09-12 03:58:49.654665 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto 2026-09-12 03:58:49.654703 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general 2026-09-12 03:58:49.654747 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql 2026-09-12 03:58:49.654787 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-09-12 03:58:49.654849 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-09-12 03:58:49.654889 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-09-12 03:58:49.654925 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-09-12 03:58:49.654960 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-09-12 03:58:49.654995 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-09-12 03:58:49.655029 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0 2026-09-12 03:58:49.655064 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0 2026-09-12 03:58:49.655098 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0 2026-09-12 03:58:49.655134 | instance | Resolved community.crypto to 2.2.3 for 2.2.3 2026-09-12 03:58:49.655168 | instance | Resolved community.general to 7.3.0 for 7.3.0 2026-09-12 03:58:49.655202 | instance | Resolved community.mysql to 3.6.0 for 3.6.0 2026-09-12 03:58:49.655235 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0 2026-09-12 03:58:49.655269 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0 2026-09-12 03:58:49.655302 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0 2026-09-12 03:58:49.655336 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0 2026-09-12 03:58:49.655374 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0 2026-09-12 03:58:49.655422 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0 2026-09-12 03:58:49.655458 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0 2026-09-12 03:58:49.655493 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-12 03:58:49.655527 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-12 03:58:49.655560 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0 2026-09-12 03:58:49.655594 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0 2026-09-12 03:58:49.655627 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3 2026-09-12 03:58:49.655661 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-12 03:58:49.655696 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-12 03:58:49.655731 | instance | Checked out ansible.netcommon to 1.2.0 2026-09-12 03:58:49.655766 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-09-12 03:58:49.655835 | instance | Starting galaxy collection install process 2026-09-12 03:58:49.655873 | instance | Process install dependency map 2026-09-12 03:58:49.655909 | instance | Starting collection install process 2026-09-12 03:58:49.655943 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-09-12 03:58:49.655977 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon 2026-09-12 03:58:49.656011 | instance | ansible.netcommon:* was installed successfully 2026-09-12 03:58:49.656044 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-09-12 03:58:49.656078 | instance | Creating virtual environment at: .venv 2026-09-12 03:58:49.656112 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-12 03:58:49.656145 | instance | Downloading kubernetes (1.9MiB) 2026-09-12 03:58:49.656178 | instance | Downloading setuptools (1.1MiB) 2026-09-12 03:58:49.656218 | instance | Downloading cryptography (4.2MiB) 2026-09-12 03:58:49.656254 | instance | Downloading ansible-core (2.1MiB) 2026-09-12 03:58:49.656288 | instance | Downloading rjsonnet (1.2MiB) 2026-09-12 03:58:49.656322 | instance | Downloading pygments (1.2MiB) 2026-09-12 03:58:49.656354 | instance | Downloading netaddr (2.2MiB) 2026-09-12 03:58:49.656379 | instance | Downloading pydantic-core (2.0MiB) 2026-09-12 03:58:49.656400 | instance | Downloading openstacksdk (1.7MiB) 2026-09-12 03:58:49.656422 | instance | Building pyperclip==1.9.0 2026-09-12 03:58:49.656445 | instance | Downloading rjsonnet 2026-09-12 03:58:49.656468 | instance | Downloading pydantic-core 2026-09-12 03:58:49.656490 | instance | Downloading netaddr 2026-09-12 03:58:49.656536 | instance | Downloading pygments 2026-09-12 03:58:49.656559 | instance | Downloading cryptography 2026-09-12 03:58:49.656580 | instance | Downloading setuptools 2026-09-12 03:58:49.656601 | instance | Downloading kubernetes 2026-09-12 03:58:49.656622 | instance | Downloading ansible-core 2026-09-12 03:58:49.656644 | instance | Downloading openstacksdk 2026-09-12 03:58:49.656665 | instance | Built pyperclip==1.9.0 2026-09-12 03:58:49.656687 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-12 03:58:49.656709 | instance | Installed 83 packages in 56ms 2026-09-12 03:58:49.656730 | instance | Checked out ansible.posix to 1.6.0 2026-09-12 03:58:49.656751 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-09-12 03:58:49.656772 | instance | Starting galaxy collection install process 2026-09-12 03:58:49.656793 | instance | Process install dependency map 2026-09-12 03:58:49.656838 | instance | Starting collection install process 2026-09-12 03:58:49.656863 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-09-12 03:58:49.656885 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix 2026-09-12 03:58:49.656907 | instance | ansible.posix:1.6.0 was installed successfully 2026-09-12 03:58:49.656929 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:58:49.656951 | instance | 2.17.12 2026-09-12 03:58:49.656972 | instance | Checked out ansible.utils to v6.1.0 2026-09-12 03:58:49.656994 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-09-12 03:58:49.657016 | instance | Starting galaxy collection install process 2026-09-12 03:58:49.657038 | instance | Process install dependency map 2026-09-12 03:58:49.657060 | instance | Starting collection install process 2026-09-12 03:58:49.657082 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-09-12 03:58:49.657104 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils 2026-09-12 03:58:49.657126 | instance | ansible.utils:6.1.0 was installed successfully 2026-09-12 03:58:49.657148 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:58:49.657170 | instance | 2.17.12 2026-09-12 03:58:49.657191 | instance | Checked out atmosphere.common to v0.7.0 2026-09-12 03:58:49.657213 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-09-12 03:58:49.657235 | instance | Starting galaxy collection install process 2026-09-12 03:58:49.657256 | instance | Process install dependency map 2026-09-12 03:58:49.657277 | instance | Starting collection install process 2026-09-12 03:58:49.657298 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common' 2026-09-12 03:58:49.657319 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common 2026-09-12 03:58:49.657340 | instance | atmosphere.common:0.7.0 was installed successfully 2026-09-12 03:58:49.657361 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:58:49.657382 | instance | 2.17.12 2026-09-12 03:58:49.657403 | instance | Checked out community.crypto to 2.2.3 2026-09-12 03:58:49.657425 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto 2026-09-12 03:58:49.657447 | instance | Starting galaxy collection install process 2026-09-12 03:58:49.657468 | instance | Process install dependency map 2026-09-12 03:58:49.657489 | instance | Starting collection install process 2026-09-12 03:58:49.657511 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-09-12 03:58:49.657531 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto 2026-09-12 03:58:49.657553 | instance | community.crypto:2.2.3 was installed successfully 2026-09-12 03:58:49.657574 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:58:49.657596 | instance | 2.17.12 2026-09-12 03:58:49.657617 | instance | Checked out community.general to 7.3.0 2026-09-12 03:58:49.657637 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general 2026-09-12 03:58:49.657665 | instance | Starting galaxy collection install process 2026-09-12 03:58:49.657687 | instance | Process install dependency map 2026-09-12 03:58:49.657709 | instance | Starting collection install process 2026-09-12 03:58:49.657730 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-09-12 03:58:49.657751 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general 2026-09-12 03:58:49.657772 | instance | community.general:7.3.0 was installed successfully 2026-09-12 03:58:49.657794 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:58:49.657828 | instance | 2.17.12 2026-09-12 03:58:49.657851 | instance | Checked out community.mysql to 3.6.0 2026-09-12 03:58:49.657876 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql 2026-09-12 03:58:49.657898 | instance | Starting galaxy collection install process 2026-09-12 03:58:49.657920 | instance | Process install dependency map 2026-09-12 03:58:49.657942 | instance | Starting collection install process 2026-09-12 03:58:49.657964 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-09-12 03:58:49.657986 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql 2026-09-12 03:58:49.658007 | instance | community.mysql:3.6.0 was installed successfully 2026-09-12 03:58:49.658028 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:58:49.658049 | instance | 2.17.12 2026-09-12 03:58:49.658070 | instance | Checked out kubernetes.core to 2.4.0 2026-09-12 03:58:49.658092 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-09-12 03:58:49.658114 | instance | Starting galaxy collection install process 2026-09-12 03:58:49.658135 | instance | Process install dependency map 2026-09-12 03:58:49.658156 | instance | Starting collection install process 2026-09-12 03:58:49.658177 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-09-12 03:58:49.658199 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core 2026-09-12 03:58:49.658220 | instance | kubernetes.core:2.4.0 was installed successfully 2026-09-12 03:58:49.658241 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:58:49.658263 | instance | 2.17.12 2026-09-12 03:58:49.658284 | instance | Checked out openstack.cloud to 2.6.0 2026-09-12 03:58:49.658305 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-09-12 03:58:49.658327 | instance | Starting galaxy collection install process 2026-09-12 03:58:49.658349 | instance | Process install dependency map 2026-09-12 03:58:49.658371 | instance | Starting collection install process 2026-09-12 03:58:49.658392 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-09-12 03:58:49.658413 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud 2026-09-12 03:58:49.658435 | instance | openstack.cloud:2.6.0 was installed successfully 2026-09-12 03:58:49.658455 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:58:49.658476 | instance | 2.17.12 2026-09-12 03:58:49.658504 | instance | Checked out vexxhost.ceph to v4.2.0 2026-09-12 03:58:49.658526 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-09-12 03:58:49.658548 | instance | Starting galaxy collection install process 2026-09-12 03:58:49.658569 | instance | Process install dependency map 2026-09-12 03:58:49.658589 | instance | Starting collection install process 2026-09-12 03:58:49.658610 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph' 2026-09-12 03:58:49.658631 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph 2026-09-12 03:58:49.658652 | instance | vexxhost.ceph:4.2.0 was installed successfully 2026-09-12 03:58:49.658673 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:58:49.658694 | instance | 2.17.12 2026-09-12 03:58:49.658715 | instance | Checked out vexxhost.containers to v1.6.8 2026-09-12 03:58:49.658736 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-09-12 03:58:49.658757 | instance | Starting galaxy collection install process 2026-09-12 03:58:49.658778 | instance | Process install dependency map 2026-09-12 03:58:49.658799 | instance | Starting collection install process 2026-09-12 03:58:49.658836 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers' 2026-09-12 03:58:49.658858 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers 2026-09-12 03:58:49.658879 | instance | vexxhost.containers:1.6.8 was installed successfully 2026-09-12 03:58:49.658900 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:58:49.658922 | instance | 2.17.12 2026-09-12 03:58:49.658943 | instance | Checked out vexxhost.kubernetes to v3.4.0 2026-09-12 03:58:49.658965 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-09-12 03:58:49.658986 | instance | Starting galaxy collection install process 2026-09-12 03:58:49.659007 | instance | Process install dependency map 2026-09-12 03:58:49.659028 | instance | Starting collection install process 2026-09-12 03:58:49.659049 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes' 2026-09-12 03:58:49.659070 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes 2026-09-12 03:58:49.659092 | instance | vexxhost.kubernetes:3.4.0 was installed successfully 2026-09-12 03:58:49.659113 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:58:49.659134 | instance | 2.17.12 2026-09-12 03:58:49.666632 | 2026-09-12 03:58:49.666693 | PLAY RECAP 2026-09-12 03:58:49.666750 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-12 03:58:49.666776 | 2026-09-12 03:58:49.809431 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-12 03:58:49.811942 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-12 03:58:50.339924 | 2026-09-12 03:58:50.340046 | PLAY [all] 2026-09-12 03:58:50.350195 | 2026-09-12 03:58:50.350267 | TASK [Install "jq" for log collection] 2026-09-12 03:58:59.342535 | instance | changed 2026-09-12 03:58:59.345977 | 2026-09-12 03:58:59.346096 | PLAY RECAP 2026-09-12 03:58:59.346300 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-12 03:58:59.346427 | 2026-09-12 03:58:59.486956 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-12 03:58:59.490444 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-12 03:59:00.051666 | 2026-09-12 03:59:00.051799 | PLAY [all] 2026-09-12 03:59:00.062281 | 2026-09-12 03:59:00.062397 | LOOP [Check inventory directories] 2026-09-12 03:59:00.415606 | instance | ok: "src/github.com/vexxhost/atmosphere" 2026-09-12 03:59:00.583657 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory" 2026-09-12 03:59:00.739887 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory" 2026-09-12 03:59:00.759802 | 2026-09-12 03:59:00.760148 | LOOP [Copy inventory file for Zuul] 2026-09-12 03:59:01.606381 | instance | changed: 2026-09-12 03:59:01.606609 | instance | { 2026-09-12 03:59:01.606642 | instance | "ansible_loop_var": "item", 2026-09-12 03:59:01.606668 | instance | "changed": false, 2026-09-12 03:59:01.606691 | instance | "failed": false, 2026-09-12 03:59:01.606716 | instance | "invocation": { 2026-09-12 03:59:01.606738 | instance | "module_args": { 2026-09-12 03:59:01.606761 | instance | "checksum_algorithm": "sha1", 2026-09-12 03:59:01.606781 | instance | "follow": true, 2026-09-12 03:59:01.606800 | instance | "get_attributes": true, 2026-09-12 03:59:01.606836 | instance | "get_checksum": true, 2026-09-12 03:59:01.606861 | instance | "get_mime": true, 2026-09-12 03:59:01.606882 | instance | "path": "src/github.com/vexxhost/atmosphere" 2026-09-12 03:59:01.606901 | instance | } 2026-09-12 03:59:01.606920 | instance | }, 2026-09-12 03:59:01.606939 | instance | "item": "src/github.com/vexxhost/atmosphere", 2026-09-12 03:59:01.606957 | instance | "stat": { 2026-09-12 03:59:01.606977 | instance | "atime": 1789185522.4743278, 2026-09-12 03:59:01.606999 | instance | "attr_flags": "e", 2026-09-12 03:59:01.607020 | instance | "attributes": [ 2026-09-12 03:59:01.607042 | instance | "extents" 2026-09-12 03:59:01.607061 | instance | ], 2026-09-12 03:59:01.607081 | instance | "block_size": 4096, 2026-09-12 03:59:01.607103 | instance | "blocks": 8, 2026-09-12 03:59:01.607124 | instance | "charset": "unknown", 2026-09-12 03:59:01.607145 | instance | "ctime": 1789185520.8782587, 2026-09-12 03:59:01.607165 | instance | "dev": 64513, 2026-09-12 03:59:01.607187 | instance | "device_type": 0, 2026-09-12 03:59:01.607207 | instance | "executable": true, 2026-09-12 03:59:01.607226 | instance | "exists": true, 2026-09-12 03:59:01.607246 | instance | "gid": 1000, 2026-09-12 03:59:01.607268 | instance | "gr_name": "zuul", 2026-09-12 03:59:01.607289 | instance | "inode": 1048806, 2026-09-12 03:59:01.607309 | instance | "isblk": false, 2026-09-12 03:59:01.607331 | instance | "ischr": false, 2026-09-12 03:59:01.607350 | instance | "isdir": true, 2026-09-12 03:59:01.607370 | instance | "isfifo": false, 2026-09-12 03:59:01.607391 | instance | "isgid": false, 2026-09-12 03:59:01.607412 | instance | "islnk": false, 2026-09-12 03:59:01.607434 | instance | "isreg": false, 2026-09-12 03:59:01.607455 | instance | "issock": false, 2026-09-12 03:59:01.607473 | instance | "isuid": false, 2026-09-12 03:59:01.607492 | instance | "mimetype": "unknown", 2026-09-12 03:59:01.607510 | instance | "mode": "0755", 2026-09-12 03:59:01.607528 | instance | "mtime": 1789185520.8782587, 2026-09-12 03:59:01.607546 | instance | "nlink": 22, 2026-09-12 03:59:01.607567 | instance | "path": "src/github.com/vexxhost/atmosphere", 2026-09-12 03:59:01.607587 | instance | "pw_name": "zuul", 2026-09-12 03:59:01.607609 | instance | "readable": true, 2026-09-12 03:59:01.607631 | instance | "rgrp": true, 2026-09-12 03:59:01.607652 | instance | "roth": true, 2026-09-12 03:59:01.607682 | instance | "rusr": true, 2026-09-12 03:59:01.607702 | instance | "size": 4096, 2026-09-12 03:59:01.607723 | instance | "uid": 1000, 2026-09-12 03:59:01.607743 | instance | "version": "2587121813", 2026-09-12 03:59:01.607762 | instance | "wgrp": false, 2026-09-12 03:59:01.607786 | instance | "woth": false, 2026-09-12 03:59:01.607807 | instance | "writeable": true, 2026-09-12 03:59:01.607848 | instance | "wusr": true, 2026-09-12 03:59:01.607870 | instance | "xgrp": true, 2026-09-12 03:59:01.607889 | instance | "xoth": true, 2026-09-12 03:59:01.607908 | instance | "xusr": true 2026-09-12 03:59:01.608116 | instance | } 2026-09-12 03:59:01.608153 | instance | } 2026-09-12 03:59:01.608200 | instance | ok: All items complete 2026-09-12 03:59:01.608227 | 2026-09-12 03:59:01.611943 | instance | skipping: Conditional result was False 2026-09-12 03:59:01.613300 | instance | skipping: Conditional result was False 2026-09-12 03:59:01.639286 | 2026-09-12 03:59:01.639517 | LOOP [Switch "ansible_host" to private IP] 2026-09-12 03:59:02.058286 | instance | changed: 1 replacements made 2026-09-12 03:59:02.065257 | instance | skipping: Conditional result was False 2026-09-12 03:59:02.072650 | instance | skipping: Conditional result was False 2026-09-12 03:59:02.090758 | 2026-09-12 03:59:02.090915 | TASK [Run Molecule scenario] 2026-09-12 03:59:02.976051 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-09-12 03:59:03.356110 | instance | INFO [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-12 03:59:03.356202 | instance | INFO [csi > prerun] Performing prerun with role_name_check=0... 2026-09-12 03:59:15.366628 | instance | INFO [csi > dependency] Executing 2026-09-12 03:59:15.366882 | instance | WARNING [csi > dependency] Missing roles requirements file: requirements.yml 2026-09-12 03:59:15.367196 | instance | WARNING [csi > dependency] Missing collections requirements file: collections.yml 2026-09-12 03:59:15.367364 | instance | WARNING [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-09-12 03:59:15.378535 | instance | INFO [csi > cleanup] Executing 2026-09-12 03:59:15.379023 | instance | WARNING [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-12 03:59:15.390760 | instance | INFO [csi > destroy] Executing 2026-09-12 03:59:15.390831 | instance | WARNING [csi > destroy] Skipping, '--destroy=never' requested. 2026-09-12 03:59:15.390980 | instance | INFO [csi > destroy] Executed: Successful 2026-09-12 03:59:15.401947 | instance | INFO [csi > syntax] Executing 2026-09-12 03:59:17.033076 | instance | 2026-09-12 03:59:17.033149 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-12 03:59:17.105256 | instance | INFO [csi > syntax] Executed: Successful 2026-09-12 03:59:17.122726 | instance | INFO [csi > create] Executing 2026-09-12 03:59:17.126998 | instance | WARNING [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-12 03:59:17.179963 | instance | INFO [csi > prepare] Executing 2026-09-12 03:59:17.997112 | instance | 2026-09-12 03:59:17.997224 | instance | PLAY [Prepare] ***************************************************************** 2026-09-12 03:59:17.997266 | instance | 2026-09-12 03:59:17.997432 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-12 03:59:17.997585 | instance | Saturday 12 September 2026 03:59:17 +0000 (0:00:00.025) 0:00:00.025 **** 2026-09-12 03:59:19.264018 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-12 03:59:19.264200 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-12 03:59:19.264306 | instance | interpreter could change the meaning of that path. See 2026-09-12 03:59:19.264485 | instance | https://docs.ansible.com/ansible- 2026-09-12 03:59:19.264645 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-12 03:59:19.271061 | instance | ok: [instance] 2026-09-12 03:59:19.271176 | instance | 2026-09-12 03:59:19.271331 | instance | TASK [Configure short hostname] ************************************************ 2026-09-12 03:59:19.271482 | instance | Saturday 12 September 2026 03:59:19 +0000 (0:00:01.274) 0:00:01.300 **** 2026-09-12 03:59:20.053890 | instance | changed: [instance] 2026-09-12 03:59:20.054203 | instance | 2026-09-12 03:59:20.054756 | instance | TASK [Ensure hostname inside hosts file] *************************************** 2026-09-12 03:59:20.055419 | instance | Saturday 12 September 2026 03:59:20 +0000 (0:00:00.782) 0:00:02.082 **** 2026-09-12 03:59:20.351370 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created 2026-09-12 03:59:20.351469 | instance | with a mode of 0700, this may cause issues when running as another user. To 2026-09-12 03:59:20.351680 | instance | avoid this, create the remote_tmp dir with the correct permissions manually 2026-09-12 03:59:20.357820 | instance | changed: [instance] 2026-09-12 03:59:20.357947 | instance | 2026-09-12 03:59:20.358105 | instance | TASK [Purge "snapd" package] *************************************************** 2026-09-12 03:59:20.358257 | instance | Saturday 12 September 2026 03:59:20 +0000 (0:00:00.304) 0:00:02.387 **** 2026-09-12 03:59:21.288364 | instance | ok: [instance] 2026-09-12 03:59:21.288450 | instance | 2026-09-12 03:59:21.288635 | instance | PLAY [Create devices for Ceph] ************************************************* 2026-09-12 03:59:21.288799 | instance | 2026-09-12 03:59:21.288962 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-12 03:59:21.289130 | instance | Saturday 12 September 2026 03:59:21 +0000 (0:00:00.930) 0:00:03.317 **** 2026-09-12 03:59:21.956440 | instance | ok: [instance] 2026-09-12 03:59:21.956550 | instance | 2026-09-12 03:59:21.956718 | instance | TASK [Install depedencies] ***************************************************** 2026-09-12 03:59:21.956952 | instance | Saturday 12 September 2026 03:59:21 +0000 (0:00:00.666) 0:00:03.983 **** 2026-09-12 03:59:46.857922 | instance | changed: [instance] 2026-09-12 03:59:46.858134 | instance | 2026-09-12 03:59:46.858387 | instance | TASK [Start up service] ******************************************************** 2026-09-12 03:59:46.858657 | instance | Saturday 12 September 2026 03:59:46 +0000 (0:00:24.902) 0:00:28.886 **** 2026-09-12 03:59:47.428251 | instance | ok: [instance] 2026-09-12 03:59:47.428539 | instance | 2026-09-12 03:59:47.428795 | instance | TASK [Generate lvm.conf] ******************************************************* 2026-09-12 03:59:47.429035 | instance | Saturday 12 September 2026 03:59:47 +0000 (0:00:00.570) 0:00:29.456 **** 2026-09-12 03:59:47.740645 | instance | ok: [instance] 2026-09-12 03:59:47.740884 | instance | 2026-09-12 03:59:47.741218 | instance | TASK [Write /etc/lvm/lvm.conf] ************************************************* 2026-09-12 03:59:47.741480 | instance | Saturday 12 September 2026 03:59:47 +0000 (0:00:00.312) 0:00:29.769 **** 2026-09-12 03:59:48.441049 | instance | changed: [instance] 2026-09-12 03:59:48.441245 | instance | 2026-09-12 03:59:48.441405 | instance | TASK [Get list of all loopback devices] **************************************** 2026-09-12 03:59:48.441605 | instance | Saturday 12 September 2026 03:59:48 +0000 (0:00:00.699) 0:00:30.469 **** 2026-09-12 03:59:48.647557 | instance | ok: [instance] 2026-09-12 03:59:48.647894 | instance | 2026-09-12 03:59:48.648308 | instance | TASK [Fail if there is any existing loopback devices] ************************** 2026-09-12 03:59:48.648784 | instance | Saturday 12 September 2026 03:59:48 +0000 (0:00:00.204) 0:00:30.674 **** 2026-09-12 03:59:48.671225 | instance | skipping: [instance] 2026-09-12 03:59:48.671259 | instance | 2026-09-12 03:59:48.671270 | instance | TASK [Create devices for Ceph] ************************************************* 2026-09-12 03:59:48.671279 | instance | Saturday 12 September 2026 03:59:48 +0000 (0:00:00.025) 0:00:30.699 **** 2026-09-12 03:59:49.310973 | instance | changed: [instance] => (item=osd0) 2026-09-12 03:59:49.311026 | instance | changed: [instance] => (item=osd1) 2026-09-12 03:59:49.311037 | instance | changed: [instance] => (item=osd2) 2026-09-12 03:59:49.311046 | instance | 2026-09-12 03:59:49.311056 | instance | TASK [Set permissions on loopback devices] ************************************* 2026-09-12 03:59:49.311065 | instance | Saturday 12 September 2026 03:59:49 +0000 (0:00:00.639) 0:00:31.338 **** 2026-09-12 03:59:49.982780 | instance | changed: [instance] => (item=osd0) 2026-09-12 03:59:49.983281 | instance | changed: [instance] => (item=osd1) 2026-09-12 03:59:49.983657 | instance | changed: [instance] => (item=osd2) 2026-09-12 03:59:49.984029 | instance | 2026-09-12 03:59:49.984351 | instance | TASK [Start loop devices] ****************************************************** 2026-09-12 03:59:49.984689 | instance | Saturday 12 September 2026 03:59:49 +0000 (0:00:00.672) 0:00:32.011 **** 2026-09-12 03:59:50.701515 | instance | changed: [instance] => (item=osd0) 2026-09-12 03:59:50.701610 | instance | changed: [instance] => (item=osd1) 2026-09-12 03:59:50.701720 | instance | changed: [instance] => (item=osd2) 2026-09-12 03:59:50.701840 | instance | 2026-09-12 03:59:50.701944 | instance | TASK [Create a volume group for each loop device] ****************************** 2026-09-12 03:59:50.702052 | instance | Saturday 12 September 2026 03:59:50 +0000 (0:00:00.719) 0:00:32.730 **** 2026-09-12 03:59:54.015336 | instance | changed: [instance] => (item=osd0) 2026-09-12 03:59:54.015421 | instance | changed: [instance] => (item=osd1) 2026-09-12 03:59:54.015435 | instance | changed: [instance] => (item=osd2) 2026-09-12 03:59:54.015448 | instance | 2026-09-12 03:59:54.015461 | instance | TASK [Create a logical volume for each loop device] **************************** 2026-09-12 03:59:54.015474 | instance | Saturday 12 September 2026 03:59:54 +0000 (0:00:03.311) 0:00:36.042 **** 2026-09-12 03:59:56.025305 | instance | changed: [instance] => (item=ceph-instance-osd0) 2026-09-12 03:59:56.026141 | instance | changed: [instance] => (item=ceph-instance-osd1) 2026-09-12 03:59:56.026185 | instance | changed: [instance] => (item=ceph-instance-osd2) 2026-09-12 03:59:56.026195 | instance | 2026-09-12 03:59:56.026225 | instance | PLAY RECAP ********************************************************************* 2026-09-12 03:59:56.026653 | instance | instance : ok=15 changed=9 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-09-12 03:59:56.027034 | instance | 2026-09-12 03:59:56.027344 | instance | Saturday 12 September 2026 03:59:56 +0000 (0:00:02.012) 0:00:38.054 **** 2026-09-12 03:59:56.027646 | instance | =============================================================================== 2026-09-12 03:59:56.027932 | instance | Install depedencies ---------------------------------------------------- 24.90s 2026-09-12 03:59:56.028215 | instance | Create a volume group for each loop device ------------------------------ 3.31s 2026-09-12 03:59:56.028562 | instance | Create a logical volume for each loop device ---------------------------- 2.01s 2026-09-12 03:59:56.028941 | instance | Gathering Facts --------------------------------------------------------- 1.27s 2026-09-12 03:59:56.029319 | instance | Purge "snapd" package --------------------------------------------------- 0.93s 2026-09-12 03:59:56.029673 | instance | Configure short hostname ------------------------------------------------ 0.78s 2026-09-12 03:59:56.029974 | instance | Start loop devices ------------------------------------------------------ 0.72s 2026-09-12 03:59:56.030261 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.70s 2026-09-12 03:59:56.030664 | instance | Set permissions on loopback devices ------------------------------------- 0.67s 2026-09-12 03:59:56.030958 | instance | Gathering Facts --------------------------------------------------------- 0.67s 2026-09-12 03:59:56.031239 | instance | Create devices for Ceph ------------------------------------------------- 0.64s 2026-09-12 03:59:56.031516 | instance | Start up service -------------------------------------------------------- 0.57s 2026-09-12 03:59:56.031823 | instance | Generate lvm.conf ------------------------------------------------------- 0.31s 2026-09-12 03:59:56.032106 | instance | Ensure hostname inside hosts file --------------------------------------- 0.30s 2026-09-12 03:59:56.032388 | instance | Get list of all loopback devices ---------------------------------------- 0.20s 2026-09-12 03:59:56.032819 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s 2026-09-12 03:59:56.118706 | instance | INFO [csi > prepare] Executed: Successful 2026-09-12 03:59:56.134230 | instance | INFO [csi > converge] Executing 2026-09-12 03:59:57.508204 | instance | 2026-09-12 03:59:57.508651 | instance | PLAY [Debug CSI driver value] ************************************************** 2026-09-12 03:59:57.508961 | instance | 2026-09-12 03:59:57.509266 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-12 03:59:57.509569 | instance | Saturday 12 September 2026 03:59:57 +0000 (0:00:00.007) 0:00:00.007 **** 2026-09-12 03:59:58.551308 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-12 03:59:58.551704 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-12 03:59:58.552013 | instance | interpreter could change the meaning of that path. See 2026-09-12 03:59:58.552358 | instance | https://docs.ansible.com/ansible- 2026-09-12 03:59:58.552732 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-12 03:59:58.569449 | instance | ok: [instance] 2026-09-12 03:59:58.569679 | instance | 2026-09-12 03:59:58.570116 | instance | TASK [Display CSI driver value and environment variable] *********************** 2026-09-12 03:59:58.570444 | instance | Saturday 12 September 2026 03:59:58 +0000 (0:00:01.059) 0:00:01.066 **** 2026-09-12 03:59:58.617394 | instance | ok: [instance] => { 2026-09-12 03:59:58.617818 | instance | "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER=" 2026-09-12 03:59:58.618115 | instance | } 2026-09-12 03:59:58.618389 | instance | 2026-09-12 03:59:58.618698 | instance | PLAY [all] ********************************************************************* 2026-09-12 03:59:58.618976 | instance | 2026-09-12 03:59:58.619264 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-12 03:59:58.619588 | instance | Saturday 12 September 2026 03:59:58 +0000 (0:00:00.049) 0:00:01.115 **** 2026-09-12 03:59:59.621565 | instance | ok: [instance] 2026-09-12 03:59:59.621987 | instance | 2026-09-12 03:59:59.622321 | instance | TASK [Fail if atmosphere_ceph_enabled is set] ********************************** 2026-09-12 03:59:59.622685 | instance | Saturday 12 September 2026 03:59:59 +0000 (0:00:01.003) 0:00:02.118 **** 2026-09-12 03:59:59.677007 | instance | skipping: [instance] 2026-09-12 03:59:59.677376 | instance | 2026-09-12 03:59:59.677800 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] ***************** 2026-09-12 03:59:59.678166 | instance | Saturday 12 September 2026 03:59:59 +0000 (0:00:00.056) 0:00:02.175 **** 2026-09-12 03:59:59.884520 | instance | ok: [instance] 2026-09-12 03:59:59.884743 | instance | 2026-09-12 03:59:59.884886 | instance | PLAY [Deploy Ceph monitors & managers] ***************************************** 2026-09-12 03:59:59.885027 | instance | 2026-09-12 03:59:59.885163 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-12 03:59:59.885309 | instance | Saturday 12 September 2026 03:59:59 +0000 (0:00:00.204) 0:00:02.380 **** 2026-09-12 04:00:00.893486 | instance | ok: [instance] 2026-09-12 04:00:00.893615 | instance | 2026-09-12 04:00:00.893977 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-12 04:00:00.896215 | instance | Saturday 12 September 2026 04:00:00 +0000 (0:00:01.011) 0:00:03.392 **** 2026-09-12 04:00:01.239349 | instance | ok: [instance] 2026-09-12 04:00:01.240135 | instance | 2026-09-12 04:00:01.240167 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-12 04:00:01.240179 | instance | Saturday 12 September 2026 04:00:01 +0000 (0:00:00.344) 0:00:03.736 **** 2026-09-12 04:00:01.290866 | instance | skipping: [instance] 2026-09-12 04:00:01.290946 | instance | 2026-09-12 04:00:01.291019 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-09-12 04:00:01.291115 | instance | Saturday 12 September 2026 04:00:01 +0000 (0:00:00.053) 0:00:03.789 **** 2026-09-12 04:00:01.731841 | instance | changed: [instance] 2026-09-12 04:00:01.731906 | instance | 2026-09-12 04:00:01.731915 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-12 04:00:01.731921 | instance | Saturday 12 September 2026 04:00:01 +0000 (0:00:00.440) 0:00:04.229 **** 2026-09-12 04:00:01.827050 | instance | ok: [instance] => { 2026-09-12 04:00:01.827120 | instance | "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64" 2026-09-12 04:00:01.827129 | instance | } 2026-09-12 04:00:01.827135 | instance | 2026-09-12 04:00:01.827141 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-12 04:00:01.827148 | instance | Saturday 12 September 2026 04:00:01 +0000 (0:00:00.094) 0:00:04.324 **** 2026-09-12 04:00:02.498197 | instance | changed: [instance] 2026-09-12 04:00:02.498299 | instance | 2026-09-12 04:00:02.498611 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-12 04:00:02.498670 | instance | Saturday 12 September 2026 04:00:02 +0000 (0:00:00.671) 0:00:04.996 **** 2026-09-12 04:00:02.564437 | instance | skipping: [instance] 2026-09-12 04:00:02.564592 | instance | 2026-09-12 04:00:02.564897 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-12 04:00:02.564969 | instance | Saturday 12 September 2026 04:00:02 +0000 (0:00:00.066) 0:00:05.062 **** 2026-09-12 04:00:02.632096 | instance | skipping: [instance] 2026-09-12 04:00:02.632211 | instance | 2026-09-12 04:00:02.632287 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-12 04:00:02.632616 | instance | Saturday 12 September 2026 04:00:02 +0000 (0:00:00.067) 0:00:05.130 **** 2026-09-12 04:00:02.851094 | instance | ok: [instance] 2026-09-12 04:00:02.851211 | instance | 2026-09-12 04:00:02.851578 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-12 04:00:02.851623 | instance | Saturday 12 September 2026 04:00:02 +0000 (0:00:00.219) 0:00:05.349 **** 2026-09-12 04:00:04.170806 | instance | ok: [instance] 2026-09-12 04:00:04.171520 | instance | 2026-09-12 04:00:04.171572 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-12 04:00:04.171601 | instance | Saturday 12 September 2026 04:00:04 +0000 (0:00:01.319) 0:00:06.669 **** 2026-09-12 04:00:04.243431 | instance | ok: [instance] => { 2026-09-12 04:00:04.244730 | instance | "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz" 2026-09-12 04:00:04.244799 | instance | } 2026-09-12 04:00:04.244811 | instance | 2026-09-12 04:00:04.244821 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-12 04:00:04.244959 | instance | Saturday 12 September 2026 04:00:04 +0000 (0:00:00.072) 0:00:06.741 **** 2026-09-12 04:00:04.940595 | instance | changed: [instance] 2026-09-12 04:00:04.941277 | instance | 2026-09-12 04:00:04.941327 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-12 04:00:04.941340 | instance | Saturday 12 September 2026 04:00:04 +0000 (0:00:00.697) 0:00:07.439 **** 2026-09-12 04:00:07.885665 | instance | changed: [instance] 2026-09-12 04:00:07.886437 | instance | 2026-09-12 04:00:07.886622 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] *************** 2026-09-12 04:00:07.886635 | instance | Saturday 12 September 2026 04:00:07 +0000 (0:00:02.945) 0:00:10.384 **** 2026-09-12 04:00:07.933305 | instance | skipping: [instance] 2026-09-12 04:00:07.934476 | instance | 2026-09-12 04:00:07.934528 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] *** 2026-09-12 04:00:07.934540 | instance | Saturday 12 September 2026 04:00:07 +0000 (0:00:00.046) 0:00:10.431 **** 2026-09-12 04:00:07.977776 | instance | skipping: [instance] 2026-09-12 04:00:07.977901 | instance | 2026-09-12 04:00:07.978170 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ******** 2026-09-12 04:00:07.978370 | instance | Saturday 12 September 2026 04:00:07 +0000 (0:00:00.044) 0:00:10.475 **** 2026-09-12 04:00:08.020412 | instance | skipping: [instance] 2026-09-12 04:00:08.020524 | instance | 2026-09-12 04:00:08.020736 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] ************** 2026-09-12 04:00:08.021000 | instance | Saturday 12 September 2026 04:00:08 +0000 (0:00:00.042) 0:00:10.518 **** 2026-09-12 04:00:14.187890 | instance | changed: [instance] 2026-09-12 04:00:14.188109 | instance | 2026-09-12 04:00:14.188634 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] *** 2026-09-12 04:00:14.188687 | instance | Saturday 12 September 2026 04:00:14 +0000 (0:00:06.167) 0:00:16.685 **** 2026-09-12 04:00:14.886783 | instance | changed: [instance] 2026-09-12 04:00:14.887551 | instance | 2026-09-12 04:00:14.887638 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] ******* 2026-09-12 04:00:14.887650 | instance | Saturday 12 September 2026 04:00:14 +0000 (0:00:00.698) 0:00:17.384 **** 2026-09-12 04:00:15.895407 | instance | changed: [instance] => (item={'path': '/etc/containerd'}) 2026-09-12 04:00:15.895496 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'}) 2026-09-12 04:00:15.896591 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'}) 2026-09-12 04:00:15.896659 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'}) 2026-09-12 04:00:15.896673 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'}) 2026-09-12 04:00:15.896683 | instance | 2026-09-12 04:00:15.896692 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] ********** 2026-09-12 04:00:15.896701 | instance | Saturday 12 September 2026 04:00:15 +0000 (0:00:01.009) 0:00:18.394 **** 2026-09-12 04:00:16.540640 | instance | changed: [instance] 2026-09-12 04:00:16.540708 | instance | 2026-09-12 04:00:16.540720 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ******** 2026-09-12 04:00:16.540761 | instance | Saturday 12 September 2026 04:00:16 +0000 (0:00:00.616) 0:00:19.010 **** 2026-09-12 04:00:16.540772 | instance | 2026-09-12 04:00:16.540791 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-12 04:00:16.540872 | instance | Saturday 12 September 2026 04:00:16 +0000 (0:00:00.027) 0:00:19.038 **** 2026-09-12 04:00:17.495389 | instance | ok: [instance] 2026-09-12 04:00:17.495505 | instance | 2026-09-12 04:00:17.495517 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] ********** 2026-09-12 04:00:17.495663 | instance | Saturday 12 September 2026 04:00:17 +0000 (0:00:00.955) 0:00:19.994 **** 2026-09-12 04:00:17.962049 | instance | changed: [instance] 2026-09-12 04:00:17.962189 | instance | 2026-09-12 04:00:17.962558 | instance | TASK [vexxhost.containers.containerd : Enable and start service] *************** 2026-09-12 04:00:17.962605 | instance | Saturday 12 September 2026 04:00:17 +0000 (0:00:00.466) 0:00:20.460 **** 2026-09-12 04:00:18.545920 | instance | changed: [instance] 2026-09-12 04:00:18.546805 | instance | 2026-09-12 04:00:18.546881 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-12 04:00:18.546893 | instance | Saturday 12 September 2026 04:00:18 +0000 (0:00:00.583) 0:00:21.043 **** 2026-09-12 04:00:18.783824 | instance | ok: [instance] 2026-09-12 04:00:18.784542 | instance | 2026-09-12 04:00:18.784600 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-12 04:00:18.784614 | instance | Saturday 12 September 2026 04:00:18 +0000 (0:00:00.238) 0:00:21.282 **** 2026-09-12 04:00:18.837384 | instance | ok: [instance] => { 2026-09-12 04:00:18.837426 | instance | "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz" 2026-09-12 04:00:18.837432 | instance | } 2026-09-12 04:00:18.837437 | instance | 2026-09-12 04:00:18.837442 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-12 04:00:18.837447 | instance | Saturday 12 September 2026 04:00:18 +0000 (0:00:00.053) 0:00:21.335 **** 2026-09-12 04:00:19.796084 | instance | changed: [instance] 2026-09-12 04:00:19.796551 | instance | 2026-09-12 04:00:19.796616 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-12 04:00:19.796847 | instance | Saturday 12 September 2026 04:00:19 +0000 (0:00:00.959) 0:00:22.294 **** 2026-09-12 04:00:25.206122 | instance | changed: [instance] 2026-09-12 04:00:25.206198 | instance | 2026-09-12 04:00:25.206449 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ****************** 2026-09-12 04:00:25.206495 | instance | Saturday 12 September 2026 04:00:25 +0000 (0:00:05.410) 0:00:27.705 **** 2026-09-12 04:00:26.334084 | instance | ok: [instance] 2026-09-12 04:00:26.334173 | instance | 2026-09-12 04:00:26.334581 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] *************** 2026-09-12 04:00:26.334633 | instance | Saturday 12 September 2026 04:00:26 +0000 (0:00:01.127) 0:00:28.832 **** 2026-09-12 04:00:26.713968 | instance | changed: [instance] 2026-09-12 04:00:26.714733 | instance | 2026-09-12 04:00:26.714815 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] ***** 2026-09-12 04:00:26.714828 | instance | Saturday 12 September 2026 04:00:26 +0000 (0:00:00.379) 0:00:29.211 **** 2026-09-12 04:00:27.233458 | instance | changed: [instance] 2026-09-12 04:00:27.233922 | instance | 2026-09-12 04:00:27.233966 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] *********** 2026-09-12 04:00:27.233974 | instance | Saturday 12 September 2026 04:00:27 +0000 (0:00:00.520) 0:00:29.732 **** 2026-09-12 04:00:27.838500 | instance | changed: [instance] => (item={'path': '/etc/docker'}) 2026-09-12 04:00:27.839293 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'}) 2026-09-12 04:00:27.839334 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'}) 2026-09-12 04:00:27.839342 | instance | 2026-09-12 04:00:27.839349 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ****** 2026-09-12 04:00:27.839354 | instance | Saturday 12 September 2026 04:00:27 +0000 (0:00:00.605) 0:00:30.337 **** 2026-09-12 04:00:28.318867 | instance | changed: [instance] 2026-09-12 04:00:28.319732 | instance | 2026-09-12 04:00:28.319942 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] *********** 2026-09-12 04:00:28.319958 | instance | Saturday 12 September 2026 04:00:28 +0000 (0:00:00.479) 0:00:30.817 **** 2026-09-12 04:00:28.833040 | instance | changed: [instance] 2026-09-12 04:00:28.833932 | instance | 2026-09-12 04:00:28.833976 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************ 2026-09-12 04:00:28.833996 | instance | Saturday 12 September 2026 04:00:28 +0000 (0:00:00.488) 0:00:31.306 **** 2026-09-12 04:00:28.834002 | instance | 2026-09-12 04:00:28.834007 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-12 04:00:28.834018 | instance | Saturday 12 September 2026 04:00:28 +0000 (0:00:00.025) 0:00:31.332 **** 2026-09-12 04:00:29.495507 | instance | ok: [instance] 2026-09-12 04:00:29.496232 | instance | 2026-09-12 04:00:29.496285 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ****************** 2026-09-12 04:00:29.496297 | instance | Saturday 12 September 2026 04:00:29 +0000 (0:00:00.661) 0:00:31.993 **** 2026-09-12 04:00:30.853522 | instance | changed: [instance] 2026-09-12 04:00:30.853585 | instance | 2026-09-12 04:00:30.853596 | instance | TASK [vexxhost.containers.docker : Enable and start service] ******************* 2026-09-12 04:00:30.853606 | instance | Saturday 12 September 2026 04:00:30 +0000 (0:00:01.356) 0:00:33.350 **** 2026-09-12 04:00:31.485024 | instance | changed: [instance] 2026-09-12 04:00:31.485138 | instance | 2026-09-12 04:00:31.485476 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ****** 2026-09-12 04:00:31.485514 | instance | Saturday 12 September 2026 04:00:31 +0000 (0:00:00.633) 0:00:33.983 **** 2026-09-12 04:00:31.551870 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml) 2026-09-12 04:00:31.551965 | instance | 2026-09-12 04:00:31.552231 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ******************************** 2026-09-12 04:00:31.552268 | instance | Saturday 12 September 2026 04:00:31 +0000 (0:00:00.066) 0:00:34.050 **** 2026-09-12 04:00:36.534892 | instance | changed: [instance] 2026-09-12 04:00:36.535652 | instance | 2026-09-12 04:00:36.535707 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] ********************* 2026-09-12 04:00:36.535719 | instance | Saturday 12 September 2026 04:00:36 +0000 (0:00:04.982) 0:00:39.033 **** 2026-09-12 04:00:37.202281 | instance | ok: [instance] => (item=chronyd) 2026-09-12 04:00:37.202465 | instance | ok: [instance] => (item=sshd) 2026-09-12 04:00:37.203164 | instance | 2026-09-12 04:00:37.203219 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ****************************** 2026-09-12 04:00:37.203230 | instance | Saturday 12 September 2026 04:00:37 +0000 (0:00:00.667) 0:00:39.700 **** 2026-09-12 04:01:00.022536 | instance | changed: [instance] 2026-09-12 04:01:00.023369 | instance | 2026-09-12 04:01:00.023422 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ******************** 2026-09-12 04:01:00.023434 | instance | Saturday 12 September 2026 04:01:00 +0000 (0:00:22.820) 0:01:02.520 **** 2026-09-12 04:01:00.242677 | instance | ok: [instance] 2026-09-12 04:01:00.243426 | instance | 2026-09-12 04:01:00.243497 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] **************** 2026-09-12 04:01:00.243514 | instance | Saturday 12 September 2026 04:01:00 +0000 (0:00:00.219) 0:01:02.740 **** 2026-09-12 04:01:00.693411 | instance | changed: [instance] 2026-09-12 04:01:00.693492 | instance | 2026-09-12 04:01:00.693809 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] *** 2026-09-12 04:01:00.693849 | instance | Saturday 12 September 2026 04:01:00 +0000 (0:00:00.451) 0:01:03.192 **** 2026-09-12 04:01:01.044182 | instance | changed: [instance] 2026-09-12 04:01:01.044417 | instance | 2026-09-12 04:01:01.044430 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] ************************* 2026-09-12 04:01:01.044641 | instance | Saturday 12 September 2026 04:01:01 +0000 (0:00:00.350) 0:01:03.543 **** 2026-09-12 04:01:01.182853 | instance | ok: [instance] 2026-09-12 04:01:01.183629 | instance | 2026-09-12 04:01:01.183688 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] ******************* 2026-09-12 04:01:01.183700 | instance | Saturday 12 September 2026 04:01:01 +0000 (0:00:00.137) 0:01:03.680 **** 2026-09-12 04:01:01.422792 | instance | ok: [instance] => (item=instance) 2026-09-12 04:01:01.423566 | instance | 2026-09-12 04:01:01.423616 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ******** 2026-09-12 04:01:01.423629 | instance | Saturday 12 September 2026 04:01:01 +0000 (0:00:00.240) 0:01:03.921 **** 2026-09-12 04:01:01.481621 | instance | ok: [instance] 2026-09-12 04:01:01.481678 | instance | 2026-09-12 04:01:01.481690 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] *********************************** 2026-09-12 04:01:01.481701 | instance | Saturday 12 September 2026 04:01:01 +0000 (0:00:00.057) 0:01:03.978 **** 2026-09-12 04:01:01.561159 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance 2026-09-12 04:01:01.561232 | instance | 2026-09-12 04:01:01.561436 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] ************* 2026-09-12 04:01:01.561476 | instance | Saturday 12 September 2026 04:01:01 +0000 (0:00:00.081) 0:01:04.060 **** 2026-09-12 04:01:01.908203 | instance | changed: [instance] 2026-09-12 04:01:01.908245 | instance | 2026-09-12 04:01:01.908253 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ****************** 2026-09-12 04:01:01.908260 | instance | Saturday 12 September 2026 04:01:01 +0000 (0:00:00.346) 0:01:04.406 **** 2026-09-12 04:01:02.633530 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True}) 2026-09-12 04:01:02.634706 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0}) 2026-09-12 04:01:02.634756 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False}) 2026-09-12 04:01:02.634767 | instance | 2026-09-12 04:01:02.634777 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] ******************************* 2026-09-12 04:01:02.634787 | instance | Saturday 12 September 2026 04:01:02 +0000 (0:00:00.725) 0:01:05.132 **** 2026-09-12 04:02:35.332131 | instance | ok: [instance] 2026-09-12 04:02:35.332196 | instance | 2026-09-12 04:02:35.332207 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] *************** 2026-09-12 04:02:35.332217 | instance | Saturday 12 September 2026 04:02:35 +0000 (0:01:32.697) 0:02:37.829 **** 2026-09-12 04:02:35.574284 | instance | changed: [instance] 2026-09-12 04:02:35.574471 | instance | 2026-09-12 04:02:35.574727 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] ********************************** 2026-09-12 04:02:35.574952 | instance | Saturday 12 September 2026 04:02:35 +0000 (0:00:00.243) 0:02:38.072 **** 2026-09-12 04:02:35.617140 | instance | ok: [instance] 2026-09-12 04:02:35.617311 | instance | 2026-09-12 04:02:35.617663 | instance | TASK [Install Ceph host] ******************************************************* 2026-09-12 04:02:35.617788 | instance | Saturday 12 September 2026 04:02:35 +0000 (0:00:00.042) 0:02:38.115 **** 2026-09-12 04:02:35.741766 | instance | included: vexxhost.ceph.cephadm_host for instance 2026-09-12 04:02:35.741827 | instance | 2026-09-12 04:02:35.742068 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ****** 2026-09-12 04:02:35.742086 | instance | Saturday 12 September 2026 04:02:35 +0000 (0:00:00.125) 0:02:38.240 **** 2026-09-12 04:02:37.381192 | instance | ok: [instance] 2026-09-12 04:02:37.381241 | instance | 2026-09-12 04:02:37.381256 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] *** 2026-09-12 04:02:37.381263 | instance | Saturday 12 September 2026 04:02:37 +0000 (0:00:01.638) 0:02:39.879 **** 2026-09-12 04:02:37.462686 | instance | ok: [instance] => (item=instance) 2026-09-12 04:02:37.462746 | instance | 2026-09-12 04:02:37.462757 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] *********** 2026-09-12 04:02:37.462767 | instance | Saturday 12 September 2026 04:02:37 +0000 (0:00:00.080) 0:02:39.960 **** 2026-09-12 04:02:37.830649 | instance | ok: [instance] 2026-09-12 04:02:37.831425 | instance | 2026-09-12 04:02:37.831491 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] *********************** 2026-09-12 04:02:37.831504 | instance | Saturday 12 September 2026 04:02:37 +0000 (0:00:00.368) 0:02:40.328 **** 2026-09-12 04:02:40.340801 | instance | ok: [instance] 2026-09-12 04:02:40.341503 | instance | 2026-09-12 04:02:40.341533 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ****************** 2026-09-12 04:02:40.341545 | instance | Saturday 12 September 2026 04:02:40 +0000 (0:00:02.511) 0:02:42.839 **** 2026-09-12 04:03:30.668368 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster) 2026-09-12 04:03:30.669212 | 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_45x9w51y/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_45x9w51y/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_45x9w51y/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_45x9w51y/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-12T04:03:30.479+0000 7f7a7d45f640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCxzqRqzjhVDCAAYEC+Io+0rOI6ajLbw2WjErW2wljQgYw5/FPEJRYdA2o=: Malformed input [buffer:3]\n2026-09-12T04:03:30.479+0000 7f7a7d45f640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-12T04:03:30.479+0000 7f7a7d45f640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCxzqRqzjhVDCAAYEC+Io+0rOI6ajLbw2WjErW2wljQgYw5/FPEJRYdA2o=: Malformed input [buffer:3]\n2026-09-12T04:03:30.479+0000 7f7a7d45f640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-12T04:03:30.479+0000 7f7a7d45f640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCxzqRqzjhVDCAAYEC+Io+0rOI6ajLbw2WjErW2wljQgYw5/FPEJRYdA2o=: Malformed input [buffer:3]\n2026-09-12T04:03:30.479+0000 7f7a7d45f640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-12T04:03:30.479+0000 7f7a7d45f640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1} 2026-09-12 04:03:30.672208 | instance | 2026-09-12 04:03:30.672449 | instance | PLAY RECAP ********************************************************************* 2026-09-12 04:03:30.678267 | instance | instance : ok=55 changed=26 unreachable=0 failed=1 skipped=7 rescued=0 ignored=0 2026-09-12 04:03:30.678345 | instance | 2026-09-12 04:03:30.678357 | instance | Saturday 12 September 2026 04:03:30 +0000 (0:00:50.332) 0:03:33.171 **** 2026-09-12 04:03:30.678367 | instance | =============================================================================== 2026-09-12 04:03:30.678379 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 92.70s 2026-09-12 04:03:30.678390 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 50.33s 2026-09-12 04:03:30.678403 | instance | vexxhost.ceph.cephadm : Download "cephadm" ----------------------------- 22.82s 2026-09-12 04:03:30.678415 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 6.17s 2026-09-12 04:03:30.678427 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 5.41s 2026-09-12 04:03:30.678439 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 4.98s 2026-09-12 04:03:30.678474 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.95s 2026-09-12 04:03:30.678487 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.51s 2026-09-12 04:03:30.678498 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.64s 2026-09-12 04:03:30.678510 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.62s 2026-09-12 04:03:30.678522 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 1.36s 2026-09-12 04:03:30.678534 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.32s 2026-09-12 04:03:30.678544 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.13s 2026-09-12 04:03:30.678553 | instance | Gathering Facts --------------------------------------------------------- 1.06s 2026-09-12 04:03:30.678562 | instance | Gathering Facts --------------------------------------------------------- 1.01s 2026-09-12 04:03:30.678570 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.01s 2026-09-12 04:03:30.678579 | instance | Gathering Facts --------------------------------------------------------- 1.00s 2026-09-12 04:03:30.678588 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.96s 2026-09-12 04:03:30.678596 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.73s 2026-09-12 04:03:30.678605 | instance | vexxhost.containers.containerd : Create systemd service file for containerd --- 0.70s 2026-09-12 04:03:30.841860 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-12 04:03:30.842174 | instance | ERROR [csi > converge] Executed: Failed 2026-09-12 04:03:30.842365 | instance | ERROR Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-12 04:03:30.979941 | instance | ERROR 2026-09-12 04:03:30.980158 | instance | { 2026-09-12 04:03:30.980215 | instance | "delta": "0:04:28.478031", 2026-09-12 04:03:30.980254 | instance | "end": "2026-09-12 04:03:30.911852", 2026-09-12 04:03:30.980289 | instance | "msg": "non-zero return code", 2026-09-12 04:03:30.980322 | instance | "rc": 2, 2026-09-12 04:03:30.980355 | instance | "start": "2026-09-12 03:59:02.433821" 2026-09-12 04:03:30.980388 | instance | } failure 2026-09-12 04:03:30.988754 | 2026-09-12 04:03:30.988875 | PLAY RECAP 2026-09-12 04:03:30.988968 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-12 04:03:30.989011 | 2026-09-12 04:03:31.168345 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-12 04:03:31.172255 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-12 04:03:31.780409 | 2026-09-12 04:03:31.780552 | PLAY [all] 2026-09-12 04:03:31.793647 | 2026-09-12 04:03:31.793727 | TASK [gather-host-logs : creating directory for system status] 2026-09-12 04:03:32.168660 | instance | changed 2026-09-12 04:03:32.177455 | 2026-09-12 04:03:32.177737 | TASK [gather-host-logs : Get logs for each host] 2026-09-12 04:03:32.579074 | instance | + systemd-cgls --full --all --no-pager 2026-09-12 04:03:32.594644 | instance | + ip addr 2026-09-12 04:03:32.596538 | instance | + ip route 2026-09-12 04:03:32.598778 | instance | + lsblk 2026-09-12 04:03:32.602272 | instance | + mount 2026-09-12 04:03:32.605696 | instance | + docker images 2026-09-12 04:03:32.629055 | instance | + brctl show 2026-09-12 04:03:32.629695 | instance | /bin/bash: line 8: brctl: command not found 2026-09-12 04:03:32.630018 | instance | + ip neigh 2026-09-12 04:03:32.631998 | instance | + bridge fdb show 2026-09-12 04:03:32.635704 | instance | + ps aux --sort=-%mem 2026-09-12 04:03:32.658195 | instance | + dpkg -l 2026-09-12 04:03:32.668820 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-09-12 04:03:32.669368 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-09-12 04:03:32.693262 | instance | + '[' '!' -z '' ']' 2026-09-12 04:03:32.750345 | instance | ok: Runtime: 0:00:00.119978 2026-09-12 04:03:32.762007 | 2026-09-12 04:03:32.762134 | TASK [gather-host-logs : Downloads logs to executor] 2026-09-12 04:03:33.436117 | instance | changed: 2026-09-12 04:03:33.436376 | instance | created directory /var/lib/zuul/builds/a4ea0dc9e94040dc886f537f2cb936be/work/logs/instance 2026-09-12 04:03:33.436434 | instance | cd+++++++++ system/ 2026-09-12 04:03:33.436478 | instance | >f+++++++++ system/brctl-show.txt 2026-09-12 04:03:33.436550 | instance | >f+++++++++ system/bridge-fdb.txt 2026-09-12 04:03:33.436593 | instance | >f+++++++++ system/docker-images.txt 2026-09-12 04:03:33.436636 | instance | >f+++++++++ system/ip-addr.txt 2026-09-12 04:03:33.436676 | instance | >f+++++++++ system/ip-neigh.txt 2026-09-12 04:03:33.436714 | instance | >f+++++++++ system/ip-route.txt 2026-09-12 04:03:33.436755 | instance | >f+++++++++ system/lsblk.txt 2026-09-12 04:03:33.436792 | instance | >f+++++++++ system/mount.txt 2026-09-12 04:03:33.436830 | instance | >f+++++++++ system/packages.txt 2026-09-12 04:03:33.436893 | instance | >f+++++++++ system/ps.txt 2026-09-12 04:03:33.436931 | instance | >f+++++++++ system/systemd-cgls.txt 2026-09-12 04:03:33.454555 | 2026-09-12 04:03:33.454689 | LOOP [helm-release-status : creating directory for helm release status] 2026-09-12 04:03:33.672747 | instance | changed: "values" 2026-09-12 04:03:33.863486 | instance | changed: "releases" 2026-09-12 04:03:33.882497 | 2026-09-12 04:03:33.882762 | TASK [helm-release-status : Gather get release status for helm charts] 2026-09-12 04:03:34.143600 | instance | /bin/bash: line 3: kubectl: command not found 2026-09-12 04:03:34.435767 | instance | ok: Runtime: 0:00:00.005270 2026-09-12 04:03:34.446452 | 2026-09-12 04:03:34.446586 | TASK [helm-release-status : Downloads logs to executor] 2026-09-12 04:03:34.967962 | instance | changed: 2026-09-12 04:03:34.968221 | instance | cd+++++++++ helm/ 2026-09-12 04:03:34.968275 | instance | cd+++++++++ helm/releases/ 2026-09-12 04:03:34.968315 | instance | cd+++++++++ helm/values/ 2026-09-12 04:03:34.986298 | 2026-09-12 04:03:34.986421 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-09-12 04:03:35.212265 | instance | changed 2026-09-12 04:03:35.223764 | 2026-09-12 04:03:35.223924 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-09-12 04:03:35.538105 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-12 04:03:35.538922 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-12 04:03:35.543059 | instance | environment: line 1: kubectl: command not found 2026-09-12 04:03:35.544234 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-12 04:03:35.545954 | instance | environment: line 1: kubectl: command not found 2026-09-12 04:03:35.547294 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-12 04:03:35.547331 | instance | environment: line 1: kubectl: command not found 2026-09-12 04:03:35.548871 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-12 04:03:35.550887 | instance | environment: line 1: kubectl: command not found 2026-09-12 04:03:35.551882 | instance | environment: line 1: kubectl: command not found 2026-09-12 04:03:35.552073 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-12 04:03:35.553299 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-12 04:03:35.780974 | instance | ok: Runtime: 0:00:00.025411 2026-09-12 04:03:35.790673 | 2026-09-12 04:03:35.790800 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-09-12 04:03:35.998014 | instance | changed 2026-09-12 04:03:36.008453 | 2026-09-12 04:03:36.008625 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-09-12 04:03:36.266821 | instance | environment: line 5: kubectl: command not found 2026-09-12 04:03:36.267115 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-12 04:03:36.267750 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-12 04:03:36.267831 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-12 04:03:36.554777 | instance | ok: Runtime: 0:00:00.008390 2026-09-12 04:03:36.565299 | 2026-09-12 04:03:36.565434 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-09-12 04:03:37.105448 | instance | changed: 2026-09-12 04:03:37.105725 | instance | cd+++++++++ objects/ 2026-09-12 04:03:37.105781 | instance | cd+++++++++ objects/cluster/ 2026-09-12 04:03:37.105821 | instance | cd+++++++++ objects/namespaced/ 2026-09-12 04:03:37.123963 | 2026-09-12 04:03:37.124093 | TASK [gather-pod-logs : creating directory for pod logs] 2026-09-12 04:03:37.350752 | instance | changed 2026-09-12 04:03:37.360326 | 2026-09-12 04:03:37.360451 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-09-12 04:03:37.580246 | instance | changed 2026-09-12 04:03:37.592764 | 2026-09-12 04:03:37.592998 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-09-12 04:03:37.848532 | instance | environment: line 3: kubectl: command not found 2026-09-12 04:03:38.138403 | instance | ok: Runtime: 0:00:00.009062 2026-09-12 04:03:38.149765 | 2026-09-12 04:03:38.150372 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-09-12 04:03:38.669471 | instance | changed: 2026-09-12 04:03:38.669694 | instance | cd+++++++++ pod-logs/ 2026-09-12 04:03:38.669739 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-09-12 04:03:38.687692 | 2026-09-12 04:03:38.687802 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-09-12 04:03:38.907128 | instance | changed 2026-09-12 04:03:38.913716 | 2026-09-12 04:03:38.913795 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-09-12 04:03:39.158535 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-12 04:03:39.463222 | instance | ok: Runtime: 0:00:00.050649 2026-09-12 04:03:39.473490 | 2026-09-12 04:03:39.473624 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-09-12 04:03:39.705181 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-12 04:03:39.752954 | instance | ceph-mgr endpoints: 2026-09-12 04:03:40.029406 | instance | ok: Runtime: 0:00:00.052791 2026-09-12 04:03:40.039316 | 2026-09-12 04:03:40.039443 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-09-12 04:03:40.281050 | instance | /bin/bash: line 4: kubectl: command not found 2026-09-12 04:03:40.584661 | instance | ok: Runtime: 0:00:00.050300 2026-09-12 04:03:40.594700 | 2026-09-12 04:03:40.594828 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-09-12 04:03:41.137235 | instance | changed: cd+++++++++ prometheus/ 2026-09-12 04:03:41.154080 | 2026-09-12 04:03:41.154203 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-09-12 04:03:41.381571 | instance | changed 2026-09-12 04:03:41.390564 | 2026-09-12 04:03:41.390690 | TASK [gather-selenium-data : Get selenium data] 2026-09-12 04:03:41.681321 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-09-12 04:03:41.682978 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-09-12 04:03:41.935258 | instance | ERROR 2026-09-12 04:03:41.935533 | instance | { 2026-09-12 04:03:41.935590 | instance | "delta": "0:00:00.008277", 2026-09-12 04:03:41.935630 | instance | "end": "2026-09-12 04:03:41.683548", 2026-09-12 04:03:41.935666 | instance | "msg": "non-zero return code", 2026-09-12 04:03:41.935701 | instance | "rc": 1, 2026-09-12 04:03:41.935734 | instance | "start": "2026-09-12 04:03:41.675271" 2026-09-12 04:03:41.935767 | instance | } 2026-09-12 04:03:41.935812 | instance | ERROR: Ignoring Errors 2026-09-12 04:03:41.944792 | 2026-09-12 04:03:41.944934 | TASK [gather-selenium-data : Downloads logs to executor] 2026-09-12 04:03:42.474055 | instance | changed: cd+++++++++ selenium/ 2026-09-12 04:03:42.484394 | 2026-09-12 04:03:42.484490 | PLAY RECAP 2026-09-12 04:03:42.484630 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-09-12 04:03:42.484672 | 2026-09-12 04:03:42.613777 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-12 04:03:42.617705 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-12 04:03:43.168764 | 2026-09-12 04:03:43.168915 | PLAY [all] 2026-09-12 04:03:43.179610 | 2026-09-12 04:03:43.179687 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-12 04:03:43.234388 | instance | skipping: Conditional result was False 2026-09-12 04:03:43.240543 | 2026-09-12 04:03:43.240639 | TASK [fetch-output : Set log path for single node] 2026-09-12 04:03:43.292648 | instance | ok 2026-09-12 04:03:43.301342 | 2026-09-12 04:03:43.301473 | LOOP [fetch-output : Ensure local output dirs] 2026-09-12 04:03:43.688948 | instance -> localhost | ok: "/var/lib/zuul/builds/a4ea0dc9e94040dc886f537f2cb936be/work/logs" 2026-09-12 04:03:43.920315 | instance -> localhost | changed: "/var/lib/zuul/builds/a4ea0dc9e94040dc886f537f2cb936be/work/artifacts" 2026-09-12 04:03:44.136626 | instance -> localhost | changed: "/var/lib/zuul/builds/a4ea0dc9e94040dc886f537f2cb936be/work/docs" 2026-09-12 04:03:44.159904 | 2026-09-12 04:03:44.160071 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-12 04:03:44.853117 | instance | changed: .d..t...... ./ 2026-09-12 04:03:44.853479 | instance | changed: All items complete 2026-09-12 04:03:44.853541 | 2026-09-12 04:03:45.359111 | instance | changed: .d..t...... ./ 2026-09-12 04:03:45.860642 | instance | changed: .d..t...... ./ 2026-09-12 04:03:45.889582 | 2026-09-12 04:03:45.889827 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-12 04:03:46.321453 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007212 2026-09-12 04:03:46.561952 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007203 2026-09-12 04:03:46.576809 | 2026-09-12 04:03:46.577021 | PLAY [all] 2026-09-12 04:03:46.588158 | 2026-09-12 04:03:46.588283 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-12 04:03:47.059921 | instance | changed 2026-09-12 04:03:47.070166 | 2026-09-12 04:03:47.070267 | PLAY RECAP 2026-09-12 04:03:47.070350 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-12 04:03:47.070392 | 2026-09-12 04:03:47.220065 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-12 04:03:47.223996 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-12 04:03:47.769910 | 2026-09-12 04:03:47.770066 | PLAY [localhost] 2026-09-12 04:03:47.779608 | 2026-09-12 04:03:47.779679 | TASK [Generate Zuul manifest] 2026-09-12 04:03:47.796257 | localhost | ok 2026-09-12 04:03:47.811734 | 2026-09-12 04:03:47.811806 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-12 04:03:48.188231 | localhost | changed 2026-09-12 04:03:48.206433 | 2026-09-12 04:03:48.206571 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-12 04:03:48.247550 | localhost | ok 2026-09-12 04:03:48.259881 | 2026-09-12 04:03:48.260002 | TASK [Upload logs] 2026-09-12 04:03:48.281844 | localhost | ok 2026-09-12 04:03:48.354267 | 2026-09-12 04:03:48.354361 | TASK [Set zuul-log-path fact] 2026-09-12 04:03:48.370732 | localhost | ok 2026-09-12 04:03:48.380472 | 2026-09-12 04:03:48.380550 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-12 04:03:48.408515 | localhost | ok 2026-09-12 04:03:48.417177 | 2026-09-12 04:03:48.417312 | TASK [upload-logs : Create log directories] 2026-09-12 04:03:48.852796 | localhost | changed 2026-09-12 04:03:48.861754 | 2026-09-12 04:03:48.861912 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-12 04:03:49.269340 | localhost -> localhost | ok: Runtime: 0:00:00.005948 2026-09-12 04:03:49.278430 | 2026-09-12 04:03:49.278557 | TASK [upload-logs : Upload logs to log server] 2026-09-12 04:03:49.767330 | localhost | Output suppressed because no_log was given 2026-09-12 04:03:49.815262 | 2026-09-12 04:03:49.815435 | LOOP [upload-logs : Compress console log and json output] 2026-09-12 04:03:49.881254 | localhost | skipping: Conditional result was False 2026-09-12 04:03:49.890582 | localhost | skipping: Conditional result was False 2026-09-12 04:03:49.900833 | 2026-09-12 04:03:49.901068 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-12 04:03:49.942534 | localhost | skipping: Conditional result was False 2026-09-12 04:03:49.942941 | 2026-09-12 04:03:49.946053 | localhost | skipping: Conditional result was False 2026-09-12 04:03:49.955439 | 2026-09-12 04:03:49.955615 | LOOP [upload-logs : Upload console log and json output]