2026-08-23 00:26:05.924619 | Job console starting
2026-08-23 00:26:05.934948 | Updating git repos
2026-08-23 00:26:05.989614 | Cloning repos into workspace
2026-08-23 00:26:06.316978 | Restoring repo states
2026-08-23 00:26:06.330076 | Merging changes
2026-08-23 00:26:07.162644 | Checking out repos
2026-08-23 00:26:07.379436 | Preparing playbooks
2026-08-23 00:26:09.111139 | Running Ansible setup
2026-08-23 00:26:12.567561 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-23 00:26:13.157913 | 
2026-08-23 00:26:13.158031 | PLAY [localhost]
2026-08-23 00:26:13.165492 | 
2026-08-23 00:26:13.165561 | TASK [Gathering Facts]
2026-08-23 00:26:14.017052 | localhost | ok
2026-08-23 00:26:14.024351 | 
2026-08-23 00:26:14.024440 | TASK [Setup log path fact]
2026-08-23 00:26:14.043390 | localhost | ok
2026-08-23 00:26:14.057715 | 
2026-08-23 00:26:14.365894 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-23 00:26:14.415000 | localhost | ok
2026-08-23 00:26:14.433421 | 
2026-08-23 00:26:14.433548 | TASK [emit-job-header : Print job information]
2026-08-23 00:26:14.475391 | # Job Information
2026-08-23 00:26:14.475626 | Ansible Version: 2.16.18
2026-08-23 00:26:14.475674 | Job: ansible-collection-kubernetes-molecule-helm
2026-08-23 00:26:14.475708 | Pipeline: check
2026-08-23 00:26:14.475738 | Executor: 2d72f0692154
2026-08-23 00:26:14.475768 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/293
2026-08-23 00:26:14.475800 | Event ID: bb812560-9e88-11f1-8d3b-8cb37988adc1
2026-08-23 00:26:14.479811 | 
2026-08-23 00:26:14.479898 | LOOP [emit-job-header : Print node information]
2026-08-23 00:26:14.563506 | localhost | ok:
2026-08-23 00:26:14.563826 | localhost | # Node Information
2026-08-23 00:26:14.563857 | localhost | Inventory Hostname: controller
2026-08-23 00:26:14.563880 | localhost | Hostname: np0000190742
2026-08-23 00:26:14.563901 | localhost | Username: zuul
2026-08-23 00:26:14.563924 | localhost | Distro: Debian 13.6
2026-08-23 00:26:14.563944 | localhost | Provider: yul1
2026-08-23 00:26:14.563964 | localhost | Region: ca-ymq-1
2026-08-23 00:26:14.563983 | localhost | Label: debian-trixie
2026-08-23 00:26:14.564002 | localhost | Product Name: OpenStack Nova
2026-08-23 00:26:14.564021 | localhost | Interface IP: 199.204.45.162
2026-08-23 00:26:14.579738 | 
2026-08-23 00:26:14.579914 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-23 00:26:15.001495 | localhost -> localhost | changed
2026-08-23 00:26:15.011824 | 
2026-08-23 00:26:15.011976 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-23 00:26:15.816419 | localhost -> localhost | changed
2026-08-23 00:26:15.826581 | 
2026-08-23 00:26:15.826653 | PLAY [all]
2026-08-23 00:26:15.835514 | 
2026-08-23 00:26:15.835608 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-23 00:26:16.051775 | controller -> localhost | ok
2026-08-23 00:26:16.057901 | 
2026-08-23 00:26:16.057988 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-23 00:26:16.085915 | controller | ok
2026-08-23 00:26:16.099116 | controller | included: /var/lib/zuul/builds/c97d012cf6e94cb59af31e225b81b149/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-23 00:26:16.103569 | 
2026-08-23 00:26:16.103630 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-23 00:26:17.770792 | controller -> localhost | Generating public/private rsa key pair.
2026-08-23 00:26:17.771055 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/c97d012cf6e94cb59af31e225b81b149/work/c97d012cf6e94cb59af31e225b81b149_id_rsa
2026-08-23 00:26:17.771149 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/c97d012cf6e94cb59af31e225b81b149/work/c97d012cf6e94cb59af31e225b81b149_id_rsa.pub
2026-08-23 00:26:17.771202 | controller -> localhost | The key fingerprint is:
2026-08-23 00:26:17.771251 | controller -> localhost | SHA256:NPBpgfu69RxQO2WHoZgxn/pc3rXcP/pjPCsavwVUo3w zuul-build-sshkey
2026-08-23 00:26:17.771318 | controller -> localhost | The key's randomart image is:
2026-08-23 00:26:17.771368 | controller -> localhost | +---[RSA 3072]----+
2026-08-23 00:26:17.771424 | controller -> localhost | |      ..+   .  o |
2026-08-23 00:26:17.771474 | controller -> localhost | |      .o O o.oo .|
2026-08-23 00:26:17.771522 | controller -> localhost | |       .O = ++.E |
2026-08-23 00:26:17.771568 | controller -> localhost | |      .o + +...  |
2026-08-23 00:26:17.771614 | controller -> localhost | |       .S o ..  .|
2026-08-23 00:26:17.771658 | controller -> localhost | |        .+ + .o.o|
2026-08-23 00:26:17.771701 | controller -> localhost | |       .. +.. o+.|
2026-08-23 00:26:17.771748 | controller -> localhost | |      .. o .o..*.|
2026-08-23 00:26:17.771799 | controller -> localhost | |      ..  o..+*o*|
2026-08-23 00:26:17.771845 | controller -> localhost | +----[SHA256]-----+
2026-08-23 00:26:17.771942 | controller -> localhost | ok: Runtime: 0:00:01.256753
2026-08-23 00:26:17.778831 | 
2026-08-23 00:26:17.778897 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-23 00:26:17.835627 | controller | ok
2026-08-23 00:26:17.843645 | controller | included: /var/lib/zuul/builds/c97d012cf6e94cb59af31e225b81b149/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-23 00:26:17.850366 | 
2026-08-23 00:26:17.850428 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-23 00:26:17.875621 | controller | skipping: Conditional result was False
2026-08-23 00:26:17.884398 | 
2026-08-23 00:26:17.884552 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-23 00:26:18.395682 | controller | changed
2026-08-23 00:26:18.401603 | 
2026-08-23 00:26:18.401675 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-23 00:26:18.621536 | controller | ok
2026-08-23 00:26:18.627668 | 
2026-08-23 00:26:18.627743 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-23 00:26:19.153419 | controller | changed
2026-08-23 00:26:19.159134 | 
2026-08-23 00:26:19.159223 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-23 00:26:19.697253 | controller | changed
2026-08-23 00:26:19.701951 | 
2026-08-23 00:26:19.702017 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-23 00:26:19.716492 | controller | skipping: Conditional result was False
2026-08-23 00:26:19.723665 | 
2026-08-23 00:26:19.723785 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-23 00:26:20.072599 | controller -> localhost | changed
2026-08-23 00:26:20.085806 | 
2026-08-23 00:26:20.085896 | TASK [add-build-sshkey : Add back temp key]
2026-08-23 00:26:20.390899 | controller -> localhost | Identity added: /var/lib/zuul/builds/c97d012cf6e94cb59af31e225b81b149/work/c97d012cf6e94cb59af31e225b81b149_id_rsa (zuul-build-sshkey)
2026-08-23 00:26:20.391099 | controller -> localhost | ok: Runtime: 0:00:00.015453
2026-08-23 00:26:20.395195 | 
2026-08-23 00:26:20.395255 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-23 00:26:20.722390 | controller | ok
2026-08-23 00:26:20.727429 | 
2026-08-23 00:26:20.727514 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-23 00:26:20.756182 | controller | skipping: Conditional result was False
2026-08-23 00:26:20.769509 | 
2026-08-23 00:26:20.769603 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-23 00:26:21.115530 | controller | ok
2026-08-23 00:26:21.122836 | 
2026-08-23 00:26:21.122901 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-23 00:26:22.113096 | controller | Output suppressed because no_log was given
2026-08-23 00:26:22.121711 | 
2026-08-23 00:26:22.121773 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-23 00:26:22.327729 | controller | ok: "logs"
2026-08-23 00:26:22.328120 | controller | ok: All items complete
2026-08-23 00:26:22.328187 | 
2026-08-23 00:26:22.524536 | controller | ok: "artifacts"
2026-08-23 00:26:22.706270 | controller | ok: "docs"
2026-08-23 00:26:22.713573 | 
2026-08-23 00:26:22.713702 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-23 00:26:22.930751 | controller | changed: "logs"
2026-08-23 00:26:23.128487 | controller | changed: "artifacts"
2026-08-23 00:26:23.322762 | controller | changed: "docs"
2026-08-23 00:26:23.342441 | 
2026-08-23 00:26:23.342540 | PLAY RECAP
2026-08-23 00:26:23.342588 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-23 00:26:23.342617 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-23 00:26:23.342638 | 
2026-08-23 00:26:23.478987 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-23 00:26:23.480852 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-23 00:26:24.059998 | 
2026-08-23 00:26:24.060125 | PLAY [all]
2026-08-23 00:26:24.069153 | 
2026-08-23 00:26:24.069227 | TASK [setup-uv : Extract archive]
2026-08-23 00:26:26.396934 | controller | changed
2026-08-23 00:26:26.403332 | 
2026-08-23 00:26:26.403440 | TASK [setup-uv : Print version]
2026-08-23 00:26:26.756756 | controller | uv 0.8.13
2026-08-23 00:26:26.934953 | controller | ok: Runtime: 0:00:00.014364
2026-08-23 00:26:26.945662 | 
2026-08-23 00:26:26.945753 | TASK [Install Ansible collection siblings]
2026-08-23 00:26:27.333673 | controller | ok:
2026-08-23 00:26:27.333923 | controller | Skipping ansible.posix: no checked-out sibling
2026-08-23 00:26:27.333987 | controller | Skipping community.crypto: no checked-out sibling
2026-08-23 00:26:27.334036 | controller | Skipping community.general: no checked-out sibling
2026-08-23 00:26:27.334081 | controller | Skipping kubernetes.core: no checked-out sibling
2026-08-23 00:26:27.334127 | controller | Skipping vexxhost.containers: no checked-out sibling
2026-08-23 00:26:27.336201 | 
2026-08-23 00:26:27.336246 | PLAY RECAP
2026-08-23 00:26:27.336291 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-23 00:26:27.336314 | 
2026-08-23 00:26:27.440996 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-23 00:26:27.443759 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-08-23 00:26:27.999639 | 
2026-08-23 00:26:27.999786 | PLAY [all]
2026-08-23 00:26:28.009577 | 
2026-08-23 00:26:28.009655 | TASK [Update apt cache]
2026-08-23 00:26:32.912821 | controller | ok
2026-08-23 00:26:32.918240 | 
2026-08-23 00:26:32.918338 | TASK [Disable firewalld]
2026-08-23 00:26:32.943351 | controller | skipping: Conditional result was False
2026-08-23 00:26:32.950835 | 
2026-08-23 00:26:32.950921 | TASK [Install "jq" for log collection]
2026-08-23 00:26:35.363700 | controller | changed
2026-08-23 00:26:35.366417 | 
2026-08-23 00:26:35.366487 | PLAY RECAP
2026-08-23 00:26:35.366552 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-23 00:26:35.366583 | 
2026-08-23 00:26:35.489802 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-08-23 00:26:35.491708 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-23 00:26:36.066061 | 
2026-08-23 00:26:36.066189 | PLAY [all]
2026-08-23 00:26:36.075600 | 
2026-08-23 00:26:36.075669 | LOOP [Check inventory directories]
2026-08-23 00:26:36.435921 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-08-23 00:26:36.607567 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/helm/inventory"
2026-08-23 00:26:36.773089 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/inventory"
2026-08-23 00:26:36.785086 | 
2026-08-23 00:26:36.785309 | LOOP [Copy inventory file for Zuul]
2026-08-23 00:26:37.612722 | controller | changed:
2026-08-23 00:26:37.612890 | controller | {
2026-08-23 00:26:37.612918 | controller |   "ansible_loop_var": "item",
2026-08-23 00:26:37.612940 | controller |   "changed": false,
2026-08-23 00:26:37.612976 | controller |   "failed": false,
2026-08-23 00:26:37.613000 | controller |   "invocation": {
2026-08-23 00:26:37.613019 | controller |     "module_args": {
2026-08-23 00:26:37.613043 | controller |       "checksum_algorithm": "sha1",
2026-08-23 00:26:37.613069 | controller |       "follow": true,
2026-08-23 00:26:37.613096 | controller |       "get_attributes": true,
2026-08-23 00:26:37.613119 | controller |       "get_checksum": true,
2026-08-23 00:26:37.613138 | controller |       "get_mime": true,
2026-08-23 00:26:37.613155 | controller |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-08-23 00:26:37.613173 | controller |     }
2026-08-23 00:26:37.613192 | controller |   },
2026-08-23 00:26:37.613210 | controller |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-08-23 00:26:37.613228 | controller |   "stat": {
2026-08-23 00:26:37.613246 | controller |     "atime": 1787444781.9792447,
2026-08-23 00:26:37.613264 | controller |     "attr_flags": "e",
2026-08-23 00:26:37.613281 | controller |     "attributes": [
2026-08-23 00:26:37.613298 | controller |       "extents"
2026-08-23 00:26:37.613315 | controller |     ],
2026-08-23 00:26:37.613332 | controller |     "block_size": 4096,
2026-08-23 00:26:37.613350 | controller |     "blocks": 8,
2026-08-23 00:26:37.613368 | controller |     "charset": "unknown",
2026-08-23 00:26:37.613387 | controller |     "ctime": 1787444782.0032477,
2026-08-23 00:26:37.613405 | controller |     "dev": 65025,
2026-08-23 00:26:37.613422 | controller |     "device_type": 0,
2026-08-23 00:26:37.613440 | controller |     "executable": true,
2026-08-23 00:26:37.613457 | controller |     "exists": true,
2026-08-23 00:26:37.613474 | controller |     "gid": 1000,
2026-08-23 00:26:37.613492 | controller |     "gr_name": "zuul",
2026-08-23 00:26:37.613509 | controller |     "inode": 520215,
2026-08-23 00:26:37.613525 | controller |     "isblk": false,
2026-08-23 00:26:37.613543 | controller |     "ischr": false,
2026-08-23 00:26:37.613560 | controller |     "isdir": true,
2026-08-23 00:26:37.613577 | controller |     "isfifo": false,
2026-08-23 00:26:37.613594 | controller |     "isgid": false,
2026-08-23 00:26:37.613610 | controller |     "islnk": false,
2026-08-23 00:26:37.613627 | controller |     "isreg": false,
2026-08-23 00:26:37.613644 | controller |     "issock": false,
2026-08-23 00:26:37.613661 | controller |     "isuid": false,
2026-08-23 00:26:37.613677 | controller |     "mimetype": "unknown",
2026-08-23 00:26:37.613694 | controller |     "mode": "0755",
2026-08-23 00:26:37.613710 | controller |     "mtime": 1787444766.467295,
2026-08-23 00:26:37.613727 | controller |     "nlink": 14,
2026-08-23 00:26:37.613744 | controller |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-08-23 00:26:37.613761 | controller |     "pw_name": "zuul",
2026-08-23 00:26:37.613782 | controller |     "readable": true,
2026-08-23 00:26:37.613800 | controller |     "rgrp": true,
2026-08-23 00:26:37.613818 | controller |     "roth": true,
2026-08-23 00:26:37.613836 | controller |     "rusr": true,
2026-08-23 00:26:37.613853 | controller |     "size": 4096,
2026-08-23 00:26:37.613870 | controller |     "uid": 1000,
2026-08-23 00:26:37.613887 | controller |     "version": "4067912062",
2026-08-23 00:26:37.613906 | controller |     "wgrp": false,
2026-08-23 00:26:37.613925 | controller |     "woth": false,
2026-08-23 00:26:37.613943 | controller |     "writeable": true,
2026-08-23 00:26:37.613961 | controller |     "wusr": true,
2026-08-23 00:26:37.613979 | controller |     "xgrp": true,
2026-08-23 00:26:37.613999 | controller |     "xoth": true,
2026-08-23 00:26:37.614024 | controller |     "xusr": true
2026-08-23 00:26:37.614048 | controller |   }
2026-08-23 00:26:37.614084 | controller | }
2026-08-23 00:26:37.614124 | controller | ok: All items complete
2026-08-23 00:26:37.614148 | 
2026-08-23 00:26:37.615379 | controller | skipping: Conditional result was False
2026-08-23 00:26:37.615987 | controller | skipping: Conditional result was False
2026-08-23 00:26:37.629924 | 
2026-08-23 00:26:37.630074 | LOOP [Switch "ansible_host" to private IP]
2026-08-23 00:26:37.988508 | controller | changed: 1 replacements made
2026-08-23 00:26:37.989721 | controller | skipping: Conditional result was False
2026-08-23 00:26:37.990309 | controller | skipping: Conditional result was False
2026-08-23 00:26:38.008200 | 
2026-08-23 00:26:38.008341 | TASK [Run Molecule scenario]
2026-08-23 00:26:38.433440 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-08-23 00:26:38.433546 | controller | Creating virtual environment at: .venv
2026-08-23 00:26:38.741855 | controller | Downloading cryptography (4.3MiB)
2026-08-23 00:26:38.744670 | controller | Downloading ansible-core (2.3MiB)
2026-08-23 00:26:38.746384 | controller | Downloading pygments (1.2MiB)
2026-08-23 00:26:38.960965 | controller |  Downloading pygments
2026-08-23 00:26:38.971220 | controller |  Downloading cryptography
2026-08-23 00:26:39.131787 | controller |  Downloading ansible-core
2026-08-23 00:26:39.177305 | controller | Installed 36 packages in 44ms
2026-08-23 00:26:39.850344 | controller | INFO     Collection 'vexxhost.kubernetes' detected.
2026-08-23 00:26:39.850397 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-08-23 00:26:40.687898 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-08-23 00:26:40.687940 | controller | INFO     default scenario not found, disabling shared state.
2026-08-23 00:26:40.688072 | controller | INFO     [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-08-23 00:26:40.688146 | controller | INFO     [helm > prerun] Performing prerun with role_name_check=0...
2026-08-23 00:27:07.907979 | controller | INFO     [helm > dependency] Executing
2026-08-23 00:27:07.908052 | controller | WARNING  [helm > dependency] Missing roles requirements file: requirements.yml
2026-08-23 00:27:07.908297 | controller | WARNING  [helm > dependency] Missing collections requirements file: collections.yml
2026-08-23 00:27:07.908404 | controller | WARNING  [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-08-23 00:27:07.914307 | controller | INFO     [helm > cleanup] Executing
2026-08-23 00:27:07.914695 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-23 00:27:07.920096 | controller | INFO     [helm > destroy] Executing
2026-08-23 00:27:07.920121 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-08-23 00:27:07.920182 | controller | INFO     [helm > destroy] Executed: Successful
2026-08-23 00:27:07.925655 | controller | INFO     [helm > syntax] Executing
2026-08-23 00:27:08.848546 | controller |
2026-08-23 00:27:08.848780 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml
2026-08-23 00:27:08.940860 | controller | INFO     [helm > syntax] Executed: Successful
2026-08-23 00:27:08.950320 | controller | INFO     [helm > create] Executing
2026-08-23 00:27:08.951813 | controller | WARNING  [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-23 00:27:08.957165 | controller | INFO     [helm > prepare] Executing
2026-08-23 00:27:09.805398 | controller |
2026-08-23 00:27:09.805573 | controller | PLAY [Prepare] *****************************************************************
2026-08-23 00:27:09.805760 | controller |
2026-08-23 00:27:09.805952 | controller | TASK [Gathering Facts] *********************************************************
2026-08-23 00:27:09.806176 | controller | Sunday 23 August 2026  00:27:09 +0000 (0:00:00.031)       0:00:00.031 *********
2026-08-23 00:27:12.053775 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information.
2026-08-23 00:27:12.059854 | controller | ok: [controller]
2026-08-23 00:27:12.060045 | controller |
2026-08-23 00:27:12.060250 | controller | TASK [Install Debian packages] *************************************************
2026-08-23 00:27:12.060461 | controller | Sunday 23 August 2026  00:27:12 +0000 (0:00:02.254)       0:00:02.286 *********
2026-08-23 00:27:13.683836 | controller | ok: [controller]
2026-08-23 00:27:13.684010 | controller |
2026-08-23 00:27:13.684209 | controller | PLAY RECAP *********************************************************************
2026-08-23 00:27:13.684458 | controller | controller                 : ok=2    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-08-23 00:27:13.684651 | controller |
2026-08-23 00:27:13.684856 | controller |
2026-08-23 00:27:13.685038 | controller | TASKS RECAP ********************************************************************
2026-08-23 00:27:13.685233 | controller | Sunday 23 August 2026  00:27:13 +0000 (0:00:01.625)       0:00:03.911 *********
2026-08-23 00:27:13.685411 | controller | ===============================================================================
2026-08-23 00:27:13.685597 | controller | Gathering Facts --------------------------------------------------------- 2.25s
2026-08-23 00:27:13.685784 | controller | Install Debian packages ------------------------------------------------- 1.63s
2026-08-23 00:27:13.772302 | controller | INFO     [helm > prepare] Executed: Successful
2026-08-23 00:27:13.783323 | controller | INFO     [helm > converge] Executing
2026-08-23 00:27:14.610676 | controller |
2026-08-23 00:27:14.610870 | controller | PLAY [Converge] ****************************************************************
2026-08-23 00:27:14.611055 | controller |
2026-08-23 00:27:14.611253 | controller | TASK [Gathering Facts] *********************************************************
2026-08-23 00:27:14.611402 | controller | Sunday 23 August 2026  00:27:14 +0000 (0:00:00.013)       0:00:00.013 *********
2026-08-23 00:27:15.702278 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information.
2026-08-23 00:27:15.707236 | controller | ok: [controller]
2026-08-23 00:27:15.707416 | controller |
2026-08-23 00:27:15.707633 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-23 00:27:15.707826 | controller | Sunday 23 August 2026  00:27:15 +0000 (0:00:01.096)       0:00:01.109 *********
2026-08-23 00:27:16.183237 | controller | ok: [controller]
2026-08-23 00:27:16.183379 | controller |
2026-08-23 00:27:16.183584 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-23 00:27:16.183784 | controller | Sunday 23 August 2026  00:27:16 +0000 (0:00:00.475)       0:00:01.585 *********
2026-08-23 00:27:17.269113 | controller | ok: [controller]
2026-08-23 00:27:17.269311 | controller |
2026-08-23 00:27:17.269491 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-08-23 00:27:17.269698 | controller | Sunday 23 August 2026  00:27:17 +0000 (0:00:01.085)       0:00:02.671 *********
2026-08-23 00:27:17.700896 | controller | changed: [controller]
2026-08-23 00:27:17.701045 | controller |
2026-08-23 00:27:17.701305 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-23 00:27:17.701486 | controller | Sunday 23 August 2026  00:27:17 +0000 (0:00:00.431)       0:00:03.102 *********
2026-08-23 00:27:17.753512 | controller | ok: [controller] => {
2026-08-23 00:27:17.753723 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-08-23 00:27:17.753973 | controller | }
2026-08-23 00:27:17.754249 | controller |
2026-08-23 00:27:17.754535 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-23 00:27:17.754942 | controller | Sunday 23 August 2026  00:27:17 +0000 (0:00:00.052)       0:00:03.155 *********
2026-08-23 00:27:18.584814 | controller | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created with a mode of 0700, this may cause issues when running as another user. To avoid this, create the remote_tmp dir with the correct permissions manually
2026-08-23 00:27:18.595073 | controller | changed: [controller]
2026-08-23 00:27:18.595365 | controller |
2026-08-23 00:27:18.595615 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-23 00:27:18.595820 | controller | Sunday 23 August 2026  00:27:18 +0000 (0:00:00.841)       0:00:03.996 *********
2026-08-23 00:27:20.662606 | controller | changed: [controller]
2026-08-23 00:27:20.662628 | controller |
2026-08-23 00:27:20.662633 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-08-23 00:27:20.662636 | controller | Sunday 23 August 2026  00:27:20 +0000 (0:00:02.064)       0:00:06.061 *********
2026-08-23 00:27:21.142264 | controller | ok: [controller]
2026-08-23 00:27:21.142376 | controller |
2026-08-23 00:27:21.142513 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-08-23 00:27:21.142701 | controller | Sunday 23 August 2026  00:27:21 +0000 (0:00:00.482)       0:00:06.544 *********
2026-08-23 00:27:21.540889 | controller | changed: [controller]
2026-08-23 00:27:21.540948 | controller |
2026-08-23 00:27:21.541108 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-08-23 00:27:21.541237 | controller | Sunday 23 August 2026  00:27:21 +0000 (0:00:00.398)       0:00:06.943 *********
2026-08-23 00:27:22.007108 | controller | ok: [controller]
2026-08-23 00:27:22.007199 | controller |
2026-08-23 00:27:22.007353 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-08-23 00:27:22.007485 | controller | Sunday 23 August 2026  00:27:22 +0000 (0:00:00.465)       0:00:07.408 *********
2026-08-23 00:27:22.295115 | controller | changed: [controller]
2026-08-23 00:27:22.295197 | controller |
2026-08-23 00:27:22.295349 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-08-23 00:27:22.295494 | controller | Sunday 23 August 2026  00:27:22 +0000 (0:00:00.288)       0:00:07.696 *********
2026-08-23 00:27:22.595074 | controller | ok: [controller]
2026-08-23 00:27:22.595153 | controller |
2026-08-23 00:27:22.597433 | controller | TASK [Install plugin] **********************************************************
2026-08-23 00:27:22.597452 | controller | Sunday 23 August 2026  00:27:22 +0000 (0:00:00.296)       0:00:07.993 *********
2026-08-23 00:27:22.656073 | controller | included: vexxhost.containers.download_artifact for controller
2026-08-23 00:27:22.656272 | controller |
2026-08-23 00:27:22.656546 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-23 00:27:22.656755 | controller | Sunday 23 August 2026  00:27:22 +0000 (0:00:00.064)       0:00:08.058 *********
2026-08-23 00:27:22.702453 | controller | ok: [controller] => {
2026-08-23 00:27:22.702686 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-08-23 00:27:22.702839 | controller | }
2026-08-23 00:27:22.702992 | controller |
2026-08-23 00:27:22.703139 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-23 00:27:22.703308 | controller | Sunday 23 August 2026  00:27:22 +0000 (0:00:00.045)       0:00:08.103 *********
2026-08-23 00:27:23.600943 | controller | changed: [controller]
2026-08-23 00:27:23.601120 | controller |
2026-08-23 00:27:23.601335 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-23 00:27:23.601527 | controller | Sunday 23 August 2026  00:27:23 +0000 (0:00:00.898)       0:00:09.001 *********
2026-08-23 00:27:25.668321 | controller | changed: [controller]
2026-08-23 00:27:25.668379 | controller |
2026-08-23 00:27:25.668389 | controller | PLAY RECAP *********************************************************************
2026-08-23 00:27:25.668407 | controller | controller                 : ok=16   changed=7    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-08-23 00:27:25.668477 | controller |
2026-08-23 00:27:25.668646 | controller |
2026-08-23 00:27:25.668840 | controller | TASKS RECAP ********************************************************************
2026-08-23 00:27:25.669041 | controller | Sunday 23 August 2026  00:27:25 +0000 (0:00:02.066)       0:00:11.068 *********
2026-08-23 00:27:25.669252 | controller | ===============================================================================
2026-08-23 00:27:25.669467 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.07s
2026-08-23 00:27:25.669676 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.06s
2026-08-23 00:27:25.669936 | controller | Gathering Facts --------------------------------------------------------- 1.10s
2026-08-23 00:27:25.670149 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.09s
2026-08-23 00:27:25.670360 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.90s
2026-08-23 00:27:25.670646 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.84s
2026-08-23 00:27:25.672568 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.48s
2026-08-23 00:27:25.672608 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.48s
2026-08-23 00:27:25.672618 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.47s
2026-08-23 00:27:25.672625 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.43s
2026-08-23 00:27:25.672646 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.40s
2026-08-23 00:27:25.672654 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.30s
2026-08-23 00:27:25.672661 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.29s
2026-08-23 00:27:25.672669 | controller | Install plugin ---------------------------------------------------------- 0.06s
2026-08-23 00:27:25.672676 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-08-23 00:27:25.672683 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-08-23 00:27:25.765434 | controller | INFO     [helm > converge] Executed: Successful
2026-08-23 00:27:25.775576 | controller | INFO     [helm > idempotence] Executing
2026-08-23 00:27:26.634846 | controller |
2026-08-23 00:27:26.635181 | controller | PLAY [Converge] ****************************************************************
2026-08-23 00:27:26.635421 | controller |
2026-08-23 00:27:26.635699 | controller | TASK [Gathering Facts] *********************************************************
2026-08-23 00:27:26.635982 | controller | Sunday 23 August 2026  00:27:26 +0000 (0:00:00.012)       0:00:00.012 *********
2026-08-23 00:27:27.702417 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information.
2026-08-23 00:27:27.707687 | controller | ok: [controller]
2026-08-23 00:27:27.707971 | controller |
2026-08-23 00:27:27.708258 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-23 00:27:27.708517 | controller | Sunday 23 August 2026  00:27:27 +0000 (0:00:01.072)       0:00:01.085 *********
2026-08-23 00:27:28.137117 | controller | ok: [controller]
2026-08-23 00:27:28.137406 | controller |
2026-08-23 00:27:28.137696 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-23 00:27:28.137954 | controller | Sunday 23 August 2026  00:27:28 +0000 (0:00:00.429)       0:00:01.515 *********
2026-08-23 00:27:29.109157 | controller | ok: [controller]
2026-08-23 00:27:29.109446 | controller |
2026-08-23 00:27:29.109727 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-08-23 00:27:29.109994 | controller | Sunday 23 August 2026  00:27:29 +0000 (0:00:00.971)       0:00:02.486 *********
2026-08-23 00:27:29.544415 | controller | ok: [controller]
2026-08-23 00:27:29.544758 | controller |
2026-08-23 00:27:29.545054 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-23 00:27:29.545348 | controller | Sunday 23 August 2026  00:27:29 +0000 (0:00:00.435)       0:00:02.921 *********
2026-08-23 00:27:29.592183 | controller | ok: [controller] => {
2026-08-23 00:27:29.592507 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-08-23 00:27:29.592764 | controller | }
2026-08-23 00:27:29.592996 | controller |
2026-08-23 00:27:29.593282 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-23 00:27:29.593573 | controller | Sunday 23 August 2026  00:27:29 +0000 (0:00:00.048)       0:00:02.969 *********
2026-08-23 00:27:30.112808 | controller | ok: [controller]
2026-08-23 00:27:30.113078 | controller |
2026-08-23 00:27:30.113337 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-23 00:27:30.113587 | controller | Sunday 23 August 2026  00:27:30 +0000 (0:00:00.519)       0:00:03.489 *********
2026-08-23 00:27:31.834970 | controller | ok: [controller]
2026-08-23 00:27:31.835142 | controller |
2026-08-23 00:27:31.835307 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-08-23 00:27:31.835466 | controller | Sunday 23 August 2026  00:27:31 +0000 (0:00:01.720)       0:00:05.209 *********
2026-08-23 00:27:32.331055 | controller | ok: [controller]
2026-08-23 00:27:32.331112 | controller |
2026-08-23 00:27:32.331213 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-08-23 00:27:32.331373 | controller | Sunday 23 August 2026  00:27:32 +0000 (0:00:00.497)       0:00:05.707 *********
2026-08-23 00:27:32.731019 | controller | ok: [controller]
2026-08-23 00:27:32.731060 | controller |
2026-08-23 00:27:32.731164 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-08-23 00:27:32.731260 | controller | Sunday 23 August 2026  00:27:32 +0000 (0:00:00.399)       0:00:06.107 *********
2026-08-23 00:27:33.199183 | controller | ok: [controller]
2026-08-23 00:27:33.199250 | controller |
2026-08-23 00:27:33.199395 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-08-23 00:27:33.199527 | controller | Sunday 23 August 2026  00:27:33 +0000 (0:00:00.469)       0:00:06.576 *********
2026-08-23 00:27:33.511902 | controller | ok: [controller]
2026-08-23 00:27:33.511939 | controller |
2026-08-23 00:27:33.511949 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-08-23 00:27:33.511956 | controller | Sunday 23 August 2026  00:27:33 +0000 (0:00:00.310)       0:00:06.887 *********
2026-08-23 00:27:33.805438 | controller | ok: [controller]
2026-08-23 00:27:33.805458 | controller |
2026-08-23 00:27:33.805462 | controller | TASK [Install plugin] **********************************************************
2026-08-23 00:27:33.805466 | controller | Sunday 23 August 2026  00:27:33 +0000 (0:00:00.293)       0:00:07.180 *********
2026-08-23 00:27:33.860535 | controller | included: vexxhost.containers.download_artifact for controller
2026-08-23 00:27:33.860556 | controller |
2026-08-23 00:27:33.860560 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-23 00:27:33.860564 | controller | Sunday 23 August 2026  00:27:33 +0000 (0:00:00.057)       0:00:07.238 *********
2026-08-23 00:27:33.915596 | controller | ok: [controller] => {
2026-08-23 00:27:33.915629 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-08-23 00:27:33.915635 | controller | }
2026-08-23 00:27:33.915641 | controller |
2026-08-23 00:27:33.915646 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-23 00:27:33.915651 | controller | Sunday 23 August 2026  00:27:33 +0000 (0:00:00.052)       0:00:07.290 *********
2026-08-23 00:27:34.343531 | controller | ok: [controller]
2026-08-23 00:27:34.346911 | controller |
2026-08-23 00:27:34.346964 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-23 00:27:34.346986 | controller | Sunday 23 August 2026  00:27:34 +0000 (0:00:00.430)       0:00:07.721 *********
2026-08-23 00:27:36.057967 | controller | ok: [controller]
2026-08-23 00:27:36.058022 | controller |
2026-08-23 00:27:36.058032 | controller | PLAY RECAP *********************************************************************
2026-08-23 00:27:36.058126 | controller | controller                 : ok=16   changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-08-23 00:27:36.058206 | controller |
2026-08-23 00:27:36.058286 | controller |
2026-08-23 00:27:36.058381 | controller | TASKS RECAP ********************************************************************
2026-08-23 00:27:36.058457 | controller | Sunday 23 August 2026  00:27:36 +0000 (0:00:01.715)       0:00:09.436 *********
2026-08-23 00:27:36.058539 | controller | ===============================================================================
2026-08-23 00:27:36.058667 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.72s
2026-08-23 00:27:36.058749 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.72s
2026-08-23 00:27:36.058835 | controller | Gathering Facts --------------------------------------------------------- 1.07s
2026-08-23 00:27:36.058923 | controller | vexxhost.containers.package : Update state for tar ---------------------- 0.97s
2026-08-23 00:27:36.059010 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.52s
2026-08-23 00:27:36.059098 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.50s
2026-08-23 00:27:36.059185 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.47s
2026-08-23 00:27:36.059272 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.44s
2026-08-23 00:27:36.059359 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.43s
2026-08-23 00:27:36.059976 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.43s
2026-08-23 00:27:36.060005 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.40s
2026-08-23 00:27:36.060009 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.31s
2026-08-23 00:27:36.060013 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.29s
2026-08-23 00:27:36.060017 | controller | Install plugin ---------------------------------------------------------- 0.06s
2026-08-23 00:27:36.060020 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-08-23 00:27:36.060023 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-08-23 00:27:36.162047 | controller | INFO     [helm > idempotence] Executed: Successful
2026-08-23 00:27:36.172540 | controller | INFO     [helm > side_effect] Executing
2026-08-23 00:27:36.173050 | controller | WARNING  [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-23 00:27:36.179218 | controller | INFO     [helm > verify] Executing
2026-08-23 00:27:36.967301 | controller |
2026-08-23 00:27:36.967622 | controller | PLAY [Verify] ******************************************************************
2026-08-23 00:27:36.967860 | controller |
2026-08-23 00:27:36.968113 | controller | TASK [Gathering Facts] *********************************************************
2026-08-23 00:27:36.968366 | controller | Sunday 23 August 2026  00:27:36 +0000 (0:00:00.009)       0:00:00.009 *********
2026-08-23 00:27:38.032484 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information.
2026-08-23 00:27:38.042048 | controller | ok: [controller]
2026-08-23 00:27:38.042072 | controller |
2026-08-23 00:27:38.042082 | controller | TASK [Get the current Helm version] ********************************************
2026-08-23 00:27:38.042091 | controller | Sunday 23 August 2026  00:27:38 +0000 (0:00:01.074)       0:00:01.084 *********
2026-08-23 00:27:38.506875 | controller | changed: [controller]
2026-08-23 00:27:38.507156 | controller |
2026-08-23 00:27:38.507418 | controller | TASK [Assert Helm version] *****************************************************
2026-08-23 00:27:38.507675 | controller | Sunday 23 August 2026  00:27:38 +0000 (0:00:00.465)       0:00:01.549 *********
2026-08-23 00:27:38.536861 | controller | ok: [controller] => {
2026-08-23 00:27:38.537134 | controller |     "changed": false,
2026-08-23 00:27:38.537383 | controller |     "msg": "All assertions passed"
2026-08-23 00:27:38.537621 | controller | }
2026-08-23 00:27:38.537850 | controller |
2026-08-23 00:27:38.538093 | controller | PLAY RECAP *********************************************************************
2026-08-23 00:27:38.538359 | controller | controller                 : ok=3    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-08-23 00:27:38.538768 | controller |
2026-08-23 00:27:38.539009 | controller |
2026-08-23 00:27:38.539251 | controller | TASKS RECAP ********************************************************************
2026-08-23 00:27:38.539501 | controller | Sunday 23 August 2026  00:27:38 +0000 (0:00:00.030)       0:00:01.580 *********
2026-08-23 00:27:38.539733 | controller | ===============================================================================
2026-08-23 00:27:38.540019 | controller | Gathering Facts --------------------------------------------------------- 1.07s
2026-08-23 00:27:38.540268 | controller | Get the current Helm version -------------------------------------------- 0.47s
2026-08-23 00:27:38.540510 | controller | Assert Helm version ----------------------------------------------------- 0.03s
2026-08-23 00:27:38.625289 | controller | INFO     [helm > verify] Executed: Successful
2026-08-23 00:27:38.634502 | controller | INFO     [helm > cleanup] Executing
2026-08-23 00:27:38.634935 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-23 00:27:38.642370 | controller | INFO     [helm > destroy] Executing
2026-08-23 00:27:38.642469 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-08-23 00:27:38.642579 | controller | INFO     [helm > destroy] Executed: Successful
2026-08-23 00:27:38.642819 | controller | WARNING  Molecule executed 1 scenario (1 missing files)
2026-08-23 00:27:39.093741 | controller | ok: Runtime: 0:01:00.375304
2026-08-23 00:27:39.100066 | 
2026-08-23 00:27:39.100111 | PLAY RECAP
2026-08-23 00:27:39.100152 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-23 00:27:39.100173 | 
2026-08-23 00:27:39.227047 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-23 00:27:39.230318 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-08-23 00:27:39.787955 | 
2026-08-23 00:27:39.788101 | PLAY [all]
2026-08-23 00:27:39.798819 | 
2026-08-23 00:27:39.798891 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-08-23 00:27:40.177962 | controller | changed
2026-08-23 00:27:40.183841 | 
2026-08-23 00:27:40.183923 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-08-23 00:27:40.557982 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-23 00:27:40.559041 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-23 00:27:40.565259 | controller | environment: line 1: kubectl: command not found
2026-08-23 00:27:40.567131 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-23 00:27:40.568505 | controller | environment: line 1: kubectl: command not found
2026-08-23 00:27:40.569022 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-23 00:27:40.571535 | controller | environment: line 1: kubectl: command not found
2026-08-23 00:27:40.572581 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-23 00:27:40.575279 | controller | environment: line 1: kubectl: command not found
2026-08-23 00:27:40.575595 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-23 00:27:40.577934 | controller | environment: line 1: kubectl: command not found
2026-08-23 00:27:40.578936 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-23 00:27:40.714407 | controller | ok: Runtime: 0:00:00.034847
2026-08-23 00:27:40.721158 | 
2026-08-23 00:27:40.721254 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-08-23 00:27:40.934370 | controller | changed
2026-08-23 00:27:40.940353 | 
2026-08-23 00:27:40.940454 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-08-23 00:27:41.188949 | controller | environment: line 5: kubectl: command not found
2026-08-23 00:27:41.189620 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-23 00:27:41.190057 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-23 00:27:41.190501 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-23 00:27:41.473388 | controller | ok: Runtime: 0:00:00.011733
2026-08-23 00:27:41.478707 | 
2026-08-23 00:27:41.478773 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-08-23 00:27:42.142069 | controller | changed:
2026-08-23 00:27:42.142271 | controller | created directory /var/lib/zuul/builds/c97d012cf6e94cb59af31e225b81b149/work/logs/controller
2026-08-23 00:27:42.142316 | controller | cd+++++++++ objects/
2026-08-23 00:27:42.142352 | controller | cd+++++++++ objects/cluster/
2026-08-23 00:27:42.142386 | controller | cd+++++++++ objects/namespaced/
2026-08-23 00:27:42.151833 | 
2026-08-23 00:27:42.151919 | TASK [gather-pod-logs : creating directory for pod logs]
2026-08-23 00:27:42.389059 | controller | changed
2026-08-23 00:27:42.392831 | 
2026-08-23 00:27:42.392897 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-08-23 00:27:42.625609 | controller | changed
2026-08-23 00:27:42.630845 | 
2026-08-23 00:27:42.630937 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-08-23 00:27:42.874196 | controller | environment: line 3: kubectl: command not found
2026-08-23 00:27:43.156904 | controller | ok: Runtime: 0:00:00.012283
2026-08-23 00:27:43.164592 | 
2026-08-23 00:27:43.164682 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-08-23 00:27:43.700800 | controller | changed:
2026-08-23 00:27:43.701307 | controller | cd+++++++++ pod-logs/
2026-08-23 00:27:43.701342 | controller | cd+++++++++ pod-logs/failed-pods/
2026-08-23 00:27:43.708043 | 
2026-08-23 00:27:43.708107 | LOOP [helm-release-status : creating directory for helm release status]
2026-08-23 00:27:43.953910 | controller | changed: "values"
2026-08-23 00:27:44.149801 | controller | changed: "releases"
2026-08-23 00:27:44.164404 | 
2026-08-23 00:27:44.164570 | TASK [helm-release-status : Gather get release status for helm charts]
2026-08-23 00:27:44.405531 | controller | /bin/bash: line 3: kubectl: command not found
2026-08-23 00:27:44.696495 | controller | ok: Runtime: 0:00:00.006210
2026-08-23 00:27:44.702631 | 
2026-08-23 00:27:44.702697 | TASK [helm-release-status : Downloads logs to executor]
2026-08-23 00:27:45.182967 | controller | changed:
2026-08-23 00:27:45.183229 | controller | cd+++++++++ helm/
2026-08-23 00:27:45.183293 | controller | cd+++++++++ helm/releases/
2026-08-23 00:27:45.183347 | controller | cd+++++++++ helm/values/
2026-08-23 00:27:45.189533 | 
2026-08-23 00:27:45.189581 | PLAY RECAP
2026-08-23 00:27:45.189625 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-23 00:27:45.189647 | 
2026-08-23 00:27:45.296769 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-08-23 00:27:45.299556 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-23 00:27:45.902390 | 
2026-08-23 00:27:45.902600 | PLAY [all]
2026-08-23 00:27:45.914036 | 
2026-08-23 00:27:45.914156 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-23 00:27:45.959152 | controller | skipping: Conditional result was False
2026-08-23 00:27:45.966558 | 
2026-08-23 00:27:45.966684 | TASK [fetch-output : Set log path for single node]
2026-08-23 00:27:46.008661 | controller | ok
2026-08-23 00:27:46.014117 | 
2026-08-23 00:27:46.014215 | LOOP [fetch-output : Ensure local output dirs]
2026-08-23 00:27:46.374173 | controller -> localhost | ok: "/var/lib/zuul/builds/c97d012cf6e94cb59af31e225b81b149/work/logs"
2026-08-23 00:27:46.569718 | controller -> localhost | changed: "/var/lib/zuul/builds/c97d012cf6e94cb59af31e225b81b149/work/artifacts"
2026-08-23 00:27:46.902687 | controller -> localhost | changed: "/var/lib/zuul/builds/c97d012cf6e94cb59af31e225b81b149/work/docs"
2026-08-23 00:27:46.922506 | 
2026-08-23 00:27:46.922638 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-23 00:27:47.579761 | controller | changed: .d..t...... ./
2026-08-23 00:27:47.580191 | controller | changed: All items complete
2026-08-23 00:27:47.580235 | 
2026-08-23 00:27:48.059002 | controller | changed: .d..t...... ./
2026-08-23 00:27:48.491842 | controller | changed: .d..t...... ./
2026-08-23 00:27:48.514639 | 
2026-08-23 00:27:48.514756 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-23 00:27:49.020168 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.015684
2026-08-23 00:27:49.247904 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006024
2026-08-23 00:27:49.260568 | 
2026-08-23 00:27:49.260683 | PLAY [all]
2026-08-23 00:27:49.265948 | 
2026-08-23 00:27:49.266015 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-23 00:27:49.718396 | controller | changed
2026-08-23 00:27:49.734301 | 
2026-08-23 00:27:49.734374 | PLAY RECAP
2026-08-23 00:27:49.734420 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-23 00:27:49.734441 | 
2026-08-23 00:27:49.889218 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-23 00:27:49.891953 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-23 00:27:50.504080 | 
2026-08-23 00:27:50.504205 | PLAY [localhost]
2026-08-23 00:27:50.514055 | 
2026-08-23 00:27:50.514132 | TASK [Generate Zuul manifest]
2026-08-23 00:27:50.532026 | localhost | ok
2026-08-23 00:27:50.546058 | 
2026-08-23 00:27:50.546140 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-23 00:27:51.082660 | localhost | changed
2026-08-23 00:27:51.100305 | 
2026-08-23 00:27:51.100434 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-23 00:27:51.132465 | localhost | ok
2026-08-23 00:27:51.141319 | 
2026-08-23 00:27:51.141386 | TASK [Upload logs]
2026-08-23 00:27:51.159586 | localhost | ok
2026-08-23 00:27:51.222498 | 
2026-08-23 00:27:51.222656 | TASK [Set zuul-log-path fact]
2026-08-23 00:27:51.244861 | localhost | ok
2026-08-23 00:27:51.259129 | 
2026-08-23 00:27:51.259239 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-23 00:27:51.291734 | localhost | ok
2026-08-23 00:27:51.299442 | 
2026-08-23 00:27:51.299507 | TASK [upload-logs : Create log directories]
2026-08-23 00:27:51.695212 | localhost | changed
2026-08-23 00:27:51.701446 | 
2026-08-23 00:27:51.701512 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-23 00:27:52.120040 | localhost -> localhost | ok: Runtime: 0:00:00.006050
2026-08-23 00:27:52.127254 | 
2026-08-23 00:27:52.127344 | TASK [upload-logs : Upload logs to log server]
2026-08-23 00:27:52.569963 | localhost | Output suppressed because no_log was given
2026-08-23 00:27:52.575907 | 
2026-08-23 00:27:52.575972 | LOOP [upload-logs : Compress console log and json output]
2026-08-23 00:27:52.630276 | localhost | skipping: Conditional result was False
2026-08-23 00:27:52.680427 | localhost | skipping: Conditional result was False
2026-08-23 00:27:52.688695 | 
2026-08-23 00:27:52.688820 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-23 00:27:52.729225 | localhost | skipping: Conditional result was False
2026-08-23 00:27:52.729489 | 
2026-08-23 00:27:52.733937 | localhost | skipping: Conditional result was False
2026-08-23 00:27:52.744574 | 
2026-08-23 00:27:52.744740 | LOOP [upload-logs : Upload console log and json output]