2026-09-24 00:47:55.267405 | Job console starting 2026-09-24 00:47:55.290553 | Updating git repos 2026-09-24 00:47:55.433358 | Cloning repos into workspace 2026-09-24 00:47:55.499598 | Restoring repo states 2026-09-24 00:47:55.558022 | Merging changes 2026-09-24 00:47:56.362921 | Checking out repos 2026-09-24 00:47:56.412693 | Preparing playbooks 2026-09-24 00:47:59.061597 | Running Ansible setup 2026-09-24 00:48:02.817643 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-24 00:48:03.394418 | 2026-09-24 00:48:03.394535 | PLAY [localhost] 2026-09-24 00:48:03.401162 | 2026-09-24 00:48:03.401226 | TASK [Gathering Facts] 2026-09-24 00:48:04.397643 | localhost | ok 2026-09-24 00:48:04.410538 | 2026-09-24 00:48:04.410632 | TASK [Setup log path fact] 2026-09-24 00:48:04.435886 | localhost | ok 2026-09-24 00:48:04.449807 | 2026-09-24 00:48:04.449944 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-24 00:48:04.477148 | localhost | ok 2026-09-24 00:48:04.484395 | 2026-09-24 00:48:04.484510 | TASK [emit-job-header : Print job information] 2026-09-24 00:48:04.532242 | # Job Information 2026-09-24 00:48:04.532429 | Ansible Version: 2.16.18 2026-09-24 00:48:04.532473 | Job: ansible-collection-kubernetes-molecule-upload-helm-chart 2026-09-24 00:48:04.532528 | Pipeline: check 2026-09-24 00:48:04.532558 | Executor: 2d72f0692154 2026-09-24 00:48:04.532584 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/293 2026-09-24 00:48:04.532614 | Event ID: e533a450-b7b0-11f1-98be-9e8d6b6260a8 2026-09-24 00:48:04.536168 | 2026-09-24 00:48:04.536259 | LOOP [emit-job-header : Print node information] 2026-09-24 00:48:04.635165 | localhost | ok: 2026-09-24 00:48:04.635710 | localhost | # Node Information 2026-09-24 00:48:04.635768 | localhost | Inventory Hostname: controller 2026-09-24 00:48:04.635811 | localhost | Hostname: np0000193850 2026-09-24 00:48:04.635850 | localhost | Username: zuul 2026-09-24 00:48:04.635894 | localhost | Distro: Debian 13.7 2026-09-24 00:48:04.635931 | localhost | Provider: yul1 2026-09-24 00:48:04.635971 | localhost | Region: ca-ymq-1 2026-09-24 00:48:04.636007 | localhost | Label: debian-trixie 2026-09-24 00:48:04.636043 | localhost | Product Name: OpenStack Nova 2026-09-24 00:48:04.636098 | localhost | Interface IP: 162.253.55.106 2026-09-24 00:48:04.653137 | 2026-09-24 00:48:04.653318 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-24 00:48:05.105488 | localhost -> localhost | changed 2026-09-24 00:48:05.116878 | 2026-09-24 00:48:05.117179 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-24 00:48:06.018635 | localhost -> localhost | changed 2026-09-24 00:48:06.024295 | 2026-09-24 00:48:06.024352 | PLAY [all] 2026-09-24 00:48:06.030492 | 2026-09-24 00:48:06.030553 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-24 00:48:06.256266 | controller -> localhost | ok 2026-09-24 00:48:06.264589 | 2026-09-24 00:48:06.264712 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-24 00:48:06.295154 | controller | ok 2026-09-24 00:48:06.320020 | controller | included: /var/lib/zuul/builds/01b1b599c5044a5982e686beee486e88/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-24 00:48:06.330243 | 2026-09-24 00:48:06.330326 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-24 00:48:08.423210 | controller -> localhost | Generating public/private rsa key pair. 2026-09-24 00:48:08.423471 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/01b1b599c5044a5982e686beee486e88/work/01b1b599c5044a5982e686beee486e88_id_rsa 2026-09-24 00:48:08.423528 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/01b1b599c5044a5982e686beee486e88/work/01b1b599c5044a5982e686beee486e88_id_rsa.pub 2026-09-24 00:48:08.423571 | controller -> localhost | The key fingerprint is: 2026-09-24 00:48:08.423612 | controller -> localhost | SHA256:xbm7yzKzSPN9dDeRRtLfYDqU34qXDTCLsSa6xJNzXzE zuul-build-sshkey 2026-09-24 00:48:08.423674 | controller -> localhost | The key's randomart image is: 2026-09-24 00:48:08.423716 | controller -> localhost | +---[RSA 3072]----+ 2026-09-24 00:48:08.423764 | controller -> localhost | | .. | 2026-09-24 00:48:08.423807 | controller -> localhost | | ...=.oo | 2026-09-24 00:48:08.423846 | controller -> localhost | | += Boo+| 2026-09-24 00:48:08.423885 | controller -> localhost | | ..+.E o++| 2026-09-24 00:48:08.423923 | controller -> localhost | | . oSo. =.=.| 2026-09-24 00:48:08.423959 | controller -> localhost | | B . .+ =.o| 2026-09-24 00:48:08.423996 | controller -> localhost | | .o= ..o o ..| 2026-09-24 00:48:08.424033 | controller -> localhost | | ..++o... | 2026-09-24 00:48:08.424095 | controller -> localhost | | . o==o | 2026-09-24 00:48:08.424137 | controller -> localhost | +----[SHA256]-----+ 2026-09-24 00:48:08.424220 | controller -> localhost | ok: Runtime: 0:00:01.660963 2026-09-24 00:48:08.432200 | 2026-09-24 00:48:08.432318 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-24 00:48:08.477621 | controller | ok 2026-09-24 00:48:08.491318 | controller | included: /var/lib/zuul/builds/01b1b599c5044a5982e686beee486e88/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-24 00:48:08.499483 | 2026-09-24 00:48:08.499565 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-24 00:48:08.523675 | controller | skipping: Conditional result was False 2026-09-24 00:48:08.532432 | 2026-09-24 00:48:08.532584 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-24 00:48:09.137854 | controller | changed 2026-09-24 00:48:09.145422 | 2026-09-24 00:48:09.145546 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-24 00:48:09.365961 | controller | ok 2026-09-24 00:48:09.375546 | 2026-09-24 00:48:09.375677 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-24 00:48:09.991402 | controller | changed 2026-09-24 00:48:09.995150 | 2026-09-24 00:48:09.995211 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-24 00:48:10.612710 | controller | changed 2026-09-24 00:48:10.618343 | 2026-09-24 00:48:10.618442 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-24 00:48:10.656253 | controller | skipping: Conditional result was False 2026-09-24 00:48:10.667125 | 2026-09-24 00:48:10.667260 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-24 00:48:11.025295 | controller -> localhost | changed 2026-09-24 00:48:11.043420 | 2026-09-24 00:48:11.043552 | TASK [add-build-sshkey : Add back temp key] 2026-09-24 00:48:11.313064 | controller -> localhost | Identity added: /var/lib/zuul/builds/01b1b599c5044a5982e686beee486e88/work/01b1b599c5044a5982e686beee486e88_id_rsa (zuul-build-sshkey) 2026-09-24 00:48:11.313252 | controller -> localhost | ok: Runtime: 0:00:00.012855 2026-09-24 00:48:11.316820 | 2026-09-24 00:48:11.316877 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-24 00:48:11.672617 | controller | ok 2026-09-24 00:48:11.679981 | 2026-09-24 00:48:11.680140 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-24 00:48:11.715182 | controller | skipping: Conditional result was False 2026-09-24 00:48:11.730844 | 2026-09-24 00:48:11.731003 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-24 00:48:12.106833 | controller | ok 2026-09-24 00:48:12.116093 | 2026-09-24 00:48:12.116309 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-24 00:48:13.188394 | controller | Output suppressed because no_log was given 2026-09-24 00:48:13.196592 | 2026-09-24 00:48:13.196676 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-24 00:48:13.448473 | controller | ok: "logs" 2026-09-24 00:48:13.449014 | controller | ok: All items complete 2026-09-24 00:48:13.449120 | 2026-09-24 00:48:13.625370 | controller | ok: "artifacts" 2026-09-24 00:48:13.828108 | controller | ok: "docs" 2026-09-24 00:48:13.839488 | 2026-09-24 00:48:13.839893 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-24 00:48:14.094123 | controller | changed: "logs" 2026-09-24 00:48:14.297157 | controller | changed: "artifacts" 2026-09-24 00:48:14.499541 | controller | changed: "docs" 2026-09-24 00:48:14.519659 | 2026-09-24 00:48:14.519830 | PLAY RECAP 2026-09-24 00:48:14.519924 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-24 00:48:14.519998 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-24 00:48:14.520046 | 2026-09-24 00:48:14.649551 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-24 00:48:14.652204 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-24 00:48:15.199118 | 2026-09-24 00:48:15.199247 | PLAY [all] 2026-09-24 00:48:15.207829 | 2026-09-24 00:48:15.207915 | TASK [setup-uv : Extract archive] 2026-09-24 00:48:17.741768 | controller | changed 2026-09-24 00:48:17.751039 | 2026-09-24 00:48:17.751223 | TASK [setup-uv : Print version] 2026-09-24 00:48:18.144747 | controller | uv 0.8.13 2026-09-24 00:48:18.293224 | controller | ok: Runtime: 0:00:00.012742 2026-09-24 00:48:18.305901 | 2026-09-24 00:48:18.306025 | TASK [Install Ansible collection siblings] 2026-09-24 00:48:18.722635 | controller | ok: 2026-09-24 00:48:18.722865 | controller | Skipping ansible.posix: no checked-out sibling 2026-09-24 00:48:18.722921 | controller | Skipping community.crypto: no checked-out sibling 2026-09-24 00:48:18.722963 | controller | Skipping community.general: no checked-out sibling 2026-09-24 00:48:18.723002 | controller | Skipping kubernetes.core: no checked-out sibling 2026-09-24 00:48:18.723039 | controller | Skipping vexxhost.containers: no checked-out sibling 2026-09-24 00:48:18.725857 | 2026-09-24 00:48:18.725991 | PLAY RECAP 2026-09-24 00:48:18.726130 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-24 00:48:18.726181 | 2026-09-24 00:48:18.835874 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-24 00:48:18.838392 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-09-24 00:48:19.351359 | 2026-09-24 00:48:19.351531 | PLAY [all] 2026-09-24 00:48:19.360569 | 2026-09-24 00:48:19.360635 | TASK [Update apt cache] 2026-09-24 00:48:25.494771 | controller | ok 2026-09-24 00:48:25.502003 | 2026-09-24 00:48:25.502166 | TASK [Disable firewalld] 2026-09-24 00:48:25.526375 | controller | skipping: Conditional result was False 2026-09-24 00:48:25.534193 | 2026-09-24 00:48:25.534319 | TASK [Install "jq" for log collection] 2026-09-24 00:48:28.353040 | controller | changed 2026-09-24 00:48:28.355886 | 2026-09-24 00:48:28.355985 | PLAY RECAP 2026-09-24 00:48:28.356112 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-24 00:48:28.356249 | 2026-09-24 00:48:28.467836 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-09-24 00:48:28.469133 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-24 00:48:29.090921 | 2026-09-24 00:48:29.091046 | PLAY [all] 2026-09-24 00:48:29.100297 | 2026-09-24 00:48:29.100389 | LOOP [Check inventory directories] 2026-09-24 00:48:29.508261 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes" 2026-09-24 00:48:29.698051 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/upload-helm-chart/inventory" 2026-09-24 00:48:29.914420 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/upload-helm-chart/inventory" 2026-09-24 00:48:29.935501 | 2026-09-24 00:48:29.935761 | LOOP [Copy inventory file for Zuul] 2026-09-24 00:48:30.905878 | controller | changed: 2026-09-24 00:48:30.906183 | controller | { 2026-09-24 00:48:30.906241 | controller | "ansible_loop_var": "item", 2026-09-24 00:48:30.906285 | controller | "changed": false, 2026-09-24 00:48:30.906325 | controller | "failed": false, 2026-09-24 00:48:30.906370 | controller | "invocation": { 2026-09-24 00:48:30.906407 | controller | "module_args": { 2026-09-24 00:48:30.906444 | controller | "checksum_algorithm": "sha1", 2026-09-24 00:48:30.906480 | controller | "follow": true, 2026-09-24 00:48:30.906514 | controller | "get_attributes": true, 2026-09-24 00:48:30.906551 | controller | "get_checksum": true, 2026-09-24 00:48:30.906586 | controller | "get_mime": true, 2026-09-24 00:48:30.906622 | controller | "path": "src/github.com/vexxhost/ansible-collection-kubernetes" 2026-09-24 00:48:30.906657 | controller | } 2026-09-24 00:48:30.906692 | controller | }, 2026-09-24 00:48:30.906726 | controller | "item": "src/github.com/vexxhost/ansible-collection-kubernetes", 2026-09-24 00:48:30.906761 | controller | "stat": { 2026-09-24 00:48:30.906811 | controller | "atime": 1790210893.0617168, 2026-09-24 00:48:30.906864 | controller | "attr_flags": "e", 2026-09-24 00:48:30.906923 | controller | "attributes": [ 2026-09-24 00:48:30.906977 | controller | "extents" 2026-09-24 00:48:30.907016 | controller | ], 2026-09-24 00:48:30.907464 | controller | "block_size": 4096, 2026-09-24 00:48:30.907903 | controller | "blocks": 8, 2026-09-24 00:48:30.907991 | controller | "charset": "unknown", 2026-09-24 00:48:30.908038 | controller | "ctime": 1790210893.0777168, 2026-09-24 00:48:30.908459 | controller | "dev": 65025, 2026-09-24 00:48:30.908946 | controller | "device_type": 0, 2026-09-24 00:48:30.909005 | controller | "executable": true, 2026-09-24 00:48:30.909044 | controller | "exists": true, 2026-09-24 00:48:30.909430 | controller | "gid": 1000, 2026-09-24 00:48:30.909875 | controller | "gr_name": "zuul", 2026-09-24 00:48:30.909958 | controller | "inode": 519447, 2026-09-24 00:48:30.910002 | controller | "isblk": false, 2026-09-24 00:48:30.910390 | controller | "ischr": false, 2026-09-24 00:48:30.910822 | controller | "isdir": true, 2026-09-24 00:48:30.910894 | controller | "isfifo": false, 2026-09-24 00:48:30.910936 | controller | "isgid": false, 2026-09-24 00:48:30.910972 | controller | "islnk": false, 2026-09-24 00:48:30.911008 | controller | "isreg": false, 2026-09-24 00:48:30.911774 | controller | "issock": false, 2026-09-24 00:48:30.911852 | controller | "isuid": false, 2026-09-24 00:48:30.911908 | controller | "mimetype": "unknown", 2026-09-24 00:48:30.911957 | controller | "mode": "0755", 2026-09-24 00:48:30.912009 | controller | "mtime": 1790210875.7982068, 2026-09-24 00:48:30.912089 | controller | "nlink": 14, 2026-09-24 00:48:30.912148 | controller | "path": "src/github.com/vexxhost/ansible-collection-kubernetes", 2026-09-24 00:48:30.912196 | controller | "pw_name": "zuul", 2026-09-24 00:48:30.912255 | controller | "readable": true, 2026-09-24 00:48:30.912308 | controller | "rgrp": true, 2026-09-24 00:48:30.912356 | controller | "roth": true, 2026-09-24 00:48:30.912410 | controller | "rusr": true, 2026-09-24 00:48:30.912457 | controller | "size": 4096, 2026-09-24 00:48:30.912526 | controller | "uid": 1000, 2026-09-24 00:48:30.912564 | controller | "version": "661704262", 2026-09-24 00:48:30.912600 | controller | "wgrp": false, 2026-09-24 00:48:30.912639 | controller | "woth": false, 2026-09-24 00:48:30.912674 | controller | "writeable": true, 2026-09-24 00:48:30.912708 | controller | "wusr": true, 2026-09-24 00:48:30.912742 | controller | "xgrp": true, 2026-09-24 00:48:30.912775 | controller | "xoth": true, 2026-09-24 00:48:30.912809 | controller | "xusr": true 2026-09-24 00:48:30.912867 | controller | } 2026-09-24 00:48:30.912903 | controller | } 2026-09-24 00:48:30.912975 | controller | ok: All items complete 2026-09-24 00:48:30.913016 | 2026-09-24 00:48:30.917423 | controller | skipping: Conditional result was False 2026-09-24 00:48:30.918749 | controller | skipping: Conditional result was False 2026-09-24 00:48:30.933707 | 2026-09-24 00:48:30.933887 | LOOP [Switch "ansible_host" to private IP] 2026-09-24 00:48:31.322188 | controller | changed: 1 replacements made 2026-09-24 00:48:31.324410 | controller | skipping: Conditional result was False 2026-09-24 00:48:31.330316 | controller | skipping: Conditional result was False 2026-09-24 00:48:31.350473 | 2026-09-24 00:48:31.350664 | TASK [Run Molecule scenario] 2026-09-24 00:48:31.811737 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-09-24 00:48:31.811847 | controller | Creating virtual environment at: .venv 2026-09-24 00:48:33.210217 | controller | Downloading ansible-core (2.3MiB) 2026-09-24 00:48:33.214682 | controller | Downloading pygments (1.2MiB) 2026-09-24 00:48:33.260960 | controller | Downloading cryptography (4.3MiB) 2026-09-24 00:48:33.388541 | controller | Downloading cryptography 2026-09-24 00:48:33.449741 | controller | Downloading pygments 2026-09-24 00:48:33.619603 | controller | Downloading ansible-core 2026-09-24 00:48:33.673573 | controller | Installed 36 packages in 53ms 2026-09-24 00:48:34.356063 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-09-24 00:48:34.356141 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-09-24 00:48:35.270661 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-09-24 00:48:35.270727 | controller | INFO default scenario not found, disabling shared state. 2026-09-24 00:48:35.270882 | controller | INFO [upload-helm-chart > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-24 00:48:35.270936 | controller | INFO [upload-helm-chart > prerun] Performing prerun with role_name_check=0... 2026-09-24 00:48:37.831997 | controller | WARNING Retrying execution failure 1 of: ansible-galaxy collection install -vvv ansible.posix:>=1.6.0 2026-09-24 00:48:37.832116 | controller | ERROR Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code: 2026-09-24 00:48:37.832126 | controller | Starting galaxy collection install process 2026-09-24 00:48:37.832134 | controller | Process install dependency map 2026-09-24 00:48:37.832143 | controller | 2026-09-24 00:48:37.832151 | controller | ansible-galaxy 2026-09-24 00:48:37.832158 | controller | config file = None 2026-09-24 00:48:37.832166 | controller | configured module search path = 2026-09-24 00:48:37.832174 | 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:37.832186 | 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:37.832195 | controller | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy 2026-09-24 00:48:37.832206 | 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:37.832214 | controller | jinja version = 3.1.6 2026-09-24 00:48:37.832221 | controller | pyyaml version = 6.0.3 (with libyaml v0.2.5) 2026-09-24 00:48:37.832229 | controller | No config file found; using defaults 2026-09-24 00:48:37.832236 | controller | Created /home/zuul/.ansible/galaxy_token 2026-09-24 00:48:37.832244 | 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:37.832252 | controller | : Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/': 2026-09-24 00:48:37.832259 | controller | 2026-09-24 00:48:37.832267 | controller | Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/'. 2026-09-24 00:48:37.832274 | controller | 2026-09-24 00:48:37.832281 | controller | <<< caused by >>> 2026-09-24 00:48:37.832288 | controller | 2026-09-24 00:48:37.832295 | controller | 2026-09-24 00:48:37.832302 | controller | 2026-09-24 00:48:37.832309 | controller | 2026-09-24 00:48:37.835362 | controller | Traceback (most recent call last): 2026-09-24 00:48:37.838465 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/molecule", line 10, in 2026-09-24 00:48:37.838494 | controller | sys.exit(main()) 2026-09-24 00:48:37.838501 | controller | ~~~~^^ 2026-09-24 00:48:37.838822 | 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:37.838833 | controller | return self.main(*args, **kwargs) 2026-09-24 00:48:37.838843 | controller | ~~~~~~~~~^^^^^^^^^^^^^^^^^ 2026-09-24 00:48:37.839098 | 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:37.839115 | controller | rv = self.invoke(ctx) 2026-09-24 00:48:37.839356 | 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:37.839365 | controller | return _process_result(sub_ctx.command.invoke(sub_ctx)) 2026-09-24 00:48:37.839373 | controller | ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^ 2026-09-24 00:48:37.839656 | 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:37.839666 | controller | return ctx.invoke(self.callback, **ctx.params) 2026-09-24 00:48:37.839673 | controller | ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:48:37.839893 | 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:37.839902 | controller | return callback(*args, **kwargs) 2026-09-24 00:48:37.840128 | 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:37.840136 | controller | return func(*args, **kwargs) 2026-09-24 00:48:37.840364 | 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:37.840373 | controller | return f(get_current_context(), *args, **kwargs) 2026-09-24 00:48:37.840614 | 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:37.840624 | controller | return func(ctx) 2026-09-24 00:48:37.840898 | 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:37.840907 | controller | base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude) 2026-09-24 00:48:37.840915 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:48:37.841177 | 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:37.841192 | controller | _run_scenarios(scenarios, command_args, default_config) 2026-09-24 00:48:37.841199 | controller | ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:48:37.841602 | 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:37.841613 | controller | scenario.config.runtime.prepare_environment( 2026-09-24 00:48:37.841621 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-24 00:48:37.841628 | controller | install_local=True, 2026-09-24 00:48:37.841635 | controller | ^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:48:37.841642 | controller | role_name_check=role_name_check, 2026-09-24 00:48:37.841649 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:48:37.841656 | controller | ) 2026-09-24 00:48:37.841663 | controller | ^ 2026-09-24 00:48:37.842028 | 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:37.842040 | controller | self.install_collection( 2026-09-24 00:48:37.842047 | controller | ~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-24 00:48:37.842062 | controller | f"{name}{',' if is_url(name) else ':'}{required_version}", 2026-09-24 00:48:37.842070 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:48:37.842077 | controller | destination=destination, 2026-09-24 00:48:37.842084 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:48:37.842090 | controller | ) 2026-09-24 00:48:37.842097 | controller | ^ 2026-09-24 00:48:37.842257 | 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:37.842265 | controller | raise InvalidPrerequisiteError(msg) 2026-09-24 00:48:37.843361 | controller | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code: 2026-09-24 00:48:37.843372 | controller | Starting galaxy collection install process 2026-09-24 00:48:37.843379 | controller | Process install dependency map 2026-09-24 00:48:37.843386 | controller | 2026-09-24 00:48:37.843393 | controller | ansible-galaxy [core 2.20.1] 2026-09-24 00:48:37.843400 | controller | config file = None 2026-09-24 00:48:37.843407 | 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:37.843449 | 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:37.843464 | 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:37.843472 | controller | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy 2026-09-24 00:48:37.843479 | 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:37.843486 | controller | jinja version = 3.1.6 2026-09-24 00:48:37.843493 | controller | pyyaml version = 6.0.3 (with libyaml v0.2.5) 2026-09-24 00:48:37.843499 | controller | No config file found; using defaults 2026-09-24 00:48:37.843506 | controller | Created /home/zuul/.ansible/galaxy_token 2026-09-24 00:48:37.843514 | 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:37.843525 | controller | [ERROR]: Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/': 2026-09-24 00:48:37.843532 | controller | 2026-09-24 00:48:37.843539 | controller | Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/'. 2026-09-24 00:48:37.843546 | controller | 2026-09-24 00:48:37.843553 | controller | <<< caused by >>> 2026-09-24 00:48:37.843560 | controller | 2026-09-24 00:48:37.843566 | controller | 2026-09-24 00:48:37.843573 | controller | 2026-09-24 00:48:37.843580 | controller | 2026-09-24 00:48:38.402630 | controller | ERROR 2026-09-24 00:48:38.402906 | controller | { 2026-09-24 00:48:38.402964 | controller | "delta": "0:00:06.202163", 2026-09-24 00:48:38.403006 | controller | "end": "2026-09-24 00:48:37.944192", 2026-09-24 00:48:38.403064 | controller | "msg": "non-zero return code", 2026-09-24 00:48:38.403105 | controller | "rc": 1, 2026-09-24 00:48:38.403141 | controller | "start": "2026-09-24 00:48:31.742029" 2026-09-24 00:48:38.403177 | controller | } failure 2026-09-24 00:48:38.410147 | 2026-09-24 00:48:38.410245 | PLAY RECAP 2026-09-24 00:48:38.410325 | controller | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-24 00:48:38.410417 | 2026-09-24 00:48:38.593997 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-24 00:48:38.596917 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-09-24 00:48:39.233538 | 2026-09-24 00:48:39.233794 | PLAY [all] 2026-09-24 00:48:39.255510 | 2026-09-24 00:48:39.255723 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-09-24 00:48:39.689233 | controller | changed 2026-09-24 00:48:39.693199 | 2026-09-24 00:48:39.693275 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-09-24 00:48:40.138190 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-24 00:48:40.138325 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-24 00:48:40.146316 | controller | environment: line 1: kubectl: command not found 2026-09-24 00:48:40.148211 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-24 00:48:40.150312 | controller | environment: line 1: kubectl: command not found 2026-09-24 00:48:40.151677 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-24 00:48:40.153334 | controller | environment: line 1: kubectl: command not found 2026-09-24 00:48:40.155840 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-24 00:48:40.156740 | controller | environment: line 1: kubectl: command not found 2026-09-24 00:48:40.158266 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-24 00:48:40.161631 | controller | environment: line 1: kubectl: command not found 2026-09-24 00:48:40.162912 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-24 00:48:40.222923 | controller | ok: Runtime: 0:00:00.038791 2026-09-24 00:48:40.233986 | 2026-09-24 00:48:40.234184 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-09-24 00:48:40.478947 | controller | changed 2026-09-24 00:48:40.486655 | 2026-09-24 00:48:40.486867 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-09-24 00:48:40.772229 | controller | environment: line 5: kubectl: command not found 2026-09-24 00:48:40.772653 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-24 00:48:40.773837 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-24 00:48:40.774124 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-24 00:48:41.025806 | controller | ok: Runtime: 0:00:00.010051 2026-09-24 00:48:41.034998 | 2026-09-24 00:48:41.035151 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-09-24 00:48:41.738519 | controller | changed: 2026-09-24 00:48:41.738784 | controller | created directory /var/lib/zuul/builds/01b1b599c5044a5982e686beee486e88/work/logs/controller 2026-09-24 00:48:41.738844 | controller | cd+++++++++ objects/ 2026-09-24 00:48:41.738888 | controller | cd+++++++++ objects/cluster/ 2026-09-24 00:48:41.738933 | controller | cd+++++++++ objects/namespaced/ 2026-09-24 00:48:41.753238 | 2026-09-24 00:48:41.753365 | TASK [gather-pod-logs : creating directory for pod logs] 2026-09-24 00:48:42.016558 | controller | changed 2026-09-24 00:48:42.026293 | 2026-09-24 00:48:42.026425 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-09-24 00:48:42.277252 | controller | changed 2026-09-24 00:48:42.281178 | 2026-09-24 00:48:42.281242 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-09-24 00:48:42.537718 | controller | environment: line 3: kubectl: command not found 2026-09-24 00:48:42.804481 | controller | ok: Runtime: 0:00:00.010442 2026-09-24 00:48:42.807957 | 2026-09-24 00:48:42.808013 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-09-24 00:48:43.324173 | controller | changed: 2026-09-24 00:48:43.324408 | controller | cd+++++++++ pod-logs/ 2026-09-24 00:48:43.324462 | controller | cd+++++++++ pod-logs/failed-pods/ 2026-09-24 00:48:43.337294 | 2026-09-24 00:48:43.337408 | LOOP [helm-release-status : creating directory for helm release status] 2026-09-24 00:48:43.593858 | controller | changed: "values" 2026-09-24 00:48:43.825087 | controller | changed: "releases" 2026-09-24 00:48:43.839897 | 2026-09-24 00:48:43.840035 | TASK [helm-release-status : Gather get release status for helm charts] 2026-09-24 00:48:44.086765 | controller | /bin/bash: line 3: kubectl: command not found 2026-09-24 00:48:44.381550 | controller | ok: Runtime: 0:00:00.009546 2026-09-24 00:48:44.389511 | 2026-09-24 00:48:44.389632 | TASK [helm-release-status : Downloads logs to executor] 2026-09-24 00:48:44.935237 | controller | changed: 2026-09-24 00:48:44.935426 | controller | cd+++++++++ helm/ 2026-09-24 00:48:44.935461 | controller | cd+++++++++ helm/releases/ 2026-09-24 00:48:44.935496 | controller | cd+++++++++ helm/values/ 2026-09-24 00:48:44.941186 | 2026-09-24 00:48:44.941278 | PLAY RECAP 2026-09-24 00:48:44.941363 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-24 00:48:44.941443 | 2026-09-24 00:48:45.088018 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-09-24 00:48:45.090695 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-24 00:48:45.641018 | 2026-09-24 00:48:45.641165 | PLAY [all] 2026-09-24 00:48:45.650281 | 2026-09-24 00:48:45.650349 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-24 00:48:45.705553 | controller | skipping: Conditional result was False 2026-09-24 00:48:45.710954 | 2026-09-24 00:48:45.711067 | TASK [fetch-output : Set log path for single node] 2026-09-24 00:48:45.761124 | controller | ok 2026-09-24 00:48:45.768404 | 2026-09-24 00:48:45.768568 | LOOP [fetch-output : Ensure local output dirs] 2026-09-24 00:48:46.169700 | controller -> localhost | ok: "/var/lib/zuul/builds/01b1b599c5044a5982e686beee486e88/work/logs" 2026-09-24 00:48:46.410322 | controller -> localhost | changed: "/var/lib/zuul/builds/01b1b599c5044a5982e686beee486e88/work/artifacts" 2026-09-24 00:48:46.641629 | controller -> localhost | changed: "/var/lib/zuul/builds/01b1b599c5044a5982e686beee486e88/work/docs" 2026-09-24 00:48:46.656028 | 2026-09-24 00:48:46.656245 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-24 00:48:47.317459 | controller | changed: .d..t...... ./ 2026-09-24 00:48:47.317803 | controller | changed: All items complete 2026-09-24 00:48:47.317854 | 2026-09-24 00:48:47.836968 | controller | changed: .d..t...... ./ 2026-09-24 00:48:48.336755 | controller | changed: .d..t...... ./ 2026-09-24 00:48:48.357237 | 2026-09-24 00:48:48.357414 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-24 00:48:48.755819 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006494 2026-09-24 00:48:48.991486 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007741 2026-09-24 00:48:49.007121 | 2026-09-24 00:48:49.007257 | PLAY [all] 2026-09-24 00:48:49.016676 | 2026-09-24 00:48:49.016847 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-24 00:48:49.493682 | controller | changed 2026-09-24 00:48:49.501799 | 2026-09-24 00:48:49.501892 | PLAY RECAP 2026-09-24 00:48:49.501976 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-24 00:48:49.502018 | 2026-09-24 00:48:49.610299 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-24 00:48:49.612968 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-24 00:48:50.283497 | 2026-09-24 00:48:50.283622 | PLAY [localhost] 2026-09-24 00:48:50.292710 | 2026-09-24 00:48:50.292781 | TASK [Generate Zuul manifest] 2026-09-24 00:48:50.309469 | localhost | ok 2026-09-24 00:48:50.322954 | 2026-09-24 00:48:50.323050 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-24 00:48:50.687572 | localhost | changed 2026-09-24 00:48:50.708424 | 2026-09-24 00:48:50.708607 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-24 00:48:50.739819 | localhost | ok 2026-09-24 00:48:50.752247 | 2026-09-24 00:48:50.752373 | TASK [Upload logs] 2026-09-24 00:48:50.772524 | localhost | ok 2026-09-24 00:48:50.837391 | 2026-09-24 00:48:50.837511 | TASK [Set zuul-log-path fact] 2026-09-24 00:48:50.854804 | localhost | ok 2026-09-24 00:48:50.864719 | 2026-09-24 00:48:50.864781 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-24 00:48:50.891931 | localhost | ok 2026-09-24 00:48:50.900681 | 2026-09-24 00:48:50.900767 | TASK [upload-logs : Create log directories] 2026-09-24 00:48:51.306607 | localhost | changed 2026-09-24 00:48:51.315249 | 2026-09-24 00:48:51.315393 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-24 00:48:51.745569 | localhost -> localhost | ok: Runtime: 0:00:00.005497 2026-09-24 00:48:51.758175 | 2026-09-24 00:48:51.758266 | TASK [upload-logs : Upload logs to log server] 2026-09-24 00:48:52.225473 | localhost | Output suppressed because no_log was given 2026-09-24 00:48:52.232852 | 2026-09-24 00:48:52.232995 | LOOP [upload-logs : Compress console log and json output] 2026-09-24 00:48:52.301081 | localhost | skipping: Conditional result was False 2026-09-24 00:48:52.361619 | localhost | skipping: Conditional result was False 2026-09-24 00:48:52.376439 | 2026-09-24 00:48:52.376681 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-24 00:48:52.439160 | localhost | skipping: Conditional result was False 2026-09-24 00:48:52.439565 | 2026-09-24 00:48:52.444996 | localhost | skipping: Conditional result was False 2026-09-24 00:48:52.459353 | 2026-09-24 00:48:52.459568 | LOOP [upload-logs : Upload console log and json output]