2026-07-27 17:59:47.403615 | Job console starting
2026-07-27 17:59:47.421595 | Updating git repos
2026-07-27 17:59:47.489661 | Cloning repos into workspace
2026-07-27 17:59:47.710001 | Restoring repo states
2026-07-27 17:59:47.725344 | Merging changes
2026-07-27 17:59:48.672281 | Checking out repos
2026-07-27 17:59:48.719652 | Preparing playbooks
2026-07-27 17:59:52.123448 | Running Ansible setup
2026-07-27 17:59:55.704727 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-27 17:59:56.327690 | 
2026-07-27 17:59:56.327864 | PLAY [localhost]
2026-07-27 17:59:56.341967 | 
2026-07-27 17:59:56.342618 | TASK [Gathering Facts]
2026-07-27 17:59:57.202818 | localhost | ok
2026-07-27 17:59:57.213640 | 
2026-07-27 17:59:57.217175 | TASK [Setup log path fact]
2026-07-27 17:59:57.249387 | localhost | ok
2026-07-27 17:59:57.261194 | 
2026-07-27 17:59:57.261319 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-27 17:59:57.292277 | localhost | ok
2026-07-27 17:59:57.301269 | 
2026-07-27 17:59:57.301347 | TASK [emit-job-header : Print job information]
2026-07-27 17:59:57.342634 | # Job Information
2026-07-27 17:59:57.342899 | Ansible Version: 2.16.18
2026-07-27 17:59:57.342968 | Job: ansible-collection-kubernetes-molecule-helm
2026-07-27 17:59:57.343019 | Pipeline: check
2026-07-27 17:59:57.343065 | Executor: 2d72f0692154
2026-07-27 17:59:57.343109 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/296
2026-07-27 17:59:57.343162 | Event ID: 050330c0-89e4-11f1-92a2-b9ce5e41e44b
2026-07-27 17:59:57.348116 | 
2026-07-27 17:59:57.348209 | LOOP [emit-job-header : Print node information]
2026-07-27 17:59:57.442057 | localhost | ok:
2026-07-27 17:59:57.442360 | localhost | # Node Information
2026-07-27 17:59:57.442424 | localhost | Inventory Hostname: controller
2026-07-27 17:59:57.442476 | localhost | Hostname: np0000187054
2026-07-27 17:59:57.442523 | localhost | Username: zuul
2026-07-27 17:59:57.442572 | localhost | Distro: Debian 13.6
2026-07-27 17:59:57.442618 | localhost | Provider: yul1
2026-07-27 17:59:57.442662 | localhost | Region: ca-ymq-1
2026-07-27 17:59:57.442705 | localhost | Label: debian-trixie
2026-07-27 17:59:57.442748 | localhost | Product Name: OpenStack Nova
2026-07-27 17:59:57.442808 | localhost | Interface IP: 199.204.45.162
2026-07-27 17:59:57.458373 | 
2026-07-27 17:59:57.458536 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-07-27 17:59:57.930420 | localhost -> localhost | changed
2026-07-27 17:59:57.937969 | 
2026-07-27 17:59:57.938074 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-07-27 17:59:58.854631 | localhost -> localhost | changed
2026-07-27 17:59:58.868835 | 
2026-07-27 17:59:58.868991 | PLAY [all]
2026-07-27 17:59:58.876105 | 
2026-07-27 17:59:58.876176 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-07-27 17:59:59.113086 | controller -> localhost | ok
2026-07-27 17:59:59.121246 | 
2026-07-27 17:59:59.121331 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-07-27 17:59:59.153885 | controller | ok
2026-07-27 17:59:59.171299 | controller | included: /var/lib/zuul/builds/e01d217ab31d4178a19f2650445dfc03/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-07-27 17:59:59.175841 | 
2026-07-27 17:59:59.175900 | TASK [add-build-sshkey : Create Temp SSH key]
2026-07-27 18:00:00.114568 | controller -> localhost | Generating public/private rsa key pair.
2026-07-27 18:00:00.114773 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/e01d217ab31d4178a19f2650445dfc03/work/e01d217ab31d4178a19f2650445dfc03_id_rsa
2026-07-27 18:00:00.114816 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/e01d217ab31d4178a19f2650445dfc03/work/e01d217ab31d4178a19f2650445dfc03_id_rsa.pub
2026-07-27 18:00:00.114848 | controller -> localhost | The key fingerprint is:
2026-07-27 18:00:00.114878 | controller -> localhost | SHA256:i9iDA7kusbl2yI1cdyyNUrR9nrtGVEuNfZBmyEQGPCw zuul-build-sshkey
2026-07-27 18:00:00.114921 | controller -> localhost | The key's randomart image is:
2026-07-27 18:00:00.114952 | controller -> localhost | +---[RSA 3072]----+
2026-07-27 18:00:00.114989 | controller -> localhost | |        o.=+=.o  |
2026-07-27 18:00:00.115021 | controller -> localhost | |     . E +.* * . |
2026-07-27 18:00:00.115049 | controller -> localhost | |    . o . + + .  |
2026-07-27 18:00:00.115077 | controller -> localhost | |   . o . o .     |
2026-07-27 18:00:00.115105 | controller -> localhost | |  o . + S .      |
2026-07-27 18:00:00.115132 | controller -> localhost | |.  = B = =       |
2026-07-27 18:00:00.115160 | controller -> localhost | |o+* * * o .      |
2026-07-27 18:00:00.115187 | controller -> localhost | |+B o . . o       |
2026-07-27 18:00:00.115218 | controller -> localhost | |ooo     ...      |
2026-07-27 18:00:00.115246 | controller -> localhost | +----[SHA256]-----+
2026-07-27 18:00:00.115311 | controller -> localhost | ok: Runtime: 0:00:00.516586
2026-07-27 18:00:00.121644 | 
2026-07-27 18:00:00.121756 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-07-27 18:00:00.153122 | controller | ok
2026-07-27 18:00:00.164202 | controller | included: /var/lib/zuul/builds/e01d217ab31d4178a19f2650445dfc03/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-07-27 18:00:00.172053 | 
2026-07-27 18:00:00.172225 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-07-27 18:00:00.197866 | controller | skipping: Conditional result was False
2026-07-27 18:00:00.206523 | 
2026-07-27 18:00:00.206689 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-07-27 18:00:00.756485 | controller | changed
2026-07-27 18:00:00.761897 | 
2026-07-27 18:00:00.761983 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-07-27 18:00:00.965271 | controller | ok
2026-07-27 18:00:00.970623 | 
2026-07-27 18:00:00.970706 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-07-27 18:00:01.527630 | controller | changed
2026-07-27 18:00:01.533860 | 
2026-07-27 18:00:01.533996 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-07-27 18:00:02.052197 | controller | changed
2026-07-27 18:00:02.058331 | 
2026-07-27 18:00:02.058420 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-07-27 18:00:02.082912 | controller | skipping: Conditional result was False
2026-07-27 18:00:02.092599 | 
2026-07-27 18:00:02.092690 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-07-27 18:00:02.471563 | controller -> localhost | changed
2026-07-27 18:00:02.484659 | 
2026-07-27 18:00:02.484806 | TASK [add-build-sshkey : Add back temp key]
2026-07-27 18:00:02.755702 | controller -> localhost | Identity added: /var/lib/zuul/builds/e01d217ab31d4178a19f2650445dfc03/work/e01d217ab31d4178a19f2650445dfc03_id_rsa (zuul-build-sshkey)
2026-07-27 18:00:02.755901 | controller -> localhost | ok: Runtime: 0:00:00.012465
2026-07-27 18:00:02.760240 | 
2026-07-27 18:00:02.760301 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-07-27 18:00:03.086421 | controller | ok
2026-07-27 18:00:03.091758 | 
2026-07-27 18:00:03.091845 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-07-27 18:00:03.117187 | controller | skipping: Conditional result was False
2026-07-27 18:00:03.132148 | 
2026-07-27 18:00:03.132265 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-07-27 18:00:03.518530 | controller | ok
2026-07-27 18:00:03.524634 | 
2026-07-27 18:00:03.524708 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-07-27 18:00:04.413803 | controller | Output suppressed because no_log was given
2026-07-27 18:00:04.421556 | 
2026-07-27 18:00:04.421622 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-07-27 18:00:04.645504 | controller | ok: "logs"
2026-07-27 18:00:04.873138 | controller | ok: All items complete
2026-07-27 18:00:04.873295 | 
2026-07-27 18:00:04.882085 | controller | ok: "artifacts"
2026-07-27 18:00:05.022745 | controller | ok: "docs"
2026-07-27 18:00:05.036158 | 
2026-07-27 18:00:05.036277 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-07-27 18:00:05.279151 | controller | changed: "logs"
2026-07-27 18:00:05.476875 | controller | changed: "artifacts"
2026-07-27 18:00:05.661102 | controller | changed: "docs"
2026-07-27 18:00:05.679915 | 
2026-07-27 18:00:05.680017 | PLAY RECAP
2026-07-27 18:00:05.680065 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-07-27 18:00:05.680094 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-27 18:00:05.680119 | 
2026-07-27 18:00:05.798004 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-27 18:00:05.799839 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-27 18:00:06.419753 | 
2026-07-27 18:00:06.419881 | PLAY [all]
2026-07-27 18:00:06.429897 | 
2026-07-27 18:00:06.429987 | TASK [setup-uv : Extract archive]
2026-07-27 18:00:08.796476 | controller | changed
2026-07-27 18:00:08.802653 | 
2026-07-27 18:00:08.802738 | TASK [setup-uv : Print version]
2026-07-27 18:00:09.169034 | controller | uv 0.8.13
2026-07-27 18:00:09.336748 | controller | ok: Runtime: 0:00:00.014167
2026-07-27 18:00:09.347131 | 
2026-07-27 18:00:09.347277 | TASK [Install Ansible collection siblings]
2026-07-27 18:00:09.754901 | controller | ok:
2026-07-27 18:00:09.820735 | controller | Skipping ansible.posix: no checked-out sibling
2026-07-27 18:00:09.820859 | controller | Skipping community.crypto: no checked-out sibling
2026-07-27 18:00:09.820903 | controller | Skipping community.general: no checked-out sibling
2026-07-27 18:00:09.820936 | controller | Skipping kubernetes.core: no checked-out sibling
2026-07-27 18:00:09.820991 | controller | Skipping vexxhost.containers: no checked-out sibling
2026-07-27 18:00:09.823480 | 
2026-07-27 18:00:09.823566 | PLAY RECAP
2026-07-27 18:00:09.823631 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-27 18:00:09.823664 | 
2026-07-27 18:00:09.965579 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-27 18:00:09.967573 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-07-27 18:00:10.547079 | 
2026-07-27 18:00:10.547669 | PLAY [all]
2026-07-27 18:00:10.558542 | 
2026-07-27 18:00:10.558620 | TASK [Update apt cache]
2026-07-27 18:00:15.397963 | controller | ok
2026-07-27 18:00:15.403694 | 
2026-07-27 18:00:15.403802 | TASK [Disable firewalld]
2026-07-27 18:00:15.430518 | controller | skipping: Conditional result was False
2026-07-27 18:00:15.439408 | 
2026-07-27 18:00:15.439504 | TASK [Install "jq" for log collection]
2026-07-27 18:00:17.647965 | controller | changed
2026-07-27 18:00:17.650056 | 
2026-07-27 18:00:17.650112 | PLAY RECAP
2026-07-27 18:00:17.650167 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-27 18:00:17.650197 | 
2026-07-27 18:00:17.815415 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-07-27 18:00:17.818498 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-07-27 18:00:18.395990 | 
2026-07-27 18:00:18.396389 | PLAY [all]
2026-07-27 18:00:18.406394 | 
2026-07-27 18:00:18.406466 | LOOP [Check inventory directories]
2026-07-27 18:00:18.757059 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-07-27 18:00:18.934939 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/helm/inventory"
2026-07-27 18:00:19.119942 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/inventory"
2026-07-27 18:00:19.138344 | 
2026-07-27 18:00:19.138548 | LOOP [Copy inventory file for Zuul]
2026-07-27 18:00:19.993722 | controller | changed:
2026-07-27 18:00:19.993889 | controller | {
2026-07-27 18:00:19.993917 | controller |   "ansible_loop_var": "item",
2026-07-27 18:00:19.993940 | controller |   "changed": false,
2026-07-27 18:00:19.993960 | controller |   "failed": false,
2026-07-27 18:00:19.993982 | controller |   "invocation": {
2026-07-27 18:00:19.994001 | controller |     "module_args": {
2026-07-27 18:00:19.994019 | controller |       "checksum_algorithm": "sha1",
2026-07-27 18:00:19.994038 | controller |       "follow": true,
2026-07-27 18:00:19.994056 | controller |       "get_attributes": true,
2026-07-27 18:00:19.994075 | controller |       "get_checksum": true,
2026-07-27 18:00:19.994093 | controller |       "get_mime": true,
2026-07-27 18:00:19.994110 | controller |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-07-27 18:00:19.994128 | controller |     }
2026-07-27 18:00:19.994146 | controller |   },
2026-07-27 18:00:19.994163 | controller |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-07-27 18:00:19.994180 | controller |   "stat": {
2026-07-27 18:00:19.994197 | controller |     "atime": 1785175204.2844615,
2026-07-27 18:00:19.994215 | controller |     "attr_flags": "e",
2026-07-27 18:00:19.994233 | controller |     "attributes": [
2026-07-27 18:00:19.994250 | controller |       "extents"
2026-07-27 18:00:19.994267 | controller |     ],
2026-07-27 18:00:19.994284 | controller |     "block_size": 4096,
2026-07-27 18:00:19.994301 | controller |     "blocks": 8,
2026-07-27 18:00:19.994318 | controller |     "charset": "unknown",
2026-07-27 18:00:19.994337 | controller |     "ctime": 1785175204.3044686,
2026-07-27 18:00:19.994355 | controller |     "dev": 65025,
2026-07-27 18:00:19.994524 | controller |     "device_type": 0,
2026-07-27 18:00:19.994568 | controller |     "executable": true,
2026-07-27 18:00:19.994591 | controller |     "exists": true,
2026-07-27 18:00:19.994611 | controller |     "gid": 1000,
2026-07-27 18:00:19.994629 | controller |     "gr_name": "zuul",
2026-07-27 18:00:19.994647 | controller |     "inode": 518935,
2026-07-27 18:00:19.994665 | controller |     "isblk": false,
2026-07-27 18:00:19.994684 | controller |     "ischr": false,
2026-07-27 18:00:19.994702 | controller |     "isdir": true,
2026-07-27 18:00:19.994719 | controller |     "isfifo": false,
2026-07-27 18:00:19.994736 | controller |     "isgid": false,
2026-07-27 18:00:19.994753 | controller |     "islnk": false,
2026-07-27 18:00:19.994770 | controller |     "isreg": false,
2026-07-27 18:00:19.994786 | controller |     "issock": false,
2026-07-27 18:00:19.994804 | controller |     "isuid": false,
2026-07-27 18:00:19.994821 | controller |     "mimetype": "unknown",
2026-07-27 18:00:19.994838 | controller |     "mode": "0755",
2026-07-27 18:00:19.994855 | controller |     "mtime": 1785175187.8328168,
2026-07-27 18:00:19.994872 | controller |     "nlink": 14,
2026-07-27 18:00:19.994889 | controller |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-07-27 18:00:19.994921 | controller |     "pw_name": "zuul",
2026-07-27 18:00:19.994945 | controller |     "readable": true,
2026-07-27 18:00:19.994964 | controller |     "rgrp": true,
2026-07-27 18:00:19.994981 | controller |     "roth": true,
2026-07-27 18:00:19.994998 | controller |     "rusr": true,
2026-07-27 18:00:19.995016 | controller |     "size": 4096,
2026-07-27 18:00:19.995033 | controller |     "uid": 1000,
2026-07-27 18:00:19.995051 | controller |     "version": "3107792064",
2026-07-27 18:00:19.995069 | controller |     "wgrp": false,
2026-07-27 18:00:19.995088 | controller |     "woth": false,
2026-07-27 18:00:19.995107 | controller |     "writeable": true,
2026-07-27 18:00:19.995125 | controller |     "wusr": true,
2026-07-27 18:00:19.995142 | controller |     "xgrp": true,
2026-07-27 18:00:19.995159 | controller |     "xoth": true,
2026-07-27 18:00:19.995176 | controller |     "xusr": true
2026-07-27 18:00:19.995192 | controller |   }
2026-07-27 18:00:19.995219 | controller | }
2026-07-27 18:00:19.995258 | controller | ok: All items complete
2026-07-27 18:00:19.995280 | 
2026-07-27 18:00:19.996563 | controller | skipping: Conditional result was False
2026-07-27 18:00:19.997157 | controller | skipping: Conditional result was False
2026-07-27 18:00:20.011420 | 
2026-07-27 18:00:20.011529 | LOOP [Switch "ansible_host" to private IP]
2026-07-27 18:00:20.401390 | controller | changed: 1 replacements made
2026-07-27 18:00:20.402829 | controller | skipping: Conditional result was False
2026-07-27 18:00:20.404968 | controller | skipping: Conditional result was False
2026-07-27 18:00:20.424046 | 
2026-07-27 18:00:20.424244 | TASK [Run Molecule scenario]
2026-07-27 18:00:20.843117 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-07-27 18:00:20.843236 | controller | Creating virtual environment at: .venv
2026-07-27 18:00:21.136544 | controller | Downloading ansible-core (2.3MiB)
2026-07-27 18:00:21.139073 | controller | Downloading cryptography (4.3MiB)
2026-07-27 18:00:21.139374 | controller | Downloading pygments (1.2MiB)
2026-07-27 18:00:21.315439 | controller |  Downloading cryptography
2026-07-27 18:00:21.361387 | controller |  Downloading pygments
2026-07-27 18:00:21.540230 | controller |  Downloading ansible-core
2026-07-27 18:00:21.588504 | controller | Installed 36 packages in 47ms
2026-07-27 18:00:22.327942 | controller | INFO     Collection 'vexxhost.kubernetes' detected.
2026-07-27 18:00:22.328011 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-07-27 18:00:23.283818 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-07-27 18:00:23.283875 | controller | INFO     default scenario not found, disabling shared state.
2026-07-27 18:00:23.283939 | controller | INFO     [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-07-27 18:00:23.284019 | controller | INFO     [helm > prerun] Performing prerun with role_name_check=0...
2026-07-27 18:02:19.994603 | controller | INFO     [helm > dependency] Executing
2026-07-27 18:02:19.994732 | controller | WARNING  [helm > dependency] Missing roles requirements file: requirements.yml
2026-07-27 18:02:19.994996 | controller | WARNING  [helm > dependency] Missing collections requirements file: collections.yml
2026-07-27 18:02:19.995182 | controller | WARNING  [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-07-27 18:02:20.000989 | controller | INFO     [helm > cleanup] Executing
2026-07-27 18:02:20.001362 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-27 18:02:20.006990 | controller | INFO     [helm > destroy] Executing
2026-07-27 18:02:20.007016 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-07-27 18:02:20.007122 | controller | INFO     [helm > destroy] Executed: Successful
2026-07-27 18:02:20.012683 | controller | INFO     [helm > syntax] Executing
2026-07-27 18:02:20.902626 | controller |
2026-07-27 18:02:20.902848 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml
2026-07-27 18:02:20.966214 | controller | INFO     [helm > syntax] Executed: Successful
2026-07-27 18:02:20.977498 | controller | INFO     [helm > create] Executing
2026-07-27 18:02:20.979590 | controller | WARNING  [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-27 18:02:20.986855 | controller | INFO     [helm > prepare] Executing
2026-07-27 18:02:21.838368 | controller |
2026-07-27 18:02:21.838475 | controller | PLAY [Prepare] *****************************************************************
2026-07-27 18:02:21.838666 | controller |
2026-07-27 18:02:21.838884 | controller | TASK [Gathering Facts] *********************************************************
2026-07-27 18:02:21.839120 | controller | Monday 27 July 2026  18:02:21 +0000 (0:00:00.032)       0:00:00.032 ***********
2026-07-27 18:02:23.067157 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information.
2026-07-27 18:02:23.073547 | controller | ok: [controller]
2026-07-27 18:02:23.073738 | controller |
2026-07-27 18:02:23.073950 | controller | TASK [Install Debian packages] *************************************************
2026-07-27 18:02:23.074165 | controller | Monday 27 July 2026  18:02:23 +0000 (0:00:01.235)       0:00:01.267 ***********
2026-07-27 18:02:24.540910 | controller | ok: [controller]
2026-07-27 18:02:24.541074 | controller |
2026-07-27 18:02:24.541277 | controller | PLAY RECAP *********************************************************************
2026-07-27 18:02:24.541493 | controller | controller                 : ok=2    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-27 18:02:24.541667 | controller |
2026-07-27 18:02:24.541838 | controller |
2026-07-27 18:02:24.542022 | controller | TASKS RECAP ********************************************************************
2026-07-27 18:02:24.542218 | controller | Monday 27 July 2026  18:02:24 +0000 (0:00:01.467)       0:00:02.735 ***********
2026-07-27 18:02:24.542391 | controller | ===============================================================================
2026-07-27 18:02:24.542595 | controller | Install Debian packages ------------------------------------------------- 1.47s
2026-07-27 18:02:24.542772 | controller | Gathering Facts --------------------------------------------------------- 1.24s
2026-07-27 18:02:24.694682 | controller | INFO     [helm > prepare] Executed: Successful
2026-07-27 18:02:24.705848 | controller | INFO     [helm > converge] Executing
2026-07-27 18:02:25.577166 | controller |
2026-07-27 18:02:25.577385 | controller | PLAY [Converge] ****************************************************************
2026-07-27 18:02:25.577589 | controller |
2026-07-27 18:02:25.577835 | controller | TASK [Gathering Facts] *********************************************************
2026-07-27 18:02:25.578062 | controller | Monday 27 July 2026  18:02:25 +0000 (0:00:00.015)       0:00:00.015 ***********
2026-07-27 18:02:26.695974 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information.
2026-07-27 18:02:26.701248 | controller | ok: [controller]
2026-07-27 18:02:26.701447 | controller |
2026-07-27 18:02:26.701672 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-07-27 18:02:26.701890 | controller | Monday 27 July 2026  18:02:26 +0000 (0:00:01.124)       0:00:01.139 ***********
2026-07-27 18:02:27.215129 | controller | ok: [controller]
2026-07-27 18:02:27.215152 | controller |
2026-07-27 18:02:27.215157 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-07-27 18:02:27.215161 | controller | Monday 27 July 2026  18:02:27 +0000 (0:00:00.512)       0:00:01.651 ***********
2026-07-27 18:02:28.470535 | controller | ok: [controller]
2026-07-27 18:02:28.470688 | controller |
2026-07-27 18:02:28.470910 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-07-27 18:02:28.471143 | controller | Monday 27 July 2026  18:02:28 +0000 (0:00:01.256)       0:00:02.908 ***********
2026-07-27 18:02:28.906969 | controller | changed: [controller]
2026-07-27 18:02:28.907165 | controller |
2026-07-27 18:02:28.907418 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-07-27 18:02:28.907592 | controller | Monday 27 July 2026  18:02:28 +0000 (0:00:00.436)       0:00:03.344 ***********
2026-07-27 18:02:28.962582 | controller | ok: [controller] => {
2026-07-27 18:02:28.962794 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-07-27 18:02:28.963012 | controller | }
2026-07-27 18:02:28.963282 | controller |
2026-07-27 18:02:28.963499 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-07-27 18:02:28.963689 | controller | Monday 27 July 2026  18:02:28 +0000 (0:00:00.055)       0:00:03.400 ***********
2026-07-27 18:02:29.831282 | controller | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created with a mode of 0700, this may cause issues when running as another user. To avoid this, create the remote_tmp dir with the correct permissions manually
2026-07-27 18:02:29.840846 | controller | changed: [controller]
2026-07-27 18:02:29.841028 | controller |
2026-07-27 18:02:29.841231 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-07-27 18:02:29.841426 | controller | Monday 27 July 2026  18:02:29 +0000 (0:00:00.878)       0:00:04.278 ***********
2026-07-27 18:02:32.028699 | controller | changed: [controller]
2026-07-27 18:02:32.028821 | controller |
2026-07-27 18:02:32.029039 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-07-27 18:02:32.029235 | controller | Monday 27 July 2026  18:02:32 +0000 (0:00:02.187)       0:00:06.466 ***********
2026-07-27 18:02:32.571900 | controller | ok: [controller]
2026-07-27 18:02:32.572045 | controller |
2026-07-27 18:02:32.572266 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-07-27 18:02:32.572466 | controller | Monday 27 July 2026  18:02:32 +0000 (0:00:00.543)       0:00:07.009 ***********
2026-07-27 18:02:33.057246 | controller | changed: [controller]
2026-07-27 18:02:33.057367 | controller |
2026-07-27 18:02:33.057582 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-07-27 18:02:33.057779 | controller | Monday 27 July 2026  18:02:33 +0000 (0:00:00.485)       0:00:07.494 ***********
2026-07-27 18:02:33.531026 | controller | ok: [controller]
2026-07-27 18:02:33.531280 | controller |
2026-07-27 18:02:33.531511 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-07-27 18:02:33.531688 | controller | Monday 27 July 2026  18:02:33 +0000 (0:00:00.473)       0:00:07.968 ***********
2026-07-27 18:02:33.845385 | controller | changed: [controller]
2026-07-27 18:02:33.845535 | controller |
2026-07-27 18:02:33.845751 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-07-27 18:02:33.845940 | controller | Monday 27 July 2026  18:02:33 +0000 (0:00:00.314)       0:00:08.282 ***********
2026-07-27 18:02:34.163711 | controller | ok: [controller]
2026-07-27 18:02:34.163837 | controller |
2026-07-27 18:02:34.164014 | controller | TASK [Install plugin] **********************************************************
2026-07-27 18:02:34.164146 | controller | Monday 27 July 2026  18:02:34 +0000 (0:00:00.315)       0:00:08.598 ***********
2026-07-27 18:02:34.223496 | controller | included: vexxhost.containers.download_artifact for controller
2026-07-27 18:02:34.223511 | controller |
2026-07-27 18:02:34.223515 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-07-27 18:02:34.223519 | controller | Monday 27 July 2026  18:02:34 +0000 (0:00:00.062)       0:00:08.661 ***********
2026-07-27 18:02:34.278169 | controller | ok: [controller] => {
2026-07-27 18:02:34.278193 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-07-27 18:02:34.278199 | controller | }
2026-07-27 18:02:34.278205 | controller |
2026-07-27 18:02:34.278211 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-07-27 18:02:34.278217 | controller | Monday 27 July 2026  18:02:34 +0000 (0:00:00.054)       0:00:08.715 ***********
2026-07-27 18:02:35.167848 | controller | changed: [controller]
2026-07-27 18:02:35.167873 | controller |
2026-07-27 18:02:35.167878 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-07-27 18:02:35.167882 | controller | Monday 27 July 2026  18:02:35 +0000 (0:00:00.888)       0:00:09.603 ***********
2026-07-27 18:02:37.249910 | controller | changed: [controller]
2026-07-27 18:02:37.250372 | controller |
2026-07-27 18:02:37.250628 | controller | PLAY RECAP *********************************************************************
2026-07-27 18:02:37.250898 | controller | controller                 : ok=16   changed=7    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-27 18:02:37.251184 | controller |
2026-07-27 18:02:37.251430 | controller |
2026-07-27 18:02:37.251672 | controller | TASKS RECAP ********************************************************************
2026-07-27 18:02:37.251919 | controller | Monday 27 July 2026  18:02:37 +0000 (0:00:02.084)       0:00:11.688 ***********
2026-07-27 18:02:37.252153 | controller | ===============================================================================
2026-07-27 18:02:37.252399 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.19s
2026-07-27 18:02:37.252647 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.08s
2026-07-27 18:02:37.252898 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.26s
2026-07-27 18:02:37.253142 | controller | Gathering Facts --------------------------------------------------------- 1.12s
2026-07-27 18:02:37.253384 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.89s
2026-07-27 18:02:37.253629 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.88s
2026-07-27 18:02:37.253943 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.54s
2026-07-27 18:02:37.254189 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.51s
2026-07-27 18:02:37.254432 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.49s
2026-07-27 18:02:37.254676 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.47s
2026-07-27 18:02:37.254916 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.44s
2026-07-27 18:02:37.255116 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.32s
2026-07-27 18:02:37.255306 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.31s
2026-07-27 18:02:37.255471 | controller | Install plugin ---------------------------------------------------------- 0.06s
2026-07-27 18:02:37.255627 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.06s
2026-07-27 18:02:37.255780 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-07-27 18:02:37.412880 | controller | INFO     [helm > converge] Executed: Successful
2026-07-27 18:02:37.424677 | controller | INFO     [helm > idempotence] Executing
2026-07-27 18:02:38.326056 | controller |
2026-07-27 18:02:38.326384 | controller | PLAY [Converge] ****************************************************************
2026-07-27 18:02:38.326628 | controller |
2026-07-27 18:02:38.326910 | controller | TASK [Gathering Facts] *********************************************************
2026-07-27 18:02:38.327230 | controller | Monday 27 July 2026  18:02:38 +0000 (0:00:00.015)       0:00:00.015 ***********
2026-07-27 18:02:40.448116 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information.
2026-07-27 18:02:40.452755 | controller | ok: [controller]
2026-07-27 18:02:40.453053 | controller |
2026-07-27 18:02:40.453347 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-07-27 18:02:40.453609 | controller | Monday 27 July 2026  18:02:40 +0000 (0:00:02.127)       0:00:02.143 ***********
2026-07-27 18:02:40.925595 | controller | ok: [controller]
2026-07-27 18:02:40.925890 | controller |
2026-07-27 18:02:40.926159 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-07-27 18:02:40.926417 | controller | Monday 27 July 2026  18:02:40 +0000 (0:00:00.472)       0:00:02.615 ***********
2026-07-27 18:02:42.122899 | controller | ok: [controller]
2026-07-27 18:02:42.123224 | controller |
2026-07-27 18:02:42.123494 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-07-27 18:02:42.123749 | controller | Monday 27 July 2026  18:02:42 +0000 (0:00:01.196)       0:00:03.812 ***********
2026-07-27 18:02:42.608149 | controller | ok: [controller]
2026-07-27 18:02:42.608219 | controller |
2026-07-27 18:02:42.608390 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-07-27 18:02:42.608520 | controller | Monday 27 July 2026  18:02:42 +0000 (0:00:00.483)       0:00:04.295 ***********
2026-07-27 18:02:42.656166 | controller | ok: [controller] => {
2026-07-27 18:02:42.656324 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-07-27 18:02:42.656849 | controller | }
2026-07-27 18:02:42.656879 | controller |
2026-07-27 18:02:42.656886 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-07-27 18:02:42.656892 | controller | Monday 27 July 2026  18:02:42 +0000 (0:00:00.049)       0:00:04.345 ***********
2026-07-27 18:02:43.198290 | controller | ok: [controller]
2026-07-27 18:02:43.198907 | controller |
2026-07-27 18:02:43.198921 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-07-27 18:02:43.198928 | controller | Monday 27 July 2026  18:02:43 +0000 (0:00:00.538)       0:00:04.883 ***********
2026-07-27 18:02:44.908137 | controller | ok: [controller]
2026-07-27 18:02:44.908192 | controller |
2026-07-27 18:02:44.908330 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-07-27 18:02:44.908450 | controller | Monday 27 July 2026  18:02:44 +0000 (0:00:01.714)       0:00:06.597 ***********
2026-07-27 18:02:45.427661 | controller | ok: [controller]
2026-07-27 18:02:45.427730 | controller |
2026-07-27 18:02:45.427854 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-07-27 18:02:45.427975 | controller | Monday 27 July 2026  18:02:45 +0000 (0:00:00.517)       0:00:07.115 ***********
2026-07-27 18:02:45.816888 | controller | ok: [controller]
2026-07-27 18:02:45.816953 | controller |
2026-07-27 18:02:45.817016 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-07-27 18:02:45.817107 | controller | Monday 27 July 2026  18:02:45 +0000 (0:00:00.391)       0:00:07.507 ***********
2026-07-27 18:02:46.271160 | controller | ok: [controller]
2026-07-27 18:02:46.271190 | controller |
2026-07-27 18:02:46.271196 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-07-27 18:02:46.271201 | controller | Monday 27 July 2026  18:02:46 +0000 (0:00:00.449)       0:00:07.956 ***********
2026-07-27 18:02:46.591646 | controller | ok: [controller]
2026-07-27 18:02:46.591679 | controller |
2026-07-27 18:02:46.591686 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-07-27 18:02:46.591691 | controller | Monday 27 July 2026  18:02:46 +0000 (0:00:00.320)       0:00:08.277 ***********
2026-07-27 18:02:46.891206 | controller | ok: [controller]
2026-07-27 18:02:46.891298 | controller |
2026-07-27 18:02:46.891431 | controller | TASK [Install plugin] **********************************************************
2026-07-27 18:02:46.891572 | controller | Monday 27 July 2026  18:02:46 +0000 (0:00:00.303)       0:00:08.580 ***********
2026-07-27 18:02:46.950617 | controller | included: vexxhost.containers.download_artifact for controller
2026-07-27 18:02:46.950793 | controller |
2026-07-27 18:02:46.951062 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-07-27 18:02:46.951451 | controller | Monday 27 July 2026  18:02:46 +0000 (0:00:00.059)       0:00:08.640 ***********
2026-07-27 18:02:47.000617 | controller | ok: [controller] => {
2026-07-27 18:02:47.000830 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-07-27 18:02:47.000999 | controller | }
2026-07-27 18:02:47.001168 | controller |
2026-07-27 18:02:47.001355 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-07-27 18:02:47.001533 | controller | Monday 27 July 2026  18:02:46 +0000 (0:00:00.049)       0:00:08.689 ***********
2026-07-27 18:02:47.429467 | controller | ok: [controller]
2026-07-27 18:02:47.429632 | controller |
2026-07-27 18:02:47.429862 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-07-27 18:02:47.430096 | controller | Monday 27 July 2026  18:02:47 +0000 (0:00:00.429)       0:00:09.118 ***********
2026-07-27 18:02:49.087581 | controller | ok: [controller]
2026-07-27 18:02:49.087745 | controller |
2026-07-27 18:02:49.087945 | controller | PLAY RECAP *********************************************************************
2026-07-27 18:02:49.088162 | controller | controller                 : ok=16   changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-27 18:02:49.088337 | controller |
2026-07-27 18:02:49.088509 | controller |
2026-07-27 18:02:49.088689 | controller | TASKS RECAP ********************************************************************
2026-07-27 18:02:49.088904 | controller | Monday 27 July 2026  18:02:49 +0000 (0:00:01.659)       0:00:10.777 ***********
2026-07-27 18:02:49.089068 | controller | ===============================================================================
2026-07-27 18:02:49.089257 | controller | Gathering Facts --------------------------------------------------------- 2.13s
2026-07-27 18:02:49.089449 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.71s
2026-07-27 18:02:49.089641 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.66s
2026-07-27 18:02:49.089835 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.20s
2026-07-27 18:02:49.090024 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.54s
2026-07-27 18:02:49.090216 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.52s
2026-07-27 18:02:49.090407 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.48s
2026-07-27 18:02:49.090626 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.47s
2026-07-27 18:02:49.090817 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.45s
2026-07-27 18:02:49.091011 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.43s
2026-07-27 18:02:49.091271 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.39s
2026-07-27 18:02:49.091475 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.32s
2026-07-27 18:02:49.091676 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.30s
2026-07-27 18:02:49.091864 | controller | Install plugin ---------------------------------------------------------- 0.06s
2026-07-27 18:02:49.092056 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-07-27 18:02:49.092241 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-07-27 18:02:49.215922 | controller | INFO     [helm > idempotence] Executed: Successful
2026-07-27 18:02:49.227732 | controller | INFO     [helm > side_effect] Executing
2026-07-27 18:02:49.228182 | controller | WARNING  [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-27 18:02:49.236853 | controller | INFO     [helm > verify] Executing
2026-07-27 18:02:50.130262 | controller |
2026-07-27 18:02:50.130325 | controller | PLAY [Verify] ******************************************************************
2026-07-27 18:02:50.130333 | controller |
2026-07-27 18:02:50.130339 | controller | TASK [Gathering Facts] *********************************************************
2026-07-27 18:02:50.130345 | controller | Monday 27 July 2026  18:02:50 +0000 (0:00:00.010)       0:00:00.010 ***********
2026-07-27 18:02:51.234497 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information.
2026-07-27 18:02:51.241193 | controller | ok: [controller]
2026-07-27 18:02:51.241350 | controller |
2026-07-27 18:02:51.241535 | controller | TASK [Get the current Helm version] ********************************************
2026-07-27 18:02:51.241728 | controller | Monday 27 July 2026  18:02:51 +0000 (0:00:01.113)       0:00:01.123 ***********
2026-07-27 18:02:51.715124 | controller | changed: [controller]
2026-07-27 18:02:51.715149 | controller |
2026-07-27 18:02:51.715153 | controller | TASK [Assert Helm version] *****************************************************
2026-07-27 18:02:51.715159 | controller | Monday 27 July 2026  18:02:51 +0000 (0:00:00.470)       0:00:01.593 ***********
2026-07-27 18:02:51.745165 | controller | ok: [controller] => {
2026-07-27 18:02:51.745342 | controller |     "changed": false,
2026-07-27 18:02:51.745523 | controller |     "msg": "All assertions passed"
2026-07-27 18:02:51.745722 | controller | }
2026-07-27 18:02:51.745911 | controller |
2026-07-27 18:02:51.746076 | controller | PLAY RECAP *********************************************************************
2026-07-27 18:02:51.746280 | controller | controller                 : ok=3    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-27 18:02:51.746442 | controller |
2026-07-27 18:02:51.746603 | controller |
2026-07-27 18:02:51.746781 | controller | TASKS RECAP ********************************************************************
2026-07-27 18:02:51.746965 | controller | Monday 27 July 2026  18:02:51 +0000 (0:00:00.034)       0:00:01.628 ***********
2026-07-27 18:02:51.747169 | controller | ===============================================================================
2026-07-27 18:02:51.747350 | controller | Gathering Facts --------------------------------------------------------- 1.11s
2026-07-27 18:02:51.747540 | controller | Get the current Helm version -------------------------------------------- 0.47s
2026-07-27 18:02:51.747721 | controller | Assert Helm version ----------------------------------------------------- 0.03s
2026-07-27 18:02:51.882313 | controller | INFO     [helm > verify] Executed: Successful
2026-07-27 18:02:51.892793 | controller | INFO     [helm > cleanup] Executing
2026-07-27 18:02:51.893211 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-27 18:02:51.900518 | controller | INFO     [helm > destroy] Executing
2026-07-27 18:02:51.900578 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-07-27 18:02:51.900657 | controller | INFO     [helm > destroy] Executed: Successful
2026-07-27 18:02:51.900968 | controller | WARNING  Molecule executed 1 scenario (1 missing files)
2026-07-27 18:02:52.558644 | controller | ok: Runtime: 0:02:31.292106
2026-07-27 18:02:52.562968 | 
2026-07-27 18:02:52.563021 | PLAY RECAP
2026-07-27 18:02:52.563090 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-27 18:02:52.563115 | 
2026-07-27 18:02:52.766396 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-07-27 18:02:52.769626 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-07-27 18:02:53.397176 | 
2026-07-27 18:02:53.397299 | PLAY [all]
2026-07-27 18:02:53.408161 | 
2026-07-27 18:02:53.408242 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-07-27 18:02:53.823376 | controller | changed
2026-07-27 18:02:53.829268 | 
2026-07-27 18:02:53.829395 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-07-27 18:02:54.226524 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-27 18:02:54.226667 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-27 18:02:54.234944 | controller | environment: line 1: kubectl: command not found
2026-07-27 18:02:54.238009 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-27 18:02:54.238026 | controller | environment: line 1: kubectl: command not found
2026-07-27 18:02:54.239150 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-27 18:02:54.240957 | controller | environment: line 1: kubectl: command not found
2026-07-27 18:02:54.242526 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-27 18:02:54.244050 | controller | environment: line 1: kubectl: command not found
2026-07-27 18:02:54.245718 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-27 18:02:54.247378 | controller | environment: line 1: kubectl: command not found
2026-07-27 18:02:54.248839 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-27 18:02:54.359226 | controller | ok: Runtime: 0:00:00.036184
2026-07-27 18:02:54.366326 | 
2026-07-27 18:02:54.366398 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-07-27 18:02:54.598788 | controller | changed
2026-07-27 18:02:54.604641 | 
2026-07-27 18:02:54.604784 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-07-27 18:02:54.845953 | controller | environment: line 5: kubectl: command not found
2026-07-27 18:02:54.847728 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-27 18:02:54.847872 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-27 18:02:54.848220 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-27 18:02:55.138168 | controller | ok: Runtime: 0:00:00.012962
2026-07-27 18:02:55.143220 | 
2026-07-27 18:02:55.143285 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-07-27 18:02:55.775721 | controller | changed:
2026-07-27 18:02:55.775899 | controller | created directory /var/lib/zuul/builds/e01d217ab31d4178a19f2650445dfc03/work/logs/controller
2026-07-27 18:02:55.775936 | controller | cd+++++++++ objects/
2026-07-27 18:02:55.775964 | controller | cd+++++++++ objects/cluster/
2026-07-27 18:02:55.775993 | controller | cd+++++++++ objects/namespaced/
2026-07-27 18:02:55.783736 | 
2026-07-27 18:02:55.783803 | TASK [gather-pod-logs : creating directory for pod logs]
2026-07-27 18:02:56.041416 | controller | changed
2026-07-27 18:02:56.049286 | 
2026-07-27 18:02:56.049431 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-07-27 18:02:56.330047 | controller | changed
2026-07-27 18:02:56.334617 | 
2026-07-27 18:02:56.334690 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-07-27 18:02:56.584802 | controller | environment: line 3: kubectl: command not found
2026-07-27 18:02:56.864154 | controller | ok: Runtime: 0:00:00.013929
2026-07-27 18:02:56.870384 | 
2026-07-27 18:02:56.870635 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-07-27 18:02:57.424881 | controller | changed:
2026-07-27 18:02:57.425498 | controller | cd+++++++++ pod-logs/
2026-07-27 18:02:57.425639 | controller | cd+++++++++ pod-logs/failed-pods/
2026-07-27 18:02:57.433068 | 
2026-07-27 18:02:57.433150 | LOOP [helm-release-status : creating directory for helm release status]
2026-07-27 18:02:57.673811 | controller | changed: "values"
2026-07-27 18:02:57.888249 | controller | changed: "releases"
2026-07-27 18:02:57.905147 | 
2026-07-27 18:02:57.905312 | TASK [helm-release-status : Gather get release status for helm charts]
2026-07-27 18:02:58.156240 | controller | /bin/bash: line 3: kubectl: command not found
2026-07-27 18:02:58.436023 | controller | ok: Runtime: 0:00:00.009284
2026-07-27 18:02:58.442211 | 
2026-07-27 18:02:58.442303 | TASK [helm-release-status : Downloads logs to executor]
2026-07-27 18:02:58.972735 | controller | changed:
2026-07-27 18:02:58.972937 | controller | cd+++++++++ helm/
2026-07-27 18:02:58.973000 | controller | cd+++++++++ helm/releases/
2026-07-27 18:02:58.973034 | controller | cd+++++++++ helm/values/
2026-07-27 18:02:58.980144 | 
2026-07-27 18:02:58.980207 | PLAY RECAP
2026-07-27 18:02:58.980270 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-27 18:02:58.980300 | 
2026-07-27 18:02:59.113660 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-07-27 18:02:59.116518 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-27 18:02:59.897920 | 
2026-07-27 18:02:59.898068 | PLAY [all]
2026-07-27 18:02:59.908253 | 
2026-07-27 18:02:59.908330 | TASK [fetch-output : Set log path for multiple nodes]
2026-07-27 18:02:59.953559 | controller | skipping: Conditional result was False
2026-07-27 18:02:59.962258 | 
2026-07-27 18:02:59.962385 | TASK [fetch-output : Set log path for single node]
2026-07-27 18:03:00.005264 | controller | ok
2026-07-27 18:03:00.012996 | 
2026-07-27 18:03:00.013144 | LOOP [fetch-output : Ensure local output dirs]
2026-07-27 18:03:00.458366 | controller -> localhost | ok: "/var/lib/zuul/builds/e01d217ab31d4178a19f2650445dfc03/work/logs"
2026-07-27 18:03:00.679881 | controller -> localhost | changed: "/var/lib/zuul/builds/e01d217ab31d4178a19f2650445dfc03/work/artifacts"
2026-07-27 18:03:00.918406 | controller -> localhost | changed: "/var/lib/zuul/builds/e01d217ab31d4178a19f2650445dfc03/work/docs"
2026-07-27 18:03:00.941208 | 
2026-07-27 18:03:00.941354 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-07-27 18:03:01.621612 | controller | changed: .d..t...... ./
2026-07-27 18:03:01.621854 | controller | changed: All items complete
2026-07-27 18:03:01.621891 | 
2026-07-27 18:03:02.083402 | controller | changed: .d..t...... ./
2026-07-27 18:03:02.547259 | controller | changed: .d..t...... ./
2026-07-27 18:03:02.573998 | 
2026-07-27 18:03:02.574173 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-07-27 18:03:03.405119 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.174709
2026-07-27 18:03:03.629775 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009014
2026-07-27 18:03:03.648102 | 
2026-07-27 18:03:03.648276 | PLAY [all]
2026-07-27 18:03:03.656219 | 
2026-07-27 18:03:03.656421 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-07-27 18:03:04.149052 | controller | changed
2026-07-27 18:03:04.154918 | 
2026-07-27 18:03:04.154976 | PLAY RECAP
2026-07-27 18:03:04.155024 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-27 18:03:04.155048 | 
2026-07-27 18:03:04.332010 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-27 18:03:04.333536 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-07-27 18:03:05.013063 | 
2026-07-27 18:03:05.013198 | PLAY [localhost]
2026-07-27 18:03:05.023026 | 
2026-07-27 18:03:05.045725 | TASK [Generate Zuul manifest]
2026-07-27 18:03:05.064459 | localhost | ok
2026-07-27 18:03:05.135903 | 
2026-07-27 18:03:05.136021 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-07-27 18:03:05.552904 | localhost | changed
2026-07-27 18:03:05.573307 | 
2026-07-27 18:03:05.573564 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-07-27 18:03:05.617570 | localhost | ok
2026-07-27 18:03:05.627525 | 
2026-07-27 18:03:05.627633 | TASK [Upload logs]
2026-07-27 18:03:05.653264 | localhost | ok
2026-07-27 18:03:05.730222 | 
2026-07-27 18:03:05.730355 | TASK [Set zuul-log-path fact]
2026-07-27 18:03:05.753540 | localhost | ok
2026-07-27 18:03:05.770070 | 
2026-07-27 18:03:05.770139 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-27 18:03:05.802741 | localhost | ok
2026-07-27 18:03:05.810842 | 
2026-07-27 18:03:05.810908 | TASK [upload-logs : Create log directories]
2026-07-27 18:03:06.276683 | localhost | changed
2026-07-27 18:03:06.283541 | 
2026-07-27 18:03:06.283616 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-07-27 18:03:06.755250 | localhost -> localhost | ok: Runtime: 0:00:00.004850
2026-07-27 18:03:06.762350 | 
2026-07-27 18:03:06.762446 | TASK [upload-logs : Upload logs to log server]
2026-07-27 18:03:07.297465 | localhost | Output suppressed because no_log was given
2026-07-27 18:03:07.301172 | 
2026-07-27 18:03:07.301241 | LOOP [upload-logs : Compress console log and json output]
2026-07-27 18:03:07.345906 | localhost | skipping: Conditional result was False
2026-07-27 18:03:07.392155 | localhost | skipping: Conditional result was False
2026-07-27 18:03:07.402184 | 
2026-07-27 18:03:07.402361 | LOOP [upload-logs : Upload compressed console log and json output]
2026-07-27 18:03:07.444598 | localhost | skipping: Conditional result was False
2026-07-27 18:03:07.445080 | 
2026-07-27 18:03:07.448653 | localhost | skipping: Conditional result was False
2026-07-27 18:03:07.467778 | 
2026-07-27 18:03:07.467937 | LOOP [upload-logs : Upload console log and json output]