2026-04-22 12:47:52.504318 | Job console starting 2026-04-22 12:47:52.593328 | Updating git repos 2026-04-22 12:47:52.646385 | Cloning repos into workspace 2026-04-22 12:48:17.564519 | Restoring repo states 2026-04-22 12:48:17.583247 | Merging changes 2026-04-22 12:48:27.048095 | Checking out repos 2026-04-22 12:48:29.550994 | Preparing playbooks 2026-04-22 12:50:33.294360 | Running Ansible setup 2026-04-22 12:50:37.371883 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-22 12:50:38.055567 | 2026-04-22 12:50:38.055768 | PLAY [localhost] 2026-04-22 12:50:38.067294 | 2026-04-22 12:50:38.067476 | TASK [Gathering Facts] 2026-04-22 12:50:39.108378 | localhost | ok 2026-04-22 12:50:39.118936 | 2026-04-22 12:50:39.119103 | TASK [Setup log path fact] 2026-04-22 12:50:39.141999 | localhost | ok 2026-04-22 12:50:39.163496 | 2026-04-22 12:50:39.163692 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-22 12:50:39.194996 | localhost | ok 2026-04-22 12:50:39.201900 | 2026-04-22 12:50:39.201975 | TASK [emit-job-header : Print job information] 2026-04-22 12:50:39.240726 | # Job Information 2026-04-22 12:50:39.240917 | Ansible Version: 2.16.16 2026-04-22 12:50:39.240970 | Job: ansible-collection-kubernetes-molecule-helm 2026-04-22 12:50:39.240999 | Pipeline: check 2026-04-22 12:50:39.241021 | Executor: 0a8996d2b663 2026-04-22 12:50:39.241043 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/266 2026-04-22 12:50:39.241067 | Event ID: 4a10b660-3e49-11f1-93c8-eb7714c8f6ce 2026-04-22 12:50:39.243959 | 2026-04-22 12:50:39.244033 | LOOP [emit-job-header : Print node information] 2026-04-22 12:50:39.357945 | localhost | ok: 2026-04-22 12:50:39.358134 | localhost | # Node Information 2026-04-22 12:50:39.358163 | localhost | Inventory Hostname: controller 2026-04-22 12:50:39.358185 | localhost | Hostname: np0000169333 2026-04-22 12:50:39.358209 | localhost | Username: zuul 2026-04-22 12:50:39.358233 | localhost | Distro: Debian 13.4 2026-04-22 12:50:39.358252 | localhost | Provider: yul1 2026-04-22 12:50:39.358271 | localhost | Region: ca-ymq-1 2026-04-22 12:50:39.358290 | localhost | Label: debian-trixie 2026-04-22 12:50:39.358309 | localhost | Product Name: OpenStack Nova 2026-04-22 12:50:39.358327 | localhost | Interface IP: 199.19.213.183 2026-04-22 12:50:39.377648 | 2026-04-22 12:50:39.377835 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-22 12:50:39.819669 | localhost -> localhost | changed 2026-04-22 12:50:39.828739 | 2026-04-22 12:50:39.828904 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-22 12:50:41.324178 | localhost -> localhost | changed 2026-04-22 12:50:41.331451 | 2026-04-22 12:50:41.331592 | PLAY [all] 2026-04-22 12:50:41.340134 | 2026-04-22 12:50:41.340220 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-22 12:50:43.148083 | controller -> localhost | ok 2026-04-22 12:50:43.164719 | 2026-04-22 12:50:43.164843 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-22 12:50:43.199894 | controller | ok 2026-04-22 12:50:43.213150 | controller | included: /var/lib/zuul/builds/5f1d48539ef542ff94ea8228f1677f3d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-22 12:50:43.218220 | 2026-04-22 12:50:43.218309 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-22 12:50:44.085258 | controller -> localhost | Generating public/private rsa key pair. 2026-04-22 12:50:44.085474 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/5f1d48539ef542ff94ea8228f1677f3d/work/5f1d48539ef542ff94ea8228f1677f3d_id_rsa 2026-04-22 12:50:44.085518 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/5f1d48539ef542ff94ea8228f1677f3d/work/5f1d48539ef542ff94ea8228f1677f3d_id_rsa.pub 2026-04-22 12:50:44.085578 | controller -> localhost | The key fingerprint is: 2026-04-22 12:50:44.085612 | controller -> localhost | SHA256:6NH/XaR7GpCzuM8XZpzDCr2x6zv2k1X2dXTArkhFguo zuul-build-sshkey 2026-04-22 12:50:44.085654 | controller -> localhost | The key's randomart image is: 2026-04-22 12:50:44.085694 | controller -> localhost | +---[RSA 3072]----+ 2026-04-22 12:50:44.085746 | controller -> localhost | | ....... | 2026-04-22 12:50:44.085790 | controller -> localhost | | . .. . o| 2026-04-22 12:50:44.085831 | controller -> localhost | | . . . ..| 2026-04-22 12:50:44.085873 | controller -> localhost | | .o . .. =| 2026-04-22 12:50:44.085914 | controller -> localhost | | .o S. o++ o*| 2026-04-22 12:50:44.085955 | controller -> localhost | | .E. .o.++Ooo| 2026-04-22 12:50:44.086000 | controller -> localhost | | . o..Bo=.| 2026-04-22 12:50:44.086045 | controller -> localhost | | +*.++.| 2026-04-22 12:50:44.086095 | controller -> localhost | | .+B*=+ | 2026-04-22 12:50:44.086138 | controller -> localhost | +----[SHA256]-----+ 2026-04-22 12:50:44.086218 | controller -> localhost | ok: Runtime: 0:00:00.385186 2026-04-22 12:50:44.091797 | 2026-04-22 12:50:44.091866 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-22 12:50:44.132015 | controller | ok 2026-04-22 12:50:44.141310 | controller | included: /var/lib/zuul/builds/5f1d48539ef542ff94ea8228f1677f3d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-22 12:50:44.151554 | 2026-04-22 12:50:44.151632 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-22 12:50:44.176372 | controller | skipping: Conditional result was False 2026-04-22 12:50:44.182465 | 2026-04-22 12:50:44.182560 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-22 12:50:44.769787 | controller | changed 2026-04-22 12:50:44.774322 | 2026-04-22 12:50:44.774386 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-22 12:50:45.008198 | controller | ok 2026-04-22 12:50:45.016475 | 2026-04-22 12:50:45.016558 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-22 12:50:45.616802 | controller | changed 2026-04-22 12:50:45.623098 | 2026-04-22 12:50:45.623213 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-22 12:50:46.180118 | controller | changed 2026-04-22 12:50:46.186570 | 2026-04-22 12:50:46.186646 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-22 12:50:46.226411 | controller | skipping: Conditional result was False 2026-04-22 12:50:46.232885 | 2026-04-22 12:50:46.232970 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-22 12:50:46.824351 | controller -> localhost | changed 2026-04-22 12:50:46.838215 | 2026-04-22 12:50:46.838380 | TASK [add-build-sshkey : Add back temp key] 2026-04-22 12:50:47.121938 | controller -> localhost | Identity added: /var/lib/zuul/builds/5f1d48539ef542ff94ea8228f1677f3d/work/5f1d48539ef542ff94ea8228f1677f3d_id_rsa (zuul-build-sshkey) 2026-04-22 12:50:47.122143 | controller -> localhost | ok: Runtime: 0:00:00.015544 2026-04-22 12:50:47.128716 | 2026-04-22 12:50:47.128785 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-22 12:50:47.609345 | controller | ok 2026-04-22 12:50:47.614953 | 2026-04-22 12:50:47.615033 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-22 12:50:47.650834 | controller | skipping: Conditional result was False 2026-04-22 12:50:47.667859 | 2026-04-22 12:50:47.667990 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-22 12:50:48.039239 | controller | ok 2026-04-22 12:50:48.059576 | 2026-04-22 12:50:48.059757 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-22 12:50:49.158370 | controller | Output suppressed because no_log was given 2026-04-22 12:50:49.165692 | 2026-04-22 12:50:49.165756 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-22 12:50:49.389168 | controller | ok: "logs" 2026-04-22 12:50:49.389447 | controller | ok: All items complete 2026-04-22 12:50:49.389475 | 2026-04-22 12:50:49.568442 | controller | ok: "artifacts" 2026-04-22 12:50:49.770931 | controller | ok: "docs" 2026-04-22 12:50:49.779288 | 2026-04-22 12:50:49.779433 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-22 12:50:50.024289 | controller | changed: "logs" 2026-04-22 12:50:50.216418 | controller | changed: "artifacts" 2026-04-22 12:50:50.394133 | controller | changed: "docs" 2026-04-22 12:50:50.403253 | 2026-04-22 12:50:50.403371 | PLAY RECAP 2026-04-22 12:50:50.403421 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-22 12:50:50.403450 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-22 12:50:50.403472 | 2026-04-22 12:50:50.549305 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-22 12:50:50.550683 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-22 12:50:51.193444 | 2026-04-22 12:50:51.193584 | PLAY [all] 2026-04-22 12:50:51.204083 | 2026-04-22 12:50:51.204160 | TASK [setup-uv : Extract archive] 2026-04-22 12:50:53.694964 | controller | changed 2026-04-22 12:50:53.701382 | 2026-04-22 12:50:53.701465 | TASK [setup-uv : Print version] 2026-04-22 12:50:54.130855 | controller | uv 0.8.13 2026-04-22 12:50:54.234812 | controller | ok: Runtime: 0:00:00.013613 2026-04-22 12:50:54.241356 | 2026-04-22 12:50:54.241418 | PLAY RECAP 2026-04-22 12:50:54.241515 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-22 12:50:54.241554 | 2026-04-22 12:50:54.360633 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-22 12:50:54.362573 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-04-22 12:50:55.028392 | 2026-04-22 12:50:55.028551 | PLAY [all] 2026-04-22 12:50:55.040067 | 2026-04-22 12:50:55.040266 | TASK [Update apt cache] 2026-04-22 12:50:59.822437 | controller | ok 2026-04-22 12:50:59.827699 | 2026-04-22 12:50:59.827794 | TASK [Disable firewalld] 2026-04-22 12:50:59.853080 | controller | skipping: Conditional result was False 2026-04-22 12:50:59.860627 | 2026-04-22 12:50:59.860852 | TASK [Install "jq" for log collection] 2026-04-22 12:51:01.924632 | controller | changed 2026-04-22 12:51:02.138594 | 2026-04-22 12:51:02.138756 | PLAY RECAP 2026-04-22 12:51:02.138851 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-22 12:51:02.138900 | 2026-04-22 12:51:02.327273 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-04-22 12:51:02.328949 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-22 12:51:03.012909 | 2026-04-22 12:51:03.013063 | PLAY [all] 2026-04-22 12:51:03.023222 | 2026-04-22 12:51:03.023307 | TASK [Copy inventory file for Zuul] 2026-04-22 12:51:03.980272 | controller | changed 2026-04-22 12:51:03.988130 | 2026-04-22 12:51:03.988259 | TASK [Switch "ansible_host" to private IP] 2026-04-22 12:51:04.295617 | controller | changed: 1 replacements made 2026-04-22 12:51:04.303579 | 2026-04-22 12:51:04.303685 | TASK [Run Molecule scenario] 2026-04-22 12:51:04.736697 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-04-22 12:51:04.736781 | controller | Creating virtual environment at: .venv 2026-04-22 12:51:05.083409 | controller | Downloading pygments (1.2MiB) 2026-04-22 12:51:05.083716 | controller | Downloading cryptography (4.3MiB) 2026-04-22 12:51:05.086118 | controller | Downloading ansible-core (2.3MiB) 2026-04-22 12:51:05.232887 | controller | Downloading cryptography 2026-04-22 12:51:05.290270 | controller | Downloading pygments 2026-04-22 12:51:05.459210 | controller | Downloading ansible-core 2026-04-22 12:51:05.508559 | controller | Installed 36 packages in 48ms 2026-04-22 12:51:06.165131 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-04-22 12:51:06.165225 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-04-22 12:51:07.088289 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-04-22 12:51:07.088375 | controller | INFO default scenario not found, disabling shared state. 2026-04-22 12:51:07.088860 | controller | INFO [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-04-22 12:51:07.089115 | controller | INFO [helm > prerun] Performing prerun with role_name_check=0... 2026-04-22 12:51:24.894596 | controller | INFO [helm > dependency] Executing 2026-04-22 12:51:24.894731 | controller | WARNING [helm > dependency] Missing roles requirements file: requirements.yml 2026-04-22 12:51:24.894972 | controller | WARNING [helm > dependency] Missing collections requirements file: collections.yml 2026-04-22 12:51:24.895083 | controller | WARNING [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-04-22 12:51:24.900521 | controller | INFO [helm > cleanup] Executing 2026-04-22 12:51:24.900874 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-22 12:51:24.906208 | controller | INFO [helm > destroy] Executing 2026-04-22 12:51:24.906280 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-04-22 12:51:24.906344 | controller | INFO [helm > destroy] Executed: Successful 2026-04-22 12:51:24.911718 | controller | INFO [helm > syntax] Executing 2026-04-22 12:51:25.817910 | controller | 2026-04-22 12:51:25.818174 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml 2026-04-22 12:51:25.876457 | controller | INFO [helm > syntax] Executed: Successful 2026-04-22 12:51:25.887598 | controller | INFO [helm > create] Executing 2026-04-22 12:51:25.889079 | controller | WARNING [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-22 12:51:25.894515 | controller | INFO [helm > prepare] Executing 2026-04-22 12:51:26.751133 | controller | 2026-04-22 12:51:26.751249 | controller | PLAY [Prepare] ***************************************************************** 2026-04-22 12:51:26.751427 | controller | 2026-04-22 12:51:26.751521 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-22 12:51:26.751660 | controller | Wednesday 22 April 2026 12:51:26 +0000 (0:00:00.028) 0:00:00.028 ******* 2026-04-22 12:51:28.004832 | 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-04-22 12:51:28.010448 | controller | ok: [controller] 2026-04-22 12:51:28.010639 | controller | 2026-04-22 12:51:28.010931 | controller | TASK [Install Debian packages] ************************************************* 2026-04-22 12:51:28.011225 | controller | Wednesday 22 April 2026 12:51:28 +0000 (0:00:01.259) 0:00:01.287 ******* 2026-04-22 12:51:29.605243 | controller | ok: [controller] 2026-04-22 12:51:29.605280 | controller | 2026-04-22 12:51:29.605291 | controller | PLAY RECAP ********************************************************************* 2026-04-22 12:51:29.605512 | controller | controller : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-22 12:51:29.605757 | controller | 2026-04-22 12:51:29.605949 | controller | 2026-04-22 12:51:29.606145 | controller | TASKS RECAP ******************************************************************** 2026-04-22 12:51:29.606340 | controller | Wednesday 22 April 2026 12:51:29 +0000 (0:00:01.594) 0:00:02.882 ******* 2026-04-22 12:51:29.606517 | controller | =============================================================================== 2026-04-22 12:51:29.606703 | controller | Install Debian packages ------------------------------------------------- 1.59s 2026-04-22 12:51:29.606880 | controller | Gathering Facts --------------------------------------------------------- 1.26s 2026-04-22 12:51:29.696574 | controller | INFO [helm > prepare] Executed: Successful 2026-04-22 12:51:29.707439 | controller | INFO [helm > converge] Executing 2026-04-22 12:51:30.580349 | controller | 2026-04-22 12:51:30.580414 | controller | PLAY [Converge] **************************************************************** 2026-04-22 12:51:30.580513 | controller | 2026-04-22 12:51:30.580644 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-22 12:51:30.580722 | controller | Wednesday 22 April 2026 12:51:30 +0000 (0:00:00.012) 0:00:00.012 ******* 2026-04-22 12:51:31.589427 | 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-04-22 12:51:31.594528 | controller | ok: [controller] 2026-04-22 12:51:31.594738 | controller | 2026-04-22 12:51:31.595021 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-22 12:51:31.595358 | controller | Wednesday 22 April 2026 12:51:31 +0000 (0:00:01.013) 0:00:01.026 ******* 2026-04-22 12:51:32.067778 | controller | ok: [controller] 2026-04-22 12:51:32.067930 | controller | 2026-04-22 12:51:32.068150 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-22 12:51:32.068350 | controller | Wednesday 22 April 2026 12:51:32 +0000 (0:00:00.473) 0:00:01.499 ******* 2026-04-22 12:51:33.029643 | controller | ok: [controller] 2026-04-22 12:51:33.029797 | controller | 2026-04-22 12:51:33.029991 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-04-22 12:51:33.030200 | controller | Wednesday 22 April 2026 12:51:33 +0000 (0:00:00.961) 0:00:02.461 ******* 2026-04-22 12:51:33.431897 | controller | changed: [controller] 2026-04-22 12:51:33.432070 | controller | 2026-04-22 12:51:33.432288 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-22 12:51:33.432489 | controller | Wednesday 22 April 2026 12:51:33 +0000 (0:00:00.402) 0:00:02.863 ******* 2026-04-22 12:51:33.475707 | controller | ok: [controller] => { 2026-04-22 12:51:33.475981 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-04-22 12:51:33.476072 | controller | } 2026-04-22 12:51:33.476244 | controller | 2026-04-22 12:51:33.476443 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-22 12:51:33.476637 | controller | Wednesday 22 April 2026 12:51:33 +0000 (0:00:00.043) 0:00:02.907 ******* 2026-04-22 12:51:35.260445 | 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-04-22 12:51:35.270746 | controller | changed: [controller] 2026-04-22 12:51:35.270917 | controller | 2026-04-22 12:51:35.271164 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-22 12:51:35.271356 | controller | Wednesday 22 April 2026 12:51:35 +0000 (0:00:01.795) 0:00:04.702 ******* 2026-04-22 12:51:37.233329 | controller | changed: [controller] 2026-04-22 12:51:37.233450 | controller | 2026-04-22 12:51:37.233663 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-04-22 12:51:37.233864 | controller | Wednesday 22 April 2026 12:51:37 +0000 (0:00:01.962) 0:00:06.665 ******* 2026-04-22 12:51:37.722186 | controller | ok: [controller] 2026-04-22 12:51:37.722236 | controller | 2026-04-22 12:51:37.722320 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-04-22 12:51:37.722412 | controller | Wednesday 22 April 2026 12:51:37 +0000 (0:00:00.488) 0:00:07.153 ******* 2026-04-22 12:51:38.111243 | controller | changed: [controller] 2026-04-22 12:51:38.111422 | controller | 2026-04-22 12:51:38.111644 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-04-22 12:51:38.111851 | controller | Wednesday 22 April 2026 12:51:38 +0000 (0:00:00.389) 0:00:07.542 ******* 2026-04-22 12:51:38.591031 | controller | ok: [controller] 2026-04-22 12:51:38.591058 | controller | 2026-04-22 12:51:38.591064 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-04-22 12:51:38.591070 | controller | Wednesday 22 April 2026 12:51:38 +0000 (0:00:00.478) 0:00:08.020 ******* 2026-04-22 12:51:38.886146 | controller | changed: [controller] 2026-04-22 12:51:38.886240 | controller | 2026-04-22 12:51:38.886358 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-04-22 12:51:38.886454 | controller | Wednesday 22 April 2026 12:51:38 +0000 (0:00:00.294) 0:00:08.315 ******* 2026-04-22 12:51:39.175781 | controller | ok: [controller] 2026-04-22 12:51:39.175897 | controller | 2026-04-22 12:51:39.176016 | controller | TASK [Install plugin] ********************************************************** 2026-04-22 12:51:39.176134 | controller | Wednesday 22 April 2026 12:51:39 +0000 (0:00:00.292) 0:00:08.607 ******* 2026-04-22 12:51:39.226146 | controller | included: vexxhost.containers.download_artifact for controller 2026-04-22 12:51:39.226324 | controller | 2026-04-22 12:51:39.226534 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-22 12:51:39.226729 | controller | Wednesday 22 April 2026 12:51:39 +0000 (0:00:00.050) 0:00:08.658 ******* 2026-04-22 12:51:39.269496 | controller | ok: [controller] => { 2026-04-22 12:51:39.269698 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-04-22 12:51:39.269887 | controller | } 2026-04-22 12:51:39.270066 | controller | 2026-04-22 12:51:39.270279 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-22 12:51:39.270506 | controller | Wednesday 22 April 2026 12:51:39 +0000 (0:00:00.043) 0:00:08.701 ******* 2026-04-22 12:51:40.029804 | controller | changed: [controller] 2026-04-22 12:51:40.029954 | controller | 2026-04-22 12:51:40.030161 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-22 12:51:40.030361 | controller | Wednesday 22 April 2026 12:51:40 +0000 (0:00:00.759) 0:00:09.461 ******* 2026-04-22 12:51:42.066993 | controller | changed: [controller] 2026-04-22 12:51:42.067019 | controller | 2026-04-22 12:51:42.067024 | controller | PLAY RECAP ********************************************************************* 2026-04-22 12:51:42.067029 | controller | controller : ok=16 changed=7 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-22 12:51:42.067032 | controller | 2026-04-22 12:51:42.067036 | controller | 2026-04-22 12:51:42.067039 | controller | TASKS RECAP ******************************************************************** 2026-04-22 12:51:42.067042 | controller | Wednesday 22 April 2026 12:51:42 +0000 (0:00:02.036) 0:00:11.497 ******* 2026-04-22 12:51:42.067045 | controller | =============================================================================== 2026-04-22 12:51:42.067048 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.04s 2026-04-22 12:51:42.067056 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.96s 2026-04-22 12:51:42.067240 | controller | vexxhost.containers.download_artifact : Download item ------------------- 1.80s 2026-04-22 12:51:42.067425 | controller | Gathering Facts --------------------------------------------------------- 1.01s 2026-04-22 12:51:42.067614 | controller | vexxhost.containers.package : Update state for tar ---------------------- 0.96s 2026-04-22 12:51:42.067806 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.76s 2026-04-22 12:51:42.067992 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.49s 2026-04-22 12:51:42.068209 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.48s 2026-04-22 12:51:42.068415 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.47s 2026-04-22 12:51:42.068617 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.40s 2026-04-22 12:51:42.068820 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.39s 2026-04-22 12:51:42.069099 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.29s 2026-04-22 12:51:42.069318 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.29s 2026-04-22 12:51:42.069530 | controller | Install plugin ---------------------------------------------------------- 0.05s 2026-04-22 12:51:42.069808 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-04-22 12:51:42.070023 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-04-22 12:51:42.160848 | controller | INFO [helm > converge] Executed: Successful 2026-04-22 12:51:42.172664 | controller | INFO [helm > idempotence] Executing 2026-04-22 12:51:43.031582 | controller | 2026-04-22 12:51:43.031750 | controller | PLAY [Converge] **************************************************************** 2026-04-22 12:51:43.031928 | controller | 2026-04-22 12:51:43.032104 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-22 12:51:43.032294 | controller | Wednesday 22 April 2026 12:51:43 +0000 (0:00:00.017) 0:00:00.017 ******* 2026-04-22 12:51:44.075453 | 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-04-22 12:51:44.082032 | controller | ok: [controller] 2026-04-22 12:51:44.082199 | controller | 2026-04-22 12:51:44.082429 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-22 12:51:44.082599 | controller | Wednesday 22 April 2026 12:51:44 +0000 (0:00:01.050) 0:00:01.068 ******* 2026-04-22 12:51:44.522093 | controller | ok: [controller] 2026-04-22 12:51:44.522149 | controller | 2026-04-22 12:51:44.522300 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-22 12:51:44.522428 | controller | Wednesday 22 April 2026 12:51:44 +0000 (0:00:00.439) 0:00:01.507 ******* 2026-04-22 12:51:45.534704 | controller | ok: [controller] 2026-04-22 12:51:45.534819 | controller | 2026-04-22 12:51:45.535099 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-04-22 12:51:45.535303 | controller | Wednesday 22 April 2026 12:51:45 +0000 (0:00:01.012) 0:00:02.520 ******* 2026-04-22 12:51:45.966121 | controller | ok: [controller] 2026-04-22 12:51:45.966289 | controller | 2026-04-22 12:51:45.966501 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-22 12:51:45.966702 | controller | Wednesday 22 April 2026 12:51:45 +0000 (0:00:00.431) 0:00:02.951 ******* 2026-04-22 12:51:46.010958 | controller | ok: [controller] => { 2026-04-22 12:51:46.011152 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-04-22 12:51:46.011347 | controller | } 2026-04-22 12:51:46.011522 | controller | 2026-04-22 12:51:46.011727 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-22 12:51:46.011917 | controller | Wednesday 22 April 2026 12:51:46 +0000 (0:00:00.044) 0:00:02.996 ******* 2026-04-22 12:51:46.541407 | controller | ok: [controller] 2026-04-22 12:51:46.541440 | controller | 2026-04-22 12:51:46.541449 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-22 12:51:46.541457 | controller | Wednesday 22 April 2026 12:51:46 +0000 (0:00:00.530) 0:00:03.526 ******* 2026-04-22 12:51:48.199347 | controller | ok: [controller] 2026-04-22 12:51:48.199392 | controller | 2026-04-22 12:51:48.199451 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-04-22 12:51:48.199537 | controller | Wednesday 22 April 2026 12:51:48 +0000 (0:00:01.657) 0:00:05.183 ******* 2026-04-22 12:51:48.712881 | controller | ok: [controller] 2026-04-22 12:51:48.712948 | controller | 2026-04-22 12:51:48.713044 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-04-22 12:51:48.713136 | controller | Wednesday 22 April 2026 12:51:48 +0000 (0:00:00.514) 0:00:05.698 ******* 2026-04-22 12:51:49.139053 | controller | ok: [controller] 2026-04-22 12:51:49.139106 | controller | 2026-04-22 12:51:49.139205 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-04-22 12:51:49.139316 | controller | Wednesday 22 April 2026 12:51:49 +0000 (0:00:00.426) 0:00:06.124 ******* 2026-04-22 12:51:49.607359 | controller | ok: [controller] 2026-04-22 12:51:49.607454 | controller | 2026-04-22 12:51:49.607616 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-04-22 12:51:49.607721 | controller | Wednesday 22 April 2026 12:51:49 +0000 (0:00:00.465) 0:00:06.590 ******* 2026-04-22 12:51:49.888401 | controller | ok: [controller] 2026-04-22 12:51:49.888616 | controller | 2026-04-22 12:51:49.888899 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-04-22 12:51:49.889138 | controller | Wednesday 22 April 2026 12:51:49 +0000 (0:00:00.282) 0:00:06.873 ******* 2026-04-22 12:51:50.182645 | controller | ok: [controller] 2026-04-22 12:51:50.182707 | controller | 2026-04-22 12:51:50.183030 | controller | TASK [Install plugin] ********************************************************** 2026-04-22 12:51:50.183061 | controller | Wednesday 22 April 2026 12:51:50 +0000 (0:00:00.294) 0:00:07.168 ******* 2026-04-22 12:51:50.239376 | controller | included: vexxhost.containers.download_artifact for controller 2026-04-22 12:51:50.239429 | controller | 2026-04-22 12:51:50.239515 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-22 12:51:50.239626 | controller | Wednesday 22 April 2026 12:51:50 +0000 (0:00:00.054) 0:00:07.223 ******* 2026-04-22 12:51:50.283100 | controller | ok: [controller] => { 2026-04-22 12:51:50.283183 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-04-22 12:51:50.283259 | controller | } 2026-04-22 12:51:50.283337 | controller | 2026-04-22 12:51:50.283426 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-22 12:51:50.283527 | controller | Wednesday 22 April 2026 12:51:50 +0000 (0:00:00.045) 0:00:07.268 ******* 2026-04-22 12:51:50.690918 | controller | ok: [controller] 2026-04-22 12:51:50.691005 | controller | 2026-04-22 12:51:50.691251 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-22 12:51:50.691281 | controller | Wednesday 22 April 2026 12:51:50 +0000 (0:00:00.406) 0:00:07.675 ******* 2026-04-22 12:51:52.293154 | controller | ok: [controller] 2026-04-22 12:51:52.293436 | controller | 2026-04-22 12:51:52.293711 | controller | PLAY RECAP ********************************************************************* 2026-04-22 12:51:52.294006 | controller | controller : ok=16 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-22 12:51:52.294240 | controller | 2026-04-22 12:51:52.294462 | controller | 2026-04-22 12:51:52.294702 | controller | TASKS RECAP ******************************************************************** 2026-04-22 12:51:52.294989 | controller | Wednesday 22 April 2026 12:51:52 +0000 (0:00:01.604) 0:00:09.279 ******* 2026-04-22 12:51:52.295227 | controller | =============================================================================== 2026-04-22 12:51:52.295471 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.66s 2026-04-22 12:51:52.295735 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.60s 2026-04-22 12:51:52.296001 | controller | Gathering Facts --------------------------------------------------------- 1.05s 2026-04-22 12:51:52.296292 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.01s 2026-04-22 12:51:52.296572 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.53s 2026-04-22 12:51:52.296833 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.51s 2026-04-22 12:51:52.297160 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.47s 2026-04-22 12:51:52.297414 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.44s 2026-04-22 12:51:52.297666 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.43s 2026-04-22 12:51:52.297834 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.43s 2026-04-22 12:51:52.298015 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.41s 2026-04-22 12:51:52.298188 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.29s 2026-04-22 12:51:52.298356 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.28s 2026-04-22 12:51:52.298514 | controller | Install plugin ---------------------------------------------------------- 0.05s 2026-04-22 12:51:52.298677 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-04-22 12:51:52.298837 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-04-22 12:51:52.406634 | controller | INFO [helm > idempotence] Executed: Successful 2026-04-22 12:51:52.419117 | controller | INFO [helm > side_effect] Executing 2026-04-22 12:51:52.419163 | controller | WARNING [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-22 12:51:52.424738 | controller | INFO [helm > verify] Executing 2026-04-22 12:51:53.271591 | controller | 2026-04-22 12:51:53.271641 | controller | PLAY [Verify] ****************************************************************** 2026-04-22 12:51:53.271829 | controller | 2026-04-22 12:51:53.272022 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-22 12:51:53.272304 | controller | Wednesday 22 April 2026 12:51:53 +0000 (0:00:00.009) 0:00:00.009 ******* 2026-04-22 12:51:55.426977 | 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-04-22 12:51:55.429044 | controller | ok: [controller] 2026-04-22 12:51:55.429236 | controller | 2026-04-22 12:51:55.429445 | controller | TASK [Get the current Helm version] ******************************************** 2026-04-22 12:51:55.429635 | controller | Wednesday 22 April 2026 12:51:55 +0000 (0:00:02.157) 0:00:02.167 ******* 2026-04-22 12:51:55.871617 | controller | changed: [controller] 2026-04-22 12:51:55.871879 | controller | 2026-04-22 12:51:55.872114 | controller | TASK [Assert Helm version] ***************************************************** 2026-04-22 12:51:55.872319 | controller | Wednesday 22 April 2026 12:51:55 +0000 (0:00:00.442) 0:00:02.609 ******* 2026-04-22 12:51:55.898792 | controller | ok: [controller] => { 2026-04-22 12:51:55.899031 | controller | "changed": false, 2026-04-22 12:51:55.899299 | controller | "msg": "All assertions passed" 2026-04-22 12:51:55.899553 | controller | } 2026-04-22 12:51:55.899755 | controller | 2026-04-22 12:51:55.899936 | controller | PLAY RECAP ********************************************************************* 2026-04-22 12:51:55.900142 | controller | controller : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-22 12:51:55.900336 | controller | 2026-04-22 12:51:55.900574 | controller | 2026-04-22 12:51:55.900770 | controller | TASKS RECAP ******************************************************************** 2026-04-22 12:51:55.900962 | controller | Wednesday 22 April 2026 12:51:55 +0000 (0:00:00.027) 0:00:02.637 ******* 2026-04-22 12:51:55.901137 | controller | =============================================================================== 2026-04-22 12:51:55.901318 | controller | Gathering Facts --------------------------------------------------------- 2.16s 2026-04-22 12:51:55.901651 | controller | Get the current Helm version -------------------------------------------- 0.44s 2026-04-22 12:51:55.901848 | controller | Assert Helm version ----------------------------------------------------- 0.03s 2026-04-22 12:51:56.009092 | controller | INFO [helm > verify] Executed: Successful 2026-04-22 12:51:56.020604 | controller | INFO [helm > cleanup] Executing 2026-04-22 12:51:56.020981 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-22 12:51:56.029009 | controller | INFO [helm > destroy] Executing 2026-04-22 12:51:56.029082 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-04-22 12:51:56.029171 | controller | INFO [helm > destroy] Executed: Successful 2026-04-22 12:51:56.029538 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-04-22 12:51:56.436393 | controller | ok: Runtime: 0:00:51.441515 2026-04-22 12:51:56.442735 | 2026-04-22 12:51:56.442833 | PLAY RECAP 2026-04-22 12:51:56.442901 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-22 12:51:56.442934 | 2026-04-22 12:51:56.586757 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-22 12:51:56.590950 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-04-22 12:51:57.283344 | 2026-04-22 12:51:57.283514 | PLAY [all] 2026-04-22 12:51:57.298071 | 2026-04-22 12:51:57.298212 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-04-22 12:51:57.706656 | controller | changed 2026-04-22 12:51:57.712635 | 2026-04-22 12:51:57.712794 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-04-22 12:51:58.149147 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-22 12:51:58.149254 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-22 12:51:58.157252 | controller | environment: line 1: kubectl: command not found 2026-04-22 12:51:58.159025 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-22 12:51:58.159460 | controller | environment: line 1: kubectl: command not found 2026-04-22 12:51:58.161606 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-22 12:51:58.163957 | controller | environment: line 1: kubectl: command not found 2026-04-22 12:51:58.165451 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-22 12:51:58.166909 | controller | environment: line 1: kubectl: command not found 2026-04-22 12:51:58.169110 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-22 12:51:58.169242 | controller | environment: line 1: kubectl: command not found 2026-04-22 12:51:58.170698 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-22 12:51:58.246013 | controller | ok: Runtime: 0:00:00.035018 2026-04-22 12:51:58.251414 | 2026-04-22 12:51:58.251490 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-04-22 12:51:58.486888 | controller | changed 2026-04-22 12:51:58.573273 | 2026-04-22 12:51:58.573433 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-04-22 12:51:58.812396 | controller | environment: line 5: kubectl: command not found 2026-04-22 12:51:58.813337 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-22 12:51:58.813787 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-22 12:51:58.813825 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-22 12:51:59.103302 | controller | ok: Runtime: 0:00:00.009974 2026-04-22 12:51:59.109628 | 2026-04-22 12:51:59.109740 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-04-22 12:51:59.808395 | controller | changed: 2026-04-22 12:51:59.808659 | controller | created directory /var/lib/zuul/builds/5f1d48539ef542ff94ea8228f1677f3d/work/logs/controller 2026-04-22 12:51:59.808708 | controller | cd+++++++++ objects/ 2026-04-22 12:51:59.808745 | controller | cd+++++++++ objects/cluster/ 2026-04-22 12:51:59.808783 | controller | cd+++++++++ objects/namespaced/ 2026-04-22 12:51:59.819879 | 2026-04-22 12:51:59.819993 | TASK [gather-pod-logs : creating directory for pod logs] 2026-04-22 12:52:00.067215 | controller | changed 2026-04-22 12:52:00.072553 | 2026-04-22 12:52:00.072632 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-04-22 12:52:00.297441 | controller | changed 2026-04-22 12:52:00.303152 | 2026-04-22 12:52:00.303252 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-04-22 12:52:00.535260 | controller | environment: line 3: kubectl: command not found 2026-04-22 12:52:00.836032 | controller | ok: Runtime: 0:00:00.012323 2026-04-22 12:52:00.842672 | 2026-04-22 12:52:00.842807 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-04-22 12:52:01.406802 | controller | changed: 2026-04-22 12:52:01.407445 | controller | cd+++++++++ pod-logs/ 2026-04-22 12:52:01.407477 | controller | cd+++++++++ pod-logs/failed-pods/ 2026-04-22 12:52:01.415585 | 2026-04-22 12:52:01.415684 | LOOP [helm-release-status : creating directory for helm release status] 2026-04-22 12:52:01.651111 | controller | changed: "values" 2026-04-22 12:52:01.856434 | controller | changed: "releases" 2026-04-22 12:52:01.871604 | 2026-04-22 12:52:01.871778 | TASK [helm-release-status : Gather get release status for helm charts] 2026-04-22 12:52:02.111836 | controller | /bin/bash: line 3: kubectl: command not found 2026-04-22 12:52:02.428163 | controller | ok: Runtime: 0:00:00.008667 2026-04-22 12:52:02.432573 | 2026-04-22 12:52:02.432676 | TASK [helm-release-status : Downloads logs to executor] 2026-04-22 12:52:03.012254 | controller | changed: 2026-04-22 12:52:03.012474 | controller | cd+++++++++ helm/ 2026-04-22 12:52:03.012513 | controller | cd+++++++++ helm/releases/ 2026-04-22 12:52:03.012546 | controller | cd+++++++++ helm/values/ 2026-04-22 12:52:03.028704 | 2026-04-22 12:52:03.028819 | PLAY RECAP 2026-04-22 12:52:03.028868 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-22 12:52:03.028890 | 2026-04-22 12:52:03.170579 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-04-22 12:52:03.230443 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-22 12:52:03.928084 | 2026-04-22 12:52:03.928237 | PLAY [all] 2026-04-22 12:52:03.939118 | 2026-04-22 12:52:03.939244 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-22 12:52:03.985788 | controller | skipping: Conditional result was False 2026-04-22 12:52:03.995362 | 2026-04-22 12:52:03.995548 | TASK [fetch-output : Set log path for single node] 2026-04-22 12:52:04.045023 | controller | ok 2026-04-22 12:52:04.050012 | 2026-04-22 12:52:04.050138 | LOOP [fetch-output : Ensure local output dirs] 2026-04-22 12:52:04.786298 | controller -> localhost | ok: "/var/lib/zuul/builds/5f1d48539ef542ff94ea8228f1677f3d/work/logs" 2026-04-22 12:52:05.221843 | controller -> localhost | changed: "/var/lib/zuul/builds/5f1d48539ef542ff94ea8228f1677f3d/work/artifacts" 2026-04-22 12:52:05.539519 | controller -> localhost | changed: "/var/lib/zuul/builds/5f1d48539ef542ff94ea8228f1677f3d/work/docs" 2026-04-22 12:52:05.555858 | 2026-04-22 12:52:05.582220 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-22 12:52:06.253142 | controller | changed: .d..t...... ./ 2026-04-22 12:52:06.349684 | controller | changed: All items complete 2026-04-22 12:52:06.349830 | 2026-04-22 12:52:06.741684 | controller | changed: .d..t...... ./ 2026-04-22 12:52:07.351727 | controller | changed: .d..t...... ./ 2026-04-22 12:52:07.364037 | 2026-04-22 12:52:07.364175 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-22 12:52:07.870594 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.010513 2026-04-22 12:52:08.154267 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009527 2026-04-22 12:52:08.169946 | 2026-04-22 12:52:08.170059 | PLAY [all] 2026-04-22 12:52:08.182852 | 2026-04-22 12:52:08.183105 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-22 12:52:08.649325 | controller | changed 2026-04-22 12:52:08.654798 | 2026-04-22 12:52:08.655855 | PLAY RECAP 2026-04-22 12:52:08.655912 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-22 12:52:08.655934 | 2026-04-22 12:52:08.903151 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-22 12:52:08.908398 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-22 12:52:09.611484 | 2026-04-22 12:52:10.082532 | PLAY [localhost] 2026-04-22 12:52:10.106879 | 2026-04-22 12:52:10.110092 | TASK [Generate Zuul manifest] 2026-04-22 12:52:10.132401 | localhost | ok 2026-04-22 12:52:10.160424 | 2026-04-22 12:52:10.160636 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-22 12:52:10.667578 | localhost | changed 2026-04-22 12:52:10.856624 | 2026-04-22 12:52:10.868092 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-22 12:52:10.902863 | localhost | ok 2026-04-22 12:52:10.910032 | 2026-04-22 12:52:10.910107 | TASK [Upload logs] 2026-04-22 12:52:10.930964 | localhost | ok 2026-04-22 12:52:10.999428 | 2026-04-22 12:52:11.164252 | TASK [Set zuul-log-path fact] 2026-04-22 12:52:11.201534 | localhost | ok 2026-04-22 12:52:11.257864 | 2026-04-22 12:52:11.270130 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-22 12:52:11.307484 | localhost | ok 2026-04-22 12:52:11.341652 | 2026-04-22 12:52:11.341806 | TASK [upload-logs : Create log directories] 2026-04-22 12:52:11.910257 | localhost | changed 2026-04-22 12:52:11.923106 | 2026-04-22 12:52:11.923189 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-22 12:52:12.639860 | localhost -> localhost | ok: Runtime: 0:00:00.072150 2026-04-22 12:52:12.645364 | 2026-04-22 12:52:12.645443 | TASK [upload-logs : Upload logs to log server] 2026-04-22 12:52:13.253109 | localhost | Output suppressed because no_log was given 2026-04-22 12:52:13.257328 | 2026-04-22 12:52:13.257402 | LOOP [upload-logs : Compress console log and json output] 2026-04-22 12:52:13.311900 | localhost | skipping: Conditional result was False 2026-04-22 12:52:13.319358 | localhost | skipping: Conditional result was False 2026-04-22 12:52:13.333025 | 2026-04-22 12:52:13.333202 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-22 12:52:13.376824 | localhost | skipping: Conditional result was False 2026-04-22 12:52:13.377392 | 2026-04-22 12:52:13.383206 | localhost | skipping: Conditional result was False 2026-04-22 12:52:13.393881 | 2026-04-22 12:52:13.394041 | LOOP [upload-logs : Upload console log and json output]