2026-02-28 00:35:52.440287 | Job console starting 2026-02-28 00:35:52.490512 | Updating git repos 2026-02-28 00:35:52.515238 | Cloning repos into workspace 2026-02-28 00:35:52.837829 | Restoring repo states 2026-02-28 00:35:52.849761 | Merging changes 2026-02-28 00:35:53.250184 | Checking out repos 2026-02-28 00:35:53.309947 | Preparing playbooks 2026-02-28 00:35:54.293549 | Running Ansible setup 2026-02-28 00:35:58.832864 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-02-28 00:35:59.461055 | 2026-02-28 00:35:59.461191 | PLAY [localhost] 2026-02-28 00:35:59.468764 | 2026-02-28 00:35:59.468834 | TASK [Gathering Facts] 2026-02-28 00:36:00.309323 | localhost | ok 2026-02-28 00:36:00.321219 | 2026-02-28 00:36:00.321300 | TASK [Setup log path fact] 2026-02-28 00:36:00.340253 | localhost | ok 2026-02-28 00:36:00.352347 | 2026-02-28 00:36:00.352414 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-28 00:36:00.383016 | localhost | ok 2026-02-28 00:36:00.391703 | 2026-02-28 00:36:00.391772 | TASK [emit-job-header : Print job information] 2026-02-28 00:36:00.433060 | # Job Information 2026-02-28 00:36:00.433281 | Ansible Version: 2.16.16 2026-02-28 00:36:00.433352 | Job: ansible-collection-kubernetes-molecule-upload-helm-chart 2026-02-28 00:36:00.433476 | Pipeline: check 2026-02-28 00:36:00.433536 | Executor: 0a8996d2b663 2026-02-28 00:36:00.433581 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/233 2026-02-28 00:36:00.433630 | Event ID: 42d4a9f0-143d-11f1-8055-a157a4e3b739 2026-02-28 00:36:00.437853 | 2026-02-28 00:36:00.437919 | LOOP [emit-job-header : Print node information] 2026-02-28 00:36:00.517551 | localhost | ok: 2026-02-28 00:36:00.518192 | localhost | # Node Information 2026-02-28 00:36:00.518225 | localhost | Inventory Hostname: controller 2026-02-28 00:36:00.518248 | localhost | Hostname: np0000157429 2026-02-28 00:36:00.518267 | localhost | Username: zuul 2026-02-28 00:36:00.518290 | localhost | Distro: Debian 13.3 2026-02-28 00:36:00.518309 | localhost | Provider: yul1 2026-02-28 00:36:00.518328 | localhost | Region: ca-ymq-1 2026-02-28 00:36:00.518346 | localhost | Label: debian-trixie 2026-02-28 00:36:00.518365 | localhost | Product Name: OpenStack Nova 2026-02-28 00:36:00.518383 | localhost | Interface IP: 199.204.45.99 2026-02-28 00:36:00.537408 | 2026-02-28 00:36:00.537598 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-28 00:36:00.929004 | localhost -> localhost | changed 2026-02-28 00:36:00.940068 | 2026-02-28 00:36:00.940147 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-28 00:36:01.766832 | localhost -> localhost | changed 2026-02-28 00:36:01.776686 | 2026-02-28 00:36:01.776957 | PLAY [all] 2026-02-28 00:36:01.785106 | 2026-02-28 00:36:01.785179 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-28 00:36:02.083403 | controller -> localhost | ok 2026-02-28 00:36:02.088854 | 2026-02-28 00:36:02.088925 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-28 00:36:02.117532 | controller | ok 2026-02-28 00:36:02.133963 | controller | included: /var/lib/zuul/builds/275d92e6d8514615983cb3e0dbb63773/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-28 00:36:02.139305 | 2026-02-28 00:36:02.139368 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-28 00:36:03.092410 | controller -> localhost | Generating public/private rsa key pair. 2026-02-28 00:36:03.379449 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/275d92e6d8514615983cb3e0dbb63773/work/275d92e6d8514615983cb3e0dbb63773_id_rsa 2026-02-28 00:36:03.379721 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/275d92e6d8514615983cb3e0dbb63773/work/275d92e6d8514615983cb3e0dbb63773_id_rsa.pub 2026-02-28 00:36:03.379823 | controller -> localhost | The key fingerprint is: 2026-02-28 00:36:03.379900 | controller -> localhost | SHA256:rOr6IM6gCeW3tIzNGnuDig3SnHYLpODf1R/MmoqqI1I zuul-build-sshkey 2026-02-28 00:36:03.379985 | controller -> localhost | The key's randomart image is: 2026-02-28 00:36:03.380040 | controller -> localhost | +---[RSA 3072]----+ 2026-02-28 00:36:03.380097 | controller -> localhost | | | 2026-02-28 00:36:03.380164 | controller -> localhost | | | 2026-02-28 00:36:03.380224 | controller -> localhost | | | 2026-02-28 00:36:03.380290 | controller -> localhost | | . | 2026-02-28 00:36:03.380348 | controller -> localhost | |. o S | 2026-02-28 00:36:03.380395 | controller -> localhost | |oBE. .. o | 2026-02-28 00:36:03.380441 | controller -> localhost | |BoX.+ .. . + | 2026-02-28 00:36:03.380494 | controller -> localhost | |@Bo&+=o + . | 2026-02-28 00:36:03.380561 | controller -> localhost | |B=OO%+ ..o . | 2026-02-28 00:36:03.380610 | controller -> localhost | +----[SHA256]-----+ 2026-02-28 00:36:03.380769 | controller -> localhost | ok: Runtime: 0:00:00.506937 2026-02-28 00:36:03.397580 | 2026-02-28 00:36:03.397651 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-28 00:36:03.429277 | controller | ok 2026-02-28 00:36:03.439905 | controller | included: /var/lib/zuul/builds/275d92e6d8514615983cb3e0dbb63773/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-28 00:36:03.446457 | 2026-02-28 00:36:03.446525 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-28 00:36:03.472000 | controller | skipping: Conditional result was False 2026-02-28 00:36:03.481662 | 2026-02-28 00:36:03.481750 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-28 00:36:04.044715 | controller | changed 2026-02-28 00:36:04.098029 | 2026-02-28 00:36:04.098156 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-28 00:36:04.324382 | controller | ok 2026-02-28 00:36:04.330637 | 2026-02-28 00:36:04.330836 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-28 00:36:04.880581 | controller | changed 2026-02-28 00:36:04.889315 | 2026-02-28 00:36:04.889407 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-28 00:36:05.441104 | controller | changed 2026-02-28 00:36:05.446830 | 2026-02-28 00:36:05.446893 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-28 00:36:05.472252 | controller | skipping: Conditional result was False 2026-02-28 00:36:05.480782 | 2026-02-28 00:36:05.480871 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-28 00:36:05.881679 | controller -> localhost | changed 2026-02-28 00:36:05.897237 | 2026-02-28 00:36:05.897307 | TASK [add-build-sshkey : Add back temp key] 2026-02-28 00:36:06.183419 | controller -> localhost | Identity added: /var/lib/zuul/builds/275d92e6d8514615983cb3e0dbb63773/work/275d92e6d8514615983cb3e0dbb63773_id_rsa (zuul-build-sshkey) 2026-02-28 00:36:06.183660 | controller -> localhost | ok: Runtime: 0:00:00.013473 2026-02-28 00:36:06.192678 | 2026-02-28 00:36:06.192747 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-28 00:36:06.544193 | controller | ok 2026-02-28 00:36:06.547944 | 2026-02-28 00:36:06.548008 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-28 00:36:06.571380 | controller | skipping: Conditional result was False 2026-02-28 00:36:06.594550 | 2026-02-28 00:36:06.594622 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-28 00:36:06.957547 | controller | ok 2026-02-28 00:36:06.964011 | 2026-02-28 00:36:06.964079 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-28 00:36:08.036535 | controller | Output suppressed because no_log was given 2026-02-28 00:36:08.044865 | 2026-02-28 00:36:08.044932 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-28 00:36:08.278750 | controller | ok: "logs" 2026-02-28 00:36:08.279052 | controller | ok: All items complete 2026-02-28 00:36:08.279096 | 2026-02-28 00:36:08.469105 | controller | ok: "artifacts" 2026-02-28 00:36:08.657823 | controller | ok: "docs" 2026-02-28 00:36:08.668862 | 2026-02-28 00:36:08.669005 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-28 00:36:08.897287 | controller | changed: "logs" 2026-02-28 00:36:09.098679 | controller | changed: "artifacts" 2026-02-28 00:36:09.287225 | controller | changed: "docs" 2026-02-28 00:36:09.301060 | 2026-02-28 00:36:09.301177 | PLAY RECAP 2026-02-28 00:36:09.301225 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-02-28 00:36:09.301253 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-28 00:36:09.301272 | 2026-02-28 00:36:09.433806 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-02-28 00:36:09.435336 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-28 00:36:10.018041 | 2026-02-28 00:36:10.018204 | PLAY [all] 2026-02-28 00:36:10.031374 | 2026-02-28 00:36:10.031480 | TASK [setup-uv : Extract archive] 2026-02-28 00:36:12.518080 | controller | changed 2026-02-28 00:36:12.523307 | 2026-02-28 00:36:12.523392 | TASK [setup-uv : Print version] 2026-02-28 00:36:12.897906 | controller | uv 0.8.13 2026-02-28 00:36:13.053669 | controller | ok: Runtime: 0:00:00.013743 2026-02-28 00:36:13.061377 | 2026-02-28 00:36:13.061445 | PLAY RECAP 2026-02-28 00:36:13.061511 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-28 00:36:13.061544 | 2026-02-28 00:36:13.217982 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-28 00:36:13.219313 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-28 00:36:13.766016 | 2026-02-28 00:36:13.766145 | PLAY [all] 2026-02-28 00:36:13.775683 | 2026-02-28 00:36:13.775755 | TASK [Copy inventory file for Zuul] 2026-02-28 00:36:14.731793 | controller | changed 2026-02-28 00:36:14.737606 | 2026-02-28 00:36:14.737712 | TASK [Switch "ansible_host" to private IP] 2026-02-28 00:36:15.049002 | controller | changed: 1 replacements made 2026-02-28 00:36:15.054395 | 2026-02-28 00:36:15.054471 | TASK [Run Molecule scenario] 2026-02-28 00:36:15.504433 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3 2026-02-28 00:36:15.504577 | controller | Creating virtual environment at: .venv 2026-02-28 00:36:15.813168 | controller | Downloading ansible-core (2.3MiB) 2026-02-28 00:36:15.816442 | controller | Downloading pygments (1.2MiB) 2026-02-28 00:36:15.827075 | controller | Downloading cryptography (4.3MiB) 2026-02-28 00:36:15.990578 | controller | Downloading cryptography 2026-02-28 00:36:16.042294 | controller | Downloading pygments 2026-02-28 00:36:16.269933 | controller | Downloading ansible-core 2026-02-28 00:36:16.323150 | controller | Installed 36 packages in 52ms 2026-02-28 00:36:17.011742 | controller | INFO Collection 'vexxhost.kubernetes' detected. 2026-02-28 00:36:17.011805 | controller | INFO Scenarios will be used from 'extensions/molecule' 2026-02-28 00:36:17.970823 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-02-28 00:36:17.970880 | controller | INFO default scenario not found, disabling shared state. 2026-02-28 00:36:17.971053 | controller | INFO [upload-helm-chart > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-02-28 00:36:17.971181 | controller | INFO [upload-helm-chart > prerun] Performing prerun with role_name_check=0... 2026-02-28 00:36:35.435527 | controller | INFO [upload-helm-chart > dependency] Executing 2026-02-28 00:36:35.435607 | controller | WARNING [upload-helm-chart > dependency] Missing roles requirements file: requirements.yml 2026-02-28 00:36:35.435618 | controller | WARNING [upload-helm-chart > dependency] Missing collections requirements file: collections.yml 2026-02-28 00:36:35.435646 | controller | WARNING [upload-helm-chart > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-02-28 00:36:35.441180 | controller | INFO [upload-helm-chart > cleanup] Executing 2026-02-28 00:36:35.441512 | controller | WARNING [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-28 00:36:35.447069 | controller | INFO [upload-helm-chart > destroy] Executing 2026-02-28 00:36:35.447098 | controller | WARNING [upload-helm-chart > destroy] Skipping, '--destroy=never' requested. 2026-02-28 00:36:35.447192 | controller | INFO [upload-helm-chart > destroy] Executed: Successful 2026-02-28 00:36:35.452600 | controller | INFO [upload-helm-chart > syntax] Executing 2026-02-28 00:36:36.322360 | controller | 2026-02-28 00:36:36.322711 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/upload-helm-chart/converge.yml 2026-02-28 00:36:36.387213 | controller | INFO [upload-helm-chart > syntax] Executed: Successful 2026-02-28 00:36:36.407419 | controller | INFO [upload-helm-chart > create] Executing 2026-02-28 00:36:36.411937 | controller | WARNING [upload-helm-chart > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-28 00:36:36.422659 | controller | INFO [upload-helm-chart > prepare] Executing 2026-02-28 00:36:36.423057 | controller | WARNING [upload-helm-chart > prepare] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-28 00:36:36.429740 | controller | INFO [upload-helm-chart > converge] Executing 2026-02-28 00:36:37.355036 | controller | 2026-02-28 00:36:37.355064 | controller | PLAY [Converge] **************************************************************** 2026-02-28 00:36:37.355068 | controller | 2026-02-28 00:36:37.355072 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-28 00:36:37.355076 | controller | Saturday 28 February 2026 00:36:37 +0000 (0:00:00.031) 0:00:00.031 ***** 2026-02-28 00:36:38.643192 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-02-28 00:36:38.646982 | controller | ok: [controller] 2026-02-28 00:36:38.647173 | controller | 2026-02-28 00:36:38.647393 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-02-28 00:36:38.647645 | controller | Saturday 28 February 2026 00:36:38 +0000 (0:00:01.294) 0:00:01.326 ***** 2026-02-28 00:36:38.725764 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-02-28 00:36:38.725862 | controller | 2026-02-28 00:36:38.726063 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-02-28 00:36:38.726252 | controller | Saturday 28 February 2026 00:36:38 +0000 (0:00:00.078) 0:00:01.405 ***** 2026-02-28 00:36:39.470623 | controller | changed: [controller] 2026-02-28 00:36:39.470791 | controller | 2026-02-28 00:36:39.471015 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-02-28 00:36:39.471214 | controller | Saturday 28 February 2026 00:36:39 +0000 (0:00:00.744) 0:00:02.149 ***** 2026-02-28 00:36:39.514757 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-02-28 00:36:39.514935 | controller | 2026-02-28 00:36:39.515158 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-02-28 00:36:39.515367 | controller | Saturday 28 February 2026 00:36:39 +0000 (0:00:00.044) 0:00:02.193 ***** 2026-02-28 00:36:39.945637 | controller | changed: [controller] 2026-02-28 00:36:39.945780 | controller | 2026-02-28 00:36:39.946068 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-02-28 00:36:39.946264 | controller | Saturday 28 February 2026 00:36:39 +0000 (0:00:00.430) 0:00:02.624 ***** 2026-02-28 00:36:41.111185 | controller | changed: [controller] 2026-02-28 00:36:41.111232 | controller | 2026-02-28 00:36:41.111354 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-02-28 00:36:41.111485 | controller | Saturday 28 February 2026 00:36:41 +0000 (0:00:01.162) 0:00:03.787 ***** 2026-02-28 00:36:41.507968 | controller | ok: [controller] 2026-02-28 00:36:41.508193 | controller | 2026-02-28 00:36:41.508524 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-02-28 00:36:41.508746 | controller | Saturday 28 February 2026 00:36:41 +0000 (0:00:00.396) 0:00:04.183 ***** 2026-02-28 00:36:41.556519 | controller | skipping: [controller] 2026-02-28 00:36:41.556632 | controller | 2026-02-28 00:36:41.556847 | controller | PLAY RECAP ********************************************************************* 2026-02-28 00:36:41.557128 | controller | controller : ok=7 changed=3 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-02-28 00:36:41.557311 | controller | 2026-02-28 00:36:41.557489 | controller | 2026-02-28 00:36:41.557744 | controller | TASKS RECAP ******************************************************************** 2026-02-28 00:36:41.557937 | controller | Saturday 28 February 2026 00:36:41 +0000 (0:00:00.052) 0:00:04.236 ***** 2026-02-28 00:36:41.558122 | controller | =============================================================================== 2026-02-28 00:36:41.558384 | controller | Gathering Facts --------------------------------------------------------- 1.29s 2026-02-28 00:36:41.558605 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.16s 2026-02-28 00:36:41.558920 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.74s 2026-02-28 00:36:41.559137 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.43s 2026-02-28 00:36:41.559304 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.40s 2026-02-28 00:36:41.559531 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.08s 2026-02-28 00:36:41.559771 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.05s 2026-02-28 00:36:41.559965 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.04s 2026-02-28 00:36:41.658295 | controller | INFO [upload-helm-chart > converge] Executed: Successful 2026-02-28 00:36:41.669478 | controller | INFO [upload-helm-chart > idempotence] Executing 2026-02-28 00:36:42.474161 | controller | 2026-02-28 00:36:42.474217 | controller | PLAY [Converge] **************************************************************** 2026-02-28 00:36:42.474311 | controller | 2026-02-28 00:36:42.474415 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-28 00:36:42.474531 | controller | Saturday 28 February 2026 00:36:42 +0000 (0:00:00.010) 0:00:00.010 ***** 2026-02-28 00:36:43.539696 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-02-28 00:36:43.543795 | controller | ok: [controller] 2026-02-28 00:36:43.544173 | controller | 2026-02-28 00:36:43.544336 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-02-28 00:36:43.544546 | controller | Saturday 28 February 2026 00:36:43 +0000 (0:00:01.069) 0:00:01.080 ***** 2026-02-28 00:36:43.615909 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-02-28 00:36:43.616209 | controller | 2026-02-28 00:36:43.616425 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-02-28 00:36:43.616686 | controller | Saturday 28 February 2026 00:36:43 +0000 (0:00:00.071) 0:00:01.152 ***** 2026-02-28 00:36:44.365741 | controller | ok: [controller] 2026-02-28 00:36:44.365910 | controller | 2026-02-28 00:36:44.366124 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-02-28 00:36:44.366335 | controller | Saturday 28 February 2026 00:36:44 +0000 (0:00:00.749) 0:00:01.901 ***** 2026-02-28 00:36:44.410934 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-02-28 00:36:44.411079 | controller | 2026-02-28 00:36:44.411318 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-02-28 00:36:44.411643 | controller | Saturday 28 February 2026 00:36:44 +0000 (0:00:00.045) 0:00:01.947 ***** 2026-02-28 00:36:44.814815 | controller | ok: [controller] 2026-02-28 00:36:44.815536 | controller | 2026-02-28 00:36:44.815565 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-02-28 00:36:44.815572 | controller | Saturday 28 February 2026 00:36:44 +0000 (0:00:00.401) 0:00:02.348 ***** 2026-02-28 00:36:45.943678 | controller | ok: [controller] 2026-02-28 00:36:45.943718 | controller | 2026-02-28 00:36:45.943729 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-02-28 00:36:45.943740 | controller | Saturday 28 February 2026 00:36:45 +0000 (0:00:01.130) 0:00:03.478 ***** 2026-02-28 00:36:46.383882 | controller | ok: [controller] 2026-02-28 00:36:46.383944 | controller | 2026-02-28 00:36:46.384073 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-02-28 00:36:46.384183 | controller | Saturday 28 February 2026 00:36:46 +0000 (0:00:00.439) 0:00:03.918 ***** 2026-02-28 00:36:46.423308 | controller | skipping: [controller] 2026-02-28 00:36:46.423366 | controller | 2026-02-28 00:36:46.423443 | controller | PLAY RECAP ********************************************************************* 2026-02-28 00:36:46.423603 | controller | controller : ok=7 changed=0 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-02-28 00:36:46.423683 | controller | 2026-02-28 00:36:46.423766 | controller | 2026-02-28 00:36:46.423845 | controller | TASKS RECAP ******************************************************************** 2026-02-28 00:36:46.423934 | controller | Saturday 28 February 2026 00:36:46 +0000 (0:00:00.042) 0:00:03.960 ***** 2026-02-28 00:36:46.424016 | controller | =============================================================================== 2026-02-28 00:36:46.424107 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.13s 2026-02-28 00:36:46.424192 | controller | Gathering Facts --------------------------------------------------------- 1.07s 2026-02-28 00:36:46.424279 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.75s 2026-02-28 00:36:46.424367 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.44s 2026-02-28 00:36:46.424473 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.40s 2026-02-28 00:36:46.424550 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.07s 2026-02-28 00:36:46.424639 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.05s 2026-02-28 00:36:46.424733 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.04s 2026-02-28 00:36:46.565562 | controller | INFO [upload-helm-chart > idempotence] Executed: Successful 2026-02-28 00:36:46.576904 | controller | INFO [upload-helm-chart > side_effect] Executing 2026-02-28 00:36:47.415309 | controller | 2026-02-28 00:36:47.415712 | controller | PLAY [Side effect] ************************************************************* 2026-02-28 00:36:47.415928 | controller | 2026-02-28 00:36:47.416180 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-28 00:36:47.416537 | controller | Saturday 28 February 2026 00:36:47 +0000 (0:00:00.012) 0:00:00.012 ***** 2026-02-28 00:36:48.547072 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-02-28 00:36:48.553164 | controller | ok: [controller] 2026-02-28 00:36:48.553347 | controller | 2026-02-28 00:36:48.553637 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-02-28 00:36:48.553887 | controller | Saturday 28 February 2026 00:36:48 +0000 (0:00:01.138) 0:00:01.150 ***** 2026-02-28 00:36:48.631112 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/synchronize.yml for controller 2026-02-28 00:36:48.631227 | controller | 2026-02-28 00:36:48.631439 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload Helm chart] *************** 2026-02-28 00:36:48.631692 | controller | Saturday 28 February 2026 00:36:48 +0000 (0:00:00.077) 0:00:01.227 ***** 2026-02-28 00:36:49.393606 | controller | changed: [controller] 2026-02-28 00:36:49.393783 | controller | 2026-02-28 00:36:49.394020 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks] *** 2026-02-28 00:36:49.394265 | controller | Saturday 28 February 2026 00:36:49 +0000 (0:00:00.762) 0:00:01.990 ***** 2026-02-28 00:36:49.438657 | controller | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes/roles/upload_helm_chart/tasks/copy.yml for controller 2026-02-28 00:36:49.438811 | controller | 2026-02-28 00:36:49.439139 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Ensure destination dir] ********** 2026-02-28 00:36:49.439287 | controller | Saturday 28 February 2026 00:36:49 +0000 (0:00:00.045) 0:00:02.035 ***** 2026-02-28 00:36:49.869854 | controller | ok: [controller] 2026-02-28 00:36:49.869892 | controller | 2026-02-28 00:36:49.869903 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy] *** 2026-02-28 00:36:49.869912 | controller | Saturday 28 February 2026 00:36:49 +0000 (0:00:00.429) 0:00:02.465 ***** 2026-02-28 00:36:51.020063 | controller | ok: [controller] 2026-02-28 00:36:51.020154 | controller | 2026-02-28 00:36:51.020369 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Find destination files] ********** 2026-02-28 00:36:51.020496 | controller | Saturday 28 February 2026 00:36:51 +0000 (0:00:01.149) 0:00:03.614 ***** 2026-02-28 00:36:51.421590 | controller | ok: [controller] 2026-02-28 00:36:51.421639 | controller | 2026-02-28 00:36:51.421825 | controller | TASK [vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources] *** 2026-02-28 00:36:51.421849 | controller | Saturday 28 February 2026 00:36:51 +0000 (0:00:00.403) 0:00:04.018 ***** 2026-02-28 00:36:51.748849 | controller | changed: [controller] => (item=/file) 2026-02-28 00:36:51.749011 | controller | 2026-02-28 00:36:51.749131 | controller | PLAY RECAP ********************************************************************* 2026-02-28 00:36:51.749258 | controller | controller : ok=8 changed=2 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-02-28 00:36:51.749364 | controller | 2026-02-28 00:36:51.749590 | controller | 2026-02-28 00:36:51.749834 | controller | TASKS RECAP ******************************************************************** 2026-02-28 00:36:51.750102 | controller | Saturday 28 February 2026 00:36:51 +0000 (0:00:00.328) 0:00:04.346 ***** 2026-02-28 00:36:51.750346 | controller | =============================================================================== 2026-02-28 00:36:51.750606 | controller | vexxhost.kubernetes.upload_helm_chart : Upload helm chart with multi_copy --- 1.15s 2026-02-28 00:36:51.750848 | controller | Gathering Facts --------------------------------------------------------- 1.14s 2026-02-28 00:36:51.751095 | controller | vexxhost.kubernetes.upload_helm_chart : Upload Helm chart --------------- 0.76s 2026-02-28 00:36:51.751345 | controller | vexxhost.kubernetes.upload_helm_chart : Ensure destination dir ---------- 0.43s 2026-02-28 00:36:51.751635 | controller | vexxhost.kubernetes.upload_helm_chart : Find destination files ---------- 0.40s 2026-02-28 00:36:51.751923 | controller | vexxhost.kubernetes.upload_helm_chart : Delete any destination files not in the sources --- 0.33s 2026-02-28 00:36:51.752210 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.08s 2026-02-28 00:36:51.752477 | controller | vexxhost.kubernetes.upload_helm_chart : Include help chart upload method tasks --- 0.05s 2026-02-28 00:36:51.839782 | controller | INFO [upload-helm-chart > side_effect] Executed: Successful 2026-02-28 00:36:51.851276 | controller | INFO [upload-helm-chart > verify] Executing 2026-02-28 00:36:52.746282 | controller | 2026-02-28 00:36:52.746607 | controller | PLAY [Verify] ****************************************************************** 2026-02-28 00:36:52.746858 | controller | 2026-02-28 00:36:52.747121 | controller | TASK [Gathering Facts] ********************************************************* 2026-02-28 00:36:52.747381 | controller | Saturday 28 February 2026 00:36:52 +0000 (0:00:00.010) 0:00:00.010 ***** 2026-02-28 00:36:53.888556 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information. 2026-02-28 00:36:53.893942 | controller | ok: [controller] 2026-02-28 00:36:53.894001 | controller | 2026-02-28 00:36:53.894096 | controller | TASK [Stat file 1] ************************************************************* 2026-02-28 00:36:53.894173 | controller | Saturday 28 February 2026 00:36:53 +0000 (0:00:01.145) 0:00:01.156 ***** 2026-02-28 00:36:54.320782 | controller | ok: [controller] 2026-02-28 00:36:54.320837 | controller | 2026-02-28 00:36:54.321092 | controller | TASK [Stat file 2] ************************************************************* 2026-02-28 00:36:54.321120 | controller | Saturday 28 February 2026 00:36:54 +0000 (0:00:00.428) 0:00:01.584 ***** 2026-02-28 00:36:54.643285 | controller | ok: [controller] 2026-02-28 00:36:54.643367 | controller | 2026-02-28 00:36:54.643826 | controller | TASK [Stat removed file 1] ***************************************************** 2026-02-28 00:36:54.643863 | controller | Saturday 28 February 2026 00:36:54 +0000 (0:00:00.322) 0:00:01.906 ***** 2026-02-28 00:36:54.943309 | controller | ok: [controller] 2026-02-28 00:36:54.943333 | controller | 2026-02-28 00:36:54.943337 | controller | TASK [Stat removed file 2] ***************************************************** 2026-02-28 00:36:54.943348 | controller | Saturday 28 February 2026 00:36:54 +0000 (0:00:00.297) 0:00:02.204 ***** 2026-02-28 00:36:55.239391 | controller | ok: [controller] 2026-02-28 00:36:55.239414 | controller | 2026-02-28 00:36:55.239419 | controller | TASK [ansible.builtin.assert] ************************************************** 2026-02-28 00:36:55.239423 | controller | Saturday 28 February 2026 00:36:55 +0000 (0:00:00.298) 0:00:02.503 ***** 2026-02-28 00:36:55.277218 | controller | ok: [controller] => { 2026-02-28 00:36:55.281576 | controller | "changed": false, 2026-02-28 00:36:55.281591 | controller | "msg": "All assertions passed" 2026-02-28 00:36:55.281597 | controller | } 2026-02-28 00:36:55.281602 | controller | 2026-02-28 00:36:55.281608 | controller | PLAY RECAP ********************************************************************* 2026-02-28 00:36:55.281613 | controller | controller : ok=6 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-02-28 00:36:55.281618 | controller | 2026-02-28 00:36:55.281632 | controller | 2026-02-28 00:36:55.281637 | controller | TASKS RECAP ******************************************************************** 2026-02-28 00:36:55.281641 | controller | Saturday 28 February 2026 00:36:55 +0000 (0:00:00.038) 0:00:02.541 ***** 2026-02-28 00:36:55.281646 | controller | =============================================================================== 2026-02-28 00:36:55.281650 | controller | Gathering Facts --------------------------------------------------------- 1.15s 2026-02-28 00:36:55.281655 | controller | Stat file 1 ------------------------------------------------------------- 0.43s 2026-02-28 00:36:55.281659 | controller | Stat file 2 ------------------------------------------------------------- 0.32s 2026-02-28 00:36:55.281664 | controller | Stat removed file 2 ----------------------------------------------------- 0.30s 2026-02-28 00:36:55.281668 | controller | Stat removed file 1 ----------------------------------------------------- 0.30s 2026-02-28 00:36:55.281673 | controller | ansible.builtin.assert -------------------------------------------------- 0.04s 2026-02-28 00:36:55.384376 | controller | INFO [upload-helm-chart > verify] Executed: Successful 2026-02-28 00:36:55.390649 | controller | INFO [upload-helm-chart > cleanup] Executing 2026-02-28 00:36:55.390995 | controller | WARNING [upload-helm-chart > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-02-28 00:36:55.396857 | controller | INFO [upload-helm-chart > destroy] Executing 2026-02-28 00:36:55.396949 | controller | WARNING [upload-helm-chart > destroy] Skipping, '--destroy=never' requested. 2026-02-28 00:36:55.397034 | controller | INFO [upload-helm-chart > destroy] Executed: Successful 2026-02-28 00:36:55.397334 | controller | WARNING Molecule executed 1 scenario (1 missing files) 2026-02-28 00:36:55.632132 | controller | ok: Runtime: 0:00:40.054243 2026-02-28 00:36:55.637600 | 2026-02-28 00:36:55.637668 | PLAY RECAP 2026-02-28 00:36:55.637732 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-28 00:36:55.637763 | 2026-02-28 00:36:55.753528 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-28 00:36:55.756717 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-02-28 00:36:56.346760 | 2026-02-28 00:36:56.346903 | PLAY [all] 2026-02-28 00:36:56.357094 | 2026-02-28 00:36:56.357184 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-28 00:36:56.401312 | controller | skipping: Conditional result was False 2026-02-28 00:36:56.409993 | 2026-02-28 00:36:56.410127 | TASK [fetch-output : Set log path for single node] 2026-02-28 00:36:56.453813 | controller | ok 2026-02-28 00:36:56.458854 | 2026-02-28 00:36:56.458957 | LOOP [fetch-output : Ensure local output dirs] 2026-02-28 00:36:56.810261 | controller -> localhost | ok: "/var/lib/zuul/builds/275d92e6d8514615983cb3e0dbb63773/work/logs" 2026-02-28 00:36:57.011436 | controller -> localhost | changed: "/var/lib/zuul/builds/275d92e6d8514615983cb3e0dbb63773/work/artifacts" 2026-02-28 00:36:57.239315 | controller -> localhost | changed: "/var/lib/zuul/builds/275d92e6d8514615983cb3e0dbb63773/work/docs" 2026-02-28 00:36:57.252104 | 2026-02-28 00:36:57.252198 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-28 00:36:57.918285 | controller | changed: .d..t...... ./ 2026-02-28 00:36:57.918483 | controller | changed: All items complete 2026-02-28 00:36:57.918510 | 2026-02-28 00:36:58.411449 | controller | changed: .d..t...... ./ 2026-02-28 00:36:58.902270 | controller | changed: .d..t...... ./ 2026-02-28 00:36:58.922846 | 2026-02-28 00:36:58.922998 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-28 00:36:59.344187 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008794 2026-02-28 00:36:59.559266 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006928 2026-02-28 00:36:59.578851 | 2026-02-28 00:36:59.578971 | PLAY [all] 2026-02-28 00:36:59.584009 | 2026-02-28 00:36:59.584071 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-28 00:37:00.070485 | controller | changed 2026-02-28 00:37:00.076657 | 2026-02-28 00:37:00.076707 | PLAY RECAP 2026-02-28 00:37:00.076752 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-02-28 00:37:00.076773 | 2026-02-28 00:37:00.198957 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-02-28 00:37:00.201647 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-28 00:37:00.738132 | 2026-02-28 00:37:00.738265 | PLAY [localhost] 2026-02-28 00:37:00.747863 | 2026-02-28 00:37:00.747937 | TASK [Generate Zuul manifest] 2026-02-28 00:37:00.764821 | localhost | ok 2026-02-28 00:37:00.778810 | 2026-02-28 00:37:00.778887 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-28 00:37:01.077121 | localhost | changed 2026-02-28 00:37:01.089707 | 2026-02-28 00:37:01.089793 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-28 00:37:01.130638 | localhost | ok 2026-02-28 00:37:01.139964 | 2026-02-28 00:37:01.140032 | TASK [Upload logs] 2026-02-28 00:37:01.161957 | localhost | ok 2026-02-28 00:37:01.228507 | 2026-02-28 00:37:01.228655 | TASK [Set zuul-log-path fact] 2026-02-28 00:37:01.246915 | localhost | ok 2026-02-28 00:37:01.259298 | 2026-02-28 00:37:01.259368 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-28 00:37:01.293422 | localhost | ok 2026-02-28 00:37:01.302654 | 2026-02-28 00:37:01.302720 | TASK [upload-logs : Create log directories] 2026-02-28 00:37:01.751176 | localhost | changed 2026-02-28 00:37:01.757659 | 2026-02-28 00:37:01.757732 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-28 00:37:02.157452 | localhost -> localhost | ok: Runtime: 0:00:00.005380 2026-02-28 00:37:02.164159 | 2026-02-28 00:37:02.164269 | TASK [upload-logs : Upload logs to log server] 2026-02-28 00:37:02.611770 | localhost | Output suppressed because no_log was given 2026-02-28 00:37:02.616653 | 2026-02-28 00:37:02.616743 | LOOP [upload-logs : Compress console log and json output] 2026-02-28 00:37:02.659491 | localhost | skipping: Conditional result was False 2026-02-28 00:37:02.666870 | localhost | skipping: Conditional result was False 2026-02-28 00:37:02.679263 | 2026-02-28 00:37:02.679383 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-28 00:37:02.717574 | localhost | skipping: Conditional result was False 2026-02-28 00:37:02.718004 | 2026-02-28 00:37:02.721684 | localhost | skipping: Conditional result was False 2026-02-28 00:37:02.732905 | 2026-02-28 00:37:02.733194 | LOOP [upload-logs : Upload console log and json output]