2026-06-18 12:06:45.822574 | Job console starting
2026-06-18 12:06:45.856831 | Updating git repos
2026-06-18 12:06:45.920763 | Cloning repos into workspace
2026-06-18 12:06:47.709291 | Restoring repo states
2026-06-18 12:06:47.937319 | Merging changes
2026-06-18 12:06:49.192125 | Checking out repos
2026-06-18 12:06:49.778681 | Preparing playbooks
2026-06-18 12:06:59.700162 | Running Ansible setup
2026-06-18 12:07:03.963114 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-06-18 12:07:04.682876 | 
2026-06-18 12:07:04.683142 | PLAY [localhost]
2026-06-18 12:07:04.696820 | 
2026-06-18 12:07:04.697198 | TASK [Gathering Facts]
2026-06-18 12:07:05.640865 | localhost | ok
2026-06-18 12:07:05.648441 | 
2026-06-18 12:07:05.648591 | TASK [Setup log path fact]
2026-06-18 12:07:05.697432 | localhost | ok
2026-06-18 12:07:05.729249 | 
2026-06-18 12:07:05.729557 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-18 12:07:05.774778 | localhost | ok
2026-06-18 12:07:05.786817 | 
2026-06-18 12:07:05.786981 | TASK [emit-job-header : Print job information]
2026-06-18 12:07:05.837067 | # Job Information
2026-06-18 12:07:05.837303 | Ansible Version: 2.16.18
2026-06-18 12:07:05.837355 | Job: ansible-collection-kubernetes-molecule-helm
2026-06-18 12:07:05.837392 | Pipeline: check
2026-06-18 12:07:05.837425 | Executor: 2d72f0692154
2026-06-18 12:07:05.837457 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/282
2026-06-18 12:07:05.837494 | Event ID: 2706d770-6b0e-11f1-8238-90f6d168163c
2026-06-18 12:07:05.845316 | 
2026-06-18 12:07:05.846063 | LOOP [emit-job-header : Print node information]
2026-06-18 12:07:05.931745 | localhost | ok:
2026-06-18 12:07:05.932009 | localhost | # Node Information
2026-06-18 12:07:05.932084 | localhost | Inventory Hostname: controller
2026-06-18 12:07:05.932130 | localhost | Hostname: np0000181927
2026-06-18 12:07:05.932200 | localhost | Username: zuul
2026-06-18 12:07:05.932265 | localhost | Distro: Debian 13.5
2026-06-18 12:07:05.932307 | localhost | Provider: yul1
2026-06-18 12:07:05.932375 | localhost | Region: ca-ymq-1
2026-06-18 12:07:05.932433 | localhost | Label: debian-trixie
2026-06-18 12:07:05.932478 | localhost | Product Name: OpenStack Nova
2026-06-18 12:07:05.932501 | localhost | Interface IP: 199.19.213.133
2026-06-18 12:07:05.944738 | 
2026-06-18 12:07:05.945001 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-06-18 12:07:06.436182 | localhost -> localhost | changed
2026-06-18 12:07:06.444501 | 
2026-06-18 12:07:06.444690 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-06-18 12:07:07.555445 | localhost -> localhost | changed
2026-06-18 12:07:07.566765 | 
2026-06-18 12:07:07.566834 | PLAY [all]
2026-06-18 12:07:07.573992 | 
2026-06-18 12:07:07.574072 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-06-18 12:07:07.833979 | controller -> localhost | ok
2026-06-18 12:07:07.842032 | 
2026-06-18 12:07:07.842174 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-06-18 12:07:07.874504 | controller | ok
2026-06-18 12:07:07.895286 | controller | included: /var/lib/zuul/builds/d579eee77fd44b7d9ead37434ad64413/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-06-18 12:07:07.902953 | 
2026-06-18 12:07:07.903085 | TASK [add-build-sshkey : Create Temp SSH key]
2026-06-18 12:07:09.414662 | controller -> localhost | Generating public/private rsa key pair.
2026-06-18 12:07:09.414884 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/d579eee77fd44b7d9ead37434ad64413/work/d579eee77fd44b7d9ead37434ad64413_id_rsa
2026-06-18 12:07:09.414928 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/d579eee77fd44b7d9ead37434ad64413/work/d579eee77fd44b7d9ead37434ad64413_id_rsa.pub
2026-06-18 12:07:09.414960 | controller -> localhost | The key fingerprint is:
2026-06-18 12:07:09.414991 | controller -> localhost | SHA256:+8pl637+2y7/mgCEiZkGcuMLV4qr6KjgGGvl6tzxjx4 zuul-build-sshkey
2026-06-18 12:07:09.415041 | controller -> localhost | The key's randomart image is:
2026-06-18 12:07:09.415072 | controller -> localhost | +---[RSA 3072]----+
2026-06-18 12:07:09.415107 | controller -> localhost | |  . + .          |
2026-06-18 12:07:09.415138 | controller -> localhost | |   = = + o       |
2026-06-18 12:07:09.415168 | controller -> localhost | |  o + = o .      |
2026-06-18 12:07:09.415197 | controller -> localhost | |   + o   .       |
2026-06-18 12:07:09.415226 | controller -> localhost | |  . .   S .      |
2026-06-18 12:07:09.415255 | controller -> localhost | |.. .     . .     |
2026-06-18 12:07:09.415283 | controller -> localhost | |= o. E  . o .    |
2026-06-18 12:07:09.415314 | controller -> localhost | |B+..o o. + ....o |
2026-06-18 12:07:09.415362 | controller -> localhost | |O*...+..o+=o..=**|
2026-06-18 12:07:09.415407 | controller -> localhost | +----[SHA256]-----+
2026-06-18 12:07:09.415476 | controller -> localhost | ok: Runtime: 0:00:00.681105
2026-06-18 12:07:09.420346 | 
2026-06-18 12:07:09.420413 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-06-18 12:07:09.451939 | controller | ok
2026-06-18 12:07:09.462230 | controller | included: /var/lib/zuul/builds/d579eee77fd44b7d9ead37434ad64413/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-06-18 12:07:09.468860 | 
2026-06-18 12:07:09.468924 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-06-18 12:07:09.514466 | controller | skipping: Conditional result was False
2026-06-18 12:07:09.522114 | 
2026-06-18 12:07:09.522212 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-06-18 12:07:10.121275 | controller | changed
2026-06-18 12:07:10.126413 | 
2026-06-18 12:07:10.126483 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-06-18 12:07:10.325792 | controller | ok
2026-06-18 12:07:10.330298 | 
2026-06-18 12:07:10.330378 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-06-18 12:07:10.897725 | controller | changed
2026-06-18 12:07:10.901953 | 
2026-06-18 12:07:10.902027 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-06-18 12:07:11.469510 | controller | changed
2026-06-18 12:07:11.475182 | 
2026-06-18 12:07:11.475270 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-06-18 12:07:11.501001 | controller | skipping: Conditional result was False
2026-06-18 12:07:11.510019 | 
2026-06-18 12:07:11.510153 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-06-18 12:07:11.901686 | controller -> localhost | changed
2026-06-18 12:07:11.916792 | 
2026-06-18 12:07:11.917043 | TASK [add-build-sshkey : Add back temp key]
2026-06-18 12:07:12.238436 | controller -> localhost | Identity added: /var/lib/zuul/builds/d579eee77fd44b7d9ead37434ad64413/work/d579eee77fd44b7d9ead37434ad64413_id_rsa (zuul-build-sshkey)
2026-06-18 12:07:12.238693 | controller -> localhost | ok: Runtime: 0:00:00.021674
2026-06-18 12:07:12.252893 | 
2026-06-18 12:07:12.253054 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-06-18 12:07:12.671106 | controller | ok
2026-06-18 12:07:12.830731 | 
2026-06-18 12:07:12.830838 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-06-18 12:07:12.861129 | controller | skipping: Conditional result was False
2026-06-18 12:07:12.869688 | 
2026-06-18 12:07:12.869769 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-06-18 12:07:13.297631 | controller | ok
2026-06-18 12:07:13.305940 | 
2026-06-18 12:07:13.306050 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-06-18 12:07:14.418484 | controller | Output suppressed because no_log was given
2026-06-18 12:07:14.428735 | 
2026-06-18 12:07:14.428864 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-06-18 12:07:14.664277 | controller | ok: "logs"
2026-06-18 12:07:14.700978 | controller | ok: All items complete
2026-06-18 12:07:14.701133 | 
2026-06-18 12:07:14.841046 | controller | ok: "artifacts"
2026-06-18 12:07:15.034360 | controller | ok: "docs"
2026-06-18 12:07:15.053255 | 
2026-06-18 12:07:15.053484 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-06-18 12:07:15.269186 | controller | changed: "logs"
2026-06-18 12:07:15.460031 | controller | changed: "artifacts"
2026-06-18 12:07:15.639576 | controller | changed: "docs"
2026-06-18 12:07:15.658794 | 
2026-06-18 12:07:15.658923 | PLAY RECAP
2026-06-18 12:07:15.658989 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-06-18 12:07:15.659030 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-18 12:07:15.659061 | 
2026-06-18 12:07:15.849964 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-06-18 12:07:15.851885 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-06-18 12:07:16.616413 | 
2026-06-18 12:07:16.677792 | PLAY [all]
2026-06-18 12:07:16.695877 | 
2026-06-18 12:07:16.695998 | TASK [setup-uv : Extract archive]
2026-06-18 12:07:19.321752 | controller | changed
2026-06-18 12:07:19.328937 | 
2026-06-18 12:07:19.329056 | TASK [setup-uv : Print version]
2026-06-18 12:07:19.738780 | controller | uv 0.8.13
2026-06-18 12:07:19.862237 | controller | ok: Runtime: 0:00:00.013979
2026-06-18 12:07:19.871393 | 
2026-06-18 12:07:19.871469 | TASK [Install Ansible collection siblings]
2026-06-18 12:07:20.367733 | controller | ok:
2026-06-18 12:07:20.368427 | controller | Skipping ansible.posix: no checked-out sibling
2026-06-18 12:07:20.368486 | controller | Skipping community.crypto: no checked-out sibling
2026-06-18 12:07:20.368520 | controller | Skipping community.general: no checked-out sibling
2026-06-18 12:07:20.368549 | controller | Skipping kubernetes.core: no checked-out sibling
2026-06-18 12:07:20.368576 | controller | Skipping vexxhost.containers: no checked-out sibling
2026-06-18 12:07:20.371477 | 
2026-06-18 12:07:20.371543 | PLAY RECAP
2026-06-18 12:07:20.371614 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-18 12:07:20.371657 | 
2026-06-18 12:07:20.598565 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-06-18 12:07:20.600068 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-06-18 12:07:21.223856 | 
2026-06-18 12:07:21.223998 | PLAY [all]
2026-06-18 12:07:21.234713 | 
2026-06-18 12:07:21.234804 | TASK [Update apt cache]
2026-06-18 12:07:26.219011 | controller | ok
2026-06-18 12:07:26.230681 | 
2026-06-18 12:07:26.230886 | TASK [Disable firewalld]
2026-06-18 12:07:26.257238 | controller | skipping: Conditional result was False
2026-06-18 12:07:26.267202 | 
2026-06-18 12:07:26.267306 | TASK [Install "jq" for log collection]
2026-06-18 12:07:28.669960 | controller | changed
2026-06-18 12:07:28.672123 | 
2026-06-18 12:07:28.672192 | PLAY RECAP
2026-06-18 12:07:28.672254 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-06-18 12:07:28.672286 | 
2026-06-18 12:07:28.781670 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-06-18 12:07:28.784403 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-06-18 12:07:29.428018 | 
2026-06-18 12:07:29.428140 | PLAY [all]
2026-06-18 12:07:29.437758 | 
2026-06-18 12:07:29.437866 | LOOP [Check inventory directories]
2026-06-18 12:07:29.820026 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-06-18 12:07:30.038389 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/helm/inventory"
2026-06-18 12:07:30.218608 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/inventory"
2026-06-18 12:07:30.233192 | 
2026-06-18 12:07:30.233406 | LOOP [Copy inventory file for Zuul]
2026-06-18 12:07:31.234172 | controller | changed:
2026-06-18 12:07:31.234394 | controller | {
2026-06-18 12:07:31.234433 | controller |   "ansible_loop_var": "item",
2026-06-18 12:07:31.234467 | controller |   "changed": false,
2026-06-18 12:07:31.234495 | controller |   "failed": false,
2026-06-18 12:07:31.234526 | controller |   "invocation": {
2026-06-18 12:07:31.234553 | controller |     "module_args": {
2026-06-18 12:07:31.234579 | controller |       "checksum_algorithm": "sha1",
2026-06-18 12:07:31.234604 | controller |       "follow": true,
2026-06-18 12:07:31.234628 | controller |       "get_attributes": true,
2026-06-18 12:07:31.234663 | controller |       "get_checksum": true,
2026-06-18 12:07:31.234702 | controller |       "get_mime": true,
2026-06-18 12:07:31.234732 | controller |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-06-18 12:07:31.234758 | controller |     }
2026-06-18 12:07:31.234782 | controller |   },
2026-06-18 12:07:31.234807 | controller |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-06-18 12:07:31.234832 | controller |   "stat": {
2026-06-18 12:07:31.234856 | controller |     "atime": 1781784434.273972,
2026-06-18 12:07:31.234882 | controller |     "attr_flags": "e",
2026-06-18 12:07:31.234907 | controller |     "attributes": [
2026-06-18 12:07:31.234931 | controller |       "extents"
2026-06-18 12:07:31.234956 | controller |     ],
2026-06-18 12:07:31.234980 | controller |     "block_size": 4096,
2026-06-18 12:07:31.235007 | controller |     "blocks": 8,
2026-06-18 12:07:31.235042 | controller |     "charset": "unknown",
2026-06-18 12:07:31.235071 | controller |     "ctime": 1781784434.2979715,
2026-06-18 12:07:31.235097 | controller |     "dev": 65025,
2026-06-18 12:07:31.235122 | controller |     "device_type": 0,
2026-06-18 12:07:31.235147 | controller |     "executable": true,
2026-06-18 12:07:31.235171 | controller |     "exists": true,
2026-06-18 12:07:31.235195 | controller |     "gid": 1000,
2026-06-18 12:07:31.235219 | controller |     "gr_name": "zuul",
2026-06-18 12:07:31.235243 | controller |     "inode": 520215,
2026-06-18 12:07:31.235267 | controller |     "isblk": false,
2026-06-18 12:07:31.235292 | controller |     "ischr": false,
2026-06-18 12:07:31.235316 | controller |     "isdir": true,
2026-06-18 12:07:31.235343 | controller |     "isfifo": false,
2026-06-18 12:07:31.235369 | controller |     "isgid": false,
2026-06-18 12:07:31.235392 | controller |     "islnk": false,
2026-06-18 12:07:31.235416 | controller |     "isreg": false,
2026-06-18 12:07:31.235439 | controller |     "issock": false,
2026-06-18 12:07:31.235463 | controller |     "isuid": false,
2026-06-18 12:07:31.235486 | controller |     "mimetype": "unknown",
2026-06-18 12:07:31.235510 | controller |     "mode": "0755",
2026-06-18 12:07:31.235534 | controller |     "mtime": 1781784408.8941064,
2026-06-18 12:07:31.235557 | controller |     "nlink": 14,
2026-06-18 12:07:31.235581 | controller |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-06-18 12:07:31.235605 | controller |     "pw_name": "zuul",
2026-06-18 12:07:31.235633 | controller |     "readable": true,
2026-06-18 12:07:31.235657 | controller |     "rgrp": true,
2026-06-18 12:07:31.235682 | controller |     "roth": true,
2026-06-18 12:07:31.235706 | controller |     "rusr": true,
2026-06-18 12:07:31.235731 | controller |     "size": 4096,
2026-06-18 12:07:31.235755 | controller |     "uid": 1000,
2026-06-18 12:07:31.235787 | controller |     "version": "1064244504",
2026-06-18 12:07:31.235822 | controller |     "wgrp": false,
2026-06-18 12:07:31.235859 | controller |     "woth": false,
2026-06-18 12:07:31.235895 | controller |     "writeable": true,
2026-06-18 12:07:31.235921 | controller |     "wusr": true,
2026-06-18 12:07:31.235945 | controller |     "xgrp": true,
2026-06-18 12:07:31.235968 | controller |     "xoth": true,
2026-06-18 12:07:31.235992 | controller |     "xusr": true
2026-06-18 12:07:31.236024 | controller |   }
2026-06-18 12:07:31.236075 | controller | }
2026-06-18 12:07:31.236130 | controller | ok: All items complete
2026-06-18 12:07:31.236161 | 
2026-06-18 12:07:31.237818 | controller | skipping: Conditional result was False
2026-06-18 12:07:31.239854 | controller | skipping: Conditional result was False
2026-06-18 12:07:31.254489 | 
2026-06-18 12:07:31.254631 | LOOP [Switch "ansible_host" to private IP]
2026-06-18 12:07:31.645043 | controller | changed: 1 replacements made
2026-06-18 12:07:31.646880 | controller | skipping: Conditional result was False
2026-06-18 12:07:31.647720 | controller | skipping: Conditional result was False
2026-06-18 12:07:31.669631 | 
2026-06-18 12:07:31.670233 | TASK [Run Molecule scenario]
2026-06-18 12:07:32.112815 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-06-18 12:07:32.112885 | controller | Creating virtual environment at: .venv
2026-06-18 12:07:32.588759 | controller | Downloading ansible-core (2.3MiB)
2026-06-18 12:07:32.594328 | controller | Downloading cryptography (4.3MiB)
2026-06-18 12:07:32.605492 | controller | Downloading pygments (1.2MiB)
2026-06-18 12:07:32.818301 | controller |  Downloading pygments
2026-06-18 12:07:32.951311 | controller |  Downloading cryptography
2026-06-18 12:07:33.008460 | controller |  Downloading ansible-core
2026-06-18 12:07:33.060684 | controller | Installed 36 packages in 51ms
2026-06-18 12:07:33.770985 | controller | INFO     Collection 'vexxhost.kubernetes' detected.
2026-06-18 12:07:33.771046 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-06-18 12:07:34.721904 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-06-18 12:07:34.721983 | controller | INFO     default scenario not found, disabling shared state.
2026-06-18 12:07:34.722005 | controller | INFO     [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-06-18 12:07:34.722014 | controller | INFO     [helm > prerun] Performing prerun with role_name_check=0...
2026-06-18 12:08:25.952931 | controller | INFO     [helm > dependency] Executing
2026-06-18 12:08:25.953068 | controller | WARNING  [helm > dependency] Missing roles requirements file: requirements.yml
2026-06-18 12:08:25.953275 | controller | WARNING  [helm > dependency] Missing collections requirements file: collections.yml
2026-06-18 12:08:25.953398 | controller | WARNING  [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-06-18 12:08:25.959319 | controller | INFO     [helm > cleanup] Executing
2026-06-18 12:08:25.959913 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-06-18 12:08:25.965676 | controller | INFO     [helm > destroy] Executing
2026-06-18 12:08:25.965744 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-06-18 12:08:25.965838 | controller | INFO     [helm > destroy] Executed: Successful
2026-06-18 12:08:25.971409 | controller | INFO     [helm > syntax] Executing
2026-06-18 12:08:27.016593 | controller |
2026-06-18 12:08:27.016650 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml
2026-06-18 12:08:27.142101 | controller | INFO     [helm > syntax] Executed: Successful
2026-06-18 12:08:27.152303 | controller | INFO     [helm > create] Executing
2026-06-18 12:08:27.153833 | controller | WARNING  [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-06-18 12:08:27.159496 | controller | INFO     [helm > prepare] Executing
2026-06-18 12:08:28.081069 | controller |
2026-06-18 12:08:28.081194 | controller | PLAY [Prepare] *****************************************************************
2026-06-18 12:08:28.081312 | controller |
2026-06-18 12:08:28.081528 | controller | TASK [Gathering Facts] *********************************************************
2026-06-18 12:08:28.081746 | controller | Thursday 18 June 2026  12:08:28 +0000 (0:00:00.036)       0:00:00.036 *********
2026-06-18 12:08:30.340200 | 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-06-18 12:08:30.344846 | controller | ok: [controller]
2026-06-18 12:08:30.344922 | controller |
2026-06-18 12:08:30.345016 | controller | TASK [Install Debian packages] *************************************************
2026-06-18 12:08:30.345112 | controller | Thursday 18 June 2026  12:08:30 +0000 (0:00:02.265)       0:00:02.301 *********
2026-06-18 12:08:31.905564 | controller | ok: [controller]
2026-06-18 12:08:31.905731 | controller |
2026-06-18 12:08:31.906056 | controller | PLAY RECAP *********************************************************************
2026-06-18 12:08:31.906289 | controller | controller                 : ok=2    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-06-18 12:08:31.906483 | controller |
2026-06-18 12:08:31.906672 | controller |
2026-06-18 12:08:31.906862 | controller | TASKS RECAP ********************************************************************
2026-06-18 12:08:31.907062 | controller | Thursday 18 June 2026  12:08:31 +0000 (0:00:01.560)       0:00:03.862 *********
2026-06-18 12:08:31.907247 | controller | ===============================================================================
2026-06-18 12:08:31.907439 | controller | Gathering Facts --------------------------------------------------------- 2.27s
2026-06-18 12:08:31.907623 | controller | Install Debian packages ------------------------------------------------- 1.56s
2026-06-18 12:08:32.049158 | controller | INFO     [helm > prepare] Executed: Successful
2026-06-18 12:08:32.062858 | controller | INFO     [helm > converge] Executing
2026-06-18 12:08:32.964121 | controller |
2026-06-18 12:08:32.964209 | controller | PLAY [Converge] ****************************************************************
2026-06-18 12:08:32.964412 | controller |
2026-06-18 12:08:32.964644 | controller | TASK [Gathering Facts] *********************************************************
2026-06-18 12:08:32.964824 | controller | Thursday 18 June 2026  12:08:32 +0000 (0:00:00.015)       0:00:00.015 *********
2026-06-18 12:08:34.078599 | 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-06-18 12:08:34.082448 | controller | ok: [controller]
2026-06-18 12:08:34.082648 | controller |
2026-06-18 12:08:34.082872 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-06-18 12:08:34.083089 | controller | Thursday 18 June 2026  12:08:34 +0000 (0:00:01.118)       0:00:01.134 *********
2026-06-18 12:08:34.587262 | controller | ok: [controller]
2026-06-18 12:08:34.587341 | controller |
2026-06-18 12:08:34.587504 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-06-18 12:08:34.587749 | controller | Thursday 18 June 2026  12:08:34 +0000 (0:00:00.503)       0:00:01.638 *********
2026-06-18 12:08:35.700146 | controller | ok: [controller]
2026-06-18 12:08:35.700206 | controller |
2026-06-18 12:08:35.700214 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-06-18 12:08:35.702078 | controller | Thursday 18 June 2026  12:08:35 +0000 (0:00:01.112)       0:00:02.750 *********
2026-06-18 12:08:36.118735 | controller | changed: [controller]
2026-06-18 12:08:36.118803 | controller |
2026-06-18 12:08:36.118916 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-06-18 12:08:36.119015 | controller | Thursday 18 June 2026  12:08:36 +0000 (0:00:00.418)       0:00:03.169 *********
2026-06-18 12:08:36.161177 | controller | ok: [controller] => {
2026-06-18 12:08:36.161272 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-06-18 12:08:36.161402 | controller | }
2026-06-18 12:08:36.161512 | controller |
2026-06-18 12:08:36.163708 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-06-18 12:08:36.163729 | controller | Thursday 18 June 2026  12:08:36 +0000 (0:00:00.043)       0:00:03.212 *********
2026-06-18 12:08:36.972987 | 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-06-18 12:08:36.983559 | controller | changed: [controller]
2026-06-18 12:08:36.983738 | controller |
2026-06-18 12:08:36.983951 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-06-18 12:08:36.984153 | controller | Thursday 18 June 2026  12:08:36 +0000 (0:00:00.822)       0:00:04.035 *********
2026-06-18 12:08:39.067752 | controller | changed: [controller]
2026-06-18 12:08:39.067811 | controller |
2026-06-18 12:08:39.067825 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-06-18 12:08:39.067944 | controller | Thursday 18 June 2026  12:08:39 +0000 (0:00:02.083)       0:00:06.119 *********
2026-06-18 12:08:39.591699 | controller | ok: [controller]
2026-06-18 12:08:39.591850 | controller |
2026-06-18 12:08:39.592072 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-06-18 12:08:39.592281 | controller | Thursday 18 June 2026  12:08:39 +0000 (0:00:00.523)       0:00:06.642 *********
2026-06-18 12:08:40.012402 | controller | changed: [controller]
2026-06-18 12:08:40.012480 | controller |
2026-06-18 12:08:40.012735 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-06-18 12:08:40.012927 | controller | Thursday 18 June 2026  12:08:40 +0000 (0:00:00.421)       0:00:07.064 *********
2026-06-18 12:08:40.497765 | controller | ok: [controller]
2026-06-18 12:08:40.497804 | controller |
2026-06-18 12:08:40.497814 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-06-18 12:08:40.497822 | controller | Thursday 18 June 2026  12:08:40 +0000 (0:00:00.480)       0:00:07.544 *********
2026-06-18 12:08:40.798542 | controller | changed: [controller]
2026-06-18 12:08:40.798608 | controller |
2026-06-18 12:08:40.798783 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-06-18 12:08:40.798849 | controller | Thursday 18 June 2026  12:08:40 +0000 (0:00:00.303)       0:00:07.848 *********
2026-06-18 12:08:41.117090 | controller | ok: [controller]
2026-06-18 12:08:41.117225 | controller |
2026-06-18 12:08:41.117379 | controller | TASK [Install plugin] **********************************************************
2026-06-18 12:08:41.117521 | controller | Thursday 18 June 2026  12:08:41 +0000 (0:00:00.320)       0:00:08.168 *********
2026-06-18 12:08:41.172886 | controller | included: vexxhost.containers.download_artifact for controller
2026-06-18 12:08:41.172944 | controller |
2026-06-18 12:08:41.173043 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-06-18 12:08:41.173178 | controller | Thursday 18 June 2026  12:08:41 +0000 (0:00:00.056)       0:00:08.225 *********
2026-06-18 12:08:41.222805 | controller | ok: [controller] => {
2026-06-18 12:08:41.222918 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-06-18 12:08:41.223046 | controller | }
2026-06-18 12:08:41.223156 | controller |
2026-06-18 12:08:41.223288 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-06-18 12:08:41.223419 | controller | Thursday 18 June 2026  12:08:41 +0000 (0:00:00.047)       0:00:08.272 *********
2026-06-18 12:08:42.161557 | controller | changed: [controller]
2026-06-18 12:08:42.161619 | controller |
2026-06-18 12:08:42.161756 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-06-18 12:08:42.161918 | controller | Thursday 18 June 2026  12:08:42 +0000 (0:00:00.940)       0:00:09.212 *********
2026-06-18 12:08:44.327789 | controller | changed: [controller]
2026-06-18 12:08:44.327835 | controller |
2026-06-18 12:08:44.327846 | controller | PLAY RECAP *********************************************************************
2026-06-18 12:08:44.327858 | controller | controller                 : ok=16   changed=7    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-06-18 12:08:44.327866 | controller |
2026-06-18 12:08:44.327874 | controller |
2026-06-18 12:08:44.327882 | controller | TASKS RECAP ********************************************************************
2026-06-18 12:08:44.327891 | controller | Thursday 18 June 2026  12:08:44 +0000 (0:00:02.165)       0:00:11.378 *********
2026-06-18 12:08:44.327899 | controller | ===============================================================================
2026-06-18 12:08:44.327907 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.17s
2026-06-18 12:08:44.327914 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.08s
2026-06-18 12:08:44.327922 | controller | Gathering Facts --------------------------------------------------------- 1.12s
2026-06-18 12:08:44.327930 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.11s
2026-06-18 12:08:44.327946 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.94s
2026-06-18 12:08:44.329666 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.82s
2026-06-18 12:08:44.329705 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.52s
2026-06-18 12:08:44.329711 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.50s
2026-06-18 12:08:44.329717 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.48s
2026-06-18 12:08:44.329723 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.42s
2026-06-18 12:08:44.329749 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.42s
2026-06-18 12:08:44.329754 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.32s
2026-06-18 12:08:44.329759 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.30s
2026-06-18 12:08:44.329764 | controller | Install plugin ---------------------------------------------------------- 0.06s
2026-06-18 12:08:44.329769 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-06-18 12:08:44.329774 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s
2026-06-18 12:08:44.469413 | controller | INFO     [helm > converge] Executed: Successful
2026-06-18 12:08:44.478812 | controller | INFO     [helm > idempotence] Executing
2026-06-18 12:08:45.395583 | controller |
2026-06-18 12:08:45.395868 | controller | PLAY [Converge] ****************************************************************
2026-06-18 12:08:45.396038 | controller |
2026-06-18 12:08:45.396215 | controller | TASK [Gathering Facts] *********************************************************
2026-06-18 12:08:45.396390 | controller | Thursday 18 June 2026  12:08:45 +0000 (0:00:00.013)       0:00:00.013 *********
2026-06-18 12:08:46.512125 | 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-06-18 12:08:46.518665 | controller | ok: [controller]
2026-06-18 12:08:46.518684 | controller |
2026-06-18 12:08:46.518692 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-06-18 12:08:46.518698 | controller | Thursday 18 June 2026  12:08:46 +0000 (0:00:01.122)       0:00:01.136 *********
2026-06-18 12:08:47.008274 | controller | ok: [controller]
2026-06-18 12:08:47.008333 | controller |
2026-06-18 12:08:47.008433 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-06-18 12:08:47.008557 | controller | Thursday 18 June 2026  12:08:47 +0000 (0:00:00.489)       0:00:01.626 *********
2026-06-18 12:08:48.112792 | controller | ok: [controller]
2026-06-18 12:08:48.112869 | controller |
2026-06-18 12:08:48.112884 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-06-18 12:08:48.112980 | controller | Thursday 18 June 2026  12:08:48 +0000 (0:00:01.104)       0:00:02.730 *********
2026-06-18 12:08:48.570077 | controller | ok: [controller]
2026-06-18 12:08:48.570136 | controller |
2026-06-18 12:08:48.570262 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-06-18 12:08:48.570391 | controller | Thursday 18 June 2026  12:08:48 +0000 (0:00:00.456)       0:00:03.187 *********
2026-06-18 12:08:48.619997 | controller | ok: [controller] => {
2026-06-18 12:08:48.620058 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-06-18 12:08:48.620185 | controller | }
2026-06-18 12:08:48.620574 | controller |
2026-06-18 12:08:48.620601 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-06-18 12:08:48.620607 | controller | Thursday 18 June 2026  12:08:48 +0000 (0:00:00.047)       0:00:03.235 *********
2026-06-18 12:08:49.248132 | controller | ok: [controller]
2026-06-18 12:08:49.248183 | controller |
2026-06-18 12:08:49.248273 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-06-18 12:08:49.248369 | controller | Thursday 18 June 2026  12:08:49 +0000 (0:00:00.629)       0:00:03.864 *********
2026-06-18 12:08:51.031270 | controller | ok: [controller]
2026-06-18 12:08:51.031355 | controller |
2026-06-18 12:08:51.031512 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-06-18 12:08:51.031706 | controller | Thursday 18 June 2026  12:08:51 +0000 (0:00:01.784)       0:00:05.649 *********
2026-06-18 12:08:51.560243 | controller | ok: [controller]
2026-06-18 12:08:51.560302 | controller |
2026-06-18 12:08:51.560424 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-06-18 12:08:51.560601 | controller | Thursday 18 June 2026  12:08:51 +0000 (0:00:00.527)       0:00:06.176 *********
2026-06-18 12:08:52.016365 | controller | ok: [controller]
2026-06-18 12:08:52.016429 | controller |
2026-06-18 12:08:52.016534 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-06-18 12:08:52.016709 | controller | Thursday 18 June 2026  12:08:52 +0000 (0:00:00.457)       0:00:06.634 *********
2026-06-18 12:08:52.504291 | controller | ok: [controller]
2026-06-18 12:08:52.504352 | controller |
2026-06-18 12:08:52.504525 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-06-18 12:08:52.504664 | controller | Thursday 18 June 2026  12:08:52 +0000 (0:00:00.486)       0:00:07.120 *********
2026-06-18 12:08:52.840666 | controller | ok: [controller]
2026-06-18 12:08:52.840768 | controller |
2026-06-18 12:08:52.840788 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-06-18 12:08:52.840870 | controller | Thursday 18 June 2026  12:08:52 +0000 (0:00:00.338)       0:00:07.458 *********
2026-06-18 12:08:53.148136 | controller | ok: [controller]
2026-06-18 12:08:53.148192 | controller |
2026-06-18 12:08:53.148288 | controller | TASK [Install plugin] **********************************************************
2026-06-18 12:08:53.148409 | controller | Thursday 18 June 2026  12:08:53 +0000 (0:00:00.307)       0:00:07.765 *********
2026-06-18 12:08:53.208273 | controller | included: vexxhost.containers.download_artifact for controller
2026-06-18 12:08:53.208352 | controller |
2026-06-18 12:08:53.208453 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-06-18 12:08:53.208549 | controller | Thursday 18 June 2026  12:08:53 +0000 (0:00:00.056)       0:00:07.822 *********
2026-06-18 12:08:53.256418 | controller | ok: [controller] => {
2026-06-18 12:08:53.256556 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-06-18 12:08:53.256586 | controller | }
2026-06-18 12:08:53.256914 | controller |
2026-06-18 12:08:53.256943 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-06-18 12:08:53.256948 | controller | Thursday 18 June 2026  12:08:53 +0000 (0:00:00.051)       0:00:07.874 *********
2026-06-18 12:08:53.739241 | controller | ok: [controller]
2026-06-18 12:08:53.739324 | controller |
2026-06-18 12:08:53.739333 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-06-18 12:08:53.739472 | controller | Thursday 18 June 2026  12:08:53 +0000 (0:00:00.482)       0:00:08.357 *********
2026-06-18 12:08:55.395783 | controller | ok: [controller]
2026-06-18 12:08:55.395825 | controller |
2026-06-18 12:08:55.395835 | controller | PLAY RECAP *********************************************************************
2026-06-18 12:08:55.395844 | controller | controller                 : ok=16   changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-06-18 12:08:55.395851 | controller |
2026-06-18 12:08:55.395859 | controller |
2026-06-18 12:08:55.395866 | controller | TASKS RECAP ********************************************************************
2026-06-18 12:08:55.395874 | controller | Thursday 18 June 2026  12:08:55 +0000 (0:00:01.652)       0:00:10.009 *********
2026-06-18 12:08:55.395881 | controller | ===============================================================================
2026-06-18 12:08:55.395888 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.78s
2026-06-18 12:08:55.395895 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.65s
2026-06-18 12:08:55.395902 | controller | Gathering Facts --------------------------------------------------------- 1.12s
2026-06-18 12:08:55.395909 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.10s
2026-06-18 12:08:55.395916 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.63s
2026-06-18 12:08:55.395923 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.53s
2026-06-18 12:08:55.395930 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.49s
2026-06-18 12:08:55.395937 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.49s
2026-06-18 12:08:55.395945 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.48s
2026-06-18 12:08:55.395952 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.46s
2026-06-18 12:08:55.395971 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.46s
2026-06-18 12:08:55.395978 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.34s
2026-06-18 12:08:55.395992 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.31s
2026-06-18 12:08:55.396592 | controller | Install plugin ---------------------------------------------------------- 0.06s
2026-06-18 12:08:55.396630 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-06-18 12:08:55.396639 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-06-18 12:08:55.487814 | controller | INFO     [helm > idempotence] Executed: Successful
2026-06-18 12:08:55.502657 | controller | INFO     [helm > side_effect] Executing
2026-06-18 12:08:55.503039 | controller | WARNING  [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-06-18 12:08:55.508537 | controller | INFO     [helm > verify] Executing
2026-06-18 12:08:56.355115 | controller |
2026-06-18 12:08:56.355329 | controller | PLAY [Verify] ******************************************************************
2026-06-18 12:08:56.355494 | controller |
2026-06-18 12:08:56.355754 | controller | TASK [Gathering Facts] *********************************************************
2026-06-18 12:08:56.355953 | controller | Thursday 18 June 2026  12:08:56 +0000 (0:00:00.009)       0:00:00.009 *********
2026-06-18 12:08:57.466915 | 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-06-18 12:08:57.475390 | controller | ok: [controller]
2026-06-18 12:08:57.475429 | controller |
2026-06-18 12:08:57.475449 | controller | TASK [Get the current Helm version] ********************************************
2026-06-18 12:08:57.475461 | controller | Thursday 18 June 2026  12:08:57 +0000 (0:00:01.119)       0:00:01.128 *********
2026-06-18 12:08:57.951276 | controller | changed: [controller]
2026-06-18 12:08:57.951592 | controller |
2026-06-18 12:08:57.951937 | controller | TASK [Assert Helm version] *****************************************************
2026-06-18 12:08:57.952228 | controller | Thursday 18 June 2026  12:08:57 +0000 (0:00:00.475)       0:00:01.604 *********
2026-06-18 12:08:57.984895 | controller | ok: [controller] => {
2026-06-18 12:08:57.985043 | controller |     "changed": false,
2026-06-18 12:08:57.985164 | controller |     "msg": "All assertions passed"
2026-06-18 12:08:57.985277 | controller | }
2026-06-18 12:08:57.985383 | controller |
2026-06-18 12:08:57.985496 | controller | PLAY RECAP *********************************************************************
2026-06-18 12:08:57.985620 | controller | controller                 : ok=3    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-06-18 12:08:57.985728 | controller |
2026-06-18 12:08:57.985876 | controller |
2026-06-18 12:08:57.986029 | controller | TASKS RECAP ********************************************************************
2026-06-18 12:08:57.986188 | controller | Thursday 18 June 2026  12:08:57 +0000 (0:00:00.034)       0:00:01.639 *********
2026-06-18 12:08:57.986339 | controller | ===============================================================================
2026-06-18 12:08:57.986495 | controller | Gathering Facts --------------------------------------------------------- 1.12s
2026-06-18 12:08:57.986652 | controller | Get the current Helm version -------------------------------------------- 0.48s
2026-06-18 12:08:57.986808 | controller | Assert Helm version ----------------------------------------------------- 0.03s
2026-06-18 12:08:58.147752 | controller | INFO     [helm > verify] Executed: Successful
2026-06-18 12:08:58.155359 | controller | INFO     [helm > cleanup] Executing
2026-06-18 12:08:58.155834 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-06-18 12:08:58.161678 | controller | INFO     [helm > destroy] Executing
2026-06-18 12:08:58.161807 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-06-18 12:08:58.161899 | controller | INFO     [helm > destroy] Executed: Successful
2026-06-18 12:08:58.162176 | controller | WARNING  Molecule executed 1 scenario (1 missing files)
2026-06-18 12:08:58.294540 | controller | ok: Runtime: 0:01:26.206148
2026-06-18 12:08:58.298296 | 
2026-06-18 12:08:58.298364 | PLAY RECAP
2026-06-18 12:08:58.298411 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-18 12:08:58.298433 | 
2026-06-18 12:08:58.421775 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-06-18 12:08:58.423927 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-06-18 12:08:59.062123 | 
2026-06-18 12:08:59.062267 | PLAY [all]
2026-06-18 12:08:59.073690 | 
2026-06-18 12:08:59.073770 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-06-18 12:08:59.461120 | controller | changed
2026-06-18 12:08:59.467564 | 
2026-06-18 12:08:59.467653 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-06-18 12:08:59.854334 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-06-18 12:08:59.856011 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-06-18 12:08:59.861497 | controller | environment: line 1: kubectl: command not found
2026-06-18 12:08:59.863154 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-06-18 12:08:59.864347 | controller | environment: line 1: kubectl: command not found
2026-06-18 12:08:59.865491 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-06-18 12:08:59.869562 | controller | environment: line 1: kubectl: command not found
2026-06-18 12:08:59.870017 | controller | environment: line 1: kubectl: command not found
2026-06-18 12:08:59.871074 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-06-18 12:08:59.872073 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-06-18 12:08:59.874105 | controller | environment: line 1: kubectl: command not found
2026-06-18 12:08:59.875415 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-06-18 12:08:59.996267 | controller | ok: Runtime: 0:00:00.037815
2026-06-18 12:09:00.005203 | 
2026-06-18 12:09:00.005336 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-06-18 12:09:00.239646 | controller | changed
2026-06-18 12:09:00.248062 | 
2026-06-18 12:09:00.248243 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-06-18 12:09:00.509624 | controller | environment: line 5: kubectl: command not found
2026-06-18 12:09:00.510428 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-06-18 12:09:00.511489 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-06-18 12:09:00.512280 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-06-18 12:09:00.779887 | controller | ok: Runtime: 0:00:00.014808
2026-06-18 12:09:00.786346 | 
2026-06-18 12:09:00.786412 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-06-18 12:09:01.428090 | controller | changed:
2026-06-18 12:09:01.428270 | controller | created directory /var/lib/zuul/builds/d579eee77fd44b7d9ead37434ad64413/work/logs/controller
2026-06-18 12:09:01.428314 | controller | cd+++++++++ objects/
2026-06-18 12:09:01.428346 | controller | cd+++++++++ objects/cluster/
2026-06-18 12:09:01.428379 | controller | cd+++++++++ objects/namespaced/
2026-06-18 12:09:01.435823 | 
2026-06-18 12:09:01.435905 | TASK [gather-pod-logs : creating directory for pod logs]
2026-06-18 12:09:01.669605 | controller | changed
2026-06-18 12:09:01.675065 | 
2026-06-18 12:09:01.675174 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-06-18 12:09:01.923785 | controller | changed
2026-06-18 12:09:01.931115 | 
2026-06-18 12:09:01.931262 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-06-18 12:09:02.166664 | controller | environment: line 3: kubectl: command not found
2026-06-18 12:09:02.468932 | controller | ok: Runtime: 0:00:00.010583
2026-06-18 12:09:02.473866 | 
2026-06-18 12:09:02.473963 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-06-18 12:09:02.994721 | controller | changed:
2026-06-18 12:09:02.994975 | controller | cd+++++++++ pod-logs/
2026-06-18 12:09:02.995037 | controller | cd+++++++++ pod-logs/failed-pods/
2026-06-18 12:09:03.003267 | 
2026-06-18 12:09:03.003381 | LOOP [helm-release-status : creating directory for helm release status]
2026-06-18 12:09:03.255469 | controller | changed: "values"
2026-06-18 12:09:03.468482 | controller | changed: "releases"
2026-06-18 12:09:03.480228 | 
2026-06-18 12:09:03.481062 | TASK [helm-release-status : Gather get release status for helm charts]
2026-06-18 12:09:03.722275 | controller | /bin/bash: line 3: kubectl: command not found
2026-06-18 12:09:04.015901 | controller | ok: Runtime: 0:00:00.008139
2026-06-18 12:09:04.021951 | 
2026-06-18 12:09:04.022133 | TASK [helm-release-status : Downloads logs to executor]
2026-06-18 12:09:04.676303 | controller | changed:
2026-06-18 12:09:04.676549 | controller | cd+++++++++ helm/
2026-06-18 12:09:04.676592 | controller | cd+++++++++ helm/releases/
2026-06-18 12:09:04.676625 | controller | cd+++++++++ helm/values/
2026-06-18 12:09:04.683121 | 
2026-06-18 12:09:04.683186 | PLAY RECAP
2026-06-18 12:09:04.683242 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-18 12:09:04.683273 | 
2026-06-18 12:09:04.852337 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-06-18 12:09:04.855256 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-06-18 12:09:05.472667 | 
2026-06-18 12:09:05.472832 | PLAY [all]
2026-06-18 12:09:05.483148 | 
2026-06-18 12:09:05.483226 | TASK [fetch-output : Set log path for multiple nodes]
2026-06-18 12:09:05.520178 | controller | skipping: Conditional result was False
2026-06-18 12:09:05.528312 | 
2026-06-18 12:09:05.528437 | TASK [fetch-output : Set log path for single node]
2026-06-18 12:09:05.571399 | controller | ok
2026-06-18 12:09:05.576093 | 
2026-06-18 12:09:05.576160 | LOOP [fetch-output : Ensure local output dirs]
2026-06-18 12:09:05.967050 | controller -> localhost | ok: "/var/lib/zuul/builds/d579eee77fd44b7d9ead37434ad64413/work/logs"
2026-06-18 12:09:06.176648 | controller -> localhost | changed: "/var/lib/zuul/builds/d579eee77fd44b7d9ead37434ad64413/work/artifacts"
2026-06-18 12:09:06.380244 | controller -> localhost | changed: "/var/lib/zuul/builds/d579eee77fd44b7d9ead37434ad64413/work/docs"
2026-06-18 12:09:06.396268 | 
2026-06-18 12:09:06.396381 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-06-18 12:09:07.028094 | controller | changed: .d..t...... ./
2026-06-18 12:09:07.028398 | controller | changed: All items complete
2026-06-18 12:09:07.028442 | 
2026-06-18 12:09:07.476614 | controller | changed: .d..t...... ./
2026-06-18 12:09:07.937511 | controller | changed: .d..t...... ./
2026-06-18 12:09:07.956950 | 
2026-06-18 12:09:07.957110 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-06-18 12:09:08.435387 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.009757
2026-06-18 12:09:08.672491 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008250
2026-06-18 12:09:08.696030 | 
2026-06-18 12:09:08.696154 | PLAY [all]
2026-06-18 12:09:08.703766 | 
2026-06-18 12:09:08.703894 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-06-18 12:09:09.173386 | controller | changed
2026-06-18 12:09:09.180071 | 
2026-06-18 12:09:09.180132 | PLAY RECAP
2026-06-18 12:09:09.180182 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-06-18 12:09:09.180205 | 
2026-06-18 12:09:09.303706 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-06-18 12:09:09.305604 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-06-18 12:09:10.103353 | 
2026-06-18 12:09:10.103545 | PLAY [localhost]
2026-06-18 12:09:10.115059 | 
2026-06-18 12:09:10.115155 | TASK [Generate Zuul manifest]
2026-06-18 12:09:10.136221 | localhost | ok
2026-06-18 12:09:10.154444 | 
2026-06-18 12:09:10.154630 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-06-18 12:09:10.531562 | localhost | changed
2026-06-18 12:09:10.547259 | 
2026-06-18 12:09:10.547428 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-06-18 12:09:10.578231 | localhost | ok
2026-06-18 12:09:10.588420 | 
2026-06-18 12:09:10.588599 | TASK [Upload logs]
2026-06-18 12:09:10.611562 | localhost | ok
2026-06-18 12:09:10.686139 | 
2026-06-18 12:09:10.686310 | TASK [Set zuul-log-path fact]
2026-06-18 12:09:10.711398 | localhost | ok
2026-06-18 12:09:10.723729 | 
2026-06-18 12:09:10.723839 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-18 12:09:10.758690 | localhost | ok
2026-06-18 12:09:10.767557 | 
2026-06-18 12:09:10.767651 | TASK [upload-logs : Create log directories]
2026-06-18 12:09:11.197150 | localhost | changed
2026-06-18 12:09:11.204040 | 
2026-06-18 12:09:11.204152 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-06-18 12:09:11.633827 | localhost -> localhost | ok: Runtime: 0:00:00.004787
2026-06-18 12:09:11.639480 | 
2026-06-18 12:09:11.639577 | TASK [upload-logs : Upload logs to log server]
2026-06-18 12:09:12.117910 | localhost | Output suppressed because no_log was given
2026-06-18 12:09:12.123606 | 
2026-06-18 12:09:12.123720 | LOOP [upload-logs : Compress console log and json output]
2026-06-18 12:09:12.170603 | localhost | skipping: Conditional result was False
2026-06-18 12:09:12.219136 | localhost | skipping: Conditional result was False
2026-06-18 12:09:12.231812 | 
2026-06-18 12:09:12.232066 | LOOP [upload-logs : Upload compressed console log and json output]
2026-06-18 12:09:12.269680 | localhost | skipping: Conditional result was False
2026-06-18 12:09:12.270210 | 
2026-06-18 12:09:12.273877 | localhost | skipping: Conditional result was False
2026-06-18 12:09:12.286011 | 
2026-06-18 12:09:12.286186 | LOOP [upload-logs : Upload console log and json output]