2026-08-24 15:27:48.031096 | Job console starting 2026-08-24 15:27:48.040677 | Updating git repos 2026-08-24 15:27:50.189210 | Cloning repos into workspace 2026-08-24 15:28:26.041891 | Restoring repo states 2026-08-24 15:28:38.356128 | Merging changes 2026-08-24 15:28:41.429187 | Checking out repos 2026-08-24 15:28:49.897229 | Preparing playbooks 2026-08-24 15:28:53.394568 | Running Ansible setup 2026-08-24 15:28:57.002060 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-08-24 15:28:57.750519 | 2026-08-24 15:28:57.750726 | PLAY [localhost] 2026-08-24 15:28:57.759306 | 2026-08-24 15:28:57.759401 | TASK [Gathering Facts] 2026-08-24 15:28:58.676373 | localhost | ok 2026-08-24 15:28:58.703889 | 2026-08-24 15:28:58.704052 | TASK [Setup log path fact] 2026-08-24 15:28:58.731239 | localhost | ok 2026-08-24 15:28:58.742492 | 2026-08-24 15:28:58.742594 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-24 15:28:58.783733 | localhost | ok 2026-08-24 15:28:58.793911 | 2026-08-24 15:28:58.794069 | TASK [emit-job-header : Print job information] 2026-08-24 15:28:58.833900 | # Job Information 2026-08-24 15:28:58.834099 | Ansible Version: 2.16.18 2026-08-24 15:28:58.834147 | Job: atmosphere-molecule-csi-local-path-provisioner 2026-08-24 15:28:58.834180 | Pipeline: check 2026-08-24 15:28:58.834210 | Executor: 2d72f0692154 2026-08-24 15:28:58.834240 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3864 2026-08-24 15:28:58.834285 | Event ID: 34730ab0-9fce-11f1-9ad9-dbc9c4f82e28 2026-08-24 15:28:58.838199 | 2026-08-24 15:28:58.838277 | LOOP [emit-job-header : Print node information] 2026-08-24 15:28:58.953312 | localhost | ok: 2026-08-24 15:28:58.953482 | localhost | # Node Information 2026-08-24 15:28:58.953518 | localhost | Inventory Hostname: instance 2026-08-24 15:28:58.953547 | localhost | Hostname: np0000190880 2026-08-24 15:28:58.953575 | localhost | Username: zuul 2026-08-24 15:28:58.953603 | localhost | Distro: Ubuntu 22.04 2026-08-24 15:28:58.953624 | localhost | Provider: yul1 2026-08-24 15:28:58.953643 | localhost | Region: ca-ymq-1 2026-08-24 15:28:58.953662 | localhost | Label: ubuntu-jammy 2026-08-24 15:28:58.953680 | localhost | Product Name: OpenStack Nova 2026-08-24 15:28:58.953698 | localhost | Interface IP: 199.19.213.233 2026-08-24 15:28:58.970710 | 2026-08-24 15:28:58.971245 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-24 15:28:59.421326 | localhost -> localhost | changed 2026-08-24 15:28:59.426192 | 2026-08-24 15:28:59.426264 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-24 15:29:00.383519 | localhost -> localhost | changed 2026-08-24 15:29:00.393657 | 2026-08-24 15:29:00.393771 | PLAY [all] 2026-08-24 15:29:00.403337 | 2026-08-24 15:29:00.403419 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-24 15:29:00.903802 | instance -> localhost | ok 2026-08-24 15:29:00.912647 | 2026-08-24 15:29:00.912764 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-24 15:29:00.947099 | instance | ok 2026-08-24 15:29:00.961366 | instance | included: /var/lib/zuul/builds/dbdd049b79334a5a8f5fbb1b40739253/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-24 15:29:00.968660 | 2026-08-24 15:29:00.968795 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-24 15:29:01.880223 | instance -> localhost | Generating public/private rsa key pair. 2026-08-24 15:29:01.880457 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/dbdd049b79334a5a8f5fbb1b40739253/work/dbdd049b79334a5a8f5fbb1b40739253_id_rsa 2026-08-24 15:29:01.880557 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/dbdd049b79334a5a8f5fbb1b40739253/work/dbdd049b79334a5a8f5fbb1b40739253_id_rsa.pub 2026-08-24 15:29:01.880603 | instance -> localhost | The key fingerprint is: 2026-08-24 15:29:01.880630 | instance -> localhost | SHA256:/HxngGg86ldnOdjh/R3kV64RmeCl3Vbor+FlkEr85Hg zuul-build-sshkey 2026-08-24 15:29:01.880779 | instance -> localhost | The key's randomart image is: 2026-08-24 15:29:01.880808 | instance -> localhost | +---[RSA 3072]----+ 2026-08-24 15:29:01.880836 | instance -> localhost | | . | 2026-08-24 15:29:01.880858 | instance -> localhost | | . o .| 2026-08-24 15:29:01.880878 | instance -> localhost | | o * =.| 2026-08-24 15:29:01.880898 | instance -> localhost | | o . .* X.+| 2026-08-24 15:29:01.880918 | instance -> localhost | | S .=.Xo*.| 2026-08-24 15:29:01.880938 | instance -> localhost | | o +o X.Eo*| 2026-08-24 15:29:01.880972 | instance -> localhost | | . .oo.+oO+| 2026-08-24 15:29:01.880995 | instance -> localhost | | . . . o+ o| 2026-08-24 15:29:01.881019 | instance -> localhost | | .. | 2026-08-24 15:29:01.881039 | instance -> localhost | +----[SHA256]-----+ 2026-08-24 15:29:01.881104 | instance -> localhost | ok: Runtime: 0:00:00.400118 2026-08-24 15:29:01.888381 | 2026-08-24 15:29:01.888476 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-24 15:29:01.922854 | instance | ok 2026-08-24 15:29:01.933380 | instance | included: /var/lib/zuul/builds/dbdd049b79334a5a8f5fbb1b40739253/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-24 15:29:01.957542 | 2026-08-24 15:29:01.957996 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-24 15:29:01.983906 | instance | skipping: Conditional result was False 2026-08-24 15:29:01.996034 | 2026-08-24 15:29:01.996191 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-24 15:29:02.466527 | instance | changed 2026-08-24 15:29:02.480316 | 2026-08-24 15:29:02.480462 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-24 15:29:02.666077 | instance | ok 2026-08-24 15:29:02.838286 | 2026-08-24 15:29:02.838421 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-24 15:29:03.347888 | instance | changed 2026-08-24 15:29:03.355845 | 2026-08-24 15:29:03.355969 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-24 15:29:03.827549 | instance | changed 2026-08-24 15:29:03.835448 | 2026-08-24 15:29:03.835558 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-24 15:29:03.859726 | instance | skipping: Conditional result was False 2026-08-24 15:29:03.871692 | 2026-08-24 15:29:03.871798 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-24 15:29:04.494531 | instance -> localhost | changed 2026-08-24 15:29:04.513976 | 2026-08-24 15:29:04.514194 | TASK [add-build-sshkey : Add back temp key] 2026-08-24 15:29:04.890540 | instance -> localhost | Identity added: /var/lib/zuul/builds/dbdd049b79334a5a8f5fbb1b40739253/work/dbdd049b79334a5a8f5fbb1b40739253_id_rsa (zuul-build-sshkey) 2026-08-24 15:29:04.890815 | instance -> localhost | ok: Runtime: 0:00:00.014391 2026-08-24 15:29:04.898017 | 2026-08-24 15:29:04.898167 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-24 15:29:05.277621 | instance | ok 2026-08-24 15:29:05.382508 | 2026-08-24 15:29:05.382700 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-24 15:29:05.417704 | instance | skipping: Conditional result was False 2026-08-24 15:29:05.432195 | 2026-08-24 15:29:05.432877 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-08-24 15:29:05.887468 | instance | ok 2026-08-24 15:29:05.895824 | 2026-08-24 15:29:05.895923 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-08-24 15:29:13.922620 | instance | Output suppressed because no_log was given 2026-08-24 15:29:13.936347 | 2026-08-24 15:29:13.936552 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-08-24 15:29:14.154701 | instance | ok: "logs" 2026-08-24 15:29:14.155209 | instance | ok: All items complete 2026-08-24 15:29:14.155263 | 2026-08-24 15:29:14.296042 | instance | ok: "artifacts" 2026-08-24 15:29:14.456313 | instance | ok: "docs" 2026-08-24 15:29:14.469902 | 2026-08-24 15:29:14.470047 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-08-24 15:29:14.666529 | instance | changed: "logs" 2026-08-24 15:29:14.825864 | instance | changed: "artifacts" 2026-08-24 15:29:14.972733 | instance | changed: "docs" 2026-08-24 15:29:14.984272 | 2026-08-24 15:29:14.984376 | PLAY RECAP 2026-08-24 15:29:14.984423 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-08-24 15:29:14.984475 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-24 15:29:14.984501 | 2026-08-24 15:29:15.138894 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-08-24 15:29:15.141181 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-08-24 15:29:15.854032 | 2026-08-24 15:29:15.854179 | PLAY [all] 2026-08-24 15:29:15.866560 | 2026-08-24 15:29:15.866688 | TASK [setup-uv : Extract archive] 2026-08-24 15:29:19.144066 | instance | changed 2026-08-24 15:29:19.151466 | 2026-08-24 15:29:19.151571 | TASK [setup-uv : Print version] 2026-08-24 15:29:19.572248 | instance | uv 0.8.13 2026-08-24 15:29:19.692175 | instance | ok: Runtime: 0:00:00.018484 2026-08-24 15:29:19.702288 | 2026-08-24 15:29:19.702372 | TASK [Install Ansible collection siblings] 2026-08-24 15:29:29.476296 | instance | changed: 2026-08-24 15:29:29.476488 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-08-24 15:29:29.476526 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-08-24 15:29:29.476549 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-08-24 15:29:29.476569 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto 2026-08-24 15:29:29.476588 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general 2026-08-24 15:29:29.476620 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql 2026-08-24 15:29:29.476649 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-08-24 15:29:29.476678 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-08-24 15:29:29.476705 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-08-24 15:29:29.476732 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-08-24 15:29:29.476760 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-08-24 15:29:29.476787 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-08-24 15:29:29.476814 | instance | Resolved ansible.netcommon to v8.5.0 for 8.5.0 2026-08-24 15:29:29.476843 | instance | Resolved ansible.posix to 2.1.0 for 2.1.0 2026-08-24 15:29:29.476869 | instance | Resolved ansible.utils to v6.1.0 for >=6.0.0,>=3.0.0 2026-08-24 15:29:29.476895 | instance | Resolved community.crypto to 3.2.0 for 3.2.0 2026-08-24 15:29:29.476922 | instance | Resolved community.general to 12.6.0 for 12.6.0 2026-08-24 15:29:29.476949 | instance | Resolved community.mysql to 4.2.0 for 4.2.0 2026-08-24 15:29:29.477021 | instance | Resolved kubernetes.core to 6.3.0 for 6.3.0 2026-08-24 15:29:29.477051 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0 2026-08-24 15:29:29.477078 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0 2026-08-24 15:29:29.477105 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0 2026-08-24 15:29:29.477135 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-08-24 15:29:29.477173 | instance | Resolved ansible.utils to v6.1.0 for >=6.0.0,>=3.0.0 2026-08-24 15:29:29.477202 | instance | Resolved community.general to 12.6.0 for 12.6.0,>=4.5.0 2026-08-24 15:29:29.477229 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-08-24 15:29:29.477256 | instance | Resolved kubernetes.core to 6.3.0 for 6.3.0,>=2.4.0,>=2.3.2 2026-08-24 15:29:29.478416 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-08-24 15:29:29.478473 | instance | Resolved ansible.posix to 2.1.0 for 2.1.0,>=1.6.0 2026-08-24 15:29:29.478506 | instance | Resolved community.crypto to 3.2.0 for 3.2.0,>=2.2.3 2026-08-24 15:29:29.478588 | instance | Resolved kubernetes.core to 6.3.0 for 6.3.0,>=2.4.0,>=2.3.2 2026-08-24 15:29:29.478677 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-08-24 15:29:29.478715 | instance | Checked out ansible.netcommon to v8.5.0 2026-08-24 15:29:29.478745 | 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-08-24 15:29:29.478791 | instance | Starting galaxy collection install process 2026-08-24 15:29:29.478834 | instance | Process install dependency map 2026-08-24 15:29:29.478863 | instance | Starting collection install process 2026-08-24 15:29:29.478909 | instance | Installing 'ansible.netcommon:8.5.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-08-24 15:29:29.478939 | instance | Created collection for ansible.netcommon:8.5.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon 2026-08-24 15:29:29.478969 | instance | ansible.netcommon:8.5.0 was installed successfully 2026-08-24 15:29:29.478997 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-08-24 15:29:29.479026 | instance | Creating virtual environment at: .venv 2026-08-24 15:29:29.479054 | instance | Downloading setuptools (1.1MiB) 2026-08-24 15:29:29.479082 | instance | Downloading pydantic-core (2.0MiB) 2026-08-24 15:29:29.479110 | instance | Downloading ansible-core (2.1MiB) 2026-08-24 15:29:29.479138 | instance | Downloading openstacksdk (1.7MiB) 2026-08-24 15:29:29.479166 | instance | Downloading pygments (1.2MiB) 2026-08-24 15:29:29.479194 | instance | Downloading cryptography (4.2MiB) 2026-08-24 15:29:29.479222 | instance | Downloading rjsonnet (1.2MiB) 2026-08-24 15:29:29.479250 | instance | Downloading kubernetes (1.9MiB) 2026-08-24 15:29:29.479279 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-08-24 15:29:29.479306 | instance | Downloading netaddr (2.2MiB) 2026-08-24 15:29:29.479334 | instance | Building pyperclip==1.9.0 2026-08-24 15:29:29.479364 | instance | Downloading rjsonnet 2026-08-24 15:29:29.479393 | instance | Downloading pydantic-core 2026-08-24 15:29:29.479442 | instance | Downloading netaddr 2026-08-24 15:29:29.479475 | instance | Downloading pygments 2026-08-24 15:29:29.479503 | instance | Downloading cryptography 2026-08-24 15:29:29.479531 | instance | Downloading setuptools 2026-08-24 15:29:29.479582 | instance | Downloading kubernetes 2026-08-24 15:29:29.479620 | instance | Downloading ansible-core 2026-08-24 15:29:29.479653 | instance | Downloading openstacksdk 2026-08-24 15:29:29.479681 | instance | Built pyperclip==1.9.0 2026-08-24 15:29:29.479709 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-08-24 15:29:29.479743 | instance | Installed 83 packages in 55ms 2026-08-24 15:29:29.479777 | instance | Checked out ansible.posix to 2.1.0 2026-08-24 15:29:29.479810 | 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-08-24 15:29:29.479846 | instance | Starting galaxy collection install process 2026-08-24 15:29:29.479876 | instance | Process install dependency map 2026-08-24 15:29:29.479915 | instance | Starting collection install process 2026-08-24 15:29:29.479945 | instance | Installing 'ansible.posix:2.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-08-24 15:29:29.479973 | instance | Created collection for ansible.posix:2.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix 2026-08-24 15:29:29.480000 | instance | ansible.posix:2.1.0 was installed successfully 2026-08-24 15:29:29.480029 | instance | Checked out ansible.utils to v6.1.0 2026-08-24 15:29:29.480056 | 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-08-24 15:29:29.480083 | instance | Starting galaxy collection install process 2026-08-24 15:29:29.480110 | instance | Process install dependency map 2026-08-24 15:29:29.480139 | instance | Starting collection install process 2026-08-24 15:29:29.480169 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-08-24 15:29:29.480197 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils 2026-08-24 15:29:29.480225 | instance | ansible.utils:6.1.0 was installed successfully 2026-08-24 15:29:29.480253 | instance | Checked out atmosphere.common to v0.7.0 2026-08-24 15:29:29.480282 | 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-08-24 15:29:29.480310 | instance | Starting galaxy collection install process 2026-08-24 15:29:29.480338 | instance | Process install dependency map 2026-08-24 15:29:29.480366 | instance | Starting collection install process 2026-08-24 15:29:29.480395 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common' 2026-08-24 15:29:29.480423 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common 2026-08-24 15:29:29.480467 | instance | atmosphere.common:0.7.0 was installed successfully 2026-08-24 15:29:29.480496 | instance | Checked out community.crypto to 3.2.0 2026-08-24 15:29:29.480525 | 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-08-24 15:29:29.480554 | instance | Starting galaxy collection install process 2026-08-24 15:29:29.480582 | instance | Process install dependency map 2026-08-24 15:29:29.480611 | instance | Starting collection install process 2026-08-24 15:29:29.480639 | instance | Installing 'community.crypto:3.2.0' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-08-24 15:29:29.480668 | instance | Created collection for community.crypto:3.2.0 at /home/zuul/.ansible/collections/ansible_collections/community/crypto 2026-08-24 15:29:29.481225 | instance | community.crypto:3.2.0 was installed successfully 2026-08-24 15:29:29.481276 | instance | Checked out community.general to 12.6.0 2026-08-24 15:29:29.481311 | 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-08-24 15:29:29.481343 | instance | Starting galaxy collection install process 2026-08-24 15:29:29.481373 | instance | Process install dependency map 2026-08-24 15:29:29.481402 | instance | Starting collection install process 2026-08-24 15:29:29.481431 | instance | Installing 'community.general:12.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-08-24 15:29:29.481460 | instance | Created collection for community.general:12.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/general 2026-08-24 15:29:29.481497 | instance | community.general:12.6.0 was installed successfully 2026-08-24 15:29:29.481572 | instance | Checked out community.mysql to 4.2.0 2026-08-24 15:29:29.481608 | 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-08-24 15:29:29.481640 | instance | Starting galaxy collection install process 2026-08-24 15:29:29.481679 | instance | Process install dependency map 2026-08-24 15:29:29.481705 | instance | Starting collection install process 2026-08-24 15:29:29.481731 | instance | Installing 'community.mysql:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-08-24 15:29:29.481768 | instance | Created collection for community.mysql:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql 2026-08-24 15:29:29.481803 | instance | community.mysql:4.2.0 was installed successfully 2026-08-24 15:29:29.481831 | instance | Checked out kubernetes.core to 6.3.0 2026-08-24 15:29:29.481859 | 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-08-24 15:29:29.481888 | instance | Starting galaxy collection install process 2026-08-24 15:29:29.481921 | instance | Process install dependency map 2026-08-24 15:29:29.481961 | instance | Starting collection install process 2026-08-24 15:29:29.481989 | instance | Installing 'kubernetes.core:6.3.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-08-24 15:29:29.482017 | instance | Created collection for kubernetes.core:6.3.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core 2026-08-24 15:29:29.482045 | instance | kubernetes.core:6.3.0 was installed successfully 2026-08-24 15:29:29.482072 | instance | Checked out openstack.cloud to 2.6.0 2026-08-24 15:29:29.482100 | 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-08-24 15:29:29.482128 | instance | Starting galaxy collection install process 2026-08-24 15:29:29.482155 | instance | Process install dependency map 2026-08-24 15:29:29.482182 | instance | Starting collection install process 2026-08-24 15:29:29.482211 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-08-24 15:29:29.482542 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud 2026-08-24 15:29:29.482712 | instance | openstack.cloud:2.6.0 was installed successfully 2026-08-24 15:29:29.482755 | instance | Checked out vexxhost.ceph to v4.2.0 2026-08-24 15:29:29.482788 | 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-08-24 15:29:29.482819 | instance | Starting galaxy collection install process 2026-08-24 15:29:29.482849 | instance | Process install dependency map 2026-08-24 15:29:29.482878 | instance | Starting collection install process 2026-08-24 15:29:29.482907 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph' 2026-08-24 15:29:29.482936 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph 2026-08-24 15:29:29.482965 | instance | vexxhost.ceph:4.2.0 was installed successfully 2026-08-24 15:29:29.482994 | instance | Checked out vexxhost.containers to v1.6.8 2026-08-24 15:29:29.483061 | 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-08-24 15:29:29.483190 | instance | Starting galaxy collection install process 2026-08-24 15:29:29.483870 | instance | Process install dependency map 2026-08-24 15:29:29.483912 | instance | Starting collection install process 2026-08-24 15:29:29.483943 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers' 2026-08-24 15:29:29.483974 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers 2026-08-24 15:29:29.484004 | instance | vexxhost.containers:1.6.8 was installed successfully 2026-08-24 15:29:29.484034 | instance | Checked out vexxhost.kubernetes to v3.3.0 2026-08-24 15:29:29.484064 | 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-08-24 15:29:29.484093 | instance | Starting galaxy collection install process 2026-08-24 15:29:29.484122 | instance | Process install dependency map 2026-08-24 15:29:29.484150 | instance | Starting collection install process 2026-08-24 15:29:29.484180 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes' 2026-08-24 15:29:29.484210 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes 2026-08-24 15:29:29.484239 | instance | vexxhost.kubernetes:3.3.0 was installed successfully 2026-08-24 15:29:29.495898 | 2026-08-24 15:29:29.496021 | PLAY RECAP 2026-08-24 15:29:29.496073 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-24 15:29:29.496096 | 2026-08-24 15:29:29.674749 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-08-24 15:29:29.677391 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-08-24 15:29:30.382248 | 2026-08-24 15:29:30.382379 | PLAY [all] 2026-08-24 15:29:30.393807 | 2026-08-24 15:29:30.394158 | TASK [Install "jq" for log collection] 2026-08-24 15:29:40.230188 | instance | changed 2026-08-24 15:29:40.232121 | 2026-08-24 15:29:40.232194 | PLAY RECAP 2026-08-24 15:29:40.232253 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-24 15:29:40.232303 | 2026-08-24 15:29:40.346737 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-08-24 15:29:40.351095 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-08-24 15:29:41.143116 | 2026-08-24 15:29:41.143314 | PLAY [all] 2026-08-24 15:29:41.160654 | 2026-08-24 15:29:41.160918 | LOOP [Check inventory directories] 2026-08-24 15:29:41.544646 | instance | ok: "src/github.com/vexxhost/atmosphere" 2026-08-24 15:29:41.700796 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory" 2026-08-24 15:29:41.863172 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory" 2026-08-24 15:29:41.879939 | 2026-08-24 15:29:41.880083 | LOOP [Copy inventory file for Zuul] 2026-08-24 15:29:42.706273 | instance | changed: 2026-08-24 15:29:42.706487 | instance | { 2026-08-24 15:29:42.706519 | instance | "ansible_loop_var": "item", 2026-08-24 15:29:42.706546 | instance | "changed": false, 2026-08-24 15:29:42.706569 | instance | "failed": false, 2026-08-24 15:29:42.706592 | instance | "invocation": { 2026-08-24 15:29:42.706613 | instance | "module_args": { 2026-08-24 15:29:42.706634 | instance | "checksum_algorithm": "sha1", 2026-08-24 15:29:42.706654 | instance | "follow": true, 2026-08-24 15:29:42.706673 | instance | "get_attributes": true, 2026-08-24 15:29:42.706695 | instance | "get_checksum": true, 2026-08-24 15:29:42.706716 | instance | "get_mime": true, 2026-08-24 15:29:42.706735 | instance | "path": "src/github.com/vexxhost/atmosphere" 2026-08-24 15:29:42.706843 | instance | } 2026-08-24 15:29:42.706873 | instance | }, 2026-08-24 15:29:42.706892 | instance | "item": "src/github.com/vexxhost/atmosphere", 2026-08-24 15:29:42.706909 | instance | "stat": { 2026-08-24 15:29:42.706926 | instance | "atime": 1787585361.6951337, 2026-08-24 15:29:42.706944 | instance | "attr_flags": "e", 2026-08-24 15:29:42.706961 | instance | "attributes": [ 2026-08-24 15:29:42.706978 | instance | "extents" 2026-08-24 15:29:42.706995 | instance | ], 2026-08-24 15:29:42.707011 | instance | "block_size": 4096, 2026-08-24 15:29:42.707028 | instance | "blocks": 8, 2026-08-24 15:29:42.707044 | instance | "charset": "unknown", 2026-08-24 15:29:42.707062 | instance | "ctime": 1787585360.6551335, 2026-08-24 15:29:42.707079 | instance | "dev": 64513, 2026-08-24 15:29:42.707096 | instance | "device_type": 0, 2026-08-24 15:29:42.707112 | instance | "executable": true, 2026-08-24 15:29:42.707129 | instance | "exists": true, 2026-08-24 15:29:42.707145 | instance | "gid": 1000, 2026-08-24 15:29:42.707161 | instance | "gr_name": "zuul", 2026-08-24 15:29:42.707178 | instance | "inode": 1046423, 2026-08-24 15:29:42.707194 | instance | "isblk": false, 2026-08-24 15:29:42.707261 | instance | "ischr": false, 2026-08-24 15:29:42.707282 | instance | "isdir": true, 2026-08-24 15:29:42.707300 | instance | "isfifo": false, 2026-08-24 15:29:42.707317 | instance | "isgid": false, 2026-08-24 15:29:42.707333 | instance | "islnk": false, 2026-08-24 15:29:42.707350 | instance | "isreg": false, 2026-08-24 15:29:42.707366 | instance | "issock": false, 2026-08-24 15:29:42.707382 | instance | "isuid": false, 2026-08-24 15:29:42.707398 | instance | "mimetype": "unknown", 2026-08-24 15:29:42.707414 | instance | "mode": "0755", 2026-08-24 15:29:42.707430 | instance | "mtime": 1787585360.6551335, 2026-08-24 15:29:42.707447 | instance | "nlink": 25, 2026-08-24 15:29:42.707463 | instance | "path": "src/github.com/vexxhost/atmosphere", 2026-08-24 15:29:42.707480 | instance | "pw_name": "zuul", 2026-08-24 15:29:42.707497 | instance | "readable": true, 2026-08-24 15:29:42.707514 | instance | "rgrp": true, 2026-08-24 15:29:42.707531 | instance | "roth": true, 2026-08-24 15:29:42.707548 | instance | "rusr": true, 2026-08-24 15:29:42.707564 | instance | "size": 4096, 2026-08-24 15:29:42.707581 | instance | "uid": 1000, 2026-08-24 15:29:42.707598 | instance | "version": "758065868", 2026-08-24 15:29:42.707615 | instance | "wgrp": false, 2026-08-24 15:29:42.707633 | instance | "woth": false, 2026-08-24 15:29:42.707650 | instance | "writeable": true, 2026-08-24 15:29:42.707667 | instance | "wusr": true, 2026-08-24 15:29:42.707684 | instance | "xgrp": true, 2026-08-24 15:29:42.707700 | instance | "xoth": true, 2026-08-24 15:29:42.707716 | instance | "xusr": true 2026-08-24 15:29:42.707732 | instance | } 2026-08-24 15:29:42.707749 | instance | } 2026-08-24 15:29:42.707788 | instance | ok: All items complete 2026-08-24 15:29:42.707810 | 2026-08-24 15:29:42.714415 | instance | skipping: Conditional result was False 2026-08-24 15:29:42.719512 | instance | skipping: Conditional result was False 2026-08-24 15:29:42.742560 | 2026-08-24 15:29:42.742725 | LOOP [Switch "ansible_host" to private IP] 2026-08-24 15:29:43.162809 | instance | changed: 1 replacements made 2026-08-24 15:29:43.164946 | instance | skipping: Conditional result was False 2026-08-24 15:29:43.167721 | instance | skipping: Conditional result was False 2026-08-24 15:29:43.187605 | 2026-08-24 15:29:43.187794 | TASK [Run Molecule scenario] 2026-08-24 15:29:44.212627 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-08-24 15:29:44.604091 | instance | INFO [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-08-24 15:29:44.604205 | instance | INFO [csi > prerun] Performing prerun with role_name_check=0... 2026-08-24 15:29:55.878388 | instance | INFO [csi > dependency] Executing 2026-08-24 15:29:55.878596 | instance | WARNING [csi > dependency] Missing roles requirements file: requirements.yml 2026-08-24 15:29:55.878883 | instance | WARNING [csi > dependency] Missing collections requirements file: collections.yml 2026-08-24 15:29:55.879058 | instance | WARNING [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-08-24 15:29:55.888496 | instance | INFO [csi > cleanup] Executing 2026-08-24 15:29:55.888822 | instance | WARNING [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-08-24 15:29:55.897362 | instance | INFO [csi > destroy] Executing 2026-08-24 15:29:55.897405 | instance | WARNING [csi > destroy] Skipping, '--destroy=never' requested. 2026-08-24 15:29:55.897500 | instance | INFO [csi > destroy] Executed: Successful 2026-08-24 15:29:55.905177 | instance | INFO [csi > syntax] Executing 2026-08-24 15:29:56.530702 | instance | [WARNING]: provided hosts list is empty, only localhost is available. Note that 2026-08-24 15:29:56.530932 | instance | the implicit localhost does not match 'all' 2026-08-24 15:29:56.662945 | instance | 2026-08-24 15:29:56.663170 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-08-24 15:29:56.723256 | instance | INFO [csi > syntax] Executed: Successful 2026-08-24 15:29:56.735497 | instance | INFO [csi > create] Executing 2026-08-24 15:29:56.737313 | instance | WARNING [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-08-24 15:29:56.744810 | instance | INFO [csi > prepare] Executing 2026-08-24 15:29:57.357718 | instance | [WARNING]: provided hosts list is empty, only localhost is available. Note that 2026-08-24 15:29:57.358024 | instance | the implicit localhost does not match 'all' 2026-08-24 15:29:57.495214 | instance | [WARNING]: Could not match supplied host pattern, ignoring: cephs 2026-08-24 15:29:57.495497 | instance | 2026-08-24 15:29:57.495860 | instance | PLAY [Prepare] ***************************************************************** 2026-08-24 15:29:57.496133 | instance | skipping: no hosts matched 2026-08-24 15:29:57.496412 | instance | 2026-08-24 15:29:57.496731 | instance | PLAY [Create devices for Ceph] ************************************************* 2026-08-24 15:29:57.496998 | instance | skipping: no hosts matched 2026-08-24 15:29:57.497309 | instance | 2026-08-24 15:29:57.497552 | instance | PLAY RECAP ********************************************************************* 2026-08-24 15:29:57.497873 | instance | 2026-08-24 15:29:57.498144 | instance | 2026-08-24 15:29:57.498457 | instance | TASKS RECAP ******************************************************************** 2026-08-24 15:29:57.498732 | instance | Monday 24 August 2026 15:29:57 +0000 (0:00:00.013) 0:00:00.013 ********* 2026-08-24 15:29:57.498989 | instance | =============================================================================== 2026-08-24 15:29:57.546713 | instance | INFO [csi > prepare] Executed: Successful 2026-08-24 15:29:57.560108 | instance | INFO [csi > converge] Executing 2026-08-24 15:29:58.185477 | instance | [WARNING]: provided hosts list is empty, only localhost is available. Note that 2026-08-24 15:29:58.185600 | instance | the implicit localhost does not match 'all' 2026-08-24 15:29:58.252672 | instance | 2026-08-24 15:29:58.252951 | instance | PLAY [all] ********************************************************************* 2026-08-24 15:29:58.253260 | instance | skipping: no hosts matched 2026-08-24 15:29:58.253608 | instance | 2026-08-24 15:29:58.254030 | instance | PLAY RECAP ********************************************************************* 2026-08-24 15:29:58.254407 | instance | 2026-08-24 15:29:58.254781 | instance | 2026-08-24 15:29:58.255139 | instance | TASKS RECAP ******************************************************************** 2026-08-24 15:29:58.255490 | instance | Monday 24 August 2026 15:29:58 +0000 (0:00:00.001) 0:00:00.001 ********* 2026-08-24 15:29:58.255821 | instance | =============================================================================== 2026-08-24 15:29:58.302367 | instance | INFO [csi > converge] Executed: Successful 2026-08-24 15:29:58.315060 | instance | INFO [csi > idempotence] Executing 2026-08-24 15:29:58.958188 | instance | [WARNING]: provided hosts list is empty, only localhost is available. Note that 2026-08-24 15:29:58.958554 | instance | the implicit localhost does not match 'all' 2026-08-24 15:29:59.021413 | instance | 2026-08-24 15:29:59.021681 | instance | PLAY [all] ********************************************************************* 2026-08-24 15:29:59.021970 | instance | skipping: no hosts matched 2026-08-24 15:29:59.022236 | instance | 2026-08-24 15:29:59.022501 | instance | PLAY RECAP ********************************************************************* 2026-08-24 15:29:59.022753 | instance | 2026-08-24 15:29:59.023032 | instance | 2026-08-24 15:29:59.023306 | instance | TASKS RECAP ******************************************************************** 2026-08-24 15:29:59.023579 | instance | Monday 24 August 2026 15:29:59 +0000 (0:00:00.001) 0:00:00.001 ********* 2026-08-24 15:29:59.023837 | instance | =============================================================================== 2026-08-24 15:29:59.075174 | instance | INFO [csi > idempotence] Executed: Successful 2026-08-24 15:29:59.088798 | instance | INFO [csi > side_effect] Executing 2026-08-24 15:29:59.089103 | instance | WARNING [csi > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-08-24 15:29:59.096527 | instance | INFO [csi > verify] Executing 2026-08-24 15:29:59.778611 | instance | [WARNING]: provided hosts list is empty, only localhost is available. Note that 2026-08-24 15:29:59.778860 | instance | the implicit localhost does not match 'all' 2026-08-24 15:29:59.827667 | instance | [WARNING]: Could not match supplied host pattern, ignoring: controllers 2026-08-24 15:29:59.827913 | instance | 2026-08-24 15:29:59.828098 | instance | PLAY [Verify] ****************************************************************** 2026-08-24 15:29:59.828337 | instance | skipping: no hosts matched 2026-08-24 15:29:59.828518 | instance | 2026-08-24 15:29:59.828691 | instance | PLAY RECAP ********************************************************************* 2026-08-24 15:29:59.828851 | instance | 2026-08-24 15:29:59.829012 | instance | 2026-08-24 15:29:59.829181 | instance | TASKS RECAP ******************************************************************** 2026-08-24 15:29:59.829353 | instance | Monday 24 August 2026 15:29:59 +0000 (0:00:00.001) 0:00:00.001 ********* 2026-08-24 15:29:59.829515 | instance | =============================================================================== 2026-08-24 15:29:59.888530 | instance | INFO [csi > verify] Executed: Successful 2026-08-24 15:29:59.976999 | instance | INFO [csi > cleanup] Executing 2026-08-24 15:29:59.977359 | instance | WARNING [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-08-24 15:30:00.023091 | instance | INFO [csi > destroy] Executing 2026-08-24 15:30:00.023185 | instance | WARNING [csi > destroy] Skipping, '--destroy=never' requested. 2026-08-24 15:30:00.023289 | instance | INFO [csi > destroy] Executed: Successful 2026-08-24 15:30:00.023937 | instance | WARNING Molecule executed 1 scenario (1 missing files) 2026-08-24 15:30:00.256878 | instance | ok: Runtime: 0:00:16.513164 2026-08-24 15:30:00.260465 | 2026-08-24 15:30:00.260539 | PLAY RECAP 2026-08-24 15:30:00.260602 | instance | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-24 15:30:00.260636 | 2026-08-24 15:30:00.376520 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-08-24 15:30:00.380646 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-08-24 15:30:01.555641 | 2026-08-24 15:30:01.555764 | PLAY [all] 2026-08-24 15:30:01.570329 | 2026-08-24 15:30:01.570447 | TASK [gather-host-logs : creating directory for system status] 2026-08-24 15:30:02.011029 | instance | changed 2026-08-24 15:30:02.023723 | 2026-08-24 15:30:02.024564 | TASK [gather-host-logs : Get logs for each host] 2026-08-24 15:30:02.412702 | instance | + systemd-cgls --full --all --no-pager 2026-08-24 15:30:02.420583 | instance | + ip addr 2026-08-24 15:30:02.423315 | instance | + ip route 2026-08-24 15:30:02.426909 | instance | + lsblk 2026-08-24 15:30:02.431948 | instance | + mount 2026-08-24 15:30:02.435464 | instance | + docker images 2026-08-24 15:30:02.435975 | instance | /bin/bash: line 7: docker: command not found 2026-08-24 15:30:02.436285 | instance | + brctl show 2026-08-24 15:30:02.437099 | instance | /bin/bash: line 8: brctl: command not found 2026-08-24 15:30:02.437321 | instance | + ps aux --sort=-%mem 2026-08-24 15:30:02.448449 | instance | + dpkg -l 2026-08-24 15:30:02.454403 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-08-24 15:30:02.454717 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-08-24 15:30:02.454748 | instance | /bin/bash: line 11: docker: command not found 2026-08-24 15:30:02.455022 | instance | + '[' '!' -z '' ']' 2026-08-24 15:30:02.807804 | instance | ok: Runtime: 0:00:00.048252 2026-08-24 15:30:02.817733 | 2026-08-24 15:30:02.817856 | TASK [gather-host-logs : Downloads logs to executor] 2026-08-24 15:30:03.539137 | instance | changed: 2026-08-24 15:30:03.539306 | instance | created directory /var/lib/zuul/builds/dbdd049b79334a5a8f5fbb1b40739253/work/logs/instance 2026-08-24 15:30:03.539337 | instance | cd+++++++++ system/ 2026-08-24 15:30:03.539361 | instance | >f+++++++++ system/brctl-show.txt 2026-08-24 15:30:03.539384 | instance | >f+++++++++ system/docker-images.txt 2026-08-24 15:30:03.539405 | instance | >f+++++++++ system/ip-addr.txt 2026-08-24 15:30:03.539430 | instance | >f+++++++++ system/ip-route.txt 2026-08-24 15:30:03.539455 | instance | >f+++++++++ system/lsblk.txt 2026-08-24 15:30:03.539477 | instance | >f+++++++++ system/mount.txt 2026-08-24 15:30:03.539500 | instance | >f+++++++++ system/packages.txt 2026-08-24 15:30:03.539521 | instance | >f+++++++++ system/ps.txt 2026-08-24 15:30:03.539541 | instance | >f+++++++++ system/systemd-cgls.txt 2026-08-24 15:30:03.549244 | 2026-08-24 15:30:03.549397 | LOOP [helm-release-status : creating directory for helm release status] 2026-08-24 15:30:03.749759 | instance | changed: "values" 2026-08-24 15:30:03.924143 | instance | changed: "releases" 2026-08-24 15:30:03.940840 | 2026-08-24 15:30:03.941086 | TASK [helm-release-status : Gather get release status for helm charts] 2026-08-24 15:30:04.163929 | instance | /bin/bash: line 3: kubectl: command not found 2026-08-24 15:30:04.475744 | instance | ok: Runtime: 0:00:00.006640 2026-08-24 15:30:04.481759 | 2026-08-24 15:30:04.481889 | TASK [helm-release-status : Downloads logs to executor] 2026-08-24 15:30:05.003630 | instance | changed: 2026-08-24 15:30:05.003819 | instance | cd+++++++++ helm/ 2026-08-24 15:30:05.003849 | instance | cd+++++++++ helm/releases/ 2026-08-24 15:30:05.003897 | instance | cd+++++++++ helm/values/ 2026-08-24 15:30:05.014855 | 2026-08-24 15:30:05.015012 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-08-24 15:30:05.212706 | instance | changed 2026-08-24 15:30:05.221421 | 2026-08-24 15:30:05.221518 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-08-24 15:30:05.485871 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args valuewarning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-08-24 15:30:05.486122 | instance | 2026-08-24 15:30:05.492208 | instance | environment: line 1: kubectl: command not found 2026-08-24 15:30:05.493753 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-08-24 15:30:05.494316 | instance | environment: line 1: kubectl: command not found 2026-08-24 15:30:05.495192 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-08-24 15:30:05.495770 | instance | environment: line 1: kubectl: command not found 2026-08-24 15:30:05.496614 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-08-24 15:30:05.498819 | instance | environment: line 1: kubectl: command not found 2026-08-24 15:30:05.499234 | instance | environment: line 1: kubectl: command not found 2026-08-24 15:30:05.500255 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-08-24 15:30:05.500278 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-08-24 15:30:05.756853 | instance | ok: Runtime: 0:00:00.026192 2026-08-24 15:30:05.764088 | 2026-08-24 15:30:05.764272 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-08-24 15:30:05.971242 | instance | changed 2026-08-24 15:30:05.978895 | 2026-08-24 15:30:05.979084 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-08-24 15:30:06.206731 | instance | environment: line 5: kubectl: command not found 2026-08-24 15:30:06.207579 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args valuewarning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-08-24 15:30:06.207625 | instance | 2026-08-24 15:30:06.208260 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-08-24 15:30:06.526393 | instance | ok: Runtime: 0:00:00.010709 2026-08-24 15:30:06.536443 | 2026-08-24 15:30:06.536584 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-08-24 15:30:07.057940 | instance | changed: 2026-08-24 15:30:07.058163 | instance | cd+++++++++ objects/ 2026-08-24 15:30:07.058191 | instance | cd+++++++++ objects/cluster/ 2026-08-24 15:30:07.058213 | instance | cd+++++++++ objects/namespaced/ 2026-08-24 15:30:07.068333 | 2026-08-24 15:30:07.068895 | TASK [gather-pod-logs : creating directory for pod logs] 2026-08-24 15:30:07.267392 | instance | changed 2026-08-24 15:30:07.275911 | 2026-08-24 15:30:07.276032 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-08-24 15:30:07.481115 | instance | changed 2026-08-24 15:30:07.486956 | 2026-08-24 15:30:07.487056 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-08-24 15:30:07.704193 | instance | environment: line 3: kubectl: command not found 2026-08-24 15:30:08.028192 | instance | ok: Runtime: 0:00:00.009371 2026-08-24 15:30:08.035084 | 2026-08-24 15:30:08.035224 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-08-24 15:30:08.581778 | instance | changed: 2026-08-24 15:30:08.582005 | instance | cd+++++++++ pod-logs/ 2026-08-24 15:30:08.582044 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-08-24 15:30:08.595834 | 2026-08-24 15:30:08.595980 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-08-24 15:30:08.815511 | instance | changed 2026-08-24 15:30:08.831354 | 2026-08-24 15:30:08.831536 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-08-24 15:30:09.061688 | instance | /bin/bash: line 2: kubectl: command not found 2026-08-24 15:30:09.373275 | instance | ok: Runtime: 0:00:00.038549 2026-08-24 15:30:09.381135 | 2026-08-24 15:30:09.381316 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-08-24 15:30:09.591731 | instance | /bin/bash: line 2: kubectl: command not found 2026-08-24 15:30:09.620715 | instance | ceph-mgr endpoints: 2026-08-24 15:30:09.921672 | instance | ok: Runtime: 0:00:00.035616 2026-08-24 15:30:09.928680 | 2026-08-24 15:30:09.928866 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-08-24 15:30:10.129569 | instance | /bin/bash: line 4: kubectl: command not found 2026-08-24 15:30:10.466850 | instance | ok: Runtime: 0:00:00.034992 2026-08-24 15:30:10.475029 | 2026-08-24 15:30:10.475197 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-08-24 15:30:11.030263 | instance | changed: cd+++++++++ prometheus/ 2026-08-24 15:30:11.043725 | 2026-08-24 15:30:11.043879 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-08-24 15:30:11.243547 | instance | changed 2026-08-24 15:30:11.248374 | 2026-08-24 15:30:11.248442 | TASK [gather-selenium-data : Get selenium data] 2026-08-24 15:30:11.495005 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-08-24 15:30:11.496530 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-08-24 15:30:11.779194 | instance | ERROR 2026-08-24 15:30:11.779371 | instance | { 2026-08-24 15:30:11.779401 | instance | "delta": "0:00:00.007563", 2026-08-24 15:30:11.779423 | instance | "end": "2026-08-24 15:30:11.496859", 2026-08-24 15:30:11.779441 | instance | "msg": "non-zero return code", 2026-08-24 15:30:11.779460 | instance | "rc": 1, 2026-08-24 15:30:11.779478 | instance | "start": "2026-08-24 15:30:11.489296" 2026-08-24 15:30:11.779495 | instance | } 2026-08-24 15:30:11.779518 | instance | ERROR: Ignoring Errors 2026-08-24 15:30:11.784375 | 2026-08-24 15:30:11.784474 | TASK [gather-selenium-data : Downloads logs to executor] 2026-08-24 15:30:12.294104 | instance | changed: cd+++++++++ selenium/ 2026-08-24 15:30:12.301034 | 2026-08-24 15:30:12.301140 | PLAY RECAP 2026-08-24 15:30:12.301187 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-08-24 15:30:12.301209 | 2026-08-24 15:30:12.465774 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-08-24 15:30:12.469370 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-08-24 15:30:13.112056 | 2026-08-24 15:30:13.112200 | PLAY [all] 2026-08-24 15:30:13.125279 | 2026-08-24 15:30:13.125395 | TASK [fetch-output : Set log path for multiple nodes] 2026-08-24 15:30:13.169874 | instance | skipping: Conditional result was False 2026-08-24 15:30:13.177858 | 2026-08-24 15:30:13.177976 | TASK [fetch-output : Set log path for single node] 2026-08-24 15:30:13.220722 | instance | ok 2026-08-24 15:30:13.227458 | 2026-08-24 15:30:13.227580 | LOOP [fetch-output : Ensure local output dirs] 2026-08-24 15:30:13.669572 | instance -> localhost | ok: "/var/lib/zuul/builds/dbdd049b79334a5a8f5fbb1b40739253/work/logs" 2026-08-24 15:30:13.896478 | instance -> localhost | changed: "/var/lib/zuul/builds/dbdd049b79334a5a8f5fbb1b40739253/work/artifacts" 2026-08-24 15:30:14.119767 | instance -> localhost | changed: "/var/lib/zuul/builds/dbdd049b79334a5a8f5fbb1b40739253/work/docs" 2026-08-24 15:30:14.135675 | 2026-08-24 15:30:14.135798 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-24 15:30:14.823907 | instance | changed: .d..t...... ./ 2026-08-24 15:30:14.824200 | instance | changed: All items complete 2026-08-24 15:30:14.824251 | 2026-08-24 15:30:15.278393 | instance | changed: .d..t...... ./ 2026-08-24 15:30:15.728444 | instance | changed: .d..t...... ./ 2026-08-24 15:30:15.758099 | 2026-08-24 15:30:15.758351 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-24 15:30:16.357411 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007988 2026-08-24 15:30:16.632382 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008225 2026-08-24 15:30:16.651287 | 2026-08-24 15:30:16.651455 | PLAY [all] 2026-08-24 15:30:16.660713 | 2026-08-24 15:30:16.660829 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-24 15:30:17.124731 | instance | changed 2026-08-24 15:30:17.132189 | 2026-08-24 15:30:17.132278 | PLAY RECAP 2026-08-24 15:30:17.132330 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-08-24 15:30:17.132352 | 2026-08-24 15:30:17.329632 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-08-24 15:30:17.332873 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-24 15:30:19.214950 | 2026-08-24 15:30:19.215162 | PLAY [localhost] 2026-08-24 15:30:19.227342 | 2026-08-24 15:30:19.227663 | TASK [Generate Zuul manifest] 2026-08-24 15:30:19.250604 | localhost | ok 2026-08-24 15:30:20.204253 | 2026-08-24 15:30:20.204485 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-24 15:30:20.661436 | localhost | changed 2026-08-24 15:30:20.719220 | 2026-08-24 15:30:20.719414 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-24 15:30:20.751014 | localhost | ok 2026-08-24 15:30:20.765495 | 2026-08-24 15:30:20.765606 | TASK [Upload logs] 2026-08-24 15:30:20.789156 | localhost | ok 2026-08-24 15:30:20.944653 | 2026-08-24 15:30:20.944846 | TASK [Set zuul-log-path fact] 2026-08-24 15:30:20.968405 | localhost | ok 2026-08-24 15:30:20.983213 | 2026-08-24 15:30:20.983321 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-24 15:30:21.015265 | localhost | ok 2026-08-24 15:30:21.024915 | 2026-08-24 15:30:21.025050 | TASK [upload-logs : Create log directories] 2026-08-24 15:30:21.493567 | localhost | changed 2026-08-24 15:30:21.500209 | 2026-08-24 15:30:21.500287 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-24 15:30:21.921209 | localhost -> localhost | ok: Runtime: 0:00:00.007943 2026-08-24 15:30:21.926868 | 2026-08-24 15:30:21.926949 | TASK [upload-logs : Upload logs to log server] 2026-08-24 15:30:22.446531 | localhost | Output suppressed because no_log was given 2026-08-24 15:30:22.494430 | 2026-08-24 15:30:22.494560 | LOOP [upload-logs : Compress console log and json output] 2026-08-24 15:30:22.547008 | localhost | skipping: Conditional result was False 2026-08-24 15:30:22.555073 | localhost | skipping: Conditional result was False 2026-08-24 15:30:22.569094 | 2026-08-24 15:30:22.569321 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-24 15:30:22.616543 | localhost | skipping: Conditional result was False 2026-08-24 15:30:22.617345 | 2026-08-24 15:30:22.622116 | localhost | skipping: Conditional result was False 2026-08-24 15:30:22.636103 | 2026-08-24 15:30:22.636294 | LOOP [upload-logs : Upload console log and json output]