2026-09-01 05:05:08.727648 | Job console starting 2026-09-01 05:05:08.738226 | Updating git repos 2026-09-01 05:05:11.180246 | Cloning repos into workspace 2026-09-01 05:05:25.964493 | Restoring repo states 2026-09-01 05:05:33.668312 | Merging changes 2026-09-01 05:05:35.340080 | Checking out repos 2026-09-01 05:05:36.893032 | Preparing playbooks 2026-09-01 05:05:39.227853 | Running Ansible setup 2026-09-01 05:05:42.780641 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-01 05:05:43.409143 | 2026-09-01 05:05:43.409295 | PLAY [localhost] 2026-09-01 05:05:43.421757 | 2026-09-01 05:05:43.421940 | TASK [Gathering Facts] 2026-09-01 05:05:44.408707 | localhost | ok 2026-09-01 05:05:44.418279 | 2026-09-01 05:05:44.418358 | TASK [Setup log path fact] 2026-09-01 05:05:44.441993 | localhost | ok 2026-09-01 05:05:44.455964 | 2026-09-01 05:05:44.456043 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-01 05:05:44.489687 | localhost | ok 2026-09-01 05:05:44.498488 | 2026-09-01 05:05:44.498564 | TASK [emit-job-header : Print job information] 2026-09-01 05:05:44.541877 | # Job Information 2026-09-01 05:05:44.542060 | Ansible Version: 2.16.18 2026-09-01 05:05:44.542107 | Job: atmosphere-molecule-csi-rbd 2026-09-01 05:05:44.542138 | Pipeline: check 2026-09-01 05:05:44.542167 | Executor: 2d72f0692154 2026-09-01 05:05:44.542195 | Triggered by: https://github.com/vexxhost/atmosphere/pull/2851 2026-09-01 05:05:44.542227 | Event ID: 1eeb94b0-a5c2-11f1-9bb0-558340f77e91 2026-09-01 05:05:44.547397 | 2026-09-01 05:05:44.547499 | LOOP [emit-job-header : Print node information] 2026-09-01 05:05:44.642234 | localhost | ok: 2026-09-01 05:05:44.642476 | localhost | # Node Information 2026-09-01 05:05:44.642514 | localhost | Inventory Hostname: instance 2026-09-01 05:05:44.642538 | localhost | Hostname: np0000191701 2026-09-01 05:05:44.642558 | localhost | Username: zuul 2026-09-01 05:05:44.642580 | localhost | Distro: Ubuntu 22.04 2026-09-01 05:05:44.642600 | localhost | Provider: yul1 2026-09-01 05:05:44.642619 | localhost | Region: ca-ymq-1 2026-09-01 05:05:44.642638 | localhost | Label: ubuntu-jammy 2026-09-01 05:05:44.642656 | localhost | Product Name: OpenStack Nova 2026-09-01 05:05:44.642675 | localhost | Interface IP: 162.253.55.15 2026-09-01 05:05:44.656141 | 2026-09-01 05:05:44.656305 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-01 05:05:45.175669 | localhost -> localhost | changed 2026-09-01 05:05:45.182916 | 2026-09-01 05:05:45.183017 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-01 05:05:46.050666 | localhost -> localhost | changed 2026-09-01 05:05:46.060431 | 2026-09-01 05:05:46.060506 | PLAY [all] 2026-09-01 05:05:46.069845 | 2026-09-01 05:05:46.069919 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-01 05:05:46.316767 | instance -> localhost | ok 2026-09-01 05:05:46.327797 | 2026-09-01 05:05:46.327872 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-01 05:05:46.361057 | instance | ok 2026-09-01 05:05:46.376100 | instance | included: /var/lib/zuul/builds/e5a9e3696bcf4831a9f9e228745c1454/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-01 05:05:46.381968 | 2026-09-01 05:05:46.382033 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-01 05:05:47.246931 | instance -> localhost | Generating public/private rsa key pair. 2026-09-01 05:05:47.247169 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/e5a9e3696bcf4831a9f9e228745c1454/work/e5a9e3696bcf4831a9f9e228745c1454_id_rsa 2026-09-01 05:05:47.247210 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/e5a9e3696bcf4831a9f9e228745c1454/work/e5a9e3696bcf4831a9f9e228745c1454_id_rsa.pub 2026-09-01 05:05:47.247243 | instance -> localhost | The key fingerprint is: 2026-09-01 05:05:47.247272 | instance -> localhost | SHA256:GwywUu+Qn7oqviZpy/PmtjybfwA9DEj3D9tlHaz3teU zuul-build-sshkey 2026-09-01 05:05:47.247320 | instance -> localhost | The key's randomart image is: 2026-09-01 05:05:47.247350 | instance -> localhost | +---[RSA 3072]----+ 2026-09-01 05:05:47.247389 | instance -> localhost | |....o .. | 2026-09-01 05:05:47.247421 | instance -> localhost | | ..o.= ... | 2026-09-01 05:05:47.247450 | instance -> localhost | | . Boo o.. | 2026-09-01 05:05:47.247477 | instance -> localhost | | o B=+o. . ..| 2026-09-01 05:05:47.247505 | instance -> localhost | | ..=oS . . ..o| 2026-09-01 05:05:47.247533 | instance -> localhost | | o o . .E| 2026-09-01 05:05:47.247561 | instance -> localhost | | . . . . | 2026-09-01 05:05:47.247589 | instance -> localhost | |=+.+.. . | 2026-09-01 05:05:47.247620 | instance -> localhost | |=*XO*.. | 2026-09-01 05:05:47.247649 | instance -> localhost | +----[SHA256]-----+ 2026-09-01 05:05:47.247717 | instance -> localhost | ok: Runtime: 0:00:00.444460 2026-09-01 05:05:47.256980 | 2026-09-01 05:05:47.257086 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-01 05:05:47.291013 | instance | ok 2026-09-01 05:05:47.300771 | instance | included: /var/lib/zuul/builds/e5a9e3696bcf4831a9f9e228745c1454/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-01 05:05:47.308371 | 2026-09-01 05:05:47.308433 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-01 05:05:47.333375 | instance | skipping: Conditional result was False 2026-09-01 05:05:47.343945 | 2026-09-01 05:05:47.344019 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-01 05:05:47.794895 | instance | changed 2026-09-01 05:05:47.803549 | 2026-09-01 05:05:47.803648 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-01 05:05:47.987709 | instance | ok 2026-09-01 05:05:47.993293 | 2026-09-01 05:05:47.993366 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-01 05:05:48.483001 | instance | changed 2026-09-01 05:05:48.489403 | 2026-09-01 05:05:48.489472 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-01 05:05:49.020527 | instance | changed 2026-09-01 05:05:49.028463 | 2026-09-01 05:05:49.028551 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-01 05:05:49.052998 | instance | skipping: Conditional result was False 2026-09-01 05:05:49.060525 | 2026-09-01 05:05:49.060616 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-01 05:05:49.459789 | instance -> localhost | changed 2026-09-01 05:05:49.471446 | 2026-09-01 05:05:49.471509 | TASK [add-build-sshkey : Add back temp key] 2026-09-01 05:05:49.766058 | instance -> localhost | Identity added: /var/lib/zuul/builds/e5a9e3696bcf4831a9f9e228745c1454/work/e5a9e3696bcf4831a9f9e228745c1454_id_rsa (zuul-build-sshkey) 2026-09-01 05:05:49.766261 | instance -> localhost | ok: Runtime: 0:00:00.014644 2026-09-01 05:05:49.773180 | 2026-09-01 05:05:49.773272 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-01 05:05:50.103829 | instance | ok 2026-09-01 05:05:50.108928 | 2026-09-01 05:05:50.109015 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-01 05:05:50.134112 | instance | skipping: Conditional result was False 2026-09-01 05:05:50.147604 | 2026-09-01 05:05:50.147674 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-01 05:05:50.522666 | instance | ok 2026-09-01 05:05:50.529783 | 2026-09-01 05:05:50.529850 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-01 05:05:54.042573 | instance | Output suppressed because no_log was given 2026-09-01 05:05:54.051022 | 2026-09-01 05:05:54.051084 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-01 05:05:54.262246 | instance | ok: "logs" 2026-09-01 05:05:54.262548 | instance | ok: All items complete 2026-09-01 05:05:54.262577 | 2026-09-01 05:05:54.405820 | instance | ok: "artifacts" 2026-09-01 05:05:54.579526 | instance | ok: "docs" 2026-09-01 05:05:54.597530 | 2026-09-01 05:05:54.597707 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-01 05:05:54.813134 | instance | changed: "logs" 2026-09-01 05:05:54.957554 | instance | changed: "artifacts" 2026-09-01 05:05:55.098244 | instance | changed: "docs" 2026-09-01 05:05:55.114130 | 2026-09-01 05:05:55.114279 | PLAY RECAP 2026-09-01 05:05:55.114335 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-01 05:05:55.114364 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-01 05:05:55.114386 | 2026-09-01 05:05:55.321876 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-01 05:05:55.326654 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-01 05:05:55.895022 | 2026-09-01 05:05:55.895157 | PLAY [all] 2026-09-01 05:05:55.905626 | 2026-09-01 05:05:55.905699 | TASK [setup-uv : Extract archive] 2026-09-01 05:05:58.572760 | instance | changed 2026-09-01 05:05:58.579894 | 2026-09-01 05:05:58.579973 | TASK [setup-uv : Print version] 2026-09-01 05:05:58.944957 | instance | uv 0.8.13 2026-09-01 05:05:59.118270 | instance | ok: Runtime: 0:00:00.013822 2026-09-01 05:05:59.128840 | 2026-09-01 05:05:59.128903 | TASK [Install Ansible collection siblings] 2026-09-01 05:06:09.104487 | instance | changed: 2026-09-01 05:06:09.104794 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-09-01 05:06:09.104850 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-09-01 05:06:09.104884 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-09-01 05:06:09.104913 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto 2026-09-01 05:06:09.104942 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general 2026-09-01 05:06:09.105000 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql 2026-09-01 05:06:09.105032 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-09-01 05:06:09.105063 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-09-01 05:06:09.105097 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-09-01 05:06:09.105126 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-09-01 05:06:09.105153 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-09-01 05:06:09.105180 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-09-01 05:06:09.105207 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0 2026-09-01 05:06:09.105234 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0 2026-09-01 05:06:09.105261 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0 2026-09-01 05:06:09.105289 | instance | Resolved community.crypto to 2.2.3 for 2.2.3 2026-09-01 05:06:09.105316 | instance | Resolved community.general to 7.3.0 for 7.3.0 2026-09-01 05:06:09.105343 | instance | Resolved community.mysql to 3.6.0 for 3.6.0 2026-09-01 05:06:09.105369 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0 2026-09-01 05:06:09.105396 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0 2026-09-01 05:06:09.105422 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0 2026-09-01 05:06:09.105448 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0 2026-09-01 05:06:09.105477 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-09-01 05:06:09.105520 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0 2026-09-01 05:06:09.105549 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0 2026-09-01 05:06:09.105576 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-01 05:06:09.105602 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-01 05:06:09.105628 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-09-01 05:06:09.105654 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0 2026-09-01 05:06:09.105680 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3 2026-09-01 05:06:09.105706 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-01 05:06:09.105733 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-01 05:06:09.105760 | instance | Checked out ansible.netcommon to 1.2.0 2026-09-01 05:06:09.105787 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-09-01 05:06:09.105821 | instance | Starting galaxy collection install process 2026-09-01 05:06:09.105847 | instance | Process install dependency map 2026-09-01 05:06:09.105874 | instance | Starting collection install process 2026-09-01 05:06:09.105900 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-09-01 05:06:09.105925 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon 2026-09-01 05:06:09.105951 | instance | ansible.netcommon:* was installed successfully 2026-09-01 05:06:09.105976 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-09-01 05:06:09.106001 | instance | Creating virtual environment at: .venv 2026-09-01 05:06:09.106027 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-01 05:06:09.106053 | instance | Downloading netaddr (2.2MiB) 2026-09-01 05:06:09.106078 | instance | Downloading cryptography (4.2MiB) 2026-09-01 05:06:09.106108 | instance | Downloading rjsonnet (1.2MiB) 2026-09-01 05:06:09.106134 | instance | Downloading pydantic-core (2.0MiB) 2026-09-01 05:06:09.106160 | instance | Downloading ansible-core (2.1MiB) 2026-09-01 05:06:09.106185 | instance | Downloading openstacksdk (1.7MiB) 2026-09-01 05:06:09.106211 | instance | Downloading pygments (1.2MiB) 2026-09-01 05:06:09.106237 | instance | Downloading setuptools (1.1MiB) 2026-09-01 05:06:09.106262 | instance | Downloading kubernetes (1.9MiB) 2026-09-01 05:06:09.106288 | instance | Building pyperclip==1.9.0 2026-09-01 05:06:09.106316 | instance | Downloading rjsonnet 2026-09-01 05:06:09.106342 | instance | Downloading pydantic-core 2026-09-01 05:06:09.106369 | instance | Downloading netaddr 2026-09-01 05:06:09.106394 | instance | Downloading pygments 2026-09-01 05:06:09.106420 | instance | Downloading cryptography 2026-09-01 05:06:09.106445 | instance | Downloading setuptools 2026-09-01 05:06:09.106470 | instance | Downloading kubernetes 2026-09-01 05:06:09.106495 | instance | Downloading ansible-core 2026-09-01 05:06:09.106521 | instance | Downloading openstacksdk 2026-09-01 05:06:09.106547 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-01 05:06:09.106572 | instance | Built pyperclip==1.9.0 2026-09-01 05:06:09.106598 | instance | Installed 83 packages in 72ms 2026-09-01 05:06:09.106624 | instance | Checked out ansible.posix to 1.6.0 2026-09-01 05:06:09.106650 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-09-01 05:06:09.106676 | instance | Starting galaxy collection install process 2026-09-01 05:06:09.106702 | instance | Process install dependency map 2026-09-01 05:06:09.106734 | instance | Starting collection install process 2026-09-01 05:06:09.106761 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-09-01 05:06:09.106787 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix 2026-09-01 05:06:09.106813 | instance | ansible.posix:1.6.0 was installed successfully 2026-09-01 05:06:09.106839 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 05:06:09.106865 | instance | 2.17.12 2026-09-01 05:06:09.106891 | instance | Checked out ansible.utils to v6.1.0 2026-09-01 05:06:09.106917 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-09-01 05:06:09.106942 | instance | Starting galaxy collection install process 2026-09-01 05:06:09.106968 | instance | Process install dependency map 2026-09-01 05:06:09.106993 | instance | Starting collection install process 2026-09-01 05:06:09.107019 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-09-01 05:06:09.107117 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils 2026-09-01 05:06:09.107144 | instance | ansible.utils:6.1.0 was installed successfully 2026-09-01 05:06:09.107170 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 05:06:09.107196 | instance | 2.17.12 2026-09-01 05:06:09.107222 | instance | Checked out atmosphere.common to v0.7.0 2026-09-01 05:06:09.107247 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-09-01 05:06:09.107274 | instance | Starting galaxy collection install process 2026-09-01 05:06:09.107301 | instance | Process install dependency map 2026-09-01 05:06:09.107326 | instance | Starting collection install process 2026-09-01 05:06:09.107352 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common' 2026-09-01 05:06:09.107378 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common 2026-09-01 05:06:09.107405 | instance | atmosphere.common:0.7.0 was installed successfully 2026-09-01 05:06:09.107430 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 05:06:09.107456 | instance | 2.17.12 2026-09-01 05:06:09.107481 | instance | Checked out community.crypto to 2.2.3 2026-09-01 05:06:09.107507 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto 2026-09-01 05:06:09.107532 | instance | Starting galaxy collection install process 2026-09-01 05:06:09.107558 | instance | Process install dependency map 2026-09-01 05:06:09.107580 | instance | Starting collection install process 2026-09-01 05:06:09.107597 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-09-01 05:06:09.107614 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto 2026-09-01 05:06:09.107631 | instance | community.crypto:2.2.3 was installed successfully 2026-09-01 05:06:09.107751 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 05:06:09.107778 | instance | 2.17.12 2026-09-01 05:06:09.107798 | instance | Checked out community.general to 7.3.0 2026-09-01 05:06:09.107816 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general 2026-09-01 05:06:09.107841 | instance | Starting galaxy collection install process 2026-09-01 05:06:09.107860 | instance | Process install dependency map 2026-09-01 05:06:09.107878 | instance | Starting collection install process 2026-09-01 05:06:09.107895 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-09-01 05:06:09.107913 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general 2026-09-01 05:06:09.107931 | instance | community.general:7.3.0 was installed successfully 2026-09-01 05:06:09.107948 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 05:06:09.107966 | instance | 2.17.12 2026-09-01 05:06:09.107983 | instance | Checked out community.mysql to 3.6.0 2026-09-01 05:06:09.108003 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql 2026-09-01 05:06:09.108022 | instance | Starting galaxy collection install process 2026-09-01 05:06:09.108040 | instance | Process install dependency map 2026-09-01 05:06:09.108057 | instance | Starting collection install process 2026-09-01 05:06:09.108075 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-09-01 05:06:09.108093 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql 2026-09-01 05:06:09.108110 | instance | community.mysql:3.6.0 was installed successfully 2026-09-01 05:06:09.108127 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 05:06:09.108144 | instance | 2.17.12 2026-09-01 05:06:09.108162 | instance | Checked out kubernetes.core to 2.4.0 2026-09-01 05:06:09.108180 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-09-01 05:06:09.108197 | instance | Starting galaxy collection install process 2026-09-01 05:06:09.108214 | instance | Process install dependency map 2026-09-01 05:06:09.108232 | instance | Starting collection install process 2026-09-01 05:06:09.108249 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-09-01 05:06:09.108267 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core 2026-09-01 05:06:09.108284 | instance | kubernetes.core:2.4.0 was installed successfully 2026-09-01 05:06:09.108302 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 05:06:09.108319 | instance | 2.17.12 2026-09-01 05:06:09.108336 | instance | Checked out openstack.cloud to 2.6.0 2026-09-01 05:06:09.108353 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-09-01 05:06:09.108371 | instance | Starting galaxy collection install process 2026-09-01 05:06:09.108388 | instance | Process install dependency map 2026-09-01 05:06:09.108405 | instance | Starting collection install process 2026-09-01 05:06:09.108423 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-09-01 05:06:09.108440 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud 2026-09-01 05:06:09.108457 | instance | openstack.cloud:2.6.0 was installed successfully 2026-09-01 05:06:09.108474 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 05:06:09.108491 | instance | 2.17.12 2026-09-01 05:06:09.108512 | instance | Checked out vexxhost.ceph to v4.2.0 2026-09-01 05:06:09.108530 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-09-01 05:06:09.108548 | instance | Starting galaxy collection install process 2026-09-01 05:06:09.108565 | instance | Process install dependency map 2026-09-01 05:06:09.108582 | instance | Starting collection install process 2026-09-01 05:06:09.108600 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph' 2026-09-01 05:06:09.108617 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph 2026-09-01 05:06:09.108634 | instance | vexxhost.ceph:4.2.0 was installed successfully 2026-09-01 05:06:09.108651 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 05:06:09.108668 | instance | 2.17.12 2026-09-01 05:06:09.108685 | instance | Checked out vexxhost.containers to v1.6.8 2026-09-01 05:06:09.108702 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-09-01 05:06:09.108719 | instance | Starting galaxy collection install process 2026-09-01 05:06:09.108737 | instance | Process install dependency map 2026-09-01 05:06:09.108754 | instance | Starting collection install process 2026-09-01 05:06:09.108771 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers' 2026-09-01 05:06:09.108788 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers 2026-09-01 05:06:09.108805 | instance | vexxhost.containers:1.6.8 was installed successfully 2026-09-01 05:06:09.108822 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 05:06:09.108840 | instance | 2.17.12 2026-09-01 05:06:09.108857 | instance | Checked out vexxhost.kubernetes to v3.3.0 2026-09-01 05:06:09.108874 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-09-01 05:06:09.108891 | instance | Starting galaxy collection install process 2026-09-01 05:06:09.108909 | instance | Process install dependency map 2026-09-01 05:06:09.108926 | instance | Starting collection install process 2026-09-01 05:06:09.108943 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes' 2026-09-01 05:06:09.109013 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes 2026-09-01 05:06:09.109033 | instance | vexxhost.kubernetes:3.3.0 was installed successfully 2026-09-01 05:06:09.109051 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-01 05:06:09.109068 | instance | 2.17.12 2026-09-01 05:06:09.116024 | 2026-09-01 05:06:09.116146 | PLAY RECAP 2026-09-01 05:06:09.116197 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-01 05:06:09.116218 | 2026-09-01 05:06:09.275288 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-01 05:06:09.279954 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-01 05:06:09.958927 | 2026-09-01 05:06:09.959180 | PLAY [all] 2026-09-01 05:06:09.973323 | 2026-09-01 05:06:09.973404 | TASK [Install "jq" for log collection] 2026-09-01 05:06:20.316664 | instance | changed 2026-09-01 05:06:20.319082 | 2026-09-01 05:06:20.319151 | PLAY RECAP 2026-09-01 05:06:20.319223 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-01 05:06:20.319305 | 2026-09-01 05:06:20.500609 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-01 05:06:20.504657 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-01 05:06:21.099879 | 2026-09-01 05:06:21.100026 | PLAY [all] 2026-09-01 05:06:21.111215 | 2026-09-01 05:06:21.111283 | LOOP [Check inventory directories] 2026-09-01 05:06:21.987305 | instance | ok: "src/github.com/vexxhost/atmosphere" 2026-09-01 05:06:22.149202 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory" 2026-09-01 05:06:22.320369 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory" 2026-09-01 05:06:22.335330 | 2026-09-01 05:06:22.335545 | LOOP [Copy inventory file for Zuul] 2026-09-01 05:06:23.158804 | instance | changed: 2026-09-01 05:06:23.159201 | instance | { 2026-09-01 05:06:23.159278 | instance | "ansible_loop_var": "item", 2026-09-01 05:06:23.159305 | instance | "changed": false, 2026-09-01 05:06:23.159331 | instance | "failed": false, 2026-09-01 05:06:23.159380 | instance | "invocation": { 2026-09-01 05:06:23.159405 | instance | "module_args": { 2026-09-01 05:06:23.159428 | instance | "checksum_algorithm": "sha1", 2026-09-01 05:06:23.159451 | instance | "follow": true, 2026-09-01 05:06:23.159474 | instance | "get_attributes": true, 2026-09-01 05:06:23.159497 | instance | "get_checksum": true, 2026-09-01 05:06:23.159520 | instance | "get_mime": true, 2026-09-01 05:06:23.159542 | instance | "path": "src/github.com/vexxhost/atmosphere" 2026-09-01 05:06:23.159565 | instance | } 2026-09-01 05:06:23.159588 | instance | }, 2026-09-01 05:06:23.159609 | instance | "item": "src/github.com/vexxhost/atmosphere", 2026-09-01 05:06:23.159631 | instance | "stat": { 2026-09-01 05:06:23.159653 | instance | "atime": 1788239161.4074862, 2026-09-01 05:06:23.159676 | instance | "attr_flags": "e", 2026-09-01 05:06:23.159698 | instance | "attributes": [ 2026-09-01 05:06:23.159720 | instance | "extents" 2026-09-01 05:06:23.159742 | instance | ], 2026-09-01 05:06:23.159763 | instance | "block_size": 4096, 2026-09-01 05:06:23.159785 | instance | "blocks": 8, 2026-09-01 05:06:23.159807 | instance | "charset": "unknown", 2026-09-01 05:06:23.159830 | instance | "ctime": 1788239160.0274262, 2026-09-01 05:06:23.159856 | instance | "dev": 64513, 2026-09-01 05:06:23.160608 | instance | "device_type": 0, 2026-09-01 05:06:23.160643 | instance | "executable": true, 2026-09-01 05:06:23.160665 | instance | "exists": true, 2026-09-01 05:06:23.160688 | instance | "gid": 1000, 2026-09-01 05:06:23.160711 | instance | "gr_name": "zuul", 2026-09-01 05:06:23.160733 | instance | "inode": 1048162, 2026-09-01 05:06:23.160755 | instance | "isblk": false, 2026-09-01 05:06:23.160778 | instance | "ischr": false, 2026-09-01 05:06:23.160800 | instance | "isdir": true, 2026-09-01 05:06:23.160822 | instance | "isfifo": false, 2026-09-01 05:06:23.160843 | instance | "isgid": false, 2026-09-01 05:06:23.160865 | instance | "islnk": false, 2026-09-01 05:06:23.160886 | instance | "isreg": false, 2026-09-01 05:06:23.160907 | instance | "issock": false, 2026-09-01 05:06:23.160928 | instance | "isuid": false, 2026-09-01 05:06:23.160949 | instance | "mimetype": "unknown", 2026-09-01 05:06:23.161174 | instance | "mode": "0755", 2026-09-01 05:06:23.161193 | instance | "mtime": 1788239160.0274262, 2026-09-01 05:06:23.161215 | instance | "nlink": 23, 2026-09-01 05:06:23.161238 | instance | "path": "src/github.com/vexxhost/atmosphere", 2026-09-01 05:06:23.161259 | instance | "pw_name": "zuul", 2026-09-01 05:06:23.161284 | instance | "readable": true, 2026-09-01 05:06:23.161307 | instance | "rgrp": true, 2026-09-01 05:06:23.161328 | instance | "roth": true, 2026-09-01 05:06:23.161445 | instance | "rusr": true, 2026-09-01 05:06:23.161474 | instance | "size": 4096, 2026-09-01 05:06:23.161492 | instance | "uid": 1000, 2026-09-01 05:06:23.161509 | instance | "version": "976654994", 2026-09-01 05:06:23.161527 | instance | "wgrp": false, 2026-09-01 05:06:23.161546 | instance | "woth": false, 2026-09-01 05:06:23.161564 | instance | "writeable": true, 2026-09-01 05:06:23.161582 | instance | "wusr": true, 2026-09-01 05:06:23.161598 | instance | "xgrp": true, 2026-09-01 05:06:23.161614 | instance | "xoth": true, 2026-09-01 05:06:23.161630 | instance | "xusr": true 2026-09-01 05:06:23.161647 | instance | } 2026-09-01 05:06:23.161663 | instance | } 2026-09-01 05:06:23.161697 | instance | ok: All items complete 2026-09-01 05:06:23.161717 | 2026-09-01 05:06:23.163150 | instance | skipping: Conditional result was False 2026-09-01 05:06:23.163738 | instance | skipping: Conditional result was False 2026-09-01 05:06:23.182406 | 2026-09-01 05:06:23.182565 | LOOP [Switch "ansible_host" to private IP] 2026-09-01 05:06:23.553664 | instance | changed: 1 replacements made 2026-09-01 05:06:23.555415 | instance | skipping: Conditional result was False 2026-09-01 05:06:23.557954 | instance | skipping: Conditional result was False 2026-09-01 05:06:23.572655 | 2026-09-01 05:06:23.572805 | TASK [Run Molecule scenario] 2026-09-01 05:06:24.509341 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-09-01 05:06:24.870310 | instance | INFO [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-01 05:06:24.870418 | instance | INFO [csi > prerun] Performing prerun with role_name_check=0... 2026-09-01 05:06:35.944146 | instance | INFO [csi > dependency] Executing 2026-09-01 05:06:35.944443 | instance | WARNING [csi > dependency] Missing roles requirements file: requirements.yml 2026-09-01 05:06:35.944847 | instance | WARNING [csi > dependency] Missing collections requirements file: collections.yml 2026-09-01 05:06:35.945066 | instance | WARNING [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-09-01 05:06:35.959265 | instance | INFO [csi > cleanup] Executing 2026-09-01 05:06:35.959853 | instance | WARNING [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-01 05:06:35.973770 | instance | INFO [csi > destroy] Executing 2026-09-01 05:06:35.973814 | instance | WARNING [csi > destroy] Skipping, '--destroy=never' requested. 2026-09-01 05:06:35.973918 | instance | INFO [csi > destroy] Executed: Successful 2026-09-01 05:06:35.983882 | instance | INFO [csi > syntax] Executing 2026-09-01 05:06:37.483307 | instance | 2026-09-01 05:06:37.483556 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-01 05:06:37.541863 | instance | INFO [csi > syntax] Executed: Successful 2026-09-01 05:06:37.557248 | instance | INFO [csi > create] Executing 2026-09-01 05:06:37.560863 | instance | WARNING [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-01 05:06:37.575480 | instance | INFO [csi > prepare] Executing 2026-09-01 05:06:38.375364 | instance | 2026-09-01 05:06:38.375575 | instance | PLAY [Prepare] ***************************************************************** 2026-09-01 05:06:38.375823 | instance | 2026-09-01 05:06:38.376089 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-01 05:06:38.376292 | instance | Tuesday 01 September 2026 05:06:38 +0000 (0:00:00.025) 0:00:00.025 ***** 2026-09-01 05:06:39.645973 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-01 05:06:39.647376 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-01 05:06:39.647423 | instance | interpreter could change the meaning of that path. See 2026-09-01 05:06:39.647434 | instance | https://docs.ansible.com/ansible- 2026-09-01 05:06:39.647444 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-01 05:06:39.658794 | instance | ok: [instance] 2026-09-01 05:06:39.658867 | instance | 2026-09-01 05:06:39.658881 | instance | TASK [Configure short hostname] ************************************************ 2026-09-01 05:06:39.659042 | instance | Tuesday 01 September 2026 05:06:39 +0000 (0:00:01.283) 0:00:01.308 ***** 2026-09-01 05:06:40.392519 | instance | changed: [instance] 2026-09-01 05:06:40.392789 | instance | 2026-09-01 05:06:40.392996 | instance | TASK [Ensure hostname inside hosts file] *************************************** 2026-09-01 05:06:40.393233 | instance | Tuesday 01 September 2026 05:06:40 +0000 (0:00:00.734) 0:00:02.042 ***** 2026-09-01 05:06:40.668251 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created 2026-09-01 05:06:40.668372 | instance | with a mode of 0700, this may cause issues when running as another user. To 2026-09-01 05:06:40.668512 | instance | avoid this, create the remote_tmp dir with the correct permissions manually 2026-09-01 05:06:40.679750 | instance | changed: [instance] 2026-09-01 05:06:40.679872 | instance | 2026-09-01 05:06:40.680011 | instance | TASK [Purge "snapd" package] *************************************************** 2026-09-01 05:06:40.680151 | instance | Tuesday 01 September 2026 05:06:40 +0000 (0:00:00.287) 0:00:02.330 ***** 2026-09-01 05:06:41.537659 | instance | ok: [instance] 2026-09-01 05:06:41.537856 | instance | 2026-09-01 05:06:41.538094 | instance | PLAY [Create devices for Ceph] ************************************************* 2026-09-01 05:06:41.538311 | instance | 2026-09-01 05:06:41.538540 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-01 05:06:41.538795 | instance | Tuesday 01 September 2026 05:06:41 +0000 (0:00:00.857) 0:00:03.188 ***** 2026-09-01 05:06:42.341060 | instance | ok: [instance] 2026-09-01 05:06:42.341233 | instance | 2026-09-01 05:06:42.341570 | instance | TASK [Install depedencies] ***************************************************** 2026-09-01 05:06:42.342047 | instance | Tuesday 01 September 2026 05:06:42 +0000 (0:00:00.802) 0:00:03.990 ***** 2026-09-01 05:07:06.228647 | instance | changed: [instance] 2026-09-01 05:07:06.228736 | instance | 2026-09-01 05:07:06.228747 | instance | TASK [Start up service] ******************************************************** 2026-09-01 05:07:06.228877 | instance | Tuesday 01 September 2026 05:07:06 +0000 (0:00:23.887) 0:00:27.878 ***** 2026-09-01 05:07:06.825566 | instance | ok: [instance] 2026-09-01 05:07:06.825664 | instance | 2026-09-01 05:07:06.825677 | instance | TASK [Generate lvm.conf] ******************************************************* 2026-09-01 05:07:06.825688 | instance | Tuesday 01 September 2026 05:07:06 +0000 (0:00:00.596) 0:00:28.474 ***** 2026-09-01 05:07:07.195070 | instance | ok: [instance] 2026-09-01 05:07:07.195166 | instance | 2026-09-01 05:07:07.195178 | instance | TASK [Write /etc/lvm/lvm.conf] ************************************************* 2026-09-01 05:07:07.195189 | instance | Tuesday 01 September 2026 05:07:07 +0000 (0:00:00.352) 0:00:28.827 ***** 2026-09-01 05:07:07.931267 | instance | changed: [instance] 2026-09-01 05:07:07.931346 | instance | 2026-09-01 05:07:07.931361 | instance | TASK [Get list of all loopback devices] **************************************** 2026-09-01 05:07:07.931372 | instance | Tuesday 01 September 2026 05:07:07 +0000 (0:00:00.752) 0:00:29.580 ***** 2026-09-01 05:07:08.186082 | instance | ok: [instance] 2026-09-01 05:07:08.186124 | instance | 2026-09-01 05:07:08.186131 | instance | TASK [Fail if there is any existing loopback devices] ************************** 2026-09-01 05:07:08.186138 | instance | Tuesday 01 September 2026 05:07:08 +0000 (0:00:00.255) 0:00:29.836 ***** 2026-09-01 05:07:08.213423 | instance | skipping: [instance] 2026-09-01 05:07:08.214310 | instance | 2026-09-01 05:07:08.214412 | instance | TASK [Create devices for Ceph] ************************************************* 2026-09-01 05:07:08.214425 | instance | Tuesday 01 September 2026 05:07:08 +0000 (0:00:00.027) 0:00:29.863 ***** 2026-09-01 05:07:08.848666 | instance | changed: [instance] => (item=osd0) 2026-09-01 05:07:08.848972 | instance | changed: [instance] => (item=osd1) 2026-09-01 05:07:08.849429 | instance | changed: [instance] => (item=osd2) 2026-09-01 05:07:08.849793 | instance | 2026-09-01 05:07:08.850105 | instance | TASK [Set permissions on loopback devices] ************************************* 2026-09-01 05:07:08.850442 | instance | Tuesday 01 September 2026 05:07:08 +0000 (0:00:00.634) 0:00:30.498 ***** 2026-09-01 05:07:09.545629 | instance | changed: [instance] => (item=osd0) 2026-09-01 05:07:09.546033 | instance | changed: [instance] => (item=osd1) 2026-09-01 05:07:09.546342 | instance | changed: [instance] => (item=osd2) 2026-09-01 05:07:09.546653 | instance | 2026-09-01 05:07:09.546949 | instance | TASK [Start loop devices] ****************************************************** 2026-09-01 05:07:09.547247 | instance | Tuesday 01 September 2026 05:07:09 +0000 (0:00:00.697) 0:00:31.195 ***** 2026-09-01 05:07:10.306017 | instance | changed: [instance] => (item=osd0) 2026-09-01 05:07:10.307316 | instance | changed: [instance] => (item=osd1) 2026-09-01 05:07:10.307403 | instance | changed: [instance] => (item=osd2) 2026-09-01 05:07:10.307415 | instance | 2026-09-01 05:07:10.307426 | instance | TASK [Create a volume group for each loop device] ****************************** 2026-09-01 05:07:10.307437 | instance | Tuesday 01 September 2026 05:07:10 +0000 (0:00:00.760) 0:00:31.956 ***** 2026-09-01 05:07:13.508651 | instance | changed: [instance] => (item=osd0) 2026-09-01 05:07:13.509512 | instance | changed: [instance] => (item=osd1) 2026-09-01 05:07:13.509546 | instance | changed: [instance] => (item=osd2) 2026-09-01 05:07:13.509557 | instance | 2026-09-01 05:07:13.509679 | instance | TASK [Create a logical volume for each loop device] **************************** 2026-09-01 05:07:13.510023 | instance | Tuesday 01 September 2026 05:07:13 +0000 (0:00:03.202) 0:00:35.158 ***** 2026-09-01 05:07:15.450647 | instance | changed: [instance] => (item=ceph-instance-osd0) 2026-09-01 05:07:15.450915 | instance | changed: [instance] => (item=ceph-instance-osd1) 2026-09-01 05:07:15.451293 | instance | changed: [instance] => (item=ceph-instance-osd2) 2026-09-01 05:07:15.451676 | instance | 2026-09-01 05:07:15.452041 | instance | PLAY RECAP ********************************************************************* 2026-09-01 05:07:15.452468 | instance | instance : ok=15 changed=9 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-09-01 05:07:15.452720 | instance | 2026-09-01 05:07:15.452970 | instance | Tuesday 01 September 2026 05:07:15 +0000 (0:00:01.942) 0:00:37.101 ***** 2026-09-01 05:07:15.453208 | instance | =============================================================================== 2026-09-01 05:07:15.453514 | instance | Install depedencies ---------------------------------------------------- 23.89s 2026-09-01 05:07:15.453779 | instance | Create a volume group for each loop device ------------------------------ 3.20s 2026-09-01 05:07:15.454012 | instance | Create a logical volume for each loop device ---------------------------- 1.94s 2026-09-01 05:07:15.454252 | instance | Gathering Facts --------------------------------------------------------- 1.28s 2026-09-01 05:07:15.454524 | instance | Purge "snapd" package --------------------------------------------------- 0.86s 2026-09-01 05:07:15.454747 | instance | Gathering Facts --------------------------------------------------------- 0.80s 2026-09-01 05:07:15.454977 | instance | Start loop devices ------------------------------------------------------ 0.76s 2026-09-01 05:07:15.455290 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.75s 2026-09-01 05:07:15.455542 | instance | Configure short hostname ------------------------------------------------ 0.73s 2026-09-01 05:07:15.455790 | instance | Set permissions on loopback devices ------------------------------------- 0.70s 2026-09-01 05:07:15.456012 | instance | Create devices for Ceph ------------------------------------------------- 0.64s 2026-09-01 05:07:15.456242 | instance | Start up service -------------------------------------------------------- 0.60s 2026-09-01 05:07:15.456500 | instance | Generate lvm.conf ------------------------------------------------------- 0.35s 2026-09-01 05:07:15.456735 | instance | Ensure hostname inside hosts file --------------------------------------- 0.29s 2026-09-01 05:07:15.456970 | instance | Get list of all loopback devices ---------------------------------------- 0.26s 2026-09-01 05:07:15.457209 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s 2026-09-01 05:07:15.555772 | instance | INFO [csi > prepare] Executed: Successful 2026-09-01 05:07:15.566689 | instance | INFO [csi > converge] Executing 2026-09-01 05:07:17.071870 | instance | 2026-09-01 05:07:17.072061 | instance | PLAY [Debug CSI driver value] ************************************************** 2026-09-01 05:07:17.072437 | instance | 2026-09-01 05:07:17.072719 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-01 05:07:17.073081 | instance | Tuesday 01 September 2026 05:07:17 +0000 (0:00:00.007) 0:00:00.007 ***** 2026-09-01 05:07:18.129526 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-01 05:07:18.129745 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-01 05:07:18.129942 | instance | interpreter could change the meaning of that path. See 2026-09-01 05:07:18.130149 | instance | https://docs.ansible.com/ansible- 2026-09-01 05:07:18.130410 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-01 05:07:18.145567 | instance | ok: [instance] 2026-09-01 05:07:18.145700 | instance | 2026-09-01 05:07:18.145860 | instance | TASK [Display CSI driver value and environment variable] *********************** 2026-09-01 05:07:18.146014 | instance | Tuesday 01 September 2026 05:07:18 +0000 (0:00:01.073) 0:00:01.081 ***** 2026-09-01 05:07:18.208192 | instance | ok: [instance] => { 2026-09-01 05:07:18.208536 | instance | "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER=" 2026-09-01 05:07:18.208894 | instance | } 2026-09-01 05:07:18.209245 | instance | 2026-09-01 05:07:18.209749 | instance | PLAY [all] ********************************************************************* 2026-09-01 05:07:18.210145 | instance | 2026-09-01 05:07:18.210559 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-01 05:07:18.210911 | instance | Tuesday 01 September 2026 05:07:18 +0000 (0:00:00.062) 0:00:01.143 ***** 2026-09-01 05:07:19.142453 | instance | ok: [instance] 2026-09-01 05:07:19.142662 | instance | 2026-09-01 05:07:19.142908 | instance | TASK [Fail if atmosphere_ceph_enabled is set] ********************************** 2026-09-01 05:07:19.143147 | instance | Tuesday 01 September 2026 05:07:19 +0000 (0:00:00.934) 0:00:02.078 ***** 2026-09-01 05:07:19.177175 | instance | skipping: [instance] 2026-09-01 05:07:19.177404 | instance | 2026-09-01 05:07:19.177689 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] ***************** 2026-09-01 05:07:19.177943 | instance | Tuesday 01 September 2026 05:07:19 +0000 (0:00:00.035) 0:00:02.113 ***** 2026-09-01 05:07:19.358082 | instance | ok: [instance] 2026-09-01 05:07:19.358345 | instance | 2026-09-01 05:07:19.358620 | instance | PLAY [Deploy Ceph monitors & managers] ***************************************** 2026-09-01 05:07:19.358885 | instance | 2026-09-01 05:07:19.359155 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-01 05:07:19.359483 | instance | Tuesday 01 September 2026 05:07:19 +0000 (0:00:00.180) 0:00:02.293 ***** 2026-09-01 05:07:20.314740 | instance | ok: [instance] 2026-09-01 05:07:20.315183 | instance | 2026-09-01 05:07:20.316658 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-01 05:07:20.316685 | instance | Tuesday 01 September 2026 05:07:20 +0000 (0:00:00.955) 0:00:03.249 ***** 2026-09-01 05:07:20.648586 | instance | ok: [instance] 2026-09-01 05:07:20.648960 | instance | 2026-09-01 05:07:20.649305 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-01 05:07:20.649679 | instance | Tuesday 01 September 2026 05:07:20 +0000 (0:00:00.331) 0:00:03.580 ***** 2026-09-01 05:07:20.701936 | instance | skipping: [instance] 2026-09-01 05:07:20.702306 | instance | 2026-09-01 05:07:20.702608 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-09-01 05:07:20.702903 | instance | Tuesday 01 September 2026 05:07:20 +0000 (0:00:00.056) 0:00:03.637 ***** 2026-09-01 05:07:21.082974 | instance | changed: [instance] 2026-09-01 05:07:21.083252 | instance | 2026-09-01 05:07:21.083451 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-01 05:07:21.083641 | instance | Tuesday 01 September 2026 05:07:21 +0000 (0:00:00.380) 0:00:04.018 ***** 2026-09-01 05:07:21.166674 | instance | ok: [instance] => { 2026-09-01 05:07:21.167057 | instance | "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64" 2026-09-01 05:07:21.167415 | instance | } 2026-09-01 05:07:21.167698 | instance | 2026-09-01 05:07:21.167988 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-01 05:07:21.168325 | instance | Tuesday 01 September 2026 05:07:21 +0000 (0:00:00.083) 0:00:04.101 ***** 2026-09-01 05:07:21.837632 | instance | changed: [instance] 2026-09-01 05:07:21.837876 | instance | 2026-09-01 05:07:21.838078 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-01 05:07:21.838307 | instance | Tuesday 01 September 2026 05:07:21 +0000 (0:00:00.671) 0:00:04.773 ***** 2026-09-01 05:07:21.902644 | instance | skipping: [instance] 2026-09-01 05:07:21.902886 | instance | 2026-09-01 05:07:21.903091 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-01 05:07:21.903338 | instance | Tuesday 01 September 2026 05:07:21 +0000 (0:00:00.065) 0:00:04.838 ***** 2026-09-01 05:07:21.960907 | instance | skipping: [instance] 2026-09-01 05:07:21.961258 | instance | 2026-09-01 05:07:21.961645 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-01 05:07:21.961968 | instance | Tuesday 01 September 2026 05:07:21 +0000 (0:00:00.058) 0:00:04.896 ***** 2026-09-01 05:07:22.217635 | instance | ok: [instance] 2026-09-01 05:07:22.217768 | instance | 2026-09-01 05:07:22.218082 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-01 05:07:22.218377 | instance | Tuesday 01 September 2026 05:07:22 +0000 (0:00:00.256) 0:00:05.153 ***** 2026-09-01 05:07:23.648698 | instance | ok: [instance] 2026-09-01 05:07:23.648876 | instance | 2026-09-01 05:07:23.649123 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-01 05:07:23.649397 | instance | Tuesday 01 September 2026 05:07:23 +0000 (0:00:01.430) 0:00:06.583 ***** 2026-09-01 05:07:23.739582 | instance | ok: [instance] => { 2026-09-01 05:07:23.739883 | instance | "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz" 2026-09-01 05:07:23.740174 | instance | } 2026-09-01 05:07:23.740419 | instance | 2026-09-01 05:07:23.740657 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-01 05:07:23.740893 | instance | Tuesday 01 September 2026 05:07:23 +0000 (0:00:00.090) 0:00:06.674 ***** 2026-09-01 05:07:24.451593 | instance | changed: [instance] 2026-09-01 05:07:24.451785 | instance | 2026-09-01 05:07:24.452180 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-01 05:07:24.452475 | instance | Tuesday 01 September 2026 05:07:24 +0000 (0:00:00.712) 0:00:07.386 ***** 2026-09-01 05:07:27.427633 | instance | changed: [instance] 2026-09-01 05:07:27.427724 | instance | 2026-09-01 05:07:27.427835 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] *************** 2026-09-01 05:07:27.427964 | instance | Tuesday 01 September 2026 05:07:27 +0000 (0:00:02.976) 0:00:10.363 ***** 2026-09-01 05:07:27.466816 | instance | skipping: [instance] 2026-09-01 05:07:27.467277 | instance | 2026-09-01 05:07:27.467421 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] *** 2026-09-01 05:07:27.467560 | instance | Tuesday 01 September 2026 05:07:27 +0000 (0:00:00.039) 0:00:10.402 ***** 2026-09-01 05:07:27.507306 | instance | skipping: [instance] 2026-09-01 05:07:27.507415 | instance | 2026-09-01 05:07:27.507546 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ******** 2026-09-01 05:07:27.507678 | instance | Tuesday 01 September 2026 05:07:27 +0000 (0:00:00.040) 0:00:10.443 ***** 2026-09-01 05:07:27.539951 | instance | skipping: [instance] 2026-09-01 05:07:27.540051 | instance | 2026-09-01 05:07:27.540151 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] ************** 2026-09-01 05:07:27.540294 | instance | Tuesday 01 September 2026 05:07:27 +0000 (0:00:00.032) 0:00:10.476 ***** 2026-09-01 05:07:34.033045 | instance | changed: [instance] 2026-09-01 05:07:34.033314 | instance | 2026-09-01 05:07:34.033326 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] *** 2026-09-01 05:07:34.033337 | instance | Tuesday 01 September 2026 05:07:34 +0000 (0:00:06.491) 0:00:16.967 ***** 2026-09-01 05:07:34.723298 | instance | changed: [instance] 2026-09-01 05:07:34.724136 | instance | 2026-09-01 05:07:34.724211 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] ******* 2026-09-01 05:07:34.724223 | instance | Tuesday 01 September 2026 05:07:34 +0000 (0:00:00.690) 0:00:17.658 ***** 2026-09-01 05:07:35.767196 | instance | changed: [instance] => (item={'path': '/etc/containerd'}) 2026-09-01 05:07:35.767282 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'}) 2026-09-01 05:07:35.768120 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'}) 2026-09-01 05:07:35.768166 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'}) 2026-09-01 05:07:35.768173 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'}) 2026-09-01 05:07:35.768178 | instance | 2026-09-01 05:07:35.768183 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] ********** 2026-09-01 05:07:35.768187 | instance | Tuesday 01 September 2026 05:07:35 +0000 (0:00:01.044) 0:00:18.703 ***** 2026-09-01 05:07:36.436027 | instance | changed: [instance] 2026-09-01 05:07:36.436140 | instance | 2026-09-01 05:07:36.436903 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ******** 2026-09-01 05:07:36.436994 | instance | Tuesday 01 September 2026 05:07:36 +0000 (0:00:00.643) 0:00:19.346 ***** 2026-09-01 05:07:36.437002 | instance | 2026-09-01 05:07:36.437009 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-01 05:07:36.437015 | instance | Tuesday 01 September 2026 05:07:36 +0000 (0:00:00.025) 0:00:19.371 ***** 2026-09-01 05:07:37.459773 | instance | ok: [instance] 2026-09-01 05:07:37.460496 | instance | 2026-09-01 05:07:37.460527 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] ********** 2026-09-01 05:07:37.460539 | instance | Tuesday 01 September 2026 05:07:37 +0000 (0:00:01.023) 0:00:20.395 ***** 2026-09-01 05:07:37.988646 | instance | changed: [instance] 2026-09-01 05:07:37.988779 | instance | 2026-09-01 05:07:37.989405 | instance | TASK [vexxhost.containers.containerd : Enable and start service] *************** 2026-09-01 05:07:37.989502 | instance | Tuesday 01 September 2026 05:07:37 +0000 (0:00:00.529) 0:00:20.924 ***** 2026-09-01 05:07:38.686244 | instance | changed: [instance] 2026-09-01 05:07:38.686337 | instance | 2026-09-01 05:07:38.686671 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-01 05:07:38.686726 | instance | Tuesday 01 September 2026 05:07:38 +0000 (0:00:00.698) 0:00:21.622 ***** 2026-09-01 05:07:38.962651 | instance | ok: [instance] 2026-09-01 05:07:38.962759 | instance | 2026-09-01 05:07:38.962833 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-01 05:07:38.963013 | instance | Tuesday 01 September 2026 05:07:38 +0000 (0:00:00.276) 0:00:21.898 ***** 2026-09-01 05:07:39.033144 | instance | ok: [instance] => { 2026-09-01 05:07:39.033253 | instance | "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz" 2026-09-01 05:07:39.033724 | instance | } 2026-09-01 05:07:39.033793 | instance | 2026-09-01 05:07:39.033805 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-01 05:07:39.033823 | instance | Tuesday 01 September 2026 05:07:39 +0000 (0:00:00.070) 0:00:21.969 ***** 2026-09-01 05:07:39.833494 | instance | changed: [instance] 2026-09-01 05:07:39.834455 | instance | 2026-09-01 05:07:39.834530 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-01 05:07:39.834542 | instance | Tuesday 01 September 2026 05:07:39 +0000 (0:00:00.799) 0:00:22.768 ***** 2026-09-01 05:07:44.205916 | instance | changed: [instance] 2026-09-01 05:07:44.206002 | instance | 2026-09-01 05:07:44.206380 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ****************** 2026-09-01 05:07:44.206439 | instance | Tuesday 01 September 2026 05:07:44 +0000 (0:00:04.372) 0:00:27.141 ***** 2026-09-01 05:07:45.441513 | instance | ok: [instance] 2026-09-01 05:07:45.441612 | instance | 2026-09-01 05:07:45.441626 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] *************** 2026-09-01 05:07:45.441798 | instance | Tuesday 01 September 2026 05:07:45 +0000 (0:00:01.235) 0:00:28.377 ***** 2026-09-01 05:07:45.812543 | instance | changed: [instance] 2026-09-01 05:07:45.813559 | instance | 2026-09-01 05:07:45.813633 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] ***** 2026-09-01 05:07:45.813639 | instance | Tuesday 01 September 2026 05:07:45 +0000 (0:00:00.370) 0:00:28.748 ***** 2026-09-01 05:07:46.276368 | instance | changed: [instance] 2026-09-01 05:07:46.276458 | instance | 2026-09-01 05:07:46.276840 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] *********** 2026-09-01 05:07:46.276896 | instance | Tuesday 01 September 2026 05:07:46 +0000 (0:00:00.464) 0:00:29.212 ***** 2026-09-01 05:07:46.929931 | instance | changed: [instance] => (item={'path': '/etc/docker'}) 2026-09-01 05:07:46.930008 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'}) 2026-09-01 05:07:46.930511 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'}) 2026-09-01 05:07:46.930565 | instance | 2026-09-01 05:07:46.930572 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ****** 2026-09-01 05:07:46.930577 | instance | Tuesday 01 September 2026 05:07:46 +0000 (0:00:00.653) 0:00:29.865 ***** 2026-09-01 05:07:47.454513 | instance | changed: [instance] 2026-09-01 05:07:47.455219 | instance | 2026-09-01 05:07:47.455481 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] *********** 2026-09-01 05:07:47.455494 | instance | Tuesday 01 September 2026 05:07:47 +0000 (0:00:00.524) 0:00:30.389 ***** 2026-09-01 05:07:48.014602 | instance | changed: [instance] 2026-09-01 05:07:48.015503 | instance | 2026-09-01 05:07:48.015597 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************ 2026-09-01 05:07:48.015633 | instance | Tuesday 01 September 2026 05:07:47 +0000 (0:00:00.530) 0:00:30.920 ***** 2026-09-01 05:07:48.015654 | instance | 2026-09-01 05:07:48.015999 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-01 05:07:48.016079 | instance | Tuesday 01 September 2026 05:07:48 +0000 (0:00:00.029) 0:00:30.950 ***** 2026-09-01 05:07:48.805489 | instance | ok: [instance] 2026-09-01 05:07:48.806241 | instance | 2026-09-01 05:07:48.806310 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ****************** 2026-09-01 05:07:48.806322 | instance | Tuesday 01 September 2026 05:07:48 +0000 (0:00:00.790) 0:00:31.740 ***** 2026-09-01 05:07:49.886850 | instance | changed: [instance] 2026-09-01 05:07:49.886940 | instance | 2026-09-01 05:07:49.887315 | instance | TASK [vexxhost.containers.docker : Enable and start service] ******************* 2026-09-01 05:07:49.887378 | instance | Tuesday 01 September 2026 05:07:49 +0000 (0:00:01.082) 0:00:32.822 ***** 2026-09-01 05:07:50.466430 | instance | changed: [instance] 2026-09-01 05:07:50.466500 | instance | 2026-09-01 05:07:50.466922 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ****** 2026-09-01 05:07:50.466981 | instance | Tuesday 01 September 2026 05:07:50 +0000 (0:00:00.579) 0:00:33.402 ***** 2026-09-01 05:07:50.539139 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml) 2026-09-01 05:07:50.539193 | instance | 2026-09-01 05:07:50.539478 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ******************************** 2026-09-01 05:07:50.539540 | instance | Tuesday 01 September 2026 05:07:50 +0000 (0:00:00.072) 0:00:33.475 ***** 2026-09-01 05:07:56.212089 | instance | changed: [instance] 2026-09-01 05:07:56.212886 | instance | 2026-09-01 05:07:56.212967 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] ********************* 2026-09-01 05:07:56.212979 | instance | Tuesday 01 September 2026 05:07:56 +0000 (0:00:05.671) 0:00:39.146 ***** 2026-09-01 05:07:56.902365 | instance | ok: [instance] => (item=chronyd) 2026-09-01 05:07:56.902458 | instance | ok: [instance] => (item=sshd) 2026-09-01 05:07:56.902794 | instance | 2026-09-01 05:07:56.902854 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ****************************** 2026-09-01 05:07:56.902860 | instance | Tuesday 01 September 2026 05:07:56 +0000 (0:00:00.691) 0:00:39.838 ***** 2026-09-01 05:07:57.259597 | instance | changed: [instance] 2026-09-01 05:07:57.259849 | instance | 2026-09-01 05:07:57.260507 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ******************** 2026-09-01 05:07:57.260619 | instance | Tuesday 01 September 2026 05:07:57 +0000 (0:00:00.355) 0:00:40.194 ***** 2026-09-01 05:07:57.499234 | instance | ok: [instance] 2026-09-01 05:07:57.500034 | instance | 2026-09-01 05:07:57.500106 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] **************** 2026-09-01 05:07:57.500119 | instance | Tuesday 01 September 2026 05:07:57 +0000 (0:00:00.239) 0:00:40.434 ***** 2026-09-01 05:07:58.026512 | instance | changed: [instance] 2026-09-01 05:07:58.027236 | instance | 2026-09-01 05:07:58.027290 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] *** 2026-09-01 05:07:58.027302 | instance | Tuesday 01 September 2026 05:07:58 +0000 (0:00:00.527) 0:00:40.961 ***** 2026-09-01 05:07:58.424896 | instance | changed: [instance] 2026-09-01 05:07:58.424963 | instance | 2026-09-01 05:07:58.425357 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] ************************* 2026-09-01 05:07:58.425414 | instance | Tuesday 01 September 2026 05:07:58 +0000 (0:00:00.397) 0:00:41.359 ***** 2026-09-01 05:07:58.546595 | instance | ok: [instance] 2026-09-01 05:07:58.547331 | instance | 2026-09-01 05:07:58.547363 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] ******************* 2026-09-01 05:07:58.547375 | instance | Tuesday 01 September 2026 05:07:58 +0000 (0:00:00.121) 0:00:41.481 ***** 2026-09-01 05:07:58.827921 | instance | ok: [instance] => (item=instance) 2026-09-01 05:07:58.828646 | instance | 2026-09-01 05:07:58.828706 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ******** 2026-09-01 05:07:58.828718 | instance | Tuesday 01 September 2026 05:07:58 +0000 (0:00:00.281) 0:00:41.763 ***** 2026-09-01 05:07:58.877205 | instance | ok: [instance] 2026-09-01 05:07:58.877300 | instance | 2026-09-01 05:07:58.877517 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] *********************************** 2026-09-01 05:07:58.877590 | instance | Tuesday 01 September 2026 05:07:58 +0000 (0:00:00.049) 0:00:41.813 ***** 2026-09-01 05:07:58.946809 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance 2026-09-01 05:07:58.946870 | instance | 2026-09-01 05:07:58.947125 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] ************* 2026-09-01 05:07:58.947144 | instance | Tuesday 01 September 2026 05:07:58 +0000 (0:00:00.069) 0:00:41.882 ***** 2026-09-01 05:07:59.304870 | instance | changed: [instance] 2026-09-01 05:07:59.304935 | instance | 2026-09-01 05:07:59.304947 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ****************** 2026-09-01 05:07:59.304958 | instance | Tuesday 01 September 2026 05:07:59 +0000 (0:00:00.356) 0:00:42.238 ***** 2026-09-01 05:08:00.107953 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True}) 2026-09-01 05:08:00.108050 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0}) 2026-09-01 05:08:00.108734 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False}) 2026-09-01 05:08:00.108786 | instance | 2026-09-01 05:08:00.108795 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] ******************************* 2026-09-01 05:08:00.108802 | instance | Tuesday 01 September 2026 05:08:00 +0000 (0:00:00.804) 0:00:43.043 ***** 2026-09-01 05:09:35.224778 | instance | ok: [instance] 2026-09-01 05:09:35.225506 | instance | 2026-09-01 05:09:35.225540 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] *************** 2026-09-01 05:09:35.225801 | instance | Tuesday 01 September 2026 05:09:35 +0000 (0:01:35.116) 0:02:18.160 ***** 2026-09-01 05:09:35.519426 | instance | changed: [instance] 2026-09-01 05:09:35.519494 | instance | 2026-09-01 05:09:35.519506 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] ********************************** 2026-09-01 05:09:35.519516 | instance | Tuesday 01 September 2026 05:09:35 +0000 (0:00:00.293) 0:02:18.453 ***** 2026-09-01 05:09:35.574669 | instance | ok: [instance] 2026-09-01 05:09:35.574716 | instance | 2026-09-01 05:09:35.574729 | instance | TASK [Install Ceph host] ******************************************************* 2026-09-01 05:09:35.574741 | instance | Tuesday 01 September 2026 05:09:35 +0000 (0:00:00.055) 0:02:18.509 ***** 2026-09-01 05:09:35.699478 | instance | included: vexxhost.ceph.cephadm_host for instance 2026-09-01 05:09:35.699570 | instance | 2026-09-01 05:09:35.699582 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ****** 2026-09-01 05:09:35.699592 | instance | Tuesday 01 September 2026 05:09:35 +0000 (0:00:00.125) 0:02:18.634 ***** 2026-09-01 05:09:37.458616 | instance | ok: [instance] 2026-09-01 05:09:37.458670 | instance | 2026-09-01 05:09:37.458698 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] *** 2026-09-01 05:09:37.458817 | instance | Tuesday 01 September 2026 05:09:37 +0000 (0:00:01.759) 0:02:20.394 ***** 2026-09-01 05:09:37.528817 | instance | ok: [instance] => (item=instance) 2026-09-01 05:09:37.529022 | instance | 2026-09-01 05:09:37.529261 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] *********** 2026-09-01 05:09:37.529532 | instance | Tuesday 01 September 2026 05:09:37 +0000 (0:00:00.070) 0:02:20.464 ***** 2026-09-01 05:09:37.926377 | instance | ok: [instance] 2026-09-01 05:09:37.928457 | instance | 2026-09-01 05:09:37.928470 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] *********************** 2026-09-01 05:09:37.928476 | instance | Tuesday 01 September 2026 05:09:37 +0000 (0:00:00.396) 0:02:20.861 ***** 2026-09-01 05:09:40.676882 | instance | ok: [instance] 2026-09-01 05:09:40.677095 | instance | 2026-09-01 05:09:40.677406 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ****************** 2026-09-01 05:09:40.677714 | instance | Tuesday 01 September 2026 05:09:40 +0000 (0:00:02.750) 0:02:23.612 ***** 2026-09-01 05:10:29.838130 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster) 2026-09-01 05:10:29.838274 | 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_1s8cbihf/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_1s8cbihf/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_1s8cbihf/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_1s8cbihf/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-01T05:10:29.633+0000 7fa7f3c58640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDkXZZq9FLMNSAAQrcyDuzib08XYtd+wZGTn/8RhUVYqLm8VhfMeKg44Zg=: Malformed input [buffer:3]\n2026-09-01T05:10:29.633+0000 7fa7f3c58640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-01T05:10:29.633+0000 7fa7f3c58640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDkXZZq9FLMNSAAQrcyDuzib08XYtd+wZGTn/8RhUVYqLm8VhfMeKg44Zg=: Malformed input [buffer:3]\n2026-09-01T05:10:29.633+0000 7fa7f3c58640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-01T05:10:29.633+0000 7fa7f3c58640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDkXZZq9FLMNSAAQrcyDuzib08XYtd+wZGTn/8RhUVYqLm8VhfMeKg44Zg=: Malformed input [buffer:3]\n2026-09-01T05:10:29.633+0000 7fa7f3c58640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-01T05:10:29.633+0000 7fa7f3c58640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1} 2026-09-01 05:10:29.838293 | instance | 2026-09-01 05:10:29.838462 | instance | PLAY RECAP ********************************************************************* 2026-09-01 05:10:29.838617 | instance | instance : ok=55 changed=26 unreachable=0 failed=1 skipped=7 rescued=0 ignored=0 2026-09-01 05:10:29.838755 | instance | 2026-09-01 05:10:29.838900 | instance | Tuesday 01 September 2026 05:10:29 +0000 (0:00:49.161) 0:03:12.774 ***** 2026-09-01 05:10:29.839106 | instance | =============================================================================== 2026-09-01 05:10:29.839365 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 95.12s 2026-09-01 05:10:29.839593 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 49.16s 2026-09-01 05:10:29.839823 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 6.49s 2026-09-01 05:10:29.840127 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.67s 2026-09-01 05:10:29.840301 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.37s 2026-09-01 05:10:29.840525 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.98s 2026-09-01 05:10:29.840755 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.75s 2026-09-01 05:10:29.840978 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.81s 2026-09-01 05:10:29.841276 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.76s 2026-09-01 05:10:29.841604 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.43s 2026-09-01 05:10:29.841843 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.24s 2026-09-01 05:10:29.842082 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 1.08s 2026-09-01 05:10:29.842357 | instance | Gathering Facts --------------------------------------------------------- 1.07s 2026-09-01 05:10:29.842572 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.04s 2026-09-01 05:10:29.842785 | instance | Gathering Facts --------------------------------------------------------- 0.96s 2026-09-01 05:10:29.843002 | instance | Gathering Facts --------------------------------------------------------- 0.93s 2026-09-01 05:10:29.843288 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.80s 2026-09-01 05:10:29.843544 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.80s 2026-09-01 05:10:29.843785 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.71s 2026-09-01 05:10:29.844106 | instance | vexxhost.containers.containerd : Enable and start service --------------- 0.70s 2026-09-01 05:10:29.944432 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-01 05:10:29.944532 | instance | ERROR [csi > converge] Executed: Failed 2026-09-01 05:10:29.944624 | instance | ERROR Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-01 05:10:30.449499 | instance | ERROR 2026-09-01 05:10:30.449765 | instance | { 2026-09-01 05:10:30.449796 | instance | "delta": "0:04:06.090980", 2026-09-01 05:10:30.449817 | instance | "end": "2026-09-01 05:10:30.022640", 2026-09-01 05:10:30.449836 | instance | "msg": "non-zero return code", 2026-09-01 05:10:30.449854 | instance | "rc": 2, 2026-09-01 05:10:30.449871 | instance | "start": "2026-09-01 05:06:23.931660" 2026-09-01 05:10:30.449889 | instance | } failure 2026-09-01 05:10:30.455889 | 2026-09-01 05:10:30.455934 | PLAY RECAP 2026-09-01 05:10:30.456008 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-01 05:10:30.456033 | 2026-09-01 05:10:30.587412 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-01 05:10:30.590061 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-01 05:10:31.203340 | 2026-09-01 05:10:31.203469 | PLAY [all] 2026-09-01 05:10:31.217282 | 2026-09-01 05:10:31.217360 | TASK [gather-host-logs : creating directory for system status] 2026-09-01 05:10:31.603603 | instance | changed 2026-09-01 05:10:31.610494 | 2026-09-01 05:10:31.610598 | TASK [gather-host-logs : Get logs for each host] 2026-09-01 05:10:31.995326 | instance | + systemd-cgls --full --all --no-pager 2026-09-01 05:10:32.011785 | instance | + ip addr 2026-09-01 05:10:32.015422 | instance | + ip route 2026-09-01 05:10:32.017903 | instance | + lsblk 2026-09-01 05:10:32.023453 | instance | + mount 2026-09-01 05:10:32.026805 | instance | + docker images 2026-09-01 05:10:32.053232 | instance | + brctl show 2026-09-01 05:10:32.053941 | instance | /bin/bash: line 8: brctl: command not found 2026-09-01 05:10:32.054270 | instance | + ps aux --sort=-%mem 2026-09-01 05:10:32.095487 | instance | + dpkg -l 2026-09-01 05:10:32.104762 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-09-01 05:10:32.105255 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-09-01 05:10:32.127941 | instance | + '[' '!' -z '' ']' 2026-09-01 05:10:32.176136 | instance | ok: Runtime: 0:00:00.137889 2026-09-01 05:10:32.182079 | 2026-09-01 05:10:32.182145 | TASK [gather-host-logs : Downloads logs to executor] 2026-09-01 05:10:32.848670 | instance | changed: 2026-09-01 05:10:32.848832 | instance | created directory /var/lib/zuul/builds/e5a9e3696bcf4831a9f9e228745c1454/work/logs/instance 2026-09-01 05:10:32.848871 | instance | cd+++++++++ system/ 2026-09-01 05:10:32.848902 | instance | >f+++++++++ system/brctl-show.txt 2026-09-01 05:10:32.848931 | instance | >f+++++++++ system/docker-images.txt 2026-09-01 05:10:32.848985 | instance | >f+++++++++ system/ip-addr.txt 2026-09-01 05:10:32.849020 | instance | >f+++++++++ system/ip-route.txt 2026-09-01 05:10:32.849050 | instance | >f+++++++++ system/lsblk.txt 2026-09-01 05:10:32.849078 | instance | >f+++++++++ system/mount.txt 2026-09-01 05:10:32.849106 | instance | >f+++++++++ system/packages.txt 2026-09-01 05:10:32.849133 | instance | >f+++++++++ system/ps.txt 2026-09-01 05:10:32.849163 | instance | >f+++++++++ system/systemd-cgls.txt 2026-09-01 05:10:32.860906 | 2026-09-01 05:10:32.860988 | LOOP [helm-release-status : creating directory for helm release status] 2026-09-01 05:10:33.099536 | instance | changed: "values" 2026-09-01 05:10:33.291440 | instance | changed: "releases" 2026-09-01 05:10:33.310032 | 2026-09-01 05:10:33.310181 | TASK [helm-release-status : Gather get release status for helm charts] 2026-09-01 05:10:33.559165 | instance | /bin/bash: line 3: kubectl: command not found 2026-09-01 05:10:33.850331 | instance | ok: Runtime: 0:00:00.006805 2026-09-01 05:10:33.857801 | 2026-09-01 05:10:33.857900 | TASK [helm-release-status : Downloads logs to executor] 2026-09-01 05:10:34.409818 | instance | changed: 2026-09-01 05:10:34.410050 | instance | cd+++++++++ helm/ 2026-09-01 05:10:34.410090 | instance | cd+++++++++ helm/releases/ 2026-09-01 05:10:34.410121 | instance | cd+++++++++ helm/values/ 2026-09-01 05:10:34.422252 | 2026-09-01 05:10:34.422350 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-09-01 05:10:34.637005 | instance | changed 2026-09-01 05:10:34.644799 | 2026-09-01 05:10:34.644879 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-09-01 05:10:34.902013 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-01 05:10:34.902410 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-01 05:10:34.908998 | instance | environment: line 1: kubectl: command not found 2026-09-01 05:10:34.910024 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-01 05:10:34.910457 | instance | environment: line 1: kubectl: command not found 2026-09-01 05:10:34.911334 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-01 05:10:34.912985 | instance | environment: line 1: kubectl: command not found 2026-09-01 05:10:34.913635 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-01 05:10:34.917272 | instance | environment: line 1: kubectl: command not found 2026-09-01 05:10:34.917870 | instance | environment: line 1: kubectl: command not found 2026-09-01 05:10:34.918816 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-01 05:10:34.919865 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-01 05:10:35.182017 | instance | ok: Runtime: 0:00:00.026107 2026-09-01 05:10:35.188559 | 2026-09-01 05:10:35.188649 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-09-01 05:10:35.427480 | instance | changed 2026-09-01 05:10:35.432353 | 2026-09-01 05:10:35.432422 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-09-01 05:10:35.654265 | instance | environment: line 5: kubectl: command not found 2026-09-01 05:10:35.654982 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-01 05:10:35.655104 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-01 05:10:35.655683 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-01 05:10:35.968423 | instance | ok: Runtime: 0:00:00.010591 2026-09-01 05:10:35.975530 | 2026-09-01 05:10:35.975627 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-09-01 05:10:36.458416 | instance | changed: 2026-09-01 05:10:36.458631 | instance | cd+++++++++ objects/ 2026-09-01 05:10:36.458658 | instance | cd+++++++++ objects/cluster/ 2026-09-01 05:10:36.458679 | instance | cd+++++++++ objects/namespaced/ 2026-09-01 05:10:36.467704 | 2026-09-01 05:10:36.467771 | TASK [gather-pod-logs : creating directory for pod logs] 2026-09-01 05:10:36.694936 | instance | changed 2026-09-01 05:10:36.701886 | 2026-09-01 05:10:36.701976 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-09-01 05:10:36.931954 | instance | changed 2026-09-01 05:10:36.936845 | 2026-09-01 05:10:36.936916 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-09-01 05:10:37.159844 | instance | environment: line 3: kubectl: command not found 2026-09-01 05:10:37.472481 | instance | ok: Runtime: 0:00:00.008909 2026-09-01 05:10:37.478378 | 2026-09-01 05:10:37.478447 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-09-01 05:10:37.961109 | instance | changed: 2026-09-01 05:10:37.961402 | instance | cd+++++++++ pod-logs/ 2026-09-01 05:10:37.961505 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-09-01 05:10:37.971590 | 2026-09-01 05:10:37.971656 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-09-01 05:10:38.216606 | instance | changed 2026-09-01 05:10:38.223336 | 2026-09-01 05:10:38.223423 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-09-01 05:10:38.457653 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-01 05:10:38.770617 | instance | ok: Runtime: 0:00:00.043941 2026-09-01 05:10:38.775350 | 2026-09-01 05:10:38.775415 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-09-01 05:10:39.000868 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-01 05:10:39.047326 | instance | ceph-mgr endpoints: 2026-09-01 05:10:39.308630 | instance | ok: Runtime: 0:00:00.052740 2026-09-01 05:10:39.313760 | 2026-09-01 05:10:39.313828 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-09-01 05:10:39.549301 | instance | /bin/bash: line 4: kubectl: command not found 2026-09-01 05:10:39.858081 | instance | ok: Runtime: 0:00:00.034679 2026-09-01 05:10:39.865630 | 2026-09-01 05:10:39.865733 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-09-01 05:10:40.346723 | instance | changed: cd+++++++++ prometheus/ 2026-09-01 05:10:40.356808 | 2026-09-01 05:10:40.356879 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-09-01 05:10:40.566065 | instance | changed 2026-09-01 05:10:40.571179 | 2026-09-01 05:10:40.571258 | TASK [gather-selenium-data : Get selenium data] 2026-09-01 05:10:40.816498 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-09-01 05:10:40.817307 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-09-01 05:10:41.104272 | instance | ERROR 2026-09-01 05:10:41.104500 | instance | { 2026-09-01 05:10:41.104532 | instance | "delta": "0:00:00.004786", 2026-09-01 05:10:41.104554 | instance | "end": "2026-09-01 05:10:40.817631", 2026-09-01 05:10:41.104575 | instance | "msg": "non-zero return code", 2026-09-01 05:10:41.104594 | instance | "rc": 1, 2026-09-01 05:10:41.104613 | instance | "start": "2026-09-01 05:10:40.812845" 2026-09-01 05:10:41.104631 | instance | } 2026-09-01 05:10:41.104654 | instance | ERROR: Ignoring Errors 2026-09-01 05:10:41.109799 | 2026-09-01 05:10:41.109866 | TASK [gather-selenium-data : Downloads logs to executor] 2026-09-01 05:10:41.632064 | instance | changed: cd+++++++++ selenium/ 2026-09-01 05:10:41.638710 | 2026-09-01 05:10:41.638762 | PLAY RECAP 2026-09-01 05:10:41.638814 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-09-01 05:10:41.638835 | 2026-09-01 05:10:41.795239 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-01 05:10:41.799617 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-01 05:10:42.428167 | 2026-09-01 05:10:42.428321 | PLAY [all] 2026-09-01 05:10:42.440804 | 2026-09-01 05:10:42.440884 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-01 05:10:42.478497 | instance | skipping: Conditional result was False 2026-09-01 05:10:42.488902 | 2026-09-01 05:10:42.489004 | TASK [fetch-output : Set log path for single node] 2026-09-01 05:10:42.531743 | instance | ok 2026-09-01 05:10:42.536752 | 2026-09-01 05:10:42.536816 | LOOP [fetch-output : Ensure local output dirs] 2026-09-01 05:10:42.911458 | instance -> localhost | ok: "/var/lib/zuul/builds/e5a9e3696bcf4831a9f9e228745c1454/work/logs" 2026-09-01 05:10:43.108867 | instance -> localhost | changed: "/var/lib/zuul/builds/e5a9e3696bcf4831a9f9e228745c1454/work/artifacts" 2026-09-01 05:10:43.306087 | instance -> localhost | changed: "/var/lib/zuul/builds/e5a9e3696bcf4831a9f9e228745c1454/work/docs" 2026-09-01 05:10:43.319428 | 2026-09-01 05:10:43.319559 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-01 05:10:43.965310 | instance | changed: .d..t...... ./ 2026-09-01 05:10:43.965556 | instance | changed: All items complete 2026-09-01 05:10:43.965598 | 2026-09-01 05:10:44.432924 | instance | changed: .d..t...... ./ 2026-09-01 05:10:44.894843 | instance | changed: .d..t...... ./ 2026-09-01 05:10:44.922634 | 2026-09-01 05:10:44.923029 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-01 05:10:45.355681 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.006785 2026-09-01 05:10:45.562454 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007941 2026-09-01 05:10:45.589125 | 2026-09-01 05:10:45.589268 | PLAY [all] 2026-09-01 05:10:45.595508 | 2026-09-01 05:10:45.595585 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-01 05:10:46.025354 | instance | changed 2026-09-01 05:10:46.033001 | 2026-09-01 05:10:46.033076 | PLAY RECAP 2026-09-01 05:10:46.033139 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-01 05:10:46.033170 | 2026-09-01 05:10:46.176672 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-01 05:10:46.178945 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-01 05:10:46.774175 | 2026-09-01 05:10:46.774325 | PLAY [localhost] 2026-09-01 05:10:46.784160 | 2026-09-01 05:10:46.784233 | TASK [Generate Zuul manifest] 2026-09-01 05:10:46.808230 | localhost | ok 2026-09-01 05:10:46.834120 | 2026-09-01 05:10:46.834344 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-01 05:10:47.209002 | localhost | changed 2026-09-01 05:10:47.224460 | 2026-09-01 05:10:47.224548 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-01 05:10:47.259605 | localhost | ok 2026-09-01 05:10:47.270069 | 2026-09-01 05:10:47.270156 | TASK [Upload logs] 2026-09-01 05:10:47.294633 | localhost | ok 2026-09-01 05:10:47.354547 | 2026-09-01 05:10:47.354688 | TASK [Set zuul-log-path fact] 2026-09-01 05:10:47.376646 | localhost | ok 2026-09-01 05:10:47.392695 | 2026-09-01 05:10:47.392764 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-01 05:10:47.424149 | localhost | ok 2026-09-01 05:10:47.431877 | 2026-09-01 05:10:47.431944 | TASK [upload-logs : Create log directories] 2026-09-01 05:10:47.838134 | localhost | changed 2026-09-01 05:10:47.844863 | 2026-09-01 05:10:47.845039 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-01 05:10:48.240999 | localhost -> localhost | ok: Runtime: 0:00:00.006310 2026-09-01 05:10:48.246423 | 2026-09-01 05:10:48.246489 | TASK [upload-logs : Upload logs to log server] 2026-09-01 05:10:48.714695 | localhost | Output suppressed because no_log was given 2026-09-01 05:10:48.763734 | 2026-09-01 05:10:48.763883 | LOOP [upload-logs : Compress console log and json output] 2026-09-01 05:10:48.808337 | localhost | skipping: Conditional result was False 2026-09-01 05:10:48.815405 | localhost | skipping: Conditional result was False 2026-09-01 05:10:48.827208 | 2026-09-01 05:10:48.827343 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-01 05:10:48.868590 | localhost | skipping: Conditional result was False 2026-09-01 05:10:48.869022 | 2026-09-01 05:10:48.872661 | localhost | skipping: Conditional result was False 2026-09-01 05:10:48.880110 | 2026-09-01 05:10:48.880237 | LOOP [upload-logs : Upload console log and json output]