2026-04-01 10:10:20.452856 | Job console starting 2026-04-01 10:10:20.472183 | Updating git repos 2026-04-01 10:10:20.566304 | Cloning repos into workspace 2026-04-01 10:10:21.531621 | Restoring repo states 2026-04-01 10:10:21.545170 | Merging changes 2026-04-01 10:10:22.536101 | Checking out repos 2026-04-01 10:10:26.935030 | Preparing playbooks 2026-04-01 10:10:47.297756 | Running Ansible setup 2026-04-01 10:10:51.050661 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-01 10:10:51.646568 | 2026-04-01 10:10:51.646703 | PLAY [localhost] 2026-04-01 10:10:51.654414 | 2026-04-01 10:10:51.654501 | TASK [Gathering Facts] 2026-04-01 10:10:52.742019 | localhost | ok 2026-04-01 10:10:52.752379 | 2026-04-01 10:10:52.752507 | TASK [Setup log path fact] 2026-04-01 10:10:52.769436 | localhost | ok 2026-04-01 10:10:52.780110 | 2026-04-01 10:10:52.780198 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-01 10:10:52.810246 | localhost | ok 2026-04-01 10:10:52.819851 | 2026-04-01 10:10:52.819983 | TASK [emit-job-header : Print job information] 2026-04-01 10:10:52.871357 | # Job Information 2026-04-01 10:10:52.871700 | Ansible Version: 2.16.16 2026-04-01 10:10:52.871800 | Job: ansible-collection-kubernetes-molecule-helm 2026-04-01 10:10:52.871859 | Pipeline: check 2026-04-01 10:10:52.871909 | Executor: 0a8996d2b663 2026-04-01 10:10:52.871956 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/258 2026-04-01 10:10:52.872007 | Event ID: 7651ffb0-2db2-11f1-942e-495b031d892c 2026-04-01 10:10:52.875351 | 2026-04-01 10:10:52.875499 | LOOP [emit-job-header : Print node information] 2026-04-01 10:10:53.238076 | localhost | ok: 2026-04-01 10:10:53.238350 | localhost | # Node Information 2026-04-01 10:10:53.238419 | localhost | Inventory Hostname: controller 2026-04-01 10:10:53.238465 | localhost | Hostname: np0000162093 2026-04-01 10:10:53.238507 | localhost | Username: zuul 2026-04-01 10:10:53.238621 | localhost | Distro: Debian 13.4 2026-04-01 10:10:53.238675 | localhost | Provider: yul1 2026-04-01 10:10:53.238714 | localhost | Region: ca-ymq-1 2026-04-01 10:10:53.238750 | localhost | Label: debian-trixie 2026-04-01 10:10:53.238778 | localhost | Product Name: OpenStack Nova 2026-04-01 10:10:53.238804 | localhost | Interface IP: 199.204.45.109 2026-04-01 10:10:53.257492 | 2026-04-01 10:10:53.257674 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-01 10:10:53.928424 | localhost -> localhost | changed 2026-04-01 10:10:53.937991 | 2026-04-01 10:10:53.938086 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-01 10:10:55.153999 | localhost -> localhost | changed 2026-04-01 10:10:55.162455 | 2026-04-01 10:10:55.162541 | PLAY [all] 2026-04-01 10:10:55.169311 | 2026-04-01 10:10:55.169389 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-01 10:10:55.387054 | controller -> localhost | ok 2026-04-01 10:10:55.393760 | 2026-04-01 10:10:55.393887 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-01 10:10:55.426537 | controller | ok 2026-04-01 10:10:55.442530 | controller | included: /var/lib/zuul/builds/0f162a75529c4016a1cf2fb1d6901421/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-01 10:10:55.447452 | 2026-04-01 10:10:55.447569 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-01 10:10:56.190626 | controller -> localhost | Generating public/private rsa key pair. 2026-04-01 10:10:56.190791 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/0f162a75529c4016a1cf2fb1d6901421/work/0f162a75529c4016a1cf2fb1d6901421_id_rsa 2026-04-01 10:10:56.190833 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/0f162a75529c4016a1cf2fb1d6901421/work/0f162a75529c4016a1cf2fb1d6901421_id_rsa.pub 2026-04-01 10:10:56.190865 | controller -> localhost | The key fingerprint is: 2026-04-01 10:10:56.190895 | controller -> localhost | SHA256:1d7CYg4L1BM2kmtLOoKvRYQUc+ABOU0tCVCoOhAdd3w zuul-build-sshkey 2026-04-01 10:10:56.190934 | controller -> localhost | The key's randomart image is: 2026-04-01 10:10:56.190964 | controller -> localhost | +---[RSA 3072]----+ 2026-04-01 10:10:56.190999 | controller -> localhost | |*#*=..o.+ | 2026-04-01 10:10:56.191029 | controller -> localhost | |B.O...o+Eo . | 2026-04-01 10:10:56.191058 | controller -> localhost | |o+.. .oo . . | 2026-04-01 10:10:56.191086 | controller -> localhost | |o. .+ o o . | 2026-04-01 10:10:56.191113 | controller -> localhost | |o.. +..S o + . | 2026-04-01 10:10:56.191139 | controller -> localhost | |+.. o .. = . . | 2026-04-01 10:10:56.191166 | controller -> localhost | | o.. . . . | 2026-04-01 10:10:56.191193 | controller -> localhost | | .. | 2026-04-01 10:10:56.191224 | controller -> localhost | |.. | 2026-04-01 10:10:56.191251 | controller -> localhost | +----[SHA256]-----+ 2026-04-01 10:10:56.191308 | controller -> localhost | ok: Runtime: 0:00:00.248281 2026-04-01 10:10:56.197082 | 2026-04-01 10:10:56.197214 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-01 10:10:56.227187 | controller | ok 2026-04-01 10:10:56.236289 | controller | included: /var/lib/zuul/builds/0f162a75529c4016a1cf2fb1d6901421/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-01 10:10:56.243327 | 2026-04-01 10:10:56.243389 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-01 10:10:56.270891 | controller | skipping: Conditional result was False 2026-04-01 10:10:56.277923 | 2026-04-01 10:10:56.278035 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-01 10:10:56.816491 | controller | changed 2026-04-01 10:10:56.820992 | 2026-04-01 10:10:56.821063 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-01 10:10:57.045318 | controller | ok 2026-04-01 10:10:57.050380 | 2026-04-01 10:10:57.050441 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-01 10:10:57.602596 | controller | changed 2026-04-01 10:10:57.608777 | 2026-04-01 10:10:57.608911 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-01 10:10:58.131405 | controller | changed 2026-04-01 10:10:58.617082 | 2026-04-01 10:10:58.617204 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-01 10:10:58.640850 | controller | skipping: Conditional result was False 2026-04-01 10:10:58.649186 | 2026-04-01 10:10:58.649286 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-01 10:10:59.069133 | controller -> localhost | changed 2026-04-01 10:10:59.079502 | 2026-04-01 10:10:59.079657 | TASK [add-build-sshkey : Add back temp key] 2026-04-01 10:10:59.339847 | controller -> localhost | Identity added: /var/lib/zuul/builds/0f162a75529c4016a1cf2fb1d6901421/work/0f162a75529c4016a1cf2fb1d6901421_id_rsa (zuul-build-sshkey) 2026-04-01 10:10:59.340041 | controller -> localhost | ok: Runtime: 0:00:00.012005 2026-04-01 10:10:59.343862 | 2026-04-01 10:10:59.343923 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-01 10:10:59.690817 | controller | ok 2026-04-01 10:10:59.698692 | 2026-04-01 10:10:59.698902 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-01 10:10:59.725315 | controller | skipping: Conditional result was False 2026-04-01 10:10:59.743894 | 2026-04-01 10:10:59.744094 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-01 10:11:00.102469 | controller | ok 2026-04-01 10:11:00.107602 | 2026-04-01 10:11:00.107686 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-01 10:11:01.156477 | controller | Output suppressed because no_log was given 2026-04-01 10:11:01.166794 | 2026-04-01 10:11:01.166911 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-01 10:11:01.394073 | controller | ok: "logs" 2026-04-01 10:11:01.394434 | controller | ok: All items complete 2026-04-01 10:11:01.394477 | 2026-04-01 10:11:01.558252 | controller | ok: "artifacts" 2026-04-01 10:11:01.752915 | controller | ok: "docs" 2026-04-01 10:11:01.765270 | 2026-04-01 10:11:01.833908 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-01 10:11:02.064602 | controller | changed: "logs" 2026-04-01 10:11:02.231263 | controller | changed: "artifacts" 2026-04-01 10:11:02.404432 | controller | changed: "docs" 2026-04-01 10:11:02.425682 | 2026-04-01 10:11:02.425876 | PLAY RECAP 2026-04-01 10:11:02.425939 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-01 10:11:02.425980 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-01 10:11:02.426011 | 2026-04-01 10:11:02.527773 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-01 10:11:02.530726 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-01 10:11:03.119393 | 2026-04-01 10:11:03.119796 | PLAY [all] 2026-04-01 10:11:03.130487 | 2026-04-01 10:11:03.130582 | TASK [setup-uv : Extract archive] 2026-04-01 10:11:06.622667 | controller | changed 2026-04-01 10:11:06.629373 | 2026-04-01 10:11:06.629534 | TASK [setup-uv : Print version] 2026-04-01 10:11:07.030249 | controller | uv 0.8.13 2026-04-01 10:11:07.161665 | controller | ok: Runtime: 0:00:00.013558 2026-04-01 10:11:07.169164 | 2026-04-01 10:11:07.169247 | PLAY RECAP 2026-04-01 10:11:07.169315 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-01 10:11:07.169351 | 2026-04-01 10:11:07.268411 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-01 10:11:07.270549 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-04-01 10:11:08.062453 | 2026-04-01 10:11:08.062635 | PLAY [all] 2026-04-01 10:11:08.073777 | 2026-04-01 10:11:08.073897 | TASK [Update apt cache] 2026-04-01 10:11:12.807890 | controller | ok 2026-04-01 10:11:12.812984 | 2026-04-01 10:11:12.813078 | TASK [Disable firewalld] 2026-04-01 10:11:12.837806 | controller | skipping: Conditional result was False 2026-04-01 10:11:12.844922 | 2026-04-01 10:11:12.845075 | TASK [Install "jq" for log collection] 2026-04-01 10:11:15.619184 | controller | changed 2026-04-01 10:11:15.621470 | 2026-04-01 10:11:15.621518 | PLAY RECAP 2026-04-01 10:11:15.621565 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-01 10:11:15.621587 | 2026-04-01 10:11:15.722034 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-04-01 10:11:15.724520 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-01 10:11:16.308115 | 2026-04-01 10:11:16.308243 | PLAY [all] 2026-04-01 10:11:16.322689 | 2026-04-01 10:11:16.322809 | TASK [Copy inventory file for Zuul] 2026-04-01 10:11:17.351995 | controller | changed 2026-04-01 10:11:17.360091 | 2026-04-01 10:11:17.360201 | TASK [Switch "ansible_host" to private IP] 2026-04-01 10:11:17.663911 | controller | changed: 1 replacements made 2026-04-01 10:11:17.669626 | 2026-04-01 10:11:17.669717 | TASK [Run Molecule scenario] 2026-04-01 10:11:18.167283 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-04-01 10:11:18.167419 | controller | Creating virtual environment at: .venv 2026-04-01 10:11:18.446858 | controller | Downloading pygments (1.2MiB) 2026-04-01 10:11:18.447799 | controller | Downloading cryptography (4.3MiB) 2026-04-01 10:11:18.448009 | controller | Downloading ansible-core (2.3MiB) 2026-04-01 10:11:18.601332 | controller | Downloading cryptography 2026-04-01 10:11:18.654173 | controller | Downloading pygments 2026-04-01 10:11:18.831711 | controller | Downloading ansible-core 2026-04-01 10:11:18.880568 | controller | Installed 36 packages in 48ms 2026-04-01 10:11:19.570928 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-04-01 10:11:19.571003 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-04-01 10:11:20.518353 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-04-01 10:11:20.518446 | controller | INFO default scenario not found, disabling shared state. 2026-04-01 10:11:20.518476 | controller | INFO [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-04-01 10:11:20.518495 | controller | INFO [helm > prerun] Performing prerun with role_name_check=0... 2026-04-01 10:11:38.231010 | controller | INFO [helm > dependency] Executing 2026-04-01 10:11:38.231134 | controller | WARNING [helm > dependency] Missing roles requirements file: requirements.yml 2026-04-01 10:11:38.231449 | controller | WARNING [helm > dependency] Missing collections requirements file: collections.yml 2026-04-01 10:11:38.231609 | controller | WARNING [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-04-01 10:11:38.238672 | controller | INFO [helm > cleanup] Executing 2026-04-01 10:11:38.238993 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-01 10:11:38.244560 | controller | INFO [helm > destroy] Executing 2026-04-01 10:11:38.244588 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-04-01 10:11:38.244631 | controller | INFO [helm > destroy] Executed: Successful 2026-04-01 10:11:38.250295 | controller | INFO [helm > syntax] Executing 2026-04-01 10:11:39.181344 | controller | 2026-04-01 10:11:39.181450 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml 2026-04-01 10:11:39.252978 | controller | INFO [helm > syntax] Executed: Successful 2026-04-01 10:11:39.262808 | controller | INFO [helm > create] Executing 2026-04-01 10:11:39.264313 | controller | WARNING [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-01 10:11:39.269705 | controller | INFO [helm > prepare] Executing 2026-04-01 10:11:40.137319 | controller | 2026-04-01 10:11:40.137514 | controller | PLAY [Prepare] ***************************************************************** 2026-04-01 10:11:40.137762 | controller | 2026-04-01 10:11:40.137965 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-01 10:11:40.138160 | controller | Wednesday 01 April 2026 10:11:40 +0000 (0:00:00.028) 0:00:00.028 ******* 2026-04-01 10:11:41.442647 | 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-01 10:11:41.447776 | controller | ok: [controller] 2026-04-01 10:11:41.447815 | controller | 2026-04-01 10:11:41.447923 | controller | TASK [Install Debian packages] ************************************************* 2026-04-01 10:11:41.448011 | controller | Wednesday 01 April 2026 10:11:41 +0000 (0:00:01.311) 0:00:01.339 ******* 2026-04-01 10:11:42.944012 | controller | ok: [controller] 2026-04-01 10:11:42.944186 | controller | 2026-04-01 10:11:42.944390 | controller | PLAY RECAP ********************************************************************* 2026-04-01 10:11:42.944612 | controller | controller : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-01 10:11:42.944815 | controller | 2026-04-01 10:11:42.944970 | controller | 2026-04-01 10:11:42.945148 | controller | TASKS RECAP ******************************************************************** 2026-04-01 10:11:42.945344 | controller | Wednesday 01 April 2026 10:11:42 +0000 (0:00:01.496) 0:00:02.835 ******* 2026-04-01 10:11:42.945521 | controller | =============================================================================== 2026-04-01 10:11:42.945709 | controller | Install Debian packages ------------------------------------------------- 1.50s 2026-04-01 10:11:42.945966 | controller | Gathering Facts --------------------------------------------------------- 1.31s 2026-04-01 10:11:43.037856 | controller | INFO [helm > prepare] Executed: Successful 2026-04-01 10:11:43.052427 | controller | INFO [helm > converge] Executing 2026-04-01 10:11:43.937699 | controller | 2026-04-01 10:11:43.937889 | controller | PLAY [Converge] **************************************************************** 2026-04-01 10:11:43.938063 | controller | 2026-04-01 10:11:43.938352 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-01 10:11:43.938541 | controller | Wednesday 01 April 2026 10:11:43 +0000 (0:00:00.013) 0:00:00.013 ******* 2026-04-01 10:11:45.031153 | 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-01 10:11:45.036420 | controller | ok: [controller] 2026-04-01 10:11:45.036701 | controller | 2026-04-01 10:11:45.037005 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-01 10:11:45.037249 | controller | Wednesday 01 April 2026 10:11:45 +0000 (0:00:01.098) 0:00:01.111 ******* 2026-04-01 10:11:45.550487 | controller | ok: [controller] 2026-04-01 10:11:45.550563 | controller | 2026-04-01 10:11:45.550654 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-01 10:11:45.550804 | controller | Wednesday 01 April 2026 10:11:45 +0000 (0:00:00.513) 0:00:01.625 ******* 2026-04-01 10:11:46.794617 | controller | ok: [controller] 2026-04-01 10:11:46.794694 | controller | 2026-04-01 10:11:46.794704 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-04-01 10:11:46.794715 | controller | Wednesday 01 April 2026 10:11:46 +0000 (0:00:01.242) 0:00:02.867 ******* 2026-04-01 10:11:47.255616 | controller | changed: [controller] 2026-04-01 10:11:47.255691 | controller | 2026-04-01 10:11:47.255789 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-01 10:11:47.255926 | controller | Wednesday 01 April 2026 10:11:47 +0000 (0:00:00.462) 0:00:03.330 ******* 2026-04-01 10:11:47.303519 | controller | ok: [controller] => { 2026-04-01 10:11:47.303636 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-04-01 10:11:47.303761 | controller | } 2026-04-01 10:11:47.303912 | controller | 2026-04-01 10:11:47.304051 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-01 10:11:47.304182 | controller | Wednesday 01 April 2026 10:11:47 +0000 (0:00:00.047) 0:00:03.378 ******* 2026-04-01 10:11:48.386982 | 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-01 10:11:48.395520 | controller | changed: [controller] 2026-04-01 10:11:48.395769 | controller | 2026-04-01 10:11:48.396080 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-01 10:11:48.396423 | controller | Wednesday 01 April 2026 10:11:48 +0000 (0:00:01.092) 0:00:04.470 ******* 2026-04-01 10:11:50.530103 | controller | changed: [controller] 2026-04-01 10:11:50.533405 | controller | 2026-04-01 10:11:50.533440 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-04-01 10:11:50.533447 | controller | Wednesday 01 April 2026 10:11:50 +0000 (0:00:02.134) 0:00:06.605 ******* 2026-04-01 10:11:51.099474 | controller | ok: [controller] 2026-04-01 10:11:51.099718 | controller | 2026-04-01 10:11:51.099962 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-04-01 10:11:51.100183 | controller | Wednesday 01 April 2026 10:11:51 +0000 (0:00:00.569) 0:00:07.174 ******* 2026-04-01 10:11:51.570708 | controller | changed: [controller] 2026-04-01 10:11:51.570781 | controller | 2026-04-01 10:11:51.570868 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-04-01 10:11:51.571066 | controller | Wednesday 01 April 2026 10:11:51 +0000 (0:00:00.471) 0:00:07.645 ******* 2026-04-01 10:11:52.092827 | controller | ok: [controller] 2026-04-01 10:11:52.092960 | controller | 2026-04-01 10:11:52.093191 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-04-01 10:11:52.093391 | controller | Wednesday 01 April 2026 10:11:52 +0000 (0:00:00.521) 0:00:08.167 ******* 2026-04-01 10:11:52.419755 | controller | changed: [controller] 2026-04-01 10:11:52.419834 | controller | 2026-04-01 10:11:52.420004 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-04-01 10:11:52.420162 | controller | Wednesday 01 April 2026 10:11:52 +0000 (0:00:00.327) 0:00:08.495 ******* 2026-04-01 10:11:52.736586 | controller | ok: [controller] 2026-04-01 10:11:52.736762 | controller | 2026-04-01 10:11:52.736991 | controller | TASK [Install plugin] ********************************************************** 2026-04-01 10:11:52.737199 | controller | Wednesday 01 April 2026 10:11:52 +0000 (0:00:00.316) 0:00:08.811 ******* 2026-04-01 10:11:52.794812 | controller | included: vexxhost.containers.download_artifact for controller 2026-04-01 10:11:52.794890 | controller | 2026-04-01 10:11:52.794912 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-01 10:11:52.795065 | controller | Wednesday 01 April 2026 10:11:52 +0000 (0:00:00.058) 0:00:08.870 ******* 2026-04-01 10:11:52.847164 | controller | ok: [controller] => { 2026-04-01 10:11:52.847368 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-04-01 10:11:52.847578 | controller | } 2026-04-01 10:11:52.847742 | controller | 2026-04-01 10:11:52.847934 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-01 10:11:52.848122 | controller | Wednesday 01 April 2026 10:11:52 +0000 (0:00:00.051) 0:00:08.922 ******* 2026-04-01 10:11:54.055418 | controller | changed: [controller] 2026-04-01 10:11:54.055563 | controller | 2026-04-01 10:11:54.055803 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-01 10:11:54.056060 | controller | Wednesday 01 April 2026 10:11:54 +0000 (0:00:01.207) 0:00:10.130 ******* 2026-04-01 10:11:56.234205 | controller | changed: [controller] 2026-04-01 10:11:56.234255 | controller | 2026-04-01 10:11:56.234270 | controller | PLAY RECAP ********************************************************************* 2026-04-01 10:11:56.234283 | controller | controller : ok=16 changed=7 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-01 10:11:56.234294 | controller | 2026-04-01 10:11:56.234305 | controller | 2026-04-01 10:11:56.234315 | controller | TASKS RECAP ******************************************************************** 2026-04-01 10:11:56.234325 | controller | Wednesday 01 April 2026 10:11:56 +0000 (0:00:02.177) 0:00:12.307 ******* 2026-04-01 10:11:56.234332 | controller | =============================================================================== 2026-04-01 10:11:56.234340 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.18s 2026-04-01 10:11:56.234347 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.13s 2026-04-01 10:11:56.234354 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.24s 2026-04-01 10:11:56.234628 | controller | vexxhost.containers.download_artifact : Download item ------------------- 1.21s 2026-04-01 10:11:56.234908 | controller | Gathering Facts --------------------------------------------------------- 1.10s 2026-04-01 10:11:56.235213 | controller | vexxhost.containers.download_artifact : Download item ------------------- 1.09s 2026-04-01 10:11:56.235473 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.57s 2026-04-01 10:11:56.235769 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.52s 2026-04-01 10:11:56.236188 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.51s 2026-04-01 10:11:56.236538 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.47s 2026-04-01 10:11:56.236753 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.46s 2026-04-01 10:11:56.236957 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.33s 2026-04-01 10:11:56.237159 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.32s 2026-04-01 10:11:56.237348 | controller | Install plugin ---------------------------------------------------------- 0.06s 2026-04-01 10:11:56.237541 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-04-01 10:11:56.237731 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-04-01 10:11:56.342054 | controller | INFO [helm > converge] Executed: Successful 2026-04-01 10:11:56.351301 | controller | INFO [helm > idempotence] Executing 2026-04-01 10:11:57.192982 | controller | 2026-04-01 10:11:57.193316 | controller | PLAY [Converge] **************************************************************** 2026-04-01 10:11:57.193567 | controller | 2026-04-01 10:11:57.193828 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-01 10:11:57.194089 | controller | Wednesday 01 April 2026 10:11:57 +0000 (0:00:00.012) 0:00:00.012 ******* 2026-04-01 10:11:58.277539 | 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-01 10:11:58.282727 | controller | ok: [controller] 2026-04-01 10:11:58.282845 | controller | 2026-04-01 10:11:58.282969 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-01 10:11:58.283122 | controller | Wednesday 01 April 2026 10:11:58 +0000 (0:00:01.090) 0:00:01.102 ******* 2026-04-01 10:11:58.737981 | controller | ok: [controller] 2026-04-01 10:11:58.738421 | controller | 2026-04-01 10:11:58.738803 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-01 10:11:58.739128 | controller | Wednesday 01 April 2026 10:11:58 +0000 (0:00:00.454) 0:00:01.557 ******* 2026-04-01 10:11:59.906364 | controller | ok: [controller] 2026-04-01 10:11:59.906665 | controller | 2026-04-01 10:11:59.906945 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-04-01 10:11:59.907210 | controller | Wednesday 01 April 2026 10:11:59 +0000 (0:00:01.168) 0:00:02.725 ******* 2026-04-01 10:12:00.364776 | controller | ok: [controller] 2026-04-01 10:12:00.365201 | controller | 2026-04-01 10:12:00.365549 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-01 10:12:00.365917 | controller | Wednesday 01 April 2026 10:12:00 +0000 (0:00:00.457) 0:00:03.183 ******* 2026-04-01 10:12:00.409364 | controller | ok: [controller] => { 2026-04-01 10:12:00.409664 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-04-01 10:12:00.409933 | controller | } 2026-04-01 10:12:00.410207 | controller | 2026-04-01 10:12:00.410473 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-01 10:12:00.410729 | controller | Wednesday 01 April 2026 10:12:00 +0000 (0:00:00.045) 0:00:03.228 ******* 2026-04-01 10:12:00.961704 | controller | ok: [controller] 2026-04-01 10:12:00.961761 | controller | 2026-04-01 10:12:00.961868 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-01 10:12:00.961986 | controller | Wednesday 01 April 2026 10:12:00 +0000 (0:00:00.552) 0:00:03.780 ******* 2026-04-01 10:12:02.758732 | controller | ok: [controller] 2026-04-01 10:12:02.758784 | controller | 2026-04-01 10:12:02.758861 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-04-01 10:12:02.758948 | controller | Wednesday 01 April 2026 10:12:02 +0000 (0:00:01.795) 0:00:05.576 ******* 2026-04-01 10:12:03.285580 | controller | ok: [controller] 2026-04-01 10:12:03.285625 | controller | 2026-04-01 10:12:03.285707 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-04-01 10:12:03.285794 | controller | Wednesday 01 April 2026 10:12:03 +0000 (0:00:00.528) 0:00:06.105 ******* 2026-04-01 10:12:03.701002 | controller | ok: [controller] 2026-04-01 10:12:03.701067 | controller | 2026-04-01 10:12:03.701184 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-04-01 10:12:03.701311 | controller | Wednesday 01 April 2026 10:12:03 +0000 (0:00:00.414) 0:00:06.520 ******* 2026-04-01 10:12:04.174955 | controller | ok: [controller] 2026-04-01 10:12:04.175056 | controller | 2026-04-01 10:12:04.175068 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-04-01 10:12:04.175186 | controller | Wednesday 01 April 2026 10:12:04 +0000 (0:00:00.471) 0:00:06.992 ******* 2026-04-01 10:12:04.505311 | controller | ok: [controller] 2026-04-01 10:12:04.505396 | controller | 2026-04-01 10:12:04.505718 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-04-01 10:12:04.505773 | controller | Wednesday 01 April 2026 10:12:04 +0000 (0:00:00.332) 0:00:07.324 ******* 2026-04-01 10:12:04.822821 | controller | ok: [controller] 2026-04-01 10:12:04.822905 | controller | 2026-04-01 10:12:04.822959 | controller | TASK [Install plugin] ********************************************************** 2026-04-01 10:12:04.823099 | controller | Wednesday 01 April 2026 10:12:04 +0000 (0:00:00.315) 0:00:07.639 ******* 2026-04-01 10:12:04.876965 | controller | included: vexxhost.containers.download_artifact for controller 2026-04-01 10:12:04.877049 | controller | 2026-04-01 10:12:04.877060 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-01 10:12:04.877133 | controller | Wednesday 01 April 2026 10:12:04 +0000 (0:00:00.056) 0:00:07.696 ******* 2026-04-01 10:12:04.924918 | controller | ok: [controller] => { 2026-04-01 10:12:04.925031 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-04-01 10:12:04.925041 | controller | } 2026-04-01 10:12:04.925053 | controller | 2026-04-01 10:12:04.925193 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-01 10:12:04.925239 | controller | Wednesday 01 April 2026 10:12:04 +0000 (0:00:00.047) 0:00:07.744 ******* 2026-04-01 10:12:05.370350 | controller | ok: [controller] 2026-04-01 10:12:05.370439 | controller | 2026-04-01 10:12:05.370511 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-01 10:12:05.370713 | controller | Wednesday 01 April 2026 10:12:05 +0000 (0:00:00.444) 0:00:08.189 ******* 2026-04-01 10:12:07.078264 | controller | ok: [controller] 2026-04-01 10:12:07.078323 | controller | 2026-04-01 10:12:07.078332 | controller | PLAY RECAP ********************************************************************* 2026-04-01 10:12:07.078341 | controller | controller : ok=16 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-01 10:12:07.078349 | controller | 2026-04-01 10:12:07.078357 | controller | 2026-04-01 10:12:07.078364 | controller | TASKS RECAP ******************************************************************** 2026-04-01 10:12:07.078372 | controller | Wednesday 01 April 2026 10:12:07 +0000 (0:00:01.705) 0:00:09.894 ******* 2026-04-01 10:12:07.078379 | controller | =============================================================================== 2026-04-01 10:12:07.078386 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.80s 2026-04-01 10:12:07.078393 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.71s 2026-04-01 10:12:07.078400 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.17s 2026-04-01 10:12:07.078407 | controller | Gathering Facts --------------------------------------------------------- 1.09s 2026-04-01 10:12:07.078414 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.55s 2026-04-01 10:12:07.078421 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.53s 2026-04-01 10:12:07.078429 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.47s 2026-04-01 10:12:07.078436 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.46s 2026-04-01 10:12:07.078445 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.45s 2026-04-01 10:12:07.078452 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.44s 2026-04-01 10:12:07.078486 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.42s 2026-04-01 10:12:07.078494 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.33s 2026-04-01 10:12:07.078717 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.32s 2026-04-01 10:12:07.078905 | controller | Install plugin ---------------------------------------------------------- 0.06s 2026-04-01 10:12:07.079110 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-04-01 10:12:07.079321 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-04-01 10:12:07.274427 | controller | INFO [helm > idempotence] Executed: Successful 2026-04-01 10:12:07.286524 | controller | INFO [helm > side_effect] Executing 2026-04-01 10:12:07.286924 | controller | WARNING [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-01 10:12:07.292692 | controller | INFO [helm > verify] Executing 2026-04-01 10:12:08.175172 | controller | 2026-04-01 10:12:08.175453 | controller | PLAY [Verify] ****************************************************************** 2026-04-01 10:12:08.175714 | controller | 2026-04-01 10:12:08.175966 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-01 10:12:08.176217 | controller | Wednesday 01 April 2026 10:12:08 +0000 (0:00:00.009) 0:00:00.009 ******* 2026-04-01 10:12:09.290451 | 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-01 10:12:09.297825 | controller | ok: [controller] 2026-04-01 10:12:09.298003 | controller | 2026-04-01 10:12:09.298339 | controller | TASK [Get the current Helm version] ******************************************** 2026-04-01 10:12:09.298550 | controller | Wednesday 01 April 2026 10:12:09 +0000 (0:00:01.122) 0:00:01.132 ******* 2026-04-01 10:12:09.781188 | controller | changed: [controller] 2026-04-01 10:12:09.781217 | controller | 2026-04-01 10:12:09.781223 | controller | TASK [Assert Helm version] ***************************************************** 2026-04-01 10:12:09.781228 | controller | Wednesday 01 April 2026 10:12:09 +0000 (0:00:00.480) 0:00:01.612 ******* 2026-04-01 10:12:09.812963 | controller | ok: [controller] => { 2026-04-01 10:12:09.813170 | controller | "changed": false, 2026-04-01 10:12:09.814053 | controller | "msg": "All assertions passed" 2026-04-01 10:12:09.814073 | controller | } 2026-04-01 10:12:09.814083 | controller | 2026-04-01 10:12:09.814091 | controller | PLAY RECAP ********************************************************************* 2026-04-01 10:12:09.814763 | controller | controller : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-01 10:12:09.814781 | controller | 2026-04-01 10:12:09.814789 | controller | 2026-04-01 10:12:09.814883 | controller | TASKS RECAP ******************************************************************** 2026-04-01 10:12:09.815134 | controller | Wednesday 01 April 2026 10:12:09 +0000 (0:00:00.035) 0:00:01.648 ******* 2026-04-01 10:12:09.815329 | controller | =============================================================================== 2026-04-01 10:12:09.815568 | controller | Gathering Facts --------------------------------------------------------- 1.12s 2026-04-01 10:12:09.815820 | controller | Get the current Helm version -------------------------------------------- 0.48s 2026-04-01 10:12:09.816077 | controller | Assert Helm version ----------------------------------------------------- 0.04s 2026-04-01 10:12:09.927853 | controller | INFO [helm > verify] Executed: Successful 2026-04-01 10:12:09.938036 | controller | INFO [helm > cleanup] Executing 2026-04-01 10:12:09.938433 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-01 10:12:09.944458 | controller | INFO [helm > destroy] Executing 2026-04-01 10:12:09.944513 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-04-01 10:12:09.944616 | controller | INFO [helm > destroy] Executed: Successful 2026-04-01 10:12:09.945007 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-04-01 10:12:10.269762 | controller | ok: Runtime: 0:00:51.964578 2026-04-01 10:12:10.273431 | 2026-04-01 10:12:10.273502 | PLAY RECAP 2026-04-01 10:12:10.273554 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-01 10:12:10.273577 | 2026-04-01 10:12:10.377794 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-01 10:12:10.380399 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-04-01 10:12:10.962549 | 2026-04-01 10:12:10.962698 | PLAY [all] 2026-04-01 10:12:10.975007 | 2026-04-01 10:12:10.975152 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-04-01 10:12:11.385837 | controller | changed 2026-04-01 10:12:11.391763 | 2026-04-01 10:12:11.391852 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-04-01 10:12:11.768060 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-01 10:12:11.768139 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-01 10:12:11.774601 | controller | environment: line 1: kubectl: command not found 2026-04-01 10:12:11.776171 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-01 10:12:11.779150 | controller | environment: line 1: kubectl: command not found 2026-04-01 10:12:11.780330 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-01 10:12:11.780742 | controller | environment: line 1: kubectl: command not found 2026-04-01 10:12:11.782986 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-01 10:12:11.784846 | controller | environment: line 1: kubectl: command not found 2026-04-01 10:12:11.786228 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-01 10:12:11.787948 | controller | environment: line 1: kubectl: command not found 2026-04-01 10:12:11.789319 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-01 10:12:11.920644 | controller | ok: Runtime: 0:00:00.036194 2026-04-01 10:12:11.926441 | 2026-04-01 10:12:11.926507 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-04-01 10:12:12.179772 | controller | changed 2026-04-01 10:12:12.185223 | 2026-04-01 10:12:12.185321 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-04-01 10:12:12.422977 | controller | environment: line 5: kubectl: command not found 2026-04-01 10:12:12.424651 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-01 10:12:12.424677 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-01 10:12:12.424812 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-01 10:12:12.717611 | controller | ok: Runtime: 0:00:00.015040 2026-04-01 10:12:12.724648 | 2026-04-01 10:12:12.724717 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-04-01 10:12:13.423443 | controller | changed: 2026-04-01 10:12:13.423761 | controller | created directory /var/lib/zuul/builds/0f162a75529c4016a1cf2fb1d6901421/work/logs/controller 2026-04-01 10:12:13.423865 | controller | cd+++++++++ objects/ 2026-04-01 10:12:13.423927 | controller | cd+++++++++ objects/cluster/ 2026-04-01 10:12:13.423983 | controller | cd+++++++++ objects/namespaced/ 2026-04-01 10:12:13.432026 | 2026-04-01 10:12:13.432091 | TASK [gather-pod-logs : creating directory for pod logs] 2026-04-01 10:12:13.677416 | controller | changed 2026-04-01 10:12:13.681463 | 2026-04-01 10:12:13.681532 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-04-01 10:12:13.934605 | controller | changed 2026-04-01 10:12:13.939286 | 2026-04-01 10:12:13.939383 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-04-01 10:12:14.186228 | controller | environment: line 3: kubectl: command not found 2026-04-01 10:12:14.472454 | controller | ok: Runtime: 0:00:00.014399 2026-04-01 10:12:14.478707 | 2026-04-01 10:12:14.478779 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-04-01 10:12:15.014611 | controller | changed: 2026-04-01 10:12:15.014838 | controller | cd+++++++++ pod-logs/ 2026-04-01 10:12:15.014867 | controller | cd+++++++++ pod-logs/failed-pods/ 2026-04-01 10:12:15.021808 | 2026-04-01 10:12:15.021893 | LOOP [helm-release-status : creating directory for helm release status] 2026-04-01 10:12:15.263825 | controller | changed: "values" 2026-04-01 10:12:15.483195 | controller | changed: "releases" 2026-04-01 10:12:15.495436 | 2026-04-01 10:12:15.495566 | TASK [helm-release-status : Gather get release status for helm charts] 2026-04-01 10:12:15.769318 | controller | /bin/bash: line 3: kubectl: command not found 2026-04-01 10:12:16.022457 | controller | ok: Runtime: 0:00:00.009714 2026-04-01 10:12:16.027954 | 2026-04-01 10:12:16.028038 | TASK [helm-release-status : Downloads logs to executor] 2026-04-01 10:12:16.565933 | controller | changed: 2026-04-01 10:12:16.566135 | controller | cd+++++++++ helm/ 2026-04-01 10:12:16.566173 | controller | cd+++++++++ helm/releases/ 2026-04-01 10:12:16.566207 | controller | cd+++++++++ helm/values/ 2026-04-01 10:12:16.572566 | 2026-04-01 10:12:16.572643 | PLAY RECAP 2026-04-01 10:12:16.572710 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-01 10:12:16.572747 | 2026-04-01 10:12:16.687761 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-04-01 10:12:16.690658 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-01 10:12:17.236391 | 2026-04-01 10:12:17.236495 | PLAY [all] 2026-04-01 10:12:17.245894 | 2026-04-01 10:12:17.245967 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-01 10:12:17.291287 | controller | skipping: Conditional result was False 2026-04-01 10:12:17.300935 | 2026-04-01 10:12:17.301031 | TASK [fetch-output : Set log path for single node] 2026-04-01 10:12:17.340787 | controller | ok 2026-04-01 10:12:17.345676 | 2026-04-01 10:12:17.345764 | LOOP [fetch-output : Ensure local output dirs] 2026-04-01 10:12:17.756121 | controller -> localhost | ok: "/var/lib/zuul/builds/0f162a75529c4016a1cf2fb1d6901421/work/logs" 2026-04-01 10:12:17.965220 | controller -> localhost | changed: "/var/lib/zuul/builds/0f162a75529c4016a1cf2fb1d6901421/work/artifacts" 2026-04-01 10:12:18.172340 | controller -> localhost | changed: "/var/lib/zuul/builds/0f162a75529c4016a1cf2fb1d6901421/work/docs" 2026-04-01 10:12:18.193845 | 2026-04-01 10:12:18.194020 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-01 10:12:18.855308 | controller | changed: .d..t...... ./ 2026-04-01 10:12:18.855553 | controller | changed: All items complete 2026-04-01 10:12:18.855581 | 2026-04-01 10:12:19.321196 | controller | changed: .d..t...... ./ 2026-04-01 10:12:19.812722 | controller | changed: .d..t...... ./ 2026-04-01 10:12:19.829894 | 2026-04-01 10:12:19.830047 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-01 10:12:20.256071 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.005049 2026-04-01 10:12:20.532838 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008145 2026-04-01 10:12:20.551715 | 2026-04-01 10:12:20.551841 | PLAY [all] 2026-04-01 10:12:20.558369 | 2026-04-01 10:12:20.558455 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-01 10:12:21.016692 | controller | changed 2026-04-01 10:12:21.022204 | 2026-04-01 10:12:21.022261 | PLAY RECAP 2026-04-01 10:12:21.022309 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-01 10:12:21.022331 | 2026-04-01 10:12:21.127415 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-01 10:12:21.130451 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-01 10:12:21.697587 | 2026-04-01 10:12:21.697795 | PLAY [localhost] 2026-04-01 10:12:21.707871 | 2026-04-01 10:12:21.707968 | TASK [Generate Zuul manifest] 2026-04-01 10:12:21.730577 | localhost | ok 2026-04-01 10:12:21.750665 | 2026-04-01 10:12:21.750793 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-01 10:12:22.087264 | localhost | changed 2026-04-01 10:12:22.101115 | 2026-04-01 10:12:22.101208 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-01 10:12:22.133547 | localhost | ok 2026-04-01 10:12:22.142425 | 2026-04-01 10:12:22.142495 | TASK [Upload logs] 2026-04-01 10:12:22.161593 | localhost | ok 2026-04-01 10:12:22.220026 | 2026-04-01 10:12:22.220147 | TASK [Set zuul-log-path fact] 2026-04-01 10:12:22.239472 | localhost | ok 2026-04-01 10:12:22.253539 | 2026-04-01 10:12:22.253682 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-01 10:12:22.285494 | localhost | ok 2026-04-01 10:12:22.294716 | 2026-04-01 10:12:22.294815 | TASK [upload-logs : Create log directories] 2026-04-01 10:12:22.684744 | localhost | changed 2026-04-01 10:12:22.691683 | 2026-04-01 10:12:22.691834 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-01 10:12:23.090920 | localhost -> localhost | ok: Runtime: 0:00:00.005128 2026-04-01 10:12:23.098237 | 2026-04-01 10:12:23.098334 | TASK [upload-logs : Upload logs to log server] 2026-04-01 10:12:23.556230 | localhost | Output suppressed because no_log was given 2026-04-01 10:12:23.562425 | 2026-04-01 10:12:23.562609 | LOOP [upload-logs : Compress console log and json output] 2026-04-01 10:12:23.607785 | localhost | skipping: Conditional result was False 2026-04-01 10:12:23.615157 | localhost | skipping: Conditional result was False 2026-04-01 10:12:23.629426 | 2026-04-01 10:12:23.629610 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-01 10:12:23.672161 | localhost | skipping: Conditional result was False 2026-04-01 10:12:23.672653 | 2026-04-01 10:12:23.676160 | localhost | skipping: Conditional result was False 2026-04-01 10:12:23.693801 | 2026-04-01 10:12:23.693981 | LOOP [upload-logs : Upload console log and json output]