2026-08-13 00:39:39.270465 | Job console starting
2026-08-13 00:39:39.280469 | Updating git repos
2026-08-13 00:39:39.316783 | Cloning repos into workspace
2026-08-13 00:39:39.356790 | Restoring repo states
2026-08-13 00:39:39.378735 | Merging changes
2026-08-13 00:39:40.175154 | Checking out repos
2026-08-13 00:39:40.219948 | Preparing playbooks
2026-08-13 00:39:42.282674 | Running Ansible setup
2026-08-13 00:39:46.854635 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-13 00:39:47.457589 | 
2026-08-13 00:39:47.457723 | PLAY [localhost]
2026-08-13 00:39:47.465111 | 
2026-08-13 00:39:47.465206 | TASK [Gathering Facts]
2026-08-13 00:39:48.323190 | localhost | ok
2026-08-13 00:39:48.334126 | 
2026-08-13 00:39:48.334259 | TASK [Setup log path fact]
2026-08-13 00:39:48.353448 | localhost | ok
2026-08-13 00:39:48.366520 | 
2026-08-13 00:39:48.366621 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-13 00:39:48.399042 | localhost | ok
2026-08-13 00:39:48.406999 | 
2026-08-13 00:39:48.407071 | TASK [emit-job-header : Print job information]
2026-08-13 00:39:48.450076 | # Job Information
2026-08-13 00:39:48.450337 | Ansible Version: 2.16.18
2026-08-13 00:39:48.450371 | Job: ansible-collection-kubernetes-molecule-helm
2026-08-13 00:39:48.450395 | Pipeline: check
2026-08-13 00:39:48.450415 | Executor: 2d72f0692154
2026-08-13 00:39:48.450435 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/293
2026-08-13 00:39:48.450458 | Event ID: a45de990-96ae-11f1-8f62-7ff15b8208de
2026-08-13 00:39:48.453555 | 
2026-08-13 00:39:48.453632 | LOOP [emit-job-header : Print node information]
2026-08-13 00:39:48.548549 | localhost | ok:
2026-08-13 00:39:48.548792 | localhost | # Node Information
2026-08-13 00:39:48.548836 | localhost | Inventory Hostname: controller
2026-08-13 00:39:48.548867 | localhost | Hostname: np0000189107
2026-08-13 00:39:48.548895 | localhost | Username: zuul
2026-08-13 00:39:48.548926 | localhost | Distro: Debian 13.6
2026-08-13 00:39:48.548954 | localhost | Provider: yul1
2026-08-13 00:39:48.549013 | localhost | Region: ca-ymq-1
2026-08-13 00:39:48.549040 | localhost | Label: debian-trixie
2026-08-13 00:39:48.549066 | localhost | Product Name: OpenStack Nova
2026-08-13 00:39:48.549092 | localhost | Interface IP: 199.204.45.162
2026-08-13 00:39:48.560735 | 
2026-08-13 00:39:48.560813 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-13 00:39:48.966396 | localhost -> localhost | changed
2026-08-13 00:39:48.976095 | 
2026-08-13 00:39:48.976195 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-13 00:39:49.828144 | localhost -> localhost | changed
2026-08-13 00:39:49.847218 | 
2026-08-13 00:39:49.847300 | PLAY [all]
2026-08-13 00:39:49.854567 | 
2026-08-13 00:39:49.854641 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-13 00:39:50.217301 | controller -> localhost | ok
2026-08-13 00:39:50.227026 | 
2026-08-13 00:39:50.227107 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-13 00:39:50.259465 | controller | ok
2026-08-13 00:39:50.274665 | controller | included: /var/lib/zuul/builds/9f769123c73444e3ae0cc3639da664d9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-13 00:39:50.279420 | 
2026-08-13 00:39:50.279479 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-13 00:39:51.166250 | controller -> localhost | Generating public/private rsa key pair.
2026-08-13 00:39:51.166453 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9f769123c73444e3ae0cc3639da664d9/work/9f769123c73444e3ae0cc3639da664d9_id_rsa
2026-08-13 00:39:51.166497 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9f769123c73444e3ae0cc3639da664d9/work/9f769123c73444e3ae0cc3639da664d9_id_rsa.pub
2026-08-13 00:39:51.166530 | controller -> localhost | The key fingerprint is:
2026-08-13 00:39:51.166560 | controller -> localhost | SHA256:oBRipTyHhJOrvhhI3FBXHY+FMbgWepMsIOlhAjVA28I zuul-build-sshkey
2026-08-13 00:39:51.166602 | controller -> localhost | The key's randomart image is:
2026-08-13 00:39:51.166633 | controller -> localhost | +---[RSA 3072]----+
2026-08-13 00:39:51.166669 | controller -> localhost | |=*Boo...o++.     |
2026-08-13 00:39:51.166701 | controller -> localhost | |*O+*.. o o=      |
2026-08-13 00:39:51.166730 | controller -> localhost | |+EB.+ + +. .     |
2026-08-13 00:39:51.166758 | controller -> localhost | |o.++ + O         |
2026-08-13 00:39:51.166787 | controller -> localhost | |.o .. + S        |
2026-08-13 00:39:51.166814 | controller -> localhost | |+                |
2026-08-13 00:39:51.166841 | controller -> localhost | |+                |
2026-08-13 00:39:51.166868 | controller -> localhost | |.o               |
2026-08-13 00:39:51.166900 | controller -> localhost | |...              |
2026-08-13 00:39:51.166928 | controller -> localhost | +----[SHA256]-----+
2026-08-13 00:39:51.166991 | controller -> localhost | ok: Runtime: 0:00:00.478442
2026-08-13 00:39:51.173078 | 
2026-08-13 00:39:51.173253 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-13 00:39:51.206202 | controller | ok
2026-08-13 00:39:51.215227 | controller | included: /var/lib/zuul/builds/9f769123c73444e3ae0cc3639da664d9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-13 00:39:51.223098 | 
2026-08-13 00:39:51.223165 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-13 00:39:51.247417 | controller | skipping: Conditional result was False
2026-08-13 00:39:51.255867 | 
2026-08-13 00:39:51.255967 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-13 00:39:51.802153 | controller | changed
2026-08-13 00:39:51.806067 | 
2026-08-13 00:39:51.806134 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-13 00:39:52.019436 | controller | ok
2026-08-13 00:39:52.023702 | 
2026-08-13 00:39:52.023773 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-13 00:39:52.565875 | controller | changed
2026-08-13 00:39:52.571541 | 
2026-08-13 00:39:52.571614 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-13 00:39:53.117848 | controller | changed
2026-08-13 00:39:53.122056 | 
2026-08-13 00:39:53.122125 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-13 00:39:53.148384 | controller | skipping: Conditional result was False
2026-08-13 00:39:53.157676 | 
2026-08-13 00:39:53.157793 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-13 00:39:53.568317 | controller -> localhost | changed
2026-08-13 00:39:53.583867 | 
2026-08-13 00:39:53.583946 | TASK [add-build-sshkey : Add back temp key]
2026-08-13 00:39:53.888691 | controller -> localhost | Identity added: /var/lib/zuul/builds/9f769123c73444e3ae0cc3639da664d9/work/9f769123c73444e3ae0cc3639da664d9_id_rsa (zuul-build-sshkey)
2026-08-13 00:39:53.888951 | controller -> localhost | ok: Runtime: 0:00:00.012259
2026-08-13 00:39:53.894123 | 
2026-08-13 00:39:53.894189 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-13 00:39:54.249553 | controller | ok
2026-08-13 00:39:54.254740 | 
2026-08-13 00:39:54.254808 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-13 00:39:54.280402 | controller | skipping: Conditional result was False
2026-08-13 00:39:54.294366 | 
2026-08-13 00:39:54.294468 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-13 00:39:54.663284 | controller | ok
2026-08-13 00:39:54.749003 | 
2026-08-13 00:39:54.749159 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-13 00:39:55.708575 | controller | Output suppressed because no_log was given
2026-08-13 00:39:55.717976 | 
2026-08-13 00:39:55.718067 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-13 00:39:55.932386 | controller | ok: "logs"
2026-08-13 00:39:55.932684 | controller | ok: All items complete
2026-08-13 00:39:55.932713 | 
2026-08-13 00:39:56.130398 | controller | ok: "artifacts"
2026-08-13 00:39:56.318069 | controller | ok: "docs"
2026-08-13 00:39:56.337503 | 
2026-08-13 00:39:56.337659 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-13 00:39:56.586520 | controller | changed: "logs"
2026-08-13 00:39:56.788583 | controller | changed: "artifacts"
2026-08-13 00:39:56.981997 | controller | changed: "docs"
2026-08-13 00:39:56.990546 | 
2026-08-13 00:39:56.990685 | PLAY RECAP
2026-08-13 00:39:56.990738 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-13 00:39:56.990768 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-13 00:39:56.990793 | 
2026-08-13 00:39:57.140369 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-13 00:39:57.143320 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-13 00:39:57.792188 | 
2026-08-13 00:39:57.792361 | PLAY [all]
2026-08-13 00:39:57.802671 | 
2026-08-13 00:39:57.802786 | TASK [setup-uv : Extract archive]
2026-08-13 00:40:00.246506 | controller | changed
2026-08-13 00:40:00.252343 | 
2026-08-13 00:40:00.252426 | TASK [setup-uv : Print version]
2026-08-13 00:40:00.637651 | controller | uv 0.8.13
2026-08-13 00:40:00.785136 | controller | ok: Runtime: 0:00:00.014172
2026-08-13 00:40:00.794952 | 
2026-08-13 00:40:00.795049 | TASK [Install Ansible collection siblings]
2026-08-13 00:40:01.215618 | controller | ok:
2026-08-13 00:40:01.215913 | controller | Skipping ansible.posix: no checked-out sibling
2026-08-13 00:40:01.215977 | controller | Skipping community.crypto: no checked-out sibling
2026-08-13 00:40:01.216027 | controller | Skipping community.general: no checked-out sibling
2026-08-13 00:40:01.216073 | controller | Skipping kubernetes.core: no checked-out sibling
2026-08-13 00:40:01.216119 | controller | Skipping vexxhost.containers: no checked-out sibling
2026-08-13 00:40:01.218970 | 
2026-08-13 00:40:01.219050 | PLAY RECAP
2026-08-13 00:40:01.219115 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-13 00:40:01.219149 | 
2026-08-13 00:40:01.352312 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-13 00:40:01.355092 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-08-13 00:40:01.955231 | 
2026-08-13 00:40:01.955371 | PLAY [all]
2026-08-13 00:40:01.965990 | 
2026-08-13 00:40:01.966072 | TASK [Update apt cache]
2026-08-13 00:40:06.526762 | controller | ok
2026-08-13 00:40:06.532327 | 
2026-08-13 00:40:06.532425 | TASK [Disable firewalld]
2026-08-13 00:40:06.558835 | controller | skipping: Conditional result was False
2026-08-13 00:40:06.567221 | 
2026-08-13 00:40:06.567332 | TASK [Install "jq" for log collection]
2026-08-13 00:40:08.666386 | controller | changed
2026-08-13 00:40:08.668862 | 
2026-08-13 00:40:08.668930 | PLAY RECAP
2026-08-13 00:40:08.669017 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-13 00:40:08.669061 | 
2026-08-13 00:40:08.845347 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-08-13 00:40:08.846855 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-13 00:40:09.423726 | 
2026-08-13 00:40:09.423853 | PLAY [all]
2026-08-13 00:40:09.433602 | 
2026-08-13 00:40:09.433674 | LOOP [Check inventory directories]
2026-08-13 00:40:09.808437 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-08-13 00:40:09.984753 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/helm/inventory"
2026-08-13 00:40:10.162348 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/inventory"
2026-08-13 00:40:10.183622 | 
2026-08-13 00:40:10.183886 | LOOP [Copy inventory file for Zuul]
2026-08-13 00:40:11.045861 | controller | changed:
2026-08-13 00:40:11.046038 | controller | {
2026-08-13 00:40:11.046065 | controller |   "ansible_loop_var": "item",
2026-08-13 00:40:11.046088 | controller |   "changed": false,
2026-08-13 00:40:11.046109 | controller |   "failed": false,
2026-08-13 00:40:11.046131 | controller |   "invocation": {
2026-08-13 00:40:11.046151 | controller |     "module_args": {
2026-08-13 00:40:11.046170 | controller |       "checksum_algorithm": "sha1",
2026-08-13 00:40:11.046188 | controller |       "follow": true,
2026-08-13 00:40:11.046206 | controller |       "get_attributes": true,
2026-08-13 00:40:11.046225 | controller |       "get_checksum": true,
2026-08-13 00:40:11.046243 | controller |       "get_mime": true,
2026-08-13 00:40:11.046261 | controller |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-08-13 00:40:11.046279 | controller |     }
2026-08-13 00:40:11.046296 | controller |   },
2026-08-13 00:40:11.046313 | controller |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-08-13 00:40:11.046331 | controller |   "stat": {
2026-08-13 00:40:11.046348 | controller |     "atime": 1786581595.5817487,
2026-08-13 00:40:11.046366 | controller |     "attr_flags": "e",
2026-08-13 00:40:11.046383 | controller |     "attributes": [
2026-08-13 00:40:11.046400 | controller |       "extents"
2026-08-13 00:40:11.046418 | controller |     ],
2026-08-13 00:40:11.046507 | controller |     "block_size": 4096,
2026-08-13 00:40:11.046529 | controller |     "blocks": 8,
2026-08-13 00:40:11.046547 | controller |     "charset": "unknown",
2026-08-13 00:40:11.046566 | controller |     "ctime": 1786581595.6017492,
2026-08-13 00:40:11.046584 | controller |     "dev": 65025,
2026-08-13 00:40:11.046602 | controller |     "device_type": 0,
2026-08-13 00:40:11.046619 | controller |     "executable": true,
2026-08-13 00:40:11.046636 | controller |     "exists": true,
2026-08-13 00:40:11.046653 | controller |     "gid": 1000,
2026-08-13 00:40:11.046669 | controller |     "gr_name": "zuul",
2026-08-13 00:40:11.046686 | controller |     "inode": 519191,
2026-08-13 00:40:11.046703 | controller |     "isblk": false,
2026-08-13 00:40:11.046720 | controller |     "ischr": false,
2026-08-13 00:40:11.046737 | controller |     "isdir": true,
2026-08-13 00:40:11.046754 | controller |     "isfifo": false,
2026-08-13 00:40:11.046771 | controller |     "isgid": false,
2026-08-13 00:40:11.046788 | controller |     "islnk": false,
2026-08-13 00:40:11.046804 | controller |     "isreg": false,
2026-08-13 00:40:11.046821 | controller |     "issock": false,
2026-08-13 00:40:11.046838 | controller |     "isuid": false,
2026-08-13 00:40:11.046855 | controller |     "mimetype": "unknown",
2026-08-13 00:40:11.046871 | controller |     "mode": "0755",
2026-08-13 00:40:11.046888 | controller |     "mtime": 1786581579.4830737,
2026-08-13 00:40:11.046905 | controller |     "nlink": 14,
2026-08-13 00:40:11.046922 | controller |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-08-13 00:40:11.046940 | controller |     "pw_name": "zuul",
2026-08-13 00:40:11.046957 | controller |     "readable": true,
2026-08-13 00:40:11.046975 | controller |     "rgrp": true,
2026-08-13 00:40:11.046992 | controller |     "roth": true,
2026-08-13 00:40:11.047010 | controller |     "rusr": true,
2026-08-13 00:40:11.047027 | controller |     "size": 4096,
2026-08-13 00:40:11.047045 | controller |     "uid": 1000,
2026-08-13 00:40:11.047062 | controller |     "version": "2213039680",
2026-08-13 00:40:11.047079 | controller |     "wgrp": false,
2026-08-13 00:40:11.047098 | controller |     "woth": false,
2026-08-13 00:40:11.047133 | controller |     "writeable": true,
2026-08-13 00:40:11.047150 | controller |     "wusr": true,
2026-08-13 00:40:11.047168 | controller |     "xgrp": true,
2026-08-13 00:40:11.047185 | controller |     "xoth": true,
2026-08-13 00:40:11.047201 | controller |     "xusr": true
2026-08-13 00:40:11.047218 | controller |   }
2026-08-13 00:40:11.047248 | controller | }
2026-08-13 00:40:11.047283 | controller | ok: All items complete
2026-08-13 00:40:11.047305 | 
2026-08-13 00:40:11.048515 | controller | skipping: Conditional result was False
2026-08-13 00:40:11.051533 | controller | skipping: Conditional result was False
2026-08-13 00:40:11.063103 | 
2026-08-13 00:40:11.063286 | LOOP [Switch "ansible_host" to private IP]
2026-08-13 00:40:11.442862 | controller | changed: 1 replacements made
2026-08-13 00:40:11.444627 | controller | skipping: Conditional result was False
2026-08-13 00:40:11.448710 | controller | skipping: Conditional result was False
2026-08-13 00:40:11.465439 | 
2026-08-13 00:40:11.465576 | TASK [Run Molecule scenario]
2026-08-13 00:40:11.905819 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-08-13 00:40:11.905953 | controller | Creating virtual environment at: .venv
2026-08-13 00:40:12.206987 | controller | Downloading ansible-core (2.3MiB)
2026-08-13 00:40:12.207418 | controller | Downloading pygments (1.2MiB)
2026-08-13 00:40:12.218008 | controller | Downloading cryptography (4.3MiB)
2026-08-13 00:40:12.391656 | controller |  Downloading cryptography
2026-08-13 00:40:12.449053 | controller |  Downloading pygments
2026-08-13 00:40:12.616284 | controller |  Downloading ansible-core
2026-08-13 00:40:12.665373 | controller | Installed 36 packages in 48ms
2026-08-13 00:40:13.342838 | controller | INFO     Collection 'vexxhost.kubernetes' detected.
2026-08-13 00:40:13.342906 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-08-13 00:40:14.229522 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-08-13 00:40:14.229585 | controller | INFO     default scenario not found, disabling shared state.
2026-08-13 00:40:14.229610 | controller | INFO     [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-08-13 00:40:14.229687 | controller | INFO     [helm > prerun] Performing prerun with role_name_check=0...
2026-08-13 00:40:39.547907 | controller | INFO     [helm > dependency] Executing
2026-08-13 00:40:39.548027 | controller | WARNING  [helm > dependency] Missing roles requirements file: requirements.yml
2026-08-13 00:40:39.548214 | controller | WARNING  [helm > dependency] Missing collections requirements file: collections.yml
2026-08-13 00:40:39.548639 | controller | WARNING  [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-08-13 00:40:39.553688 | controller | INFO     [helm > cleanup] Executing
2026-08-13 00:40:39.554028 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-13 00:40:39.559343 | controller | INFO     [helm > destroy] Executing
2026-08-13 00:40:39.559374 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-08-13 00:40:39.559462 | controller | INFO     [helm > destroy] Executed: Successful
2026-08-13 00:40:39.564720 | controller | INFO     [helm > syntax] Executing
2026-08-13 00:40:40.457601 | controller |
2026-08-13 00:40:40.457932 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml
2026-08-13 00:40:40.516226 | controller | INFO     [helm > syntax] Executed: Successful
2026-08-13 00:40:40.527637 | controller | INFO     [helm > create] Executing
2026-08-13 00:40:40.529636 | controller | WARNING  [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-13 00:40:40.537133 | controller | INFO     [helm > prepare] Executing
2026-08-13 00:40:41.390205 | controller |
2026-08-13 00:40:41.390384 | controller | PLAY [Prepare] *****************************************************************
2026-08-13 00:40:41.390569 | controller |
2026-08-13 00:40:41.390773 | controller | TASK [Gathering Facts] *********************************************************
2026-08-13 00:40:41.390974 | controller | Thursday 13 August 2026  00:40:41 +0000 (0:00:00.031)       0:00:00.031 *******
2026-08-13 00:40:42.644402 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information.
2026-08-13 00:40:42.650220 | controller | ok: [controller]
2026-08-13 00:40:42.650406 | controller |
2026-08-13 00:40:42.650616 | controller | TASK [Install Debian packages] *************************************************
2026-08-13 00:40:42.650835 | controller | Thursday 13 August 2026  00:40:42 +0000 (0:00:01.260)       0:00:01.291 *******
2026-08-13 00:40:44.086458 | controller | ok: [controller]
2026-08-13 00:40:44.086494 | controller |
2026-08-13 00:40:44.086747 | controller | PLAY RECAP *********************************************************************
2026-08-13 00:40:44.087148 | controller | controller                 : ok=2    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-08-13 00:40:44.087369 | controller |
2026-08-13 00:40:44.087542 | controller |
2026-08-13 00:40:44.087739 | controller | TASKS RECAP ********************************************************************
2026-08-13 00:40:44.088009 | controller | Thursday 13 August 2026  00:40:44 +0000 (0:00:01.436)       0:00:02.727 *******
2026-08-13 00:40:44.088247 | controller | ===============================================================================
2026-08-13 00:40:44.088442 | controller | Install Debian packages ------------------------------------------------- 1.44s
2026-08-13 00:40:44.088817 | controller | Gathering Facts --------------------------------------------------------- 1.26s
2026-08-13 00:40:44.183790 | controller | INFO     [helm > prepare] Executed: Successful
2026-08-13 00:40:44.194138 | controller | INFO     [helm > converge] Executing
2026-08-13 00:40:45.086237 | controller |
2026-08-13 00:40:45.086365 | controller | PLAY [Converge] ****************************************************************
2026-08-13 00:40:45.086621 | controller |
2026-08-13 00:40:45.086853 | controller | TASK [Gathering Facts] *********************************************************
2026-08-13 00:40:45.087074 | controller | Thursday 13 August 2026  00:40:45 +0000 (0:00:00.013)       0:00:00.013 *******
2026-08-13 00:40:46.163167 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information.
2026-08-13 00:40:46.168835 | controller | ok: [controller]
2026-08-13 00:40:46.169042 | controller |
2026-08-13 00:40:46.169271 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-13 00:40:46.169492 | controller | Thursday 13 August 2026  00:40:46 +0000 (0:00:01.082)       0:00:01.096 *******
2026-08-13 00:40:46.697988 | controller | ok: [controller]
2026-08-13 00:40:46.698079 | controller |
2026-08-13 00:40:46.698177 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-13 00:40:46.698373 | controller | Thursday 13 August 2026  00:40:46 +0000 (0:00:00.528)       0:00:01.624 *******
2026-08-13 00:40:47.681536 | controller | ok: [controller]
2026-08-13 00:40:47.681597 | controller |
2026-08-13 00:40:47.681776 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-08-13 00:40:47.681894 | controller | Thursday 13 August 2026  00:40:47 +0000 (0:00:00.983)       0:00:02.608 *******
2026-08-13 00:40:48.138566 | controller | changed: [controller]
2026-08-13 00:40:48.138715 | controller |
2026-08-13 00:40:48.138929 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-13 00:40:48.139130 | controller | Thursday 13 August 2026  00:40:48 +0000 (0:00:00.456)       0:00:03.065 *******
2026-08-13 00:40:48.195540 | controller | ok: [controller] => {
2026-08-13 00:40:48.195698 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-08-13 00:40:48.195929 | controller | }
2026-08-13 00:40:48.196104 | controller |
2026-08-13 00:40:48.196322 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-13 00:40:48.196532 | controller | Thursday 13 August 2026  00:40:48 +0000 (0:00:00.056)       0:00:03.122 *******
2026-08-13 00:41:00.534668 | controller | FAILED - RETRYING: [controller]: Download item (4 retries left).
2026-08-13 00:41:00.534791 | controller | changed: [controller]
2026-08-13 00:41:00.534990 | controller |
2026-08-13 00:41:00.535264 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-13 00:41:00.535461 | controller | Thursday 13 August 2026  00:41:00 +0000 (0:00:12.339)       0:00:15.461 *******
2026-08-13 00:41:02.582350 | controller | changed: [controller]
2026-08-13 00:41:02.582492 | controller |
2026-08-13 00:41:02.582776 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-08-13 00:41:02.583038 | controller | Thursday 13 August 2026  00:41:02 +0000 (0:00:02.047)       0:00:17.509 *******
2026-08-13 00:41:03.088736 | controller | ok: [controller]
2026-08-13 00:41:03.088876 | controller |
2026-08-13 00:41:03.089161 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-08-13 00:41:03.089365 | controller | Thursday 13 August 2026  00:41:03 +0000 (0:00:00.506)       0:00:18.015 *******
2026-08-13 00:41:03.491949 | controller | changed: [controller]
2026-08-13 00:41:03.492081 | controller |
2026-08-13 00:41:03.492290 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-08-13 00:41:03.492488 | controller | Thursday 13 August 2026  00:41:03 +0000 (0:00:00.403)       0:00:18.419 *******
2026-08-13 00:41:03.955382 | controller | ok: [controller]
2026-08-13 00:41:03.955553 | controller |
2026-08-13 00:41:03.955813 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-08-13 00:41:03.956055 | controller | Thursday 13 August 2026  00:41:03 +0000 (0:00:00.463)       0:00:18.882 *******
2026-08-13 00:41:04.257932 | controller | changed: [controller]
2026-08-13 00:41:04.258085 | controller |
2026-08-13 00:41:04.258304 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-08-13 00:41:04.258531 | controller | Thursday 13 August 2026  00:41:04 +0000 (0:00:00.297)       0:00:19.180 *******
2026-08-13 00:41:04.560514 | controller | ok: [controller]
2026-08-13 00:41:04.560712 | controller |
2026-08-13 00:41:04.560977 | controller | TASK [Install plugin] **********************************************************
2026-08-13 00:41:04.561295 | controller | Thursday 13 August 2026  00:41:04 +0000 (0:00:00.307)       0:00:19.487 *******
2026-08-13 00:41:04.619515 | controller | included: vexxhost.containers.download_artifact for controller
2026-08-13 00:41:04.619656 | controller |
2026-08-13 00:41:04.619886 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-13 00:41:04.620098 | controller | Thursday 13 August 2026  00:41:04 +0000 (0:00:00.059)       0:00:19.546 *******
2026-08-13 00:41:04.670226 | controller | ok: [controller] => {
2026-08-13 00:41:04.670329 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-08-13 00:41:04.670342 | controller | }
2026-08-13 00:41:04.670440 | controller |
2026-08-13 00:41:04.670560 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-13 00:41:04.670699 | controller | Thursday 13 August 2026  00:41:04 +0000 (0:00:00.050)       0:00:19.597 *******
2026-08-13 00:41:05.429464 | controller | changed: [controller]
2026-08-13 00:41:05.429506 | controller |
2026-08-13 00:41:05.429518 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-13 00:41:05.429528 | controller | Thursday 13 August 2026  00:41:05 +0000 (0:00:00.758)       0:00:20.355 *******
2026-08-13 00:41:07.474213 | controller | changed: [controller]
2026-08-13 00:41:07.474267 | controller |
2026-08-13 00:41:07.474332 | controller | PLAY RECAP *********************************************************************
2026-08-13 00:41:07.474430 | controller | controller                 : ok=16   changed=7    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-08-13 00:41:07.474510 | controller |
2026-08-13 00:41:07.474592 | controller |
2026-08-13 00:41:07.474672 | controller | TASKS RECAP ********************************************************************
2026-08-13 00:41:07.474758 | controller | Thursday 13 August 2026  00:41:07 +0000 (0:00:02.043)       0:00:22.398 *******
2026-08-13 00:41:07.474855 | controller | ===============================================================================
2026-08-13 00:41:07.474942 | controller | vexxhost.containers.download_artifact : Download item ------------------ 12.34s
2026-08-13 00:41:07.475023 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.05s
2026-08-13 00:41:07.475109 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.04s
2026-08-13 00:41:07.475192 | controller | Gathering Facts --------------------------------------------------------- 1.08s
2026-08-13 00:41:07.475277 | controller | vexxhost.containers.package : Update state for tar ---------------------- 0.98s
2026-08-13 00:41:07.476233 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.76s
2026-08-13 00:41:07.476255 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.53s
2026-08-13 00:41:07.476259 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.51s
2026-08-13 00:41:07.476262 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.46s
2026-08-13 00:41:07.476266 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.46s
2026-08-13 00:41:07.476270 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.40s
2026-08-13 00:41:07.476280 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.31s
2026-08-13 00:41:07.476284 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.30s
2026-08-13 00:41:07.476287 | controller | Install plugin ---------------------------------------------------------- 0.06s
2026-08-13 00:41:07.476290 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.06s
2026-08-13 00:41:07.476293 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-08-13 00:41:07.583385 | controller | INFO     [helm > converge] Executed: Successful
2026-08-13 00:41:07.592539 | controller | INFO     [helm > idempotence] Executing
2026-08-13 00:41:08.455067 | controller |
2026-08-13 00:41:08.455387 | controller | PLAY [Converge] ****************************************************************
2026-08-13 00:41:08.455629 | controller |
2026-08-13 00:41:08.455885 | controller | TASK [Gathering Facts] *********************************************************
2026-08-13 00:41:08.456142 | controller | Thursday 13 August 2026  00:41:08 +0000 (0:00:00.013)       0:00:00.013 *******
2026-08-13 00:41:09.497723 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information.
2026-08-13 00:41:09.503679 | controller | ok: [controller]
2026-08-13 00:41:09.503944 | controller |
2026-08-13 00:41:09.504252 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-13 00:41:09.504514 | controller | Thursday 13 August 2026  00:41:09 +0000 (0:00:01.048)       0:00:01.062 *******
2026-08-13 00:41:09.930080 | controller | ok: [controller]
2026-08-13 00:41:09.930105 | controller |
2026-08-13 00:41:09.930110 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-13 00:41:09.930115 | controller | Thursday 13 August 2026  00:41:09 +0000 (0:00:00.425)       0:00:01.488 *******
2026-08-13 00:41:10.916328 | controller | ok: [controller]
2026-08-13 00:41:10.916604 | controller |
2026-08-13 00:41:10.916868 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-08-13 00:41:10.917120 | controller | Thursday 13 August 2026  00:41:10 +0000 (0:00:00.986)       0:00:02.474 *******
2026-08-13 00:41:11.345993 | controller | ok: [controller]
2026-08-13 00:41:11.346063 | controller |
2026-08-13 00:41:11.346209 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-13 00:41:11.346320 | controller | Thursday 13 August 2026  00:41:11 +0000 (0:00:00.428)       0:00:02.902 *******
2026-08-13 00:41:11.388015 | controller | ok: [controller] => {
2026-08-13 00:41:11.388130 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-08-13 00:41:11.388218 | controller | }
2026-08-13 00:41:11.388295 | controller |
2026-08-13 00:41:11.388386 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-13 00:41:11.388469 | controller | Thursday 13 August 2026  00:41:11 +0000 (0:00:00.043)       0:00:02.945 *******
2026-08-13 00:41:11.901663 | controller | ok: [controller]
2026-08-13 00:41:11.901718 | controller |
2026-08-13 00:41:11.901895 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-13 00:41:11.901915 | controller | Thursday 13 August 2026  00:41:11 +0000 (0:00:00.509)       0:00:03.455 *******
2026-08-13 00:41:13.574089 | controller | ok: [controller]
2026-08-13 00:41:13.574187 | controller |
2026-08-13 00:41:13.574309 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-08-13 00:41:13.574406 | controller | Thursday 13 August 2026  00:41:13 +0000 (0:00:01.676)       0:00:05.131 *******
2026-08-13 00:41:14.084789 | controller | ok: [controller]
2026-08-13 00:41:14.084811 | controller |
2026-08-13 00:41:14.084815 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-08-13 00:41:14.084819 | controller | Thursday 13 August 2026  00:41:14 +0000 (0:00:00.508)       0:00:05.639 *******
2026-08-13 00:41:14.497149 | controller | ok: [controller]
2026-08-13 00:41:14.498388 | controller |
2026-08-13 00:41:14.498720 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-08-13 00:41:14.499100 | controller | Thursday 13 August 2026  00:41:14 +0000 (0:00:00.415)       0:00:06.055 *******
2026-08-13 00:41:14.945512 | controller | ok: [controller]
2026-08-13 00:41:14.945636 | controller |
2026-08-13 00:41:14.946005 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-08-13 00:41:14.946019 | controller | Thursday 13 August 2026  00:41:14 +0000 (0:00:00.448)       0:00:06.503 *******
2026-08-13 00:41:15.254301 | controller | ok: [controller]
2026-08-13 00:41:15.254469 | controller |
2026-08-13 00:41:15.254636 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-08-13 00:41:15.254771 | controller | Thursday 13 August 2026  00:41:15 +0000 (0:00:00.306)       0:00:06.810 *******
2026-08-13 00:41:15.571201 | controller | ok: [controller]
2026-08-13 00:41:15.571253 | controller |
2026-08-13 00:41:15.571407 | controller | TASK [Install plugin] **********************************************************
2026-08-13 00:41:15.571540 | controller | Thursday 13 August 2026  00:41:15 +0000 (0:00:00.318)       0:00:07.129 *******
2026-08-13 00:41:15.625264 | controller | included: vexxhost.containers.download_artifact for controller
2026-08-13 00:41:15.625315 | controller |
2026-08-13 00:41:15.625410 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-13 00:41:15.625485 | controller | Thursday 13 August 2026  00:41:15 +0000 (0:00:00.054)       0:00:07.183 *******
2026-08-13 00:41:15.673278 | controller | ok: [controller] => {
2026-08-13 00:41:15.673335 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-08-13 00:41:15.673470 | controller | }
2026-08-13 00:41:15.673583 | controller |
2026-08-13 00:41:15.673885 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-13 00:41:15.673933 | controller | Thursday 13 August 2026  00:41:15 +0000 (0:00:00.047)       0:00:07.231 *******
2026-08-13 00:41:16.093591 | controller | ok: [controller]
2026-08-13 00:41:16.093645 | controller |
2026-08-13 00:41:16.093719 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-13 00:41:16.093864 | controller | Thursday 13 August 2026  00:41:16 +0000 (0:00:00.420)       0:00:07.651 *******
2026-08-13 00:41:17.734785 | controller | ok: [controller]
2026-08-13 00:41:17.734821 | controller |
2026-08-13 00:41:17.734829 | controller | PLAY RECAP *********************************************************************
2026-08-13 00:41:17.734834 | controller | controller                 : ok=16   changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-08-13 00:41:17.734840 | controller |
2026-08-13 00:41:17.734844 | controller |
2026-08-13 00:41:17.734849 | controller | TASKS RECAP ********************************************************************
2026-08-13 00:41:17.734854 | controller | Thursday 13 August 2026  00:41:17 +0000 (0:00:01.638)       0:00:09.290 *******
2026-08-13 00:41:17.734859 | controller | ===============================================================================
2026-08-13 00:41:17.734864 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.68s
2026-08-13 00:41:17.734869 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.64s
2026-08-13 00:41:17.734873 | controller | Gathering Facts --------------------------------------------------------- 1.05s
2026-08-13 00:41:17.734878 | controller | vexxhost.containers.package : Update state for tar ---------------------- 0.99s
2026-08-13 00:41:17.734883 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.51s
2026-08-13 00:41:17.734887 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.51s
2026-08-13 00:41:17.734909 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.45s
2026-08-13 00:41:17.734915 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.43s
2026-08-13 00:41:17.734920 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.43s
2026-08-13 00:41:17.734925 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.42s
2026-08-13 00:41:17.734929 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.42s
2026-08-13 00:41:17.734933 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.32s
2026-08-13 00:41:17.734945 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.31s
2026-08-13 00:41:17.734950 | controller | Install plugin ---------------------------------------------------------- 0.05s
2026-08-13 00:41:17.734954 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-08-13 00:41:17.734959 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s
2026-08-13 00:41:17.817882 | controller | INFO     [helm > idempotence] Executed: Successful
2026-08-13 00:41:17.828288 | controller | INFO     [helm > side_effect] Executing
2026-08-13 00:41:17.828774 | controller | WARNING  [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-13 00:41:17.834958 | controller | INFO     [helm > verify] Executing
2026-08-13 00:41:18.666268 | controller |
2026-08-13 00:41:18.666578 | controller | PLAY [Verify] ******************************************************************
2026-08-13 00:41:18.666817 | controller |
2026-08-13 00:41:18.667071 | controller | TASK [Gathering Facts] *********************************************************
2026-08-13 00:41:18.667327 | controller | Thursday 13 August 2026  00:41:18 +0000 (0:00:00.009)       0:00:00.009 *******
2026-08-13 00:41:20.733059 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information.
2026-08-13 00:41:20.738189 | controller | ok: [controller]
2026-08-13 00:41:20.738356 | controller |
2026-08-13 00:41:20.738523 | controller | TASK [Get the current Helm version] ********************************************
2026-08-13 00:41:20.738688 | controller | Thursday 13 August 2026  00:41:20 +0000 (0:00:02.072)       0:00:02.082 *******
2026-08-13 00:41:21.187447 | controller | changed: [controller]
2026-08-13 00:41:21.187717 | controller |
2026-08-13 00:41:21.187971 | controller | TASK [Assert Helm version] *****************************************************
2026-08-13 00:41:21.188219 | controller | Thursday 13 August 2026  00:41:21 +0000 (0:00:00.448)       0:00:02.530 *******
2026-08-13 00:41:21.215882 | controller | ok: [controller] => {
2026-08-13 00:41:21.216233 | controller |     "changed": false,
2026-08-13 00:41:21.216542 | controller |     "msg": "All assertions passed"
2026-08-13 00:41:21.216805 | controller | }
2026-08-13 00:41:21.217051 | controller |
2026-08-13 00:41:21.217316 | controller | PLAY RECAP *********************************************************************
2026-08-13 00:41:21.217602 | controller | controller                 : ok=3    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-08-13 00:41:21.217955 | controller |
2026-08-13 00:41:21.218202 | controller |
2026-08-13 00:41:21.218461 | controller | TASKS RECAP ********************************************************************
2026-08-13 00:41:21.218726 | controller | Thursday 13 August 2026  00:41:21 +0000 (0:00:00.029)       0:00:02.559 *******
2026-08-13 00:41:21.218977 | controller | ===============================================================================
2026-08-13 00:41:21.219240 | controller | Gathering Facts --------------------------------------------------------- 2.07s
2026-08-13 00:41:21.219501 | controller | Get the current Helm version -------------------------------------------- 0.45s
2026-08-13 00:41:21.219759 | controller | Assert Helm version ----------------------------------------------------- 0.03s
2026-08-13 00:41:21.308800 | controller | INFO     [helm > verify] Executed: Successful
2026-08-13 00:41:21.318463 | controller | INFO     [helm > cleanup] Executing
2026-08-13 00:41:21.318947 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-13 00:41:21.324726 | controller | INFO     [helm > destroy] Executing
2026-08-13 00:41:21.324835 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-08-13 00:41:21.324927 | controller | INFO     [helm > destroy] Executed: Successful
2026-08-13 00:41:21.325202 | controller | WARNING  Molecule executed 1 scenario (1 missing files)
2026-08-13 00:41:22.328364 | controller | ok: Runtime: 0:01:09.575558
2026-08-13 00:41:22.828935 | 
2026-08-13 00:41:22.829451 | PLAY RECAP
2026-08-13 00:41:22.829524 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-13 00:41:22.829556 | 
2026-08-13 00:41:22.964757 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-13 00:41:22.968142 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-08-13 00:41:23.620481 | 
2026-08-13 00:41:23.620671 | PLAY [all]
2026-08-13 00:41:23.632385 | 
2026-08-13 00:41:23.632550 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-08-13 00:41:24.009647 | controller | changed
2026-08-13 00:41:24.014679 | 
2026-08-13 00:41:24.014761 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-08-13 00:41:24.406748 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-13 00:41:24.406994 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-13 00:41:24.414791 | controller | environment: line 1: kubectl: command not found
2026-08-13 00:41:24.416695 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-13 00:41:24.417601 | controller | environment: line 1: kubectl: command not found
2026-08-13 00:41:24.419635 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-13 00:41:24.420200 | controller | environment: line 1: kubectl: command not found
2026-08-13 00:41:24.421293 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-13 00:41:24.423366 | controller | environment: line 1: kubectl: command not found
2026-08-13 00:41:24.425562 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-13 00:41:24.425580 | controller | environment: line 1: kubectl: command not found
2026-08-13 00:41:24.426800 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-13 00:41:24.544356 | controller | ok: Runtime: 0:00:00.038663
2026-08-13 00:41:24.550220 | 
2026-08-13 00:41:24.550325 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-08-13 00:41:24.792095 | controller | changed
2026-08-13 00:41:24.798449 | 
2026-08-13 00:41:24.798543 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-08-13 00:41:25.029820 | controller | environment: line 5: kubectl: command not found
2026-08-13 00:41:25.031123 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-13 00:41:25.031253 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-13 00:41:25.031356 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-13 00:41:25.327905 | controller | ok: Runtime: 0:00:00.012115
2026-08-13 00:41:25.336667 | 
2026-08-13 00:41:25.336732 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-08-13 00:41:25.970554 | controller | changed:
2026-08-13 00:41:25.970826 | controller | created directory /var/lib/zuul/builds/9f769123c73444e3ae0cc3639da664d9/work/logs/controller
2026-08-13 00:41:25.970963 | controller | cd+++++++++ objects/
2026-08-13 00:41:25.971036 | controller | cd+++++++++ objects/cluster/
2026-08-13 00:41:25.971093 | controller | cd+++++++++ objects/namespaced/
2026-08-13 00:41:25.981123 | 
2026-08-13 00:41:25.981213 | TASK [gather-pod-logs : creating directory for pod logs]
2026-08-13 00:41:26.219004 | controller | changed
2026-08-13 00:41:26.225279 | 
2026-08-13 00:41:26.225386 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-08-13 00:41:26.460804 | controller | changed
2026-08-13 00:41:26.467032 | 
2026-08-13 00:41:26.467133 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-08-13 00:41:26.706054 | controller | environment: line 3: kubectl: command not found
2026-08-13 00:41:26.999228 | controller | ok: Runtime: 0:00:00.011653
2026-08-13 00:41:27.005066 | 
2026-08-13 00:41:27.005162 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-08-13 00:41:29.481205 | controller | changed:
2026-08-13 00:41:29.481382 | controller | cd+++++++++ pod-logs/
2026-08-13 00:41:29.481411 | controller | cd+++++++++ pod-logs/failed-pods/
2026-08-13 00:41:29.488167 | 
2026-08-13 00:41:29.488230 | LOOP [helm-release-status : creating directory for helm release status]
2026-08-13 00:41:29.719305 | controller | changed: "values"
2026-08-13 00:41:29.926705 | controller | changed: "releases"
2026-08-13 00:41:29.944055 | 
2026-08-13 00:41:29.944230 | TASK [helm-release-status : Gather get release status for helm charts]
2026-08-13 00:41:30.190125 | controller | /bin/bash: line 3: kubectl: command not found
2026-08-13 00:41:30.472353 | controller | ok: Runtime: 0:00:00.008674
2026-08-13 00:41:30.477883 | 
2026-08-13 00:41:30.477960 | TASK [helm-release-status : Downloads logs to executor]
2026-08-13 00:41:31.007665 | controller | changed:
2026-08-13 00:41:31.007872 | controller | cd+++++++++ helm/
2026-08-13 00:41:31.007911 | controller | cd+++++++++ helm/releases/
2026-08-13 00:41:31.007944 | controller | cd+++++++++ helm/values/
2026-08-13 00:41:31.014758 | 
2026-08-13 00:41:31.014829 | PLAY RECAP
2026-08-13 00:41:31.014890 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-13 00:41:31.014919 | 
2026-08-13 00:41:31.134422 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-08-13 00:41:32.069706 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-13 00:41:32.699192 | 
2026-08-13 00:41:32.699696 | PLAY [all]
2026-08-13 00:41:32.711486 | 
2026-08-13 00:41:32.711607 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-13 00:41:32.759041 | controller | skipping: Conditional result was False
2026-08-13 00:41:32.767851 | 
2026-08-13 00:41:32.767943 | TASK [fetch-output : Set log path for single node]
2026-08-13 00:41:32.811767 | controller | ok
2026-08-13 00:41:32.817288 | 
2026-08-13 00:41:32.817415 | LOOP [fetch-output : Ensure local output dirs]
2026-08-13 00:41:33.274149 | controller -> localhost | ok: "/var/lib/zuul/builds/9f769123c73444e3ae0cc3639da664d9/work/logs"
2026-08-13 00:41:33.498275 | controller -> localhost | changed: "/var/lib/zuul/builds/9f769123c73444e3ae0cc3639da664d9/work/artifacts"
2026-08-13 00:41:33.708619 | controller -> localhost | changed: "/var/lib/zuul/builds/9f769123c73444e3ae0cc3639da664d9/work/docs"
2026-08-13 00:41:33.725937 | 
2026-08-13 00:41:33.726127 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-13 00:41:34.473322 | controller | changed: .d..t...... ./
2026-08-13 00:41:34.473592 | controller | changed: All items complete
2026-08-13 00:41:34.473632 | 
2026-08-13 00:41:34.941732 | controller | changed: .d..t...... ./
2026-08-13 00:41:35.604992 | controller | changed: .d..t...... ./
2026-08-13 00:41:35.619652 | 
2026-08-13 00:41:35.620109 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-13 00:41:36.135510 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007123
2026-08-13 00:41:36.394327 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008049
2026-08-13 00:41:36.410611 | 
2026-08-13 00:41:36.410759 | PLAY [all]
2026-08-13 00:41:36.416049 | 
2026-08-13 00:41:36.416118 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-13 00:41:36.887716 | controller | changed
2026-08-13 00:41:36.895862 | 
2026-08-13 00:41:36.896012 | PLAY RECAP
2026-08-13 00:41:36.896083 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-13 00:41:36.896115 | 
2026-08-13 00:41:37.095722 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-13 00:41:37.098667 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-13 00:41:37.825933 | 
2026-08-13 00:41:37.826210 | PLAY [localhost]
2026-08-13 00:41:37.837509 | 
2026-08-13 00:41:37.837654 | TASK [Generate Zuul manifest]
2026-08-13 00:41:37.858729 | localhost | ok
2026-08-13 00:41:37.878412 | 
2026-08-13 00:41:37.878595 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-13 00:41:38.336846 | localhost | changed
2026-08-13 00:41:38.349909 | 
2026-08-13 00:41:38.350072 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-13 00:41:38.460455 | localhost | ok
2026-08-13 00:41:38.474128 | 
2026-08-13 00:41:38.474305 | TASK [Upload logs]
2026-08-13 00:41:38.495722 | localhost | ok
2026-08-13 00:41:38.570757 | 
2026-08-13 00:41:38.678185 | TASK [Set zuul-log-path fact]
2026-08-13 00:41:38.699430 | localhost | ok
2026-08-13 00:41:38.716252 | 
2026-08-13 00:41:38.716407 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-13 00:41:38.748909 | localhost | ok
2026-08-13 00:41:38.761254 | 
2026-08-13 00:41:38.761431 | TASK [upload-logs : Create log directories]
2026-08-13 00:41:39.325178 | localhost | changed
2026-08-13 00:41:39.332634 | 
2026-08-13 00:41:39.332736 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-13 00:41:39.812840 | localhost -> localhost | ok: Runtime: 0:00:00.053670
2026-08-13 00:41:39.818989 | 
2026-08-13 00:41:39.866765 | TASK [upload-logs : Upload logs to log server]
2026-08-13 00:41:40.447888 | localhost | Output suppressed because no_log was given
2026-08-13 00:41:40.453203 | 
2026-08-13 00:41:40.453283 | LOOP [upload-logs : Compress console log and json output]
2026-08-13 00:41:40.513084 | localhost | skipping: Conditional result was False
2026-08-13 00:41:40.565313 | localhost | skipping: Conditional result was False
2026-08-13 00:41:40.676690 | 
2026-08-13 00:41:40.676840 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-13 00:41:40.723117 | localhost | skipping: Conditional result was False
2026-08-13 00:41:40.745558 | 
2026-08-13 00:41:40.746982 | localhost | skipping: Conditional result was False
2026-08-13 00:41:40.758507 | 
2026-08-13 00:41:40.758761 | LOOP [upload-logs : Upload console log and json output]