2026-10-13 10:15:35.673718 | Job console starting 2026-10-13 10:15:35.688522 | Updating git repos 2026-10-13 10:15:35.747030 | Cloning repos into workspace 2026-10-13 10:15:35.960232 | Restoring repo states 2026-10-13 10:15:35.973839 | Merging changes 2026-10-13 10:15:36.750963 | Checking out repos 2026-10-13 10:15:37.258499 | Preparing playbooks 2026-10-13 10:15:41.517509 | Running Ansible setup 2026-10-13 10:15:45.750657 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-10-13 10:15:46.517939 | 2026-10-13 10:15:46.518161 | PLAY [localhost] 2026-10-13 10:15:46.528075 | 2026-10-13 10:15:46.528244 | TASK [Gathering Facts] 2026-10-13 10:15:47.552072 | localhost | ok 2026-10-13 10:15:47.561655 | 2026-10-13 10:15:47.561857 | TASK [Setup log path fact] 2026-10-13 10:15:47.582497 | localhost | ok 2026-10-13 10:15:47.604946 | 2026-10-13 10:15:47.605145 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-10-13 10:15:47.637342 | localhost | ok 2026-10-13 10:15:47.645689 | 2026-10-13 10:15:47.645858 | TASK [emit-job-header : Print job information] 2026-10-13 10:15:47.699179 | # Job Information 2026-10-13 10:15:47.699847 | Ansible Version: 2.16.18 2026-10-13 10:15:47.699894 | Job: ansible-collection-kubernetes-molecule-aio-debian-trixie 2026-10-13 10:15:47.699917 | Pipeline: check 2026-10-13 10:15:47.699935 | Executor: 2d72f0692154 2026-10-13 10:15:47.699953 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/300 2026-10-13 10:15:47.699973 | Event ID: 02d97d80-c6ee-11f1-9985-13f3e9f6a334 2026-10-13 10:15:47.703496 | 2026-10-13 10:15:47.703574 | LOOP [emit-job-header : Print node information] 2026-10-13 10:15:47.818690 | localhost | ok: 2026-10-13 10:15:47.818883 | localhost | # Node Information 2026-10-13 10:15:47.818921 | localhost | Inventory Hostname: controller 2026-10-13 10:15:47.818950 | localhost | Hostname: np0000195248 2026-10-13 10:15:47.818976 | localhost | Username: zuul 2026-10-13 10:15:47.819007 | localhost | Distro: Debian 13.7 2026-10-13 10:15:47.819033 | localhost | Provider: yul1 2026-10-13 10:15:47.819058 | localhost | Region: ca-ymq-1 2026-10-13 10:15:47.819082 | localhost | Label: debian-trixie 2026-10-13 10:15:47.819125 | localhost | Product Name: OpenStack Nova 2026-10-13 10:15:47.819155 | localhost | Interface IP: 199.19.213.38 2026-10-13 10:15:47.846769 | 2026-10-13 10:15:47.846995 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-10-13 10:15:48.304928 | localhost -> localhost | changed 2026-10-13 10:15:48.312007 | 2026-10-13 10:15:48.312178 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-10-13 10:15:49.346273 | localhost -> localhost | changed 2026-10-13 10:15:49.353904 | 2026-10-13 10:15:49.354014 | PLAY [all] 2026-10-13 10:15:49.362616 | 2026-10-13 10:15:49.362790 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-10-13 10:15:49.631692 | controller -> localhost | ok 2026-10-13 10:15:49.637382 | 2026-10-13 10:15:49.637490 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-10-13 10:15:49.665400 | controller | ok 2026-10-13 10:15:49.678553 | controller | included: /var/lib/zuul/builds/7df723ddab234eebb7041ca63d9a0882/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-10-13 10:15:49.682898 | 2026-10-13 10:15:49.682963 | TASK [add-build-sshkey : Create Temp SSH key] 2026-10-13 10:15:50.545829 | controller -> localhost | Generating public/private rsa key pair. 2026-10-13 10:15:50.546170 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/7df723ddab234eebb7041ca63d9a0882/work/7df723ddab234eebb7041ca63d9a0882_id_rsa 2026-10-13 10:15:50.546235 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/7df723ddab234eebb7041ca63d9a0882/work/7df723ddab234eebb7041ca63d9a0882_id_rsa.pub 2026-10-13 10:15:50.546281 | controller -> localhost | The key fingerprint is: 2026-10-13 10:15:50.546324 | controller -> localhost | SHA256:SkzlarqtEzltInF9rVsPZqO661SD3dHnyY7rEY53cyo zuul-build-sshkey 2026-10-13 10:15:50.546389 | controller -> localhost | The key's randomart image is: 2026-10-13 10:15:50.546432 | controller -> localhost | +---[RSA 3072]----+ 2026-10-13 10:15:50.546486 | controller -> localhost | | . | 2026-10-13 10:15:50.546533 | controller -> localhost | | o . | 2026-10-13 10:15:50.546574 | controller -> localhost | | .. ... . . | 2026-10-13 10:15:50.546614 | controller -> localhost | | . .o.+.... + . | 2026-10-13 10:15:50.546653 | controller -> localhost | | o o*.S.. . + | 2026-10-13 10:15:50.546693 | controller -> localhost | | . =+oo..*o + | 2026-10-13 10:15:50.546731 | controller -> localhost | | ..=o *.+= + .| 2026-10-13 10:15:50.546771 | controller -> localhost | | .+ o .E+ + | 2026-10-13 10:15:50.546818 | controller -> localhost | | o+*+ .o.. | 2026-10-13 10:15:50.546859 | controller -> localhost | +----[SHA256]-----+ 2026-10-13 10:15:50.546945 | controller -> localhost | ok: Runtime: 0:00:00.262331 2026-10-13 10:15:50.556284 | 2026-10-13 10:15:50.556442 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-10-13 10:15:50.588836 | controller | ok 2026-10-13 10:15:50.604759 | controller | included: /var/lib/zuul/builds/7df723ddab234eebb7041ca63d9a0882/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-10-13 10:15:50.613232 | 2026-10-13 10:15:50.613313 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-10-13 10:15:50.637716 | controller | skipping: Conditional result was False 2026-10-13 10:15:50.646005 | 2026-10-13 10:15:50.646194 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-10-13 10:15:51.259359 | controller | changed 2026-10-13 10:15:51.266848 | 2026-10-13 10:15:51.266974 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-10-13 10:15:51.495728 | controller | ok 2026-10-13 10:15:51.505142 | 2026-10-13 10:15:51.505511 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-10-13 10:15:52.114657 | controller | changed 2026-10-13 10:15:52.119517 | 2026-10-13 10:15:52.119698 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-10-13 10:15:52.714145 | controller | changed 2026-10-13 10:15:52.725005 | 2026-10-13 10:15:52.725277 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-10-13 10:15:52.762294 | controller | skipping: Conditional result was False 2026-10-13 10:15:52.772219 | 2026-10-13 10:15:52.772439 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-10-13 10:15:53.206190 | controller -> localhost | changed 2026-10-13 10:15:53.218007 | 2026-10-13 10:15:53.218161 | TASK [add-build-sshkey : Add back temp key] 2026-10-13 10:15:53.510633 | controller -> localhost | Identity added: /var/lib/zuul/builds/7df723ddab234eebb7041ca63d9a0882/work/7df723ddab234eebb7041ca63d9a0882_id_rsa (zuul-build-sshkey) 2026-10-13 10:15:53.510904 | controller -> localhost | ok: Runtime: 0:00:00.018934 2026-10-13 10:15:53.516308 | 2026-10-13 10:15:53.516405 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-10-13 10:15:53.860922 | controller | ok 2026-10-13 10:15:53.865993 | 2026-10-13 10:15:53.866136 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-10-13 10:15:53.902039 | controller | skipping: Conditional result was False 2026-10-13 10:15:53.913230 | 2026-10-13 10:15:53.913386 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-10-13 10:15:54.271982 | controller | ok 2026-10-13 10:15:54.279583 | 2026-10-13 10:15:54.279719 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-10-13 10:15:55.666944 | controller | Output suppressed because no_log was given 2026-10-13 10:15:55.677635 | 2026-10-13 10:15:55.677726 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-10-13 10:15:55.925588 | controller | ok: "logs" 2026-10-13 10:15:55.925927 | controller | ok: All items complete 2026-10-13 10:15:55.925983 | 2026-10-13 10:15:56.120927 | controller | ok: "artifacts" 2026-10-13 10:15:56.301042 | controller | ok: "docs" 2026-10-13 10:15:56.320054 | 2026-10-13 10:15:56.320234 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-10-13 10:15:56.568326 | controller | changed: "logs" 2026-10-13 10:15:56.783906 | controller | changed: "artifacts" 2026-10-13 10:15:56.970191 | controller | changed: "docs" 2026-10-13 10:15:56.991216 | 2026-10-13 10:15:56.991384 | PLAY RECAP 2026-10-13 10:15:56.991473 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-10-13 10:15:56.991660 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-10-13 10:15:56.991757 | 2026-10-13 10:15:57.221360 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-10-13 10:15:57.223302 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-10-13 10:15:57.929753 | 2026-10-13 10:15:57.960963 | PLAY [all] 2026-10-13 10:15:57.975245 | 2026-10-13 10:15:57.975439 | TASK [setup-uv : Extract archive] 2026-10-13 10:16:00.527100 | controller | changed 2026-10-13 10:16:00.538577 | 2026-10-13 10:16:00.538748 | TASK [setup-uv : Print version] 2026-10-13 10:16:00.973752 | controller | uv 0.8.13 2026-10-13 10:16:01.078166 | controller | ok: Runtime: 0:00:00.015274 2026-10-13 10:16:01.096484 | 2026-10-13 10:16:01.096623 | TASK [Install Ansible collection siblings] 2026-10-13 10:16:01.560552 | controller | ok: 2026-10-13 10:16:01.561079 | controller | Skipping ansible.posix: no checked-out sibling 2026-10-13 10:16:01.561198 | controller | Skipping community.crypto: no checked-out sibling 2026-10-13 10:16:01.561261 | controller | Skipping community.general: no checked-out sibling 2026-10-13 10:16:01.561315 | controller | Skipping kubernetes.core: no checked-out sibling 2026-10-13 10:16:01.561369 | controller | Skipping vexxhost.containers: no checked-out sibling 2026-10-13 10:16:01.565429 | 2026-10-13 10:16:01.565549 | PLAY RECAP 2026-10-13 10:16:01.565641 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-10-13 10:16:01.565684 | 2026-10-13 10:16:01.779411 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-10-13 10:16:01.784127 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-10-13 10:16:02.433456 | 2026-10-13 10:16:02.433657 | PLAY [all] 2026-10-13 10:16:02.446273 | 2026-10-13 10:16:02.446493 | TASK [Update apt cache] 2026-10-13 10:16:07.312666 | controller | ok 2026-10-13 10:16:07.320827 | 2026-10-13 10:16:07.320976 | TASK [Disable firewalld] 2026-10-13 10:16:07.353084 | controller | skipping: Conditional result was False 2026-10-13 10:16:07.362733 | 2026-10-13 10:16:07.362879 | TASK [Install "jq" for log collection] 2026-10-13 10:16:09.769128 | controller | changed 2026-10-13 10:16:09.771792 | 2026-10-13 10:16:09.771856 | PLAY RECAP 2026-10-13 10:16:09.771916 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-10-13 10:16:09.771946 | 2026-10-13 10:16:09.932403 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-10-13 10:16:09.933848 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-10-13 10:16:10.563103 | 2026-10-13 10:16:10.563922 | PLAY [all] 2026-10-13 10:16:10.575089 | 2026-10-13 10:16:10.575209 | LOOP [Check inventory directories] 2026-10-13 10:16:11.012069 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes" 2026-10-13 10:16:11.214326 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/aio/inventory" 2026-10-13 10:16:11.402304 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/aio/inventory" 2026-10-13 10:16:11.420678 | 2026-10-13 10:16:11.421265 | LOOP [Copy inventory file for Zuul] 2026-10-13 10:16:12.447249 | controller | changed: 2026-10-13 10:16:12.447449 | controller | { 2026-10-13 10:16:12.447478 | controller | "ansible_loop_var": "item", 2026-10-13 10:16:12.447522 | controller | "changed": false, 2026-10-13 10:16:12.447551 | controller | "failed": false, 2026-10-13 10:16:12.447573 | controller | "invocation": { 2026-10-13 10:16:12.447590 | controller | "module_args": { 2026-10-13 10:16:12.447605 | controller | "checksum_algorithm": "sha1", 2026-10-13 10:16:12.447620 | controller | "follow": true, 2026-10-13 10:16:12.447635 | controller | "get_attributes": true, 2026-10-13 10:16:12.447651 | controller | "get_checksum": true, 2026-10-13 10:16:12.447666 | controller | "get_mime": true, 2026-10-13 10:16:12.447681 | controller | "path": "src/github.com/vexxhost/ansible-collection-kubernetes" 2026-10-13 10:16:12.447696 | controller | } 2026-10-13 10:16:12.447710 | controller | }, 2026-10-13 10:16:12.447726 | controller | "item": "src/github.com/vexxhost/ansible-collection-kubernetes", 2026-10-13 10:16:12.447740 | controller | "stat": { 2026-10-13 10:16:12.447754 | controller | "atime": 1791886555.444697, 2026-10-13 10:16:12.447768 | controller | "attr_flags": "e", 2026-10-13 10:16:12.447782 | controller | "attributes": [ 2026-10-13 10:16:12.447796 | controller | "extents" 2026-10-13 10:16:12.447810 | controller | ], 2026-10-13 10:16:12.447824 | controller | "block_size": 4096, 2026-10-13 10:16:12.447838 | controller | "blocks": 8, 2026-10-13 10:16:12.447852 | controller | "charset": "unknown", 2026-10-13 10:16:12.447867 | controller | "ctime": 1791886555.5526981, 2026-10-13 10:16:12.447882 | controller | "dev": 65025, 2026-10-13 10:16:12.447896 | controller | "device_type": 0, 2026-10-13 10:16:12.447910 | controller | "executable": true, 2026-10-13 10:16:12.447925 | controller | "exists": true, 2026-10-13 10:16:12.447938 | controller | "gid": 1000, 2026-10-13 10:16:12.447952 | controller | "gr_name": "zuul", 2026-10-13 10:16:12.447966 | controller | "inode": 521239, 2026-10-13 10:16:12.447980 | controller | "isblk": false, 2026-10-13 10:16:12.447994 | controller | "ischr": false, 2026-10-13 10:16:12.448008 | controller | "isdir": true, 2026-10-13 10:16:12.448022 | controller | "isfifo": false, 2026-10-13 10:16:12.448035 | controller | "isgid": false, 2026-10-13 10:16:12.448049 | controller | "islnk": false, 2026-10-13 10:16:12.448063 | controller | "isreg": false, 2026-10-13 10:16:12.448076 | controller | "issock": false, 2026-10-13 10:16:12.448090 | controller | "isuid": false, 2026-10-13 10:16:12.448103 | controller | "mimetype": "unknown", 2026-10-13 10:16:12.448138 | controller | "mode": "0755", 2026-10-13 10:16:12.448154 | controller | "mtime": 1791886536.1061175, 2026-10-13 10:16:12.448168 | controller | "nlink": 14, 2026-10-13 10:16:12.448181 | controller | "path": "src/github.com/vexxhost/ansible-collection-kubernetes", 2026-10-13 10:16:12.448195 | controller | "pw_name": "zuul", 2026-10-13 10:16:12.448215 | controller | "readable": true, 2026-10-13 10:16:12.448230 | controller | "rgrp": true, 2026-10-13 10:16:12.448243 | controller | "roth": true, 2026-10-13 10:16:12.448257 | controller | "rusr": true, 2026-10-13 10:16:12.448271 | controller | "size": 4096, 2026-10-13 10:16:12.448285 | controller | "uid": 1000, 2026-10-13 10:16:12.448299 | controller | "version": "3025133097", 2026-10-13 10:16:12.448314 | controller | "wgrp": false, 2026-10-13 10:16:12.448329 | controller | "woth": false, 2026-10-13 10:16:12.448343 | controller | "writeable": true, 2026-10-13 10:16:12.448358 | controller | "wusr": true, 2026-10-13 10:16:12.448372 | controller | "xgrp": true, 2026-10-13 10:16:12.448386 | controller | "xoth": true, 2026-10-13 10:16:12.448399 | controller | "xusr": true 2026-10-13 10:16:12.448427 | controller | } 2026-10-13 10:16:12.448442 | controller | } 2026-10-13 10:16:12.448474 | controller | ok: All items complete 2026-10-13 10:16:12.448513 | 2026-10-13 10:16:12.449688 | controller | skipping: Conditional result was False 2026-10-13 10:16:12.450249 | controller | skipping: Conditional result was False 2026-10-13 10:16:12.463732 | 2026-10-13 10:16:12.463903 | LOOP [Switch "ansible_host" to private IP] 2026-10-13 10:16:12.877439 | controller | changed: 1 replacements made 2026-10-13 10:16:12.880601 | controller | skipping: Conditional result was False 2026-10-13 10:16:12.889522 | controller | skipping: Conditional result was False 2026-10-13 10:16:12.899680 | 2026-10-13 10:16:12.899861 | TASK [Run Molecule scenario] 2026-10-13 10:16:13.413555 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-10-13 10:16:13.413615 | controller | Creating virtual environment at: .venv 2026-10-13 10:16:13.680724 | controller | Downloading pygments (1.2MiB) 2026-10-13 10:16:13.680904 | controller | Downloading cryptography (4.3MiB) 2026-10-13 10:16:13.681116 | controller | Downloading ansible-core (2.3MiB) 2026-10-13 10:16:13.835851 | controller | Downloading cryptography 2026-10-13 10:16:13.894983 | controller | Downloading pygments 2026-10-13 10:16:14.041695 | controller | Downloading ansible-core 2026-10-13 10:16:14.106889 | controller | Installed 36 packages in 64ms 2026-10-13 10:16:14.821653 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-10-13 10:16:14.821707 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-10-13 10:16:15.688627 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-10-13 10:16:15.688679 | controller | INFO default scenario not found, disabling shared state. 2026-10-13 10:16:15.688766 | controller | INFO [aio > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-10-13 10:16:15.688813 | controller | INFO [aio > prerun] Performing prerun with role_name_check=0... 2026-10-13 10:17:17.369251 | controller | WARNING Retrying execution failure 1 of: ansible-galaxy collection install -vvv community.general:>=4.5.0 2026-10-13 10:17:17.369338 | controller | ERROR Command ansible-galaxy collection install -vvv community.general:>=4.5.0, returned 1 code: 2026-10-13 10:17:17.369421 | controller | Starting galaxy collection install process 2026-10-13 10:17:17.369430 | controller | Process install dependency map 2026-10-13 10:17:17.369440 | controller | 2026-10-13 10:17:17.369448 | controller | ansible-galaxy 2026-10-13 10:17:17.369455 | controller | config file = None 2026-10-13 10:17:17.369463 | controller | configured module search path = 2026-10-13 10:17:17.369471 | controller | ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible 2026-10-13 10:17:17.369491 | 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-13 10:17:17.369501 | controller | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy 2026-10-13 10:17:17.369514 | 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-13 10:17:17.369522 | controller | jinja version = 3.1.6 2026-10-13 10:17:17.369529 | controller | pyyaml version = 6.0.3 (with libyaml v0.2.5) 2026-10-13 10:17:17.369537 | controller | No config file found; using defaults 2026-10-13 10:17:17.369544 | controller | Found installed collection community.crypto:3.5.0 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-10-13 10:17:17.369552 | controller | Found installed collection ansible.posix:2.2.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-10-13 10:17:17.369559 | controller | Opened /home/zuul/.ansible/galaxy_token 2026-10-13 10:17:17.369574 | controller | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an unexpected error when getting available versions of collection community.general: Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/index/community/general/versions/?limit=100&offset=100': Connection reset by peer 2026-10-13 10:17:17.369583 | controller | : Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/index/community/general/versions/?limit=100&offset=100': Connection reset by peer 2026-10-13 10:17:17.369599 | controller | 2026-10-13 10:17:17.372797 | controller | Traceback (most recent call last): 2026-10-13 10:17:17.376017 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/molecule", line 10, in 2026-10-13 10:17:17.376051 | controller | sys.exit(main()) 2026-10-13 10:17:17.376059 | controller | ~~~~^^ 2026-10-13 10:17:17.376366 | 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-13 10:17:17.376378 | controller | return self.main(*args, **kwargs) 2026-10-13 10:17:17.376386 | controller | ~~~~~~~~~^^^^^^^^^^^^^^^^^ 2026-10-13 10:17:17.376620 | 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-13 10:17:17.376629 | controller | rv = self.invoke(ctx) 2026-10-13 10:17:17.376899 | 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-13 10:17:17.376908 | controller | return _process_result(sub_ctx.command.invoke(sub_ctx)) 2026-10-13 10:17:17.376916 | controller | ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^ 2026-10-13 10:17:17.377161 | 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-13 10:17:17.377170 | controller | return ctx.invoke(self.callback, **ctx.params) 2026-10-13 10:17:17.377177 | controller | ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-10-13 10:17:17.377427 | 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-13 10:17:17.377439 | controller | return callback(*args, **kwargs) 2026-10-13 10:17:17.377655 | 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-13 10:17:17.377664 | controller | return func(*args, **kwargs) 2026-10-13 10:17:17.377893 | 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-13 10:17:17.377903 | controller | return f(get_current_context(), *args, **kwargs) 2026-10-13 10:17:17.378127 | 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-13 10:17:17.378136 | controller | return func(ctx) 2026-10-13 10:17:17.378431 | 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-13 10:17:17.378441 | controller | base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude) 2026-10-13 10:17:17.378448 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-10-13 10:17:17.378703 | 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-13 10:17:17.378713 | controller | _run_scenarios(scenarios, command_args, default_config) 2026-10-13 10:17:17.378720 | controller | ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-10-13 10:17:17.379123 | 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-13 10:17:17.379131 | controller | scenario.config.runtime.prepare_environment( 2026-10-13 10:17:17.379139 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-10-13 10:17:17.379146 | controller | install_local=True, 2026-10-13 10:17:17.379153 | controller | ^^^^^^^^^^^^^^^^^^^ 2026-10-13 10:17:17.379160 | controller | role_name_check=role_name_check, 2026-10-13 10:17:17.379166 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-10-13 10:17:17.379173 | controller | ) 2026-10-13 10:17:17.379180 | controller | ^ 2026-10-13 10:17:17.379560 | 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-13 10:17:17.379577 | controller | self.install_collection( 2026-10-13 10:17:17.379584 | controller | ~~~~~~~~~~~~~~~~~~~~~~~^ 2026-10-13 10:17:17.379591 | controller | f"{name}{',' if is_url(name) else ':'}{required_version}", 2026-10-13 10:17:17.379599 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-10-13 10:17:17.379606 | controller | destination=destination, 2026-10-13 10:17:17.379612 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^ 2026-10-13 10:17:17.379619 | controller | ) 2026-10-13 10:17:17.379626 | controller | ^ 2026-10-13 10:17:17.379805 | 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-13 10:17:17.379814 | controller | raise InvalidPrerequisiteError(msg) 2026-10-13 10:17:17.380883 | controller | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv community.general:>=4.5.0, returned 1 code: 2026-10-13 10:17:17.380893 | controller | Starting galaxy collection install process 2026-10-13 10:17:17.380900 | controller | Process install dependency map 2026-10-13 10:17:17.380908 | controller | 2026-10-13 10:17:17.380915 | controller | ansible-galaxy [core 2.20.1] 2026-10-13 10:17:17.380922 | controller | config file = None 2026-10-13 10:17:17.380938 | 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-13 10:17:17.380954 | controller | ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible 2026-10-13 10:17:17.380961 | 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-13 10:17:17.380969 | controller | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy 2026-10-13 10:17:17.380976 | 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-13 10:17:17.380983 | controller | jinja version = 3.1.6 2026-10-13 10:17:17.380990 | controller | pyyaml version = 6.0.3 (with libyaml v0.2.5) 2026-10-13 10:17:17.380997 | controller | No config file found; using defaults 2026-10-13 10:17:17.381004 | controller | Found installed collection community.crypto:3.5.0 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-10-13 10:17:17.381011 | controller | Found installed collection ansible.posix:2.2.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-10-13 10:17:17.381018 | controller | Opened /home/zuul/.ansible/galaxy_token 2026-10-13 10:17:17.381031 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an unexpected error when getting available versions of collection community.general: Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/index/community/general/versions/?limit=100&offset=100': [Errno 104] Connection reset by peer 2026-10-13 10:17:17.381041 | controller | [ERROR]: Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/index/community/general/versions/?limit=100&offset=100': [Errno 104] Connection reset by peer 2026-10-13 10:17:17.381049 | controller | 2026-10-13 10:17:17.979270 | controller | ERROR 2026-10-13 10:17:17.979473 | controller | { 2026-10-13 10:17:17.979511 | controller | "delta": "0:01:04.176747", 2026-10-13 10:17:17.979536 | controller | "end": "2026-10-13 10:17:17.488883", 2026-10-13 10:17:17.979558 | controller | "msg": "non-zero return code", 2026-10-13 10:17:17.979582 | controller | "rc": 1, 2026-10-13 10:17:17.979603 | controller | "start": "2026-10-13 10:16:13.312136" 2026-10-13 10:17:17.979625 | controller | } failure 2026-10-13 10:17:17.982690 | 2026-10-13 10:17:17.982820 | PLAY RECAP 2026-10-13 10:17:17.982933 | controller | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-10-13 10:17:17.982995 | 2026-10-13 10:17:18.134576 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-10-13 10:17:18.136794 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-10-13 10:17:18.800647 | 2026-10-13 10:17:18.800793 | PLAY [all] 2026-10-13 10:17:18.812041 | 2026-10-13 10:17:18.812150 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-10-13 10:17:19.248934 | controller | changed 2026-10-13 10:17:19.257933 | 2026-10-13 10:17:19.258118 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-10-13 10:17:19.752981 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-10-13 10:17:19.753098 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-10-13 10:17:19.759894 | controller | environment: line 1: kubectl: command not found 2026-10-13 10:17:19.760774 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-10-13 10:17:19.764283 | controller | environment: line 1: kubectl: command not found 2026-10-13 10:17:19.765289 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-10-13 10:17:19.766982 | controller | environment: line 1: kubectl: command not found 2026-10-13 10:17:19.768583 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-10-13 10:17:19.771740 | controller | environment: line 1: kubectl: command not found 2026-10-13 10:17:19.773352 | controller | environment: line 1: kubectl: command not found 2026-10-13 10:17:19.773769 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-10-13 10:17:19.775162 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-10-13 10:17:19.820221 | controller | ok: Runtime: 0:00:00.036674 2026-10-13 10:17:19.828802 | 2026-10-13 10:17:19.828951 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-10-13 10:17:20.114784 | controller | changed 2026-10-13 10:17:20.121234 | 2026-10-13 10:17:20.121353 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-10-13 10:17:20.414339 | controller | environment: line 5: kubectl: command not found 2026-10-13 10:17:20.415178 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-10-13 10:17:20.416684 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-10-13 10:17:20.416991 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-10-13 10:17:20.652834 | controller | ok: Runtime: 0:00:00.015627 2026-10-13 10:17:20.657457 | 2026-10-13 10:17:20.657524 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-10-13 10:17:21.348841 | controller | changed: 2026-10-13 10:17:21.349084 | controller | created directory /var/lib/zuul/builds/7df723ddab234eebb7041ca63d9a0882/work/logs/controller 2026-10-13 10:17:21.349136 | controller | cd+++++++++ objects/ 2026-10-13 10:17:21.349207 | controller | cd+++++++++ objects/cluster/ 2026-10-13 10:17:21.349274 | controller | cd+++++++++ objects/namespaced/ 2026-10-13 10:17:21.364024 | 2026-10-13 10:17:21.364238 | TASK [gather-pod-logs : creating directory for pod logs] 2026-10-13 10:17:21.631181 | controller | changed 2026-10-13 10:17:21.637258 | 2026-10-13 10:17:21.637396 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-10-13 10:17:21.891964 | controller | changed 2026-10-13 10:17:21.895591 | 2026-10-13 10:17:21.895662 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-10-13 10:17:22.171818 | controller | environment: line 3: kubectl: command not found 2026-10-13 10:17:22.423067 | controller | ok: Runtime: 0:00:00.014551 2026-10-13 10:17:22.434699 | 2026-10-13 10:17:22.434857 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-10-13 10:17:22.952283 | controller | changed: 2026-10-13 10:17:22.952567 | controller | cd+++++++++ pod-logs/ 2026-10-13 10:17:22.952613 | controller | cd+++++++++ pod-logs/failed-pods/ 2026-10-13 10:17:22.965699 | 2026-10-13 10:17:22.965861 | LOOP [helm-release-status : creating directory for helm release status] 2026-10-13 10:17:23.225668 | controller | changed: "values" 2026-10-13 10:17:23.447537 | controller | changed: "releases" 2026-10-13 10:17:23.455519 | 2026-10-13 10:17:23.455685 | TASK [helm-release-status : Gather get release status for helm charts] 2026-10-13 10:17:23.737645 | controller | /bin/bash: line 7: helm: command not found 2026-10-13 10:17:23.742514 | controller | /bin/bash: line 11: kubectl: command not found 2026-10-13 10:17:23.984996 | controller | ok: Runtime: 0:00:00.014719 2026-10-13 10:17:23.993917 | 2026-10-13 10:17:23.994129 | TASK [helm-release-status : Downloads logs to executor] 2026-10-13 10:17:24.503703 | controller | changed: 2026-10-13 10:17:24.504100 | controller | cd+++++++++ helm/ 2026-10-13 10:17:24.504246 | controller | cd+++++++++ helm/releases/ 2026-10-13 10:17:24.504301 | controller | cd+++++++++ helm/values/ 2026-10-13 10:17:24.513524 | 2026-10-13 10:17:24.513618 | PLAY RECAP 2026-10-13 10:17:24.513697 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-10-13 10:17:24.513735 | 2026-10-13 10:17:24.690789 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-10-13 10:17:24.693598 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-10-13 10:17:25.326181 | 2026-10-13 10:17:25.326345 | PLAY [all] 2026-10-13 10:17:25.337241 | 2026-10-13 10:17:25.337384 | TASK [fetch-output : Set log path for multiple nodes] 2026-10-13 10:17:25.393779 | controller | skipping: Conditional result was False 2026-10-13 10:17:25.399013 | 2026-10-13 10:17:25.399126 | TASK [fetch-output : Set log path for single node] 2026-10-13 10:17:25.428817 | controller | ok 2026-10-13 10:17:25.433984 | 2026-10-13 10:17:25.434066 | LOOP [fetch-output : Ensure local output dirs] 2026-10-13 10:17:25.906840 | controller -> localhost | ok: "/var/lib/zuul/builds/7df723ddab234eebb7041ca63d9a0882/work/logs" 2026-10-13 10:17:26.206126 | controller -> localhost | changed: "/var/lib/zuul/builds/7df723ddab234eebb7041ca63d9a0882/work/artifacts" 2026-10-13 10:17:26.430983 | controller -> localhost | changed: "/var/lib/zuul/builds/7df723ddab234eebb7041ca63d9a0882/work/docs" 2026-10-13 10:17:26.450857 | 2026-10-13 10:17:26.451724 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-10-13 10:17:27.219216 | controller | changed: .d..t...... ./ 2026-10-13 10:17:27.219538 | controller | changed: All items complete 2026-10-13 10:17:27.219569 | 2026-10-13 10:17:27.706278 | controller | changed: .d..t...... ./ 2026-10-13 10:17:28.204131 | controller | changed: .d..t...... ./ 2026-10-13 10:17:28.219126 | 2026-10-13 10:17:28.219335 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-10-13 10:17:28.720753 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006417 2026-10-13 10:17:28.976463 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007232 2026-10-13 10:17:29.000946 | 2026-10-13 10:17:29.001137 | PLAY [all] 2026-10-13 10:17:29.023167 | 2026-10-13 10:17:29.023399 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-10-13 10:17:29.481492 | controller | changed 2026-10-13 10:17:29.486849 | 2026-10-13 10:17:29.487212 | PLAY RECAP 2026-10-13 10:17:29.487289 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-10-13 10:17:29.487321 | 2026-10-13 10:17:29.681093 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-10-13 10:17:29.684386 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-10-13 10:17:30.337263 | 2026-10-13 10:17:30.337468 | PLAY [localhost] 2026-10-13 10:17:30.350931 | 2026-10-13 10:17:30.351131 | TASK [Generate Zuul manifest] 2026-10-13 10:17:30.369525 | localhost | ok 2026-10-13 10:17:30.384056 | 2026-10-13 10:17:30.384276 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-10-13 10:17:30.788856 | localhost | changed 2026-10-13 10:17:30.801598 | 2026-10-13 10:17:30.801802 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-10-13 10:17:30.831957 | localhost | ok 2026-10-13 10:17:30.841427 | 2026-10-13 10:17:30.841578 | TASK [Upload logs] 2026-10-13 10:17:30.861903 | localhost | ok 2026-10-13 10:17:30.929971 | 2026-10-13 10:17:30.930237 | TASK [Set zuul-log-path fact] 2026-10-13 10:17:30.946981 | localhost | ok 2026-10-13 10:17:30.957064 | 2026-10-13 10:17:30.957216 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-10-13 10:17:30.986913 | localhost | ok 2026-10-13 10:17:30.995367 | 2026-10-13 10:17:30.995496 | TASK [upload-logs : Create log directories] 2026-10-13 10:17:31.443665 | localhost | changed 2026-10-13 10:17:31.452475 | 2026-10-13 10:17:31.452690 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-10-13 10:17:31.910323 | localhost -> localhost | ok: Runtime: 0:00:00.005523 2026-10-13 10:17:31.917408 | 2026-10-13 10:17:31.917570 | TASK [upload-logs : Upload logs to log server] 2026-10-13 10:17:32.438332 | localhost | Output suppressed because no_log was given 2026-10-13 10:17:32.447221 | 2026-10-13 10:17:32.447378 | LOOP [upload-logs : Compress console log and json output] 2026-10-13 10:17:32.490748 | localhost | skipping: Conditional result was False 2026-10-13 10:17:32.548580 | localhost | skipping: Conditional result was False 2026-10-13 10:17:32.563083 | 2026-10-13 10:17:32.563257 | LOOP [upload-logs : Upload compressed console log and json output] 2026-10-13 10:17:32.603628 | localhost | skipping: Conditional result was False 2026-10-13 10:17:32.603991 | 2026-10-13 10:17:32.608264 | localhost | skipping: Conditional result was False 2026-10-13 10:17:32.623647 | 2026-10-13 10:17:32.623796 | LOOP [upload-logs : Upload console log and json output]