2026-04-18 17:51:14.163485 | Job console starting 2026-04-18 17:51:14.178061 | Updating git repos 2026-04-18 17:51:14.221624 | Cloning repos into workspace 2026-04-18 17:51:17.650089 | Restoring repo states 2026-04-18 17:51:17.666223 | Merging changes 2026-04-18 17:51:19.829070 | Checking out repos 2026-04-18 17:51:19.944135 | Preparing playbooks 2026-04-18 17:51:24.892199 | Running Ansible setup 2026-04-18 17:51:29.041433 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-18 17:51:29.716614 | 2026-04-18 17:51:29.921085 | PLAY [localhost] 2026-04-18 17:51:29.935656 | 2026-04-18 17:51:29.935801 | TASK [Gathering Facts] 2026-04-18 17:51:30.870195 | localhost | ok 2026-04-18 17:51:30.879116 | 2026-04-18 17:51:30.879214 | TASK [Setup log path fact] 2026-04-18 17:51:30.901146 | localhost | ok 2026-04-18 17:51:30.914072 | 2026-04-18 17:51:30.914173 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-18 17:51:30.943319 | localhost | ok 2026-04-18 17:51:30.952610 | 2026-04-18 17:51:30.952733 | TASK [emit-job-header : Print job information] 2026-04-18 17:51:30.994168 | # Job Information 2026-04-18 17:51:30.994399 | Ansible Version: 2.16.16 2026-04-18 17:51:30.994452 | Job: ansible-collection-kubernetes-molecule-helm 2026-04-18 17:51:30.994492 | Pipeline: check 2026-04-18 17:51:30.994525 | Executor: 0a8996d2b663 2026-04-18 17:51:30.994554 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/260 2026-04-18 17:51:30.994588 | Event ID: 63431510-3b4c-11f1-9dcc-f0b7af1855b2 2026-04-18 17:51:30.998536 | 2026-04-18 17:51:30.998625 | LOOP [emit-job-header : Print node information] 2026-04-18 17:51:31.094269 | localhost | ok: 2026-04-18 17:51:31.094452 | localhost | # Node Information 2026-04-18 17:51:31.094484 | localhost | Inventory Hostname: controller 2026-04-18 17:51:31.094507 | localhost | Hostname: np0000167608 2026-04-18 17:51:31.094530 | localhost | Username: zuul 2026-04-18 17:51:31.094561 | localhost | Distro: Debian 13.4 2026-04-18 17:51:31.094588 | localhost | Provider: yul1 2026-04-18 17:51:31.094618 | localhost | Region: ca-ymq-1 2026-04-18 17:51:31.094645 | localhost | Label: debian-trixie 2026-04-18 17:51:31.094677 | localhost | Product Name: OpenStack Nova 2026-04-18 17:51:31.094704 | localhost | Interface IP: 199.19.213.231 2026-04-18 17:51:31.112005 | 2026-04-18 17:51:31.112134 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-18 17:51:31.653302 | localhost -> localhost | changed 2026-04-18 17:51:31.661323 | 2026-04-18 17:51:31.661463 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-18 17:51:32.831229 | localhost -> localhost | changed 2026-04-18 17:51:32.839752 | 2026-04-18 17:51:32.839868 | PLAY [all] 2026-04-18 17:51:32.850204 | 2026-04-18 17:51:32.850320 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-18 17:51:33.087545 | controller -> localhost | ok 2026-04-18 17:51:33.096818 | 2026-04-18 17:51:33.096931 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-18 17:51:33.131207 | controller | ok 2026-04-18 17:51:33.148899 | controller | included: /var/lib/zuul/builds/11f715a30cff4122832558c15429d30f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-18 17:51:33.154199 | 2026-04-18 17:51:33.154263 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-18 17:51:35.272101 | controller -> localhost | Generating public/private rsa key pair. 2026-04-18 17:51:35.272364 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/11f715a30cff4122832558c15429d30f/work/11f715a30cff4122832558c15429d30f_id_rsa 2026-04-18 17:51:35.272435 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/11f715a30cff4122832558c15429d30f/work/11f715a30cff4122832558c15429d30f_id_rsa.pub 2026-04-18 17:51:35.272490 | controller -> localhost | The key fingerprint is: 2026-04-18 17:51:35.272542 | controller -> localhost | SHA256:iPyG7nmghKBSebyQAteGesY+FF9Hz8JtUuUxZvd4rwk zuul-build-sshkey 2026-04-18 17:51:35.272610 | controller -> localhost | The key's randomart image is: 2026-04-18 17:51:35.272663 | controller -> localhost | +---[RSA 3072]----+ 2026-04-18 17:51:35.272777 | controller -> localhost | | o .. ..* . | 2026-04-18 17:51:35.272838 | controller -> localhost | |. + o ...= + + o | 2026-04-18 17:51:35.272890 | controller -> localhost | |.+ B . .+ = . . o| 2026-04-18 17:51:35.272940 | controller -> localhost | |+ X.+. . + ..| 2026-04-18 17:51:35.273022 | controller -> localhost | |oO oo.. S E .| 2026-04-18 17:51:35.273074 | controller -> localhost | |+ + oo . o | 2026-04-18 17:51:35.273123 | controller -> localhost | |.. o..o o | 2026-04-18 17:51:35.273172 | controller -> localhost | | .. o. | 2026-04-18 17:51:35.273227 | controller -> localhost | | .+. | 2026-04-18 17:51:35.273276 | controller -> localhost | +----[SHA256]-----+ 2026-04-18 17:51:35.273371 | controller -> localhost | ok: Runtime: 0:00:01.686599 2026-04-18 17:51:35.277712 | 2026-04-18 17:51:35.277774 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-18 17:51:35.310013 | controller | ok 2026-04-18 17:51:35.318101 | controller | included: /var/lib/zuul/builds/11f715a30cff4122832558c15429d30f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-18 17:51:35.326904 | 2026-04-18 17:51:35.327019 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-18 17:51:35.351521 | controller | skipping: Conditional result was False 2026-04-18 17:51:35.361696 | 2026-04-18 17:51:35.361790 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-18 17:51:35.907309 | controller | changed 2026-04-18 17:51:35.912120 | 2026-04-18 17:51:35.912188 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-18 17:51:36.126486 | controller | ok 2026-04-18 17:51:36.131075 | 2026-04-18 17:51:36.131141 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-18 17:51:37.202141 | controller | changed 2026-04-18 17:51:37.208502 | 2026-04-18 17:51:37.208600 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-18 17:51:37.783989 | controller | changed 2026-04-18 17:51:37.795097 | 2026-04-18 17:51:37.795246 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-18 17:51:37.821880 | controller | skipping: Conditional result was False 2026-04-18 17:51:37.834097 | 2026-04-18 17:51:37.834232 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-18 17:51:38.282078 | controller -> localhost | changed 2026-04-18 17:51:38.294916 | 2026-04-18 17:51:38.295038 | TASK [add-build-sshkey : Add back temp key] 2026-04-18 17:51:38.597960 | controller -> localhost | Identity added: /var/lib/zuul/builds/11f715a30cff4122832558c15429d30f/work/11f715a30cff4122832558c15429d30f_id_rsa (zuul-build-sshkey) 2026-04-18 17:51:38.598401 | controller -> localhost | ok: Runtime: 0:00:00.014637 2026-04-18 17:51:38.606418 | 2026-04-18 17:51:38.606636 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-18 17:51:38.963568 | controller | ok 2026-04-18 17:51:38.968573 | 2026-04-18 17:51:38.968657 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-18 17:51:38.994099 | controller | skipping: Conditional result was False 2026-04-18 17:51:39.007075 | 2026-04-18 17:51:39.007219 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-18 17:51:39.400337 | controller | ok 2026-04-18 17:51:39.405410 | 2026-04-18 17:51:39.405470 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-18 17:51:40.508461 | controller | Output suppressed because no_log was given 2026-04-18 17:51:40.515994 | 2026-04-18 17:51:40.516095 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-18 17:51:40.734491 | controller | ok: "logs" 2026-04-18 17:51:40.941456 | controller | ok: All items complete 2026-04-18 17:51:40.941607 | 2026-04-18 17:51:40.950293 | controller | ok: "artifacts" 2026-04-18 17:51:41.110727 | controller | ok: "docs" 2026-04-18 17:51:41.123609 | 2026-04-18 17:51:41.123737 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-18 17:51:41.325726 | controller | changed: "logs" 2026-04-18 17:51:41.531700 | controller | changed: "artifacts" 2026-04-18 17:51:41.757580 | controller | changed: "docs" 2026-04-18 17:51:41.773769 | 2026-04-18 17:51:41.773881 | PLAY RECAP 2026-04-18 17:51:41.773931 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-18 17:51:41.773960 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-18 17:51:41.773983 | 2026-04-18 17:51:41.905672 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-18 17:51:41.912276 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-18 17:51:42.535166 | 2026-04-18 17:51:42.535363 | PLAY [all] 2026-04-18 17:51:42.549536 | 2026-04-18 17:51:42.549677 | TASK [setup-uv : Extract archive] 2026-04-18 17:51:45.082465 | controller | changed 2026-04-18 17:51:45.088041 | 2026-04-18 17:51:45.088117 | TASK [setup-uv : Print version] 2026-04-18 17:51:45.456593 | controller | uv 0.8.13 2026-04-18 17:51:45.614917 | controller | ok: Runtime: 0:00:00.011727 2026-04-18 17:51:45.621432 | 2026-04-18 17:51:45.621507 | PLAY RECAP 2026-04-18 17:51:45.621550 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-18 17:51:45.621576 | 2026-04-18 17:51:45.734751 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-18 17:51:45.736078 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-04-18 17:51:46.387072 | 2026-04-18 17:51:46.387202 | PLAY [all] 2026-04-18 17:51:46.397299 | 2026-04-18 17:51:46.397447 | TASK [Update apt cache] 2026-04-18 17:51:50.932740 | controller | ok 2026-04-18 17:51:50.939661 | 2026-04-18 17:51:50.939764 | TASK [Disable firewalld] 2026-04-18 17:51:50.954622 | controller | skipping: Conditional result was False 2026-04-18 17:51:50.962071 | 2026-04-18 17:51:50.962140 | TASK [Install "jq" for log collection] 2026-04-18 17:51:53.048717 | controller | changed 2026-04-18 17:51:53.051468 | 2026-04-18 17:51:53.051731 | PLAY RECAP 2026-04-18 17:51:53.051831 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-18 17:51:53.051867 | 2026-04-18 17:51:53.238785 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-04-18 17:51:53.240613 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-18 17:51:53.850063 | 2026-04-18 17:51:53.850195 | PLAY [all] 2026-04-18 17:51:53.860151 | 2026-04-18 17:51:53.860225 | TASK [Copy inventory file for Zuul] 2026-04-18 17:51:54.792630 | controller | changed 2026-04-18 17:51:54.802687 | 2026-04-18 17:51:54.802839 | TASK [Switch "ansible_host" to private IP] 2026-04-18 17:51:55.099048 | controller | changed: 1 replacements made 2026-04-18 17:51:55.104405 | 2026-04-18 17:51:55.104517 | TASK [Run Molecule scenario] 2026-04-18 17:51:55.539408 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-04-18 17:51:55.539498 | controller | Creating virtual environment at: .venv 2026-04-18 17:51:55.808951 | controller | Downloading pygments (1.2MiB) 2026-04-18 17:51:55.809115 | controller | Downloading cryptography (4.3MiB) 2026-04-18 17:51:55.810190 | controller | Downloading ansible-core (2.3MiB) 2026-04-18 17:51:55.962556 | controller | Downloading cryptography 2026-04-18 17:51:56.007798 | controller | Downloading pygments 2026-04-18 17:51:56.185543 | controller | Downloading ansible-core 2026-04-18 17:51:56.229873 | controller | Installed 36 packages in 43ms 2026-04-18 17:51:56.878834 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-04-18 17:51:56.878887 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-04-18 17:51:57.750671 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-04-18 17:51:57.750732 | controller | INFO default scenario not found, disabling shared state. 2026-04-18 17:51:57.750868 | controller | INFO [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-04-18 17:51:57.750936 | controller | INFO [helm > prerun] Performing prerun with role_name_check=0... 2026-04-18 17:52:12.902495 | controller | INFO [helm > dependency] Executing 2026-04-18 17:52:12.902586 | controller | WARNING [helm > dependency] Missing roles requirements file: requirements.yml 2026-04-18 17:52:12.902830 | controller | WARNING [helm > dependency] Missing collections requirements file: collections.yml 2026-04-18 17:52:12.902880 | controller | WARNING [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-04-18 17:52:12.908238 | controller | INFO [helm > cleanup] Executing 2026-04-18 17:52:12.908501 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-18 17:52:12.913788 | controller | INFO [helm > destroy] Executing 2026-04-18 17:52:12.913812 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-04-18 17:52:12.913825 | controller | INFO [helm > destroy] Executed: Successful 2026-04-18 17:52:12.919084 | controller | INFO [helm > syntax] Executing 2026-04-18 17:52:13.785602 | controller | 2026-04-18 17:52:13.785891 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml 2026-04-18 17:52:13.849899 | controller | INFO [helm > syntax] Executed: Successful 2026-04-18 17:52:13.860088 | controller | INFO [helm > create] Executing 2026-04-18 17:52:13.861537 | controller | WARNING [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-18 17:52:13.866817 | controller | INFO [helm > prepare] Executing 2026-04-18 17:52:14.708321 | controller | 2026-04-18 17:52:14.708499 | controller | PLAY [Prepare] ***************************************************************** 2026-04-18 17:52:14.708674 | controller | 2026-04-18 17:52:14.708924 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-18 17:52:14.709112 | controller | Saturday 18 April 2026 17:52:14 +0000 (0:00:00.027) 0:00:00.027 ******** 2026-04-18 17:52:16.949244 | 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-18 17:52:16.954237 | controller | ok: [controller] 2026-04-18 17:52:16.954388 | controller | 2026-04-18 17:52:16.954542 | controller | TASK [Install Debian packages] ************************************************* 2026-04-18 17:52:16.954678 | controller | Saturday 18 April 2026 17:52:16 +0000 (0:00:02.246) 0:00:02.274 ******** 2026-04-18 17:52:18.198341 | controller | ok: [controller] 2026-04-18 17:52:18.198438 | controller | 2026-04-18 17:52:18.198560 | controller | PLAY RECAP ********************************************************************* 2026-04-18 17:52:18.198695 | controller | controller : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-18 17:52:18.198805 | controller | 2026-04-18 17:52:18.198904 | controller | 2026-04-18 17:52:18.199028 | controller | TASKS RECAP ******************************************************************** 2026-04-18 17:52:18.199167 | controller | Saturday 18 April 2026 17:52:18 +0000 (0:00:01.244) 0:00:03.519 ******** 2026-04-18 17:52:18.199282 | controller | =============================================================================== 2026-04-18 17:52:18.199405 | controller | Gathering Facts --------------------------------------------------------- 2.25s 2026-04-18 17:52:18.199524 | controller | Install Debian packages ------------------------------------------------- 1.24s 2026-04-18 17:52:18.295550 | controller | INFO [helm > prepare] Executed: Successful 2026-04-18 17:52:18.303166 | controller | INFO [helm > converge] Executing 2026-04-18 17:52:19.117218 | controller | 2026-04-18 17:52:19.117391 | controller | PLAY [Converge] **************************************************************** 2026-04-18 17:52:19.117569 | controller | 2026-04-18 17:52:19.117777 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-18 17:52:19.117992 | controller | Saturday 18 April 2026 17:52:19 +0000 (0:00:00.012) 0:00:00.012 ******** 2026-04-18 17:52:20.139146 | 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-18 17:52:20.144433 | controller | ok: [controller] 2026-04-18 17:52:20.144615 | controller | 2026-04-18 17:52:20.144951 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-18 17:52:20.145121 | controller | Saturday 18 April 2026 17:52:20 +0000 (0:00:01.026) 0:00:01.039 ******** 2026-04-18 17:52:20.619477 | controller | ok: [controller] 2026-04-18 17:52:20.619718 | controller | 2026-04-18 17:52:20.619996 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-18 17:52:20.620253 | controller | Saturday 18 April 2026 17:52:20 +0000 (0:00:00.474) 0:00:01.514 ******** 2026-04-18 17:52:21.610472 | controller | ok: [controller] 2026-04-18 17:52:21.610559 | controller | 2026-04-18 17:52:21.610703 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-04-18 17:52:21.610831 | controller | Saturday 18 April 2026 17:52:21 +0000 (0:00:00.991) 0:00:02.505 ******** 2026-04-18 17:52:22.029935 | controller | changed: [controller] 2026-04-18 17:52:22.030091 | controller | 2026-04-18 17:52:22.030299 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-18 17:52:22.030496 | controller | Saturday 18 April 2026 17:52:22 +0000 (0:00:00.419) 0:00:02.924 ******** 2026-04-18 17:52:22.071357 | controller | ok: [controller] => { 2026-04-18 17:52:22.071539 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-04-18 17:52:22.071716 | controller | } 2026-04-18 17:52:22.071884 | controller | 2026-04-18 17:52:22.072075 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-18 17:52:22.072265 | controller | Saturday 18 April 2026 17:52:22 +0000 (0:00:00.041) 0:00:02.966 ******** 2026-04-18 17:52:23.060522 | 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-18 17:52:23.069867 | controller | changed: [controller] 2026-04-18 17:52:23.070055 | controller | 2026-04-18 17:52:23.070262 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-18 17:52:23.070460 | controller | Saturday 18 April 2026 17:52:23 +0000 (0:00:00.998) 0:00:03.964 ******** 2026-04-18 17:52:25.046398 | controller | changed: [controller] 2026-04-18 17:52:25.046594 | controller | 2026-04-18 17:52:25.046803 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-04-18 17:52:25.047000 | controller | Saturday 18 April 2026 17:52:25 +0000 (0:00:01.976) 0:00:05.941 ******** 2026-04-18 17:52:25.545198 | controller | ok: [controller] 2026-04-18 17:52:25.545266 | controller | 2026-04-18 17:52:25.545401 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-04-18 17:52:25.545524 | controller | Saturday 18 April 2026 17:52:25 +0000 (0:00:00.498) 0:00:06.440 ******** 2026-04-18 17:52:25.929333 | controller | changed: [controller] 2026-04-18 17:52:25.929505 | controller | 2026-04-18 17:52:25.929773 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-04-18 17:52:25.929989 | controller | Saturday 18 April 2026 17:52:25 +0000 (0:00:00.383) 0:00:06.823 ******** 2026-04-18 17:52:26.359487 | controller | ok: [controller] 2026-04-18 17:52:26.359651 | controller | 2026-04-18 17:52:26.359899 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-04-18 17:52:26.360139 | controller | Saturday 18 April 2026 17:52:26 +0000 (0:00:00.430) 0:00:07.254 ******** 2026-04-18 17:52:26.644204 | controller | changed: [controller] 2026-04-18 17:52:26.644360 | controller | 2026-04-18 17:52:26.644583 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-04-18 17:52:26.644861 | controller | Saturday 18 April 2026 17:52:26 +0000 (0:00:00.284) 0:00:07.539 ******** 2026-04-18 17:52:26.930515 | controller | ok: [controller] 2026-04-18 17:52:26.930656 | controller | 2026-04-18 17:52:26.930868 | controller | TASK [Install plugin] ********************************************************** 2026-04-18 17:52:26.931070 | controller | Saturday 18 April 2026 17:52:26 +0000 (0:00:00.286) 0:00:07.825 ******** 2026-04-18 17:52:26.978772 | controller | included: vexxhost.containers.download_artifact for controller 2026-04-18 17:52:26.978859 | controller | 2026-04-18 17:52:26.979002 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-18 17:52:26.979201 | controller | Saturday 18 April 2026 17:52:26 +0000 (0:00:00.048) 0:00:07.873 ******** 2026-04-18 17:52:27.021701 | controller | ok: [controller] => { 2026-04-18 17:52:27.021791 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-04-18 17:52:27.021972 | controller | } 2026-04-18 17:52:27.022048 | controller | 2026-04-18 17:52:27.022241 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-18 17:52:27.022325 | controller | Saturday 18 April 2026 17:52:27 +0000 (0:00:00.041) 0:00:07.915 ******** 2026-04-18 17:52:37.235901 | controller | FAILED - RETRYING: [controller]: Download item (4 retries left). 2026-04-18 17:52:37.236029 | controller | changed: [controller] 2026-04-18 17:52:37.236173 | controller | 2026-04-18 17:52:37.236330 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-18 17:52:37.236523 | controller | Saturday 18 April 2026 17:52:37 +0000 (0:00:10.214) 0:00:18.130 ******** 2026-04-18 17:52:39.285740 | controller | changed: [controller] 2026-04-18 17:52:39.285823 | controller | 2026-04-18 17:52:39.286000 | controller | PLAY RECAP ********************************************************************* 2026-04-18 17:52:39.286193 | controller | controller : ok=16 changed=7 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-18 17:52:39.286355 | controller | 2026-04-18 17:52:39.286506 | controller | 2026-04-18 17:52:39.286653 | controller | TASKS RECAP ******************************************************************** 2026-04-18 17:52:39.286771 | controller | Saturday 18 April 2026 17:52:39 +0000 (0:00:02.050) 0:00:20.181 ******** 2026-04-18 17:52:39.286924 | controller | =============================================================================== 2026-04-18 17:52:39.287057 | controller | vexxhost.containers.download_artifact : Download item ------------------ 10.21s 2026-04-18 17:52:39.287218 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.05s 2026-04-18 17:52:39.287345 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.98s 2026-04-18 17:52:39.287462 | controller | Gathering Facts --------------------------------------------------------- 1.03s 2026-04-18 17:52:39.287578 | controller | vexxhost.containers.download_artifact : Download item ------------------- 1.00s 2026-04-18 17:52:39.287695 | controller | vexxhost.containers.package : Update state for tar ---------------------- 0.99s 2026-04-18 17:52:39.287811 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.50s 2026-04-18 17:52:39.287927 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.47s 2026-04-18 17:52:39.288091 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.43s 2026-04-18 17:52:39.288224 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.42s 2026-04-18 17:52:39.288344 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.38s 2026-04-18 17:52:39.288486 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.29s 2026-04-18 17:52:39.288612 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.28s 2026-04-18 17:52:39.288760 | controller | Install plugin ---------------------------------------------------------- 0.05s 2026-04-18 17:52:39.288879 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-04-18 17:52:39.288999 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-04-18 17:52:39.371926 | controller | INFO [helm > converge] Executed: Successful 2026-04-18 17:52:39.381946 | controller | INFO [helm > idempotence] Executing 2026-04-18 17:52:40.220178 | controller | 2026-04-18 17:52:40.220288 | controller | PLAY [Converge] **************************************************************** 2026-04-18 17:52:40.220471 | controller | 2026-04-18 17:52:40.220664 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-18 17:52:40.220934 | controller | Saturday 18 April 2026 17:52:40 +0000 (0:00:00.012) 0:00:00.012 ******** 2026-04-18 17:52:41.272394 | 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-18 17:52:41.280156 | controller | ok: [controller] 2026-04-18 17:52:41.280273 | controller | 2026-04-18 17:52:41.280401 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-18 17:52:41.280522 | controller | Saturday 18 April 2026 17:52:41 +0000 (0:00:01.057) 0:00:01.070 ******** 2026-04-18 17:52:41.682122 | controller | ok: [controller] 2026-04-18 17:52:41.682180 | controller | 2026-04-18 17:52:41.682227 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-18 17:52:41.684780 | controller | Saturday 18 April 2026 17:52:41 +0000 (0:00:00.404) 0:00:01.475 ******** 2026-04-18 17:52:42.661461 | controller | ok: [controller] 2026-04-18 17:52:42.661647 | controller | 2026-04-18 17:52:42.661868 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-04-18 17:52:42.662073 | controller | Saturday 18 April 2026 17:52:42 +0000 (0:00:00.978) 0:00:02.453 ******** 2026-04-18 17:52:43.066263 | controller | ok: [controller] 2026-04-18 17:52:43.066393 | controller | 2026-04-18 17:52:43.066622 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-18 17:52:43.066837 | controller | Saturday 18 April 2026 17:52:43 +0000 (0:00:00.405) 0:00:02.858 ******** 2026-04-18 17:52:43.112925 | controller | ok: [controller] => { 2026-04-18 17:52:43.113021 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-04-18 17:52:43.113137 | controller | } 2026-04-18 17:52:43.113256 | controller | 2026-04-18 17:52:43.113384 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-18 17:52:43.113504 | controller | Saturday 18 April 2026 17:52:43 +0000 (0:00:00.046) 0:00:02.905 ******** 2026-04-18 17:52:43.624385 | controller | ok: [controller] 2026-04-18 17:52:43.624544 | controller | 2026-04-18 17:52:43.624814 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-18 17:52:43.625028 | controller | Saturday 18 April 2026 17:52:43 +0000 (0:00:00.511) 0:00:03.416 ******** 2026-04-18 17:52:45.289075 | controller | ok: [controller] 2026-04-18 17:52:45.289115 | controller | 2026-04-18 17:52:45.289221 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-04-18 17:52:45.289301 | controller | Saturday 18 April 2026 17:52:45 +0000 (0:00:01.663) 0:00:05.080 ******** 2026-04-18 17:52:45.772967 | controller | ok: [controller] 2026-04-18 17:52:45.773031 | controller | 2026-04-18 17:52:45.773159 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-04-18 17:52:45.773285 | controller | Saturday 18 April 2026 17:52:45 +0000 (0:00:00.485) 0:00:05.565 ******** 2026-04-18 17:52:46.161941 | controller | ok: [controller] 2026-04-18 17:52:46.161989 | controller | 2026-04-18 17:52:46.162145 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-04-18 17:52:46.162283 | controller | Saturday 18 April 2026 17:52:46 +0000 (0:00:00.388) 0:00:05.954 ******** 2026-04-18 17:52:46.608618 | controller | ok: [controller] 2026-04-18 17:52:46.608639 | controller | 2026-04-18 17:52:46.608643 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-04-18 17:52:46.608646 | controller | Saturday 18 April 2026 17:52:46 +0000 (0:00:00.443) 0:00:06.398 ******** 2026-04-18 17:52:46.899139 | controller | ok: [controller] 2026-04-18 17:52:46.899290 | controller | 2026-04-18 17:52:46.899524 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-04-18 17:52:46.899723 | controller | Saturday 18 April 2026 17:52:46 +0000 (0:00:00.292) 0:00:06.691 ******** 2026-04-18 17:52:47.197069 | controller | ok: [controller] 2026-04-18 17:52:47.197229 | controller | 2026-04-18 17:52:47.200802 | controller | TASK [Install plugin] ********************************************************** 2026-04-18 17:52:47.200828 | controller | Saturday 18 April 2026 17:52:47 +0000 (0:00:00.297) 0:00:06.989 ******** 2026-04-18 17:52:47.256430 | controller | included: vexxhost.containers.download_artifact for controller 2026-04-18 17:52:47.256461 | controller | 2026-04-18 17:52:47.256471 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-18 17:52:47.256479 | controller | Saturday 18 April 2026 17:52:47 +0000 (0:00:00.059) 0:00:07.048 ******** 2026-04-18 17:52:47.304077 | controller | ok: [controller] => { 2026-04-18 17:52:47.304403 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-04-18 17:52:47.304657 | controller | } 2026-04-18 17:52:47.304928 | controller | 2026-04-18 17:52:47.305183 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-18 17:52:47.305473 | controller | Saturday 18 April 2026 17:52:47 +0000 (0:00:00.048) 0:00:07.096 ******** 2026-04-18 17:52:47.710589 | controller | ok: [controller] 2026-04-18 17:52:47.710859 | controller | 2026-04-18 17:52:47.711120 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-18 17:52:47.711370 | controller | Saturday 18 April 2026 17:52:47 +0000 (0:00:00.405) 0:00:07.502 ******** 2026-04-18 17:52:49.301308 | controller | ok: [controller] 2026-04-18 17:52:49.301592 | controller | 2026-04-18 17:52:49.301853 | controller | PLAY RECAP ********************************************************************* 2026-04-18 17:52:49.302132 | controller | controller : ok=16 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-18 17:52:49.302369 | controller | 2026-04-18 17:52:49.302624 | controller | 2026-04-18 17:52:49.302879 | controller | TASKS RECAP ******************************************************************** 2026-04-18 17:52:49.303137 | controller | Saturday 18 April 2026 17:52:49 +0000 (0:00:01.586) 0:00:09.089 ******** 2026-04-18 17:52:49.303375 | controller | =============================================================================== 2026-04-18 17:52:49.303622 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.66s 2026-04-18 17:52:49.303872 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.59s 2026-04-18 17:52:49.304116 | controller | Gathering Facts --------------------------------------------------------- 1.06s 2026-04-18 17:52:49.304379 | controller | vexxhost.containers.package : Update state for tar ---------------------- 0.98s 2026-04-18 17:52:49.304633 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.51s 2026-04-18 17:52:49.304924 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.49s 2026-04-18 17:52:49.305176 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.44s 2026-04-18 17:52:49.305425 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.41s 2026-04-18 17:52:49.305672 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.41s 2026-04-18 17:52:49.305935 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.40s 2026-04-18 17:52:49.306187 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.39s 2026-04-18 17:52:49.306449 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.30s 2026-04-18 17:52:49.306738 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.29s 2026-04-18 17:52:49.306982 | controller | Install plugin ---------------------------------------------------------- 0.06s 2026-04-18 17:52:49.307228 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-04-18 17:52:49.307472 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-04-18 17:52:49.386125 | controller | INFO [helm > idempotence] Executed: Successful 2026-04-18 17:52:49.397146 | controller | INFO [helm > side_effect] Executing 2026-04-18 17:52:49.397390 | controller | WARNING [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-18 17:52:49.402962 | controller | INFO [helm > verify] Executing 2026-04-18 17:52:50.205626 | controller | 2026-04-18 17:52:50.205827 | controller | PLAY [Verify] ****************************************************************** 2026-04-18 17:52:50.206006 | controller | 2026-04-18 17:52:50.206201 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-18 17:52:50.206397 | controller | Saturday 18 April 2026 17:52:50 +0000 (0:00:00.009) 0:00:00.009 ******** 2026-04-18 17:52:51.253213 | 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-18 17:52:51.262412 | controller | ok: [controller] 2026-04-18 17:52:51.262618 | controller | 2026-04-18 17:52:51.262914 | controller | TASK [Get the current Helm version] ******************************************** 2026-04-18 17:52:51.263111 | controller | Saturday 18 April 2026 17:52:51 +0000 (0:00:01.055) 0:00:01.065 ******** 2026-04-18 17:52:51.699279 | controller | changed: [controller] 2026-04-18 17:52:51.699299 | controller | 2026-04-18 17:52:51.699303 | controller | TASK [Assert Helm version] ***************************************************** 2026-04-18 17:52:51.699307 | controller | Saturday 18 April 2026 17:52:51 +0000 (0:00:00.436) 0:00:01.501 ******** 2026-04-18 17:52:51.724322 | controller | ok: [controller] => { 2026-04-18 17:52:51.724557 | controller | "changed": false, 2026-04-18 17:52:51.724761 | controller | "msg": "All assertions passed" 2026-04-18 17:52:51.724955 | controller | } 2026-04-18 17:52:51.725141 | controller | 2026-04-18 17:52:51.725327 | controller | PLAY RECAP ********************************************************************* 2026-04-18 17:52:51.725542 | controller | controller : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-18 17:52:51.725718 | controller | 2026-04-18 17:52:51.725897 | controller | 2026-04-18 17:52:51.726088 | controller | TASKS RECAP ******************************************************************** 2026-04-18 17:52:51.726285 | controller | Saturday 18 April 2026 17:52:51 +0000 (0:00:00.026) 0:00:01.528 ******** 2026-04-18 17:52:51.726472 | controller | =============================================================================== 2026-04-18 17:52:51.726667 | controller | Gathering Facts --------------------------------------------------------- 1.06s 2026-04-18 17:52:51.726862 | controller | Get the current Helm version -------------------------------------------- 0.44s 2026-04-18 17:52:51.727051 | controller | Assert Helm version ----------------------------------------------------- 0.03s 2026-04-18 17:52:51.805754 | controller | INFO [helm > verify] Executed: Successful 2026-04-18 17:52:51.817717 | controller | INFO [helm > cleanup] Executing 2026-04-18 17:52:51.818011 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-18 17:52:51.823249 | controller | INFO [helm > destroy] Executing 2026-04-18 17:52:51.823351 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-04-18 17:52:51.823438 | controller | INFO [helm > destroy] Executed: Successful 2026-04-18 17:52:51.823706 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-04-18 17:52:52.177066 | controller | ok: Runtime: 0:00:56.415337 2026-04-18 17:52:52.181709 | 2026-04-18 17:52:52.181759 | PLAY RECAP 2026-04-18 17:52:52.181804 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-18 17:52:52.181826 | 2026-04-18 17:52:52.310515 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-18 17:52:52.313829 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-04-18 17:52:52.973932 | 2026-04-18 17:52:52.974075 | PLAY [all] 2026-04-18 17:52:52.985906 | 2026-04-18 17:52:52.986020 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-04-18 17:52:53.374521 | controller | changed 2026-04-18 17:52:53.382045 | 2026-04-18 17:52:53.382197 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-04-18 17:52:53.767228 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-18 17:52:53.767330 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-18 17:52:53.774130 | controller | environment: line 1: kubectl: command not found 2026-04-18 17:52:53.776036 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-18 17:52:53.777894 | controller | environment: line 1: kubectl: command not found 2026-04-18 17:52:53.779024 | controller | environment: line 1: kubectl: command not found 2026-04-18 17:52:53.779339 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-18 17:52:53.780077 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-18 17:52:53.784657 | controller | environment: line 1: kubectl: command not found 2026-04-18 17:52:53.785725 | controller | environment: line 1: kubectl: command not found 2026-04-18 17:52:53.786228 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-18 17:52:53.787814 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-18 17:52:53.914003 | controller | ok: Runtime: 0:00:00.033429 2026-04-18 17:52:53.922743 | 2026-04-18 17:52:53.922895 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-04-18 17:52:54.156704 | controller | changed 2026-04-18 17:52:54.524268 | 2026-04-18 17:52:54.524564 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-04-18 17:52:54.757337 | controller | environment: line 5: kubectl: command not found 2026-04-18 17:52:54.758214 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-18 17:52:54.758803 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-18 17:52:54.759222 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-18 17:52:55.057868 | controller | ok: Runtime: 0:00:00.012399 2026-04-18 17:52:55.065114 | 2026-04-18 17:52:55.065206 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-04-18 17:52:55.776322 | controller | changed: 2026-04-18 17:52:55.776511 | controller | created directory /var/lib/zuul/builds/11f715a30cff4122832558c15429d30f/work/logs/controller 2026-04-18 17:52:55.776555 | controller | cd+++++++++ objects/ 2026-04-18 17:52:55.776587 | controller | cd+++++++++ objects/cluster/ 2026-04-18 17:52:55.776619 | controller | cd+++++++++ objects/namespaced/ 2026-04-18 17:52:55.785011 | 2026-04-18 17:52:55.785082 | TASK [gather-pod-logs : creating directory for pod logs] 2026-04-18 17:52:56.019322 | controller | changed 2026-04-18 17:52:56.025061 | 2026-04-18 17:52:56.025151 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-04-18 17:52:56.249989 | controller | changed 2026-04-18 17:52:56.255479 | 2026-04-18 17:52:56.255568 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-04-18 17:52:56.489294 | controller | environment: line 3: kubectl: command not found 2026-04-18 17:52:56.785593 | controller | ok: Runtime: 0:00:00.012033 2026-04-18 17:52:56.791782 | 2026-04-18 17:52:56.791882 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-04-18 17:52:57.471279 | controller | changed: 2026-04-18 17:52:57.471564 | controller | cd+++++++++ pod-logs/ 2026-04-18 17:52:57.471615 | controller | cd+++++++++ pod-logs/failed-pods/ 2026-04-18 17:52:57.487099 | 2026-04-18 17:52:57.487218 | LOOP [helm-release-status : creating directory for helm release status] 2026-04-18 17:52:57.710889 | controller | changed: "values" 2026-04-18 17:52:57.901344 | controller | changed: "releases" 2026-04-18 17:52:57.912843 | 2026-04-18 17:52:57.913102 | TASK [helm-release-status : Gather get release status for helm charts] 2026-04-18 17:52:58.148068 | controller | /bin/bash: line 3: kubectl: command not found 2026-04-18 17:52:58.447366 | controller | ok: Runtime: 0:00:00.008671 2026-04-18 17:52:58.454981 | 2026-04-18 17:52:58.455133 | TASK [helm-release-status : Downloads logs to executor] 2026-04-18 17:52:59.004373 | controller | changed: 2026-04-18 17:52:59.004563 | controller | cd+++++++++ helm/ 2026-04-18 17:52:59.004702 | controller | cd+++++++++ helm/releases/ 2026-04-18 17:52:59.004743 | controller | cd+++++++++ helm/values/ 2026-04-18 17:52:59.012438 | 2026-04-18 17:52:59.012544 | PLAY RECAP 2026-04-18 17:52:59.012595 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-18 17:52:59.012617 | 2026-04-18 17:52:59.140701 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-04-18 17:52:59.143529 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-18 17:52:59.743161 | 2026-04-18 17:52:59.743294 | PLAY [all] 2026-04-18 17:52:59.753946 | 2026-04-18 17:52:59.754040 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-18 17:52:59.798783 | controller | skipping: Conditional result was False 2026-04-18 17:52:59.807255 | 2026-04-18 17:52:59.807379 | TASK [fetch-output : Set log path for single node] 2026-04-18 17:52:59.849373 | controller | ok 2026-04-18 17:52:59.854851 | 2026-04-18 17:52:59.855001 | LOOP [fetch-output : Ensure local output dirs] 2026-04-18 17:53:00.296711 | controller -> localhost | ok: "/var/lib/zuul/builds/11f715a30cff4122832558c15429d30f/work/logs" 2026-04-18 17:53:00.512071 | controller -> localhost | changed: "/var/lib/zuul/builds/11f715a30cff4122832558c15429d30f/work/artifacts" 2026-04-18 17:53:00.722176 | controller -> localhost | changed: "/var/lib/zuul/builds/11f715a30cff4122832558c15429d30f/work/docs" 2026-04-18 17:53:00.737858 | 2026-04-18 17:53:00.738044 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-18 17:53:01.400278 | controller | changed: .d..t...... ./ 2026-04-18 17:53:01.400500 | controller | changed: All items complete 2026-04-18 17:53:01.400529 | 2026-04-18 17:53:01.954764 | controller | changed: .d..t...... ./ 2026-04-18 17:53:02.440448 | controller | changed: .d..t...... ./ 2026-04-18 17:53:02.459015 | 2026-04-18 17:53:02.459165 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-18 17:53:02.878440 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006468 2026-04-18 17:53:03.114559 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007741 2026-04-18 17:53:03.132135 | 2026-04-18 17:53:03.132286 | PLAY [all] 2026-04-18 17:53:03.139150 | 2026-04-18 17:53:03.139234 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-18 17:53:03.597295 | controller | changed 2026-04-18 17:53:03.606525 | 2026-04-18 17:53:03.606616 | PLAY RECAP 2026-04-18 17:53:03.606693 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-18 17:53:03.606745 | 2026-04-18 17:53:03.750441 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-18 17:53:03.753249 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-18 17:53:04.356705 | 2026-04-18 17:53:04.356862 | PLAY [localhost] 2026-04-18 17:53:04.367395 | 2026-04-18 17:53:04.367517 | TASK [Generate Zuul manifest] 2026-04-18 17:53:04.386980 | localhost | ok 2026-04-18 17:53:04.404167 | 2026-04-18 17:53:04.404307 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-18 17:53:04.724884 | localhost | changed 2026-04-18 17:53:04.735370 | 2026-04-18 17:53:04.735450 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-18 17:53:05.148410 | localhost | ok 2026-04-18 17:53:05.159082 | 2026-04-18 17:53:05.159232 | TASK [Upload logs] 2026-04-18 17:53:05.178157 | localhost | ok 2026-04-18 17:53:05.243941 | 2026-04-18 17:53:05.244075 | TASK [Set zuul-log-path fact] 2026-04-18 17:53:05.263536 | localhost | ok 2026-04-18 17:53:05.277849 | 2026-04-18 17:53:05.277956 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-18 17:53:05.308268 | localhost | ok 2026-04-18 17:53:05.316710 | 2026-04-18 17:53:05.316785 | TASK [upload-logs : Create log directories] 2026-04-18 17:53:05.723061 | localhost | changed 2026-04-18 17:53:05.729533 | 2026-04-18 17:53:05.729618 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-18 17:53:06.139133 | localhost -> localhost | ok: Runtime: 0:00:00.004444 2026-04-18 17:53:06.145857 | 2026-04-18 17:53:06.145969 | TASK [upload-logs : Upload logs to log server] 2026-04-18 17:53:08.060135 | localhost | Output suppressed because no_log was given 2026-04-18 17:53:08.063840 | 2026-04-18 17:53:08.063907 | LOOP [upload-logs : Compress console log and json output] 2026-04-18 17:53:08.113372 | localhost | skipping: Conditional result was False 2026-04-18 17:53:08.121348 | localhost | skipping: Conditional result was False 2026-04-18 17:53:08.134642 | 2026-04-18 17:53:08.134878 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-18 17:53:08.174710 | localhost | skipping: Conditional result was False 2026-04-18 17:53:08.222178 | 2026-04-18 17:53:08.223919 | localhost | skipping: Conditional result was False 2026-04-18 17:53:08.244448 | 2026-04-18 17:53:08.244579 | LOOP [upload-logs : Upload console log and json output]