2026-04-22 09:29:04.863826 | Job console starting 2026-04-22 09:29:04.881193 | Updating git repos 2026-04-22 09:29:04.935223 | Cloning repos into workspace 2026-04-22 09:29:06.123220 | Restoring repo states 2026-04-22 09:29:06.172313 | Merging changes 2026-04-22 09:29:07.090765 | Checking out repos 2026-04-22 09:29:08.537289 | Preparing playbooks 2026-04-22 09:29:15.241653 | Running Ansible setup 2026-04-22 09:29:20.134453 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-22 09:29:20.757862 | 2026-04-22 09:29:20.757990 | PLAY [localhost] 2026-04-22 09:29:20.765813 | 2026-04-22 09:29:20.765923 | TASK [Gathering Facts] 2026-04-22 09:29:21.684841 | localhost | ok 2026-04-22 09:29:21.694177 | 2026-04-22 09:29:21.694282 | TASK [Setup log path fact] 2026-04-22 09:29:21.735613 | localhost | ok 2026-04-22 09:29:21.749237 | 2026-04-22 09:29:21.749332 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-22 09:29:21.779632 | localhost | ok 2026-04-22 09:29:21.786577 | 2026-04-22 09:29:21.786645 | TASK [emit-job-header : Print job information] 2026-04-22 09:29:21.827195 | # Job Information 2026-04-22 09:29:21.827502 | Ansible Version: 2.16.16 2026-04-22 09:29:21.827609 | Job: ansible-collection-kubernetes-molecule-helm 2026-04-22 09:29:21.827685 | Pipeline: check 2026-04-22 09:29:21.827753 | Executor: 0a8996d2b663 2026-04-22 09:29:21.827818 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/269 2026-04-22 09:29:21.828009 | Event ID: 6f69c850-3e2d-11f1-86ca-e28b6f9e0ade 2026-04-22 09:29:21.834739 | 2026-04-22 09:29:21.834826 | LOOP [emit-job-header : Print node information] 2026-04-22 09:29:21.921478 | localhost | ok: 2026-04-22 09:29:21.921710 | localhost | # Node Information 2026-04-22 09:29:21.921760 | localhost | Inventory Hostname: controller 2026-04-22 09:29:21.921799 | localhost | Hostname: np0000169307 2026-04-22 09:29:21.921834 | localhost | Username: zuul 2026-04-22 09:29:21.921872 | localhost | Distro: Debian 13.4 2026-04-22 09:29:21.921906 | localhost | Provider: yul1 2026-04-22 09:29:21.921938 | localhost | Region: ca-ymq-1 2026-04-22 09:29:21.921971 | localhost | Label: debian-trixie 2026-04-22 09:29:21.922004 | localhost | Product Name: OpenStack Nova 2026-04-22 09:29:21.922038 | localhost | Interface IP: 199.19.213.100 2026-04-22 09:29:21.933354 | 2026-04-22 09:29:21.933523 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-22 09:29:22.343838 | localhost -> localhost | changed 2026-04-22 09:29:22.348806 | 2026-04-22 09:29:22.348876 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-22 09:29:23.242375 | localhost -> localhost | changed 2026-04-22 09:29:23.255110 | 2026-04-22 09:29:23.255187 | PLAY [all] 2026-04-22 09:29:23.264626 | 2026-04-22 09:29:23.264752 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-22 09:29:23.509642 | controller -> localhost | ok 2026-04-22 09:29:23.519152 | 2026-04-22 09:29:23.519291 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-22 09:29:23.553659 | controller | ok 2026-04-22 09:29:23.565632 | controller | included: /var/lib/zuul/builds/9c5a06722c6a484da8e9b48a6e4de80f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-22 09:29:23.570217 | 2026-04-22 09:29:23.570279 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-22 09:29:25.061867 | controller -> localhost | Generating public/private rsa key pair. 2026-04-22 09:29:25.062124 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9c5a06722c6a484da8e9b48a6e4de80f/work/9c5a06722c6a484da8e9b48a6e4de80f_id_rsa 2026-04-22 09:29:25.062173 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9c5a06722c6a484da8e9b48a6e4de80f/work/9c5a06722c6a484da8e9b48a6e4de80f_id_rsa.pub 2026-04-22 09:29:25.062208 | controller -> localhost | The key fingerprint is: 2026-04-22 09:29:25.062240 | controller -> localhost | SHA256:MKk+nsHrRcDEPmRwSksPSDTDuc0kceVR17oucKNlvKQ zuul-build-sshkey 2026-04-22 09:29:25.062288 | controller -> localhost | The key's randomart image is: 2026-04-22 09:29:25.062321 | controller -> localhost | +---[RSA 3072]----+ 2026-04-22 09:29:25.062358 | controller -> localhost | |+BB++o.. .. | 2026-04-22 09:29:25.062390 | controller -> localhost | |.*+X= ... . | 2026-04-22 09:29:25.062420 | controller -> localhost | | O++.+ . | 2026-04-22 09:29:25.062450 | controller -> localhost | | . ooo o . | 2026-04-22 09:29:25.062479 | controller -> localhost | | ..o S . | 2026-04-22 09:29:25.062512 | controller -> localhost | | o o B . | 2026-04-22 09:29:25.062541 | controller -> localhost | | = X + | 2026-04-22 09:29:25.062570 | controller -> localhost | | . E o . | 2026-04-22 09:29:25.062604 | controller -> localhost | | .= . | 2026-04-22 09:29:25.062633 | controller -> localhost | +----[SHA256]-----+ 2026-04-22 09:29:25.062702 | controller -> localhost | ok: Runtime: 0:00:01.039097 2026-04-22 09:29:25.068894 | 2026-04-22 09:29:25.069011 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-22 09:29:25.104134 | controller | ok 2026-04-22 09:29:25.114714 | controller | included: /var/lib/zuul/builds/9c5a06722c6a484da8e9b48a6e4de80f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-22 09:29:25.122118 | 2026-04-22 09:29:25.122183 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-22 09:29:25.152236 | controller | skipping: Conditional result was False 2026-04-22 09:29:25.156979 | 2026-04-22 09:29:25.157054 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-22 09:29:25.724700 | controller | changed 2026-04-22 09:29:25.732392 | 2026-04-22 09:29:25.734032 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-22 09:29:25.947134 | controller | ok 2026-04-22 09:29:26.008866 | 2026-04-22 09:29:26.009020 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-22 09:29:26.724862 | controller | changed 2026-04-22 09:29:26.730014 | 2026-04-22 09:29:26.730162 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-22 09:29:27.282619 | controller | changed 2026-04-22 09:29:27.287883 | 2026-04-22 09:29:27.287957 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-22 09:29:27.312916 | controller | skipping: Conditional result was False 2026-04-22 09:29:27.320575 | 2026-04-22 09:29:27.320703 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-22 09:29:27.741017 | controller -> localhost | changed 2026-04-22 09:29:27.756029 | 2026-04-22 09:29:27.756175 | TASK [add-build-sshkey : Add back temp key] 2026-04-22 09:29:28.070392 | controller -> localhost | Identity added: /var/lib/zuul/builds/9c5a06722c6a484da8e9b48a6e4de80f/work/9c5a06722c6a484da8e9b48a6e4de80f_id_rsa (zuul-build-sshkey) 2026-04-22 09:29:28.070668 | controller -> localhost | ok: Runtime: 0:00:00.017354 2026-04-22 09:29:28.077289 | 2026-04-22 09:29:28.077419 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-22 09:29:28.412736 | controller | ok 2026-04-22 09:29:28.416626 | 2026-04-22 09:29:28.416696 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-22 09:29:28.441508 | controller | skipping: Conditional result was False 2026-04-22 09:29:28.452647 | 2026-04-22 09:29:28.452767 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-22 09:29:28.830340 | controller | ok 2026-04-22 09:29:28.835651 | 2026-04-22 09:29:28.835727 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-22 09:29:30.008469 | controller | Output suppressed because no_log was given 2026-04-22 09:29:30.018205 | 2026-04-22 09:29:30.018270 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-22 09:29:30.252519 | controller | ok: "logs" 2026-04-22 09:29:30.252813 | controller | ok: All items complete 2026-04-22 09:29:30.252849 | 2026-04-22 09:29:30.446704 | controller | ok: "artifacts" 2026-04-22 09:29:30.633460 | controller | ok: "docs" 2026-04-22 09:29:30.646763 | 2026-04-22 09:29:30.646959 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-22 09:29:30.857329 | controller | changed: "logs" 2026-04-22 09:29:31.044372 | controller | changed: "artifacts" 2026-04-22 09:29:31.228187 | controller | changed: "docs" 2026-04-22 09:29:31.244410 | 2026-04-22 09:29:31.245348 | PLAY RECAP 2026-04-22 09:29:31.245431 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-22 09:29:31.245469 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-22 09:29:31.245496 | 2026-04-22 09:29:31.427578 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-22 09:29:31.429178 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-22 09:29:32.028598 | 2026-04-22 09:29:32.519323 | PLAY [all] 2026-04-22 09:29:32.537105 | 2026-04-22 09:29:32.537216 | TASK [setup-uv : Extract archive] 2026-04-22 09:29:35.421196 | controller | changed 2026-04-22 09:29:35.428926 | 2026-04-22 09:29:35.429073 | TASK [setup-uv : Print version] 2026-04-22 09:29:35.814605 | controller | uv 0.8.13 2026-04-22 09:29:35.958515 | controller | ok: Runtime: 0:00:00.010905 2026-04-22 09:29:35.965260 | 2026-04-22 09:29:35.965324 | PLAY RECAP 2026-04-22 09:29:35.965376 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-22 09:29:35.965406 | 2026-04-22 09:29:36.092527 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-22 09:29:36.095335 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-04-22 09:29:36.702903 | 2026-04-22 09:29:36.703035 | PLAY [all] 2026-04-22 09:29:36.713262 | 2026-04-22 09:29:36.713337 | TASK [Update apt cache] 2026-04-22 09:29:42.074754 | controller | ok 2026-04-22 09:29:42.081778 | 2026-04-22 09:29:42.081889 | TASK [Disable firewalld] 2026-04-22 09:29:42.108006 | controller | skipping: Conditional result was False 2026-04-22 09:29:42.116504 | 2026-04-22 09:29:42.116574 | TASK [Install "jq" for log collection] 2026-04-22 09:29:44.602158 | controller | changed 2026-04-22 09:29:44.606122 | 2026-04-22 09:29:44.606177 | PLAY RECAP 2026-04-22 09:29:44.606224 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-22 09:29:44.606246 | 2026-04-22 09:29:44.735548 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-04-22 09:29:44.738294 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-22 09:29:45.374329 | 2026-04-22 09:29:45.374476 | PLAY [all] 2026-04-22 09:29:45.384367 | 2026-04-22 09:29:45.384441 | TASK [Copy inventory file for Zuul] 2026-04-22 09:29:46.385621 | controller | changed 2026-04-22 09:29:46.392400 | 2026-04-22 09:29:46.392512 | TASK [Switch "ansible_host" to private IP] 2026-04-22 09:29:46.723573 | controller | changed: 1 replacements made 2026-04-22 09:29:46.732140 | 2026-04-22 09:29:46.732223 | TASK [Run Molecule scenario] 2026-04-22 09:29:47.203674 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-04-22 09:29:47.203808 | controller | Creating virtual environment at: .venv 2026-04-22 09:29:47.446431 | controller | Downloading ansible-core (2.3MiB) 2026-04-22 09:29:47.447612 | controller | Downloading pygments (1.2MiB) 2026-04-22 09:29:47.773718 | controller | Downloading cryptography (4.3MiB) 2026-04-22 09:29:48.031924 | controller | Downloading pygments 2026-04-22 09:29:48.183450 | controller | Downloading cryptography 2026-04-22 09:29:48.205252 | controller | Downloading ansible-core 2026-04-22 09:29:48.250083 | controller | Installed 36 packages in 44ms 2026-04-22 09:29:48.964993 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-04-22 09:29:48.965060 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-04-22 09:29:49.873820 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-04-22 09:29:49.873876 | controller | INFO default scenario not found, disabling shared state. 2026-04-22 09:29:49.873956 | controller | INFO [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-04-22 09:29:49.874031 | controller | INFO [helm > prerun] Performing prerun with role_name_check=0... 2026-04-22 09:30:13.401078 | controller | INFO [helm > dependency] Executing 2026-04-22 09:30:13.401237 | controller | WARNING [helm > dependency] Missing roles requirements file: requirements.yml 2026-04-22 09:30:13.401569 | controller | WARNING [helm > dependency] Missing collections requirements file: collections.yml 2026-04-22 09:30:13.401766 | controller | WARNING [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-04-22 09:30:13.409158 | controller | INFO [helm > cleanup] Executing 2026-04-22 09:30:13.409497 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-22 09:30:13.415747 | controller | INFO [helm > destroy] Executing 2026-04-22 09:30:13.415814 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-04-22 09:30:13.415955 | controller | INFO [helm > destroy] Executed: Successful 2026-04-22 09:30:13.421561 | controller | INFO [helm > syntax] Executing 2026-04-22 09:30:14.360261 | controller | 2026-04-22 09:30:14.360519 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml 2026-04-22 09:30:14.427055 | controller | INFO [helm > syntax] Executed: Successful 2026-04-22 09:30:14.657180 | controller | INFO [helm > create] Executing 2026-04-22 09:30:14.757303 | controller | WARNING [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-22 09:30:14.766229 | controller | INFO [helm > prepare] Executing 2026-04-22 09:30:15.745811 | controller | 2026-04-22 09:30:15.745900 | controller | PLAY [Prepare] ***************************************************************** 2026-04-22 09:30:15.746136 | controller | 2026-04-22 09:30:15.746335 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-22 09:30:15.746592 | controller | Wednesday 22 April 2026 09:30:15 +0000 (0:00:00.082) 0:00:00.082 ******* 2026-04-22 09:30:17.059531 | 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 09:30:17.076675 | controller | ok: [controller] 2026-04-22 09:30:17.076746 | controller | 2026-04-22 09:30:17.076751 | controller | TASK [Install Debian packages] ************************************************* 2026-04-22 09:30:17.076756 | controller | Wednesday 22 April 2026 09:30:17 +0000 (0:00:01.320) 0:00:01.403 ******* 2026-04-22 09:30:18.659488 | controller | ok: [controller] 2026-04-22 09:30:18.659665 | controller | 2026-04-22 09:30:18.659908 | controller | PLAY RECAP ********************************************************************* 2026-04-22 09:30:18.660187 | controller | controller : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-22 09:30:18.660386 | controller | 2026-04-22 09:30:18.660561 | controller | 2026-04-22 09:30:18.660751 | controller | TASKS RECAP ******************************************************************** 2026-04-22 09:30:18.660951 | controller | Wednesday 22 April 2026 09:30:18 +0000 (0:00:01.593) 0:00:02.997 ******* 2026-04-22 09:30:18.661132 | controller | =============================================================================== 2026-04-22 09:30:18.661383 | controller | Install Debian packages ------------------------------------------------- 1.59s 2026-04-22 09:30:18.661583 | controller | Gathering Facts --------------------------------------------------------- 1.32s 2026-04-22 09:30:18.799941 | controller | INFO [helm > prepare] Executed: Successful 2026-04-22 09:30:18.881091 | controller | INFO [helm > converge] Executing 2026-04-22 09:30:19.742626 | controller | 2026-04-22 09:30:19.742692 | controller | PLAY [Converge] **************************************************************** 2026-04-22 09:30:19.742789 | controller | 2026-04-22 09:30:19.742933 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-22 09:30:19.743060 | controller | Wednesday 22 April 2026 09:30:19 +0000 (0:00:00.012) 0:00:00.012 ******* 2026-04-22 09:30:20.809153 | 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 09:30:20.813696 | controller | ok: [controller] 2026-04-22 09:30:20.813895 | controller | 2026-04-22 09:30:20.814122 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-22 09:30:20.814340 | controller | Wednesday 22 April 2026 09:30:20 +0000 (0:00:01.071) 0:00:01.083 ******* 2026-04-22 09:30:21.338702 | controller | ok: [controller] 2026-04-22 09:30:21.338944 | controller | 2026-04-22 09:30:21.339184 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-22 09:30:21.339498 | controller | Wednesday 22 April 2026 09:30:21 +0000 (0:00:00.524) 0:00:01.607 ******* 2026-04-22 09:30:22.596237 | controller | ok: [controller] 2026-04-22 09:30:22.596366 | controller | 2026-04-22 09:30:22.596590 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-04-22 09:30:22.596791 | controller | Wednesday 22 April 2026 09:30:22 +0000 (0:00:01.257) 0:00:02.865 ******* 2026-04-22 09:30:23.025416 | controller | changed: [controller] 2026-04-22 09:30:23.025502 | controller | 2026-04-22 09:30:23.025642 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-22 09:30:23.025791 | controller | Wednesday 22 April 2026 09:30:23 +0000 (0:00:00.429) 0:00:03.294 ******* 2026-04-22 09:30:23.070102 | controller | ok: [controller] => { 2026-04-22 09:30:23.070286 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-04-22 09:30:23.070489 | controller | } 2026-04-22 09:30:23.070680 | controller | 2026-04-22 09:30:23.070905 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-22 09:30:23.071114 | controller | Wednesday 22 April 2026 09:30:23 +0000 (0:00:00.044) 0:00:03.339 ******* 2026-04-22 09:30:23.946260 | 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 09:30:23.955728 | controller | changed: [controller] 2026-04-22 09:30:23.956020 | controller | 2026-04-22 09:30:23.956403 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-22 09:30:23.956705 | controller | Wednesday 22 April 2026 09:30:23 +0000 (0:00:00.885) 0:00:04.225 ******* 2026-04-22 09:30:26.007749 | controller | changed: [controller] 2026-04-22 09:30:26.007783 | controller | 2026-04-22 09:30:26.007787 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-04-22 09:30:26.007792 | controller | Wednesday 22 April 2026 09:30:25 +0000 (0:00:02.048) 0:00:06.274 ******* 2026-04-22 09:30:26.546654 | controller | ok: [controller] 2026-04-22 09:30:26.546697 | controller | 2026-04-22 09:30:26.546766 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-04-22 09:30:26.546852 | controller | Wednesday 22 April 2026 09:30:26 +0000 (0:00:00.541) 0:00:06.815 ******* 2026-04-22 09:30:27.112167 | controller | changed: [controller] 2026-04-22 09:30:27.112226 | controller | 2026-04-22 09:30:27.112399 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-04-22 09:30:27.112530 | controller | Wednesday 22 April 2026 09:30:27 +0000 (0:00:00.565) 0:00:07.381 ******* 2026-04-22 09:30:27.574863 | controller | ok: [controller] 2026-04-22 09:30:27.574942 | controller | 2026-04-22 09:30:27.574949 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-04-22 09:30:27.575084 | controller | Wednesday 22 April 2026 09:30:27 +0000 (0:00:00.461) 0:00:07.842 ******* 2026-04-22 09:30:27.927635 | controller | changed: [controller] 2026-04-22 09:30:27.927693 | controller | 2026-04-22 09:30:27.928024 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-04-22 09:30:27.928064 | controller | Wednesday 22 April 2026 09:30:27 +0000 (0:00:00.354) 0:00:08.196 ******* 2026-04-22 09:30:28.230938 | controller | ok: [controller] 2026-04-22 09:30:28.231062 | controller | 2026-04-22 09:30:28.231237 | controller | TASK [Install plugin] ********************************************************** 2026-04-22 09:30:28.231357 | controller | Wednesday 22 April 2026 09:30:28 +0000 (0:00:00.302) 0:00:08.499 ******* 2026-04-22 09:30:28.291737 | controller | included: vexxhost.containers.download_artifact for controller 2026-04-22 09:30:28.291789 | controller | 2026-04-22 09:30:28.291859 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-22 09:30:28.291975 | controller | Wednesday 22 April 2026 09:30:28 +0000 (0:00:00.058) 0:00:08.558 ******* 2026-04-22 09:30:28.344234 | controller | ok: [controller] => { 2026-04-22 09:30:28.344302 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-04-22 09:30:28.344311 | controller | } 2026-04-22 09:30:28.347419 | controller | 2026-04-22 09:30:28.347451 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-22 09:30:28.347457 | controller | Wednesday 22 April 2026 09:30:28 +0000 (0:00:00.055) 0:00:08.613 ******* 2026-04-22 09:30:29.068317 | controller | changed: [controller] 2026-04-22 09:30:29.068370 | controller | 2026-04-22 09:30:29.068379 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-22 09:30:29.068388 | controller | Wednesday 22 April 2026 09:30:29 +0000 (0:00:00.719) 0:00:09.333 ******* 2026-04-22 09:30:31.244098 | controller | changed: [controller] 2026-04-22 09:30:31.244252 | controller | 2026-04-22 09:30:31.244527 | controller | PLAY RECAP ********************************************************************* 2026-04-22 09:30:31.244735 | controller | controller : ok=16 changed=7 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-22 09:30:31.244911 | controller | 2026-04-22 09:30:31.245095 | controller | 2026-04-22 09:30:31.245287 | controller | TASKS RECAP ******************************************************************** 2026-04-22 09:30:31.245481 | controller | Wednesday 22 April 2026 09:30:31 +0000 (0:00:02.180) 0:00:11.514 ******* 2026-04-22 09:30:31.245649 | controller | =============================================================================== 2026-04-22 09:30:31.245839 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.18s 2026-04-22 09:30:31.246025 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.05s 2026-04-22 09:30:31.246214 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.26s 2026-04-22 09:30:31.246395 | controller | Gathering Facts --------------------------------------------------------- 1.07s 2026-04-22 09:30:31.246581 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.89s 2026-04-22 09:30:31.246766 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.72s 2026-04-22 09:30:31.246952 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.57s 2026-04-22 09:30:31.247137 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.54s 2026-04-22 09:30:31.247322 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.52s 2026-04-22 09:30:31.247566 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.46s 2026-04-22 09:30:31.247743 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.43s 2026-04-22 09:30:31.247939 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.35s 2026-04-22 09:30:31.248136 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.30s 2026-04-22 09:30:31.248390 | controller | Install plugin ---------------------------------------------------------- 0.06s 2026-04-22 09:30:31.248563 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.06s 2026-04-22 09:30:31.248746 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-04-22 09:30:31.391893 | controller | INFO [helm > converge] Executed: Successful 2026-04-22 09:30:31.403042 | controller | INFO [helm > idempotence] Executing 2026-04-22 09:30:32.258294 | controller | 2026-04-22 09:30:32.258508 | controller | PLAY [Converge] **************************************************************** 2026-04-22 09:30:32.258695 | controller | 2026-04-22 09:30:32.258890 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-22 09:30:32.259082 | controller | Wednesday 22 April 2026 09:30:32 +0000 (0:00:00.012) 0:00:00.012 ******* 2026-04-22 09:30:33.368129 | 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 09:30:33.373852 | controller | ok: [controller] 2026-04-22 09:30:33.374012 | controller | 2026-04-22 09:30:33.374228 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-22 09:30:33.374433 | controller | Wednesday 22 April 2026 09:30:33 +0000 (0:00:01.115) 0:00:01.127 ******* 2026-04-22 09:30:33.818476 | controller | ok: [controller] 2026-04-22 09:30:33.818524 | controller | 2026-04-22 09:30:33.818678 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-22 09:30:33.818801 | controller | Wednesday 22 April 2026 09:30:33 +0000 (0:00:00.444) 0:00:01.572 ******* 2026-04-22 09:30:34.866758 | controller | ok: [controller] 2026-04-22 09:30:34.866918 | controller | 2026-04-22 09:30:34.867140 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-04-22 09:30:34.867422 | controller | Wednesday 22 April 2026 09:30:34 +0000 (0:00:01.048) 0:00:02.620 ******* 2026-04-22 09:30:35.290206 | controller | ok: [controller] 2026-04-22 09:30:35.290232 | controller | 2026-04-22 09:30:35.290236 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-22 09:30:35.290240 | controller | Wednesday 22 April 2026 09:30:35 +0000 (0:00:00.422) 0:00:03.043 ******* 2026-04-22 09:30:35.332228 | controller | ok: [controller] => { 2026-04-22 09:30:35.332409 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-04-22 09:30:35.332585 | controller | } 2026-04-22 09:30:35.332754 | controller | 2026-04-22 09:30:35.332945 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-22 09:30:35.333135 | controller | Wednesday 22 April 2026 09:30:35 +0000 (0:00:00.042) 0:00:03.085 ******* 2026-04-22 09:30:35.906714 | controller | ok: [controller] 2026-04-22 09:30:35.906897 | controller | 2026-04-22 09:30:35.907025 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-22 09:30:35.907148 | controller | Wednesday 22 April 2026 09:30:35 +0000 (0:00:00.574) 0:00:03.660 ******* 2026-04-22 09:30:37.619477 | controller | ok: [controller] 2026-04-22 09:30:37.619550 | controller | 2026-04-22 09:30:37.619574 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-04-22 09:30:37.619587 | controller | Wednesday 22 April 2026 09:30:37 +0000 (0:00:01.709) 0:00:05.370 ******* 2026-04-22 09:30:38.139869 | controller | ok: [controller] 2026-04-22 09:30:38.139912 | controller | 2026-04-22 09:30:38.140041 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-04-22 09:30:38.140134 | controller | Wednesday 22 April 2026 09:30:38 +0000 (0:00:00.523) 0:00:05.894 ******* 2026-04-22 09:30:38.631540 | controller | ok: [controller] 2026-04-22 09:30:38.631587 | controller | 2026-04-22 09:30:38.631597 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-04-22 09:30:38.631615 | controller | Wednesday 22 April 2026 09:30:38 +0000 (0:00:00.490) 0:00:06.385 ******* 2026-04-22 09:30:39.095854 | controller | ok: [controller] 2026-04-22 09:30:39.096063 | controller | 2026-04-22 09:30:39.096680 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-04-22 09:30:39.096704 | controller | Wednesday 22 April 2026 09:30:39 +0000 (0:00:00.464) 0:00:06.849 ******* 2026-04-22 09:30:39.387137 | controller | ok: [controller] 2026-04-22 09:30:39.387220 | controller | 2026-04-22 09:30:39.387586 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-04-22 09:30:39.387621 | controller | Wednesday 22 April 2026 09:30:39 +0000 (0:00:00.288) 0:00:07.138 ******* 2026-04-22 09:30:39.696073 | controller | ok: [controller] 2026-04-22 09:30:39.696363 | controller | 2026-04-22 09:30:39.696638 | controller | TASK [Install plugin] ********************************************************** 2026-04-22 09:30:39.696934 | controller | Wednesday 22 April 2026 09:30:39 +0000 (0:00:00.309) 0:00:07.447 ******* 2026-04-22 09:30:39.755916 | controller | included: vexxhost.containers.download_artifact for controller 2026-04-22 09:30:39.755949 | controller | 2026-04-22 09:30:39.756070 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-22 09:30:39.756150 | controller | Wednesday 22 April 2026 09:30:39 +0000 (0:00:00.059) 0:00:07.506 ******* 2026-04-22 09:30:39.810651 | controller | ok: [controller] => { 2026-04-22 09:30:39.810710 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-04-22 09:30:39.810781 | controller | } 2026-04-22 09:30:39.810864 | controller | 2026-04-22 09:30:39.810962 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-22 09:30:39.811101 | controller | Wednesday 22 April 2026 09:30:39 +0000 (0:00:00.057) 0:00:07.564 ******* 2026-04-22 09:30:40.239937 | controller | ok: [controller] 2026-04-22 09:30:40.240025 | controller | 2026-04-22 09:30:40.240133 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-22 09:30:40.240310 | controller | Wednesday 22 April 2026 09:30:40 +0000 (0:00:00.425) 0:00:07.990 ******* 2026-04-22 09:30:41.937769 | controller | ok: [controller] 2026-04-22 09:30:41.937797 | controller | 2026-04-22 09:30:41.937802 | controller | PLAY RECAP ********************************************************************* 2026-04-22 09:30:41.937806 | controller | controller : ok=16 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-22 09:30:41.937809 | controller | 2026-04-22 09:30:41.937813 | controller | 2026-04-22 09:30:41.937816 | controller | TASKS RECAP ******************************************************************** 2026-04-22 09:30:41.937820 | controller | Wednesday 22 April 2026 09:30:41 +0000 (0:00:01.696) 0:00:09.686 ******* 2026-04-22 09:30:41.937823 | controller | =============================================================================== 2026-04-22 09:30:41.937827 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.71s 2026-04-22 09:30:41.937830 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.70s 2026-04-22 09:30:41.937833 | controller | Gathering Facts --------------------------------------------------------- 1.12s 2026-04-22 09:30:41.937836 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.05s 2026-04-22 09:30:41.937839 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.57s 2026-04-22 09:30:41.937843 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.52s 2026-04-22 09:30:41.937846 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.49s 2026-04-22 09:30:41.937849 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.46s 2026-04-22 09:30:41.937853 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.44s 2026-04-22 09:30:41.937856 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.43s 2026-04-22 09:30:41.937867 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.42s 2026-04-22 09:30:41.937870 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.31s 2026-04-22 09:30:41.937873 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.29s 2026-04-22 09:30:41.937876 | controller | Install plugin ---------------------------------------------------------- 0.06s 2026-04-22 09:30:41.937880 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.06s 2026-04-22 09:30:41.937883 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s 2026-04-22 09:30:42.058857 | controller | INFO [helm > idempotence] Executed: Successful 2026-04-22 09:30:42.065189 | controller | INFO [helm > side_effect] Executing 2026-04-22 09:30:42.065555 | controller | WARNING [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-22 09:30:42.070885 | controller | INFO [helm > verify] Executing 2026-04-22 09:30:42.957220 | controller | 2026-04-22 09:30:42.957445 | controller | PLAY [Verify] ****************************************************************** 2026-04-22 09:30:42.957611 | controller | 2026-04-22 09:30:42.957782 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-22 09:30:42.957954 | controller | Wednesday 22 April 2026 09:30:42 +0000 (0:00:00.009) 0:00:00.009 ******* 2026-04-22 09:30:44.011097 | 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 09:30:44.016350 | controller | ok: [controller] 2026-04-22 09:30:44.016362 | controller | 2026-04-22 09:30:44.016366 | controller | TASK [Get the current Helm version] ******************************************** 2026-04-22 09:30:44.016370 | controller | Wednesday 22 April 2026 09:30:44 +0000 (0:00:01.059) 0:00:01.068 ******* 2026-04-22 09:30:44.501907 | controller | changed: [controller] 2026-04-22 09:30:44.501930 | controller | 2026-04-22 09:30:44.501934 | controller | TASK [Assert Helm version] ***************************************************** 2026-04-22 09:30:44.501939 | controller | Wednesday 22 April 2026 09:30:44 +0000 (0:00:00.485) 0:00:01.553 ******* 2026-04-22 09:30:44.533253 | controller | ok: [controller] => { 2026-04-22 09:30:44.533529 | controller | "changed": false, 2026-04-22 09:30:44.533780 | controller | "msg": "All assertions passed" 2026-04-22 09:30:44.534018 | controller | } 2026-04-22 09:30:44.534245 | controller | 2026-04-22 09:30:44.535759 | controller | PLAY RECAP ********************************************************************* 2026-04-22 09:30:44.535860 | controller | controller : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-22 09:30:44.535947 | controller | 2026-04-22 09:30:44.536026 | controller | 2026-04-22 09:30:44.536125 | controller | TASKS RECAP ******************************************************************** 2026-04-22 09:30:44.536221 | controller | Wednesday 22 April 2026 09:30:44 +0000 (0:00:00.031) 0:00:01.585 ******* 2026-04-22 09:30:44.536298 | controller | =============================================================================== 2026-04-22 09:30:44.536386 | controller | Gathering Facts --------------------------------------------------------- 1.06s 2026-04-22 09:30:44.536468 | controller | Get the current Helm version -------------------------------------------- 0.49s 2026-04-22 09:30:44.536554 | controller | Assert Helm version ----------------------------------------------------- 0.03s 2026-04-22 09:30:44.627580 | controller | INFO [helm > verify] Executed: Successful 2026-04-22 09:30:44.637203 | controller | INFO [helm > cleanup] Executing 2026-04-22 09:30:44.637568 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-22 09:30:44.643635 | controller | INFO [helm > destroy] Executing 2026-04-22 09:30:44.643691 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-04-22 09:30:44.643757 | controller | INFO [helm > destroy] Executed: Successful 2026-04-22 09:30:44.644004 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-04-22 09:30:44.835743 | controller | ok: Runtime: 0:00:57.626430 2026-04-22 09:30:44.840650 | 2026-04-22 09:30:44.840711 | PLAY RECAP 2026-04-22 09:30:44.840767 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-22 09:30:44.840797 | 2026-04-22 09:30:44.976629 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-22 09:30:44.978477 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-04-22 09:30:45.577920 | 2026-04-22 09:30:45.578042 | PLAY [all] 2026-04-22 09:30:45.589590 | 2026-04-22 09:30:45.589715 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-04-22 09:30:45.998794 | controller | changed 2026-04-22 09:30:46.005188 | 2026-04-22 09:30:46.005306 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-04-22 09:30:46.397549 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-22 09:30:46.397867 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-22 09:30:46.405347 | controller | environment: line 1: kubectl: command not found 2026-04-22 09:30:46.407035 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-22 09:30:46.408536 | controller | environment: line 1: kubectl: command not found 2026-04-22 09:30:46.410580 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-22 09:30:46.411865 | controller | environment: line 1: kubectl: command not found 2026-04-22 09:30:46.414034 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-22 09:30:46.415446 | controller | environment: line 1: kubectl: command not found 2026-04-22 09:30:46.415848 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-22 09:30:46.416621 | controller | environment: line 1: kubectl: command not found 2026-04-22 09:30:46.417912 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-22 09:30:46.535553 | controller | ok: Runtime: 0:00:00.047934 2026-04-22 09:30:46.541787 | 2026-04-22 09:30:46.541882 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-04-22 09:30:46.777556 | controller | changed 2026-04-22 09:30:46.781465 | 2026-04-22 09:30:46.781541 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-04-22 09:30:47.032723 | controller | environment: line 5: kubectl: command not found 2026-04-22 09:30:47.034348 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-22 09:30:47.034601 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-22 09:30:47.035129 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-22 09:30:47.303912 | controller | ok: Runtime: 0:00:00.014022 2026-04-22 09:30:47.308791 | 2026-04-22 09:30:47.308860 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-04-22 09:30:49.972251 | controller | changed: 2026-04-22 09:30:49.972455 | controller | created directory /var/lib/zuul/builds/9c5a06722c6a484da8e9b48a6e4de80f/work/logs/controller 2026-04-22 09:30:49.972499 | controller | cd+++++++++ objects/ 2026-04-22 09:30:49.972531 | controller | cd+++++++++ objects/cluster/ 2026-04-22 09:30:49.972564 | controller | cd+++++++++ objects/namespaced/ 2026-04-22 09:30:49.980115 | 2026-04-22 09:30:49.980182 | TASK [gather-pod-logs : creating directory for pod logs] 2026-04-22 09:30:50.214077 | controller | changed 2026-04-22 09:30:50.219574 | 2026-04-22 09:30:50.219698 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-04-22 09:30:50.473859 | controller | changed 2026-04-22 09:30:50.479613 | 2026-04-22 09:30:50.479711 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-04-22 09:30:50.799818 | controller | environment: line 3: kubectl: command not found 2026-04-22 09:30:51.010677 | controller | ok: Runtime: 0:00:00.012667 2026-04-22 09:30:51.017516 | 2026-04-22 09:30:51.017623 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-04-22 09:30:51.586917 | controller | changed: 2026-04-22 09:30:51.587223 | controller | cd+++++++++ pod-logs/ 2026-04-22 09:30:51.587292 | controller | cd+++++++++ pod-logs/failed-pods/ 2026-04-22 09:30:51.596760 | 2026-04-22 09:30:51.596823 | LOOP [helm-release-status : creating directory for helm release status] 2026-04-22 09:30:51.863323 | controller | changed: "values" 2026-04-22 09:30:52.079066 | controller | changed: "releases" 2026-04-22 09:30:52.088694 | 2026-04-22 09:30:52.088873 | TASK [helm-release-status : Gather get release status for helm charts] 2026-04-22 09:30:52.331807 | controller | /bin/bash: line 3: kubectl: command not found 2026-04-22 09:30:58.073257 | controller | ok: Runtime: 0:00:00.008580 2026-04-22 09:30:58.080726 | 2026-04-22 09:30:58.080883 | TASK [helm-release-status : Downloads logs to executor] 2026-04-22 09:30:58.667250 | controller | changed: 2026-04-22 09:30:58.667513 | controller | cd+++++++++ helm/ 2026-04-22 09:30:58.667581 | controller | cd+++++++++ helm/releases/ 2026-04-22 09:30:58.667618 | controller | cd+++++++++ helm/values/ 2026-04-22 09:30:58.673749 | 2026-04-22 09:30:58.673795 | PLAY RECAP 2026-04-22 09:30:58.673842 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-22 09:30:58.673866 | 2026-04-22 09:30:58.825104 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-04-22 09:30:58.828311 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-22 09:30:59.399513 | 2026-04-22 09:30:59.399652 | PLAY [all] 2026-04-22 09:30:59.409684 | 2026-04-22 09:30:59.409786 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-22 09:30:59.455098 | controller | skipping: Conditional result was False 2026-04-22 09:30:59.464871 | 2026-04-22 09:30:59.464977 | TASK [fetch-output : Set log path for single node] 2026-04-22 09:30:59.504486 | controller | ok 2026-04-22 09:30:59.509925 | 2026-04-22 09:30:59.510020 | LOOP [fetch-output : Ensure local output dirs] 2026-04-22 09:31:00.123516 | controller -> localhost | ok: "/var/lib/zuul/builds/9c5a06722c6a484da8e9b48a6e4de80f/work/logs" 2026-04-22 09:31:00.351612 | controller -> localhost | changed: "/var/lib/zuul/builds/9c5a06722c6a484da8e9b48a6e4de80f/work/artifacts" 2026-04-22 09:31:00.559227 | controller -> localhost | changed: "/var/lib/zuul/builds/9c5a06722c6a484da8e9b48a6e4de80f/work/docs" 2026-04-22 09:31:00.581295 | 2026-04-22 09:31:00.581483 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-22 09:31:01.296996 | controller | changed: .d..t...... ./ 2026-04-22 09:31:01.297376 | controller | changed: All items complete 2026-04-22 09:31:01.297433 | 2026-04-22 09:31:01.796578 | controller | changed: .d..t...... ./ 2026-04-22 09:31:02.338851 | controller | changed: .d..t...... ./ 2026-04-22 09:31:02.349019 | 2026-04-22 09:31:02.349090 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-22 09:31:02.764759 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.005814 2026-04-22 09:31:03.000179 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.005806 2026-04-22 09:31:03.018317 | 2026-04-22 09:31:03.018418 | PLAY [all] 2026-04-22 09:31:03.024444 | 2026-04-22 09:31:03.024508 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-22 09:31:03.550019 | controller | changed 2026-04-22 09:31:03.555580 | 2026-04-22 09:31:03.555629 | PLAY RECAP 2026-04-22 09:31:03.555674 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-22 09:31:03.555696 | 2026-04-22 09:31:03.704004 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-22 09:31:03.706113 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-22 09:31:04.262403 | 2026-04-22 09:31:04.380194 | PLAY [localhost] 2026-04-22 09:31:04.396284 | 2026-04-22 09:31:04.396410 | TASK [Generate Zuul manifest] 2026-04-22 09:31:04.418814 | localhost | ok 2026-04-22 09:31:04.436209 | 2026-04-22 09:31:04.436351 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-22 09:31:04.876276 | localhost | changed 2026-04-22 09:31:04.889906 | 2026-04-22 09:31:04.889991 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-22 09:31:04.923231 | localhost | ok 2026-04-22 09:31:04.930516 | 2026-04-22 09:31:04.930585 | TASK [Upload logs] 2026-04-22 09:31:04.952061 | localhost | ok 2026-04-22 09:31:05.028956 | 2026-04-22 09:31:05.029118 | TASK [Set zuul-log-path fact] 2026-04-22 09:31:05.047253 | localhost | ok 2026-04-22 09:31:05.061029 | 2026-04-22 09:31:05.061143 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-22 09:31:05.091530 | localhost | ok 2026-04-22 09:31:05.100187 | 2026-04-22 09:31:05.100263 | TASK [upload-logs : Create log directories] 2026-04-22 09:31:05.514462 | localhost | changed 2026-04-22 09:31:05.521338 | 2026-04-22 09:31:05.521421 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-22 09:31:05.963497 | localhost -> localhost | ok: Runtime: 0:00:00.006104 2026-04-22 09:31:05.969179 | 2026-04-22 09:31:05.969250 | TASK [upload-logs : Upload logs to log server] 2026-04-22 09:31:06.491427 | localhost | Output suppressed because no_log was given 2026-04-22 09:31:06.494996 | 2026-04-22 09:31:06.495070 | LOOP [upload-logs : Compress console log and json output] 2026-04-22 09:31:06.535653 | localhost | skipping: Conditional result was False 2026-04-22 09:31:06.542231 | localhost | skipping: Conditional result was False 2026-04-22 09:31:06.559066 | 2026-04-22 09:31:06.559207 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-22 09:31:06.592558 | localhost | skipping: Conditional result was False 2026-04-22 09:31:06.592856 | 2026-04-22 09:31:06.597057 | localhost | skipping: Conditional result was False 2026-04-22 09:31:06.611240 | 2026-04-22 09:31:06.611382 | LOOP [upload-logs : Upload console log and json output]