2026-08-20 16:46:57.754808 | Job console starting
2026-08-20 16:46:57.764338 | Updating git repos
2026-08-20 16:47:00.604669 | Cloning repos into workspace
2026-08-20 16:47:15.945320 | Restoring repo states
2026-08-20 16:47:29.385953 | Merging changes
2026-08-20 16:47:30.469712 | Checking out repos
2026-08-20 16:47:31.839522 | Preparing playbooks
2026-08-20 16:47:33.798356 | Running Ansible setup
2026-08-20 16:47:37.772555 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-20 16:47:38.457233 | 
2026-08-20 16:47:38.457406 | PLAY [localhost]
2026-08-20 16:47:38.466620 | 
2026-08-20 16:47:38.466796 | TASK [Gathering Facts]
2026-08-20 16:47:39.429277 | localhost | ok
2026-08-20 16:47:39.437928 | 
2026-08-20 16:47:39.438012 | TASK [Setup log path fact]
2026-08-20 16:47:39.459014 | localhost | ok
2026-08-20 16:47:39.475041 | 
2026-08-20 16:47:39.475169 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-20 16:47:39.503830 | localhost | ok
2026-08-20 16:47:39.512172 | 
2026-08-20 16:47:39.512299 | TASK [emit-job-header : Print job information]
2026-08-20 16:47:39.552685 | # Job Information
2026-08-20 16:47:39.552884 | Ansible Version: 2.16.18
2026-08-20 16:47:39.552933 | Job: atmosphere-molecule-csi-rbd
2026-08-20 16:47:39.552992 | Pipeline: check
2026-08-20 16:47:39.553022 | Executor: 2d72f0692154
2026-08-20 16:47:39.553048 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3501
2026-08-20 16:47:39.553077 | Event ID: 057af080-9cb6-11f1-8ae3-61fcfd3b4f1c
2026-08-20 16:47:39.556976 | 
2026-08-20 16:47:39.557071 | LOOP [emit-job-header : Print node information]
2026-08-20 16:47:39.659422 | localhost | ok:
2026-08-20 16:47:39.659622 | localhost | # Node Information
2026-08-20 16:47:39.659665 | localhost | Inventory Hostname: instance
2026-08-20 16:47:39.659698 | localhost | Hostname: np0000190226
2026-08-20 16:47:39.659728 | localhost | Username: zuul
2026-08-20 16:47:39.659761 | localhost | Distro: Ubuntu 22.04
2026-08-20 16:47:39.659790 | localhost | Provider: yul1
2026-08-20 16:47:39.659818 | localhost | Region: ca-ymq-1
2026-08-20 16:47:39.659847 | localhost | Label: ubuntu-jammy
2026-08-20 16:47:39.659874 | localhost | Product Name: OpenStack Nova
2026-08-20 16:47:39.659902 | localhost | Interface IP: 199.204.45.47
2026-08-20 16:47:39.695097 | 
2026-08-20 16:47:39.695254 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-20 16:47:40.198123 | localhost -> localhost | changed
2026-08-20 16:47:40.210322 | 
2026-08-20 16:47:40.210539 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-20 16:47:41.162121 | localhost -> localhost | changed
2026-08-20 16:47:41.174086 | 
2026-08-20 16:47:41.174298 | PLAY [all]
2026-08-20 16:47:41.185614 | 
2026-08-20 16:47:41.185700 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-20 16:47:41.414044 | instance -> localhost | ok
2026-08-20 16:47:41.425439 | 
2026-08-20 16:47:41.425516 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-20 16:47:41.461380 | instance | ok
2026-08-20 16:47:41.479470 | instance | included: /var/lib/zuul/builds/89cef8696a7d43efa4364cb877facdbb/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-20 16:47:41.485892 | 
2026-08-20 16:47:41.485968 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-20 16:47:43.439001 | instance -> localhost | Generating public/private rsa key pair.
2026-08-20 16:47:43.439206 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/89cef8696a7d43efa4364cb877facdbb/work/89cef8696a7d43efa4364cb877facdbb_id_rsa
2026-08-20 16:47:43.439238 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/89cef8696a7d43efa4364cb877facdbb/work/89cef8696a7d43efa4364cb877facdbb_id_rsa.pub
2026-08-20 16:47:43.439261 | instance -> localhost | The key fingerprint is:
2026-08-20 16:47:43.439283 | instance -> localhost | SHA256:86FjUgVeJCTuZIJfjX/tJI7I0OpxWtSZJv3JM1pMtec zuul-build-sshkey
2026-08-20 16:47:43.439323 | instance -> localhost | The key's randomart image is:
2026-08-20 16:47:43.439359 | instance -> localhost | +---[RSA 3072]----+
2026-08-20 16:47:43.439385 | instance -> localhost | |      ..+.o      |
2026-08-20 16:47:43.439407 | instance -> localhost | |   . . = +       |
2026-08-20 16:47:43.439427 | instance -> localhost | |  . . * o . .    |
2026-08-20 16:47:43.439448 | instance -> localhost | |   . B + + o .   |
2026-08-20 16:47:43.439468 | instance -> localhost | |    o = S = + .  |
2026-08-20 16:47:43.439488 | instance -> localhost | |     = = % * o   |
2026-08-20 16:47:43.439507 | instance -> localhost | |    o * = @ . E  |
2026-08-20 16:47:43.439527 | instance -> localhost | |   . = o + o     |
2026-08-20 16:47:43.439549 | instance -> localhost | |    o   .        |
2026-08-20 16:47:43.439569 | instance -> localhost | +----[SHA256]-----+
2026-08-20 16:47:43.439621 | instance -> localhost | ok: Runtime: 0:00:01.522420
2026-08-20 16:47:43.446670 | 
2026-08-20 16:47:43.446885 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-20 16:47:43.482871 | instance | ok
2026-08-20 16:47:43.492770 | instance | included: /var/lib/zuul/builds/89cef8696a7d43efa4364cb877facdbb/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-20 16:47:43.500564 | 
2026-08-20 16:47:43.500628 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-20 16:47:43.526460 | instance | skipping: Conditional result was False
2026-08-20 16:47:43.537278 | 
2026-08-20 16:47:43.537398 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-20 16:47:43.985362 | instance | changed
2026-08-20 16:47:43.993209 | 
2026-08-20 16:47:43.993274 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-20 16:47:44.179914 | instance | ok
2026-08-20 16:47:44.192198 | 
2026-08-20 16:47:44.192403 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-20 16:47:44.633996 | instance | changed
2026-08-20 16:47:44.641148 | 
2026-08-20 16:47:44.641256 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-20 16:47:45.087100 | instance | changed
2026-08-20 16:47:45.097236 | 
2026-08-20 16:47:45.097450 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-20 16:47:45.123944 | instance | skipping: Conditional result was False
2026-08-20 16:47:45.135859 | 
2026-08-20 16:47:45.136023 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-20 16:47:45.518348 | instance -> localhost | changed
2026-08-20 16:47:45.534389 | 
2026-08-20 16:47:45.534568 | TASK [add-build-sshkey : Add back temp key]
2026-08-20 16:47:45.834953 | instance -> localhost | Identity added: /var/lib/zuul/builds/89cef8696a7d43efa4364cb877facdbb/work/89cef8696a7d43efa4364cb877facdbb_id_rsa (zuul-build-sshkey)
2026-08-20 16:47:45.835192 | instance -> localhost | ok: Runtime: 0:00:00.013890
2026-08-20 16:47:45.842683 | 
2026-08-20 16:47:45.842776 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-20 16:47:46.194549 | instance | ok
2026-08-20 16:47:46.202127 | 
2026-08-20 16:47:46.202311 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-20 16:47:46.227772 | instance | skipping: Conditional result was False
2026-08-20 16:47:46.243145 | 
2026-08-20 16:47:46.243335 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-20 16:47:46.584816 | instance | ok
2026-08-20 16:47:46.593989 | 
2026-08-20 16:47:46.594179 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-20 16:47:50.048836 | instance | Output suppressed because no_log was given
2026-08-20 16:47:50.059951 | 
2026-08-20 16:47:50.060085 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-20 16:47:50.252910 | instance | ok: "logs"
2026-08-20 16:47:50.253237 | instance | ok: All items complete
2026-08-20 16:47:50.253271 | 
2026-08-20 16:47:50.402164 | instance | ok: "artifacts"
2026-08-20 16:47:50.569980 | instance | ok: "docs"
2026-08-20 16:47:50.593110 | 
2026-08-20 16:47:50.593331 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-20 16:47:50.788570 | instance | changed: "logs"
2026-08-20 16:47:50.946756 | instance | changed: "artifacts"
2026-08-20 16:47:51.111500 | instance | changed: "docs"
2026-08-20 16:47:51.137504 | 
2026-08-20 16:47:51.137702 | PLAY RECAP
2026-08-20 16:47:51.137765 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-20 16:47:51.137795 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-20 16:47:51.137816 | 
2026-08-20 16:47:51.292503 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-20 16:47:51.297557 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-20 16:47:51.950558 | 
2026-08-20 16:47:51.950717 | PLAY [all]
2026-08-20 16:47:51.962360 | 
2026-08-20 16:47:51.962477 | TASK [setup-uv : Extract archive]
2026-08-20 16:47:54.155864 | instance | changed
2026-08-20 16:47:54.161388 | 
2026-08-20 16:47:54.161471 | TASK [setup-uv : Print version]
2026-08-20 16:47:54.558292 | instance | uv 0.8.13
2026-08-20 16:47:54.700686 | instance | ok: Runtime: 0:00:00.013811
2026-08-20 16:47:54.716018 | 
2026-08-20 16:47:54.716159 | TASK [Install Ansible collection siblings]
2026-08-20 16:48:03.709052 | instance | changed:
2026-08-20 16:48:03.709306 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-08-20 16:48:03.709350 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-08-20 16:48:03.709383 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-08-20 16:48:03.709413 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-08-20 16:48:03.709441 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-08-20 16:48:03.709476 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-08-20 16:48:03.709511 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-08-20 16:48:03.709556 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-08-20 16:48:03.709591 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-08-20 16:48:03.709621 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-08-20 16:48:03.709649 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-08-20 16:48:03.709676 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-08-20 16:48:03.709703 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-08-20 16:48:03.709738 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-08-20 16:48:03.709772 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0
2026-08-20 16:48:03.709801 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-08-20 16:48:03.709828 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-08-20 16:48:03.709855 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-08-20 16:48:03.709882 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-08-20 16:48:03.709909 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-08-20 16:48:03.709937 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0
2026-08-20 16:48:03.709965 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-08-20 16:48:03.709995 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-08-20 16:48:03.710044 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-08-20 16:48:03.710126 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0
2026-08-20 16:48:03.710161 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-08-20 16:48:03.710189 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-08-20 16:48:03.710216 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-08-20 16:48:03.710242 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-08-20 16:48:03.710269 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-08-20 16:48:03.710298 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-08-20 16:48:03.710337 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-08-20 16:48:03.710366 | instance | Checked out ansible.netcommon to 1.2.0
2026-08-20 16:48:03.710393 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-08-20 16:48:03.710431 | instance | Starting galaxy collection install process
2026-08-20 16:48:03.710459 | instance | Process install dependency map
2026-08-20 16:48:03.710486 | instance | Starting collection install process
2026-08-20 16:48:03.710519 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-08-20 16:48:03.710555 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-08-20 16:48:03.710584 | instance | ansible.netcommon:* was installed successfully
2026-08-20 16:48:03.710610 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-08-20 16:48:03.710638 | instance | Creating virtual environment at: .venv
2026-08-20 16:48:03.710665 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-08-20 16:48:03.710693 | instance | Downloading netaddr (2.2MiB)
2026-08-20 16:48:03.710747 | instance | Downloading setuptools (1.1MiB)
2026-08-20 16:48:03.710831 | instance | Downloading kubernetes (1.9MiB)
2026-08-20 16:48:03.710865 | instance | Downloading cryptography (4.2MiB)
2026-08-20 16:48:03.710893 | instance | Downloading ansible-core (2.1MiB)
2026-08-20 16:48:03.710920 | instance | Downloading rjsonnet (1.2MiB)
2026-08-20 16:48:03.710948 | instance | Downloading pygments (1.2MiB)
2026-08-20 16:48:03.710976 | instance | Downloading openstacksdk (1.7MiB)
2026-08-20 16:48:03.711059 | instance | Downloading pydantic-core (2.0MiB)
2026-08-20 16:48:03.711123 | instance |    Building pyperclip==1.9.0
2026-08-20 16:48:03.711170 | instance |  Downloading rjsonnet
2026-08-20 16:48:03.711202 | instance |  Downloading pydantic-core
2026-08-20 16:48:03.711230 | instance |  Downloading netaddr
2026-08-20 16:48:03.711258 | instance |  Downloading pygments
2026-08-20 16:48:03.711286 | instance |  Downloading cryptography
2026-08-20 16:48:03.711323 | instance |  Downloading setuptools
2026-08-20 16:48:03.711352 | instance |  Downloading kubernetes
2026-08-20 16:48:03.711373 | instance |  Downloading ansible-core
2026-08-20 16:48:03.711394 | instance |  Downloading openstacksdk
2026-08-20 16:48:03.711411 | instance |       Built pyperclip==1.9.0
2026-08-20 16:48:03.711429 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-08-20 16:48:03.711447 | instance | Installed 83 packages in 46ms
2026-08-20 16:48:03.711465 | instance | Checked out ansible.posix to 1.6.0
2026-08-20 16:48:03.711482 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-08-20 16:48:03.711504 | instance | Starting galaxy collection install process
2026-08-20 16:48:03.711530 | instance | Process install dependency map
2026-08-20 16:48:03.711557 | instance | Starting collection install process
2026-08-20 16:48:03.711576 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-08-20 16:48:03.711594 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-08-20 16:48:03.711611 | instance | ansible.posix:1.6.0 was installed successfully
2026-08-20 16:48:03.711630 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-20 16:48:03.711648 | instance | 2.17.12
2026-08-20 16:48:03.711666 | instance | Checked out ansible.utils to v6.1.0
2026-08-20 16:48:03.711683 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-08-20 16:48:03.711702 | instance | Starting galaxy collection install process
2026-08-20 16:48:03.711727 | instance | Process install dependency map
2026-08-20 16:48:03.711780 | instance | Starting collection install process
2026-08-20 16:48:03.711805 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-08-20 16:48:03.711824 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-08-20 16:48:03.711842 | instance | ansible.utils:6.1.0 was installed successfully
2026-08-20 16:48:03.711860 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-20 16:48:03.711878 | instance | 2.17.12
2026-08-20 16:48:03.711896 | instance | Checked out atmosphere.common to v0.7.0
2026-08-20 16:48:03.711913 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-08-20 16:48:03.711933 | instance | Starting galaxy collection install process
2026-08-20 16:48:03.711958 | instance | Process install dependency map
2026-08-20 16:48:03.712005 | instance | Starting collection install process
2026-08-20 16:48:03.712031 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-08-20 16:48:03.712050 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-08-20 16:48:03.712069 | instance | atmosphere.common:0.7.0 was installed successfully
2026-08-20 16:48:03.712088 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-20 16:48:03.712106 | instance | 2.17.12
2026-08-20 16:48:03.712125 | instance | Checked out community.crypto to 2.2.3
2026-08-20 16:48:03.712143 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto
2026-08-20 16:48:03.712161 | instance | Starting galaxy collection install process
2026-08-20 16:48:03.712179 | instance | Process install dependency map
2026-08-20 16:48:03.712197 | instance | Starting collection install process
2026-08-20 16:48:03.712224 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-08-20 16:48:03.712268 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-08-20 16:48:03.712291 | instance | community.crypto:2.2.3 was installed successfully
2026-08-20 16:48:03.712313 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-20 16:48:03.712333 | instance | 2.17.12
2026-08-20 16:48:03.712351 | instance | Checked out community.general to 7.3.0
2026-08-20 16:48:03.712368 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general
2026-08-20 16:48:03.712392 | instance | Starting galaxy collection install process
2026-08-20 16:48:03.712410 | instance | Process install dependency map
2026-08-20 16:48:03.712428 | instance | Starting collection install process
2026-08-20 16:48:03.712446 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-08-20 16:48:03.712464 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-08-20 16:48:03.712482 | instance | community.general:7.3.0 was installed successfully
2026-08-20 16:48:03.713211 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-20 16:48:03.713243 | instance | 2.17.12
2026-08-20 16:48:03.713270 | instance | Checked out community.mysql to 3.6.0
2026-08-20 16:48:03.713297 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql
2026-08-20 16:48:03.713320 | instance | Starting galaxy collection install process
2026-08-20 16:48:03.713340 | instance | Process install dependency map
2026-08-20 16:48:03.713387 | instance | Starting collection install process
2026-08-20 16:48:03.713418 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-08-20 16:48:03.713459 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-08-20 16:48:03.713490 | instance | community.mysql:3.6.0 was installed successfully
2026-08-20 16:48:03.713517 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-20 16:48:03.713539 | instance | 2.17.12
2026-08-20 16:48:03.713572 | instance | Checked out kubernetes.core to 2.4.0
2026-08-20 16:48:03.713592 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-08-20 16:48:03.713610 | instance | Starting galaxy collection install process
2026-08-20 16:48:03.713628 | instance | Process install dependency map
2026-08-20 16:48:03.713646 | instance | Starting collection install process
2026-08-20 16:48:03.713664 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-08-20 16:48:03.713682 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-08-20 16:48:03.713700 | instance | kubernetes.core:2.4.0 was installed successfully
2026-08-20 16:48:03.713718 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-20 16:48:03.713737 | instance | 2.17.12
2026-08-20 16:48:03.713755 | instance | Checked out openstack.cloud to 2.6.0
2026-08-20 16:48:03.713773 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-08-20 16:48:03.713791 | instance | Starting galaxy collection install process
2026-08-20 16:48:03.713809 | instance | Process install dependency map
2026-08-20 16:48:03.713828 | instance | Starting collection install process
2026-08-20 16:48:03.713877 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-08-20 16:48:03.713906 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-08-20 16:48:03.713926 | instance | openstack.cloud:2.6.0 was installed successfully
2026-08-20 16:48:03.713944 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-20 16:48:03.713962 | instance | 2.17.12
2026-08-20 16:48:03.713989 | instance | Checked out vexxhost.ceph to v4.2.0
2026-08-20 16:48:03.714009 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-08-20 16:48:03.714027 | instance | Starting galaxy collection install process
2026-08-20 16:48:03.714045 | instance | Process install dependency map
2026-08-20 16:48:03.714062 | instance | Starting collection install process
2026-08-20 16:48:03.714157 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-08-20 16:48:03.714184 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-08-20 16:48:03.714203 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-08-20 16:48:03.714222 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-20 16:48:03.714241 | instance | 2.17.12
2026-08-20 16:48:03.714260 | instance | Checked out vexxhost.containers to v1.6.8
2026-08-20 16:48:03.714278 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-08-20 16:48:03.714297 | instance | Starting galaxy collection install process
2026-08-20 16:48:03.714315 | instance | Process install dependency map
2026-08-20 16:48:03.714332 | instance | Starting collection install process
2026-08-20 16:48:03.714350 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-08-20 16:48:03.714369 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-08-20 16:48:03.714387 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-08-20 16:48:03.714405 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-20 16:48:03.714423 | instance | 2.17.12
2026-08-20 16:48:03.714441 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-08-20 16:48:03.714459 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-08-20 16:48:03.714477 | instance | Starting galaxy collection install process
2026-08-20 16:48:03.714495 | instance | Process install dependency map
2026-08-20 16:48:03.714513 | instance | Starting collection install process
2026-08-20 16:48:03.714531 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-08-20 16:48:03.714549 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-08-20 16:48:03.714567 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-08-20 16:48:03.714584 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-20 16:48:03.714601 | instance | 2.17.12
2026-08-20 16:48:03.721262 | 
2026-08-20 16:48:03.721329 | PLAY RECAP
2026-08-20 16:48:03.721386 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-20 16:48:03.721407 | 
2026-08-20 16:48:03.904293 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-20 16:48:03.907312 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-08-20 16:48:04.539123 | 
2026-08-20 16:48:04.539299 | PLAY [all]
2026-08-20 16:48:04.552245 | 
2026-08-20 16:48:04.552425 | TASK [Install "jq" for log collection]
2026-08-20 16:48:14.743876 | instance | changed
2026-08-20 16:48:14.746631 | 
2026-08-20 16:48:14.746720 | PLAY RECAP
2026-08-20 16:48:14.746793 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-20 16:48:14.746880 | 
2026-08-20 16:48:14.918097 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-08-20 16:48:14.920710 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-20 16:48:15.535920 | 
2026-08-20 16:48:15.536089 | PLAY [all]
2026-08-20 16:48:15.549266 | 
2026-08-20 16:48:15.549430 | LOOP [Check inventory directories]
2026-08-20 16:48:15.910031 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-08-20 16:48:16.065390 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-08-20 16:48:16.204391 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-08-20 16:48:16.220052 | 
2026-08-20 16:48:16.220306 | LOOP [Copy inventory file for Zuul]
2026-08-20 16:48:17.011573 | instance | changed:
2026-08-20 16:48:17.011838 | instance | {
2026-08-20 16:48:17.011881 | instance |   "ansible_loop_var": "item",
2026-08-20 16:48:17.011915 | instance |   "changed": false,
2026-08-20 16:48:17.011942 | instance |   "failed": false,
2026-08-20 16:48:17.011973 | instance |   "invocation": {
2026-08-20 16:48:17.011999 | instance |     "module_args": {
2026-08-20 16:48:17.012024 | instance |       "checksum_algorithm": "sha1",
2026-08-20 16:48:17.012049 | instance |       "follow": true,
2026-08-20 16:48:17.012074 | instance |       "get_attributes": true,
2026-08-20 16:48:17.012100 | instance |       "get_checksum": true,
2026-08-20 16:48:17.012126 | instance |       "get_mime": true,
2026-08-20 16:48:17.012151 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-08-20 16:48:17.012212 | instance |     }
2026-08-20 16:48:17.012242 | instance |   },
2026-08-20 16:48:17.012267 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-08-20 16:48:17.012292 | instance |   "stat": {
2026-08-20 16:48:17.012316 | instance |     "atime": 1787244476.503382,
2026-08-20 16:48:17.012342 | instance |     "attr_flags": "e",
2026-08-20 16:48:17.012372 | instance |     "attributes": [
2026-08-20 16:48:17.012409 | instance |       "extents"
2026-08-20 16:48:17.012435 | instance |     ],
2026-08-20 16:48:17.012459 | instance |     "block_size": 4096,
2026-08-20 16:48:17.012483 | instance |     "blocks": 8,
2026-08-20 16:48:17.012507 | instance |     "charset": "unknown",
2026-08-20 16:48:17.012534 | instance |     "ctime": 1787244475.4834015,
2026-08-20 16:48:17.012559 | instance |     "dev": 64513,
2026-08-20 16:48:17.012583 | instance |     "device_type": 0,
2026-08-20 16:48:17.012607 | instance |     "executable": true,
2026-08-20 16:48:17.012631 | instance |     "exists": true,
2026-08-20 16:48:17.012655 | instance |     "gid": 1000,
2026-08-20 16:48:17.012678 | instance |     "gr_name": "zuul",
2026-08-20 16:48:17.012702 | instance |     "inode": 1047799,
2026-08-20 16:48:17.012726 | instance |     "isblk": false,
2026-08-20 16:48:17.012750 | instance |     "ischr": false,
2026-08-20 16:48:17.012773 | instance |     "isdir": true,
2026-08-20 16:48:17.012797 | instance |     "isfifo": false,
2026-08-20 16:48:17.012820 | instance |     "isgid": false,
2026-08-20 16:48:17.012887 | instance |     "islnk": false,
2026-08-20 16:48:17.012925 | instance |     "isreg": false,
2026-08-20 16:48:17.012951 | instance |     "issock": false,
2026-08-20 16:48:17.013009 | instance |     "isuid": false,
2026-08-20 16:48:17.013035 | instance |     "mimetype": "unknown",
2026-08-20 16:48:17.013059 | instance |     "mode": "0755",
2026-08-20 16:48:17.013082 | instance |     "mtime": 1787244475.4834015,
2026-08-20 16:48:17.013106 | instance |     "nlink": 23,
2026-08-20 16:48:17.013128 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-08-20 16:48:17.013152 | instance |     "pw_name": "zuul",
2026-08-20 16:48:17.013177 | instance |     "readable": true,
2026-08-20 16:48:17.013201 | instance |     "rgrp": true,
2026-08-20 16:48:17.013225 | instance |     "roth": true,
2026-08-20 16:48:17.013264 | instance |     "rusr": true,
2026-08-20 16:48:17.013289 | instance |     "size": 4096,
2026-08-20 16:48:17.013313 | instance |     "uid": 1000,
2026-08-20 16:48:17.013337 | instance |     "version": "3943689224",
2026-08-20 16:48:17.013361 | instance |     "wgrp": false,
2026-08-20 16:48:17.013388 | instance |     "woth": false,
2026-08-20 16:48:17.013413 | instance |     "writeable": true,
2026-08-20 16:48:17.013438 | instance |     "wusr": true,
2026-08-20 16:48:17.013461 | instance |     "xgrp": true,
2026-08-20 16:48:17.013484 | instance |     "xoth": true,
2026-08-20 16:48:17.013508 | instance |     "xusr": true
2026-08-20 16:48:17.013531 | instance |   }
2026-08-20 16:48:17.013555 | instance | }
2026-08-20 16:48:17.013604 | instance | ok: All items complete
2026-08-20 16:48:17.013634 | 
2026-08-20 16:48:17.015332 | instance | skipping: Conditional result was False
2026-08-20 16:48:17.016148 | instance | skipping: Conditional result was False
2026-08-20 16:48:17.032322 | 
2026-08-20 16:48:17.032492 | LOOP [Switch "ansible_host" to private IP]
2026-08-20 16:48:17.375912 | instance | changed: 1 replacements made
2026-08-20 16:48:17.378429 | instance | skipping: Conditional result was False
2026-08-20 16:48:17.382064 | instance | skipping: Conditional result was False
2026-08-20 16:48:17.396167 | 
2026-08-20 16:48:17.396344 | TASK [Run Molecule scenario]
2026-08-20 16:48:18.335852 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-08-20 16:48:18.728538 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-08-20 16:48:18.728638 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-08-20 16:48:29.245368 | instance | INFO     [csi > dependency] Executing
2026-08-20 16:48:29.245533 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-08-20 16:48:29.245756 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-08-20 16:48:29.245884 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-08-20 16:48:29.253507 | instance | INFO     [csi > cleanup] Executing
2026-08-20 16:48:29.253838 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-20 16:48:29.261383 | instance | INFO     [csi > destroy] Executing
2026-08-20 16:48:29.261460 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-08-20 16:48:29.261545 | instance | INFO     [csi > destroy] Executed: Successful
2026-08-20 16:48:29.268863 | instance | INFO     [csi > syntax] Executing
2026-08-20 16:48:30.880050 | instance |
2026-08-20 16:48:30.880439 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-08-20 16:48:30.945951 | instance | INFO     [csi > syntax] Executed: Successful
2026-08-20 16:48:30.958976 | instance | INFO     [csi > create] Executing
2026-08-20 16:48:30.960766 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-20 16:48:30.968155 | instance | INFO     [csi > prepare] Executing
2026-08-20 16:48:31.749009 | instance |
2026-08-20 16:48:31.749421 | instance | PLAY [Prepare] *****************************************************************
2026-08-20 16:48:31.749746 | instance |
2026-08-20 16:48:31.750126 | instance | TASK [Gathering Facts] *********************************************************
2026-08-20 16:48:31.750451 | instance | Thursday 20 August 2026  16:48:31 +0000 (0:00:00.028)       0:00:00.028 *******
2026-08-20 16:48:32.891631 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-08-20 16:48:32.891933 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-20 16:48:32.892218 | instance | interpreter could change the meaning of that path. See
2026-08-20 16:48:32.892515 | instance | https://docs.ansible.com/ansible-
2026-08-20 16:48:32.892794 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-08-20 16:48:32.900678 | instance | ok: [instance]
2026-08-20 16:48:32.900935 | instance |
2026-08-20 16:48:32.901217 | instance | TASK [Configure short hostname] ************************************************
2026-08-20 16:48:32.901488 | instance | Thursday 20 August 2026  16:48:32 +0000 (0:00:01.152)       0:00:01.181 *******
2026-08-20 16:48:34.517239 | instance | changed: [instance]
2026-08-20 16:48:34.517505 | instance |
2026-08-20 16:48:34.517885 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-08-20 16:48:34.518254 | instance | Thursday 20 August 2026  16:48:34 +0000 (0:00:01.615)       0:00:02.797 *******
2026-08-20 16:48:34.787964 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-08-20 16:48:34.788245 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-08-20 16:48:34.788612 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-08-20 16:48:34.798137 | instance | changed: [instance]
2026-08-20 16:48:34.798436 | instance |
2026-08-20 16:48:34.798762 | instance | TASK [Purge "snapd" package] ***************************************************
2026-08-20 16:48:34.799069 | instance | Thursday 20 August 2026  16:48:34 +0000 (0:00:00.281)       0:00:03.078 *******
2026-08-20 16:48:35.537847 | instance | ok: [instance]
2026-08-20 16:48:35.537969 | instance |
2026-08-20 16:48:35.538155 | instance | PLAY [Create devices for Ceph] *************************************************
2026-08-20 16:48:35.538322 | instance |
2026-08-20 16:48:35.538501 | instance | TASK [Gathering Facts] *********************************************************
2026-08-20 16:48:35.538683 | instance | Thursday 20 August 2026  16:48:35 +0000 (0:00:00.739)       0:00:03.818 *******
2026-08-20 16:48:36.208222 | instance | ok: [instance]
2026-08-20 16:48:36.208610 | instance |
2026-08-20 16:48:36.208899 | instance | TASK [Install depedencies] *****************************************************
2026-08-20 16:48:36.209181 | instance | Thursday 20 August 2026  16:48:36 +0000 (0:00:00.669)       0:00:04.488 *******
2026-08-20 16:49:01.679168 | instance | changed: [instance]
2026-08-20 16:49:01.679431 | instance |
2026-08-20 16:49:01.679805 | instance | TASK [Start up service] ********************************************************
2026-08-20 16:49:01.680312 | instance | Thursday 20 August 2026  16:49:01 +0000 (0:00:25.470)       0:00:29.959 *******
2026-08-20 16:49:02.221430 | instance | ok: [instance]
2026-08-20 16:49:02.221757 | instance |
2026-08-20 16:49:02.222121 | instance | TASK [Generate lvm.conf] *******************************************************
2026-08-20 16:49:02.222446 | instance | Thursday 20 August 2026  16:49:02 +0000 (0:00:00.542)       0:00:30.501 *******
2026-08-20 16:49:02.523985 | instance | ok: [instance]
2026-08-20 16:49:02.524167 | instance |
2026-08-20 16:49:02.524342 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-08-20 16:49:02.524635 | instance | Thursday 20 August 2026  16:49:02 +0000 (0:00:00.302)       0:00:30.804 *******
2026-08-20 16:49:03.159242 | instance | changed: [instance]
2026-08-20 16:49:03.159293 | instance |
2026-08-20 16:49:03.159305 | instance | TASK [Get list of all loopback devices] ****************************************
2026-08-20 16:49:03.159315 | instance | Thursday 20 August 2026  16:49:03 +0000 (0:00:00.634)       0:00:31.438 *******
2026-08-20 16:49:03.357742 | instance | ok: [instance]
2026-08-20 16:49:03.357862 | instance |
2026-08-20 16:49:03.358223 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-08-20 16:49:03.358261 | instance | Thursday 20 August 2026  16:49:03 +0000 (0:00:00.199)       0:00:31.638 *******
2026-08-20 16:49:03.380678 | instance | skipping: [instance]
2026-08-20 16:49:03.380750 | instance |
2026-08-20 16:49:03.381442 | instance | TASK [Create devices for Ceph] *************************************************
2026-08-20 16:49:03.381474 | instance | Thursday 20 August 2026  16:49:03 +0000 (0:00:00.023)       0:00:31.661 *******
2026-08-20 16:49:03.949996 | instance | changed: [instance] => (item=osd0)
2026-08-20 16:49:03.950067 | instance | changed: [instance] => (item=osd1)
2026-08-20 16:49:03.950084 | instance | changed: [instance] => (item=osd2)
2026-08-20 16:49:03.950098 | instance |
2026-08-20 16:49:03.950113 | instance | TASK [Set permissions on loopback devices] *************************************
2026-08-20 16:49:03.950128 | instance | Thursday 20 August 2026  16:49:03 +0000 (0:00:00.566)       0:00:32.228 *******
2026-08-20 16:49:04.588773 | instance | changed: [instance] => (item=osd0)
2026-08-20 16:49:04.588935 | instance | changed: [instance] => (item=osd1)
2026-08-20 16:49:04.589266 | instance | changed: [instance] => (item=osd2)
2026-08-20 16:49:04.589719 | instance |
2026-08-20 16:49:04.590011 | instance | TASK [Start loop devices] ******************************************************
2026-08-20 16:49:04.590254 | instance | Thursday 20 August 2026  16:49:04 +0000 (0:00:00.640)       0:00:32.869 *******
2026-08-20 16:49:05.258023 | instance | changed: [instance] => (item=osd0)
2026-08-20 16:49:05.258101 | instance | changed: [instance] => (item=osd1)
2026-08-20 16:49:05.258561 | instance | changed: [instance] => (item=osd2)
2026-08-20 16:49:05.258614 | instance |
2026-08-20 16:49:05.258626 | instance | TASK [Create a volume group for each loop device] ******************************
2026-08-20 16:49:05.258640 | instance | Thursday 20 August 2026  16:49:05 +0000 (0:00:00.669)       0:00:33.538 *******
2026-08-20 16:49:08.611359 | instance | changed: [instance] => (item=osd0)
2026-08-20 16:49:08.611935 | instance | changed: [instance] => (item=osd1)
2026-08-20 16:49:08.612034 | instance | changed: [instance] => (item=osd2)
2026-08-20 16:49:08.612049 | instance |
2026-08-20 16:49:08.612069 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-08-20 16:49:08.612219 | instance | Thursday 20 August 2026  16:49:08 +0000 (0:00:03.353)       0:00:36.892 *******
2026-08-20 16:49:10.575859 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-08-20 16:49:10.575922 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-08-20 16:49:10.579894 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-08-20 16:49:10.579966 | instance |
2026-08-20 16:49:10.579978 | instance | PLAY RECAP *********************************************************************
2026-08-20 16:49:10.579989 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-08-20 16:49:10.579998 | instance |
2026-08-20 16:49:10.580007 | instance | Thursday 20 August 2026  16:49:10 +0000 (0:00:01.964)       0:00:38.856 *******
2026-08-20 16:49:10.580017 | instance | ===============================================================================
2026-08-20 16:49:10.580025 | instance | Install depedencies ---------------------------------------------------- 25.47s
2026-08-20 16:49:10.580034 | instance | Create a volume group for each loop device ------------------------------ 3.35s
2026-08-20 16:49:10.580043 | instance | Create a logical volume for each loop device ---------------------------- 1.96s
2026-08-20 16:49:10.580052 | instance | Configure short hostname ------------------------------------------------ 1.62s
2026-08-20 16:49:10.580061 | instance | Gathering Facts --------------------------------------------------------- 1.15s
2026-08-20 16:49:10.580078 | instance | Purge "snapd" package --------------------------------------------------- 0.74s
2026-08-20 16:49:10.580344 | instance | Gathering Facts --------------------------------------------------------- 0.67s
2026-08-20 16:49:10.580718 | instance | Start loop devices ------------------------------------------------------ 0.67s
2026-08-20 16:49:10.581191 | instance | Set permissions on loopback devices ------------------------------------- 0.64s
2026-08-20 16:49:10.581687 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.63s
2026-08-20 16:49:10.581998 | instance | Create devices for Ceph ------------------------------------------------- 0.57s
2026-08-20 16:49:10.582151 | instance | Start up service -------------------------------------------------------- 0.54s
2026-08-20 16:49:10.582301 | instance | Generate lvm.conf ------------------------------------------------------- 0.30s
2026-08-20 16:49:10.582462 | instance | Ensure hostname inside hosts file --------------------------------------- 0.28s
2026-08-20 16:49:10.582623 | instance | Get list of all loopback devices ---------------------------------------- 0.20s
2026-08-20 16:49:10.582775 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s
2026-08-20 16:49:10.646962 | instance | INFO     [csi > prepare] Executed: Successful
2026-08-20 16:49:10.655591 | instance | INFO     [csi > converge] Executing
2026-08-20 16:49:12.163068 | instance |
2026-08-20 16:49:12.163322 | instance | PLAY [Debug CSI driver value] **************************************************
2026-08-20 16:49:12.163509 | instance |
2026-08-20 16:49:12.163700 | instance | TASK [Gathering Facts] *********************************************************
2026-08-20 16:49:12.163916 | instance | Thursday 20 August 2026  16:49:12 +0000 (0:00:00.008)       0:00:00.008 *******
2026-08-20 16:49:13.116742 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-08-20 16:49:13.117241 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-20 16:49:13.117597 | instance | interpreter could change the meaning of that path. See
2026-08-20 16:49:13.117932 | instance | https://docs.ansible.com/ansible-
2026-08-20 16:49:13.118269 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-08-20 16:49:13.126291 | instance | ok: [instance]
2026-08-20 16:49:13.126577 | instance |
2026-08-20 16:49:13.126870 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-08-20 16:49:13.127161 | instance | Thursday 20 August 2026  16:49:13 +0000 (0:00:00.963)       0:00:00.971 *******
2026-08-20 16:49:13.177000 | instance | ok: [instance] => {
2026-08-20 16:49:13.177367 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-08-20 16:49:13.177692 | instance | }
2026-08-20 16:49:13.178005 | instance |
2026-08-20 16:49:13.178330 | instance | PLAY [all] *********************************************************************
2026-08-20 16:49:13.178639 | instance |
2026-08-20 16:49:13.179003 | instance | TASK [Gathering Facts] *********************************************************
2026-08-20 16:49:13.179364 | instance | Thursday 20 August 2026  16:49:13 +0000 (0:00:00.050)       0:00:01.022 *******
2026-08-20 16:49:14.084412 | instance | ok: [instance]
2026-08-20 16:49:14.084649 | instance |
2026-08-20 16:49:14.084849 | instance | TASK [Fail if atmosphere_ceph_enabled is set] **********************************
2026-08-20 16:49:14.085056 | instance | Thursday 20 August 2026  16:49:14 +0000 (0:00:00.907)       0:00:01.929 *******
2026-08-20 16:49:14.121865 | instance | skipping: [instance]
2026-08-20 16:49:14.122207 | instance |
2026-08-20 16:49:14.122544 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-08-20 16:49:14.122876 | instance | Thursday 20 August 2026  16:49:14 +0000 (0:00:00.037)       0:00:01.966 *******
2026-08-20 16:49:14.303997 | instance | ok: [instance]
2026-08-20 16:49:14.304403 | instance |
2026-08-20 16:49:14.304747 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-08-20 16:49:14.305061 | instance |
2026-08-20 16:49:14.305390 | instance | TASK [Gathering Facts] *********************************************************
2026-08-20 16:49:14.305738 | instance | Thursday 20 August 2026  16:49:14 +0000 (0:00:00.182)       0:00:02.149 *******
2026-08-20 16:49:15.191936 | instance | ok: [instance]
2026-08-20 16:49:15.192255 | instance |
2026-08-20 16:49:15.192411 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-20 16:49:15.192578 | instance | Thursday 20 August 2026  16:49:15 +0000 (0:00:00.885)       0:00:03.034 *******
2026-08-20 16:49:15.503076 | instance | ok: [instance]
2026-08-20 16:49:15.503458 | instance |
2026-08-20 16:49:15.503860 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-20 16:49:15.504406 | instance | Thursday 20 August 2026  16:49:15 +0000 (0:00:00.313)       0:00:03.347 *******
2026-08-20 16:49:15.544584 | instance | skipping: [instance]
2026-08-20 16:49:15.544976 | instance |
2026-08-20 16:49:15.545411 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-08-20 16:49:15.545947 | instance | Thursday 20 August 2026  16:49:15 +0000 (0:00:00.041)       0:00:03.389 *******
2026-08-20 16:49:15.876144 | instance | changed: [instance]
2026-08-20 16:49:15.876516 | instance |
2026-08-20 16:49:15.876860 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-20 16:49:15.877278 | instance | Thursday 20 August 2026  16:49:15 +0000 (0:00:00.331)       0:00:03.721 *******
2026-08-20 16:49:15.945199 | instance | ok: [instance] => {
2026-08-20 16:49:15.945583 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-08-20 16:49:15.945905 | instance | }
2026-08-20 16:49:15.946214 | instance |
2026-08-20 16:49:15.946543 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-20 16:49:15.946877 | instance | Thursday 20 August 2026  16:49:15 +0000 (0:00:00.068)       0:00:03.789 *******
2026-08-20 16:49:16.523483 | instance | changed: [instance]
2026-08-20 16:49:16.523946 | instance |
2026-08-20 16:49:16.524402 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-20 16:49:16.524755 | instance | Thursday 20 August 2026  16:49:16 +0000 (0:00:00.578)       0:00:04.368 *******
2026-08-20 16:49:16.576765 | instance | skipping: [instance]
2026-08-20 16:49:16.576855 | instance |
2026-08-20 16:49:16.577018 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-20 16:49:16.577258 | instance | Thursday 20 August 2026  16:49:16 +0000 (0:00:00.050)       0:00:04.419 *******
2026-08-20 16:49:16.628488 | instance | skipping: [instance]
2026-08-20 16:49:16.628613 | instance |
2026-08-20 16:49:16.628631 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-20 16:49:16.628812 | instance | Thursday 20 August 2026  16:49:16 +0000 (0:00:00.053)       0:00:04.472 *******
2026-08-20 16:49:16.852274 | instance | ok: [instance]
2026-08-20 16:49:16.852340 | instance |
2026-08-20 16:49:16.852540 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-20 16:49:16.852741 | instance | Thursday 20 August 2026  16:49:16 +0000 (0:00:00.224)       0:00:04.696 *******
2026-08-20 16:49:18.072256 | instance | ok: [instance]
2026-08-20 16:49:18.072336 | instance |
2026-08-20 16:49:18.072467 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-20 16:49:18.072662 | instance | Thursday 20 August 2026  16:49:18 +0000 (0:00:01.218)       0:00:05.915 *******
2026-08-20 16:49:18.132708 | instance | ok: [instance] => {
2026-08-20 16:49:18.133164 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-08-20 16:49:18.133530 | instance | }
2026-08-20 16:49:18.133846 | instance |
2026-08-20 16:49:18.134183 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-20 16:49:18.134503 | instance | Thursday 20 August 2026  16:49:18 +0000 (0:00:00.060)       0:00:05.976 *******
2026-08-20 16:49:18.732294 | instance | changed: [instance]
2026-08-20 16:49:18.732370 | instance |
2026-08-20 16:49:18.732537 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-20 16:49:18.732812 | instance | Thursday 20 August 2026  16:49:18 +0000 (0:00:00.599)       0:00:06.575 *******
2026-08-20 16:49:21.530797 | instance | changed: [instance]
2026-08-20 16:49:21.530983 | instance |
2026-08-20 16:49:21.531140 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-08-20 16:49:21.531292 | instance | Thursday 20 August 2026  16:49:21 +0000 (0:00:02.799)       0:00:09.374 *******
2026-08-20 16:49:21.562835 | instance | skipping: [instance]
2026-08-20 16:49:21.562864 | instance |
2026-08-20 16:49:21.562870 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-08-20 16:49:21.562875 | instance | Thursday 20 August 2026  16:49:21 +0000 (0:00:00.032)       0:00:09.407 *******
2026-08-20 16:49:21.594424 | instance | skipping: [instance]
2026-08-20 16:49:21.594453 | instance |
2026-08-20 16:49:21.594460 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-08-20 16:49:21.594466 | instance | Thursday 20 August 2026  16:49:21 +0000 (0:00:00.031)       0:00:09.439 *******
2026-08-20 16:49:21.624078 | instance | skipping: [instance]
2026-08-20 16:49:21.624452 | instance |
2026-08-20 16:49:21.624491 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-08-20 16:49:21.624497 | instance | Thursday 20 August 2026  16:49:21 +0000 (0:00:00.030)       0:00:09.469 *******
2026-08-20 16:49:42.124819 | instance | changed: [instance]
2026-08-20 16:49:42.124894 | instance |
2026-08-20 16:49:42.125188 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-08-20 16:49:42.125219 | instance | Thursday 20 August 2026  16:49:42 +0000 (0:00:20.500)       0:00:29.970 *******
2026-08-20 16:49:42.693387 | instance | changed: [instance]
2026-08-20 16:49:42.693818 | instance |
2026-08-20 16:49:42.693935 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-08-20 16:49:42.693942 | instance | Thursday 20 August 2026  16:49:42 +0000 (0:00:00.568)       0:00:30.538 *******
2026-08-20 16:49:43.575106 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-08-20 16:49:43.575890 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-08-20 16:49:43.575949 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-08-20 16:49:43.575972 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-08-20 16:49:43.575982 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-08-20 16:49:43.575992 | instance |
2026-08-20 16:49:43.576009 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-08-20 16:49:43.576018 | instance | Thursday 20 August 2026  16:49:43 +0000 (0:00:00.881)       0:00:31.420 *******
2026-08-20 16:49:44.150375 | instance | changed: [instance]
2026-08-20 16:49:44.150442 | instance |
2026-08-20 16:49:44.151142 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-08-20 16:49:44.151184 | instance | Thursday 20 August 2026  16:49:44 +0000 (0:00:00.558)       0:00:31.978 *******
2026-08-20 16:49:44.151190 | instance |
2026-08-20 16:49:44.151194 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-20 16:49:44.151198 | instance | Thursday 20 August 2026  16:49:44 +0000 (0:00:00.016)       0:00:31.995 *******
2026-08-20 16:49:45.073882 | instance | ok: [instance]
2026-08-20 16:49:45.073965 | instance |
2026-08-20 16:49:45.074247 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-08-20 16:49:45.074280 | instance | Thursday 20 August 2026  16:49:45 +0000 (0:00:00.923)       0:00:32.919 *******
2026-08-20 16:49:45.488689 | instance | changed: [instance]
2026-08-20 16:49:45.489168 | instance |
2026-08-20 16:49:45.489187 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-08-20 16:49:45.489194 | instance | Thursday 20 August 2026  16:49:45 +0000 (0:00:00.414)       0:00:33.333 *******
2026-08-20 16:49:46.026560 | instance | changed: [instance]
2026-08-20 16:49:46.026673 | instance |
2026-08-20 16:49:46.026948 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-20 16:49:46.026985 | instance | Thursday 20 August 2026  16:49:46 +0000 (0:00:00.537)       0:00:33.871 *******
2026-08-20 16:49:46.232262 | instance | ok: [instance]
2026-08-20 16:49:46.232332 | instance |
2026-08-20 16:49:46.232644 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-20 16:49:46.232684 | instance | Thursday 20 August 2026  16:49:46 +0000 (0:00:00.205)       0:00:34.077 *******
2026-08-20 16:49:46.284377 | instance | ok: [instance] => {
2026-08-20 16:49:46.284513 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-08-20 16:49:46.284982 | instance | }
2026-08-20 16:49:46.285020 | instance |
2026-08-20 16:49:46.285026 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-20 16:49:46.285032 | instance | Thursday 20 August 2026  16:49:46 +0000 (0:00:00.052)       0:00:34.129 *******
2026-08-20 16:49:47.027127 | instance | changed: [instance]
2026-08-20 16:49:47.027228 | instance |
2026-08-20 16:49:47.027543 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-20 16:49:47.027580 | instance | Thursday 20 August 2026  16:49:47 +0000 (0:00:00.742)       0:00:34.872 *******
2026-08-20 16:49:51.347914 | instance | changed: [instance]
2026-08-20 16:49:51.347997 | instance |
2026-08-20 16:49:51.348017 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-08-20 16:49:51.348028 | instance | Thursday 20 August 2026  16:49:51 +0000 (0:00:04.320)       0:00:39.192 *******
2026-08-20 16:49:52.335472 | instance | ok: [instance]
2026-08-20 16:49:52.335603 | instance |
2026-08-20 16:49:52.335873 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-08-20 16:49:52.335939 | instance | Thursday 20 August 2026  16:49:52 +0000 (0:00:00.987)       0:00:40.180 *******
2026-08-20 16:49:52.670271 | instance | changed: [instance]
2026-08-20 16:49:52.670337 | instance |
2026-08-20 16:49:52.670676 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-08-20 16:49:52.670712 | instance | Thursday 20 August 2026  16:49:52 +0000 (0:00:00.335)       0:00:40.515 *******
2026-08-20 16:49:53.143974 | instance | changed: [instance]
2026-08-20 16:49:53.144042 | instance |
2026-08-20 16:49:53.144317 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-08-20 16:49:53.144358 | instance | Thursday 20 August 2026  16:49:53 +0000 (0:00:00.473)       0:00:40.989 *******
2026-08-20 16:49:53.700987 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-08-20 16:49:53.701025 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-08-20 16:49:53.701031 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-08-20 16:49:53.701036 | instance |
2026-08-20 16:49:53.701040 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-08-20 16:49:53.701044 | instance | Thursday 20 August 2026  16:49:53 +0000 (0:00:00.556)       0:00:41.545 *******
2026-08-20 16:49:54.141829 | instance | changed: [instance]
2026-08-20 16:49:54.142993 | instance |
2026-08-20 16:49:54.143047 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-08-20 16:49:54.143059 | instance | Thursday 20 August 2026  16:49:54 +0000 (0:00:00.440)       0:00:41.986 *******
2026-08-20 16:49:54.620929 | instance | changed: [instance]
2026-08-20 16:49:54.620989 | instance |
2026-08-20 16:49:54.621712 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-08-20 16:49:54.621765 | instance | Thursday 20 August 2026  16:49:54 +0000 (0:00:00.462)       0:00:42.448 *******
2026-08-20 16:49:54.621770 | instance |
2026-08-20 16:49:54.621775 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-20 16:49:54.621783 | instance | Thursday 20 August 2026  16:49:54 +0000 (0:00:00.017)       0:00:42.465 *******
2026-08-20 16:49:55.314599 | instance | ok: [instance]
2026-08-20 16:49:55.314662 | instance |
2026-08-20 16:49:55.314958 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-08-20 16:49:55.314997 | instance | Thursday 20 August 2026  16:49:55 +0000 (0:00:00.693)       0:00:43.159 *******
2026-08-20 16:49:56.310821 | instance | changed: [instance]
2026-08-20 16:49:56.311168 | instance |
2026-08-20 16:49:56.311183 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-08-20 16:49:56.311189 | instance | Thursday 20 August 2026  16:49:56 +0000 (0:00:00.996)       0:00:44.156 *******
2026-08-20 16:49:56.852477 | instance | changed: [instance]
2026-08-20 16:49:56.852546 | instance |
2026-08-20 16:49:56.852833 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-08-20 16:49:56.852866 | instance | Thursday 20 August 2026  16:49:56 +0000 (0:00:00.541)       0:00:44.697 *******
2026-08-20 16:49:56.908636 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-08-20 16:49:56.909125 | instance |
2026-08-20 16:49:56.909167 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-08-20 16:49:56.909175 | instance | Thursday 20 August 2026  16:49:56 +0000 (0:00:00.055)       0:00:44.753 *******
2026-08-20 16:50:01.379932 | instance | changed: [instance]
2026-08-20 16:50:01.379998 | instance |
2026-08-20 16:50:01.380292 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-08-20 16:50:01.380440 | instance | Thursday 20 August 2026  16:50:01 +0000 (0:00:04.471)       0:00:49.225 *******
2026-08-20 16:50:02.019879 | instance | ok: [instance] => (item=chronyd)
2026-08-20 16:50:02.020381 | instance | ok: [instance] => (item=sshd)
2026-08-20 16:50:02.020419 | instance |
2026-08-20 16:50:02.020424 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-08-20 16:50:02.020429 | instance | Thursday 20 August 2026  16:50:02 +0000 (0:00:00.639)       0:00:49.865 *******
2026-08-20 16:50:03.377865 | instance | changed: [instance]
2026-08-20 16:50:03.378656 | instance |
2026-08-20 16:50:03.378795 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-08-20 16:50:03.378805 | instance | Thursday 20 August 2026  16:50:03 +0000 (0:00:01.357)       0:00:51.222 *******
2026-08-20 16:50:03.581626 | instance | ok: [instance]
2026-08-20 16:50:03.581710 | instance |
2026-08-20 16:50:03.582002 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-08-20 16:50:03.582043 | instance | Thursday 20 August 2026  16:50:03 +0000 (0:00:00.203)       0:00:51.426 *******
2026-08-20 16:50:04.014979 | instance | changed: [instance]
2026-08-20 16:50:04.015509 | instance |
2026-08-20 16:50:04.015556 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-08-20 16:50:04.015564 | instance | Thursday 20 August 2026  16:50:04 +0000 (0:00:00.433)       0:00:51.859 *******
2026-08-20 16:50:04.334701 | instance | changed: [instance]
2026-08-20 16:50:04.334779 | instance |
2026-08-20 16:50:04.335041 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-08-20 16:50:04.335057 | instance | Thursday 20 August 2026  16:50:04 +0000 (0:00:00.319)       0:00:52.179 *******
2026-08-20 16:50:04.524676 | instance | ok: [instance]
2026-08-20 16:50:04.524735 | instance |
2026-08-20 16:50:04.524968 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-08-20 16:50:04.524983 | instance | Thursday 20 August 2026  16:50:04 +0000 (0:00:00.189)       0:00:52.369 *******
2026-08-20 16:50:04.749855 | instance | ok: [instance] => (item=instance)
2026-08-20 16:50:04.749920 | instance |
2026-08-20 16:50:04.750168 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-08-20 16:50:04.750206 | instance | Thursday 20 August 2026  16:50:04 +0000 (0:00:00.225)       0:00:52.594 *******
2026-08-20 16:50:04.803510 | instance | ok: [instance]
2026-08-20 16:50:04.803862 | instance |
2026-08-20 16:50:04.803929 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-08-20 16:50:04.804085 | instance | Thursday 20 August 2026  16:50:04 +0000 (0:00:00.053)       0:00:52.648 *******
2026-08-20 16:50:04.868372 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-08-20 16:50:04.868515 | instance |
2026-08-20 16:50:04.868783 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-08-20 16:50:04.869042 | instance | Thursday 20 August 2026  16:50:04 +0000 (0:00:00.064)       0:00:52.713 *******
2026-08-20 16:50:05.133323 | instance | changed: [instance]
2026-08-20 16:50:05.133549 | instance |
2026-08-20 16:50:05.133834 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-08-20 16:50:05.134113 | instance | Thursday 20 August 2026  16:50:05 +0000 (0:00:00.264)       0:00:52.978 *******
2026-08-20 16:50:05.753010 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-08-20 16:50:05.753126 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-08-20 16:50:05.753326 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-08-20 16:50:05.753481 | instance |
2026-08-20 16:50:05.753672 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-08-20 16:50:05.753827 | instance | Thursday 20 August 2026  16:50:05 +0000 (0:00:00.619)       0:00:53.598 *******
2026-08-20 16:51:28.783560 | instance | ok: [instance]
2026-08-20 16:51:28.783842 | instance |
2026-08-20 16:51:28.784075 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-08-20 16:51:28.784328 | instance | Thursday 20 August 2026  16:51:28 +0000 (0:01:23.030)       0:02:16.628 *******
2026-08-20 16:51:28.994361 | instance | changed: [instance]
2026-08-20 16:51:28.994587 | instance |
2026-08-20 16:51:28.994860 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-08-20 16:51:28.995129 | instance | Thursday 20 August 2026  16:51:28 +0000 (0:00:00.210)       0:02:16.839 *******
2026-08-20 16:51:29.034060 | instance | ok: [instance]
2026-08-20 16:51:29.034272 | instance |
2026-08-20 16:51:29.034537 | instance | TASK [Install Ceph host] *******************************************************
2026-08-20 16:51:29.034802 | instance | Thursday 20 August 2026  16:51:29 +0000 (0:00:00.040)       0:02:16.879 *******
2026-08-20 16:51:29.119350 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-08-20 16:51:29.119577 | instance |
2026-08-20 16:51:29.119918 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-08-20 16:51:29.120188 | instance | Thursday 20 August 2026  16:51:29 +0000 (0:00:00.084)       0:02:16.964 *******
2026-08-20 16:51:30.618876 | instance | ok: [instance]
2026-08-20 16:51:30.619099 | instance |
2026-08-20 16:51:30.619415 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-08-20 16:51:30.619701 | instance | Thursday 20 August 2026  16:51:30 +0000 (0:00:01.499)       0:02:18.463 *******
2026-08-20 16:51:30.679764 | instance | ok: [instance] => (item=instance)
2026-08-20 16:51:30.680050 | instance |
2026-08-20 16:51:30.680350 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-08-20 16:51:30.680629 | instance | Thursday 20 August 2026  16:51:30 +0000 (0:00:00.060)       0:02:18.524 *******
2026-08-20 16:51:31.026470 | instance | ok: [instance]
2026-08-20 16:51:31.026686 | instance |
2026-08-20 16:51:31.027034 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-08-20 16:51:31.027331 | instance | Thursday 20 August 2026  16:51:31 +0000 (0:00:00.346)       0:02:18.871 *******
2026-08-20 16:51:33.499429 | instance | ok: [instance]
2026-08-20 16:51:33.499704 | instance |
2026-08-20 16:51:33.500017 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-08-20 16:51:33.500441 | instance | Thursday 20 August 2026  16:51:33 +0000 (0:00:02.473)       0:02:21.344 *******
2026-08-20 16:54:56.888757 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster)
2026-08-20 16:54:56.889669 | 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__xeswur9/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__xeswur9/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__xeswur9/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__xeswur9/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 120, in apply_spec\nRuntimeError: Inferring config /var/lib/ceph/4837cbf8-4f90-4300-b3f6-726c9b9f89b4/mon.instance/config\n2026-08-20T16:54:56.734+0000 7f8ddd7d6640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgBnMIdqK3avKSAAZRGV7pdlvNpZo1awzXqgNDkmfTfxY4CjVwf4Jb8E8dE=: Malformed input [buffer:3]\n2026-08-20T16:54:56.734+0000 7f8ddd7d6640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-20T16:54:56.734+0000 7f8ddd7d6640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgBnMIdqK3avKSAAZRGV7pdlvNpZo1awzXqgNDkmfTfxY4CjVwf4Jb8E8dE=: Malformed input [buffer:3]\n2026-08-20T16:54:56.734+0000 7f8ddd7d6640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-20T16:54:56.734+0000 7f8ddd7d6640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgBnMIdqK3avKSAAZRGV7pdlvNpZo1awzXqgNDkmfTfxY4CjVwf4Jb8E8dE=: Malformed input [buffer:3]\n2026-08-20T16:54:56.734+0000 7f8ddd7d6640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-20T16:54:56.734+0000 7f8ddd7d6640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1}
2026-08-20 16:54:56.891057 | instance |
2026-08-20 16:54:56.891416 | instance | PLAY RECAP *********************************************************************
2026-08-20 16:54:56.891731 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=7    rescued=0    ignored=0
2026-08-20 16:54:56.892032 | instance |
2026-08-20 16:54:56.892323 | instance | Thursday 20 August 2026  16:54:56 +0000 (0:03:23.392)       0:05:44.736 *******
2026-08-20 16:54:56.892611 | instance | ===============================================================================
2026-08-20 16:54:56.892912 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ---------------- 203.39s
2026-08-20 16:54:56.893201 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 83.03s
2026-08-20 16:54:56.893490 | instance | vexxhost.containers.containerd : Install AppArmor packages ------------- 20.50s
2026-08-20 16:54:56.893775 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 4.47s
2026-08-20 16:54:56.894062 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.32s
2026-08-20 16:54:56.894345 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.80s
2026-08-20 16:54:56.894632 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.47s
2026-08-20 16:54:56.894917 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.62s
2026-08-20 16:54:56.895218 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.50s
2026-08-20 16:54:56.895511 | instance | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 1.36s
2026-08-20 16:54:56.895826 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.22s
2026-08-20 16:54:56.896112 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 1.00s
2026-08-20 16:54:56.896395 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 0.99s
2026-08-20 16:54:56.896677 | instance | Gathering Facts --------------------------------------------------------- 0.96s
2026-08-20 16:54:56.896958 | instance | Gathering Facts --------------------------------------------------------- 0.91s
2026-08-20 16:54:56.897239 | instance | Gathering Facts --------------------------------------------------------- 0.89s
2026-08-20 16:54:56.897535 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.88s
2026-08-20 16:54:56.897825 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.74s
2026-08-20 16:54:56.898110 | instance | vexxhost.ceph.cephadm : Ensure services are started --------------------- 0.64s
2026-08-20 16:54:56.898396 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.62s
2026-08-20 16:54:56.977715 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-08-20 16:54:56.977922 | instance | ERROR    [csi > converge] Executed: Failed
2026-08-20 16:54:56.978113 | instance | ERROR    Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-08-20 16:54:57.529363 | instance | ERROR
2026-08-20 16:54:57.529685 | instance | {
2026-08-20 16:54:57.529731 | instance |   "delta": "0:06:39.299437",
2026-08-20 16:54:57.529763 | instance |   "end": "2026-08-20 16:54:57.027916",
2026-08-20 16:54:57.529791 | instance |   "msg": "non-zero return code",
2026-08-20 16:54:57.529817 | instance |   "rc": 2,
2026-08-20 16:54:57.529844 | instance |   "start": "2026-08-20 16:48:17.728479"
2026-08-20 16:54:57.529871 | instance | }
failure
2026-08-20 16:54:57.536640 | 
2026-08-20 16:54:57.536740 | PLAY RECAP
2026-08-20 16:54:57.536803 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-08-20 16:54:57.536832 | 
2026-08-20 16:54:57.707141 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-20 16:54:57.709288 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-08-20 16:54:58.496099 | 
2026-08-20 16:54:58.496281 | PLAY [all]
2026-08-20 16:54:58.513588 | 
2026-08-20 16:54:58.513738 | TASK [gather-host-logs : creating directory for system status]
2026-08-20 16:54:58.885608 | instance | changed
2026-08-20 16:54:58.893273 | 
2026-08-20 16:54:58.893481 | TASK [gather-host-logs : Get logs for each host]
2026-08-20 16:54:59.257886 | instance | + systemd-cgls --full --all --no-pager
2026-08-20 16:54:59.271713 | instance | + ip addr
2026-08-20 16:54:59.274788 | instance | + ip route
2026-08-20 16:54:59.277831 | instance | + lsblk
2026-08-20 16:54:59.283571 | instance | + mount
2026-08-20 16:54:59.286033 | instance | + docker images
2026-08-20 16:54:59.304571 | instance | + brctl show
2026-08-20 16:54:59.305206 | instance | /bin/bash: line 8: brctl: command not found
2026-08-20 16:54:59.305820 | instance | + ps aux --sort=-%mem
2026-08-20 16:54:59.322557 | instance | + dpkg -l
2026-08-20 16:54:59.334723 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-08-20 16:54:59.335183 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-08-20 16:54:59.353244 | instance | + '[' '!' -z '' ']'
2026-08-20 16:54:59.433970 | instance | ok: Runtime: 0:00:00.100559
2026-08-20 16:54:59.441625 | 
2026-08-20 16:54:59.441697 | TASK [gather-host-logs : Downloads logs to executor]
2026-08-20 16:55:00.105706 | instance | changed:
2026-08-20 16:55:00.105902 | instance | created directory /var/lib/zuul/builds/89cef8696a7d43efa4364cb877facdbb/work/logs/instance
2026-08-20 16:55:00.105942 | instance | cd+++++++++ system/
2026-08-20 16:55:00.105972 | instance | >f+++++++++ system/brctl-show.txt
2026-08-20 16:55:00.106033 | instance | >f+++++++++ system/docker-images.txt
2026-08-20 16:55:00.106094 | instance | >f+++++++++ system/ip-addr.txt
2026-08-20 16:55:00.106161 | instance | >f+++++++++ system/ip-route.txt
2026-08-20 16:55:00.106197 | instance | >f+++++++++ system/lsblk.txt
2026-08-20 16:55:00.106227 | instance | >f+++++++++ system/mount.txt
2026-08-20 16:55:00.106255 | instance | >f+++++++++ system/packages.txt
2026-08-20 16:55:00.106285 | instance | >f+++++++++ system/ps.txt
2026-08-20 16:55:00.106356 | instance | >f+++++++++ system/systemd-cgls.txt
2026-08-20 16:55:00.117807 | 
2026-08-20 16:55:00.117932 | LOOP [helm-release-status : creating directory for helm release status]
2026-08-20 16:55:00.324079 | instance | changed: "values"
2026-08-20 16:55:00.489021 | instance | changed: "releases"
2026-08-20 16:55:00.505222 | 
2026-08-20 16:55:00.505385 | TASK [helm-release-status : Gather get release status for helm charts]
2026-08-20 16:55:00.718313 | instance | /bin/bash: line 3: kubectl: command not found
2026-08-20 16:55:01.045177 | instance | ok: Runtime: 0:00:00.007414
2026-08-20 16:55:01.051594 | 
2026-08-20 16:55:01.051782 | TASK [helm-release-status : Downloads logs to executor]
2026-08-20 16:55:01.554716 | instance | changed:
2026-08-20 16:55:01.554891 | instance | cd+++++++++ helm/
2026-08-20 16:55:01.554920 | instance | cd+++++++++ helm/releases/
2026-08-20 16:55:01.554950 | instance | cd+++++++++ helm/values/
2026-08-20 16:55:01.564255 | 
2026-08-20 16:55:01.564335 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-08-20 16:55:01.762315 | instance | changed
2026-08-20 16:55:01.768374 | 
2026-08-20 16:55:01.768449 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-08-20 16:55:02.020599 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-20 16:55:02.020960 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-20 16:55:02.026377 | instance | environment: line 1: kubectl: command not found
2026-08-20 16:55:02.027188 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-20 16:55:02.028927 | instance | environment: line 1: kubectl: command not found
2026-08-20 16:55:02.030315 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-20 16:55:02.031686 | instance | environment: line 1: kubectl: command not found
2026-08-20 16:55:02.032383 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-20 16:55:02.034323 | instance | environment: line 1: kubectl: command not found
2026-08-20 16:55:02.035039 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-20 16:55:02.035440 | instance | environment: line 1: kubectl: command not found
2026-08-20 16:55:02.036459 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-20 16:55:02.304902 | instance | ok: Runtime: 0:00:00.023442
2026-08-20 16:55:02.310686 | 
2026-08-20 16:55:02.310765 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-08-20 16:55:02.504250 | instance | changed
2026-08-20 16:55:02.511137 | 
2026-08-20 16:55:02.511207 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-08-20 16:55:02.720127 | instance | environment: line 5: kubectl: command not found
2026-08-20 16:55:02.721318 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-20 16:55:02.721348 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-20 16:55:02.721554 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-20 16:55:03.047439 | instance | ok: Runtime: 0:00:00.007042
2026-08-20 16:55:03.053202 | 
2026-08-20 16:55:03.053294 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-08-20 16:55:03.555038 | instance | changed:
2026-08-20 16:55:03.555297 | instance | cd+++++++++ objects/
2026-08-20 16:55:03.555350 | instance | cd+++++++++ objects/cluster/
2026-08-20 16:55:03.555394 | instance | cd+++++++++ objects/namespaced/
2026-08-20 16:55:03.565794 | 
2026-08-20 16:55:03.565921 | TASK [gather-pod-logs : creating directory for pod logs]
2026-08-20 16:55:03.768697 | instance | changed
2026-08-20 16:55:03.776440 | 
2026-08-20 16:55:03.776597 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-08-20 16:55:03.974819 | instance | changed
2026-08-20 16:55:03.982032 | 
2026-08-20 16:55:03.982151 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-08-20 16:55:04.186932 | instance | environment: line 3: kubectl: command not found
2026-08-20 16:55:04.519771 | instance | ok: Runtime: 0:00:00.007657
2026-08-20 16:55:04.525587 | 
2026-08-20 16:55:04.525715 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-08-20 16:55:05.010701 | instance | changed:
2026-08-20 16:55:05.010907 | instance | cd+++++++++ pod-logs/
2026-08-20 16:55:05.010951 | instance | cd+++++++++ pod-logs/failed-pods/
2026-08-20 16:55:05.025911 | 
2026-08-20 16:55:05.026027 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-08-20 16:55:05.222844 | instance | changed
2026-08-20 16:55:05.230120 | 
2026-08-20 16:55:05.230242 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-08-20 16:55:05.437893 | instance | /bin/bash: line 2: kubectl: command not found
2026-08-20 16:55:05.771487 | instance | ok: Runtime: 0:00:00.037192
2026-08-20 16:55:05.778121 | 
2026-08-20 16:55:05.778385 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-08-20 16:55:05.995723 | instance | /bin/bash: line 2: kubectl: command not found
2026-08-20 16:55:06.023874 | instance | ceph-mgr endpoints:
2026-08-20 16:55:06.321156 | instance | ok: Runtime: 0:00:00.034226
2026-08-20 16:55:06.328171 | 
2026-08-20 16:55:06.328292 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-08-20 16:55:06.535735 | instance | /bin/bash: line 4: kubectl: command not found
2026-08-20 16:55:06.865919 | instance | ok: Runtime: 0:00:00.035355
2026-08-20 16:55:06.881748 | 
2026-08-20 16:55:06.882005 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-08-20 16:55:07.456075 | instance | changed: cd+++++++++ prometheus/
2026-08-20 16:55:07.466384 | 
2026-08-20 16:55:07.466474 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-08-20 16:55:07.663883 | instance | changed
2026-08-20 16:55:07.672334 | 
2026-08-20 16:55:07.672532 | TASK [gather-selenium-data : Get selenium data]
2026-08-20 16:55:07.942362 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-08-20 16:55:07.943807 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-08-20 16:55:08.212946 | instance | ERROR
2026-08-20 16:55:08.213203 | instance | {
2026-08-20 16:55:08.213243 | instance |   "delta": "0:00:00.006188",
2026-08-20 16:55:08.213267 | instance |   "end": "2026-08-20 16:55:07.944193",
2026-08-20 16:55:08.213289 | instance |   "msg": "non-zero return code",
2026-08-20 16:55:08.213309 | instance |   "rc": 1,
2026-08-20 16:55:08.213329 | instance |   "start": "2026-08-20 16:55:07.938005"
2026-08-20 16:55:08.213348 | instance | }
2026-08-20 16:55:08.213374 | instance | ERROR: Ignoring Errors
2026-08-20 16:55:08.218429 | 
2026-08-20 16:55:08.218499 | TASK [gather-selenium-data : Downloads logs to executor]
2026-08-20 16:55:08.750689 | instance | changed: cd+++++++++ selenium/
2026-08-20 16:55:08.757917 | 
2026-08-20 16:55:08.758028 | PLAY RECAP
2026-08-20 16:55:08.758081 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-08-20 16:55:08.758104 | 
2026-08-20 16:55:08.948768 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-08-20 16:55:08.950961 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-20 16:55:09.608948 | 
2026-08-20 16:55:09.609209 | PLAY [all]
2026-08-20 16:55:09.622838 | 
2026-08-20 16:55:09.622934 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-20 16:55:09.668034 | instance | skipping: Conditional result was False
2026-08-20 16:55:09.676615 | 
2026-08-20 16:55:09.676777 | TASK [fetch-output : Set log path for single node]
2026-08-20 16:55:09.720885 | instance | ok
2026-08-20 16:55:09.727153 | 
2026-08-20 16:55:09.727236 | LOOP [fetch-output : Ensure local output dirs]
2026-08-20 16:55:10.145691 | instance -> localhost | ok: "/var/lib/zuul/builds/89cef8696a7d43efa4364cb877facdbb/work/logs"
2026-08-20 16:55:10.371400 | instance -> localhost | changed: "/var/lib/zuul/builds/89cef8696a7d43efa4364cb877facdbb/work/artifacts"
2026-08-20 16:55:10.659868 | instance -> localhost | changed: "/var/lib/zuul/builds/89cef8696a7d43efa4364cb877facdbb/work/docs"
2026-08-20 16:55:10.675703 | 
2026-08-20 16:55:10.675810 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-20 16:55:11.384771 | instance | changed: .d..t...... ./
2026-08-20 16:55:11.385063 | instance | changed: All items complete
2026-08-20 16:55:11.385115 | 
2026-08-20 16:55:11.844663 | instance | changed: .d..t...... ./
2026-08-20 16:55:12.316832 | instance | changed: .d..t...... ./
2026-08-20 16:55:12.341670 | 
2026-08-20 16:55:12.341852 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-20 16:55:12.779157 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.009661
2026-08-20 16:55:13.007927 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006136
2026-08-20 16:55:13.022478 | 
2026-08-20 16:55:13.022617 | PLAY [all]
2026-08-20 16:55:13.029319 | 
2026-08-20 16:55:13.029474 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-20 16:55:13.471141 | instance | changed
2026-08-20 16:55:13.479015 | 
2026-08-20 16:55:13.479123 | PLAY RECAP
2026-08-20 16:55:13.479172 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-20 16:55:13.479195 | 
2026-08-20 16:55:13.642199 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-20 16:55:13.644429 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-20 16:55:14.286141 | 
2026-08-20 16:55:14.286304 | PLAY [localhost]
2026-08-20 16:55:14.298657 | 
2026-08-20 16:55:14.298786 | TASK [Generate Zuul manifest]
2026-08-20 16:55:14.318605 | localhost | ok
2026-08-20 16:55:14.339359 | 
2026-08-20 16:55:14.339538 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-20 16:55:14.736068 | localhost | changed
2026-08-20 16:55:14.754429 | 
2026-08-20 16:55:14.754602 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-20 16:55:14.784641 | localhost | ok
2026-08-20 16:55:14.794334 | 
2026-08-20 16:55:14.794459 | TASK [Upload logs]
2026-08-20 16:55:14.814914 | localhost | ok
2026-08-20 16:55:14.903030 | 
2026-08-20 16:55:14.903175 | TASK [Set zuul-log-path fact]
2026-08-20 16:55:14.924457 | localhost | ok
2026-08-20 16:55:14.938909 | 
2026-08-20 16:55:14.939051 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-20 16:55:14.972516 | localhost | ok
2026-08-20 16:55:14.980443 | 
2026-08-20 16:55:14.980542 | TASK [upload-logs : Create log directories]
2026-08-20 16:55:15.387809 | localhost | changed
2026-08-20 16:55:15.393714 | 
2026-08-20 16:55:15.393821 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-20 16:55:15.827921 | localhost -> localhost | ok: Runtime: 0:00:00.005611
2026-08-20 16:55:15.835504 | 
2026-08-20 16:55:15.835672 | TASK [upload-logs : Upload logs to log server]
2026-08-20 16:55:16.384585 | localhost | Output suppressed because no_log was given
2026-08-20 16:55:16.435194 | 
2026-08-20 16:55:16.435378 | LOOP [upload-logs : Compress console log and json output]
2026-08-20 16:55:16.481735 | localhost | skipping: Conditional result was False
2026-08-20 16:55:16.492072 | localhost | skipping: Conditional result was False
2026-08-20 16:55:16.508879 | 
2026-08-20 16:55:16.509105 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-20 16:55:16.551371 | localhost | skipping: Conditional result was False
2026-08-20 16:55:16.551842 | 
2026-08-20 16:55:16.555537 | localhost | skipping: Conditional result was False
2026-08-20 16:55:16.573895 | 
2026-08-20 16:55:16.574145 | LOOP [upload-logs : Upload console log and json output]