2026-04-29 21:39:54.786373 | Job console starting 2026-04-29 21:39:54.801346 | Updating git repos 2026-04-29 21:39:54.845066 | Cloning repos into workspace 2026-04-29 21:39:55.037960 | Restoring repo states 2026-04-29 21:39:55.054714 | Merging changes 2026-04-29 21:39:55.954944 | Checking out repos 2026-04-29 21:39:56.223080 | Preparing playbooks 2026-04-29 21:40:08.757513 | Running Ansible setup 2026-04-29 21:40:15.473921 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-29 21:40:16.120663 | 2026-04-29 21:40:16.120799 | PLAY [localhost] 2026-04-29 21:40:16.128296 | 2026-04-29 21:40:16.128366 | TASK [Gathering Facts] 2026-04-29 21:40:17.226962 | localhost | ok 2026-04-29 21:40:17.237587 | 2026-04-29 21:40:17.237681 | TASK [Setup log path fact] 2026-04-29 21:40:17.258459 | localhost | ok 2026-04-29 21:40:17.272348 | 2026-04-29 21:40:17.272472 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-29 21:40:17.302276 | localhost | ok 2026-04-29 21:40:17.310267 | 2026-04-29 21:40:17.310344 | TASK [emit-job-header : Print job information] 2026-04-29 21:40:17.353471 | # Job Information 2026-04-29 21:40:17.355421 | Ansible Version: 2.16.18 2026-04-29 21:40:17.355513 | Job: ansible-collection-kubernetes-molecule-helm 2026-04-29 21:40:17.355556 | Pipeline: check 2026-04-29 21:40:17.355592 | Executor: 2d72f0692154 2026-04-29 21:40:17.355627 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/271 2026-04-29 21:40:17.355664 | Event ID: d5e5a360-4413-11f1-919f-4bd0ddffef59 2026-04-29 21:40:17.361590 | 2026-04-29 21:40:17.361674 | LOOP [emit-job-header : Print node information] 2026-04-29 21:40:17.454422 | localhost | ok: 2026-04-29 21:40:17.454590 | localhost | # Node Information 2026-04-29 21:40:17.454618 | localhost | Inventory Hostname: controller 2026-04-29 21:40:17.454640 | localhost | Hostname: np0000171337 2026-04-29 21:40:17.454660 | localhost | Username: zuul 2026-04-29 21:40:17.454682 | localhost | Distro: Debian 13.4 2026-04-29 21:40:17.454702 | localhost | Provider: yul1 2026-04-29 21:40:17.454721 | localhost | Region: ca-ymq-1 2026-04-29 21:40:17.454739 | localhost | Label: debian-trixie 2026-04-29 21:40:17.454757 | localhost | Product Name: OpenStack Nova 2026-04-29 21:40:17.454775 | localhost | Interface IP: 199.204.45.244 2026-04-29 21:40:17.477531 | 2026-04-29 21:40:17.477675 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-29 21:40:17.909768 | localhost -> localhost | changed 2026-04-29 21:40:17.915325 | 2026-04-29 21:40:17.915399 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-29 21:40:18.792638 | localhost -> localhost | changed 2026-04-29 21:40:18.804034 | 2026-04-29 21:40:18.804138 | PLAY [all] 2026-04-29 21:40:18.811749 | 2026-04-29 21:40:18.811833 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-29 21:40:19.051587 | controller -> localhost | ok 2026-04-29 21:40:19.061369 | 2026-04-29 21:40:19.061536 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-29 21:40:19.093921 | controller | ok 2026-04-29 21:40:19.109248 | controller | included: /var/lib/zuul/builds/a5eeaa03328a46e5a953d6d882ef5a56/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-29 21:40:19.114260 | 2026-04-29 21:40:19.114335 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-29 21:40:20.364368 | controller -> localhost | Generating public/private rsa key pair. 2026-04-29 21:40:20.364595 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/a5eeaa03328a46e5a953d6d882ef5a56/work/a5eeaa03328a46e5a953d6d882ef5a56_id_rsa 2026-04-29 21:40:20.364627 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/a5eeaa03328a46e5a953d6d882ef5a56/work/a5eeaa03328a46e5a953d6d882ef5a56_id_rsa.pub 2026-04-29 21:40:20.364653 | controller -> localhost | The key fingerprint is: 2026-04-29 21:40:20.364680 | controller -> localhost | SHA256:q7GCbGdy7M4dXcAYEUoXk5DpgB7I38RqD3yYCX2I/p4 zuul-build-sshkey 2026-04-29 21:40:20.364727 | controller -> localhost | The key's randomart image is: 2026-04-29 21:40:20.364751 | controller -> localhost | +---[RSA 3072]----+ 2026-04-29 21:40:20.364781 | controller -> localhost | |o.o =+B= | 2026-04-29 21:40:20.364805 | controller -> localhost | |o=.+o*.= | 2026-04-29 21:40:20.364828 | controller -> localhost | |o =oX . o | 2026-04-29 21:40:20.365178 | controller -> localhost | | o X.o . | 2026-04-29 21:40:20.365208 | controller -> localhost | | o + S . | 2026-04-29 21:40:20.365232 | controller -> localhost | | . . . o | 2026-04-29 21:40:20.365253 | controller -> localhost | | ..o. o o | 2026-04-29 21:40:20.365275 | controller -> localhost | | =E*. = | 2026-04-29 21:40:20.365382 | controller -> localhost | | . B+.+ | 2026-04-29 21:40:20.365407 | controller -> localhost | +----[SHA256]-----+ 2026-04-29 21:40:20.365467 | controller -> localhost | ok: Runtime: 0:00:00.815794 2026-04-29 21:40:20.369919 | 2026-04-29 21:40:20.369983 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-29 21:40:20.403137 | controller | ok 2026-04-29 21:40:20.412425 | controller | included: /var/lib/zuul/builds/a5eeaa03328a46e5a953d6d882ef5a56/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-29 21:40:20.419596 | 2026-04-29 21:40:20.419700 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-29 21:40:20.445726 | controller | skipping: Conditional result was False 2026-04-29 21:40:20.455453 | 2026-04-29 21:40:20.455565 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-29 21:40:20.992343 | controller | changed 2026-04-29 21:40:20.998588 | 2026-04-29 21:40:20.998659 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-29 21:40:21.212415 | controller | ok 2026-04-29 21:40:21.217320 | 2026-04-29 21:40:21.217392 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-29 21:40:21.777881 | controller | changed 2026-04-29 21:40:22.094730 | 2026-04-29 21:40:22.094915 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-29 21:40:22.623327 | controller | changed 2026-04-29 21:40:22.627633 | 2026-04-29 21:40:22.627705 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-29 21:40:22.653825 | controller | skipping: Conditional result was False 2026-04-29 21:40:22.658895 | 2026-04-29 21:40:22.658966 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-29 21:40:23.161500 | controller -> localhost | changed 2026-04-29 21:40:23.177341 | 2026-04-29 21:40:23.177511 | TASK [add-build-sshkey : Add back temp key] 2026-04-29 21:40:23.747166 | controller -> localhost | Identity added: /var/lib/zuul/builds/a5eeaa03328a46e5a953d6d882ef5a56/work/a5eeaa03328a46e5a953d6d882ef5a56_id_rsa (zuul-build-sshkey) 2026-04-29 21:40:23.747399 | controller -> localhost | ok: Runtime: 0:00:00.012901 2026-04-29 21:40:23.752390 | 2026-04-29 21:40:23.752474 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-29 21:40:24.202005 | controller | ok 2026-04-29 21:40:24.394746 | 2026-04-29 21:40:24.394894 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-29 21:40:24.421180 | controller | skipping: Conditional result was False 2026-04-29 21:40:24.432354 | 2026-04-29 21:40:24.432536 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-29 21:40:24.828486 | controller | ok 2026-04-29 21:40:24.835923 | 2026-04-29 21:40:24.835984 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-29 21:40:25.934631 | controller | Output suppressed because no_log was given 2026-04-29 21:40:26.074215 | 2026-04-29 21:40:26.074315 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-29 21:40:26.294017 | controller | ok: "logs" 2026-04-29 21:40:26.294349 | controller | ok: All items complete 2026-04-29 21:40:26.294388 | 2026-04-29 21:40:26.488120 | controller | ok: "artifacts" 2026-04-29 21:40:26.743534 | controller | ok: "docs" 2026-04-29 21:40:26.754062 | 2026-04-29 21:40:26.754247 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-29 21:40:26.966773 | controller | changed: "logs" 2026-04-29 21:40:27.157354 | controller | changed: "artifacts" 2026-04-29 21:40:27.350364 | controller | changed: "docs" 2026-04-29 21:40:27.467307 | 2026-04-29 21:40:27.467455 | PLAY RECAP 2026-04-29 21:40:27.467526 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-29 21:40:27.467570 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-29 21:40:27.467603 | 2026-04-29 21:40:27.662624 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-29 21:40:27.665353 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-29 21:40:28.326534 | 2026-04-29 21:40:28.326675 | PLAY [all] 2026-04-29 21:40:28.337635 | 2026-04-29 21:40:28.337718 | TASK [setup-uv : Extract archive] 2026-04-29 21:40:30.684657 | controller | changed 2026-04-29 21:40:30.689945 | 2026-04-29 21:40:30.690027 | TASK [setup-uv : Print version] 2026-04-29 21:40:31.115639 | controller | uv 0.8.13 2026-04-29 21:40:31.231458 | controller | ok: Runtime: 0:00:00.015997 2026-04-29 21:40:31.237248 | 2026-04-29 21:40:31.237357 | PLAY RECAP 2026-04-29 21:40:31.237406 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-29 21:40:31.237431 | 2026-04-29 21:40:31.407096 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-29 21:40:31.409547 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-04-29 21:40:32.051190 | 2026-04-29 21:40:32.051815 | PLAY [all] 2026-04-29 21:40:32.063073 | 2026-04-29 21:40:32.063152 | TASK [Update apt cache] 2026-04-29 21:40:37.962691 | controller | ok 2026-04-29 21:40:37.981339 | 2026-04-29 21:40:37.981531 | TASK [Disable firewalld] 2026-04-29 21:40:38.006741 | controller | skipping: Conditional result was False 2026-04-29 21:40:38.013577 | 2026-04-29 21:40:38.013673 | TASK [Install "jq" for log collection] 2026-04-29 21:40:40.406845 | controller | changed 2026-04-29 21:40:40.735342 | 2026-04-29 21:40:40.735498 | PLAY RECAP 2026-04-29 21:40:40.735598 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-29 21:40:40.735648 | 2026-04-29 21:40:40.918717 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main] 2026-04-29 21:40:40.921627 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-29 21:40:41.550621 | 2026-04-29 21:40:41.550860 | PLAY [all] 2026-04-29 21:40:41.561733 | 2026-04-29 21:40:41.561816 | TASK [Copy inventory file for Zuul] 2026-04-29 21:40:42.542064 | controller | changed 2026-04-29 21:40:42.547191 | 2026-04-29 21:40:42.547270 | TASK [Switch "ansible_host" to private IP] 2026-04-29 21:40:42.973892 | controller | changed: 1 replacements made 2026-04-29 21:40:42.978526 | 2026-04-29 21:40:42.978595 | TASK [Run Molecule scenario] 2026-04-29 21:40:43.416314 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-04-29 21:40:43.416491 | controller | Creating virtual environment at: .venv 2026-04-29 21:40:43.691945 | controller | Downloading ansible-core (2.3MiB) 2026-04-29 21:40:43.692110 | controller | Downloading cryptography (4.3MiB) 2026-04-29 21:40:43.692597 | controller | Downloading pygments (1.2MiB) 2026-04-29 21:40:43.863966 | controller | Downloading cryptography 2026-04-29 21:40:43.926168 | controller | Downloading pygments 2026-04-29 21:40:44.165041 | controller | Downloading ansible-core 2026-04-29 21:40:44.216226 | controller | Installed 36 packages in 49ms 2026-04-29 21:40:44.894935 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-04-29 21:40:44.894999 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-04-29 21:40:45.826651 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-04-29 21:40:45.826721 | controller | INFO default scenario not found, disabling shared state. 2026-04-29 21:40:45.826743 | controller | INFO [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-04-29 21:40:45.826828 | controller | INFO [helm > prerun] Performing prerun with role_name_check=0... 2026-04-29 21:41:02.259816 | controller | INFO [helm > dependency] Executing 2026-04-29 21:41:02.260027 | controller | WARNING [helm > dependency] Missing roles requirements file: requirements.yml 2026-04-29 21:41:02.260407 | controller | WARNING [helm > dependency] Missing collections requirements file: collections.yml 2026-04-29 21:41:02.260562 | controller | WARNING [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-04-29 21:41:02.266168 | controller | INFO [helm > cleanup] Executing 2026-04-29 21:41:02.266523 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-29 21:41:02.271865 | controller | INFO [helm > destroy] Executing 2026-04-29 21:41:02.271893 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-04-29 21:41:02.271987 | controller | INFO [helm > destroy] Executed: Successful 2026-04-29 21:41:02.277321 | controller | INFO [helm > syntax] Executing 2026-04-29 21:41:03.197089 | controller | 2026-04-29 21:41:03.197368 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml 2026-04-29 21:41:03.257580 | controller | INFO [helm > syntax] Executed: Successful 2026-04-29 21:41:03.267763 | controller | INFO [helm > create] Executing 2026-04-29 21:41:03.269277 | controller | WARNING [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-29 21:41:03.274676 | controller | INFO [helm > prepare] Executing 2026-04-29 21:41:04.113974 | controller | 2026-04-29 21:41:04.114126 | controller | PLAY [Prepare] ***************************************************************** 2026-04-29 21:41:04.114303 | controller | 2026-04-29 21:41:04.114532 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-29 21:41:04.114788 | controller | Wednesday 29 April 2026 21:41:04 +0000 (0:00:00.032) 0:00:00.032 ******* 2026-04-29 21:41:06.403256 | 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-29 21:41:06.412470 | controller | ok: [controller] 2026-04-29 21:41:06.412651 | controller | 2026-04-29 21:41:06.412872 | controller | TASK [Install Debian packages] ************************************************* 2026-04-29 21:41:06.413081 | controller | Wednesday 29 April 2026 21:41:06 +0000 (0:00:02.298) 0:00:02.331 ******* 2026-04-29 21:41:07.845647 | controller | ok: [controller] 2026-04-29 21:41:07.845815 | controller | 2026-04-29 21:41:07.846026 | controller | PLAY RECAP ********************************************************************* 2026-04-29 21:41:07.846304 | controller | controller : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-29 21:41:07.846564 | controller | 2026-04-29 21:41:07.846800 | controller | 2026-04-29 21:41:07.847109 | controller | TASKS RECAP ******************************************************************** 2026-04-29 21:41:07.847352 | controller | Wednesday 29 April 2026 21:41:07 +0000 (0:00:01.433) 0:00:03.764 ******* 2026-04-29 21:41:07.847536 | controller | =============================================================================== 2026-04-29 21:41:07.847806 | controller | Gathering Facts --------------------------------------------------------- 2.30s 2026-04-29 21:41:07.848061 | controller | Install Debian packages ------------------------------------------------- 1.43s 2026-04-29 21:41:07.948906 | controller | INFO [helm > prepare] Executed: Successful 2026-04-29 21:41:07.958421 | controller | INFO [helm > converge] Executing 2026-04-29 21:41:08.805318 | controller | 2026-04-29 21:41:08.805461 | controller | PLAY [Converge] **************************************************************** 2026-04-29 21:41:08.805637 | controller | 2026-04-29 21:41:08.805845 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-29 21:41:08.806039 | controller | Wednesday 29 April 2026 21:41:08 +0000 (0:00:00.013) 0:00:00.013 ******* 2026-04-29 21:41:09.907553 | 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-29 21:41:09.913476 | controller | ok: [controller] 2026-04-29 21:41:09.913706 | controller | 2026-04-29 21:41:09.913957 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-29 21:41:09.914161 | controller | Wednesday 29 April 2026 21:41:09 +0000 (0:00:01.107) 0:00:01.121 ******* 2026-04-29 21:41:10.401931 | controller | ok: [controller] 2026-04-29 21:41:10.402001 | controller | 2026-04-29 21:41:10.402228 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-29 21:41:10.402431 | controller | Wednesday 29 April 2026 21:41:10 +0000 (0:00:00.488) 0:00:01.609 ******* 2026-04-29 21:41:11.580177 | controller | ok: [controller] 2026-04-29 21:41:11.580282 | controller | 2026-04-29 21:41:11.580500 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-04-29 21:41:11.580701 | controller | Wednesday 29 April 2026 21:41:11 +0000 (0:00:01.178) 0:00:02.787 ******* 2026-04-29 21:41:12.004676 | controller | changed: [controller] 2026-04-29 21:41:12.004759 | controller | 2026-04-29 21:41:12.004899 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-29 21:41:12.005037 | controller | Wednesday 29 April 2026 21:41:11 +0000 (0:00:00.425) 0:00:03.212 ******* 2026-04-29 21:41:12.058447 | controller | ok: [controller] => { 2026-04-29 21:41:12.058651 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-04-29 21:41:12.058848 | controller | } 2026-04-29 21:41:12.059028 | controller | 2026-04-29 21:41:12.059233 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-29 21:41:12.059432 | controller | Wednesday 29 April 2026 21:41:12 +0000 (0:00:00.053) 0:00:03.266 ******* 2026-04-29 21:41:13.301532 | 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-29 21:41:13.312599 | controller | changed: [controller] 2026-04-29 21:41:13.312686 | controller | 2026-04-29 21:41:13.312831 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-29 21:41:13.312953 | controller | Wednesday 29 April 2026 21:41:13 +0000 (0:00:01.254) 0:00:04.520 ******* 2026-04-29 21:41:15.436389 | controller | changed: [controller] 2026-04-29 21:41:15.436461 | controller | 2026-04-29 21:41:15.436536 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-04-29 21:41:15.439790 | controller | Wednesday 29 April 2026 21:41:15 +0000 (0:00:02.121) 0:00:06.642 ******* 2026-04-29 21:41:16.005418 | controller | ok: [controller] 2026-04-29 21:41:16.005451 | controller | 2026-04-29 21:41:16.005455 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-04-29 21:41:16.005460 | controller | Wednesday 29 April 2026 21:41:15 +0000 (0:00:00.570) 0:00:07.213 ******* 2026-04-29 21:41:16.444703 | controller | changed: [controller] 2026-04-29 21:41:16.444740 | controller | 2026-04-29 21:41:16.444747 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-04-29 21:41:16.444753 | controller | Wednesday 29 April 2026 21:41:16 +0000 (0:00:00.436) 0:00:07.649 ******* 2026-04-29 21:41:16.918501 | controller | ok: [controller] 2026-04-29 21:41:16.918784 | controller | 2026-04-29 21:41:16.919063 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-04-29 21:41:16.919351 | controller | Wednesday 29 April 2026 21:41:16 +0000 (0:00:00.476) 0:00:08.126 ******* 2026-04-29 21:41:17.222185 | controller | changed: [controller] 2026-04-29 21:41:17.222344 | controller | 2026-04-29 21:41:17.222479 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-04-29 21:41:17.222601 | controller | Wednesday 29 April 2026 21:41:17 +0000 (0:00:00.303) 0:00:08.429 ******* 2026-04-29 21:41:17.520463 | controller | ok: [controller] 2026-04-29 21:41:17.520746 | controller | 2026-04-29 21:41:17.521009 | controller | TASK [Install plugin] ********************************************************** 2026-04-29 21:41:17.521263 | controller | Wednesday 29 April 2026 21:41:17 +0000 (0:00:00.298) 0:00:08.728 ******* 2026-04-29 21:41:17.571155 | controller | included: vexxhost.containers.download_artifact for controller 2026-04-29 21:41:17.571418 | controller | 2026-04-29 21:41:17.571680 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-29 21:41:17.571970 | controller | Wednesday 29 April 2026 21:41:17 +0000 (0:00:00.050) 0:00:08.779 ******* 2026-04-29 21:41:17.624743 | controller | ok: [controller] => { 2026-04-29 21:41:17.624988 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-04-29 21:41:17.625164 | controller | } 2026-04-29 21:41:17.625333 | controller | 2026-04-29 21:41:17.625563 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-29 21:41:17.625750 | controller | Wednesday 29 April 2026 21:41:17 +0000 (0:00:00.052) 0:00:08.832 ******* 2026-04-29 21:41:18.536586 | controller | changed: [controller] 2026-04-29 21:41:18.536879 | controller | 2026-04-29 21:41:18.537160 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-29 21:41:18.537430 | controller | Wednesday 29 April 2026 21:41:18 +0000 (0:00:00.911) 0:00:09.743 ******* 2026-04-29 21:41:21.643818 | controller | changed: [controller] 2026-04-29 21:41:21.644101 | controller | 2026-04-29 21:41:21.644361 | controller | PLAY RECAP ********************************************************************* 2026-04-29 21:41:21.644634 | controller | controller : ok=16 changed=7 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-29 21:41:21.644865 | controller | 2026-04-29 21:41:21.645093 | controller | 2026-04-29 21:41:21.645336 | controller | TASKS RECAP ******************************************************************** 2026-04-29 21:41:21.645588 | controller | Wednesday 29 April 2026 21:41:21 +0000 (0:00:03.108) 0:00:12.852 ******* 2026-04-29 21:41:21.645825 | controller | =============================================================================== 2026-04-29 21:41:21.646073 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 3.11s 2026-04-29 21:41:21.646318 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.12s 2026-04-29 21:41:21.646563 | controller | vexxhost.containers.download_artifact : Download item ------------------- 1.25s 2026-04-29 21:41:21.646808 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.18s 2026-04-29 21:41:21.647050 | controller | Gathering Facts --------------------------------------------------------- 1.11s 2026-04-29 21:41:21.647297 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.91s 2026-04-29 21:41:21.647573 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.57s 2026-04-29 21:41:21.647938 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.49s 2026-04-29 21:41:21.648201 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.48s 2026-04-29 21:41:21.648449 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.44s 2026-04-29 21:41:21.648715 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.43s 2026-04-29 21:41:21.648973 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.30s 2026-04-29 21:41:21.649232 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.30s 2026-04-29 21:41:21.649476 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-04-29 21:41:21.649718 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-04-29 21:41:21.649956 | controller | Install plugin ---------------------------------------------------------- 0.05s 2026-04-29 21:41:21.767776 | controller | INFO [helm > converge] Executed: Successful 2026-04-29 21:41:21.777519 | controller | INFO [helm > idempotence] Executing 2026-04-29 21:41:22.686853 | controller | 2026-04-29 21:41:22.686920 | controller | PLAY [Converge] **************************************************************** 2026-04-29 21:41:22.687015 | controller | 2026-04-29 21:41:22.687149 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-29 21:41:22.687284 | controller | Wednesday 29 April 2026 21:41:22 +0000 (0:00:00.015) 0:00:00.015 ******* 2026-04-29 21:41:23.772000 | 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-29 21:41:23.780195 | controller | ok: [controller] 2026-04-29 21:41:23.780277 | controller | 2026-04-29 21:41:23.780372 | controller | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-29 21:41:23.780463 | controller | Wednesday 29 April 2026 21:41:23 +0000 (0:00:01.090) 0:00:01.106 ******* 2026-04-29 21:41:24.256697 | controller | ok: [controller] 2026-04-29 21:41:24.256752 | controller | 2026-04-29 21:41:24.259789 | controller | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-29 21:41:24.259826 | controller | Wednesday 29 April 2026 21:41:24 +0000 (0:00:00.479) 0:00:01.585 ******* 2026-04-29 21:41:25.360195 | controller | ok: [controller] 2026-04-29 21:41:25.360271 | controller | 2026-04-29 21:41:25.360283 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-04-29 21:41:25.360379 | controller | Wednesday 29 April 2026 21:41:25 +0000 (0:00:01.100) 0:00:02.685 ******* 2026-04-29 21:41:25.785854 | controller | ok: [controller] 2026-04-29 21:41:25.785910 | controller | 2026-04-29 21:41:25.786041 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-29 21:41:25.786203 | controller | Wednesday 29 April 2026 21:41:25 +0000 (0:00:00.428) 0:00:03.114 ******* 2026-04-29 21:41:25.835514 | controller | ok: [controller] => { 2026-04-29 21:41:25.835572 | controller | "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz" 2026-04-29 21:41:25.835663 | controller | } 2026-04-29 21:41:25.835733 | controller | 2026-04-29 21:41:25.835961 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-29 21:41:25.835982 | controller | Wednesday 29 April 2026 21:41:25 +0000 (0:00:00.046) 0:00:03.160 ******* 2026-04-29 21:41:26.408787 | controller | ok: [controller] 2026-04-29 21:41:26.408940 | controller | 2026-04-29 21:41:26.409066 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-29 21:41:26.409187 | controller | Wednesday 29 April 2026 21:41:26 +0000 (0:00:00.576) 0:00:03.737 ******* 2026-04-29 21:41:28.128212 | controller | ok: [controller] 2026-04-29 21:41:28.128286 | controller | 2026-04-29 21:41:28.128369 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] *********************** 2026-04-29 21:41:28.128492 | controller | Wednesday 29 April 2026 21:41:28 +0000 (0:00:01.717) 0:00:05.454 ******* 2026-04-29 21:41:28.653221 | controller | ok: [controller] 2026-04-29 21:41:28.653299 | controller | 2026-04-29 21:41:28.653374 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] *************** 2026-04-29 21:41:28.653500 | controller | Wednesday 29 April 2026 21:41:28 +0000 (0:00:00.526) 0:00:05.981 ******* 2026-04-29 21:41:29.062850 | controller | ok: [controller] 2026-04-29 21:41:29.062946 | controller | 2026-04-29 21:41:29.062964 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] ************************* 2026-04-29 21:41:29.063122 | controller | Wednesday 29 April 2026 21:41:29 +0000 (0:00:00.409) 0:00:06.391 ******* 2026-04-29 21:41:29.532631 | controller | ok: [controller] 2026-04-29 21:41:29.532661 | controller | 2026-04-29 21:41:29.532665 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] *** 2026-04-29 21:41:29.532675 | controller | Wednesday 29 April 2026 21:41:29 +0000 (0:00:00.464) 0:00:06.856 ******* 2026-04-29 21:41:29.840540 | controller | ok: [controller] 2026-04-29 21:41:29.840567 | controller | 2026-04-29 21:41:29.840573 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] *** 2026-04-29 21:41:29.840578 | controller | Wednesday 29 April 2026 21:41:29 +0000 (0:00:00.309) 0:00:07.165 ******* 2026-04-29 21:41:30.153557 | controller | ok: [controller] 2026-04-29 21:41:30.153616 | controller | 2026-04-29 21:41:30.155859 | controller | TASK [Install plugin] ********************************************************** 2026-04-29 21:41:30.155905 | controller | Wednesday 29 April 2026 21:41:30 +0000 (0:00:00.316) 0:00:07.482 ******* 2026-04-29 21:41:30.223454 | controller | included: vexxhost.containers.download_artifact for controller 2026-04-29 21:41:30.223523 | controller | 2026-04-29 21:41:30.223678 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-29 21:41:30.223902 | controller | Wednesday 29 April 2026 21:41:30 +0000 (0:00:00.069) 0:00:07.551 ******* 2026-04-29 21:41:30.276737 | controller | ok: [controller] => { 2026-04-29 21:41:30.276765 | controller | "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz" 2026-04-29 21:41:30.276770 | controller | } 2026-04-29 21:41:30.276774 | controller | 2026-04-29 21:41:30.276778 | controller | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-29 21:41:30.276782 | controller | Wednesday 29 April 2026 21:41:30 +0000 (0:00:00.050) 0:00:07.602 ******* 2026-04-29 21:41:30.728548 | controller | ok: [controller] 2026-04-29 21:41:30.728607 | controller | 2026-04-29 21:41:30.728751 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-29 21:41:30.728869 | controller | Wednesday 29 April 2026 21:41:30 +0000 (0:00:00.450) 0:00:08.052 ******* 2026-04-29 21:41:32.423820 | controller | ok: [controller] 2026-04-29 21:41:32.423859 | controller | 2026-04-29 21:41:32.423868 | controller | PLAY RECAP ********************************************************************* 2026-04-29 21:41:32.423877 | controller | controller : ok=16 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-29 21:41:32.423885 | controller | 2026-04-29 21:41:32.423892 | controller | 2026-04-29 21:41:32.423899 | controller | TASKS RECAP ******************************************************************** 2026-04-29 21:41:32.423907 | controller | Wednesday 29 April 2026 21:41:32 +0000 (0:00:01.695) 0:00:09.748 ******* 2026-04-29 21:41:32.423914 | controller | =============================================================================== 2026-04-29 21:41:32.423921 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.72s 2026-04-29 21:41:32.423929 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.70s 2026-04-29 21:41:32.423936 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.10s 2026-04-29 21:41:32.423943 | controller | Gathering Facts --------------------------------------------------------- 1.09s 2026-04-29 21:41:32.423951 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.58s 2026-04-29 21:41:32.423958 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.53s 2026-04-29 21:41:32.423965 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.48s 2026-04-29 21:41:32.423972 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.47s 2026-04-29 21:41:32.423981 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.45s 2026-04-29 21:41:32.423989 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.43s 2026-04-29 21:41:32.424011 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.41s 2026-04-29 21:41:32.424018 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.32s 2026-04-29 21:41:32.424026 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.31s 2026-04-29 21:41:32.424076 | controller | Install plugin ---------------------------------------------------------- 0.07s 2026-04-29 21:41:32.424360 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-04-29 21:41:32.424725 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s 2026-04-29 21:41:32.542783 | controller | INFO [helm > idempotence] Executed: Successful 2026-04-29 21:41:32.551913 | controller | INFO [helm > side_effect] Executing 2026-04-29 21:41:32.552243 | controller | WARNING [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-29 21:41:32.559054 | controller | INFO [helm > verify] Executing 2026-04-29 21:41:33.484736 | controller | 2026-04-29 21:41:33.484824 | controller | PLAY [Verify] ****************************************************************** 2026-04-29 21:41:33.485007 | controller | 2026-04-29 21:41:33.485210 | controller | TASK [Gathering Facts] ********************************************************* 2026-04-29 21:41:33.485410 | controller | Wednesday 29 April 2026 21:41:33 +0000 (0:00:00.010) 0:00:00.010 ******* 2026-04-29 21:41:34.591578 | 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-29 21:41:34.598419 | controller | ok: [controller] 2026-04-29 21:41:34.598512 | controller | 2026-04-29 21:41:34.598622 | controller | TASK [Get the current Helm version] ******************************************** 2026-04-29 21:41:34.598731 | controller | Wednesday 29 April 2026 21:41:34 +0000 (0:00:01.113) 0:00:01.124 ******* 2026-04-29 21:41:35.084141 | controller | changed: [controller] 2026-04-29 21:41:35.084224 | controller | 2026-04-29 21:41:35.084233 | controller | TASK [Assert Helm version] ***************************************************** 2026-04-29 21:41:35.084317 | controller | Wednesday 29 April 2026 21:41:35 +0000 (0:00:00.483) 0:00:01.607 ******* 2026-04-29 21:41:35.112506 | controller | ok: [controller] => { 2026-04-29 21:41:35.112692 | controller | "changed": false, 2026-04-29 21:41:35.115239 | controller | "msg": "All assertions passed" 2026-04-29 21:41:35.115282 | controller | } 2026-04-29 21:41:35.115292 | controller | 2026-04-29 21:41:35.115300 | controller | PLAY RECAP ********************************************************************* 2026-04-29 21:41:35.115308 | controller | controller : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-29 21:41:35.115314 | controller | 2026-04-29 21:41:35.115319 | controller | 2026-04-29 21:41:35.115325 | controller | TASKS RECAP ******************************************************************** 2026-04-29 21:41:35.115330 | controller | Wednesday 29 April 2026 21:41:35 +0000 (0:00:00.030) 0:00:01.638 ******* 2026-04-29 21:41:35.115335 | controller | =============================================================================== 2026-04-29 21:41:35.115340 | controller | Gathering Facts --------------------------------------------------------- 1.11s 2026-04-29 21:41:35.115345 | controller | Get the current Helm version -------------------------------------------- 0.48s 2026-04-29 21:41:35.115350 | controller | Assert Helm version ----------------------------------------------------- 0.03s 2026-04-29 21:41:35.251806 | controller | INFO [helm > verify] Executed: Successful 2026-04-29 21:41:35.261068 | controller | INFO [helm > cleanup] Executing 2026-04-29 21:41:35.261378 | controller | WARNING [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-29 21:41:35.266952 | controller | INFO [helm > destroy] Executing 2026-04-29 21:41:35.267040 | controller | WARNING [helm > destroy] Skipping, '--destroy=never' requested. 2026-04-29 21:41:35.267108 | controller | INFO [helm > destroy] Executed: Successful 2026-04-29 21:41:35.267358 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-04-29 21:41:35.552365 | controller | ok: Runtime: 0:00:52.015229 2026-04-29 21:41:35.558186 | 2026-04-29 21:41:35.558284 | PLAY RECAP 2026-04-29 21:41:35.558349 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-29 21:41:35.558380 | 2026-04-29 21:41:35.747745 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-29 21:41:35.750734 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-04-29 21:41:36.405812 | 2026-04-29 21:41:36.405970 | PLAY [all] 2026-04-29 21:41:36.417554 | 2026-04-29 21:41:36.417644 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-04-29 21:41:36.805868 | controller | changed 2026-04-29 21:41:37.593257 | 2026-04-29 21:41:37.593447 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-04-29 21:41:38.006473 | controller | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-29 21:41:38.006584 | controller | warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-29 21:41:38.014016 | controller | environment: line 1: kubectl: command not found 2026-04-29 21:41:38.016220 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-29 21:41:38.017303 | controller | environment: line 1: kubectl: command not found 2026-04-29 21:41:38.019526 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-29 21:41:38.020797 | controller | environment: line 1: kubectl: command not found 2026-04-29 21:41:38.022831 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-29 21:41:38.025206 | controller | environment: line 1: kubectl: command not found 2026-04-29 21:41:38.026546 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-29 21:41:38.027585 | controller | environment: line 1: kubectl: command not found 2026-04-29 21:41:38.029247 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-29 21:41:38.127295 | controller | ok: Runtime: 0:00:00.037958 2026-04-29 21:41:38.134489 | 2026-04-29 21:41:38.134555 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-04-29 21:41:38.381501 | controller | changed 2026-04-29 21:41:38.387223 | 2026-04-29 21:41:38.387327 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-04-29 21:41:38.632648 | controller | environment: line 5: kubectl: command not found 2026-04-29 21:41:38.633102 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-29 21:41:38.634119 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-29 21:41:38.634285 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-29 21:41:38.922820 | controller | ok: Runtime: 0:00:00.015038 2026-04-29 21:41:38.933728 | 2026-04-29 21:41:38.933942 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-04-29 21:41:39.609395 | controller | changed: 2026-04-29 21:41:39.610182 | controller | created directory /var/lib/zuul/builds/a5eeaa03328a46e5a953d6d882ef5a56/work/logs/controller 2026-04-29 21:41:39.610255 | controller | cd+++++++++ objects/ 2026-04-29 21:41:39.610305 | controller | cd+++++++++ objects/cluster/ 2026-04-29 21:41:39.610358 | controller | cd+++++++++ objects/namespaced/ 2026-04-29 21:41:39.618763 | 2026-04-29 21:41:39.618831 | TASK [gather-pod-logs : creating directory for pod logs] 2026-04-29 21:41:39.864614 | controller | changed 2026-04-29 21:41:39.870105 | 2026-04-29 21:41:39.870195 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-04-29 21:41:40.127097 | controller | changed 2026-04-29 21:41:40.132666 | 2026-04-29 21:41:40.132768 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-04-29 21:41:40.407492 | controller | environment: line 3: kubectl: command not found 2026-04-29 21:41:40.663804 | controller | ok: Runtime: 0:00:00.015334 2026-04-29 21:41:40.669444 | 2026-04-29 21:41:40.669529 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-04-29 21:41:41.218044 | controller | changed: 2026-04-29 21:41:41.218246 | controller | cd+++++++++ pod-logs/ 2026-04-29 21:41:41.218273 | controller | cd+++++++++ pod-logs/failed-pods/ 2026-04-29 21:41:41.225209 | 2026-04-29 21:41:41.225279 | LOOP [helm-release-status : creating directory for helm release status] 2026-04-29 21:41:41.470717 | controller | changed: "values" 2026-04-29 21:41:41.690740 | controller | changed: "releases" 2026-04-29 21:41:41.882282 | 2026-04-29 21:41:41.882452 | TASK [helm-release-status : Gather get release status for helm charts] 2026-04-29 21:41:42.136012 | controller | /bin/bash: line 3: kubectl: command not found 2026-04-29 21:41:42.414618 | controller | ok: Runtime: 0:00:00.009845 2026-04-29 21:41:42.419136 | 2026-04-29 21:41:42.419204 | TASK [helm-release-status : Downloads logs to executor] 2026-04-29 21:41:42.979074 | controller | changed: 2026-04-29 21:41:42.979335 | controller | cd+++++++++ helm/ 2026-04-29 21:41:42.979400 | controller | cd+++++++++ helm/releases/ 2026-04-29 21:41:42.979454 | controller | cd+++++++++ helm/values/ 2026-04-29 21:41:42.986606 | 2026-04-29 21:41:42.986652 | PLAY RECAP 2026-04-29 21:41:42.986695 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-29 21:41:42.986716 | 2026-04-29 21:41:43.109131 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main] 2026-04-29 21:41:43.112630 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-29 21:41:43.773325 | 2026-04-29 21:41:43.773907 | PLAY [all] 2026-04-29 21:41:43.784904 | 2026-04-29 21:41:43.785135 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-29 21:41:43.830391 | controller | skipping: Conditional result was False 2026-04-29 21:41:43.838098 | 2026-04-29 21:41:43.838214 | TASK [fetch-output : Set log path for single node] 2026-04-29 21:41:43.882399 | controller | ok 2026-04-29 21:41:43.888829 | 2026-04-29 21:41:43.888901 | LOOP [fetch-output : Ensure local output dirs] 2026-04-29 21:41:44.291060 | controller -> localhost | ok: "/var/lib/zuul/builds/a5eeaa03328a46e5a953d6d882ef5a56/work/logs" 2026-04-29 21:41:44.512442 | controller -> localhost | changed: "/var/lib/zuul/builds/a5eeaa03328a46e5a953d6d882ef5a56/work/artifacts" 2026-04-29 21:41:44.730774 | controller -> localhost | changed: "/var/lib/zuul/builds/a5eeaa03328a46e5a953d6d882ef5a56/work/docs" 2026-04-29 21:41:44.746843 | 2026-04-29 21:41:44.747020 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-29 21:41:45.427326 | controller | changed: .d..t...... ./ 2026-04-29 21:41:45.427631 | controller | changed: All items complete 2026-04-29 21:41:45.427678 | 2026-04-29 21:41:45.916712 | controller | changed: .d..t...... ./ 2026-04-29 21:41:46.439819 | controller | changed: .d..t...... ./ 2026-04-29 21:41:46.461782 | 2026-04-29 21:41:46.461873 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-29 21:41:46.881366 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007993 2026-04-29 21:41:47.144838 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009249 2026-04-29 21:41:47.167197 | 2026-04-29 21:41:47.167330 | PLAY [all] 2026-04-29 21:41:47.172880 | 2026-04-29 21:41:47.172942 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-29 21:41:47.661652 | controller | changed 2026-04-29 21:41:47.666228 | 2026-04-29 21:41:47.666278 | PLAY RECAP 2026-04-29 21:41:47.666334 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-29 21:41:47.666367 | 2026-04-29 21:41:47.800115 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-29 21:41:47.803393 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-29 21:41:48.371214 | 2026-04-29 21:41:48.371339 | PLAY [localhost] 2026-04-29 21:41:48.380937 | 2026-04-29 21:41:48.381026 | TASK [Generate Zuul manifest] 2026-04-29 21:41:48.403081 | localhost | ok 2026-04-29 21:41:48.421372 | 2026-04-29 21:41:48.421451 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-29 21:41:48.818474 | localhost | changed 2026-04-29 21:41:48.834634 | 2026-04-29 21:41:48.834734 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-29 21:41:48.949343 | localhost | ok 2026-04-29 21:41:48.958928 | 2026-04-29 21:41:48.958996 | TASK [Upload logs] 2026-04-29 21:41:48.982848 | localhost | ok 2026-04-29 21:41:49.046912 | 2026-04-29 21:41:49.047065 | TASK [Set zuul-log-path fact] 2026-04-29 21:41:49.071176 | localhost | ok 2026-04-29 21:41:49.090029 | 2026-04-29 21:41:49.090204 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-29 21:41:49.123381 | localhost | ok 2026-04-29 21:41:49.134185 | 2026-04-29 21:41:49.134272 | TASK [upload-logs : Create log directories] 2026-04-29 21:41:49.609588 | localhost | changed 2026-04-29 21:41:49.614985 | 2026-04-29 21:41:49.615073 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-29 21:41:50.053252 | localhost -> localhost | ok: Runtime: 0:00:00.006074 2026-04-29 21:41:50.059142 | 2026-04-29 21:41:50.059211 | TASK [upload-logs : Upload logs to log server] 2026-04-29 21:41:50.536507 | localhost | Output suppressed because no_log was given 2026-04-29 21:41:50.544484 | 2026-04-29 21:41:50.544579 | LOOP [upload-logs : Compress console log and json output] 2026-04-29 21:41:50.601470 | localhost | skipping: Conditional result was False 2026-04-29 21:41:50.659132 | localhost | skipping: Conditional result was False 2026-04-29 21:41:50.670989 | 2026-04-29 21:41:50.671196 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-29 21:41:50.717461 | localhost | skipping: Conditional result was False 2026-04-29 21:41:50.717924 | 2026-04-29 21:41:50.721474 | localhost | skipping: Conditional result was False 2026-04-29 21:41:50.739551 | 2026-04-29 21:41:50.739832 | LOOP [upload-logs : Upload console log and json output]