2026-07-30 16:18:01.331389 | Job console starting
2026-07-30 16:18:01.345989 | Updating git repos
2026-07-30 16:18:01.397070 | Cloning repos into workspace
2026-07-30 16:18:09.445754 | Restoring repo states
2026-07-30 16:18:09.462191 | Merging changes
2026-07-30 16:18:10.093851 | Checking out repos
2026-07-30 16:18:10.319721 | Preparing playbooks
2026-07-30 16:18:15.594776 | Running Ansible setup
2026-07-30 16:18:19.383179 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-30 16:18:20.067605 | 
2026-07-30 16:18:20.093833 | PLAY [localhost]
2026-07-30 16:18:20.108528 | 
2026-07-30 16:18:20.108619 | TASK [Gathering Facts]
2026-07-30 16:18:21.067711 | localhost | ok
2026-07-30 16:18:21.075370 | 
2026-07-30 16:18:21.075450 | TASK [Setup log path fact]
2026-07-30 16:18:21.101359 | localhost | ok
2026-07-30 16:18:21.114811 | 
2026-07-30 16:18:21.114897 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-30 16:18:21.146640 | localhost | ok
2026-07-30 16:18:21.153125 | 
2026-07-30 16:18:21.153199 | TASK [emit-job-header : Print job information]
2026-07-30 16:18:21.196534 | # Job Information
2026-07-30 16:18:21.196738 | Ansible Version: 2.16.18
2026-07-30 16:18:21.196790 | Job: ansible-collection-kubernetes-molecule-helm
2026-07-30 16:18:21.196823 | Pipeline: check
2026-07-30 16:18:21.196854 | Executor: 2d72f0692154
2026-07-30 16:18:21.196884 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/291
2026-07-30 16:18:21.196917 | Event ID: 6e4c8230-8c31-11f1-97b3-4d9a1e1e32cf
2026-07-30 16:18:21.201115 | 
2026-07-30 16:18:21.201212 | LOOP [emit-job-header : Print node information]
2026-07-30 16:18:21.291138 | localhost | ok:
2026-07-30 16:18:21.291421 | localhost | # Node Information
2026-07-30 16:18:21.291496 | localhost | Inventory Hostname: controller
2026-07-30 16:18:21.291555 | localhost | Hostname: np0000187526
2026-07-30 16:18:21.291602 | localhost | Username: zuul
2026-07-30 16:18:21.291631 | localhost | Distro: Debian 13.6
2026-07-30 16:18:21.291655 | localhost | Provider: yul1
2026-07-30 16:18:21.291679 | localhost | Region: ca-ymq-1
2026-07-30 16:18:21.291702 | localhost | Label: debian-trixie
2026-07-30 16:18:21.291725 | localhost | Product Name: OpenStack Nova
2026-07-30 16:18:21.291748 | localhost | Interface IP: 199.19.213.237
2026-07-30 16:18:21.302219 | 
2026-07-30 16:18:21.302386 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-07-30 16:18:21.684507 | localhost -> localhost | changed
2026-07-30 16:18:21.693561 | 
2026-07-30 16:18:21.693691 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-07-30 16:18:22.851659 | localhost -> localhost | changed
2026-07-30 16:18:22.862261 | 
2026-07-30 16:18:22.862329 | PLAY [all]
2026-07-30 16:18:22.869338 | 
2026-07-30 16:18:22.869414 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-07-30 16:18:23.128037 | controller -> localhost | ok
2026-07-30 16:18:23.137027 | 
2026-07-30 16:18:23.137101 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-07-30 16:18:23.169292 | controller | ok
2026-07-30 16:18:23.184144 | controller | included: /var/lib/zuul/builds/aea28a425f0f47d7a08f39befdecd2b6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-07-30 16:18:23.188862 | 
2026-07-30 16:18:23.188922 | TASK [add-build-sshkey : Create Temp SSH key]
2026-07-30 16:18:24.115374 | controller -> localhost | Generating public/private rsa key pair.
2026-07-30 16:18:24.115562 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/aea28a425f0f47d7a08f39befdecd2b6/work/aea28a425f0f47d7a08f39befdecd2b6_id_rsa
2026-07-30 16:18:24.115594 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/aea28a425f0f47d7a08f39befdecd2b6/work/aea28a425f0f47d7a08f39befdecd2b6_id_rsa.pub
2026-07-30 16:18:24.115618 | controller -> localhost | The key fingerprint is:
2026-07-30 16:18:24.115640 | controller -> localhost | SHA256:nJ/0tcdojGLbMAK+gMmw0WvDT/mO9TvMih3FpGEFhh4 zuul-build-sshkey
2026-07-30 16:18:24.115681 | controller -> localhost | The key's randomart image is:
2026-07-30 16:18:24.115704 | controller -> localhost | +---[RSA 3072]----+
2026-07-30 16:18:24.115736 | controller -> localhost | |     .o..        |
2026-07-30 16:18:24.115758 | controller -> localhost | |    E. .         |
2026-07-30 16:18:24.115780 | controller -> localhost | |   . .o .        |
2026-07-30 16:18:24.115801 | controller -> localhost | | .  .. * .       |
2026-07-30 16:18:24.115821 | controller -> localhost | |o .   o S .   .  |
2026-07-30 16:18:24.115841 | controller -> localhost | | * + o o o o + + |
2026-07-30 16:18:24.115862 | controller -> localhost | |. O + +o. B o = o|
2026-07-30 16:18:24.115883 | controller -> localhost | | . + B ++o * . . |
2026-07-30 16:18:24.115907 | controller -> localhost | |    +.*.oo. .    |
2026-07-30 16:18:24.115928 | controller -> localhost | +----[SHA256]-----+
2026-07-30 16:18:24.115979 | controller -> localhost | ok: Runtime: 0:00:00.447122
2026-07-30 16:18:24.120225 | 
2026-07-30 16:18:24.120289 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-07-30 16:18:24.160166 | controller | ok
2026-07-30 16:18:24.169587 | controller | included: /var/lib/zuul/builds/aea28a425f0f47d7a08f39befdecd2b6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-07-30 16:18:24.176862 | 
2026-07-30 16:18:24.176946 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-07-30 16:18:24.202794 | controller | skipping: Conditional result was False
2026-07-30 16:18:24.214003 | 
2026-07-30 16:18:24.214127 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-07-30 16:18:24.792492 | controller | changed
2026-07-30 16:18:24.796581 | 
2026-07-30 16:18:24.796651 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-07-30 16:18:25.032076 | controller | ok
2026-07-30 16:18:25.036158 | 
2026-07-30 16:18:25.036248 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-07-30 16:18:25.574329 | controller | changed
2026-07-30 16:18:25.580261 | 
2026-07-30 16:18:25.580338 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-07-30 16:18:26.123276 | controller | changed
2026-07-30 16:18:26.128175 | 
2026-07-30 16:18:26.128240 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-07-30 16:18:26.162052 | controller | skipping: Conditional result was False
2026-07-30 16:18:26.171771 | 
2026-07-30 16:18:26.172010 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-07-30 16:18:26.526838 | controller -> localhost | changed
2026-07-30 16:18:26.541872 | 
2026-07-30 16:18:26.541948 | TASK [add-build-sshkey : Add back temp key]
2026-07-30 16:18:26.836221 | controller -> localhost | Identity added: /var/lib/zuul/builds/aea28a425f0f47d7a08f39befdecd2b6/work/aea28a425f0f47d7a08f39befdecd2b6_id_rsa (zuul-build-sshkey)
2026-07-30 16:18:26.836472 | controller -> localhost | ok: Runtime: 0:00:00.014055
2026-07-30 16:18:26.842485 | 
2026-07-30 16:18:26.842554 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-07-30 16:18:27.220194 | controller | ok
2026-07-30 16:18:27.225782 | 
2026-07-30 16:18:27.225878 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-07-30 16:18:27.251603 | controller | skipping: Conditional result was False
2026-07-30 16:18:27.265417 | 
2026-07-30 16:18:27.265517 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-07-30 16:18:27.632659 | controller | ok
2026-07-30 16:18:27.641230 | 
2026-07-30 16:18:27.641321 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-07-30 16:18:28.647024 | controller | Output suppressed because no_log was given
2026-07-30 16:18:28.657452 | 
2026-07-30 16:18:28.657564 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-07-30 16:18:28.880347 | controller | ok: "logs"
2026-07-30 16:18:28.880743 | controller | ok: All items complete
2026-07-30 16:18:28.880777 | 
2026-07-30 16:18:29.076314 | controller | ok: "artifacts"
2026-07-30 16:18:29.291794 | controller | ok: "docs"
2026-07-30 16:18:29.308072 | 
2026-07-30 16:18:29.308240 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-07-30 16:18:29.521338 | controller | changed: "logs"
2026-07-30 16:18:29.735540 | controller | changed: "artifacts"
2026-07-30 16:18:29.932033 | controller | changed: "docs"
2026-07-30 16:18:29.950875 | 
2026-07-30 16:18:29.951020 | PLAY RECAP
2026-07-30 16:18:29.951088 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-07-30 16:18:29.951129 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-30 16:18:29.951159 | 
2026-07-30 16:18:30.111866 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-30 16:18:30.114953 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-30 16:18:30.733724 | 
2026-07-30 16:18:30.733882 | PLAY [all]
2026-07-30 16:18:30.744095 | 
2026-07-30 16:18:30.744177 | TASK [setup-uv : Extract archive]
2026-07-30 16:18:33.068600 | controller | changed
2026-07-30 16:18:33.074878 | 
2026-07-30 16:18:33.074993 | TASK [setup-uv : Print version]
2026-07-30 16:18:33.447775 | controller | uv 0.8.13
2026-07-30 16:18:33.607610 | controller | ok: Runtime: 0:00:00.015218
2026-07-30 16:18:33.619066 | 
2026-07-30 16:18:33.619296 | TASK [Install Ansible collection siblings]
2026-07-30 16:18:34.025197 | controller | ok:
2026-07-30 16:18:34.025418 | controller | Skipping ansible.posix: no checked-out sibling
2026-07-30 16:18:34.025462 | controller | Skipping community.crypto: no checked-out sibling
2026-07-30 16:18:34.025498 | controller | Skipping community.general: no checked-out sibling
2026-07-30 16:18:34.025531 | controller | Skipping kubernetes.core: no checked-out sibling
2026-07-30 16:18:34.025623 | controller | Skipping vexxhost.containers: no checked-out sibling
2026-07-30 16:18:34.028124 | 
2026-07-30 16:18:34.028188 | PLAY RECAP
2026-07-30 16:18:34.028254 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-30 16:18:34.028297 | 
2026-07-30 16:18:34.180818 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-30 16:18:34.183912 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-07-30 16:18:34.781352 | 
2026-07-30 16:18:34.781519 | PLAY [all]
2026-07-30 16:18:34.792135 | 
2026-07-30 16:18:34.792277 | TASK [Update apt cache]
2026-07-30 16:18:39.837359 | controller | ok
2026-07-30 16:18:39.842433 | 
2026-07-30 16:18:39.842508 | TASK [Disable firewalld]
2026-07-30 16:18:39.866889 | controller | skipping: Conditional result was False
2026-07-30 16:18:39.873878 | 
2026-07-30 16:18:39.874111 | TASK [Install "jq" for log collection]
2026-07-30 16:18:42.193690 | controller | changed
2026-07-30 16:18:42.195889 | 
2026-07-30 16:18:42.195954 | PLAY RECAP
2026-07-30 16:18:42.196005 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-30 16:18:42.196027 | 
2026-07-30 16:18:42.336140 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-07-30 16:18:42.337733 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-07-30 16:18:43.082390 | 
2026-07-30 16:18:43.082560 | PLAY [all]
2026-07-30 16:18:43.092990 | 
2026-07-30 16:18:43.093106 | LOOP [Check inventory directories]
2026-07-30 16:18:43.484041 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-07-30 16:18:43.658925 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/helm/inventory"
2026-07-30 16:18:43.836173 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/inventory"
2026-07-30 16:18:44.576547 | 
2026-07-30 16:18:44.576754 | LOOP [Copy inventory file for Zuul]
2026-07-30 16:18:45.666981 | controller | changed:
2026-07-30 16:18:45.667179 | controller | {
2026-07-30 16:18:45.667208 | controller |   "ansible_loop_var": "item",
2026-07-30 16:18:45.667232 | controller |   "changed": false,
2026-07-30 16:18:45.667253 | controller |   "failed": false,
2026-07-30 16:18:45.667275 | controller |   "invocation": {
2026-07-30 16:18:45.667295 | controller |     "module_args": {
2026-07-30 16:18:45.667314 | controller |       "checksum_algorithm": "sha1",
2026-07-30 16:18:45.667333 | controller |       "follow": true,
2026-07-30 16:18:45.667351 | controller |       "get_attributes": true,
2026-07-30 16:18:45.667371 | controller |       "get_checksum": true,
2026-07-30 16:18:45.667389 | controller |       "get_mime": true,
2026-07-30 16:18:45.667407 | controller |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-07-30 16:18:45.667425 | controller |     }
2026-07-30 16:18:45.667444 | controller |   },
2026-07-30 16:18:45.667461 | controller |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-07-30 16:18:45.667479 | controller |   "stat": {
2026-07-30 16:18:45.667496 | controller |     "atime": 1785428308.5170357,
2026-07-30 16:18:45.667514 | controller |     "attr_flags": "e",
2026-07-30 16:18:45.667532 | controller |     "attributes": [
2026-07-30 16:18:45.667550 | controller |       "extents"
2026-07-30 16:18:45.667567 | controller |     ],
2026-07-30 16:18:45.667584 | controller |     "block_size": 4096,
2026-07-30 16:18:45.667601 | controller |     "blocks": 8,
2026-07-30 16:18:45.667619 | controller |     "charset": "unknown",
2026-07-30 16:18:45.667638 | controller |     "ctime": 1785428308.5330358,
2026-07-30 16:18:45.667656 | controller |     "dev": 65025,
2026-07-30 16:18:45.667674 | controller |     "device_type": 0,
2026-07-30 16:18:45.667691 | controller |     "executable": true,
2026-07-30 16:18:45.667708 | controller |     "exists": true,
2026-07-30 16:18:45.667726 | controller |     "gid": 1000,
2026-07-30 16:18:45.667744 | controller |     "gr_name": "zuul",
2026-07-30 16:18:45.667761 | controller |     "inode": 518935,
2026-07-30 16:18:45.667778 | controller |     "isblk": false,
2026-07-30 16:18:45.667796 | controller |     "ischr": false,
2026-07-30 16:18:45.667814 | controller |     "isdir": true,
2026-07-30 16:18:45.667831 | controller |     "isfifo": false,
2026-07-30 16:18:45.667848 | controller |     "isgid": false,
2026-07-30 16:18:45.667865 | controller |     "islnk": false,
2026-07-30 16:18:45.667882 | controller |     "isreg": false,
2026-07-30 16:18:45.667900 | controller |     "issock": false,
2026-07-30 16:18:45.667916 | controller |     "isuid": false,
2026-07-30 16:18:45.667933 | controller |     "mimetype": "unknown",
2026-07-30 16:18:45.667951 | controller |     "mode": "0755",
2026-07-30 16:18:45.667968 | controller |     "mtime": 1785428289.9720511,
2026-07-30 16:18:45.667985 | controller |     "nlink": 14,
2026-07-30 16:18:45.668003 | controller |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-07-30 16:18:45.668020 | controller |     "pw_name": "zuul",
2026-07-30 16:18:45.668038 | controller |     "readable": true,
2026-07-30 16:18:45.668056 | controller |     "rgrp": true,
2026-07-30 16:18:45.668074 | controller |     "roth": true,
2026-07-30 16:18:45.668092 | controller |     "rusr": true,
2026-07-30 16:18:45.668109 | controller |     "size": 4096,
2026-07-30 16:18:45.668126 | controller |     "uid": 1000,
2026-07-30 16:18:45.668144 | controller |     "version": "3304948081",
2026-07-30 16:18:45.668163 | controller |     "wgrp": false,
2026-07-30 16:18:45.668182 | controller |     "woth": false,
2026-07-30 16:18:45.668200 | controller |     "writeable": true,
2026-07-30 16:18:45.668219 | controller |     "wusr": true,
2026-07-30 16:18:45.668237 | controller |     "xgrp": true,
2026-07-30 16:18:45.668254 | controller |     "xoth": true,
2026-07-30 16:18:45.668271 | controller |     "xusr": true
2026-07-30 16:18:45.668289 | controller |   }
2026-07-30 16:18:45.668323 | controller | }
2026-07-30 16:18:45.668362 | controller | ok: All items complete
2026-07-30 16:18:45.668384 | 
2026-07-30 16:18:45.669794 | controller | skipping: Conditional result was False
2026-07-30 16:18:45.670485 | controller | skipping: Conditional result was False
2026-07-30 16:18:45.681656 | 
2026-07-30 16:18:45.681877 | LOOP [Switch "ansible_host" to private IP]
2026-07-30 16:18:46.064236 | controller | changed: 1 replacements made
2026-07-30 16:18:46.065600 | controller | skipping: Conditional result was False
2026-07-30 16:18:46.066409 | controller | skipping: Conditional result was False
2026-07-30 16:18:46.089106 | 
2026-07-30 16:18:46.089269 | TASK [Run Molecule scenario]
2026-07-30 16:18:46.554312 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-07-30 16:18:46.554394 | controller | Creating virtual environment at: .venv
2026-07-30 16:18:46.607763 | controller | Downloading ansible-core (2.3MiB)
2026-07-30 16:18:46.607946 | controller | Downloading cryptography (4.3MiB)
2026-07-30 16:18:46.609349 | controller | Downloading pygments (1.2MiB)
2026-07-30 16:18:46.768483 | controller |  Downloading cryptography
2026-07-30 16:18:46.827810 | controller |  Downloading pygments
2026-07-30 16:18:47.035115 | controller |  Downloading ansible-core
2026-07-30 16:18:47.085203 | controller | Installed 36 packages in 49ms
2026-07-30 16:18:47.811222 | controller | INFO     Collection 'vexxhost.kubernetes' detected.
2026-07-30 16:18:47.811312 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-07-30 16:18:48.773041 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-07-30 16:18:48.773148 | controller | INFO     default scenario not found, disabling shared state.
2026-07-30 16:18:48.773165 | controller | INFO     [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-07-30 16:18:48.773271 | controller | INFO     [helm > prerun] Performing prerun with role_name_check=0...
2026-07-30 16:19:13.942833 | controller | INFO     [helm > dependency] Executing
2026-07-30 16:19:13.943139 | controller | WARNING  [helm > dependency] Missing roles requirements file: requirements.yml
2026-07-30 16:19:13.943768 | controller | WARNING  [helm > dependency] Missing collections requirements file: collections.yml
2026-07-30 16:19:13.944151 | controller | WARNING  [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-07-30 16:19:13.954159 | controller | INFO     [helm > cleanup] Executing
2026-07-30 16:19:13.954452 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-30 16:19:13.959921 | controller | INFO     [helm > destroy] Executing
2026-07-30 16:19:13.959943 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-07-30 16:19:13.960048 | controller | INFO     [helm > destroy] Executed: Successful
2026-07-30 16:19:13.965442 | controller | INFO     [helm > syntax] Executing
2026-07-30 16:19:14.978263 | controller |
2026-07-30 16:19:14.978627 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml
2026-07-30 16:19:15.072985 | controller | INFO     [helm > syntax] Executed: Successful
2026-07-30 16:19:15.084321 | controller | INFO     [helm > create] Executing
2026-07-30 16:19:15.085844 | controller | WARNING  [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-30 16:19:15.091406 | controller | INFO     [helm > prepare] Executing
2026-07-30 16:19:16.021080 | controller |
2026-07-30 16:19:16.021273 | controller | PLAY [Prepare] *****************************************************************
2026-07-30 16:19:16.021462 | controller |
2026-07-30 16:19:16.021847 | controller | TASK [Gathering Facts] *********************************************************
2026-07-30 16:19:16.022182 | controller | Thursday 30 July 2026  16:19:16 +0000 (0:00:00.031)       0:00:00.031 *********
2026-07-30 16:19:18.200816 | 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.21/reference_appendices/interpreter_discovery.html for more information.
2026-07-30 16:19:18.208195 | controller | ok: [controller]
2026-07-30 16:19:18.208263 | controller |
2026-07-30 16:19:18.208373 | controller | TASK [Install Debian packages] *************************************************
2026-07-30 16:19:18.208478 | controller | Thursday 30 July 2026  16:19:18 +0000 (0:00:02.185)       0:00:02.216 *********
2026-07-30 16:19:19.541447 | controller | ok: [controller]
2026-07-30 16:19:19.541504 | controller |
2026-07-30 16:19:19.541614 | controller | PLAY RECAP *********************************************************************
2026-07-30 16:19:19.541754 | controller | controller                 : ok=2    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-30 16:19:19.541866 | controller |
2026-07-30 16:19:19.542625 | controller |
2026-07-30 16:19:19.542655 | controller | TASKS RECAP ********************************************************************
2026-07-30 16:19:19.542671 | controller | Thursday 30 July 2026  16:19:19 +0000 (0:00:01.335)       0:00:03.552 *********
2026-07-30 16:19:19.542676 | controller | ===============================================================================
2026-07-30 16:19:19.542681 | controller | Gathering Facts --------------------------------------------------------- 2.19s
2026-07-30 16:19:19.542686 | controller | Install Debian packages ------------------------------------------------- 1.34s
2026-07-30 16:19:19.653178 | controller | INFO     [helm > prepare] Executed: Successful
2026-07-30 16:19:19.662135 | controller | INFO     [helm > converge] Executing
2026-07-30 16:19:20.588626 | controller |
2026-07-30 16:19:20.588661 | controller | PLAY [Converge] ****************************************************************
2026-07-30 16:19:20.588666 | controller |
2026-07-30 16:19:20.588670 | controller | TASK [Gathering Facts] *********************************************************
2026-07-30 16:19:20.588674 | controller | Thursday 30 July 2026  16:19:20 +0000 (0:00:00.013)       0:00:00.013 *********
2026-07-30 16:19:21.609341 | 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.21/reference_appendices/interpreter_discovery.html for more information.
2026-07-30 16:19:21.615236 | controller | ok: [controller]
2026-07-30 16:19:21.615404 | controller |
2026-07-30 16:19:21.615599 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-07-30 16:19:21.619539 | controller | Thursday 30 July 2026  16:19:21 +0000 (0:00:01.029)       0:00:01.042 *********
2026-07-30 16:19:22.091689 | controller | ok: [controller]
2026-07-30 16:19:22.092054 | controller |
2026-07-30 16:19:22.092365 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-07-30 16:19:22.092661 | controller | Thursday 30 July 2026  16:19:22 +0000 (0:00:00.476)       0:00:01.518 *********
2026-07-30 16:19:23.113038 | controller | ok: [controller]
2026-07-30 16:19:23.113061 | controller |
2026-07-30 16:19:23.113066 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-07-30 16:19:23.113071 | controller | Thursday 30 July 2026  16:19:23 +0000 (0:00:01.018)       0:00:02.537 *********
2026-07-30 16:19:23.557266 | controller | changed: [controller]
2026-07-30 16:19:23.557643 | controller |
2026-07-30 16:19:23.557971 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-07-30 16:19:23.558278 | controller | Thursday 30 July 2026  16:19:23 +0000 (0:00:00.446)       0:00:02.983 *********
2026-07-30 16:19:23.608602 | controller | ok: [controller] => {
2026-07-30 16:19:23.608927 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-07-30 16:19:23.609216 | controller | }
2026-07-30 16:19:23.609495 | controller |
2026-07-30 16:19:23.609804 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-07-30 16:19:23.610065 | controller | Thursday 30 July 2026  16:19:23 +0000 (0:00:00.051)       0:00:03.034 *********
2026-07-30 16:19:24.377694 | 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-30 16:19:24.387783 | controller | changed: [controller]
2026-07-30 16:19:24.388097 | controller |
2026-07-30 16:19:24.388378 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-07-30 16:19:24.388640 | controller | Thursday 30 July 2026  16:19:24 +0000 (0:00:00.780)       0:00:03.815 *********
2026-07-30 16:19:26.371620 | controller | changed: [controller]
2026-07-30 16:19:26.371907 | controller |
2026-07-30 16:19:26.372299 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-07-30 16:19:26.372601 | controller | Thursday 30 July 2026  16:19:26 +0000 (0:00:01.983)       0:00:05.798 *********
2026-07-30 16:19:26.880641 | controller | ok: [controller]
2026-07-30 16:19:26.880791 | controller |
2026-07-30 16:19:26.880918 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-07-30 16:19:26.881038 | controller | Thursday 30 July 2026  16:19:26 +0000 (0:00:00.506)       0:00:06.305 *********
2026-07-30 16:19:27.348969 | controller | changed: [controller]
2026-07-30 16:19:27.348992 | controller |
2026-07-30 16:19:27.348997 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-07-30 16:19:27.349001 | controller | Thursday 30 July 2026  16:19:27 +0000 (0:00:00.467)       0:00:06.772 *********
2026-07-30 16:19:27.793557 | controller | ok: [controller]
2026-07-30 16:19:27.793676 | controller |
2026-07-30 16:19:27.793864 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-07-30 16:19:27.794030 | controller | Thursday 30 July 2026  16:19:27 +0000 (0:00:00.447)       0:00:07.220 *********
2026-07-30 16:19:28.108220 | controller | changed: [controller]
2026-07-30 16:19:28.108302 | controller |
2026-07-30 16:19:28.108428 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-07-30 16:19:28.108590 | controller | Thursday 30 July 2026  16:19:28 +0000 (0:00:00.315)       0:00:07.535 *********
2026-07-30 16:19:28.411454 | controller | ok: [controller]
2026-07-30 16:19:28.411511 | controller |
2026-07-30 16:19:28.411624 | controller | TASK [Install plugin] **********************************************************
2026-07-30 16:19:28.411745 | controller | Thursday 30 July 2026  16:19:28 +0000 (0:00:00.302)       0:00:07.838 *********
2026-07-30 16:19:28.471843 | controller | included: vexxhost.containers.download_artifact for controller
2026-07-30 16:19:28.471920 | controller |
2026-07-30 16:19:28.472091 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-07-30 16:19:28.472267 | controller | Thursday 30 July 2026  16:19:28 +0000 (0:00:00.060)       0:00:07.898 *********
2026-07-30 16:19:28.532535 | controller | ok: [controller] => {
2026-07-30 16:19:28.532619 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-07-30 16:19:28.532629 | controller | }
2026-07-30 16:19:28.532721 | controller |
2026-07-30 16:19:28.532810 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-07-30 16:19:28.532903 | controller | Thursday 30 July 2026  16:19:28 +0000 (0:00:00.059)       0:00:07.957 *********
2026-07-30 16:19:29.375487 | controller | changed: [controller]
2026-07-30 16:19:29.375604 | controller |
2026-07-30 16:19:29.375919 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-07-30 16:19:29.375948 | controller | Thursday 30 July 2026  16:19:29 +0000 (0:00:00.844)       0:00:08.802 *********
2026-07-30 16:19:31.483455 | controller | changed: [controller]
2026-07-30 16:19:31.483621 | controller |
2026-07-30 16:19:31.483769 | controller | PLAY RECAP *********************************************************************
2026-07-30 16:19:31.483925 | controller | controller                 : ok=16   changed=7    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-30 16:19:31.484105 | controller |
2026-07-30 16:19:31.484238 | controller |
2026-07-30 16:19:31.484421 | controller | TASKS RECAP ********************************************************************
2026-07-30 16:19:31.484560 | controller | Thursday 30 July 2026  16:19:31 +0000 (0:00:02.107)       0:00:10.910 *********
2026-07-30 16:19:31.484688 | controller | ===============================================================================
2026-07-30 16:19:31.484826 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.11s
2026-07-30 16:19:31.484965 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.98s
2026-07-30 16:19:31.485104 | controller | Gathering Facts --------------------------------------------------------- 1.03s
2026-07-30 16:19:31.485245 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.02s
2026-07-30 16:19:31.485381 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.84s
2026-07-30 16:19:31.485517 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.78s
2026-07-30 16:19:31.485655 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.51s
2026-07-30 16:19:31.485791 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.48s
2026-07-30 16:19:31.485932 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.47s
2026-07-30 16:19:31.486076 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.45s
2026-07-30 16:19:31.486224 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.45s
2026-07-30 16:19:31.486371 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.32s
2026-07-30 16:19:31.486516 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.30s
2026-07-30 16:19:31.486658 | controller | Install plugin ---------------------------------------------------------- 0.06s
2026-07-30 16:19:31.486801 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.06s
2026-07-30 16:19:31.486941 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-07-30 16:19:31.591156 | controller | INFO     [helm > converge] Executed: Successful
2026-07-30 16:19:31.602037 | controller | INFO     [helm > idempotence] Executing
2026-07-30 16:19:32.500773 | controller |
2026-07-30 16:19:32.501083 | controller | PLAY [Converge] ****************************************************************
2026-07-30 16:19:32.501360 | controller |
2026-07-30 16:19:32.501651 | controller | TASK [Gathering Facts] *********************************************************
2026-07-30 16:19:32.501924 | controller | Thursday 30 July 2026  16:19:32 +0000 (0:00:00.013)       0:00:00.013 *********
2026-07-30 16:19:33.520486 | 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.21/reference_appendices/interpreter_discovery.html for more information.
2026-07-30 16:19:33.526611 | controller | ok: [controller]
2026-07-30 16:19:33.526878 | controller |
2026-07-30 16:19:33.527238 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-07-30 16:19:33.527538 | controller | Thursday 30 July 2026  16:19:33 +0000 (0:00:01.025)       0:00:01.039 *********
2026-07-30 16:19:33.956449 | controller | ok: [controller]
2026-07-30 16:19:33.956479 | controller |
2026-07-30 16:19:33.956484 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-07-30 16:19:33.956488 | controller | Thursday 30 July 2026  16:19:33 +0000 (0:00:00.429)       0:00:01.468 *********
2026-07-30 16:19:35.052524 | controller | ok: [controller]
2026-07-30 16:19:35.052829 | controller |
2026-07-30 16:19:35.053104 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-07-30 16:19:35.053365 | controller | Thursday 30 July 2026  16:19:35 +0000 (0:00:01.096)       0:00:02.564 *********
2026-07-30 16:19:35.472570 | controller | ok: [controller]
2026-07-30 16:19:35.472638 | controller |
2026-07-30 16:19:35.472651 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-07-30 16:19:35.472805 | controller | Thursday 30 July 2026  16:19:35 +0000 (0:00:00.418)       0:00:02.983 *********
2026-07-30 16:19:35.532062 | controller | ok: [controller] => {
2026-07-30 16:19:35.532109 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-07-30 16:19:35.532115 | controller | }
2026-07-30 16:19:35.532120 | controller |
2026-07-30 16:19:35.532125 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-07-30 16:19:35.532131 | controller | Thursday 30 July 2026  16:19:35 +0000 (0:00:00.058)       0:00:03.041 *********
2026-07-30 16:19:36.097505 | controller | ok: [controller]
2026-07-30 16:19:36.097554 | controller |
2026-07-30 16:19:36.097563 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-07-30 16:19:36.097572 | controller | Thursday 30 July 2026  16:19:36 +0000 (0:00:00.565)       0:00:03.606 *********
2026-07-30 16:19:37.787319 | controller | ok: [controller]
2026-07-30 16:19:37.787369 | controller |
2026-07-30 16:19:37.787475 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-07-30 16:19:37.787576 | controller | Thursday 30 July 2026  16:19:37 +0000 (0:00:01.692)       0:00:05.299 *********
2026-07-30 16:19:38.293307 | controller | ok: [controller]
2026-07-30 16:19:38.293344 | controller |
2026-07-30 16:19:38.293353 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-07-30 16:19:38.293362 | controller | Thursday 30 July 2026  16:19:38 +0000 (0:00:00.503)       0:00:05.803 *********
2026-07-30 16:19:38.680075 | controller | ok: [controller]
2026-07-30 16:19:38.680444 | controller |
2026-07-30 16:19:38.680458 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-07-30 16:19:38.680464 | controller | Thursday 30 July 2026  16:19:38 +0000 (0:00:00.385)       0:00:06.189 *********
2026-07-30 16:19:39.127609 | controller | ok: [controller]
2026-07-30 16:19:39.127677 | controller |
2026-07-30 16:19:39.127793 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-07-30 16:19:39.127921 | controller | Thursday 30 July 2026  16:19:39 +0000 (0:00:00.450)       0:00:06.639 *********
2026-07-30 16:19:39.431760 | controller | ok: [controller]
2026-07-30 16:19:39.431874 | controller |
2026-07-30 16:19:39.432254 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-07-30 16:19:39.432281 | controller | Thursday 30 July 2026  16:19:39 +0000 (0:00:00.304)       0:00:06.943 *********
2026-07-30 16:19:39.740496 | controller | ok: [controller]
2026-07-30 16:19:39.740587 | controller |
2026-07-30 16:19:39.740720 | controller | TASK [Install plugin] **********************************************************
2026-07-30 16:19:39.740847 | controller | Thursday 30 July 2026  16:19:39 +0000 (0:00:00.306)       0:00:07.250 *********
2026-07-30 16:19:39.791414 | controller | included: vexxhost.containers.download_artifact for controller
2026-07-30 16:19:39.791469 | controller |
2026-07-30 16:19:39.791550 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-07-30 16:19:39.791641 | controller | Thursday 30 July 2026  16:19:39 +0000 (0:00:00.053)       0:00:07.304 *********
2026-07-30 16:19:39.843225 | controller | ok: [controller] => {
2026-07-30 16:19:39.843369 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-07-30 16:19:39.843872 | controller | }
2026-07-30 16:19:39.843903 | controller |
2026-07-30 16:19:39.843910 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-07-30 16:19:39.843917 | controller | Thursday 30 July 2026  16:19:39 +0000 (0:00:00.051)       0:00:07.355 *********
2026-07-30 16:19:40.276528 | controller | ok: [controller]
2026-07-30 16:19:40.276607 | controller |
2026-07-30 16:19:40.276778 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-07-30 16:19:40.276874 | controller | Thursday 30 July 2026  16:19:40 +0000 (0:00:00.431)       0:00:07.787 *********
2026-07-30 16:19:41.928065 | controller | ok: [controller]
2026-07-30 16:19:41.928101 | controller |
2026-07-30 16:19:41.928111 | controller | PLAY RECAP *********************************************************************
2026-07-30 16:19:41.928119 | controller | controller                 : ok=16   changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-30 16:19:41.928127 | controller |
2026-07-30 16:19:41.928134 | controller |
2026-07-30 16:19:41.928142 | controller | TASKS RECAP ********************************************************************
2026-07-30 16:19:41.928149 | controller | Thursday 30 July 2026  16:19:41 +0000 (0:00:01.650)       0:00:09.438 *********
2026-07-30 16:19:41.928156 | controller | ===============================================================================
2026-07-30 16:19:41.928163 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.69s
2026-07-30 16:19:41.928170 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.65s
2026-07-30 16:19:41.928177 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.10s
2026-07-30 16:19:41.928184 | controller | Gathering Facts --------------------------------------------------------- 1.03s
2026-07-30 16:19:41.928191 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.57s
2026-07-30 16:19:41.928352 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.50s
2026-07-30 16:19:41.928620 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.45s
2026-07-30 16:19:41.928876 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.43s
2026-07-30 16:19:41.929129 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.43s
2026-07-30 16:19:41.929488 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.42s
2026-07-30 16:19:41.929767 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.39s
2026-07-30 16:19:41.930041 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.31s
2026-07-30 16:19:41.930352 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.30s
2026-07-30 16:19:41.930533 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.06s
2026-07-30 16:19:41.930693 | controller | Install plugin ---------------------------------------------------------- 0.05s
2026-07-30 16:19:41.930850 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-07-30 16:19:42.030529 | controller | INFO     [helm > idempotence] Executed: Successful
2026-07-30 16:19:42.040488 | controller | INFO     [helm > side_effect] Executing
2026-07-30 16:19:42.040983 | controller | WARNING  [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-30 16:19:42.046949 | controller | INFO     [helm > verify] Executing
2026-07-30 16:19:42.940711 | controller |
2026-07-30 16:19:42.941036 | controller | PLAY [Verify] ******************************************************************
2026-07-30 16:19:42.941372 | controller |
2026-07-30 16:19:42.941638 | controller | TASK [Gathering Facts] *********************************************************
2026-07-30 16:19:42.941903 | controller | Thursday 30 July 2026  16:19:42 +0000 (0:00:00.009)       0:00:00.009 *********
2026-07-30 16:19:43.972718 | 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.21/reference_appendices/interpreter_discovery.html for more information.
2026-07-30 16:19:43.975680 | controller | ok: [controller]
2026-07-30 16:19:43.975863 | controller |
2026-07-30 16:19:43.976129 | controller | TASK [Get the current Helm version] ********************************************
2026-07-30 16:19:43.976332 | controller | Thursday 30 July 2026  16:19:43 +0000 (0:00:01.033)       0:00:01.043 *********
2026-07-30 16:19:44.432281 | controller | changed: [controller]
2026-07-30 16:19:44.432342 | controller |
2026-07-30 16:19:44.432387 | controller | TASK [Assert Helm version] *****************************************************
2026-07-30 16:19:44.432482 | controller | Thursday 30 July 2026  16:19:44 +0000 (0:00:00.454)       0:00:01.497 *********
2026-07-30 16:19:44.461340 | controller | ok: [controller] => {
2026-07-30 16:19:44.461470 | controller |     "changed": false,
2026-07-30 16:19:44.461594 | controller |     "msg": "All assertions passed"
2026-07-30 16:19:44.461712 | controller | }
2026-07-30 16:19:44.461826 | controller |
2026-07-30 16:19:44.461933 | controller | PLAY RECAP *********************************************************************
2026-07-30 16:19:44.462078 | controller | controller                 : ok=3    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-30 16:19:44.462176 | controller |
2026-07-30 16:19:44.462279 | controller |
2026-07-30 16:19:44.462397 | controller | TASKS RECAP ********************************************************************
2026-07-30 16:19:44.462522 | controller | Thursday 30 July 2026  16:19:44 +0000 (0:00:00.033)       0:00:01.531 *********
2026-07-30 16:19:44.464121 | controller | ===============================================================================
2026-07-30 16:19:44.464176 | controller | Gathering Facts --------------------------------------------------------- 1.03s
2026-07-30 16:19:44.464184 | controller | Get the current Helm version -------------------------------------------- 0.45s
2026-07-30 16:19:44.464192 | controller | Assert Helm version ----------------------------------------------------- 0.03s
2026-07-30 16:19:44.578516 | controller | INFO     [helm > verify] Executed: Successful
2026-07-30 16:19:44.588388 | controller | INFO     [helm > cleanup] Executing
2026-07-30 16:19:44.588701 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-30 16:19:44.594055 | controller | INFO     [helm > destroy] Executing
2026-07-30 16:19:44.594085 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-07-30 16:19:44.594150 | controller | INFO     [helm > destroy] Executed: Successful
2026-07-30 16:19:44.594437 | controller | WARNING  Molecule executed 1 scenario (1 missing files)
2026-07-30 16:19:44.745744 | controller | ok: Runtime: 0:00:58.193254
2026-07-30 16:19:44.750711 | 
2026-07-30 16:19:44.750784 | PLAY RECAP
2026-07-30 16:19:44.750856 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-30 16:19:44.750890 | 
2026-07-30 16:19:44.922588 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-07-30 16:19:44.925757 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-07-30 16:19:45.549596 | 
2026-07-30 16:19:45.550128 | PLAY [all]
2026-07-30 16:19:45.561926 | 
2026-07-30 16:19:45.562018 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-07-30 16:19:45.950555 | controller | changed
2026-07-30 16:19:45.956897 | 
2026-07-30 16:19:45.957043 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-07-30 16:19:46.380699 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-30 16:19:46.380839 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-30 16:19:46.387553 | controller | environment: line 1: kubectl: command not found
2026-07-30 16:19:46.389115 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-30 16:19:46.391357 | controller | environment: line 1: kubectl: command not found
2026-07-30 16:19:46.392927 | controller | environment: line 1: kubectl: command not found
2026-07-30 16:19:46.393886 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-30 16:19:46.394235 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-30 16:19:46.397529 | controller | environment: line 1: kubectl: command not found
2026-07-30 16:19:46.397844 | controller | environment: line 1: kubectl: command not found
2026-07-30 16:19:46.399765 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-30 16:19:46.400647 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-30 16:19:46.493712 | controller | ok: Runtime: 0:00:00.033484
2026-07-30 16:19:46.499033 | 
2026-07-30 16:19:46.499130 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-07-30 16:19:46.735791 | controller | changed
2026-07-30 16:19:46.741685 | 
2026-07-30 16:19:46.741757 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-07-30 16:19:46.987070 | controller | environment: line 5: kubectl: command not found
2026-07-30 16:19:46.989199 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-30 16:19:46.989508 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-30 16:19:46.990042 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-30 16:19:47.273672 | controller | ok: Runtime: 0:00:00.012494
2026-07-30 16:19:47.279659 | 
2026-07-30 16:19:47.279728 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-07-30 16:19:47.974741 | controller | changed:
2026-07-30 16:19:47.975094 | controller | created directory /var/lib/zuul/builds/aea28a425f0f47d7a08f39befdecd2b6/work/logs/controller
2026-07-30 16:19:47.975155 | controller | cd+++++++++ objects/
2026-07-30 16:19:47.975193 | controller | cd+++++++++ objects/cluster/
2026-07-30 16:19:47.975227 | controller | cd+++++++++ objects/namespaced/
2026-07-30 16:19:47.985049 | 
2026-07-30 16:19:47.985127 | TASK [gather-pod-logs : creating directory for pod logs]
2026-07-30 16:19:48.227735 | controller | changed
2026-07-30 16:19:48.231874 | 
2026-07-30 16:19:48.231941 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-07-30 16:19:48.487938 | controller | changed
2026-07-30 16:19:48.494771 | 
2026-07-30 16:19:48.494863 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-07-30 16:19:48.743393 | controller | environment: line 3: kubectl: command not found
2026-07-30 16:19:49.028433 | controller | ok: Runtime: 0:00:00.014724
2026-07-30 16:19:49.034188 | 
2026-07-30 16:19:49.034259 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-07-30 16:19:49.537687 | controller | changed:
2026-07-30 16:19:49.537916 | controller | cd+++++++++ pod-logs/
2026-07-30 16:19:49.537959 | controller | cd+++++++++ pod-logs/failed-pods/
2026-07-30 16:19:49.549296 | 
2026-07-30 16:19:49.549431 | LOOP [helm-release-status : creating directory for helm release status]
2026-07-30 16:19:49.794513 | controller | changed: "values"
2026-07-30 16:19:50.006505 | controller | changed: "releases"
2026-07-30 16:19:50.015125 | 
2026-07-30 16:19:50.015319 | TASK [helm-release-status : Gather get release status for helm charts]
2026-07-30 16:19:50.268415 | controller | /bin/bash: line 3: kubectl: command not found
2026-07-30 16:19:50.550818 | controller | ok: Runtime: 0:00:00.010457
2026-07-30 16:19:50.556676 | 
2026-07-30 16:19:50.556766 | TASK [helm-release-status : Downloads logs to executor]
2026-07-30 16:19:51.093401 | controller | changed:
2026-07-30 16:19:51.093615 | controller | cd+++++++++ helm/
2026-07-30 16:19:51.093649 | controller | cd+++++++++ helm/releases/
2026-07-30 16:19:51.093679 | controller | cd+++++++++ helm/values/
2026-07-30 16:19:51.101245 | 
2026-07-30 16:19:51.101305 | PLAY RECAP
2026-07-30 16:19:51.101362 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-30 16:19:51.101389 | 
2026-07-30 16:19:51.269570 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-07-30 16:19:51.273054 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-30 16:19:51.931709 | 
2026-07-30 16:19:51.931894 | PLAY [all]
2026-07-30 16:19:51.942696 | 
2026-07-30 16:19:51.942785 | TASK [fetch-output : Set log path for multiple nodes]
2026-07-30 16:19:51.999354 | controller | skipping: Conditional result was False
2026-07-30 16:19:52.009068 | 
2026-07-30 16:19:52.009215 | TASK [fetch-output : Set log path for single node]
2026-07-30 16:19:52.051336 | controller | ok
2026-07-30 16:19:52.056555 | 
2026-07-30 16:19:52.056625 | LOOP [fetch-output : Ensure local output dirs]
2026-07-30 16:19:52.492778 | controller -> localhost | ok: "/var/lib/zuul/builds/aea28a425f0f47d7a08f39befdecd2b6/work/logs"
2026-07-30 16:19:52.783194 | controller -> localhost | changed: "/var/lib/zuul/builds/aea28a425f0f47d7a08f39befdecd2b6/work/artifacts"
2026-07-30 16:19:53.008902 | controller -> localhost | changed: "/var/lib/zuul/builds/aea28a425f0f47d7a08f39befdecd2b6/work/docs"
2026-07-30 16:19:53.024581 | 
2026-07-30 16:19:53.024738 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-07-30 16:19:53.684403 | controller | changed: .d..t...... ./
2026-07-30 16:19:53.684684 | controller | changed: All items complete
2026-07-30 16:19:53.684733 | 
2026-07-30 16:19:54.181587 | controller | changed: .d..t...... ./
2026-07-30 16:19:54.662774 | controller | changed: .d..t...... ./
2026-07-30 16:19:54.699961 | 
2026-07-30 16:19:54.700123 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-07-30 16:19:55.345688 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.140980
2026-07-30 16:19:55.581456 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008948
2026-07-30 16:19:55.600751 | 
2026-07-30 16:19:55.600887 | PLAY [all]
2026-07-30 16:19:55.606488 | 
2026-07-30 16:19:55.606553 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-07-30 16:19:56.095262 | controller | changed
2026-07-30 16:19:56.101319 | 
2026-07-30 16:19:56.101384 | PLAY RECAP
2026-07-30 16:19:56.101441 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-30 16:19:56.101471 | 
2026-07-30 16:19:56.251063 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-30 16:19:56.254936 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-07-30 16:19:56.867747 | 
2026-07-30 16:19:56.868086 | PLAY [localhost]
2026-07-30 16:19:56.879495 | 
2026-07-30 16:19:56.879583 | TASK [Generate Zuul manifest]
2026-07-30 16:19:56.909862 | localhost | ok
2026-07-30 16:19:56.928350 | 
2026-07-30 16:19:56.928466 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-07-30 16:19:57.462127 | localhost | changed
2026-07-30 16:19:57.538669 | 
2026-07-30 16:19:57.539032 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-07-30 16:19:57.754880 | localhost | ok
2026-07-30 16:19:57.764085 | 
2026-07-30 16:19:57.764199 | TASK [Upload logs]
2026-07-30 16:19:57.783773 | localhost | ok
2026-07-30 16:19:58.026198 | 
2026-07-30 16:19:58.026329 | TASK [Set zuul-log-path fact]
2026-07-30 16:19:58.045116 | localhost | ok
2026-07-30 16:19:58.058266 | 
2026-07-30 16:19:58.058357 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-30 16:19:58.090624 | localhost | ok
2026-07-30 16:19:58.175530 | 
2026-07-30 16:19:58.175706 | TASK [upload-logs : Create log directories]
2026-07-30 16:19:59.072497 | localhost | changed
2026-07-30 16:19:59.079931 | 
2026-07-30 16:19:59.080116 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-07-30 16:19:59.491639 | localhost -> localhost | ok: Runtime: 0:00:00.006755
2026-07-30 16:19:59.499353 | 
2026-07-30 16:19:59.508592 | TASK [upload-logs : Upload logs to log server]
2026-07-30 16:19:59.981023 | localhost | Output suppressed because no_log was given
2026-07-30 16:19:59.985729 | 
2026-07-30 16:19:59.985815 | LOOP [upload-logs : Compress console log and json output]
2026-07-30 16:20:00.031139 | localhost | skipping: Conditional result was False
2026-07-30 16:20:00.089088 | localhost | skipping: Conditional result was False
2026-07-30 16:20:00.101895 | 
2026-07-30 16:20:00.102095 | LOOP [upload-logs : Upload compressed console log and json output]
2026-07-30 16:20:00.152114 | localhost | skipping: Conditional result was False
2026-07-30 16:20:00.152639 | 
2026-07-30 16:20:00.156725 | localhost | skipping: Conditional result was False
2026-07-30 16:20:00.166065 | 
2026-07-30 16:20:00.166249 | LOOP [upload-logs : Upload console log and json output]