2026-03-09 15:23:22.691661 | Job console starting 2026-03-09 15:23:22.705065 | Updating git repos 2026-03-09 15:23:22.757518 | Cloning repos into workspace 2026-03-09 15:23:22.851455 | Restoring repo states 2026-03-09 15:23:22.862863 | Merging changes 2026-03-09 15:23:23.572930 | Checking out repos 2026-03-09 15:23:23.643920 | Preparing playbooks 2026-03-09 15:23:26.093312 | Running Ansible setup 2026-03-09 15:23:30.295227 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-09 15:23:30.953550 | 2026-03-09 15:23:30.953735 | PLAY [localhost] 2026-03-09 15:23:30.964370 | 2026-03-09 15:23:30.964474 | TASK [Gathering Facts] 2026-03-09 15:23:31.880714 | localhost | ok 2026-03-09 15:23:31.963854 | 2026-03-09 15:23:31.964163 | TASK [Setup log path fact] 2026-03-09 15:23:31.987602 | localhost | ok 2026-03-09 15:23:32.029482 | 2026-03-09 15:23:32.029613 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-09 15:23:32.071188 | localhost | ok 2026-03-09 15:23:32.080208 | 2026-03-09 15:23:32.080312 | TASK [emit-job-header : Print job information] 2026-03-09 15:23:32.125179 | # Job Information 2026-03-09 15:23:32.125433 | Ansible Version: 2.16.16 2026-03-09 15:23:32.125500 | Job: ansible-collection-kubernetes-molecule-upload-helm-chart 2026-03-09 15:23:32.125546 | Pipeline: check 2026-03-09 15:23:32.125589 | Executor: 0a8996d2b663 2026-03-09 15:23:32.125631 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/236 2026-03-09 15:23:32.125678 | Event ID: c2871620-1bcb-11f1-9a65-bf7aaf81b926 2026-03-09 15:23:32.131259 | 2026-03-09 15:23:32.131326 | LOOP [emit-job-header : Print node information] 2026-03-09 15:23:32.213798 | localhost | ok: 2026-03-09 15:23:32.214007 | localhost | # Node Information 2026-03-09 15:23:32.214036 | localhost | Inventory Hostname: controller 2026-03-09 15:23:32.214057 | localhost | Hostname: np0000158214 2026-03-09 15:23:32.214077 | localhost | Username: zuul 2026-03-09 15:23:32.214098 | localhost | Distro: Debian 13.3 2026-03-09 15:23:32.214118 | localhost | Provider: yul1 2026-03-09 15:23:32.214137 | localhost | Region: ca-ymq-1 2026-03-09 15:23:32.214156 | localhost | Label: debian-trixie 2026-03-09 15:23:32.214174 | localhost | Product Name: OpenStack Nova 2026-03-09 15:23:32.214192 | localhost | Interface IP: 199.204.45.70 2026-03-09 15:23:32.229410 | 2026-03-09 15:23:32.229645 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-09 15:23:32.659824 | localhost -> localhost | changed 2026-03-09 15:23:32.667838 | 2026-03-09 15:23:32.667910 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-09 15:23:33.631880 | localhost -> localhost | changed 2026-03-09 15:23:33.646146 | 2026-03-09 15:23:33.646223 | PLAY [all] 2026-03-09 15:23:33.653894 | 2026-03-09 15:23:33.653965 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-09 15:23:33.920764 | controller -> localhost | ok 2026-03-09 15:23:33.929877 | 2026-03-09 15:23:33.929946 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-09 15:23:33.958371 | controller | ok 2026-03-09 15:23:33.974171 | controller | included: /var/lib/zuul/builds/18269cbb7eb7489098289af7fd60a961/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-09 15:23:33.978928 | 2026-03-09 15:23:33.979001 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-09 15:23:34.935855 | controller -> localhost | Generating public/private rsa key pair. 2026-03-09 15:23:34.952727 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/18269cbb7eb7489098289af7fd60a961/work/18269cbb7eb7489098289af7fd60a961_id_rsa 2026-03-09 15:23:34.952939 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/18269cbb7eb7489098289af7fd60a961/work/18269cbb7eb7489098289af7fd60a961_id_rsa.pub 2026-03-09 15:23:34.953230 | controller -> localhost | The key fingerprint is: 2026-03-09 15:23:34.953291 | controller -> localhost | SHA256:Wf18zXv/8ngJO3wxBIilQ4iez/0ZWAOd/4/3uCmL8ug zuul-build-sshkey 2026-03-09 15:23:34.953360 | controller -> localhost | The key's randomart image is: 2026-03-09 15:23:34.953406 | controller -> localhost | +---[RSA 3072]----+ 2026-03-09 15:23:34.953460 | controller -> localhost | | . ..+.o | 2026-03-09 15:23:34.953506 | controller -> localhost | | . ..o.= . | 2026-03-09 15:23:34.953549 | controller -> localhost | | . . oo o . | 2026-03-09 15:23:34.953592 | controller -> localhost | | o o.o + o.| 2026-03-09 15:23:34.953634 | controller -> localhost | | o S o . = +| 2026-03-09 15:23:34.953675 | controller -> localhost | | o o . . =.| 2026-03-09 15:23:34.953717 | controller -> localhost | | . + oo*| 2026-03-09 15:23:34.953759 | controller -> localhost | | .. +.+o**| 2026-03-09 15:23:34.953806 | controller -> localhost | | .Eoo .oB*B| 2026-03-09 15:23:34.953849 | controller -> localhost | +----[SHA256]-----+ 2026-03-09 15:23:34.953953 | controller -> localhost | ok: Runtime: 0:00:00.447041 2026-03-09 15:23:34.964017 | 2026-03-09 15:23:34.964104 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-09 15:23:35.005141 | controller | ok 2026-03-09 15:23:35.015491 | controller | included: /var/lib/zuul/builds/18269cbb7eb7489098289af7fd60a961/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-09 15:23:35.021933 | 2026-03-09 15:23:35.021997 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-09 15:23:35.046796 | controller | skipping: Conditional result was False 2026-03-09 15:23:35.057435 | 2026-03-09 15:23:35.057497 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-09 15:23:35.610233 | controller | changed 2026-03-09 15:23:35.615668 | 2026-03-09 15:23:35.615751 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-09 15:23:35.819420 | controller | ok 2026-03-09 15:23:35.823393 | 2026-03-09 15:23:35.823450 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-09 15:23:36.399697 | controller | changed 2026-03-09 15:23:36.403968 | 2026-03-09 15:23:36.404028 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-09 15:23:36.954758 | controller | changed 2026-03-09 15:23:36.962423 | 2026-03-09 15:23:36.962513 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-09 15:23:36.987763 | controller | skipping: Conditional result was False 2026-03-09 15:23:37.039513 | 2026-03-09 15:23:37.039605 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-09 15:23:37.426031 | controller -> localhost | changed 2026-03-09 15:23:37.434514 | 2026-03-09 15:23:37.434574 | TASK [add-build-sshkey : Add back temp key] 2026-03-09 15:23:37.769322 | controller -> localhost | Identity added: /var/lib/zuul/builds/18269cbb7eb7489098289af7fd60a961/work/18269cbb7eb7489098289af7fd60a961_id_rsa (zuul-build-sshkey) 2026-03-09 15:23:37.769630 | controller -> localhost | ok: Runtime: 0:00:00.014050 2026-03-09 15:23:37.776889 | 2026-03-09 15:23:37.776948 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-09 15:23:38.160363 | controller | ok 2026-03-09 15:23:38.165890 | 2026-03-09 15:23:38.165953 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-09 15:23:38.201199 | controller | skipping: Conditional result was False 2026-03-09 15:23:38.212357 | 2026-03-09 15:23:38.212441 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-09 15:23:38.588008 | controller | ok 2026-03-09 15:23:38.594673 | 2026-03-09 15:23:38.594752 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-09 15:23:39.636810 | controller | Output suppressed because no_log was given 2026-03-09 15:23:39.643821 | 2026-03-09 15:23:39.643883 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-09 15:23:39.879527 | controller | ok: "logs" 2026-03-09 15:23:39.879850 | controller | ok: All items complete 2026-03-09 15:23:39.879903 | 2026-03-09 15:23:40.066520 | controller | ok: "artifacts" 2026-03-09 15:23:40.246009 | controller | ok: "docs" 2026-03-09 15:23:40.258703 | 2026-03-09 15:23:40.258889 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-09 15:23:40.493168 | controller | changed: "logs" 2026-03-09 15:23:40.678562 | controller | changed: "artifacts" 2026-03-09 15:23:40.874880 | controller | changed: "docs" 2026-03-09 15:23:40.887227 | 2026-03-09 15:23:40.887371 | PLAY RECAP 2026-03-09 15:23:40.887508 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-09 15:23:40.887546 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-09 15:23:40.887570 | 2026-03-09 15:23:41.040670 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-09 15:23:41.042357 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-09 15:23:41.713405 | 2026-03-09 15:23:41.713544 | PLAY [all] 2026-03-09 15:23:41.723792 | 2026-03-09 15:23:41.723865 | TASK [setup-uv : Extract archive] 2026-03-09 15:23:44.838869 | controller | changed 2026-03-09 15:23:44.846236 | 2026-03-09 15:23:44.846357 | TASK [setup-uv : Print version] 2026-03-09 15:23:45.250170 | controller | uv 0.8.13 2026-03-09 15:23:45.386057 | controller | ok: Runtime: 0:00:00.014088 2026-03-09 15:23:45.393329 | 2026-03-09 15:23:45.393466 | PLAY RECAP 2026-03-09 15:23:45.393518 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-09 15:23:45.393542 | 2026-03-09 15:23:45.547051 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-09 15:23:45.548494 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-09 15:23:46.101380 | 2026-03-09 15:23:46.101514 | PLAY [all] 2026-03-09 15:23:46.111254 | 2026-03-09 15:23:46.111333 | TASK [Copy inventory file for Zuul] 2026-03-09 15:23:47.149193 | controller | changed 2026-03-09 15:23:47.155265 | 2026-03-09 15:23:47.155823 | TASK [Switch "ansible_host" to private IP] 2026-03-09 15:23:47.467255 | controller | changed: 1 replacements made 2026-03-09 15:23:47.474540 | 2026-03-09 15:23:47.474615 | TASK [Run Molecule scenario] 2026-03-09 15:23:47.898199 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-03-09 15:23:47.898297 | controller | Creating virtual environment at: .venv 2026-03-09 15:23:48.174429 | controller | Downloading ansible-core (2.3MiB) 2026-03-09 15:23:48.176263 | controller | Downloading cryptography (4.3MiB) 2026-03-09 15:23:48.176979 | controller | Downloading pygments (1.2MiB) 2026-03-09 15:23:48.353216 | controller | Downloading cryptography 2026-03-09 15:23:48.414610 | controller | Downloading pygments 2026-03-09 15:23:48.613860 | controller | Downloading ansible-core 2026-03-09 15:23:48.672634 | controller | Installed 36 packages in 58ms 2026-03-09 15:23:49.364232 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-03-09 15:23:49.364304 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-03-09 15:23:50.274187 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-03-09 15:23:50.274250 | controller | INFO default scenario not found, disabling shared state. 2026-03-09 15:23:50.274327 | controller | INFO [upload-helm-chart > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-03-09 15:23:50.274442 | controller | INFO [upload-helm-chart > prerun] Performing prerun with role_name_check=0... 2026-03-09 15:24:07.881692 | controller | INFO [upload-helm-chart > dependency] Executing 2026-03-09 15:24:07.881778 | controller | WARNING [upload-helm-chart > dependency] Missing roles requirements file: requirements.yml 2026-03-09 15:24:07.881789 | controller | WARNING [upload-helm-chart > dependency] Missing collections requirements file: collections.yml 2026-03-09 15:24:07.882081 | controller | WARNING [upload-helm-chart > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-03-09 15:24:07.886687 | controller | INFO [upload-helm-chart > cleanup] Executing 2026-03-09 15:24:07.887027 | controller | WARNING [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-09 15:24:07.892464 | controller | INFO [upload-helm-chart > destroy] Executing 2026-03-09 15:24:07.892491 | controller | WARNING [upload-helm-chart > destroy] Skipping, '--destroy=never' requested. 2026-03-09 15:24:07.892517 | controller | INFO [upload-helm-chart > destroy] Executed: Successful 2026-03-09 15:24:07.897975 | controller | INFO [upload-helm-chart > syntax] Executing 2026-03-09 15:24:08.773861 | controller | 2026-03-09 15:24:08.774157 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/upload-helm-chart/converge.yml 2026-03-09 15:24:08.856969 | controller | INFO [upload-helm-chart > syntax] Executed: Successful 2026-03-09 15:24:08.867695 | controller | INFO [upload-helm-chart > create] Executing 2026-03-09 15:24:08.869282 | controller | WARNING [upload-helm-chart > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-09 15:24:08.874920 | controller | INFO [upload-helm-chart > prepare] Executing 2026-03-09 15:24:08.875193 | controller | WARNING [upload-helm-chart > prepare] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-09 15:24:08.880521 | controller | INFO [upload-helm-chart > converge] Executing 2026-03-09 15:24:09.719751 | controller | 2026-03-09 15:24:09.719824 | controller | PLAY [Converge] **************************************************************** 2026-03-09 15:24:09.719995 | controller | 2026-03-09 15:24:09.720216 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-09 15:24:09.720506 | controller | Monday 09 March 2026 15:24:09 +0000 (0:00:00.030) 0:00:00.030 ********** 2026-03-09 15:24:13.072242 | 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-09 15:24:13.077236 | controller | ok: [controller] 2026-03-09 15:24:13.077429 | controller | 2026-03-09 15:24:13.077649 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-09 15:24:13.077859 | controller | Monday 09 March 2026 15:24:13 +0000 (0:00:03.358) 0:00:03.388 ********** 2026-03-09 15:24:13.153597 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-09 15:24:13.153744 | controller | 2026-03-09 15:24:13.153948 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-09 15:24:13.154186 | controller | Monday 09 March 2026 15:24:13 +0000 (0:00:00.076) 0:00:03.465 ********** 2026-03-09 15:24:13.876169 | controller | changed: [controller] 2026-03-09 15:24:13.876335 | controller | 2026-03-09 15:24:13.876574 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-09 15:24:13.876765 | controller | Monday 09 March 2026 15:24:13 +0000 (0:00:00.721) 0:00:04.187 ********** 2026-03-09 15:24:13.910411 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-09 15:24:13.910577 | controller | 2026-03-09 15:24:13.910796 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-09 15:24:13.911020 | controller | Monday 09 March 2026 15:24:13 +0000 (0:00:00.035) 0:00:04.222 ********** 2026-03-09 15:24:14.311259 | controller | changed: [controller] 2026-03-09 15:24:14.311412 | controller | 2026-03-09 15:24:14.311633 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-09 15:24:14.311829 | controller | Monday 09 March 2026 15:24:14 +0000 (0:00:00.400) 0:00:04.622 ********** 2026-03-09 15:24:15.399669 | controller | changed: [controller] 2026-03-09 15:24:15.399743 | controller | 2026-03-09 15:24:15.399966 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-09 15:24:15.400167 | controller | Monday 09 March 2026 15:24:15 +0000 (0:00:01.088) 0:00:05.710 ********** 2026-03-09 15:24:15.799092 | controller | ok: [controller] 2026-03-09 15:24:15.799177 | controller | 2026-03-09 15:24:15.799447 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-09 15:24:15.799664 | controller | Monday 09 March 2026 15:24:15 +0000 (0:00:00.398) 0:00:06.109 ********** 2026-03-09 15:24:15.852081 | controller | skipping: [controller] 2026-03-09 15:24:15.852264 | controller | 2026-03-09 15:24:15.852573 | controller | PLAY RECAP ********************************************************************* 2026-03-09 15:24:15.852831 | controller | controller : ok=7 changed=3 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-03-09 15:24:15.853037 | controller | 2026-03-09 15:24:15.853228 | controller | 2026-03-09 15:24:15.853493 | controller | TASKS RECAP ******************************************************************** 2026-03-09 15:24:15.853753 | controller | Monday 09 March 2026 15:24:15 +0000 (0:00:00.054) 0:00:06.163 ********** 2026-03-09 15:24:15.853981 | controller | =============================================================================== 2026-03-09 15:24:15.854170 | controller | Gathering Facts --------------------------------------------------------- 3.36s 2026-03-09 15:24:15.854392 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.09s 2026-03-09 15:24:15.854625 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.72s 2026-03-09 15:24:15.854865 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.40s 2026-03-09 15:24:15.855071 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.40s 2026-03-09 15:24:15.855304 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.08s 2026-03-09 15:24:15.855539 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.05s 2026-03-09 15:24:15.855838 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.04s 2026-03-09 15:24:15.944837 | controller | INFO [upload-helm-chart > converge] Executed: Successful 2026-03-09 15:24:15.954803 | controller | INFO [upload-helm-chart > idempotence] Executing 2026-03-09 15:24:16.766941 | controller | 2026-03-09 15:24:16.767257 | controller | PLAY [Converge] **************************************************************** 2026-03-09 15:24:16.767466 | controller | 2026-03-09 15:24:16.767685 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-09 15:24:16.768007 | controller | Monday 09 March 2026 15:24:16 +0000 (0:00:00.010) 0:00:00.010 ********** 2026-03-09 15:24:18.855305 | 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-09 15:24:18.861889 | controller | ok: [controller] 2026-03-09 15:24:18.862064 | controller | 2026-03-09 15:24:18.862299 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-09 15:24:18.862508 | controller | Monday 09 March 2026 15:24:18 +0000 (0:00:02.094) 0:00:02.105 ********** 2026-03-09 15:24:18.962682 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-09 15:24:18.962740 | controller | 2026-03-09 15:24:18.962856 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-09 15:24:18.963011 | controller | Monday 09 March 2026 15:24:18 +0000 (0:00:00.100) 0:00:02.206 ********** 2026-03-09 15:24:19.709234 | controller | ok: [controller] 2026-03-09 15:24:19.709382 | controller | 2026-03-09 15:24:19.709609 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-09 15:24:19.709805 | controller | Monday 09 March 2026 15:24:19 +0000 (0:00:00.746) 0:00:02.953 ********** 2026-03-09 15:24:19.757455 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-09 15:24:19.757593 | controller | 2026-03-09 15:24:19.757800 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-09 15:24:19.757983 | controller | Monday 09 March 2026 15:24:19 +0000 (0:00:00.048) 0:00:03.001 ********** 2026-03-09 15:24:20.177778 | controller | ok: [controller] 2026-03-09 15:24:20.177810 | controller | 2026-03-09 15:24:20.177815 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-09 15:24:20.177820 | controller | Monday 09 March 2026 15:24:20 +0000 (0:00:00.418) 0:00:03.419 ********** 2026-03-09 15:24:21.317129 | controller | ok: [controller] 2026-03-09 15:24:21.317254 | controller | 2026-03-09 15:24:21.317473 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-09 15:24:21.317675 | controller | Monday 09 March 2026 15:24:21 +0000 (0:00:01.139) 0:00:04.559 ********** 2026-03-09 15:24:21.727875 | controller | ok: [controller] 2026-03-09 15:24:21.728019 | controller | 2026-03-09 15:24:21.728270 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-09 15:24:21.728660 | controller | Monday 09 March 2026 15:24:21 +0000 (0:00:00.411) 0:00:04.971 ********** 2026-03-09 15:24:21.766543 | controller | skipping: [controller] 2026-03-09 15:24:21.766698 | controller | 2026-03-09 15:24:21.766920 | controller | PLAY RECAP ********************************************************************* 2026-03-09 15:24:21.767151 | controller | controller : ok=7 changed=0 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-03-09 15:24:21.767323 | controller | 2026-03-09 15:24:21.767482 | controller | 2026-03-09 15:24:21.767669 | controller | TASKS RECAP ******************************************************************** 2026-03-09 15:24:21.767875 | controller | Monday 09 March 2026 15:24:21 +0000 (0:00:00.040) 0:00:05.011 ********** 2026-03-09 15:24:21.768050 | controller | =============================================================================== 2026-03-09 15:24:21.768224 | controller | Gathering Facts --------------------------------------------------------- 2.09s 2026-03-09 15:24:21.768439 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.14s 2026-03-09 15:24:21.768608 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.75s 2026-03-09 15:24:21.768798 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.42s 2026-03-09 15:24:21.769007 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.41s 2026-03-09 15:24:21.769259 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.10s 2026-03-09 15:24:21.769491 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.05s 2026-03-09 15:24:21.769697 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.04s 2026-03-09 15:24:21.887181 | controller | INFO [upload-helm-chart > idempotence] Executed: Successful 2026-03-09 15:24:21.899996 | controller | INFO [upload-helm-chart > side_effect] Executing 2026-03-09 15:24:22.801279 | controller | 2026-03-09 15:24:22.801472 | controller | PLAY [Side effect] ************************************************************* 2026-03-09 15:24:22.801655 | controller | 2026-03-09 15:24:22.801855 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-09 15:24:22.802055 | controller | Monday 09 March 2026 15:24:22 +0000 (0:00:00.011) 0:00:00.011 ********** 2026-03-09 15:24:23.929594 | 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-09 15:24:23.935950 | controller | ok: [controller] 2026-03-09 15:24:23.936067 | controller | 2026-03-09 15:24:23.936190 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-09 15:24:23.936318 | controller | Monday 09 March 2026 15:24:23 +0000 (0:00:01.134) 0:00:01.146 ********** 2026-03-09 15:24:24.015822 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-09 15:24:24.015872 | controller | 2026-03-09 15:24:24.016005 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-09 15:24:24.016137 | controller | Monday 09 March 2026 15:24:24 +0000 (0:00:00.079) 0:00:01.225 ********** 2026-03-09 15:24:24.755756 | controller | changed: [controller] 2026-03-09 15:24:24.755857 | controller | 2026-03-09 15:24:24.755981 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-09 15:24:24.756073 | controller | Monday 09 March 2026 15:24:24 +0000 (0:00:00.739) 0:00:01.965 ********** 2026-03-09 15:24:24.801075 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-09 15:24:24.801126 | controller | 2026-03-09 15:24:24.801187 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-09 15:24:24.801280 | controller | Monday 09 March 2026 15:24:24 +0000 (0:00:00.045) 0:00:02.011 ********** 2026-03-09 15:24:25.233328 | controller | ok: [controller] 2026-03-09 15:24:25.233475 | controller | 2026-03-09 15:24:25.233694 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-09 15:24:25.233892 | controller | Monday 09 March 2026 15:24:25 +0000 (0:00:00.431) 0:00:02.442 ********** 2026-03-09 15:24:26.387924 | controller | ok: [controller] 2026-03-09 15:24:26.387955 | controller | 2026-03-09 15:24:26.387959 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-09 15:24:26.387964 | controller | Monday 09 March 2026 15:24:26 +0000 (0:00:01.152) 0:00:03.594 ********** 2026-03-09 15:24:26.815466 | controller | ok: [controller] 2026-03-09 15:24:26.815551 | controller | 2026-03-09 15:24:26.815569 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-09 15:24:26.815719 | controller | Monday 09 March 2026 15:24:26 +0000 (0:00:00.429) 0:00:04.023 ********** 2026-03-09 15:24:27.137518 | controller | changed: [controller] => (item=/file) 2026-03-09 15:24:27.137562 | controller | 2026-03-09 15:24:27.137571 | controller | PLAY RECAP ********************************************************************* 2026-03-09 15:24:27.137580 | controller | controller : ok=8 changed=2 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-09 15:24:27.137588 | controller | 2026-03-09 15:24:27.137596 | controller | 2026-03-09 15:24:27.137603 | controller | TASKS RECAP ******************************************************************** 2026-03-09 15:24:27.137624 | controller | Monday 09 March 2026 15:24:27 +0000 (0:00:00.320) 0:00:04.344 ********** 2026-03-09 15:24:27.137631 | controller | =============================================================================== 2026-03-09 15:24:27.137638 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.15s 2026-03-09 15:24:27.137646 | controller | Gathering Facts --------------------------------------------------------- 1.13s 2026-03-09 15:24:27.137653 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.74s 2026-03-09 15:24:27.137660 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.43s 2026-03-09 15:24:27.137667 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.43s 2026-03-09 15:24:27.137676 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.32s 2026-03-09 15:24:27.137683 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.08s 2026-03-09 15:24:27.137690 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.05s 2026-03-09 15:24:27.243303 | controller | INFO [upload-helm-chart > side_effect] Executed: Successful 2026-03-09 15:24:27.254184 | controller | INFO [upload-helm-chart > verify] Executing 2026-03-09 15:24:28.110062 | controller | 2026-03-09 15:24:28.110236 | controller | PLAY [Verify] ****************************************************************** 2026-03-09 15:24:28.110350 | controller | 2026-03-09 15:24:28.110469 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-09 15:24:28.110587 | controller | Monday 09 March 2026 15:24:28 +0000 (0:00:00.009) 0:00:00.009 ********** 2026-03-09 15:24:29.214159 | 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-09 15:24:29.219466 | controller | ok: [controller] 2026-03-09 15:24:29.219769 | controller | 2026-03-09 15:24:29.220044 | controller | TASK [Stat file 1] ************************************************************* 2026-03-09 15:24:29.220302 | controller | Monday 09 March 2026 15:24:29 +0000 (0:00:01.109) 0:00:01.118 ********** 2026-03-09 15:24:29.630820 | controller | ok: [controller] 2026-03-09 15:24:29.631297 | controller | 2026-03-09 15:24:29.631607 | controller | TASK [Stat file 2] ************************************************************* 2026-03-09 15:24:29.631960 | controller | Monday 09 March 2026 15:24:29 +0000 (0:00:00.410) 0:00:01.529 ********** 2026-03-09 15:24:29.943887 | controller | ok: [controller] 2026-03-09 15:24:29.944192 | controller | 2026-03-09 15:24:29.944458 | controller | TASK [Stat removed file 1] ***************************************************** 2026-03-09 15:24:29.944719 | controller | Monday 09 March 2026 15:24:29 +0000 (0:00:00.313) 0:00:01.842 ********** 2026-03-09 15:24:30.253119 | controller | ok: [controller] 2026-03-09 15:24:30.253412 | controller | 2026-03-09 15:24:30.253679 | controller | TASK [Stat removed file 2] ***************************************************** 2026-03-09 15:24:30.253963 | controller | Monday 09 March 2026 15:24:30 +0000 (0:00:00.308) 0:00:02.151 ********** 2026-03-09 15:24:30.603191 | controller | ok: [controller] 2026-03-09 15:24:30.603258 | controller | 2026-03-09 15:24:30.603268 | controller | TASK [ansible.builtin.assert] ************************************************** 2026-03-09 15:24:30.603354 | controller | Monday 09 March 2026 15:24:30 +0000 (0:00:00.347) 0:00:02.499 ********** 2026-03-09 15:24:30.637439 | controller | ok: [controller] => { 2026-03-09 15:24:30.637474 | controller | "changed": false, 2026-03-09 15:24:30.637481 | controller | "msg": "All assertions passed" 2026-03-09 15:24:30.637486 | controller | } 2026-03-09 15:24:30.637491 | controller | 2026-03-09 15:24:30.637496 | controller | PLAY RECAP ********************************************************************* 2026-03-09 15:24:30.637502 | controller | controller : ok=6 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-09 15:24:30.637507 | controller | 2026-03-09 15:24:30.637524 | controller | 2026-03-09 15:24:30.637528 | controller | TASKS RECAP ******************************************************************** 2026-03-09 15:24:30.637533 | controller | Monday 09 March 2026 15:24:30 +0000 (0:00:00.035) 0:00:02.534 ********** 2026-03-09 15:24:30.637537 | controller | =============================================================================== 2026-03-09 15:24:30.637542 | controller | Gathering Facts --------------------------------------------------------- 1.11s 2026-03-09 15:24:30.637546 | controller | Stat file 1 ------------------------------------------------------------- 0.41s 2026-03-09 15:24:30.637551 | controller | Stat removed file 2 ----------------------------------------------------- 0.35s 2026-03-09 15:24:30.637555 | controller | Stat file 2 ------------------------------------------------------------- 0.31s 2026-03-09 15:24:30.637560 | controller | Stat removed file 1 ----------------------------------------------------- 0.31s 2026-03-09 15:24:30.637564 | controller | ansible.builtin.assert -------------------------------------------------- 0.04s 2026-03-09 15:24:30.750359 | controller | INFO [upload-helm-chart > verify] Executed: Successful 2026-03-09 15:24:30.761870 | controller | INFO [upload-helm-chart > cleanup] Executing 2026-03-09 15:24:30.762377 | controller | WARNING [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-09 15:24:30.770255 | controller | INFO [upload-helm-chart > destroy] Executing 2026-03-09 15:24:30.770387 | controller | WARNING [upload-helm-chart > destroy] Skipping, '--destroy=never' requested. 2026-03-09 15:24:30.770477 | controller | INFO [upload-helm-chart > destroy] Executed: Successful 2026-03-09 15:24:30.770847 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-03-09 15:24:31.157674 | controller | ok: Runtime: 0:00:43.072429 2026-03-09 15:24:31.163862 | 2026-03-09 15:24:31.163948 | PLAY RECAP 2026-03-09 15:24:31.164025 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-09 15:24:31.164071 | 2026-03-09 15:24:31.381542 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-09 15:24:31.384653 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-09 15:24:32.013917 | 2026-03-09 15:24:32.014560 | PLAY [all] 2026-03-09 15:24:32.026053 | 2026-03-09 15:24:32.026129 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-09 15:24:32.071763 | controller | skipping: Conditional result was False 2026-03-09 15:24:32.087172 | 2026-03-09 15:24:32.087344 | TASK [fetch-output : Set log path for single node] 2026-03-09 15:24:32.132254 | controller | ok 2026-03-09 15:24:32.135832 | 2026-03-09 15:24:32.135912 | LOOP [fetch-output : Ensure local output dirs] 2026-03-09 15:24:32.567853 | controller -> localhost | ok: "/var/lib/zuul/builds/18269cbb7eb7489098289af7fd60a961/work/logs" 2026-03-09 15:24:32.781332 | controller -> localhost | changed: "/var/lib/zuul/builds/18269cbb7eb7489098289af7fd60a961/work/artifacts" 2026-03-09 15:24:32.989608 | controller -> localhost | changed: "/var/lib/zuul/builds/18269cbb7eb7489098289af7fd60a961/work/docs" 2026-03-09 15:24:33.008609 | 2026-03-09 15:24:33.008812 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-09 15:24:33.723009 | controller | ok 2026-03-09 15:24:33.751750 | controller | changed: All items complete 2026-03-09 15:24:33.751853 | 2026-03-09 15:24:34.237046 | controller | changed: .d..t...... ./ 2026-03-09 15:24:34.758419 | controller | changed: .d..t...... ./ 2026-03-09 15:24:34.781738 | 2026-03-09 15:24:34.781921 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-09 15:24:35.243228 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007656 2026-03-09 15:24:35.988818 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009670 2026-03-09 15:24:36.010486 | 2026-03-09 15:24:36.010630 | PLAY [all] 2026-03-09 15:24:36.017934 | 2026-03-09 15:24:36.017999 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-09 15:24:36.493741 | controller | changed 2026-03-09 15:24:36.601632 | 2026-03-09 15:24:36.601742 | PLAY RECAP 2026-03-09 15:24:36.601805 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-09 15:24:36.601838 | 2026-03-09 15:24:36.750206 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-09 15:24:36.752128 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-09 15:24:37.299800 | 2026-03-09 15:24:37.437530 | PLAY [localhost] 2026-03-09 15:24:37.455977 | 2026-03-09 15:24:37.456103 | TASK [Generate Zuul manifest] 2026-03-09 15:24:37.477997 | localhost | ok 2026-03-09 15:24:37.494510 | 2026-03-09 15:24:37.494638 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-09 15:24:38.013360 | localhost | changed 2026-03-09 15:24:38.027946 | 2026-03-09 15:24:38.028091 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-09 15:24:38.142726 | localhost | ok 2026-03-09 15:24:38.153972 | 2026-03-09 15:24:38.154049 | TASK [Upload logs] 2026-03-09 15:24:38.186110 | localhost | ok 2026-03-09 15:24:38.250198 | 2026-03-09 15:24:38.504380 | TASK [Set zuul-log-path fact] 2026-03-09 15:24:38.528282 | localhost | ok 2026-03-09 15:24:38.541980 | 2026-03-09 15:24:38.542068 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-09 15:24:38.576614 | localhost | ok 2026-03-09 15:24:38.587281 | 2026-03-09 15:24:38.587398 | TASK [upload-logs : Create log directories] 2026-03-09 15:24:39.022520 | localhost | changed 2026-03-09 15:24:39.027339 | 2026-03-09 15:24:39.027413 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-09 15:24:39.474838 | localhost -> localhost | ok: Runtime: 0:00:00.006487 2026-03-09 15:24:39.481802 | 2026-03-09 15:24:39.481895 | TASK [upload-logs : Upload logs to log server] 2026-03-09 15:24:40.208453 | localhost | Output suppressed because no_log was given 2026-03-09 15:24:40.212378 | 2026-03-09 15:24:40.212450 | LOOP [upload-logs : Compress console log and json output] 2026-03-09 15:24:40.259271 | localhost | skipping: Conditional result was False 2026-03-09 15:24:40.266711 | localhost | skipping: Conditional result was False 2026-03-09 15:24:40.274986 | 2026-03-09 15:24:40.275174 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-09 15:24:40.319260 | localhost | skipping: Conditional result was False 2026-03-09 15:24:40.319694 | 2026-03-09 15:24:40.323525 | localhost | skipping: Conditional result was False 2026-03-09 15:24:40.334425 | 2026-03-09 15:24:40.334541 | LOOP [upload-logs : Upload console log and json output]