2026-07-26 14:31:23.325939 | Job console starting
2026-07-26 14:31:23.358546 | Updating git repos
2026-07-26 14:31:25.281191 | Cloning repos into workspace
2026-07-26 14:31:37.885567 | Restoring repo states
2026-07-26 14:31:49.609792 | Merging changes
2026-07-26 14:31:50.945480 | Checking out repos
2026-07-26 14:31:52.410391 | Preparing playbooks
2026-07-26 14:31:55.981812 | Running Ansible setup
2026-07-26 14:31:59.569908 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-26 14:32:00.180012 | 
2026-07-26 14:32:00.180150 | PLAY [localhost]
2026-07-26 14:32:00.187845 | 
2026-07-26 14:32:00.187925 | TASK [Gathering Facts]
2026-07-26 14:32:01.044783 | localhost | ok
2026-07-26 14:32:01.052292 | 
2026-07-26 14:32:01.052372 | TASK [Setup log path fact]
2026-07-26 14:32:01.072533 | localhost | ok
2026-07-26 14:32:01.085473 | 
2026-07-26 14:32:01.085572 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-26 14:32:01.117193 | localhost | ok
2026-07-26 14:32:01.125556 | 
2026-07-26 14:32:01.125655 | TASK [emit-job-header : Print job information]
2026-07-26 14:32:01.167946 | # Job Information
2026-07-26 14:32:01.168156 | Ansible Version: 2.16.18
2026-07-26 14:32:01.168227 | Job: atmosphere-molecule-csi-local-path-provisioner-selective
2026-07-26 14:32:01.168277 | Pipeline: check
2026-07-26 14:32:01.168322 | Executor: 2d72f0692154
2026-07-26 14:32:01.168365 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4163
2026-07-26 14:32:01.168413 | Event ID: 37d6c9d0-88fe-11f1-9db7-9cdafa815eb2
2026-07-26 14:32:01.172946 | 
2026-07-26 14:32:01.173033 | LOOP [emit-job-header : Print node information]
2026-07-26 14:32:01.267453 | localhost | ok:
2026-07-26 14:32:01.267773 | localhost | # Node Information
2026-07-26 14:32:01.267836 | localhost | Inventory Hostname: instance
2026-07-26 14:32:01.267884 | localhost | Hostname: np0000186964
2026-07-26 14:32:01.267928 | localhost | Username: zuul
2026-07-26 14:32:01.267978 | localhost | Distro: Ubuntu 22.04
2026-07-26 14:32:01.268022 | localhost | Provider: yul1
2026-07-26 14:32:01.268065 | localhost | Region: ca-ymq-1
2026-07-26 14:32:01.268107 | localhost | Label: ubuntu-jammy
2026-07-26 14:32:01.268148 | localhost | Product Name: OpenStack Nova
2026-07-26 14:32:01.268189 | localhost | Interface IP: 199.204.45.129
2026-07-26 14:32:01.280386 | 
2026-07-26 14:32:01.280497 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-07-26 14:32:01.689722 | localhost -> localhost | changed
2026-07-26 14:32:01.700292 | 
2026-07-26 14:32:01.700375 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-07-26 14:32:02.568911 | localhost -> localhost | changed
2026-07-26 14:32:02.578010 | 
2026-07-26 14:32:02.578174 | PLAY [all]
2026-07-26 14:32:02.587316 | 
2026-07-26 14:32:02.587443 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-07-26 14:32:02.827724 | instance -> localhost | ok
2026-07-26 14:32:02.834248 | 
2026-07-26 14:32:02.834325 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-07-26 14:32:02.869095 | instance | ok
2026-07-26 14:32:02.882261 | instance | included: /var/lib/zuul/builds/f2a087c71fcb490fb897a7337391e383/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-07-26 14:32:02.888769 | 
2026-07-26 14:32:02.888828 | TASK [add-build-sshkey : Create Temp SSH key]
2026-07-26 14:32:05.001957 | instance -> localhost | Generating public/private rsa key pair.
2026-07-26 14:32:05.002142 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/f2a087c71fcb490fb897a7337391e383/work/f2a087c71fcb490fb897a7337391e383_id_rsa
2026-07-26 14:32:05.002172 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/f2a087c71fcb490fb897a7337391e383/work/f2a087c71fcb490fb897a7337391e383_id_rsa.pub
2026-07-26 14:32:05.002194 | instance -> localhost | The key fingerprint is:
2026-07-26 14:32:05.002215 | instance -> localhost | SHA256:8z+dJVN65qU7uxxBQiIi0Cde4coz4ICL/+E41qgR8z8 zuul-build-sshkey
2026-07-26 14:32:05.002247 | instance -> localhost | The key's randomart image is:
2026-07-26 14:32:05.002269 | instance -> localhost | +---[RSA 3072]----+
2026-07-26 14:32:05.002294 | instance -> localhost | |  .o. o.. . .    |
2026-07-26 14:32:05.002315 | instance -> localhost | |.   ooo. . o     |
2026-07-26 14:32:05.002335 | instance -> localhost | |o .. +.     . .  |
2026-07-26 14:32:05.002355 | instance -> localhost | |.+ o..       o  .|
2026-07-26 14:32:05.002377 | instance -> localhost | |= . =   S     .o |
2026-07-26 14:32:05.002396 | instance -> localhost | | =   o   o    +.=|
2026-07-26 14:32:05.002416 | instance -> localhost | |. oo.     .  ..X.|
2026-07-26 14:32:05.002435 | instance -> localhost | | .+=E.     ...=..|
2026-07-26 14:32:05.002457 | instance -> localhost | |.o..+.      ..== |
2026-07-26 14:32:05.002478 | instance -> localhost | +----[SHA256]-----+
2026-07-26 14:32:05.002524 | instance -> localhost | ok: Runtime: 0:00:01.691531
2026-07-26 14:32:05.007488 | 
2026-07-26 14:32:05.007552 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-07-26 14:32:05.040341 | instance | ok
2026-07-26 14:32:05.051301 | instance | included: /var/lib/zuul/builds/f2a087c71fcb490fb897a7337391e383/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-07-26 14:32:05.058851 | 
2026-07-26 14:32:05.058913 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-07-26 14:32:05.083077 | instance | skipping: Conditional result was False
2026-07-26 14:32:05.095009 | 
2026-07-26 14:32:05.095075 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-07-26 14:32:05.522222 | instance | changed
2026-07-26 14:32:05.529036 | 
2026-07-26 14:32:05.529123 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-07-26 14:32:05.715326 | instance | ok
2026-07-26 14:32:05.720552 | 
2026-07-26 14:32:05.720619 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-07-26 14:32:06.178596 | instance | changed
2026-07-26 14:32:06.185870 | 
2026-07-26 14:32:06.185943 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-07-26 14:32:06.624326 | instance | changed
2026-07-26 14:32:06.630673 | 
2026-07-26 14:32:06.630739 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-07-26 14:32:06.655258 | instance | skipping: Conditional result was False
2026-07-26 14:32:06.664826 | 
2026-07-26 14:32:06.664892 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-07-26 14:32:07.013596 | instance -> localhost | changed
2026-07-26 14:32:07.033778 | 
2026-07-26 14:32:07.033857 | TASK [add-build-sshkey : Add back temp key]
2026-07-26 14:32:07.316123 | instance -> localhost | Identity added: /var/lib/zuul/builds/f2a087c71fcb490fb897a7337391e383/work/f2a087c71fcb490fb897a7337391e383_id_rsa (zuul-build-sshkey)
2026-07-26 14:32:07.316654 | instance -> localhost | ok: Runtime: 0:00:00.012750
2026-07-26 14:32:07.322440 | 
2026-07-26 14:32:07.322502 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-07-26 14:32:07.630711 | instance | ok
2026-07-26 14:32:07.635713 | 
2026-07-26 14:32:07.635784 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-07-26 14:32:07.661437 | instance | skipping: Conditional result was False
2026-07-26 14:32:07.677934 | 
2026-07-26 14:32:07.678000 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-07-26 14:32:07.997127 | instance | ok
2026-07-26 14:32:08.046162 | 
2026-07-26 14:32:08.046312 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-07-26 14:32:11.186380 | instance | Output suppressed because no_log was given
2026-07-26 14:32:11.196765 | 
2026-07-26 14:32:11.196868 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-07-26 14:32:11.401384 | instance | ok: "logs"
2026-07-26 14:32:11.401651 | instance | ok: All items complete
2026-07-26 14:32:11.401680 | 
2026-07-26 14:32:11.573196 | instance | ok: "artifacts"
2026-07-26 14:32:11.728433 | instance | ok: "docs"
2026-07-26 14:32:11.746055 | 
2026-07-26 14:32:11.746213 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-07-26 14:32:11.932902 | instance | changed: "logs"
2026-07-26 14:32:12.089277 | instance | changed: "artifacts"
2026-07-26 14:32:12.238635 | instance | changed: "docs"
2026-07-26 14:32:12.263699 | 
2026-07-26 14:32:12.263811 | PLAY RECAP
2026-07-26 14:32:12.263857 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-07-26 14:32:12.263885 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-26 14:32:12.263907 | 
2026-07-26 14:32:12.406875 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-26 14:32:12.411554 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-26 14:32:13.021564 | 
2026-07-26 14:32:13.022220 | PLAY [all]
2026-07-26 14:32:13.033687 | 
2026-07-26 14:32:13.033763 | TASK [setup-uv : Extract archive]
2026-07-26 14:32:16.171826 | instance | changed
2026-07-26 14:32:16.177572 | 
2026-07-26 14:32:16.177651 | TASK [setup-uv : Print version]
2026-07-26 14:32:16.543491 | instance | uv 0.8.13
2026-07-26 14:32:16.714193 | instance | ok: Runtime: 0:00:00.012930
2026-07-26 14:32:16.724176 | 
2026-07-26 14:32:16.724246 | TASK [Install Ansible collection siblings]
2026-07-26 14:32:26.553272 | instance | changed:
2026-07-26 14:32:26.553514 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-07-26 14:32:26.553543 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-07-26 14:32:26.553566 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-07-26 14:32:26.553586 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-07-26 14:32:26.553606 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-07-26 14:32:26.553631 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-07-26 14:32:26.553652 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-07-26 14:32:26.553674 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-07-26 14:32:26.553693 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-07-26 14:32:26.553713 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-07-26 14:32:26.553731 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-07-26 14:32:26.553750 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-07-26 14:32:26.553769 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-07-26 14:32:26.553788 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-07-26 14:32:26.553806 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0
2026-07-26 14:32:26.553824 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-07-26 14:32:26.553843 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-07-26 14:32:26.553861 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-07-26 14:32:26.553879 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-07-26 14:32:26.553897 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-07-26 14:32:26.553915 | instance | Resolved vexxhost.ceph to v4.1.0 for >=3.2.0
2026-07-26 14:32:26.553933 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-07-26 14:32:26.553953 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-26 14:32:26.553989 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-07-26 14:32:26.554008 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0,>=6.0.0
2026-07-26 14:32:26.554027 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-26 14:32:26.554045 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-26 14:32:26.554063 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-26 14:32:26.554082 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-07-26 14:32:26.554099 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-07-26 14:32:26.554117 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-26 14:32:26.554136 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-26 14:32:26.554154 | instance | Checked out ansible.netcommon to 1.2.0
2026-07-26 14:32:26.554173 | 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 14:32:26.554197 | instance | Starting galaxy collection install process
2026-07-26 14:32:26.554215 | instance | Process install dependency map
2026-07-26 14:32:26.554233 | instance | Starting collection install process
2026-07-26 14:32:26.554251 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-07-26 14:32:26.554269 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-07-26 14:32:26.554287 | instance | ansible.netcommon:* was installed successfully
2026-07-26 14:32:26.554305 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-07-26 14:32:26.554323 | instance | Creating virtual environment at: .venv
2026-07-26 14:32:26.554341 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-26 14:32:26.554360 | instance | Downloading cryptography (4.2MiB)
2026-07-26 14:32:26.554378 | instance | Downloading rjsonnet (1.2MiB)
2026-07-26 14:32:26.554403 | instance | Downloading kubernetes (1.9MiB)
2026-07-26 14:32:26.554422 | instance | Downloading netaddr (2.2MiB)
2026-07-26 14:32:26.554440 | instance | Downloading setuptools (1.1MiB)
2026-07-26 14:32:26.554459 | instance | Downloading pygments (1.2MiB)
2026-07-26 14:32:26.554477 | instance | Downloading ansible-core (2.1MiB)
2026-07-26 14:32:26.554495 | instance | Downloading openstacksdk (1.7MiB)
2026-07-26 14:32:26.554514 | instance | Downloading pydantic-core (2.0MiB)
2026-07-26 14:32:26.554532 | instance |  Downloading rjsonnet
2026-07-26 14:32:26.554552 | instance |    Building pyperclip==1.9.0
2026-07-26 14:32:26.554570 | instance |  Downloading pydantic-core
2026-07-26 14:32:26.554589 | instance |  Downloading netaddr
2026-07-26 14:32:26.554607 | instance |  Downloading pygments
2026-07-26 14:32:26.554626 | instance |  Downloading cryptography
2026-07-26 14:32:26.554644 | instance |  Downloading setuptools
2026-07-26 14:32:26.554662 | instance |  Downloading kubernetes
2026-07-26 14:32:26.554680 | instance |  Downloading ansible-core
2026-07-26 14:32:26.554698 | instance |  Downloading openstacksdk
2026-07-26 14:32:26.554716 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-26 14:32:26.554734 | instance |       Built pyperclip==1.9.0
2026-07-26 14:32:26.554753 | instance | Installed 83 packages in 151ms
2026-07-26 14:32:26.554771 | instance | Checked out ansible.posix to 1.6.0
2026-07-26 14:32:26.554789 | 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 14:32:26.554807 | instance | Starting galaxy collection install process
2026-07-26 14:32:26.554825 | instance | Process install dependency map
2026-07-26 14:32:26.554848 | instance | Starting collection install process
2026-07-26 14:32:26.554868 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-07-26 14:32:26.554887 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-07-26 14:32:26.554905 | instance | ansible.posix:1.6.0 was installed successfully
2026-07-26 14:32:26.554923 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 14:32:26.554941 | instance | 2.17.12
2026-07-26 14:32:26.554959 | instance | Checked out ansible.utils to v6.0.3
2026-07-26 14:32:26.554977 | 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 14:32:26.554995 | instance | Starting galaxy collection install process
2026-07-26 14:32:26.555013 | instance | Process install dependency map
2026-07-26 14:32:26.555031 | instance | Starting collection install process
2026-07-26 14:32:26.555050 | instance | Installing 'ansible.utils:6.0.3' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-07-26 14:32:26.555068 | instance | Created collection for ansible.utils:6.0.3 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-07-26 14:32:26.555086 | instance | ansible.utils:6.0.3 was installed successfully
2026-07-26 14:32:26.555104 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 14:32:26.555122 | instance | 2.17.12
2026-07-26 14:32:26.555140 | instance | Checked out atmosphere.common to v0.7.0
2026-07-26 14:32:26.555157 | 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 14:32:26.555175 | instance | Starting galaxy collection install process
2026-07-26 14:32:26.555193 | instance | Process install dependency map
2026-07-26 14:32:26.555210 | instance | Starting collection install process
2026-07-26 14:32:26.555228 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-07-26 14:32:26.555246 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-07-26 14:32:26.555264 | instance | atmosphere.common:0.7.0 was installed successfully
2026-07-26 14:32:26.555281 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 14:32:26.555299 | instance | 2.17.12
2026-07-26 14:32:26.555317 | instance | Checked out community.crypto to 2.2.3
2026-07-26 14:32:26.555335 | 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 14:32:26.555353 | instance | Starting galaxy collection install process
2026-07-26 14:32:26.555371 | instance | Process install dependency map
2026-07-26 14:32:26.555388 | instance | Starting collection install process
2026-07-26 14:32:26.555406 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-07-26 14:32:26.555423 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-07-26 14:32:26.555442 | instance | community.crypto:2.2.3 was installed successfully
2026-07-26 14:32:26.555459 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 14:32:26.555477 | instance | 2.17.12
2026-07-26 14:32:26.555495 | instance | Checked out community.general to 7.3.0
2026-07-26 14:32:26.555513 | 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 14:32:26.555535 | instance | Starting galaxy collection install process
2026-07-26 14:32:26.555554 | instance | Process install dependency map
2026-07-26 14:32:26.555572 | instance | Starting collection install process
2026-07-26 14:32:26.555590 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-07-26 14:32:26.555608 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-07-26 14:32:26.555626 | instance | community.general:7.3.0 was installed successfully
2026-07-26 14:32:26.555644 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 14:32:26.555663 | instance | 2.17.12
2026-07-26 14:32:26.555681 | instance | Checked out community.mysql to 3.6.0
2026-07-26 14:32:26.555701 | 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 14:32:26.555720 | instance | Starting galaxy collection install process
2026-07-26 14:32:26.555739 | instance | Process install dependency map
2026-07-26 14:32:26.555758 | instance | Starting collection install process
2026-07-26 14:32:26.555776 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-07-26 14:32:26.555794 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-07-26 14:32:26.555812 | instance | community.mysql:3.6.0 was installed successfully
2026-07-26 14:32:26.555830 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 14:32:26.555848 | instance | 2.17.12
2026-07-26 14:32:26.555867 | instance | Checked out kubernetes.core to 2.4.0
2026-07-26 14:32:26.555885 | 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 14:32:26.555903 | instance | Starting galaxy collection install process
2026-07-26 14:32:26.555921 | instance | Process install dependency map
2026-07-26 14:32:26.555940 | instance | Starting collection install process
2026-07-26 14:32:26.555958 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-07-26 14:32:26.555975 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-07-26 14:32:26.555994 | instance | kubernetes.core:2.4.0 was installed successfully
2026-07-26 14:32:26.556012 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 14:32:26.556030 | instance | 2.17.12
2026-07-26 14:32:26.556047 | instance | Checked out openstack.cloud to 2.6.0
2026-07-26 14:32:26.556065 | 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 14:32:26.556083 | instance | Starting galaxy collection install process
2026-07-26 14:32:26.556101 | instance | Process install dependency map
2026-07-26 14:32:26.556119 | instance | Starting collection install process
2026-07-26 14:32:26.556137 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-07-26 14:32:26.556155 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-07-26 14:32:26.556173 | instance | openstack.cloud:2.6.0 was installed successfully
2026-07-26 14:32:26.556190 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 14:32:26.556213 | instance | 2.17.12
2026-07-26 14:32:26.556231 | instance | Checked out vexxhost.ceph to v4.1.0
2026-07-26 14:32:26.556250 | 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 14:32:26.556268 | instance | Starting galaxy collection install process
2026-07-26 14:32:26.556285 | instance | Process install dependency map
2026-07-26 14:32:26.556303 | instance | Starting collection install process
2026-07-26 14:32:26.556321 | instance | Installing 'vexxhost.ceph:4.1.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-07-26 14:32:26.556340 | instance | Created collection for vexxhost.ceph:4.1.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-07-26 14:32:26.556358 | instance | vexxhost.ceph:4.1.0 was installed successfully
2026-07-26 14:32:26.556375 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 14:32:26.556392 | instance | 2.17.12
2026-07-26 14:32:26.556410 | instance | Checked out vexxhost.containers to v1.6.8
2026-07-26 14:32:26.556427 | 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 14:32:26.556446 | instance | Starting galaxy collection install process
2026-07-26 14:32:26.556494 | instance | Process install dependency map
2026-07-26 14:32:26.556518 | instance | Starting collection install process
2026-07-26 14:32:26.556539 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-07-26 14:32:26.556557 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-07-26 14:32:26.556576 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-07-26 14:32:26.556594 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 14:32:26.556612 | instance | 2.17.12
2026-07-26 14:32:26.556630 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-07-26 14:32:26.556648 | 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 14:32:26.556666 | instance | Starting galaxy collection install process
2026-07-26 14:32:26.556684 | instance | Process install dependency map
2026-07-26 14:32:26.556702 | instance | Starting collection install process
2026-07-26 14:32:26.556720 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-07-26 14:32:26.556738 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-07-26 14:32:26.556756 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-07-26 14:32:26.556774 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 14:32:26.556791 | instance | 2.17.12
2026-07-26 14:32:26.562910 | 
2026-07-26 14:32:26.562959 | PLAY RECAP
2026-07-26 14:32:26.563006 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-26 14:32:26.563027 | 
2026-07-26 14:32:26.687771 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-26 14:32:26.692197 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-26 14:32:27.360607 | 
2026-07-26 14:32:27.360734 | PLAY [all]
2026-07-26 14:32:27.372232 | 
2026-07-26 14:32:27.372304 | TASK [Install "jq" for log collection]
2026-07-26 14:32:38.125719 | instance | changed
2026-07-26 14:32:38.128102 | 
2026-07-26 14:32:38.128156 | PLAY RECAP
2026-07-26 14:32:38.128227 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-26 14:32:38.128319 | 
2026-07-26 14:32:38.295000 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-26 14:32:38.298445 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-26 14:32:38.915438 | 
2026-07-26 14:32:38.915568 | PLAY [Prepare Molecule dependencies]
2026-07-26 14:32:38.926874 | 
2026-07-26 14:32:38.927001 | TASK [Install jq for log collection]
2026-07-26 14:32:41.364398 | instance | ok
2026-07-26 14:32:41.368161 | 
2026-07-26 14:32:41.368245 | PLAY [Install Go for the Atmosphere deployment binary]
2026-07-26 14:32:41.377469 | 
2026-07-26 14:32:41.377797 | TASK [ensure-go : Check installed go version]
2026-07-26 14:32:41.917577 | instance | ok: ERROR (ignored)
2026-07-26 14:32:41.917958 | instance | {
2026-07-26 14:32:41.918024 | instance |   "failed_when_result": false,
2026-07-26 14:32:41.918075 | instance |   "msg": "[Errno 2] No such file or directory: b'go'",
2026-07-26 14:32:41.918117 | instance |   "rc": 2
2026-07-26 14:32:41.918157 | instance | }
2026-07-26 14:32:41.924695 | 
2026-07-26 14:32:41.924762 | TASK [ensure-go : Skip if correct version of go is installed]
2026-07-26 14:32:41.987908 | instance | ok
2026-07-26 14:32:41.998690 | instance | included: /var/lib/zuul/builds/f2a087c71fcb490fb897a7337391e383/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-go/tasks/install-go.yaml
2026-07-26 14:32:42.003505 | 
2026-07-26 14:32:42.003573 | TASK [ensure-go : Create temp directory]
2026-07-26 14:32:42.313300 | instance | changed
2026-07-26 14:32:42.325649 | 
2026-07-26 14:32:42.325734 | TASK [ensure-go : Get archive checksum]
2026-07-26 14:32:42.971601 | instance | ok: OK (64 bytes)
2026-07-26 14:32:42.979572 | 
2026-07-26 14:32:42.979667 | TASK [ensure-go : Download go archive]
2026-07-26 14:32:44.259563 | instance | changed: OK (78559214 bytes)
2026-07-26 14:32:44.267630 | 
2026-07-26 14:32:44.267704 | TASK [ensure-go : Install go]
2026-07-26 14:32:50.052244 | instance | changed
2026-07-26 14:32:50.061827 | 
2026-07-26 14:32:50.061890 | PLAY [Prepare selective Molecule execution plan]
2026-07-26 14:32:50.068066 | 
2026-07-26 14:32:50.068134 | TASK [Build atmosphere binary]
2026-07-26 14:32:50.272424 | instance | go: downloading github.com/spf13/cobra v1.9.1
2026-07-26 14:32:50.275777 | instance | go: downloading github.com/goccy/go-yaml v1.18.0
2026-07-26 14:32:50.276982 | instance | go: downloading golang.org/x/sync v0.18.0
2026-07-26 14:32:50.648566 | instance | go: downloading github.com/spf13/pflag v1.0.7
2026-07-26 14:32:56.610288 | instance | ok: Runtime: 0:00:06.302274
2026-07-26 14:32:56.617804 | 
2026-07-26 14:32:56.617894 | TASK [Generate selective CI plan]
2026-07-26 14:32:57.188702 | instance | ok: Runtime: 0:00:00.032188
2026-07-26 14:32:57.194789 | 
2026-07-26 14:32:57.194858 | TASK [Explain selective CI plan]
2026-07-26 14:32:57.439738 | instance | CI plan mode: selective
2026-07-26 14:32:57.439893 | instance | Changed files:
2026-07-26 14:32:57.439901 | instance |   A	roles/heat/.ci-selective-test
2026-07-26 14:32:57.439906 | instance | Matches:
2026-07-26 14:32:57.439911 | instance |   roles/heat/.ci-selective-test: component:heat (targets) -> heat
2026-07-26 14:32:57.439917 | instance | Direct targets: heat
2026-07-26 14:32:57.439924 | instance | Deployment roots: glance-images, heat, neutron
2026-07-26 14:32:57.439930 | instance | Verification profiles: orchestration
2026-07-26 14:32:57.439943 | 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 14:32:57.439948 | instance | Molecule jobs:
2026-07-26 14:32:57.439953 | instance |   aio-openvswitch: run (22 components) - executes the openvswitch deployment variant
2026-07-26 14:32:57.439957 | instance |   aio-ovn: skip - plan has no ovn deployment variant
2026-07-26 14:32:57.439961 | instance |   csi-local-path-provisioner: skip - none of the job's verification profiles were requested
2026-07-26 14:32:57.439966 | instance |   csi-rbd: skip - none of the job's verification profiles were requested
2026-07-26 14:32:57.439972 | instance |   keycloak: skip - none of the job's verification profiles were requested
2026-07-26 14:32:57.844845 | instance | ok: Runtime: 0:00:00.032046
2026-07-26 14:32:57.853641 | 
2026-07-26 14:32:57.853736 | TASK [Print selective CI plan]
2026-07-26 14:32:57.891674 | instance | ok
2026-07-26 14:32:57.898239 | 
2026-07-26 14:32:57.898331 | TASK [Report conservative fallback]
2026-07-26 14:32:57.924754 | instance | skipping: Conditional result was False
2026-07-26 14:32:57.935067 | 
2026-07-26 14:32:57.935175 | TASK [Create selective CI plan log directory]
2026-07-26 14:32:58.244037 | instance | changed
2026-07-26 14:32:58.254373 | 
2026-07-26 14:32:58.254540 | TASK [Publish selective CI plan with job logs]
2026-07-26 14:32:58.604442 | instance | changed
2026-07-26 14:32:58.635152 | 
2026-07-26 14:32:58.635260 | PLAY RECAP
2026-07-26 14:32:58.635347 | instance | ok: 13 changed: 5 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-26 14:32:58.635393 | 
2026-07-26 14:32:58.842179 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-26 14:32:58.847070 | RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-26 14:33:02.448817 | 
2026-07-26 14:33:02.448993 | PLAY [Run selected Molecule deployment and verification]
2026-07-26 14:33:02.517527 | 
2026-07-26 14:33:02.517655 | TASK [Check for selective CI plan]
2026-07-26 14:33:02.846937 | instance | ok
2026-07-26 14:33:02.965269 | 
2026-07-26 14:33:02.965406 | TASK [Validate selective CI plan]
2026-07-26 14:33:03.503108 | instance | ok: Runtime: 0:00:00.028817
2026-07-26 14:33:03.510914 | 
2026-07-26 14:33:03.511008 | TASK [Resolve selective CI plan availability]
2026-07-26 14:33:03.563251 | instance | ok
2026-07-26 14:33:03.569792 | 
2026-07-26 14:33:03.569888 | TASK [Read selective CI plan]
2026-07-26 14:33:03.864393 | instance | ok
2026-07-26 14:33:04.460311 | 
2026-07-26 14:33:04.460489 | TASK [Load selective CI plan or full fallback]
2026-07-26 14:33:04.517185 | instance | ok
2026-07-26 14:33:04.524870 | 
2026-07-26 14:33:04.524938 | TASK [Find this job in the selective CI plan]
2026-07-26 14:33:04.589621 | instance | ok
2026-07-26 14:33:04.596272 | 
2026-07-26 14:33:04.596356 | TASK [Resolve this job or use full fallback]
2026-07-26 14:33:04.651722 | instance | ok
2026-07-26 14:33:04.656729 | 
2026-07-26 14:33:04.656794 | TASK [Resolve deployment and verification inputs]
2026-07-26 14:33:04.724221 | instance | ok
2026-07-26 14:33:04.730609 | 
2026-07-26 14:33:04.730728 | TASK [Resolve deployment tags]
2026-07-26 14:33:04.795949 | instance | ok
2026-07-26 14:33:04.802250 | 
2026-07-26 14:33:04.802315 | TASK [Report Molecule job decision]
2026-07-26 14:33:04.837659 | 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': ['orchestration'], 'extra_vars': {}}
2026-07-26 14:33:04.844116 | 
2026-07-26 14:33:04.844177 | TASK [Copy inventory file for Zuul]
2026-07-26 14:33:04.869780 | instance | skipping: Conditional result was False
2026-07-26 14:33:04.881854 | 
2026-07-26 14:33:04.881923 | TASK [Switch ansible_host to private IP]
2026-07-26 14:33:04.906736 | instance | skipping: Conditional result was False
2026-07-26 14:33:04.916878 | 
2026-07-26 14:33:04.916940 | TASK [Prepare Molecule scenario]
2026-07-26 14:33:05.445646 | instance | skipping: Conditional result was False
2026-07-26 14:33:05.489487 | 
2026-07-26 14:33:05.489654 | TASK [Prepare host for Ceph-backed storage]
2026-07-26 14:33:06.015143 | instance | skipping: Conditional result was False
2026-07-26 14:33:06.785448 | 
2026-07-26 14:33:06.785603 | TASK [Build deployment command]
2026-07-26 14:33:06.810476 | instance | skipping: Conditional result was False
2026-07-26 14:33:06.816978 | 
2026-07-26 14:33:06.817069 | TASK [Run initial deployment with progress heartbeat]
2026-07-26 14:33:06.842107 | instance | skipping: Conditional result was False
2026-07-26 14:33:06.851375 | 
2026-07-26 14:33:06.851439 | TASK [Run idempotence deployment with progress heartbeat]
2026-07-26 14:33:06.875664 | instance | skipping: Conditional result was False
2026-07-26 14:33:06.886286 | 
2026-07-26 14:33:06.886355 | TASK [Check for br-ex interface]
2026-07-26 14:33:07.415176 | instance | skipping: Conditional result was False
2026-07-26 14:33:07.447137 | 
2026-07-26 14:33:07.447289 | TASK [Add IP address to br-ex]
2026-07-26 14:33:07.975436 | instance | skipping: Conditional result was False
2026-07-26 14:33:07.985229 | 
2026-07-26 14:33:07.985322 | TASK [Set br-ex interface up]
2026-07-26 14:33:08.512309 | instance | skipping: Conditional result was False
2026-07-26 14:33:08.526983 | 
2026-07-26 14:33:08.527064 | TASK [Resolve Molecule verification command]
2026-07-26 14:33:08.552060 | instance | skipping: Conditional result was False
2026-07-26 14:33:08.558027 | 
2026-07-26 14:33:08.558095 | TASK [Run Molecule verification with progress heartbeat]
2026-07-26 14:33:08.583329 | instance | skipping: Conditional result was False
2026-07-26 14:33:08.585179 | 
2026-07-26 14:33:08.585275 | PLAY RECAP
2026-07-26 14:33:08.585377 | instance | ok: 10 changed: 0 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 0
2026-07-26 14:33:08.585428 | 
2026-07-26 14:33:08.754095 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-26 14:33:08.759475 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-26 14:33:09.369960 | 
2026-07-26 14:33:09.388202 | PLAY [all]
2026-07-26 14:33:09.412847 | 
2026-07-26 14:33:09.413086 | TASK [gather-host-logs : creating directory for system status]
2026-07-26 14:33:09.754852 | instance | ok
2026-07-26 14:33:09.762425 | 
2026-07-26 14:33:09.762525 | TASK [gather-host-logs : Get logs for each host]
2026-07-26 14:33:10.105955 | instance | + systemd-cgls --full --all --no-pager
2026-07-26 14:33:10.116815 | instance | + ip addr
2026-07-26 14:33:10.118569 | instance | + ip route
2026-07-26 14:33:10.120573 | instance | + lsblk
2026-07-26 14:33:10.124278 | instance | + mount
2026-07-26 14:33:10.126450 | instance | + docker images
2026-07-26 14:33:10.126787 | instance | /bin/bash: line 7: docker: command not found
2026-07-26 14:33:10.126983 | instance | + brctl show
2026-07-26 14:33:10.127307 | instance | /bin/bash: line 8: brctl: command not found
2026-07-26 14:33:10.127504 | instance | + ps aux --sort=-%mem
2026-07-26 14:33:10.154129 | instance | + dpkg -l
2026-07-26 14:33:10.161281 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-07-26 14:33:10.161561 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-07-26 14:33:10.161628 | instance | /bin/bash: line 11: docker: command not found
2026-07-26 14:33:10.161824 | instance | + '[' '!' -z '' ']'
2026-07-26 14:33:10.296265 | instance | ok: Runtime: 0:00:00.059869
2026-07-26 14:33:10.303747 | 
2026-07-26 14:33:10.303822 | TASK [gather-host-logs : Downloads logs to executor]
2026-07-26 14:33:10.965794 | instance | ERROR
2026-07-26 14:33:10.966101 | instance | {
2026-07-26 14:33:10.966149 | instance |   "msg": "Warning: Permanently added '199.204.45.129' (ED25519) to the list of known hosts.\r\nrsync: [generator] chown \"/var/lib/zuul/builds/f2a087c71fcb490fb897a7337391e383/work/logs/instance/system\" failed: Invalid argument (22)\nrsync: [receiver] chown \"/var/lib/zuul/builds/f2a087c71fcb490fb897a7337391e383/work/logs/instance/system/.ci-plan.json.NrTLUo\" 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 14:33:10.966200 | instance |   "rc": 23
2026-07-26 14:33:10.966230 | instance | }
2026-07-26 14:33:10.966270 | instance | ERROR: Ignoring Errors
2026-07-26 14:33:10.977095 | 
2026-07-26 14:33:10.977168 | LOOP [helm-release-status : creating directory for helm release status]
2026-07-26 14:33:11.168130 | instance | changed: "values"
2026-07-26 14:33:11.336309 | instance | changed: "releases"
2026-07-26 14:33:11.356033 | 
2026-07-26 14:33:11.356229 | TASK [helm-release-status : Gather get release status for helm charts]
2026-07-26 14:33:11.573046 | instance | /bin/bash: line 3: kubectl: command not found
2026-07-26 14:33:11.901132 | instance | ok: Runtime: 0:00:00.005157
2026-07-26 14:33:11.906559 | 
2026-07-26 14:33:11.906632 | TASK [helm-release-status : Downloads logs to executor]
2026-07-26 14:33:12.405786 | instance | changed:
2026-07-26 14:33:12.405989 | instance | cd+++++++++ helm/
2026-07-26 14:33:12.406017 | instance | cd+++++++++ helm/releases/
2026-07-26 14:33:12.406038 | instance | cd+++++++++ helm/values/
2026-07-26 14:33:12.415786 | 
2026-07-26 14:33:12.415855 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-07-26 14:33:12.613220 | instance | changed
2026-07-26 14:33:12.618402 | 
2026-07-26 14:33:12.618480 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-07-26 14:33:12.876241 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-26 14:33:12.876360 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-26 14:33:12.881303 | instance | environment: line 1: kubectl: command not found
2026-07-26 14:33:12.883129 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-26 14:33:12.883795 | instance | environment: line 1: kubectl: command not found
2026-07-26 14:33:12.884714 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-26 14:33:12.886557 | instance | environment: line 1: kubectl: command not found
2026-07-26 14:33:12.887458 | instance | environment: line 1: kubectl: command not found
2026-07-26 14:33:12.887597 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-26 14:33:12.888919 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-26 14:33:12.889754 | instance | environment: line 1: kubectl: command not found
2026-07-26 14:33:12.890810 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-26 14:33:13.203831 | instance | ok: Runtime: 0:00:00.024872
2026-07-26 14:33:13.210302 | 
2026-07-26 14:33:13.210375 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-07-26 14:33:13.396711 | instance | changed
2026-07-26 14:33:13.403735 | 
2026-07-26 14:33:13.403838 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-07-26 14:33:13.616301 | instance | environment: line 5: kubectl: command not found
2026-07-26 14:33:13.617580 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-26 14:33:13.617617 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-26 14:33:13.617990 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-26 14:33:13.938456 | instance | ok: Runtime: 0:00:00.008656
2026-07-26 14:33:13.945765 | 
2026-07-26 14:33:13.954995 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-07-26 14:33:14.576211 | instance | changed:
2026-07-26 14:33:14.576449 | instance | cd+++++++++ objects/
2026-07-26 14:33:14.576494 | instance | cd+++++++++ objects/cluster/
2026-07-26 14:33:14.576528 | instance | cd+++++++++ objects/namespaced/
2026-07-26 14:33:14.587054 | 
2026-07-26 14:33:14.587125 | TASK [gather-pod-logs : creating directory for pod logs]
2026-07-26 14:33:14.794421 | instance | changed
2026-07-26 14:33:14.800579 | 
2026-07-26 14:33:14.800643 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-07-26 14:33:14.995968 | instance | changed
2026-07-26 14:33:15.003668 | 
2026-07-26 14:33:15.003744 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-07-26 14:33:15.227807 | instance | environment: line 3: kubectl: command not found
2026-07-26 14:33:15.541898 | instance | ok: Runtime: 0:00:00.008051
2026-07-26 14:33:15.547689 | 
2026-07-26 14:33:15.547759 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-07-26 14:33:16.044646 | instance | changed:
2026-07-26 14:33:16.044888 | instance | cd+++++++++ pod-logs/
2026-07-26 14:33:16.044936 | instance | cd+++++++++ pod-logs/failed-pods/
2026-07-26 14:33:16.055481 | 
2026-07-26 14:33:16.055546 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-07-26 14:33:16.249909 | instance | changed
2026-07-26 14:33:16.258176 | 
2026-07-26 14:33:16.258277 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-07-26 14:33:16.472947 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-26 14:33:16.798061 | instance | ok: Runtime: 0:00:00.034519
2026-07-26 14:33:16.803782 | 
2026-07-26 14:33:16.803859 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-07-26 14:33:17.004854 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-26 14:33:17.032934 | instance | ceph-mgr endpoints:
2026-07-26 14:33:17.340192 | instance | ok: Runtime: 0:00:00.034353
2026-07-26 14:33:17.347229 | 
2026-07-26 14:33:17.347327 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-07-26 14:33:17.549215 | instance | /bin/bash: line 4: kubectl: command not found
2026-07-26 14:33:17.883724 | instance | ok: Runtime: 0:00:00.032647
2026-07-26 14:33:17.892522 | 
2026-07-26 14:33:17.892599 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-07-26 14:33:18.382478 | instance | changed: cd+++++++++ prometheus/
2026-07-26 14:33:18.393660 | 
2026-07-26 14:33:18.393732 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-07-26 14:33:18.583509 | instance | changed
2026-07-26 14:33:18.589417 | 
2026-07-26 14:33:18.589488 | TASK [gather-selenium-data : Get selenium data]
2026-07-26 14:33:18.842595 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-07-26 14:33:18.844087 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-07-26 14:33:19.170184 | instance | ERROR
2026-07-26 14:33:19.171403 | instance | {
2026-07-26 14:33:19.171498 | instance |   "delta": "0:00:00.006793",
2026-07-26 14:33:19.171551 | instance |   "end": "2026-07-26 14:33:18.844619",
2026-07-26 14:33:19.171595 | instance |   "msg": "non-zero return code",
2026-07-26 14:33:19.171637 | instance |   "rc": 1,
2026-07-26 14:33:19.171686 | instance |   "start": "2026-07-26 14:33:18.837826"
2026-07-26 14:33:19.171729 | instance | }
2026-07-26 14:33:19.171793 | instance | ERROR: Ignoring Errors
2026-07-26 14:33:19.177152 | 
2026-07-26 14:33:19.177221 | TASK [gather-selenium-data : Downloads logs to executor]
2026-07-26 14:33:19.640932 | instance | changed: cd+++++++++ selenium/
2026-07-26 14:33:19.651215 | 
2026-07-26 14:33:19.651298 | PLAY RECAP
2026-07-26 14:33:19.651349 | instance | ok: 23 changed: 21 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-07-26 14:33:19.651371 | 
2026-07-26 14:33:19.791164 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-26 14:33:19.794394 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-26 14:33:20.398647 | 
2026-07-26 14:33:20.399009 | PLAY [all]
2026-07-26 14:33:20.411318 | 
2026-07-26 14:33:20.411397 | TASK [fetch-output : Set log path for multiple nodes]
2026-07-26 14:33:20.457377 | instance | skipping: Conditional result was False
2026-07-26 14:33:20.466822 | 
2026-07-26 14:33:20.466930 | TASK [fetch-output : Set log path for single node]
2026-07-26 14:33:20.512130 | instance | ok
2026-07-26 14:33:20.516870 | 
2026-07-26 14:33:20.516938 | LOOP [fetch-output : Ensure local output dirs]
2026-07-26 14:33:20.926406 | instance -> localhost | ok: "/var/lib/zuul/builds/f2a087c71fcb490fb897a7337391e383/work/logs"
2026-07-26 14:33:21.153303 | instance -> localhost | changed: "/var/lib/zuul/builds/f2a087c71fcb490fb897a7337391e383/work/artifacts"
2026-07-26 14:33:21.382249 | instance -> localhost | changed: "/var/lib/zuul/builds/f2a087c71fcb490fb897a7337391e383/work/docs"
2026-07-26 14:33:21.394467 | 
2026-07-26 14:33:21.394629 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-07-26 14:33:22.018063 | instance | changed: .d..t...... ./
2026-07-26 14:33:22.018410 | instance | changed: All items complete
2026-07-26 14:33:22.018445 | 
2026-07-26 14:33:23.929695 | instance | changed: .d..t...... ./
2026-07-26 14:33:24.366354 | instance | changed: .d..t...... ./
2026-07-26 14:33:24.394372 | 
2026-07-26 14:33:24.394538 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-07-26 14:33:24.832616 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.029971
2026-07-26 14:33:25.413569 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007385
2026-07-26 14:33:25.436560 | 
2026-07-26 14:33:25.436698 | PLAY [all]
2026-07-26 14:33:25.442574 | 
2026-07-26 14:33:25.442637 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-07-26 14:33:26.794419 | instance | changed
2026-07-26 14:33:26.802784 | 
2026-07-26 14:33:26.802905 | PLAY RECAP
2026-07-26 14:33:26.803048 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-26 14:33:26.803128 | 
2026-07-26 14:33:26.942478 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-26 14:33:26.947904 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-07-26 14:33:27.537711 | 
2026-07-26 14:33:27.537860 | PLAY [localhost]
2026-07-26 14:33:27.548517 | 
2026-07-26 14:33:27.548600 | TASK [Generate Zuul manifest]
2026-07-26 14:33:27.570750 | localhost | ok
2026-07-26 14:33:27.587807 | 
2026-07-26 14:33:27.587937 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-07-26 14:33:28.011859 | localhost | changed
2026-07-26 14:33:28.022416 | 
2026-07-26 14:33:28.022496 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-07-26 14:33:28.054700 | localhost | ok
2026-07-26 14:33:28.062798 | 
2026-07-26 14:33:28.062863 | TASK [Upload logs]
2026-07-26 14:33:28.084463 | localhost | ok
2026-07-26 14:33:28.145860 | 
2026-07-26 14:33:28.145996 | TASK [Set zuul-log-path fact]
2026-07-26 14:33:28.168421 | localhost | ok
2026-07-26 14:33:28.182597 | 
2026-07-26 14:33:28.182674 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-26 14:33:28.214675 | localhost | ok
2026-07-26 14:33:28.222953 | 
2026-07-26 14:33:28.223022 | TASK [upload-logs : Create log directories]
2026-07-26 14:33:28.642869 | localhost | changed
2026-07-26 14:33:28.647769 | 
2026-07-26 14:33:28.647844 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-07-26 14:33:29.078330 | localhost -> localhost | ok: Runtime: 0:00:00.007301
2026-07-26 14:33:29.120923 | 
2026-07-26 14:33:29.121093 | TASK [upload-logs : Upload logs to log server]
2026-07-26 14:33:29.541930 | localhost | Output suppressed because no_log was given
2026-07-26 14:33:29.545961 | 
2026-07-26 14:33:29.546043 | LOOP [upload-logs : Compress console log and json output]
2026-07-26 14:33:29.601061 | localhost | skipping: Conditional result was False
2026-07-26 14:33:29.610229 | localhost | skipping: Conditional result was False
2026-07-26 14:33:29.620981 | 
2026-07-26 14:33:29.621166 | LOOP [upload-logs : Upload compressed console log and json output]
2026-07-26 14:33:29.671946 | localhost | skipping: Conditional result was False
2026-07-26 14:33:29.672350 | 
2026-07-26 14:33:29.676364 | localhost | skipping: Conditional result was False
2026-07-26 14:33:29.689302 | 
2026-07-26 14:33:29.689532 | LOOP [upload-logs : Upload console log and json output]