2026-07-24 16:28:51.353563 | Job console starting
2026-07-24 16:28:51.365554 | Updating git repos
2026-07-24 16:28:51.407814 | Cloning repos into workspace
2026-07-24 16:28:51.797039 | Restoring repo states
2026-07-24 16:28:51.811615 | Merging changes
2026-07-24 16:28:52.307589 | Checking out repos
2026-07-24 16:28:52.501424 | Preparing playbooks
2026-07-24 16:28:55.065776 | Running Ansible setup
2026-07-24 16:28:59.610442 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-24 16:29:00.267300 | 
2026-07-24 16:29:00.267983 | PLAY [localhost]
2026-07-24 16:29:00.276793 | 
2026-07-24 16:29:00.276878 | TASK [Gathering Facts]
2026-07-24 16:29:01.184597 | localhost | ok
2026-07-24 16:29:01.192156 | 
2026-07-24 16:29:01.192271 | TASK [Setup log path fact]
2026-07-24 16:29:01.213350 | localhost | ok
2026-07-24 16:29:01.226581 | 
2026-07-24 16:29:01.228737 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-24 16:29:01.259851 | localhost | ok
2026-07-24 16:29:01.268751 | 
2026-07-24 16:29:01.289674 | TASK [emit-job-header : Print job information]
2026-07-24 16:29:01.332663 | # Job Information
2026-07-24 16:29:01.332893 | Ansible Version: 2.16.18
2026-07-24 16:29:01.333039 | Job: ansible-collection-kubernetes-molecule-helm
2026-07-24 16:29:01.333132 | Pipeline: check
2026-07-24 16:29:01.333184 | Executor: 2d72f0692154
2026-07-24 16:29:01.333229 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/294
2026-07-24 16:29:01.333277 | Event ID: 49bc2ec0-877c-11f1-8df9-cbeb8cc41362
2026-07-24 16:29:01.339313 | 
2026-07-24 16:29:01.339391 | LOOP [emit-job-header : Print node information]
2026-07-24 16:29:01.420774 | localhost | ok:
2026-07-24 16:29:01.421014 | localhost | # Node Information
2026-07-24 16:29:01.421047 | localhost | Inventory Hostname: controller
2026-07-24 16:29:01.421070 | localhost | Hostname: np0000186614
2026-07-24 16:29:01.421095 | localhost | Username: zuul
2026-07-24 16:29:01.421126 | localhost | Distro: Debian 13.6
2026-07-24 16:29:01.421155 | localhost | Provider: yul1
2026-07-24 16:29:01.421181 | localhost | Region: ca-ymq-1
2026-07-24 16:29:01.421208 | localhost | Label: debian-trixie
2026-07-24 16:29:01.421234 | localhost | Product Name: OpenStack Nova
2026-07-24 16:29:01.421255 | localhost | Interface IP: 199.204.45.23
2026-07-24 16:29:01.439933 | 
2026-07-24 16:29:01.440114 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-07-24 16:29:01.860080 | localhost -> localhost | changed
2026-07-24 16:29:01.869240 | 
2026-07-24 16:29:01.869340 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-07-24 16:29:03.181835 | localhost -> localhost | changed
2026-07-24 16:29:03.195569 | 
2026-07-24 16:29:03.195690 | PLAY [all]
2026-07-24 16:29:03.203115 | 
2026-07-24 16:29:03.203192 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-07-24 16:29:03.440929 | controller -> localhost | ok
2026-07-24 16:29:03.449818 | 
2026-07-24 16:29:03.449974 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-07-24 16:29:03.483655 | controller | ok
2026-07-24 16:29:03.499392 | controller | included: /var/lib/zuul/builds/97c6a3f6fccc45a5a237d2f53a608e38/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-07-24 16:29:03.504343 | 
2026-07-24 16:29:03.504410 | TASK [add-build-sshkey : Create Temp SSH key]
2026-07-24 16:29:04.269765 | controller -> localhost | Generating public/private rsa key pair.
2026-07-24 16:29:04.270047 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/97c6a3f6fccc45a5a237d2f53a608e38/work/97c6a3f6fccc45a5a237d2f53a608e38_id_rsa
2026-07-24 16:29:04.270114 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/97c6a3f6fccc45a5a237d2f53a608e38/work/97c6a3f6fccc45a5a237d2f53a608e38_id_rsa.pub
2026-07-24 16:29:04.270166 | controller -> localhost | The key fingerprint is:
2026-07-24 16:29:04.270215 | controller -> localhost | SHA256:0Fz51ICCsss1kkyU3vgdxXESDRiNskbjt70OKQWJ6Lo zuul-build-sshkey
2026-07-24 16:29:04.270283 | controller -> localhost | The key's randomart image is:
2026-07-24 16:29:04.270331 | controller -> localhost | +---[RSA 3072]----+
2026-07-24 16:29:04.270390 | controller -> localhost | |   ... ..**Boo   |
2026-07-24 16:29:04.270439 | controller -> localhost | |   .+.=++.*oo .  |
2026-07-24 16:29:04.270485 | controller -> localhost | |  .+.O++oo o     |
2026-07-24 16:29:04.270531 | controller -> localhost | | .  B Boo   .    |
2026-07-24 16:29:04.270576 | controller -> localhost | |  .. * +S+       |
2026-07-24 16:29:04.270718 | controller -> localhost | | .  o ..o..      |
2026-07-24 16:29:04.270779 | controller -> localhost | |.     . o  .     |
2026-07-24 16:29:04.270826 | controller -> localhost | | .     . ..      |
2026-07-24 16:29:04.270876 | controller -> localhost | |E        ..      |
2026-07-24 16:29:04.270923 | controller -> localhost | +----[SHA256]-----+
2026-07-24 16:29:04.271031 | controller -> localhost | ok: Runtime: 0:00:00.359486
2026-07-24 16:29:04.275710 | 
2026-07-24 16:29:04.275800 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-07-24 16:29:04.305447 | controller | ok
2026-07-24 16:29:04.318890 | controller | included: /var/lib/zuul/builds/97c6a3f6fccc45a5a237d2f53a608e38/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-07-24 16:29:04.330436 | 
2026-07-24 16:29:04.330666 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-07-24 16:29:04.355736 | controller | skipping: Conditional result was False
2026-07-24 16:29:04.363066 | 
2026-07-24 16:29:04.363256 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-07-24 16:29:04.874332 | controller | changed
2026-07-24 16:29:04.878417 | 
2026-07-24 16:29:04.878494 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-07-24 16:29:05.093477 | controller | ok
2026-07-24 16:29:05.099873 | 
2026-07-24 16:29:05.099976 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-07-24 16:29:05.656258 | controller | changed
2026-07-24 16:29:05.662025 | 
2026-07-24 16:29:05.662128 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-07-24 16:29:06.210072 | controller | changed
2026-07-24 16:29:06.215606 | 
2026-07-24 16:29:06.215740 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-07-24 16:29:06.241257 | controller | skipping: Conditional result was False
2026-07-24 16:29:06.246307 | 
2026-07-24 16:29:06.246420 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-07-24 16:29:06.666974 | controller -> localhost | changed
2026-07-24 16:29:06.683274 | 
2026-07-24 16:29:06.683411 | TASK [add-build-sshkey : Add back temp key]
2026-07-24 16:29:06.960240 | controller -> localhost | Identity added: /var/lib/zuul/builds/97c6a3f6fccc45a5a237d2f53a608e38/work/97c6a3f6fccc45a5a237d2f53a608e38_id_rsa (zuul-build-sshkey)
2026-07-24 16:29:06.960529 | controller -> localhost | ok: Runtime: 0:00:00.013255
2026-07-24 16:29:06.967923 | 
2026-07-24 16:29:06.968015 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-07-24 16:29:07.317390 | controller | ok
2026-07-24 16:29:07.323120 | 
2026-07-24 16:29:07.323292 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-07-24 16:29:07.348235 | controller | skipping: Conditional result was False
2026-07-24 16:29:07.363947 | 
2026-07-24 16:29:07.364161 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-07-24 16:29:07.727600 | controller | ok
2026-07-24 16:29:07.732064 | 
2026-07-24 16:29:07.732148 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-07-24 16:29:08.715836 | controller | Output suppressed because no_log was given
2026-07-24 16:29:08.725628 | 
2026-07-24 16:29:08.725710 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-07-24 16:29:08.934560 | controller | ok: "logs"
2026-07-24 16:29:08.934797 | controller | ok: All items complete
2026-07-24 16:29:08.934826 | 
2026-07-24 16:29:09.117235 | controller | ok: "artifacts"
2026-07-24 16:29:09.296502 | controller | ok: "docs"
2026-07-24 16:29:09.313750 | 
2026-07-24 16:29:09.313929 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-07-24 16:29:09.528574 | controller | changed: "logs"
2026-07-24 16:29:09.710836 | controller | changed: "artifacts"
2026-07-24 16:29:09.895289 | controller | changed: "docs"
2026-07-24 16:29:09.908815 | 
2026-07-24 16:29:09.909007 | PLAY RECAP
2026-07-24 16:29:09.909078 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-07-24 16:29:09.909119 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-24 16:29:09.909152 | 
2026-07-24 16:29:10.080656 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-24 16:29:10.082618 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-24 16:29:10.698836 | 
2026-07-24 16:29:10.699029 | PLAY [all]
2026-07-24 16:29:10.709869 | 
2026-07-24 16:29:10.710000 | TASK [setup-uv : Extract archive]
2026-07-24 16:29:14.431458 | controller | changed
2026-07-24 16:29:14.437723 | 
2026-07-24 16:29:14.437848 | TASK [setup-uv : Print version]
2026-07-24 16:29:14.800385 | controller | uv 0.8.13
2026-07-24 16:29:14.971399 | controller | ok: Runtime: 0:00:00.014891
2026-07-24 16:29:14.985317 | 
2026-07-24 16:29:14.985468 | TASK [Install Ansible collection siblings]
2026-07-24 16:29:15.398590 | controller | ok:
2026-07-24 16:29:15.398784 | controller | Skipping ansible.posix: no checked-out sibling
2026-07-24 16:29:15.398826 | controller | Skipping community.crypto: no checked-out sibling
2026-07-24 16:29:15.398857 | controller | Skipping community.general: no checked-out sibling
2026-07-24 16:29:15.398886 | controller | Skipping kubernetes.core: no checked-out sibling
2026-07-24 16:29:15.398913 | controller | Skipping vexxhost.containers: no checked-out sibling
2026-07-24 16:29:15.401283 | 
2026-07-24 16:29:15.401360 | PLAY RECAP
2026-07-24 16:29:15.401473 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-24 16:29:15.401515 | 
2026-07-24 16:29:15.527937 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-24 16:29:15.531108 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-07-24 16:29:16.167568 | 
2026-07-24 16:29:16.167745 | PLAY [all]
2026-07-24 16:29:16.183263 | 
2026-07-24 16:29:16.184701 | TASK [Update apt cache]
2026-07-24 16:29:20.733091 | controller | ok
2026-07-24 16:29:20.740348 | 
2026-07-24 16:29:20.740426 | TASK [Disable firewalld]
2026-07-24 16:29:20.755701 | controller | skipping: Conditional result was False
2026-07-24 16:29:20.762451 | 
2026-07-24 16:29:20.762548 | TASK [Install "jq" for log collection]
2026-07-24 16:29:22.915966 | controller | changed
2026-07-24 16:29:22.918283 | 
2026-07-24 16:29:22.918338 | PLAY RECAP
2026-07-24 16:29:22.918388 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-24 16:29:22.918413 | 
2026-07-24 16:29:23.077113 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-07-24 16:29:23.081572 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-07-24 16:29:23.777547 | 
2026-07-24 16:29:23.777699 | PLAY [all]
2026-07-24 16:29:23.788843 | 
2026-07-24 16:29:23.788938 | LOOP [Check inventory directories]
2026-07-24 16:29:24.132570 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-07-24 16:29:24.309100 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/helm/inventory"
2026-07-24 16:29:24.488190 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/inventory"
2026-07-24 16:29:24.512602 | 
2026-07-24 16:29:24.513875 | LOOP [Copy inventory file for Zuul]
2026-07-24 16:29:25.433982 | controller | changed:
2026-07-24 16:29:25.434247 | controller | {
2026-07-24 16:29:25.434289 | controller |   "ansible_loop_var": "item",
2026-07-24 16:29:25.434324 | controller |   "changed": false,
2026-07-24 16:29:25.434353 | controller |   "failed": false,
2026-07-24 16:29:25.434386 | controller |   "invocation": {
2026-07-24 16:29:25.434414 | controller |     "module_args": {
2026-07-24 16:29:25.434441 | controller |       "checksum_algorithm": "sha1",
2026-07-24 16:29:25.434468 | controller |       "follow": true,
2026-07-24 16:29:25.434495 | controller |       "get_attributes": true,
2026-07-24 16:29:25.434523 | controller |       "get_checksum": true,
2026-07-24 16:29:25.434550 | controller |       "get_mime": true,
2026-07-24 16:29:25.434577 | controller |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-07-24 16:29:25.434603 | controller |     }
2026-07-24 16:29:25.434629 | controller |   },
2026-07-24 16:29:25.434656 | controller |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-07-24 16:29:25.434682 | controller |   "stat": {
2026-07-24 16:29:25.434708 | controller |     "atime": 1784910548.5762756,
2026-07-24 16:29:25.434735 | controller |     "attr_flags": "e",
2026-07-24 16:29:25.434761 | controller |     "attributes": [
2026-07-24 16:29:25.434788 | controller |       "extents"
2026-07-24 16:29:25.434814 | controller |     ],
2026-07-24 16:29:25.434840 | controller |     "block_size": 4096,
2026-07-24 16:29:25.434866 | controller |     "blocks": 8,
2026-07-24 16:29:25.434892 | controller |     "charset": "unknown",
2026-07-24 16:29:25.434921 | controller |     "ctime": 1784910548.604278,
2026-07-24 16:29:25.434948 | controller |     "dev": 65025,
2026-07-24 16:29:25.434976 | controller |     "device_type": 0,
2026-07-24 16:29:25.435003 | controller |     "executable": true,
2026-07-24 16:29:25.435029 | controller |     "exists": true,
2026-07-24 16:29:25.435055 | controller |     "gid": 1000,
2026-07-24 16:29:25.435081 | controller |     "gr_name": "zuul",
2026-07-24 16:29:25.435106 | controller |     "inode": 518935,
2026-07-24 16:29:25.435132 | controller |     "isblk": false,
2026-07-24 16:29:25.435158 | controller |     "ischr": false,
2026-07-24 16:29:25.435207 | controller |     "isdir": true,
2026-07-24 16:29:25.435243 | controller |     "isfifo": false,
2026-07-24 16:29:25.435275 | controller |     "isgid": false,
2026-07-24 16:29:25.435302 | controller |     "islnk": false,
2026-07-24 16:29:25.435329 | controller |     "isreg": false,
2026-07-24 16:29:25.435355 | controller |     "issock": false,
2026-07-24 16:29:25.435381 | controller |     "isuid": false,
2026-07-24 16:29:25.435407 | controller |     "mimetype": "unknown",
2026-07-24 16:29:25.435433 | controller |     "mode": "0755",
2026-07-24 16:29:25.435458 | controller |     "mtime": 1784910531.9289253,
2026-07-24 16:29:25.435484 | controller |     "nlink": 14,
2026-07-24 16:29:25.435511 | controller |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-07-24 16:29:25.435537 | controller |     "pw_name": "zuul",
2026-07-24 16:29:25.435568 | controller |     "readable": true,
2026-07-24 16:29:25.435596 | controller |     "rgrp": true,
2026-07-24 16:29:25.435623 | controller |     "roth": true,
2026-07-24 16:29:25.435649 | controller |     "rusr": true,
2026-07-24 16:29:25.435675 | controller |     "size": 4096,
2026-07-24 16:29:25.435701 | controller |     "uid": 1000,
2026-07-24 16:29:25.435727 | controller |     "version": "1387933689",
2026-07-24 16:29:25.435754 | controller |     "wgrp": false,
2026-07-24 16:29:25.435781 | controller |     "woth": false,
2026-07-24 16:29:25.435808 | controller |     "writeable": true,
2026-07-24 16:29:25.435834 | controller |     "wusr": true,
2026-07-24 16:29:25.435860 | controller |     "xgrp": true,
2026-07-24 16:29:25.435885 | controller |     "xoth": true,
2026-07-24 16:29:25.435910 | controller |     "xusr": true
2026-07-24 16:29:25.435936 | controller |   }
2026-07-24 16:29:25.435985 | controller | }
2026-07-24 16:29:25.436038 | controller | ok: All items complete
2026-07-24 16:29:25.436070 | 
2026-07-24 16:29:25.438219 | controller | skipping: Conditional result was False
2026-07-24 16:29:25.439168 | controller | skipping: Conditional result was False
2026-07-24 16:29:25.448887 | 
2026-07-24 16:29:25.449067 | LOOP [Switch "ansible_host" to private IP]
2026-07-24 16:29:25.821956 | controller | changed: 1 replacements made
2026-07-24 16:29:25.824525 | controller | skipping: Conditional result was False
2026-07-24 16:29:25.825190 | controller | skipping: Conditional result was False
2026-07-24 16:29:25.839015 | 
2026-07-24 16:29:25.839193 | TASK [Run Molecule scenario]
2026-07-24 16:29:26.288567 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-07-24 16:29:26.288663 | controller | Creating virtual environment at: .venv
2026-07-24 16:29:26.565148 | controller | Downloading ansible-core (2.3MiB)
2026-07-24 16:29:26.565667 | controller | Downloading cryptography (4.3MiB)
2026-07-24 16:29:26.571004 | controller | Downloading pygments (1.2MiB)
2026-07-24 16:29:26.732064 | controller |  Downloading cryptography
2026-07-24 16:29:26.797205 | controller |  Downloading pygments
2026-07-24 16:29:26.987189 | controller |  Downloading ansible-core
2026-07-24 16:29:27.036070 | controller | Installed 36 packages in 48ms
2026-07-24 16:29:27.759536 | controller | INFO     Collection 'vexxhost.kubernetes' detected.
2026-07-24 16:29:27.759600 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-07-24 16:29:28.586257 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-07-24 16:29:28.586323 | controller | INFO     default scenario not found, disabling shared state.
2026-07-24 16:29:28.586439 | controller | INFO     [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-07-24 16:29:28.586514 | controller | INFO     [helm > prerun] Performing prerun with role_name_check=0...
2026-07-24 16:29:45.152516 | controller | INFO     [helm > dependency] Executing
2026-07-24 16:29:45.152768 | controller | WARNING  [helm > dependency] Missing roles requirements file: requirements.yml
2026-07-24 16:29:45.153098 | controller | WARNING  [helm > dependency] Missing collections requirements file: collections.yml
2026-07-24 16:29:45.153236 | controller | WARNING  [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-07-24 16:29:45.160456 | controller | INFO     [helm > cleanup] Executing
2026-07-24 16:29:45.160745 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-24 16:29:45.165993 | controller | INFO     [helm > destroy] Executing
2026-07-24 16:29:45.166049 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-07-24 16:29:45.166139 | controller | INFO     [helm > destroy] Executed: Successful
2026-07-24 16:29:45.171384 | controller | INFO     [helm > syntax] Executing
2026-07-24 16:29:46.067467 | controller |
2026-07-24 16:29:46.067820 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml
2026-07-24 16:29:46.140846 | controller | INFO     [helm > syntax] Executed: Successful
2026-07-24 16:29:46.153977 | controller | INFO     [helm > create] Executing
2026-07-24 16:29:46.156333 | controller | WARNING  [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-24 16:29:46.161688 | controller | INFO     [helm > prepare] Executing
2026-07-24 16:29:46.985616 | controller |
2026-07-24 16:29:46.985787 | controller | PLAY [Prepare] *****************************************************************
2026-07-24 16:29:46.985974 | controller |
2026-07-24 16:29:46.986174 | controller | TASK [Gathering Facts] *********************************************************
2026-07-24 16:29:46.986374 | controller | Friday 24 July 2026  16:29:46 +0000 (0:00:00.029)       0:00:00.029 ***********
2026-07-24 16:29:48.223819 | 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-07-24 16:29:48.227233 | controller | ok: [controller]
2026-07-24 16:29:48.227412 | controller |
2026-07-24 16:29:48.227609 | controller | TASK [Install Debian packages] *************************************************
2026-07-24 16:29:48.227841 | controller | Friday 24 July 2026  16:29:48 +0000 (0:00:01.242)       0:00:01.271 ***********
2026-07-24 16:29:49.593576 | controller | ok: [controller]
2026-07-24 16:29:49.593744 | controller |
2026-07-24 16:29:49.593948 | controller | PLAY RECAP *********************************************************************
2026-07-24 16:29:49.594171 | controller | controller                 : ok=2    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-24 16:29:49.595838 | controller |
2026-07-24 16:29:49.595870 | controller |
2026-07-24 16:29:49.595876 | controller | TASKS RECAP ********************************************************************
2026-07-24 16:29:49.595892 | controller | Friday 24 July 2026  16:29:49 +0000 (0:00:01.366)       0:00:02.638 ***********
2026-07-24 16:29:49.595896 | controller | ===============================================================================
2026-07-24 16:29:49.595901 | controller | Install Debian packages ------------------------------------------------- 1.37s
2026-07-24 16:29:49.595907 | controller | Gathering Facts --------------------------------------------------------- 1.24s
2026-07-24 16:29:49.688612 | controller | INFO     [helm > prepare] Executed: Successful
2026-07-24 16:29:49.699307 | controller | INFO     [helm > converge] Executing
2026-07-24 16:29:50.529620 | controller |
2026-07-24 16:29:50.529835 | controller | PLAY [Converge] ****************************************************************
2026-07-24 16:29:50.530021 | controller |
2026-07-24 16:29:50.530243 | controller | TASK [Gathering Facts] *********************************************************
2026-07-24 16:29:50.530440 | controller | Friday 24 July 2026  16:29:50 +0000 (0:00:00.013)       0:00:00.013 ***********
2026-07-24 16:29:51.563605 | 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-07-24 16:29:51.568357 | controller | ok: [controller]
2026-07-24 16:29:51.568555 | controller |
2026-07-24 16:29:51.568767 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-07-24 16:29:51.568963 | controller | Friday 24 July 2026  16:29:51 +0000 (0:00:01.038)       0:00:01.051 ***********
2026-07-24 16:29:52.035961 | controller | ok: [controller]
2026-07-24 16:29:52.036073 | controller |
2026-07-24 16:29:52.036330 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-07-24 16:29:52.036538 | controller | Friday 24 July 2026  16:29:52 +0000 (0:00:00.466)       0:00:01.518 ***********
2026-07-24 16:29:53.047375 | controller | ok: [controller]
2026-07-24 16:29:53.047455 | controller |
2026-07-24 16:29:53.047615 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-07-24 16:29:53.047821 | controller | Friday 24 July 2026  16:29:53 +0000 (0:00:01.010)       0:00:02.529 ***********
2026-07-24 16:29:53.450564 | controller | changed: [controller]
2026-07-24 16:29:53.450714 | controller |
2026-07-24 16:29:53.450928 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-07-24 16:29:53.451144 | controller | Friday 24 July 2026  16:29:53 +0000 (0:00:00.404)       0:00:02.933 ***********
2026-07-24 16:29:53.494112 | controller | ok: [controller] => {
2026-07-24 16:29:53.494284 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-07-24 16:29:53.494472 | controller | }
2026-07-24 16:29:53.494650 | controller |
2026-07-24 16:29:53.494853 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-07-24 16:29:53.495049 | controller | Friday 24 July 2026  16:29:53 +0000 (0:00:00.043)       0:00:02.977 ***********
2026-07-24 16:29:54.363307 | controller | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created with a mode of 0700, this may cause issues when running as another user. To avoid this, create the remote_tmp dir with the correct permissions manually
2026-07-24 16:29:54.372802 | controller | changed: [controller]
2026-07-24 16:29:54.372994 | controller |
2026-07-24 16:29:54.373211 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-07-24 16:29:54.373419 | controller | Friday 24 July 2026  16:29:54 +0000 (0:00:00.878)       0:00:03.856 ***********
2026-07-24 16:29:56.365990 | controller | changed: [controller]
2026-07-24 16:29:56.366128 | controller |
2026-07-24 16:29:56.366339 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-07-24 16:29:56.366553 | controller | Friday 24 July 2026  16:29:56 +0000 (0:00:01.992)       0:00:05.849 ***********
2026-07-24 16:29:56.845615 | controller | ok: [controller]
2026-07-24 16:29:56.845766 | controller |
2026-07-24 16:29:56.846005 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-07-24 16:29:56.846197 | controller | Friday 24 July 2026  16:29:56 +0000 (0:00:00.479)       0:00:06.328 ***********
2026-07-24 16:29:57.236133 | controller | changed: [controller]
2026-07-24 16:29:57.236295 | controller |
2026-07-24 16:29:57.236513 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-07-24 16:29:57.236707 | controller | Friday 24 July 2026  16:29:57 +0000 (0:00:00.390)       0:00:06.719 ***********
2026-07-24 16:29:57.669965 | controller | ok: [controller]
2026-07-24 16:29:57.670148 | controller |
2026-07-24 16:29:57.670334 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-07-24 16:29:57.670560 | controller | Friday 24 July 2026  16:29:57 +0000 (0:00:00.433)       0:00:07.153 ***********
2026-07-24 16:29:57.952588 | controller | changed: [controller]
2026-07-24 16:29:57.952629 | controller |
2026-07-24 16:29:57.952738 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-07-24 16:29:57.952827 | controller | Friday 24 July 2026  16:29:57 +0000 (0:00:00.282)       0:00:07.435 ***********
2026-07-24 16:29:58.244495 | controller | ok: [controller]
2026-07-24 16:29:58.244672 | controller |
2026-07-24 16:29:58.244887 | controller | TASK [Install plugin] **********************************************************
2026-07-24 16:29:58.245094 | controller | Friday 24 July 2026  16:29:58 +0000 (0:00:00.289)       0:00:07.725 ***********
2026-07-24 16:29:58.297798 | controller | included: vexxhost.containers.download_artifact for controller
2026-07-24 16:29:58.297956 | controller |
2026-07-24 16:29:58.298152 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-07-24 16:29:58.298351 | controller | Friday 24 July 2026  16:29:58 +0000 (0:00:00.056)       0:00:07.781 ***********
2026-07-24 16:29:58.343924 | controller | ok: [controller] => {
2026-07-24 16:29:58.344110 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-07-24 16:29:58.344289 | controller | }
2026-07-24 16:29:58.344458 | controller |
2026-07-24 16:29:58.344666 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-07-24 16:29:58.344875 | controller | Friday 24 July 2026  16:29:58 +0000 (0:00:00.045)       0:00:07.827 ***********
2026-07-24 16:29:59.151747 | controller | changed: [controller]
2026-07-24 16:29:59.151926 | controller |
2026-07-24 16:29:59.152131 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-07-24 16:29:59.152323 | controller | Friday 24 July 2026  16:29:59 +0000 (0:00:00.807)       0:00:08.634 ***********
2026-07-24 16:30:01.181581 | controller | changed: [controller]
2026-07-24 16:30:01.181733 | controller |
2026-07-24 16:30:01.181926 | controller | PLAY RECAP *********************************************************************
2026-07-24 16:30:01.182138 | controller | controller                 : ok=16   changed=7    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-24 16:30:01.182310 | controller |
2026-07-24 16:30:01.182478 | controller |
2026-07-24 16:30:01.182656 | controller | TASKS RECAP ********************************************************************
2026-07-24 16:30:01.182845 | controller | Friday 24 July 2026  16:30:01 +0000 (0:00:02.030)       0:00:10.665 ***********
2026-07-24 16:30:01.183026 | controller | ===============================================================================
2026-07-24 16:30:01.183209 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.03s
2026-07-24 16:30:01.183398 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.99s
2026-07-24 16:30:01.183582 | controller | Gathering Facts --------------------------------------------------------- 1.04s
2026-07-24 16:30:01.183847 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.01s
2026-07-24 16:30:01.184017 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.88s
2026-07-24 16:30:01.184209 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.81s
2026-07-24 16:30:01.184428 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.48s
2026-07-24 16:30:01.184632 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.47s
2026-07-24 16:30:01.184851 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.43s
2026-07-24 16:30:01.185045 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.40s
2026-07-24 16:30:01.185238 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.39s
2026-07-24 16:30:01.185443 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.29s
2026-07-24 16:30:01.185647 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.28s
2026-07-24 16:30:01.185838 | controller | Install plugin ---------------------------------------------------------- 0.06s
2026-07-24 16:30:01.186031 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-07-24 16:30:01.186222 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s
2026-07-24 16:30:01.277934 | controller | INFO     [helm > converge] Executed: Successful
2026-07-24 16:30:01.288243 | controller | INFO     [helm > idempotence] Executing
2026-07-24 16:30:02.136942 | controller |
2026-07-24 16:30:02.137060 | controller | PLAY [Converge] ****************************************************************
2026-07-24 16:30:02.137282 | controller |
2026-07-24 16:30:02.137485 | controller | TASK [Gathering Facts] *********************************************************
2026-07-24 16:30:02.137648 | controller | Friday 24 July 2026  16:30:02 +0000 (0:00:00.013)       0:00:00.013 ***********
2026-07-24 16:30:03.193768 | 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-07-24 16:30:03.200096 | controller | ok: [controller]
2026-07-24 16:30:03.200300 | controller |
2026-07-24 16:30:03.200515 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-07-24 16:30:03.200717 | controller | Friday 24 July 2026  16:30:03 +0000 (0:00:01.063)       0:00:01.076 ***********
2026-07-24 16:30:03.625370 | controller | ok: [controller]
2026-07-24 16:30:03.625421 | controller |
2026-07-24 16:30:03.625550 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-07-24 16:30:03.625701 | controller | Friday 24 July 2026  16:30:03 +0000 (0:00:00.425)       0:00:01.501 ***********
2026-07-24 16:30:04.600149 | controller | ok: [controller]
2026-07-24 16:30:04.600203 | controller |
2026-07-24 16:30:04.600281 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-07-24 16:30:04.602809 | controller | Friday 24 July 2026  16:30:04 +0000 (0:00:00.973)       0:00:02.475 ***********
2026-07-24 16:30:05.034657 | controller | ok: [controller]
2026-07-24 16:30:05.034830 | controller |
2026-07-24 16:30:05.035033 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-07-24 16:30:05.035230 | controller | Friday 24 July 2026  16:30:05 +0000 (0:00:00.435)       0:00:02.910 ***********
2026-07-24 16:30:05.076101 | controller | ok: [controller] => {
2026-07-24 16:30:05.076351 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-07-24 16:30:05.076546 | controller | }
2026-07-24 16:30:05.076726 | controller |
2026-07-24 16:30:05.076919 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-07-24 16:30:05.077117 | controller | Friday 24 July 2026  16:30:05 +0000 (0:00:00.041)       0:00:02.952 ***********
2026-07-24 16:30:05.577269 | controller | ok: [controller]
2026-07-24 16:30:05.577436 | controller |
2026-07-24 16:30:05.577637 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-07-24 16:30:05.577841 | controller | Friday 24 July 2026  16:30:05 +0000 (0:00:00.500)       0:00:03.453 ***********
2026-07-24 16:30:07.219361 | controller | ok: [controller]
2026-07-24 16:30:07.219380 | controller |
2026-07-24 16:30:07.219384 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-07-24 16:30:07.219388 | controller | Friday 24 July 2026  16:30:07 +0000 (0:00:01.639)       0:00:05.092 ***********
2026-07-24 16:30:07.720194 | controller | ok: [controller]
2026-07-24 16:30:07.720248 | controller |
2026-07-24 16:30:07.720387 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-07-24 16:30:07.720595 | controller | Friday 24 July 2026  16:30:07 +0000 (0:00:00.503)       0:00:05.596 ***********
2026-07-24 16:30:08.111811 | controller | ok: [controller]
2026-07-24 16:30:08.111869 | controller |
2026-07-24 16:30:08.111914 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-07-24 16:30:08.112014 | controller | Friday 24 July 2026  16:30:08 +0000 (0:00:00.391)       0:00:05.988 ***********
2026-07-24 16:30:08.559138 | controller | ok: [controller]
2026-07-24 16:30:08.559225 | controller |
2026-07-24 16:30:08.559385 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-07-24 16:30:08.559512 | controller | Friday 24 July 2026  16:30:08 +0000 (0:00:00.443)       0:00:06.432 ***********
2026-07-24 16:30:08.849409 | controller | ok: [controller]
2026-07-24 16:30:08.849576 | controller |
2026-07-24 16:30:08.849825 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-07-24 16:30:08.850042 | controller | Friday 24 July 2026  16:30:08 +0000 (0:00:00.293)       0:00:06.725 ***********
2026-07-24 16:30:09.140052 | controller | ok: [controller]
2026-07-24 16:30:09.140240 | controller |
2026-07-24 16:30:09.140476 | controller | TASK [Install plugin] **********************************************************
2026-07-24 16:30:09.140686 | controller | Friday 24 July 2026  16:30:09 +0000 (0:00:00.291)       0:00:07.016 ***********
2026-07-24 16:30:09.193419 | controller | included: vexxhost.containers.download_artifact for controller
2026-07-24 16:30:09.193589 | controller |
2026-07-24 16:30:09.193801 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-07-24 16:30:09.194030 | controller | Friday 24 July 2026  16:30:09 +0000 (0:00:00.053)       0:00:07.069 ***********
2026-07-24 16:30:09.239082 | controller | ok: [controller] => {
2026-07-24 16:30:09.239320 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-07-24 16:30:09.239507 | controller | }
2026-07-24 16:30:09.239700 | controller |
2026-07-24 16:30:09.239973 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-07-24 16:30:09.240207 | controller | Friday 24 July 2026  16:30:09 +0000 (0:00:00.045)       0:00:07.115 ***********
2026-07-24 16:30:09.702403 | controller | ok: [controller]
2026-07-24 16:30:09.702448 | controller |
2026-07-24 16:30:09.702565 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-07-24 16:30:09.702667 | controller | Friday 24 July 2026  16:30:09 +0000 (0:00:00.463)       0:00:07.578 ***********
2026-07-24 16:30:11.273907 | controller | ok: [controller]
2026-07-24 16:30:11.274054 | controller |
2026-07-24 16:30:11.274287 | controller | PLAY RECAP *********************************************************************
2026-07-24 16:30:11.274460 | controller | controller                 : ok=16   changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-24 16:30:11.274632 | controller |
2026-07-24 16:30:11.274804 | controller |
2026-07-24 16:30:11.274988 | controller | TASKS RECAP ********************************************************************
2026-07-24 16:30:11.275194 | controller | Friday 24 July 2026  16:30:11 +0000 (0:00:01.572)       0:00:09.150 ***********
2026-07-24 16:30:11.275367 | controller | ===============================================================================
2026-07-24 16:30:11.275628 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.64s
2026-07-24 16:30:11.275937 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.57s
2026-07-24 16:30:11.276111 | controller | Gathering Facts --------------------------------------------------------- 1.06s
2026-07-24 16:30:11.276298 | controller | vexxhost.containers.package : Update state for tar ---------------------- 0.97s
2026-07-24 16:30:11.276487 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.50s
2026-07-24 16:30:11.276670 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.50s
2026-07-24 16:30:11.276854 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.46s
2026-07-24 16:30:11.277040 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.44s
2026-07-24 16:30:11.277229 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.44s
2026-07-24 16:30:11.277415 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.43s
2026-07-24 16:30:11.277603 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.39s
2026-07-24 16:30:11.277810 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.29s
2026-07-24 16:30:11.278001 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.29s
2026-07-24 16:30:11.278204 | controller | Install plugin ---------------------------------------------------------- 0.05s
2026-07-24 16:30:11.278402 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-07-24 16:30:11.278588 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s
2026-07-24 16:30:11.360715 | controller | INFO     [helm > idempotence] Executed: Successful
2026-07-24 16:30:11.370132 | controller | INFO     [helm > side_effect] Executing
2026-07-24 16:30:11.370533 | controller | WARNING  [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-24 16:30:11.377583 | controller | INFO     [helm > verify] Executing
2026-07-24 16:30:12.169246 | controller |
2026-07-24 16:30:12.169409 | controller | PLAY [Verify] ******************************************************************
2026-07-24 16:30:12.169581 | controller |
2026-07-24 16:30:12.169776 | controller | TASK [Gathering Facts] *********************************************************
2026-07-24 16:30:12.169971 | controller | Friday 24 July 2026  16:30:12 +0000 (0:00:00.009)       0:00:00.009 ***********
2026-07-24 16:30:13.180706 | 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-07-24 16:30:13.186361 | controller | ok: [controller]
2026-07-24 16:30:13.186459 | controller |
2026-07-24 16:30:13.186546 | controller | TASK [Get the current Helm version] ********************************************
2026-07-24 16:30:13.186631 | controller | Friday 24 July 2026  16:30:13 +0000 (0:00:01.017)       0:00:01.027 ***********
2026-07-24 16:30:13.623389 | controller | changed: [controller]
2026-07-24 16:30:13.623583 | controller |
2026-07-24 16:30:13.623849 | controller | TASK [Assert Helm version] *****************************************************
2026-07-24 16:30:13.624063 | controller | Friday 24 July 2026  16:30:13 +0000 (0:00:00.436)       0:00:01.463 ***********
2026-07-24 16:30:13.652304 | controller | ok: [controller] => {
2026-07-24 16:30:13.652492 | controller |     "changed": false,
2026-07-24 16:30:13.652684 | controller |     "msg": "All assertions passed"
2026-07-24 16:30:13.652876 | controller | }
2026-07-24 16:30:13.653029 | controller |
2026-07-24 16:30:13.653215 | controller | PLAY RECAP *********************************************************************
2026-07-24 16:30:13.653424 | controller | controller                 : ok=3    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-24 16:30:13.653588 | controller |
2026-07-24 16:30:13.653747 | controller |
2026-07-24 16:30:13.653931 | controller | TASKS RECAP ********************************************************************
2026-07-24 16:30:13.654166 | controller | Friday 24 July 2026  16:30:13 +0000 (0:00:00.029)       0:00:01.493 ***********
2026-07-24 16:30:13.654333 | controller | ===============================================================================
2026-07-24 16:30:13.654510 | controller | Gathering Facts --------------------------------------------------------- 1.02s
2026-07-24 16:30:13.654695 | controller | Get the current Helm version -------------------------------------------- 0.44s
2026-07-24 16:30:13.654873 | controller | Assert Helm version ----------------------------------------------------- 0.03s
2026-07-24 16:30:13.743879 | controller | INFO     [helm > verify] Executed: Successful
2026-07-24 16:30:13.751877 | controller | INFO     [helm > cleanup] Executing
2026-07-24 16:30:13.752285 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-24 16:30:13.758421 | controller | INFO     [helm > destroy] Executing
2026-07-24 16:30:13.758496 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-07-24 16:30:13.758558 | controller | INFO     [helm > destroy] Executed: Successful
2026-07-24 16:30:13.758804 | controller | WARNING  Molecule executed 1 scenario (1 missing files)
2026-07-24 16:30:13.899821 | controller | ok: Runtime: 0:00:47.638398
2026-07-24 16:30:13.906618 | 
2026-07-24 16:30:13.906678 | PLAY RECAP
2026-07-24 16:30:13.906721 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-24 16:30:13.906743 | 
2026-07-24 16:30:14.066435 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-07-24 16:30:14.069598 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-07-24 16:30:14.729344 | 
2026-07-24 16:30:14.729522 | PLAY [all]
2026-07-24 16:30:14.744004 | 
2026-07-24 16:30:14.744136 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-07-24 16:30:15.138437 | controller | changed
2026-07-24 16:30:15.151118 | 
2026-07-24 16:30:15.151251 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-07-24 16:30:15.537974 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-24 16:30:15.538546 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-24 16:30:15.545197 | controller | environment: line 1: kubectl: command not found
2026-07-24 16:30:15.546966 | controller | environment: line 1: kubectl: command not found
2026-07-24 16:30:15.546990 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-24 16:30:15.548195 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-24 16:30:15.550435 | controller | environment: line 1: kubectl: command not found
2026-07-24 16:30:15.551298 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-24 16:30:15.553492 | controller | environment: line 1: kubectl: command not found
2026-07-24 16:30:15.554452 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-24 16:30:15.555253 | controller | environment: line 1: kubectl: command not found
2026-07-24 16:30:15.556410 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-24 16:30:15.686198 | controller | ok: Runtime: 0:00:00.031521
2026-07-24 16:30:15.692663 | 
2026-07-24 16:30:15.692766 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-07-24 16:30:15.927124 | controller | changed
2026-07-24 16:30:15.932630 | 
2026-07-24 16:30:15.932722 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-07-24 16:30:16.161361 | controller | environment: line 5: kubectl: command not found
2026-07-24 16:30:16.162963 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-24 16:30:16.163130 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-24 16:30:16.163562 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-24 16:30:16.460467 | controller | ok: Runtime: 0:00:00.012320
2026-07-24 16:30:16.468061 | 
2026-07-24 16:30:16.468163 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-07-24 16:30:17.120619 | controller | changed:
2026-07-24 16:30:17.121033 | controller | created directory /var/lib/zuul/builds/97c6a3f6fccc45a5a237d2f53a608e38/work/logs/controller
2026-07-24 16:30:17.121112 | controller | cd+++++++++ objects/
2026-07-24 16:30:17.121166 | controller | cd+++++++++ objects/cluster/
2026-07-24 16:30:17.121219 | controller | cd+++++++++ objects/namespaced/
2026-07-24 16:30:17.129050 | 
2026-07-24 16:30:17.129119 | TASK [gather-pod-logs : creating directory for pod logs]
2026-07-24 16:30:17.351840 | controller | changed
2026-07-24 16:30:17.371918 | 
2026-07-24 16:30:17.372098 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-07-24 16:30:17.596787 | controller | changed
2026-07-24 16:30:17.603430 | 
2026-07-24 16:30:17.603561 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-07-24 16:30:17.850762 | controller | environment: line 3: kubectl: command not found
2026-07-24 16:30:18.135760 | controller | ok: Runtime: 0:00:00.010379
2026-07-24 16:30:18.141760 | 
2026-07-24 16:30:18.141907 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-07-24 16:30:18.609290 | controller | changed:
2026-07-24 16:30:18.609464 | controller | cd+++++++++ pod-logs/
2026-07-24 16:30:18.609506 | controller | cd+++++++++ pod-logs/failed-pods/
2026-07-24 16:30:18.619542 | 
2026-07-24 16:30:18.619634 | LOOP [helm-release-status : creating directory for helm release status]
2026-07-24 16:30:18.857927 | controller | changed: "values"
2026-07-24 16:30:19.061223 | controller | changed: "releases"
2026-07-24 16:30:19.079241 | 
2026-07-24 16:30:19.079333 | TASK [helm-release-status : Gather get release status for helm charts]
2026-07-24 16:30:19.319221 | controller | /bin/bash: line 3: kubectl: command not found
2026-07-24 16:30:19.608907 | controller | ok: Runtime: 0:00:00.008333
2026-07-24 16:30:19.614171 | 
2026-07-24 16:30:19.614256 | TASK [helm-release-status : Downloads logs to executor]
2026-07-24 16:30:20.113994 | controller | changed:
2026-07-24 16:30:20.114266 | controller | cd+++++++++ helm/
2026-07-24 16:30:20.114333 | controller | cd+++++++++ helm/releases/
2026-07-24 16:30:20.114389 | controller | cd+++++++++ helm/values/
2026-07-24 16:30:20.120704 | 
2026-07-24 16:30:20.120764 | PLAY RECAP
2026-07-24 16:30:20.120813 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-24 16:30:20.120838 | 
2026-07-24 16:30:20.242242 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-07-24 16:30:20.245059 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-24 16:30:20.872474 | 
2026-07-24 16:30:20.872610 | PLAY [all]
2026-07-24 16:30:20.883375 | 
2026-07-24 16:30:20.883486 | TASK [fetch-output : Set log path for multiple nodes]
2026-07-24 16:30:20.930394 | controller | skipping: Conditional result was False
2026-07-24 16:30:20.938412 | 
2026-07-24 16:30:20.938545 | TASK [fetch-output : Set log path for single node]
2026-07-24 16:30:20.970723 | controller | ok
2026-07-24 16:30:20.976899 | 
2026-07-24 16:30:20.977066 | LOOP [fetch-output : Ensure local output dirs]
2026-07-24 16:30:21.366687 | controller -> localhost | ok: "/var/lib/zuul/builds/97c6a3f6fccc45a5a237d2f53a608e38/work/logs"
2026-07-24 16:30:21.594513 | controller -> localhost | changed: "/var/lib/zuul/builds/97c6a3f6fccc45a5a237d2f53a608e38/work/artifacts"
2026-07-24 16:30:21.822874 | controller -> localhost | changed: "/var/lib/zuul/builds/97c6a3f6fccc45a5a237d2f53a608e38/work/docs"
2026-07-24 16:30:21.838760 | 
2026-07-24 16:30:21.838909 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-07-24 16:30:22.474162 | controller | changed: .d..t...... ./
2026-07-24 16:30:22.474498 | controller | changed: All items complete
2026-07-24 16:30:22.474556 | 
2026-07-24 16:30:22.905959 | controller | changed: .d..t...... ./
2026-07-24 16:30:23.380738 | controller | changed: .d..t...... ./
2026-07-24 16:30:23.393420 | 
2026-07-24 16:30:23.393580 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-07-24 16:30:23.825588 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007978
2026-07-24 16:30:24.063772 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008099
2026-07-24 16:30:24.083781 | 
2026-07-24 16:30:24.084004 | PLAY [all]
2026-07-24 16:30:24.090945 | 
2026-07-24 16:30:24.091062 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-07-24 16:30:24.549032 | controller | changed
2026-07-24 16:30:24.555744 | 
2026-07-24 16:30:24.555814 | PLAY RECAP
2026-07-24 16:30:24.555875 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-24 16:30:24.555906 | 
2026-07-24 16:30:24.677938 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-24 16:30:24.680315 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-07-24 16:30:25.275752 | 
2026-07-24 16:30:25.275901 | PLAY [localhost]
2026-07-24 16:30:25.285979 | 
2026-07-24 16:30:25.286096 | TASK [Generate Zuul manifest]
2026-07-24 16:30:25.306845 | localhost | ok
2026-07-24 16:30:25.324050 | 
2026-07-24 16:30:25.324199 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-07-24 16:30:25.698327 | localhost | changed
2026-07-24 16:30:25.709656 | 
2026-07-24 16:30:25.709745 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-07-24 16:30:25.742340 | localhost | ok
2026-07-24 16:30:25.750704 | 
2026-07-24 16:30:25.750779 | TASK [Upload logs]
2026-07-24 16:30:25.772933 | localhost | ok
2026-07-24 16:30:25.835712 | 
2026-07-24 16:30:25.835857 | TASK [Set zuul-log-path fact]
2026-07-24 16:30:25.854857 | localhost | ok
2026-07-24 16:30:25.867311 | 
2026-07-24 16:30:25.867384 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-24 16:30:25.897681 | localhost | ok
2026-07-24 16:30:25.906509 | 
2026-07-24 16:30:25.906578 | TASK [upload-logs : Create log directories]
2026-07-24 16:30:26.340005 | localhost | changed
2026-07-24 16:30:26.345361 | 
2026-07-24 16:30:26.345463 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-07-24 16:30:26.758503 | localhost -> localhost | ok: Runtime: 0:00:00.006427
2026-07-24 16:30:26.763858 | 
2026-07-24 16:30:26.763956 | TASK [upload-logs : Upload logs to log server]
2026-07-24 16:30:27.276299 | localhost | Output suppressed because no_log was given
2026-07-24 16:30:27.281463 | 
2026-07-24 16:30:27.281583 | LOOP [upload-logs : Compress console log and json output]
2026-07-24 16:30:27.337505 | localhost | skipping: Conditional result was False
2026-07-24 16:30:27.399864 | localhost | skipping: Conditional result was False
2026-07-24 16:30:27.411009 | 
2026-07-24 16:30:27.411088 | LOOP [upload-logs : Upload compressed console log and json output]
2026-07-24 16:30:27.457624 | localhost | skipping: Conditional result was False
2026-07-24 16:30:27.458004 | 
2026-07-24 16:30:27.463699 | localhost | skipping: Conditional result was False
2026-07-24 16:30:27.471743 | 
2026-07-24 16:30:27.471861 | LOOP [upload-logs : Upload console log and json output]