2026-09-10 21:17:52.040117 | Job console starting 2026-09-10 21:17:52.050874 | Updating git repos 2026-09-10 21:17:52.093858 | Cloning repos into workspace 2026-09-10 21:17:52.139429 | Restoring repo states 2026-09-10 21:17:52.153661 | Merging changes 2026-09-10 21:17:52.876864 | Checking out repos 2026-09-10 21:17:53.172740 | Preparing playbooks 2026-09-10 21:17:55.388072 | Running Ansible setup 2026-09-10 21:17:59.392404 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-10 21:18:00.013037 | 2026-09-10 21:18:00.013248 | PLAY [localhost] 2026-09-10 21:18:00.021827 | 2026-09-10 21:18:00.021913 | TASK [Gathering Facts] 2026-09-10 21:18:00.937183 | localhost | ok 2026-09-10 21:18:00.943898 | 2026-09-10 21:18:00.945122 | TASK [Setup log path fact] 2026-09-10 21:18:00.962658 | localhost | ok 2026-09-10 21:18:00.973735 | 2026-09-10 21:18:00.973800 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-10 21:18:01.001598 | localhost | ok 2026-09-10 21:18:01.008759 | 2026-09-10 21:18:01.008848 | TASK [emit-job-header : Print job information] 2026-09-10 21:18:01.046929 | # Job Information 2026-09-10 21:18:01.047080 | Ansible Version: 2.16.18 2026-09-10 21:18:01.047119 | Job: ansible-collection-kubernetes-molecule-download-binaries 2026-09-10 21:18:01.047147 | Pipeline: check 2026-09-10 21:18:01.047172 | Executor: 2d72f0692154 2026-09-10 21:18:01.047197 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/300 2026-09-10 21:18:01.047224 | Event ID: 628dc250-ad5c-11f1-880f-7bd6db772b47 2026-09-10 21:18:01.050737 | 2026-09-10 21:18:01.050825 | LOOP [emit-job-header : Print node information] 2026-09-10 21:18:01.133011 | localhost | ok: 2026-09-10 21:18:01.133269 | localhost | # Node Information 2026-09-10 21:18:01.133307 | localhost | Inventory Hostname: controller 2026-09-10 21:18:01.133353 | localhost | Hostname: np0000192545 2026-09-10 21:18:01.133380 | localhost | Username: zuul 2026-09-10 21:18:01.133410 | localhost | Distro: Debian 13.6 2026-09-10 21:18:01.133436 | localhost | Provider: yul1 2026-09-10 21:18:01.133460 | localhost | Region: ca-ymq-1 2026-09-10 21:18:01.133489 | localhost | Label: debian-trixie 2026-09-10 21:18:01.133525 | localhost | Product Name: OpenStack Nova 2026-09-10 21:18:01.133552 | localhost | Interface IP: 162.253.55.77 2026-09-10 21:18:01.147478 | 2026-09-10 21:18:01.147664 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-10 21:18:01.538751 | localhost -> localhost | changed 2026-09-10 21:18:01.546275 | 2026-09-10 21:18:01.546450 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-10 21:18:02.468447 | localhost -> localhost | changed 2026-09-10 21:18:02.482085 | 2026-09-10 21:18:02.482249 | PLAY [all] 2026-09-10 21:18:02.496351 | 2026-09-10 21:18:02.496559 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-10 21:18:02.747850 | controller -> localhost | ok 2026-09-10 21:18:02.766279 | 2026-09-10 21:18:02.768593 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-10 21:18:02.803945 | controller | ok 2026-09-10 21:18:02.826793 | controller | included: /var/lib/zuul/builds/0972b2982de548fabde959561b16c3ef/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-10 21:18:02.832746 | 2026-09-10 21:18:02.832823 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-10 21:18:03.531451 | controller -> localhost | Generating public/private rsa key pair. 2026-09-10 21:18:03.531759 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/0972b2982de548fabde959561b16c3ef/work/0972b2982de548fabde959561b16c3ef_id_rsa 2026-09-10 21:18:03.531820 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/0972b2982de548fabde959561b16c3ef/work/0972b2982de548fabde959561b16c3ef_id_rsa.pub 2026-09-10 21:18:03.531867 | controller -> localhost | The key fingerprint is: 2026-09-10 21:18:03.531909 | controller -> localhost | SHA256:6oOWUjRcCceUPsCB12OYZ72SKIbkioEiCYQJyvBhzKw zuul-build-sshkey 2026-09-10 21:18:03.531974 | controller -> localhost | The key's randomart image is: 2026-09-10 21:18:03.532017 | controller -> localhost | +---[RSA 3072]----+ 2026-09-10 21:18:03.532071 | controller -> localhost | |**oooOo+ | 2026-09-10 21:18:03.532116 | controller -> localhost | |B+=.*.@ . | 2026-09-10 21:18:03.532160 | controller -> localhost | |*=.o O o . | 2026-09-10 21:18:03.532201 | controller -> localhost | |E.o = = . | 2026-09-10 21:18:03.532242 | controller -> localhost | |=o o . oS | 2026-09-10 21:18:03.532283 | controller -> localhost | |o . . | 2026-09-10 21:18:03.532348 | controller -> localhost | | . o. | 2026-09-10 21:18:03.532392 | controller -> localhost | | . +.. | 2026-09-10 21:18:03.532440 | controller -> localhost | | o .. | 2026-09-10 21:18:03.532484 | controller -> localhost | +----[SHA256]-----+ 2026-09-10 21:18:03.532660 | controller -> localhost | ok: Runtime: 0:00:00.209763 2026-09-10 21:18:03.546410 | 2026-09-10 21:18:03.546642 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-10 21:18:03.571774 | controller | ok 2026-09-10 21:18:03.586904 | controller | included: /var/lib/zuul/builds/0972b2982de548fabde959561b16c3ef/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-10 21:18:03.595491 | 2026-09-10 21:18:03.595663 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-10 21:18:03.620864 | controller | skipping: Conditional result was False 2026-09-10 21:18:03.630691 | 2026-09-10 21:18:03.630838 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-10 21:18:04.223487 | controller | changed 2026-09-10 21:18:04.231919 | 2026-09-10 21:18:04.232105 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-10 21:18:04.448192 | controller | ok 2026-09-10 21:18:04.456179 | 2026-09-10 21:18:04.456313 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-10 21:18:05.036151 | controller | changed 2026-09-10 21:18:05.044073 | 2026-09-10 21:18:05.044201 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-10 21:18:05.617836 | controller | changed 2026-09-10 21:18:05.627168 | 2026-09-10 21:18:05.627304 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-10 21:18:05.654398 | controller | skipping: Conditional result was False 2026-09-10 21:18:05.664382 | 2026-09-10 21:18:05.664592 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-10 21:18:06.096367 | controller -> localhost | changed 2026-09-10 21:18:06.114978 | 2026-09-10 21:18:06.115164 | TASK [add-build-sshkey : Add back temp key] 2026-09-10 21:18:06.399361 | controller -> localhost | Identity added: /var/lib/zuul/builds/0972b2982de548fabde959561b16c3ef/work/0972b2982de548fabde959561b16c3ef_id_rsa (zuul-build-sshkey) 2026-09-10 21:18:06.399679 | controller -> localhost | ok: Runtime: 0:00:00.015185 2026-09-10 21:18:06.407812 | 2026-09-10 21:18:06.407942 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-10 21:18:06.785981 | controller | ok 2026-09-10 21:18:06.799266 | 2026-09-10 21:18:06.799467 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-10 21:18:06.835669 | controller | skipping: Conditional result was False 2026-09-10 21:18:06.851638 | 2026-09-10 21:18:06.851831 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-10 21:18:07.283394 | controller | ok 2026-09-10 21:18:07.293656 | 2026-09-10 21:18:07.293833 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-10 21:18:08.409964 | controller | Output suppressed because no_log was given 2026-09-10 21:18:08.424994 | 2026-09-10 21:18:08.425148 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-10 21:18:08.665411 | controller | ok: "logs" 2026-09-10 21:18:08.666179 | controller | ok: All items complete 2026-09-10 21:18:08.666253 | 2026-09-10 21:18:08.878998 | controller | ok: "artifacts" 2026-09-10 21:18:09.065566 | controller | ok: "docs" 2026-09-10 21:18:09.079314 | 2026-09-10 21:18:09.079489 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-10 21:18:09.317607 | controller | changed: "logs" 2026-09-10 21:18:09.533427 | controller | changed: "artifacts" 2026-09-10 21:18:09.735066 | controller | changed: "docs" 2026-09-10 21:18:09.741629 | 2026-09-10 21:18:09.741697 | PLAY RECAP 2026-09-10 21:18:09.741753 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-10 21:18:09.741791 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-10 21:18:09.741820 | 2026-09-10 21:18:09.958806 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-10 21:18:09.960260 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-10 21:18:10.591979 | 2026-09-10 21:18:10.592127 | PLAY [all] 2026-09-10 21:18:10.601471 | 2026-09-10 21:18:10.601546 | TASK [setup-uv : Extract archive] 2026-09-10 21:18:13.123504 | controller | changed 2026-09-10 21:18:13.133565 | 2026-09-10 21:18:13.133731 | TASK [setup-uv : Print version] 2026-09-10 21:18:13.569556 | controller | uv 0.8.13 2026-09-10 21:18:13.675206 | controller | ok: Runtime: 0:00:00.016189 2026-09-10 21:18:13.690071 | 2026-09-10 21:18:13.690204 | TASK [Install Ansible collection siblings] 2026-09-10 21:18:14.118676 | controller | ok: 2026-09-10 21:18:14.118945 | controller | Skipping ansible.posix: no checked-out sibling 2026-09-10 21:18:14.119187 | controller | Skipping community.crypto: no checked-out sibling 2026-09-10 21:18:14.119230 | controller | Skipping community.general: no checked-out sibling 2026-09-10 21:18:14.119270 | controller | Skipping kubernetes.core: no checked-out sibling 2026-09-10 21:18:14.119310 | controller | Skipping vexxhost.containers: no checked-out sibling 2026-09-10 21:18:14.122213 | 2026-09-10 21:18:14.122301 | PLAY RECAP 2026-09-10 21:18:14.122409 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-10 21:18:14.122453 | 2026-09-10 21:18:14.289259 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-10 21:18:14.291251 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-09-10 21:18:14.871288 | 2026-09-10 21:18:14.871437 | PLAY [all] 2026-09-10 21:18:14.881393 | 2026-09-10 21:18:14.881463 | TASK [Update apt cache] 2026-09-10 21:18:20.903373 | controller | ok 2026-09-10 21:18:20.914061 | 2026-09-10 21:18:20.914253 | TASK [Disable firewalld] 2026-09-10 21:18:20.930914 | controller | skipping: Conditional result was False 2026-09-10 21:18:20.942941 | 2026-09-10 21:18:20.943114 | TASK [Install "jq" for log collection] 2026-09-10 21:18:23.894444 | controller | changed 2026-09-10 21:18:23.896755 | 2026-09-10 21:18:23.896814 | PLAY RECAP 2026-09-10 21:18:23.896866 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-10 21:18:23.896895 | 2026-09-10 21:18:24.037752 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-09-10 21:18:24.040667 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-10 21:18:24.576978 | 2026-09-10 21:18:24.577125 | PLAY [all] 2026-09-10 21:18:24.586667 | 2026-09-10 21:18:24.586732 | LOOP [Check inventory directories] 2026-09-10 21:18:24.996613 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes" 2026-09-10 21:18:25.199781 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/download-binaries/inventory" 2026-09-10 21:18:25.407209 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/download-binaries/inventory" 2026-09-10 21:18:25.425451 | 2026-09-10 21:18:25.425685 | LOOP [Copy inventory file for Zuul] 2026-09-10 21:18:26.368538 | controller | changed: 2026-09-10 21:18:26.368941 | controller | { 2026-09-10 21:18:26.369028 | controller | "ansible_loop_var": "item", 2026-09-10 21:18:26.369093 | controller | "changed": false, 2026-09-10 21:18:26.369156 | controller | "failed": false, 2026-09-10 21:18:26.369224 | controller | "invocation": { 2026-09-10 21:18:26.369290 | controller | "module_args": { 2026-09-10 21:18:26.369387 | controller | "checksum_algorithm": "sha1", 2026-09-10 21:18:26.369457 | controller | "follow": true, 2026-09-10 21:18:26.369521 | controller | "get_attributes": true, 2026-09-10 21:18:26.369584 | controller | "get_checksum": true, 2026-09-10 21:18:26.369639 | controller | "get_mime": true, 2026-09-10 21:18:26.369696 | controller | "path": "src/github.com/vexxhost/ansible-collection-kubernetes" 2026-09-10 21:18:26.369757 | controller | } 2026-09-10 21:18:26.369824 | controller | }, 2026-09-10 21:18:26.369890 | controller | "item": "src/github.com/vexxhost/ansible-collection-kubernetes", 2026-09-10 21:18:26.369956 | controller | "stat": { 2026-09-10 21:18:26.370016 | controller | "atime": 1789075088.243702, 2026-09-10 21:18:26.370073 | controller | "attr_flags": "e", 2026-09-10 21:18:26.370127 | controller | "attributes": [ 2026-09-10 21:18:26.370188 | controller | "extents" 2026-09-10 21:18:26.370257 | controller | ], 2026-09-10 21:18:26.370345 | controller | "block_size": 4096, 2026-09-10 21:18:26.370415 | controller | "blocks": 8, 2026-09-10 21:18:26.370474 | controller | "charset": "unknown", 2026-09-10 21:18:26.370535 | controller | "ctime": 1789075088.2837029, 2026-09-10 21:18:26.370591 | controller | "dev": 65025, 2026-09-10 21:18:26.370646 | controller | "device_type": 0, 2026-09-10 21:18:26.370699 | controller | "executable": true, 2026-09-10 21:18:26.370753 | controller | "exists": true, 2026-09-10 21:18:26.370806 | controller | "gid": 1000, 2026-09-10 21:18:26.370860 | controller | "gr_name": "zuul", 2026-09-10 21:18:26.370915 | controller | "inode": 521495, 2026-09-10 21:18:26.370979 | controller | "isblk": false, 2026-09-10 21:18:26.371048 | controller | "ischr": false, 2026-09-10 21:18:26.371115 | controller | "isdir": true, 2026-09-10 21:18:26.371181 | controller | "isfifo": false, 2026-09-10 21:18:26.371247 | controller | "isgid": false, 2026-09-10 21:18:26.371310 | controller | "islnk": false, 2026-09-10 21:18:26.371397 | controller | "isreg": false, 2026-09-10 21:18:26.371460 | controller | "issock": false, 2026-09-10 21:18:26.371519 | controller | "isuid": false, 2026-09-10 21:18:26.371580 | controller | "mimetype": "unknown", 2026-09-10 21:18:26.371640 | controller | "mode": "0755", 2026-09-10 21:18:26.371703 | controller | "mtime": 1789075072.259523, 2026-09-10 21:18:26.371766 | controller | "nlink": 14, 2026-09-10 21:18:26.371829 | controller | "path": "src/github.com/vexxhost/ansible-collection-kubernetes", 2026-09-10 21:18:26.371891 | controller | "pw_name": "zuul", 2026-09-10 21:18:26.371961 | controller | "readable": true, 2026-09-10 21:18:26.372024 | controller | "rgrp": true, 2026-09-10 21:18:26.372085 | controller | "roth": true, 2026-09-10 21:18:26.372142 | controller | "rusr": true, 2026-09-10 21:18:26.372196 | controller | "size": 4096, 2026-09-10 21:18:26.372249 | controller | "uid": 1000, 2026-09-10 21:18:26.372306 | controller | "version": "2173107822", 2026-09-10 21:18:26.372390 | controller | "wgrp": false, 2026-09-10 21:18:26.372452 | controller | "woth": false, 2026-09-10 21:18:26.372537 | controller | "writeable": true, 2026-09-10 21:18:26.372602 | controller | "wusr": true, 2026-09-10 21:18:26.372656 | controller | "xgrp": true, 2026-09-10 21:18:26.372708 | controller | "xoth": true, 2026-09-10 21:18:26.372762 | controller | "xusr": true 2026-09-10 21:18:26.372836 | controller | } 2026-09-10 21:18:26.372894 | controller | } 2026-09-10 21:18:26.372996 | controller | ok: All items complete 2026-09-10 21:18:26.373067 | 2026-09-10 21:18:26.376995 | controller | skipping: Conditional result was False 2026-09-10 21:18:26.378812 | controller | skipping: Conditional result was False 2026-09-10 21:18:26.404845 | 2026-09-10 21:18:26.405061 | LOOP [Switch "ansible_host" to private IP] 2026-09-10 21:18:26.777349 | controller | changed: 1 replacements made 2026-09-10 21:18:26.779778 | controller | skipping: Conditional result was False 2026-09-10 21:18:26.781024 | controller | skipping: Conditional result was False 2026-09-10 21:18:26.795670 | 2026-09-10 21:18:26.795898 | TASK [Run Molecule scenario] 2026-09-10 21:18:27.287768 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-09-10 21:18:27.287855 | controller | Creating virtual environment at: .venv 2026-09-10 21:18:28.523751 | controller | Downloading pygments (1.2MiB) 2026-09-10 21:18:28.524603 | controller | Downloading cryptography (4.3MiB) 2026-09-10 21:18:28.525932 | controller | Downloading ansible-core (2.3MiB) 2026-09-10 21:18:28.695718 | controller | Downloading cryptography 2026-09-10 21:18:28.751743 | controller | Downloading pygments 2026-09-10 21:18:28.905187 | controller | Downloading ansible-core 2026-09-10 21:18:28.971594 | controller | Installed 36 packages in 65ms 2026-09-10 21:18:29.613695 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-09-10 21:18:29.613744 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-09-10 21:18:30.513213 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-09-10 21:18:30.513265 | controller | INFO default scenario not found, disabling shared state. 2026-09-10 21:18:30.513356 | controller | INFO [download-binaries > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-10 21:18:30.513421 | controller | INFO [download-binaries > prerun] Performing prerun with role_name_check=0... 2026-09-10 21:20:00.892474 | controller | WARNING Retrying execution failure 1 of: ansible-galaxy collection install -vvv ansible.posix:>=1.6.0 2026-09-10 21:20:00.892589 | controller | ERROR Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code: 2026-09-10 21:20:00.892600 | controller | Starting galaxy collection install process 2026-09-10 21:20:00.892608 | controller | Process install dependency map 2026-09-10 21:20:00.892617 | controller | Starting collection install process 2026-09-10 21:20:00.892625 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-posix-2.2.2.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1490nmg6kqhv/tmplr60oaf_/ansible-posix-2.2.2-2hrlppmp 2026-09-10 21:20:00.892647 | controller | 2026-09-10 21:20:00.892655 | controller | ansible-galaxy 2026-09-10 21:20:00.892663 | controller | config file = None 2026-09-10 21:20:00.892670 | controller | configured module search path = 2026-09-10 21:20:00.892677 | controller | ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible 2026-09-10 21:20:00.892694 | controller | ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/local/lib/python3.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages 2026-09-10 21:20:00.892705 | controller | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy 2026-09-10 21:20:00.892712 | controller | python version = 3.13.5 (main, Jul 15 2026, 20:25:40) (/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/python) 2026-09-10 21:20:00.892719 | controller | jinja version = 3.1.6 2026-09-10 21:20:00.892726 | controller | pyyaml version = 6.0.3 (with libyaml v0.2.5) 2026-09-10 21:20:00.892733 | controller | No config file found; using defaults 2026-09-10 21:20:00.892741 | controller | Created /home/zuul/.ansible/galaxy_token 2026-09-10 21:20:00.892748 | controller | : Failed to download collection tar from 'default': 2026-09-10 21:20:00.892755 | controller | 2026-09-10 21:20:00.892762 | controller | Failed to download collection tar from 'default': 2026-09-10 21:20:00.892769 | controller | 2026-09-10 21:20:00.892776 | controller | <<< caused by >>> 2026-09-10 21:20:00.892783 | controller | 2026-09-10 21:20:00.892789 | controller | 2026-09-10 21:20:00.892804 | controller | 2026-09-10 21:20:00.892835 | controller | 2026-09-10 21:20:00.895702 | controller | Traceback (most recent call last): 2026-09-10 21:20:00.898798 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/molecule", line 10, in 2026-09-10 21:20:00.898875 | controller | sys.exit(main()) 2026-09-10 21:20:00.898884 | controller | ~~~~^^ 2026-09-10 21:20:00.899120 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 1485, in __call__ 2026-09-10 21:20:00.899145 | controller | return self.main(*args, **kwargs) 2026-09-10 21:20:00.899154 | controller | ~~~~~~~~~^^^^^^^^^^^^^^^^^ 2026-09-10 21:20:00.899362 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 1406, in main 2026-09-10 21:20:00.899385 | controller | rv = self.invoke(ctx) 2026-09-10 21:20:00.899635 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 1873, in invoke 2026-09-10 21:20:00.899659 | controller | return _process_result(sub_ctx.command.invoke(sub_ctx)) 2026-09-10 21:20:00.899669 | controller | ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^ 2026-09-10 21:20:00.899917 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 1269, in invoke 2026-09-10 21:20:00.899940 | controller | return ctx.invoke(self.callback, **ctx.params) 2026-09-10 21:20:00.899950 | controller | ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:20:00.900105 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 824, in invoke 2026-09-10 21:20:00.900116 | controller | return callback(*args, **kwargs) 2026-09-10 21:20:00.900334 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/click_cfg.py", line 583, in wrapper 2026-09-10 21:20:00.900343 | controller | return func(*args, **kwargs) 2026-09-10 21:20:00.900553 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/decorators.py", line 34, in new_func 2026-09-10 21:20:00.900561 | controller | return f(get_current_context(), *args, **kwargs) 2026-09-10 21:20:00.900775 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/click_cfg.py", line 418, in wrapper 2026-09-10 21:20:00.900783 | controller | return func(ctx) 2026-09-10 21:20:00.901092 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/command/test.py", line 81, in test 2026-09-10 21:20:00.901101 | controller | base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude) 2026-09-10 21:20:00.901108 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:20:00.901383 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/command/base.py", line 169, in execute_cmdline_scenarios 2026-09-10 21:20:00.901393 | controller | _run_scenarios(scenarios, command_args, default_config) 2026-09-10 21:20:00.901400 | controller | ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:20:00.901795 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/command/base.py", line 240, in _run_scenarios 2026-09-10 21:20:00.901803 | controller | scenario.config.runtime.prepare_environment( 2026-09-10 21:20:00.901836 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-10 21:20:00.901843 | controller | install_local=True, 2026-09-10 21:20:00.901849 | controller | ^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:20:00.901856 | controller | role_name_check=role_name_check, 2026-09-10 21:20:00.901863 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:20:00.901870 | controller | ) 2026-09-10 21:20:00.901877 | controller | ^ 2026-09-10 21:20:00.902255 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible_compat/runtime.py", line 752, in prepare_environment 2026-09-10 21:20:00.902266 | controller | self.install_collection( 2026-09-10 21:20:00.902282 | controller | ~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-10 21:20:00.902289 | controller | f"{name}{',' if is_url(name) else ':'}{required_version}", 2026-09-10 21:20:00.902296 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:20:00.902303 | controller | destination=destination, 2026-09-10 21:20:00.902310 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:20:00.902317 | controller | ) 2026-09-10 21:20:00.902324 | controller | ^ 2026-09-10 21:20:00.902585 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible_compat/runtime.py", line 609, in install_collection 2026-09-10 21:20:00.902609 | controller | raise InvalidPrerequisiteError(msg) 2026-09-10 21:20:00.903659 | controller | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code: 2026-09-10 21:20:00.903683 | controller | Starting galaxy collection install process 2026-09-10 21:20:00.903691 | controller | Process install dependency map 2026-09-10 21:20:00.903699 | controller | Starting collection install process 2026-09-10 21:20:00.903717 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-posix-2.2.2.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1490nmg6kqhv/tmplr60oaf_/ansible-posix-2.2.2-2hrlppmp 2026-09-10 21:20:00.903725 | controller | 2026-09-10 21:20:00.903733 | controller | ansible-galaxy [core 2.20.1] 2026-09-10 21:20:00.903740 | controller | config file = None 2026-09-10 21:20:00.903747 | controller | configured module search path = ['/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/plugins/modules', '/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules'] 2026-09-10 21:20:00.903763 | controller | ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible 2026-09-10 21:20:00.903771 | controller | ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/local/lib/python3.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages 2026-09-10 21:20:00.903779 | controller | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy 2026-09-10 21:20:00.903786 | controller | python version = 3.13.5 (main, Jul 15 2026, 20:25:40) [GCC 14.2.0] (/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/python) 2026-09-10 21:20:00.903793 | controller | jinja version = 3.1.6 2026-09-10 21:20:00.903800 | controller | pyyaml version = 6.0.3 (with libyaml v0.2.5) 2026-09-10 21:20:00.903825 | controller | No config file found; using defaults 2026-09-10 21:20:00.903833 | controller | Created /home/zuul/.ansible/galaxy_token 2026-09-10 21:20:00.903839 | controller | [ERROR]: Failed to download collection tar from 'default': 2026-09-10 21:20:00.903847 | controller | 2026-09-10 21:20:00.903854 | controller | Failed to download collection tar from 'default': 2026-09-10 21:20:00.903861 | controller | 2026-09-10 21:20:00.903867 | controller | <<< caused by >>> 2026-09-10 21:20:00.903874 | controller | 2026-09-10 21:20:00.903881 | controller | 2026-09-10 21:20:00.903888 | controller | 2026-09-10 21:20:00.903895 | controller | 2026-09-10 21:20:01.393800 | controller | ERROR 2026-09-10 21:20:01.394488 | controller | { 2026-09-10 21:20:01.394532 | controller | "delta": "0:01:33.785925", 2026-09-10 21:20:01.394561 | controller | "end": "2026-09-10 21:20:00.992661", 2026-09-10 21:20:01.394585 | controller | "msg": "non-zero return code", 2026-09-10 21:20:01.394609 | controller | "rc": 1, 2026-09-10 21:20:01.394632 | controller | "start": "2026-09-10 21:18:27.206736" 2026-09-10 21:20:01.394655 | controller | } failure 2026-09-10 21:20:01.398150 | 2026-09-10 21:20:01.398296 | PLAY RECAP 2026-09-10 21:20:01.398384 | controller | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-10 21:20:01.398415 | 2026-09-10 21:20:01.520577 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-10 21:20:01.523367 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-09-10 21:20:02.358680 | 2026-09-10 21:20:02.359382 | PLAY [all] 2026-09-10 21:20:02.378682 | 2026-09-10 21:20:02.378843 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-09-10 21:20:02.832717 | controller | changed 2026-09-10 21:20:02.836863 | 2026-09-10 21:20:02.836946 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-09-10 21:20:03.228693 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-10 21:20:03.229028 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-10 21:20:03.237867 | controller | environment: line 1: kubectl: command not found 2026-09-10 21:20:03.240006 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-10 21:20:03.242296 | controller | environment: line 1: kubectl: command not found 2026-09-10 21:20:03.244285 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-10 21:20:03.245258 | controller | environment: line 1: kubectl: command not found 2026-09-10 21:20:03.247822 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-10 21:20:03.250017 | controller | environment: line 1: kubectl: command not found 2026-09-10 21:20:03.252382 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-10 21:20:03.253055 | controller | environment: line 1: kubectl: command not found 2026-09-10 21:20:03.255226 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-10 21:20:03.365637 | controller | ok: Runtime: 0:00:00.040386 2026-09-10 21:20:03.373655 | 2026-09-10 21:20:03.373784 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-09-10 21:20:03.632596 | controller | changed 2026-09-10 21:20:03.638112 | 2026-09-10 21:20:03.638217 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-09-10 21:20:03.906400 | controller | environment: line 5: kubectl: command not found 2026-09-10 21:20:03.908202 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-10 21:20:03.908545 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-10 21:20:03.908979 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-10 21:20:04.168969 | controller | ok: Runtime: 0:00:00.015305 2026-09-10 21:20:04.178309 | 2026-09-10 21:20:04.178472 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-09-10 21:20:04.831112 | controller | changed: 2026-09-10 21:20:04.831447 | controller | created directory /var/lib/zuul/builds/0972b2982de548fabde959561b16c3ef/work/logs/controller 2026-09-10 21:20:04.831655 | controller | cd+++++++++ objects/ 2026-09-10 21:20:04.831915 | controller | cd+++++++++ objects/cluster/ 2026-09-10 21:20:04.831992 | controller | cd+++++++++ objects/namespaced/ 2026-09-10 21:20:04.850589 | 2026-09-10 21:20:04.850833 | TASK [gather-pod-logs : creating directory for pod logs] 2026-09-10 21:20:05.106421 | controller | changed 2026-09-10 21:20:05.110765 | 2026-09-10 21:20:05.110834 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-09-10 21:20:05.371810 | controller | changed 2026-09-10 21:20:05.375381 | 2026-09-10 21:20:05.375448 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-09-10 21:20:05.674077 | controller | environment: line 3: kubectl: command not found 2026-09-10 21:20:05.901243 | controller | ok: Runtime: 0:00:00.013583 2026-09-10 21:20:05.910727 | 2026-09-10 21:20:05.910900 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-09-10 21:20:06.437260 | controller | changed: 2026-09-10 21:20:06.437554 | controller | cd+++++++++ pod-logs/ 2026-09-10 21:20:06.437609 | controller | cd+++++++++ pod-logs/failed-pods/ 2026-09-10 21:20:06.462668 | 2026-09-10 21:20:06.462936 | LOOP [helm-release-status : creating directory for helm release status] 2026-09-10 21:20:06.716376 | controller | changed: "values" 2026-09-10 21:20:06.945207 | controller | changed: "releases" 2026-09-10 21:20:06.962664 | 2026-09-10 21:20:06.962898 | TASK [helm-release-status : Gather get release status for helm charts] 2026-09-10 21:20:07.259233 | controller | /bin/bash: line 3: kubectl: command not found 2026-09-10 21:20:07.501674 | controller | ok: Runtime: 0:00:00.009141 2026-09-10 21:20:07.509267 | 2026-09-10 21:20:07.509413 | TASK [helm-release-status : Downloads logs to executor] 2026-09-10 21:20:08.018389 | controller | changed: 2026-09-10 21:20:08.018644 | controller | cd+++++++++ helm/ 2026-09-10 21:20:08.018699 | controller | cd+++++++++ helm/releases/ 2026-09-10 21:20:08.018746 | controller | cd+++++++++ helm/values/ 2026-09-10 21:20:08.028227 | 2026-09-10 21:20:08.028459 | PLAY RECAP 2026-09-10 21:20:08.028613 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-10 21:20:08.028669 | 2026-09-10 21:20:08.174377 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-09-10 21:20:08.176966 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-10 21:20:08.771139 | 2026-09-10 21:20:08.771297 | PLAY [all] 2026-09-10 21:20:08.781098 | 2026-09-10 21:20:08.781210 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-10 21:20:08.824757 | controller | skipping: Conditional result was False 2026-09-10 21:20:08.828852 | 2026-09-10 21:20:08.828946 | TASK [fetch-output : Set log path for single node] 2026-09-10 21:20:08.857568 | controller | ok 2026-09-10 21:20:08.862541 | 2026-09-10 21:20:08.862639 | LOOP [fetch-output : Ensure local output dirs] 2026-09-10 21:20:09.310075 | controller -> localhost | ok: "/var/lib/zuul/builds/0972b2982de548fabde959561b16c3ef/work/logs" 2026-09-10 21:20:09.538226 | controller -> localhost | changed: "/var/lib/zuul/builds/0972b2982de548fabde959561b16c3ef/work/artifacts" 2026-09-10 21:20:09.745792 | controller -> localhost | changed: "/var/lib/zuul/builds/0972b2982de548fabde959561b16c3ef/work/docs" 2026-09-10 21:20:09.764387 | 2026-09-10 21:20:09.764615 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-10 21:20:10.392731 | controller | changed: .d..t...... ./ 2026-09-10 21:20:10.392896 | controller | changed: All items complete 2026-09-10 21:20:10.392920 | 2026-09-10 21:20:10.902982 | controller | changed: .d..t...... ./ 2026-09-10 21:20:11.409634 | controller | changed: .d..t...... ./ 2026-09-10 21:20:11.447705 | 2026-09-10 21:20:11.447895 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-10 21:20:11.877992 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007527 2026-09-10 21:20:12.102603 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006688 2026-09-10 21:20:12.117458 | 2026-09-10 21:20:12.117594 | PLAY [all] 2026-09-10 21:20:12.126514 | 2026-09-10 21:20:12.126629 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-10 21:20:12.604416 | controller | changed 2026-09-10 21:20:12.612285 | 2026-09-10 21:20:12.612408 | PLAY RECAP 2026-09-10 21:20:12.612490 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-10 21:20:12.612563 | 2026-09-10 21:20:12.727235 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-10 21:20:12.729985 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-10 21:20:13.241186 | 2026-09-10 21:20:13.241291 | PLAY [localhost] 2026-09-10 21:20:13.249940 | 2026-09-10 21:20:13.250009 | TASK [Generate Zuul manifest] 2026-09-10 21:20:13.266854 | localhost | ok 2026-09-10 21:20:13.280149 | 2026-09-10 21:20:13.280235 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-10 21:20:13.665600 | localhost | changed 2026-09-10 21:20:13.686081 | 2026-09-10 21:20:13.686233 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-10 21:20:13.717438 | localhost | ok 2026-09-10 21:20:13.730060 | 2026-09-10 21:20:13.730182 | TASK [Upload logs] 2026-09-10 21:20:13.750345 | localhost | ok 2026-09-10 21:20:13.814893 | 2026-09-10 21:20:13.814997 | TASK [Set zuul-log-path fact] 2026-09-10 21:20:13.831129 | localhost | ok 2026-09-10 21:20:13.840507 | 2026-09-10 21:20:13.840566 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-10 21:20:13.868118 | localhost | ok 2026-09-10 21:20:13.876768 | 2026-09-10 21:20:13.876851 | TASK [upload-logs : Create log directories] 2026-09-10 21:20:14.283380 | localhost | changed 2026-09-10 21:20:14.291989 | 2026-09-10 21:20:14.292120 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-10 21:20:14.690119 | localhost -> localhost | ok: Runtime: 0:00:00.004222 2026-09-10 21:20:14.699661 | 2026-09-10 21:20:14.699786 | TASK [upload-logs : Upload logs to log server] 2026-09-10 21:20:15.155135 | localhost | Output suppressed because no_log was given 2026-09-10 21:20:15.162274 | 2026-09-10 21:20:15.162456 | LOOP [upload-logs : Compress console log and json output] 2026-09-10 21:20:15.223577 | localhost | skipping: Conditional result was False 2026-09-10 21:20:15.265109 | localhost | skipping: Conditional result was False 2026-09-10 21:20:15.276019 | 2026-09-10 21:20:15.276158 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-10 21:20:15.335468 | localhost | skipping: Conditional result was False 2026-09-10 21:20:15.335766 | 2026-09-10 21:20:15.341752 | localhost | skipping: Conditional result was False 2026-09-10 21:20:15.358436 | 2026-09-10 21:20:15.358570 | LOOP [upload-logs : Upload console log and json output]