2026-09-24 00:47:54.857319 | Job console starting 2026-09-24 00:47:54.873224 | Updating git repos 2026-09-24 00:47:54.948745 | Cloning repos into workspace 2026-09-24 00:47:55.014947 | Restoring repo states 2026-09-24 00:47:55.033742 | Merging changes 2026-09-24 00:47:55.870802 | Checking out repos 2026-09-24 00:47:56.093743 | Preparing playbooks 2026-09-24 00:47:58.636663 | Running Ansible setup 2026-09-24 00:48:02.379385 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-24 00:48:02.929102 | 2026-09-24 00:48:02.929236 | PLAY [localhost] 2026-09-24 00:48:02.936294 | 2026-09-24 00:48:02.936380 | TASK [Gathering Facts] 2026-09-24 00:48:03.760756 | localhost | ok 2026-09-24 00:48:03.769607 | 2026-09-24 00:48:03.769719 | TASK [Setup log path fact] 2026-09-24 00:48:03.789115 | localhost | ok 2026-09-24 00:48:03.800401 | 2026-09-24 00:48:03.800465 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-24 00:48:03.838298 | localhost | ok 2026-09-24 00:48:03.845612 | 2026-09-24 00:48:03.845695 | TASK [emit-job-header : Print job information] 2026-09-24 00:48:03.897148 | # Job Information 2026-09-24 00:48:03.897415 | Ansible Version: 2.16.18 2026-09-24 00:48:03.897503 | Job: ansible-collection-kubernetes-molecule-helm 2026-09-24 00:48:03.897565 | Pipeline: check 2026-09-24 00:48:03.897623 | Executor: 2d72f0692154 2026-09-24 00:48:03.897675 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/293 2026-09-24 00:48:03.897736 | Event ID: e533a450-b7b0-11f1-98be-9e8d6b6260a8 2026-09-24 00:48:03.903521 | 2026-09-24 00:48:03.903652 | LOOP [emit-job-header : Print node information] 2026-09-24 00:48:03.988127 | localhost | ok: 2026-09-24 00:48:03.988310 | localhost | # Node Information 2026-09-24 00:48:03.988335 | localhost | Inventory Hostname: controller 2026-09-24 00:48:03.988354 | localhost | Hostname: np0000193849 2026-09-24 00:48:03.988371 | localhost | Username: zuul 2026-09-24 00:48:03.988392 | localhost | Distro: Debian 13.7 2026-09-24 00:48:03.988408 | localhost | Provider: yul1 2026-09-24 00:48:03.988424 | localhost | Region: ca-ymq-1 2026-09-24 00:48:03.988440 | localhost | Label: debian-trixie 2026-09-24 00:48:03.988456 | localhost | Product Name: OpenStack Nova 2026-09-24 00:48:03.988473 | localhost | Interface IP: 162.253.55.17 2026-09-24 00:48:03.998872 | 2026-09-24 00:48:03.999052 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-24 00:48:04.414960 | localhost -> localhost | changed 2026-09-24 00:48:04.423993 | 2026-09-24 00:48:04.424162 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-24 00:48:05.358742 | localhost -> localhost | changed 2026-09-24 00:48:05.370356 | 2026-09-24 00:48:05.370465 | PLAY [all] 2026-09-24 00:48:05.384884 | 2026-09-24 00:48:05.385042 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-24 00:48:05.654770 | controller -> localhost | ok 2026-09-24 00:48:05.659301 | 2026-09-24 00:48:05.659363 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-24 00:48:05.687454 | controller | ok 2026-09-24 00:48:05.703962 | controller | included: /var/lib/zuul/builds/89213df3091e4d208a58293f0bfa19dc/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-24 00:48:05.708838 | 2026-09-24 00:48:05.708895 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-24 00:48:06.940230 | controller -> localhost | Generating public/private rsa key pair. 2026-09-24 00:48:06.940489 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/89213df3091e4d208a58293f0bfa19dc/work/89213df3091e4d208a58293f0bfa19dc_id_rsa 2026-09-24 00:48:06.940574 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/89213df3091e4d208a58293f0bfa19dc/work/89213df3091e4d208a58293f0bfa19dc_id_rsa.pub 2026-09-24 00:48:06.940617 | controller -> localhost | The key fingerprint is: 2026-09-24 00:48:06.940656 | controller -> localhost | SHA256:LZfgFJnhI5GVltSwufM3vCPXkLipYVB/y9OGBuCrwzA zuul-build-sshkey 2026-09-24 00:48:06.940715 | controller -> localhost | The key's randomart image is: 2026-09-24 00:48:06.940754 | controller -> localhost | +---[RSA 3072]----+ 2026-09-24 00:48:06.940803 | controller -> localhost | | .+*O | 2026-09-24 00:48:06.940844 | controller -> localhost | | .o*+. | 2026-09-24 00:48:06.940882 | controller -> localhost | | ..X | 2026-09-24 00:48:06.940918 | controller -> localhost | | * B . | 2026-09-24 00:48:06.940954 | controller -> localhost | | . S *... | 2026-09-24 00:48:06.940990 | controller -> localhost | | E . *.=o+ | 2026-09-24 00:48:06.941025 | controller -> localhost | | + + .oXoo | 2026-09-24 00:48:06.941061 | controller -> localhost | | oo .+oo=. | 2026-09-24 00:48:06.941124 | controller -> localhost | | .... o.. | 2026-09-24 00:48:06.941162 | controller -> localhost | +----[SHA256]-----+ 2026-09-24 00:48:06.941246 | controller -> localhost | ok: Runtime: 0:00:00.710661 2026-09-24 00:48:06.949316 | 2026-09-24 00:48:06.949435 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-24 00:48:06.980129 | controller | ok 2026-09-24 00:48:06.994922 | controller | included: /var/lib/zuul/builds/89213df3091e4d208a58293f0bfa19dc/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-24 00:48:07.007818 | 2026-09-24 00:48:07.007899 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-24 00:48:07.031577 | controller | skipping: Conditional result was False 2026-09-24 00:48:07.057315 | 2026-09-24 00:48:07.057409 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-24 00:48:07.627548 | controller | changed 2026-09-24 00:48:07.634805 | 2026-09-24 00:48:07.634924 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-24 00:48:07.851331 | controller | ok 2026-09-24 00:48:07.858855 | 2026-09-24 00:48:07.858977 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-24 00:48:08.417121 | controller | changed 2026-09-24 00:48:08.425576 | 2026-09-24 00:48:08.425705 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-24 00:48:09.037482 | controller | changed 2026-09-24 00:48:09.045562 | 2026-09-24 00:48:09.045687 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-24 00:48:09.070302 | controller | skipping: Conditional result was False 2026-09-24 00:48:09.095743 | 2026-09-24 00:48:09.095908 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-24 00:48:09.539513 | controller -> localhost | changed 2026-09-24 00:48:09.558563 | 2026-09-24 00:48:09.558688 | TASK [add-build-sshkey : Add back temp key] 2026-09-24 00:48:09.877043 | controller -> localhost | Identity added: /var/lib/zuul/builds/89213df3091e4d208a58293f0bfa19dc/work/89213df3091e4d208a58293f0bfa19dc_id_rsa (zuul-build-sshkey) 2026-09-24 00:48:09.877401 | controller -> localhost | ok: Runtime: 0:00:00.012631 2026-09-24 00:48:09.886031 | 2026-09-24 00:48:09.886176 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-24 00:48:10.319024 | controller | ok 2026-09-24 00:48:10.328454 | 2026-09-24 00:48:10.328607 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-24 00:48:10.363304 | controller | skipping: Conditional result was False 2026-09-24 00:48:10.377467 | 2026-09-24 00:48:10.377589 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-24 00:48:10.762490 | controller | ok 2026-09-24 00:48:10.773138 | 2026-09-24 00:48:10.773224 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-24 00:48:11.899405 | controller | Output suppressed because no_log was given 2026-09-24 00:48:11.915819 | 2026-09-24 00:48:11.915953 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-24 00:48:12.158221 | controller | ok: "logs" 2026-09-24 00:48:12.158663 | controller | ok: All items complete 2026-09-24 00:48:12.158727 | 2026-09-24 00:48:12.357682 | controller | ok: "artifacts" 2026-09-24 00:48:12.540436 | controller | ok: "docs" 2026-09-24 00:48:12.551019 | 2026-09-24 00:48:12.551163 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-24 00:48:12.749408 | controller | changed: "logs" 2026-09-24 00:48:12.923579 | controller | changed: "artifacts" 2026-09-24 00:48:13.094728 | controller | changed: "docs" 2026-09-24 00:48:13.106124 | 2026-09-24 00:48:13.106217 | PLAY RECAP 2026-09-24 00:48:13.106304 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-24 00:48:13.106357 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-24 00:48:13.106399 | 2026-09-24 00:48:13.239039 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-24 00:48:13.242260 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-24 00:48:13.775263 | 2026-09-24 00:48:13.775378 | PLAY [all] 2026-09-24 00:48:13.783802 | 2026-09-24 00:48:13.783873 | TASK [setup-uv : Extract archive] 2026-09-24 00:48:16.138219 | controller | changed 2026-09-24 00:48:16.147061 | 2026-09-24 00:48:16.147223 | TASK [setup-uv : Print version] 2026-09-24 00:48:16.542954 | controller | uv 0.8.13 2026-09-24 00:48:16.688519 | controller | ok: Runtime: 0:00:00.017552 2026-09-24 00:48:16.701298 | 2026-09-24 00:48:16.701423 | TASK [Install Ansible collection siblings] 2026-09-24 00:48:17.057263 | controller | ok: 2026-09-24 00:48:17.057510 | controller | Skipping ansible.posix: no checked-out sibling 2026-09-24 00:48:17.057563 | controller | Skipping community.crypto: no checked-out sibling 2026-09-24 00:48:17.057605 | controller | Skipping community.general: no checked-out sibling 2026-09-24 00:48:17.057643 | controller | Skipping kubernetes.core: no checked-out sibling 2026-09-24 00:48:17.057681 | controller | Skipping vexxhost.containers: no checked-out sibling 2026-09-24 00:48:17.060327 | 2026-09-24 00:48:17.060408 | PLAY RECAP 2026-09-24 00:48:17.060490 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-24 00:48:17.060570 | 2026-09-24 00:48:17.177851 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-24 00:48:17.180243 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-09-24 00:48:17.767483 | 2026-09-24 00:48:17.767645 | PLAY [all] 2026-09-24 00:48:17.778224 | 2026-09-24 00:48:17.778302 | TASK [Update apt cache] 2026-09-24 00:48:23.791965 | controller | ok 2026-09-24 00:48:23.799477 | 2026-09-24 00:48:23.799617 | TASK [Disable firewalld] 2026-09-24 00:48:23.824527 | controller | skipping: Conditional result was False 2026-09-24 00:48:23.832789 | 2026-09-24 00:48:23.832923 | TASK [Install "jq" for log collection] 2026-09-24 00:48:26.577292 | controller | changed 2026-09-24 00:48:26.580454 | 2026-09-24 00:48:26.580572 | PLAY RECAP 2026-09-24 00:48:26.580653 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-24 00:48:26.580695 | 2026-09-24 00:48:26.699703 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-09-24 00:48:26.702189 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-24 00:48:27.239654 | 2026-09-24 00:48:27.239764 | PLAY [all] 2026-09-24 00:48:27.248385 | 2026-09-24 00:48:27.248451 | LOOP [Check inventory directories] 2026-09-24 00:48:27.646162 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes" 2026-09-24 00:48:27.825365 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/helm/inventory" 2026-09-24 00:48:28.026983 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/inventory" 2026-09-24 00:48:28.043870 | 2026-09-24 00:48:28.044080 | LOOP [Copy inventory file for Zuul] 2026-09-24 00:48:29.021263 | controller | changed: 2026-09-24 00:48:29.021460 | controller | { 2026-09-24 00:48:29.021487 | controller | "ansible_loop_var": "item", 2026-09-24 00:48:29.021513 | controller | "changed": false, 2026-09-24 00:48:29.021533 | controller | "failed": false, 2026-09-24 00:48:29.021558 | controller | "invocation": { 2026-09-24 00:48:29.021579 | controller | "module_args": { 2026-09-24 00:48:29.021598 | controller | "checksum_algorithm": "sha1", 2026-09-24 00:48:29.021620 | controller | "follow": true, 2026-09-24 00:48:29.021638 | controller | "get_attributes": true, 2026-09-24 00:48:29.021659 | controller | "get_checksum": true, 2026-09-24 00:48:29.021678 | controller | "get_mime": true, 2026-09-24 00:48:29.021698 | controller | "path": "src/github.com/vexxhost/ansible-collection-kubernetes" 2026-09-24 00:48:29.021715 | controller | } 2026-09-24 00:48:29.021736 | controller | }, 2026-09-24 00:48:29.021753 | controller | "item": "src/github.com/vexxhost/ansible-collection-kubernetes", 2026-09-24 00:48:29.021773 | controller | "stat": { 2026-09-24 00:48:29.021790 | controller | "atime": 1790210891.743843, 2026-09-24 00:48:29.021810 | controller | "attr_flags": "e", 2026-09-24 00:48:29.021828 | controller | "attributes": [ 2026-09-24 00:48:29.021848 | controller | "extents" 2026-09-24 00:48:29.021866 | controller | ], 2026-09-24 00:48:29.021886 | controller | "block_size": 4096, 2026-09-24 00:48:29.021903 | controller | "blocks": 8, 2026-09-24 00:48:29.021923 | controller | "charset": "unknown", 2026-09-24 00:48:29.021942 | controller | "ctime": 1790210891.7678432, 2026-09-24 00:48:29.021962 | controller | "dev": 65025, 2026-09-24 00:48:29.021980 | controller | "device_type": 0, 2026-09-24 00:48:29.021998 | controller | "executable": true, 2026-09-24 00:48:29.022017 | controller | "exists": true, 2026-09-24 00:48:29.022034 | controller | "gid": 1000, 2026-09-24 00:48:29.022051 | controller | "gr_name": "zuul", 2026-09-24 00:48:29.022079 | controller | "inode": 519447, 2026-09-24 00:48:29.022098 | controller | "isblk": false, 2026-09-24 00:48:29.022116 | controller | "ischr": false, 2026-09-24 00:48:29.022135 | controller | "isdir": true, 2026-09-24 00:48:29.022153 | controller | "isfifo": false, 2026-09-24 00:48:29.022173 | controller | "isgid": false, 2026-09-24 00:48:29.022189 | controller | "islnk": false, 2026-09-24 00:48:29.022209 | controller | "isreg": false, 2026-09-24 00:48:29.022226 | controller | "issock": false, 2026-09-24 00:48:29.022242 | controller | "isuid": false, 2026-09-24 00:48:29.022261 | controller | "mimetype": "unknown", 2026-09-24 00:48:29.022278 | controller | "mode": "0755", 2026-09-24 00:48:29.022298 | controller | "mtime": 1790210875.1541927, 2026-09-24 00:48:29.022317 | controller | "nlink": 14, 2026-09-24 00:48:29.022332 | controller | "path": "src/github.com/vexxhost/ansible-collection-kubernetes", 2026-09-24 00:48:29.022352 | controller | "pw_name": "zuul", 2026-09-24 00:48:29.022370 | controller | "readable": true, 2026-09-24 00:48:29.022391 | controller | "rgrp": true, 2026-09-24 00:48:29.022409 | controller | "roth": true, 2026-09-24 00:48:29.022429 | controller | "rusr": true, 2026-09-24 00:48:29.022446 | controller | "size": 4096, 2026-09-24 00:48:29.022463 | controller | "uid": 1000, 2026-09-24 00:48:29.022480 | controller | "version": "2361030095", 2026-09-24 00:48:29.022500 | controller | "wgrp": false, 2026-09-24 00:48:29.022519 | controller | "woth": false, 2026-09-24 00:48:29.022539 | controller | "writeable": true, 2026-09-24 00:48:29.022556 | controller | "wusr": true, 2026-09-24 00:48:29.022577 | controller | "xgrp": true, 2026-09-24 00:48:29.022594 | controller | "xoth": true, 2026-09-24 00:48:29.022613 | controller | "xusr": true 2026-09-24 00:48:29.022633 | controller | } 2026-09-24 00:48:29.022730 | controller | } 2026-09-24 00:48:29.022771 | controller | ok: All items complete 2026-09-24 00:48:29.022792 | 2026-09-24 00:48:29.024799 | controller | skipping: Conditional result was False 2026-09-24 00:48:29.031910 | controller | skipping: Conditional result was False 2026-09-24 00:48:29.044903 | 2026-09-24 00:48:29.045157 | LOOP [Switch "ansible_host" to private IP] 2026-09-24 00:48:29.400692 | controller | changed: 1 replacements made 2026-09-24 00:48:29.402926 | controller | skipping: Conditional result was False 2026-09-24 00:48:29.408168 | controller | skipping: Conditional result was False 2026-09-24 00:48:29.418853 | 2026-09-24 00:48:29.419032 | TASK [Run Molecule scenario] 2026-09-24 00:48:29.873422 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-09-24 00:48:29.873556 | controller | Creating virtual environment at: .venv 2026-09-24 00:48:31.212919 | controller | Downloading pygments (1.2MiB) 2026-09-24 00:48:31.214502 | controller | Downloading ansible-core (2.3MiB) 2026-09-24 00:48:31.221293 | controller | Downloading cryptography (4.3MiB) 2026-09-24 00:48:31.387095 | controller | Downloading cryptography 2026-09-24 00:48:31.448825 | controller | Downloading pygments 2026-09-24 00:48:31.643428 | controller | Downloading ansible-core 2026-09-24 00:48:31.688907 | controller | Installed 36 packages in 44ms 2026-09-24 00:48:32.340061 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-09-24 00:48:32.340141 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-09-24 00:48:33.322794 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-09-24 00:48:33.322872 | controller | INFO default scenario not found, disabling shared state. 2026-09-24 00:48:33.322898 | controller | INFO [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-24 00:48:33.322907 | controller | INFO [helm > prerun] Performing prerun with role_name_check=0... 2026-09-24 00:48:41.557762 | controller | WARNING Retrying execution failure 1 of: ansible-galaxy collection install -vvv ansible.posix:>=1.6.0 2026-09-24 00:48:41.557840 | controller | ERROR Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code: 2026-09-24 00:48:41.557850 | controller | Starting galaxy collection install process 2026-09-24 00:48:41.557858 | controller | Process install dependency map 2026-09-24 00:48:41.557867 | controller | 2026-09-24 00:48:41.557877 | controller | ansible-galaxy 2026-09-24 00:48:41.557887 | controller | config file = None 2026-09-24 00:48:41.557927 | controller | configured module search path = 2026-09-24 00:48:41.557935 | controller | ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible 2026-09-24 00:48:41.557954 | controller | ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/local/lib/python3.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages 2026-09-24 00:48:41.557963 | controller | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy 2026-09-24 00:48:41.557974 | 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-09-24 00:48:41.557981 | controller | jinja version = 3.1.6 2026-09-24 00:48:41.557989 | controller | pyyaml version = 6.0.3 (with libyaml v0.2.5) 2026-09-24 00:48:41.557998 | controller | No config file found; using defaults 2026-09-24 00:48:41.558047 | controller | Created /home/zuul/.ansible/galaxy_token 2026-09-24 00:48:41.558060 | controller | : Skipping Galaxy server https://galaxy.ansible.com. Got an unexpected error when getting available versions of collection ansible.posix: Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/': 2026-09-24 00:48:41.558070 | controller | : Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/': 2026-09-24 00:48:41.558080 | controller | 2026-09-24 00:48:41.558090 | controller | Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/'. 2026-09-24 00:48:41.558097 | controller | 2026-09-24 00:48:41.558104 | controller | <<< caused by >>> 2026-09-24 00:48:41.558111 | controller | 2026-09-24 00:48:41.558117 | controller | 2026-09-24 00:48:41.558124 | controller | 2026-09-24 00:48:41.558131 | controller | 2026-09-24 00:48:41.561104 | controller | Traceback (most recent call last): 2026-09-24 00:48:41.564612 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/molecule", line 10, in 2026-09-24 00:48:41.564651 | controller | sys.exit(main()) 2026-09-24 00:48:41.564659 | controller | ~~~~^^ 2026-09-24 00:48:41.564900 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 1485, in __call__ 2026-09-24 00:48:41.564909 | controller | return self.main(*args, **kwargs) 2026-09-24 00:48:41.564925 | controller | ~~~~~~~~~^^^^^^^^^^^^^^^^^ 2026-09-24 00:48:41.565191 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 1406, in main 2026-09-24 00:48:41.565207 | controller | rv = self.invoke(ctx) 2026-09-24 00:48:41.565582 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 1873, in invoke 2026-09-24 00:48:41.565596 | controller | return _process_result(sub_ctx.command.invoke(sub_ctx)) 2026-09-24 00:48:41.565604 | controller | ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^ 2026-09-24 00:48:41.565849 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 1269, in invoke 2026-09-24 00:48:41.565858 | controller | return ctx.invoke(self.callback, **ctx.params) 2026-09-24 00:48:41.565865 | controller | ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:48:41.566113 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 824, in invoke 2026-09-24 00:48:41.566128 | controller | return callback(*args, **kwargs) 2026-09-24 00:48:41.566349 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/click_cfg.py", line 583, in wrapper 2026-09-24 00:48:41.566359 | controller | return func(*args, **kwargs) 2026-09-24 00:48:41.566583 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/decorators.py", line 34, in new_func 2026-09-24 00:48:41.566595 | controller | return f(get_current_context(), *args, **kwargs) 2026-09-24 00:48:41.566816 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/click_cfg.py", line 418, in wrapper 2026-09-24 00:48:41.566826 | controller | return func(ctx) 2026-09-24 00:48:41.567111 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/command/test.py", line 81, in test 2026-09-24 00:48:41.567125 | controller | base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude) 2026-09-24 00:48:41.567134 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:48:41.567384 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/command/base.py", line 169, in execute_cmdline_scenarios 2026-09-24 00:48:41.567395 | controller | _run_scenarios(scenarios, command_args, default_config) 2026-09-24 00:48:41.567402 | controller | ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:48:41.567805 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/command/base.py", line 240, in _run_scenarios 2026-09-24 00:48:41.567818 | controller | scenario.config.runtime.prepare_environment( 2026-09-24 00:48:41.567824 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-24 00:48:41.567831 | controller | install_local=True, 2026-09-24 00:48:41.567838 | controller | ^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:48:41.567845 | controller | role_name_check=role_name_check, 2026-09-24 00:48:41.567852 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:48:41.567859 | controller | ) 2026-09-24 00:48:41.567866 | controller | ^ 2026-09-24 00:48:41.568239 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible_compat/runtime.py", line 752, in prepare_environment 2026-09-24 00:48:41.568252 | controller | self.install_collection( 2026-09-24 00:48:41.568261 | controller | ~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-24 00:48:41.568280 | controller | f"{name}{',' if is_url(name) else ':'}{required_version}", 2026-09-24 00:48:41.568290 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:48:41.568299 | controller | destination=destination, 2026-09-24 00:48:41.568308 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:48:41.568315 | controller | ) 2026-09-24 00:48:41.568322 | controller | ^ 2026-09-24 00:48:41.568480 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible_compat/runtime.py", line 609, in install_collection 2026-09-24 00:48:41.568489 | controller | raise InvalidPrerequisiteError(msg) 2026-09-24 00:48:41.569604 | controller | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code: 2026-09-24 00:48:41.569621 | controller | Starting galaxy collection install process 2026-09-24 00:48:41.569628 | controller | Process install dependency map 2026-09-24 00:48:41.569636 | controller | 2026-09-24 00:48:41.569643 | controller | ansible-galaxy [core 2.20.1] 2026-09-24 00:48:41.569650 | controller | config file = None 2026-09-24 00:48:41.569840 | controller | configured module search path = ['/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/plugins/modules', '/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules'] 2026-09-24 00:48:41.569861 | controller | ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible 2026-09-24 00:48:41.569869 | controller | ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/local/lib/python3.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages 2026-09-24 00:48:41.569877 | controller | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy 2026-09-24 00:48:41.569884 | 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-09-24 00:48:41.569891 | controller | jinja version = 3.1.6 2026-09-24 00:48:41.569898 | controller | pyyaml version = 6.0.3 (with libyaml v0.2.5) 2026-09-24 00:48:41.569905 | controller | No config file found; using defaults 2026-09-24 00:48:41.569912 | controller | Created /home/zuul/.ansible/galaxy_token 2026-09-24 00:48:41.569919 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com. Got an unexpected error when getting available versions of collection ansible.posix: Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/': 2026-09-24 00:48:41.569933 | controller | [ERROR]: Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/': 2026-09-24 00:48:41.569940 | controller | 2026-09-24 00:48:41.569947 | controller | Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/'. 2026-09-24 00:48:41.569953 | controller | 2026-09-24 00:48:41.569960 | controller | <<< caused by >>> 2026-09-24 00:48:41.569967 | controller | 2026-09-24 00:48:41.569974 | controller | 2026-09-24 00:48:41.569981 | controller | 2026-09-24 00:48:41.569988 | controller | 2026-09-24 00:48:41.983409 | controller | ERROR 2026-09-24 00:48:41.983600 | controller | { 2026-09-24 00:48:41.983654 | controller | "delta": "0:00:11.907802", 2026-09-24 00:48:41.983692 | controller | "end": "2026-09-24 00:48:41.672281", 2026-09-24 00:48:41.983728 | controller | "msg": "non-zero return code", 2026-09-24 00:48:41.983763 | controller | "rc": 1, 2026-09-24 00:48:41.983796 | controller | "start": "2026-09-24 00:48:29.764479" 2026-09-24 00:48:41.983830 | controller | } failure 2026-09-24 00:48:41.987879 | 2026-09-24 00:48:41.987969 | PLAY RECAP 2026-09-24 00:48:41.988077 | controller | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-24 00:48:41.988123 | 2026-09-24 00:48:42.142973 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-24 00:48:42.145668 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-09-24 00:48:42.749348 | 2026-09-24 00:48:42.749467 | PLAY [all] 2026-09-24 00:48:42.759510 | 2026-09-24 00:48:42.759594 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-09-24 00:48:43.144550 | controller | changed 2026-09-24 00:48:43.153162 | 2026-09-24 00:48:43.153329 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-09-24 00:48:43.617650 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-24 00:48:43.617739 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-24 00:48:43.624190 | controller | environment: line 1: kubectl: command not found 2026-09-24 00:48:43.625515 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-24 00:48:43.628264 | controller | environment: line 1: kubectl: command not found 2026-09-24 00:48:43.630206 | controller | environment: line 1: kubectl: command not found 2026-09-24 00:48:43.630219 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-24 00:48:43.632877 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-24 00:48:43.635650 | controller | environment: line 1: kubectl: command not found 2026-09-24 00:48:43.635686 | controller | environment: line 1: kubectl: command not found 2026-09-24 00:48:43.636798 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-24 00:48:43.637821 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-24 00:48:43.691419 | controller | ok: Runtime: 0:00:00.033521 2026-09-24 00:48:43.699071 | 2026-09-24 00:48:43.699197 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-09-24 00:48:43.951047 | controller | changed 2026-09-24 00:48:43.955825 | 2026-09-24 00:48:43.955920 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-09-24 00:48:44.221385 | controller | environment: line 5: kubectl: command not found 2026-09-24 00:48:44.222289 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-24 00:48:44.223505 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-24 00:48:44.224355 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-24 00:48:44.491584 | controller | ok: Runtime: 0:00:00.011613 2026-09-24 00:48:44.500855 | 2026-09-24 00:48:44.500987 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-09-24 00:48:45.188450 | controller | changed: 2026-09-24 00:48:45.188642 | controller | created directory /var/lib/zuul/builds/89213df3091e4d208a58293f0bfa19dc/work/logs/controller 2026-09-24 00:48:45.188680 | controller | cd+++++++++ objects/ 2026-09-24 00:48:45.188707 | controller | cd+++++++++ objects/cluster/ 2026-09-24 00:48:45.188735 | controller | cd+++++++++ objects/namespaced/ 2026-09-24 00:48:45.197204 | 2026-09-24 00:48:45.197293 | TASK [gather-pod-logs : creating directory for pod logs] 2026-09-24 00:48:45.457863 | controller | changed 2026-09-24 00:48:45.461445 | 2026-09-24 00:48:45.461508 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-09-24 00:48:45.741929 | controller | changed 2026-09-24 00:48:45.749443 | 2026-09-24 00:48:45.749599 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-09-24 00:48:46.035273 | controller | environment: line 3: kubectl: command not found 2026-09-24 00:48:46.286513 | controller | ok: Runtime: 0:00:00.014014 2026-09-24 00:48:46.294191 | 2026-09-24 00:48:46.294311 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-09-24 00:48:46.838121 | controller | changed: 2026-09-24 00:48:46.838289 | controller | cd+++++++++ pod-logs/ 2026-09-24 00:48:46.838314 | controller | cd+++++++++ pod-logs/failed-pods/ 2026-09-24 00:48:46.844304 | 2026-09-24 00:48:46.844362 | LOOP [helm-release-status : creating directory for helm release status] 2026-09-24 00:48:47.124284 | controller | changed: "values" 2026-09-24 00:48:47.351611 | controller | changed: "releases" 2026-09-24 00:48:47.367980 | 2026-09-24 00:48:47.368159 | TASK [helm-release-status : Gather get release status for helm charts] 2026-09-24 00:48:47.655031 | controller | /bin/bash: line 3: kubectl: command not found 2026-09-24 00:48:47.900890 | controller | ok: Runtime: 0:00:00.009102 2026-09-24 00:48:47.906183 | 2026-09-24 00:48:47.906308 | TASK [helm-release-status : Downloads logs to executor] 2026-09-24 00:48:48.443527 | controller | changed: 2026-09-24 00:48:48.443770 | controller | cd+++++++++ helm/ 2026-09-24 00:48:48.443825 | controller | cd+++++++++ helm/releases/ 2026-09-24 00:48:48.443871 | controller | cd+++++++++ helm/values/ 2026-09-24 00:48:48.452253 | 2026-09-24 00:48:48.452348 | PLAY RECAP 2026-09-24 00:48:48.452424 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-24 00:48:48.452483 | 2026-09-24 00:48:48.562352 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-09-24 00:48:48.565031 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-24 00:48:49.164047 | 2026-09-24 00:48:49.164172 | PLAY [all] 2026-09-24 00:48:49.173820 | 2026-09-24 00:48:49.173924 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-24 00:48:49.229238 | controller | skipping: Conditional result was False 2026-09-24 00:48:49.234967 | 2026-09-24 00:48:49.235454 | TASK [fetch-output : Set log path for single node] 2026-09-24 00:48:49.275494 | controller | ok 2026-09-24 00:48:49.282966 | 2026-09-24 00:48:49.283146 | LOOP [fetch-output : Ensure local output dirs] 2026-09-24 00:48:49.674340 | controller -> localhost | ok: "/var/lib/zuul/builds/89213df3091e4d208a58293f0bfa19dc/work/logs" 2026-09-24 00:48:49.891244 | controller -> localhost | changed: "/var/lib/zuul/builds/89213df3091e4d208a58293f0bfa19dc/work/artifacts" 2026-09-24 00:48:50.130924 | controller -> localhost | changed: "/var/lib/zuul/builds/89213df3091e4d208a58293f0bfa19dc/work/docs" 2026-09-24 00:48:50.143173 | 2026-09-24 00:48:50.143291 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-24 00:48:50.794402 | controller | changed: .d..t...... ./ 2026-09-24 00:48:50.794729 | controller | changed: All items complete 2026-09-24 00:48:50.794796 | 2026-09-24 00:48:51.320180 | controller | changed: .d..t...... ./ 2026-09-24 00:48:51.801263 | controller | changed: .d..t...... ./ 2026-09-24 00:48:51.824200 | 2026-09-24 00:48:51.824335 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-24 00:48:52.245666 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006461 2026-09-24 00:48:52.477758 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007030 2026-09-24 00:48:52.494559 | 2026-09-24 00:48:52.496604 | PLAY [all] 2026-09-24 00:48:52.502382 | 2026-09-24 00:48:52.502460 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-24 00:48:52.968780 | controller | changed 2026-09-24 00:48:52.977858 | 2026-09-24 00:48:52.977957 | PLAY RECAP 2026-09-24 00:48:52.978065 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-24 00:48:52.978113 | 2026-09-24 00:48:53.100542 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-24 00:48:53.104437 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-24 00:48:53.695928 | 2026-09-24 00:48:53.709041 | PLAY [localhost] 2026-09-24 00:48:53.719157 | 2026-09-24 00:48:53.719236 | TASK [Generate Zuul manifest] 2026-09-24 00:48:53.738242 | localhost | ok 2026-09-24 00:48:53.752972 | 2026-09-24 00:48:53.753089 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-24 00:48:54.278102 | localhost | changed 2026-09-24 00:48:54.299754 | 2026-09-24 00:48:54.302731 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-24 00:48:54.337271 | localhost | ok 2026-09-24 00:48:54.351813 | 2026-09-24 00:48:54.351991 | TASK [Upload logs] 2026-09-24 00:48:54.373706 | localhost | ok 2026-09-24 00:48:54.451196 | 2026-09-24 00:48:54.451338 | TASK [Set zuul-log-path fact] 2026-09-24 00:48:54.468916 | localhost | ok 2026-09-24 00:48:54.479123 | 2026-09-24 00:48:54.479238 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-24 00:48:54.507654 | localhost | ok 2026-09-24 00:48:54.517670 | 2026-09-24 00:48:54.517765 | TASK [upload-logs : Create log directories] 2026-09-24 00:48:54.901724 | localhost | changed 2026-09-24 00:48:54.907507 | 2026-09-24 00:48:54.907601 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-24 00:48:55.282338 | localhost -> localhost | ok: Runtime: 0:00:00.004766 2026-09-24 00:48:55.291460 | 2026-09-24 00:48:55.291592 | TASK [upload-logs : Upload logs to log server] 2026-09-24 00:48:55.790326 | localhost | Output suppressed because no_log was given 2026-09-24 00:48:55.800573 | 2026-09-24 00:48:55.800714 | LOOP [upload-logs : Compress console log and json output] 2026-09-24 00:48:55.865331 | localhost | skipping: Conditional result was False 2026-09-24 00:48:55.909061 | localhost | skipping: Conditional result was False 2026-09-24 00:48:55.925529 | 2026-09-24 00:48:56.018654 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-24 00:48:56.058357 | localhost | skipping: Conditional result was False 2026-09-24 00:48:56.058717 | 2026-09-24 00:48:56.061917 | localhost | skipping: Conditional result was False 2026-09-24 00:48:56.072387 | 2026-09-24 00:48:56.072571 | LOOP [upload-logs : Upload console log and json output]