2026-07-27 18:22:50.110168 | Job console starting
2026-07-27 18:22:50.120455 | Updating git repos
2026-07-27 18:22:50.161086 | Cloning repos into workspace
2026-07-27 18:22:50.195304 | Restoring repo states
2026-07-27 18:22:50.220801 | Merging changes
2026-07-27 18:22:50.855661 | Checking out repos
2026-07-27 18:22:51.063121 | Preparing playbooks
2026-07-27 18:22:53.646281 | Running Ansible setup
2026-07-27 18:22:57.414734 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-27 18:22:58.087178 | 
2026-07-27 18:22:58.087354 | PLAY [localhost]
2026-07-27 18:22:58.095908 | 
2026-07-27 18:22:58.095982 | TASK [Gathering Facts]
2026-07-27 18:22:59.064441 | localhost | ok
2026-07-27 18:22:59.091322 | 
2026-07-27 18:22:59.091602 | TASK [Setup log path fact]
2026-07-27 18:22:59.123556 | localhost | ok
2026-07-27 18:22:59.139598 | 
2026-07-27 18:22:59.139691 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-27 18:22:59.171074 | localhost | ok
2026-07-27 18:22:59.180530 | 
2026-07-27 18:22:59.180627 | TASK [emit-job-header : Print job information]
2026-07-27 18:22:59.233651 | # Job Information
2026-07-27 18:22:59.233866 | Ansible Version: 2.16.18
2026-07-27 18:22:59.233913 | Job: ansible-collection-kubernetes-molecule-helm
2026-07-27 18:22:59.233945 | Pipeline: check
2026-07-27 18:22:59.233975 | Executor: 2d72f0692154
2026-07-27 18:22:59.234004 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/295
2026-07-27 18:22:59.234036 | Event ID: cde4f160-89e7-11f1-9a6d-228582bbc722
2026-07-27 18:22:59.248287 | 
2026-07-27 18:22:59.249039 | LOOP [emit-job-header : Print node information]
2026-07-27 18:22:59.339313 | localhost | ok:
2026-07-27 18:22:59.339581 | localhost | # Node Information
2026-07-27 18:22:59.339644 | localhost | Inventory Hostname: controller
2026-07-27 18:22:59.339693 | localhost | Hostname: np0000187069
2026-07-27 18:22:59.339737 | localhost | Username: zuul
2026-07-27 18:22:59.339789 | localhost | Distro: Debian 13.6
2026-07-27 18:22:59.339836 | localhost | Provider: yul1
2026-07-27 18:22:59.339885 | localhost | Region: ca-ymq-1
2026-07-27 18:22:59.339946 | localhost | Label: debian-trixie
2026-07-27 18:22:59.339995 | localhost | Product Name: OpenStack Nova
2026-07-27 18:22:59.340038 | localhost | Interface IP: 199.204.45.156
2026-07-27 18:22:59.351097 | 
2026-07-27 18:22:59.351242 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-07-27 18:22:59.740599 | localhost -> localhost | changed
2026-07-27 18:22:59.749824 | 
2026-07-27 18:22:59.749923 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-07-27 18:23:01.585871 | localhost -> localhost | changed
2026-07-27 18:23:01.594745 | 
2026-07-27 18:23:01.594821 | PLAY [all]
2026-07-27 18:23:01.601597 | 
2026-07-27 18:23:01.601663 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-07-27 18:23:01.822901 | controller -> localhost | ok
2026-07-27 18:23:01.828336 | 
2026-07-27 18:23:01.828410 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-07-27 18:23:01.865820 | controller | ok
2026-07-27 18:23:01.890539 | controller | included: /var/lib/zuul/builds/b4e41f3366714628b4450fa54f7fb5e9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-07-27 18:23:01.898932 | 
2026-07-27 18:23:01.899026 | TASK [add-build-sshkey : Create Temp SSH key]
2026-07-27 18:23:02.612510 | controller -> localhost | Generating public/private rsa key pair.
2026-07-27 18:23:02.612787 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/b4e41f3366714628b4450fa54f7fb5e9/work/b4e41f3366714628b4450fa54f7fb5e9_id_rsa
2026-07-27 18:23:02.612852 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/b4e41f3366714628b4450fa54f7fb5e9/work/b4e41f3366714628b4450fa54f7fb5e9_id_rsa.pub
2026-07-27 18:23:02.612902 | controller -> localhost | The key fingerprint is:
2026-07-27 18:23:02.612950 | controller -> localhost | SHA256:unrleZRHORYCpbKNZjTxwS1yOcjy/dYfqD4ps3vdV3s zuul-build-sshkey
2026-07-27 18:23:02.613047 | controller -> localhost | The key's randomart image is:
2026-07-27 18:23:02.613096 | controller -> localhost | +---[RSA 3072]----+
2026-07-27 18:23:02.613155 | controller -> localhost | |     ..oo=.      |
2026-07-27 18:23:02.613206 | controller -> localhost | |    . +o*oo .    |
2026-07-27 18:23:02.613252 | controller -> localhost | |     o++oo . o   |
2026-07-27 18:23:02.613297 | controller -> localhost | |     ..*.   =    |
2026-07-27 18:23:02.613342 | controller -> localhost | |      = S. = o   |
2026-07-27 18:23:02.613387 | controller -> localhost | |     o .. = + . .|
2026-07-27 18:23:02.613430 | controller -> localhost | |      .o + = o .o|
2026-07-27 18:23:02.613473 | controller -> localhost | |      ..= * . o.E|
2026-07-27 18:23:02.613522 | controller -> localhost | |    .o. oO..   ..|
2026-07-27 18:23:02.613568 | controller -> localhost | +----[SHA256]-----+
2026-07-27 18:23:02.613663 | controller -> localhost | ok: Runtime: 0:00:00.247450
2026-07-27 18:23:02.619982 | 
2026-07-27 18:23:02.620067 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-07-27 18:23:02.652827 | controller | ok
2026-07-27 18:23:02.664003 | controller | included: /var/lib/zuul/builds/b4e41f3366714628b4450fa54f7fb5e9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-07-27 18:23:02.677413 | 
2026-07-27 18:23:02.677621 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-07-27 18:23:02.703309 | controller | skipping: Conditional result was False
2026-07-27 18:23:02.711739 | 
2026-07-27 18:23:02.711833 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-07-27 18:23:03.286647 | controller | changed
2026-07-27 18:23:03.295979 | 
2026-07-27 18:23:03.296047 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-07-27 18:23:03.524448 | controller | ok
2026-07-27 18:23:03.529021 | 
2026-07-27 18:23:03.529089 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-07-27 18:23:04.098776 | controller | changed
2026-07-27 18:23:04.103997 | 
2026-07-27 18:23:04.104112 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-07-27 18:23:04.612800 | controller | changed
2026-07-27 18:23:04.618732 | 
2026-07-27 18:23:04.618857 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-07-27 18:23:04.643826 | controller | skipping: Conditional result was False
2026-07-27 18:23:04.651078 | 
2026-07-27 18:23:04.651246 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-07-27 18:23:05.051125 | controller -> localhost | changed
2026-07-27 18:23:05.063648 | 
2026-07-27 18:23:05.063763 | TASK [add-build-sshkey : Add back temp key]
2026-07-27 18:23:05.363375 | controller -> localhost | Identity added: /var/lib/zuul/builds/b4e41f3366714628b4450fa54f7fb5e9/work/b4e41f3366714628b4450fa54f7fb5e9_id_rsa (zuul-build-sshkey)
2026-07-27 18:23:05.363604 | controller -> localhost | ok: Runtime: 0:00:00.012912
2026-07-27 18:23:05.369688 | 
2026-07-27 18:23:05.369776 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-07-27 18:23:05.706589 | controller | ok
2026-07-27 18:23:05.711997 | 
2026-07-27 18:23:05.712086 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-07-27 18:23:05.737117 | controller | skipping: Conditional result was False
2026-07-27 18:23:05.749868 | 
2026-07-27 18:23:05.749984 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-07-27 18:23:06.097748 | controller | ok
2026-07-27 18:23:06.102744 | 
2026-07-27 18:23:06.102804 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-07-27 18:23:07.023564 | controller | Output suppressed because no_log was given
2026-07-27 18:23:07.035147 | 
2026-07-27 18:23:07.035289 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-07-27 18:23:07.250622 | controller | ok: "logs"
2026-07-27 18:23:07.250962 | controller | ok: All items complete
2026-07-27 18:23:07.251016 | 
2026-07-27 18:23:07.450740 | controller | ok: "artifacts"
2026-07-27 18:23:07.625144 | controller | ok: "docs"
2026-07-27 18:23:07.636676 | 
2026-07-27 18:23:07.636882 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-07-27 18:23:07.850043 | controller | changed: "logs"
2026-07-27 18:23:08.029570 | controller | changed: "artifacts"
2026-07-27 18:23:08.217265 | controller | changed: "docs"
2026-07-27 18:23:08.250463 | 
2026-07-27 18:23:08.250583 | PLAY RECAP
2026-07-27 18:23:08.250639 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-07-27 18:23:08.250672 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-27 18:23:08.250695 | 
2026-07-27 18:23:08.429440 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-27 18:23:08.430832 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-27 18:23:09.038866 | 
2026-07-27 18:23:09.038977 | PLAY [all]
2026-07-27 18:23:09.048577 | 
2026-07-27 18:23:09.048650 | TASK [setup-uv : Extract archive]
2026-07-27 18:23:11.403383 | controller | changed
2026-07-27 18:23:11.410075 | 
2026-07-27 18:23:11.410187 | TASK [setup-uv : Print version]
2026-07-27 18:23:11.811539 | controller | uv 0.8.13
2026-07-27 18:23:11.937957 | controller | ok: Runtime: 0:00:00.016443
2026-07-27 18:23:11.945435 | 
2026-07-27 18:23:11.945550 | TASK [Install Ansible collection siblings]
2026-07-27 18:23:12.365518 | controller | ok:
2026-07-27 18:23:12.365770 | controller | Skipping ansible.posix: no checked-out sibling
2026-07-27 18:23:12.365811 | controller | Skipping community.crypto: no checked-out sibling
2026-07-27 18:23:12.365842 | controller | Skipping community.general: no checked-out sibling
2026-07-27 18:23:12.365871 | controller | Skipping kubernetes.core: no checked-out sibling
2026-07-27 18:23:12.365906 | controller | Skipping vexxhost.containers: no checked-out sibling
2026-07-27 18:23:12.368423 | 
2026-07-27 18:23:12.368489 | PLAY RECAP
2026-07-27 18:23:12.368557 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-27 18:23:12.368589 | 
2026-07-27 18:23:12.535787 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-27 18:23:12.537209 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-07-27 18:23:13.223262 | 
2026-07-27 18:23:13.223384 | PLAY [all]
2026-07-27 18:23:13.233195 | 
2026-07-27 18:23:13.233266 | TASK [Update apt cache]
2026-07-27 18:23:17.870887 | controller | ok
2026-07-27 18:23:17.876535 | 
2026-07-27 18:23:17.876640 | TASK [Disable firewalld]
2026-07-27 18:23:17.903740 | controller | skipping: Conditional result was False
2026-07-27 18:23:17.913669 | 
2026-07-27 18:23:17.913768 | TASK [Install "jq" for log collection]
2026-07-27 18:23:20.374069 | controller | changed
2026-07-27 18:23:20.382215 | 
2026-07-27 18:23:20.382273 | PLAY RECAP
2026-07-27 18:23:20.382320 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-27 18:23:20.382343 | 
2026-07-27 18:23:20.503997 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-07-27 18:23:20.505515 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-07-27 18:23:21.682574 | 
2026-07-27 18:23:21.682737 | PLAY [all]
2026-07-27 18:23:21.692635 | 
2026-07-27 18:23:21.692707 | LOOP [Check inventory directories]
2026-07-27 18:23:22.051399 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-07-27 18:23:22.246771 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/helm/inventory"
2026-07-27 18:23:22.437175 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/inventory"
2026-07-27 18:23:22.448432 | 
2026-07-27 18:23:22.448654 | LOOP [Copy inventory file for Zuul]
2026-07-27 18:23:23.346195 | controller | changed:
2026-07-27 18:23:23.346416 | controller | {
2026-07-27 18:23:23.346455 | controller |   "ansible_loop_var": "item",
2026-07-27 18:23:23.346485 | controller |   "changed": false,
2026-07-27 18:23:23.346512 | controller |   "failed": false,
2026-07-27 18:23:23.346542 | controller |   "invocation": {
2026-07-27 18:23:23.346568 | controller |     "module_args": {
2026-07-27 18:23:23.346593 | controller |       "checksum_algorithm": "sha1",
2026-07-27 18:23:23.346618 | controller |       "follow": true,
2026-07-27 18:23:23.346642 | controller |       "get_attributes": true,
2026-07-27 18:23:23.346668 | controller |       "get_checksum": true,
2026-07-27 18:23:23.346693 | controller |       "get_mime": true,
2026-07-27 18:23:23.346716 | controller |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-07-27 18:23:23.346740 | controller |     }
2026-07-27 18:23:23.346764 | controller |   },
2026-07-27 18:23:23.346788 | controller |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-07-27 18:23:23.346811 | controller |   "stat": {
2026-07-27 18:23:23.346834 | controller |     "atime": 1785176586.9018676,
2026-07-27 18:23:23.346859 | controller |     "attr_flags": "e",
2026-07-27 18:23:23.346882 | controller |     "attributes": [
2026-07-27 18:23:23.346906 | controller |       "extents"
2026-07-27 18:23:23.346930 | controller |     ],
2026-07-27 18:23:23.346953 | controller |     "block_size": 4096,
2026-07-27 18:23:23.346976 | controller |     "blocks": 8,
2026-07-27 18:23:23.347000 | controller |     "charset": "unknown",
2026-07-27 18:23:23.347026 | controller |     "ctime": 1785176586.917868,
2026-07-27 18:23:23.347050 | controller |     "dev": 65025,
2026-07-27 18:23:23.347074 | controller |     "device_type": 0,
2026-07-27 18:23:23.347097 | controller |     "executable": true,
2026-07-27 18:23:23.347156 | controller |     "exists": true,
2026-07-27 18:23:23.347188 | controller |     "gid": 1000,
2026-07-27 18:23:23.347217 | controller |     "gr_name": "zuul",
2026-07-27 18:23:23.347245 | controller |     "inode": 518935,
2026-07-27 18:23:23.347277 | controller |     "isblk": false,
2026-07-27 18:23:23.347309 | controller |     "ischr": false,
2026-07-27 18:23:23.347335 | controller |     "isdir": true,
2026-07-27 18:23:23.347360 | controller |     "isfifo": false,
2026-07-27 18:23:23.347387 | controller |     "isgid": false,
2026-07-27 18:23:23.347413 | controller |     "islnk": false,
2026-07-27 18:23:23.347437 | controller |     "isreg": false,
2026-07-27 18:23:23.347459 | controller |     "issock": false,
2026-07-27 18:23:23.347482 | controller |     "isuid": false,
2026-07-27 18:23:23.347504 | controller |     "mimetype": "unknown",
2026-07-27 18:23:23.347527 | controller |     "mode": "0755",
2026-07-27 18:23:23.347550 | controller |     "mtime": 1785176570.7438796,
2026-07-27 18:23:23.347573 | controller |     "nlink": 14,
2026-07-27 18:23:23.347596 | controller |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-07-27 18:23:23.347619 | controller |     "pw_name": "zuul",
2026-07-27 18:23:23.347647 | controller |     "readable": true,
2026-07-27 18:23:23.347673 | controller |     "rgrp": true,
2026-07-27 18:23:23.347699 | controller |     "roth": true,
2026-07-27 18:23:23.347722 | controller |     "rusr": true,
2026-07-27 18:23:23.347745 | controller |     "size": 4096,
2026-07-27 18:23:23.347769 | controller |     "uid": 1000,
2026-07-27 18:23:23.347793 | controller |     "version": "510695070",
2026-07-27 18:23:23.347820 | controller |     "wgrp": false,
2026-07-27 18:23:23.347846 | controller |     "woth": false,
2026-07-27 18:23:23.347870 | controller |     "writeable": true,
2026-07-27 18:23:23.347893 | controller |     "wusr": true,
2026-07-27 18:23:23.347919 | controller |     "xgrp": true,
2026-07-27 18:23:23.347943 | controller |     "xoth": true,
2026-07-27 18:23:23.347965 | controller |     "xusr": true
2026-07-27 18:23:23.347988 | controller |   }
2026-07-27 18:23:23.348031 | controller | }
2026-07-27 18:23:23.348078 | controller | ok: All items complete
2026-07-27 18:23:23.348107 | 
2026-07-27 18:23:23.349783 | controller | skipping: Conditional result was False
2026-07-27 18:23:23.350613 | controller | skipping: Conditional result was False
2026-07-27 18:23:23.364449 | 
2026-07-27 18:23:23.364614 | LOOP [Switch "ansible_host" to private IP]
2026-07-27 18:23:23.760698 | controller | changed: 1 replacements made
2026-07-27 18:23:23.763777 | controller | skipping: Conditional result was False
2026-07-27 18:23:23.765157 | controller | skipping: Conditional result was False
2026-07-27 18:23:23.778944 | 
2026-07-27 18:23:23.779079 | TASK [Run Molecule scenario]
2026-07-27 18:23:24.252447 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-07-27 18:23:24.252570 | controller | Creating virtual environment at: .venv
2026-07-27 18:23:24.537640 | controller | Downloading pygments (1.2MiB)
2026-07-27 18:23:24.538568 | controller | Downloading cryptography (4.3MiB)
2026-07-27 18:23:24.539346 | controller | Downloading ansible-core (2.3MiB)
2026-07-27 18:23:24.696165 | controller |  Downloading cryptography
2026-07-27 18:23:24.755101 | controller |  Downloading pygments
2026-07-27 18:23:24.929293 | controller |  Downloading ansible-core
2026-07-27 18:23:24.978154 | controller | Installed 36 packages in 48ms
2026-07-27 18:23:25.685166 | controller | INFO     Collection 'vexxhost.kubernetes' detected.
2026-07-27 18:23:25.685248 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-07-27 18:23:26.553902 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-07-27 18:23:26.553987 | controller | INFO     default scenario not found, disabling shared state.
2026-07-27 18:23:26.554124 | controller | INFO     [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-07-27 18:23:26.554224 | controller | INFO     [helm > prerun] Performing prerun with role_name_check=0...
2026-07-27 18:24:25.612164 | controller | INFO     [helm > dependency] Executing
2026-07-27 18:24:25.612237 | controller | WARNING  [helm > dependency] Missing roles requirements file: requirements.yml
2026-07-27 18:24:25.612243 | controller | WARNING  [helm > dependency] Missing collections requirements file: collections.yml
2026-07-27 18:24:25.612275 | controller | WARNING  [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-07-27 18:24:25.617647 | controller | INFO     [helm > cleanup] Executing
2026-07-27 18:24:25.617969 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-27 18:24:25.623395 | controller | INFO     [helm > destroy] Executing
2026-07-27 18:24:25.623453 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-07-27 18:24:25.623519 | controller | INFO     [helm > destroy] Executed: Successful
2026-07-27 18:24:25.628942 | controller | INFO     [helm > syntax] Executing
2026-07-27 18:24:26.522468 | controller |
2026-07-27 18:24:26.522755 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml
2026-07-27 18:24:26.601791 | controller | INFO     [helm > syntax] Executed: Successful
2026-07-27 18:24:26.611454 | controller | INFO     [helm > create] Executing
2026-07-27 18:24:26.613487 | controller | WARNING  [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-27 18:24:26.620158 | controller | INFO     [helm > prepare] Executing
2026-07-27 18:24:27.494368 | controller |
2026-07-27 18:24:27.494542 | controller | PLAY [Prepare] *****************************************************************
2026-07-27 18:24:27.494726 | controller |
2026-07-27 18:24:27.494920 | controller | TASK [Gathering Facts] *********************************************************
2026-07-27 18:24:27.495112 | controller | Monday 27 July 2026  18:24:27 +0000 (0:00:00.031)       0:00:00.031 ***********
2026-07-27 18:24:28.723578 | 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:24:28.730864 | controller | ok: [controller]
2026-07-27 18:24:28.730886 | controller |
2026-07-27 18:24:28.730895 | controller | TASK [Install Debian packages] *************************************************
2026-07-27 18:24:28.730904 | controller | Monday 27 July 2026  18:24:28 +0000 (0:00:01.236)       0:00:01.267 ***********
2026-07-27 18:24:30.111248 | controller | ok: [controller]
2026-07-27 18:24:30.111603 | controller |
2026-07-27 18:24:30.111894 | controller | PLAY RECAP *********************************************************************
2026-07-27 18:24:30.112191 | controller | controller                 : ok=2    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-27 18:24:30.112445 | controller |
2026-07-27 18:24:30.112768 | controller |
2026-07-27 18:24:30.113056 | controller | TASKS RECAP ********************************************************************
2026-07-27 18:24:30.113388 | controller | Monday 27 July 2026  18:24:30 +0000 (0:00:01.381)       0:00:02.649 ***********
2026-07-27 18:24:30.113704 | controller | ===============================================================================
2026-07-27 18:24:30.113970 | controller | Install Debian packages ------------------------------------------------- 1.38s
2026-07-27 18:24:30.114244 | controller | Gathering Facts --------------------------------------------------------- 1.24s
2026-07-27 18:24:30.208063 | controller | INFO     [helm > prepare] Executed: Successful
2026-07-27 18:24:30.218953 | controller | INFO     [helm > converge] Executing
2026-07-27 18:24:31.095758 | controller |
2026-07-27 18:24:31.096090 | controller | PLAY [Converge] ****************************************************************
2026-07-27 18:24:31.096346 | controller |
2026-07-27 18:24:31.096674 | controller | TASK [Gathering Facts] *********************************************************
2026-07-27 18:24:31.096943 | controller | Monday 27 July 2026  18:24:31 +0000 (0:00:00.019)       0:00:00.019 ***********
2026-07-27 18:24:32.183487 | 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:24:32.188405 | controller | ok: [controller]
2026-07-27 18:24:32.188675 | controller |
2026-07-27 18:24:32.188952 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-07-27 18:24:32.189216 | controller | Monday 27 July 2026  18:24:32 +0000 (0:00:01.092)       0:00:01.111 ***********
2026-07-27 18:24:32.686529 | controller | ok: [controller]
2026-07-27 18:24:32.686821 | controller |
2026-07-27 18:24:32.687094 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-07-27 18:24:32.687354 | controller | Monday 27 July 2026  18:24:32 +0000 (0:00:00.498)       0:00:01.610 ***********
2026-07-27 18:24:33.799645 | controller | ok: [controller]
2026-07-27 18:24:33.799683 | controller |
2026-07-27 18:24:33.799693 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-07-27 18:24:33.799703 | controller | Monday 27 July 2026  18:24:33 +0000 (0:00:01.112)       0:00:02.722 ***********
2026-07-27 18:24:34.239496 | controller | changed: [controller]
2026-07-27 18:24:34.239817 | controller |
2026-07-27 18:24:34.240098 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-07-27 18:24:34.240362 | controller | Monday 27 July 2026  18:24:34 +0000 (0:00:00.440)       0:00:03.162 ***********
2026-07-27 18:24:34.288645 | controller | ok: [controller] => {
2026-07-27 18:24:34.288938 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-07-27 18:24:34.289243 | controller | }
2026-07-27 18:24:34.289532 | controller |
2026-07-27 18:24:34.289826 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-07-27 18:24:34.290086 | controller | Monday 27 July 2026  18:24:34 +0000 (0:00:00.048)       0:00:03.211 ***********
2026-07-27 18:24:35.195687 | 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:24:35.204150 | controller | changed: [controller]
2026-07-27 18:24:35.204325 | controller |
2026-07-27 18:24:35.204500 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-07-27 18:24:35.204713 | controller | Monday 27 July 2026  18:24:35 +0000 (0:00:00.916)       0:00:04.128 ***********
2026-07-27 18:24:37.252924 | controller | changed: [controller]
2026-07-27 18:24:37.253222 | controller |
2026-07-27 18:24:37.253561 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-07-27 18:24:37.253834 | controller | Monday 27 July 2026  18:24:37 +0000 (0:00:02.048)       0:00:06.176 ***********
2026-07-27 18:24:37.815026 | controller | ok: [controller]
2026-07-27 18:24:37.815296 | controller |
2026-07-27 18:24:37.815589 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-07-27 18:24:37.815837 | controller | Monday 27 July 2026  18:24:37 +0000 (0:00:00.562)       0:00:06.738 ***********
2026-07-27 18:24:38.224362 | controller | changed: [controller]
2026-07-27 18:24:38.224549 | controller |
2026-07-27 18:24:38.224717 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-07-27 18:24:38.224877 | controller | Monday 27 July 2026  18:24:38 +0000 (0:00:00.409)       0:00:07.147 ***********
2026-07-27 18:24:38.692126 | controller | ok: [controller]
2026-07-27 18:24:38.692443 | controller |
2026-07-27 18:24:38.692808 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-07-27 18:24:38.693095 | controller | Monday 27 July 2026  18:24:38 +0000 (0:00:00.467)       0:00:07.615 ***********
2026-07-27 18:24:38.985586 | controller | changed: [controller]
2026-07-27 18:24:38.985635 | controller |
2026-07-27 18:24:38.985645 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-07-27 18:24:38.985653 | controller | Monday 27 July 2026  18:24:38 +0000 (0:00:00.293)       0:00:07.908 ***********
2026-07-27 18:24:39.277955 | controller | ok: [controller]
2026-07-27 18:24:39.278105 | controller |
2026-07-27 18:24:39.278306 | controller | TASK [Install plugin] **********************************************************
2026-07-27 18:24:39.278498 | controller | Monday 27 July 2026  18:24:39 +0000 (0:00:00.292)       0:00:08.201 ***********
2026-07-27 18:24:39.332204 | controller | included: vexxhost.containers.download_artifact for controller
2026-07-27 18:24:39.332272 | controller |
2026-07-27 18:24:39.332286 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-07-27 18:24:39.332400 | controller | Monday 27 July 2026  18:24:39 +0000 (0:00:00.054)       0:00:08.255 ***********
2026-07-27 18:24:39.385799 | controller | ok: [controller] => {
2026-07-27 18:24:39.385946 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-07-27 18:24:39.389673 | controller | }
2026-07-27 18:24:39.389695 | controller |
2026-07-27 18:24:39.389704 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-07-27 18:24:39.389715 | controller | Monday 27 July 2026  18:24:39 +0000 (0:00:00.053)       0:00:08.308 ***********
2026-07-27 18:24:40.171910 | controller | changed: [controller]
2026-07-27 18:24:40.172032 | controller |
2026-07-27 18:24:40.172196 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-07-27 18:24:40.172364 | controller | Monday 27 July 2026  18:24:40 +0000 (0:00:00.785)       0:00:09.094 ***********
2026-07-27 18:24:42.251803 | controller | changed: [controller]
2026-07-27 18:24:42.252102 | controller |
2026-07-27 18:24:42.252371 | controller | PLAY RECAP *********************************************************************
2026-07-27 18:24:42.252666 | controller | controller                 : ok=16   changed=7    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-27 18:24:42.252907 | controller |
2026-07-27 18:24:42.253178 | controller |
2026-07-27 18:24:42.253493 | controller | TASKS RECAP ********************************************************************
2026-07-27 18:24:42.253801 | controller | Monday 27 July 2026  18:24:42 +0000 (0:00:02.080)       0:00:11.175 ***********
2026-07-27 18:24:42.254049 | controller | ===============================================================================
2026-07-27 18:24:42.254305 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.08s
2026-07-27 18:24:42.254561 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.05s
2026-07-27 18:24:42.254814 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.11s
2026-07-27 18:24:42.255061 | controller | Gathering Facts --------------------------------------------------------- 1.09s
2026-07-27 18:24:42.255312 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.92s
2026-07-27 18:24:42.255564 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.79s
2026-07-27 18:24:42.255814 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.56s
2026-07-27 18:24:42.256061 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.50s
2026-07-27 18:24:42.256310 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.47s
2026-07-27 18:24:42.256562 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.44s
2026-07-27 18:24:42.256828 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.41s
2026-07-27 18:24:42.257089 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.29s
2026-07-27 18:24:42.257392 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.29s
2026-07-27 18:24:42.257706 | controller | Install plugin ---------------------------------------------------------- 0.05s
2026-07-27 18:24:42.257964 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-07-27 18:24:42.258213 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-07-27 18:24:42.349597 | controller | INFO     [helm > converge] Executed: Successful
2026-07-27 18:24:42.361776 | controller | INFO     [helm > idempotence] Executing
2026-07-27 18:24:43.206552 | controller |
2026-07-27 18:24:43.206889 | controller | PLAY [Converge] ****************************************************************
2026-07-27 18:24:43.207140 | controller |
2026-07-27 18:24:43.207394 | controller | TASK [Gathering Facts] *********************************************************
2026-07-27 18:24:43.207649 | controller | Monday 27 July 2026  18:24:43 +0000 (0:00:00.013)       0:00:00.013 ***********
2026-07-27 18:24:46.282263 | 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:24:46.287965 | controller | ok: [controller]
2026-07-27 18:24:46.288281 | controller |
2026-07-27 18:24:46.288571 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-07-27 18:24:46.288841 | controller | Monday 27 July 2026  18:24:46 +0000 (0:00:03.081)       0:00:03.095 ***********
2026-07-27 18:24:46.733000 | controller | ok: [controller]
2026-07-27 18:24:46.733295 | controller |
2026-07-27 18:24:46.733618 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-07-27 18:24:46.733887 | controller | Monday 27 July 2026  18:24:46 +0000 (0:00:00.445)       0:00:03.540 ***********
2026-07-27 18:24:47.717566 | controller | ok: [controller]
2026-07-27 18:24:47.717599 | controller |
2026-07-27 18:24:47.717610 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-07-27 18:24:47.717618 | controller | Monday 27 July 2026  18:24:47 +0000 (0:00:00.983)       0:00:04.524 ***********
2026-07-27 18:24:48.131612 | controller | ok: [controller]
2026-07-27 18:24:48.131779 | controller |
2026-07-27 18:24:48.132045 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-07-27 18:24:48.132270 | controller | Monday 27 July 2026  18:24:48 +0000 (0:00:00.414)       0:00:04.938 ***********
2026-07-27 18:24:48.179965 | controller | ok: [controller] => {
2026-07-27 18:24:48.180147 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-07-27 18:24:48.180341 | controller | }
2026-07-27 18:24:48.180574 | controller |
2026-07-27 18:24:48.180813 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-07-27 18:24:48.181108 | controller | Monday 27 July 2026  18:24:48 +0000 (0:00:00.048)       0:00:04.986 ***********
2026-07-27 18:24:48.719855 | controller | ok: [controller]
2026-07-27 18:24:48.719905 | controller |
2026-07-27 18:24:48.719915 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-07-27 18:24:48.719924 | controller | Monday 27 July 2026  18:24:48 +0000 (0:00:00.538)       0:00:05.525 ***********
2026-07-27 18:24:51.461939 | controller | ok: [controller]
2026-07-27 18:24:51.461994 | controller |
2026-07-27 18:24:51.462146 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-07-27 18:24:51.462268 | controller | Monday 27 July 2026  18:24:51 +0000 (0:00:02.742)       0:00:08.267 ***********
2026-07-27 18:24:51.977063 | controller | ok: [controller]
2026-07-27 18:24:51.977121 | controller |
2026-07-27 18:24:51.977277 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-07-27 18:24:51.977408 | controller | Monday 27 July 2026  18:24:51 +0000 (0:00:00.516)       0:00:08.784 ***********
2026-07-27 18:24:52.369549 | controller | ok: [controller]
2026-07-27 18:24:52.369643 | controller |
2026-07-27 18:24:52.369781 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-07-27 18:24:52.369913 | controller | Monday 27 July 2026  18:24:52 +0000 (0:00:00.392)       0:00:09.176 ***********
2026-07-27 18:24:52.853413 | controller | ok: [controller]
2026-07-27 18:24:52.854054 | controller |
2026-07-27 18:24:52.854140 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-07-27 18:24:52.854150 | controller | Monday 27 July 2026  18:24:52 +0000 (0:00:00.475)       0:00:09.652 ***********
2026-07-27 18:24:53.160223 | controller | ok: [controller]
2026-07-27 18:24:53.160279 | controller |
2026-07-27 18:24:53.160834 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-07-27 18:24:53.160844 | controller | Monday 27 July 2026  18:24:53 +0000 (0:00:00.312)       0:00:09.965 ***********
2026-07-27 18:24:53.467862 | controller | ok: [controller]
2026-07-27 18:24:53.467901 | controller |
2026-07-27 18:24:53.468020 | controller | TASK [Install plugin] **********************************************************
2026-07-27 18:24:53.468147 | controller | Monday 27 July 2026  18:24:53 +0000 (0:00:00.309)       0:00:10.274 ***********
2026-07-27 18:24:53.522881 | controller | included: vexxhost.containers.download_artifact for controller
2026-07-27 18:24:53.522912 | controller |
2026-07-27 18:24:53.522993 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-07-27 18:24:53.523083 | controller | Monday 27 July 2026  18:24:53 +0000 (0:00:00.055)       0:00:10.330 ***********
2026-07-27 18:24:53.572911 | controller | ok: [controller] => {
2026-07-27 18:24:53.573006 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-07-27 18:24:53.573110 | controller | }
2026-07-27 18:24:53.573221 | controller |
2026-07-27 18:24:53.573346 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-07-27 18:24:53.573630 | controller | Monday 27 July 2026  18:24:53 +0000 (0:00:00.049)       0:00:10.379 ***********
2026-07-27 18:24:53.996768 | controller | ok: [controller]
2026-07-27 18:24:53.996826 | controller |
2026-07-27 18:24:53.996926 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-07-27 18:24:53.997022 | controller | Monday 27 July 2026  18:24:53 +0000 (0:00:00.421)       0:00:10.801 ***********
2026-07-27 18:24:55.645690 | controller | ok: [controller]
2026-07-27 18:24:55.645752 | controller |
2026-07-27 18:24:55.645761 | controller | PLAY RECAP *********************************************************************
2026-07-27 18:24:55.645770 | controller | controller                 : ok=16   changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-27 18:24:55.645778 | controller |
2026-07-27 18:24:55.645785 | controller |
2026-07-27 18:24:55.645803 | controller | TASKS RECAP ********************************************************************
2026-07-27 18:24:55.649176 | controller | Monday 27 July 2026  18:24:55 +0000 (0:00:01.651)       0:00:12.452 ***********
2026-07-27 18:24:55.649224 | controller | ===============================================================================
2026-07-27 18:24:55.649232 | controller | Gathering Facts --------------------------------------------------------- 3.08s
2026-07-27 18:24:55.649239 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.74s
2026-07-27 18:24:55.649247 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.65s
2026-07-27 18:24:55.649254 | controller | vexxhost.containers.package : Update state for tar ---------------------- 0.98s
2026-07-27 18:24:55.649261 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.54s
2026-07-27 18:24:55.649268 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.52s
2026-07-27 18:24:55.649275 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.48s
2026-07-27 18:24:55.649281 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.45s
2026-07-27 18:24:55.649290 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.42s
2026-07-27 18:24:55.649297 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.41s
2026-07-27 18:24:55.649316 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.39s
2026-07-27 18:24:55.649323 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.31s
2026-07-27 18:24:55.649331 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.31s
2026-07-27 18:24:55.649338 | controller | Install plugin ---------------------------------------------------------- 0.06s
2026-07-27 18:24:55.649345 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-07-27 18:24:55.649352 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-07-27 18:24:55.779118 | controller | INFO     [helm > idempotence] Executed: Successful
2026-07-27 18:24:55.791467 | controller | INFO     [helm > side_effect] Executing
2026-07-27 18:24:55.791838 | controller | WARNING  [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-27 18:24:55.797142 | controller | INFO     [helm > verify] Executing
2026-07-27 18:24:56.637959 | controller |
2026-07-27 18:24:56.638402 | controller | PLAY [Verify] ******************************************************************
2026-07-27 18:24:56.638654 | controller |
2026-07-27 18:24:56.639033 | controller | TASK [Gathering Facts] *********************************************************
2026-07-27 18:24:56.639310 | controller | Monday 27 July 2026  18:24:56 +0000 (0:00:00.009)       0:00:00.009 ***********
2026-07-27 18:24:57.718366 | 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:24:57.727538 | controller | ok: [controller]
2026-07-27 18:24:57.727796 | controller |
2026-07-27 18:24:57.728056 | controller | TASK [Get the current Helm version] ********************************************
2026-07-27 18:24:57.728312 | controller | Monday 27 July 2026  18:24:57 +0000 (0:00:01.086)       0:00:01.096 ***********
2026-07-27 18:24:58.173155 | controller | changed: [controller]
2026-07-27 18:24:58.173508 | controller |
2026-07-27 18:24:58.173780 | controller | TASK [Assert Helm version] *****************************************************
2026-07-27 18:24:58.174040 | controller | Monday 27 July 2026  18:24:58 +0000 (0:00:00.448)       0:00:01.544 ***********
2026-07-27 18:24:58.203764 | controller | ok: [controller] => {
2026-07-27 18:24:58.204028 | controller |     "changed": false,
2026-07-27 18:24:58.204305 | controller |     "msg": "All assertions passed"
2026-07-27 18:24:58.204553 | controller | }
2026-07-27 18:24:58.204782 | controller |
2026-07-27 18:24:58.205050 | controller | PLAY RECAP *********************************************************************
2026-07-27 18:24:58.205370 | controller | controller                 : ok=3    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-27 18:24:58.205641 | controller |
2026-07-27 18:24:58.205865 | controller |
2026-07-27 18:24:58.206105 | controller | TASKS RECAP ********************************************************************
2026-07-27 18:24:58.206352 | controller | Monday 27 July 2026  18:24:58 +0000 (0:00:00.031)       0:00:01.576 ***********
2026-07-27 18:24:58.206582 | controller | ===============================================================================
2026-07-27 18:24:58.206822 | controller | Gathering Facts --------------------------------------------------------- 1.09s
2026-07-27 18:24:58.207065 | controller | Get the current Helm version -------------------------------------------- 0.45s
2026-07-27 18:24:58.207304 | controller | Assert Helm version ----------------------------------------------------- 0.03s
2026-07-27 18:24:58.319733 | controller | INFO     [helm > verify] Executed: Successful
2026-07-27 18:24:58.330575 | controller | INFO     [helm > cleanup] Executing
2026-07-27 18:24:58.331068 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-27 18:24:58.338624 | controller | INFO     [helm > destroy] Executing
2026-07-27 18:24:58.338743 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-07-27 18:24:58.338854 | controller | INFO     [helm > destroy] Executed: Successful
2026-07-27 18:24:58.339192 | controller | WARNING  Molecule executed 1 scenario (1 missing files)
2026-07-27 18:24:58.866675 | controller | ok: Runtime: 0:01:34.268829
2026-07-27 18:24:58.870778 | 
2026-07-27 18:24:58.870829 | PLAY RECAP
2026-07-27 18:24:58.870877 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-27 18:24:58.870900 | 
2026-07-27 18:24:59.000191 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-07-27 18:24:59.003066 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-07-27 18:24:59.623271 | 
2026-07-27 18:24:59.623409 | PLAY [all]
2026-07-27 18:24:59.635380 | 
2026-07-27 18:24:59.635484 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-07-27 18:25:00.025848 | controller | changed
2026-07-27 18:25:00.030707 | 
2026-07-27 18:25:00.030794 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-07-27 18:25:00.436796 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-27 18:25:00.436901 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-27 18:25:00.441428 | controller | environment: line 1: kubectl: command not found
2026-07-27 18:25:00.443709 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-27 18:25:00.443764 | controller | environment: line 1: kubectl: command not found
2026-07-27 18:25:00.445223 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-27 18:25:00.449149 | controller | environment: line 1: kubectl: command not found
2026-07-27 18:25:00.450859 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-27 18:25:00.451764 | controller | environment: line 1: kubectl: command not found
2026-07-27 18:25:00.453571 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-27 18:25:00.454579 | controller | environment: line 1: kubectl: command not found
2026-07-27 18:25:00.455762 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-27 18:25:00.563071 | controller | ok: Runtime: 0:00:00.032342
2026-07-27 18:25:00.569752 | 
2026-07-27 18:25:00.569851 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-07-27 18:25:00.806412 | controller | changed
2026-07-27 18:25:00.812058 | 
2026-07-27 18:25:00.812164 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-07-27 18:25:01.057516 | controller | environment: line 5: kubectl: command not found
2026-07-27 18:25:01.058662 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-27 18:25:01.059033 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-27 18:25:01.059686 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-27 18:25:01.342886 | controller | ok: Runtime: 0:00:00.013472
2026-07-27 18:25:01.349586 | 
2026-07-27 18:25:01.349684 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-07-27 18:25:03.159288 | controller | changed:
2026-07-27 18:25:03.881140 | controller | created directory /var/lib/zuul/builds/b4e41f3366714628b4450fa54f7fb5e9/work/logs/controller
2026-07-27 18:25:03.881331 | controller | cd+++++++++ objects/
2026-07-27 18:25:03.881390 | controller | cd+++++++++ objects/cluster/
2026-07-27 18:25:03.881442 | controller | cd+++++++++ objects/namespaced/
2026-07-27 18:25:03.894481 | 
2026-07-27 18:25:03.894557 | TASK [gather-pod-logs : creating directory for pod logs]
2026-07-27 18:25:04.131670 | controller | changed
2026-07-27 18:25:04.135724 | 
2026-07-27 18:25:04.135789 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-07-27 18:25:04.391777 | controller | changed
2026-07-27 18:25:04.397365 | 
2026-07-27 18:25:04.397467 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-07-27 18:25:04.641554 | controller | environment: line 3: kubectl: command not found
2026-07-27 18:25:04.930026 | controller | ok: Runtime: 0:00:00.012157
2026-07-27 18:25:04.936043 | 
2026-07-27 18:25:04.936130 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-07-27 18:25:05.438974 | controller | changed:
2026-07-27 18:25:05.439441 | controller | cd+++++++++ pod-logs/
2026-07-27 18:25:05.439501 | controller | cd+++++++++ pod-logs/failed-pods/
2026-07-27 18:25:05.448699 | 
2026-07-27 18:25:05.448769 | LOOP [helm-release-status : creating directory for helm release status]
2026-07-27 18:25:05.686786 | controller | changed: "values"
2026-07-27 18:25:05.889841 | controller | changed: "releases"
2026-07-27 18:25:05.909901 | 
2026-07-27 18:25:05.910108 | TASK [helm-release-status : Gather get release status for helm charts]
2026-07-27 18:25:06.168513 | controller | /bin/bash: line 3: kubectl: command not found
2026-07-27 18:25:06.444169 | controller | ok: Runtime: 0:00:00.007127
2026-07-27 18:25:06.449955 | 
2026-07-27 18:25:06.450047 | TASK [helm-release-status : Downloads logs to executor]
2026-07-27 18:25:06.970280 | controller | changed:
2026-07-27 18:25:06.970496 | controller | cd+++++++++ helm/
2026-07-27 18:25:06.970541 | controller | cd+++++++++ helm/releases/
2026-07-27 18:25:06.970573 | controller | cd+++++++++ helm/values/
2026-07-27 18:25:06.977212 | 
2026-07-27 18:25:06.977282 | PLAY RECAP
2026-07-27 18:25:06.977345 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-27 18:25:06.977399 | 
2026-07-27 18:25:07.255416 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-07-27 18:25:07.258518 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-27 18:25:07.834576 | 
2026-07-27 18:25:07.834702 | PLAY [all]
2026-07-27 18:25:07.844605 | 
2026-07-27 18:25:07.844684 | TASK [fetch-output : Set log path for multiple nodes]
2026-07-27 18:25:07.891589 | controller | skipping: Conditional result was False
2026-07-27 18:25:07.903922 | 
2026-07-27 18:25:07.904036 | TASK [fetch-output : Set log path for single node]
2026-07-27 18:25:07.947087 | controller | ok
2026-07-27 18:25:07.954190 | 
2026-07-27 18:25:07.954283 | LOOP [fetch-output : Ensure local output dirs]
2026-07-27 18:25:08.826153 | controller -> localhost | ok: "/var/lib/zuul/builds/b4e41f3366714628b4450fa54f7fb5e9/work/logs"
2026-07-27 18:25:09.045703 | controller -> localhost | changed: "/var/lib/zuul/builds/b4e41f3366714628b4450fa54f7fb5e9/work/artifacts"
2026-07-27 18:25:09.252243 | controller -> localhost | changed: "/var/lib/zuul/builds/b4e41f3366714628b4450fa54f7fb5e9/work/docs"
2026-07-27 18:25:09.265957 | 
2026-07-27 18:25:09.266115 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-07-27 18:25:09.884853 | controller | changed: .d..t...... ./
2026-07-27 18:25:09.885162 | controller | changed: All items complete
2026-07-27 18:25:09.885202 | 
2026-07-27 18:25:10.324243 | controller | changed: .d..t...... ./
2026-07-27 18:25:10.803574 | controller | changed: .d..t...... ./
2026-07-27 18:25:10.820127 | 
2026-07-27 18:25:10.820308 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-07-27 18:25:11.989434 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.740632
2026-07-27 18:25:12.232161 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007446
2026-07-27 18:25:12.255737 | 
2026-07-27 18:25:12.255921 | PLAY [all]
2026-07-27 18:25:12.263188 | 
2026-07-27 18:25:12.263255 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-07-27 18:25:12.722508 | controller | changed
2026-07-27 18:25:12.729085 | 
2026-07-27 18:25:12.729152 | PLAY RECAP
2026-07-27 18:25:12.729211 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-27 18:25:12.729240 | 
2026-07-27 18:25:12.864053 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-27 18:25:13.532172 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-07-27 18:25:14.185566 | 
2026-07-27 18:25:14.185676 | PLAY [localhost]
2026-07-27 18:25:14.195689 | 
2026-07-27 18:25:14.195766 | TASK [Generate Zuul manifest]
2026-07-27 18:25:14.218531 | localhost | ok
2026-07-27 18:25:14.236390 | 
2026-07-27 18:25:14.236473 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-07-27 18:25:14.599761 | localhost | changed
2026-07-27 18:25:14.615557 | 
2026-07-27 18:25:14.615732 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-07-27 18:25:17.212930 | localhost | ok
2026-07-27 18:25:17.222140 | 
2026-07-27 18:25:17.222249 | TASK [Upload logs]
2026-07-27 18:25:17.244277 | localhost | ok
2026-07-27 18:25:17.308003 | 
2026-07-27 18:25:17.308146 | TASK [Set zuul-log-path fact]
2026-07-27 18:25:17.331530 | localhost | ok
2026-07-27 18:25:17.346308 | 
2026-07-27 18:25:17.346376 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-27 18:25:17.378425 | localhost | ok
2026-07-27 18:25:17.389147 | 
2026-07-27 18:25:17.389213 | TASK [upload-logs : Create log directories]
2026-07-27 18:25:17.827055 | localhost | changed
2026-07-27 18:25:17.834574 | 
2026-07-27 18:25:17.834684 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-07-27 18:25:18.246418 | localhost -> localhost | ok: Runtime: 0:00:00.005423
2026-07-27 18:25:18.253441 | 
2026-07-27 18:25:18.253551 | TASK [upload-logs : Upload logs to log server]
2026-07-27 18:25:18.755160 | localhost | Output suppressed because no_log was given
2026-07-27 18:25:18.760134 | 
2026-07-27 18:25:18.760201 | LOOP [upload-logs : Compress console log and json output]
2026-07-27 18:25:18.819315 | localhost | skipping: Conditional result was False
2026-07-27 18:25:18.877693 | localhost | skipping: Conditional result was False
2026-07-27 18:25:18.903337 | 
2026-07-27 18:25:18.903499 | LOOP [upload-logs : Upload compressed console log and json output]
2026-07-27 18:25:18.950686 | localhost | skipping: Conditional result was False
2026-07-27 18:25:18.951063 | 
2026-07-27 18:25:18.955317 | localhost | skipping: Conditional result was False
2026-07-27 18:25:18.963356 | 
2026-07-27 18:25:18.963459 | LOOP [upload-logs : Upload console log and json output]