2026-09-17 20:39:58.054563 | Job console starting
2026-09-17 20:39:58.063988 | Updating git repos
2026-09-17 20:40:00.492716 | Cloning repos into workspace
2026-09-17 20:40:18.751259 | Restoring repo states
2026-09-17 20:40:34.259367 | Merging changes
2026-09-17 20:40:35.522846 | Checking out repos
2026-09-17 20:40:39.004854 | Preparing playbooks
2026-09-17 20:40:42.329993 | Running Ansible setup
2026-09-17 20:40:45.662463 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-17 20:40:46.202825 | 
2026-09-17 20:40:46.202942 | PLAY [localhost]
2026-09-17 20:40:46.209881 | 
2026-09-17 20:40:46.209949 | TASK [Gathering Facts]
2026-09-17 20:40:48.025232 | localhost | ok
2026-09-17 20:40:48.037126 | 
2026-09-17 20:40:48.037265 | TASK [Setup log path fact]
2026-09-17 20:40:48.056133 | localhost | ok
2026-09-17 20:40:48.074010 | 
2026-09-17 20:40:48.074146 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-17 20:40:48.103528 | localhost | ok
2026-09-17 20:40:48.114001 | 
2026-09-17 20:40:48.114129 | TASK [emit-job-header : Print job information]
2026-09-17 20:40:48.155289 | # Job Information
2026-09-17 20:40:48.155524 | Ansible Version: 2.16.18
2026-09-17 20:40:48.155589 | Job: atmosphere-molecule-csi-rbd
2026-09-17 20:40:48.155633 | Pipeline: check
2026-09-17 20:40:48.155673 | Executor: 2d72f0692154
2026-09-17 20:40:48.155712 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4381
2026-09-17 20:40:48.155757 | Event ID: bfeea6b0-b2d6-11f1-9b9a-2d561500b0c3
2026-09-17 20:40:48.160755 | 
2026-09-17 20:40:48.160871 | LOOP [emit-job-header : Print node information]
2026-09-17 20:40:48.274178 | localhost | ok:
2026-09-17 20:40:48.274338 | localhost | # Node Information
2026-09-17 20:40:48.274365 | localhost | Inventory Hostname: instance
2026-09-17 20:40:48.274387 | localhost | Hostname: np0000193114
2026-09-17 20:40:48.274407 | localhost | Username: zuul
2026-09-17 20:40:48.274445 | localhost | Distro: Ubuntu 22.04
2026-09-17 20:40:48.274467 | localhost | Provider: yul1
2026-09-17 20:40:48.274485 | localhost | Region: ca-ymq-1
2026-09-17 20:40:48.274507 | localhost | Label: ubuntu-jammy
2026-09-17 20:40:48.274532 | localhost | Product Name: OpenStack Nova
2026-09-17 20:40:48.274554 | localhost | Interface IP: 162.253.55.81
2026-09-17 20:40:48.287089 | 
2026-09-17 20:40:48.287250 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-17 20:40:48.712805 | localhost -> localhost | changed
2026-09-17 20:40:48.723241 | 
2026-09-17 20:40:48.723463 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-17 20:40:49.592366 | localhost -> localhost | changed
2026-09-17 20:40:49.599094 | 
2026-09-17 20:40:49.599142 | PLAY [all]
2026-09-17 20:40:49.608470 | 
2026-09-17 20:40:49.608596 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-17 20:40:49.845363 | instance -> localhost | ok
2026-09-17 20:40:49.852011 | 
2026-09-17 20:40:49.852109 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-17 20:40:49.880516 | instance | ok
2026-09-17 20:40:49.897199 | instance | included: /var/lib/zuul/builds/2d073a8390234bcc93d492796e7bb29d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-17 20:40:49.905200 | 
2026-09-17 20:40:49.905294 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-17 20:40:51.192516 | instance -> localhost | Generating public/private rsa key pair.
2026-09-17 20:40:51.192734 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/2d073a8390234bcc93d492796e7bb29d/work/2d073a8390234bcc93d492796e7bb29d_id_rsa
2026-09-17 20:40:51.192793 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/2d073a8390234bcc93d492796e7bb29d/work/2d073a8390234bcc93d492796e7bb29d_id_rsa.pub
2026-09-17 20:40:51.192838 | instance -> localhost | The key fingerprint is:
2026-09-17 20:40:51.192880 | instance -> localhost | SHA256:OMiYuf8rXPRktqVeBPffnXQ7kRnk5pijqTnKkV4oTks zuul-build-sshkey
2026-09-17 20:40:51.192936 | instance -> localhost | The key's randomart image is:
2026-09-17 20:40:51.192979 | instance -> localhost | +---[RSA 3072]----+
2026-09-17 20:40:51.193026 | instance -> localhost | |              .. |
2026-09-17 20:40:51.193068 | instance -> localhost | |        . .   .. |
2026-09-17 20:40:51.193109 | instance -> localhost | |         o .   o+|
2026-09-17 20:40:51.193149 | instance -> localhost | |   = ...+ o . ==.|
2026-09-17 20:40:51.193189 | instance -> localhost | |  + o.o=S=   =.o*|
2026-09-17 20:40:51.193227 | instance -> localhost | |   .  ..* . o o+o|
2026-09-17 20:40:51.193265 | instance -> localhost | |  .. E = o o    .|
2026-09-17 20:40:51.193304 | instance -> localhost | |   .* = +.o      |
2026-09-17 20:40:51.193348 | instance -> localhost | |    .=o=.o.      |
2026-09-17 20:40:51.193388 | instance -> localhost | +----[SHA256]-----+
2026-09-17 20:40:51.193499 | instance -> localhost | ok: Runtime: 0:00:00.864194
2026-09-17 20:40:51.204213 | 
2026-09-17 20:40:51.204328 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-17 20:40:51.226026 | instance | ok
2026-09-17 20:40:51.241479 | instance | included: /var/lib/zuul/builds/2d073a8390234bcc93d492796e7bb29d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-17 20:40:51.253010 | 
2026-09-17 20:40:51.253094 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-17 20:40:51.276469 | instance | skipping: Conditional result was False
2026-09-17 20:40:51.288749 | 
2026-09-17 20:40:51.289065 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-17 20:40:51.736554 | instance | changed
2026-09-17 20:40:51.747502 | 
2026-09-17 20:40:51.747632 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-17 20:40:51.983988 | instance | ok
2026-09-17 20:40:51.997114 | 
2026-09-17 20:40:51.997300 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-17 20:40:52.454739 | instance | changed
2026-09-17 20:40:52.461065 | 
2026-09-17 20:40:52.461225 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-17 20:40:52.939322 | instance | changed
2026-09-17 20:40:52.950752 | 
2026-09-17 20:40:52.950891 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-17 20:40:52.975362 | instance | skipping: Conditional result was False
2026-09-17 20:40:52.986452 | 
2026-09-17 20:40:52.986624 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-17 20:40:53.409694 | instance -> localhost | changed
2026-09-17 20:40:53.428860 | 
2026-09-17 20:40:53.429002 | TASK [add-build-sshkey : Add back temp key]
2026-09-17 20:40:53.692136 | instance -> localhost | Identity added: /var/lib/zuul/builds/2d073a8390234bcc93d492796e7bb29d/work/2d073a8390234bcc93d492796e7bb29d_id_rsa (zuul-build-sshkey)
2026-09-17 20:40:53.692340 | instance -> localhost | ok: Runtime: 0:00:00.014066
2026-09-17 20:40:53.698836 | 
2026-09-17 20:40:53.698919 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-17 20:40:54.011898 | instance | ok
2026-09-17 20:40:54.016000 | 
2026-09-17 20:40:54.016066 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-17 20:40:54.039902 | instance | skipping: Conditional result was False
2026-09-17 20:40:54.058686 | 
2026-09-17 20:40:54.058837 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-17 20:40:54.393047 | instance | ok
2026-09-17 20:40:54.402253 | 
2026-09-17 20:40:54.402335 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-17 20:40:57.859501 | instance | Output suppressed because no_log was given
2026-09-17 20:40:57.878893 | 
2026-09-17 20:40:57.879126 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-17 20:40:58.132570 | instance | ok: "logs"
2026-09-17 20:40:58.132996 | instance | ok: All items complete
2026-09-17 20:40:58.133070 | 
2026-09-17 20:40:58.277662 | instance | ok: "artifacts"
2026-09-17 20:40:58.456954 | instance | ok: "docs"
2026-09-17 20:40:58.470408 | 
2026-09-17 20:40:58.470649 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-17 20:40:58.667098 | instance | changed: "logs"
2026-09-17 20:40:58.827767 | instance | changed: "artifacts"
2026-09-17 20:40:58.981656 | instance | changed: "docs"
2026-09-17 20:40:59.000399 | 
2026-09-17 20:40:59.000612 | PLAY RECAP
2026-09-17 20:40:59.000705 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-17 20:40:59.000763 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-17 20:40:59.000801 | 
2026-09-17 20:40:59.120523 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-17 20:40:59.124230 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-17 20:40:59.654150 | 
2026-09-17 20:40:59.654255 | PLAY [all]
2026-09-17 20:40:59.663387 | 
2026-09-17 20:40:59.663473 | TASK [setup-uv : Extract archive]
2026-09-17 20:41:01.982955 | instance | changed
2026-09-17 20:41:01.988605 | 
2026-09-17 20:41:01.988726 | TASK [setup-uv : Print version]
2026-09-17 20:41:02.359182 | instance | uv 0.8.13
2026-09-17 20:41:02.524637 | instance | ok: Runtime: 0:00:00.008607
2026-09-17 20:41:02.537075 | 
2026-09-17 20:41:02.537228 | TASK [Install Ansible collection siblings]
2026-09-17 20:41:12.037306 | instance | changed:
2026-09-17 20:41:12.037597 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-17 20:41:12.037662 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-17 20:41:12.037707 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-17 20:41:12.037765 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-17 20:41:12.037818 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-17 20:41:12.037866 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-17 20:41:12.037908 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-17 20:41:12.037955 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-17 20:41:12.037995 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-17 20:41:12.038033 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-17 20:41:12.038071 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-17 20:41:12.038108 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-17 20:41:12.038144 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-09-17 20:41:12.038180 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-17 20:41:12.038216 | instance | Resolved ansible.utils to v6.1.1 for >=2.9.0
2026-09-17 20:41:12.038254 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-17 20:41:12.038291 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-17 20:41:12.038328 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-17 20:41:12.038364 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-17 20:41:12.038400 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-09-17 20:41:12.038471 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0
2026-09-17 20:41:12.038523 | instance | Resolved atmosphere.common to v0.7.1 for >=0.7.0
2026-09-17 20:41:12.038573 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-17 20:41:12.038650 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-17 20:41:12.038709 | instance | Resolved ansible.utils to v6.1.1 for >=2.9.0,>=6.0.0
2026-09-17 20:41:12.038763 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-17 20:41:12.038804 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-17 20:41:12.038839 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-17 20:41:12.038874 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-17 20:41:12.038910 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-17 20:41:12.038944 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-17 20:41:12.038980 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-17 20:41:12.039015 | instance | Checked out ansible.netcommon to 1.2.0
2026-09-17 20:41:12.039050 | 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 20:41:12.039095 | instance | Starting galaxy collection install process
2026-09-17 20:41:12.039130 | instance | Process install dependency map
2026-09-17 20:41:12.039164 | instance | Starting collection install process
2026-09-17 20:41:12.039198 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-17 20:41:12.039233 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-17 20:41:12.039267 | instance | ansible.netcommon:* was installed successfully
2026-09-17 20:41:12.039301 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-17 20:41:12.039334 | instance | Creating virtual environment at: .venv
2026-09-17 20:41:12.039368 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-17 20:41:12.039402 | instance | Downloading setuptools (1.1MiB)
2026-09-17 20:41:12.039459 | instance | Downloading netaddr (2.2MiB)
2026-09-17 20:41:12.039496 | instance | Downloading pygments (1.2MiB)
2026-09-17 20:41:12.039540 | instance | Downloading kubernetes (1.9MiB)
2026-09-17 20:41:12.039594 | instance | Downloading pydantic-core (2.0MiB)
2026-09-17 20:41:12.039647 | instance | Downloading cryptography (4.2MiB)
2026-09-17 20:41:12.039694 | instance | Downloading ansible-core (2.1MiB)
2026-09-17 20:41:12.039731 | instance | Downloading rjsonnet (1.2MiB)
2026-09-17 20:41:12.039767 | instance | Downloading openstacksdk (1.7MiB)
2026-09-17 20:41:12.039802 | instance |    Building pyperclip==1.9.0
2026-09-17 20:41:12.039841 | instance |  Downloading rjsonnet
2026-09-17 20:41:12.039877 | instance |  Downloading pydantic-core
2026-09-17 20:41:12.039912 | instance |  Downloading netaddr
2026-09-17 20:41:12.039947 | instance |  Downloading pygments
2026-09-17 20:41:12.039982 | instance |  Downloading cryptography
2026-09-17 20:41:12.040017 | instance |  Downloading setuptools
2026-09-17 20:41:12.040051 | instance |  Downloading kubernetes
2026-09-17 20:41:12.040086 | instance |  Downloading ansible-core
2026-09-17 20:41:12.040121 | instance |  Downloading openstacksdk
2026-09-17 20:41:12.040155 | instance |       Built pyperclip==1.9.0
2026-09-17 20:41:12.040189 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-17 20:41:12.040223 | instance | Installed 83 packages in 82ms
2026-09-17 20:41:12.040258 | instance | Checked out ansible.posix to 1.6.0
2026-09-17 20:41:12.040294 | 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 20:41:12.040328 | instance | Starting galaxy collection install process
2026-09-17 20:41:12.040363 | instance | Process install dependency map
2026-09-17 20:41:12.040407 | instance | Starting collection install process
2026-09-17 20:41:12.040468 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-17 20:41:12.040722 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-17 20:41:12.040795 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-17 20:41:12.040837 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 20:41:12.040874 | instance | 2.17.12
2026-09-17 20:41:12.040910 | instance | Checked out ansible.utils to v6.1.1
2026-09-17 20:41:12.040945 | 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 20:41:12.040984 | instance | Starting galaxy collection install process
2026-09-17 20:41:12.041036 | instance | Process install dependency map
2026-09-17 20:41:12.041091 | instance | Starting collection install process
2026-09-17 20:41:12.041145 | instance | Installing 'ansible.utils:6.1.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-17 20:41:12.041200 | instance | Created collection for ansible.utils:6.1.1 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-17 20:41:12.041250 | instance | ansible.utils:6.1.1 was installed successfully
2026-09-17 20:41:12.041287 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 20:41:12.041321 | instance | 2.17.12
2026-09-17 20:41:12.041354 | instance | Checked out atmosphere.common to v0.7.1
2026-09-17 20:41:12.041388 | 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 20:41:12.041450 | instance | Starting galaxy collection install process
2026-09-17 20:41:12.041488 | instance | Process install dependency map
2026-09-17 20:41:12.041521 | instance | Starting collection install process
2026-09-17 20:41:12.041555 | instance | Installing 'atmosphere.common:0.7.1' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-17 20:41:12.041588 | instance | Created collection for atmosphere.common:0.7.1 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-17 20:41:12.041621 | instance | atmosphere.common:0.7.1 was installed successfully
2026-09-17 20:41:12.041654 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 20:41:12.041769 | instance | 2.17.12
2026-09-17 20:41:12.041827 | instance | Checked out community.crypto to 2.2.3
2026-09-17 20:41:12.041866 | 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 20:41:12.041901 | instance | Starting galaxy collection install process
2026-09-17 20:41:12.041936 | instance | Process install dependency map
2026-09-17 20:41:12.041969 | instance | Starting collection install process
2026-09-17 20:41:12.042003 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-17 20:41:12.042035 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-17 20:41:12.042069 | instance | community.crypto:2.2.3 was installed successfully
2026-09-17 20:41:12.042102 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 20:41:12.042135 | instance | 2.17.12
2026-09-17 20:41:12.042167 | instance | Checked out community.general to 7.3.0
2026-09-17 20:41:12.042201 | 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 20:41:12.042263 | instance | Starting galaxy collection install process
2026-09-17 20:41:12.042320 | instance | Process install dependency map
2026-09-17 20:41:12.042370 | instance | Starting collection install process
2026-09-17 20:41:12.042407 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-17 20:41:12.042486 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-17 20:41:12.042540 | instance | community.general:7.3.0 was installed successfully
2026-09-17 20:41:12.042578 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 20:41:12.042613 | instance | 2.17.12
2026-09-17 20:41:12.042647 | instance | Checked out community.mysql to 3.6.0
2026-09-17 20:41:12.042687 | 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 20:41:12.042723 | instance | Starting galaxy collection install process
2026-09-17 20:41:12.042758 | instance | Process install dependency map
2026-09-17 20:41:12.042793 | instance | Starting collection install process
2026-09-17 20:41:12.042827 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-17 20:41:12.042861 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-17 20:41:12.042896 | instance | community.mysql:3.6.0 was installed successfully
2026-09-17 20:41:12.042930 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 20:41:12.042965 | instance | 2.17.12
2026-09-17 20:41:12.042998 | instance | Checked out kubernetes.core to 2.4.0
2026-09-17 20:41:12.043034 | 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 20:41:12.043068 | instance | Starting galaxy collection install process
2026-09-17 20:41:12.043102 | instance | Process install dependency map
2026-09-17 20:41:12.043137 | instance | Starting collection install process
2026-09-17 20:41:12.043172 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-17 20:41:12.043207 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-17 20:41:12.043240 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-17 20:41:12.043274 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 20:41:12.043307 | instance | 2.17.12
2026-09-17 20:41:12.043341 | instance | Checked out openstack.cloud to 2.6.0
2026-09-17 20:41:12.043374 | 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 20:41:12.043407 | instance | Starting galaxy collection install process
2026-09-17 20:41:12.043467 | instance | Process install dependency map
2026-09-17 20:41:12.043502 | instance | Starting collection install process
2026-09-17 20:41:12.043541 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-17 20:41:12.043592 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-17 20:41:12.043645 | instance | openstack.cloud:2.6.0 was installed successfully
2026-09-17 20:41:12.043694 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 20:41:12.043730 | instance | 2.17.12
2026-09-17 20:41:12.043774 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-17 20:41:12.043809 | 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 20:41:12.043842 | instance | Starting galaxy collection install process
2026-09-17 20:41:12.043876 | instance | Process install dependency map
2026-09-17 20:41:12.043908 | instance | Starting collection install process
2026-09-17 20:41:12.043942 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-17 20:41:12.043975 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-17 20:41:12.044008 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-17 20:41:12.044042 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 20:41:12.044078 | instance | 2.17.12
2026-09-17 20:41:12.044111 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-17 20:41:12.044144 | 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 20:41:12.044178 | instance | Starting galaxy collection install process
2026-09-17 20:41:12.044213 | instance | Process install dependency map
2026-09-17 20:41:12.044245 | instance | Starting collection install process
2026-09-17 20:41:12.044282 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-17 20:41:12.044331 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-17 20:41:12.044382 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-17 20:41:12.044451 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 20:41:12.044520 | instance | 2.17.12
2026-09-17 20:41:12.044561 | instance | Checked out vexxhost.kubernetes to v3.4.0
2026-09-17 20:41:12.044599 | 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 20:41:12.044634 | instance | Starting galaxy collection install process
2026-09-17 20:41:12.044669 | instance | Process install dependency map
2026-09-17 20:41:12.044703 | instance | Starting collection install process
2026-09-17 20:41:12.044736 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-17 20:41:12.044770 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-17 20:41:12.044804 | instance | vexxhost.kubernetes:3.4.0 was installed successfully
2026-09-17 20:41:12.044840 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 20:41:12.044873 | instance | 2.17.12
2026-09-17 20:41:12.057819 | 
2026-09-17 20:41:12.057972 | PLAY RECAP
2026-09-17 20:41:12.058077 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-17 20:41:12.058132 | 
2026-09-17 20:41:12.182388 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-17 20:41:12.184819 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-17 20:41:12.707136 | 
2026-09-17 20:41:12.707642 | PLAY [all]
2026-09-17 20:41:12.717601 | 
2026-09-17 20:41:12.717678 | TASK [Install "jq" for log collection]
2026-09-17 20:41:21.928946 | instance | changed
2026-09-17 20:41:21.932511 | 
2026-09-17 20:41:21.932636 | PLAY RECAP
2026-09-17 20:41:21.932733 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-17 20:41:21.932855 | 
2026-09-17 20:41:22.097549 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-17 20:41:22.099246 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-17 20:41:22.644341 | 
2026-09-17 20:41:22.644515 | PLAY [all]
2026-09-17 20:41:22.655359 | 
2026-09-17 20:41:22.655469 | LOOP [Check inventory directories]
2026-09-17 20:41:23.009890 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-17 20:41:23.174739 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-17 20:41:23.341818 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-17 20:41:23.361210 | 
2026-09-17 20:41:23.361504 | LOOP [Copy inventory file for Zuul]
2026-09-17 20:41:24.107708 | instance | changed:
2026-09-17 20:41:24.107992 | instance | {
2026-09-17 20:41:24.108051 | instance |   "ansible_loop_var": "item",
2026-09-17 20:41:24.108113 | instance |   "changed": false,
2026-09-17 20:41:24.108166 | instance |   "failed": false,
2026-09-17 20:41:24.108209 | instance |   "invocation": {
2026-09-17 20:41:24.108244 | instance |     "module_args": {
2026-09-17 20:41:24.108279 | instance |       "checksum_algorithm": "sha1",
2026-09-17 20:41:24.108313 | instance |       "follow": true,
2026-09-17 20:41:24.108346 | instance |       "get_attributes": true,
2026-09-17 20:41:24.108382 | instance |       "get_checksum": true,
2026-09-17 20:41:24.108440 | instance |       "get_mime": true,
2026-09-17 20:41:24.108477 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-17 20:41:24.108542 | instance |     }
2026-09-17 20:41:24.108577 | instance |   },
2026-09-17 20:41:24.108610 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-17 20:41:24.108643 | instance |   "stat": {
2026-09-17 20:41:24.108675 | instance |     "atime": 1789677664.8916142,
2026-09-17 20:41:24.108709 | instance |     "attr_flags": "e",
2026-09-17 20:41:24.108742 | instance |     "attributes": [
2026-09-17 20:41:24.108774 | instance |       "extents"
2026-09-17 20:41:24.108807 | instance |     ],
2026-09-17 20:41:24.108839 | instance |     "block_size": 4096,
2026-09-17 20:41:24.108872 | instance |     "blocks": 8,
2026-09-17 20:41:24.108905 | instance |     "charset": "unknown",
2026-09-17 20:41:24.108941 | instance |     "ctime": 1789677663.4395404,
2026-09-17 20:41:24.108975 | instance |     "dev": 64513,
2026-09-17 20:41:24.109008 | instance |     "device_type": 0,
2026-09-17 20:41:24.109042 | instance |     "executable": true,
2026-09-17 20:41:24.109091 | instance |     "exists": true,
2026-09-17 20:41:24.109135 | instance |     "gid": 1000,
2026-09-17 20:41:24.109168 | instance |     "gr_name": "zuul",
2026-09-17 20:41:24.109201 | instance |     "inode": 1048772,
2026-09-17 20:41:24.109233 | instance |     "isblk": false,
2026-09-17 20:41:24.109266 | instance |     "ischr": false,
2026-09-17 20:41:24.109298 | instance |     "isdir": true,
2026-09-17 20:41:24.109332 | instance |     "isfifo": false,
2026-09-17 20:41:24.109377 | instance |     "isgid": false,
2026-09-17 20:41:24.109473 | instance |     "islnk": false,
2026-09-17 20:41:24.109547 | instance |     "isreg": false,
2026-09-17 20:41:24.109585 | instance |     "issock": false,
2026-09-17 20:41:24.109619 | instance |     "isuid": false,
2026-09-17 20:41:24.109651 | instance |     "mimetype": "unknown",
2026-09-17 20:41:24.109684 | instance |     "mode": "0755",
2026-09-17 20:41:24.109715 | instance |     "mtime": 1789677663.4395404,
2026-09-17 20:41:24.109748 | instance |     "nlink": 23,
2026-09-17 20:41:24.109779 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-17 20:41:24.109811 | instance |     "pw_name": "zuul",
2026-09-17 20:41:24.109858 | instance |     "readable": true,
2026-09-17 20:41:24.109902 | instance |     "rgrp": true,
2026-09-17 20:41:24.109950 | instance |     "roth": true,
2026-09-17 20:41:24.110013 | instance |     "rusr": true,
2026-09-17 20:41:24.110048 | instance |     "size": 4096,
2026-09-17 20:41:24.110080 | instance |     "uid": 1000,
2026-09-17 20:41:24.110111 | instance |     "version": "339625952",
2026-09-17 20:41:24.110144 | instance |     "wgrp": false,
2026-09-17 20:41:24.110181 | instance |     "woth": false,
2026-09-17 20:41:24.110214 | instance |     "writeable": true,
2026-09-17 20:41:24.110252 | instance |     "wusr": true,
2026-09-17 20:41:24.110298 | instance |     "xgrp": true,
2026-09-17 20:41:24.110347 | instance |     "xoth": true,
2026-09-17 20:41:24.110389 | instance |     "xusr": true
2026-09-17 20:41:24.110458 | instance |   }
2026-09-17 20:41:24.110503 | instance | }
2026-09-17 20:41:24.110589 | instance | ok: All items complete
2026-09-17 20:41:24.110638 | 
2026-09-17 20:41:24.113553 | instance | skipping: Conditional result was False
2026-09-17 20:41:24.114815 | instance | skipping: Conditional result was False
2026-09-17 20:41:24.135176 | 
2026-09-17 20:41:24.135345 | LOOP [Switch "ansible_host" to private IP]
2026-09-17 20:41:24.515027 | instance | changed: 1 replacements made
2026-09-17 20:41:24.520511 | instance | skipping: Conditional result was False
2026-09-17 20:41:24.527718 | instance | skipping: Conditional result was False
2026-09-17 20:41:24.545005 | 
2026-09-17 20:41:24.545148 | TASK [Run Molecule scenario]
2026-09-17 20:41:25.469276 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-17 20:41:25.903757 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-17 20:41:25.903840 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-17 20:41:36.571058 | instance | INFO     [csi > dependency] Executing
2026-09-17 20:41:36.571449 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-17 20:41:36.571815 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-17 20:41:36.571975 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-17 20:41:36.580008 | instance | INFO     [csi > cleanup] Executing
2026-09-17 20:41:36.580366 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-17 20:41:36.588753 | instance | INFO     [csi > destroy] Executing
2026-09-17 20:41:36.588831 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-17 20:41:36.588939 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-17 20:41:36.596903 | instance | INFO     [csi > syntax] Executing
2026-09-17 20:41:38.132634 | instance |
2026-09-17 20:41:38.132788 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-17 20:41:38.193704 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-17 20:41:38.200736 | instance | INFO     [csi > create] Executing
2026-09-17 20:41:38.202394 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-17 20:41:38.209010 | instance | INFO     [csi > prepare] Executing
2026-09-17 20:41:38.959525 | instance |
2026-09-17 20:41:38.959774 | instance | PLAY [Prepare] *****************************************************************
2026-09-17 20:41:38.959998 | instance |
2026-09-17 20:41:38.960228 | instance | TASK [Gathering Facts] *********************************************************
2026-09-17 20:41:38.960495 | instance | Thursday 17 September 2026  20:41:38 +0000 (0:00:00.023)       0:00:00.023 ****
2026-09-17 20:41:40.158095 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-17 20:41:40.158451 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-17 20:41:40.158749 | instance | interpreter could change the meaning of that path. See
2026-09-17 20:41:40.159072 | instance | https://docs.ansible.com/ansible-
2026-09-17 20:41:40.159486 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-17 20:41:40.168489 | instance | ok: [instance]
2026-09-17 20:41:40.168734 | instance |
2026-09-17 20:41:40.169053 | instance | TASK [Configure short hostname] ************************************************
2026-09-17 20:41:40.169372 | instance | Thursday 17 September 2026  20:41:40 +0000 (0:00:01.209)       0:00:01.232 ****
2026-09-17 20:41:40.846982 | instance | changed: [instance]
2026-09-17 20:41:40.847211 | instance |
2026-09-17 20:41:40.847490 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-17 20:41:40.847742 | instance | Thursday 17 September 2026  20:41:40 +0000 (0:00:00.678)       0:00:01.910 ****
2026-09-17 20:41:41.094507 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-17 20:41:41.094768 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-17 20:41:41.095020 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-17 20:41:41.100883 | instance | changed: [instance]
2026-09-17 20:41:41.101083 | instance |
2026-09-17 20:41:41.101347 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-17 20:41:41.101580 | instance | Thursday 17 September 2026  20:41:41 +0000 (0:00:00.254)       0:00:02.165 ****
2026-09-17 20:41:41.862677 | instance | ok: [instance]
2026-09-17 20:41:41.862778 | instance |
2026-09-17 20:41:41.862996 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-17 20:41:41.863090 | instance |
2026-09-17 20:41:41.863282 | instance | TASK [Gathering Facts] *********************************************************
2026-09-17 20:41:41.863443 | instance | Thursday 17 September 2026  20:41:41 +0000 (0:00:00.761)       0:00:02.926 ****
2026-09-17 20:41:42.613450 | instance | ok: [instance]
2026-09-17 20:41:42.613670 | instance |
2026-09-17 20:41:42.613952 | instance | TASK [Install depedencies] *****************************************************
2026-09-17 20:41:42.614171 | instance | Thursday 17 September 2026  20:41:42 +0000 (0:00:00.750)       0:00:03.677 ****
2026-09-17 20:42:04.751500 | instance | changed: [instance]
2026-09-17 20:42:04.751606 | instance |
2026-09-17 20:42:04.751757 | instance | TASK [Start up service] ********************************************************
2026-09-17 20:42:04.751905 | instance | Thursday 17 September 2026  20:42:04 +0000 (0:00:22.138)       0:00:25.816 ****
2026-09-17 20:42:05.254964 | instance | ok: [instance]
2026-09-17 20:42:05.255315 | instance |
2026-09-17 20:42:05.255610 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-17 20:42:05.255891 | instance | Thursday 17 September 2026  20:42:05 +0000 (0:00:00.502)       0:00:26.318 ****
2026-09-17 20:42:05.585127 | instance | ok: [instance]
2026-09-17 20:42:05.585395 | instance |
2026-09-17 20:42:05.585660 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-17 20:42:05.585960 | instance | Thursday 17 September 2026  20:42:05 +0000 (0:00:00.330)       0:00:26.649 ****
2026-09-17 20:42:06.272120 | instance | changed: [instance]
2026-09-17 20:42:06.272245 | instance |
2026-09-17 20:42:06.272402 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-17 20:42:06.272557 | instance | Thursday 17 September 2026  20:42:06 +0000 (0:00:00.687)       0:00:27.336 ****
2026-09-17 20:42:06.481329 | instance | ok: [instance]
2026-09-17 20:42:06.481502 | instance |
2026-09-17 20:42:06.481746 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-17 20:42:06.481996 | instance | Thursday 17 September 2026  20:42:06 +0000 (0:00:00.209)       0:00:27.545 ****
2026-09-17 20:42:06.502687 | instance | skipping: [instance]
2026-09-17 20:42:06.502901 | instance |
2026-09-17 20:42:06.503186 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-17 20:42:06.503431 | instance | Thursday 17 September 2026  20:42:06 +0000 (0:00:00.021)       0:00:27.566 ****
2026-09-17 20:42:07.076927 | instance | changed: [instance] => (item=osd0)
2026-09-17 20:42:07.077269 | instance | changed: [instance] => (item=osd1)
2026-09-17 20:42:07.077595 | instance | changed: [instance] => (item=osd2)
2026-09-17 20:42:07.077938 | instance |
2026-09-17 20:42:07.078268 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-17 20:42:07.078560 | instance | Thursday 17 September 2026  20:42:07 +0000 (0:00:00.573)       0:00:28.140 ****
2026-09-17 20:42:07.685550 | instance | changed: [instance] => (item=osd0)
2026-09-17 20:42:07.685603 | instance | changed: [instance] => (item=osd1)
2026-09-17 20:42:07.685614 | instance | changed: [instance] => (item=osd2)
2026-09-17 20:42:07.685624 | instance |
2026-09-17 20:42:07.685633 | instance | TASK [Start loop devices] ******************************************************
2026-09-17 20:42:07.685648 | instance | Thursday 17 September 2026  20:42:07 +0000 (0:00:00.607)       0:00:28.748 ****
2026-09-17 20:42:08.421127 | instance | changed: [instance] => (item=osd0)
2026-09-17 20:42:08.421400 | instance | changed: [instance] => (item=osd1)
2026-09-17 20:42:08.421752 | instance | changed: [instance] => (item=osd2)
2026-09-17 20:42:08.422064 | instance |
2026-09-17 20:42:08.422458 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-17 20:42:08.422828 | instance | Thursday 17 September 2026  20:42:08 +0000 (0:00:00.736)       0:00:29.484 ****
2026-09-17 20:42:11.834364 | instance | changed: [instance] => (item=osd0)
2026-09-17 20:42:11.835210 | instance | changed: [instance] => (item=osd1)
2026-09-17 20:42:11.835270 | instance | changed: [instance] => (item=osd2)
2026-09-17 20:42:11.835344 | instance |
2026-09-17 20:42:11.835672 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-17 20:42:11.835921 | instance | Thursday 17 September 2026  20:42:11 +0000 (0:00:03.413)       0:00:32.898 ****
2026-09-17 20:42:13.751465 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-17 20:42:13.751680 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-17 20:42:13.751906 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-17 20:42:13.752130 | instance |
2026-09-17 20:42:13.752357 | instance | PLAY RECAP *********************************************************************
2026-09-17 20:42:13.752600 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-17 20:42:13.752803 | instance |
2026-09-17 20:42:13.753030 | instance | Thursday 17 September 2026  20:42:13 +0000 (0:00:01.918)       0:00:34.816 ****
2026-09-17 20:42:13.753263 | instance | ===============================================================================
2026-09-17 20:42:13.753496 | instance | Install depedencies ---------------------------------------------------- 22.14s
2026-09-17 20:42:13.753702 | instance | Create a volume group for each loop device ------------------------------ 3.41s
2026-09-17 20:42:13.753924 | instance | Create a logical volume for each loop device ---------------------------- 1.92s
2026-09-17 20:42:13.754180 | instance | Gathering Facts --------------------------------------------------------- 1.21s
2026-09-17 20:42:13.754401 | instance | Purge "snapd" package --------------------------------------------------- 0.76s
2026-09-17 20:42:13.754620 | instance | Gathering Facts --------------------------------------------------------- 0.75s
2026-09-17 20:42:13.754837 | instance | Start loop devices ------------------------------------------------------ 0.74s
2026-09-17 20:42:13.755065 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.69s
2026-09-17 20:42:13.755353 | instance | Configure short hostname ------------------------------------------------ 0.68s
2026-09-17 20:42:13.755568 | instance | Set permissions on loopback devices ------------------------------------- 0.61s
2026-09-17 20:42:13.755788 | instance | Create devices for Ceph ------------------------------------------------- 0.57s
2026-09-17 20:42:13.756005 | instance | Start up service -------------------------------------------------------- 0.50s
2026-09-17 20:42:13.756244 | instance | Generate lvm.conf ------------------------------------------------------- 0.33s
2026-09-17 20:42:13.756472 | instance | Ensure hostname inside hosts file --------------------------------------- 0.25s
2026-09-17 20:42:13.756699 | instance | Get list of all loopback devices ---------------------------------------- 0.21s
2026-09-17 20:42:13.756919 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s
2026-09-17 20:42:13.828955 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-17 20:42:13.843205 | instance | INFO     [csi > converge] Executing
2026-09-17 20:42:15.234158 | instance |
2026-09-17 20:42:15.234373 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-17 20:42:15.234603 | instance |
2026-09-17 20:42:15.234850 | instance | TASK [Gathering Facts] *********************************************************
2026-09-17 20:42:15.235088 | instance | Thursday 17 September 2026  20:42:15 +0000 (0:00:00.007)       0:00:00.007 ****
2026-09-17 20:42:16.228582 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-17 20:42:16.228783 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-17 20:42:16.229024 | instance | interpreter could change the meaning of that path. See
2026-09-17 20:42:16.229285 | instance | https://docs.ansible.com/ansible-
2026-09-17 20:42:16.229524 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-17 20:42:16.244698 | instance | ok: [instance]
2026-09-17 20:42:16.244904 | instance |
2026-09-17 20:42:16.245161 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-17 20:42:16.245393 | instance | Thursday 17 September 2026  20:42:16 +0000 (0:00:01.010)       0:00:01.018 ****
2026-09-17 20:42:16.295793 | instance | ok: [instance] => {
2026-09-17 20:42:16.296130 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-17 20:42:16.296368 | instance | }
2026-09-17 20:42:16.296597 | instance |
2026-09-17 20:42:16.296835 | instance | PLAY [all] *********************************************************************
2026-09-17 20:42:16.297051 | instance |
2026-09-17 20:42:16.297317 | instance | TASK [Gathering Facts] *********************************************************
2026-09-17 20:42:16.297555 | instance | Thursday 17 September 2026  20:42:16 +0000 (0:00:00.051)       0:00:01.069 ****
2026-09-17 20:42:17.214099 | instance | ok: [instance]
2026-09-17 20:42:17.214352 | instance |
2026-09-17 20:42:17.214635 | instance | TASK [Fail if atmosphere_ceph_enabled is set] **********************************
2026-09-17 20:42:17.214897 | instance | Thursday 17 September 2026  20:42:17 +0000 (0:00:00.917)       0:00:01.987 ****
2026-09-17 20:42:17.258861 | instance | skipping: [instance]
2026-09-17 20:42:17.259061 | instance |
2026-09-17 20:42:17.259373 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-17 20:42:17.259606 | instance | Thursday 17 September 2026  20:42:17 +0000 (0:00:00.044)       0:00:02.032 ****
2026-09-17 20:42:17.471042 | instance | ok: [instance]
2026-09-17 20:42:17.471231 | instance |
2026-09-17 20:42:17.471402 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-17 20:42:17.471556 | instance |
2026-09-17 20:42:17.471731 | instance | TASK [Gathering Facts] *********************************************************
2026-09-17 20:42:17.471898 | instance | Thursday 17 September 2026  20:42:17 +0000 (0:00:00.212)       0:00:02.245 ****
2026-09-17 20:42:18.420434 | instance | ok: [instance]
2026-09-17 20:42:18.420659 | instance |
2026-09-17 20:42:18.420854 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-17 20:42:18.421041 | instance | Thursday 17 September 2026  20:42:18 +0000 (0:00:00.948)       0:00:03.193 ****
2026-09-17 20:42:18.755814 | instance | ok: [instance]
2026-09-17 20:42:18.756191 | instance |
2026-09-17 20:42:18.756560 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-17 20:42:18.757007 | instance | Thursday 17 September 2026  20:42:18 +0000 (0:00:00.333)       0:00:03.526 ****
2026-09-17 20:42:18.803667 | instance | skipping: [instance]
2026-09-17 20:42:18.803742 | instance |
2026-09-17 20:42:18.803926 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-17 20:42:18.804182 | instance | Thursday 17 September 2026  20:42:18 +0000 (0:00:00.049)       0:00:03.576 ****
2026-09-17 20:42:19.181984 | instance | changed: [instance]
2026-09-17 20:42:19.182181 | instance |
2026-09-17 20:42:19.182327 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-17 20:42:19.182466 | instance | Thursday 17 September 2026  20:42:19 +0000 (0:00:00.377)       0:00:03.954 ****
2026-09-17 20:42:19.271148 | instance | ok: [instance] => {
2026-09-17 20:42:19.271204 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-17 20:42:19.271217 | instance | }
2026-09-17 20:42:19.271227 | instance |
2026-09-17 20:42:19.271238 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-17 20:42:19.271248 | instance | Thursday 17 September 2026  20:42:19 +0000 (0:00:00.087)       0:00:04.041 ****
2026-09-17 20:42:20.207216 | instance | changed: [instance]
2026-09-17 20:42:20.207276 | instance |
2026-09-17 20:42:20.207289 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-17 20:42:20.207439 | instance | Thursday 17 September 2026  20:42:20 +0000 (0:00:00.938)       0:00:04.979 ****
2026-09-17 20:42:20.272799 | instance | skipping: [instance]
2026-09-17 20:42:20.272910 | instance |
2026-09-17 20:42:20.273299 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-17 20:42:20.273343 | instance | Thursday 17 September 2026  20:42:20 +0000 (0:00:00.066)       0:00:05.046 ****
2026-09-17 20:42:20.334991 | instance | skipping: [instance]
2026-09-17 20:42:20.335036 | instance |
2026-09-17 20:42:20.335044 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-17 20:42:20.335051 | instance | Thursday 17 September 2026  20:42:20 +0000 (0:00:00.061)       0:00:05.108 ****
2026-09-17 20:42:20.574753 | instance | ok: [instance]
2026-09-17 20:42:20.574799 | instance |
2026-09-17 20:42:20.574809 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-17 20:42:20.574817 | instance | Thursday 17 September 2026  20:42:20 +0000 (0:00:00.239)       0:00:05.347 ****
2026-09-17 20:42:21.861522 | instance | ok: [instance]
2026-09-17 20:42:21.861603 | instance |
2026-09-17 20:42:21.861665 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-17 20:42:21.861803 | instance | Thursday 17 September 2026  20:42:21 +0000 (0:00:01.287)       0:00:06.635 ****
2026-09-17 20:42:21.926257 | instance | ok: [instance] => {
2026-09-17 20:42:21.926334 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-17 20:42:21.926747 | instance | }
2026-09-17 20:42:21.926785 | instance |
2026-09-17 20:42:21.926790 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-17 20:42:21.926796 | instance | Thursday 17 September 2026  20:42:21 +0000 (0:00:00.064)       0:00:06.700 ****
2026-09-17 20:42:22.680309 | instance | changed: [instance]
2026-09-17 20:42:22.681320 | instance |
2026-09-17 20:42:22.681357 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-17 20:42:22.681372 | instance | Thursday 17 September 2026  20:42:22 +0000 (0:00:00.753)       0:00:07.453 ****
2026-09-17 20:42:25.551187 | instance | changed: [instance]
2026-09-17 20:42:25.551262 | instance |
2026-09-17 20:42:25.551632 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-17 20:42:25.551679 | instance | Thursday 17 September 2026  20:42:25 +0000 (0:00:02.871)       0:00:10.324 ****
2026-09-17 20:42:25.589925 | instance | skipping: [instance]
2026-09-17 20:42:25.590192 | instance |
2026-09-17 20:42:25.590781 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-17 20:42:25.591163 | instance | Thursday 17 September 2026  20:42:25 +0000 (0:00:00.038)       0:00:10.363 ****
2026-09-17 20:42:25.625271 | instance | skipping: [instance]
2026-09-17 20:42:25.625451 | instance |
2026-09-17 20:42:25.625787 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-17 20:42:25.626106 | instance | Thursday 17 September 2026  20:42:25 +0000 (0:00:00.035)       0:00:10.398 ****
2026-09-17 20:42:25.659273 | instance | skipping: [instance]
2026-09-17 20:42:25.659646 | instance |
2026-09-17 20:42:25.660057 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-17 20:42:25.660438 | instance | Thursday 17 September 2026  20:42:25 +0000 (0:00:00.033)       0:00:10.432 ****
2026-09-17 20:42:31.604451 | instance | changed: [instance]
2026-09-17 20:42:31.605335 | instance |
2026-09-17 20:42:31.605409 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-17 20:42:31.605422 | instance | Thursday 17 September 2026  20:42:31 +0000 (0:00:05.944)       0:00:16.377 ****
2026-09-17 20:42:32.204372 | instance | changed: [instance]
2026-09-17 20:42:32.204451 | instance |
2026-09-17 20:42:32.204714 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-17 20:42:32.204746 | instance | Thursday 17 September 2026  20:42:32 +0000 (0:00:00.601)       0:00:16.978 ****
2026-09-17 20:42:33.138291 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-17 20:42:33.140306 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-17 20:42:33.140362 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-17 20:42:33.140384 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-17 20:42:33.140394 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-17 20:42:33.140404 | instance |
2026-09-17 20:42:33.140413 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-17 20:42:33.140422 | instance | Thursday 17 September 2026  20:42:33 +0000 (0:00:00.932)       0:00:17.911 ****
2026-09-17 20:42:33.759871 | instance | changed: [instance]
2026-09-17 20:42:33.760791 | instance |
2026-09-17 20:42:33.760901 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-17 20:42:33.760910 | instance | Thursday 17 September 2026  20:42:33 +0000 (0:00:00.594)       0:00:18.506 ****
2026-09-17 20:42:33.760916 | instance |
2026-09-17 20:42:33.760922 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-17 20:42:33.760927 | instance | Thursday 17 September 2026  20:42:33 +0000 (0:00:00.027)       0:00:18.533 ****
2026-09-17 20:42:34.726456 | instance | ok: [instance]
2026-09-17 20:42:34.726540 | instance |
2026-09-17 20:42:34.726787 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-17 20:42:34.726803 | instance | Thursday 17 September 2026  20:42:34 +0000 (0:00:00.966)       0:00:19.500 ****
2026-09-17 20:42:35.177468 | instance | changed: [instance]
2026-09-17 20:42:35.178260 | instance |
2026-09-17 20:42:35.178311 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-17 20:42:35.178323 | instance | Thursday 17 September 2026  20:42:35 +0000 (0:00:00.450)       0:00:19.950 ****
2026-09-17 20:42:35.799801 | instance | changed: [instance]
2026-09-17 20:42:35.800294 | instance |
2026-09-17 20:42:35.800338 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-17 20:42:35.800347 | instance | Thursday 17 September 2026  20:42:35 +0000 (0:00:00.622)       0:00:20.573 ****
2026-09-17 20:42:36.033005 | instance | ok: [instance]
2026-09-17 20:42:36.033093 | instance |
2026-09-17 20:42:36.033167 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-17 20:42:36.033284 | instance | Thursday 17 September 2026  20:42:36 +0000 (0:00:00.233)       0:00:20.807 ****
2026-09-17 20:42:36.110972 | instance | ok: [instance] => {
2026-09-17 20:42:36.112272 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-17 20:42:36.112331 | instance | }
2026-09-17 20:42:36.112343 | instance |
2026-09-17 20:42:36.112353 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-17 20:42:36.112364 | instance | Thursday 17 September 2026  20:42:36 +0000 (0:00:00.077)       0:00:20.884 ****
2026-09-17 20:42:37.991914 | instance | changed: [instance]
2026-09-17 20:42:37.992595 | instance |
2026-09-17 20:42:37.992648 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-17 20:42:37.992661 | instance | Thursday 17 September 2026  20:42:37 +0000 (0:00:01.880)       0:00:22.765 ****
2026-09-17 20:42:42.376479 | instance | changed: [instance]
2026-09-17 20:42:42.376558 | instance |
2026-09-17 20:42:42.376924 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-17 20:42:42.376972 | instance | Thursday 17 September 2026  20:42:42 +0000 (0:00:04.385)       0:00:27.150 ****
2026-09-17 20:42:43.468669 | instance | ok: [instance]
2026-09-17 20:42:43.468737 | instance |
2026-09-17 20:42:43.468756 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-17 20:42:43.468771 | instance | Thursday 17 September 2026  20:42:43 +0000 (0:00:01.089)       0:00:28.240 ****
2026-09-17 20:42:43.806365 | instance | changed: [instance]
2026-09-17 20:42:43.806452 | instance |
2026-09-17 20:42:43.806734 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-17 20:42:43.806772 | instance | Thursday 17 September 2026  20:42:43 +0000 (0:00:00.339)       0:00:28.580 ****
2026-09-17 20:42:44.299293 | instance | changed: [instance]
2026-09-17 20:42:44.299375 | instance |
2026-09-17 20:42:44.299655 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-17 20:42:44.299696 | instance | Thursday 17 September 2026  20:42:44 +0000 (0:00:00.493)       0:00:29.073 ****
2026-09-17 20:42:44.896555 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-17 20:42:44.896610 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-17 20:42:44.897079 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-17 20:42:44.897094 | instance |
2026-09-17 20:42:44.897100 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-17 20:42:44.897104 | instance | Thursday 17 September 2026  20:42:44 +0000 (0:00:00.597)       0:00:29.670 ****
2026-09-17 20:42:45.415608 | instance | changed: [instance]
2026-09-17 20:42:45.416406 | instance |
2026-09-17 20:42:45.416598 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-17 20:42:45.416612 | instance | Thursday 17 September 2026  20:42:45 +0000 (0:00:00.518)       0:00:30.188 ****
2026-09-17 20:42:45.932546 | instance | changed: [instance]
2026-09-17 20:42:45.932623 | instance |
2026-09-17 20:42:45.933167 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-17 20:42:45.933216 | instance | Thursday 17 September 2026  20:42:45 +0000 (0:00:00.503)       0:00:30.691 ****
2026-09-17 20:42:45.933222 | instance |
2026-09-17 20:42:45.933227 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-17 20:42:45.933235 | instance | Thursday 17 September 2026  20:42:45 +0000 (0:00:00.014)       0:00:30.706 ****
2026-09-17 20:42:46.659253 | instance | ok: [instance]
2026-09-17 20:42:46.659358 | instance |
2026-09-17 20:42:46.659523 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-17 20:42:46.659685 | instance | Thursday 17 September 2026  20:42:46 +0000 (0:00:00.726)       0:00:31.432 ****
2026-09-17 20:42:47.557592 | instance | changed: [instance]
2026-09-17 20:42:47.557796 | instance |
2026-09-17 20:42:47.558085 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-17 20:42:47.558332 | instance | Thursday 17 September 2026  20:42:47 +0000 (0:00:00.898)       0:00:32.331 ****
2026-09-17 20:42:48.190777 | instance | changed: [instance]
2026-09-17 20:42:48.191002 | instance |
2026-09-17 20:42:48.191288 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-17 20:42:48.191601 | instance | Thursday 17 September 2026  20:42:48 +0000 (0:00:00.633)       0:00:32.964 ****
2026-09-17 20:42:48.245403 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-17 20:42:48.245638 | instance |
2026-09-17 20:42:48.245944 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-17 20:42:48.246222 | instance | Thursday 17 September 2026  20:42:48 +0000 (0:00:00.054)       0:00:33.018 ****
2026-09-17 20:42:53.462081 | instance | changed: [instance]
2026-09-17 20:42:53.462143 | instance |
2026-09-17 20:42:53.462155 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-17 20:42:53.462165 | instance | Thursday 17 September 2026  20:42:53 +0000 (0:00:05.215)       0:00:38.234 ****
2026-09-17 20:42:54.155685 | instance | ok: [instance] => (item=chronyd)
2026-09-17 20:42:54.155761 | instance | ok: [instance] => (item=sshd)
2026-09-17 20:42:54.156106 | instance |
2026-09-17 20:42:54.156219 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-17 20:42:54.156225 | instance | Thursday 17 September 2026  20:42:54 +0000 (0:00:00.695)       0:00:38.929 ****
2026-09-17 20:42:55.892085 | instance | changed: [instance]
2026-09-17 20:42:55.892793 | instance |
2026-09-17 20:42:55.892951 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-09-17 20:42:55.892964 | instance | Thursday 17 September 2026  20:42:55 +0000 (0:00:01.735)       0:00:40.665 ****
2026-09-17 20:42:56.123809 | instance | ok: [instance]
2026-09-17 20:42:56.124269 | instance |
2026-09-17 20:42:56.124291 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-09-17 20:42:56.124299 | instance | Thursday 17 September 2026  20:42:56 +0000 (0:00:00.231)       0:00:40.897 ****
2026-09-17 20:42:56.581610 | instance | changed: [instance]
2026-09-17 20:42:56.581667 | instance |
2026-09-17 20:42:56.581923 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-09-17 20:42:56.581938 | instance | Thursday 17 September 2026  20:42:56 +0000 (0:00:00.458)       0:00:41.355 ****
2026-09-17 20:42:56.954301 | instance | changed: [instance]
2026-09-17 20:42:56.954416 | instance |
2026-09-17 20:42:56.954825 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-09-17 20:42:56.954870 | instance | Thursday 17 September 2026  20:42:56 +0000 (0:00:00.372)       0:00:41.727 ****
2026-09-17 20:42:57.077648 | instance | ok: [instance]
2026-09-17 20:42:57.078407 | instance |
2026-09-17 20:42:57.078462 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-09-17 20:42:57.078475 | instance | Thursday 17 September 2026  20:42:57 +0000 (0:00:00.122)       0:00:41.850 ****
2026-09-17 20:42:57.333934 | instance | ok: [instance] => (item=instance)
2026-09-17 20:42:57.334019 | instance |
2026-09-17 20:42:57.334319 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-09-17 20:42:57.334357 | instance | Thursday 17 September 2026  20:42:57 +0000 (0:00:00.256)       0:00:42.107 ****
2026-09-17 20:42:57.408520 | instance | ok: [instance]
2026-09-17 20:42:57.409262 | instance |
2026-09-17 20:42:57.409314 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-09-17 20:42:57.409327 | instance | Thursday 17 September 2026  20:42:57 +0000 (0:00:00.074)       0:00:42.181 ****
2026-09-17 20:42:57.510133 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-09-17 20:42:57.510229 | instance |
2026-09-17 20:42:57.510310 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-09-17 20:42:57.510499 | instance | Thursday 17 September 2026  20:42:57 +0000 (0:00:00.102)       0:00:42.283 ****
2026-09-17 20:42:57.850105 | instance | changed: [instance]
2026-09-17 20:42:57.851181 | instance |
2026-09-17 20:42:57.851233 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-09-17 20:42:57.851241 | instance | Thursday 17 September 2026  20:42:57 +0000 (0:00:00.339)       0:00:42.623 ****
2026-09-17 20:42:58.588901 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-09-17 20:42:58.588959 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-09-17 20:42:58.589427 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-09-17 20:42:58.589464 | instance |
2026-09-17 20:42:58.589470 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-09-17 20:42:58.589475 | instance | Thursday 17 September 2026  20:42:58 +0000 (0:00:00.739)       0:00:43.363 ****
2026-09-17 20:44:41.474260 | instance | ok: [instance]
2026-09-17 20:44:41.474458 | instance |
2026-09-17 20:44:41.474973 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-09-17 20:44:41.475004 | instance | Thursday 17 September 2026  20:44:41 +0000 (0:01:42.884)       0:02:26.247 ****
2026-09-17 20:44:41.703198 | instance | changed: [instance]
2026-09-17 20:44:41.703344 | instance |
2026-09-17 20:44:41.703510 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-09-17 20:44:41.703668 | instance | Thursday 17 September 2026  20:44:41 +0000 (0:00:00.229)       0:02:26.476 ****
2026-09-17 20:44:41.746001 | instance | ok: [instance]
2026-09-17 20:44:41.746082 | instance |
2026-09-17 20:44:41.746195 | instance | TASK [Install Ceph host] *******************************************************
2026-09-17 20:44:41.746305 | instance | Thursday 17 September 2026  20:44:41 +0000 (0:00:00.043)       0:02:26.519 ****
2026-09-17 20:44:41.879180 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-09-17 20:44:41.879246 | instance |
2026-09-17 20:44:41.879336 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-09-17 20:44:41.879441 | instance | Thursday 17 September 2026  20:44:41 +0000 (0:00:00.133)       0:02:26.653 ****
2026-09-17 20:44:43.496792 | instance | ok: [instance]
2026-09-17 20:44:43.497012 | instance |
2026-09-17 20:44:43.497258 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-09-17 20:44:43.497418 | instance | Thursday 17 September 2026  20:44:43 +0000 (0:00:01.617)       0:02:28.270 ****
2026-09-17 20:44:43.561974 | instance | ok: [instance] => (item=instance)
2026-09-17 20:44:43.562072 | instance |
2026-09-17 20:44:43.562191 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-09-17 20:44:43.562308 | instance | Thursday 17 September 2026  20:44:43 +0000 (0:00:00.065)       0:02:28.336 ****
2026-09-17 20:44:43.916235 | instance | ok: [instance]
2026-09-17 20:44:43.916326 | instance |
2026-09-17 20:44:43.916453 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-09-17 20:44:43.916568 | instance | Thursday 17 September 2026  20:44:43 +0000 (0:00:00.354)       0:02:28.690 ****
2026-09-17 20:44:46.392806 | instance | ok: [instance]
2026-09-17 20:44:46.392909 | instance |
2026-09-17 20:44:46.392926 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-09-17 20:44:46.392940 | instance | Thursday 17 September 2026  20:44:46 +0000 (0:00:02.474)       0:02:31.164 ****
2026-09-17 20:45:31.910651 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster)
2026-09-17 20:45:31.911504 | 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_ndk22xyv/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_ndk22xyv/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_ndk22xyv/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_ndk22xyv/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-17T20:45:31.695+0000 7feac7a20640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgANUaxqZNtOHiAA+FVJbybeI3FkgL645tDs/A4A3OpOjxWPiVl5M6KLO/Q=: Malformed input [buffer:3]\n2026-09-17T20:45:31.695+0000 7feac7a20640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-17T20:45:31.699+0000 7feac7a20640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgANUaxqZNtOHiAA+FVJbybeI3FkgL645tDs/A4A3OpOjxWPiVl5M6KLO/Q=: Malformed input [buffer:3]\n2026-09-17T20:45:31.699+0000 7feac7a20640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-17T20:45:31.699+0000 7feac7a20640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgANUaxqZNtOHiAA+FVJbybeI3FkgL645tDs/A4A3OpOjxWPiVl5M6KLO/Q=: Malformed input [buffer:3]\n2026-09-17T20:45:31.699+0000 7feac7a20640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-17T20:45:31.699+0000 7feac7a20640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1}
2026-09-17 20:45:31.914491 | instance |
2026-09-17 20:45:31.915226 | instance | PLAY RECAP *********************************************************************
2026-09-17 20:45:31.915311 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=7    rescued=0    ignored=0
2026-09-17 20:45:31.919216 | instance |
2026-09-17 20:45:31.919277 | instance | Thursday 17 September 2026  20:45:31 +0000 (0:00:45.523)       0:03:16.688 ****
2026-09-17 20:45:31.919289 | instance | ===============================================================================
2026-09-17 20:45:31.919298 | instance | vexxhost.ceph.mon : Run Bootstrap command ----------------------------- 102.88s
2026-09-17 20:45:31.919307 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 45.52s
2026-09-17 20:45:31.919316 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.94s
2026-09-17 20:45:31.919325 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.22s
2026-09-17 20:45:31.919333 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.39s
2026-09-17 20:45:31.919342 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.87s
2026-09-17 20:45:31.919369 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.47s
2026-09-17 20:45:31.919378 | instance | vexxhost.containers.download_artifact : Download item ------------------- 1.88s
2026-09-17 20:45:31.919387 | instance | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 1.74s
2026-09-17 20:45:31.919395 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.69s
2026-09-17 20:45:31.919404 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.62s
2026-09-17 20:45:31.919413 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.29s
2026-09-17 20:45:31.919421 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.09s
2026-09-17 20:45:31.919453 | instance | Gathering Facts --------------------------------------------------------- 1.01s
2026-09-17 20:45:31.919467 | instance | Gathering Facts --------------------------------------------------------- 0.95s
2026-09-17 20:45:31.919631 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.94s
2026-09-17 20:45:31.919958 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.93s
2026-09-17 20:45:31.920248 | instance | Gathering Facts --------------------------------------------------------- 0.92s
2026-09-17 20:45:31.920630 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.90s
2026-09-17 20:45:31.920929 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.75s
2026-09-17 20:45:32.041503 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-17 20:45:32.041852 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-17 20:45:32.042069 | instance | ERROR    Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-17 20:45:32.403796 | instance | ERROR
2026-09-17 20:45:32.403938 | instance | {
2026-09-17 20:45:32.403977 | instance |   "delta": "0:04:07.170543",
2026-09-17 20:45:32.404006 | instance |   "end": "2026-09-17 20:45:32.106133",
2026-09-17 20:45:32.404032 | instance |   "msg": "non-zero return code",
2026-09-17 20:45:32.404056 | instance |   "rc": 2,
2026-09-17 20:45:32.404080 | instance |   "start": "2026-09-17 20:41:24.935590"
2026-09-17 20:45:32.404105 | instance | }
failure
2026-09-17 20:45:32.410685 | 
2026-09-17 20:45:32.410787 | PLAY RECAP
2026-09-17 20:45:32.410846 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-17 20:45:32.410876 | 
2026-09-17 20:45:32.549673 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-17 20:45:32.551593 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-17 20:45:33.173849 | 
2026-09-17 20:45:33.173981 | PLAY [all]
2026-09-17 20:45:33.187926 | 
2026-09-17 20:45:33.188051 | TASK [gather-host-logs : creating directory for system status]
2026-09-17 20:45:33.581458 | instance | changed
2026-09-17 20:45:33.587384 | 
2026-09-17 20:45:33.587480 | TASK [gather-host-logs : Get logs for each host]
2026-09-17 20:45:33.934738 | instance | + systemd-cgls --full --all --no-pager
2026-09-17 20:45:33.948803 | instance | + ip addr
2026-09-17 20:45:33.950620 | instance | + ip route
2026-09-17 20:45:33.952733 | instance | + lsblk
2026-09-17 20:45:33.957162 | instance | + mount
2026-09-17 20:45:33.960042 | instance | + docker images
2026-09-17 20:45:33.976175 | instance | + brctl show
2026-09-17 20:45:33.976711 | instance | /bin/bash: line 8: brctl: command not found
2026-09-17 20:45:33.976968 | instance | + ip neigh
2026-09-17 20:45:33.978769 | instance | + bridge fdb show
2026-09-17 20:45:33.982127 | instance | + ps aux --sort=-%mem
2026-09-17 20:45:33.995494 | instance | + dpkg -l
2026-09-17 20:45:34.006822 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-17 20:45:34.007321 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-17 20:45:34.025312 | instance | + '[' '!' -z '' ']'
2026-09-17 20:45:34.128113 | instance | ok: Runtime: 0:00:00.094642
2026-09-17 20:45:34.138763 | 
2026-09-17 20:45:34.138897 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-17 20:45:34.874018 | instance | changed:
2026-09-17 20:45:34.874319 | instance | created directory /var/lib/zuul/builds/2d073a8390234bcc93d492796e7bb29d/work/logs/instance
2026-09-17 20:45:34.874378 | instance | cd+++++++++ system/
2026-09-17 20:45:34.874422 | instance | >f+++++++++ system/brctl-show.txt
2026-09-17 20:45:34.874462 | instance | >f+++++++++ system/bridge-fdb.txt
2026-09-17 20:45:34.874500 | instance | >f+++++++++ system/docker-images.txt
2026-09-17 20:45:34.874543 | instance | >f+++++++++ system/ip-addr.txt
2026-09-17 20:45:34.874584 | instance | >f+++++++++ system/ip-neigh.txt
2026-09-17 20:45:34.874622 | instance | >f+++++++++ system/ip-route.txt
2026-09-17 20:45:34.874664 | instance | >f+++++++++ system/lsblk.txt
2026-09-17 20:45:34.874702 | instance | >f+++++++++ system/mount.txt
2026-09-17 20:45:34.874738 | instance | >f+++++++++ system/packages.txt
2026-09-17 20:45:34.874774 | instance | >f+++++++++ system/ps.txt
2026-09-17 20:45:34.874808 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-17 20:45:34.893018 | 
2026-09-17 20:45:34.893177 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-17 20:45:35.099199 | instance | changed: "values"
2026-09-17 20:45:35.269246 | instance | changed: "releases"
2026-09-17 20:45:35.282638 | 
2026-09-17 20:45:35.282867 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-17 20:45:35.524435 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-17 20:45:35.829575 | instance | ok: Runtime: 0:00:00.003086
2026-09-17 20:45:35.835862 | 
2026-09-17 20:45:35.835957 | TASK [helm-release-status : Downloads logs to executor]
2026-09-17 20:45:36.390610 | instance | changed:
2026-09-17 20:45:36.390907 | instance | cd+++++++++ helm/
2026-09-17 20:45:36.390960 | instance | cd+++++++++ helm/releases/
2026-09-17 20:45:36.391002 | instance | cd+++++++++ helm/values/
2026-09-17 20:45:36.408428 | 
2026-09-17 20:45:36.408637 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-17 20:45:36.614474 | instance | changed
2026-09-17 20:45:36.624820 | 
2026-09-17 20:45:36.625084 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-17 20:45:36.910153 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-17 20:45:36.910723 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-17 20:45:36.916320 | instance | environment: line 1: kubectl: command not found
2026-09-17 20:45:36.918072 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-17 20:45:36.918179 | instance | environment: line 1: kubectl: command not found
2026-09-17 20:45:36.919692 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-17 20:45:36.921983 | instance | environment: line 1: kubectl: command not found
2026-09-17 20:45:36.923589 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-17 20:45:36.924084 | instance | environment: line 1: kubectl: command not found
2026-09-17 20:45:36.925150 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-17 20:45:36.927363 | instance | environment: line 1: kubectl: command not found
2026-09-17 20:45:36.928564 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-17 20:45:37.170457 | instance | ok: Runtime: 0:00:00.027180
2026-09-17 20:45:37.176827 | 
2026-09-17 20:45:37.176919 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-17 20:45:37.403081 | instance | changed
2026-09-17 20:45:37.409647 | 
2026-09-17 20:45:37.409757 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-17 20:45:37.664239 | instance | environment: line 5: kubectl: command not found
2026-09-17 20:45:37.664565 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-17 20:45:37.664942 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-17 20:45:37.665099 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-17 20:45:37.946534 | instance | ok: Runtime: 0:00:00.006350
2026-09-17 20:45:37.953429 | 
2026-09-17 20:45:37.953599 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-17 20:45:38.455871 | instance | changed:
2026-09-17 20:45:38.456178 | instance | cd+++++++++ objects/
2026-09-17 20:45:38.456231 | instance | cd+++++++++ objects/cluster/
2026-09-17 20:45:38.456273 | instance | cd+++++++++ objects/namespaced/
2026-09-17 20:45:38.475501 | 
2026-09-17 20:45:38.475734 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-17 20:45:38.680849 | instance | changed
2026-09-17 20:45:38.686069 | 
2026-09-17 20:45:38.686181 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-17 20:45:38.904781 | instance | changed
2026-09-17 20:45:38.914516 | 
2026-09-17 20:45:38.914699 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-17 20:45:39.152142 | instance | environment: line 3: kubectl: command not found
2026-09-17 20:45:39.457436 | instance | ok: Runtime: 0:00:00.008346
2026-09-17 20:45:39.464372 | 
2026-09-17 20:45:39.464538 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-17 20:45:39.969754 | instance | changed:
2026-09-17 20:45:39.970123 | instance | cd+++++++++ pod-logs/
2026-09-17 20:45:39.970194 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-17 20:45:39.990228 | 
2026-09-17 20:45:39.990435 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-17 20:45:40.206251 | instance | changed
2026-09-17 20:45:40.212198 | 
2026-09-17 20:45:40.212306 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-17 20:45:40.468596 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-17 20:45:40.753559 | instance | ok: Runtime: 0:00:00.053284
2026-09-17 20:45:40.762665 | 
2026-09-17 20:45:40.762818 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-17 20:45:41.019847 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-17 20:45:41.064597 | instance | ceph-mgr endpoints:
2026-09-17 20:45:41.315568 | instance | ok: Runtime: 0:00:00.049958
2026-09-17 20:45:41.326176 | 
2026-09-17 20:45:41.326352 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-17 20:45:41.562936 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-17 20:45:41.875116 | instance | ok: Runtime: 0:00:00.051357
2026-09-17 20:45:41.887550 | 
2026-09-17 20:45:41.887727 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-17 20:45:42.399772 | instance | changed: cd+++++++++ prometheus/
2026-09-17 20:45:42.411505 | 
2026-09-17 20:45:42.411638 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-17 20:45:42.624034 | instance | changed
2026-09-17 20:45:42.633894 | 
2026-09-17 20:45:42.634082 | TASK [gather-selenium-data : Get selenium data]
2026-09-17 20:45:42.928178 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-17 20:45:42.929656 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-17 20:45:43.181789 | instance | ERROR
2026-09-17 20:45:43.182048 | instance | {
2026-09-17 20:45:43.182108 | instance |   "delta": "0:00:00.006646",
2026-09-17 20:45:43.182150 | instance |   "end": "2026-09-17 20:45:42.930036",
2026-09-17 20:45:43.182187 | instance |   "msg": "non-zero return code",
2026-09-17 20:45:43.182222 | instance |   "rc": 1,
2026-09-17 20:45:43.182258 | instance |   "start": "2026-09-17 20:45:42.923390"
2026-09-17 20:45:43.182307 | instance | }
2026-09-17 20:45:43.182355 | instance | ERROR: Ignoring Errors
2026-09-17 20:45:43.191734 | 
2026-09-17 20:45:43.191863 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-17 20:45:43.711703 | instance | changed: cd+++++++++ selenium/
2026-09-17 20:45:43.722118 | 
2026-09-17 20:45:43.722220 | PLAY RECAP
2026-09-17 20:45:43.722332 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-17 20:45:43.722381 | 
2026-09-17 20:45:43.901502 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-17 20:45:43.905700 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-17 20:45:44.469844 | 
2026-09-17 20:45:44.469993 | PLAY [all]
2026-09-17 20:45:44.480902 | 
2026-09-17 20:45:44.481036 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-17 20:45:44.544744 | instance | skipping: Conditional result was False
2026-09-17 20:45:44.551331 | 
2026-09-17 20:45:44.551430 | TASK [fetch-output : Set log path for single node]
2026-09-17 20:45:44.589211 | instance | ok
2026-09-17 20:45:44.597568 | 
2026-09-17 20:45:44.597705 | LOOP [fetch-output : Ensure local output dirs]
2026-09-17 20:45:44.980477 | instance -> localhost | ok: "/var/lib/zuul/builds/2d073a8390234bcc93d492796e7bb29d/work/logs"
2026-09-17 20:45:45.173120 | instance -> localhost | changed: "/var/lib/zuul/builds/2d073a8390234bcc93d492796e7bb29d/work/artifacts"
2026-09-17 20:45:45.388106 | instance -> localhost | changed: "/var/lib/zuul/builds/2d073a8390234bcc93d492796e7bb29d/work/docs"
2026-09-17 20:45:45.414026 | 
2026-09-17 20:45:45.414217 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-17 20:45:46.161802 | instance | changed: .d..t...... ./
2026-09-17 20:45:46.162144 | instance | changed: All items complete
2026-09-17 20:45:46.162208 | 
2026-09-17 20:45:46.679437 | instance | changed: .d..t...... ./
2026-09-17 20:45:47.192438 | instance | changed: .d..t...... ./
2026-09-17 20:45:47.223997 | 
2026-09-17 20:45:47.224139 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-17 20:45:47.627245 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007416
2026-09-17 20:45:47.867180 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006818
2026-09-17 20:45:47.890190 | 
2026-09-17 20:45:47.890376 | PLAY [all]
2026-09-17 20:45:47.898976 | 
2026-09-17 20:45:47.899076 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-17 20:45:48.358702 | instance | changed
2026-09-17 20:45:48.365256 | 
2026-09-17 20:45:48.365347 | PLAY RECAP
2026-09-17 20:45:48.365400 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-17 20:45:48.365420 | 
2026-09-17 20:45:48.552636 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-17 20:45:48.555511 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-17 20:45:49.223378 | 
2026-09-17 20:45:49.223588 | PLAY [localhost]
2026-09-17 20:45:49.234401 | 
2026-09-17 20:45:49.234521 | TASK [Generate Zuul manifest]
2026-09-17 20:45:49.252409 | localhost | ok
2026-09-17 20:45:49.267147 | 
2026-09-17 20:45:49.267298 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-17 20:45:49.653778 | localhost | changed
2026-09-17 20:45:49.674659 | 
2026-09-17 20:45:49.674928 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-17 20:45:49.708258 | localhost | ok
2026-09-17 20:45:49.721998 | 
2026-09-17 20:45:49.722222 | TASK [Upload logs]
2026-09-17 20:45:49.747096 | localhost | ok
2026-09-17 20:45:49.814236 | 
2026-09-17 20:45:49.814416 | TASK [Set zuul-log-path fact]
2026-09-17 20:45:49.831751 | localhost | ok
2026-09-17 20:45:49.842541 | 
2026-09-17 20:45:49.842702 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-17 20:45:49.872092 | localhost | ok
2026-09-17 20:45:49.881309 | 
2026-09-17 20:45:49.881485 | TASK [upload-logs : Create log directories]
2026-09-17 20:45:50.346391 | localhost | changed
2026-09-17 20:45:50.354929 | 
2026-09-17 20:45:50.355056 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-17 20:45:50.872013 | localhost -> localhost | ok: Runtime: 0:00:00.005546
2026-09-17 20:45:50.881580 | 
2026-09-17 20:45:50.881741 | TASK [upload-logs : Upload logs to log server]
2026-09-17 20:45:51.536054 | localhost | Output suppressed because no_log was given
2026-09-17 20:45:51.585542 | 
2026-09-17 20:45:51.585671 | LOOP [upload-logs : Compress console log and json output]
2026-09-17 20:45:51.647280 | localhost | skipping: Conditional result was False
2026-09-17 20:45:51.656190 | localhost | skipping: Conditional result was False
2026-09-17 20:45:51.668315 | 
2026-09-17 20:45:51.668582 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-17 20:45:51.710790 | localhost | skipping: Conditional result was False
2026-09-17 20:45:51.711188 | 
2026-09-17 20:45:51.714701 | localhost | skipping: Conditional result was False
2026-09-17 20:45:51.731578 | 
2026-09-17 20:45:51.731765 | LOOP [upload-logs : Upload console log and json output]