2026-07-29 07:44:55.075681 | Job console starting
2026-07-29 07:44:55.087120 | Updating git repos
2026-07-29 07:44:56.032192 | Cloning repos into workspace
2026-07-29 07:46:29.029260 | Restoring repo states
2026-07-29 07:46:37.952321 | Merging changes
2026-07-29 07:46:57.661270 | Checking out repos
2026-07-29 07:47:50.210913 | Preparing playbooks
2026-07-29 07:49:51.971210 | Running Ansible setup
2026-07-29 07:50:02.396198 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-29 07:50:03.077949 | 
2026-07-29 07:50:03.078090 | PLAY [localhost]
2026-07-29 07:50:03.086543 | 
2026-07-29 07:50:03.086668 | TASK [Gathering Facts]
2026-07-29 07:50:06.096401 | localhost | ok
2026-07-29 07:50:06.107517 | 
2026-07-29 07:50:06.107642 | TASK [Setup log path fact]
2026-07-29 07:50:06.129357 | localhost | ok
2026-07-29 07:50:06.143675 | 
2026-07-29 07:50:06.143871 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-29 07:50:06.174906 | localhost | ok
2026-07-29 07:50:06.184631 | 
2026-07-29 07:50:06.184714 | TASK [emit-job-header : Print job information]
2026-07-29 07:50:06.226616 | # Job Information
2026-07-29 07:50:06.226836 | Ansible Version: 2.16.18
2026-07-29 07:50:06.226884 | Job: atmosphere-molecule-csi-rbd-selective
2026-07-29 07:50:06.226916 | Pipeline: check
2026-07-29 07:50:06.226945 | Executor: 2d72f0692154
2026-07-29 07:50:06.226973 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4165
2026-07-29 07:50:06.227005 | Event ID: a6013f70-8b20-11f1-956d-0d6c9cab974f
2026-07-29 07:50:06.231215 | 
2026-07-29 07:50:06.231306 | LOOP [emit-job-header : Print node information]
2026-07-29 07:50:06.331935 | localhost | ok:
2026-07-29 07:50:06.332098 | localhost | # Node Information
2026-07-29 07:50:06.332127 | localhost | Inventory Hostname: instance
2026-07-29 07:50:06.332150 | localhost | Hostname: np0000187365
2026-07-29 07:50:06.332170 | localhost | Username: zuul
2026-07-29 07:50:06.332194 | localhost | Distro: Ubuntu 22.04
2026-07-29 07:50:06.332214 | localhost | Provider: yul1
2026-07-29 07:50:06.332234 | localhost | Region: ca-ymq-1
2026-07-29 07:50:06.332253 | localhost | Label: ubuntu-jammy
2026-07-29 07:50:06.332272 | localhost | Product Name: OpenStack Nova
2026-07-29 07:50:06.332291 | localhost | Interface IP: 199.19.213.203
2026-07-29 07:50:06.350237 | 
2026-07-29 07:50:06.350353 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-07-29 07:50:06.965497 | localhost -> localhost | changed
2026-07-29 07:50:06.974388 | 
2026-07-29 07:50:06.974488 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-07-29 07:50:08.412224 | localhost -> localhost | changed
2026-07-29 07:50:08.425155 | 
2026-07-29 07:50:08.425254 | PLAY [all]
2026-07-29 07:50:08.433214 | 
2026-07-29 07:50:08.433282 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-07-29 07:50:08.864988 | instance -> localhost | ok
2026-07-29 07:50:08.877717 | 
2026-07-29 07:50:08.877822 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-07-29 07:50:08.947090 | instance | ok
2026-07-29 07:50:08.964341 | instance | included: /var/lib/zuul/builds/b4ea3e868c754b13b269666998f697c1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-07-29 07:50:08.972673 | 
2026-07-29 07:50:08.972763 | TASK [add-build-sshkey : Create Temp SSH key]
2026-07-29 07:50:12.073971 | instance -> localhost | Generating public/private rsa key pair.
2026-07-29 07:50:12.074187 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/b4ea3e868c754b13b269666998f697c1/work/b4ea3e868c754b13b269666998f697c1_id_rsa
2026-07-29 07:50:12.074230 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/b4ea3e868c754b13b269666998f697c1/work/b4ea3e868c754b13b269666998f697c1_id_rsa.pub
2026-07-29 07:50:12.074266 | instance -> localhost | The key fingerprint is:
2026-07-29 07:50:12.074309 | instance -> localhost | SHA256:k2s+t5Ynzww1ePDu01ssd9EJu6jv2JQSvLzqnJQYW34 zuul-build-sshkey
2026-07-29 07:50:12.074356 | instance -> localhost | The key's randomart image is:
2026-07-29 07:50:12.074387 | instance -> localhost | +---[RSA 3072]----+
2026-07-29 07:50:12.074423 | instance -> localhost | |                 |
2026-07-29 07:50:12.074573 | instance -> localhost | |                 |
2026-07-29 07:50:12.074616 | instance -> localhost | |          .  .   |
2026-07-29 07:50:12.074646 | instance -> localhost | |        .. +  o o|
2026-07-29 07:50:12.074675 | instance -> localhost | |     . .So. =. o.|
2026-07-29 07:50:12.074705 | instance -> localhost | |      * ooo+o....|
2026-07-29 07:50:12.074763 | instance -> localhost | |     o +oE.=.o. =|
2026-07-29 07:50:12.074795 | instance -> localhost | |      oo+ %=o .+.|
2026-07-29 07:50:12.074835 | instance -> localhost | |      .=oB+O=... |
2026-07-29 07:50:12.074868 | instance -> localhost | +----[SHA256]-----+
2026-07-29 07:50:12.074946 | instance -> localhost | ok: Runtime: 0:00:00.959763
2026-07-29 07:50:12.082321 | 
2026-07-29 07:50:12.082396 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-07-29 07:50:12.117637 | instance | ok
2026-07-29 07:50:12.129437 | instance | included: /var/lib/zuul/builds/b4ea3e868c754b13b269666998f697c1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-07-29 07:50:12.137542 | 
2026-07-29 07:50:12.137614 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-07-29 07:50:12.163124 | instance | skipping: Conditional result was False
2026-07-29 07:50:12.172606 | 
2026-07-29 07:50:12.172747 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-07-29 07:50:12.624511 | instance | changed
2026-07-29 07:50:12.629998 | 
2026-07-29 07:50:12.630068 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-07-29 07:50:12.806631 | instance | ok
2026-07-29 07:50:12.813872 | 
2026-07-29 07:50:12.813975 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-07-29 07:50:13.279130 | instance | changed
2026-07-29 07:50:13.286643 | 
2026-07-29 07:50:13.286712 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-07-29 07:50:13.761908 | instance | changed
2026-07-29 07:50:13.766910 | 
2026-07-29 07:50:13.766977 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-07-29 07:50:13.791906 | instance | skipping: Conditional result was False
2026-07-29 07:50:13.804581 | 
2026-07-29 07:50:13.804717 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-07-29 07:50:15.353785 | instance -> localhost | changed
2026-07-29 07:50:15.367992 | 
2026-07-29 07:50:15.368076 | TASK [add-build-sshkey : Add back temp key]
2026-07-29 07:50:15.890451 | instance -> localhost | Identity added: /var/lib/zuul/builds/b4ea3e868c754b13b269666998f697c1/work/b4ea3e868c754b13b269666998f697c1_id_rsa (zuul-build-sshkey)
2026-07-29 07:50:15.890646 | instance -> localhost | ok: Runtime: 0:00:00.015030
2026-07-29 07:50:15.895647 | 
2026-07-29 07:50:15.895709 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-07-29 07:50:16.207429 | instance | ok
2026-07-29 07:50:16.212755 | 
2026-07-29 07:50:16.212824 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-07-29 07:50:16.236433 | instance | skipping: Conditional result was False
2026-07-29 07:50:16.251206 | 
2026-07-29 07:50:16.251275 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-07-29 07:50:16.574042 | instance | ok
2026-07-29 07:50:16.623621 | 
2026-07-29 07:50:16.623744 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-07-29 07:50:34.617991 | instance | Output suppressed because no_log was given
2026-07-29 07:50:34.638715 | 
2026-07-29 07:50:34.638880 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-07-29 07:50:34.835335 | instance | ok: "logs"
2026-07-29 07:50:34.835633 | instance | ok: All items complete
2026-07-29 07:50:34.835673 | 
2026-07-29 07:50:35.010978 | instance | ok: "artifacts"
2026-07-29 07:50:35.178988 | instance | ok: "docs"
2026-07-29 07:50:35.193881 | 
2026-07-29 07:50:35.194092 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-07-29 07:50:35.390833 | instance | changed: "logs"
2026-07-29 07:50:35.547554 | instance | changed: "artifacts"
2026-07-29 07:50:35.720087 | instance | changed: "docs"
2026-07-29 07:50:36.440424 | 
2026-07-29 07:50:36.440555 | PLAY RECAP
2026-07-29 07:50:36.440619 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-07-29 07:50:36.440655 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-29 07:50:36.440682 | 
2026-07-29 07:50:36.860519 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-29 07:50:36.865058 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-29 07:50:37.517097 | 
2026-07-29 07:50:37.517219 | PLAY [all]
2026-07-29 07:50:37.528167 | 
2026-07-29 07:50:37.528250 | TASK [setup-uv : Extract archive]
2026-07-29 07:50:40.125947 | instance | changed
2026-07-29 07:50:40.384284 | 
2026-07-29 07:50:40.384468 | TASK [setup-uv : Print version]
2026-07-29 07:50:40.734041 | instance | uv 0.8.13
2026-07-29 07:50:40.925734 | instance | ok: Runtime: 0:00:00.011875
2026-07-29 07:50:40.937631 | 
2026-07-29 07:50:40.937769 | TASK [Install Ansible collection siblings]
2026-07-29 07:50:49.537178 | instance | changed:
2026-07-29 07:50:49.537579 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-07-29 07:50:49.537622 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-07-29 07:50:49.537653 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-07-29 07:50:49.537680 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-07-29 07:50:49.537706 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-07-29 07:50:49.537739 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-07-29 07:50:49.537768 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-07-29 07:50:49.537798 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-07-29 07:50:49.537825 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-07-29 07:50:49.537852 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-07-29 07:50:49.537878 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-07-29 07:50:49.537904 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-07-29 07:50:49.537930 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-07-29 07:50:49.537956 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-07-29 07:50:49.537982 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0
2026-07-29 07:50:49.538009 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-07-29 07:50:49.538035 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-07-29 07:50:49.538060 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-07-29 07:50:49.538085 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-07-29 07:50:49.538110 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-07-29 07:50:49.538135 | instance | Resolved vexxhost.ceph to v4.1.0 for >=3.2.0
2026-07-29 07:50:49.538160 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-07-29 07:50:49.538187 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-29 07:50:49.538235 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-07-29 07:50:49.538261 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0,>=6.0.0
2026-07-29 07:50:49.538287 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-29 07:50:49.538311 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-29 07:50:49.538337 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-29 07:50:49.538362 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-07-29 07:50:49.538387 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-07-29 07:50:49.538412 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-29 07:50:49.538438 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-29 07:50:49.538463 | instance | Checked out ansible.netcommon to 1.2.0
2026-07-29 07:50:49.538489 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-07-29 07:50:49.538524 | instance | Starting galaxy collection install process
2026-07-29 07:50:49.538550 | instance | Process install dependency map
2026-07-29 07:50:49.538575 | instance | Starting collection install process
2026-07-29 07:50:49.538600 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-07-29 07:50:49.538625 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-07-29 07:50:49.538650 | instance | ansible.netcommon:* was installed successfully
2026-07-29 07:50:49.538674 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-07-29 07:50:49.538698 | instance | Creating virtual environment at: .venv
2026-07-29 07:50:49.538723 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-29 07:50:49.538748 | instance | Downloading cryptography (4.2MiB)
2026-07-29 07:50:49.538773 | instance | Downloading pygments (1.2MiB)
2026-07-29 07:50:49.538798 | instance | Downloading openstacksdk (1.7MiB)
2026-07-29 07:50:49.538823 | instance | Downloading rjsonnet (1.2MiB)
2026-07-29 07:50:49.538848 | instance | Downloading ansible-core (2.1MiB)
2026-07-29 07:50:49.538873 | instance | Downloading kubernetes (1.9MiB)
2026-07-29 07:50:49.538898 | instance | Downloading netaddr (2.2MiB)
2026-07-29 07:50:49.538924 | instance | Downloading setuptools (1.1MiB)
2026-07-29 07:50:49.538948 | instance | Downloading pydantic-core (2.0MiB)
2026-07-29 07:50:49.538973 | instance |  Downloading rjsonnet
2026-07-29 07:50:49.539001 | instance |    Building pyperclip==1.9.0
2026-07-29 07:50:49.539026 | instance |  Downloading pydantic-core
2026-07-29 07:50:49.539052 | instance |  Downloading netaddr
2026-07-29 07:50:49.539077 | instance |  Downloading pygments
2026-07-29 07:50:49.539102 | instance |  Downloading cryptography
2026-07-29 07:50:49.539126 | instance |  Downloading setuptools
2026-07-29 07:50:49.539151 | instance |  Downloading kubernetes
2026-07-29 07:50:49.539176 | instance |  Downloading ansible-core
2026-07-29 07:50:49.539201 | instance |  Downloading openstacksdk
2026-07-29 07:50:49.539225 | instance |       Built pyperclip==1.9.0
2026-07-29 07:50:49.539259 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-29 07:50:49.539285 | instance | Installed 87 packages in 46ms
2026-07-29 07:50:49.539309 | instance | Checked out ansible.posix to 1.6.0
2026-07-29 07:50:49.539333 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-07-29 07:50:49.539358 | instance | Starting galaxy collection install process
2026-07-29 07:50:49.539382 | instance | Process install dependency map
2026-07-29 07:50:49.539413 | instance | Starting collection install process
2026-07-29 07:50:49.539438 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-07-29 07:50:49.539463 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-07-29 07:50:49.539487 | instance | ansible.posix:1.6.0 was installed successfully
2026-07-29 07:50:49.539513 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 07:50:49.539538 | instance | 2.17.12
2026-07-29 07:50:49.539562 | instance | Checked out ansible.utils to v6.0.3
2026-07-29 07:50:49.539587 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-07-29 07:50:49.539610 | instance | Starting galaxy collection install process
2026-07-29 07:50:49.539633 | instance | Process install dependency map
2026-07-29 07:50:49.539657 | instance | Starting collection install process
2026-07-29 07:50:49.539682 | instance | Installing 'ansible.utils:6.0.3' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-07-29 07:50:49.539707 | instance | Created collection for ansible.utils:6.0.3 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-07-29 07:50:49.539732 | instance | ansible.utils:6.0.3 was installed successfully
2026-07-29 07:50:49.539757 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 07:50:49.539782 | instance | 2.17.12
2026-07-29 07:50:49.539810 | instance | Checked out atmosphere.common to v0.7.0
2026-07-29 07:50:49.539837 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-07-29 07:50:49.539862 | instance | Starting galaxy collection install process
2026-07-29 07:50:49.539887 | instance | Process install dependency map
2026-07-29 07:50:49.539911 | instance | Starting collection install process
2026-07-29 07:50:49.539936 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-07-29 07:50:49.539960 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-07-29 07:50:49.539985 | instance | atmosphere.common:0.7.0 was installed successfully
2026-07-29 07:50:49.540009 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 07:50:49.540034 | instance | 2.17.12
2026-07-29 07:50:49.540058 | instance | Checked out community.crypto to 2.2.3
2026-07-29 07:50:49.540082 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto
2026-07-29 07:50:49.540107 | instance | Starting galaxy collection install process
2026-07-29 07:50:49.540131 | instance | Process install dependency map
2026-07-29 07:50:49.540155 | instance | Starting collection install process
2026-07-29 07:50:49.540179 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-07-29 07:50:49.540203 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-07-29 07:50:49.540228 | instance | community.crypto:2.2.3 was installed successfully
2026-07-29 07:50:49.540252 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 07:50:49.540276 | instance | 2.17.12
2026-07-29 07:50:49.540300 | instance | Checked out community.general to 7.3.0
2026-07-29 07:50:49.540324 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general
2026-07-29 07:50:49.540354 | instance | Starting galaxy collection install process
2026-07-29 07:50:49.540379 | instance | Process install dependency map
2026-07-29 07:50:49.540403 | instance | Starting collection install process
2026-07-29 07:50:49.540427 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-07-29 07:50:49.540451 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-07-29 07:50:49.540474 | instance | community.general:7.3.0 was installed successfully
2026-07-29 07:50:49.540499 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 07:50:49.540523 | instance | 2.17.12
2026-07-29 07:50:49.540548 | instance | Checked out community.mysql to 3.6.0
2026-07-29 07:50:49.540575 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql
2026-07-29 07:50:49.540601 | instance | Starting galaxy collection install process
2026-07-29 07:50:49.540779 | instance | Process install dependency map
2026-07-29 07:50:49.540865 | instance | Starting collection install process
2026-07-29 07:50:49.540894 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-07-29 07:50:49.540920 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-07-29 07:50:49.540945 | instance | community.mysql:3.6.0 was installed successfully
2026-07-29 07:50:49.540989 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 07:50:49.541016 | instance | 2.17.12
2026-07-29 07:50:49.541041 | instance | Checked out kubernetes.core to 2.4.0
2026-07-29 07:50:49.541067 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-07-29 07:50:49.541092 | instance | Starting galaxy collection install process
2026-07-29 07:50:49.541117 | instance | Process install dependency map
2026-07-29 07:50:49.541142 | instance | Starting collection install process
2026-07-29 07:50:49.541167 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-07-29 07:50:49.541192 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-07-29 07:50:49.541217 | instance | kubernetes.core:2.4.0 was installed successfully
2026-07-29 07:50:49.541242 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 07:50:49.541267 | instance | 2.17.12
2026-07-29 07:50:49.541291 | instance | Checked out openstack.cloud to 2.6.0
2026-07-29 07:50:49.541315 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-07-29 07:50:49.541342 | instance | Starting galaxy collection install process
2026-07-29 07:50:49.541367 | instance | Process install dependency map
2026-07-29 07:50:49.541392 | instance | Starting collection install process
2026-07-29 07:50:49.541416 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-07-29 07:50:49.541441 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-07-29 07:50:49.541465 | instance | openstack.cloud:2.6.0 was installed successfully
2026-07-29 07:50:49.541489 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 07:50:49.541513 | instance | 2.17.12
2026-07-29 07:50:49.541545 | instance | Checked out vexxhost.ceph to v4.1.0
2026-07-29 07:50:49.541571 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-07-29 07:50:49.541596 | instance | Starting galaxy collection install process
2026-07-29 07:50:49.541620 | instance | Process install dependency map
2026-07-29 07:50:49.541645 | instance | Starting collection install process
2026-07-29 07:50:49.541669 | instance | Installing 'vexxhost.ceph:4.1.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-07-29 07:50:49.541693 | instance | Created collection for vexxhost.ceph:4.1.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-07-29 07:50:49.541718 | instance | vexxhost.ceph:4.1.0 was installed successfully
2026-07-29 07:50:49.541742 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 07:50:49.541766 | instance | 2.17.12
2026-07-29 07:50:49.541791 | instance | Checked out vexxhost.containers to v1.6.8
2026-07-29 07:50:49.541819 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-07-29 07:50:49.541844 | instance | Starting galaxy collection install process
2026-07-29 07:50:49.541868 | instance | Process install dependency map
2026-07-29 07:50:49.541893 | instance | Starting collection install process
2026-07-29 07:50:49.541917 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-07-29 07:50:49.541941 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-07-29 07:50:49.541966 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-07-29 07:50:49.541990 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 07:50:49.542015 | instance | 2.17.12
2026-07-29 07:50:49.542039 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-07-29 07:50:49.542063 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-07-29 07:50:49.542087 | instance | Starting galaxy collection install process
2026-07-29 07:50:49.542112 | instance | Process install dependency map
2026-07-29 07:50:49.542136 | instance | Starting collection install process
2026-07-29 07:50:49.542160 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-07-29 07:50:49.542184 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-07-29 07:50:49.542209 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-07-29 07:50:49.542233 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 07:50:49.542257 | instance | 2.17.12
2026-07-29 07:50:49.551215 | 
2026-07-29 07:50:49.551294 | PLAY RECAP
2026-07-29 07:50:49.551360 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-29 07:50:49.551390 | 
2026-07-29 07:50:49.774657 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-29 07:50:49.777316 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-29 07:50:50.368733 | 
2026-07-29 07:50:50.368844 | PLAY [all]
2026-07-29 07:50:50.381592 | 
2026-07-29 07:50:50.381716 | TASK [Install "jq" for log collection]
2026-07-29 07:51:01.730593 | instance | changed
2026-07-29 07:51:02.332819 | 
2026-07-29 07:51:02.333874 | PLAY RECAP
2026-07-29 07:51:02.334272 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-29 07:51:02.334400 | 
2026-07-29 07:51:02.484070 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-29 07:51:02.486942 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-29 07:51:03.177112 | 
2026-07-29 07:51:03.177565 | PLAY [Prepare selective Molecule plan]
2026-07-29 07:51:03.189084 | 
2026-07-29 07:51:03.189164 | TASK [Generate selective Molecule plan]
2026-07-29 07:51:03.751369 | instance | ok: Runtime: 0:00:00.166764
2026-07-29 07:51:06.195948 | 
2026-07-29 07:51:06.196132 | TASK [Render selective Molecule plan]
2026-07-29 07:51:06.484991 | instance | Selective Molecule plan: selective
2026-07-29 07:51:06.485201 | instance | Changed files:
2026-07-29 07:51:06.485216 | instance |   M roles/cinder/README.md
2026-07-29 07:51:06.485227 | instance | Targets: cinder
2026-07-29 07:51:06.485237 | instance | Job decisions:
2026-07-29 07:51:06.485250 | instance |   RUN  aio-openvswitch: selected by changed components
2026-07-29 07:51:06.485266 | instance |        components: ceph, ceph-provisioners, cert-manager, cinder, cluster-issuer, csi, ingress-nginx, keycloak, keystone, kubernetes, memcached, percona-xtradb-cluster, percona-xtradb-cluster-operator, rabbitmq-cluster-operator
2026-07-29 07:51:06.485277 | instance |        Tempest include: ^tempest\.api\.volume\.
2026-07-29 07:51:06.485287 | instance |   SKIP aio-ovn: no changed component requires this job
2026-07-29 07:51:06.485297 | instance |   SKIP csi-local-path-provisioner: no changed component requires this job
2026-07-29 07:51:06.485307 | instance |   SKIP csi-rbd: no changed component requires this job
2026-07-29 07:51:06.485344 | instance |   SKIP keycloak: no changed component requires this job
2026-07-29 07:51:06.736534 | instance | ok: Runtime: 0:00:00.097759
2026-07-29 07:51:06.739580 | 
2026-07-29 07:51:06.739634 | PLAY RECAP
2026-07-29 07:51:06.739682 | instance | ok: 2 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-29 07:51:06.739704 | 
2026-07-29 07:51:06.856312 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-29 07:51:06.859078 | RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-29 07:51:07.552414 | 
2026-07-29 07:51:07.552625 | PLAY [Run selected Molecule scenario]
2026-07-29 07:51:07.566180 | 
2026-07-29 07:51:07.566290 | TASK [Read selective Molecule plan]
2026-07-29 07:51:07.908188 | instance | ok
2026-07-29 07:51:07.915447 | 
2026-07-29 07:51:07.915557 | TASK [Resolve this job decision]
2026-07-29 07:51:07.965195 | instance | ok
2026-07-29 07:51:07.972185 | 
2026-07-29 07:51:07.972253 | TASK [Assert this job exists in the plan]
2026-07-29 07:51:08.003760 | instance | ok: All assertions passed
2026-07-29 07:51:08.010382 | 
2026-07-29 07:51:08.010469 | TASK [Resolve selected scenario inputs]
2026-07-29 07:51:08.046713 | instance | ok
2026-07-29 07:51:08.968356 | 
2026-07-29 07:51:08.968479 | TASK [Report this job decision]
2026-07-29 07:51:09.022629 | instance | ok:
2026-07-29 07:51:09.022928 | instance | Selective Molecule job: csi-rbd
2026-07-29 07:51:09.022985 | instance | Decision: SKIP
2026-07-29 07:51:09.023031 | instance | Reason: no changed component requires this job
2026-07-29 07:51:09.023076 | instance | Scenario: csi
2026-07-29 07:51:09.023114 | instance | Targets: (none)
2026-07-29 07:51:09.023155 | instance | Components: (full scenario)
2026-07-29 07:51:09.031359 | 
2026-07-29 07:51:09.031477 | LOOP [Check Molecule inventory directories]
2026-07-29 07:51:09.076517 | instance | skipping: Conditional result was False
2026-07-29 07:51:09.080043 | instance | skipping: Conditional result was False
2026-07-29 07:51:09.083215 | instance | skipping: Conditional result was False
2026-07-29 07:51:09.097774 | 
2026-07-29 07:51:09.097978 | LOOP [Copy Zuul inventory for Molecule]
2026-07-29 07:51:09.144712 | instance | skipping: Conditional result was False
2026-07-29 07:51:09.145317 | 
2026-07-29 07:51:09.146748 | instance | skipping: Conditional result was False
2026-07-29 07:51:09.149732 | instance | skipping: Conditional result was False
2026-07-29 07:51:09.166222 | 
2026-07-29 07:51:09.166427 | LOOP [Use private addresses in Molecule inventory]
2026-07-29 07:51:09.205353 | instance | skipping: Conditional result was False
2026-07-29 07:51:09.205760 | 
2026-07-29 07:51:09.207177 | instance | skipping: Conditional result was False
2026-07-29 07:51:09.211102 | instance | skipping: Conditional result was False
2026-07-29 07:51:09.221605 | 
2026-07-29 07:51:09.221821 | TASK [Prepare Molecule command]
2026-07-29 07:51:09.269099 | instance | skipping: Conditional result was False
2026-07-29 07:51:09.280611 | 
2026-07-29 07:51:09.280806 | TASK [Run standard Molecule lifecycle with progress output]
2026-07-29 07:51:09.328704 | instance | skipping: Conditional result was False
2026-07-29 07:51:09.332827 | 
2026-07-29 07:51:09.332995 | PLAY RECAP
2026-07-29 07:51:09.333102 | instance | ok: 5 changed: 0 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-07-29 07:51:09.333156 | 
2026-07-29 07:51:09.532307 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-29 07:51:09.534646 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-07-29 07:51:10.214582 | 
2026-07-29 07:51:10.214718 | PLAY [Publish selective Molecule plan]
2026-07-29 07:51:10.227313 | 
2026-07-29 07:51:10.227415 | TASK [Check for selective Molecule plan]
2026-07-29 07:51:10.656148 | instance | ok
2026-07-29 07:51:10.663441 | 
2026-07-29 07:51:10.663583 | TASK [Create log directory]
2026-07-29 07:51:11.048228 | instance | changed
2026-07-29 07:51:11.053448 | 
2026-07-29 07:51:11.053512 | TASK [Copy selective Molecule plan to logs]
2026-07-29 07:51:11.514420 | instance | changed
2026-07-29 07:51:11.517395 | 
2026-07-29 07:51:11.517533 | PLAY RECAP
2026-07-29 07:51:11.517632 | instance | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-29 07:51:11.517704 | 
2026-07-29 07:51:11.765520 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-07-29 07:51:11.769459 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-29 07:51:12.422351 | 
2026-07-29 07:51:12.637525 | PLAY [all]
2026-07-29 07:51:12.661499 | 
2026-07-29 07:51:12.661984 | TASK [gather-host-logs : creating directory for system status]
2026-07-29 07:51:13.055471 | instance | changed
2026-07-29 07:51:14.364131 | 
2026-07-29 07:51:14.364321 | TASK [gather-host-logs : Get logs for each host]
2026-07-29 07:51:14.775628 | instance | + systemd-cgls --full --all --no-pager
2026-07-29 07:51:14.786483 | instance | + ip addr
2026-07-29 07:51:14.789537 | instance | + ip route
2026-07-29 07:51:14.792264 | instance | + lsblk
2026-07-29 07:51:14.794443 | instance | + mount
2026-07-29 07:51:14.798800 | instance | + docker images
2026-07-29 07:51:14.799246 | instance | /bin/bash: line 7: docker: command not found
2026-07-29 07:51:14.799450 | instance | + brctl show
2026-07-29 07:51:14.800063 | instance | /bin/bash: line 8: brctl: command not found
2026-07-29 07:51:14.800359 | instance | + ps aux --sort=-%mem
2026-07-29 07:51:14.814247 | instance | + dpkg -l
2026-07-29 07:51:14.824502 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-07-29 07:51:14.824928 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-07-29 07:51:14.824972 | instance | /bin/bash: line 11: docker: command not found
2026-07-29 07:51:14.825302 | instance | + '[' '!' -z '' ']'
2026-07-29 07:51:14.910296 | instance | ok: Runtime: 0:00:00.055348
2026-07-29 07:51:14.919475 | 
2026-07-29 07:51:14.919661 | TASK [gather-host-logs : Downloads logs to executor]
2026-07-29 07:51:15.647295 | instance | changed:
2026-07-29 07:51:15.647474 | instance | created directory /var/lib/zuul/builds/b4ea3e868c754b13b269666998f697c1/work/logs/instance
2026-07-29 07:51:15.647504 | instance | cd+++++++++ system/
2026-07-29 07:51:15.647527 | instance | >f+++++++++ system/brctl-show.txt
2026-07-29 07:51:15.647549 | instance | >f+++++++++ system/docker-images.txt
2026-07-29 07:51:15.647570 | instance | >f+++++++++ system/ip-addr.txt
2026-07-29 07:51:15.647592 | instance | >f+++++++++ system/ip-route.txt
2026-07-29 07:51:15.647613 | instance | >f+++++++++ system/lsblk.txt
2026-07-29 07:51:15.647634 | instance | >f+++++++++ system/mount.txt
2026-07-29 07:51:15.647657 | instance | >f+++++++++ system/packages.txt
2026-07-29 07:51:15.647677 | instance | >f+++++++++ system/ps.txt
2026-07-29 07:51:15.647697 | instance | >f+++++++++ system/systemd-cgls.txt
2026-07-29 07:51:15.656868 | 
2026-07-29 07:51:15.656974 | LOOP [helm-release-status : creating directory for helm release status]
2026-07-29 07:51:15.863113 | instance | changed: "values"
2026-07-29 07:51:16.067858 | instance | changed: "releases"
2026-07-29 07:51:16.076226 | 
2026-07-29 07:51:16.076325 | TASK [helm-release-status : Gather get release status for helm charts]
2026-07-29 07:51:16.321364 | instance | /bin/bash: line 3: kubectl: command not found
2026-07-29 07:51:16.617951 | instance | ok: Runtime: 0:00:00.006376
2026-07-29 07:51:16.625967 | 
2026-07-29 07:51:16.626117 | TASK [helm-release-status : Downloads logs to executor]
2026-07-29 07:51:17.162445 | instance | changed:
2026-07-29 07:51:17.162622 | instance | cd+++++++++ helm/
2026-07-29 07:51:17.162650 | instance | cd+++++++++ helm/releases/
2026-07-29 07:51:17.162672 | instance | cd+++++++++ helm/values/
2026-07-29 07:51:17.174000 | 
2026-07-29 07:51:17.174141 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-07-29 07:51:17.369658 | instance | changed
2026-07-29 07:51:17.377253 | 
2026-07-29 07:51:17.377358 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-07-29 07:51:17.666762 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-29 07:51:17.667421 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-29 07:51:17.672606 | instance | environment: line 1: kubectl: command not found
2026-07-29 07:51:17.674351 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-29 07:51:17.674742 | instance | environment: line 1: kubectl: command not found
2026-07-29 07:51:17.675695 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-29 07:51:17.676958 | instance | environment: line 1: kubectl: command not found
2026-07-29 07:51:17.678582 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-29 07:51:17.678784 | instance | environment: line 1: kubectl: command not found
2026-07-29 07:51:17.680204 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-29 07:51:17.681349 | instance | environment: line 1: kubectl: command not found
2026-07-29 07:51:17.682024 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-29 07:51:18.170972 | instance | ok: Runtime: 0:00:00.024722
2026-07-29 07:51:18.177887 | 
2026-07-29 07:51:18.177987 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-07-29 07:51:18.388798 | instance | changed
2026-07-29 07:51:18.395471 | 
2026-07-29 07:51:18.395572 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-07-29 07:51:18.621997 | instance | environment: line 5: kubectl: command not found
2026-07-29 07:51:18.623353 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-29 07:51:18.623389 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-29 07:51:18.623687 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-29 07:51:18.932600 | instance | ok: Runtime: 0:00:00.009758
2026-07-29 07:51:18.939990 | 
2026-07-29 07:51:18.940616 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-07-29 07:51:19.482990 | instance | changed:
2026-07-29 07:51:19.483190 | instance | cd+++++++++ objects/
2026-07-29 07:51:19.483226 | instance | cd+++++++++ objects/cluster/
2026-07-29 07:51:19.483255 | instance | cd+++++++++ objects/namespaced/
2026-07-29 07:51:19.504470 | 
2026-07-29 07:51:19.504602 | TASK [gather-pod-logs : creating directory for pod logs]
2026-07-29 07:51:19.720364 | instance | changed
2026-07-29 07:51:19.728566 | 
2026-07-29 07:51:19.728675 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-07-29 07:51:19.944082 | instance | changed
2026-07-29 07:51:19.965934 | 
2026-07-29 07:51:19.966107 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-07-29 07:51:20.193578 | instance | environment: line 3: kubectl: command not found
2026-07-29 07:51:21.474436 | instance | ok: Runtime: 0:00:00.009046
2026-07-29 07:51:21.480681 | 
2026-07-29 07:51:21.480749 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-07-29 07:51:22.284279 | instance | changed:
2026-07-29 07:51:22.285193 | instance | cd+++++++++ pod-logs/
2026-07-29 07:51:22.285253 | instance | cd+++++++++ pod-logs/failed-pods/
2026-07-29 07:51:22.301395 | 
2026-07-29 07:51:22.301552 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-07-29 07:51:22.506537 | instance | changed
2026-07-29 07:51:22.514030 | 
2026-07-29 07:51:22.514143 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-07-29 07:51:22.726924 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-29 07:51:23.339173 | instance | ok: Runtime: 0:00:00.036603
2026-07-29 07:51:23.345060 | 
2026-07-29 07:51:23.345169 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-07-29 07:51:23.557510 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-29 07:51:23.590732 | instance | ceph-mgr endpoints:
2026-07-29 07:51:25.180091 | instance | ok: Runtime: 0:00:00.039624
2026-07-29 07:51:25.194750 | 
2026-07-29 07:51:25.194882 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-07-29 07:51:25.446671 | instance | /bin/bash: line 4: kubectl: command not found
2026-07-29 07:51:25.755669 | instance | ok: Runtime: 0:00:00.040451
2026-07-29 07:51:25.762708 | 
2026-07-29 07:51:25.762804 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-07-29 07:51:26.272594 | instance | changed: cd+++++++++ prometheus/
2026-07-29 07:51:26.678835 | 
2026-07-29 07:51:26.678952 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-07-29 07:51:26.915951 | instance | changed
2026-07-29 07:51:26.923197 | 
2026-07-29 07:51:26.923291 | TASK [gather-selenium-data : Get selenium data]
2026-07-29 07:51:27.179431 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-07-29 07:51:27.181235 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-07-29 07:51:27.501871 | instance | ERROR
2026-07-29 07:51:27.502188 | instance | {
2026-07-29 07:51:27.502263 | instance |   "delta": "0:00:00.007271",
2026-07-29 07:51:27.502311 | instance |   "end": "2026-07-29 07:51:27.181620",
2026-07-29 07:51:27.502354 | instance |   "msg": "non-zero return code",
2026-07-29 07:51:27.502395 | instance |   "rc": 1,
2026-07-29 07:51:27.502436 | instance |   "start": "2026-07-29 07:51:27.174349"
2026-07-29 07:51:27.502608 | instance | }
2026-07-29 07:51:27.502725 | instance | ERROR: Ignoring Errors
2026-07-29 07:51:27.508235 | 
2026-07-29 07:51:27.508301 | TASK [gather-selenium-data : Downloads logs to executor]
2026-07-29 07:51:28.017534 | instance | changed: cd+++++++++ selenium/
2026-07-29 07:51:28.027407 | 
2026-07-29 07:51:28.027468 | PLAY RECAP
2026-07-29 07:51:28.027512 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-07-29 07:51:28.027534 | 
2026-07-29 07:51:28.163360 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-29 07:51:28.167253 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-29 07:51:28.870015 | 
2026-07-29 07:51:28.897159 | PLAY [all]
2026-07-29 07:51:28.938576 | 
2026-07-29 07:51:28.938966 | TASK [fetch-output : Set log path for multiple nodes]
2026-07-29 07:51:28.973783 | instance | skipping: Conditional result was False
2026-07-29 07:51:28.982524 | 
2026-07-29 07:51:28.982660 | TASK [fetch-output : Set log path for single node]
2026-07-29 07:51:29.025230 | instance | ok
2026-07-29 07:51:29.031669 | 
2026-07-29 07:51:29.031765 | LOOP [fetch-output : Ensure local output dirs]
2026-07-29 07:51:29.555044 | instance -> localhost | ok: "/var/lib/zuul/builds/b4ea3e868c754b13b269666998f697c1/work/logs"
2026-07-29 07:51:29.812250 | instance -> localhost | changed: "/var/lib/zuul/builds/b4ea3e868c754b13b269666998f697c1/work/artifacts"
2026-07-29 07:51:30.020630 | instance -> localhost | changed: "/var/lib/zuul/builds/b4ea3e868c754b13b269666998f697c1/work/docs"
2026-07-29 07:51:30.040699 | 
2026-07-29 07:51:30.040819 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-07-29 07:51:30.771845 | instance | changed: .d..t...... ./
2026-07-29 07:51:30.772172 | instance | changed: All items complete
2026-07-29 07:51:30.772238 | 
2026-07-29 07:51:31.249931 | instance | changed: .d..t...... ./
2026-07-29 07:51:32.196270 | instance | changed: .d..t...... ./
2026-07-29 07:51:32.213808 | 
2026-07-29 07:51:32.213968 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-07-29 07:51:33.224967 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.556110
2026-07-29 07:51:33.459658 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.010498
2026-07-29 07:51:33.480795 | 
2026-07-29 07:51:33.480913 | PLAY [all]
2026-07-29 07:51:33.487142 | 
2026-07-29 07:51:33.487233 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-07-29 07:51:33.900531 | instance | changed
2026-07-29 07:51:33.909558 | 
2026-07-29 07:51:33.909651 | PLAY RECAP
2026-07-29 07:51:33.909699 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-29 07:51:33.909722 | 
2026-07-29 07:51:34.045406 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-29 07:51:34.807141 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-07-29 07:51:35.453451 | 
2026-07-29 07:51:36.367283 | PLAY [localhost]
2026-07-29 07:51:36.385050 | 
2026-07-29 07:51:36.385148 | TASK [Generate Zuul manifest]
2026-07-29 07:51:36.407366 | localhost | ok
2026-07-29 07:51:36.424587 | 
2026-07-29 07:51:36.424681 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-07-29 07:51:36.807794 | localhost | changed
2026-07-29 07:51:37.008622 | 
2026-07-29 07:51:37.008810 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-07-29 07:51:37.100561 | localhost | ok
2026-07-29 07:51:37.114516 | 
2026-07-29 07:51:37.114625 | TASK [Upload logs]
2026-07-29 07:51:37.137684 | localhost | ok
2026-07-29 07:51:37.371607 | 
2026-07-29 07:51:37.372147 | TASK [Set zuul-log-path fact]
2026-07-29 07:51:37.393289 | localhost | ok
2026-07-29 07:51:37.404864 | 
2026-07-29 07:51:37.404943 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-29 07:51:37.437054 | localhost | ok
2026-07-29 07:51:37.448898 | 
2026-07-29 07:51:37.449148 | TASK [upload-logs : Create log directories]
2026-07-29 07:51:37.874234 | localhost | changed
2026-07-29 07:51:37.883917 | 
2026-07-29 07:51:37.884102 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-07-29 07:51:38.838747 | localhost -> localhost | ok: Runtime: 0:00:00.006464
2026-07-29 07:51:38.906315 | 
2026-07-29 07:51:38.906752 | TASK [upload-logs : Upload logs to log server]
2026-07-29 07:51:40.080437 | localhost | Output suppressed because no_log was given
2026-07-29 07:51:40.086021 | 
2026-07-29 07:51:40.086120 | LOOP [upload-logs : Compress console log and json output]
2026-07-29 07:51:40.132637 | localhost | skipping: Conditional result was False
2026-07-29 07:51:40.140777 | localhost | skipping: Conditional result was False
2026-07-29 07:51:40.148391 | 
2026-07-29 07:51:40.148496 | LOOP [upload-logs : Upload compressed console log and json output]
2026-07-29 07:51:40.191959 | localhost | skipping: Conditional result was False
2026-07-29 07:51:40.192247 | 
2026-07-29 07:51:40.196832 | localhost | skipping: Conditional result was False
2026-07-29 07:51:40.213349 | 
2026-07-29 07:51:40.213492 | LOOP [upload-logs : Upload console log and json output]