2026-03-05 16:13:51.772393 | Job console starting 2026-03-05 16:13:51.786993 | Updating git repos 2026-03-05 16:13:51.827289 | Cloning repos into workspace 2026-03-05 16:13:51.882822 | Restoring repo states 2026-03-05 16:13:51.892927 | Merging changes 2026-03-05 16:13:52.274862 | Checking out repos 2026-03-05 16:13:52.314997 | Preparing playbooks 2026-03-05 16:13:53.330798 | Running Ansible setup 2026-03-05 16:13:57.944469 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-05 16:13:58.568420 | 2026-03-05 16:13:58.568566 | PLAY [localhost] 2026-03-05 16:13:58.576233 | 2026-03-05 16:13:58.576346 | TASK [Gathering Facts] 2026-03-05 16:13:59.469619 | localhost | ok 2026-03-05 16:13:59.483477 | 2026-03-05 16:13:59.484175 | TASK [Setup log path fact] 2026-03-05 16:13:59.506265 | localhost | ok 2026-03-05 16:13:59.517617 | 2026-03-05 16:13:59.517712 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-05 16:13:59.559556 | localhost | ok 2026-03-05 16:13:59.568771 | 2026-03-05 16:13:59.568881 | TASK [emit-job-header : Print job information] 2026-03-05 16:13:59.600783 | # Job Information 2026-03-05 16:13:59.608178 | Ansible Version: 2.16.16 2026-03-05 16:13:59.608269 | Job: ansible-collection-kubernetes-molecule-upload-helm-chart 2026-03-05 16:13:59.608373 | Pipeline: check 2026-03-05 16:13:59.608465 | Executor: 0a8996d2b663 2026-03-05 16:13:59.608491 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/234 2026-03-05 16:13:59.608734 | Event ID: f483c040-18ad-11f1-82de-983357ded420 2026-03-05 16:13:59.613515 | 2026-03-05 16:13:59.613673 | LOOP [emit-job-header : Print node information] 2026-03-05 16:13:59.722257 | localhost | ok: 2026-03-05 16:13:59.722569 | localhost | # Node Information 2026-03-05 16:13:59.722611 | localhost | Inventory Hostname: controller 2026-03-05 16:13:59.722638 | localhost | Hostname: np0000157886 2026-03-05 16:13:59.722661 | localhost | Username: zuul 2026-03-05 16:13:59.722691 | localhost | Distro: Debian 13.3 2026-03-05 16:13:59.722715 | localhost | Provider: yul1 2026-03-05 16:13:59.722737 | localhost | Region: ca-ymq-1 2026-03-05 16:13:59.722760 | localhost | Label: debian-trixie 2026-03-05 16:13:59.722782 | localhost | Product Name: OpenStack Nova 2026-03-05 16:13:59.722804 | localhost | Interface IP: 199.204.45.132 2026-03-05 16:13:59.736640 | 2026-03-05 16:13:59.736837 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-05 16:14:00.155065 | localhost -> localhost | changed 2026-03-05 16:14:00.162735 | 2026-03-05 16:14:00.162972 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-05 16:14:01.175629 | localhost -> localhost | changed 2026-03-05 16:14:01.184329 | 2026-03-05 16:14:01.184392 | PLAY [all] 2026-03-05 16:14:01.191459 | 2026-03-05 16:14:01.191525 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-05 16:14:01.439361 | controller -> localhost | ok 2026-03-05 16:14:01.448411 | 2026-03-05 16:14:01.448522 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-05 16:14:01.481939 | controller | ok 2026-03-05 16:14:01.500429 | controller | included: /var/lib/zuul/builds/40d5c44897f348db83c24946148e6ac0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-05 16:14:01.505742 | 2026-03-05 16:14:01.505907 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-05 16:14:02.253995 | controller -> localhost | Generating public/private rsa key pair. 2026-03-05 16:14:02.254225 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/40d5c44897f348db83c24946148e6ac0/work/40d5c44897f348db83c24946148e6ac0_id_rsa 2026-03-05 16:14:02.254258 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/40d5c44897f348db83c24946148e6ac0/work/40d5c44897f348db83c24946148e6ac0_id_rsa.pub 2026-03-05 16:14:02.254287 | controller -> localhost | The key fingerprint is: 2026-03-05 16:14:02.254315 | controller -> localhost | SHA256:KmhPYI7Dfo9121gRZLVCOwFtNvbi/BO20BPXjDpDo3A zuul-build-sshkey 2026-03-05 16:14:02.254353 | controller -> localhost | The key's randomart image is: 2026-03-05 16:14:02.254391 | controller -> localhost | +---[RSA 3072]----+ 2026-03-05 16:14:02.254737 | controller -> localhost | | .o=.. | 2026-03-05 16:14:02.254811 | controller -> localhost | | +*o . | 2026-03-05 16:14:02.254844 | controller -> localhost | | +=o. + | 2026-03-05 16:14:02.254867 | controller -> localhost | | . E+= o o | 2026-03-05 16:14:02.254889 | controller -> localhost | | o S.= = | 2026-03-05 16:14:02.254910 | controller -> localhost | |.+ o . =.O | 2026-03-05 16:14:02.254932 | controller -> localhost | |o.+ o... .+ * | 2026-03-05 16:14:02.254954 | controller -> localhost | |.o +o.. = + | 2026-03-05 16:14:02.254978 | controller -> localhost | | ...o. o . . | 2026-03-05 16:14:02.255001 | controller -> localhost | +----[SHA256]-----+ 2026-03-05 16:14:02.255062 | controller -> localhost | ok: Runtime: 0:00:00.307187 2026-03-05 16:14:02.260488 | 2026-03-05 16:14:02.260556 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-05 16:14:02.292226 | controller | ok 2026-03-05 16:14:02.302881 | controller | included: /var/lib/zuul/builds/40d5c44897f348db83c24946148e6ac0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-05 16:14:02.311822 | 2026-03-05 16:14:02.311882 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-05 16:14:02.335810 | controller | skipping: Conditional result was False 2026-03-05 16:14:02.343365 | 2026-03-05 16:14:02.343448 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-05 16:14:02.838606 | controller | changed 2026-03-05 16:14:02.842536 | 2026-03-05 16:14:02.842599 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-05 16:14:03.056117 | controller | ok 2026-03-05 16:14:03.062171 | 2026-03-05 16:14:03.062256 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-05 16:14:03.603227 | controller | changed 2026-03-05 16:14:03.608649 | 2026-03-05 16:14:03.608735 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-05 16:14:04.133439 | controller | changed 2026-03-05 16:14:04.138015 | 2026-03-05 16:14:04.138086 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-05 16:14:04.163297 | controller | skipping: Conditional result was False 2026-03-05 16:14:04.173454 | 2026-03-05 16:14:04.173545 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-05 16:14:04.541534 | controller -> localhost | changed 2026-03-05 16:14:04.559239 | 2026-03-05 16:14:04.559322 | TASK [add-build-sshkey : Add back temp key] 2026-03-05 16:14:04.880339 | controller -> localhost | Identity added: /var/lib/zuul/builds/40d5c44897f348db83c24946148e6ac0/work/40d5c44897f348db83c24946148e6ac0_id_rsa (zuul-build-sshkey) 2026-03-05 16:14:04.880556 | controller -> localhost | ok: Runtime: 0:00:00.021335 2026-03-05 16:14:04.885166 | 2026-03-05 16:14:04.885226 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-05 16:14:05.245672 | controller | ok 2026-03-05 16:14:05.249471 | 2026-03-05 16:14:05.249535 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-05 16:14:05.274904 | controller | skipping: Conditional result was False 2026-03-05 16:14:05.296139 | 2026-03-05 16:14:05.296227 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-05 16:14:05.657357 | controller | ok 2026-03-05 16:14:05.663426 | 2026-03-05 16:14:05.663492 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-05 16:14:06.758200 | controller | Output suppressed because no_log was given 2026-03-05 16:14:06.795262 | 2026-03-05 16:14:06.795367 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-05 16:14:07.015123 | controller | ok: "logs" 2026-03-05 16:14:07.015389 | controller | ok: All items complete 2026-03-05 16:14:07.015440 | 2026-03-05 16:14:07.197851 | controller | ok: "artifacts" 2026-03-05 16:14:07.405890 | controller | ok: "docs" 2026-03-05 16:14:07.422230 | 2026-03-05 16:14:07.422486 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-05 16:14:07.645428 | controller | changed: "logs" 2026-03-05 16:14:07.840139 | controller | changed: "artifacts" 2026-03-05 16:14:08.027778 | controller | changed: "docs" 2026-03-05 16:14:08.037471 | 2026-03-05 16:14:08.041118 | PLAY RECAP 2026-03-05 16:14:08.041223 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-05 16:14:08.041270 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-05 16:14:08.041303 | 2026-03-05 16:14:08.162022 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-05 16:14:08.164746 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-05 16:14:08.773179 | 2026-03-05 16:14:08.773960 | PLAY [all] 2026-03-05 16:14:08.784542 | 2026-03-05 16:14:08.784621 | TASK [setup-uv : Extract archive] 2026-03-05 16:14:11.296578 | controller | changed 2026-03-05 16:14:11.311223 | 2026-03-05 16:14:11.311314 | TASK [setup-uv : Print version] 2026-03-05 16:14:11.173237 | controller | uv 0.8.13 2026-03-05 16:14:11.860127 | controller | ok: Runtime: 0:00:00.017714 2026-03-05 16:14:11.875351 | 2026-03-05 16:14:11.875412 | PLAY RECAP 2026-03-05 16:14:11.875619 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-05 16:14:11.875666 | 2026-03-05 16:14:11.995952 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-05 16:14:11.998771 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-05 16:14:12.565454 | 2026-03-05 16:14:12.565572 | PLAY [all] 2026-03-05 16:14:12.575081 | 2026-03-05 16:14:12.575152 | TASK [Copy inventory file for Zuul] 2026-03-05 16:14:13.600619 | controller | changed 2026-03-05 16:14:13.605552 | 2026-03-05 16:14:13.605630 | TASK [Switch "ansible_host" to private IP] 2026-03-05 16:14:13.897891 | controller | changed: 1 replacements made 2026-03-05 16:14:13.903706 | 2026-03-05 16:14:13.903798 | TASK [Run Molecule scenario] 2026-03-05 16:14:13.820062 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-03-05 16:14:13.820191 | controller | Creating virtual environment at: .venv 2026-03-05 16:14:14.091717 | controller | Downloading pygments (1.2MiB) 2026-03-05 16:14:14.092568 | controller | Downloading cryptography (4.3MiB) 2026-03-05 16:14:14.092795 | controller | Downloading ansible-core (2.3MiB) 2026-03-05 16:14:14.248192 | controller | Downloading cryptography 2026-03-05 16:14:14.305551 | controller | Downloading pygments 2026-03-05 16:14:14.498399 | controller | Downloading ansible-core 2026-03-05 16:14:14.547111 | controller | Installed 36 packages in 48ms 2026-03-05 16:14:15.229596 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-03-05 16:14:15.229677 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-03-05 16:14:16.071586 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-03-05 16:14:16.071667 | controller | INFO default scenario not found, disabling shared state. 2026-03-05 16:14:16.071698 | controller | INFO [upload-helm-chart > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-03-05 16:14:16.071710 | controller | INFO [upload-helm-chart > prerun] Performing prerun with role_name_check=0... 2026-03-05 16:14:33.997903 | controller | INFO [upload-helm-chart > dependency] Executing 2026-03-05 16:14:33.997976 | controller | WARNING [upload-helm-chart > dependency] Missing roles requirements file: requirements.yml 2026-03-05 16:14:33.997986 | controller | WARNING [upload-helm-chart > dependency] Missing collections requirements file: collections.yml 2026-03-05 16:14:33.998024 | controller | WARNING [upload-helm-chart > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-03-05 16:14:34.003963 | controller | INFO [upload-helm-chart > cleanup] Executing 2026-03-05 16:14:34.004325 | controller | WARNING [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-05 16:14:34.010824 | controller | INFO [upload-helm-chart > destroy] Executing 2026-03-05 16:14:34.010856 | controller | WARNING [upload-helm-chart > destroy] Skipping, '--destroy=never' requested. 2026-03-05 16:14:34.010949 | controller | INFO [upload-helm-chart > destroy] Executed: Successful 2026-03-05 16:14:34.017619 | controller | INFO [upload-helm-chart > syntax] Executing 2026-03-05 16:14:34.922110 | controller | 2026-03-05 16:14:34.922301 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/upload-helm-chart/converge.yml 2026-03-05 16:14:34.990214 | controller | INFO [upload-helm-chart > syntax] Executed: Successful 2026-03-05 16:14:34.998320 | controller | INFO [upload-helm-chart > create] Executing 2026-03-05 16:14:34.999778 | controller | WARNING [upload-helm-chart > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-05 16:14:35.005345 | controller | INFO [upload-helm-chart > prepare] Executing 2026-03-05 16:14:35.005601 | controller | WARNING [upload-helm-chart > prepare] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-05 16:14:35.011044 | controller | INFO [upload-helm-chart > converge] Executing 2026-03-05 16:14:35.879809 | controller | 2026-03-05 16:14:35.880007 | controller | PLAY [Converge] **************************************************************** 2026-03-05 16:14:35.880186 | controller | 2026-03-05 16:14:35.880383 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-05 16:14:35.880602 | controller | Thursday 05 March 2026 16:14:35 +0000 (0:00:00.032) 0:00:00.032 ******** 2026-03-05 16:14:37.177279 | 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-05 16:14:37.184490 | controller | ok: [controller] 2026-03-05 16:14:37.184678 | controller | 2026-03-05 16:14:37.184925 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-05 16:14:37.185136 | controller | Thursday 05 March 2026 16:14:37 +0000 (0:00:01.305) 0:00:01.338 ******** 2026-03-05 16:14:37.263571 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-05 16:14:37.263741 | controller | 2026-03-05 16:14:37.263950 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-05 16:14:37.264144 | controller | Thursday 05 March 2026 16:14:37 +0000 (0:00:00.079) 0:00:01.417 ******** 2026-03-05 16:14:37.993560 | controller | changed: [controller] 2026-03-05 16:14:37.993650 | controller | 2026-03-05 16:14:37.993879 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-05 16:14:37.994075 | controller | Thursday 05 March 2026 16:14:37 +0000 (0:00:00.729) 0:00:02.147 ******** 2026-03-05 16:14:38.038635 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-05 16:14:38.038788 | controller | 2026-03-05 16:14:38.038990 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-05 16:14:38.039217 | controller | Thursday 05 March 2026 16:14:38 +0000 (0:00:00.045) 0:00:02.192 ******** 2026-03-05 16:14:38.444736 | controller | changed: [controller] 2026-03-05 16:14:38.444977 | controller | 2026-03-05 16:14:38.445211 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-05 16:14:38.445411 | controller | Thursday 05 March 2026 16:14:38 +0000 (0:00:00.405) 0:00:02.598 ******** 2026-03-05 16:14:39.581421 | controller | changed: [controller] 2026-03-05 16:14:39.581581 | controller | 2026-03-05 16:14:39.581795 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-05 16:14:39.581991 | controller | Thursday 05 March 2026 16:14:39 +0000 (0:00:01.130) 0:00:03.728 ******** 2026-03-05 16:14:39.990960 | controller | ok: [controller] 2026-03-05 16:14:39.991139 | controller | 2026-03-05 16:14:39.991391 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-05 16:14:39.991609 | controller | Thursday 05 March 2026 16:14:39 +0000 (0:00:00.415) 0:00:04.144 ******** 2026-03-05 16:14:40.044657 | controller | skipping: [controller] 2026-03-05 16:14:40.044918 | controller | 2026-03-05 16:14:40.045130 | controller | PLAY RECAP ********************************************************************* 2026-03-05 16:14:40.045361 | controller | controller : ok=7 changed=3 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-03-05 16:14:40.045534 | controller | 2026-03-05 16:14:40.045694 | controller | 2026-03-05 16:14:40.045880 | controller | TASKS RECAP ******************************************************************** 2026-03-05 16:14:40.046089 | controller | Thursday 05 March 2026 16:14:40 +0000 (0:00:00.054) 0:00:04.198 ******** 2026-03-05 16:14:40.046256 | controller | =============================================================================== 2026-03-05 16:14:40.046438 | controller | Gathering Facts --------------------------------------------------------- 1.31s 2026-03-05 16:14:40.046637 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.13s 2026-03-05 16:14:40.046828 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.73s 2026-03-05 16:14:40.047033 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.42s 2026-03-05 16:14:40.047208 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.41s 2026-03-05 16:14:40.047403 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.08s 2026-03-05 16:14:40.047602 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.05s 2026-03-05 16:14:40.047802 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.05s 2026-03-05 16:14:40.160775 | controller | INFO [upload-helm-chart > converge] Executed: Successful 2026-03-05 16:14:40.174316 | controller | INFO [upload-helm-chart > idempotence] Executing 2026-03-05 16:14:41.011466 | controller | 2026-03-05 16:14:41.011664 | controller | PLAY [Converge] **************************************************************** 2026-03-05 16:14:41.011836 | controller | 2026-03-05 16:14:41.012028 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-05 16:14:41.012226 | controller | Thursday 05 March 2026 16:14:41 +0000 (0:00:00.012) 0:00:00.012 ******** 2026-03-05 16:14:42.123664 | 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-05 16:14:42.130858 | controller | ok: [controller] 2026-03-05 16:14:42.130997 | controller | 2026-03-05 16:14:42.131214 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-05 16:14:42.131422 | controller | Thursday 05 March 2026 16:14:42 +0000 (0:00:01.119) 0:00:01.132 ******** 2026-03-05 16:14:42.208196 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-05 16:14:42.208338 | controller | 2026-03-05 16:14:42.208540 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-05 16:14:42.208730 | controller | Thursday 05 March 2026 16:14:42 +0000 (0:00:00.077) 0:00:01.209 ******** 2026-03-05 16:14:42.985594 | controller | ok: [controller] 2026-03-05 16:14:42.985664 | controller | 2026-03-05 16:14:42.985675 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-05 16:14:42.985777 | controller | Thursday 05 March 2026 16:14:42 +0000 (0:00:00.776) 0:00:01.986 ******** 2026-03-05 16:14:43.036435 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-05 16:14:43.036592 | controller | 2026-03-05 16:14:43.036788 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-05 16:14:43.037025 | controller | Thursday 05 March 2026 16:14:43 +0000 (0:00:00.052) 0:00:02.038 ******** 2026-03-05 16:14:43.488761 | controller | ok: [controller] 2026-03-05 16:14:43.488947 | controller | 2026-03-05 16:14:43.489159 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-05 16:14:43.489375 | controller | Thursday 05 March 2026 16:14:43 +0000 (0:00:00.451) 0:00:02.489 ******** 2026-03-05 16:14:44.704606 | controller | ok: [controller] 2026-03-05 16:14:44.704753 | controller | 2026-03-05 16:14:44.705016 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-05 16:14:44.705219 | controller | Thursday 05 March 2026 16:14:44 +0000 (0:00:01.216) 0:00:03.705 ******** 2026-03-05 16:14:45.106327 | controller | ok: [controller] 2026-03-05 16:14:45.106507 | controller | 2026-03-05 16:14:45.106742 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-05 16:14:45.106946 | controller | Thursday 05 March 2026 16:14:45 +0000 (0:00:00.401) 0:00:04.107 ******** 2026-03-05 16:14:45.149109 | controller | skipping: [controller] 2026-03-05 16:14:45.149283 | controller | 2026-03-05 16:14:45.149476 | controller | PLAY RECAP ********************************************************************* 2026-03-05 16:14:45.149683 | controller | controller : ok=7 changed=0 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-03-05 16:14:45.149855 | controller | 2026-03-05 16:14:45.150024 | controller | 2026-03-05 16:14:45.150205 | controller | TASKS RECAP ******************************************************************** 2026-03-05 16:14:45.150427 | controller | Thursday 05 March 2026 16:14:45 +0000 (0:00:00.044) 0:00:04.151 ******** 2026-03-05 16:14:45.150595 | controller | =============================================================================== 2026-03-05 16:14:45.150795 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.22s 2026-03-05 16:14:45.150978 | controller | Gathering Facts --------------------------------------------------------- 1.12s 2026-03-05 16:14:45.151164 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.78s 2026-03-05 16:14:45.151351 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.45s 2026-03-05 16:14:45.151538 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.40s 2026-03-05 16:14:45.151734 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.08s 2026-03-05 16:14:45.151925 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.05s 2026-03-05 16:14:45.152120 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.04s 2026-03-05 16:14:45.278392 | controller | INFO [upload-helm-chart > idempotence] Executed: Successful 2026-03-05 16:14:45.289327 | controller | INFO [upload-helm-chart > side_effect] Executing 2026-03-05 16:14:46.110183 | controller | 2026-03-05 16:14:46.110380 | controller | PLAY [Side effect] ************************************************************* 2026-03-05 16:14:46.110551 | controller | 2026-03-05 16:14:46.110737 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-05 16:14:46.110925 | controller | Thursday 05 March 2026 16:14:46 +0000 (0:00:00.010) 0:00:00.010 ******** 2026-03-05 16:14:47.228405 | 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-05 16:14:47.234988 | controller | ok: [controller] 2026-03-05 16:14:47.235157 | controller | 2026-03-05 16:14:47.235374 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-05 16:14:47.235559 | controller | Thursday 05 March 2026 16:14:47 +0000 (0:00:01.124) 0:00:01.135 ******** 2026-03-05 16:14:47.310637 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-05 16:14:47.310787 | controller | 2026-03-05 16:14:47.310983 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-05 16:14:47.311173 | controller | Thursday 05 March 2026 16:14:47 +0000 (0:00:00.075) 0:00:01.211 ******** 2026-03-05 16:14:48.061544 | controller | changed: [controller] 2026-03-05 16:14:48.061679 | controller | 2026-03-05 16:14:48.061901 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-05 16:14:48.062096 | controller | Thursday 05 March 2026 16:14:48 +0000 (0:00:00.750) 0:00:01.961 ******** 2026-03-05 16:14:48.109012 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-05 16:14:48.109186 | controller | 2026-03-05 16:14:48.109383 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-05 16:14:48.109575 | controller | Thursday 05 March 2026 16:14:48 +0000 (0:00:00.047) 0:00:02.009 ******** 2026-03-05 16:14:48.517283 | controller | ok: [controller] 2026-03-05 16:14:48.517431 | controller | 2026-03-05 16:14:48.517636 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-05 16:14:48.517829 | controller | Thursday 05 March 2026 16:14:48 +0000 (0:00:00.407) 0:00:02.417 ******** 2026-03-05 16:14:49.662317 | controller | ok: [controller] 2026-03-05 16:14:49.662358 | controller | 2026-03-05 16:14:49.662367 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-05 16:14:49.662375 | controller | Thursday 05 March 2026 16:14:49 +0000 (0:00:01.143) 0:00:03.561 ******** 2026-03-05 16:14:50.077313 | controller | ok: [controller] 2026-03-05 16:14:50.077458 | controller | 2026-03-05 16:14:50.077592 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-05 16:14:50.077712 | controller | Thursday 05 March 2026 16:14:50 +0000 (0:00:00.414) 0:00:03.976 ******** 2026-03-05 16:14:50.401332 | controller | changed: [controller] => (item=/file) 2026-03-05 16:14:50.401399 | controller | 2026-03-05 16:14:50.401414 | controller | PLAY RECAP ********************************************************************* 2026-03-05 16:14:50.401544 | controller | controller : ok=8 changed=2 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-05 16:14:50.401621 | controller | 2026-03-05 16:14:50.401702 | controller | 2026-03-05 16:14:50.401791 | controller | TASKS RECAP ******************************************************************** 2026-03-05 16:14:50.401876 | controller | Thursday 05 March 2026 16:14:50 +0000 (0:00:00.322) 0:00:04.299 ******** 2026-03-05 16:14:50.401960 | controller | =============================================================================== 2026-03-05 16:14:50.402052 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.14s 2026-03-05 16:14:50.402140 | controller | Gathering Facts --------------------------------------------------------- 1.12s 2026-03-05 16:14:50.402229 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.75s 2026-03-05 16:14:50.402334 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.41s 2026-03-05 16:14:50.402424 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.41s 2026-03-05 16:14:50.402519 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.32s 2026-03-05 16:14:50.402608 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.08s 2026-03-05 16:14:50.402701 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.05s 2026-03-05 16:14:50.529062 | controller | INFO [upload-helm-chart > side_effect] Executed: Successful 2026-03-05 16:14:50.534207 | controller | INFO [upload-helm-chart > verify] Executing 2026-03-05 16:14:51.430543 | controller | 2026-03-05 16:14:51.430623 | controller | PLAY [Verify] ****************************************************************** 2026-03-05 16:14:51.430755 | controller | 2026-03-05 16:14:51.430936 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-05 16:14:51.431123 | controller | Thursday 05 March 2026 16:14:51 +0000 (0:00:00.010) 0:00:00.010 ******** 2026-03-05 16:14:52.558145 | 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-05 16:14:52.563047 | controller | ok: [controller] 2026-03-05 16:14:52.563227 | controller | 2026-03-05 16:14:52.563415 | controller | TASK [Stat file 1] ************************************************************* 2026-03-05 16:14:52.563605 | controller | Thursday 05 March 2026 16:14:52 +0000 (0:00:01.132) 0:00:01.143 ******** 2026-03-05 16:14:52.984205 | controller | ok: [controller] 2026-03-05 16:14:52.984332 | controller | 2026-03-05 16:14:52.984632 | controller | TASK [Stat file 2] ************************************************************* 2026-03-05 16:14:52.984945 | controller | Thursday 05 March 2026 16:14:52 +0000 (0:00:00.420) 0:00:01.563 ******** 2026-03-05 16:14:53.287220 | controller | ok: [controller] 2026-03-05 16:14:53.287302 | controller | 2026-03-05 16:14:53.287554 | controller | TASK [Stat removed file 1] ***************************************************** 2026-03-05 16:14:53.287762 | controller | Thursday 05 March 2026 16:14:53 +0000 (0:00:00.303) 0:00:01.866 ******** 2026-03-05 16:14:53.582880 | controller | ok: [controller] 2026-03-05 16:14:53.582924 | controller | 2026-03-05 16:14:53.582933 | controller | TASK [Stat removed file 2] ***************************************************** 2026-03-05 16:14:53.582959 | controller | Thursday 05 March 2026 16:14:53 +0000 (0:00:00.294) 0:00:02.160 ******** 2026-03-05 16:14:53.885463 | controller | ok: [controller] 2026-03-05 16:14:53.885532 | controller | 2026-03-05 16:14:53.885793 | controller | TASK [ansible.builtin.assert] ************************************************** 2026-03-05 16:14:53.885830 | controller | Thursday 05 March 2026 16:14:53 +0000 (0:00:00.302) 0:00:02.463 ******** 2026-03-05 16:14:53.921589 | controller | ok: [controller] => { 2026-03-05 16:14:53.921894 | controller | "changed": false, 2026-03-05 16:14:53.922149 | controller | "msg": "All assertions passed" 2026-03-05 16:14:53.922389 | controller | } 2026-03-05 16:14:53.922617 | controller | 2026-03-05 16:14:53.922861 | controller | PLAY RECAP ********************************************************************* 2026-03-05 16:14:53.923131 | controller | controller : ok=6 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-05 16:14:53.923361 | controller | 2026-03-05 16:14:53.923603 | controller | 2026-03-05 16:14:53.923845 | controller | TASKS RECAP ******************************************************************** 2026-03-05 16:14:53.924126 | controller | Thursday 05 March 2026 16:14:53 +0000 (0:00:00.035) 0:00:02.499 ******** 2026-03-05 16:14:53.924362 | controller | =============================================================================== 2026-03-05 16:14:53.924621 | controller | Gathering Facts --------------------------------------------------------- 1.13s 2026-03-05 16:14:53.924903 | controller | Stat file 1 ------------------------------------------------------------- 0.42s 2026-03-05 16:14:53.925151 | controller | Stat file 2 ------------------------------------------------------------- 0.30s 2026-03-05 16:14:53.925396 | controller | Stat removed file 2 ----------------------------------------------------- 0.30s 2026-03-05 16:14:53.925664 | controller | Stat removed file 1 ----------------------------------------------------- 0.29s 2026-03-05 16:14:53.925907 | controller | ansible.builtin.assert -------------------------------------------------- 0.04s 2026-03-05 16:14:54.072272 | controller | INFO [upload-helm-chart > verify] Executed: Successful 2026-03-05 16:14:54.081530 | controller | INFO [upload-helm-chart > cleanup] Executing 2026-03-05 16:14:54.081856 | controller | WARNING [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-05 16:14:54.089025 | controller | INFO [upload-helm-chart > destroy] Executing 2026-03-05 16:14:54.089055 | controller | WARNING [upload-helm-chart > destroy] Skipping, '--destroy=never' requested. 2026-03-05 16:14:54.089136 | controller | INFO [upload-helm-chart > destroy] Executed: Successful 2026-03-05 16:14:54.089449 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-03-05 16:14:54.575441 | controller | ok: Runtime: 0:00:40.455917 2026-03-05 16:14:54.579976 | 2026-03-05 16:14:54.580411 | PLAY RECAP 2026-03-05 16:14:54.580480 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-05 16:14:54.580511 | 2026-03-05 16:14:54.673513 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-05 16:14:54.676711 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-05 16:14:55.238476 | 2026-03-05 16:14:55.238596 | PLAY [all] 2026-03-05 16:14:55.248823 | 2026-03-05 16:14:55.252042 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-05 16:14:55.299413 | controller | skipping: Conditional result was False 2026-03-05 16:14:55.308823 | 2026-03-05 16:14:55.309043 | TASK [fetch-output : Set log path for single node] 2026-03-05 16:14:55.353824 | controller | ok 2026-03-05 16:14:55.358718 | 2026-03-05 16:14:55.358796 | LOOP [fetch-output : Ensure local output dirs] 2026-03-05 16:14:55.747284 | controller -> localhost | ok: "/var/lib/zuul/builds/40d5c44897f348db83c24946148e6ac0/work/logs" 2026-03-05 16:14:55.960432 | controller -> localhost | changed: "/var/lib/zuul/builds/40d5c44897f348db83c24946148e6ac0/work/artifacts" 2026-03-05 16:14:56.173286 | controller -> localhost | changed: "/var/lib/zuul/builds/40d5c44897f348db83c24946148e6ac0/work/docs" 2026-03-05 16:14:56.196674 | 2026-03-05 16:14:56.196806 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-05 16:14:56.817459 | controller | changed: .d..t...... ./ 2026-03-05 16:14:56.817815 | controller | changed: All items complete 2026-03-05 16:14:56.817863 | 2026-03-05 16:14:57.309854 | controller | changed: .d..t...... ./ 2026-03-05 16:14:57.800159 | controller | changed: .d..t...... ./ 2026-03-05 16:14:57.829332 | 2026-03-05 16:14:57.829463 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-05 16:14:58.274791 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006831 2026-03-05 16:14:58.498998 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008333 2026-03-05 16:14:58.521801 | 2026-03-05 16:14:58.521977 | PLAY [all] 2026-03-05 16:14:58.528859 | 2026-03-05 16:14:58.528946 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-05 16:14:59.011155 | controller | changed 2026-03-05 16:14:59.037016 | 2026-03-05 16:14:59.037250 | PLAY RECAP 2026-03-05 16:14:59.037356 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-05 16:14:59.037407 | 2026-03-05 16:14:59.147815 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-05 16:14:59.150792 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-05 16:14:59.698824 | 2026-03-05 16:14:59.698943 | PLAY [localhost] 2026-03-05 16:14:59.708322 | 2026-03-05 16:14:59.708391 | TASK [Generate Zuul manifest] 2026-03-05 16:14:59.727707 | localhost | ok 2026-03-05 16:14:59.742596 | 2026-03-05 16:14:59.742678 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-05 16:15:00.069183 | localhost | changed 2026-03-05 16:15:00.081093 | 2026-03-05 16:15:00.081163 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-05 16:15:00.111500 | localhost | ok 2026-03-05 16:15:00.122570 | 2026-03-05 16:15:00.122628 | TASK [Upload logs] 2026-03-05 16:15:00.140466 | localhost | ok 2026-03-05 16:15:00.201508 | 2026-03-05 16:15:00.201597 | TASK [Set zuul-log-path fact] 2026-03-05 16:15:00.220063 | localhost | ok 2026-03-05 16:15:00.236130 | 2026-03-05 16:15:00.236197 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-05 16:15:00.267729 | localhost | ok 2026-03-05 16:15:00.276173 | 2026-03-05 16:15:00.276260 | TASK [upload-logs : Create log directories] 2026-03-05 16:15:00.675535 | localhost | changed 2026-03-05 16:15:00.681497 | 2026-03-05 16:15:00.681562 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-05 16:15:01.056465 | localhost -> localhost | ok: Runtime: 0:00:00.005783 2026-03-05 16:15:01.063217 | 2026-03-05 16:15:01.063306 | TASK [upload-logs : Upload logs to log server] 2026-03-05 16:15:01.512927 | localhost | Output suppressed because no_log was given 2026-03-05 16:15:01.517884 | 2026-03-05 16:15:01.517959 | LOOP [upload-logs : Compress console log and json output] 2026-03-05 16:15:01.570195 | localhost | skipping: Conditional result was False 2026-03-05 16:15:01.576192 | localhost | skipping: Conditional result was False 2026-03-05 16:15:01.593323 | 2026-03-05 16:15:01.593523 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-05 16:15:01.652625 | localhost | skipping: Conditional result was False 2026-03-05 16:15:01.653140 | 2026-03-05 16:15:01.657587 | localhost | skipping: Conditional result was False 2026-03-05 16:15:01.668531 | 2026-03-05 16:15:01.668741 | LOOP [upload-logs : Upload console log and json output]