2026-07-30 16:44:46.642044 | Job console starting
2026-07-30 16:44:46.654453 | Updating git repos
2026-07-30 16:44:46.693380 | Cloning repos into workspace
2026-07-30 16:44:48.626086 | Restoring repo states
2026-07-30 16:44:48.669947 | Merging changes
2026-07-30 16:44:49.718490 | Checking out repos
2026-07-30 16:44:49.887266 | Preparing playbooks
2026-07-30 16:44:59.540945 | Running Ansible setup
2026-07-30 16:45:03.586067 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-30 16:45:04.223661 | 
2026-07-30 16:45:04.223803 | PLAY [localhost]
2026-07-30 16:45:04.231862 | 
2026-07-30 16:45:04.231948 | TASK [Gathering Facts]
2026-07-30 16:45:05.117178 | localhost | ok
2026-07-30 16:45:05.128290 | 
2026-07-30 16:45:05.128401 | TASK [Setup log path fact]
2026-07-30 16:45:05.148500 | localhost | ok
2026-07-30 16:45:05.168036 | 
2026-07-30 16:45:05.168369 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-30 16:45:05.198022 | localhost | ok
2026-07-30 16:45:05.206735 | 
2026-07-30 16:45:05.206834 | TASK [emit-job-header : Print job information]
2026-07-30 16:45:05.249143 | # Job Information
2026-07-30 16:45:05.249410 | Ansible Version: 2.16.18
2026-07-30 16:45:05.249585 | Job: ansible-collection-kubernetes-molecule-helm
2026-07-30 16:45:05.249724 | Pipeline: check
2026-07-30 16:45:05.249779 | Executor: 2d72f0692154
2026-07-30 16:45:05.249817 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/295
2026-07-30 16:45:05.249860 | Event ID: 5857cb20-8c35-11f1-928e-0ef560c33bf4
2026-07-30 16:45:05.253283 | 
2026-07-30 16:45:05.253351 | LOOP [emit-job-header : Print node information]
2026-07-30 16:45:05.331107 | localhost | ok:
2026-07-30 16:45:05.331280 | localhost | # Node Information
2026-07-30 16:45:05.331310 | localhost | Inventory Hostname: controller
2026-07-30 16:45:05.331332 | localhost | Hostname: np0000187556
2026-07-30 16:45:05.331354 | localhost | Username: zuul
2026-07-30 16:45:05.331382 | localhost | Distro: Debian 13.6
2026-07-30 16:45:05.331403 | localhost | Provider: yul1
2026-07-30 16:45:05.331422 | localhost | Region: ca-ymq-1
2026-07-30 16:45:05.331441 | localhost | Label: debian-trixie
2026-07-30 16:45:05.331460 | localhost | Product Name: OpenStack Nova
2026-07-30 16:45:05.331479 | localhost | Interface IP: 199.19.213.18
2026-07-30 16:45:05.349341 | 
2026-07-30 16:45:05.349491 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-07-30 16:45:05.718368 | localhost -> localhost | changed
2026-07-30 16:45:05.728791 | 
2026-07-30 16:45:05.728892 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-07-30 16:45:07.080600 | localhost -> localhost | changed
2026-07-30 16:45:07.091777 | 
2026-07-30 16:45:07.091850 | PLAY [all]
2026-07-30 16:45:07.099865 | 
2026-07-30 16:45:07.099929 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-07-30 16:45:07.326286 | controller -> localhost | ok
2026-07-30 16:45:07.332101 | 
2026-07-30 16:45:07.332187 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-07-30 16:45:07.359379 | controller | ok
2026-07-30 16:45:07.375657 | controller | included: /var/lib/zuul/builds/ed0ec3aeb1934f9caac86edc81a42613/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-07-30 16:45:07.380018 | 
2026-07-30 16:45:07.380075 | TASK [add-build-sshkey : Create Temp SSH key]
2026-07-30 16:45:08.166019 | controller -> localhost | Generating public/private rsa key pair.
2026-07-30 16:45:08.166209 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/ed0ec3aeb1934f9caac86edc81a42613/work/ed0ec3aeb1934f9caac86edc81a42613_id_rsa
2026-07-30 16:45:08.166250 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/ed0ec3aeb1934f9caac86edc81a42613/work/ed0ec3aeb1934f9caac86edc81a42613_id_rsa.pub
2026-07-30 16:45:08.166297 | controller -> localhost | The key fingerprint is:
2026-07-30 16:45:08.166329 | controller -> localhost | SHA256:sugUlOWJZ1viVrQjIDaDpT+E+WPswcEF3j60JAAMPjg zuul-build-sshkey
2026-07-30 16:45:08.166371 | controller -> localhost | The key's randomart image is:
2026-07-30 16:45:08.166401 | controller -> localhost | +---[RSA 3072]----+
2026-07-30 16:45:08.166435 | controller -> localhost | |BoB.o . .        |
2026-07-30 16:45:08.166465 | controller -> localhost | |+X * * o .       |
2026-07-30 16:45:08.166493 | controller -> localhost | |Eo* B B =        |
2026-07-30 16:45:08.166521 | controller -> localhost | | B.B = * .       |
2026-07-30 16:45:08.166549 | controller -> localhost | |  X = = S        |
2026-07-30 16:45:08.166577 | controller -> localhost | | o + = o         |
2026-07-30 16:45:08.166604 | controller -> localhost | |  . o .          |
2026-07-30 16:45:08.166631 | controller -> localhost | |   o             |
2026-07-30 16:45:08.166662 | controller -> localhost | |    .            |
2026-07-30 16:45:08.166690 | controller -> localhost | +----[SHA256]-----+
2026-07-30 16:45:08.166753 | controller -> localhost | ok: Runtime: 0:00:00.340141
2026-07-30 16:45:08.172989 | 
2026-07-30 16:45:08.173078 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-07-30 16:45:08.203725 | controller | ok
2026-07-30 16:45:08.214560 | controller | included: /var/lib/zuul/builds/ed0ec3aeb1934f9caac86edc81a42613/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-07-30 16:45:08.220969 | 
2026-07-30 16:45:08.221043 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-07-30 16:45:08.246068 | controller | skipping: Conditional result was False
2026-07-30 16:45:08.254787 | 
2026-07-30 16:45:08.254854 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-07-30 16:45:08.785460 | controller | changed
2026-07-30 16:45:09.311658 | 
2026-07-30 16:45:09.311804 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-07-30 16:45:09.517560 | controller | ok
2026-07-30 16:45:09.522096 | 
2026-07-30 16:45:09.533771 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-07-30 16:45:10.076253 | controller | changed
2026-07-30 16:45:10.081679 | 
2026-07-30 16:45:10.081870 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-07-30 16:45:10.602450 | controller | changed
2026-07-30 16:45:10.606979 | 
2026-07-30 16:45:10.607043 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-07-30 16:45:10.632294 | controller | skipping: Conditional result was False
2026-07-30 16:45:10.639241 | 
2026-07-30 16:45:10.639352 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-07-30 16:45:11.018266 | controller -> localhost | changed
2026-07-30 16:45:11.029102 | 
2026-07-30 16:45:11.029193 | TASK [add-build-sshkey : Add back temp key]
2026-07-30 16:45:11.320179 | controller -> localhost | Identity added: /var/lib/zuul/builds/ed0ec3aeb1934f9caac86edc81a42613/work/ed0ec3aeb1934f9caac86edc81a42613_id_rsa (zuul-build-sshkey)
2026-07-30 16:45:11.320426 | controller -> localhost | ok: Runtime: 0:00:00.013194
2026-07-30 16:45:11.340090 | 
2026-07-30 16:45:11.340277 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-07-30 16:45:11.664169 | controller | ok
2026-07-30 16:45:11.669172 | 
2026-07-30 16:45:11.669266 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-07-30 16:45:11.693983 | controller | skipping: Conditional result was False
2026-07-30 16:45:11.707460 | 
2026-07-30 16:45:11.707524 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-07-30 16:45:12.078943 | controller | ok
2026-07-30 16:45:12.083293 | 
2026-07-30 16:45:12.083360 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-07-30 16:45:13.054950 | controller | Output suppressed because no_log was given
2026-07-30 16:45:13.063882 | 
2026-07-30 16:45:13.063950 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-07-30 16:45:13.283205 | controller | ok: "logs"
2026-07-30 16:45:13.283626 | controller | ok: All items complete
2026-07-30 16:45:13.283701 | 
2026-07-30 16:45:13.474068 | controller | ok: "artifacts"
2026-07-30 16:45:13.657865 | controller | ok: "docs"
2026-07-30 16:45:13.669577 | 
2026-07-30 16:45:13.669714 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-07-30 16:45:13.885449 | controller | changed: "logs"
2026-07-30 16:45:14.061714 | controller | changed: "artifacts"
2026-07-30 16:45:14.245126 | controller | changed: "docs"
2026-07-30 16:45:14.263263 | 
2026-07-30 16:45:14.263407 | PLAY RECAP
2026-07-30 16:45:14.263472 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-07-30 16:45:14.263514 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-30 16:45:14.263547 | 
2026-07-30 16:45:14.391259 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-30 16:45:14.393126 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-30 16:45:15.004360 | 
2026-07-30 16:45:15.004516 | PLAY [all]
2026-07-30 16:45:15.015132 | 
2026-07-30 16:45:15.015210 | TASK [setup-uv : Extract archive]
2026-07-30 16:45:17.457442 | controller | changed
2026-07-30 16:45:17.464411 | 
2026-07-30 16:45:17.464542 | TASK [setup-uv : Print version]
2026-07-30 16:45:17.881280 | controller | uv 0.8.13
2026-07-30 16:45:17.996463 | controller | ok: Runtime: 0:00:00.014965
2026-07-30 16:45:18.008391 | 
2026-07-30 16:45:18.008490 | TASK [Install Ansible collection siblings]
2026-07-30 16:45:18.387443 | controller | ok:
2026-07-30 16:45:18.387647 | controller | Skipping ansible.posix: no checked-out sibling
2026-07-30 16:45:18.387689 | controller | Skipping community.crypto: no checked-out sibling
2026-07-30 16:45:18.387721 | controller | Skipping community.general: no checked-out sibling
2026-07-30 16:45:18.387750 | controller | Skipping kubernetes.core: no checked-out sibling
2026-07-30 16:45:18.387779 | controller | Skipping vexxhost.containers: no checked-out sibling
2026-07-30 16:45:18.390279 | 
2026-07-30 16:45:18.390345 | PLAY RECAP
2026-07-30 16:45:18.390412 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-30 16:45:18.390446 | 
2026-07-30 16:45:18.487745 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-30 16:45:18.489110 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-07-30 16:45:19.070812 | 
2026-07-30 16:45:19.938837 | PLAY [all]
2026-07-30 16:45:19.950602 | 
2026-07-30 16:45:19.950696 | TASK [Update apt cache]
2026-07-30 16:45:24.756920 | controller | ok
2026-07-30 16:45:24.762934 | 
2026-07-30 16:45:24.763049 | TASK [Disable firewalld]
2026-07-30 16:45:24.788792 | controller | skipping: Conditional result was False
2026-07-30 16:45:24.794865 | 
2026-07-30 16:45:24.794949 | TASK [Install "jq" for log collection]
2026-07-30 16:45:27.144024 | controller | changed
2026-07-30 16:45:27.146319 | 
2026-07-30 16:45:27.146384 | PLAY RECAP
2026-07-30 16:45:27.146464 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-30 16:45:27.146497 | 
2026-07-30 16:45:27.310516 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-07-30 16:45:27.313245 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-07-30 16:45:27.990713 | 
2026-07-30 16:45:27.990887 | PLAY [all]
2026-07-30 16:45:28.003050 | 
2026-07-30 16:45:28.003133 | LOOP [Check inventory directories]
2026-07-30 16:45:28.401092 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-07-30 16:45:28.587676 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/helm/inventory"
2026-07-30 16:45:28.762780 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/inventory"
2026-07-30 16:45:28.778545 | 
2026-07-30 16:45:28.778699 | LOOP [Copy inventory file for Zuul]
2026-07-30 16:45:29.665367 | controller | changed:
2026-07-30 16:45:29.665625 | controller | {
2026-07-30 16:45:29.665665 | controller |   "ansible_loop_var": "item",
2026-07-30 16:45:29.665698 | controller |   "changed": false,
2026-07-30 16:45:29.665726 | controller |   "failed": false,
2026-07-30 16:45:29.665763 | controller |   "invocation": {
2026-07-30 16:45:29.665791 | controller |     "module_args": {
2026-07-30 16:45:29.665817 | controller |       "checksum_algorithm": "sha1",
2026-07-30 16:45:29.665842 | controller |       "follow": true,
2026-07-30 16:45:29.665866 | controller |       "get_attributes": true,
2026-07-30 16:45:29.665892 | controller |       "get_checksum": true,
2026-07-30 16:45:29.665918 | controller |       "get_mime": true,
2026-07-30 16:45:29.665942 | controller |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-07-30 16:45:29.665966 | controller |     }
2026-07-30 16:45:29.665990 | controller |   },
2026-07-30 16:45:29.666015 | controller |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-07-30 16:45:29.666039 | controller |   "stat": {
2026-07-30 16:45:29.666063 | controller |     "atime": 1785429912.9466012,
2026-07-30 16:45:29.666095 | controller |     "attr_flags": "e",
2026-07-30 16:45:29.666121 | controller |     "attributes": [
2026-07-30 16:45:29.666146 | controller |       "extents"
2026-07-30 16:45:29.666173 | controller |     ],
2026-07-30 16:45:29.666199 | controller |     "block_size": 4096,
2026-07-30 16:45:29.666224 | controller |     "blocks": 8,
2026-07-30 16:45:29.666249 | controller |     "charset": "unknown",
2026-07-30 16:45:29.666276 | controller |     "ctime": 1785429912.9666014,
2026-07-30 16:45:29.666302 | controller |     "dev": 65025,
2026-07-30 16:45:29.666328 | controller |     "device_type": 0,
2026-07-30 16:45:29.666353 | controller |     "executable": true,
2026-07-30 16:45:29.666379 | controller |     "exists": true,
2026-07-30 16:45:29.666404 | controller |     "gid": 1000,
2026-07-30 16:45:29.666430 | controller |     "gr_name": "zuul",
2026-07-30 16:45:29.666459 | controller |     "inode": 518935,
2026-07-30 16:45:29.666498 | controller |     "isblk": false,
2026-07-30 16:45:29.666536 | controller |     "ischr": false,
2026-07-30 16:45:29.666573 | controller |     "isdir": true,
2026-07-30 16:45:29.666608 | controller |     "isfifo": false,
2026-07-30 16:45:29.666636 | controller |     "isgid": false,
2026-07-30 16:45:29.666667 | controller |     "islnk": false,
2026-07-30 16:45:29.666703 | controller |     "isreg": false,
2026-07-30 16:45:29.666731 | controller |     "issock": false,
2026-07-30 16:45:29.666756 | controller |     "isuid": false,
2026-07-30 16:45:29.666781 | controller |     "mimetype": "unknown",
2026-07-30 16:45:29.666807 | controller |     "mode": "0755",
2026-07-30 16:45:29.666832 | controller |     "mtime": 1785429889.6721184,
2026-07-30 16:45:29.666857 | controller |     "nlink": 14,
2026-07-30 16:45:29.666883 | controller |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-07-30 16:45:29.666908 | controller |     "pw_name": "zuul",
2026-07-30 16:45:29.666934 | controller |     "readable": true,
2026-07-30 16:45:29.666960 | controller |     "rgrp": true,
2026-07-30 16:45:29.666986 | controller |     "roth": true,
2026-07-30 16:45:29.667011 | controller |     "rusr": true,
2026-07-30 16:45:29.667037 | controller |     "size": 4096,
2026-07-30 16:45:29.667062 | controller |     "uid": 1000,
2026-07-30 16:45:29.667087 | controller |     "version": "1866240176",
2026-07-30 16:45:29.667112 | controller |     "wgrp": false,
2026-07-30 16:45:29.667140 | controller |     "woth": false,
2026-07-30 16:45:29.667166 | controller |     "writeable": true,
2026-07-30 16:45:29.667192 | controller |     "wusr": true,
2026-07-30 16:45:29.667217 | controller |     "xgrp": true,
2026-07-30 16:45:29.667243 | controller |     "xoth": true,
2026-07-30 16:45:29.667268 | controller |     "xusr": true
2026-07-30 16:45:29.667293 | controller |   }
2026-07-30 16:45:29.667353 | controller | }
2026-07-30 16:45:29.667407 | controller | ok: All items complete
2026-07-30 16:45:29.667440 | 
2026-07-30 16:45:29.669570 | controller | skipping: Conditional result was False
2026-07-30 16:45:29.670461 | controller | skipping: Conditional result was False
2026-07-30 16:45:29.691327 | 
2026-07-30 16:45:29.691520 | LOOP [Switch "ansible_host" to private IP]
2026-07-30 16:45:30.066593 | controller | changed: 1 replacements made
2026-07-30 16:45:30.068105 | controller | skipping: Conditional result was False
2026-07-30 16:45:30.069894 | controller | skipping: Conditional result was False
2026-07-30 16:45:30.086180 | 
2026-07-30 16:45:30.086342 | TASK [Run Molecule scenario]
2026-07-30 16:45:30.516990 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-07-30 16:45:30.517080 | controller | Creating virtual environment at: .venv
2026-07-30 16:45:30.824957 | controller | Downloading cryptography (4.3MiB)
2026-07-30 16:45:30.825451 | controller | Downloading pygments (1.2MiB)
2026-07-30 16:45:30.825685 | controller | Downloading ansible-core (2.3MiB)
2026-07-30 16:45:30.988735 | controller |  Downloading cryptography
2026-07-30 16:45:31.034273 | controller |  Downloading pygments
2026-07-30 16:45:31.181856 | controller |  Downloading ansible-core
2026-07-30 16:45:31.229846 | controller | Installed 36 packages in 47ms
2026-07-30 16:45:31.930814 | controller | INFO     Collection 'vexxhost.kubernetes' detected.
2026-07-30 16:45:31.930879 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-07-30 16:45:32.810460 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-07-30 16:45:32.810504 | controller | INFO     default scenario not found, disabling shared state.
2026-07-30 16:45:32.810623 | controller | INFO     [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-07-30 16:45:32.810728 | controller | INFO     [helm > prerun] Performing prerun with role_name_check=0...
2026-07-30 16:46:12.065162 | controller | INFO     [helm > dependency] Executing
2026-07-30 16:46:12.065220 | controller | WARNING  [helm > dependency] Missing roles requirements file: requirements.yml
2026-07-30 16:46:12.065392 | controller | WARNING  [helm > dependency] Missing collections requirements file: collections.yml
2026-07-30 16:46:12.065512 | controller | WARNING  [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-07-30 16:46:12.070938 | controller | INFO     [helm > cleanup] Executing
2026-07-30 16:46:12.071219 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-30 16:46:12.076682 | controller | INFO     [helm > destroy] Executing
2026-07-30 16:46:12.076707 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-07-30 16:46:12.076766 | controller | INFO     [helm > destroy] Executed: Successful
2026-07-30 16:46:12.082375 | controller | INFO     [helm > syntax] Executing
2026-07-30 16:46:12.981337 | controller |
2026-07-30 16:46:12.981512 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml
2026-07-30 16:46:13.037824 | controller | INFO     [helm > syntax] Executed: Successful
2026-07-30 16:46:13.045849 | controller | INFO     [helm > create] Executing
2026-07-30 16:46:13.047853 | controller | WARNING  [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-30 16:46:13.055044 | controller | INFO     [helm > prepare] Executing
2026-07-30 16:46:13.957163 | controller |
2026-07-30 16:46:13.957275 | controller | PLAY [Prepare] *****************************************************************
2026-07-30 16:46:13.957460 | controller |
2026-07-30 16:46:13.957657 | controller | TASK [Gathering Facts] *********************************************************
2026-07-30 16:46:13.957950 | controller | Thursday 30 July 2026  16:46:13 +0000 (0:00:00.030)       0:00:00.030 *********
2026-07-30 16:46:15.120160 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information.
2026-07-30 16:46:15.126772 | controller | ok: [controller]
2026-07-30 16:46:15.126921 | controller |
2026-07-30 16:46:15.127113 | controller | TASK [Install Debian packages] *************************************************
2026-07-30 16:46:15.127315 | controller | Thursday 30 July 2026  16:46:15 +0000 (0:00:01.169)       0:00:01.199 *********
2026-07-30 16:46:16.520423 | controller | ok: [controller]
2026-07-30 16:46:16.520568 | controller |
2026-07-30 16:46:16.520762 | controller | PLAY RECAP *********************************************************************
2026-07-30 16:46:16.520975 | controller | controller                 : ok=2    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-30 16:46:16.521150 | controller |
2026-07-30 16:46:16.521320 | controller |
2026-07-30 16:46:16.521501 | controller | TASKS RECAP ********************************************************************
2026-07-30 16:46:16.521691 | controller | Thursday 30 July 2026  16:46:16 +0000 (0:00:01.394)       0:00:02.593 *********
2026-07-30 16:46:16.521921 | controller | ===============================================================================
2026-07-30 16:46:16.522105 | controller | Install Debian packages ------------------------------------------------- 1.39s
2026-07-30 16:46:16.522290 | controller | Gathering Facts --------------------------------------------------------- 1.17s
2026-07-30 16:46:16.648944 | controller | INFO     [helm > prepare] Executed: Successful
2026-07-30 16:46:16.659696 | controller | INFO     [helm > converge] Executing
2026-07-30 16:46:17.545007 | controller |
2026-07-30 16:46:17.545132 | controller | PLAY [Converge] ****************************************************************
2026-07-30 16:46:17.545283 | controller |
2026-07-30 16:46:17.545484 | controller | TASK [Gathering Facts] *********************************************************
2026-07-30 16:46:17.545676 | controller | Thursday 30 July 2026  16:46:17 +0000 (0:00:00.014)       0:00:00.014 *********
2026-07-30 16:46:18.605896 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information.
2026-07-30 16:46:18.611821 | controller | ok: [controller]
2026-07-30 16:46:18.611843 | controller |
2026-07-30 16:46:18.611852 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-07-30 16:46:18.611860 | controller | Thursday 30 July 2026  16:46:18 +0000 (0:00:01.066)       0:00:01.080 *********
2026-07-30 16:46:19.093229 | controller | ok: [controller]
2026-07-30 16:46:19.093551 | controller |
2026-07-30 16:46:19.093873 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-07-30 16:46:19.094135 | controller | Thursday 30 July 2026  16:46:19 +0000 (0:00:00.481)       0:00:01.562 *********
2026-07-30 16:46:20.153433 | controller | ok: [controller]
2026-07-30 16:46:20.153797 | controller |
2026-07-30 16:46:20.154095 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-07-30 16:46:20.154354 | controller | Thursday 30 July 2026  16:46:20 +0000 (0:00:01.060)       0:00:02.622 *********
2026-07-30 16:46:20.561520 | controller | changed: [controller]
2026-07-30 16:46:20.561900 | controller |
2026-07-30 16:46:20.562270 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-07-30 16:46:20.562583 | controller | Thursday 30 July 2026  16:46:20 +0000 (0:00:00.408)       0:00:03.030 *********
2026-07-30 16:46:20.608261 | controller | ok: [controller] => {
2026-07-30 16:46:20.608585 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-07-30 16:46:20.608868 | controller | }
2026-07-30 16:46:20.609137 | controller |
2026-07-30 16:46:20.609415 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-07-30 16:46:20.609690 | controller | Thursday 30 July 2026  16:46:20 +0000 (0:00:00.046)       0:00:03.077 *********
2026-07-30 16:46:21.467663 | 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:46:21.476355 | controller | changed: [controller]
2026-07-30 16:46:21.476366 | controller |
2026-07-30 16:46:21.476371 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-07-30 16:46:21.476374 | controller | Thursday 30 July 2026  16:46:21 +0000 (0:00:00.868)       0:00:03.945 *********
2026-07-30 16:46:23.516372 | controller | changed: [controller]
2026-07-30 16:46:23.516665 | controller |
2026-07-30 16:46:23.516933 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-07-30 16:46:23.517185 | controller | Thursday 30 July 2026  16:46:23 +0000 (0:00:02.039)       0:00:05.985 *********
2026-07-30 16:46:24.022170 | controller | ok: [controller]
2026-07-30 16:46:24.022486 | controller |
2026-07-30 16:46:24.022770 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-07-30 16:46:24.023096 | controller | Thursday 30 July 2026  16:46:24 +0000 (0:00:00.505)       0:00:06.490 *********
2026-07-30 16:46:24.423049 | controller | changed: [controller]
2026-07-30 16:46:24.423368 | controller |
2026-07-30 16:46:24.423644 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-07-30 16:46:24.423901 | controller | Thursday 30 July 2026  16:46:24 +0000 (0:00:00.395)       0:00:06.885 *********
2026-07-30 16:46:24.903372 | controller | ok: [controller]
2026-07-30 16:46:24.903677 | controller |
2026-07-30 16:46:24.903969 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-07-30 16:46:24.904237 | controller | Thursday 30 July 2026  16:46:24 +0000 (0:00:00.485)       0:00:07.371 *********
2026-07-30 16:46:25.212997 | controller | changed: [controller]
2026-07-30 16:46:25.213295 | controller |
2026-07-30 16:46:25.213585 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-07-30 16:46:25.213927 | controller | Thursday 30 July 2026  16:46:25 +0000 (0:00:00.310)       0:00:07.681 *********
2026-07-30 16:46:25.511404 | controller | ok: [controller]
2026-07-30 16:46:25.511709 | controller |
2026-07-30 16:46:25.511984 | controller | TASK [Install plugin] **********************************************************
2026-07-30 16:46:25.512248 | controller | Thursday 30 July 2026  16:46:25 +0000 (0:00:00.298)       0:00:07.979 *********
2026-07-30 16:46:25.564279 | controller | included: vexxhost.containers.download_artifact for controller
2026-07-30 16:46:25.564537 | controller |
2026-07-30 16:46:25.564802 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-07-30 16:46:25.565058 | controller | Thursday 30 July 2026  16:46:25 +0000 (0:00:00.053)       0:00:08.033 *********
2026-07-30 16:46:25.612287 | controller | ok: [controller] => {
2026-07-30 16:46:25.612583 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-07-30 16:46:25.612833 | controller | }
2026-07-30 16:46:25.613068 | controller |
2026-07-30 16:46:25.613371 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-07-30 16:46:25.613636 | controller | Thursday 30 July 2026  16:46:25 +0000 (0:00:00.047)       0:00:08.081 *********
2026-07-30 16:46:26.374129 | controller | changed: [controller]
2026-07-30 16:46:26.374450 | controller |
2026-07-30 16:46:26.374728 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-07-30 16:46:26.374996 | controller | Thursday 30 July 2026  16:46:26 +0000 (0:00:00.761)       0:00:08.842 *********
2026-07-30 16:46:28.412141 | controller | changed: [controller]
2026-07-30 16:46:28.412163 | controller |
2026-07-30 16:46:28.412167 | controller | PLAY RECAP *********************************************************************
2026-07-30 16:46:28.412172 | controller | controller                 : ok=16   changed=7    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-30 16:46:28.412175 | controller |
2026-07-30 16:46:28.412179 | controller |
2026-07-30 16:46:28.412182 | controller | TASKS RECAP ********************************************************************
2026-07-30 16:46:28.412185 | controller | Thursday 30 July 2026  16:46:28 +0000 (0:00:02.032)       0:00:10.875 *********
2026-07-30 16:46:28.412188 | controller | ===============================================================================
2026-07-30 16:46:28.412191 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.04s
2026-07-30 16:46:28.412195 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.03s
2026-07-30 16:46:28.412198 | controller | Gathering Facts --------------------------------------------------------- 1.07s
2026-07-30 16:46:28.412201 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.06s
2026-07-30 16:46:28.412204 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.87s
2026-07-30 16:46:28.412207 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.76s
2026-07-30 16:46:28.412210 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.51s
2026-07-30 16:46:28.412213 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.49s
2026-07-30 16:46:28.412216 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.48s
2026-07-30 16:46:28.412219 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.41s
2026-07-30 16:46:28.412229 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.40s
2026-07-30 16:46:28.412232 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.31s
2026-07-30 16:46:28.412235 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.30s
2026-07-30 16:46:28.412238 | controller | Install plugin ---------------------------------------------------------- 0.05s
2026-07-30 16:46:28.412241 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-07-30 16:46:28.412245 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-07-30 16:46:28.501134 | controller | INFO     [helm > converge] Executed: Successful
2026-07-30 16:46:28.506863 | controller | INFO     [helm > idempotence] Executing
2026-07-30 16:46:29.337356 | controller |
2026-07-30 16:46:29.337784 | controller | PLAY [Converge] ****************************************************************
2026-07-30 16:46:29.338034 | controller |
2026-07-30 16:46:29.338281 | controller | TASK [Gathering Facts] *********************************************************
2026-07-30 16:46:29.338528 | controller | Thursday 30 July 2026  16:46:29 +0000 (0:00:00.012)       0:00:00.012 *********
2026-07-30 16:46:30.404907 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information.
2026-07-30 16:46:30.409689 | controller | ok: [controller]
2026-07-30 16:46:30.410011 | controller |
2026-07-30 16:46:30.410294 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-07-30 16:46:30.410554 | controller | Thursday 30 July 2026  16:46:30 +0000 (0:00:01.072)       0:00:01.084 *********
2026-07-30 16:46:30.842059 | controller | ok: [controller]
2026-07-30 16:46:30.845358 | controller |
2026-07-30 16:46:30.845368 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-07-30 16:46:30.845372 | controller | Thursday 30 July 2026  16:46:30 +0000 (0:00:00.432)       0:00:01.517 *********
2026-07-30 16:46:31.853355 | controller | ok: [controller]
2026-07-30 16:46:31.853658 | controller |
2026-07-30 16:46:31.853984 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-07-30 16:46:31.854256 | controller | Thursday 30 July 2026  16:46:31 +0000 (0:00:01.010)       0:00:02.527 *********
2026-07-30 16:46:32.280338 | controller | ok: [controller]
2026-07-30 16:46:32.280611 | controller |
2026-07-30 16:46:32.280876 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-07-30 16:46:32.281128 | controller | Thursday 30 July 2026  16:46:32 +0000 (0:00:00.427)       0:00:02.955 *********
2026-07-30 16:46:32.323807 | controller | ok: [controller] => {
2026-07-30 16:46:32.324099 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-07-30 16:46:32.324349 | controller | }
2026-07-30 16:46:32.324584 | controller |
2026-07-30 16:46:32.324839 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-07-30 16:46:32.325087 | controller | Thursday 30 July 2026  16:46:32 +0000 (0:00:00.043)       0:00:02.998 *********
2026-07-30 16:46:32.843260 | controller | ok: [controller]
2026-07-30 16:46:32.843540 | controller |
2026-07-30 16:46:32.843829 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-07-30 16:46:32.844095 | controller | Thursday 30 July 2026  16:46:32 +0000 (0:00:00.519)       0:00:03.518 *********
2026-07-30 16:46:34.476122 | controller | ok: [controller]
2026-07-30 16:46:34.476411 | controller |
2026-07-30 16:46:34.476681 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-07-30 16:46:34.476942 | controller | Thursday 30 July 2026  16:46:34 +0000 (0:00:01.632)       0:00:05.150 *********
2026-07-30 16:46:34.977106 | controller | ok: [controller]
2026-07-30 16:46:34.977199 | controller |
2026-07-30 16:46:34.977333 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-07-30 16:46:34.977463 | controller | Thursday 30 July 2026  16:46:34 +0000 (0:00:00.501)       0:00:05.651 *********
2026-07-30 16:46:35.386236 | controller | ok: [controller]
2026-07-30 16:46:35.386276 | controller |
2026-07-30 16:46:35.386571 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-07-30 16:46:35.386601 | controller | Thursday 30 July 2026  16:46:35 +0000 (0:00:00.406)       0:00:06.058 *********
2026-07-30 16:46:35.822122 | controller | ok: [controller]
2026-07-30 16:46:35.822267 | controller |
2026-07-30 16:46:35.822399 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-07-30 16:46:35.822519 | controller | Thursday 30 July 2026  16:46:35 +0000 (0:00:00.435)       0:00:06.494 *********
2026-07-30 16:46:36.105798 | controller | ok: [controller]
2026-07-30 16:46:36.106341 | controller |
2026-07-30 16:46:36.106377 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-07-30 16:46:36.106384 | controller | Thursday 30 July 2026  16:46:36 +0000 (0:00:00.285)       0:00:06.780 *********
2026-07-30 16:46:36.409337 | controller | ok: [controller]
2026-07-30 16:46:36.409384 | controller |
2026-07-30 16:46:36.409472 | controller | TASK [Install plugin] **********************************************************
2026-07-30 16:46:36.409583 | controller | Thursday 30 July 2026  16:46:36 +0000 (0:00:00.303)       0:00:07.084 *********
2026-07-30 16:46:36.470159 | controller | included: vexxhost.containers.download_artifact for controller
2026-07-30 16:46:36.470231 | controller |
2026-07-30 16:46:36.470330 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-07-30 16:46:36.470422 | controller | Thursday 30 July 2026  16:46:36 +0000 (0:00:00.058)       0:00:07.142 *********
2026-07-30 16:46:36.511855 | controller | ok: [controller] => {
2026-07-30 16:46:36.511993 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-07-30 16:46:36.512101 | controller | }
2026-07-30 16:46:36.512489 | controller |
2026-07-30 16:46:36.512516 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-07-30 16:46:36.512522 | controller | Thursday 30 July 2026  16:46:36 +0000 (0:00:00.044)       0:00:07.187 *********
2026-07-30 16:46:36.921041 | controller | ok: [controller]
2026-07-30 16:46:36.921099 | controller |
2026-07-30 16:46:36.921182 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-07-30 16:46:36.921276 | controller | Thursday 30 July 2026  16:46:36 +0000 (0:00:00.408)       0:00:07.595 *********
2026-07-30 16:46:38.501766 | controller | ok: [controller]
2026-07-30 16:46:38.501806 | controller |
2026-07-30 16:46:38.501815 | controller | PLAY RECAP *********************************************************************
2026-07-30 16:46:38.501823 | controller | controller                 : ok=16   changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-30 16:46:38.501831 | controller |
2026-07-30 16:46:38.501839 | controller |
2026-07-30 16:46:38.501846 | controller | TASKS RECAP ********************************************************************
2026-07-30 16:46:38.501853 | controller | Thursday 30 July 2026  16:46:38 +0000 (0:00:01.577)       0:00:09.173 *********
2026-07-30 16:46:38.501860 | controller | ===============================================================================
2026-07-30 16:46:38.501867 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.63s
2026-07-30 16:46:38.501873 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.58s
2026-07-30 16:46:38.501880 | controller | Gathering Facts --------------------------------------------------------- 1.07s
2026-07-30 16:46:38.501887 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.01s
2026-07-30 16:46:38.501894 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.52s
2026-07-30 16:46:38.501900 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.50s
2026-07-30 16:46:38.501907 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.44s
2026-07-30 16:46:38.501914 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.43s
2026-07-30 16:46:38.501922 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.43s
2026-07-30 16:46:38.501929 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.41s
2026-07-30 16:46:38.501949 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.41s
2026-07-30 16:46:38.501956 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.30s
2026-07-30 16:46:38.501963 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.29s
2026-07-30 16:46:38.502073 | controller | Install plugin ---------------------------------------------------------- 0.06s
2026-07-30 16:46:38.502379 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s
2026-07-30 16:46:38.502616 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s
2026-07-30 16:46:38.589075 | controller | INFO     [helm > idempotence] Executed: Successful
2026-07-30 16:46:38.599234 | controller | INFO     [helm > side_effect] Executing
2026-07-30 16:46:38.599712 | controller | WARNING  [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-30 16:46:38.607200 | controller | INFO     [helm > verify] Executing
2026-07-30 16:46:39.401588 | controller |
2026-07-30 16:46:39.402078 | controller | PLAY [Verify] ******************************************************************
2026-07-30 16:46:39.402365 | controller |
2026-07-30 16:46:39.402664 | controller | TASK [Gathering Facts] *********************************************************
2026-07-30 16:46:39.402928 | controller | Thursday 30 July 2026  16:46:39 +0000 (0:00:00.009)       0:00:00.009 *********
2026-07-30 16:46:40.441912 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information.
2026-07-30 16:46:40.446981 | controller | ok: [controller]
2026-07-30 16:46:40.447096 | controller |
2026-07-30 16:46:40.447214 | controller | TASK [Get the current Helm version] ********************************************
2026-07-30 16:46:40.447329 | controller | Thursday 30 July 2026  16:46:40 +0000 (0:00:01.045)       0:00:01.055 *********
2026-07-30 16:46:40.901199 | controller | changed: [controller]
2026-07-30 16:46:40.901503 | controller |
2026-07-30 16:46:40.901832 | controller | TASK [Assert Helm version] *****************************************************
2026-07-30 16:46:40.902116 | controller | Thursday 30 July 2026  16:46:40 +0000 (0:00:00.453)       0:00:01.508 *********
2026-07-30 16:46:40.935126 | controller | ok: [controller] => {
2026-07-30 16:46:40.935417 | controller |     "changed": false,
2026-07-30 16:46:40.935671 | controller |     "msg": "All assertions passed"
2026-07-30 16:46:40.935930 | controller | }
2026-07-30 16:46:40.936195 | controller |
2026-07-30 16:46:40.936448 | controller | PLAY RECAP *********************************************************************
2026-07-30 16:46:40.936873 | controller | controller                 : ok=3    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-30 16:46:40.937138 | controller |
2026-07-30 16:46:40.937372 | controller |
2026-07-30 16:46:40.937624 | controller | TASKS RECAP ********************************************************************
2026-07-30 16:46:40.937979 | controller | Thursday 30 July 2026  16:46:40 +0000 (0:00:00.034)       0:00:01.543 *********
2026-07-30 16:46:40.938233 | controller | ===============================================================================
2026-07-30 16:46:40.938475 | controller | Gathering Facts --------------------------------------------------------- 1.05s
2026-07-30 16:46:40.938717 | controller | Get the current Helm version -------------------------------------------- 0.45s
2026-07-30 16:46:40.938955 | controller | Assert Helm version ----------------------------------------------------- 0.03s
2026-07-30 16:46:41.039930 | controller | INFO     [helm > verify] Executed: Successful
2026-07-30 16:46:41.050175 | controller | INFO     [helm > cleanup] Executing
2026-07-30 16:46:41.050664 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-30 16:46:41.058132 | controller | INFO     [helm > destroy] Executing
2026-07-30 16:46:41.058226 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-07-30 16:46:41.058311 | controller | INFO     [helm > destroy] Executed: Successful
2026-07-30 16:46:41.058623 | controller | WARNING  Molecule executed 1 scenario (1 missing files)
2026-07-30 16:46:41.176395 | controller | ok: Runtime: 0:01:10.698049
2026-07-30 16:46:41.179810 | 
2026-07-30 16:46:41.179859 | PLAY RECAP
2026-07-30 16:46:41.179903 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-30 16:46:41.179926 | 
2026-07-30 16:46:41.286535 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-07-30 16:46:41.290128 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-07-30 16:46:41.931713 | 
2026-07-30 16:46:41.932400 | PLAY [all]
2026-07-30 16:46:41.943768 | 
2026-07-30 16:46:41.943846 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-07-30 16:46:42.310504 | controller | changed
2026-07-30 16:46:42.316552 | 
2026-07-30 16:46:42.316685 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-07-30 16:46:42.704169 | controller | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args valuewarning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-30 16:46:42.704251 | controller |
2026-07-30 16:46:42.711555 | controller | environment: line 1: kubectl: command not found
2026-07-30 16:46:42.713478 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-30 16:46:42.715054 | controller | environment: line 1: kubectl: command not found
2026-07-30 16:46:42.717021 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-30 16:46:42.719728 | controller | environment: line 1: kubectl: command not found
2026-07-30 16:46:42.720986 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-30 16:46:42.721644 | controller | environment: line 1: kubectl: command not found
2026-07-30 16:46:42.722967 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-30 16:46:42.725728 | controller | environment: line 1: kubectl: command not found
2026-07-30 16:46:42.727516 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-30 16:46:42.849106 | controller | ok: Runtime: 0:00:00.037394
2026-07-30 16:46:42.855179 | 
2026-07-30 16:46:42.856913 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-07-30 16:46:43.085081 | controller | changed
2026-07-30 16:46:43.089532 | 
2026-07-30 16:46:43.089622 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-07-30 16:46:43.336552 | controller | environment: line 5: kubectl: command not found
2026-07-30 16:46:43.337683 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-30 16:46:43.337735 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-30 16:46:43.338185 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-30 16:46:43.623337 | controller | ok: Runtime: 0:00:00.013527
2026-07-30 16:46:43.630297 | 
2026-07-30 16:46:43.630370 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-07-30 16:46:44.266632 | controller | changed:
2026-07-30 16:46:44.266896 | controller | created directory /var/lib/zuul/builds/ed0ec3aeb1934f9caac86edc81a42613/work/logs/controller
2026-07-30 16:46:44.266931 | controller | cd+++++++++ objects/
2026-07-30 16:46:44.266954 | controller | cd+++++++++ objects/cluster/
2026-07-30 16:46:44.266978 | controller | cd+++++++++ objects/namespaced/
2026-07-30 16:46:44.275007 | 
2026-07-30 16:46:44.275089 | TASK [gather-pod-logs : creating directory for pod logs]
2026-07-30 16:46:44.514079 | controller | changed
2026-07-30 16:46:44.519645 | 
2026-07-30 16:46:44.519737 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-07-30 16:46:44.756069 | controller | changed
2026-07-30 16:46:44.762161 | 
2026-07-30 16:46:44.762261 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-07-30 16:46:44.999615 | controller | environment: line 3: kubectl: command not found
2026-07-30 16:46:45.293723 | controller | ok: Runtime: 0:00:00.012330
2026-07-30 16:46:45.298546 | 
2026-07-30 16:46:45.298618 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-07-30 16:46:45.808677 | controller | changed:
2026-07-30 16:46:45.809015 | controller | cd+++++++++ pod-logs/
2026-07-30 16:46:45.809083 | controller | cd+++++++++ pod-logs/failed-pods/
2026-07-30 16:46:45.817066 | 
2026-07-30 16:46:45.817173 | LOOP [helm-release-status : creating directory for helm release status]
2026-07-30 16:46:46.042681 | controller | changed: "values"
2026-07-30 16:46:46.251467 | controller | changed: "releases"
2026-07-30 16:46:46.271497 | 
2026-07-30 16:46:46.271697 | TASK [helm-release-status : Gather get release status for helm charts]
2026-07-30 16:46:46.514937 | controller | /bin/bash: line 3: kubectl: command not found
2026-07-30 16:46:46.800369 | controller | ok: Runtime: 0:00:00.010666
2026-07-30 16:46:46.804385 | 
2026-07-30 16:46:46.804454 | TASK [helm-release-status : Downloads logs to executor]
2026-07-30 16:46:47.342000 | controller | changed:
2026-07-30 16:46:47.342287 | controller | cd+++++++++ helm/
2026-07-30 16:46:47.342353 | controller | cd+++++++++ helm/releases/
2026-07-30 16:46:47.342409 | controller | cd+++++++++ helm/values/
2026-07-30 16:46:47.348105 | 
2026-07-30 16:46:47.348158 | PLAY RECAP
2026-07-30 16:46:47.348208 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-30 16:46:47.348237 | 
2026-07-30 16:46:47.487192 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-07-30 16:46:47.488632 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-30 16:46:48.119814 | 
2026-07-30 16:46:48.119955 | PLAY [all]
2026-07-30 16:46:48.130838 | 
2026-07-30 16:46:48.130923 | TASK [fetch-output : Set log path for multiple nodes]
2026-07-30 16:46:48.176623 | controller | skipping: Conditional result was False
2026-07-30 16:46:48.184798 | 
2026-07-30 16:46:48.184885 | TASK [fetch-output : Set log path for single node]
2026-07-30 16:46:48.226737 | controller | ok
2026-07-30 16:46:48.291451 | 
2026-07-30 16:46:48.291593 | LOOP [fetch-output : Ensure local output dirs]
2026-07-30 16:46:48.715954 | controller -> localhost | ok: "/var/lib/zuul/builds/ed0ec3aeb1934f9caac86edc81a42613/work/logs"
2026-07-30 16:46:49.035387 | controller -> localhost | changed: "/var/lib/zuul/builds/ed0ec3aeb1934f9caac86edc81a42613/work/artifacts"
2026-07-30 16:46:49.246766 | controller -> localhost | changed: "/var/lib/zuul/builds/ed0ec3aeb1934f9caac86edc81a42613/work/docs"
2026-07-30 16:46:49.259508 | 
2026-07-30 16:46:49.259681 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-07-30 16:46:49.907274 | controller | changed: .d..t...... ./
2026-07-30 16:46:49.907561 | controller | changed: All items complete
2026-07-30 16:46:49.907613 | 
2026-07-30 16:46:50.345092 | controller | changed: .d..t...... ./
2026-07-30 16:46:50.786952 | controller | changed: .d..t...... ./
2026-07-30 16:46:50.799445 | 
2026-07-30 16:46:50.799522 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-07-30 16:46:51.202642 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008124
2026-07-30 16:46:51.470693 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009638
2026-07-30 16:46:51.491376 | 
2026-07-30 16:46:51.491478 | PLAY [all]
2026-07-30 16:46:51.497292 | 
2026-07-30 16:46:51.497358 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-07-30 16:46:51.996229 | controller | changed
2026-07-30 16:46:52.001166 | 
2026-07-30 16:46:52.001217 | PLAY RECAP
2026-07-30 16:46:52.001260 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-30 16:46:52.001281 | 
2026-07-30 16:46:52.142593 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-30 16:46:52.145497 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-07-30 16:46:52.796586 | 
2026-07-30 16:46:52.796739 | PLAY [localhost]
2026-07-30 16:46:52.806546 | 
2026-07-30 16:46:52.806814 | TASK [Generate Zuul manifest]
2026-07-30 16:46:52.828018 | localhost | ok
2026-07-30 16:46:52.845073 | 
2026-07-30 16:46:52.845204 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-07-30 16:46:53.638417 | localhost | changed
2026-07-30 16:46:53.653747 | 
2026-07-30 16:46:53.653855 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-07-30 16:46:53.697218 | localhost | ok
2026-07-30 16:46:53.707229 | 
2026-07-30 16:46:53.707295 | TASK [Upload logs]
2026-07-30 16:46:53.730461 | localhost | ok
2026-07-30 16:46:53.797393 | 
2026-07-30 16:46:53.797568 | TASK [Set zuul-log-path fact]
2026-07-30 16:46:53.819402 | localhost | ok
2026-07-30 16:46:53.834893 | 
2026-07-30 16:46:53.834976 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-30 16:46:53.867449 | localhost | ok
2026-07-30 16:46:53.876993 | 
2026-07-30 16:46:53.877076 | TASK [upload-logs : Create log directories]
2026-07-30 16:46:54.287915 | localhost | changed
2026-07-30 16:46:54.294202 | 
2026-07-30 16:46:54.294280 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-07-30 16:46:54.937304 | localhost -> localhost | ok: Runtime: 0:00:00.006475
2026-07-30 16:46:54.943847 | 
2026-07-30 16:46:54.943912 | TASK [upload-logs : Upload logs to log server]
2026-07-30 16:46:55.424066 | localhost | Output suppressed because no_log was given
2026-07-30 16:46:55.429290 | 
2026-07-30 16:46:55.429372 | LOOP [upload-logs : Compress console log and json output]
2026-07-30 16:46:55.475631 | localhost | skipping: Conditional result was False
2026-07-30 16:46:55.520052 | localhost | skipping: Conditional result was False
2026-07-30 16:46:55.536601 | 
2026-07-30 16:46:55.536811 | LOOP [upload-logs : Upload compressed console log and json output]
2026-07-30 16:46:55.581153 | localhost | skipping: Conditional result was False
2026-07-30 16:46:55.581614 | 
2026-07-30 16:46:55.584653 | localhost | skipping: Conditional result was False
2026-07-30 16:46:55.601607 | 
2026-07-30 16:46:55.601761 | LOOP [upload-logs : Upload console log and json output]