2026-07-28 05:15:24.564242 | Job console starting
2026-07-28 05:15:24.574697 | Updating git repos
2026-07-28 05:15:26.827422 | Cloning repos into workspace
2026-07-28 05:15:40.000891 | Restoring repo states
2026-07-28 05:15:51.035510 | Merging changes
2026-07-28 05:15:52.001911 | Checking out repos
2026-07-28 05:15:53.490475 | Preparing playbooks
2026-07-28 05:15:55.807708 | Running Ansible setup
2026-07-28 05:15:59.093309 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-28 05:15:59.693732 | 
2026-07-28 05:15:59.693870 | PLAY [localhost]
2026-07-28 05:15:59.701227 | 
2026-07-28 05:15:59.701296 | TASK [Gathering Facts]
2026-07-28 05:16:00.570645 | localhost | ok
2026-07-28 05:16:00.580220 | 
2026-07-28 05:16:00.580324 | TASK [Setup log path fact]
2026-07-28 05:16:00.600304 | localhost | ok
2026-07-28 05:16:00.612834 | 
2026-07-28 05:16:00.612901 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-28 05:16:00.641185 | localhost | ok
2026-07-28 05:16:00.650650 | 
2026-07-28 05:16:00.650714 | TASK [emit-job-header : Print job information]
2026-07-28 05:16:00.689587 | # Job Information
2026-07-28 05:16:00.689792 | Ansible Version: 2.16.18
2026-07-28 05:16:00.689859 | Job: atmosphere-molecule-csi-rbd-selective
2026-07-28 05:16:00.689906 | Pipeline: check
2026-07-28 05:16:00.689949 | Executor: 2d72f0692154
2026-07-28 05:16:00.689991 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4152
2026-07-28 05:16:00.690038 | Event ID: f82624c0-8a42-11f1-86dd-541e7fd58861
2026-07-28 05:16:00.694229 | 
2026-07-28 05:16:00.694312 | LOOP [emit-job-header : Print node information]
2026-07-28 05:16:00.791304 | localhost | ok:
2026-07-28 05:16:00.791697 | localhost | # Node Information
2026-07-28 05:16:00.791770 | localhost | Inventory Hostname: instance
2026-07-28 05:16:00.791818 | localhost | Hostname: np0000187241
2026-07-28 05:16:00.791861 | localhost | Username: zuul
2026-07-28 05:16:00.791909 | localhost | Distro: Ubuntu 22.04
2026-07-28 05:16:00.791952 | localhost | Provider: yul1
2026-07-28 05:16:00.791992 | localhost | Region: ca-ymq-1
2026-07-28 05:16:00.792033 | localhost | Label: ubuntu-jammy
2026-07-28 05:16:00.792072 | localhost | Product Name: OpenStack Nova
2026-07-28 05:16:00.792112 | localhost | Interface IP: 199.204.45.144
2026-07-28 05:16:00.813316 | 
2026-07-28 05:16:00.813473 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-07-28 05:16:01.183665 | localhost -> localhost | changed
2026-07-28 05:16:01.193850 | 
2026-07-28 05:16:01.193943 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-07-28 05:16:01.996950 | localhost -> localhost | changed
2026-07-28 05:16:02.004402 | 
2026-07-28 05:16:02.004479 | PLAY [all]
2026-07-28 05:16:02.012690 | 
2026-07-28 05:16:02.012759 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-07-28 05:16:02.228748 | instance -> localhost | ok
2026-07-28 05:16:02.240195 | 
2026-07-28 05:16:02.240264 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-07-28 05:16:02.272319 | instance | ok
2026-07-28 05:16:02.284896 | instance | included: /var/lib/zuul/builds/af6d44f35e744ef19431f5a80809af12/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-07-28 05:16:02.291016 | 
2026-07-28 05:16:02.291074 | TASK [add-build-sshkey : Create Temp SSH key]
2026-07-28 05:16:03.380857 | instance -> localhost | Generating public/private rsa key pair.
2026-07-28 05:16:03.381121 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/af6d44f35e744ef19431f5a80809af12/work/af6d44f35e744ef19431f5a80809af12_id_rsa
2026-07-28 05:16:03.381166 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/af6d44f35e744ef19431f5a80809af12/work/af6d44f35e744ef19431f5a80809af12_id_rsa.pub
2026-07-28 05:16:03.381200 | instance -> localhost | The key fingerprint is:
2026-07-28 05:16:03.381232 | instance -> localhost | SHA256:d4PN0zL4WLWXnOfqIPiPb2wfrTEmonMERbLYC5nUZ8M zuul-build-sshkey
2026-07-28 05:16:03.381282 | instance -> localhost | The key's randomart image is:
2026-07-28 05:16:03.381313 | instance -> localhost | +---[RSA 3072]----+
2026-07-28 05:16:03.381350 | instance -> localhost | |      ...o.      |
2026-07-28 05:16:03.381383 | instance -> localhost | |     . =.oE      |
2026-07-28 05:16:03.381414 | instance -> localhost | |      = o+ .  .  |
2026-07-28 05:16:03.381444 | instance -> localhost | |       ... = o..o|
2026-07-28 05:16:03.381473 | instance -> localhost | |        S.+ X o+o|
2026-07-28 05:16:03.381503 | instance -> localhost | |         o.= = +.|
2026-07-28 05:16:03.381532 | instance -> localhost | |        ..+.+ = o|
2026-07-28 05:16:03.381561 | instance -> localhost | |        .o.+++ * |
2026-07-28 05:16:03.381594 | instance -> localhost | |        .oo=oo=  |
2026-07-28 05:16:03.381624 | instance -> localhost | +----[SHA256]-----+
2026-07-28 05:16:03.381708 | instance -> localhost | ok: Runtime: 0:00:00.694378
2026-07-28 05:16:03.389595 | 
2026-07-28 05:16:03.389739 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-07-28 05:16:03.424115 | instance | ok
2026-07-28 05:16:03.435528 | instance | included: /var/lib/zuul/builds/af6d44f35e744ef19431f5a80809af12/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-07-28 05:16:03.443999 | 
2026-07-28 05:16:03.444141 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-07-28 05:16:03.469603 | instance | skipping: Conditional result was False
2026-07-28 05:16:03.483784 | 
2026-07-28 05:16:03.483948 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-07-28 05:16:03.928793 | instance | changed
2026-07-28 05:16:03.935077 | 
2026-07-28 05:16:03.935136 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-07-28 05:16:04.122837 | instance | ok
2026-07-28 05:16:04.129333 | 
2026-07-28 05:16:04.129399 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-07-28 05:16:04.569136 | instance | changed
2026-07-28 05:16:04.575077 | 
2026-07-28 05:16:04.575141 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-07-28 05:16:05.021005 | instance | changed
2026-07-28 05:16:05.028013 | 
2026-07-28 05:16:05.028099 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-07-28 05:16:05.053344 | instance | skipping: Conditional result was False
2026-07-28 05:16:05.058668 | 
2026-07-28 05:16:05.058735 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-07-28 05:16:05.416166 | instance -> localhost | changed
2026-07-28 05:16:05.430430 | 
2026-07-28 05:16:05.430493 | TASK [add-build-sshkey : Add back temp key]
2026-07-28 05:16:05.699142 | instance -> localhost | Identity added: /var/lib/zuul/builds/af6d44f35e744ef19431f5a80809af12/work/af6d44f35e744ef19431f5a80809af12_id_rsa (zuul-build-sshkey)
2026-07-28 05:16:05.699382 | instance -> localhost | ok: Runtime: 0:00:00.013673
2026-07-28 05:16:05.705829 | 
2026-07-28 05:16:05.705912 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-07-28 05:16:05.999348 | instance | ok
2026-07-28 05:16:06.004316 | 
2026-07-28 05:16:06.004377 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-07-28 05:16:06.028459 | instance | skipping: Conditional result was False
2026-07-28 05:16:06.042179 | 
2026-07-28 05:16:06.042240 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-07-28 05:16:06.339644 | instance | ok
2026-07-28 05:16:06.381350 | 
2026-07-28 05:16:06.381522 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-07-28 05:16:09.457182 | instance | Output suppressed because no_log was given
2026-07-28 05:16:09.468947 | 
2026-07-28 05:16:09.469054 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-07-28 05:16:09.657614 | instance | ok: "logs"
2026-07-28 05:16:09.658411 | instance | ok: All items complete
2026-07-28 05:16:09.658442 | 
2026-07-28 05:16:09.799909 | instance | ok: "artifacts"
2026-07-28 05:16:09.952064 | instance | ok: "docs"
2026-07-28 05:16:09.973033 | 
2026-07-28 05:16:09.973217 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-07-28 05:16:10.155066 | instance | changed: "logs"
2026-07-28 05:16:10.297432 | instance | changed: "artifacts"
2026-07-28 05:16:10.448794 | instance | changed: "docs"
2026-07-28 05:16:10.461563 | 
2026-07-28 05:16:10.461699 | PLAY RECAP
2026-07-28 05:16:10.461761 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-07-28 05:16:10.461801 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-28 05:16:10.461832 | 
2026-07-28 05:16:10.632591 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-28 05:16:10.636646 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-28 05:16:11.190989 | 
2026-07-28 05:16:11.191105 | PLAY [all]
2026-07-28 05:16:11.201629 | 
2026-07-28 05:16:11.201705 | TASK [setup-uv : Extract archive]
2026-07-28 05:16:13.340528 | instance | changed
2026-07-28 05:16:13.347342 | 
2026-07-28 05:16:13.347421 | TASK [setup-uv : Print version]
2026-07-28 05:16:13.671488 | instance | uv 0.8.13
2026-07-28 05:16:13.882810 | instance | ok: Runtime: 0:00:00.013113
2026-07-28 05:16:13.893488 | 
2026-07-28 05:16:13.893557 | TASK [Install Ansible collection siblings]
2026-07-28 05:16:22.520135 | instance | changed:
2026-07-28 05:16:22.520421 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-07-28 05:16:22.520454 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-07-28 05:16:22.520477 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-07-28 05:16:22.520497 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-07-28 05:16:22.520517 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-07-28 05:16:22.520541 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-07-28 05:16:22.520562 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-07-28 05:16:22.520583 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-07-28 05:16:22.520604 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-07-28 05:16:22.520623 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-07-28 05:16:22.520642 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-07-28 05:16:22.520660 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-07-28 05:16:22.520678 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-07-28 05:16:22.520696 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-07-28 05:16:22.520715 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0
2026-07-28 05:16:22.520733 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-07-28 05:16:22.520751 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-07-28 05:16:22.520769 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-07-28 05:16:22.520787 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-07-28 05:16:22.520805 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-07-28 05:16:22.520823 | instance | Resolved vexxhost.ceph to v4.1.0 for >=3.2.0
2026-07-28 05:16:22.520841 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-07-28 05:16:22.520860 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-28 05:16:22.520901 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-07-28 05:16:22.520920 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0,>=6.0.0
2026-07-28 05:16:22.520938 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-28 05:16:22.520973 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-28 05:16:22.520993 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-28 05:16:22.521011 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-07-28 05:16:22.521029 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-07-28 05:16:22.521046 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-28 05:16:22.521064 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-28 05:16:22.521082 | instance | Checked out ansible.netcommon to 1.2.0
2026-07-28 05:16:22.521100 | 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-07-28 05:16:22.521127 | instance | Starting galaxy collection install process
2026-07-28 05:16:22.521145 | instance | Process install dependency map
2026-07-28 05:16:22.521162 | instance | Starting collection install process
2026-07-28 05:16:22.521181 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-07-28 05:16:22.521199 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-07-28 05:16:22.521216 | instance | ansible.netcommon:* was installed successfully
2026-07-28 05:16:22.521234 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-07-28 05:16:22.521252 | instance | Creating virtual environment at: .venv
2026-07-28 05:16:22.521270 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-28 05:16:22.521288 | instance | Downloading setuptools (1.1MiB)
2026-07-28 05:16:22.521306 | instance | Downloading openstacksdk (1.7MiB)
2026-07-28 05:16:22.521328 | instance | Downloading kubernetes (1.9MiB)
2026-07-28 05:16:22.521346 | instance | Downloading ansible-core (2.1MiB)
2026-07-28 05:16:22.521364 | instance | Downloading cryptography (4.2MiB)
2026-07-28 05:16:22.521382 | instance | Downloading pydantic-core (2.0MiB)
2026-07-28 05:16:22.521400 | instance | Downloading pygments (1.2MiB)
2026-07-28 05:16:22.521419 | instance | Downloading netaddr (2.2MiB)
2026-07-28 05:16:22.521437 | instance | Downloading rjsonnet (1.2MiB)
2026-07-28 05:16:22.521456 | instance |    Building pyperclip==1.9.0
2026-07-28 05:16:22.521475 | instance |  Downloading rjsonnet
2026-07-28 05:16:22.521494 | instance |  Downloading pydantic-core
2026-07-28 05:16:22.521512 | instance |  Downloading netaddr
2026-07-28 05:16:22.521530 | instance |  Downloading pygments
2026-07-28 05:16:22.521548 | instance |  Downloading cryptography
2026-07-28 05:16:22.521567 | instance |  Downloading setuptools
2026-07-28 05:16:22.521585 | instance |  Downloading kubernetes
2026-07-28 05:16:22.521604 | instance |  Downloading ansible-core
2026-07-28 05:16:22.521627 | instance |  Downloading openstacksdk
2026-07-28 05:16:22.521653 | instance |       Built pyperclip==1.9.0
2026-07-28 05:16:22.521677 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-28 05:16:22.521702 | instance | Installed 83 packages in 36ms
2026-07-28 05:16:22.521722 | instance | Checked out ansible.posix to 1.6.0
2026-07-28 05:16:22.521744 | 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-07-28 05:16:22.521771 | instance | Starting galaxy collection install process
2026-07-28 05:16:22.521794 | instance | Process install dependency map
2026-07-28 05:16:22.521820 | instance | Starting collection install process
2026-07-28 05:16:22.521843 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-07-28 05:16:22.521870 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-07-28 05:16:22.521892 | instance | ansible.posix:1.6.0 was installed successfully
2026-07-28 05:16:22.521911 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:16:22.521933 | instance | 2.17.12
2026-07-28 05:16:22.521959 | instance | Checked out ansible.utils to v6.0.3
2026-07-28 05:16:22.521983 | 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-07-28 05:16:22.522006 | instance | Starting galaxy collection install process
2026-07-28 05:16:22.522029 | instance | Process install dependency map
2026-07-28 05:16:22.522055 | instance | Starting collection install process
2026-07-28 05:16:22.522080 | instance | Installing 'ansible.utils:6.0.3' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-07-28 05:16:22.522103 | instance | Created collection for ansible.utils:6.0.3 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-07-28 05:16:22.522128 | instance | ansible.utils:6.0.3 was installed successfully
2026-07-28 05:16:22.522153 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:16:22.522176 | instance | 2.17.12
2026-07-28 05:16:22.522200 | instance | Checked out atmosphere.common to v0.7.0
2026-07-28 05:16:22.522225 | 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-07-28 05:16:22.522248 | instance | Starting galaxy collection install process
2026-07-28 05:16:22.522270 | instance | Process install dependency map
2026-07-28 05:16:22.522295 | instance | Starting collection install process
2026-07-28 05:16:22.522319 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-07-28 05:16:22.522341 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-07-28 05:16:22.522364 | instance | atmosphere.common:0.7.0 was installed successfully
2026-07-28 05:16:22.522385 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:16:22.522403 | instance | 2.17.12
2026-07-28 05:16:22.522421 | instance | Checked out community.crypto to 2.2.3
2026-07-28 05:16:22.522439 | 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-07-28 05:16:22.522502 | instance | Starting galaxy collection install process
2026-07-28 05:16:22.522522 | instance | Process install dependency map
2026-07-28 05:16:22.522540 | instance | Starting collection install process
2026-07-28 05:16:22.522560 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-07-28 05:16:22.522585 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-07-28 05:16:22.522610 | instance | community.crypto:2.2.3 was installed successfully
2026-07-28 05:16:22.522629 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:16:22.522647 | instance | 2.17.12
2026-07-28 05:16:22.522665 | instance | Checked out community.general to 7.3.0
2026-07-28 05:16:22.522682 | 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-07-28 05:16:22.522708 | instance | Starting galaxy collection install process
2026-07-28 05:16:22.522726 | instance | Process install dependency map
2026-07-28 05:16:22.522743 | instance | Starting collection install process
2026-07-28 05:16:22.522761 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-07-28 05:16:22.522784 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-07-28 05:16:22.522812 | instance | community.general:7.3.0 was installed successfully
2026-07-28 05:16:22.522833 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:16:22.522851 | instance | 2.17.12
2026-07-28 05:16:22.522872 | instance | Checked out community.mysql to 3.6.0
2026-07-28 05:16:22.522902 | 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-07-28 05:16:22.522929 | instance | Starting galaxy collection install process
2026-07-28 05:16:22.522948 | instance | Process install dependency map
2026-07-28 05:16:22.522971 | instance | Starting collection install process
2026-07-28 05:16:22.522998 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-07-28 05:16:22.523023 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-07-28 05:16:22.523045 | instance | community.mysql:3.6.0 was installed successfully
2026-07-28 05:16:22.523065 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:16:22.523092 | instance | 2.17.12
2026-07-28 05:16:22.523118 | instance | Checked out kubernetes.core to 2.4.0
2026-07-28 05:16:22.523141 | 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-07-28 05:16:22.523165 | instance | Starting galaxy collection install process
2026-07-28 05:16:22.523191 | instance | Process install dependency map
2026-07-28 05:16:22.523216 | instance | Starting collection install process
2026-07-28 05:16:22.523239 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-07-28 05:16:22.523263 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-07-28 05:16:22.523289 | instance | kubernetes.core:2.4.0 was installed successfully
2026-07-28 05:16:22.523312 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:16:22.523334 | instance | 2.17.12
2026-07-28 05:16:22.523362 | instance | Checked out openstack.cloud to 2.6.0
2026-07-28 05:16:22.523391 | 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-07-28 05:16:22.523414 | instance | Starting galaxy collection install process
2026-07-28 05:16:22.523438 | instance | Process install dependency map
2026-07-28 05:16:22.523465 | instance | Starting collection install process
2026-07-28 05:16:22.523489 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-07-28 05:16:22.523511 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-07-28 05:16:22.523536 | instance | openstack.cloud:2.6.0 was installed successfully
2026-07-28 05:16:22.523560 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:16:22.523582 | instance | 2.17.12
2026-07-28 05:16:22.523614 | instance | Checked out vexxhost.ceph to v4.1.0
2026-07-28 05:16:22.523640 | 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-07-28 05:16:22.523663 | instance | Starting galaxy collection install process
2026-07-28 05:16:22.523686 | instance | Process install dependency map
2026-07-28 05:16:22.523712 | instance | Starting collection install process
2026-07-28 05:16:22.523736 | instance | Installing 'vexxhost.ceph:4.1.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-07-28 05:16:22.523758 | instance | Created collection for vexxhost.ceph:4.1.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-07-28 05:16:22.523780 | instance | vexxhost.ceph:4.1.0 was installed successfully
2026-07-28 05:16:22.523803 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:16:22.523822 | instance | 2.17.12
2026-07-28 05:16:22.523840 | instance | Checked out vexxhost.containers to v1.6.8
2026-07-28 05:16:22.523858 | 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-07-28 05:16:22.523876 | instance | Starting galaxy collection install process
2026-07-28 05:16:22.523894 | instance | Process install dependency map
2026-07-28 05:16:22.523912 | instance | Starting collection install process
2026-07-28 05:16:22.523930 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-07-28 05:16:22.523948 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-07-28 05:16:22.523967 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-07-28 05:16:22.523984 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:16:22.524006 | instance | 2.17.12
2026-07-28 05:16:22.524033 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-07-28 05:16:22.524059 | 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-07-28 05:16:22.524078 | instance | Starting galaxy collection install process
2026-07-28 05:16:22.524097 | instance | Process install dependency map
2026-07-28 05:16:22.524124 | instance | Starting collection install process
2026-07-28 05:16:22.524148 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-07-28 05:16:22.524167 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-07-28 05:16:22.524186 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-07-28 05:16:22.524203 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:16:22.524221 | instance | 2.17.12
2026-07-28 05:16:22.530271 | 
2026-07-28 05:16:22.530323 | PLAY RECAP
2026-07-28 05:16:22.530376 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-28 05:16:22.530399 | 
2026-07-28 05:16:22.695982 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-28 05:16:22.700287 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-28 05:16:23.320101 | 
2026-07-28 05:16:23.409828 | PLAY [all]
2026-07-28 05:16:23.428878 | 
2026-07-28 05:16:23.428978 | TASK [Install "jq" for log collection]
2026-07-28 05:17:27.393336 | instance | changed
2026-07-28 05:17:27.395907 | 
2026-07-28 05:17:27.396029 | PLAY RECAP
2026-07-28 05:17:27.396098 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-28 05:17:27.396228 | 
2026-07-28 05:17:27.578365 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-28 05:17:27.581080 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-28 05:17:28.157848 | 
2026-07-28 05:17:28.157982 | PLAY [Prepare selective Molecule plan]
2026-07-28 05:17:28.169070 | 
2026-07-28 05:17:28.169142 | TASK [Generate selective Molecule plan]
2026-07-28 05:17:28.700196 | instance | ok: Runtime: 0:00:00.160698
2026-07-28 05:17:28.708036 | 
2026-07-28 05:17:28.708125 | TASK [Render selective Molecule plan]
2026-07-28 05:17:29.006646 | instance | Selective Molecule plan: full
2026-07-28 05:17:29.006769 | instance | Changed files:
2026-07-28 05:17:29.006782 | instance |   M .zuul.yaml
2026-07-28 05:17:29.006792 | instance |   A atmosphere/ci/__init__.py
2026-07-28 05:17:29.006805 | instance |   A atmosphere/ci/molecule_plan.py
2026-07-28 05:17:29.006816 | instance |   A charts/patches/tempest/0002-mount-only-ca-certificate.patch
2026-07-28 05:17:29.006829 | instance |   A charts/patches/tempest/0003-use-ca-for-python-ssl.patch
2026-07-28 05:17:29.006842 | instance |   A charts/patches/tempest/0004-support-ca-only-identity-trust.patch
2026-07-28 05:17:29.006852 | instance |   M charts/tempest/templates/job-ks-user.yaml
2026-07-28 05:17:29.006861 | instance |   M charts/tempest/templates/job-run-tests.yaml
2026-07-28 05:17:29.006872 | instance |   M charts/tempest/values.yaml
2026-07-28 05:17:29.006881 | instance |   A ci/molecule-plan.yaml
2026-07-28 05:17:29.006890 | instance |   M doc/source/deploy/index.rst
2026-07-28 05:17:29.006900 | instance |   A doc/source/deploy/selective-ci.rst
2026-07-28 05:17:29.006909 | instance |   M molecule/aio/converge.yml
2026-07-28 05:17:29.006917 | instance |   M molecule/aio/prepare.yml
2026-07-28 05:17:29.006926 | instance |   M molecule/aio/verify.yml
2026-07-28 05:17:29.006935 | instance |   M playbooks/tempest.yml
2026-07-28 05:17:29.006944 | instance |   M pyproject.toml
2026-07-28 05:17:29.006953 | instance |   A releasenotes/notes/selective-molecule-ci-3233e7ee8348d528.yaml
2026-07-28 05:17:29.006961 | instance |   M roles/tempest/defaults/main.yml
2026-07-28 05:17:29.006970 | instance |   M roles/tempest/tasks/main.yml
2026-07-28 05:17:29.006979 | instance |   M roles/tempest/vars/main.yml
2026-07-28 05:17:29.006988 | instance |   A test-playbooks/molecule/poll-command.yml
2026-07-28 05:17:29.006996 | instance |   A test-playbooks/molecule/post.yml
2026-07-28 05:17:29.007009 | instance |   A test-playbooks/molecule/prepare.yml
2026-07-28 05:17:29.007018 | instance |   A test-playbooks/molecule/run-command.yml
2026-07-28 05:17:29.007027 | instance |   A test-playbooks/molecule/selective-run.yml
2026-07-28 05:17:29.007036 | instance |   A tests/unit/ci/__init__.py
2026-07-28 05:17:29.007045 | instance |   A tests/unit/ci/test_molecule_plan.py
2026-07-28 05:17:29.007053 | instance |   M uv.lock
2026-07-28 05:17:29.007062 | instance | Job decisions:
2026-07-28 05:17:29.007071 | instance |   RUN  aio-openvswitch: full fallback
2026-07-28 05:17:29.007079 | instance |   RUN  aio-ovn: full fallback
2026-07-28 05:17:29.007088 | instance |   RUN  csi-local-path-provisioner: full fallback
2026-07-28 05:17:29.007097 | instance |   RUN  csi-rbd: full fallback
2026-07-28 05:17:29.007106 | instance |   RUN  keycloak: full fallback
2026-07-28 05:17:29.007114 | instance | Reason: the CI selection policy changed
2026-07-28 05:17:29.007123 | instance | Reason: shared deployment code can affect unrelated components
2026-07-28 05:17:29.007132 | instance | Reason: operational workflows need the complete integration environment
2026-07-28 05:17:29.247335 | instance | ok: Runtime: 0:00:00.099559
2026-07-28 05:17:29.255090 | 
2026-07-28 05:17:29.255161 | TASK [Report selective Molecule plan]
2026-07-28 05:17:29.290338 | instance | ok:
2026-07-28 05:17:29.290494 | instance | Selective Molecule plan: full
2026-07-28 05:17:29.290547 | instance | Changed files:
2026-07-28 05:17:29.290579 | instance |   M .zuul.yaml
2026-07-28 05:17:29.290606 | instance |   A atmosphere/ci/__init__.py
2026-07-28 05:17:29.290633 | instance |   A atmosphere/ci/molecule_plan.py
2026-07-28 05:17:29.290665 | instance |   A charts/patches/tempest/0002-mount-only-ca-certificate.patch
2026-07-28 05:17:29.290695 | instance |   A charts/patches/tempest/0003-use-ca-for-python-ssl.patch
2026-07-28 05:17:29.290721 | instance |   A charts/patches/tempest/0004-support-ca-only-identity-trust.patch
2026-07-28 05:17:29.290748 | instance |   M charts/tempest/templates/job-ks-user.yaml
2026-07-28 05:17:29.290774 | instance |   M charts/tempest/templates/job-run-tests.yaml
2026-07-28 05:17:29.290800 | instance |   M charts/tempest/values.yaml
2026-07-28 05:17:29.290826 | instance |   A ci/molecule-plan.yaml
2026-07-28 05:17:29.290851 | instance |   M doc/source/deploy/index.rst
2026-07-28 05:17:29.290877 | instance |   A doc/source/deploy/selective-ci.rst
2026-07-28 05:17:29.290906 | instance |   M molecule/aio/converge.yml
2026-07-28 05:17:29.290932 | instance |   M molecule/aio/prepare.yml
2026-07-28 05:17:29.290958 | instance |   M molecule/aio/verify.yml
2026-07-28 05:17:29.290984 | instance |   M playbooks/tempest.yml
2026-07-28 05:17:29.291009 | instance |   M pyproject.toml
2026-07-28 05:17:29.291036 | instance |   A releasenotes/notes/selective-molecule-ci-3233e7ee8348d528.yaml
2026-07-28 05:17:29.291061 | instance |   M roles/tempest/defaults/main.yml
2026-07-28 05:17:29.291087 | instance |   M roles/tempest/tasks/main.yml
2026-07-28 05:17:29.291113 | instance |   M roles/tempest/vars/main.yml
2026-07-28 05:17:29.291139 | instance |   A test-playbooks/molecule/poll-command.yml
2026-07-28 05:17:29.291165 | instance |   A test-playbooks/molecule/post.yml
2026-07-28 05:17:29.291190 | instance |   A test-playbooks/molecule/prepare.yml
2026-07-28 05:17:29.291216 | instance |   A test-playbooks/molecule/run-command.yml
2026-07-28 05:17:29.291241 | instance |   A test-playbooks/molecule/selective-run.yml
2026-07-28 05:17:29.291266 | instance |   A tests/unit/ci/__init__.py
2026-07-28 05:17:29.291291 | instance |   A tests/unit/ci/test_molecule_plan.py
2026-07-28 05:17:29.291317 | instance |   M uv.lock
2026-07-28 05:17:29.291343 | instance | Job decisions:
2026-07-28 05:17:29.291369 | instance |   RUN  aio-openvswitch: full fallback
2026-07-28 05:17:29.291394 | instance |   RUN  aio-ovn: full fallback
2026-07-28 05:17:29.291420 | instance |   RUN  csi-local-path-provisioner: full fallback
2026-07-28 05:17:29.291445 | instance |   RUN  csi-rbd: full fallback
2026-07-28 05:17:29.291471 | instance |   RUN  keycloak: full fallback
2026-07-28 05:17:29.291496 | instance | Reason: the CI selection policy changed
2026-07-28 05:17:29.291521 | instance | Reason: shared deployment code can affect unrelated components
2026-07-28 05:17:29.291547 | instance | Reason: operational workflows need the complete integration environment
2026-07-28 05:17:29.292853 | 
2026-07-28 05:17:29.292981 | PLAY RECAP
2026-07-28 05:17:29.293053 | instance | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-28 05:17:29.293084 | 
2026-07-28 05:17:29.426214 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-28 05:17:29.430393 | RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-28 05:17:30.006722 | 
2026-07-28 05:17:30.006848 | PLAY [Run selected Molecule scenario]
2026-07-28 05:17:30.018167 | 
2026-07-28 05:17:30.018247 | TASK [Read selective Molecule plan]
2026-07-28 05:17:30.315999 | instance | ok
2026-07-28 05:17:30.321605 | 
2026-07-28 05:17:30.321679 | TASK [Resolve this job decision]
2026-07-28 05:17:30.375601 | instance | ok
2026-07-28 05:17:30.384613 | 
2026-07-28 05:17:30.384678 | TASK [Assert this job exists in the plan]
2026-07-28 05:17:30.418756 | instance | ok: All assertions passed
2026-07-28 05:17:30.422991 | 
2026-07-28 05:17:30.423049 | TASK [Resolve selected scenario inputs]
2026-07-28 05:17:30.458025 | instance | ok
2026-07-28 05:17:30.462937 | 
2026-07-28 05:17:30.462993 | TASK [Report this job decision]
2026-07-28 05:17:30.516861 | instance | ok:
2026-07-28 05:17:30.517131 | instance | Selective Molecule job: csi-rbd
2026-07-28 05:17:30.517198 | instance | Decision: RUN
2026-07-28 05:17:30.517252 | instance | Reason: full fallback
2026-07-28 05:17:30.517304 | instance | Scenario: csi
2026-07-28 05:17:30.517349 | instance | Targets: (none)
2026-07-28 05:17:30.517393 | instance | Components: (full scenario)
2026-07-28 05:17:30.523276 | 
2026-07-28 05:17:30.523335 | TASK [Prepare Molecule command]
2026-07-28 05:17:30.589473 | instance | ok
2026-07-28 05:17:30.594944 | 
2026-07-28 05:17:30.595018 | TASK [Run standard Molecule lifecycle with progress output]
2026-07-28 05:17:30.648976 | instance | ok
2026-07-28 05:17:30.660459 | instance | included: /var/lib/zuul/builds/af6d44f35e744ef19431f5a80809af12/untrusted/project_5/github.com/vexxhost/atmosphere/test-playbooks/molecule/run-command.yml
2026-07-28 05:17:30.665695 | 
2026-07-28 05:17:30.665770 | TASK [Create Molecule log directory]
2026-07-28 05:17:31.026929 | instance | changed
2026-07-28 05:17:31.031995 | 
2026-07-28 05:17:31.032058 | TASK [Resolve live Molecule log path]
2026-07-28 05:17:31.064315 | instance | ok
2026-07-28 05:17:31.073210 | 
2026-07-28 05:17:31.073279 | TASK [Start Molecule csi (csi-rbd)]
2026-07-28 05:17:31.698936 | instance | ok: ERROR (ignored)
2026-07-28 05:17:31.699153 | instance | {
2026-07-28 05:17:31.699185 | instance |   "ansible_job_id": "j804314475474.1437",
2026-07-28 05:17:31.699207 | instance |   "finished": 0,
2026-07-28 05:17:31.699226 | instance |   "results_file": "/home/zuul/.ansible_async/j804314475474.1437",
2026-07-28 05:17:31.699246 | instance |   "started": 1
2026-07-28 05:17:31.699264 | instance | }
2026-07-28 05:17:31.705866 | 
2026-07-28 05:17:31.705936 | TASK [Initialize Molecule csi (csi-rbd) progress monitor]
2026-07-28 05:17:31.780397 | instance | ok
2026-07-28 05:17:31.786455 | 
2026-07-28 05:17:31.786522 | TASK [Monitor Molecule csi (csi-rbd)]
2026-07-28 05:17:31.809102 | instance | ok
2026-07-28 05:17:31.822024 | instance | included: /var/lib/zuul/builds/af6d44f35e744ef19431f5a80809af12/untrusted/project_5/github.com/vexxhost/atmosphere/test-playbooks/molecule/poll-command.yml
2026-07-28 05:17:31.830582 | 
2026-07-28 05:17:31.830648 | TASK [Wait before polling Molecule csi (csi-rbd)]
2026-07-28 05:17:51.907724 | instance | ok
2026-07-28 05:17:51.915563 | 
2026-07-28 05:17:51.915631 | TASK [Count Molecule csi (csi-rbd) progress poll]
2026-07-28 05:17:51.958743 | instance | ok
2026-07-28 05:17:51.969101 | 
2026-07-28 05:17:51.969170 | TASK [Poll Molecule csi (csi-rbd) status]
2026-07-28 05:17:52.298980 | instance | ok: non-zero return code
2026-07-28 05:17:52.308270 | 
2026-07-28 05:17:52.308336 | TASK [Inspect Molecule csi (csi-rbd) live log]
2026-07-28 05:17:52.625989 | instance | ok
2026-07-28 05:17:52.631779 | 
2026-07-28 05:17:52.631908 | TASK [Calculate new Molecule csi (csi-rbd) output]
2026-07-28 05:17:52.685000 | instance | ok
2026-07-28 05:17:52.692325 | 
2026-07-28 05:17:52.692392 | TASK [Read new Molecule csi (csi-rbd) output]
2026-07-28 05:17:53.219760 | instance | skipping: Conditional result was False
2026-07-28 05:17:53.234127 | 
2026-07-28 05:17:53.234250 | TASK [Print new Molecule csi (csi-rbd) output]
2026-07-28 05:17:53.271363 | instance | skipping: Conditional result was False
2026-07-28 05:17:53.281595 | 
2026-07-28 05:17:53.281685 | TASK [Advance Molecule csi (csi-rbd) live log offset]
2026-07-28 05:17:53.335626 | instance | ok
2026-07-28 05:17:53.341889 | 
2026-07-28 05:17:53.341954 | TASK [Report Molecule csi (csi-rbd) progress heartbeat]
2026-07-28 05:17:53.386311 | instance | skipping: Conditional result was False
2026-07-28 05:17:53.397497 | 
2026-07-28 05:17:53.397564 | TASK [Continue monitoring Molecule csi (csi-rbd)]
2026-07-28 05:17:53.442174 | instance | skipping: Conditional result was False
2026-07-28 05:17:53.453616 | 
2026-07-28 05:17:53.453682 | TASK [Clean up Molecule csi (csi-rbd) async state]
2026-07-28 05:17:53.648691 | instance | ok
2026-07-28 05:17:53.656700 | 
2026-07-28 05:17:53.656765 | TASK [Assert Molecule csi (csi-rbd) succeeded]
2026-07-28 05:17:53.710572 | instance | ERROR
2026-07-28 05:17:53.710862 | instance | {
2026-07-28 05:17:53.710924 | instance |   "assertion": "(_atmosphere_ci_phase_result.rc | default(1) | int) == 0",
2026-07-28 05:17:53.710969 | instance |   "evaluated_to": false,
2026-07-28 05:17:53.711010 | instance |   "msg": "Molecule csi (csi-rbd) failed with exit code 1. Complete output is in /tmp/logs/molecule-csi-rbd.log."
2026-07-28 05:17:53.711054 | instance | }
failure
2026-07-28 05:17:53.712738 | 
2026-07-28 05:17:53.712784 | PLAY RECAP
2026-07-28 05:17:53.712830 | instance | ok: 19 changed: 2 unreachable: 0 failed: 1 skipped: 4 rescued: 0 ignored: 0
2026-07-28 05:17:53.712850 | 
2026-07-28 05:17:53.843801 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-28 05:17:53.847984 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-07-28 05:17:54.421664 | 
2026-07-28 05:17:54.421791 | PLAY [Publish selective Molecule plan]
2026-07-28 05:17:54.432998 | 
2026-07-28 05:17:54.433072 | TASK [Check for selective Molecule plan]
2026-07-28 05:17:54.749885 | instance | ok
2026-07-28 05:17:54.756186 | 
2026-07-28 05:17:54.756260 | TASK [Create log directory]
2026-07-28 05:17:55.082824 | instance | ok
2026-07-28 05:17:55.088808 | 
2026-07-28 05:17:55.088870 | TASK [Copy selective Molecule plan to logs]
2026-07-28 05:17:55.509162 | instance | changed
2026-07-28 05:17:55.510657 | 
2026-07-28 05:17:55.510703 | PLAY RECAP
2026-07-28 05:17:55.510746 | instance | ok: 3 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-28 05:17:55.510767 | 
2026-07-28 05:17:55.639541 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-07-28 05:17:55.643764 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-28 05:17:56.243963 | 
2026-07-28 05:17:56.244076 | PLAY [all]
2026-07-28 05:17:56.257421 | 
2026-07-28 05:17:56.257499 | TASK [gather-host-logs : creating directory for system status]
2026-07-28 05:17:56.613569 | instance | changed
2026-07-28 05:17:56.621342 | 
2026-07-28 05:17:56.621447 | TASK [gather-host-logs : Get logs for each host]
2026-07-28 05:17:56.961570 | instance | + systemd-cgls --full --all --no-pager
2026-07-28 05:17:56.972952 | instance | + ip addr
2026-07-28 05:17:56.974739 | instance | + ip route
2026-07-28 05:17:56.975779 | instance | + lsblk
2026-07-28 05:17:56.977725 | instance | + mount
2026-07-28 05:17:56.978882 | instance | + docker images
2026-07-28 05:17:56.979163 | instance | /bin/bash: line 7: docker: command not found
2026-07-28 05:17:56.979299 | instance | + brctl show
2026-07-28 05:17:56.979589 | instance | /bin/bash: line 8: brctl: command not found
2026-07-28 05:17:56.979750 | instance | + ps aux --sort=-%mem
2026-07-28 05:17:56.987309 | instance | + dpkg -l
2026-07-28 05:17:56.994511 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-07-28 05:17:56.994882 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-07-28 05:17:56.994939 | instance | /bin/bash: line 11: docker: command not found
2026-07-28 05:17:56.995156 | instance | + '[' '!' -z '' ']'
2026-07-28 05:17:57.161747 | instance | ok: Runtime: 0:00:00.036826
2026-07-28 05:17:57.170004 | 
2026-07-28 05:17:57.170097 | TASK [gather-host-logs : Downloads logs to executor]
2026-07-28 05:17:57.820733 | instance | changed:
2026-07-28 05:17:57.821050 | instance | created directory /var/lib/zuul/builds/af6d44f35e744ef19431f5a80809af12/work/logs/instance
2026-07-28 05:17:57.821119 | instance | cd+++++++++ system/
2026-07-28 05:17:57.821172 | instance | >f+++++++++ system/brctl-show.txt
2026-07-28 05:17:57.821241 | instance | >f+++++++++ system/docker-images.txt
2026-07-28 05:17:57.821290 | instance | >f+++++++++ system/ip-addr.txt
2026-07-28 05:17:57.821343 | instance | >f+++++++++ system/ip-route.txt
2026-07-28 05:17:57.821393 | instance | >f+++++++++ system/lsblk.txt
2026-07-28 05:17:57.821440 | instance | >f+++++++++ system/mount.txt
2026-07-28 05:17:57.821491 | instance | >f+++++++++ system/packages.txt
2026-07-28 05:17:57.821537 | instance | >f+++++++++ system/ps.txt
2026-07-28 05:17:57.821583 | instance | >f+++++++++ system/systemd-cgls.txt
2026-07-28 05:17:57.831521 | 
2026-07-28 05:17:57.831585 | LOOP [helm-release-status : creating directory for helm release status]
2026-07-28 05:17:58.027882 | instance | changed: "values"
2026-07-28 05:17:58.233828 | instance | changed: "releases"
2026-07-28 05:17:58.275514 | 
2026-07-28 05:17:58.275705 | TASK [helm-release-status : Gather get release status for helm charts]
2026-07-28 05:17:58.486887 | instance | /bin/bash: line 3: kubectl: command not found
2026-07-28 05:17:58.813803 | instance | ok: Runtime: 0:00:00.005935
2026-07-28 05:17:58.821222 | 
2026-07-28 05:17:58.821320 | TASK [helm-release-status : Downloads logs to executor]
2026-07-28 05:17:59.320163 | instance | changed:
2026-07-28 05:17:59.320357 | instance | cd+++++++++ helm/
2026-07-28 05:17:59.320398 | instance | cd+++++++++ helm/releases/
2026-07-28 05:17:59.320430 | instance | cd+++++++++ helm/values/
2026-07-28 05:17:59.333089 | 
2026-07-28 05:17:59.333151 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-07-28 05:17:59.528131 | instance | changed
2026-07-28 05:17:59.533322 | 
2026-07-28 05:17:59.533410 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-07-28 05:17:59.777295 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-28 05:17:59.777768 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-28 05:17:59.781632 | instance | environment: line 1: kubectl: command not found
2026-07-28 05:17:59.782320 | instance | environment: line 1: kubectl: command not found
2026-07-28 05:17:59.783212 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-28 05:17:59.784196 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-28 05:17:59.786135 | instance | environment: line 1: kubectl: command not found
2026-07-28 05:17:59.786665 | instance | environment: line 1: kubectl: command not found
2026-07-28 05:17:59.787110 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-28 05:17:59.788037 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-28 05:17:59.789536 | instance | environment: line 1: kubectl: command not found
2026-07-28 05:17:59.790297 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-28 05:18:00.106225 | instance | ok: Runtime: 0:00:00.021790
2026-07-28 05:18:00.112564 | 
2026-07-28 05:18:00.112639 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-07-28 05:18:00.309430 | instance | changed
2026-07-28 05:18:00.316612 | 
2026-07-28 05:18:00.316698 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-07-28 05:18:00.521862 | instance | environment: line 5: kubectl: command not found
2026-07-28 05:18:00.522093 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-28 05:18:00.522699 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-28 05:18:00.522979 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-28 05:18:00.852213 | instance | ok: Runtime: 0:00:00.009150
2026-07-28 05:18:00.859795 | 
2026-07-28 05:18:00.859894 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-07-28 05:18:01.350482 | instance | changed:
2026-07-28 05:18:01.350785 | instance | cd+++++++++ objects/
2026-07-28 05:18:01.350815 | instance | cd+++++++++ objects/cluster/
2026-07-28 05:18:01.350837 | instance | cd+++++++++ objects/namespaced/
2026-07-28 05:18:01.359649 | 
2026-07-28 05:18:01.359716 | TASK [gather-pod-logs : creating directory for pod logs]
2026-07-28 05:18:01.569456 | instance | changed
2026-07-28 05:18:01.575373 | 
2026-07-28 05:18:01.575437 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-07-28 05:18:01.772698 | instance | changed
2026-07-28 05:18:01.778015 | 
2026-07-28 05:18:01.778090 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-07-28 05:18:01.997709 | instance | environment: line 3: kubectl: command not found
2026-07-28 05:18:02.320480 | instance | ok: Runtime: 0:00:00.008889
2026-07-28 05:18:02.327697 | 
2026-07-28 05:18:02.327788 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-07-28 05:18:02.807767 | instance | changed:
2026-07-28 05:18:02.808082 | instance | cd+++++++++ pod-logs/
2026-07-28 05:18:02.808144 | instance | cd+++++++++ pod-logs/failed-pods/
2026-07-28 05:18:02.820407 | 
2026-07-28 05:18:02.820476 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-07-28 05:18:03.006913 | instance | changed
2026-07-28 05:18:03.013576 | 
2026-07-28 05:18:03.013669 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-07-28 05:18:03.223781 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-28 05:18:03.550504 | instance | ok: Runtime: 0:00:00.036177
2026-07-28 05:18:03.557334 | 
2026-07-28 05:18:03.557440 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-07-28 05:18:03.772698 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-28 05:18:03.804746 | instance | ceph-mgr endpoints:
2026-07-28 05:18:04.093387 | instance | ok: Runtime: 0:00:00.039175
2026-07-28 05:18:04.098440 | 
2026-07-28 05:18:04.098509 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-07-28 05:18:04.317644 | instance | /bin/bash: line 4: kubectl: command not found
2026-07-28 05:18:04.636295 | instance | ok: Runtime: 0:00:00.035581
2026-07-28 05:18:04.647569 | 
2026-07-28 05:18:04.647639 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-07-28 05:18:05.137925 | instance | changed: cd+++++++++ prometheus/
2026-07-28 05:18:05.149626 | 
2026-07-28 05:18:05.149691 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-07-28 05:18:05.355914 | instance | changed
2026-07-28 05:18:05.360884 | 
2026-07-28 05:18:05.360951 | TASK [gather-selenium-data : Get selenium data]
2026-07-28 05:18:05.608022 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-07-28 05:18:05.609544 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-07-28 05:18:05.935808 | instance | ERROR
2026-07-28 05:18:05.936113 | instance | {
2026-07-28 05:18:05.936188 | instance |   "delta": "0:00:00.006137",
2026-07-28 05:18:05.936236 | instance |   "end": "2026-07-28 05:18:05.609870",
2026-07-28 05:18:05.936280 | instance |   "msg": "non-zero return code",
2026-07-28 05:18:05.936322 | instance |   "rc": 1,
2026-07-28 05:18:05.936365 | instance |   "start": "2026-07-28 05:18:05.603733"
2026-07-28 05:18:05.936406 | instance | }
2026-07-28 05:18:05.936457 | instance | ERROR: Ignoring Errors
2026-07-28 05:18:05.941495 | 
2026-07-28 05:18:05.941562 | TASK [gather-selenium-data : Downloads logs to executor]
2026-07-28 05:18:06.442453 | instance | changed: cd+++++++++ selenium/
2026-07-28 05:18:06.450902 | 
2026-07-28 05:18:06.450954 | PLAY RECAP
2026-07-28 05:18:06.450998 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-07-28 05:18:06.451021 | 
2026-07-28 05:18:06.576672 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-28 05:18:06.581178 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-28 05:18:07.227914 | 
2026-07-28 05:18:07.228066 | PLAY [all]
2026-07-28 05:18:07.239846 | 
2026-07-28 05:18:07.239952 | TASK [fetch-output : Set log path for multiple nodes]
2026-07-28 05:18:07.285376 | instance | skipping: Conditional result was False
2026-07-28 05:18:07.296213 | 
2026-07-28 05:18:07.296300 | TASK [fetch-output : Set log path for single node]
2026-07-28 05:18:07.338998 | instance | ok
2026-07-28 05:18:07.346088 | 
2026-07-28 05:18:07.346197 | LOOP [fetch-output : Ensure local output dirs]
2026-07-28 05:18:07.777342 | instance -> localhost | ok: "/var/lib/zuul/builds/af6d44f35e744ef19431f5a80809af12/work/logs"
2026-07-28 05:18:07.991866 | instance -> localhost | changed: "/var/lib/zuul/builds/af6d44f35e744ef19431f5a80809af12/work/artifacts"
2026-07-28 05:18:08.197676 | instance -> localhost | changed: "/var/lib/zuul/builds/af6d44f35e744ef19431f5a80809af12/work/docs"
2026-07-28 05:18:08.218639 | 
2026-07-28 05:18:08.218811 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-07-28 05:18:08.828278 | instance | changed: .d..t...... ./
2026-07-28 05:18:08.828687 | instance | changed: All items complete
2026-07-28 05:18:08.828719 | 
2026-07-28 05:18:09.256614 | instance | changed: .d..t...... ./
2026-07-28 05:18:09.692920 | instance | changed: .d..t...... ./
2026-07-28 05:18:09.727466 | 
2026-07-28 05:18:09.727598 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-07-28 05:18:10.141692 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007262
2026-07-28 05:18:10.377345 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007529
2026-07-28 05:18:10.396150 | 
2026-07-28 05:18:10.396282 | PLAY [all]
2026-07-28 05:18:10.402806 | 
2026-07-28 05:18:10.402873 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-07-28 05:18:10.797851 | instance | changed
2026-07-28 05:18:10.811313 | 
2026-07-28 05:18:10.811371 | PLAY RECAP
2026-07-28 05:18:10.811416 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-28 05:18:10.811438 | 
2026-07-28 05:18:10.978645 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-28 05:18:10.983269 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-07-28 05:18:11.594804 | 
2026-07-28 05:18:11.594954 | PLAY [localhost]
2026-07-28 05:18:11.605717 | 
2026-07-28 05:18:11.605803 | TASK [Generate Zuul manifest]
2026-07-28 05:18:11.626203 | localhost | ok
2026-07-28 05:18:11.644530 | 
2026-07-28 05:18:11.644612 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-07-28 05:18:12.048782 | localhost | changed
2026-07-28 05:18:12.059119 | 
2026-07-28 05:18:12.059195 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-07-28 05:18:12.372147 | localhost | ok
2026-07-28 05:18:12.383167 | 
2026-07-28 05:18:12.383259 | TASK [Upload logs]
2026-07-28 05:18:12.400930 | localhost | ok
2026-07-28 05:18:12.463505 | 
2026-07-28 05:18:12.463629 | TASK [Set zuul-log-path fact]
2026-07-28 05:18:12.482758 | localhost | ok
2026-07-28 05:18:12.497143 | 
2026-07-28 05:18:12.497211 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-28 05:18:12.530118 | localhost | ok
2026-07-28 05:18:12.539498 | 
2026-07-28 05:18:12.539563 | TASK [upload-logs : Create log directories]
2026-07-28 05:18:13.149815 | localhost | changed
2026-07-28 05:18:13.155769 | 
2026-07-28 05:18:13.155902 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-07-28 05:18:13.599698 | localhost -> localhost | ok: Runtime: 0:00:00.007039
2026-07-28 05:18:13.672000 | 
2026-07-28 05:18:13.672161 | TASK [upload-logs : Upload logs to log server]
2026-07-28 05:18:14.121980 | localhost | Output suppressed because no_log was given
2026-07-28 05:18:14.127479 | 
2026-07-28 05:18:14.127571 | LOOP [upload-logs : Compress console log and json output]
2026-07-28 05:18:14.176473 | localhost | skipping: Conditional result was False
2026-07-28 05:18:14.181377 | localhost | skipping: Conditional result was False
2026-07-28 05:18:14.196646 | 
2026-07-28 05:18:14.196871 | LOOP [upload-logs : Upload compressed console log and json output]
2026-07-28 05:18:14.238138 | localhost | skipping: Conditional result was False
2026-07-28 05:18:14.238456 | 
2026-07-28 05:18:14.242337 | localhost | skipping: Conditional result was False
2026-07-28 05:18:14.260683 | 
2026-07-28 05:18:14.260848 | LOOP [upload-logs : Upload console log and json output]