2026-07-29 13:26:57.112250 | Job console starting
2026-07-29 13:26:57.124131 | Updating git repos
2026-07-29 13:26:58.697505 | Cloning repos into workspace
2026-07-29 13:27:49.970260 | Restoring repo states
2026-07-29 13:27:59.862950 | Merging changes
2026-07-29 13:28:14.207042 | Checking out repos
2026-07-29 13:28:25.438933 | Preparing playbooks
2026-07-29 13:28:44.756249 | Running Ansible setup
2026-07-29 13:28:55.560494 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-29 13:28:56.169486 | 
2026-07-29 13:28:56.169593 | PLAY [localhost]
2026-07-29 13:28:56.176767 | 
2026-07-29 13:28:56.176836 | TASK [Gathering Facts]
2026-07-29 13:28:57.202701 | localhost | ok
2026-07-29 13:28:57.211864 | 
2026-07-29 13:28:57.211958 | TASK [Setup log path fact]
2026-07-29 13:28:57.230492 | localhost | ok
2026-07-29 13:28:57.246210 | 
2026-07-29 13:28:57.246320 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-29 13:28:57.276995 | localhost | ok
2026-07-29 13:28:57.284934 | 
2026-07-29 13:28:57.285051 | TASK [emit-job-header : Print job information]
2026-07-29 13:28:57.326101 | # Job Information
2026-07-29 13:28:57.326350 | Ansible Version: 2.16.18
2026-07-29 13:28:57.326426 | Job: atmosphere-molecule-csi-rbd-selective
2026-07-29 13:28:57.326480 | Pipeline: check
2026-07-29 13:28:57.326529 | Executor: 2d72f0692154
2026-07-29 13:28:57.326577 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4166
2026-07-29 13:28:57.326633 | Event ID: 09555d70-8b50-11f1-9e22-fde1b19d7ef8
2026-07-29 13:28:57.332553 | 
2026-07-29 13:28:57.332615 | LOOP [emit-job-header : Print node information]
2026-07-29 13:28:57.424112 | localhost | ok:
2026-07-29 13:28:57.424329 | localhost | # Node Information
2026-07-29 13:28:57.424359 | localhost | Inventory Hostname: instance
2026-07-29 13:28:57.424381 | localhost | Hostname: np0000187396
2026-07-29 13:28:57.424402 | localhost | Username: zuul
2026-07-29 13:28:57.424425 | localhost | Distro: Ubuntu 22.04
2026-07-29 13:28:57.424445 | localhost | Provider: yul1
2026-07-29 13:28:57.424464 | localhost | Region: ca-ymq-1
2026-07-29 13:28:57.424483 | localhost | Label: ubuntu-jammy
2026-07-29 13:28:57.424502 | localhost | Product Name: OpenStack Nova
2026-07-29 13:28:57.424520 | localhost | Interface IP: 199.19.213.226
2026-07-29 13:28:57.441226 | 
2026-07-29 13:28:57.441360 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-07-29 13:28:57.829817 | localhost -> localhost | changed
2026-07-29 13:28:57.838218 | 
2026-07-29 13:28:57.838313 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-07-29 13:28:58.737921 | localhost -> localhost | changed
2026-07-29 13:28:58.747982 | 
2026-07-29 13:28:58.748050 | PLAY [all]
2026-07-29 13:28:58.758448 | 
2026-07-29 13:28:58.758875 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-07-29 13:28:58.987477 | instance -> localhost | ok
2026-07-29 13:28:58.995780 | 
2026-07-29 13:28:58.995881 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-07-29 13:28:59.027260 | instance | ok
2026-07-29 13:29:00.206446 | instance | included: /var/lib/zuul/builds/5d554b09ba0f41ddb45152ce71184934/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-07-29 13:29:00.212660 | 
2026-07-29 13:29:00.212733 | TASK [add-build-sshkey : Create Temp SSH key]
2026-07-29 13:29:01.439479 | instance -> localhost | Generating public/private rsa key pair.
2026-07-29 13:29:01.439788 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/5d554b09ba0f41ddb45152ce71184934/work/5d554b09ba0f41ddb45152ce71184934_id_rsa
2026-07-29 13:29:01.439840 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/5d554b09ba0f41ddb45152ce71184934/work/5d554b09ba0f41ddb45152ce71184934_id_rsa.pub
2026-07-29 13:29:01.439872 | instance -> localhost | The key fingerprint is:
2026-07-29 13:29:01.439902 | instance -> localhost | SHA256:Dg4KXLp7uiBQUcoHwT3uD3A3ZPU1Eep1+wF90xzHvH4 zuul-build-sshkey
2026-07-29 13:29:01.439953 | instance -> localhost | The key's randomart image is:
2026-07-29 13:29:01.439984 | instance -> localhost | +---[RSA 3072]----+
2026-07-29 13:29:01.440020 | instance -> localhost | | .++.  ..   =o +.|
2026-07-29 13:29:01.440051 | instance -> localhost | | ..+o o  . o ...*|
2026-07-29 13:29:01.440079 | instance -> localhost | |  +o.+    o ...o=|
2026-07-29 13:29:01.440107 | instance -> localhost | |..+.o o  . . ..oo|
2026-07-29 13:29:01.440135 | instance -> localhost | |oo +....S .   o. |
2026-07-29 13:29:01.440162 | instance -> localhost | |....oo o       oE|
2026-07-29 13:29:01.440189 | instance -> localhost | |o..  o. .       o|
2026-07-29 13:29:01.440216 | instance -> localhost | |o ..  .          |
2026-07-29 13:29:01.440247 | instance -> localhost | | ++              |
2026-07-29 13:29:01.440275 | instance -> localhost | +----[SHA256]-----+
2026-07-29 13:29:01.440370 | instance -> localhost | ok: Runtime: 0:00:00.812969
2026-07-29 13:29:01.447672 | 
2026-07-29 13:29:01.447810 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-07-29 13:29:01.482213 | instance | ok
2026-07-29 13:29:01.492419 | instance | included: /var/lib/zuul/builds/5d554b09ba0f41ddb45152ce71184934/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-07-29 13:29:01.500728 | 
2026-07-29 13:29:01.501372 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-07-29 13:29:01.527912 | instance | skipping: Conditional result was False
2026-07-29 13:29:01.538724 | 
2026-07-29 13:29:01.538822 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-07-29 13:29:02.004091 | instance | changed
2026-07-29 13:29:02.014658 | 
2026-07-29 13:29:02.014948 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-07-29 13:29:02.222922 | instance | ok
2026-07-29 13:29:02.230288 | 
2026-07-29 13:29:02.230402 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-07-29 13:29:02.682122 | instance | changed
2026-07-29 13:29:02.691662 | 
2026-07-29 13:29:02.691877 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-07-29 13:29:03.142937 | instance | changed
2026-07-29 13:29:03.151804 | 
2026-07-29 13:29:03.151988 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-07-29 13:29:03.178328 | instance | skipping: Conditional result was False
2026-07-29 13:29:03.188728 | 
2026-07-29 13:29:03.188941 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-07-29 13:29:03.571802 | instance -> localhost | changed
2026-07-29 13:29:03.587586 | 
2026-07-29 13:29:03.593251 | TASK [add-build-sshkey : Add back temp key]
2026-07-29 13:29:05.069165 | instance -> localhost | Identity added: /var/lib/zuul/builds/5d554b09ba0f41ddb45152ce71184934/work/5d554b09ba0f41ddb45152ce71184934_id_rsa (zuul-build-sshkey)
2026-07-29 13:29:05.069450 | instance -> localhost | ok: Runtime: 0:00:00.012577
2026-07-29 13:29:05.075021 | 
2026-07-29 13:29:05.075107 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-07-29 13:29:05.382986 | instance | ok
2026-07-29 13:29:05.389838 | 
2026-07-29 13:29:05.389940 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-07-29 13:29:05.416176 | instance | skipping: Conditional result was False
2026-07-29 13:29:05.432343 | 
2026-07-29 13:29:05.432432 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-07-29 13:29:05.792715 | instance | ok
2026-07-29 13:29:05.798136 | 
2026-07-29 13:29:05.799741 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-07-29 13:29:10.281618 | instance | Output suppressed because no_log was given
2026-07-29 13:29:10.294428 | 
2026-07-29 13:29:10.294577 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-07-29 13:29:10.511478 | instance | ok: "logs"
2026-07-29 13:29:10.512020 | instance | ok: All items complete
2026-07-29 13:29:10.512055 | 
2026-07-29 13:29:10.670025 | instance | ok: "artifacts"
2026-07-29 13:29:10.869043 | instance | ok: "docs"
2026-07-29 13:29:12.334194 | 
2026-07-29 13:29:12.334372 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-07-29 13:29:12.521292 | instance | changed: "logs"
2026-07-29 13:29:12.696803 | instance | changed: "artifacts"
2026-07-29 13:29:12.906193 | instance | changed: "docs"
2026-07-29 13:29:12.922447 | 
2026-07-29 13:29:12.922543 | PLAY RECAP
2026-07-29 13:29:12.922596 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-07-29 13:29:12.922626 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-29 13:29:12.922648 | 
2026-07-29 13:29:13.040157 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-29 13:29:13.042789 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-29 13:29:13.677370 | 
2026-07-29 13:29:15.551747 | PLAY [all]
2026-07-29 13:29:15.571437 | 
2026-07-29 13:29:15.571612 | TASK [setup-uv : Extract archive]
2026-07-29 13:29:18.113156 | instance | changed
2026-07-29 13:29:18.122209 | 
2026-07-29 13:29:18.122430 | TASK [setup-uv : Print version]
2026-07-29 13:29:18.478893 | instance | uv 0.8.13
2026-07-29 13:29:20.101207 | instance | ok: Runtime: 0:00:00.011200
2026-07-29 13:29:20.113548 | 
2026-07-29 13:29:20.113620 | TASK [Install Ansible collection siblings]
2026-07-29 13:29:29.409998 | instance | changed:
2026-07-29 13:29:29.410265 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-07-29 13:29:29.410309 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-07-29 13:29:29.410341 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-07-29 13:29:29.410376 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-07-29 13:29:29.410444 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-07-29 13:29:29.410486 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-07-29 13:29:29.410519 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-07-29 13:29:29.410552 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-07-29 13:29:29.410582 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-07-29 13:29:29.410610 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-07-29 13:29:29.410639 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-07-29 13:29:29.410666 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-07-29 13:29:29.410703 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-07-29 13:29:29.410739 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-07-29 13:29:29.410768 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0
2026-07-29 13:29:29.410796 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-07-29 13:29:29.410827 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-07-29 13:29:29.410864 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-07-29 13:29:29.410893 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-07-29 13:29:29.410920 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-07-29 13:29:29.410947 | instance | Resolved vexxhost.ceph to v4.1.0 for >=3.2.0
2026-07-29 13:29:29.410986 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-07-29 13:29:29.411050 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-29 13:29:29.411117 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-07-29 13:29:29.411182 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0,>=6.0.0
2026-07-29 13:29:29.411222 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-29 13:29:29.411253 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-29 13:29:29.411283 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-29 13:29:29.411311 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-07-29 13:29:29.411339 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-07-29 13:29:29.411368 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-29 13:29:29.411406 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-29 13:29:29.411472 | instance | Checked out ansible.netcommon to 1.2.0
2026-07-29 13:29:29.411516 | 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-29 13:29:29.411557 | instance | Starting galaxy collection install process
2026-07-29 13:29:29.411587 | instance | Process install dependency map
2026-07-29 13:29:29.411617 | instance | Starting collection install process
2026-07-29 13:29:29.411645 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-07-29 13:29:29.411673 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-07-29 13:29:29.411699 | instance | ansible.netcommon:* was installed successfully
2026-07-29 13:29:29.411726 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-07-29 13:29:29.411752 | instance | Creating virtual environment at: .venv
2026-07-29 13:29:29.411779 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-29 13:29:29.411805 | instance | Downloading kubernetes (1.9MiB)
2026-07-29 13:29:29.411832 | instance | Downloading pydantic-core (2.0MiB)
2026-07-29 13:29:29.411860 | instance | Downloading openstacksdk (1.7MiB)
2026-07-29 13:29:29.411888 | instance | Downloading ansible-core (2.1MiB)
2026-07-29 13:29:29.411914 | instance | Downloading cryptography (4.2MiB)
2026-07-29 13:29:29.411948 | instance | Downloading setuptools (1.1MiB)
2026-07-29 13:29:29.411984 | instance | Downloading pygments (1.2MiB)
2026-07-29 13:29:29.412014 | instance | Downloading netaddr (2.2MiB)
2026-07-29 13:29:29.412042 | instance | Downloading rjsonnet (1.2MiB)
2026-07-29 13:29:29.412069 | instance |    Building pyperclip==1.9.0
2026-07-29 13:29:29.412099 | instance |  Downloading rjsonnet
2026-07-29 13:29:29.412126 | instance |  Downloading pydantic-core
2026-07-29 13:29:29.412153 | instance |  Downloading netaddr
2026-07-29 13:29:29.412180 | instance |  Downloading pygments
2026-07-29 13:29:29.412207 | instance |  Downloading cryptography
2026-07-29 13:29:29.412234 | instance |  Downloading setuptools
2026-07-29 13:29:29.412260 | instance |  Downloading kubernetes
2026-07-29 13:29:29.412380 | instance |  Downloading ansible-core
2026-07-29 13:29:29.412413 | instance |  Downloading openstacksdk
2026-07-29 13:29:29.412441 | instance |       Built pyperclip==1.9.0
2026-07-29 13:29:29.412470 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-29 13:29:29.412506 | instance | Installed 87 packages in 37ms
2026-07-29 13:29:29.412543 | instance | Checked out ansible.posix to 1.6.0
2026-07-29 13:29:29.412701 | 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-29 13:29:29.412735 | instance | Starting galaxy collection install process
2026-07-29 13:29:29.412763 | instance | Process install dependency map
2026-07-29 13:29:29.412797 | instance | Starting collection install process
2026-07-29 13:29:29.412826 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-07-29 13:29:29.412855 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-07-29 13:29:29.412919 | instance | ansible.posix:1.6.0 was installed successfully
2026-07-29 13:29:29.412992 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 13:29:29.413032 | instance | 2.17.12
2026-07-29 13:29:29.413070 | instance | Checked out ansible.utils to v6.0.3
2026-07-29 13:29:29.413107 | 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-29 13:29:29.413143 | instance | Starting galaxy collection install process
2026-07-29 13:29:29.413180 | instance | Process install dependency map
2026-07-29 13:29:29.413216 | instance | Starting collection install process
2026-07-29 13:29:29.413251 | instance | Installing 'ansible.utils:6.0.3' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-07-29 13:29:29.413284 | instance | Created collection for ansible.utils:6.0.3 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-07-29 13:29:29.413318 | instance | ansible.utils:6.0.3 was installed successfully
2026-07-29 13:29:29.413354 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 13:29:29.413392 | instance | 2.17.12
2026-07-29 13:29:29.413428 | instance | Checked out atmosphere.common to v0.7.0
2026-07-29 13:29:29.413456 | 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-29 13:29:29.413484 | instance | Starting galaxy collection install process
2026-07-29 13:29:29.413510 | instance | Process install dependency map
2026-07-29 13:29:29.413537 | instance | Starting collection install process
2026-07-29 13:29:29.413563 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-07-29 13:29:29.413590 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-07-29 13:29:29.413617 | instance | atmosphere.common:0.7.0 was installed successfully
2026-07-29 13:29:29.413644 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 13:29:29.413671 | instance | 2.17.12
2026-07-29 13:29:29.413697 | instance | Checked out community.crypto to 2.2.3
2026-07-29 13:29:29.413724 | 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-29 13:29:29.413750 | instance | Starting galaxy collection install process
2026-07-29 13:29:29.413776 | instance | Process install dependency map
2026-07-29 13:29:29.413802 | instance | Starting collection install process
2026-07-29 13:29:29.413828 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-07-29 13:29:29.413857 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-07-29 13:29:29.413884 | instance | community.crypto:2.2.3 was installed successfully
2026-07-29 13:29:29.413910 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 13:29:29.413937 | instance | 2.17.12
2026-07-29 13:29:29.413963 | instance | Checked out community.general to 7.3.0
2026-07-29 13:29:29.413990 | 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-29 13:29:29.414029 | instance | Starting galaxy collection install process
2026-07-29 13:29:29.414081 | instance | Process install dependency map
2026-07-29 13:29:29.414111 | instance | Starting collection install process
2026-07-29 13:29:29.414241 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-07-29 13:29:29.414286 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-07-29 13:29:29.414316 | instance | community.general:7.3.0 was installed successfully
2026-07-29 13:29:29.414346 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 13:29:29.414374 | instance | 2.17.12
2026-07-29 13:29:29.414404 | instance | Checked out community.mysql to 3.6.0
2026-07-29 13:29:29.414436 | 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-29 13:29:29.414465 | instance | Starting galaxy collection install process
2026-07-29 13:29:29.414493 | instance | Process install dependency map
2026-07-29 13:29:29.414519 | instance | Starting collection install process
2026-07-29 13:29:29.414547 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-07-29 13:29:29.414573 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-07-29 13:29:29.414600 | instance | community.mysql:3.6.0 was installed successfully
2026-07-29 13:29:29.414626 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 13:29:29.414652 | instance | 2.17.12
2026-07-29 13:29:29.414678 | instance | Checked out kubernetes.core to 2.4.0
2026-07-29 13:29:29.414706 | 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-29 13:29:29.414732 | instance | Starting galaxy collection install process
2026-07-29 13:29:29.414759 | instance | Process install dependency map
2026-07-29 13:29:29.414786 | instance | Starting collection install process
2026-07-29 13:29:29.414813 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-07-29 13:29:29.414840 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-07-29 13:29:29.414867 | instance | kubernetes.core:2.4.0 was installed successfully
2026-07-29 13:29:29.414893 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 13:29:29.414920 | instance | 2.17.12
2026-07-29 13:29:29.414946 | instance | Checked out openstack.cloud to 2.6.0
2026-07-29 13:29:29.414973 | 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-29 13:29:29.415000 | instance | Starting galaxy collection install process
2026-07-29 13:29:29.415026 | instance | Process install dependency map
2026-07-29 13:29:29.415052 | instance | Starting collection install process
2026-07-29 13:29:29.415078 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-07-29 13:29:29.415104 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-07-29 13:29:29.415130 | instance | openstack.cloud:2.6.0 was installed successfully
2026-07-29 13:29:29.415156 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 13:29:29.415182 | instance | 2.17.12
2026-07-29 13:29:29.415216 | instance | Checked out vexxhost.ceph to v4.1.0
2026-07-29 13:29:29.415244 | 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-29 13:29:29.415271 | instance | Starting galaxy collection install process
2026-07-29 13:29:29.415298 | instance | Process install dependency map
2026-07-29 13:29:29.415324 | instance | Starting collection install process
2026-07-29 13:29:29.415350 | instance | Installing 'vexxhost.ceph:4.1.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-07-29 13:29:29.415376 | instance | Created collection for vexxhost.ceph:4.1.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-07-29 13:29:29.415403 | instance | vexxhost.ceph:4.1.0 was installed successfully
2026-07-29 13:29:29.415430 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 13:29:29.415457 | instance | 2.17.12
2026-07-29 13:29:29.415483 | instance | Checked out vexxhost.containers to v1.6.8
2026-07-29 13:29:29.415511 | 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-29 13:29:29.415537 | instance | Starting galaxy collection install process
2026-07-29 13:29:29.415565 | instance | Process install dependency map
2026-07-29 13:29:29.415592 | instance | Starting collection install process
2026-07-29 13:29:29.415619 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-07-29 13:29:29.415646 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-07-29 13:29:29.415672 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-07-29 13:29:29.415700 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 13:29:29.415727 | instance | 2.17.12
2026-07-29 13:29:29.415754 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-07-29 13:29:29.415781 | 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-29 13:29:29.415807 | instance | Starting galaxy collection install process
2026-07-29 13:29:29.415834 | instance | Process install dependency map
2026-07-29 13:29:29.415861 | instance | Starting collection install process
2026-07-29 13:29:29.415888 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-07-29 13:29:29.415916 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-07-29 13:29:29.415942 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-07-29 13:29:29.415969 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 13:29:29.415996 | instance | 2.17.12
2026-07-29 13:29:29.423340 | 
2026-07-29 13:29:29.423423 | PLAY RECAP
2026-07-29 13:29:29.423474 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-29 13:29:29.423496 | 
2026-07-29 13:29:29.556599 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-29 13:29:29.561524 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-29 13:29:30.174080 | 
2026-07-29 13:29:30.174202 | PLAY [all]
2026-07-29 13:29:30.185694 | 
2026-07-29 13:29:30.185785 | TASK [Install "jq" for log collection]
2026-07-29 13:29:40.489477 | instance | changed
2026-07-29 13:29:40.492447 | 
2026-07-29 13:29:40.492636 | PLAY RECAP
2026-07-29 13:29:40.492721 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-29 13:29:40.492795 | 
2026-07-29 13:29:40.643749 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-29 13:29:40.647722 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-29 13:29:41.322710 | 
2026-07-29 13:29:41.322871 | PLAY [Prepare selective Molecule plan]
2026-07-29 13:29:41.341131 | 
2026-07-29 13:29:41.341295 | TASK [Generate selective Molecule plan]
2026-07-29 13:29:42.378693 | instance | ok: Runtime: 0:00:00.176138
2026-07-29 13:29:42.385623 | 
2026-07-29 13:29:42.385746 | TASK [Render selective Molecule plan]
2026-07-29 13:29:42.661754 | instance | Selective Molecule plan: selective
2026-07-29 13:29:42.661937 | instance | Changed files:
2026-07-29 13:29:42.661948 | instance |   M roles/nova/README.md
2026-07-29 13:29:42.661954 | instance | Targets: nova
2026-07-29 13:29:42.661960 | instance | Job decisions:
2026-07-29 13:29:42.661969 | instance |   RUN  aio-openvswitch: selected by changed components
2026-07-29 13:29:42.661977 | instance |        components: ceph, ceph-provisioners, cert-manager, cluster-issuer, coredns, csi, glance, ingress-nginx, keycloak, keystone, kubernetes, libvirt, memcached, neutron, nova, openvswitch, percona-xtradb-cluster, percona-xtradb-cluster-operator, placement, rabbitmq-cluster-operator
2026-07-29 13:29:42.661989 | instance |        Tempest include: ^tempest\.api\.compute\., ^tempest\.scenario\.test_server_
2026-07-29 13:29:42.661996 | instance |   SKIP aio-ovn: no changed component requires this job
2026-07-29 13:29:42.662001 | instance |   SKIP csi-local-path-provisioner: no changed component requires this job
2026-07-29 13:29:42.662007 | instance |   SKIP csi-rbd: no changed component requires this job
2026-07-29 13:29:42.662013 | instance |   SKIP keycloak: no changed component requires this job
2026-07-29 13:29:42.921391 | instance | ok: Runtime: 0:00:00.092248
2026-07-29 13:29:42.923475 | 
2026-07-29 13:29:42.923558 | PLAY RECAP
2026-07-29 13:29:42.923614 | instance | ok: 2 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-29 13:29:42.923646 | 
2026-07-29 13:29:43.103755 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-29 13:29:43.106669 | RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-29 13:29:43.830212 | 
2026-07-29 13:29:43.830426 | PLAY [Run selected Molecule scenario]
2026-07-29 13:29:43.845973 | 
2026-07-29 13:29:43.950935 | TASK [Read selective Molecule plan]
2026-07-29 13:29:44.353570 | instance | ok
2026-07-29 13:29:44.360348 | 
2026-07-29 13:29:44.360456 | TASK [Resolve this job decision]
2026-07-29 13:29:44.409390 | instance | ok
2026-07-29 13:29:44.418355 | 
2026-07-29 13:29:44.418440 | TASK [Assert this job exists in the plan]
2026-07-29 13:29:44.451556 | instance | ok: All assertions passed
2026-07-29 13:29:44.458170 | 
2026-07-29 13:29:44.458277 | TASK [Resolve selected scenario inputs]
2026-07-29 13:29:44.500257 | instance | ok
2026-07-29 13:29:44.507706 | 
2026-07-29 13:29:44.507824 | TASK [Report this job decision]
2026-07-29 13:29:44.561890 | instance | ok:
2026-07-29 13:29:44.562064 | instance | Selective Molecule job: csi-rbd
2026-07-29 13:29:44.562106 | instance | Decision: SKIP
2026-07-29 13:29:44.562147 | instance | Reason: no changed component requires this job
2026-07-29 13:29:44.562185 | instance | Scenario: csi
2026-07-29 13:29:44.562218 | instance | Targets: (none)
2026-07-29 13:29:44.562249 | instance | Components: (full scenario)
2026-07-29 13:29:44.569799 | 
2026-07-29 13:29:44.569934 | LOOP [Check Molecule inventory directories]
2026-07-29 13:29:44.607494 | instance | skipping: Conditional result was False
2026-07-29 13:29:44.609311 | instance | skipping: Conditional result was False
2026-07-29 13:29:44.611396 | instance | skipping: Conditional result was False
2026-07-29 13:29:44.622297 | 
2026-07-29 13:29:44.622424 | LOOP [Copy Zuul inventory for Molecule]
2026-07-29 13:29:44.663117 | instance | skipping: Conditional result was False
2026-07-29 13:29:44.663359 | 
2026-07-29 13:29:44.666014 | instance | skipping: Conditional result was False
2026-07-29 13:29:44.670042 | instance | skipping: Conditional result was False
2026-07-29 13:29:44.686565 | 
2026-07-29 13:29:44.686681 | LOOP [Use private addresses in Molecule inventory]
2026-07-29 13:29:44.723961 | instance | skipping: Conditional result was False
2026-07-29 13:29:44.724244 | 
2026-07-29 13:29:44.726736 | instance | skipping: Conditional result was False
2026-07-29 13:29:44.729594 | instance | skipping: Conditional result was False
2026-07-29 13:29:44.741765 | 
2026-07-29 13:29:44.742002 | TASK [Prepare Molecule command]
2026-07-29 13:29:44.781198 | instance | skipping: Conditional result was False
2026-07-29 13:29:44.792187 | 
2026-07-29 13:29:44.792444 | TASK [Run standard Molecule lifecycle with progress output]
2026-07-29 13:29:44.838660 | instance | skipping: Conditional result was False
2026-07-29 13:29:44.840703 | 
2026-07-29 13:29:44.840798 | PLAY RECAP
2026-07-29 13:29:44.840887 | instance | ok: 5 changed: 0 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-07-29 13:29:44.840935 | 
2026-07-29 13:29:44.967779 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-29 13:29:44.970065 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-07-29 13:29:45.675972 | 
2026-07-29 13:29:47.241118 | PLAY [Publish selective Molecule plan]
2026-07-29 13:29:47.259167 | 
2026-07-29 13:29:47.259348 | TASK [Check for selective Molecule plan]
2026-07-29 13:29:47.649069 | instance | ok
2026-07-29 13:29:47.654020 | 
2026-07-29 13:29:47.654171 | TASK [Create log directory]
2026-07-29 13:29:48.049748 | instance | changed
2026-07-29 13:29:48.056420 | 
2026-07-29 13:29:48.056563 | TASK [Copy selective Molecule plan to logs]
2026-07-29 13:29:48.517456 | instance | changed
2026-07-29 13:29:48.519567 | 
2026-07-29 13:29:48.519622 | PLAY RECAP
2026-07-29 13:29:48.519675 | instance | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-29 13:29:48.519697 | 
2026-07-29 13:29:48.732862 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-07-29 13:29:48.737381 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-29 13:29:49.460290 | 
2026-07-29 13:29:49.460457 | PLAY [all]
2026-07-29 13:29:49.478143 | 
2026-07-29 13:29:49.478309 | TASK [gather-host-logs : creating directory for system status]
2026-07-29 13:29:49.886152 | instance | changed
2026-07-29 13:29:49.894148 | 
2026-07-29 13:29:49.894305 | TASK [gather-host-logs : Get logs for each host]
2026-07-29 13:29:50.258806 | instance | + systemd-cgls --full --all --no-pager
2026-07-29 13:29:50.267236 | instance | + ip addr
2026-07-29 13:29:50.268739 | instance | + ip route
2026-07-29 13:29:50.270044 | instance | + lsblk
2026-07-29 13:29:50.272468 | instance | + mount
2026-07-29 13:29:50.273905 | instance | + docker images
2026-07-29 13:29:50.274260 | instance | /bin/bash: line 7: docker: command not found
2026-07-29 13:29:50.274457 | instance | + brctl show
2026-07-29 13:29:50.274831 | instance | /bin/bash: line 8: brctl: command not found
2026-07-29 13:29:50.275036 | instance | + ps aux --sort=-%mem
2026-07-29 13:29:50.283920 | instance | + dpkg -l
2026-07-29 13:29:50.290785 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-07-29 13:29:50.291142 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-07-29 13:29:50.291193 | instance | /bin/bash: line 11: docker: command not found
2026-07-29 13:29:50.291462 | instance | + '[' '!' -z '' ']'
2026-07-29 13:29:50.879643 | instance | ok: Runtime: 0:00:00.037981
2026-07-29 13:29:50.890003 | 
2026-07-29 13:29:50.890134 | TASK [gather-host-logs : Downloads logs to executor]
2026-07-29 13:29:51.559632 | instance | changed:
2026-07-29 13:29:51.559820 | instance | created directory /var/lib/zuul/builds/5d554b09ba0f41ddb45152ce71184934/work/logs/instance
2026-07-29 13:29:51.559857 | instance | cd+++++++++ system/
2026-07-29 13:29:51.559887 | instance | >f+++++++++ system/brctl-show.txt
2026-07-29 13:29:51.559914 | instance | >f+++++++++ system/docker-images.txt
2026-07-29 13:29:51.559943 | instance | >f+++++++++ system/ip-addr.txt
2026-07-29 13:29:51.559973 | instance | >f+++++++++ system/ip-route.txt
2026-07-29 13:29:51.560000 | instance | >f+++++++++ system/lsblk.txt
2026-07-29 13:29:51.560026 | instance | >f+++++++++ system/mount.txt
2026-07-29 13:29:51.560054 | instance | >f+++++++++ system/packages.txt
2026-07-29 13:29:51.560080 | instance | >f+++++++++ system/ps.txt
2026-07-29 13:29:51.560105 | instance | >f+++++++++ system/systemd-cgls.txt
2026-07-29 13:29:51.572833 | 
2026-07-29 13:29:51.572994 | LOOP [helm-release-status : creating directory for helm release status]
2026-07-29 13:29:51.764589 | instance | changed: "values"
2026-07-29 13:29:51.934198 | instance | changed: "releases"
2026-07-29 13:29:51.944967 | 
2026-07-29 13:29:51.945114 | TASK [helm-release-status : Gather get release status for helm charts]
2026-07-29 13:29:52.158759 | instance | /bin/bash: line 3: kubectl: command not found
2026-07-29 13:29:52.486770 | instance | ok: Runtime: 0:00:00.006326
2026-07-29 13:29:52.494470 | 
2026-07-29 13:29:52.494629 | TASK [helm-release-status : Downloads logs to executor]
2026-07-29 13:29:53.016529 | instance | changed:
2026-07-29 13:29:53.016740 | instance | cd+++++++++ helm/
2026-07-29 13:29:53.016784 | instance | cd+++++++++ helm/releases/
2026-07-29 13:29:53.016818 | instance | cd+++++++++ helm/values/
2026-07-29 13:29:53.032999 | 
2026-07-29 13:29:53.033148 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-07-29 13:29:53.235773 | instance | changed
2026-07-29 13:29:53.243188 | 
2026-07-29 13:29:53.243308 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-07-29 13:29:53.523897 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-29 13:29:53.524189 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-29 13:29:53.530367 | instance | environment: line 1: kubectl: command not found
2026-07-29 13:29:53.531123 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-29 13:29:53.531330 | instance | environment: line 1: kubectl: command not found
2026-07-29 13:29:53.532380 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-29 13:29:53.533454 | instance | environment: line 1: kubectl: command not found
2026-07-29 13:29:53.535062 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-29 13:29:53.535154 | instance | environment: line 1: kubectl: command not found
2026-07-29 13:29:53.536086 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-29 13:29:53.537467 | instance | environment: line 1: kubectl: command not found
2026-07-29 13:29:53.538266 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-29 13:29:53.837305 | instance | ok: Runtime: 0:00:00.025417
2026-07-29 13:29:53.844463 | 
2026-07-29 13:29:53.844563 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-07-29 13:29:54.071118 | instance | changed
2026-07-29 13:29:54.077587 | 
2026-07-29 13:29:54.077731 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-07-29 13:29:54.297132 | instance | environment: line 5: kubectl: command not found
2026-07-29 13:29:54.297677 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-29 13:29:54.298457 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-29 13:29:54.298771 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-29 13:29:54.616075 | instance | ok: Runtime: 0:00:00.009141
2026-07-29 13:29:54.623914 | 
2026-07-29 13:29:54.624021 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-07-29 13:29:55.121875 | instance | changed:
2026-07-29 13:29:55.122120 | instance | cd+++++++++ objects/
2026-07-29 13:29:55.122167 | instance | cd+++++++++ objects/cluster/
2026-07-29 13:29:55.122203 | instance | cd+++++++++ objects/namespaced/
2026-07-29 13:29:55.134235 | 
2026-07-29 13:29:55.134370 | TASK [gather-pod-logs : creating directory for pod logs]
2026-07-29 13:29:55.349091 | instance | changed
2026-07-29 13:29:55.357852 | 
2026-07-29 13:29:55.358009 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-07-29 13:29:55.552560 | instance | changed
2026-07-29 13:29:55.558857 | 
2026-07-29 13:29:55.559001 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-07-29 13:29:55.784126 | instance | environment: line 3: kubectl: command not found
2026-07-29 13:29:56.098698 | instance | ok: Runtime: 0:00:00.007537
2026-07-29 13:29:56.108055 | 
2026-07-29 13:29:56.108189 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-07-29 13:29:56.643328 | instance | changed:
2026-07-29 13:29:56.643587 | instance | cd+++++++++ pod-logs/
2026-07-29 13:29:56.643631 | instance | cd+++++++++ pod-logs/failed-pods/
2026-07-29 13:29:56.657810 | 
2026-07-29 13:29:56.657911 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-07-29 13:29:56.868828 | instance | changed
2026-07-29 13:29:56.894574 | 
2026-07-29 13:29:56.894784 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-07-29 13:29:57.134130 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-29 13:29:57.436240 | instance | ok: Runtime: 0:00:00.037569
2026-07-29 13:29:57.442579 | 
2026-07-29 13:29:57.442695 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-07-29 13:29:57.649290 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-29 13:29:57.678419 | instance | ceph-mgr endpoints:
2026-07-29 13:29:57.980501 | instance | ok: Runtime: 0:00:00.036286
2026-07-29 13:29:57.987735 | 
2026-07-29 13:29:57.987885 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-07-29 13:29:58.238928 | instance | /bin/bash: line 4: kubectl: command not found
2026-07-29 13:29:58.527186 | instance | ok: Runtime: 0:00:00.037202
2026-07-29 13:29:58.535906 | 
2026-07-29 13:29:58.536074 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-07-29 13:29:59.087766 | instance | changed: cd+++++++++ prometheus/
2026-07-29 13:29:59.098778 | 
2026-07-29 13:29:59.098874 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-07-29 13:29:59.328715 | instance | changed
2026-07-29 13:29:59.336551 | 
2026-07-29 13:29:59.336730 | TASK [gather-selenium-data : Get selenium data]
2026-07-29 13:29:59.626057 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-07-29 13:29:59.627441 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-07-29 13:29:59.954169 | instance | ERROR
2026-07-29 13:29:59.954707 | instance | {
2026-07-29 13:29:59.954805 | instance |   "delta": "0:00:00.006374",
2026-07-29 13:29:59.954857 | instance |   "end": "2026-07-29 13:29:59.627985",
2026-07-29 13:29:59.954904 | instance |   "msg": "non-zero return code",
2026-07-29 13:29:59.954946 | instance |   "rc": 1,
2026-07-29 13:29:59.954990 | instance |   "start": "2026-07-29 13:29:59.621611"
2026-07-29 13:29:59.955057 | instance | }
2026-07-29 13:29:59.955116 | instance | ERROR: Ignoring Errors
2026-07-29 13:29:59.960533 | 
2026-07-29 13:29:59.960616 | TASK [gather-selenium-data : Downloads logs to executor]
2026-07-29 13:30:00.502537 | instance | changed: cd+++++++++ selenium/
2026-07-29 13:30:00.511507 | 
2026-07-29 13:30:00.511580 | PLAY RECAP
2026-07-29 13:30:00.511629 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-07-29 13:30:00.511653 | 
2026-07-29 13:30:00.628872 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-29 13:30:00.632266 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-29 13:30:01.395457 | 
2026-07-29 13:30:01.395621 | PLAY [all]
2026-07-29 13:30:01.412651 | 
2026-07-29 13:30:01.412867 | TASK [fetch-output : Set log path for multiple nodes]
2026-07-29 13:30:01.459194 | instance | skipping: Conditional result was False
2026-07-29 13:30:01.467126 | 
2026-07-29 13:30:01.467360 | TASK [fetch-output : Set log path for single node]
2026-07-29 13:30:01.510220 | instance | ok
2026-07-29 13:30:01.517393 | 
2026-07-29 13:30:01.517556 | LOOP [fetch-output : Ensure local output dirs]
2026-07-29 13:30:01.926580 | instance -> localhost | ok: "/var/lib/zuul/builds/5d554b09ba0f41ddb45152ce71184934/work/logs"
2026-07-29 13:30:02.157850 | instance -> localhost | changed: "/var/lib/zuul/builds/5d554b09ba0f41ddb45152ce71184934/work/artifacts"
2026-07-29 13:30:02.413230 | instance -> localhost | changed: "/var/lib/zuul/builds/5d554b09ba0f41ddb45152ce71184934/work/docs"
2026-07-29 13:30:02.433364 | 
2026-07-29 13:30:02.433517 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-07-29 13:30:03.167988 | instance | changed: .d..t...... ./
2026-07-29 13:30:03.168262 | instance | changed: All items complete
2026-07-29 13:30:03.168291 | 
2026-07-29 13:30:03.633025 | instance | changed: .d..t...... ./
2026-07-29 13:30:04.165697 | instance | changed: .d..t...... ./
2026-07-29 13:30:04.179346 | 
2026-07-29 13:30:04.179536 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-07-29 13:30:04.657973 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.006073
2026-07-29 13:30:04.889766 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.005755
2026-07-29 13:30:04.897648 | 
2026-07-29 13:30:04.897727 | PLAY [all]
2026-07-29 13:30:04.903539 | 
2026-07-29 13:30:04.903614 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-07-29 13:30:05.334737 | instance | changed
2026-07-29 13:30:05.340523 | 
2026-07-29 13:30:05.340593 | PLAY RECAP
2026-07-29 13:30:05.340642 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-29 13:30:05.340666 | 
2026-07-29 13:30:05.460590 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-29 13:30:05.463532 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-07-29 13:30:06.185478 | 
2026-07-29 13:30:06.463970 | PLAY [localhost]
2026-07-29 13:30:06.483372 | 
2026-07-29 13:30:06.483523 | TASK [Generate Zuul manifest]
2026-07-29 13:30:06.502709 | localhost | ok
2026-07-29 13:30:06.529595 | 
2026-07-29 13:30:06.529730 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-07-29 13:30:06.921069 | localhost | changed
2026-07-29 13:30:06.939286 | 
2026-07-29 13:30:06.939464 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-07-29 13:30:06.972307 | localhost | ok
2026-07-29 13:30:06.984154 | 
2026-07-29 13:30:06.984294 | TASK [Upload logs]
2026-07-29 13:30:07.003242 | localhost | ok
2026-07-29 13:30:07.081145 | 
2026-07-29 13:30:07.081272 | TASK [Set zuul-log-path fact]
2026-07-29 13:30:07.100644 | localhost | ok
2026-07-29 13:30:07.116980 | 
2026-07-29 13:30:07.117104 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-29 13:30:07.146918 | localhost | ok
2026-07-29 13:30:07.156586 | 
2026-07-29 13:30:07.156683 | TASK [upload-logs : Create log directories]
2026-07-29 13:30:07.583733 | localhost | changed
2026-07-29 13:30:07.592471 | 
2026-07-29 13:30:07.592638 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-07-29 13:30:08.020291 | localhost -> localhost | ok: Runtime: 0:00:00.005976
2026-07-29 13:30:08.062973 | 
2026-07-29 13:30:08.063081 | TASK [upload-logs : Upload logs to log server]
2026-07-29 13:30:08.520634 | localhost | Output suppressed because no_log was given
2026-07-29 13:30:08.525736 | 
2026-07-29 13:30:08.525812 | LOOP [upload-logs : Compress console log and json output]
2026-07-29 13:30:08.567938 | localhost | skipping: Conditional result was False
2026-07-29 13:30:08.578316 | localhost | skipping: Conditional result was False
2026-07-29 13:30:08.586833 | 
2026-07-29 13:30:08.586992 | LOOP [upload-logs : Upload compressed console log and json output]
2026-07-29 13:30:08.627647 | localhost | skipping: Conditional result was False
2026-07-29 13:30:08.628211 | 
2026-07-29 13:30:08.632693 | localhost | skipping: Conditional result was False
2026-07-29 13:30:08.651457 | 
2026-07-29 13:30:08.651575 | LOOP [upload-logs : Upload console log and json output]