2026-09-17 19:28:40.487073 | Job console starting
2026-09-17 19:28:40.498922 | Updating git repos
2026-09-17 19:28:44.926372 | Cloning repos into workspace
2026-09-17 19:29:01.620934 | Restoring repo states
2026-09-17 19:29:16.077118 | Merging changes
2026-09-17 19:29:17.281333 | Checking out repos
2026-09-17 19:29:19.319887 | Preparing playbooks
2026-09-17 19:29:21.352917 | Running Ansible setup
2026-09-17 19:29:25.201830 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-17 19:29:25.771204 | 
2026-09-17 19:29:25.771334 | PLAY [localhost]
2026-09-17 19:29:25.778705 | 
2026-09-17 19:29:25.778802 | TASK [Gathering Facts]
2026-09-17 19:29:26.668680 | localhost | ok
2026-09-17 19:29:26.674120 | 
2026-09-17 19:29:26.674210 | TASK [Setup log path fact]
2026-09-17 19:29:26.689846 | localhost | ok
2026-09-17 19:29:26.704686 | 
2026-09-17 19:29:26.704857 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-17 19:29:26.743505 | localhost | ok
2026-09-17 19:29:26.751587 | 
2026-09-17 19:29:26.751711 | TASK [emit-job-header : Print job information]
2026-09-17 19:29:26.791559 | # Job Information
2026-09-17 19:29:26.791834 | Ansible Version: 2.16.18
2026-09-17 19:29:26.791902 | Job: atmosphere-molecule-csi-rbd
2026-09-17 19:29:26.791949 | Pipeline: check
2026-09-17 19:29:26.792044 | Executor: 2d72f0692154
2026-09-17 19:29:26.792118 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4380
2026-09-17 19:29:26.792188 | Event ID: 612e4c60-b2cd-11f1-85cd-32cf5e7f0c13
2026-09-17 19:29:26.796426 | 
2026-09-17 19:29:26.796533 | LOOP [emit-job-header : Print node information]
2026-09-17 19:29:26.884530 | localhost | ok:
2026-09-17 19:29:26.884704 | localhost | # Node Information
2026-09-17 19:29:26.884737 | localhost | Inventory Hostname: instance
2026-09-17 19:29:26.884761 | localhost | Hostname: np0000193103
2026-09-17 19:29:26.884784 | localhost | Username: zuul
2026-09-17 19:29:26.884809 | localhost | Distro: Ubuntu 22.04
2026-09-17 19:29:26.884831 | localhost | Provider: yul1
2026-09-17 19:29:26.884853 | localhost | Region: ca-ymq-1
2026-09-17 19:29:26.884874 | localhost | Label: ubuntu-jammy
2026-09-17 19:29:26.884895 | localhost | Product Name: OpenStack Nova
2026-09-17 19:29:26.884916 | localhost | Interface IP: 162.253.55.195
2026-09-17 19:29:26.902883 | 
2026-09-17 19:29:26.903051 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-17 19:29:27.303815 | localhost -> localhost | changed
2026-09-17 19:29:27.312426 | 
2026-09-17 19:29:27.312671 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-17 19:29:28.170000 | localhost -> localhost | changed
2026-09-17 19:29:28.181057 | 
2026-09-17 19:29:28.181178 | PLAY [all]
2026-09-17 19:29:28.194788 | 
2026-09-17 19:29:28.194967 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-17 19:29:28.471793 | instance -> localhost | ok
2026-09-17 19:29:28.478437 | 
2026-09-17 19:29:28.478537 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-17 19:29:28.507508 | instance | ok
2026-09-17 19:29:28.523887 | instance | included: /var/lib/zuul/builds/a2d35a242255493ba372e2fb9cca335d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-17 19:29:28.532756 | 
2026-09-17 19:29:28.532878 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-17 19:29:29.416937 | instance -> localhost | Generating public/private rsa key pair.
2026-09-17 19:29:29.417170 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/a2d35a242255493ba372e2fb9cca335d/work/a2d35a242255493ba372e2fb9cca335d_id_rsa
2026-09-17 19:29:29.417221 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/a2d35a242255493ba372e2fb9cca335d/work/a2d35a242255493ba372e2fb9cca335d_id_rsa.pub
2026-09-17 19:29:29.417263 | instance -> localhost | The key fingerprint is:
2026-09-17 19:29:29.417301 | instance -> localhost | SHA256:t3MW5Y6r+cpZibyRcW9tDJxaumt6jHF4ovZTlJhxywc zuul-build-sshkey
2026-09-17 19:29:29.417356 | instance -> localhost | The key's randomart image is:
2026-09-17 19:29:29.417393 | instance -> localhost | +---[RSA 3072]----+
2026-09-17 19:29:29.417436 | instance -> localhost | |                 |
2026-09-17 19:29:29.417472 | instance -> localhost | |          . E    |
2026-09-17 19:29:29.417506 | instance -> localhost | |           * +.  |
2026-09-17 19:29:29.417540 | instance -> localhost | |          o =+.. |
2026-09-17 19:29:29.417573 | instance -> localhost | |        S ooo.*  |
2026-09-17 19:29:29.417608 | instance -> localhost | |         o+*+X + |
2026-09-17 19:29:29.417666 | instance -> localhost | |         .BOO + +|
2026-09-17 19:29:29.417707 | instance -> localhost | |        o.oX++ . |
2026-09-17 19:29:29.417747 | instance -> localhost | |       . .XO=.   |
2026-09-17 19:29:29.418009 | instance -> localhost | +----[SHA256]-----+
2026-09-17 19:29:29.418105 | instance -> localhost | ok: Runtime: 0:00:00.498401
2026-09-17 19:29:29.429094 | 
2026-09-17 19:29:29.429273 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-17 19:29:29.459718 | instance | ok
2026-09-17 19:29:29.472882 | instance | included: /var/lib/zuul/builds/a2d35a242255493ba372e2fb9cca335d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-17 19:29:29.483834 | 
2026-09-17 19:29:29.483963 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-17 19:29:29.507395 | instance | skipping: Conditional result was False
2026-09-17 19:29:29.514642 | 
2026-09-17 19:29:29.514795 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-17 19:29:30.001225 | instance | changed
2026-09-17 19:29:30.008959 | 
2026-09-17 19:29:30.009104 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-17 19:29:30.192695 | instance | ok
2026-09-17 19:29:30.197306 | 
2026-09-17 19:29:30.197369 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-17 19:29:30.675365 | instance | changed
2026-09-17 19:29:30.681805 | 
2026-09-17 19:29:30.681922 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-17 19:29:31.156161 | instance | changed
2026-09-17 19:29:31.168397 | 
2026-09-17 19:29:31.168582 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-17 19:29:31.192679 | instance | skipping: Conditional result was False
2026-09-17 19:29:31.203942 | 
2026-09-17 19:29:31.204129 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-17 19:29:31.646193 | instance -> localhost | changed
2026-09-17 19:29:31.672383 | 
2026-09-17 19:29:31.672751 | TASK [add-build-sshkey : Add back temp key]
2026-09-17 19:29:31.981223 | instance -> localhost | Identity added: /var/lib/zuul/builds/a2d35a242255493ba372e2fb9cca335d/work/a2d35a242255493ba372e2fb9cca335d_id_rsa (zuul-build-sshkey)
2026-09-17 19:29:31.981568 | instance -> localhost | ok: Runtime: 0:00:00.007868
2026-09-17 19:29:31.988150 | 
2026-09-17 19:29:31.988223 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-17 19:29:32.330964 | instance | ok
2026-09-17 19:29:32.340207 | 
2026-09-17 19:29:32.340329 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-17 19:29:32.375623 | instance | skipping: Conditional result was False
2026-09-17 19:29:32.396490 | 
2026-09-17 19:29:32.396707 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-17 19:29:32.776954 | instance | ok
2026-09-17 19:29:32.793253 | 
2026-09-17 19:29:32.793485 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-17 19:29:36.453234 | instance | Output suppressed because no_log was given
2026-09-17 19:29:36.469845 | 
2026-09-17 19:29:36.469968 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-17 19:29:36.689815 | instance | ok: "logs"
2026-09-17 19:29:36.690142 | instance | ok: All items complete
2026-09-17 19:29:36.690197 | 
2026-09-17 19:29:36.859397 | instance | ok: "artifacts"
2026-09-17 19:29:37.037429 | instance | ok: "docs"
2026-09-17 19:29:37.056531 | 
2026-09-17 19:29:37.057130 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-17 19:29:37.253610 | instance | changed: "logs"
2026-09-17 19:29:37.433198 | instance | changed: "artifacts"
2026-09-17 19:29:37.611180 | instance | changed: "docs"
2026-09-17 19:29:37.628222 | 
2026-09-17 19:29:37.628325 | PLAY RECAP
2026-09-17 19:29:37.628385 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-17 19:29:37.628426 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-17 19:29:37.628456 | 
2026-09-17 19:29:37.741340 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-17 19:29:37.744828 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-17 19:29:38.308821 | 
2026-09-17 19:29:38.308948 | PLAY [all]
2026-09-17 19:29:38.318937 | 
2026-09-17 19:29:38.319037 | TASK [setup-uv : Extract archive]
2026-09-17 19:29:40.716622 | instance | changed
2026-09-17 19:29:40.730271 | 
2026-09-17 19:29:40.730455 | TASK [setup-uv : Print version]
2026-09-17 19:29:41.109252 | instance | uv 0.8.13
2026-09-17 19:29:41.281234 | instance | ok: Runtime: 0:00:00.014169
2026-09-17 19:29:41.298389 | 
2026-09-17 19:29:41.298518 | TASK [Install Ansible collection siblings]
2026-09-17 19:29:51.350832 | instance | changed:
2026-09-17 19:29:51.351155 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-17 19:29:51.351257 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-17 19:29:51.351339 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-17 19:29:51.351415 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-17 19:29:51.351490 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-17 19:29:51.351577 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-17 19:29:51.351681 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-17 19:29:51.351772 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-17 19:29:51.351846 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-17 19:29:51.351918 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-17 19:29:51.351990 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-17 19:29:51.352062 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-17 19:29:51.352133 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-09-17 19:29:51.352203 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-17 19:29:51.352274 | instance | Resolved ansible.utils to v6.1.1 for >=2.9.0
2026-09-17 19:29:51.352346 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-17 19:29:51.352416 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-17 19:29:51.352487 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-17 19:29:51.352606 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-17 19:29:51.352707 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-09-17 19:29:51.352783 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0
2026-09-17 19:29:51.352854 | instance | Resolved atmosphere.common to v0.7.1 for >=0.7.0
2026-09-17 19:29:51.352931 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-17 19:29:51.353025 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-17 19:29:51.353101 | instance | Resolved ansible.utils to v6.1.1 for >=2.9.0,>=6.0.0
2026-09-17 19:29:51.353172 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-17 19:29:51.353242 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-17 19:29:51.353312 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-17 19:29:51.353381 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-17 19:29:51.353450 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-17 19:29:51.353519 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-17 19:29:51.353591 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-17 19:29:51.353688 | instance | Checked out ansible.netcommon to 1.2.0
2026-09-17 19:29:51.353752 | 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-17 19:29:51.353803 | instance | Starting galaxy collection install process
2026-09-17 19:29:51.353840 | instance | Process install dependency map
2026-09-17 19:29:51.353876 | instance | Starting collection install process
2026-09-17 19:29:51.353911 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-17 19:29:51.353946 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-17 19:29:51.353980 | instance | ansible.netcommon:* was installed successfully
2026-09-17 19:29:51.354014 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-17 19:29:51.354048 | instance | Creating virtual environment at: .venv
2026-09-17 19:29:51.354082 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-17 19:29:51.354115 | instance | Downloading setuptools (1.1MiB)
2026-09-17 19:29:51.354149 | instance | Downloading ansible-core (2.1MiB)
2026-09-17 19:29:51.354192 | instance | Downloading cryptography (4.2MiB)
2026-09-17 19:29:51.354227 | instance | Downloading pydantic-core (2.0MiB)
2026-09-17 19:29:51.354261 | instance | Downloading rjsonnet (1.2MiB)
2026-09-17 19:29:51.354294 | instance | Downloading netaddr (2.2MiB)
2026-09-17 19:29:51.354329 | instance | Downloading kubernetes (1.9MiB)
2026-09-17 19:29:51.354364 | instance | Downloading openstacksdk (1.7MiB)
2026-09-17 19:29:51.354400 | instance | Downloading pygments (1.2MiB)
2026-09-17 19:29:51.354434 | instance |  Downloading rjsonnet
2026-09-17 19:29:51.354473 | instance |    Building pyperclip==1.9.0
2026-09-17 19:29:51.354508 | instance |  Downloading pydantic-core
2026-09-17 19:29:51.354542 | instance |  Downloading netaddr
2026-09-17 19:29:51.354578 | instance |  Downloading pygments
2026-09-17 19:29:51.354613 | instance |  Downloading cryptography
2026-09-17 19:29:51.354647 | instance |  Downloading setuptools
2026-09-17 19:29:51.354700 | instance |  Downloading kubernetes
2026-09-17 19:29:51.354736 | instance |  Downloading ansible-core
2026-09-17 19:29:51.354770 | instance |  Downloading openstacksdk
2026-09-17 19:29:51.354803 | instance |       Built pyperclip==1.9.0
2026-09-17 19:29:51.354836 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-17 19:29:51.354870 | instance | Installed 83 packages in 387ms
2026-09-17 19:29:51.354903 | instance | Checked out ansible.posix to 1.6.0
2026-09-17 19:29:51.354936 | 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-17 19:29:51.354970 | instance | Starting galaxy collection install process
2026-09-17 19:29:51.355006 | instance | Process install dependency map
2026-09-17 19:29:51.355053 | instance | Starting collection install process
2026-09-17 19:29:51.355088 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-17 19:29:51.355121 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-17 19:29:51.355154 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-17 19:29:51.355187 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:29:51.355220 | instance | 2.17.12
2026-09-17 19:29:51.355255 | instance | Checked out ansible.utils to v6.1.1
2026-09-17 19:29:51.355289 | 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-17 19:29:51.355322 | instance | Starting galaxy collection install process
2026-09-17 19:29:51.355355 | instance | Process install dependency map
2026-09-17 19:29:51.355388 | instance | Starting collection install process
2026-09-17 19:29:51.355421 | instance | Installing 'ansible.utils:6.1.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-17 19:29:51.355456 | instance | Created collection for ansible.utils:6.1.1 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-17 19:29:51.355489 | instance | ansible.utils:6.1.1 was installed successfully
2026-09-17 19:29:51.355523 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:29:51.355557 | instance | 2.17.12
2026-09-17 19:29:51.355591 | instance | Checked out atmosphere.common to v0.7.1
2026-09-17 19:29:51.355625 | 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-17 19:29:51.355681 | instance | Starting galaxy collection install process
2026-09-17 19:29:51.355716 | instance | Process install dependency map
2026-09-17 19:29:51.355749 | instance | Starting collection install process
2026-09-17 19:29:51.355783 | instance | Installing 'atmosphere.common:0.7.1' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-17 19:29:51.355817 | instance | Created collection for atmosphere.common:0.7.1 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-17 19:29:51.355851 | instance | atmosphere.common:0.7.1 was installed successfully
2026-09-17 19:29:51.355885 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:29:51.355920 | instance | 2.17.12
2026-09-17 19:29:51.355956 | instance | Checked out community.crypto to 2.2.3
2026-09-17 19:29:51.355994 | 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-17 19:29:51.356029 | instance | Starting galaxy collection install process
2026-09-17 19:29:51.356064 | instance | Process install dependency map
2026-09-17 19:29:51.356099 | instance | Starting collection install process
2026-09-17 19:29:51.356133 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-17 19:29:51.356168 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-17 19:29:51.356201 | instance | community.crypto:2.2.3 was installed successfully
2026-09-17 19:29:51.356235 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:29:51.356269 | instance | 2.17.12
2026-09-17 19:29:51.356303 | instance | Checked out community.general to 7.3.0
2026-09-17 19:29:51.356337 | 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-17 19:29:51.356380 | instance | Starting galaxy collection install process
2026-09-17 19:29:51.356416 | instance | Process install dependency map
2026-09-17 19:29:51.356450 | instance | Starting collection install process
2026-09-17 19:29:51.356484 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-17 19:29:51.356570 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-17 19:29:51.356607 | instance | community.general:7.3.0 was installed successfully
2026-09-17 19:29:51.356642 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:29:51.356699 | instance | 2.17.12
2026-09-17 19:29:51.356736 | instance | Checked out community.mysql to 3.6.0
2026-09-17 19:29:51.356798 | 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-17 19:29:51.356838 | instance | Starting galaxy collection install process
2026-09-17 19:29:51.356874 | instance | Process install dependency map
2026-09-17 19:29:51.356908 | instance | Starting collection install process
2026-09-17 19:29:51.356942 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-17 19:29:51.356976 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-17 19:29:51.357010 | instance | community.mysql:3.6.0 was installed successfully
2026-09-17 19:29:51.357044 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:29:51.357077 | instance | 2.17.12
2026-09-17 19:29:51.357111 | instance | Checked out kubernetes.core to 2.4.0
2026-09-17 19:29:51.357146 | 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-17 19:29:51.357278 | instance | Starting galaxy collection install process
2026-09-17 19:29:51.357485 | instance | Process install dependency map
2026-09-17 19:29:51.357545 | instance | Starting collection install process
2026-09-17 19:29:51.357586 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-17 19:29:51.357622 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-17 19:29:51.357688 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-17 19:29:51.357730 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:29:51.357766 | instance | 2.17.12
2026-09-17 19:29:51.357802 | instance | Checked out openstack.cloud to 2.6.0
2026-09-17 19:29:51.357837 | 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-17 19:29:51.357872 | instance | Starting galaxy collection install process
2026-09-17 19:29:51.357906 | instance | Process install dependency map
2026-09-17 19:29:51.357940 | instance | Starting collection install process
2026-09-17 19:29:51.357973 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-17 19:29:51.358007 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-17 19:29:51.358039 | instance | openstack.cloud:2.6.0 was installed successfully
2026-09-17 19:29:51.358072 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:29:51.358105 | instance | 2.17.12
2026-09-17 19:29:51.358149 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-17 19:29:51.358185 | 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-17 19:29:51.358218 | instance | Starting galaxy collection install process
2026-09-17 19:29:51.358251 | instance | Process install dependency map
2026-09-17 19:29:51.358284 | instance | Starting collection install process
2026-09-17 19:29:51.358318 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-17 19:29:51.358351 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-17 19:29:51.358385 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-17 19:29:51.358417 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:29:51.358450 | instance | 2.17.12
2026-09-17 19:29:51.358483 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-17 19:29:51.358516 | 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-17 19:29:51.358549 | instance | Starting galaxy collection install process
2026-09-17 19:29:51.358584 | instance | Process install dependency map
2026-09-17 19:29:51.358617 | instance | Starting collection install process
2026-09-17 19:29:51.358672 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-17 19:29:51.358716 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-17 19:29:51.358752 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-17 19:29:51.358787 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:29:51.358822 | instance | 2.17.12
2026-09-17 19:29:51.358856 | instance | Checked out vexxhost.kubernetes to v3.4.0
2026-09-17 19:29:51.358893 | 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-17 19:29:51.359051 | instance | Starting galaxy collection install process
2026-09-17 19:29:51.359126 | instance | Process install dependency map
2026-09-17 19:29:51.359169 | instance | Starting collection install process
2026-09-17 19:29:51.359208 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-17 19:29:51.359245 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-17 19:29:51.359283 | instance | vexxhost.kubernetes:3.4.0 was installed successfully
2026-09-17 19:29:51.359321 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:29:51.359357 | instance | 2.17.12
2026-09-17 19:29:51.370810 | 
2026-09-17 19:29:51.370918 | PLAY RECAP
2026-09-17 19:29:51.371002 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-17 19:29:51.371047 | 
2026-09-17 19:29:51.493703 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-17 19:29:51.495440 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-17 19:29:52.130136 | 
2026-09-17 19:29:52.130279 | PLAY [all]
2026-09-17 19:29:52.140422 | 
2026-09-17 19:29:52.140487 | TASK [Install "jq" for log collection]
2026-09-17 19:30:03.327632 | instance | changed
2026-09-17 19:30:03.331134 | 
2026-09-17 19:30:03.331364 | PLAY RECAP
2026-09-17 19:30:03.331472 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-17 19:30:03.331587 | 
2026-09-17 19:30:03.449955 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-17 19:30:03.452058 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-17 19:30:04.070465 | 
2026-09-17 19:30:04.070580 | PLAY [all]
2026-09-17 19:30:04.080567 | 
2026-09-17 19:30:04.080634 | LOOP [Check inventory directories]
2026-09-17 19:30:04.410935 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-17 19:30:04.584199 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-17 19:30:04.787106 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-17 19:30:04.806634 | 
2026-09-17 19:30:04.806981 | LOOP [Copy inventory file for Zuul]
2026-09-17 19:30:05.692895 | instance | changed:
2026-09-17 19:30:05.693059 | instance | {
2026-09-17 19:30:05.693085 | instance |   "ansible_loop_var": "item",
2026-09-17 19:30:05.693104 | instance |   "changed": false,
2026-09-17 19:30:05.693122 | instance |   "failed": false,
2026-09-17 19:30:05.693142 | instance |   "invocation": {
2026-09-17 19:30:05.693158 | instance |     "module_args": {
2026-09-17 19:30:05.693174 | instance |       "checksum_algorithm": "sha1",
2026-09-17 19:30:05.693189 | instance |       "follow": true,
2026-09-17 19:30:05.693205 | instance |       "get_attributes": true,
2026-09-17 19:30:05.693221 | instance |       "get_checksum": true,
2026-09-17 19:30:05.693237 | instance |       "get_mime": true,
2026-09-17 19:30:05.693252 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-17 19:30:05.693267 | instance |     }
2026-09-17 19:30:05.693282 | instance |   },
2026-09-17 19:30:05.693297 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-17 19:30:05.693312 | instance |   "stat": {
2026-09-17 19:30:05.693326 | instance |     "atime": 1789673383.2707684,
2026-09-17 19:30:05.693342 | instance |     "attr_flags": "e",
2026-09-17 19:30:05.693357 | instance |     "attributes": [
2026-09-17 19:30:05.693372 | instance |       "extents"
2026-09-17 19:30:05.693387 | instance |     ],
2026-09-17 19:30:05.693401 | instance |     "block_size": 4096,
2026-09-17 19:30:05.693416 | instance |     "blocks": 8,
2026-09-17 19:30:05.693430 | instance |     "charset": "unknown",
2026-09-17 19:30:05.693446 | instance |     "ctime": 1789673382.198717,
2026-09-17 19:30:05.693462 | instance |     "dev": 64513,
2026-09-17 19:30:05.693477 | instance |     "device_type": 0,
2026-09-17 19:30:05.693491 | instance |     "executable": true,
2026-09-17 19:30:05.693506 | instance |     "exists": true,
2026-09-17 19:30:05.693521 | instance |     "gid": 1000,
2026-09-17 19:30:05.693536 | instance |     "gr_name": "zuul",
2026-09-17 19:30:05.693550 | instance |     "inode": 1048772,
2026-09-17 19:30:05.693564 | instance |     "isblk": false,
2026-09-17 19:30:05.693579 | instance |     "ischr": false,
2026-09-17 19:30:05.693594 | instance |     "isdir": true,
2026-09-17 19:30:05.693608 | instance |     "isfifo": false,
2026-09-17 19:30:05.693623 | instance |     "isgid": false,
2026-09-17 19:30:05.693637 | instance |     "islnk": false,
2026-09-17 19:30:05.693652 | instance |     "isreg": false,
2026-09-17 19:30:05.693679 | instance |     "issock": false,
2026-09-17 19:30:05.693695 | instance |     "isuid": false,
2026-09-17 19:30:05.693709 | instance |     "mimetype": "unknown",
2026-09-17 19:30:05.693724 | instance |     "mode": "0755",
2026-09-17 19:30:05.693738 | instance |     "mtime": 1789673382.198717,
2026-09-17 19:30:05.693752 | instance |     "nlink": 23,
2026-09-17 19:30:05.693767 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-17 19:30:05.693782 | instance |     "pw_name": "zuul",
2026-09-17 19:30:05.693801 | instance |     "readable": true,
2026-09-17 19:30:05.693816 | instance |     "rgrp": true,
2026-09-17 19:30:05.693831 | instance |     "roth": true,
2026-09-17 19:30:05.693857 | instance |     "rusr": true,
2026-09-17 19:30:05.693872 | instance |     "size": 4096,
2026-09-17 19:30:05.693887 | instance |     "uid": 1000,
2026-09-17 19:30:05.693902 | instance |     "version": "3044273833",
2026-09-17 19:30:05.693918 | instance |     "wgrp": false,
2026-09-17 19:30:05.693934 | instance |     "woth": false,
2026-09-17 19:30:05.693950 | instance |     "writeable": true,
2026-09-17 19:30:05.693965 | instance |     "wusr": true,
2026-09-17 19:30:05.693979 | instance |     "xgrp": true,
2026-09-17 19:30:05.693994 | instance |     "xoth": true,
2026-09-17 19:30:05.694009 | instance |     "xusr": true
2026-09-17 19:30:05.694023 | instance |   }
2026-09-17 19:30:05.694038 | instance | }
2026-09-17 19:30:05.694066 | instance | ok: All items complete
2026-09-17 19:30:05.694084 | 
2026-09-17 19:30:05.698391 | instance | skipping: Conditional result was False
2026-09-17 19:30:05.707289 | instance | skipping: Conditional result was False
2026-09-17 19:30:05.730454 | 
2026-09-17 19:30:05.730742 | LOOP [Switch "ansible_host" to private IP]
2026-09-17 19:30:06.096583 | instance | changed: 1 replacements made
2026-09-17 19:30:06.099049 | instance | skipping: Conditional result was False
2026-09-17 19:30:06.106083 | instance | skipping: Conditional result was False
2026-09-17 19:30:06.129565 | 
2026-09-17 19:30:06.129833 | TASK [Run Molecule scenario]
2026-09-17 19:30:07.111024 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-17 19:30:07.556885 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-17 19:30:07.557071 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-17 19:30:18.869635 | instance | INFO     [csi > dependency] Executing
2026-09-17 19:30:18.870268 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-17 19:30:18.870748 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-17 19:30:18.871057 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-17 19:30:18.885982 | instance | INFO     [csi > cleanup] Executing
2026-09-17 19:30:18.886488 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-17 19:30:18.896320 | instance | INFO     [csi > destroy] Executing
2026-09-17 19:30:18.896465 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-17 19:30:18.896598 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-17 19:30:18.906415 | instance | INFO     [csi > syntax] Executing
2026-09-17 19:30:20.548690 | instance |
2026-09-17 19:30:20.549134 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-17 19:30:20.623051 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-17 19:30:20.637373 | instance | INFO     [csi > create] Executing
2026-09-17 19:30:20.642005 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-17 19:30:20.656167 | instance | INFO     [csi > prepare] Executing
2026-09-17 19:30:21.556741 | instance |
2026-09-17 19:30:21.557270 | instance | PLAY [Prepare] *****************************************************************
2026-09-17 19:30:21.557799 | instance |
2026-09-17 19:30:21.558133 | instance | TASK [Gathering Facts] *********************************************************
2026-09-17 19:30:21.558481 | instance | Thursday 17 September 2026  19:30:21 +0000 (0:00:00.026)       0:00:00.026 ****
2026-09-17 19:30:22.878654 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-17 19:30:22.879139 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-17 19:30:22.879512 | instance | interpreter could change the meaning of that path. See
2026-09-17 19:30:22.879897 | instance | https://docs.ansible.com/ansible-
2026-09-17 19:30:22.880265 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-17 19:30:22.889728 | instance | ok: [instance]
2026-09-17 19:30:22.890032 | instance |
2026-09-17 19:30:22.890382 | instance | TASK [Configure short hostname] ************************************************
2026-09-17 19:30:22.890697 | instance | Thursday 17 September 2026  19:30:22 +0000 (0:00:01.333)       0:00:01.360 ****
2026-09-17 19:30:23.675806 | instance | changed: [instance]
2026-09-17 19:30:23.676193 | instance |
2026-09-17 19:30:23.676521 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-17 19:30:23.676833 | instance | Thursday 17 September 2026  19:30:23 +0000 (0:00:00.785)       0:00:02.146 ****
2026-09-17 19:30:23.967690 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-17 19:30:23.968104 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-17 19:30:23.968520 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-17 19:30:23.977902 | instance | changed: [instance]
2026-09-17 19:30:23.978257 | instance |
2026-09-17 19:30:23.978614 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-17 19:30:23.978973 | instance | Thursday 17 September 2026  19:30:23 +0000 (0:00:00.302)       0:00:02.448 ****
2026-09-17 19:30:24.865296 | instance | ok: [instance]
2026-09-17 19:30:24.865478 | instance |
2026-09-17 19:30:24.865661 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-17 19:30:24.865843 | instance |
2026-09-17 19:30:24.866028 | instance | TASK [Gathering Facts] *********************************************************
2026-09-17 19:30:24.866254 | instance | Thursday 17 September 2026  19:30:24 +0000 (0:00:00.887)       0:00:03.336 ****
2026-09-17 19:30:25.579675 | instance | ok: [instance]
2026-09-17 19:30:25.580019 | instance |
2026-09-17 19:30:25.580360 | instance | TASK [Install depedencies] *****************************************************
2026-09-17 19:30:25.580665 | instance | Thursday 17 September 2026  19:30:25 +0000 (0:00:00.713)       0:00:04.050 ****
2026-09-17 19:30:50.787048 | instance | changed: [instance]
2026-09-17 19:30:50.788014 | instance |
2026-09-17 19:30:50.788070 | instance | TASK [Start up service] ********************************************************
2026-09-17 19:30:50.788083 | instance | Thursday 17 September 2026  19:30:50 +0000 (0:00:25.207)       0:00:29.257 ****
2026-09-17 19:30:51.356064 | instance | ok: [instance]
2026-09-17 19:30:51.356130 | instance |
2026-09-17 19:30:51.356142 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-17 19:30:51.356152 | instance | Thursday 17 September 2026  19:30:51 +0000 (0:00:00.566)       0:00:29.824 ****
2026-09-17 19:30:51.665522 | instance | ok: [instance]
2026-09-17 19:30:51.665635 | instance |
2026-09-17 19:30:51.665656 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-17 19:30:51.665670 | instance | Thursday 17 September 2026  19:30:51 +0000 (0:00:00.310)       0:00:30.135 ****
2026-09-17 19:30:52.445764 | instance | changed: [instance]
2026-09-17 19:30:52.446099 | instance |
2026-09-17 19:30:52.446117 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-17 19:30:52.446137 | instance | Thursday 17 September 2026  19:30:52 +0000 (0:00:00.780)       0:00:30.915 ****
2026-09-17 19:30:52.674560 | instance | ok: [instance]
2026-09-17 19:30:52.674794 | instance |
2026-09-17 19:30:52.675052 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-17 19:30:52.675349 | instance | Thursday 17 September 2026  19:30:52 +0000 (0:00:00.229)       0:00:31.145 ****
2026-09-17 19:30:52.700166 | instance | skipping: [instance]
2026-09-17 19:30:52.700516 | instance |
2026-09-17 19:30:52.701012 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-17 19:30:52.701335 | instance | Thursday 17 September 2026  19:30:52 +0000 (0:00:00.026)       0:00:31.171 ****
2026-09-17 19:30:53.311006 | instance | changed: [instance] => (item=osd0)
2026-09-17 19:30:53.311119 | instance | changed: [instance] => (item=osd1)
2026-09-17 19:30:53.311281 | instance | changed: [instance] => (item=osd2)
2026-09-17 19:30:53.311410 | instance |
2026-09-17 19:30:53.311546 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-17 19:30:53.311692 | instance | Thursday 17 September 2026  19:30:53 +0000 (0:00:00.610)       0:00:31.782 ****
2026-09-17 19:30:54.050373 | instance | changed: [instance] => (item=osd0)
2026-09-17 19:30:54.050415 | instance | changed: [instance] => (item=osd1)
2026-09-17 19:30:54.050420 | instance | changed: [instance] => (item=osd2)
2026-09-17 19:30:54.050425 | instance |
2026-09-17 19:30:54.050430 | instance | TASK [Start loop devices] ******************************************************
2026-09-17 19:30:54.050434 | instance | Thursday 17 September 2026  19:30:54 +0000 (0:00:00.738)       0:00:32.520 ****
2026-09-17 19:30:54.824654 | instance | changed: [instance] => (item=osd0)
2026-09-17 19:30:54.825693 | instance | changed: [instance] => (item=osd1)
2026-09-17 19:30:54.825753 | instance | changed: [instance] => (item=osd2)
2026-09-17 19:30:54.825761 | instance |
2026-09-17 19:30:54.825775 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-17 19:30:54.826023 | instance | Thursday 17 September 2026  19:30:54 +0000 (0:00:00.773)       0:00:33.294 ****
2026-09-17 19:30:58.213505 | instance | changed: [instance] => (item=osd0)
2026-09-17 19:30:58.214828 | instance | changed: [instance] => (item=osd1)
2026-09-17 19:30:58.214907 | instance | changed: [instance] => (item=osd2)
2026-09-17 19:30:58.214919 | instance |
2026-09-17 19:30:58.214929 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-17 19:30:58.214939 | instance | Thursday 17 September 2026  19:30:58 +0000 (0:00:03.389)       0:00:36.684 ****
2026-09-17 19:31:00.236805 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-17 19:31:00.237036 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-17 19:31:00.237484 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-17 19:31:00.237923 | instance |
2026-09-17 19:31:00.238282 | instance | PLAY RECAP *********************************************************************
2026-09-17 19:31:00.238614 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-17 19:31:00.238897 | instance |
2026-09-17 19:31:00.239247 | instance | Thursday 17 September 2026  19:31:00 +0000 (0:00:02.023)       0:00:38.707 ****
2026-09-17 19:31:00.239589 | instance | ===============================================================================
2026-09-17 19:31:00.239900 | instance | Install depedencies ---------------------------------------------------- 25.21s
2026-09-17 19:31:00.240194 | instance | Create a volume group for each loop device ------------------------------ 3.39s
2026-09-17 19:31:00.240518 | instance | Create a logical volume for each loop device ---------------------------- 2.02s
2026-09-17 19:31:00.240812 | instance | Gathering Facts --------------------------------------------------------- 1.33s
2026-09-17 19:31:00.241100 | instance | Purge "snapd" package --------------------------------------------------- 0.89s
2026-09-17 19:31:00.241408 | instance | Configure short hostname ------------------------------------------------ 0.79s
2026-09-17 19:31:00.241729 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.78s
2026-09-17 19:31:00.242022 | instance | Start loop devices ------------------------------------------------------ 0.77s
2026-09-17 19:31:00.242336 | instance | Set permissions on loopback devices ------------------------------------- 0.74s
2026-09-17 19:31:00.242625 | instance | Gathering Facts --------------------------------------------------------- 0.71s
2026-09-17 19:31:00.242913 | instance | Create devices for Ceph ------------------------------------------------- 0.61s
2026-09-17 19:31:00.243260 | instance | Start up service -------------------------------------------------------- 0.57s
2026-09-17 19:31:00.243562 | instance | Generate lvm.conf ------------------------------------------------------- 0.31s
2026-09-17 19:31:00.243854 | instance | Ensure hostname inside hosts file --------------------------------------- 0.30s
2026-09-17 19:31:00.244143 | instance | Get list of all loopback devices ---------------------------------------- 0.23s
2026-09-17 19:31:00.244539 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s
2026-09-17 19:31:00.341527 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-17 19:31:00.352349 | instance | INFO     [csi > converge] Executing
2026-09-17 19:31:01.942071 | instance |
2026-09-17 19:31:01.942390 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-17 19:31:01.942588 | instance |
2026-09-17 19:31:01.942816 | instance | TASK [Gathering Facts] *********************************************************
2026-09-17 19:31:01.943022 | instance | Thursday 17 September 2026  19:31:01 +0000 (0:00:00.008)       0:00:00.008 ****
2026-09-17 19:31:03.037803 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-17 19:31:03.038240 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-17 19:31:03.038561 | instance | interpreter could change the meaning of that path. See
2026-09-17 19:31:03.038852 | instance | https://docs.ansible.com/ansible-
2026-09-17 19:31:03.039142 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-17 19:31:03.049003 | instance | ok: [instance]
2026-09-17 19:31:03.049325 | instance |
2026-09-17 19:31:03.049647 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-17 19:31:03.049944 | instance | Thursday 17 September 2026  19:31:03 +0000 (0:00:01.106)       0:00:01.115 ****
2026-09-17 19:31:03.107835 | instance | ok: [instance] => {
2026-09-17 19:31:03.108259 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-17 19:31:03.108572 | instance | }
2026-09-17 19:31:03.108863 | instance |
2026-09-17 19:31:03.109170 | instance | PLAY [all] *********************************************************************
2026-09-17 19:31:03.109476 | instance |
2026-09-17 19:31:03.109815 | instance | TASK [Gathering Facts] *********************************************************
2026-09-17 19:31:03.110147 | instance | Thursday 17 September 2026  19:31:03 +0000 (0:00:00.058)       0:00:01.173 ****
2026-09-17 19:31:04.067468 | instance | ok: [instance]
2026-09-17 19:31:04.067835 | instance |
2026-09-17 19:31:04.068169 | instance | TASK [Fail if atmosphere_ceph_enabled is set] **********************************
2026-09-17 19:31:04.068626 | instance | Thursday 17 September 2026  19:31:04 +0000 (0:00:00.959)       0:00:02.133 ****
2026-09-17 19:31:04.121925 | instance | skipping: [instance]
2026-09-17 19:31:04.122170 | instance |
2026-09-17 19:31:04.122361 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-17 19:31:04.122587 | instance | Thursday 17 September 2026  19:31:04 +0000 (0:00:00.051)       0:00:02.184 ****
2026-09-17 19:31:04.337892 | instance | ok: [instance]
2026-09-17 19:31:04.338020 | instance |
2026-09-17 19:31:04.338048 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-17 19:31:04.338077 | instance |
2026-09-17 19:31:04.338090 | instance | TASK [Gathering Facts] *********************************************************
2026-09-17 19:31:04.338130 | instance | Thursday 17 September 2026  19:31:04 +0000 (0:00:00.219)       0:00:02.404 ****
2026-09-17 19:31:05.332999 | instance | ok: [instance]
2026-09-17 19:31:05.333088 | instance |
2026-09-17 19:31:05.333201 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-17 19:31:05.333386 | instance | Thursday 17 September 2026  19:31:05 +0000 (0:00:00.994)       0:00:03.398 ****
2026-09-17 19:31:05.635112 | instance | ok: [instance]
2026-09-17 19:31:05.635340 | instance |
2026-09-17 19:31:05.635596 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-17 19:31:05.635835 | instance | Thursday 17 September 2026  19:31:05 +0000 (0:00:00.302)       0:00:03.701 ****
2026-09-17 19:31:05.668212 | instance | skipping: [instance]
2026-09-17 19:31:05.668334 | instance |
2026-09-17 19:31:05.668356 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-17 19:31:05.668522 | instance | Thursday 17 September 2026  19:31:05 +0000 (0:00:00.033)       0:00:03.734 ****
2026-09-17 19:31:06.069500 | instance | changed: [instance]
2026-09-17 19:31:06.069587 | instance |
2026-09-17 19:31:06.069776 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-17 19:31:06.069928 | instance | Thursday 17 September 2026  19:31:06 +0000 (0:00:00.401)       0:00:04.135 ****
2026-09-17 19:31:06.152837 | instance | ok: [instance] => {
2026-09-17 19:31:06.153047 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-17 19:31:06.153328 | instance | }
2026-09-17 19:31:06.153582 | instance |
2026-09-17 19:31:06.153981 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-17 19:31:06.154423 | instance | Thursday 17 September 2026  19:31:06 +0000 (0:00:00.082)       0:00:04.217 ****
2026-09-17 19:31:06.888405 | instance | changed: [instance]
2026-09-17 19:31:06.888718 | instance |
2026-09-17 19:31:06.888937 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-17 19:31:06.889136 | instance | Thursday 17 September 2026  19:31:06 +0000 (0:00:00.736)       0:00:04.954 ****
2026-09-17 19:31:06.950490 | instance | skipping: [instance]
2026-09-17 19:31:06.950592 | instance |
2026-09-17 19:31:06.950719 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-17 19:31:06.950983 | instance | Thursday 17 September 2026  19:31:06 +0000 (0:00:00.061)       0:00:05.015 ****
2026-09-17 19:31:07.006424 | instance | skipping: [instance]
2026-09-17 19:31:07.006702 | instance |
2026-09-17 19:31:07.006951 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-17 19:31:07.007168 | instance | Thursday 17 September 2026  19:31:07 +0000 (0:00:00.053)       0:00:05.069 ****
2026-09-17 19:31:07.280821 | instance | ok: [instance]
2026-09-17 19:31:07.280881 | instance |
2026-09-17 19:31:07.280895 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-17 19:31:07.280909 | instance | Thursday 17 September 2026  19:31:07 +0000 (0:00:00.275)       0:00:05.345 ****
2026-09-17 19:31:08.896901 | instance | ok: [instance]
2026-09-17 19:31:08.896971 | instance |
2026-09-17 19:31:08.896980 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-17 19:31:08.897005 | instance | Thursday 17 September 2026  19:31:08 +0000 (0:00:01.616)       0:00:06.962 ****
2026-09-17 19:31:08.996081 | instance | ok: [instance] => {
2026-09-17 19:31:08.996158 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-17 19:31:08.996170 | instance | }
2026-09-17 19:31:08.996180 | instance |
2026-09-17 19:31:08.996189 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-17 19:31:08.996198 | instance | Thursday 17 September 2026  19:31:08 +0000 (0:00:00.098)       0:00:07.060 ****
2026-09-17 19:31:10.157007 | instance | changed: [instance]
2026-09-17 19:31:10.157772 | instance |
2026-09-17 19:31:10.158032 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-17 19:31:10.158062 | instance | Thursday 17 September 2026  19:31:10 +0000 (0:00:01.162)       0:00:08.222 ****
2026-09-17 19:31:13.187058 | instance | changed: [instance]
2026-09-17 19:31:13.187703 | instance |
2026-09-17 19:31:13.187719 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-17 19:31:13.187730 | instance | Thursday 17 September 2026  19:31:13 +0000 (0:00:03.028)       0:00:11.251 ****
2026-09-17 19:31:13.229852 | instance | skipping: [instance]
2026-09-17 19:31:13.230043 | instance |
2026-09-17 19:31:13.230574 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-17 19:31:13.230814 | instance | Thursday 17 September 2026  19:31:13 +0000 (0:00:00.044)       0:00:11.296 ****
2026-09-17 19:31:13.263463 | instance | skipping: [instance]
2026-09-17 19:31:13.263653 | instance |
2026-09-17 19:31:13.263897 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-17 19:31:13.264139 | instance | Thursday 17 September 2026  19:31:13 +0000 (0:00:00.033)       0:00:11.329 ****
2026-09-17 19:31:13.295032 | instance | skipping: [instance]
2026-09-17 19:31:13.295168 | instance |
2026-09-17 19:31:13.295444 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-17 19:31:13.295677 | instance | Thursday 17 September 2026  19:31:13 +0000 (0:00:00.031)       0:00:11.361 ****
2026-09-17 19:31:19.645729 | instance | changed: [instance]
2026-09-17 19:31:19.645896 | instance |
2026-09-17 19:31:19.646085 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-17 19:31:19.646360 | instance | Thursday 17 September 2026  19:31:19 +0000 (0:00:06.350)       0:00:17.711 ****
2026-09-17 19:31:20.365117 | instance | changed: [instance]
2026-09-17 19:31:20.365239 | instance |
2026-09-17 19:31:20.365384 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-17 19:31:20.365547 | instance | Thursday 17 September 2026  19:31:20 +0000 (0:00:00.719)       0:00:18.430 ****
2026-09-17 19:31:21.433749 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-17 19:31:21.435619 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-17 19:31:21.435698 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-17 19:31:21.435726 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-17 19:31:21.435742 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-17 19:31:21.435752 | instance |
2026-09-17 19:31:21.435762 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-17 19:31:21.435770 | instance | Thursday 17 September 2026  19:31:21 +0000 (0:00:01.068)       0:00:19.499 ****
2026-09-17 19:31:22.113712 | instance | changed: [instance]
2026-09-17 19:31:22.113805 | instance |
2026-09-17 19:31:22.114677 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-17 19:31:22.114703 | instance | Thursday 17 September 2026  19:31:22 +0000 (0:00:00.656)       0:00:20.156 ****
2026-09-17 19:31:22.114710 | instance |
2026-09-17 19:31:22.114716 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-17 19:31:22.114722 | instance | Thursday 17 September 2026  19:31:22 +0000 (0:00:00.023)       0:00:20.179 ****
2026-09-17 19:31:23.047534 | instance | ok: [instance]
2026-09-17 19:31:23.047637 | instance |
2026-09-17 19:31:23.047649 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-17 19:31:23.047659 | instance | Thursday 17 September 2026  19:31:23 +0000 (0:00:00.932)       0:00:21.111 ****
2026-09-17 19:31:23.591373 | instance | changed: [instance]
2026-09-17 19:31:23.591761 | instance |
2026-09-17 19:31:23.591778 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-17 19:31:23.591788 | instance | Thursday 17 September 2026  19:31:23 +0000 (0:00:00.544)       0:00:21.655 ****
2026-09-17 19:31:24.231191 | instance | changed: [instance]
2026-09-17 19:31:24.232121 | instance |
2026-09-17 19:31:24.232160 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-17 19:31:24.232175 | instance | Thursday 17 September 2026  19:31:24 +0000 (0:00:00.640)       0:00:22.296 ****
2026-09-17 19:31:24.473513 | instance | ok: [instance]
2026-09-17 19:31:24.473571 | instance |
2026-09-17 19:31:24.473581 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-17 19:31:24.473610 | instance | Thursday 17 September 2026  19:31:24 +0000 (0:00:00.242)       0:00:22.538 ****
2026-09-17 19:31:24.563386 | instance | ok: [instance] => {
2026-09-17 19:31:24.563474 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-17 19:31:24.563487 | instance | }
2026-09-17 19:31:24.563497 | instance |
2026-09-17 19:31:24.563506 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-17 19:31:24.563517 | instance | Thursday 17 September 2026  19:31:24 +0000 (0:00:00.088)       0:00:22.627 ****
2026-09-17 19:31:25.433126 | instance | changed: [instance]
2026-09-17 19:31:25.433745 | instance |
2026-09-17 19:31:25.433851 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-17 19:31:25.434222 | instance | Thursday 17 September 2026  19:31:25 +0000 (0:00:00.871)       0:00:23.498 ****
2026-09-17 19:31:29.959135 | instance | changed: [instance]
2026-09-17 19:31:29.959226 | instance |
2026-09-17 19:31:29.959238 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-17 19:31:29.959248 | instance | Thursday 17 September 2026  19:31:29 +0000 (0:00:04.525)       0:00:28.024 ****
2026-09-17 19:31:31.317773 | instance | ok: [instance]
2026-09-17 19:31:31.317836 | instance |
2026-09-17 19:31:31.317848 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-17 19:31:31.318246 | instance | Thursday 17 September 2026  19:31:31 +0000 (0:00:01.358)       0:00:29.382 ****
2026-09-17 19:31:31.751800 | instance | changed: [instance]
2026-09-17 19:31:31.751877 | instance |
2026-09-17 19:31:31.752088 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-17 19:31:31.752139 | instance | Thursday 17 September 2026  19:31:31 +0000 (0:00:00.435)       0:00:29.818 ****
2026-09-17 19:31:32.275360 | instance | changed: [instance]
2026-09-17 19:31:32.276345 | instance |
2026-09-17 19:31:32.276385 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-17 19:31:32.276400 | instance | Thursday 17 September 2026  19:31:32 +0000 (0:00:00.522)       0:00:30.340 ****
2026-09-17 19:31:32.926175 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-17 19:31:32.926970 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-17 19:31:32.926994 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-17 19:31:32.927002 | instance |
2026-09-17 19:31:32.927009 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-17 19:31:32.927015 | instance | Thursday 17 September 2026  19:31:32 +0000 (0:00:00.651)       0:00:30.991 ****
2026-09-17 19:31:33.446004 | instance | changed: [instance]
2026-09-17 19:31:33.446814 | instance |
2026-09-17 19:31:33.446847 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-17 19:31:33.446860 | instance | Thursday 17 September 2026  19:31:33 +0000 (0:00:00.519)       0:00:31.511 ****
2026-09-17 19:31:33.943308 | instance | changed: [instance]
2026-09-17 19:31:33.943463 | instance |
2026-09-17 19:31:33.943484 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-17 19:31:33.943540 | instance | Thursday 17 September 2026  19:31:33 +0000 (0:00:00.483)       0:00:31.994 ****
2026-09-17 19:31:33.943979 | instance |
2026-09-17 19:31:33.944042 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-17 19:31:33.944058 | instance | Thursday 17 September 2026  19:31:33 +0000 (0:00:00.014)       0:00:32.009 ****
2026-09-17 19:31:34.707147 | instance | ok: [instance]
2026-09-17 19:31:34.708006 | instance |
2026-09-17 19:31:34.708062 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-17 19:31:34.708073 | instance | Thursday 17 September 2026  19:31:34 +0000 (0:00:00.763)       0:00:32.772 ****
2026-09-17 19:31:35.998885 | instance | changed: [instance]
2026-09-17 19:31:35.999746 | instance |
2026-09-17 19:31:35.999822 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-17 19:31:35.999835 | instance | Thursday 17 September 2026  19:31:35 +0000 (0:00:01.291)       0:00:34.064 ****
2026-09-17 19:31:36.646864 | instance | changed: [instance]
2026-09-17 19:31:36.646923 | instance |
2026-09-17 19:31:36.647167 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-17 19:31:36.647197 | instance | Thursday 17 September 2026  19:31:36 +0000 (0:00:00.648)       0:00:34.712 ****
2026-09-17 19:31:36.732901 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-17 19:31:36.733022 | instance |
2026-09-17 19:31:36.733622 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-17 19:31:36.733953 | instance | Thursday 17 September 2026  19:31:36 +0000 (0:00:00.085)       0:00:34.798 ****
2026-09-17 19:31:42.888311 | instance | changed: [instance]
2026-09-17 19:31:42.888424 | instance |
2026-09-17 19:31:42.888771 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-17 19:31:42.888820 | instance | Thursday 17 September 2026  19:31:42 +0000 (0:00:06.155)       0:00:40.954 ****
2026-09-17 19:31:43.613495 | instance | ok: [instance] => (item=chronyd)
2026-09-17 19:31:43.614530 | instance | ok: [instance] => (item=sshd)
2026-09-17 19:31:43.614588 | instance |
2026-09-17 19:31:43.614599 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-17 19:31:43.614609 | instance | Thursday 17 September 2026  19:31:43 +0000 (0:00:00.725)       0:00:41.679 ****
2026-09-17 19:31:45.703286 | instance | changed: [instance]
2026-09-17 19:31:45.704143 | instance |
2026-09-17 19:31:45.704252 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-09-17 19:31:45.704267 | instance | Thursday 17 September 2026  19:31:45 +0000 (0:00:02.088)       0:00:43.768 ****
2026-09-17 19:31:45.929556 | instance | ok: [instance]
2026-09-17 19:31:45.929717 | instance |
2026-09-17 19:31:45.929992 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-09-17 19:31:45.930268 | instance | Thursday 17 September 2026  19:31:45 +0000 (0:00:00.225)       0:00:43.994 ****
2026-09-17 19:31:46.463013 | instance | changed: [instance]
2026-09-17 19:31:46.463231 | instance |
2026-09-17 19:31:46.463540 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-09-17 19:31:46.463773 | instance | Thursday 17 September 2026  19:31:46 +0000 (0:00:00.533)       0:00:44.528 ****
2026-09-17 19:31:46.892070 | instance | changed: [instance]
2026-09-17 19:31:46.892279 | instance |
2026-09-17 19:31:46.892626 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-09-17 19:31:46.892787 | instance | Thursday 17 September 2026  19:31:46 +0000 (0:00:00.429)       0:00:44.957 ****
2026-09-17 19:31:47.027919 | instance | ok: [instance]
2026-09-17 19:31:47.028102 | instance |
2026-09-17 19:31:47.028365 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-09-17 19:31:47.028662 | instance | Thursday 17 September 2026  19:31:47 +0000 (0:00:00.135)       0:00:45.093 ****
2026-09-17 19:31:47.272581 | instance | ok: [instance] => (item=instance)
2026-09-17 19:31:47.272891 | instance |
2026-09-17 19:31:47.273207 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-09-17 19:31:47.273526 | instance | Thursday 17 September 2026  19:31:47 +0000 (0:00:00.244)       0:00:45.338 ****
2026-09-17 19:31:47.346056 | instance | ok: [instance]
2026-09-17 19:31:47.346211 | instance |
2026-09-17 19:31:47.346467 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-09-17 19:31:47.346703 | instance | Thursday 17 September 2026  19:31:47 +0000 (0:00:00.073)       0:00:45.411 ****
2026-09-17 19:31:47.432711 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-09-17 19:31:47.432886 | instance |
2026-09-17 19:31:47.433175 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-09-17 19:31:47.433429 | instance | Thursday 17 September 2026  19:31:47 +0000 (0:00:00.086)       0:00:45.498 ****
2026-09-17 19:31:47.783288 | instance | changed: [instance]
2026-09-17 19:31:47.783519 | instance |
2026-09-17 19:31:47.783867 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-09-17 19:31:47.784132 | instance | Thursday 17 September 2026  19:31:47 +0000 (0:00:00.350)       0:00:45.848 ****
2026-09-17 19:31:48.565700 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-09-17 19:31:48.565755 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-09-17 19:31:48.565772 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-09-17 19:31:48.566361 | instance |
2026-09-17 19:31:48.566383 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-09-17 19:31:48.566805 | instance | Thursday 17 September 2026  19:31:48 +0000 (0:00:00.782)       0:00:46.630 ****
2026-09-17 19:33:20.968569 | instance | ok: [instance]
2026-09-17 19:33:20.969027 | instance |
2026-09-17 19:33:20.969349 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-09-17 19:33:20.969360 | instance | Thursday 17 September 2026  19:33:20 +0000 (0:01:32.403)       0:02:19.034 ****
2026-09-17 19:33:21.230546 | instance | changed: [instance]
2026-09-17 19:33:21.230606 | instance |
2026-09-17 19:33:21.230617 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-09-17 19:33:21.230628 | instance | Thursday 17 September 2026  19:33:21 +0000 (0:00:00.260)       0:02:19.295 ****
2026-09-17 19:33:21.268019 | instance | ok: [instance]
2026-09-17 19:33:21.268062 | instance |
2026-09-17 19:33:21.268070 | instance | TASK [Install Ceph host] *******************************************************
2026-09-17 19:33:21.268076 | instance | Thursday 17 September 2026  19:33:21 +0000 (0:00:00.038)       0:02:19.333 ****
2026-09-17 19:33:21.380986 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-09-17 19:33:21.381018 | instance |
2026-09-17 19:33:21.381023 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-09-17 19:33:21.381028 | instance | Thursday 17 September 2026  19:33:21 +0000 (0:00:00.113)       0:02:19.447 ****
2026-09-17 19:33:23.146195 | instance | ok: [instance]
2026-09-17 19:33:23.146316 | instance |
2026-09-17 19:33:23.146490 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-09-17 19:33:23.146656 | instance | Thursday 17 September 2026  19:33:23 +0000 (0:00:01.765)       0:02:21.212 ****
2026-09-17 19:33:23.223626 | instance | ok: [instance] => (item=instance)
2026-09-17 19:33:23.223824 | instance |
2026-09-17 19:33:23.224087 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-09-17 19:33:23.224344 | instance | Thursday 17 September 2026  19:33:23 +0000 (0:00:00.076)       0:02:21.289 ****
2026-09-17 19:33:23.613682 | instance | ok: [instance]
2026-09-17 19:33:23.614361 | instance |
2026-09-17 19:33:23.614422 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-09-17 19:33:23.614440 | instance | Thursday 17 September 2026  19:33:23 +0000 (0:00:00.389)       0:02:21.679 ****
2026-09-17 19:33:25.920884 | instance | ok: [instance]
2026-09-17 19:33:25.920988 | instance |
2026-09-17 19:33:25.921285 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-09-17 19:33:25.921306 | instance | Thursday 17 September 2026  19:33:25 +0000 (0:00:02.308)       0:02:23.987 ****
2026-09-17 19:34:10.609564 | 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-17 19:34:10.610143 | instance | fatal: [instance]: FAILED! => {"changed": false, "module_stderr": "Traceback (most recent call last):\n  File \"<stdin>\", line 107, in <module>\n  File \"<stdin>\", line 99, in _ansiballz_main\n  File \"<stdin>\", 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_jpsm8i_9/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 202, in <module>\n  File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_jpsm8i_9/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_jpsm8i_9/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_jpsm8i_9/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-17T19:34:10.395+0000 7f36c19c0640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgBYQKxqC+jsHiAAgOCQoFDCvem4BNLU2xqZJs7m0csWkBhXUODQNeMYFJM=: Malformed input [buffer:3]\n2026-09-17T19:34:10.395+0000 7f36c19c0640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-17T19:34:10.395+0000 7f36c19c0640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgBYQKxqC+jsHiAAgOCQoFDCvem4BNLU2xqZJs7m0csWkBhXUODQNeMYFJM=: Malformed input [buffer:3]\n2026-09-17T19:34:10.395+0000 7f36c19c0640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-17T19:34:10.399+0000 7f36c19c0640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgBYQKxqC+jsHiAAgOCQoFDCvem4BNLU2xqZJs7m0csWkBhXUODQNeMYFJM=: Malformed input [buffer:3]\n2026-09-17T19:34:10.399+0000 7f36c19c0640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-17T19:34:10.399+0000 7f36c19c0640 -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-17 19:34:10.612289 | instance |
2026-09-17 19:34:10.613634 | instance | PLAY RECAP *********************************************************************
2026-09-17 19:34:10.613655 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=7    rescued=0    ignored=0
2026-09-17 19:34:10.613662 | instance |
2026-09-17 19:34:10.613684 | instance | Thursday 17 September 2026  19:34:10 +0000 (0:00:44.691)       0:03:08.679 ****
2026-09-17 19:34:10.613690 | instance | ===============================================================================
2026-09-17 19:34:10.613695 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 92.40s
2026-09-17 19:34:10.613701 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 44.69s
2026-09-17 19:34:10.613709 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 6.35s
2026-09-17 19:34:10.616414 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 6.16s
2026-09-17 19:34:10.616436 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.53s
2026-09-17 19:34:10.616442 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 3.03s
2026-09-17 19:34:10.616459 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.31s
2026-09-17 19:34:10.616465 | instance | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 2.09s
2026-09-17 19:34:10.616470 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.77s
2026-09-17 19:34:10.616475 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.70s
2026-09-17 19:34:10.616481 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.62s
2026-09-17 19:34:10.616486 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.36s
2026-09-17 19:34:10.616491 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 1.29s
2026-09-17 19:34:10.616496 | instance | vexxhost.containers.download_artifact : Download item ------------------- 1.16s
2026-09-17 19:34:10.616501 | instance | Gathering Facts --------------------------------------------------------- 1.11s
2026-09-17 19:34:10.616506 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.07s
2026-09-17 19:34:10.616512 | instance | Gathering Facts --------------------------------------------------------- 0.99s
2026-09-17 19:34:10.616517 | instance | Gathering Facts --------------------------------------------------------- 0.96s
2026-09-17 19:34:10.616522 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.87s
2026-09-17 19:34:10.616527 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.78s
2026-09-17 19:34:10.718027 | 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-17 19:34:10.718293 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-17 19:34:10.718478 | 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-17 19:34:11.008911 | instance | ERROR
2026-09-17 19:34:11.009218 | instance | {
2026-09-17 19:34:11.009293 | instance |   "delta": "0:04:04.299709",
2026-09-17 19:34:11.009345 | instance |   "end": "2026-09-17 19:34:10.793192",
2026-09-17 19:34:11.009393 | instance |   "msg": "non-zero return code",
2026-09-17 19:34:11.009436 | instance |   "rc": 2,
2026-09-17 19:34:11.009471 | instance |   "start": "2026-09-17 19:30:06.493483"
2026-09-17 19:34:11.009509 | instance | }
failure
2026-09-17 19:34:11.021978 | 
2026-09-17 19:34:11.022118 | PLAY RECAP
2026-09-17 19:34:11.022205 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-17 19:34:11.022246 | 
2026-09-17 19:34:11.133602 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-17 19:34:11.135414 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-17 19:34:11.783034 | 
2026-09-17 19:34:11.783196 | PLAY [all]
2026-09-17 19:34:11.797479 | 
2026-09-17 19:34:11.797573 | TASK [gather-host-logs : creating directory for system status]
2026-09-17 19:34:12.167042 | instance | changed
2026-09-17 19:34:12.177857 | 
2026-09-17 19:34:12.178081 | TASK [gather-host-logs : Get logs for each host]
2026-09-17 19:34:12.528369 | instance | + systemd-cgls --full --all --no-pager
2026-09-17 19:34:12.551926 | instance | + ip addr
2026-09-17 19:34:12.553926 | instance | + ip route
2026-09-17 19:34:12.556005 | instance | + lsblk
2026-09-17 19:34:12.560213 | instance | + mount
2026-09-17 19:34:12.562339 | instance | + docker images
2026-09-17 19:34:12.581174 | instance | + brctl show
2026-09-17 19:34:12.581586 | instance | /bin/bash: line 8: brctl: command not found
2026-09-17 19:34:12.581836 | instance | + ip neigh
2026-09-17 19:34:12.583186 | instance | + bridge fdb show
2026-09-17 19:34:12.586486 | instance | + ps aux --sort=-%mem
2026-09-17 19:34:12.608046 | instance | + dpkg -l
2026-09-17 19:34:12.617501 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-17 19:34:12.618119 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-17 19:34:12.635048 | instance | + '[' '!' -z '' ']'
2026-09-17 19:34:12.726205 | instance | ok: Runtime: 0:00:00.110858
2026-09-17 19:34:12.735389 | 
2026-09-17 19:34:12.735576 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-17 19:34:13.393462 | instance | changed:
2026-09-17 19:34:13.393658 | instance | created directory /var/lib/zuul/builds/a2d35a242255493ba372e2fb9cca335d/work/logs/instance
2026-09-17 19:34:13.393685 | instance | cd+++++++++ system/
2026-09-17 19:34:13.393705 | instance | >f+++++++++ system/brctl-show.txt
2026-09-17 19:34:13.393724 | instance | >f+++++++++ system/bridge-fdb.txt
2026-09-17 19:34:13.393742 | instance | >f+++++++++ system/docker-images.txt
2026-09-17 19:34:13.393776 | instance | >f+++++++++ system/ip-addr.txt
2026-09-17 19:34:13.393796 | instance | >f+++++++++ system/ip-neigh.txt
2026-09-17 19:34:13.393814 | instance | >f+++++++++ system/ip-route.txt
2026-09-17 19:34:13.393833 | instance | >f+++++++++ system/lsblk.txt
2026-09-17 19:34:13.393850 | instance | >f+++++++++ system/mount.txt
2026-09-17 19:34:13.393867 | instance | >f+++++++++ system/packages.txt
2026-09-17 19:34:13.393884 | instance | >f+++++++++ system/ps.txt
2026-09-17 19:34:13.393900 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-17 19:34:13.402379 | 
2026-09-17 19:34:13.402510 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-17 19:34:13.629528 | instance | changed: "values"
2026-09-17 19:34:13.802208 | instance | changed: "releases"
2026-09-17 19:34:13.813174 | 
2026-09-17 19:34:13.813442 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-17 19:34:14.050621 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-17 19:34:14.359519 | instance | ok: Runtime: 0:00:00.007277
2026-09-17 19:34:14.369737 | 
2026-09-17 19:34:14.369895 | TASK [helm-release-status : Downloads logs to executor]
2026-09-17 19:34:14.862807 | instance | changed:
2026-09-17 19:34:14.863124 | instance | cd+++++++++ helm/
2026-09-17 19:34:14.863203 | instance | cd+++++++++ helm/releases/
2026-09-17 19:34:14.863257 | instance | cd+++++++++ helm/values/
2026-09-17 19:34:14.882065 | 
2026-09-17 19:34:14.882259 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-17 19:34:15.099332 | instance | changed
2026-09-17 19:34:15.106970 | 
2026-09-17 19:34:15.107071 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-17 19:34:15.387468 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-17 19:34:15.388745 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-17 19:34:15.394157 | instance | environment: line 1: kubectl: command not found
2026-09-17 19:34:15.394963 | instance | environment: line 1: kubectl: command not found
2026-09-17 19:34:15.395562 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-17 19:34:15.396840 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-17 19:34:15.398961 | instance | environment: line 1: kubectl: command not found
2026-09-17 19:34:15.399652 | instance | environment: line 1: kubectl: command not found
2026-09-17 19:34:15.400415 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-17 19:34:15.400547 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-17 19:34:15.404115 | instance | environment: line 1: kubectl: command not found
2026-09-17 19:34:15.405813 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-17 19:34:15.651736 | instance | ok: Runtime: 0:00:00.025873
2026-09-17 19:34:15.661780 | 
2026-09-17 19:34:15.662027 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-17 19:34:15.881132 | instance | changed
2026-09-17 19:34:15.890991 | 
2026-09-17 19:34:15.891152 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-17 19:34:16.139544 | instance | environment: line 5: kubectl: command not found
2026-09-17 19:34:16.140670 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-17 19:34:16.140734 | instance | warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-17 19:34:16.141081 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-17 19:34:16.448685 | instance | ok: Runtime: 0:00:00.008810
2026-09-17 19:34:16.461861 | 
2026-09-17 19:34:16.462101 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-17 19:34:16.987875 | instance | changed:
2026-09-17 19:34:16.988212 | instance | cd+++++++++ objects/
2026-09-17 19:34:16.988273 | instance | cd+++++++++ objects/cluster/
2026-09-17 19:34:16.988316 | instance | cd+++++++++ objects/namespaced/
2026-09-17 19:34:17.007400 | 
2026-09-17 19:34:17.007684 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-17 19:34:17.256487 | instance | changed
2026-09-17 19:34:17.268053 | 
2026-09-17 19:34:17.268268 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-17 19:34:17.505675 | instance | changed
2026-09-17 19:34:17.512069 | 
2026-09-17 19:34:17.512170 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-17 19:34:17.776962 | instance | environment: line 3: kubectl: command not found
2026-09-17 19:34:18.055609 | instance | ok: Runtime: 0:00:00.010821
2026-09-17 19:34:18.062649 | 
2026-09-17 19:34:18.062794 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-17 19:34:18.593438 | instance | changed:
2026-09-17 19:34:18.593685 | instance | cd+++++++++ pod-logs/
2026-09-17 19:34:18.593758 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-17 19:34:18.613271 | 
2026-09-17 19:34:18.613405 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-17 19:34:18.854421 | instance | changed
2026-09-17 19:34:18.864572 | 
2026-09-17 19:34:18.864775 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-17 19:34:19.130709 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-17 19:34:19.411889 | instance | ok: Runtime: 0:00:00.044507
2026-09-17 19:34:19.421390 | 
2026-09-17 19:34:19.421584 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-17 19:34:19.695479 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-17 19:34:19.743376 | instance | ceph-mgr endpoints:
2026-09-17 19:34:19.974095 | instance | ok: Runtime: 0:00:00.054929
2026-09-17 19:34:19.988251 | 
2026-09-17 19:34:19.988422 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-17 19:34:20.257623 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-17 19:34:20.539424 | instance | ok: Runtime: 0:00:00.050781
2026-09-17 19:34:20.548554 | 
2026-09-17 19:34:20.548682 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-17 19:34:21.094025 | instance | changed: cd+++++++++ prometheus/
2026-09-17 19:34:21.101598 | 
2026-09-17 19:34:21.101653 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-17 19:34:21.310801 | instance | changed
2026-09-17 19:34:21.315164 | 
2026-09-17 19:34:21.315231 | TASK [gather-selenium-data : Get selenium data]
2026-09-17 19:34:21.599020 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-17 19:34:21.599661 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-17 19:34:21.849433 | instance | ERROR
2026-09-17 19:34:21.849612 | instance | {
2026-09-17 19:34:21.849650 | instance |   "delta": "0:00:00.003685",
2026-09-17 19:34:21.849675 | instance |   "end": "2026-09-17 19:34:21.599924",
2026-09-17 19:34:21.849700 | instance |   "msg": "non-zero return code",
2026-09-17 19:34:21.849723 | instance |   "rc": 1,
2026-09-17 19:34:21.849760 | instance |   "start": "2026-09-17 19:34:21.596239"
2026-09-17 19:34:21.849783 | instance | }
2026-09-17 19:34:21.849812 | instance | ERROR: Ignoring Errors
2026-09-17 19:34:21.855306 | 
2026-09-17 19:34:21.855383 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-17 19:34:22.359557 | instance | changed: cd+++++++++ selenium/
2026-09-17 19:34:22.367031 | 
2026-09-17 19:34:22.367093 | PLAY RECAP
2026-09-17 19:34:22.367143 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-17 19:34:22.367170 | 
2026-09-17 19:34:22.473456 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-17 19:34:22.476232 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-17 19:34:23.045165 | 
2026-09-17 19:34:23.045273 | PLAY [all]
2026-09-17 19:34:23.054997 | 
2026-09-17 19:34:23.055067 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-17 19:34:23.108324 | instance | skipping: Conditional result was False
2026-09-17 19:34:23.114759 | 
2026-09-17 19:34:23.114932 | TASK [fetch-output : Set log path for single node]
2026-09-17 19:34:23.166500 | instance | ok
2026-09-17 19:34:23.172300 | 
2026-09-17 19:34:23.172394 | LOOP [fetch-output : Ensure local output dirs]
2026-09-17 19:34:23.559938 | instance -> localhost | ok: "/var/lib/zuul/builds/a2d35a242255493ba372e2fb9cca335d/work/logs"
2026-09-17 19:34:23.773752 | instance -> localhost | changed: "/var/lib/zuul/builds/a2d35a242255493ba372e2fb9cca335d/work/artifacts"
2026-09-17 19:34:23.986788 | instance -> localhost | changed: "/var/lib/zuul/builds/a2d35a242255493ba372e2fb9cca335d/work/docs"
2026-09-17 19:34:24.007528 | 
2026-09-17 19:34:24.007670 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-17 19:34:24.682861 | instance | changed: .d..t...... ./
2026-09-17 19:34:24.683209 | instance | changed: All items complete
2026-09-17 19:34:24.683281 | 
2026-09-17 19:34:25.196950 | instance | changed: .d..t...... ./
2026-09-17 19:34:25.689587 | instance | changed: .d..t...... ./
2026-09-17 19:34:25.713841 | 
2026-09-17 19:34:25.714062 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-17 19:34:26.138717 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.005395
2026-09-17 19:34:26.399923 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008460
2026-09-17 19:34:26.413446 | 
2026-09-17 19:34:26.413556 | PLAY [all]
2026-09-17 19:34:26.427533 | 
2026-09-17 19:34:26.427749 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-17 19:34:26.907340 | instance | changed
2026-09-17 19:34:26.913538 | 
2026-09-17 19:34:26.913601 | PLAY RECAP
2026-09-17 19:34:26.913650 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-17 19:34:26.913677 | 
2026-09-17 19:34:27.039902 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-17 19:34:27.042492 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-17 19:34:27.568075 | 
2026-09-17 19:34:27.568187 | PLAY [localhost]
2026-09-17 19:34:27.576584 | 
2026-09-17 19:34:27.576650 | TASK [Generate Zuul manifest]
2026-09-17 19:34:27.592098 | localhost | ok
2026-09-17 19:34:27.605083 | 
2026-09-17 19:34:27.605156 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-17 19:34:27.989862 | localhost | changed
2026-09-17 19:34:28.009473 | 
2026-09-17 19:34:28.009617 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-17 19:34:28.142612 | localhost | ok
2026-09-17 19:34:28.157587 | 
2026-09-17 19:34:28.157744 | TASK [Upload logs]
2026-09-17 19:34:28.179789 | localhost | ok
2026-09-17 19:34:28.246734 | 
2026-09-17 19:34:28.246849 | TASK [Set zuul-log-path fact]
2026-09-17 19:34:28.263199 | localhost | ok
2026-09-17 19:34:28.276683 | 
2026-09-17 19:34:28.276802 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-17 19:34:28.304130 | localhost | ok
2026-09-17 19:34:28.312960 | 
2026-09-17 19:34:28.313060 | TASK [upload-logs : Create log directories]
2026-09-17 19:34:28.727544 | localhost | changed
2026-09-17 19:34:28.736025 | 
2026-09-17 19:34:28.736151 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-17 19:34:29.102030 | localhost -> localhost | ok: Runtime: 0:00:00.004587
2026-09-17 19:34:29.112057 | 
2026-09-17 19:34:29.112199 | TASK [upload-logs : Upload logs to log server]
2026-09-17 19:34:29.600976 | localhost | Output suppressed because no_log was given
2026-09-17 19:34:29.644116 | 
2026-09-17 19:34:29.644241 | LOOP [upload-logs : Compress console log and json output]
2026-09-17 19:34:29.693516 | localhost | skipping: Conditional result was False
2026-09-17 19:34:29.699712 | localhost | skipping: Conditional result was False
2026-09-17 19:34:29.716828 | 
2026-09-17 19:34:29.717044 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-17 19:34:29.757480 | localhost | skipping: Conditional result was False
2026-09-17 19:34:29.757693 | 
2026-09-17 19:34:29.761337 | localhost | skipping: Conditional result was False
2026-09-17 19:34:29.768854 | 
2026-09-17 19:34:29.768979 | LOOP [upload-logs : Upload console log and json output]