2026-03-11 20:22:23.927413 | Job console starting 2026-03-11 20:22:23.935818 | Updating git repos 2026-03-11 20:22:23.968438 | Cloning repos into workspace 2026-03-11 20:22:24.036037 | Restoring repo states 2026-03-11 20:22:24.057205 | Merging changes 2026-03-11 20:22:24.715309 | Checking out repos 2026-03-11 20:22:25.027103 | Preparing playbooks 2026-03-11 20:22:26.179173 | Running Ansible setup 2026-03-11 20:22:29.849019 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-11 20:22:30.551805 | 2026-03-11 20:22:30.551943 | PLAY [localhost] 2026-03-11 20:22:30.559311 | 2026-03-11 20:22:30.559380 | TASK [Gathering Facts] 2026-03-11 20:22:31.475913 | localhost | ok 2026-03-11 20:22:31.484789 | 2026-03-11 20:22:31.484870 | TASK [Setup log path fact] 2026-03-11 20:22:31.513798 | localhost | ok 2026-03-11 20:22:31.527729 | 2026-03-11 20:22:31.527815 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-11 20:22:31.568379 | localhost | ok 2026-03-11 20:22:31.576478 | 2026-03-11 20:22:31.576567 | TASK [emit-job-header : Print job information] 2026-03-11 20:22:31.615354 | # Job Information 2026-03-11 20:22:31.615502 | Ansible Version: 2.16.16 2026-03-11 20:22:31.615546 | Job: ansible-collection-kubernetes-molecule-upload-helm-chart 2026-03-11 20:22:31.615576 | Pipeline: check 2026-03-11 20:22:31.615604 | Executor: 0a8996d2b663 2026-03-11 20:22:31.615630 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/238 2026-03-11 20:22:31.615660 | Event ID: deadab30-1d87-11f1-972a-c6d16a6328cd 2026-03-11 20:22:31.619292 | 2026-03-11 20:22:31.619378 | LOOP [emit-job-header : Print node information] 2026-03-11 20:22:31.724753 | localhost | ok: 2026-03-11 20:22:31.725090 | localhost | # Node Information 2026-03-11 20:22:31.725153 | localhost | Inventory Hostname: controller 2026-03-11 20:22:31.725199 | localhost | Hostname: np0000158447 2026-03-11 20:22:31.725242 | localhost | Username: zuul 2026-03-11 20:22:31.725291 | localhost | Distro: Debian 13.3 2026-03-11 20:22:31.725334 | localhost | Provider: yul1 2026-03-11 20:22:31.725375 | localhost | Region: ca-ymq-1 2026-03-11 20:22:31.725717 | localhost | Label: debian-trixie 2026-03-11 20:22:31.725789 | localhost | Product Name: OpenStack Nova 2026-03-11 20:22:31.725834 | localhost | Interface IP: 199.204.45.250 2026-03-11 20:22:31.742031 | 2026-03-11 20:22:31.742178 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-11 20:22:32.222193 | localhost -> localhost | changed 2026-03-11 20:22:32.227817 | 2026-03-11 20:22:32.227884 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-11 20:22:33.065144 | localhost -> localhost | changed 2026-03-11 20:22:33.079147 | 2026-03-11 20:22:33.079202 | PLAY [all] 2026-03-11 20:22:33.086146 | 2026-03-11 20:22:33.086213 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-11 20:22:33.340329 | controller -> localhost | ok 2026-03-11 20:22:33.349337 | 2026-03-11 20:22:33.349434 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-11 20:22:33.381002 | controller | ok 2026-03-11 20:22:33.397392 | controller | included: /var/lib/zuul/builds/3980bc22f16f492c99b3ab857ed261cc/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-11 20:22:33.402264 | 2026-03-11 20:22:33.402323 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-11 20:22:34.522478 | controller -> localhost | Generating public/private rsa key pair. 2026-03-11 20:22:34.522736 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/3980bc22f16f492c99b3ab857ed261cc/work/3980bc22f16f492c99b3ab857ed261cc_id_rsa 2026-03-11 20:22:34.522767 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/3980bc22f16f492c99b3ab857ed261cc/work/3980bc22f16f492c99b3ab857ed261cc_id_rsa.pub 2026-03-11 20:22:34.522789 | controller -> localhost | The key fingerprint is: 2026-03-11 20:22:34.522810 | controller -> localhost | SHA256:fMi18EjBolp6hWDOJhns8aH84fZg8bEl4wn+l780Osc zuul-build-sshkey 2026-03-11 20:22:34.522845 | controller -> localhost | The key's randomart image is: 2026-03-11 20:22:34.522869 | controller -> localhost | +---[RSA 3072]----+ 2026-03-11 20:22:34.522897 | controller -> localhost | |. .. | 2026-03-11 20:22:34.522918 | controller -> localhost | |.oo. . .. | 2026-03-11 20:22:34.522938 | controller -> localhost | |o*+..o .o . | 2026-03-11 20:22:34.522958 | controller -> localhost | |o++=++.= * . | 2026-03-11 20:22:34.522978 | controller -> localhost | | o++*.B S + | 2026-03-11 20:22:34.522997 | controller -> localhost | | oB.= . | 2026-03-11 20:22:34.523023 | controller -> localhost | | o.+ oo | 2026-03-11 20:22:34.523047 | controller -> localhost | | o +oE. | 2026-03-11 20:22:34.523069 | controller -> localhost | | ..+o. | 2026-03-11 20:22:34.523088 | controller -> localhost | +----[SHA256]-----+ 2026-03-11 20:22:34.523266 | controller -> localhost | ok: Runtime: 0:00:00.664226 2026-03-11 20:22:34.527975 | 2026-03-11 20:22:34.528049 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-11 20:22:34.559535 | controller | ok 2026-03-11 20:22:34.570769 | controller | included: /var/lib/zuul/builds/3980bc22f16f492c99b3ab857ed261cc/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-11 20:22:34.577121 | 2026-03-11 20:22:34.577183 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-11 20:22:34.603082 | controller | skipping: Conditional result was False 2026-03-11 20:22:34.611029 | 2026-03-11 20:22:34.611115 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-11 20:22:35.152149 | controller | changed 2026-03-11 20:22:35.156043 | 2026-03-11 20:22:35.156104 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-11 20:22:35.369526 | controller | ok 2026-03-11 20:22:35.374993 | 2026-03-11 20:22:35.375060 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-11 20:22:35.914742 | controller | changed 2026-03-11 20:22:35.922110 | 2026-03-11 20:22:35.922176 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-11 20:22:36.491578 | controller | changed 2026-03-11 20:22:36.497131 | 2026-03-11 20:22:36.497197 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-11 20:22:36.532017 | controller | skipping: Conditional result was False 2026-03-11 20:22:36.538184 | 2026-03-11 20:22:36.538284 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-11 20:22:37.049659 | controller -> localhost | changed 2026-03-11 20:22:37.070966 | 2026-03-11 20:22:37.071056 | TASK [add-build-sshkey : Add back temp key] 2026-03-11 20:22:37.400101 | controller -> localhost | Identity added: /var/lib/zuul/builds/3980bc22f16f492c99b3ab857ed261cc/work/3980bc22f16f492c99b3ab857ed261cc_id_rsa (zuul-build-sshkey) 2026-03-11 20:22:37.400299 | controller -> localhost | ok: Runtime: 0:00:00.016021 2026-03-11 20:22:37.404501 | 2026-03-11 20:22:37.404565 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-11 20:22:37.752555 | controller | ok 2026-03-11 20:22:37.756608 | 2026-03-11 20:22:37.756672 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-11 20:22:37.783447 | controller | skipping: Conditional result was False 2026-03-11 20:22:37.793348 | 2026-03-11 20:22:37.793443 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-11 20:22:38.188128 | controller | ok 2026-03-11 20:22:38.194054 | 2026-03-11 20:22:38.194123 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-11 20:22:39.304402 | controller | Output suppressed because no_log was given 2026-03-11 20:22:39.314127 | 2026-03-11 20:22:39.314193 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-11 20:22:39.527369 | controller | ok: "logs" 2026-03-11 20:22:39.527642 | controller | ok: All items complete 2026-03-11 20:22:39.527679 | 2026-03-11 20:22:39.693018 | controller | ok: "artifacts" 2026-03-11 20:22:39.876340 | controller | ok: "docs" 2026-03-11 20:22:39.891052 | 2026-03-11 20:22:39.891251 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-11 20:22:40.121183 | controller | changed: "logs" 2026-03-11 20:22:40.310974 | controller | changed: "artifacts" 2026-03-11 20:22:40.507895 | controller | changed: "docs" 2026-03-11 20:22:40.524054 | 2026-03-11 20:22:40.524182 | PLAY RECAP 2026-03-11 20:22:40.524235 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-11 20:22:40.524265 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-11 20:22:40.524286 | 2026-03-11 20:22:40.683729 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-11 20:22:40.686340 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-11 20:22:41.335084 | 2026-03-11 20:22:41.335216 | PLAY [all] 2026-03-11 20:22:41.345363 | 2026-03-11 20:22:41.345436 | TASK [setup-uv : Extract archive] 2026-03-11 20:22:43.779454 | controller | changed 2026-03-11 20:22:43.784534 | 2026-03-11 20:22:43.784611 | TASK [setup-uv : Print version] 2026-03-11 20:22:44.145498 | controller | uv 0.8.13 2026-03-11 20:22:44.318359 | controller | ok: Runtime: 0:00:00.014172 2026-03-11 20:22:44.331727 | 2026-03-11 20:22:44.331839 | PLAY RECAP 2026-03-11 20:22:44.331891 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-11 20:22:44.331916 | 2026-03-11 20:22:44.444924 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-11 20:22:44.447931 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-11 20:22:45.015219 | 2026-03-11 20:22:45.015370 | PLAY [all] 2026-03-11 20:22:45.024862 | 2026-03-11 20:22:45.024934 | TASK [Copy inventory file for Zuul] 2026-03-11 20:22:45.977900 | controller | changed 2026-03-11 20:22:45.984603 | 2026-03-11 20:22:45.984717 | TASK [Switch "ansible_host" to private IP] 2026-03-11 20:22:46.276420 | controller | changed: 1 replacements made 2026-03-11 20:22:46.285601 | 2026-03-11 20:22:46.285704 | TASK [Run Molecule scenario] 2026-03-11 20:22:46.717790 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-03-11 20:22:46.717886 | controller | Creating virtual environment at: .venv 2026-03-11 20:22:47.109544 | controller | Downloading pygments (1.2MiB) 2026-03-11 20:22:47.109798 | controller | Downloading ansible-core (2.3MiB) 2026-03-11 20:22:47.114158 | controller | Downloading cryptography (4.3MiB) 2026-03-11 20:22:47.344998 | controller | Downloading pygments 2026-03-11 20:22:47.356443 | controller | Downloading cryptography 2026-03-11 20:22:47.525065 | controller | Downloading ansible-core 2026-03-11 20:22:47.571216 | controller | Installed 36 packages in 45ms 2026-03-11 20:22:48.251141 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-03-11 20:22:48.251209 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-03-11 20:22:49.086655 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-03-11 20:22:49.086707 | controller | INFO default scenario not found, disabling shared state. 2026-03-11 20:22:49.086866 | controller | INFO [upload-helm-chart > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-03-11 20:22:49.086931 | controller | INFO [upload-helm-chart > prerun] Performing prerun with role_name_check=0... 2026-03-11 20:23:10.809911 | controller | INFO [upload-helm-chart > dependency] Executing 2026-03-11 20:23:10.810017 | controller | WARNING [upload-helm-chart > dependency] Missing roles requirements file: requirements.yml 2026-03-11 20:23:10.810311 | controller | WARNING [upload-helm-chart > dependency] Missing collections requirements file: collections.yml 2026-03-11 20:23:10.810387 | controller | WARNING [upload-helm-chart > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-03-11 20:23:10.815813 | controller | INFO [upload-helm-chart > cleanup] Executing 2026-03-11 20:23:10.816153 | controller | WARNING [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-11 20:23:10.821576 | controller | INFO [upload-helm-chart > destroy] Executing 2026-03-11 20:23:10.821633 | controller | WARNING [upload-helm-chart > destroy] Skipping, '--destroy=never' requested. 2026-03-11 20:23:10.821697 | controller | INFO [upload-helm-chart > destroy] Executed: Successful 2026-03-11 20:23:10.827117 | controller | INFO [upload-helm-chart > syntax] Executing 2026-03-11 20:23:11.758723 | controller | 2026-03-11 20:23:11.759001 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/upload-helm-chart/converge.yml 2026-03-11 20:23:11.845304 | controller | INFO [upload-helm-chart > syntax] Executed: Successful 2026-03-11 20:23:11.852433 | controller | INFO [upload-helm-chart > create] Executing 2026-03-11 20:23:11.854003 | controller | WARNING [upload-helm-chart > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-11 20:23:11.859354 | controller | INFO [upload-helm-chart > prepare] Executing 2026-03-11 20:23:11.859636 | controller | WARNING [upload-helm-chart > prepare] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-11 20:23:11.864899 | controller | INFO [upload-helm-chart > converge] Executing 2026-03-11 20:23:12.732543 | controller | 2026-03-11 20:23:12.732708 | controller | PLAY [Converge] **************************************************************** 2026-03-11 20:23:12.732964 | controller | 2026-03-11 20:23:12.733121 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-11 20:23:12.733369 | controller | Wednesday 11 March 2026 20:23:12 +0000 (0:00:00.029) 0:00:00.029 ******* 2026-03-11 20:23:14.046909 | 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-03-11 20:23:14.052076 | controller | ok: [controller] 2026-03-11 20:23:14.052240 | controller | 2026-03-11 20:23:14.052445 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 20:23:14.052717 | controller | Wednesday 11 March 2026 20:23:14 +0000 (0:00:01.320) 0:00:01.349 ******* 2026-03-11 20:23:14.138126 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-11 20:23:14.138351 | controller | 2026-03-11 20:23:14.138583 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-11 20:23:14.138760 | controller | Wednesday 11 March 2026 20:23:14 +0000 (0:00:00.086) 0:00:01.436 ******* 2026-03-11 20:23:14.871543 | controller | changed: [controller] 2026-03-11 20:23:14.871694 | controller | 2026-03-11 20:23:14.871910 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 20:23:14.872107 | controller | Wednesday 11 March 2026 20:23:14 +0000 (0:00:00.732) 0:00:02.169 ******* 2026-03-11 20:23:14.918630 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-11 20:23:14.918784 | controller | 2026-03-11 20:23:14.918988 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-11 20:23:14.919170 | controller | Wednesday 11 March 2026 20:23:14 +0000 (0:00:00.047) 0:00:02.216 ******* 2026-03-11 20:23:15.330070 | controller | changed: [controller] 2026-03-11 20:23:15.330128 | controller | 2026-03-11 20:23:15.330207 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-11 20:23:15.330339 | controller | Wednesday 11 March 2026 20:23:15 +0000 (0:00:00.410) 0:00:02.627 ******* 2026-03-11 20:23:16.459994 | controller | changed: [controller] 2026-03-11 20:23:16.460069 | controller | 2026-03-11 20:23:16.460215 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-11 20:23:16.460375 | controller | Wednesday 11 March 2026 20:23:16 +0000 (0:00:01.129) 0:00:03.756 ******* 2026-03-11 20:23:16.861824 | controller | ok: [controller] 2026-03-11 20:23:16.862038 | controller | 2026-03-11 20:23:16.865156 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-11 20:23:16.865167 | controller | Wednesday 11 March 2026 20:23:16 +0000 (0:00:00.401) 0:00:04.158 ******* 2026-03-11 20:23:16.918498 | controller | skipping: [controller] 2026-03-11 20:23:16.918698 | controller | 2026-03-11 20:23:16.918946 | controller | PLAY RECAP ********************************************************************* 2026-03-11 20:23:16.919161 | controller | controller : ok=7 changed=3 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-03-11 20:23:16.919339 | controller | 2026-03-11 20:23:16.919506 | controller | 2026-03-11 20:23:16.919721 | controller | TASKS RECAP ******************************************************************** 2026-03-11 20:23:16.919923 | controller | Wednesday 11 March 2026 20:23:16 +0000 (0:00:00.057) 0:00:04.216 ******* 2026-03-11 20:23:16.920103 | controller | =============================================================================== 2026-03-11 20:23:16.920285 | controller | Gathering Facts --------------------------------------------------------- 1.32s 2026-03-11 20:23:16.920484 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.13s 2026-03-11 20:23:16.920673 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.73s 2026-03-11 20:23:16.920866 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.41s 2026-03-11 20:23:16.921045 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.40s 2026-03-11 20:23:16.921242 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.09s 2026-03-11 20:23:16.921436 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.06s 2026-03-11 20:23:16.921631 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.05s 2026-03-11 20:23:17.041724 | controller | INFO [upload-helm-chart > converge] Executed: Successful 2026-03-11 20:23:17.051028 | controller | INFO [upload-helm-chart > idempotence] Executing 2026-03-11 20:23:17.899456 | controller | 2026-03-11 20:23:17.899550 | controller | PLAY [Converge] **************************************************************** 2026-03-11 20:23:17.899735 | controller | 2026-03-11 20:23:17.899916 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-11 20:23:17.900069 | controller | Wednesday 11 March 2026 20:23:17 +0000 (0:00:00.012) 0:00:00.012 ******* 2026-03-11 20:23:18.978224 | 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-03-11 20:23:18.988103 | controller | ok: [controller] 2026-03-11 20:23:18.988126 | controller | 2026-03-11 20:23:18.988136 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 20:23:18.988144 | controller | Wednesday 11 March 2026 20:23:18 +0000 (0:00:01.087) 0:00:01.100 ******* 2026-03-11 20:23:19.066976 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-11 20:23:19.067248 | controller | 2026-03-11 20:23:19.067521 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-11 20:23:19.067779 | controller | Wednesday 11 March 2026 20:23:19 +0000 (0:00:00.080) 0:00:01.180 ******* 2026-03-11 20:23:19.804292 | controller | ok: [controller] 2026-03-11 20:23:19.804583 | controller | 2026-03-11 20:23:19.804867 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 20:23:19.805127 | controller | Wednesday 11 March 2026 20:23:19 +0000 (0:00:00.736) 0:00:01.917 ******* 2026-03-11 20:23:19.844826 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-11 20:23:19.845072 | controller | 2026-03-11 20:23:19.845332 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-11 20:23:19.845586 | controller | Wednesday 11 March 2026 20:23:19 +0000 (0:00:00.041) 0:00:01.959 ******* 2026-03-11 20:23:20.258859 | controller | ok: [controller] 2026-03-11 20:23:20.259142 | controller | 2026-03-11 20:23:20.259446 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-11 20:23:20.259705 | controller | Wednesday 11 March 2026 20:23:20 +0000 (0:00:00.412) 0:00:02.372 ******* 2026-03-11 20:23:21.350064 | controller | ok: [controller] 2026-03-11 20:23:21.350890 | controller | 2026-03-11 20:23:21.350911 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-11 20:23:21.350920 | controller | Wednesday 11 March 2026 20:23:21 +0000 (0:00:01.091) 0:00:03.463 ******* 2026-03-11 20:23:21.745967 | controller | ok: [controller] 2026-03-11 20:23:21.746283 | controller | 2026-03-11 20:23:21.746601 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-11 20:23:21.746871 | controller | Wednesday 11 March 2026 20:23:21 +0000 (0:00:00.391) 0:00:03.855 ******* 2026-03-11 20:23:21.788422 | controller | skipping: [controller] 2026-03-11 20:23:21.788667 | controller | 2026-03-11 20:23:21.788910 | controller | PLAY RECAP ********************************************************************* 2026-03-11 20:23:21.789172 | controller | controller : ok=7 changed=0 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-03-11 20:23:21.789395 | controller | 2026-03-11 20:23:21.789617 | controller | 2026-03-11 20:23:21.789853 | controller | TASKS RECAP ******************************************************************** 2026-03-11 20:23:21.790097 | controller | Wednesday 11 March 2026 20:23:21 +0000 (0:00:00.047) 0:00:03.903 ******* 2026-03-11 20:23:21.790364 | controller | =============================================================================== 2026-03-11 20:23:21.790626 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.09s 2026-03-11 20:23:21.790867 | controller | Gathering Facts --------------------------------------------------------- 1.09s 2026-03-11 20:23:21.791116 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.74s 2026-03-11 20:23:21.791361 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.41s 2026-03-11 20:23:21.791605 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.39s 2026-03-11 20:23:21.791869 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.08s 2026-03-11 20:23:21.792172 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.05s 2026-03-11 20:23:21.792425 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.04s 2026-03-11 20:23:21.902306 | controller | INFO [upload-helm-chart > idempotence] Executed: Successful 2026-03-11 20:23:21.914288 | controller | INFO [upload-helm-chart > side_effect] Executing 2026-03-11 20:23:22.735547 | controller | 2026-03-11 20:23:22.735721 | controller | PLAY [Side effect] ************************************************************* 2026-03-11 20:23:22.735891 | controller | 2026-03-11 20:23:22.736080 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-11 20:23:22.736271 | controller | Wednesday 11 March 2026 20:23:22 +0000 (0:00:00.011) 0:00:00.011 ******* 2026-03-11 20:23:23.826569 | 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-03-11 20:23:23.832318 | controller | ok: [controller] 2026-03-11 20:23:23.832494 | controller | 2026-03-11 20:23:23.832700 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 20:23:23.832925 | controller | Wednesday 11 March 2026 20:23:23 +0000 (0:00:01.096) 0:00:01.108 ******* 2026-03-11 20:23:23.913012 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-11 20:23:23.913155 | controller | 2026-03-11 20:23:23.913420 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-11 20:23:23.913581 | controller | Wednesday 11 March 2026 20:23:23 +0000 (0:00:00.080) 0:00:01.189 ******* 2026-03-11 20:23:24.658112 | controller | changed: [controller] 2026-03-11 20:23:24.658263 | controller | 2026-03-11 20:23:24.658631 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-11 20:23:24.658856 | controller | Wednesday 11 March 2026 20:23:24 +0000 (0:00:00.744) 0:00:01.933 ******* 2026-03-11 20:23:24.703979 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-11 20:23:24.704149 | controller | 2026-03-11 20:23:24.704354 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-11 20:23:24.704552 | controller | Wednesday 11 March 2026 20:23:24 +0000 (0:00:00.046) 0:00:01.980 ******* 2026-03-11 20:23:25.123771 | controller | ok: [controller] 2026-03-11 20:23:25.123811 | controller | 2026-03-11 20:23:25.123821 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-11 20:23:25.123829 | controller | Wednesday 11 March 2026 20:23:25 +0000 (0:00:00.418) 0:00:02.398 ******* 2026-03-11 20:23:26.262855 | controller | ok: [controller] 2026-03-11 20:23:26.262933 | controller | 2026-03-11 20:23:26.262944 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-11 20:23:26.263034 | controller | Wednesday 11 March 2026 20:23:26 +0000 (0:00:01.136) 0:00:03.535 ******* 2026-03-11 20:23:26.694077 | controller | ok: [controller] 2026-03-11 20:23:26.694154 | controller | 2026-03-11 20:23:26.694168 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-11 20:23:26.694334 | controller | Wednesday 11 March 2026 20:23:26 +0000 (0:00:00.434) 0:00:03.969 ******* 2026-03-11 20:23:27.022780 | controller | changed: [controller] => (item=/file) 2026-03-11 20:23:27.022827 | controller | 2026-03-11 20:23:27.022893 | controller | PLAY RECAP ********************************************************************* 2026-03-11 20:23:27.022987 | controller | controller : ok=8 changed=2 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-11 20:23:27.023065 | controller | 2026-03-11 20:23:27.023134 | controller | 2026-03-11 20:23:27.023225 | controller | TASKS RECAP ******************************************************************** 2026-03-11 20:23:27.023312 | controller | Wednesday 11 March 2026 20:23:27 +0000 (0:00:00.325) 0:00:04.295 ******* 2026-03-11 20:23:27.024072 | controller | =============================================================================== 2026-03-11 20:23:27.024082 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.14s 2026-03-11 20:23:27.024086 | controller | Gathering Facts --------------------------------------------------------- 1.10s 2026-03-11 20:23:27.024090 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.74s 2026-03-11 20:23:27.024093 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.43s 2026-03-11 20:23:27.024097 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.42s 2026-03-11 20:23:27.024101 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.33s 2026-03-11 20:23:27.024104 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.08s 2026-03-11 20:23:27.024108 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.05s 2026-03-11 20:23:27.142973 | controller | INFO [upload-helm-chart > side_effect] Executed: Successful 2026-03-11 20:23:27.153657 | controller | INFO [upload-helm-chart > verify] Executing 2026-03-11 20:23:27.979035 | controller | 2026-03-11 20:23:27.979367 | controller | PLAY [Verify] ****************************************************************** 2026-03-11 20:23:27.979794 | controller | 2026-03-11 20:23:27.980044 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-11 20:23:27.980295 | controller | Wednesday 11 March 2026 20:23:27 +0000 (0:00:00.010) 0:00:00.010 ******* 2026-03-11 20:23:29.071572 | 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-03-11 20:23:29.077918 | controller | ok: [controller] 2026-03-11 20:23:29.077938 | controller | 2026-03-11 20:23:29.077946 | controller | TASK [Stat file 1] ************************************************************* 2026-03-11 20:23:29.077953 | controller | Wednesday 11 March 2026 20:23:29 +0000 (0:00:01.098) 0:00:01.109 ******* 2026-03-11 20:23:29.492636 | controller | ok: [controller] 2026-03-11 20:23:29.492903 | controller | 2026-03-11 20:23:29.493183 | controller | TASK [Stat file 2] ************************************************************* 2026-03-11 20:23:29.493475 | controller | Wednesday 11 March 2026 20:23:29 +0000 (0:00:00.414) 0:00:01.523 ******* 2026-03-11 20:23:29.800296 | controller | ok: [controller] 2026-03-11 20:23:29.800561 | controller | 2026-03-11 20:23:29.800822 | controller | TASK [Stat removed file 1] ***************************************************** 2026-03-11 20:23:29.801081 | controller | Wednesday 11 March 2026 20:23:29 +0000 (0:00:00.307) 0:00:01.831 ******* 2026-03-11 20:23:30.088192 | controller | ok: [controller] 2026-03-11 20:23:30.088463 | controller | 2026-03-11 20:23:30.088723 | controller | TASK [Stat removed file 2] ***************************************************** 2026-03-11 20:23:30.088994 | controller | Wednesday 11 March 2026 20:23:30 +0000 (0:00:00.287) 0:00:02.119 ******* 2026-03-11 20:23:30.379257 | controller | ok: [controller] 2026-03-11 20:23:30.379522 | controller | 2026-03-11 20:23:30.379828 | controller | TASK [ansible.builtin.assert] ************************************************** 2026-03-11 20:23:30.380089 | controller | Wednesday 11 March 2026 20:23:30 +0000 (0:00:00.291) 0:00:02.410 ******* 2026-03-11 20:23:30.414994 | controller | ok: [controller] => { 2026-03-11 20:23:30.415277 | controller | "changed": false, 2026-03-11 20:23:30.415530 | controller | "msg": "All assertions passed" 2026-03-11 20:23:30.415802 | controller | } 2026-03-11 20:23:30.416035 | controller | 2026-03-11 20:23:30.416279 | controller | PLAY RECAP ********************************************************************* 2026-03-11 20:23:30.416549 | controller | controller : ok=6 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-11 20:23:30.416779 | controller | 2026-03-11 20:23:30.417021 | controller | 2026-03-11 20:23:30.417268 | controller | TASKS RECAP ******************************************************************** 2026-03-11 20:23:30.417520 | controller | Wednesday 11 March 2026 20:23:30 +0000 (0:00:00.036) 0:00:02.447 ******* 2026-03-11 20:23:30.417759 | controller | =============================================================================== 2026-03-11 20:23:30.418003 | controller | Gathering Facts --------------------------------------------------------- 1.10s 2026-03-11 20:23:30.418297 | controller | Stat file 1 ------------------------------------------------------------- 0.41s 2026-03-11 20:23:30.418554 | controller | Stat file 2 ------------------------------------------------------------- 0.31s 2026-03-11 20:23:30.418802 | controller | Stat removed file 2 ----------------------------------------------------- 0.29s 2026-03-11 20:23:30.419046 | controller | Stat removed file 1 ----------------------------------------------------- 0.29s 2026-03-11 20:23:30.419291 | controller | ansible.builtin.assert -------------------------------------------------- 0.04s 2026-03-11 20:23:30.522576 | controller | INFO [upload-helm-chart > verify] Executed: Successful 2026-03-11 20:23:30.533977 | controller | INFO [upload-helm-chart > cleanup] Executing 2026-03-11 20:23:30.534344 | controller | WARNING [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-11 20:23:30.539792 | controller | INFO [upload-helm-chart > destroy] Executing 2026-03-11 20:23:30.539893 | controller | WARNING [upload-helm-chart > destroy] Skipping, '--destroy=never' requested. 2026-03-11 20:23:30.539990 | controller | INFO [upload-helm-chart > destroy] Executed: Successful 2026-03-11 20:23:30.540365 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-03-11 20:23:30.868251 | controller | ok: Runtime: 0:00:43.993849 2026-03-11 20:23:30.873464 | 2026-03-11 20:23:30.873510 | PLAY RECAP 2026-03-11 20:23:30.873557 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-11 20:23:30.873577 | 2026-03-11 20:23:30.973082 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-11 20:23:30.976021 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-11 20:23:31.534989 | 2026-03-11 20:23:31.535129 | PLAY [all] 2026-03-11 20:23:31.545053 | 2026-03-11 20:23:31.545131 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-11 20:23:31.590501 | controller | skipping: Conditional result was False 2026-03-11 20:23:31.597252 | 2026-03-11 20:23:31.597372 | TASK [fetch-output : Set log path for single node] 2026-03-11 20:23:31.639398 | controller | ok 2026-03-11 20:23:31.643891 | 2026-03-11 20:23:31.643968 | LOOP [fetch-output : Ensure local output dirs] 2026-03-11 20:23:32.012827 | controller -> localhost | ok: "/var/lib/zuul/builds/3980bc22f16f492c99b3ab857ed261cc/work/logs" 2026-03-11 20:23:32.238690 | controller -> localhost | changed: "/var/lib/zuul/builds/3980bc22f16f492c99b3ab857ed261cc/work/artifacts" 2026-03-11 20:23:32.455623 | controller -> localhost | changed: "/var/lib/zuul/builds/3980bc22f16f492c99b3ab857ed261cc/work/docs" 2026-03-11 20:23:32.471103 | 2026-03-11 20:23:32.471266 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-11 20:23:33.157630 | controller | ok 2026-03-11 20:23:33.157950 | controller | changed: All items complete 2026-03-11 20:23:33.157978 | 2026-03-11 20:23:33.664343 | controller | changed: .d..t...... ./ 2026-03-11 20:23:34.171066 | controller | changed: .d..t...... ./ 2026-03-11 20:23:34.194262 | 2026-03-11 20:23:34.194460 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-11 20:23:34.599921 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007816 2026-03-11 20:23:34.817118 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006989 2026-03-11 20:23:34.835574 | 2026-03-11 20:23:34.835722 | PLAY [all] 2026-03-11 20:23:34.841547 | 2026-03-11 20:23:34.841613 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-11 20:23:35.300778 | controller | changed 2026-03-11 20:23:35.306241 | 2026-03-11 20:23:35.306296 | PLAY RECAP 2026-03-11 20:23:35.306347 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-11 20:23:35.306369 | 2026-03-11 20:23:35.452191 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-11 20:23:35.454895 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-11 20:23:36.000860 | 2026-03-11 20:23:36.001003 | PLAY [localhost] 2026-03-11 20:23:36.010443 | 2026-03-11 20:23:36.010510 | TASK [Generate Zuul manifest] 2026-03-11 20:23:36.030077 | localhost | ok 2026-03-11 20:23:36.049562 | 2026-03-11 20:23:36.049691 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-11 20:23:36.343759 | localhost | changed 2026-03-11 20:23:36.356013 | 2026-03-11 20:23:36.356114 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-11 20:23:36.387573 | localhost | ok 2026-03-11 20:23:36.396931 | 2026-03-11 20:23:36.397022 | TASK [Upload logs] 2026-03-11 20:23:36.417508 | localhost | ok 2026-03-11 20:23:36.477317 | 2026-03-11 20:23:36.477435 | TASK [Set zuul-log-path fact] 2026-03-11 20:23:36.498035 | localhost | ok 2026-03-11 20:23:36.513099 | 2026-03-11 20:23:36.513171 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-11 20:23:36.545205 | localhost | ok 2026-03-11 20:23:36.553384 | 2026-03-11 20:23:36.553446 | TASK [upload-logs : Create log directories] 2026-03-11 20:23:37.009248 | localhost | changed 2026-03-11 20:23:37.015130 | 2026-03-11 20:23:37.015201 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-11 20:23:37.433552 | localhost -> localhost | ok: Runtime: 0:00:00.005314 2026-03-11 20:23:37.439547 | 2026-03-11 20:23:37.439639 | TASK [upload-logs : Upload logs to log server] 2026-03-11 20:23:37.909484 | localhost | Output suppressed because no_log was given 2026-03-11 20:23:37.916133 | 2026-03-11 20:23:37.916204 | LOOP [upload-logs : Compress console log and json output] 2026-03-11 20:23:37.958551 | localhost | skipping: Conditional result was False 2026-03-11 20:23:37.965125 | localhost | skipping: Conditional result was False 2026-03-11 20:23:37.977709 | 2026-03-11 20:23:37.977880 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-11 20:23:38.019096 | localhost | skipping: Conditional result was False 2026-03-11 20:23:38.019455 | 2026-03-11 20:23:38.023459 | localhost | skipping: Conditional result was False 2026-03-11 20:23:38.033921 | 2026-03-11 20:23:38.034055 | LOOP [upload-logs : Upload console log and json output]