2026-09-10 21:17:03.937868 | Job console starting 2026-09-10 21:17:03.952828 | Updating git repos 2026-09-10 21:17:04.067488 | Cloning repos into workspace 2026-09-10 21:17:04.127793 | Restoring repo states 2026-09-10 21:17:04.150068 | Merging changes 2026-09-10 21:17:05.013796 | Checking out repos 2026-09-10 21:17:05.227202 | Preparing playbooks 2026-09-10 21:17:07.753278 | Running Ansible setup 2026-09-10 21:17:11.625868 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-10 21:17:12.239404 | 2026-09-10 21:17:12.239593 | PLAY [localhost] 2026-09-10 21:17:12.248076 | 2026-09-10 21:17:12.248171 | TASK [Gathering Facts] 2026-09-10 21:17:13.115728 | localhost | ok 2026-09-10 21:17:13.121789 | 2026-09-10 21:17:13.121900 | TASK [Setup log path fact] 2026-09-10 21:17:13.149947 | localhost | ok 2026-09-10 21:17:13.164128 | 2026-09-10 21:17:13.164267 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-10 21:17:13.202799 | localhost | ok 2026-09-10 21:17:13.210558 | 2026-09-10 21:17:13.210671 | TASK [emit-job-header : Print job information] 2026-09-10 21:17:13.252236 | # Job Information 2026-09-10 21:17:13.252489 | Ansible Version: 2.16.18 2026-09-10 21:17:13.252582 | Job: ansible-collection-kubernetes-molecule-cluster-api 2026-09-10 21:17:13.252626 | Pipeline: check 2026-09-10 21:17:13.252665 | Executor: 2d72f0692154 2026-09-10 21:17:13.252703 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/300 2026-09-10 21:17:13.252747 | Event ID: 628dc250-ad5c-11f1-880f-7bd6db772b47 2026-09-10 21:17:13.257953 | 2026-09-10 21:17:13.258094 | LOOP [emit-job-header : Print node information] 2026-09-10 21:17:13.354420 | localhost | ok: 2026-09-10 21:17:13.354727 | localhost | # Node Information 2026-09-10 21:17:13.354807 | localhost | Inventory Hostname: controller 2026-09-10 21:17:13.354865 | localhost | Hostname: np0000192544 2026-09-10 21:17:13.354915 | localhost | Username: zuul 2026-09-10 21:17:13.354954 | localhost | Distro: Debian 13.6 2026-09-10 21:17:13.354985 | localhost | Provider: yul1 2026-09-10 21:17:13.355017 | localhost | Region: ca-ymq-1 2026-09-10 21:17:13.355048 | localhost | Label: debian-trixie 2026-09-10 21:17:13.355079 | localhost | Product Name: OpenStack Nova 2026-09-10 21:17:13.355111 | localhost | Interface IP: 162.253.55.121 2026-09-10 21:17:13.369554 | 2026-09-10 21:17:13.369821 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-10 21:17:13.832294 | localhost -> localhost | changed 2026-09-10 21:17:13.837417 | 2026-09-10 21:17:13.837486 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-10 21:17:14.791616 | localhost -> localhost | changed 2026-09-10 21:17:14.807569 | 2026-09-10 21:17:14.807697 | PLAY [all] 2026-09-10 21:17:14.820074 | 2026-09-10 21:17:14.820207 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-10 21:17:15.075193 | controller -> localhost | ok 2026-09-10 21:17:15.091824 | 2026-09-10 21:17:15.092029 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-10 21:17:15.123736 | controller | ok 2026-09-10 21:17:15.145903 | controller | included: /var/lib/zuul/builds/59c0e6fc287e46639c679319f045fe00/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-10 21:17:15.155594 | 2026-09-10 21:17:15.155736 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-10 21:17:16.725477 | controller -> localhost | Generating public/private rsa key pair. 2026-09-10 21:17:16.725715 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/59c0e6fc287e46639c679319f045fe00/work/59c0e6fc287e46639c679319f045fe00_id_rsa 2026-09-10 21:17:16.725755 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/59c0e6fc287e46639c679319f045fe00/work/59c0e6fc287e46639c679319f045fe00_id_rsa.pub 2026-09-10 21:17:16.725786 | controller -> localhost | The key fingerprint is: 2026-09-10 21:17:16.725814 | controller -> localhost | SHA256:FjBAAVec1h77HCEpkvZCM1v77xX2TMX81veslBrD12g zuul-build-sshkey 2026-09-10 21:17:16.725861 | controller -> localhost | The key's randomart image is: 2026-09-10 21:17:16.725890 | controller -> localhost | +---[RSA 3072]----+ 2026-09-10 21:17:16.725922 | controller -> localhost | | .o=*+o . | 2026-09-10 21:17:16.725950 | controller -> localhost | | .B *o= . o | 2026-09-10 21:17:16.725976 | controller -> localhost | | o O +.+ . +| 2026-09-10 21:17:16.726003 | controller -> localhost | | o o o.. .o| 2026-09-10 21:17:16.726029 | controller -> localhost | | . .So .o . =| 2026-09-10 21:17:16.726055 | controller -> localhost | | .. oo = *o| 2026-09-10 21:17:16.726080 | controller -> localhost | | . = E +| 2026-09-10 21:17:16.726106 | controller -> localhost | | .. B . | 2026-09-10 21:17:16.726136 | controller -> localhost | | .. . . | 2026-09-10 21:17:16.726215 | controller -> localhost | +----[SHA256]-----+ 2026-09-10 21:17:16.726287 | controller -> localhost | ok: Runtime: 0:00:01.139527 2026-09-10 21:17:16.731834 | 2026-09-10 21:17:16.731919 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-10 21:17:16.762992 | controller | ok 2026-09-10 21:17:16.773098 | controller | included: /var/lib/zuul/builds/59c0e6fc287e46639c679319f045fe00/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-10 21:17:16.781569 | 2026-09-10 21:17:16.781659 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-10 21:17:16.807432 | controller | skipping: Conditional result was False 2026-09-10 21:17:16.813972 | 2026-09-10 21:17:16.814073 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-10 21:17:17.405440 | controller | changed 2026-09-10 21:17:17.413226 | 2026-09-10 21:17:17.413378 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-10 21:17:17.635424 | controller | ok 2026-09-10 21:17:17.648255 | 2026-09-10 21:17:17.648527 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-10 21:17:18.254015 | controller | changed 2026-09-10 21:17:18.262374 | 2026-09-10 21:17:18.262573 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-10 21:17:18.862682 | controller | changed 2026-09-10 21:17:18.869097 | 2026-09-10 21:17:18.869182 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-10 21:17:18.895183 | controller | skipping: Conditional result was False 2026-09-10 21:17:18.901278 | 2026-09-10 21:17:18.901380 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-10 21:17:19.363275 | controller -> localhost | changed 2026-09-10 21:17:19.381821 | 2026-09-10 21:17:19.382056 | TASK [add-build-sshkey : Add back temp key] 2026-09-10 21:17:19.738798 | controller -> localhost | Identity added: /var/lib/zuul/builds/59c0e6fc287e46639c679319f045fe00/work/59c0e6fc287e46639c679319f045fe00_id_rsa (zuul-build-sshkey) 2026-09-10 21:17:19.739136 | controller -> localhost | ok: Runtime: 0:00:00.019335 2026-09-10 21:17:19.746761 | 2026-09-10 21:17:19.788664 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-10 21:17:20.156917 | controller | ok 2026-09-10 21:17:20.164234 | 2026-09-10 21:17:20.166524 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-10 21:17:20.201719 | controller | skipping: Conditional result was False 2026-09-10 21:17:20.218063 | 2026-09-10 21:17:20.218236 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-10 21:17:20.575317 | controller | ok 2026-09-10 21:17:20.581211 | 2026-09-10 21:17:20.581295 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-10 21:17:21.678265 | controller | Output suppressed because no_log was given 2026-09-10 21:17:21.699609 | 2026-09-10 21:17:21.699836 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-10 21:17:21.948187 | controller | ok: "logs" 2026-09-10 21:17:21.948704 | controller | ok: All items complete 2026-09-10 21:17:21.948765 | 2026-09-10 21:17:22.151871 | controller | ok: "artifacts" 2026-09-10 21:17:22.355853 | controller | ok: "docs" 2026-09-10 21:17:22.367687 | 2026-09-10 21:17:22.367933 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-10 21:17:22.610919 | controller | changed: "logs" 2026-09-10 21:17:22.817661 | controller | changed: "artifacts" 2026-09-10 21:17:22.993120 | controller | changed: "docs" 2026-09-10 21:17:23.013912 | 2026-09-10 21:17:23.014104 | PLAY RECAP 2026-09-10 21:17:23.014195 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-10 21:17:23.014248 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-10 21:17:23.014288 | 2026-09-10 21:17:23.246557 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-10 21:17:23.248164 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-10 21:17:23.934573 | 2026-09-10 21:17:23.935424 | PLAY [all] 2026-09-10 21:17:23.950066 | 2026-09-10 21:17:23.950263 | TASK [setup-uv : Extract archive] 2026-09-10 21:17:26.472009 | controller | changed 2026-09-10 21:17:26.482134 | 2026-09-10 21:17:26.482303 | TASK [setup-uv : Print version] 2026-09-10 21:17:26.882551 | controller | uv 0.8.13 2026-09-10 21:17:27.026279 | controller | ok: Runtime: 0:00:00.015576 2026-09-10 21:17:27.039578 | 2026-09-10 21:17:27.039727 | TASK [Install Ansible collection siblings] 2026-09-10 21:17:27.478354 | controller | ok: 2026-09-10 21:17:27.478831 | controller | Skipping ansible.posix: no checked-out sibling 2026-09-10 21:17:27.478886 | controller | Skipping community.crypto: no checked-out sibling 2026-09-10 21:17:27.478927 | controller | Skipping community.general: no checked-out sibling 2026-09-10 21:17:27.478966 | controller | Skipping kubernetes.core: no checked-out sibling 2026-09-10 21:17:27.479003 | controller | Skipping vexxhost.containers: no checked-out sibling 2026-09-10 21:17:27.482224 | 2026-09-10 21:17:27.482310 | PLAY RECAP 2026-09-10 21:17:27.482423 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-10 21:17:27.482466 | 2026-09-10 21:17:27.652674 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-10 21:17:27.656237 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-09-10 21:17:28.259406 | 2026-09-10 21:17:28.259556 | PLAY [all] 2026-09-10 21:17:28.268966 | 2026-09-10 21:17:28.269033 | TASK [Update apt cache] 2026-09-10 21:17:33.998953 | controller | ok 2026-09-10 21:17:34.003889 | 2026-09-10 21:17:34.003984 | TASK [Disable firewalld] 2026-09-10 21:17:34.027555 | controller | skipping: Conditional result was False 2026-09-10 21:17:34.033012 | 2026-09-10 21:17:34.033097 | TASK [Install "jq" for log collection] 2026-09-10 21:17:36.718802 | controller | changed 2026-09-10 21:17:36.720580 | 2026-09-10 21:17:36.720631 | PLAY RECAP 2026-09-10 21:17:36.720673 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-10 21:17:36.720693 | 2026-09-10 21:17:36.866173 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-09-10 21:17:36.867986 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-10 21:17:37.454248 | 2026-09-10 21:17:37.454396 | PLAY [all] 2026-09-10 21:17:37.464018 | 2026-09-10 21:17:37.464093 | LOOP [Check inventory directories] 2026-09-10 21:17:37.883190 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes" 2026-09-10 21:17:38.076846 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/cluster-api/inventory" 2026-09-10 21:17:38.276927 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/cluster-api/inventory" 2026-09-10 21:17:38.294862 | 2026-09-10 21:17:38.295113 | LOOP [Copy inventory file for Zuul] 2026-09-10 21:17:39.289810 | controller | changed: 2026-09-10 21:17:39.290104 | controller | { 2026-09-10 21:17:39.290170 | controller | "ansible_loop_var": "item", 2026-09-10 21:17:39.290226 | controller | "changed": false, 2026-09-10 21:17:39.290270 | controller | "failed": false, 2026-09-10 21:17:39.290347 | controller | "invocation": { 2026-09-10 21:17:39.290396 | controller | "module_args": { 2026-09-10 21:17:39.290446 | controller | "checksum_algorithm": "sha1", 2026-09-10 21:17:39.290484 | controller | "follow": true, 2026-09-10 21:17:39.290527 | controller | "get_attributes": true, 2026-09-10 21:17:39.290570 | controller | "get_checksum": true, 2026-09-10 21:17:39.290618 | controller | "get_mime": true, 2026-09-10 21:17:39.290670 | controller | "path": "src/github.com/vexxhost/ansible-collection-kubernetes" 2026-09-10 21:17:39.290707 | controller | } 2026-09-10 21:17:39.290742 | controller | }, 2026-09-10 21:17:39.290775 | controller | "item": "src/github.com/vexxhost/ansible-collection-kubernetes", 2026-09-10 21:17:39.290808 | controller | "stat": { 2026-09-10 21:17:39.290841 | controller | "atime": 1789075041.5136652, 2026-09-10 21:17:39.290875 | controller | "attr_flags": "e", 2026-09-10 21:17:39.290912 | controller | "attributes": [ 2026-09-10 21:17:39.290946 | controller | "extents" 2026-09-10 21:17:39.290980 | controller | ], 2026-09-10 21:17:39.291013 | controller | "block_size": 4096, 2026-09-10 21:17:39.291045 | controller | "blocks": 8, 2026-09-10 21:17:39.291078 | controller | "charset": "unknown", 2026-09-10 21:17:39.291114 | controller | "ctime": 1789075041.5536659, 2026-09-10 21:17:39.291147 | controller | "dev": 65025, 2026-09-10 21:17:39.291183 | controller | "device_type": 0, 2026-09-10 21:17:39.291217 | controller | "executable": true, 2026-09-10 21:17:39.291250 | controller | "exists": true, 2026-09-10 21:17:39.291285 | controller | "gid": 1000, 2026-09-10 21:17:39.291318 | controller | "gr_name": "zuul", 2026-09-10 21:17:39.291379 | controller | "inode": 521495, 2026-09-10 21:17:39.291413 | controller | "isblk": false, 2026-09-10 21:17:39.291448 | controller | "ischr": false, 2026-09-10 21:17:39.291482 | controller | "isdir": true, 2026-09-10 21:17:39.291514 | controller | "isfifo": false, 2026-09-10 21:17:39.291546 | controller | "isgid": false, 2026-09-10 21:17:39.291578 | controller | "islnk": false, 2026-09-10 21:17:39.291611 | controller | "isreg": false, 2026-09-10 21:17:39.291657 | controller | "issock": false, 2026-09-10 21:17:39.291707 | controller | "isuid": false, 2026-09-10 21:17:39.291742 | controller | "mimetype": "unknown", 2026-09-10 21:17:39.291774 | controller | "mode": "0755", 2026-09-10 21:17:39.291807 | controller | "mtime": 1789075024.282453, 2026-09-10 21:17:39.291840 | controller | "nlink": 14, 2026-09-10 21:17:39.291873 | controller | "path": "src/github.com/vexxhost/ansible-collection-kubernetes", 2026-09-10 21:17:39.291905 | controller | "pw_name": "zuul", 2026-09-10 21:17:39.291945 | controller | "readable": true, 2026-09-10 21:17:39.291980 | controller | "rgrp": true, 2026-09-10 21:17:39.292014 | controller | "roth": true, 2026-09-10 21:17:39.292048 | controller | "rusr": true, 2026-09-10 21:17:39.292081 | controller | "size": 4096, 2026-09-10 21:17:39.292113 | controller | "uid": 1000, 2026-09-10 21:17:39.292146 | controller | "version": "3439776130", 2026-09-10 21:17:39.292179 | controller | "wgrp": false, 2026-09-10 21:17:39.292215 | controller | "woth": false, 2026-09-10 21:17:39.292253 | controller | "writeable": true, 2026-09-10 21:17:39.292286 | controller | "wusr": true, 2026-09-10 21:17:39.292319 | controller | "xgrp": true, 2026-09-10 21:17:39.292573 | controller | "xoth": true, 2026-09-10 21:17:39.292612 | controller | "xusr": true 2026-09-10 21:17:39.292667 | controller | } 2026-09-10 21:17:39.292722 | controller | } 2026-09-10 21:17:39.292800 | controller | ok: All items complete 2026-09-10 21:17:39.292844 | 2026-09-10 21:17:39.295385 | controller | skipping: Conditional result was False 2026-09-10 21:17:39.296648 | controller | skipping: Conditional result was False 2026-09-10 21:17:39.317532 | 2026-09-10 21:17:39.317723 | LOOP [Switch "ansible_host" to private IP] 2026-09-10 21:17:39.696655 | controller | changed: 1 replacements made 2026-09-10 21:17:39.699056 | controller | skipping: Conditional result was False 2026-09-10 21:17:39.703852 | controller | skipping: Conditional result was False 2026-09-10 21:17:39.718869 | 2026-09-10 21:17:39.719033 | TASK [Run Molecule scenario] 2026-09-10 21:17:40.266519 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-09-10 21:17:40.266615 | controller | Creating virtual environment at: .venv 2026-09-10 21:17:41.542551 | controller | Downloading pygments (1.2MiB) 2026-09-10 21:17:41.550716 | controller | Downloading ansible-core (2.3MiB) 2026-09-10 21:17:41.551006 | controller | Downloading cryptography (4.3MiB) 2026-09-10 21:17:41.732256 | controller | Downloading cryptography 2026-09-10 21:17:41.786923 | controller | Downloading pygments 2026-09-10 21:17:41.985183 | controller | Downloading ansible-core 2026-09-10 21:17:42.053684 | controller | Installed 36 packages in 67ms 2026-09-10 21:17:42.707631 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-09-10 21:17:42.707663 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-09-10 21:17:43.558011 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-09-10 21:17:43.558119 | controller | INFO default scenario not found, disabling shared state. 2026-09-10 21:17:43.558307 | controller | INFO [cluster-api > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-10 21:17:43.558428 | controller | INFO [cluster-api > prerun] Performing prerun with role_name_check=0... 2026-09-10 21:18:35.133546 | controller | WARNING Retrying execution failure 1 of: ansible-galaxy collection install -vvv ansible.posix:>=1.6.0 2026-09-10 21:18:35.133772 | controller | ERROR Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code: 2026-09-10 21:18:35.133787 | controller | Starting galaxy collection install process 2026-09-10 21:18:35.133795 | controller | Process install dependency map 2026-09-10 21:18:35.133804 | controller | 2026-09-10 21:18:35.133812 | controller | ansible-galaxy 2026-09-10 21:18:35.133819 | controller | config file = None 2026-09-10 21:18:35.133846 | controller | configured module search path = 2026-09-10 21:18:35.133853 | 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:18:35.133863 | 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:18:35.133873 | controller | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy 2026-09-10 21:18:35.133881 | 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:18:35.133889 | controller | jinja version = 3.1.6 2026-09-10 21:18:35.133896 | controller | pyyaml version = 6.0.3 (with libyaml v0.2.5) 2026-09-10 21:18:35.133904 | controller | No config file found; using defaults 2026-09-10 21:18:35.133910 | controller | Created /home/zuul/.ansible/galaxy_token 2026-09-10 21:18:35.133918 | controller | : Skipping Galaxy server https://galaxy.ansible.com. Got an unexpected error when getting available versions of collection ansible.posix: Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/': 2026-09-10 21:18:35.133926 | controller | : Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/': 2026-09-10 21:18:35.133933 | controller | 2026-09-10 21:18:35.133941 | controller | Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/'. 2026-09-10 21:18:35.133948 | controller | 2026-09-10 21:18:35.133955 | controller | <<< caused by >>> 2026-09-10 21:18:35.133961 | controller | 2026-09-10 21:18:35.133968 | controller | 2026-09-10 21:18:35.133975 | controller | 2026-09-10 21:18:35.133982 | controller | 2026-09-10 21:18:35.137293 | controller | Traceback (most recent call last): 2026-09-10 21:18:35.140703 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/molecule", line 10, in 2026-09-10 21:18:35.140806 | controller | sys.exit(main()) 2026-09-10 21:18:35.140815 | controller | ~~~~^^ 2026-09-10 21:18:35.140916 | 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:18:35.140927 | controller | return self.main(*args, **kwargs) 2026-09-10 21:18:35.140935 | controller | ~~~~~~~~~^^^^^^^^^^^^^^^^^ 2026-09-10 21:18:35.141191 | 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:18:35.141201 | controller | rv = self.invoke(ctx) 2026-09-10 21:18:35.141484 | 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:18:35.141493 | controller | return _process_result(sub_ctx.command.invoke(sub_ctx)) 2026-09-10 21:18:35.141501 | controller | ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^ 2026-09-10 21:18:35.141761 | 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:18:35.141770 | controller | return ctx.invoke(self.callback, **ctx.params) 2026-09-10 21:18:35.141777 | controller | ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:18:35.142008 | 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:18:35.142022 | controller | return callback(*args, **kwargs) 2026-09-10 21:18:35.142245 | 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:18:35.142253 | controller | return func(*args, **kwargs) 2026-09-10 21:18:35.142555 | 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:18:35.142566 | controller | return f(get_current_context(), *args, **kwargs) 2026-09-10 21:18:35.142786 | 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:18:35.142794 | controller | return func(ctx) 2026-09-10 21:18:35.143077 | 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:18:35.143086 | controller | base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude) 2026-09-10 21:18:35.143093 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:18:35.143385 | 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:18:35.143394 | controller | _run_scenarios(scenarios, command_args, default_config) 2026-09-10 21:18:35.143401 | controller | ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:18:35.143815 | 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:18:35.143824 | controller | scenario.config.runtime.prepare_environment( 2026-09-10 21:18:35.143831 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-10 21:18:35.143838 | controller | install_local=True, 2026-09-10 21:18:35.143845 | controller | ^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:18:35.143852 | controller | role_name_check=role_name_check, 2026-09-10 21:18:35.143859 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:18:35.143866 | controller | ) 2026-09-10 21:18:35.143873 | controller | ^ 2026-09-10 21:18:35.144242 | 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:18:35.144250 | controller | self.install_collection( 2026-09-10 21:18:35.144257 | controller | ~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-10 21:18:35.144292 | controller | f"{name}{',' if is_url(name) else ':'}{required_version}", 2026-09-10 21:18:35.144300 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:18:35.144307 | controller | destination=destination, 2026-09-10 21:18:35.144314 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:18:35.144320 | controller | ) 2026-09-10 21:18:35.144327 | controller | ^ 2026-09-10 21:18:35.144520 | 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:18:35.144529 | controller | raise InvalidPrerequisiteError(msg) 2026-09-10 21:18:35.145601 | controller | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code: 2026-09-10 21:18:35.145610 | controller | Starting galaxy collection install process 2026-09-10 21:18:35.145617 | controller | Process install dependency map 2026-09-10 21:18:35.145624 | controller | 2026-09-10 21:18:35.145632 | controller | ansible-galaxy [core 2.20.1] 2026-09-10 21:18:35.145639 | controller | config file = None 2026-09-10 21:18:35.145646 | 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:18:35.145653 | 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:18:35.145670 | 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:18:35.145679 | controller | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy 2026-09-10 21:18:35.145686 | 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:18:35.145693 | controller | jinja version = 3.1.6 2026-09-10 21:18:35.145700 | controller | pyyaml version = 6.0.3 (with libyaml v0.2.5) 2026-09-10 21:18:35.145707 | controller | No config file found; using defaults 2026-09-10 21:18:35.145713 | controller | Created /home/zuul/.ansible/galaxy_token 2026-09-10 21:18:35.145721 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com. Got an unexpected error when getting available versions of collection ansible.posix: Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/': 2026-09-10 21:18:35.145728 | controller | [ERROR]: Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/': 2026-09-10 21:18:35.145735 | controller | 2026-09-10 21:18:35.145743 | controller | Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/'. 2026-09-10 21:18:35.145750 | controller | 2026-09-10 21:18:35.145757 | controller | <<< caused by >>> 2026-09-10 21:18:35.145764 | controller | 2026-09-10 21:18:35.145771 | controller | 2026-09-10 21:18:35.145777 | controller | 2026-09-10 21:18:35.145784 | controller | 2026-09-10 21:18:35.287704 | controller | ERROR 2026-09-10 21:18:35.288000 | controller | { 2026-09-10 21:18:35.288058 | controller | "delta": "0:00:55.070628", 2026-09-10 21:18:35.288099 | controller | "end": "2026-09-10 21:18:35.230953", 2026-09-10 21:18:35.288136 | controller | "msg": "non-zero return code", 2026-09-10 21:18:35.288172 | controller | "rc": 1, 2026-09-10 21:18:35.288208 | controller | "start": "2026-09-10 21:17:40.160325" 2026-09-10 21:18:35.288244 | controller | } failure 2026-09-10 21:18:35.292192 | 2026-09-10 21:18:35.292278 | PLAY RECAP 2026-09-10 21:18:35.292443 | controller | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-10 21:18:35.292487 | 2026-09-10 21:18:35.442096 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-10 21:18:35.443747 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-09-10 21:18:36.064735 | 2026-09-10 21:18:36.064885 | PLAY [all] 2026-09-10 21:18:36.075334 | 2026-09-10 21:18:36.075407 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-09-10 21:18:36.493356 | controller | changed 2026-09-10 21:18:36.505082 | 2026-09-10 21:18:36.505249 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-09-10 21:18:36.983780 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-10 21:18:36.984038 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-10 21:18:36.992438 | controller | environment: line 1: kubectl: command not found 2026-09-10 21:18:36.995341 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-10 21:18:36.996609 | controller | environment: line 1: kubectl: command not found 2026-09-10 21:18:36.997975 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-10 21:18:37.001356 | controller | environment: line 1: kubectl: command not found 2026-09-10 21:18:37.003475 | controller | environment: line 1: kubectl: command not found 2026-09-10 21:18:37.003914 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-10 21:18:37.005529 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-10 21:18:37.008993 | controller | environment: line 1: kubectl: command not found 2026-09-10 21:18:37.011367 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-10 21:18:37.070345 | controller | ok: Runtime: 0:00:00.044704 2026-09-10 21:18:37.078194 | 2026-09-10 21:18:37.078336 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-09-10 21:18:37.334609 | controller | changed 2026-09-10 21:18:37.342128 | 2026-09-10 21:18:37.342274 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-09-10 21:18:37.638498 | controller | environment: line 5: kubectl: command not found 2026-09-10 21:18:37.639930 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-10 21:18:37.640124 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-10 21:18:37.640500 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-10 21:18:37.881758 | controller | ok: Runtime: 0:00:00.014262 2026-09-10 21:18:37.891020 | 2026-09-10 21:18:37.891146 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-09-10 21:18:38.598546 | controller | changed: 2026-09-10 21:18:38.598842 | controller | created directory /var/lib/zuul/builds/59c0e6fc287e46639c679319f045fe00/work/logs/controller 2026-09-10 21:18:38.598959 | controller | cd+++++++++ objects/ 2026-09-10 21:18:38.599017 | controller | cd+++++++++ objects/cluster/ 2026-09-10 21:18:38.599065 | controller | cd+++++++++ objects/namespaced/ 2026-09-10 21:18:38.612665 | 2026-09-10 21:18:38.612791 | TASK [gather-pod-logs : creating directory for pod logs] 2026-09-10 21:18:38.871034 | controller | changed 2026-09-10 21:18:38.878867 | 2026-09-10 21:18:38.878997 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-09-10 21:18:39.154862 | controller | changed 2026-09-10 21:18:39.162932 | 2026-09-10 21:18:39.163075 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-09-10 21:18:39.457139 | controller | environment: line 3: kubectl: command not found 2026-09-10 21:18:39.703708 | controller | ok: Runtime: 0:00:00.014913 2026-09-10 21:18:39.711705 | 2026-09-10 21:18:39.711827 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-09-10 21:18:40.268982 | controller | changed: 2026-09-10 21:18:40.269214 | controller | cd+++++++++ pod-logs/ 2026-09-10 21:18:40.269249 | controller | cd+++++++++ pod-logs/failed-pods/ 2026-09-10 21:18:40.279791 | 2026-09-10 21:18:40.279899 | LOOP [helm-release-status : creating directory for helm release status] 2026-09-10 21:18:40.544245 | controller | changed: "values" 2026-09-10 21:18:40.747784 | controller | changed: "releases" 2026-09-10 21:18:40.759256 | 2026-09-10 21:18:40.760634 | TASK [helm-release-status : Gather get release status for helm charts] 2026-09-10 21:18:41.057122 | controller | /bin/bash: line 3: kubectl: command not found 2026-09-10 21:18:41.323665 | controller | ok: Runtime: 0:00:00.010106 2026-09-10 21:18:41.331707 | 2026-09-10 21:18:41.331828 | TASK [helm-release-status : Downloads logs to executor] 2026-09-10 21:18:41.911751 | controller | changed: 2026-09-10 21:18:41.912072 | controller | cd+++++++++ helm/ 2026-09-10 21:18:41.912127 | controller | cd+++++++++ helm/releases/ 2026-09-10 21:18:41.912174 | controller | cd+++++++++ helm/values/ 2026-09-10 21:18:41.921436 | 2026-09-10 21:18:41.921536 | PLAY RECAP 2026-09-10 21:18:41.921621 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-10 21:18:41.921684 | 2026-09-10 21:18:42.089743 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-09-10 21:18:42.091367 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-10 21:18:42.696670 | 2026-09-10 21:18:42.696818 | PLAY [all] 2026-09-10 21:18:42.706350 | 2026-09-10 21:18:42.706423 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-10 21:18:42.774195 | controller | skipping: Conditional result was False 2026-09-10 21:18:42.780440 | 2026-09-10 21:18:42.780571 | TASK [fetch-output : Set log path for single node] 2026-09-10 21:18:42.843229 | controller | ok 2026-09-10 21:18:42.851191 | 2026-09-10 21:18:42.851353 | LOOP [fetch-output : Ensure local output dirs] 2026-09-10 21:18:43.322060 | controller -> localhost | ok: "/var/lib/zuul/builds/59c0e6fc287e46639c679319f045fe00/work/logs" 2026-09-10 21:18:43.547127 | controller -> localhost | changed: "/var/lib/zuul/builds/59c0e6fc287e46639c679319f045fe00/work/artifacts" 2026-09-10 21:18:43.782012 | controller -> localhost | changed: "/var/lib/zuul/builds/59c0e6fc287e46639c679319f045fe00/work/docs" 2026-09-10 21:18:43.800331 | 2026-09-10 21:18:43.800546 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-10 21:18:44.487766 | controller | changed: .d..t...... ./ 2026-09-10 21:18:44.487948 | controller | changed: All items complete 2026-09-10 21:18:44.487972 | 2026-09-10 21:18:44.973881 | controller | changed: .d..t...... ./ 2026-09-10 21:18:45.477661 | controller | changed: .d..t...... ./ 2026-09-10 21:18:45.503739 | 2026-09-10 21:18:45.503978 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-10 21:18:45.986304 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006651 2026-09-10 21:18:46.236194 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007989 2026-09-10 21:18:46.257451 | 2026-09-10 21:18:46.257662 | PLAY [all] 2026-09-10 21:18:46.268068 | 2026-09-10 21:18:46.268377 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-10 21:18:46.758920 | controller | changed 2026-09-10 21:18:46.767554 | 2026-09-10 21:18:46.767649 | PLAY RECAP 2026-09-10 21:18:46.767733 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-10 21:18:46.767775 | 2026-09-10 21:18:46.958270 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-10 21:18:46.959885 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-10 21:18:47.509429 | 2026-09-10 21:18:47.509565 | PLAY [localhost] 2026-09-10 21:18:47.518938 | 2026-09-10 21:18:47.519003 | TASK [Generate Zuul manifest] 2026-09-10 21:18:47.536556 | localhost | ok 2026-09-10 21:18:47.549666 | 2026-09-10 21:18:47.549742 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-10 21:18:47.964969 | localhost | changed 2026-09-10 21:18:47.984861 | 2026-09-10 21:18:47.985005 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-10 21:18:48.016633 | localhost | ok 2026-09-10 21:18:48.029387 | 2026-09-10 21:18:48.029512 | TASK [Upload logs] 2026-09-10 21:18:48.049578 | localhost | ok 2026-09-10 21:18:48.116264 | 2026-09-10 21:18:48.116412 | TASK [Set zuul-log-path fact] 2026-09-10 21:18:48.133009 | localhost | ok 2026-09-10 21:18:48.142747 | 2026-09-10 21:18:48.142809 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-10 21:18:48.169464 | localhost | ok 2026-09-10 21:18:48.178436 | 2026-09-10 21:18:48.178518 | TASK [upload-logs : Create log directories] 2026-09-10 21:18:48.625125 | localhost | changed 2026-09-10 21:18:48.635118 | 2026-09-10 21:18:48.635260 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-10 21:18:49.041179 | localhost -> localhost | ok: Runtime: 0:00:00.005151 2026-09-10 21:18:49.047446 | 2026-09-10 21:18:49.047531 | TASK [upload-logs : Upload logs to log server] 2026-09-10 21:18:49.524631 | localhost | Output suppressed because no_log was given 2026-09-10 21:18:49.531790 | 2026-09-10 21:18:49.531915 | LOOP [upload-logs : Compress console log and json output] 2026-09-10 21:18:49.594997 | localhost | skipping: Conditional result was False 2026-09-10 21:18:49.649947 | localhost | skipping: Conditional result was False 2026-09-10 21:18:49.666258 | 2026-09-10 21:18:49.666455 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-10 21:18:49.708449 | localhost | skipping: Conditional result was False 2026-09-10 21:18:49.708876 | 2026-09-10 21:18:49.712077 | localhost | skipping: Conditional result was False 2026-09-10 21:18:49.729803 | 2026-09-10 21:18:49.729984 | LOOP [upload-logs : Upload console log and json output]