2026-09-10 21:15:34.779076 | Job console starting 2026-09-10 21:15:34.790174 | Updating git repos 2026-09-10 21:15:34.839745 | Cloning repos into workspace 2026-09-10 21:15:34.897710 | Restoring repo states 2026-09-10 21:15:34.917206 | Merging changes 2026-09-10 21:15:35.786182 | Checking out repos 2026-09-10 21:15:35.968904 | Preparing playbooks 2026-09-10 21:15:38.575249 | Running Ansible setup 2026-09-10 21:15:43.371157 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-10 21:15:43.971394 | 2026-09-10 21:15:43.971546 | PLAY [localhost] 2026-09-10 21:15:43.978658 | 2026-09-10 21:15:43.978722 | TASK [Gathering Facts] 2026-09-10 21:15:44.887531 | localhost | ok 2026-09-10 21:15:44.904668 | 2026-09-10 21:15:44.904826 | TASK [Setup log path fact] 2026-09-10 21:15:44.926762 | localhost | ok 2026-09-10 21:15:44.947112 | 2026-09-10 21:15:44.947203 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-10 21:15:44.977339 | localhost | ok 2026-09-10 21:15:44.988091 | 2026-09-10 21:15:44.988217 | TASK [emit-job-header : Print job information] 2026-09-10 21:15:45.039203 | # Job Information 2026-09-10 21:15:45.039416 | Ansible Version: 2.16.18 2026-09-10 21:15:45.039480 | Job: ansible-collection-kubernetes-molecule-aio-rockylinux-9 2026-09-10 21:15:45.039523 | Pipeline: check 2026-09-10 21:15:45.039561 | Executor: 2d72f0692154 2026-09-10 21:15:45.039600 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/300 2026-09-10 21:15:45.039644 | Event ID: 628dc250-ad5c-11f1-880f-7bd6db772b47 2026-09-10 21:15:45.045066 | 2026-09-10 21:15:45.045196 | LOOP [emit-job-header : Print node information] 2026-09-10 21:15:45.143023 | localhost | ok: 2026-09-10 21:15:45.143321 | localhost | # Node Information 2026-09-10 21:15:45.143409 | localhost | Inventory Hostname: controller 2026-09-10 21:15:45.143463 | localhost | Hostname: np0000192534 2026-09-10 21:15:45.143517 | localhost | Username: zuul 2026-09-10 21:15:45.143570 | localhost | Distro: Rocky 9.7 2026-09-10 21:15:45.143616 | localhost | Provider: yul1 2026-09-10 21:15:45.143660 | localhost | Region: ca-ymq-1 2026-09-10 21:15:45.143705 | localhost | Label: rockylinux-9 2026-09-10 21:15:45.143749 | localhost | Product Name: OpenStack Nova 2026-09-10 21:15:45.143794 | localhost | Interface IP: 162.253.55.108 2026-09-10 21:15:45.156371 | 2026-09-10 21:15:45.156661 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-10 21:15:45.655272 | localhost -> localhost | changed 2026-09-10 21:15:45.661028 | 2026-09-10 21:15:45.661148 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-10 21:15:46.589546 | localhost -> localhost | changed 2026-09-10 21:15:46.598704 | 2026-09-10 21:15:46.598854 | PLAY [all] 2026-09-10 21:15:46.616930 | 2026-09-10 21:15:46.617189 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-10 21:15:46.867018 | controller -> localhost | ok 2026-09-10 21:15:46.877338 | 2026-09-10 21:15:46.877480 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-10 21:15:46.908981 | controller | ok 2026-09-10 21:15:46.927396 | controller | included: /var/lib/zuul/builds/4a42280a8b4e480f971d341a1418a823/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-10 21:15:46.934421 | 2026-09-10 21:15:46.934505 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-10 21:15:48.334078 | controller -> localhost | Generating public/private rsa key pair. 2026-09-10 21:15:48.334404 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/4a42280a8b4e480f971d341a1418a823/work/4a42280a8b4e480f971d341a1418a823_id_rsa 2026-09-10 21:15:48.334468 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/4a42280a8b4e480f971d341a1418a823/work/4a42280a8b4e480f971d341a1418a823_id_rsa.pub 2026-09-10 21:15:48.334754 | controller -> localhost | The key fingerprint is: 2026-09-10 21:15:48.334827 | controller -> localhost | SHA256:xRcsP87XlfryRpwT1GizCPUFkAq59OkfDBPMzJwmJJI zuul-build-sshkey 2026-09-10 21:15:48.334890 | controller -> localhost | The key's randomart image is: 2026-09-10 21:15:48.334934 | controller -> localhost | +---[RSA 3072]----+ 2026-09-10 21:15:48.334992 | controller -> localhost | | .....B ++o.oo| 2026-09-10 21:15:48.335038 | controller -> localhost | | E. .=.% oo+o.| 2026-09-10 21:15:48.335080 | controller -> localhost | | . *oB.ooo.| 2026-09-10 21:15:48.335121 | controller -> localhost | | ..*.+ .o.| 2026-09-10 21:15:48.335162 | controller -> localhost | | S. * .o.+| 2026-09-10 21:15:48.335202 | controller -> localhost | | . =..=.| 2026-09-10 21:15:48.335242 | controller -> localhost | | . oo .| 2026-09-10 21:15:48.335283 | controller -> localhost | | .. o | 2026-09-10 21:15:48.335329 | controller -> localhost | | +. | 2026-09-10 21:15:48.335394 | controller -> localhost | +----[SHA256]-----+ 2026-09-10 21:15:48.335501 | controller -> localhost | ok: Runtime: 0:00:00.930252 2026-09-10 21:15:48.346971 | 2026-09-10 21:15:48.347413 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-10 21:15:48.380782 | controller | ok 2026-09-10 21:15:48.395778 | controller | included: /var/lib/zuul/builds/4a42280a8b4e480f971d341a1418a823/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-10 21:15:48.405448 | 2026-09-10 21:15:48.405540 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-10 21:15:48.429483 | controller | skipping: Conditional result was False 2026-09-10 21:15:48.440949 | 2026-09-10 21:15:48.441100 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-10 21:15:48.948079 | controller | changed 2026-09-10 21:15:48.957962 | 2026-09-10 21:15:48.958062 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-10 21:15:49.245926 | controller | ok 2026-09-10 21:15:49.250357 | 2026-09-10 21:15:49.250421 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-10 21:15:50.215111 | controller | changed 2026-09-10 21:15:50.225525 | 2026-09-10 21:15:50.225690 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-10 21:15:51.200031 | controller | changed 2026-09-10 21:15:51.204940 | 2026-09-10 21:15:51.205029 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-10 21:15:51.229959 | controller | skipping: Conditional result was False 2026-09-10 21:15:51.239811 | 2026-09-10 21:15:51.239948 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-10 21:15:51.669362 | controller -> localhost | changed 2026-09-10 21:15:51.687019 | 2026-09-10 21:15:51.687197 | TASK [add-build-sshkey : Add back temp key] 2026-09-10 21:15:51.971246 | controller -> localhost | Identity added: /var/lib/zuul/builds/4a42280a8b4e480f971d341a1418a823/work/4a42280a8b4e480f971d341a1418a823_id_rsa (zuul-build-sshkey) 2026-09-10 21:15:51.971419 | controller -> localhost | ok: Runtime: 0:00:00.014787 2026-09-10 21:15:51.976616 | 2026-09-10 21:15:51.976672 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-10 21:15:52.439532 | controller | ok 2026-09-10 21:15:52.445230 | 2026-09-10 21:15:52.445369 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-10 21:15:52.469879 | controller | skipping: Conditional result was False 2026-09-10 21:15:52.481703 | 2026-09-10 21:15:52.481816 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-10 21:15:52.888927 | controller | ok 2026-09-10 21:15:52.898598 | 2026-09-10 21:15:52.898716 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-10 21:15:54.125943 | controller | Output suppressed because no_log was given 2026-09-10 21:15:54.154376 | 2026-09-10 21:15:54.154519 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-10 21:15:54.450974 | controller | ok: "logs" 2026-09-10 21:15:54.451334 | controller | ok: All items complete 2026-09-10 21:15:54.451424 | 2026-09-10 21:15:54.679929 | controller | ok: "artifacts" 2026-09-10 21:15:54.933152 | controller | ok: "docs" 2026-09-10 21:15:54.997389 | 2026-09-10 21:15:54.997577 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-10 21:15:55.270610 | controller | changed: "logs" 2026-09-10 21:15:55.499583 | controller | changed: "artifacts" 2026-09-10 21:15:55.719898 | controller | changed: "docs" 2026-09-10 21:15:55.731905 | 2026-09-10 21:15:55.731978 | PLAY RECAP 2026-09-10 21:15:55.732035 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-10 21:15:55.732068 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-10 21:15:55.732091 | 2026-09-10 21:15:55.916458 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-10 21:15:55.919289 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-10 21:15:56.439642 | 2026-09-10 21:15:56.439756 | PLAY [all] 2026-09-10 21:15:56.448841 | 2026-09-10 21:15:56.448914 | TASK [setup-uv : Extract archive] 2026-09-10 21:15:59.399289 | controller | changed 2026-09-10 21:15:59.409841 | 2026-09-10 21:15:59.410013 | TASK [setup-uv : Print version] 2026-09-10 21:15:59.444810 | [controller] Waiting on logger 2026-09-10 21:15:59.959530 | controller | ok: Runtime: 0:00:00.018556 2026-09-10 21:15:59.975262 | 2026-09-10 21:15:59.975430 | TASK [Install Ansible collection siblings] 2026-09-10 21:16:00.422431 | controller | ok: 2026-09-10 21:16:00.422605 | controller | Skipping ansible.posix: no checked-out sibling 2026-09-10 21:16:00.422642 | controller | Skipping community.crypto: no checked-out sibling 2026-09-10 21:16:00.422669 | controller | Skipping community.general: no checked-out sibling 2026-09-10 21:16:00.422695 | controller | Skipping kubernetes.core: no checked-out sibling 2026-09-10 21:16:00.422720 | controller | Skipping vexxhost.containers: no checked-out sibling 2026-09-10 21:16:00.425497 | 2026-09-10 21:16:00.425570 | PLAY RECAP 2026-09-10 21:16:00.425643 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-10 21:16:00.425674 | 2026-09-10 21:16:00.540768 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-10 21:16:00.542226 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-09-10 21:16:01.159110 | 2026-09-10 21:16:01.159237 | PLAY [all] 2026-09-10 21:16:01.169392 | 2026-09-10 21:16:01.169465 | TASK [Update apt cache] 2026-09-10 21:16:01.193216 | controller | skipping: Conditional result was False 2026-09-10 21:16:01.208897 | 2026-09-10 21:16:01.209012 | TASK [Disable firewalld] 2026-09-10 21:16:02.360570 | controller | changed 2026-09-10 21:16:02.402547 | 2026-09-10 21:16:02.402696 | TASK [Install "jq" for log collection] 2026-09-10 21:16:44.730999 | controller | ok: Nothing to do 2026-09-10 21:16:44.734676 | 2026-09-10 21:16:44.734791 | PLAY RECAP 2026-09-10 21:16:44.734911 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-10 21:16:44.734974 | 2026-09-10 21:16:44.907515 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-09-10 21:16:44.910415 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-10 21:16:45.494418 | 2026-09-10 21:16:45.494526 | PLAY [all] 2026-09-10 21:16:45.503725 | 2026-09-10 21:16:45.503804 | LOOP [Check inventory directories] 2026-09-10 21:16:45.917437 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes" 2026-09-10 21:16:46.142727 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/aio/inventory" 2026-09-10 21:16:46.363985 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/aio/inventory" 2026-09-10 21:16:46.379969 | 2026-09-10 21:16:46.380607 | LOOP [Copy inventory file for Zuul] 2026-09-10 21:16:47.711920 | controller | changed: 2026-09-10 21:16:47.712187 | controller | { 2026-09-10 21:16:47.712240 | controller | "ansible_loop_var": "item", 2026-09-10 21:16:47.712283 | controller | "changed": false, 2026-09-10 21:16:47.712319 | controller | "failed": false, 2026-09-10 21:16:47.712388 | controller | "invocation": { 2026-09-10 21:16:47.712433 | controller | "module_args": { 2026-09-10 21:16:47.712468 | controller | "checksum_algorithm": "sha1", 2026-09-10 21:16:47.712532 | controller | "follow": true, 2026-09-10 21:16:47.712568 | controller | "get_attributes": true, 2026-09-10 21:16:47.712604 | controller | "get_checksum": true, 2026-09-10 21:16:47.712638 | controller | "get_mime": true, 2026-09-10 21:16:47.712671 | controller | "path": "src/github.com/vexxhost/ansible-collection-kubernetes" 2026-09-10 21:16:47.712704 | controller | } 2026-09-10 21:16:47.712737 | controller | }, 2026-09-10 21:16:47.712769 | controller | "item": "src/github.com/vexxhost/ansible-collection-kubernetes", 2026-09-10 21:16:47.712802 | controller | "stat": { 2026-09-10 21:16:47.712836 | controller | "atime": 1789074953.616, 2026-09-10 21:16:47.712870 | controller | "attr_flags": "e", 2026-09-10 21:16:47.712902 | controller | "attributes": [ 2026-09-10 21:16:47.712935 | controller | "extents" 2026-09-10 21:16:47.712968 | controller | ], 2026-09-10 21:16:47.713001 | controller | "block_size": 4096, 2026-09-10 21:16:47.713033 | controller | "blocks": 8, 2026-09-10 21:16:47.713067 | controller | "charset": "binary", 2026-09-10 21:16:47.713103 | controller | "ctime": 1789074953.664, 2026-09-10 21:16:47.713136 | controller | "dev": 64513, 2026-09-10 21:16:47.713170 | controller | "device_type": 0, 2026-09-10 21:16:47.713203 | controller | "executable": true, 2026-09-10 21:16:47.713235 | controller | "exists": true, 2026-09-10 21:16:47.713267 | controller | "gid": 1000, 2026-09-10 21:16:47.713301 | controller | "gr_name": "zuul", 2026-09-10 21:16:47.713350 | controller | "inode": 525401, 2026-09-10 21:16:47.713385 | controller | "isblk": false, 2026-09-10 21:16:47.713419 | controller | "ischr": false, 2026-09-10 21:16:47.713450 | controller | "isdir": true, 2026-09-10 21:16:47.713482 | controller | "isfifo": false, 2026-09-10 21:16:47.713514 | controller | "isgid": false, 2026-09-10 21:16:47.713544 | controller | "islnk": false, 2026-09-10 21:16:47.713576 | controller | "isreg": false, 2026-09-10 21:16:47.713607 | controller | "issock": false, 2026-09-10 21:16:47.713638 | controller | "isuid": false, 2026-09-10 21:16:47.713669 | controller | "mimetype": "inode/directory", 2026-09-10 21:16:47.713699 | controller | "mode": "0755", 2026-09-10 21:16:47.713730 | controller | "mtime": 1789074935.020464, 2026-09-10 21:16:47.713761 | controller | "nlink": 14, 2026-09-10 21:16:47.713793 | controller | "path": "src/github.com/vexxhost/ansible-collection-kubernetes", 2026-09-10 21:16:47.713824 | controller | "pw_name": "zuul", 2026-09-10 21:16:47.713857 | controller | "readable": true, 2026-09-10 21:16:47.713888 | controller | "rgrp": true, 2026-09-10 21:16:47.713921 | controller | "roth": true, 2026-09-10 21:16:47.713953 | controller | "rusr": true, 2026-09-10 21:16:47.713986 | controller | "size": 4096, 2026-09-10 21:16:47.714018 | controller | "uid": 1000, 2026-09-10 21:16:47.714050 | controller | "version": "4028163674", 2026-09-10 21:16:47.714083 | controller | "wgrp": false, 2026-09-10 21:16:47.714119 | controller | "woth": false, 2026-09-10 21:16:47.714152 | controller | "writeable": true, 2026-09-10 21:16:47.714184 | controller | "wusr": true, 2026-09-10 21:16:47.714217 | controller | "xgrp": true, 2026-09-10 21:16:47.714249 | controller | "xoth": true, 2026-09-10 21:16:47.714280 | controller | "xusr": true 2026-09-10 21:16:47.714324 | controller | } 2026-09-10 21:16:47.714376 | controller | } 2026-09-10 21:16:47.714442 | controller | ok: All items complete 2026-09-10 21:16:47.714481 | 2026-09-10 21:16:47.716863 | controller | skipping: Conditional result was False 2026-09-10 21:16:47.719252 | controller | skipping: Conditional result was False 2026-09-10 21:16:47.746928 | 2026-09-10 21:16:47.747413 | LOOP [Switch "ansible_host" to private IP] 2026-09-10 21:16:48.167439 | controller | changed: 1 replacements made 2026-09-10 21:16:48.169750 | controller | skipping: Conditional result was False 2026-09-10 21:16:48.174093 | controller | skipping: Conditional result was False 2026-09-10 21:16:48.195723 | 2026-09-10 21:16:48.195960 | TASK [Run Molecule scenario] 2026-09-10 21:16:48.558409 | controller | Downloading cpython-3.13.7-linux-x86_64-gnu (download) (30.9MiB) 2026-09-10 21:16:49.423336 | controller | Downloading cpython-3.13.7-linux-x86_64-gnu (download) 2026-09-10 21:16:49.625917 | controller | Using CPython 3.13.7 2026-09-10 21:16:49.625987 | controller | Creating virtual environment at: .venv 2026-09-10 21:16:50.847779 | controller | Downloading pygments (1.2MiB) 2026-09-10 21:16:50.853799 | controller | Downloading cryptography (4.3MiB) 2026-09-10 21:16:50.870488 | controller | Downloading ansible-core (2.3MiB) 2026-09-10 21:16:51.109520 | controller | Downloading cryptography 2026-09-10 21:16:51.156029 | controller | Downloading pygments 2026-09-10 21:16:51.307293 | controller | Downloading ansible-core 2026-09-10 21:16:51.356833 | controller | Installed 36 packages in 48ms 2026-09-10 21:16:52.466119 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-09-10 21:16:52.466269 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-09-10 21:16:53.312627 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-09-10 21:16:53.312697 | controller | INFO default scenario not found, disabling shared state. 2026-09-10 21:16:53.312780 | controller | INFO [aio > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-10 21:16:53.312829 | controller | INFO [aio > prerun] Performing prerun with role_name_check=0... 2026-09-10 21:17:57.168519 | controller | WARNING Retrying execution failure 250 of: ansible-galaxy collection install -vvv ansible.posix:>=1.6.0 2026-09-10 21:17:57.169141 | controller | ERROR Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 250 code: 2026-09-10 21:17:57.169179 | controller | Starting galaxy collection install process 2026-09-10 21:17:57.169188 | controller | Process install dependency map 2026-09-10 21:17:57.169195 | controller | 2026-09-10 21:17:57.169200 | controller | ansible-galaxy 2026-09-10 21:17:57.169205 | controller | config file = None 2026-09-10 21:17:57.169210 | controller | configured module search path = 2026-09-10 21:17:57.169216 | 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:17:57.169224 | 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/site-packages 2026-09-10 21:17:57.169235 | controller | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy 2026-09-10 21:17:57.169241 | controller | python version = 3.13.7 (main, Aug 18 2025, 19:20:03) (/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/python) 2026-09-10 21:17:57.169246 | controller | jinja version = 3.1.6 2026-09-10 21:17:57.169251 | controller | pyyaml version = 6.0.3 (with libyaml v0.2.5) 2026-09-10 21:17:57.169256 | controller | No config file found; using defaults 2026-09-10 21:17:57.169262 | controller | Created /home/zuul/.ansible/galaxy_token 2026-09-10 21:17:57.169267 | controller | : Skipping Galaxy server https://galaxy.ansible.com. Got an unexpected error when getting available versions of collection ansible.posix: 'results' 2026-09-10 21:17:57.169273 | controller | : Unexpected Exception, this is probably a bug: 'results' 2026-09-10 21:17:57.169279 | controller | 2026-09-10 21:17:57.169284 | controller | Traceback (most recent call last): 2026-09-10 21:17:57.169289 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/__init__.py", line 661, in cli_executor 2026-09-10 21:17:57.169295 | controller | exit_code = cli.run() 2026-09-10 21:17:57.169300 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 723, in run 2026-09-10 21:17:57.169305 | controller | return context.CLIARGS() 2026-09-10 21:17:57.169310 | controller | ~~~~~~~~~~~~~~~~~~~~~~~^^ 2026-09-10 21:17:57.169316 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 98, in method_wrapper 2026-09-10 21:17:57.169321 | controller | return wrapped_method(*args, **kwargs) 2026-09-10 21:17:57.169326 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 1388, in execute_install 2026-09-10 21:17:57.169352 | controller | self._execute_install_collection( 2026-09-10 21:17:57.169358 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-10 21:17:57.169363 | controller | collection_requirements, collection_path, 2026-09-10 21:17:57.169368 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:17:57.169373 | controller | artifacts_manager=artifacts_manager, 2026-09-10 21:17:57.169378 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:17:57.169383 | controller | ) 2026-09-10 21:17:57.169388 | controller | ^ 2026-09-10 21:17:57.169393 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 1437, in _execute_install_collection 2026-09-10 21:17:57.169399 | controller | install_collections( 2026-09-10 21:17:57.169404 | controller | ~~~~~~~~~~~~~~~~~~~^ 2026-09-10 21:17:57.169409 | controller | requirements, output_path, self.api_servers, ignore_errors, 2026-09-10 21:17:57.169414 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:17:57.169419 | controller | ...<5 lines>... 2026-09-10 21:17:57.169425 | controller | read_requirement_paths=read_req_paths, 2026-09-10 21:17:57.169430 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:17:57.169435 | controller | ) 2026-09-10 21:17:57.169440 | controller | ^ 2026-09-10 21:17:57.169446 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/__init__.py", line 722, in install_collections 2026-09-10 21:17:57.169451 | controller | dependency_map = _resolve_depenency_map( 2026-09-10 21:17:57.169456 | controller | collections, 2026-09-10 21:17:57.169461 | controller | ...<7 lines>... 2026-09-10 21:17:57.169466 | controller | offline=offline, 2026-09-10 21:17:57.169471 | controller | ) 2026-09-10 21:17:57.169476 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/__init__.py", line 1844, in _resolve_depenency_map 2026-09-10 21:17:57.169482 | controller | collection_dep_resolver.resolve( 2026-09-10 21:17:57.169487 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-10 21:17:57.169492 | controller | requested_requirements, 2026-09-10 21:17:57.169497 | controller | ^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:17:57.169502 | controller | max_rounds=2000000, # NOTE: same constant pip uses 2026-09-10 21:17:57.169507 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:17:57.169512 | controller | ).mapping, 2026-09-10 21:17:57.169517 | controller | ^ 2026-09-10 21:17:57.169522 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 601, in resolve 2026-09-10 21:17:57.169528 | controller | state = resolution.resolve(requirements, max_rounds=max_rounds) 2026-09-10 21:17:57.169536 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 434, in resolve 2026-09-10 21:17:57.169541 | controller | self._add_to_criteria(self.state.criteria, r, parent=None) 2026-09-10 21:17:57.169546 | controller | ~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:17:57.169556 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 125, in _add_to_criteria 2026-09-10 21:17:57.169561 | controller | matches = self._p.find_matches( 2026-09-10 21:17:57.169566 | controller | identifier=identifier, 2026-09-10 21:17:57.169571 | controller | ...<9 lines>... 2026-09-10 21:17:57.169576 | controller | ), 2026-09-10 21:17:57.169581 | controller | ) 2026-09-10 21:17:57.169587 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 192, in find_matches 2026-09-10 21:17:57.169592 | controller | match for match in self._find_matches(list(requirements)) 2026-09-10 21:17:57.169597 | controller | ~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:17:57.169608 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 219, in _find_matches 2026-09-10 21:17:57.169613 | controller | else self._api_proxy.get_collection_versions(first_req) 2026-09-10 21:17:57.169618 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^ 2026-09-10 21:17:57.169623 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 103, in get_collection_versions 2026-09-10 21:17:57.169629 | controller | return set( 2026-09-10 21:17:57.169634 | controller | (version, api) 2026-09-10 21:17:57.169639 | controller | ...<2 lines>... 2026-09-10 21:17:57.169644 | controller | ) 2026-09-10 21:17:57.169662 | controller | ) 2026-09-10 21:17:57.169668 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 105, in 2026-09-10 21:17:57.169673 | controller | for api, version in self._get_collection_versions( 2026-09-10 21:17:57.169679 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-10 21:17:57.169686 | controller | requirement, 2026-09-10 21:17:57.169691 | controller | ^^^^^^^^^^^^ 2026-09-10 21:17:57.169697 | controller | ) 2026-09-10 21:17:57.169703 | controller | ^ 2026-09-10 21:17:57.169708 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 85, in _get_collection_versions 2026-09-10 21:17:57.169713 | controller | raise last_error 2026-09-10 21:17:57.169718 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 64, in _get_collection_versions 2026-09-10 21:17:57.169723 | controller | versions = api.get_collection_versions(requirement.namespace, requirement.name) 2026-09-10 21:17:57.169729 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 92, in wrapped 2026-09-10 21:17:57.169734 | controller | data = self._call_galaxy(n_url, method='GET', error_context_msg=error_context_msg, cache=True) 2026-09-10 21:17:57.169739 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/module_utils/api.py", line 168, in run_function 2026-09-10 21:17:57.169744 | controller | return call_retryable_function() 2026-09-10 21:17:57.169749 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 366, in _call_galaxy 2026-09-10 21:17:57.169754 | controller | res = path_cache 2026-09-10 21:17:57.169759 | controller | ~~~~~~~~~~^^^^^^^^^^^ 2026-09-10 21:17:57.169764 | controller | KeyError: 'results' 2026-09-10 21:17:57.169769 | controller | 2026-09-10 21:17:57.169774 | controller | The above exception was the direct cause of the following exception: 2026-09-10 21:17:57.169779 | controller | 2026-09-10 21:17:57.169784 | controller | Traceback (most recent call last): 2026-09-10 21:17:57.169789 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/__init__.py", line 670, in cli_executor 2026-09-10 21:17:57.169795 | controller | raise AnsibleError("Unexpected Exception, this is probably a bug.") from ex 2026-09-10 21:17:57.169800 | controller | ansible.errors.AnsibleError: Unexpected Exception, this is probably a bug: 'results' 2026-09-10 21:17:57.169805 | controller | 2026-09-10 21:17:57.169810 | controller | 2026-09-10 21:17:57.170279 | controller | Traceback (most recent call last): 2026-09-10 21:17:57.172016 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/molecule", line 10, in 2026-09-10 21:17:57.172036 | controller | sys.exit(main()) 2026-09-10 21:17:57.172047 | controller | ~~~~^^ 2026-09-10 21:17:57.172146 | 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:17:57.172190 | controller | return self.main(*args, **kwargs) 2026-09-10 21:17:57.172205 | controller | ~~~~~~~~~^^^^^^^^^^^^^^^^^ 2026-09-10 21:17:57.172276 | 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:17:57.172290 | controller | rv = self.invoke(ctx) 2026-09-10 21:17:57.172403 | 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:17:57.172416 | controller | return _process_result(sub_ctx.command.invoke(sub_ctx)) 2026-09-10 21:17:57.172428 | controller | ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^ 2026-09-10 21:17:57.172508 | 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:17:57.172529 | controller | return ctx.invoke(self.callback, **ctx.params) 2026-09-10 21:17:57.172541 | controller | ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:17:57.172610 | 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:17:57.172622 | controller | return callback(*args, **kwargs) 2026-09-10 21:17:57.172746 | 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:17:57.172761 | controller | return func(*args, **kwargs) 2026-09-10 21:17:57.172832 | 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:17:57.172844 | controller | return f(get_current_context(), *args, **kwargs) 2026-09-10 21:17:57.172929 | 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:17:57.172941 | controller | return func(ctx) 2026-09-10 21:17:57.173046 | 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:17:57.173058 | controller | base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude) 2026-09-10 21:17:57.173070 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:17:57.173176 | 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:17:57.173192 | controller | _run_scenarios(scenarios, command_args, default_config) 2026-09-10 21:17:57.173203 | controller | ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:17:57.173393 | 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:17:57.173407 | controller | scenario.config.runtime.prepare_environment( 2026-09-10 21:17:57.173418 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-10 21:17:57.173430 | controller | install_local=True, 2026-09-10 21:17:57.173441 | controller | ^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:17:57.173452 | controller | role_name_check=role_name_check, 2026-09-10 21:17:57.173464 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:17:57.173475 | controller | ) 2026-09-10 21:17:57.173486 | controller | ^ 2026-09-10 21:17:57.173568 | 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:17:57.173580 | controller | self.install_collection( 2026-09-10 21:17:57.173591 | controller | ~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-10 21:17:57.173603 | controller | f"{name}{',' if is_url(name) else ':'}{required_version}", 2026-09-10 21:17:57.173614 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:17:57.173625 | controller | destination=destination, 2026-09-10 21:17:57.174222 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:17:57.174239 | controller | ) 2026-09-10 21:17:57.174251 | controller | ^ 2026-09-10 21:17:57.174268 | 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:17:57.174280 | controller | raise InvalidPrerequisiteError(msg) 2026-09-10 21:17:57.175444 | controller | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 250 code: 2026-09-10 21:17:57.175464 | controller | Starting galaxy collection install process 2026-09-10 21:17:57.175477 | controller | Process install dependency map 2026-09-10 21:17:57.175489 | controller | 2026-09-10 21:17:57.175501 | controller | ansible-galaxy [core 2.20.1] 2026-09-10 21:17:57.175512 | controller | config file = None 2026-09-10 21:17:57.175524 | 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:17:57.175536 | 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:17:57.175548 | 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/site-packages 2026-09-10 21:17:57.175560 | controller | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy 2026-09-10 21:17:57.175571 | controller | python version = 3.13.7 (main, Aug 18 2025, 19:20:03) [Clang 20.1.4 ] (/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/python) 2026-09-10 21:17:57.175583 | controller | jinja version = 3.1.6 2026-09-10 21:17:57.175595 | controller | pyyaml version = 6.0.3 (with libyaml v0.2.5) 2026-09-10 21:17:57.175606 | controller | No config file found; using defaults 2026-09-10 21:17:57.175618 | controller | Created /home/zuul/.ansible/galaxy_token 2026-09-10 21:17:57.175629 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com. Got an unexpected error when getting available versions of collection ansible.posix: 'results' 2026-09-10 21:17:57.175641 | controller | [ERROR]: Unexpected Exception, this is probably a bug: 'results' 2026-09-10 21:17:57.175669 | controller | 2026-09-10 21:17:57.175682 | controller | Traceback (most recent call last): 2026-09-10 21:17:57.175694 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/__init__.py", line 661, in cli_executor 2026-09-10 21:17:57.175707 | controller | exit_code = cli.run() 2026-09-10 21:17:57.175726 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 723, in run 2026-09-10 21:17:57.175738 | controller | return context.CLIARGS['func']() 2026-09-10 21:17:57.175750 | controller | ~~~~~~~~~~~~~~~~~~~~~~~^^ 2026-09-10 21:17:57.175761 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 98, in method_wrapper 2026-09-10 21:17:57.175773 | controller | return wrapped_method(*args, **kwargs) 2026-09-10 21:17:57.175784 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 1388, in execute_install 2026-09-10 21:17:57.175796 | controller | self._execute_install_collection( 2026-09-10 21:17:57.175807 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-10 21:17:57.175818 | controller | collection_requirements, collection_path, 2026-09-10 21:17:57.175830 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:17:57.175841 | controller | artifacts_manager=artifacts_manager, 2026-09-10 21:17:57.175852 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:17:57.175864 | controller | ) 2026-09-10 21:17:57.175875 | controller | ^ 2026-09-10 21:17:57.175897 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 1437, in _execute_install_collection 2026-09-10 21:17:57.175921 | controller | install_collections( 2026-09-10 21:17:57.175932 | controller | ~~~~~~~~~~~~~~~~~~~^ 2026-09-10 21:17:57.175944 | controller | requirements, output_path, self.api_servers, ignore_errors, 2026-09-10 21:17:57.175955 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:17:57.175967 | controller | ...<5 lines>... 2026-09-10 21:17:57.175978 | controller | read_requirement_paths=read_req_paths, 2026-09-10 21:17:57.175990 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:17:57.176001 | controller | ) 2026-09-10 21:17:57.176013 | controller | ^ 2026-09-10 21:17:57.176024 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/__init__.py", line 722, in install_collections 2026-09-10 21:17:57.176036 | controller | dependency_map = _resolve_depenency_map( 2026-09-10 21:17:57.176047 | controller | collections, 2026-09-10 21:17:57.176059 | controller | ...<7 lines>... 2026-09-10 21:17:57.176070 | controller | offline=offline, 2026-09-10 21:17:57.176081 | controller | ) 2026-09-10 21:17:57.176093 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/__init__.py", line 1844, in _resolve_depenency_map 2026-09-10 21:17:57.176105 | controller | collection_dep_resolver.resolve( 2026-09-10 21:17:57.176116 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-10 21:17:57.176128 | controller | requested_requirements, 2026-09-10 21:17:57.176139 | controller | ^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:17:57.176150 | controller | max_rounds=2000000, # NOTE: same constant pip uses 2026-09-10 21:17:57.176180 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:17:57.176195 | controller | ).mapping, 2026-09-10 21:17:57.176206 | controller | ^ 2026-09-10 21:17:57.176218 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 601, in resolve 2026-09-10 21:17:57.176229 | controller | state = resolution.resolve(requirements, max_rounds=max_rounds) 2026-09-10 21:17:57.176250 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 434, in resolve 2026-09-10 21:17:57.176261 | controller | self._add_to_criteria(self.state.criteria, r, parent=None) 2026-09-10 21:17:57.176273 | controller | ~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:17:57.176284 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 125, in _add_to_criteria 2026-09-10 21:17:57.176296 | controller | matches = self._p.find_matches( 2026-09-10 21:17:57.176307 | controller | identifier=identifier, 2026-09-10 21:17:57.176319 | controller | ...<9 lines>... 2026-09-10 21:17:57.176330 | controller | ), 2026-09-10 21:17:57.176341 | controller | ) 2026-09-10 21:17:57.176353 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 192, in find_matches 2026-09-10 21:17:57.176364 | controller | match for match in self._find_matches(list(requirements[identifier])) 2026-09-10 21:17:57.176376 | controller | ~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-10 21:17:57.176387 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 219, in _find_matches 2026-09-10 21:17:57.176398 | controller | else self._api_proxy.get_collection_versions(first_req) 2026-09-10 21:17:57.176410 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^ 2026-09-10 21:17:57.176421 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 103, in get_collection_versions 2026-09-10 21:17:57.176433 | controller | return set( 2026-09-10 21:17:57.176450 | controller | (version, api) 2026-09-10 21:17:57.176473 | controller | ...<2 lines>... 2026-09-10 21:17:57.176484 | controller | ) 2026-09-10 21:17:57.176496 | controller | ) 2026-09-10 21:17:57.176507 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 105, in 2026-09-10 21:17:57.176519 | controller | for api, version in self._get_collection_versions( 2026-09-10 21:17:57.176530 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-10 21:17:57.176541 | controller | requirement, 2026-09-10 21:17:57.176553 | controller | ^^^^^^^^^^^^ 2026-09-10 21:17:57.176564 | controller | ) 2026-09-10 21:17:57.176575 | controller | ^ 2026-09-10 21:17:57.176587 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 85, in _get_collection_versions 2026-09-10 21:17:57.176598 | controller | raise last_error 2026-09-10 21:17:57.176610 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 64, in _get_collection_versions 2026-09-10 21:17:57.176621 | controller | versions = api.get_collection_versions(requirement.namespace, requirement.name) 2026-09-10 21:17:57.176632 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 92, in wrapped 2026-09-10 21:17:57.176644 | controller | data = self._call_galaxy(n_url, method='GET', error_context_msg=error_context_msg, cache=True) 2026-09-10 21:17:57.176675 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/module_utils/api.py", line 168, in run_function 2026-09-10 21:17:57.176686 | controller | return call_retryable_function() 2026-09-10 21:17:57.176698 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 366, in _call_galaxy 2026-09-10 21:17:57.176709 | controller | res = path_cache['results'] 2026-09-10 21:17:57.176720 | controller | ~~~~~~~~~~^^^^^^^^^^^ 2026-09-10 21:17:57.176732 | controller | KeyError: 'results' 2026-09-10 21:17:57.176743 | controller | 2026-09-10 21:17:57.176754 | controller | The above exception was the direct cause of the following exception: 2026-09-10 21:17:57.176766 | controller | 2026-09-10 21:17:57.176777 | controller | Traceback (most recent call last): 2026-09-10 21:17:57.176789 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/__init__.py", line 670, in cli_executor 2026-09-10 21:17:57.176800 | controller | raise AnsibleError("Unexpected Exception, this is probably a bug.") from ex 2026-09-10 21:17:57.176812 | controller | ansible.errors.AnsibleError: Unexpected Exception, this is probably a bug: 'results' 2026-09-10 21:17:57.176823 | controller | 2026-09-10 21:17:57.176834 | controller | 2026-09-10 21:17:57.856689 | controller | ERROR 2026-09-10 21:17:57.857032 | controller | { 2026-09-10 21:17:57.857096 | controller | "delta": "0:01:08.977051", 2026-09-10 21:17:57.857139 | controller | "end": "2026-09-10 21:17:57.295761", 2026-09-10 21:17:57.857176 | controller | "msg": "non-zero return code", 2026-09-10 21:17:57.857211 | controller | "rc": 1, 2026-09-10 21:17:57.857245 | controller | "start": "2026-09-10 21:16:48.318710" 2026-09-10 21:17:57.857279 | controller | } failure 2026-09-10 21:17:57.864191 | 2026-09-10 21:17:57.864295 | PLAY RECAP 2026-09-10 21:17:57.864408 | controller | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-10 21:17:57.864450 | 2026-09-10 21:17:58.087970 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-10 21:17:58.090947 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-09-10 21:17:58.760070 | 2026-09-10 21:17:58.760654 | PLAY [all] 2026-09-10 21:17:58.771782 | 2026-09-10 21:17:58.771867 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-09-10 21:17:59.290371 | controller | changed 2026-09-10 21:17:59.299997 | 2026-09-10 21:17:59.300706 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-09-10 21:17:59.475105 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-10 21:17:59.475425 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-10 21:17:59.482573 | controller | environment: line 1: kubectl: command not found 2026-09-10 21:17:59.485323 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-10 21:17:59.485742 | controller | environment: line 1: kubectl: command not found 2026-09-10 21:17:59.486924 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-10 21:17:59.491667 | controller | environment: line 1: kubectl: command not found 2026-09-10 21:17:59.492772 | controller | environment: line 1: kubectl: command not found 2026-09-10 21:17:59.493427 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-10 21:17:59.495214 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-10 21:17:59.497011 | controller | environment: line 1: kubectl: command not found 2026-09-10 21:17:59.498949 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-10 21:18:00.349672 | controller | ok: Runtime: 0:00:00.038747 2026-09-10 21:18:00.358825 | 2026-09-10 21:18:00.358946 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-09-10 21:18:00.699362 | controller | changed 2026-09-10 21:18:00.707796 | 2026-09-10 21:18:00.707923 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-09-10 21:18:00.702443 | controller | environment: line 5: kubectl: command not found 2026-09-10 21:18:00.703934 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-10 21:18:00.704211 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-10 21:18:00.704504 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-10 21:18:01.242410 | controller | ok: Runtime: 0:00:00.017963 2026-09-10 21:18:01.254192 | 2026-09-10 21:18:01.254387 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-09-10 21:18:02.070981 | controller | changed: 2026-09-10 21:18:02.071285 | controller | created directory /var/lib/zuul/builds/4a42280a8b4e480f971d341a1418a823/work/logs/controller 2026-09-10 21:18:02.071364 | controller | cd+++++++++ objects/ 2026-09-10 21:18:02.071411 | controller | cd+++++++++ objects/cluster/ 2026-09-10 21:18:02.071458 | controller | cd+++++++++ objects/namespaced/ 2026-09-10 21:18:02.089428 | 2026-09-10 21:18:02.089616 | TASK [gather-pod-logs : creating directory for pod logs] 2026-09-10 21:18:02.408674 | controller | changed 2026-09-10 21:18:02.421840 | 2026-09-10 21:18:02.422049 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-09-10 21:18:02.741857 | controller | changed 2026-09-10 21:18:02.761677 | 2026-09-10 21:18:02.761989 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-09-10 21:18:02.753680 | controller | environment: line 3: kubectl: command not found 2026-09-10 21:18:03.315509 | controller | ok: Runtime: 0:00:00.013519 2026-09-10 21:18:03.329034 | 2026-09-10 21:18:03.329216 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-09-10 21:18:03.953591 | controller | changed: 2026-09-10 21:18:03.953874 | controller | cd+++++++++ pod-logs/ 2026-09-10 21:18:03.953928 | controller | cd+++++++++ pod-logs/failed-pods/ 2026-09-10 21:18:03.970499 | 2026-09-10 21:18:03.970676 | LOOP [helm-release-status : creating directory for helm release status] 2026-09-10 21:18:04.320690 | controller | changed: "values" 2026-09-10 21:18:04.630386 | controller | changed: "releases" 2026-09-10 21:18:04.652574 | 2026-09-10 21:18:04.652765 | TASK [helm-release-status : Gather get release status for helm charts] 2026-09-10 21:18:04.685111 | controller | /bin/bash: line 3: kubectl: command not found 2026-09-10 21:18:05.202135 | controller | ok: Runtime: 0:00:00.011185 2026-09-10 21:18:05.212212 | 2026-09-10 21:18:05.212400 | TASK [helm-release-status : Downloads logs to executor] 2026-09-10 21:18:05.876513 | controller | changed: 2026-09-10 21:18:05.876815 | controller | cd+++++++++ helm/ 2026-09-10 21:18:05.876870 | controller | cd+++++++++ helm/releases/ 2026-09-10 21:18:05.876918 | controller | cd+++++++++ helm/values/ 2026-09-10 21:18:05.887313 | 2026-09-10 21:18:05.887494 | PLAY RECAP 2026-09-10 21:18:05.887584 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-10 21:18:05.887628 | 2026-09-10 21:18:06.047313 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-09-10 21:18:06.049789 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-10 21:18:06.623859 | 2026-09-10 21:18:06.624020 | PLAY [all] 2026-09-10 21:18:06.634304 | 2026-09-10 21:18:06.634400 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-10 21:18:06.699286 | controller | skipping: Conditional result was False 2026-09-10 21:18:06.705789 | 2026-09-10 21:18:06.705897 | TASK [fetch-output : Set log path for single node] 2026-09-10 21:18:06.758585 | controller | ok 2026-09-10 21:18:06.768818 | 2026-09-10 21:18:06.768960 | LOOP [fetch-output : Ensure local output dirs] 2026-09-10 21:18:07.309239 | controller -> localhost | ok: "/var/lib/zuul/builds/4a42280a8b4e480f971d341a1418a823/work/logs" 2026-09-10 21:18:07.522088 | controller -> localhost | changed: "/var/lib/zuul/builds/4a42280a8b4e480f971d341a1418a823/work/artifacts" 2026-09-10 21:18:07.751674 | controller -> localhost | changed: "/var/lib/zuul/builds/4a42280a8b4e480f971d341a1418a823/work/docs" 2026-09-10 21:18:07.773353 | 2026-09-10 21:18:07.773519 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-10 21:18:08.552958 | controller | changed: .d..t...... ./ 2026-09-10 21:18:08.553432 | controller | changed: All items complete 2026-09-10 21:18:08.553518 | 2026-09-10 21:18:09.110431 | controller | changed: .d..t...... ./ 2026-09-10 21:18:09.707859 | controller | changed: .d..t...... ./ 2026-09-10 21:18:09.733733 | 2026-09-10 21:18:09.734369 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-10 21:18:10.251870 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007170 2026-09-10 21:18:10.498579 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007666 2026-09-10 21:18:10.512263 | 2026-09-10 21:18:10.512403 | PLAY [all] 2026-09-10 21:18:10.518337 | 2026-09-10 21:18:10.518425 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-10 21:18:11.064245 | controller | changed 2026-09-10 21:18:11.074994 | 2026-09-10 21:18:11.075096 | PLAY RECAP 2026-09-10 21:18:11.075184 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-10 21:18:11.075248 | 2026-09-10 21:18:11.247651 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-10 21:18:11.252802 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-10 21:18:11.863485 | 2026-09-10 21:18:11.863660 | PLAY [localhost] 2026-09-10 21:18:11.873718 | 2026-09-10 21:18:11.873806 | TASK [Generate Zuul manifest] 2026-09-10 21:18:11.892075 | localhost | ok 2026-09-10 21:18:11.905555 | 2026-09-10 21:18:11.905661 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-10 21:18:12.338028 | localhost | changed 2026-09-10 21:18:12.350495 | 2026-09-10 21:18:12.350733 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-10 21:18:12.390831 | localhost | ok 2026-09-10 21:18:12.397459 | 2026-09-10 21:18:12.397521 | TASK [Upload logs] 2026-09-10 21:18:12.415686 | localhost | ok 2026-09-10 21:18:12.469975 | 2026-09-10 21:18:12.470102 | TASK [Set zuul-log-path fact] 2026-09-10 21:18:12.488911 | localhost | ok 2026-09-10 21:18:12.498830 | 2026-09-10 21:18:12.498888 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-10 21:18:12.536418 | localhost | ok 2026-09-10 21:18:12.543403 | 2026-09-10 21:18:12.543467 | TASK [upload-logs : Create log directories] 2026-09-10 21:18:12.985098 | localhost | changed 2026-09-10 21:18:12.989465 | 2026-09-10 21:18:12.989528 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-10 21:18:13.456457 | localhost -> localhost | ok: Runtime: 0:00:00.005737 2026-09-10 21:18:13.466220 | 2026-09-10 21:18:13.466368 | TASK [upload-logs : Upload logs to log server] 2026-09-10 21:18:13.944306 | localhost | Output suppressed because no_log was given 2026-09-10 21:18:13.951708 | 2026-09-10 21:18:13.951832 | LOOP [upload-logs : Compress console log and json output] 2026-09-10 21:18:14.002155 | localhost | skipping: Conditional result was False 2026-09-10 21:18:14.056481 | localhost | skipping: Conditional result was False 2026-09-10 21:18:14.068177 | 2026-09-10 21:18:14.068432 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-10 21:18:14.132617 | localhost | skipping: Conditional result was False 2026-09-10 21:18:14.133117 | 2026-09-10 21:18:14.138199 | localhost | skipping: Conditional result was False 2026-09-10 21:18:14.157258 | 2026-09-10 21:18:14.157493 | LOOP [upload-logs : Upload console log and json output]