2026-09-02 12:13:51.970728 | Job console starting
2026-09-02 12:13:51.981615 | Updating git repos
2026-09-02 12:13:54.935696 | Cloning repos into workspace
2026-09-02 12:14:19.600984 | Restoring repo states
2026-09-02 12:14:31.037355 | Merging changes
2026-09-02 12:14:32.108749 | Checking out repos
2026-09-02 12:14:34.013657 | Preparing playbooks
2026-09-02 12:14:36.873980 | Running Ansible setup
2026-09-02 12:14:41.054246 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-02 12:14:41.701244 | 
2026-09-02 12:14:41.701379 | PLAY [localhost]
2026-09-02 12:14:41.709114 | 
2026-09-02 12:14:41.709181 | TASK [Gathering Facts]
2026-09-02 12:14:42.608563 | localhost | ok
2026-09-02 12:14:42.623092 | 
2026-09-02 12:14:42.623181 | TASK [Setup log path fact]
2026-09-02 12:14:42.649566 | localhost | ok
2026-09-02 12:14:42.806886 | 
2026-09-02 12:14:42.807011 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-02 12:14:42.850395 | localhost | ok
2026-09-02 12:14:42.859474 | 
2026-09-02 12:14:42.859539 | TASK [emit-job-header : Print job information]
2026-09-02 12:14:42.901882 | # Job Information
2026-09-02 12:14:42.902595 | Ansible Version: 2.16.18
2026-09-02 12:14:42.902698 | Job: atmosphere-molecule-csi-rbd
2026-09-02 12:14:42.902751 | Pipeline: check
2026-09-02 12:14:42.902796 | Executor: 2d72f0692154
2026-09-02 12:14:42.902838 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3501
2026-09-02 12:14:42.902888 | Event ID: 29f598a0-a6c7-11f1-8e0e-816e1b9ed736
2026-09-02 12:14:42.908401 | 
2026-09-02 12:14:42.908488 | LOOP [emit-job-header : Print node information]
2026-09-02 12:14:43.007374 | localhost | ok:
2026-09-02 12:14:43.007675 | localhost | # Node Information
2026-09-02 12:14:43.007727 | localhost | Inventory Hostname: instance
2026-09-02 12:14:43.007764 | localhost | Hostname: np0000191822
2026-09-02 12:14:43.007798 | localhost | Username: zuul
2026-09-02 12:14:43.007838 | localhost | Distro: Ubuntu 22.04
2026-09-02 12:14:43.007952 | localhost | Provider: yul1
2026-09-02 12:14:43.008023 | localhost | Region: ca-ymq-1
2026-09-02 12:14:43.008057 | localhost | Label: ubuntu-jammy
2026-09-02 12:14:43.008084 | localhost | Product Name: OpenStack Nova
2026-09-02 12:14:43.008110 | localhost | Interface IP: 162.253.55.24
2026-09-02 12:14:43.019837 | 
2026-09-02 12:14:43.020382 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-02 12:14:43.416983 | localhost -> localhost | changed
2026-09-02 12:14:43.425406 | 
2026-09-02 12:14:43.425497 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-02 12:14:44.497605 | localhost -> localhost | changed
2026-09-02 12:14:44.508284 | 
2026-09-02 12:14:44.508343 | PLAY [all]
2026-09-02 12:14:44.522709 | 
2026-09-02 12:14:44.522773 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-02 12:14:44.794357 | instance -> localhost | ok
2026-09-02 12:14:44.806568 | 
2026-09-02 12:14:44.806635 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-02 12:14:44.842671 | instance | ok
2026-09-02 12:14:44.886694 | instance | included: /var/lib/zuul/builds/30281012fcef43d5a825b4f5594f07a3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-02 12:14:44.893086 | 
2026-09-02 12:14:44.893147 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-02 12:14:45.712128 | instance -> localhost | Generating public/private rsa key pair.
2026-09-02 12:14:45.712303 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/30281012fcef43d5a825b4f5594f07a3/work/30281012fcef43d5a825b4f5594f07a3_id_rsa
2026-09-02 12:14:45.712333 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/30281012fcef43d5a825b4f5594f07a3/work/30281012fcef43d5a825b4f5594f07a3_id_rsa.pub
2026-09-02 12:14:45.712356 | instance -> localhost | The key fingerprint is:
2026-09-02 12:14:45.712378 | instance -> localhost | SHA256:P45WDNCjsaTovxPcxJfP23hAUbWYz49fnWTbbv/NKVA zuul-build-sshkey
2026-09-02 12:14:45.712421 | instance -> localhost | The key's randomart image is:
2026-09-02 12:14:45.712444 | instance -> localhost | +---[RSA 3072]----+
2026-09-02 12:14:45.712472 | instance -> localhost | |       .   ....  |
2026-09-02 12:14:45.712494 | instance -> localhost | |      + o .  o . |
2026-09-02 12:14:45.712515 | instance -> localhost | |   . + = o .o .  |
2026-09-02 12:14:45.712536 | instance -> localhost | |  . . = + .  E   |
2026-09-02 12:14:45.712591 | instance -> localhost | | . . o .S*  . oo |
2026-09-02 12:14:45.712614 | instance -> localhost | |  . o .  .*.  oo=|
2026-09-02 12:14:45.712635 | instance -> localhost | |   . .   .o=. .o=|
2026-09-02 12:14:45.712655 | instance -> localhost | |    o   .oo.o. +=|
2026-09-02 12:14:45.712678 | instance -> localhost | |    .o .. ..  .oX|
2026-09-02 12:14:45.712699 | instance -> localhost | +----[SHA256]-----+
2026-09-02 12:14:45.712753 | instance -> localhost | ok: Runtime: 0:00:00.348359
2026-09-02 12:14:45.719811 | 
2026-09-02 12:14:45.719878 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-02 12:14:45.749938 | instance | ok
2026-09-02 12:14:45.759402 | instance | included: /var/lib/zuul/builds/30281012fcef43d5a825b4f5594f07a3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-02 12:14:45.767220 | 
2026-09-02 12:14:45.767290 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-02 12:14:45.792971 | instance | skipping: Conditional result was False
2026-09-02 12:14:45.799121 | 
2026-09-02 12:14:45.799190 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-02 12:14:46.263736 | instance | changed
2026-09-02 12:14:46.270415 | 
2026-09-02 12:14:46.270478 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-02 12:14:46.484000 | instance | ok
2026-09-02 12:14:46.490764 | 
2026-09-02 12:14:46.490854 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-02 12:14:46.997344 | instance | changed
2026-09-02 12:14:47.004976 | 
2026-09-02 12:14:47.005040 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-02 12:14:47.505086 | instance | changed
2026-09-02 12:14:47.514189 | 
2026-09-02 12:14:47.514265 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-02 12:14:47.539345 | instance | skipping: Conditional result was False
2026-09-02 12:14:47.550584 | 
2026-09-02 12:14:47.550715 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-02 12:14:47.955229 | instance -> localhost | changed
2026-09-02 12:14:47.969057 | 
2026-09-02 12:14:47.969153 | TASK [add-build-sshkey : Add back temp key]
2026-09-02 12:14:48.245035 | instance -> localhost | Identity added: /var/lib/zuul/builds/30281012fcef43d5a825b4f5594f07a3/work/30281012fcef43d5a825b4f5594f07a3_id_rsa (zuul-build-sshkey)
2026-09-02 12:14:48.245308 | instance -> localhost | ok: Runtime: 0:00:00.014309
2026-09-02 12:14:48.252156 | 
2026-09-02 12:14:48.252243 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-02 12:14:48.573938 | instance | ok
2026-09-02 12:14:48.580324 | 
2026-09-02 12:14:48.580411 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-02 12:14:48.605694 | instance | skipping: Conditional result was False
2026-09-02 12:14:48.620116 | 
2026-09-02 12:14:48.620201 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-02 12:14:48.943980 | instance | ok
2026-09-02 12:14:48.949965 | 
2026-09-02 12:14:48.950028 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-02 12:14:52.212299 | instance | Output suppressed because no_log was given
2026-09-02 12:14:52.221020 | 
2026-09-02 12:14:52.221085 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-02 12:14:52.434534 | instance | ok: "logs"
2026-09-02 12:14:52.435090 | instance | ok: All items complete
2026-09-02 12:14:52.435135 | 
2026-09-02 12:14:52.606681 | instance | ok: "artifacts"
2026-09-02 12:14:52.789398 | instance | ok: "docs"
2026-09-02 12:14:52.804341 | 
2026-09-02 12:14:52.804516 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-02 12:14:53.016027 | instance | changed: "logs"
2026-09-02 12:14:53.171321 | instance | changed: "artifacts"
2026-09-02 12:14:53.336501 | instance | changed: "docs"
2026-09-02 12:14:53.350200 | 
2026-09-02 12:14:53.350335 | PLAY RECAP
2026-09-02 12:14:53.350388 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-02 12:14:53.350418 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-02 12:14:53.350440 | 
2026-09-02 12:14:53.536905 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-02 12:14:53.541284 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-02 12:14:54.148401 | 
2026-09-02 12:14:54.148990 | PLAY [all]
2026-09-02 12:14:54.159423 | 
2026-09-02 12:14:54.159497 | TASK [setup-uv : Extract archive]
2026-09-02 12:14:56.413603 | instance | changed
2026-09-02 12:14:56.421590 | 
2026-09-02 12:14:56.421669 | TASK [setup-uv : Print version]
2026-09-02 12:14:56.791542 | instance | uv 0.8.13
2026-09-02 12:14:56.959862 | instance | ok: Runtime: 0:00:00.008066
2026-09-02 12:14:56.972554 | 
2026-09-02 12:14:56.972625 | TASK [Install Ansible collection siblings]
2026-09-02 12:15:06.031626 | instance | changed:
2026-09-02 12:15:06.031837 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-02 12:15:06.031877 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-02 12:15:06.031908 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-02 12:15:06.031936 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-02 12:15:06.031963 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-02 12:15:06.031994 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-02 12:15:06.032022 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-02 12:15:06.032051 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-02 12:15:06.032079 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-02 12:15:06.032105 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-02 12:15:06.032131 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-02 12:15:06.032156 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-02 12:15:06.032181 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-09-02 12:15:06.032206 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-02 12:15:06.032231 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0
2026-09-02 12:15:06.032257 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-02 12:15:06.032282 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-02 12:15:06.032307 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-02 12:15:06.032333 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-02 12:15:06.032359 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-09-02 12:15:06.032383 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0
2026-09-02 12:15:06.032408 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-09-02 12:15:06.032435 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-09-02 12:15:06.032476 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-02 12:15:06.032503 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0
2026-09-02 12:15:06.032528 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-02 12:15:06.032553 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-02 12:15:06.032578 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-09-02 12:15:06.032603 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-02 12:15:06.032627 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-02 12:15:06.032652 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-02 12:15:06.032677 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-02 12:15:06.032702 | instance | Checked out ansible.netcommon to 1.2.0
2026-09-02 12:15:06.032727 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-02 12:15:06.032760 | instance | Starting galaxy collection install process
2026-09-02 12:15:06.032785 | instance | Process install dependency map
2026-09-02 12:15:06.032809 | instance | Starting collection install process
2026-09-02 12:15:06.032833 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-02 12:15:06.032858 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-02 12:15:06.032883 | instance | ansible.netcommon:* was installed successfully
2026-09-02 12:15:06.032907 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-02 12:15:06.032931 | instance | Creating virtual environment at: .venv
2026-09-02 12:15:06.032956 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-02 12:15:06.032985 | instance | Downloading pygments (1.2MiB)
2026-09-02 12:15:06.033038 | instance | Downloading openstacksdk (1.7MiB)
2026-09-02 12:15:06.033073 | instance | Downloading ansible-core (2.1MiB)
2026-09-02 12:15:06.033099 | instance | Downloading cryptography (4.2MiB)
2026-09-02 12:15:06.033125 | instance | Downloading pydantic-core (2.0MiB)
2026-09-02 12:15:06.033150 | instance | Downloading kubernetes (1.9MiB)
2026-09-02 12:15:06.033176 | instance | Downloading rjsonnet (1.2MiB)
2026-09-02 12:15:06.033201 | instance | Downloading setuptools (1.1MiB)
2026-09-02 12:15:06.033226 | instance | Downloading netaddr (2.2MiB)
2026-09-02 12:15:06.033251 | instance |    Building pyperclip==1.9.0
2026-09-02 12:15:06.033278 | instance |  Downloading rjsonnet
2026-09-02 12:15:06.033303 | instance |  Downloading pydantic-core
2026-09-02 12:15:06.033329 | instance |  Downloading netaddr
2026-09-02 12:15:06.033354 | instance |  Downloading pygments
2026-09-02 12:15:06.033379 | instance |  Downloading cryptography
2026-09-02 12:15:06.033404 | instance |  Downloading setuptools
2026-09-02 12:15:06.033429 | instance |  Downloading kubernetes
2026-09-02 12:15:06.033453 | instance |  Downloading ansible-core
2026-09-02 12:15:06.033478 | instance |  Downloading openstacksdk
2026-09-02 12:15:06.033503 | instance |       Built pyperclip==1.9.0
2026-09-02 12:15:06.033527 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-02 12:15:06.033552 | instance | Installed 83 packages in 56ms
2026-09-02 12:15:06.033576 | instance | Checked out ansible.posix to 1.6.0
2026-09-02 12:15:06.033600 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-02 12:15:06.033625 | instance | Starting galaxy collection install process
2026-09-02 12:15:06.033649 | instance | Process install dependency map
2026-09-02 12:15:06.033681 | instance | Starting collection install process
2026-09-02 12:15:06.033706 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-02 12:15:06.033731 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-02 12:15:06.033755 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-02 12:15:06.033780 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 12:15:06.033804 | instance | 2.17.12
2026-09-02 12:15:06.033829 | instance | Checked out ansible.utils to v6.1.0
2026-09-02 12:15:06.033853 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-02 12:15:06.033877 | instance | Starting galaxy collection install process
2026-09-02 12:15:06.033901 | instance | Process install dependency map
2026-09-02 12:15:06.033925 | instance | Starting collection install process
2026-09-02 12:15:06.033950 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-02 12:15:06.033975 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-02 12:15:06.034004 | instance | ansible.utils:6.1.0 was installed successfully
2026-09-02 12:15:06.034031 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 12:15:06.034056 | instance | 2.17.12
2026-09-02 12:15:06.034081 | instance | Checked out atmosphere.common to v0.7.0
2026-09-02 12:15:06.034150 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-02 12:15:06.034185 | instance | Starting galaxy collection install process
2026-09-02 12:15:06.034211 | instance | Process install dependency map
2026-09-02 12:15:06.034236 | instance | Starting collection install process
2026-09-02 12:15:06.034261 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-02 12:15:06.034286 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-02 12:15:06.034310 | instance | atmosphere.common:0.7.0 was installed successfully
2026-09-02 12:15:06.034334 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 12:15:06.034358 | instance | 2.17.12
2026-09-02 12:15:06.034382 | instance | Checked out community.crypto to 2.2.3
2026-09-02 12:15:06.034407 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-02 12:15:06.034432 | instance | Starting galaxy collection install process
2026-09-02 12:15:06.034456 | instance | Process install dependency map
2026-09-02 12:15:06.034480 | instance | Starting collection install process
2026-09-02 12:15:06.034504 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-02 12:15:06.034528 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-02 12:15:06.034552 | instance | community.crypto:2.2.3 was installed successfully
2026-09-02 12:15:06.034576 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 12:15:06.034600 | instance | 2.17.12
2026-09-02 12:15:06.034624 | instance | Checked out community.general to 7.3.0
2026-09-02 12:15:06.034649 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general
2026-09-02 12:15:06.034683 | instance | Starting galaxy collection install process
2026-09-02 12:15:06.034709 | instance | Process install dependency map
2026-09-02 12:15:06.034735 | instance | Starting collection install process
2026-09-02 12:15:06.034761 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-02 12:15:06.034787 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-02 12:15:06.034813 | instance | community.general:7.3.0 was installed successfully
2026-09-02 12:15:06.034838 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 12:15:06.034863 | instance | 2.17.12
2026-09-02 12:15:06.034885 | instance | Checked out community.mysql to 3.6.0
2026-09-02 12:15:06.034904 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-02 12:15:06.034923 | instance | Starting galaxy collection install process
2026-09-02 12:15:06.034942 | instance | Process install dependency map
2026-09-02 12:15:06.034960 | instance | Starting collection install process
2026-09-02 12:15:06.034978 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-02 12:15:06.034996 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-02 12:15:06.035014 | instance | community.mysql:3.6.0 was installed successfully
2026-09-02 12:15:06.035032 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 12:15:06.035050 | instance | 2.17.12
2026-09-02 12:15:06.035067 | instance | Checked out kubernetes.core to 2.4.0
2026-09-02 12:15:06.035086 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-02 12:15:06.035104 | instance | Starting galaxy collection install process
2026-09-02 12:15:06.035123 | instance | Process install dependency map
2026-09-02 12:15:06.035141 | instance | Starting collection install process
2026-09-02 12:15:06.035159 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-02 12:15:06.035177 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-02 12:15:06.035194 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-02 12:15:06.035212 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 12:15:06.035229 | instance | 2.17.12
2026-09-02 12:15:06.035246 | instance | Checked out openstack.cloud to 2.6.0
2026-09-02 12:15:06.035264 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-02 12:15:06.035282 | instance | Starting galaxy collection install process
2026-09-02 12:15:06.035299 | instance | Process install dependency map
2026-09-02 12:15:06.035317 | instance | Starting collection install process
2026-09-02 12:15:06.035334 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-02 12:15:06.035351 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-02 12:15:06.035368 | instance | openstack.cloud:2.6.0 was installed successfully
2026-09-02 12:15:06.035385 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 12:15:06.035402 | instance | 2.17.12
2026-09-02 12:15:06.035423 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-02 12:15:06.035442 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-02 12:15:06.035459 | instance | Starting galaxy collection install process
2026-09-02 12:15:06.035476 | instance | Process install dependency map
2026-09-02 12:15:06.035494 | instance | Starting collection install process
2026-09-02 12:15:06.035511 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-02 12:15:06.035528 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-02 12:15:06.035546 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-02 12:15:06.035563 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 12:15:06.035580 | instance | 2.17.12
2026-09-02 12:15:06.035597 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-02 12:15:06.035615 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-02 12:15:06.035632 | instance | Starting galaxy collection install process
2026-09-02 12:15:06.035650 | instance | Process install dependency map
2026-09-02 12:15:06.035667 | instance | Starting collection install process
2026-09-02 12:15:06.035685 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-02 12:15:06.035703 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-02 12:15:06.035720 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-02 12:15:06.035737 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 12:15:06.035755 | instance | 2.17.12
2026-09-02 12:15:06.035772 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-09-02 12:15:06.035790 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-02 12:15:06.035807 | instance | Starting galaxy collection install process
2026-09-02 12:15:06.035825 | instance | Process install dependency map
2026-09-02 12:15:06.035842 | instance | Starting collection install process
2026-09-02 12:15:06.035859 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-02 12:15:06.035876 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-02 12:15:06.035894 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-09-02 12:15:06.035911 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 12:15:06.035929 | instance | 2.17.12
2026-09-02 12:15:06.042569 | 
2026-09-02 12:15:06.042617 | PLAY RECAP
2026-09-02 12:15:06.042666 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-02 12:15:06.042687 | 
2026-09-02 12:15:06.205550 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-02 12:15:06.212589 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-02 12:15:06.825366 | 
2026-09-02 12:15:06.825510 | PLAY [all]
2026-09-02 12:15:06.836982 | 
2026-09-02 12:15:06.837075 | TASK [Install "jq" for log collection]
2026-09-02 12:15:16.979941 | instance | changed
2026-09-02 12:15:16.982945 | 
2026-09-02 12:15:16.983002 | PLAY RECAP
2026-09-02 12:15:16.983051 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-02 12:15:16.983109 | 
2026-09-02 12:15:17.094004 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-02 12:15:17.097441 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-02 12:15:17.751871 | 
2026-09-02 12:15:17.752010 | PLAY [all]
2026-09-02 12:15:17.763335 | 
2026-09-02 12:15:17.763421 | LOOP [Check inventory directories]
2026-09-02 12:15:18.138582 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-02 12:15:18.302046 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-02 12:15:18.480559 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-02 12:15:18.492380 | 
2026-09-02 12:15:18.492557 | LOOP [Copy inventory file for Zuul]
2026-09-02 12:15:19.341320 | instance | changed:
2026-09-02 12:15:19.341515 | instance | {
2026-09-02 12:15:19.341545 | instance |   "ansible_loop_var": "item",
2026-09-02 12:15:19.341570 | instance |   "changed": false,
2026-09-02 12:15:19.341591 | instance |   "failed": false,
2026-09-02 12:15:19.341614 | instance |   "invocation": {
2026-09-02 12:15:19.341634 | instance |     "module_args": {
2026-09-02 12:15:19.341652 | instance |       "checksum_algorithm": "sha1",
2026-09-02 12:15:19.341671 | instance |       "follow": true,
2026-09-02 12:15:19.341689 | instance |       "get_attributes": true,
2026-09-02 12:15:19.341708 | instance |       "get_checksum": true,
2026-09-02 12:15:19.341728 | instance |       "get_mime": true,
2026-09-02 12:15:19.341747 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-02 12:15:19.341830 | instance |     }
2026-09-02 12:15:19.341938 | instance |   },
2026-09-02 12:15:19.342082 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-02 12:15:19.342122 | instance |   "stat": {
2026-09-02 12:15:19.342144 | instance |     "atime": 1788351298.9519632,
2026-09-02 12:15:19.342165 | instance |     "attr_flags": "e",
2026-09-02 12:15:19.342192 | instance |     "attributes": [
2026-09-02 12:15:19.342239 | instance |       "extents"
2026-09-02 12:15:19.342262 | instance |     ],
2026-09-02 12:15:19.342406 | instance |     "block_size": 4096,
2026-09-02 12:15:19.342499 | instance |     "blocks": 8,
2026-09-02 12:15:19.342533 | instance |     "charset": "unknown",
2026-09-02 12:15:19.342561 | instance |     "ctime": 1788351297.8359542,
2026-09-02 12:15:19.342855 | instance |     "dev": 64513,
2026-09-02 12:15:19.342894 | instance |     "device_type": 0,
2026-09-02 12:15:19.342919 | instance |     "executable": true,
2026-09-02 12:15:19.342945 | instance |     "exists": true,
2026-09-02 12:15:19.343049 | instance |     "gid": 1000,
2026-09-02 12:15:19.343107 | instance |     "gr_name": "zuul",
2026-09-02 12:15:19.343130 | instance |     "inode": 1048020,
2026-09-02 12:15:19.343239 | instance |     "isblk": false,
2026-09-02 12:15:19.343265 | instance |     "ischr": false,
2026-09-02 12:15:19.343285 | instance |     "isdir": true,
2026-09-02 12:15:19.343304 | instance |     "isfifo": false,
2026-09-02 12:15:19.343322 | instance |     "isgid": false,
2026-09-02 12:15:19.343340 | instance |     "islnk": false,
2026-09-02 12:15:19.343359 | instance |     "isreg": false,
2026-09-02 12:15:19.343377 | instance |     "issock": false,
2026-09-02 12:15:19.343396 | instance |     "isuid": false,
2026-09-02 12:15:19.343413 | instance |     "mimetype": "unknown",
2026-09-02 12:15:19.343719 | instance |     "mode": "0755",
2026-09-02 12:15:19.343760 | instance |     "mtime": 1788351297.8359542,
2026-09-02 12:15:19.343783 | instance |     "nlink": 23,
2026-09-02 12:15:19.343802 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-02 12:15:19.343821 | instance |     "pw_name": "zuul",
2026-09-02 12:15:19.343845 | instance |     "readable": true,
2026-09-02 12:15:19.343863 | instance |     "rgrp": true,
2026-09-02 12:15:19.343881 | instance |     "roth": true,
2026-09-02 12:15:19.344020 | instance |     "rusr": true,
2026-09-02 12:15:19.344048 | instance |     "size": 4096,
2026-09-02 12:15:19.344067 | instance |     "uid": 1000,
2026-09-02 12:15:19.344086 | instance |     "version": "672305674",
2026-09-02 12:15:19.344104 | instance |     "wgrp": false,
2026-09-02 12:15:19.344124 | instance |     "woth": false,
2026-09-02 12:15:19.344142 | instance |     "writeable": true,
2026-09-02 12:15:19.344160 | instance |     "wusr": true,
2026-09-02 12:15:19.344178 | instance |     "xgrp": true,
2026-09-02 12:15:19.344196 | instance |     "xoth": true,
2026-09-02 12:15:19.344214 | instance |     "xusr": true
2026-09-02 12:15:19.344232 | instance |   }
2026-09-02 12:15:19.344250 | instance | }
2026-09-02 12:15:19.344288 | instance | ok: All items complete
2026-09-02 12:15:19.344310 | 
2026-09-02 12:15:19.347762 | instance | skipping: Conditional result was False
2026-09-02 12:15:19.349527 | instance | skipping: Conditional result was False
2026-09-02 12:15:19.373626 | 
2026-09-02 12:15:19.373721 | LOOP [Switch "ansible_host" to private IP]
2026-09-02 12:15:19.778429 | instance | changed: 1 replacements made
2026-09-02 12:15:19.780142 | instance | skipping: Conditional result was False
2026-09-02 12:15:19.781118 | instance | skipping: Conditional result was False
2026-09-02 12:15:19.797432 | 
2026-09-02 12:15:19.797606 | TASK [Run Molecule scenario]
2026-09-02 12:15:20.723021 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-02 12:15:21.116080 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-02 12:15:21.116142 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-02 12:15:31.541489 | instance | INFO     [csi > dependency] Executing
2026-09-02 12:15:31.541893 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-02 12:15:31.542311 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-02 12:15:31.542545 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-02 12:15:31.556891 | instance | INFO     [csi > cleanup] Executing
2026-09-02 12:15:31.557577 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-02 12:15:31.571339 | instance | INFO     [csi > destroy] Executing
2026-09-02 12:15:31.571439 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-02 12:15:31.571533 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-02 12:15:31.580405 | instance | INFO     [csi > syntax] Executing
2026-09-02 12:15:33.064851 | instance |
2026-09-02 12:15:33.065112 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-02 12:15:33.137222 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-02 12:15:33.152865 | instance | INFO     [csi > create] Executing
2026-09-02 12:15:33.156556 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-02 12:15:33.170667 | instance | INFO     [csi > prepare] Executing
2026-09-02 12:15:33.909007 | instance |
2026-09-02 12:15:33.909272 | instance | PLAY [Prepare] *****************************************************************
2026-09-02 12:15:33.909509 | instance |
2026-09-02 12:15:33.909752 | instance | TASK [Gathering Facts] *********************************************************
2026-09-02 12:15:33.910020 | instance | Wednesday 02 September 2026  12:15:33 +0000 (0:00:00.023)       0:00:00.023 ***
2026-09-02 12:15:35.176192 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-02 12:15:35.176401 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-02 12:15:35.176719 | instance | interpreter could change the meaning of that path. See
2026-09-02 12:15:35.177021 | instance | https://docs.ansible.com/ansible-
2026-09-02 12:15:35.177303 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-02 12:15:35.187040 | instance | ok: [instance]
2026-09-02 12:15:35.187243 | instance |
2026-09-02 12:15:35.187482 | instance | TASK [Configure short hostname] ************************************************
2026-09-02 12:15:35.187720 | instance | Wednesday 02 September 2026  12:15:35 +0000 (0:00:01.278)       0:00:01.302 ***
2026-09-02 12:15:35.939033 | instance | changed: [instance]
2026-09-02 12:15:35.939207 | instance |
2026-09-02 12:15:35.939465 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-02 12:15:35.939711 | instance | Wednesday 02 September 2026  12:15:35 +0000 (0:00:00.751)       0:00:02.053 ***
2026-09-02 12:15:36.216989 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-02 12:15:36.217362 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-02 12:15:36.217693 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-02 12:15:36.226584 | instance | changed: [instance]
2026-09-02 12:15:36.226825 | instance |
2026-09-02 12:15:36.227076 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-02 12:15:36.227352 | instance | Wednesday 02 September 2026  12:15:36 +0000 (0:00:00.287)       0:00:02.341 ***
2026-09-02 12:15:37.110719 | instance | ok: [instance]
2026-09-02 12:15:37.110955 | instance |
2026-09-02 12:15:37.111255 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-02 12:15:37.111432 | instance |
2026-09-02 12:15:37.111658 | instance | TASK [Gathering Facts] *********************************************************
2026-09-02 12:15:37.111893 | instance | Wednesday 02 September 2026  12:15:37 +0000 (0:00:00.883)       0:00:03.225 ***
2026-09-02 12:15:37.858594 | instance | ok: [instance]
2026-09-02 12:15:37.858753 | instance |
2026-09-02 12:15:37.859037 | instance | TASK [Install depedencies] *****************************************************
2026-09-02 12:15:37.859246 | instance | Wednesday 02 September 2026  12:15:37 +0000 (0:00:00.747)       0:00:03.973 ***
2026-09-02 12:16:01.321959 | instance | changed: [instance]
2026-09-02 12:16:01.322188 | instance |
2026-09-02 12:16:01.322407 | instance | TASK [Start up service] ********************************************************
2026-09-02 12:16:01.322673 | instance | Wednesday 02 September 2026  12:16:01 +0000 (0:00:23.463)       0:00:27.436 ***
2026-09-02 12:16:01.900447 | instance | ok: [instance]
2026-09-02 12:16:01.900668 | instance |
2026-09-02 12:16:01.900902 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-02 12:16:01.901131 | instance | Wednesday 02 September 2026  12:16:01 +0000 (0:00:00.578)       0:00:28.015 ***
2026-09-02 12:16:02.210047 | instance | ok: [instance]
2026-09-02 12:16:02.210227 | instance |
2026-09-02 12:16:02.210458 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-02 12:16:02.210711 | instance | Wednesday 02 September 2026  12:16:02 +0000 (0:00:00.309)       0:00:28.324 ***
2026-09-02 12:16:02.931545 | instance | changed: [instance]
2026-09-02 12:16:02.931946 | instance |
2026-09-02 12:16:02.932277 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-02 12:16:02.932673 | instance | Wednesday 02 September 2026  12:16:02 +0000 (0:00:00.721)       0:00:29.046 ***
2026-09-02 12:16:03.143024 | instance | ok: [instance]
2026-09-02 12:16:03.143388 | instance |
2026-09-02 12:16:03.143712 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-02 12:16:03.144013 | instance | Wednesday 02 September 2026  12:16:03 +0000 (0:00:00.211)       0:00:29.257 ***
2026-09-02 12:16:03.166748 | instance | skipping: [instance]
2026-09-02 12:16:03.167086 | instance |
2026-09-02 12:16:03.167403 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-02 12:16:03.167736 | instance | Wednesday 02 September 2026  12:16:03 +0000 (0:00:00.024)       0:00:29.281 ***
2026-09-02 12:16:03.764456 | instance | changed: [instance] => (item=osd0)
2026-09-02 12:16:03.764515 | instance | changed: [instance] => (item=osd1)
2026-09-02 12:16:03.764526 | instance | changed: [instance] => (item=osd2)
2026-09-02 12:16:03.764536 | instance |
2026-09-02 12:16:03.764546 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-02 12:16:03.764556 | instance | Wednesday 02 September 2026  12:16:03 +0000 (0:00:00.595)       0:00:29.877 ***
2026-09-02 12:16:04.423667 | instance | changed: [instance] => (item=osd0)
2026-09-02 12:16:04.423882 | instance | changed: [instance] => (item=osd1)
2026-09-02 12:16:04.424111 | instance | changed: [instance] => (item=osd2)
2026-09-02 12:16:04.424328 | instance |
2026-09-02 12:16:04.424616 | instance | TASK [Start loop devices] ******************************************************
2026-09-02 12:16:04.424866 | instance | Wednesday 02 September 2026  12:16:04 +0000 (0:00:00.660)       0:00:30.538 ***
2026-09-02 12:16:05.202594 | instance | changed: [instance] => (item=osd0)
2026-09-02 12:16:05.202798 | instance | changed: [instance] => (item=osd1)
2026-09-02 12:16:05.203024 | instance | changed: [instance] => (item=osd2)
2026-09-02 12:16:05.203240 | instance |
2026-09-02 12:16:05.203476 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-02 12:16:05.203742 | instance | Wednesday 02 September 2026  12:16:05 +0000 (0:00:00.778)       0:00:31.316 ***
2026-09-02 12:16:08.597305 | instance | changed: [instance] => (item=osd0)
2026-09-02 12:16:08.597387 | instance | changed: [instance] => (item=osd1)
2026-09-02 12:16:08.597398 | instance | changed: [instance] => (item=osd2)
2026-09-02 12:16:08.597408 | instance |
2026-09-02 12:16:08.597418 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-02 12:16:08.597428 | instance | Wednesday 02 September 2026  12:16:08 +0000 (0:00:03.393)       0:00:34.710 ***
2026-09-02 12:16:10.618332 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-02 12:16:10.618483 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-02 12:16:10.620457 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-02 12:16:10.620540 | instance |
2026-09-02 12:16:10.620554 | instance | PLAY RECAP *********************************************************************
2026-09-02 12:16:10.620565 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-02 12:16:10.620575 | instance |
2026-09-02 12:16:10.620584 | instance | Wednesday 02 September 2026  12:16:10 +0000 (0:00:02.023)       0:00:36.733 ***
2026-09-02 12:16:10.620593 | instance | ===============================================================================
2026-09-02 12:16:10.620602 | instance | Install depedencies ---------------------------------------------------- 23.46s
2026-09-02 12:16:10.620619 | instance | Create a volume group for each loop device ------------------------------ 3.39s
2026-09-02 12:16:10.623976 | instance | Create a logical volume for each loop device ---------------------------- 2.02s
2026-09-02 12:16:10.624046 | instance | Gathering Facts --------------------------------------------------------- 1.28s
2026-09-02 12:16:10.624057 | instance | Purge "snapd" package --------------------------------------------------- 0.88s
2026-09-02 12:16:10.624066 | instance | Start loop devices ------------------------------------------------------ 0.78s
2026-09-02 12:16:10.624075 | instance | Configure short hostname ------------------------------------------------ 0.75s
2026-09-02 12:16:10.624084 | instance | Gathering Facts --------------------------------------------------------- 0.75s
2026-09-02 12:16:10.624092 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.72s
2026-09-02 12:16:10.624101 | instance | Set permissions on loopback devices ------------------------------------- 0.66s
2026-09-02 12:16:10.624110 | instance | Create devices for Ceph ------------------------------------------------- 0.60s
2026-09-02 12:16:10.624118 | instance | Start up service -------------------------------------------------------- 0.58s
2026-09-02 12:16:10.624127 | instance | Generate lvm.conf ------------------------------------------------------- 0.31s
2026-09-02 12:16:10.624136 | instance | Ensure hostname inside hosts file --------------------------------------- 0.29s
2026-09-02 12:16:10.624145 | instance | Get list of all loopback devices ---------------------------------------- 0.21s
2026-09-02 12:16:10.624154 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s
2026-09-02 12:16:10.704224 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-02 12:16:10.720207 | instance | INFO     [csi > converge] Executing
2026-09-02 12:16:12.160292 | instance |
2026-09-02 12:16:12.160771 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-02 12:16:12.161079 | instance |
2026-09-02 12:16:12.161381 | instance | TASK [Gathering Facts] *********************************************************
2026-09-02 12:16:12.161679 | instance | Wednesday 02 September 2026  12:16:12 +0000 (0:00:00.007)       0:00:00.007 ***
2026-09-02 12:16:13.203748 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-02 12:16:13.204127 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-02 12:16:13.204455 | instance | interpreter could change the meaning of that path. See
2026-09-02 12:16:13.204779 | instance | https://docs.ansible.com/ansible-
2026-09-02 12:16:13.205078 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-02 12:16:13.218568 | instance | ok: [instance]
2026-09-02 12:16:13.218918 | instance |
2026-09-02 12:16:13.219224 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-02 12:16:13.219523 | instance | Wednesday 02 September 2026  12:16:13 +0000 (0:00:01.057)       0:00:01.065 ***
2026-09-02 12:16:13.287922 | instance | ok: [instance] => {
2026-09-02 12:16:13.288282 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-02 12:16:13.288608 | instance | }
2026-09-02 12:16:13.288917 | instance |
2026-09-02 12:16:13.289210 | instance | PLAY [all] *********************************************************************
2026-09-02 12:16:13.289486 | instance |
2026-09-02 12:16:13.289809 | instance | TASK [Gathering Facts] *********************************************************
2026-09-02 12:16:13.290141 | instance | Wednesday 02 September 2026  12:16:13 +0000 (0:00:00.069)       0:00:01.135 ***
2026-09-02 12:16:14.266798 | instance | ok: [instance]
2026-09-02 12:16:14.267147 | instance |
2026-09-02 12:16:14.267472 | instance | TASK [Fail if atmosphere_ceph_enabled is set] **********************************
2026-09-02 12:16:14.267827 | instance | Wednesday 02 September 2026  12:16:14 +0000 (0:00:00.978)       0:00:02.113 ***
2026-09-02 12:16:14.317379 | instance | skipping: [instance]
2026-09-02 12:16:14.317697 | instance |
2026-09-02 12:16:14.318086 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-02 12:16:14.318419 | instance | Wednesday 02 September 2026  12:16:14 +0000 (0:00:00.051)       0:00:02.164 ***
2026-09-02 12:16:14.546948 | instance | ok: [instance]
2026-09-02 12:16:14.547005 | instance |
2026-09-02 12:16:14.547017 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-02 12:16:14.547027 | instance |
2026-09-02 12:16:14.547036 | instance | TASK [Gathering Facts] *********************************************************
2026-09-02 12:16:14.547057 | instance | Wednesday 02 September 2026  12:16:14 +0000 (0:00:00.228)       0:00:02.393 ***
2026-09-02 12:16:15.544442 | instance | ok: [instance]
2026-09-02 12:16:15.545157 | instance |
2026-09-02 12:16:15.545190 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-02 12:16:15.545202 | instance | Wednesday 02 September 2026  12:16:15 +0000 (0:00:00.998)       0:00:03.391 ***
2026-09-02 12:16:15.915975 | instance | ok: [instance]
2026-09-02 12:16:15.916411 | instance |
2026-09-02 12:16:15.916809 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-02 12:16:15.916842 | instance | Wednesday 02 September 2026  12:16:15 +0000 (0:00:00.371)       0:00:03.762 ***
2026-09-02 12:16:15.973731 | instance | skipping: [instance]
2026-09-02 12:16:15.974445 | instance |
2026-09-02 12:16:15.974476 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-02 12:16:15.974487 | instance | Wednesday 02 September 2026  12:16:15 +0000 (0:00:00.058)       0:00:03.820 ***
2026-09-02 12:16:16.344092 | instance | changed: [instance]
2026-09-02 12:16:16.344174 | instance |
2026-09-02 12:16:16.344492 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-02 12:16:16.344599 | instance | Wednesday 02 September 2026  12:16:16 +0000 (0:00:00.369)       0:00:04.190 ***
2026-09-02 12:16:16.444993 | instance | ok: [instance] => {
2026-09-02 12:16:16.445209 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-02 12:16:16.445439 | instance | }
2026-09-02 12:16:16.445651 | instance |
2026-09-02 12:16:16.445906 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-02 12:16:16.446139 | instance | Wednesday 02 September 2026  12:16:16 +0000 (0:00:00.101)       0:00:04.291 ***
2026-09-02 12:16:17.245512 | instance | changed: [instance]
2026-09-02 12:16:17.245746 | instance |
2026-09-02 12:16:17.245967 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-02 12:16:17.246200 | instance | Wednesday 02 September 2026  12:16:17 +0000 (0:00:00.800)       0:00:05.092 ***
2026-09-02 12:16:17.307974 | instance | skipping: [instance]
2026-09-02 12:16:17.308187 | instance |
2026-09-02 12:16:17.308447 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-02 12:16:17.308700 | instance | Wednesday 02 September 2026  12:16:17 +0000 (0:00:00.063)       0:00:05.155 ***
2026-09-02 12:16:17.364108 | instance | skipping: [instance]
2026-09-02 12:16:17.364312 | instance |
2026-09-02 12:16:17.364615 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-02 12:16:17.364871 | instance | Wednesday 02 September 2026  12:16:17 +0000 (0:00:00.055)       0:00:05.211 ***
2026-09-02 12:16:17.613269 | instance | ok: [instance]
2026-09-02 12:16:17.613463 | instance |
2026-09-02 12:16:17.613709 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-02 12:16:17.613981 | instance | Wednesday 02 September 2026  12:16:17 +0000 (0:00:00.248)       0:00:05.460 ***
2026-09-02 12:16:18.983344 | instance | ok: [instance]
2026-09-02 12:16:18.983701 | instance |
2026-09-02 12:16:18.984037 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-02 12:16:18.984395 | instance | Wednesday 02 September 2026  12:16:18 +0000 (0:00:01.370)       0:00:06.830 ***
2026-09-02 12:16:19.077556 | instance | ok: [instance] => {
2026-09-02 12:16:19.078227 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-02 12:16:19.078548 | instance | }
2026-09-02 12:16:19.078868 | instance |
2026-09-02 12:16:19.079192 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-02 12:16:19.079505 | instance | Wednesday 02 September 2026  12:16:19 +0000 (0:00:00.090)       0:00:06.920 ***
2026-09-02 12:16:19.761006 | instance | changed: [instance]
2026-09-02 12:16:19.761108 | instance |
2026-09-02 12:16:19.761123 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-02 12:16:19.761269 | instance | Wednesday 02 September 2026  12:16:19 +0000 (0:00:00.686)       0:00:07.607 ***
2026-09-02 12:16:22.757112 | instance | changed: [instance]
2026-09-02 12:16:22.757241 | instance |
2026-09-02 12:16:22.757457 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-02 12:16:22.757720 | instance | Wednesday 02 September 2026  12:16:22 +0000 (0:00:02.993)       0:00:10.600 ***
2026-09-02 12:16:22.801202 | instance | skipping: [instance]
2026-09-02 12:16:22.801387 | instance |
2026-09-02 12:16:22.802155 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-02 12:16:22.802614 | instance | Wednesday 02 September 2026  12:16:22 +0000 (0:00:00.044)       0:00:10.645 ***
2026-09-02 12:16:22.839801 | instance | skipping: [instance]
2026-09-02 12:16:22.840204 | instance |
2026-09-02 12:16:22.840590 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-02 12:16:22.840909 | instance | Wednesday 02 September 2026  12:16:22 +0000 (0:00:00.038)       0:00:10.684 ***
2026-09-02 12:16:22.879836 | instance | skipping: [instance]
2026-09-02 12:16:22.880172 | instance |
2026-09-02 12:16:22.880518 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-02 12:16:22.880833 | instance | Wednesday 02 September 2026  12:16:22 +0000 (0:00:00.041)       0:00:10.726 ***
2026-09-02 12:16:28.555079 | instance | changed: [instance]
2026-09-02 12:16:28.555154 | instance |
2026-09-02 12:16:28.555166 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-02 12:16:28.555177 | instance | Wednesday 02 September 2026  12:16:28 +0000 (0:00:05.673)       0:00:16.400 ***
2026-09-02 12:16:29.225447 | instance | changed: [instance]
2026-09-02 12:16:29.225511 | instance |
2026-09-02 12:16:29.225523 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-02 12:16:29.225533 | instance | Wednesday 02 September 2026  12:16:29 +0000 (0:00:00.670)       0:00:17.071 ***
2026-09-02 12:16:30.276402 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-02 12:16:30.278272 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-02 12:16:30.278310 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-02 12:16:30.278341 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-02 12:16:30.278355 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-02 12:16:30.278368 | instance |
2026-09-02 12:16:30.278381 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-02 12:16:30.278393 | instance | Wednesday 02 September 2026  12:16:30 +0000 (0:00:01.051)       0:00:18.122 ***
2026-09-02 12:16:30.929931 | instance | changed: [instance]
2026-09-02 12:16:30.930042 | instance |
2026-09-02 12:16:30.930801 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-02 12:16:30.930857 | instance | Wednesday 02 September 2026  12:16:30 +0000 (0:00:00.630)       0:00:18.752 ***
2026-09-02 12:16:30.930864 | instance |
2026-09-02 12:16:30.930871 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-02 12:16:30.930877 | instance | Wednesday 02 September 2026  12:16:30 +0000 (0:00:00.024)       0:00:18.777 ***
2026-09-02 12:16:32.059466 | instance | ok: [instance]
2026-09-02 12:16:32.060203 | instance |
2026-09-02 12:16:32.060563 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-02 12:16:32.060592 | instance | Wednesday 02 September 2026  12:16:32 +0000 (0:00:01.129)       0:00:19.906 ***
2026-09-02 12:16:32.558926 | instance | changed: [instance]
2026-09-02 12:16:32.559040 | instance |
2026-09-02 12:16:32.559361 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-02 12:16:32.559418 | instance | Wednesday 02 September 2026  12:16:32 +0000 (0:00:00.500)       0:00:20.406 ***
2026-09-02 12:16:33.197963 | instance | changed: [instance]
2026-09-02 12:16:33.198034 | instance |
2026-09-02 12:16:33.198045 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-02 12:16:33.198056 | instance | Wednesday 02 September 2026  12:16:33 +0000 (0:00:00.637)       0:00:21.044 ***
2026-09-02 12:16:33.451943 | instance | ok: [instance]
2026-09-02 12:16:33.452556 | instance |
2026-09-02 12:16:33.452652 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-02 12:16:33.452674 | instance | Wednesday 02 September 2026  12:16:33 +0000 (0:00:00.254)       0:00:21.298 ***
2026-09-02 12:16:33.526273 | instance | ok: [instance] => {
2026-09-02 12:16:33.527409 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-02 12:16:33.527447 | instance | }
2026-09-02 12:16:33.527459 | instance |
2026-09-02 12:16:33.527469 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-02 12:16:33.527480 | instance | Wednesday 02 September 2026  12:16:33 +0000 (0:00:00.074)       0:00:21.373 ***
2026-09-02 12:16:34.301168 | instance | changed: [instance]
2026-09-02 12:16:34.301929 | instance |
2026-09-02 12:16:34.302002 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-02 12:16:34.302014 | instance | Wednesday 02 September 2026  12:16:34 +0000 (0:00:00.773)       0:00:22.147 ***
2026-09-02 12:16:38.718284 | instance | changed: [instance]
2026-09-02 12:16:38.718367 | instance |
2026-09-02 12:16:38.718722 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-02 12:16:38.718785 | instance | Wednesday 02 September 2026  12:16:38 +0000 (0:00:04.417)       0:00:26.565 ***
2026-09-02 12:16:39.960000 | instance | ok: [instance]
2026-09-02 12:16:39.960406 | instance |
2026-09-02 12:16:39.960857 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-02 12:16:39.961115 | instance | Wednesday 02 September 2026  12:16:39 +0000 (0:00:01.240)       0:00:27.806 ***
2026-09-02 12:16:40.338193 | instance | changed: [instance]
2026-09-02 12:16:40.338290 | instance |
2026-09-02 12:16:40.338653 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-02 12:16:40.338709 | instance | Wednesday 02 September 2026  12:16:40 +0000 (0:00:00.379)       0:00:28.185 ***
2026-09-02 12:16:40.858516 | instance | changed: [instance]
2026-09-02 12:16:40.858587 | instance |
2026-09-02 12:16:40.858810 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-02 12:16:40.858859 | instance | Wednesday 02 September 2026  12:16:40 +0000 (0:00:00.520)       0:00:28.706 ***
2026-09-02 12:16:41.480794 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-02 12:16:41.481558 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-02 12:16:41.481612 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-02 12:16:41.481620 | instance |
2026-09-02 12:16:41.481627 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-02 12:16:41.481633 | instance | Wednesday 02 September 2026  12:16:41 +0000 (0:00:00.622)       0:00:29.328 ***
2026-09-02 12:16:42.029764 | instance | changed: [instance]
2026-09-02 12:16:42.030591 | instance |
2026-09-02 12:16:42.030677 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-02 12:16:42.030691 | instance | Wednesday 02 September 2026  12:16:42 +0000 (0:00:00.548)       0:00:29.876 ***
2026-09-02 12:16:42.556522 | instance | changed: [instance]
2026-09-02 12:16:42.556590 | instance |
2026-09-02 12:16:42.556603 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-02 12:16:42.556634 | instance | Wednesday 02 September 2026  12:16:42 +0000 (0:00:00.500)       0:00:30.376 ***
2026-09-02 12:16:42.556645 | instance |
2026-09-02 12:16:42.556654 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-02 12:16:42.556671 | instance | Wednesday 02 September 2026  12:16:42 +0000 (0:00:00.026)       0:00:30.402 ***
2026-09-02 12:16:43.279377 | instance | ok: [instance]
2026-09-02 12:16:43.279425 | instance |
2026-09-02 12:16:43.279431 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-02 12:16:43.279436 | instance | Wednesday 02 September 2026  12:16:43 +0000 (0:00:00.723)       0:00:31.126 ***
2026-09-02 12:16:44.217511 | instance | changed: [instance]
2026-09-02 12:16:44.218314 | instance |
2026-09-02 12:16:44.218377 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-02 12:16:44.218389 | instance | Wednesday 02 September 2026  12:16:44 +0000 (0:00:00.937)       0:00:32.064 ***
2026-09-02 12:16:44.782418 | instance | changed: [instance]
2026-09-02 12:16:44.782490 | instance |
2026-09-02 12:16:44.782758 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-02 12:16:44.782813 | instance | Wednesday 02 September 2026  12:16:44 +0000 (0:00:00.564)       0:00:32.629 ***
2026-09-02 12:16:44.866840 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-02 12:16:44.867559 | instance |
2026-09-02 12:16:44.867610 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-02 12:16:44.867623 | instance | Wednesday 02 September 2026  12:16:44 +0000 (0:00:00.084)       0:00:32.713 ***
2026-09-02 12:16:50.591728 | instance | changed: [instance]
2026-09-02 12:16:50.591948 | instance |
2026-09-02 12:16:50.591961 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-02 12:16:50.591971 | instance | Wednesday 02 September 2026  12:16:50 +0000 (0:00:05.724)       0:00:38.437 ***
2026-09-02 12:16:51.281356 | instance | ok: [instance] => (item=chronyd)
2026-09-02 12:16:51.282343 | instance | ok: [instance] => (item=sshd)
2026-09-02 12:16:51.282414 | instance |
2026-09-02 12:16:51.282427 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-02 12:16:51.282438 | instance | Wednesday 02 September 2026  12:16:51 +0000 (0:00:00.690)       0:00:39.128 ***
2026-09-02 12:17:02.282750 | instance | changed: [instance]
2026-09-02 12:17:02.282901 | instance |
2026-09-02 12:17:02.283452 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-09-02 12:17:02.283725 | instance | Wednesday 02 September 2026  12:17:02 +0000 (0:00:11.001)       0:00:50.129 ***
2026-09-02 12:17:02.523171 | instance | ok: [instance]
2026-09-02 12:17:02.523889 | instance |
2026-09-02 12:17:02.523958 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-09-02 12:17:02.523970 | instance | Wednesday 02 September 2026  12:17:02 +0000 (0:00:00.239)       0:00:50.369 ***
2026-09-02 12:17:03.016058 | instance | changed: [instance]
2026-09-02 12:17:03.016128 | instance |
2026-09-02 12:17:03.016508 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-09-02 12:17:03.016643 | instance | Wednesday 02 September 2026  12:17:03 +0000 (0:00:00.493)       0:00:50.862 ***
2026-09-02 12:17:03.401869 | instance | changed: [instance]
2026-09-02 12:17:03.401968 | instance |
2026-09-02 12:17:03.402247 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-09-02 12:17:03.402307 | instance | Wednesday 02 September 2026  12:17:03 +0000 (0:00:00.385)       0:00:51.248 ***
2026-09-02 12:17:03.635661 | instance | ok: [instance]
2026-09-02 12:17:03.635703 | instance |
2026-09-02 12:17:03.635983 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-09-02 12:17:03.636046 | instance | Wednesday 02 September 2026  12:17:03 +0000 (0:00:00.234)       0:00:51.482 ***
2026-09-02 12:17:03.897519 | instance | ok: [instance] => (item=instance)
2026-09-02 12:17:03.897705 | instance |
2026-09-02 12:17:03.898239 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-09-02 12:17:03.898322 | instance | Wednesday 02 September 2026  12:17:03 +0000 (0:00:00.261)       0:00:51.744 ***
2026-09-02 12:17:03.954183 | instance | ok: [instance]
2026-09-02 12:17:03.954287 | instance |
2026-09-02 12:17:03.954535 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-09-02 12:17:03.954595 | instance | Wednesday 02 September 2026  12:17:03 +0000 (0:00:00.057)       0:00:51.801 ***
2026-09-02 12:17:04.059258 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-09-02 12:17:04.059354 | instance |
2026-09-02 12:17:04.059660 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-09-02 12:17:04.059713 | instance | Wednesday 02 September 2026  12:17:04 +0000 (0:00:00.104)       0:00:51.906 ***
2026-09-02 12:17:04.381152 | instance | changed: [instance]
2026-09-02 12:17:04.381945 | instance |
2026-09-02 12:17:04.382021 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-09-02 12:17:04.382036 | instance | Wednesday 02 September 2026  12:17:04 +0000 (0:00:00.321)       0:00:52.227 ***
2026-09-02 12:17:05.101663 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-09-02 12:17:05.101745 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-09-02 12:17:05.102164 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-09-02 12:17:05.102215 | instance |
2026-09-02 12:17:05.102221 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-09-02 12:17:05.102227 | instance | Wednesday 02 September 2026  12:17:05 +0000 (0:00:00.721)       0:00:52.949 ***
2026-09-02 12:18:39.506715 | instance | ok: [instance]
2026-09-02 12:18:39.507676 | instance |
2026-09-02 12:18:39.507770 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-09-02 12:18:39.507780 | instance | Wednesday 02 September 2026  12:18:39 +0000 (0:01:34.404)       0:02:27.353 ***
2026-09-02 12:18:39.783831 | instance | changed: [instance]
2026-09-02 12:18:39.783990 | instance |
2026-09-02 12:18:39.784231 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-09-02 12:18:39.784528 | instance | Wednesday 02 September 2026  12:18:39 +0000 (0:00:00.277)       0:02:27.630 ***
2026-09-02 12:18:39.834854 | instance | ok: [instance]
2026-09-02 12:18:39.835023 | instance |
2026-09-02 12:18:39.835248 | instance | TASK [Install Ceph host] *******************************************************
2026-09-02 12:18:39.835509 | instance | Wednesday 02 September 2026  12:18:39 +0000 (0:00:00.051)       0:02:27.682 ***
2026-09-02 12:18:39.918925 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-09-02 12:18:39.919051 | instance |
2026-09-02 12:18:39.919309 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-09-02 12:18:39.919550 | instance | Wednesday 02 September 2026  12:18:39 +0000 (0:00:00.084)       0:02:27.766 ***
2026-09-02 12:18:41.544129 | instance | ok: [instance]
2026-09-02 12:18:41.544331 | instance |
2026-09-02 12:18:41.544614 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-09-02 12:18:41.544838 | instance | Wednesday 02 September 2026  12:18:41 +0000 (0:00:01.624)       0:02:29.390 ***
2026-09-02 12:18:41.621953 | instance | ok: [instance] => (item=instance)
2026-09-02 12:18:41.622149 | instance |
2026-09-02 12:18:41.622401 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-09-02 12:18:41.622624 | instance | Wednesday 02 September 2026  12:18:41 +0000 (0:00:00.078)       0:02:29.469 ***
2026-09-02 12:18:41.987538 | instance | ok: [instance]
2026-09-02 12:18:41.987613 | instance |
2026-09-02 12:18:41.987795 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-09-02 12:18:41.987939 | instance | Wednesday 02 September 2026  12:18:41 +0000 (0:00:00.365)       0:02:29.834 ***
2026-09-02 12:18:44.394989 | instance | ok: [instance]
2026-09-02 12:18:44.395042 | instance |
2026-09-02 12:18:44.395054 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-09-02 12:18:44.395064 | instance | Wednesday 02 September 2026  12:18:44 +0000 (0:00:02.406)       0:02:32.241 ***
2026-09-02 12:19:35.437802 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster)
2026-09-02 12:19:35.437901 | 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_lpg77pdy/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_lpg77pdy/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_lpg77pdy/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_lpg77pdy/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-02T12:19:35.258+0000 7ffb012de640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgD5E5hqbD1VFyAAhl9TKfsIjfBHwwkAZLJ8c1KKVN3drf+J7ejxmoswV88=: Malformed input [buffer:3]\n2026-09-02T12:19:35.258+0000 7ffb012de640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-02T12:19:35.258+0000 7ffb012de640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgD5E5hqbD1VFyAAhl9TKfsIjfBHwwkAZLJ8c1KKVN3drf+J7ejxmoswV88=: Malformed input [buffer:3]\n2026-09-02T12:19:35.258+0000 7ffb012de640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-02T12:19:35.258+0000 7ffb012de640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgD5E5hqbD1VFyAAhl9TKfsIjfBHwwkAZLJ8c1KKVN3drf+J7ejxmoswV88=: Malformed input [buffer:3]\n2026-09-02T12:19:35.258+0000 7ffb012de640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-02T12:19:35.258+0000 7ffb012de640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1}
2026-09-02 12:19:35.439653 | instance |
2026-09-02 12:19:35.439902 | instance | PLAY RECAP *********************************************************************
2026-09-02 12:19:35.440418 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=7    rescued=0    ignored=0
2026-09-02 12:19:35.440612 | instance |
2026-09-02 12:19:35.444424 | instance | Wednesday 02 September 2026  12:19:35 +0000 (0:00:51.046)       0:03:23.287 ***
2026-09-02 12:19:35.444458 | instance | ===============================================================================
2026-09-02 12:19:35.444468 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 94.40s
2026-09-02 12:19:35.444477 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 51.05s
2026-09-02 12:19:35.444486 | instance | vexxhost.ceph.cephadm : Download "cephadm" ----------------------------- 11.00s
2026-09-02 12:19:35.444494 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.72s
2026-09-02 12:19:35.444503 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.67s
2026-09-02 12:19:35.444511 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.42s
2026-09-02 12:19:35.444537 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.99s
2026-09-02 12:19:35.444546 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.41s
2026-09-02 12:19:35.444555 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.85s
2026-09-02 12:19:35.444563 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.62s
2026-09-02 12:19:35.444571 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.37s
2026-09-02 12:19:35.444580 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.24s
2026-09-02 12:19:35.444588 | instance | Gathering Facts --------------------------------------------------------- 1.06s
2026-09-02 12:19:35.444596 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.05s
2026-09-02 12:19:35.444646 | instance | Gathering Facts --------------------------------------------------------- 1.00s
2026-09-02 12:19:35.444958 | instance | Gathering Facts --------------------------------------------------------- 0.98s
2026-09-02 12:19:35.445291 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.94s
2026-09-02 12:19:35.445589 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.80s
2026-09-02 12:19:35.445884 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.77s
2026-09-02 12:19:35.446197 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.72s
2026-09-02 12:19:35.541519 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-02 12:19:35.541784 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-02 12:19:35.541958 | instance | ERROR    Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-02 12:19:35.675420 | instance | ERROR
2026-09-02 12:19:35.675578 | instance | {
2026-09-02 12:19:35.675618 | instance |   "delta": "0:04:15.430886",
2026-09-02 12:19:35.675647 | instance |   "end": "2026-09-02 12:19:35.607313",
2026-09-02 12:19:35.675672 | instance |   "msg": "non-zero return code",
2026-09-02 12:19:35.675697 | instance |   "rc": 2,
2026-09-02 12:19:35.675721 | instance |   "start": "2026-09-02 12:15:20.176427"
2026-09-02 12:19:35.675746 | instance | }
failure
2026-09-02 12:19:35.682347 | 
2026-09-02 12:19:35.682411 | PLAY RECAP
2026-09-02 12:19:35.682472 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-02 12:19:35.682503 | 
2026-09-02 12:19:35.821273 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-02 12:19:35.825499 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-02 12:19:36.459488 | 
2026-09-02 12:19:36.459623 | PLAY [all]
2026-09-02 12:19:36.474040 | 
2026-09-02 12:19:36.474130 | TASK [gather-host-logs : creating directory for system status]
2026-09-02 12:19:37.270116 | instance | changed
2026-09-02 12:19:37.277930 | 
2026-09-02 12:19:37.278015 | TASK [gather-host-logs : Get logs for each host]
2026-09-02 12:19:37.679781 | instance | + systemd-cgls --full --all --no-pager
2026-09-02 12:19:37.690318 | instance | + ip addr
2026-09-02 12:19:37.693502 | instance | + ip route
2026-09-02 12:19:37.695747 | instance | + lsblk
2026-09-02 12:19:37.702106 | instance | + mount
2026-09-02 12:19:37.705155 | instance | + docker images
2026-09-02 12:19:37.729385 | instance | + brctl show
2026-09-02 12:19:37.729981 | instance | /bin/bash: line 8: brctl: command not found
2026-09-02 12:19:37.730237 | instance | + ps aux --sort=-%mem
2026-09-02 12:19:37.753660 | instance | + dpkg -l
2026-09-02 12:19:37.765619 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-02 12:19:37.766233 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-02 12:19:37.788411 | instance | + '[' '!' -z '' ']'
2026-09-02 12:19:37.841313 | instance | ok: Runtime: 0:00:00.113797
2026-09-02 12:19:37.848014 | 
2026-09-02 12:19:37.848106 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-02 12:19:38.482547 | instance | changed:
2026-09-02 12:19:38.482778 | instance | created directory /var/lib/zuul/builds/30281012fcef43d5a825b4f5594f07a3/work/logs/instance
2026-09-02 12:19:38.482818 | instance | cd+++++++++ system/
2026-09-02 12:19:38.482850 | instance | >f+++++++++ system/brctl-show.txt
2026-09-02 12:19:38.482879 | instance | >f+++++++++ system/docker-images.txt
2026-09-02 12:19:38.482907 | instance | >f+++++++++ system/ip-addr.txt
2026-09-02 12:19:38.482940 | instance | >f+++++++++ system/ip-route.txt
2026-09-02 12:19:38.482972 | instance | >f+++++++++ system/lsblk.txt
2026-09-02 12:19:38.483003 | instance | >f+++++++++ system/mount.txt
2026-09-02 12:19:38.483030 | instance | >f+++++++++ system/packages.txt
2026-09-02 12:19:38.483057 | instance | >f+++++++++ system/ps.txt
2026-09-02 12:19:38.483087 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-02 12:19:38.493834 | 
2026-09-02 12:19:38.493906 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-02 12:19:38.723322 | instance | changed: "values"
2026-09-02 12:19:38.907017 | instance | changed: "releases"
2026-09-02 12:19:38.916140 | 
2026-09-02 12:19:38.916295 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-02 12:19:39.158390 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-02 12:19:39.457944 | instance | ok: Runtime: 0:00:00.006311
2026-09-02 12:19:39.465842 | 
2026-09-02 12:19:39.465933 | TASK [helm-release-status : Downloads logs to executor]
2026-09-02 12:19:39.939989 | instance | changed:
2026-09-02 12:19:39.940225 | instance | cd+++++++++ helm/
2026-09-02 12:19:39.940265 | instance | cd+++++++++ helm/releases/
2026-09-02 12:19:39.940295 | instance | cd+++++++++ helm/values/
2026-09-02 12:19:39.950882 | 
2026-09-02 12:19:39.950949 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-02 12:19:40.168862 | instance | changed
2026-09-02 12:19:40.176239 | 
2026-09-02 12:19:40.176319 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-02 12:19:40.452602 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-02 12:19:40.453161 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-02 12:19:40.457814 | instance | environment: line 1: kubectl: command not found
2026-09-02 12:19:40.459417 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-02 12:19:40.460799 | instance | environment: line 1: kubectl: command not found
2026-09-02 12:19:40.463301 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-02 12:19:40.463590 | instance | environment: line 1: kubectl: command not found
2026-09-02 12:19:40.465259 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-02 12:19:40.467282 | instance | environment: line 1: kubectl: command not found
2026-09-02 12:19:40.468077 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-02 12:19:40.469373 | instance | environment: line 1: kubectl: command not found
2026-09-02 12:19:40.470941 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-02 12:19:40.714589 | instance | ok: Runtime: 0:00:00.027887
2026-09-02 12:19:40.721274 | 
2026-09-02 12:19:40.721364 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-02 12:19:40.931512 | instance | changed
2026-09-02 12:19:40.938319 | 
2026-09-02 12:19:40.938409 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-02 12:19:41.184588 | instance | environment: line 5: kubectl: command not found
2026-09-02 12:19:41.185815 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-02 12:19:41.185949 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-02 12:19:41.186625 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-02 12:19:41.482065 | instance | ok: Runtime: 0:00:00.010353
2026-09-02 12:19:41.488197 | 
2026-09-02 12:19:41.488268 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-02 12:19:41.986143 | instance | changed:
2026-09-02 12:19:41.986369 | instance | cd+++++++++ objects/
2026-09-02 12:19:41.986410 | instance | cd+++++++++ objects/cluster/
2026-09-02 12:19:41.986444 | instance | cd+++++++++ objects/namespaced/
2026-09-02 12:19:41.996658 | 
2026-09-02 12:19:41.996724 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-02 12:19:42.224840 | instance | changed
2026-09-02 12:19:42.234406 | 
2026-09-02 12:19:42.234580 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-02 12:19:42.451515 | instance | changed
2026-09-02 12:19:42.459114 | 
2026-09-02 12:19:42.459231 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-02 12:19:42.701940 | instance | environment: line 3: kubectl: command not found
2026-09-02 12:19:43.000364 | instance | ok: Runtime: 0:00:00.007783
2026-09-02 12:19:43.005769 | 
2026-09-02 12:19:43.005841 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-02 12:19:43.713219 | instance | changed:
2026-09-02 12:19:43.713417 | instance | cd+++++++++ pod-logs/
2026-09-02 12:19:43.713447 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-02 12:19:43.723190 | 
2026-09-02 12:19:43.723257 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-02 12:19:43.938971 | instance | changed
2026-09-02 12:19:43.945837 | 
2026-09-02 12:19:43.945985 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-02 12:19:44.187615 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-02 12:19:44.483513 | instance | ok: Runtime: 0:00:00.049867
2026-09-02 12:19:44.490941 | 
2026-09-02 12:19:44.491080 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-02 12:19:44.712276 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-02 12:19:44.757134 | instance | ceph-mgr endpoints:
2026-09-02 12:19:45.030592 | instance | ok: Runtime: 0:00:00.051830
2026-09-02 12:19:45.037577 | 
2026-09-02 12:19:45.037649 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-02 12:19:45.274857 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-02 12:19:45.576441 | instance | ok: Runtime: 0:00:00.047343
2026-09-02 12:19:45.582198 | 
2026-09-02 12:19:45.582267 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-02 12:19:46.102004 | instance | changed: cd+++++++++ prometheus/
2026-09-02 12:19:46.111727 | 
2026-09-02 12:19:46.111792 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-02 12:19:46.329426 | instance | changed
2026-09-02 12:19:46.334136 | 
2026-09-02 12:19:46.334206 | TASK [gather-selenium-data : Get selenium data]
2026-09-02 12:19:46.603079 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-02 12:19:46.604624 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-02 12:19:46.872922 | instance | ERROR
2026-09-02 12:19:46.873311 | instance | {
2026-09-02 12:19:46.873351 | instance |   "delta": "0:00:00.005978",
2026-09-02 12:19:46.873373 | instance |   "end": "2026-09-02 12:19:46.604919",
2026-09-02 12:19:46.873395 | instance |   "msg": "non-zero return code",
2026-09-02 12:19:46.873416 | instance |   "rc": 1,
2026-09-02 12:19:46.873435 | instance |   "start": "2026-09-02 12:19:46.598941"
2026-09-02 12:19:46.873454 | instance | }
2026-09-02 12:19:46.873480 | instance | ERROR: Ignoring Errors
2026-09-02 12:19:46.878221 | 
2026-09-02 12:19:46.878283 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-02 12:19:47.366275 | instance | changed: cd+++++++++ selenium/
2026-09-02 12:19:47.374049 | 
2026-09-02 12:19:47.374097 | PLAY RECAP
2026-09-02 12:19:47.374144 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-02 12:19:47.374166 | 
2026-09-02 12:19:47.524601 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-02 12:19:47.528804 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-02 12:19:48.126322 | 
2026-09-02 12:19:48.126466 | PLAY [all]
2026-09-02 12:19:48.138101 | 
2026-09-02 12:19:48.138178 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-02 12:19:48.174263 | instance | skipping: Conditional result was False
2026-09-02 12:19:48.185252 | 
2026-09-02 12:19:48.185331 | TASK [fetch-output : Set log path for single node]
2026-09-02 12:19:48.220847 | instance | ok
2026-09-02 12:19:48.225740 | 
2026-09-02 12:19:48.225814 | LOOP [fetch-output : Ensure local output dirs]
2026-09-02 12:19:48.605500 | instance -> localhost | ok: "/var/lib/zuul/builds/30281012fcef43d5a825b4f5594f07a3/work/logs"
2026-09-02 12:19:48.807578 | instance -> localhost | changed: "/var/lib/zuul/builds/30281012fcef43d5a825b4f5594f07a3/work/artifacts"
2026-09-02 12:19:49.036573 | instance -> localhost | changed: "/var/lib/zuul/builds/30281012fcef43d5a825b4f5594f07a3/work/docs"
2026-09-02 12:19:49.053747 | 
2026-09-02 12:19:49.054002 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-02 12:19:49.699888 | instance | changed: .d..t...... ./
2026-09-02 12:19:49.700110 | instance | changed: All items complete
2026-09-02 12:19:49.700138 | 
2026-09-02 12:19:50.160614 | instance | changed: .d..t...... ./
2026-09-02 12:19:50.609535 | instance | changed: .d..t...... ./
2026-09-02 12:19:50.633122 | 
2026-09-02 12:19:50.633293 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-02 12:19:51.069417 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.009984
2026-09-02 12:19:51.297624 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007325
2026-09-02 12:19:51.316762 | 
2026-09-02 12:19:51.316892 | PLAY [all]
2026-09-02 12:19:51.322854 | 
2026-09-02 12:19:51.322925 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-02 12:19:51.754621 | instance | changed
2026-09-02 12:19:51.762127 | 
2026-09-02 12:19:51.762236 | PLAY RECAP
2026-09-02 12:19:51.762314 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-02 12:19:51.762337 | 
2026-09-02 12:19:51.900232 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-02 12:19:51.905141 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-02 12:19:52.471077 | 
2026-09-02 12:19:52.471210 | PLAY [localhost]
2026-09-02 12:19:52.481009 | 
2026-09-02 12:19:52.481079 | TASK [Generate Zuul manifest]
2026-09-02 12:19:52.501164 | localhost | ok
2026-09-02 12:19:52.517638 | 
2026-09-02 12:19:52.517714 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-02 12:19:52.884762 | localhost | changed
2026-09-02 12:19:52.904755 | 
2026-09-02 12:19:52.904859 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-02 12:19:52.936665 | localhost | ok
2026-09-02 12:19:52.945701 | 
2026-09-02 12:19:52.945784 | TASK [Upload logs]
2026-09-02 12:19:52.967400 | localhost | ok
2026-09-02 12:19:53.037656 | 
2026-09-02 12:19:53.037749 | TASK [Set zuul-log-path fact]
2026-09-02 12:19:53.058365 | localhost | ok
2026-09-02 12:19:53.074242 | 
2026-09-02 12:19:53.074309 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-02 12:19:53.106032 | localhost | ok
2026-09-02 12:19:53.115501 | 
2026-09-02 12:19:53.115593 | TASK [upload-logs : Create log directories]
2026-09-02 12:19:53.541654 | localhost | changed
2026-09-02 12:19:53.548485 | 
2026-09-02 12:19:53.548573 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-02 12:19:53.957081 | localhost -> localhost | ok: Runtime: 0:00:00.004708
2026-09-02 12:19:53.962301 | 
2026-09-02 12:19:53.962366 | TASK [upload-logs : Upload logs to log server]
2026-09-02 12:19:54.423684 | localhost | Output suppressed because no_log was given
2026-09-02 12:19:54.466181 | 
2026-09-02 12:19:54.466330 | LOOP [upload-logs : Compress console log and json output]
2026-09-02 12:19:54.513820 | localhost | skipping: Conditional result was False
2026-09-02 12:19:54.520853 | localhost | skipping: Conditional result was False
2026-09-02 12:19:54.530220 | 
2026-09-02 12:19:54.530359 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-02 12:19:54.572864 | localhost | skipping: Conditional result was False
2026-09-02 12:19:54.573249 | 
2026-09-02 12:19:54.576748 | localhost | skipping: Conditional result was False
2026-09-02 12:19:54.595012 | 
2026-09-02 12:19:54.595196 | LOOP [upload-logs : Upload console log and json output]