2026-10-21 11:27:13.509052 | Job console starting 2026-10-21 11:27:13.526847 | Updating git repos 2026-10-21 11:27:13.585129 | Cloning repos into workspace 2026-10-21 11:27:13.799386 | Restoring repo states 2026-10-21 11:27:13.818089 | Merging changes 2026-10-21 11:27:14.397832 | Checking out repos 2026-10-21 11:27:14.441168 | Preparing playbooks 2026-10-21 11:27:17.467848 | Running Ansible setup 2026-10-21 11:27:21.124326 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-10-21 11:27:21.696423 | 2026-10-21 11:27:21.697461 | PLAY [localhost] 2026-10-21 11:27:21.704941 | 2026-10-21 11:27:21.705009 | TASK [Gathering Facts] 2026-10-21 11:27:22.584876 | localhost | ok 2026-10-21 11:27:22.597146 | 2026-10-21 11:27:22.597293 | TASK [Setup log path fact] 2026-10-21 11:27:22.617479 | localhost | ok 2026-10-21 11:27:22.637350 | 2026-10-21 11:27:22.637511 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-10-21 11:27:22.668300 | localhost | ok 2026-10-21 11:27:22.680020 | 2026-10-21 11:27:22.680208 | TASK [emit-job-header : Print job information] 2026-10-21 11:27:22.730280 | # Job Information 2026-10-21 11:27:22.730515 | Ansible Version: 2.16.18 2026-10-21 11:27:22.730577 | Job: ansible-collection-kubernetes-molecule-aio-debian-trixie 2026-10-21 11:27:22.730621 | Pipeline: check 2026-10-21 11:27:22.730660 | Executor: 2d72f0692154 2026-10-21 11:27:22.730697 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/302 2026-10-21 11:27:22.730740 | Event ID: d0b60882-cd41-11f1-84be-365f486686b8 2026-10-21 11:27:22.736046 | 2026-10-21 11:27:22.736198 | LOOP [emit-job-header : Print node information] 2026-10-21 11:27:22.824571 | localhost | ok: 2026-10-21 11:27:22.824808 | localhost | # Node Information 2026-10-21 11:27:22.824873 | localhost | Inventory Hostname: controller 2026-10-21 11:27:22.824941 | localhost | Hostname: np0000196235 2026-10-21 11:27:22.824989 | localhost | Username: zuul 2026-10-21 11:27:22.825041 | localhost | Distro: Debian 13.7 2026-10-21 11:27:22.825087 | localhost | Provider: yul1 2026-10-21 11:27:22.825131 | localhost | Region: ca-ymq-1 2026-10-21 11:27:22.825174 | localhost | Label: debian-trixie 2026-10-21 11:27:22.825216 | localhost | Product Name: OpenStack Nova 2026-10-21 11:27:22.825258 | localhost | Interface IP: 162.253.55.36 2026-10-21 11:27:22.837171 | 2026-10-21 11:27:22.837347 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-10-21 11:27:23.227666 | localhost -> localhost | changed 2026-10-21 11:27:23.236759 | 2026-10-21 11:27:23.236893 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-10-21 11:27:24.104630 | localhost -> localhost | changed 2026-10-21 11:27:24.112229 | 2026-10-21 11:27:24.112350 | PLAY [all] 2026-10-21 11:27:24.122685 | 2026-10-21 11:27:24.123369 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-10-21 11:27:24.423068 | controller -> localhost | ok 2026-10-21 11:27:24.432417 | 2026-10-21 11:27:24.432778 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-10-21 11:27:24.463310 | controller | ok 2026-10-21 11:27:24.480373 | controller | included: /var/lib/zuul/builds/e4e196074c9d4b2cb1864105020d898a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-10-21 11:27:24.486316 | 2026-10-21 11:27:24.486415 | TASK [add-build-sshkey : Create Temp SSH key] 2026-10-21 11:27:25.519195 | controller -> localhost | Generating public/private rsa key pair. 2026-10-21 11:27:25.519448 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/e4e196074c9d4b2cb1864105020d898a/work/e4e196074c9d4b2cb1864105020d898a_id_rsa 2026-10-21 11:27:25.519511 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/e4e196074c9d4b2cb1864105020d898a/work/e4e196074c9d4b2cb1864105020d898a_id_rsa.pub 2026-10-21 11:27:25.519559 | controller -> localhost | The key fingerprint is: 2026-10-21 11:27:25.519604 | controller -> localhost | SHA256:SvFetIoQ1LE3mQIeB4olJBY6VQyh5ZL6+s0C6qOQnUk zuul-build-sshkey 2026-10-21 11:27:25.519666 | controller -> localhost | The key's randomart image is: 2026-10-21 11:27:25.519711 | controller -> localhost | +---[RSA 3072]----+ 2026-10-21 11:27:25.519767 | controller -> localhost | |++B=*oo. | 2026-10-21 11:27:25.519814 | controller -> localhost | |+X +.+.. o | 2026-10-21 11:27:25.519857 | controller -> localhost | |B o o + = . | 2026-10-21 11:27:25.519900 | controller -> localhost | |.o . = o . | 2026-10-21 11:27:25.519965 | controller -> localhost | |. E . . S o | 2026-10-21 11:27:25.520008 | controller -> localhost | |.= o o + o | 2026-10-21 11:27:25.520049 | controller -> localhost | |+.= o o | 2026-10-21 11:27:25.520090 | controller -> localhost | |oo.o | 2026-10-21 11:27:25.520138 | controller -> localhost | |=oo.o | 2026-10-21 11:27:25.520180 | controller -> localhost | +----[SHA256]-----+ 2026-10-21 11:27:25.520282 | controller -> localhost | ok: Runtime: 0:00:00.591076 2026-10-21 11:27:25.528802 | 2026-10-21 11:27:25.528959 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-10-21 11:27:25.560284 | controller | ok 2026-10-21 11:27:25.574107 | controller | included: /var/lib/zuul/builds/e4e196074c9d4b2cb1864105020d898a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-10-21 11:27:25.584090 | 2026-10-21 11:27:25.584190 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-10-21 11:27:25.608050 | controller | skipping: Conditional result was False 2026-10-21 11:27:25.616048 | 2026-10-21 11:27:25.616181 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-10-21 11:27:26.150084 | controller | changed 2026-10-21 11:27:26.153445 | 2026-10-21 11:27:26.153505 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-10-21 11:27:26.396206 | controller | ok 2026-10-21 11:27:26.403738 | 2026-10-21 11:27:26.403862 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-10-21 11:27:26.962687 | controller | changed 2026-10-21 11:27:26.970891 | 2026-10-21 11:27:26.971041 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-10-21 11:27:27.539462 | controller | changed 2026-10-21 11:27:27.546893 | 2026-10-21 11:27:27.547040 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-10-21 11:27:27.572800 | controller | skipping: Conditional result was False 2026-10-21 11:27:27.578535 | 2026-10-21 11:27:27.578622 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-10-21 11:27:27.986291 | controller -> localhost | changed 2026-10-21 11:27:28.001569 | 2026-10-21 11:27:28.001725 | TASK [add-build-sshkey : Add back temp key] 2026-10-21 11:27:28.328575 | controller -> localhost | Identity added: /var/lib/zuul/builds/e4e196074c9d4b2cb1864105020d898a/work/e4e196074c9d4b2cb1864105020d898a_id_rsa (zuul-build-sshkey) 2026-10-21 11:27:28.328872 | controller -> localhost | ok: Runtime: 0:00:00.015550 2026-10-21 11:27:28.338575 | 2026-10-21 11:27:28.338801 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-10-21 11:27:28.685040 | controller | ok 2026-10-21 11:27:28.692462 | 2026-10-21 11:27:28.692631 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-10-21 11:27:28.728047 | controller | skipping: Conditional result was False 2026-10-21 11:27:28.742246 | 2026-10-21 11:27:28.742387 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-10-21 11:27:29.136842 | controller | ok 2026-10-21 11:27:29.142893 | 2026-10-21 11:27:29.142987 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-10-21 11:27:30.499753 | controller | Output suppressed because no_log was given 2026-10-21 11:27:30.513598 | 2026-10-21 11:27:30.513720 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-10-21 11:27:30.760769 | controller | ok: "logs" 2026-10-21 11:27:30.761252 | controller | ok: All items complete 2026-10-21 11:27:30.761555 | 2026-10-21 11:27:30.956469 | controller | ok: "artifacts" 2026-10-21 11:27:31.128771 | controller | ok: "docs" 2026-10-21 11:27:31.141892 | 2026-10-21 11:27:31.142129 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-10-21 11:27:31.388521 | controller | changed: "logs" 2026-10-21 11:27:31.578997 | controller | changed: "artifacts" 2026-10-21 11:27:31.781541 | controller | changed: "docs" 2026-10-21 11:27:31.793167 | 2026-10-21 11:27:31.793624 | PLAY RECAP 2026-10-21 11:27:31.793716 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-10-21 11:27:31.793770 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-10-21 11:27:31.793808 | 2026-10-21 11:27:31.928681 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-10-21 11:27:31.931456 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-10-21 11:27:32.565714 | 2026-10-21 11:27:32.565884 | PLAY [all] 2026-10-21 11:27:32.577578 | 2026-10-21 11:27:32.577735 | TASK [setup-uv : Extract archive] 2026-10-21 11:27:35.154766 | controller | changed 2026-10-21 11:27:35.168082 | 2026-10-21 11:27:35.168307 | TASK [setup-uv : Print version] 2026-10-21 11:27:35.576777 | controller | uv 0.8.13 2026-10-21 11:27:35.709367 | controller | ok: Runtime: 0:00:00.016602 2026-10-21 11:27:35.723057 | 2026-10-21 11:27:35.723188 | TASK [Install Ansible collection siblings] 2026-10-21 11:27:36.152792 | controller | ok: 2026-10-21 11:27:36.153129 | controller | Skipping ansible.posix: no checked-out sibling 2026-10-21 11:27:36.153222 | controller | Skipping community.crypto: no checked-out sibling 2026-10-21 11:27:36.153269 | controller | Skipping community.general: no checked-out sibling 2026-10-21 11:27:36.153309 | controller | Skipping kubernetes.core: no checked-out sibling 2026-10-21 11:27:36.153346 | controller | Skipping vexxhost.containers: no checked-out sibling 2026-10-21 11:27:36.156560 | 2026-10-21 11:27:36.156680 | PLAY RECAP 2026-10-21 11:27:36.156804 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-10-21 11:27:36.156858 | 2026-10-21 11:27:36.314333 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-10-21 11:27:36.321398 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-10-21 11:27:36.851240 | 2026-10-21 11:27:36.851359 | PLAY [all] 2026-10-21 11:27:36.860044 | 2026-10-21 11:27:36.860112 | TASK [Update apt cache] 2026-10-21 11:27:42.214808 | controller | ok 2026-10-21 11:27:42.222171 | 2026-10-21 11:27:42.222324 | TASK [Disable firewalld] 2026-10-21 11:27:42.247437 | controller | skipping: Conditional result was False 2026-10-21 11:27:42.255812 | 2026-10-21 11:27:42.255984 | TASK [Install "jq" for log collection] 2026-10-21 11:27:44.460886 | controller | changed 2026-10-21 11:27:44.463435 | 2026-10-21 11:27:44.463518 | PLAY RECAP 2026-10-21 11:27:44.463594 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-10-21 11:27:44.463638 | 2026-10-21 11:27:44.569376 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-10-21 11:27:44.571199 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-10-21 11:27:45.125861 | 2026-10-21 11:27:45.126004 | PLAY [all] 2026-10-21 11:27:45.135238 | 2026-10-21 11:27:45.135303 | LOOP [Check inventory directories] 2026-10-21 11:27:45.542197 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes" 2026-10-21 11:27:45.717001 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/aio/inventory" 2026-10-21 11:27:45.914263 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/aio/inventory" 2026-10-21 11:27:45.931457 | 2026-10-21 11:27:45.931746 | LOOP [Copy inventory file for Zuul] 2026-10-21 11:27:46.897575 | controller | changed: 2026-10-21 11:27:46.897869 | controller | { 2026-10-21 11:27:46.897951 | controller | "ansible_loop_var": "item", 2026-10-21 11:27:46.897998 | controller | "changed": false, 2026-10-21 11:27:46.898037 | controller | "failed": false, 2026-10-21 11:27:46.898085 | controller | "invocation": { 2026-10-21 11:27:46.898123 | controller | "module_args": { 2026-10-21 11:27:46.898158 | controller | "checksum_algorithm": "sha1", 2026-10-21 11:27:46.898193 | controller | "follow": true, 2026-10-21 11:27:46.898227 | controller | "get_attributes": true, 2026-10-21 11:27:46.898263 | controller | "get_checksum": true, 2026-10-21 11:27:46.898297 | controller | "get_mime": true, 2026-10-21 11:27:46.898332 | controller | "path": "src/github.com/vexxhost/ansible-collection-kubernetes" 2026-10-21 11:27:46.898366 | controller | } 2026-10-21 11:27:46.898399 | controller | }, 2026-10-21 11:27:46.898432 | controller | "item": "src/github.com/vexxhost/ansible-collection-kubernetes", 2026-10-21 11:27:46.898464 | controller | "stat": { 2026-10-21 11:27:46.898498 | controller | "atime": 1792582050.292075, 2026-10-21 11:27:46.898532 | controller | "attr_flags": "e", 2026-10-21 11:27:46.898565 | controller | "attributes": [ 2026-10-21 11:27:46.898606 | controller | "extents" 2026-10-21 11:27:46.898655 | controller | ], 2026-10-21 11:27:46.898697 | controller | "block_size": 4096, 2026-10-21 11:27:46.898731 | controller | "blocks": 8, 2026-10-21 11:27:46.898765 | controller | "charset": "unknown", 2026-10-21 11:27:46.898802 | controller | "ctime": 1792582050.384078, 2026-10-21 11:27:46.898835 | controller | "dev": 65025, 2026-10-21 11:27:46.898869 | controller | "device_type": 0, 2026-10-21 11:27:46.898903 | controller | "executable": true, 2026-10-21 11:27:46.898954 | controller | "exists": true, 2026-10-21 11:27:46.898987 | controller | "gid": 1000, 2026-10-21 11:27:46.899019 | controller | "gr_name": "zuul", 2026-10-21 11:27:46.899052 | controller | "inode": 521239, 2026-10-21 11:27:46.899084 | controller | "isblk": false, 2026-10-21 11:27:46.899118 | controller | "ischr": false, 2026-10-21 11:27:46.899151 | controller | "isdir": true, 2026-10-21 11:27:46.899184 | controller | "isfifo": false, 2026-10-21 11:27:46.899216 | controller | "isgid": false, 2026-10-21 11:27:46.899248 | controller | "islnk": false, 2026-10-21 11:27:46.899279 | controller | "isreg": false, 2026-10-21 11:27:46.899311 | controller | "issock": false, 2026-10-21 11:27:46.899343 | controller | "isuid": false, 2026-10-21 11:27:46.899375 | controller | "mimetype": "unknown", 2026-10-21 11:27:46.899407 | controller | "mode": "0755", 2026-10-21 11:27:46.899439 | controller | "mtime": 1792582033.9876728, 2026-10-21 11:27:46.899471 | controller | "nlink": 14, 2026-10-21 11:27:46.899503 | controller | "path": "src/github.com/vexxhost/ansible-collection-kubernetes", 2026-10-21 11:27:46.899536 | controller | "pw_name": "zuul", 2026-10-21 11:27:46.899569 | controller | "readable": true, 2026-10-21 11:27:46.899601 | controller | "rgrp": true, 2026-10-21 11:27:46.899634 | controller | "roth": true, 2026-10-21 11:27:46.899668 | controller | "rusr": true, 2026-10-21 11:27:46.899701 | controller | "size": 4096, 2026-10-21 11:27:46.899735 | controller | "uid": 1000, 2026-10-21 11:27:46.899768 | controller | "version": "620456766", 2026-10-21 11:27:46.899803 | controller | "wgrp": false, 2026-10-21 11:27:46.899840 | controller | "woth": false, 2026-10-21 11:27:46.899874 | controller | "writeable": true, 2026-10-21 11:27:46.899908 | controller | "wusr": true, 2026-10-21 11:27:46.899963 | controller | "xgrp": true, 2026-10-21 11:27:46.899997 | controller | "xoth": true, 2026-10-21 11:27:46.900030 | controller | "xusr": true 2026-10-21 11:27:46.900080 | controller | } 2026-10-21 11:27:46.900116 | controller | } 2026-10-21 11:27:46.900184 | controller | ok: All items complete 2026-10-21 11:27:46.900226 | 2026-10-21 11:27:46.902532 | controller | skipping: Conditional result was False 2026-10-21 11:27:46.908820 | controller | skipping: Conditional result was False 2026-10-21 11:27:46.919998 | 2026-10-21 11:27:46.920221 | LOOP [Switch "ansible_host" to private IP] 2026-10-21 11:27:47.325997 | controller | changed: 1 replacements made 2026-10-21 11:27:47.328405 | controller | skipping: Conditional result was False 2026-10-21 11:27:47.329708 | controller | skipping: Conditional result was False 2026-10-21 11:27:47.351028 | 2026-10-21 11:27:47.351232 | TASK [Run Molecule scenario] 2026-10-21 11:27:47.874881 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-10-21 11:27:47.874980 | controller | Creating virtual environment at: .venv 2026-10-21 11:27:48.152109 | controller | Downloading pygments (1.2MiB) 2026-10-21 11:27:48.154044 | controller | Downloading cryptography (4.3MiB) 2026-10-21 11:27:48.159641 | controller | Downloading ansible-core (2.3MiB) 2026-10-21 11:27:48.318915 | controller | Downloading cryptography 2026-10-21 11:27:48.370046 | controller | Downloading pygments 2026-10-21 11:27:48.524344 | controller | Downloading ansible-core 2026-10-21 11:27:48.588563 | controller | Installed 36 packages in 63ms 2026-10-21 11:27:49.241931 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-10-21 11:27:49.241997 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-10-21 11:27:50.143691 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-10-21 11:27:50.143746 | controller | INFO default scenario not found, disabling shared state. 2026-10-21 11:27:50.143788 | controller | INFO [aio > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-10-21 11:27:50.143894 | controller | INFO [aio > prerun] Performing prerun with role_name_check=0... 2026-10-21 11:28:07.471168 | controller | WARNING Retrying execution failure 250 of: ansible-galaxy collection install -vvv kubernetes.core:>=2.3.2 2026-10-21 11:28:07.471239 | controller | ERROR Command ansible-galaxy collection install -vvv kubernetes.core:>=2.3.2, returned 250 code: 2026-10-21 11:28:07.471286 | controller | Starting galaxy collection install process 2026-10-21 11:28:07.471294 | controller | Process install dependency map 2026-10-21 11:28:07.471302 | controller | 2026-10-21 11:28:07.471310 | controller | ansible-galaxy 2026-10-21 11:28:07.471318 | controller | config file = None 2026-10-21 11:28:07.471325 | controller | configured module search path = 2026-10-21 11:28:07.471332 | controller | ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible 2026-10-21 11:28:07.471350 | 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-10-21 11:28:07.471359 | controller | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy 2026-10-21 11:28:07.471369 | controller | python version = 3.13.5 (main, Aug 10 2026, 12:06:59) (/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/python) 2026-10-21 11:28:07.471377 | controller | jinja version = 3.1.6 2026-10-21 11:28:07.471384 | controller | pyyaml version = 6.0.3 (with libyaml v0.2.5) 2026-10-21 11:28:07.471391 | controller | No config file found; using defaults 2026-10-21 11:28:07.471398 | controller | Found installed collection ansible.posix:2.2.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-10-21 11:28:07.471405 | controller | Found installed collection community.general:13.5.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-10-21 11:28:07.471412 | controller | Found installed collection community.crypto:3.5.1 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-10-21 11:28:07.471419 | controller | Found installed collection community.library_inventory_filtering_v1:1.1.5 at '/home/zuul/.ansible/collections/ansible_collections/community/library_inventory_filtering_v1' 2026-10-21 11:28:07.471427 | controller | Opened /home/zuul/.ansible/galaxy_token 2026-10-21 11:28:07.471440 | controller | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an unexpected error when getting available versions of collection kubernetes.core: Connection reset by peer 2026-10-21 11:28:07.471447 | controller | : Unexpected Exception, this is probably a bug: Connection reset by peer 2026-10-21 11:28:07.471454 | controller | 2026-10-21 11:28:07.471461 | controller | Traceback (most recent call last): 2026-10-21 11:28:07.471483 | 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-10-21 11:28:07.471491 | controller | exit_code = cli.run() 2026-10-21 11:28:07.471498 | 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-10-21 11:28:07.471505 | controller | return context.CLIARGS() 2026-10-21 11:28:07.471512 | controller | ~~~~~~~~~~~~~~~~~~~~~~~^^ 2026-10-21 11:28:07.471519 | 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-10-21 11:28:07.471526 | controller | return wrapped_method(*args, **kwargs) 2026-10-21 11:28:07.471533 | 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-10-21 11:28:07.471540 | controller | self._execute_install_collection( 2026-10-21 11:28:07.471546 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-10-21 11:28:07.471553 | controller | collection_requirements, collection_path, 2026-10-21 11:28:07.471560 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-10-21 11:28:07.471567 | controller | artifacts_manager=artifacts_manager, 2026-10-21 11:28:07.471573 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-10-21 11:28:07.471581 | controller | ) 2026-10-21 11:28:07.471588 | controller | ^ 2026-10-21 11:28:07.471595 | 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-10-21 11:28:07.471602 | controller | install_collections( 2026-10-21 11:28:07.471609 | controller | ~~~~~~~~~~~~~~~~~~~^ 2026-10-21 11:28:07.471617 | controller | requirements, output_path, self.api_servers, ignore_errors, 2026-10-21 11:28:07.471651 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-10-21 11:28:07.471659 | controller | ...<5 lines>... 2026-10-21 11:28:07.471666 | controller | read_requirement_paths=read_req_paths, 2026-10-21 11:28:07.471673 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-10-21 11:28:07.471680 | controller | ) 2026-10-21 11:28:07.471687 | controller | ^ 2026-10-21 11:28:07.471694 | 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-10-21 11:28:07.471702 | controller | dependency_map = _resolve_depenency_map( 2026-10-21 11:28:07.471708 | controller | collections, 2026-10-21 11:28:07.471715 | controller | ...<7 lines>... 2026-10-21 11:28:07.471722 | controller | offline=offline, 2026-10-21 11:28:07.471729 | controller | ) 2026-10-21 11:28:07.471745 | 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-10-21 11:28:07.471752 | controller | collection_dep_resolver.resolve( 2026-10-21 11:28:07.471759 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-10-21 11:28:07.471766 | controller | requested_requirements, 2026-10-21 11:28:07.471773 | controller | ^^^^^^^^^^^^^^^^^^^^^^^ 2026-10-21 11:28:07.471780 | controller | max_rounds=2000000, # NOTE: same constant pip uses 2026-10-21 11:28:07.471787 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-10-21 11:28:07.471794 | controller | ).mapping, 2026-10-21 11:28:07.471800 | controller | ^ 2026-10-21 11:28:07.471807 | 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-10-21 11:28:07.471814 | controller | state = resolution.resolve(requirements, max_rounds=max_rounds) 2026-10-21 11:28:07.471821 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 513, in resolve 2026-10-21 11:28:07.471828 | controller | failure_criterion = self._attempt_to_pin_criterion(name) 2026-10-21 11:28:07.471835 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 220, in _attempt_to_pin_criterion 2026-10-21 11:28:07.471848 | controller | criteria = self._get_updated_criteria(candidate) 2026-10-21 11:28:07.471886 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 210, in _get_updated_criteria 2026-10-21 11:28:07.471893 | controller | for requirement in self._p.get_dependencies(candidate=candidate): 2026-10-21 11:28:07.471900 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^ 2026-10-21 11:28:07.471907 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 410, in get_dependencies 2026-10-21 11:28:07.471914 | controller | req_map = self._api_proxy.get_collection_dependencies(candidate) 2026-10-21 11:28:07.471921 | 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 171, in get_collection_dependencies 2026-10-21 11:28:07.471933 | controller | get_collection_version_metadata(collection_candidate). 2026-10-21 11:28:07.471940 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^ 2026-10-21 11:28:07.471952 | 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 157, in get_collection_version_metadata 2026-10-21 11:28:07.471959 | controller | raise last_err 2026-10-21 11:28:07.471966 | 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 124, in get_collection_version_metadata 2026-10-21 11:28:07.471973 | controller | version_metadata = api.get_collection_version_metadata( 2026-10-21 11:28:07.471980 | controller | collection_candidate.namespace, 2026-10-21 11:28:07.471987 | controller | collection_candidate.name, 2026-10-21 11:28:07.471994 | controller | collection_candidate.ver, 2026-10-21 11:28:07.472001 | controller | ) 2026-10-21 11:28:07.472008 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 128, in wrapped 2026-10-21 11:28:07.472015 | controller | return method(self, *args, **kwargs) 2026-10-21 11:28:07.472022 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 770, in get_collection_version_metadata 2026-10-21 11:28:07.472029 | controller | data = self._call_galaxy(n_collection_url, error_context_msg=error_context_msg, cache=True) 2026-10-21 11:28:07.472036 | 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-10-21 11:28:07.472043 | controller | return call_retryable_function() 2026-10-21 11:28:07.472050 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 391, in _call_galaxy 2026-10-21 11:28:07.472056 | controller | resp_data = to_text(resp.read(), errors='surrogate_or_strict') 2026-10-21 11:28:07.472063 | controller | ~~~~~~~~~^^ 2026-10-21 11:28:07.472070 | controller | File "/usr/lib/python3.13/http/client.py", line 500, in read 2026-10-21 11:28:07.472077 | controller | s = self._safe_read(self.length) 2026-10-21 11:28:07.472084 | controller | File "/usr/lib/python3.13/http/client.py", line 648, in _safe_read 2026-10-21 11:28:07.472091 | controller | data = self.fp.read(cursize) 2026-10-21 11:28:07.472098 | controller | File "/usr/lib/python3.13/socket.py", line 719, in readinto 2026-10-21 11:28:07.472104 | controller | return self._sock.recv_into(b) 2026-10-21 11:28:07.472111 | controller | ~~~~~~~~~~~~~~~~~~~~^^^ 2026-10-21 11:28:07.472118 | controller | File "/usr/lib/python3.13/ssl.py", line 1304, in recv_into 2026-10-21 11:28:07.472125 | controller | return self.read(nbytes, buffer) 2026-10-21 11:28:07.472137 | controller | ~~~~~~~~~^^^^^^^^^^^^^^^^ 2026-10-21 11:28:07.472144 | controller | File "/usr/lib/python3.13/ssl.py", line 1138, in read 2026-10-21 11:28:07.472151 | controller | return self._sslobj.read(len, buffer) 2026-10-21 11:28:07.472158 | controller | ~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^ 2026-10-21 11:28:07.472165 | controller | ConnectionResetError: Connection reset by peer 2026-10-21 11:28:07.472172 | controller | 2026-10-21 11:28:07.472185 | controller | The above exception was the direct cause of the following exception: 2026-10-21 11:28:07.472192 | controller | 2026-10-21 11:28:07.472199 | controller | Traceback (most recent call last): 2026-10-21 11:28:07.472206 | 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-10-21 11:28:07.472213 | controller | raise AnsibleError("Unexpected Exception, this is probably a bug.") from ex 2026-10-21 11:28:07.472220 | controller | ansible.errors.AnsibleError: Unexpected Exception, this is probably a bug: Connection reset by peer 2026-10-21 11:28:07.472227 | controller | 2026-10-21 11:28:07.472234 | controller | 2026-10-21 11:28:07.474782 | controller | Traceback (most recent call last): 2026-10-21 11:28:07.478056 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/molecule", line 10, in 2026-10-21 11:28:07.478081 | controller | sys.exit(main()) 2026-10-21 11:28:07.478089 | controller | ~~~~^^ 2026-10-21 11:28:07.478360 | 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-10-21 11:28:07.478384 | controller | return self.main(*args, **kwargs) 2026-10-21 11:28:07.478392 | controller | ~~~~~~~~~^^^^^^^^^^^^^^^^^ 2026-10-21 11:28:07.478567 | 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-10-21 11:28:07.478575 | controller | rv = self.invoke(ctx) 2026-10-21 11:28:07.478869 | 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-10-21 11:28:07.478889 | controller | return _process_result(sub_ctx.command.invoke(sub_ctx)) 2026-10-21 11:28:07.478897 | controller | ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^ 2026-10-21 11:28:07.479146 | 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-10-21 11:28:07.479155 | controller | return ctx.invoke(self.callback, **ctx.params) 2026-10-21 11:28:07.479162 | controller | ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-10-21 11:28:07.479381 | 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-10-21 11:28:07.479389 | controller | return callback(*args, **kwargs) 2026-10-21 11:28:07.479618 | 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-10-21 11:28:07.479644 | controller | return func(*args, **kwargs) 2026-10-21 11:28:07.479914 | 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-10-21 11:28:07.479924 | controller | return f(get_current_context(), *args, **kwargs) 2026-10-21 11:28:07.480216 | 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-10-21 11:28:07.480239 | controller | return func(ctx) 2026-10-21 11:28:07.480500 | 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-10-21 11:28:07.480524 | controller | base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude) 2026-10-21 11:28:07.480533 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-10-21 11:28:07.480799 | 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-10-21 11:28:07.480831 | controller | _run_scenarios(scenarios, command_args, default_config) 2026-10-21 11:28:07.480840 | controller | ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-10-21 11:28:07.481227 | 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-10-21 11:28:07.481251 | controller | scenario.config.runtime.prepare_environment( 2026-10-21 11:28:07.481259 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-10-21 11:28:07.481267 | controller | install_local=True, 2026-10-21 11:28:07.481274 | controller | ^^^^^^^^^^^^^^^^^^^ 2026-10-21 11:28:07.481281 | controller | role_name_check=role_name_check, 2026-10-21 11:28:07.481288 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-10-21 11:28:07.481294 | controller | ) 2026-10-21 11:28:07.481301 | controller | ^ 2026-10-21 11:28:07.481602 | 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-10-21 11:28:07.481611 | controller | self.install_collection( 2026-10-21 11:28:07.481618 | controller | ~~~~~~~~~~~~~~~~~~~~~~~^ 2026-10-21 11:28:07.481640 | controller | f"{name}{',' if is_url(name) else ':'}{required_version}", 2026-10-21 11:28:07.481648 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-10-21 11:28:07.481654 | controller | destination=destination, 2026-10-21 11:28:07.481661 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^ 2026-10-21 11:28:07.481668 | controller | ) 2026-10-21 11:28:07.481675 | controller | ^ 2026-10-21 11:28:07.481862 | 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-10-21 11:28:07.481870 | controller | raise InvalidPrerequisiteError(msg) 2026-10-21 11:28:07.486162 | controller | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv kubernetes.core:>=2.3.2, returned 250 code: 2026-10-21 11:28:07.486186 | controller | Starting galaxy collection install process 2026-10-21 11:28:07.486195 | controller | Process install dependency map 2026-10-21 11:28:07.486203 | controller | 2026-10-21 11:28:07.486211 | controller | ansible-galaxy [core 2.20.1] 2026-10-21 11:28:07.486218 | controller | config file = None 2026-10-21 11:28:07.486225 | 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-10-21 11:28:07.486232 | controller | ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible 2026-10-21 11:28:07.486240 | 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-10-21 11:28:07.486248 | controller | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy 2026-10-21 11:28:07.486255 | controller | python version = 3.13.5 (main, Aug 10 2026, 12:06:59) [GCC 14.2.0] (/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/python) 2026-10-21 11:28:07.486262 | controller | jinja version = 3.1.6 2026-10-21 11:28:07.486269 | controller | pyyaml version = 6.0.3 (with libyaml v0.2.5) 2026-10-21 11:28:07.486276 | controller | No config file found; using defaults 2026-10-21 11:28:07.486283 | controller | Found installed collection ansible.posix:2.2.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-10-21 11:28:07.486290 | controller | Found installed collection community.general:13.5.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-10-21 11:28:07.486313 | controller | Found installed collection community.crypto:3.5.1 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-10-21 11:28:07.486321 | controller | Found installed collection community.library_inventory_filtering_v1:1.1.5 at '/home/zuul/.ansible/collections/ansible_collections/community/library_inventory_filtering_v1' 2026-10-21 11:28:07.486328 | controller | Opened /home/zuul/.ansible/galaxy_token 2026-10-21 11:28:07.486334 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an unexpected error when getting available versions of collection kubernetes.core: [Errno 104] Connection reset by peer 2026-10-21 11:28:07.486342 | controller | [ERROR]: Unexpected Exception, this is probably a bug: [Errno 104] Connection reset by peer 2026-10-21 11:28:07.486349 | controller | 2026-10-21 11:28:07.486356 | controller | Traceback (most recent call last): 2026-10-21 11:28:07.486363 | 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-10-21 11:28:07.486369 | controller | exit_code = cli.run() 2026-10-21 11:28:07.486377 | 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-10-21 11:28:07.486384 | controller | return context.CLIARGS['func']() 2026-10-21 11:28:07.486390 | controller | ~~~~~~~~~~~~~~~~~~~~~~~^^ 2026-10-21 11:28:07.486397 | 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-10-21 11:28:07.486404 | controller | return wrapped_method(*args, **kwargs) 2026-10-21 11:28:07.486411 | 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-10-21 11:28:07.486418 | controller | self._execute_install_collection( 2026-10-21 11:28:07.486425 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-10-21 11:28:07.486431 | controller | collection_requirements, collection_path, 2026-10-21 11:28:07.486438 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-10-21 11:28:07.486445 | controller | artifacts_manager=artifacts_manager, 2026-10-21 11:28:07.486452 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-10-21 11:28:07.486459 | controller | ) 2026-10-21 11:28:07.486465 | controller | ^ 2026-10-21 11:28:07.486472 | 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-10-21 11:28:07.486479 | controller | install_collections( 2026-10-21 11:28:07.486486 | controller | ~~~~~~~~~~~~~~~~~~~^ 2026-10-21 11:28:07.486493 | controller | requirements, output_path, self.api_servers, ignore_errors, 2026-10-21 11:28:07.486500 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-10-21 11:28:07.486507 | controller | ...<5 lines>... 2026-10-21 11:28:07.486514 | controller | read_requirement_paths=read_req_paths, 2026-10-21 11:28:07.486520 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-10-21 11:28:07.486527 | controller | ) 2026-10-21 11:28:07.486534 | controller | ^ 2026-10-21 11:28:07.486541 | 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-10-21 11:28:07.486556 | controller | dependency_map = _resolve_depenency_map( 2026-10-21 11:28:07.486563 | controller | collections, 2026-10-21 11:28:07.486570 | controller | ...<7 lines>... 2026-10-21 11:28:07.486576 | controller | offline=offline, 2026-10-21 11:28:07.486583 | controller | ) 2026-10-21 11:28:07.486590 | 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-10-21 11:28:07.486597 | controller | collection_dep_resolver.resolve( 2026-10-21 11:28:07.486604 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-10-21 11:28:07.486610 | controller | requested_requirements, 2026-10-21 11:28:07.486617 | controller | ^^^^^^^^^^^^^^^^^^^^^^^ 2026-10-21 11:28:07.486655 | controller | max_rounds=2000000, # NOTE: same constant pip uses 2026-10-21 11:28:07.486662 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-10-21 11:28:07.486669 | controller | ).mapping, 2026-10-21 11:28:07.486676 | controller | ^ 2026-10-21 11:28:07.486684 | 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-10-21 11:28:07.486692 | controller | state = resolution.resolve(requirements, max_rounds=max_rounds) 2026-10-21 11:28:07.486699 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 513, in resolve 2026-10-21 11:28:07.486706 | controller | failure_criterion = self._attempt_to_pin_criterion(name) 2026-10-21 11:28:07.486713 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 220, in _attempt_to_pin_criterion 2026-10-21 11:28:07.486720 | controller | criteria = self._get_updated_criteria(candidate) 2026-10-21 11:28:07.486726 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 210, in _get_updated_criteria 2026-10-21 11:28:07.486733 | controller | for requirement in self._p.get_dependencies(candidate=candidate): 2026-10-21 11:28:07.486740 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^ 2026-10-21 11:28:07.486753 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 410, in get_dependencies 2026-10-21 11:28:07.486760 | controller | req_map = self._api_proxy.get_collection_dependencies(candidate) 2026-10-21 11:28:07.486767 | 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 171, in get_collection_dependencies 2026-10-21 11:28:07.486774 | controller | get_collection_version_metadata(collection_candidate). 2026-10-21 11:28:07.486781 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^ 2026-10-21 11:28:07.486788 | 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 157, in get_collection_version_metadata 2026-10-21 11:28:07.486795 | controller | raise last_err 2026-10-21 11:28:07.486801 | 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 124, in get_collection_version_metadata 2026-10-21 11:28:07.486808 | controller | version_metadata = api.get_collection_version_metadata( 2026-10-21 11:28:07.486815 | controller | collection_candidate.namespace, 2026-10-21 11:28:07.486822 | controller | collection_candidate.name, 2026-10-21 11:28:07.486829 | controller | collection_candidate.ver, 2026-10-21 11:28:07.486836 | controller | ) 2026-10-21 11:28:07.486842 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 128, in wrapped 2026-10-21 11:28:07.486849 | controller | return method(self, *args, **kwargs) 2026-10-21 11:28:07.486856 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 770, in get_collection_version_metadata 2026-10-21 11:28:07.486863 | controller | data = self._call_galaxy(n_collection_url, error_context_msg=error_context_msg, cache=True) 2026-10-21 11:28:07.486870 | 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-10-21 11:28:07.486876 | controller | return call_retryable_function() 2026-10-21 11:28:07.486883 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 391, in _call_galaxy 2026-10-21 11:28:07.486895 | controller | resp_data = to_text(resp.read(), errors='surrogate_or_strict') 2026-10-21 11:28:07.486902 | controller | ~~~~~~~~~^^ 2026-10-21 11:28:07.486908 | controller | File "/usr/lib/python3.13/http/client.py", line 500, in read 2026-10-21 11:28:07.486915 | controller | s = self._safe_read(self.length) 2026-10-21 11:28:07.486922 | controller | File "/usr/lib/python3.13/http/client.py", line 648, in _safe_read 2026-10-21 11:28:07.486929 | controller | data = self.fp.read(cursize) 2026-10-21 11:28:07.486936 | controller | File "/usr/lib/python3.13/socket.py", line 719, in readinto 2026-10-21 11:28:07.486943 | controller | return self._sock.recv_into(b) 2026-10-21 11:28:07.486950 | controller | ~~~~~~~~~~~~~~~~~~~~^^^ 2026-10-21 11:28:07.486956 | controller | File "/usr/lib/python3.13/ssl.py", line 1304, in recv_into 2026-10-21 11:28:07.486969 | controller | return self.read(nbytes, buffer) 2026-10-21 11:28:07.486981 | controller | ~~~~~~~~~^^^^^^^^^^^^^^^^ 2026-10-21 11:28:07.486988 | controller | File "/usr/lib/python3.13/ssl.py", line 1138, in read 2026-10-21 11:28:07.486995 | controller | return self._sslobj.read(len, buffer) 2026-10-21 11:28:07.487001 | controller | ~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^ 2026-10-21 11:28:07.487008 | controller | ConnectionResetError: [Errno 104] Connection reset by peer 2026-10-21 11:28:07.487015 | controller | 2026-10-21 11:28:07.487022 | controller | The above exception was the direct cause of the following exception: 2026-10-21 11:28:07.487029 | controller | 2026-10-21 11:28:07.487036 | controller | Traceback (most recent call last): 2026-10-21 11:28:07.487043 | 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-10-21 11:28:07.487049 | controller | raise AnsibleError("Unexpected Exception, this is probably a bug.") from ex 2026-10-21 11:28:07.487056 | controller | ansible.errors.AnsibleError: Unexpected Exception, this is probably a bug: [Errno 104] Connection reset by peer 2026-10-21 11:28:07.487063 | controller | 2026-10-21 11:28:07.487070 | controller | 2026-10-21 11:28:07.926853 | controller | ERROR 2026-10-21 11:28:07.927061 | controller | { 2026-10-21 11:28:07.927117 | controller | "delta": "0:00:19.817194", 2026-10-21 11:28:07.927158 | controller | "end": "2026-10-21 11:28:07.586819", 2026-10-21 11:28:07.927196 | controller | "msg": "non-zero return code", 2026-10-21 11:28:07.927232 | controller | "rc": 1, 2026-10-21 11:28:07.927267 | controller | "start": "2026-10-21 11:27:47.769625" 2026-10-21 11:28:07.927306 | controller | } failure 2026-10-21 11:28:07.932653 | 2026-10-21 11:28:07.932713 | PLAY RECAP 2026-10-21 11:28:07.932764 | controller | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-10-21 11:28:07.932791 | 2026-10-21 11:28:08.065715 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-10-21 11:28:08.067688 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-10-21 11:28:08.647589 | 2026-10-21 11:28:08.647735 | PLAY [all] 2026-10-21 11:28:08.657822 | 2026-10-21 11:28:08.657896 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-10-21 11:28:09.093353 | controller | changed 2026-10-21 11:28:09.101261 | 2026-10-21 11:28:09.101418 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-10-21 11:28:09.552838 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-10-21 11:28:09.552947 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-10-21 11:28:09.560345 | controller | environment: line 1: kubectl: command not found 2026-10-21 11:28:09.562836 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-10-21 11:28:09.564896 | controller | environment: line 1: kubectl: command not found 2026-10-21 11:28:09.567120 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-10-21 11:28:09.568055 | controller | environment: line 1: kubectl: command not found 2026-10-21 11:28:09.570535 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-10-21 11:28:09.574626 | controller | environment: line 1: kubectl: command not found 2026-10-21 11:28:09.574649 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-10-21 11:28:09.578779 | controller | environment: line 1: kubectl: command not found 2026-10-21 11:28:09.580668 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-10-21 11:28:09.641617 | controller | ok: Runtime: 0:00:00.043819 2026-10-21 11:28:09.649067 | 2026-10-21 11:28:09.649193 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-10-21 11:28:09.905831 | controller | changed 2026-10-21 11:28:09.913539 | 2026-10-21 11:28:09.913672 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-10-21 11:28:10.205372 | controller | environment: line 5: kubectl: command not found 2026-10-21 11:28:10.206090 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-10-21 11:28:10.206727 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-10-21 11:28:10.207810 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-10-21 11:28:10.457079 | controller | ok: Runtime: 0:00:00.014140 2026-10-21 11:28:10.467663 | 2026-10-21 11:28:10.467898 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-10-21 11:28:11.152309 | controller | changed: 2026-10-21 11:28:11.152607 | controller | created directory /var/lib/zuul/builds/e4e196074c9d4b2cb1864105020d898a/work/logs/controller 2026-10-21 11:28:11.152669 | controller | cd+++++++++ objects/ 2026-10-21 11:28:11.152712 | controller | cd+++++++++ objects/cluster/ 2026-10-21 11:28:11.152756 | controller | cd+++++++++ objects/namespaced/ 2026-10-21 11:28:11.165768 | 2026-10-21 11:28:11.165891 | TASK [gather-pod-logs : creating directory for pod logs] 2026-10-21 11:28:11.464157 | controller | changed 2026-10-21 11:28:11.471768 | 2026-10-21 11:28:11.471885 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-10-21 11:28:11.726668 | controller | changed 2026-10-21 11:28:11.733824 | 2026-10-21 11:28:11.733964 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-10-21 11:28:12.024047 | controller | environment: line 3: kubectl: command not found 2026-10-21 11:28:12.273377 | controller | ok: Runtime: 0:00:00.012520 2026-10-21 11:28:12.281968 | 2026-10-21 11:28:12.282094 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-10-21 11:28:12.800999 | controller | changed: 2026-10-21 11:28:12.801307 | controller | cd+++++++++ pod-logs/ 2026-10-21 11:28:12.801363 | controller | cd+++++++++ pod-logs/failed-pods/ 2026-10-21 11:28:12.814481 | 2026-10-21 11:28:12.814621 | LOOP [helm-release-status : creating directory for helm release status] 2026-10-21 11:28:13.083906 | controller | changed: "values" 2026-10-21 11:28:13.309289 | controller | changed: "releases" 2026-10-21 11:28:13.317752 | 2026-10-21 11:28:13.317960 | TASK [helm-release-status : Gather get release status for helm charts] 2026-10-21 11:28:13.592944 | controller | /bin/bash: line 7: helm: command not found 2026-10-21 11:28:13.596731 | controller | /bin/bash: line 11: kubectl: command not found 2026-10-21 11:28:13.853096 | controller | ok: Runtime: 0:00:00.010371 2026-10-21 11:28:13.860402 | 2026-10-21 11:28:13.860553 | TASK [helm-release-status : Downloads logs to executor] 2026-10-21 11:28:14.419223 | controller | changed: 2026-10-21 11:28:14.419480 | controller | cd+++++++++ helm/ 2026-10-21 11:28:14.419538 | controller | cd+++++++++ helm/releases/ 2026-10-21 11:28:14.419586 | controller | cd+++++++++ helm/values/ 2026-10-21 11:28:14.428909 | 2026-10-21 11:28:14.429042 | PLAY RECAP 2026-10-21 11:28:14.429123 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-10-21 11:28:14.429165 | 2026-10-21 11:28:14.602047 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-10-21 11:28:14.604525 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-10-21 11:28:15.169548 | 2026-10-21 11:28:15.169885 | PLAY [all] 2026-10-21 11:28:15.179480 | 2026-10-21 11:28:15.179562 | TASK [fetch-output : Set log path for multiple nodes] 2026-10-21 11:28:15.223211 | controller | skipping: Conditional result was False 2026-10-21 11:28:15.227760 | 2026-10-21 11:28:15.227844 | TASK [fetch-output : Set log path for single node] 2026-10-21 11:28:15.277343 | controller | ok 2026-10-21 11:28:15.284283 | 2026-10-21 11:28:15.284418 | LOOP [fetch-output : Ensure local output dirs] 2026-10-21 11:28:15.723378 | controller -> localhost | ok: "/var/lib/zuul/builds/e4e196074c9d4b2cb1864105020d898a/work/logs" 2026-10-21 11:28:15.971663 | controller -> localhost | changed: "/var/lib/zuul/builds/e4e196074c9d4b2cb1864105020d898a/work/artifacts" 2026-10-21 11:28:16.220278 | controller -> localhost | changed: "/var/lib/zuul/builds/e4e196074c9d4b2cb1864105020d898a/work/docs" 2026-10-21 11:28:16.234730 | 2026-10-21 11:28:16.234919 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-10-21 11:28:16.936279 | controller | changed: .d..t...... ./ 2026-10-21 11:28:16.936635 | controller | changed: All items complete 2026-10-21 11:28:16.936690 | 2026-10-21 11:28:17.429744 | controller | changed: .d..t...... ./ 2026-10-21 11:28:17.950222 | controller | changed: .d..t...... ./ 2026-10-21 11:28:17.966368 | 2026-10-21 11:28:17.966513 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-10-21 11:28:18.408571 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007958 2026-10-21 11:28:18.641176 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.005749 2026-10-21 11:28:18.656231 | 2026-10-21 11:28:18.656421 | PLAY [all] 2026-10-21 11:28:18.665813 | 2026-10-21 11:28:18.665954 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-10-21 11:28:19.144478 | controller | changed 2026-10-21 11:28:19.152874 | 2026-10-21 11:28:19.152995 | PLAY RECAP 2026-10-21 11:28:19.153079 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-10-21 11:28:19.153120 | 2026-10-21 11:28:19.271959 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-10-21 11:28:19.274452 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-10-21 11:28:19.798818 | 2026-10-21 11:28:19.798954 | PLAY [localhost] 2026-10-21 11:28:19.808136 | 2026-10-21 11:28:19.808204 | TASK [Generate Zuul manifest] 2026-10-21 11:28:19.824707 | localhost | ok 2026-10-21 11:28:19.837694 | 2026-10-21 11:28:19.837768 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-10-21 11:28:20.201729 | localhost | changed 2026-10-21 11:28:20.222642 | 2026-10-21 11:28:20.222802 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-10-21 11:28:20.254222 | localhost | ok 2026-10-21 11:28:20.267153 | 2026-10-21 11:28:20.267286 | TASK [Upload logs] 2026-10-21 11:28:20.287657 | localhost | ok 2026-10-21 11:28:20.352373 | 2026-10-21 11:28:20.352458 | TASK [Set zuul-log-path fact] 2026-10-21 11:28:20.369645 | localhost | ok 2026-10-21 11:28:20.384305 | 2026-10-21 11:28:20.384403 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-10-21 11:28:20.412659 | localhost | ok 2026-10-21 11:28:20.422272 | 2026-10-21 11:28:20.422368 | TASK [upload-logs : Create log directories] 2026-10-21 11:28:20.838330 | localhost | changed 2026-10-21 11:28:20.847206 | 2026-10-21 11:28:20.847344 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-10-21 11:28:21.284041 | localhost -> localhost | ok: Runtime: 0:00:00.005529 2026-10-21 11:28:21.293276 | 2026-10-21 11:28:21.293408 | TASK [upload-logs : Upload logs to log server] 2026-10-21 11:28:21.780899 | localhost | Output suppressed because no_log was given 2026-10-21 11:28:21.787482 | 2026-10-21 11:28:21.787593 | LOOP [upload-logs : Compress console log and json output] 2026-10-21 11:28:21.860163 | localhost | skipping: Conditional result was False 2026-10-21 11:28:21.906680 | localhost | skipping: Conditional result was False 2026-10-21 11:28:21.923515 | 2026-10-21 11:28:21.923797 | LOOP [upload-logs : Upload compressed console log and json output] 2026-10-21 11:28:21.987670 | localhost | skipping: Conditional result was False 2026-10-21 11:28:21.988128 | 2026-10-21 11:28:21.991073 | localhost | skipping: Conditional result was False 2026-10-21 11:28:22.009532 | 2026-10-21 11:28:22.009857 | LOOP [upload-logs : Upload console log and json output]