2026-07-25 22:15:11.223973 | Job console starting
2026-07-25 22:15:11.236204 | Updating git repos
2026-07-25 22:15:12.886563 | Cloning repos into workspace
2026-07-25 22:15:24.241912 | Restoring repo states
2026-07-25 22:15:35.147826 | Merging changes
2026-07-25 22:15:36.855662 | Checking out repos
2026-07-25 22:15:38.500874 | Preparing playbooks
2026-07-25 22:15:41.589551 | Running Ansible setup
2026-07-25 22:15:45.018836 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-25 22:15:45.612049 | 
2026-07-25 22:15:45.612567 | PLAY [localhost]
2026-07-25 22:15:45.620053 | 
2026-07-25 22:15:45.620122 | TASK [Gathering Facts]
2026-07-25 22:15:46.479846 | localhost | ok
2026-07-25 22:15:46.491118 | 
2026-07-25 22:15:46.491203 | TASK [Setup log path fact]
2026-07-25 22:15:46.510609 | localhost | ok
2026-07-25 22:15:46.524159 | 
2026-07-25 22:15:46.524231 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-25 22:15:46.556221 | localhost | ok
2026-07-25 22:15:46.564471 | 
2026-07-25 22:15:46.564566 | TASK [emit-job-header : Print job information]
2026-07-25 22:15:46.609549 | # Job Information
2026-07-25 22:15:46.609763 | Ansible Version: 2.16.18
2026-07-25 22:15:46.609810 | Job: atmosphere-molecule-csi-local-path-provisioner-selective
2026-07-25 22:15:46.609842 | Pipeline: check
2026-07-25 22:15:46.609876 | Executor: 2d72f0692154
2026-07-25 22:15:46.609904 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4163
2026-07-25 22:15:46.609935 | Event ID: f8cde3f0-8875-11f1-9f44-271c078549d4
2026-07-25 22:15:46.614983 | 
2026-07-25 22:15:46.615057 | LOOP [emit-job-header : Print node information]
2026-07-25 22:15:46.710333 | localhost | ok:
2026-07-25 22:15:46.710593 | localhost | # Node Information
2026-07-25 22:15:46.710634 | localhost | Inventory Hostname: instance
2026-07-25 22:15:46.710666 | localhost | Hostname: np0000186902
2026-07-25 22:15:46.710697 | localhost | Username: zuul
2026-07-25 22:15:46.710730 | localhost | Distro: Ubuntu 22.04
2026-07-25 22:15:46.710759 | localhost | Provider: yul1
2026-07-25 22:15:46.710787 | localhost | Region: ca-ymq-1
2026-07-25 22:15:46.710816 | localhost | Label: ubuntu-jammy
2026-07-25 22:15:46.710843 | localhost | Product Name: OpenStack Nova
2026-07-25 22:15:46.710870 | localhost | Interface IP: 199.204.45.116
2026-07-25 22:15:46.726350 | 
2026-07-25 22:15:46.726504 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-07-25 22:15:47.126555 | localhost -> localhost | changed
2026-07-25 22:15:47.133259 | 
2026-07-25 22:15:47.133366 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-07-25 22:15:47.939500 | localhost -> localhost | changed
2026-07-25 22:15:47.947306 | 
2026-07-25 22:15:47.947456 | PLAY [all]
2026-07-25 22:15:47.954718 | 
2026-07-25 22:15:47.954779 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-07-25 22:15:48.203089 | instance -> localhost | ok
2026-07-25 22:15:48.212926 | 
2026-07-25 22:15:48.213017 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-07-25 22:15:48.247771 | instance | ok
2026-07-25 22:15:48.260604 | instance | included: /var/lib/zuul/builds/b8f4bc18a21248609f66b6081bc34bf7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-07-25 22:15:48.266808 | 
2026-07-25 22:15:48.266867 | TASK [add-build-sshkey : Create Temp SSH key]
2026-07-25 22:15:48.938248 | instance -> localhost | Generating public/private rsa key pair.
2026-07-25 22:15:48.938447 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/b8f4bc18a21248609f66b6081bc34bf7/work/b8f4bc18a21248609f66b6081bc34bf7_id_rsa
2026-07-25 22:15:48.938487 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/b8f4bc18a21248609f66b6081bc34bf7/work/b8f4bc18a21248609f66b6081bc34bf7_id_rsa.pub
2026-07-25 22:15:48.938520 | instance -> localhost | The key fingerprint is:
2026-07-25 22:15:48.938550 | instance -> localhost | SHA256:FYVZNv4MSbudOmrx9lxQkobpn4DFOkiQ0lnZd4Rr9DM zuul-build-sshkey
2026-07-25 22:15:48.938595 | instance -> localhost | The key's randomart image is:
2026-07-25 22:15:48.938625 | instance -> localhost | +---[RSA 3072]----+
2026-07-25 22:15:48.938662 | instance -> localhost | |     ..+.o.=*o.  |
2026-07-25 22:15:48.938693 | instance -> localhost | |    . +.. +*+*.. |
2026-07-25 22:15:48.938722 | instance -> localhost | |     .  . .oX++ .|
2026-07-25 22:15:48.938751 | instance -> localhost | |       . o =oBE+ |
2026-07-25 22:15:48.938778 | instance -> localhost | |        S +.+ *o |
2026-07-25 22:15:48.938806 | instance -> localhost | |          .. + o |
2026-07-25 22:15:48.938833 | instance -> localhost | |           oo o .|
2026-07-25 22:15:48.938861 | instance -> localhost | |          ..oo . |
2026-07-25 22:15:48.938893 | instance -> localhost | |         ... .o  |
2026-07-25 22:15:48.938922 | instance -> localhost | +----[SHA256]-----+
2026-07-25 22:15:48.938980 | instance -> localhost | ok: Runtime: 0:00:00.243321
2026-07-25 22:15:48.945298 | 
2026-07-25 22:15:48.945366 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-07-25 22:15:48.980189 | instance | ok
2026-07-25 22:15:48.990592 | instance | included: /var/lib/zuul/builds/b8f4bc18a21248609f66b6081bc34bf7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-07-25 22:15:48.997892 | 
2026-07-25 22:15:48.997953 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-07-25 22:15:49.024054 | instance | skipping: Conditional result was False
2026-07-25 22:15:49.034976 | 
2026-07-25 22:15:49.035066 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-07-25 22:15:49.485357 | instance | changed
2026-07-25 22:15:49.490344 | 
2026-07-25 22:15:49.490406 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-07-25 22:15:49.675016 | instance | ok
2026-07-25 22:15:49.681496 | 
2026-07-25 22:15:49.681560 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-07-25 22:15:50.134413 | instance | changed
2026-07-25 22:15:50.140341 | 
2026-07-25 22:15:50.140424 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-07-25 22:15:50.599610 | instance | changed
2026-07-25 22:15:50.605252 | 
2026-07-25 22:15:50.605315 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-07-25 22:15:50.630820 | instance | skipping: Conditional result was False
2026-07-25 22:15:50.637887 | 
2026-07-25 22:15:50.637956 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-07-25 22:15:51.002015 | instance -> localhost | changed
2026-07-25 22:15:51.018512 | 
2026-07-25 22:15:51.018661 | TASK [add-build-sshkey : Add back temp key]
2026-07-25 22:15:51.289020 | instance -> localhost | Identity added: /var/lib/zuul/builds/b8f4bc18a21248609f66b6081bc34bf7/work/b8f4bc18a21248609f66b6081bc34bf7_id_rsa (zuul-build-sshkey)
2026-07-25 22:15:51.289288 | instance -> localhost | ok: Runtime: 0:00:00.012870
2026-07-25 22:15:51.294890 | 
2026-07-25 22:15:51.295039 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-07-25 22:15:51.597882 | instance | ok
2026-07-25 22:15:51.602967 | 
2026-07-25 22:15:51.603031 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-07-25 22:15:51.629101 | instance | skipping: Conditional result was False
2026-07-25 22:15:51.643514 | 
2026-07-25 22:15:51.643583 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-07-25 22:15:52.026424 | instance | ok
2026-07-25 22:15:52.032533 | 
2026-07-25 22:15:52.032604 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-07-25 22:15:55.094686 | instance | Output suppressed because no_log was given
2026-07-25 22:15:55.106353 | 
2026-07-25 22:15:55.106421 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-07-25 22:15:55.292068 | instance | ok: "logs"
2026-07-25 22:15:55.292408 | instance | ok: All items complete
2026-07-25 22:15:55.292438 | 
2026-07-25 22:15:55.438493 | instance | ok: "artifacts"
2026-07-25 22:15:55.598561 | instance | ok: "docs"
2026-07-25 22:15:55.617210 | 
2026-07-25 22:15:55.617397 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-07-25 22:15:55.805745 | instance | changed: "logs"
2026-07-25 22:15:55.963688 | instance | changed: "artifacts"
2026-07-25 22:15:56.115994 | instance | changed: "docs"
2026-07-25 22:15:56.157429 | 
2026-07-25 22:15:56.157564 | PLAY RECAP
2026-07-25 22:15:56.157635 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-07-25 22:15:56.157678 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-25 22:15:56.157710 | 
2026-07-25 22:15:56.339009 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-25 22:15:56.341822 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-25 22:15:56.917989 | 
2026-07-25 22:15:56.918104 | PLAY [all]
2026-07-25 22:15:56.928687 | 
2026-07-25 22:15:56.928765 | TASK [setup-uv : Extract archive]
2026-07-25 22:15:59.192801 | instance | changed
2026-07-25 22:15:59.201371 | 
2026-07-25 22:15:59.201492 | TASK [setup-uv : Print version]
2026-07-25 22:15:59.531263 | instance | uv 0.8.13
2026-07-25 22:15:59.738727 | instance | ok: Runtime: 0:00:00.011120
2026-07-25 22:15:59.748824 | 
2026-07-25 22:15:59.748889 | TASK [Install Ansible collection siblings]
2026-07-25 22:16:08.606442 | instance | changed:
2026-07-25 22:16:08.607156 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-07-25 22:16:08.607275 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-07-25 22:16:08.607325 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-07-25 22:16:08.607369 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-07-25 22:16:08.607411 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-07-25 22:16:08.607464 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-07-25 22:16:08.607508 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-07-25 22:16:08.607556 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-07-25 22:16:08.607586 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-07-25 22:16:08.607608 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-07-25 22:16:08.607629 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-07-25 22:16:08.607648 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-07-25 22:16:08.607666 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-07-25 22:16:08.607684 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-07-25 22:16:08.607702 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0
2026-07-25 22:16:08.607721 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-07-25 22:16:08.607739 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-07-25 22:16:08.607757 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-07-25 22:16:08.607776 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-07-25 22:16:08.607793 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-07-25 22:16:08.607811 | instance | Resolved vexxhost.ceph to v4.1.0 for >=3.2.0
2026-07-25 22:16:08.607829 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-07-25 22:16:08.607848 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-25 22:16:08.607878 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-07-25 22:16:08.607898 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0,>=6.0.0
2026-07-25 22:16:08.607916 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-25 22:16:08.607933 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-25 22:16:08.607951 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-25 22:16:08.607969 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-07-25 22:16:08.607987 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-07-25 22:16:08.608005 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-25 22:16:08.608023 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-25 22:16:08.608041 | instance | Checked out ansible.netcommon to 1.2.0
2026-07-25 22:16:08.608059 | 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-25 22:16:08.608082 | instance | Starting galaxy collection install process
2026-07-25 22:16:08.608100 | instance | Process install dependency map
2026-07-25 22:16:08.608118 | instance | Starting collection install process
2026-07-25 22:16:08.608136 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-07-25 22:16:08.608153 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-07-25 22:16:08.608170 | instance | ansible.netcommon:* was installed successfully
2026-07-25 22:16:08.608188 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-07-25 22:16:08.608205 | instance | Creating virtual environment at: .venv
2026-07-25 22:16:08.608223 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-25 22:16:08.608240 | instance | Downloading openstacksdk (1.7MiB)
2026-07-25 22:16:08.608257 | instance | Downloading cryptography (4.2MiB)
2026-07-25 22:16:08.608277 | instance | Downloading netaddr (2.2MiB)
2026-07-25 22:16:08.608295 | instance | Downloading kubernetes (1.9MiB)
2026-07-25 22:16:08.608312 | instance | Downloading ansible-core (2.1MiB)
2026-07-25 22:16:08.608330 | instance | Downloading rjsonnet (1.2MiB)
2026-07-25 22:16:08.608348 | instance | Downloading pydantic-core (2.0MiB)
2026-07-25 22:16:08.608365 | instance | Downloading setuptools (1.1MiB)
2026-07-25 22:16:08.608383 | instance | Downloading pygments (1.2MiB)
2026-07-25 22:16:08.608400 | instance |    Building pyperclip==1.9.0
2026-07-25 22:16:08.608419 | instance |  Downloading rjsonnet
2026-07-25 22:16:08.608437 | instance |  Downloading pydantic-core
2026-07-25 22:16:08.608455 | instance |  Downloading netaddr
2026-07-25 22:16:08.608473 | instance |  Downloading pygments
2026-07-25 22:16:08.608490 | instance |  Downloading cryptography
2026-07-25 22:16:08.608507 | instance |  Downloading setuptools
2026-07-25 22:16:08.608524 | instance |  Downloading kubernetes
2026-07-25 22:16:08.608541 | instance |  Downloading ansible-core
2026-07-25 22:16:08.608559 | instance |  Downloading openstacksdk
2026-07-25 22:16:08.608577 | instance |       Built pyperclip==1.9.0
2026-07-25 22:16:08.608597 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-25 22:16:08.608617 | instance | Installed 83 packages in 45ms
2026-07-25 22:16:08.608635 | instance | Checked out ansible.posix to 1.6.0
2026-07-25 22:16:08.608652 | 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-25 22:16:08.608670 | instance | Starting galaxy collection install process
2026-07-25 22:16:08.608688 | instance | Process install dependency map
2026-07-25 22:16:08.608711 | instance | Starting collection install process
2026-07-25 22:16:08.608729 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-07-25 22:16:08.608747 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-07-25 22:16:08.608764 | instance | ansible.posix:1.6.0 was installed successfully
2026-07-25 22:16:08.608782 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 22:16:08.608800 | instance | 2.17.12
2026-07-25 22:16:08.608817 | instance | Checked out ansible.utils to v6.0.3
2026-07-25 22:16:08.608835 | 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-25 22:16:08.608852 | instance | Starting galaxy collection install process
2026-07-25 22:16:08.608870 | instance | Process install dependency map
2026-07-25 22:16:08.608887 | instance | Starting collection install process
2026-07-25 22:16:08.608905 | instance | Installing 'ansible.utils:6.0.3' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-07-25 22:16:08.608923 | instance | Created collection for ansible.utils:6.0.3 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-07-25 22:16:08.608940 | instance | ansible.utils:6.0.3 was installed successfully
2026-07-25 22:16:08.608974 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 22:16:08.608994 | instance | 2.17.12
2026-07-25 22:16:08.609013 | instance | Checked out atmosphere.common to v0.7.0
2026-07-25 22:16:08.609031 | 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-25 22:16:08.609049 | instance | Starting galaxy collection install process
2026-07-25 22:16:08.609066 | instance | Process install dependency map
2026-07-25 22:16:08.609084 | instance | Starting collection install process
2026-07-25 22:16:08.609101 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-07-25 22:16:08.609119 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-07-25 22:16:08.609137 | instance | atmosphere.common:0.7.0 was installed successfully
2026-07-25 22:16:08.609154 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 22:16:08.609171 | instance | 2.17.12
2026-07-25 22:16:08.609189 | instance | Checked out community.crypto to 2.2.3
2026-07-25 22:16:08.609206 | 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-25 22:16:08.609224 | instance | Starting galaxy collection install process
2026-07-25 22:16:08.609241 | instance | Process install dependency map
2026-07-25 22:16:08.609259 | instance | Starting collection install process
2026-07-25 22:16:08.609276 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-07-25 22:16:08.609294 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-07-25 22:16:08.609311 | instance | community.crypto:2.2.3 was installed successfully
2026-07-25 22:16:08.609328 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 22:16:08.609346 | instance | 2.17.12
2026-07-25 22:16:08.609363 | instance | Checked out community.general to 7.3.0
2026-07-25 22:16:08.609380 | 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-25 22:16:08.609403 | instance | Starting galaxy collection install process
2026-07-25 22:16:08.609421 | instance | Process install dependency map
2026-07-25 22:16:08.609439 | instance | Starting collection install process
2026-07-25 22:16:08.609457 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-07-25 22:16:08.609474 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-07-25 22:16:08.609492 | instance | community.general:7.3.0 was installed successfully
2026-07-25 22:16:08.609510 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 22:16:08.609528 | instance | 2.17.12
2026-07-25 22:16:08.609546 | instance | Checked out community.mysql to 3.6.0
2026-07-25 22:16:08.609566 | 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-25 22:16:08.609584 | instance | Starting galaxy collection install process
2026-07-25 22:16:08.609602 | instance | Process install dependency map
2026-07-25 22:16:08.609620 | instance | Starting collection install process
2026-07-25 22:16:08.609637 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-07-25 22:16:08.609655 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-07-25 22:16:08.609672 | instance | community.mysql:3.6.0 was installed successfully
2026-07-25 22:16:08.609690 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 22:16:08.609707 | instance | 2.17.12
2026-07-25 22:16:08.609725 | instance | Checked out kubernetes.core to 2.4.0
2026-07-25 22:16:08.609743 | 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-25 22:16:08.609760 | instance | Starting galaxy collection install process
2026-07-25 22:16:08.609778 | instance | Process install dependency map
2026-07-25 22:16:08.609796 | instance | Starting collection install process
2026-07-25 22:16:08.609813 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-07-25 22:16:08.609831 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-07-25 22:16:08.609848 | instance | kubernetes.core:2.4.0 was installed successfully
2026-07-25 22:16:08.609865 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 22:16:08.609883 | instance | 2.17.12
2026-07-25 22:16:08.609900 | instance | Checked out openstack.cloud to 2.6.0
2026-07-25 22:16:08.609918 | 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-25 22:16:08.609936 | instance | Starting galaxy collection install process
2026-07-25 22:16:08.609953 | instance | Process install dependency map
2026-07-25 22:16:08.609970 | instance | Starting collection install process
2026-07-25 22:16:08.609987 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-07-25 22:16:08.610005 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-07-25 22:16:08.610022 | instance | openstack.cloud:2.6.0 was installed successfully
2026-07-25 22:16:08.610040 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 22:16:08.610062 | instance | 2.17.12
2026-07-25 22:16:08.610079 | instance | Checked out vexxhost.ceph to v4.1.0
2026-07-25 22:16:08.610097 | 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-25 22:16:08.610114 | instance | Starting galaxy collection install process
2026-07-25 22:16:08.610132 | instance | Process install dependency map
2026-07-25 22:16:08.610149 | instance | Starting collection install process
2026-07-25 22:16:08.610165 | instance | Installing 'vexxhost.ceph:4.1.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-07-25 22:16:08.610183 | instance | Created collection for vexxhost.ceph:4.1.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-07-25 22:16:08.610200 | instance | vexxhost.ceph:4.1.0 was installed successfully
2026-07-25 22:16:08.610217 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 22:16:08.610234 | instance | 2.17.12
2026-07-25 22:16:08.610251 | instance | Checked out vexxhost.containers to v1.6.8
2026-07-25 22:16:08.610268 | 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-25 22:16:08.610285 | instance | Starting galaxy collection install process
2026-07-25 22:16:08.610303 | instance | Process install dependency map
2026-07-25 22:16:08.610320 | instance | Starting collection install process
2026-07-25 22:16:08.610337 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-07-25 22:16:08.610355 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-07-25 22:16:08.610373 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-07-25 22:16:08.610391 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 22:16:08.610408 | instance | 2.17.12
2026-07-25 22:16:08.610426 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-07-25 22:16:08.610443 | 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-25 22:16:08.610461 | instance | Starting galaxy collection install process
2026-07-25 22:16:08.610478 | instance | Process install dependency map
2026-07-25 22:16:08.610496 | instance | Starting collection install process
2026-07-25 22:16:08.610513 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-07-25 22:16:08.610530 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-07-25 22:16:08.610548 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-07-25 22:16:08.610566 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 22:16:08.610583 | instance | 2.17.12
2026-07-25 22:16:08.616750 | 
2026-07-25 22:16:08.616800 | PLAY RECAP
2026-07-25 22:16:08.616845 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-25 22:16:08.616866 | 
2026-07-25 22:16:08.727707 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-25 22:16:08.733281 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-25 22:16:09.293474 | 
2026-07-25 22:16:09.293586 | PLAY [all]
2026-07-25 22:16:09.304560 | 
2026-07-25 22:16:09.304630 | TASK [Install "jq" for log collection]
2026-07-25 22:16:19.519127 | instance | changed
2026-07-25 22:16:19.521614 | 
2026-07-25 22:16:19.521721 | PLAY RECAP
2026-07-25 22:16:19.521810 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-25 22:16:19.521880 | 
2026-07-25 22:16:19.635247 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-25 22:16:19.637634 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-25 22:16:20.263114 | 
2026-07-25 22:16:20.263245 | PLAY [Prepare Molecule dependencies]
2026-07-25 22:16:20.274273 | 
2026-07-25 22:16:20.274345 | TASK [Install jq for log collection]
2026-07-25 22:16:22.456165 | instance | ok
2026-07-25 22:16:22.459230 | 
2026-07-25 22:16:22.459297 | PLAY [Install Go for the Atmosphere deployment binary]
2026-07-25 22:16:22.466440 | 
2026-07-25 22:16:22.466538 | TASK [ensure-go : Check installed go version]
2026-07-25 22:16:23.004512 | instance | ok: ERROR (ignored)
2026-07-25 22:16:23.004855 | instance | {
2026-07-25 22:16:23.004901 | instance |   "failed_when_result": false,
2026-07-25 22:16:23.004936 | instance |   "msg": "[Errno 2] No such file or directory: b'go'",
2026-07-25 22:16:23.005028 | instance |   "rc": 2
2026-07-25 22:16:23.005061 | instance | }
2026-07-25 22:16:23.012010 | 
2026-07-25 22:16:23.012087 | TASK [ensure-go : Skip if correct version of go is installed]
2026-07-25 22:16:23.069595 | instance | ok
2026-07-25 22:16:23.077784 | instance | included: /var/lib/zuul/builds/b8f4bc18a21248609f66b6081bc34bf7/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-go/tasks/install-go.yaml
2026-07-25 22:16:23.083330 | 
2026-07-25 22:16:23.083393 | TASK [ensure-go : Create temp directory]
2026-07-25 22:16:23.412520 | instance | changed
2026-07-25 22:16:23.418563 | 
2026-07-25 22:16:23.418639 | TASK [ensure-go : Get archive checksum]
2026-07-25 22:16:24.045072 | instance | ok: OK (64 bytes)
2026-07-25 22:16:24.050776 | 
2026-07-25 22:16:24.050847 | TASK [ensure-go : Download go archive]
2026-07-25 22:16:25.758860 | instance | changed: OK (78559214 bytes)
2026-07-25 22:16:25.765511 | 
2026-07-25 22:16:25.765580 | TASK [ensure-go : Install go]
2026-07-25 22:16:31.543106 | instance | changed
2026-07-25 22:16:31.549497 | 
2026-07-25 22:16:31.549556 | PLAY [Prepare selective Molecule execution plan]
2026-07-25 22:16:31.555634 | 
2026-07-25 22:16:31.555699 | TASK [Build atmosphere binary]
2026-07-25 22:16:31.779230 | instance | go: downloading github.com/spf13/cobra v1.9.1
2026-07-25 22:16:31.783169 | instance | go: downloading github.com/goccy/go-yaml v1.18.0
2026-07-25 22:16:31.798362 | instance | go: downloading golang.org/x/sync v0.18.0
2026-07-25 22:16:32.069746 | instance | go: downloading github.com/spf13/pflag v1.0.7
2026-07-25 22:16:38.095410 | instance | ok: Runtime: 0:00:06.297277
2026-07-25 22:16:38.101904 | 
2026-07-25 22:16:38.101990 | TASK [Generate selective CI plan]
2026-07-25 22:16:38.673027 | instance | ok: Runtime: 0:00:00.029808
2026-07-25 22:16:38.680245 | 
2026-07-25 22:16:38.680314 | TASK [Explain selective CI plan]
2026-07-25 22:16:38.909115 | instance | CI plan mode: selective
2026-07-25 22:16:38.909300 | instance | Changed files:
2026-07-25 22:16:38.909315 | instance |   A	roles/ceph_csi_rbd/.ci-selective-test
2026-07-25 22:16:38.909326 | instance | Matches:
2026-07-25 22:16:38.909336 | instance |   roles/ceph_csi_rbd/.ci-selective-test: csi-rbd (targets) -> csi
2026-07-25 22:16:38.909352 | instance | Direct targets: csi
2026-07-25 22:16:38.909368 | instance | Deployment roots: csi
2026-07-25 22:16:38.909378 | instance | Verification profiles: csi-rbd
2026-07-25 22:16:38.909390 | instance | Variant openvswitch (3 components): ceph, csi, kubernetes
2026-07-25 22:16:38.909399 | instance | Molecule jobs:
2026-07-25 22:16:38.909409 | instance |   aio-openvswitch: skip (3 components) - verification is handled by dedicated profiles: csi-rbd
2026-07-25 22:16:38.909419 | instance |   aio-ovn: skip - plan has no ovn deployment variant
2026-07-25 22:16:38.909428 | instance |   csi-local-path-provisioner: skip - none of the job's verification profiles were requested
2026-07-25 22:16:38.909440 | instance |   csi-rbd: run (3 components) - requested verification profiles: csi-rbd
2026-07-25 22:16:38.909449 | instance |   keycloak: skip - none of the job's verification profiles were requested
2026-07-25 22:16:39.216475 | instance | ok: Runtime: 0:00:00.027084
2026-07-25 22:16:39.223034 | 
2026-07-25 22:16:39.223097 | TASK [Print selective CI plan]
2026-07-25 22:16:39.256486 | instance | ok
2026-07-25 22:16:39.262033 | 
2026-07-25 22:16:39.262097 | TASK [Report conservative fallback]
2026-07-25 22:16:39.287595 | instance | skipping: Conditional result was False
2026-07-25 22:16:39.296795 | 
2026-07-25 22:16:39.296885 | TASK [Create selective CI plan log directory]
2026-07-25 22:16:39.587127 | instance | changed
2026-07-25 22:16:39.592806 | 
2026-07-25 22:16:39.592869 | TASK [Publish selective CI plan with job logs]
2026-07-25 22:16:39.932840 | instance | changed
2026-07-25 22:16:39.935935 | 
2026-07-25 22:16:39.935983 | PLAY RECAP
2026-07-25 22:16:39.936034 | instance | ok: 13 changed: 5 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-25 22:16:39.936058 | 
2026-07-25 22:16:40.090329 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-25 22:16:40.094679 | RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-25 22:16:40.702040 | 
2026-07-25 22:16:40.702210 | PLAY [Run selected Molecule deployment and verification]
2026-07-25 22:16:40.713998 | 
2026-07-25 22:16:40.714110 | TASK [Check for selective CI plan]
2026-07-25 22:16:41.041357 | instance | ok
2026-07-25 22:16:41.048507 | 
2026-07-25 22:16:41.048588 | TASK [Validate selective CI plan]
2026-07-25 22:16:41.586176 | instance | ok: Runtime: 0:00:00.032532
2026-07-25 22:16:41.593716 | 
2026-07-25 22:16:41.593809 | TASK [Resolve selective CI plan availability]
2026-07-25 22:16:41.649501 | instance | ok
2026-07-25 22:16:41.655963 | 
2026-07-25 22:16:41.656057 | TASK [Read selective CI plan]
2026-07-25 22:16:41.970194 | instance | ok
2026-07-25 22:16:41.974807 | 
2026-07-25 22:16:41.974877 | TASK [Load selective CI plan or full fallback]
2026-07-25 22:16:42.030237 | instance | ok
2026-07-25 22:16:42.036485 | 
2026-07-25 22:16:42.036549 | TASK [Find this job in the selective CI plan]
2026-07-25 22:16:42.090368 | instance | ok
2026-07-25 22:16:42.096382 | 
2026-07-25 22:16:42.096449 | TASK [Resolve this job or use full fallback]
2026-07-25 22:16:42.152112 | instance | ok
2026-07-25 22:16:42.158142 | 
2026-07-25 22:16:42.158233 | TASK [Resolve deployment and verification inputs]
2026-07-25 22:16:42.225751 | instance | ok
2026-07-25 22:16:42.230950 | 
2026-07-25 22:16:42.231028 | TASK [Resolve deployment tags]
2026-07-25 22:16:42.286846 | instance | ok
2026-07-25 22:16:42.291875 | 
2026-07-25 22:16:42.291933 | TASK [Report Molecule job decision]
2026-07-25 22:16:42.326492 | instance | ok: {'job': 'csi-local-path-provisioner', 'mode': 'selective', 'run': False, 'reason': "none of the job's verification profiles were requested", 'network_backend': 'openvswitch', 'csi_driver': 'local-path-provisioner', 'components': [], 'verification_profiles': ['csi-rbd'], 'extra_vars': {}}
2026-07-25 22:16:42.332119 | 
2026-07-25 22:16:42.332178 | TASK [Copy inventory file for Zuul]
2026-07-25 22:16:42.358206 | instance | skipping: Conditional result was False
2026-07-25 22:16:42.370685 | 
2026-07-25 22:16:42.370871 | TASK [Switch ansible_host to private IP]
2026-07-25 22:16:42.396319 | instance | skipping: Conditional result was False
2026-07-25 22:16:42.405086 | 
2026-07-25 22:16:42.405153 | TASK [Prepare Molecule scenario]
2026-07-25 22:16:42.932898 | instance | skipping: Conditional result was False
2026-07-25 22:16:42.995542 | 
2026-07-25 22:16:42.995693 | TASK [Prepare host for Ceph-backed storage]
2026-07-25 22:16:43.526827 | instance | skipping: Conditional result was False
2026-07-25 22:16:43.537759 | 
2026-07-25 22:16:43.537833 | TASK [Build deployment command]
2026-07-25 22:16:43.564645 | instance | skipping: Conditional result was False
2026-07-25 22:16:43.573617 | 
2026-07-25 22:16:43.573707 | TASK [Run initial deployment with progress heartbeat]
2026-07-25 22:16:43.599089 | instance | skipping: Conditional result was False
2026-07-25 22:16:43.609595 | 
2026-07-25 22:16:43.609662 | TASK [Run idempotence deployment with progress heartbeat]
2026-07-25 22:16:43.634911 | instance | skipping: Conditional result was False
2026-07-25 22:16:43.644051 | 
2026-07-25 22:16:43.644143 | TASK [Check for br-ex interface]
2026-07-25 22:16:44.175500 | instance | skipping: Conditional result was False
2026-07-25 22:16:44.184017 | 
2026-07-25 22:16:44.184166 | TASK [Add IP address to br-ex]
2026-07-25 22:16:44.714897 | instance | skipping: Conditional result was False
2026-07-25 22:16:44.722505 | 
2026-07-25 22:16:44.722601 | TASK [Set br-ex interface up]
2026-07-25 22:16:45.251795 | instance | skipping: Conditional result was False
2026-07-25 22:16:45.260433 | 
2026-07-25 22:16:45.260529 | TASK [Resolve Molecule verification command]
2026-07-25 22:16:45.286732 | instance | skipping: Conditional result was False
2026-07-25 22:16:45.295800 | 
2026-07-25 22:16:45.295898 | TASK [Run Molecule verification with progress heartbeat]
2026-07-25 22:16:45.310175 | instance | skipping: Conditional result was False
2026-07-25 22:16:45.312005 | 
2026-07-25 22:16:45.312096 | PLAY RECAP
2026-07-25 22:16:45.312190 | instance | ok: 10 changed: 0 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 0
2026-07-25 22:16:45.312235 | 
2026-07-25 22:16:45.452761 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-25 22:16:45.457777 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-25 22:16:46.058729 | 
2026-07-25 22:16:46.058849 | PLAY [all]
2026-07-25 22:16:46.072401 | 
2026-07-25 22:16:46.072478 | TASK [gather-host-logs : creating directory for system status]
2026-07-25 22:16:46.389397 | instance | ok
2026-07-25 22:16:46.396290 | 
2026-07-25 22:16:46.396367 | TASK [gather-host-logs : Get logs for each host]
2026-07-25 22:16:46.729401 | instance | + systemd-cgls --full --all --no-pager
2026-07-25 22:16:46.747909 | instance | + ip addr
2026-07-25 22:16:46.750374 | instance | + ip route
2026-07-25 22:16:46.752745 | instance | + lsblk
2026-07-25 22:16:46.756668 | instance | + mount
2026-07-25 22:16:46.759237 | instance | + docker images
2026-07-25 22:16:46.759775 | instance | /bin/bash: line 7: docker: command not found
2026-07-25 22:16:46.760015 | instance | + brctl show
2026-07-25 22:16:46.760358 | instance | /bin/bash: line 8: brctl: command not found
2026-07-25 22:16:46.760601 | instance | + ps aux --sort=-%mem
2026-07-25 22:16:46.781296 | instance | + dpkg -l
2026-07-25 22:16:46.788344 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-07-25 22:16:46.788809 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-07-25 22:16:46.788846 | instance | /bin/bash: line 11: docker: command not found
2026-07-25 22:16:46.789129 | instance | + '[' '!' -z '' ']'
2026-07-25 22:16:46.933365 | instance | ok: Runtime: 0:00:00.064709
2026-07-25 22:16:46.940442 | 
2026-07-25 22:16:46.940506 | TASK [gather-host-logs : Downloads logs to executor]
2026-07-25 22:16:47.568754 | instance | ERROR
2026-07-25 22:16:47.569956 | instance | {
2026-07-25 22:16:47.570018 | instance |   "msg": "Warning: Permanently added '199.204.45.116' (ED25519) to the list of known hosts.\r\nrsync: [generator] chown \"/var/lib/zuul/builds/b8f4bc18a21248609f66b6081bc34bf7/work/logs/instance/system\" failed: Invalid argument (22)\nrsync: [receiver] chown \"/var/lib/zuul/builds/b8f4bc18a21248609f66b6081bc34bf7/work/logs/instance/system/.ci-plan.json.YH3AMp\" 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-25 22:16:47.570056 | instance |   "rc": 23
2026-07-25 22:16:47.570085 | instance | }
2026-07-25 22:16:47.570140 | instance | ERROR: Ignoring Errors
2026-07-25 22:16:47.586213 | 
2026-07-25 22:16:47.586311 | LOOP [helm-release-status : creating directory for helm release status]
2026-07-25 22:16:47.767103 | instance | changed: "values"
2026-07-25 22:16:47.941391 | instance | changed: "releases"
2026-07-25 22:16:47.951391 | 
2026-07-25 22:16:47.951553 | TASK [helm-release-status : Gather get release status for helm charts]
2026-07-25 22:16:48.167355 | instance | /bin/bash: line 3: kubectl: command not found
2026-07-25 22:16:48.485429 | instance | ok: Runtime: 0:00:00.007064
2026-07-25 22:16:48.497825 | 
2026-07-25 22:16:48.497912 | TASK [helm-release-status : Downloads logs to executor]
2026-07-25 22:16:48.974311 | instance | changed:
2026-07-25 22:16:48.974532 | instance | cd+++++++++ helm/
2026-07-25 22:16:48.974560 | instance | cd+++++++++ helm/releases/
2026-07-25 22:16:48.974582 | instance | cd+++++++++ helm/values/
2026-07-25 22:16:48.984385 | 
2026-07-25 22:16:48.984454 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-07-25 22:16:49.181215 | instance | changed
2026-07-25 22:16:49.187367 | 
2026-07-25 22:16:49.187435 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-07-25 22:16:49.453175 | 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-25 22:16:49.453267 | instance |
2026-07-25 22:16:49.457642 | instance | environment: line 1: kubectl: command not found
2026-07-25 22:16:49.458839 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-25 22:16:49.458873 | instance | environment: line 1: kubectl: command not found
2026-07-25 22:16:49.460030 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-25 22:16:49.462271 | instance | environment: line 1: kubectl: command not found
2026-07-25 22:16:49.463372 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-25 22:16:49.463780 | instance | environment: line 1: kubectl: command not found
2026-07-25 22:16:49.464905 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-25 22:16:49.465304 | instance | environment: line 1: kubectl: command not found
2026-07-25 22:16:49.466636 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-25 22:16:49.769637 | instance | ok: Runtime: 0:00:00.023456
2026-07-25 22:16:49.776272 | 
2026-07-25 22:16:49.776338 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-07-25 22:16:49.963974 | instance | changed
2026-07-25 22:16:49.969093 | 
2026-07-25 22:16:49.969169 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-07-25 22:16:50.188068 | instance | environment: line 5: kubectl: command not found
2026-07-25 22:16:50.189090 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-25 22:16:50.189351 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-25 22:16:50.189685 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-25 22:16:50.510061 | instance | ok: Runtime: 0:00:00.008140
2026-07-25 22:16:50.515683 | 
2026-07-25 22:16:50.515749 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-07-25 22:16:50.982726 | instance | changed:
2026-07-25 22:16:50.982935 | instance | cd+++++++++ objects/
2026-07-25 22:16:50.982963 | instance | cd+++++++++ objects/cluster/
2026-07-25 22:16:50.983000 | instance | cd+++++++++ objects/namespaced/
2026-07-25 22:16:50.992021 | 
2026-07-25 22:16:50.992090 | TASK [gather-pod-logs : creating directory for pod logs]
2026-07-25 22:16:51.181024 | instance | changed
2026-07-25 22:16:51.187690 | 
2026-07-25 22:16:51.187756 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-07-25 22:16:51.376247 | instance | changed
2026-07-25 22:16:51.382120 | 
2026-07-25 22:16:51.382195 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-07-25 22:16:51.593951 | instance | environment: line 3: kubectl: command not found
2026-07-25 22:16:51.916390 | instance | ok: Runtime: 0:00:00.007436
2026-07-25 22:16:51.921446 | 
2026-07-25 22:16:51.921511 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-07-25 22:16:52.388083 | instance | changed:
2026-07-25 22:16:52.388329 | instance | cd+++++++++ pod-logs/
2026-07-25 22:16:52.388368 | instance | cd+++++++++ pod-logs/failed-pods/
2026-07-25 22:16:52.408666 | 
2026-07-25 22:16:52.408745 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-07-25 22:16:52.612025 | instance | changed
2026-07-25 22:16:52.616948 | 
2026-07-25 22:16:52.617034 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-07-25 22:16:52.835143 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-25 22:16:53.152085 | instance | ok: Runtime: 0:00:00.034713
2026-07-25 22:16:53.164905 | 
2026-07-25 22:16:53.165147 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-07-25 22:16:53.379504 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-25 22:16:53.407267 | instance | ceph-mgr endpoints:
2026-07-25 22:16:53.702480 | instance | ok: Runtime: 0:00:00.033719
2026-07-25 22:16:53.709144 | 
2026-07-25 22:16:53.709233 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-07-25 22:16:53.930892 | instance | /bin/bash: line 4: kubectl: command not found
2026-07-25 22:16:54.244824 | instance | ok: Runtime: 0:00:00.035641
2026-07-25 22:16:54.251771 | 
2026-07-25 22:16:54.251842 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-07-25 22:16:54.740817 | instance | changed: cd+++++++++ prometheus/
2026-07-25 22:16:54.751612 | 
2026-07-25 22:16:54.751675 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-07-25 22:16:54.947388 | instance | changed
2026-07-25 22:16:54.953790 | 
2026-07-25 22:16:54.953874 | TASK [gather-selenium-data : Get selenium data]
2026-07-25 22:16:55.203173 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-07-25 22:16:55.204693 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-07-25 22:16:55.524715 | instance | ERROR
2026-07-25 22:16:55.524924 | instance | {
2026-07-25 22:16:55.524991 | instance |   "delta": "0:00:00.006026",
2026-07-25 22:16:55.525021 | instance |   "end": "2026-07-25 22:16:55.204990",
2026-07-25 22:16:55.525047 | instance |   "msg": "non-zero return code",
2026-07-25 22:16:55.525076 | instance |   "rc": 1,
2026-07-25 22:16:55.525104 | instance |   "start": "2026-07-25 22:16:55.198964"
2026-07-25 22:16:55.525130 | instance | }
2026-07-25 22:16:55.525165 | instance | ERROR: Ignoring Errors
2026-07-25 22:16:55.531716 | 
2026-07-25 22:16:55.531785 | TASK [gather-selenium-data : Downloads logs to executor]
2026-07-25 22:16:55.999160 | instance | changed: cd+++++++++ selenium/
2026-07-25 22:16:56.007037 | 
2026-07-25 22:16:56.007089 | PLAY RECAP
2026-07-25 22:16:56.007136 | instance | ok: 23 changed: 21 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-07-25 22:16:56.007158 | 
2026-07-25 22:16:56.133383 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-25 22:16:56.138309 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-25 22:16:56.750398 | 
2026-07-25 22:16:56.750564 | PLAY [all]
2026-07-25 22:16:56.764233 | 
2026-07-25 22:16:56.764388 | TASK [fetch-output : Set log path for multiple nodes]
2026-07-25 22:16:56.798667 | instance | skipping: Conditional result was False
2026-07-25 22:16:56.804002 | 
2026-07-25 22:16:56.804080 | TASK [fetch-output : Set log path for single node]
2026-07-25 22:16:56.837870 | instance | ok
2026-07-25 22:16:56.843515 | 
2026-07-25 22:16:56.843578 | LOOP [fetch-output : Ensure local output dirs]
2026-07-25 22:16:57.199241 | instance -> localhost | ok: "/var/lib/zuul/builds/b8f4bc18a21248609f66b6081bc34bf7/work/logs"
2026-07-25 22:16:57.398502 | instance -> localhost | changed: "/var/lib/zuul/builds/b8f4bc18a21248609f66b6081bc34bf7/work/artifacts"
2026-07-25 22:16:57.607385 | instance -> localhost | changed: "/var/lib/zuul/builds/b8f4bc18a21248609f66b6081bc34bf7/work/docs"
2026-07-25 22:16:57.628565 | 
2026-07-25 22:16:57.628688 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-07-25 22:16:58.224504 | instance | changed: .d..t...... ./
2026-07-25 22:16:58.224888 | instance | changed: All items complete
2026-07-25 22:16:58.225002 | 
2026-07-25 22:16:58.658326 | instance | changed: .d..t...... ./
2026-07-25 22:16:59.104833 | instance | changed: .d..t...... ./
2026-07-25 22:16:59.121106 | 
2026-07-25 22:16:59.121233 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-07-25 22:16:59.548223 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007177
2026-07-25 22:16:59.767305 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007525
2026-07-25 22:16:59.782247 | 
2026-07-25 22:16:59.782351 | PLAY [all]
2026-07-25 22:16:59.788151 | 
2026-07-25 22:16:59.788213 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-07-25 22:17:00.194648 | instance | changed
2026-07-25 22:17:00.200451 | 
2026-07-25 22:17:00.200500 | PLAY RECAP
2026-07-25 22:17:00.200550 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-25 22:17:00.200571 | 
2026-07-25 22:17:00.317074 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-25 22:17:00.321609 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-07-25 22:17:00.891123 | 
2026-07-25 22:17:00.891252 | PLAY [localhost]
2026-07-25 22:17:00.901915 | 
2026-07-25 22:17:00.901986 | TASK [Generate Zuul manifest]
2026-07-25 22:17:00.922675 | localhost | ok
2026-07-25 22:17:00.943468 | 
2026-07-25 22:17:00.943541 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-07-25 22:17:01.298959 | localhost | changed
2026-07-25 22:17:01.310780 | 
2026-07-25 22:17:01.310853 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-07-25 22:17:01.624233 | localhost | ok
2026-07-25 22:17:01.631549 | 
2026-07-25 22:17:01.631614 | TASK [Upload logs]
2026-07-25 22:17:01.661516 | localhost | ok
2026-07-25 22:17:01.721286 | 
2026-07-25 22:17:01.721470 | TASK [Set zuul-log-path fact]
2026-07-25 22:17:01.755200 | localhost | ok
2026-07-25 22:17:01.767072 | 
2026-07-25 22:17:01.767146 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-25 22:17:01.800783 | localhost | ok
2026-07-25 22:17:01.808876 | 
2026-07-25 22:17:01.808944 | TASK [upload-logs : Create log directories]
2026-07-25 22:17:02.218325 | localhost | changed
2026-07-25 22:17:02.225289 | 
2026-07-25 22:17:02.225368 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-07-25 22:17:02.653423 | localhost -> localhost | ok: Runtime: 0:00:00.006090
2026-07-25 22:17:02.693666 | 
2026-07-25 22:17:02.693815 | TASK [upload-logs : Upload logs to log server]
2026-07-25 22:17:03.114316 | localhost | Output suppressed because no_log was given
2026-07-25 22:17:03.119652 | 
2026-07-25 22:17:03.119753 | LOOP [upload-logs : Compress console log and json output]
2026-07-25 22:17:03.164824 | localhost | skipping: Conditional result was False
2026-07-25 22:17:03.172328 | localhost | skipping: Conditional result was False
2026-07-25 22:17:03.185619 | 
2026-07-25 22:17:03.185859 | LOOP [upload-logs : Upload compressed console log and json output]
2026-07-25 22:17:03.231460 | localhost | skipping: Conditional result was False
2026-07-25 22:17:03.231701 | 
2026-07-25 22:17:03.236584 | localhost | skipping: Conditional result was False
2026-07-25 22:17:03.251750 | 
2026-07-25 22:17:03.252035 | LOOP [upload-logs : Upload console log and json output]