2026-07-26 15:41:35.319372 | Job console starting
2026-07-26 15:41:35.329851 | Updating git repos
2026-07-26 15:41:39.297414 | Cloning repos into workspace
2026-07-26 15:41:50.459067 | Restoring repo states
2026-07-26 15:42:00.141156 | Merging changes
2026-07-26 15:42:02.419940 | Checking out repos
2026-07-26 15:42:04.277565 | Preparing playbooks
2026-07-26 15:42:08.291275 | Running Ansible setup
2026-07-26 15:42:11.944088 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-26 15:42:12.567713 | 
2026-07-26 15:42:12.567915 | PLAY [localhost]
2026-07-26 15:42:12.577793 | 
2026-07-26 15:42:12.577962 | TASK [Gathering Facts]
2026-07-26 15:42:13.424858 | localhost | ok
2026-07-26 15:42:13.434556 | 
2026-07-26 15:42:13.434633 | TASK [Setup log path fact]
2026-07-26 15:42:13.453960 | localhost | ok
2026-07-26 15:42:13.466430 | 
2026-07-26 15:42:13.466496 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-26 15:42:13.499005 | localhost | ok
2026-07-26 15:42:13.506415 | 
2026-07-26 15:42:13.506492 | TASK [emit-job-header : Print job information]
2026-07-26 15:42:13.551648 | # Job Information
2026-07-26 15:42:13.551939 | Ansible Version: 2.16.18
2026-07-26 15:42:13.552017 | Job: atmosphere-molecule-csi-rbd-selective
2026-07-26 15:42:13.552069 | Pipeline: check
2026-07-26 15:42:13.552117 | Executor: 2d72f0692154
2026-07-26 15:42:13.552164 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4163
2026-07-26 15:42:13.552217 | Event ID: 0d497e60-8908-11f1-8bf4-7b5c6963f0a7
2026-07-26 15:42:13.556185 | 
2026-07-26 15:42:13.556261 | LOOP [emit-job-header : Print node information]
2026-07-26 15:42:13.652504 | localhost | ok:
2026-07-26 15:42:13.652668 | localhost | # Node Information
2026-07-26 15:42:13.652697 | localhost | Inventory Hostname: instance
2026-07-26 15:42:13.652718 | localhost | Hostname: np0000186974
2026-07-26 15:42:13.652739 | localhost | Username: zuul
2026-07-26 15:42:13.652762 | localhost | Distro: Ubuntu 22.04
2026-07-26 15:42:13.652783 | localhost | Provider: yul1
2026-07-26 15:42:13.652802 | localhost | Region: ca-ymq-1
2026-07-26 15:42:13.652826 | localhost | Label: ubuntu-jammy
2026-07-26 15:42:13.661119 | localhost | Product Name: OpenStack Nova
2026-07-26 15:42:13.661152 | localhost | Interface IP: 199.204.45.44
2026-07-26 15:42:13.671355 | 
2026-07-26 15:42:13.671526 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-07-26 15:42:14.084833 | localhost -> localhost | changed
2026-07-26 15:42:14.091853 | 
2026-07-26 15:42:14.091961 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-07-26 15:42:14.984506 | localhost -> localhost | changed
2026-07-26 15:42:14.994810 | 
2026-07-26 15:42:14.994885 | PLAY [all]
2026-07-26 15:42:15.004727 | 
2026-07-26 15:42:15.004794 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-07-26 15:42:15.233640 | instance -> localhost | ok
2026-07-26 15:42:15.242938 | 
2026-07-26 15:42:15.243054 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-07-26 15:42:15.276975 | instance | ok
2026-07-26 15:42:15.293082 | instance | included: /var/lib/zuul/builds/2c54d7e7e08244abb3e6defbc266abec/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-07-26 15:42:15.299955 | 
2026-07-26 15:42:15.300038 | TASK [add-build-sshkey : Create Temp SSH key]
2026-07-26 15:42:16.331157 | instance -> localhost | Generating public/private rsa key pair.
2026-07-26 15:42:16.331301 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/2c54d7e7e08244abb3e6defbc266abec/work/2c54d7e7e08244abb3e6defbc266abec_id_rsa
2026-07-26 15:42:16.331331 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/2c54d7e7e08244abb3e6defbc266abec/work/2c54d7e7e08244abb3e6defbc266abec_id_rsa.pub
2026-07-26 15:42:16.331353 | instance -> localhost | The key fingerprint is:
2026-07-26 15:42:16.331375 | instance -> localhost | SHA256:QXdmPgGODQ01+JH/SgztbPHynAiZ+D57or7hE3dMUdw zuul-build-sshkey
2026-07-26 15:42:16.331405 | instance -> localhost | The key's randomart image is:
2026-07-26 15:42:16.331427 | instance -> localhost | +---[RSA 3072]----+
2026-07-26 15:42:16.331451 | instance -> localhost | |        +==o=o.. |
2026-07-26 15:42:16.331473 | instance -> localhost | |       ..*+*... E|
2026-07-26 15:42:16.331494 | instance -> localhost | |        o.o+o.   |
2026-07-26 15:42:16.331514 | instance -> localhost | |         .o =.   |
2026-07-26 15:42:16.331534 | instance -> localhost | |        S. X +   |
2026-07-26 15:42:16.331555 | instance -> localhost | |        o = X o  |
2026-07-26 15:42:16.331575 | instance -> localhost | |        .+ = * . |
2026-07-26 15:42:16.331595 | instance -> localhost | |       ...+ + +  |
2026-07-26 15:42:16.331618 | instance -> localhost | |       .==o=     |
2026-07-26 15:42:16.331639 | instance -> localhost | +----[SHA256]-----+
2026-07-26 15:42:16.331685 | instance -> localhost | ok: Runtime: 0:00:00.602425
2026-07-26 15:42:16.336591 | 
2026-07-26 15:42:16.336651 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-07-26 15:42:16.366783 | instance | ok
2026-07-26 15:42:16.376420 | instance | included: /var/lib/zuul/builds/2c54d7e7e08244abb3e6defbc266abec/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-07-26 15:42:16.383771 | 
2026-07-26 15:42:16.383833 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-07-26 15:42:16.408892 | instance | skipping: Conditional result was False
2026-07-26 15:42:16.424095 | 
2026-07-26 15:42:16.424266 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-07-26 15:42:16.854035 | instance | changed
2026-07-26 15:42:16.861045 | 
2026-07-26 15:42:16.861115 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-07-26 15:42:17.048911 | instance | ok
2026-07-26 15:42:17.055694 | 
2026-07-26 15:42:17.055756 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-07-26 15:42:17.556742 | instance | changed
2026-07-26 15:42:17.563073 | 
2026-07-26 15:42:17.563168 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-07-26 15:42:18.013913 | instance | changed
2026-07-26 15:42:18.020069 | 
2026-07-26 15:42:18.020135 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-07-26 15:42:18.062747 | instance | skipping: Conditional result was False
2026-07-26 15:42:18.069399 | 
2026-07-26 15:42:18.069478 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-07-26 15:42:18.446436 | instance -> localhost | changed
2026-07-26 15:42:18.461524 | 
2026-07-26 15:42:18.461596 | TASK [add-build-sshkey : Add back temp key]
2026-07-26 15:42:18.756019 | instance -> localhost | Identity added: /var/lib/zuul/builds/2c54d7e7e08244abb3e6defbc266abec/work/2c54d7e7e08244abb3e6defbc266abec_id_rsa (zuul-build-sshkey)
2026-07-26 15:42:18.756252 | instance -> localhost | ok: Runtime: 0:00:00.012303
2026-07-26 15:42:18.761247 | 
2026-07-26 15:42:18.761310 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-07-26 15:42:19.070593 | instance | ok
2026-07-26 15:42:19.076156 | 
2026-07-26 15:42:19.076221 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-07-26 15:42:19.114849 | instance | skipping: Conditional result was False
2026-07-26 15:42:19.131380 | 
2026-07-26 15:42:19.131516 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-07-26 15:42:19.465950 | instance | ok
2026-07-26 15:42:19.505434 | 
2026-07-26 15:42:19.505566 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-07-26 15:42:22.627533 | instance | Output suppressed because no_log was given
2026-07-26 15:42:22.637736 | 
2026-07-26 15:42:22.637815 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-07-26 15:42:22.832449 | instance | ok: "logs"
2026-07-26 15:42:22.832732 | instance | ok: All items complete
2026-07-26 15:42:22.832762 | 
2026-07-26 15:42:22.982398 | instance | ok: "artifacts"
2026-07-26 15:42:23.141734 | instance | ok: "docs"
2026-07-26 15:42:23.159110 | 
2026-07-26 15:42:23.159215 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-07-26 15:42:23.352059 | instance | changed: "logs"
2026-07-26 15:42:23.507684 | instance | changed: "artifacts"
2026-07-26 15:42:23.690180 | instance | changed: "docs"
2026-07-26 15:42:23.705014 | 
2026-07-26 15:42:23.705166 | PLAY RECAP
2026-07-26 15:42:23.705232 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-07-26 15:42:23.705272 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-26 15:42:23.705304 | 
2026-07-26 15:42:23.911354 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-26 15:42:23.915915 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-26 15:42:24.522443 | 
2026-07-26 15:42:24.522575 | PLAY [all]
2026-07-26 15:42:24.533726 | 
2026-07-26 15:42:24.533808 | TASK [setup-uv : Extract archive]
2026-07-26 15:42:26.840391 | instance | changed
2026-07-26 15:42:26.847580 | 
2026-07-26 15:42:26.847657 | TASK [setup-uv : Print version]
2026-07-26 15:42:27.199907 | instance | uv 0.8.13
2026-07-26 15:42:27.387934 | instance | ok: Runtime: 0:00:00.011562
2026-07-26 15:42:27.398278 | 
2026-07-26 15:42:27.398389 | TASK [Install Ansible collection siblings]
2026-07-26 15:42:36.553679 | instance | changed:
2026-07-26 15:42:36.553898 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-07-26 15:42:36.553939 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-07-26 15:42:36.553969 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-07-26 15:42:36.553996 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-07-26 15:42:36.554023 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-07-26 15:42:36.554055 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-07-26 15:42:36.554084 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-07-26 15:42:36.554113 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-07-26 15:42:36.554140 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-07-26 15:42:36.554168 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-07-26 15:42:36.554194 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-07-26 15:42:36.554220 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-07-26 15:42:36.554246 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-07-26 15:42:36.554271 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-07-26 15:42:36.554297 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0
2026-07-26 15:42:36.554323 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-07-26 15:42:36.554348 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-07-26 15:42:36.554374 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-07-26 15:42:36.554400 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-07-26 15:42:36.554425 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-07-26 15:42:36.554450 | instance | Resolved vexxhost.ceph to v4.1.0 for >=3.2.0
2026-07-26 15:42:36.554474 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-07-26 15:42:36.554501 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-26 15:42:36.554538 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-07-26 15:42:36.554566 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0,>=6.0.0
2026-07-26 15:42:36.554591 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-26 15:42:36.554616 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-26 15:42:36.554641 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-26 15:42:36.554666 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-07-26 15:42:36.554692 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-07-26 15:42:36.554716 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-26 15:42:36.554742 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-26 15:42:36.554767 | instance | Checked out ansible.netcommon to 1.2.0
2026-07-26 15:42:36.554793 | 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-26 15:42:36.554824 | instance | Starting galaxy collection install process
2026-07-26 15:42:36.554850 | instance | Process install dependency map
2026-07-26 15:42:36.554876 | instance | Starting collection install process
2026-07-26 15:42:36.554901 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-07-26 15:42:36.554925 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-07-26 15:42:36.554950 | instance | ansible.netcommon:* was installed successfully
2026-07-26 15:42:36.554975 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-07-26 15:42:36.555000 | instance | Creating virtual environment at: .venv
2026-07-26 15:42:36.555025 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-26 15:42:36.555050 | instance | Downloading openstacksdk (1.7MiB)
2026-07-26 15:42:36.555074 | instance | Downloading cryptography (4.2MiB)
2026-07-26 15:42:36.555105 | instance | Downloading pydantic-core (2.0MiB)
2026-07-26 15:42:36.555132 | instance | Downloading kubernetes (1.9MiB)
2026-07-26 15:42:36.555157 | instance | Downloading ansible-core (2.1MiB)
2026-07-26 15:42:36.555182 | instance | Downloading netaddr (2.2MiB)
2026-07-26 15:42:36.555208 | instance | Downloading rjsonnet (1.2MiB)
2026-07-26 15:42:36.555234 | instance | Downloading pygments (1.2MiB)
2026-07-26 15:42:36.555259 | instance | Downloading setuptools (1.1MiB)
2026-07-26 15:42:36.555285 | instance |    Building pyperclip==1.9.0
2026-07-26 15:42:36.555312 | instance |  Downloading rjsonnet
2026-07-26 15:42:36.555338 | instance |  Downloading pydantic-core
2026-07-26 15:42:36.555364 | instance |  Downloading netaddr
2026-07-26 15:42:36.555389 | instance |  Downloading pygments
2026-07-26 15:42:36.555414 | instance |  Downloading cryptography
2026-07-26 15:42:36.555439 | instance |  Downloading setuptools
2026-07-26 15:42:36.555465 | instance |  Downloading kubernetes
2026-07-26 15:42:36.555490 | instance |  Downloading ansible-core
2026-07-26 15:42:36.555516 | instance |  Downloading openstacksdk
2026-07-26 15:42:36.555541 | instance |       Built pyperclip==1.9.0
2026-07-26 15:42:36.555567 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-26 15:42:36.555592 | instance | Installed 83 packages in 37ms
2026-07-26 15:42:36.555617 | instance | Checked out ansible.posix to 1.6.0
2026-07-26 15:42:36.555642 | 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-26 15:42:36.555668 | instance | Starting galaxy collection install process
2026-07-26 15:42:36.555693 | instance | Process install dependency map
2026-07-26 15:42:36.555724 | instance | Starting collection install process
2026-07-26 15:42:36.555750 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-07-26 15:42:36.555794 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-07-26 15:42:36.555822 | instance | ansible.posix:1.6.0 was installed successfully
2026-07-26 15:42:36.555847 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 15:42:36.555873 | instance | 2.17.12
2026-07-26 15:42:36.555898 | instance | Checked out ansible.utils to v6.0.3
2026-07-26 15:42:36.555924 | 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-26 15:42:36.555949 | instance | Starting galaxy collection install process
2026-07-26 15:42:36.555974 | instance | Process install dependency map
2026-07-26 15:42:36.555999 | instance | Starting collection install process
2026-07-26 15:42:36.556025 | instance | Installing 'ansible.utils:6.0.3' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-07-26 15:42:36.556051 | instance | Created collection for ansible.utils:6.0.3 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-07-26 15:42:36.556076 | instance | ansible.utils:6.0.3 was installed successfully
2026-07-26 15:42:36.556102 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 15:42:36.556127 | instance | 2.17.12
2026-07-26 15:42:36.556151 | instance | Checked out atmosphere.common to v0.7.0
2026-07-26 15:42:36.556177 | 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-26 15:42:36.556201 | instance | Starting galaxy collection install process
2026-07-26 15:42:36.556226 | instance | Process install dependency map
2026-07-26 15:42:36.556251 | instance | Starting collection install process
2026-07-26 15:42:36.556275 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-07-26 15:42:36.556300 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-07-26 15:42:36.556326 | instance | atmosphere.common:0.7.0 was installed successfully
2026-07-26 15:42:36.556351 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 15:42:36.556376 | instance | 2.17.12
2026-07-26 15:42:36.556401 | instance | Checked out community.crypto to 2.2.3
2026-07-26 15:42:36.556427 | 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-26 15:42:36.556453 | instance | Starting galaxy collection install process
2026-07-26 15:42:36.556477 | instance | Process install dependency map
2026-07-26 15:42:36.556502 | instance | Starting collection install process
2026-07-26 15:42:36.556526 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-07-26 15:42:36.556550 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-07-26 15:42:36.556577 | instance | community.crypto:2.2.3 was installed successfully
2026-07-26 15:42:36.556602 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 15:42:36.556627 | instance | 2.17.12
2026-07-26 15:42:36.556652 | instance | Checked out community.general to 7.3.0
2026-07-26 15:42:36.556677 | 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-26 15:42:36.556709 | instance | Starting galaxy collection install process
2026-07-26 15:42:36.556734 | instance | Process install dependency map
2026-07-26 15:42:36.556759 | instance | Starting collection install process
2026-07-26 15:42:36.556784 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-07-26 15:42:36.556809 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-07-26 15:42:36.556835 | instance | community.general:7.3.0 was installed successfully
2026-07-26 15:42:36.556860 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 15:42:36.556885 | instance | 2.17.12
2026-07-26 15:42:36.556910 | instance | Checked out community.mysql to 3.6.0
2026-07-26 15:42:36.556938 | 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-26 15:42:36.556988 | instance | Starting galaxy collection install process
2026-07-26 15:42:36.557017 | instance | Process install dependency map
2026-07-26 15:42:36.557043 | instance | Starting collection install process
2026-07-26 15:42:36.557068 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-07-26 15:42:36.557093 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-07-26 15:42:36.557119 | instance | community.mysql:3.6.0 was installed successfully
2026-07-26 15:42:36.557144 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 15:42:36.557169 | instance | 2.17.12
2026-07-26 15:42:36.557194 | instance | Checked out kubernetes.core to 2.4.0
2026-07-26 15:42:36.557220 | 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-26 15:42:36.557245 | instance | Starting galaxy collection install process
2026-07-26 15:42:36.557270 | instance | Process install dependency map
2026-07-26 15:42:36.557295 | instance | Starting collection install process
2026-07-26 15:42:36.557321 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-07-26 15:42:36.557346 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-07-26 15:42:36.557371 | instance | kubernetes.core:2.4.0 was installed successfully
2026-07-26 15:42:36.557396 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 15:42:36.557420 | instance | 2.17.12
2026-07-26 15:42:36.557445 | instance | Checked out openstack.cloud to 2.6.0
2026-07-26 15:42:36.557470 | 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-26 15:42:36.557496 | instance | Starting galaxy collection install process
2026-07-26 15:42:36.557521 | instance | Process install dependency map
2026-07-26 15:42:36.557546 | instance | Starting collection install process
2026-07-26 15:42:36.557571 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-07-26 15:42:36.557595 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-07-26 15:42:36.557620 | instance | openstack.cloud:2.6.0 was installed successfully
2026-07-26 15:42:36.557645 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 15:42:36.557669 | instance | 2.17.12
2026-07-26 15:42:36.557701 | instance | Checked out vexxhost.ceph to v4.1.0
2026-07-26 15:42:36.557728 | 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-26 15:42:36.557754 | instance | Starting galaxy collection install process
2026-07-26 15:42:36.557778 | instance | Process install dependency map
2026-07-26 15:42:36.557803 | instance | Starting collection install process
2026-07-26 15:42:36.557827 | instance | Installing 'vexxhost.ceph:4.1.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-07-26 15:42:36.557852 | instance | Created collection for vexxhost.ceph:4.1.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-07-26 15:42:36.557877 | instance | vexxhost.ceph:4.1.0 was installed successfully
2026-07-26 15:42:36.557902 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 15:42:36.557927 | instance | 2.17.12
2026-07-26 15:42:36.557951 | instance | Checked out vexxhost.containers to v1.6.8
2026-07-26 15:42:36.557976 | 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-26 15:42:36.558001 | instance | Starting galaxy collection install process
2026-07-26 15:42:36.558025 | instance | Process install dependency map
2026-07-26 15:42:36.558050 | instance | Starting collection install process
2026-07-26 15:42:36.558074 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-07-26 15:42:36.558099 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-07-26 15:42:36.558125 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-07-26 15:42:36.558150 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 15:42:36.558175 | instance | 2.17.12
2026-07-26 15:42:36.558200 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-07-26 15:42:36.558225 | 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-26 15:42:36.558250 | instance | Starting galaxy collection install process
2026-07-26 15:42:36.558274 | instance | Process install dependency map
2026-07-26 15:42:36.558299 | instance | Starting collection install process
2026-07-26 15:42:36.558323 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-07-26 15:42:36.558347 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-07-26 15:42:36.558373 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-07-26 15:42:36.558398 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 15:42:36.558422 | instance | 2.17.12
2026-07-26 15:42:36.564951 | 
2026-07-26 15:42:36.565018 | PLAY RECAP
2026-07-26 15:42:36.565066 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-26 15:42:36.565087 | 
2026-07-26 15:42:36.677965 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-26 15:42:36.680369 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-26 15:42:37.293364 | 
2026-07-26 15:42:37.293496 | PLAY [all]
2026-07-26 15:42:37.305305 | 
2026-07-26 15:42:37.305376 | TASK [Install "jq" for log collection]
2026-07-26 15:42:48.191246 | instance | changed
2026-07-26 15:42:48.193097 | 
2026-07-26 15:42:48.193157 | PLAY RECAP
2026-07-26 15:42:48.193205 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-26 15:42:48.193261 | 
2026-07-26 15:42:48.307004 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-26 15:42:48.311282 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-26 15:42:48.940614 | 
2026-07-26 15:42:48.940770 | PLAY [Prepare Molecule dependencies]
2026-07-26 15:42:48.952591 | 
2026-07-26 15:42:48.952666 | TASK [Install jq for log collection]
2026-07-26 15:42:51.588607 | instance | ok
2026-07-26 15:42:51.591704 | 
2026-07-26 15:42:51.591774 | PLAY [Install Go for the Atmosphere deployment binary]
2026-07-26 15:42:51.599690 | 
2026-07-26 15:42:51.599788 | TASK [ensure-go : Check installed go version]
2026-07-26 15:42:52.134389 | instance | ok: ERROR (ignored)
2026-07-26 15:42:52.134727 | instance | {
2026-07-26 15:42:52.134769 | instance |   "failed_when_result": false,
2026-07-26 15:42:52.134801 | instance |   "msg": "[Errno 2] No such file or directory: b'go'",
2026-07-26 15:42:52.134827 | instance |   "rc": 2
2026-07-26 15:42:52.134853 | instance | }
2026-07-26 15:42:52.145852 | 
2026-07-26 15:42:52.145945 | TASK [ensure-go : Skip if correct version of go is installed]
2026-07-26 15:42:52.202826 | instance | ok
2026-07-26 15:42:52.213231 | instance | included: /var/lib/zuul/builds/2c54d7e7e08244abb3e6defbc266abec/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-go/tasks/install-go.yaml
2026-07-26 15:42:52.217969 | 
2026-07-26 15:42:52.218035 | TASK [ensure-go : Create temp directory]
2026-07-26 15:42:52.544568 | instance | changed
2026-07-26 15:42:52.549892 | 
2026-07-26 15:42:52.549965 | TASK [ensure-go : Get archive checksum]
2026-07-26 15:42:53.126779 | instance | ok: OK (64 bytes)
2026-07-26 15:42:53.132316 | 
2026-07-26 15:42:53.132388 | TASK [ensure-go : Download go archive]
2026-07-26 15:42:54.451834 | instance | changed: OK (78559214 bytes)
2026-07-26 15:42:54.457474 | 
2026-07-26 15:42:54.457561 | TASK [ensure-go : Install go]
2026-07-26 15:43:00.508606 | instance | changed
2026-07-26 15:43:00.516226 | 
2026-07-26 15:43:00.516363 | PLAY [Prepare selective Molecule execution plan]
2026-07-26 15:43:00.523759 | 
2026-07-26 15:43:00.523872 | TASK [Build atmosphere binary]
2026-07-26 15:43:00.767906 | instance | go: downloading github.com/spf13/cobra v1.9.1
2026-07-26 15:43:00.776201 | instance | go: downloading golang.org/x/sync v0.18.0
2026-07-26 15:43:00.787650 | instance | go: downloading github.com/goccy/go-yaml v1.18.0
2026-07-26 15:43:01.030076 | instance | go: downloading github.com/spf13/pflag v1.0.7
2026-07-26 15:43:08.570703 | instance | ok: Runtime: 0:00:07.415987
2026-07-26 15:43:08.577205 | 
2026-07-26 15:43:08.577299 | TASK [Generate selective CI plan]
2026-07-26 15:43:09.150519 | instance | ok: Runtime: 0:00:00.031358
2026-07-26 15:43:09.157553 | 
2026-07-26 15:43:09.157657 | TASK [Explain selective CI plan]
2026-07-26 15:43:09.401106 | instance | CI plan mode: selective
2026-07-26 15:43:09.401354 | instance | Changed files:
2026-07-26 15:43:09.401369 | instance |   A	roles/heat/.ci-selective-test
2026-07-26 15:43:09.401379 | instance | Matches:
2026-07-26 15:43:09.401391 | instance |   roles/heat/.ci-selective-test: component:heat (targets) -> heat
2026-07-26 15:43:09.401400 | instance | Direct targets: heat
2026-07-26 15:43:09.401413 | instance | Deployment roots: glance-images, heat, neutron
2026-07-26 15:43:09.401426 | instance | Verification profiles: orchestration
2026-07-26 15:43:09.401439 | instance | Variant openvswitch (22 components): ceph, ceph-provisioners, cert-manager, cluster-issuer, coredns, csi, glance, glance-images, heat, ingress-nginx, keycloak, keystone, kubernetes, libvirt, memcached, neutron, nova, openvswitch, percona-xtradb-cluster, percona-xtradb-cluster-operator, placement, rabbitmq-cluster-operator
2026-07-26 15:43:09.401449 | instance | Molecule jobs:
2026-07-26 15:43:09.401457 | instance |   aio-openvswitch: run (22 components) - executes the openvswitch deployment variant
2026-07-26 15:43:09.401466 | instance |   aio-ovn: skip - plan has no ovn deployment variant
2026-07-26 15:43:09.401475 | instance |   csi-local-path-provisioner: skip - none of the job's verification profiles were requested
2026-07-26 15:43:09.401484 | instance |   csi-rbd: skip - none of the job's verification profiles were requested
2026-07-26 15:43:09.401497 | instance |   keycloak: skip - none of the job's verification profiles were requested
2026-07-26 15:43:09.696797 | instance | ok: Runtime: 0:00:00.033088
2026-07-26 15:43:09.704042 | 
2026-07-26 15:43:09.704133 | TASK [Print selective CI plan]
2026-07-26 15:43:09.739732 | instance | ok
2026-07-26 15:43:09.745873 | 
2026-07-26 15:43:09.745961 | TASK [Report conservative fallback]
2026-07-26 15:43:09.772079 | instance | skipping: Conditional result was False
2026-07-26 15:43:09.783407 | 
2026-07-26 15:43:09.783511 | TASK [Create selective CI plan log directory]
2026-07-26 15:43:10.056461 | instance | changed
2026-07-26 15:43:10.062269 | 
2026-07-26 15:43:10.062333 | TASK [Publish selective CI plan with job logs]
2026-07-26 15:43:10.411455 | instance | changed
2026-07-26 15:43:10.413500 | 
2026-07-26 15:43:10.413567 | PLAY RECAP
2026-07-26 15:43:10.413639 | instance | ok: 13 changed: 5 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-26 15:43:10.413673 | 
2026-07-26 15:43:10.561473 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-26 15:43:10.565932 | RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-26 15:43:11.172048 | 
2026-07-26 15:43:11.172192 | PLAY [Run selected Molecule deployment and verification]
2026-07-26 15:43:11.183667 | 
2026-07-26 15:43:11.183744 | TASK [Check for selective CI plan]
2026-07-26 15:43:11.521380 | instance | ok
2026-07-26 15:43:11.529594 | 
2026-07-26 15:43:11.529704 | TASK [Validate selective CI plan]
2026-07-26 15:43:12.068388 | instance | ok: Runtime: 0:00:00.036325
2026-07-26 15:43:12.075651 | 
2026-07-26 15:43:12.075763 | TASK [Resolve selective CI plan availability]
2026-07-26 15:43:12.129852 | instance | ok
2026-07-26 15:43:12.136560 | 
2026-07-26 15:43:12.136661 | TASK [Read selective CI plan]
2026-07-26 15:43:12.475681 | instance | ok
2026-07-26 15:43:12.481868 | 
2026-07-26 15:43:12.481958 | TASK [Load selective CI plan or full fallback]
2026-07-26 15:43:12.538243 | instance | ok
2026-07-26 15:43:12.545652 | 
2026-07-26 15:43:12.545739 | TASK [Find this job in the selective CI plan]
2026-07-26 15:43:12.600270 | instance | ok
2026-07-26 15:43:12.604568 | 
2026-07-26 15:43:12.604629 | TASK [Resolve this job or use full fallback]
2026-07-26 15:43:12.656819 | instance | ok
2026-07-26 15:43:12.662940 | 
2026-07-26 15:43:12.663044 | TASK [Resolve deployment and verification inputs]
2026-07-26 15:43:12.729766 | instance | ok
2026-07-26 15:43:12.734039 | 
2026-07-26 15:43:12.734099 | TASK [Resolve deployment tags]
2026-07-26 15:43:12.787995 | instance | ok
2026-07-26 15:43:12.794190 | 
2026-07-26 15:43:12.794270 | TASK [Report Molecule job decision]
2026-07-26 15:43:12.829380 | instance | ok: {'job': 'csi-rbd', 'mode': 'selective', 'run': False, 'reason': "none of the job's verification profiles were requested", 'network_backend': 'openvswitch', 'csi_driver': 'rbd', 'components': [], 'verification_profiles': ['orchestration'], 'extra_vars': {}}
2026-07-26 15:43:12.836194 | 
2026-07-26 15:43:12.836279 | TASK [Copy inventory file for Zuul]
2026-07-26 15:43:12.859722 | instance | skipping: Conditional result was False
2026-07-26 15:43:12.870465 | 
2026-07-26 15:43:12.870549 | TASK [Switch ansible_host to private IP]
2026-07-26 15:43:12.894879 | instance | skipping: Conditional result was False
2026-07-26 15:43:12.902944 | 
2026-07-26 15:43:12.903027 | TASK [Prepare Molecule scenario]
2026-07-26 15:43:13.433384 | instance | skipping: Conditional result was False
2026-07-26 15:43:13.474382 | 
2026-07-26 15:43:13.474526 | TASK [Prepare host for Ceph-backed storage]
2026-07-26 15:43:14.000098 | instance | skipping: Conditional result was False
2026-07-26 15:43:14.005680 | 
2026-07-26 15:43:14.005743 | TASK [Build deployment command]
2026-07-26 15:43:14.020061 | instance | skipping: Conditional result was False
2026-07-26 15:43:14.025553 | 
2026-07-26 15:43:14.025617 | TASK [Run initial deployment with progress heartbeat]
2026-07-26 15:43:14.049479 | instance | skipping: Conditional result was False
2026-07-26 15:43:14.054663 | 
2026-07-26 15:43:14.054724 | TASK [Run idempotence deployment with progress heartbeat]
2026-07-26 15:43:14.079683 | instance | skipping: Conditional result was False
2026-07-26 15:43:14.084686 | 
2026-07-26 15:43:14.084748 | TASK [Check for br-ex interface]
2026-07-26 15:43:14.613457 | instance | skipping: Conditional result was False
2026-07-26 15:43:14.624033 | 
2026-07-26 15:43:14.624108 | TASK [Add IP address to br-ex]
2026-07-26 15:43:15.150965 | instance | skipping: Conditional result was False
2026-07-26 15:43:15.161195 | 
2026-07-26 15:43:15.161259 | TASK [Set br-ex interface up]
2026-07-26 15:43:15.687911 | instance | skipping: Conditional result was False
2026-07-26 15:43:15.696927 | 
2026-07-26 15:43:15.697036 | TASK [Resolve Molecule verification command]
2026-07-26 15:43:15.722135 | instance | skipping: Conditional result was False
2026-07-26 15:43:15.731623 | 
2026-07-26 15:43:15.731710 | TASK [Run Molecule verification with progress heartbeat]
2026-07-26 15:43:15.746218 | instance | skipping: Conditional result was False
2026-07-26 15:43:15.747978 | 
2026-07-26 15:43:15.748028 | PLAY RECAP
2026-07-26 15:43:15.748081 | instance | ok: 10 changed: 0 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 0
2026-07-26 15:43:15.748103 | 
2026-07-26 15:43:15.873395 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-26 15:43:15.878450 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-26 15:43:16.454422 | 
2026-07-26 15:43:16.454531 | PLAY [all]
2026-07-26 15:43:16.467472 | 
2026-07-26 15:43:16.467543 | TASK [gather-host-logs : creating directory for system status]
2026-07-26 15:43:16.804099 | instance | ok
2026-07-26 15:43:16.809999 | 
2026-07-26 15:43:16.810096 | TASK [gather-host-logs : Get logs for each host]
2026-07-26 15:43:17.170211 | instance | + systemd-cgls --full --all --no-pager
2026-07-26 15:43:17.181889 | instance | + ip addr
2026-07-26 15:43:17.183846 | instance | + ip route
2026-07-26 15:43:17.185235 | instance | + lsblk
2026-07-26 15:43:17.188002 | instance | + mount
2026-07-26 15:43:17.189641 | instance | + docker images
2026-07-26 15:43:17.190112 | instance | /bin/bash: line 7: docker: command not found
2026-07-26 15:43:17.190307 | instance | + brctl show
2026-07-26 15:43:17.190693 | instance | /bin/bash: line 8: brctl: command not found
2026-07-26 15:43:17.190927 | instance | + ps aux --sort=-%mem
2026-07-26 15:43:17.200235 | instance | + dpkg -l
2026-07-26 15:43:17.206193 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-07-26 15:43:17.206571 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-07-26 15:43:17.206617 | instance | /bin/bash: line 11: docker: command not found
2026-07-26 15:43:17.206879 | instance | + '[' '!' -z '' ']'
2026-07-26 15:43:17.346290 | instance | ok: Runtime: 0:00:00.042847
2026-07-26 15:43:17.353760 | 
2026-07-26 15:43:17.353833 | TASK [gather-host-logs : Downloads logs to executor]
2026-07-26 15:43:17.984584 | instance | ERROR
2026-07-26 15:43:17.984855 | instance | {
2026-07-26 15:43:17.984899 | instance |   "msg": "Warning: Permanently added '199.204.45.44' (ED25519) to the list of known hosts.\r\nrsync: [generator] chown \"/var/lib/zuul/builds/2c54d7e7e08244abb3e6defbc266abec/work/logs/instance/system\" failed: Invalid argument (22)\nrsync: [receiver] chown \"/var/lib/zuul/builds/2c54d7e7e08244abb3e6defbc266abec/work/logs/instance/system/.ci-plan.json.Ltf3lW\" failed: Invalid argument (22)\nrsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1865) [generator=3.2.7]\n",
2026-07-26 15:43:17.984975 | instance |   "rc": 23
2026-07-26 15:43:17.985009 | instance | }
2026-07-26 15:43:17.985049 | instance | ERROR: Ignoring Errors
2026-07-26 15:43:17.996557 | 
2026-07-26 15:43:17.996623 | LOOP [helm-release-status : creating directory for helm release status]
2026-07-26 15:43:18.197611 | instance | changed: "values"
2026-07-26 15:43:18.370648 | instance | changed: "releases"
2026-07-26 15:43:18.380840 | 
2026-07-26 15:43:18.381079 | TASK [helm-release-status : Gather get release status for helm charts]
2026-07-26 15:43:18.603679 | instance | /bin/bash: line 3: kubectl: command not found
2026-07-26 15:43:18.919280 | instance | ok: Runtime: 0:00:00.006180
2026-07-26 15:43:18.926031 | 
2026-07-26 15:43:18.926113 | TASK [helm-release-status : Downloads logs to executor]
2026-07-26 15:43:19.460590 | instance | changed:
2026-07-26 15:43:19.460842 | instance | cd+++++++++ helm/
2026-07-26 15:43:19.460890 | instance | cd+++++++++ helm/releases/
2026-07-26 15:43:19.460942 | instance | cd+++++++++ helm/values/
2026-07-26 15:43:19.479696 | 
2026-07-26 15:43:19.479901 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-07-26 15:43:19.700394 | instance | changed
2026-07-26 15:43:19.721748 | 
2026-07-26 15:43:19.721918 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-07-26 15:43:20.042900 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args valuewarning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-26 15:43:20.043163 | instance |
2026-07-26 15:43:20.049032 | instance | environment: line 1: kubectl: command not found
2026-07-26 15:43:20.050619 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-26 15:43:20.051025 | instance | environment: line 1: kubectl: command not found
2026-07-26 15:43:20.052330 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-26 15:43:20.054418 | instance | environment: line 1: kubectl: command not found
2026-07-26 15:43:20.055484 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-26 15:43:20.056968 | instance | environment: line 1: kubectl: command not found
2026-07-26 15:43:20.058827 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-26 15:43:20.059118 | instance | environment: line 1: kubectl: command not found
2026-07-26 15:43:20.060580 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-26 15:43:20.336379 | instance | ok: Runtime: 0:00:00.027851
2026-07-26 15:43:20.343149 | 
2026-07-26 15:43:20.343241 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-07-26 15:43:20.562184 | instance | changed
2026-07-26 15:43:20.571670 | 
2026-07-26 15:43:20.571781 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-07-26 15:43:20.799474 | instance | environment: line 5: kubectl: command not found
2026-07-26 15:43:20.800572 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-26 15:43:20.800966 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-26 15:43:20.801184 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-26 15:43:21.110744 | instance | ok: Runtime: 0:00:00.009600
2026-07-26 15:43:21.116518 | 
2026-07-26 15:43:21.116598 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-07-26 15:43:21.599997 | instance | changed:
2026-07-26 15:43:21.600199 | instance | cd+++++++++ objects/
2026-07-26 15:43:21.600228 | instance | cd+++++++++ objects/cluster/
2026-07-26 15:43:21.600251 | instance | cd+++++++++ objects/namespaced/
2026-07-26 15:43:21.610495 | 
2026-07-26 15:43:21.610566 | TASK [gather-pod-logs : creating directory for pod logs]
2026-07-26 15:43:21.818288 | instance | changed
2026-07-26 15:43:21.826851 | 
2026-07-26 15:43:21.826943 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-07-26 15:43:22.038278 | instance | changed
2026-07-26 15:43:22.048530 | 
2026-07-26 15:43:22.048642 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-07-26 15:43:22.277605 | instance | environment: line 3: kubectl: command not found
2026-07-26 15:43:22.587221 | instance | ok: Runtime: 0:00:00.010468
2026-07-26 15:43:22.594894 | 
2026-07-26 15:43:22.594983 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-07-26 15:43:23.074659 | instance | changed:
2026-07-26 15:43:23.074872 | instance | cd+++++++++ pod-logs/
2026-07-26 15:43:23.074913 | instance | cd+++++++++ pod-logs/failed-pods/
2026-07-26 15:43:23.087352 | 
2026-07-26 15:43:23.087471 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-07-26 15:43:23.303132 | instance | changed
2026-07-26 15:43:23.309316 | 
2026-07-26 15:43:23.309379 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-07-26 15:43:23.524921 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-26 15:43:23.845404 | instance | ok: Runtime: 0:00:00.040259
2026-07-26 15:43:23.852116 | 
2026-07-26 15:43:23.852203 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-07-26 15:43:24.079098 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-26 15:43:24.111914 | instance | ceph-mgr endpoints:
2026-07-26 15:43:24.394651 | instance | ok: Runtime: 0:00:00.040903
2026-07-26 15:43:24.401538 | 
2026-07-26 15:43:24.401608 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-07-26 15:43:24.622967 | instance | /bin/bash: line 4: kubectl: command not found
2026-07-26 15:43:24.938255 | instance | ok: Runtime: 0:00:00.036655
2026-07-26 15:43:24.945176 | 
2026-07-26 15:43:24.945262 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-07-26 15:43:25.457641 | instance | changed: cd+++++++++ prometheus/
2026-07-26 15:43:25.469584 | 
2026-07-26 15:43:25.469662 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-07-26 15:43:25.675564 | instance | changed
2026-07-26 15:43:25.680601 | 
2026-07-26 15:43:25.680662 | TASK [gather-selenium-data : Get selenium data]
2026-07-26 15:43:25.935461 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-07-26 15:43:25.936206 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-07-26 15:43:26.253233 | instance | ERROR
2026-07-26 15:43:26.253545 | instance | {
2026-07-26 15:43:26.253596 | instance |   "delta": "0:00:00.004249",
2026-07-26 15:43:26.253627 | instance |   "end": "2026-07-26 15:43:25.936462",
2026-07-26 15:43:26.253654 | instance |   "msg": "non-zero return code",
2026-07-26 15:43:26.253681 | instance |   "rc": 1,
2026-07-26 15:43:26.253712 | instance |   "start": "2026-07-26 15:43:25.932213"
2026-07-26 15:43:26.253738 | instance | }
2026-07-26 15:43:26.253779 | instance | ERROR: Ignoring Errors
2026-07-26 15:43:26.260968 | 
2026-07-26 15:43:26.261059 | TASK [gather-selenium-data : Downloads logs to executor]
2026-07-26 15:43:27.072498 | instance | changed: cd+++++++++ selenium/
2026-07-26 15:43:27.079549 | 
2026-07-26 15:43:27.079605 | PLAY RECAP
2026-07-26 15:43:27.079657 | instance | ok: 23 changed: 21 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-07-26 15:43:27.079679 | 
2026-07-26 15:43:27.263425 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-26 15:43:27.268289 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-26 15:43:27.845262 | 
2026-07-26 15:43:27.845384 | PLAY [all]
2026-07-26 15:43:27.856545 | 
2026-07-26 15:43:27.856624 | TASK [fetch-output : Set log path for multiple nodes]
2026-07-26 15:43:27.891494 | instance | skipping: Conditional result was False
2026-07-26 15:43:27.900522 | 
2026-07-26 15:43:27.900647 | TASK [fetch-output : Set log path for single node]
2026-07-26 15:43:27.942466 | instance | ok
2026-07-26 15:43:27.948507 | 
2026-07-26 15:43:27.948595 | LOOP [fetch-output : Ensure local output dirs]
2026-07-26 15:43:28.509717 | instance -> localhost | ok: "/var/lib/zuul/builds/2c54d7e7e08244abb3e6defbc266abec/work/logs"
2026-07-26 15:43:28.721583 | instance -> localhost | changed: "/var/lib/zuul/builds/2c54d7e7e08244abb3e6defbc266abec/work/artifacts"
2026-07-26 15:43:28.954318 | instance -> localhost | changed: "/var/lib/zuul/builds/2c54d7e7e08244abb3e6defbc266abec/work/docs"
2026-07-26 15:43:28.970489 | 
2026-07-26 15:43:28.970620 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-07-26 15:43:29.757463 | instance | changed: .d..t...... ./
2026-07-26 15:43:29.757843 | instance | changed: All items complete
2026-07-26 15:43:29.757961 | 
2026-07-26 15:43:30.222156 | instance | changed: .d..t...... ./
2026-07-26 15:43:30.695640 | instance | changed: .d..t...... ./
2026-07-26 15:43:30.719252 | 
2026-07-26 15:43:30.719405 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-07-26 15:43:31.123037 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.009116
2026-07-26 15:43:31.336249 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.005999
2026-07-26 15:43:31.350879 | 
2026-07-26 15:43:31.350991 | PLAY [all]
2026-07-26 15:43:31.356682 | 
2026-07-26 15:43:31.356749 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-07-26 15:43:31.780100 | instance | changed
2026-07-26 15:43:31.787151 | 
2026-07-26 15:43:31.787203 | PLAY RECAP
2026-07-26 15:43:31.787249 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-26 15:43:31.787271 | 
2026-07-26 15:43:31.936636 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-26 15:43:31.941282 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-07-26 15:43:32.520605 | 
2026-07-26 15:43:32.520739 | PLAY [localhost]
2026-07-26 15:43:32.531001 | 
2026-07-26 15:43:32.531072 | TASK [Generate Zuul manifest]
2026-07-26 15:43:32.554806 | localhost | ok
2026-07-26 15:43:32.572069 | 
2026-07-26 15:43:32.572153 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-07-26 15:43:32.979182 | localhost | changed
2026-07-26 15:43:32.989884 | 
2026-07-26 15:43:32.989962 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-07-26 15:43:33.164803 | localhost | ok
2026-07-26 15:43:33.175798 | 
2026-07-26 15:43:33.175886 | TASK [Upload logs]
2026-07-26 15:43:33.196585 | localhost | ok
2026-07-26 15:43:33.255151 | 
2026-07-26 15:43:33.255280 | TASK [Set zuul-log-path fact]
2026-07-26 15:43:33.277091 | localhost | ok
2026-07-26 15:43:33.290808 | 
2026-07-26 15:43:33.290879 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-26 15:43:33.320887 | localhost | ok
2026-07-26 15:43:33.330010 | 
2026-07-26 15:43:33.330099 | TASK [upload-logs : Create log directories]
2026-07-26 15:43:33.757614 | localhost | changed
2026-07-26 15:43:33.764763 | 
2026-07-26 15:43:33.764939 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-07-26 15:43:34.155764 | localhost -> localhost | ok: Runtime: 0:00:00.006006
2026-07-26 15:43:34.203024 | 
2026-07-26 15:43:34.203154 | TASK [upload-logs : Upload logs to log server]
2026-07-26 15:43:34.629429 | localhost | Output suppressed because no_log was given
2026-07-26 15:43:34.633126 | 
2026-07-26 15:43:34.633193 | LOOP [upload-logs : Compress console log and json output]
2026-07-26 15:43:34.674695 | localhost | skipping: Conditional result was False
2026-07-26 15:43:34.681645 | localhost | skipping: Conditional result was False
2026-07-26 15:43:34.694211 | 
2026-07-26 15:43:34.694336 | LOOP [upload-logs : Upload compressed console log and json output]
2026-07-26 15:43:34.734378 | localhost | skipping: Conditional result was False
2026-07-26 15:43:34.734734 | 
2026-07-26 15:43:34.738631 | localhost | skipping: Conditional result was False
2026-07-26 15:43:34.748347 | 
2026-07-26 15:43:34.748501 | LOOP [upload-logs : Upload console log and json output]