2026-08-22 10:28:39.564686 | Job console starting
2026-08-22 10:28:39.578136 | Updating git repos
2026-08-22 10:28:42.300193 | Cloning repos into workspace
2026-08-22 10:29:11.147845 | Restoring repo states
2026-08-22 10:29:17.030194 | Merging changes
2026-08-22 10:29:18.637790 | Checking out repos
2026-08-22 10:29:47.716004 | Preparing playbooks
2026-08-22 10:30:06.459557 | Running Ansible setup
2026-08-22 10:30:11.034874 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-22 10:30:11.648575 | 
2026-08-22 10:30:11.648692 | PLAY [localhost]
2026-08-22 10:30:11.656666 | 
2026-08-22 10:30:11.656761 | TASK [Gathering Facts]
2026-08-22 10:30:12.615109 | localhost | ok
2026-08-22 10:30:12.626904 | 
2026-08-22 10:30:12.627036 | TASK [Setup log path fact]
2026-08-22 10:30:12.647514 | localhost | ok
2026-08-22 10:30:12.660077 | 
2026-08-22 10:30:12.660196 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-22 10:30:12.690629 | localhost | ok
2026-08-22 10:30:12.701714 | 
2026-08-22 10:30:12.701861 | TASK [emit-job-header : Print job information]
2026-08-22 10:30:12.744169 | # Job Information
2026-08-22 10:30:12.744414 | Ansible Version: 2.16.18
2026-08-22 10:30:12.744493 | Job: atmosphere-molecule-csi-rbd
2026-08-22 10:30:12.744548 | Pipeline: check
2026-08-22 10:30:12.744597 | Executor: 2d72f0692154
2026-08-22 10:30:12.744644 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4094
2026-08-22 10:30:12.744700 | Event ID: 624a26b0-9e13-11f1-820d-a98fd2148bf3
2026-08-22 10:30:12.750638 | 
2026-08-22 10:30:12.750708 | LOOP [emit-job-header : Print node information]
2026-08-22 10:30:12.886485 | localhost | ok:
2026-08-22 10:30:12.886749 | localhost | # Node Information
2026-08-22 10:30:12.886804 | localhost | Inventory Hostname: instance
2026-08-22 10:30:12.886844 | localhost | Hostname: np0000190588
2026-08-22 10:30:12.886879 | localhost | Username: zuul
2026-08-22 10:30:12.886920 | localhost | Distro: Ubuntu 22.04
2026-08-22 10:30:12.886956 | localhost | Provider: yul1
2026-08-22 10:30:12.886991 | localhost | Region: ca-ymq-1
2026-08-22 10:30:12.887025 | localhost | Label: ubuntu-jammy
2026-08-22 10:30:12.887058 | localhost | Product Name: OpenStack Nova
2026-08-22 10:30:12.887091 | localhost | Interface IP: 199.19.213.119
2026-08-22 10:30:12.894804 | 
2026-08-22 10:30:12.894966 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-22 10:30:13.420296 | localhost -> localhost | changed
2026-08-22 10:30:13.430625 | 
2026-08-22 10:30:13.430735 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-22 10:30:14.481881 | localhost -> localhost | changed
2026-08-22 10:30:14.492597 | 
2026-08-22 10:30:14.492757 | PLAY [all]
2026-08-22 10:30:14.503624 | 
2026-08-22 10:30:14.503797 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-22 10:30:14.873810 | instance -> localhost | ok
2026-08-22 10:30:14.882268 | 
2026-08-22 10:30:14.882364 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-22 10:30:14.918718 | instance | ok
2026-08-22 10:30:14.936320 | instance | included: /var/lib/zuul/builds/50e1acad7a384cb193eeefcafd923752/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-22 10:30:14.942932 | 
2026-08-22 10:30:14.943001 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-22 10:30:16.244656 | instance -> localhost | Generating public/private rsa key pair.
2026-08-22 10:30:16.244874 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/50e1acad7a384cb193eeefcafd923752/work/50e1acad7a384cb193eeefcafd923752_id_rsa
2026-08-22 10:30:16.244925 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/50e1acad7a384cb193eeefcafd923752/work/50e1acad7a384cb193eeefcafd923752_id_rsa.pub
2026-08-22 10:30:16.245168 | instance -> localhost | The key fingerprint is:
2026-08-22 10:30:16.245211 | instance -> localhost | SHA256:1zw6LitKRwpa2aykS020YaPsHZ0l5XWynOLpzXq2sBg zuul-build-sshkey
2026-08-22 10:30:16.245257 | instance -> localhost | The key's randomart image is:
2026-08-22 10:30:16.245290 | instance -> localhost | +---[RSA 3072]----+
2026-08-22 10:30:16.245328 | instance -> localhost | |       . o .     |
2026-08-22 10:30:16.245363 | instance -> localhost | |      o o =      |
2026-08-22 10:30:16.245395 | instance -> localhost | |   = . + +       |
2026-08-22 10:30:16.245425 | instance -> localhost | |. ++= = o  o     |
2026-08-22 10:30:16.245455 | instance -> localhost | | o=+oo.oS . +    |
2026-08-22 10:30:16.245484 | instance -> localhost | |.=+o.o. o. . .   |
2026-08-22 10:30:16.245514 | instance -> localhost | |oo.ooE.o oo      |
2026-08-22 10:30:16.245543 | instance -> localhost | |.. . .+ =+ .     |
2026-08-22 10:30:16.245667 | instance -> localhost | |.   .o +++o      |
2026-08-22 10:30:16.245710 | instance -> localhost | +----[SHA256]-----+
2026-08-22 10:30:16.245791 | instance -> localhost | ok: Runtime: 0:00:00.858346
2026-08-22 10:30:16.254962 | 
2026-08-22 10:30:16.255088 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-22 10:30:16.288676 | instance | ok
2026-08-22 10:30:16.302596 | instance | included: /var/lib/zuul/builds/50e1acad7a384cb193eeefcafd923752/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-22 10:30:16.312383 | 
2026-08-22 10:30:16.312490 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-22 10:30:16.336992 | instance | skipping: Conditional result was False
2026-08-22 10:30:16.345800 | 
2026-08-22 10:30:16.345937 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-22 10:30:16.786514 | instance | changed
2026-08-22 10:30:16.795409 | 
2026-08-22 10:30:16.795511 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-22 10:30:16.983048 | instance | ok
2026-08-22 10:30:16.990491 | 
2026-08-22 10:30:16.990585 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-22 10:30:17.481125 | instance | changed
2026-08-22 10:30:17.492534 | 
2026-08-22 10:30:17.492755 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-22 10:30:17.944934 | instance | changed
2026-08-22 10:30:17.953088 | 
2026-08-22 10:30:17.953212 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-22 10:30:17.977301 | instance | skipping: Conditional result was False
2026-08-22 10:30:17.988024 | 
2026-08-22 10:30:17.988094 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-22 10:30:18.642856 | instance -> localhost | changed
2026-08-22 10:30:18.660750 | 
2026-08-22 10:30:18.660869 | TASK [add-build-sshkey : Add back temp key]
2026-08-22 10:30:18.997832 | instance -> localhost | Identity added: /var/lib/zuul/builds/50e1acad7a384cb193eeefcafd923752/work/50e1acad7a384cb193eeefcafd923752_id_rsa (zuul-build-sshkey)
2026-08-22 10:30:18.998018 | instance -> localhost | ok: Runtime: 0:00:00.013129
2026-08-22 10:30:19.003151 | 
2026-08-22 10:30:19.003219 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-22 10:30:19.316295 | instance | ok
2026-08-22 10:30:19.412875 | 
2026-08-22 10:30:19.413096 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-22 10:30:19.438083 | instance | skipping: Conditional result was False
2026-08-22 10:30:19.452877 | 
2026-08-22 10:30:19.453136 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-22 10:30:19.802076 | instance | ok
2026-08-22 10:30:19.810921 | 
2026-08-22 10:30:19.811046 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-22 10:30:24.843401 | instance | Output suppressed because no_log was given
2026-08-22 10:30:24.977044 | 
2026-08-22 10:30:24.977171 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-22 10:30:25.175429 | instance | ok: "logs"
2026-08-22 10:30:25.175713 | instance | ok: All items complete
2026-08-22 10:30:25.175752 | 
2026-08-22 10:30:25.322884 | instance | ok: "artifacts"
2026-08-22 10:30:25.474291 | instance | ok: "docs"
2026-08-22 10:30:25.483157 | 
2026-08-22 10:30:25.483307 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-22 10:30:25.683957 | instance | changed: "logs"
2026-08-22 10:30:25.849785 | instance | changed: "artifacts"
2026-08-22 10:30:26.009410 | instance | changed: "docs"
2026-08-22 10:30:26.026579 | 
2026-08-22 10:30:26.026722 | PLAY RECAP
2026-08-22 10:30:26.026789 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-22 10:30:26.026835 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 10:30:26.026870 | 
2026-08-22 10:30:26.190157 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-22 10:30:26.193402 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-22 10:30:26.865826 | 
2026-08-22 10:30:30.451945 | PLAY [all]
2026-08-22 10:30:30.469685 | 
2026-08-22 10:30:30.469829 | TASK [setup-uv : Extract archive]
2026-08-22 10:30:32.716157 | instance | changed
2026-08-22 10:30:32.722302 | 
2026-08-22 10:30:32.722377 | TASK [setup-uv : Print version]
2026-08-22 10:30:33.128500 | instance | uv 0.8.13
2026-08-22 10:30:33.261231 | instance | ok: Runtime: 0:00:00.012152
2026-08-22 10:30:33.271756 | 
2026-08-22 10:30:33.271829 | TASK [Install Ansible collection siblings]
2026-08-22 10:30:42.568404 | instance | changed:
2026-08-22 10:30:42.568766 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-08-22 10:30:42.568824 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-08-22 10:30:42.568865 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-08-22 10:30:42.568904 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-08-22 10:30:42.568941 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-08-22 10:30:42.569074 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-08-22 10:30:42.569121 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-08-22 10:30:42.569166 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-08-22 10:30:42.569207 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-08-22 10:30:42.569242 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-08-22 10:30:42.569278 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-08-22 10:30:42.569313 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-08-22 10:30:42.569453 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-08-22 10:30:42.569528 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-08-22 10:30:42.569570 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0
2026-08-22 10:30:42.569608 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-08-22 10:30:42.569644 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-08-22 10:30:42.569679 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-08-22 10:30:42.569714 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-08-22 10:30:42.569748 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-08-22 10:30:42.569784 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0
2026-08-22 10:30:42.569819 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-08-22 10:30:42.569861 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-08-22 10:30:42.569916 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-08-22 10:30:42.569956 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0
2026-08-22 10:30:42.569996 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-08-22 10:30:42.570035 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-08-22 10:30:42.570075 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-08-22 10:30:42.570114 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-08-22 10:30:42.570152 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-08-22 10:30:42.570190 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-08-22 10:30:42.570230 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-08-22 10:30:42.570374 | instance | Checked out ansible.netcommon to 1.2.0
2026-08-22 10:30:42.570424 | 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:30:42.570474 | instance | Starting galaxy collection install process
2026-08-22 10:30:42.570514 | instance | Process install dependency map
2026-08-22 10:30:42.570554 | instance | Starting collection install process
2026-08-22 10:30:42.570587 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-08-22 10:30:42.570615 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-08-22 10:30:42.570650 | instance | ansible.netcommon:* was installed successfully
2026-08-22 10:30:42.570689 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-08-22 10:30:42.570729 | instance | Creating virtual environment at: .venv
2026-08-22 10:30:42.570767 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-08-22 10:30:42.570796 | instance | Downloading openstacksdk (1.7MiB)
2026-08-22 10:30:42.570826 | instance | Downloading pygments (1.2MiB)
2026-08-22 10:30:42.570855 | instance | Downloading netaddr (2.2MiB)
2026-08-22 10:30:42.570882 | instance | Downloading rjsonnet (1.2MiB)
2026-08-22 10:30:42.570909 | instance | Downloading cryptography (4.2MiB)
2026-08-22 10:30:42.570936 | instance | Downloading setuptools (1.1MiB)
2026-08-22 10:30:42.570964 | instance | Downloading kubernetes (1.9MiB)
2026-08-22 10:30:42.570992 | instance | Downloading ansible-core (2.1MiB)
2026-08-22 10:30:42.571018 | instance | Downloading pydantic-core (2.0MiB)
2026-08-22 10:30:42.571046 | instance |    Building pyperclip==1.9.0
2026-08-22 10:30:42.571075 | instance |  Downloading rjsonnet
2026-08-22 10:30:42.571103 | instance |  Downloading pydantic-core
2026-08-22 10:30:42.571131 | instance |  Downloading netaddr
2026-08-22 10:30:42.571157 | instance |  Downloading pygments
2026-08-22 10:30:42.571184 | instance |  Downloading cryptography
2026-08-22 10:30:42.571211 | instance |  Downloading setuptools
2026-08-22 10:30:42.571238 | instance |  Downloading kubernetes
2026-08-22 10:30:42.571265 | instance |  Downloading ansible-core
2026-08-22 10:30:42.571298 | instance |  Downloading openstacksdk
2026-08-22 10:30:42.571363 | instance |       Built pyperclip==1.9.0
2026-08-22 10:30:42.571409 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-08-22 10:30:42.571449 | instance | Installed 83 packages in 157ms
2026-08-22 10:30:42.571489 | instance | Checked out ansible.posix to 1.6.0
2026-08-22 10:30:42.571523 | 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:30:42.571552 | instance | Starting galaxy collection install process
2026-08-22 10:30:42.571580 | instance | Process install dependency map
2026-08-22 10:30:42.571617 | instance | Starting collection install process
2026-08-22 10:30:42.571645 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-08-22 10:30:42.571676 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-08-22 10:30:42.571711 | instance | ansible.posix:1.6.0 was installed successfully
2026-08-22 10:30:42.571968 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 10:30:42.572001 | instance | 2.17.12
2026-08-22 10:30:42.572053 | instance | Checked out ansible.utils to v6.1.0
2026-08-22 10:30:42.572090 | 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:30:42.572174 | instance | Starting galaxy collection install process
2026-08-22 10:30:42.572211 | instance | Process install dependency map
2026-08-22 10:30:42.572239 | instance | Starting collection install process
2026-08-22 10:30:42.572268 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-08-22 10:30:42.572297 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-08-22 10:30:42.572326 | instance | ansible.utils:6.1.0 was installed successfully
2026-08-22 10:30:42.572367 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 10:30:42.572404 | instance | 2.17.12
2026-08-22 10:30:42.572442 | instance | Checked out atmosphere.common to v0.7.0
2026-08-22 10:30:42.572479 | 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:30:42.572508 | instance | Starting galaxy collection install process
2026-08-22 10:30:42.572536 | instance | Process install dependency map
2026-08-22 10:30:42.572562 | instance | Starting collection install process
2026-08-22 10:30:42.572589 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-08-22 10:30:42.572617 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-08-22 10:30:42.572644 | instance | atmosphere.common:0.7.0 was installed successfully
2026-08-22 10:30:42.572672 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 10:30:42.572700 | instance | 2.17.12
2026-08-22 10:30:42.572727 | instance | Checked out community.crypto to 2.2.3
2026-08-22 10:30:42.572754 | 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:30:42.572782 | instance | Starting galaxy collection install process
2026-08-22 10:30:42.572809 | instance | Process install dependency map
2026-08-22 10:30:42.572836 | instance | Starting collection install process
2026-08-22 10:30:42.572863 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-08-22 10:30:42.572890 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-08-22 10:30:42.572917 | instance | community.crypto:2.2.3 was installed successfully
2026-08-22 10:30:42.573214 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 10:30:42.573240 | instance | 2.17.12
2026-08-22 10:30:42.573260 | instance | Checked out community.general to 7.3.0
2026-08-22 10:30:42.573279 | 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:30:42.573305 | instance | Starting galaxy collection install process
2026-08-22 10:30:42.573324 | instance | Process install dependency map
2026-08-22 10:30:42.573343 | instance | Starting collection install process
2026-08-22 10:30:42.573361 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-08-22 10:30:42.573380 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-08-22 10:30:42.573399 | instance | community.general:7.3.0 was installed successfully
2026-08-22 10:30:42.573418 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 10:30:42.573436 | instance | 2.17.12
2026-08-22 10:30:42.573455 | instance | Checked out community.mysql to 3.6.0
2026-08-22 10:30:42.573477 | 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:30:42.573496 | instance | Starting galaxy collection install process
2026-08-22 10:30:42.573515 | instance | Process install dependency map
2026-08-22 10:30:42.573533 | instance | Starting collection install process
2026-08-22 10:30:42.573551 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-08-22 10:30:42.573570 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-08-22 10:30:42.573588 | instance | community.mysql:3.6.0 was installed successfully
2026-08-22 10:30:42.573607 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 10:30:42.573626 | instance | 2.17.12
2026-08-22 10:30:42.573644 | instance | Checked out kubernetes.core to 2.4.0
2026-08-22 10:30:42.573662 | 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:30:42.573681 | instance | Starting galaxy collection install process
2026-08-22 10:30:42.573699 | instance | Process install dependency map
2026-08-22 10:30:42.573716 | instance | Starting collection install process
2026-08-22 10:30:42.573734 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-08-22 10:30:42.573753 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-08-22 10:30:42.573771 | instance | kubernetes.core:2.4.0 was installed successfully
2026-08-22 10:30:42.573790 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 10:30:42.573808 | instance | 2.17.12
2026-08-22 10:30:42.573826 | instance | Checked out openstack.cloud to 2.6.0
2026-08-22 10:30:42.573845 | 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:30:42.573864 | instance | Starting galaxy collection install process
2026-08-22 10:30:42.573882 | instance | Process install dependency map
2026-08-22 10:30:42.573901 | instance | Starting collection install process
2026-08-22 10:30:42.573919 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-08-22 10:30:42.573938 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-08-22 10:30:42.573956 | instance | openstack.cloud:2.6.0 was installed successfully
2026-08-22 10:30:42.573974 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 10:30:42.574033 | instance | 2.17.12
2026-08-22 10:30:42.574108 | instance | Checked out vexxhost.ceph to v4.2.0
2026-08-22 10:30:42.574496 | 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:30:42.574554 | instance | Starting galaxy collection install process
2026-08-22 10:30:42.574593 | instance | Process install dependency map
2026-08-22 10:30:42.574620 | instance | Starting collection install process
2026-08-22 10:30:42.574641 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-08-22 10:30:42.574660 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-08-22 10:30:42.574679 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-08-22 10:30:42.574816 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 10:30:42.574842 | instance | 2.17.12
2026-08-22 10:30:42.574861 | instance | Checked out vexxhost.containers to v1.6.8
2026-08-22 10:30:42.574881 | 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:30:42.574900 | instance | Starting galaxy collection install process
2026-08-22 10:30:42.574920 | instance | Process install dependency map
2026-08-22 10:30:42.574938 | instance | Starting collection install process
2026-08-22 10:30:42.574956 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-08-22 10:30:42.574975 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-08-22 10:30:42.574994 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-08-22 10:30:42.575013 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 10:30:42.575031 | instance | 2.17.12
2026-08-22 10:30:42.575050 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-08-22 10:30:42.575069 | 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:30:42.575088 | instance | Starting galaxy collection install process
2026-08-22 10:30:42.575107 | instance | Process install dependency map
2026-08-22 10:30:42.575363 | instance | Starting collection install process
2026-08-22 10:30:42.575405 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-08-22 10:30:42.575429 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-08-22 10:30:42.575449 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-08-22 10:30:42.575469 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 10:30:42.575488 | instance | 2.17.12
2026-08-22 10:30:42.593995 | 
2026-08-22 10:30:42.594070 | PLAY RECAP
2026-08-22 10:30:42.594116 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 10:30:42.594137 | 
2026-08-22 10:30:42.718224 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-22 10:30:42.720445 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-08-22 10:30:43.392329 | 
2026-08-22 10:30:43.392447 | PLAY [all]
2026-08-22 10:30:43.403146 | 
2026-08-22 10:30:43.403231 | TASK [Install "jq" for log collection]
2026-08-22 10:30:53.142181 | instance | changed
2026-08-22 10:30:53.144817 | 
2026-08-22 10:30:53.144894 | PLAY RECAP
2026-08-22 10:30:53.144981 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 10:30:53.145063 | 
2026-08-22 10:30:53.308577 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-08-22 10:30:53.312789 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-22 10:30:53.977971 | 
2026-08-22 10:30:53.978166 | PLAY [all]
2026-08-22 10:30:53.990279 | 
2026-08-22 10:30:53.990424 | LOOP [Check inventory directories]
2026-08-22 10:30:54.320227 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-08-22 10:30:54.476313 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-08-22 10:30:54.627438 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-08-22 10:30:54.837323 | 
2026-08-22 10:30:54.837497 | LOOP [Copy inventory file for Zuul]
2026-08-22 10:30:55.635479 | instance | changed:
2026-08-22 10:30:55.635927 | instance | {
2026-08-22 10:30:55.635985 | instance |   "ansible_loop_var": "item",
2026-08-22 10:30:55.636021 | instance |   "changed": false,
2026-08-22 10:30:55.636052 | instance |   "failed": false,
2026-08-22 10:30:55.636094 | instance |   "invocation": {
2026-08-22 10:30:55.636125 | instance |     "module_args": {
2026-08-22 10:30:55.636152 | instance |       "checksum_algorithm": "sha1",
2026-08-22 10:30:55.636180 | instance |       "follow": true,
2026-08-22 10:30:55.636207 | instance |       "get_attributes": true,
2026-08-22 10:30:55.636235 | instance |       "get_checksum": true,
2026-08-22 10:30:55.636262 | instance |       "get_mime": true,
2026-08-22 10:30:55.636288 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-08-22 10:30:55.636315 | instance |     }
2026-08-22 10:30:55.636342 | instance |   },
2026-08-22 10:30:55.636368 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-08-22 10:30:55.636395 | instance |   "stat": {
2026-08-22 10:30:55.636421 | instance |     "atime": 1787394635.2660177,
2026-08-22 10:30:55.636448 | instance |     "attr_flags": "e",
2026-08-22 10:30:55.636475 | instance |     "attributes": [
2026-08-22 10:30:55.636501 | instance |       "extents"
2026-08-22 10:30:55.636527 | instance |     ],
2026-08-22 10:30:55.636553 | instance |     "block_size": 4096,
2026-08-22 10:30:55.636590 | instance |     "blocks": 8,
2026-08-22 10:30:55.636627 | instance |     "charset": "unknown",
2026-08-22 10:30:55.636660 | instance |     "ctime": 1787394634.1299965,
2026-08-22 10:30:55.636794 | instance |     "dev": 64513,
2026-08-22 10:30:55.636834 | instance |     "device_type": 0,
2026-08-22 10:30:55.636863 | instance |     "executable": true,
2026-08-22 10:30:55.636890 | instance |     "exists": true,
2026-08-22 10:30:55.636916 | instance |     "gid": 1000,
2026-08-22 10:30:55.636942 | instance |     "gr_name": "zuul",
2026-08-22 10:30:55.636994 | instance |     "inode": 1046982,
2026-08-22 10:30:55.637022 | instance |     "isblk": false,
2026-08-22 10:30:55.637049 | instance |     "ischr": false,
2026-08-22 10:30:55.637075 | instance |     "isdir": true,
2026-08-22 10:30:55.637101 | instance |     "isfifo": false,
2026-08-22 10:30:55.637126 | instance |     "isgid": false,
2026-08-22 10:30:55.637150 | instance |     "islnk": false,
2026-08-22 10:30:55.637175 | instance |     "isreg": false,
2026-08-22 10:30:55.637200 | instance |     "issock": false,
2026-08-22 10:30:55.637224 | instance |     "isuid": false,
2026-08-22 10:30:55.637248 | instance |     "mimetype": "unknown",
2026-08-22 10:30:55.637272 | instance |     "mode": "0755",
2026-08-22 10:30:55.637296 | instance |     "mtime": 1787394634.1299965,
2026-08-22 10:30:55.637321 | instance |     "nlink": 22,
2026-08-22 10:30:55.637346 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-08-22 10:30:55.637371 | instance |     "pw_name": "zuul",
2026-08-22 10:30:55.637397 | instance |     "readable": true,
2026-08-22 10:30:55.637423 | instance |     "rgrp": true,
2026-08-22 10:30:55.637448 | instance |     "roth": true,
2026-08-22 10:30:55.637496 | instance |     "rusr": true,
2026-08-22 10:30:55.637522 | instance |     "size": 4096,
2026-08-22 10:30:55.637548 | instance |     "uid": 1000,
2026-08-22 10:30:55.637573 | instance |     "version": "1245310150",
2026-08-22 10:30:55.637598 | instance |     "wgrp": false,
2026-08-22 10:30:55.637619 | instance |     "woth": false,
2026-08-22 10:30:55.637637 | instance |     "writeable": true,
2026-08-22 10:30:55.637654 | instance |     "wusr": true,
2026-08-22 10:30:55.637671 | instance |     "xgrp": true,
2026-08-22 10:30:55.637688 | instance |     "xoth": true,
2026-08-22 10:30:55.637705 | instance |     "xusr": true
2026-08-22 10:30:55.637723 | instance |   }
2026-08-22 10:30:55.637741 | instance | }
2026-08-22 10:30:55.637783 | instance | ok: All items complete
2026-08-22 10:30:55.637806 | 
2026-08-22 10:30:55.639303 | instance | skipping: Conditional result was False
2026-08-22 10:30:55.639900 | instance | skipping: Conditional result was False
2026-08-22 10:30:55.657903 | 
2026-08-22 10:30:55.658095 | LOOP [Switch "ansible_host" to private IP]
2026-08-22 10:30:56.019173 | instance | changed: 1 replacements made
2026-08-22 10:30:56.079622 | instance | skipping: Conditional result was False
2026-08-22 10:30:56.080307 | instance | skipping: Conditional result was False
2026-08-22 10:30:56.099415 | 
2026-08-22 10:30:56.099710 | TASK [Run Molecule scenario]
2026-08-22 10:30:56.990194 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-08-22 10:30:57.350532 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-08-22 10:30:57.350581 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-08-22 10:31:08.141099 | instance | INFO     [csi > dependency] Executing
2026-08-22 10:31:08.141296 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-08-22 10:31:08.141479 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-08-22 10:31:08.141589 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-08-22 10:31:08.149173 | instance | INFO     [csi > cleanup] Executing
2026-08-22 10:31:08.149517 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-22 10:31:08.157295 | instance | INFO     [csi > destroy] Executing
2026-08-22 10:31:08.157329 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-08-22 10:31:08.157454 | instance | INFO     [csi > destroy] Executed: Successful
2026-08-22 10:31:08.164894 | instance | INFO     [csi > syntax] Executing
2026-08-22 10:31:09.741245 | instance |
2026-08-22 10:31:09.741463 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-08-22 10:31:09.799343 | instance | INFO     [csi > syntax] Executed: Successful
2026-08-22 10:31:09.814078 | instance | INFO     [csi > create] Executing
2026-08-22 10:31:09.815872 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-22 10:31:09.823368 | instance | INFO     [csi > prepare] Executing
2026-08-22 10:31:10.590031 | instance |
2026-08-22 10:31:10.590295 | instance | PLAY [Prepare] *****************************************************************
2026-08-22 10:31:10.590570 | instance |
2026-08-22 10:31:10.590806 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 10:31:10.591078 | instance | Saturday 22 August 2026  10:31:10 +0000 (0:00:00.024)       0:00:00.024 *******
2026-08-22 10:31:11.774061 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-08-22 10:31:11.774352 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-22 10:31:11.774705 | instance | interpreter could change the meaning of that path. See
2026-08-22 10:31:11.774996 | instance | https://docs.ansible.com/ansible-
2026-08-22 10:31:11.775278 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-08-22 10:31:11.782083 | instance | ok: [instance]
2026-08-22 10:31:11.782348 | instance |
2026-08-22 10:31:11.782660 | instance | TASK [Configure short hostname] ************************************************
2026-08-22 10:31:11.782947 | instance | Saturday 22 August 2026  10:31:11 +0000 (0:00:01.192)       0:00:01.217 *******
2026-08-22 10:31:12.431710 | instance | changed: [instance]
2026-08-22 10:31:12.431966 | instance |
2026-08-22 10:31:12.432254 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-08-22 10:31:12.432550 | instance | Saturday 22 August 2026  10:31:12 +0000 (0:00:00.648)       0:00:01.866 *******
2026-08-22 10:31:12.679754 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-08-22 10:31:12.679791 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-08-22 10:31:12.679796 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-08-22 10:31:12.688173 | instance | changed: [instance]
2026-08-22 10:31:12.688328 | instance |
2026-08-22 10:31:12.688485 | instance | TASK [Purge "snapd" package] ***************************************************
2026-08-22 10:31:12.688638 | instance | Saturday 22 August 2026  10:31:12 +0000 (0:00:00.257)       0:00:02.124 *******
2026-08-22 10:31:13.640148 | instance | ok: [instance]
2026-08-22 10:31:13.640182 | instance |
2026-08-22 10:31:13.640188 | instance | PLAY [Create devices for Ceph] *************************************************
2026-08-22 10:31:13.640193 | instance |
2026-08-22 10:31:13.640197 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 10:31:13.640211 | instance | Saturday 22 August 2026  10:31:13 +0000 (0:00:00.951)       0:00:03.075 *******
2026-08-22 10:31:14.323857 | instance | ok: [instance]
2026-08-22 10:31:14.323905 | instance |
2026-08-22 10:31:14.323916 | instance | TASK [Install depedencies] *****************************************************
2026-08-22 10:31:14.323925 | instance | Saturday 22 August 2026  10:31:14 +0000 (0:00:00.683)       0:00:03.758 *******
2026-08-22 10:31:38.625797 | instance | changed: [instance]
2026-08-22 10:31:38.625856 | instance |
2026-08-22 10:31:38.625868 | instance | TASK [Start up service] ********************************************************
2026-08-22 10:31:38.625880 | instance | Saturday 22 August 2026  10:31:38 +0000 (0:00:24.301)       0:00:28.060 *******
2026-08-22 10:31:39.133873 | instance | ok: [instance]
2026-08-22 10:31:39.133941 | instance |
2026-08-22 10:31:39.133953 | instance | TASK [Generate lvm.conf] *******************************************************
2026-08-22 10:31:39.133970 | instance | Saturday 22 August 2026  10:31:39 +0000 (0:00:00.508)       0:00:28.568 *******
2026-08-22 10:31:39.433194 | instance | ok: [instance]
2026-08-22 10:31:39.433337 | instance |
2026-08-22 10:31:39.433654 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-08-22 10:31:39.433982 | instance | Saturday 22 August 2026  10:31:39 +0000 (0:00:00.299)       0:00:28.868 *******
2026-08-22 10:31:40.063056 | instance | changed: [instance]
2026-08-22 10:31:40.063137 | instance |
2026-08-22 10:31:40.063261 | instance | TASK [Get list of all loopback devices] ****************************************
2026-08-22 10:31:40.063383 | instance | Saturday 22 August 2026  10:31:40 +0000 (0:00:00.630)       0:00:29.498 *******
2026-08-22 10:31:40.248901 | instance | ok: [instance]
2026-08-22 10:31:40.249062 | instance |
2026-08-22 10:31:40.249246 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-08-22 10:31:40.249425 | instance | Saturday 22 August 2026  10:31:40 +0000 (0:00:00.185)       0:00:29.684 *******
2026-08-22 10:31:40.271476 | instance | skipping: [instance]
2026-08-22 10:31:40.271748 | instance |
2026-08-22 10:31:40.272041 | instance | TASK [Create devices for Ceph] *************************************************
2026-08-22 10:31:40.272326 | instance | Saturday 22 August 2026  10:31:40 +0000 (0:00:00.022)       0:00:29.706 *******
2026-08-22 10:31:40.788199 | instance | changed: [instance] => (item=osd0)
2026-08-22 10:31:40.788432 | instance | changed: [instance] => (item=osd1)
2026-08-22 10:31:40.788713 | instance | changed: [instance] => (item=osd2)
2026-08-22 10:31:40.789029 | instance |
2026-08-22 10:31:40.789325 | instance | TASK [Set permissions on loopback devices] *************************************
2026-08-22 10:31:40.789609 | instance | Saturday 22 August 2026  10:31:40 +0000 (0:00:00.516)       0:00:30.223 *******
2026-08-22 10:31:41.364540 | instance | changed: [instance] => (item=osd0)
2026-08-22 10:31:41.364777 | instance | changed: [instance] => (item=osd1)
2026-08-22 10:31:41.365056 | instance | changed: [instance] => (item=osd2)
2026-08-22 10:31:41.365322 | instance |
2026-08-22 10:31:41.365710 | instance | TASK [Start loop devices] ******************************************************
2026-08-22 10:31:41.366119 | instance | Saturday 22 August 2026  10:31:41 +0000 (0:00:00.576)       0:00:30.799 *******
2026-08-22 10:31:42.003570 | instance | changed: [instance] => (item=osd0)
2026-08-22 10:31:42.003669 | instance | changed: [instance] => (item=osd1)
2026-08-22 10:31:42.003827 | instance | changed: [instance] => (item=osd2)
2026-08-22 10:31:42.003987 | instance |
2026-08-22 10:31:42.004158 | instance | TASK [Create a volume group for each loop device] ******************************
2026-08-22 10:31:42.004333 | instance | Saturday 22 August 2026  10:31:41 +0000 (0:00:00.639)       0:00:31.438 *******
2026-08-22 10:31:45.200579 | instance | changed: [instance] => (item=osd0)
2026-08-22 10:31:45.200657 | instance | changed: [instance] => (item=osd1)
2026-08-22 10:31:45.201177 | instance | changed: [instance] => (item=osd2)
2026-08-22 10:31:45.201337 | instance |
2026-08-22 10:31:45.201345 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-08-22 10:31:45.201350 | instance | Saturday 22 August 2026  10:31:45 +0000 (0:00:03.196)       0:00:34.635 *******
2026-08-22 10:31:47.094787 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-08-22 10:31:47.097851 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-08-22 10:31:47.097913 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-08-22 10:31:47.097921 | instance |
2026-08-22 10:31:47.097927 | instance | PLAY RECAP *********************************************************************
2026-08-22 10:31:47.097934 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-08-22 10:31:47.097940 | instance |
2026-08-22 10:31:47.097946 | instance | Saturday 22 August 2026  10:31:47 +0000 (0:00:01.894)       0:00:36.530 *******
2026-08-22 10:31:47.097952 | instance | ===============================================================================
2026-08-22 10:31:47.097957 | instance | Install depedencies ---------------------------------------------------- 24.30s
2026-08-22 10:31:47.097963 | instance | Create a volume group for each loop device ------------------------------ 3.20s
2026-08-22 10:31:47.097968 | instance | Create a logical volume for each loop device ---------------------------- 1.89s
2026-08-22 10:31:47.097974 | instance | Gathering Facts --------------------------------------------------------- 1.19s
2026-08-22 10:31:47.097980 | instance | Purge "snapd" package --------------------------------------------------- 0.95s
2026-08-22 10:31:47.097985 | instance | Gathering Facts --------------------------------------------------------- 0.68s
2026-08-22 10:31:47.097991 | instance | Configure short hostname ------------------------------------------------ 0.65s
2026-08-22 10:31:47.097996 | instance | Start loop devices ------------------------------------------------------ 0.64s
2026-08-22 10:31:47.098006 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.63s
2026-08-22 10:31:47.099146 | instance | Set permissions on loopback devices ------------------------------------- 0.58s
2026-08-22 10:31:47.099193 | instance | Create devices for Ceph ------------------------------------------------- 0.52s
2026-08-22 10:31:47.099200 | instance | Start up service -------------------------------------------------------- 0.51s
2026-08-22 10:31:47.099206 | instance | Generate lvm.conf ------------------------------------------------------- 0.30s
2026-08-22 10:31:47.099212 | instance | Ensure hostname inside hosts file --------------------------------------- 0.26s
2026-08-22 10:31:47.099218 | instance | Get list of all loopback devices ---------------------------------------- 0.19s
2026-08-22 10:31:47.099224 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s
2026-08-22 10:31:47.159850 | instance | INFO     [csi > prepare] Executed: Successful
2026-08-22 10:31:47.171390 | instance | INFO     [csi > converge] Executing
2026-08-22 10:31:48.643185 | instance |
2026-08-22 10:31:48.643630 | instance | PLAY [Debug CSI driver value] **************************************************
2026-08-22 10:31:48.643981 | instance |
2026-08-22 10:31:48.644316 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 10:31:48.644756 | instance | Saturday 22 August 2026  10:31:48 +0000 (0:00:00.007)       0:00:00.007 *******
2026-08-22 10:31:49.593755 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-08-22 10:31:49.593858 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-22 10:31:49.593871 | instance | interpreter could change the meaning of that path. See
2026-08-22 10:31:49.593881 | instance | https://docs.ansible.com/ansible-
2026-08-22 10:31:49.593891 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-08-22 10:31:49.605876 | instance | ok: [instance]
2026-08-22 10:31:49.605934 | instance |
2026-08-22 10:31:49.605953 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-08-22 10:31:49.606220 | instance | Saturday 22 August 2026  10:31:49 +0000 (0:00:00.962)       0:00:00.969 *******
2026-08-22 10:31:49.655659 | instance | ok: [instance] => {
2026-08-22 10:31:49.655955 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-08-22 10:31:49.656241 | instance | }
2026-08-22 10:31:49.656512 | instance |
2026-08-22 10:31:49.656750 | instance | PLAY [all] *********************************************************************
2026-08-22 10:31:49.657016 | instance |
2026-08-22 10:31:49.657343 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 10:31:49.657628 | instance | Saturday 22 August 2026  10:31:49 +0000 (0:00:00.050)       0:00:01.020 *******
2026-08-22 10:31:50.519185 | instance | ok: [instance]
2026-08-22 10:31:50.519445 | instance |
2026-08-22 10:31:50.519740 | instance | TASK [Fail if atmosphere_ceph_enabled is set] **********************************
2026-08-22 10:31:50.520028 | instance | Saturday 22 August 2026  10:31:50 +0000 (0:00:00.862)       0:00:01.883 *******
2026-08-22 10:31:50.562634 | instance | skipping: [instance]
2026-08-22 10:31:50.562884 | instance |
2026-08-22 10:31:50.563156 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-08-22 10:31:50.563426 | instance | Saturday 22 August 2026  10:31:50 +0000 (0:00:00.043)       0:00:01.927 *******
2026-08-22 10:31:50.744338 | instance | ok: [instance]
2026-08-22 10:31:50.744685 | instance |
2026-08-22 10:31:50.745032 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-08-22 10:31:50.745321 | instance |
2026-08-22 10:31:50.745635 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 10:31:50.746002 | instance | Saturday 22 August 2026  10:31:50 +0000 (0:00:00.181)       0:00:02.108 *******
2026-08-22 10:31:51.668857 | instance | ok: [instance]
2026-08-22 10:31:51.669044 | instance |
2026-08-22 10:31:51.669203 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-22 10:31:51.669358 | instance | Saturday 22 August 2026  10:31:51 +0000 (0:00:00.922)       0:00:03.030 *******
2026-08-22 10:31:51.977813 | instance | ok: [instance]
2026-08-22 10:31:51.977885 | instance |
2026-08-22 10:31:51.978041 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-22 10:31:51.978288 | instance | Saturday 22 August 2026  10:31:51 +0000 (0:00:00.311)       0:00:03.342 *******
2026-08-22 10:31:52.022395 | instance | skipping: [instance]
2026-08-22 10:31:52.022504 | instance |
2026-08-22 10:31:52.022699 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-08-22 10:31:52.022871 | instance | Saturday 22 August 2026  10:31:52 +0000 (0:00:00.044)       0:00:03.386 *******
2026-08-22 10:31:52.354004 | instance | changed: [instance]
2026-08-22 10:31:52.354071 | instance |
2026-08-22 10:31:52.354201 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 10:31:52.354325 | instance | Saturday 22 August 2026  10:31:52 +0000 (0:00:00.331)       0:00:03.718 *******
2026-08-22 10:31:52.420057 | instance | ok: [instance] => {
2026-08-22 10:31:52.420343 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-08-22 10:31:52.420606 | instance | }
2026-08-22 10:31:52.420890 | instance |
2026-08-22 10:31:52.421182 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 10:31:52.421454 | instance | Saturday 22 August 2026  10:31:52 +0000 (0:00:00.065)       0:00:03.784 *******
2026-08-22 10:31:52.987231 | instance | changed: [instance]
2026-08-22 10:31:52.987307 | instance |
2026-08-22 10:31:52.987438 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 10:31:52.987563 | instance | Saturday 22 August 2026  10:31:52 +0000 (0:00:00.566)       0:00:04.351 *******
2026-08-22 10:31:53.041226 | instance | skipping: [instance]
2026-08-22 10:31:53.041348 | instance |
2026-08-22 10:31:53.041481 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-22 10:31:53.041611 | instance | Saturday 22 August 2026  10:31:53 +0000 (0:00:00.054)       0:00:04.405 *******
2026-08-22 10:31:53.090738 | instance | skipping: [instance]
2026-08-22 10:31:53.090991 | instance |
2026-08-22 10:31:53.091273 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-22 10:31:53.103021 | instance | Saturday 22 August 2026  10:31:53 +0000 (0:00:00.049)       0:00:04.454 *******
2026-08-22 10:31:53.314235 | instance | ok: [instance]
2026-08-22 10:31:53.314510 | instance |
2026-08-22 10:31:53.314798 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-22 10:31:53.315074 | instance | Saturday 22 August 2026  10:31:53 +0000 (0:00:00.223)       0:00:04.678 *******
2026-08-22 10:31:54.474346 | instance | ok: [instance]
2026-08-22 10:31:54.474416 | instance |
2026-08-22 10:31:54.474637 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 10:31:54.474689 | instance | Saturday 22 August 2026  10:31:54 +0000 (0:00:01.160)       0:00:05.838 *******
2026-08-22 10:31:54.541501 | instance | ok: [instance] => {
2026-08-22 10:31:54.541994 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-08-22 10:31:54.542067 | instance | }
2026-08-22 10:31:54.542079 | instance |
2026-08-22 10:31:54.542097 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 10:31:54.542106 | instance | Saturday 22 August 2026  10:31:54 +0000 (0:00:00.067)       0:00:05.905 *******
2026-08-22 10:31:55.152448 | instance | changed: [instance]
2026-08-22 10:31:55.152479 | instance |
2026-08-22 10:31:55.152485 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 10:31:55.152490 | instance | Saturday 22 August 2026  10:31:55 +0000 (0:00:00.610)       0:00:06.516 *******
2026-08-22 10:31:57.971763 | instance | changed: [instance]
2026-08-22 10:31:57.971818 | instance |
2026-08-22 10:31:57.972064 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-08-22 10:31:57.972079 | instance | Saturday 22 August 2026  10:31:57 +0000 (0:00:02.819)       0:00:09.336 *******
2026-08-22 10:31:58.001948 | instance | skipping: [instance]
2026-08-22 10:31:58.002547 | instance |
2026-08-22 10:31:58.002588 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-08-22 10:31:58.002594 | instance | Saturday 22 August 2026  10:31:57 +0000 (0:00:00.029)       0:00:09.366 *******
2026-08-22 10:31:58.035048 | instance | skipping: [instance]
2026-08-22 10:31:58.035210 | instance |
2026-08-22 10:31:58.035573 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-08-22 10:31:58.035597 | instance | Saturday 22 August 2026  10:31:58 +0000 (0:00:00.033)       0:00:09.399 *******
2026-08-22 10:31:58.063939 | instance | skipping: [instance]
2026-08-22 10:31:58.064046 | instance |
2026-08-22 10:31:58.064303 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-08-22 10:31:58.064321 | instance | Saturday 22 August 2026  10:31:58 +0000 (0:00:00.029)       0:00:09.428 *******
2026-08-22 10:32:02.631647 | instance | changed: [instance]
2026-08-22 10:32:02.631863 | instance |
2026-08-22 10:32:02.631876 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-08-22 10:32:02.632011 | instance | Saturday 22 August 2026  10:32:02 +0000 (0:00:04.567)       0:00:13.996 *******
2026-08-22 10:32:03.192326 | instance | changed: [instance]
2026-08-22 10:32:03.192389 | instance |
2026-08-22 10:32:03.192705 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-08-22 10:32:03.192739 | instance | Saturday 22 August 2026  10:32:03 +0000 (0:00:00.560)       0:00:14.556 *******
2026-08-22 10:32:04.098616 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-08-22 10:32:04.098689 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-08-22 10:32:04.099451 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-08-22 10:32:04.099498 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-08-22 10:32:04.099506 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-08-22 10:32:04.099511 | instance |
2026-08-22 10:32:04.099515 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-08-22 10:32:04.099520 | instance | Saturday 22 August 2026  10:32:04 +0000 (0:00:00.905)       0:00:15.462 *******
2026-08-22 10:32:04.698739 | instance | changed: [instance]
2026-08-22 10:32:04.698776 | instance |
2026-08-22 10:32:04.698782 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-08-22 10:32:04.698786 | instance | Saturday 22 August 2026  10:32:04 +0000 (0:00:00.583)       0:00:16.046 *******
2026-08-22 10:32:04.698791 | instance |
2026-08-22 10:32:04.698795 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-22 10:32:04.698799 | instance | Saturday 22 August 2026  10:32:04 +0000 (0:00:00.016)       0:00:16.062 *******
2026-08-22 10:32:05.643386 | instance | ok: [instance]
2026-08-22 10:32:05.643474 | instance |
2026-08-22 10:32:05.643749 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-08-22 10:32:05.643783 | instance | Saturday 22 August 2026  10:32:05 +0000 (0:00:00.945)       0:00:17.007 *******
2026-08-22 10:32:06.077053 | instance | changed: [instance]
2026-08-22 10:32:06.077097 | instance |
2026-08-22 10:32:06.077102 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-08-22 10:32:06.077107 | instance | Saturday 22 August 2026  10:32:06 +0000 (0:00:00.433)       0:00:17.441 *******
2026-08-22 10:32:06.620157 | instance | changed: [instance]
2026-08-22 10:32:06.620220 | instance |
2026-08-22 10:32:06.620535 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-22 10:32:06.620803 | instance | Saturday 22 August 2026  10:32:06 +0000 (0:00:00.542)       0:00:17.984 *******
2026-08-22 10:32:06.834432 | instance | ok: [instance]
2026-08-22 10:32:06.834479 | instance |
2026-08-22 10:32:06.834485 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 10:32:06.834490 | instance | Saturday 22 August 2026  10:32:06 +0000 (0:00:00.214)       0:00:18.198 *******
2026-08-22 10:32:06.897090 | instance | ok: [instance] => {
2026-08-22 10:32:06.897817 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-08-22 10:32:06.897872 | instance | }
2026-08-22 10:32:06.897880 | instance |
2026-08-22 10:32:06.897886 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 10:32:06.897894 | instance | Saturday 22 August 2026  10:32:06 +0000 (0:00:00.062)       0:00:18.261 *******
2026-08-22 10:32:07.629675 | instance | changed: [instance]
2026-08-22 10:32:07.629784 | instance |
2026-08-22 10:32:07.630022 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 10:32:07.630054 | instance | Saturday 22 August 2026  10:32:07 +0000 (0:00:00.732)       0:00:18.994 *******
2026-08-22 10:32:11.953537 | instance | changed: [instance]
2026-08-22 10:32:11.953584 | instance |
2026-08-22 10:32:11.953592 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-08-22 10:32:11.953599 | instance | Saturday 22 August 2026  10:32:11 +0000 (0:00:04.323)       0:00:23.317 *******
2026-08-22 10:32:13.079911 | instance | ok: [instance]
2026-08-22 10:32:13.079970 | instance |
2026-08-22 10:32:13.080279 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-08-22 10:32:13.080317 | instance | Saturday 22 August 2026  10:32:13 +0000 (0:00:01.127)       0:00:24.444 *******
2026-08-22 10:32:13.424152 | instance | changed: [instance]
2026-08-22 10:32:13.424649 | instance |
2026-08-22 10:32:13.424670 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-08-22 10:32:13.424677 | instance | Saturday 22 August 2026  10:32:13 +0000 (0:00:00.344)       0:00:24.788 *******
2026-08-22 10:32:13.883338 | instance | changed: [instance]
2026-08-22 10:32:13.883890 | instance |
2026-08-22 10:32:13.883932 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-08-22 10:32:13.883940 | instance | Saturday 22 August 2026  10:32:13 +0000 (0:00:00.458)       0:00:25.247 *******
2026-08-22 10:32:14.461184 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-08-22 10:32:14.461299 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-08-22 10:32:14.461861 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-08-22 10:32:14.461901 | instance |
2026-08-22 10:32:14.461906 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-08-22 10:32:14.461910 | instance | Saturday 22 August 2026  10:32:14 +0000 (0:00:00.578)       0:00:25.825 *******
2026-08-22 10:32:14.899082 | instance | changed: [instance]
2026-08-22 10:32:14.899134 | instance |
2026-08-22 10:32:14.899376 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-08-22 10:32:14.899392 | instance | Saturday 22 August 2026  10:32:14 +0000 (0:00:00.437)       0:00:26.263 *******
2026-08-22 10:32:15.352512 | instance | changed: [instance]
2026-08-22 10:32:15.352586 | instance |
2026-08-22 10:32:15.352591 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-08-22 10:32:15.352615 | instance | Saturday 22 August 2026  10:32:15 +0000 (0:00:00.436)       0:00:26.699 *******
2026-08-22 10:32:15.352619 | instance |
2026-08-22 10:32:15.352641 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-22 10:32:15.352656 | instance | Saturday 22 August 2026  10:32:15 +0000 (0:00:00.016)       0:00:26.716 *******
2026-08-22 10:32:16.026548 | instance | ok: [instance]
2026-08-22 10:32:16.026660 | instance |
2026-08-22 10:32:16.026726 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-08-22 10:32:16.026886 | instance | Saturday 22 August 2026  10:32:16 +0000 (0:00:00.674)       0:00:27.391 *******
2026-08-22 10:32:17.118565 | instance | changed: [instance]
2026-08-22 10:32:17.118646 | instance |
2026-08-22 10:32:17.118901 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-08-22 10:32:17.118943 | instance | Saturday 22 August 2026  10:32:17 +0000 (0:00:01.091)       0:00:28.483 *******
2026-08-22 10:32:17.684589 | instance | changed: [instance]
2026-08-22 10:32:17.684661 | instance |
2026-08-22 10:32:17.684976 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-08-22 10:32:17.685029 | instance | Saturday 22 August 2026  10:32:17 +0000 (0:00:00.564)       0:00:29.047 *******
2026-08-22 10:32:17.742324 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-08-22 10:32:17.742371 | instance |
2026-08-22 10:32:17.742377 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-08-22 10:32:17.742383 | instance | Saturday 22 August 2026  10:32:17 +0000 (0:00:00.058)       0:00:29.105 *******
2026-08-22 10:32:22.848782 | instance | changed: [instance]
2026-08-22 10:32:22.848909 | instance |
2026-08-22 10:32:22.849325 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-08-22 10:32:22.849498 | instance | Saturday 22 August 2026  10:32:22 +0000 (0:00:05.107)       0:00:34.213 *******
2026-08-22 10:32:23.486656 | instance | ok: [instance] => (item=chronyd)
2026-08-22 10:32:23.486749 | instance | ok: [instance] => (item=sshd)
2026-08-22 10:32:23.486831 | instance |
2026-08-22 10:32:23.487092 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-08-22 10:32:23.487132 | instance | Saturday 22 August 2026  10:32:23 +0000 (0:00:00.637)       0:00:34.850 *******
2026-08-22 10:32:25.131271 | instance | changed: [instance]
2026-08-22 10:32:25.131373 | instance |
2026-08-22 10:32:25.131713 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-08-22 10:32:25.131734 | instance | Saturday 22 August 2026  10:32:25 +0000 (0:00:01.644)       0:00:36.495 *******
2026-08-22 10:32:25.329905 | instance | ok: [instance]
2026-08-22 10:32:25.330001 | instance |
2026-08-22 10:32:25.330244 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-08-22 10:32:25.330259 | instance | Saturday 22 August 2026  10:32:25 +0000 (0:00:00.198)       0:00:36.694 *******
2026-08-22 10:32:25.797199 | instance | changed: [instance]
2026-08-22 10:32:25.797715 | instance |
2026-08-22 10:32:25.797795 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-08-22 10:32:25.797828 | instance | Saturday 22 August 2026  10:32:25 +0000 (0:00:00.467)       0:00:37.161 *******
2026-08-22 10:32:26.178428 | instance | changed: [instance]
2026-08-22 10:32:26.178497 | instance |
2026-08-22 10:32:26.178503 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-08-22 10:32:26.178508 | instance | Saturday 22 August 2026  10:32:26 +0000 (0:00:00.380)       0:00:37.541 *******
2026-08-22 10:32:26.378831 | instance | ok: [instance]
2026-08-22 10:32:26.378950 | instance |
2026-08-22 10:32:26.378994 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-08-22 10:32:26.379179 | instance | Saturday 22 August 2026  10:32:26 +0000 (0:00:00.201)       0:00:37.743 *******
2026-08-22 10:32:26.603474 | instance | ok: [instance] => (item=instance)
2026-08-22 10:32:26.603554 | instance |
2026-08-22 10:32:26.603799 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-08-22 10:32:26.603959 | instance | Saturday 22 August 2026  10:32:26 +0000 (0:00:00.224)       0:00:37.967 *******
2026-08-22 10:32:26.660537 | instance | ok: [instance]
2026-08-22 10:32:26.660593 | instance |
2026-08-22 10:32:26.660860 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-08-22 10:32:26.660882 | instance | Saturday 22 August 2026  10:32:26 +0000 (0:00:00.056)       0:00:38.024 *******
2026-08-22 10:32:26.733176 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-08-22 10:32:26.733581 | instance |
2026-08-22 10:32:26.733640 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-08-22 10:32:26.733647 | instance | Saturday 22 August 2026  10:32:26 +0000 (0:00:00.072)       0:00:38.097 *******
2026-08-22 10:32:26.998346 | instance | changed: [instance]
2026-08-22 10:32:26.998478 | instance |
2026-08-22 10:32:26.998490 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-08-22 10:32:26.998670 | instance | Saturday 22 August 2026  10:32:26 +0000 (0:00:00.265)       0:00:38.362 *******
2026-08-22 10:32:27.661512 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-08-22 10:32:27.661597 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-08-22 10:32:27.661857 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-08-22 10:32:27.661921 | instance |
2026-08-22 10:32:27.662218 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-08-22 10:32:27.662295 | instance | Saturday 22 August 2026  10:32:27 +0000 (0:00:00.662)       0:00:39.025 *******
2026-08-22 10:34:01.267641 | instance | ok: [instance]
2026-08-22 10:34:01.267944 | instance |
2026-08-22 10:34:01.267958 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-08-22 10:34:01.268091 | instance | Saturday 22 August 2026  10:34:01 +0000 (0:01:33.606)       0:02:12.631 *******
2026-08-22 10:34:01.495258 | instance | changed: [instance]
2026-08-22 10:34:01.495334 | instance |
2026-08-22 10:34:01.495425 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-08-22 10:34:01.495547 | instance | Saturday 22 August 2026  10:34:01 +0000 (0:00:00.227)       0:02:12.859 *******
2026-08-22 10:34:01.537027 | instance | ok: [instance]
2026-08-22 10:34:01.537094 | instance |
2026-08-22 10:34:01.537219 | instance | TASK [Install Ceph host] *******************************************************
2026-08-22 10:34:01.537340 | instance | Saturday 22 August 2026  10:34:01 +0000 (0:00:00.042)       0:02:12.901 *******
2026-08-22 10:34:01.616477 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-08-22 10:34:01.616550 | instance |
2026-08-22 10:34:01.616650 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-08-22 10:34:01.616807 | instance | Saturday 22 August 2026  10:34:01 +0000 (0:00:00.079)       0:02:12.981 *******
2026-08-22 10:34:03.171568 | instance | ok: [instance]
2026-08-22 10:34:03.171639 | instance |
2026-08-22 10:34:03.171747 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-08-22 10:34:03.171859 | instance | Saturday 22 August 2026  10:34:03 +0000 (0:00:01.554)       0:02:14.535 *******
2026-08-22 10:34:03.228446 | instance | ok: [instance] => (item=instance)
2026-08-22 10:34:03.228508 | instance |
2026-08-22 10:34:03.228606 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-08-22 10:34:03.228720 | instance | Saturday 22 August 2026  10:34:03 +0000 (0:00:00.057)       0:02:14.593 *******
2026-08-22 10:34:03.559445 | instance | ok: [instance]
2026-08-22 10:34:03.559482 | instance |
2026-08-22 10:34:03.559488 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-08-22 10:34:03.559493 | instance | Saturday 22 August 2026  10:34:03 +0000 (0:00:00.329)       0:02:14.922 *******
2026-08-22 10:34:05.823700 | instance | ok: [instance]
2026-08-22 10:34:05.823766 | instance |
2026-08-22 10:34:05.824050 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-08-22 10:34:05.824087 | instance | Saturday 22 August 2026  10:34:05 +0000 (0:00:02.265)       0:02:17.188 *******
2026-08-22 10:35:47.865284 | 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:35:47.865827 | 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_nj9253cc/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_nj9253cc/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_nj9253cc/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_nj9253cc/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:35:47.701+0000 7ff0cf752640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDreolqPiE9MiAALQPOIyiTWXuz7gKrzYkSujM+cCfdRjFeQFklPfF96A4=: Malformed input [buffer:3]\n2026-08-22T10:35:47.701+0000 7ff0cf752640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-22T10:35:47.701+0000 7ff0cf752640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDreolqPiE9MiAALQPOIyiTWXuz7gKrzYkSujM+cCfdRjFeQFklPfF96A4=: Malformed input [buffer:3]\n2026-08-22T10:35:47.701+0000 7ff0cf752640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-22T10:35:47.701+0000 7ff0cf752640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDreolqPiE9MiAALQPOIyiTWXuz7gKrzYkSujM+cCfdRjFeQFklPfF96A4=: Malformed input [buffer:3]\n2026-08-22T10:35:47.701+0000 7ff0cf752640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-22T10:35:47.701+0000 7ff0cf752640 -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:35:47.867959 | instance |
2026-08-22 10:35:47.868100 | instance | PLAY RECAP *********************************************************************
2026-08-22 10:35:47.869868 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=7    rescued=0    ignored=0
2026-08-22 10:35:47.869937 | instance |
2026-08-22 10:35:47.869948 | instance | Saturday 22 August 2026  10:35:47 +0000 (0:01:42.044)       0:03:59.232 *******
2026-08-22 10:35:47.869954 | instance | ===============================================================================
2026-08-22 10:35:47.869961 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ---------------- 102.04s
2026-08-22 10:35:47.869966 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 93.61s
2026-08-22 10:35:47.869972 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.11s
2026-08-22 10:35:47.869978 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 4.57s
2026-08-22 10:35:47.869984 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.32s
2026-08-22 10:35:47.869994 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.82s
2026-08-22 10:35:47.870230 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.27s
2026-08-22 10:35:47.870347 | instance | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 1.64s
2026-08-22 10:35:47.872293 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.62s
2026-08-22 10:35:47.872351 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.55s
2026-08-22 10:35:47.872359 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.16s
2026-08-22 10:35:47.872365 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.13s
2026-08-22 10:35:47.872371 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 1.09s
2026-08-22 10:35:47.872377 | instance | Gathering Facts --------------------------------------------------------- 0.96s
2026-08-22 10:35:47.872383 | instance | Gathering Facts --------------------------------------------------------- 0.92s
2026-08-22 10:35:47.872389 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.91s
2026-08-22 10:35:47.872394 | instance | Gathering Facts --------------------------------------------------------- 0.86s
2026-08-22 10:35:47.872400 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.73s
2026-08-22 10:35:47.872406 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.66s
2026-08-22 10:35:47.872412 | instance | vexxhost.ceph.cephadm : Ensure services are started --------------------- 0.64s
2026-08-22 10:35:47.970912 | 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:35:47.971247 | instance | ERROR    [csi > converge] Executed: Failed
2026-08-22 10:35:47.971466 | 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:35:48.082373 | instance | ERROR
2026-08-22 10:35:48.082589 | instance | {
2026-08-22 10:35:48.082631 | instance |   "delta": "0:04:51.587204",
2026-08-22 10:35:48.082665 | instance |   "end": "2026-08-22 10:35:48.029702",
2026-08-22 10:35:48.082691 | instance |   "msg": "non-zero return code",
2026-08-22 10:35:48.082716 | instance |   "rc": 2,
2026-08-22 10:35:48.082743 | instance |   "start": "2026-08-22 10:30:56.442498"
2026-08-22 10:35:48.082988 | instance | }
failure
2026-08-22 10:35:48.089076 | 
2026-08-22 10:35:48.089134 | PLAY RECAP
2026-08-22 10:35:48.089179 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-08-22 10:35:48.089200 | 
2026-08-22 10:35:48.234096 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-22 10:35:48.236626 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-08-22 10:35:48.948606 | 
2026-08-22 10:35:48.948751 | PLAY [all]
2026-08-22 10:35:48.962834 | 
2026-08-22 10:35:48.962918 | TASK [gather-host-logs : creating directory for system status]
2026-08-22 10:35:49.346822 | instance | changed
2026-08-22 10:35:49.353744 | 
2026-08-22 10:35:49.353837 | TASK [gather-host-logs : Get logs for each host]
2026-08-22 10:35:49.699460 | instance | + systemd-cgls --full --all --no-pager
2026-08-22 10:35:49.720429 | instance | + ip addr
2026-08-22 10:35:49.725054 | instance | + ip route
2026-08-22 10:35:49.725742 | instance | + lsblk
2026-08-22 10:35:49.731266 | instance | + mount
2026-08-22 10:35:49.734523 | instance | + docker images
2026-08-22 10:35:49.758551 | instance | + brctl show
2026-08-22 10:35:49.759069 | instance | /bin/bash: line 8: brctl: command not found
2026-08-22 10:35:49.759352 | instance | + ps aux --sort=-%mem
2026-08-22 10:35:49.780718 | instance | + dpkg -l
2026-08-22 10:35:49.794231 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-08-22 10:35:49.794801 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-08-22 10:35:49.814820 | instance | + '[' '!' -z '' ']'
2026-08-22 10:35:49.893901 | instance | ok: Runtime: 0:00:00.121713
2026-08-22 10:35:49.904104 | 
2026-08-22 10:35:49.904232 | TASK [gather-host-logs : Downloads logs to executor]
2026-08-22 10:35:50.568262 | instance | changed:
2026-08-22 10:35:50.568823 | instance | created directory /var/lib/zuul/builds/50e1acad7a384cb193eeefcafd923752/work/logs/instance
2026-08-22 10:35:50.568869 | instance | cd+++++++++ system/
2026-08-22 10:35:50.568903 | instance | >f+++++++++ system/brctl-show.txt
2026-08-22 10:35:50.568935 | instance | >f+++++++++ system/docker-images.txt
2026-08-22 10:35:50.569029 | instance | >f+++++++++ system/ip-addr.txt
2026-08-22 10:35:50.569073 | instance | >f+++++++++ system/ip-route.txt
2026-08-22 10:35:50.569105 | instance | >f+++++++++ system/lsblk.txt
2026-08-22 10:35:50.569135 | instance | >f+++++++++ system/mount.txt
2026-08-22 10:35:50.569165 | instance | >f+++++++++ system/packages.txt
2026-08-22 10:35:50.569193 | instance | >f+++++++++ system/ps.txt
2026-08-22 10:35:50.569224 | instance | >f+++++++++ system/systemd-cgls.txt
2026-08-22 10:35:50.580880 | 
2026-08-22 10:35:50.580975 | LOOP [helm-release-status : creating directory for helm release status]
2026-08-22 10:35:50.776830 | instance | changed: "values"
2026-08-22 10:35:50.944527 | instance | changed: "releases"
2026-08-22 10:35:50.963280 | 
2026-08-22 10:35:50.963504 | TASK [helm-release-status : Gather get release status for helm charts]
2026-08-22 10:35:51.191665 | instance | /bin/bash: line 3: kubectl: command not found
2026-08-22 10:35:51.503396 | instance | ok: Runtime: 0:00:00.024838
2026-08-22 10:35:51.510880 | 
2026-08-22 10:35:51.510980 | TASK [helm-release-status : Downloads logs to executor]
2026-08-22 10:35:51.986910 | instance | changed:
2026-08-22 10:35:51.987341 | instance | cd+++++++++ helm/
2026-08-22 10:35:51.987373 | instance | cd+++++++++ helm/releases/
2026-08-22 10:35:51.987395 | instance | cd+++++++++ helm/values/
2026-08-22 10:35:51.996721 | 
2026-08-22 10:35:51.996790 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-08-22 10:35:52.190115 | instance | changed
2026-08-22 10:35:52.201624 | 
2026-08-22 10:35:52.201798 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-08-22 10:35:52.449479 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 10:35:52.450035 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 10:35:52.456337 | instance | environment: line 1: kubectl: command not found
2026-08-22 10:35:52.457697 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 10:35:52.458798 | instance | environment: line 1: kubectl: command not found
2026-08-22 10:35:52.460259 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 10:35:52.461152 | instance | environment: line 1: kubectl: command not found
2026-08-22 10:35:52.462865 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 10:35:52.464016 | instance | environment: line 1: kubectl: command not found
2026-08-22 10:35:52.465242 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 10:35:52.465546 | instance | environment: line 1: kubectl: command not found
2026-08-22 10:35:52.466499 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 10:35:52.748261 | instance | ok: Runtime: 0:00:00.025001
2026-08-22 10:35:52.754491 | 
2026-08-22 10:35:52.754557 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-08-22 10:35:52.950140 | instance | changed
2026-08-22 10:35:52.957422 | 
2026-08-22 10:35:52.957505 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-08-22 10:35:53.164851 | instance | environment: line 5: kubectl: command not found
2026-08-22 10:35:53.165672 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 10:35:53.165958 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 10:35:53.166560 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 10:35:53.491526 | instance | ok: Runtime: 0:00:00.008973
2026-08-22 10:35:53.496819 | 
2026-08-22 10:35:53.496881 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-08-22 10:35:53.985108 | instance | changed:
2026-08-22 10:35:53.985299 | instance | cd+++++++++ objects/
2026-08-22 10:35:53.985338 | instance | cd+++++++++ objects/cluster/
2026-08-22 10:35:53.985370 | instance | cd+++++++++ objects/namespaced/
2026-08-22 10:35:53.997177 | 
2026-08-22 10:35:53.997253 | TASK [gather-pod-logs : creating directory for pod logs]
2026-08-22 10:35:54.197979 | instance | changed
2026-08-22 10:35:54.205293 | 
2026-08-22 10:35:54.205369 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-08-22 10:35:54.400704 | instance | changed
2026-08-22 10:35:54.408557 | 
2026-08-22 10:35:54.408663 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-08-22 10:35:54.618656 | instance | environment: line 3: kubectl: command not found
2026-08-22 10:35:54.947627 | instance | ok: Runtime: 0:00:00.008431
2026-08-22 10:35:54.958249 | 
2026-08-22 10:35:54.958341 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-08-22 10:35:55.459279 | instance | changed:
2026-08-22 10:35:55.459582 | instance | cd+++++++++ pod-logs/
2026-08-22 10:35:55.459650 | instance | cd+++++++++ pod-logs/failed-pods/
2026-08-22 10:35:55.469876 | 
2026-08-22 10:35:55.469945 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-08-22 10:35:55.669640 | instance | changed
2026-08-22 10:35:55.676227 | 
2026-08-22 10:35:55.676315 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-08-22 10:35:55.880425 | instance | /bin/bash: line 2: kubectl: command not found
2026-08-22 10:35:56.212899 | instance | ok: Runtime: 0:00:00.035497
2026-08-22 10:35:56.219520 | 
2026-08-22 10:35:56.219589 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-08-22 10:35:56.431202 | instance | /bin/bash: line 2: kubectl: command not found
2026-08-22 10:35:56.460186 | instance | ceph-mgr endpoints:
2026-08-22 10:35:56.756858 | instance | ok: Runtime: 0:00:00.034781
2026-08-22 10:35:56.762773 | 
2026-08-22 10:35:56.762839 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-08-22 10:35:56.971393 | instance | /bin/bash: line 4: kubectl: command not found
2026-08-22 10:35:57.295789 | instance | ok: Runtime: 0:00:00.034925
2026-08-22 10:35:57.302519 | 
2026-08-22 10:35:57.302624 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-08-22 10:35:57.802772 | instance | changed: cd+++++++++ prometheus/
2026-08-22 10:35:57.812138 | 
2026-08-22 10:35:57.812206 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-08-22 10:35:58.019251 | instance | changed
2026-08-22 10:35:58.027027 | 
2026-08-22 10:35:58.027119 | TASK [gather-selenium-data : Get selenium data]
2026-08-22 10:35:58.273494 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-08-22 10:35:58.275025 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-08-22 10:35:58.573106 | instance | ERROR
2026-08-22 10:35:58.573398 | instance | {
2026-08-22 10:35:58.573443 | instance |   "delta": "0:00:00.006301",
2026-08-22 10:35:58.573474 | instance |   "end": "2026-08-22 10:35:58.275319",
2026-08-22 10:35:58.573505 | instance |   "msg": "non-zero return code",
2026-08-22 10:35:58.573532 | instance |   "rc": 1,
2026-08-22 10:35:58.573560 | instance |   "start": "2026-08-22 10:35:58.269018"
2026-08-22 10:35:58.573586 | instance | }
2026-08-22 10:35:58.573620 | instance | ERROR: Ignoring Errors
2026-08-22 10:35:58.580725 | 
2026-08-22 10:35:58.580794 | TASK [gather-selenium-data : Downloads logs to executor]
2026-08-22 10:35:59.097400 | instance | changed: cd+++++++++ selenium/
2026-08-22 10:35:59.104280 | 
2026-08-22 10:35:59.104358 | PLAY RECAP
2026-08-22 10:35:59.104424 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-08-22 10:35:59.104447 | 
2026-08-22 10:35:59.283373 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-08-22 10:35:59.285734 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-22 10:35:59.942898 | 
2026-08-22 10:35:59.943030 | PLAY [all]
2026-08-22 10:35:59.955938 | 
2026-08-22 10:35:59.956033 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-22 10:36:00.001614 | instance | skipping: Conditional result was False
2026-08-22 10:36:00.012101 | 
2026-08-22 10:36:00.012220 | TASK [fetch-output : Set log path for single node]
2026-08-22 10:36:00.055113 | instance | ok
2026-08-22 10:36:00.060798 | 
2026-08-22 10:36:00.060869 | LOOP [fetch-output : Ensure local output dirs]
2026-08-22 10:36:00.448749 | instance -> localhost | ok: "/var/lib/zuul/builds/50e1acad7a384cb193eeefcafd923752/work/logs"
2026-08-22 10:36:00.654561 | instance -> localhost | changed: "/var/lib/zuul/builds/50e1acad7a384cb193eeefcafd923752/work/artifacts"
2026-08-22 10:36:00.860489 | instance -> localhost | changed: "/var/lib/zuul/builds/50e1acad7a384cb193eeefcafd923752/work/docs"
2026-08-22 10:36:00.881011 | 
2026-08-22 10:36:00.881195 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-22 10:36:01.546023 | instance | changed: .d..t...... ./
2026-08-22 10:36:01.546264 | instance | changed: All items complete
2026-08-22 10:36:01.546310 | 
2026-08-22 10:36:02.013331 | instance | changed: .d..t...... ./
2026-08-22 10:36:02.487804 | instance | changed: .d..t...... ./
2026-08-22 10:36:02.513988 | 
2026-08-22 10:36:02.514117 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-22 10:36:02.949943 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.006710
2026-08-22 10:36:03.172041 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007238
2026-08-22 10:36:03.187404 | 
2026-08-22 10:36:03.187883 | PLAY [all]
2026-08-22 10:36:03.196011 | 
2026-08-22 10:36:03.196081 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-22 10:36:03.627190 | instance | changed
2026-08-22 10:36:03.634442 | 
2026-08-22 10:36:03.634507 | PLAY RECAP
2026-08-22 10:36:03.634565 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-22 10:36:03.634596 | 
2026-08-22 10:36:03.760880 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-22 10:36:03.765638 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-22 10:36:04.384688 | 
2026-08-22 10:36:04.384853 | PLAY [localhost]
2026-08-22 10:36:04.395810 | 
2026-08-22 10:36:04.395899 | TASK [Generate Zuul manifest]
2026-08-22 10:36:04.417472 | localhost | ok
2026-08-22 10:36:04.437457 | 
2026-08-22 10:36:04.437562 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-22 10:36:04.796342 | localhost | changed
2026-08-22 10:36:04.808240 | 
2026-08-22 10:36:04.808320 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-22 10:36:04.839679 | localhost | ok
2026-08-22 10:36:04.848665 | 
2026-08-22 10:36:04.848750 | TASK [Upload logs]
2026-08-22 10:36:04.871765 | localhost | ok
2026-08-22 10:36:04.930942 | 
2026-08-22 10:36:04.931090 | TASK [Set zuul-log-path fact]
2026-08-22 10:36:04.953775 | localhost | ok
2026-08-22 10:36:04.966665 | 
2026-08-22 10:36:04.966738 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-22 10:36:04.997381 | localhost | ok
2026-08-22 10:36:05.007046 | 
2026-08-22 10:36:05.007155 | TASK [upload-logs : Create log directories]
2026-08-22 10:36:05.444167 | localhost | changed
2026-08-22 10:36:05.450653 | 
2026-08-22 10:36:05.450777 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-22 10:36:05.908813 | localhost -> localhost | ok: Runtime: 0:00:00.007448
2026-08-22 10:36:05.918790 | 
2026-08-22 10:36:05.918919 | TASK [upload-logs : Upload logs to log server]
2026-08-22 10:36:06.408081 | localhost | Output suppressed because no_log was given
2026-08-22 10:36:06.443643 | 
2026-08-22 10:36:06.443956 | LOOP [upload-logs : Compress console log and json output]
2026-08-22 10:36:06.503933 | localhost | skipping: Conditional result was False
2026-08-22 10:36:06.514485 | localhost | skipping: Conditional result was False
2026-08-22 10:36:06.529943 | 
2026-08-22 10:36:06.530220 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-22 10:36:06.578287 | localhost | skipping: Conditional result was False
2026-08-22 10:36:06.578734 | 
2026-08-22 10:36:06.584209 | localhost | skipping: Conditional result was False
2026-08-22 10:36:06.595049 | 
2026-08-22 10:36:06.595197 | LOOP [upload-logs : Upload console log and json output]