2026-09-08 00:16:50.990718 | Job console starting 2026-09-08 00:16:51.007748 | Updating git repos 2026-09-08 00:16:51.056306 | Cloning repos into workspace 2026-09-08 00:16:51.408490 | Restoring repo states 2026-09-08 00:16:51.415839 | Merging changes 2026-09-08 00:16:51.875536 | Checking out repos 2026-09-08 00:16:51.929769 | Preparing playbooks 2026-09-08 00:16:54.693010 | Running Ansible setup 2026-09-08 00:16:58.453524 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-08 00:16:59.095203 | 2026-09-08 00:16:59.095348 | PLAY [localhost] 2026-09-08 00:16:59.102352 | 2026-09-08 00:16:59.102414 | TASK [Gathering Facts] 2026-09-08 00:16:59.970146 | localhost | ok 2026-09-08 00:16:59.982669 | 2026-09-08 00:16:59.982809 | TASK [Setup log path fact] 2026-09-08 00:17:00.004243 | localhost | ok 2026-09-08 00:17:00.024912 | 2026-09-08 00:17:00.025068 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-08 00:17:00.056165 | localhost | ok 2026-09-08 00:17:00.066794 | 2026-09-08 00:17:00.066919 | TASK [emit-job-header : Print job information] 2026-09-08 00:17:00.128766 | # Job Information 2026-09-08 00:17:00.128975 | Ansible Version: 2.16.18 2026-09-08 00:17:00.129062 | Job: atmosphere-common-molecule-secretgen-controller 2026-09-08 00:17:00.129107 | Pipeline: check 2026-09-08 00:17:00.129147 | Executor: 2d72f0692154 2026-09-08 00:17:00.129185 | Triggered by: https://github.com/vexxhost/atmosphere.common/pull/97 2026-09-08 00:17:00.129229 | Event ID: 42b84cf0-ab1a-11f1-87ef-e0556d19cba1 2026-09-08 00:17:00.134340 | 2026-09-08 00:17:00.134462 | LOOP [emit-job-header : Print node information] 2026-09-08 00:17:00.245802 | localhost | ok: 2026-09-08 00:17:00.246156 | localhost | # Node Information 2026-09-08 00:17:00.246220 | localhost | Inventory Hostname: controller 2026-09-08 00:17:00.246262 | localhost | Hostname: np0000192185 2026-09-08 00:17:00.246308 | localhost | Username: zuul 2026-09-08 00:17:00.246372 | localhost | Distro: Ubuntu 24.04 2026-09-08 00:17:00.246415 | localhost | Provider: yul1 2026-09-08 00:17:00.246455 | localhost | Region: ca-ymq-1 2026-09-08 00:17:00.246492 | localhost | Label: ubuntu-noble 2026-09-08 00:17:00.246527 | localhost | Product Name: OpenStack Nova 2026-09-08 00:17:00.246562 | localhost | Interface IP: 162.253.55.210 2026-09-08 00:17:00.263083 | 2026-09-08 00:17:00.263264 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-08 00:17:00.673816 | localhost -> localhost | changed 2026-09-08 00:17:00.682532 | 2026-09-08 00:17:00.682665 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-08 00:17:01.577401 | localhost -> localhost | changed 2026-09-08 00:17:01.588161 | 2026-09-08 00:17:01.588279 | PLAY [all] 2026-09-08 00:17:01.603935 | 2026-09-08 00:17:01.604216 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-08 00:17:01.832121 | controller -> localhost | ok 2026-09-08 00:17:01.838103 | 2026-09-08 00:17:01.838177 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-08 00:17:01.860250 | controller | ok 2026-09-08 00:17:01.877191 | controller | included: /var/lib/zuul/builds/56ce1720346641b69adc6ccdaa2ecc5a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-08 00:17:01.884451 | 2026-09-08 00:17:01.884532 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-08 00:17:03.072282 | controller -> localhost | Generating public/private rsa key pair. 2026-09-08 00:17:03.072585 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/56ce1720346641b69adc6ccdaa2ecc5a/work/56ce1720346641b69adc6ccdaa2ecc5a_id_rsa 2026-09-08 00:17:03.072646 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/56ce1720346641b69adc6ccdaa2ecc5a/work/56ce1720346641b69adc6ccdaa2ecc5a_id_rsa.pub 2026-09-08 00:17:03.072688 | controller -> localhost | The key fingerprint is: 2026-09-08 00:17:03.072835 | controller -> localhost | SHA256:6pCbk6DEszYwQpob4qkb6wc0Fc16OC3KbeqkEX5MVws zuul-build-sshkey 2026-09-08 00:17:03.072894 | controller -> localhost | The key's randomart image is: 2026-09-08 00:17:03.072934 | controller -> localhost | +---[RSA 3072]----+ 2026-09-08 00:17:03.072998 | controller -> localhost | | .+ | 2026-09-08 00:17:03.073042 | controller -> localhost | | . o | 2026-09-08 00:17:03.073080 | controller -> localhost | | . +E . | 2026-09-08 00:17:03.073117 | controller -> localhost | | + = oo . | 2026-09-08 00:17:03.073153 | controller -> localhost | |B.+.+. .S | 2026-09-08 00:17:03.073190 | controller -> localhost | |XO+o.. . | 2026-09-08 00:17:03.073226 | controller -> localhost | |OBB+o.. | 2026-09-08 00:17:03.073262 | controller -> localhost | |=@..o= | 2026-09-08 00:17:03.073304 | controller -> localhost | |X+o o.. | 2026-09-08 00:17:03.073341 | controller -> localhost | +----[SHA256]-----+ 2026-09-08 00:17:03.073431 | controller -> localhost | ok: Runtime: 0:00:00.737850 2026-09-08 00:17:03.084887 | 2026-09-08 00:17:03.085027 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-08 00:17:03.118482 | controller | ok 2026-09-08 00:17:03.134718 | controller | included: /var/lib/zuul/builds/56ce1720346641b69adc6ccdaa2ecc5a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-08 00:17:03.147099 | 2026-09-08 00:17:03.147177 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-08 00:17:03.171316 | controller | skipping: Conditional result was False 2026-09-08 00:17:03.183582 | 2026-09-08 00:17:03.184028 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-08 00:17:03.731719 | controller | changed 2026-09-08 00:17:03.741820 | 2026-09-08 00:17:03.741943 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-08 00:17:04.000523 | controller | ok 2026-09-08 00:17:04.010895 | 2026-09-08 00:17:04.011061 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-08 00:17:04.633534 | controller | changed 2026-09-08 00:17:04.644793 | 2026-09-08 00:17:04.644924 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-08 00:17:05.223816 | controller | changed 2026-09-08 00:17:05.236380 | 2026-09-08 00:17:05.236669 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-08 00:17:05.273452 | controller | skipping: Conditional result was False 2026-09-08 00:17:05.289300 | 2026-09-08 00:17:05.289525 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-08 00:17:05.730813 | controller -> localhost | changed 2026-09-08 00:17:05.751567 | 2026-09-08 00:17:05.751702 | TASK [add-build-sshkey : Add back temp key] 2026-09-08 00:17:06.062132 | controller -> localhost | Identity added: /var/lib/zuul/builds/56ce1720346641b69adc6ccdaa2ecc5a/work/56ce1720346641b69adc6ccdaa2ecc5a_id_rsa (zuul-build-sshkey) 2026-09-08 00:17:06.062487 | controller -> localhost | ok: Runtime: 0:00:00.015674 2026-09-08 00:17:06.073364 | 2026-09-08 00:17:06.073485 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-08 00:17:06.456630 | controller | ok 2026-09-08 00:17:06.466430 | 2026-09-08 00:17:06.466555 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-08 00:17:06.501745 | controller | skipping: Conditional result was False 2026-09-08 00:17:06.521794 | 2026-09-08 00:17:06.521916 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-08 00:17:06.987929 | controller | ok 2026-09-08 00:17:06.995818 | 2026-09-08 00:17:06.995956 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-08 00:17:08.639898 | controller | Output suppressed because no_log was given 2026-09-08 00:17:08.659309 | 2026-09-08 00:17:08.659454 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-08 00:17:08.931380 | controller | ok: "logs" 2026-09-08 00:17:08.931652 | controller | ok: All items complete 2026-09-08 00:17:08.931689 | 2026-09-08 00:17:09.124147 | controller | ok: "artifacts" 2026-09-08 00:17:09.316992 | controller | ok: "docs" 2026-09-08 00:17:09.336284 | 2026-09-08 00:17:09.336566 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-08 00:17:09.576524 | controller | changed: "logs" 2026-09-08 00:17:09.796820 | controller | changed: "artifacts" 2026-09-08 00:17:09.992199 | controller | changed: "docs" 2026-09-08 00:17:10.017168 | 2026-09-08 00:17:10.017363 | PLAY RECAP 2026-09-08 00:17:10.017460 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-08 00:17:10.017520 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-08 00:17:10.017563 | 2026-09-08 00:17:10.233122 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-08 00:17:10.235850 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-08 00:17:10.802156 | 2026-09-08 00:17:10.802271 | PLAY [all] 2026-09-08 00:17:10.812950 | 2026-09-08 00:17:10.813035 | TASK [setup-uv : Extract archive] 2026-09-08 00:17:13.566074 | controller | changed 2026-09-08 00:17:13.578282 | 2026-09-08 00:17:13.578626 | TASK [setup-uv : Print version] 2026-09-08 00:17:14.033047 | controller | uv 0.8.13 2026-09-08 00:17:14.121273 | controller | ok: Runtime: 0:00:00.017517 2026-09-08 00:17:14.133280 | 2026-09-08 00:17:14.133366 | TASK [Install Ansible collection siblings] 2026-09-08 00:17:14.586545 | controller | ok: 2026-09-08 00:17:14.586819 | controller | Skipping kubernetes.core: no checked-out sibling 2026-09-08 00:17:14.586878 | controller | Skipping openstack.cloud: no checked-out sibling 2026-09-08 00:17:14.586923 | controller | Skipping vexxhost.kubernetes: no checked-out sibling 2026-09-08 00:17:14.589696 | 2026-09-08 00:17:14.589784 | PLAY RECAP 2026-09-08 00:17:14.589869 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-08 00:17:14.589913 | 2026-09-08 00:17:14.726585 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-08 00:17:14.728068 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere.common/test-playbooks/molecule/pre.yml@main] 2026-09-08 00:17:15.285681 | 2026-09-08 00:17:15.285818 | PLAY [Prepare Molecule log collection] 2026-09-08 00:17:15.296609 | 2026-09-08 00:17:15.296688 | TASK [Install jq for log collection] 2026-09-08 00:17:22.994677 | controller | changed 2026-09-08 00:17:22.997629 | 2026-09-08 00:17:22.997704 | PLAY RECAP 2026-09-08 00:17:22.997770 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-08 00:17:22.997835 | 2026-09-08 00:17:23.184259 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere.common/test-playbooks/molecule/pre.yml@main] 2026-09-08 00:17:23.187258 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-08 00:17:23.747509 | 2026-09-08 00:17:23.747643 | PLAY [all] 2026-09-08 00:17:23.758453 | 2026-09-08 00:17:23.758522 | LOOP [Check inventory directories] 2026-09-08 00:17:24.158407 | controller | ok: "src/github.com/vexxhost/atmosphere.common" 2026-09-08 00:17:24.340096 | controller | ok: "src/github.com/vexxhost/atmosphere.common/molecule/secretgen-controller/inventory" 2026-09-08 00:17:24.510507 | controller | ok: "src/github.com/vexxhost/atmosphere.common/extensions/molecule/secretgen-controller/inventory" 2026-09-08 00:17:24.527931 | 2026-09-08 00:17:24.528219 | LOOP [Copy inventory file for Zuul] 2026-09-08 00:17:25.534618 | controller | changed: 2026-09-08 00:17:25.534926 | controller | { 2026-09-08 00:17:25.535023 | controller | "ansible_loop_var": "item", 2026-09-08 00:17:25.535072 | controller | "changed": false, 2026-09-08 00:17:25.535114 | controller | "failed": false, 2026-09-08 00:17:25.535167 | controller | "invocation": { 2026-09-08 00:17:25.535207 | controller | "module_args": { 2026-09-08 00:17:25.535276 | controller | "checksum_algorithm": "sha1", 2026-09-08 00:17:25.535330 | controller | "follow": true, 2026-09-08 00:17:25.535381 | controller | "get_attributes": true, 2026-09-08 00:17:25.535421 | controller | "get_checksum": true, 2026-09-08 00:17:25.535456 | controller | "get_mime": true, 2026-09-08 00:17:25.535491 | controller | "path": "src/github.com/vexxhost/atmosphere.common" 2026-09-08 00:17:25.535526 | controller | } 2026-09-08 00:17:25.535560 | controller | }, 2026-09-08 00:17:25.535594 | controller | "item": "src/github.com/vexxhost/atmosphere.common", 2026-09-08 00:17:25.535627 | controller | "stat": { 2026-09-08 00:17:25.535661 | controller | "atime": 1788826628.4759004, 2026-09-08 00:17:25.535696 | controller | "attr_flags": "e", 2026-09-08 00:17:25.535730 | controller | "attributes": [ 2026-09-08 00:17:25.535763 | controller | "extents" 2026-09-08 00:17:25.535796 | controller | ], 2026-09-08 00:17:25.535829 | controller | "block_size": 4096, 2026-09-08 00:17:25.535863 | controller | "blocks": 8, 2026-09-08 00:17:25.535897 | controller | "charset": "unknown", 2026-09-08 00:17:25.535933 | controller | "ctime": 1788826628.5139, 2026-09-08 00:17:25.535989 | controller | "dev": 64769, 2026-09-08 00:17:25.536025 | controller | "device_type": 0, 2026-09-08 00:17:25.536059 | controller | "executable": true, 2026-09-08 00:17:25.536093 | controller | "exists": true, 2026-09-08 00:17:25.536127 | controller | "gid": 1000, 2026-09-08 00:17:25.536179 | controller | "gr_name": "zuul", 2026-09-08 00:17:25.536215 | controller | "inode": 1034272, 2026-09-08 00:17:25.536248 | controller | "isblk": false, 2026-09-08 00:17:25.536296 | controller | "ischr": false, 2026-09-08 00:17:25.536330 | controller | "isdir": true, 2026-09-08 00:17:25.536362 | controller | "isfifo": false, 2026-09-08 00:17:25.536394 | controller | "isgid": false, 2026-09-08 00:17:25.536426 | controller | "islnk": false, 2026-09-08 00:17:25.536458 | controller | "isreg": false, 2026-09-08 00:17:25.536489 | controller | "issock": false, 2026-09-08 00:17:25.536568 | controller | "isuid": false, 2026-09-08 00:17:25.536601 | controller | "mimetype": "unknown", 2026-09-08 00:17:25.536633 | controller | "mode": "0755", 2026-09-08 00:17:25.536665 | controller | "mtime": 1788826611.7459595, 2026-09-08 00:17:25.536697 | controller | "nlink": 10, 2026-09-08 00:17:25.536729 | controller | "path": "src/github.com/vexxhost/atmosphere.common", 2026-09-08 00:17:25.536761 | controller | "pw_name": "zuul", 2026-09-08 00:17:25.536796 | controller | "readable": true, 2026-09-08 00:17:25.536829 | controller | "rgrp": true, 2026-09-08 00:17:25.537070 | controller | "roth": true, 2026-09-08 00:17:25.537108 | controller | "rusr": true, 2026-09-08 00:17:25.537157 | controller | "size": 4096, 2026-09-08 00:17:25.537193 | controller | "uid": 1000, 2026-09-08 00:17:25.537226 | controller | "version": "1602850821", 2026-09-08 00:17:25.537259 | controller | "wgrp": false, 2026-09-08 00:17:25.537316 | controller | "woth": false, 2026-09-08 00:17:25.537351 | controller | "writeable": true, 2026-09-08 00:17:25.537385 | controller | "wusr": true, 2026-09-08 00:17:25.537418 | controller | "xgrp": true, 2026-09-08 00:17:25.537450 | controller | "xoth": true, 2026-09-08 00:17:25.537483 | controller | "xusr": true 2026-09-08 00:17:25.537529 | controller | } 2026-09-08 00:17:25.537563 | controller | } 2026-09-08 00:17:25.537630 | controller | ok: All items complete 2026-09-08 00:17:25.537672 | 2026-09-08 00:17:25.540900 | controller | skipping: Conditional result was False 2026-09-08 00:17:25.542352 | controller | skipping: Conditional result was False 2026-09-08 00:17:25.559612 | 2026-09-08 00:17:25.559848 | LOOP [Switch "ansible_host" to private IP] 2026-09-08 00:17:25.963040 | controller | changed: 1 replacements made 2026-09-08 00:17:25.965403 | controller | skipping: Conditional result was False 2026-09-08 00:17:25.966736 | controller | skipping: Conditional result was False 2026-09-08 00:17:25.984595 | 2026-09-08 00:17:25.984808 | TASK [Run Molecule scenario] 2026-09-08 00:17:26.512775 | controller | Using CPython 3.12.3 interpreter at: /usr/bin/python3 2026-09-08 00:17:26.512988 | controller | Creating virtual environment at: .venv 2026-09-08 00:17:26.524756 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`. 2026-09-08 00:17:27.515698 | controller | Downloading cryptography (4.5MiB) 2026-09-08 00:17:27.515871 | controller | Downloading aiohttp (1.7MiB) 2026-09-08 00:17:27.531164 | controller | Downloading ansible-core (2.1MiB) 2026-09-08 00:17:27.532306 | controller | Downloading pygments (1.2MiB) 2026-09-08 00:17:27.533014 | controller | Downloading pydantic-core (2.0MiB) 2026-09-08 00:17:27.535126 | controller | Downloading kubernetes (4.4MiB) 2026-09-08 00:17:27.772797 | controller | Downloading pydantic-core 2026-09-08 00:17:27.779241 | controller | Downloading aiohttp 2026-09-08 00:17:27.865847 | controller | Downloading cryptography 2026-09-08 00:17:27.898181 | controller | Downloading pygments 2026-09-08 00:17:28.080378 | controller | Downloading ansible-core 2026-09-08 00:17:28.259232 | controller | Downloading kubernetes 2026-09-08 00:17:28.337469 | controller | Installed 53 packages in 77ms 2026-09-08 00:17:29.083943 | controller | INFO Collection 'atmosphere.common' detected. 2026-09-08 00:17:29.083998 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-09-08 00:17:29.704867 | controller | INFO [secretgen-controller > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-08 00:17:29.704922 | controller | INFO [secretgen-controller > prerun] Performing prerun with role_name_check=0... 2026-09-08 00:20:43.780096 | controller | WARNING Retrying execution failure 1 of: ansible-galaxy collection install -vvv vexxhost.kubernetes:>=2.5.0 2026-09-08 00:20:43.780302 | controller | ERROR Command ansible-galaxy collection install -vvv vexxhost.kubernetes:>=2.5.0, returned 1 code: 2026-09-08 00:20:43.780322 | controller | Starting galaxy collection install process 2026-09-08 00:20:43.780335 | controller | Process install dependency map 2026-09-08 00:20:43.780347 | controller | Starting collection install process 2026-09-08 00:20:43.780359 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/vexxhost-kubernetes-3.3.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1372vb1_922n/tmp68r8zdh6/vexxhost-kubernetes-3.3.0-veqywt6s 2026-09-08 00:20:43.780381 | controller | 2026-09-08 00:20:43.780394 | controller | ansible-galaxy 2026-09-08 00:20:43.780405 | controller | config file = None 2026-09-08 00:20:43.780417 | controller | configured module search path = 2026-09-08 00:20:43.780428 | controller | ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible 2026-09-08 00:20:43.780446 | controller | ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/local/lib/python3.12/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages 2026-09-08 00:20:43.780460 | controller | executable location = /home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/bin/ansible-galaxy 2026-09-08 00:20:43.780472 | controller | python version = 3.12.3 (main, Jul 15 2026, 23:46:41) (/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/bin/python) 2026-09-08 00:20:43.780483 | controller | jinja version = 3.1.6 2026-09-08 00:20:43.780494 | controller | libyaml = True 2026-09-08 00:20:43.780506 | controller | No config file found; using defaults 2026-09-08 00:20:43.780518 | controller | Found installed collection kubernetes.core:6.5.0 at '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-09-08 00:20:43.780529 | controller | Found installed collection openstack.cloud:2.6.0 at '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-09-08 00:20:43.780540 | controller | Opened /home/zuul/.ansible/galaxy_token 2026-09-08 00:20:43.780551 | controller | ERROR! Failed to download collection tar from 'default' due to the following unforeseen error: The read operation timed out 2026-09-08 00:20:43.780790 | controller | 2026-09-08 00:20:43.780911 | controller | Traceback (most recent call last): 2026-09-08 00:20:43.781265 | controller | File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/bin/molecule", line 10, in 2026-09-08 00:20:43.781742 | controller | sys.exit(main()) 2026-09-08 00:20:43.782019 | controller | ^^^^^^ 2026-09-08 00:20:43.782423 | controller | File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/click/core.py", line 1631, in __call__ 2026-09-08 00:20:43.783571 | controller | return self.main(*args, **kwargs) 2026-09-08 00:20:43.783865 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-08 00:20:43.784112 | controller | File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/click/core.py", line 1552, in main 2026-09-08 00:20:43.784937 | controller | rv = self.invoke(ctx) 2026-09-08 00:20:43.785120 | controller | ^^^^^^^^^^^^^^^^ 2026-09-08 00:20:43.785461 | controller | File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/click/core.py", line 2032, in invoke 2026-09-08 00:20:43.786322 | controller | return _process_result(sub_ctx.command.invoke(sub_ctx)) 2026-09-08 00:20:43.786692 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-08 00:20:43.786889 | controller | File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/click/core.py", line 1415, in invoke 2026-09-08 00:20:43.787623 | controller | return ctx.invoke(self.callback, **ctx.params) 2026-09-08 00:20:43.787887 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-08 00:20:43.788074 | controller | File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/click/core.py", line 910, in invoke 2026-09-08 00:20:43.788840 | controller | return callback(*args, **kwargs) 2026-09-08 00:20:43.789043 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-08 00:20:43.789260 | controller | File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/molecule/click_cfg.py", line 660, in wrapper 2026-09-08 00:20:43.789846 | controller | return func(*args, **kwargs) 2026-09-08 00:20:43.790085 | controller | ^^^^^^^^^^^^^^^^^^^^^ 2026-09-08 00:20:43.790307 | controller | File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/click/decorators.py", line 34, in new_func 2026-09-08 00:20:43.790673 | controller | return f(get_current_context(), *args, **kwargs) 2026-09-08 00:20:43.790970 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-08 00:20:43.791159 | controller | File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/molecule/click_cfg.py", line 479, in wrapper 2026-09-08 00:20:43.791687 | controller | return func(ctx) 2026-09-08 00:20:43.791932 | controller | ^^^^^^^^^ 2026-09-08 00:20:43.792263 | controller | File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/molecule/command/test.py", line 95, in test 2026-09-08 00:20:43.792632 | controller | base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude) 2026-09-08 00:20:43.792938 | controller | File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/molecule/command/base.py", line 243, in execute_cmdline_scenarios 2026-09-08 00:20:43.793417 | controller | _run_scenarios(scenarios, command_args, default_config) 2026-09-08 00:20:43.793658 | controller | File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/molecule/command/base.py", line 361, in _run_scenarios 2026-09-08 00:20:43.794087 | controller | scenario.config.runtime.prepare_environment( 2026-09-08 00:20:43.794370 | controller | File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible_compat/runtime.py", line 789, in prepare_environment 2026-09-08 00:20:43.794956 | controller | self._install_deps_from_galaxy(item, destination) 2026-09-08 00:20:43.795262 | controller | File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible_compat/runtime.py", line 818, in _install_deps_from_galaxy 2026-09-08 00:20:43.795773 | controller | self.install_collection( 2026-09-08 00:20:43.795959 | controller | File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible_compat/runtime.py", line 622, in install_collection 2026-09-08 00:20:43.796527 | controller | raise InvalidPrerequisiteError(msg) 2026-09-08 00:20:43.797730 | controller | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv vexxhost.kubernetes:>=2.5.0, returned 1 code: 2026-09-08 00:20:43.797748 | controller | Starting galaxy collection install process 2026-09-08 00:20:43.797756 | controller | Process install dependency map 2026-09-08 00:20:43.797765 | controller | Starting collection install process 2026-09-08 00:20:43.797774 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/vexxhost-kubernetes-3.3.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1372vb1_922n/tmp68r8zdh6/vexxhost-kubernetes-3.3.0-veqywt6s 2026-09-08 00:20:43.797783 | controller | 2026-09-08 00:20:43.797792 | controller | ansible-galaxy [core 2.18.19] 2026-09-08 00:20:43.797800 | controller | config file = None 2026-09-08 00:20:43.797809 | controller | configured module search path = ['/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules'] 2026-09-08 00:20:43.797817 | controller | ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible 2026-09-08 00:20:43.797825 | controller | ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/local/lib/python3.12/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages 2026-09-08 00:20:43.797834 | controller | executable location = /home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/bin/ansible-galaxy 2026-09-08 00:20:43.797842 | controller | python version = 3.12.3 (main, Jul 15 2026, 23:46:41) [GCC 13.3.0] (/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/bin/python) 2026-09-08 00:20:43.797851 | controller | jinja version = 3.1.6 2026-09-08 00:20:43.797859 | controller | libyaml = True 2026-09-08 00:20:43.797867 | controller | No config file found; using defaults 2026-09-08 00:20:43.797875 | controller | Found installed collection kubernetes.core:6.5.0 at '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-09-08 00:20:43.797884 | controller | Found installed collection openstack.cloud:2.6.0 at '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-09-08 00:20:43.797892 | controller | Opened /home/zuul/.ansible/galaxy_token 2026-09-08 00:20:43.797900 | controller | ERROR! Failed to download collection tar from 'default' due to the following unforeseen error: The read operation timed out 2026-09-08 00:20:43.797913 | controller | 2026-09-08 00:20:44.153158 | controller | ERROR 2026-09-08 00:20:44.153433 | controller | { 2026-09-08 00:20:44.153488 | controller | "delta": "0:03:17.506124", 2026-09-08 00:20:44.153528 | controller | "end": "2026-09-08 00:20:43.908013", 2026-09-08 00:20:44.153565 | controller | "msg": "non-zero return code", 2026-09-08 00:20:44.153601 | controller | "rc": 1, 2026-09-08 00:20:44.153636 | controller | "start": "2026-09-08 00:17:26.401889" 2026-09-08 00:20:44.153672 | controller | } failure 2026-09-08 00:20:44.157897 | 2026-09-08 00:20:44.157986 | PLAY RECAP 2026-09-08 00:20:44.158068 | controller | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-08 00:20:44.158111 | 2026-09-08 00:20:44.309289 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-08 00:20:44.311045 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere.common/test-playbooks/molecule/post.yml@main] 2026-09-08 00:20:44.874705 | 2026-09-08 00:20:44.874867 | PLAY [Collect Molecule logs] 2026-09-08 00:20:44.890234 | 2026-09-08 00:20:44.890578 | TASK [gather-host-logs : creating directory for system status] 2026-09-08 00:20:45.333978 | controller | changed 2026-09-08 00:20:45.345537 | 2026-09-08 00:20:45.345705 | TASK [gather-host-logs : Get logs for each host] 2026-09-08 00:20:45.787167 | controller | + systemd-cgls --full --all --no-pager 2026-09-08 00:20:45.813680 | controller | + ip addr 2026-09-08 00:20:45.818127 | controller | + ip route 2026-09-08 00:20:45.822867 | controller | + lsblk 2026-09-08 00:20:45.831875 | controller | + mount 2026-09-08 00:20:45.836521 | controller | + docker images 2026-09-08 00:20:45.837460 | controller | /bin/bash: line 7: docker: command not found 2026-09-08 00:20:45.838041 | controller | + brctl show 2026-09-08 00:20:45.839099 | controller | /bin/bash: line 8: brctl: command not found 2026-09-08 00:20:45.839740 | controller | + ps aux --sort=-%mem 2026-09-08 00:20:45.879429 | controller | + dpkg -l 2026-09-08 00:20:45.896499 | controller | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-09-08 00:20:45.897530 | controller | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-09-08 00:20:45.897631 | controller | /bin/bash: line 11: docker: command not found 2026-09-08 00:20:45.898262 | controller | + '[' '!' -z '' ']' 2026-09-08 00:20:46.397372 | controller | ok: Runtime: 0:00:00.119113 2026-09-08 00:20:46.408987 | 2026-09-08 00:20:46.409114 | TASK [gather-host-logs : Downloads logs to executor] 2026-09-08 00:20:47.144196 | controller | changed: 2026-09-08 00:20:47.144459 | controller | created directory /var/lib/zuul/builds/56ce1720346641b69adc6ccdaa2ecc5a/work/logs/controller 2026-09-08 00:20:47.144565 | controller | cd+++++++++ system/ 2026-09-08 00:20:47.144615 | controller | >f+++++++++ system/brctl-show.txt 2026-09-08 00:20:47.144665 | controller | >f+++++++++ system/docker-images.txt 2026-09-08 00:20:47.144706 | controller | >f+++++++++ system/ip-addr.txt 2026-09-08 00:20:47.144751 | controller | >f+++++++++ system/ip-route.txt 2026-09-08 00:20:47.144791 | controller | >f+++++++++ system/lsblk.txt 2026-09-08 00:20:47.144829 | controller | >f+++++++++ system/mount.txt 2026-09-08 00:20:47.144897 | controller | >f+++++++++ system/packages.txt 2026-09-08 00:20:47.144936 | controller | >f+++++++++ system/ps.txt 2026-09-08 00:20:47.144972 | controller | >f+++++++++ system/systemd-cgls.txt 2026-09-08 00:20:47.165031 | 2026-09-08 00:20:47.165164 | LOOP [helm-release-status : creating directory for helm release status] 2026-09-08 00:20:47.438691 | controller | changed: "values" 2026-09-08 00:20:47.667393 | controller | changed: "releases" 2026-09-08 00:20:47.686907 | 2026-09-08 00:20:47.687134 | TASK [helm-release-status : Gather get release status for helm charts] 2026-09-08 00:20:48.015246 | controller | /bin/bash: line 3: kubectl: command not found 2026-09-08 00:20:48.243226 | controller | ok: Runtime: 0:00:00.012070 2026-09-08 00:20:48.254246 | 2026-09-08 00:20:48.254378 | TASK [helm-release-status : Downloads logs to executor] 2026-09-08 00:20:48.838294 | controller | changed: 2026-09-08 00:20:48.838556 | controller | cd+++++++++ helm/ 2026-09-08 00:20:48.838611 | controller | cd+++++++++ helm/releases/ 2026-09-08 00:20:48.838653 | controller | cd+++++++++ helm/values/ 2026-09-08 00:20:48.858489 | 2026-09-08 00:20:48.858618 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-09-08 00:20:49.150805 | controller | changed 2026-09-08 00:20:49.160918 | 2026-09-08 00:20:49.161055 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-09-08 00:20:49.528964 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-08 00:20:49.529113 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-08 00:20:49.537046 | controller | environment: line 1: kubectl: command not found 2026-09-08 00:20:49.538347 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-08 00:20:49.540819 | controller | environment: line 1: kubectl: command not found 2026-09-08 00:20:49.542883 | controller | environment: line 1: kubectl: command not found 2026-09-08 00:20:49.543035 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-08 00:20:49.544178 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-08 00:20:49.548534 | controller | environment: line 1: kubectl: command not found 2026-09-08 00:20:49.550596 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-08 00:20:49.552877 | controller | environment: line 1: kubectl: command not found 2026-09-08 00:20:49.554692 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-08 00:20:49.764180 | controller | ok: Runtime: 0:00:00.042510 2026-09-08 00:20:49.774407 | 2026-09-08 00:20:49.774533 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-09-08 00:20:50.044949 | controller | changed 2026-09-08 00:20:50.056380 | 2026-09-08 00:20:50.056549 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-09-08 00:20:50.364118 | controller | environment: line 5: kubectl: command not found 2026-09-08 00:20:50.365484 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-08 00:20:50.365640 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-08 00:20:50.366107 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-08 00:20:50.606945 | controller | ok: Runtime: 0:00:00.015415 2026-09-08 00:20:50.618379 | 2026-09-08 00:20:50.618703 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-09-08 00:20:51.173072 | controller | changed: 2026-09-08 00:20:51.173344 | controller | cd+++++++++ objects/ 2026-09-08 00:20:51.173397 | controller | cd+++++++++ objects/cluster/ 2026-09-08 00:20:51.173440 | controller | cd+++++++++ objects/namespaced/ 2026-09-08 00:20:51.193732 | 2026-09-08 00:20:51.193865 | TASK [gather-pod-logs : creating directory for pod logs] 2026-09-08 00:20:51.473473 | controller | changed 2026-09-08 00:20:51.486580 | 2026-09-08 00:20:51.486687 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-09-08 00:20:51.747581 | controller | changed 2026-09-08 00:20:51.758309 | 2026-09-08 00:20:51.758448 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-09-08 00:20:52.072891 | controller | environment: line 3: kubectl: command not found 2026-09-08 00:20:52.308817 | controller | ok: Runtime: 0:00:00.014521 2026-09-08 00:20:52.319368 | 2026-09-08 00:20:52.319494 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-09-08 00:20:52.870861 | controller | changed: 2026-09-08 00:20:52.871238 | controller | cd+++++++++ pod-logs/ 2026-09-08 00:20:52.871303 | controller | cd+++++++++ pod-logs/failed-pods/ 2026-09-08 00:20:52.889674 | 2026-09-08 00:20:52.889799 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-09-08 00:20:53.152771 | controller | changed 2026-09-08 00:20:53.159019 | 2026-09-08 00:20:53.159110 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-09-08 00:20:53.443978 | controller | /bin/bash: line 2: kubectl: command not found 2026-09-08 00:20:53.692786 | controller | ok: Runtime: 0:00:00.016357 2026-09-08 00:20:53.703022 | 2026-09-08 00:20:53.703165 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-09-08 00:20:54.006501 | controller | /bin/bash: line 2: kubectl: command not found 2026-09-08 00:20:54.010543 | controller | ceph-mgr endpoints: 2026-09-08 00:20:54.254653 | controller | ok: Runtime: 0:00:00.014503 2026-09-08 00:20:54.265216 | 2026-09-08 00:20:54.265362 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-09-08 00:20:54.557032 | controller | /bin/bash: line 4: kubectl: command not found 2026-09-08 00:20:54.817236 | controller | ok: Runtime: 0:00:00.016380 2026-09-08 00:20:54.827064 | 2026-09-08 00:20:54.827184 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-09-08 00:20:55.388774 | controller | changed: cd+++++++++ prometheus/ 2026-09-08 00:20:55.453335 | 2026-09-08 00:20:55.453488 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-09-08 00:20:55.731220 | controller | changed 2026-09-08 00:20:55.741792 | 2026-09-08 00:20:55.742020 | TASK [gather-selenium-data : Get selenium data] 2026-09-08 00:20:56.010400 | controller | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-09-08 00:20:56.015392 | controller | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-09-08 00:20:56.290559 | controller | ERROR 2026-09-08 00:20:56.290794 | controller | { 2026-09-08 00:20:56.290878 | controller | "delta": "0:00:00.012644", 2026-09-08 00:20:56.290922 | controller | "end": "2026-09-08 00:20:56.016006", 2026-09-08 00:20:56.290961 | controller | "msg": "non-zero return code", 2026-09-08 00:20:56.290997 | controller | "rc": 1, 2026-09-08 00:20:56.291032 | controller | "start": "2026-09-08 00:20:56.003362" 2026-09-08 00:20:56.291066 | controller | } 2026-09-08 00:20:56.291110 | controller | ERROR: Ignoring Errors 2026-09-08 00:20:56.301337 | 2026-09-08 00:20:56.301464 | TASK [gather-selenium-data : Downloads logs to executor] 2026-09-08 00:20:56.853979 | controller | changed: cd+++++++++ selenium/ 2026-09-08 00:20:56.865288 | 2026-09-08 00:20:56.865389 | PLAY RECAP 2026-09-08 00:20:56.865471 | controller | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-09-08 00:20:56.865512 | 2026-09-08 00:20:56.994803 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere.common/test-playbooks/molecule/post.yml@main] 2026-09-08 00:20:56.997612 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-08 00:20:57.588439 | 2026-09-08 00:20:57.588607 | PLAY [all] 2026-09-08 00:20:57.600338 | 2026-09-08 00:20:57.600430 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-08 00:20:57.655388 | controller | skipping: Conditional result was False 2026-09-08 00:20:57.662419 | 2026-09-08 00:20:57.662521 | TASK [fetch-output : Set log path for single node] 2026-09-08 00:20:57.697880 | controller | ok 2026-09-08 00:20:57.703716 | 2026-09-08 00:20:57.703802 | LOOP [fetch-output : Ensure local output dirs] 2026-09-08 00:20:58.109436 | controller -> localhost | ok: "/var/lib/zuul/builds/56ce1720346641b69adc6ccdaa2ecc5a/work/logs" 2026-09-08 00:20:58.330893 | controller -> localhost | changed: "/var/lib/zuul/builds/56ce1720346641b69adc6ccdaa2ecc5a/work/artifacts" 2026-09-08 00:20:58.544624 | controller -> localhost | changed: "/var/lib/zuul/builds/56ce1720346641b69adc6ccdaa2ecc5a/work/docs" 2026-09-08 00:20:58.563754 | 2026-09-08 00:20:58.564048 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-08 00:20:59.260312 | controller | changed: .d..t...... ./ 2026-09-08 00:20:59.260538 | controller | changed: All items complete 2026-09-08 00:20:59.260566 | 2026-09-08 00:20:59.784930 | controller | changed: .d..t...... ./ 2026-09-08 00:21:00.348693 | controller | changed: .d..t...... ./ 2026-09-08 00:21:00.381869 | 2026-09-08 00:21:00.382080 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-08 00:21:00.849454 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.010610 2026-09-08 00:21:01.099650 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007630 2026-09-08 00:21:01.116574 | 2026-09-08 00:21:01.116762 | PLAY [all] 2026-09-08 00:21:01.128974 | 2026-09-08 00:21:01.129103 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-08 00:21:01.661869 | controller | changed 2026-09-08 00:21:01.672622 | 2026-09-08 00:21:01.672724 | PLAY RECAP 2026-09-08 00:21:01.672806 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-08 00:21:01.672876 | 2026-09-08 00:21:01.823874 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-08 00:21:01.826642 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-08 00:21:02.352149 | 2026-09-08 00:21:02.352312 | PLAY [localhost] 2026-09-08 00:21:02.362022 | 2026-09-08 00:21:02.362108 | TASK [Generate Zuul manifest] 2026-09-08 00:21:02.382082 | localhost | ok 2026-09-08 00:21:02.396053 | 2026-09-08 00:21:02.396136 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-08 00:21:02.803636 | localhost | changed 2026-09-08 00:21:02.825893 | 2026-09-08 00:21:02.826064 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-08 00:21:02.859474 | localhost | ok 2026-09-08 00:21:02.872424 | 2026-09-08 00:21:02.872583 | TASK [Upload logs] 2026-09-08 00:21:02.894061 | localhost | ok 2026-09-08 00:21:02.964594 | 2026-09-08 00:21:02.964726 | TASK [Set zuul-log-path fact] 2026-09-08 00:21:02.983090 | localhost | ok 2026-09-08 00:21:02.992976 | 2026-09-08 00:21:02.993056 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-08 00:21:03.031179 | localhost | ok 2026-09-08 00:21:03.040178 | 2026-09-08 00:21:03.040261 | TASK [upload-logs : Create log directories] 2026-09-08 00:21:03.495509 | localhost | changed 2026-09-08 00:21:03.501467 | 2026-09-08 00:21:03.501562 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-08 00:21:03.910386 | localhost -> localhost | ok: Runtime: 0:00:00.005748 2026-09-08 00:21:03.919929 | 2026-09-08 00:21:03.920070 | TASK [upload-logs : Upload logs to log server] 2026-09-08 00:21:04.435962 | localhost | Output suppressed because no_log was given 2026-09-08 00:21:04.440639 | 2026-09-08 00:21:04.440727 | LOOP [upload-logs : Compress console log and json output] 2026-09-08 00:21:04.494585 | localhost | skipping: Conditional result was False 2026-09-08 00:21:04.544166 | localhost | skipping: Conditional result was False 2026-09-08 00:21:04.557133 | 2026-09-08 00:21:04.557345 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-08 00:21:04.620361 | localhost | skipping: Conditional result was False 2026-09-08 00:21:04.620865 | 2026-09-08 00:21:04.626305 | localhost | skipping: Conditional result was False 2026-09-08 00:21:04.641502 | 2026-09-08 00:21:04.641716 | LOOP [upload-logs : Upload console log and json output]