2026-09-24 00:47:52.267854 | Job console starting 2026-09-24 00:47:52.280923 | Updating git repos 2026-09-24 00:47:52.343881 | Cloning repos into workspace 2026-09-24 00:47:52.400973 | Restoring repo states 2026-09-24 00:47:52.413648 | Merging changes 2026-09-24 00:47:53.232558 | Checking out repos 2026-09-24 00:47:53.398520 | Preparing playbooks 2026-09-24 00:47:55.999820 | Running Ansible setup 2026-09-24 00:47:59.662630 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-24 00:48:00.252348 | 2026-09-24 00:48:00.252457 | PLAY [localhost] 2026-09-24 00:48:00.259108 | 2026-09-24 00:48:00.259167 | TASK [Gathering Facts] 2026-09-24 00:48:01.145856 | localhost | ok 2026-09-24 00:48:01.158170 | 2026-09-24 00:48:01.158395 | TASK [Setup log path fact] 2026-09-24 00:48:01.178602 | localhost | ok 2026-09-24 00:48:01.198139 | 2026-09-24 00:48:01.198223 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-24 00:48:01.226728 | localhost | ok 2026-09-24 00:48:01.237399 | 2026-09-24 00:48:01.237533 | TASK [emit-job-header : Print job information] 2026-09-24 00:48:01.288706 | # Job Information 2026-09-24 00:48:01.288935 | Ansible Version: 2.16.18 2026-09-24 00:48:01.289000 | Job: ansible-collection-kubernetes-molecule-cluster-api 2026-09-24 00:48:01.289043 | Pipeline: check 2026-09-24 00:48:01.289103 | Executor: 2d72f0692154 2026-09-24 00:48:01.289144 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/293 2026-09-24 00:48:01.289355 | Event ID: e533a450-b7b0-11f1-98be-9e8d6b6260a8 2026-09-24 00:48:01.294582 | 2026-09-24 00:48:01.294701 | LOOP [emit-job-header : Print node information] 2026-09-24 00:48:01.395883 | localhost | ok: 2026-09-24 00:48:01.396060 | localhost | # Node Information 2026-09-24 00:48:01.396115 | localhost | Inventory Hostname: controller 2026-09-24 00:48:01.396145 | localhost | Hostname: np0000193845 2026-09-24 00:48:01.396171 | localhost | Username: zuul 2026-09-24 00:48:01.396200 | localhost | Distro: Debian 13.7 2026-09-24 00:48:01.396225 | localhost | Provider: yul1 2026-09-24 00:48:01.396250 | localhost | Region: ca-ymq-1 2026-09-24 00:48:01.396273 | localhost | Label: debian-trixie 2026-09-24 00:48:01.396297 | localhost | Product Name: OpenStack Nova 2026-09-24 00:48:01.396321 | localhost | Interface IP: 162.253.55.164 2026-09-24 00:48:01.420652 | 2026-09-24 00:48:01.420923 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-24 00:48:01.821579 | localhost -> localhost | changed 2026-09-24 00:48:01.827375 | 2026-09-24 00:48:01.827459 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-24 00:48:02.641865 | localhost -> localhost | changed 2026-09-24 00:48:02.652250 | 2026-09-24 00:48:02.652353 | PLAY [all] 2026-09-24 00:48:02.664161 | 2026-09-24 00:48:02.664286 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-24 00:48:02.910493 | controller -> localhost | ok 2026-09-24 00:48:02.918529 | 2026-09-24 00:48:02.918657 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-24 00:48:02.939964 | controller | ok 2026-09-24 00:48:02.965159 | controller | included: /var/lib/zuul/builds/feb208d310ed4774b10c4f23392ea55a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-24 00:48:02.971403 | 2026-09-24 00:48:02.971508 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-24 00:48:04.971140 | controller -> localhost | Generating public/private rsa key pair. 2026-09-24 00:48:04.971331 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/feb208d310ed4774b10c4f23392ea55a/work/feb208d310ed4774b10c4f23392ea55a_id_rsa 2026-09-24 00:48:04.971368 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/feb208d310ed4774b10c4f23392ea55a/work/feb208d310ed4774b10c4f23392ea55a_id_rsa.pub 2026-09-24 00:48:04.971396 | controller -> localhost | The key fingerprint is: 2026-09-24 00:48:04.971423 | controller -> localhost | SHA256:nNs4dyongeYgHcONfS0M0qPIZsXX54o1l/SndVqw9Ag zuul-build-sshkey 2026-09-24 00:48:04.971464 | controller -> localhost | The key's randomart image is: 2026-09-24 00:48:04.971491 | controller -> localhost | +---[RSA 3072]----+ 2026-09-24 00:48:04.971521 | controller -> localhost | | . . . | 2026-09-24 00:48:04.971547 | controller -> localhost | | + = . o E o | 2026-09-24 00:48:04.971571 | controller -> localhost | | . + B + = o o = | 2026-09-24 00:48:04.971595 | controller -> localhost | | = * o.*.= . = +| 2026-09-24 00:48:04.971619 | controller -> localhost | | o . o =S= + + | 2026-09-24 00:48:04.971642 | controller -> localhost | | . o + o+ . . | 2026-09-24 00:48:04.971665 | controller -> localhost | | . + +.o . | 2026-09-24 00:48:04.971688 | controller -> localhost | | . oo.o | 2026-09-24 00:48:04.971716 | controller -> localhost | | +. | 2026-09-24 00:48:04.971739 | controller -> localhost | +----[SHA256]-----+ 2026-09-24 00:48:04.971792 | controller -> localhost | ok: Runtime: 0:00:01.591880 2026-09-24 00:48:04.976929 | 2026-09-24 00:48:04.977021 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-24 00:48:04.995828 | controller | ok 2026-09-24 00:48:05.007627 | controller | included: /var/lib/zuul/builds/feb208d310ed4774b10c4f23392ea55a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-24 00:48:05.015106 | 2026-09-24 00:48:05.015180 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-24 00:48:05.038570 | controller | skipping: Conditional result was False 2026-09-24 00:48:05.045967 | 2026-09-24 00:48:05.046111 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-24 00:48:05.645527 | controller | changed 2026-09-24 00:48:05.666705 | 2026-09-24 00:48:05.667529 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-24 00:48:05.880588 | controller | ok 2026-09-24 00:48:05.884281 | 2026-09-24 00:48:05.884337 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-24 00:48:06.446850 | controller | changed 2026-09-24 00:48:06.450540 | 2026-09-24 00:48:06.450640 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-24 00:48:07.036319 | controller | changed 2026-09-24 00:48:07.043741 | 2026-09-24 00:48:07.043861 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-24 00:48:07.068750 | controller | skipping: Conditional result was False 2026-09-24 00:48:07.076925 | 2026-09-24 00:48:07.077053 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-24 00:48:07.445330 | controller -> localhost | changed 2026-09-24 00:48:07.454019 | 2026-09-24 00:48:07.454095 | TASK [add-build-sshkey : Add back temp key] 2026-09-24 00:48:07.757200 | controller -> localhost | Identity added: /var/lib/zuul/builds/feb208d310ed4774b10c4f23392ea55a/work/feb208d310ed4774b10c4f23392ea55a_id_rsa (zuul-build-sshkey) 2026-09-24 00:48:07.757493 | controller -> localhost | ok: Runtime: 0:00:00.012540 2026-09-24 00:48:07.765003 | 2026-09-24 00:48:07.765151 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-24 00:48:08.119680 | controller | ok 2026-09-24 00:48:08.127189 | 2026-09-24 00:48:08.127312 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-24 00:48:08.163417 | controller | skipping: Conditional result was False 2026-09-24 00:48:08.177482 | 2026-09-24 00:48:08.177610 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-24 00:48:08.586317 | controller | ok 2026-09-24 00:48:08.595396 | 2026-09-24 00:48:08.595659 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-24 00:48:09.635901 | controller | Output suppressed because no_log was given 2026-09-24 00:48:09.653980 | 2026-09-24 00:48:09.654217 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-24 00:48:09.890720 | controller | ok: "logs" 2026-09-24 00:48:09.891103 | controller | ok: All items complete 2026-09-24 00:48:09.891201 | 2026-09-24 00:48:10.080885 | controller | ok: "artifacts" 2026-09-24 00:48:10.312226 | controller | ok: "docs" 2026-09-24 00:48:10.326188 | 2026-09-24 00:48:10.326332 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-24 00:48:10.562549 | controller | changed: "logs" 2026-09-24 00:48:10.758634 | controller | changed: "artifacts" 2026-09-24 00:48:10.927157 | controller | changed: "docs" 2026-09-24 00:48:10.947743 | 2026-09-24 00:48:10.947884 | PLAY RECAP 2026-09-24 00:48:10.947968 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-24 00:48:10.948020 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-24 00:48:10.948058 | 2026-09-24 00:48:11.111753 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-24 00:48:11.113547 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-24 00:48:11.731625 | 2026-09-24 00:48:11.731900 | PLAY [all] 2026-09-24 00:48:11.742409 | 2026-09-24 00:48:11.742478 | TASK [setup-uv : Extract archive] 2026-09-24 00:48:14.208017 | controller | changed 2026-09-24 00:48:14.214529 | 2026-09-24 00:48:14.214686 | TASK [setup-uv : Print version] 2026-09-24 00:48:14.653160 | controller | uv 0.8.13 2026-09-24 00:48:14.745103 | controller | ok: Runtime: 0:00:00.016857 2026-09-24 00:48:14.760586 | 2026-09-24 00:48:14.760744 | TASK [Install Ansible collection siblings] 2026-09-24 00:48:15.210281 | controller | ok: 2026-09-24 00:48:15.210586 | controller | Skipping ansible.posix: no checked-out sibling 2026-09-24 00:48:15.210647 | controller | Skipping community.crypto: no checked-out sibling 2026-09-24 00:48:15.210692 | controller | Skipping community.general: no checked-out sibling 2026-09-24 00:48:15.210735 | controller | Skipping kubernetes.core: no checked-out sibling 2026-09-24 00:48:15.210776 | controller | Skipping vexxhost.containers: no checked-out sibling 2026-09-24 00:48:15.213656 | 2026-09-24 00:48:15.213746 | PLAY RECAP 2026-09-24 00:48:15.213834 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-24 00:48:15.213879 | 2026-09-24 00:48:15.358844 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-24 00:48:15.361720 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-09-24 00:48:15.892844 | 2026-09-24 00:48:15.892971 | PLAY [all] 2026-09-24 00:48:15.902023 | 2026-09-24 00:48:15.902122 | TASK [Update apt cache] 2026-09-24 00:48:21.925512 | controller | ok 2026-09-24 00:48:21.933223 | 2026-09-24 00:48:21.933382 | TASK [Disable firewalld] 2026-09-24 00:48:21.958444 | controller | skipping: Conditional result was False 2026-09-24 00:48:21.966174 | 2026-09-24 00:48:21.966299 | TASK [Install "jq" for log collection] 2026-09-24 00:48:25.010576 | controller | changed 2026-09-24 00:48:25.014147 | 2026-09-24 00:48:25.014247 | PLAY RECAP 2026-09-24 00:48:25.014326 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-24 00:48:25.014369 | 2026-09-24 00:48:25.172282 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-09-24 00:48:25.174888 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-24 00:48:25.707927 | 2026-09-24 00:48:25.708038 | PLAY [all] 2026-09-24 00:48:25.717010 | 2026-09-24 00:48:25.717088 | LOOP [Check inventory directories] 2026-09-24 00:48:26.100996 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes" 2026-09-24 00:48:26.287652 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/cluster-api/inventory" 2026-09-24 00:48:26.480048 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/cluster-api/inventory" 2026-09-24 00:48:26.491453 | 2026-09-24 00:48:26.491701 | LOOP [Copy inventory file for Zuul] 2026-09-24 00:48:27.390831 | controller | changed: 2026-09-24 00:48:27.391174 | controller | { 2026-09-24 00:48:27.391257 | controller | "ansible_loop_var": "item", 2026-09-24 00:48:27.391308 | controller | "changed": false, 2026-09-24 00:48:27.391362 | controller | "failed": false, 2026-09-24 00:48:27.391435 | controller | "invocation": { 2026-09-24 00:48:27.391483 | controller | "module_args": { 2026-09-24 00:48:27.391529 | controller | "checksum_algorithm": "sha1", 2026-09-24 00:48:27.391570 | controller | "follow": true, 2026-09-24 00:48:27.391611 | controller | "get_attributes": true, 2026-09-24 00:48:27.391655 | controller | "get_checksum": true, 2026-09-24 00:48:27.391695 | controller | "get_mime": true, 2026-09-24 00:48:27.391734 | controller | "path": "src/github.com/vexxhost/ansible-collection-kubernetes" 2026-09-24 00:48:27.391774 | controller | } 2026-09-24 00:48:27.391814 | controller | }, 2026-09-24 00:48:27.391852 | controller | "item": "src/github.com/vexxhost/ansible-collection-kubernetes", 2026-09-24 00:48:27.391978 | controller | "stat": { 2026-09-24 00:48:27.392037 | controller | "atime": 1790210889.4799318, 2026-09-24 00:48:27.392103 | controller | "attr_flags": "e", 2026-09-24 00:48:27.392145 | controller | "attributes": [ 2026-09-24 00:48:27.392185 | controller | "extents" 2026-09-24 00:48:27.392224 | controller | ], 2026-09-24 00:48:27.392263 | controller | "block_size": 4096, 2026-09-24 00:48:27.392300 | controller | "blocks": 8, 2026-09-24 00:48:27.392339 | controller | "charset": "unknown", 2026-09-24 00:48:27.392382 | controller | "ctime": 1790210889.5199318, 2026-09-24 00:48:27.392422 | controller | "dev": 65025, 2026-09-24 00:48:27.392462 | controller | "device_type": 0, 2026-09-24 00:48:27.392534 | controller | "executable": true, 2026-09-24 00:48:27.392577 | controller | "exists": true, 2026-09-24 00:48:27.392616 | controller | "gid": 1000, 2026-09-24 00:48:27.392660 | controller | "gr_name": "zuul", 2026-09-24 00:48:27.392698 | controller | "inode": 519447, 2026-09-24 00:48:27.392736 | controller | "isblk": false, 2026-09-24 00:48:27.392776 | controller | "ischr": false, 2026-09-24 00:48:27.392815 | controller | "isdir": true, 2026-09-24 00:48:27.392854 | controller | "isfifo": false, 2026-09-24 00:48:27.392893 | controller | "isgid": false, 2026-09-24 00:48:27.392931 | controller | "islnk": false, 2026-09-24 00:48:27.392970 | controller | "isreg": false, 2026-09-24 00:48:27.393008 | controller | "issock": false, 2026-09-24 00:48:27.393045 | controller | "isuid": false, 2026-09-24 00:48:27.393104 | controller | "mimetype": "unknown", 2026-09-24 00:48:27.393144 | controller | "mode": "0755", 2026-09-24 00:48:27.393182 | controller | "mtime": 1790210872.6141367, 2026-09-24 00:48:27.393220 | controller | "nlink": 14, 2026-09-24 00:48:27.393257 | controller | "path": "src/github.com/vexxhost/ansible-collection-kubernetes", 2026-09-24 00:48:27.393296 | controller | "pw_name": "zuul", 2026-09-24 00:48:27.393336 | controller | "readable": true, 2026-09-24 00:48:27.393376 | controller | "rgrp": true, 2026-09-24 00:48:27.393416 | controller | "roth": true, 2026-09-24 00:48:27.393455 | controller | "rusr": true, 2026-09-24 00:48:27.393495 | controller | "size": 4096, 2026-09-24 00:48:27.393534 | controller | "uid": 1000, 2026-09-24 00:48:27.393572 | controller | "version": "3415823136", 2026-09-24 00:48:27.393612 | controller | "wgrp": false, 2026-09-24 00:48:27.393655 | controller | "woth": false, 2026-09-24 00:48:27.393694 | controller | "writeable": true, 2026-09-24 00:48:27.393734 | controller | "wusr": true, 2026-09-24 00:48:27.393773 | controller | "xgrp": true, 2026-09-24 00:48:27.393812 | controller | "xoth": true, 2026-09-24 00:48:27.393851 | controller | "xusr": true 2026-09-24 00:48:27.394004 | controller | } 2026-09-24 00:48:27.394076 | controller | } 2026-09-24 00:48:27.394146 | controller | ok: All items complete 2026-09-24 00:48:27.394187 | 2026-09-24 00:48:27.396621 | controller | skipping: Conditional result was False 2026-09-24 00:48:27.397831 | controller | skipping: Conditional result was False 2026-09-24 00:48:27.415179 | 2026-09-24 00:48:27.415370 | LOOP [Switch "ansible_host" to private IP] 2026-09-24 00:48:27.807691 | controller | changed: 1 replacements made 2026-09-24 00:48:27.809942 | controller | skipping: Conditional result was False 2026-09-24 00:48:27.815319 | controller | skipping: Conditional result was False 2026-09-24 00:48:27.838129 | 2026-09-24 00:48:27.838324 | TASK [Run Molecule scenario] 2026-09-24 00:48:28.295153 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-09-24 00:48:28.295293 | controller | Creating virtual environment at: .venv 2026-09-24 00:48:29.683684 | controller | Downloading pygments (1.2MiB) 2026-09-24 00:48:29.684972 | controller | Downloading cryptography (4.3MiB) 2026-09-24 00:48:29.696705 | controller | Downloading ansible-core (2.3MiB) 2026-09-24 00:48:29.871138 | controller | Downloading cryptography 2026-09-24 00:48:29.944146 | controller | Downloading pygments 2026-09-24 00:48:30.115766 | controller | Downloading ansible-core 2026-09-24 00:48:30.180478 | controller | Installed 36 packages in 64ms 2026-09-24 00:48:30.840621 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-09-24 00:48:30.840685 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-09-24 00:48:31.764548 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-09-24 00:48:31.764606 | controller | INFO default scenario not found, disabling shared state. 2026-09-24 00:48:31.764759 | controller | INFO [cluster-api > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-24 00:48:31.764809 | controller | INFO [cluster-api > prerun] Performing prerun with role_name_check=0... 2026-09-24 00:48:34.347398 | controller | WARNING Retrying execution failure 1 of: ansible-galaxy collection install -vvv ansible.posix:>=1.6.0 2026-09-24 00:48:34.347471 | controller | ERROR Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code: 2026-09-24 00:48:34.347483 | controller | Starting galaxy collection install process 2026-09-24 00:48:34.347493 | controller | Process install dependency map 2026-09-24 00:48:34.347505 | controller | 2026-09-24 00:48:34.347515 | controller | ansible-galaxy 2026-09-24 00:48:34.347524 | controller | config file = None 2026-09-24 00:48:34.347568 | controller | configured module search path = 2026-09-24 00:48:34.347577 | 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:34.347638 | 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:34.347651 | controller | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy 2026-09-24 00:48:34.347663 | 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:34.347673 | controller | jinja version = 3.1.6 2026-09-24 00:48:34.347682 | controller | pyyaml version = 6.0.3 (with libyaml v0.2.5) 2026-09-24 00:48:34.347692 | controller | No config file found; using defaults 2026-09-24 00:48:34.347701 | controller | Created /home/zuul/.ansible/galaxy_token 2026-09-24 00:48:34.347711 | 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:34.347721 | controller | : Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/': 2026-09-24 00:48:34.347731 | controller | 2026-09-24 00:48:34.347740 | controller | Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/'. 2026-09-24 00:48:34.347749 | controller | 2026-09-24 00:48:34.347758 | controller | <<< caused by >>> 2026-09-24 00:48:34.347767 | controller | 2026-09-24 00:48:34.347776 | controller | 2026-09-24 00:48:34.347785 | controller | 2026-09-24 00:48:34.347794 | controller | 2026-09-24 00:48:34.349945 | controller | Traceback (most recent call last): 2026-09-24 00:48:34.352021 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/molecule", line 10, in 2026-09-24 00:48:34.352055 | controller | sys.exit(main()) 2026-09-24 00:48:34.352063 | controller | ~~~~^^ 2026-09-24 00:48:34.352197 | 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:34.352207 | controller | return self.main(*args, **kwargs) 2026-09-24 00:48:34.352215 | controller | ~~~~~~~~~^^^^^^^^^^^^^^^^^ 2026-09-24 00:48:34.352418 | 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:34.352431 | controller | rv = self.invoke(ctx) 2026-09-24 00:48:34.352586 | 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:34.352595 | controller | return _process_result(sub_ctx.command.invoke(sub_ctx)) 2026-09-24 00:48:34.352602 | controller | ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^ 2026-09-24 00:48:34.352752 | 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:34.352830 | controller | return ctx.invoke(self.callback, **ctx.params) 2026-09-24 00:48:34.352839 | controller | ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:48:34.352894 | 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:34.352903 | controller | return callback(*args, **kwargs) 2026-09-24 00:48:34.353041 | 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:34.353049 | controller | return func(*args, **kwargs) 2026-09-24 00:48:34.353235 | 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:34.353245 | controller | return f(get_current_context(), *args, **kwargs) 2026-09-24 00:48:34.353311 | 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:34.353319 | controller | return func(ctx) 2026-09-24 00:48:34.353489 | 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:34.353497 | controller | base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude) 2026-09-24 00:48:34.353504 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:48:34.353701 | 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:34.353711 | controller | _run_scenarios(scenarios, command_args, default_config) 2026-09-24 00:48:34.353718 | controller | ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:48:34.353911 | 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:34.353919 | controller | scenario.config.runtime.prepare_environment( 2026-09-24 00:48:34.353926 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-24 00:48:34.353933 | controller | install_local=True, 2026-09-24 00:48:34.353940 | controller | ^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:48:34.353948 | controller | role_name_check=role_name_check, 2026-09-24 00:48:34.353955 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:48:34.353962 | controller | ) 2026-09-24 00:48:34.353969 | controller | ^ 2026-09-24 00:48:34.354161 | 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:34.354171 | controller | self.install_collection( 2026-09-24 00:48:34.354178 | controller | ~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-24 00:48:34.354193 | controller | f"{name}{',' if is_url(name) else ':'}{required_version}", 2026-09-24 00:48:34.354201 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:48:34.354208 | controller | destination=destination, 2026-09-24 00:48:34.354215 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-24 00:48:34.354222 | controller | ) 2026-09-24 00:48:34.354229 | controller | ^ 2026-09-24 00:48:34.354401 | 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:34.354410 | controller | raise InvalidPrerequisiteError(msg) 2026-09-24 00:48:34.355041 | controller | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code: 2026-09-24 00:48:34.355057 | controller | Starting galaxy collection install process 2026-09-24 00:48:34.355064 | controller | Process install dependency map 2026-09-24 00:48:34.355072 | controller | 2026-09-24 00:48:34.355080 | controller | ansible-galaxy [core 2.20.1] 2026-09-24 00:48:34.355087 | controller | config file = None 2026-09-24 00:48:34.355094 | 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:34.355102 | 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:34.355136 | 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:34.355147 | controller | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy 2026-09-24 00:48:34.355157 | 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:34.355165 | controller | jinja version = 3.1.6 2026-09-24 00:48:34.355172 | controller | pyyaml version = 6.0.3 (with libyaml v0.2.5) 2026-09-24 00:48:34.355179 | controller | No config file found; using defaults 2026-09-24 00:48:34.355202 | controller | Created /home/zuul/.ansible/galaxy_token 2026-09-24 00:48:34.355212 | 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:34.355222 | controller | [ERROR]: Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/': 2026-09-24 00:48:34.355231 | controller | 2026-09-24 00:48:34.355255 | controller | Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/'. 2026-09-24 00:48:34.355385 | controller | 2026-09-24 00:48:34.355395 | controller | <<< caused by >>> 2026-09-24 00:48:34.355402 | controller | 2026-09-24 00:48:34.355409 | controller | 2026-09-24 00:48:34.355417 | controller | 2026-09-24 00:48:34.355425 | controller | 2026-09-24 00:48:34.886679 | controller | ERROR 2026-09-24 00:48:34.886991 | controller | { 2026-09-24 00:48:34.887066 | controller | "delta": "0:00:06.253733", 2026-09-24 00:48:34.887110 | controller | "end": "2026-09-24 00:48:34.478269", 2026-09-24 00:48:34.887148 | controller | "msg": "non-zero return code", 2026-09-24 00:48:34.887184 | controller | "rc": 1, 2026-09-24 00:48:34.887219 | controller | "start": "2026-09-24 00:48:28.224536" 2026-09-24 00:48:34.887253 | controller | } failure 2026-09-24 00:48:34.892303 | 2026-09-24 00:48:34.892398 | PLAY RECAP 2026-09-24 00:48:34.892482 | controller | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-24 00:48:34.892556 | 2026-09-24 00:48:35.105174 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-24 00:48:35.107285 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-09-24 00:48:35.693894 | 2026-09-24 00:48:35.694017 | PLAY [all] 2026-09-24 00:48:35.703824 | 2026-09-24 00:48:35.703895 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-09-24 00:48:36.169887 | controller | changed 2026-09-24 00:48:36.177949 | 2026-09-24 00:48:36.178124 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-09-24 00:48:36.638855 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-24 00:48:36.638996 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-24 00:48:36.647201 | controller | environment: line 1: kubectl: command not found 2026-09-24 00:48:36.649583 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-24 00:48:36.650844 | controller | environment: line 1: kubectl: command not found 2026-09-24 00:48:36.652733 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-24 00:48:36.655741 | controller | environment: line 1: kubectl: command not found 2026-09-24 00:48:36.657969 | controller | environment: line 1: kubectl: command not found 2026-09-24 00:48:36.658063 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-24 00:48:36.659534 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-24 00:48:36.662678 | controller | environment: line 1: kubectl: command not found 2026-09-24 00:48:36.664699 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-24 00:48:36.727797 | controller | ok: Runtime: 0:00:00.041298 2026-09-24 00:48:36.735420 | 2026-09-24 00:48:36.735547 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-09-24 00:48:36.990700 | controller | changed 2026-09-24 00:48:36.998767 | 2026-09-24 00:48:36.998911 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-09-24 00:48:37.307749 | controller | environment: line 5: kubectl: command not found 2026-09-24 00:48:37.309738 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-24 00:48:37.309752 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-24 00:48:37.310262 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-24 00:48:37.529338 | controller | ok: Runtime: 0:00:00.015376 2026-09-24 00:48:37.533568 | 2026-09-24 00:48:37.533652 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-09-24 00:48:38.242175 | controller | changed: 2026-09-24 00:48:38.242426 | controller | created directory /var/lib/zuul/builds/feb208d310ed4774b10c4f23392ea55a/work/logs/controller 2026-09-24 00:48:38.242489 | controller | cd+++++++++ objects/ 2026-09-24 00:48:38.242535 | controller | cd+++++++++ objects/cluster/ 2026-09-24 00:48:38.242581 | controller | cd+++++++++ objects/namespaced/ 2026-09-24 00:48:38.255803 | 2026-09-24 00:48:38.255933 | TASK [gather-pod-logs : creating directory for pod logs] 2026-09-24 00:48:38.542028 | controller | changed 2026-09-24 00:48:38.549366 | 2026-09-24 00:48:38.549494 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-09-24 00:48:38.813412 | controller | changed 2026-09-24 00:48:38.820823 | 2026-09-24 00:48:38.820953 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-09-24 00:48:39.088119 | controller | environment: line 3: kubectl: command not found 2026-09-24 00:48:39.357759 | controller | ok: Runtime: 0:00:00.013347 2026-09-24 00:48:39.366157 | 2026-09-24 00:48:39.366289 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-09-24 00:48:39.892792 | controller | changed: 2026-09-24 00:48:39.893034 | controller | cd+++++++++ pod-logs/ 2026-09-24 00:48:39.893111 | controller | cd+++++++++ pod-logs/failed-pods/ 2026-09-24 00:48:39.905981 | 2026-09-24 00:48:39.906149 | LOOP [helm-release-status : creating directory for helm release status] 2026-09-24 00:48:40.195311 | controller | changed: "values" 2026-09-24 00:48:40.428463 | controller | changed: "releases" 2026-09-24 00:48:40.439704 | 2026-09-24 00:48:40.439932 | TASK [helm-release-status : Gather get release status for helm charts] 2026-09-24 00:48:40.720199 | controller | /bin/bash: line 3: kubectl: command not found 2026-09-24 00:48:40.979422 | controller | ok: Runtime: 0:00:00.009081 2026-09-24 00:48:40.986644 | 2026-09-24 00:48:40.986761 | TASK [helm-release-status : Downloads logs to executor] 2026-09-24 00:48:41.533851 | controller | changed: 2026-09-24 00:48:41.534131 | controller | cd+++++++++ helm/ 2026-09-24 00:48:41.534190 | controller | cd+++++++++ helm/releases/ 2026-09-24 00:48:41.534237 | controller | cd+++++++++ helm/values/ 2026-09-24 00:48:41.542916 | 2026-09-24 00:48:41.543012 | PLAY RECAP 2026-09-24 00:48:41.543124 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-24 00:48:41.543191 | 2026-09-24 00:48:41.655990 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-09-24 00:48:41.658777 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-24 00:48:42.262448 | 2026-09-24 00:48:42.262600 | PLAY [all] 2026-09-24 00:48:42.272871 | 2026-09-24 00:48:42.273003 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-24 00:48:42.334142 | controller | skipping: Conditional result was False 2026-09-24 00:48:42.340131 | 2026-09-24 00:48:42.340240 | TASK [fetch-output : Set log path for single node] 2026-09-24 00:48:42.389252 | controller | ok 2026-09-24 00:48:42.394602 | 2026-09-24 00:48:42.394689 | LOOP [fetch-output : Ensure local output dirs] 2026-09-24 00:48:42.793228 | controller -> localhost | ok: "/var/lib/zuul/builds/feb208d310ed4774b10c4f23392ea55a/work/logs" 2026-09-24 00:48:43.013345 | controller -> localhost | changed: "/var/lib/zuul/builds/feb208d310ed4774b10c4f23392ea55a/work/artifacts" 2026-09-24 00:48:43.226840 | controller -> localhost | changed: "/var/lib/zuul/builds/feb208d310ed4774b10c4f23392ea55a/work/docs" 2026-09-24 00:48:43.242068 | 2026-09-24 00:48:43.242241 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-24 00:48:43.899850 | controller | changed: .d..t...... ./ 2026-09-24 00:48:43.900032 | controller | changed: All items complete 2026-09-24 00:48:43.900068 | 2026-09-24 00:48:44.401894 | controller | changed: .d..t...... ./ 2026-09-24 00:48:44.891736 | controller | changed: .d..t...... ./ 2026-09-24 00:48:44.912064 | 2026-09-24 00:48:44.912255 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-24 00:48:45.310901 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006081 2026-09-24 00:48:45.564376 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006794 2026-09-24 00:48:45.583497 | 2026-09-24 00:48:45.584098 | PLAY [all] 2026-09-24 00:48:45.593862 | 2026-09-24 00:48:45.593990 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-24 00:48:46.073795 | controller | changed 2026-09-24 00:48:46.094728 | 2026-09-24 00:48:46.095000 | PLAY RECAP 2026-09-24 00:48:46.095143 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-24 00:48:46.095188 | 2026-09-24 00:48:46.222387 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-24 00:48:46.225153 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-24 00:48:46.788828 | 2026-09-24 00:48:46.788939 | PLAY [localhost] 2026-09-24 00:48:46.797660 | 2026-09-24 00:48:46.797732 | TASK [Generate Zuul manifest] 2026-09-24 00:48:46.814127 | localhost | ok 2026-09-24 00:48:46.826559 | 2026-09-24 00:48:46.826631 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-24 00:48:47.219097 | localhost | changed 2026-09-24 00:48:47.233202 | 2026-09-24 00:48:47.233346 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-24 00:48:47.261583 | localhost | ok 2026-09-24 00:48:47.267869 | 2026-09-24 00:48:47.267933 | TASK [Upload logs] 2026-09-24 00:48:47.284974 | localhost | ok 2026-09-24 00:48:47.337729 | 2026-09-24 00:48:47.337881 | TASK [Set zuul-log-path fact] 2026-09-24 00:48:47.366773 | localhost | ok 2026-09-24 00:48:47.378181 | 2026-09-24 00:48:47.378257 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-24 00:48:47.407083 | localhost | ok 2026-09-24 00:48:47.413981 | 2026-09-24 00:48:47.414087 | TASK [upload-logs : Create log directories] 2026-09-24 00:48:47.856450 | localhost | changed 2026-09-24 00:48:47.862144 | 2026-09-24 00:48:47.862234 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-24 00:48:48.284220 | localhost -> localhost | ok: Runtime: 0:00:00.004233 2026-09-24 00:48:48.289973 | 2026-09-24 00:48:48.290074 | TASK [upload-logs : Upload logs to log server] 2026-09-24 00:48:48.828646 | localhost | Output suppressed because no_log was given 2026-09-24 00:48:48.836760 | 2026-09-24 00:48:48.836910 | LOOP [upload-logs : Compress console log and json output] 2026-09-24 00:48:48.893581 | localhost | skipping: Conditional result was False 2026-09-24 00:48:48.936025 | localhost | skipping: Conditional result was False 2026-09-24 00:48:48.953176 | 2026-09-24 00:48:48.953377 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-24 00:48:48.992261 | localhost | skipping: Conditional result was False 2026-09-24 00:48:48.992720 | 2026-09-24 00:48:48.995881 | localhost | skipping: Conditional result was False 2026-09-24 00:48:49.006527 | 2026-09-24 00:48:49.006748 | LOOP [upload-logs : Upload console log and json output]