2026-09-24 00:46:47.077115 | Job console starting 2026-09-24 00:46:47.093583 | Updating git repos 2026-09-24 00:46:47.143592 | Cloning repos into workspace 2026-09-24 00:46:47.200150 | Restoring repo states 2026-09-24 00:46:47.214761 | Merging changes 2026-09-24 00:46:48.026750 | Checking out repos 2026-09-24 00:46:48.279295 | Preparing playbooks 2026-09-24 00:46:50.665317 | Running Ansible setup 2026-09-24 00:46:54.308558 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-24 00:46:54.843933 | 2026-09-24 00:46:54.844056 | PLAY [localhost] 2026-09-24 00:46:54.851148 | 2026-09-24 00:46:54.851225 | TASK [Gathering Facts] 2026-09-24 00:46:55.756914 | localhost | ok 2026-09-24 00:46:55.765542 | 2026-09-24 00:46:55.765637 | TASK [Setup log path fact] 2026-09-24 00:46:55.785089 | localhost | ok 2026-09-24 00:46:55.802850 | 2026-09-24 00:46:55.802962 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-24 00:46:55.837336 | localhost | ok 2026-09-24 00:46:55.845618 | 2026-09-24 00:46:55.845706 | TASK [emit-job-header : Print job information] 2026-09-24 00:46:55.878776 | # Job Information 2026-09-24 00:46:55.878934 | Ansible Version: 2.16.18 2026-09-24 00:46:55.878974 | Job: ansible-collection-kubernetes-molecule-download-binaries 2026-09-24 00:46:55.879000 | Pipeline: check 2026-09-24 00:46:55.879025 | Executor: 2d72f0692154 2026-09-24 00:46:55.879048 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/293 2026-09-24 00:46:55.879075 | Event ID: e533a450-b7b0-11f1-98be-9e8d6b6260a8 2026-09-24 00:46:55.887850 | 2026-09-24 00:46:55.887980 | LOOP [emit-job-header : Print node information] 2026-09-24 00:46:55.993831 | localhost | ok: 2026-09-24 00:46:55.994664 | localhost | # Node Information 2026-09-24 00:46:55.994741 | localhost | Inventory Hostname: controller 2026-09-24 00:46:55.994789 | localhost | Hostname: np0000193841 2026-09-24 00:46:55.994828 | localhost | Username: zuul 2026-09-24 00:46:55.994872 | localhost | Distro: Debian 13.7 2026-09-24 00:46:55.994911 | localhost | Provider: yul1 2026-09-24 00:46:55.994948 | localhost | Region: ca-ymq-1 2026-09-24 00:46:55.994983 | localhost | Label: debian-trixie 2026-09-24 00:46:55.995019 | localhost | Product Name: OpenStack Nova 2026-09-24 00:46:55.995055 | localhost | Interface IP: 162.253.55.115 2026-09-24 00:46:56.015451 | 2026-09-24 00:46:56.015626 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-24 00:46:56.406358 | localhost -> localhost | changed 2026-09-24 00:46:56.415494 | 2026-09-24 00:46:56.415627 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-24 00:46:57.301419 | localhost -> localhost | changed 2026-09-24 00:46:57.312253 | 2026-09-24 00:46:57.312366 | PLAY [all] 2026-09-24 00:46:57.324558 | 2026-09-24 00:46:57.324683 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-24 00:46:57.561676 | controller -> localhost | ok 2026-09-24 00:46:57.570511 | 2026-09-24 00:46:57.570638 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-24 00:46:57.601432 | controller | ok 2026-09-24 00:46:57.618995 | controller | included: /var/lib/zuul/builds/fb0274beb5bf4e78839d082467de213d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-24 00:46:57.624759 | 2026-09-24 00:46:57.624840 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-24 00:46:58.487009 | controller -> localhost | Generating public/private rsa key pair. 2026-09-24 00:46:58.487286 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/fb0274beb5bf4e78839d082467de213d/work/fb0274beb5bf4e78839d082467de213d_id_rsa 2026-09-24 00:46:58.487346 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/fb0274beb5bf4e78839d082467de213d/work/fb0274beb5bf4e78839d082467de213d_id_rsa.pub 2026-09-24 00:46:58.487389 | controller -> localhost | The key fingerprint is: 2026-09-24 00:46:58.487430 | controller -> localhost | SHA256:SyCG65Gonqi2iZW+yGXW7Ix6Fs2/3S9rVGDTHDLgL8Y zuul-build-sshkey 2026-09-24 00:46:58.487489 | controller -> localhost | The key's randomart image is: 2026-09-24 00:46:58.487530 | controller -> localhost | +---[RSA 3072]----+ 2026-09-24 00:46:58.487583 | controller -> localhost | | ..oo.. | 2026-09-24 00:46:58.487625 | controller -> localhost | | . . +oo | 2026-09-24 00:46:58.487663 | controller -> localhost | | . o . .. o | 2026-09-24 00:46:58.487701 | controller -> localhost | | . + . .. . . | 2026-09-24 00:46:58.487739 | controller -> localhost | |. + o SE .. | 2026-09-24 00:46:58.487775 | controller -> localhost | |.. +oo ..... | 2026-09-24 00:46:58.487812 | controller -> localhost | |. ++.o. . . | 2026-09-24 00:46:58.487849 | controller -> localhost | |==*o+ .. .o | 2026-09-24 00:46:58.487892 | controller -> localhost | |BBBo o ....o+. | 2026-09-24 00:46:58.487931 | controller -> localhost | +----[SHA256]-----+ 2026-09-24 00:46:58.488018 | controller -> localhost | ok: Runtime: 0:00:00.418149 2026-09-24 00:46:58.496099 | 2026-09-24 00:46:58.496241 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-24 00:46:58.527252 | controller | ok 2026-09-24 00:46:58.541720 | controller | included: /var/lib/zuul/builds/fb0274beb5bf4e78839d082467de213d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-24 00:46:58.554336 | 2026-09-24 00:46:58.554415 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-24 00:46:58.579323 | controller | skipping: Conditional result was False 2026-09-24 00:46:58.589368 | 2026-09-24 00:46:58.589520 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-24 00:46:59.121968 | controller | changed 2026-09-24 00:46:59.132408 | 2026-09-24 00:46:59.132591 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-24 00:46:59.388375 | controller | ok 2026-09-24 00:46:59.396115 | 2026-09-24 00:46:59.396266 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-24 00:46:59.965018 | controller | changed 2026-09-24 00:46:59.972815 | 2026-09-24 00:46:59.972940 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-24 00:47:00.572421 | controller | changed 2026-09-24 00:47:00.580447 | 2026-09-24 00:47:00.580627 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-24 00:47:00.595539 | controller | skipping: Conditional result was False 2026-09-24 00:47:00.604090 | 2026-09-24 00:47:00.604249 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-24 00:47:00.973492 | controller -> localhost | changed 2026-09-24 00:47:00.990898 | 2026-09-24 00:47:00.991025 | TASK [add-build-sshkey : Add back temp key] 2026-09-24 00:47:01.292018 | controller -> localhost | Identity added: /var/lib/zuul/builds/fb0274beb5bf4e78839d082467de213d/work/fb0274beb5bf4e78839d082467de213d_id_rsa (zuul-build-sshkey) 2026-09-24 00:47:01.292354 | controller -> localhost | ok: Runtime: 0:00:00.014754 2026-09-24 00:47:01.300482 | 2026-09-24 00:47:01.300642 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-24 00:47:01.656609 | controller | ok 2026-09-24 00:47:01.663938 | 2026-09-24 00:47:01.664062 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-24 00:47:01.699204 | controller | skipping: Conditional result was False 2026-09-24 00:47:01.713873 | 2026-09-24 00:47:01.713999 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-24 00:47:02.080781 | controller | ok 2026-09-24 00:47:02.089510 | 2026-09-24 00:47:02.089664 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-24 00:47:03.127740 | controller | Output suppressed because no_log was given 2026-09-24 00:47:03.155442 | 2026-09-24 00:47:03.155609 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-24 00:47:03.399220 | controller | ok: "logs" 2026-09-24 00:47:03.399520 | controller | ok: All items complete 2026-09-24 00:47:03.399567 | 2026-09-24 00:47:03.605425 | controller | ok: "artifacts" 2026-09-24 00:47:03.820010 | controller | ok: "docs" 2026-09-24 00:47:03.842114 | 2026-09-24 00:47:03.842390 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-24 00:47:04.099781 | controller | changed: "logs" 2026-09-24 00:47:04.318014 | controller | changed: "artifacts" 2026-09-24 00:47:04.524011 | controller | changed: "docs" 2026-09-24 00:47:04.534714 | 2026-09-24 00:47:04.534847 | PLAY RECAP 2026-09-24 00:47:04.534910 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-24 00:47:04.534946 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-24 00:47:04.534973 | 2026-09-24 00:47:04.670559 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-24 00:47:04.674514 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-24 00:47:05.223380 | 2026-09-24 00:47:05.223505 | PLAY [all] 2026-09-24 00:47:05.232347 | 2026-09-24 00:47:05.232421 | TASK [setup-uv : Extract archive] 2026-09-24 00:47:08.614488 | controller | changed 2026-09-24 00:47:08.623877 | 2026-09-24 00:47:08.624041 | TASK [setup-uv : Print version] 2026-09-24 00:47:09.035735 | controller | uv 0.8.13 2026-09-24 00:47:09.163967 | controller | ok: Runtime: 0:00:00.016979 2026-09-24 00:47:09.170494 | 2026-09-24 00:47:09.170582 | TASK [Install Ansible collection siblings] 2026-09-24 00:47:09.579133 | controller | ok: 2026-09-24 00:47:09.579438 | controller | Skipping ansible.posix: no checked-out sibling 2026-09-24 00:47:09.579496 | controller | Skipping community.crypto: no checked-out sibling 2026-09-24 00:47:09.579540 | controller | Skipping community.general: no checked-out sibling 2026-09-24 00:47:09.579581 | controller | Skipping kubernetes.core: no checked-out sibling 2026-09-24 00:47:09.579620 | controller | Skipping vexxhost.containers: no checked-out sibling 2026-09-24 00:47:09.583202 | 2026-09-24 00:47:09.583302 | PLAY RECAP 2026-09-24 00:47:09.583395 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-24 00:47:09.583438 | 2026-09-24 00:47:09.734567 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-24 00:47:09.737111 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-09-24 00:47:10.313102 | 2026-09-24 00:47:10.313253 | PLAY [all] 2026-09-24 00:47:10.322772 | 2026-09-24 00:47:10.322841 | TASK [Update apt cache] 2026-09-24 00:47:16.247649 | controller | ok 2026-09-24 00:47:16.255349 | 2026-09-24 00:47:16.255492 | TASK [Disable firewalld] 2026-09-24 00:47:16.280467 | controller | skipping: Conditional result was False 2026-09-24 00:47:16.288963 | 2026-09-24 00:47:16.289095 | TASK [Install "jq" for log collection] 2026-09-24 00:47:18.972587 | controller | changed 2026-09-24 00:47:18.975505 | 2026-09-24 00:47:18.975596 | PLAY RECAP 2026-09-24 00:47:18.975674 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-24 00:47:18.975714 | 2026-09-24 00:47:19.099761 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-09-24 00:47:19.104233 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-24 00:47:19.644219 | 2026-09-24 00:47:19.644337 | PLAY [all] 2026-09-24 00:47:19.653114 | 2026-09-24 00:47:19.653197 | LOOP [Check inventory directories] 2026-09-24 00:47:20.058910 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes" 2026-09-24 00:47:20.255550 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/download-binaries/inventory" 2026-09-24 00:47:20.466186 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/download-binaries/inventory" 2026-09-24 00:47:20.477254 | 2026-09-24 00:47:20.477508 | LOOP [Copy inventory file for Zuul] 2026-09-24 00:47:21.423890 | controller | changed: 2026-09-24 00:47:21.424168 | controller | { 2026-09-24 00:47:21.424225 | controller | "ansible_loop_var": "item", 2026-09-24 00:47:21.424269 | controller | "changed": false, 2026-09-24 00:47:21.424308 | controller | "failed": false, 2026-09-24 00:47:21.424351 | controller | "invocation": { 2026-09-24 00:47:21.424388 | controller | "module_args": { 2026-09-24 00:47:21.424424 | controller | "checksum_algorithm": "sha1", 2026-09-24 00:47:21.424459 | controller | "follow": true, 2026-09-24 00:47:21.424522 | controller | "get_attributes": true, 2026-09-24 00:47:21.424564 | controller | "get_checksum": true, 2026-09-24 00:47:21.424601 | controller | "get_mime": true, 2026-09-24 00:47:21.424642 | controller | "path": "src/github.com/vexxhost/ansible-collection-kubernetes" 2026-09-24 00:47:21.424677 | controller | } 2026-09-24 00:47:21.424712 | controller | }, 2026-09-24 00:47:21.424747 | controller | "item": "src/github.com/vexxhost/ansible-collection-kubernetes", 2026-09-24 00:47:21.424782 | controller | "stat": { 2026-09-24 00:47:21.424818 | controller | "atime": 1790210822.9629025, 2026-09-24 00:47:21.424853 | controller | "attr_flags": "e", 2026-09-24 00:47:21.424888 | controller | "attributes": [ 2026-09-24 00:47:21.424923 | controller | "extents" 2026-09-24 00:47:21.424957 | controller | ], 2026-09-24 00:47:21.424992 | controller | "block_size": 4096, 2026-09-24 00:47:21.425026 | controller | "blocks": 8, 2026-09-24 00:47:21.425060 | controller | "charset": "unknown", 2026-09-24 00:47:21.425098 | controller | "ctime": 1790210823.0029047, 2026-09-24 00:47:21.425154 | controller | "dev": 65025, 2026-09-24 00:47:21.425191 | controller | "device_type": 0, 2026-09-24 00:47:21.425225 | controller | "executable": true, 2026-09-24 00:47:21.425260 | controller | "exists": true, 2026-09-24 00:47:21.425295 | controller | "gid": 1000, 2026-09-24 00:47:21.425330 | controller | "gr_name": "zuul", 2026-09-24 00:47:21.425364 | controller | "inode": 519447, 2026-09-24 00:47:21.425398 | controller | "isblk": false, 2026-09-24 00:47:21.425435 | controller | "ischr": false, 2026-09-24 00:47:21.425469 | controller | "isdir": true, 2026-09-24 00:47:21.425504 | controller | "isfifo": false, 2026-09-24 00:47:21.425538 | controller | "isgid": false, 2026-09-24 00:47:21.425572 | controller | "islnk": false, 2026-09-24 00:47:21.425606 | controller | "isreg": false, 2026-09-24 00:47:21.425640 | controller | "issock": false, 2026-09-24 00:47:21.425673 | controller | "isuid": false, 2026-09-24 00:47:21.425707 | controller | "mimetype": "unknown", 2026-09-24 00:47:21.425740 | controller | "mode": "0755", 2026-09-24 00:47:21.425772 | controller | "mtime": 1790210807.3366992, 2026-09-24 00:47:21.425808 | controller | "nlink": 14, 2026-09-24 00:47:21.425840 | controller | "path": "src/github.com/vexxhost/ansible-collection-kubernetes", 2026-09-24 00:47:21.425874 | controller | "pw_name": "zuul", 2026-09-24 00:47:21.425913 | controller | "readable": true, 2026-09-24 00:47:21.425948 | controller | "rgrp": true, 2026-09-24 00:47:21.425983 | controller | "roth": true, 2026-09-24 00:47:21.426018 | controller | "rusr": true, 2026-09-24 00:47:21.426052 | controller | "size": 4096, 2026-09-24 00:47:21.426085 | controller | "uid": 1000, 2026-09-24 00:47:21.426119 | controller | "version": "2837984919", 2026-09-24 00:47:21.426172 | controller | "wgrp": false, 2026-09-24 00:47:21.426209 | controller | "woth": false, 2026-09-24 00:47:21.426243 | controller | "writeable": true, 2026-09-24 00:47:21.426278 | controller | "wusr": true, 2026-09-24 00:47:21.426312 | controller | "xgrp": true, 2026-09-24 00:47:21.426346 | controller | "xoth": true, 2026-09-24 00:47:21.426380 | controller | "xusr": true 2026-09-24 00:47:21.426432 | controller | } 2026-09-24 00:47:21.426467 | controller | } 2026-09-24 00:47:21.426527 | controller | ok: All items complete 2026-09-24 00:47:21.426569 | 2026-09-24 00:47:21.428848 | controller | skipping: Conditional result was False 2026-09-24 00:47:21.430468 | controller | skipping: Conditional result was False 2026-09-24 00:47:21.449054 | 2026-09-24 00:47:21.449331 | LOOP [Switch "ansible_host" to private IP] 2026-09-24 00:47:21.831041 | controller | changed: 1 replacements made 2026-09-24 00:47:21.833383 | controller | skipping: Conditional result was False 2026-09-24 00:47:21.838908 | controller | skipping: Conditional result was False 2026-09-24 00:47:21.850911 | 2026-09-24 00:47:21.851193 | TASK [Run Molecule scenario] 2026-09-24 00:47:22.346466 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-09-24 00:47:22.346595 | controller | Creating virtual environment at: .venv 2026-09-24 00:47:23.766709 | controller | Downloading pygments (1.2MiB) 2026-09-24 00:47:23.770828 | controller | Downloading cryptography (4.3MiB) 2026-09-24 00:47:23.775443 | controller | Downloading ansible-core (2.3MiB) 2026-09-24 00:47:23.957407 | controller | Downloading cryptography 2026-09-24 00:47:24.013050 | controller | Downloading pygments 2026-09-24 00:47:24.165466 | controller | Downloading ansible-core 2026-09-24 00:47:24.234375 | controller | Installed 36 packages in 68ms 2026-09-24 00:47:24.902175 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-09-24 00:47:24.902253 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-09-24 00:47:25.794024 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-09-24 00:47:25.794093 | controller | INFO default scenario not found, disabling shared state. 2026-09-24 00:47:25.794115 | controller | INFO [download-binaries > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-24 00:47:25.794187 | controller | INFO [download-binaries > prerun] Performing prerun with role_name_check=0... 2026-09-24 00:47:28.366528 | controller | WARNING Retrying execution failure 1 of: ansible-galaxy collection install -vvv ansible.posix:>=1.6.0 2026-09-24 00:47:28.366693 | controller | ERROR Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code: 2026-09-24 00:47:28.366705 | controller | Starting galaxy collection install process 2026-09-24 00:47:28.366714 | controller | Process install dependency map 2026-09-24 00:47:28.366723 | controller | 2026-09-24 00:47:28.366731 | controller | ansible-galaxy 2026-09-24 00:47:28.366739 | controller | config file = None 2026-09-24 00:47:28.366747 | controller | configured module search path = 2026-09-24 00:47:28.366755 | 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:47:28.366766 | 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:47:28.366776 | controller | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy 2026-09-24 00:47:28.366786 | 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:47:28.366794 | controller | jinja version = 3.1.6 2026-09-24 00:47:28.366802 | controller | pyyaml version = 6.0.3 (with libyaml v0.2.5) 2026-09-24 00:47:28.366809 | controller | No config file found; using defaults 2026-09-24 00:47:28.366817 | controller | Created /home/zuul/.ansible/galaxy_token 2026-09-24 00:47:28.366825 | 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:47:28.366834 | controller | : Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/': 2026-09-24 00:47:28.366842 | controller | 2026-09-24 00:47:28.366850 | controller | Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/'. 2026-09-24 00:47:28.366857 | controller | 2026-09-24 00:47:28.366864 | controller | <<< caused by >>> 2026-09-24 00:47:28.366871 | controller | 2026-09-24 00:47:28.366879 | controller | 2026-09-24 00:47:28.366885 | controller | 2026-09-24 00:47:28.366892 | controller | 2026-09-24 00:47:28.370648 | controller | Traceback (most recent call last): 2026-09-24 00:47:28.374078 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/molecule", line 10, in 2026-09-24 00:47:28.374120 | controller | sys.exit(main()) 2026-09-24 00:47:28.374129 | controller | ~~~~^^ 2026-09-24 00:47:28.374367 | 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:47:28.374377 | controller | return self.main(*args, **kwargs) 2026-09-24 00:47:28.374385 | controller | ~~~~~~~~~^^^^^^^^^^^^^^^^^ 2026-09-24 00:47:28.374705 | 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:47:28.374716 | controller | rv = self.invoke(ctx) 2026-09-24 00:47:28.375048 | 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:47:28.375071 | controller | return _process_result(sub_ctx.command.invoke(sub_ctx)) 2026-09-24 00:47:28.375080 | controller | ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^ 2026-09-24 00:47:28.375358 | 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:47:28.375381 | controller | return ctx.invoke(self.callback, **ctx.params) 2026-09-24 00:47:28.375390 | controller | ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:47:28.375655 | 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:47:28.375680 | controller | return callback(*args, **kwargs) 2026-09-24 00:47:28.375901 | 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:47:28.375924 | controller | return func(*args, **kwargs) 2026-09-24 00:47:28.376145 | 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:47:28.376168 | controller | return f(get_current_context(), *args, **kwargs) 2026-09-24 00:47:28.376381 | 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:47:28.376404 | controller | return func(ctx) 2026-09-24 00:47:28.376654 | 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:47:28.376664 | controller | base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude) 2026-09-24 00:47:28.376673 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:47:28.376935 | 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:47:28.376944 | controller | _run_scenarios(scenarios, command_args, default_config) 2026-09-24 00:47:28.376951 | controller | ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:47:28.377368 | 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:47:28.377376 | controller | scenario.config.runtime.prepare_environment( 2026-09-24 00:47:28.377384 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-24 00:47:28.377391 | controller | install_local=True, 2026-09-24 00:47:28.377398 | controller | ^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:47:28.377405 | controller | role_name_check=role_name_check, 2026-09-24 00:47:28.377412 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:47:28.377420 | controller | ) 2026-09-24 00:47:28.377427 | controller | ^ 2026-09-24 00:47:28.377819 | 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:47:28.377827 | controller | self.install_collection( 2026-09-24 00:47:28.377834 | controller | ~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-24 00:47:28.377850 | controller | f"{name}{',' if is_url(name) else ':'}{required_version}", 2026-09-24 00:47:28.377858 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:47:28.377865 | controller | destination=destination, 2026-09-24 00:47:28.377872 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:47:28.377879 | controller | ) 2026-09-24 00:47:28.377886 | controller | ^ 2026-09-24 00:47:28.378141 | 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:47:28.378149 | controller | raise InvalidPrerequisiteError(msg) 2026-09-24 00:47:28.379593 | controller | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code: 2026-09-24 00:47:28.379617 | controller | Starting galaxy collection install process 2026-09-24 00:47:28.379626 | controller | Process install dependency map 2026-09-24 00:47:28.379634 | controller | 2026-09-24 00:47:28.379642 | controller | ansible-galaxy [core 2.20.1] 2026-09-24 00:47:28.379649 | controller | config file = None 2026-09-24 00:47:28.379656 | 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:47:28.379672 | 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:47:28.379688 | 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:47:28.379696 | controller | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy 2026-09-24 00:47:28.379704 | 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:47:28.379711 | controller | jinja version = 3.1.6 2026-09-24 00:47:28.379718 | controller | pyyaml version = 6.0.3 (with libyaml v0.2.5) 2026-09-24 00:47:28.379725 | controller | No config file found; using defaults 2026-09-24 00:47:28.379732 | controller | Created /home/zuul/.ansible/galaxy_token 2026-09-24 00:47:28.379740 | 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:47:28.379752 | controller | [ERROR]: Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/': 2026-09-24 00:47:28.379759 | controller | 2026-09-24 00:47:28.379767 | controller | Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/'. 2026-09-24 00:47:28.379774 | controller | 2026-09-24 00:47:28.379781 | controller | <<< caused by >>> 2026-09-24 00:47:28.379788 | controller | 2026-09-24 00:47:28.379795 | controller | 2026-09-24 00:47:28.379802 | controller | 2026-09-24 00:47:28.379809 | controller | 2026-09-24 00:47:28.896190 | controller | ERROR 2026-09-24 00:47:28.896457 | controller | { 2026-09-24 00:47:28.896549 | controller | "delta": "0:00:06.242571", 2026-09-24 00:47:28.896608 | controller | "end": "2026-09-24 00:47:28.482408", 2026-09-24 00:47:28.896645 | controller | "msg": "non-zero return code", 2026-09-24 00:47:28.896679 | controller | "rc": 1, 2026-09-24 00:47:28.896711 | controller | "start": "2026-09-24 00:47:22.239837" 2026-09-24 00:47:28.896743 | controller | } failure 2026-09-24 00:47:28.901007 | 2026-09-24 00:47:28.901100 | PLAY RECAP 2026-09-24 00:47:28.901215 | controller | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-24 00:47:28.901257 | 2026-09-24 00:47:29.029921 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-24 00:47:29.032809 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-09-24 00:47:29.588108 | 2026-09-24 00:47:29.588273 | PLAY [all] 2026-09-24 00:47:29.598167 | 2026-09-24 00:47:29.598242 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-09-24 00:47:29.992946 | controller | changed 2026-09-24 00:47:30.001675 | 2026-09-24 00:47:30.028772 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-09-24 00:47:30.445443 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-24 00:47:30.445567 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-24 00:47:30.453946 | controller | environment: line 1: kubectl: command not found 2026-09-24 00:47:30.456095 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-24 00:47:30.457517 | controller | environment: line 1: kubectl: command not found 2026-09-24 00:47:30.459868 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-24 00:47:30.461806 | controller | environment: line 1: kubectl: command not found 2026-09-24 00:47:30.463712 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-24 00:47:30.465675 | controller | environment: line 1: kubectl: command not found 2026-09-24 00:47:30.467536 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-24 00:47:30.469204 | controller | environment: line 1: kubectl: command not found 2026-09-24 00:47:30.471255 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-24 00:47:30.569955 | controller | ok: Runtime: 0:00:00.041061 2026-09-24 00:47:30.578064 | 2026-09-24 00:47:30.578220 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-09-24 00:47:30.853020 | controller | changed 2026-09-24 00:47:30.860650 | 2026-09-24 00:47:30.860795 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-09-24 00:47:31.154757 | controller | environment: line 5: kubectl: command not found 2026-09-24 00:47:31.156438 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-24 00:47:31.156895 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-24 00:47:31.156932 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-24 00:47:31.399559 | controller | ok: Runtime: 0:00:00.014987 2026-09-24 00:47:31.408404 | 2026-09-24 00:47:31.408554 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-09-24 00:47:32.077746 | controller | changed: 2026-09-24 00:47:32.077984 | controller | created directory /var/lib/zuul/builds/fb0274beb5bf4e78839d082467de213d/work/logs/controller 2026-09-24 00:47:32.078043 | controller | cd+++++++++ objects/ 2026-09-24 00:47:32.078087 | controller | cd+++++++++ objects/cluster/ 2026-09-24 00:47:32.078151 | controller | cd+++++++++ objects/namespaced/ 2026-09-24 00:47:32.091013 | 2026-09-24 00:47:32.091155 | TASK [gather-pod-logs : creating directory for pod logs] 2026-09-24 00:47:32.346187 | controller | changed 2026-09-24 00:47:32.353305 | 2026-09-24 00:47:32.353432 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-09-24 00:47:32.603719 | controller | changed 2026-09-24 00:47:32.607150 | 2026-09-24 00:47:32.607212 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-09-24 00:47:32.879882 | controller | environment: line 3: kubectl: command not found 2026-09-24 00:47:33.131064 | controller | ok: Runtime: 0:00:00.013899 2026-09-24 00:47:33.138642 | 2026-09-24 00:47:33.138762 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-09-24 00:47:33.665894 | controller | changed: 2026-09-24 00:47:33.666171 | controller | cd+++++++++ pod-logs/ 2026-09-24 00:47:33.666232 | controller | cd+++++++++ pod-logs/failed-pods/ 2026-09-24 00:47:33.680246 | 2026-09-24 00:47:33.680379 | LOOP [helm-release-status : creating directory for helm release status] 2026-09-24 00:47:33.945136 | controller | changed: "values" 2026-09-24 00:47:34.170067 | controller | changed: "releases" 2026-09-24 00:47:34.179228 | 2026-09-24 00:47:34.179378 | TASK [helm-release-status : Gather get release status for helm charts] 2026-09-24 00:47:34.461852 | controller | /bin/bash: line 3: kubectl: command not found 2026-09-24 00:47:34.719763 | controller | ok: Runtime: 0:00:00.011487 2026-09-24 00:47:34.727048 | 2026-09-24 00:47:34.727199 | TASK [helm-release-status : Downloads logs to executor] 2026-09-24 00:47:35.305650 | controller | changed: 2026-09-24 00:47:35.305951 | controller | cd+++++++++ helm/ 2026-09-24 00:47:35.306008 | controller | cd+++++++++ helm/releases/ 2026-09-24 00:47:35.306055 | controller | cd+++++++++ helm/values/ 2026-09-24 00:47:35.313796 | 2026-09-24 00:47:35.313859 | PLAY RECAP 2026-09-24 00:47:35.313917 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-24 00:47:35.313962 | 2026-09-24 00:47:35.454683 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-09-24 00:47:35.456706 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-24 00:47:36.001932 | 2026-09-24 00:47:36.002053 | PLAY [all] 2026-09-24 00:47:36.010960 | 2026-09-24 00:47:36.011030 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-24 00:47:36.074910 | controller | skipping: Conditional result was False 2026-09-24 00:47:36.079330 | 2026-09-24 00:47:36.079440 | TASK [fetch-output : Set log path for single node] 2026-09-24 00:47:36.120922 | controller | ok 2026-09-24 00:47:36.127851 | 2026-09-24 00:47:36.127986 | LOOP [fetch-output : Ensure local output dirs] 2026-09-24 00:47:36.569572 | controller -> localhost | ok: "/var/lib/zuul/builds/fb0274beb5bf4e78839d082467de213d/work/logs" 2026-09-24 00:47:36.791709 | controller -> localhost | changed: "/var/lib/zuul/builds/fb0274beb5bf4e78839d082467de213d/work/artifacts" 2026-09-24 00:47:37.019340 | controller -> localhost | changed: "/var/lib/zuul/builds/fb0274beb5bf4e78839d082467de213d/work/docs" 2026-09-24 00:47:37.038666 | 2026-09-24 00:47:37.038858 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-24 00:47:37.795256 | controller | changed: .d..t...... ./ 2026-09-24 00:47:37.795617 | controller | changed: All items complete 2026-09-24 00:47:37.795678 | 2026-09-24 00:47:38.315198 | controller | changed: .d..t...... ./ 2026-09-24 00:47:38.835937 | controller | changed: .d..t...... ./ 2026-09-24 00:47:38.858952 | 2026-09-24 00:47:38.859230 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-24 00:47:39.295150 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008059 2026-09-24 00:47:39.592162 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009114 2026-09-24 00:47:39.603165 | 2026-09-24 00:47:39.603359 | PLAY [all] 2026-09-24 00:47:39.609744 | 2026-09-24 00:47:39.609824 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-24 00:47:40.076015 | controller | changed 2026-09-24 00:47:40.086483 | 2026-09-24 00:47:40.140268 | PLAY RECAP 2026-09-24 00:47:40.140484 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-24 00:47:40.140558 | 2026-09-24 00:47:40.261444 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-24 00:47:40.264580 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-24 00:47:40.863074 | 2026-09-24 00:47:40.863241 | PLAY [localhost] 2026-09-24 00:47:40.872651 | 2026-09-24 00:47:40.872719 | TASK [Generate Zuul manifest] 2026-09-24 00:47:40.889227 | localhost | ok 2026-09-24 00:47:40.901747 | 2026-09-24 00:47:40.901818 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-24 00:47:41.245960 | localhost | changed 2026-09-24 00:47:41.266902 | 2026-09-24 00:47:41.267044 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-24 00:47:41.300354 | localhost | ok 2026-09-24 00:47:41.313307 | 2026-09-24 00:47:41.313432 | TASK [Upload logs] 2026-09-24 00:47:41.334085 | localhost | ok 2026-09-24 00:47:41.400918 | 2026-09-24 00:47:41.401025 | TASK [Set zuul-log-path fact] 2026-09-24 00:47:41.418131 | localhost | ok 2026-09-24 00:47:41.431338 | 2026-09-24 00:47:41.431425 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-24 00:47:41.460200 | localhost | ok 2026-09-24 00:47:41.469266 | 2026-09-24 00:47:41.469351 | TASK [upload-logs : Create log directories] 2026-09-24 00:47:41.871191 | localhost | changed 2026-09-24 00:47:41.877056 | 2026-09-24 00:47:41.877230 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-24 00:47:42.304148 | localhost -> localhost | ok: Runtime: 0:00:00.003305 2026-09-24 00:47:42.313226 | 2026-09-24 00:47:42.313357 | TASK [upload-logs : Upload logs to log server] 2026-09-24 00:47:42.801409 | localhost | Output suppressed because no_log was given 2026-09-24 00:47:42.808808 | 2026-09-24 00:47:42.808941 | LOOP [upload-logs : Compress console log and json output] 2026-09-24 00:47:42.868466 | localhost | skipping: Conditional result was False 2026-09-24 00:47:42.913136 | localhost | skipping: Conditional result was False 2026-09-24 00:47:42.922772 | 2026-09-24 00:47:42.923028 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-24 00:47:42.983669 | localhost | skipping: Conditional result was False 2026-09-24 00:47:42.984042 | 2026-09-24 00:47:42.989765 | localhost | skipping: Conditional result was False 2026-09-24 00:47:43.006518 | 2026-09-24 00:47:43.006678 | LOOP [upload-logs : Upload console log and json output]