2026-06-08 07:29:16.563648 | Job console starting 2026-06-08 07:29:16.641765 | Updating git repos 2026-06-08 07:29:16.807524 | Cloning repos into workspace 2026-06-08 07:29:17.839211 | Restoring repo states 2026-06-08 07:29:17.859493 | Merging changes 2026-06-08 07:29:20.636848 | Checking out repos 2026-06-08 07:29:20.971300 | Preparing playbooks 2026-06-08 07:29:23.125007 | Running Ansible setup 2026-06-08 07:29:26.855048 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-08 07:29:27.569878 | 2026-06-08 07:29:27.570081 | PLAY [localhost] 2026-06-08 07:29:27.579513 | 2026-06-08 07:29:27.579679 | TASK [Gathering Facts] 2026-06-08 07:29:28.557334 | localhost | ok 2026-06-08 07:29:28.567454 | 2026-06-08 07:29:28.567558 | TASK [Setup log path fact] 2026-06-08 07:29:28.592913 | localhost | ok 2026-06-08 07:29:28.608383 | 2026-06-08 07:29:28.608559 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-08 07:29:28.651030 | localhost | ok 2026-06-08 07:29:28.661395 | 2026-06-08 07:29:28.661482 | TASK [emit-job-header : Print job information] 2026-06-08 07:29:28.703474 | # Job Information 2026-06-08 07:29:28.703763 | Ansible Version: 2.16.18 2026-06-08 07:29:28.703836 | Job: ansible-collection-kubernetes-molecule-helm 2026-06-08 07:29:28.703885 | Pipeline: check 2026-06-08 07:29:28.703930 | Executor: 2d72f0692154 2026-06-08 07:29:28.703974 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/279 2026-06-08 07:29:28.704023 | Event ID: 90fa9f50-630b-11f1-85f8-42517629e8ab 2026-06-08 07:29:28.708565 | 2026-06-08 07:29:28.708632 | LOOP [emit-job-header : Print node information] 2026-06-08 07:29:28.793502 | localhost | ok: 2026-06-08 07:29:28.794082 | localhost | # Node Information 2026-06-08 07:29:28.794127 | localhost | Inventory Hostname: controller 2026-06-08 07:29:28.794160 | localhost | Hostname: np0000178810 2026-06-08 07:29:28.794189 | localhost | Username: zuul 2026-06-08 07:29:28.794223 | localhost | Distro: Debian 13.5 2026-06-08 07:29:28.794252 | localhost | Provider: yul1 2026-06-08 07:29:28.794279 | localhost | Region: ca-ymq-1 2026-06-08 07:29:28.794307 | localhost | Label: debian-trixie 2026-06-08 07:29:28.794334 | localhost | Product Name: OpenStack Nova 2026-06-08 07:29:28.794478 | localhost | Interface IP: 199.19.213.170 2026-06-08 07:29:28.816222 | 2026-06-08 07:29:28.816404 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-08 07:29:29.278359 | localhost -> localhost | changed 2026-06-08 07:29:29.283751 | 2026-06-08 07:29:29.283838 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-08 07:29:30.178543 | localhost -> localhost | changed 2026-06-08 07:29:30.188319 | 2026-06-08 07:29:30.188416 | PLAY [all] 2026-06-08 07:29:30.195423 | 2026-06-08 07:29:30.195496 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-08 07:29:30.465475 | controller -> localhost | ok 2026-06-08 07:29:30.472004 | 2026-06-08 07:29:30.472143 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-08 07:29:30.501979 | controller | ok 2026-06-08 07:29:30.516392 | controller | included: /var/lib/zuul/builds/ecb56855898d49d6a646dc52abbeb899/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-08 07:29:30.521926 | 2026-06-08 07:29:30.522011 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-08 07:29:32.667038 | controller -> localhost | Generating public/private rsa key pair. 2026-06-08 07:29:32.667236 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/ecb56855898d49d6a646dc52abbeb899/work/ecb56855898d49d6a646dc52abbeb899_id_rsa 2026-06-08 07:29:32.667270 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/ecb56855898d49d6a646dc52abbeb899/work/ecb56855898d49d6a646dc52abbeb899_id_rsa.pub 2026-06-08 07:29:32.667295 | controller -> localhost | The key fingerprint is: 2026-06-08 07:29:32.667318 | controller -> localhost | SHA256:OZUXwvzrcp/DHEmr7HbETFyBcyPhP+bIuXS3CSfSrWc zuul-build-sshkey 2026-06-08 07:29:32.667356 | controller -> localhost | The key's randomart image is: 2026-06-08 07:29:32.667380 | controller -> localhost | +---[RSA 3072]----+ 2026-06-08 07:29:32.667405 | controller -> localhost | | o. ..o..| 2026-06-08 07:29:32.667428 | controller -> localhost | | oo.= o.| 2026-06-08 07:29:32.667450 | controller -> localhost | | o..o+..| 2026-06-08 07:29:32.667473 | controller -> localhost | | o .. = | 2026-06-08 07:29:32.667494 | controller -> localhost | | S * * | 2026-06-08 07:29:32.667516 | controller -> localhost | | . + & .| 2026-06-08 07:29:32.667537 | controller -> localhost | | + & *.| 2026-06-08 07:29:32.667558 | controller -> localhost | | . O %E+| 2026-06-08 07:29:32.667582 | controller -> localhost | | =.==+ | 2026-06-08 07:29:32.667604 | controller -> localhost | +----[SHA256]-----+ 2026-06-08 07:29:32.667656 | controller -> localhost | ok: Runtime: 0:00:01.680392 2026-06-08 07:29:32.672087 | 2026-06-08 07:29:32.672176 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-08 07:29:32.702759 | controller | ok 2026-06-08 07:29:32.711173 | controller | included: /var/lib/zuul/builds/ecb56855898d49d6a646dc52abbeb899/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-08 07:29:32.718030 | 2026-06-08 07:29:32.718143 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-08 07:29:32.743319 | controller | skipping: Conditional result was False 2026-06-08 07:29:32.750323 | 2026-06-08 07:29:32.750432 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-08 07:29:33.366964 | controller | changed 2026-06-08 07:29:33.372905 | 2026-06-08 07:29:33.373050 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-08 07:29:33.578026 | controller | ok 2026-06-08 07:29:33.585039 | 2026-06-08 07:29:33.585323 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-08 07:29:34.116775 | controller | changed 2026-06-08 07:29:34.122904 | 2026-06-08 07:29:34.123078 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-08 07:29:34.674157 | controller | changed 2026-06-08 07:29:34.680362 | 2026-06-08 07:29:34.680497 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-08 07:29:34.705249 | controller | skipping: Conditional result was False 2026-06-08 07:29:34.711939 | 2026-06-08 07:29:34.712122 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-08 07:29:35.114354 | controller -> localhost | changed 2026-06-08 07:29:35.128865 | 2026-06-08 07:29:35.129036 | TASK [add-build-sshkey : Add back temp key] 2026-06-08 07:29:35.462053 | controller -> localhost | Identity added: /var/lib/zuul/builds/ecb56855898d49d6a646dc52abbeb899/work/ecb56855898d49d6a646dc52abbeb899_id_rsa (zuul-build-sshkey) 2026-06-08 07:29:35.462278 | controller -> localhost | ok: Runtime: 0:00:00.015996 2026-06-08 07:29:35.466461 | 2026-06-08 07:29:35.466529 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-08 07:29:35.813307 | controller | ok 2026-06-08 07:29:35.819682 | 2026-06-08 07:29:35.819756 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-08 07:29:35.845238 | controller | skipping: Conditional result was False 2026-06-08 07:29:35.859334 | 2026-06-08 07:29:35.859422 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-08 07:29:36.290676 | controller | ok 2026-06-08 07:29:36.297477 | 2026-06-08 07:29:36.297586 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-08 07:29:38.434141 | controller | Output suppressed because no_log was given 2026-06-08 07:29:38.494543 | 2026-06-08 07:29:38.494681 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-08 07:29:38.713800 | controller | ok: "logs" 2026-06-08 07:29:38.723780 | controller | ok: All items complete 2026-06-08 07:29:38.723842 | 2026-06-08 07:29:38.891554 | controller | ok: "artifacts" 2026-06-08 07:29:39.072575 | controller | ok: "docs" 2026-06-08 07:29:39.086672 | 2026-06-08 07:29:39.086877 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-08 07:29:39.309975 | controller | changed: "logs" 2026-06-08 07:29:39.497936 | controller | changed: "artifacts" 2026-06-08 07:29:39.688481 | controller | changed: "docs" 2026-06-08 07:29:39.703328 | 2026-06-08 07:29:39.703491 | PLAY RECAP 2026-06-08 07:29:39.703559 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-06-08 07:29:39.703606 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-08 07:29:39.703640 | 2026-06-08 07:29:39.854565 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-08 07:29:39.857706 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-08 07:29:40.496901 | 2026-06-08 07:29:40.497079 | PLAY [all] 2026-06-08 07:29:40.507882 | 2026-06-08 07:29:40.508018 | TASK [setup-uv : Extract archive] 2026-06-08 07:29:43.547311 | controller | changed 2026-06-08 07:29:43.552611 | 2026-06-08 07:29:43.552708 | TASK [setup-uv : Print version] 2026-06-08 07:29:43.841089 | controller | uv 0.8.13 2026-06-08 07:29:44.076747 | controller | ok: Runtime: 0:00:00.015166 2026-06-08 07:29:44.083623 | 2026-06-08 07:29:44.083701 | TASK [Install Ansible collection siblings] 2026-06-08 07:29:44.491373 | controller | ok: 2026-06-08 07:29:44.492830 | controller | Sibling collection vexxhost.atmosphere at /home/zuul/src/github.com/vexxhost/atmosphere 2026-06-08 07:29:44.492880 | controller | Skipping ansible.posix: no checked-out sibling 2026-06-08 07:29:44.492914 | controller | Skipping community.crypto: no checked-out sibling 2026-06-08 07:29:44.492945 | controller | Skipping community.general: no checked-out sibling 2026-06-08 07:29:44.492996 | controller | Skipping kubernetes.core: no checked-out sibling 2026-06-08 07:29:44.493033 | controller | Skipping vexxhost.containers: no checked-out sibling 2026-06-08 07:29:44.495899 | 2026-06-08 07:29:44.495967 | PLAY RECAP 2026-06-08 07:29:44.496032 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-08 07:29:44.496065 | 2026-06-08 07:29:44.662487 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-08 07:29:44.665910 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-06-08 07:29:45.262943 | 2026-06-08 07:29:45.263073 | PLAY [all] 2026-06-08 07:29:45.273087 | 2026-06-08 07:29:45.273172 | TASK [Update apt cache] 2026-06-08 07:29:50.146434 | controller | ok 2026-06-08 07:29:50.151237 | 2026-06-08 07:29:50.151317 | TASK [Disable firewalld] 2026-06-08 07:29:50.175836 | controller | skipping: Conditional result was False 2026-06-08 07:29:50.182118 | 2026-06-08 07:29:50.182281 | TASK [Install "jq" for log collection] 2026-06-08 07:29:52.540687 | controller | changed 2026-06-08 07:29:52.542704 | 2026-06-08 07:29:52.542758 | PLAY RECAP 2026-06-08 07:29:52.542808 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-08 07:29:52.542833 | 2026-06-08 07:29:52.702058 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-06-08 07:29:52.704108 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-06-08 07:29:53.315499 | 2026-06-08 07:29:53.315665 | PLAY [all] 2026-06-08 07:29:53.326746 | 2026-06-08 07:29:53.326898 | TASK [Copy inventory file for Zuul] 2026-06-08 07:29:54.333390 | controller | changed 2026-06-08 07:29:54.339878 | 2026-06-08 07:29:54.340083 | TASK [Switch "ansible_host" to private IP] 2026-06-08 07:29:54.770934 | controller | changed: 1 replacements made 2026-06-08 07:29:54.783858 | 2026-06-08 07:29:54.783954 | TASK [Run Molecule scenario] 2026-06-08 07:29:55.227019 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-06-08 07:29:55.227133 | controller | Creating virtual environment at: .venv 2026-06-08 07:29:55.498378 | controller | Downloading ansible-core (2.3MiB) 2026-06-08 07:29:55.499310 | controller | Downloading pygments (1.2MiB) 2026-06-08 07:29:55.499902 | controller | Downloading cryptography (4.3MiB) 2026-06-08 07:29:55.655735 | controller | Downloading cryptography 2026-06-08 07:29:55.707002 | controller | Downloading pygments 2026-06-08 07:29:55.894357 | controller | Downloading ansible-core 2026-06-08 07:29:55.943500 | controller | Installed 36 packages in 48ms 2026-06-08 07:29:56.647413 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-06-08 07:29:56.647483 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-06-08 07:29:57.594479 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-06-08 07:29:57.594552 | controller | INFO default scenario not found, disabling shared state. 2026-06-08 07:29:57.594933 | controller | INFO [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-06-08 07:29:57.595097 | controller | INFO [helm > prerun] Performing prerun with role_name_check=0... 2026-06-08 07:30:15.469071 | controller | INFO [helm > dependency] Executing 2026-06-08 07:30:15.469143 | controller | WARNING [helm > dependency] Missing roles requirements file: requirements.yml 2026-06-08 07:30:15.469162 | controller | WARNING [helm > dependency] Missing collections requirements file: collections.yml 2026-06-08 07:30:15.469208 | controller | WARNING [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-06-08 07:30:15.475391 | controller | INFO [helm > cleanup] Executing 2026-06-08 07:30:15.475696 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-06-08 07:30:15.481095 | controller | INFO [helm > destroy] Executing 2026-06-08 07:30:15.481124 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-06-08 07:30:15.481217 | controller | INFO [helm > destroy] Executed: Successful 2026-06-08 07:30:15.486621 | controller | INFO [helm > syntax] Executing 2026-06-08 07:30:16.433884 | controller | 2026-06-08 07:30:16.433922 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml 2026-06-08 07:30:16.515162 | controller | INFO [helm > syntax] Executed: Successful 2026-06-08 07:30:16.527599 | controller | INFO [helm > create] Executing 2026-06-08 07:30:16.529142 | controller | WARNING [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-06-08 07:30:16.534881 | controller | INFO [helm > prepare] Executing 2026-06-08 07:30:17.452567 | controller | 2026-06-08 07:30:17.452590 | controller | PLAY [Prepare] ***************************************************************** 2026-06-08 07:30:17.452594 | controller | 2026-06-08 07:30:17.452598 | controller | TASK [Gathering Facts] ********************************************************* 2026-06-08 07:30:17.452602 | controller | Monday 08 June 2026 07:30:17 +0000 (0:00:00.031) 0:00:00.031 *********** 2026-06-08 07:30:18.725066 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-06-08 07:30:18.725187 | controller | ok: [controller] 2026-06-08 07:30:18.725338 | controller | 2026-06-08 07:30:18.725410 | controller | TASK [Install Debian packages] ************************************************* 2026-06-08 07:30:18.725547 | controller | Monday 08 June 2026 07:30:18 +0000 (0:00:01.273) 0:00:01.304 *********** 2026-06-08 07:30:20.404855 | controller | ok: [controller] 2026-06-08 07:30:20.404984 | controller | 2026-06-08 07:30:20.405172 | controller | PLAY RECAP ********************************************************************* 2026-06-08 07:30:20.405382 | controller | controller : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-06-08 07:30:20.405553 | controller | 2026-06-08 07:30:20.405722 | controller | 2026-06-08 07:30:20.405951 | controller | TASKS RECAP ******************************************************************** 2026-06-08 07:30:20.406138 | controller | Monday 08 June 2026 07:30:20 +0000 (0:00:01.682) 0:00:02.987 *********** 2026-06-08 07:30:20.406314 | controller | =============================================================================== 2026-06-08 07:30:20.406494 | controller | Install Debian packages ------------------------------------------------- 1.68s 2026-06-08 07:30:20.406681 | controller | Gathering Facts --------------------------------------------------------- 1.27s 2026-06-08 07:30:20.519266 | controller | INFO [helm > prepare] Executed: Successful 2026-06-08 07:30:20.528602 | controller | INFO [helm > converge] Executing 2026-06-08 07:30:21.465315 | controller | 2026-06-08 07:30:21.465445 | controller | PLAY [Converge] **************************************************************** 2026-06-08 07:30:21.465568 | controller | 2026-06-08 07:30:21.465729 | controller | TASK [Gathering Facts] ********************************************************* 2026-06-08 07:30:21.465972 | controller | Monday 08 June 2026 07:30:21 +0000 (0:00:00.013) 0:00:00.013 *********** 2026-06-08 07:30:23.595974 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-06-08 07:30:23.601139 | controller | ok: [controller] 2026-06-08 07:30:23.601409 | controller | 2026-06-08 07:30:23.601681 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-06-08 07:30:23.602048 | controller | Monday 08 June 2026 07:30:23 +0000 (0:00:02.136) 0:00:02.149 *********** 2026-06-08 07:30:24.121435 | controller | ok: [controller] 2026-06-08 07:30:24.121514 | controller | 2026-06-08 07:30:24.121611 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-06-08 07:30:24.121781 | controller | Monday 08 June 2026 07:30:24 +0000 (0:00:00.520) 0:00:02.669 *********** 2026-06-08 07:30:25.187117 | controller | ok: [controller] 2026-06-08 07:30:25.187264 | controller | 2026-06-08 07:30:25.187483 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-06-08 07:30:25.187713 | controller | Monday 08 June 2026 07:30:25 +0000 (0:00:01.065) 0:00:03.735 *********** 2026-06-08 07:30:25.641573 | controller | changed: [controller] 2026-06-08 07:30:25.641847 | controller | 2026-06-08 07:30:25.642158 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-06-08 07:30:25.642413 | controller | Monday 08 June 2026 07:30:25 +0000 (0:00:00.454) 0:00:04.189 *********** 2026-06-08 07:30:25.691442 | controller | ok: [controller] => { 2026-06-08 07:30:25.691634 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-06-08 07:30:25.691882 | controller | } 2026-06-08 07:30:25.692027 | controller | 2026-06-08 07:30:25.692237 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-06-08 07:30:25.692442 | controller | Monday 08 June 2026 07:30:25 +0000 (0:00:00.049) 0:00:04.239 *********** 2026-06-08 07:30:26.689918 | controller | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created with a mode of 0700, this may cause issues when running as another user. To avoid this, create the remote_tmp dir with the correct permissions manually 2026-06-08 07:30:26.699139 | controller | changed: [controller] 2026-06-08 07:30:26.699235 | controller | 2026-06-08 07:30:26.699374 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-06-08 07:30:26.699504 | controller | Monday 08 June 2026 07:30:26 +0000 (0:00:01.007) 0:00:05.247 *********** 2026-06-08 07:30:28.832690 | controller | changed: [controller] 2026-06-08 07:30:28.832746 | controller | 2026-06-08 07:30:28.832898 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-06-08 07:30:28.833030 | controller | Monday 08 June 2026 07:30:28 +0000 (0:00:02.133) 0:00:07.381 *********** 2026-06-08 07:30:29.394312 | controller | ok: [controller] 2026-06-08 07:30:29.394379 | controller | 2026-06-08 07:30:29.394389 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-06-08 07:30:29.394536 | controller | Monday 08 June 2026 07:30:29 +0000 (0:00:00.557) 0:00:07.938 *********** 2026-06-08 07:30:29.841418 | controller | changed: [controller] 2026-06-08 07:30:29.841445 | controller | 2026-06-08 07:30:29.841452 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-06-08 07:30:29.841457 | controller | Monday 08 June 2026 07:30:29 +0000 (0:00:00.449) 0:00:08.387 *********** 2026-06-08 07:30:30.329440 | controller | ok: [controller] 2026-06-08 07:30:30.329591 | controller | 2026-06-08 07:30:30.329916 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-06-08 07:30:30.330142 | controller | Monday 08 June 2026 07:30:30 +0000 (0:00:00.489) 0:00:08.877 *********** 2026-06-08 07:30:30.643895 | controller | changed: [controller] 2026-06-08 07:30:30.643920 | controller | 2026-06-08 07:30:30.643925 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-06-08 07:30:30.643929 | controller | Monday 08 June 2026 07:30:30 +0000 (0:00:00.312) 0:00:09.190 *********** 2026-06-08 07:30:30.945451 | controller | ok: [controller] 2026-06-08 07:30:30.945734 | controller | 2026-06-08 07:30:30.946060 | controller | TASK [Install plugin] ********************************************************** 2026-06-08 07:30:30.946413 | controller | Monday 08 June 2026 07:30:30 +0000 (0:00:00.302) 0:00:09.492 *********** 2026-06-08 07:30:31.010387 | controller | included: vexxhost.containers.download_artifact for controller 2026-06-08 07:30:31.010432 | controller | 2026-06-08 07:30:31.010480 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-06-08 07:30:31.010605 | controller | Monday 08 June 2026 07:30:31 +0000 (0:00:00.061) 0:00:09.554 *********** 2026-06-08 07:30:31.062299 | controller | ok: [controller] => { 2026-06-08 07:30:31.062367 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-06-08 07:30:31.062445 | controller | } 2026-06-08 07:30:31.062516 | controller | 2026-06-08 07:30:31.062607 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-06-08 07:30:31.062699 | controller | Monday 08 June 2026 07:30:31 +0000 (0:00:00.054) 0:00:09.608 *********** 2026-06-08 07:31:03.280243 | controller | [ERROR]: Task failed: Module failed: Request failed 2026-06-08 07:31:03.281919 | controller | Origin: /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers/roles/download_artifact/tasks/main.yml:19:3 2026-06-08 07:31:03.281962 | controller | 2026-06-08 07:31:03.281969 | controller | 17 msg: "{{ download_artifact_url }}" 2026-06-08 07:31:03.281974 | controller | 18 2026-06-08 07:31:03.281978 | controller | 19 - name: Download item 2026-06-08 07:31:03.281983 | controller | ^ column 3 2026-06-08 07:31:03.281993 | controller | 2026-06-08 07:31:03.282367 | controller | fatal: [controller]: FAILED! => {"attempts": 4, "censored": "the output has been hidden due to the fact that 'no_log: true' was specified for this result", "changed": false} 2026-06-08 07:31:03.284009 | controller | FAILED - RETRYING: [controller]: Download item (4 retries left). 2026-06-08 07:31:03.284129 | controller | FAILED - RETRYING: [controller]: Download item (3 retries left). 2026-06-08 07:31:03.284261 | controller | FAILED - RETRYING: [controller]: Download item (2 retries left). 2026-06-08 07:31:03.284392 | controller | FAILED - RETRYING: [controller]: Download item (1 retries left). 2026-06-08 07:31:03.284509 | controller | 2026-06-08 07:31:03.284636 | controller | PLAY RECAP ********************************************************************* 2026-06-08 07:31:03.284768 | controller | controller : ok=14 changed=5 unreachable=0 failed=1 skipped=0 rescued=0 ignored=0 2026-06-08 07:31:03.284880 | controller | 2026-06-08 07:31:03.284990 | controller | 2026-06-08 07:31:03.285112 | controller | TASKS RECAP ******************************************************************** 2026-06-08 07:31:03.285237 | controller | Monday 08 June 2026 07:31:03 +0000 (0:00:32.224) 0:00:41.833 *********** 2026-06-08 07:31:03.285952 | controller | =============================================================================== 2026-06-08 07:31:03.286013 | controller | vexxhost.containers.download_artifact : Download item ------------------ 32.22s 2026-06-08 07:31:03.286020 | controller | Gathering Facts --------------------------------------------------------- 2.14s 2026-06-08 07:31:03.286028 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.13s 2026-06-08 07:31:03.286056 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.07s 2026-06-08 07:31:03.286070 | controller | vexxhost.containers.download_artifact : Download item ------------------- 1.01s 2026-06-08 07:31:03.287278 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.56s 2026-06-08 07:31:03.287298 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.52s 2026-06-08 07:31:03.287304 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.49s 2026-06-08 07:31:03.287310 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.45s 2026-06-08 07:31:03.287315 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.45s 2026-06-08 07:31:03.287320 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.31s 2026-06-08 07:31:03.287326 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.30s 2026-06-08 07:31:03.287331 | controller | Install plugin ---------------------------------------------------------- 0.06s 2026-06-08 07:31:03.287336 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-06-08 07:31:03.287341 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-06-08 07:31:03.421100 | controller | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.Bs5j.helm/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/inventory.yaml /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml 2026-06-08 07:31:03.421363 | controller | ERROR [helm > converge] Executed: Failed 2026-06-08 07:31:03.421545 | controller | ERROR Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.Bs5j.helm/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/inventory.yaml /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml 2026-06-08 07:31:03.859356 | controller | ERROR 2026-06-08 07:31:03.859652 | controller | { 2026-06-08 07:31:03.859697 | controller | "delta": "0:01:08.369682", 2026-06-08 07:31:03.859730 | controller | "end": "2026-06-08 07:31:03.514203", 2026-06-08 07:31:03.859758 | controller | "msg": "non-zero return code", 2026-06-08 07:31:03.859784 | controller | "rc": 2, 2026-06-08 07:31:03.859814 | controller | "start": "2026-06-08 07:29:55.144521" 2026-06-08 07:31:03.859874 | controller | } failure 2026-06-08 07:31:03.866123 | 2026-06-08 07:31:03.866213 | PLAY RECAP 2026-06-08 07:31:03.866322 | controller | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-06-08 07:31:03.866389 | 2026-06-08 07:31:04.023988 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-06-08 07:31:04.025683 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-06-08 07:31:04.667677 | 2026-06-08 07:31:04.668270 | PLAY [all] 2026-06-08 07:31:04.684144 | 2026-06-08 07:31:04.684298 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-06-08 07:31:05.103561 | controller | changed 2026-06-08 07:31:05.109030 | 2026-06-08 07:31:05.109118 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-06-08 07:31:05.567449 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-06-08 07:31:05.567574 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-06-08 07:31:05.575132 | controller | environment: line 1: kubectl: command not found 2026-06-08 07:31:05.576668 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-06-08 07:31:05.578342 | controller | environment: line 1: kubectl: command not found 2026-06-08 07:31:05.580072 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-06-08 07:31:05.584195 | controller | environment: line 1: kubectl: command not found 2026-06-08 07:31:05.584216 | controller | environment: line 1: kubectl: command not found 2026-06-08 07:31:05.584794 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-06-08 07:31:05.585439 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-06-08 07:31:05.588265 | controller | environment: line 1: kubectl: command not found 2026-06-08 07:31:05.590315 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-06-08 07:31:05.643270 | controller | ok: Runtime: 0:00:00.037867 2026-06-08 07:31:05.647772 | 2026-06-08 07:31:05.647852 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-06-08 07:31:05.907774 | controller | changed 2026-06-08 07:31:05.914143 | 2026-06-08 07:31:05.914339 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-06-08 07:31:06.160855 | controller | environment: line 5: kubectl: command not found 2026-06-08 07:31:06.162091 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-06-08 07:31:06.162267 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-06-08 07:31:06.162492 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-06-08 07:31:06.449854 | controller | ok: Runtime: 0:00:00.014597 2026-06-08 07:31:06.455989 | 2026-06-08 07:31:06.456057 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-06-08 07:31:07.117652 | controller | changed: 2026-06-08 07:31:07.117902 | controller | created directory /var/lib/zuul/builds/ecb56855898d49d6a646dc52abbeb899/work/logs/controller 2026-06-08 07:31:07.117949 | controller | cd+++++++++ objects/ 2026-06-08 07:31:07.117995 | controller | cd+++++++++ objects/cluster/ 2026-06-08 07:31:07.118041 | controller | cd+++++++++ objects/namespaced/ 2026-06-08 07:31:07.125757 | 2026-06-08 07:31:07.125845 | TASK [gather-pod-logs : creating directory for pod logs] 2026-06-08 07:31:07.371657 | controller | changed 2026-06-08 07:31:07.377569 | 2026-06-08 07:31:07.377656 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-06-08 07:31:07.634337 | controller | changed 2026-06-08 07:31:07.640600 | 2026-06-08 07:31:07.640714 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-06-08 07:31:07.926666 | controller | environment: line 3: kubectl: command not found 2026-06-08 07:31:08.176357 | controller | ok: Runtime: 0:00:00.011932 2026-06-08 07:31:08.182278 | 2026-06-08 07:31:08.182430 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-06-08 07:31:08.681315 | controller | changed: 2026-06-08 07:31:08.681547 | controller | cd+++++++++ pod-logs/ 2026-06-08 07:31:08.681609 | controller | cd+++++++++ pod-logs/failed-pods/ 2026-06-08 07:31:08.692398 | 2026-06-08 07:31:08.692535 | LOOP [helm-release-status : creating directory for helm release status] 2026-06-08 07:31:08.923478 | controller | changed: "values" 2026-06-08 07:31:09.138325 | controller | changed: "releases" 2026-06-08 07:31:09.155074 | 2026-06-08 07:31:09.155283 | TASK [helm-release-status : Gather get release status for helm charts] 2026-06-08 07:31:09.402623 | controller | /bin/bash: line 3: kubectl: command not found 2026-06-08 07:31:09.691154 | controller | ok: Runtime: 0:00:00.009523 2026-06-08 07:31:09.697208 | 2026-06-08 07:31:09.697321 | TASK [helm-release-status : Downloads logs to executor] 2026-06-08 07:31:10.176849 | controller | changed: 2026-06-08 07:31:10.177136 | controller | cd+++++++++ helm/ 2026-06-08 07:31:10.177183 | controller | cd+++++++++ helm/releases/ 2026-06-08 07:31:10.177219 | controller | cd+++++++++ helm/values/ 2026-06-08 07:31:10.183985 | 2026-06-08 07:31:10.184062 | PLAY RECAP 2026-06-08 07:31:10.184125 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-08 07:31:10.184156 | 2026-06-08 07:31:10.333309 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-06-08 07:31:10.336415 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-08 07:31:10.923394 | 2026-06-08 07:31:10.924013 | PLAY [all] 2026-06-08 07:31:10.934355 | 2026-06-08 07:31:10.934434 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-08 07:31:10.979465 | controller | skipping: Conditional result was False 2026-06-08 07:31:10.988613 | 2026-06-08 07:31:10.988789 | TASK [fetch-output : Set log path for single node] 2026-06-08 07:31:11.028408 | controller | ok 2026-06-08 07:31:11.033330 | 2026-06-08 07:31:11.033444 | LOOP [fetch-output : Ensure local output dirs] 2026-06-08 07:31:11.445408 | controller -> localhost | ok: "/var/lib/zuul/builds/ecb56855898d49d6a646dc52abbeb899/work/logs" 2026-06-08 07:31:11.669612 | controller -> localhost | changed: "/var/lib/zuul/builds/ecb56855898d49d6a646dc52abbeb899/work/artifacts" 2026-06-08 07:31:11.885694 | controller -> localhost | changed: "/var/lib/zuul/builds/ecb56855898d49d6a646dc52abbeb899/work/docs" 2026-06-08 07:31:11.897246 | 2026-06-08 07:31:11.897415 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-08 07:31:12.575894 | controller | changed: .d..t...... ./ 2026-06-08 07:31:12.576117 | controller | changed: All items complete 2026-06-08 07:31:12.576146 | 2026-06-08 07:31:13.028268 | controller | changed: .d..t...... ./ 2026-06-08 07:31:13.485113 | controller | changed: .d..t...... ./ 2026-06-08 07:31:13.501852 | 2026-06-08 07:31:13.502066 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-08 07:31:13.963906 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008843 2026-06-08 07:31:14.218290 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008637 2026-06-08 07:31:14.242710 | 2026-06-08 07:31:14.242861 | PLAY [all] 2026-06-08 07:31:14.247877 | 2026-06-08 07:31:14.247944 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-08 07:31:14.717334 | controller | changed 2026-06-08 07:31:14.723387 | 2026-06-08 07:31:14.723453 | PLAY RECAP 2026-06-08 07:31:14.723511 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-08 07:31:14.723541 | 2026-06-08 07:31:14.861990 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-08 07:31:14.864924 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-08 07:31:15.478460 | 2026-06-08 07:31:15.478823 | PLAY [localhost] 2026-06-08 07:31:15.488686 | 2026-06-08 07:31:15.488756 | TASK [Generate Zuul manifest] 2026-06-08 07:31:15.509117 | localhost | ok 2026-06-08 07:31:15.524624 | 2026-06-08 07:31:15.524740 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-08 07:31:15.912436 | localhost | changed 2026-06-08 07:31:15.928290 | 2026-06-08 07:31:15.928393 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-08 07:31:15.970412 | localhost | ok 2026-06-08 07:31:15.979752 | 2026-06-08 07:31:15.979859 | TASK [Upload logs] 2026-06-08 07:31:16.003790 | localhost | ok 2026-06-08 07:31:16.067848 | 2026-06-08 07:31:16.068006 | TASK [Set zuul-log-path fact] 2026-06-08 07:31:16.092324 | localhost | ok 2026-06-08 07:31:16.106663 | 2026-06-08 07:31:16.106735 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-08 07:31:16.140099 | localhost | ok 2026-06-08 07:31:16.151763 | 2026-06-08 07:31:16.201782 | TASK [upload-logs : Create log directories] 2026-06-08 07:31:16.622666 | localhost | changed 2026-06-08 07:31:16.629371 | 2026-06-08 07:31:16.629462 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-08 07:31:17.061816 | localhost -> localhost | ok: Runtime: 0:00:00.006074 2026-06-08 07:31:17.069007 | 2026-06-08 07:31:17.069165 | TASK [upload-logs : Upload logs to log server] 2026-06-08 07:31:17.560059 | localhost | Output suppressed because no_log was given 2026-06-08 07:31:17.565280 | 2026-06-08 07:31:17.565374 | LOOP [upload-logs : Compress console log and json output] 2026-06-08 07:31:17.617137 | localhost | skipping: Conditional result was False 2026-06-08 07:31:17.678493 | localhost | skipping: Conditional result was False 2026-06-08 07:31:17.692440 | 2026-06-08 07:31:17.692654 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-08 07:31:17.739929 | localhost | skipping: Conditional result was False 2026-06-08 07:31:17.740448 | 2026-06-08 07:31:17.743830 | localhost | skipping: Conditional result was False 2026-06-08 07:31:17.757168 | 2026-06-08 07:31:17.757372 | LOOP [upload-logs : Upload console log and json output]