2026-09-10 01:45:20.419907 | Job console starting 2026-09-10 01:45:20.440738 | Updating git repos 2026-09-10 01:45:23.073972 | Cloning repos into workspace 2026-09-10 01:45:39.178571 | Restoring repo states 2026-09-10 01:45:47.803654 | Merging changes 2026-09-10 01:45:48.880917 | Checking out repos 2026-09-10 01:45:51.515943 | Preparing playbooks 2026-09-10 01:45:54.106414 | Running Ansible setup 2026-09-10 01:45:58.874659 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-10 01:45:59.436342 | 2026-09-10 01:45:59.436507 | PLAY [localhost] 2026-09-10 01:45:59.443700 | 2026-09-10 01:45:59.443768 | TASK [Gathering Facts] 2026-09-10 01:46:00.361752 | localhost | ok 2026-09-10 01:46:00.377543 | 2026-09-10 01:46:00.377771 | TASK [Setup log path fact] 2026-09-10 01:46:00.399686 | localhost | ok 2026-09-10 01:46:00.419701 | 2026-09-10 01:46:00.419841 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-10 01:46:00.459863 | localhost | ok 2026-09-10 01:46:00.472160 | 2026-09-10 01:46:00.472359 | TASK [emit-job-header : Print job information] 2026-09-10 01:46:00.524828 | # Job Information 2026-09-10 01:46:00.525060 | Ansible Version: 2.16.18 2026-09-10 01:46:00.525123 | Job: atmosphere-molecule-csi-rbd 2026-09-10 01:46:00.525165 | Pipeline: check 2026-09-10 01:46:00.525203 | Executor: 2d72f0692154 2026-09-10 01:46:00.525241 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3501 2026-09-10 01:46:00.525285 | Event ID: 867524a0-acb8-11f1-98a0-a78c2c93c11d 2026-09-10 01:46:00.534037 | 2026-09-10 01:46:00.534175 | LOOP [emit-job-header : Print node information] 2026-09-10 01:46:00.713414 | localhost | ok: 2026-09-10 01:46:00.713631 | localhost | # Node Information 2026-09-10 01:46:00.713663 | localhost | Inventory Hostname: instance 2026-09-10 01:46:00.713688 | localhost | Hostname: np0000192433 2026-09-10 01:46:00.713712 | localhost | Username: zuul 2026-09-10 01:46:00.713739 | localhost | Distro: Ubuntu 22.04 2026-09-10 01:46:00.713762 | localhost | Provider: yul1 2026-09-10 01:46:00.713788 | localhost | Region: ca-ymq-1 2026-09-10 01:46:00.713811 | localhost | Label: ubuntu-jammy 2026-09-10 01:46:00.713834 | localhost | Product Name: OpenStack Nova 2026-09-10 01:46:00.713857 | localhost | Interface IP: 162.253.55.181 2026-09-10 01:46:00.720151 | 2026-09-10 01:46:00.720217 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-10 01:46:01.235118 | localhost -> localhost | changed 2026-09-10 01:46:01.241479 | 2026-09-10 01:46:01.241577 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-10 01:46:02.549691 | localhost -> localhost | changed 2026-09-10 01:46:02.557175 | 2026-09-10 01:46:02.557245 | PLAY [all] 2026-09-10 01:46:02.569891 | 2026-09-10 01:46:02.570081 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-10 01:46:02.788948 | instance -> localhost | ok 2026-09-10 01:46:02.794274 | 2026-09-10 01:46:02.794350 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-10 01:46:02.821011 | instance | ok 2026-09-10 01:46:02.864769 | instance | included: /var/lib/zuul/builds/6f7058b7a697492d9a66e887635f477c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-10 01:46:02.873406 | 2026-09-10 01:46:02.873516 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-10 01:46:03.859620 | instance -> localhost | Generating public/private rsa key pair. 2026-09-10 01:46:03.860172 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/6f7058b7a697492d9a66e887635f477c/work/6f7058b7a697492d9a66e887635f477c_id_rsa 2026-09-10 01:46:03.860207 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/6f7058b7a697492d9a66e887635f477c/work/6f7058b7a697492d9a66e887635f477c_id_rsa.pub 2026-09-10 01:46:03.860228 | instance -> localhost | The key fingerprint is: 2026-09-10 01:46:03.860247 | instance -> localhost | SHA256:gztIVc2I49kB6zZBR6o6ISmUU7hjINodXM0Jus0wiKg zuul-build-sshkey 2026-09-10 01:46:03.860274 | instance -> localhost | The key's randomart image is: 2026-09-10 01:46:03.860293 | instance -> localhost | +---[RSA 3072]----+ 2026-09-10 01:46:03.860319 | instance -> localhost | | .o .=B+= | 2026-09-10 01:46:03.860338 | instance -> localhost | |+oo.ooo=* o | 2026-09-10 01:46:03.860356 | instance -> localhost | |*=o.+o=+ . | 2026-09-10 01:46:03.860374 | instance -> localhost | |+=o .Xoo. | 2026-09-10 01:46:03.860408 | instance -> localhost | |E o + B S | 2026-09-10 01:46:03.860432 | instance -> localhost | |.. + o o . | 2026-09-10 01:46:03.860481 | instance -> localhost | | o . o | 2026-09-10 01:46:03.860535 | instance -> localhost | | . . | 2026-09-10 01:46:03.860557 | instance -> localhost | | | 2026-09-10 01:46:03.860575 | instance -> localhost | +----[SHA256]-----+ 2026-09-10 01:46:03.860620 | instance -> localhost | ok: Runtime: 0:00:00.419180 2026-09-10 01:46:03.869537 | 2026-09-10 01:46:03.869815 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-10 01:46:03.902063 | instance | ok 2026-09-10 01:46:03.914951 | instance | included: /var/lib/zuul/builds/6f7058b7a697492d9a66e887635f477c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-10 01:46:03.929479 | 2026-09-10 01:46:03.929569 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-10 01:46:03.946926 | instance | skipping: Conditional result was False 2026-09-10 01:46:03.955483 | 2026-09-10 01:46:03.955560 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-10 01:46:04.457334 | instance | changed 2026-09-10 01:46:04.465573 | 2026-09-10 01:46:04.465672 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-10 01:46:04.672713 | instance | ok 2026-09-10 01:46:04.682601 | 2026-09-10 01:46:04.682738 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-10 01:46:05.201757 | instance | changed 2026-09-10 01:46:05.208792 | 2026-09-10 01:46:05.208877 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-10 01:46:05.720714 | instance | changed 2026-09-10 01:46:05.732405 | 2026-09-10 01:46:05.732682 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-10 01:46:05.758603 | instance | skipping: Conditional result was False 2026-09-10 01:46:05.767404 | 2026-09-10 01:46:05.767550 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-10 01:46:06.212420 | instance -> localhost | changed 2026-09-10 01:46:06.234292 | 2026-09-10 01:46:06.234363 | TASK [add-build-sshkey : Add back temp key] 2026-09-10 01:46:06.566298 | instance -> localhost | Identity added: /var/lib/zuul/builds/6f7058b7a697492d9a66e887635f477c/work/6f7058b7a697492d9a66e887635f477c_id_rsa (zuul-build-sshkey) 2026-09-10 01:46:06.566626 | instance -> localhost | ok: Runtime: 0:00:00.015633 2026-09-10 01:46:06.575081 | 2026-09-10 01:46:06.575187 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-10 01:46:06.931881 | instance | ok 2026-09-10 01:46:06.978286 | 2026-09-10 01:46:06.978479 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-10 01:46:07.003857 | instance | skipping: Conditional result was False 2026-09-10 01:46:07.022177 | 2026-09-10 01:46:07.022320 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-10 01:46:07.383264 | instance | ok 2026-09-10 01:46:07.394989 | 2026-09-10 01:46:07.395193 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-10 01:46:11.101108 | instance | Output suppressed because no_log was given 2026-09-10 01:46:11.109269 | 2026-09-10 01:46:11.109332 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-10 01:46:11.321339 | instance | ok: "logs" 2026-09-10 01:46:11.321760 | instance | ok: All items complete 2026-09-10 01:46:11.321822 | 2026-09-10 01:46:11.479523 | instance | ok: "artifacts" 2026-09-10 01:46:11.658967 | instance | ok: "docs" 2026-09-10 01:46:11.667667 | 2026-09-10 01:46:11.667822 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-10 01:46:11.883906 | instance | changed: "logs" 2026-09-10 01:46:12.054165 | instance | changed: "artifacts" 2026-09-10 01:46:12.223163 | instance | changed: "docs" 2026-09-10 01:46:12.247537 | 2026-09-10 01:46:12.247715 | PLAY RECAP 2026-09-10 01:46:12.247807 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-10 01:46:12.247863 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-10 01:46:12.247906 | 2026-09-10 01:46:12.403310 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-10 01:46:12.407083 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-10 01:46:12.998412 | 2026-09-10 01:46:12.998571 | PLAY [all] 2026-09-10 01:46:13.008905 | 2026-09-10 01:46:13.008976 | TASK [setup-uv : Extract archive] 2026-09-10 01:46:15.329369 | instance | changed 2026-09-10 01:46:15.340025 | 2026-09-10 01:46:15.340158 | TASK [setup-uv : Print version] 2026-09-10 01:46:15.750334 | instance | uv 0.8.13 2026-09-10 01:46:15.877089 | instance | ok: Runtime: 0:00:00.012461 2026-09-10 01:46:15.885433 | 2026-09-10 01:46:15.885511 | TASK [Install Ansible collection siblings] 2026-09-10 01:46:24.830207 | instance | changed: 2026-09-10 01:46:24.830498 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-09-10 01:46:24.830691 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-09-10 01:46:24.830786 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-09-10 01:46:24.830830 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto 2026-09-10 01:46:24.830868 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general 2026-09-10 01:46:24.830916 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql 2026-09-10 01:46:24.830958 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-09-10 01:46:24.831001 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-09-10 01:46:24.831040 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-09-10 01:46:24.831077 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-09-10 01:46:24.831114 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-09-10 01:46:24.831150 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-09-10 01:46:24.831185 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0 2026-09-10 01:46:24.831220 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0 2026-09-10 01:46:24.831255 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0 2026-09-10 01:46:24.831291 | instance | Resolved community.crypto to 2.2.3 for 2.2.3 2026-09-10 01:46:24.831328 | instance | Resolved community.general to 7.3.0 for 7.3.0 2026-09-10 01:46:24.831364 | instance | Resolved community.mysql to 3.6.0 for 3.6.0 2026-09-10 01:46:24.831399 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0 2026-09-10 01:46:24.831434 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0 2026-09-10 01:46:24.831497 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0 2026-09-10 01:46:24.831534 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0 2026-09-10 01:46:24.831573 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0 2026-09-10 01:46:24.831627 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0 2026-09-10 01:46:24.831666 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0 2026-09-10 01:46:24.831702 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-10 01:46:24.831737 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-10 01:46:24.831772 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0 2026-09-10 01:46:24.831807 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0 2026-09-10 01:46:24.831842 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3 2026-09-10 01:46:24.831878 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-10 01:46:24.831914 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-10 01:46:24.831950 | instance | Checked out ansible.netcommon to 1.2.0 2026-09-10 01:46:24.831985 | 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-10 01:46:24.832031 | instance | Starting galaxy collection install process 2026-09-10 01:46:24.832066 | instance | Process install dependency map 2026-09-10 01:46:24.832101 | instance | Starting collection install process 2026-09-10 01:46:24.832136 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-09-10 01:46:24.832171 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon 2026-09-10 01:46:24.832205 | instance | ansible.netcommon:* was installed successfully 2026-09-10 01:46:24.832240 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-09-10 01:46:24.832274 | instance | Creating virtual environment at: .venv 2026-09-10 01:46:24.832309 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-10 01:46:24.832343 | instance | Downloading setuptools (1.1MiB) 2026-09-10 01:46:24.832378 | instance | Downloading openstacksdk (1.7MiB) 2026-09-10 01:46:24.832421 | instance | Downloading cryptography (4.2MiB) 2026-09-10 01:46:24.832477 | instance | Downloading netaddr (2.2MiB) 2026-09-10 01:46:24.832542 | instance | Downloading rjsonnet (1.2MiB) 2026-09-10 01:46:24.832581 | instance | Downloading pygments (1.2MiB) 2026-09-10 01:46:24.832617 | instance | Downloading pydantic-core (2.0MiB) 2026-09-10 01:46:24.832653 | instance | Downloading kubernetes (1.9MiB) 2026-09-10 01:46:24.832689 | instance | Downloading ansible-core (2.1MiB) 2026-09-10 01:46:24.832724 | instance | Building pyperclip==1.9.0 2026-09-10 01:46:24.832762 | instance | Downloading rjsonnet 2026-09-10 01:46:24.832798 | instance | Downloading pydantic-core 2026-09-10 01:46:24.832834 | instance | Downloading pygments 2026-09-10 01:46:24.832870 | instance | Downloading netaddr 2026-09-10 01:46:24.832918 | instance | Downloading setuptools 2026-09-10 01:46:24.832955 | instance | Downloading cryptography 2026-09-10 01:46:24.832990 | instance | Downloading kubernetes 2026-09-10 01:46:24.833024 | instance | Downloading ansible-core 2026-09-10 01:46:24.833059 | instance | Downloading openstacksdk 2026-09-10 01:46:24.833094 | instance | Built pyperclip==1.9.0 2026-09-10 01:46:24.833130 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-10 01:46:24.833166 | instance | Installed 83 packages in 90ms 2026-09-10 01:46:24.833201 | instance | Checked out ansible.posix to 1.6.0 2026-09-10 01:46:24.833237 | 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-10 01:46:24.833271 | instance | Starting galaxy collection install process 2026-09-10 01:46:24.833305 | instance | Process install dependency map 2026-09-10 01:46:24.833351 | instance | Starting collection install process 2026-09-10 01:46:24.833387 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-09-10 01:46:24.833423 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix 2026-09-10 01:46:24.833479 | instance | ansible.posix:1.6.0 was installed successfully 2026-09-10 01:46:24.833517 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-10 01:46:24.833553 | instance | 2.17.12 2026-09-10 01:46:24.833588 | instance | Checked out ansible.utils to v6.1.0 2026-09-10 01:46:24.833623 | 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-10 01:46:24.833658 | instance | Starting galaxy collection install process 2026-09-10 01:46:24.833693 | instance | Process install dependency map 2026-09-10 01:46:24.833727 | instance | Starting collection install process 2026-09-10 01:46:24.833763 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-09-10 01:46:24.833798 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils 2026-09-10 01:46:24.833834 | instance | ansible.utils:6.1.0 was installed successfully 2026-09-10 01:46:24.833869 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-10 01:46:24.833904 | instance | 2.17.12 2026-09-10 01:46:24.833938 | instance | Checked out atmosphere.common to v0.7.0 2026-09-10 01:46:24.833973 | 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-10 01:46:24.834007 | instance | Starting galaxy collection install process 2026-09-10 01:46:24.834041 | instance | Process install dependency map 2026-09-10 01:46:24.834075 | instance | Starting collection install process 2026-09-10 01:46:24.834109 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common' 2026-09-10 01:46:24.834143 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common 2026-09-10 01:46:24.834177 | instance | atmosphere.common:0.7.0 was installed successfully 2026-09-10 01:46:24.834211 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-10 01:46:24.834245 | instance | 2.17.12 2026-09-10 01:46:24.834279 | instance | Checked out community.crypto to 2.2.3 2026-09-10 01:46:24.834314 | 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-10 01:46:24.834349 | instance | Starting galaxy collection install process 2026-09-10 01:46:24.834382 | instance | Process install dependency map 2026-09-10 01:46:24.834416 | instance | Starting collection install process 2026-09-10 01:46:24.834468 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-09-10 01:46:24.834685 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto 2026-09-10 01:46:24.834719 | instance | community.crypto:2.2.3 was installed successfully 2026-09-10 01:46:24.834753 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-10 01:46:24.834787 | instance | 2.17.12 2026-09-10 01:46:24.834821 | instance | Checked out community.general to 7.3.0 2026-09-10 01:46:24.834855 | 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-10 01:46:24.834899 | instance | Starting galaxy collection install process 2026-09-10 01:46:24.834935 | instance | Process install dependency map 2026-09-10 01:46:24.834970 | instance | Starting collection install process 2026-09-10 01:46:24.835004 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-09-10 01:46:24.835038 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general 2026-09-10 01:46:24.835072 | instance | community.general:7.3.0 was installed successfully 2026-09-10 01:46:24.835106 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-10 01:46:24.835142 | instance | 2.17.12 2026-09-10 01:46:24.835178 | instance | Checked out community.mysql to 3.6.0 2026-09-10 01:46:24.835216 | 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-10 01:46:24.835253 | instance | Starting galaxy collection install process 2026-09-10 01:46:24.835289 | instance | Process install dependency map 2026-09-10 01:46:24.835326 | instance | Starting collection install process 2026-09-10 01:46:24.835361 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-09-10 01:46:24.835396 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql 2026-09-10 01:46:24.835432 | instance | community.mysql:3.6.0 was installed successfully 2026-09-10 01:46:24.835487 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-10 01:46:24.835524 | instance | 2.17.12 2026-09-10 01:46:24.835560 | instance | Checked out kubernetes.core to 2.4.0 2026-09-10 01:46:24.835596 | 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-10 01:46:24.835632 | instance | Starting galaxy collection install process 2026-09-10 01:46:24.835666 | instance | Process install dependency map 2026-09-10 01:46:24.835702 | instance | Starting collection install process 2026-09-10 01:46:24.835738 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-09-10 01:46:24.835773 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core 2026-09-10 01:46:24.835808 | instance | kubernetes.core:2.4.0 was installed successfully 2026-09-10 01:46:24.835842 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-10 01:46:24.835877 | instance | 2.17.12 2026-09-10 01:46:24.835912 | instance | Checked out openstack.cloud to 2.6.0 2026-09-10 01:46:24.835947 | 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-10 01:46:24.835982 | instance | Starting galaxy collection install process 2026-09-10 01:46:24.836018 | instance | Process install dependency map 2026-09-10 01:46:24.836052 | instance | Starting collection install process 2026-09-10 01:46:24.836086 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-09-10 01:46:24.836121 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud 2026-09-10 01:46:24.836155 | instance | openstack.cloud:2.6.0 was installed successfully 2026-09-10 01:46:24.836189 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-10 01:46:24.836224 | instance | 2.17.12 2026-09-10 01:46:24.836268 | instance | Checked out vexxhost.ceph to v4.2.0 2026-09-10 01:46:24.836305 | 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-10 01:46:24.836342 | instance | Starting galaxy collection install process 2026-09-10 01:46:24.836376 | instance | Process install dependency map 2026-09-10 01:46:24.836410 | instance | Starting collection install process 2026-09-10 01:46:24.836444 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph' 2026-09-10 01:46:24.836561 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph 2026-09-10 01:46:24.836603 | instance | vexxhost.ceph:4.2.0 was installed successfully 2026-09-10 01:46:24.836639 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-10 01:46:24.836673 | instance | 2.17.12 2026-09-10 01:46:24.836708 | instance | Checked out vexxhost.containers to v1.6.8 2026-09-10 01:46:24.836743 | 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-10 01:46:24.836776 | instance | Starting galaxy collection install process 2026-09-10 01:46:24.836811 | instance | Process install dependency map 2026-09-10 01:46:24.836845 | instance | Starting collection install process 2026-09-10 01:46:24.836879 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers' 2026-09-10 01:46:24.836913 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers 2026-09-10 01:46:24.836948 | instance | vexxhost.containers:1.6.8 was installed successfully 2026-09-10 01:46:24.836982 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-10 01:46:24.837018 | instance | 2.17.12 2026-09-10 01:46:24.837053 | instance | Checked out vexxhost.kubernetes to v3.4.0 2026-09-10 01:46:24.837089 | 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-10 01:46:24.837124 | instance | Starting galaxy collection install process 2026-09-10 01:46:24.837231 | instance | Process install dependency map 2026-09-10 01:46:24.837318 | instance | Starting collection install process 2026-09-10 01:46:24.837362 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes' 2026-09-10 01:46:24.837400 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes 2026-09-10 01:46:24.837436 | instance | vexxhost.kubernetes:3.4.0 was installed successfully 2026-09-10 01:46:24.837501 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-10 01:46:24.837538 | instance | 2.17.12 2026-09-10 01:46:24.847583 | 2026-09-10 01:46:24.847671 | PLAY RECAP 2026-09-10 01:46:24.847750 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-10 01:46:24.847790 | 2026-09-10 01:46:25.038549 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-10 01:46:25.045143 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-10 01:46:25.597930 | 2026-09-10 01:46:25.598087 | PLAY [all] 2026-09-10 01:46:25.609215 | 2026-09-10 01:46:25.609299 | TASK [Install "jq" for log collection] 2026-09-10 01:46:34.802642 | instance | changed 2026-09-10 01:46:34.808029 | 2026-09-10 01:46:34.808367 | PLAY RECAP 2026-09-10 01:46:34.808448 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-10 01:46:34.808578 | 2026-09-10 01:46:34.990688 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-10 01:46:34.992927 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-10 01:46:35.590528 | 2026-09-10 01:46:35.590657 | PLAY [all] 2026-09-10 01:46:35.600708 | 2026-09-10 01:46:35.600778 | LOOP [Check inventory directories] 2026-09-10 01:46:35.976702 | instance | ok: "src/github.com/vexxhost/atmosphere" 2026-09-10 01:46:36.156242 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory" 2026-09-10 01:46:36.326594 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory" 2026-09-10 01:46:36.340860 | 2026-09-10 01:46:36.341074 | LOOP [Copy inventory file for Zuul] 2026-09-10 01:46:37.254420 | instance | changed: 2026-09-10 01:46:37.254724 | instance | { 2026-09-10 01:46:37.254770 | instance | "ansible_loop_var": "item", 2026-09-10 01:46:37.254803 | instance | "changed": false, 2026-09-10 01:46:37.254832 | instance | "failed": false, 2026-09-10 01:46:37.254865 | instance | "invocation": { 2026-09-10 01:46:37.254890 | instance | "module_args": { 2026-09-10 01:46:37.254914 | instance | "checksum_algorithm": "sha1", 2026-09-10 01:46:37.254938 | instance | "follow": true, 2026-09-10 01:46:37.254960 | instance | "get_attributes": true, 2026-09-10 01:46:37.254984 | instance | "get_checksum": true, 2026-09-10 01:46:37.255007 | instance | "get_mime": true, 2026-09-10 01:46:37.255029 | instance | "path": "src/github.com/vexxhost/atmosphere" 2026-09-10 01:46:37.255051 | instance | } 2026-09-10 01:46:37.255073 | instance | }, 2026-09-10 01:46:37.255094 | instance | "item": "src/github.com/vexxhost/atmosphere", 2026-09-10 01:46:37.255118 | instance | "stat": { 2026-09-10 01:46:37.255144 | instance | "atime": 1789004778.207309, 2026-09-10 01:46:37.255168 | instance | "attr_flags": "e", 2026-09-10 01:46:37.255190 | instance | "attributes": [ 2026-09-10 01:46:37.255212 | instance | "extents" 2026-09-10 01:46:37.255234 | instance | ], 2026-09-10 01:46:37.255255 | instance | "block_size": 4096, 2026-09-10 01:46:37.255277 | instance | "blocks": 8, 2026-09-10 01:46:37.255299 | instance | "charset": "unknown", 2026-09-10 01:46:37.255325 | instance | "ctime": 1789004776.751226, 2026-09-10 01:46:37.255348 | instance | "dev": 64513, 2026-09-10 01:46:37.255370 | instance | "device_type": 0, 2026-09-10 01:46:37.255394 | instance | "executable": true, 2026-09-10 01:46:37.255418 | instance | "exists": true, 2026-09-10 01:46:37.255476 | instance | "gid": 1000, 2026-09-10 01:46:37.255509 | instance | "gr_name": "zuul", 2026-09-10 01:46:37.255536 | instance | "inode": 1048312, 2026-09-10 01:46:37.255559 | instance | "isblk": false, 2026-09-10 01:46:37.255582 | instance | "ischr": false, 2026-09-10 01:46:37.255605 | instance | "isdir": true, 2026-09-10 01:46:37.255627 | instance | "isfifo": false, 2026-09-10 01:46:37.255648 | instance | "isgid": false, 2026-09-10 01:46:37.255669 | instance | "islnk": false, 2026-09-10 01:46:37.255690 | instance | "isreg": false, 2026-09-10 01:46:37.255711 | instance | "issock": false, 2026-09-10 01:46:37.255731 | instance | "isuid": false, 2026-09-10 01:46:37.255752 | instance | "mimetype": "unknown", 2026-09-10 01:46:37.255773 | instance | "mode": "0755", 2026-09-10 01:46:37.255797 | instance | "mtime": 1789004776.751226, 2026-09-10 01:46:37.255821 | instance | "nlink": 23, 2026-09-10 01:46:37.255846 | instance | "path": "src/github.com/vexxhost/atmosphere", 2026-09-10 01:46:37.255872 | instance | "pw_name": "zuul", 2026-09-10 01:46:37.255896 | instance | "readable": true, 2026-09-10 01:46:37.255918 | instance | "rgrp": true, 2026-09-10 01:46:37.255941 | instance | "roth": true, 2026-09-10 01:46:37.255974 | instance | "rusr": true, 2026-09-10 01:46:37.255997 | instance | "size": 4096, 2026-09-10 01:46:37.256019 | instance | "uid": 1000, 2026-09-10 01:46:37.256041 | instance | "version": "131602076", 2026-09-10 01:46:37.256063 | instance | "wgrp": false, 2026-09-10 01:46:37.256086 | instance | "woth": false, 2026-09-10 01:46:37.256108 | instance | "writeable": true, 2026-09-10 01:46:37.256130 | instance | "wusr": true, 2026-09-10 01:46:37.256154 | instance | "xgrp": true, 2026-09-10 01:46:37.256177 | instance | "xoth": true, 2026-09-10 01:46:37.256201 | instance | "xusr": true 2026-09-10 01:46:37.256225 | instance | } 2026-09-10 01:46:37.256250 | instance | } 2026-09-10 01:46:37.256295 | instance | ok: All items complete 2026-09-10 01:46:37.256322 | 2026-09-10 01:46:37.258263 | instance | skipping: Conditional result was False 2026-09-10 01:46:37.259075 | instance | skipping: Conditional result was False 2026-09-10 01:46:37.276439 | 2026-09-10 01:46:37.276648 | LOOP [Switch "ansible_host" to private IP] 2026-09-10 01:46:37.632258 | instance | changed: 1 replacements made 2026-09-10 01:46:37.634629 | instance | skipping: Conditional result was False 2026-09-10 01:46:37.635867 | instance | skipping: Conditional result was False 2026-09-10 01:46:37.655791 | 2026-09-10 01:46:37.655960 | TASK [Run Molecule scenario] 2026-09-10 01:46:38.569891 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-09-10 01:46:38.978159 | instance | INFO [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-10 01:46:38.978253 | instance | INFO [csi > prerun] Performing prerun with role_name_check=0... 2026-09-10 01:46:48.943675 | instance | INFO [csi > dependency] Executing 2026-09-10 01:46:48.944028 | instance | WARNING [csi > dependency] Missing roles requirements file: requirements.yml 2026-09-10 01:46:48.944407 | instance | WARNING [csi > dependency] Missing collections requirements file: collections.yml 2026-09-10 01:46:48.944655 | instance | WARNING [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-09-10 01:46:48.959146 | instance | INFO [csi > cleanup] Executing 2026-09-10 01:46:48.959825 | instance | WARNING [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-10 01:46:48.974177 | instance | INFO [csi > destroy] Executing 2026-09-10 01:46:48.974216 | instance | WARNING [csi > destroy] Skipping, '--destroy=never' requested. 2026-09-10 01:46:48.974339 | instance | INFO [csi > destroy] Executed: Successful 2026-09-10 01:46:48.983220 | instance | INFO [csi > syntax] Executing 2026-09-10 01:46:50.369748 | instance | 2026-09-10 01:46:50.369986 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-10 01:46:50.424258 | instance | INFO [csi > syntax] Executed: Successful 2026-09-10 01:46:50.439275 | instance | INFO [csi > create] Executing 2026-09-10 01:46:50.442795 | instance | WARNING [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-10 01:46:50.456794 | instance | INFO [csi > prepare] Executing 2026-09-10 01:46:51.140660 | instance | 2026-09-10 01:46:51.140852 | instance | PLAY [Prepare] ***************************************************************** 2026-09-10 01:46:51.141074 | instance | 2026-09-10 01:46:51.141307 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-10 01:46:51.141541 | instance | Thursday 10 September 2026 01:46:51 +0000 (0:00:00.024) 0:00:00.024 **** 2026-09-10 01:46:52.411258 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-10 01:46:52.411480 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-10 01:46:52.411718 | instance | interpreter could change the meaning of that path. See 2026-09-10 01:46:52.411981 | instance | https://docs.ansible.com/ansible- 2026-09-10 01:46:52.412206 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-10 01:46:52.422247 | instance | ok: [instance] 2026-09-10 01:46:52.422461 | instance | 2026-09-10 01:46:52.422688 | instance | TASK [Configure short hostname] ************************************************ 2026-09-10 01:46:52.422948 | instance | Thursday 10 September 2026 01:46:52 +0000 (0:00:01.281) 0:00:01.306 **** 2026-09-10 01:46:53.158973 | instance | changed: [instance] 2026-09-10 01:46:53.159258 | instance | 2026-09-10 01:46:53.159572 | instance | TASK [Ensure hostname inside hosts file] *************************************** 2026-09-10 01:46:53.159934 | instance | Thursday 10 September 2026 01:46:53 +0000 (0:00:00.736) 0:00:02.042 **** 2026-09-10 01:46:53.452796 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created 2026-09-10 01:46:53.453126 | instance | with a mode of 0700, this may cause issues when running as another user. To 2026-09-10 01:46:53.453446 | instance | avoid this, create the remote_tmp dir with the correct permissions manually 2026-09-10 01:46:53.461926 | instance | changed: [instance] 2026-09-10 01:46:53.462168 | instance | 2026-09-10 01:46:53.462408 | instance | TASK [Purge "snapd" package] *************************************************** 2026-09-10 01:46:53.462639 | instance | Thursday 10 September 2026 01:46:53 +0000 (0:00:00.303) 0:00:02.345 **** 2026-09-10 01:46:54.246029 | instance | ok: [instance] 2026-09-10 01:46:54.246237 | instance | 2026-09-10 01:46:54.246469 | instance | PLAY [Create devices for Ceph] ************************************************* 2026-09-10 01:46:54.246675 | instance | 2026-09-10 01:46:54.246922 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-10 01:46:54.247152 | instance | Thursday 10 September 2026 01:46:54 +0000 (0:00:00.784) 0:00:03.130 **** 2026-09-10 01:46:54.998708 | instance | ok: [instance] 2026-09-10 01:46:54.998934 | instance | 2026-09-10 01:46:54.999173 | instance | TASK [Install depedencies] ***************************************************** 2026-09-10 01:46:54.999414 | instance | Thursday 10 September 2026 01:46:54 +0000 (0:00:00.752) 0:00:03.882 **** 2026-09-10 01:47:19.217830 | instance | changed: [instance] 2026-09-10 01:47:19.218052 | instance | 2026-09-10 01:47:19.218073 | instance | TASK [Start up service] ******************************************************** 2026-09-10 01:47:19.218292 | instance | Thursday 10 September 2026 01:47:19 +0000 (0:00:24.218) 0:00:28.101 **** 2026-09-10 01:47:19.760121 | instance | ok: [instance] 2026-09-10 01:47:19.760956 | instance | 2026-09-10 01:47:19.761004 | instance | TASK [Generate lvm.conf] ******************************************************* 2026-09-10 01:47:19.761015 | instance | Thursday 10 September 2026 01:47:19 +0000 (0:00:00.542) 0:00:28.643 **** 2026-09-10 01:47:20.075578 | instance | ok: [instance] 2026-09-10 01:47:20.076278 | instance | 2026-09-10 01:47:20.076323 | instance | TASK [Write /etc/lvm/lvm.conf] ************************************************* 2026-09-10 01:47:20.076334 | instance | Thursday 10 September 2026 01:47:20 +0000 (0:00:00.315) 0:00:28.959 **** 2026-09-10 01:47:20.756036 | instance | changed: [instance] 2026-09-10 01:47:20.756245 | instance | 2026-09-10 01:47:20.756609 | instance | TASK [Get list of all loopback devices] **************************************** 2026-09-10 01:47:20.756910 | instance | Thursday 10 September 2026 01:47:20 +0000 (0:00:00.680) 0:00:29.639 **** 2026-09-10 01:47:20.950502 | instance | ok: [instance] 2026-09-10 01:47:20.950569 | instance | 2026-09-10 01:47:20.950649 | instance | TASK [Fail if there is any existing loopback devices] ************************** 2026-09-10 01:47:20.950815 | instance | Thursday 10 September 2026 01:47:20 +0000 (0:00:00.195) 0:00:29.835 **** 2026-09-10 01:47:20.975704 | instance | skipping: [instance] 2026-09-10 01:47:20.975941 | instance | 2026-09-10 01:47:20.976281 | instance | TASK [Create devices for Ceph] ************************************************* 2026-09-10 01:47:20.976587 | instance | Thursday 10 September 2026 01:47:20 +0000 (0:00:00.024) 0:00:29.859 **** 2026-09-10 01:47:21.531690 | instance | changed: [instance] => (item=osd0) 2026-09-10 01:47:21.532839 | instance | changed: [instance] => (item=osd1) 2026-09-10 01:47:21.532870 | instance | changed: [instance] => (item=osd2) 2026-09-10 01:47:21.532881 | instance | 2026-09-10 01:47:21.532891 | instance | TASK [Set permissions on loopback devices] ************************************* 2026-09-10 01:47:21.532900 | instance | Thursday 10 September 2026 01:47:21 +0000 (0:00:00.555) 0:00:30.415 **** 2026-09-10 01:47:22.154918 | instance | changed: [instance] => (item=osd0) 2026-09-10 01:47:22.156072 | instance | changed: [instance] => (item=osd1) 2026-09-10 01:47:22.156105 | instance | changed: [instance] => (item=osd2) 2026-09-10 01:47:22.156116 | instance | 2026-09-10 01:47:22.156126 | instance | TASK [Start loop devices] ****************************************************** 2026-09-10 01:47:22.156136 | instance | Thursday 10 September 2026 01:47:22 +0000 (0:00:00.623) 0:00:31.038 **** 2026-09-10 01:47:22.923332 | instance | changed: [instance] => (item=osd0) 2026-09-10 01:47:22.923403 | instance | changed: [instance] => (item=osd1) 2026-09-10 01:47:22.923415 | instance | changed: [instance] => (item=osd2) 2026-09-10 01:47:22.923425 | instance | 2026-09-10 01:47:22.923434 | instance | TASK [Create a volume group for each loop device] ****************************** 2026-09-10 01:47:22.923443 | instance | Thursday 10 September 2026 01:47:22 +0000 (0:00:00.767) 0:00:31.805 **** 2026-09-10 01:47:26.261641 | instance | changed: [instance] => (item=osd0) 2026-09-10 01:47:26.263248 | instance | changed: [instance] => (item=osd1) 2026-09-10 01:47:26.263307 | instance | changed: [instance] => (item=osd2) 2026-09-10 01:47:26.263318 | instance | 2026-09-10 01:47:26.263328 | instance | TASK [Create a logical volume for each loop device] **************************** 2026-09-10 01:47:26.263338 | instance | Thursday 10 September 2026 01:47:26 +0000 (0:00:03.339) 0:00:35.145 **** 2026-09-10 01:47:28.168828 | instance | changed: [instance] => (item=ceph-instance-osd0) 2026-09-10 01:47:28.169813 | instance | changed: [instance] => (item=ceph-instance-osd1) 2026-09-10 01:47:28.169884 | instance | changed: [instance] => (item=ceph-instance-osd2) 2026-09-10 01:47:28.169896 | instance | 2026-09-10 01:47:28.169906 | instance | PLAY RECAP ********************************************************************* 2026-09-10 01:47:28.173805 | instance | instance : ok=15 changed=9 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-09-10 01:47:28.173863 | instance | 2026-09-10 01:47:28.173875 | instance | Thursday 10 September 2026 01:47:28 +0000 (0:00:01.907) 0:00:37.053 **** 2026-09-10 01:47:28.173885 | instance | =============================================================================== 2026-09-10 01:47:28.173894 | instance | Install depedencies ---------------------------------------------------- 24.22s 2026-09-10 01:47:28.173902 | instance | Create a volume group for each loop device ------------------------------ 3.34s 2026-09-10 01:47:28.173911 | instance | Create a logical volume for each loop device ---------------------------- 1.91s 2026-09-10 01:47:28.173921 | instance | Gathering Facts --------------------------------------------------------- 1.28s 2026-09-10 01:47:28.173929 | instance | Purge "snapd" package --------------------------------------------------- 0.78s 2026-09-10 01:47:28.173938 | instance | Start loop devices ------------------------------------------------------ 0.77s 2026-09-10 01:47:28.173946 | instance | Gathering Facts --------------------------------------------------------- 0.75s 2026-09-10 01:47:28.173955 | instance | Configure short hostname ------------------------------------------------ 0.74s 2026-09-10 01:47:28.173963 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.68s 2026-09-10 01:47:28.173972 | instance | Set permissions on loopback devices ------------------------------------- 0.62s 2026-09-10 01:47:28.173980 | instance | Create devices for Ceph ------------------------------------------------- 0.56s 2026-09-10 01:47:28.173988 | instance | Start up service -------------------------------------------------------- 0.54s 2026-09-10 01:47:28.174004 | instance | Generate lvm.conf ------------------------------------------------------- 0.32s 2026-09-10 01:47:28.174146 | instance | Ensure hostname inside hosts file --------------------------------------- 0.30s 2026-09-10 01:47:28.174508 | instance | Get list of all loopback devices ---------------------------------------- 0.20s 2026-09-10 01:47:28.174816 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s 2026-09-10 01:47:28.264257 | instance | INFO [csi > prepare] Executed: Successful 2026-09-10 01:47:28.271452 | instance | INFO [csi > converge] Executing 2026-09-10 01:47:29.575059 | instance | 2026-09-10 01:47:29.575431 | instance | PLAY [Debug CSI driver value] ************************************************** 2026-09-10 01:47:29.575748 | instance | 2026-09-10 01:47:29.576080 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-10 01:47:29.576371 | instance | Thursday 10 September 2026 01:47:29 +0000 (0:00:00.006) 0:00:00.006 **** 2026-09-10 01:47:30.562788 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-10 01:47:30.563254 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-10 01:47:30.563769 | instance | interpreter could change the meaning of that path. See 2026-09-10 01:47:30.564110 | instance | https://docs.ansible.com/ansible- 2026-09-10 01:47:30.564428 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-10 01:47:30.575380 | instance | ok: [instance] 2026-09-10 01:47:30.575704 | instance | 2026-09-10 01:47:30.576055 | instance | TASK [Display CSI driver value and environment variable] *********************** 2026-09-10 01:47:30.576372 | instance | Thursday 10 September 2026 01:47:30 +0000 (0:00:01.000) 0:00:01.006 **** 2026-09-10 01:47:30.636450 | instance | ok: [instance] => { 2026-09-10 01:47:30.636895 | instance | "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER=" 2026-09-10 01:47:30.637201 | instance | } 2026-09-10 01:47:30.637484 | instance | 2026-09-10 01:47:30.637818 | instance | PLAY [all] ********************************************************************* 2026-09-10 01:47:30.638134 | instance | 2026-09-10 01:47:30.638428 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-10 01:47:30.638763 | instance | Thursday 10 September 2026 01:47:30 +0000 (0:00:00.060) 0:00:01.067 **** 2026-09-10 01:47:31.593857 | instance | ok: [instance] 2026-09-10 01:47:31.594128 | instance | 2026-09-10 01:47:31.594407 | instance | TASK [Fail if atmosphere_ceph_enabled is set] ********************************** 2026-09-10 01:47:31.594651 | instance | Thursday 10 September 2026 01:47:31 +0000 (0:00:00.956) 0:00:02.024 **** 2026-09-10 01:47:31.645812 | instance | skipping: [instance] 2026-09-10 01:47:31.646669 | instance | 2026-09-10 01:47:31.646745 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] ***************** 2026-09-10 01:47:31.646758 | instance | Thursday 10 September 2026 01:47:31 +0000 (0:00:00.052) 0:00:02.077 **** 2026-09-10 01:47:31.856778 | instance | ok: [instance] 2026-09-10 01:47:31.856923 | instance | 2026-09-10 01:47:31.856940 | instance | PLAY [Deploy Ceph monitors & managers] ***************************************** 2026-09-10 01:47:31.857009 | instance | 2026-09-10 01:47:31.857251 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-10 01:47:31.857302 | instance | Thursday 10 September 2026 01:47:31 +0000 (0:00:00.211) 0:00:02.288 **** 2026-09-10 01:47:32.860157 | instance | ok: [instance] 2026-09-10 01:47:32.861178 | instance | 2026-09-10 01:47:32.861400 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-10 01:47:32.861416 | instance | Thursday 10 September 2026 01:47:32 +0000 (0:00:01.002) 0:00:03.291 **** 2026-09-10 01:47:33.221752 | instance | ok: [instance] 2026-09-10 01:47:33.222233 | instance | 2026-09-10 01:47:33.222276 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-10 01:47:33.222285 | instance | Thursday 10 September 2026 01:47:33 +0000 (0:00:00.361) 0:00:03.653 **** 2026-09-10 01:47:33.281846 | instance | skipping: [instance] 2026-09-10 01:47:33.282002 | instance | 2026-09-10 01:47:33.282186 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-09-10 01:47:33.282352 | instance | Thursday 10 September 2026 01:47:33 +0000 (0:00:00.059) 0:00:03.712 **** 2026-09-10 01:47:33.642599 | instance | changed: [instance] 2026-09-10 01:47:33.642658 | instance | 2026-09-10 01:47:33.642751 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-10 01:47:33.642863 | instance | Thursday 10 September 2026 01:47:33 +0000 (0:00:00.361) 0:00:04.074 **** 2026-09-10 01:47:33.736606 | instance | ok: [instance] => { 2026-09-10 01:47:33.736756 | instance | "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64" 2026-09-10 01:47:33.736898 | instance | } 2026-09-10 01:47:33.737050 | instance | 2026-09-10 01:47:33.737202 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-10 01:47:33.737349 | instance | Thursday 10 September 2026 01:47:33 +0000 (0:00:00.094) 0:00:04.168 **** 2026-09-10 01:47:34.668256 | instance | changed: [instance] 2026-09-10 01:47:34.668481 | instance | 2026-09-10 01:47:34.668739 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-10 01:47:34.668985 | instance | Thursday 10 September 2026 01:47:34 +0000 (0:00:00.930) 0:00:05.098 **** 2026-09-10 01:47:34.721670 | instance | skipping: [instance] 2026-09-10 01:47:34.721915 | instance | 2026-09-10 01:47:34.722191 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-10 01:47:34.722435 | instance | Thursday 10 September 2026 01:47:34 +0000 (0:00:00.054) 0:00:05.153 **** 2026-09-10 01:47:34.769343 | instance | skipping: [instance] 2026-09-10 01:47:34.769650 | instance | 2026-09-10 01:47:34.769793 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-10 01:47:34.770041 | instance | Thursday 10 September 2026 01:47:34 +0000 (0:00:00.047) 0:00:05.201 **** 2026-09-10 01:47:35.010215 | instance | ok: [instance] 2026-09-10 01:47:35.010402 | instance | 2026-09-10 01:47:35.010647 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-10 01:47:35.010891 | instance | Thursday 10 September 2026 01:47:35 +0000 (0:00:00.239) 0:00:05.441 **** 2026-09-10 01:47:36.183518 | instance | ok: [instance] 2026-09-10 01:47:36.183884 | instance | 2026-09-10 01:47:36.184277 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-10 01:47:36.184740 | instance | Thursday 10 September 2026 01:47:36 +0000 (0:00:01.173) 0:00:06.614 **** 2026-09-10 01:47:36.266853 | instance | ok: [instance] => { 2026-09-10 01:47:36.267290 | instance | "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz" 2026-09-10 01:47:36.267600 | instance | } 2026-09-10 01:47:36.267921 | instance | 2026-09-10 01:47:36.268254 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-10 01:47:36.268559 | instance | Thursday 10 September 2026 01:47:36 +0000 (0:00:00.082) 0:00:06.697 **** 2026-09-10 01:47:37.046967 | instance | changed: [instance] 2026-09-10 01:47:37.047024 | instance | 2026-09-10 01:47:37.047035 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-10 01:47:37.047046 | instance | Thursday 10 September 2026 01:47:37 +0000 (0:00:00.780) 0:00:07.477 **** 2026-09-10 01:47:39.955141 | instance | changed: [instance] 2026-09-10 01:47:39.955336 | instance | 2026-09-10 01:47:39.955349 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] *************** 2026-09-10 01:47:39.955359 | instance | Thursday 10 September 2026 01:47:39 +0000 (0:00:02.908) 0:00:10.385 **** 2026-09-10 01:47:39.995430 | instance | skipping: [instance] 2026-09-10 01:47:39.995481 | instance | 2026-09-10 01:47:39.995492 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] *** 2026-09-10 01:47:39.995502 | instance | Thursday 10 September 2026 01:47:39 +0000 (0:00:00.040) 0:00:10.425 **** 2026-09-10 01:47:40.034620 | instance | skipping: [instance] 2026-09-10 01:47:40.034673 | instance | 2026-09-10 01:47:40.034688 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ******** 2026-09-10 01:47:40.034701 | instance | Thursday 10 September 2026 01:47:40 +0000 (0:00:00.039) 0:00:10.465 **** 2026-09-10 01:47:40.072260 | instance | skipping: [instance] 2026-09-10 01:47:40.072603 | instance | 2026-09-10 01:47:40.072948 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] ************** 2026-09-10 01:47:40.073260 | instance | Thursday 10 September 2026 01:47:40 +0000 (0:00:00.038) 0:00:10.503 **** 2026-09-10 01:47:45.893526 | instance | changed: [instance] 2026-09-10 01:47:45.893574 | instance | 2026-09-10 01:47:45.893586 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] *** 2026-09-10 01:47:45.893596 | instance | Thursday 10 September 2026 01:47:45 +0000 (0:00:05.820) 0:00:16.324 **** 2026-09-10 01:47:46.550340 | instance | changed: [instance] 2026-09-10 01:47:46.551149 | instance | 2026-09-10 01:47:46.551199 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] ******* 2026-09-10 01:47:46.551211 | instance | Thursday 10 September 2026 01:47:46 +0000 (0:00:00.657) 0:00:16.981 **** 2026-09-10 01:47:47.543310 | instance | changed: [instance] => (item={'path': '/etc/containerd'}) 2026-09-10 01:47:47.545298 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'}) 2026-09-10 01:47:47.545558 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'}) 2026-09-10 01:47:47.545582 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'}) 2026-09-10 01:47:47.545594 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'}) 2026-09-10 01:47:47.545605 | instance | 2026-09-10 01:47:47.545615 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] ********** 2026-09-10 01:47:47.545624 | instance | Thursday 10 September 2026 01:47:47 +0000 (0:00:00.992) 0:00:17.973 **** 2026-09-10 01:47:48.174455 | instance | changed: [instance] 2026-09-10 01:47:48.174570 | instance | 2026-09-10 01:47:48.175288 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ******** 2026-09-10 01:47:48.175344 | instance | Thursday 10 September 2026 01:47:48 +0000 (0:00:00.604) 0:00:18.578 **** 2026-09-10 01:47:48.175352 | instance | 2026-09-10 01:47:48.175359 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-10 01:47:48.175365 | instance | Thursday 10 September 2026 01:47:48 +0000 (0:00:00.027) 0:00:18.606 **** 2026-09-10 01:47:49.144755 | instance | ok: [instance] 2026-09-10 01:47:49.145459 | instance | 2026-09-10 01:47:49.145818 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] ********** 2026-09-10 01:47:49.145834 | instance | Thursday 10 September 2026 01:47:49 +0000 (0:00:00.969) 0:00:19.576 **** 2026-09-10 01:47:49.647017 | instance | changed: [instance] 2026-09-10 01:47:49.647756 | instance | 2026-09-10 01:47:49.647813 | instance | TASK [vexxhost.containers.containerd : Enable and start service] *************** 2026-09-10 01:47:49.647825 | instance | Thursday 10 September 2026 01:47:49 +0000 (0:00:00.502) 0:00:20.078 **** 2026-09-10 01:47:50.248064 | instance | changed: [instance] 2026-09-10 01:47:50.248109 | instance | 2026-09-10 01:47:50.248116 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-10 01:47:50.248121 | instance | Thursday 10 September 2026 01:47:50 +0000 (0:00:00.601) 0:00:20.679 **** 2026-09-10 01:47:50.487975 | instance | ok: [instance] 2026-09-10 01:47:50.488742 | instance | 2026-09-10 01:47:50.488796 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-10 01:47:50.488808 | instance | Thursday 10 September 2026 01:47:50 +0000 (0:00:00.239) 0:00:20.919 **** 2026-09-10 01:47:50.567410 | instance | ok: [instance] => { 2026-09-10 01:47:50.567667 | instance | "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz" 2026-09-10 01:47:50.567908 | instance | } 2026-09-10 01:47:50.568163 | instance | 2026-09-10 01:47:50.568432 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-10 01:47:50.568680 | instance | Thursday 10 September 2026 01:47:50 +0000 (0:00:00.079) 0:00:20.998 **** 2026-09-10 01:47:51.435700 | instance | changed: [instance] 2026-09-10 01:47:51.435769 | instance | 2026-09-10 01:47:51.435852 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-10 01:47:51.435955 | instance | Thursday 10 September 2026 01:47:51 +0000 (0:00:00.868) 0:00:21.867 **** 2026-09-10 01:47:55.742015 | instance | changed: [instance] 2026-09-10 01:47:55.742116 | instance | 2026-09-10 01:47:55.742674 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ****************** 2026-09-10 01:47:55.742739 | instance | Thursday 10 September 2026 01:47:55 +0000 (0:00:04.306) 0:00:26.173 **** 2026-09-10 01:47:57.019097 | instance | ok: [instance] 2026-09-10 01:47:57.019861 | instance | 2026-09-10 01:47:57.019893 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] *************** 2026-09-10 01:47:57.019905 | instance | Thursday 10 September 2026 01:47:57 +0000 (0:00:01.277) 0:00:27.450 **** 2026-09-10 01:47:57.428862 | instance | changed: [instance] 2026-09-10 01:47:57.428901 | instance | 2026-09-10 01:47:57.428908 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] ***** 2026-09-10 01:47:57.428915 | instance | Thursday 10 September 2026 01:47:57 +0000 (0:00:00.409) 0:00:27.860 **** 2026-09-10 01:47:57.941773 | instance | changed: [instance] 2026-09-10 01:47:57.942523 | instance | 2026-09-10 01:47:57.942794 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] *********** 2026-09-10 01:47:57.942808 | instance | Thursday 10 September 2026 01:47:57 +0000 (0:00:00.512) 0:00:28.372 **** 2026-09-10 01:47:58.582447 | instance | changed: [instance] => (item={'path': '/etc/docker'}) 2026-09-10 01:47:58.582586 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'}) 2026-09-10 01:47:58.583158 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'}) 2026-09-10 01:47:58.583212 | instance | 2026-09-10 01:47:58.583221 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ****** 2026-09-10 01:47:58.583228 | instance | Thursday 10 September 2026 01:47:58 +0000 (0:00:00.641) 0:00:29.014 **** 2026-09-10 01:47:59.106762 | instance | changed: [instance] 2026-09-10 01:47:59.106846 | instance | 2026-09-10 01:47:59.106858 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] *********** 2026-09-10 01:47:59.106869 | instance | Thursday 10 September 2026 01:47:59 +0000 (0:00:00.522) 0:00:29.536 **** 2026-09-10 01:47:59.663887 | instance | changed: [instance] 2026-09-10 01:47:59.663965 | instance | 2026-09-10 01:47:59.663985 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************ 2026-09-10 01:47:59.664025 | instance | Thursday 10 September 2026 01:47:59 +0000 (0:00:00.535) 0:00:30.072 **** 2026-09-10 01:47:59.664449 | instance | 2026-09-10 01:47:59.664500 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-10 01:47:59.664516 | instance | Thursday 10 September 2026 01:47:59 +0000 (0:00:00.022) 0:00:30.095 **** 2026-09-10 01:48:00.434733 | instance | ok: [instance] 2026-09-10 01:48:00.435428 | instance | 2026-09-10 01:48:00.435482 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ****************** 2026-09-10 01:48:00.435494 | instance | Thursday 10 September 2026 01:48:00 +0000 (0:00:00.770) 0:00:30.866 **** 2026-09-10 01:48:01.358680 | instance | changed: [instance] 2026-09-10 01:48:01.359395 | instance | 2026-09-10 01:48:01.359446 | instance | TASK [vexxhost.containers.docker : Enable and start service] ******************* 2026-09-10 01:48:01.359459 | instance | Thursday 10 September 2026 01:48:01 +0000 (0:00:00.923) 0:00:31.789 **** 2026-09-10 01:48:02.008691 | instance | changed: [instance] 2026-09-10 01:48:02.008800 | instance | 2026-09-10 01:48:02.009138 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ****** 2026-09-10 01:48:02.009177 | instance | Thursday 10 September 2026 01:48:02 +0000 (0:00:00.650) 0:00:32.440 **** 2026-09-10 01:48:02.082726 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml) 2026-09-10 01:48:02.082793 | instance | 2026-09-10 01:48:02.082801 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ******************************** 2026-09-10 01:48:02.082809 | instance | Thursday 10 September 2026 01:48:02 +0000 (0:00:00.072) 0:00:32.513 **** 2026-09-10 01:48:06.872812 | instance | changed: [instance] 2026-09-10 01:48:06.873010 | instance | 2026-09-10 01:48:06.873282 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] ********************* 2026-09-10 01:48:06.873327 | instance | Thursday 10 September 2026 01:48:06 +0000 (0:00:04.791) 0:00:37.304 **** 2026-09-10 01:48:07.541610 | instance | ok: [instance] => (item=chronyd) 2026-09-10 01:48:07.542618 | instance | ok: [instance] => (item=sshd) 2026-09-10 01:48:07.542686 | instance | 2026-09-10 01:48:07.542698 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ****************************** 2026-09-10 01:48:07.542708 | instance | Thursday 10 September 2026 01:48:07 +0000 (0:00:00.667) 0:00:37.971 **** 2026-09-10 01:48:17.153460 | instance | changed: [instance] 2026-09-10 01:48:17.153604 | instance | 2026-09-10 01:48:17.154074 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ******************** 2026-09-10 01:48:17.155338 | instance | Thursday 10 September 2026 01:48:17 +0000 (0:00:09.613) 0:00:47.584 **** 2026-09-10 01:48:17.391439 | instance | ok: [instance] 2026-09-10 01:48:17.391528 | instance | 2026-09-10 01:48:17.391887 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] **************** 2026-09-10 01:48:17.391946 | instance | Thursday 10 September 2026 01:48:17 +0000 (0:00:00.238) 0:00:47.822 **** 2026-09-10 01:48:17.900756 | instance | changed: [instance] 2026-09-10 01:48:17.901535 | instance | 2026-09-10 01:48:17.901589 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] *** 2026-09-10 01:48:17.901601 | instance | Thursday 10 September 2026 01:48:17 +0000 (0:00:00.509) 0:00:48.332 **** 2026-09-10 01:48:18.256639 | instance | changed: [instance] 2026-09-10 01:48:18.256692 | instance | 2026-09-10 01:48:18.256935 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] ************************* 2026-09-10 01:48:18.256975 | instance | Thursday 10 September 2026 01:48:18 +0000 (0:00:00.356) 0:00:48.688 **** 2026-09-10 01:48:18.458301 | instance | ok: [instance] 2026-09-10 01:48:18.458770 | instance | 2026-09-10 01:48:18.458816 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] ******************* 2026-09-10 01:48:18.458824 | instance | Thursday 10 September 2026 01:48:18 +0000 (0:00:00.201) 0:00:48.890 **** 2026-09-10 01:48:18.716160 | instance | ok: [instance] => (item=instance) 2026-09-10 01:48:18.717099 | instance | 2026-09-10 01:48:18.717135 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ******** 2026-09-10 01:48:18.717148 | instance | Thursday 10 September 2026 01:48:18 +0000 (0:00:00.257) 0:00:49.147 **** 2026-09-10 01:48:18.770797 | instance | ok: [instance] 2026-09-10 01:48:18.771731 | instance | 2026-09-10 01:48:18.771766 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] *********************************** 2026-09-10 01:48:18.771780 | instance | Thursday 10 September 2026 01:48:18 +0000 (0:00:00.054) 0:00:49.202 **** 2026-09-10 01:48:18.851418 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance 2026-09-10 01:48:18.851469 | instance | 2026-09-10 01:48:18.851678 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] ************* 2026-09-10 01:48:18.851694 | instance | Thursday 10 September 2026 01:48:18 +0000 (0:00:00.081) 0:00:49.283 **** 2026-09-10 01:48:19.154514 | instance | changed: [instance] 2026-09-10 01:48:19.155244 | instance | 2026-09-10 01:48:19.155313 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ****************** 2026-09-10 01:48:19.155326 | instance | Thursday 10 September 2026 01:48:19 +0000 (0:00:00.302) 0:00:49.585 **** 2026-09-10 01:48:19.842379 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True}) 2026-09-10 01:48:19.843613 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0}) 2026-09-10 01:48:19.843667 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False}) 2026-09-10 01:48:19.843678 | instance | 2026-09-10 01:48:19.843688 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] ******************************* 2026-09-10 01:48:19.843697 | instance | Thursday 10 September 2026 01:48:19 +0000 (0:00:00.688) 0:00:50.273 **** 2026-09-10 01:49:53.193446 | instance | ok: [instance] 2026-09-10 01:49:53.193836 | instance | 2026-09-10 01:49:53.194359 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] *************** 2026-09-10 01:49:53.194570 | instance | Thursday 10 September 2026 01:49:53 +0000 (0:01:33.351) 0:02:23.625 **** 2026-09-10 01:49:53.425492 | instance | changed: [instance] 2026-09-10 01:49:53.425587 | instance | 2026-09-10 01:49:53.425599 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] ********************************** 2026-09-10 01:49:53.425609 | instance | Thursday 10 September 2026 01:49:53 +0000 (0:00:00.230) 0:02:23.856 **** 2026-09-10 01:49:53.468234 | instance | ok: [instance] 2026-09-10 01:49:53.468267 | instance | 2026-09-10 01:49:53.468277 | instance | TASK [Install Ceph host] ******************************************************* 2026-09-10 01:49:53.468286 | instance | Thursday 10 September 2026 01:49:53 +0000 (0:00:00.043) 0:02:23.899 **** 2026-09-10 01:49:53.563111 | instance | included: vexxhost.ceph.cephadm_host for instance 2026-09-10 01:49:53.563160 | instance | 2026-09-10 01:49:53.563171 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ****** 2026-09-10 01:49:53.563180 | instance | Thursday 10 September 2026 01:49:53 +0000 (0:00:00.095) 0:02:23.994 **** 2026-09-10 01:49:55.145330 | instance | ok: [instance] 2026-09-10 01:49:55.145367 | instance | 2026-09-10 01:49:55.145637 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] *** 2026-09-10 01:49:55.146044 | instance | Thursday 10 September 2026 01:49:55 +0000 (0:00:01.582) 0:02:25.576 **** 2026-09-10 01:49:55.224629 | instance | ok: [instance] => (item=instance) 2026-09-10 01:49:55.224741 | instance | 2026-09-10 01:49:55.224893 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] *********** 2026-09-10 01:49:55.225041 | instance | Thursday 10 September 2026 01:49:55 +0000 (0:00:00.079) 0:02:25.655 **** 2026-09-10 01:49:55.618373 | instance | ok: [instance] 2026-09-10 01:49:55.618661 | instance | 2026-09-10 01:49:55.618944 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] *********************** 2026-09-10 01:49:55.619192 | instance | Thursday 10 September 2026 01:49:55 +0000 (0:00:00.393) 0:02:26.049 **** 2026-09-10 01:49:58.283052 | instance | ok: [instance] 2026-09-10 01:49:58.283107 | instance | 2026-09-10 01:49:58.283120 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ****************** 2026-09-10 01:49:58.283130 | instance | Thursday 10 September 2026 01:49:58 +0000 (0:00:02.664) 0:02:28.713 **** 2026-09-10 01:51:05.933235 | 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-10 01:51:05.934509 | 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_3jqizfzh/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_3jqizfzh/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_3jqizfzh/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_3jqizfzh/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-10T01:51:05.758+0000 7f9847fa0640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCaDKJqmYWWGCAA/yjzrZsBlyfzjhyVi/f+rWtVqwi2iAH1Uj0PiwUHEE0=: Malformed input [buffer:3]\n2026-09-10T01:51:05.758+0000 7f9847fa0640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-10T01:51:05.758+0000 7f9847fa0640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCaDKJqmYWWGCAA/yjzrZsBlyfzjhyVi/f+rWtVqwi2iAH1Uj0PiwUHEE0=: Malformed input [buffer:3]\n2026-09-10T01:51:05.758+0000 7f9847fa0640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-10T01:51:05.758+0000 7f9847fa0640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCaDKJqmYWWGCAA/yjzrZsBlyfzjhyVi/f+rWtVqwi2iAH1Uj0PiwUHEE0=: Malformed input [buffer:3]\n2026-09-10T01:51:05.758+0000 7f9847fa0640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-10T01:51:05.758+0000 7f9847fa0640 -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-10 01:51:05.937847 | instance | 2026-09-10 01:51:05.941783 | instance | PLAY RECAP ********************************************************************* 2026-09-10 01:51:05.941818 | instance | instance : ok=55 changed=26 unreachable=0 failed=1 skipped=7 rescued=0 ignored=0 2026-09-10 01:51:05.941829 | instance | 2026-09-10 01:51:05.941839 | instance | Thursday 10 September 2026 01:51:05 +0000 (0:01:07.656) 0:03:36.369 **** 2026-09-10 01:51:05.941848 | instance | =============================================================================== 2026-09-10 01:51:05.941857 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 93.35s 2026-09-10 01:51:05.941866 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 67.66s 2026-09-10 01:51:05.941874 | instance | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 9.61s 2026-09-10 01:51:05.941883 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.82s 2026-09-10 01:51:05.942013 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 4.79s 2026-09-10 01:51:05.942494 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.31s 2026-09-10 01:51:05.942945 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.91s 2026-09-10 01:51:05.943355 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.66s 2026-09-10 01:51:05.943804 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.74s 2026-09-10 01:51:05.944258 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.58s 2026-09-10 01:51:05.944663 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.28s 2026-09-10 01:51:05.944970 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.17s 2026-09-10 01:51:05.945263 | instance | Gathering Facts --------------------------------------------------------- 1.00s 2026-09-10 01:51:05.945638 | instance | Gathering Facts --------------------------------------------------------- 1.00s 2026-09-10 01:51:05.945998 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.99s 2026-09-10 01:51:05.946297 | instance | Gathering Facts --------------------------------------------------------- 0.96s 2026-09-10 01:51:05.946674 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.93s 2026-09-10 01:51:05.946976 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.92s 2026-09-10 01:51:05.947261 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.87s 2026-09-10 01:51:05.947573 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.78s 2026-09-10 01:51:06.035264 | 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-10 01:51:06.035321 | instance | ERROR [csi > converge] Executed: Failed 2026-09-10 01:51:06.035372 | 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-10 01:51:06.548077 | instance | ERROR 2026-09-10 01:51:06.548349 | instance | { 2026-09-10 01:51:06.548409 | instance | "delta": "0:04:28.049015", 2026-09-10 01:51:06.548452 | instance | "end": "2026-09-10 01:51:06.079008", 2026-09-10 01:51:06.548491 | instance | "msg": "non-zero return code", 2026-09-10 01:51:06.548588 | instance | "rc": 2, 2026-09-10 01:51:06.548629 | instance | "start": "2026-09-10 01:46:38.029993" 2026-09-10 01:51:06.548671 | instance | } failure 2026-09-10 01:51:06.558280 | 2026-09-10 01:51:06.558381 | PLAY RECAP 2026-09-10 01:51:06.558466 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-10 01:51:06.558509 | 2026-09-10 01:51:06.713957 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-10 01:51:06.718972 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-10 01:51:07.322229 | 2026-09-10 01:51:07.322372 | PLAY [all] 2026-09-10 01:51:07.335910 | 2026-09-10 01:51:07.335984 | TASK [gather-host-logs : creating directory for system status] 2026-09-10 01:51:07.743829 | instance | changed 2026-09-10 01:51:07.758536 | 2026-09-10 01:51:07.758690 | TASK [gather-host-logs : Get logs for each host] 2026-09-10 01:51:08.274930 | instance | + systemd-cgls --full --all --no-pager 2026-09-10 01:51:08.291772 | instance | + ip addr 2026-09-10 01:51:08.294158 | instance | + ip route 2026-09-10 01:51:08.296505 | instance | + lsblk 2026-09-10 01:51:08.302163 | instance | + mount 2026-09-10 01:51:08.304779 | instance | + docker images 2026-09-10 01:51:08.333352 | instance | + brctl show 2026-09-10 01:51:08.333970 | instance | /bin/bash: line 8: brctl: command not found 2026-09-10 01:51:08.334406 | instance | + ps aux --sort=-%mem 2026-09-10 01:51:08.354353 | instance | + dpkg -l 2026-09-10 01:51:08.365866 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-09-10 01:51:08.366623 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-09-10 01:51:08.389584 | instance | + '[' '!' -z '' ']' 2026-09-10 01:51:08.832958 | instance | ok: Runtime: 0:00:00.120932 2026-09-10 01:51:08.847387 | 2026-09-10 01:51:08.847534 | TASK [gather-host-logs : Downloads logs to executor] 2026-09-10 01:51:09.600438 | instance | changed: 2026-09-10 01:51:09.600771 | instance | created directory /var/lib/zuul/builds/6f7058b7a697492d9a66e887635f477c/work/logs/instance 2026-09-10 01:51:09.600827 | instance | cd+++++++++ system/ 2026-09-10 01:51:09.600868 | instance | >f+++++++++ system/brctl-show.txt 2026-09-10 01:51:09.600907 | instance | >f+++++++++ system/docker-images.txt 2026-09-10 01:51:09.600945 | instance | >f+++++++++ system/ip-addr.txt 2026-09-10 01:51:09.600989 | instance | >f+++++++++ system/ip-route.txt 2026-09-10 01:51:09.601029 | instance | >f+++++++++ system/lsblk.txt 2026-09-10 01:51:09.601068 | instance | >f+++++++++ system/mount.txt 2026-09-10 01:51:09.601104 | instance | >f+++++++++ system/packages.txt 2026-09-10 01:51:09.601139 | instance | >f+++++++++ system/ps.txt 2026-09-10 01:51:09.601179 | instance | >f+++++++++ system/systemd-cgls.txt 2026-09-10 01:51:09.619126 | 2026-09-10 01:51:09.619301 | LOOP [helm-release-status : creating directory for helm release status] 2026-09-10 01:51:09.852213 | instance | changed: "values" 2026-09-10 01:51:10.056196 | instance | changed: "releases" 2026-09-10 01:51:10.075644 | 2026-09-10 01:51:10.075907 | TASK [helm-release-status : Gather get release status for helm charts] 2026-09-10 01:51:10.329143 | instance | /bin/bash: line 3: kubectl: command not found 2026-09-10 01:51:10.623006 | instance | ok: Runtime: 0:00:00.005494 2026-09-10 01:51:10.634135 | 2026-09-10 01:51:10.634290 | TASK [helm-release-status : Downloads logs to executor] 2026-09-10 01:51:11.202596 | instance | changed: 2026-09-10 01:51:11.202859 | instance | cd+++++++++ helm/ 2026-09-10 01:51:11.202915 | instance | cd+++++++++ helm/releases/ 2026-09-10 01:51:11.202957 | instance | cd+++++++++ helm/values/ 2026-09-10 01:51:11.221343 | 2026-09-10 01:51:11.221475 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-09-10 01:51:11.445438 | instance | changed 2026-09-10 01:51:11.459514 | 2026-09-10 01:51:11.459904 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-09-10 01:51:11.767610 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args valuewarning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-10 01:51:11.767686 | instance | 2026-09-10 01:51:11.771492 | instance | environment: line 1: kubectl: command not found 2026-09-10 01:51:11.772925 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-10 01:51:11.774827 | instance | environment: line 1: kubectl: command not found 2026-09-10 01:51:11.775818 | instance | environment: line 1: kubectl: command not found 2026-09-10 01:51:11.776083 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-10 01:51:11.777282 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-10 01:51:11.778471 | instance | environment: line 1: kubectl: command not found 2026-09-10 01:51:11.780541 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-10 01:51:11.780985 | instance | environment: line 1: kubectl: command not found 2026-09-10 01:51:11.782760 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-10 01:51:12.010522 | instance | ok: Runtime: 0:00:00.022618 2026-09-10 01:51:12.020686 | 2026-09-10 01:51:12.020817 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-09-10 01:51:12.246240 | instance | changed 2026-09-10 01:51:12.256297 | 2026-09-10 01:51:12.256556 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-09-10 01:51:12.502338 | instance | environment: line 5: kubectl: command not found 2026-09-10 01:51:12.502730 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-10 01:51:12.503474 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-10 01:51:12.503694 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-10 01:51:12.806707 | instance | ok: Runtime: 0:00:00.007698 2026-09-10 01:51:12.814109 | 2026-09-10 01:51:12.814191 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-09-10 01:51:13.355228 | instance | changed: 2026-09-10 01:51:13.355523 | instance | cd+++++++++ objects/ 2026-09-10 01:51:13.355604 | instance | cd+++++++++ objects/cluster/ 2026-09-10 01:51:13.355649 | instance | cd+++++++++ objects/namespaced/ 2026-09-10 01:51:13.368079 | 2026-09-10 01:51:13.368170 | TASK [gather-pod-logs : creating directory for pod logs] 2026-09-10 01:51:13.594620 | instance | changed 2026-09-10 01:51:13.604295 | 2026-09-10 01:51:13.604424 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-09-10 01:51:13.841845 | instance | changed 2026-09-10 01:51:13.848321 | 2026-09-10 01:51:13.848425 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-09-10 01:51:14.105525 | instance | environment: line 3: kubectl: command not found 2026-09-10 01:51:14.387920 | instance | ok: Runtime: 0:00:00.008609 2026-09-10 01:51:14.394596 | 2026-09-10 01:51:14.394677 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-09-10 01:51:14.921367 | instance | changed: 2026-09-10 01:51:14.921601 | instance | cd+++++++++ pod-logs/ 2026-09-10 01:51:14.921642 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-09-10 01:51:14.938383 | 2026-09-10 01:51:14.938507 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-09-10 01:51:15.155764 | instance | changed 2026-09-10 01:51:15.167463 | 2026-09-10 01:51:15.167592 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-09-10 01:51:15.444713 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-10 01:51:15.719252 | instance | ok: Runtime: 0:00:00.048612 2026-09-10 01:51:15.731477 | 2026-09-10 01:51:15.731729 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-09-10 01:51:16.007947 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-10 01:51:16.053703 | instance | ceph-mgr endpoints: 2026-09-10 01:51:16.288904 | instance | ok: Runtime: 0:00:00.051966 2026-09-10 01:51:16.301782 | 2026-09-10 01:51:16.301978 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-09-10 01:51:16.580638 | instance | /bin/bash: line 4: kubectl: command not found 2026-09-10 01:51:16.861827 | instance | ok: Runtime: 0:00:00.054578 2026-09-10 01:51:16.872049 | 2026-09-10 01:51:16.872175 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-09-10 01:51:17.550606 | instance | changed: cd+++++++++ prometheus/ 2026-09-10 01:51:17.570248 | 2026-09-10 01:51:17.570385 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-09-10 01:51:17.799281 | instance | changed 2026-09-10 01:51:17.809826 | 2026-09-10 01:51:17.810157 | TASK [gather-selenium-data : Get selenium data] 2026-09-10 01:51:18.097108 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-09-10 01:51:18.098062 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-09-10 01:51:18.353216 | instance | ERROR 2026-09-10 01:51:18.353521 | instance | { 2026-09-10 01:51:18.353605 | instance | "delta": "0:00:00.004947", 2026-09-10 01:51:18.353650 | instance | "end": "2026-09-10 01:51:18.098425", 2026-09-10 01:51:18.353693 | instance | "msg": "non-zero return code", 2026-09-10 01:51:18.353731 | instance | "rc": 1, 2026-09-10 01:51:18.353767 | instance | "start": "2026-09-10 01:51:18.093478" 2026-09-10 01:51:18.353802 | instance | } 2026-09-10 01:51:18.353849 | instance | ERROR: Ignoring Errors 2026-09-10 01:51:18.363419 | 2026-09-10 01:51:18.363599 | TASK [gather-selenium-data : Downloads logs to executor] 2026-09-10 01:51:18.950916 | instance | changed: cd+++++++++ selenium/ 2026-09-10 01:51:18.960559 | 2026-09-10 01:51:18.960638 | PLAY RECAP 2026-09-10 01:51:18.960694 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-09-10 01:51:18.960721 | 2026-09-10 01:51:19.104483 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-10 01:51:19.109134 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-10 01:51:19.832035 | 2026-09-10 01:51:19.832269 | PLAY [all] 2026-09-10 01:51:19.845607 | 2026-09-10 01:51:19.845754 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-10 01:51:19.911139 | instance | skipping: Conditional result was False 2026-09-10 01:51:19.921238 | 2026-09-10 01:51:19.921420 | TASK [fetch-output : Set log path for single node] 2026-09-10 01:51:19.993938 | instance | ok 2026-09-10 01:51:20.000404 | 2026-09-10 01:51:20.000527 | LOOP [fetch-output : Ensure local output dirs] 2026-09-10 01:51:20.445462 | instance -> localhost | ok: "/var/lib/zuul/builds/6f7058b7a697492d9a66e887635f477c/work/logs" 2026-09-10 01:51:20.797941 | instance -> localhost | changed: "/var/lib/zuul/builds/6f7058b7a697492d9a66e887635f477c/work/artifacts" 2026-09-10 01:51:21.041755 | instance -> localhost | changed: "/var/lib/zuul/builds/6f7058b7a697492d9a66e887635f477c/work/docs" 2026-09-10 01:51:21.063767 | 2026-09-10 01:51:21.063969 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-10 01:51:21.925460 | instance | changed: .d..t...... ./ 2026-09-10 01:51:21.925795 | instance | changed: All items complete 2026-09-10 01:51:21.925842 | 2026-09-10 01:51:22.433555 | instance | changed: .d..t...... ./ 2026-09-10 01:51:22.928133 | instance | changed: .d..t...... ./ 2026-09-10 01:51:22.972476 | 2026-09-10 01:51:22.972985 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-10 01:51:23.800472 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.339474 2026-09-10 01:51:24.095429 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006504 2026-09-10 01:51:24.117328 | 2026-09-10 01:51:24.117511 | PLAY [all] 2026-09-10 01:51:24.130701 | 2026-09-10 01:51:24.130894 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-10 01:51:24.638648 | instance | changed 2026-09-10 01:51:24.644617 | 2026-09-10 01:51:24.644668 | PLAY RECAP 2026-09-10 01:51:24.644713 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-10 01:51:24.644734 | 2026-09-10 01:51:24.804886 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-10 01:51:24.810184 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-10 01:51:25.539195 | 2026-09-10 01:51:25.539365 | PLAY [localhost] 2026-09-10 01:51:25.550289 | 2026-09-10 01:51:25.550439 | TASK [Generate Zuul manifest] 2026-09-10 01:51:25.568802 | localhost | ok 2026-09-10 01:51:25.610957 | 2026-09-10 01:51:25.611127 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-10 01:51:26.147129 | localhost | changed 2026-09-10 01:51:26.195002 | 2026-09-10 01:51:26.195189 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-10 01:51:26.238839 | localhost | ok 2026-09-10 01:51:26.365477 | 2026-09-10 01:51:26.365892 | TASK [Upload logs] 2026-09-10 01:51:26.402825 | localhost | ok 2026-09-10 01:51:26.545580 | 2026-09-10 01:51:26.545738 | TASK [Set zuul-log-path fact] 2026-09-10 01:51:26.567103 | localhost | ok 2026-09-10 01:51:26.590194 | 2026-09-10 01:51:26.663589 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-10 01:51:26.706044 | localhost | ok 2026-09-10 01:51:26.720295 | 2026-09-10 01:51:26.720434 | TASK [upload-logs : Create log directories] 2026-09-10 01:51:27.249503 | localhost | changed 2026-09-10 01:51:27.260374 | 2026-09-10 01:51:27.260588 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-10 01:51:27.691646 | localhost -> localhost | ok: Runtime: 0:00:00.006166 2026-09-10 01:51:27.698149 | 2026-09-10 01:51:27.698304 | TASK [upload-logs : Upload logs to log server] 2026-09-10 01:51:28.295177 | localhost | Output suppressed because no_log was given 2026-09-10 01:51:28.352523 | 2026-09-10 01:51:28.352705 | LOOP [upload-logs : Compress console log and json output] 2026-09-10 01:51:28.411359 | localhost | skipping: Conditional result was False 2026-09-10 01:51:28.421782 | localhost | skipping: Conditional result was False 2026-09-10 01:51:28.436063 | 2026-09-10 01:51:28.436298 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-10 01:51:28.514759 | localhost | skipping: Conditional result was False 2026-09-10 01:51:28.520205 | 2026-09-10 01:51:28.521108 | localhost | skipping: Conditional result was False 2026-09-10 01:51:28.549022 | 2026-09-10 01:51:28.550837 | LOOP [upload-logs : Upload console log and json output]