2026-07-06 16:45:35.109609 | Job console starting
2026-07-06 16:45:35.120180 | Updating git repos
2026-07-06 16:45:35.176044 | Cloning repos into workspace
2026-07-06 16:45:35.875409 | Restoring repo states
2026-07-06 16:45:35.898346 | Merging changes
2026-07-06 16:45:36.904613 | Checking out repos
2026-07-06 16:45:37.149138 | Preparing playbooks
2026-07-06 16:45:41.173767 | Running Ansible setup
2026-07-06 16:45:44.852020 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-06 16:45:45.517575 | 
2026-07-06 16:45:45.517956 | PLAY [localhost]
2026-07-06 16:45:45.527304 | 
2026-07-06 16:45:45.527422 | TASK [Gathering Facts]
2026-07-06 16:45:46.429893 | localhost | ok
2026-07-06 16:45:46.438960 | 
2026-07-06 16:45:46.439057 | TASK [Setup log path fact]
2026-07-06 16:45:46.460524 | localhost | ok
2026-07-06 16:45:46.475659 | 
2026-07-06 16:45:46.475759 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-06 16:45:46.507833 | localhost | ok
2026-07-06 16:45:46.514804 | 
2026-07-06 16:45:46.514881 | TASK [emit-job-header : Print job information]
2026-07-06 16:45:46.556493 | # Job Information
2026-07-06 16:45:46.556709 | Ansible Version: 2.16.18
2026-07-06 16:45:46.556759 | Job: ansible-collection-kubernetes-molecule-helm
2026-07-06 16:45:46.556803 | Pipeline: check
2026-07-06 16:45:46.556837 | Executor: 2d72f0692154
2026-07-06 16:45:46.556868 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/289
2026-07-06 16:45:46.556902 | Event ID: f88bfc90-7959-11f1-9e80-256e6193278f
2026-07-06 16:45:46.561051 | 
2026-07-06 16:45:46.561152 | LOOP [emit-job-header : Print node information]
2026-07-06 16:45:46.659587 | localhost | ok:
2026-07-06 16:45:46.659872 | localhost | # Node Information
2026-07-06 16:45:46.659907 | localhost | Inventory Hostname: controller
2026-07-06 16:45:46.659931 | localhost | Hostname: np0000184204
2026-07-06 16:45:46.659951 | localhost | Username: zuul
2026-07-06 16:45:46.659973 | localhost | Distro: Debian 13.5
2026-07-06 16:45:46.659994 | localhost | Provider: yul1
2026-07-06 16:45:46.660013 | localhost | Region: ca-ymq-1
2026-07-06 16:45:46.660033 | localhost | Label: debian-trixie
2026-07-06 16:45:46.660052 | localhost | Product Name: OpenStack Nova
2026-07-06 16:45:46.660072 | localhost | Interface IP: 199.19.213.162
2026-07-06 16:45:46.680365 | 
2026-07-06 16:45:46.680931 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-07-06 16:45:47.446355 | localhost -> localhost | changed
2026-07-06 16:45:47.452157 | 
2026-07-06 16:45:47.452228 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-07-06 16:45:48.346009 | localhost -> localhost | changed
2026-07-06 16:45:48.355363 | 
2026-07-06 16:45:48.355418 | PLAY [all]
2026-07-06 16:45:48.362031 | 
2026-07-06 16:45:48.362096 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-07-06 16:45:48.608507 | controller -> localhost | ok
2026-07-06 16:45:48.613452 | 
2026-07-06 16:45:48.613522 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-07-06 16:45:48.645155 | controller | ok
2026-07-06 16:45:48.660881 | controller | included: /var/lib/zuul/builds/7cca019674994fa69d20eae2b5b781cf/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-07-06 16:45:48.665510 | 
2026-07-06 16:45:48.665577 | TASK [add-build-sshkey : Create Temp SSH key]
2026-07-06 16:45:49.519989 | controller -> localhost | Generating public/private rsa key pair.
2026-07-06 16:45:49.520192 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/7cca019674994fa69d20eae2b5b781cf/work/7cca019674994fa69d20eae2b5b781cf_id_rsa
2026-07-06 16:45:49.520235 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/7cca019674994fa69d20eae2b5b781cf/work/7cca019674994fa69d20eae2b5b781cf_id_rsa.pub
2026-07-06 16:45:49.520269 | controller -> localhost | The key fingerprint is:
2026-07-06 16:45:49.520299 | controller -> localhost | SHA256:pG1K68395VVEFX6OkCtsLcfPhQcUoVJGWQxf8QDgvuk zuul-build-sshkey
2026-07-06 16:45:49.520341 | controller -> localhost | The key's randomart image is:
2026-07-06 16:45:49.520371 | controller -> localhost | +---[RSA 3072]----+
2026-07-06 16:45:49.520407 | controller -> localhost | |          .o***=B|
2026-07-06 16:45:49.520437 | controller -> localhost | |         . o.=o+.|
2026-07-06 16:45:49.520466 | controller -> localhost | |        . o + o.+|
2026-07-06 16:45:49.520494 | controller -> localhost | |       + o + o *.|
2026-07-06 16:45:49.520522 | controller -> localhost | |      o S * = o =|
2026-07-06 16:45:49.520550 | controller -> localhost | |     . + . * o o.|
2026-07-06 16:45:49.520577 | controller -> localhost | |      o   o   + .|
2026-07-06 16:45:49.520605 | controller -> localhost | |     . o o   o . |
2026-07-06 16:45:49.520636 | controller -> localhost | |      . o E.. .  |
2026-07-06 16:45:49.520665 | controller -> localhost | +----[SHA256]-----+
2026-07-06 16:45:49.520729 | controller -> localhost | ok: Runtime: 0:00:00.441624
2026-07-06 16:45:49.525694 | 
2026-07-06 16:45:49.525758 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-07-06 16:45:49.557715 | controller | ok
2026-07-06 16:45:49.568947 | controller | included: /var/lib/zuul/builds/7cca019674994fa69d20eae2b5b781cf/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-07-06 16:45:49.575174 | 
2026-07-06 16:45:49.575233 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-07-06 16:45:49.600064 | controller | skipping: Conditional result was False
2026-07-06 16:45:49.609000 | 
2026-07-06 16:45:49.609071 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-07-06 16:45:50.126338 | controller | changed
2026-07-06 16:45:50.130378 | 
2026-07-06 16:45:50.130441 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-07-06 16:45:50.342983 | controller | ok
2026-07-06 16:45:50.347365 | 
2026-07-06 16:45:50.347431 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-07-06 16:45:50.872544 | controller | changed
2026-07-06 16:45:50.878653 | 
2026-07-06 16:45:50.878721 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-07-06 16:45:51.442580 | controller | changed
2026-07-06 16:45:51.448067 | 
2026-07-06 16:45:51.448153 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-07-06 16:45:51.473334 | controller | skipping: Conditional result was False
2026-07-06 16:45:51.480032 | 
2026-07-06 16:45:51.480151 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-07-06 16:45:51.873834 | controller -> localhost | changed
2026-07-06 16:45:51.886473 | 
2026-07-06 16:45:51.886574 | TASK [add-build-sshkey : Add back temp key]
2026-07-06 16:45:52.162588 | controller -> localhost | Identity added: /var/lib/zuul/builds/7cca019674994fa69d20eae2b5b781cf/work/7cca019674994fa69d20eae2b5b781cf_id_rsa (zuul-build-sshkey)
2026-07-06 16:45:52.162885 | controller -> localhost | ok: Runtime: 0:00:00.016671
2026-07-06 16:45:52.166899 | 
2026-07-06 16:45:52.166959 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-07-06 16:45:52.503140 | controller | ok
2026-07-06 16:45:52.508381 | 
2026-07-06 16:45:52.508475 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-07-06 16:45:52.534481 | controller | skipping: Conditional result was False
2026-07-06 16:45:52.547055 | 
2026-07-06 16:45:52.547205 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-07-06 16:45:52.917128 | controller | ok
2026-07-06 16:45:52.923538 | 
2026-07-06 16:45:52.923602 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-07-06 16:45:53.944382 | controller | Output suppressed because no_log was given
2026-07-06 16:45:53.953275 | 
2026-07-06 16:45:53.953374 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-07-06 16:45:54.172609 | controller | ok: "logs"
2026-07-06 16:45:54.172860 | controller | ok: All items complete
2026-07-06 16:45:54.172897 | 
2026-07-06 16:45:54.351354 | controller | ok: "artifacts"
2026-07-06 16:45:54.527491 | controller | ok: "docs"
2026-07-06 16:45:54.548124 | 
2026-07-06 16:45:54.548330 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-07-06 16:45:54.755238 | controller | changed: "logs"
2026-07-06 16:45:54.949173 | controller | changed: "artifacts"
2026-07-06 16:45:55.128854 | controller | changed: "docs"
2026-07-06 16:45:55.145157 | 
2026-07-06 16:45:55.145306 | PLAY RECAP
2026-07-06 16:45:55.145360 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-07-06 16:45:55.145388 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-06 16:45:55.145413 | 
2026-07-06 16:45:55.314532 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-06 16:45:55.317071 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-06 16:45:55.905481 | 
2026-07-06 16:45:55.905654 | PLAY [all]
2026-07-06 16:45:55.916377 | 
2026-07-06 16:45:55.916541 | TASK [setup-uv : Extract archive]
2026-07-06 16:45:58.317237 | controller | changed
2026-07-06 16:45:58.323303 | 
2026-07-06 16:45:58.323387 | TASK [setup-uv : Print version]
2026-07-06 16:45:58.693631 | controller | uv 0.8.13
2026-07-06 16:45:58.853070 | controller | ok: Runtime: 0:00:00.013311
2026-07-06 16:45:58.862038 | 
2026-07-06 16:45:58.862107 | TASK [Install Ansible collection siblings]
2026-07-06 16:45:59.277530 | controller | ok:
2026-07-06 16:45:59.277740 | controller | Skipping ansible.posix: no checked-out sibling
2026-07-06 16:45:59.277781 | controller | Skipping community.crypto: no checked-out sibling
2026-07-06 16:45:59.277811 | controller | Skipping community.general: no checked-out sibling
2026-07-06 16:45:59.277839 | controller | Skipping kubernetes.core: no checked-out sibling
2026-07-06 16:45:59.277866 | controller | Skipping vexxhost.containers: no checked-out sibling
2026-07-06 16:45:59.280174 | 
2026-07-06 16:45:59.280242 | PLAY RECAP
2026-07-06 16:45:59.280309 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-06 16:45:59.280340 | 
2026-07-06 16:45:59.404068 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-06 16:45:59.407337 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-07-06 16:45:59.996710 | 
2026-07-06 16:45:59.997455 | PLAY [all]
2026-07-06 16:46:00.008267 | 
2026-07-06 16:46:00.008360 | TASK [Update apt cache]
2026-07-06 16:46:04.987323 | controller | ok
2026-07-06 16:46:04.992971 | 
2026-07-06 16:46:04.993079 | TASK [Disable firewalld]
2026-07-06 16:46:05.019000 | controller | skipping: Conditional result was False
2026-07-06 16:46:05.029321 | 
2026-07-06 16:46:05.029552 | TASK [Install "jq" for log collection]
2026-07-06 16:46:07.567182 | controller | changed
2026-07-06 16:46:07.569886 | 
2026-07-06 16:46:07.569956 | PLAY RECAP
2026-07-06 16:46:07.570017 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-06 16:46:07.570049 | 
2026-07-06 16:46:07.750860 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-07-06 16:46:07.752291 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-07-06 16:46:08.337120 | 
2026-07-06 16:46:08.337241 | PLAY [all]
2026-07-06 16:46:08.346874 | 
2026-07-06 16:46:08.346945 | LOOP [Check inventory directories]
2026-07-06 16:46:08.692841 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-07-06 16:46:08.870119 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/helm/inventory"
2026-07-06 16:46:09.065378 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/inventory"
2026-07-06 16:46:09.077267 | 
2026-07-06 16:46:09.077463 | LOOP [Copy inventory file for Zuul]
2026-07-06 16:46:09.963859 | controller | changed:
2026-07-06 16:46:09.964189 | controller | {
2026-07-06 16:46:09.964227 | controller |   "ansible_loop_var": "item",
2026-07-06 16:46:09.964257 | controller |   "changed": false,
2026-07-06 16:46:09.964281 | controller |   "failed": false,
2026-07-06 16:46:09.964315 | controller |   "invocation": {
2026-07-06 16:46:09.964344 | controller |     "module_args": {
2026-07-06 16:46:09.964368 | controller |       "checksum_algorithm": "sha1",
2026-07-06 16:46:09.964391 | controller |       "follow": true,
2026-07-06 16:46:09.964413 | controller |       "get_attributes": true,
2026-07-06 16:46:09.964436 | controller |       "get_checksum": true,
2026-07-06 16:46:09.964458 | controller |       "get_mime": true,
2026-07-06 16:46:09.964482 | controller |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-07-06 16:46:09.964505 | controller |     }
2026-07-06 16:46:09.964527 | controller |   },
2026-07-06 16:46:09.964550 | controller |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-07-06 16:46:09.964572 | controller |   "stat": {
2026-07-06 16:46:09.964593 | controller |     "atime": 1783356353.8103127,
2026-07-06 16:46:09.964617 | controller |     "attr_flags": "e",
2026-07-06 16:46:09.964639 | controller |     "attributes": [
2026-07-06 16:46:09.964661 | controller |       "extents"
2026-07-06 16:46:09.964684 | controller |     ],
2026-07-06 16:46:09.964706 | controller |     "block_size": 4096,
2026-07-06 16:46:09.964727 | controller |     "blocks": 8,
2026-07-06 16:46:09.964750 | controller |     "charset": "unknown",
2026-07-06 16:46:09.964774 | controller |     "ctime": 1783356353.8383129,
2026-07-06 16:46:09.964796 | controller |     "dev": 65025,
2026-07-06 16:46:09.964818 | controller |     "device_type": 0,
2026-07-06 16:46:09.964841 | controller |     "executable": true,
2026-07-06 16:46:09.964863 | controller |     "exists": true,
2026-07-06 16:46:09.964885 | controller |     "gid": 1000,
2026-07-06 16:46:09.964908 | controller |     "gr_name": "zuul",
2026-07-06 16:46:09.964930 | controller |     "inode": 520215,
2026-07-06 16:46:09.964951 | controller |     "isblk": false,
2026-07-06 16:46:09.964998 | controller |     "ischr": false,
2026-07-06 16:46:09.965021 | controller |     "isdir": true,
2026-07-06 16:46:09.965043 | controller |     "isfifo": false,
2026-07-06 16:46:09.965067 | controller |     "isgid": false,
2026-07-06 16:46:09.965088 | controller |     "islnk": false,
2026-07-06 16:46:09.965110 | controller |     "isreg": false,
2026-07-06 16:46:09.965132 | controller |     "issock": false,
2026-07-06 16:46:09.965154 | controller |     "isuid": false,
2026-07-06 16:46:09.965175 | controller |     "mimetype": "unknown",
2026-07-06 16:46:09.965197 | controller |     "mode": "0755",
2026-07-06 16:46:09.965218 | controller |     "mtime": 1783356336.0866187,
2026-07-06 16:46:09.965239 | controller |     "nlink": 14,
2026-07-06 16:46:09.965260 | controller |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-07-06 16:46:09.965282 | controller |     "pw_name": "zuul",
2026-07-06 16:46:09.965306 | controller |     "readable": true,
2026-07-06 16:46:09.965325 | controller |     "rgrp": true,
2026-07-06 16:46:09.965343 | controller |     "roth": true,
2026-07-06 16:46:09.965361 | controller |     "rusr": true,
2026-07-06 16:46:09.965378 | controller |     "size": 4096,
2026-07-06 16:46:09.965396 | controller |     "uid": 1000,
2026-07-06 16:46:09.965413 | controller |     "version": "148521240",
2026-07-06 16:46:09.965431 | controller |     "wgrp": false,
2026-07-06 16:46:09.965450 | controller |     "woth": false,
2026-07-06 16:46:09.965644 | controller |     "writeable": true,
2026-07-06 16:46:09.965662 | controller |     "wusr": true,
2026-07-06 16:46:09.965679 | controller |     "xgrp": true,
2026-07-06 16:46:09.965696 | controller |     "xoth": true,
2026-07-06 16:46:09.965713 | controller |     "xusr": true
2026-07-06 16:46:09.965730 | controller |   }
2026-07-06 16:46:09.965911 | controller | }
2026-07-06 16:46:09.965964 | controller | ok: All items complete
2026-07-06 16:46:09.965988 | 
2026-07-06 16:46:09.967418 | controller | skipping: Conditional result was False
2026-07-06 16:46:09.968047 | controller | skipping: Conditional result was False
2026-07-06 16:46:09.981520 | 
2026-07-06 16:46:09.981679 | LOOP [Switch "ansible_host" to private IP]
2026-07-06 16:46:10.367243 | controller | changed: 1 replacements made
2026-07-06 16:46:10.369755 | controller | skipping: Conditional result was False
2026-07-06 16:46:10.370375 | controller | skipping: Conditional result was False
2026-07-06 16:46:10.385641 | 
2026-07-06 16:46:10.385794 | TASK [Run Molecule scenario]
2026-07-06 16:46:10.848628 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-07-06 16:46:10.848765 | controller | Creating virtual environment at: .venv
2026-07-06 16:46:11.123284 | controller | Downloading cryptography (4.3MiB)
2026-07-06 16:46:11.125394 | controller | Downloading ansible-core (2.3MiB)
2026-07-06 16:46:11.126220 | controller | Downloading pygments (1.2MiB)
2026-07-06 16:46:11.302998 | controller |  Downloading cryptography
2026-07-06 16:46:11.354161 | controller |  Downloading pygments
2026-07-06 16:46:11.485827 | controller |  Downloading ansible-core
2026-07-06 16:46:11.529742 | controller | Installed 36 packages in 42ms
2026-07-06 16:46:12.215057 | controller | INFO     Collection 'vexxhost.kubernetes' detected.
2026-07-06 16:46:12.215186 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-07-06 16:46:13.055230 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-07-06 16:46:13.055295 | controller | INFO     default scenario not found, disabling shared state.
2026-07-06 16:46:13.055421 | controller | INFO     [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-07-06 16:46:13.055504 | controller | INFO     [helm > prerun] Performing prerun with role_name_check=0...
2026-07-06 16:46:32.772101 | controller | INFO     [helm > dependency] Executing
2026-07-06 16:46:32.772263 | controller | WARNING  [helm > dependency] Missing roles requirements file: requirements.yml
2026-07-06 16:46:32.772574 | controller | WARNING  [helm > dependency] Missing collections requirements file: collections.yml
2026-07-06 16:46:32.772724 | controller | WARNING  [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-07-06 16:46:32.780348 | controller | INFO     [helm > cleanup] Executing
2026-07-06 16:46:32.780749 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-06 16:46:32.787107 | controller | INFO     [helm > destroy] Executing
2026-07-06 16:46:32.787132 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-07-06 16:46:32.787176 | controller | INFO     [helm > destroy] Executed: Successful
2026-07-06 16:46:32.792494 | controller | INFO     [helm > syntax] Executing
2026-07-06 16:46:33.701850 | controller |
2026-07-06 16:46:33.702058 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml
2026-07-06 16:46:33.785519 | controller | INFO     [helm > syntax] Executed: Successful
2026-07-06 16:46:33.796158 | controller | INFO     [helm > create] Executing
2026-07-06 16:46:33.797688 | controller | WARNING  [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-06 16:46:33.803340 | controller | INFO     [helm > prepare] Executing
2026-07-06 16:46:34.647307 | controller |
2026-07-06 16:46:34.647492 | controller | PLAY [Prepare] *****************************************************************
2026-07-06 16:46:34.647676 | controller |
2026-07-06 16:46:34.647879 | controller | TASK [Gathering Facts] *********************************************************
2026-07-06 16:46:34.648080 | controller | Monday 06 July 2026  16:46:34 +0000 (0:00:00.030)       0:00:00.030 ***********
2026-07-06 16:46:35.894983 | 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-06 16:46:35.900937 | controller | ok: [controller]
2026-07-06 16:46:35.901106 | controller |
2026-07-06 16:46:35.901305 | controller | TASK [Install Debian packages] *************************************************
2026-07-06 16:46:35.901538 | controller | Monday 06 July 2026  16:46:35 +0000 (0:00:01.254)       0:00:01.285 ***********
2026-07-06 16:46:37.446096 | controller | ok: [controller]
2026-07-06 16:46:37.446177 | controller |
2026-07-06 16:46:37.446317 | controller | PLAY RECAP *********************************************************************
2026-07-06 16:46:37.446463 | controller | controller                 : ok=2    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-06 16:46:37.446578 | controller |
2026-07-06 16:46:37.446687 | controller |
2026-07-06 16:46:37.446812 | controller | TASKS RECAP ********************************************************************
2026-07-06 16:46:37.446931 | controller | Monday 06 July 2026  16:46:37 +0000 (0:00:01.545)       0:00:02.830 ***********
2026-07-06 16:46:37.447087 | controller | ===============================================================================
2026-07-06 16:46:37.447207 | controller | Install Debian packages ------------------------------------------------- 1.55s
2026-07-06 16:46:37.447420 | controller | Gathering Facts --------------------------------------------------------- 1.25s
2026-07-06 16:46:37.580676 | controller | INFO     [helm > prepare] Executed: Successful
2026-07-06 16:46:37.590948 | controller | INFO     [helm > converge] Executing
2026-07-06 16:46:38.488580 | controller |
2026-07-06 16:46:38.488675 | controller | PLAY [Converge] ****************************************************************
2026-07-06 16:46:38.488881 | controller |
2026-07-06 16:46:38.489100 | controller | TASK [Gathering Facts] *********************************************************
2026-07-06 16:46:38.489350 | controller | Monday 06 July 2026  16:46:38 +0000 (0:00:00.013)       0:00:00.013 ***********
2026-07-06 16:46:39.617238 | 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-06 16:46:39.623485 | controller | ok: [controller]
2026-07-06 16:46:39.623580 | controller |
2026-07-06 16:46:39.623709 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-07-06 16:46:39.623816 | controller | Monday 06 July 2026  16:46:39 +0000 (0:00:01.134)       0:00:01.148 ***********
2026-07-06 16:46:40.170538 | controller | ok: [controller]
2026-07-06 16:46:40.170624 | controller |
2026-07-06 16:46:40.170835 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-07-06 16:46:40.171039 | controller | Monday 06 July 2026  16:46:40 +0000 (0:00:00.547)       0:00:01.695 ***********
2026-07-06 16:46:41.234162 | controller | ok: [controller]
2026-07-06 16:46:41.234333 | controller |
2026-07-06 16:46:41.234556 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-07-06 16:46:41.234773 | controller | Monday 06 July 2026  16:46:41 +0000 (0:00:01.063)       0:00:02.758 ***********
2026-07-06 16:46:41.669800 | controller | changed: [controller]
2026-07-06 16:46:41.669963 | controller |
2026-07-06 16:46:41.670182 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-07-06 16:46:41.670385 | controller | Monday 06 July 2026  16:46:41 +0000 (0:00:00.435)       0:00:03.194 ***********
2026-07-06 16:46:41.721051 | controller | ok: [controller] => {
2026-07-06 16:46:41.721131 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-07-06 16:46:41.721215 | controller | }
2026-07-06 16:46:41.721326 | controller |
2026-07-06 16:46:41.721456 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-07-06 16:46:41.721583 | controller | Monday 06 July 2026  16:46:41 +0000 (0:00:00.050)       0:00:03.245 ***********
2026-07-06 16:46:42.607604 | 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-06 16:46:42.621045 | controller | changed: [controller]
2026-07-06 16:46:42.621140 | controller |
2026-07-06 16:46:42.621232 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-07-06 16:46:42.621316 | controller | Monday 06 July 2026  16:46:42 +0000 (0:00:00.900)       0:00:04.145 ***********
2026-07-06 16:46:44.695370 | controller | changed: [controller]
2026-07-06 16:46:44.695404 | controller |
2026-07-06 16:46:44.695410 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-07-06 16:46:44.695416 | controller | Monday 06 July 2026  16:46:44 +0000 (0:00:02.071)       0:00:06.216 ***********
2026-07-06 16:46:45.190568 | controller | ok: [controller]
2026-07-06 16:46:45.190748 | controller |
2026-07-06 16:46:45.191013 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-07-06 16:46:45.191451 | controller | Monday 06 July 2026  16:46:45 +0000 (0:00:00.498)       0:00:06.715 ***********
2026-07-06 16:46:45.610303 | controller | changed: [controller]
2026-07-06 16:46:45.610386 | controller |
2026-07-06 16:46:45.610631 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-07-06 16:46:45.610817 | controller | Monday 06 July 2026  16:46:45 +0000 (0:00:00.419)       0:00:07.134 ***********
2026-07-06 16:46:46.086498 | controller | ok: [controller]
2026-07-06 16:46:46.086628 | controller |
2026-07-06 16:46:46.086858 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-07-06 16:46:46.087070 | controller | Monday 06 July 2026  16:46:46 +0000 (0:00:00.476)       0:00:07.611 ***********
2026-07-06 16:46:46.409185 | controller | changed: [controller]
2026-07-06 16:46:46.409402 | controller |
2026-07-06 16:46:46.409703 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-07-06 16:46:46.409975 | controller | Monday 06 July 2026  16:46:46 +0000 (0:00:00.322)       0:00:07.933 ***********
2026-07-06 16:46:46.728218 | controller | ok: [controller]
2026-07-06 16:46:46.728344 | controller |
2026-07-06 16:46:46.728583 | controller | TASK [Install plugin] **********************************************************
2026-07-06 16:46:46.728831 | controller | Monday 06 July 2026  16:46:46 +0000 (0:00:00.318)       0:00:08.251 ***********
2026-07-06 16:46:46.790296 | controller | included: vexxhost.containers.download_artifact for controller
2026-07-06 16:46:46.790451 | controller |
2026-07-06 16:46:46.790678 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-07-06 16:46:46.790893 | controller | Monday 06 July 2026  16:46:46 +0000 (0:00:00.062)       0:00:08.314 ***********
2026-07-06 16:46:46.839033 | controller | ok: [controller] => {
2026-07-06 16:46:46.839229 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-07-06 16:46:46.839540 | controller | }
2026-07-06 16:46:46.839730 | controller |
2026-07-06 16:46:46.839933 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-07-06 16:46:46.840122 | controller | Monday 06 July 2026  16:46:46 +0000 (0:00:00.048)       0:00:08.363 ***********
2026-07-06 16:46:47.735673 | controller | changed: [controller]
2026-07-06 16:46:47.735706 | controller |
2026-07-06 16:46:47.735714 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-07-06 16:46:47.735722 | controller | Monday 06 July 2026  16:46:47 +0000 (0:00:00.896)       0:00:09.259 ***********
2026-07-06 16:46:49.895628 | controller | changed: [controller]
2026-07-06 16:46:49.895953 | controller |
2026-07-06 16:46:49.896226 | controller | PLAY RECAP *********************************************************************
2026-07-06 16:46:49.896540 | controller | controller                 : ok=16   changed=7    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-06 16:46:49.896804 | controller |
2026-07-06 16:46:49.897043 | controller |
2026-07-06 16:46:49.897293 | controller | TASKS RECAP ********************************************************************
2026-07-06 16:46:49.897552 | controller | Monday 06 July 2026  16:46:49 +0000 (0:00:02.161)       0:00:11.420 ***********
2026-07-06 16:46:49.897911 | controller | ===============================================================================
2026-07-06 16:46:49.898176 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.16s
2026-07-06 16:46:49.898346 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.07s
2026-07-06 16:46:49.898503 | controller | Gathering Facts --------------------------------------------------------- 1.13s
2026-07-06 16:46:49.898669 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.06s
2026-07-06 16:46:49.898846 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.90s
2026-07-06 16:46:49.899008 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.90s
2026-07-06 16:46:49.899170 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.55s
2026-07-06 16:46:49.899377 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.50s
2026-07-06 16:46:49.899547 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.48s
2026-07-06 16:46:49.899719 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.44s
2026-07-06 16:46:49.899896 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.42s
2026-07-06 16:46:49.900061 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.32s
2026-07-06 16:46:49.900232 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.32s
2026-07-06 16:46:49.900389 | controller | Install plugin ---------------------------------------------------------- 0.06s
2026-07-06 16:46:49.900558 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-07-06 16:46:49.900744 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-07-06 16:46:50.017456 | controller | INFO     [helm > converge] Executed: Successful
2026-07-06 16:46:50.027796 | controller | INFO     [helm > idempotence] Executing
2026-07-06 16:46:50.910664 | controller |
2026-07-06 16:46:50.910809 | controller | PLAY [Converge] ****************************************************************
2026-07-06 16:46:50.911004 | controller |
2026-07-06 16:46:50.911207 | controller | TASK [Gathering Facts] *********************************************************
2026-07-06 16:46:50.911476 | controller | Monday 06 July 2026  16:46:50 +0000 (0:00:00.014)       0:00:00.014 ***********
2026-07-06 16:46:52.011410 | 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-06 16:46:52.020943 | controller | ok: [controller]
2026-07-06 16:46:52.020988 | controller |
2026-07-06 16:46:52.020999 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-07-06 16:46:52.021008 | controller | Monday 06 July 2026  16:46:52 +0000 (0:00:01.106)       0:00:01.120 ***********
2026-07-06 16:46:52.470983 | controller | ok: [controller]
2026-07-06 16:46:52.471073 | controller |
2026-07-06 16:46:52.471202 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-07-06 16:46:52.471481 | controller | Monday 06 July 2026  16:46:52 +0000 (0:00:00.454)       0:00:01.574 ***********
2026-07-06 16:46:53.587951 | controller | ok: [controller]
2026-07-06 16:46:53.588028 | controller |
2026-07-06 16:46:53.588176 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-07-06 16:46:53.588280 | controller | Monday 06 July 2026  16:46:53 +0000 (0:00:01.114)       0:00:02.689 ***********
2026-07-06 16:46:54.022649 | controller | ok: [controller]
2026-07-06 16:46:54.022675 | controller |
2026-07-06 16:46:54.022682 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-07-06 16:46:54.022688 | controller | Monday 06 July 2026  16:46:54 +0000 (0:00:00.436)       0:00:03.125 ***********
2026-07-06 16:46:54.066755 | controller | ok: [controller] => {
2026-07-06 16:46:54.068200 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-07-06 16:46:54.068533 | controller | }
2026-07-06 16:46:54.068792 | controller |
2026-07-06 16:46:54.069080 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-07-06 16:46:54.069357 | controller | Monday 06 July 2026  16:46:54 +0000 (0:00:00.044)       0:00:03.170 ***********
2026-07-06 16:46:54.664490 | controller | ok: [controller]
2026-07-06 16:46:54.664522 | controller |
2026-07-06 16:46:54.664526 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-07-06 16:46:54.664531 | controller | Monday 06 July 2026  16:46:54 +0000 (0:00:00.593)       0:00:03.764 ***********
2026-07-06 16:46:56.446384 | controller | ok: [controller]
2026-07-06 16:46:56.446459 | controller |
2026-07-06 16:46:56.446472 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-07-06 16:46:56.446481 | controller | Monday 06 July 2026  16:46:56 +0000 (0:00:01.780)       0:00:05.545 ***********
2026-07-06 16:46:56.983444 | controller | ok: [controller]
2026-07-06 16:46:56.983502 | controller |
2026-07-06 16:46:56.983571 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-07-06 16:46:56.983836 | controller | Monday 06 July 2026  16:46:56 +0000 (0:00:00.540)       0:00:06.086 ***********
2026-07-06 16:46:57.403468 | controller | ok: [controller]
2026-07-06 16:46:57.403580 | controller |
2026-07-06 16:46:57.403864 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-07-06 16:46:57.403878 | controller | Monday 06 July 2026  16:46:57 +0000 (0:00:00.419)       0:00:06.505 ***********
2026-07-06 16:46:57.867560 | controller | ok: [controller]
2026-07-06 16:46:57.867634 | controller |
2026-07-06 16:46:57.867736 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-07-06 16:46:57.867829 | controller | Monday 06 July 2026  16:46:57 +0000 (0:00:00.462)       0:00:06.967 ***********
2026-07-06 16:46:58.171250 | controller | ok: [controller]
2026-07-06 16:46:58.171305 | controller |
2026-07-06 16:46:58.171542 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-07-06 16:46:58.171570 | controller | Monday 06 July 2026  16:46:58 +0000 (0:00:00.307)       0:00:07.275 ***********
2026-07-06 16:46:58.480579 | controller | ok: [controller]
2026-07-06 16:46:58.480648 | controller |
2026-07-06 16:46:58.480658 | controller | TASK [Install plugin] **********************************************************
2026-07-06 16:46:58.480666 | controller | Monday 06 July 2026  16:46:58 +0000 (0:00:00.305)       0:00:07.580 ***********
2026-07-06 16:46:58.530357 | controller | included: vexxhost.containers.download_artifact for controller
2026-07-06 16:46:58.530405 | controller |
2026-07-06 16:46:58.530495 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-07-06 16:46:58.530607 | controller | Monday 06 July 2026  16:46:58 +0000 (0:00:00.053)       0:00:07.634 ***********
2026-07-06 16:46:58.578727 | controller | ok: [controller] => {
2026-07-06 16:46:58.578835 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-07-06 16:46:58.578908 | controller | }
2026-07-06 16:46:58.579004 | controller |
2026-07-06 16:46:58.579111 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-07-06 16:46:58.579211 | controller | Monday 06 July 2026  16:46:58 +0000 (0:00:00.048)       0:00:07.682 ***********
2026-07-06 16:46:59.026611 | controller | ok: [controller]
2026-07-06 16:46:59.026738 | controller |
2026-07-06 16:46:59.026864 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-07-06 16:46:59.027047 | controller | Monday 06 July 2026  16:46:59 +0000 (0:00:00.445)       0:00:08.127 ***********
2026-07-06 16:47:00.714344 | controller | ok: [controller]
2026-07-06 16:47:00.714392 | controller |
2026-07-06 16:47:00.714403 | controller | PLAY RECAP *********************************************************************
2026-07-06 16:47:00.714413 | controller | controller                 : ok=16   changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-06 16:47:00.714421 | controller |
2026-07-06 16:47:00.714429 | controller |
2026-07-06 16:47:00.714437 | controller | TASKS RECAP ********************************************************************
2026-07-06 16:47:00.714446 | controller | Monday 06 July 2026  16:47:00 +0000 (0:00:01.685)       0:00:09.813 ***********
2026-07-06 16:47:00.714454 | controller | ===============================================================================
2026-07-06 16:47:00.714461 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.78s
2026-07-06 16:47:00.714469 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.69s
2026-07-06 16:47:00.714477 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.11s
2026-07-06 16:47:00.714484 | controller | Gathering Facts --------------------------------------------------------- 1.11s
2026-07-06 16:47:00.714492 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.59s
2026-07-06 16:47:00.714499 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.54s
2026-07-06 16:47:00.714507 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.46s
2026-07-06 16:47:00.714515 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.45s
2026-07-06 16:47:00.714523 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.45s
2026-07-06 16:47:00.714531 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.44s
2026-07-06 16:47:00.714551 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.42s
2026-07-06 16:47:00.714559 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.31s
2026-07-06 16:47:00.714567 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.31s
2026-07-06 16:47:00.714575 | controller | Install plugin ---------------------------------------------------------- 0.05s
2026-07-06 16:47:00.714582 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-07-06 16:47:00.714590 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s
2026-07-06 16:47:00.834697 | controller | INFO     [helm > idempotence] Executed: Successful
2026-07-06 16:47:00.845893 | controller | INFO     [helm > side_effect] Executing
2026-07-06 16:47:00.846252 | controller | WARNING  [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-06 16:47:00.851700 | controller | INFO     [helm > verify] Executing
2026-07-06 16:47:01.680705 | controller |
2026-07-06 16:47:01.681028 | controller | PLAY [Verify] ******************************************************************
2026-07-06 16:47:01.681266 | controller |
2026-07-06 16:47:01.681520 | controller | TASK [Gathering Facts] *********************************************************
2026-07-06 16:47:01.681769 | controller | Monday 06 July 2026  16:47:01 +0000 (0:00:00.010)       0:00:00.010 ***********
2026-07-06 16:47:02.784334 | 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-06 16:47:02.791799 | controller | ok: [controller]
2026-07-06 16:47:02.791976 | controller |
2026-07-06 16:47:02.792125 | controller | TASK [Get the current Helm version] ********************************************
2026-07-06 16:47:02.792271 | controller | Monday 06 July 2026  16:47:02 +0000 (0:00:01.110)       0:00:01.121 ***********
2026-07-06 16:47:03.235124 | controller | changed: [controller]
2026-07-06 16:47:03.235457 | controller |
2026-07-06 16:47:03.235725 | controller | TASK [Assert Helm version] *****************************************************
2026-07-06 16:47:03.235983 | controller | Monday 06 July 2026  16:47:03 +0000 (0:00:00.442)       0:00:01.564 ***********
2026-07-06 16:47:03.263845 | controller | ok: [controller] => {
2026-07-06 16:47:03.264105 | controller |     "changed": false,
2026-07-06 16:47:03.264354 | controller |     "msg": "All assertions passed"
2026-07-06 16:47:03.264616 | controller | }
2026-07-06 16:47:03.264886 | controller |
2026-07-06 16:47:03.265128 | controller | PLAY RECAP *********************************************************************
2026-07-06 16:47:03.265395 | controller | controller                 : ok=3    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-06 16:47:03.265621 | controller |
2026-07-06 16:47:03.265844 | controller |
2026-07-06 16:47:03.266084 | controller | TASKS RECAP ********************************************************************
2026-07-06 16:47:03.266331 | controller | Monday 06 July 2026  16:47:03 +0000 (0:00:00.029)       0:00:01.594 ***********
2026-07-06 16:47:03.266568 | controller | ===============================================================================
2026-07-06 16:47:03.266809 | controller | Gathering Facts --------------------------------------------------------- 1.11s
2026-07-06 16:47:03.267051 | controller | Get the current Helm version -------------------------------------------- 0.44s
2026-07-06 16:47:03.267292 | controller | Assert Helm version ----------------------------------------------------- 0.03s
2026-07-06 16:47:03.374925 | controller | INFO     [helm > verify] Executed: Successful
2026-07-06 16:47:03.386484 | controller | INFO     [helm > cleanup] Executing
2026-07-06 16:47:03.386835 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-06 16:47:03.392207 | controller | INFO     [helm > destroy] Executing
2026-07-06 16:47:03.392299 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-07-06 16:47:03.392410 | controller | INFO     [helm > destroy] Executed: Successful
2026-07-06 16:47:03.392694 | controller | WARNING  Molecule executed 1 scenario (1 missing files)
2026-07-06 16:47:03.969955 | controller | ok: Runtime: 0:00:52.694105
2026-07-06 16:47:03.974650 | 
2026-07-06 16:47:03.974694 | PLAY RECAP
2026-07-06 16:47:03.974737 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-06 16:47:03.974759 | 
2026-07-06 16:47:04.086947 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-07-06 16:47:04.090105 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-07-06 16:47:04.711979 | 
2026-07-06 16:47:04.712105 | PLAY [all]
2026-07-06 16:47:04.724321 | 
2026-07-06 16:47:04.724404 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-07-06 16:47:05.162462 | controller | changed
2026-07-06 16:47:05.169505 | 
2026-07-06 16:47:05.169665 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-07-06 16:47:05.577633 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-06 16:47:05.577836 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-06 16:47:05.585617 | controller | environment: line 1: kubectl: command not found
2026-07-06 16:47:05.588533 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-06 16:47:05.589818 | controller | environment: line 1: kubectl: command not found
2026-07-06 16:47:05.591954 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-06 16:47:05.593676 | controller | environment: line 1: kubectl: command not found
2026-07-06 16:47:05.595549 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-06 16:47:05.595788 | controller | environment: line 1: kubectl: command not found
2026-07-06 16:47:05.597385 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-06 16:47:05.599440 | controller | environment: line 1: kubectl: command not found
2026-07-06 16:47:05.600762 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-06 16:47:05.701455 | controller | ok: Runtime: 0:00:00.039143
2026-07-06 16:47:05.706226 | 
2026-07-06 16:47:05.706299 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-07-06 16:47:05.952867 | controller | changed
2026-07-06 16:47:05.958845 | 
2026-07-06 16:47:05.958962 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-07-06 16:47:06.221919 | controller | environment: line 5: kubectl: command not found
2026-07-06 16:47:06.222828 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-06 16:47:06.223401 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-06 16:47:06.224064 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-06 16:47:06.490915 | controller | ok: Runtime: 0:00:00.011555
2026-07-06 16:47:06.498754 | 
2026-07-06 16:47:06.498823 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-07-06 16:47:07.172290 | controller | changed:
2026-07-06 16:47:07.172495 | controller | created directory /var/lib/zuul/builds/7cca019674994fa69d20eae2b5b781cf/work/logs/controller
2026-07-06 16:47:07.172536 | controller | cd+++++++++ objects/
2026-07-06 16:47:07.172568 | controller | cd+++++++++ objects/cluster/
2026-07-06 16:47:07.172600 | controller | cd+++++++++ objects/namespaced/
2026-07-06 16:47:07.182397 | 
2026-07-06 16:47:07.182491 | TASK [gather-pod-logs : creating directory for pod logs]
2026-07-06 16:47:07.440776 | controller | changed
2026-07-06 16:47:07.444831 | 
2026-07-06 16:47:07.444897 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-07-06 16:47:07.716721 | controller | changed
2026-07-06 16:47:07.722651 | 
2026-07-06 16:47:07.722728 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-07-06 16:47:07.975346 | controller | environment: line 3: kubectl: command not found
2026-07-06 16:47:08.253684 | controller | ok: Runtime: 0:00:00.014862
2026-07-06 16:47:08.259073 | 
2026-07-06 16:47:08.259142 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-07-06 16:47:08.758492 | controller | changed:
2026-07-06 16:47:08.758808 | controller | cd+++++++++ pod-logs/
2026-07-06 16:47:08.758883 | controller | cd+++++++++ pod-logs/failed-pods/
2026-07-06 16:47:08.768980 | 
2026-07-06 16:47:08.769048 | LOOP [helm-release-status : creating directory for helm release status]
2026-07-06 16:47:09.016233 | controller | changed: "values"
2026-07-06 16:47:09.221895 | controller | changed: "releases"
2026-07-06 16:47:09.242011 | 
2026-07-06 16:47:09.242214 | TASK [helm-release-status : Gather get release status for helm charts]
2026-07-06 16:47:09.484985 | controller | /bin/bash: line 3: kubectl: command not found
2026-07-06 16:47:09.775196 | controller | ok: Runtime: 0:00:00.009434
2026-07-06 16:47:09.781947 | 
2026-07-06 16:47:09.782039 | TASK [helm-release-status : Downloads logs to executor]
2026-07-06 16:47:10.275610 | controller | changed:
2026-07-06 16:47:10.275971 | controller | cd+++++++++ helm/
2026-07-06 16:47:10.276039 | controller | cd+++++++++ helm/releases/
2026-07-06 16:47:10.276097 | controller | cd+++++++++ helm/values/
2026-07-06 16:47:10.283145 | 
2026-07-06 16:47:10.283192 | PLAY RECAP
2026-07-06 16:47:10.283243 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-06 16:47:10.283264 | 
2026-07-06 16:47:10.425991 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-07-06 16:47:10.428841 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-06 16:47:11.055336 | 
2026-07-06 16:47:11.055493 | PLAY [all]
2026-07-06 16:47:11.066254 | 
2026-07-06 16:47:11.066334 | TASK [fetch-output : Set log path for multiple nodes]
2026-07-06 16:47:11.112354 | controller | skipping: Conditional result was False
2026-07-06 16:47:11.120730 | 
2026-07-06 16:47:11.120811 | TASK [fetch-output : Set log path for single node]
2026-07-06 16:47:11.162865 | controller | ok
2026-07-06 16:47:11.168353 | 
2026-07-06 16:47:11.168446 | LOOP [fetch-output : Ensure local output dirs]
2026-07-06 16:47:11.557090 | controller -> localhost | ok: "/var/lib/zuul/builds/7cca019674994fa69d20eae2b5b781cf/work/logs"
2026-07-06 16:47:11.770801 | controller -> localhost | changed: "/var/lib/zuul/builds/7cca019674994fa69d20eae2b5b781cf/work/artifacts"
2026-07-06 16:47:11.996447 | controller -> localhost | changed: "/var/lib/zuul/builds/7cca019674994fa69d20eae2b5b781cf/work/docs"
2026-07-06 16:47:12.018350 | 
2026-07-06 16:47:12.018542 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-07-06 16:47:12.675032 | controller | changed: .d..t...... ./
2026-07-06 16:47:12.675274 | controller | changed: All items complete
2026-07-06 16:47:12.675315 | 
2026-07-06 16:47:13.111919 | controller | changed: .d..t...... ./
2026-07-06 16:47:13.570126 | controller | changed: .d..t...... ./
2026-07-06 16:47:13.592562 | 
2026-07-06 16:47:13.592711 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-07-06 16:47:14.016357 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008191
2026-07-06 16:47:14.270553 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.010580
2026-07-06 16:47:14.290949 | 
2026-07-06 16:47:14.291057 | PLAY [all]
2026-07-06 16:47:14.295892 | 
2026-07-06 16:47:14.295950 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-07-06 16:47:14.754242 | controller | changed
2026-07-06 16:47:14.761246 | 
2026-07-06 16:47:14.761315 | PLAY RECAP
2026-07-06 16:47:14.761375 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-06 16:47:14.761406 | 
2026-07-06 16:47:14.913080 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-06 16:47:14.916247 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-07-06 16:47:15.473342 | 
2026-07-06 16:47:15.473469 | PLAY [localhost]
2026-07-06 16:47:15.483363 | 
2026-07-06 16:47:15.483434 | TASK [Generate Zuul manifest]
2026-07-06 16:47:15.504894 | localhost | ok
2026-07-06 16:47:15.519807 | 
2026-07-06 16:47:15.519934 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-07-06 16:47:15.917729 | localhost | changed
2026-07-06 16:47:15.931674 | 
2026-07-06 16:47:15.931764 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-07-06 16:47:15.964682 | localhost | ok
2026-07-06 16:47:15.975267 | 
2026-07-06 16:47:15.975340 | TASK [Upload logs]
2026-07-06 16:47:15.997499 | localhost | ok
2026-07-06 16:47:16.060372 | 
2026-07-06 16:47:16.060537 | TASK [Set zuul-log-path fact]
2026-07-06 16:47:16.082456 | localhost | ok
2026-07-06 16:47:16.095865 | 
2026-07-06 16:47:16.095932 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-06 16:47:16.124698 | localhost | ok
2026-07-06 16:47:16.133867 | 
2026-07-06 16:47:16.133967 | TASK [upload-logs : Create log directories]
2026-07-06 16:47:16.542560 | localhost | changed
2026-07-06 16:47:16.549521 | 
2026-07-06 16:47:16.549648 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-07-06 16:47:16.970598 | localhost -> localhost | ok: Runtime: 0:00:00.006420
2026-07-06 16:47:16.975708 | 
2026-07-06 16:47:16.975780 | TASK [upload-logs : Upload logs to log server]
2026-07-06 16:47:17.451599 | localhost | Output suppressed because no_log was given
2026-07-06 16:47:17.456722 | 
2026-07-06 16:47:17.456826 | LOOP [upload-logs : Compress console log and json output]
2026-07-06 16:47:17.504191 | localhost | skipping: Conditional result was False
2026-07-06 16:47:17.549396 | localhost | skipping: Conditional result was False
2026-07-06 16:47:17.563142 | 
2026-07-06 16:47:17.563334 | LOOP [upload-logs : Upload compressed console log and json output]
2026-07-06 16:47:17.606042 | localhost | skipping: Conditional result was False
2026-07-06 16:47:17.606525 | 
2026-07-06 16:47:17.610107 | localhost | skipping: Conditional result was False
2026-07-06 16:47:17.624792 | 
2026-07-06 16:47:17.625008 | LOOP [upload-logs : Upload console log and json output]