2026-06-08 08:39:01.763588 | Job console starting 2026-06-08 08:39:01.791173 | Updating git repos 2026-06-08 08:39:01.868784 | Cloning repos into workspace 2026-06-08 08:39:08.931797 | Restoring repo states 2026-06-08 08:39:08.960389 | Merging changes 2026-06-08 08:39:11.409857 | Checking out repos 2026-06-08 08:39:11.841067 | Preparing playbooks 2026-06-08 08:39:27.006386 | Running Ansible setup 2026-06-08 08:39:31.967248 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-08 08:39:32.762382 | 2026-06-08 08:39:32.762506 | PLAY [localhost] 2026-06-08 08:39:32.770568 | 2026-06-08 08:39:32.770659 | TASK [Gathering Facts] 2026-06-08 08:39:33.712749 | localhost | ok 2026-06-08 08:39:33.724504 | 2026-06-08 08:39:33.724599 | TASK [Setup log path fact] 2026-06-08 08:39:33.746685 | localhost | ok 2026-06-08 08:39:33.761030 | 2026-06-08 08:39:33.761231 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-08 08:39:33.791918 | localhost | ok 2026-06-08 08:39:33.801437 | 2026-06-08 08:39:33.801591 | TASK [emit-job-header : Print job information] 2026-06-08 08:39:33.843697 | # Job Information 2026-06-08 08:39:33.843900 | Ansible Version: 2.16.18 2026-06-08 08:39:33.843947 | Job: ansible-collection-kubernetes-molecule-cluster-api 2026-06-08 08:39:33.843979 | Pipeline: check 2026-06-08 08:39:33.844008 | Executor: 2d72f0692154 2026-06-08 08:39:33.844036 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/279 2026-06-08 08:39:33.844068 | Event ID: 5782d0d0-6315-11f1-8a9d-e779b683d8a0 2026-06-08 08:39:33.847900 | 2026-06-08 08:39:33.848000 | LOOP [emit-job-header : Print node information] 2026-06-08 08:39:33.935705 | localhost | ok: 2026-06-08 08:39:33.935926 | localhost | # Node Information 2026-06-08 08:39:33.935989 | localhost | Inventory Hostname: controller 2026-06-08 08:39:33.936036 | localhost | Hostname: np0000178946 2026-06-08 08:39:33.936084 | localhost | Username: zuul 2026-06-08 08:39:33.936133 | localhost | Distro: Debian 13.5 2026-06-08 08:39:33.949106 | localhost | Provider: yul1 2026-06-08 08:39:33.949257 | localhost | Region: ca-ymq-1 2026-06-08 08:39:33.949283 | localhost | Label: debian-trixie 2026-06-08 08:39:33.949305 | localhost | Product Name: OpenStack Nova 2026-06-08 08:39:33.949327 | localhost | Interface IP: 199.19.213.176 2026-06-08 08:39:33.966479 | 2026-06-08 08:39:33.966629 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-08 08:39:34.407900 | localhost -> localhost | changed 2026-06-08 08:39:34.414896 | 2026-06-08 08:39:34.415037 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-08 08:39:35.782630 | localhost -> localhost | changed 2026-06-08 08:39:35.792654 | 2026-06-08 08:39:35.792731 | PLAY [all] 2026-06-08 08:39:35.803587 | 2026-06-08 08:39:35.803675 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-08 08:39:36.093282 | controller -> localhost | ok 2026-06-08 08:39:36.100101 | 2026-06-08 08:39:36.100291 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-08 08:39:36.132736 | controller | ok 2026-06-08 08:39:36.148905 | controller | included: /var/lib/zuul/builds/f332812a596c44f2b03a48cc212cba1b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-08 08:39:36.154803 | 2026-06-08 08:39:36.154936 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-08 08:39:37.421411 | controller -> localhost | Generating public/private rsa key pair. 2026-06-08 08:39:37.421585 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/f332812a596c44f2b03a48cc212cba1b/work/f332812a596c44f2b03a48cc212cba1b_id_rsa 2026-06-08 08:39:37.421615 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/f332812a596c44f2b03a48cc212cba1b/work/f332812a596c44f2b03a48cc212cba1b_id_rsa.pub 2026-06-08 08:39:37.421640 | controller -> localhost | The key fingerprint is: 2026-06-08 08:39:37.421661 | controller -> localhost | SHA256:9ixlaiJqAi+dkkEcz+RljecFrGKzfvigwRAyJRoO+e4 zuul-build-sshkey 2026-06-08 08:39:37.421693 | controller -> localhost | The key's randomart image is: 2026-06-08 08:39:37.421715 | controller -> localhost | +---[RSA 3072]----+ 2026-06-08 08:39:37.421740 | controller -> localhost | |+.. +.. | 2026-06-08 08:39:37.421761 | controller -> localhost | |== . + + . | 2026-06-08 08:39:37.421782 | controller -> localhost | |*o* o + . | 2026-06-08 08:39:37.421802 | controller -> localhost | |.=.B . . | 2026-06-08 08:39:37.421822 | controller -> localhost | |o.. + S o | 2026-06-08 08:39:37.421842 | controller -> localhost | |= .. . * | 2026-06-08 08:39:37.421862 | controller -> localhost | |.O.oo . + o | 2026-06-08 08:39:37.421881 | controller -> localhost | |= E+.o o . | 2026-06-08 08:39:37.421904 | controller -> localhost | | *. o. | 2026-06-08 08:39:37.421925 | controller -> localhost | +----[SHA256]-----+ 2026-06-08 08:39:37.422009 | controller -> localhost | ok: Runtime: 0:00:00.747604 2026-06-08 08:39:37.426397 | 2026-06-08 08:39:37.426463 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-08 08:39:37.457873 | controller | ok 2026-06-08 08:39:37.466848 | controller | included: /var/lib/zuul/builds/f332812a596c44f2b03a48cc212cba1b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-08 08:39:37.473218 | 2026-06-08 08:39:37.473286 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-08 08:39:37.497766 | controller | skipping: Conditional result was False 2026-06-08 08:39:37.506542 | 2026-06-08 08:39:37.506715 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-08 08:39:38.132649 | controller | changed 2026-06-08 08:39:38.139391 | 2026-06-08 08:39:38.139458 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-08 08:39:38.359239 | controller | ok 2026-06-08 08:39:38.373121 | 2026-06-08 08:39:38.373226 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-08 08:39:38.947785 | controller | changed 2026-06-08 08:39:38.954375 | 2026-06-08 08:39:38.954520 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-08 08:39:39.491933 | controller | changed 2026-06-08 08:39:39.499599 | 2026-06-08 08:39:39.499794 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-08 08:39:39.526828 | controller | skipping: Conditional result was False 2026-06-08 08:39:39.537015 | 2026-06-08 08:39:39.537196 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-08 08:39:40.138965 | controller -> localhost | changed 2026-06-08 08:39:40.152806 | 2026-06-08 08:39:40.152993 | TASK [add-build-sshkey : Add back temp key] 2026-06-08 08:39:40.468237 | controller -> localhost | Identity added: /var/lib/zuul/builds/f332812a596c44f2b03a48cc212cba1b/work/f332812a596c44f2b03a48cc212cba1b_id_rsa (zuul-build-sshkey) 2026-06-08 08:39:40.468475 | controller -> localhost | ok: Runtime: 0:00:00.013383 2026-06-08 08:39:40.473899 | 2026-06-08 08:39:40.473985 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-08 08:39:40.841113 | controller | ok 2026-06-08 08:39:40.847567 | 2026-06-08 08:39:40.847667 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-08 08:39:40.873906 | controller | skipping: Conditional result was False 2026-06-08 08:39:40.883624 | 2026-06-08 08:39:40.883783 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-08 08:39:41.290805 | controller | ok 2026-06-08 08:39:41.297389 | 2026-06-08 08:39:41.297511 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-08 08:39:42.386696 | controller | Output suppressed because no_log was given 2026-06-08 08:39:42.393890 | 2026-06-08 08:39:42.393967 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-08 08:39:42.646458 | controller | ok: "logs" 2026-06-08 08:39:42.646788 | controller | ok: All items complete 2026-06-08 08:39:42.646837 | 2026-06-08 08:39:42.823768 | controller | ok: "artifacts" 2026-06-08 08:39:43.017254 | controller | ok: "docs" 2026-06-08 08:39:43.028720 | 2026-06-08 08:39:43.028865 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-08 08:39:43.283142 | controller | changed: "logs" 2026-06-08 08:39:43.461213 | controller | changed: "artifacts" 2026-06-08 08:39:43.650294 | controller | changed: "docs" 2026-06-08 08:39:43.675418 | 2026-06-08 08:39:43.675579 | PLAY RECAP 2026-06-08 08:39:43.675733 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-06-08 08:39:43.675914 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-08 08:39:43.675957 | 2026-06-08 08:39:43.838319 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-08 08:39:44.852010 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-08 08:39:45.559271 | 2026-06-08 08:39:45.559460 | PLAY [all] 2026-06-08 08:39:45.570180 | 2026-06-08 08:39:45.570288 | TASK [setup-uv : Extract archive] 2026-06-08 08:39:48.670689 | controller | changed 2026-06-08 08:39:48.676671 | 2026-06-08 08:39:48.676815 | TASK [setup-uv : Print version] 2026-06-08 08:39:49.064357 | controller | uv 0.8.13 2026-06-08 08:39:49.210246 | controller | ok: Runtime: 0:00:00.015471 2026-06-08 08:39:49.219248 | 2026-06-08 08:39:49.219352 | TASK [Install Ansible collection siblings] 2026-06-08 08:39:49.648604 | controller | ok: 2026-06-08 08:39:49.648841 | controller | Skipping ansible.posix: no checked-out sibling 2026-06-08 08:39:49.648894 | controller | Skipping community.crypto: no checked-out sibling 2026-06-08 08:39:49.648927 | controller | Skipping community.general: no checked-out sibling 2026-06-08 08:39:49.648976 | controller | Skipping kubernetes.core: no checked-out sibling 2026-06-08 08:39:49.649009 | controller | Skipping vexxhost.containers: no checked-out sibling 2026-06-08 08:39:49.651155 | 2026-06-08 08:39:49.651213 | PLAY RECAP 2026-06-08 08:39:49.651274 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-08 08:39:49.651305 | 2026-06-08 08:39:52.821311 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-08 08:39:52.846541 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-06-08 08:39:53.628049 | 2026-06-08 08:39:53.628268 | PLAY [all] 2026-06-08 08:39:53.640016 | 2026-06-08 08:39:53.640162 | TASK [Update apt cache] 2026-06-08 08:40:00.099431 | controller | ok 2026-06-08 08:40:00.109888 | 2026-06-08 08:40:00.110083 | TASK [Disable firewalld] 2026-06-08 08:40:00.139950 | controller | skipping: Conditional result was False 2026-06-08 08:40:00.147985 | 2026-06-08 08:40:00.148067 | TASK [Install "jq" for log collection] 2026-06-08 08:40:02.512042 | controller | changed 2026-06-08 08:40:02.514244 | 2026-06-08 08:40:02.514298 | PLAY RECAP 2026-06-08 08:40:02.514344 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-08 08:40:02.514366 | 2026-06-08 08:40:02.680919 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-06-08 08:40:02.683074 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-06-08 08:40:03.346315 | 2026-06-08 08:40:03.346521 | PLAY [all] 2026-06-08 08:40:03.356678 | 2026-06-08 08:40:03.356759 | TASK [Copy inventory file for Zuul] 2026-06-08 08:40:04.444388 | controller | changed 2026-06-08 08:40:04.449243 | 2026-06-08 08:40:04.449348 | TASK [Switch "ansible_host" to private IP] 2026-06-08 08:40:04.847606 | controller | changed: 1 replacements made 2026-06-08 08:40:04.854446 | 2026-06-08 08:40:04.854622 | TASK [Run Molecule scenario] 2026-06-08 08:40:05.317193 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-06-08 08:40:05.317315 | controller | Creating virtual environment at: .venv 2026-06-08 08:40:05.591638 | controller | Downloading ansible-core (2.3MiB) 2026-06-08 08:40:05.593454 | controller | Downloading cryptography (4.3MiB) 2026-06-08 08:40:05.594131 | controller | Downloading pygments (1.2MiB) 2026-06-08 08:40:05.752878 | controller | Downloading cryptography 2026-06-08 08:40:05.810168 | controller | Downloading pygments 2026-06-08 08:40:05.979916 | controller | Downloading ansible-core 2026-06-08 08:40:06.026450 | controller | Installed 36 packages in 45ms 2026-06-08 08:40:06.763871 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-06-08 08:40:06.764011 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-06-08 08:40:07.656313 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-06-08 08:40:07.656401 | controller | INFO default scenario not found, disabling shared state. 2026-06-08 08:40:07.656470 | controller | INFO [cluster-api > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-06-08 08:40:07.656622 | controller | INFO [cluster-api > prerun] Performing prerun with role_name_check=0... 2026-06-08 08:40:11.052898 | controller | WARNING Retrying execution failure 1 of: ansible-galaxy collection install -vvv ansible.posix:>=1.6.0 2026-06-08 08:40:11.053027 | controller | ERROR Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code: 2026-06-08 08:40:11.053034 | controller | Starting galaxy collection install process 2026-06-08 08:40:11.053038 | controller | Process install dependency map 2026-06-08 08:40:11.053042 | controller | Starting collection install process 2026-06-08 08:40:11.053064 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-posix-2.2.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1482y48lkgrl/tmpaejsowdj/ansible-posix-2.2.0-cjfsm5yp 2026-06-08 08:40:11.053074 | controller | 2026-06-08 08:40:11.053079 | controller | ansible-galaxy 2026-06-08 08:40:11.053083 | controller | config file = None 2026-06-08 08:40:11.053088 | controller | configured module search path = 2026-06-08 08:40:11.053092 | controller | ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible 2026-06-08 08:40:11.053106 | 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-06-08 08:40:11.053112 | controller | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy 2026-06-08 08:40:11.053116 | controller | python version = 3.13.5 (main, May 5 2026, 21:05:52) (/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/python) 2026-06-08 08:40:11.053119 | controller | jinja version = 3.1.6 2026-06-08 08:40:11.053123 | controller | pyyaml version = 6.0.3 (with libyaml v0.2.5) 2026-06-08 08:40:11.053127 | controller | No config file found; using defaults 2026-06-08 08:40:11.053130 | controller | Created /home/zuul/.ansible/galaxy_token 2026-06-08 08:40:11.053134 | controller | : Failed to download collection tar from 'default': 2026-06-08 08:40:11.053138 | controller | 2026-06-08 08:40:11.053142 | controller | Failed to download collection tar from 'default': 2026-06-08 08:40:11.053148 | controller | 2026-06-08 08:40:11.053160 | controller | <<< caused by >>> 2026-06-08 08:40:11.053165 | controller | 2026-06-08 08:40:11.053170 | controller | 2026-06-08 08:40:11.053175 | controller | 2026-06-08 08:40:11.053179 | controller | 2026-06-08 08:40:11.057778 | controller | Traceback (most recent call last): 2026-06-08 08:40:11.060476 | controller | File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/molecule", line 10, in 2026-06-08 08:40:11.060510 | controller | sys.exit(main()) 2026-06-08 08:40:11.060516 | controller | ~~~~^^ 2026-06-08 08:40:11.060690 | 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-06-08 08:40:11.060700 | controller | return self.main(*args, **kwargs) 2026-06-08 08:40:11.060706 | controller | ~~~~~~~~~^^^^^^^^^^^^^^^^^ 2026-06-08 08:40:11.060888 | 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-06-08 08:40:11.060895 | controller | rv = self.invoke(ctx) 2026-06-08 08:40:11.061129 | 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-06-08 08:40:11.061143 | controller | return _process_result(sub_ctx.command.invoke(sub_ctx)) 2026-06-08 08:40:11.061148 | controller | ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^ 2026-06-08 08:40:11.061342 | 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-06-08 08:40:11.061358 | controller | return ctx.invoke(self.callback, **ctx.params) 2026-06-08 08:40:11.061365 | controller | ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-06-08 08:40:11.061575 | 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-06-08 08:40:11.061601 | controller | return callback(*args, **kwargs) 2026-06-08 08:40:11.061752 | 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-06-08 08:40:11.061763 | controller | return func(*args, **kwargs) 2026-06-08 08:40:11.061940 | 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-06-08 08:40:11.061949 | controller | return f(get_current_context(), *args, **kwargs) 2026-06-08 08:40:11.062114 | 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-06-08 08:40:11.062123 | controller | return func(ctx) 2026-06-08 08:40:11.062342 | 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-06-08 08:40:11.062352 | controller | base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude) 2026-06-08 08:40:11.062361 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-06-08 08:40:11.062536 | 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-06-08 08:40:11.062545 | controller | _run_scenarios(scenarios, command_args, default_config) 2026-06-08 08:40:11.062555 | controller | ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-06-08 08:40:11.062888 | 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-06-08 08:40:11.062904 | controller | scenario.config.runtime.prepare_environment( 2026-06-08 08:40:11.062909 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-06-08 08:40:11.062914 | controller | install_local=True, 2026-06-08 08:40:11.062918 | controller | ^^^^^^^^^^^^^^^^^^^ 2026-06-08 08:40:11.062923 | controller | role_name_check=role_name_check, 2026-06-08 08:40:11.062928 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-06-08 08:40:11.062932 | controller | ) 2026-06-08 08:40:11.062937 | controller | ^ 2026-06-08 08:40:11.063195 | 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-06-08 08:40:11.063202 | controller | self.install_collection( 2026-06-08 08:40:11.063206 | controller | ~~~~~~~~~~~~~~~~~~~~~~~^ 2026-06-08 08:40:11.063217 | controller | f"{name}{',' if is_url(name) else ':'}{required_version}", 2026-06-08 08:40:11.063221 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-06-08 08:40:11.063226 | controller | destination=destination, 2026-06-08 08:40:11.063231 | controller | ^^^^^^^^^^^^^^^^^^^^^^^^ 2026-06-08 08:40:11.063235 | controller | ) 2026-06-08 08:40:11.063240 | controller | ^ 2026-06-08 08:40:11.063378 | 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-06-08 08:40:11.063384 | controller | raise InvalidPrerequisiteError(msg) 2026-06-08 08:40:11.064312 | controller | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code: 2026-06-08 08:40:11.064327 | controller | Starting galaxy collection install process 2026-06-08 08:40:11.064332 | controller | Process install dependency map 2026-06-08 08:40:11.064336 | controller | Starting collection install process 2026-06-08 08:40:11.064341 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-posix-2.2.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1482y48lkgrl/tmpaejsowdj/ansible-posix-2.2.0-cjfsm5yp 2026-06-08 08:40:11.064346 | controller | 2026-06-08 08:40:11.064351 | controller | ansible-galaxy [core 2.20.1] 2026-06-08 08:40:11.064356 | controller | config file = None 2026-06-08 08:40:11.064367 | 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-06-08 08:40:11.064379 | controller | ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible 2026-06-08 08:40:11.064384 | 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-06-08 08:40:11.064390 | controller | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy 2026-06-08 08:40:11.064395 | controller | python version = 3.13.5 (main, May 5 2026, 21:05:52) [GCC 14.2.0] (/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/python) 2026-06-08 08:40:11.064399 | controller | jinja version = 3.1.6 2026-06-08 08:40:11.064404 | controller | pyyaml version = 6.0.3 (with libyaml v0.2.5) 2026-06-08 08:40:11.064409 | controller | No config file found; using defaults 2026-06-08 08:40:11.064413 | controller | Created /home/zuul/.ansible/galaxy_token 2026-06-08 08:40:11.064418 | controller | [ERROR]: Failed to download collection tar from 'default': 2026-06-08 08:40:11.064422 | controller | 2026-06-08 08:40:11.064427 | controller | Failed to download collection tar from 'default': 2026-06-08 08:40:11.064432 | controller | 2026-06-08 08:40:11.064436 | controller | <<< caused by >>> 2026-06-08 08:40:11.064441 | controller | 2026-06-08 08:40:11.064445 | controller | 2026-06-08 08:40:11.064450 | controller | 2026-06-08 08:40:11.064454 | controller | 2026-06-08 08:40:11.406728 | controller | ERROR 2026-06-08 08:40:11.407600 | controller | { 2026-06-08 08:40:11.407690 | controller | "delta": "0:00:05.951253", 2026-06-08 08:40:11.407744 | controller | "end": "2026-06-08 08:40:11.190767", 2026-06-08 08:40:11.407790 | controller | "msg": "non-zero return code", 2026-06-08 08:40:11.407830 | controller | "rc": 1, 2026-06-08 08:40:11.407876 | controller | "start": "2026-06-08 08:40:05.239514" 2026-06-08 08:40:11.407917 | controller | } failure 2026-06-08 08:40:11.413816 | 2026-06-08 08:40:11.413924 | PLAY RECAP 2026-06-08 08:40:11.414020 | controller | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-06-08 08:40:11.414071 | 2026-06-08 08:40:11.564919 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-06-08 08:40:11.569187 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-06-08 08:40:12.250278 | 2026-06-08 08:40:12.250429 | PLAY [all] 2026-06-08 08:40:12.262139 | 2026-06-08 08:40:12.262227 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-06-08 08:40:12.679864 | controller | changed 2026-06-08 08:40:12.686605 | 2026-06-08 08:40:12.686728 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-06-08 08:40:13.106139 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-06-08 08:40:13.106820 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-06-08 08:40:13.113227 | controller | environment: line 1: kubectl: command not found 2026-06-08 08:40:13.114181 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-06-08 08:40:13.116535 | controller | environment: line 1: kubectl: command not found 2026-06-08 08:40:13.117947 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-06-08 08:40:13.119147 | controller | environment: line 1: kubectl: command not found 2026-06-08 08:40:13.120577 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-06-08 08:40:13.122016 | controller | environment: line 1: kubectl: command not found 2026-06-08 08:40:13.123412 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-06-08 08:40:13.124920 | controller | environment: line 1: kubectl: command not found 2026-06-08 08:40:13.127129 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-06-08 08:40:13.218930 | controller | ok: Runtime: 0:00:00.035803 2026-06-08 08:40:13.224593 | 2026-06-08 08:40:13.224762 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-06-08 08:40:13.466657 | controller | changed 2026-06-08 08:40:13.470624 | 2026-06-08 08:40:13.470697 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-06-08 08:40:13.728655 | controller | environment: line 5: kubectl: command not found 2026-06-08 08:40:13.728959 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-06-08 08:40:13.729646 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-06-08 08:40:13.729824 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-06-08 08:40:14.000015 | controller | ok: Runtime: 0:00:00.011755 2026-06-08 08:40:14.007261 | 2026-06-08 08:40:14.007346 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-06-08 08:40:14.695985 | controller | changed: 2026-06-08 08:40:14.696241 | controller | created directory /var/lib/zuul/builds/f332812a596c44f2b03a48cc212cba1b/work/logs/controller 2026-06-08 08:40:14.696290 | controller | cd+++++++++ objects/ 2026-06-08 08:40:14.696325 | controller | cd+++++++++ objects/cluster/ 2026-06-08 08:40:14.696359 | controller | cd+++++++++ objects/namespaced/ 2026-06-08 08:40:14.707984 | 2026-06-08 08:40:14.708159 | TASK [gather-pod-logs : creating directory for pod logs] 2026-06-08 08:40:14.947384 | controller | changed 2026-06-08 08:40:14.951635 | 2026-06-08 08:40:14.951715 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-06-08 08:40:15.260233 | controller | changed 2026-06-08 08:40:15.266069 | 2026-06-08 08:40:15.266165 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-06-08 08:40:15.514112 | controller | environment: line 3: kubectl: command not found 2026-06-08 08:40:15.798796 | controller | ok: Runtime: 0:00:00.014739 2026-06-08 08:40:15.804591 | 2026-06-08 08:40:15.804690 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-06-08 08:40:16.333547 | controller | changed: 2026-06-08 08:40:16.333788 | controller | cd+++++++++ pod-logs/ 2026-06-08 08:40:16.333829 | controller | cd+++++++++ pod-logs/failed-pods/ 2026-06-08 08:40:16.343968 | 2026-06-08 08:40:16.344077 | LOOP [helm-release-status : creating directory for helm release status] 2026-06-08 08:40:16.608417 | controller | changed: "values" 2026-06-08 08:40:16.832876 | controller | changed: "releases" 2026-06-08 08:40:16.850064 | 2026-06-08 08:40:16.850599 | TASK [helm-release-status : Gather get release status for helm charts] 2026-06-08 08:40:17.107235 | controller | /bin/bash: line 3: kubectl: command not found 2026-06-08 08:40:17.384112 | controller | ok: Runtime: 0:00:00.008914 2026-06-08 08:40:17.388848 | 2026-06-08 08:40:17.388982 | TASK [helm-release-status : Downloads logs to executor] 2026-06-08 08:40:17.929180 | controller | changed: 2026-06-08 08:40:17.929363 | controller | cd+++++++++ helm/ 2026-06-08 08:40:17.929391 | controller | cd+++++++++ helm/releases/ 2026-06-08 08:40:17.929416 | controller | cd+++++++++ helm/values/ 2026-06-08 08:40:17.933971 | 2026-06-08 08:40:17.934017 | PLAY RECAP 2026-06-08 08:40:17.934071 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-08 08:40:17.934097 | 2026-06-08 08:40:18.163834 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-06-08 08:40:18.166403 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-08 08:40:18.846050 | 2026-06-08 08:40:18.846294 | PLAY [all] 2026-06-08 08:40:18.860139 | 2026-06-08 08:40:18.860341 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-08 08:40:18.907125 | controller | skipping: Conditional result was False 2026-06-08 08:40:18.912029 | 2026-06-08 08:40:18.912117 | TASK [fetch-output : Set log path for single node] 2026-06-08 08:40:18.954857 | controller | ok 2026-06-08 08:40:18.960180 | 2026-06-08 08:40:18.960277 | LOOP [fetch-output : Ensure local output dirs] 2026-06-08 08:40:19.407092 | controller -> localhost | ok: "/var/lib/zuul/builds/f332812a596c44f2b03a48cc212cba1b/work/logs" 2026-06-08 08:40:19.678645 | controller -> localhost | changed: "/var/lib/zuul/builds/f332812a596c44f2b03a48cc212cba1b/work/artifacts" 2026-06-08 08:40:19.897456 | controller -> localhost | changed: "/var/lib/zuul/builds/f332812a596c44f2b03a48cc212cba1b/work/docs" 2026-06-08 08:40:20.225962 | 2026-06-08 08:40:20.226579 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-08 08:40:20.898977 | controller | changed: .d..t...... ./ 2026-06-08 08:40:20.899555 | controller | changed: All items complete 2026-06-08 08:40:20.899598 | 2026-06-08 08:40:21.392070 | controller | changed: .d..t...... ./ 2026-06-08 08:40:21.899592 | controller | changed: .d..t...... ./ 2026-06-08 08:40:21.909646 | 2026-06-08 08:40:21.909745 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-08 08:40:22.368534 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007277 2026-06-08 08:40:22.623835 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008299 2026-06-08 08:40:22.645957 | 2026-06-08 08:40:22.646059 | PLAY [all] 2026-06-08 08:40:22.651134 | 2026-06-08 08:40:22.651213 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-08 08:40:23.137836 | controller | changed 2026-06-08 08:40:23.144033 | 2026-06-08 08:40:23.144090 | PLAY RECAP 2026-06-08 08:40:23.144135 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-08 08:40:23.144158 | 2026-06-08 08:40:23.330314 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-08 08:40:23.333937 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-08 08:40:24.030344 | 2026-06-08 08:40:24.030547 | PLAY [localhost] 2026-06-08 08:40:24.042041 | 2026-06-08 08:40:24.042129 | TASK [Generate Zuul manifest] 2026-06-08 08:40:24.066284 | localhost | ok 2026-06-08 08:40:24.086561 | 2026-06-08 08:40:24.088508 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-08 08:40:24.468600 | localhost | changed 2026-06-08 08:40:24.483851 | 2026-06-08 08:40:24.484030 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-08 08:40:24.517531 | localhost | ok 2026-06-08 08:40:24.528908 | 2026-06-08 08:40:24.529090 | TASK [Upload logs] 2026-06-08 08:40:24.553350 | localhost | ok 2026-06-08 08:40:24.616497 | 2026-06-08 08:40:24.616661 | TASK [Set zuul-log-path fact] 2026-06-08 08:40:24.637381 | localhost | ok 2026-06-08 08:40:24.653901 | 2026-06-08 08:40:24.654063 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-08 08:40:24.687191 | localhost | ok 2026-06-08 08:40:24.701436 | 2026-06-08 08:40:24.701618 | TASK [upload-logs : Create log directories] 2026-06-08 08:40:25.140028 | localhost | changed 2026-06-08 08:40:25.146967 | 2026-06-08 08:40:25.147065 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-08 08:40:25.601341 | localhost -> localhost | ok: Runtime: 0:00:00.005414 2026-06-08 08:40:25.611792 | 2026-06-08 08:40:25.611894 | TASK [upload-logs : Upload logs to log server] 2026-06-08 08:40:26.102357 | localhost | Output suppressed because no_log was given 2026-06-08 08:40:26.108393 | 2026-06-08 08:40:26.108565 | LOOP [upload-logs : Compress console log and json output] 2026-06-08 08:40:26.159501 | localhost | skipping: Conditional result was False 2026-06-08 08:40:26.225756 | localhost | skipping: Conditional result was False 2026-06-08 08:40:26.235037 | 2026-06-08 08:40:26.237930 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-08 08:40:26.286462 | localhost | skipping: Conditional result was False 2026-06-08 08:40:26.287027 | 2026-06-08 08:40:26.291151 | localhost | skipping: Conditional result was False 2026-06-08 08:40:26.306861 | 2026-06-08 08:40:26.307001 | LOOP [upload-logs : Upload console log and json output]