2026-08-22 08:55:08.374408 | Job console starting
2026-08-22 08:55:08.384169 | Updating git repos
2026-08-22 08:55:08.428717 | Cloning repos into workspace
2026-08-22 08:55:12.848547 | Restoring repo states
2026-08-22 08:55:12.869650 | Merging changes
2026-08-22 08:55:13.428497 | Checking out repos
2026-08-22 08:55:13.701381 | Preparing playbooks
2026-08-22 08:55:16.904639 | Running Ansible setup
2026-08-22 08:55:20.619463 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-22 08:55:21.313159 | 
2026-08-22 08:55:21.313318 | PLAY [localhost]
2026-08-22 08:55:21.321452 | 
2026-08-22 08:55:21.321566 | TASK [Gathering Facts]
2026-08-22 08:55:22.225949 | localhost | ok
2026-08-22 08:55:22.240291 | 
2026-08-22 08:55:22.240489 | TASK [Setup log path fact]
2026-08-22 08:55:22.260247 | localhost | ok
2026-08-22 08:55:22.274676 | 
2026-08-22 08:55:22.274802 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-22 08:55:22.305127 | localhost | ok
2026-08-22 08:55:22.314561 | 
2026-08-22 08:55:22.314631 | TASK [emit-job-header : Print job information]
2026-08-22 08:55:22.351397 | # Job Information
2026-08-22 08:55:22.351534 | Ansible Version: 2.16.18
2026-08-22 08:55:22.351615 | Job: ansible-collection-kubernetes-molecule-helm
2026-08-22 08:55:22.351644 | Pipeline: check
2026-08-22 08:55:22.351664 | Executor: 2d72f0692154
2026-08-22 08:55:22.351684 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/295
2026-08-22 08:55:22.351706 | Event ID: 77ded280-9e06-11f1-995b-e0e28f484098
2026-08-22 08:55:22.354532 | 
2026-08-22 08:55:22.354596 | LOOP [emit-job-header : Print node information]
2026-08-22 08:55:22.456804 | localhost | ok:
2026-08-22 08:55:22.457051 | localhost | # Node Information
2026-08-22 08:55:22.457099 | localhost | Inventory Hostname: controller
2026-08-22 08:55:22.457133 | localhost | Hostname: np0000190475
2026-08-22 08:55:22.457164 | localhost | Username: zuul
2026-08-22 08:55:22.457196 | localhost | Distro: Debian 13.6
2026-08-22 08:55:22.457228 | localhost | Provider: yul1
2026-08-22 08:55:22.457275 | localhost | Region: ca-ymq-1
2026-08-22 08:55:22.457306 | localhost | Label: debian-trixie
2026-08-22 08:55:22.457336 | localhost | Product Name: OpenStack Nova
2026-08-22 08:55:22.457365 | localhost | Interface IP: 199.19.213.111
2026-08-22 08:55:22.473987 | 
2026-08-22 08:55:22.474165 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-22 08:55:22.893876 | localhost -> localhost | changed
2026-08-22 08:55:22.899171 | 
2026-08-22 08:55:22.899268 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-22 08:55:23.838598 | localhost -> localhost | changed
2026-08-22 08:55:23.850485 | 
2026-08-22 08:55:23.850565 | PLAY [all]
2026-08-22 08:55:23.859358 | 
2026-08-22 08:55:23.859455 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-22 08:55:24.139284 | controller -> localhost | ok
2026-08-22 08:55:24.146144 | 
2026-08-22 08:55:24.146303 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-22 08:55:24.177810 | controller | ok
2026-08-22 08:55:24.192886 | controller | included: /var/lib/zuul/builds/6f1228fe6e4444138a2bd37dd9d506e6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-22 08:55:24.197870 | 
2026-08-22 08:55:24.197933 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-22 08:55:25.010990 | controller -> localhost | Generating public/private rsa key pair.
2026-08-22 08:55:25.011258 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/6f1228fe6e4444138a2bd37dd9d506e6/work/6f1228fe6e4444138a2bd37dd9d506e6_id_rsa
2026-08-22 08:55:25.011363 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/6f1228fe6e4444138a2bd37dd9d506e6/work/6f1228fe6e4444138a2bd37dd9d506e6_id_rsa.pub
2026-08-22 08:55:25.011434 | controller -> localhost | The key fingerprint is:
2026-08-22 08:55:25.011483 | controller -> localhost | SHA256:qXVvHmFLjfwSQMC7d5HYvSBU7dNHvt+MQ3oDkTMwV6c zuul-build-sshkey
2026-08-22 08:55:25.011539 | controller -> localhost | The key's randomart image is:
2026-08-22 08:55:25.011573 | controller -> localhost | +---[RSA 3072]----+
2026-08-22 08:55:25.011613 | controller -> localhost | |       ......o. .|
2026-08-22 08:55:25.011647 | controller -> localhost | |        ..+ . .o.|
2026-08-22 08:55:25.011701 | controller -> localhost | |         o.* =E+ |
2026-08-22 08:55:25.011770 | controller -> localhost | |        ..ooXo+ +|
2026-08-22 08:55:25.011820 | controller -> localhost | |        S...B*.oo|
2026-08-22 08:55:25.011865 | controller -> localhost | |       o...+o=o. |
2026-08-22 08:55:25.011909 | controller -> localhost | |      .  . .*+.oo|
2026-08-22 08:55:25.011953 | controller -> localhost | |           o.o= +|
2026-08-22 08:55:25.012003 | controller -> localhost | |            .. o |
2026-08-22 08:55:25.012046 | controller -> localhost | +----[SHA256]-----+
2026-08-22 08:55:25.012140 | controller -> localhost | ok: Runtime: 0:00:00.406540
2026-08-22 08:55:25.019048 | 
2026-08-22 08:55:25.019157 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-22 08:55:25.048949 | controller | ok
2026-08-22 08:55:25.062100 | controller | included: /var/lib/zuul/builds/6f1228fe6e4444138a2bd37dd9d506e6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-22 08:55:25.069915 | 
2026-08-22 08:55:25.070012 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-22 08:55:25.095177 | controller | skipping: Conditional result was False
2026-08-22 08:55:25.101878 | 
2026-08-22 08:55:25.101973 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-22 08:55:25.648934 | controller | changed
2026-08-22 08:55:25.654838 | 
2026-08-22 08:55:25.654932 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-22 08:55:25.859870 | controller | ok
2026-08-22 08:55:25.868130 | 
2026-08-22 08:55:25.868272 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-22 08:55:26.423248 | controller | changed
2026-08-22 08:55:26.428737 | 
2026-08-22 08:55:26.428836 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-22 08:55:26.967132 | controller | changed
2026-08-22 08:55:26.973257 | 
2026-08-22 08:55:26.973356 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-22 08:55:26.999329 | controller | skipping: Conditional result was False
2026-08-22 08:55:27.006500 | 
2026-08-22 08:55:27.006619 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-22 08:55:27.427490 | controller -> localhost | changed
2026-08-22 08:55:27.446988 | 
2026-08-22 08:55:27.447156 | TASK [add-build-sshkey : Add back temp key]
2026-08-22 08:55:28.141858 | controller -> localhost | Identity added: /var/lib/zuul/builds/6f1228fe6e4444138a2bd37dd9d506e6/work/6f1228fe6e4444138a2bd37dd9d506e6_id_rsa (zuul-build-sshkey)
2026-08-22 08:55:28.142176 | controller -> localhost | ok: Runtime: 0:00:00.013120
2026-08-22 08:55:28.149322 | 
2026-08-22 08:55:28.149531 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-22 08:55:28.567543 | controller | ok
2026-08-22 08:55:28.572476 | 
2026-08-22 08:55:28.572612 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-22 08:55:28.598832 | controller | skipping: Conditional result was False
2026-08-22 08:55:28.612779 | 
2026-08-22 08:55:28.612948 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-22 08:55:29.026034 | controller | ok
2026-08-22 08:55:29.042130 | 
2026-08-22 08:55:29.042315 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-22 08:55:30.004264 | controller | Output suppressed because no_log was given
2026-08-22 08:55:30.013117 | 
2026-08-22 08:55:30.013185 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-22 08:55:30.234335 | controller | ok: "logs"
2026-08-22 08:55:30.234585 | controller | ok: All items complete
2026-08-22 08:55:30.234616 | 
2026-08-22 08:55:30.415467 | controller | ok: "artifacts"
2026-08-22 08:55:30.600932 | controller | ok: "docs"
2026-08-22 08:55:30.618473 | 
2026-08-22 08:55:30.618587 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-22 08:55:30.835838 | controller | changed: "logs"
2026-08-22 08:55:31.023832 | controller | changed: "artifacts"
2026-08-22 08:55:31.204324 | controller | changed: "docs"
2026-08-22 08:55:31.214862 | 
2026-08-22 08:55:31.214986 | PLAY RECAP
2026-08-22 08:55:31.215049 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-22 08:55:31.215093 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 08:55:31.215126 | 
2026-08-22 08:55:31.366792 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-22 08:55:31.368840 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-22 08:55:32.016146 | 
2026-08-22 08:55:32.016315 | PLAY [all]
2026-08-22 08:55:32.028343 | 
2026-08-22 08:55:32.028480 | TASK [setup-uv : Extract archive]
2026-08-22 08:55:34.377382 | controller | changed
2026-08-22 08:55:34.384045 | 
2026-08-22 08:55:34.384158 | TASK [setup-uv : Print version]
2026-08-22 08:55:34.767056 | controller | uv 0.8.13
2026-08-22 08:55:34.923350 | controller | ok: Runtime: 0:00:00.015399
2026-08-22 08:55:34.932247 | 
2026-08-22 08:55:34.932392 | TASK [Install Ansible collection siblings]
2026-08-22 08:55:35.344051 | controller | ok:
2026-08-22 08:55:35.344260 | controller | Skipping ansible.posix: no checked-out sibling
2026-08-22 08:55:35.344304 | controller | Skipping community.crypto: no checked-out sibling
2026-08-22 08:55:35.344338 | controller | Skipping community.general: no checked-out sibling
2026-08-22 08:55:35.344369 | controller | Skipping kubernetes.core: no checked-out sibling
2026-08-22 08:55:35.344400 | controller | Skipping vexxhost.containers: no checked-out sibling
2026-08-22 08:55:35.347033 | 
2026-08-22 08:55:35.347113 | PLAY RECAP
2026-08-22 08:55:35.347187 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 08:55:35.347220 | 
2026-08-22 08:55:35.482242 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-22 08:55:35.485057 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-08-22 08:55:36.194218 | 
2026-08-22 08:55:36.194379 | PLAY [all]
2026-08-22 08:55:36.206456 | 
2026-08-22 08:55:36.206645 | TASK [Update apt cache]
2026-08-22 08:55:40.545070 | controller | ok
2026-08-22 08:55:40.552547 | 
2026-08-22 08:55:40.552742 | TASK [Disable firewalld]
2026-08-22 08:55:40.578136 | controller | skipping: Conditional result was False
2026-08-22 08:55:40.586317 | 
2026-08-22 08:55:40.586480 | TASK [Install "jq" for log collection]
2026-08-22 08:55:42.633252 | controller | changed
2026-08-22 08:55:42.635919 | 
2026-08-22 08:55:42.635995 | PLAY RECAP
2026-08-22 08:55:42.636062 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-22 08:55:42.636096 | 
2026-08-22 08:55:42.766160 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-08-22 08:55:42.769048 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-22 08:55:43.361202 | 
2026-08-22 08:55:43.361356 | PLAY [all]
2026-08-22 08:55:43.371114 | 
2026-08-22 08:55:43.371186 | LOOP [Check inventory directories]
2026-08-22 08:55:43.743349 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-08-22 08:55:43.911738 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/helm/inventory"
2026-08-22 08:55:44.080759 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/inventory"
2026-08-22 08:55:44.088095 | 
2026-08-22 08:55:44.088236 | LOOP [Copy inventory file for Zuul]
2026-08-22 08:55:44.985829 | controller | changed:
2026-08-22 08:55:44.986081 | controller | {
2026-08-22 08:55:44.986124 | controller |   "ansible_loop_var": "item",
2026-08-22 08:55:44.986157 | controller |   "changed": false,
2026-08-22 08:55:44.986185 | controller |   "failed": false,
2026-08-22 08:55:44.986216 | controller |   "invocation": {
2026-08-22 08:55:44.986243 | controller |     "module_args": {
2026-08-22 08:55:44.986269 | controller |       "checksum_algorithm": "sha1",
2026-08-22 08:55:44.986295 | controller |       "follow": true,
2026-08-22 08:55:44.986321 | controller |       "get_attributes": true,
2026-08-22 08:55:44.986347 | controller |       "get_checksum": true,
2026-08-22 08:55:44.986373 | controller |       "get_mime": true,
2026-08-22 08:55:44.986398 | controller |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-08-22 08:55:44.986424 | controller |     }
2026-08-22 08:55:44.986449 | controller |   },
2026-08-22 08:55:44.986473 | controller |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-08-22 08:55:44.986497 | controller |   "stat": {
2026-08-22 08:55:44.986521 | controller |     "atime": 1787388929.881976,
2026-08-22 08:55:44.986546 | controller |     "attr_flags": "e",
2026-08-22 08:55:44.986570 | controller |     "attributes": [
2026-08-22 08:55:44.986594 | controller |       "extents"
2026-08-22 08:55:44.986619 | controller |     ],
2026-08-22 08:55:44.986643 | controller |     "block_size": 4096,
2026-08-22 08:55:44.986666 | controller |     "blocks": 8,
2026-08-22 08:55:44.986691 | controller |     "charset": "unknown",
2026-08-22 08:55:44.986718 | controller |     "ctime": 1787388929.9059615,
2026-08-22 08:55:44.986746 | controller |     "dev": 65025,
2026-08-22 08:55:44.986771 | controller |     "device_type": 0,
2026-08-22 08:55:44.986794 | controller |     "executable": true,
2026-08-22 08:55:44.986818 | controller |     "exists": true,
2026-08-22 08:55:44.986842 | controller |     "gid": 1000,
2026-08-22 08:55:44.986866 | controller |     "gr_name": "zuul",
2026-08-22 08:55:44.986890 | controller |     "inode": 520215,
2026-08-22 08:55:44.986914 | controller |     "isblk": false,
2026-08-22 08:55:44.986939 | controller |     "ischr": false,
2026-08-22 08:55:44.986962 | controller |     "isdir": true,
2026-08-22 08:55:44.986987 | controller |     "isfifo": false,
2026-08-22 08:55:44.987010 | controller |     "isgid": false,
2026-08-22 08:55:44.987033 | controller |     "islnk": false,
2026-08-22 08:55:44.987057 | controller |     "isreg": false,
2026-08-22 08:55:44.987080 | controller |     "issock": false,
2026-08-22 08:55:44.987103 | controller |     "isuid": false,
2026-08-22 08:55:44.987126 | controller |     "mimetype": "unknown",
2026-08-22 08:55:44.987150 | controller |     "mode": "0755",
2026-08-22 08:55:44.987173 | controller |     "mtime": 1787388913.3360996,
2026-08-22 08:55:44.987197 | controller |     "nlink": 14,
2026-08-22 08:55:44.987220 | controller |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-08-22 08:55:44.987244 | controller |     "pw_name": "zuul",
2026-08-22 08:55:44.987270 | controller |     "readable": true,
2026-08-22 08:55:44.987295 | controller |     "rgrp": true,
2026-08-22 08:55:44.987320 | controller |     "roth": true,
2026-08-22 08:55:44.987344 | controller |     "rusr": true,
2026-08-22 08:55:44.987369 | controller |     "size": 4096,
2026-08-22 08:55:44.987393 | controller |     "uid": 1000,
2026-08-22 08:55:44.987417 | controller |     "version": "1895776412",
2026-08-22 08:55:44.987441 | controller |     "wgrp": false,
2026-08-22 08:55:44.987467 | controller |     "woth": false,
2026-08-22 08:55:44.987492 | controller |     "writeable": true,
2026-08-22 08:55:44.987517 | controller |     "wusr": true,
2026-08-22 08:55:44.987541 | controller |     "xgrp": true,
2026-08-22 08:55:44.987565 | controller |     "xoth": true,
2026-08-22 08:55:44.987589 | controller |     "xusr": true
2026-08-22 08:55:44.987613 | controller |   }
2026-08-22 08:55:44.987652 | controller | }
2026-08-22 08:55:44.987780 | controller | ok: All items complete
2026-08-22 08:55:44.987818 | 
2026-08-22 08:55:44.989593 | controller | skipping: Conditional result was False
2026-08-22 08:55:44.990551 | controller | skipping: Conditional result was False
2026-08-22 08:55:45.006396 | 
2026-08-22 08:55:45.006499 | LOOP [Switch "ansible_host" to private IP]
2026-08-22 08:55:45.414387 | controller | changed: 1 replacements made
2026-08-22 08:55:45.416354 | controller | skipping: Conditional result was False
2026-08-22 08:55:45.417301 | controller | skipping: Conditional result was False
2026-08-22 08:55:45.426971 | 
2026-08-22 08:55:45.427074 | TASK [Run Molecule scenario]
2026-08-22 08:55:45.875575 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-08-22 08:55:45.875696 | controller | Creating virtual environment at: .venv
2026-08-22 08:55:46.146882 | controller | Downloading pygments (1.2MiB)
2026-08-22 08:55:46.147353 | controller | Downloading ansible-core (2.3MiB)
2026-08-22 08:55:46.163305 | controller | Downloading cryptography (4.3MiB)
2026-08-22 08:55:46.319266 | controller |  Downloading cryptography
2026-08-22 08:55:46.371948 | controller |  Downloading pygments
2026-08-22 08:55:46.539356 | controller |  Downloading ansible-core
2026-08-22 08:55:46.586572 | controller | Installed 36 packages in 46ms
2026-08-22 08:55:47.290004 | controller | INFO     Collection 'vexxhost.kubernetes' detected.
2026-08-22 08:55:47.290081 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-08-22 08:55:48.156722 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-08-22 08:55:48.156798 | controller | INFO     default scenario not found, disabling shared state.
2026-08-22 08:55:48.156823 | controller | INFO     [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-08-22 08:55:48.156901 | controller | INFO     [helm > prerun] Performing prerun with role_name_check=0...
2026-08-22 08:56:05.764348 | controller | INFO     [helm > dependency] Executing
2026-08-22 08:56:05.764414 | controller | WARNING  [helm > dependency] Missing roles requirements file: requirements.yml
2026-08-22 08:56:05.764424 | controller | WARNING  [helm > dependency] Missing collections requirements file: collections.yml
2026-08-22 08:56:05.764457 | controller | WARNING  [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-08-22 08:56:05.768386 | controller | INFO     [helm > cleanup] Executing
2026-08-22 08:56:05.768710 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-22 08:56:05.774035 | controller | INFO     [helm > destroy] Executing
2026-08-22 08:56:05.774131 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-08-22 08:56:05.774171 | controller | INFO     [helm > destroy] Executed: Successful
2026-08-22 08:56:05.779576 | controller | INFO     [helm > syntax] Executing
2026-08-22 08:56:06.651840 | controller |
2026-08-22 08:56:06.652070 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml
2026-08-22 08:56:06.712016 | controller | INFO     [helm > syntax] Executed: Successful
2026-08-22 08:56:06.722436 | controller | INFO     [helm > create] Executing
2026-08-22 08:56:06.723959 | controller | WARNING  [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-22 08:56:06.729264 | controller | INFO     [helm > prepare] Executing
2026-08-22 08:56:07.551040 | controller |
2026-08-22 08:56:07.551222 | controller | PLAY [Prepare] *****************************************************************
2026-08-22 08:56:07.551411 | controller |
2026-08-22 08:56:07.551674 | controller | TASK [Gathering Facts] *********************************************************
2026-08-22 08:56:07.551881 | controller | Saturday 22 August 2026  08:56:07 +0000 (0:00:00.029)       0:00:00.029 *******
2026-08-22 08:56:08.789279 | 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-08-22 08:56:08.793734 | controller | ok: [controller]
2026-08-22 08:56:08.793898 | controller |
2026-08-22 08:56:08.794255 | controller | TASK [Install Debian packages] *************************************************
2026-08-22 08:56:08.794474 | controller | Saturday 22 August 2026  08:56:08 +0000 (0:00:01.243)       0:00:01.273 *******
2026-08-22 08:56:10.248294 | controller | ok: [controller]
2026-08-22 08:56:10.248357 | controller |
2026-08-22 08:56:10.248481 | controller | PLAY RECAP *********************************************************************
2026-08-22 08:56:10.248716 | controller | controller                 : ok=2    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-08-22 08:56:10.248888 | controller |
2026-08-22 08:56:10.249069 | controller |
2026-08-22 08:56:10.249260 | controller | TASKS RECAP ********************************************************************
2026-08-22 08:56:10.249587 | controller | Saturday 22 August 2026  08:56:10 +0000 (0:00:01.453)       0:00:02.726 *******
2026-08-22 08:56:10.249829 | controller | ===============================================================================
2026-08-22 08:56:10.249992 | controller | Install Debian packages ------------------------------------------------- 1.45s
2026-08-22 08:56:10.250154 | controller | Gathering Facts --------------------------------------------------------- 1.24s
2026-08-22 08:56:10.337246 | controller | INFO     [helm > prepare] Executed: Successful
2026-08-22 08:56:10.346442 | controller | INFO     [helm > converge] Executing
2026-08-22 08:56:11.189574 | controller |
2026-08-22 08:56:11.189726 | controller | PLAY [Converge] ****************************************************************
2026-08-22 08:56:11.189935 | controller |
2026-08-22 08:56:11.190155 | controller | TASK [Gathering Facts] *********************************************************
2026-08-22 08:56:11.190363 | controller | Saturday 22 August 2026  08:56:11 +0000 (0:00:00.013)       0:00:00.013 *******
2026-08-22 08:56:12.275319 | 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-08-22 08:56:12.280072 | controller | ok: [controller]
2026-08-22 08:56:12.280304 | controller |
2026-08-22 08:56:12.280587 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-22 08:56:12.280811 | controller | Saturday 22 August 2026  08:56:12 +0000 (0:00:01.090)       0:00:01.103 *******
2026-08-22 08:56:12.780422 | controller | ok: [controller]
2026-08-22 08:56:12.780567 | controller |
2026-08-22 08:56:12.780781 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-22 08:56:12.781024 | controller | Saturday 22 August 2026  08:56:12 +0000 (0:00:00.499)       0:00:01.603 *******
2026-08-22 08:56:13.866465 | controller | ok: [controller]
2026-08-22 08:56:13.866487 | controller |
2026-08-22 08:56:13.866491 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-08-22 08:56:13.866496 | controller | Saturday 22 August 2026  08:56:13 +0000 (0:00:01.083)       0:00:02.687 *******
2026-08-22 08:56:14.291279 | controller | changed: [controller]
2026-08-22 08:56:14.291444 | controller |
2026-08-22 08:56:14.291734 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 08:56:14.291963 | controller | Saturday 22 August 2026  08:56:14 +0000 (0:00:00.426)       0:00:03.114 *******
2026-08-22 08:56:14.334072 | controller | ok: [controller] => {
2026-08-22 08:56:14.334256 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-08-22 08:56:14.334446 | controller | }
2026-08-22 08:56:14.334623 | controller |
2026-08-22 08:56:14.334824 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 08:56:14.335025 | controller | Saturday 22 August 2026  08:56:14 +0000 (0:00:00.042)       0:00:03.156 *******
2026-08-22 08:56:15.249935 | 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-08-22 08:56:15.258018 | controller | changed: [controller]
2026-08-22 08:56:15.258185 | controller |
2026-08-22 08:56:15.258388 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 08:56:15.258583 | controller | Saturday 22 August 2026  08:56:15 +0000 (0:00:00.924)       0:00:04.081 *******
2026-08-22 08:56:17.235594 | controller | changed: [controller]
2026-08-22 08:56:17.235768 | controller |
2026-08-22 08:56:17.235978 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-08-22 08:56:17.236174 | controller | Saturday 22 August 2026  08:56:17 +0000 (0:00:01.977)       0:00:06.058 *******
2026-08-22 08:56:17.728312 | controller | ok: [controller]
2026-08-22 08:56:17.728448 | controller |
2026-08-22 08:56:17.728702 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-08-22 08:56:17.728862 | controller | Saturday 22 August 2026  08:56:17 +0000 (0:00:00.490)       0:00:06.548 *******
2026-08-22 08:56:18.114238 | controller | changed: [controller]
2026-08-22 08:56:18.114390 | controller |
2026-08-22 08:56:18.114607 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-08-22 08:56:18.114839 | controller | Saturday 22 August 2026  08:56:18 +0000 (0:00:00.388)       0:00:06.937 *******
2026-08-22 08:56:18.572029 | controller | ok: [controller]
2026-08-22 08:56:18.572191 | controller |
2026-08-22 08:56:18.572412 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-08-22 08:56:18.572608 | controller | Saturday 22 August 2026  08:56:18 +0000 (0:00:00.457)       0:00:07.395 *******
2026-08-22 08:56:18.878237 | controller | changed: [controller]
2026-08-22 08:56:18.878321 | controller |
2026-08-22 08:56:18.878452 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-08-22 08:56:18.878568 | controller | Saturday 22 August 2026  08:56:18 +0000 (0:00:00.306)       0:00:07.701 *******
2026-08-22 08:56:19.196248 | controller | ok: [controller]
2026-08-22 08:56:19.196443 | controller |
2026-08-22 08:56:19.196667 | controller | TASK [Install plugin] **********************************************************
2026-08-22 08:56:19.196872 | controller | Saturday 22 August 2026  08:56:19 +0000 (0:00:00.317)       0:00:08.019 *******
2026-08-22 08:56:19.250986 | controller | included: vexxhost.containers.download_artifact for controller
2026-08-22 08:56:19.251183 | controller |
2026-08-22 08:56:19.251463 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 08:56:19.251686 | controller | Saturday 22 August 2026  08:56:19 +0000 (0:00:00.055)       0:00:08.074 *******
2026-08-22 08:56:19.298078 | controller | ok: [controller] => {
2026-08-22 08:56:19.298311 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-08-22 08:56:19.298520 | controller | }
2026-08-22 08:56:19.298706 | controller |
2026-08-22 08:56:19.298919 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 08:56:19.299131 | controller | Saturday 22 August 2026  08:56:19 +0000 (0:00:00.046)       0:00:08.121 *******
2026-08-22 08:56:20.081219 | controller | changed: [controller]
2026-08-22 08:56:20.081415 | controller |
2026-08-22 08:56:20.081717 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 08:56:20.081966 | controller | Saturday 22 August 2026  08:56:20 +0000 (0:00:00.782)       0:00:08.903 *******
2026-08-22 08:56:22.210786 | controller | changed: [controller]
2026-08-22 08:56:22.210942 | controller |
2026-08-22 08:56:22.211135 | controller | PLAY RECAP *********************************************************************
2026-08-22 08:56:22.211351 | controller | controller                 : ok=16   changed=7    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-08-22 08:56:22.211569 | controller |
2026-08-22 08:56:22.211776 | controller |
2026-08-22 08:56:22.211925 | controller | TASKS RECAP ********************************************************************
2026-08-22 08:56:22.212120 | controller | Saturday 22 August 2026  08:56:22 +0000 (0:00:02.130)       0:00:11.034 *******
2026-08-22 08:56:22.212295 | controller | ===============================================================================
2026-08-22 08:56:22.212500 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.13s
2026-08-22 08:56:22.212676 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.98s
2026-08-22 08:56:22.212866 | controller | Gathering Facts --------------------------------------------------------- 1.09s
2026-08-22 08:56:22.213075 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.08s
2026-08-22 08:56:22.213247 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.92s
2026-08-22 08:56:22.213455 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.78s
2026-08-22 08:56:22.213637 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.50s
2026-08-22 08:56:22.213816 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.49s
2026-08-22 08:56:22.214006 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.46s
2026-08-22 08:56:22.214201 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.43s
2026-08-22 08:56:22.214387 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.39s
2026-08-22 08:56:22.214675 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.32s
2026-08-22 08:56:22.214867 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.31s
2026-08-22 08:56:22.215078 | controller | Install plugin ---------------------------------------------------------- 0.06s
2026-08-22 08:56:22.215185 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-08-22 08:56:22.215302 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s
2026-08-22 08:56:22.314183 | controller | INFO     [helm > converge] Executed: Successful
2026-08-22 08:56:22.324916 | controller | INFO     [helm > idempotence] Executing
2026-08-22 08:56:23.196548 | controller |
2026-08-22 08:56:23.196611 | controller | PLAY [Converge] ****************************************************************
2026-08-22 08:56:23.196621 | controller |
2026-08-22 08:56:23.196630 | controller | TASK [Gathering Facts] *********************************************************
2026-08-22 08:56:23.196638 | controller | Saturday 22 August 2026  08:56:23 +0000 (0:00:00.012)       0:00:00.012 *******
2026-08-22 08:56:24.218071 | 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-08-22 08:56:24.223361 | controller | ok: [controller]
2026-08-22 08:56:24.223383 | controller |
2026-08-22 08:56:24.223392 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-22 08:56:24.223401 | controller | Saturday 22 August 2026  08:56:24 +0000 (0:00:01.027)       0:00:01.040 *******
2026-08-22 08:56:24.680549 | controller | ok: [controller]
2026-08-22 08:56:24.680883 | controller |
2026-08-22 08:56:24.681156 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-22 08:56:24.681415 | controller | Saturday 22 August 2026  08:56:24 +0000 (0:00:00.457)       0:00:01.497 *******
2026-08-22 08:56:25.624809 | controller | ok: [controller]
2026-08-22 08:56:25.625120 | controller |
2026-08-22 08:56:25.625874 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-08-22 08:56:25.626159 | controller | Saturday 22 August 2026  08:56:25 +0000 (0:00:00.944)       0:00:02.441 *******
2026-08-22 08:56:26.052269 | controller | ok: [controller]
2026-08-22 08:56:26.052610 | controller |
2026-08-22 08:56:26.052889 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 08:56:26.053146 | controller | Saturday 22 August 2026  08:56:26 +0000 (0:00:00.427)       0:00:02.869 *******
2026-08-22 08:56:26.095676 | controller | ok: [controller] => {
2026-08-22 08:56:26.096022 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-08-22 08:56:26.096282 | controller | }
2026-08-22 08:56:26.096522 | controller |
2026-08-22 08:56:26.096785 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 08:56:26.097046 | controller | Saturday 22 August 2026  08:56:26 +0000 (0:00:00.043)       0:00:02.912 *******
2026-08-22 08:56:26.615280 | controller | ok: [controller]
2026-08-22 08:56:26.615582 | controller |
2026-08-22 08:56:26.615784 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 08:56:26.615981 | controller | Saturday 22 August 2026  08:56:26 +0000 (0:00:00.519)       0:00:03.432 *******
2026-08-22 08:56:28.287145 | controller | ok: [controller]
2026-08-22 08:56:28.287538 | controller |
2026-08-22 08:56:28.287868 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-08-22 08:56:28.288128 | controller | Saturday 22 August 2026  08:56:28 +0000 (0:00:01.672)       0:00:05.104 *******
2026-08-22 08:56:28.771979 | controller | ok: [controller]
2026-08-22 08:56:28.772063 | controller |
2026-08-22 08:56:28.772143 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-08-22 08:56:28.772315 | controller | Saturday 22 August 2026  08:56:28 +0000 (0:00:00.482)       0:00:05.587 *******
2026-08-22 08:56:29.187573 | controller | ok: [controller]
2026-08-22 08:56:29.187613 | controller |
2026-08-22 08:56:29.187623 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-08-22 08:56:29.187631 | controller | Saturday 22 August 2026  08:56:29 +0000 (0:00:00.417)       0:00:06.004 *******
2026-08-22 08:56:29.635527 | controller | ok: [controller]
2026-08-22 08:56:29.635573 | controller |
2026-08-22 08:56:29.635663 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-08-22 08:56:29.635753 | controller | Saturday 22 August 2026  08:56:29 +0000 (0:00:00.448)       0:00:06.453 *******
2026-08-22 08:56:29.921578 | controller | ok: [controller]
2026-08-22 08:56:29.921626 | controller |
2026-08-22 08:56:29.921722 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-08-22 08:56:29.921812 | controller | Saturday 22 August 2026  08:56:29 +0000 (0:00:00.284)       0:00:06.737 *******
2026-08-22 08:56:30.250378 | controller | ok: [controller]
2026-08-22 08:56:30.250416 | controller |
2026-08-22 08:56:30.250558 | controller | TASK [Install plugin] **********************************************************
2026-08-22 08:56:30.250969 | controller | Saturday 22 August 2026  08:56:30 +0000 (0:00:00.330)       0:00:07.067 *******
2026-08-22 08:56:30.316572 | controller | included: vexxhost.containers.download_artifact for controller
2026-08-22 08:56:30.316603 | controller |
2026-08-22 08:56:30.316690 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 08:56:30.316802 | controller | Saturday 22 August 2026  08:56:30 +0000 (0:00:00.066)       0:00:07.134 *******
2026-08-22 08:56:30.363696 | controller | ok: [controller] => {
2026-08-22 08:56:30.363766 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-08-22 08:56:30.363853 | controller | }
2026-08-22 08:56:30.363937 | controller |
2026-08-22 08:56:30.364036 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 08:56:30.364115 | controller | Saturday 22 August 2026  08:56:30 +0000 (0:00:00.046)       0:00:07.180 *******
2026-08-22 08:56:30.768276 | controller | ok: [controller]
2026-08-22 08:56:30.768299 | controller |
2026-08-22 08:56:30.768305 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 08:56:30.768310 | controller | Saturday 22 August 2026  08:56:30 +0000 (0:00:00.403)       0:00:07.584 *******
2026-08-22 08:56:32.408607 | controller | ok: [controller]
2026-08-22 08:56:32.408751 | controller |
2026-08-22 08:56:32.413525 | controller | PLAY RECAP *********************************************************************
2026-08-22 08:56:32.413535 | controller | controller                 : ok=16   changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-08-22 08:56:32.413540 | controller |
2026-08-22 08:56:32.413543 | controller |
2026-08-22 08:56:32.413547 | controller | TASKS RECAP ********************************************************************
2026-08-22 08:56:32.413550 | controller | Saturday 22 August 2026  08:56:32 +0000 (0:00:01.642)       0:00:09.226 *******
2026-08-22 08:56:32.413554 | controller | ===============================================================================
2026-08-22 08:56:32.413557 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.67s
2026-08-22 08:56:32.413560 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.64s
2026-08-22 08:56:32.413564 | controller | Gathering Facts --------------------------------------------------------- 1.03s
2026-08-22 08:56:32.413567 | controller | vexxhost.containers.package : Update state for tar ---------------------- 0.94s
2026-08-22 08:56:32.413571 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.52s
2026-08-22 08:56:32.413574 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.48s
2026-08-22 08:56:32.413577 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.46s
2026-08-22 08:56:32.413580 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.45s
2026-08-22 08:56:32.413584 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.43s
2026-08-22 08:56:32.413588 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.42s
2026-08-22 08:56:32.413597 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.40s
2026-08-22 08:56:32.413601 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.33s
2026-08-22 08:56:32.413604 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.28s
2026-08-22 08:56:32.413607 | controller | Install plugin ---------------------------------------------------------- 0.07s
2026-08-22 08:56:32.413611 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-08-22 08:56:32.413614 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s
2026-08-22 08:56:32.497271 | controller | INFO     [helm > idempotence] Executed: Successful
2026-08-22 08:56:32.503066 | controller | INFO     [helm > side_effect] Executing
2026-08-22 08:56:32.503417 | controller | WARNING  [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-22 08:56:32.508779 | controller | INFO     [helm > verify] Executing
2026-08-22 08:56:33.317167 | controller |
2026-08-22 08:56:33.317490 | controller | PLAY [Verify] ******************************************************************
2026-08-22 08:56:33.317735 | controller |
2026-08-22 08:56:33.317993 | controller | TASK [Gathering Facts] *********************************************************
2026-08-22 08:56:33.318316 | controller | Saturday 22 August 2026  08:56:33 +0000 (0:00:00.009)       0:00:00.009 *******
2026-08-22 08:56:34.408148 | 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-08-22 08:56:34.412304 | controller | ok: [controller]
2026-08-22 08:56:34.412533 | controller |
2026-08-22 08:56:34.412728 | controller | TASK [Get the current Helm version] ********************************************
2026-08-22 08:56:34.412929 | controller | Saturday 22 August 2026  08:56:34 +0000 (0:00:01.095)       0:00:01.105 *******
2026-08-22 08:56:34.857960 | controller | changed: [controller]
2026-08-22 08:56:34.858074 | controller |
2026-08-22 08:56:34.858339 | controller | TASK [Assert Helm version] *****************************************************
2026-08-22 08:56:34.858507 | controller | Saturday 22 August 2026  08:56:34 +0000 (0:00:00.445)       0:00:01.550 *******
2026-08-22 08:56:34.889301 | controller | ok: [controller] => {
2026-08-22 08:56:34.889494 | controller |     "changed": false,
2026-08-22 08:56:34.889691 | controller |     "msg": "All assertions passed"
2026-08-22 08:56:34.889876 | controller | }
2026-08-22 08:56:34.890052 | controller |
2026-08-22 08:56:34.890238 | controller | PLAY RECAP *********************************************************************
2026-08-22 08:56:34.890443 | controller | controller                 : ok=3    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-08-22 08:56:34.890613 | controller |
2026-08-22 08:56:34.890792 | controller |
2026-08-22 08:56:34.890970 | controller | TASKS RECAP ********************************************************************
2026-08-22 08:56:34.891172 | controller | Saturday 22 August 2026  08:56:34 +0000 (0:00:00.032)       0:00:01.583 *******
2026-08-22 08:56:34.891348 | controller | ===============================================================================
2026-08-22 08:56:34.891608 | controller | Gathering Facts --------------------------------------------------------- 1.10s
2026-08-22 08:56:34.891884 | controller | Get the current Helm version -------------------------------------------- 0.45s
2026-08-22 08:56:34.892094 | controller | Assert Helm version ----------------------------------------------------- 0.03s
2026-08-22 08:56:34.990490 | controller | INFO     [helm > verify] Executed: Successful
2026-08-22 08:56:35.000976 | controller | INFO     [helm > cleanup] Executing
2026-08-22 08:56:35.001354 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-22 08:56:35.007530 | controller | INFO     [helm > destroy] Executing
2026-08-22 08:56:35.007590 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-08-22 08:56:35.007663 | controller | INFO     [helm > destroy] Executed: Successful
2026-08-22 08:56:35.007925 | controller | WARNING  Molecule executed 1 scenario (1 missing files)
2026-08-22 08:56:35.514196 | controller | ok: Runtime: 0:00:49.287198
2026-08-22 08:56:35.519351 | 
2026-08-22 08:56:35.519519 | PLAY RECAP
2026-08-22 08:56:35.519581 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 08:56:35.519616 | 
2026-08-22 08:56:35.668108 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-22 08:56:35.670605 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-08-22 08:56:36.298615 | 
2026-08-22 08:56:36.298738 | PLAY [all]
2026-08-22 08:56:36.310357 | 
2026-08-22 08:56:36.310443 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-08-22 08:56:36.684688 | controller | changed
2026-08-22 08:56:36.691169 | 
2026-08-22 08:56:36.691319 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-08-22 08:56:37.081920 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 08:56:37.082009 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 08:56:37.089535 | controller | environment: line 1: kubectl: command not found
2026-08-22 08:56:37.091676 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 08:56:37.092556 | controller | environment: line 1: kubectl: command not found
2026-08-22 08:56:37.094736 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 08:56:37.095101 | controller | environment: line 1: kubectl: command not found
2026-08-22 08:56:37.097516 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 08:56:37.098084 | controller | environment: line 1: kubectl: command not found
2026-08-22 08:56:37.099517 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 08:56:37.100848 | controller | environment: line 1: kubectl: command not found
2026-08-22 08:56:37.102655 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 08:56:37.222698 | controller | ok: Runtime: 0:00:00.035324
2026-08-22 08:56:37.227874 | 
2026-08-22 08:56:37.227977 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-08-22 08:56:37.452604 | controller | changed
2026-08-22 08:56:37.456902 | 
2026-08-22 08:56:37.457010 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-08-22 08:56:37.701606 | controller | environment: line 5: kubectl: command not found
2026-08-22 08:56:37.703273 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 08:56:37.703778 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 08:56:37.704458 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 08:56:37.987173 | controller | ok: Runtime: 0:00:00.013569
2026-08-22 08:56:37.993807 | 
2026-08-22 08:56:37.993902 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-08-22 08:56:38.642960 | controller | changed:
2026-08-22 08:56:38.643132 | controller | created directory /var/lib/zuul/builds/6f1228fe6e4444138a2bd37dd9d506e6/work/logs/controller
2026-08-22 08:56:38.643165 | controller | cd+++++++++ objects/
2026-08-22 08:56:38.643187 | controller | cd+++++++++ objects/cluster/
2026-08-22 08:56:38.643210 | controller | cd+++++++++ objects/namespaced/
2026-08-22 08:56:38.651531 | 
2026-08-22 08:56:38.651669 | TASK [gather-pod-logs : creating directory for pod logs]
2026-08-22 08:56:38.886706 | controller | changed
2026-08-22 08:56:38.891700 | 
2026-08-22 08:56:38.891789 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-08-22 08:56:39.127352 | controller | changed
2026-08-22 08:56:39.133309 | 
2026-08-22 08:56:39.133408 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-08-22 08:56:39.363400 | controller | environment: line 3: kubectl: command not found
2026-08-22 08:56:39.661947 | controller | ok: Runtime: 0:00:00.013544
2026-08-22 08:56:39.668916 | 
2026-08-22 08:56:39.669041 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-08-22 08:56:40.152875 | controller | changed:
2026-08-22 08:56:40.153074 | controller | cd+++++++++ pod-logs/
2026-08-22 08:56:40.153115 | controller | cd+++++++++ pod-logs/failed-pods/
2026-08-22 08:56:40.162305 | 
2026-08-22 08:56:40.162366 | LOOP [helm-release-status : creating directory for helm release status]
2026-08-22 08:56:40.388444 | controller | changed: "values"
2026-08-22 08:56:40.586748 | controller | changed: "releases"
2026-08-22 08:56:40.606986 | 
2026-08-22 08:56:40.607169 | TASK [helm-release-status : Gather get release status for helm charts]
2026-08-22 08:56:40.880697 | controller | /bin/bash: line 3: kubectl: command not found
2026-08-22 08:56:41.138162 | controller | ok: Runtime: 0:00:00.008726
2026-08-22 08:56:41.144497 | 
2026-08-22 08:56:41.144598 | TASK [helm-release-status : Downloads logs to executor]
2026-08-22 08:56:41.724493 | controller | changed:
2026-08-22 08:56:41.724710 | controller | cd+++++++++ helm/
2026-08-22 08:56:41.724740 | controller | cd+++++++++ helm/releases/
2026-08-22 08:56:41.724764 | controller | cd+++++++++ helm/values/
2026-08-22 08:56:41.729857 | 
2026-08-22 08:56:41.729912 | PLAY RECAP
2026-08-22 08:56:41.729992 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 08:56:41.730044 | 
2026-08-22 08:56:41.856854 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-08-22 08:56:41.860672 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-22 08:56:43.029784 | 
2026-08-22 08:56:43.029908 | PLAY [all]
2026-08-22 08:56:43.042726 | 
2026-08-22 08:56:43.042851 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-22 08:56:43.087694 | controller | skipping: Conditional result was False
2026-08-22 08:56:43.094874 | 
2026-08-22 08:56:43.094992 | TASK [fetch-output : Set log path for single node]
2026-08-22 08:56:43.138691 | controller | ok
2026-08-22 08:56:43.143770 | 
2026-08-22 08:56:43.143872 | LOOP [fetch-output : Ensure local output dirs]
2026-08-22 08:56:43.645367 | controller -> localhost | ok: "/var/lib/zuul/builds/6f1228fe6e4444138a2bd37dd9d506e6/work/logs"
2026-08-22 08:56:43.866388 | controller -> localhost | changed: "/var/lib/zuul/builds/6f1228fe6e4444138a2bd37dd9d506e6/work/artifacts"
2026-08-22 08:56:44.095552 | controller -> localhost | changed: "/var/lib/zuul/builds/6f1228fe6e4444138a2bd37dd9d506e6/work/docs"
2026-08-22 08:56:44.107104 | 
2026-08-22 08:56:44.107270 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-22 08:56:44.815728 | controller | changed: .d..t...... ./
2026-08-22 08:56:44.816044 | controller | changed: All items complete
2026-08-22 08:56:44.816104 | 
2026-08-22 08:56:45.304591 | controller | changed: .d..t...... ./
2026-08-22 08:56:45.755047 | controller | changed: .d..t...... ./
2026-08-22 08:56:45.775039 | 
2026-08-22 08:56:45.775210 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-22 08:56:46.442310 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008795
2026-08-22 08:56:46.905328 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009122
2026-08-22 08:56:46.916247 | 
2026-08-22 08:56:46.916395 | PLAY [all]
2026-08-22 08:56:46.921905 | 
2026-08-22 08:56:46.921997 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-22 08:56:47.387403 | controller | changed
2026-08-22 08:56:47.393962 | 
2026-08-22 08:56:47.394018 | PLAY RECAP
2026-08-22 08:56:47.394103 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-22 08:56:47.394133 | 
2026-08-22 08:56:47.552990 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-22 08:56:47.556092 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-22 08:56:48.293627 | 
2026-08-22 08:56:48.293759 | PLAY [localhost]
2026-08-22 08:56:48.303853 | 
2026-08-22 08:56:48.303925 | TASK [Generate Zuul manifest]
2026-08-22 08:56:48.324856 | localhost | ok
2026-08-22 08:56:48.342094 | 
2026-08-22 08:56:48.342178 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-22 08:56:48.781316 | localhost | changed
2026-08-22 08:56:48.793963 | 
2026-08-22 08:56:48.794086 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-22 08:56:49.008883 | localhost | ok
2026-08-22 08:56:49.016489 | 
2026-08-22 08:56:49.016571 | TASK [Upload logs]
2026-08-22 08:56:49.038406 | localhost | ok
2026-08-22 08:56:49.106280 | 
2026-08-22 08:56:49.106423 | TASK [Set zuul-log-path fact]
2026-08-22 08:56:49.128952 | localhost | ok
2026-08-22 08:56:49.142576 | 
2026-08-22 08:56:49.142694 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-22 08:56:49.173789 | localhost | ok
2026-08-22 08:56:49.184662 | 
2026-08-22 08:56:49.184736 | TASK [upload-logs : Create log directories]
2026-08-22 08:56:49.646911 | localhost | changed
2026-08-22 08:56:49.652406 | 
2026-08-22 08:56:49.652474 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-22 08:56:50.391438 | localhost -> localhost | ok: Runtime: 0:00:00.007652
2026-08-22 08:56:50.399751 | 
2026-08-22 08:56:50.399952 | TASK [upload-logs : Upload logs to log server]
2026-08-22 08:56:50.897229 | localhost | Output suppressed because no_log was given
2026-08-22 08:56:50.903766 | 
2026-08-22 08:56:50.903937 | LOOP [upload-logs : Compress console log and json output]
2026-08-22 08:56:50.956598 | localhost | skipping: Conditional result was False
2026-08-22 08:56:51.004397 | localhost | skipping: Conditional result was False
2026-08-22 08:56:51.021158 | 
2026-08-22 08:56:51.021319 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-22 08:56:51.061768 | localhost | skipping: Conditional result was False
2026-08-22 08:56:51.062088 | 
2026-08-22 08:56:51.065947 | localhost | skipping: Conditional result was False
2026-08-22 08:56:51.074836 | 
2026-08-22 08:56:51.075000 | LOOP [upload-logs : Upload console log and json output]