2026-09-01 18:20:34.854420 | Job console starting
2026-09-01 18:20:34.866453 | Updating git repos
2026-09-01 18:20:37.738020 | Cloning repos into workspace
2026-09-01 18:20:54.935151 | Restoring repo states
2026-09-01 18:21:03.937086 | Merging changes
2026-09-01 18:21:05.427270 | Checking out repos
2026-09-01 18:21:07.090635 | Preparing playbooks
2026-09-01 18:21:09.964833 | Running Ansible setup
2026-09-01 18:21:13.504520 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-01 18:21:14.125218 | 
2026-09-01 18:21:14.125347 | PLAY [localhost]
2026-09-01 18:21:14.133028 | 
2026-09-01 18:21:14.133097 | TASK [Gathering Facts]
2026-09-01 18:21:15.111406 | localhost | ok
2026-09-01 18:21:15.118421 | 
2026-09-01 18:21:15.118543 | TASK [Setup log path fact]
2026-09-01 18:21:15.135130 | localhost | ok
2026-09-01 18:21:15.145947 | 
2026-09-01 18:21:15.146010 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-01 18:21:15.175984 | localhost | ok
2026-09-01 18:21:15.182409 | 
2026-09-01 18:21:15.323896 | TASK [emit-job-header : Print job information]
2026-09-01 18:21:15.384138 | # Job Information
2026-09-01 18:21:15.384310 | Ansible Version: 2.16.18
2026-09-01 18:21:15.384343 | Job: atmosphere-molecule-csi-rbd
2026-09-01 18:21:15.384366 | Pipeline: check
2026-09-01 18:21:15.384387 | Executor: 2d72f0692154
2026-09-01 18:21:15.384408 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4090
2026-09-01 18:21:15.384431 | Event ID: bb298ee0-a630-11f1-92bc-b0d969191a8f
2026-09-01 18:21:15.387630 | 
2026-09-01 18:21:15.387696 | LOOP [emit-job-header : Print node information]
2026-09-01 18:21:15.485252 | localhost | ok:
2026-09-01 18:21:15.485478 | localhost | # Node Information
2026-09-01 18:21:15.485518 | localhost | Inventory Hostname: instance
2026-09-01 18:21:15.485547 | localhost | Hostname: np0000191762
2026-09-01 18:21:15.485575 | localhost | Username: zuul
2026-09-01 18:21:15.485606 | localhost | Distro: Ubuntu 22.04
2026-09-01 18:21:15.485632 | localhost | Provider: yul1
2026-09-01 18:21:15.485658 | localhost | Region: ca-ymq-1
2026-09-01 18:21:15.485684 | localhost | Label: ubuntu-jammy
2026-09-01 18:21:15.485708 | localhost | Product Name: OpenStack Nova
2026-09-01 18:21:15.485732 | localhost | Interface IP: 162.253.55.208
2026-09-01 18:21:15.504014 | 
2026-09-01 18:21:15.504216 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-01 18:21:16.030531 | localhost -> localhost | changed
2026-09-01 18:21:16.035996 | 
2026-09-01 18:21:16.036081 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-01 18:21:17.084202 | localhost -> localhost | changed
2026-09-01 18:21:17.096091 | 
2026-09-01 18:21:17.096169 | PLAY [all]
2026-09-01 18:21:17.107543 | 
2026-09-01 18:21:17.107632 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-01 18:21:17.355474 | instance -> localhost | ok
2026-09-01 18:21:17.362589 | 
2026-09-01 18:21:17.362655 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-01 18:21:17.397538 | instance | ok
2026-09-01 18:21:17.529971 | instance | included: /var/lib/zuul/builds/ecd2c083d5524ba9891eec0e4cbae6ff/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-01 18:21:17.535876 | 
2026-09-01 18:21:17.535940 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-01 18:21:18.828504 | instance -> localhost | Generating public/private rsa key pair.
2026-09-01 18:21:18.828711 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/ecd2c083d5524ba9891eec0e4cbae6ff/work/ecd2c083d5524ba9891eec0e4cbae6ff_id_rsa
2026-09-01 18:21:18.828751 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/ecd2c083d5524ba9891eec0e4cbae6ff/work/ecd2c083d5524ba9891eec0e4cbae6ff_id_rsa.pub
2026-09-01 18:21:18.828782 | instance -> localhost | The key fingerprint is:
2026-09-01 18:21:18.828812 | instance -> localhost | SHA256:+jM5jnBSxm+ssUcYsita4e9Yj6cGBHOXdxPJefBHGJ4 zuul-build-sshkey
2026-09-01 18:21:18.828873 | instance -> localhost | The key's randomart image is:
2026-09-01 18:21:18.828905 | instance -> localhost | +---[RSA 3072]----+
2026-09-01 18:21:18.828943 | instance -> localhost | |     . .o=.o.    |
2026-09-01 18:21:18.828996 | instance -> localhost | |o . o . *ooo     |
2026-09-01 18:21:18.829026 | instance -> localhost | | + . . . oE .    |
2026-09-01 18:21:18.829054 | instance -> localhost | |  . ...    .     |
2026-09-01 18:21:18.829082 | instance -> localhost | | ..  o+oS        |
2026-09-01 18:21:18.829110 | instance -> localhost | | ....o.+.        |
2026-09-01 18:21:18.829138 | instance -> localhost | |  o.+.+.+.       |
2026-09-01 18:21:18.829165 | instance -> localhost | | ..+o*.B*        |
2026-09-01 18:21:18.829196 | instance -> localhost | |...+=o=oo+       |
2026-09-01 18:21:18.830470 | instance -> localhost | +----[SHA256]-----+
2026-09-01 18:21:18.830545 | instance -> localhost | ok: Runtime: 0:00:00.828180
2026-09-01 18:21:18.840746 | 
2026-09-01 18:21:18.840828 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-01 18:21:18.884609 | instance | ok
2026-09-01 18:21:18.899512 | instance | included: /var/lib/zuul/builds/ecd2c083d5524ba9891eec0e4cbae6ff/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-01 18:21:18.907109 | 
2026-09-01 18:21:19.048508 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-01 18:21:19.075006 | instance | skipping: Conditional result was False
2026-09-01 18:21:19.087113 | 
2026-09-01 18:21:19.087190 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-01 18:21:19.629987 | instance | changed
2026-09-01 18:21:19.637974 | 
2026-09-01 18:21:19.638060 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-01 18:21:19.836882 | instance | ok
2026-09-01 18:21:19.841996 | 
2026-09-01 18:21:19.842060 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-01 18:21:20.356192 | instance | changed
2026-09-01 18:21:20.362934 | 
2026-09-01 18:21:20.363020 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-01 18:21:20.878657 | instance | changed
2026-09-01 18:21:20.954294 | 
2026-09-01 18:21:20.954416 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-01 18:21:20.978705 | instance | skipping: Conditional result was False
2026-09-01 18:21:20.986840 | 
2026-09-01 18:21:20.986927 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-01 18:21:21.395752 | instance -> localhost | changed
2026-09-01 18:21:21.406034 | 
2026-09-01 18:21:21.406102 | TASK [add-build-sshkey : Add back temp key]
2026-09-01 18:21:21.703229 | instance -> localhost | Identity added: /var/lib/zuul/builds/ecd2c083d5524ba9891eec0e4cbae6ff/work/ecd2c083d5524ba9891eec0e4cbae6ff_id_rsa (zuul-build-sshkey)
2026-09-01 18:21:21.703560 | instance -> localhost | ok: Runtime: 0:00:00.015304
2026-09-01 18:21:21.710409 | 
2026-09-01 18:21:21.710492 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-01 18:21:22.093886 | instance | ok
2026-09-01 18:21:22.099104 | 
2026-09-01 18:21:22.099174 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-01 18:21:22.126137 | instance | skipping: Conditional result was False
2026-09-01 18:21:22.143172 | 
2026-09-01 18:21:22.143263 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-01 18:21:22.492255 | instance | ok
2026-09-01 18:21:22.499855 | 
2026-09-01 18:21:22.501008 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-01 18:21:26.216062 | instance | Output suppressed because no_log was given
2026-09-01 18:21:26.229107 | 
2026-09-01 18:21:26.229229 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-01 18:21:26.503008 | instance | ok: "logs"
2026-09-01 18:21:26.503229 | instance | ok: All items complete
2026-09-01 18:21:26.503258 | 
2026-09-01 18:21:26.670201 | instance | ok: "artifacts"
2026-09-01 18:21:26.864692 | instance | ok: "docs"
2026-09-01 18:21:26.870990 | 
2026-09-01 18:21:26.871105 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-01 18:21:27.080837 | instance | changed: "logs"
2026-09-01 18:21:27.233774 | instance | changed: "artifacts"
2026-09-01 18:21:27.383497 | instance | changed: "docs"
2026-09-01 18:21:27.395835 | 
2026-09-01 18:21:27.395986 | PLAY RECAP
2026-09-01 18:21:27.396042 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-01 18:21:27.396072 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-01 18:21:27.396093 | 
2026-09-01 18:21:27.615704 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-01 18:21:27.623852 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-01 18:21:28.553596 | 
2026-09-01 18:21:28.553792 | PLAY [all]
2026-09-01 18:21:28.565897 | 
2026-09-01 18:21:28.565984 | TASK [setup-uv : Extract archive]
2026-09-01 18:21:31.056700 | instance | changed
2026-09-01 18:21:31.067015 | 
2026-09-01 18:21:31.067146 | TASK [setup-uv : Print version]
2026-09-01 18:21:31.440639 | instance | uv 0.8.13
2026-09-01 18:21:31.610661 | instance | ok: Runtime: 0:00:00.012403
2026-09-01 18:21:31.623157 | 
2026-09-01 18:21:31.623231 | TASK [Install Ansible collection siblings]
2026-09-01 18:21:41.009496 | instance | changed:
2026-09-01 18:21:41.009686 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-01 18:21:41.009717 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-01 18:21:41.009739 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-01 18:21:41.009758 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-01 18:21:41.009777 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-01 18:21:41.009799 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-01 18:21:41.009820 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-01 18:21:41.009841 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-01 18:21:41.009861 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-01 18:21:41.009880 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-01 18:21:41.009899 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-01 18:21:41.009918 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-01 18:21:41.009936 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-09-01 18:21:41.009954 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-01 18:21:41.009972 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0
2026-09-01 18:21:41.009991 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-01 18:21:41.010010 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-01 18:21:41.010028 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-01 18:21:41.010053 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-01 18:21:41.010071 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-09-01 18:21:41.010089 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.1.2
2026-09-01 18:21:41.010107 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-09-01 18:21:41.010126 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-09-01 18:21:41.010159 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-01 18:21:41.010178 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0
2026-09-01 18:21:41.010197 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-01 18:21:41.010218 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-01 18:21:41.010238 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-09-01 18:21:41.010255 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-01 18:21:41.010273 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-01 18:21:41.010299 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-01 18:21:41.010318 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-01 18:21:41.010336 | instance | Checked out ansible.netcommon to 1.2.0
2026-09-01 18:21:41.010354 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-01 18:21:41.010378 | instance | Starting galaxy collection install process
2026-09-01 18:21:41.010396 | instance | Process install dependency map
2026-09-01 18:21:41.010414 | instance | Starting collection install process
2026-09-01 18:21:41.010431 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-01 18:21:41.010449 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-01 18:21:41.010467 | instance | ansible.netcommon:* was installed successfully
2026-09-01 18:21:41.010491 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-01 18:21:41.010509 | instance | Creating virtual environment at: .venv
2026-09-01 18:21:41.010527 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-01 18:21:41.010545 | instance | Downloading netaddr (2.2MiB)
2026-09-01 18:21:41.010562 | instance | Downloading rjsonnet (1.2MiB)
2026-09-01 18:21:41.010584 | instance | Downloading cryptography (4.2MiB)
2026-09-01 18:21:41.010602 | instance | Downloading ansible-core (2.1MiB)
2026-09-01 18:21:41.010621 | instance | Downloading kubernetes (1.9MiB)
2026-09-01 18:21:41.010638 | instance | Downloading openstacksdk (1.7MiB)
2026-09-01 18:21:41.010656 | instance | Downloading setuptools (1.1MiB)
2026-09-01 18:21:41.010675 | instance | Downloading pygments (1.2MiB)
2026-09-01 18:21:41.010693 | instance |  Downloading rjsonnet
2026-09-01 18:21:41.010711 | instance |    Building pyperclip==1.9.0
2026-09-01 18:21:41.010730 | instance |  Downloading netaddr
2026-09-01 18:21:41.010749 | instance |  Downloading cryptography
2026-09-01 18:21:41.010767 | instance |  Downloading pygments
2026-09-01 18:21:41.010785 | instance |  Downloading setuptools
2026-09-01 18:21:41.010802 | instance |  Downloading kubernetes
2026-09-01 18:21:41.010820 | instance |  Downloading ansible-core
2026-09-01 18:21:41.010838 | instance |  Downloading openstacksdk
2026-09-01 18:21:41.010855 | instance |       Built pyperclip==1.9.0
2026-09-01 18:21:41.010873 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-01 18:21:41.010891 | instance | Installed 79 packages in 106ms
2026-09-01 18:21:41.010909 | instance | Checked out ansible.posix to 1.6.0
2026-09-01 18:21:41.010927 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-01 18:21:41.010945 | instance | Starting galaxy collection install process
2026-09-01 18:21:41.010962 | instance | Process install dependency map
2026-09-01 18:21:41.010980 | instance | Starting collection install process
2026-09-01 18:21:41.010998 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-01 18:21:41.011021 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-01 18:21:41.011039 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-01 18:21:41.011057 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-01 18:21:41.011074 | instance | 2.17.12
2026-09-01 18:21:41.011092 | instance | Checked out ansible.utils to v6.1.0
2026-09-01 18:21:41.011111 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-01 18:21:41.011128 | instance | Starting galaxy collection install process
2026-09-01 18:21:41.011146 | instance | Process install dependency map
2026-09-01 18:21:41.011164 | instance | Starting collection install process
2026-09-01 18:21:41.011182 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-01 18:21:41.011200 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-01 18:21:41.011219 | instance | ansible.utils:6.1.0 was installed successfully
2026-09-01 18:21:41.011237 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-01 18:21:41.011255 | instance | 2.17.12
2026-09-01 18:21:41.011273 | instance | Checked out atmosphere.common to v0.7.0
2026-09-01 18:21:41.011291 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-01 18:21:41.011309 | instance | Starting galaxy collection install process
2026-09-01 18:21:41.011328 | instance | Process install dependency map
2026-09-01 18:21:41.011345 | instance | Starting collection install process
2026-09-01 18:21:41.011363 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-01 18:21:41.011381 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-01 18:21:41.011398 | instance | atmosphere.common:0.7.0 was installed successfully
2026-09-01 18:21:41.011416 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-01 18:21:41.011433 | instance | 2.17.12
2026-09-01 18:21:41.011451 | instance | Checked out community.crypto to 2.2.3
2026-09-01 18:21:41.011468 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-01 18:21:41.011486 | instance | Starting galaxy collection install process
2026-09-01 18:21:41.011503 | instance | Process install dependency map
2026-09-01 18:21:41.011521 | instance | Starting collection install process
2026-09-01 18:21:41.011539 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-01 18:21:41.011556 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-01 18:21:41.011574 | instance | community.crypto:2.2.3 was installed successfully
2026-09-01 18:21:41.011591 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-01 18:21:41.011609 | instance | 2.17.12
2026-09-01 18:21:41.011626 | instance | Checked out community.general to 7.3.0
2026-09-01 18:21:41.011643 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general
2026-09-01 18:21:41.011661 | instance | Starting galaxy collection install process
2026-09-01 18:21:41.011685 | instance | Process install dependency map
2026-09-01 18:21:41.011703 | instance | Starting collection install process
2026-09-01 18:21:41.011736 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-01 18:21:41.011756 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-01 18:21:41.011774 | instance | community.general:7.3.0 was installed successfully
2026-09-01 18:21:41.011793 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-01 18:21:41.011811 | instance | 2.17.12
2026-09-01 18:21:41.011830 | instance | Checked out community.mysql to 3.6.0
2026-09-01 18:21:41.011849 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-01 18:21:41.011868 | instance | Starting galaxy collection install process
2026-09-01 18:21:41.011889 | instance | Process install dependency map
2026-09-01 18:21:41.011915 | instance | Starting collection install process
2026-09-01 18:21:41.011935 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-01 18:21:41.011953 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-01 18:21:41.011982 | instance | community.mysql:3.6.0 was installed successfully
2026-09-01 18:21:41.012010 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-01 18:21:41.012030 | instance | 2.17.12
2026-09-01 18:21:41.012049 | instance | Checked out kubernetes.core to 2.4.0
2026-09-01 18:21:41.012068 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-01 18:21:41.012087 | instance | Starting galaxy collection install process
2026-09-01 18:21:41.012106 | instance | Process install dependency map
2026-09-01 18:21:41.012124 | instance | Starting collection install process
2026-09-01 18:21:41.012143 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-01 18:21:41.012161 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-01 18:21:41.012180 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-01 18:21:41.012198 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-01 18:21:41.012216 | instance | 2.17.12
2026-09-01 18:21:41.012235 | instance | Checked out openstack.cloud to 2.6.0
2026-09-01 18:21:41.012253 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-01 18:21:41.012272 | instance | Starting galaxy collection install process
2026-09-01 18:21:41.012296 | instance | Process install dependency map
2026-09-01 18:21:41.012316 | instance | Starting collection install process
2026-09-01 18:21:41.012335 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-01 18:21:41.012353 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-01 18:21:41.012371 | instance | openstack.cloud:2.6.0 was installed successfully
2026-09-01 18:21:41.012389 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-01 18:21:41.012407 | instance | 2.17.12
2026-09-01 18:21:41.012425 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-01 18:21:41.012470 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-01 18:21:41.012497 | instance | Starting galaxy collection install process
2026-09-01 18:21:41.012516 | instance | Process install dependency map
2026-09-01 18:21:41.012544 | instance | Starting collection install process
2026-09-01 18:21:41.012565 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-01 18:21:41.012584 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-01 18:21:41.012607 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-01 18:21:41.012640 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-01 18:21:41.012659 | instance | 2.17.12
2026-09-01 18:21:41.012677 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-01 18:21:41.012695 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-01 18:21:41.012714 | instance | Starting galaxy collection install process
2026-09-01 18:21:41.012732 | instance | Process install dependency map
2026-09-01 18:21:41.012750 | instance | Starting collection install process
2026-09-01 18:21:41.012769 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-01 18:21:41.012788 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-01 18:21:41.012807 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-01 18:21:41.012825 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-01 18:21:41.012844 | instance | 2.17.12
2026-09-01 18:21:41.012862 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-09-01 18:21:41.012881 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-01 18:21:41.012900 | instance | Starting galaxy collection install process
2026-09-01 18:21:41.012918 | instance | Process install dependency map
2026-09-01 18:21:41.012936 | instance | Starting collection install process
2026-09-01 18:21:41.012975 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-01 18:21:41.013001 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-01 18:21:41.013032 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-09-01 18:21:41.013056 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-01 18:21:41.013076 | instance | 2.17.12
2026-09-01 18:21:41.021882 | 
2026-09-01 18:21:41.021932 | PLAY RECAP
2026-09-01 18:21:41.021979 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-01 18:21:41.022001 | 
2026-09-01 18:21:41.151694 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-01 18:21:41.156157 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-01 18:21:41.763442 | 
2026-09-01 18:21:41.763673 | PLAY [all]
2026-09-01 18:21:41.777935 | 
2026-09-01 18:21:41.778017 | TASK [Install "jq" for log collection]
2026-09-01 18:21:52.774701 | instance | changed
2026-09-01 18:21:52.777336 | 
2026-09-01 18:21:52.777414 | PLAY RECAP
2026-09-01 18:21:52.777482 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-01 18:21:52.777548 | 
2026-09-01 18:21:52.924609 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-01 18:21:52.943467 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-01 18:21:53.675523 | 
2026-09-01 18:21:53.675677 | PLAY [all]
2026-09-01 18:21:53.687764 | 
2026-09-01 18:21:53.687847 | LOOP [Check inventory directories]
2026-09-01 18:21:54.052001 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-01 18:21:54.215821 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-01 18:21:54.398386 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-01 18:21:54.678738 | 
2026-09-01 18:21:54.678966 | LOOP [Copy inventory file for Zuul]
2026-09-01 18:21:55.523380 | instance | changed:
2026-09-01 18:21:55.523622 | instance | {
2026-09-01 18:21:55.523662 | instance |   "ansible_loop_var": "item",
2026-09-01 18:21:55.523693 | instance |   "changed": false,
2026-09-01 18:21:55.523720 | instance |   "failed": false,
2026-09-01 18:21:55.523752 | instance |   "invocation": {
2026-09-01 18:21:55.523779 | instance |     "module_args": {
2026-09-01 18:21:55.523804 | instance |       "checksum_algorithm": "sha1",
2026-09-01 18:21:55.523828 | instance |       "follow": true,
2026-09-01 18:21:55.523853 | instance |       "get_attributes": true,
2026-09-01 18:21:55.523879 | instance |       "get_checksum": true,
2026-09-01 18:21:55.523903 | instance |       "get_mime": true,
2026-09-01 18:21:55.523928 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-01 18:21:55.523952 | instance |     }
2026-09-01 18:21:55.523976 | instance |   },
2026-09-01 18:21:55.524000 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-01 18:21:55.524023 | instance |   "stat": {
2026-09-01 18:21:55.524048 | instance |     "atime": 1788286893.4735804,
2026-09-01 18:21:55.524072 | instance |     "attr_flags": "e",
2026-09-01 18:21:55.524096 | instance |     "attributes": [
2026-09-01 18:21:55.524121 | instance |       "extents"
2026-09-01 18:21:55.524145 | instance |     ],
2026-09-01 18:21:55.524169 | instance |     "block_size": 4096,
2026-09-01 18:21:55.524192 | instance |     "blocks": 8,
2026-09-01 18:21:55.524215 | instance |     "charset": "unknown",
2026-09-01 18:21:55.524241 | instance |     "ctime": 1788286892.3575687,
2026-09-01 18:21:55.524265 | instance |     "dev": 64513,
2026-09-01 18:21:55.524290 | instance |     "device_type": 0,
2026-09-01 18:21:55.524314 | instance |     "executable": true,
2026-09-01 18:21:55.524338 | instance |     "exists": true,
2026-09-01 18:21:55.524362 | instance |     "gid": 1000,
2026-09-01 18:21:55.524385 | instance |     "gr_name": "zuul",
2026-09-01 18:21:55.524410 | instance |     "inode": 1047497,
2026-09-01 18:21:55.524433 | instance |     "isblk": false,
2026-09-01 18:21:55.524458 | instance |     "ischr": false,
2026-09-01 18:21:55.524482 | instance |     "isdir": true,
2026-09-01 18:21:55.524506 | instance |     "isfifo": false,
2026-09-01 18:21:55.524530 | instance |     "isgid": false,
2026-09-01 18:21:55.524553 | instance |     "islnk": false,
2026-09-01 18:21:55.524577 | instance |     "isreg": false,
2026-09-01 18:21:55.524600 | instance |     "issock": false,
2026-09-01 18:21:55.524623 | instance |     "isuid": false,
2026-09-01 18:21:55.524645 | instance |     "mimetype": "unknown",
2026-09-01 18:21:55.524669 | instance |     "mode": "0755",
2026-09-01 18:21:55.524692 | instance |     "mtime": 1788286892.3575687,
2026-09-01 18:21:55.524715 | instance |     "nlink": 21,
2026-09-01 18:21:55.524738 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-01 18:21:55.524761 | instance |     "pw_name": "zuul",
2026-09-01 18:21:55.524786 | instance |     "readable": true,
2026-09-01 18:21:55.524810 | instance |     "rgrp": true,
2026-09-01 18:21:55.524833 | instance |     "roth": true,
2026-09-01 18:21:55.524857 | instance |     "rusr": true,
2026-09-01 18:21:55.524881 | instance |     "size": 4096,
2026-09-01 18:21:55.524905 | instance |     "uid": 1000,
2026-09-01 18:21:55.524940 | instance |     "version": "4043428196",
2026-09-01 18:21:55.524989 | instance |     "wgrp": false,
2026-09-01 18:21:55.525017 | instance |     "woth": false,
2026-09-01 18:21:55.525042 | instance |     "writeable": true,
2026-09-01 18:21:55.525066 | instance |     "wusr": true,
2026-09-01 18:21:55.525090 | instance |     "xgrp": true,
2026-09-01 18:21:55.525114 | instance |     "xoth": true,
2026-09-01 18:21:55.525138 | instance |     "xusr": true
2026-09-01 18:21:55.525162 | instance |   }
2026-09-01 18:21:55.525186 | instance | }
2026-09-01 18:21:55.525232 | instance | ok: All items complete
2026-09-01 18:21:55.525261 | 
2026-09-01 18:21:55.526968 | instance | skipping: Conditional result was False
2026-09-01 18:21:55.527773 | instance | skipping: Conditional result was False
2026-09-01 18:21:55.544766 | 
2026-09-01 18:21:55.544931 | LOOP [Switch "ansible_host" to private IP]
2026-09-01 18:21:55.918108 | instance | changed: 1 replacements made
2026-09-01 18:21:55.985149 | instance | skipping: Conditional result was False
2026-09-01 18:21:55.986452 | instance | skipping: Conditional result was False
2026-09-01 18:21:56.011828 | 
2026-09-01 18:21:56.012566 | TASK [Run Molecule scenario]
2026-09-01 18:21:56.989488 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-01 18:21:57.335043 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-01 18:21:57.335164 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-01 18:22:08.162404 | instance | INFO     [csi > dependency] Executing
2026-09-01 18:22:08.162550 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-01 18:22:08.162670 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-01 18:22:08.162777 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-01 18:22:08.169338 | instance | INFO     [csi > cleanup] Executing
2026-09-01 18:22:08.169685 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-01 18:22:08.176239 | instance | INFO     [csi > destroy] Executing
2026-09-01 18:22:08.176279 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-01 18:22:08.176356 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-01 18:22:08.182740 | instance | INFO     [csi > syntax] Executing
2026-09-01 18:22:09.618437 | instance |
2026-09-01 18:22:09.618778 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-01 18:22:09.680312 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-01 18:22:09.689392 | instance | INFO     [csi > create] Executing
2026-09-01 18:22:09.692293 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-01 18:22:09.700415 | instance | INFO     [csi > prepare] Executing
2026-09-01 18:22:10.627786 | instance |
2026-09-01 18:22:10.628048 | instance | PLAY [Prepare] *****************************************************************
2026-09-01 18:22:10.628285 | instance |
2026-09-01 18:22:10.628788 | instance | TASK [Gathering Facts] *********************************************************
2026-09-01 18:22:10.629031 | instance | Tuesday 01 September 2026  18:22:10 +0000 (0:00:00.025)       0:00:00.025 *****
2026-09-01 18:22:11.842424 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-01 18:22:11.842539 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-01 18:22:11.842633 | instance | interpreter could change the meaning of that path. See
2026-09-01 18:22:11.842754 | instance | https://docs.ansible.com/ansible-
2026-09-01 18:22:11.842879 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-01 18:22:11.853100 | instance | ok: [instance]
2026-09-01 18:22:11.853199 | instance |
2026-09-01 18:22:11.853340 | instance | TASK [Configure short hostname] ************************************************
2026-09-01 18:22:11.853506 | instance | Tuesday 01 September 2026  18:22:11 +0000 (0:00:01.225)       0:00:01.250 *****
2026-09-01 18:22:12.647151 | instance | changed: [instance]
2026-09-01 18:22:12.647376 | instance |
2026-09-01 18:22:12.647694 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-01 18:22:12.647948 | instance | Tuesday 01 September 2026  18:22:12 +0000 (0:00:00.793)       0:00:02.044 *****
2026-09-01 18:22:12.912579 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-01 18:22:12.912631 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-01 18:22:12.912649 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-01 18:22:12.918266 | instance | changed: [instance]
2026-09-01 18:22:12.918413 | instance |
2026-09-01 18:22:12.918583 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-01 18:22:12.918725 | instance | Tuesday 01 September 2026  18:22:12 +0000 (0:00:00.271)       0:00:02.315 *****
2026-09-01 18:22:13.694781 | instance | ok: [instance]
2026-09-01 18:22:13.694862 | instance |
2026-09-01 18:22:13.694871 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-01 18:22:13.694878 | instance |
2026-09-01 18:22:13.694884 | instance | TASK [Gathering Facts] *********************************************************
2026-09-01 18:22:13.694925 | instance | Tuesday 01 September 2026  18:22:13 +0000 (0:00:00.774)       0:00:03.090 *****
2026-09-01 18:22:14.447275 | instance | ok: [instance]
2026-09-01 18:22:14.447504 | instance |
2026-09-01 18:22:14.447694 | instance | TASK [Install depedencies] *****************************************************
2026-09-01 18:22:14.447883 | instance | Tuesday 01 September 2026  18:22:14 +0000 (0:00:00.754)       0:00:03.844 *****
2026-09-01 18:22:39.187407 | instance | changed: [instance]
2026-09-01 18:22:39.187499 | instance |
2026-09-01 18:22:39.187855 | instance | TASK [Start up service] ********************************************************
2026-09-01 18:22:39.187895 | instance | Tuesday 01 September 2026  18:22:39 +0000 (0:00:24.740)       0:00:28.585 *****
2026-09-01 18:22:39.691524 | instance | ok: [instance]
2026-09-01 18:22:39.691588 | instance |
2026-09-01 18:22:39.691927 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-01 18:22:39.691963 | instance | Tuesday 01 September 2026  18:22:39 +0000 (0:00:00.504)       0:00:29.089 *****
2026-09-01 18:22:40.031764 | instance | ok: [instance]
2026-09-01 18:22:40.032508 | instance |
2026-09-01 18:22:40.032572 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-01 18:22:40.032584 | instance | Tuesday 01 September 2026  18:22:40 +0000 (0:00:00.339)       0:00:29.428 *****
2026-09-01 18:22:40.787025 | instance | changed: [instance]
2026-09-01 18:22:40.787926 | instance |
2026-09-01 18:22:40.787974 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-01 18:22:40.788007 | instance | Tuesday 01 September 2026  18:22:40 +0000 (0:00:00.754)       0:00:30.183 *****
2026-09-01 18:22:41.013015 | instance | ok: [instance]
2026-09-01 18:22:41.013126 | instance |
2026-09-01 18:22:41.013559 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-01 18:22:41.013582 | instance | Tuesday 01 September 2026  18:22:41 +0000 (0:00:00.226)       0:00:30.410 *****
2026-09-01 18:22:41.040320 | instance | skipping: [instance]
2026-09-01 18:22:41.040399 | instance |
2026-09-01 18:22:41.040786 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-01 18:22:41.040810 | instance | Tuesday 01 September 2026  18:22:41 +0000 (0:00:00.027)       0:00:30.437 *****
2026-09-01 18:22:41.630446 | instance | changed: [instance] => (item=osd0)
2026-09-01 18:22:41.630695 | instance | changed: [instance] => (item=osd1)
2026-09-01 18:22:41.630881 | instance | changed: [instance] => (item=osd2)
2026-09-01 18:22:41.631279 | instance |
2026-09-01 18:22:41.631587 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-01 18:22:41.631892 | instance | Tuesday 01 September 2026  18:22:41 +0000 (0:00:00.589)       0:00:31.027 *****
2026-09-01 18:22:42.314953 | instance | changed: [instance] => (item=osd0)
2026-09-01 18:22:42.315100 | instance | changed: [instance] => (item=osd1)
2026-09-01 18:22:42.315381 | instance | changed: [instance] => (item=osd2)
2026-09-01 18:22:42.315718 | instance |
2026-09-01 18:22:42.316038 | instance | TASK [Start loop devices] ******************************************************
2026-09-01 18:22:42.316338 | instance | Tuesday 01 September 2026  18:22:42 +0000 (0:00:00.685)       0:00:31.712 *****
2026-09-01 18:22:43.098230 | instance | changed: [instance] => (item=osd0)
2026-09-01 18:22:43.098294 | instance | changed: [instance] => (item=osd1)
2026-09-01 18:22:43.098925 | instance | changed: [instance] => (item=osd2)
2026-09-01 18:22:43.098986 | instance |
2026-09-01 18:22:43.098994 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-01 18:22:43.099000 | instance | Tuesday 01 September 2026  18:22:43 +0000 (0:00:00.783)       0:00:32.495 *****
2026-09-01 18:22:46.244561 | instance | changed: [instance] => (item=osd0)
2026-09-01 18:22:46.245270 | instance | changed: [instance] => (item=osd1)
2026-09-01 18:22:46.245321 | instance | changed: [instance] => (item=osd2)
2026-09-01 18:22:46.245328 | instance |
2026-09-01 18:22:46.245334 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-01 18:22:46.245340 | instance | Tuesday 01 September 2026  18:22:46 +0000 (0:00:03.146)       0:00:35.642 *****
2026-09-01 18:22:48.213175 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-01 18:22:48.213236 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-01 18:22:48.213263 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-01 18:22:48.213273 | instance |
2026-09-01 18:22:48.213475 | instance | PLAY RECAP *********************************************************************
2026-09-01 18:22:48.216530 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-01 18:22:48.216548 | instance |
2026-09-01 18:22:48.216558 | instance | Tuesday 01 September 2026  18:22:48 +0000 (0:00:01.968)       0:00:37.610 *****
2026-09-01 18:22:48.216568 | instance | ===============================================================================
2026-09-01 18:22:48.216576 | instance | Install depedencies ---------------------------------------------------- 24.74s
2026-09-01 18:22:48.216585 | instance | Create a volume group for each loop device ------------------------------ 3.15s
2026-09-01 18:22:48.216593 | instance | Create a logical volume for each loop device ---------------------------- 1.97s
2026-09-01 18:22:48.216603 | instance | Gathering Facts --------------------------------------------------------- 1.23s
2026-09-01 18:22:48.216612 | instance | Configure short hostname ------------------------------------------------ 0.79s
2026-09-01 18:22:48.216620 | instance | Start loop devices ------------------------------------------------------ 0.78s
2026-09-01 18:22:48.216629 | instance | Purge "snapd" package --------------------------------------------------- 0.77s
2026-09-01 18:22:48.216637 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.76s
2026-09-01 18:22:48.216646 | instance | Gathering Facts --------------------------------------------------------- 0.75s
2026-09-01 18:22:48.216657 | instance | Set permissions on loopback devices ------------------------------------- 0.69s
2026-09-01 18:22:48.216866 | instance | Create devices for Ceph ------------------------------------------------- 0.59s
2026-09-01 18:22:48.217208 | instance | Start up service -------------------------------------------------------- 0.50s
2026-09-01 18:22:48.217506 | instance | Generate lvm.conf ------------------------------------------------------- 0.34s
2026-09-01 18:22:48.217809 | instance | Ensure hostname inside hosts file --------------------------------------- 0.27s
2026-09-01 18:22:48.218114 | instance | Get list of all loopback devices ---------------------------------------- 0.23s
2026-09-01 18:22:48.218396 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s
2026-09-01 18:22:48.297180 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-01 18:22:48.311704 | instance | INFO     [csi > converge] Executing
2026-09-01 18:22:49.863801 | instance |
2026-09-01 18:22:49.864219 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-01 18:22:49.864541 | instance |
2026-09-01 18:22:49.864864 | instance | TASK [Gathering Facts] *********************************************************
2026-09-01 18:22:49.865174 | instance | Tuesday 01 September 2026  18:22:49 +0000 (0:00:00.008)       0:00:00.008 *****
2026-09-01 18:22:50.949921 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-01 18:22:50.950287 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-01 18:22:50.950590 | instance | interpreter could change the meaning of that path. See
2026-09-01 18:22:50.950933 | instance | https://docs.ansible.com/ansible-
2026-09-01 18:22:50.951239 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-01 18:22:50.960160 | instance | ok: [instance]
2026-09-01 18:22:50.960500 | instance |
2026-09-01 18:22:50.960817 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-01 18:22:50.961174 | instance | Tuesday 01 September 2026  18:22:50 +0000 (0:00:01.096)       0:00:01.104 *****
2026-09-01 18:22:51.026430 | instance | ok: [instance] => {
2026-09-01 18:22:51.026802 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-01 18:22:51.027134 | instance | }
2026-09-01 18:22:51.027411 | instance |
2026-09-01 18:22:51.027697 | instance | PLAY [all] *********************************************************************
2026-09-01 18:22:51.028000 | instance |
2026-09-01 18:22:51.028401 | instance | TASK [Gathering Facts] *********************************************************
2026-09-01 18:22:51.028905 | instance | Tuesday 01 September 2026  18:22:51 +0000 (0:00:00.065)       0:00:01.170 *****
2026-09-01 18:22:51.952538 | instance | ok: [instance]
2026-09-01 18:22:51.952919 | instance |
2026-09-01 18:22:51.953227 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-01 18:22:51.953520 | instance | Tuesday 01 September 2026  18:22:51 +0000 (0:00:00.926)       0:00:02.097 *****
2026-09-01 18:22:52.141151 | instance | ok: [instance]
2026-09-01 18:22:52.141478 | instance |
2026-09-01 18:22:52.141782 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-01 18:22:52.142202 | instance |
2026-09-01 18:22:52.142633 | instance | TASK [Gathering Facts] *********************************************************
2026-09-01 18:22:52.143088 | instance | Tuesday 01 September 2026  18:22:52 +0000 (0:00:00.188)       0:00:02.286 *****
2026-09-01 18:22:53.081057 | instance | ok: [instance]
2026-09-01 18:22:53.081150 | instance |
2026-09-01 18:22:53.081338 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-01 18:22:53.081518 | instance | Tuesday 01 September 2026  18:22:53 +0000 (0:00:00.938)       0:00:03.224 *****
2026-09-01 18:22:53.403726 | instance | ok: [instance]
2026-09-01 18:22:53.403981 | instance |
2026-09-01 18:22:53.404191 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-01 18:22:53.404413 | instance | Tuesday 01 September 2026  18:22:53 +0000 (0:00:00.321)       0:00:03.546 *****
2026-09-01 18:22:53.444575 | instance | skipping: [instance]
2026-09-01 18:22:53.444714 | instance |
2026-09-01 18:22:53.444918 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-01 18:22:53.445078 | instance | Tuesday 01 September 2026  18:22:53 +0000 (0:00:00.042)       0:00:03.589 *****
2026-09-01 18:22:53.798053 | instance | changed: [instance]
2026-09-01 18:22:53.798266 | instance |
2026-09-01 18:22:53.798507 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-01 18:22:53.798737 | instance | Tuesday 01 September 2026  18:22:53 +0000 (0:00:00.353)       0:00:03.942 *****
2026-09-01 18:22:53.861543 | instance | ok: [instance] => {
2026-09-01 18:22:53.861650 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-01 18:22:53.861756 | instance | }
2026-09-01 18:22:53.861870 | instance |
2026-09-01 18:22:53.861979 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-01 18:22:53.862084 | instance | Tuesday 01 September 2026  18:22:53 +0000 (0:00:00.063)       0:00:04.006 *****
2026-09-01 18:22:54.514730 | instance | changed: [instance]
2026-09-01 18:22:54.514956 | instance |
2026-09-01 18:22:54.515209 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-01 18:22:54.515445 | instance | Tuesday 01 September 2026  18:22:54 +0000 (0:00:00.652)       0:00:04.659 *****
2026-09-01 18:22:54.555094 | instance | skipping: [instance]
2026-09-01 18:22:54.555292 | instance |
2026-09-01 18:22:54.555533 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-01 18:22:54.555760 | instance | Tuesday 01 September 2026  18:22:54 +0000 (0:00:00.040)       0:00:04.699 *****
2026-09-01 18:22:54.599448 | instance | skipping: [instance]
2026-09-01 18:22:54.599663 | instance |
2026-09-01 18:22:54.599954 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-01 18:22:54.600189 | instance | Tuesday 01 September 2026  18:22:54 +0000 (0:00:00.044)       0:00:04.744 *****
2026-09-01 18:22:54.785156 | instance | ok: [instance]
2026-09-01 18:22:54.785260 | instance |
2026-09-01 18:22:54.785405 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-01 18:22:54.785541 | instance | Tuesday 01 September 2026  18:22:54 +0000 (0:00:00.185)       0:00:04.929 *****
2026-09-01 18:22:55.920113 | instance | ok: [instance]
2026-09-01 18:22:55.920405 | instance |
2026-09-01 18:22:55.920756 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-01 18:22:55.921066 | instance | Tuesday 01 September 2026  18:22:55 +0000 (0:00:01.134)       0:00:06.064 *****
2026-09-01 18:22:55.985328 | instance | ok: [instance] => {
2026-09-01 18:22:55.985529 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-01 18:22:55.985834 | instance | }
2026-09-01 18:22:55.986063 | instance |
2026-09-01 18:22:55.986302 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-01 18:22:55.986540 | instance | Tuesday 01 September 2026  18:22:55 +0000 (0:00:00.065)       0:00:06.130 *****
2026-09-01 18:22:56.785106 | instance | changed: [instance]
2026-09-01 18:22:56.785377 | instance |
2026-09-01 18:22:56.785670 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-01 18:22:56.785983 | instance | Tuesday 01 September 2026  18:22:56 +0000 (0:00:00.799)       0:00:06.929 *****
2026-09-01 18:22:59.739259 | instance | changed: [instance]
2026-09-01 18:22:59.739633 | instance |
2026-09-01 18:22:59.739977 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-01 18:22:59.740288 | instance | Tuesday 01 September 2026  18:22:59 +0000 (0:00:02.953)       0:00:09.882 *****
2026-09-01 18:22:59.781157 | instance | skipping: [instance]
2026-09-01 18:22:59.781531 | instance |
2026-09-01 18:22:59.781844 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-01 18:22:59.782170 | instance | Tuesday 01 September 2026  18:22:59 +0000 (0:00:00.040)       0:00:09.923 *****
2026-09-01 18:22:59.816634 | instance | skipping: [instance]
2026-09-01 18:22:59.816718 | instance |
2026-09-01 18:22:59.816943 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-01 18:22:59.817209 | instance | Tuesday 01 September 2026  18:22:59 +0000 (0:00:00.037)       0:00:09.960 *****
2026-09-01 18:22:59.857478 | instance | skipping: [instance]
2026-09-01 18:22:59.857662 | instance |
2026-09-01 18:22:59.857935 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-01 18:22:59.858173 | instance | Tuesday 01 September 2026  18:22:59 +0000 (0:00:00.041)       0:00:10.002 *****
2026-09-01 18:23:06.100053 | instance | changed: [instance]
2026-09-01 18:23:06.100192 | instance |
2026-09-01 18:23:06.100453 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-01 18:23:06.100743 | instance | Tuesday 01 September 2026  18:23:06 +0000 (0:00:06.241)       0:00:16.243 *****
2026-09-01 18:23:06.758810 | instance | changed: [instance]
2026-09-01 18:23:06.758936 | instance |
2026-09-01 18:23:06.759196 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-01 18:23:06.759426 | instance | Tuesday 01 September 2026  18:23:06 +0000 (0:00:00.658)       0:00:16.901 *****
2026-09-01 18:23:07.716546 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-01 18:23:07.716605 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-01 18:23:07.716614 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-01 18:23:07.716620 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-01 18:23:07.716632 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-01 18:23:07.716790 | instance |
2026-09-01 18:23:07.717287 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-01 18:23:07.717638 | instance | Tuesday 01 September 2026  18:23:07 +0000 (0:00:00.957)       0:00:17.859 *****
2026-09-01 18:23:08.322381 | instance | changed: [instance]
2026-09-01 18:23:08.323676 | instance |
2026-09-01 18:23:08.323729 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-01 18:23:08.323739 | instance | Tuesday 01 September 2026  18:23:08 +0000 (0:00:00.579)       0:00:18.439 *****
2026-09-01 18:23:08.323747 | instance |
2026-09-01 18:23:08.323755 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-01 18:23:08.323763 | instance | Tuesday 01 September 2026  18:23:08 +0000 (0:00:00.027)       0:00:18.466 *****
2026-09-01 18:23:09.231640 | instance | ok: [instance]
2026-09-01 18:23:09.232192 | instance |
2026-09-01 18:23:09.232211 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-01 18:23:09.232219 | instance | Tuesday 01 September 2026  18:23:09 +0000 (0:00:00.909)       0:00:19.376 *****
2026-09-01 18:23:09.683914 | instance | changed: [instance]
2026-09-01 18:23:09.683998 | instance |
2026-09-01 18:23:09.684345 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-01 18:23:09.684615 | instance | Tuesday 01 September 2026  18:23:09 +0000 (0:00:00.452)       0:00:19.828 *****
2026-09-01 18:23:10.258493 | instance | changed: [instance]
2026-09-01 18:23:10.258569 | instance |
2026-09-01 18:23:10.258905 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-01 18:23:10.258943 | instance | Tuesday 01 September 2026  18:23:10 +0000 (0:00:00.574)       0:00:20.403 *****
2026-09-01 18:23:10.490338 | instance | ok: [instance]
2026-09-01 18:23:10.491386 | instance |
2026-09-01 18:23:10.491424 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-01 18:23:10.491438 | instance | Tuesday 01 September 2026  18:23:10 +0000 (0:00:00.231)       0:00:20.634 *****
2026-09-01 18:23:10.567958 | instance | ok: [instance] => {
2026-09-01 18:23:10.568609 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-01 18:23:10.569868 | instance | }
2026-09-01 18:23:10.569906 | instance |
2026-09-01 18:23:10.569923 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-01 18:23:10.569937 | instance | Tuesday 01 September 2026  18:23:10 +0000 (0:00:00.077)       0:00:20.712 *****
2026-09-01 18:23:11.357890 | instance | changed: [instance]
2026-09-01 18:23:11.358634 | instance |
2026-09-01 18:23:11.358664 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-01 18:23:11.358676 | instance | Tuesday 01 September 2026  18:23:11 +0000 (0:00:00.790)       0:00:21.502 *****
2026-09-01 18:23:15.556041 | instance | changed: [instance]
2026-09-01 18:23:15.556554 | instance |
2026-09-01 18:23:15.556705 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-01 18:23:15.556986 | instance | Tuesday 01 September 2026  18:23:15 +0000 (0:00:04.197)       0:00:25.700 *****
2026-09-01 18:23:16.681736 | instance | ok: [instance]
2026-09-01 18:23:16.681980 | instance |
2026-09-01 18:23:16.682279 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-01 18:23:16.682565 | instance | Tuesday 01 September 2026  18:23:16 +0000 (0:00:01.125)       0:00:26.825 *****
2026-09-01 18:23:17.010707 | instance | changed: [instance]
2026-09-01 18:23:17.010918 | instance |
2026-09-01 18:23:17.011179 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-01 18:23:17.011418 | instance | Tuesday 01 September 2026  18:23:17 +0000 (0:00:00.329)       0:00:27.155 *****
2026-09-01 18:23:17.445482 | instance | changed: [instance]
2026-09-01 18:23:17.445673 | instance |
2026-09-01 18:23:17.445899 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-01 18:23:17.446143 | instance | Tuesday 01 September 2026  18:23:17 +0000 (0:00:00.434)       0:00:27.589 *****
2026-09-01 18:23:18.037041 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-01 18:23:18.037354 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-01 18:23:18.037373 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-01 18:23:18.037383 | instance |
2026-09-01 18:23:18.037393 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-01 18:23:18.037402 | instance | Tuesday 01 September 2026  18:23:18 +0000 (0:00:00.590)       0:00:28.179 *****
2026-09-01 18:23:18.516342 | instance | changed: [instance]
2026-09-01 18:23:18.516611 | instance |
2026-09-01 18:23:18.517174 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-01 18:23:18.517209 | instance | Tuesday 01 September 2026  18:23:18 +0000 (0:00:00.481)       0:00:28.660 *****
2026-09-01 18:23:19.024491 | instance | changed: [instance]
2026-09-01 18:23:19.025212 | instance |
2026-09-01 18:23:19.025251 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-01 18:23:19.025256 | instance | Tuesday 01 September 2026  18:23:18 +0000 (0:00:00.488)       0:00:29.148 *****
2026-09-01 18:23:19.025261 | instance |
2026-09-01 18:23:19.025265 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-01 18:23:19.025269 | instance | Tuesday 01 September 2026  18:23:19 +0000 (0:00:00.020)       0:00:29.169 *****
2026-09-01 18:23:19.813355 | instance | ok: [instance]
2026-09-01 18:23:19.813470 | instance |
2026-09-01 18:23:19.813766 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-01 18:23:19.813825 | instance | Tuesday 01 September 2026  18:23:19 +0000 (0:00:00.788)       0:00:29.957 *****
2026-09-01 18:23:20.894006 | instance | changed: [instance]
2026-09-01 18:23:20.894102 | instance |
2026-09-01 18:23:20.894393 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-01 18:23:20.894412 | instance | Tuesday 01 September 2026  18:23:20 +0000 (0:00:01.080)       0:00:31.038 *****
2026-09-01 18:23:21.565279 | instance | changed: [instance]
2026-09-01 18:23:21.565391 | instance |
2026-09-01 18:23:21.565722 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-01 18:23:21.565762 | instance | Tuesday 01 September 2026  18:23:21 +0000 (0:00:00.671)       0:00:31.709 *****
2026-09-01 18:23:21.636139 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-01 18:23:21.636242 | instance |
2026-09-01 18:23:21.636727 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-01 18:23:21.636838 | instance | Tuesday 01 September 2026  18:23:21 +0000 (0:00:00.070)       0:00:31.780 *****
2026-09-01 18:23:27.296000 | instance | changed: [instance]
2026-09-01 18:23:27.296534 | instance |
2026-09-01 18:23:27.297057 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-01 18:23:27.297105 | instance | Tuesday 01 September 2026  18:23:27 +0000 (0:00:05.659)       0:00:37.440 *****
2026-09-01 18:23:27.961463 | instance | ok: [instance] => (item=chronyd)
2026-09-01 18:23:27.962071 | instance | ok: [instance] => (item=sshd)
2026-09-01 18:23:27.962117 | instance |
2026-09-01 18:23:27.962124 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-01 18:23:27.962131 | instance | Tuesday 01 September 2026  18:23:27 +0000 (0:00:00.665)       0:00:38.105 *****
2026-09-01 18:23:28.929990 | instance | changed: [instance]
2026-09-01 18:23:28.930044 | instance |
2026-09-01 18:23:28.930055 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-09-01 18:23:28.930065 | instance | Tuesday 01 September 2026  18:23:28 +0000 (0:00:00.967)       0:00:39.073 *****
2026-09-01 18:23:29.142089 | instance | ok: [instance]
2026-09-01 18:23:29.142128 | instance |
2026-09-01 18:23:29.142134 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-09-01 18:23:29.142138 | instance | Tuesday 01 September 2026  18:23:29 +0000 (0:00:00.213)       0:00:39.286 *****
2026-09-01 18:23:29.653746 | instance | changed: [instance]
2026-09-01 18:23:29.654624 | instance |
2026-09-01 18:23:29.654681 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-09-01 18:23:29.654693 | instance | Tuesday 01 September 2026  18:23:29 +0000 (0:00:00.510)       0:00:39.797 *****
2026-09-01 18:23:30.016058 | instance | changed: [instance]
2026-09-01 18:23:30.016184 | instance |
2026-09-01 18:23:30.016521 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-09-01 18:23:30.016566 | instance | Tuesday 01 September 2026  18:23:30 +0000 (0:00:00.362)       0:00:40.160 *****
2026-09-01 18:23:30.129585 | instance | ok: [instance]
2026-09-01 18:23:30.130291 | instance |
2026-09-01 18:23:30.130335 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-09-01 18:23:30.130347 | instance | Tuesday 01 September 2026  18:23:30 +0000 (0:00:00.113)       0:00:40.273 *****
2026-09-01 18:23:30.358070 | instance | ok: [instance] => (item=instance)
2026-09-01 18:23:30.358154 | instance |
2026-09-01 18:23:30.358440 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-09-01 18:23:30.358490 | instance | Tuesday 01 September 2026  18:23:30 +0000 (0:00:00.228)       0:00:40.502 *****
2026-09-01 18:23:30.419181 | instance | ok: [instance]
2026-09-01 18:23:30.419307 | instance |
2026-09-01 18:23:30.419599 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-09-01 18:23:30.419639 | instance | Tuesday 01 September 2026  18:23:30 +0000 (0:00:00.060)       0:00:40.563 *****
2026-09-01 18:23:30.506939 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-09-01 18:23:30.507265 | instance |
2026-09-01 18:23:30.507299 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-09-01 18:23:30.507305 | instance | Tuesday 01 September 2026  18:23:30 +0000 (0:00:00.087)       0:00:40.651 *****
2026-09-01 18:23:30.833362 | instance | changed: [instance]
2026-09-01 18:23:30.833442 | instance |
2026-09-01 18:23:30.833747 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-09-01 18:23:30.833786 | instance | Tuesday 01 September 2026  18:23:30 +0000 (0:00:00.326)       0:00:40.978 *****
2026-09-01 18:23:31.531513 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-09-01 18:23:31.532203 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-09-01 18:23:31.532221 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-09-01 18:23:31.532227 | instance |
2026-09-01 18:23:31.532233 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-09-01 18:23:31.532239 | instance | Tuesday 01 September 2026  18:23:31 +0000 (0:00:00.698)       0:00:41.676 *****
2026-09-01 18:25:02.265376 | instance | ok: [instance]
2026-09-01 18:25:02.265828 | instance |
2026-09-01 18:25:02.265875 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-09-01 18:25:02.265882 | instance | Tuesday 01 September 2026  18:25:02 +0000 (0:01:30.733)       0:02:12.409 *****
2026-09-01 18:25:02.532071 | instance | changed: [instance]
2026-09-01 18:25:02.532121 | instance |
2026-09-01 18:25:02.532129 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-09-01 18:25:02.532136 | instance | Tuesday 01 September 2026  18:25:02 +0000 (0:00:00.266)       0:02:12.676 *****
2026-09-01 18:25:02.609210 | instance | ok: [instance]
2026-09-01 18:25:02.609248 | instance |
2026-09-01 18:25:02.609254 | instance | TASK [Install Ceph host] *******************************************************
2026-09-01 18:25:02.609259 | instance | Tuesday 01 September 2026  18:25:02 +0000 (0:00:00.077)       0:02:12.753 *****
2026-09-01 18:25:02.680635 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-09-01 18:25:02.680689 | instance |
2026-09-01 18:25:02.680699 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-09-01 18:25:02.680713 | instance | Tuesday 01 September 2026  18:25:02 +0000 (0:00:00.071)       0:02:12.825 *****
2026-09-01 18:25:04.335670 | instance | ok: [instance]
2026-09-01 18:25:04.335717 | instance |
2026-09-01 18:25:04.335724 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-09-01 18:25:04.335730 | instance | Tuesday 01 September 2026  18:25:04 +0000 (0:00:01.654)       0:02:14.480 *****
2026-09-01 18:25:04.415908 | instance | ok: [instance] => (item=instance)
2026-09-01 18:25:04.415961 | instance |
2026-09-01 18:25:04.415973 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-09-01 18:25:04.415982 | instance | Tuesday 01 September 2026  18:25:04 +0000 (0:00:00.079)       0:02:14.559 *****
2026-09-01 18:25:04.790777 | instance | ok: [instance]
2026-09-01 18:25:04.790833 | instance |
2026-09-01 18:25:04.790845 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-09-01 18:25:04.790855 | instance | Tuesday 01 September 2026  18:25:04 +0000 (0:00:00.374)       0:02:14.934 *****
2026-09-01 18:25:07.271787 | instance | ok: [instance]
2026-09-01 18:25:07.272535 | instance |
2026-09-01 18:25:07.272613 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-09-01 18:25:07.272622 | instance | Tuesday 01 September 2026  18:25:07 +0000 (0:00:02.480)       0:02:17.415 *****
2026-09-01 18:25:57.402717 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster)
2026-09-01 18:25:57.403189 | 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_xhot9k7x/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_xhot9k7x/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_xhot9k7x/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_xhot9k7x/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 120, in apply_spec\nRuntimeError: Inferring config /var/lib/ceph/4837cbf8-4f90-4300-b3f6-726c9b9f89b4/mon.instance/config\n2026-09-01T18:25:57.173+0000 7fcf3616f640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgBRGJdqaEm0ByAAluBApLBWxmRo9dskpSs2priYA6f7r5FRZ3MLfIa+NQk=: Malformed input [buffer:3]\n2026-09-01T18:25:57.173+0000 7fcf3616f640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-01T18:25:57.177+0000 7fcf3616f640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgBRGJdqaEm0ByAAluBApLBWxmRo9dskpSs2priYA6f7r5FRZ3MLfIa+NQk=: Malformed input [buffer:3]\n2026-09-01T18:25:57.177+0000 7fcf3616f640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-01T18:25:57.177+0000 7fcf3616f640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgBRGJdqaEm0ByAAluBApLBWxmRo9dskpSs2priYA6f7r5FRZ3MLfIa+NQk=: Malformed input [buffer:3]\n2026-09-01T18:25:57.177+0000 7fcf3616f640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-01T18:25:57.177+0000 7fcf3616f640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1}
2026-09-01 18:25:57.405014 | instance |
2026-09-01 18:25:57.405145 | instance | PLAY RECAP *********************************************************************
2026-09-01 18:25:57.405291 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2026-09-01 18:25:57.408178 | instance |
2026-09-01 18:25:57.408197 | instance | Tuesday 01 September 2026  18:25:57 +0000 (0:00:50.135)       0:03:07.550 *****
2026-09-01 18:25:57.408204 | instance | ===============================================================================
2026-09-01 18:25:57.408209 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 90.73s
2026-09-01 18:25:57.408214 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 50.14s
2026-09-01 18:25:57.408219 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 6.24s
2026-09-01 18:25:57.408224 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.66s
2026-09-01 18:25:57.408229 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.20s
2026-09-01 18:25:57.408234 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.95s
2026-09-01 18:25:57.408239 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.48s
2026-09-01 18:25:57.408244 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.70s
2026-09-01 18:25:57.408257 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.65s
2026-09-01 18:25:57.408262 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.13s
2026-09-01 18:25:57.408267 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.13s
2026-09-01 18:25:57.408272 | instance | Gathering Facts --------------------------------------------------------- 1.10s
2026-09-01 18:25:57.408277 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 1.08s
2026-09-01 18:25:57.408282 | instance | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 0.97s
2026-09-01 18:25:57.408287 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.96s
2026-09-01 18:25:57.408292 | instance | Gathering Facts --------------------------------------------------------- 0.94s
2026-09-01 18:25:57.408297 | instance | Gathering Facts --------------------------------------------------------- 0.93s
2026-09-01 18:25:57.408302 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.80s
2026-09-01 18:25:57.408307 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.79s
2026-09-01 18:25:57.408312 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.70s
2026-09-01 18:25:57.508609 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-01 18:25:57.508662 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-01 18:25:57.508669 | instance | ERROR    Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-01 18:25:57.857346 | instance | ERROR
2026-09-01 18:25:57.857581 | instance | {
2026-09-01 18:25:57.857637 | instance |   "delta": "0:04:01.146279",
2026-09-01 18:25:57.857668 | instance |   "end": "2026-09-01 18:25:57.573058",
2026-09-01 18:25:57.857694 | instance |   "msg": "non-zero return code",
2026-09-01 18:25:57.857720 | instance |   "rc": 2,
2026-09-01 18:25:57.857745 | instance |   "start": "2026-09-01 18:21:56.426779"
2026-09-01 18:25:57.858046 | instance | }
failure
2026-09-01 18:25:57.868566 | 
2026-09-01 18:25:57.868777 | PLAY RECAP
2026-09-01 18:25:57.868877 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-01 18:25:57.868926 | 
2026-09-01 18:25:58.057933 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-01 18:25:58.062926 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-01 18:25:58.697484 | 
2026-09-01 18:25:58.697665 | PLAY [all]
2026-09-01 18:25:58.713539 | 
2026-09-01 18:25:58.713617 | TASK [gather-host-logs : creating directory for system status]
2026-09-01 18:25:59.057553 | instance | changed
2026-09-01 18:25:59.063717 | 
2026-09-01 18:25:59.063805 | TASK [gather-host-logs : Get logs for each host]
2026-09-01 18:25:59.433036 | instance | + systemd-cgls --full --all --no-pager
2026-09-01 18:25:59.451954 | instance | + ip addr
2026-09-01 18:25:59.454783 | instance | + ip route
2026-09-01 18:25:59.457424 | instance | + lsblk
2026-09-01 18:25:59.461907 | instance | + mount
2026-09-01 18:25:59.464624 | instance | + docker images
2026-09-01 18:25:59.488272 | instance | + brctl show
2026-09-01 18:25:59.489065 | instance | /bin/bash: line 8: brctl: command not found
2026-09-01 18:25:59.489319 | instance | + ps aux --sort=-%mem
2026-09-01 18:25:59.512149 | instance | + dpkg -l
2026-09-01 18:25:59.524338 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-01 18:25:59.525337 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-01 18:25:59.547196 | instance | + '[' '!' -z '' ']'
2026-09-01 18:25:59.597529 | instance | ok: Runtime: 0:00:00.118064
2026-09-01 18:25:59.603880 | 
2026-09-01 18:25:59.603954 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-01 18:26:00.235829 | instance | changed:
2026-09-01 18:26:00.236081 | instance | created directory /var/lib/zuul/builds/ecd2c083d5524ba9891eec0e4cbae6ff/work/logs/instance
2026-09-01 18:26:00.236143 | instance | cd+++++++++ system/
2026-09-01 18:26:00.236192 | instance | >f+++++++++ system/brctl-show.txt
2026-09-01 18:26:00.236238 | instance | >f+++++++++ system/docker-images.txt
2026-09-01 18:26:00.236282 | instance | >f+++++++++ system/ip-addr.txt
2026-09-01 18:26:00.236330 | instance | >f+++++++++ system/ip-route.txt
2026-09-01 18:26:00.236374 | instance | >f+++++++++ system/lsblk.txt
2026-09-01 18:26:00.236419 | instance | >f+++++++++ system/mount.txt
2026-09-01 18:26:00.236461 | instance | >f+++++++++ system/packages.txt
2026-09-01 18:26:00.236503 | instance | >f+++++++++ system/ps.txt
2026-09-01 18:26:00.236548 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-01 18:26:00.246109 | 
2026-09-01 18:26:00.246178 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-01 18:26:00.462713 | instance | changed: "values"
2026-09-01 18:26:00.674907 | instance | changed: "releases"
2026-09-01 18:26:00.686619 | 
2026-09-01 18:26:00.686759 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-01 18:26:00.914522 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-01 18:26:01.235846 | instance | ok: Runtime: 0:00:00.007826
2026-09-01 18:26:01.242782 | 
2026-09-01 18:26:01.242886 | TASK [helm-release-status : Downloads logs to executor]
2026-09-01 18:26:01.722997 | instance | changed:
2026-09-01 18:26:01.723280 | instance | cd+++++++++ helm/
2026-09-01 18:26:01.723339 | instance | cd+++++++++ helm/releases/
2026-09-01 18:26:01.723386 | instance | cd+++++++++ helm/values/
2026-09-01 18:26:01.733377 | 
2026-09-01 18:26:01.733459 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-01 18:26:01.948175 | instance | changed
2026-09-01 18:26:01.957136 | 
2026-09-01 18:26:01.957240 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-01 18:26:02.215082 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-01 18:26:02.215180 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-01 18:26:02.219675 | instance | environment: line 1: kubectl: command not found
2026-09-01 18:26:02.221107 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-01 18:26:02.221480 | instance | environment: line 1: kubectl: command not found
2026-09-01 18:26:02.223818 | instance | environment: line 1: kubectl: command not found
2026-09-01 18:26:02.224385 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-01 18:26:02.224522 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-01 18:26:02.226527 | instance | environment: line 1: kubectl: command not found
2026-09-01 18:26:02.227411 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-01 18:26:02.227764 | instance | environment: line 1: kubectl: command not found
2026-09-01 18:26:02.228970 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-01 18:26:02.497460 | instance | ok: Runtime: 0:00:00.021000
2026-09-01 18:26:02.510694 | 
2026-09-01 18:26:02.510821 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-01 18:26:02.731553 | instance | changed
2026-09-01 18:26:02.736807 | 
2026-09-01 18:26:02.736879 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-01 18:26:03.016628 | instance | environment: line 5: kubectl: command not found
2026-09-01 18:26:03.017389 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-01 18:26:03.017713 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-01 18:26:03.017806 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-01 18:26:03.281178 | instance | ok: Runtime: 0:00:00.006702
2026-09-01 18:26:03.289091 | 
2026-09-01 18:26:03.289193 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-01 18:26:03.788504 | instance | changed:
2026-09-01 18:26:03.788715 | instance | cd+++++++++ objects/
2026-09-01 18:26:03.788742 | instance | cd+++++++++ objects/cluster/
2026-09-01 18:26:03.788765 | instance | cd+++++++++ objects/namespaced/
2026-09-01 18:26:03.798000 | 
2026-09-01 18:26:03.798077 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-01 18:26:04.014899 | instance | changed
2026-09-01 18:26:04.022386 | 
2026-09-01 18:26:04.022477 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-01 18:26:04.240948 | instance | changed
2026-09-01 18:26:04.247817 | 
2026-09-01 18:26:04.247890 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-01 18:26:04.476170 | instance | environment: line 3: kubectl: command not found
2026-09-01 18:26:04.784173 | instance | ok: Runtime: 0:00:00.005938
2026-09-01 18:26:04.790035 | 
2026-09-01 18:26:04.790103 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-01 18:26:05.270398 | instance | changed:
2026-09-01 18:26:05.270731 | instance | cd+++++++++ pod-logs/
2026-09-01 18:26:05.270825 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-01 18:26:05.280790 | 
2026-09-01 18:26:05.280858 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-01 18:26:05.498980 | instance | changed
2026-09-01 18:26:05.504344 | 
2026-09-01 18:26:05.504415 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-01 18:26:05.733680 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-01 18:26:06.041980 | instance | ok: Runtime: 0:00:00.051853
2026-09-01 18:26:06.049392 | 
2026-09-01 18:26:06.049505 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-01 18:26:06.251489 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-01 18:26:06.295627 | instance | ceph-mgr endpoints:
2026-09-01 18:26:06.588273 | instance | ok: Runtime: 0:00:00.050012
2026-09-01 18:26:06.595204 | 
2026-09-01 18:26:06.595290 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-01 18:26:06.793123 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-01 18:26:07.134645 | instance | ok: Runtime: 0:00:00.053171
2026-09-01 18:26:07.140173 | 
2026-09-01 18:26:07.140242 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-01 18:26:07.649054 | instance | changed: cd+++++++++ prometheus/
2026-09-01 18:26:07.660729 | 
2026-09-01 18:26:07.660796 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-01 18:26:07.889780 | instance | changed
2026-09-01 18:26:07.896414 | 
2026-09-01 18:26:07.896500 | TASK [gather-selenium-data : Get selenium data]
2026-09-01 18:26:08.150794 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-01 18:26:08.152812 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-01 18:26:08.435888 | instance | ERROR
2026-09-01 18:26:08.436132 | instance | {
2026-09-01 18:26:08.436199 | instance |   "delta": "0:00:00.006188",
2026-09-01 18:26:08.436244 | instance |   "end": "2026-09-01 18:26:08.153225",
2026-09-01 18:26:08.436289 | instance |   "msg": "non-zero return code",
2026-09-01 18:26:08.436375 | instance |   "rc": 1,
2026-09-01 18:26:08.436429 | instance |   "start": "2026-09-01 18:26:08.147037"
2026-09-01 18:26:08.436470 | instance | }
2026-09-01 18:26:08.436547 | instance | ERROR: Ignoring Errors
2026-09-01 18:26:08.445157 | 
2026-09-01 18:26:08.445235 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-01 18:26:08.917157 | instance | changed: cd+++++++++ selenium/
2026-09-01 18:26:08.923433 | 
2026-09-01 18:26:08.923502 | PLAY RECAP
2026-09-01 18:26:08.923555 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-01 18:26:08.923576 | 
2026-09-01 18:26:09.126123 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-01 18:26:09.141120 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-01 18:26:09.934130 | 
2026-09-01 18:26:09.934276 | PLAY [all]
2026-09-01 18:26:09.945955 | 
2026-09-01 18:26:09.946031 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-01 18:26:09.991275 | instance | skipping: Conditional result was False
2026-09-01 18:26:10.000068 | 
2026-09-01 18:26:10.000177 | TASK [fetch-output : Set log path for single node]
2026-09-01 18:26:10.043034 | instance | ok
2026-09-01 18:26:10.049180 | 
2026-09-01 18:26:10.049266 | LOOP [fetch-output : Ensure local output dirs]
2026-09-01 18:26:10.476224 | instance -> localhost | ok: "/var/lib/zuul/builds/ecd2c083d5524ba9891eec0e4cbae6ff/work/logs"
2026-09-01 18:26:10.677452 | instance -> localhost | changed: "/var/lib/zuul/builds/ecd2c083d5524ba9891eec0e4cbae6ff/work/artifacts"
2026-09-01 18:26:10.895013 | instance -> localhost | changed: "/var/lib/zuul/builds/ecd2c083d5524ba9891eec0e4cbae6ff/work/docs"
2026-09-01 18:26:10.911453 | 
2026-09-01 18:26:10.911665 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-01 18:26:11.570237 | instance | changed: .d..t...... ./
2026-09-01 18:26:11.570504 | instance | changed: All items complete
2026-09-01 18:26:11.570551 | 
2026-09-01 18:26:12.052473 | instance | changed: .d..t...... ./
2026-09-01 18:26:12.507401 | instance | changed: .d..t...... ./
2026-09-01 18:26:12.526861 | 
2026-09-01 18:26:12.527697 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-01 18:26:12.960709 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007090
2026-09-01 18:26:13.187239 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006828
2026-09-01 18:26:13.206871 | 
2026-09-01 18:26:13.207007 | PLAY [all]
2026-09-01 18:26:13.213636 | 
2026-09-01 18:26:13.213703 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-01 18:26:13.632742 | instance | changed
2026-09-01 18:26:13.640283 | 
2026-09-01 18:26:13.640351 | PLAY RECAP
2026-09-01 18:26:13.640411 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-01 18:26:13.640442 | 
2026-09-01 18:26:13.785516 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-01 18:26:13.787847 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-01 18:26:14.361383 | 
2026-09-01 18:26:14.361520 | PLAY [localhost]
2026-09-01 18:26:14.371366 | 
2026-09-01 18:26:14.371447 | TASK [Generate Zuul manifest]
2026-09-01 18:26:14.390445 | localhost | ok
2026-09-01 18:26:14.408672 | 
2026-09-01 18:26:14.408749 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-01 18:26:14.766290 | localhost | changed
2026-09-01 18:26:14.778993 | 
2026-09-01 18:26:14.779189 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-01 18:26:14.821042 | localhost | ok
2026-09-01 18:26:14.830123 | 
2026-09-01 18:26:14.830234 | TASK [Upload logs]
2026-09-01 18:26:14.851498 | localhost | ok
2026-09-01 18:26:14.912798 | 
2026-09-01 18:26:14.912979 | TASK [Set zuul-log-path fact]
2026-09-01 18:26:14.934907 | localhost | ok
2026-09-01 18:26:14.948383 | 
2026-09-01 18:26:14.948451 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-01 18:26:14.974494 | localhost | ok
2026-09-01 18:26:14.983557 | 
2026-09-01 18:26:14.983651 | TASK [upload-logs : Create log directories]
2026-09-01 18:26:15.377147 | localhost | changed
2026-09-01 18:26:15.385049 | 
2026-09-01 18:26:15.385170 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-01 18:26:15.803434 | localhost -> localhost | ok: Runtime: 0:00:00.007236
2026-09-01 18:26:15.809991 | 
2026-09-01 18:26:15.810060 | TASK [upload-logs : Upload logs to log server]
2026-09-01 18:26:16.292487 | localhost | Output suppressed because no_log was given
2026-09-01 18:26:16.331259 | 
2026-09-01 18:26:16.331415 | LOOP [upload-logs : Compress console log and json output]
2026-09-01 18:26:16.376297 | localhost | skipping: Conditional result was False
2026-09-01 18:26:16.383240 | localhost | skipping: Conditional result was False
2026-09-01 18:26:16.395939 | 
2026-09-01 18:26:16.401543 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-01 18:26:16.446786 | localhost | skipping: Conditional result was False
2026-09-01 18:26:16.447200 | 
2026-09-01 18:26:16.450997 | localhost | skipping: Conditional result was False
2026-09-01 18:26:16.464793 | 
2026-09-01 18:26:16.465113 | LOOP [upload-logs : Upload console log and json output]