2026-03-17 19:46:40.421559 | Job console starting 2026-03-17 19:46:40.570290 | Updating git repos 2026-03-17 19:46:40.615853 | Cloning repos into workspace 2026-03-17 19:46:40.773540 | Restoring repo states 2026-03-17 19:46:40.827145 | Merging changes 2026-03-17 19:46:42.134837 | Checking out repos 2026-03-17 19:46:42.572752 | Preparing playbooks 2026-03-17 19:46:46.544078 | Running Ansible setup 2026-03-17 19:46:50.656320 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-17 19:46:51.301729 | 2026-03-17 19:46:51.302060 | PLAY [localhost] 2026-03-17 19:46:51.322125 | 2026-03-17 19:46:51.322264 | TASK [Gathering Facts] 2026-03-17 19:46:52.311375 | localhost | ok 2026-03-17 19:46:52.323521 | 2026-03-17 19:46:52.323628 | TASK [Setup log path fact] 2026-03-17 19:46:52.345172 | localhost | ok 2026-03-17 19:46:52.358818 | 2026-03-17 19:46:52.358884 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-17 19:46:52.387841 | localhost | ok 2026-03-17 19:46:52.395755 | 2026-03-17 19:46:52.395843 | TASK [emit-job-header : Print job information] 2026-03-17 19:46:52.447973 | # Job Information 2026-03-17 19:46:52.448160 | Ansible Version: 2.16.16 2026-03-17 19:46:52.448205 | Job: ansible-collection-kubernetes-molecule-upload-helm-chart 2026-03-17 19:46:52.448235 | Pipeline: check 2026-03-17 19:46:52.448263 | Executor: 0a8996d2b663 2026-03-17 19:46:52.448290 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/238 2026-03-17 19:46:52.448321 | Event ID: b11771e0-2239-11f1-9cf9-cf3d0ed948e5 2026-03-17 19:46:52.453338 | 2026-03-17 19:46:52.453429 | LOOP [emit-job-header : Print node information] 2026-03-17 19:46:52.547111 | localhost | ok: 2026-03-17 19:46:52.547265 | localhost | # Node Information 2026-03-17 19:46:52.547330 | localhost | Inventory Hostname: controller 2026-03-17 19:46:52.547353 | localhost | Hostname: np0000159569 2026-03-17 19:46:52.547373 | localhost | Username: zuul 2026-03-17 19:46:52.547395 | localhost | Distro: Debian 13.3 2026-03-17 19:46:52.547415 | localhost | Provider: yul1 2026-03-17 19:46:52.547434 | localhost | Region: ca-ymq-1 2026-03-17 19:46:52.547452 | localhost | Label: debian-trixie 2026-03-17 19:46:52.547471 | localhost | Product Name: OpenStack Nova 2026-03-17 19:46:52.547490 | localhost | Interface IP: 199.19.213.23 2026-03-17 19:46:52.562084 | 2026-03-17 19:46:52.562313 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-17 19:46:52.916212 | localhost -> localhost | changed 2026-03-17 19:46:53.000409 | 2026-03-17 19:46:53.000531 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-17 19:46:54.040715 | localhost -> localhost | changed 2026-03-17 19:46:54.061659 | 2026-03-17 19:46:54.061806 | PLAY [all] 2026-03-17 19:46:54.071368 | 2026-03-17 19:46:54.071463 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-17 19:46:54.321853 | controller -> localhost | ok 2026-03-17 19:46:54.326663 | 2026-03-17 19:46:54.326725 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-17 19:46:54.357654 | controller | ok 2026-03-17 19:46:54.375238 | controller | included: /var/lib/zuul/builds/b262c5fe7e9c46eb99b1d692107cd302/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-17 19:46:54.380775 | 2026-03-17 19:46:54.380837 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-17 19:46:55.466326 | controller -> localhost | Generating public/private rsa key pair. 2026-03-17 19:46:55.466514 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/b262c5fe7e9c46eb99b1d692107cd302/work/b262c5fe7e9c46eb99b1d692107cd302_id_rsa 2026-03-17 19:46:55.466545 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/b262c5fe7e9c46eb99b1d692107cd302/work/b262c5fe7e9c46eb99b1d692107cd302_id_rsa.pub 2026-03-17 19:46:55.466568 | controller -> localhost | The key fingerprint is: 2026-03-17 19:46:55.466589 | controller -> localhost | SHA256:YrvZoA5C6CjdJySCRgqHXNcW8H6vf/7y1PsdfAXsY40 zuul-build-sshkey 2026-03-17 19:46:55.466620 | controller -> localhost | The key's randomart image is: 2026-03-17 19:46:55.466641 | controller -> localhost | +---[RSA 3072]----+ 2026-03-17 19:46:55.466680 | controller -> localhost | | . oo.. | 2026-03-17 19:46:55.466702 | controller -> localhost | |... . .o . | 2026-03-17 19:46:55.466722 | controller -> localhost | |oo. .. o | 2026-03-17 19:46:55.466742 | controller -> localhost | |*. . . + | 2026-03-17 19:46:55.466762 | controller -> localhost | |=o. . o.S. E o| 2026-03-17 19:46:55.466782 | controller -> localhost | |=o + . o. . ...o| 2026-03-17 19:46:55.466801 | controller -> localhost | |+.o o + . +o| 2026-03-17 19:46:55.466820 | controller -> localhost | |.. . + = . o. =| 2026-03-17 19:46:55.466842 | controller -> localhost | | .o o ....o.+++| 2026-03-17 19:46:55.466862 | controller -> localhost | +----[SHA256]-----+ 2026-03-17 19:46:55.466910 | controller -> localhost | ok: Runtime: 0:00:00.653476 2026-03-17 19:46:55.471102 | 2026-03-17 19:46:55.471161 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-17 19:46:55.498277 | controller | ok 2026-03-17 19:46:55.505593 | controller | included: /var/lib/zuul/builds/b262c5fe7e9c46eb99b1d692107cd302/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-17 19:46:55.518503 | 2026-03-17 19:46:55.518578 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-17 19:46:55.542524 | controller | skipping: Conditional result was False 2026-03-17 19:46:55.551251 | 2026-03-17 19:46:55.551363 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-17 19:46:56.119771 | controller | changed 2026-03-17 19:46:56.125672 | 2026-03-17 19:46:56.125765 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-17 19:46:56.339502 | controller | ok 2026-03-17 19:46:56.344085 | 2026-03-17 19:46:56.344172 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-17 19:46:56.896647 | controller | changed 2026-03-17 19:46:56.902647 | 2026-03-17 19:46:56.902716 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-17 19:46:57.452528 | controller | changed 2026-03-17 19:46:57.518839 | 2026-03-17 19:46:57.518994 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-17 19:46:57.543942 | controller | skipping: Conditional result was False 2026-03-17 19:46:57.550857 | 2026-03-17 19:46:57.550978 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-17 19:46:57.993265 | controller -> localhost | changed 2026-03-17 19:46:58.005661 | 2026-03-17 19:46:58.005773 | TASK [add-build-sshkey : Add back temp key] 2026-03-17 19:46:58.287072 | controller -> localhost | Identity added: /var/lib/zuul/builds/b262c5fe7e9c46eb99b1d692107cd302/work/b262c5fe7e9c46eb99b1d692107cd302_id_rsa (zuul-build-sshkey) 2026-03-17 19:46:58.287292 | controller -> localhost | ok: Runtime: 0:00:00.013597 2026-03-17 19:46:58.293645 | 2026-03-17 19:46:58.293734 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-17 19:46:58.656716 | controller | ok 2026-03-17 19:46:58.663412 | 2026-03-17 19:46:58.663478 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-17 19:46:58.692988 | controller | skipping: Conditional result was False 2026-03-17 19:46:58.706156 | 2026-03-17 19:46:58.706237 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-17 19:46:59.071476 | controller | ok 2026-03-17 19:46:59.079391 | 2026-03-17 19:46:59.079463 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-17 19:47:00.125512 | controller | Output suppressed because no_log was given 2026-03-17 19:47:00.131928 | 2026-03-17 19:47:00.131995 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-17 19:47:00.334915 | controller | ok: "logs" 2026-03-17 19:47:00.335133 | controller | ok: All items complete 2026-03-17 19:47:00.335173 | 2026-03-17 19:47:00.513345 | controller | ok: "artifacts" 2026-03-17 19:47:00.701016 | controller | ok: "docs" 2026-03-17 19:47:00.712241 | 2026-03-17 19:47:00.712332 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-17 19:47:00.910534 | controller | changed: "logs" 2026-03-17 19:47:01.093990 | controller | changed: "artifacts" 2026-03-17 19:47:01.279494 | controller | changed: "docs" 2026-03-17 19:47:01.289668 | 2026-03-17 19:47:01.289750 | PLAY RECAP 2026-03-17 19:47:01.289793 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-17 19:47:01.289822 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-17 19:47:01.289843 | 2026-03-17 19:47:01.394044 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-17 19:47:01.395268 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-17 19:47:01.974899 | 2026-03-17 19:47:01.975368 | PLAY [all] 2026-03-17 19:47:01.985901 | 2026-03-17 19:47:01.985973 | TASK [setup-uv : Extract archive] 2026-03-17 19:47:04.295854 | controller | changed 2026-03-17 19:47:04.303820 | 2026-03-17 19:47:04.303922 | TASK [setup-uv : Print version] 2026-03-17 19:47:04.671908 | controller | uv 0.8.13 2026-03-17 19:47:04.830398 | controller | ok: Runtime: 0:00:00.014298 2026-03-17 19:47:04.837543 | 2026-03-17 19:47:04.837619 | PLAY RECAP 2026-03-17 19:47:04.837690 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-17 19:47:04.837731 | 2026-03-17 19:47:05.044847 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-17 19:47:05.047461 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-17 19:47:05.627854 | 2026-03-17 19:47:05.628261 | PLAY [all] 2026-03-17 19:47:05.638183 | 2026-03-17 19:47:05.638253 | TASK [Copy inventory file for Zuul] 2026-03-17 19:47:06.560166 | controller | changed 2026-03-17 19:47:06.566202 | 2026-03-17 19:47:06.566272 | TASK [Switch "ansible_host" to private IP] 2026-03-17 19:47:06.864094 | controller | changed: 1 replacements made 2026-03-17 19:47:06.871048 | 2026-03-17 19:47:06.871134 | TASK [Run Molecule scenario] 2026-03-17 19:47:07.298080 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-03-17 19:47:07.298164 | controller | Creating virtual environment at: .venv 2026-03-17 19:47:07.572229 | controller | Downloading cryptography (4.3MiB) 2026-03-17 19:47:07.572675 | controller | Downloading pygments (1.2MiB) 2026-03-17 19:47:07.573305 | controller | Downloading ansible-core (2.3MiB) 2026-03-17 19:47:07.733545 | controller | Downloading cryptography 2026-03-17 19:47:07.790017 | controller | Downloading pygments 2026-03-17 19:47:08.012920 | controller | Downloading ansible-core 2026-03-17 19:47:08.061135 | controller | Installed 36 packages in 47ms 2026-03-17 19:47:08.763065 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-03-17 19:47:08.763154 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-03-17 19:47:09.636552 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-03-17 19:47:09.636646 | controller | INFO default scenario not found, disabling shared state. 2026-03-17 19:47:09.637015 | controller | INFO [upload-helm-chart > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-03-17 19:47:09.637219 | controller | INFO [upload-helm-chart > prerun] Performing prerun with role_name_check=0... 2026-03-17 19:47:25.187899 | controller | INFO [upload-helm-chart > dependency] Executing 2026-03-17 19:47:25.188068 | controller | WARNING [upload-helm-chart > dependency] Missing roles requirements file: requirements.yml 2026-03-17 19:47:25.188278 | controller | WARNING [upload-helm-chart > dependency] Missing collections requirements file: collections.yml 2026-03-17 19:47:25.188390 | controller | WARNING [upload-helm-chart > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-03-17 19:47:25.193888 | controller | INFO [upload-helm-chart > cleanup] Executing 2026-03-17 19:47:25.194343 | controller | WARNING [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-17 19:47:25.199625 | controller | INFO [upload-helm-chart > destroy] Executing 2026-03-17 19:47:25.199710 | controller | WARNING [upload-helm-chart > destroy] Skipping, '--destroy=never' requested. 2026-03-17 19:47:25.199775 | controller | INFO [upload-helm-chart > destroy] Executed: Successful 2026-03-17 19:47:25.205301 | controller | INFO [upload-helm-chart > syntax] Executing 2026-03-17 19:47:26.080604 | controller | 2026-03-17 19:47:26.080873 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/upload-helm-chart/converge.yml 2026-03-17 19:47:26.137529 | controller | INFO [upload-helm-chart > syntax] Executed: Successful 2026-03-17 19:47:26.147141 | controller | INFO [upload-helm-chart > create] Executing 2026-03-17 19:47:26.149136 | controller | WARNING [upload-helm-chart > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-17 19:47:26.155613 | controller | INFO [upload-helm-chart > prepare] Executing 2026-03-17 19:47:26.155859 | controller | WARNING [upload-helm-chart > prepare] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-17 19:47:26.161107 | controller | INFO [upload-helm-chart > converge] Executing 2026-03-17 19:47:27.003004 | controller | 2026-03-17 19:47:27.003180 | controller | PLAY [Converge] **************************************************************** 2026-03-17 19:47:27.003368 | controller | 2026-03-17 19:47:27.003606 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-17 19:47:27.003852 | controller | Tuesday 17 March 2026 19:47:26 +0000 (0:00:00.029) 0:00:00.029 ********* 2026-03-17 19:47:28.314538 | 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-17 19:47:28.317537 | controller | ok: [controller] 2026-03-17 19:47:28.317724 | controller | 2026-03-17 19:47:28.317976 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-17 19:47:28.318237 | controller | Tuesday 17 March 2026 19:47:28 +0000 (0:00:01.315) 0:00:01.345 ********* 2026-03-17 19:47:28.394796 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-17 19:47:28.394972 | controller | 2026-03-17 19:47:28.395188 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-17 19:47:28.395388 | controller | Tuesday 17 March 2026 19:47:28 +0000 (0:00:00.077) 0:00:01.422 ********* 2026-03-17 19:47:29.118058 | controller | changed: [controller] 2026-03-17 19:47:29.118096 | controller | 2026-03-17 19:47:29.118339 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-17 19:47:29.118534 | controller | Tuesday 17 March 2026 19:47:29 +0000 (0:00:00.722) 0:00:02.144 ********* 2026-03-17 19:47:29.174707 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-17 19:47:29.174899 | controller | 2026-03-17 19:47:29.175120 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-17 19:47:29.175323 | controller | Tuesday 17 March 2026 19:47:29 +0000 (0:00:00.057) 0:00:02.202 ********* 2026-03-17 19:47:29.579474 | controller | changed: [controller] 2026-03-17 19:47:29.579663 | controller | 2026-03-17 19:47:29.580007 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-17 19:47:29.580244 | controller | Tuesday 17 March 2026 19:47:29 +0000 (0:00:00.404) 0:00:02.606 ********* 2026-03-17 19:47:30.669194 | controller | changed: [controller] 2026-03-17 19:47:30.669387 | controller | 2026-03-17 19:47:30.669654 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-17 19:47:30.669846 | controller | Tuesday 17 March 2026 19:47:30 +0000 (0:00:01.089) 0:00:03.696 ********* 2026-03-17 19:47:31.058756 | controller | ok: [controller] 2026-03-17 19:47:31.058976 | controller | 2026-03-17 19:47:31.059244 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-17 19:47:31.059478 | controller | Tuesday 17 March 2026 19:47:31 +0000 (0:00:00.389) 0:00:04.086 ********* 2026-03-17 19:47:31.112820 | controller | skipping: [controller] 2026-03-17 19:47:31.113062 | controller | 2026-03-17 19:47:31.113299 | controller | PLAY RECAP ********************************************************************* 2026-03-17 19:47:31.113553 | controller | controller : ok=7 changed=3 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-03-17 19:47:31.113768 | controller | 2026-03-17 19:47:31.114052 | controller | 2026-03-17 19:47:31.114325 | controller | TASKS RECAP ******************************************************************** 2026-03-17 19:47:31.114627 | controller | Tuesday 17 March 2026 19:47:31 +0000 (0:00:00.054) 0:00:04.140 ********* 2026-03-17 19:47:31.114891 | controller | =============================================================================== 2026-03-17 19:47:31.115120 | controller | Gathering Facts --------------------------------------------------------- 1.32s 2026-03-17 19:47:31.115408 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.09s 2026-03-17 19:47:31.115639 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.72s 2026-03-17 19:47:31.115900 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.40s 2026-03-17 19:47:31.116110 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.39s 2026-03-17 19:47:31.116350 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.08s 2026-03-17 19:47:31.116599 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.06s 2026-03-17 19:47:31.116872 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.05s 2026-03-17 19:47:31.203481 | controller | INFO [upload-helm-chart > converge] Executed: Successful 2026-03-17 19:47:31.213224 | controller | INFO [upload-helm-chart > idempotence] Executing 2026-03-17 19:47:32.071764 | controller | 2026-03-17 19:47:32.071976 | controller | PLAY [Converge] **************************************************************** 2026-03-17 19:47:32.072202 | controller | 2026-03-17 19:47:32.072414 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-17 19:47:32.072625 | controller | Tuesday 17 March 2026 19:47:32 +0000 (0:00:00.010) 0:00:00.010 ********* 2026-03-17 19:47:34.140766 | 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-17 19:47:34.146651 | controller | ok: [controller] 2026-03-17 19:47:34.146853 | controller | 2026-03-17 19:47:34.147082 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-17 19:47:34.147323 | controller | Tuesday 17 March 2026 19:47:34 +0000 (0:00:02.072) 0:00:02.083 ********* 2026-03-17 19:47:34.221099 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-17 19:47:34.221328 | controller | 2026-03-17 19:47:34.221603 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-17 19:47:34.221856 | controller | Tuesday 17 March 2026 19:47:34 +0000 (0:00:00.076) 0:00:02.160 ********* 2026-03-17 19:47:34.957792 | controller | ok: [controller] 2026-03-17 19:47:34.957842 | controller | 2026-03-17 19:47:34.958059 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-17 19:47:34.958214 | controller | Tuesday 17 March 2026 19:47:34 +0000 (0:00:00.736) 0:00:02.896 ********* 2026-03-17 19:47:35.001706 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-17 19:47:35.001892 | controller | 2026-03-17 19:47:35.002150 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-17 19:47:35.002350 | controller | Tuesday 17 March 2026 19:47:34 +0000 (0:00:00.044) 0:00:02.940 ********* 2026-03-17 19:47:35.420286 | controller | ok: [controller] 2026-03-17 19:47:35.420427 | controller | 2026-03-17 19:47:35.420695 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-17 19:47:35.420895 | controller | Tuesday 17 March 2026 19:47:35 +0000 (0:00:00.417) 0:00:03.358 ********* 2026-03-17 19:47:36.536099 | controller | ok: [controller] 2026-03-17 19:47:36.536235 | controller | 2026-03-17 19:47:36.536456 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-17 19:47:36.536665 | controller | Tuesday 17 March 2026 19:47:36 +0000 (0:00:01.116) 0:00:04.474 ********* 2026-03-17 19:47:36.918476 | controller | ok: [controller] 2026-03-17 19:47:36.918695 | controller | 2026-03-17 19:47:36.918940 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-17 19:47:36.919149 | controller | Tuesday 17 March 2026 19:47:36 +0000 (0:00:00.382) 0:00:04.857 ********* 2026-03-17 19:47:36.958952 | controller | skipping: [controller] 2026-03-17 19:47:36.959090 | controller | 2026-03-17 19:47:36.959288 | controller | PLAY RECAP ********************************************************************* 2026-03-17 19:47:36.959486 | controller | controller : ok=7 changed=0 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-03-17 19:47:36.959657 | controller | 2026-03-17 19:47:36.959822 | controller | 2026-03-17 19:47:36.959998 | controller | TASKS RECAP ******************************************************************** 2026-03-17 19:47:36.960242 | controller | Tuesday 17 March 2026 19:47:36 +0000 (0:00:00.041) 0:00:04.898 ********* 2026-03-17 19:47:36.960396 | controller | =============================================================================== 2026-03-17 19:47:36.960581 | controller | Gathering Facts --------------------------------------------------------- 2.07s 2026-03-17 19:47:36.960777 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.12s 2026-03-17 19:47:36.960963 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.74s 2026-03-17 19:47:36.961154 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.42s 2026-03-17 19:47:36.961341 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.38s 2026-03-17 19:47:36.961531 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.08s 2026-03-17 19:47:36.961723 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.04s 2026-03-17 19:47:36.961939 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.04s 2026-03-17 19:47:37.043007 | controller | INFO [upload-helm-chart > idempotence] Executed: Successful 2026-03-17 19:47:37.054261 | controller | INFO [upload-helm-chart > side_effect] Executing 2026-03-17 19:47:37.878804 | controller | 2026-03-17 19:47:37.878951 | controller | PLAY [Side effect] ************************************************************* 2026-03-17 19:47:37.879074 | controller | 2026-03-17 19:47:37.879201 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-17 19:47:37.879329 | controller | Tuesday 17 March 2026 19:47:37 +0000 (0:00:00.010) 0:00:00.010 ********* 2026-03-17 19:47:38.930961 | 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-17 19:47:38.936702 | controller | ok: [controller] 2026-03-17 19:47:38.936738 | controller | 2026-03-17 19:47:38.936747 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-17 19:47:38.936756 | controller | Tuesday 17 March 2026 19:47:38 +0000 (0:00:01.057) 0:00:01.068 ********* 2026-03-17 19:47:39.006279 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-17 19:47:39.006524 | controller | 2026-03-17 19:47:39.006778 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-17 19:47:39.007020 | controller | Tuesday 17 March 2026 19:47:39 +0000 (0:00:00.070) 0:00:01.138 ********* 2026-03-17 19:47:39.702380 | controller | changed: [controller] 2026-03-17 19:47:39.702649 | controller | 2026-03-17 19:47:39.702923 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-17 19:47:39.703174 | controller | Tuesday 17 March 2026 19:47:39 +0000 (0:00:00.695) 0:00:01.834 ********* 2026-03-17 19:47:39.736918 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-17 19:47:39.737175 | controller | 2026-03-17 19:47:39.737433 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-17 19:47:39.737679 | controller | Tuesday 17 March 2026 19:47:39 +0000 (0:00:00.034) 0:00:01.869 ********* 2026-03-17 19:47:40.157444 | controller | ok: [controller] 2026-03-17 19:47:40.157745 | controller | 2026-03-17 19:47:40.158087 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-17 19:47:40.158379 | controller | Tuesday 17 March 2026 19:47:40 +0000 (0:00:00.419) 0:00:02.288 ********* 2026-03-17 19:47:41.242282 | controller | ok: [controller] 2026-03-17 19:47:41.242417 | controller | 2026-03-17 19:47:41.242547 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-17 19:47:41.242667 | controller | Tuesday 17 March 2026 19:47:41 +0000 (0:00:01.083) 0:00:03.372 ********* 2026-03-17 19:47:41.640368 | controller | ok: [controller] 2026-03-17 19:47:41.640485 | controller | 2026-03-17 19:47:41.640631 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-17 19:47:41.640758 | controller | Tuesday 17 March 2026 19:47:41 +0000 (0:00:00.397) 0:00:03.770 ********* 2026-03-17 19:47:41.947733 | controller | changed: [controller] => (item=/file) 2026-03-17 19:47:41.947792 | controller | 2026-03-17 19:47:41.947869 | controller | PLAY RECAP ********************************************************************* 2026-03-17 19:47:41.949032 | controller | controller : ok=8 changed=2 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-17 19:47:41.949060 | controller | 2026-03-17 19:47:41.949065 | controller | 2026-03-17 19:47:41.949070 | controller | TASKS RECAP ******************************************************************** 2026-03-17 19:47:41.949083 | controller | Tuesday 17 March 2026 19:47:41 +0000 (0:00:00.309) 0:00:04.080 ********* 2026-03-17 19:47:41.949087 | controller | =============================================================================== 2026-03-17 19:47:41.949091 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.08s 2026-03-17 19:47:41.949095 | controller | Gathering Facts --------------------------------------------------------- 1.06s 2026-03-17 19:47:41.949099 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.70s 2026-03-17 19:47:41.949103 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.42s 2026-03-17 19:47:41.949107 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.40s 2026-03-17 19:47:41.949111 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.31s 2026-03-17 19:47:41.949115 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.07s 2026-03-17 19:47:41.949119 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.03s 2026-03-17 19:47:42.046705 | controller | INFO [upload-helm-chart > side_effect] Executed: Successful 2026-03-17 19:47:42.119514 | controller | INFO [upload-helm-chart > verify] Executing 2026-03-17 19:47:42.935810 | controller | 2026-03-17 19:47:42.936108 | controller | PLAY [Verify] ****************************************************************** 2026-03-17 19:47:42.936346 | controller | 2026-03-17 19:47:42.936597 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-17 19:47:42.936844 | controller | Tuesday 17 March 2026 19:47:42 +0000 (0:00:00.009) 0:00:00.009 ********* 2026-03-17 19:47:43.993797 | 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-17 19:47:43.998077 | controller | ok: [controller] 2026-03-17 19:47:43.998167 | controller | 2026-03-17 19:47:43.998277 | controller | TASK [Stat file 1] ************************************************************* 2026-03-17 19:47:43.998388 | controller | Tuesday 17 March 2026 19:47:43 +0000 (0:00:01.062) 0:00:01.071 ********* 2026-03-17 19:47:44.390372 | controller | ok: [controller] 2026-03-17 19:47:44.390491 | controller | 2026-03-17 19:47:44.390665 | controller | TASK [Stat file 2] ************************************************************* 2026-03-17 19:47:44.390791 | controller | Tuesday 17 March 2026 19:47:44 +0000 (0:00:00.389) 0:00:01.460 ********* 2026-03-17 19:47:44.716620 | controller | ok: [controller] 2026-03-17 19:47:44.716717 | controller | 2026-03-17 19:47:44.717014 | controller | TASK [Stat removed file 1] ***************************************************** 2026-03-17 19:47:44.717042 | controller | Tuesday 17 March 2026 19:47:44 +0000 (0:00:00.328) 0:00:01.789 ********* 2026-03-17 19:47:44.998400 | controller | ok: [controller] 2026-03-17 19:47:44.998447 | controller | 2026-03-17 19:47:44.998600 | controller | TASK [Stat removed file 2] ***************************************************** 2026-03-17 19:47:44.998737 | controller | Tuesday 17 March 2026 19:47:44 +0000 (0:00:00.280) 0:00:02.070 ********* 2026-03-17 19:47:45.271492 | controller | ok: [controller] 2026-03-17 19:47:45.271543 | controller | 2026-03-17 19:47:45.271653 | controller | TASK [ansible.builtin.assert] ************************************************** 2026-03-17 19:47:45.271735 | controller | Tuesday 17 March 2026 19:47:45 +0000 (0:00:00.274) 0:00:02.345 ********* 2026-03-17 19:47:45.307958 | controller | ok: [controller] => { 2026-03-17 19:47:45.307988 | controller | "changed": false, 2026-03-17 19:47:45.307992 | controller | "msg": "All assertions passed" 2026-03-17 19:47:45.307996 | controller | } 2026-03-17 19:47:45.308000 | controller | 2026-03-17 19:47:45.308003 | controller | PLAY RECAP ********************************************************************* 2026-03-17 19:47:45.308007 | controller | controller : ok=6 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-17 19:47:45.308011 | controller | 2026-03-17 19:47:45.308023 | controller | 2026-03-17 19:47:45.308026 | controller | TASKS RECAP ******************************************************************** 2026-03-17 19:47:45.308030 | controller | Tuesday 17 March 2026 19:47:45 +0000 (0:00:00.033) 0:00:02.378 ********* 2026-03-17 19:47:45.308033 | controller | =============================================================================== 2026-03-17 19:47:45.308036 | controller | Gathering Facts --------------------------------------------------------- 1.06s 2026-03-17 19:47:45.308039 | controller | Stat file 1 ------------------------------------------------------------- 0.39s 2026-03-17 19:47:45.308042 | controller | Stat file 2 ------------------------------------------------------------- 0.33s 2026-03-17 19:47:45.308046 | controller | Stat removed file 1 ----------------------------------------------------- 0.28s 2026-03-17 19:47:45.308049 | controller | Stat removed file 2 ----------------------------------------------------- 0.27s 2026-03-17 19:47:45.308052 | controller | ansible.builtin.assert -------------------------------------------------- 0.03s 2026-03-17 19:47:45.389861 | controller | INFO [upload-helm-chart > verify] Executed: Successful 2026-03-17 19:47:45.399923 | controller | INFO [upload-helm-chart > cleanup] Executing 2026-03-17 19:47:45.400317 | controller | WARNING [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-17 19:47:45.405795 | controller | INFO [upload-helm-chart > destroy] Executing 2026-03-17 19:47:45.405892 | controller | WARNING [upload-helm-chart > destroy] Skipping, '--destroy=never' requested. 2026-03-17 19:47:45.406040 | controller | INFO [upload-helm-chart > destroy] Executed: Successful 2026-03-17 19:47:45.406338 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-03-17 19:47:45.953582 | controller | ok: Runtime: 0:00:38.267132 2026-03-17 19:47:45.957887 | 2026-03-17 19:47:45.957953 | PLAY RECAP 2026-03-17 19:47:45.958009 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-17 19:47:45.958039 | 2026-03-17 19:47:46.052666 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-17 19:47:46.055566 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-17 19:47:46.612833 | 2026-03-17 19:47:46.612965 | PLAY [all] 2026-03-17 19:47:46.623039 | 2026-03-17 19:47:46.623118 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-17 19:47:46.657528 | controller | skipping: Conditional result was False 2026-03-17 19:47:46.665755 | 2026-03-17 19:47:46.665863 | TASK [fetch-output : Set log path for single node] 2026-03-17 19:47:46.708069 | controller | ok 2026-03-17 19:47:46.712856 | 2026-03-17 19:47:46.712975 | LOOP [fetch-output : Ensure local output dirs] 2026-03-17 19:47:47.057714 | controller -> localhost | ok: "/var/lib/zuul/builds/b262c5fe7e9c46eb99b1d692107cd302/work/logs" 2026-03-17 19:47:47.264394 | controller -> localhost | changed: "/var/lib/zuul/builds/b262c5fe7e9c46eb99b1d692107cd302/work/artifacts" 2026-03-17 19:47:47.481923 | controller -> localhost | changed: "/var/lib/zuul/builds/b262c5fe7e9c46eb99b1d692107cd302/work/docs" 2026-03-17 19:47:47.496555 | 2026-03-17 19:47:47.497255 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-17 19:47:48.145949 | controller | changed: .d..t...... ./ 2026-03-17 19:47:48.146125 | controller | changed: All items complete 2026-03-17 19:47:48.146153 | 2026-03-17 19:47:48.631054 | controller | changed: .d..t...... ./ 2026-03-17 19:47:49.099853 | controller | changed: .d..t...... ./ 2026-03-17 19:47:49.113791 | 2026-03-17 19:47:49.113928 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-17 19:47:49.523627 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.005794 2026-03-17 19:47:49.740285 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006294 2026-03-17 19:47:49.757554 | 2026-03-17 19:47:49.757616 | PLAY [all] 2026-03-17 19:47:49.762203 | 2026-03-17 19:47:49.762264 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-17 19:47:50.209704 | controller | changed 2026-03-17 19:47:50.219768 | 2026-03-17 19:47:50.219910 | PLAY RECAP 2026-03-17 19:47:50.220004 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-17 19:47:50.220053 | 2026-03-17 19:47:50.318737 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-17 19:47:50.321291 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-17 19:47:50.856014 | 2026-03-17 19:47:50.856413 | PLAY [localhost] 2026-03-17 19:47:50.865734 | 2026-03-17 19:47:50.865801 | TASK [Generate Zuul manifest] 2026-03-17 19:47:50.883818 | localhost | ok 2026-03-17 19:47:50.900649 | 2026-03-17 19:47:50.900726 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-17 19:47:51.225732 | localhost | changed 2026-03-17 19:47:51.237230 | 2026-03-17 19:47:51.237303 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-17 19:47:51.267630 | localhost | ok 2026-03-17 19:47:51.276751 | 2026-03-17 19:47:51.276814 | TASK [Upload logs] 2026-03-17 19:47:51.294561 | localhost | ok 2026-03-17 19:47:51.351527 | 2026-03-17 19:47:51.351606 | TASK [Set zuul-log-path fact] 2026-03-17 19:47:51.372843 | localhost | ok 2026-03-17 19:47:51.386511 | 2026-03-17 19:47:51.386579 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-17 19:47:51.419134 | localhost | ok 2026-03-17 19:47:51.426383 | 2026-03-17 19:47:51.426444 | TASK [upload-logs : Create log directories] 2026-03-17 19:47:51.811845 | localhost | changed 2026-03-17 19:47:51.817936 | 2026-03-17 19:47:51.818026 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-17 19:47:52.233758 | localhost -> localhost | ok: Runtime: 0:00:00.004340 2026-03-17 19:47:52.239699 | 2026-03-17 19:47:52.239763 | TASK [upload-logs : Upload logs to log server] 2026-03-17 19:47:52.684138 | localhost | Output suppressed because no_log was given 2026-03-17 19:47:52.688878 | 2026-03-17 19:47:52.688979 | LOOP [upload-logs : Compress console log and json output] 2026-03-17 19:47:52.734298 | localhost | skipping: Conditional result was False 2026-03-17 19:47:52.741773 | localhost | skipping: Conditional result was False 2026-03-17 19:47:52.753914 | 2026-03-17 19:47:52.754078 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-17 19:47:52.798597 | localhost | skipping: Conditional result was False 2026-03-17 19:47:52.799105 | 2026-03-17 19:47:52.802757 | localhost | skipping: Conditional result was False 2026-03-17 19:47:52.817105 | 2026-03-17 19:47:52.817257 | LOOP [upload-logs : Upload console log and json output]