2026-08-12 08:58:18.305880 | Job console starting
2026-08-12 08:58:18.316663 | Updating git repos
2026-08-12 08:58:18.369053 | Cloning repos into workspace
2026-08-12 08:58:18.435553 | Restoring repo states
2026-08-12 08:58:18.454396 | Merging changes
2026-08-12 08:58:19.118352 | Checking out repos
2026-08-12 08:58:19.216344 | Preparing playbooks
2026-08-12 08:58:22.295415 | Running Ansible setup
2026-08-12 08:58:26.250043 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-12 08:58:26.863855 | 
2026-08-12 08:58:26.864008 | PLAY [localhost]
2026-08-12 08:58:26.871822 | 
2026-08-12 08:58:26.871897 | TASK [Gathering Facts]
2026-08-12 08:58:27.742213 | localhost | ok
2026-08-12 08:58:27.753446 | 
2026-08-12 08:58:27.753739 | TASK [Setup log path fact]
2026-08-12 08:58:27.775951 | localhost | ok
2026-08-12 08:58:27.794410 | 
2026-08-12 08:58:27.794548 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-12 08:58:27.826205 | localhost | ok
2026-08-12 08:58:27.834726 | 
2026-08-12 08:58:27.834823 | TASK [emit-job-header : Print job information]
2026-08-12 08:58:27.876836 | # Job Information
2026-08-12 08:58:27.877023 | Ansible Version: 2.16.18
2026-08-12 08:58:27.877074 | Job: ansible-collection-kubernetes-molecule-helm
2026-08-12 08:58:27.877106 | Pipeline: check
2026-08-12 08:58:27.877137 | Executor: 2d72f0692154
2026-08-12 08:58:27.877168 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/297
2026-08-12 08:58:27.877201 | Event ID: 74183660-962b-11f1-8f1d-937ba49a4a3c
2026-08-12 08:58:27.881186 | 
2026-08-12 08:58:27.881280 | LOOP [emit-job-header : Print node information]
2026-08-12 08:58:27.967076 | localhost | ok:
2026-08-12 08:58:27.967283 | localhost | # Node Information
2026-08-12 08:58:27.967323 | localhost | Inventory Hostname: controller
2026-08-12 08:58:27.967359 | localhost | Hostname: np0000188937
2026-08-12 08:58:27.967388 | localhost | Username: zuul
2026-08-12 08:58:27.967421 | localhost | Distro: Debian 13.6
2026-08-12 08:58:27.967450 | localhost | Provider: yul1
2026-08-12 08:58:27.967477 | localhost | Region: ca-ymq-1
2026-08-12 08:58:27.967503 | localhost | Label: debian-trixie
2026-08-12 08:58:27.967530 | localhost | Product Name: OpenStack Nova
2026-08-12 08:58:27.967556 | localhost | Interface IP: 199.204.45.113
2026-08-12 08:58:27.979207 | 
2026-08-12 08:58:27.979384 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-12 08:58:28.372277 | localhost -> localhost | changed
2026-08-12 08:58:28.380731 | 
2026-08-12 08:58:28.380826 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-12 08:58:29.207147 | localhost -> localhost | changed
2026-08-12 08:58:29.216624 | 
2026-08-12 08:58:29.216746 | PLAY [all]
2026-08-12 08:58:29.224995 | 
2026-08-12 08:58:29.225072 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-12 08:58:29.443028 | controller -> localhost | ok
2026-08-12 08:58:29.454034 | 
2026-08-12 08:58:29.454108 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-12 08:58:29.488091 | controller | ok
2026-08-12 08:58:29.503652 | controller | included: /var/lib/zuul/builds/3216daca38b84208957fab3ea2756336/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-12 08:58:29.509929 | 
2026-08-12 08:58:29.510015 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-12 08:58:31.896307 | controller -> localhost | Generating public/private rsa key pair.
2026-08-12 08:58:31.896562 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/3216daca38b84208957fab3ea2756336/work/3216daca38b84208957fab3ea2756336_id_rsa
2026-08-12 08:58:31.896613 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/3216daca38b84208957fab3ea2756336/work/3216daca38b84208957fab3ea2756336_id_rsa.pub
2026-08-12 08:58:31.896650 | controller -> localhost | The key fingerprint is:
2026-08-12 08:58:31.896682 | controller -> localhost | SHA256:+sNXb9RK34jw4A7YWZ+Sudgl25EXt+d3DVxUcD8qb3g zuul-build-sshkey
2026-08-12 08:58:31.896732 | controller -> localhost | The key's randomart image is:
2026-08-12 08:58:31.896765 | controller -> localhost | +---[RSA 3072]----+
2026-08-12 08:58:31.896803 | controller -> localhost | |              ..+|
2026-08-12 08:58:31.896835 | controller -> localhost | |               .o|
2026-08-12 08:58:31.896866 | controller -> localhost | |               o.|
2026-08-12 08:58:31.896896 | controller -> localhost | |              . o|
2026-08-12 08:58:31.896926 | controller -> localhost | |        S .. o.o.|
2026-08-12 08:58:31.897016 | controller -> localhost | |       + oo+=o=oo|
2026-08-12 08:58:31.897098 | controller -> localhost | |      o.+.=**E.*+|
2026-08-12 08:58:31.897135 | controller -> localhost | |       .o+oB=o=.B|
2026-08-12 08:58:31.897172 | controller -> localhost | |        o+= ..  +|
2026-08-12 08:58:31.897203 | controller -> localhost | +----[SHA256]-----+
2026-08-12 08:58:31.897278 | controller -> localhost | ok: Runtime: 0:00:01.920735
2026-08-12 08:58:31.903793 | 
2026-08-12 08:58:31.903892 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-12 08:58:31.934409 | controller | ok
2026-08-12 08:58:31.944469 | controller | included: /var/lib/zuul/builds/3216daca38b84208957fab3ea2756336/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-12 08:58:31.954033 | 
2026-08-12 08:58:31.954202 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-12 08:58:31.981909 | controller | skipping: Conditional result was False
2026-08-12 08:58:31.988814 | 
2026-08-12 08:58:31.988918 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-12 08:58:32.543564 | controller | changed
2026-08-12 08:58:32.551682 | 
2026-08-12 08:58:32.551763 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-12 08:58:32.791512 | controller | ok
2026-08-12 08:58:32.795878 | 
2026-08-12 08:58:32.795944 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-12 08:58:33.365544 | controller | changed
2026-08-12 08:58:33.370955 | 
2026-08-12 08:58:33.371022 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-12 08:58:33.918054 | controller | changed
2026-08-12 08:58:33.923584 | 
2026-08-12 08:58:33.923671 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-12 08:58:33.948917 | controller | skipping: Conditional result was False
2026-08-12 08:58:33.960121 | 
2026-08-12 08:58:33.960260 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-12 08:58:34.390986 | controller -> localhost | changed
2026-08-12 08:58:34.407331 | 
2026-08-12 08:58:34.407421 | TASK [add-build-sshkey : Add back temp key]
2026-08-12 08:58:34.692722 | controller -> localhost | Identity added: /var/lib/zuul/builds/3216daca38b84208957fab3ea2756336/work/3216daca38b84208957fab3ea2756336_id_rsa (zuul-build-sshkey)
2026-08-12 08:58:34.693072 | controller -> localhost | ok: Runtime: 0:00:00.015574
2026-08-12 08:58:34.701030 | 
2026-08-12 08:58:34.701093 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-12 08:58:35.057300 | controller | ok
2026-08-12 08:58:35.062211 | 
2026-08-12 08:58:35.062294 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-12 08:58:35.088308 | controller | skipping: Conditional result was False
2026-08-12 08:58:35.101032 | 
2026-08-12 08:58:35.101161 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-12 08:58:35.464540 | controller | ok
2026-08-12 08:58:35.469405 | 
2026-08-12 08:58:35.469507 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-12 08:58:36.432918 | controller | Output suppressed because no_log was given
2026-08-12 08:58:36.443210 | 
2026-08-12 08:58:36.443278 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-12 08:58:36.653415 | controller | ok: "logs"
2026-08-12 08:58:36.653689 | controller | ok: All items complete
2026-08-12 08:58:36.653723 | 
2026-08-12 08:58:36.863002 | controller | ok: "artifacts"
2026-08-12 08:58:37.047485 | controller | ok: "docs"
2026-08-12 08:58:37.058167 | 
2026-08-12 08:58:37.058372 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-12 08:58:37.267512 | controller | changed: "logs"
2026-08-12 08:58:37.459684 | controller | changed: "artifacts"
2026-08-12 08:58:37.657091 | controller | changed: "docs"
2026-08-12 08:58:37.678962 | 
2026-08-12 08:58:37.679474 | PLAY RECAP
2026-08-12 08:58:37.679543 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-12 08:58:37.679584 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-12 08:58:37.679615 | 
2026-08-12 08:58:37.824022 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-12 08:58:37.826878 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-12 08:58:38.505944 | 
2026-08-12 08:58:38.506083 | PLAY [all]
2026-08-12 08:58:38.515610 | 
2026-08-12 08:58:38.515686 | TASK [setup-uv : Extract archive]
2026-08-12 08:58:41.984377 | controller | changed
2026-08-12 08:58:41.990811 | 
2026-08-12 08:58:41.990920 | TASK [setup-uv : Print version]
2026-08-12 08:58:42.364728 | controller | uv 0.8.13
2026-08-12 08:58:42.528710 | controller | ok: Runtime: 0:00:00.014648
2026-08-12 08:58:42.536673 | 
2026-08-12 08:58:42.536748 | TASK [Install Ansible collection siblings]
2026-08-12 08:58:42.945313 | controller | ok:
2026-08-12 08:58:42.945578 | controller | Skipping ansible.posix: no checked-out sibling
2026-08-12 08:58:42.945652 | controller | Skipping community.crypto: no checked-out sibling
2026-08-12 08:58:42.945754 | controller | Skipping community.general: no checked-out sibling
2026-08-12 08:58:42.945806 | controller | Skipping kubernetes.core: no checked-out sibling
2026-08-12 08:58:42.945852 | controller | Skipping vexxhost.containers: no checked-out sibling
2026-08-12 08:58:42.949010 | 
2026-08-12 08:58:42.949058 | PLAY RECAP
2026-08-12 08:58:42.949106 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-12 08:58:42.949128 | 
2026-08-12 08:58:43.069820 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-12 08:58:43.072622 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-08-12 08:58:43.683127 | 
2026-08-12 08:58:43.683251 | PLAY [all]
2026-08-12 08:58:43.693163 | 
2026-08-12 08:58:43.693242 | TASK [Update apt cache]
2026-08-12 08:58:48.573553 | controller | ok
2026-08-12 08:58:48.580247 | 
2026-08-12 08:58:48.580343 | TASK [Disable firewalld]
2026-08-12 08:58:48.596332 | controller | skipping: Conditional result was False
2026-08-12 08:58:48.605868 | 
2026-08-12 08:58:48.606017 | TASK [Install "jq" for log collection]
2026-08-12 08:58:50.826047 | controller | changed
2026-08-12 08:58:50.828619 | 
2026-08-12 08:58:50.828687 | PLAY RECAP
2026-08-12 08:58:50.828746 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-12 08:58:50.828779 | 
2026-08-12 08:58:50.967647 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-08-12 08:58:50.969738 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-12 08:58:51.559903 | 
2026-08-12 08:58:51.560044 | PLAY [all]
2026-08-12 08:58:51.570101 | 
2026-08-12 08:58:51.570176 | LOOP [Check inventory directories]
2026-08-12 08:58:51.953015 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-08-12 08:58:52.133296 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/helm/inventory"
2026-08-12 08:58:52.318322 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/inventory"
2026-08-12 08:58:52.338785 | 
2026-08-12 08:58:52.339038 | LOOP [Copy inventory file for Zuul]
2026-08-12 08:58:53.257666 | controller | changed:
2026-08-12 08:58:53.257994 | controller | {
2026-08-12 08:58:53.258047 | controller |   "ansible_loop_var": "item",
2026-08-12 08:58:53.258082 | controller |   "changed": false,
2026-08-12 08:58:53.258111 | controller |   "failed": false,
2026-08-12 08:58:53.258142 | controller |   "invocation": {
2026-08-12 08:58:53.258169 | controller |     "module_args": {
2026-08-12 08:58:53.258195 | controller |       "checksum_algorithm": "sha1",
2026-08-12 08:58:53.258221 | controller |       "follow": true,
2026-08-12 08:58:53.258247 | controller |       "get_attributes": true,
2026-08-12 08:58:53.258274 | controller |       "get_checksum": true,
2026-08-12 08:58:53.258301 | controller |       "get_mime": true,
2026-08-12 08:58:53.258327 | controller |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-08-12 08:58:53.258353 | controller |     }
2026-08-12 08:58:53.258378 | controller |   },
2026-08-12 08:58:53.258427 | controller |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-08-12 08:58:53.258455 | controller |   "stat": {
2026-08-12 08:58:53.258480 | controller |     "atime": 1786525116.3108275,
2026-08-12 08:58:53.258505 | controller |     "attr_flags": "e",
2026-08-12 08:58:53.258531 | controller |     "attributes": [
2026-08-12 08:58:53.258556 | controller |       "extents"
2026-08-12 08:58:53.258581 | controller |     ],
2026-08-12 08:58:53.258606 | controller |     "block_size": 4096,
2026-08-12 08:58:53.258631 | controller |     "blocks": 8,
2026-08-12 08:58:53.258656 | controller |     "charset": "unknown",
2026-08-12 08:58:53.258683 | controller |     "ctime": 1786525116.3308277,
2026-08-12 08:58:53.258709 | controller |     "dev": 65025,
2026-08-12 08:58:53.258734 | controller |     "device_type": 0,
2026-08-12 08:58:53.258759 | controller |     "executable": true,
2026-08-12 08:58:53.258784 | controller |     "exists": true,
2026-08-12 08:58:53.258809 | controller |     "gid": 1000,
2026-08-12 08:58:53.258834 | controller |     "gr_name": "zuul",
2026-08-12 08:58:53.258859 | controller |     "inode": 519191,
2026-08-12 08:58:53.258883 | controller |     "isblk": false,
2026-08-12 08:58:53.258908 | controller |     "ischr": false,
2026-08-12 08:58:53.258933 | controller |     "isdir": true,
2026-08-12 08:58:53.258958 | controller |     "isfifo": false,
2026-08-12 08:58:53.258983 | controller |     "isgid": false,
2026-08-12 08:58:53.259008 | controller |     "islnk": false,
2026-08-12 08:58:53.259032 | controller |     "isreg": false,
2026-08-12 08:58:53.259057 | controller |     "issock": false,
2026-08-12 08:58:53.259082 | controller |     "isuid": false,
2026-08-12 08:58:53.259106 | controller |     "mimetype": "unknown",
2026-08-12 08:58:53.259131 | controller |     "mode": "0755",
2026-08-12 08:58:53.259156 | controller |     "mtime": 1786525098.562699,
2026-08-12 08:58:53.259180 | controller |     "nlink": 14,
2026-08-12 08:58:53.259205 | controller |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-08-12 08:58:53.259229 | controller |     "pw_name": "zuul",
2026-08-12 08:58:53.259258 | controller |     "readable": true,
2026-08-12 08:58:53.259284 | controller |     "rgrp": true,
2026-08-12 08:58:53.259309 | controller |     "roth": true,
2026-08-12 08:58:53.259334 | controller |     "rusr": true,
2026-08-12 08:58:53.259359 | controller |     "size": 4096,
2026-08-12 08:58:53.259384 | controller |     "uid": 1000,
2026-08-12 08:58:53.259410 | controller |     "version": "3428838630",
2026-08-12 08:58:53.259435 | controller |     "wgrp": false,
2026-08-12 08:58:53.259461 | controller |     "woth": false,
2026-08-12 08:58:53.259486 | controller |     "writeable": true,
2026-08-12 08:58:53.259512 | controller |     "wusr": true,
2026-08-12 08:58:53.259536 | controller |     "xgrp": true,
2026-08-12 08:58:53.259561 | controller |     "xoth": true,
2026-08-12 08:58:53.259586 | controller |     "xusr": true
2026-08-12 08:58:53.259610 | controller |   }
2026-08-12 08:58:53.259652 | controller | }
2026-08-12 08:58:53.259704 | controller | ok: All items complete
2026-08-12 08:58:53.259736 | 
2026-08-12 08:58:53.261437 | controller | skipping: Conditional result was False
2026-08-12 08:58:53.262244 | controller | skipping: Conditional result was False
2026-08-12 08:58:53.279801 | 
2026-08-12 08:58:53.279938 | LOOP [Switch "ansible_host" to private IP]
2026-08-12 08:58:53.662343 | controller | changed: 1 replacements made
2026-08-12 08:58:53.664026 | controller | skipping: Conditional result was False
2026-08-12 08:58:53.668009 | controller | skipping: Conditional result was False
2026-08-12 08:58:53.682636 | 
2026-08-12 08:58:53.682793 | TASK [Run Molecule scenario]
2026-08-12 08:58:54.115393 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-08-12 08:58:54.115504 | controller | Creating virtual environment at: .venv
2026-08-12 08:58:54.367434 | controller | Downloading ansible-core (2.3MiB)
2026-08-12 08:58:54.367600 | controller | Downloading pygments (1.2MiB)
2026-08-12 08:58:54.368365 | controller | Downloading cryptography (4.3MiB)
2026-08-12 08:58:54.520138 | controller |  Downloading cryptography
2026-08-12 08:58:54.575863 | controller |  Downloading pygments
2026-08-12 08:58:54.721229 | controller |  Downloading ansible-core
2026-08-12 08:58:54.768921 | controller | Installed 36 packages in 47ms
2026-08-12 08:58:55.445814 | controller | INFO     Collection 'vexxhost.kubernetes' detected.
2026-08-12 08:58:55.445894 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-08-12 08:58:56.355210 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-08-12 08:58:56.355280 | controller | INFO     default scenario not found, disabling shared state.
2026-08-12 08:58:56.355302 | controller | INFO     [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-08-12 08:58:56.355399 | controller | INFO     [helm > prerun] Performing prerun with role_name_check=0...
2026-08-12 08:59:21.793333 | controller | INFO     [helm > dependency] Executing
2026-08-12 08:59:21.793473 | controller | WARNING  [helm > dependency] Missing roles requirements file: requirements.yml
2026-08-12 08:59:21.793482 | controller | WARNING  [helm > dependency] Missing collections requirements file: collections.yml
2026-08-12 08:59:21.793512 | controller | WARNING  [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-08-12 08:59:21.799668 | controller | INFO     [helm > cleanup] Executing
2026-08-12 08:59:21.799971 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-12 08:59:21.805683 | controller | INFO     [helm > destroy] Executing
2026-08-12 08:59:21.805708 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-08-12 08:59:21.805837 | controller | INFO     [helm > destroy] Executed: Successful
2026-08-12 08:59:21.811296 | controller | INFO     [helm > syntax] Executing
2026-08-12 08:59:22.806752 | controller |
2026-08-12 08:59:22.806991 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml
2026-08-12 08:59:22.873711 | controller | INFO     [helm > syntax] Executed: Successful
2026-08-12 08:59:22.883795 | controller | INFO     [helm > create] Executing
2026-08-12 08:59:22.885979 | controller | WARNING  [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-12 08:59:22.891597 | controller | INFO     [helm > prepare] Executing
2026-08-12 08:59:23.835359 | controller |
2026-08-12 08:59:23.835440 | controller | PLAY [Prepare] *****************************************************************
2026-08-12 08:59:23.835667 | controller |
2026-08-12 08:59:23.835999 | controller | TASK [Gathering Facts] *********************************************************
2026-08-12 08:59:23.836222 | controller | Wednesday 12 August 2026  08:59:23 +0000 (0:00:00.035)       0:00:00.035 ******
2026-08-12 08:59:26.134921 | 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-12 08:59:26.140744 | controller | ok: [controller]
2026-08-12 08:59:26.140927 | controller |
2026-08-12 08:59:26.141242 | controller | TASK [Install Debian packages] *************************************************
2026-08-12 08:59:26.141442 | controller | Wednesday 12 August 2026  08:59:26 +0000 (0:00:02.305)       0:00:02.341 ******
2026-08-12 08:59:27.708975 | controller | ok: [controller]
2026-08-12 08:59:27.709135 | controller |
2026-08-12 08:59:27.709343 | controller | PLAY RECAP *********************************************************************
2026-08-12 08:59:27.709618 | controller | controller                 : ok=2    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-08-12 08:59:27.709923 | controller |
2026-08-12 08:59:27.710137 | controller |
2026-08-12 08:59:27.710326 | controller | TASKS RECAP ********************************************************************
2026-08-12 08:59:27.710547 | controller | Wednesday 12 August 2026  08:59:27 +0000 (0:00:01.568)       0:00:03.910 ******
2026-08-12 08:59:27.710788 | controller | ===============================================================================
2026-08-12 08:59:27.710977 | controller | Gathering Facts --------------------------------------------------------- 2.31s
2026-08-12 08:59:27.711226 | controller | Install Debian packages ------------------------------------------------- 1.57s
2026-08-12 08:59:27.808423 | controller | INFO     [helm > prepare] Executed: Successful
2026-08-12 08:59:27.818902 | controller | INFO     [helm > converge] Executing
2026-08-12 08:59:28.719146 | controller |
2026-08-12 08:59:28.719321 | controller | PLAY [Converge] ****************************************************************
2026-08-12 08:59:28.719609 | controller |
2026-08-12 08:59:28.719918 | controller | TASK [Gathering Facts] *********************************************************
2026-08-12 08:59:28.720146 | controller | Wednesday 12 August 2026  08:59:28 +0000 (0:00:00.013)       0:00:00.013 ******
2026-08-12 08:59:29.798186 | 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-12 08:59:29.801847 | controller | ok: [controller]
2026-08-12 08:59:29.802052 | controller |
2026-08-12 08:59:29.802280 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-12 08:59:29.802494 | controller | Wednesday 12 August 2026  08:59:29 +0000 (0:00:01.082)       0:00:01.096 ******
2026-08-12 08:59:30.291669 | controller | ok: [controller]
2026-08-12 08:59:30.291944 | controller |
2026-08-12 08:59:30.292246 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-12 08:59:30.292486 | controller | Wednesday 12 August 2026  08:59:30 +0000 (0:00:00.489)       0:00:01.586 ******
2026-08-12 08:59:31.495038 | controller | ok: [controller]
2026-08-12 08:59:31.495130 | controller |
2026-08-12 08:59:31.495524 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-08-12 08:59:31.495928 | controller | Wednesday 12 August 2026  08:59:31 +0000 (0:00:01.203)       0:00:02.789 ******
2026-08-12 08:59:31.945986 | controller | changed: [controller]
2026-08-12 08:59:31.946073 | controller |
2026-08-12 08:59:31.946084 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-12 08:59:31.947560 | controller | Wednesday 12 August 2026  08:59:31 +0000 (0:00:00.449)       0:00:03.238 ******
2026-08-12 08:59:31.995707 | controller | ok: [controller] => {
2026-08-12 08:59:31.995898 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-08-12 08:59:31.996101 | controller | }
2026-08-12 08:59:31.996272 | controller |
2026-08-12 08:59:31.996473 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-12 08:59:31.996856 | controller | Wednesday 12 August 2026  08:59:31 +0000 (0:00:00.050)       0:00:03.289 ******
2026-08-12 08:59:33.108681 | 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-12 08:59:33.116151 | controller | changed: [controller]
2026-08-12 08:59:33.116337 | controller |
2026-08-12 08:59:33.116533 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-12 08:59:33.116758 | controller | Wednesday 12 August 2026  08:59:33 +0000 (0:00:01.120)       0:00:04.410 ******
2026-08-12 08:59:35.240460 | controller | changed: [controller]
2026-08-12 08:59:35.240549 | controller |
2026-08-12 08:59:35.240556 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-08-12 08:59:35.240562 | controller | Wednesday 12 August 2026  08:59:35 +0000 (0:00:02.118)       0:00:06.529 ******
2026-08-12 08:59:35.743368 | controller | ok: [controller]
2026-08-12 08:59:35.743450 | controller |
2026-08-12 08:59:35.743739 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-08-12 08:59:35.743936 | controller | Wednesday 12 August 2026  08:59:35 +0000 (0:00:00.508)       0:00:07.037 ******
2026-08-12 08:59:36.231083 | controller | changed: [controller]
2026-08-12 08:59:36.231222 | controller |
2026-08-12 08:59:36.231408 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-08-12 08:59:36.231584 | controller | Wednesday 12 August 2026  08:59:36 +0000 (0:00:00.487)       0:00:07.524 ******
2026-08-12 08:59:36.701507 | controller | ok: [controller]
2026-08-12 08:59:36.701558 | controller |
2026-08-12 08:59:36.702138 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-08-12 08:59:36.702177 | controller | Wednesday 12 August 2026  08:59:36 +0000 (0:00:00.470)       0:00:07.995 ******
2026-08-12 08:59:37.013643 | controller | changed: [controller]
2026-08-12 08:59:37.013700 | controller |
2026-08-12 08:59:37.013782 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-08-12 08:59:37.013875 | controller | Wednesday 12 August 2026  08:59:37 +0000 (0:00:00.311)       0:00:08.306 ******
2026-08-12 08:59:37.336764 | controller | ok: [controller]
2026-08-12 08:59:37.336821 | controller |
2026-08-12 08:59:37.337714 | controller | TASK [Install plugin] **********************************************************
2026-08-12 08:59:37.337740 | controller | Wednesday 12 August 2026  08:59:37 +0000 (0:00:00.325)       0:00:08.631 ******
2026-08-12 08:59:37.396971 | controller | included: vexxhost.containers.download_artifact for controller
2026-08-12 08:59:37.397009 | controller |
2026-08-12 08:59:37.397126 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-12 08:59:37.397219 | controller | Wednesday 12 August 2026  08:59:37 +0000 (0:00:00.060)       0:00:08.691 ******
2026-08-12 08:59:37.446690 | controller | ok: [controller] => {
2026-08-12 08:59:37.446710 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-08-12 08:59:37.446715 | controller | }
2026-08-12 08:59:37.446719 | controller |
2026-08-12 08:59:37.446722 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-12 08:59:37.446727 | controller | Wednesday 12 August 2026  08:59:37 +0000 (0:00:00.046)       0:00:08.738 ******
2026-08-12 08:59:38.254038 | controller | changed: [controller]
2026-08-12 08:59:38.257155 | controller |
2026-08-12 08:59:38.257179 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-12 08:59:38.257189 | controller | Wednesday 12 August 2026  08:59:38 +0000 (0:00:00.807)       0:00:09.545 ******
2026-08-12 08:59:40.438264 | controller | changed: [controller]
2026-08-12 08:59:40.438577 | controller |
2026-08-12 08:59:40.438853 | controller | PLAY RECAP *********************************************************************
2026-08-12 08:59:40.439146 | controller | controller                 : ok=16   changed=7    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-08-12 08:59:40.439385 | controller |
2026-08-12 08:59:40.439683 | controller |
2026-08-12 08:59:40.439952 | controller | TASKS RECAP ********************************************************************
2026-08-12 08:59:40.440217 | controller | Wednesday 12 August 2026  08:59:40 +0000 (0:00:02.187)       0:00:11.732 ******
2026-08-12 08:59:40.440459 | controller | ===============================================================================
2026-08-12 08:59:40.440712 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.19s
2026-08-12 08:59:40.440981 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.12s
2026-08-12 08:59:40.441353 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.20s
2026-08-12 08:59:40.441660 | controller | vexxhost.containers.download_artifact : Download item ------------------- 1.12s
2026-08-12 08:59:40.441935 | controller | Gathering Facts --------------------------------------------------------- 1.08s
2026-08-12 08:59:40.442191 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.81s
2026-08-12 08:59:40.442441 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.51s
2026-08-12 08:59:40.442690 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.49s
2026-08-12 08:59:40.442940 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.49s
2026-08-12 08:59:40.443193 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.47s
2026-08-12 08:59:40.443463 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.45s
2026-08-12 08:59:40.443726 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.33s
2026-08-12 08:59:40.443990 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.31s
2026-08-12 08:59:40.444235 | controller | Install plugin ---------------------------------------------------------- 0.06s
2026-08-12 08:59:40.444488 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-08-12 08:59:40.444736 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-08-12 08:59:40.553473 | controller | INFO     [helm > converge] Executed: Successful
2026-08-12 08:59:40.564541 | controller | INFO     [helm > idempotence] Executing
2026-08-12 08:59:41.486675 | controller |
2026-08-12 08:59:41.486929 | controller | PLAY [Converge] ****************************************************************
2026-08-12 08:59:41.487131 | controller |
2026-08-12 08:59:41.487308 | controller | TASK [Gathering Facts] *********************************************************
2026-08-12 08:59:41.487487 | controller | Wednesday 12 August 2026  08:59:41 +0000 (0:00:00.014)       0:00:00.014 ******
2026-08-12 08:59:42.635870 | 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-12 08:59:42.640846 | controller | ok: [controller]
2026-08-12 08:59:42.641188 | controller |
2026-08-12 08:59:42.641522 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-12 08:59:42.641812 | controller | Wednesday 12 August 2026  08:59:42 +0000 (0:00:01.154)       0:00:01.168 ******
2026-08-12 08:59:43.111101 | controller | ok: [controller]
2026-08-12 08:59:43.111418 | controller |
2026-08-12 08:59:43.111699 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-12 08:59:43.111968 | controller | Wednesday 12 August 2026  08:59:43 +0000 (0:00:00.469)       0:00:01.638 ******
2026-08-12 08:59:44.220074 | controller | ok: [controller]
2026-08-12 08:59:44.220394 | controller |
2026-08-12 08:59:44.220688 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-08-12 08:59:44.221016 | controller | Wednesday 12 August 2026  08:59:44 +0000 (0:00:01.108)       0:00:02.747 ******
2026-08-12 08:59:44.669829 | controller | ok: [controller]
2026-08-12 08:59:44.669902 | controller |
2026-08-12 08:59:44.669965 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-12 08:59:44.670060 | controller | Wednesday 12 August 2026  08:59:44 +0000 (0:00:00.448)       0:00:03.196 ******
2026-08-12 08:59:44.716308 | controller | ok: [controller] => {
2026-08-12 08:59:44.716457 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-08-12 08:59:44.716580 | controller | }
2026-08-12 08:59:44.716714 | controller |
2026-08-12 08:59:44.716879 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-12 08:59:44.717015 | controller | Wednesday 12 August 2026  08:59:44 +0000 (0:00:00.047)       0:00:03.243 ******
2026-08-12 08:59:45.273954 | controller | ok: [controller]
2026-08-12 08:59:45.274049 | controller |
2026-08-12 08:59:45.274164 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-12 08:59:45.274301 | controller | Wednesday 12 August 2026  08:59:45 +0000 (0:00:00.555)       0:00:03.799 ******
2026-08-12 08:59:47.070006 | controller | ok: [controller]
2026-08-12 08:59:47.070082 | controller |
2026-08-12 08:59:47.070097 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-08-12 08:59:47.070248 | controller | Wednesday 12 August 2026  08:59:47 +0000 (0:00:01.798)       0:00:05.597 ******
2026-08-12 08:59:47.616732 | controller | ok: [controller]
2026-08-12 08:59:47.616771 | controller |
2026-08-12 08:59:47.616866 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-08-12 08:59:47.616957 | controller | Wednesday 12 August 2026  08:59:47 +0000 (0:00:00.546)       0:00:06.144 ******
2026-08-12 08:59:48.049362 | controller | ok: [controller]
2026-08-12 08:59:48.049437 | controller |
2026-08-12 08:59:48.049622 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-08-12 08:59:48.049644 | controller | Wednesday 12 August 2026  08:59:48 +0000 (0:00:00.430)       0:00:06.574 ******
2026-08-12 08:59:48.537878 | controller | ok: [controller]
2026-08-12 08:59:48.537936 | controller |
2026-08-12 08:59:48.538085 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-08-12 08:59:48.538221 | controller | Wednesday 12 August 2026  08:59:48 +0000 (0:00:00.489)       0:00:07.063 ******
2026-08-12 08:59:48.858857 | controller | ok: [controller]
2026-08-12 08:59:48.858897 | controller |
2026-08-12 08:59:48.858999 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-08-12 08:59:48.859092 | controller | Wednesday 12 August 2026  08:59:48 +0000 (0:00:00.321)       0:00:07.385 ******
2026-08-12 08:59:49.180576 | controller | ok: [controller]
2026-08-12 08:59:49.180651 | controller |
2026-08-12 08:59:49.180788 | controller | TASK [Install plugin] **********************************************************
2026-08-12 08:59:49.180923 | controller | Wednesday 12 August 2026  08:59:49 +0000 (0:00:00.322)       0:00:07.707 ******
2026-08-12 08:59:49.244856 | controller | included: vexxhost.containers.download_artifact for controller
2026-08-12 08:59:49.244898 | controller |
2026-08-12 08:59:49.244988 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-12 08:59:49.245081 | controller | Wednesday 12 August 2026  08:59:49 +0000 (0:00:00.062)       0:00:07.770 ******
2026-08-12 08:59:49.289752 | controller | ok: [controller] => {
2026-08-12 08:59:49.289882 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-08-12 08:59:49.289999 | controller | }
2026-08-12 08:59:49.290119 | controller |
2026-08-12 08:59:49.290249 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-12 08:59:49.290374 | controller | Wednesday 12 August 2026  08:59:49 +0000 (0:00:00.047)       0:00:07.817 ******
2026-08-12 08:59:49.719486 | controller | ok: [controller]
2026-08-12 08:59:49.719620 | controller |
2026-08-12 08:59:49.719770 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-12 08:59:49.719911 | controller | Wednesday 12 August 2026  08:59:49 +0000 (0:00:00.429)       0:00:08.246 ******
2026-08-12 08:59:51.401663 | controller | ok: [controller]
2026-08-12 08:59:51.401861 | controller |
2026-08-12 08:59:51.402176 | controller | PLAY RECAP *********************************************************************
2026-08-12 08:59:51.402539 | controller | controller                 : ok=16   changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-08-12 08:59:51.402831 | controller |
2026-08-12 08:59:51.403123 | controller |
2026-08-12 08:59:51.403496 | controller | TASKS RECAP ********************************************************************
2026-08-12 08:59:51.403830 | controller | Wednesday 12 August 2026  08:59:51 +0000 (0:00:01.682)       0:00:09.929 ******
2026-08-12 08:59:51.404064 | controller | ===============================================================================
2026-08-12 08:59:51.404272 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.80s
2026-08-12 08:59:51.404473 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.68s
2026-08-12 08:59:51.404684 | controller | Gathering Facts --------------------------------------------------------- 1.15s
2026-08-12 08:59:51.404895 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.11s
2026-08-12 08:59:51.405161 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.56s
2026-08-12 08:59:51.405445 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.55s
2026-08-12 08:59:51.405712 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.49s
2026-08-12 08:59:51.405991 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.47s
2026-08-12 08:59:51.406202 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.45s
2026-08-12 08:59:51.406393 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.43s
2026-08-12 08:59:51.406589 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.43s
2026-08-12 08:59:51.406803 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.32s
2026-08-12 08:59:51.407009 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.32s
2026-08-12 08:59:51.407147 | controller | Install plugin ---------------------------------------------------------- 0.06s
2026-08-12 08:59:51.407335 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-08-12 08:59:51.407466 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-08-12 08:59:51.554019 | controller | INFO     [helm > idempotence] Executed: Successful
2026-08-12 08:59:51.560090 | controller | INFO     [helm > side_effect] Executing
2026-08-12 08:59:51.560473 | controller | WARNING  [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-12 08:59:51.568534 | controller | INFO     [helm > verify] Executing
2026-08-12 08:59:52.438143 | controller |
2026-08-12 08:59:52.438309 | controller | PLAY [Verify] ******************************************************************
2026-08-12 08:59:52.438511 | controller |
2026-08-12 08:59:52.438718 | controller | TASK [Gathering Facts] *********************************************************
2026-08-12 08:59:52.438951 | controller | Wednesday 12 August 2026  08:59:52 +0000 (0:00:00.010)       0:00:00.010 ******
2026-08-12 08:59:53.513899 | 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-12 08:59:53.518066 | controller | ok: [controller]
2026-08-12 08:59:53.518169 | controller |
2026-08-12 08:59:53.518345 | controller | TASK [Get the current Helm version] ********************************************
2026-08-12 08:59:53.518454 | controller | Wednesday 12 August 2026  08:59:53 +0000 (0:00:01.079)       0:00:01.090 ******
2026-08-12 08:59:54.048344 | controller | changed: [controller]
2026-08-12 08:59:54.048519 | controller |
2026-08-12 08:59:54.048766 | controller | TASK [Assert Helm version] *****************************************************
2026-08-12 08:59:54.048992 | controller | Wednesday 12 August 2026  08:59:54 +0000 (0:00:00.530)       0:00:01.620 ******
2026-08-12 08:59:54.078443 | controller | ok: [controller] => {
2026-08-12 08:59:54.078712 | controller |     "changed": false,
2026-08-12 08:59:54.078849 | controller |     "msg": "All assertions passed"
2026-08-12 08:59:54.078991 | controller | }
2026-08-12 08:59:54.079108 | controller |
2026-08-12 08:59:54.079244 | controller | PLAY RECAP *********************************************************************
2026-08-12 08:59:54.079398 | controller | controller                 : ok=3    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-08-12 08:59:54.079515 | controller |
2026-08-12 08:59:54.079628 | controller |
2026-08-12 08:59:54.079783 | controller | TASKS RECAP ********************************************************************
2026-08-12 08:59:54.079959 | controller | Wednesday 12 August 2026  08:59:54 +0000 (0:00:00.030)       0:00:01.651 ******
2026-08-12 08:59:54.080085 | controller | ===============================================================================
2026-08-12 08:59:54.080211 | controller | Gathering Facts --------------------------------------------------------- 1.08s
2026-08-12 08:59:54.080336 | controller | Get the current Helm version -------------------------------------------- 0.53s
2026-08-12 08:59:54.080463 | controller | Assert Helm version ----------------------------------------------------- 0.03s
2026-08-12 08:59:54.182048 | controller | INFO     [helm > verify] Executed: Successful
2026-08-12 08:59:54.194431 | controller | INFO     [helm > cleanup] Executing
2026-08-12 08:59:54.194752 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-12 08:59:54.200164 | controller | INFO     [helm > destroy] Executing
2026-08-12 08:59:54.200236 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-08-12 08:59:54.200306 | controller | INFO     [helm > destroy] Executed: Successful
2026-08-12 08:59:54.200567 | controller | WARNING  Molecule executed 1 scenario (1 missing files)
2026-08-12 08:59:54.799927 | controller | ok: Runtime: 0:01:00.254911
2026-08-12 08:59:54.804966 | 
2026-08-12 08:59:54.805017 | PLAY RECAP
2026-08-12 08:59:54.805062 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-12 08:59:54.805085 | 
2026-08-12 08:59:54.928301 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-12 08:59:54.931265 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-08-12 08:59:55.516706 | 
2026-08-12 08:59:55.516832 | PLAY [all]
2026-08-12 08:59:55.527540 | 
2026-08-12 08:59:55.527653 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-08-12 08:59:55.908905 | controller | changed
2026-08-12 08:59:55.914809 | 
2026-08-12 08:59:55.914997 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-08-12 08:59:56.318757 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-12 08:59:56.319003 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-12 08:59:56.324588 | controller | environment: line 1: kubectl: command not found
2026-08-12 08:59:56.326407 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-12 08:59:56.327869 | controller | environment: line 1: kubectl: command not found
2026-08-12 08:59:56.329472 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-12 08:59:56.331942 | controller | environment: line 1: kubectl: command not found
2026-08-12 08:59:56.334461 | controller | environment: line 1: kubectl: command not found
2026-08-12 08:59:56.334474 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-12 08:59:56.335821 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-12 08:59:56.339813 | controller | environment: line 1: kubectl: command not found
2026-08-12 08:59:56.340950 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-12 08:59:56.446685 | controller | ok: Runtime: 0:00:00.034453
2026-08-12 08:59:56.451977 | 
2026-08-12 08:59:56.452044 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-08-12 08:59:56.681421 | controller | changed
2026-08-12 08:59:56.831660 | 
2026-08-12 08:59:56.831812 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-08-12 08:59:57.081593 | controller | environment: line 5: kubectl: command not found
2026-08-12 08:59:57.082042 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-12 08:59:57.082596 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-12 08:59:57.083100 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-12 08:59:57.364984 | controller | ok: Runtime: 0:00:00.011826
2026-08-12 08:59:57.372245 | 
2026-08-12 08:59:57.372342 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-08-12 08:59:58.027519 | controller | changed:
2026-08-12 08:59:58.027805 | controller | created directory /var/lib/zuul/builds/3216daca38b84208957fab3ea2756336/work/logs/controller
2026-08-12 08:59:58.027873 | controller | cd+++++++++ objects/
2026-08-12 08:59:58.027924 | controller | cd+++++++++ objects/cluster/
2026-08-12 08:59:58.027976 | controller | cd+++++++++ objects/namespaced/
2026-08-12 08:59:58.036575 | 
2026-08-12 08:59:58.036643 | TASK [gather-pod-logs : creating directory for pod logs]
2026-08-12 08:59:58.294353 | controller | changed
2026-08-12 08:59:58.300032 | 
2026-08-12 08:59:58.300097 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-08-12 08:59:58.542211 | controller | changed
2026-08-12 08:59:58.549028 | 
2026-08-12 08:59:58.549251 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-08-12 08:59:58.795328 | controller | environment: line 3: kubectl: command not found
2026-08-12 08:59:59.081771 | controller | ok: Runtime: 0:00:00.013341
2026-08-12 08:59:59.088769 | 
2026-08-12 08:59:59.088834 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-08-12 08:59:59.653597 | controller | changed:
2026-08-12 08:59:59.653820 | controller | cd+++++++++ pod-logs/
2026-08-12 08:59:59.653864 | controller | cd+++++++++ pod-logs/failed-pods/
2026-08-12 08:59:59.662928 | 
2026-08-12 08:59:59.662995 | LOOP [helm-release-status : creating directory for helm release status]
2026-08-12 08:59:59.901488 | controller | changed: "values"
2026-08-12 09:00:00.126239 | controller | changed: "releases"
2026-08-12 09:00:00.136161 | 
2026-08-12 09:00:00.136323 | TASK [helm-release-status : Gather get release status for helm charts]
2026-08-12 09:00:00.394927 | controller | /bin/bash: line 3: kubectl: command not found
2026-08-12 09:00:00.666744 | controller | ok: Runtime: 0:00:00.009587
2026-08-12 09:00:00.671102 | 
2026-08-12 09:00:00.671187 | TASK [helm-release-status : Downloads logs to executor]
2026-08-12 09:00:01.162819 | controller | changed:
2026-08-12 09:00:01.163140 | controller | cd+++++++++ helm/
2026-08-12 09:00:01.163207 | controller | cd+++++++++ helm/releases/
2026-08-12 09:00:01.163262 | controller | cd+++++++++ helm/values/
2026-08-12 09:00:01.169043 | 
2026-08-12 09:00:01.169093 | PLAY RECAP
2026-08-12 09:00:01.169144 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-12 09:00:01.169167 | 
2026-08-12 09:00:01.302592 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-08-12 09:00:01.305489 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-12 09:00:01.922663 | 
2026-08-12 09:00:01.922805 | PLAY [all]
2026-08-12 09:00:01.932925 | 
2026-08-12 09:00:01.933022 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-12 09:00:01.978968 | controller | skipping: Conditional result was False
2026-08-12 09:00:01.989145 | 
2026-08-12 09:00:01.989493 | TASK [fetch-output : Set log path for single node]
2026-08-12 09:00:02.034518 | controller | ok
2026-08-12 09:00:02.039537 | 
2026-08-12 09:00:02.039648 | LOOP [fetch-output : Ensure local output dirs]
2026-08-12 09:00:02.413060 | controller -> localhost | ok: "/var/lib/zuul/builds/3216daca38b84208957fab3ea2756336/work/logs"
2026-08-12 09:00:02.647140 | controller -> localhost | changed: "/var/lib/zuul/builds/3216daca38b84208957fab3ea2756336/work/artifacts"
2026-08-12 09:00:02.867369 | controller -> localhost | changed: "/var/lib/zuul/builds/3216daca38b84208957fab3ea2756336/work/docs"
2026-08-12 09:00:02.878970 | 
2026-08-12 09:00:02.879050 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-12 09:00:03.492070 | controller | changed: .d..t...... ./
2026-08-12 09:00:03.492302 | controller | changed: All items complete
2026-08-12 09:00:03.492332 | 
2026-08-12 09:00:03.944002 | controller | changed: .d..t...... ./
2026-08-12 09:00:04.379342 | controller | changed: .d..t...... ./
2026-08-12 09:00:04.392371 | 
2026-08-12 09:00:04.392463 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-12 09:00:04.815611 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007680
2026-08-12 09:00:05.055997 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008091
2026-08-12 09:00:05.074614 | 
2026-08-12 09:00:05.074672 | PLAY [all]
2026-08-12 09:00:05.084074 | 
2026-08-12 09:00:05.084209 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-12 09:00:05.557423 | controller | changed
2026-08-12 09:00:05.569111 | 
2026-08-12 09:00:05.569198 | PLAY RECAP
2026-08-12 09:00:05.569262 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-12 09:00:05.569294 | 
2026-08-12 09:00:05.716222 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-12 09:00:05.719008 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-12 09:00:06.314778 | 
2026-08-12 09:00:06.314907 | PLAY [localhost]
2026-08-12 09:00:06.324636 | 
2026-08-12 09:00:06.324709 | TASK [Generate Zuul manifest]
2026-08-12 09:00:06.344101 | localhost | ok
2026-08-12 09:00:06.360285 | 
2026-08-12 09:00:06.360368 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-12 09:00:06.727884 | localhost | changed
2026-08-12 09:00:06.741438 | 
2026-08-12 09:00:06.741519 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-12 09:00:06.774506 | localhost | ok
2026-08-12 09:00:06.781735 | 
2026-08-12 09:00:06.781796 | TASK [Upload logs]
2026-08-12 09:00:06.802637 | localhost | ok
2026-08-12 09:00:06.861297 | 
2026-08-12 09:00:06.861447 | TASK [Set zuul-log-path fact]
2026-08-12 09:00:06.881195 | localhost | ok
2026-08-12 09:00:06.894151 | 
2026-08-12 09:00:06.894227 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-12 09:00:06.926099 | localhost | ok
2026-08-12 09:00:06.934296 | 
2026-08-12 09:00:06.934367 | TASK [upload-logs : Create log directories]
2026-08-12 09:00:07.339247 | localhost | changed
2026-08-12 09:00:07.346498 | 
2026-08-12 09:00:07.346641 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-12 09:00:07.750811 | localhost -> localhost | ok: Runtime: 0:00:00.005653
2026-08-12 09:00:07.755835 | 
2026-08-12 09:00:07.755902 | TASK [upload-logs : Upload logs to log server]
2026-08-12 09:00:08.206600 | localhost | Output suppressed because no_log was given
2026-08-12 09:00:08.212354 | 
2026-08-12 09:00:08.212429 | LOOP [upload-logs : Compress console log and json output]
2026-08-12 09:00:08.258986 | localhost | skipping: Conditional result was False
2026-08-12 09:00:08.307461 | localhost | skipping: Conditional result was False
2026-08-12 09:00:08.320545 | 
2026-08-12 09:00:08.320785 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-12 09:00:08.361643 | localhost | skipping: Conditional result was False
2026-08-12 09:00:08.362061 | 
2026-08-12 09:00:08.365856 | localhost | skipping: Conditional result was False
2026-08-12 09:00:08.376921 | 
2026-08-12 09:00:08.377109 | LOOP [upload-logs : Upload console log and json output]