2026-03-17 18:54:38.753598 | Job console starting 2026-03-17 18:54:38.776340 | Updating git repos 2026-03-17 18:54:38.818178 | Cloning repos into workspace 2026-03-17 18:54:38.870655 | Restoring repo states 2026-03-17 18:54:38.881747 | Merging changes 2026-03-17 18:54:39.338124 | Checking out repos 2026-03-17 18:54:39.402583 | Preparing playbooks 2026-03-17 18:54:40.977710 | Running Ansible setup 2026-03-17 18:54:44.407167 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-17 18:54:44.976400 | 2026-03-17 18:54:44.976504 | PLAY [localhost] 2026-03-17 18:54:44.983529 | 2026-03-17 18:54:44.983595 | TASK [Gathering Facts] 2026-03-17 18:54:45.826721 | localhost | ok 2026-03-17 18:54:45.841441 | 2026-03-17 18:54:45.841606 | TASK [Setup log path fact] 2026-03-17 18:54:45.862062 | localhost | ok 2026-03-17 18:54:45.876090 | 2026-03-17 18:54:45.876182 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-17 18:54:45.916858 | localhost | ok 2026-03-17 18:54:45.926523 | 2026-03-17 18:54:45.926615 | TASK [emit-job-header : Print job information] 2026-03-17 18:54:45.967965 | # Job Information 2026-03-17 18:54:45.968164 | Ansible Version: 2.16.16 2026-03-17 18:54:45.968235 | Job: ansible-collection-kubernetes-molecule-upload-helm-chart 2026-03-17 18:54:45.968284 | Pipeline: check 2026-03-17 18:54:45.968328 | Executor: 0a8996d2b663 2026-03-17 18:54:45.968371 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/238 2026-03-17 18:54:45.968419 | Event ID: 728760e0-2232-11f1-89e0-7dc7177ef4eb 2026-03-17 18:54:45.972339 | 2026-03-17 18:54:45.972422 | LOOP [emit-job-header : Print node information] 2026-03-17 18:54:46.064164 | localhost | ok: 2026-03-17 18:54:46.064485 | localhost | # Node Information 2026-03-17 18:54:46.064534 | localhost | Inventory Hostname: controller 2026-03-17 18:54:46.064608 | localhost | Hostname: np0000159475 2026-03-17 18:54:46.064673 | localhost | Username: zuul 2026-03-17 18:54:46.064715 | localhost | Distro: Debian 13.3 2026-03-17 18:54:46.064746 | localhost | Provider: yul1 2026-03-17 18:54:46.064800 | localhost | Region: ca-ymq-1 2026-03-17 18:54:46.064860 | localhost | Label: debian-trixie 2026-03-17 18:54:46.064935 | localhost | Product Name: OpenStack Nova 2026-03-17 18:54:46.065013 | localhost | Interface IP: 162.253.55.62 2026-03-17 18:54:46.082085 | 2026-03-17 18:54:46.082201 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-17 18:54:46.482168 | localhost -> localhost | changed 2026-03-17 18:54:46.492044 | 2026-03-17 18:54:46.492137 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-17 18:54:47.287800 | localhost -> localhost | changed 2026-03-17 18:54:47.297762 | 2026-03-17 18:54:47.297930 | PLAY [all] 2026-03-17 18:54:47.306964 | 2026-03-17 18:54:47.307053 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-17 18:54:47.530162 | controller -> localhost | ok 2026-03-17 18:54:47.538055 | 2026-03-17 18:54:47.538146 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-17 18:54:47.570927 | controller | ok 2026-03-17 18:54:47.590854 | controller | included: /var/lib/zuul/builds/ebaf26562293429c804df6b7867615dd/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-17 18:54:47.597469 | 2026-03-17 18:54:47.597639 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-17 18:54:49.554394 | controller -> localhost | Generating public/private rsa key pair. 2026-03-17 18:54:49.554582 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/ebaf26562293429c804df6b7867615dd/work/ebaf26562293429c804df6b7867615dd_id_rsa 2026-03-17 18:54:49.554622 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/ebaf26562293429c804df6b7867615dd/work/ebaf26562293429c804df6b7867615dd_id_rsa.pub 2026-03-17 18:54:49.554652 | controller -> localhost | The key fingerprint is: 2026-03-17 18:54:49.554681 | controller -> localhost | SHA256:REad5FN7+1G7DXy+koXAZ7/65vSrF0f698bP97Yz0Gc zuul-build-sshkey 2026-03-17 18:54:49.554724 | controller -> localhost | The key's randomart image is: 2026-03-17 18:54:49.554753 | controller -> localhost | +---[RSA 3072]----+ 2026-03-17 18:54:49.554786 | controller -> localhost | | .+o... | 2026-03-17 18:54:49.554816 | controller -> localhost | | o .o. . | 2026-03-17 18:54:49.554843 | controller -> localhost | | . + . . .| 2026-03-17 18:54:49.554871 | controller -> localhost | | . + = .+| 2026-03-17 18:54:49.554898 | controller -> localhost | | S + B=.| 2026-03-17 18:54:49.554926 | controller -> localhost | | o.XE| 2026-03-17 18:54:49.554952 | controller -> localhost | | +*O| 2026-03-17 18:54:49.554979 | controller -> localhost | | o.BX| 2026-03-17 18:54:49.555009 | controller -> localhost | | oOO^| 2026-03-17 18:54:49.555037 | controller -> localhost | +----[SHA256]-----+ 2026-03-17 18:54:49.555094 | controller -> localhost | ok: Runtime: 0:00:01.558668 2026-03-17 18:54:49.560605 | 2026-03-17 18:54:49.560686 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-17 18:54:49.591844 | controller | ok 2026-03-17 18:54:49.601780 | controller | included: /var/lib/zuul/builds/ebaf26562293429c804df6b7867615dd/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-17 18:54:49.609150 | 2026-03-17 18:54:49.609209 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-17 18:54:49.623425 | controller | skipping: Conditional result was False 2026-03-17 18:54:49.633719 | 2026-03-17 18:54:49.633860 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-17 18:54:50.128333 | controller | changed 2026-03-17 18:54:50.136553 | 2026-03-17 18:54:50.136646 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-17 18:54:50.341507 | controller | ok 2026-03-17 18:54:50.347787 | 2026-03-17 18:54:50.347880 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-17 18:54:50.871871 | controller | changed 2026-03-17 18:54:50.875792 | 2026-03-17 18:54:50.875856 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-17 18:54:51.416258 | controller | changed 2026-03-17 18:54:51.421562 | 2026-03-17 18:54:51.421648 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-17 18:54:51.445510 | controller | skipping: Conditional result was False 2026-03-17 18:54:51.455199 | 2026-03-17 18:54:51.455266 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-17 18:54:51.869235 | controller -> localhost | changed 2026-03-17 18:54:51.881151 | 2026-03-17 18:54:51.881237 | TASK [add-build-sshkey : Add back temp key] 2026-03-17 18:54:52.149405 | controller -> localhost | Identity added: /var/lib/zuul/builds/ebaf26562293429c804df6b7867615dd/work/ebaf26562293429c804df6b7867615dd_id_rsa (zuul-build-sshkey) 2026-03-17 18:54:52.149684 | controller -> localhost | ok: Runtime: 0:00:00.011985 2026-03-17 18:54:52.155208 | 2026-03-17 18:54:52.155267 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-17 18:54:52.459095 | controller | ok 2026-03-17 18:54:52.463577 | 2026-03-17 18:54:52.463638 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-17 18:54:52.486407 | controller | skipping: Conditional result was False 2026-03-17 18:54:52.499010 | 2026-03-17 18:54:52.499073 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-17 18:54:52.884810 | controller | ok 2026-03-17 18:54:52.893814 | 2026-03-17 18:54:52.893904 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-17 18:54:54.000223 | controller | Output suppressed because no_log was given 2026-03-17 18:54:54.009793 | 2026-03-17 18:54:54.009882 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-17 18:54:54.242341 | controller | ok: "logs" 2026-03-17 18:54:54.242598 | controller | ok: All items complete 2026-03-17 18:54:54.242650 | 2026-03-17 18:54:54.430846 | controller | ok: "artifacts" 2026-03-17 18:54:54.625549 | controller | ok: "docs" 2026-03-17 18:54:54.642778 | 2026-03-17 18:54:54.642924 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-17 18:54:54.848574 | controller | changed: "logs" 2026-03-17 18:54:55.027812 | controller | changed: "artifacts" 2026-03-17 18:54:55.214358 | controller | changed: "docs" 2026-03-17 18:54:55.236689 | 2026-03-17 18:54:55.236845 | PLAY RECAP 2026-03-17 18:54:55.236914 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-17 18:54:55.236998 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-17 18:54:55.237067 | 2026-03-17 18:54:55.372776 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-17 18:54:55.374144 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-17 18:54:55.900572 | 2026-03-17 18:54:55.906746 | PLAY [all] 2026-03-17 18:54:55.919976 | 2026-03-17 18:54:55.920080 | TASK [setup-uv : Extract archive] 2026-03-17 18:54:58.336124 | controller | changed 2026-03-17 18:54:58.342764 | 2026-03-17 18:54:58.342846 | TASK [setup-uv : Print version] 2026-03-17 18:54:58.677489 | controller | uv 0.8.13 2026-03-17 18:54:58.872449 | controller | ok: Runtime: 0:00:00.012916 2026-03-17 18:54:58.879564 | 2026-03-17 18:54:58.879615 | PLAY RECAP 2026-03-17 18:54:58.879658 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-17 18:54:58.879683 | 2026-03-17 18:54:58.969053 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-17 18:54:58.971401 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-17 18:54:59.530342 | 2026-03-17 18:54:59.530469 | PLAY [all] 2026-03-17 18:54:59.541290 | 2026-03-17 18:54:59.541400 | TASK [Copy inventory file for Zuul] 2026-03-17 18:55:00.410398 | controller | changed 2026-03-17 18:55:00.416454 | 2026-03-17 18:55:00.416614 | TASK [Switch "ansible_host" to private IP] 2026-03-17 18:55:00.701901 | controller | changed: 1 replacements made 2026-03-17 18:55:00.709409 | 2026-03-17 18:55:00.709512 | TASK [Run Molecule scenario] 2026-03-17 18:55:01.127464 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-03-17 18:55:01.127554 | controller | Creating virtual environment at: .venv 2026-03-17 18:55:01.393138 | controller | Downloading pygments (1.2MiB) 2026-03-17 18:55:01.395216 | controller | Downloading ansible-core (2.3MiB) 2026-03-17 18:55:01.395444 | controller | Downloading cryptography (4.3MiB) 2026-03-17 18:55:01.548997 | controller | Downloading cryptography 2026-03-17 18:55:01.603238 | controller | Downloading pygments 2026-03-17 18:55:01.805606 | controller | Downloading ansible-core 2026-03-17 18:55:01.857209 | controller | Installed 36 packages in 50ms 2026-03-17 18:55:02.511645 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-03-17 18:55:02.511692 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-03-17 18:55:03.342392 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-03-17 18:55:03.342444 | controller | INFO default scenario not found, disabling shared state. 2026-03-17 18:55:03.342812 | controller | INFO [upload-helm-chart > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-03-17 18:55:03.342984 | controller | INFO [upload-helm-chart > prerun] Performing prerun with role_name_check=0... 2026-03-17 18:55:17.667877 | controller | INFO [upload-helm-chart > dependency] Executing 2026-03-17 18:55:17.667931 | controller | WARNING [upload-helm-chart > dependency] Missing roles requirements file: requirements.yml 2026-03-17 18:55:17.667947 | controller | WARNING [upload-helm-chart > dependency] Missing collections requirements file: collections.yml 2026-03-17 18:55:17.668117 | controller | WARNING [upload-helm-chart > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-03-17 18:55:17.673853 | controller | INFO [upload-helm-chart > cleanup] Executing 2026-03-17 18:55:17.674209 | controller | WARNING [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-17 18:55:17.679653 | controller | INFO [upload-helm-chart > destroy] Executing 2026-03-17 18:55:17.679678 | controller | WARNING [upload-helm-chart > destroy] Skipping, '--destroy=never' requested. 2026-03-17 18:55:17.679694 | controller | INFO [upload-helm-chart > destroy] Executed: Successful 2026-03-17 18:55:17.685279 | controller | INFO [upload-helm-chart > syntax] Executing 2026-03-17 18:55:18.516146 | controller | 2026-03-17 18:55:18.516444 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/upload-helm-chart/converge.yml 2026-03-17 18:55:18.571335 | controller | INFO [upload-helm-chart > syntax] Executed: Successful 2026-03-17 18:55:18.583106 | controller | INFO [upload-helm-chart > create] Executing 2026-03-17 18:55:18.584585 | controller | WARNING [upload-helm-chart > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-17 18:55:18.589969 | controller | INFO [upload-helm-chart > prepare] Executing 2026-03-17 18:55:18.590229 | controller | WARNING [upload-helm-chart > prepare] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-17 18:55:18.595472 | controller | INFO [upload-helm-chart > converge] Executing 2026-03-17 18:55:19.441169 | controller | 2026-03-17 18:55:19.441246 | controller | PLAY [Converge] **************************************************************** 2026-03-17 18:55:19.441340 | controller | 2026-03-17 18:55:19.441434 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-17 18:55:19.441529 | controller | Tuesday 17 March 2026 18:55:19 +0000 (0:00:00.029) 0:00:00.029 ********* 2026-03-17 18:55:20.746800 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-03-17 18:55:20.752292 | controller | ok: [controller] 2026-03-17 18:55:20.752475 | controller | 2026-03-17 18:55:20.752687 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-17 18:55:20.752919 | controller | Tuesday 17 March 2026 18:55:20 +0000 (0:00:01.311) 0:00:01.341 ********* 2026-03-17 18:55:20.831757 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-17 18:55:20.831883 | controller | 2026-03-17 18:55:20.832083 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-17 18:55:20.832275 | controller | Tuesday 17 March 2026 18:55:20 +0000 (0:00:00.079) 0:00:01.421 ********* 2026-03-17 18:55:21.602662 | controller | changed: [controller] 2026-03-17 18:55:21.602787 | controller | 2026-03-17 18:55:21.603051 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-17 18:55:21.603259 | controller | Tuesday 17 March 2026 18:55:21 +0000 (0:00:00.769) 0:00:02.190 ********* 2026-03-17 18:55:21.644054 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-17 18:55:21.644155 | controller | 2026-03-17 18:55:21.644364 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-17 18:55:21.644552 | controller | Tuesday 17 March 2026 18:55:21 +0000 (0:00:00.042) 0:00:02.233 ********* 2026-03-17 18:55:22.053655 | controller | changed: [controller] 2026-03-17 18:55:22.053709 | controller | 2026-03-17 18:55:22.053850 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-17 18:55:22.053937 | controller | Tuesday 17 March 2026 18:55:22 +0000 (0:00:00.408) 0:00:02.642 ********* 2026-03-17 18:55:23.204658 | controller | changed: [controller] 2026-03-17 18:55:23.204810 | controller | 2026-03-17 18:55:23.205074 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-17 18:55:23.205262 | controller | Tuesday 17 March 2026 18:55:23 +0000 (0:00:01.150) 0:00:03.793 ********* 2026-03-17 18:55:23.599399 | controller | ok: [controller] 2026-03-17 18:55:23.599462 | controller | 2026-03-17 18:55:23.599759 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-17 18:55:23.599923 | controller | Tuesday 17 March 2026 18:55:23 +0000 (0:00:00.394) 0:00:04.188 ********* 2026-03-17 18:55:23.656152 | controller | skipping: [controller] 2026-03-17 18:55:23.656331 | controller | 2026-03-17 18:55:23.656529 | controller | PLAY RECAP ********************************************************************* 2026-03-17 18:55:23.656751 | controller | controller : ok=7 changed=3 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-03-17 18:55:23.656958 | controller | 2026-03-17 18:55:23.657146 | controller | 2026-03-17 18:55:23.657324 | controller | TASKS RECAP ******************************************************************** 2026-03-17 18:55:23.657518 | controller | Tuesday 17 March 2026 18:55:23 +0000 (0:00:00.057) 0:00:04.245 ********* 2026-03-17 18:55:23.657699 | controller | =============================================================================== 2026-03-17 18:55:23.657887 | controller | Gathering Facts --------------------------------------------------------- 1.31s 2026-03-17 18:55:23.658117 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.15s 2026-03-17 18:55:23.658285 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.77s 2026-03-17 18:55:23.658488 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.41s 2026-03-17 18:55:23.658674 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.39s 2026-03-17 18:55:23.658875 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.08s 2026-03-17 18:55:23.659076 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.06s 2026-03-17 18:55:23.659278 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.04s 2026-03-17 18:55:23.765455 | controller | INFO [upload-helm-chart > converge] Executed: Successful 2026-03-17 18:55:23.775419 | controller | INFO [upload-helm-chart > idempotence] Executing 2026-03-17 18:55:24.562483 | controller | 2026-03-17 18:55:24.562595 | controller | PLAY [Converge] **************************************************************** 2026-03-17 18:55:24.562828 | controller | 2026-03-17 18:55:24.562973 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-17 18:55:24.563158 | controller | Tuesday 17 March 2026 18:55:24 +0000 (0:00:00.010) 0:00:00.010 ********* 2026-03-17 18:55:25.624972 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-03-17 18:55:25.627683 | controller | ok: [controller] 2026-03-17 18:55:25.627865 | controller | 2026-03-17 18:55:25.628070 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-17 18:55:25.628265 | controller | Tuesday 17 March 2026 18:55:25 +0000 (0:00:01.065) 0:00:01.075 ********* 2026-03-17 18:55:25.701120 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-17 18:55:25.701298 | controller | 2026-03-17 18:55:25.701553 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-17 18:55:25.701755 | controller | Tuesday 17 March 2026 18:55:25 +0000 (0:00:00.073) 0:00:01.148 ********* 2026-03-17 18:55:26.442138 | controller | ok: [controller] 2026-03-17 18:55:26.442256 | controller | 2026-03-17 18:55:26.442486 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-17 18:55:26.442707 | controller | Tuesday 17 March 2026 18:55:26 +0000 (0:00:00.733) 0:00:01.882 ********* 2026-03-17 18:55:26.479753 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-17 18:55:26.479896 | controller | 2026-03-17 18:55:26.480090 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-17 18:55:26.480268 | controller | Tuesday 17 March 2026 18:55:26 +0000 (0:00:00.044) 0:00:01.927 ********* 2026-03-17 18:55:26.904792 | controller | ok: [controller] 2026-03-17 18:55:26.904983 | controller | 2026-03-17 18:55:26.905205 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-17 18:55:26.905407 | controller | Tuesday 17 March 2026 18:55:26 +0000 (0:00:00.424) 0:00:02.352 ********* 2026-03-17 18:55:28.009701 | controller | ok: [controller] 2026-03-17 18:55:28.009849 | controller | 2026-03-17 18:55:28.010054 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-17 18:55:28.010250 | controller | Tuesday 17 March 2026 18:55:28 +0000 (0:00:01.105) 0:00:03.457 ********* 2026-03-17 18:55:28.403979 | controller | ok: [controller] 2026-03-17 18:55:28.404105 | controller | 2026-03-17 18:55:28.404341 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-17 18:55:28.404540 | controller | Tuesday 17 March 2026 18:55:28 +0000 (0:00:00.393) 0:00:03.851 ********* 2026-03-17 18:55:28.443003 | controller | skipping: [controller] 2026-03-17 18:55:28.443111 | controller | 2026-03-17 18:55:28.443311 | controller | PLAY RECAP ********************************************************************* 2026-03-17 18:55:28.443537 | controller | controller : ok=7 changed=0 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-03-17 18:55:28.443712 | controller | 2026-03-17 18:55:28.443877 | controller | 2026-03-17 18:55:28.444061 | controller | TASKS RECAP ******************************************************************** 2026-03-17 18:55:28.444238 | controller | Tuesday 17 March 2026 18:55:28 +0000 (0:00:00.040) 0:00:03.891 ********* 2026-03-17 18:55:28.444414 | controller | =============================================================================== 2026-03-17 18:55:28.444613 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.11s 2026-03-17 18:55:28.444813 | controller | Gathering Facts --------------------------------------------------------- 1.07s 2026-03-17 18:55:28.445084 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.73s 2026-03-17 18:55:28.445272 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.42s 2026-03-17 18:55:28.445462 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.39s 2026-03-17 18:55:28.445657 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.07s 2026-03-17 18:55:28.445849 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.04s 2026-03-17 18:55:28.446046 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.04s 2026-03-17 18:55:28.532358 | controller | INFO [upload-helm-chart > idempotence] Executed: Successful 2026-03-17 18:55:28.542809 | controller | INFO [upload-helm-chart > side_effect] Executing 2026-03-17 18:55:29.343911 | controller | 2026-03-17 18:55:29.344099 | controller | PLAY [Side effect] ************************************************************* 2026-03-17 18:55:29.344293 | controller | 2026-03-17 18:55:29.344485 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-17 18:55:29.344682 | controller | Tuesday 17 March 2026 18:55:29 +0000 (0:00:00.010) 0:00:00.010 ********* 2026-03-17 18:55:30.396317 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-03-17 18:55:30.405683 | controller | ok: [controller] 2026-03-17 18:55:30.405708 | controller | 2026-03-17 18:55:30.405713 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-17 18:55:30.405717 | controller | Tuesday 17 March 2026 18:55:30 +0000 (0:00:01.057) 0:00:01.068 ********* 2026-03-17 18:55:30.474934 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-03-17 18:55:30.474949 | controller | 2026-03-17 18:55:30.474953 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-03-17 18:55:30.474957 | controller | Tuesday 17 March 2026 18:55:30 +0000 (0:00:00.073) 0:00:01.141 ********* 2026-03-17 18:55:31.215106 | controller | changed: [controller] 2026-03-17 18:55:31.215287 | controller | 2026-03-17 18:55:31.215463 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-03-17 18:55:31.215625 | controller | Tuesday 17 March 2026 18:55:31 +0000 (0:00:00.739) 0:00:01.881 ********* 2026-03-17 18:55:31.264020 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-03-17 18:55:31.264141 | controller | 2026-03-17 18:55:31.264262 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-03-17 18:55:31.264377 | controller | Tuesday 17 March 2026 18:55:31 +0000 (0:00:00.049) 0:00:01.931 ********* 2026-03-17 18:55:31.695696 | controller | ok: [controller] 2026-03-17 18:55:31.697112 | controller | 2026-03-17 18:55:31.697355 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-03-17 18:55:31.697530 | controller | Tuesday 17 March 2026 18:55:31 +0000 (0:00:00.431) 0:00:02.362 ********* 2026-03-17 18:55:32.839263 | controller | ok: [controller] 2026-03-17 18:55:32.841354 | controller | 2026-03-17 18:55:32.841367 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-03-17 18:55:32.841373 | controller | Tuesday 17 March 2026 18:55:32 +0000 (0:00:01.143) 0:00:03.505 ********* 2026-03-17 18:55:33.249741 | controller | ok: [controller] 2026-03-17 18:55:33.249792 | controller | 2026-03-17 18:55:33.249935 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-03-17 18:55:33.250064 | controller | Tuesday 17 March 2026 18:55:33 +0000 (0:00:00.408) 0:00:03.914 ********* 2026-03-17 18:55:33.577716 | controller | changed: [controller] => (item=/file) 2026-03-17 18:55:33.577802 | controller | 2026-03-17 18:55:33.578075 | controller | PLAY RECAP ********************************************************************* 2026-03-17 18:55:33.580793 | controller | controller : ok=8 changed=2 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-17 18:55:33.580850 | controller | 2026-03-17 18:55:33.580860 | controller | 2026-03-17 18:55:33.580868 | controller | TASKS RECAP ******************************************************************** 2026-03-17 18:55:33.580946 | controller | Tuesday 17 March 2026 18:55:33 +0000 (0:00:00.327) 0:00:04.241 ********* 2026-03-17 18:55:33.580957 | controller | =============================================================================== 2026-03-17 18:55:33.580965 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.14s 2026-03-17 18:55:33.580974 | controller | Gathering Facts --------------------------------------------------------- 1.06s 2026-03-17 18:55:33.580981 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.74s 2026-03-17 18:55:33.580989 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.43s 2026-03-17 18:55:33.580996 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.41s 2026-03-17 18:55:33.581005 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.33s 2026-03-17 18:55:33.581012 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.07s 2026-03-17 18:55:33.581020 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.05s 2026-03-17 18:55:33.673778 | controller | INFO [upload-helm-chart > side_effect] Executed: Successful 2026-03-17 18:55:33.685186 | controller | INFO [upload-helm-chart > verify] Executing 2026-03-17 18:55:34.548627 | controller | 2026-03-17 18:55:34.549053 | controller | PLAY [Verify] ****************************************************************** 2026-03-17 18:55:34.549291 | controller | 2026-03-17 18:55:34.549537 | controller | TASK [Gathering Facts] ********************************************************* 2026-03-17 18:55:34.549784 | controller | Tuesday 17 March 2026 18:55:34 +0000 (0:00:00.009) 0:00:00.009 ********* 2026-03-17 18:55:35.661620 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-03-17 18:55:35.665022 | controller | ok: [controller] 2026-03-17 18:55:35.665083 | controller | 2026-03-17 18:55:35.665154 | controller | TASK [Stat file 1] ************************************************************* 2026-03-17 18:55:35.665324 | controller | Tuesday 17 March 2026 18:55:35 +0000 (0:00:01.116) 0:00:01.126 ********* 2026-03-17 18:55:36.074240 | controller | ok: [controller] 2026-03-17 18:55:36.074319 | controller | 2026-03-17 18:55:36.074343 | controller | TASK [Stat file 2] ************************************************************* 2026-03-17 18:55:36.074427 | controller | Tuesday 17 March 2026 18:55:36 +0000 (0:00:00.408) 0:00:01.535 ********* 2026-03-17 18:55:36.399103 | controller | ok: [controller] 2026-03-17 18:55:36.399130 | controller | 2026-03-17 18:55:36.399135 | controller | TASK [Stat removed file 1] ***************************************************** 2026-03-17 18:55:36.399139 | controller | Tuesday 17 March 2026 18:55:36 +0000 (0:00:00.322) 0:00:01.858 ********* 2026-03-17 18:55:36.680599 | controller | ok: [controller] 2026-03-17 18:55:36.680971 | controller | 2026-03-17 18:55:36.681257 | controller | TASK [Stat removed file 2] ***************************************************** 2026-03-17 18:55:36.681544 | controller | Tuesday 17 March 2026 18:55:36 +0000 (0:00:00.283) 0:00:02.141 ********* 2026-03-17 18:55:36.989404 | controller | ok: [controller] 2026-03-17 18:55:36.989499 | controller | 2026-03-17 18:55:36.989509 | controller | TASK [ansible.builtin.assert] ************************************************** 2026-03-17 18:55:36.989522 | controller | Tuesday 17 March 2026 18:55:36 +0000 (0:00:00.305) 0:00:02.447 ********* 2026-03-17 18:55:37.022146 | controller | ok: [controller] => { 2026-03-17 18:55:37.022198 | controller | "changed": false, 2026-03-17 18:55:37.022260 | controller | "msg": "All assertions passed" 2026-03-17 18:55:37.022344 | controller | } 2026-03-17 18:55:37.022422 | controller | 2026-03-17 18:55:37.022507 | controller | PLAY RECAP ********************************************************************* 2026-03-17 18:55:37.022597 | controller | controller : ok=6 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-03-17 18:55:37.023491 | controller | 2026-03-17 18:55:37.023529 | controller | 2026-03-17 18:55:37.023533 | controller | TASKS RECAP ******************************************************************** 2026-03-17 18:55:37.023538 | controller | Tuesday 17 March 2026 18:55:37 +0000 (0:00:00.037) 0:00:02.484 ********* 2026-03-17 18:55:37.023541 | controller | =============================================================================== 2026-03-17 18:55:37.023545 | controller | Gathering Facts --------------------------------------------------------- 1.12s 2026-03-17 18:55:37.023548 | controller | Stat file 1 ------------------------------------------------------------- 0.41s 2026-03-17 18:55:37.023552 | controller | Stat file 2 ------------------------------------------------------------- 0.32s 2026-03-17 18:55:37.023555 | controller | Stat removed file 2 ----------------------------------------------------- 0.31s 2026-03-17 18:55:37.023558 | controller | Stat removed file 1 ----------------------------------------------------- 0.28s 2026-03-17 18:55:37.023562 | controller | ansible.builtin.assert -------------------------------------------------- 0.04s 2026-03-17 18:55:37.170889 | controller | INFO [upload-helm-chart > verify] Executed: Successful 2026-03-17 18:55:37.182290 | controller | INFO [upload-helm-chart > cleanup] Executing 2026-03-17 18:55:37.182635 | controller | WARNING [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-03-17 18:55:37.187922 | controller | INFO [upload-helm-chart > destroy] Executing 2026-03-17 18:55:37.188041 | controller | WARNING [upload-helm-chart > destroy] Skipping, '--destroy=never' requested. 2026-03-17 18:55:37.188129 | controller | INFO [upload-helm-chart > destroy] Executed: Successful 2026-03-17 18:55:37.188498 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-03-17 18:55:37.498801 | controller | ok: Runtime: 0:00:36.222927 2026-03-17 18:55:37.505883 | 2026-03-17 18:55:37.505982 | PLAY RECAP 2026-03-17 18:55:37.506068 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-17 18:55:37.506114 | 2026-03-17 18:55:37.603127 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-17 18:55:37.679905 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-17 18:55:38.269650 | 2026-03-17 18:55:38.269789 | PLAY [all] 2026-03-17 18:55:38.280172 | 2026-03-17 18:55:38.280242 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-17 18:55:38.325098 | controller | skipping: Conditional result was False 2026-03-17 18:55:38.334103 | 2026-03-17 18:55:38.334268 | TASK [fetch-output : Set log path for single node] 2026-03-17 18:55:38.376178 | controller | ok 2026-03-17 18:55:38.380978 | 2026-03-17 18:55:38.381108 | LOOP [fetch-output : Ensure local output dirs] 2026-03-17 18:55:38.893450 | controller -> localhost | ok: "/var/lib/zuul/builds/ebaf26562293429c804df6b7867615dd/work/logs" 2026-03-17 18:55:39.093221 | controller -> localhost | changed: "/var/lib/zuul/builds/ebaf26562293429c804df6b7867615dd/work/artifacts" 2026-03-17 18:55:39.287667 | controller -> localhost | changed: "/var/lib/zuul/builds/ebaf26562293429c804df6b7867615dd/work/docs" 2026-03-17 18:55:39.306967 | 2026-03-17 18:55:39.307104 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-17 18:55:39.975806 | controller | changed: .d..t...... ./ 2026-03-17 18:55:39.976103 | controller | changed: All items complete 2026-03-17 18:55:39.976164 | 2026-03-17 18:55:40.488940 | controller | changed: .d..t...... ./ 2026-03-17 18:55:40.993231 | controller | changed: .d..t...... ./ 2026-03-17 18:55:41.018755 | 2026-03-17 18:55:41.018871 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-17 18:55:41.431195 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.005793 2026-03-17 18:55:41.645736 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006608 2026-03-17 18:55:41.667479 | 2026-03-17 18:55:41.667600 | PLAY [all] 2026-03-17 18:55:41.675719 | 2026-03-17 18:55:41.675789 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-17 18:55:42.107061 | controller | changed 2026-03-17 18:55:42.122386 | 2026-03-17 18:55:42.122543 | PLAY RECAP 2026-03-17 18:55:42.122636 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-17 18:55:42.122683 | 2026-03-17 18:55:42.221635 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-17 18:55:42.223392 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-17 18:55:42.765029 | 2026-03-17 18:55:42.765404 | PLAY [localhost] 2026-03-17 18:55:42.775074 | 2026-03-17 18:55:42.775151 | TASK [Generate Zuul manifest] 2026-03-17 18:55:42.795497 | localhost | ok 2026-03-17 18:55:42.809034 | 2026-03-17 18:55:42.809135 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-17 18:55:43.116263 | localhost | changed 2026-03-17 18:55:43.128258 | 2026-03-17 18:55:43.128363 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-17 18:55:43.161544 | localhost | ok 2026-03-17 18:55:43.172388 | 2026-03-17 18:55:43.172486 | TASK [Upload logs] 2026-03-17 18:55:43.193951 | localhost | ok 2026-03-17 18:55:43.257513 | 2026-03-17 18:55:43.257670 | TASK [Set zuul-log-path fact] 2026-03-17 18:55:43.279443 | localhost | ok 2026-03-17 18:55:43.293600 | 2026-03-17 18:55:43.293713 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-17 18:55:43.327087 | localhost | ok 2026-03-17 18:55:43.334407 | 2026-03-17 18:55:43.334471 | TASK [upload-logs : Create log directories] 2026-03-17 18:55:43.727042 | localhost | changed 2026-03-17 18:55:43.731932 | 2026-03-17 18:55:43.732003 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-17 18:55:44.109382 | localhost -> localhost | ok: Runtime: 0:00:00.003158 2026-03-17 18:55:44.116743 | 2026-03-17 18:55:44.116825 | TASK [upload-logs : Upload logs to log server] 2026-03-17 18:55:44.541659 | localhost | Output suppressed because no_log was given 2026-03-17 18:55:44.546103 | 2026-03-17 18:55:44.546238 | LOOP [upload-logs : Compress console log and json output] 2026-03-17 18:55:44.588406 | localhost | skipping: Conditional result was False 2026-03-17 18:55:44.596722 | localhost | skipping: Conditional result was False 2026-03-17 18:55:44.610761 | 2026-03-17 18:55:44.610953 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-17 18:55:44.658438 | localhost | skipping: Conditional result was False 2026-03-17 18:55:44.658832 | 2026-03-17 18:55:44.663918 | localhost | skipping: Conditional result was False 2026-03-17 18:55:44.674832 | 2026-03-17 18:55:44.675034 | LOOP [upload-logs : Upload console log and json output]