2026-02-28 03:50:27.767333 | Job console starting 2026-02-28 03:50:28.733009 | Updating git repos 2026-02-28 03:50:28.763643 | Cloning repos into workspace 2026-02-28 03:50:30.967521 | Restoring repo states 2026-02-28 03:50:30.981974 | Merging changes 2026-02-28 03:50:31.815103 | Checking out repos 2026-02-28 03:50:33.224834 | Preparing playbooks 2026-02-28 03:50:44.131782 | Running Ansible setup 2026-02-28 03:50:48.082070 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-02-28 03:50:48.717395 | 2026-02-28 03:50:48.717584 | PLAY [localhost] 2026-02-28 03:50:48.726337 | 2026-02-28 03:50:48.726418 | TASK [Gathering Facts] 2026-02-28 03:50:49.590866 | localhost | ok 2026-02-28 03:50:49.598888 | 2026-02-28 03:50:49.598962 | TASK [Setup log path fact] 2026-02-28 03:50:49.615070 | localhost | ok 2026-02-28 03:50:49.627713 | 2026-02-28 03:50:49.627813 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-28 03:50:49.657785 | localhost | ok 2026-02-28 03:50:49.666620 | 2026-02-28 03:50:49.666715 | TASK [emit-job-header : Print job information] 2026-02-28 03:50:49.708349 | # Job Information 2026-02-28 03:50:49.708539 | Ansible Version: 2.16.16 2026-02-28 03:50:49.708586 | Job: ansible-collection-kubernetes-molecule-upload-helm-chart 2026-02-28 03:50:49.708618 | Pipeline: check 2026-02-28 03:50:49.708647 | Executor: 0a8996d2b663 2026-02-28 03:50:49.708674 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/228 2026-02-28 03:50:49.708706 | Event ID: 6fc3b350-1458-11f1-8c58-29f65cadad4c 2026-02-28 03:50:49.712607 | 2026-02-28 03:50:49.712699 | LOOP [emit-job-header : Print node information] 2026-02-28 03:50:49.795929 | localhost | ok: 2026-02-28 03:50:49.796125 | localhost | # Node Information 2026-02-28 03:50:49.796154 | localhost | Inventory Hostname: controller 2026-02-28 03:50:49.796177 | localhost | Hostname: np0000157473 2026-02-28 03:50:49.796197 | localhost | Username: zuul 2026-02-28 03:50:49.796220 | localhost | Distro: Debian 13.3 2026-02-28 03:50:49.796240 | localhost | Provider: yul1 2026-02-28 03:50:49.796260 | localhost | Region: ca-ymq-1 2026-02-28 03:50:49.796279 | localhost | Label: debian-trixie 2026-02-28 03:50:49.796298 | localhost | Product Name: OpenStack Nova 2026-02-28 03:50:49.796317 | localhost | Interface IP: 199.204.45.235 2026-02-28 03:50:49.807767 | 2026-02-28 03:50:49.807914 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-28 03:50:50.214390 | localhost -> localhost | changed 2026-02-28 03:50:50.219600 | 2026-02-28 03:50:50.219668 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-28 03:50:51.062139 | localhost -> localhost | changed 2026-02-28 03:50:51.068311 | 2026-02-28 03:50:51.068368 | PLAY [all] 2026-02-28 03:50:51.075192 | 2026-02-28 03:50:51.075257 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-28 03:50:51.327947 | controller -> localhost | ok 2026-02-28 03:50:51.340291 | 2026-02-28 03:50:51.340397 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-28 03:50:51.374919 | controller | ok 2026-02-28 03:50:51.393548 | controller | included: /var/lib/zuul/builds/b906c610d0e948afb3f9ce94ebc21f06/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-28 03:50:51.400484 | 2026-02-28 03:50:51.400568 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-28 03:50:53.180922 | controller -> localhost | Generating public/private rsa key pair. 2026-02-28 03:50:53.181238 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/b906c610d0e948afb3f9ce94ebc21f06/work/b906c610d0e948afb3f9ce94ebc21f06_id_rsa 2026-02-28 03:50:53.181281 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/b906c610d0e948afb3f9ce94ebc21f06/work/b906c610d0e948afb3f9ce94ebc21f06_id_rsa.pub 2026-02-28 03:50:53.181313 | controller -> localhost | The key fingerprint is: 2026-02-28 03:50:53.181344 | controller -> localhost | SHA256:PLZAM6R7VGlmQD677gR0dcMQiZGP/QzH5ja1COxLQx4 zuul-build-sshkey 2026-02-28 03:50:53.181904 | controller -> localhost | The key's randomart image is: 2026-02-28 03:50:53.181955 | controller -> localhost | +---[RSA 3072]----+ 2026-02-28 03:50:53.181996 | controller -> localhost | | .===*o | 2026-02-28 03:50:53.182027 | controller -> localhost | | +oo*... | 2026-02-28 03:50:53.182056 | controller -> localhost | | o OO . | 2026-02-28 03:50:53.182086 | controller -> localhost | | . =.*E + . | 2026-02-28 03:50:53.182116 | controller -> localhost | | o ++SX o . | 2026-02-28 03:50:53.182144 | controller -> localhost | | o +=oB . | 2026-02-28 03:50:53.182171 | controller -> localhost | | o..+ . | 2026-02-28 03:50:53.182198 | controller -> localhost | | o . | 2026-02-28 03:50:53.182230 | controller -> localhost | | .o | 2026-02-28 03:50:53.182258 | controller -> localhost | +----[SHA256]-----+ 2026-02-28 03:50:53.182329 | controller -> localhost | ok: Runtime: 0:00:01.311345 2026-02-28 03:50:53.190164 | 2026-02-28 03:50:53.190227 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-28 03:50:53.218712 | controller | ok 2026-02-28 03:50:53.227235 | controller | included: /var/lib/zuul/builds/b906c610d0e948afb3f9ce94ebc21f06/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-28 03:50:53.233881 | 2026-02-28 03:50:53.233942 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-28 03:50:53.258972 | controller | skipping: Conditional result was False 2026-02-28 03:50:53.268224 | 2026-02-28 03:50:53.268285 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-28 03:50:53.801578 | controller | changed 2026-02-28 03:50:53.807114 | 2026-02-28 03:50:53.807177 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-28 03:50:54.022328 | controller | ok 2026-02-28 03:50:54.028097 | 2026-02-28 03:50:54.028184 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-28 03:50:54.556922 | controller | changed 2026-02-28 03:50:54.562289 | 2026-02-28 03:50:54.562350 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-28 03:50:55.081982 | controller | changed 2026-02-28 03:50:55.086269 | 2026-02-28 03:50:55.086330 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-28 03:50:55.111372 | controller | skipping: Conditional result was False 2026-02-28 03:50:55.123708 | 2026-02-28 03:50:55.123954 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-28 03:50:55.531239 | controller -> localhost | changed 2026-02-28 03:50:55.548611 | 2026-02-28 03:50:55.548737 | TASK [add-build-sshkey : Add back temp key] 2026-02-28 03:50:55.860768 | controller -> localhost | Identity added: /var/lib/zuul/builds/b906c610d0e948afb3f9ce94ebc21f06/work/b906c610d0e948afb3f9ce94ebc21f06_id_rsa (zuul-build-sshkey) 2026-02-28 03:50:55.861029 | controller -> localhost | ok: Runtime: 0:00:00.012912 2026-02-28 03:50:55.864910 | 2026-02-28 03:50:55.865006 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-28 03:50:56.206461 | controller | ok 2026-02-28 03:50:56.210948 | 2026-02-28 03:50:56.211009 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-28 03:50:56.245805 | controller | skipping: Conditional result was False 2026-02-28 03:50:56.259097 | 2026-02-28 03:50:56.259172 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-28 03:50:56.593420 | controller | ok 2026-02-28 03:50:56.601274 | 2026-02-28 03:50:56.601335 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-28 03:50:57.712768 | controller | Output suppressed because no_log was given 2026-02-28 03:50:57.734190 | 2026-02-28 03:50:57.734387 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-28 03:50:57.946731 | controller | ok: "logs" 2026-02-28 03:50:57.947023 | controller | ok: All items complete 2026-02-28 03:50:57.947075 | 2026-02-28 03:50:58.132362 | controller | ok: "artifacts" 2026-02-28 03:50:58.376189 | controller | ok: "docs" 2026-02-28 03:50:58.391600 | 2026-02-28 03:50:58.391692 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-28 03:50:58.599508 | controller | changed: "logs" 2026-02-28 03:50:58.784907 | controller | changed: "artifacts" 2026-02-28 03:50:58.973975 | controller | changed: "docs" 2026-02-28 03:50:58.994065 | 2026-02-28 03:50:58.994174 | PLAY RECAP 2026-02-28 03:50:58.994222 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-02-28 03:50:58.994252 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-28 03:50:58.994273 | 2026-02-28 03:50:59.111101 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-02-28 03:50:59.113258 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-28 03:50:59.711602 | 2026-02-28 03:50:59.711733 | PLAY [all] 2026-02-28 03:50:59.722541 | 2026-02-28 03:50:59.722621 | TASK [setup-uv : Extract archive] 2026-02-28 03:51:02.222480 | controller | changed 2026-02-28 03:51:02.227557 | 2026-02-28 03:51:02.227643 | TASK [setup-uv : Print version] 2026-02-28 03:51:02.596238 | controller | uv 0.8.13 2026-02-28 03:51:02.769326 | controller | ok: Runtime: 0:00:00.013719 2026-02-28 03:51:02.777282 | 2026-02-28 03:51:02.777350 | PLAY RECAP 2026-02-28 03:51:02.777412 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-28 03:51:02.777446 | 2026-02-28 03:51:02.895697 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-28 03:51:02.898347 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-28 03:51:03.524900 | 2026-02-28 03:51:03.525089 | PLAY [all] 2026-02-28 03:51:03.535766 | 2026-02-28 03:51:03.535840 | TASK [Copy inventory file for Zuul] 2026-02-28 03:51:04.531541 | controller | changed 2026-02-28 03:51:04.537521 | 2026-02-28 03:51:04.537599 | TASK [Switch "ansible_host" to private IP] 2026-02-28 03:51:04.848774 | controller | changed: 1 replacements made 2026-02-28 03:51:04.858856 | 2026-02-28 03:51:04.858953 | TASK [Run Molecule scenario] 2026-02-28 03:51:05.308753 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-02-28 03:51:05.308888 | controller | Creating virtual environment at: .venv 2026-02-28 03:51:05.620377 | controller | Downloading pygments (1.2MiB) 2026-02-28 03:51:05.621560 | controller | Downloading cryptography (4.3MiB) 2026-02-28 03:51:05.621818 | controller | Downloading ansible-core (2.3MiB) 2026-02-28 03:51:05.778215 | controller | Downloading cryptography 2026-02-28 03:51:05.837602 | controller | Downloading pygments 2026-02-28 03:51:06.043537 | controller | Downloading ansible-core 2026-02-28 03:51:06.094457 | controller | Installed 36 packages in 50ms 2026-02-28 03:51:06.766421 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-02-28 03:51:06.766490 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-02-28 03:51:07.639168 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-02-28 03:51:07.639240 | controller | INFO default scenario not found, disabling shared state. 2026-02-28 03:51:07.639264 | controller | INFO [upload-helm-chart > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-02-28 03:51:07.639392 | controller | INFO [upload-helm-chart > prerun] Performing prerun with role_name_check=0... 2026-02-28 03:51:24.601221 | controller | INFO [upload-helm-chart > dependency] Executing 2026-02-28 03:51:24.601379 | controller | WARNING [upload-helm-chart > dependency] Missing roles requirements file: requirements.yml 2026-02-28 03:51:24.601704 | controller | WARNING [upload-helm-chart > dependency] Missing collections requirements file: collections.yml 2026-02-28 03:51:24.601873 | controller | WARNING [upload-helm-chart > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-02-28 03:51:24.609217 | controller | INFO [upload-helm-chart > cleanup] Executing 2026-02-28 03:51:24.609534 | controller | WARNING [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-28 03:51:24.614847 | controller | INFO [upload-helm-chart > destroy] Executing 2026-02-28 03:51:24.614876 | controller | WARNING [upload-helm-chart > destroy] Skipping, '--destroy=never' requested. 2026-02-28 03:51:24.614956 | controller | INFO [upload-helm-chart > destroy] Executed: Successful 2026-02-28 03:51:24.620393 | controller | INFO [upload-helm-chart > syntax] Executing 2026-02-28 03:51:25.506378 | controller | 2026-02-28 03:51:25.506627 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/upload-helm-chart/converge.yml 2026-02-28 03:51:25.566125 | controller | INFO [upload-helm-chart > syntax] Executed: Successful 2026-02-28 03:51:25.579788 | controller | INFO [upload-helm-chart > create] Executing 2026-02-28 03:51:25.581362 | controller | WARNING [upload-helm-chart > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-28 03:51:25.586765 | controller | INFO [upload-helm-chart > prepare] Executing 2026-02-28 03:51:25.587080 | controller | WARNING [upload-helm-chart > prepare] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-28 03:51:25.592465 | controller | INFO [upload-helm-chart > converge] Executing 2026-02-28 03:51:26.472772 | controller | 2026-02-28 03:51:26.472951 | controller | PLAY [Converge] **************************************************************** 2026-02-28 03:51:26.473249 | controller | 2026-02-28 03:51:26.473526 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-28 03:51:26.473891 | controller | Saturday 28 February 2026 03:51:26 +0000 (0:00:00.032) 0:00:00.032 ***** 2026-02-28 03:51:27.810828 | 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-02-28 03:51:27.810902 | controller | ok: [controller] 2026-02-28 03:51:27.810913 | controller | 2026-02-28 03:51:27.810922 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-02-28 03:51:27.810930 | controller | Saturday 28 February 2026 03:51:27 +0000 (0:00:01.336) 0:00:01.369 ***** 2026-02-28 03:51:27.889144 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-02-28 03:51:27.889355 | controller | 2026-02-28 03:51:27.889593 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-02-28 03:51:27.889808 | controller | Saturday 28 February 2026 03:51:27 +0000 (0:00:00.080) 0:00:01.450 ***** 2026-02-28 03:51:28.618376 | controller | changed: [controller] 2026-02-28 03:51:28.618508 | controller | 2026-02-28 03:51:28.618748 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-02-28 03:51:28.618962 | controller | Saturday 28 February 2026 03:51:28 +0000 (0:00:00.728) 0:00:02.178 ***** 2026-02-28 03:51:28.659987 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-02-28 03:51:28.660135 | controller | 2026-02-28 03:51:28.660363 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-02-28 03:51:28.660569 | controller | Saturday 28 February 2026 03:51:28 +0000 (0:00:00.042) 0:00:02.221 ***** 2026-02-28 03:51:29.064393 | controller | changed: [controller] 2026-02-28 03:51:29.064482 | controller | 2026-02-28 03:51:29.064798 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-02-28 03:51:29.064990 | controller | Saturday 28 February 2026 03:51:29 +0000 (0:00:00.403) 0:00:02.624 ***** 2026-02-28 03:51:30.164578 | controller | changed: [controller] 2026-02-28 03:51:30.164660 | controller | 2026-02-28 03:51:30.164802 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-02-28 03:51:30.164930 | controller | Saturday 28 February 2026 03:51:30 +0000 (0:00:01.098) 0:00:03.723 ***** 2026-02-28 03:51:30.565533 | controller | ok: [controller] 2026-02-28 03:51:30.565653 | controller | 2026-02-28 03:51:30.565871 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-02-28 03:51:30.566065 | controller | Saturday 28 February 2026 03:51:30 +0000 (0:00:00.402) 0:00:04.126 ***** 2026-02-28 03:51:30.617046 | controller | skipping: [controller] 2026-02-28 03:51:30.617234 | controller | 2026-02-28 03:51:30.617475 | controller | PLAY RECAP ********************************************************************* 2026-02-28 03:51:30.617712 | controller | controller : ok=7 changed=3 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-02-28 03:51:30.617900 | controller | 2026-02-28 03:51:30.618082 | controller | 2026-02-28 03:51:30.618270 | controller | TASKS RECAP ******************************************************************** 2026-02-28 03:51:30.618474 | controller | Saturday 28 February 2026 03:51:30 +0000 (0:00:00.052) 0:00:04.178 ***** 2026-02-28 03:51:30.618647 | controller | =============================================================================== 2026-02-28 03:51:30.618829 | controller | Gathering Facts --------------------------------------------------------- 1.34s 2026-02-28 03:51:30.619023 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.10s 2026-02-28 03:51:30.619212 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.73s 2026-02-28 03:51:30.619413 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.40s 2026-02-28 03:51:30.619586 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.40s 2026-02-28 03:51:30.619775 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.08s 2026-02-28 03:51:30.620077 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.05s 2026-02-28 03:51:30.620272 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.04s 2026-02-28 03:51:30.718853 | controller | INFO [upload-helm-chart > converge] Executed: Successful 2026-02-28 03:51:30.729335 | controller | INFO [upload-helm-chart > idempotence] Executing 2026-02-28 03:51:31.554026 | controller | 2026-02-28 03:51:31.554095 | controller | PLAY [Converge] **************************************************************** 2026-02-28 03:51:31.554106 | controller | 2026-02-28 03:51:31.554172 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-28 03:51:31.554274 | controller | Saturday 28 February 2026 03:51:31 +0000 (0:00:00.010) 0:00:00.010 ***** 2026-02-28 03:51:32.626382 | 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-02-28 03:51:32.634083 | controller | ok: [controller] 2026-02-28 03:51:32.634267 | controller | 2026-02-28 03:51:32.634483 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-02-28 03:51:32.634694 | controller | Saturday 28 February 2026 03:51:32 +0000 (0:00:01.079) 0:00:01.090 ***** 2026-02-28 03:51:32.712573 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-02-28 03:51:32.712749 | controller | 2026-02-28 03:51:32.712979 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-02-28 03:51:32.713231 | controller | Saturday 28 February 2026 03:51:32 +0000 (0:00:00.078) 0:00:01.168 ***** 2026-02-28 03:51:33.479387 | controller | ok: [controller] 2026-02-28 03:51:33.479490 | controller | 2026-02-28 03:51:33.479622 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-02-28 03:51:33.479808 | controller | Saturday 28 February 2026 03:51:33 +0000 (0:00:00.765) 0:00:01.934 ***** 2026-02-28 03:51:33.518495 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-02-28 03:51:33.518710 | controller | 2026-02-28 03:51:33.518943 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-02-28 03:51:33.519144 | controller | Saturday 28 February 2026 03:51:33 +0000 (0:00:00.040) 0:00:01.975 ***** 2026-02-28 03:51:33.910504 | controller | ok: [controller] 2026-02-28 03:51:33.910632 | controller | 2026-02-28 03:51:33.910850 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-02-28 03:51:33.911048 | controller | Saturday 28 February 2026 03:51:33 +0000 (0:00:00.390) 0:00:02.366 ***** 2026-02-28 03:51:35.016719 | controller | ok: [controller] 2026-02-28 03:51:35.016794 | controller | 2026-02-28 03:51:35.017014 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-02-28 03:51:35.017238 | controller | Saturday 28 February 2026 03:51:35 +0000 (0:00:01.106) 0:00:03.472 ***** 2026-02-28 03:51:35.431130 | controller | ok: [controller] 2026-02-28 03:51:35.431265 | controller | 2026-02-28 03:51:35.431518 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-02-28 03:51:35.431908 | controller | Saturday 28 February 2026 03:51:35 +0000 (0:00:00.413) 0:00:03.886 ***** 2026-02-28 03:51:35.479143 | controller | skipping: [controller] 2026-02-28 03:51:35.479321 | controller | 2026-02-28 03:51:35.479495 | controller | PLAY RECAP ********************************************************************* 2026-02-28 03:51:35.479714 | controller | controller : ok=7 changed=0 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-02-28 03:51:35.479899 | controller | 2026-02-28 03:51:35.480109 | controller | 2026-02-28 03:51:35.480293 | controller | TASKS RECAP ******************************************************************** 2026-02-28 03:51:35.480483 | controller | Saturday 28 February 2026 03:51:35 +0000 (0:00:00.049) 0:00:03.935 ***** 2026-02-28 03:51:35.480657 | controller | =============================================================================== 2026-02-28 03:51:35.480887 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.11s 2026-02-28 03:51:35.481053 | controller | Gathering Facts --------------------------------------------------------- 1.08s 2026-02-28 03:51:35.481239 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.77s 2026-02-28 03:51:35.481426 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.41s 2026-02-28 03:51:35.481649 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.39s 2026-02-28 03:51:35.481874 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.08s 2026-02-28 03:51:35.482082 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.05s 2026-02-28 03:51:35.482266 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.04s 2026-02-28 03:51:35.569703 | controller | INFO [upload-helm-chart > idempotence] Executed: Successful 2026-02-28 03:51:35.583104 | controller | INFO [upload-helm-chart > side_effect] Executing 2026-02-28 03:51:36.447250 | controller | 2026-02-28 03:51:36.447403 | controller | PLAY [Side effect] ************************************************************* 2026-02-28 03:51:36.447582 | controller | 2026-02-28 03:51:36.447780 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-28 03:51:36.447985 | controller | Saturday 28 February 2026 03:51:36 +0000 (0:00:00.011) 0:00:00.011 ***** 2026-02-28 03:51:38.540652 | 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-02-28 03:51:38.545785 | controller | ok: [controller] 2026-02-28 03:51:38.545796 | controller | 2026-02-28 03:51:38.545800 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-02-28 03:51:38.545805 | controller | Saturday 28 February 2026 03:51:38 +0000 (0:00:02.098) 0:00:02.110 ***** 2026-02-28 03:51:38.621836 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-02-28 03:51:38.622002 | controller | 2026-02-28 03:51:38.622169 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-02-28 03:51:38.622328 | controller | Saturday 28 February 2026 03:51:38 +0000 (0:00:00.076) 0:00:02.186 ***** 2026-02-28 03:51:39.336960 | controller | changed: [controller] 2026-02-28 03:51:39.337237 | controller | 2026-02-28 03:51:39.337515 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-02-28 03:51:39.337771 | controller | Saturday 28 February 2026 03:51:39 +0000 (0:00:00.714) 0:00:02.900 ***** 2026-02-28 03:51:39.384457 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-02-28 03:51:39.384726 | controller | 2026-02-28 03:51:39.384978 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-02-28 03:51:39.385222 | controller | Saturday 28 February 2026 03:51:39 +0000 (0:00:00.047) 0:00:02.948 ***** 2026-02-28 03:51:39.808963 | controller | ok: [controller] 2026-02-28 03:51:39.809243 | controller | 2026-02-28 03:51:39.809525 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-02-28 03:51:39.809782 | controller | Saturday 28 February 2026 03:51:39 +0000 (0:00:00.424) 0:00:03.372 ***** 2026-02-28 03:51:40.919925 | controller | ok: [controller] 2026-02-28 03:51:40.919992 | controller | 2026-02-28 03:51:40.920003 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-02-28 03:51:40.920011 | controller | Saturday 28 February 2026 03:51:40 +0000 (0:00:01.110) 0:00:04.483 ***** 2026-02-28 03:51:41.333570 | controller | ok: [controller] 2026-02-28 03:51:41.333726 | controller | 2026-02-28 03:51:41.333862 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-02-28 03:51:41.334417 | controller | Saturday 28 February 2026 03:51:41 +0000 (0:00:00.405) 0:00:04.888 ***** 2026-02-28 03:51:41.636379 | controller | changed: [controller] => (item=/file) 2026-02-28 03:51:41.636468 | controller | 2026-02-28 03:51:41.636592 | controller | PLAY RECAP ********************************************************************* 2026-02-28 03:51:41.636727 | controller | controller : ok=8 changed=2 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-02-28 03:51:41.636842 | controller | 2026-02-28 03:51:41.638261 | controller | 2026-02-28 03:51:41.638292 | controller | TASKS RECAP ******************************************************************** 2026-02-28 03:51:41.638308 | controller | Saturday 28 February 2026 03:51:41 +0000 (0:00:00.309) 0:00:05.198 ***** 2026-02-28 03:51:41.638313 | controller | =============================================================================== 2026-02-28 03:51:41.638318 | controller | Gathering Facts --------------------------------------------------------- 2.10s 2026-02-28 03:51:41.638322 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.11s 2026-02-28 03:51:41.638327 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.71s 2026-02-28 03:51:41.638332 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.42s 2026-02-28 03:51:41.638336 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.41s 2026-02-28 03:51:41.638342 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.31s 2026-02-28 03:51:41.638346 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.08s 2026-02-28 03:51:41.638351 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.05s 2026-02-28 03:51:41.731133 | controller | INFO [upload-helm-chart > side_effect] Executed: Successful 2026-02-28 03:51:41.744466 | controller | INFO [upload-helm-chart > verify] Executing 2026-02-28 03:51:42.555510 | controller | 2026-02-28 03:51:42.555670 | controller | PLAY [Verify] ****************************************************************** 2026-02-28 03:51:42.555783 | controller | 2026-02-28 03:51:42.555900 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-28 03:51:42.556059 | controller | Saturday 28 February 2026 03:51:42 +0000 (0:00:00.009) 0:00:00.009 ***** 2026-02-28 03:51:43.659396 | 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-02-28 03:51:43.668486 | controller | ok: [controller] 2026-02-28 03:51:43.668599 | controller | 2026-02-28 03:51:43.668729 | controller | TASK [Stat file 1] ************************************************************* 2026-02-28 03:51:43.668887 | controller | Saturday 28 February 2026 03:51:43 +0000 (0:00:01.109) 0:00:01.119 ***** 2026-02-28 03:51:44.096617 | controller | ok: [controller] 2026-02-28 03:51:44.096675 | controller | 2026-02-28 03:51:44.096799 | controller | TASK [Stat file 2] ************************************************************* 2026-02-28 03:51:44.096936 | controller | Saturday 28 February 2026 03:51:44 +0000 (0:00:00.427) 0:00:01.547 ***** 2026-02-28 03:51:44.425562 | controller | ok: [controller] 2026-02-28 03:51:44.425649 | controller | 2026-02-28 03:51:44.425760 | controller | TASK [Stat removed file 1] ***************************************************** 2026-02-28 03:51:44.425903 | controller | Saturday 28 February 2026 03:51:44 +0000 (0:00:00.332) 0:00:01.879 ***** 2026-02-28 03:51:44.732394 | controller | ok: [controller] 2026-02-28 03:51:44.732486 | controller | 2026-02-28 03:51:44.732674 | controller | TASK [Stat removed file 2] ***************************************************** 2026-02-28 03:51:44.732910 | controller | Saturday 28 February 2026 03:51:44 +0000 (0:00:00.306) 0:00:02.186 ***** 2026-02-28 03:51:45.044463 | controller | ok: [controller] 2026-02-28 03:51:45.044513 | controller | 2026-02-28 03:51:45.044570 | controller | TASK [ansible.builtin.assert] ************************************************** 2026-02-28 03:51:45.044661 | controller | Saturday 28 February 2026 03:51:45 +0000 (0:00:00.312) 0:00:02.498 ***** 2026-02-28 03:51:45.088078 | controller | ok: [controller] => { 2026-02-28 03:51:45.088147 | controller | "changed": false, 2026-02-28 03:51:45.088160 | controller | "msg": "All assertions passed" 2026-02-28 03:51:45.088174 | controller | } 2026-02-28 03:51:45.088186 | controller | 2026-02-28 03:51:45.088197 | controller | PLAY RECAP ********************************************************************* 2026-02-28 03:51:45.088210 | controller | controller : ok=6 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-02-28 03:51:45.088222 | controller | 2026-02-28 03:51:45.088248 | controller | 2026-02-28 03:51:45.088259 | controller | TASKS RECAP ******************************************************************** 2026-02-28 03:51:45.088270 | controller | Saturday 28 February 2026 03:51:45 +0000 (0:00:00.037) 0:00:02.536 ***** 2026-02-28 03:51:45.088281 | controller | =============================================================================== 2026-02-28 03:51:45.088291 | controller | Gathering Facts --------------------------------------------------------- 1.11s 2026-02-28 03:51:45.088301 | controller | Stat file 1 ------------------------------------------------------------- 0.43s 2026-02-28 03:51:45.088453 | controller | Stat file 2 ------------------------------------------------------------- 0.33s 2026-02-28 03:51:45.088824 | controller | Stat removed file 2 ----------------------------------------------------- 0.31s 2026-02-28 03:51:45.089237 | controller | Stat removed file 1 ----------------------------------------------------- 0.31s 2026-02-28 03:51:45.089526 | controller | ansible.builtin.assert -------------------------------------------------- 0.04s 2026-02-28 03:51:45.203316 | controller | INFO [upload-helm-chart > verify] Executed: Successful 2026-02-28 03:51:45.214801 | controller | INFO [upload-helm-chart > cleanup] Executing 2026-02-28 03:51:45.215264 | controller | WARNING [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-28 03:51:45.222595 | controller | INFO [upload-helm-chart > destroy] Executing 2026-02-28 03:51:45.222720 | controller | WARNING [upload-helm-chart > destroy] Skipping, '--destroy=never' requested. 2026-02-28 03:51:45.222809 | controller | INFO [upload-helm-chart > destroy] Executed: Successful 2026-02-28 03:51:45.223153 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-02-28 03:51:45.427513 | controller | ok: Runtime: 0:00:40.100048 2026-02-28 03:51:45.432067 | 2026-02-28 03:51:45.432132 | PLAY RECAP 2026-02-28 03:51:45.432193 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-28 03:51:45.432222 | 2026-02-28 03:51:45.577855 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-28 03:51:45.579491 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-02-28 03:51:46.115661 | 2026-02-28 03:51:46.115777 | PLAY [all] 2026-02-28 03:51:46.125338 | 2026-02-28 03:51:46.125410 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-28 03:51:46.170480 | controller | skipping: Conditional result was False 2026-02-28 03:51:46.174530 | 2026-02-28 03:51:46.174622 | TASK [fetch-output : Set log path for single node] 2026-02-28 03:51:46.213301 | controller | ok 2026-02-28 03:51:46.216696 | 2026-02-28 03:51:46.216770 | LOOP [fetch-output : Ensure local output dirs] 2026-02-28 03:51:46.576725 | controller -> localhost | ok: "/var/lib/zuul/builds/b906c610d0e948afb3f9ce94ebc21f06/work/logs" 2026-02-28 03:51:46.777243 | controller -> localhost | changed: "/var/lib/zuul/builds/b906c610d0e948afb3f9ce94ebc21f06/work/artifacts" 2026-02-28 03:51:46.991926 | controller -> localhost | changed: "/var/lib/zuul/builds/b906c610d0e948afb3f9ce94ebc21f06/work/docs" 2026-02-28 03:51:47.010001 | 2026-02-28 03:51:47.010858 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-28 03:51:47.697029 | controller | ok 2026-02-28 03:51:47.697257 | controller | changed: All items complete 2026-02-28 03:51:47.697296 | 2026-02-28 03:51:48.169375 | controller | changed: .d..t...... ./ 2026-02-28 03:51:48.682051 | controller | changed: .d..t...... ./ 2026-02-28 03:51:48.696207 | 2026-02-28 03:51:48.696328 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-28 03:51:49.093232 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006473 2026-02-28 03:51:49.310870 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006894 2026-02-28 03:51:49.326436 | 2026-02-28 03:51:49.326514 | PLAY [all] 2026-02-28 03:51:49.333420 | 2026-02-28 03:51:49.333498 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-28 03:51:49.774393 | controller | changed 2026-02-28 03:51:49.781345 | 2026-02-28 03:51:49.781398 | PLAY RECAP 2026-02-28 03:51:49.781448 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-02-28 03:51:49.781469 | 2026-02-28 03:51:49.940880 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-02-28 03:51:49.943848 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-28 03:51:50.517113 | 2026-02-28 03:51:50.517278 | PLAY [localhost] 2026-02-28 03:51:50.527816 | 2026-02-28 03:51:50.527887 | TASK [Generate Zuul manifest] 2026-02-28 03:51:50.551537 | localhost | ok 2026-02-28 03:51:50.568095 | 2026-02-28 03:51:50.568179 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-28 03:51:50.887566 | localhost | changed 2026-02-28 03:51:50.900475 | 2026-02-28 03:51:50.900646 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-28 03:51:50.932775 | localhost | ok 2026-02-28 03:51:50.940022 | 2026-02-28 03:51:50.940087 | TASK [Upload logs] 2026-02-28 03:51:50.958656 | localhost | ok 2026-02-28 03:51:51.020141 | 2026-02-28 03:51:51.020294 | TASK [Set zuul-log-path fact] 2026-02-28 03:51:51.038848 | localhost | ok 2026-02-28 03:51:51.049726 | 2026-02-28 03:51:51.049827 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-28 03:51:51.078514 | localhost | ok 2026-02-28 03:51:51.084858 | 2026-02-28 03:51:51.084921 | TASK [upload-logs : Create log directories] 2026-02-28 03:51:51.482074 | localhost | changed 2026-02-28 03:51:51.488721 | 2026-02-28 03:51:51.488819 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-28 03:51:51.888420 | localhost -> localhost | ok: Runtime: 0:00:00.006276 2026-02-28 03:51:51.895215 | 2026-02-28 03:51:51.895306 | TASK [upload-logs : Upload logs to log server] 2026-02-28 03:51:52.353169 | localhost | Output suppressed because no_log was given 2026-02-28 03:51:52.357861 | 2026-02-28 03:51:52.357944 | LOOP [upload-logs : Compress console log and json output] 2026-02-28 03:51:52.402010 | localhost | skipping: Conditional result was False 2026-02-28 03:51:52.408996 | localhost | skipping: Conditional result was False 2026-02-28 03:51:52.419765 | 2026-02-28 03:51:52.419923 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-28 03:51:52.461209 | localhost | skipping: Conditional result was False 2026-02-28 03:51:52.461578 | 2026-02-28 03:51:52.465476 | localhost | skipping: Conditional result was False 2026-02-28 03:51:52.475540 | 2026-02-28 03:51:52.475702 | LOOP [upload-logs : Upload console log and json output]