2026-04-22 07:00:06.780367 | Job console starting 2026-04-22 07:00:06.789875 | Updating git repos 2026-04-22 07:00:06.822716 | Cloning repos into workspace 2026-04-22 07:00:10.021431 | Restoring repo states 2026-04-22 07:00:10.045858 | Merging changes 2026-04-22 07:00:11.291997 | Checking out repos 2026-04-22 07:00:11.738338 | Preparing playbooks 2026-04-22 07:00:29.221376 | Running Ansible setup 2026-04-22 07:00:33.527102 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-22 07:00:34.128171 | 2026-04-22 07:00:34.128314 | PLAY [localhost] 2026-04-22 07:00:34.137305 | 2026-04-22 07:00:34.137377 | TASK [Gathering Facts] 2026-04-22 07:00:35.191143 | localhost | ok 2026-04-22 07:00:35.200211 | 2026-04-22 07:00:35.200287 | TASK [Setup log path fact] 2026-04-22 07:00:35.218329 | localhost | ok 2026-04-22 07:00:35.228180 | 2026-04-22 07:00:35.228242 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-22 07:00:35.263548 | localhost | ok 2026-04-22 07:00:35.270086 | 2026-04-22 07:00:35.270162 | TASK [emit-job-header : Print job information] 2026-04-22 07:00:35.311425 | # Job Information 2026-04-22 07:00:35.311571 | Ansible Version: 2.16.16 2026-04-22 07:00:35.311618 | Job: ansible-collection-kubernetes-molecule-helm 2026-04-22 07:00:35.311651 | Pipeline: check 2026-04-22 07:00:35.311680 | Executor: 0a8996d2b663 2026-04-22 07:00:35.311708 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/268 2026-04-22 07:00:35.311739 | Event ID: c36242d0-3e18-11f1-861f-7176c27794fb 2026-04-22 07:00:35.315620 | 2026-04-22 07:00:35.315711 | LOOP [emit-job-header : Print node information] 2026-04-22 07:00:35.398334 | localhost | ok: 2026-04-22 07:00:35.398531 | localhost | # Node Information 2026-04-22 07:00:35.398560 | localhost | Inventory Hostname: controller 2026-04-22 07:00:35.398582 | localhost | Hostname: np0000169165 2026-04-22 07:00:35.398602 | localhost | Username: zuul 2026-04-22 07:00:35.398624 | localhost | Distro: Debian 13.4 2026-04-22 07:00:35.398643 | localhost | Provider: yul1 2026-04-22 07:00:35.398663 | localhost | Region: ca-ymq-1 2026-04-22 07:00:35.398681 | localhost | Label: debian-trixie 2026-04-22 07:00:35.398700 | localhost | Product Name: OpenStack Nova 2026-04-22 07:00:35.398718 | localhost | Interface IP: 199.204.45.138 2026-04-22 07:00:35.411688 | 2026-04-22 07:00:35.411801 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-22 07:00:35.791342 | localhost -> localhost | changed 2026-04-22 07:00:35.798453 | 2026-04-22 07:00:35.799389 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-22 07:00:36.759015 | localhost -> localhost | changed 2026-04-22 07:00:36.772321 | 2026-04-22 07:00:36.772414 | PLAY [all] 2026-04-22 07:00:36.779733 | 2026-04-22 07:00:36.779802 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-22 07:00:37.007803 | controller -> localhost | ok 2026-04-22 07:00:37.016032 | 2026-04-22 07:00:37.016222 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-22 07:00:37.047968 | controller | ok 2026-04-22 07:00:37.061733 | controller | included: /var/lib/zuul/builds/a9a1def6c1c34bd6999a8ed296619854/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-22 07:00:37.066448 | 2026-04-22 07:00:37.066512 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-22 07:00:38.411610 | controller -> localhost | Generating public/private rsa key pair. 2026-04-22 07:00:38.411831 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/a9a1def6c1c34bd6999a8ed296619854/work/a9a1def6c1c34bd6999a8ed296619854_id_rsa 2026-04-22 07:00:38.411872 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/a9a1def6c1c34bd6999a8ed296619854/work/a9a1def6c1c34bd6999a8ed296619854_id_rsa.pub 2026-04-22 07:00:38.411904 | controller -> localhost | The key fingerprint is: 2026-04-22 07:00:38.411934 | controller -> localhost | SHA256:nCOJ2SOLCW20mLC+RHcRqg/RBuSvvyc9RHjhaxGA8jE zuul-build-sshkey 2026-04-22 07:00:38.411973 | controller -> localhost | The key's randomart image is: 2026-04-22 07:00:38.412003 | controller -> localhost | +---[RSA 3072]----+ 2026-04-22 07:00:38.412040 | controller -> localhost | |.o.... | 2026-04-22 07:00:38.412072 | controller -> localhost | |o.E .o. | 2026-04-22 07:00:38.412102 | controller -> localhost | |o+.*o.o | 2026-04-22 07:00:38.412131 | controller -> localhost | |.**o O.o . | 2026-04-22 07:00:38.412159 | controller -> localhost | |=++o*.B S | 2026-04-22 07:00:38.412186 | controller -> localhost | |oo=o.* o . | 2026-04-22 07:00:38.412214 | controller -> localhost | | +o.= | 2026-04-22 07:00:38.412242 | controller -> localhost | |. o. + | 2026-04-22 07:00:38.412273 | controller -> localhost | | . o+ . | 2026-04-22 07:00:38.412301 | controller -> localhost | +----[SHA256]-----+ 2026-04-22 07:00:38.412361 | controller -> localhost | ok: Runtime: 0:00:00.928208 2026-04-22 07:00:38.418048 | 2026-04-22 07:00:38.418137 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-22 07:00:38.439411 | controller | ok 2026-04-22 07:00:38.448816 | controller | included: /var/lib/zuul/builds/a9a1def6c1c34bd6999a8ed296619854/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-22 07:00:38.455813 | 2026-04-22 07:00:38.455870 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-22 07:00:38.470193 | controller | skipping: Conditional result was False 2026-04-22 07:00:38.478719 | 2026-04-22 07:00:38.478846 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-22 07:00:39.013233 | controller | changed 2026-04-22 07:00:39.017198 | 2026-04-22 07:00:39.017260 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-22 07:00:39.222336 | controller | ok 2026-04-22 07:00:39.227775 | 2026-04-22 07:00:39.227866 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-22 07:00:39.746863 | controller | changed 2026-04-22 07:00:39.752421 | 2026-04-22 07:00:39.752520 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-22 07:00:40.364324 | controller | changed 2026-04-22 07:00:40.371869 | 2026-04-22 07:00:40.371988 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-22 07:00:40.397365 | controller | skipping: Conditional result was False 2026-04-22 07:00:40.404276 | 2026-04-22 07:00:40.404376 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-22 07:00:40.780861 | controller -> localhost | changed 2026-04-22 07:00:40.790855 | 2026-04-22 07:00:40.790973 | TASK [add-build-sshkey : Add back temp key] 2026-04-22 07:00:41.077027 | controller -> localhost | Identity added: /var/lib/zuul/builds/a9a1def6c1c34bd6999a8ed296619854/work/a9a1def6c1c34bd6999a8ed296619854_id_rsa (zuul-build-sshkey) 2026-04-22 07:00:41.077299 | controller -> localhost | ok: Runtime: 0:00:00.013332 2026-04-22 07:00:41.082668 | 2026-04-22 07:00:41.082731 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-22 07:00:41.419081 | controller | ok 2026-04-22 07:00:41.424677 | 2026-04-22 07:00:41.424747 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-22 07:00:41.449932 | controller | skipping: Conditional result was False 2026-04-22 07:00:41.461852 | 2026-04-22 07:00:41.461942 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-22 07:00:41.857909 | controller | ok 2026-04-22 07:00:41.863924 | 2026-04-22 07:00:41.864040 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-22 07:00:42.889913 | controller | Output suppressed because no_log was given 2026-04-22 07:00:42.898038 | 2026-04-22 07:00:42.898101 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-22 07:00:43.135618 | controller | ok: "logs" 2026-04-22 07:00:43.135877 | controller | ok: All items complete 2026-04-22 07:00:43.135906 | 2026-04-22 07:00:43.318214 | controller | ok: "artifacts" 2026-04-22 07:00:43.513423 | controller | ok: "docs" 2026-04-22 07:00:43.525437 | 2026-04-22 07:00:43.525599 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-22 07:00:43.750345 | controller | changed: "logs" 2026-04-22 07:00:43.942878 | controller | changed: "artifacts" 2026-04-22 07:00:44.148825 | controller | changed: "docs" 2026-04-22 07:00:44.170680 | 2026-04-22 07:00:44.170810 | PLAY RECAP 2026-04-22 07:00:44.170863 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-22 07:00:44.170892 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-22 07:00:44.170913 | 2026-04-22 07:00:44.366841 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-22 07:00:44.369782 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-22 07:00:44.960833 | 2026-04-22 07:00:44.961399 | PLAY [all] 2026-04-22 07:00:44.971899 | 2026-04-22 07:00:44.971977 | TASK [setup-uv : Extract archive] 2026-04-22 07:00:47.357159 | controller | changed 2026-04-22 07:00:47.365392 | 2026-04-22 07:00:47.365470 | TASK [setup-uv : Print version] 2026-04-22 07:00:47.730062 | controller | uv 0.8.13 2026-04-22 07:00:47.891587 | controller | ok: Runtime: 0:00:00.013247 2026-04-22 07:00:47.896439 | 2026-04-22 07:00:47.896488 | PLAY RECAP 2026-04-22 07:00:47.896532 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-22 07:00:47.896555 | 2026-04-22 07:00:48.006387 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-22 07:00:48.009062 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-04-22 07:00:48.595349 | 2026-04-22 07:00:48.595507 | PLAY [all] 2026-04-22 07:00:48.606187 | 2026-04-22 07:00:48.606330 | TASK [Update apt cache] 2026-04-22 07:00:53.381175 | controller | ok 2026-04-22 07:00:53.386558 | 2026-04-22 07:00:53.386637 | TASK [Disable firewalld] 2026-04-22 07:00:53.412243 | controller | skipping: Conditional result was False 2026-04-22 07:00:53.420858 | 2026-04-22 07:00:53.420975 | TASK [Install "jq" for log collection] 2026-04-22 07:00:55.617273 | controller | changed 2026-04-22 07:00:55.619804 | 2026-04-22 07:00:55.619860 | PLAY RECAP 2026-04-22 07:00:55.619907 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-22 07:00:55.619929 | 2026-04-22 07:00:55.742567 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-04-22 07:00:55.745443 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-22 07:00:56.292739 | 2026-04-22 07:00:56.292879 | PLAY [all] 2026-04-22 07:00:56.302631 | 2026-04-22 07:00:56.302706 | TASK [Copy inventory file for Zuul] 2026-04-22 07:00:57.281488 | controller | changed 2026-04-22 07:00:57.286975 | 2026-04-22 07:00:57.287072 | TASK [Switch "ansible_host" to private IP] 2026-04-22 07:00:57.595703 | controller | changed: 1 replacements made 2026-04-22 07:00:57.600926 | 2026-04-22 07:00:57.601025 | TASK [Run Molecule scenario] 2026-04-22 07:00:58.039254 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-04-22 07:00:58.039365 | controller | Creating virtual environment at: .venv 2026-04-22 07:00:58.272116 | controller | Downloading ansible-core (2.3MiB) 2026-04-22 07:00:58.272323 | controller | Downloading cryptography (4.3MiB) 2026-04-22 07:00:58.272585 | controller | Downloading pygments (1.2MiB) 2026-04-22 07:00:58.441771 | controller | Downloading cryptography 2026-04-22 07:00:58.498977 | controller | Downloading pygments 2026-04-22 07:00:58.718754 | controller | Downloading ansible-core 2026-04-22 07:00:58.765559 | controller | Installed 36 packages in 45ms 2026-04-22 07:00:59.437730 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-04-22 07:00:59.437781 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-04-22 07:01:00.329299 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-04-22 07:01:00.329370 | controller | INFO default scenario not found, disabling shared state. 2026-04-22 07:01:00.329392 | controller | INFO [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-04-22 07:01:00.329458 | controller | INFO [helm > prerun] Performing prerun with role_name_check=0... 2026-04-22 07:02:04.647639 | controller | INFO [helm > dependency] Executing 2026-04-22 07:02:04.647701 | controller | WARNING [helm > dependency] Missing roles requirements file: requirements.yml 2026-04-22 07:02:04.647707 | controller | WARNING [helm > dependency] Missing collections requirements file: collections.yml 2026-04-22 07:02:04.647749 | controller | WARNING [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-04-22 07:02:04.652405 | controller | INFO [helm > cleanup] Executing 2026-04-22 07:02:04.652702 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-22 07:02:04.659012 | controller | INFO [helm > destroy] Executing 2026-04-22 07:02:04.659083 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-04-22 07:02:04.659166 | controller | INFO [helm > destroy] Executed: Successful 2026-04-22 07:02:04.666445 | controller | INFO [helm > syntax] Executing 2026-04-22 07:02:05.532201 | controller | 2026-04-22 07:02:05.532342 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml 2026-04-22 07:02:05.603365 | controller | INFO [helm > syntax] Executed: Successful 2026-04-22 07:02:05.615063 | controller | INFO [helm > create] Executing 2026-04-22 07:02:05.616592 | controller | WARNING [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-22 07:02:05.622004 | controller | INFO [helm > prepare] Executing 2026-04-22 07:02:06.464362 | controller | 2026-04-22 07:02:06.464424 | controller | PLAY [Prepare] ***************************************************************** 2026-04-22 07:02:06.464545 | controller | 2026-04-22 07:02:06.464666 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-22 07:02:06.464787 | controller | Wednesday 22 April 2026 07:02:06 +0000 (0:00:00.038) 0:00:00.038 ******* 2026-04-22 07:02:08.750449 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-04-22 07:02:08.756041 | controller | ok: [controller] 2026-04-22 07:02:08.756213 | controller | 2026-04-22 07:02:08.756410 | controller | TASK [Install Debian packages] ************************************************* 2026-04-22 07:02:08.756612 | controller | Wednesday 22 April 2026 07:02:08 +0000 (0:00:02.292) 0:00:02.331 ******* 2026-04-22 07:02:10.235962 | controller | ok: [controller] 2026-04-22 07:02:10.236066 | controller | 2026-04-22 07:02:10.236159 | controller | PLAY RECAP ********************************************************************* 2026-04-22 07:02:10.236401 | controller | controller : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-22 07:02:10.236680 | controller | 2026-04-22 07:02:10.236844 | controller | 2026-04-22 07:02:10.237038 | controller | TASKS RECAP ******************************************************************** 2026-04-22 07:02:10.237252 | controller | Wednesday 22 April 2026 07:02:10 +0000 (0:00:01.479) 0:00:03.811 ******* 2026-04-22 07:02:10.237428 | controller | =============================================================================== 2026-04-22 07:02:10.237614 | controller | Gathering Facts --------------------------------------------------------- 2.29s 2026-04-22 07:02:10.237797 | controller | Install Debian packages ------------------------------------------------- 1.48s 2026-04-22 07:02:10.367274 | controller | INFO [helm > prepare] Executed: Successful 2026-04-22 07:02:10.376154 | controller | INFO [helm > converge] Executing 2026-04-22 07:02:11.222708 | controller | 2026-04-22 07:02:11.222879 | controller | PLAY [Converge] **************************************************************** 2026-04-22 07:02:11.223130 | controller | 2026-04-22 07:02:11.223286 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-22 07:02:11.223488 | controller | Wednesday 22 April 2026 07:02:11 +0000 (0:00:00.013) 0:00:00.013 ******* 2026-04-22 07:02:12.306691 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-04-22 07:02:12.309323 | controller | ok: [controller] 2026-04-22 07:02:12.309493 | controller | 2026-04-22 07:02:12.309687 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-22 07:02:12.309931 | controller | Wednesday 22 April 2026 07:02:12 +0000 (0:00:01.086) 0:00:01.099 ******* 2026-04-22 07:02:12.817217 | controller | ok: [controller] 2026-04-22 07:02:12.817385 | controller | 2026-04-22 07:02:12.817609 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-22 07:02:12.817813 | controller | Wednesday 22 April 2026 07:02:12 +0000 (0:00:00.507) 0:00:01.607 ******* 2026-04-22 07:02:13.930566 | controller | ok: [controller] 2026-04-22 07:02:13.930806 | controller | 2026-04-22 07:02:13.931063 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-04-22 07:02:13.931403 | controller | Wednesday 22 April 2026 07:02:13 +0000 (0:00:01.113) 0:00:02.720 ******* 2026-04-22 07:02:14.374951 | controller | changed: [controller] 2026-04-22 07:02:14.375161 | controller | 2026-04-22 07:02:14.375452 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-22 07:02:14.375686 | controller | Wednesday 22 April 2026 07:02:14 +0000 (0:00:00.444) 0:00:03.164 ******* 2026-04-22 07:02:14.415285 | controller | ok: [controller] => { 2026-04-22 07:02:14.415450 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-04-22 07:02:14.415647 | controller | } 2026-04-22 07:02:14.415830 | controller | 2026-04-22 07:02:14.416035 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-22 07:02:14.416234 | controller | Wednesday 22 April 2026 07:02:14 +0000 (0:00:00.040) 0:00:03.205 ******* 2026-04-22 07:02:15.358726 | controller | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created with a mode of 0700, this may cause issues when running as another user. To avoid this, create the remote_tmp dir with the correct permissions manually 2026-04-22 07:02:15.368067 | controller | changed: [controller] 2026-04-22 07:02:15.368244 | controller | 2026-04-22 07:02:15.368478 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-22 07:02:15.368672 | controller | Wednesday 22 April 2026 07:02:15 +0000 (0:00:00.953) 0:00:04.158 ******* 2026-04-22 07:02:18.426280 | controller | changed: [controller] 2026-04-22 07:02:18.426330 | controller | 2026-04-22 07:02:18.426391 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-04-22 07:02:18.426519 | controller | Wednesday 22 April 2026 07:02:18 +0000 (0:00:03.054) 0:00:07.213 ******* 2026-04-22 07:02:18.944022 | controller | ok: [controller] 2026-04-22 07:02:18.944107 | controller | 2026-04-22 07:02:18.944264 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-04-22 07:02:18.944415 | controller | Wednesday 22 April 2026 07:02:18 +0000 (0:00:00.520) 0:00:07.734 ******* 2026-04-22 07:02:19.367183 | controller | changed: [controller] 2026-04-22 07:02:19.367240 | controller | 2026-04-22 07:02:19.367412 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-04-22 07:02:19.367599 | controller | Wednesday 22 April 2026 07:02:19 +0000 (0:00:00.421) 0:00:08.155 ******* 2026-04-22 07:02:19.823453 | controller | ok: [controller] 2026-04-22 07:02:19.823518 | controller | 2026-04-22 07:02:19.823531 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-04-22 07:02:19.823647 | controller | Wednesday 22 April 2026 07:02:19 +0000 (0:00:00.458) 0:00:08.613 ******* 2026-04-22 07:02:20.136466 | controller | changed: [controller] 2026-04-22 07:02:20.136516 | controller | 2026-04-22 07:02:20.136687 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-04-22 07:02:20.136817 | controller | Wednesday 22 April 2026 07:02:20 +0000 (0:00:00.312) 0:00:08.926 ******* 2026-04-22 07:02:20.448131 | controller | ok: [controller] 2026-04-22 07:02:20.448169 | controller | 2026-04-22 07:02:20.448179 | controller | TASK [Install plugin] ********************************************************** 2026-04-22 07:02:20.448187 | controller | Wednesday 22 April 2026 07:02:20 +0000 (0:00:00.307) 0:00:09.234 ******* 2026-04-22 07:02:20.500697 | controller | included: vexxhost.containers.download_artifact for controller 2026-04-22 07:02:20.500996 | controller | 2026-04-22 07:02:20.501293 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-22 07:02:20.501562 | controller | Wednesday 22 April 2026 07:02:20 +0000 (0:00:00.056) 0:00:09.291 ******* 2026-04-22 07:02:20.551880 | controller | ok: [controller] => { 2026-04-22 07:02:20.552413 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-04-22 07:02:20.552850 | controller | } 2026-04-22 07:02:20.553177 | controller | 2026-04-22 07:02:20.553448 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-22 07:02:20.553706 | controller | Wednesday 22 April 2026 07:02:20 +0000 (0:00:00.050) 0:00:09.342 ******* 2026-04-22 07:02:21.506672 | controller | changed: [controller] 2026-04-22 07:02:21.506722 | controller | 2026-04-22 07:02:21.506732 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-22 07:02:21.506740 | controller | Wednesday 22 April 2026 07:02:21 +0000 (0:00:00.953) 0:00:10.295 ******* 2026-04-22 07:02:23.567493 | controller | changed: [controller] 2026-04-22 07:02:23.570769 | controller | 2026-04-22 07:02:23.570796 | controller | PLAY RECAP ********************************************************************* 2026-04-22 07:02:23.570807 | controller | controller : ok=16 changed=7 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-22 07:02:23.570815 | controller | 2026-04-22 07:02:23.570823 | controller | 2026-04-22 07:02:23.570831 | controller | TASKS RECAP ******************************************************************** 2026-04-22 07:02:23.570839 | controller | Wednesday 22 April 2026 07:02:23 +0000 (0:00:02.060) 0:00:12.355 ******* 2026-04-22 07:02:23.570847 | controller | =============================================================================== 2026-04-22 07:02:23.570855 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 3.05s 2026-04-22 07:02:23.570862 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.06s 2026-04-22 07:02:23.570870 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.11s 2026-04-22 07:02:23.570878 | controller | Gathering Facts --------------------------------------------------------- 1.09s 2026-04-22 07:02:23.570885 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.95s 2026-04-22 07:02:23.570893 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.95s 2026-04-22 07:02:23.570901 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.52s 2026-04-22 07:02:23.571171 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.51s 2026-04-22 07:02:23.571461 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.46s 2026-04-22 07:02:23.571721 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.44s 2026-04-22 07:02:23.571848 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.42s 2026-04-22 07:02:23.571965 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.31s 2026-04-22 07:02:23.572082 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.31s 2026-04-22 07:02:23.572192 | controller | Install plugin ---------------------------------------------------------- 0.06s 2026-04-22 07:02:23.572304 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-04-22 07:02:23.572416 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-04-22 07:02:23.674397 | controller | INFO [helm > converge] Executed: Successful 2026-04-22 07:02:23.683851 | controller | INFO [helm > idempotence] Executing 2026-04-22 07:02:24.565469 | controller | 2026-04-22 07:02:24.565685 | controller | PLAY [Converge] **************************************************************** 2026-04-22 07:02:24.565873 | controller | 2026-04-22 07:02:24.566064 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-22 07:02:24.566238 | controller | Wednesday 22 April 2026 07:02:24 +0000 (0:00:00.013) 0:00:00.013 ******* 2026-04-22 07:02:25.607692 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-04-22 07:02:25.613131 | controller | ok: [controller] 2026-04-22 07:02:25.613390 | controller | 2026-04-22 07:02:25.613733 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-22 07:02:25.613941 | controller | Wednesday 22 April 2026 07:02:25 +0000 (0:00:01.047) 0:00:01.061 ******* 2026-04-22 07:02:26.033815 | controller | ok: [controller] 2026-04-22 07:02:26.034129 | controller | 2026-04-22 07:02:26.034428 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-22 07:02:26.034749 | controller | Wednesday 22 April 2026 07:02:26 +0000 (0:00:00.420) 0:00:01.481 ******* 2026-04-22 07:02:27.045421 | controller | ok: [controller] 2026-04-22 07:02:27.045702 | controller | 2026-04-22 07:02:27.045966 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-04-22 07:02:27.046218 | controller | Wednesday 22 April 2026 07:02:27 +0000 (0:00:01.011) 0:00:02.493 ******* 2026-04-22 07:02:27.474310 | controller | ok: [controller] 2026-04-22 07:02:27.474515 | controller | 2026-04-22 07:02:27.474825 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-22 07:02:27.475021 | controller | Wednesday 22 April 2026 07:02:27 +0000 (0:00:00.428) 0:00:02.922 ******* 2026-04-22 07:02:27.514602 | controller | ok: [controller] => { 2026-04-22 07:02:27.514978 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-04-22 07:02:27.515238 | controller | } 2026-04-22 07:02:27.515482 | controller | 2026-04-22 07:02:27.515770 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-22 07:02:27.516027 | controller | Wednesday 22 April 2026 07:02:27 +0000 (0:00:00.040) 0:00:02.962 ******* 2026-04-22 07:02:28.037156 | controller | ok: [controller] 2026-04-22 07:02:28.037538 | controller | 2026-04-22 07:02:28.037939 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-22 07:02:28.038395 | controller | Wednesday 22 April 2026 07:02:28 +0000 (0:00:00.522) 0:00:03.484 ******* 2026-04-22 07:02:29.706380 | controller | ok: [controller] 2026-04-22 07:02:29.706661 | controller | 2026-04-22 07:02:29.707037 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-04-22 07:02:29.707301 | controller | Wednesday 22 April 2026 07:02:29 +0000 (0:00:01.669) 0:00:05.154 ******* 2026-04-22 07:02:30.215038 | controller | ok: [controller] 2026-04-22 07:02:30.215068 | controller | 2026-04-22 07:02:30.215135 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-04-22 07:02:30.215548 | controller | Wednesday 22 April 2026 07:02:30 +0000 (0:00:00.505) 0:00:05.660 ******* 2026-04-22 07:02:30.604216 | controller | ok: [controller] 2026-04-22 07:02:30.604263 | controller | 2026-04-22 07:02:30.604344 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-04-22 07:02:30.604436 | controller | Wednesday 22 April 2026 07:02:30 +0000 (0:00:00.392) 0:00:06.052 ******* 2026-04-22 07:02:31.040186 | controller | ok: [controller] 2026-04-22 07:02:31.040300 | controller | 2026-04-22 07:02:31.040311 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-04-22 07:02:31.040325 | controller | Wednesday 22 April 2026 07:02:31 +0000 (0:00:00.435) 0:00:06.488 ******* 2026-04-22 07:02:31.326356 | controller | ok: [controller] 2026-04-22 07:02:31.326428 | controller | 2026-04-22 07:02:31.326610 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-04-22 07:02:31.326819 | controller | Wednesday 22 April 2026 07:02:31 +0000 (0:00:00.285) 0:00:06.774 ******* 2026-04-22 07:02:31.618779 | controller | ok: [controller] 2026-04-22 07:02:31.618814 | controller | 2026-04-22 07:02:31.618821 | controller | TASK [Install plugin] ********************************************************** 2026-04-22 07:02:31.618921 | controller | Wednesday 22 April 2026 07:02:31 +0000 (0:00:00.289) 0:00:07.063 ******* 2026-04-22 07:02:31.670311 | controller | included: vexxhost.containers.download_artifact for controller 2026-04-22 07:02:31.670368 | controller | 2026-04-22 07:02:31.670439 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-22 07:02:31.670533 | controller | Wednesday 22 April 2026 07:02:31 +0000 (0:00:00.054) 0:00:07.118 ******* 2026-04-22 07:02:31.718819 | controller | ok: [controller] => { 2026-04-22 07:02:31.718892 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-04-22 07:02:31.718989 | controller | } 2026-04-22 07:02:31.719055 | controller | 2026-04-22 07:02:31.719156 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-22 07:02:31.719245 | controller | Wednesday 22 April 2026 07:02:31 +0000 (0:00:00.048) 0:00:07.166 ******* 2026-04-22 07:02:32.167285 | controller | ok: [controller] 2026-04-22 07:02:32.167381 | controller | 2026-04-22 07:02:32.167396 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-22 07:02:32.167557 | controller | Wednesday 22 April 2026 07:02:32 +0000 (0:00:00.446) 0:00:07.613 ******* 2026-04-22 07:02:33.814311 | controller | ok: [controller] 2026-04-22 07:02:33.814352 | controller | 2026-04-22 07:02:33.814362 | controller | PLAY RECAP ********************************************************************* 2026-04-22 07:02:33.814370 | controller | controller : ok=16 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-22 07:02:33.814377 | controller | 2026-04-22 07:02:33.814385 | controller | 2026-04-22 07:02:33.814392 | controller | TASKS RECAP ******************************************************************** 2026-04-22 07:02:33.814399 | controller | Wednesday 22 April 2026 07:02:33 +0000 (0:00:01.644) 0:00:09.257 ******* 2026-04-22 07:02:33.814406 | controller | =============================================================================== 2026-04-22 07:02:33.814413 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.67s 2026-04-22 07:02:33.814421 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.64s 2026-04-22 07:02:33.814428 | controller | Gathering Facts --------------------------------------------------------- 1.05s 2026-04-22 07:02:33.814435 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.01s 2026-04-22 07:02:33.814442 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.52s 2026-04-22 07:02:33.814449 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.51s 2026-04-22 07:02:33.814455 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.45s 2026-04-22 07:02:33.814462 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.44s 2026-04-22 07:02:33.814471 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.43s 2026-04-22 07:02:33.814478 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.42s 2026-04-22 07:02:33.814514 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.39s 2026-04-22 07:02:33.814521 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.29s 2026-04-22 07:02:33.814528 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.29s 2026-04-22 07:02:33.814535 | controller | Install plugin ---------------------------------------------------------- 0.05s 2026-04-22 07:02:33.814543 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-04-22 07:02:33.814550 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-04-22 07:02:33.896947 | controller | INFO [helm > idempotence] Executed: Successful 2026-04-22 07:02:33.904738 | controller | INFO [helm > side_effect] Executing 2026-04-22 07:02:33.905214 | controller | WARNING [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-22 07:02:33.911547 | controller | INFO [helm > verify] Executing 2026-04-22 07:02:34.704730 | controller | 2026-04-22 07:02:34.705041 | controller | PLAY [Verify] ****************************************************************** 2026-04-22 07:02:34.705296 | controller | 2026-04-22 07:02:34.705572 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-22 07:02:34.705826 | controller | Wednesday 22 April 2026 07:02:34 +0000 (0:00:00.009) 0:00:00.009 ******* 2026-04-22 07:02:35.758812 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-04-22 07:02:35.765522 | controller | ok: [controller] 2026-04-22 07:02:35.765532 | controller | 2026-04-22 07:02:35.765536 | controller | TASK [Get the current Helm version] ******************************************** 2026-04-22 07:02:35.765540 | controller | Wednesday 22 April 2026 07:02:35 +0000 (0:00:01.060) 0:00:01.070 ******* 2026-04-22 07:02:36.193330 | controller | changed: [controller] 2026-04-22 07:02:36.193608 | controller | 2026-04-22 07:02:36.193863 | controller | TASK [Assert Helm version] ***************************************************** 2026-04-22 07:02:36.194115 | controller | Wednesday 22 April 2026 07:02:36 +0000 (0:00:00.427) 0:00:01.497 ******* 2026-04-22 07:02:36.218188 | controller | ok: [controller] => { 2026-04-22 07:02:36.218528 | controller | "changed": false, 2026-04-22 07:02:36.218855 | controller | "msg": "All assertions passed" 2026-04-22 07:02:36.219100 | controller | } 2026-04-22 07:02:36.219327 | controller | 2026-04-22 07:02:36.219567 | controller | PLAY RECAP ********************************************************************* 2026-04-22 07:02:36.219834 | controller | controller : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-22 07:02:36.220058 | controller | 2026-04-22 07:02:36.220282 | controller | 2026-04-22 07:02:36.220520 | controller | TASKS RECAP ******************************************************************** 2026-04-22 07:02:36.220768 | controller | Wednesday 22 April 2026 07:02:36 +0000 (0:00:00.025) 0:00:01.523 ******* 2026-04-22 07:02:36.221001 | controller | =============================================================================== 2026-04-22 07:02:36.221242 | controller | Gathering Facts --------------------------------------------------------- 1.06s 2026-04-22 07:02:36.221485 | controller | Get the current Helm version -------------------------------------------- 0.43s 2026-04-22 07:02:36.221724 | controller | Assert Helm version ----------------------------------------------------- 0.03s 2026-04-22 07:02:36.339099 | controller | INFO [helm > verify] Executed: Successful 2026-04-22 07:02:36.349770 | controller | INFO [helm > cleanup] Executing 2026-04-22 07:02:36.350102 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-22 07:02:36.355866 | controller | INFO [helm > destroy] Executing 2026-04-22 07:02:36.355973 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-04-22 07:02:36.356061 | controller | INFO [helm > destroy] Executed: Successful 2026-04-22 07:02:36.356362 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-04-22 07:02:36.729278 | controller | ok: Runtime: 0:01:38.479611 2026-04-22 07:02:36.735695 | 2026-04-22 07:02:36.735761 | PLAY RECAP 2026-04-22 07:02:36.735816 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-22 07:02:36.735845 | 2026-04-22 07:02:36.852349 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-22 07:02:36.855198 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-04-22 07:02:37.408491 | 2026-04-22 07:02:37.408619 | PLAY [all] 2026-04-22 07:02:37.419182 | 2026-04-22 07:02:37.419258 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-04-22 07:02:37.788688 | controller | changed 2026-04-22 07:02:37.793294 | 2026-04-22 07:02:37.793374 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-04-22 07:02:38.171635 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-22 07:02:38.171860 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-22 07:02:38.178068 | controller | environment: line 1: kubectl: command not found 2026-04-22 07:02:38.180182 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-22 07:02:38.180974 | controller | environment: line 1: kubectl: command not found 2026-04-22 07:02:38.183002 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-22 07:02:38.185564 | controller | environment: line 1: kubectl: command not found 2026-04-22 07:02:38.187959 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-22 07:02:38.187996 | controller | environment: line 1: kubectl: command not found 2026-04-22 07:02:38.189113 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-22 07:02:38.190863 | controller | environment: line 1: kubectl: command not found 2026-04-22 07:02:38.192089 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-22 07:02:38.323236 | controller | ok: Runtime: 0:00:00.034078 2026-04-22 07:02:38.328226 | 2026-04-22 07:02:38.328292 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-04-22 07:02:38.554245 | controller | changed 2026-04-22 07:02:38.559572 | 2026-04-22 07:02:38.559641 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-04-22 07:02:38.804247 | controller | environment: line 5: kubectl: command not found 2026-04-22 07:02:38.804956 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-22 07:02:38.805451 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-22 07:02:38.805631 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-22 07:02:39.088453 | controller | ok: Runtime: 0:00:00.014062 2026-04-22 07:02:39.095787 | 2026-04-22 07:02:39.095853 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-04-22 07:02:39.777989 | controller | changed: 2026-04-22 07:02:39.778186 | controller | created directory /var/lib/zuul/builds/a9a1def6c1c34bd6999a8ed296619854/work/logs/controller 2026-04-22 07:02:39.778229 | controller | cd+++++++++ objects/ 2026-04-22 07:02:39.778261 | controller | cd+++++++++ objects/cluster/ 2026-04-22 07:02:39.778294 | controller | cd+++++++++ objects/namespaced/ 2026-04-22 07:02:39.787911 | 2026-04-22 07:02:39.788007 | TASK [gather-pod-logs : creating directory for pod logs] 2026-04-22 07:02:40.012469 | controller | changed 2026-04-22 07:02:40.018420 | 2026-04-22 07:02:40.018483 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-04-22 07:02:40.253509 | controller | changed 2026-04-22 07:02:40.259393 | 2026-04-22 07:02:40.259622 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-04-22 07:02:40.495341 | controller | environment: line 3: kubectl: command not found 2026-04-22 07:02:40.792653 | controller | ok: Runtime: 0:00:00.012077 2026-04-22 07:02:40.798942 | 2026-04-22 07:02:40.799008 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-04-22 07:02:41.328680 | controller | changed: 2026-04-22 07:02:41.328980 | controller | cd+++++++++ pod-logs/ 2026-04-22 07:02:41.329055 | controller | cd+++++++++ pod-logs/failed-pods/ 2026-04-22 07:02:41.338259 | 2026-04-22 07:02:41.338324 | LOOP [helm-release-status : creating directory for helm release status] 2026-04-22 07:02:41.563192 | controller | changed: "values" 2026-04-22 07:02:41.767267 | controller | changed: "releases" 2026-04-22 07:02:41.783245 | 2026-04-22 07:02:41.783447 | TASK [helm-release-status : Gather get release status for helm charts] 2026-04-22 07:02:42.027056 | controller | /bin/bash: line 3: kubectl: command not found 2026-04-22 07:02:42.318310 | controller | ok: Runtime: 0:00:00.008632 2026-04-22 07:02:42.323496 | 2026-04-22 07:02:42.323580 | TASK [helm-release-status : Downloads logs to executor] 2026-04-22 07:02:42.872976 | controller | changed: 2026-04-22 07:02:42.873351 | controller | cd+++++++++ helm/ 2026-04-22 07:02:42.873427 | controller | cd+++++++++ helm/releases/ 2026-04-22 07:02:42.873480 | controller | cd+++++++++ helm/values/ 2026-04-22 07:02:42.879683 | 2026-04-22 07:02:42.879734 | PLAY RECAP 2026-04-22 07:02:42.879776 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-22 07:02:42.879796 | 2026-04-22 07:02:43.003972 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-04-22 07:02:43.008094 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-22 07:02:43.598013 | 2026-04-22 07:02:43.598515 | PLAY [all] 2026-04-22 07:02:43.609257 | 2026-04-22 07:02:43.609354 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-22 07:02:43.655685 | controller | skipping: Conditional result was False 2026-04-22 07:02:43.665223 | 2026-04-22 07:02:43.665337 | TASK [fetch-output : Set log path for single node] 2026-04-22 07:02:43.708454 | controller | ok 2026-04-22 07:02:43.712106 | 2026-04-22 07:02:43.712178 | LOOP [fetch-output : Ensure local output dirs] 2026-04-22 07:02:44.127108 | controller -> localhost | ok: "/var/lib/zuul/builds/a9a1def6c1c34bd6999a8ed296619854/work/logs" 2026-04-22 07:02:44.342128 | controller -> localhost | changed: "/var/lib/zuul/builds/a9a1def6c1c34bd6999a8ed296619854/work/artifacts" 2026-04-22 07:02:44.554415 | controller -> localhost | changed: "/var/lib/zuul/builds/a9a1def6c1c34bd6999a8ed296619854/work/docs" 2026-04-22 07:02:44.570135 | 2026-04-22 07:02:44.570271 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-22 07:02:45.234192 | controller | changed: .d..t...... ./ 2026-04-22 07:02:45.234493 | controller | changed: All items complete 2026-04-22 07:02:45.234523 | 2026-04-22 07:02:45.720752 | controller | changed: .d..t...... ./ 2026-04-22 07:02:46.213603 | controller | changed: .d..t...... ./ 2026-04-22 07:02:46.237323 | 2026-04-22 07:02:46.237482 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-22 07:02:46.655421 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008809 2026-04-22 07:02:46.888426 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008833 2026-04-22 07:02:46.909783 | 2026-04-22 07:02:46.909930 | PLAY [all] 2026-04-22 07:02:46.917369 | 2026-04-22 07:02:46.917434 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-22 07:02:47.366316 | controller | changed 2026-04-22 07:02:47.371520 | 2026-04-22 07:02:47.371570 | PLAY RECAP 2026-04-22 07:02:47.371613 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-22 07:02:47.371634 | 2026-04-22 07:02:47.486280 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-22 07:02:47.488856 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-22 07:02:48.024072 | 2026-04-22 07:02:48.024199 | PLAY [localhost] 2026-04-22 07:02:48.034001 | 2026-04-22 07:02:48.034072 | TASK [Generate Zuul manifest] 2026-04-22 07:02:48.056310 | localhost | ok 2026-04-22 07:02:48.073651 | 2026-04-22 07:02:48.073725 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-22 07:02:48.399471 | localhost | changed 2026-04-22 07:02:48.409421 | 2026-04-22 07:02:48.409516 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-22 07:02:48.440716 | localhost | ok 2026-04-22 07:02:48.449509 | 2026-04-22 07:02:48.449598 | TASK [Upload logs] 2026-04-22 07:02:48.470431 | localhost | ok 2026-04-22 07:02:48.531491 | 2026-04-22 07:02:48.531627 | TASK [Set zuul-log-path fact] 2026-04-22 07:02:48.553145 | localhost | ok 2026-04-22 07:02:48.567796 | 2026-04-22 07:02:48.567864 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-22 07:02:48.600540 | localhost | ok 2026-04-22 07:02:48.610817 | 2026-04-22 07:02:48.610883 | TASK [upload-logs : Create log directories] 2026-04-22 07:02:49.016631 | localhost | changed 2026-04-22 07:02:49.024389 | 2026-04-22 07:02:49.024486 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-22 07:02:49.413120 | localhost -> localhost | ok: Runtime: 0:00:00.003763 2026-04-22 07:02:49.419565 | 2026-04-22 07:02:49.419631 | TASK [upload-logs : Upload logs to log server] 2026-04-22 07:02:49.876172 | localhost | Output suppressed because no_log was given 2026-04-22 07:02:49.881214 | 2026-04-22 07:02:49.881311 | LOOP [upload-logs : Compress console log and json output] 2026-04-22 07:02:49.927330 | localhost | skipping: Conditional result was False 2026-04-22 07:02:49.934683 | localhost | skipping: Conditional result was False 2026-04-22 07:02:49.946829 | 2026-04-22 07:02:49.946925 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-22 07:02:49.991398 | localhost | skipping: Conditional result was False 2026-04-22 07:02:49.991768 | 2026-04-22 07:02:49.995724 | localhost | skipping: Conditional result was False 2026-04-22 07:02:50.009977 | 2026-04-22 07:02:50.010125 | LOOP [upload-logs : Upload console log and json output]