2026-02-28 19:25:45.449178 | Job console starting 2026-02-28 19:25:45.459938 | Updating git repos 2026-02-28 19:25:45.494719 | Cloning repos into workspace 2026-02-28 19:25:45.622180 | Restoring repo states 2026-02-28 19:25:45.637890 | Merging changes 2026-02-28 19:25:46.136816 | Checking out repos 2026-02-28 19:25:46.672995 | Preparing playbooks 2026-02-28 19:25:48.532472 | Running Ansible setup 2026-02-28 19:25:52.572783 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-02-28 19:25:53.204270 | 2026-02-28 19:25:53.204385 | PLAY [localhost] 2026-02-28 19:25:53.211600 | 2026-02-28 19:25:53.211672 | TASK [Gathering Facts] 2026-02-28 19:25:54.099199 | localhost | ok 2026-02-28 19:25:54.112844 | 2026-02-28 19:25:54.113157 | TASK [Setup log path fact] 2026-02-28 19:25:54.134822 | localhost | ok 2026-02-28 19:25:54.147407 | 2026-02-28 19:25:54.147486 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-28 19:25:54.182506 | localhost | ok 2026-02-28 19:25:54.193270 | 2026-02-28 19:25:54.211642 | TASK [emit-job-header : Print job information] 2026-02-28 19:25:54.253443 | # Job Information 2026-02-28 19:25:54.253652 | Ansible Version: 2.16.16 2026-02-28 19:25:54.253697 | Job: ansible-collection-kubernetes-molecule-upload-helm-chart 2026-02-28 19:25:54.253728 | Pipeline: check 2026-02-28 19:25:54.253757 | Executor: 0a8996d2b663 2026-02-28 19:25:54.253784 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/228 2026-02-28 19:25:54.253815 | Event ID: 26d2ab50-14db-11f1-80cc-4ed984950f1c 2026-02-28 19:25:54.257853 | 2026-02-28 19:25:54.257947 | LOOP [emit-job-header : Print node information] 2026-02-28 19:25:54.360253 | localhost | ok: 2026-02-28 19:25:54.360572 | localhost | # Node Information 2026-02-28 19:25:54.360619 | localhost | Inventory Hostname: controller 2026-02-28 19:25:54.360653 | localhost | Hostname: np0000157498 2026-02-28 19:25:54.360683 | localhost | Username: zuul 2026-02-28 19:25:54.360716 | localhost | Distro: Debian 13.3 2026-02-28 19:25:54.360746 | localhost | Provider: yul1 2026-02-28 19:25:54.360775 | localhost | Region: ca-ymq-1 2026-02-28 19:25:54.360802 | localhost | Label: debian-trixie 2026-02-28 19:25:54.360830 | localhost | Product Name: OpenStack Nova 2026-02-28 19:25:54.360858 | localhost | Interface IP: 162.253.55.43 2026-02-28 19:25:54.372388 | 2026-02-28 19:25:54.372571 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-28 19:25:54.999018 | localhost -> localhost | changed 2026-02-28 19:25:55.005725 | 2026-02-28 19:25:55.005797 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-28 19:25:55.857739 | localhost -> localhost | changed 2026-02-28 19:25:55.864407 | 2026-02-28 19:25:55.864462 | PLAY [all] 2026-02-28 19:25:55.871641 | 2026-02-28 19:25:55.871720 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-28 19:25:56.160705 | controller -> localhost | ok 2026-02-28 19:25:56.167093 | 2026-02-28 19:25:56.167159 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-28 19:25:56.197214 | controller | ok 2026-02-28 19:25:56.229574 | controller | included: /var/lib/zuul/builds/c3359be1afb8450f933bff203d8818df/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-28 19:25:56.238848 | 2026-02-28 19:25:56.249580 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-28 19:25:56.988826 | controller -> localhost | Generating public/private rsa key pair. 2026-02-28 19:25:56.990697 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/c3359be1afb8450f933bff203d8818df/work/c3359be1afb8450f933bff203d8818df_id_rsa 2026-02-28 19:25:56.990762 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/c3359be1afb8450f933bff203d8818df/work/c3359be1afb8450f933bff203d8818df_id_rsa.pub 2026-02-28 19:25:56.990798 | controller -> localhost | The key fingerprint is: 2026-02-28 19:25:56.990827 | controller -> localhost | SHA256:i3MwU8AudIiC9iJ9sWskb3Apy0nsb2x6E3hh4upIWSQ zuul-build-sshkey 2026-02-28 19:25:56.990871 | controller -> localhost | The key's randomart image is: 2026-02-28 19:25:56.990900 | controller -> localhost | +---[RSA 3072]----+ 2026-02-28 19:25:56.990938 | controller -> localhost | |. . o. | 2026-02-28 19:25:56.990968 | controller -> localhost | |o.. + o. | 2026-02-28 19:25:56.990995 | controller -> localhost | |.E.o * . | 2026-02-28 19:25:56.991023 | controller -> localhost | |..@.X .. | 2026-02-28 19:25:56.991049 | controller -> localhost | |.=./ ++ S | 2026-02-28 19:25:56.991076 | controller -> localhost | | X B = . | 2026-02-28 19:25:56.991103 | controller -> localhost | | + * .o o | 2026-02-28 19:25:56.991129 | controller -> localhost | |+ B o | 2026-02-28 19:25:56.991181 | controller -> localhost | |o..= . | 2026-02-28 19:25:56.991211 | controller -> localhost | +----[SHA256]-----+ 2026-02-28 19:25:56.991272 | controller -> localhost | ok: Runtime: 0:00:00.312519 2026-02-28 19:25:56.997826 | 2026-02-28 19:25:56.997898 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-28 19:25:57.031360 | controller | ok 2026-02-28 19:25:57.040203 | controller | included: /var/lib/zuul/builds/c3359be1afb8450f933bff203d8818df/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-28 19:25:57.047383 | 2026-02-28 19:25:57.047446 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-28 19:25:57.071031 | controller | skipping: Conditional result was False 2026-02-28 19:25:57.086538 | 2026-02-28 19:25:57.086628 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-28 19:25:57.689302 | controller | changed 2026-02-28 19:25:57.693382 | 2026-02-28 19:25:57.693453 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-28 19:25:57.904250 | controller | ok 2026-02-28 19:25:57.910080 | 2026-02-28 19:25:57.910168 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-28 19:25:58.483143 | controller | changed 2026-02-28 19:25:58.488642 | 2026-02-28 19:25:58.488729 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-28 19:25:59.016630 | controller | changed 2026-02-28 19:25:59.022748 | 2026-02-28 19:25:59.022865 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-28 19:25:59.048408 | controller | skipping: Conditional result was False 2026-02-28 19:25:59.062709 | 2026-02-28 19:25:59.062853 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-28 19:25:59.546990 | controller -> localhost | changed 2026-02-28 19:25:59.555346 | 2026-02-28 19:25:59.555413 | TASK [add-build-sshkey : Add back temp key] 2026-02-28 19:25:59.848272 | controller -> localhost | Identity added: /var/lib/zuul/builds/c3359be1afb8450f933bff203d8818df/work/c3359be1afb8450f933bff203d8818df_id_rsa (zuul-build-sshkey) 2026-02-28 19:25:59.848503 | controller -> localhost | ok: Runtime: 0:00:00.011773 2026-02-28 19:25:59.855365 | 2026-02-28 19:25:59.855428 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-28 19:26:00.249831 | controller | ok 2026-02-28 19:26:00.255211 | 2026-02-28 19:26:00.255295 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-28 19:26:00.280415 | controller | skipping: Conditional result was False 2026-02-28 19:26:00.290817 | 2026-02-28 19:26:00.290900 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-28 19:26:00.657336 | controller | ok 2026-02-28 19:26:00.665876 | 2026-02-28 19:26:00.665963 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-28 19:26:01.828816 | controller | Output suppressed because no_log was given 2026-02-28 19:26:01.838357 | 2026-02-28 19:26:01.838424 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-28 19:26:02.055414 | controller | ok: "logs" 2026-02-28 19:26:02.055724 | controller | ok: All items complete 2026-02-28 19:26:02.055773 | 2026-02-28 19:26:02.242034 | controller | ok: "artifacts" 2026-02-28 19:26:02.437906 | controller | ok: "docs" 2026-02-28 19:26:02.452479 | 2026-02-28 19:26:02.452629 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-28 19:26:02.681092 | controller | changed: "logs" 2026-02-28 19:26:02.873176 | controller | changed: "artifacts" 2026-02-28 19:26:03.058646 | controller | changed: "docs" 2026-02-28 19:26:03.073418 | 2026-02-28 19:26:03.073580 | PLAY RECAP 2026-02-28 19:26:03.073645 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-02-28 19:26:03.073684 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-28 19:26:03.073714 | 2026-02-28 19:26:03.226081 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-02-28 19:26:03.227345 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-28 19:26:03.812599 | 2026-02-28 19:26:03.812710 | PLAY [all] 2026-02-28 19:26:03.822709 | 2026-02-28 19:26:03.822783 | TASK [setup-uv : Extract archive] 2026-02-28 19:26:06.242575 | controller | changed 2026-02-28 19:26:06.247416 | 2026-02-28 19:26:06.247494 | TASK [setup-uv : Print version] 2026-02-28 19:26:06.831217 | controller | uv 0.8.13 2026-02-28 19:26:06.798768 | controller | ok: Runtime: 0:00:00.013130 2026-02-28 19:26:06.803698 | 2026-02-28 19:26:06.803751 | PLAY RECAP 2026-02-28 19:26:06.803795 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-28 19:26:06.803819 | 2026-02-28 19:26:06.996763 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-28 19:26:06.998713 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-28 19:26:07.591427 | 2026-02-28 19:26:07.591562 | PLAY [all] 2026-02-28 19:26:07.601612 | 2026-02-28 19:26:07.601688 | TASK [Copy inventory file for Zuul] 2026-02-28 19:26:08.594082 | controller | changed 2026-02-28 19:26:08.599643 | 2026-02-28 19:26:08.599743 | TASK [Switch "ansible_host" to private IP] 2026-02-28 19:26:08.912584 | controller | changed: 1 replacements made 2026-02-28 19:26:08.917980 | 2026-02-28 19:26:08.918047 | TASK [Run Molecule scenario] 2026-02-28 19:26:09.497577 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-02-28 19:26:09.497718 | controller | Creating virtual environment at: .venv 2026-02-28 19:26:10.020125 | controller | Downloading pygments (1.2MiB) 2026-02-28 19:26:10.020829 | controller | Downloading cryptography (4.3MiB) 2026-02-28 19:26:10.021233 | controller | Downloading ansible-core (2.3MiB) 2026-02-28 19:26:10.188925 | controller | Downloading cryptography 2026-02-28 19:26:10.227627 | controller | Downloading pygments 2026-02-28 19:26:10.400861 | controller | Downloading ansible-core 2026-02-28 19:26:10.450114 | controller | Installed 36 packages in 48ms 2026-02-28 19:26:11.114379 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-02-28 19:26:11.114450 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-02-28 19:26:12.095839 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-02-28 19:26:12.095894 | controller | INFO default scenario not found, disabling shared state. 2026-02-28 19:26:12.096041 | 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 19:26:12.096106 | controller | INFO [upload-helm-chart > prerun] Performing prerun with role_name_check=0... 2026-02-28 19:26:25.917371 | controller | INFO [upload-helm-chart > dependency] Executing 2026-02-28 19:26:25.917457 | controller | WARNING [upload-helm-chart > dependency] Missing roles requirements file: requirements.yml 2026-02-28 19:26:25.917468 | controller | WARNING [upload-helm-chart > dependency] Missing collections requirements file: collections.yml 2026-02-28 19:26:25.917507 | controller | WARNING [upload-helm-chart > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-02-28 19:26:25.923743 | controller | INFO [upload-helm-chart > cleanup] Executing 2026-02-28 19:26:25.924024 | controller | WARNING [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-28 19:26:25.929475 | controller | INFO [upload-helm-chart > destroy] Executing 2026-02-28 19:26:25.929502 | controller | WARNING [upload-helm-chart > destroy] Skipping, '--destroy=never' requested. 2026-02-28 19:26:25.929601 | controller | INFO [upload-helm-chart > destroy] Executed: Successful 2026-02-28 19:26:25.934822 | controller | INFO [upload-helm-chart > syntax] Executing 2026-02-28 19:26:26.802794 | controller | 2026-02-28 19:26:26.803028 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/upload-helm-chart/converge.yml 2026-02-28 19:26:26.866861 | controller | INFO [upload-helm-chart > syntax] Executed: Successful 2026-02-28 19:26:26.878068 | controller | INFO [upload-helm-chart > create] Executing 2026-02-28 19:26:26.879606 | controller | WARNING [upload-helm-chart > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-28 19:26:26.885284 | controller | INFO [upload-helm-chart > prepare] Executing 2026-02-28 19:26:26.885586 | controller | WARNING [upload-helm-chart > prepare] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-28 19:26:26.890932 | controller | INFO [upload-helm-chart > converge] Executing 2026-02-28 19:26:27.794348 | controller | 2026-02-28 19:26:27.794442 | controller | PLAY [Converge] **************************************************************** 2026-02-28 19:26:27.794491 | controller | 2026-02-28 19:26:27.794617 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-28 19:26:27.794731 | controller | Saturday 28 February 2026 19:26:27 +0000 (0:00:00.036) 0:00:00.036 ***** 2026-02-28 19:26:29.068696 | 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 19:26:29.075626 | controller | ok: [controller] 2026-02-28 19:26:29.075815 | controller | 2026-02-28 19:26:29.076011 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-02-28 19:26:29.076210 | controller | Saturday 28 February 2026 19:26:29 +0000 (0:00:01.292) 0:00:01.328 ***** 2026-02-28 19:26:29.162076 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-02-28 19:26:29.162254 | controller | 2026-02-28 19:26:29.162451 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-02-28 19:26:29.162644 | controller | Saturday 28 February 2026 19:26:29 +0000 (0:00:00.086) 0:00:01.414 ***** 2026-02-28 19:26:29.926829 | controller | changed: [controller] 2026-02-28 19:26:29.926918 | controller | 2026-02-28 19:26:29.927184 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-02-28 19:26:29.927367 | controller | Saturday 28 February 2026 19:26:29 +0000 (0:00:00.764) 0:00:02.179 ***** 2026-02-28 19:26:29.969719 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-02-28 19:26:29.969905 | controller | 2026-02-28 19:26:29.970096 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-02-28 19:26:29.970315 | controller | Saturday 28 February 2026 19:26:29 +0000 (0:00:00.043) 0:00:02.223 ***** 2026-02-28 19:26:30.381561 | controller | changed: [controller] 2026-02-28 19:26:30.381677 | controller | 2026-02-28 19:26:30.381941 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-02-28 19:26:30.382142 | controller | Saturday 28 February 2026 19:26:30 +0000 (0:00:00.411) 0:00:02.634 ***** 2026-02-28 19:26:31.522019 | controller | changed: [controller] 2026-02-28 19:26:31.522172 | controller | 2026-02-28 19:26:31.522387 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-02-28 19:26:31.522604 | controller | Saturday 28 February 2026 19:26:31 +0000 (0:00:01.138) 0:00:03.772 ***** 2026-02-28 19:26:31.950555 | controller | ok: [controller] 2026-02-28 19:26:31.950617 | controller | 2026-02-28 19:26:31.950725 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-02-28 19:26:31.950850 | controller | Saturday 28 February 2026 19:26:31 +0000 (0:00:00.427) 0:00:04.200 ***** 2026-02-28 19:26:31.996457 | controller | skipping: [controller] 2026-02-28 19:26:31.996569 | controller | 2026-02-28 19:26:31.996691 | controller | PLAY RECAP ********************************************************************* 2026-02-28 19:26:31.996830 | controller | controller : ok=7 changed=3 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-02-28 19:26:31.996942 | controller | 2026-02-28 19:26:31.997041 | controller | 2026-02-28 19:26:31.997177 | controller | TASKS RECAP ******************************************************************** 2026-02-28 19:26:31.997287 | controller | Saturday 28 February 2026 19:26:31 +0000 (0:00:00.050) 0:00:04.250 ***** 2026-02-28 19:26:31.997914 | controller | =============================================================================== 2026-02-28 19:26:31.997967 | controller | Gathering Facts --------------------------------------------------------- 1.29s 2026-02-28 19:26:31.997976 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.14s 2026-02-28 19:26:31.997984 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.76s 2026-02-28 19:26:31.998017 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.43s 2026-02-28 19:26:31.998031 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.41s 2026-02-28 19:26:31.998187 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.09s 2026-02-28 19:26:31.998308 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.05s 2026-02-28 19:26:31.998433 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.04s 2026-02-28 19:26:32.088319 | controller | INFO [upload-helm-chart > converge] Executed: Successful 2026-02-28 19:26:32.096441 | controller | INFO [upload-helm-chart > idempotence] Executing 2026-02-28 19:26:32.948986 | controller | 2026-02-28 19:26:32.949107 | controller | PLAY [Converge] **************************************************************** 2026-02-28 19:26:32.949283 | controller | 2026-02-28 19:26:32.949474 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-28 19:26:32.949780 | controller | Saturday 28 February 2026 19:26:32 +0000 (0:00:00.011) 0:00:00.011 ***** 2026-02-28 19:26:34.993665 | 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 19:26:34.999606 | controller | ok: [controller] 2026-02-28 19:26:34.999797 | controller | 2026-02-28 19:26:35.000009 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-02-28 19:26:35.000204 | controller | Saturday 28 February 2026 19:26:34 +0000 (0:00:02.050) 0:00:02.061 ***** 2026-02-28 19:26:35.076113 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-02-28 19:26:35.076190 | controller | 2026-02-28 19:26:35.076424 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-02-28 19:26:35.076615 | controller | Saturday 28 February 2026 19:26:35 +0000 (0:00:00.076) 0:00:02.138 ***** 2026-02-28 19:26:35.819593 | controller | ok: [controller] 2026-02-28 19:26:35.819739 | controller | 2026-02-28 19:26:35.819948 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-02-28 19:26:35.820150 | controller | Saturday 28 February 2026 19:26:35 +0000 (0:00:00.742) 0:00:02.881 ***** 2026-02-28 19:26:35.868607 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-02-28 19:26:35.868742 | controller | 2026-02-28 19:26:35.868937 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-02-28 19:26:35.869124 | controller | Saturday 28 February 2026 19:26:35 +0000 (0:00:00.049) 0:00:02.930 ***** 2026-02-28 19:26:36.300402 | controller | ok: [controller] 2026-02-28 19:26:36.300512 | controller | 2026-02-28 19:26:36.300720 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-02-28 19:26:36.300911 | controller | Saturday 28 February 2026 19:26:36 +0000 (0:00:00.430) 0:00:03.361 ***** 2026-02-28 19:26:37.394809 | controller | ok: [controller] 2026-02-28 19:26:37.394850 | controller | 2026-02-28 19:26:37.394855 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-02-28 19:26:37.394859 | controller | Saturday 28 February 2026 19:26:37 +0000 (0:00:01.090) 0:00:04.451 ***** 2026-02-28 19:26:37.780863 | controller | ok: [controller] 2026-02-28 19:26:37.780951 | controller | 2026-02-28 19:26:37.781133 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-02-28 19:26:37.781281 | controller | Saturday 28 February 2026 19:26:37 +0000 (0:00:00.390) 0:00:04.842 ***** 2026-02-28 19:26:37.822152 | controller | skipping: [controller] 2026-02-28 19:26:37.822273 | controller | 2026-02-28 19:26:37.822424 | controller | PLAY RECAP ********************************************************************* 2026-02-28 19:26:37.822578 | controller | controller : ok=7 changed=0 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-02-28 19:26:37.822703 | controller | 2026-02-28 19:26:37.822827 | controller | 2026-02-28 19:26:37.822957 | controller | TASKS RECAP ******************************************************************** 2026-02-28 19:26:37.823093 | controller | Saturday 28 February 2026 19:26:37 +0000 (0:00:00.042) 0:00:04.884 ***** 2026-02-28 19:26:37.823208 | controller | =============================================================================== 2026-02-28 19:26:37.823337 | controller | Gathering Facts --------------------------------------------------------- 2.05s 2026-02-28 19:26:37.823473 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.09s 2026-02-28 19:26:37.823612 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.74s 2026-02-28 19:26:37.823747 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.43s 2026-02-28 19:26:37.823883 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.39s 2026-02-28 19:26:37.824023 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.08s 2026-02-28 19:26:37.824159 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.05s 2026-02-28 19:26:37.824296 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.04s 2026-02-28 19:26:37.959955 | controller | INFO [upload-helm-chart > idempotence] Executed: Successful 2026-02-28 19:26:37.967617 | controller | INFO [upload-helm-chart > side_effect] Executing 2026-02-28 19:26:38.803140 | controller | 2026-02-28 19:26:38.803274 | controller | PLAY [Side effect] ************************************************************* 2026-02-28 19:26:38.803457 | controller | 2026-02-28 19:26:38.803651 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-28 19:26:38.803847 | controller | Saturday 28 February 2026 19:26:38 +0000 (0:00:00.010) 0:00:00.010 ***** 2026-02-28 19:26:39.845697 | 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 19:26:39.852535 | controller | ok: [controller] 2026-02-28 19:26:39.852706 | controller | 2026-02-28 19:26:39.852979 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-02-28 19:26:39.853246 | controller | Saturday 28 February 2026 19:26:39 +0000 (0:00:01.049) 0:00:01.060 ***** 2026-02-28 19:26:39.926474 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-02-28 19:26:39.926605 | controller | 2026-02-28 19:26:39.926843 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-02-28 19:26:39.927041 | controller | Saturday 28 February 2026 19:26:39 +0000 (0:00:00.073) 0:00:01.133 ***** 2026-02-28 19:26:40.628927 | controller | changed: [controller] 2026-02-28 19:26:40.629047 | controller | 2026-02-28 19:26:40.629264 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-02-28 19:26:40.629455 | controller | Saturday 28 February 2026 19:26:40 +0000 (0:00:00.701) 0:00:01.835 ***** 2026-02-28 19:26:40.666137 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-02-28 19:26:40.666282 | controller | 2026-02-28 19:26:40.666478 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-02-28 19:26:40.666669 | controller | Saturday 28 February 2026 19:26:40 +0000 (0:00:00.037) 0:00:01.873 ***** 2026-02-28 19:26:41.101431 | controller | ok: [controller] 2026-02-28 19:26:41.101465 | controller | 2026-02-28 19:26:41.101475 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-02-28 19:26:41.101483 | controller | Saturday 28 February 2026 19:26:41 +0000 (0:00:00.434) 0:00:02.307 ***** 2026-02-28 19:26:42.169816 | controller | ok: [controller] 2026-02-28 19:26:42.169866 | controller | 2026-02-28 19:26:42.170020 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-02-28 19:26:42.170136 | controller | Saturday 28 February 2026 19:26:42 +0000 (0:00:01.069) 0:00:03.376 ***** 2026-02-28 19:26:42.582253 | controller | ok: [controller] 2026-02-28 19:26:42.582281 | controller | 2026-02-28 19:26:42.582287 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-02-28 19:26:42.582292 | controller | Saturday 28 February 2026 19:26:42 +0000 (0:00:00.409) 0:00:03.786 ***** 2026-02-28 19:26:42.915887 | controller | changed: [controller] => (item=/file) 2026-02-28 19:26:42.916066 | controller | 2026-02-28 19:26:42.916216 | controller | PLAY RECAP ********************************************************************* 2026-02-28 19:26:42.916374 | controller | controller : ok=8 changed=2 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-02-28 19:26:42.916507 | controller | 2026-02-28 19:26:42.916635 | controller | 2026-02-28 19:26:42.916774 | controller | TASKS RECAP ******************************************************************** 2026-02-28 19:26:42.916936 | controller | Saturday 28 February 2026 19:26:42 +0000 (0:00:00.336) 0:00:04.123 ***** 2026-02-28 19:26:42.917071 | controller | =============================================================================== 2026-02-28 19:26:42.917219 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.07s 2026-02-28 19:26:42.917358 | controller | Gathering Facts --------------------------------------------------------- 1.05s 2026-02-28 19:26:42.917498 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.70s 2026-02-28 19:26:42.917646 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.43s 2026-02-28 19:26:42.917825 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.41s 2026-02-28 19:26:42.917979 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.34s 2026-02-28 19:26:42.918122 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.07s 2026-02-28 19:26:42.918268 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.04s 2026-02-28 19:26:42.991270 | controller | INFO [upload-helm-chart > side_effect] Executed: Successful 2026-02-28 19:26:43.080264 | controller | INFO [upload-helm-chart > verify] Executing 2026-02-28 19:26:43.892442 | controller | 2026-02-28 19:26:43.892484 | controller | PLAY [Verify] ****************************************************************** 2026-02-28 19:26:43.892555 | controller | 2026-02-28 19:26:43.892654 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-28 19:26:43.892734 | controller | Saturday 28 February 2026 19:26:43 +0000 (0:00:00.010) 0:00:00.010 ***** 2026-02-28 19:26:44.926220 | 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 19:26:44.928234 | controller | ok: [controller] 2026-02-28 19:26:44.928303 | controller | 2026-02-28 19:26:44.928389 | controller | TASK [Stat file 1] ************************************************************* 2026-02-28 19:26:44.928477 | controller | Saturday 28 February 2026 19:26:44 +0000 (0:00:01.035) 0:00:01.046 ***** 2026-02-28 19:26:45.323243 | controller | ok: [controller] 2026-02-28 19:26:45.323375 | controller | 2026-02-28 19:26:45.323542 | controller | TASK [Stat file 2] ************************************************************* 2026-02-28 19:26:45.323693 | controller | Saturday 28 February 2026 19:26:45 +0000 (0:00:00.394) 0:00:01.441 ***** 2026-02-28 19:26:45.645441 | controller | ok: [controller] 2026-02-28 19:26:45.645501 | controller | 2026-02-28 19:26:45.645601 | controller | TASK [Stat removed file 1] ***************************************************** 2026-02-28 19:26:45.645794 | controller | Saturday 28 February 2026 19:26:45 +0000 (0:00:00.322) 0:00:01.763 ***** 2026-02-28 19:26:45.929190 | controller | ok: [controller] 2026-02-28 19:26:45.929243 | controller | 2026-02-28 19:26:45.929421 | controller | TASK [Stat removed file 2] ***************************************************** 2026-02-28 19:26:45.929594 | controller | Saturday 28 February 2026 19:26:45 +0000 (0:00:00.281) 0:00:02.044 ***** 2026-02-28 19:26:46.243700 | controller | ok: [controller] 2026-02-28 19:26:46.243748 | controller | 2026-02-28 19:26:46.243842 | controller | TASK [ansible.builtin.assert] ************************************************** 2026-02-28 19:26:46.243929 | controller | Saturday 28 February 2026 19:26:46 +0000 (0:00:00.317) 0:00:02.362 ***** 2026-02-28 19:26:46.274609 | controller | ok: [controller] => { 2026-02-28 19:26:46.277041 | controller | "changed": false, 2026-02-28 19:26:46.277071 | controller | "msg": "All assertions passed" 2026-02-28 19:26:46.277077 | controller | } 2026-02-28 19:26:46.277083 | controller | 2026-02-28 19:26:46.277088 | controller | PLAY RECAP ********************************************************************* 2026-02-28 19:26:46.277094 | controller | controller : ok=6 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-02-28 19:26:46.277099 | controller | 2026-02-28 19:26:46.277112 | controller | 2026-02-28 19:26:46.277117 | controller | TASKS RECAP ******************************************************************** 2026-02-28 19:26:46.277122 | controller | Saturday 28 February 2026 19:26:46 +0000 (0:00:00.031) 0:00:02.393 ***** 2026-02-28 19:26:46.277127 | controller | =============================================================================== 2026-02-28 19:26:46.277132 | controller | Gathering Facts --------------------------------------------------------- 1.04s 2026-02-28 19:26:46.277137 | controller | Stat file 1 ------------------------------------------------------------- 0.39s 2026-02-28 19:26:46.277141 | controller | Stat file 2 ------------------------------------------------------------- 0.32s 2026-02-28 19:26:46.277146 | controller | Stat removed file 2 ----------------------------------------------------- 0.32s 2026-02-28 19:26:46.277151 | controller | Stat removed file 1 ----------------------------------------------------- 0.28s 2026-02-28 19:26:46.277155 | controller | ansible.builtin.assert -------------------------------------------------- 0.03s 2026-02-28 19:26:46.377674 | controller | INFO [upload-helm-chart > verify] Executed: Successful 2026-02-28 19:26:46.388631 | controller | INFO [upload-helm-chart > cleanup] Executing 2026-02-28 19:26:46.388976 | controller | WARNING [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-28 19:26:46.394414 | controller | INFO [upload-helm-chart > destroy] Executing 2026-02-28 19:26:46.394508 | controller | WARNING [upload-helm-chart > destroy] Skipping, '--destroy=never' requested. 2026-02-28 19:26:46.394630 | controller | INFO [upload-helm-chart > destroy] Executed: Successful 2026-02-28 19:26:46.394937 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-02-28 19:26:46.640988 | controller | ok: Runtime: 0:00:37.056718 2026-02-28 19:26:46.645988 | 2026-02-28 19:26:46.646062 | PLAY RECAP 2026-02-28 19:26:46.646127 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-28 19:26:46.646158 | 2026-02-28 19:26:46.787690 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-28 19:26:46.790634 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-02-28 19:26:47.347194 | 2026-02-28 19:26:47.347311 | PLAY [all] 2026-02-28 19:26:47.356955 | 2026-02-28 19:26:47.357043 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-28 19:26:47.401616 | controller | skipping: Conditional result was False 2026-02-28 19:26:47.410526 | 2026-02-28 19:26:47.410707 | TASK [fetch-output : Set log path for single node] 2026-02-28 19:26:47.455148 | controller | ok 2026-02-28 19:26:47.458695 | 2026-02-28 19:26:47.458775 | LOOP [fetch-output : Ensure local output dirs] 2026-02-28 19:26:47.878225 | controller -> localhost | ok: "/var/lib/zuul/builds/c3359be1afb8450f933bff203d8818df/work/logs" 2026-02-28 19:26:48.090931 | controller -> localhost | changed: "/var/lib/zuul/builds/c3359be1afb8450f933bff203d8818df/work/artifacts" 2026-02-28 19:26:48.327635 | controller -> localhost | changed: "/var/lib/zuul/builds/c3359be1afb8450f933bff203d8818df/work/docs" 2026-02-28 19:26:48.346873 | 2026-02-28 19:26:48.347047 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-28 19:26:48.996163 | controller | ok 2026-02-28 19:26:48.996512 | controller | changed: All items complete 2026-02-28 19:26:48.996572 | 2026-02-28 19:26:49.467499 | controller | changed: .d..t...... ./ 2026-02-28 19:26:49.930216 | controller | changed: .d..t...... ./ 2026-02-28 19:26:49.951941 | 2026-02-28 19:26:49.952052 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-28 19:26:50.365563 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008305 2026-02-28 19:26:50.598678 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007458 2026-02-28 19:26:50.619408 | 2026-02-28 19:26:50.619575 | PLAY [all] 2026-02-28 19:26:50.625187 | 2026-02-28 19:26:50.625255 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-28 19:26:51.065504 | controller | changed 2026-02-28 19:26:51.072532 | 2026-02-28 19:26:51.072600 | PLAY RECAP 2026-02-28 19:26:51.072669 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-02-28 19:26:51.072700 | 2026-02-28 19:26:51.214967 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-02-28 19:26:51.217577 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-28 19:26:51.796147 | 2026-02-28 19:26:51.796285 | PLAY [localhost] 2026-02-28 19:26:51.805896 | 2026-02-28 19:26:51.805965 | TASK [Generate Zuul manifest] 2026-02-28 19:26:51.827823 | localhost | ok 2026-02-28 19:26:51.843339 | 2026-02-28 19:26:51.843414 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-28 19:26:52.138222 | localhost | changed 2026-02-28 19:26:52.149205 | 2026-02-28 19:26:52.149275 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-28 19:26:52.179295 | localhost | ok 2026-02-28 19:26:52.188116 | 2026-02-28 19:26:52.188180 | TASK [Upload logs] 2026-02-28 19:26:52.207979 | localhost | ok 2026-02-28 19:26:52.304300 | 2026-02-28 19:26:52.304443 | TASK [Set zuul-log-path fact] 2026-02-28 19:26:52.323037 | localhost | ok 2026-02-28 19:26:52.335842 | 2026-02-28 19:26:52.335934 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-28 19:26:52.368078 | localhost | ok 2026-02-28 19:26:52.374267 | 2026-02-28 19:26:52.374330 | TASK [upload-logs : Create log directories] 2026-02-28 19:26:52.756442 | localhost | changed 2026-02-28 19:26:52.762626 | 2026-02-28 19:26:52.762696 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-28 19:26:53.169093 | localhost -> localhost | ok: Runtime: 0:00:00.003455 2026-02-28 19:26:53.176443 | 2026-02-28 19:26:53.176511 | TASK [upload-logs : Upload logs to log server] 2026-02-28 19:26:53.611188 | localhost | Output suppressed because no_log was given 2026-02-28 19:26:53.616101 | 2026-02-28 19:26:53.616168 | LOOP [upload-logs : Compress console log and json output] 2026-02-28 19:26:53.663672 | localhost | skipping: Conditional result was False 2026-02-28 19:26:53.671116 | localhost | skipping: Conditional result was False 2026-02-28 19:26:53.688396 | 2026-02-28 19:26:53.688520 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-28 19:26:53.733116 | localhost | skipping: Conditional result was False 2026-02-28 19:26:53.733479 | 2026-02-28 19:26:53.737345 | localhost | skipping: Conditional result was False 2026-02-28 19:26:53.754056 | 2026-02-28 19:26:53.754233 | LOOP [upload-logs : Upload console log and json output]