2026-07-26 09:21:51.753708 | Job console starting
2026-07-26 09:21:51.772235 | Updating git repos
2026-07-26 09:21:52.224658 | Cloning repos into workspace
2026-07-26 09:22:03.547102 | Restoring repo states
2026-07-26 09:22:10.972630 | Merging changes
2026-07-26 09:22:13.271511 | Checking out repos
2026-07-26 09:22:15.780782 | Preparing playbooks
2026-07-26 09:22:19.329954 | Running Ansible setup
2026-07-26 09:22:23.736082 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-26 09:22:24.316872 | 
2026-07-26 09:22:24.317004 | PLAY [localhost]
2026-07-26 09:22:24.324289 | 
2026-07-26 09:22:24.324359 | TASK [Gathering Facts]
2026-07-26 09:22:25.234161 | localhost | ok
2026-07-26 09:22:25.245018 | 
2026-07-26 09:22:25.245133 | TASK [Setup log path fact]
2026-07-26 09:22:25.265962 | localhost | ok
2026-07-26 09:22:25.277459 | 
2026-07-26 09:22:25.277548 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-26 09:22:25.309110 | localhost | ok
2026-07-26 09:22:25.318780 | 
2026-07-26 09:22:25.318875 | TASK [emit-job-header : Print job information]
2026-07-26 09:22:25.360466 | # Job Information
2026-07-26 09:22:25.360672 | Ansible Version: 2.16.18
2026-07-26 09:22:25.360719 | Job: atmosphere-molecule-csi-rbd-selective
2026-07-26 09:22:25.360752 | Pipeline: check
2026-07-26 09:22:25.360805 | Executor: 2d72f0692154
2026-07-26 09:22:25.360844 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4163
2026-07-26 09:22:25.360877 | Event ID: 10b8cea0-88d3-11f1-87ff-12aed53a5439
2026-07-26 09:22:25.365164 | 
2026-07-26 09:22:25.365264 | LOOP [emit-job-header : Print node information]
2026-07-26 09:22:25.460895 | localhost | ok:
2026-07-26 09:22:25.461100 | localhost | # Node Information
2026-07-26 09:22:25.461141 | localhost | Inventory Hostname: instance
2026-07-26 09:22:25.461171 | localhost | Hostname: np0000186945
2026-07-26 09:22:25.461200 | localhost | Username: zuul
2026-07-26 09:22:25.461232 | localhost | Distro: Ubuntu 22.04
2026-07-26 09:22:25.461260 | localhost | Provider: yul1
2026-07-26 09:22:25.461288 | localhost | Region: ca-ymq-1
2026-07-26 09:22:25.461314 | localhost | Label: ubuntu-jammy
2026-07-26 09:22:25.461340 | localhost | Product Name: OpenStack Nova
2026-07-26 09:22:25.461367 | localhost | Interface IP: 199.204.45.227
2026-07-26 09:22:25.473315 | 
2026-07-26 09:22:25.473504 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-07-26 09:22:25.843172 | localhost -> localhost | changed
2026-07-26 09:22:25.853800 | 
2026-07-26 09:22:25.853883 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-07-26 09:22:26.723457 | localhost -> localhost | changed
2026-07-26 09:22:26.734870 | 
2026-07-26 09:22:26.734962 | PLAY [all]
2026-07-26 09:22:26.743961 | 
2026-07-26 09:22:26.744033 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-07-26 09:22:26.983208 | instance -> localhost | ok
2026-07-26 09:22:26.993590 | 
2026-07-26 09:22:26.993681 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-07-26 09:22:27.029927 | instance | ok
2026-07-26 09:22:27.046700 | instance | included: /var/lib/zuul/builds/db0579f03f654c52b51d1a11b17eb0b1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-07-26 09:22:27.054057 | 
2026-07-26 09:22:27.054139 | TASK [add-build-sshkey : Create Temp SSH key]
2026-07-26 09:22:29.067707 | instance -> localhost | Generating public/private rsa key pair.
2026-07-26 09:22:29.067941 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/db0579f03f654c52b51d1a11b17eb0b1/work/db0579f03f654c52b51d1a11b17eb0b1_id_rsa
2026-07-26 09:22:29.067986 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/db0579f03f654c52b51d1a11b17eb0b1/work/db0579f03f654c52b51d1a11b17eb0b1_id_rsa.pub
2026-07-26 09:22:29.068036 | instance -> localhost | The key fingerprint is:
2026-07-26 09:22:29.068071 | instance -> localhost | SHA256:WjWaUfQPA4LsMApGibX3z9TSbFFvyL2BcE9Rsq17/hA zuul-build-sshkey
2026-07-26 09:22:29.068121 | instance -> localhost | The key's randomart image is:
2026-07-26 09:22:29.068153 | instance -> localhost | +---[RSA 3072]----+
2026-07-26 09:22:29.068190 | instance -> localhost | |ooo  . ...= o +o.|
2026-07-26 09:22:29.068223 | instance -> localhost | |oo .o o  o B B + |
2026-07-26 09:22:29.068254 | instance -> localhost | |.....+  . + B B .|
2026-07-26 09:22:29.068284 | instance -> localhost | |  .. ..  O o = + |
2026-07-26 09:22:29.068313 | instance -> localhost | |      . S =   E  |
2026-07-26 09:22:29.068343 | instance -> localhost | |       * o     o |
2026-07-26 09:22:29.068372 | instance -> localhost | |      . o     o .|
2026-07-26 09:22:29.068402 | instance -> localhost | |               + |
2026-07-26 09:22:29.068435 | instance -> localhost | |                +|
2026-07-26 09:22:29.068466 | instance -> localhost | +----[SHA256]-----+
2026-07-26 09:22:29.068537 | instance -> localhost | ok: Runtime: 0:00:01.584497
2026-07-26 09:22:29.076346 | 
2026-07-26 09:22:29.076441 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-07-26 09:22:29.112624 | instance | ok
2026-07-26 09:22:29.124485 | instance | included: /var/lib/zuul/builds/db0579f03f654c52b51d1a11b17eb0b1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-07-26 09:22:29.133443 | 
2026-07-26 09:22:29.133504 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-07-26 09:22:29.159083 | instance | skipping: Conditional result was False
2026-07-26 09:22:29.169400 | 
2026-07-26 09:22:29.169484 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-07-26 09:22:29.615567 | instance | changed
2026-07-26 09:22:29.621275 | 
2026-07-26 09:22:29.621346 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-07-26 09:22:29.807004 | instance | ok
2026-07-26 09:22:29.813601 | 
2026-07-26 09:22:29.813692 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-07-26 09:22:30.263419 | instance | changed
2026-07-26 09:22:30.271460 | 
2026-07-26 09:22:30.271545 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-07-26 09:22:30.738843 | instance | changed
2026-07-26 09:22:30.745083 | 
2026-07-26 09:22:30.745145 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-07-26 09:22:30.770526 | instance | skipping: Conditional result was False
2026-07-26 09:22:30.780896 | 
2026-07-26 09:22:30.781000 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-07-26 09:22:31.160731 | instance -> localhost | changed
2026-07-26 09:22:31.176997 | 
2026-07-26 09:22:31.177068 | TASK [add-build-sshkey : Add back temp key]
2026-07-26 09:22:31.458063 | instance -> localhost | Identity added: /var/lib/zuul/builds/db0579f03f654c52b51d1a11b17eb0b1/work/db0579f03f654c52b51d1a11b17eb0b1_id_rsa (zuul-build-sshkey)
2026-07-26 09:22:31.458442 | instance -> localhost | ok: Runtime: 0:00:00.013420
2026-07-26 09:22:31.464420 | 
2026-07-26 09:22:31.464548 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-07-26 09:22:31.795419 | instance | ok
2026-07-26 09:22:31.800715 | 
2026-07-26 09:22:31.800800 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-07-26 09:22:31.829181 | instance | skipping: Conditional result was False
2026-07-26 09:22:31.847304 | 
2026-07-26 09:22:31.847595 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-07-26 09:22:32.172633 | instance | ok
2026-07-26 09:22:32.220235 | 
2026-07-26 09:22:32.220425 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-07-26 09:22:35.384348 | instance | Output suppressed because no_log was given
2026-07-26 09:22:35.511651 | 
2026-07-26 09:22:35.511841 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-07-26 09:22:35.704161 | instance | ok: "logs"
2026-07-26 09:22:35.704500 | instance | ok: All items complete
2026-07-26 09:22:35.704531 | 
2026-07-26 09:22:35.855837 | instance | ok: "artifacts"
2026-07-26 09:22:36.004000 | instance | ok: "docs"
2026-07-26 09:22:36.027481 | 
2026-07-26 09:22:36.027636 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-07-26 09:22:36.211298 | instance | changed: "logs"
2026-07-26 09:22:36.369337 | instance | changed: "artifacts"
2026-07-26 09:22:36.518219 | instance | changed: "docs"
2026-07-26 09:22:36.541743 | 
2026-07-26 09:22:36.541874 | PLAY RECAP
2026-07-26 09:22:36.541925 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-07-26 09:22:36.541954 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-26 09:22:36.541977 | 
2026-07-26 09:22:36.692010 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-26 09:22:36.697441 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-26 09:22:37.262710 | 
2026-07-26 09:22:37.262832 | PLAY [all]
2026-07-26 09:22:37.273938 | 
2026-07-26 09:22:37.274020 | TASK [setup-uv : Extract archive]
2026-07-26 09:22:39.379648 | instance | changed
2026-07-26 09:22:39.386697 | 
2026-07-26 09:22:39.386893 | TASK [setup-uv : Print version]
2026-07-26 09:22:39.766108 | instance | uv 0.8.13
2026-07-26 09:22:39.927660 | instance | ok: Runtime: 0:00:00.014066
2026-07-26 09:22:39.937481 | 
2026-07-26 09:22:39.937555 | TASK [Install Ansible collection siblings]
2026-07-26 09:22:48.900353 | instance | changed:
2026-07-26 09:22:48.900594 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-07-26 09:22:48.900636 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-07-26 09:22:48.900668 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-07-26 09:22:48.900697 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-07-26 09:22:48.900725 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-07-26 09:22:48.900758 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-07-26 09:22:48.900787 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-07-26 09:22:48.900818 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-07-26 09:22:48.900846 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-07-26 09:22:48.900874 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-07-26 09:22:48.900901 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-07-26 09:22:48.900928 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-07-26 09:22:48.900974 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-07-26 09:22:48.901007 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-07-26 09:22:48.901034 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0
2026-07-26 09:22:48.901061 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-07-26 09:22:48.901088 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-07-26 09:22:48.901114 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-07-26 09:22:48.901140 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-07-26 09:22:48.901165 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-07-26 09:22:48.901190 | instance | Resolved vexxhost.ceph to v4.1.0 for >=3.2.0
2026-07-26 09:22:48.901215 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-07-26 09:22:48.901243 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-26 09:22:48.901290 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-07-26 09:22:48.901317 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0,>=6.0.0
2026-07-26 09:22:48.901343 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-26 09:22:48.901370 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-26 09:22:48.901395 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-26 09:22:48.901421 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-07-26 09:22:48.901447 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-07-26 09:22:48.901472 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-26 09:22:48.901498 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-26 09:22:48.901524 | instance | Checked out ansible.netcommon to 1.2.0
2026-07-26 09:22:48.901550 | 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 09:22:48.901584 | instance | Starting galaxy collection install process
2026-07-26 09:22:48.901610 | instance | Process install dependency map
2026-07-26 09:22:48.901636 | instance | Starting collection install process
2026-07-26 09:22:48.901662 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-07-26 09:22:48.901687 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-07-26 09:22:48.901713 | instance | ansible.netcommon:* was installed successfully
2026-07-26 09:22:48.901738 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-07-26 09:22:48.901764 | instance | Creating virtual environment at: .venv
2026-07-26 09:22:48.901789 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-26 09:22:48.901815 | instance | Downloading netaddr (2.2MiB)
2026-07-26 09:22:48.901840 | instance | Downloading setuptools (1.1MiB)
2026-07-26 09:22:48.901871 | instance | Downloading pydantic-core (2.0MiB)
2026-07-26 09:22:48.901897 | instance | Downloading openstacksdk (1.7MiB)
2026-07-26 09:22:48.901922 | instance | Downloading cryptography (4.2MiB)
2026-07-26 09:22:48.901948 | instance | Downloading pygments (1.2MiB)
2026-07-26 09:22:48.901974 | instance | Downloading ansible-core (2.1MiB)
2026-07-26 09:22:48.902000 | instance | Downloading rjsonnet (1.2MiB)
2026-07-26 09:22:48.902026 | instance | Downloading kubernetes (1.9MiB)
2026-07-26 09:22:48.902052 | instance |    Building pyperclip==1.9.0
2026-07-26 09:22:48.902080 | instance |  Downloading rjsonnet
2026-07-26 09:22:48.902106 | instance |  Downloading pydantic-core
2026-07-26 09:22:48.902132 | instance |  Downloading netaddr
2026-07-26 09:22:48.902158 | instance |  Downloading pygments
2026-07-26 09:22:48.902184 | instance |  Downloading cryptography
2026-07-26 09:22:48.902209 | instance |  Downloading setuptools
2026-07-26 09:22:48.902234 | instance |  Downloading kubernetes
2026-07-26 09:22:48.902259 | instance |  Downloading ansible-core
2026-07-26 09:22:48.902284 | instance |  Downloading openstacksdk
2026-07-26 09:22:48.902309 | instance |       Built pyperclip==1.9.0
2026-07-26 09:22:48.902335 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-26 09:22:48.902360 | instance | Installed 83 packages in 45ms
2026-07-26 09:22:48.902386 | instance | Checked out ansible.posix to 1.6.0
2026-07-26 09:22:48.902411 | 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 09:22:48.902436 | instance | Starting galaxy collection install process
2026-07-26 09:22:48.902461 | instance | Process install dependency map
2026-07-26 09:22:48.902493 | instance | Starting collection install process
2026-07-26 09:22:48.902519 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-07-26 09:22:48.902544 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-07-26 09:22:48.902569 | instance | ansible.posix:1.6.0 was installed successfully
2026-07-26 09:22:48.902595 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 09:22:48.902620 | instance | 2.17.12
2026-07-26 09:22:48.902645 | instance | Checked out ansible.utils to v6.0.3
2026-07-26 09:22:48.902671 | 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 09:22:48.902697 | instance | Starting galaxy collection install process
2026-07-26 09:22:48.902722 | instance | Process install dependency map
2026-07-26 09:22:48.902747 | instance | Starting collection install process
2026-07-26 09:22:48.902772 | instance | Installing 'ansible.utils:6.0.3' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-07-26 09:22:48.902798 | instance | Created collection for ansible.utils:6.0.3 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-07-26 09:22:48.902824 | instance | ansible.utils:6.0.3 was installed successfully
2026-07-26 09:22:48.902851 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 09:22:48.902877 | instance | 2.17.12
2026-07-26 09:22:48.902902 | instance | Checked out atmosphere.common to v0.7.0
2026-07-26 09:22:48.902927 | 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 09:22:48.902952 | instance | Starting galaxy collection install process
2026-07-26 09:22:48.902977 | instance | Process install dependency map
2026-07-26 09:22:48.903002 | instance | Starting collection install process
2026-07-26 09:22:48.903027 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-07-26 09:22:48.903052 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-07-26 09:22:48.903078 | instance | atmosphere.common:0.7.0 was installed successfully
2026-07-26 09:22:48.903104 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 09:22:48.903129 | instance | 2.17.12
2026-07-26 09:22:48.903153 | instance | Checked out community.crypto to 2.2.3
2026-07-26 09:22:48.903177 | 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 09:22:48.903201 | instance | Starting galaxy collection install process
2026-07-26 09:22:48.903226 | instance | Process install dependency map
2026-07-26 09:22:48.903250 | instance | Starting collection install process
2026-07-26 09:22:48.903274 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-07-26 09:22:48.903298 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-07-26 09:22:48.903324 | instance | community.crypto:2.2.3 was installed successfully
2026-07-26 09:22:48.903349 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 09:22:48.903374 | instance | 2.17.12
2026-07-26 09:22:48.903399 | instance | Checked out community.general to 7.3.0
2026-07-26 09:22:48.903424 | 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 09:22:48.903457 | instance | Starting galaxy collection install process
2026-07-26 09:22:48.903484 | instance | Process install dependency map
2026-07-26 09:22:48.903509 | instance | Starting collection install process
2026-07-26 09:22:48.903533 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-07-26 09:22:48.903558 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-07-26 09:22:48.903583 | instance | community.general:7.3.0 was installed successfully
2026-07-26 09:22:48.903609 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 09:22:48.903672 | instance | 2.17.12
2026-07-26 09:22:48.903703 | instance | Checked out community.mysql to 3.6.0
2026-07-26 09:22:48.903732 | 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 09:22:48.903760 | instance | Starting galaxy collection install process
2026-07-26 09:22:48.903786 | instance | Process install dependency map
2026-07-26 09:22:48.903812 | instance | Starting collection install process
2026-07-26 09:22:48.903838 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-07-26 09:22:48.903864 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-07-26 09:22:48.903890 | instance | community.mysql:3.6.0 was installed successfully
2026-07-26 09:22:48.903915 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 09:22:48.903941 | instance | 2.17.12
2026-07-26 09:22:48.903966 | instance | Checked out kubernetes.core to 2.4.0
2026-07-26 09:22:48.903992 | 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 09:22:48.904018 | instance | Starting galaxy collection install process
2026-07-26 09:22:48.904049 | instance | Process install dependency map
2026-07-26 09:22:48.904078 | instance | Starting collection install process
2026-07-26 09:22:48.904101 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-07-26 09:22:48.904124 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-07-26 09:22:48.904147 | instance | kubernetes.core:2.4.0 was installed successfully
2026-07-26 09:22:48.904170 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 09:22:48.904189 | instance | 2.17.12
2026-07-26 09:22:48.904207 | instance | Checked out openstack.cloud to 2.6.0
2026-07-26 09:22:48.904226 | 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 09:22:48.904245 | instance | Starting galaxy collection install process
2026-07-26 09:22:48.904263 | instance | Process install dependency map
2026-07-26 09:22:48.904281 | instance | Starting collection install process
2026-07-26 09:22:48.904300 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-07-26 09:22:48.904318 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-07-26 09:22:48.904337 | instance | openstack.cloud:2.6.0 was installed successfully
2026-07-26 09:22:48.904356 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 09:22:48.904374 | instance | 2.17.12
2026-07-26 09:22:48.904398 | instance | Checked out vexxhost.ceph to v4.1.0
2026-07-26 09:22:48.904417 | 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 09:22:48.904436 | instance | Starting galaxy collection install process
2026-07-26 09:22:48.904454 | instance | Process install dependency map
2026-07-26 09:22:48.904472 | instance | Starting collection install process
2026-07-26 09:22:48.904491 | instance | Installing 'vexxhost.ceph:4.1.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-07-26 09:22:48.904509 | instance | Created collection for vexxhost.ceph:4.1.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-07-26 09:22:48.904528 | instance | vexxhost.ceph:4.1.0 was installed successfully
2026-07-26 09:22:48.904547 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 09:22:48.904565 | instance | 2.17.12
2026-07-26 09:22:48.904584 | instance | Checked out vexxhost.containers to v1.6.8
2026-07-26 09:22:48.904603 | 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 09:22:48.904621 | instance | Starting galaxy collection install process
2026-07-26 09:22:48.904640 | instance | Process install dependency map
2026-07-26 09:22:48.904658 | instance | Starting collection install process
2026-07-26 09:22:48.904677 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-07-26 09:22:48.904695 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-07-26 09:22:48.904714 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-07-26 09:22:48.904733 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 09:22:48.904751 | instance | 2.17.12
2026-07-26 09:22:48.904770 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-07-26 09:22:48.904789 | 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 09:22:48.904807 | instance | Starting galaxy collection install process
2026-07-26 09:22:48.904825 | instance | Process install dependency map
2026-07-26 09:22:48.904843 | instance | Starting collection install process
2026-07-26 09:22:48.904861 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-07-26 09:22:48.904880 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-07-26 09:22:48.904899 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-07-26 09:22:48.904918 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-26 09:22:48.904936 | instance | 2.17.12
2026-07-26 09:22:48.910687 | 
2026-07-26 09:22:48.910737 | PLAY RECAP
2026-07-26 09:22:48.910787 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-26 09:22:48.910811 | 
2026-07-26 09:22:49.043314 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-26 09:22:49.047819 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-26 09:22:49.642411 | 
2026-07-26 09:22:49.642563 | PLAY [all]
2026-07-26 09:22:49.654969 | 
2026-07-26 09:22:49.655050 | TASK [Install "jq" for log collection]
2026-07-26 09:23:05.735277 | instance | changed
2026-07-26 09:23:05.737228 | 
2026-07-26 09:23:05.737302 | PLAY RECAP
2026-07-26 09:23:05.737374 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-26 09:23:05.737450 | 
2026-07-26 09:23:05.856651 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-26 09:23:05.860930 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-26 09:23:06.450108 | 
2026-07-26 09:23:06.450229 | PLAY [Prepare Molecule dependencies]
2026-07-26 09:23:06.461341 | 
2026-07-26 09:23:06.461459 | TASK [Install jq for log collection]
2026-07-26 09:23:08.745841 | instance | ok
2026-07-26 09:23:08.748695 | 
2026-07-26 09:23:08.748757 | PLAY [Install Go for the Atmosphere deployment binary]
2026-07-26 09:23:08.755249 | 
2026-07-26 09:23:08.755335 | TASK [ensure-go : Check installed go version]
2026-07-26 09:23:09.291087 | instance | ok: ERROR (ignored)
2026-07-26 09:23:09.291423 | instance | {
2026-07-26 09:23:09.291467 | instance |   "failed_when_result": false,
2026-07-26 09:23:09.291500 | instance |   "msg": "[Errno 2] No such file or directory: b'go'",
2026-07-26 09:23:09.291527 | instance |   "rc": 2
2026-07-26 09:23:09.291553 | instance | }
2026-07-26 09:23:09.298145 | 
2026-07-26 09:23:09.298214 | TASK [ensure-go : Skip if correct version of go is installed]
2026-07-26 09:23:09.355918 | instance | ok
2026-07-26 09:23:09.572241 | instance | included: /var/lib/zuul/builds/db0579f03f654c52b51d1a11b17eb0b1/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-go/tasks/install-go.yaml
2026-07-26 09:23:09.577009 | 
2026-07-26 09:23:09.577074 | TASK [ensure-go : Create temp directory]
2026-07-26 09:23:09.886364 | instance | changed
2026-07-26 09:23:09.892676 | 
2026-07-26 09:23:09.892765 | TASK [ensure-go : Get archive checksum]
2026-07-26 09:23:10.496254 | instance | ok: OK (64 bytes)
2026-07-26 09:23:10.503430 | 
2026-07-26 09:23:10.503520 | TASK [ensure-go : Download go archive]
2026-07-26 09:23:11.872669 | instance | changed: OK (78559214 bytes)
2026-07-26 09:23:11.878042 | 
2026-07-26 09:23:11.878107 | TASK [ensure-go : Install go]
2026-07-26 09:23:17.611174 | instance | changed
2026-07-26 09:23:17.620674 | 
2026-07-26 09:23:17.620741 | PLAY [Prepare selective Molecule execution plan]
2026-07-26 09:23:17.627323 | 
2026-07-26 09:23:17.627395 | TASK [Build atmosphere binary]
2026-07-26 09:23:17.851394 | instance | go: downloading github.com/spf13/cobra v1.9.1
2026-07-26 09:23:17.855345 | instance | go: downloading github.com/goccy/go-yaml v1.18.0
2026-07-26 09:23:17.859684 | instance | go: downloading golang.org/x/sync v0.18.0
2026-07-26 09:23:18.111371 | instance | go: downloading github.com/spf13/pflag v1.0.7
2026-07-26 09:23:24.692513 | instance | ok: Runtime: 0:00:06.832965
2026-07-26 09:23:24.697142 | 
2026-07-26 09:23:24.697206 | TASK [Generate selective CI plan]
2026-07-26 09:23:25.270876 | instance | ok: Runtime: 0:00:00.032586
2026-07-26 09:23:25.277387 | 
2026-07-26 09:23:25.277469 | TASK [Explain selective CI plan]
2026-07-26 09:23:25.531513 | instance | CI plan mode: selective
2026-07-26 09:23:25.531793 | instance | Changed files:
2026-07-26 09:23:25.531828 | instance |   A	roles/horizon/.ci-selective-test
2026-07-26 09:23:25.531857 | instance | Matches:
2026-07-26 09:23:25.531890 | instance |   roles/horizon/.ci-selective-test: component:horizon (targets) -> horizon
2026-07-26 09:23:25.531918 | instance | Direct targets: horizon
2026-07-26 09:23:25.531949 | instance | Deployment roots: horizon
2026-07-26 09:23:25.531981 | instance | Verification profiles: dashboard
2026-07-26 09:23:25.532017 | instance | Variant openvswitch (13 components): ceph, cert-manager, cluster-issuer, csi, horizon, ingress-nginx, keycloak, keystone, kubernetes, memcached, percona-xtradb-cluster, percona-xtradb-cluster-operator, rabbitmq-cluster-operator
2026-07-26 09:23:25.532216 | instance | Molecule jobs:
2026-07-26 09:23:25.532244 | instance |   aio-openvswitch: run (13 components) - executes the openvswitch deployment variant
2026-07-26 09:23:25.532271 | instance |   aio-ovn: skip - plan has no ovn deployment variant
2026-07-26 09:23:25.532310 | instance |   csi-local-path-provisioner: skip - none of the job's verification profiles were requested
2026-07-26 09:23:25.532338 | instance |   csi-rbd: skip - none of the job's verification profiles were requested
2026-07-26 09:23:25.532365 | instance |   keycloak: skip - none of the job's verification profiles were requested
2026-07-26 09:23:25.810281 | instance | ok: Runtime: 0:00:00.032014
2026-07-26 09:23:25.815449 | 
2026-07-26 09:23:25.815513 | TASK [Print selective CI plan]
2026-07-26 09:23:25.849691 | instance | ok
2026-07-26 09:23:25.856014 | 
2026-07-26 09:23:25.856099 | TASK [Report conservative fallback]
2026-07-26 09:23:25.881124 | instance | skipping: Conditional result was False
2026-07-26 09:23:25.889969 | 
2026-07-26 09:23:25.890064 | TASK [Create selective CI plan log directory]
2026-07-26 09:23:26.175172 | instance | changed
2026-07-26 09:23:26.181814 | 
2026-07-26 09:23:26.181899 | TASK [Publish selective CI plan with job logs]
2026-07-26 09:23:26.588688 | instance | changed
2026-07-26 09:23:26.600682 | 
2026-07-26 09:23:26.600746 | PLAY RECAP
2026-07-26 09:23:26.600804 | instance | ok: 13 changed: 5 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-26 09:23:26.600835 | 
2026-07-26 09:23:26.807992 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-26 09:23:26.810788 | RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-26 09:23:27.442339 | 
2026-07-26 09:23:27.943701 | PLAY [Run selected Molecule deployment and verification]
2026-07-26 09:23:27.960865 | 
2026-07-26 09:23:27.961006 | TASK [Check for selective CI plan]
2026-07-26 09:23:28.338178 | instance | ok
2026-07-26 09:23:28.345926 | 
2026-07-26 09:23:28.346001 | TASK [Validate selective CI plan]
2026-07-26 09:23:28.877707 | instance | ok: Runtime: 0:00:00.034537
2026-07-26 09:23:28.882860 | 
2026-07-26 09:23:28.882925 | TASK [Resolve selective CI plan availability]
2026-07-26 09:23:28.937038 | instance | ok
2026-07-26 09:23:28.943374 | 
2026-07-26 09:23:28.943437 | TASK [Read selective CI plan]
2026-07-26 09:23:29.287542 | instance | ok
2026-07-26 09:23:29.293718 | 
2026-07-26 09:23:29.293806 | TASK [Load selective CI plan or full fallback]
2026-07-26 09:23:29.350170 | instance | ok
2026-07-26 09:23:29.358262 | 
2026-07-26 09:23:29.358347 | TASK [Find this job in the selective CI plan]
2026-07-26 09:23:29.413244 | instance | ok
2026-07-26 09:23:29.418224 | 
2026-07-26 09:23:29.418284 | TASK [Resolve this job or use full fallback]
2026-07-26 09:23:29.475737 | instance | ok
2026-07-26 09:23:29.483105 | 
2026-07-26 09:23:29.483203 | TASK [Resolve deployment and verification inputs]
2026-07-26 09:23:29.549112 | instance | ok
2026-07-26 09:23:29.554905 | 
2026-07-26 09:23:29.554984 | TASK [Resolve deployment tags]
2026-07-26 09:23:29.608629 | instance | ok
2026-07-26 09:23:29.614797 | 
2026-07-26 09:23:29.614875 | TASK [Report Molecule job decision]
2026-07-26 09:23:29.658422 | instance | ok: {'job': 'csi-rbd', 'mode': 'selective', 'run': False, 'reason': "none of the job's verification profiles were requested", 'network_backend': 'openvswitch', 'csi_driver': 'rbd', 'components': [], 'verification_profiles': ['dashboard'], 'extra_vars': {}}
2026-07-26 09:23:29.665041 | 
2026-07-26 09:23:29.665130 | TASK [Copy inventory file for Zuul]
2026-07-26 09:23:29.690164 | instance | skipping: Conditional result was False
2026-07-26 09:23:29.701870 | 
2026-07-26 09:23:29.701939 | TASK [Switch ansible_host to private IP]
2026-07-26 09:23:29.726306 | instance | skipping: Conditional result was False
2026-07-26 09:23:29.737030 | 
2026-07-26 09:23:29.737164 | TASK [Prepare Molecule scenario]
2026-07-26 09:23:30.263598 | instance | skipping: Conditional result was False
2026-07-26 09:23:30.306484 | 
2026-07-26 09:23:30.306692 | TASK [Prepare host for Ceph-backed storage]
2026-07-26 09:23:30.831378 | instance | skipping: Conditional result was False
2026-07-26 09:23:30.842657 | 
2026-07-26 09:23:30.842773 | TASK [Build deployment command]
2026-07-26 09:23:30.869546 | instance | skipping: Conditional result was False
2026-07-26 09:23:30.880121 | 
2026-07-26 09:23:30.880219 | TASK [Run initial deployment with progress heartbeat]
2026-07-26 09:23:30.905324 | instance | skipping: Conditional result was False
2026-07-26 09:23:30.914934 | 
2026-07-26 09:23:30.915030 | TASK [Run idempotence deployment with progress heartbeat]
2026-07-26 09:23:30.941236 | instance | skipping: Conditional result was False
2026-07-26 09:23:30.952555 | 
2026-07-26 09:23:30.952701 | TASK [Check for br-ex interface]
2026-07-26 09:23:31.485341 | instance | skipping: Conditional result was False
2026-07-26 09:23:31.491104 | 
2026-07-26 09:23:31.491204 | TASK [Add IP address to br-ex]
2026-07-26 09:23:32.032889 | instance | skipping: Conditional result was False
2026-07-26 09:23:32.043052 | 
2026-07-26 09:23:32.043188 | TASK [Set br-ex interface up]
2026-07-26 09:23:32.572094 | instance | skipping: Conditional result was False
2026-07-26 09:23:32.578212 | 
2026-07-26 09:23:32.578280 | TASK [Resolve Molecule verification command]
2026-07-26 09:23:32.602858 | instance | skipping: Conditional result was False
2026-07-26 09:23:32.612370 | 
2026-07-26 09:23:32.612459 | TASK [Run Molecule verification with progress heartbeat]
2026-07-26 09:23:32.637913 | instance | skipping: Conditional result was False
2026-07-26 09:23:32.639810 | 
2026-07-26 09:23:32.639905 | PLAY RECAP
2026-07-26 09:23:32.640010 | instance | ok: 10 changed: 0 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 0
2026-07-26 09:23:32.640057 | 
2026-07-26 09:23:32.775211 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-26 09:23:32.777454 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-26 09:23:33.367829 | 
2026-07-26 09:23:33.367957 | PLAY [all]
2026-07-26 09:23:33.381559 | 
2026-07-26 09:23:33.381635 | TASK [gather-host-logs : creating directory for system status]
2026-07-26 09:23:33.739306 | instance | ok
2026-07-26 09:23:33.747355 | 
2026-07-26 09:23:33.747441 | TASK [gather-host-logs : Get logs for each host]
2026-07-26 09:23:34.118144 | instance | + systemd-cgls --full --all --no-pager
2026-07-26 09:23:34.129310 | instance | + ip addr
2026-07-26 09:23:34.131161 | instance | + ip route
2026-07-26 09:23:34.134413 | instance | + lsblk
2026-07-26 09:23:34.137231 | instance | + mount
2026-07-26 09:23:34.139694 | instance | + docker images
2026-07-26 09:23:34.140193 | instance | /bin/bash: line 7: docker: command not found
2026-07-26 09:23:34.140472 | instance | + brctl show
2026-07-26 09:23:34.140928 | instance | /bin/bash: line 8: brctl: command not found
2026-07-26 09:23:34.141078 | instance | + ps aux --sort=-%mem
2026-07-26 09:23:34.151744 | instance | + dpkg -l
2026-07-26 09:23:34.157350 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-07-26 09:23:34.157678 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-07-26 09:23:34.157722 | instance | /bin/bash: line 11: docker: command not found
2026-07-26 09:23:34.158051 | instance | + '[' '!' -z '' ']'
2026-07-26 09:23:34.281329 | instance | ok: Runtime: 0:00:00.046462
2026-07-26 09:23:34.287689 | 
2026-07-26 09:23:34.287756 | TASK [gather-host-logs : Downloads logs to executor]
2026-07-26 09:23:34.904053 | instance | ERROR
2026-07-26 09:23:34.904270 | instance | {
2026-07-26 09:23:34.904303 | instance |   "msg": "Warning: Permanently added '199.204.45.227' (ED25519) to the list of known hosts.\r\nrsync: [generator] chown \"/var/lib/zuul/builds/db0579f03f654c52b51d1a11b17eb0b1/work/logs/instance/system\" failed: Invalid argument (22)\nrsync: [receiver] chown \"/var/lib/zuul/builds/db0579f03f654c52b51d1a11b17eb0b1/work/logs/instance/system/.ci-plan.json.ki2B0E\" 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 09:23:34.904337 | instance |   "rc": 23
2026-07-26 09:23:34.904358 | instance | }
2026-07-26 09:23:34.904386 | instance | ERROR: Ignoring Errors
2026-07-26 09:23:34.913147 | 
2026-07-26 09:23:34.913216 | LOOP [helm-release-status : creating directory for helm release status]
2026-07-26 09:23:35.111212 | instance | changed: "values"
2026-07-26 09:23:35.277244 | instance | changed: "releases"
2026-07-26 09:23:35.288392 | 
2026-07-26 09:23:35.288518 | TASK [helm-release-status : Gather get release status for helm charts]
2026-07-26 09:23:35.489183 | instance | /bin/bash: line 3: kubectl: command not found
2026-07-26 09:23:35.822844 | instance | ok: Runtime: 0:00:00.005991
2026-07-26 09:23:35.829170 | 
2026-07-26 09:23:35.829236 | TASK [helm-release-status : Downloads logs to executor]
2026-07-26 09:23:36.325691 | instance | changed:
2026-07-26 09:23:36.325905 | instance | cd+++++++++ helm/
2026-07-26 09:23:36.325934 | instance | cd+++++++++ helm/releases/
2026-07-26 09:23:36.325957 | instance | cd+++++++++ helm/values/
2026-07-26 09:23:36.339972 | 
2026-07-26 09:23:36.340057 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-07-26 09:23:36.528246 | instance | changed
2026-07-26 09:23:36.535678 | 
2026-07-26 09:23:36.535785 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-07-26 09:23:36.818914 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-26 09:23:36.819210 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-26 09:23:36.824225 | instance | environment: line 1: kubectl: command not found
2026-07-26 09:23:36.825635 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-26 09:23:36.826729 | instance | environment: line 1: kubectl: command not found
2026-07-26 09:23:36.827652 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-26 09:23:36.828277 | instance | environment: line 1: kubectl: command not found
2026-07-26 09:23:36.830055 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-26 09:23:36.830201 | instance | environment: line 1: kubectl: command not found
2026-07-26 09:23:36.831058 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-26 09:23:36.832773 | instance | environment: line 1: kubectl: command not found
2026-07-26 09:23:36.833563 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-26 09:23:37.124379 | instance | ok: Runtime: 0:00:00.024442
2026-07-26 09:23:37.131506 | 
2026-07-26 09:23:37.131594 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-07-26 09:23:37.336746 | instance | changed
2026-07-26 09:23:37.343948 | 
2026-07-26 09:23:37.344047 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-07-26 09:23:37.555258 | instance | environment: line 5: kubectl: command not found
2026-07-26 09:23:37.555757 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-26 09:23:37.555917 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-26 09:23:37.556184 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-26 09:23:37.878361 | instance | ok: Runtime: 0:00:00.009062
2026-07-26 09:23:37.885677 | 
2026-07-26 09:23:37.885742 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-07-26 09:23:38.382221 | instance | changed:
2026-07-26 09:23:38.382427 | instance | cd+++++++++ objects/
2026-07-26 09:23:38.382469 | instance | cd+++++++++ objects/cluster/
2026-07-26 09:23:38.382504 | instance | cd+++++++++ objects/namespaced/
2026-07-26 09:23:38.394300 | 
2026-07-26 09:23:38.394364 | TASK [gather-pod-logs : creating directory for pod logs]
2026-07-26 09:23:38.589274 | instance | changed
2026-07-26 09:23:38.598094 | 
2026-07-26 09:23:38.598246 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-07-26 09:23:38.796099 | instance | changed
2026-07-26 09:23:38.802113 | 
2026-07-26 09:23:38.802185 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-07-26 09:23:39.016559 | instance | environment: line 3: kubectl: command not found
2026-07-26 09:23:39.340874 | instance | ok: Runtime: 0:00:00.009370
2026-07-26 09:23:39.347601 | 
2026-07-26 09:23:39.347687 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-07-26 09:23:39.858051 | instance | changed:
2026-07-26 09:23:39.858346 | instance | cd+++++++++ pod-logs/
2026-07-26 09:23:39.858394 | instance | cd+++++++++ pod-logs/failed-pods/
2026-07-26 09:23:39.871038 | 
2026-07-26 09:23:39.871144 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-07-26 09:23:40.077051 | instance | changed
2026-07-26 09:23:40.085328 | 
2026-07-26 09:23:40.085430 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-07-26 09:23:40.304706 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-26 09:23:40.626455 | instance | ok: Runtime: 0:00:00.035553
2026-07-26 09:23:40.631955 | 
2026-07-26 09:23:40.632020 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-07-26 09:23:40.838278 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-26 09:23:40.869805 | instance | ceph-mgr endpoints:
2026-07-26 09:23:41.164626 | instance | ok: Runtime: 0:00:00.038462
2026-07-26 09:23:41.172151 | 
2026-07-26 09:23:41.172243 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-07-26 09:23:41.378941 | instance | /bin/bash: line 4: kubectl: command not found
2026-07-26 09:23:41.710440 | instance | ok: Runtime: 0:00:00.034969
2026-07-26 09:23:41.717620 | 
2026-07-26 09:23:41.717726 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-07-26 09:23:42.200003 | instance | changed: cd+++++++++ prometheus/
2026-07-26 09:23:42.212277 | 
2026-07-26 09:23:42.428575 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-07-26 09:23:42.622722 | instance | changed
2026-07-26 09:23:42.629456 | 
2026-07-26 09:23:42.629533 | TASK [gather-selenium-data : Get selenium data]
2026-07-26 09:23:42.879740 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-07-26 09:23:42.881299 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-07-26 09:23:43.201387 | instance | ERROR
2026-07-26 09:23:43.201740 | instance | {
2026-07-26 09:23:43.201813 | instance |   "delta": "0:00:00.006773",
2026-07-26 09:23:43.201864 | instance |   "end": "2026-07-26 09:23:42.881661",
2026-07-26 09:23:43.201906 | instance |   "msg": "non-zero return code",
2026-07-26 09:23:43.201949 | instance |   "rc": 1,
2026-07-26 09:23:43.201991 | instance |   "start": "2026-07-26 09:23:42.874888"
2026-07-26 09:23:43.202034 | instance | }
2026-07-26 09:23:43.202090 | instance | ERROR: Ignoring Errors
2026-07-26 09:23:43.208257 | 
2026-07-26 09:23:43.208330 | TASK [gather-selenium-data : Downloads logs to executor]
2026-07-26 09:23:43.692995 | instance | changed: cd+++++++++ selenium/
2026-07-26 09:23:43.702051 | 
2026-07-26 09:23:43.702106 | PLAY RECAP
2026-07-26 09:23:43.702158 | instance | ok: 23 changed: 21 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-07-26 09:23:43.702180 | 
2026-07-26 09:23:43.844669 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-26 09:23:43.847096 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-26 09:23:44.449449 | 
2026-07-26 09:23:44.750108 | PLAY [all]
2026-07-26 09:23:44.767521 | 
2026-07-26 09:23:44.767607 | TASK [fetch-output : Set log path for multiple nodes]
2026-07-26 09:23:44.818386 | instance | skipping: Conditional result was False
2026-07-26 09:23:44.827391 | 
2026-07-26 09:23:44.827502 | TASK [fetch-output : Set log path for single node]
2026-07-26 09:23:44.872967 | instance | ok
2026-07-26 09:23:44.877720 | 
2026-07-26 09:23:44.877788 | LOOP [fetch-output : Ensure local output dirs]
2026-07-26 09:23:45.247568 | instance -> localhost | ok: "/var/lib/zuul/builds/db0579f03f654c52b51d1a11b17eb0b1/work/logs"
2026-07-26 09:23:45.464384 | instance -> localhost | changed: "/var/lib/zuul/builds/db0579f03f654c52b51d1a11b17eb0b1/work/artifacts"
2026-07-26 09:23:45.665545 | instance -> localhost | changed: "/var/lib/zuul/builds/db0579f03f654c52b51d1a11b17eb0b1/work/docs"
2026-07-26 09:23:45.684178 | 
2026-07-26 09:23:45.684341 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-07-26 09:23:46.320686 | instance | changed: .d..t...... ./
2026-07-26 09:23:46.320904 | instance | changed: All items complete
2026-07-26 09:23:46.320930 | 
2026-07-26 09:23:46.765206 | instance | changed: .d..t...... ./
2026-07-26 09:23:47.215842 | instance | changed: .d..t...... ./
2026-07-26 09:23:47.236895 | 
2026-07-26 09:23:47.237046 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-07-26 09:23:47.816321 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.006960
2026-07-26 09:23:48.051755 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008087
2026-07-26 09:23:48.067046 | 
2026-07-26 09:23:48.067146 | PLAY [all]
2026-07-26 09:23:48.072890 | 
2026-07-26 09:23:48.072956 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-07-26 09:23:48.536503 | instance | changed
2026-07-26 09:23:48.617240 | 
2026-07-26 09:23:48.617301 | PLAY RECAP
2026-07-26 09:23:48.617352 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-26 09:23:48.617373 | 
2026-07-26 09:23:48.742253 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-26 09:23:48.747069 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-07-26 09:23:49.398455 | 
2026-07-26 09:23:49.398587 | PLAY [localhost]
2026-07-26 09:23:49.409250 | 
2026-07-26 09:23:49.409320 | TASK [Generate Zuul manifest]
2026-07-26 09:23:49.440664 | localhost | ok
2026-07-26 09:23:49.456519 | 
2026-07-26 09:23:49.456595 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-07-26 09:23:50.196244 | localhost | changed
2026-07-26 09:23:50.207755 | 
2026-07-26 09:23:50.208288 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-07-26 09:23:50.371949 | localhost | ok
2026-07-26 09:23:50.381543 | 
2026-07-26 09:23:50.381606 | TASK [Upload logs]
2026-07-26 09:23:50.404003 | localhost | ok
2026-07-26 09:23:50.462108 | 
2026-07-26 09:23:50.462234 | TASK [Set zuul-log-path fact]
2026-07-26 09:23:50.487936 | localhost | ok
2026-07-26 09:23:50.498791 | 
2026-07-26 09:23:50.498860 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-26 09:23:50.532264 | localhost | ok
2026-07-26 09:23:50.541340 | 
2026-07-26 09:23:50.541406 | TASK [upload-logs : Create log directories]
2026-07-26 09:23:51.042241 | localhost | changed
2026-07-26 09:23:51.047115 | 
2026-07-26 09:23:51.047185 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-07-26 09:23:51.479613 | localhost -> localhost | ok: Runtime: 0:00:00.006854
2026-07-26 09:23:51.517881 | 
2026-07-26 09:23:51.518033 | TASK [upload-logs : Upload logs to log server]
2026-07-26 09:23:51.977948 | localhost | Output suppressed because no_log was given
2026-07-26 09:23:51.982880 | 
2026-07-26 09:23:51.982972 | LOOP [upload-logs : Compress console log and json output]
2026-07-26 09:23:52.030176 | localhost | skipping: Conditional result was False
2026-07-26 09:23:52.062931 | localhost | skipping: Conditional result was False
2026-07-26 09:23:52.076430 | 
2026-07-26 09:23:52.076579 | LOOP [upload-logs : Upload compressed console log and json output]
2026-07-26 09:23:52.121454 | localhost | skipping: Conditional result was False
2026-07-26 09:23:52.121838 | 
2026-07-26 09:23:52.125597 | localhost | skipping: Conditional result was False
2026-07-26 09:23:52.140655 | 
2026-07-26 09:23:52.140818 | LOOP [upload-logs : Upload console log and json output]