2026-07-06 14:11:37.497118 | Job console starting
2026-07-06 14:11:37.511473 | Updating git repos
2026-07-06 14:11:37.587550 | Cloning repos into workspace
2026-07-06 14:11:38.264757 | Restoring repo states
2026-07-06 14:11:38.277620 | Merging changes
2026-07-06 14:11:39.096475 | Checking out repos
2026-07-06 14:11:39.559945 | Preparing playbooks
2026-07-06 14:11:46.462686 | Running Ansible setup
2026-07-06 14:11:50.374812 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-06 14:11:51.103894 | 
2026-07-06 14:11:51.104094 | PLAY [localhost]
2026-07-06 14:11:51.115321 | 
2026-07-06 14:11:51.115477 | TASK [Gathering Facts]
2026-07-06 14:11:52.090987 | localhost | ok
2026-07-06 14:11:52.105646 | 
2026-07-06 14:11:52.105818 | TASK [Setup log path fact]
2026-07-06 14:11:52.137592 | localhost | ok
2026-07-06 14:11:52.155065 | 
2026-07-06 14:11:52.155216 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-06 14:11:52.197867 | localhost | ok
2026-07-06 14:11:52.204883 | 
2026-07-06 14:11:52.204989 | TASK [emit-job-header : Print job information]
2026-07-06 14:11:52.245259 | # Job Information
2026-07-06 14:11:52.245474 | Ansible Version: 2.16.18
2026-07-06 14:11:52.245531 | Job: ansible-collection-kubernetes-molecule-helm
2026-07-06 14:11:52.245566 | Pipeline: check
2026-07-06 14:11:52.245596 | Executor: 2d72f0692154
2026-07-06 14:11:52.245626 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/288
2026-07-06 14:11:52.245659 | Event ID: 72a6d830-7944-11f1-9e22-7735b3cab6a8
2026-07-06 14:11:52.249428 | 
2026-07-06 14:11:52.249499 | LOOP [emit-job-header : Print node information]
2026-07-06 14:11:52.331937 | localhost | ok:
2026-07-06 14:11:52.332103 | localhost | # Node Information
2026-07-06 14:11:52.332137 | localhost | Inventory Hostname: controller
2026-07-06 14:11:52.332165 | localhost | Hostname: np0000184187
2026-07-06 14:11:52.332189 | localhost | Username: zuul
2026-07-06 14:11:52.332217 | localhost | Distro: Debian 13.5
2026-07-06 14:11:52.332242 | localhost | Provider: yul1
2026-07-06 14:11:52.332265 | localhost | Region: ca-ymq-1
2026-07-06 14:11:52.332288 | localhost | Label: debian-trixie
2026-07-06 14:11:52.332309 | localhost | Product Name: OpenStack Nova
2026-07-06 14:11:52.332327 | localhost | Interface IP: 199.19.213.184
2026-07-06 14:11:52.347024 | 
2026-07-06 14:11:52.347225 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-07-06 14:11:52.870576 | localhost -> localhost | changed
2026-07-06 14:11:52.878505 | 
2026-07-06 14:11:52.878685 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-07-06 14:11:54.105848 | localhost -> localhost | changed
2026-07-06 14:11:54.120754 | 
2026-07-06 14:11:54.120845 | PLAY [all]
2026-07-06 14:11:54.127544 | 
2026-07-06 14:11:54.127622 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-07-06 14:11:54.365248 | controller -> localhost | ok
2026-07-06 14:11:54.373684 | 
2026-07-06 14:11:54.373803 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-07-06 14:11:54.406559 | controller | ok
2026-07-06 14:11:54.423789 | controller | included: /var/lib/zuul/builds/73c8991e4dd84a2f99cf15c7443b9c56/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-07-06 14:11:54.428441 | 
2026-07-06 14:11:54.428522 | TASK [add-build-sshkey : Create Temp SSH key]
2026-07-06 14:11:56.209149 | controller -> localhost | Generating public/private rsa key pair.
2026-07-06 14:11:56.209425 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/73c8991e4dd84a2f99cf15c7443b9c56/work/73c8991e4dd84a2f99cf15c7443b9c56_id_rsa
2026-07-06 14:11:56.209469 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/73c8991e4dd84a2f99cf15c7443b9c56/work/73c8991e4dd84a2f99cf15c7443b9c56_id_rsa.pub
2026-07-06 14:11:56.209503 | controller -> localhost | The key fingerprint is:
2026-07-06 14:11:56.209535 | controller -> localhost | SHA256:VsLK09iyQdQW0pltCJZ8ghIrYVEn/NSvEjqCTTIA9IY zuul-build-sshkey
2026-07-06 14:11:56.209586 | controller -> localhost | The key's randomart image is:
2026-07-06 14:11:56.209619 | controller -> localhost | +---[RSA 3072]----+
2026-07-06 14:11:56.209662 | controller -> localhost | |=+=+ +*=.*       |
2026-07-06 14:11:56.209695 | controller -> localhost | |o.+o++++O o      |
2026-07-06 14:11:56.209725 | controller -> localhost | |oE.+o .++..      |
2026-07-06 14:11:56.209753 | controller -> localhost | |o.o  = = +       |
2026-07-06 14:11:56.209780 | controller -> localhost | |.=  . O S        |
2026-07-06 14:11:56.209807 | controller -> localhost | |...o . B         |
2026-07-06 14:11:56.209834 | controller -> localhost | |  . . o          |
2026-07-06 14:11:56.209860 | controller -> localhost | |                 |
2026-07-06 14:11:56.209891 | controller -> localhost | |                 |
2026-07-06 14:11:56.209918 | controller -> localhost | +----[SHA256]-----+
2026-07-06 14:11:56.209982 | controller -> localhost | ok: Runtime: 0:00:01.342671
2026-07-06 14:11:56.215572 | 
2026-07-06 14:11:56.215660 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-07-06 14:11:56.248486 | controller | ok
2026-07-06 14:11:56.259260 | controller | included: /var/lib/zuul/builds/73c8991e4dd84a2f99cf15c7443b9c56/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-07-06 14:11:56.275557 | 
2026-07-06 14:11:56.275729 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-07-06 14:11:56.301049 | controller | skipping: Conditional result was False
2026-07-06 14:11:56.309272 | 
2026-07-06 14:11:56.309374 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-07-06 14:11:57.012238 | controller | changed
2026-07-06 14:11:57.019800 | 
2026-07-06 14:11:57.019898 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-07-06 14:11:57.261684 | controller | ok
2026-07-06 14:11:57.265980 | 
2026-07-06 14:11:57.266072 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-07-06 14:11:57.825716 | controller | changed
2026-07-06 14:11:57.834168 | 
2026-07-06 14:11:57.834237 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-07-06 14:11:58.408587 | controller | changed
2026-07-06 14:11:58.414162 | 
2026-07-06 14:11:58.414253 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-07-06 14:11:58.439749 | controller | skipping: Conditional result was False
2026-07-06 14:11:58.448051 | 
2026-07-06 14:11:58.448209 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-07-06 14:11:58.871017 | controller -> localhost | changed
2026-07-06 14:11:58.888818 | 
2026-07-06 14:11:58.888936 | TASK [add-build-sshkey : Add back temp key]
2026-07-06 14:11:59.168911 | controller -> localhost | Identity added: /var/lib/zuul/builds/73c8991e4dd84a2f99cf15c7443b9c56/work/73c8991e4dd84a2f99cf15c7443b9c56_id_rsa (zuul-build-sshkey)
2026-07-06 14:11:59.169194 | controller -> localhost | ok: Runtime: 0:00:00.016569
2026-07-06 14:11:59.174225 | 
2026-07-06 14:11:59.174301 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-07-06 14:11:59.531974 | controller | ok
2026-07-06 14:11:59.537889 | 
2026-07-06 14:11:59.538016 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-07-06 14:11:59.564905 | controller | skipping: Conditional result was False
2026-07-06 14:11:59.580973 | 
2026-07-06 14:11:59.581103 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-07-06 14:11:59.966325 | controller | ok
2026-07-06 14:11:59.971711 | 
2026-07-06 14:11:59.971794 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-07-06 14:12:01.020914 | controller | Output suppressed because no_log was given
2026-07-06 14:12:01.029818 | 
2026-07-06 14:12:01.029906 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-07-06 14:12:01.270887 | controller | ok: "logs"
2026-07-06 14:12:01.271342 | controller | ok: All items complete
2026-07-06 14:12:01.271378 | 
2026-07-06 14:12:01.484057 | controller | ok: "artifacts"
2026-07-06 14:12:01.683735 | controller | ok: "docs"
2026-07-06 14:12:01.695930 | 
2026-07-06 14:12:01.696101 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-07-06 14:12:01.925831 | controller | changed: "logs"
2026-07-06 14:12:02.114907 | controller | changed: "artifacts"
2026-07-06 14:12:02.310265 | controller | changed: "docs"
2026-07-06 14:12:02.319396 | 
2026-07-06 14:12:02.319550 | PLAY RECAP
2026-07-06 14:12:02.319618 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-07-06 14:12:02.319659 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-06 14:12:02.319692 | 
2026-07-06 14:12:02.509936 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-06 14:12:02.513372 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-06 14:12:03.197980 | 
2026-07-06 14:12:03.198153 | PLAY [all]
2026-07-06 14:12:03.209099 | 
2026-07-06 14:12:03.209212 | TASK [setup-uv : Extract archive]
2026-07-06 14:12:06.060665 | controller | changed
2026-07-06 14:12:06.068284 | 
2026-07-06 14:12:06.068438 | TASK [setup-uv : Print version]
2026-07-06 14:12:06.500714 | controller | uv 0.8.13
2026-07-06 14:12:06.603997 | controller | ok: Runtime: 0:00:00.014126
2026-07-06 14:12:06.615039 | 
2026-07-06 14:12:06.615150 | TASK [Install Ansible collection siblings]
2026-07-06 14:12:07.046639 | controller | ok:
2026-07-06 14:12:07.047013 | controller | Skipping ansible.posix: no checked-out sibling
2026-07-06 14:12:07.047116 | controller | Skipping community.crypto: no checked-out sibling
2026-07-06 14:12:07.047188 | controller | Skipping community.general: no checked-out sibling
2026-07-06 14:12:07.047255 | controller | Skipping kubernetes.core: no checked-out sibling
2026-07-06 14:12:07.047320 | controller | Skipping vexxhost.containers: no checked-out sibling
2026-07-06 14:12:07.050819 | 
2026-07-06 14:12:07.050873 | PLAY RECAP
2026-07-06 14:12:07.050925 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-06 14:12:07.050947 | 
2026-07-06 14:12:07.230869 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-06 14:12:07.233685 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-07-06 14:12:07.834524 | 
2026-07-06 14:12:07.834648 | PLAY [all]
2026-07-06 14:12:07.844307 | 
2026-07-06 14:12:07.844383 | TASK [Update apt cache]
2026-07-06 14:12:14.017710 | controller | ok
2026-07-06 14:12:14.023668 | 
2026-07-06 14:12:14.023866 | TASK [Disable firewalld]
2026-07-06 14:12:14.049844 | controller | skipping: Conditional result was False
2026-07-06 14:12:14.056205 | 
2026-07-06 14:12:14.056347 | TASK [Install "jq" for log collection]
2026-07-06 14:12:16.645089 | controller | changed
2026-07-06 14:12:16.647576 | 
2026-07-06 14:12:16.647644 | PLAY RECAP
2026-07-06 14:12:16.647743 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-06 14:12:16.647778 | 
2026-07-06 14:12:16.792740 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-07-06 14:12:16.795897 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-07-06 14:12:17.438305 | 
2026-07-06 14:12:17.438443 | PLAY [all]
2026-07-06 14:12:17.454893 | 
2026-07-06 14:12:17.454993 | LOOP [Check inventory directories]
2026-07-06 14:12:17.871346 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-07-06 14:12:18.048572 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/helm/inventory"
2026-07-06 14:12:18.234317 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/inventory"
2026-07-06 14:12:18.241003 | 
2026-07-06 14:12:18.241123 | LOOP [Copy inventory file for Zuul]
2026-07-06 14:12:19.123135 | controller | changed:
2026-07-06 14:12:19.123337 | controller | {
2026-07-06 14:12:19.123365 | controller |   "ansible_loop_var": "item",
2026-07-06 14:12:19.123389 | controller |   "changed": false,
2026-07-06 14:12:19.123410 | controller |   "failed": false,
2026-07-06 14:12:19.123432 | controller |   "invocation": {
2026-07-06 14:12:19.123451 | controller |     "module_args": {
2026-07-06 14:12:19.123469 | controller |       "checksum_algorithm": "sha1",
2026-07-06 14:12:19.123488 | controller |       "follow": true,
2026-07-06 14:12:19.123506 | controller |       "get_attributes": true,
2026-07-06 14:12:19.123524 | controller |       "get_checksum": true,
2026-07-06 14:12:19.123542 | controller |       "get_mime": true,
2026-07-06 14:12:19.123560 | controller |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-07-06 14:12:19.123577 | controller |     }
2026-07-06 14:12:19.123594 | controller |   },
2026-07-06 14:12:19.123611 | controller |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-07-06 14:12:19.123628 | controller |   "stat": {
2026-07-06 14:12:19.123645 | controller |     "atime": 1783347120.9652116,
2026-07-06 14:12:19.123662 | controller |     "attr_flags": "e",
2026-07-06 14:12:19.123679 | controller |     "attributes": [
2026-07-06 14:12:19.123696 | controller |       "extents"
2026-07-06 14:12:19.123713 | controller |     ],
2026-07-06 14:12:19.123730 | controller |     "block_size": 4096,
2026-07-06 14:12:19.123747 | controller |     "blocks": 8,
2026-07-06 14:12:19.123764 | controller |     "charset": "unknown",
2026-07-06 14:12:19.123782 | controller |     "ctime": 1783347120.9889069,
2026-07-06 14:12:19.123800 | controller |     "dev": 65025,
2026-07-06 14:12:19.123818 | controller |     "device_type": 0,
2026-07-06 14:12:19.123837 | controller |     "executable": true,
2026-07-06 14:12:19.123854 | controller |     "exists": true,
2026-07-06 14:12:19.123872 | controller |     "gid": 1000,
2026-07-06 14:12:19.123889 | controller |     "gr_name": "zuul",
2026-07-06 14:12:19.123906 | controller |     "inode": 520215,
2026-07-06 14:12:19.123924 | controller |     "isblk": false,
2026-07-06 14:12:19.123942 | controller |     "ischr": false,
2026-07-06 14:12:19.123960 | controller |     "isdir": true,
2026-07-06 14:12:19.123978 | controller |     "isfifo": false,
2026-07-06 14:12:19.123995 | controller |     "isgid": false,
2026-07-06 14:12:19.124013 | controller |     "islnk": false,
2026-07-06 14:12:19.124030 | controller |     "isreg": false,
2026-07-06 14:12:19.124047 | controller |     "issock": false,
2026-07-06 14:12:19.124065 | controller |     "isuid": false,
2026-07-06 14:12:19.124082 | controller |     "mimetype": "unknown",
2026-07-06 14:12:19.124099 | controller |     "mode": "0755",
2026-07-06 14:12:19.124116 | controller |     "mtime": 1783347098.4267173,
2026-07-06 14:12:19.124132 | controller |     "nlink": 14,
2026-07-06 14:12:19.124149 | controller |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-07-06 14:12:19.124166 | controller |     "pw_name": "zuul",
2026-07-06 14:12:19.124186 | controller |     "readable": true,
2026-07-06 14:12:19.124204 | controller |     "rgrp": true,
2026-07-06 14:12:19.124221 | controller |     "roth": true,
2026-07-06 14:12:19.124239 | controller |     "rusr": true,
2026-07-06 14:12:19.124256 | controller |     "size": 4096,
2026-07-06 14:12:19.124273 | controller |     "uid": 1000,
2026-07-06 14:12:19.124290 | controller |     "version": "1799363263",
2026-07-06 14:12:19.124307 | controller |     "wgrp": false,
2026-07-06 14:12:19.124326 | controller |     "woth": false,
2026-07-06 14:12:19.124344 | controller |     "writeable": true,
2026-07-06 14:12:19.124361 | controller |     "wusr": true,
2026-07-06 14:12:19.124378 | controller |     "xgrp": true,
2026-07-06 14:12:19.124395 | controller |     "xoth": true,
2026-07-06 14:12:19.124412 | controller |     "xusr": true
2026-07-06 14:12:19.124429 | controller |   }
2026-07-06 14:12:19.124461 | controller | }
2026-07-06 14:12:19.124497 | controller | ok: All items complete
2026-07-06 14:12:19.124519 | 
2026-07-06 14:12:19.125854 | controller | skipping: Conditional result was False
2026-07-06 14:12:19.128308 | controller | skipping: Conditional result was False
2026-07-06 14:12:19.142252 | 
2026-07-06 14:12:19.142439 | LOOP [Switch "ansible_host" to private IP]
2026-07-06 14:12:19.527619 | controller | changed: 1 replacements made
2026-07-06 14:12:19.531602 | controller | skipping: Conditional result was False
2026-07-06 14:12:19.535837 | controller | skipping: Conditional result was False
2026-07-06 14:12:19.547803 | 
2026-07-06 14:12:19.547984 | TASK [Run Molecule scenario]
2026-07-06 14:12:19.994433 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-07-06 14:12:19.994552 | controller | Creating virtual environment at: .venv
2026-07-06 14:12:20.396294 | controller | Downloading pygments (1.2MiB)
2026-07-06 14:12:20.397477 | controller | Downloading ansible-core (2.3MiB)
2026-07-06 14:12:20.397704 | controller | Downloading cryptography (4.3MiB)
2026-07-06 14:12:20.546688 | controller |  Downloading cryptography
2026-07-06 14:12:20.596996 | controller |  Downloading pygments
2026-07-06 14:12:20.760030 | controller |  Downloading ansible-core
2026-07-06 14:12:20.805972 | controller | Installed 36 packages in 45ms
2026-07-06 14:12:21.521213 | controller | INFO     Collection 'vexxhost.kubernetes' detected.
2026-07-06 14:12:21.521277 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-07-06 14:12:22.414504 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-07-06 14:12:22.414574 | controller | INFO     default scenario not found, disabling shared state.
2026-07-06 14:12:22.414636 | controller | INFO     [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-07-06 14:12:22.414737 | controller | INFO     [helm > prerun] Performing prerun with role_name_check=0...
2026-07-06 14:12:50.342254 | controller | INFO     [helm > dependency] Executing
2026-07-06 14:12:50.342478 | controller | WARNING  [helm > dependency] Missing roles requirements file: requirements.yml
2026-07-06 14:12:50.342846 | controller | WARNING  [helm > dependency] Missing collections requirements file: collections.yml
2026-07-06 14:12:50.343028 | controller | WARNING  [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-07-06 14:12:50.348942 | controller | INFO     [helm > cleanup] Executing
2026-07-06 14:12:50.349230 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-06 14:12:50.428545 | controller | INFO     [helm > destroy] Executing
2026-07-06 14:12:50.428621 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-07-06 14:12:50.428731 | controller | INFO     [helm > destroy] Executed: Successful
2026-07-06 14:12:50.435096 | controller | INFO     [helm > syntax] Executing
2026-07-06 14:12:51.351234 | controller |
2026-07-06 14:12:51.351435 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml
2026-07-06 14:12:51.430163 | controller | INFO     [helm > syntax] Executed: Successful
2026-07-06 14:12:51.441275 | controller | INFO     [helm > create] Executing
2026-07-06 14:12:51.443436 | controller | WARNING  [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-06 14:12:51.449357 | controller | INFO     [helm > prepare] Executing
2026-07-06 14:12:52.331863 | controller |
2026-07-06 14:12:52.331961 | controller | PLAY [Prepare] *****************************************************************
2026-07-06 14:12:52.332086 | controller |
2026-07-06 14:12:52.332219 | controller | TASK [Gathering Facts] *********************************************************
2026-07-06 14:12:52.332347 | controller | Monday 06 July 2026  14:12:52 +0000 (0:00:00.031)       0:00:00.031 ***********
2026-07-06 14:12:53.552692 | 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 14:12:53.558325 | controller | ok: [controller]
2026-07-06 14:12:53.558453 | controller |
2026-07-06 14:12:53.558600 | controller | TASK [Install Debian packages] *************************************************
2026-07-06 14:12:53.558729 | controller | Monday 06 July 2026  14:12:53 +0000 (0:00:01.227)       0:00:01.258 ***********
2026-07-06 14:12:55.098647 | controller | ok: [controller]
2026-07-06 14:12:55.098805 | controller |
2026-07-06 14:12:55.099012 | controller | PLAY RECAP *********************************************************************
2026-07-06 14:12:55.099232 | controller | controller                 : ok=2    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-06 14:12:55.099411 | controller |
2026-07-06 14:12:55.099593 | controller |
2026-07-06 14:12:55.099900 | controller | TASKS RECAP ********************************************************************
2026-07-06 14:12:55.100100 | controller | Monday 06 July 2026  14:12:55 +0000 (0:00:01.540)       0:00:02.799 ***********
2026-07-06 14:12:55.100288 | controller | ===============================================================================
2026-07-06 14:12:55.100492 | controller | Install Debian packages ------------------------------------------------- 1.54s
2026-07-06 14:12:55.100699 | controller | Gathering Facts --------------------------------------------------------- 1.23s
2026-07-06 14:12:55.183613 | controller | INFO     [helm > prepare] Executed: Successful
2026-07-06 14:12:55.192192 | controller | INFO     [helm > converge] Executing
2026-07-06 14:12:56.055015 | controller |
2026-07-06 14:12:56.055195 | controller | PLAY [Converge] ****************************************************************
2026-07-06 14:12:56.055373 | controller |
2026-07-06 14:12:56.055574 | controller | TASK [Gathering Facts] *********************************************************
2026-07-06 14:12:56.055766 | controller | Monday 06 July 2026  14:12:56 +0000 (0:00:00.014)       0:00:00.014 ***********
2026-07-06 14:12:57.100471 | 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 14:12:57.105104 | controller | ok: [controller]
2026-07-06 14:12:57.105203 | controller |
2026-07-06 14:12:57.105336 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-07-06 14:12:57.107854 | controller | Monday 06 July 2026  14:12:57 +0000 (0:00:01.049)       0:00:01.064 ***********
2026-07-06 14:12:57.580427 | controller | ok: [controller]
2026-07-06 14:12:57.580447 | controller |
2026-07-06 14:12:57.580451 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-07-06 14:12:57.580455 | controller | Monday 06 July 2026  14:12:57 +0000 (0:00:00.473)       0:00:01.538 ***********
2026-07-06 14:12:58.560076 | controller | ok: [controller]
2026-07-06 14:12:58.560375 | controller |
2026-07-06 14:12:58.560659 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-07-06 14:12:58.560926 | controller | Monday 06 July 2026  14:12:58 +0000 (0:00:00.980)       0:00:02.518 ***********
2026-07-06 14:12:58.984402 | controller | changed: [controller]
2026-07-06 14:12:58.984421 | controller |
2026-07-06 14:12:58.984426 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-07-06 14:12:58.984430 | controller | Monday 06 July 2026  14:12:58 +0000 (0:00:00.421)       0:00:02.940 ***********
2026-07-06 14:12:59.027466 | controller | ok: [controller] => {
2026-07-06 14:12:59.027491 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-07-06 14:12:59.027496 | controller | }
2026-07-06 14:12:59.027502 | controller |
2026-07-06 14:12:59.027507 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-07-06 14:12:59.027512 | controller | Monday 06 July 2026  14:12:59 +0000 (0:00:00.044)       0:00:02.985 ***********
2026-07-06 14:12:59.904513 | 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 14:12:59.912180 | controller | changed: [controller]
2026-07-06 14:12:59.912361 | controller |
2026-07-06 14:12:59.912574 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-07-06 14:12:59.912772 | controller | Monday 06 July 2026  14:12:59 +0000 (0:00:00.885)       0:00:03.870 ***********
2026-07-06 14:13:01.983743 | controller | changed: [controller]
2026-07-06 14:13:01.983830 | controller |
2026-07-06 14:13:01.983876 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-07-06 14:13:01.983982 | controller | Monday 06 July 2026  14:13:01 +0000 (0:00:02.068)       0:00:05.939 ***********
2026-07-06 14:13:02.461036 | controller | ok: [controller]
2026-07-06 14:13:02.461195 | controller |
2026-07-06 14:13:02.461406 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-07-06 14:13:02.461614 | controller | Monday 06 July 2026  14:13:02 +0000 (0:00:00.480)       0:00:06.419 ***********
2026-07-06 14:13:02.854202 | controller | changed: [controller]
2026-07-06 14:13:02.854275 | controller |
2026-07-06 14:13:02.854395 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-07-06 14:13:02.854507 | controller | Monday 06 July 2026  14:13:02 +0000 (0:00:00.393)       0:00:06.813 ***********
2026-07-06 14:13:03.314045 | controller | ok: [controller]
2026-07-06 14:13:03.314195 | controller |
2026-07-06 14:13:03.314420 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-07-06 14:13:03.314621 | controller | Monday 06 July 2026  14:13:03 +0000 (0:00:00.459)       0:00:07.272 ***********
2026-07-06 14:13:03.613007 | controller | changed: [controller]
2026-07-06 14:13:03.613180 | controller |
2026-07-06 14:13:03.613450 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-07-06 14:13:03.613626 | controller | Monday 06 July 2026  14:13:03 +0000 (0:00:00.298)       0:00:07.571 ***********
2026-07-06 14:13:03.927150 | controller | ok: [controller]
2026-07-06 14:13:03.927291 | controller |
2026-07-06 14:13:03.927502 | controller | TASK [Install plugin] **********************************************************
2026-07-06 14:13:03.927706 | controller | Monday 06 July 2026  14:13:03 +0000 (0:00:00.314)       0:00:07.886 ***********
2026-07-06 14:13:03.978790 | controller | included: vexxhost.containers.download_artifact for controller
2026-07-06 14:13:03.978921 | controller |
2026-07-06 14:13:03.979082 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-07-06 14:13:03.979266 | controller | Monday 06 July 2026  14:13:03 +0000 (0:00:00.051)       0:00:07.937 ***********
2026-07-06 14:13:04.025148 | controller | ok: [controller] => {
2026-07-06 14:13:04.025390 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-07-06 14:13:04.025579 | controller | }
2026-07-06 14:13:04.025748 | controller |
2026-07-06 14:13:04.025946 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-07-06 14:13:04.026143 | controller | Monday 06 July 2026  14:13:04 +0000 (0:00:00.045)       0:00:07.983 ***********
2026-07-06 14:13:04.861855 | controller | changed: [controller]
2026-07-06 14:13:04.861985 | controller |
2026-07-06 14:13:04.862193 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-07-06 14:13:04.862389 | controller | Monday 06 July 2026  14:13:04 +0000 (0:00:00.837)       0:00:08.820 ***********
2026-07-06 14:13:06.919914 | controller | changed: [controller]
2026-07-06 14:13:06.919960 | controller |
2026-07-06 14:13:06.919970 | controller | PLAY RECAP *********************************************************************
2026-07-06 14:13:06.919980 | controller | controller                 : ok=16   changed=7    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-06 14:13:06.919988 | controller |
2026-07-06 14:13:06.919996 | controller |
2026-07-06 14:13:06.920003 | controller | TASKS RECAP ********************************************************************
2026-07-06 14:13:06.920010 | controller | Monday 06 July 2026  14:13:06 +0000 (0:00:02.053)       0:00:10.873 ***********
2026-07-06 14:13:06.920018 | controller | ===============================================================================
2026-07-06 14:13:06.920025 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.07s
2026-07-06 14:13:06.920032 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.05s
2026-07-06 14:13:06.920039 | controller | Gathering Facts --------------------------------------------------------- 1.05s
2026-07-06 14:13:06.920046 | controller | vexxhost.containers.package : Update state for tar ---------------------- 0.98s
2026-07-06 14:13:06.920053 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.89s
2026-07-06 14:13:06.920061 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.84s
2026-07-06 14:13:06.920068 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.48s
2026-07-06 14:13:06.920074 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.47s
2026-07-06 14:13:06.920082 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.46s
2026-07-06 14:13:06.920089 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.42s
2026-07-06 14:13:06.920108 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.39s
2026-07-06 14:13:06.920116 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.31s
2026-07-06 14:13:06.920123 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.30s
2026-07-06 14:13:06.920138 | controller | Install plugin ---------------------------------------------------------- 0.05s
2026-07-06 14:13:06.920146 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-07-06 14:13:06.920156 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s
2026-07-06 14:13:07.015965 | controller | INFO     [helm > converge] Executed: Successful
2026-07-06 14:13:07.025425 | controller | INFO     [helm > idempotence] Executing
2026-07-06 14:13:07.867600 | controller |
2026-07-06 14:13:07.867846 | controller | PLAY [Converge] ****************************************************************
2026-07-06 14:13:07.868029 | controller |
2026-07-06 14:13:07.868305 | controller | TASK [Gathering Facts] *********************************************************
2026-07-06 14:13:07.868517 | controller | Monday 06 July 2026  14:13:07 +0000 (0:00:00.013)       0:00:00.013 ***********
2026-07-06 14:13:09.936261 | 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 14:13:09.940394 | controller | ok: [controller]
2026-07-06 14:13:09.940470 | controller |
2026-07-06 14:13:09.940556 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-07-06 14:13:09.940644 | controller | Monday 06 July 2026  14:13:09 +0000 (0:00:02.073)       0:00:02.086 ***********
2026-07-06 14:13:10.386604 | controller | ok: [controller]
2026-07-06 14:13:10.386799 | controller |
2026-07-06 14:13:10.387020 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-07-06 14:13:10.387270 | controller | Monday 06 July 2026  14:13:10 +0000 (0:00:00.446)       0:00:02.532 ***********
2026-07-06 14:13:11.490275 | controller | ok: [controller]
2026-07-06 14:13:11.490455 | controller |
2026-07-06 14:13:11.490685 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-07-06 14:13:11.490916 | controller | Monday 06 July 2026  14:13:11 +0000 (0:00:01.103)       0:00:03.635 ***********
2026-07-06 14:13:11.922800 | controller | ok: [controller]
2026-07-06 14:13:11.923025 | controller |
2026-07-06 14:13:11.923245 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-07-06 14:13:11.923448 | controller | Monday 06 July 2026  14:13:11 +0000 (0:00:00.432)       0:00:04.068 ***********
2026-07-06 14:13:11.967089 | controller | ok: [controller] => {
2026-07-06 14:13:11.967294 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-07-06 14:13:11.967488 | controller | }
2026-07-06 14:13:11.967667 | controller |
2026-07-06 14:13:11.968004 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-07-06 14:13:11.968221 | controller | Monday 06 July 2026  14:13:11 +0000 (0:00:00.044)       0:00:04.112 ***********
2026-07-06 14:13:12.492766 | controller | ok: [controller]
2026-07-06 14:13:12.493077 | controller |
2026-07-06 14:13:12.493379 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-07-06 14:13:12.493670 | controller | Monday 06 July 2026  14:13:12 +0000 (0:00:00.525)       0:00:04.637 ***********
2026-07-06 14:13:14.180243 | controller | ok: [controller]
2026-07-06 14:13:14.180510 | controller |
2026-07-06 14:13:14.180814 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-07-06 14:13:14.181104 | controller | Monday 06 July 2026  14:13:14 +0000 (0:00:01.685)       0:00:06.323 ***********
2026-07-06 14:13:14.687250 | controller | ok: [controller]
2026-07-06 14:13:14.687322 | controller |
2026-07-06 14:13:14.687475 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-07-06 14:13:14.687603 | controller | Monday 06 July 2026  14:13:14 +0000 (0:00:00.506)       0:00:06.830 ***********
2026-07-06 14:13:15.091678 | controller | ok: [controller]
2026-07-06 14:13:15.095352 | controller |
2026-07-06 14:13:15.095372 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-07-06 14:13:15.095381 | controller | Monday 06 July 2026  14:13:15 +0000 (0:00:00.407)       0:00:07.237 ***********
2026-07-06 14:13:15.564196 | controller | ok: [controller]
2026-07-06 14:13:15.564407 | controller |
2026-07-06 14:13:15.564592 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-07-06 14:13:15.564758 | controller | Monday 06 July 2026  14:13:15 +0000 (0:00:00.470)       0:00:07.707 ***********
2026-07-06 14:13:15.868254 | controller | ok: [controller]
2026-07-06 14:13:15.868316 | controller |
2026-07-06 14:13:15.868453 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-07-06 14:13:15.868581 | controller | Monday 06 July 2026  14:13:15 +0000 (0:00:00.304)       0:00:08.012 ***********
2026-07-06 14:13:16.159319 | controller | ok: [controller]
2026-07-06 14:13:16.159371 | controller |
2026-07-06 14:13:16.159887 | controller | TASK [Install plugin] **********************************************************
2026-07-06 14:13:16.159912 | controller | Monday 06 July 2026  14:13:16 +0000 (0:00:00.292)       0:00:08.304 ***********
2026-07-06 14:13:16.216224 | controller | included: vexxhost.containers.download_artifact for controller
2026-07-06 14:13:16.216274 | controller |
2026-07-06 14:13:16.218136 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-07-06 14:13:16.218165 | controller | Monday 06 July 2026  14:13:16 +0000 (0:00:00.056)       0:00:08.360 ***********
2026-07-06 14:13:16.259187 | controller | ok: [controller] => {
2026-07-06 14:13:16.260195 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-07-06 14:13:16.260470 | controller | }
2026-07-06 14:13:16.260879 | controller |
2026-07-06 14:13:16.261136 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-07-06 14:13:16.261384 | controller | Monday 06 July 2026  14:13:16 +0000 (0:00:00.043)       0:00:08.404 ***********
2026-07-06 14:13:16.707757 | controller | ok: [controller]
2026-07-06 14:13:16.707842 | controller |
2026-07-06 14:13:16.707856 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-07-06 14:13:16.707863 | controller | Monday 06 July 2026  14:13:16 +0000 (0:00:00.445)       0:00:08.850 ***********
2026-07-06 14:13:19.318648 | controller | ok: [controller]
2026-07-06 14:13:19.318687 | controller |
2026-07-06 14:13:19.318697 | controller | PLAY RECAP *********************************************************************
2026-07-06 14:13:19.318706 | controller | controller                 : ok=16   changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-06 14:13:19.318714 | controller |
2026-07-06 14:13:19.318721 | controller |
2026-07-06 14:13:19.318728 | controller | TASKS RECAP ********************************************************************
2026-07-06 14:13:19.318736 | controller | Monday 06 July 2026  14:13:19 +0000 (0:00:02.609)       0:00:11.459 ***********
2026-07-06 14:13:19.318743 | controller | ===============================================================================
2026-07-06 14:13:19.318750 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.61s
2026-07-06 14:13:19.318757 | controller | Gathering Facts --------------------------------------------------------- 2.07s
2026-07-06 14:13:19.318764 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.69s
2026-07-06 14:13:19.318771 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.10s
2026-07-06 14:13:19.318778 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.53s
2026-07-06 14:13:19.318786 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.51s
2026-07-06 14:13:19.318793 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.47s
2026-07-06 14:13:19.318800 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.45s
2026-07-06 14:13:19.318808 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.45s
2026-07-06 14:13:19.318815 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.43s
2026-07-06 14:13:19.318838 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.41s
2026-07-06 14:13:19.318845 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.30s
2026-07-06 14:13:19.318852 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.29s
2026-07-06 14:13:19.318860 | controller | Install plugin ---------------------------------------------------------- 0.06s
2026-07-06 14:13:19.318867 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s
2026-07-06 14:13:19.318874 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s
2026-07-06 14:13:19.404853 | controller | INFO     [helm > idempotence] Executed: Successful
2026-07-06 14:13:19.414980 | controller | INFO     [helm > side_effect] Executing
2026-07-06 14:13:19.415488 | controller | WARNING  [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-06 14:13:19.422530 | controller | INFO     [helm > verify] Executing
2026-07-06 14:13:20.280438 | controller |
2026-07-06 14:13:20.280743 | controller | PLAY [Verify] ******************************************************************
2026-07-06 14:13:20.280980 | controller |
2026-07-06 14:13:20.281233 | controller | TASK [Gathering Facts] *********************************************************
2026-07-06 14:13:20.281483 | controller | Monday 06 July 2026  14:13:20 +0000 (0:00:00.010)       0:00:00.010 ***********
2026-07-06 14:13:21.337625 | 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 14:13:21.343173 | controller | ok: [controller]
2026-07-06 14:13:21.343447 | controller |
2026-07-06 14:13:21.343752 | controller | TASK [Get the current Helm version] ********************************************
2026-07-06 14:13:21.344062 | controller | Monday 06 July 2026  14:13:21 +0000 (0:00:01.063)       0:00:01.073 ***********
2026-07-06 14:13:21.816166 | controller | changed: [controller]
2026-07-06 14:13:21.816469 | controller |
2026-07-06 14:13:21.816754 | controller | TASK [Assert Helm version] *****************************************************
2026-07-06 14:13:21.817028 | controller | Monday 06 July 2026  14:13:21 +0000 (0:00:00.472)       0:00:01.545 ***********
2026-07-06 14:13:21.843854 | controller | ok: [controller] => {
2026-07-06 14:13:21.844005 | controller |     "changed": false,
2026-07-06 14:13:21.844123 | controller |     "msg": "All assertions passed"
2026-07-06 14:13:21.844273 | controller | }
2026-07-06 14:13:21.844391 | controller |
2026-07-06 14:13:21.844593 | controller | PLAY RECAP *********************************************************************
2026-07-06 14:13:21.844842 | controller | controller                 : ok=3    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-07-06 14:13:21.845003 | controller |
2026-07-06 14:13:21.845152 | controller |
2026-07-06 14:13:21.845311 | controller | TASKS RECAP ********************************************************************
2026-07-06 14:13:21.845475 | controller | Monday 06 July 2026  14:13:21 +0000 (0:00:00.028)       0:00:01.574 ***********
2026-07-06 14:13:21.845637 | controller | ===============================================================================
2026-07-06 14:13:21.845798 | controller | Gathering Facts --------------------------------------------------------- 1.06s
2026-07-06 14:13:21.845960 | controller | Get the current Helm version -------------------------------------------- 0.47s
2026-07-06 14:13:21.846160 | controller | Assert Helm version ----------------------------------------------------- 0.03s
2026-07-06 14:13:21.939938 | controller | INFO     [helm > verify] Executed: Successful
2026-07-06 14:13:21.949580 | controller | INFO     [helm > cleanup] Executing
2026-07-06 14:13:21.950092 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-07-06 14:13:21.957857 | controller | INFO     [helm > destroy] Executing
2026-07-06 14:13:21.957999 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-07-06 14:13:21.958091 | controller | INFO     [helm > destroy] Executed: Successful
2026-07-06 14:13:21.958418 | controller | WARNING  Molecule executed 1 scenario (1 missing files)
2026-07-06 14:13:22.338811 | controller | ok: Runtime: 0:01:02.118306
2026-07-06 14:13:22.344645 | 
2026-07-06 14:13:22.344733 | PLAY RECAP
2026-07-06 14:13:22.344807 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-06 14:13:22.344840 | 
2026-07-06 14:13:22.486220 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-07-06 14:13:22.488037 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-07-06 14:13:23.107830 | 
2026-07-06 14:13:23.107990 | PLAY [all]
2026-07-06 14:13:23.119932 | 
2026-07-06 14:13:23.144086 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-07-06 14:13:23.521401 | controller | changed
2026-07-06 14:13:23.528045 | 
2026-07-06 14:13:23.528188 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-07-06 14:13:23.927194 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-06 14:13:23.927575 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-06 14:13:23.935203 | controller | environment: line 1: kubectl: command not found
2026-07-06 14:13:23.937515 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-06 14:13:23.939831 | controller | environment: line 1: kubectl: command not found
2026-07-06 14:13:23.941117 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-06 14:13:23.941290 | controller | environment: line 1: kubectl: command not found
2026-07-06 14:13:23.943671 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-06 14:13:23.946818 | controller | environment: line 1: kubectl: command not found
2026-07-06 14:13:23.948858 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-06 14:13:23.949292 | controller | environment: line 1: kubectl: command not found
2026-07-06 14:13:23.951432 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-06 14:13:24.067057 | controller | ok: Runtime: 0:00:00.037387
2026-07-06 14:13:24.073189 | 
2026-07-06 14:13:24.073330 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-07-06 14:13:24.309999 | controller | changed
2026-07-06 14:13:24.317529 | 
2026-07-06 14:13:24.317830 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-07-06 14:13:24.559848 | controller | environment: line 5: kubectl: command not found
2026-07-06 14:13:24.561636 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-06 14:13:24.562176 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-06 14:13:24.562572 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-06 14:13:24.851194 | controller | ok: Runtime: 0:00:00.012697
2026-07-06 14:13:24.858488 | 
2026-07-06 14:13:24.858621 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-07-06 14:13:25.528029 | controller | changed:
2026-07-06 14:13:25.528241 | controller | created directory /var/lib/zuul/builds/73c8991e4dd84a2f99cf15c7443b9c56/work/logs/controller
2026-07-06 14:13:25.528297 | controller | cd+++++++++ objects/
2026-07-06 14:13:25.528332 | controller | cd+++++++++ objects/cluster/
2026-07-06 14:13:25.528365 | controller | cd+++++++++ objects/namespaced/
2026-07-06 14:13:25.537648 | 
2026-07-06 14:13:25.537714 | TASK [gather-pod-logs : creating directory for pod logs]
2026-07-06 14:13:25.782683 | controller | changed
2026-07-06 14:13:25.788473 | 
2026-07-06 14:13:25.788586 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-07-06 14:13:26.034347 | controller | changed
2026-07-06 14:13:26.039599 | 
2026-07-06 14:13:26.039698 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-07-06 14:13:26.288147 | controller | environment: line 3: kubectl: command not found
2026-07-06 14:13:26.572487 | controller | ok: Runtime: 0:00:00.012249
2026-07-06 14:13:26.578472 | 
2026-07-06 14:13:26.578536 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-07-06 14:13:27.088193 | controller | changed:
2026-07-06 14:13:27.088427 | controller | cd+++++++++ pod-logs/
2026-07-06 14:13:27.088467 | controller | cd+++++++++ pod-logs/failed-pods/
2026-07-06 14:13:27.097833 | 
2026-07-06 14:13:27.097919 | LOOP [helm-release-status : creating directory for helm release status]
2026-07-06 14:13:27.326964 | controller | changed: "values"
2026-07-06 14:13:27.530919 | controller | changed: "releases"
2026-07-06 14:13:27.551291 | 
2026-07-06 14:13:27.551457 | TASK [helm-release-status : Gather get release status for helm charts]
2026-07-06 14:13:27.783379 | controller | /bin/bash: line 3: kubectl: command not found
2026-07-06 14:13:28.082215 | controller | ok: Runtime: 0:00:00.008026
2026-07-06 14:13:28.088469 | 
2026-07-06 14:13:28.088540 | TASK [helm-release-status : Downloads logs to executor]
2026-07-06 14:13:28.576000 | controller | changed:
2026-07-06 14:13:28.576226 | controller | cd+++++++++ helm/
2026-07-06 14:13:28.576264 | controller | cd+++++++++ helm/releases/
2026-07-06 14:13:28.576296 | controller | cd+++++++++ helm/values/
2026-07-06 14:13:28.582843 | 
2026-07-06 14:13:28.582922 | PLAY RECAP
2026-07-06 14:13:28.582993 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-06 14:13:28.583022 | 
2026-07-06 14:13:28.724029 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-07-06 14:13:28.730988 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-06 14:13:29.372902 | 
2026-07-06 14:13:29.373642 | PLAY [all]
2026-07-06 14:13:29.384259 | 
2026-07-06 14:13:29.384337 | TASK [fetch-output : Set log path for multiple nodes]
2026-07-06 14:13:29.430674 | controller | skipping: Conditional result was False
2026-07-06 14:13:29.439380 | 
2026-07-06 14:13:29.439560 | TASK [fetch-output : Set log path for single node]
2026-07-06 14:13:29.484571 | controller | ok
2026-07-06 14:13:29.488939 | 
2026-07-06 14:13:29.489033 | LOOP [fetch-output : Ensure local output dirs]
2026-07-06 14:13:29.881094 | controller -> localhost | ok: "/var/lib/zuul/builds/73c8991e4dd84a2f99cf15c7443b9c56/work/logs"
2026-07-06 14:13:30.084935 | controller -> localhost | changed: "/var/lib/zuul/builds/73c8991e4dd84a2f99cf15c7443b9c56/work/artifacts"
2026-07-06 14:13:30.289545 | controller -> localhost | changed: "/var/lib/zuul/builds/73c8991e4dd84a2f99cf15c7443b9c56/work/docs"
2026-07-06 14:13:30.307090 | 
2026-07-06 14:13:30.307332 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-07-06 14:13:30.945760 | controller | changed: .d..t...... ./
2026-07-06 14:13:30.946111 | controller | changed: All items complete
2026-07-06 14:13:30.946176 | 
2026-07-06 14:13:31.378904 | controller | changed: .d..t...... ./
2026-07-06 14:13:31.806134 | controller | changed: .d..t...... ./
2026-07-06 14:13:31.833629 | 
2026-07-06 14:13:31.833807 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-07-06 14:13:32.271563 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.009331
2026-07-06 14:13:32.504259 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008075
2026-07-06 14:13:32.518195 | 
2026-07-06 14:13:32.518351 | PLAY [all]
2026-07-06 14:13:32.526097 | 
2026-07-06 14:13:32.526202 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-07-06 14:13:32.995194 | controller | changed
2026-07-06 14:13:33.002358 | 
2026-07-06 14:13:33.002440 | PLAY RECAP
2026-07-06 14:13:33.002514 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-06 14:13:33.002553 | 
2026-07-06 14:13:33.143867 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-06 14:13:33.147266 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-07-06 14:13:33.766447 | 
2026-07-06 14:13:33.766675 | PLAY [localhost]
2026-07-06 14:13:33.778404 | 
2026-07-06 14:13:33.778494 | TASK [Generate Zuul manifest]
2026-07-06 14:13:33.801201 | localhost | ok
2026-07-06 14:13:33.822746 | 
2026-07-06 14:13:33.822991 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-07-06 14:13:34.222494 | localhost | changed
2026-07-06 14:13:34.235614 | 
2026-07-06 14:13:34.235762 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-07-06 14:13:34.270925 | localhost | ok
2026-07-06 14:13:34.278526 | 
2026-07-06 14:13:34.278637 | TASK [Upload logs]
2026-07-06 14:13:34.300879 | localhost | ok
2026-07-06 14:13:34.365570 | 
2026-07-06 14:13:34.365733 | TASK [Set zuul-log-path fact]
2026-07-06 14:13:34.398308 | localhost | ok
2026-07-06 14:13:34.412274 | 
2026-07-06 14:13:34.412406 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-06 14:13:34.444922 | localhost | ok
2026-07-06 14:13:34.455295 | 
2026-07-06 14:13:34.455424 | TASK [upload-logs : Create log directories]
2026-07-06 14:13:34.884218 | localhost | changed
2026-07-06 14:13:34.890677 | 
2026-07-06 14:13:34.890755 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-07-06 14:13:35.319204 | localhost -> localhost | ok: Runtime: 0:00:00.006033
2026-07-06 14:13:35.325736 | 
2026-07-06 14:13:35.325826 | TASK [upload-logs : Upload logs to log server]
2026-07-06 14:13:35.803218 | localhost | Output suppressed because no_log was given
2026-07-06 14:13:35.809216 | 
2026-07-06 14:13:35.809298 | LOOP [upload-logs : Compress console log and json output]
2026-07-06 14:13:35.857707 | localhost | skipping: Conditional result was False
2026-07-06 14:13:35.908251 | localhost | skipping: Conditional result was False
2026-07-06 14:13:35.924322 | 
2026-07-06 14:13:35.924498 | LOOP [upload-logs : Upload compressed console log and json output]
2026-07-06 14:13:35.972288 | localhost | skipping: Conditional result was False
2026-07-06 14:13:35.972849 | 
2026-07-06 14:13:35.976716 | localhost | skipping: Conditional result was False
2026-07-06 14:13:35.990771 | 
2026-07-06 14:13:35.991332 | LOOP [upload-logs : Upload console log and json output]