2026-08-14 09:00:04.948207 | Job console starting 2026-08-14 09:00:04.960993 | Updating git repos 2026-08-14 09:00:07.393596 | Cloning repos into workspace 2026-08-14 09:00:30.502548 | Restoring repo states 2026-08-14 09:00:43.956545 | Merging changes 2026-08-14 09:00:46.981185 | Checking out repos 2026-08-14 09:01:00.891755 | Preparing playbooks 2026-08-14 09:01:14.544274 | Running Ansible setup 2026-08-14 09:01:27.216885 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-08-14 09:01:27.867914 | 2026-08-14 09:01:27.868146 | PLAY [localhost] 2026-08-14 09:01:28.049623 | 2026-08-14 09:01:28.049818 | TASK [Gathering Facts] 2026-08-14 09:01:29.682043 | localhost | ok 2026-08-14 09:01:29.694639 | 2026-08-14 09:01:29.694757 | TASK [Setup log path fact] 2026-08-14 09:01:29.718550 | localhost | ok 2026-08-14 09:01:29.731562 | 2026-08-14 09:01:29.731659 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-14 09:01:29.762023 | localhost | ok 2026-08-14 09:01:29.771768 | 2026-08-14 09:01:29.771980 | TASK [emit-job-header : Print job information] 2026-08-14 09:01:29.816444 | # Job Information 2026-08-14 09:01:29.816651 | Ansible Version: 2.16.18 2026-08-14 09:01:29.816684 | Job: atmosphere-molecule-csi-local-path-provisioner 2026-08-14 09:01:29.816708 | Pipeline: check 2026-08-14 09:01:29.816729 | Executor: 2d72f0692154 2026-08-14 09:01:29.816750 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3864 2026-08-14 09:01:29.816773 | Event ID: 3dfc75f0-97bb-11f1-888e-d5c685d976b3 2026-08-14 09:01:29.820372 | 2026-08-14 09:01:29.820479 | LOOP [emit-job-header : Print node information] 2026-08-14 09:01:29.916733 | localhost | ok: 2026-08-14 09:01:30.909254 | localhost | # Node Information 2026-08-14 09:01:30.909485 | localhost | Inventory Hostname: instance 2026-08-14 09:01:30.909549 | localhost | Hostname: np0000189649 2026-08-14 09:01:30.909647 | localhost | Username: zuul 2026-08-14 09:01:30.909755 | localhost | Distro: Ubuntu 22.04 2026-08-14 09:01:30.909824 | localhost | Provider: yul1 2026-08-14 09:01:30.909874 | localhost | Region: ca-ymq-1 2026-08-14 09:01:30.910009 | localhost | Label: ubuntu-jammy 2026-08-14 09:01:30.910070 | localhost | Product Name: OpenStack Nova 2026-08-14 09:01:30.910117 | localhost | Interface IP: 199.19.213.237 2026-08-14 09:01:30.922046 | 2026-08-14 09:01:30.922197 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-14 09:01:31.324950 | localhost -> localhost | changed 2026-08-14 09:01:31.335043 | 2026-08-14 09:01:31.335167 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-14 09:01:32.867281 | localhost -> localhost | changed 2026-08-14 09:01:32.878667 | 2026-08-14 09:01:32.878762 | PLAY [all] 2026-08-14 09:01:32.890167 | 2026-08-14 09:01:32.890339 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-14 09:01:33.141585 | instance -> localhost | ok 2026-08-14 09:01:33.152336 | 2026-08-14 09:01:33.152479 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-14 09:01:33.182880 | instance | ok 2026-08-14 09:01:33.198934 | instance | included: /var/lib/zuul/builds/60529035895f45e790e2e354c0bac87c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-14 09:01:33.205110 | 2026-08-14 09:01:33.205192 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-14 09:01:38.828438 | instance -> localhost | Generating public/private rsa key pair. 2026-08-14 09:01:38.828681 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/60529035895f45e790e2e354c0bac87c/work/60529035895f45e790e2e354c0bac87c_id_rsa 2026-08-14 09:01:38.828725 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/60529035895f45e790e2e354c0bac87c/work/60529035895f45e790e2e354c0bac87c_id_rsa.pub 2026-08-14 09:01:38.828758 | instance -> localhost | The key fingerprint is: 2026-08-14 09:01:38.828788 | instance -> localhost | SHA256:+xcpBr4aE7CF2Z1GO7NNPXIVBQicbXnliMRcWY82weY zuul-build-sshkey 2026-08-14 09:01:38.828835 | instance -> localhost | The key's randomart image is: 2026-08-14 09:01:38.828866 | instance -> localhost | +---[RSA 3072]----+ 2026-08-14 09:01:38.828906 | instance -> localhost | | ...*.=*B+| 2026-08-14 09:01:38.828938 | instance -> localhost | | + o oooBo==.| 2026-08-14 09:01:38.830075 | instance -> localhost | | + o B o.=+= o| 2026-08-14 09:01:38.830111 | instance -> localhost | | + ..* o oE. | 2026-08-14 09:01:38.830140 | instance -> localhost | | . ..S.. . | 2026-08-14 09:01:38.830170 | instance -> localhost | | ...o o | 2026-08-14 09:01:38.830199 | instance -> localhost | | o .o . . | 2026-08-14 09:01:38.830227 | instance -> localhost | | o.. . | 2026-08-14 09:01:38.830259 | instance -> localhost | | .. .. | 2026-08-14 09:01:38.830289 | instance -> localhost | +----[SHA256]-----+ 2026-08-14 09:01:38.830426 | instance -> localhost | ok: Runtime: 0:00:05.185475 2026-08-14 09:01:38.837877 | 2026-08-14 09:01:38.838012 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-14 09:01:38.873635 | instance | ok 2026-08-14 09:01:38.882744 | instance | included: /var/lib/zuul/builds/60529035895f45e790e2e354c0bac87c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-14 09:01:38.890851 | 2026-08-14 09:01:38.890976 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-14 09:01:38.915934 | instance | skipping: Conditional result was False 2026-08-14 09:01:38.923968 | 2026-08-14 09:01:38.924065 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-14 09:01:39.383841 | instance | changed 2026-08-14 09:01:39.395691 | 2026-08-14 09:01:39.395803 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-14 09:01:39.569976 | instance | ok 2026-08-14 09:01:39.575309 | 2026-08-14 09:01:39.575390 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-14 09:01:40.046172 | instance | changed 2026-08-14 09:01:40.054540 | 2026-08-14 09:01:40.054660 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-14 09:01:40.514581 | instance | changed 2026-08-14 09:01:40.530999 | 2026-08-14 09:01:40.531167 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-14 09:01:40.556762 | instance | skipping: Conditional result was False 2026-08-14 09:01:40.571206 | 2026-08-14 09:01:40.571406 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-14 09:01:41.002124 | instance -> localhost | changed 2026-08-14 09:01:41.019225 | 2026-08-14 09:01:41.019329 | TASK [add-build-sshkey : Add back temp key] 2026-08-14 09:01:41.483974 | instance -> localhost | Identity added: /var/lib/zuul/builds/60529035895f45e790e2e354c0bac87c/work/60529035895f45e790e2e354c0bac87c_id_rsa (zuul-build-sshkey) 2026-08-14 09:01:41.484217 | instance -> localhost | ok: Runtime: 0:00:00.117382 2026-08-14 09:01:41.492786 | 2026-08-14 09:01:41.492879 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-14 09:01:41.868213 | instance | ok 2026-08-14 09:01:41.874657 | 2026-08-14 09:01:41.874751 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-14 09:01:41.899750 | instance | skipping: Conditional result was False 2026-08-14 09:01:41.915215 | 2026-08-14 09:01:41.915305 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-08-14 09:01:42.249198 | instance | ok 2026-08-14 09:01:42.256823 | 2026-08-14 09:01:42.257131 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-08-14 09:01:56.678425 | instance | Output suppressed because no_log was given 2026-08-14 09:01:56.690845 | 2026-08-14 09:01:56.690947 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-08-14 09:01:56.878959 | instance | ok: "logs" 2026-08-14 09:01:56.879226 | instance | ok: All items complete 2026-08-14 09:01:56.879256 | 2026-08-14 09:01:57.013139 | instance | ok: "artifacts" 2026-08-14 09:01:57.184817 | instance | ok: "docs" 2026-08-14 09:01:57.207232 | 2026-08-14 09:01:57.207427 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-08-14 09:01:57.390211 | instance | changed: "logs" 2026-08-14 09:01:57.549740 | instance | changed: "artifacts" 2026-08-14 09:01:57.713092 | instance | changed: "docs" 2026-08-14 09:01:59.594049 | 2026-08-14 09:01:59.594467 | PLAY RECAP 2026-08-14 09:01:59.594548 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-08-14 09:01:59.594595 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-14 09:01:59.594628 | 2026-08-14 09:01:59.805659 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-08-14 09:01:59.808354 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-08-14 09:02:00.438911 | 2026-08-14 09:02:00.439095 | PLAY [all] 2026-08-14 09:02:00.454011 | 2026-08-14 09:02:00.454288 | TASK [setup-uv : Extract archive] 2026-08-14 09:02:02.909931 | instance | changed 2026-08-14 09:02:02.997603 | 2026-08-14 09:02:02.997775 | TASK [setup-uv : Print version] 2026-08-14 09:02:03.366887 | instance | uv 0.8.13 2026-08-14 09:02:03.541729 | instance | ok: Runtime: 0:00:00.014209 2026-08-14 09:02:03.554129 | 2026-08-14 09:02:03.554223 | TASK [Install Ansible collection siblings] 2026-08-14 09:02:12.765557 | instance | changed: 2026-08-14 09:02:12.765785 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-08-14 09:02:12.765827 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-08-14 09:02:12.765853 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-08-14 09:02:12.765873 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto 2026-08-14 09:02:12.765893 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general 2026-08-14 09:02:12.765917 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql 2026-08-14 09:02:12.765937 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-08-14 09:02:12.765959 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-08-14 09:02:12.765978 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-08-14 09:02:12.765998 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-08-14 09:02:12.766017 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-08-14 09:02:12.766036 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-08-14 09:02:12.766054 | instance | Resolved ansible.netcommon to v8.5.0 for 8.5.0 2026-08-14 09:02:12.766072 | instance | Resolved ansible.posix to 2.1.0 for 2.1.0 2026-08-14 09:02:12.766090 | instance | Resolved ansible.utils to v6.1.0 for >=6.0.0,>=3.0.0 2026-08-14 09:02:12.766111 | instance | Resolved community.crypto to 3.2.0 for 3.2.0 2026-08-14 09:02:12.766138 | instance | Resolved community.general to 12.6.0 for 12.6.0 2026-08-14 09:02:12.766163 | instance | Resolved community.mysql to 4.2.0 for 4.2.0 2026-08-14 09:02:12.766185 | instance | Resolved kubernetes.core to 6.3.0 for 6.3.0 2026-08-14 09:02:12.766204 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0 2026-08-14 09:02:12.766222 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0 2026-08-14 09:02:12.766240 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0 2026-08-14 09:02:12.766260 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-08-14 09:02:12.766289 | instance | Resolved ansible.utils to v6.1.0 for >=6.0.0,>=3.0.0 2026-08-14 09:02:12.766309 | instance | Resolved community.general to 12.6.0 for 12.6.0,>=4.5.0 2026-08-14 09:02:12.766327 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-08-14 09:02:12.766345 | instance | Resolved kubernetes.core to 6.3.0 for 6.3.0,>=2.4.0,>=2.3.2 2026-08-14 09:02:12.766363 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-08-14 09:02:12.766380 | instance | Resolved ansible.posix to 2.1.0 for 2.1.0,>=1.6.0 2026-08-14 09:02:12.766398 | instance | Resolved community.crypto to 3.2.0 for 3.2.0,>=2.2.3 2026-08-14 09:02:12.766416 | instance | Resolved kubernetes.core to 6.3.0 for 6.3.0,>=2.4.0,>=2.3.2 2026-08-14 09:02:12.766434 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-08-14 09:02:12.766452 | instance | Checked out ansible.netcommon to v8.5.0 2026-08-14 09:02:12.766470 | 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-14 09:02:12.766497 | instance | Starting galaxy collection install process 2026-08-14 09:02:12.766515 | instance | Process install dependency map 2026-08-14 09:02:12.766534 | instance | Starting collection install process 2026-08-14 09:02:12.766553 | instance | Installing 'ansible.netcommon:8.5.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-08-14 09:02:12.766570 | instance | Created collection for ansible.netcommon:8.5.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon 2026-08-14 09:02:12.766588 | instance | ansible.netcommon:8.5.0 was installed successfully 2026-08-14 09:02:12.766605 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-08-14 09:02:12.766623 | instance | Creating virtual environment at: .venv 2026-08-14 09:02:12.766640 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-08-14 09:02:12.766658 | instance | Downloading rjsonnet (1.2MiB) 2026-08-14 09:02:12.766675 | instance | Downloading openstacksdk (1.7MiB) 2026-08-14 09:02:12.766696 | instance | Downloading kubernetes (1.9MiB) 2026-08-14 09:02:12.766715 | instance | Downloading pygments (1.2MiB) 2026-08-14 09:02:12.766732 | instance | Downloading pydantic-core (2.0MiB) 2026-08-14 09:02:12.766750 | instance | Downloading cryptography (4.2MiB) 2026-08-14 09:02:12.766768 | instance | Downloading netaddr (2.2MiB) 2026-08-14 09:02:12.766786 | instance | Downloading ansible-core (2.1MiB) 2026-08-14 09:02:12.766804 | instance | Downloading setuptools (1.1MiB) 2026-08-14 09:02:12.766822 | instance | Building pyperclip==1.9.0 2026-08-14 09:02:12.766844 | instance | Downloading rjsonnet 2026-08-14 09:02:12.766863 | instance | Downloading pydantic-core 2026-08-14 09:02:12.766881 | instance | Downloading netaddr 2026-08-14 09:02:12.766899 | instance | Downloading pygments 2026-08-14 09:02:12.766916 | instance | Downloading cryptography 2026-08-14 09:02:12.766934 | instance | Downloading setuptools 2026-08-14 09:02:12.766951 | instance | Downloading kubernetes 2026-08-14 09:02:12.766969 | instance | Downloading ansible-core 2026-08-14 09:02:12.766987 | instance | Downloading openstacksdk 2026-08-14 09:02:12.767005 | instance | Built pyperclip==1.9.0 2026-08-14 09:02:12.767023 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-08-14 09:02:12.767041 | instance | Installed 83 packages in 38ms 2026-08-14 09:02:12.767058 | instance | Checked out ansible.posix to 2.1.0 2026-08-14 09:02:12.767076 | 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-14 09:02:12.767093 | instance | Starting galaxy collection install process 2026-08-14 09:02:12.767111 | instance | Process install dependency map 2026-08-14 09:02:12.767133 | instance | Starting collection install process 2026-08-14 09:02:12.767152 | instance | Installing 'ansible.posix:2.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-08-14 09:02:12.767170 | instance | Created collection for ansible.posix:2.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix 2026-08-14 09:02:12.767187 | instance | ansible.posix:2.1.0 was installed successfully 2026-08-14 09:02:12.767205 | instance | Checked out ansible.utils to v6.1.0 2026-08-14 09:02:12.767223 | 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-14 09:02:12.767240 | instance | Starting galaxy collection install process 2026-08-14 09:02:12.767258 | instance | Process install dependency map 2026-08-14 09:02:12.767275 | instance | Starting collection install process 2026-08-14 09:02:12.767293 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-08-14 09:02:12.767311 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils 2026-08-14 09:02:12.767329 | instance | ansible.utils:6.1.0 was installed successfully 2026-08-14 09:02:12.767347 | instance | Checked out atmosphere.common to v0.7.0 2026-08-14 09:02:12.767365 | 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-14 09:02:12.767383 | instance | Starting galaxy collection install process 2026-08-14 09:02:12.767401 | instance | Process install dependency map 2026-08-14 09:02:12.767419 | instance | Starting collection install process 2026-08-14 09:02:12.767436 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common' 2026-08-14 09:02:12.767453 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common 2026-08-14 09:02:12.767471 | instance | atmosphere.common:0.7.0 was installed successfully 2026-08-14 09:02:12.767488 | instance | Checked out community.crypto to 3.2.0 2026-08-14 09:02:12.767506 | 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-14 09:02:12.767524 | instance | Starting galaxy collection install process 2026-08-14 09:02:12.767555 | instance | Process install dependency map 2026-08-14 09:02:12.767574 | instance | Starting collection install process 2026-08-14 09:02:12.767592 | instance | Installing 'community.crypto:3.2.0' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-08-14 09:02:12.767609 | instance | Created collection for community.crypto:3.2.0 at /home/zuul/.ansible/collections/ansible_collections/community/crypto 2026-08-14 09:02:12.767627 | instance | community.crypto:3.2.0 was installed successfully 2026-08-14 09:02:12.767645 | instance | Checked out community.general to 12.6.0 2026-08-14 09:02:12.767663 | 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-14 09:02:12.767681 | instance | Starting galaxy collection install process 2026-08-14 09:02:12.767699 | instance | Process install dependency map 2026-08-14 09:02:12.767716 | instance | Starting collection install process 2026-08-14 09:02:12.767734 | instance | Installing 'community.general:12.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-08-14 09:02:12.767751 | instance | Created collection for community.general:12.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/general 2026-08-14 09:02:12.767774 | instance | community.general:12.6.0 was installed successfully 2026-08-14 09:02:12.767792 | instance | Checked out community.mysql to 4.2.0 2026-08-14 09:02:12.767811 | 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-14 09:02:12.767828 | instance | Starting galaxy collection install process 2026-08-14 09:02:12.767846 | instance | Process install dependency map 2026-08-14 09:02:12.767863 | instance | Starting collection install process 2026-08-14 09:02:12.767880 | instance | Installing 'community.mysql:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-08-14 09:02:12.767897 | instance | Created collection for community.mysql:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql 2026-08-14 09:02:12.767915 | instance | community.mysql:4.2.0 was installed successfully 2026-08-14 09:02:12.767933 | instance | Checked out kubernetes.core to 6.3.0 2026-08-14 09:02:12.767951 | 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-14 09:02:12.767969 | instance | Starting galaxy collection install process 2026-08-14 09:02:12.767988 | instance | Process install dependency map 2026-08-14 09:02:12.768151 | instance | Starting collection install process 2026-08-14 09:02:12.768169 | instance | Installing 'kubernetes.core:6.3.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-08-14 09:02:12.768187 | instance | Created collection for kubernetes.core:6.3.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core 2026-08-14 09:02:12.768204 | instance | kubernetes.core:6.3.0 was installed successfully 2026-08-14 09:02:12.768222 | instance | Checked out openstack.cloud to 2.6.0 2026-08-14 09:02:12.768239 | 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-14 09:02:12.768257 | instance | Starting galaxy collection install process 2026-08-14 09:02:12.768274 | instance | Process install dependency map 2026-08-14 09:02:12.768292 | instance | Starting collection install process 2026-08-14 09:02:12.768310 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-08-14 09:02:12.768327 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud 2026-08-14 09:02:12.768344 | instance | openstack.cloud:2.6.0 was installed successfully 2026-08-14 09:02:12.768362 | instance | Checked out vexxhost.ceph to v4.2.0 2026-08-14 09:02:12.768379 | 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-14 09:02:12.768396 | instance | Starting galaxy collection install process 2026-08-14 09:02:12.768414 | instance | Process install dependency map 2026-08-14 09:02:12.768431 | instance | Starting collection install process 2026-08-14 09:02:12.768449 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph' 2026-08-14 09:02:12.768467 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph 2026-08-14 09:02:12.768484 | instance | vexxhost.ceph:4.2.0 was installed successfully 2026-08-14 09:02:12.768502 | instance | Checked out vexxhost.containers to v1.6.8 2026-08-14 09:02:12.768520 | 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-14 09:02:12.768537 | instance | Starting galaxy collection install process 2026-08-14 09:02:12.768559 | instance | Process install dependency map 2026-08-14 09:02:12.768577 | instance | Starting collection install process 2026-08-14 09:02:12.768595 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers' 2026-08-14 09:02:12.768612 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers 2026-08-14 09:02:12.768630 | instance | vexxhost.containers:1.6.8 was installed successfully 2026-08-14 09:02:12.768648 | instance | Checked out vexxhost.kubernetes to v3.3.0 2026-08-14 09:02:12.768665 | 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-14 09:02:12.768683 | instance | Starting galaxy collection install process 2026-08-14 09:02:12.768700 | instance | Process install dependency map 2026-08-14 09:02:12.768718 | instance | Starting collection install process 2026-08-14 09:02:12.768735 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes' 2026-08-14 09:02:12.768752 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes 2026-08-14 09:02:12.768770 | instance | vexxhost.kubernetes:3.3.0 was installed successfully 2026-08-14 09:02:12.774280 | 2026-08-14 09:02:12.774325 | PLAY RECAP 2026-08-14 09:02:12.774366 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-14 09:02:12.774387 | 2026-08-14 09:02:12.918399 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-08-14 09:02:12.921022 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-08-14 09:02:13.582616 | 2026-08-14 09:02:13.797163 | PLAY [all] 2026-08-14 09:02:13.814751 | 2026-08-14 09:02:13.814915 | TASK [Install "jq" for log collection] 2026-08-14 09:02:49.809566 | instance | changed 2026-08-14 09:02:49.812843 | 2026-08-14 09:02:49.813004 | PLAY RECAP 2026-08-14 09:02:49.813093 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-14 09:02:49.813163 | 2026-08-14 09:02:49.958373 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-08-14 09:02:49.962887 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-08-14 09:02:50.632913 | 2026-08-14 09:02:50.633090 | PLAY [all] 2026-08-14 09:02:50.645187 | 2026-08-14 09:02:50.645335 | LOOP [Check inventory directories] 2026-08-14 09:02:50.975782 | instance | ok: "src/github.com/vexxhost/atmosphere" 2026-08-14 09:02:51.123362 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory" 2026-08-14 09:02:51.274831 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory" 2026-08-14 09:02:51.287871 | 2026-08-14 09:02:51.288120 | LOOP [Copy inventory file for Zuul] 2026-08-14 09:02:52.185453 | instance | changed: 2026-08-14 09:02:52.186556 | instance | { 2026-08-14 09:02:52.186636 | instance | "ansible_loop_var": "item", 2026-08-14 09:02:52.186689 | instance | "changed": false, 2026-08-14 09:02:52.186783 | instance | "failed": false, 2026-08-14 09:02:52.186848 | instance | "invocation": { 2026-08-14 09:02:52.186900 | instance | "module_args": { 2026-08-14 09:02:52.186948 | instance | "checksum_algorithm": "sha1", 2026-08-14 09:02:52.186995 | instance | "follow": true, 2026-08-14 09:02:52.187043 | instance | "get_attributes": true, 2026-08-14 09:02:52.187329 | instance | "get_checksum": true, 2026-08-14 09:02:52.187392 | instance | "get_mime": true, 2026-08-14 09:02:52.187436 | instance | "path": "src/github.com/vexxhost/atmosphere" 2026-08-14 09:02:52.187494 | instance | } 2026-08-14 09:02:52.187535 | instance | }, 2026-08-14 09:02:52.187573 | instance | "item": "src/github.com/vexxhost/atmosphere", 2026-08-14 09:02:52.187612 | instance | "stat": { 2026-08-14 09:02:52.187651 | instance | "atime": 1786698125.3855405, 2026-08-14 09:02:52.187690 | instance | "attr_flags": "e", 2026-08-14 09:02:52.187728 | instance | "attributes": [ 2026-08-14 09:02:52.187766 | instance | "extents" 2026-08-14 09:02:52.187803 | instance | ], 2026-08-14 09:02:52.187842 | instance | "block_size": 4096, 2026-08-14 09:02:52.187880 | instance | "blocks": 8, 2026-08-14 09:02:52.187919 | instance | "charset": "unknown", 2026-08-14 09:02:52.187963 | instance | "ctime": 1786698124.34153, 2026-08-14 09:02:52.188003 | instance | "dev": 64513, 2026-08-14 09:02:52.188042 | instance | "device_type": 0, 2026-08-14 09:02:52.188080 | instance | "executable": true, 2026-08-14 09:02:52.188118 | instance | "exists": true, 2026-08-14 09:02:52.188159 | instance | "gid": 1000, 2026-08-14 09:02:52.188197 | instance | "gr_name": "zuul", 2026-08-14 09:02:52.188236 | instance | "inode": 1047438, 2026-08-14 09:02:52.188274 | instance | "isblk": false, 2026-08-14 09:02:52.188313 | instance | "ischr": false, 2026-08-14 09:02:52.188352 | instance | "isdir": true, 2026-08-14 09:02:52.188391 | instance | "isfifo": false, 2026-08-14 09:02:52.188436 | instance | "isgid": false, 2026-08-14 09:02:52.188476 | instance | "islnk": false, 2026-08-14 09:02:52.188519 | instance | "isreg": false, 2026-08-14 09:02:52.188559 | instance | "issock": false, 2026-08-14 09:02:52.188609 | instance | "isuid": false, 2026-08-14 09:02:52.188657 | instance | "mimetype": "unknown", 2026-08-14 09:02:52.188698 | instance | "mode": "0755", 2026-08-14 09:02:52.189394 | instance | "mtime": 1786698124.34153, 2026-08-14 09:02:52.189438 | instance | "nlink": 25, 2026-08-14 09:02:52.189472 | instance | "path": "src/github.com/vexxhost/atmosphere", 2026-08-14 09:02:52.189505 | instance | "pw_name": "zuul", 2026-08-14 09:02:52.189541 | instance | "readable": true, 2026-08-14 09:02:52.189570 | instance | "rgrp": true, 2026-08-14 09:02:52.189605 | instance | "roth": true, 2026-08-14 09:02:52.189632 | instance | "rusr": true, 2026-08-14 09:02:52.189658 | instance | "size": 4096, 2026-08-14 09:02:52.189683 | instance | "uid": 1000, 2026-08-14 09:02:52.189709 | instance | "version": "241356651", 2026-08-14 09:02:52.189735 | instance | "wgrp": false, 2026-08-14 09:02:52.189764 | instance | "woth": false, 2026-08-14 09:02:52.189790 | instance | "writeable": true, 2026-08-14 09:02:52.189816 | instance | "wusr": true, 2026-08-14 09:02:52.189842 | instance | "xgrp": true, 2026-08-14 09:02:52.189868 | instance | "xoth": true, 2026-08-14 09:02:52.189893 | instance | "xusr": true 2026-08-14 09:02:52.189918 | instance | } 2026-08-14 09:02:52.189944 | instance | } 2026-08-14 09:02:52.190006 | instance | ok: All items complete 2026-08-14 09:02:52.190040 | 2026-08-14 09:02:52.192482 | instance | skipping: Conditional result was False 2026-08-14 09:02:52.193435 | instance | skipping: Conditional result was False 2026-08-14 09:02:52.208200 | 2026-08-14 09:02:52.208370 | LOOP [Switch "ansible_host" to private IP] 2026-08-14 09:02:52.595170 | instance | changed: 1 replacements made 2026-08-14 09:02:52.596935 | instance | skipping: Conditional result was False 2026-08-14 09:02:52.600948 | instance | skipping: Conditional result was False 2026-08-14 09:02:52.618185 | 2026-08-14 09:02:52.618402 | TASK [Run Molecule scenario] 2026-08-14 09:02:53.575571 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-08-14 09:02:53.980926 | instance | INFO [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-08-14 09:02:53.981042 | instance | INFO [csi > prerun] Performing prerun with role_name_check=0... 2026-08-14 09:03:04.610480 | instance | INFO [csi > dependency] Executing 2026-08-14 09:03:04.610555 | instance | WARNING [csi > dependency] Missing roles requirements file: requirements.yml 2026-08-14 09:03:04.610575 | instance | WARNING [csi > dependency] Missing collections requirements file: collections.yml 2026-08-14 09:03:04.610598 | instance | WARNING [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-08-14 09:03:04.617884 | instance | INFO [csi > cleanup] Executing 2026-08-14 09:03:04.618202 | instance | WARNING [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-08-14 09:03:04.625573 | instance | INFO [csi > destroy] Executing 2026-08-14 09:03:04.625607 | instance | WARNING [csi > destroy] Skipping, '--destroy=never' requested. 2026-08-14 09:03:04.625741 | instance | INFO [csi > destroy] Executed: Successful 2026-08-14 09:03:04.632931 | instance | INFO [csi > syntax] Executing 2026-08-14 09:03:05.287410 | instance | [WARNING]: provided hosts list is empty, only localhost is available. Note that 2026-08-14 09:03:05.287639 | instance | the implicit localhost does not match 'all' 2026-08-14 09:03:05.419297 | instance | 2026-08-14 09:03:05.419555 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-08-14 09:03:05.479307 | instance | INFO [csi > syntax] Executed: Successful 2026-08-14 09:03:05.493805 | instance | INFO [csi > create] Executing 2026-08-14 09:03:05.496275 | instance | WARNING [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-08-14 09:03:05.503743 | instance | INFO [csi > prepare] Executing 2026-08-14 09:03:06.169030 | instance | [WARNING]: provided hosts list is empty, only localhost is available. Note that 2026-08-14 09:03:06.169229 | instance | the implicit localhost does not match 'all' 2026-08-14 09:03:06.299993 | instance | [WARNING]: Could not match supplied host pattern, ignoring: cephs 2026-08-14 09:03:06.300308 | instance | 2026-08-14 09:03:06.300627 | instance | PLAY [Prepare] ***************************************************************** 2026-08-14 09:03:06.300933 | instance | skipping: no hosts matched 2026-08-14 09:03:06.301231 | instance | 2026-08-14 09:03:06.301543 | instance | PLAY [Create devices for Ceph] ************************************************* 2026-08-14 09:03:06.301929 | instance | skipping: no hosts matched 2026-08-14 09:03:06.302236 | instance | 2026-08-14 09:03:06.302543 | instance | PLAY RECAP ********************************************************************* 2026-08-14 09:03:06.302831 | instance | 2026-08-14 09:03:06.303120 | instance | 2026-08-14 09:03:06.303426 | instance | TASKS RECAP ******************************************************************** 2026-08-14 09:03:06.303762 | instance | Friday 14 August 2026 09:03:06 +0000 (0:00:00.012) 0:00:00.012 ********* 2026-08-14 09:03:06.304053 | instance | =============================================================================== 2026-08-14 09:03:06.352038 | instance | INFO [csi > prepare] Executed: Successful 2026-08-14 09:03:06.364563 | instance | INFO [csi > converge] Executing 2026-08-14 09:03:06.990399 | instance | [WARNING]: provided hosts list is empty, only localhost is available. Note that 2026-08-14 09:03:06.990743 | instance | the implicit localhost does not match 'all' 2026-08-14 09:03:07.051297 | instance | 2026-08-14 09:03:07.051539 | instance | PLAY [all] ********************************************************************* 2026-08-14 09:03:07.051807 | instance | skipping: no hosts matched 2026-08-14 09:03:07.052058 | instance | 2026-08-14 09:03:07.052367 | instance | PLAY RECAP ********************************************************************* 2026-08-14 09:03:07.052616 | instance | 2026-08-14 09:03:07.052872 | instance | 2026-08-14 09:03:07.053128 | instance | TASKS RECAP ******************************************************************** 2026-08-14 09:03:07.053397 | instance | Friday 14 August 2026 09:03:07 +0000 (0:00:00.001) 0:00:00.001 ********* 2026-08-14 09:03:07.053660 | instance | =============================================================================== 2026-08-14 09:03:07.093652 | instance | INFO [csi > converge] Executed: Successful 2026-08-14 09:03:07.105722 | instance | INFO [csi > idempotence] Executing 2026-08-14 09:03:07.698179 | instance | [WARNING]: provided hosts list is empty, only localhost is available. Note that 2026-08-14 09:03:07.698310 | instance | the implicit localhost does not match 'all' 2026-08-14 09:03:07.759118 | instance | 2026-08-14 09:03:07.759208 | instance | PLAY [all] ********************************************************************* 2026-08-14 09:03:07.759342 | instance | skipping: no hosts matched 2026-08-14 09:03:07.759513 | instance | 2026-08-14 09:03:07.759634 | instance | PLAY RECAP ********************************************************************* 2026-08-14 09:03:07.759771 | instance | 2026-08-14 09:03:07.759871 | instance | 2026-08-14 09:03:07.759994 | instance | TASKS RECAP ******************************************************************** 2026-08-14 09:03:07.760120 | instance | Friday 14 August 2026 09:03:07 +0000 (0:00:00.001) 0:00:00.001 ********* 2026-08-14 09:03:07.760267 | instance | =============================================================================== 2026-08-14 09:03:07.800709 | instance | INFO [csi > idempotence] Executed: Successful 2026-08-14 09:03:07.815676 | instance | INFO [csi > side_effect] Executing 2026-08-14 09:03:07.815987 | instance | WARNING [csi > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-08-14 09:03:07.823536 | instance | INFO [csi > verify] Executing 2026-08-14 09:03:08.426621 | instance | [WARNING]: provided hosts list is empty, only localhost is available. Note that 2026-08-14 09:03:08.426867 | instance | the implicit localhost does not match 'all' 2026-08-14 09:03:08.470437 | instance | [WARNING]: Could not match supplied host pattern, ignoring: controllers 2026-08-14 09:03:08.470812 | instance | 2026-08-14 09:03:08.471153 | instance | PLAY [Verify] ****************************************************************** 2026-08-14 09:03:08.471468 | instance | skipping: no hosts matched 2026-08-14 09:03:08.471767 | instance | 2026-08-14 09:03:08.472079 | instance | PLAY RECAP ********************************************************************* 2026-08-14 09:03:08.472371 | instance | 2026-08-14 09:03:08.472663 | instance | 2026-08-14 09:03:08.473161 | instance | TASKS RECAP ******************************************************************** 2026-08-14 09:03:08.473432 | instance | Friday 14 August 2026 09:03:08 +0000 (0:00:00.001) 0:00:00.001 ********* 2026-08-14 09:03:08.473647 | instance | =============================================================================== 2026-08-14 09:03:08.506703 | instance | INFO [csi > verify] Executed: Successful 2026-08-14 09:03:08.518422 | instance | INFO [csi > cleanup] Executing 2026-08-14 09:03:08.518720 | instance | WARNING [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-08-14 09:03:08.525960 | instance | INFO [csi > destroy] Executing 2026-08-14 09:03:08.525993 | instance | WARNING [csi > destroy] Skipping, '--destroy=never' requested. 2026-08-14 09:03:08.526074 | instance | INFO [csi > destroy] Executed: Successful 2026-08-14 09:03:08.526359 | instance | WARNING Molecule executed 1 scenario (1 missing files) 2026-08-14 09:03:08.681059 | instance | ok: Runtime: 0:00:15.597651 2026-08-14 09:03:08.685340 | 2026-08-14 09:03:08.685455 | PLAY RECAP 2026-08-14 09:03:08.685505 | instance | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-14 09:03:08.685529 | 2026-08-14 09:03:08.868274 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-08-14 09:03:08.872856 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-08-14 09:03:09.531757 | 2026-08-14 09:03:09.531894 | PLAY [all] 2026-08-14 09:03:09.546423 | 2026-08-14 09:03:09.546512 | TASK [gather-host-logs : creating directory for system status] 2026-08-14 09:03:09.920293 | instance | changed 2026-08-14 09:03:09.925817 | 2026-08-14 09:03:09.925909 | TASK [gather-host-logs : Get logs for each host] 2026-08-14 09:03:10.315753 | instance | + systemd-cgls --full --all --no-pager 2026-08-14 09:03:10.325208 | instance | + ip addr 2026-08-14 09:03:10.326848 | instance | + ip route 2026-08-14 09:03:10.328206 | instance | + lsblk 2026-08-14 09:03:10.330711 | instance | + mount 2026-08-14 09:03:10.331860 | instance | + docker images 2026-08-14 09:03:10.332166 | instance | /bin/bash: line 7: docker: command not found 2026-08-14 09:03:10.332325 | instance | + brctl show 2026-08-14 09:03:10.332624 | instance | /bin/bash: line 8: brctl: command not found 2026-08-14 09:03:10.332793 | instance | + ps aux --sort=-%mem 2026-08-14 09:03:10.341092 | instance | + dpkg -l 2026-08-14 09:03:10.348630 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-08-14 09:03:10.348958 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-08-14 09:03:10.349011 | instance | /bin/bash: line 11: docker: command not found 2026-08-14 09:03:10.349266 | instance | + '[' '!' -z '' ']' 2026-08-14 09:03:10.464258 | instance | ok: Runtime: 0:00:00.039201 2026-08-14 09:03:10.472995 | 2026-08-14 09:03:10.473113 | TASK [gather-host-logs : Downloads logs to executor] 2026-08-14 09:03:11.142544 | instance | changed: 2026-08-14 09:03:11.142760 | instance | created directory /var/lib/zuul/builds/60529035895f45e790e2e354c0bac87c/work/logs/instance 2026-08-14 09:03:11.142801 | instance | cd+++++++++ system/ 2026-08-14 09:03:11.142831 | instance | >f+++++++++ system/brctl-show.txt 2026-08-14 09:03:11.142860 | instance | >f+++++++++ system/docker-images.txt 2026-08-14 09:03:11.142888 | instance | >f+++++++++ system/ip-addr.txt 2026-08-14 09:03:11.142923 | instance | >f+++++++++ system/ip-route.txt 2026-08-14 09:03:11.142952 | instance | >f+++++++++ system/lsblk.txt 2026-08-14 09:03:11.142980 | instance | >f+++++++++ system/mount.txt 2026-08-14 09:03:11.143010 | instance | >f+++++++++ system/packages.txt 2026-08-14 09:03:11.143038 | instance | >f+++++++++ system/ps.txt 2026-08-14 09:03:11.143065 | instance | >f+++++++++ system/systemd-cgls.txt 2026-08-14 09:03:11.154018 | 2026-08-14 09:03:11.154096 | LOOP [helm-release-status : creating directory for helm release status] 2026-08-14 09:03:11.373398 | instance | changed: "values" 2026-08-14 09:03:11.537777 | instance | changed: "releases" 2026-08-14 09:03:11.562004 | 2026-08-14 09:03:11.562248 | TASK [helm-release-status : Gather get release status for helm charts] 2026-08-14 09:03:11.810516 | instance | /bin/bash: line 3: kubectl: command not found 2026-08-14 09:03:12.097751 | instance | ok: Runtime: 0:00:00.007056 2026-08-14 09:03:12.104782 | 2026-08-14 09:03:12.104978 | TASK [helm-release-status : Downloads logs to executor] 2026-08-14 09:03:12.628127 | instance | changed: 2026-08-14 09:03:12.628358 | instance | cd+++++++++ helm/ 2026-08-14 09:03:12.628404 | instance | cd+++++++++ helm/releases/ 2026-08-14 09:03:12.628445 | instance | cd+++++++++ helm/values/ 2026-08-14 09:03:12.641887 | 2026-08-14 09:03:12.642134 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-08-14 09:03:12.830543 | instance | changed 2026-08-14 09:03:12.840035 | 2026-08-14 09:03:12.840127 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-08-14 09:03:13.109653 | 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-14 09:03:13.109948 | instance | 2026-08-14 09:03:13.115514 | instance | environment: line 1: kubectl: command not found 2026-08-14 09:03:13.117141 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-08-14 09:03:13.118472 | instance | environment: line 1: kubectl: command not found 2026-08-14 09:03:13.119519 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-08-14 09:03:13.120636 | instance | environment: line 1: kubectl: command not found 2026-08-14 09:03:13.122169 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-08-14 09:03:13.122841 | instance | environment: line 1: kubectl: command not found 2026-08-14 09:03:13.124067 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-08-14 09:03:13.125443 | instance | environment: line 1: kubectl: command not found 2026-08-14 09:03:13.126414 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-08-14 09:03:13.374645 | instance | ok: Runtime: 0:00:00.028985 2026-08-14 09:03:13.380707 | 2026-08-14 09:03:13.380777 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-08-14 09:03:13.577460 | instance | changed 2026-08-14 09:03:13.585953 | 2026-08-14 09:03:13.586062 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-08-14 09:03:13.809851 | instance | environment: line 5: kubectl: command not found 2026-08-14 09:03:13.810805 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-08-14 09:03:13.810926 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-08-14 09:03:13.811868 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-08-14 09:03:14.134498 | instance | ok: Runtime: 0:00:00.011238 2026-08-14 09:03:14.143272 | 2026-08-14 09:03:14.143398 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-08-14 09:03:14.643249 | instance | changed: 2026-08-14 09:03:14.643606 | instance | cd+++++++++ objects/ 2026-08-14 09:03:14.643646 | instance | cd+++++++++ objects/cluster/ 2026-08-14 09:03:14.643670 | instance | cd+++++++++ objects/namespaced/ 2026-08-14 09:03:14.654382 | 2026-08-14 09:03:14.654474 | TASK [gather-pod-logs : creating directory for pod logs] 2026-08-14 09:03:14.863357 | instance | changed 2026-08-14 09:03:14.869326 | 2026-08-14 09:03:14.869417 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-08-14 09:03:15.080230 | instance | changed 2026-08-14 09:03:15.085846 | 2026-08-14 09:03:15.085986 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-08-14 09:03:15.308840 | instance | environment: line 3: kubectl: command not found 2026-08-14 09:03:15.624421 | instance | ok: Runtime: 0:00:00.009607 2026-08-14 09:03:15.632254 | 2026-08-14 09:03:15.632351 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-08-14 09:03:16.154324 | instance | changed: 2026-08-14 09:03:16.311515 | instance | cd+++++++++ pod-logs/ 2026-08-14 09:03:16.311696 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-08-14 09:03:16.328743 | 2026-08-14 09:03:16.328830 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-08-14 09:03:16.545126 | instance | changed 2026-08-14 09:03:16.553191 | 2026-08-14 09:03:16.553291 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-08-14 09:03:16.765812 | instance | /bin/bash: line 2: kubectl: command not found 2026-08-14 09:03:17.091874 | instance | ok: Runtime: 0:00:00.034826 2026-08-14 09:03:17.097022 | 2026-08-14 09:03:17.097088 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-08-14 09:03:17.324199 | instance | /bin/bash: line 2: kubectl: command not found 2026-08-14 09:03:17.354465 | instance | ceph-mgr endpoints: 2026-08-14 09:03:17.645802 | instance | ok: Runtime: 0:00:00.037246 2026-08-14 09:03:17.652324 | 2026-08-14 09:03:17.652425 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-08-14 09:03:17.872587 | instance | /bin/bash: line 4: kubectl: command not found 2026-08-14 09:03:18.189660 | instance | ok: Runtime: 0:00:00.037400 2026-08-14 09:03:18.197118 | 2026-08-14 09:03:18.197218 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-08-14 09:03:18.955867 | instance | changed: cd+++++++++ prometheus/ 2026-08-14 09:03:18.966311 | 2026-08-14 09:03:18.966450 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-08-14 09:03:19.178618 | instance | changed 2026-08-14 09:03:19.183728 | 2026-08-14 09:03:19.183802 | TASK [gather-selenium-data : Get selenium data] 2026-08-14 09:03:19.437780 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-08-14 09:03:19.438888 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-08-14 09:03:19.719847 | instance | ERROR 2026-08-14 09:03:19.720099 | instance | { 2026-08-14 09:03:19.720143 | instance | "delta": "0:00:00.006268", 2026-08-14 09:03:19.720172 | instance | "end": "2026-08-14 09:03:19.439279", 2026-08-14 09:03:19.720198 | instance | "msg": "non-zero return code", 2026-08-14 09:03:19.720223 | instance | "rc": 1, 2026-08-14 09:03:19.720248 | instance | "start": "2026-08-14 09:03:19.433011" 2026-08-14 09:03:19.720272 | instance | } 2026-08-14 09:03:19.720303 | instance | ERROR: Ignoring Errors 2026-08-14 09:03:19.726018 | 2026-08-14 09:03:19.726151 | TASK [gather-selenium-data : Downloads logs to executor] 2026-08-14 09:03:20.232184 | instance | changed: cd+++++++++ selenium/ 2026-08-14 09:03:20.241330 | 2026-08-14 09:03:20.241469 | PLAY RECAP 2026-08-14 09:03:20.241545 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-08-14 09:03:20.241581 | 2026-08-14 09:03:20.435577 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-08-14 09:03:20.437849 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-08-14 09:03:21.372056 | 2026-08-14 09:03:21.372231 | PLAY [all] 2026-08-14 09:03:21.387005 | 2026-08-14 09:03:21.387114 | TASK [fetch-output : Set log path for multiple nodes] 2026-08-14 09:03:21.456833 | instance | skipping: Conditional result was False 2026-08-14 09:03:21.462356 | 2026-08-14 09:03:21.462443 | TASK [fetch-output : Set log path for single node] 2026-08-14 09:03:21.508579 | instance | ok 2026-08-14 09:03:21.515181 | 2026-08-14 09:03:21.515255 | LOOP [fetch-output : Ensure local output dirs] 2026-08-14 09:03:22.469251 | instance -> localhost | ok: "/var/lib/zuul/builds/60529035895f45e790e2e354c0bac87c/work/logs" 2026-08-14 09:03:22.851933 | instance -> localhost | changed: "/var/lib/zuul/builds/60529035895f45e790e2e354c0bac87c/work/artifacts" 2026-08-14 09:03:23.077236 | instance -> localhost | changed: "/var/lib/zuul/builds/60529035895f45e790e2e354c0bac87c/work/docs" 2026-08-14 09:03:23.090975 | 2026-08-14 09:03:23.091133 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-14 09:03:23.806598 | instance | changed: .d..t...... ./ 2026-08-14 09:03:23.806940 | instance | changed: All items complete 2026-08-14 09:03:23.806992 | 2026-08-14 09:03:24.306779 | instance | changed: .d..t...... ./ 2026-08-14 09:03:24.788702 | instance | changed: .d..t...... ./ 2026-08-14 09:03:24.814649 | 2026-08-14 09:03:24.814831 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-14 09:03:25.339014 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.016963 2026-08-14 09:03:25.575719 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007917 2026-08-14 09:03:25.584869 | 2026-08-14 09:03:25.585015 | PLAY [all] 2026-08-14 09:03:25.590845 | 2026-08-14 09:03:25.590915 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-14 09:03:26.031304 | instance | changed 2026-08-14 09:03:26.038672 | 2026-08-14 09:03:26.038723 | PLAY RECAP 2026-08-14 09:03:26.038772 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-08-14 09:03:26.038806 | 2026-08-14 09:03:26.199463 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-08-14 09:03:26.251134 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-14 09:03:28.117923 | 2026-08-14 09:03:28.118127 | PLAY [localhost] 2026-08-14 09:03:28.130289 | 2026-08-14 09:03:28.130404 | TASK [Generate Zuul manifest] 2026-08-14 09:03:28.151435 | localhost | ok 2026-08-14 09:03:28.203237 | 2026-08-14 09:03:28.203628 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-14 09:03:28.653087 | localhost | changed 2026-08-14 09:03:28.666742 | 2026-08-14 09:03:28.666846 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-14 09:03:28.760213 | localhost | ok 2026-08-14 09:03:28.770133 | 2026-08-14 09:03:28.770259 | TASK [Upload logs] 2026-08-14 09:03:28.792943 | localhost | ok 2026-08-14 09:03:28.910075 | 2026-08-14 09:03:28.910209 | TASK [Set zuul-log-path fact] 2026-08-14 09:03:28.944252 | localhost | ok 2026-08-14 09:03:28.966165 | 2026-08-14 09:03:28.966359 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-14 09:03:29.001548 | localhost | ok 2026-08-14 09:03:29.011288 | 2026-08-14 09:03:29.011387 | TASK [upload-logs : Create log directories] 2026-08-14 09:03:29.441003 | localhost | changed 2026-08-14 09:03:29.448571 | 2026-08-14 09:03:29.448752 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-14 09:03:29.916267 | localhost -> localhost | ok: Runtime: 0:00:00.005757 2026-08-14 09:03:29.924856 | 2026-08-14 09:03:29.924995 | TASK [upload-logs : Upload logs to log server] 2026-08-14 09:03:30.462913 | localhost | Output suppressed because no_log was given 2026-08-14 09:03:30.513854 | 2026-08-14 09:03:30.514009 | LOOP [upload-logs : Compress console log and json output] 2026-08-14 09:03:30.571520 | localhost | skipping: Conditional result was False 2026-08-14 09:03:30.581218 | localhost | skipping: Conditional result was False 2026-08-14 09:03:30.592853 | 2026-08-14 09:03:30.593091 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-14 09:03:30.638816 | localhost | skipping: Conditional result was False 2026-08-14 09:03:30.639236 | 2026-08-14 09:03:30.643039 | localhost | skipping: Conditional result was False 2026-08-14 09:03:30.658776 | 2026-08-14 09:03:30.658950 | LOOP [upload-logs : Upload console log and json output]