2026-04-23 04:38:31.457876 | Job console starting 2026-04-23 04:38:31.468312 | Updating git repos 2026-04-23 04:38:31.506965 | Cloning repos into workspace 2026-04-23 04:38:31.569371 | Restoring repo states 2026-04-23 04:38:31.588271 | Merging changes 2026-04-23 04:38:32.207842 | Checking out repos 2026-04-23 04:38:32.245045 | Preparing playbooks 2026-04-23 04:38:36.513615 | Running Ansible setup 2026-04-23 04:38:41.742394 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-23 04:38:42.354782 | 2026-04-23 04:38:42.354917 | PLAY [localhost] 2026-04-23 04:38:42.362443 | 2026-04-23 04:38:42.362532 | TASK [Gathering Facts] 2026-04-23 04:38:43.240049 | localhost | ok 2026-04-23 04:38:43.250474 | 2026-04-23 04:38:43.250596 | TASK [Setup log path fact] 2026-04-23 04:38:43.270440 | localhost | ok 2026-04-23 04:38:43.283038 | 2026-04-23 04:38:43.283109 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-23 04:38:43.312433 | localhost | ok 2026-04-23 04:38:43.321372 | 2026-04-23 04:38:43.321477 | TASK [emit-job-header : Print job information] 2026-04-23 04:38:43.362202 | # Job Information 2026-04-23 04:38:43.362670 | Ansible Version: 2.16.16 2026-04-23 04:38:43.362748 | Job: ansible-collection-kubernetes-molecule-helm 2026-04-23 04:38:43.362798 | Pipeline: check 2026-04-23 04:38:43.362843 | Executor: 0a8996d2b663 2026-04-23 04:38:43.362887 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/268 2026-04-23 04:38:43.362936 | Event ID: 1370a310-3ece-11f1-8cb2-08168b2742d0 2026-04-23 04:38:43.368605 | 2026-04-23 04:38:43.368752 | LOOP [emit-job-header : Print node information] 2026-04-23 04:38:43.739507 | localhost | ok: 2026-04-23 04:38:43.739664 | localhost | # Node Information 2026-04-23 04:38:43.739693 | localhost | Inventory Hostname: controller 2026-04-23 04:38:43.739715 | localhost | Hostname: np0000169668 2026-04-23 04:38:43.739735 | localhost | Username: zuul 2026-04-23 04:38:43.739757 | localhost | Distro: Debian 13.4 2026-04-23 04:38:43.739777 | localhost | Provider: yul1 2026-04-23 04:38:43.739796 | localhost | Region: ca-ymq-1 2026-04-23 04:38:43.739815 | localhost | Label: debian-trixie 2026-04-23 04:38:43.739834 | localhost | Product Name: OpenStack Nova 2026-04-23 04:38:43.739853 | localhost | Interface IP: 199.204.45.3 2026-04-23 04:38:43.751124 | 2026-04-23 04:38:43.751272 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-23 04:38:44.204248 | localhost -> localhost | changed 2026-04-23 04:38:44.213517 | 2026-04-23 04:38:44.213710 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-23 04:38:45.222088 | localhost -> localhost | changed 2026-04-23 04:38:45.232718 | 2026-04-23 04:38:45.232818 | PLAY [all] 2026-04-23 04:38:45.239565 | 2026-04-23 04:38:45.239688 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-23 04:38:45.465540 | controller -> localhost | ok 2026-04-23 04:38:45.476414 | 2026-04-23 04:38:45.476519 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-23 04:38:45.505967 | controller | ok 2026-04-23 04:38:45.521462 | controller | included: /var/lib/zuul/builds/9cddb2ac833d434580fa8b351c4ad344/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-23 04:38:45.526157 | 2026-04-23 04:38:45.526266 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-23 04:38:47.773449 | controller -> localhost | Generating public/private rsa key pair. 2026-04-23 04:38:47.773594 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9cddb2ac833d434580fa8b351c4ad344/work/9cddb2ac833d434580fa8b351c4ad344_id_rsa 2026-04-23 04:38:47.773623 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9cddb2ac833d434580fa8b351c4ad344/work/9cddb2ac833d434580fa8b351c4ad344_id_rsa.pub 2026-04-23 04:38:47.773647 | controller -> localhost | The key fingerprint is: 2026-04-23 04:38:47.773669 | controller -> localhost | SHA256:oipoAizHiuwgxrj2gJpQLCpvAJ9255IsbOVw6qKoW6A zuul-build-sshkey 2026-04-23 04:38:47.773701 | controller -> localhost | The key's randomart image is: 2026-04-23 04:38:47.773723 | controller -> localhost | +---[RSA 3072]----+ 2026-04-23 04:38:47.773747 | controller -> localhost | | | 2026-04-23 04:38:47.773768 | controller -> localhost | | | 2026-04-23 04:38:47.773789 | controller -> localhost | | | 2026-04-23 04:38:47.773809 | controller -> localhost | |.. | 2026-04-23 04:38:47.773829 | controller -> localhost | |=oo. . S | 2026-04-23 04:38:47.773849 | controller -> localhost | |X=B +... | 2026-04-23 04:38:47.773869 | controller -> localhost | |EX.O.+ | 2026-04-23 04:38:47.773889 | controller -> localhost | |/OB.= . | 2026-04-23 04:38:47.773912 | controller -> localhost | |/O=o . | 2026-04-23 04:38:47.773933 | controller -> localhost | +----[SHA256]-----+ 2026-04-23 04:38:47.773978 | controller -> localhost | ok: Runtime: 0:00:01.850842 2026-04-23 04:38:47.778076 | 2026-04-23 04:38:47.778137 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-23 04:38:47.808705 | controller | ok 2026-04-23 04:38:47.818759 | controller | included: /var/lib/zuul/builds/9cddb2ac833d434580fa8b351c4ad344/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-23 04:38:47.826268 | 2026-04-23 04:38:47.826341 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-23 04:38:47.849797 | controller | skipping: Conditional result was False 2026-04-23 04:38:47.857561 | 2026-04-23 04:38:47.857681 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-23 04:38:48.393229 | controller | changed 2026-04-23 04:38:48.424614 | 2026-04-23 04:38:48.424782 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-23 04:38:48.638660 | controller | ok 2026-04-23 04:38:48.643874 | 2026-04-23 04:38:48.643957 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-23 04:38:49.189235 | controller | changed 2026-04-23 04:38:49.194383 | 2026-04-23 04:38:49.194473 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-23 04:38:49.715881 | controller | changed 2026-04-23 04:38:49.724134 | 2026-04-23 04:38:49.724290 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-23 04:38:49.749045 | controller | skipping: Conditional result was False 2026-04-23 04:38:49.758100 | 2026-04-23 04:38:49.758276 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-23 04:38:50.141466 | controller -> localhost | changed 2026-04-23 04:38:50.156143 | 2026-04-23 04:38:50.156246 | TASK [add-build-sshkey : Add back temp key] 2026-04-23 04:38:50.467261 | controller -> localhost | Identity added: /var/lib/zuul/builds/9cddb2ac833d434580fa8b351c4ad344/work/9cddb2ac833d434580fa8b351c4ad344_id_rsa (zuul-build-sshkey) 2026-04-23 04:38:50.467432 | controller -> localhost | ok: Runtime: 0:00:00.010523 2026-04-23 04:38:50.471517 | 2026-04-23 04:38:50.471581 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-23 04:38:50.814800 | controller | ok 2026-04-23 04:38:50.822674 | 2026-04-23 04:38:50.822785 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-23 04:38:50.847144 | controller | skipping: Conditional result was False 2026-04-23 04:38:50.862281 | 2026-04-23 04:38:50.862401 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-23 04:38:51.225485 | controller | ok 2026-04-23 04:38:51.231729 | 2026-04-23 04:38:51.231842 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-23 04:38:52.206312 | controller | Output suppressed because no_log was given 2026-04-23 04:38:52.214789 | 2026-04-23 04:38:52.214871 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-23 04:38:52.428364 | controller | ok: "logs" 2026-04-23 04:38:52.428665 | controller | ok: All items complete 2026-04-23 04:38:52.428709 | 2026-04-23 04:38:52.620697 | controller | ok: "artifacts" 2026-04-23 04:38:52.822728 | controller | ok: "docs" 2026-04-23 04:38:52.832112 | 2026-04-23 04:38:52.832255 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-23 04:38:53.056106 | controller | changed: "logs" 2026-04-23 04:38:53.250152 | controller | changed: "artifacts" 2026-04-23 04:38:53.432990 | controller | changed: "docs" 2026-04-23 04:38:53.450734 | 2026-04-23 04:38:53.450881 | PLAY RECAP 2026-04-23 04:38:53.451016 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-23 04:38:53.451080 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-23 04:38:53.451124 | 2026-04-23 04:38:53.562508 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-23 04:38:53.564171 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-23 04:38:54.235068 | 2026-04-23 04:38:54.235280 | PLAY [all] 2026-04-23 04:38:54.251039 | 2026-04-23 04:38:54.251176 | TASK [setup-uv : Extract archive] 2026-04-23 04:38:56.680596 | controller | changed 2026-04-23 04:38:56.686091 | 2026-04-23 04:38:56.686184 | TASK [setup-uv : Print version] 2026-04-23 04:38:57.028950 | controller | uv 0.8.13 2026-04-23 04:38:57.217296 | controller | ok: Runtime: 0:00:00.013070 2026-04-23 04:38:57.226315 | 2026-04-23 04:38:57.226380 | PLAY RECAP 2026-04-23 04:38:57.226424 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-23 04:38:57.226449 | 2026-04-23 04:38:57.326768 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-23 04:38:57.328356 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-04-23 04:38:57.887117 | 2026-04-23 04:38:57.887257 | PLAY [all] 2026-04-23 04:38:57.898898 | 2026-04-23 04:38:57.899040 | TASK [Update apt cache] 2026-04-23 04:39:02.618935 | controller | ok 2026-04-23 04:39:02.628714 | 2026-04-23 04:39:02.628892 | TASK [Disable firewalld] 2026-04-23 04:39:02.643049 | controller | skipping: Conditional result was False 2026-04-23 04:39:02.649990 | 2026-04-23 04:39:02.650128 | TASK [Install "jq" for log collection] 2026-04-23 04:39:04.738229 | controller | changed 2026-04-23 04:39:04.740286 | 2026-04-23 04:39:04.740343 | PLAY RECAP 2026-04-23 04:39:04.740399 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-23 04:39:04.740429 | 2026-04-23 04:39:04.917877 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-04-23 04:39:04.919577 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-23 04:39:05.517119 | 2026-04-23 04:39:05.517305 | PLAY [all] 2026-04-23 04:39:05.528037 | 2026-04-23 04:39:05.528189 | TASK [Copy inventory file for Zuul] 2026-04-23 04:39:06.494003 | controller | changed 2026-04-23 04:39:06.508197 | 2026-04-23 04:39:06.508376 | TASK [Switch "ansible_host" to private IP] 2026-04-23 04:39:06.806776 | controller | changed: 1 replacements made 2026-04-23 04:39:06.813043 | 2026-04-23 04:39:06.813125 | TASK [Run Molecule scenario] 2026-04-23 04:39:07.245290 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-04-23 04:39:07.245377 | controller | Creating virtual environment at: .venv 2026-04-23 04:39:07.515599 | controller | Downloading cryptography (4.3MiB) 2026-04-23 04:39:07.517148 | controller | Downloading ansible-core (2.3MiB) 2026-04-23 04:39:07.517704 | controller | Downloading pygments (1.2MiB) 2026-04-23 04:39:07.732690 | controller | Downloading cryptography 2026-04-23 04:39:07.753439 | controller | Downloading pygments 2026-04-23 04:39:07.948751 | controller | Downloading ansible-core 2026-04-23 04:39:07.995611 | controller | Installed 36 packages in 46ms 2026-04-23 04:39:08.680325 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-04-23 04:39:08.680392 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-04-23 04:39:09.585132 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-04-23 04:39:09.585191 | controller | INFO default scenario not found, disabling shared state. 2026-04-23 04:39:09.585215 | controller | INFO [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-04-23 04:39:09.585332 | controller | INFO [helm > prerun] Performing prerun with role_name_check=0... 2026-04-23 04:39:25.434005 | controller | INFO [helm > dependency] Executing 2026-04-23 04:39:25.434220 | controller | WARNING [helm > dependency] Missing roles requirements file: requirements.yml 2026-04-23 04:39:25.434537 | controller | WARNING [helm > dependency] Missing collections requirements file: collections.yml 2026-04-23 04:39:25.434710 | controller | WARNING [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-04-23 04:39:25.441500 | controller | INFO [helm > cleanup] Executing 2026-04-23 04:39:25.441825 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-23 04:39:25.448228 | controller | INFO [helm > destroy] Executing 2026-04-23 04:39:25.448295 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-04-23 04:39:25.448367 | controller | INFO [helm > destroy] Executed: Successful 2026-04-23 04:39:25.453941 | controller | INFO [helm > syntax] Executing 2026-04-23 04:39:26.344371 | controller | 2026-04-23 04:39:26.344673 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml 2026-04-23 04:39:26.415619 | controller | INFO [helm > syntax] Executed: Successful 2026-04-23 04:39:26.427313 | controller | INFO [helm > create] Executing 2026-04-23 04:39:26.429931 | controller | WARNING [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-23 04:39:26.436977 | controller | INFO [helm > prepare] Executing 2026-04-23 04:39:27.287095 | controller | 2026-04-23 04:39:27.287205 | controller | PLAY [Prepare] ***************************************************************** 2026-04-23 04:39:27.287391 | controller | 2026-04-23 04:39:27.287581 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-23 04:39:27.287779 | controller | Thursday 23 April 2026 04:39:27 +0000 (0:00:00.029) 0:00:00.029 ******** 2026-04-23 04:39:28.515716 | 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-23 04:39:28.521778 | controller | ok: [controller] 2026-04-23 04:39:28.521858 | controller | 2026-04-23 04:39:28.521939 | controller | TASK [Install Debian packages] ************************************************* 2026-04-23 04:39:28.522041 | controller | Thursday 23 April 2026 04:39:28 +0000 (0:00:01.235) 0:00:01.265 ******** 2026-04-23 04:39:30.140612 | controller | ok: [controller] 2026-04-23 04:39:30.140807 | controller | 2026-04-23 04:39:30.141077 | controller | PLAY RECAP ********************************************************************* 2026-04-23 04:39:30.141270 | controller | controller : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-23 04:39:30.141447 | controller | 2026-04-23 04:39:30.141642 | controller | 2026-04-23 04:39:30.141997 | controller | TASKS RECAP ******************************************************************** 2026-04-23 04:39:30.142200 | controller | Thursday 23 April 2026 04:39:30 +0000 (0:00:01.618) 0:00:02.884 ******** 2026-04-23 04:39:30.142396 | controller | =============================================================================== 2026-04-23 04:39:30.142711 | controller | Install Debian packages ------------------------------------------------- 1.62s 2026-04-23 04:39:30.142947 | controller | Gathering Facts --------------------------------------------------------- 1.24s 2026-04-23 04:39:30.235546 | controller | INFO [helm > prepare] Executed: Successful 2026-04-23 04:39:30.243778 | controller | INFO [helm > converge] Executing 2026-04-23 04:39:31.177841 | controller | 2026-04-23 04:39:31.177905 | controller | PLAY [Converge] **************************************************************** 2026-04-23 04:39:31.177916 | controller | 2026-04-23 04:39:31.177925 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-23 04:39:31.178121 | controller | Thursday 23 April 2026 04:39:31 +0000 (0:00:00.014) 0:00:00.014 ******** 2026-04-23 04:39:32.214453 | 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-23 04:39:32.221733 | controller | ok: [controller] 2026-04-23 04:39:32.222057 | controller | 2026-04-23 04:39:32.222291 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-23 04:39:32.222489 | controller | Thursday 23 April 2026 04:39:32 +0000 (0:00:01.044) 0:00:01.058 ******** 2026-04-23 04:39:32.726264 | controller | ok: [controller] 2026-04-23 04:39:32.726422 | controller | 2026-04-23 04:39:32.726810 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-23 04:39:32.727047 | controller | Thursday 23 April 2026 04:39:32 +0000 (0:00:00.504) 0:00:01.562 ******** 2026-04-23 04:39:33.714246 | controller | ok: [controller] 2026-04-23 04:39:33.714336 | controller | 2026-04-23 04:39:33.714385 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-04-23 04:39:33.714573 | controller | Thursday 23 April 2026 04:39:33 +0000 (0:00:00.988) 0:00:02.550 ******** 2026-04-23 04:39:34.129346 | controller | changed: [controller] 2026-04-23 04:39:34.129437 | controller | 2026-04-23 04:39:34.129447 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-23 04:39:34.129460 | controller | Thursday 23 April 2026 04:39:34 +0000 (0:00:00.413) 0:00:02.964 ******** 2026-04-23 04:39:34.167877 | controller | ok: [controller] => { 2026-04-23 04:39:34.167968 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-04-23 04:39:34.168047 | controller | } 2026-04-23 04:39:34.168171 | controller | 2026-04-23 04:39:34.170586 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-23 04:39:34.170611 | controller | Thursday 23 April 2026 04:39:34 +0000 (0:00:00.040) 0:00:03.004 ******** 2026-04-23 04:39:35.395026 | 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-23 04:39:35.404969 | controller | changed: [controller] 2026-04-23 04:39:35.405210 | controller | 2026-04-23 04:39:35.405489 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-23 04:39:35.405761 | controller | Thursday 23 April 2026 04:39:35 +0000 (0:00:01.237) 0:00:04.241 ******** 2026-04-23 04:39:37.407411 | controller | changed: [controller] 2026-04-23 04:39:37.407443 | controller | 2026-04-23 04:39:37.407450 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-04-23 04:39:37.407455 | controller | Thursday 23 April 2026 04:39:37 +0000 (0:00:02.002) 0:00:06.243 ******** 2026-04-23 04:39:37.957176 | controller | ok: [controller] 2026-04-23 04:39:37.957213 | controller | 2026-04-23 04:39:37.957222 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-04-23 04:39:37.957231 | controller | Thursday 23 April 2026 04:39:37 +0000 (0:00:00.549) 0:00:06.793 ******** 2026-04-23 04:39:38.373280 | controller | changed: [controller] 2026-04-23 04:39:38.373567 | controller | 2026-04-23 04:39:38.373832 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-04-23 04:39:38.374087 | controller | Thursday 23 April 2026 04:39:38 +0000 (0:00:00.416) 0:00:07.209 ******** 2026-04-23 04:39:38.804035 | controller | ok: [controller] 2026-04-23 04:39:38.804330 | controller | 2026-04-23 04:39:38.804611 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-04-23 04:39:38.804863 | controller | Thursday 23 April 2026 04:39:38 +0000 (0:00:00.430) 0:00:07.640 ******** 2026-04-23 04:39:39.096219 | controller | changed: [controller] 2026-04-23 04:39:39.096369 | controller | 2026-04-23 04:39:39.096503 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-04-23 04:39:39.096619 | controller | Thursday 23 April 2026 04:39:39 +0000 (0:00:00.292) 0:00:07.932 ******** 2026-04-23 04:39:39.424468 | controller | ok: [controller] 2026-04-23 04:39:39.424859 | controller | 2026-04-23 04:39:39.425239 | controller | TASK [Install plugin] ********************************************************** 2026-04-23 04:39:39.425607 | controller | Thursday 23 April 2026 04:39:39 +0000 (0:00:00.327) 0:00:08.260 ******** 2026-04-23 04:39:39.479387 | controller | included: vexxhost.containers.download_artifact for controller 2026-04-23 04:39:39.479732 | controller | 2026-04-23 04:39:39.480033 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-23 04:39:39.480307 | controller | Thursday 23 April 2026 04:39:39 +0000 (0:00:00.054) 0:00:08.315 ******** 2026-04-23 04:39:39.527558 | controller | ok: [controller] => { 2026-04-23 04:39:39.527852 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-04-23 04:39:39.528183 | controller | } 2026-04-23 04:39:39.528481 | controller | 2026-04-23 04:39:39.528783 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-23 04:39:39.529098 | controller | Thursday 23 April 2026 04:39:39 +0000 (0:00:00.048) 0:00:08.363 ******** 2026-04-23 04:39:40.311448 | controller | changed: [controller] 2026-04-23 04:39:40.311732 | controller | 2026-04-23 04:39:40.311995 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-23 04:39:40.312248 | controller | Thursday 23 April 2026 04:39:40 +0000 (0:00:00.783) 0:00:09.147 ******** 2026-04-23 04:39:42.344649 | controller | changed: [controller] 2026-04-23 04:39:42.344937 | controller | 2026-04-23 04:39:42.345206 | controller | PLAY RECAP ********************************************************************* 2026-04-23 04:39:42.345488 | controller | controller : ok=16 changed=7 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-23 04:39:42.345754 | controller | 2026-04-23 04:39:42.346118 | controller | 2026-04-23 04:39:42.346421 | controller | TASKS RECAP ******************************************************************** 2026-04-23 04:39:42.346743 | controller | Thursday 23 April 2026 04:39:42 +0000 (0:00:02.034) 0:00:11.181 ******** 2026-04-23 04:39:42.346950 | controller | =============================================================================== 2026-04-23 04:39:42.347108 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.03s 2026-04-23 04:39:42.347263 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.00s 2026-04-23 04:39:42.347427 | controller | vexxhost.containers.download_artifact : Download item ------------------- 1.24s 2026-04-23 04:39:42.347580 | controller | Gathering Facts --------------------------------------------------------- 1.04s 2026-04-23 04:39:42.347734 | controller | vexxhost.containers.package : Update state for tar ---------------------- 0.99s 2026-04-23 04:39:42.347889 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.78s 2026-04-23 04:39:42.348042 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.55s 2026-04-23 04:39:42.348193 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.50s 2026-04-23 04:39:42.348347 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.43s 2026-04-23 04:39:42.348501 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.42s 2026-04-23 04:39:42.348671 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.41s 2026-04-23 04:39:42.348834 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.33s 2026-04-23 04:39:42.348993 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.29s 2026-04-23 04:39:42.349144 | controller | Install plugin ---------------------------------------------------------- 0.05s 2026-04-23 04:39:42.349298 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-04-23 04:39:42.349528 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-04-23 04:39:42.450004 | controller | INFO [helm > converge] Executed: Successful 2026-04-23 04:39:42.458460 | controller | INFO [helm > idempotence] Executing 2026-04-23 04:39:43.313309 | controller | 2026-04-23 04:39:43.313500 | controller | PLAY [Converge] **************************************************************** 2026-04-23 04:39:43.313682 | controller | 2026-04-23 04:39:43.313867 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-23 04:39:43.314069 | controller | Thursday 23 April 2026 04:39:43 +0000 (0:00:00.012) 0:00:00.012 ******** 2026-04-23 04:39:45.334916 | 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-23 04:39:45.342784 | controller | ok: [controller] 2026-04-23 04:39:45.342964 | controller | 2026-04-23 04:39:45.343162 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-23 04:39:45.343350 | controller | Thursday 23 April 2026 04:39:45 +0000 (0:00:02.029) 0:00:02.041 ******** 2026-04-23 04:39:45.776658 | controller | ok: [controller] 2026-04-23 04:39:45.776712 | controller | 2026-04-23 04:39:45.776826 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-23 04:39:45.776929 | controller | Thursday 23 April 2026 04:39:45 +0000 (0:00:00.434) 0:00:02.476 ******** 2026-04-23 04:39:46.728352 | controller | ok: [controller] 2026-04-23 04:39:46.728518 | controller | 2026-04-23 04:39:46.728731 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-04-23 04:39:46.728927 | controller | Thursday 23 April 2026 04:39:46 +0000 (0:00:00.951) 0:00:03.427 ******** 2026-04-23 04:39:47.136877 | controller | ok: [controller] 2026-04-23 04:39:47.137050 | controller | 2026-04-23 04:39:47.137272 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-23 04:39:47.137462 | controller | Thursday 23 April 2026 04:39:47 +0000 (0:00:00.408) 0:00:03.836 ******** 2026-04-23 04:39:47.181308 | controller | ok: [controller] => { 2026-04-23 04:39:47.181500 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-04-23 04:39:47.181867 | controller | } 2026-04-23 04:39:47.182068 | controller | 2026-04-23 04:39:47.182328 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-23 04:39:47.182633 | controller | Thursday 23 April 2026 04:39:47 +0000 (0:00:00.044) 0:00:03.880 ******** 2026-04-23 04:39:47.754139 | controller | ok: [controller] 2026-04-23 04:39:47.754182 | controller | 2026-04-23 04:39:47.754195 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-23 04:39:47.754207 | controller | Thursday 23 April 2026 04:39:47 +0000 (0:00:00.571) 0:00:04.451 ******** 2026-04-23 04:39:49.414149 | controller | ok: [controller] 2026-04-23 04:39:49.414201 | controller | 2026-04-23 04:39:49.414346 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-04-23 04:39:49.414478 | controller | Thursday 23 April 2026 04:39:49 +0000 (0:00:01.658) 0:00:06.110 ******** 2026-04-23 04:39:49.902573 | controller | ok: [controller] 2026-04-23 04:39:49.902599 | controller | 2026-04-23 04:39:49.902607 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-04-23 04:39:49.902614 | controller | Thursday 23 April 2026 04:39:49 +0000 (0:00:00.490) 0:00:06.601 ******** 2026-04-23 04:39:50.301837 | controller | ok: [controller] 2026-04-23 04:39:50.301942 | controller | 2026-04-23 04:39:50.302085 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-04-23 04:39:50.302252 | controller | Thursday 23 April 2026 04:39:50 +0000 (0:00:00.399) 0:00:07.001 ******** 2026-04-23 04:39:50.746292 | controller | ok: [controller] 2026-04-23 04:39:50.746318 | controller | 2026-04-23 04:39:50.746323 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-04-23 04:39:50.746327 | controller | Thursday 23 April 2026 04:39:50 +0000 (0:00:00.440) 0:00:07.441 ******** 2026-04-23 04:39:51.051078 | controller | ok: [controller] 2026-04-23 04:39:51.051410 | controller | 2026-04-23 04:39:51.051762 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-04-23 04:39:51.052083 | controller | Thursday 23 April 2026 04:39:51 +0000 (0:00:00.308) 0:00:07.750 ******** 2026-04-23 04:39:51.358615 | controller | ok: [controller] 2026-04-23 04:39:51.359122 | controller | 2026-04-23 04:39:51.359157 | controller | TASK [Install plugin] ********************************************************** 2026-04-23 04:39:51.359164 | controller | Thursday 23 April 2026 04:39:51 +0000 (0:00:00.304) 0:00:08.055 ******** 2026-04-23 04:39:51.405838 | controller | included: vexxhost.containers.download_artifact for controller 2026-04-23 04:39:51.405890 | controller | 2026-04-23 04:39:51.405981 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-23 04:39:51.406070 | controller | Thursday 23 April 2026 04:39:51 +0000 (0:00:00.048) 0:00:08.103 ******** 2026-04-23 04:39:51.448932 | controller | ok: [controller] => { 2026-04-23 04:39:51.449024 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-04-23 04:39:51.449094 | controller | } 2026-04-23 04:39:51.449175 | controller | 2026-04-23 04:39:51.449269 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-23 04:39:51.449359 | controller | Thursday 23 April 2026 04:39:51 +0000 (0:00:00.045) 0:00:08.148 ******** 2026-04-23 04:39:51.854451 | controller | ok: [controller] 2026-04-23 04:39:51.854506 | controller | 2026-04-23 04:39:51.854598 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-23 04:39:51.854722 | controller | Thursday 23 April 2026 04:39:51 +0000 (0:00:00.405) 0:00:08.554 ******** 2026-04-23 04:39:54.485100 | controller | ok: [controller] 2026-04-23 04:39:54.485135 | controller | 2026-04-23 04:39:54.485144 | controller | PLAY RECAP ********************************************************************* 2026-04-23 04:39:54.485152 | controller | controller : ok=16 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-23 04:39:54.485160 | controller | 2026-04-23 04:39:54.485168 | controller | 2026-04-23 04:39:54.485175 | controller | TASKS RECAP ******************************************************************** 2026-04-23 04:39:54.485183 | controller | Thursday 23 April 2026 04:39:54 +0000 (0:00:02.626) 0:00:11.180 ******** 2026-04-23 04:39:54.485190 | controller | =============================================================================== 2026-04-23 04:39:54.485197 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.63s 2026-04-23 04:39:54.485204 | controller | Gathering Facts --------------------------------------------------------- 2.03s 2026-04-23 04:39:54.485211 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.66s 2026-04-23 04:39:54.485218 | controller | vexxhost.containers.package : Update state for tar ---------------------- 0.95s 2026-04-23 04:39:54.485226 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.57s 2026-04-23 04:39:54.485233 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.49s 2026-04-23 04:39:54.485240 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.44s 2026-04-23 04:39:54.485247 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.43s 2026-04-23 04:39:54.485255 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.41s 2026-04-23 04:39:54.485262 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.41s 2026-04-23 04:39:54.485280 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.40s 2026-04-23 04:39:54.485287 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.31s 2026-04-23 04:39:54.485294 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.30s 2026-04-23 04:39:54.485302 | controller | Install plugin ---------------------------------------------------------- 0.05s 2026-04-23 04:39:54.485340 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-04-23 04:39:54.485349 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-04-23 04:39:54.574648 | controller | INFO [helm > idempotence] Executed: Successful 2026-04-23 04:39:54.584416 | controller | INFO [helm > side_effect] Executing 2026-04-23 04:39:54.584886 | controller | WARNING [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-23 04:39:54.592096 | controller | INFO [helm > verify] Executing 2026-04-23 04:39:55.460360 | controller | 2026-04-23 04:39:55.460697 | controller | PLAY [Verify] ****************************************************************** 2026-04-23 04:39:55.461043 | controller | 2026-04-23 04:39:55.461335 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-23 04:39:55.461647 | controller | Thursday 23 April 2026 04:39:55 +0000 (0:00:00.009) 0:00:00.009 ******** 2026-04-23 04:39:56.525829 | 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-23 04:39:56.531384 | controller | ok: [controller] 2026-04-23 04:39:56.531668 | controller | 2026-04-23 04:39:56.532071 | controller | TASK [Get the current Helm version] ******************************************** 2026-04-23 04:39:56.532439 | controller | Thursday 23 April 2026 04:39:56 +0000 (0:00:01.071) 0:00:01.080 ******** 2026-04-23 04:39:56.976412 | controller | changed: [controller] 2026-04-23 04:39:56.976611 | controller | 2026-04-23 04:39:56.976785 | controller | TASK [Assert Helm version] ***************************************************** 2026-04-23 04:39:56.976955 | controller | Thursday 23 April 2026 04:39:56 +0000 (0:00:00.444) 0:00:01.525 ******** 2026-04-23 04:39:57.006376 | controller | ok: [controller] => { 2026-04-23 04:39:57.006611 | controller | "changed": false, 2026-04-23 04:39:57.006779 | controller | "msg": "All assertions passed" 2026-04-23 04:39:57.006942 | controller | } 2026-04-23 04:39:57.007096 | controller | 2026-04-23 04:39:57.007257 | controller | PLAY RECAP ********************************************************************* 2026-04-23 04:39:57.007436 | controller | controller : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-23 04:39:57.007587 | controller | 2026-04-23 04:39:57.007737 | controller | 2026-04-23 04:39:57.007896 | controller | TASKS RECAP ******************************************************************** 2026-04-23 04:39:57.008062 | controller | Thursday 23 April 2026 04:39:57 +0000 (0:00:00.030) 0:00:01.555 ******** 2026-04-23 04:39:57.008217 | controller | =============================================================================== 2026-04-23 04:39:57.008414 | controller | Gathering Facts --------------------------------------------------------- 1.07s 2026-04-23 04:39:57.008575 | controller | Get the current Helm version -------------------------------------------- 0.44s 2026-04-23 04:39:57.008737 | controller | Assert Helm version ----------------------------------------------------- 0.03s 2026-04-23 04:39:57.104554 | controller | INFO [helm > verify] Executed: Successful 2026-04-23 04:39:57.113368 | controller | INFO [helm > cleanup] Executing 2026-04-23 04:39:57.113862 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-23 04:39:57.122101 | controller | INFO [helm > destroy] Executing 2026-04-23 04:39:57.122240 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-04-23 04:39:57.122376 | controller | INFO [helm > destroy] Executed: Successful 2026-04-23 04:39:57.122790 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-04-23 04:39:57.419707 | controller | ok: Runtime: 0:00:50.033480 2026-04-23 04:39:57.426124 | 2026-04-23 04:39:57.426209 | PLAY RECAP 2026-04-23 04:39:57.426258 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-23 04:39:57.426283 | 2026-04-23 04:39:57.564122 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-23 04:39:57.566024 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-04-23 04:39:58.179486 | 2026-04-23 04:39:58.179629 | PLAY [all] 2026-04-23 04:39:58.191952 | 2026-04-23 04:39:58.192102 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-04-23 04:39:58.603044 | controller | changed 2026-04-23 04:39:58.607903 | 2026-04-23 04:39:58.608001 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-04-23 04:39:59.065112 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-23 04:39:59.065864 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-23 04:39:59.072616 | controller | environment: line 1: kubectl: command not found 2026-04-23 04:39:59.073551 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-23 04:39:59.075859 | controller | environment: line 1: kubectl: command not found 2026-04-23 04:39:59.076773 | controller | environment: line 1: kubectl: command not found 2026-04-23 04:39:59.077069 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-23 04:39:59.079093 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-23 04:39:59.081810 | controller | environment: line 1: kubectl: command not found 2026-04-23 04:39:59.083165 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-23 04:39:59.084580 | controller | environment: line 1: kubectl: command not found 2026-04-23 04:39:59.085710 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-23 04:39:59.134777 | controller | ok: Runtime: 0:00:00.038366 2026-04-23 04:39:59.142165 | 2026-04-23 04:39:59.142316 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-04-23 04:39:59.394942 | controller | changed 2026-04-23 04:39:59.400579 | 2026-04-23 04:39:59.400742 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-04-23 04:39:59.659238 | controller | environment: line 5: kubectl: command not found 2026-04-23 04:39:59.662668 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-23 04:39:59.662730 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-23 04:39:59.662743 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-23 04:39:59.934040 | controller | ok: Runtime: 0:00:00.012533 2026-04-23 04:39:59.941825 | 2026-04-23 04:39:59.941979 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-04-23 04:40:00.668655 | controller | changed: 2026-04-23 04:40:00.668825 | controller | created directory /var/lib/zuul/builds/9cddb2ac833d434580fa8b351c4ad344/work/logs/controller 2026-04-23 04:40:00.668867 | controller | cd+++++++++ objects/ 2026-04-23 04:40:00.668898 | controller | cd+++++++++ objects/cluster/ 2026-04-23 04:40:00.668929 | controller | cd+++++++++ objects/namespaced/ 2026-04-23 04:40:00.678502 | 2026-04-23 04:40:00.678622 | TASK [gather-pod-logs : creating directory for pod logs] 2026-04-23 04:40:00.904409 | controller | changed 2026-04-23 04:40:00.912258 | 2026-04-23 04:40:00.912380 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-04-23 04:40:01.144992 | controller | changed 2026-04-23 04:40:01.150667 | 2026-04-23 04:40:01.150823 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-04-23 04:40:01.383303 | controller | environment: line 3: kubectl: command not found 2026-04-23 04:40:01.680896 | controller | ok: Runtime: 0:00:00.011755 2026-04-23 04:40:01.687271 | 2026-04-23 04:40:01.687446 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-04-23 04:40:02.236400 | controller | changed: 2026-04-23 04:40:02.236599 | controller | cd+++++++++ pod-logs/ 2026-04-23 04:40:02.236647 | controller | cd+++++++++ pod-logs/failed-pods/ 2026-04-23 04:40:02.246695 | 2026-04-23 04:40:02.246831 | LOOP [helm-release-status : creating directory for helm release status] 2026-04-23 04:40:02.469923 | controller | changed: "values" 2026-04-23 04:40:02.668435 | controller | changed: "releases" 2026-04-23 04:40:02.678152 | 2026-04-23 04:40:02.678342 | TASK [helm-release-status : Gather get release status for helm charts] 2026-04-23 04:40:02.908292 | controller | /bin/bash: line 3: kubectl: command not found 2026-04-23 04:40:03.206668 | controller | ok: Runtime: 0:00:00.009119 2026-04-23 04:40:03.212201 | 2026-04-23 04:40:03.212348 | TASK [helm-release-status : Downloads logs to executor] 2026-04-23 04:40:03.741218 | controller | changed: 2026-04-23 04:40:03.741414 | controller | cd+++++++++ helm/ 2026-04-23 04:40:03.741452 | controller | cd+++++++++ helm/releases/ 2026-04-23 04:40:03.741486 | controller | cd+++++++++ helm/values/ 2026-04-23 04:40:03.748384 | 2026-04-23 04:40:03.748509 | PLAY RECAP 2026-04-23 04:40:03.748567 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-23 04:40:03.748599 | 2026-04-23 04:40:03.933575 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-04-23 04:40:03.936863 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-23 04:40:04.655093 | 2026-04-23 04:40:04.655638 | PLAY [all] 2026-04-23 04:40:04.666988 | 2026-04-23 04:40:04.667134 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-23 04:40:04.712128 | controller | skipping: Conditional result was False 2026-04-23 04:40:04.722042 | 2026-04-23 04:40:04.722223 | TASK [fetch-output : Set log path for single node] 2026-04-23 04:40:04.761589 | controller | ok 2026-04-23 04:40:04.766836 | 2026-04-23 04:40:04.766973 | LOOP [fetch-output : Ensure local output dirs] 2026-04-23 04:40:05.148433 | controller -> localhost | ok: "/var/lib/zuul/builds/9cddb2ac833d434580fa8b351c4ad344/work/logs" 2026-04-23 04:40:05.359004 | controller -> localhost | changed: "/var/lib/zuul/builds/9cddb2ac833d434580fa8b351c4ad344/work/artifacts" 2026-04-23 04:40:05.590239 | controller -> localhost | changed: "/var/lib/zuul/builds/9cddb2ac833d434580fa8b351c4ad344/work/docs" 2026-04-23 04:40:05.607237 | 2026-04-23 04:40:05.607439 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-23 04:40:06.556404 | controller | changed: .d..t...... ./ 2026-04-23 04:40:06.556691 | controller | changed: All items complete 2026-04-23 04:40:06.556743 | 2026-04-23 04:40:07.112621 | controller | changed: .d..t...... ./ 2026-04-23 04:40:07.597005 | controller | changed: .d..t...... ./ 2026-04-23 04:40:07.612388 | 2026-04-23 04:40:07.612567 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-23 04:40:08.101115 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007209 2026-04-23 04:40:08.350184 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006793 2026-04-23 04:40:08.359949 | 2026-04-23 04:40:08.360078 | PLAY [all] 2026-04-23 04:40:08.367574 | 2026-04-23 04:40:08.367688 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-23 04:40:08.843668 | controller | changed 2026-04-23 04:40:08.848860 | 2026-04-23 04:40:08.849056 | PLAY RECAP 2026-04-23 04:40:08.849150 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-23 04:40:08.849199 | 2026-04-23 04:40:09.007178 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-23 04:40:09.008471 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-23 04:40:09.604679 | 2026-04-23 04:40:09.604799 | PLAY [localhost] 2026-04-23 04:40:09.615690 | 2026-04-23 04:40:09.615770 | TASK [Generate Zuul manifest] 2026-04-23 04:40:09.635563 | localhost | ok 2026-04-23 04:40:09.655441 | 2026-04-23 04:40:09.655562 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-23 04:40:10.042392 | localhost | changed 2026-04-23 04:40:10.056503 | 2026-04-23 04:40:10.056639 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-23 04:40:10.087619 | localhost | ok 2026-04-23 04:40:10.097621 | 2026-04-23 04:40:10.097728 | TASK [Upload logs] 2026-04-23 04:40:10.118791 | localhost | ok 2026-04-23 04:40:10.182835 | 2026-04-23 04:40:10.182956 | TASK [Set zuul-log-path fact] 2026-04-23 04:40:10.202757 | localhost | ok 2026-04-23 04:40:10.215782 | 2026-04-23 04:40:10.215905 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-23 04:40:10.245595 | localhost | ok 2026-04-23 04:40:10.255057 | 2026-04-23 04:40:10.255211 | TASK [upload-logs : Create log directories] 2026-04-23 04:40:10.674814 | localhost | changed 2026-04-23 04:40:10.680119 | 2026-04-23 04:40:10.680185 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-23 04:40:11.113880 | localhost -> localhost | ok: Runtime: 0:00:00.004630 2026-04-23 04:40:11.118731 | 2026-04-23 04:40:11.118795 | TASK [upload-logs : Upload logs to log server] 2026-04-23 04:40:11.611918 | localhost | Output suppressed because no_log was given 2026-04-23 04:40:11.615905 | 2026-04-23 04:40:11.615975 | LOOP [upload-logs : Compress console log and json output] 2026-04-23 04:40:11.664535 | localhost | skipping: Conditional result was False 2026-04-23 04:40:11.671834 | localhost | skipping: Conditional result was False 2026-04-23 04:40:11.691873 | 2026-04-23 04:40:11.692053 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-23 04:40:11.735886 | localhost | skipping: Conditional result was False 2026-04-23 04:40:11.736220 | 2026-04-23 04:40:11.743856 | localhost | skipping: Conditional result was False 2026-04-23 04:40:11.756162 | 2026-04-23 04:40:11.756351 | LOOP [upload-logs : Upload console log and json output]