2026-09-17 23:56:45.357264 | Job console starting
2026-09-17 23:56:45.365547 | Updating git repos
2026-09-17 23:56:47.776112 | Cloning repos into workspace
2026-09-17 23:57:03.376393 | Restoring repo states
2026-09-17 23:57:11.605893 | Merging changes
2026-09-17 23:57:12.565213 | Checking out repos
2026-09-17 23:57:14.535829 | Preparing playbooks
2026-09-17 23:57:16.670702 | Running Ansible setup
2026-09-17 23:57:20.234846 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-17 23:57:20.772195 | 
2026-09-17 23:57:20.772307 | PLAY [localhost]
2026-09-17 23:57:20.778831 | 
2026-09-17 23:57:20.778892 | TASK [Gathering Facts]
2026-09-17 23:57:21.646278 | localhost | ok
2026-09-17 23:57:21.655111 | 
2026-09-17 23:57:21.655210 | TASK [Setup log path fact]
2026-09-17 23:57:21.673134 | localhost | ok
2026-09-17 23:57:21.686362 | 
2026-09-17 23:57:21.686494 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-17 23:57:21.725055 | localhost | ok
2026-09-17 23:57:21.732348 | 
2026-09-17 23:57:21.732624 | TASK [emit-job-header : Print job information]
2026-09-17 23:57:21.781841 | # Job Information
2026-09-17 23:57:21.782012 | Ansible Version: 2.16.18
2026-09-17 23:57:21.782053 | Job: atmosphere-molecule-csi-rbd
2026-09-17 23:57:21.782095 | Pipeline: check
2026-09-17 23:57:21.782125 | Executor: 2d72f0692154
2026-09-17 23:57:21.782150 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3501
2026-09-17 23:57:21.782177 | Event ID: e6332fa0-b2f2-11f1-9421-17bfafafd2cc
2026-09-17 23:57:21.785879 | 
2026-09-17 23:57:21.785964 | LOOP [emit-job-header : Print node information]
2026-09-17 23:57:21.891357 | localhost | ok:
2026-09-17 23:57:21.891703 | localhost | # Node Information
2026-09-17 23:57:21.891747 | localhost | Inventory Hostname: instance
2026-09-17 23:57:21.891775 | localhost | Hostname: np0000193133
2026-09-17 23:57:21.891801 | localhost | Username: zuul
2026-09-17 23:57:21.891832 | localhost | Distro: Ubuntu 22.04
2026-09-17 23:57:21.891863 | localhost | Provider: yul1
2026-09-17 23:57:21.891891 | localhost | Region: ca-ymq-1
2026-09-17 23:57:21.891921 | localhost | Label: ubuntu-jammy
2026-09-17 23:57:21.891947 | localhost | Product Name: OpenStack Nova
2026-09-17 23:57:21.891972 | localhost | Interface IP: 162.253.55.119
2026-09-17 23:57:21.899596 | 
2026-09-17 23:57:21.899713 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-17 23:57:22.300224 | localhost -> localhost | changed
2026-09-17 23:57:22.308796 | 
2026-09-17 23:57:22.308939 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-17 23:57:23.159403 | localhost -> localhost | changed
2026-09-17 23:57:23.167032 | 
2026-09-17 23:57:23.167132 | PLAY [all]
2026-09-17 23:57:23.177241 | 
2026-09-17 23:57:23.177341 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-17 23:57:23.399495 | instance -> localhost | ok
2026-09-17 23:57:23.406886 | 
2026-09-17 23:57:23.407012 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-17 23:57:23.438580 | instance | ok
2026-09-17 23:57:23.458480 | instance | included: /var/lib/zuul/builds/b3b038136ff74094acc21529f2121a1c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-17 23:57:23.465479 | 
2026-09-17 23:57:23.465561 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-17 23:57:24.503845 | instance -> localhost | Generating public/private rsa key pair.
2026-09-17 23:57:24.504073 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/b3b038136ff74094acc21529f2121a1c/work/b3b038136ff74094acc21529f2121a1c_id_rsa
2026-09-17 23:57:24.504148 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/b3b038136ff74094acc21529f2121a1c/work/b3b038136ff74094acc21529f2121a1c_id_rsa.pub
2026-09-17 23:57:24.504192 | instance -> localhost | The key fingerprint is:
2026-09-17 23:57:24.504233 | instance -> localhost | SHA256:up7xbxrjnnFhFV60Vtf5m/d89LNPlfCz+2AdAdWsweI zuul-build-sshkey
2026-09-17 23:57:24.504286 | instance -> localhost | The key's randomart image is:
2026-09-17 23:57:24.504326 | instance -> localhost | +---[RSA 3072]----+
2026-09-17 23:57:24.504369 | instance -> localhost | |            .++o*|
2026-09-17 23:57:24.504409 | instance -> localhost | |           ..o++=|
2026-09-17 23:57:24.504446 | instance -> localhost | |           .ooo+.|
2026-09-17 23:57:24.504484 | instance -> localhost | |           .E.+ +|
2026-09-17 23:57:24.504556 | instance -> localhost | |        S o    =+|
2026-09-17 23:57:24.504595 | instance -> localhost | |       . . .   +B|
2026-09-17 23:57:24.504632 | instance -> localhost | |      o + .   o+*|
2026-09-17 23:57:24.504668 | instance -> localhost | |       * *.  . +*|
2026-09-17 23:57:24.504711 | instance -> localhost | |     .+.*+.    oO|
2026-09-17 23:57:24.504748 | instance -> localhost | +----[SHA256]-----+
2026-09-17 23:57:24.504838 | instance -> localhost | ok: Runtime: 0:00:00.629541
2026-09-17 23:57:24.516576 | 
2026-09-17 23:57:24.516718 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-17 23:57:24.555763 | instance | ok
2026-09-17 23:57:24.570863 | instance | included: /var/lib/zuul/builds/b3b038136ff74094acc21529f2121a1c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-17 23:57:24.580807 | 
2026-09-17 23:57:24.580924 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-17 23:57:24.604757 | instance | skipping: Conditional result was False
2026-09-17 23:57:24.614414 | 
2026-09-17 23:57:24.614579 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-17 23:57:25.053298 | instance | changed
2026-09-17 23:57:25.063983 | 
2026-09-17 23:57:25.064159 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-17 23:57:25.249155 | instance | ok
2026-09-17 23:57:25.291898 | 
2026-09-17 23:57:25.292064 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-17 23:57:25.793958 | instance | changed
2026-09-17 23:57:25.864527 | 
2026-09-17 23:57:25.864688 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-17 23:57:26.374104 | instance | changed
2026-09-17 23:57:26.387193 | 
2026-09-17 23:57:26.387445 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-17 23:57:26.411781 | instance | skipping: Conditional result was False
2026-09-17 23:57:26.437532 | 
2026-09-17 23:57:26.437677 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-17 23:57:26.836698 | instance -> localhost | changed
2026-09-17 23:57:26.856486 | 
2026-09-17 23:57:26.856663 | TASK [add-build-sshkey : Add back temp key]
2026-09-17 23:57:27.282874 | instance -> localhost | Identity added: /var/lib/zuul/builds/b3b038136ff74094acc21529f2121a1c/work/b3b038136ff74094acc21529f2121a1c_id_rsa (zuul-build-sshkey)
2026-09-17 23:57:27.283204 | instance -> localhost | ok: Runtime: 0:00:00.015802
2026-09-17 23:57:27.298336 | 
2026-09-17 23:57:27.298490 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-17 23:57:27.621404 | instance | ok
2026-09-17 23:57:27.630754 | 
2026-09-17 23:57:27.630896 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-17 23:57:27.665642 | instance | skipping: Conditional result was False
2026-09-17 23:57:27.683682 | 
2026-09-17 23:57:27.683831 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-17 23:57:28.239510 | instance | ok
2026-09-17 23:57:28.249521 | 
2026-09-17 23:57:28.249643 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-17 23:57:32.824061 | instance | Output suppressed because no_log was given
2026-09-17 23:57:32.834799 | 
2026-09-17 23:57:32.834923 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-17 23:57:33.051480 | instance | ok: "logs"
2026-09-17 23:57:33.051950 | instance | ok: All items complete
2026-09-17 23:57:33.052016 | 
2026-09-17 23:57:33.210559 | instance | ok: "artifacts"
2026-09-17 23:57:33.385578 | instance | ok: "docs"
2026-09-17 23:57:33.400487 | 
2026-09-17 23:57:33.400687 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-17 23:57:33.608482 | instance | changed: "logs"
2026-09-17 23:57:33.793525 | instance | changed: "artifacts"
2026-09-17 23:57:33.958098 | instance | changed: "docs"
2026-09-17 23:57:33.976269 | 
2026-09-17 23:57:33.976485 | PLAY RECAP
2026-09-17 23:57:33.976577 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-17 23:57:33.976614 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-17 23:57:33.976641 | 
2026-09-17 23:57:34.113007 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-17 23:57:34.117669 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-17 23:57:34.654886 | 
2026-09-17 23:57:34.654992 | PLAY [all]
2026-09-17 23:57:34.664837 | 
2026-09-17 23:57:34.664909 | TASK [setup-uv : Extract archive]
2026-09-17 23:57:40.425026 | instance | changed
2026-09-17 23:57:40.435297 | 
2026-09-17 23:57:40.435446 | TASK [setup-uv : Print version]
2026-09-17 23:57:40.804533 | instance | uv 0.8.13
2026-09-17 23:57:40.979258 | instance | ok: Runtime: 0:00:00.013426
2026-09-17 23:57:40.995225 | 
2026-09-17 23:57:40.995352 | TASK [Install Ansible collection siblings]
2026-09-17 23:57:53.675744 | instance | changed:
2026-09-17 23:57:53.675988 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-17 23:57:53.676044 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-17 23:57:53.676109 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-17 23:57:53.676152 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-17 23:57:53.676191 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-17 23:57:53.676237 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-17 23:57:53.676277 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-17 23:57:53.676318 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-17 23:57:53.676356 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-17 23:57:53.676394 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-17 23:57:53.676430 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-17 23:57:53.676465 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-17 23:57:53.676526 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-09-17 23:57:53.676567 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-17 23:57:53.676602 | instance | Resolved ansible.utils to v6.1.1 for >=2.9.0
2026-09-17 23:57:53.676638 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-17 23:57:53.676673 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-17 23:57:53.676709 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-17 23:57:53.676744 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-17 23:57:53.676779 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-09-17 23:57:53.676813 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0
2026-09-17 23:57:53.676847 | instance | Resolved atmosphere.common to v0.7.1 for >=0.7.0
2026-09-17 23:57:53.676886 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-17 23:57:53.676934 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-17 23:57:53.676971 | instance | Resolved ansible.utils to v6.1.1 for >=2.9.0,>=6.0.0
2026-09-17 23:57:53.677006 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-17 23:57:53.677039 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-17 23:57:53.677073 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-17 23:57:53.677135 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-17 23:57:53.677172 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-17 23:57:53.677206 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-17 23:57:53.677241 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-17 23:57:53.677277 | instance | Checked out ansible.netcommon to 1.2.0
2026-09-17 23:57:53.677312 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-17 23:57:53.677357 | instance | Starting galaxy collection install process
2026-09-17 23:57:53.677392 | instance | Process install dependency map
2026-09-17 23:57:53.677427 | instance | Starting collection install process
2026-09-17 23:57:53.677461 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-17 23:57:53.677495 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-17 23:57:53.677529 | instance | ansible.netcommon:* was installed successfully
2026-09-17 23:57:53.677562 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-17 23:57:53.677596 | instance | Creating virtual environment at: .venv
2026-09-17 23:57:53.677630 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-17 23:57:53.677664 | instance | Downloading pydantic-core (2.0MiB)
2026-09-17 23:57:53.677698 | instance | Downloading rjsonnet (1.2MiB)
2026-09-17 23:57:53.677739 | instance | Downloading cryptography (4.2MiB)
2026-09-17 23:57:53.677775 | instance | Downloading setuptools (1.1MiB)
2026-09-17 23:57:53.677810 | instance | Downloading netaddr (2.2MiB)
2026-09-17 23:57:53.677845 | instance | Downloading pygments (1.2MiB)
2026-09-17 23:57:53.677879 | instance | Downloading kubernetes (1.9MiB)
2026-09-17 23:57:53.677914 | instance | Downloading openstacksdk (1.7MiB)
2026-09-17 23:57:53.677950 | instance | Downloading ansible-core (2.1MiB)
2026-09-17 23:57:53.677985 | instance |    Building pyperclip==1.9.0
2026-09-17 23:57:53.678024 | instance |  Downloading rjsonnet
2026-09-17 23:57:53.678060 | instance |  Downloading pydantic-core
2026-09-17 23:57:53.678114 | instance |  Downloading netaddr
2026-09-17 23:57:53.678152 | instance |  Downloading pygments
2026-09-17 23:57:53.678186 | instance |  Downloading cryptography
2026-09-17 23:57:53.678220 | instance |  Downloading setuptools
2026-09-17 23:57:53.678254 | instance |  Downloading kubernetes
2026-09-17 23:57:53.678289 | instance |  Downloading ansible-core
2026-09-17 23:57:53.678323 | instance |  Downloading openstacksdk
2026-09-17 23:57:53.678358 | instance |       Built pyperclip==1.9.0
2026-09-17 23:57:53.678392 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-17 23:57:53.678426 | instance | Installed 83 packages in 55ms
2026-09-17 23:57:53.678459 | instance | Checked out ansible.posix to 1.6.0
2026-09-17 23:57:53.678493 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-17 23:57:53.678528 | instance | Starting galaxy collection install process
2026-09-17 23:57:53.678562 | instance | Process install dependency map
2026-09-17 23:57:53.678605 | instance | Starting collection install process
2026-09-17 23:57:53.678641 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-17 23:57:53.678675 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-17 23:57:53.678709 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-17 23:57:53.678744 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 23:57:53.678777 | instance | 2.17.12
2026-09-17 23:57:53.678811 | instance | Checked out ansible.utils to v6.1.1
2026-09-17 23:57:53.678845 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-17 23:57:53.678879 | instance | Starting galaxy collection install process
2026-09-17 23:57:53.678916 | instance | Process install dependency map
2026-09-17 23:57:53.678950 | instance | Starting collection install process
2026-09-17 23:57:53.678985 | instance | Installing 'ansible.utils:6.1.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-17 23:57:53.679019 | instance | Created collection for ansible.utils:6.1.1 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-17 23:57:53.679053 | instance | ansible.utils:6.1.1 was installed successfully
2026-09-17 23:57:53.679104 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 23:57:53.679141 | instance | 2.17.12
2026-09-17 23:57:53.679175 | instance | Checked out atmosphere.common to v0.7.1
2026-09-17 23:57:53.679209 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-17 23:57:53.679243 | instance | Starting galaxy collection install process
2026-09-17 23:57:53.679276 | instance | Process install dependency map
2026-09-17 23:57:53.679309 | instance | Starting collection install process
2026-09-17 23:57:53.679343 | instance | Installing 'atmosphere.common:0.7.1' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-17 23:57:53.679377 | instance | Created collection for atmosphere.common:0.7.1 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-17 23:57:53.679410 | instance | atmosphere.common:0.7.1 was installed successfully
2026-09-17 23:57:53.679444 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 23:57:53.679478 | instance | 2.17.12
2026-09-17 23:57:53.679511 | instance | Checked out community.crypto to 2.2.3
2026-09-17 23:57:53.679545 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-17 23:57:53.679579 | instance | Starting galaxy collection install process
2026-09-17 23:57:53.679612 | instance | Process install dependency map
2026-09-17 23:57:53.679645 | instance | Starting collection install process
2026-09-17 23:57:53.679678 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-17 23:57:53.679713 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-17 23:57:53.679746 | instance | community.crypto:2.2.3 was installed successfully
2026-09-17 23:57:53.679779 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 23:57:53.679813 | instance | 2.17.12
2026-09-17 23:57:53.679846 | instance | Checked out community.general to 7.3.0
2026-09-17 23:57:53.679880 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general
2026-09-17 23:57:53.679923 | instance | Starting galaxy collection install process
2026-09-17 23:57:53.679959 | instance | Process install dependency map
2026-09-17 23:57:53.679992 | instance | Starting collection install process
2026-09-17 23:57:53.680036 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-17 23:57:53.680114 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-17 23:57:53.680189 | instance | community.general:7.3.0 was installed successfully
2026-09-17 23:57:53.680428 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 23:57:53.680566 | instance | 2.17.12
2026-09-17 23:57:53.680614 | instance | Checked out community.mysql to 3.6.0
2026-09-17 23:57:53.680657 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-17 23:57:53.680695 | instance | Starting galaxy collection install process
2026-09-17 23:57:53.680731 | instance | Process install dependency map
2026-09-17 23:57:53.680766 | instance | Starting collection install process
2026-09-17 23:57:53.680800 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-17 23:57:53.680835 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-17 23:57:53.680869 | instance | community.mysql:3.6.0 was installed successfully
2026-09-17 23:57:53.680903 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 23:57:53.680937 | instance | 2.17.12
2026-09-17 23:57:53.680971 | instance | Checked out kubernetes.core to 2.4.0
2026-09-17 23:57:53.681006 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-17 23:57:53.681040 | instance | Starting galaxy collection install process
2026-09-17 23:57:53.681074 | instance | Process install dependency map
2026-09-17 23:57:53.681132 | instance | Starting collection install process
2026-09-17 23:57:53.681168 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-17 23:57:53.681201 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-17 23:57:53.681235 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-17 23:57:53.681270 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 23:57:53.681303 | instance | 2.17.12
2026-09-17 23:57:53.681337 | instance | Checked out openstack.cloud to 2.6.0
2026-09-17 23:57:53.681371 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-17 23:57:53.681404 | instance | Starting galaxy collection install process
2026-09-17 23:57:53.681438 | instance | Process install dependency map
2026-09-17 23:57:53.681471 | instance | Starting collection install process
2026-09-17 23:57:53.681504 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-17 23:57:53.681538 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-17 23:57:53.681571 | instance | openstack.cloud:2.6.0 was installed successfully
2026-09-17 23:57:53.681605 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 23:57:53.681638 | instance | 2.17.12
2026-09-17 23:57:53.681685 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-17 23:57:53.681720 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-17 23:57:53.681753 | instance | Starting galaxy collection install process
2026-09-17 23:57:53.681787 | instance | Process install dependency map
2026-09-17 23:57:53.681820 | instance | Starting collection install process
2026-09-17 23:57:53.681854 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-17 23:57:53.681887 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-17 23:57:53.681920 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-17 23:57:53.681954 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 23:57:53.681986 | instance | 2.17.12
2026-09-17 23:57:53.682020 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-17 23:57:53.682053 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-17 23:57:53.682104 | instance | Starting galaxy collection install process
2026-09-17 23:57:53.682143 | instance | Process install dependency map
2026-09-17 23:57:53.682177 | instance | Starting collection install process
2026-09-17 23:57:53.682211 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-17 23:57:53.682245 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-17 23:57:53.682279 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-17 23:57:53.682313 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 23:57:53.682347 | instance | 2.17.12
2026-09-17 23:57:53.682381 | instance | Checked out vexxhost.kubernetes to v3.4.0
2026-09-17 23:57:53.682416 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-17 23:57:53.682450 | instance | Starting galaxy collection install process
2026-09-17 23:57:53.682484 | instance | Process install dependency map
2026-09-17 23:57:53.682517 | instance | Starting collection install process
2026-09-17 23:57:53.682550 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-17 23:57:53.682583 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-17 23:57:53.682616 | instance | vexxhost.kubernetes:3.4.0 was installed successfully
2026-09-17 23:57:53.682649 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 23:57:53.682683 | instance | 2.17.12
2026-09-17 23:57:53.691851 | 
2026-09-17 23:57:53.691913 | PLAY RECAP
2026-09-17 23:57:53.691967 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-17 23:57:53.691993 | 
2026-09-17 23:57:53.799971 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-17 23:57:53.801784 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-17 23:57:54.358588 | 
2026-09-17 23:57:54.358703 | PLAY [all]
2026-09-17 23:57:54.368944 | 
2026-09-17 23:57:54.369023 | TASK [Install "jq" for log collection]
2026-09-17 23:58:03.710854 | instance | changed
2026-09-17 23:58:03.713680 | 
2026-09-17 23:58:03.713772 | PLAY RECAP
2026-09-17 23:58:03.713854 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-17 23:58:03.713952 | 
2026-09-17 23:58:03.944151 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-17 23:58:03.947931 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-17 23:58:04.484416 | 
2026-09-17 23:58:04.484543 | PLAY [all]
2026-09-17 23:58:04.494304 | 
2026-09-17 23:58:04.494369 | LOOP [Check inventory directories]
2026-09-17 23:58:04.856072 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-17 23:58:05.032652 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-17 23:58:05.213275 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-17 23:58:05.232065 | 
2026-09-17 23:58:05.232347 | LOOP [Copy inventory file for Zuul]
2026-09-17 23:58:07.120735 | instance | changed:
2026-09-17 23:58:07.121117 | instance | {
2026-09-17 23:58:07.121189 | instance |   "ansible_loop_var": "item",
2026-09-17 23:58:07.121240 | instance |   "changed": false,
2026-09-17 23:58:07.121281 | instance |   "failed": false,
2026-09-17 23:58:07.121328 | instance |   "invocation": {
2026-09-17 23:58:07.121367 | instance |     "module_args": {
2026-09-17 23:58:07.121405 | instance |       "checksum_algorithm": "sha1",
2026-09-17 23:58:07.121445 | instance |       "follow": true,
2026-09-17 23:58:07.121481 | instance |       "get_attributes": true,
2026-09-17 23:58:07.121519 | instance |       "get_checksum": true,
2026-09-17 23:58:07.121554 | instance |       "get_mime": true,
2026-09-17 23:58:07.121588 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-17 23:58:07.121624 | instance |     }
2026-09-17 23:58:07.121658 | instance |   },
2026-09-17 23:58:07.121692 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-17 23:58:07.121727 | instance |   "stat": {
2026-09-17 23:58:07.121761 | instance |     "atime": 1789689466.5768945,
2026-09-17 23:58:07.121796 | instance |     "attr_flags": "e",
2026-09-17 23:58:07.121830 | instance |     "attributes": [
2026-09-17 23:58:07.121864 | instance |       "extents"
2026-09-17 23:58:07.121898 | instance |     ],
2026-09-17 23:58:07.121932 | instance |     "block_size": 4096,
2026-09-17 23:58:07.121965 | instance |     "blocks": 8,
2026-09-17 23:58:07.121999 | instance |     "charset": "unknown",
2026-09-17 23:58:07.122035 | instance |     "ctime": 1789689461.9087138,
2026-09-17 23:58:07.122070 | instance |     "dev": 64513,
2026-09-17 23:58:07.122123 | instance |     "device_type": 0,
2026-09-17 23:58:07.122159 | instance |     "executable": true,
2026-09-17 23:58:07.122193 | instance |     "exists": true,
2026-09-17 23:58:07.122227 | instance |     "gid": 1000,
2026-09-17 23:58:07.122261 | instance |     "gr_name": "zuul",
2026-09-17 23:58:07.122298 | instance |     "inode": 1048809,
2026-09-17 23:58:07.122332 | instance |     "isblk": false,
2026-09-17 23:58:07.122366 | instance |     "ischr": false,
2026-09-17 23:58:07.122400 | instance |     "isdir": true,
2026-09-17 23:58:07.122433 | instance |     "isfifo": false,
2026-09-17 23:58:07.122466 | instance |     "isgid": false,
2026-09-17 23:58:07.122499 | instance |     "islnk": false,
2026-09-17 23:58:07.122532 | instance |     "isreg": false,
2026-09-17 23:58:07.122564 | instance |     "issock": false,
2026-09-17 23:58:07.122597 | instance |     "isuid": false,
2026-09-17 23:58:07.122630 | instance |     "mimetype": "unknown",
2026-09-17 23:58:07.122664 | instance |     "mode": "0755",
2026-09-17 23:58:07.122698 | instance |     "mtime": 1789689461.9087138,
2026-09-17 23:58:07.122739 | instance |     "nlink": 23,
2026-09-17 23:58:07.122786 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-17 23:58:07.122821 | instance |     "pw_name": "zuul",
2026-09-17 23:58:07.122857 | instance |     "readable": true,
2026-09-17 23:58:07.122891 | instance |     "rgrp": true,
2026-09-17 23:58:07.122926 | instance |     "roth": true,
2026-09-17 23:58:07.122976 | instance |     "rusr": true,
2026-09-17 23:58:07.123013 | instance |     "size": 4096,
2026-09-17 23:58:07.123047 | instance |     "uid": 1000,
2026-09-17 23:58:07.123081 | instance |     "version": "12149204",
2026-09-17 23:58:07.123134 | instance |     "wgrp": false,
2026-09-17 23:58:07.123172 | instance |     "woth": false,
2026-09-17 23:58:07.123207 | instance |     "writeable": true,
2026-09-17 23:58:07.123242 | instance |     "wusr": true,
2026-09-17 23:58:07.123276 | instance |     "xgrp": true,
2026-09-17 23:58:07.123414 | instance |     "xoth": true,
2026-09-17 23:58:07.123482 | instance |     "xusr": true
2026-09-17 23:58:07.123522 | instance |   }
2026-09-17 23:58:07.123560 | instance | }
2026-09-17 23:58:07.123626 | instance | ok: All items complete
2026-09-17 23:58:07.123668 | 
2026-09-17 23:58:07.125951 | instance | skipping: Conditional result was False
2026-09-17 23:58:07.129468 | instance | skipping: Conditional result was False
2026-09-17 23:58:07.144344 | 
2026-09-17 23:58:07.144519 | LOOP [Switch "ansible_host" to private IP]
2026-09-17 23:58:07.571906 | instance | changed: 1 replacements made
2026-09-17 23:58:07.576170 | instance | skipping: Conditional result was False
2026-09-17 23:58:07.584642 | instance | skipping: Conditional result was False
2026-09-17 23:58:07.601475 | 
2026-09-17 23:58:07.601568 | TASK [Run Molecule scenario]
2026-09-17 23:58:08.548716 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-17 23:58:08.946396 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-17 23:58:08.946508 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-17 23:58:19.249720 | instance | INFO     [csi > dependency] Executing
2026-09-17 23:58:19.250229 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-17 23:58:19.250680 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-17 23:58:19.250936 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-17 23:58:19.266250 | instance | INFO     [csi > cleanup] Executing
2026-09-17 23:58:19.266887 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-17 23:58:19.279785 | instance | INFO     [csi > destroy] Executing
2026-09-17 23:58:19.279901 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-17 23:58:19.280003 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-17 23:58:19.289726 | instance | INFO     [csi > syntax] Executing
2026-09-17 23:58:20.752264 | instance |
2026-09-17 23:58:20.752643 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-17 23:58:20.817330 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-17 23:58:20.827163 | instance | INFO     [csi > create] Executing
2026-09-17 23:58:20.828933 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-17 23:58:20.835590 | instance | INFO     [csi > prepare] Executing
2026-09-17 23:58:21.537649 | instance |
2026-09-17 23:58:21.537921 | instance | PLAY [Prepare] *****************************************************************
2026-09-17 23:58:21.538143 | instance |
2026-09-17 23:58:21.538386 | instance | TASK [Gathering Facts] *********************************************************
2026-09-17 23:58:21.538617 | instance | Thursday 17 September 2026  23:58:21 +0000 (0:00:00.022)       0:00:00.022 ****
2026-09-17 23:58:22.770776 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-17 23:58:22.771072 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-17 23:58:22.771442 | instance | interpreter could change the meaning of that path. See
2026-09-17 23:58:22.771752 | instance | https://docs.ansible.com/ansible-
2026-09-17 23:58:22.772059 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-17 23:58:22.781974 | instance | ok: [instance]
2026-09-17 23:58:22.782211 | instance |
2026-09-17 23:58:22.782485 | instance | TASK [Configure short hostname] ************************************************
2026-09-17 23:58:22.782789 | instance | Thursday 17 September 2026  23:58:22 +0000 (0:00:01.244)       0:00:01.267 ****
2026-09-17 23:58:23.484174 | instance | changed: [instance]
2026-09-17 23:58:23.484393 | instance |
2026-09-17 23:58:23.484727 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-17 23:58:23.485037 | instance | Thursday 17 September 2026  23:58:23 +0000 (0:00:00.701)       0:00:01.969 ****
2026-09-17 23:58:23.766787 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-17 23:58:23.766974 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-17 23:58:23.767212 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-17 23:58:23.775573 | instance | changed: [instance]
2026-09-17 23:58:23.775828 | instance |
2026-09-17 23:58:23.776104 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-17 23:58:23.776342 | instance | Thursday 17 September 2026  23:58:23 +0000 (0:00:00.291)       0:00:02.260 ****
2026-09-17 23:58:24.513614 | instance | ok: [instance]
2026-09-17 23:58:24.513820 | instance |
2026-09-17 23:58:24.514069 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-17 23:58:24.514288 | instance |
2026-09-17 23:58:24.514512 | instance | TASK [Gathering Facts] *********************************************************
2026-09-17 23:58:24.514744 | instance | Thursday 17 September 2026  23:58:24 +0000 (0:00:00.737)       0:00:02.998 ****
2026-09-17 23:58:25.276695 | instance | ok: [instance]
2026-09-17 23:58:25.276878 | instance |
2026-09-17 23:58:25.277125 | instance | TASK [Install depedencies] *****************************************************
2026-09-17 23:58:25.277398 | instance | Thursday 17 September 2026  23:58:25 +0000 (0:00:00.763)       0:00:03.761 ****
2026-09-17 23:58:49.574347 | instance | changed: [instance]
2026-09-17 23:58:49.574578 | instance |
2026-09-17 23:58:49.574846 | instance | TASK [Start up service] ********************************************************
2026-09-17 23:58:49.575122 | instance | Thursday 17 September 2026  23:58:49 +0000 (0:00:24.297)       0:00:28.059 ****
2026-09-17 23:58:50.151424 | instance | ok: [instance]
2026-09-17 23:58:50.151703 | instance |
2026-09-17 23:58:50.152045 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-17 23:58:50.152233 | instance | Thursday 17 September 2026  23:58:50 +0000 (0:00:00.577)       0:00:28.636 ****
2026-09-17 23:58:50.456579 | instance | ok: [instance]
2026-09-17 23:58:50.456776 | instance |
2026-09-17 23:58:50.457021 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-17 23:58:50.457311 | instance | Thursday 17 September 2026  23:58:50 +0000 (0:00:00.305)       0:00:28.941 ****
2026-09-17 23:58:51.125060 | instance | changed: [instance]
2026-09-17 23:58:51.125847 | instance |
2026-09-17 23:58:51.126022 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-17 23:58:51.126035 | instance | Thursday 17 September 2026  23:58:51 +0000 (0:00:00.668)       0:00:29.610 ****
2026-09-17 23:58:51.335885 | instance | ok: [instance]
2026-09-17 23:58:51.336011 | instance |
2026-09-17 23:58:51.336549 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-17 23:58:51.336649 | instance | Thursday 17 September 2026  23:58:51 +0000 (0:00:00.210)       0:00:29.820 ****
2026-09-17 23:58:51.361231 | instance | skipping: [instance]
2026-09-17 23:58:51.362019 | instance |
2026-09-17 23:58:51.362074 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-17 23:58:51.362086 | instance | Thursday 17 September 2026  23:58:51 +0000 (0:00:00.025)       0:00:29.845 ****
2026-09-17 23:58:51.940136 | instance | changed: [instance] => (item=osd0)
2026-09-17 23:58:51.940369 | instance | changed: [instance] => (item=osd1)
2026-09-17 23:58:51.940739 | instance | changed: [instance] => (item=osd2)
2026-09-17 23:58:51.941030 | instance |
2026-09-17 23:58:51.941334 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-17 23:58:51.941657 | instance | Thursday 17 September 2026  23:58:51 +0000 (0:00:00.579)       0:00:30.425 ****
2026-09-17 23:58:52.613168 | instance | changed: [instance] => (item=osd0)
2026-09-17 23:58:52.614659 | instance | changed: [instance] => (item=osd1)
2026-09-17 23:58:52.614710 | instance | changed: [instance] => (item=osd2)
2026-09-17 23:58:52.614722 | instance |
2026-09-17 23:58:52.614731 | instance | TASK [Start loop devices] ******************************************************
2026-09-17 23:58:52.614741 | instance | Thursday 17 September 2026  23:58:52 +0000 (0:00:00.672)       0:00:31.097 ****
2026-09-17 23:58:53.361430 | instance | changed: [instance] => (item=osd0)
2026-09-17 23:58:53.362604 | instance | changed: [instance] => (item=osd1)
2026-09-17 23:58:53.362661 | instance | changed: [instance] => (item=osd2)
2026-09-17 23:58:53.362672 | instance |
2026-09-17 23:58:53.362683 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-17 23:58:53.362692 | instance | Thursday 17 September 2026  23:58:53 +0000 (0:00:00.747)       0:00:31.845 ****
2026-09-17 23:58:56.527360 | instance | changed: [instance] => (item=osd0)
2026-09-17 23:58:56.528620 | instance | changed: [instance] => (item=osd1)
2026-09-17 23:58:56.528670 | instance | changed: [instance] => (item=osd2)
2026-09-17 23:58:56.528682 | instance |
2026-09-17 23:58:56.528692 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-17 23:58:56.528702 | instance | Thursday 17 September 2026  23:58:56 +0000 (0:00:03.166)       0:00:35.012 ****
2026-09-17 23:58:58.503946 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-17 23:58:58.504222 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-17 23:58:58.506352 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-17 23:58:58.506446 | instance |
2026-09-17 23:58:58.506459 | instance | PLAY RECAP *********************************************************************
2026-09-17 23:58:58.506471 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-17 23:58:58.506481 | instance |
2026-09-17 23:58:58.506490 | instance | Thursday 17 September 2026  23:58:58 +0000 (0:00:01.977)       0:00:36.989 ****
2026-09-17 23:58:58.506499 | instance | ===============================================================================
2026-09-17 23:58:58.506508 | instance | Install depedencies ---------------------------------------------------- 24.30s
2026-09-17 23:58:58.506517 | instance | Create a volume group for each loop device ------------------------------ 3.17s
2026-09-17 23:58:58.506533 | instance | Create a logical volume for each loop device ---------------------------- 1.98s
2026-09-17 23:58:58.507517 | instance | Gathering Facts --------------------------------------------------------- 1.24s
2026-09-17 23:58:58.507580 | instance | Gathering Facts --------------------------------------------------------- 0.76s
2026-09-17 23:58:58.507591 | instance | Start loop devices ------------------------------------------------------ 0.75s
2026-09-17 23:58:58.507600 | instance | Purge "snapd" package --------------------------------------------------- 0.74s
2026-09-17 23:58:58.507695 | instance | Configure short hostname ------------------------------------------------ 0.70s
2026-09-17 23:58:58.508029 | instance | Set permissions on loopback devices ------------------------------------- 0.67s
2026-09-17 23:58:58.508323 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.67s
2026-09-17 23:58:58.508640 | instance | Create devices for Ceph ------------------------------------------------- 0.58s
2026-09-17 23:58:58.508931 | instance | Start up service -------------------------------------------------------- 0.58s
2026-09-17 23:58:58.509219 | instance | Generate lvm.conf ------------------------------------------------------- 0.31s
2026-09-17 23:58:58.509538 | instance | Ensure hostname inside hosts file --------------------------------------- 0.29s
2026-09-17 23:58:58.509831 | instance | Get list of all loopback devices ---------------------------------------- 0.21s
2026-09-17 23:58:58.510166 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s
2026-09-17 23:58:58.608098 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-17 23:58:58.624043 | instance | INFO     [csi > converge] Executing
2026-09-17 23:58:59.956055 | instance |
2026-09-17 23:58:59.956423 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-17 23:58:59.956704 | instance |
2026-09-17 23:58:59.956986 | instance | TASK [Gathering Facts] *********************************************************
2026-09-17 23:58:59.957268 | instance | Thursday 17 September 2026  23:58:59 +0000 (0:00:00.007)       0:00:00.007 ****
2026-09-17 23:59:00.972521 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-17 23:59:00.973042 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-17 23:59:00.973518 | instance | interpreter could change the meaning of that path. See
2026-09-17 23:59:00.973959 | instance | https://docs.ansible.com/ansible-
2026-09-17 23:59:00.974425 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-17 23:59:00.986510 | instance | ok: [instance]
2026-09-17 23:59:00.986914 | instance |
2026-09-17 23:59:00.987300 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-17 23:59:00.987729 | instance | Thursday 17 September 2026  23:59:00 +0000 (0:00:01.029)       0:00:01.037 ****
2026-09-17 23:59:01.058496 | instance | ok: [instance] => {
2026-09-17 23:59:01.059036 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-17 23:59:01.059565 | instance | }
2026-09-17 23:59:01.060022 | instance |
2026-09-17 23:59:01.060518 | instance | PLAY [all] *********************************************************************
2026-09-17 23:59:01.060958 | instance |
2026-09-17 23:59:01.061446 | instance | TASK [Gathering Facts] *********************************************************
2026-09-17 23:59:01.061948 | instance | Thursday 17 September 2026  23:59:01 +0000 (0:00:00.072)       0:00:01.109 ****
2026-09-17 23:59:02.038699 | instance | ok: [instance]
2026-09-17 23:59:02.039034 | instance |
2026-09-17 23:59:02.039370 | instance | TASK [Fail if atmosphere_ceph_enabled is set] **********************************
2026-09-17 23:59:02.040098 | instance | Thursday 17 September 2026  23:59:02 +0000 (0:00:00.979)       0:00:02.089 ****
2026-09-17 23:59:02.091587 | instance | skipping: [instance]
2026-09-17 23:59:02.091758 | instance |
2026-09-17 23:59:02.092020 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-17 23:59:02.092264 | instance | Thursday 17 September 2026  23:59:02 +0000 (0:00:00.050)       0:00:02.139 ****
2026-09-17 23:59:02.293190 | instance | ok: [instance]
2026-09-17 23:59:02.293417 | instance |
2026-09-17 23:59:02.293654 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-17 23:59:02.293866 | instance |
2026-09-17 23:59:02.294092 | instance | TASK [Gathering Facts] *********************************************************
2026-09-17 23:59:02.294350 | instance | Thursday 17 September 2026  23:59:02 +0000 (0:00:00.204)       0:00:02.344 ****
2026-09-17 23:59:03.299659 | instance | ok: [instance]
2026-09-17 23:59:03.299748 | instance |
2026-09-17 23:59:03.299990 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-17 23:59:03.300197 | instance | Thursday 17 September 2026  23:59:03 +0000 (0:00:01.004)       0:00:03.349 ****
2026-09-17 23:59:03.628543 | instance | ok: [instance]
2026-09-17 23:59:03.628753 | instance |
2026-09-17 23:59:03.629030 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-17 23:59:03.629284 | instance | Thursday 17 September 2026  23:59:03 +0000 (0:00:00.329)       0:00:03.679 ****
2026-09-17 23:59:03.686903 | instance | skipping: [instance]
2026-09-17 23:59:03.687180 | instance |
2026-09-17 23:59:03.687533 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-17 23:59:03.687834 | instance | Thursday 17 September 2026  23:59:03 +0000 (0:00:00.058)       0:00:03.737 ****
2026-09-17 23:59:04.108970 | instance | changed: [instance]
2026-09-17 23:59:04.109189 | instance |
2026-09-17 23:59:04.109480 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-17 23:59:04.109728 | instance | Thursday 17 September 2026  23:59:04 +0000 (0:00:00.421)       0:00:04.159 ****
2026-09-17 23:59:04.204328 | instance | ok: [instance] => {
2026-09-17 23:59:04.204617 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-17 23:59:04.204867 | instance | }
2026-09-17 23:59:04.205094 | instance |
2026-09-17 23:59:04.205373 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-17 23:59:04.205627 | instance | Thursday 17 September 2026  23:59:04 +0000 (0:00:00.095)       0:00:04.254 ****
2026-09-17 23:59:04.930516 | instance | changed: [instance]
2026-09-17 23:59:04.930701 | instance |
2026-09-17 23:59:04.930974 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-17 23:59:04.931205 | instance | Thursday 17 September 2026  23:59:04 +0000 (0:00:00.726)       0:00:04.980 ****
2026-09-17 23:59:04.992507 | instance | skipping: [instance]
2026-09-17 23:59:04.992745 | instance |
2026-09-17 23:59:04.992987 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-17 23:59:04.993227 | instance | Thursday 17 September 2026  23:59:04 +0000 (0:00:00.061)       0:00:05.042 ****
2026-09-17 23:59:05.035696 | instance | skipping: [instance]
2026-09-17 23:59:05.035825 | instance |
2026-09-17 23:59:05.036076 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-17 23:59:05.036321 | instance | Thursday 17 September 2026  23:59:05 +0000 (0:00:00.043)       0:00:05.086 ****
2026-09-17 23:59:05.264559 | instance | ok: [instance]
2026-09-17 23:59:05.264736 | instance |
2026-09-17 23:59:05.264984 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-17 23:59:05.265228 | instance | Thursday 17 September 2026  23:59:05 +0000 (0:00:00.228)       0:00:05.315 ****
2026-09-17 23:59:06.722543 | instance | ok: [instance]
2026-09-17 23:59:06.722923 | instance |
2026-09-17 23:59:06.723234 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-17 23:59:06.723642 | instance | Thursday 17 September 2026  23:59:06 +0000 (0:00:01.457)       0:00:06.772 ****
2026-09-17 23:59:06.808458 | instance | ok: [instance] => {
2026-09-17 23:59:06.808730 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-17 23:59:06.809081 | instance | }
2026-09-17 23:59:06.809389 | instance |
2026-09-17 23:59:06.809697 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-17 23:59:06.809995 | instance | Thursday 17 September 2026  23:59:06 +0000 (0:00:00.083)       0:00:06.856 ****
2026-09-17 23:59:07.676387 | instance | changed: [instance]
2026-09-17 23:59:07.676591 | instance |
2026-09-17 23:59:07.676984 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-17 23:59:07.677321 | instance | Thursday 17 September 2026  23:59:07 +0000 (0:00:00.867)       0:00:07.724 ****
2026-09-17 23:59:10.600078 | instance | changed: [instance]
2026-09-17 23:59:10.600117 | instance |
2026-09-17 23:59:10.600125 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-17 23:59:10.600131 | instance | Thursday 17 September 2026  23:59:10 +0000 (0:00:02.926)       0:00:10.651 ****
2026-09-17 23:59:10.640324 | instance | skipping: [instance]
2026-09-17 23:59:10.641039 | instance |
2026-09-17 23:59:10.641078 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-17 23:59:10.641085 | instance | Thursday 17 September 2026  23:59:10 +0000 (0:00:00.040)       0:00:10.691 ****
2026-09-17 23:59:10.679863 | instance | skipping: [instance]
2026-09-17 23:59:10.680063 | instance |
2026-09-17 23:59:10.680250 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-17 23:59:10.680413 | instance | Thursday 17 September 2026  23:59:10 +0000 (0:00:00.039)       0:00:10.730 ****
2026-09-17 23:59:10.728897 | instance | skipping: [instance]
2026-09-17 23:59:10.729090 | instance |
2026-09-17 23:59:10.729366 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-17 23:59:10.729607 | instance | Thursday 17 September 2026  23:59:10 +0000 (0:00:00.049)       0:00:10.779 ****
2026-09-17 23:59:17.405814 | instance | changed: [instance]
2026-09-17 23:59:17.406040 | instance |
2026-09-17 23:59:17.406332 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-17 23:59:17.406569 | instance | Thursday 17 September 2026  23:59:17 +0000 (0:00:06.676)       0:00:17.456 ****
2026-09-17 23:59:17.970497 | instance | changed: [instance]
2026-09-17 23:59:17.970562 | instance |
2026-09-17 23:59:17.970675 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-17 23:59:17.970790 | instance | Thursday 17 September 2026  23:59:17 +0000 (0:00:00.565)       0:00:18.021 ****
2026-09-17 23:59:18.941190 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-17 23:59:18.942884 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-17 23:59:18.943070 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-17 23:59:18.943092 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-17 23:59:18.943102 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-17 23:59:18.943112 | instance |
2026-09-17 23:59:18.943122 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-17 23:59:18.943131 | instance | Thursday 17 September 2026  23:59:18 +0000 (0:00:00.970)       0:00:18.992 ****
2026-09-17 23:59:19.553285 | instance | changed: [instance]
2026-09-17 23:59:19.553350 | instance |
2026-09-17 23:59:19.554116 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-17 23:59:19.554159 | instance | Thursday 17 September 2026  23:59:19 +0000 (0:00:00.585)       0:00:19.577 ****
2026-09-17 23:59:19.554166 | instance |
2026-09-17 23:59:19.554172 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-17 23:59:19.554178 | instance | Thursday 17 September 2026  23:59:19 +0000 (0:00:00.027)       0:00:19.604 ****
2026-09-17 23:59:20.525270 | instance | ok: [instance]
2026-09-17 23:59:20.525606 | instance |
2026-09-17 23:59:20.525640 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-17 23:59:20.525646 | instance | Thursday 17 September 2026  23:59:20 +0000 (0:00:00.972)       0:00:20.576 ****
2026-09-17 23:59:20.964048 | instance | changed: [instance]
2026-09-17 23:59:20.964155 | instance |
2026-09-17 23:59:20.964445 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-17 23:59:20.964468 | instance | Thursday 17 September 2026  23:59:20 +0000 (0:00:00.437)       0:00:21.014 ****
2026-09-17 23:59:21.632247 | instance | changed: [instance]
2026-09-17 23:59:21.632363 | instance |
2026-09-17 23:59:21.632895 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-17 23:59:21.632958 | instance | Thursday 17 September 2026  23:59:21 +0000 (0:00:00.668)       0:00:21.682 ****
2026-09-17 23:59:21.870262 | instance | ok: [instance]
2026-09-17 23:59:21.870983 | instance |
2026-09-17 23:59:21.871082 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-17 23:59:21.871094 | instance | Thursday 17 September 2026  23:59:21 +0000 (0:00:00.237)       0:00:21.920 ****
2026-09-17 23:59:21.942229 | instance | ok: [instance] => {
2026-09-17 23:59:21.943433 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-17 23:59:21.943477 | instance | }
2026-09-17 23:59:21.943490 | instance |
2026-09-17 23:59:21.943499 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-17 23:59:21.943511 | instance | Thursday 17 September 2026  23:59:21 +0000 (0:00:00.072)       0:00:21.992 ****
2026-09-17 23:59:22.815259 | instance | changed: [instance]
2026-09-17 23:59:22.816109 | instance |
2026-09-17 23:59:22.816187 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-17 23:59:22.816199 | instance | Thursday 17 September 2026  23:59:22 +0000 (0:00:00.872)       0:00:22.865 ****
2026-09-17 23:59:27.198825 | instance | changed: [instance]
2026-09-17 23:59:27.198957 | instance |
2026-09-17 23:59:27.199442 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-17 23:59:27.199519 | instance | Thursday 17 September 2026  23:59:27 +0000 (0:00:04.383)       0:00:27.249 ****
2026-09-17 23:59:28.622881 | instance | ok: [instance]
2026-09-17 23:59:28.623512 | instance |
2026-09-17 23:59:28.623596 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-17 23:59:28.623666 | instance | Thursday 17 September 2026  23:59:28 +0000 (0:00:01.423)       0:00:28.673 ****
2026-09-17 23:59:29.014446 | instance | changed: [instance]
2026-09-17 23:59:29.014603 | instance |
2026-09-17 23:59:29.014903 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-17 23:59:29.015153 | instance | Thursday 17 September 2026  23:59:29 +0000 (0:00:00.392)       0:00:29.065 ****
2026-09-17 23:59:29.560037 | instance | changed: [instance]
2026-09-17 23:59:29.560225 | instance |
2026-09-17 23:59:29.560475 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-17 23:59:29.560706 | instance | Thursday 17 September 2026  23:59:29 +0000 (0:00:00.544)       0:00:29.610 ****
2026-09-17 23:59:30.253740 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-17 23:59:30.254030 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-17 23:59:30.254322 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-17 23:59:30.254573 | instance |
2026-09-17 23:59:30.254909 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-17 23:59:30.255152 | instance | Thursday 17 September 2026  23:59:30 +0000 (0:00:00.693)       0:00:30.303 ****
2026-09-17 23:59:30.777849 | instance | changed: [instance]
2026-09-17 23:59:30.778087 | instance |
2026-09-17 23:59:30.778356 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-17 23:59:30.778613 | instance | Thursday 17 September 2026  23:59:30 +0000 (0:00:00.524)       0:00:30.828 ****
2026-09-17 23:59:31.307534 | instance | changed: [instance]
2026-09-17 23:59:31.307606 | instance |
2026-09-17 23:59:31.307622 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-17 23:59:31.307656 | instance | Thursday 17 September 2026  23:59:31 +0000 (0:00:00.503)       0:00:31.332 ****
2026-09-17 23:59:31.307669 | instance |
2026-09-17 23:59:31.307682 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-17 23:59:31.307713 | instance | Thursday 17 September 2026  23:59:31 +0000 (0:00:00.024)       0:00:31.357 ****
2026-09-17 23:59:32.106872 | instance | ok: [instance]
2026-09-17 23:59:32.106938 | instance |
2026-09-17 23:59:32.107374 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-17 23:59:32.107474 | instance | Thursday 17 September 2026  23:59:32 +0000 (0:00:00.800)       0:00:32.157 ****
2026-09-17 23:59:33.069171 | instance | changed: [instance]
2026-09-17 23:59:33.069241 | instance |
2026-09-17 23:59:33.069253 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-17 23:59:33.069263 | instance | Thursday 17 September 2026  23:59:33 +0000 (0:00:00.961)       0:00:33.119 ****
2026-09-17 23:59:33.703058 | instance | changed: [instance]
2026-09-17 23:59:33.703119 | instance |
2026-09-17 23:59:33.703358 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-17 23:59:33.703404 | instance | Thursday 17 September 2026  23:59:33 +0000 (0:00:00.635)       0:00:33.754 ****
2026-09-17 23:59:33.780613 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-17 23:59:33.781408 | instance |
2026-09-17 23:59:33.781457 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-17 23:59:33.781469 | instance | Thursday 17 September 2026  23:59:33 +0000 (0:00:00.076)       0:00:33.831 ****
2026-09-17 23:59:39.175414 | instance | changed: [instance]
2026-09-17 23:59:39.175901 | instance |
2026-09-17 23:59:39.175951 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-17 23:59:39.175958 | instance | Thursday 17 September 2026  23:59:39 +0000 (0:00:05.395)       0:00:39.226 ****
2026-09-17 23:59:39.793055 | instance | ok: [instance] => (item=chronyd)
2026-09-17 23:59:39.793999 | instance | ok: [instance] => (item=sshd)
2026-09-17 23:59:39.794191 | instance |
2026-09-17 23:59:39.794204 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-17 23:59:39.794215 | instance | Thursday 17 September 2026  23:59:39 +0000 (0:00:00.617)       0:00:39.844 ****
2026-09-17 23:59:41.724306 | instance | changed: [instance]
2026-09-17 23:59:41.725144 | instance |
2026-09-17 23:59:41.725201 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-09-17 23:59:41.725214 | instance | Thursday 17 September 2026  23:59:41 +0000 (0:00:01.930)       0:00:41.775 ****
2026-09-17 23:59:41.947813 | instance | ok: [instance]
2026-09-17 23:59:41.948273 | instance |
2026-09-17 23:59:41.948311 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-09-17 23:59:41.948319 | instance | Thursday 17 September 2026  23:59:41 +0000 (0:00:00.223)       0:00:41.999 ****
2026-09-17 23:59:42.408957 | instance | changed: [instance]
2026-09-17 23:59:42.408995 | instance |
2026-09-17 23:59:42.409003 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-09-17 23:59:42.409010 | instance | Thursday 17 September 2026  23:59:42 +0000 (0:00:00.460)       0:00:42.459 ****
2026-09-17 23:59:42.776426 | instance | changed: [instance]
2026-09-17 23:59:42.777199 | instance |
2026-09-17 23:59:42.777232 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-09-17 23:59:42.777245 | instance | Thursday 17 September 2026  23:59:42 +0000 (0:00:00.367)       0:00:42.827 ****
2026-09-17 23:59:42.909458 | instance | ok: [instance]
2026-09-17 23:59:42.910175 | instance |
2026-09-17 23:59:42.910205 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-09-17 23:59:42.910217 | instance | Thursday 17 September 2026  23:59:42 +0000 (0:00:00.133)       0:00:42.960 ****
2026-09-17 23:59:43.153275 | instance | ok: [instance] => (item=instance)
2026-09-17 23:59:43.153730 | instance |
2026-09-17 23:59:43.153751 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-09-17 23:59:43.153759 | instance | Thursday 17 September 2026  23:59:43 +0000 (0:00:00.244)       0:00:43.204 ****
2026-09-17 23:59:43.224015 | instance | ok: [instance]
2026-09-17 23:59:43.224725 | instance |
2026-09-17 23:59:43.224758 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-09-17 23:59:43.224770 | instance | Thursday 17 September 2026  23:59:43 +0000 (0:00:00.070)       0:00:43.274 ****
2026-09-17 23:59:43.313485 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-09-17 23:59:43.313638 | instance |
2026-09-17 23:59:43.313936 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-09-17 23:59:43.313956 | instance | Thursday 17 September 2026  23:59:43 +0000 (0:00:00.090)       0:00:43.365 ****
2026-09-17 23:59:43.633545 | instance | changed: [instance]
2026-09-17 23:59:43.634273 | instance |
2026-09-17 23:59:43.634306 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-09-17 23:59:43.634317 | instance | Thursday 17 September 2026  23:59:43 +0000 (0:00:00.319)       0:00:43.684 ****
2026-09-17 23:59:44.376823 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-09-17 23:59:44.376882 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-09-17 23:59:44.376894 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-09-17 23:59:44.376904 | instance |
2026-09-17 23:59:44.376914 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-09-17 23:59:44.376923 | instance | Thursday 17 September 2026  23:59:44 +0000 (0:00:00.741)       0:00:44.426 ****
2026-09-18 00:01:18.695755 | instance | ok: [instance]
2026-09-18 00:01:18.695846 | instance |
2026-09-18 00:01:18.696035 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-09-18 00:01:18.696837 | instance | Friday 18 September 2026  00:01:18 +0000 (0:01:34.320)       0:02:18.746 ******
2026-09-18 00:01:18.938070 | instance | changed: [instance]
2026-09-18 00:01:18.938222 | instance |
2026-09-18 00:01:18.938342 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-09-18 00:01:18.938451 | instance | Friday 18 September 2026  00:01:18 +0000 (0:00:00.242)       0:02:18.989 ******
2026-09-18 00:01:18.987528 | instance | ok: [instance]
2026-09-18 00:01:18.987770 | instance |
2026-09-18 00:01:18.988047 | instance | TASK [Install Ceph host] *******************************************************
2026-09-18 00:01:18.988304 | instance | Friday 18 September 2026  00:01:18 +0000 (0:00:00.049)       0:02:19.038 ******
2026-09-18 00:01:19.105433 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-09-18 00:01:19.105635 | instance |
2026-09-18 00:01:19.105874 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-09-18 00:01:19.106125 | instance | Friday 18 September 2026  00:01:19 +0000 (0:00:00.118)       0:02:19.156 ******
2026-09-18 00:01:20.776877 | instance | ok: [instance]
2026-09-18 00:01:20.777182 | instance |
2026-09-18 00:01:20.777525 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-09-18 00:01:20.777849 | instance | Friday 18 September 2026  00:01:20 +0000 (0:00:01.670)       0:02:20.827 ******
2026-09-18 00:01:20.826367 | instance | ok: [instance] => (item=instance)
2026-09-18 00:01:20.826446 | instance |
2026-09-18 00:01:20.826517 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-09-18 00:01:20.826621 | instance | Friday 18 September 2026  00:01:20 +0000 (0:00:00.049)       0:02:20.877 ******
2026-09-18 00:01:21.147706 | instance | ok: [instance]
2026-09-18 00:01:21.147917 | instance |
2026-09-18 00:01:21.148188 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-09-18 00:01:21.148420 | instance | Friday 18 September 2026  00:01:21 +0000 (0:00:00.321)       0:02:21.198 ******
2026-09-18 00:01:23.603241 | instance | ok: [instance]
2026-09-18 00:01:23.603305 | instance |
2026-09-18 00:01:23.603318 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-09-18 00:01:23.603328 | instance | Friday 18 September 2026  00:01:23 +0000 (0:00:02.454)       0:02:23.653 ******
2026-09-18 00:02:17.784328 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster)
2026-09-18 00:02:17.785163 | 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_akexf1a0/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_akexf1a0/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_akexf1a0/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_akexf1a0/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 120, in apply_spec\nRuntimeError: Inferring config /var/lib/ceph/4837cbf8-4f90-4300-b3f6-726c9b9f89b4/mon.instance/config\n2026-09-18T00:02:17.604+0000 7f4f9ce90640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAlf6xq5/74IyAAPzerddL7+iZMTtFBVgFC/PePGClkixQ01HkwfrLYIhI=: Malformed input [buffer:3]\n2026-09-18T00:02:17.604+0000 7f4f9ce90640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-18T00:02:17.608+0000 7f4f9ce90640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAlf6xq5/74IyAAPzerddL7+iZMTtFBVgFC/PePGClkixQ01HkwfrLYIhI=: Malformed input [buffer:3]\n2026-09-18T00:02:17.608+0000 7f4f9ce90640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-18T00:02:17.608+0000 7f4f9ce90640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAlf6xq5/74IyAAPzerddL7+iZMTtFBVgFC/PePGClkixQ01HkwfrLYIhI=: Malformed input [buffer:3]\n2026-09-18T00:02:17.608+0000 7f4f9ce90640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-18T00:02:17.608+0000 7f4f9ce90640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1}
2026-09-18 00:02:17.788154 | instance |
2026-09-18 00:02:17.788400 | instance | PLAY RECAP *********************************************************************
2026-09-18 00:02:17.791695 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=7    rescued=0    ignored=0
2026-09-18 00:02:17.791807 | instance |
2026-09-18 00:02:17.791820 | instance | Friday 18 September 2026  00:02:17 +0000 (0:00:54.186)       0:03:17.839 ******
2026-09-18 00:02:17.791830 | instance | ===============================================================================
2026-09-18 00:02:17.791839 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 94.32s
2026-09-18 00:02:17.791848 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 54.19s
2026-09-18 00:02:17.791857 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 6.68s
2026-09-18 00:02:17.791866 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.40s
2026-09-18 00:02:17.791874 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.38s
2026-09-18 00:02:17.791883 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.93s
2026-09-18 00:02:17.791930 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.45s
2026-09-18 00:02:17.791939 | instance | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 1.93s
2026-09-18 00:02:17.791948 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.77s
2026-09-18 00:02:17.791956 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.67s
2026-09-18 00:02:17.791976 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.46s
2026-09-18 00:02:17.794294 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.42s
2026-09-18 00:02:17.794365 | instance | Gathering Facts --------------------------------------------------------- 1.03s
2026-09-18 00:02:17.794377 | instance | Gathering Facts --------------------------------------------------------- 1.00s
2026-09-18 00:02:17.794387 | instance | Gathering Facts --------------------------------------------------------- 0.98s
2026-09-18 00:02:17.794396 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.97s
2026-09-18 00:02:17.794405 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.96s
2026-09-18 00:02:17.794414 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.87s
2026-09-18 00:02:17.794423 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.87s
2026-09-18 00:02:17.794432 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.74s
2026-09-18 00:02:17.891757 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-18 00:02:17.892074 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-18 00:02:17.892262 | instance | ERROR    Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-18 00:02:18.459363 | instance | ERROR
2026-09-18 00:02:18.459617 | instance | {
2026-09-18 00:02:18.459680 | instance |   "delta": "0:04:10.031013",
2026-09-18 00:02:18.459724 | instance |   "end": "2026-09-18 00:02:17.968927",
2026-09-18 00:02:18.459763 | instance |   "msg": "non-zero return code",
2026-09-18 00:02:18.459803 | instance |   "rc": 2,
2026-09-18 00:02:18.459841 | instance |   "start": "2026-09-17 23:58:07.937914"
2026-09-18 00:02:18.459880 | instance | }
failure
2026-09-18 00:02:18.470018 | 
2026-09-18 00:02:18.470078 | PLAY RECAP
2026-09-18 00:02:18.470129 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-18 00:02:18.470179 | 
2026-09-18 00:02:18.590058 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-18 00:02:18.593699 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-18 00:02:19.172068 | 
2026-09-18 00:02:19.172533 | PLAY [all]
2026-09-18 00:02:19.185271 | 
2026-09-18 00:02:19.185344 | TASK [gather-host-logs : creating directory for system status]
2026-09-18 00:02:19.541817 | instance | changed
2026-09-18 00:02:19.552416 | 
2026-09-18 00:02:19.552690 | TASK [gather-host-logs : Get logs for each host]
2026-09-18 00:02:19.924127 | instance | + systemd-cgls --full --all --no-pager
2026-09-18 00:02:19.943388 | instance | + ip addr
2026-09-18 00:02:19.946575 | instance | + ip route
2026-09-18 00:02:19.949573 | instance | + lsblk
2026-09-18 00:02:19.955317 | instance | + mount
2026-09-18 00:02:19.958320 | instance | + docker images
2026-09-18 00:02:19.982013 | instance | + brctl show
2026-09-18 00:02:19.982707 | instance | /bin/bash: line 8: brctl: command not found
2026-09-18 00:02:19.983054 | instance | + ip neigh
2026-09-18 00:02:19.985814 | instance | + bridge fdb show
2026-09-18 00:02:19.989683 | instance | + ps aux --sort=-%mem
2026-09-18 00:02:20.010600 | instance | + dpkg -l
2026-09-18 00:02:20.023329 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-18 00:02:20.023930 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-18 00:02:20.049833 | instance | + '[' '!' -z '' ']'
2026-09-18 00:02:20.112306 | instance | ok: Runtime: 0:00:00.132784
2026-09-18 00:02:20.130035 | 
2026-09-18 00:02:20.130329 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-18 00:02:20.830731 | instance | changed:
2026-09-18 00:02:20.830990 | instance | created directory /var/lib/zuul/builds/b3b038136ff74094acc21529f2121a1c/work/logs/instance
2026-09-18 00:02:20.831048 | instance | cd+++++++++ system/
2026-09-18 00:02:20.831093 | instance | >f+++++++++ system/brctl-show.txt
2026-09-18 00:02:20.831133 | instance | >f+++++++++ system/bridge-fdb.txt
2026-09-18 00:02:20.831195 | instance | >f+++++++++ system/docker-images.txt
2026-09-18 00:02:20.831242 | instance | >f+++++++++ system/ip-addr.txt
2026-09-18 00:02:20.831284 | instance | >f+++++++++ system/ip-neigh.txt
2026-09-18 00:02:20.831322 | instance | >f+++++++++ system/ip-route.txt
2026-09-18 00:02:20.831364 | instance | >f+++++++++ system/lsblk.txt
2026-09-18 00:02:20.831403 | instance | >f+++++++++ system/mount.txt
2026-09-18 00:02:20.831439 | instance | >f+++++++++ system/packages.txt
2026-09-18 00:02:20.831476 | instance | >f+++++++++ system/ps.txt
2026-09-18 00:02:20.831511 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-18 00:02:20.848337 | 
2026-09-18 00:02:20.848591 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-18 00:02:21.087638 | instance | changed: "values"
2026-09-18 00:02:21.285369 | instance | changed: "releases"
2026-09-18 00:02:21.302695 | 
2026-09-18 00:02:21.302854 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-18 00:02:21.559629 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-18 00:02:21.853163 | instance | ok: Runtime: 0:00:00.006865
2026-09-18 00:02:21.862180 | 
2026-09-18 00:02:21.862305 | TASK [helm-release-status : Downloads logs to executor]
2026-09-18 00:02:22.398396 | instance | changed:
2026-09-18 00:02:22.398580 | instance | cd+++++++++ helm/
2026-09-18 00:02:22.398616 | instance | cd+++++++++ helm/releases/
2026-09-18 00:02:22.398644 | instance | cd+++++++++ helm/values/
2026-09-18 00:02:22.423442 | 
2026-09-18 00:02:22.423616 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-18 00:02:22.638591 | instance | changed
2026-09-18 00:02:22.649880 | 
2026-09-18 00:02:22.650018 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-18 00:02:22.918501 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-18 00:02:22.918987 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-18 00:02:22.923777 | instance | environment: line 1: kubectl: command not found
2026-09-18 00:02:22.925351 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-18 00:02:22.926310 | instance | environment: line 1: kubectl: command not found
2026-09-18 00:02:22.927569 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-18 00:02:22.929173 | instance | environment: line 1: kubectl: command not found
2026-09-18 00:02:22.930406 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-18 00:02:22.931929 | instance | environment: line 1: kubectl: command not found
2026-09-18 00:02:22.933146 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-18 00:02:22.934051 | instance | environment: line 1: kubectl: command not found
2026-09-18 00:02:22.935201 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-18 00:02:23.193678 | instance | ok: Runtime: 0:00:00.026620
2026-09-18 00:02:23.202671 | 
2026-09-18 00:02:23.202795 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-18 00:02:23.418779 | instance | changed
2026-09-18 00:02:23.428014 | 
2026-09-18 00:02:23.428165 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-18 00:02:23.690406 | instance | environment: line 5: kubectl: command not found
2026-09-18 00:02:23.691501 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-18 00:02:23.691831 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-18 00:02:23.692186 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-18 00:02:23.977030 | instance | ok: Runtime: 0:00:00.009276
2026-09-18 00:02:23.986563 | 
2026-09-18 00:02:23.986685 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-18 00:02:24.532016 | instance | changed:
2026-09-18 00:02:24.532162 | instance | cd+++++++++ objects/
2026-09-18 00:02:24.532188 | instance | cd+++++++++ objects/cluster/
2026-09-18 00:02:24.532206 | instance | cd+++++++++ objects/namespaced/
2026-09-18 00:02:24.539957 | 
2026-09-18 00:02:24.540015 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-18 00:02:24.753798 | instance | changed
2026-09-18 00:02:24.763094 | 
2026-09-18 00:02:24.763235 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-18 00:02:24.980756 | instance | changed
2026-09-18 00:02:24.990591 | 
2026-09-18 00:02:24.990781 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-18 00:02:25.249773 | instance | environment: line 3: kubectl: command not found
2026-09-18 00:02:25.541416 | instance | ok: Runtime: 0:00:00.010553
2026-09-18 00:02:25.550986 | 
2026-09-18 00:02:25.551117 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-18 00:02:26.070271 | instance | changed:
2026-09-18 00:02:26.070510 | instance | cd+++++++++ pod-logs/
2026-09-18 00:02:26.070565 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-18 00:02:26.090029 | 
2026-09-18 00:02:26.090193 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-18 00:02:26.305675 | instance | changed
2026-09-18 00:02:26.315066 | 
2026-09-18 00:02:26.315215 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-18 00:02:26.563452 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-18 00:02:26.860935 | instance | ok: Runtime: 0:00:00.053610
2026-09-18 00:02:26.869826 | 
2026-09-18 00:02:26.869952 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-18 00:02:27.118587 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-18 00:02:27.160333 | instance | ceph-mgr endpoints:
2026-09-18 00:02:27.419093 | instance | ok: Runtime: 0:00:00.046170
2026-09-18 00:02:27.428620 | 
2026-09-18 00:02:27.428747 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-18 00:02:27.700818 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-18 00:02:27.975493 | instance | ok: Runtime: 0:00:00.059376
2026-09-18 00:02:27.984936 | 
2026-09-18 00:02:27.985066 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-18 00:02:28.545260 | instance | changed: cd+++++++++ prometheus/
2026-09-18 00:02:28.568572 | 
2026-09-18 00:02:28.570346 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-18 00:02:28.789024 | instance | changed
2026-09-18 00:02:28.797909 | 
2026-09-18 00:02:28.798033 | TASK [gather-selenium-data : Get selenium data]
2026-09-18 00:02:29.072333 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-18 00:02:29.073830 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-18 00:02:29.343220 | instance | ERROR
2026-09-18 00:02:29.343416 | instance | {
2026-09-18 00:02:29.343473 | instance |   "delta": "0:00:00.005460",
2026-09-18 00:02:29.343512 | instance |   "end": "2026-09-18 00:02:29.074188",
2026-09-18 00:02:29.343548 | instance |   "msg": "non-zero return code",
2026-09-18 00:02:29.343582 | instance |   "rc": 1,
2026-09-18 00:02:29.343617 | instance |   "start": "2026-09-18 00:02:29.068728"
2026-09-18 00:02:29.343651 | instance | }
2026-09-18 00:02:29.343695 | instance | ERROR: Ignoring Errors
2026-09-18 00:02:29.352522 | 
2026-09-18 00:02:29.352646 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-18 00:02:29.838161 | instance | changed: cd+++++++++ selenium/
2026-09-18 00:02:29.842874 | 
2026-09-18 00:02:29.842916 | PLAY RECAP
2026-09-18 00:02:29.842957 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-18 00:02:29.842976 | 
2026-09-18 00:02:29.936814 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-18 00:02:29.939063 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-18 00:02:30.547187 | 
2026-09-18 00:02:30.547308 | PLAY [all]
2026-09-18 00:02:30.558006 | 
2026-09-18 00:02:30.558076 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-18 00:02:30.612330 | instance | skipping: Conditional result was False
2026-09-18 00:02:30.619701 | 
2026-09-18 00:02:30.619814 | TASK [fetch-output : Set log path for single node]
2026-09-18 00:02:30.673715 | instance | ok
2026-09-18 00:02:30.680804 | 
2026-09-18 00:02:30.680897 | LOOP [fetch-output : Ensure local output dirs]
2026-09-18 00:02:31.069620 | instance -> localhost | ok: "/var/lib/zuul/builds/b3b038136ff74094acc21529f2121a1c/work/logs"
2026-09-18 00:02:31.322625 | instance -> localhost | changed: "/var/lib/zuul/builds/b3b038136ff74094acc21529f2121a1c/work/artifacts"
2026-09-18 00:02:31.540656 | instance -> localhost | changed: "/var/lib/zuul/builds/b3b038136ff74094acc21529f2121a1c/work/docs"
2026-09-18 00:02:31.557115 | 
2026-09-18 00:02:31.557319 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-18 00:02:32.240019 | instance | changed: .d..t...... ./
2026-09-18 00:02:32.240338 | instance | changed: All items complete
2026-09-18 00:02:32.240397 | 
2026-09-18 00:02:32.725790 | instance | changed: .d..t...... ./
2026-09-18 00:02:33.211883 | instance | changed: .d..t...... ./
2026-09-18 00:02:33.238347 | 
2026-09-18 00:02:33.238519 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-18 00:02:33.636288 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008666
2026-09-18 00:02:33.873128 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006201
2026-09-18 00:02:33.891903 | 
2026-09-18 00:02:33.892045 | PLAY [all]
2026-09-18 00:02:33.902475 | 
2026-09-18 00:02:33.902596 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-18 00:02:34.311200 | instance | changed
2026-09-18 00:02:34.320693 | 
2026-09-18 00:02:34.320799 | PLAY RECAP
2026-09-18 00:02:34.320878 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-18 00:02:34.320922 | 
2026-09-18 00:02:34.437050 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-18 00:02:34.441209 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-18 00:02:34.965048 | 
2026-09-18 00:02:34.965166 | PLAY [localhost]
2026-09-18 00:02:34.973760 | 
2026-09-18 00:02:34.973824 | TASK [Generate Zuul manifest]
2026-09-18 00:02:34.989639 | localhost | ok
2026-09-18 00:02:35.003262 | 
2026-09-18 00:02:35.003333 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-18 00:02:35.387944 | localhost | changed
2026-09-18 00:02:35.406043 | 
2026-09-18 00:02:35.406204 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-18 00:02:35.437698 | localhost | ok
2026-09-18 00:02:35.449842 | 
2026-09-18 00:02:35.449964 | TASK [Upload logs]
2026-09-18 00:02:35.471644 | localhost | ok
2026-09-18 00:02:35.584278 | 
2026-09-18 00:02:35.584400 | TASK [Set zuul-log-path fact]
2026-09-18 00:02:35.600383 | localhost | ok
2026-09-18 00:02:35.610265 | 
2026-09-18 00:02:35.610327 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-18 00:02:35.637305 | localhost | ok
2026-09-18 00:02:35.645028 | 
2026-09-18 00:02:35.645113 | TASK [upload-logs : Create log directories]
2026-09-18 00:02:36.040946 | localhost | changed
2026-09-18 00:02:36.057952 | 
2026-09-18 00:02:36.058097 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-18 00:02:36.518619 | localhost -> localhost | ok: Runtime: 0:00:00.005205
2026-09-18 00:02:36.528484 | 
2026-09-18 00:02:36.528655 | TASK [upload-logs : Upload logs to log server]
2026-09-18 00:02:37.048365 | localhost | Output suppressed because no_log was given
2026-09-18 00:02:37.090685 | 
2026-09-18 00:02:37.090825 | LOOP [upload-logs : Compress console log and json output]
2026-09-18 00:02:37.136816 | localhost | skipping: Conditional result was False
2026-09-18 00:02:37.145353 | localhost | skipping: Conditional result was False
2026-09-18 00:02:37.154911 | 
2026-09-18 00:02:37.155054 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-18 00:02:37.191221 | localhost | skipping: Conditional result was False
2026-09-18 00:02:37.191489 | 
2026-09-18 00:02:37.195862 | localhost | skipping: Conditional result was False
2026-09-18 00:02:37.208924 | 
2026-09-18 00:02:37.209087 | LOOP [upload-logs : Upload console log and json output]