2026-04-20 17:35:58.650071 | Job console starting 2026-04-20 17:35:58.665282 | Updating git repos 2026-04-20 17:35:58.723173 | Cloning repos into workspace 2026-04-20 17:36:01.395889 | Restoring repo states 2026-04-20 17:36:01.408534 | Merging changes 2026-04-20 17:36:02.643868 | Checking out repos 2026-04-20 17:36:03.035955 | Preparing playbooks 2026-04-20 17:36:28.264816 | Running Ansible setup 2026-04-20 17:36:33.833557 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-20 17:36:34.556415 | 2026-04-20 17:36:34.556851 | PLAY [localhost] 2026-04-20 17:36:34.570677 | 2026-04-20 17:36:34.570972 | TASK [Gathering Facts] 2026-04-20 17:36:35.802846 | localhost | ok 2026-04-20 17:36:35.824218 | 2026-04-20 17:36:35.824370 | TASK [Setup log path fact] 2026-04-20 17:36:35.850099 | localhost | ok 2026-04-20 17:36:35.861221 | 2026-04-20 17:36:35.861329 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-20 17:36:35.889636 | localhost | ok 2026-04-20 17:36:35.899694 | 2026-04-20 17:36:35.899815 | TASK [emit-job-header : Print job information] 2026-04-20 17:36:35.928344 | # Job Information 2026-04-20 17:36:35.928505 | Ansible Version: 2.16.16 2026-04-20 17:36:35.928553 | Job: ansible-collection-kubernetes-molecule-helm 2026-04-20 17:36:35.928587 | Pipeline: check 2026-04-20 17:36:35.928618 | Executor: 0a8996d2b663 2026-04-20 17:36:35.928648 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/265 2026-04-20 17:36:35.928682 | Event ID: 3832e870-3cdf-11f1-8c82-cb0931aeec95 2026-04-20 17:36:35.932629 | 2026-04-20 17:36:35.932723 | LOOP [emit-job-header : Print node information] 2026-04-20 17:36:36.056894 | localhost | ok: 2026-04-20 17:36:36.057065 | localhost | # Node Information 2026-04-20 17:36:36.057095 | localhost | Inventory Hostname: controller 2026-04-20 17:36:36.057117 | localhost | Hostname: np0000168500 2026-04-20 17:36:36.057137 | localhost | Username: zuul 2026-04-20 17:36:36.057161 | localhost | Distro: Debian 13.4 2026-04-20 17:36:36.057181 | localhost | Provider: yul1 2026-04-20 17:36:36.057200 | localhost | Region: ca-ymq-1 2026-04-20 17:36:36.057219 | localhost | Label: debian-trixie 2026-04-20 17:36:36.057238 | localhost | Product Name: OpenStack Nova 2026-04-20 17:36:36.057268 | localhost | Interface IP: 199.19.213.111 2026-04-20 17:36:36.065625 | 2026-04-20 17:36:36.065759 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-20 17:36:36.678040 | localhost -> localhost | changed 2026-04-20 17:36:36.682631 | 2026-04-20 17:36:36.682698 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-20 17:36:38.021345 | localhost -> localhost | changed 2026-04-20 17:36:38.027327 | 2026-04-20 17:36:38.027392 | PLAY [all] 2026-04-20 17:36:38.034389 | 2026-04-20 17:36:38.034460 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-20 17:36:38.449092 | controller -> localhost | ok 2026-04-20 17:36:38.456747 | 2026-04-20 17:36:38.456859 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-20 17:36:38.486119 | controller | ok 2026-04-20 17:36:38.502251 | controller | included: /var/lib/zuul/builds/ff6c54f45fe3453fbd8042f258234043/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-20 17:36:38.506756 | 2026-04-20 17:36:38.506815 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-20 17:36:39.401876 | controller -> localhost | Generating public/private rsa key pair. 2026-04-20 17:36:39.402054 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/ff6c54f45fe3453fbd8042f258234043/work/ff6c54f45fe3453fbd8042f258234043_id_rsa 2026-04-20 17:36:39.402091 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/ff6c54f45fe3453fbd8042f258234043/work/ff6c54f45fe3453fbd8042f258234043_id_rsa.pub 2026-04-20 17:36:39.402121 | controller -> localhost | The key fingerprint is: 2026-04-20 17:36:39.402151 | controller -> localhost | SHA256:BLALiiEPIFGPmlUfWNV90s7HWMjeeklyJRtwJM7/z8s zuul-build-sshkey 2026-04-20 17:36:39.402199 | controller -> localhost | The key's randomart image is: 2026-04-20 17:36:39.402234 | controller -> localhost | +---[RSA 3072]----+ 2026-04-20 17:36:39.402274 | controller -> localhost | |+o. o++... .o=o. | 2026-04-20 17:36:39.402305 | controller -> localhost | |o +.o o .oooB o| 2026-04-20 17:36:39.402335 | controller -> localhost | |+ + o . . o* O.| 2026-04-20 17:36:39.402367 | controller -> localhost | |oO . . . o*o+| 2026-04-20 17:36:39.402400 | controller -> localhost | |= . . S =o.| 2026-04-20 17:36:39.402433 | controller -> localhost | | .o.| 2026-04-20 17:36:39.402464 | controller -> localhost | | ..| 2026-04-20 17:36:39.402497 | controller -> localhost | | ...| 2026-04-20 17:36:39.402532 | controller -> localhost | | E+| 2026-04-20 17:36:39.402563 | controller -> localhost | +----[SHA256]-----+ 2026-04-20 17:36:39.402630 | controller -> localhost | ok: Runtime: 0:00:00.295686 2026-04-20 17:36:39.408736 | 2026-04-20 17:36:39.408823 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-20 17:36:39.428183 | controller | ok 2026-04-20 17:36:39.449978 | controller | included: /var/lib/zuul/builds/ff6c54f45fe3453fbd8042f258234043/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-20 17:36:39.461129 | 2026-04-20 17:36:39.461197 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-20 17:36:39.485365 | controller | skipping: Conditional result was False 2026-04-20 17:36:39.492652 | 2026-04-20 17:36:39.492753 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-20 17:36:40.036737 | controller | changed 2026-04-20 17:36:40.041813 | 2026-04-20 17:36:40.041896 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-20 17:36:40.256778 | controller | ok 2026-04-20 17:36:40.264197 | 2026-04-20 17:36:40.264264 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-20 17:36:40.790242 | controller | changed 2026-04-20 17:36:40.796024 | 2026-04-20 17:36:40.796125 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-20 17:36:41.323866 | controller | changed 2026-04-20 17:36:41.330617 | 2026-04-20 17:36:41.330697 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-20 17:36:41.354412 | controller | skipping: Conditional result was False 2026-04-20 17:36:41.422357 | 2026-04-20 17:36:41.422553 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-20 17:36:41.787061 | controller -> localhost | changed 2026-04-20 17:36:41.797003 | 2026-04-20 17:36:41.797101 | TASK [add-build-sshkey : Add back temp key] 2026-04-20 17:36:42.170614 | controller -> localhost | Identity added: /var/lib/zuul/builds/ff6c54f45fe3453fbd8042f258234043/work/ff6c54f45fe3453fbd8042f258234043_id_rsa (zuul-build-sshkey) 2026-04-20 17:36:42.170830 | controller -> localhost | ok: Runtime: 0:00:00.010000 2026-04-20 17:36:42.176321 | 2026-04-20 17:36:42.176452 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-20 17:36:42.510937 | controller | ok 2026-04-20 17:36:42.525417 | 2026-04-20 17:36:42.525518 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-20 17:36:42.549287 | controller | skipping: Conditional result was False 2026-04-20 17:36:42.558418 | 2026-04-20 17:36:42.558488 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-20 17:36:42.931810 | controller | ok 2026-04-20 17:36:42.939708 | 2026-04-20 17:36:42.939874 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-20 17:36:44.096018 | controller | Output suppressed because no_log was given 2026-04-20 17:36:44.102545 | 2026-04-20 17:36:44.102609 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-20 17:36:44.304755 | controller | ok: "logs" 2026-04-20 17:36:44.312466 | controller | ok: All items complete 2026-04-20 17:36:44.312598 | 2026-04-20 17:36:44.496069 | controller | ok: "artifacts" 2026-04-20 17:36:44.681506 | controller | ok: "docs" 2026-04-20 17:36:44.701729 | 2026-04-20 17:36:44.702001 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-20 17:36:44.923114 | controller | changed: "logs" 2026-04-20 17:36:45.109143 | controller | changed: "artifacts" 2026-04-20 17:36:45.295828 | controller | changed: "docs" 2026-04-20 17:36:45.324077 | 2026-04-20 17:36:45.324225 | PLAY RECAP 2026-04-20 17:36:45.324285 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-20 17:36:45.324315 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-20 17:36:45.324335 | 2026-04-20 17:36:45.522371 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-20 17:36:45.523699 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-20 17:36:46.156982 | 2026-04-20 17:36:46.157144 | PLAY [all] 2026-04-20 17:36:46.169669 | 2026-04-20 17:36:46.169776 | TASK [setup-uv : Extract archive] 2026-04-20 17:36:48.577016 | controller | changed 2026-04-20 17:36:48.585015 | 2026-04-20 17:36:48.585125 | TASK [setup-uv : Print version] 2026-04-20 17:36:48.963121 | controller | uv 0.8.13 2026-04-20 17:36:49.115356 | controller | ok: Runtime: 0:00:00.017394 2026-04-20 17:36:49.141696 | 2026-04-20 17:36:49.141808 | PLAY RECAP 2026-04-20 17:36:49.141861 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-20 17:36:49.141886 | 2026-04-20 17:36:49.326045 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-20 17:36:49.328789 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-04-20 17:36:49.959460 | 2026-04-20 17:36:49.959718 | PLAY [all] 2026-04-20 17:36:49.973720 | 2026-04-20 17:36:49.973844 | TASK [Update apt cache] 2026-04-20 17:36:54.948070 | controller | ok 2026-04-20 17:36:54.954570 | 2026-04-20 17:36:54.954667 | TASK [Disable firewalld] 2026-04-20 17:36:54.979623 | controller | skipping: Conditional result was False 2026-04-20 17:36:54.991049 | 2026-04-20 17:36:54.991227 | TASK [Install "jq" for log collection] 2026-04-20 17:36:57.156644 | controller | changed 2026-04-20 17:36:57.159749 | 2026-04-20 17:36:57.159818 | PLAY RECAP 2026-04-20 17:36:57.159861 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-20 17:36:57.159883 | 2026-04-20 17:36:57.289050 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-04-20 17:36:57.290864 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-20 17:36:57.923865 | 2026-04-20 17:36:57.924039 | PLAY [all] 2026-04-20 17:36:57.935551 | 2026-04-20 17:36:57.935737 | TASK [Copy inventory file for Zuul] 2026-04-20 17:36:58.955789 | controller | changed 2026-04-20 17:36:58.960620 | 2026-04-20 17:36:58.960692 | TASK [Switch "ansible_host" to private IP] 2026-04-20 17:36:59.268161 | controller | changed: 1 replacements made 2026-04-20 17:36:59.275627 | 2026-04-20 17:36:59.275702 | TASK [Run Molecule scenario] 2026-04-20 17:36:59.713498 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-04-20 17:36:59.714151 | controller | Creating virtual environment at: .venv 2026-04-20 17:36:59.762204 | controller | Downloading cryptography (4.3MiB) 2026-04-20 17:36:59.762440 | controller | Downloading pygments (1.2MiB) 2026-04-20 17:36:59.762690 | controller | Downloading ansible-core (2.3MiB) 2026-04-20 17:36:59.915182 | controller | Downloading cryptography 2026-04-20 17:36:59.966668 | controller | Downloading pygments 2026-04-20 17:37:00.139977 | controller | Downloading ansible-core 2026-04-20 17:37:00.183870 | controller | Installed 36 packages in 42ms 2026-04-20 17:37:00.855042 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-04-20 17:37:00.855108 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-04-20 17:37:01.758552 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-04-20 17:37:01.758625 | controller | INFO default scenario not found, disabling shared state. 2026-04-20 17:37:01.758698 | controller | INFO [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-04-20 17:37:01.758766 | controller | INFO [helm > prerun] Performing prerun with role_name_check=0... 2026-04-20 17:37:18.673711 | controller | INFO [helm > dependency] Executing 2026-04-20 17:37:18.673892 | controller | WARNING [helm > dependency] Missing roles requirements file: requirements.yml 2026-04-20 17:37:18.674107 | controller | WARNING [helm > dependency] Missing collections requirements file: collections.yml 2026-04-20 17:37:18.674234 | controller | WARNING [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-04-20 17:37:18.680234 | controller | INFO [helm > cleanup] Executing 2026-04-20 17:37:18.680570 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-20 17:37:18.689877 | controller | INFO [helm > destroy] Executing 2026-04-20 17:37:18.689924 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-04-20 17:37:18.690041 | controller | INFO [helm > destroy] Executed: Successful 2026-04-20 17:37:18.698005 | controller | INFO [helm > syntax] Executing 2026-04-20 17:37:19.556626 | controller | 2026-04-20 17:37:19.556706 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml 2026-04-20 17:37:19.615119 | controller | INFO [helm > syntax] Executed: Successful 2026-04-20 17:37:19.626805 | controller | INFO [helm > create] Executing 2026-04-20 17:37:19.629129 | controller | WARNING [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-20 17:37:19.634749 | controller | INFO [helm > prepare] Executing 2026-04-20 17:37:20.463992 | controller | 2026-04-20 17:37:20.464139 | controller | PLAY [Prepare] ***************************************************************** 2026-04-20 17:37:20.464319 | controller | 2026-04-20 17:37:20.464609 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-20 17:37:20.464806 | controller | Monday 20 April 2026 17:37:20 +0000 (0:00:00.030) 0:00:00.030 ********** 2026-04-20 17:37:22.730210 | 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-20 17:37:22.736734 | controller | ok: [controller] 2026-04-20 17:37:22.736940 | controller | 2026-04-20 17:37:22.737142 | controller | TASK [Install Debian packages] ************************************************* 2026-04-20 17:37:22.737343 | controller | Monday 20 April 2026 17:37:22 +0000 (0:00:02.273) 0:00:02.303 ********** 2026-04-20 17:37:24.114915 | controller | ok: [controller] 2026-04-20 17:37:24.115050 | controller | 2026-04-20 17:37:24.115263 | controller | PLAY RECAP ********************************************************************* 2026-04-20 17:37:24.115512 | controller | controller : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-20 17:37:24.115698 | controller | 2026-04-20 17:37:24.115870 | controller | 2026-04-20 17:37:24.116065 | controller | TASKS RECAP ******************************************************************** 2026-04-20 17:37:24.116265 | controller | Monday 20 April 2026 17:37:24 +0000 (0:00:01.378) 0:00:03.682 ********** 2026-04-20 17:37:24.116453 | controller | =============================================================================== 2026-04-20 17:37:24.116643 | controller | Gathering Facts --------------------------------------------------------- 2.27s 2026-04-20 17:37:24.116829 | controller | Install Debian packages ------------------------------------------------- 1.38s 2026-04-20 17:37:24.201244 | controller | INFO [helm > prepare] Executed: Successful 2026-04-20 17:37:24.210206 | controller | INFO [helm > converge] Executing 2026-04-20 17:37:25.033361 | controller | 2026-04-20 17:37:25.033421 | controller | PLAY [Converge] **************************************************************** 2026-04-20 17:37:25.033587 | controller | 2026-04-20 17:37:25.033723 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-20 17:37:25.033871 | controller | Monday 20 April 2026 17:37:25 +0000 (0:00:00.013) 0:00:00.013 ********** 2026-04-20 17:37:26.105765 | 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-20 17:37:26.112655 | controller | ok: [controller] 2026-04-20 17:37:26.112777 | controller | 2026-04-20 17:37:26.112971 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-20 17:37:26.113166 | controller | Monday 20 April 2026 17:37:26 +0000 (0:00:01.079) 0:00:01.092 ********** 2026-04-20 17:37:26.627308 | controller | ok: [controller] 2026-04-20 17:37:26.627377 | controller | 2026-04-20 17:37:26.627440 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-20 17:37:26.627578 | controller | Monday 20 April 2026 17:37:26 +0000 (0:00:00.514) 0:00:01.606 ********** 2026-04-20 17:37:27.589573 | controller | ok: [controller] 2026-04-20 17:37:27.589644 | controller | 2026-04-20 17:37:27.589902 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-04-20 17:37:27.590098 | controller | Monday 20 April 2026 17:37:27 +0000 (0:00:00.962) 0:00:02.569 ********** 2026-04-20 17:37:28.021349 | controller | changed: [controller] 2026-04-20 17:37:28.021497 | controller | 2026-04-20 17:37:28.021696 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-20 17:37:28.021891 | controller | Monday 20 April 2026 17:37:28 +0000 (0:00:00.431) 0:00:03.001 ********** 2026-04-20 17:37:28.065427 | controller | ok: [controller] => { 2026-04-20 17:37:28.065617 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-04-20 17:37:28.065731 | controller | } 2026-04-20 17:37:28.065899 | controller | 2026-04-20 17:37:28.066094 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-20 17:37:28.066291 | controller | Monday 20 April 2026 17:37:28 +0000 (0:00:00.044) 0:00:03.045 ********** 2026-04-20 17:37:28.818599 | 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-20 17:37:28.826046 | controller | changed: [controller] 2026-04-20 17:37:28.826196 | controller | 2026-04-20 17:37:28.826513 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-20 17:37:28.826745 | controller | Monday 20 April 2026 17:37:28 +0000 (0:00:00.760) 0:00:03.805 ********** 2026-04-20 17:37:30.798802 | controller | changed: [controller] 2026-04-20 17:37:30.798984 | controller | 2026-04-20 17:37:30.799219 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-04-20 17:37:30.799429 | controller | Monday 20 April 2026 17:37:30 +0000 (0:00:01.972) 0:00:05.778 ********** 2026-04-20 17:37:31.324461 | controller | ok: [controller] 2026-04-20 17:37:31.324546 | controller | 2026-04-20 17:37:31.324661 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-04-20 17:37:31.324773 | controller | Monday 20 April 2026 17:37:31 +0000 (0:00:00.525) 0:00:06.304 ********** 2026-04-20 17:37:31.754328 | controller | changed: [controller] 2026-04-20 17:37:31.754358 | controller | 2026-04-20 17:37:31.754364 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-04-20 17:37:31.754367 | controller | Monday 20 April 2026 17:37:31 +0000 (0:00:00.427) 0:00:06.732 ********** 2026-04-20 17:37:32.205365 | controller | ok: [controller] 2026-04-20 17:37:32.205540 | controller | 2026-04-20 17:37:32.205767 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-04-20 17:37:32.205964 | controller | Monday 20 April 2026 17:37:32 +0000 (0:00:00.452) 0:00:07.184 ********** 2026-04-20 17:37:32.531875 | controller | changed: [controller] 2026-04-20 17:37:32.532007 | controller | 2026-04-20 17:37:32.532220 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-04-20 17:37:32.532411 | controller | Monday 20 April 2026 17:37:32 +0000 (0:00:00.326) 0:00:07.511 ********** 2026-04-20 17:37:32.840924 | controller | ok: [controller] 2026-04-20 17:37:32.840980 | controller | 2026-04-20 17:37:32.841152 | controller | TASK [Install plugin] ********************************************************** 2026-04-20 17:37:32.841296 | controller | Monday 20 April 2026 17:37:32 +0000 (0:00:00.309) 0:00:07.820 ********** 2026-04-20 17:37:32.898077 | controller | included: vexxhost.containers.download_artifact for controller 2026-04-20 17:37:32.898121 | controller | 2026-04-20 17:37:32.898213 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-20 17:37:32.898299 | controller | Monday 20 April 2026 17:37:32 +0000 (0:00:00.057) 0:00:07.878 ********** 2026-04-20 17:37:32.941635 | controller | ok: [controller] => { 2026-04-20 17:37:32.941712 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-04-20 17:37:32.941791 | controller | } 2026-04-20 17:37:32.941867 | controller | 2026-04-20 17:37:32.941947 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-20 17:37:32.942041 | controller | Monday 20 April 2026 17:37:32 +0000 (0:00:00.043) 0:00:07.921 ********** 2026-04-20 17:37:33.739373 | controller | changed: [controller] 2026-04-20 17:37:33.739459 | controller | 2026-04-20 17:37:33.739670 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-20 17:37:33.739878 | controller | Monday 20 April 2026 17:37:33 +0000 (0:00:00.796) 0:00:08.718 ********** 2026-04-20 17:37:35.849817 | controller | changed: [controller] 2026-04-20 17:37:35.849856 | controller | 2026-04-20 17:37:35.849866 | controller | PLAY RECAP ********************************************************************* 2026-04-20 17:37:35.849876 | controller | controller : ok=16 changed=7 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-20 17:37:35.849883 | controller | 2026-04-20 17:37:35.849891 | controller | 2026-04-20 17:37:35.849898 | controller | TASKS RECAP ******************************************************************** 2026-04-20 17:37:35.849905 | controller | Monday 20 April 2026 17:37:35 +0000 (0:00:02.106) 0:00:10.825 ********** 2026-04-20 17:37:35.849913 | controller | =============================================================================== 2026-04-20 17:37:35.849920 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.11s 2026-04-20 17:37:35.849927 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.97s 2026-04-20 17:37:35.849934 | controller | Gathering Facts --------------------------------------------------------- 1.08s 2026-04-20 17:37:35.849941 | controller | vexxhost.containers.package : Update state for tar ---------------------- 0.96s 2026-04-20 17:37:35.849948 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.80s 2026-04-20 17:37:35.849955 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.76s 2026-04-20 17:37:35.849962 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.53s 2026-04-20 17:37:35.849969 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.51s 2026-04-20 17:37:35.849976 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.45s 2026-04-20 17:37:35.849983 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.43s 2026-04-20 17:37:35.850011 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.43s 2026-04-20 17:37:35.850018 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.33s 2026-04-20 17:37:35.850026 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.31s 2026-04-20 17:37:35.850033 | controller | Install plugin ---------------------------------------------------------- 0.06s 2026-04-20 17:37:35.850040 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-04-20 17:37:35.850047 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-04-20 17:37:35.951648 | controller | INFO [helm > converge] Executed: Successful 2026-04-20 17:37:35.963111 | controller | INFO [helm > idempotence] Executing 2026-04-20 17:37:36.836492 | controller | 2026-04-20 17:37:36.836854 | controller | PLAY [Converge] **************************************************************** 2026-04-20 17:37:36.837103 | controller | 2026-04-20 17:37:36.837439 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-20 17:37:36.837766 | controller | Monday 20 April 2026 17:37:36 +0000 (0:00:00.012) 0:00:00.012 ********** 2026-04-20 17:37:37.890848 | 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-20 17:37:37.896034 | controller | ok: [controller] 2026-04-20 17:37:37.896415 | controller | 2026-04-20 17:37:37.896668 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-20 17:37:37.896944 | controller | Monday 20 April 2026 17:37:37 +0000 (0:00:01.059) 0:00:01.072 ********** 2026-04-20 17:37:38.330171 | controller | ok: [controller] 2026-04-20 17:37:38.330493 | controller | 2026-04-20 17:37:38.330766 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-20 17:37:38.331020 | controller | Monday 20 April 2026 17:37:38 +0000 (0:00:00.433) 0:00:01.506 ********** 2026-04-20 17:37:39.254443 | controller | ok: [controller] 2026-04-20 17:37:39.254742 | controller | 2026-04-20 17:37:39.255010 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-04-20 17:37:39.255297 | controller | Monday 20 April 2026 17:37:39 +0000 (0:00:00.922) 0:00:02.428 ********** 2026-04-20 17:37:39.704858 | controller | ok: [controller] 2026-04-20 17:37:39.705114 | controller | 2026-04-20 17:37:39.705290 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-20 17:37:39.705507 | controller | Monday 20 April 2026 17:37:39 +0000 (0:00:00.451) 0:00:02.880 ********** 2026-04-20 17:37:39.751972 | controller | ok: [controller] => { 2026-04-20 17:37:39.752257 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-04-20 17:37:39.752428 | controller | } 2026-04-20 17:37:39.752589 | controller | 2026-04-20 17:37:39.752845 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-20 17:37:39.753094 | controller | Monday 20 April 2026 17:37:39 +0000 (0:00:00.046) 0:00:02.927 ********** 2026-04-20 17:37:40.289456 | controller | ok: [controller] 2026-04-20 17:37:40.289767 | controller | 2026-04-20 17:37:40.290078 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-20 17:37:40.290473 | controller | Monday 20 April 2026 17:37:40 +0000 (0:00:00.537) 0:00:03.464 ********** 2026-04-20 17:37:41.974579 | controller | ok: [controller] 2026-04-20 17:37:41.974869 | controller | 2026-04-20 17:37:41.975173 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-04-20 17:37:41.975430 | controller | Monday 20 April 2026 17:37:41 +0000 (0:00:01.685) 0:00:05.150 ********** 2026-04-20 17:37:42.536384 | controller | ok: [controller] 2026-04-20 17:37:42.536688 | controller | 2026-04-20 17:37:42.536977 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-04-20 17:37:42.537249 | controller | Monday 20 April 2026 17:37:42 +0000 (0:00:00.561) 0:00:05.711 ********** 2026-04-20 17:37:42.951293 | controller | ok: [controller] 2026-04-20 17:37:42.951596 | controller | 2026-04-20 17:37:42.951869 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-04-20 17:37:42.952223 | controller | Monday 20 April 2026 17:37:42 +0000 (0:00:00.414) 0:00:06.126 ********** 2026-04-20 17:37:43.422697 | controller | ok: [controller] 2026-04-20 17:37:43.422833 | controller | 2026-04-20 17:37:43.422958 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-04-20 17:37:43.423071 | controller | Monday 20 April 2026 17:37:43 +0000 (0:00:00.469) 0:00:06.596 ********** 2026-04-20 17:37:43.741837 | controller | ok: [controller] 2026-04-20 17:37:43.741893 | controller | 2026-04-20 17:37:43.742040 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-04-20 17:37:43.742199 | controller | Monday 20 April 2026 17:37:43 +0000 (0:00:00.321) 0:00:06.917 ********** 2026-04-20 17:37:44.061692 | controller | ok: [controller] 2026-04-20 17:37:44.061797 | controller | 2026-04-20 17:37:44.061945 | controller | TASK [Install plugin] ********************************************************** 2026-04-20 17:37:44.062128 | controller | Monday 20 April 2026 17:37:44 +0000 (0:00:00.319) 0:00:07.237 ********** 2026-04-20 17:37:44.117456 | controller | included: vexxhost.containers.download_artifact for controller 2026-04-20 17:37:44.117495 | controller | 2026-04-20 17:37:44.117597 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-20 17:37:44.117684 | controller | Monday 20 April 2026 17:37:44 +0000 (0:00:00.054) 0:00:07.292 ********** 2026-04-20 17:37:44.157716 | controller | ok: [controller] => { 2026-04-20 17:37:44.157969 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-04-20 17:37:44.158168 | controller | } 2026-04-20 17:37:44.158669 | controller | 2026-04-20 17:37:44.158683 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-20 17:37:44.158689 | controller | Monday 20 April 2026 17:37:44 +0000 (0:00:00.040) 0:00:07.333 ********** 2026-04-20 17:37:44.598972 | controller | ok: [controller] 2026-04-20 17:37:44.599276 | controller | 2026-04-20 17:37:44.599569 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-20 17:37:44.599853 | controller | Monday 20 April 2026 17:37:44 +0000 (0:00:00.439) 0:00:07.773 ********** 2026-04-20 17:37:46.239731 | controller | ok: [controller] 2026-04-20 17:37:46.240003 | controller | 2026-04-20 17:37:46.240256 | controller | PLAY RECAP ********************************************************************* 2026-04-20 17:37:46.240528 | controller | controller : ok=16 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-20 17:37:46.240756 | controller | 2026-04-20 17:37:46.240980 | controller | 2026-04-20 17:37:46.241277 | controller | TASKS RECAP ******************************************************************** 2026-04-20 17:37:46.241531 | controller | Monday 20 April 2026 17:37:46 +0000 (0:00:01.643) 0:00:09.416 ********** 2026-04-20 17:37:46.241765 | controller | =============================================================================== 2026-04-20 17:37:46.242010 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.69s 2026-04-20 17:37:46.242255 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.64s 2026-04-20 17:37:46.242542 | controller | Gathering Facts --------------------------------------------------------- 1.06s 2026-04-20 17:37:46.242790 | controller | vexxhost.containers.package : Update state for tar ---------------------- 0.92s 2026-04-20 17:37:46.243036 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.56s 2026-04-20 17:37:46.243283 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.54s 2026-04-20 17:37:46.243535 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.47s 2026-04-20 17:37:46.243780 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.45s 2026-04-20 17:37:46.244019 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.44s 2026-04-20 17:37:46.244264 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.43s 2026-04-20 17:37:46.244523 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.41s 2026-04-20 17:37:46.244780 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.32s 2026-04-20 17:37:46.245095 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.32s 2026-04-20 17:37:46.245351 | controller | Install plugin ---------------------------------------------------------- 0.05s 2026-04-20 17:37:46.245596 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-04-20 17:37:46.245763 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-04-20 17:37:46.326559 | controller | INFO [helm > idempotence] Executed: Successful 2026-04-20 17:37:46.336211 | controller | INFO [helm > side_effect] Executing 2026-04-20 17:37:46.338300 | controller | WARNING [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-20 17:37:46.341877 | controller | INFO [helm > verify] Executing 2026-04-20 17:37:47.151999 | controller | 2026-04-20 17:37:47.152401 | controller | PLAY [Verify] ****************************************************************** 2026-04-20 17:37:47.152723 | controller | 2026-04-20 17:37:47.153028 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-20 17:37:47.153370 | controller | Monday 20 April 2026 17:37:47 +0000 (0:00:00.009) 0:00:00.009 ********** 2026-04-20 17:37:48.197598 | 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-20 17:37:48.203325 | controller | ok: [controller] 2026-04-20 17:37:48.203601 | controller | 2026-04-20 17:37:48.203871 | controller | TASK [Get the current Helm version] ******************************************** 2026-04-20 17:37:48.204212 | controller | Monday 20 April 2026 17:37:48 +0000 (0:00:01.051) 0:00:01.060 ********** 2026-04-20 17:37:48.642431 | controller | changed: [controller] 2026-04-20 17:37:48.642661 | controller | 2026-04-20 17:37:48.642880 | controller | TASK [Assert Helm version] ***************************************************** 2026-04-20 17:37:48.643088 | controller | Monday 20 April 2026 17:37:48 +0000 (0:00:00.438) 0:00:01.499 ********** 2026-04-20 17:37:48.674105 | controller | ok: [controller] => { 2026-04-20 17:37:48.674413 | controller | "changed": false, 2026-04-20 17:37:48.674670 | controller | "msg": "All assertions passed" 2026-04-20 17:37:48.674909 | controller | } 2026-04-20 17:37:48.675136 | controller | 2026-04-20 17:37:48.675379 | controller | PLAY RECAP ********************************************************************* 2026-04-20 17:37:48.675648 | controller | controller : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-20 17:37:48.675875 | controller | 2026-04-20 17:37:48.676186 | controller | 2026-04-20 17:37:48.676450 | controller | TASKS RECAP ******************************************************************** 2026-04-20 17:37:48.676702 | controller | Monday 20 April 2026 17:37:48 +0000 (0:00:00.032) 0:00:01.531 ********** 2026-04-20 17:37:48.676939 | controller | =============================================================================== 2026-04-20 17:37:48.677199 | controller | Gathering Facts --------------------------------------------------------- 1.05s 2026-04-20 17:37:48.677442 | controller | Get the current Helm version -------------------------------------------- 0.44s 2026-04-20 17:37:48.677683 | controller | Assert Helm version ----------------------------------------------------- 0.03s 2026-04-20 17:37:48.841166 | controller | INFO [helm > verify] Executed: Successful 2026-04-20 17:37:48.850875 | controller | INFO [helm > cleanup] Executing 2026-04-20 17:37:48.851333 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-20 17:37:48.858443 | controller | INFO [helm > destroy] Executing 2026-04-20 17:37:48.858539 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-04-20 17:37:48.858625 | controller | INFO [helm > destroy] Executed: Successful 2026-04-20 17:37:48.858916 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-04-20 17:37:49.329385 | controller | ok: Runtime: 0:00:49.305230 2026-04-20 17:37:49.333147 | 2026-04-20 17:37:49.333199 | PLAY RECAP 2026-04-20 17:37:49.333244 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-20 17:37:49.333266 | 2026-04-20 17:37:49.464031 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-20 17:37:49.468786 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-04-20 17:37:50.045255 | 2026-04-20 17:37:50.045430 | PLAY [all] 2026-04-20 17:37:50.056733 | 2026-04-20 17:37:50.056827 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-04-20 17:37:50.462302 | controller | changed 2026-04-20 17:37:50.538948 | 2026-04-20 17:37:50.539141 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-04-20 17:37:50.973748 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-20 17:37:50.974488 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-20 17:37:50.981934 | controller | environment: line 1: kubectl: command not found 2026-04-20 17:37:50.984386 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-20 17:37:50.985143 | controller | environment: line 1: kubectl: command not found 2026-04-20 17:37:50.986302 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-20 17:37:50.989731 | controller | environment: line 1: kubectl: command not found 2026-04-20 17:37:50.991034 | controller | environment: line 1: kubectl: command not found 2026-04-20 17:37:50.991722 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-20 17:37:50.992414 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-20 17:37:50.996453 | controller | environment: line 1: kubectl: command not found 2026-04-20 17:37:50.997594 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-20 17:37:51.070364 | controller | ok: Runtime: 0:00:00.038797 2026-04-20 17:37:51.075097 | 2026-04-20 17:37:51.075164 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-04-20 17:37:51.305935 | controller | changed 2026-04-20 17:37:51.310084 | 2026-04-20 17:37:51.310180 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-04-20 17:37:51.582064 | controller | environment: line 5: kubectl: command not found 2026-04-20 17:37:51.583496 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-20 17:37:51.583851 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-20 17:37:51.583956 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-20 17:37:51.838893 | controller | ok: Runtime: 0:00:00.013636 2026-04-20 17:37:51.849484 | 2026-04-20 17:37:51.849626 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-04-20 17:37:52.614713 | controller | changed: 2026-04-20 17:37:52.614998 | controller | created directory /var/lib/zuul/builds/ff6c54f45fe3453fbd8042f258234043/work/logs/controller 2026-04-20 17:37:52.615043 | controller | cd+++++++++ objects/ 2026-04-20 17:37:52.615078 | controller | cd+++++++++ objects/cluster/ 2026-04-20 17:37:52.615111 | controller | cd+++++++++ objects/namespaced/ 2026-04-20 17:37:52.625298 | 2026-04-20 17:37:52.625403 | TASK [gather-pod-logs : creating directory for pod logs] 2026-04-20 17:37:52.864077 | controller | changed 2026-04-20 17:37:52.870626 | 2026-04-20 17:37:52.870727 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-04-20 17:37:53.137331 | controller | changed 2026-04-20 17:37:53.217568 | 2026-04-20 17:37:53.217723 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-04-20 17:37:53.468740 | controller | environment: line 3: kubectl: command not found 2026-04-20 17:37:53.994903 | controller | ok: Runtime: 0:00:00.016001 2026-04-20 17:37:54.002279 | 2026-04-20 17:37:54.002469 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-04-20 17:37:54.603215 | controller | changed: 2026-04-20 17:37:54.603489 | controller | cd+++++++++ pod-logs/ 2026-04-20 17:37:54.603533 | controller | cd+++++++++ pod-logs/failed-pods/ 2026-04-20 17:37:54.612244 | 2026-04-20 17:37:54.612310 | LOOP [helm-release-status : creating directory for helm release status] 2026-04-20 17:37:54.868733 | controller | changed: "values" 2026-04-20 17:37:55.083667 | controller | changed: "releases" 2026-04-20 17:37:55.096778 | 2026-04-20 17:37:55.097465 | TASK [helm-release-status : Gather get release status for helm charts] 2026-04-20 17:37:55.356063 | controller | /bin/bash: line 3: kubectl: command not found 2026-04-20 17:37:55.626540 | controller | ok: Runtime: 0:00:00.008345 2026-04-20 17:37:55.632942 | 2026-04-20 17:37:55.633087 | TASK [helm-release-status : Downloads logs to executor] 2026-04-20 17:37:56.270955 | controller | changed: 2026-04-20 17:37:56.271138 | controller | cd+++++++++ helm/ 2026-04-20 17:37:56.271167 | controller | cd+++++++++ helm/releases/ 2026-04-20 17:37:56.271190 | controller | cd+++++++++ helm/values/ 2026-04-20 17:37:56.278629 | 2026-04-20 17:37:56.278684 | PLAY RECAP 2026-04-20 17:37:56.278729 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-20 17:37:56.278752 | 2026-04-20 17:37:56.560729 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-04-20 17:37:56.564013 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-20 17:37:57.155480 | 2026-04-20 17:37:57.155919 | PLAY [all] 2026-04-20 17:37:57.166508 | 2026-04-20 17:37:57.167588 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-20 17:37:57.222178 | controller | skipping: Conditional result was False 2026-04-20 17:37:57.230739 | 2026-04-20 17:37:57.230918 | TASK [fetch-output : Set log path for single node] 2026-04-20 17:37:57.271748 | controller | ok 2026-04-20 17:37:57.276005 | 2026-04-20 17:37:57.276072 | LOOP [fetch-output : Ensure local output dirs] 2026-04-20 17:37:57.723997 | controller -> localhost | ok: "/var/lib/zuul/builds/ff6c54f45fe3453fbd8042f258234043/work/logs" 2026-04-20 17:37:57.965453 | controller -> localhost | changed: "/var/lib/zuul/builds/ff6c54f45fe3453fbd8042f258234043/work/artifacts" 2026-04-20 17:37:58.166962 | controller -> localhost | changed: "/var/lib/zuul/builds/ff6c54f45fe3453fbd8042f258234043/work/docs" 2026-04-20 17:37:58.185289 | 2026-04-20 17:37:58.185417 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-20 17:37:58.961569 | controller | changed: .d..t...... ./ 2026-04-20 17:37:58.962020 | controller | changed: All items complete 2026-04-20 17:37:58.962089 | 2026-04-20 17:37:59.480604 | controller | changed: .d..t...... ./ 2026-04-20 17:38:00.061999 | controller | changed: .d..t...... ./ 2026-04-20 17:38:00.085704 | 2026-04-20 17:38:00.085851 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-20 17:38:00.578894 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007851 2026-04-20 17:38:00.809843 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007261 2026-04-20 17:38:00.821934 | 2026-04-20 17:38:00.822041 | PLAY [all] 2026-04-20 17:38:00.843730 | 2026-04-20 17:38:00.843893 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-20 17:38:01.328186 | controller | changed 2026-04-20 17:38:01.334173 | 2026-04-20 17:38:01.334256 | PLAY RECAP 2026-04-20 17:38:01.334318 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-20 17:38:01.334349 | 2026-04-20 17:38:01.559534 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-20 17:38:01.576248 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-20 17:38:02.130305 | 2026-04-20 17:38:02.130427 | PLAY [localhost] 2026-04-20 17:38:02.151192 | 2026-04-20 17:38:02.362970 | TASK [Generate Zuul manifest] 2026-04-20 17:38:02.396609 | localhost | ok 2026-04-20 17:38:02.421723 | 2026-04-20 17:38:02.421847 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-20 17:38:02.737267 | localhost | changed 2026-04-20 17:38:02.749083 | 2026-04-20 17:38:02.749207 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-20 17:38:02.781076 | localhost | ok 2026-04-20 17:38:02.790594 | 2026-04-20 17:38:02.790693 | TASK [Upload logs] 2026-04-20 17:38:02.813825 | localhost | ok 2026-04-20 17:38:02.876641 | 2026-04-20 17:38:02.876797 | TASK [Set zuul-log-path fact] 2026-04-20 17:38:02.899224 | localhost | ok 2026-04-20 17:38:02.913895 | 2026-04-20 17:38:02.913984 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-20 17:38:02.947522 | localhost | ok 2026-04-20 17:38:02.957141 | 2026-04-20 17:38:02.957214 | TASK [upload-logs : Create log directories] 2026-04-20 17:38:03.343488 | localhost | changed 2026-04-20 17:38:03.350933 | 2026-04-20 17:38:03.351031 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-20 17:38:03.730177 | localhost -> localhost | ok: Runtime: 0:00:00.005693 2026-04-20 17:38:03.737508 | 2026-04-20 17:38:03.737612 | TASK [upload-logs : Upload logs to log server] 2026-04-20 17:38:04.182393 | localhost | Output suppressed because no_log was given 2026-04-20 17:38:04.186139 | 2026-04-20 17:38:04.186216 | LOOP [upload-logs : Compress console log and json output] 2026-04-20 17:38:04.232862 | localhost | skipping: Conditional result was False 2026-04-20 17:38:04.239781 | localhost | skipping: Conditional result was False 2026-04-20 17:38:04.252226 | 2026-04-20 17:38:04.252521 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-20 17:38:04.305827 | localhost | skipping: Conditional result was False 2026-04-20 17:38:04.306131 | 2026-04-20 17:38:04.306944 | localhost | skipping: Conditional result was False 2026-04-20 17:38:04.313994 | 2026-04-20 17:38:04.314165 | LOOP [upload-logs : Upload console log and json output]