2026-08-22 10:22:52.256657 | Job console starting
2026-08-22 10:22:52.268507 | Updating git repos
2026-08-22 10:22:57.642802 | Cloning repos into workspace
2026-08-22 10:23:11.391413 | Restoring repo states
2026-08-22 10:23:22.566144 | Merging changes
2026-08-22 10:23:23.987164 | Checking out repos
2026-08-22 10:23:26.438483 | Preparing playbooks
2026-08-22 10:23:28.792271 | Running Ansible setup
2026-08-22 10:23:32.471854 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-22 10:23:33.082338 | 
2026-08-22 10:23:33.082482 | PLAY [localhost]
2026-08-22 10:23:33.089876 | 
2026-08-22 10:23:33.089944 | TASK [Gathering Facts]
2026-08-22 10:23:34.062349 | localhost | ok
2026-08-22 10:23:34.075417 | 
2026-08-22 10:23:34.075534 | TASK [Setup log path fact]
2026-08-22 10:23:34.097125 | localhost | ok
2026-08-22 10:23:34.109911 | 
2026-08-22 10:23:34.109988 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-22 10:23:34.153376 | localhost | ok
2026-08-22 10:23:34.162574 | 
2026-08-22 10:23:34.162647 | TASK [emit-job-header : Print job information]
2026-08-22 10:23:34.207639 | # Job Information
2026-08-22 10:23:34.207900 | Ansible Version: 2.16.18
2026-08-22 10:23:34.208289 | Job: atmosphere-molecule-csi-rbd
2026-08-22 10:23:34.208332 | Pipeline: check
2026-08-22 10:23:34.208362 | Executor: 2d72f0692154
2026-08-22 10:23:34.208391 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3501
2026-08-22 10:23:34.208425 | Event ID: b3b94720-9e12-11f1-93a0-48c80f7af537
2026-08-22 10:23:34.212295 | 
2026-08-22 10:23:34.212390 | LOOP [emit-job-header : Print node information]
2026-08-22 10:23:34.315705 | localhost | ok:
2026-08-22 10:23:34.315929 | localhost | # Node Information
2026-08-22 10:23:34.315960 | localhost | Inventory Hostname: instance
2026-08-22 10:23:34.315982 | localhost | Hostname: np0000190560
2026-08-22 10:23:34.316002 | localhost | Username: zuul
2026-08-22 10:23:34.316025 | localhost | Distro: Ubuntu 22.04
2026-08-22 10:23:34.316046 | localhost | Provider: yul1
2026-08-22 10:23:34.316066 | localhost | Region: ca-ymq-1
2026-08-22 10:23:34.316085 | localhost | Label: ubuntu-jammy
2026-08-22 10:23:34.316104 | localhost | Product Name: OpenStack Nova
2026-08-22 10:23:34.316123 | localhost | Interface IP: 199.204.45.210
2026-08-22 10:23:34.326399 | 
2026-08-22 10:23:34.326564 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-22 10:23:34.717932 | localhost -> localhost | changed
2026-08-22 10:23:34.723028 | 
2026-08-22 10:23:34.723115 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-22 10:23:35.604362 | localhost -> localhost | changed
2026-08-22 10:23:35.613862 | 
2026-08-22 10:23:35.613992 | PLAY [all]
2026-08-22 10:23:35.623495 | 
2026-08-22 10:23:35.623576 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-22 10:23:35.870806 | instance -> localhost | ok
2026-08-22 10:23:35.882366 | 
2026-08-22 10:23:35.882439 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-22 10:23:35.915820 | instance | ok
2026-08-22 10:23:35.929283 | instance | included: /var/lib/zuul/builds/97677deb5e0a4b29a1ff802b70bf3df3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-22 10:23:35.934835 | 
2026-08-22 10:23:35.934897 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-22 10:23:37.105018 | instance -> localhost | Generating public/private rsa key pair.
2026-08-22 10:23:37.105226 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/97677deb5e0a4b29a1ff802b70bf3df3/work/97677deb5e0a4b29a1ff802b70bf3df3_id_rsa
2026-08-22 10:23:37.105275 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/97677deb5e0a4b29a1ff802b70bf3df3/work/97677deb5e0a4b29a1ff802b70bf3df3_id_rsa.pub
2026-08-22 10:23:37.105311 | instance -> localhost | The key fingerprint is:
2026-08-22 10:23:37.105341 | instance -> localhost | SHA256:xK+hzIL+FzQuRGcP2AWEJQUQOyJ9BT2AzSfP7ucSwak zuul-build-sshkey
2026-08-22 10:23:37.105386 | instance -> localhost | The key's randomart image is:
2026-08-22 10:23:37.105421 | instance -> localhost | +---[RSA 3072]----+
2026-08-22 10:23:37.105462 | instance -> localhost | |  o*=%*o.        |
2026-08-22 10:23:37.105496 | instance -> localhost | | ...BoO.         |
2026-08-22 10:23:37.105529 | instance -> localhost | |o +..O =o        |
2026-08-22 10:23:37.105561 | instance -> localhost | |.. o. O...       |
2026-08-22 10:23:37.105594 | instance -> localhost | |   . = oS .      |
2026-08-22 10:23:37.105625 | instance -> localhost | |   .Eo=. o       |
2026-08-22 10:23:37.105656 | instance -> localhost | |  . .o+o.        |
2026-08-22 10:23:37.105685 | instance -> localhost | | .   .+ .        |
2026-08-22 10:23:37.105720 | instance -> localhost | |  .... +.        |
2026-08-22 10:23:37.105751 | instance -> localhost | +----[SHA256]-----+
2026-08-22 10:23:37.105818 | instance -> localhost | ok: Runtime: 0:00:00.721868
2026-08-22 10:23:37.111956 | 
2026-08-22 10:23:37.112018 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-22 10:23:37.145768 | instance | ok
2026-08-22 10:23:37.157848 | instance | included: /var/lib/zuul/builds/97677deb5e0a4b29a1ff802b70bf3df3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-22 10:23:37.167232 | 
2026-08-22 10:23:37.167358 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-22 10:23:37.182044 | instance | skipping: Conditional result was False
2026-08-22 10:23:37.191659 | 
2026-08-22 10:23:37.191749 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-22 10:23:37.660696 | instance | changed
2026-08-22 10:23:37.667423 | 
2026-08-22 10:23:37.667491 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-22 10:23:37.850547 | instance | ok
2026-08-22 10:23:37.855987 | 
2026-08-22 10:23:37.856074 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-22 10:23:38.334054 | instance | changed
2026-08-22 10:23:38.341733 | 
2026-08-22 10:23:38.341857 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-22 10:23:38.796794 | instance | changed
2026-08-22 10:23:38.805777 | 
2026-08-22 10:23:38.805871 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-22 10:23:38.832505 | instance | skipping: Conditional result was False
2026-08-22 10:23:38.844768 | 
2026-08-22 10:23:38.844859 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-22 10:23:39.207614 | instance -> localhost | changed
2026-08-22 10:23:39.218682 | 
2026-08-22 10:23:39.218763 | TASK [add-build-sshkey : Add back temp key]
2026-08-22 10:23:39.522470 | instance -> localhost | Identity added: /var/lib/zuul/builds/97677deb5e0a4b29a1ff802b70bf3df3/work/97677deb5e0a4b29a1ff802b70bf3df3_id_rsa (zuul-build-sshkey)
2026-08-22 10:23:39.522676 | instance -> localhost | ok: Runtime: 0:00:00.016455
2026-08-22 10:23:39.527439 | 
2026-08-22 10:23:39.527498 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-22 10:23:39.822664 | instance | ok
2026-08-22 10:23:39.827015 | 
2026-08-22 10:23:39.827077 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-22 10:23:39.850821 | instance | skipping: Conditional result was False
2026-08-22 10:23:39.860645 | 
2026-08-22 10:23:39.860728 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-22 10:23:40.277624 | instance | ok
2026-08-22 10:23:40.287802 | 
2026-08-22 10:23:40.287867 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-22 10:23:43.639763 | instance | Output suppressed because no_log was given
2026-08-22 10:23:43.653886 | 
2026-08-22 10:23:43.654007 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-22 10:23:43.837565 | instance | ok: "logs"
2026-08-22 10:23:43.837793 | instance | ok: All items complete
2026-08-22 10:23:43.837823 | 
2026-08-22 10:23:43.984315 | instance | ok: "artifacts"
2026-08-22 10:23:44.145797 | instance | ok: "docs"
2026-08-22 10:23:44.164730 | 
2026-08-22 10:23:44.164874 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-22 10:23:44.362830 | instance | changed: "logs"
2026-08-22 10:23:44.510709 | instance | changed: "artifacts"
2026-08-22 10:23:44.661679 | instance | changed: "docs"
2026-08-22 10:23:44.675178 | 
2026-08-22 10:23:44.675300 | PLAY RECAP
2026-08-22 10:23:44.675366 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-22 10:23:44.675411 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 10:23:44.675440 | 
2026-08-22 10:23:44.807941 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-22 10:23:44.812395 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-22 10:23:45.441185 | 
2026-08-22 10:23:45.441307 | PLAY [all]
2026-08-22 10:23:45.456925 | 
2026-08-22 10:23:45.457801 | TASK [setup-uv : Extract archive]
2026-08-22 10:23:47.805616 | instance | changed
2026-08-22 10:23:47.812380 | 
2026-08-22 10:23:47.812481 | TASK [setup-uv : Print version]
2026-08-22 10:23:48.181371 | instance | uv 0.8.13
2026-08-22 10:23:48.350664 | instance | ok: Runtime: 0:00:00.012518
2026-08-22 10:23:48.362263 | 
2026-08-22 10:23:48.362330 | TASK [Install Ansible collection siblings]
2026-08-22 10:23:59.621554 | instance | changed:
2026-08-22 10:23:59.621787 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-08-22 10:23:59.621827 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-08-22 10:23:59.621858 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-08-22 10:23:59.621886 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-08-22 10:23:59.621914 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-08-22 10:23:59.621945 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-08-22 10:23:59.621974 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-08-22 10:23:59.622005 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-08-22 10:23:59.622038 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-08-22 10:23:59.622072 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-08-22 10:23:59.622105 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-08-22 10:23:59.622138 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-08-22 10:23:59.622171 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-08-22 10:23:59.622204 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-08-22 10:23:59.622230 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0
2026-08-22 10:23:59.622257 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-08-22 10:23:59.622284 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-08-22 10:23:59.622310 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-08-22 10:23:59.622336 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-08-22 10:23:59.622362 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-08-22 10:23:59.622387 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0
2026-08-22 10:23:59.622413 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-08-22 10:23:59.622441 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-08-22 10:23:59.622488 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-08-22 10:23:59.622515 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0
2026-08-22 10:23:59.622541 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-08-22 10:23:59.622567 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-08-22 10:23:59.622593 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-08-22 10:23:59.622619 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-08-22 10:23:59.622644 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-08-22 10:23:59.622670 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-08-22 10:23:59.622696 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-08-22 10:23:59.622723 | instance | Checked out ansible.netcommon to 1.2.0
2026-08-22 10:23:59.622749 | 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-08-22 10:23:59.622784 | instance | Starting galaxy collection install process
2026-08-22 10:23:59.622810 | instance | Process install dependency map
2026-08-22 10:23:59.622836 | instance | Starting collection install process
2026-08-22 10:23:59.622863 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-08-22 10:23:59.622889 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-08-22 10:23:59.622914 | instance | ansible.netcommon:* was installed successfully
2026-08-22 10:23:59.622940 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-08-22 10:23:59.622966 | instance | Creating virtual environment at: .venv
2026-08-22 10:23:59.622991 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-08-22 10:23:59.623017 | instance | Downloading netaddr (2.2MiB)
2026-08-22 10:23:59.623043 | instance | Downloading cryptography (4.2MiB)
2026-08-22 10:23:59.623070 | instance | Downloading ansible-core (2.1MiB)
2026-08-22 10:23:59.623096 | instance | Downloading pydantic-core (2.0MiB)
2026-08-22 10:23:59.623121 | instance | Downloading openstacksdk (1.7MiB)
2026-08-22 10:23:59.623148 | instance | Downloading rjsonnet (1.2MiB)
2026-08-22 10:23:59.623173 | instance | Downloading setuptools (1.1MiB)
2026-08-22 10:23:59.623199 | instance | Downloading kubernetes (1.9MiB)
2026-08-22 10:23:59.623225 | instance | Downloading pygments (1.2MiB)
2026-08-22 10:23:59.623251 | instance |    Building pyperclip==1.9.0
2026-08-22 10:23:59.623279 | instance |  Downloading rjsonnet
2026-08-22 10:23:59.623305 | instance |  Downloading pydantic-core
2026-08-22 10:23:59.623331 | instance |  Downloading netaddr
2026-08-22 10:23:59.623357 | instance |  Downloading pygments
2026-08-22 10:23:59.623382 | instance |  Downloading cryptography
2026-08-22 10:23:59.623407 | instance |  Downloading setuptools
2026-08-22 10:23:59.623432 | instance |  Downloading kubernetes
2026-08-22 10:23:59.623457 | instance |  Downloading ansible-core
2026-08-22 10:23:59.623483 | instance |  Downloading openstacksdk
2026-08-22 10:23:59.623509 | instance |       Built pyperclip==1.9.0
2026-08-22 10:23:59.623535 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-08-22 10:23:59.623561 | instance | Installed 83 packages in 238ms
2026-08-22 10:23:59.623587 | instance | Checked out ansible.posix to 1.6.0
2026-08-22 10:23:59.623613 | 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-08-22 10:23:59.623639 | instance | Starting galaxy collection install process
2026-08-22 10:23:59.623665 | instance | Process install dependency map
2026-08-22 10:23:59.623697 | instance | Starting collection install process
2026-08-22 10:23:59.623724 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-08-22 10:23:59.623749 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-08-22 10:23:59.623775 | instance | ansible.posix:1.6.0 was installed successfully
2026-08-22 10:23:59.623800 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 10:23:59.623826 | instance | 2.17.12
2026-08-22 10:23:59.623851 | instance | Checked out ansible.utils to v6.1.0
2026-08-22 10:23:59.623877 | 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-08-22 10:23:59.623902 | instance | Starting galaxy collection install process
2026-08-22 10:23:59.623927 | instance | Process install dependency map
2026-08-22 10:23:59.623952 | instance | Starting collection install process
2026-08-22 10:23:59.623978 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-08-22 10:23:59.624005 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-08-22 10:23:59.624031 | instance | ansible.utils:6.1.0 was installed successfully
2026-08-22 10:23:59.624058 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 10:23:59.624084 | instance | 2.17.12
2026-08-22 10:23:59.624110 | instance | Checked out atmosphere.common to v0.7.0
2026-08-22 10:23:59.624136 | 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-08-22 10:23:59.624162 | instance | Starting galaxy collection install process
2026-08-22 10:23:59.624187 | instance | Process install dependency map
2026-08-22 10:23:59.624213 | instance | Starting collection install process
2026-08-22 10:23:59.624239 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-08-22 10:23:59.624264 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-08-22 10:23:59.624289 | instance | atmosphere.common:0.7.0 was installed successfully
2026-08-22 10:23:59.624315 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 10:23:59.624340 | instance | 2.17.12
2026-08-22 10:23:59.624366 | instance | Checked out community.crypto to 2.2.3
2026-08-22 10:23:59.624392 | 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-08-22 10:23:59.624418 | instance | Starting galaxy collection install process
2026-08-22 10:23:59.624444 | instance | Process install dependency map
2026-08-22 10:23:59.624470 | instance | Starting collection install process
2026-08-22 10:23:59.624496 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-08-22 10:23:59.624521 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-08-22 10:23:59.624547 | instance | community.crypto:2.2.3 was installed successfully
2026-08-22 10:23:59.624573 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 10:23:59.624598 | instance | 2.17.12
2026-08-22 10:23:59.624623 | instance | Checked out community.general to 7.3.0
2026-08-22 10:23:59.624649 | 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-08-22 10:23:59.624681 | instance | Starting galaxy collection install process
2026-08-22 10:23:59.624707 | instance | Process install dependency map
2026-08-22 10:23:59.624733 | instance | Starting collection install process
2026-08-22 10:23:59.624759 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-08-22 10:23:59.624784 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-08-22 10:23:59.624810 | instance | community.general:7.3.0 was installed successfully
2026-08-22 10:23:59.624836 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 10:23:59.624862 | instance | 2.17.12
2026-08-22 10:23:59.624888 | instance | Checked out community.mysql to 3.6.0
2026-08-22 10:23:59.624916 | 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-08-22 10:23:59.624943 | instance | Starting galaxy collection install process
2026-08-22 10:23:59.624993 | instance | Process install dependency map
2026-08-22 10:23:59.625024 | instance | Starting collection install process
2026-08-22 10:23:59.625057 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-08-22 10:23:59.625088 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-08-22 10:23:59.625120 | instance | community.mysql:3.6.0 was installed successfully
2026-08-22 10:23:59.625152 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 10:23:59.625183 | instance | 2.17.12
2026-08-22 10:23:59.625215 | instance | Checked out kubernetes.core to 2.4.0
2026-08-22 10:23:59.625246 | 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-08-22 10:23:59.625273 | instance | Starting galaxy collection install process
2026-08-22 10:23:59.625298 | instance | Process install dependency map
2026-08-22 10:23:59.625324 | instance | Starting collection install process
2026-08-22 10:23:59.625361 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-08-22 10:23:59.625389 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-08-22 10:23:59.625415 | instance | kubernetes.core:2.4.0 was installed successfully
2026-08-22 10:23:59.625441 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 10:23:59.625466 | instance | 2.17.12
2026-08-22 10:23:59.625491 | instance | Checked out openstack.cloud to 2.6.0
2026-08-22 10:23:59.625517 | 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-08-22 10:23:59.625543 | instance | Starting galaxy collection install process
2026-08-22 10:23:59.625568 | instance | Process install dependency map
2026-08-22 10:23:59.625593 | instance | Starting collection install process
2026-08-22 10:23:59.625619 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-08-22 10:23:59.625645 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-08-22 10:23:59.625670 | instance | openstack.cloud:2.6.0 was installed successfully
2026-08-22 10:23:59.625696 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 10:23:59.625721 | instance | 2.17.12
2026-08-22 10:23:59.625754 | instance | Checked out vexxhost.ceph to v4.2.0
2026-08-22 10:23:59.625780 | 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-08-22 10:23:59.625806 | instance | Starting galaxy collection install process
2026-08-22 10:23:59.625831 | instance | Process install dependency map
2026-08-22 10:23:59.625856 | instance | Starting collection install process
2026-08-22 10:23:59.625881 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-08-22 10:23:59.625907 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-08-22 10:23:59.625932 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-08-22 10:23:59.625958 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 10:23:59.625983 | instance | 2.17.12
2026-08-22 10:23:59.626008 | instance | Checked out vexxhost.containers to v1.6.8
2026-08-22 10:23:59.626033 | 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-08-22 10:23:59.626059 | instance | Starting galaxy collection install process
2026-08-22 10:23:59.626085 | instance | Process install dependency map
2026-08-22 10:23:59.626110 | instance | Starting collection install process
2026-08-22 10:23:59.626136 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-08-22 10:23:59.626161 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-08-22 10:23:59.626186 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-08-22 10:23:59.626212 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 10:23:59.626238 | instance | 2.17.12
2026-08-22 10:23:59.626264 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-08-22 10:23:59.626290 | 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-08-22 10:23:59.626316 | instance | Starting galaxy collection install process
2026-08-22 10:23:59.626342 | instance | Process install dependency map
2026-08-22 10:23:59.626367 | instance | Starting collection install process
2026-08-22 10:23:59.626393 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-08-22 10:23:59.626419 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-08-22 10:23:59.626446 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-08-22 10:23:59.626471 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 10:23:59.626497 | instance | 2.17.12
2026-08-22 10:23:59.633620 | 
2026-08-22 10:23:59.633667 | PLAY RECAP
2026-08-22 10:23:59.633715 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 10:23:59.633736 | 
2026-08-22 10:23:59.768022 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-22 10:23:59.772669 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-08-22 10:24:00.344775 | 
2026-08-22 10:24:00.344892 | PLAY [all]
2026-08-22 10:24:00.355631 | 
2026-08-22 10:24:00.355698 | TASK [Install "jq" for log collection]
2026-08-22 10:24:10.403249 | instance | changed
2026-08-22 10:24:10.405612 | 
2026-08-22 10:24:10.405669 | PLAY RECAP
2026-08-22 10:24:10.405747 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 10:24:10.405831 | 
2026-08-22 10:24:10.565320 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-08-22 10:24:10.851033 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-22 10:24:11.477985 | 
2026-08-22 10:24:11.609680 | PLAY [all]
2026-08-22 10:24:11.625849 | 
2026-08-22 10:24:11.625943 | LOOP [Check inventory directories]
2026-08-22 10:24:11.956461 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-08-22 10:24:12.105006 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-08-22 10:24:12.256523 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-08-22 10:24:12.277602 | 
2026-08-22 10:24:12.277869 | LOOP [Copy inventory file for Zuul]
2026-08-22 10:24:13.073680 | instance | changed:
2026-08-22 10:24:13.073909 | instance | {
2026-08-22 10:24:13.073949 | instance |   "ansible_loop_var": "item",
2026-08-22 10:24:13.073982 | instance |   "changed": false,
2026-08-22 10:24:13.074011 | instance |   "failed": false,
2026-08-22 10:24:13.074043 | instance |   "invocation": {
2026-08-22 10:24:13.074070 | instance |     "module_args": {
2026-08-22 10:24:13.074096 | instance |       "checksum_algorithm": "sha1",
2026-08-22 10:24:13.074122 | instance |       "follow": true,
2026-08-22 10:24:13.074146 | instance |       "get_attributes": true,
2026-08-22 10:24:13.074173 | instance |       "get_checksum": true,
2026-08-22 10:24:13.074199 | instance |       "get_mime": true,
2026-08-22 10:24:13.074225 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-08-22 10:24:13.074250 | instance |     }
2026-08-22 10:24:13.074275 | instance |   },
2026-08-22 10:24:13.074300 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-08-22 10:24:13.074326 | instance |   "stat": {
2026-08-22 10:24:13.074350 | instance |     "atime": 1787394230.455745,
2026-08-22 10:24:13.074375 | instance |     "attr_flags": "e",
2026-08-22 10:24:13.074400 | instance |     "attributes": [
2026-08-22 10:24:13.074425 | instance |       "extents"
2026-08-22 10:24:13.074450 | instance |     ],
2026-08-22 10:24:13.074474 | instance |     "block_size": 4096,
2026-08-22 10:24:13.074499 | instance |     "blocks": 8,
2026-08-22 10:24:13.074524 | instance |     "charset": "unknown",
2026-08-22 10:24:13.074550 | instance |     "ctime": 1787394229.1997416,
2026-08-22 10:24:13.074576 | instance |     "dev": 64513,
2026-08-22 10:24:13.074601 | instance |     "device_type": 0,
2026-08-22 10:24:13.074626 | instance |     "executable": true,
2026-08-22 10:24:13.074651 | instance |     "exists": true,
2026-08-22 10:24:13.074675 | instance |     "gid": 1000,
2026-08-22 10:24:13.074699 | instance |     "gr_name": "zuul",
2026-08-22 10:24:13.074724 | instance |     "inode": 1047622,
2026-08-22 10:24:13.074748 | instance |     "isblk": false,
2026-08-22 10:24:13.074773 | instance |     "ischr": false,
2026-08-22 10:24:13.074797 | instance |     "isdir": true,
2026-08-22 10:24:13.074821 | instance |     "isfifo": false,
2026-08-22 10:24:13.074846 | instance |     "isgid": false,
2026-08-22 10:24:13.074870 | instance |     "islnk": false,
2026-08-22 10:24:13.074895 | instance |     "isreg": false,
2026-08-22 10:24:13.074919 | instance |     "issock": false,
2026-08-22 10:24:13.074944 | instance |     "isuid": false,
2026-08-22 10:24:13.074968 | instance |     "mimetype": "unknown",
2026-08-22 10:24:13.074992 | instance |     "mode": "0755",
2026-08-22 10:24:13.075016 | instance |     "mtime": 1787394229.1997416,
2026-08-22 10:24:13.075040 | instance |     "nlink": 23,
2026-08-22 10:24:13.075065 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-08-22 10:24:13.075090 | instance |     "pw_name": "zuul",
2026-08-22 10:24:13.075119 | instance |     "readable": true,
2026-08-22 10:24:13.075144 | instance |     "rgrp": true,
2026-08-22 10:24:13.075169 | instance |     "roth": true,
2026-08-22 10:24:13.075208 | instance |     "rusr": true,
2026-08-22 10:24:13.075234 | instance |     "size": 4096,
2026-08-22 10:24:13.075258 | instance |     "uid": 1000,
2026-08-22 10:24:13.075283 | instance |     "version": "35764876",
2026-08-22 10:24:13.075308 | instance |     "wgrp": false,
2026-08-22 10:24:13.075335 | instance |     "woth": false,
2026-08-22 10:24:13.075360 | instance |     "writeable": true,
2026-08-22 10:24:13.075384 | instance |     "wusr": true,
2026-08-22 10:24:13.075409 | instance |     "xgrp": true,
2026-08-22 10:24:13.075434 | instance |     "xoth": true,
2026-08-22 10:24:13.075463 | instance |     "xusr": true
2026-08-22 10:24:13.075488 | instance |   }
2026-08-22 10:24:13.075513 | instance | }
2026-08-22 10:24:13.075558 | instance | ok: All items complete
2026-08-22 10:24:13.075588 | 
2026-08-22 10:24:13.077279 | instance | skipping: Conditional result was False
2026-08-22 10:24:13.078083 | instance | skipping: Conditional result was False
2026-08-22 10:24:13.092622 | 
2026-08-22 10:24:13.093186 | LOOP [Switch "ansible_host" to private IP]
2026-08-22 10:24:13.432611 | instance | changed: 1 replacements made
2026-08-22 10:24:13.437384 | instance | skipping: Conditional result was False
2026-08-22 10:24:13.443439 | instance | skipping: Conditional result was False
2026-08-22 10:24:13.464712 | 
2026-08-22 10:24:13.464921 | TASK [Run Molecule scenario]
2026-08-22 10:24:14.386454 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-08-22 10:24:14.771381 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-08-22 10:24:14.771480 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-08-22 10:24:26.356096 | instance | INFO     [csi > dependency] Executing
2026-08-22 10:24:26.356588 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-08-22 10:24:26.357032 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-08-22 10:24:26.357289 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-08-22 10:24:26.365332 | instance | INFO     [csi > cleanup] Executing
2026-08-22 10:24:26.365696 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-22 10:24:26.373152 | instance | INFO     [csi > destroy] Executing
2026-08-22 10:24:26.373213 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-08-22 10:24:26.373292 | instance | INFO     [csi > destroy] Executed: Successful
2026-08-22 10:24:26.380712 | instance | INFO     [csi > syntax] Executing
2026-08-22 10:24:27.978734 | instance |
2026-08-22 10:24:27.979000 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-08-22 10:24:28.042177 | instance | INFO     [csi > syntax] Executed: Successful
2026-08-22 10:24:28.055176 | instance | INFO     [csi > create] Executing
2026-08-22 10:24:28.057632 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-22 10:24:28.064988 | instance | INFO     [csi > prepare] Executing
2026-08-22 10:24:28.941895 | instance |
2026-08-22 10:24:28.942142 | instance | PLAY [Prepare] *****************************************************************
2026-08-22 10:24:28.942401 | instance |
2026-08-22 10:24:28.942675 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 10:24:28.942947 | instance | Saturday 22 August 2026  10:24:28 +0000 (0:00:00.028)       0:00:00.028 *******
2026-08-22 10:24:30.090476 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-08-22 10:24:30.090798 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-22 10:24:30.091655 | instance | interpreter could change the meaning of that path. See
2026-08-22 10:24:30.091729 | instance | https://docs.ansible.com/ansible-
2026-08-22 10:24:30.091745 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-08-22 10:24:30.099662 | instance | ok: [instance]
2026-08-22 10:24:30.100030 | instance |
2026-08-22 10:24:30.100394 | instance | TASK [Configure short hostname] ************************************************
2026-08-22 10:24:30.100734 | instance | Saturday 22 August 2026  10:24:30 +0000 (0:00:01.158)       0:00:01.187 *******
2026-08-22 10:24:30.751864 | instance | changed: [instance]
2026-08-22 10:24:30.752271 | instance |
2026-08-22 10:24:30.752712 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-08-22 10:24:30.753077 | instance | Saturday 22 August 2026  10:24:30 +0000 (0:00:00.651)       0:00:01.838 *******
2026-08-22 10:24:31.020889 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-08-22 10:24:31.021286 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-08-22 10:24:31.021622 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-08-22 10:24:31.029171 | instance | changed: [instance]
2026-08-22 10:24:31.029506 | instance |
2026-08-22 10:24:31.029843 | instance | TASK [Purge "snapd" package] ***************************************************
2026-08-22 10:24:31.030172 | instance | Saturday 22 August 2026  10:24:31 +0000 (0:00:00.277)       0:00:02.116 *******
2026-08-22 10:24:31.784527 | instance | ok: [instance]
2026-08-22 10:24:31.784928 | instance |
2026-08-22 10:24:31.785275 | instance | PLAY [Create devices for Ceph] *************************************************
2026-08-22 10:24:31.785596 | instance |
2026-08-22 10:24:31.785936 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 10:24:31.786307 | instance | Saturday 22 August 2026  10:24:31 +0000 (0:00:00.755)       0:00:02.871 *******
2026-08-22 10:24:32.506641 | instance | ok: [instance]
2026-08-22 10:24:32.507233 | instance |
2026-08-22 10:24:32.507695 | instance | TASK [Install depedencies] *****************************************************
2026-08-22 10:24:32.508103 | instance | Saturday 22 August 2026  10:24:32 +0000 (0:00:00.721)       0:00:03.593 *******
2026-08-22 10:24:57.276741 | instance | changed: [instance]
2026-08-22 10:24:57.277155 | instance |
2026-08-22 10:24:57.277513 | instance | TASK [Start up service] ********************************************************
2026-08-22 10:24:57.277873 | instance | Saturday 22 August 2026  10:24:57 +0000 (0:00:24.769)       0:00:28.362 *******
2026-08-22 10:24:57.834379 | instance | ok: [instance]
2026-08-22 10:24:57.834777 | instance |
2026-08-22 10:24:57.835123 | instance | TASK [Generate lvm.conf] *******************************************************
2026-08-22 10:24:57.836794 | instance | Saturday 22 August 2026  10:24:57 +0000 (0:00:00.558)       0:00:28.921 *******
2026-08-22 10:24:58.135353 | instance | ok: [instance]
2026-08-22 10:24:58.135741 | instance |
2026-08-22 10:24:58.136094 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-08-22 10:24:58.136473 | instance | Saturday 22 August 2026  10:24:58 +0000 (0:00:00.300)       0:00:29.222 *******
2026-08-22 10:24:58.817077 | instance | changed: [instance]
2026-08-22 10:24:58.817121 | instance |
2026-08-22 10:24:58.817128 | instance | TASK [Get list of all loopback devices] ****************************************
2026-08-22 10:24:58.817134 | instance | Saturday 22 August 2026  10:24:58 +0000 (0:00:00.680)       0:00:29.903 *******
2026-08-22 10:24:59.014215 | instance | ok: [instance]
2026-08-22 10:24:59.014270 | instance |
2026-08-22 10:24:59.014282 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-08-22 10:24:59.014292 | instance | Saturday 22 August 2026  10:24:59 +0000 (0:00:00.197)       0:00:30.100 *******
2026-08-22 10:24:59.037094 | instance | skipping: [instance]
2026-08-22 10:24:59.037120 | instance |
2026-08-22 10:24:59.037130 | instance | TASK [Create devices for Ceph] *************************************************
2026-08-22 10:24:59.037140 | instance | Saturday 22 August 2026  10:24:59 +0000 (0:00:00.023)       0:00:30.123 *******
2026-08-22 10:24:59.564488 | instance | changed: [instance] => (item=osd0)
2026-08-22 10:24:59.564560 | instance | changed: [instance] => (item=osd1)
2026-08-22 10:24:59.564578 | instance | changed: [instance] => (item=osd2)
2026-08-22 10:24:59.564708 | instance |
2026-08-22 10:24:59.564877 | instance | TASK [Set permissions on loopback devices] *************************************
2026-08-22 10:24:59.565043 | instance | Saturday 22 August 2026  10:24:59 +0000 (0:00:00.527)       0:00:30.651 *******
2026-08-22 10:25:00.183299 | instance | changed: [instance] => (item=osd0)
2026-08-22 10:25:00.183358 | instance | changed: [instance] => (item=osd1)
2026-08-22 10:25:00.183365 | instance | changed: [instance] => (item=osd2)
2026-08-22 10:25:00.184246 | instance |
2026-08-22 10:25:00.184322 | instance | TASK [Start loop devices] ******************************************************
2026-08-22 10:25:00.184336 | instance | Saturday 22 August 2026  10:25:00 +0000 (0:00:00.618)       0:00:31.269 *******
2026-08-22 10:25:00.859840 | instance | changed: [instance] => (item=osd0)
2026-08-22 10:25:00.859895 | instance | changed: [instance] => (item=osd1)
2026-08-22 10:25:00.859903 | instance | changed: [instance] => (item=osd2)
2026-08-22 10:25:00.859909 | instance |
2026-08-22 10:25:00.859916 | instance | TASK [Create a volume group for each loop device] ******************************
2026-08-22 10:25:00.859923 | instance | Saturday 22 August 2026  10:25:00 +0000 (0:00:00.676)       0:00:31.946 *******
2026-08-22 10:25:04.263247 | instance | changed: [instance] => (item=osd0)
2026-08-22 10:25:04.264149 | instance | changed: [instance] => (item=osd1)
2026-08-22 10:25:04.264199 | instance | changed: [instance] => (item=osd2)
2026-08-22 10:25:04.264208 | instance |
2026-08-22 10:25:04.264215 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-08-22 10:25:04.264222 | instance | Saturday 22 August 2026  10:25:04 +0000 (0:00:03.404)       0:00:35.350 *******
2026-08-22 10:25:06.200872 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-08-22 10:25:06.200987 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-08-22 10:25:06.201690 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-08-22 10:25:06.201711 | instance |
2026-08-22 10:25:06.201721 | instance | PLAY RECAP *********************************************************************
2026-08-22 10:25:06.201733 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-08-22 10:25:06.202425 | instance |
2026-08-22 10:25:06.202455 | instance | Saturday 22 August 2026  10:25:06 +0000 (0:00:01.938)       0:00:37.288 *******
2026-08-22 10:25:06.202467 | instance | ===============================================================================
2026-08-22 10:25:06.202477 | instance | Install depedencies ---------------------------------------------------- 24.77s
2026-08-22 10:25:06.202491 | instance | Create a volume group for each loop device ------------------------------ 3.40s
2026-08-22 10:25:06.204379 | instance | Create a logical volume for each loop device ---------------------------- 1.94s
2026-08-22 10:25:06.204387 | instance | Gathering Facts --------------------------------------------------------- 1.16s
2026-08-22 10:25:06.204391 | instance | Purge "snapd" package --------------------------------------------------- 0.76s
2026-08-22 10:25:06.204395 | instance | Gathering Facts --------------------------------------------------------- 0.72s
2026-08-22 10:25:06.204400 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.68s
2026-08-22 10:25:06.204404 | instance | Start loop devices ------------------------------------------------------ 0.68s
2026-08-22 10:25:06.204408 | instance | Configure short hostname ------------------------------------------------ 0.65s
2026-08-22 10:25:06.204413 | instance | Set permissions on loopback devices ------------------------------------- 0.62s
2026-08-22 10:25:06.204417 | instance | Start up service -------------------------------------------------------- 0.56s
2026-08-22 10:25:06.204421 | instance | Create devices for Ceph ------------------------------------------------- 0.53s
2026-08-22 10:25:06.204425 | instance | Generate lvm.conf ------------------------------------------------------- 0.30s
2026-08-22 10:25:06.204432 | instance | Ensure hostname inside hosts file --------------------------------------- 0.28s
2026-08-22 10:25:06.204651 | instance | Get list of all loopback devices ---------------------------------------- 0.20s
2026-08-22 10:25:06.204929 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s
2026-08-22 10:25:06.294471 | instance | INFO     [csi > prepare] Executed: Successful
2026-08-22 10:25:06.307219 | instance | INFO     [csi > converge] Executing
2026-08-22 10:25:07.838487 | instance |
2026-08-22 10:25:07.838918 | instance | PLAY [Debug CSI driver value] **************************************************
2026-08-22 10:25:07.839239 | instance |
2026-08-22 10:25:07.839573 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 10:25:07.839909 | instance | Saturday 22 August 2026  10:25:07 +0000 (0:00:00.007)       0:00:00.007 *******
2026-08-22 10:25:08.819675 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-08-22 10:25:08.820130 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-22 10:25:08.820552 | instance | interpreter could change the meaning of that path. See
2026-08-22 10:25:08.820938 | instance | https://docs.ansible.com/ansible-
2026-08-22 10:25:08.821302 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-08-22 10:25:08.829352 | instance | ok: [instance]
2026-08-22 10:25:08.829868 | instance |
2026-08-22 10:25:08.830218 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-08-22 10:25:08.830612 | instance | Saturday 22 August 2026  10:25:08 +0000 (0:00:00.991)       0:00:00.999 *******
2026-08-22 10:25:08.877411 | instance | ok: [instance] => {
2026-08-22 10:25:08.877793 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-08-22 10:25:08.878166 | instance | }
2026-08-22 10:25:08.878605 | instance |
2026-08-22 10:25:08.878968 | instance | PLAY [all] *********************************************************************
2026-08-22 10:25:08.879337 | instance |
2026-08-22 10:25:08.879786 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 10:25:08.880296 | instance | Saturday 22 August 2026  10:25:08 +0000 (0:00:00.048)       0:00:01.047 *******
2026-08-22 10:25:09.756532 | instance | ok: [instance]
2026-08-22 10:25:09.756575 | instance |
2026-08-22 10:25:09.756581 | instance | TASK [Fail if atmosphere_ceph_enabled is set] **********************************
2026-08-22 10:25:09.756587 | instance | Saturday 22 August 2026  10:25:09 +0000 (0:00:00.878)       0:00:01.925 *******
2026-08-22 10:25:09.796566 | instance | skipping: [instance]
2026-08-22 10:25:09.796658 | instance |
2026-08-22 10:25:09.796681 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-08-22 10:25:09.796843 | instance | Saturday 22 August 2026  10:25:09 +0000 (0:00:00.039)       0:00:01.965 *******
2026-08-22 10:25:09.992202 | instance | ok: [instance]
2026-08-22 10:25:09.992268 | instance |
2026-08-22 10:25:09.992280 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-08-22 10:25:09.992291 | instance |
2026-08-22 10:25:09.992300 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 10:25:09.992329 | instance | Saturday 22 August 2026  10:25:09 +0000 (0:00:00.194)       0:00:02.160 *******
2026-08-22 10:25:10.860950 | instance | ok: [instance]
2026-08-22 10:25:10.861039 | instance |
2026-08-22 10:25:10.861158 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-22 10:25:10.861356 | instance | Saturday 22 August 2026  10:25:10 +0000 (0:00:00.870)       0:00:03.030 *******
2026-08-22 10:25:11.194791 | instance | ok: [instance]
2026-08-22 10:25:11.194864 | instance |
2026-08-22 10:25:11.195019 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-22 10:25:11.195213 | instance | Saturday 22 August 2026  10:25:11 +0000 (0:00:00.334)       0:00:03.364 *******
2026-08-22 10:25:11.237926 | instance | skipping: [instance]
2026-08-22 10:25:11.238033 | instance |
2026-08-22 10:25:11.238256 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-08-22 10:25:11.238420 | instance | Saturday 22 August 2026  10:25:11 +0000 (0:00:00.043)       0:00:03.407 *******
2026-08-22 10:25:11.582279 | instance | changed: [instance]
2026-08-22 10:25:11.582328 | instance |
2026-08-22 10:25:11.582335 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 10:25:11.582340 | instance | Saturday 22 August 2026  10:25:11 +0000 (0:00:00.343)       0:00:03.751 *******
2026-08-22 10:25:11.651616 | instance | ok: [instance] => {
2026-08-22 10:25:11.651646 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-08-22 10:25:11.651652 | instance | }
2026-08-22 10:25:11.651657 | instance |
2026-08-22 10:25:11.651661 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 10:25:11.651666 | instance | Saturday 22 August 2026  10:25:11 +0000 (0:00:00.069)       0:00:03.821 *******
2026-08-22 10:25:12.295992 | instance | changed: [instance]
2026-08-22 10:25:12.296049 | instance |
2026-08-22 10:25:12.296322 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 10:25:12.296339 | instance | Saturday 22 August 2026  10:25:12 +0000 (0:00:00.644)       0:00:04.465 *******
2026-08-22 10:25:12.347443 | instance | skipping: [instance]
2026-08-22 10:25:12.347512 | instance |
2026-08-22 10:25:12.347769 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-22 10:25:12.347785 | instance | Saturday 22 August 2026  10:25:12 +0000 (0:00:00.051)       0:00:04.517 *******
2026-08-22 10:25:12.394679 | instance | skipping: [instance]
2026-08-22 10:25:12.394777 | instance |
2026-08-22 10:25:12.394992 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-22 10:25:12.395022 | instance | Saturday 22 August 2026  10:25:12 +0000 (0:00:00.047)       0:00:04.564 *******
2026-08-22 10:25:12.595094 | instance | ok: [instance]
2026-08-22 10:25:12.595468 | instance |
2026-08-22 10:25:12.595509 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-22 10:25:12.595516 | instance | Saturday 22 August 2026  10:25:12 +0000 (0:00:00.200)       0:00:04.764 *******
2026-08-22 10:25:13.926701 | instance | ok: [instance]
2026-08-22 10:25:13.926778 | instance |
2026-08-22 10:25:13.927037 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 10:25:13.927096 | instance | Saturday 22 August 2026  10:25:13 +0000 (0:00:01.331)       0:00:06.096 *******
2026-08-22 10:25:13.991505 | instance | ok: [instance] => {
2026-08-22 10:25:13.991652 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-08-22 10:25:13.991870 | instance | }
2026-08-22 10:25:13.992020 | instance |
2026-08-22 10:25:13.992179 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 10:25:13.992335 | instance | Saturday 22 August 2026  10:25:13 +0000 (0:00:00.064)       0:00:06.160 *******
2026-08-22 10:25:14.956871 | instance | changed: [instance]
2026-08-22 10:25:14.956976 | instance |
2026-08-22 10:25:14.957039 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 10:25:14.957188 | instance | Saturday 22 August 2026  10:25:14 +0000 (0:00:00.965)       0:00:07.126 *******
2026-08-22 10:25:17.757542 | instance | changed: [instance]
2026-08-22 10:25:17.757611 | instance |
2026-08-22 10:25:17.757896 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-08-22 10:25:17.757945 | instance | Saturday 22 August 2026  10:25:17 +0000 (0:00:02.800)       0:00:09.927 *******
2026-08-22 10:25:17.794167 | instance | skipping: [instance]
2026-08-22 10:25:17.794791 | instance |
2026-08-22 10:25:17.794818 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-08-22 10:25:17.794825 | instance | Saturday 22 August 2026  10:25:17 +0000 (0:00:00.036)       0:00:09.963 *******
2026-08-22 10:25:17.830311 | instance | skipping: [instance]
2026-08-22 10:25:17.830703 | instance |
2026-08-22 10:25:17.830752 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-08-22 10:25:17.830759 | instance | Saturday 22 August 2026  10:25:17 +0000 (0:00:00.036)       0:00:09.999 *******
2026-08-22 10:25:17.863426 | instance | skipping: [instance]
2026-08-22 10:25:17.863571 | instance |
2026-08-22 10:25:17.863844 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-08-22 10:25:17.863865 | instance | Saturday 22 August 2026  10:25:17 +0000 (0:00:00.033)       0:00:10.033 *******
2026-08-22 10:25:22.671784 | instance | changed: [instance]
2026-08-22 10:25:22.671989 | instance |
2026-08-22 10:25:22.672004 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-08-22 10:25:22.672130 | instance | Saturday 22 August 2026  10:25:22 +0000 (0:00:04.808)       0:00:14.841 *******
2026-08-22 10:25:23.246707 | instance | changed: [instance]
2026-08-22 10:25:23.246770 | instance |
2026-08-22 10:25:23.247042 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-08-22 10:25:23.247058 | instance | Saturday 22 August 2026  10:25:23 +0000 (0:00:00.574)       0:00:15.416 *******
2026-08-22 10:25:24.120685 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-08-22 10:25:24.120827 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-08-22 10:25:24.121029 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-08-22 10:25:24.121179 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-08-22 10:25:24.121300 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-08-22 10:25:24.121408 | instance |
2026-08-22 10:25:24.121543 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-08-22 10:25:24.121664 | instance | Saturday 22 August 2026  10:25:24 +0000 (0:00:00.873)       0:00:16.290 *******
2026-08-22 10:25:24.703273 | instance | changed: [instance]
2026-08-22 10:25:24.703510 | instance |
2026-08-22 10:25:24.703785 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-08-22 10:25:24.704057 | instance | Saturday 22 August 2026  10:25:24 +0000 (0:00:00.566)       0:00:16.856 *******
2026-08-22 10:25:24.704306 | instance |
2026-08-22 10:25:24.704616 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-22 10:25:24.704884 | instance | Saturday 22 August 2026  10:25:24 +0000 (0:00:00.016)       0:00:16.872 *******
2026-08-22 10:25:25.649319 | instance | ok: [instance]
2026-08-22 10:25:25.649422 | instance |
2026-08-22 10:25:25.649612 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-08-22 10:25:25.649782 | instance | Saturday 22 August 2026  10:25:25 +0000 (0:00:00.946)       0:00:17.818 *******
2026-08-22 10:25:26.086711 | instance | changed: [instance]
2026-08-22 10:25:26.086942 | instance |
2026-08-22 10:25:26.087231 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-08-22 10:25:26.087515 | instance | Saturday 22 August 2026  10:25:26 +0000 (0:00:00.437)       0:00:18.256 *******
2026-08-22 10:25:26.628878 | instance | changed: [instance]
2026-08-22 10:25:26.629115 | instance |
2026-08-22 10:25:26.629423 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-22 10:25:26.629680 | instance | Saturday 22 August 2026  10:25:26 +0000 (0:00:00.541)       0:00:18.798 *******
2026-08-22 10:25:26.831337 | instance | ok: [instance]
2026-08-22 10:25:26.831406 | instance |
2026-08-22 10:25:26.831419 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 10:25:26.831430 | instance | Saturday 22 August 2026  10:25:26 +0000 (0:00:00.201)       0:00:18.999 *******
2026-08-22 10:25:26.880081 | instance | ok: [instance] => {
2026-08-22 10:25:26.880418 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-08-22 10:25:26.880455 | instance | }
2026-08-22 10:25:26.880471 | instance |
2026-08-22 10:25:26.880807 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 10:25:26.880863 | instance | Saturday 22 August 2026  10:25:26 +0000 (0:00:00.049)       0:00:19.049 *******
2026-08-22 10:25:27.563653 | instance | changed: [instance]
2026-08-22 10:25:27.563870 | instance |
2026-08-22 10:25:27.563884 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 10:25:27.564006 | instance | Saturday 22 August 2026  10:25:27 +0000 (0:00:00.683)       0:00:19.733 *******
2026-08-22 10:25:31.894189 | instance | changed: [instance]
2026-08-22 10:25:31.894254 | instance |
2026-08-22 10:25:31.894520 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-08-22 10:25:31.894537 | instance | Saturday 22 August 2026  10:25:31 +0000 (0:00:04.330)       0:00:24.064 *******
2026-08-22 10:25:33.204464 | instance | ok: [instance]
2026-08-22 10:25:33.204543 | instance |
2026-08-22 10:25:33.204828 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-08-22 10:25:33.204890 | instance | Saturday 22 August 2026  10:25:33 +0000 (0:00:01.310)       0:00:25.374 *******
2026-08-22 10:25:33.569007 | instance | changed: [instance]
2026-08-22 10:25:33.569083 | instance |
2026-08-22 10:25:33.569409 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-08-22 10:25:33.569457 | instance | Saturday 22 August 2026  10:25:33 +0000 (0:00:00.364)       0:00:25.738 *******
2026-08-22 10:25:34.017347 | instance | changed: [instance]
2026-08-22 10:25:34.017426 | instance |
2026-08-22 10:25:34.017712 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-08-22 10:25:34.017761 | instance | Saturday 22 August 2026  10:25:34 +0000 (0:00:00.448)       0:00:26.187 *******
2026-08-22 10:25:34.579690 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-08-22 10:25:34.579774 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-08-22 10:25:34.580295 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-08-22 10:25:34.580489 | instance |
2026-08-22 10:25:34.580498 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-08-22 10:25:34.580504 | instance | Saturday 22 August 2026  10:25:34 +0000 (0:00:00.562)       0:00:26.749 *******
2026-08-22 10:25:35.022046 | instance | changed: [instance]
2026-08-22 10:25:35.022122 | instance |
2026-08-22 10:25:35.022412 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-08-22 10:25:35.022458 | instance | Saturday 22 August 2026  10:25:35 +0000 (0:00:00.442)       0:00:27.191 *******
2026-08-22 10:25:35.486445 | instance | changed: [instance]
2026-08-22 10:25:35.486519 | instance |
2026-08-22 10:25:35.487148 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-08-22 10:25:35.487214 | instance | Saturday 22 August 2026  10:25:35 +0000 (0:00:00.444)       0:00:27.636 *******
2026-08-22 10:25:35.487221 | instance |
2026-08-22 10:25:35.487226 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-22 10:25:35.487236 | instance | Saturday 22 August 2026  10:25:35 +0000 (0:00:00.020)       0:00:27.656 *******
2026-08-22 10:25:36.201233 | instance | ok: [instance]
2026-08-22 10:25:36.201307 | instance |
2026-08-22 10:25:36.201612 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-08-22 10:25:36.201663 | instance | Saturday 22 August 2026  10:25:36 +0000 (0:00:00.714)       0:00:28.370 *******
2026-08-22 10:25:37.690867 | instance | changed: [instance]
2026-08-22 10:25:37.690970 | instance |
2026-08-22 10:25:37.691210 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-08-22 10:25:37.691258 | instance | Saturday 22 August 2026  10:25:37 +0000 (0:00:01.489)       0:00:29.860 *******
2026-08-22 10:25:38.243183 | instance | changed: [instance]
2026-08-22 10:25:38.243278 | instance |
2026-08-22 10:25:38.243530 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-08-22 10:25:38.243546 | instance | Saturday 22 August 2026  10:25:38 +0000 (0:00:00.552)       0:00:30.412 *******
2026-08-22 10:25:38.298784 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-08-22 10:25:38.298890 | instance |
2026-08-22 10:25:38.299016 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-08-22 10:25:38.299150 | instance | Saturday 22 August 2026  10:25:38 +0000 (0:00:00.055)       0:00:30.468 *******
2026-08-22 10:25:43.712539 | instance | changed: [instance]
2026-08-22 10:25:43.712633 | instance |
2026-08-22 10:25:43.712901 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-08-22 10:25:43.712941 | instance | Saturday 22 August 2026  10:25:43 +0000 (0:00:05.413)       0:00:35.882 *******
2026-08-22 10:25:44.339668 | instance | ok: [instance] => (item=chronyd)
2026-08-22 10:25:44.339741 | instance | ok: [instance] => (item=sshd)
2026-08-22 10:25:44.340163 | instance |
2026-08-22 10:25:44.340298 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-08-22 10:25:44.340305 | instance | Saturday 22 August 2026  10:25:44 +0000 (0:00:00.627)       0:00:36.509 *******
2026-08-22 10:25:46.130387 | instance | changed: [instance]
2026-08-22 10:25:46.130462 | instance |
2026-08-22 10:25:46.130809 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-08-22 10:25:46.131059 | instance | Saturday 22 August 2026  10:25:46 +0000 (0:00:01.790)       0:00:38.299 *******
2026-08-22 10:25:46.332953 | instance | ok: [instance]
2026-08-22 10:25:46.333008 | instance |
2026-08-22 10:25:46.333015 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-08-22 10:25:46.333021 | instance | Saturday 22 August 2026  10:25:46 +0000 (0:00:00.202)       0:00:38.501 *******
2026-08-22 10:25:46.897230 | instance | changed: [instance]
2026-08-22 10:25:46.897278 | instance |
2026-08-22 10:25:46.897285 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-08-22 10:25:46.897291 | instance | Saturday 22 August 2026  10:25:46 +0000 (0:00:00.563)       0:00:39.065 *******
2026-08-22 10:25:47.231804 | instance | changed: [instance]
2026-08-22 10:25:47.231882 | instance |
2026-08-22 10:25:47.232168 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-08-22 10:25:47.232205 | instance | Saturday 22 August 2026  10:25:47 +0000 (0:00:00.335)       0:00:39.401 *******
2026-08-22 10:25:47.448972 | instance | ok: [instance]
2026-08-22 10:25:47.449054 | instance |
2026-08-22 10:25:47.449406 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-08-22 10:25:47.449452 | instance | Saturday 22 August 2026  10:25:47 +0000 (0:00:00.216)       0:00:39.618 *******
2026-08-22 10:25:47.665206 | instance | ok: [instance] => (item=instance)
2026-08-22 10:25:47.665277 | instance |
2026-08-22 10:25:47.665572 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-08-22 10:25:47.665613 | instance | Saturday 22 August 2026  10:25:47 +0000 (0:00:00.216)       0:00:39.834 *******
2026-08-22 10:25:47.713887 | instance | ok: [instance]
2026-08-22 10:25:47.714322 | instance |
2026-08-22 10:25:47.714371 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-08-22 10:25:47.714379 | instance | Saturday 22 August 2026  10:25:47 +0000 (0:00:00.048)       0:00:39.883 *******
2026-08-22 10:25:47.786301 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-08-22 10:25:47.786679 | instance |
2026-08-22 10:25:47.786710 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-08-22 10:25:47.786717 | instance | Saturday 22 August 2026  10:25:47 +0000 (0:00:00.072)       0:00:39.956 *******
2026-08-22 10:25:48.052154 | instance | changed: [instance]
2026-08-22 10:25:48.052228 | instance |
2026-08-22 10:25:48.052477 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-08-22 10:25:48.052545 | instance | Saturday 22 August 2026  10:25:48 +0000 (0:00:00.265)       0:00:40.221 *******
2026-08-22 10:25:48.706656 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-08-22 10:25:48.706741 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-08-22 10:25:48.707225 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-08-22 10:25:48.707264 | instance |
2026-08-22 10:25:48.707270 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-08-22 10:25:48.707275 | instance | Saturday 22 August 2026  10:25:48 +0000 (0:00:00.654)       0:00:40.876 *******
2026-08-22 10:27:32.309426 | instance | ok: [instance]
2026-08-22 10:27:32.309583 | instance |
2026-08-22 10:27:32.309792 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-08-22 10:27:32.309830 | instance | Saturday 22 August 2026  10:27:32 +0000 (0:01:43.602)       0:02:24.479 *******
2026-08-22 10:27:32.514101 | instance | changed: [instance]
2026-08-22 10:27:32.514142 | instance |
2026-08-22 10:27:32.514150 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-08-22 10:27:32.514156 | instance | Saturday 22 August 2026  10:27:32 +0000 (0:00:00.204)       0:02:24.683 *******
2026-08-22 10:27:32.558412 | instance | ok: [instance]
2026-08-22 10:27:32.558439 | instance |
2026-08-22 10:27:32.558444 | instance | TASK [Install Ceph host] *******************************************************
2026-08-22 10:27:32.558449 | instance | Saturday 22 August 2026  10:27:32 +0000 (0:00:00.044)       0:02:24.728 *******
2026-08-22 10:27:32.638783 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-08-22 10:27:32.638810 | instance |
2026-08-22 10:27:32.638815 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-08-22 10:27:32.638820 | instance | Saturday 22 August 2026  10:27:32 +0000 (0:00:00.080)       0:02:24.808 *******
2026-08-22 10:27:34.352067 | instance | ok: [instance]
2026-08-22 10:27:34.352100 | instance |
2026-08-22 10:27:34.352111 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-08-22 10:27:34.352117 | instance | Saturday 22 August 2026  10:27:34 +0000 (0:00:01.713)       0:02:26.521 *******
2026-08-22 10:27:34.411565 | instance | ok: [instance] => (item=instance)
2026-08-22 10:27:34.411590 | instance |
2026-08-22 10:27:34.411597 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-08-22 10:27:34.411603 | instance | Saturday 22 August 2026  10:27:34 +0000 (0:00:00.059)       0:02:26.581 *******
2026-08-22 10:27:34.743615 | instance | ok: [instance]
2026-08-22 10:27:34.743685 | instance |
2026-08-22 10:27:34.743997 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-08-22 10:27:34.744033 | instance | Saturday 22 August 2026  10:27:34 +0000 (0:00:00.332)       0:02:26.913 *******
2026-08-22 10:27:37.024886 | instance | ok: [instance]
2026-08-22 10:27:37.024961 | instance |
2026-08-22 10:27:37.025240 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-08-22 10:27:37.025278 | instance | Saturday 22 August 2026  10:27:37 +0000 (0:00:02.281)       0:02:29.194 *******
2026-08-22 10:28:29.423558 | 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-08-22 10:28:29.423658 | 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_ji121y13/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_ji121y13/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_ji121y13/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_ji121y13/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-08-22T10:28:29.261+0000 7f248ced4640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgBgeYlqTWH4JiAAXOuuQotkDr4PFoRiAnS3AHNAY9V3FVkbZbalKtntP5c=: Malformed input [buffer:3]\n2026-08-22T10:28:29.261+0000 7f248ced4640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-22T10:28:29.265+0000 7f248ced4640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgBgeYlqTWH4JiAAXOuuQotkDr4PFoRiAnS3AHNAY9V3FVkbZbalKtntP5c=: Malformed input [buffer:3]\n2026-08-22T10:28:29.265+0000 7f248ced4640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-22T10:28:29.265+0000 7f248ced4640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgBgeYlqTWH4JiAAXOuuQotkDr4PFoRiAnS3AHNAY9V3FVkbZbalKtntP5c=: Malformed input [buffer:3]\n2026-08-22T10:28:29.265+0000 7f248ced4640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-22T10:28:29.265+0000 7f248ced4640 -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-08-22 10:28:29.432463 | instance |
2026-08-22 10:28:29.432526 | instance | PLAY RECAP *********************************************************************
2026-08-22 10:28:29.432540 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=7    rescued=0    ignored=0
2026-08-22 10:28:29.432550 | instance |
2026-08-22 10:28:29.432559 | instance | Saturday 22 August 2026  10:28:29 +0000 (0:00:52.401)       0:03:21.595 *******
2026-08-22 10:28:29.432569 | instance | ===============================================================================
2026-08-22 10:28:29.432578 | instance | vexxhost.ceph.mon : Run Bootstrap command ----------------------------- 103.60s
2026-08-22 10:28:29.432587 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 52.40s
2026-08-22 10:28:29.432596 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.41s
2026-08-22 10:28:29.432605 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 4.81s
2026-08-22 10:28:29.432614 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.33s
2026-08-22 10:28:29.432623 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.80s
2026-08-22 10:28:29.432651 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.28s
2026-08-22 10:28:29.432669 | instance | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 1.79s
2026-08-22 10:28:29.432975 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.71s
2026-08-22 10:28:29.433357 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.66s
2026-08-22 10:28:29.433706 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 1.49s
2026-08-22 10:28:29.434105 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.33s
2026-08-22 10:28:29.434500 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.31s
2026-08-22 10:28:29.434850 | instance | Gathering Facts --------------------------------------------------------- 0.99s
2026-08-22 10:28:29.435197 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.97s
2026-08-22 10:28:29.435543 | instance | Gathering Facts --------------------------------------------------------- 0.88s
2026-08-22 10:28:29.435908 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.87s
2026-08-22 10:28:29.436317 | instance | Gathering Facts --------------------------------------------------------- 0.87s
2026-08-22 10:28:29.436697 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.68s
2026-08-22 10:28:29.436915 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.65s
2026-08-22 10:28:29.511215 | 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-08-22 10:28:29.511497 | instance | ERROR    [csi > converge] Executed: Failed
2026-08-22 10:28:29.511711 | 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-08-22 10:28:29.852227 | instance | ERROR
2026-08-22 10:28:29.852412 | instance | {
2026-08-22 10:28:29.852441 | instance |   "delta": "0:04:15.748479",
2026-08-22 10:28:29.852462 | instance |   "end": "2026-08-22 10:28:29.561773",
2026-08-22 10:28:29.852481 | instance |   "msg": "non-zero return code",
2026-08-22 10:28:29.852499 | instance |   "rc": 2,
2026-08-22 10:28:29.852517 | instance |   "start": "2026-08-22 10:24:13.813294"
2026-08-22 10:28:29.852536 | instance | }
failure
2026-08-22 10:28:29.857955 | 
2026-08-22 10:28:29.858003 | PLAY RECAP
2026-08-22 10:28:29.858048 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-08-22 10:28:29.858071 | 
2026-08-22 10:28:30.146884 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-22 10:28:30.168429 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-08-22 10:28:30.897957 | 
2026-08-22 10:28:30.898155 | PLAY [all]
2026-08-22 10:28:30.913660 | 
2026-08-22 10:28:30.913760 | TASK [gather-host-logs : creating directory for system status]
2026-08-22 10:28:31.260144 | instance | changed
2026-08-22 10:28:31.279975 | 
2026-08-22 10:28:31.280107 | TASK [gather-host-logs : Get logs for each host]
2026-08-22 10:28:31.677671 | instance | + systemd-cgls --full --all --no-pager
2026-08-22 10:28:31.689981 | instance | + ip addr
2026-08-22 10:28:31.692258 | instance | + ip route
2026-08-22 10:28:31.694433 | instance | + lsblk
2026-08-22 10:28:31.697624 | instance | + mount
2026-08-22 10:28:31.700128 | instance | + docker images
2026-08-22 10:28:31.717625 | instance | + brctl show
2026-08-22 10:28:31.718119 | instance | /bin/bash: line 8: brctl: command not found
2026-08-22 10:28:31.718373 | instance | + ps aux --sort=-%mem
2026-08-22 10:28:31.731187 | instance | + dpkg -l
2026-08-22 10:28:31.742371 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-08-22 10:28:31.742862 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-08-22 10:28:31.760888 | instance | + '[' '!' -z '' ']'
2026-08-22 10:28:31.825357 | instance | ok: Runtime: 0:00:00.088324
2026-08-22 10:28:31.832351 | 
2026-08-22 10:28:31.832426 | TASK [gather-host-logs : Downloads logs to executor]
2026-08-22 10:28:32.566611 | instance | changed:
2026-08-22 10:28:32.566837 | instance | created directory /var/lib/zuul/builds/97677deb5e0a4b29a1ff802b70bf3df3/work/logs/instance
2026-08-22 10:28:32.566876 | instance | cd+++++++++ system/
2026-08-22 10:28:32.566906 | instance | >f+++++++++ system/brctl-show.txt
2026-08-22 10:28:32.566936 | instance | >f+++++++++ system/docker-images.txt
2026-08-22 10:28:32.566964 | instance | >f+++++++++ system/ip-addr.txt
2026-08-22 10:28:32.566994 | instance | >f+++++++++ system/ip-route.txt
2026-08-22 10:28:32.567023 | instance | >f+++++++++ system/lsblk.txt
2026-08-22 10:28:32.567051 | instance | >f+++++++++ system/mount.txt
2026-08-22 10:28:32.567078 | instance | >f+++++++++ system/packages.txt
2026-08-22 10:28:32.567104 | instance | >f+++++++++ system/ps.txt
2026-08-22 10:28:32.567132 | instance | >f+++++++++ system/systemd-cgls.txt
2026-08-22 10:28:32.578960 | 
2026-08-22 10:28:32.579029 | LOOP [helm-release-status : creating directory for helm release status]
2026-08-22 10:28:32.772107 | instance | changed: "values"
2026-08-22 10:28:32.942546 | instance | changed: "releases"
2026-08-22 10:28:32.955618 | 
2026-08-22 10:28:32.955855 | TASK [helm-release-status : Gather get release status for helm charts]
2026-08-22 10:28:33.161893 | instance | /bin/bash: line 3: kubectl: command not found
2026-08-22 10:28:33.501552 | instance | ok: Runtime: 0:00:00.004612
2026-08-22 10:28:33.515883 | 
2026-08-22 10:28:33.516022 | TASK [helm-release-status : Downloads logs to executor]
2026-08-22 10:28:34.007017 | instance | changed:
2026-08-22 10:28:34.007215 | instance | cd+++++++++ helm/
2026-08-22 10:28:34.007254 | instance | cd+++++++++ helm/releases/
2026-08-22 10:28:34.007286 | instance | cd+++++++++ helm/values/
2026-08-22 10:28:34.019228 | 
2026-08-22 10:28:34.019293 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-08-22 10:28:34.220746 | instance | changed
2026-08-22 10:28:34.227214 | 
2026-08-22 10:28:34.227289 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-08-22 10:28:34.482517 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args valuewarning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 10:28:34.482592 | instance |
2026-08-22 10:28:34.487324 | instance | environment: line 1: kubectl: command not found
2026-08-22 10:28:34.488731 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 10:28:34.489554 | instance | environment: line 1: kubectl: command not found
2026-08-22 10:28:34.490357 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 10:28:34.491309 | instance | environment: line 1: kubectl: command not found
2026-08-22 10:28:34.492341 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 10:28:34.492513 | instance | environment: line 1: kubectl: command not found
2026-08-22 10:28:34.494087 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 10:28:34.494903 | instance | environment: line 1: kubectl: command not found
2026-08-22 10:28:34.495686 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 10:28:34.768133 | instance | ok: Runtime: 0:00:00.020910
2026-08-22 10:28:34.773691 | 
2026-08-22 10:28:34.773802 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-08-22 10:28:34.970758 | instance | changed
2026-08-22 10:28:35.147838 | 
2026-08-22 10:28:35.148015 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-08-22 10:28:35.358395 | instance | environment: line 5: kubectl: command not found
2026-08-22 10:28:35.359532 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 10:28:35.359716 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 10:28:35.359865 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 10:28:35.681534 | instance | ok: Runtime: 0:00:00.009076
2026-08-22 10:28:35.689530 | 
2026-08-22 10:28:35.689625 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-08-22 10:28:36.214462 | instance | changed:
2026-08-22 10:28:36.214793 | instance | cd+++++++++ objects/
2026-08-22 10:28:36.214879 | instance | cd+++++++++ objects/cluster/
2026-08-22 10:28:36.214937 | instance | cd+++++++++ objects/namespaced/
2026-08-22 10:28:36.225374 | 
2026-08-22 10:28:36.225440 | TASK [gather-pod-logs : creating directory for pod logs]
2026-08-22 10:28:36.422356 | instance | changed
2026-08-22 10:28:36.428238 | 
2026-08-22 10:28:36.428314 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-08-22 10:28:36.629262 | instance | changed
2026-08-22 10:28:36.636154 | 
2026-08-22 10:28:36.636254 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-08-22 10:28:36.857416 | instance | environment: line 3: kubectl: command not found
2026-08-22 10:28:37.175311 | instance | ok: Runtime: 0:00:00.008261
2026-08-22 10:28:37.182451 | 
2026-08-22 10:28:37.182546 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-08-22 10:28:37.693266 | instance | changed:
2026-08-22 10:28:37.693583 | instance | cd+++++++++ pod-logs/
2026-08-22 10:28:37.693649 | instance | cd+++++++++ pod-logs/failed-pods/
2026-08-22 10:28:37.704650 | 
2026-08-22 10:28:37.704766 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-08-22 10:28:37.901494 | instance | changed
2026-08-22 10:28:37.909097 | 
2026-08-22 10:28:37.909199 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-08-22 10:28:38.133390 | instance | /bin/bash: line 2: kubectl: command not found
2026-08-22 10:28:38.448456 | instance | ok: Runtime: 0:00:00.034897
2026-08-22 10:28:38.455668 | 
2026-08-22 10:28:38.455765 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-08-22 10:28:38.686109 | instance | /bin/bash: line 2: kubectl: command not found
2026-08-22 10:28:38.720673 | instance | ceph-mgr endpoints:
2026-08-22 10:28:38.995581 | instance | ok: Runtime: 0:00:00.041695
2026-08-22 10:28:39.003404 | 
2026-08-22 10:28:39.003505 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-08-22 10:28:39.207919 | instance | /bin/bash: line 4: kubectl: command not found
2026-08-22 10:28:39.538215 | instance | ok: Runtime: 0:00:00.036598
2026-08-22 10:28:39.546229 | 
2026-08-22 10:28:39.546322 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-08-22 10:28:40.074226 | instance | changed: cd+++++++++ prometheus/
2026-08-22 10:28:40.083121 | 
2026-08-22 10:28:40.083188 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-08-22 10:28:40.291923 | instance | changed
2026-08-22 10:28:40.298565 | 
2026-08-22 10:28:40.298654 | TASK [gather-selenium-data : Get selenium data]
2026-08-22 10:28:40.564631 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-08-22 10:28:40.566231 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-08-22 10:28:40.832642 | instance | ERROR
2026-08-22 10:28:40.832867 | instance | {
2026-08-22 10:28:40.832998 | instance |   "delta": "0:00:00.006835",
2026-08-22 10:28:40.833040 | instance |   "end": "2026-08-22 10:28:40.566557",
2026-08-22 10:28:40.833071 | instance |   "msg": "non-zero return code",
2026-08-22 10:28:40.833097 | instance |   "rc": 1,
2026-08-22 10:28:40.833124 | instance |   "start": "2026-08-22 10:28:40.559722"
2026-08-22 10:28:40.833150 | instance | }
2026-08-22 10:28:40.833184 | instance | ERROR: Ignoring Errors
2026-08-22 10:28:40.840127 | 
2026-08-22 10:28:40.840214 | TASK [gather-selenium-data : Downloads logs to executor]
2026-08-22 10:28:41.354342 | instance | changed: cd+++++++++ selenium/
2026-08-22 10:28:41.362772 | 
2026-08-22 10:28:41.362828 | PLAY RECAP
2026-08-22 10:28:41.362880 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-08-22 10:28:41.363050 | 
2026-08-22 10:28:41.544275 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-08-22 10:28:41.549014 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-22 10:28:42.207383 | 
2026-08-22 10:28:42.207513 | PLAY [all]
2026-08-22 10:28:42.219181 | 
2026-08-22 10:28:42.219269 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-22 10:28:42.265972 | instance | skipping: Conditional result was False
2026-08-22 10:28:42.274508 | 
2026-08-22 10:28:42.274596 | TASK [fetch-output : Set log path for single node]
2026-08-22 10:28:42.320380 | instance | ok
2026-08-22 10:28:42.326784 | 
2026-08-22 10:28:42.326878 | LOOP [fetch-output : Ensure local output dirs]
2026-08-22 10:28:42.739974 | instance -> localhost | ok: "/var/lib/zuul/builds/97677deb5e0a4b29a1ff802b70bf3df3/work/logs"
2026-08-22 10:28:42.959663 | instance -> localhost | changed: "/var/lib/zuul/builds/97677deb5e0a4b29a1ff802b70bf3df3/work/artifacts"
2026-08-22 10:28:43.171606 | instance -> localhost | changed: "/var/lib/zuul/builds/97677deb5e0a4b29a1ff802b70bf3df3/work/docs"
2026-08-22 10:28:43.186479 | 
2026-08-22 10:28:43.186602 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-22 10:28:43.835498 | instance | changed: .d..t...... ./
2026-08-22 10:28:43.835834 | instance | changed: All items complete
2026-08-22 10:28:43.835906 | 
2026-08-22 10:28:44.327493 | instance | changed: .d..t...... ./
2026-08-22 10:28:44.807975 | instance | changed: .d..t...... ./
2026-08-22 10:28:44.835453 | 
2026-08-22 10:28:44.835568 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-22 10:28:45.258851 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.014704
2026-08-22 10:28:45.488718 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007489
2026-08-22 10:28:45.505430 | 
2026-08-22 10:28:45.505587 | PLAY [all]
2026-08-22 10:28:45.512302 | 
2026-08-22 10:28:45.512367 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-22 10:28:45.912654 | instance | changed
2026-08-22 10:28:45.923679 | 
2026-08-22 10:28:45.923802 | PLAY RECAP
2026-08-22 10:28:45.924014 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-22 10:28:45.924070 | 
2026-08-22 10:28:46.107440 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-22 10:28:46.123480 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-22 10:28:46.709447 | 
2026-08-22 10:28:46.709586 | PLAY [localhost]
2026-08-22 10:28:46.719657 | 
2026-08-22 10:28:46.719780 | TASK [Generate Zuul manifest]
2026-08-22 10:28:46.741628 | localhost | ok
2026-08-22 10:28:46.776415 | 
2026-08-22 10:28:46.776745 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-22 10:28:47.217723 | localhost | changed
2026-08-22 10:28:47.267972 | 
2026-08-22 10:28:47.268120 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-22 10:28:47.301952 | localhost | ok
2026-08-22 10:28:47.313392 | 
2026-08-22 10:28:47.313512 | TASK [Upload logs]
2026-08-22 10:28:47.339802 | localhost | ok
2026-08-22 10:28:47.446183 | 
2026-08-22 10:28:47.446574 | TASK [Set zuul-log-path fact]
2026-08-22 10:28:47.465864 | localhost | ok
2026-08-22 10:28:47.485902 | 
2026-08-22 10:28:47.485985 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-22 10:28:47.519685 | localhost | ok
2026-08-22 10:28:47.530216 | 
2026-08-22 10:28:47.530360 | TASK [upload-logs : Create log directories]
2026-08-22 10:28:47.937158 | localhost | changed
2026-08-22 10:28:47.943991 | 
2026-08-22 10:28:48.021817 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-22 10:28:48.431471 | localhost -> localhost | ok: Runtime: 0:00:00.005694
2026-08-22 10:28:48.438410 | 
2026-08-22 10:28:48.438510 | TASK [upload-logs : Upload logs to log server]
2026-08-22 10:28:48.927138 | localhost | Output suppressed because no_log was given
2026-08-22 10:28:48.962363 | 
2026-08-22 10:28:48.962494 | LOOP [upload-logs : Compress console log and json output]
2026-08-22 10:28:49.010088 | localhost | skipping: Conditional result was False
2026-08-22 10:28:49.017160 | localhost | skipping: Conditional result was False
2026-08-22 10:28:49.027387 | 
2026-08-22 10:28:49.027520 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-22 10:28:49.076667 | localhost | skipping: Conditional result was False
2026-08-22 10:28:49.077212 | 
2026-08-22 10:28:49.080867 | localhost | skipping: Conditional result was False
2026-08-22 10:28:49.092980 | 
2026-08-22 10:28:49.093173 | LOOP [upload-logs : Upload console log and json output]