2026-09-24 00:45:43.371709 | Job console starting 2026-09-24 00:45:43.385174 | Updating git repos 2026-09-24 00:45:43.501966 | Cloning repos into workspace 2026-09-24 00:45:44.160717 | Restoring repo states 2026-09-24 00:45:44.176208 | Merging changes 2026-09-24 00:45:45.058606 | Checking out repos 2026-09-24 00:45:45.402534 | Preparing playbooks 2026-09-24 00:45:49.538851 | Running Ansible setup 2026-09-24 00:45:54.317449 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-24 00:45:54.850565 | 2026-09-24 00:45:54.850680 | PLAY [localhost] 2026-09-24 00:45:54.858093 | 2026-09-24 00:45:54.858159 | TASK [Gathering Facts] 2026-09-24 00:45:55.857379 | localhost | ok 2026-09-24 00:45:55.874991 | 2026-09-24 00:45:55.875155 | TASK [Setup log path fact] 2026-09-24 00:45:55.897944 | localhost | ok 2026-09-24 00:45:55.917841 | 2026-09-24 00:45:55.917934 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-24 00:45:55.948533 | localhost | ok 2026-09-24 00:45:55.959986 | 2026-09-24 00:45:55.960077 | TASK [emit-job-header : Print job information] 2026-09-24 00:45:55.989093 | # Job Information 2026-09-24 00:45:55.989227 | Ansible Version: 2.16.18 2026-09-24 00:45:55.989282 | Job: ansible-collection-kubernetes-molecule-aio-debian-trixie 2026-09-24 00:45:55.989310 | Pipeline: check 2026-09-24 00:45:55.989334 | Executor: 2d72f0692154 2026-09-24 00:45:55.989358 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/293 2026-09-24 00:45:55.989387 | Event ID: e533a450-b7b0-11f1-98be-9e8d6b6260a8 2026-09-24 00:45:55.992872 | 2026-09-24 00:45:55.992951 | LOOP [emit-job-header : Print node information] 2026-09-24 00:45:56.065298 | localhost | ok: 2026-09-24 00:45:56.065533 | localhost | # Node Information 2026-09-24 00:45:56.065587 | localhost | Inventory Hostname: controller 2026-09-24 00:45:56.065720 | localhost | Hostname: np0000193836 2026-09-24 00:45:56.065759 | localhost | Username: zuul 2026-09-24 00:45:56.065805 | localhost | Distro: Debian 13.7 2026-09-24 00:45:56.065842 | localhost | Provider: yul1 2026-09-24 00:45:56.065877 | localhost | Region: ca-ymq-1 2026-09-24 00:45:56.065912 | localhost | Label: debian-trixie 2026-09-24 00:45:56.065947 | localhost | Product Name: OpenStack Nova 2026-09-24 00:45:56.065981 | localhost | Interface IP: 162.253.55.193 2026-09-24 00:45:56.078405 | 2026-09-24 00:45:56.078538 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-24 00:45:56.438562 | localhost -> localhost | changed 2026-09-24 00:45:56.448302 | 2026-09-24 00:45:56.448436 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-24 00:45:57.289362 | localhost -> localhost | changed 2026-09-24 00:45:57.302412 | 2026-09-24 00:45:57.302564 | PLAY [all] 2026-09-24 00:45:57.320949 | 2026-09-24 00:45:57.321202 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-24 00:45:57.560294 | controller -> localhost | ok 2026-09-24 00:45:57.565312 | 2026-09-24 00:45:57.565381 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-24 00:45:57.592299 | controller | ok 2026-09-24 00:45:57.617680 | controller | included: /var/lib/zuul/builds/45584d2c077c45188bf72bce8de4dabb/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-24 00:45:57.625222 | 2026-09-24 00:45:57.625329 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-24 00:45:58.465522 | controller -> localhost | Generating public/private rsa key pair. 2026-09-24 00:45:58.465769 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/45584d2c077c45188bf72bce8de4dabb/work/45584d2c077c45188bf72bce8de4dabb_id_rsa 2026-09-24 00:45:58.465827 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/45584d2c077c45188bf72bce8de4dabb/work/45584d2c077c45188bf72bce8de4dabb_id_rsa.pub 2026-09-24 00:45:58.465869 | controller -> localhost | The key fingerprint is: 2026-09-24 00:45:58.465910 | controller -> localhost | SHA256:K4FViKCeDpmI00eBrb5xJ4a1afBhZSVpCBLp8uAGZq0 zuul-build-sshkey 2026-09-24 00:45:58.465969 | controller -> localhost | The key's randomart image is: 2026-09-24 00:45:58.466010 | controller -> localhost | +---[RSA 3072]----+ 2026-09-24 00:45:58.466058 | controller -> localhost | |ooo=.o.+o | 2026-09-24 00:45:58.466099 | controller -> localhost | |.o. +.*o | 2026-09-24 00:45:58.466137 | controller -> localhost | |o ...+. | 2026-09-24 00:45:58.466175 | controller -> localhost | |BB+o+o | 2026-09-24 00:45:58.466213 | controller -> localhost | |&*o*o+. S | 2026-09-24 00:45:58.466271 | controller -> localhost | |oE=.O .. . | 2026-09-24 00:45:58.466309 | controller -> localhost | |.. * o. . | 2026-09-24 00:45:58.466347 | controller -> localhost | | . . | 2026-09-24 00:45:58.466391 | controller -> localhost | | | 2026-09-24 00:45:58.466429 | controller -> localhost | +----[SHA256]-----+ 2026-09-24 00:45:58.466516 | controller -> localhost | ok: Runtime: 0:00:00.371793 2026-09-24 00:45:58.474189 | 2026-09-24 00:45:58.474335 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-24 00:45:58.504578 | controller | ok 2026-09-24 00:45:58.518226 | controller | included: /var/lib/zuul/builds/45584d2c077c45188bf72bce8de4dabb/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-24 00:45:58.527800 | 2026-09-24 00:45:58.527873 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-24 00:45:58.551277 | controller | skipping: Conditional result was False 2026-09-24 00:45:58.559410 | 2026-09-24 00:45:58.559535 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-24 00:45:59.096769 | controller | changed 2026-09-24 00:45:59.102050 | 2026-09-24 00:45:59.102159 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-24 00:45:59.327334 | controller | ok 2026-09-24 00:45:59.335273 | 2026-09-24 00:45:59.335431 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-24 00:45:59.911810 | controller | changed 2026-09-24 00:45:59.916912 | 2026-09-24 00:45:59.916996 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-24 00:46:00.495446 | controller | changed 2026-09-24 00:46:00.501029 | 2026-09-24 00:46:00.501117 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-24 00:46:00.527378 | controller | skipping: Conditional result was False 2026-09-24 00:46:00.536172 | 2026-09-24 00:46:00.536323 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-24 00:46:00.917559 | controller -> localhost | changed 2026-09-24 00:46:00.928378 | 2026-09-24 00:46:00.928445 | TASK [add-build-sshkey : Add back temp key] 2026-09-24 00:46:01.229050 | controller -> localhost | Identity added: /var/lib/zuul/builds/45584d2c077c45188bf72bce8de4dabb/work/45584d2c077c45188bf72bce8de4dabb_id_rsa (zuul-build-sshkey) 2026-09-24 00:46:01.229248 | controller -> localhost | ok: Runtime: 0:00:00.016086 2026-09-24 00:46:01.232788 | 2026-09-24 00:46:01.232846 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-24 00:46:01.578333 | controller | ok 2026-09-24 00:46:01.585661 | 2026-09-24 00:46:01.585791 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-24 00:46:01.623085 | controller | skipping: Conditional result was False 2026-09-24 00:46:01.633184 | 2026-09-24 00:46:01.633287 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-24 00:46:02.017554 | controller | ok 2026-09-24 00:46:02.025036 | 2026-09-24 00:46:02.025137 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-24 00:46:03.171974 | controller | Output suppressed because no_log was given 2026-09-24 00:46:03.185742 | 2026-09-24 00:46:03.185883 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-24 00:46:03.437556 | controller | ok: "logs" 2026-09-24 00:46:03.437946 | controller | ok: All items complete 2026-09-24 00:46:03.438029 | 2026-09-24 00:46:03.635941 | controller | ok: "artifacts" 2026-09-24 00:46:03.822599 | controller | ok: "docs" 2026-09-24 00:46:03.844114 | 2026-09-24 00:46:03.844385 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-24 00:46:04.047199 | controller | changed: "logs" 2026-09-24 00:46:04.216164 | controller | changed: "artifacts" 2026-09-24 00:46:04.385490 | controller | changed: "docs" 2026-09-24 00:46:04.395661 | 2026-09-24 00:46:04.395789 | PLAY RECAP 2026-09-24 00:46:04.395846 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-24 00:46:04.395881 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-24 00:46:04.395908 | 2026-09-24 00:46:04.538358 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-24 00:46:04.542125 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-24 00:46:05.135506 | 2026-09-24 00:46:05.135927 | PLAY [all] 2026-09-24 00:46:05.144771 | 2026-09-24 00:46:05.144845 | TASK [setup-uv : Extract archive] 2026-09-24 00:46:07.803683 | controller | changed 2026-09-24 00:46:07.808148 | 2026-09-24 00:46:07.808235 | TASK [setup-uv : Print version] 2026-09-24 00:46:08.206101 | controller | uv 0.8.13 2026-09-24 00:46:08.348125 | controller | ok: Runtime: 0:00:00.017336 2026-09-24 00:46:08.354197 | 2026-09-24 00:46:08.354274 | TASK [Install Ansible collection siblings] 2026-09-24 00:46:08.796322 | controller | ok: 2026-09-24 00:46:08.796536 | controller | Skipping ansible.posix: no checked-out sibling 2026-09-24 00:46:08.796567 | controller | Skipping community.crypto: no checked-out sibling 2026-09-24 00:46:08.796588 | controller | Skipping community.general: no checked-out sibling 2026-09-24 00:46:08.796607 | controller | Skipping kubernetes.core: no checked-out sibling 2026-09-24 00:46:08.796626 | controller | Skipping vexxhost.containers: no checked-out sibling 2026-09-24 00:46:08.798042 | 2026-09-24 00:46:08.798091 | PLAY RECAP 2026-09-24 00:46:08.798142 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-24 00:46:08.798171 | 2026-09-24 00:46:08.902641 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-24 00:46:08.904656 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-09-24 00:46:09.472952 | 2026-09-24 00:46:09.592590 | PLAY [all] 2026-09-24 00:46:09.613354 | 2026-09-24 00:46:09.613507 | TASK [Update apt cache] 2026-09-24 00:46:15.451526 | controller | ok 2026-09-24 00:46:15.458624 | 2026-09-24 00:46:15.458761 | TASK [Disable firewalld] 2026-09-24 00:46:15.484956 | controller | skipping: Conditional result was False 2026-09-24 00:46:15.491337 | 2026-09-24 00:46:15.491489 | TASK [Install "jq" for log collection] 2026-09-24 00:46:18.211138 | controller | changed 2026-09-24 00:46:18.214192 | 2026-09-24 00:46:18.214319 | PLAY RECAP 2026-09-24 00:46:18.214406 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-24 00:46:18.214452 | 2026-09-24 00:46:18.324027 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-09-24 00:46:18.325673 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-24 00:46:18.870151 | 2026-09-24 00:46:18.870280 | PLAY [all] 2026-09-24 00:46:18.879883 | 2026-09-24 00:46:18.879972 | LOOP [Check inventory directories] 2026-09-24 00:46:19.249124 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes" 2026-09-24 00:46:19.415903 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/aio/inventory" 2026-09-24 00:46:19.573832 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/aio/inventory" 2026-09-24 00:46:19.584702 | 2026-09-24 00:46:19.585097 | LOOP [Copy inventory file for Zuul] 2026-09-24 00:46:20.506501 | controller | changed: 2026-09-24 00:46:20.506706 | controller | { 2026-09-24 00:46:20.506748 | controller | "ansible_loop_var": "item", 2026-09-24 00:46:20.506778 | controller | "changed": false, 2026-09-24 00:46:20.506807 | controller | "failed": false, 2026-09-24 00:46:20.506839 | controller | "invocation": { 2026-09-24 00:46:20.506866 | controller | "module_args": { 2026-09-24 00:46:20.506893 | controller | "checksum_algorithm": "sha1", 2026-09-24 00:46:20.506918 | controller | "follow": true, 2026-09-24 00:46:20.506944 | controller | "get_attributes": true, 2026-09-24 00:46:20.506970 | controller | "get_checksum": true, 2026-09-24 00:46:20.506996 | controller | "get_mime": true, 2026-09-24 00:46:20.507021 | controller | "path": "src/github.com/vexxhost/ansible-collection-kubernetes" 2026-09-24 00:46:20.507046 | controller | } 2026-09-24 00:46:20.507071 | controller | }, 2026-09-24 00:46:20.507095 | controller | "item": "src/github.com/vexxhost/ansible-collection-kubernetes", 2026-09-24 00:46:20.507120 | controller | "stat": { 2026-09-24 00:46:20.507144 | controller | "atime": 1790210763.014233, 2026-09-24 00:46:20.507170 | controller | "attr_flags": "e", 2026-09-24 00:46:20.507195 | controller | "attributes": [ 2026-09-24 00:46:20.507238 | controller | "extents" 2026-09-24 00:46:20.507265 | controller | ], 2026-09-24 00:46:20.507290 | controller | "block_size": 4096, 2026-09-24 00:46:20.507315 | controller | "blocks": 8, 2026-09-24 00:46:20.507339 | controller | "charset": "unknown", 2026-09-24 00:46:20.507366 | controller | "ctime": 1790210763.054234, 2026-09-24 00:46:20.507392 | controller | "dev": 65025, 2026-09-24 00:46:20.507417 | controller | "device_type": 0, 2026-09-24 00:46:20.507442 | controller | "executable": true, 2026-09-24 00:46:20.507468 | controller | "exists": true, 2026-09-24 00:46:20.507492 | controller | "gid": 1000, 2026-09-24 00:46:20.507517 | controller | "gr_name": "zuul", 2026-09-24 00:46:20.507542 | controller | "inode": 519447, 2026-09-24 00:46:20.507567 | controller | "isblk": false, 2026-09-24 00:46:20.507592 | controller | "ischr": false, 2026-09-24 00:46:20.507617 | controller | "isdir": true, 2026-09-24 00:46:20.507642 | controller | "isfifo": false, 2026-09-24 00:46:20.507666 | controller | "isgid": false, 2026-09-24 00:46:20.507691 | controller | "islnk": false, 2026-09-24 00:46:20.507715 | controller | "isreg": false, 2026-09-24 00:46:20.507740 | controller | "issock": false, 2026-09-24 00:46:20.507764 | controller | "isuid": false, 2026-09-24 00:46:20.507788 | controller | "mimetype": "unknown", 2026-09-24 00:46:20.507812 | controller | "mode": "0755", 2026-09-24 00:46:20.507836 | controller | "mtime": 1790210744.3193114, 2026-09-24 00:46:20.507861 | controller | "nlink": 14, 2026-09-24 00:46:20.507885 | controller | "path": "src/github.com/vexxhost/ansible-collection-kubernetes", 2026-09-24 00:46:20.507910 | controller | "pw_name": "zuul", 2026-09-24 00:46:20.507939 | controller | "readable": true, 2026-09-24 00:46:20.507965 | controller | "rgrp": true, 2026-09-24 00:46:20.507990 | controller | "roth": true, 2026-09-24 00:46:20.508017 | controller | "rusr": true, 2026-09-24 00:46:20.508042 | controller | "size": 4096, 2026-09-24 00:46:20.508068 | controller | "uid": 1000, 2026-09-24 00:46:20.508094 | controller | "version": "1926313906", 2026-09-24 00:46:20.508119 | controller | "wgrp": false, 2026-09-24 00:46:20.508146 | controller | "woth": false, 2026-09-24 00:46:20.508172 | controller | "writeable": true, 2026-09-24 00:46:20.508197 | controller | "wusr": true, 2026-09-24 00:46:20.508237 | controller | "xgrp": true, 2026-09-24 00:46:20.508263 | controller | "xoth": true, 2026-09-24 00:46:20.508288 | controller | "xusr": true 2026-09-24 00:46:20.508640 | controller | } 2026-09-24 00:46:20.508683 | controller | } 2026-09-24 00:46:20.508731 | controller | ok: All items complete 2026-09-24 00:46:20.508760 | 2026-09-24 00:46:20.510275 | controller | skipping: Conditional result was False 2026-09-24 00:46:20.515893 | controller | skipping: Conditional result was False 2026-09-24 00:46:20.528742 | 2026-09-24 00:46:20.528925 | LOOP [Switch "ansible_host" to private IP] 2026-09-24 00:46:20.910896 | controller | changed: 1 replacements made 2026-09-24 00:46:20.915158 | controller | skipping: Conditional result was False 2026-09-24 00:46:20.923242 | controller | skipping: Conditional result was False 2026-09-24 00:46:20.941865 | 2026-09-24 00:46:20.942023 | TASK [Run Molecule scenario] 2026-09-24 00:46:21.431947 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-09-24 00:46:21.432044 | controller | Creating virtual environment at: .venv 2026-09-24 00:46:21.760388 | controller | Downloading cryptography (4.3MiB) 2026-09-24 00:46:21.775595 | controller | Downloading pygments (1.2MiB) 2026-09-24 00:46:21.821902 | controller | Downloading ansible-core (2.3MiB) 2026-09-24 00:46:21.931659 | controller | Downloading cryptography 2026-09-24 00:46:21.987744 | controller | Downloading pygments 2026-09-24 00:46:22.140583 | controller | Downloading ansible-core 2026-09-24 00:46:22.211964 | controller | Installed 36 packages in 70ms 2026-09-24 00:46:22.901225 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-09-24 00:46:22.901259 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-09-24 00:46:23.737875 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-09-24 00:46:23.737941 | controller | INFO default scenario not found, disabling shared state. 2026-09-24 00:46:23.738031 | controller | INFO [aio > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-24 00:46:23.738104 | controller | INFO [aio > prerun] Performing prerun with role_name_check=0... 2026-09-24 00:47:33.867453 | controller | WARNING Retrying execution failure 1 of: ansible-galaxy collection install -vvv ansible.posix:>=1.6.0 2026-09-24 00:47:33.867545 | controller | ERROR Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code: 2026-09-24 00:47:33.867586 | controller | Starting galaxy collection install process 2026-09-24 00:47:33.867594 | controller | Process install dependency map 2026-09-24 00:47:33.867614 | controller | Starting collection install process 2026-09-24 00:47:33.867622 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-posix-2.2.2.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1493i8i3eixg/tmpdnd8v1l4/ansible-posix-2.2.2-zmi1nxkk 2026-09-24 00:47:33.867643 | controller | 2026-09-24 00:47:33.867651 | controller | ansible-galaxy 2026-09-24 00:47:33.867659 | controller | config file = None 2026-09-24 00:47:33.867666 | controller | configured module search path = 2026-09-24 00:47:33.867673 | 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:33.867692 | 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:33.867703 | controller | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy 2026-09-24 00:47:33.867710 | 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:33.867717 | controller | jinja version = 3.1.6 2026-09-24 00:47:33.867724 | controller | pyyaml version = 6.0.3 (with libyaml v0.2.5) 2026-09-24 00:47:33.867731 | controller | No config file found; using defaults 2026-09-24 00:47:33.867738 | controller | Created /home/zuul/.ansible/galaxy_token 2026-09-24 00:47:33.867745 | controller | : Failed to download collection tar from 'default': 2026-09-24 00:47:33.867752 | controller | 2026-09-24 00:47:33.867759 | controller | Failed to download collection tar from 'default': 2026-09-24 00:47:33.867766 | controller | 2026-09-24 00:47:33.867773 | controller | <<< caused by >>> 2026-09-24 00:47:33.867780 | controller | 2026-09-24 00:47:33.867787 | controller | 2026-09-24 00:47:33.867794 | controller | 2026-09-24 00:47:33.867801 | controller | 2026-09-24 00:47:33.869560 | controller | Traceback (most recent call last): 2026-09-24 00:47:33.872813 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/molecule", line 10, in 2026-09-24 00:47:33.872855 | controller | sys.exit(main()) 2026-09-24 00:47:33.872864 | controller | ~~~~^^ 2026-09-24 00:47:33.873116 | 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:33.873143 | controller | return self.main(*args, **kwargs) 2026-09-24 00:47:33.873153 | controller | ~~~~~~~~~^^^^^^^^^^^^^^^^^ 2026-09-24 00:47:33.873380 | 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:33.873395 | controller | rv = self.invoke(ctx) 2026-09-24 00:47:33.873702 | 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:33.873728 | controller | return _process_result(sub_ctx.command.invoke(sub_ctx)) 2026-09-24 00:47:33.873738 | controller | ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^ 2026-09-24 00:47:33.873960 | 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:33.873977 | controller | return ctx.invoke(self.callback, **ctx.params) 2026-09-24 00:47:33.873989 | controller | ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:47:33.874263 | 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:33.874287 | controller | return callback(*args, **kwargs) 2026-09-24 00:47:33.874462 | 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:33.874475 | controller | return func(*args, **kwargs) 2026-09-24 00:47:33.874703 | 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:33.874711 | controller | return f(get_current_context(), *args, **kwargs) 2026-09-24 00:47:33.874932 | 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:33.874940 | controller | return func(ctx) 2026-09-24 00:47:33.875290 | 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:33.875301 | controller | base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude) 2026-09-24 00:47:33.875308 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:47:33.875565 | 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:33.875574 | controller | _run_scenarios(scenarios, command_args, default_config) 2026-09-24 00:47:33.875581 | controller | ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:47:33.875997 | 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:33.876006 | controller | scenario.config.runtime.prepare_environment( 2026-09-24 00:47:33.876013 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-24 00:47:33.876019 | controller | install_local=True, 2026-09-24 00:47:33.876026 | controller | ^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:47:33.876033 | controller | role_name_check=role_name_check, 2026-09-24 00:47:33.876040 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:47:33.876047 | controller | ) 2026-09-24 00:47:33.876054 | controller | ^ 2026-09-24 00:47:33.876450 | 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:33.876460 | controller | self.install_collection( 2026-09-24 00:47:33.876467 | controller | ~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-24 00:47:33.876474 | controller | f"{name}{',' if is_url(name) else ':'}{required_version}", 2026-09-24 00:47:33.876490 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:47:33.876497 | controller | destination=destination, 2026-09-24 00:47:33.876504 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:47:33.876511 | controller | ) 2026-09-24 00:47:33.876518 | controller | ^ 2026-09-24 00:47:33.876696 | 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:33.876704 | controller | raise InvalidPrerequisiteError(msg) 2026-09-24 00:47:33.877812 | controller | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code: 2026-09-24 00:47:33.877820 | controller | Starting galaxy collection install process 2026-09-24 00:47:33.877827 | controller | Process install dependency map 2026-09-24 00:47:33.877834 | controller | Starting collection install process 2026-09-24 00:47:33.877841 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-posix-2.2.2.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1493i8i3eixg/tmpdnd8v1l4/ansible-posix-2.2.2-zmi1nxkk 2026-09-24 00:47:33.877848 | controller | 2026-09-24 00:47:33.877856 | controller | ansible-galaxy [core 2.20.1] 2026-09-24 00:47:33.877863 | controller | config file = None 2026-09-24 00:47:33.877879 | 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:33.877895 | 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:33.877903 | 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:33.877910 | controller | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy 2026-09-24 00:47:33.877918 | 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:33.877924 | controller | jinja version = 3.1.6 2026-09-24 00:47:33.877931 | controller | pyyaml version = 6.0.3 (with libyaml v0.2.5) 2026-09-24 00:47:33.877938 | controller | No config file found; using defaults 2026-09-24 00:47:33.877945 | controller | Created /home/zuul/.ansible/galaxy_token 2026-09-24 00:47:33.877952 | controller | [ERROR]: Failed to download collection tar from 'default': 2026-09-24 00:47:33.877959 | controller | 2026-09-24 00:47:33.877966 | controller | Failed to download collection tar from 'default': 2026-09-24 00:47:33.877973 | controller | 2026-09-24 00:47:33.877980 | controller | <<< caused by >>> 2026-09-24 00:47:33.877987 | controller | 2026-09-24 00:47:33.877994 | controller | 2026-09-24 00:47:33.878001 | controller | 2026-09-24 00:47:33.878007 | controller | 2026-09-24 00:47:34.041294 | controller | ERROR 2026-09-24 00:47:34.041505 | controller | { 2026-09-24 00:47:34.041533 | controller | "delta": "0:01:12.644856", 2026-09-24 00:47:34.041553 | controller | "end": "2026-09-24 00:47:33.970697", 2026-09-24 00:47:34.041571 | controller | "msg": "non-zero return code", 2026-09-24 00:47:34.041589 | controller | "rc": 1, 2026-09-24 00:47:34.041606 | controller | "start": "2026-09-24 00:46:21.325841" 2026-09-24 00:47:34.041622 | controller | } failure 2026-09-24 00:47:34.043774 | 2026-09-24 00:47:34.043819 | PLAY RECAP 2026-09-24 00:47:34.043863 | controller | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-24 00:47:34.043885 | 2026-09-24 00:47:34.157515 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-24 00:47:34.158894 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-09-24 00:47:34.826657 | 2026-09-24 00:47:34.826790 | PLAY [all] 2026-09-24 00:47:34.836785 | 2026-09-24 00:47:34.836856 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-09-24 00:47:35.268155 | controller | changed 2026-09-24 00:47:35.272122 | 2026-09-24 00:47:35.272201 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-09-24 00:47:35.687132 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-24 00:47:35.687502 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-24 00:47:35.694986 | controller | environment: line 1: kubectl: command not found 2026-09-24 00:47:35.697603 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-24 00:47:35.700096 | controller | environment: line 1: kubectl: command not found 2026-09-24 00:47:35.702198 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-24 00:47:35.703067 | controller | environment: line 1: kubectl: command not found 2026-09-24 00:47:35.705112 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-24 00:47:35.709527 | controller | environment: line 1: kubectl: command not found 2026-09-24 00:47:35.710595 | controller | environment: line 1: kubectl: command not found 2026-09-24 00:47:35.711689 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-24 00:47:35.712685 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-24 00:47:35.798453 | controller | ok: Runtime: 0:00:00.041431 2026-09-24 00:47:35.805788 | 2026-09-24 00:47:35.805912 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-09-24 00:47:36.070267 | controller | changed 2026-09-24 00:47:36.077502 | 2026-09-24 00:47:36.077648 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-09-24 00:47:36.371867 | controller | environment: line 5: kubectl: command not found 2026-09-24 00:47:36.373251 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-24 00:47:36.373627 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-24 00:47:36.374041 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-24 00:47:36.615374 | controller | ok: Runtime: 0:00:00.015035 2026-09-24 00:47:36.624260 | 2026-09-24 00:47:36.624393 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-09-24 00:47:37.303966 | controller | changed: 2026-09-24 00:47:37.304243 | controller | created directory /var/lib/zuul/builds/45584d2c077c45188bf72bce8de4dabb/work/logs/controller 2026-09-24 00:47:37.304314 | controller | cd+++++++++ objects/ 2026-09-24 00:47:37.304358 | controller | cd+++++++++ objects/cluster/ 2026-09-24 00:47:37.304402 | controller | cd+++++++++ objects/namespaced/ 2026-09-24 00:47:37.317897 | 2026-09-24 00:47:37.318023 | TASK [gather-pod-logs : creating directory for pod logs] 2026-09-24 00:47:37.576309 | controller | changed 2026-09-24 00:47:37.583987 | 2026-09-24 00:47:37.584180 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-09-24 00:47:37.842693 | controller | changed 2026-09-24 00:47:37.850414 | 2026-09-24 00:47:37.850564 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-09-24 00:47:38.133624 | controller | environment: line 3: kubectl: command not found 2026-09-24 00:47:38.393321 | controller | ok: Runtime: 0:00:00.014316 2026-09-24 00:47:38.400968 | 2026-09-24 00:47:38.401099 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-09-24 00:47:38.939220 | controller | changed: 2026-09-24 00:47:38.939498 | controller | cd+++++++++ pod-logs/ 2026-09-24 00:47:38.939553 | controller | cd+++++++++ pod-logs/failed-pods/ 2026-09-24 00:47:38.953365 | 2026-09-24 00:47:38.953501 | LOOP [helm-release-status : creating directory for helm release status] 2026-09-24 00:47:39.226370 | controller | changed: "values" 2026-09-24 00:47:39.455385 | controller | changed: "releases" 2026-09-24 00:47:39.462158 | 2026-09-24 00:47:39.462332 | TASK [helm-release-status : Gather get release status for helm charts] 2026-09-24 00:47:39.719537 | controller | /bin/bash: line 3: kubectl: command not found 2026-09-24 00:47:39.989254 | controller | ok: Runtime: 0:00:00.009126 2026-09-24 00:47:39.996739 | 2026-09-24 00:47:39.996869 | TASK [helm-release-status : Downloads logs to executor] 2026-09-24 00:47:40.545605 | controller | changed: 2026-09-24 00:47:40.545846 | controller | cd+++++++++ helm/ 2026-09-24 00:47:40.545900 | controller | cd+++++++++ helm/releases/ 2026-09-24 00:47:40.545946 | controller | cd+++++++++ helm/values/ 2026-09-24 00:47:40.554129 | 2026-09-24 00:47:40.554221 | PLAY RECAP 2026-09-24 00:47:40.554301 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-24 00:47:40.554354 | 2026-09-24 00:47:40.674787 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-09-24 00:47:40.677384 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-24 00:47:41.248926 | 2026-09-24 00:47:41.249045 | PLAY [all] 2026-09-24 00:47:41.257891 | 2026-09-24 00:47:41.257963 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-24 00:47:41.291518 | controller | skipping: Conditional result was False 2026-09-24 00:47:41.295218 | 2026-09-24 00:47:41.295288 | TASK [fetch-output : Set log path for single node] 2026-09-24 00:47:41.344118 | controller | ok 2026-09-24 00:47:41.350986 | 2026-09-24 00:47:41.351133 | LOOP [fetch-output : Ensure local output dirs] 2026-09-24 00:47:41.725403 | controller -> localhost | ok: "/var/lib/zuul/builds/45584d2c077c45188bf72bce8de4dabb/work/logs" 2026-09-24 00:47:41.946396 | controller -> localhost | changed: "/var/lib/zuul/builds/45584d2c077c45188bf72bce8de4dabb/work/artifacts" 2026-09-24 00:47:42.162182 | controller -> localhost | changed: "/var/lib/zuul/builds/45584d2c077c45188bf72bce8de4dabb/work/docs" 2026-09-24 00:47:42.177337 | 2026-09-24 00:47:42.177517 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-24 00:47:42.870281 | controller | changed: .d..t...... ./ 2026-09-24 00:47:42.870673 | controller | changed: All items complete 2026-09-24 00:47:42.870746 | 2026-09-24 00:47:43.391578 | controller | changed: .d..t...... ./ 2026-09-24 00:47:43.885503 | controller | changed: .d..t...... ./ 2026-09-24 00:47:43.913459 | 2026-09-24 00:47:43.913654 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-24 00:47:44.353164 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.005547 2026-09-24 00:47:44.549375 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.005684 2026-09-24 00:47:44.572462 | 2026-09-24 00:47:44.572696 | PLAY [all] 2026-09-24 00:47:44.585277 | 2026-09-24 00:47:44.585404 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-24 00:47:45.034945 | controller | changed 2026-09-24 00:47:45.042958 | 2026-09-24 00:47:45.043048 | PLAY RECAP 2026-09-24 00:47:45.043155 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-24 00:47:45.043202 | 2026-09-24 00:47:45.177858 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-24 00:47:45.193121 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-24 00:47:45.718984 | 2026-09-24 00:47:45.719118 | PLAY [localhost] 2026-09-24 00:47:45.728070 | 2026-09-24 00:47:45.728156 | TASK [Generate Zuul manifest] 2026-09-24 00:47:45.744789 | localhost | ok 2026-09-24 00:47:45.757708 | 2026-09-24 00:47:45.757783 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-24 00:47:46.152139 | localhost | changed 2026-09-24 00:47:46.171494 | 2026-09-24 00:47:46.171672 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-24 00:47:46.202535 | localhost | ok 2026-09-24 00:47:46.214925 | 2026-09-24 00:47:46.215062 | TASK [Upload logs] 2026-09-24 00:47:46.235523 | localhost | ok 2026-09-24 00:47:46.302744 | 2026-09-24 00:47:46.302878 | TASK [Set zuul-log-path fact] 2026-09-24 00:47:46.319090 | localhost | ok 2026-09-24 00:47:46.328602 | 2026-09-24 00:47:46.328692 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-24 00:47:46.356357 | localhost | ok 2026-09-24 00:47:46.365626 | 2026-09-24 00:47:46.365720 | TASK [upload-logs : Create log directories] 2026-09-24 00:47:46.770927 | localhost | changed 2026-09-24 00:47:46.779669 | 2026-09-24 00:47:46.779821 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-24 00:47:47.186084 | localhost -> localhost | ok: Runtime: 0:00:00.005313 2026-09-24 00:47:47.195250 | 2026-09-24 00:47:47.195384 | TASK [upload-logs : Upload logs to log server] 2026-09-24 00:47:47.708287 | localhost | Output suppressed because no_log was given 2026-09-24 00:47:47.722357 | 2026-09-24 00:47:47.722850 | LOOP [upload-logs : Compress console log and json output] 2026-09-24 00:47:47.785481 | localhost | skipping: Conditional result was False 2026-09-24 00:47:47.829698 | localhost | skipping: Conditional result was False 2026-09-24 00:47:47.838925 | 2026-09-24 00:47:47.839074 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-24 00:47:47.904183 | localhost | skipping: Conditional result was False 2026-09-24 00:47:47.904584 | 2026-09-24 00:47:47.909895 | localhost | skipping: Conditional result was False 2026-09-24 00:47:47.923270 | 2026-09-24 00:47:47.923411 | LOOP [upload-logs : Upload console log and json output]