2026-08-12 09:21:56.795409 | Job console starting
2026-08-12 09:21:56.803721 | Updating git repos
2026-08-12 09:21:56.841586 | Cloning repos into workspace
2026-08-12 09:21:59.136320 | Restoring repo states
2026-08-12 09:21:59.154438 | Merging changes
2026-08-12 09:21:59.963118 | Checking out repos
2026-08-12 09:22:00.440009 | Preparing playbooks
2026-08-12 09:22:16.056354 | Running Ansible setup
2026-08-12 09:22:22.258953 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-12 09:22:22.868022 | 
2026-08-12 09:22:22.868190 | PLAY [localhost]
2026-08-12 09:22:22.878744 | 
2026-08-12 09:22:22.878849 | TASK [Gathering Facts]
2026-08-12 09:22:24.906264 | localhost | ok
2026-08-12 09:22:24.915491 | 
2026-08-12 09:22:24.915575 | TASK [Setup log path fact]
2026-08-12 09:22:24.938183 | localhost | ok
2026-08-12 09:22:24.954303 | 
2026-08-12 09:22:24.954393 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-12 09:22:24.996299 | localhost | ok
2026-08-12 09:22:25.004730 | 
2026-08-12 09:22:25.004806 | TASK [emit-job-header : Print job information]
2026-08-12 09:22:25.046508 | # Job Information
2026-08-12 09:22:25.046763 | Ansible Version: 2.16.18
2026-08-12 09:22:25.046810 | Job: ansible-collection-kubernetes-molecule-helm
2026-08-12 09:22:25.046842 | Pipeline: check
2026-08-12 09:22:25.046873 | Executor: 2d72f0692154
2026-08-12 09:22:25.046901 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/295
2026-08-12 09:22:25.046933 | Event ID: b9f1ce50-962e-11f1-8bac-28aa6bcd84ab
2026-08-12 09:22:25.051432 | 
2026-08-12 09:22:25.051536 | LOOP [emit-job-header : Print node information]
2026-08-12 09:22:25.151099 | localhost | ok:
2026-08-12 09:22:25.151414 | localhost | # Node Information
2026-08-12 09:22:25.151453 | localhost | Inventory Hostname: controller
2026-08-12 09:22:25.151476 | localhost | Hostname: np0000188961
2026-08-12 09:22:25.151497 | localhost | Username: zuul
2026-08-12 09:22:25.151520 | localhost | Distro: Debian 13.6
2026-08-12 09:22:25.151540 | localhost | Provider: yul1
2026-08-12 09:22:25.151559 | localhost | Region: ca-ymq-1
2026-08-12 09:22:25.151578 | localhost | Label: debian-trixie
2026-08-12 09:22:25.151596 | localhost | Product Name: OpenStack Nova
2026-08-12 09:22:25.151615 | localhost | Interface IP: 199.19.213.212
2026-08-12 09:22:25.172337 | 
2026-08-12 09:22:25.172525 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-12 09:22:25.595439 | localhost -> localhost | changed
2026-08-12 09:22:25.606000 | 
2026-08-12 09:22:25.606075 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-12 09:22:26.517107 | localhost -> localhost | changed
2026-08-12 09:22:26.527081 | 
2026-08-12 09:22:26.527164 | PLAY [all]
2026-08-12 09:22:26.534443 | 
2026-08-12 09:22:26.534517 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-12 09:22:26.772340 | controller -> localhost | ok
2026-08-12 09:22:26.780057 | 
2026-08-12 09:22:26.780134 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-12 09:22:26.811948 | controller | ok
2026-08-12 09:22:26.828585 | controller | included: /var/lib/zuul/builds/82ae6278da1942508396b3244a8ca5c7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-12 09:22:26.833327 | 
2026-08-12 09:22:26.833387 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-12 09:22:28.610862 | controller -> localhost | Generating public/private rsa key pair.
2026-08-12 09:22:28.611048 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/82ae6278da1942508396b3244a8ca5c7/work/82ae6278da1942508396b3244a8ca5c7_id_rsa
2026-08-12 09:22:28.611079 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/82ae6278da1942508396b3244a8ca5c7/work/82ae6278da1942508396b3244a8ca5c7_id_rsa.pub
2026-08-12 09:22:28.611103 | controller -> localhost | The key fingerprint is:
2026-08-12 09:22:28.611125 | controller -> localhost | SHA256:01xCsdf0c4xsAnkngd4JcOxNwTf7tD2abSDL0fEexmU zuul-build-sshkey
2026-08-12 09:22:28.611161 | controller -> localhost | The key's randomart image is:
2026-08-12 09:22:28.611183 | controller -> localhost | +---[RSA 3072]----+
2026-08-12 09:22:28.611209 | controller -> localhost | |        .o==oo.  |
2026-08-12 09:22:28.611232 | controller -> localhost | |         o=o=+++ |
2026-08-12 09:22:28.611253 | controller -> localhost | |         oo*+==++|
2026-08-12 09:22:28.611274 | controller -> localhost | |         oo+++. E|
2026-08-12 09:22:28.611295 | controller -> localhost | |        S o . +++|
2026-08-12 09:22:28.611315 | controller -> localhost | |         . o o Bo|
2026-08-12 09:22:28.611336 | controller -> localhost | |          . + B o|
2026-08-12 09:22:28.611357 | controller -> localhost | |           o o + |
2026-08-12 09:22:28.611380 | controller -> localhost | |              .  |
2026-08-12 09:22:28.611400 | controller -> localhost | +----[SHA256]-----+
2026-08-12 09:22:28.611452 | controller -> localhost | ok: Runtime: 0:00:01.320536
2026-08-12 09:22:28.617959 | 
2026-08-12 09:22:28.618112 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-12 09:22:28.652267 | controller | ok
2026-08-12 09:22:28.662591 | controller | included: /var/lib/zuul/builds/82ae6278da1942508396b3244a8ca5c7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-12 09:22:28.669016 | 
2026-08-12 09:22:28.669080 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-12 09:22:28.694831 | controller | skipping: Conditional result was False
2026-08-12 09:22:28.702477 | 
2026-08-12 09:22:28.702568 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-12 09:22:29.240164 | controller | changed
2026-08-12 09:22:29.250599 | 
2026-08-12 09:22:29.250966 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-12 09:22:29.472718 | controller | ok
2026-08-12 09:22:29.479209 | 
2026-08-12 09:22:29.479433 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-12 09:22:30.038301 | controller | changed
2026-08-12 09:22:30.043769 | 
2026-08-12 09:22:30.043859 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-12 09:22:30.550646 | controller | changed
2026-08-12 09:22:30.692680 | 
2026-08-12 09:22:30.692807 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-12 09:22:30.727994 | controller | skipping: Conditional result was False
2026-08-12 09:22:30.736648 | 
2026-08-12 09:22:30.736725 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-12 09:22:31.118307 | controller -> localhost | changed
2026-08-12 09:22:31.132287 | 
2026-08-12 09:22:31.132391 | TASK [add-build-sshkey : Add back temp key]
2026-08-12 09:22:31.415970 | controller -> localhost | Identity added: /var/lib/zuul/builds/82ae6278da1942508396b3244a8ca5c7/work/82ae6278da1942508396b3244a8ca5c7_id_rsa (zuul-build-sshkey)
2026-08-12 09:22:31.416183 | controller -> localhost | ok: Runtime: 0:00:00.011752
2026-08-12 09:22:31.420361 | 
2026-08-12 09:22:31.420429 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-12 09:22:31.776416 | controller | ok
2026-08-12 09:22:31.781626 | 
2026-08-12 09:22:31.781721 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-12 09:22:31.807527 | controller | skipping: Conditional result was False
2026-08-12 09:22:31.819892 | 
2026-08-12 09:22:31.819983 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-12 09:22:32.174106 | controller | ok
2026-08-12 09:22:32.186563 | 
2026-08-12 09:22:32.186741 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-12 09:22:33.127363 | controller | Output suppressed because no_log was given
2026-08-12 09:22:33.134273 | 
2026-08-12 09:22:33.134337 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-12 09:22:33.345877 | controller | ok: "logs"
2026-08-12 09:22:33.346187 | controller | ok: All items complete
2026-08-12 09:22:33.346228 | 
2026-08-12 09:22:33.524553 | controller | ok: "artifacts"
2026-08-12 09:22:33.718405 | controller | ok: "docs"
2026-08-12 09:22:33.731677 | 
2026-08-12 09:22:33.731838 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-12 09:22:33.955714 | controller | changed: "logs"
2026-08-12 09:22:34.144920 | controller | changed: "artifacts"
2026-08-12 09:22:34.333430 | controller | changed: "docs"
2026-08-12 09:22:34.352167 | 
2026-08-12 09:22:34.352285 | PLAY RECAP
2026-08-12 09:22:34.352334 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-12 09:22:34.352364 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-12 09:22:34.352386 | 
2026-08-12 09:22:34.488343 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-12 09:22:34.559064 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-12 09:22:35.172023 | 
2026-08-12 09:22:35.172179 | PLAY [all]
2026-08-12 09:22:35.182312 | 
2026-08-12 09:22:35.182471 | TASK [setup-uv : Extract archive]
2026-08-12 09:22:37.573956 | controller | changed
2026-08-12 09:22:37.580655 | 
2026-08-12 09:22:37.580767 | TASK [setup-uv : Print version]
2026-08-12 09:22:37.987868 | controller | uv 0.8.13
2026-08-12 09:22:38.110466 | controller | ok: Runtime: 0:00:00.013356
2026-08-12 09:22:38.120694 | 
2026-08-12 09:22:38.120817 | TASK [Install Ansible collection siblings]
2026-08-12 09:22:38.505345 | controller | ok:
2026-08-12 09:22:38.505563 | controller | Skipping ansible.posix: no checked-out sibling
2026-08-12 09:22:38.505601 | controller | Skipping community.crypto: no checked-out sibling
2026-08-12 09:22:38.505630 | controller | Skipping community.general: no checked-out sibling
2026-08-12 09:22:38.505657 | controller | Skipping kubernetes.core: no checked-out sibling
2026-08-12 09:22:38.505683 | controller | Skipping vexxhost.containers: no checked-out sibling
2026-08-12 09:22:38.507766 | 
2026-08-12 09:22:38.507824 | PLAY RECAP
2026-08-12 09:22:38.507885 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-12 09:22:38.507915 | 
2026-08-12 09:22:38.638463 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-12 09:22:38.641308 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-08-12 09:22:39.295521 | 
2026-08-12 09:22:39.295676 | PLAY [all]
2026-08-12 09:22:39.312616 | 
2026-08-12 09:22:39.313396 | TASK [Update apt cache]
2026-08-12 09:22:44.313414 | controller | ok
2026-08-12 09:22:44.319280 | 
2026-08-12 09:22:44.319385 | TASK [Disable firewalld]
2026-08-12 09:22:44.345256 | controller | skipping: Conditional result was False
2026-08-12 09:22:44.353735 | 
2026-08-12 09:22:44.353832 | TASK [Install "jq" for log collection]
2026-08-12 09:22:46.682926 | controller | changed
2026-08-12 09:22:46.688158 | 
2026-08-12 09:22:46.688918 | PLAY RECAP
2026-08-12 09:22:46.689079 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-12 09:22:46.689134 | 
2026-08-12 09:22:46.853818 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-08-12 09:22:46.855241 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-12 09:22:47.462836 | 
2026-08-12 09:22:47.462988 | PLAY [all]
2026-08-12 09:22:47.473220 | 
2026-08-12 09:22:47.473296 | LOOP [Check inventory directories]
2026-08-12 09:22:47.849812 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-08-12 09:22:48.021849 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/helm/inventory"
2026-08-12 09:22:48.190801 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/inventory"
2026-08-12 09:22:48.209267 | 
2026-08-12 09:22:48.209553 | LOOP [Copy inventory file for Zuul]
2026-08-12 09:22:49.096213 | controller | changed:
2026-08-12 09:22:49.096516 | controller | {
2026-08-12 09:22:49.096576 | controller |   "ansible_loop_var": "item",
2026-08-12 09:22:49.096611 | controller |   "changed": false,
2026-08-12 09:22:49.096638 | controller |   "failed": false,
2026-08-12 09:22:49.096670 | controller |   "invocation": {
2026-08-12 09:22:49.096698 | controller |     "module_args": {
2026-08-12 09:22:49.096724 | controller |       "checksum_algorithm": "sha1",
2026-08-12 09:22:49.096758 | controller |       "follow": true,
2026-08-12 09:22:49.096784 | controller |       "get_attributes": true,
2026-08-12 09:22:49.096811 | controller |       "get_checksum": true,
2026-08-12 09:22:49.096853 | controller |       "get_mime": true,
2026-08-12 09:22:49.096879 | controller |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-08-12 09:22:49.096904 | controller |     }
2026-08-12 09:22:49.096927 | controller |   },
2026-08-12 09:22:49.096950 | controller |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-08-12 09:22:49.097059 | controller |   "stat": {
2026-08-12 09:22:49.097091 | controller |     "atime": 1786526552.9943726,
2026-08-12 09:22:49.097117 | controller |     "attr_flags": "e",
2026-08-12 09:22:49.097142 | controller |     "attributes": [
2026-08-12 09:22:49.097166 | controller |       "extents"
2026-08-12 09:22:49.097190 | controller |     ],
2026-08-12 09:22:49.097214 | controller |     "block_size": 4096,
2026-08-12 09:22:49.097238 | controller |     "blocks": 8,
2026-08-12 09:22:49.097262 | controller |     "charset": "unknown",
2026-08-12 09:22:49.097288 | controller |     "ctime": 1786526553.0143728,
2026-08-12 09:22:49.097312 | controller |     "dev": 65025,
2026-08-12 09:22:49.097338 | controller |     "device_type": 0,
2026-08-12 09:22:49.097361 | controller |     "executable": true,
2026-08-12 09:22:49.097384 | controller |     "exists": true,
2026-08-12 09:22:49.097407 | controller |     "gid": 1000,
2026-08-12 09:22:49.097431 | controller |     "gr_name": "zuul",
2026-08-12 09:22:49.097454 | controller |     "inode": 519191,
2026-08-12 09:22:49.097477 | controller |     "isblk": false,
2026-08-12 09:22:49.097501 | controller |     "ischr": false,
2026-08-12 09:22:49.097536 | controller |     "isdir": true,
2026-08-12 09:22:49.097564 | controller |     "isfifo": false,
2026-08-12 09:22:49.097595 | controller |     "isgid": false,
2026-08-12 09:22:49.097619 | controller |     "islnk": false,
2026-08-12 09:22:49.097641 | controller |     "isreg": false,
2026-08-12 09:22:49.097663 | controller |     "issock": false,
2026-08-12 09:22:49.097686 | controller |     "isuid": false,
2026-08-12 09:22:49.097708 | controller |     "mimetype": "unknown",
2026-08-12 09:22:49.097731 | controller |     "mode": "0755",
2026-08-12 09:22:49.097753 | controller |     "mtime": 1786526519.8198738,
2026-08-12 09:22:49.097776 | controller |     "nlink": 14,
2026-08-12 09:22:49.097798 | controller |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-08-12 09:22:49.097821 | controller |     "pw_name": "zuul",
2026-08-12 09:22:49.097852 | controller |     "readable": true,
2026-08-12 09:22:49.097884 | controller |     "rgrp": true,
2026-08-12 09:22:49.097908 | controller |     "roth": true,
2026-08-12 09:22:49.097934 | controller |     "rusr": true,
2026-08-12 09:22:49.097957 | controller |     "size": 4096,
2026-08-12 09:22:49.097980 | controller |     "uid": 1000,
2026-08-12 09:22:49.098003 | controller |     "version": "4277174996",
2026-08-12 09:22:49.098026 | controller |     "wgrp": false,
2026-08-12 09:22:49.098051 | controller |     "woth": false,
2026-08-12 09:22:49.098074 | controller |     "writeable": true,
2026-08-12 09:22:49.098098 | controller |     "wusr": true,
2026-08-12 09:22:49.098121 | controller |     "xgrp": true,
2026-08-12 09:22:49.098144 | controller |     "xoth": true,
2026-08-12 09:22:49.098167 | controller |     "xusr": true
2026-08-12 09:22:49.098189 | controller |   }
2026-08-12 09:22:49.098230 | controller | }
2026-08-12 09:22:49.098284 | controller | ok: All items complete
2026-08-12 09:22:49.098315 | 
2026-08-12 09:22:49.100148 | controller | skipping: Conditional result was False
2026-08-12 09:22:49.101510 | controller | skipping: Conditional result was False
2026-08-12 09:22:49.115365 | 
2026-08-12 09:22:49.115528 | LOOP [Switch "ansible_host" to private IP]
2026-08-12 09:22:49.503682 | controller | changed: 1 replacements made
2026-08-12 09:22:49.504890 | controller | skipping: Conditional result was False
2026-08-12 09:22:49.505494 | controller | skipping: Conditional result was False
2026-08-12 09:22:49.518051 | 
2026-08-12 09:22:49.518193 | TASK [Run Molecule scenario]
2026-08-12 09:22:49.962025 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-08-12 09:22:49.962133 | controller | Creating virtual environment at: .venv
2026-08-12 09:22:50.262791 | controller | Downloading cryptography (4.3MiB)
2026-08-12 09:22:50.263205 | controller | Downloading ansible-core (2.3MiB)
2026-08-12 09:22:50.263786 | controller | Downloading pygments (1.2MiB)
2026-08-12 09:22:50.429359 | controller |  Downloading cryptography
2026-08-12 09:22:50.477686 | controller |  Downloading pygments
2026-08-12 09:22:50.675112 | controller |  Downloading ansible-core
2026-08-12 09:22:50.724246 | controller | Installed 36 packages in 48ms
2026-08-12 09:22:51.413688 | controller | INFO     Collection 'vexxhost.kubernetes' detected.
2026-08-12 09:22:51.413758 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-08-12 09:22:52.274966 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-08-12 09:22:52.275011 | controller | INFO     default scenario not found, disabling shared state.
2026-08-12 09:22:52.275136 | controller | INFO     [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-08-12 09:22:52.275211 | controller | INFO     [helm > prerun] Performing prerun with role_name_check=0...
2026-08-12 09:23:39.498568 | controller | INFO     [helm > dependency] Executing
2026-08-12 09:23:39.498640 | controller | WARNING  [helm > dependency] Missing roles requirements file: requirements.yml
2026-08-12 09:23:39.498650 | controller | WARNING  [helm > dependency] Missing collections requirements file: collections.yml
2026-08-12 09:23:39.498692 | controller | WARNING  [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-08-12 09:23:39.504067 | controller | INFO     [helm > cleanup] Executing
2026-08-12 09:23:39.504411 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-12 09:23:39.509740 | controller | INFO     [helm > destroy] Executing
2026-08-12 09:23:39.509768 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-08-12 09:23:39.509849 | controller | INFO     [helm > destroy] Executed: Successful
2026-08-12 09:23:39.515361 | controller | INFO     [helm > syntax] Executing
2026-08-12 09:23:40.474049 | controller |
2026-08-12 09:23:40.474088 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml
2026-08-12 09:23:40.543093 | controller | INFO     [helm > syntax] Executed: Successful
2026-08-12 09:23:40.552562 | controller | INFO     [helm > create] Executing
2026-08-12 09:23:40.554672 | controller | WARNING  [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-12 09:23:40.559980 | controller | INFO     [helm > prepare] Executing
2026-08-12 09:23:41.420910 | controller |
2026-08-12 09:23:41.420936 | controller | PLAY [Prepare] *****************************************************************
2026-08-12 09:23:41.420940 | controller |
2026-08-12 09:23:41.420944 | controller | TASK [Gathering Facts] *********************************************************
2026-08-12 09:23:41.420948 | controller | Wednesday 12 August 2026  09:23:41 +0000 (0:00:00.029)       0:00:00.029 ******
2026-08-12 09:23:42.665446 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information.
2026-08-12 09:23:42.671424 | controller | ok: [controller]
2026-08-12 09:23:42.671585 | controller |
2026-08-12 09:23:42.671782 | controller | TASK [Install Debian packages] *************************************************
2026-08-12 09:23:42.672046 | controller | Wednesday 12 August 2026  09:23:42 +0000 (0:00:01.252)       0:00:01.282 ******
2026-08-12 09:23:44.199777 | controller | ok: [controller]
2026-08-12 09:23:44.199979 | controller |
2026-08-12 09:23:44.200227 | controller | PLAY RECAP *********************************************************************
2026-08-12 09:23:44.200486 | controller | controller                 : ok=2    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-08-12 09:23:44.200705 | controller |
2026-08-12 09:23:44.200928 | controller |
2026-08-12 09:23:44.201204 | controller | TASKS RECAP ********************************************************************
2026-08-12 09:23:44.201430 | controller | Wednesday 12 August 2026  09:23:44 +0000 (0:00:01.528)       0:00:02.811 ******
2026-08-12 09:23:44.201656 | controller | ===============================================================================
2026-08-12 09:23:44.201887 | controller | Install Debian packages ------------------------------------------------- 1.53s
2026-08-12 09:23:44.202231 | controller | Gathering Facts --------------------------------------------------------- 1.25s
2026-08-12 09:23:44.296518 | controller | INFO     [helm > prepare] Executed: Successful
2026-08-12 09:23:44.305388 | controller | INFO     [helm > converge] Executing
2026-08-12 09:23:45.193441 | controller |
2026-08-12 09:23:45.194035 | controller | PLAY [Converge] ****************************************************************
2026-08-12 09:23:45.194047 | controller |
2026-08-12 09:23:45.194053 | controller | TASK [Gathering Facts] *********************************************************
2026-08-12 09:23:45.194058 | controller | Wednesday 12 August 2026  09:23:45 +0000 (0:00:00.013)       0:00:00.013 ******
2026-08-12 09:23:47.256370 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information.
2026-08-12 09:23:47.262125 | controller | ok: [controller]
2026-08-12 09:23:47.262200 | controller |
2026-08-12 09:23:47.262399 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-12 09:23:47.262657 | controller | Wednesday 12 August 2026  09:23:47 +0000 (0:00:02.068)       0:00:02.081 ******
2026-08-12 09:23:47.779108 | controller | ok: [controller]
2026-08-12 09:23:47.779179 | controller |
2026-08-12 09:23:47.779403 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-12 09:23:47.779605 | controller | Wednesday 12 August 2026  09:23:47 +0000 (0:00:00.516)       0:00:02.597 ******
2026-08-12 09:23:48.931201 | controller | ok: [controller]
2026-08-12 09:23:48.931272 | controller |
2026-08-12 09:23:48.931541 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-08-12 09:23:48.931749 | controller | Wednesday 12 August 2026  09:23:48 +0000 (0:00:01.152)       0:00:03.749 ******
2026-08-12 09:23:49.396668 | controller | changed: [controller]
2026-08-12 09:23:49.396811 | controller |
2026-08-12 09:23:49.397044 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-12 09:23:49.397259 | controller | Wednesday 12 August 2026  09:23:49 +0000 (0:00:00.465)       0:00:04.215 ******
2026-08-12 09:23:49.443249 | controller | ok: [controller] => {
2026-08-12 09:23:49.443459 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-08-12 09:23:49.443649 | controller | }
2026-08-12 09:23:49.443791 | controller |
2026-08-12 09:23:49.444032 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-12 09:23:49.444185 | controller | Wednesday 12 August 2026  09:23:49 +0000 (0:00:00.046)       0:00:04.262 ******
2026-08-12 09:23:50.487437 | 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-08-12 09:23:50.496583 | controller | changed: [controller]
2026-08-12 09:23:50.496799 | controller |
2026-08-12 09:23:50.497028 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-12 09:23:50.497271 | controller | Wednesday 12 August 2026  09:23:50 +0000 (0:00:01.053)       0:00:05.315 ******
2026-08-12 09:23:52.524044 | controller | changed: [controller]
2026-08-12 09:23:52.524190 | controller |
2026-08-12 09:23:52.524432 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-08-12 09:23:52.524661 | controller | Wednesday 12 August 2026  09:23:52 +0000 (0:00:02.027)       0:00:07.342 ******
2026-08-12 09:23:53.030286 | controller | ok: [controller]
2026-08-12 09:23:53.030358 | controller |
2026-08-12 09:23:53.030456 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-08-12 09:23:53.030586 | controller | Wednesday 12 August 2026  09:23:53 +0000 (0:00:00.506)       0:00:07.849 ******
2026-08-12 09:23:53.450691 | controller | changed: [controller]
2026-08-12 09:23:53.450746 | controller |
2026-08-12 09:23:53.450797 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-08-12 09:23:53.450884 | controller | Wednesday 12 August 2026  09:23:53 +0000 (0:00:00.417)       0:00:08.267 ******
2026-08-12 09:23:53.919488 | controller | ok: [controller]
2026-08-12 09:23:53.919674 | controller |
2026-08-12 09:23:53.919912 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-08-12 09:23:53.920133 | controller | Wednesday 12 August 2026  09:23:53 +0000 (0:00:00.470)       0:00:08.737 ******
2026-08-12 09:23:54.232365 | controller | changed: [controller]
2026-08-12 09:23:54.232534 | controller |
2026-08-12 09:23:54.232809 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-08-12 09:23:54.233047 | controller | Wednesday 12 August 2026  09:23:54 +0000 (0:00:00.313)       0:00:09.050 ******
2026-08-12 09:23:54.534669 | controller | ok: [controller]
2026-08-12 09:23:54.534727 | controller |
2026-08-12 09:23:54.534794 | controller | TASK [Install plugin] **********************************************************
2026-08-12 09:23:54.534878 | controller | Wednesday 12 August 2026  09:23:54 +0000 (0:00:00.301)       0:00:09.352 ******
2026-08-12 09:23:54.591465 | controller | included: vexxhost.containers.download_artifact for controller
2026-08-12 09:23:54.591539 | controller |
2026-08-12 09:23:54.591640 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-12 09:23:54.591752 | controller | Wednesday 12 August 2026  09:23:54 +0000 (0:00:00.058)       0:00:09.410 ******
2026-08-12 09:23:54.641324 | controller | ok: [controller] => {
2026-08-12 09:23:54.641444 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-08-12 09:23:54.641578 | controller | }
2026-08-12 09:23:54.646083 | controller |
2026-08-12 09:23:54.646122 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-12 09:23:54.646130 | controller | Wednesday 12 August 2026  09:23:54 +0000 (0:00:00.049)       0:00:09.460 ******
2026-08-12 09:23:55.433925 | controller | changed: [controller]
2026-08-12 09:23:55.433976 | controller |
2026-08-12 09:23:55.434021 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-12 09:23:55.434031 | controller | Wednesday 12 August 2026  09:23:55 +0000 (0:00:00.791)       0:00:10.251 ******
2026-08-12 09:23:57.534489 | controller | changed: [controller]
2026-08-12 09:23:57.534942 | controller |
2026-08-12 09:23:57.535365 | controller | PLAY RECAP *********************************************************************
2026-08-12 09:23:57.535833 | controller | controller                 : ok=16   changed=7    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-08-12 09:23:57.536176 | controller |
2026-08-12 09:23:57.536485 | controller |
2026-08-12 09:23:57.536805 | controller | TASKS RECAP ********************************************************************
2026-08-12 09:23:57.537086 | controller | Wednesday 12 August 2026  09:23:57 +0000 (0:00:02.102)       0:00:12.353 ******
2026-08-12 09:23:57.537346 | controller | ===============================================================================
2026-08-12 09:23:57.537703 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.10s
2026-08-12 09:23:57.537979 | controller | Gathering Facts --------------------------------------------------------- 2.07s
2026-08-12 09:23:57.538385 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.03s
2026-08-12 09:23:57.538697 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.15s
2026-08-12 09:23:57.538978 | controller | vexxhost.containers.download_artifact : Download item ------------------- 1.05s
2026-08-12 09:23:57.539302 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.79s
2026-08-12 09:23:57.539613 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.52s
2026-08-12 09:23:57.539946 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.51s
2026-08-12 09:23:57.540274 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.47s
2026-08-12 09:23:57.540552 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.47s
2026-08-12 09:23:57.540891 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.42s
2026-08-12 09:23:57.541186 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.31s
2026-08-12 09:23:57.541568 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.30s
2026-08-12 09:23:57.541912 | controller | Install plugin ---------------------------------------------------------- 0.06s
2026-08-12 09:23:57.542179 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-08-12 09:23:57.542382 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-08-12 09:23:57.655365 | controller | INFO     [helm > converge] Executed: Successful
2026-08-12 09:23:57.664987 | controller | INFO     [helm > idempotence] Executing
2026-08-12 09:23:58.505856 | controller |
2026-08-12 09:23:58.506276 | controller | PLAY [Converge] ****************************************************************
2026-08-12 09:23:58.506558 | controller |
2026-08-12 09:23:58.506838 | controller | TASK [Gathering Facts] *********************************************************
2026-08-12 09:23:58.507103 | controller | Wednesday 12 August 2026  09:23:58 +0000 (0:00:00.013)       0:00:00.013 ******
2026-08-12 09:23:59.577713 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information.
2026-08-12 09:23:59.583466 | controller | ok: [controller]
2026-08-12 09:23:59.583489 | controller |
2026-08-12 09:23:59.583498 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-12 09:23:59.583507 | controller | Wednesday 12 August 2026  09:23:59 +0000 (0:00:01.076)       0:00:01.090 ******
2026-08-12 09:24:00.026592 | controller | ok: [controller]
2026-08-12 09:24:00.026960 | controller |
2026-08-12 09:24:00.027323 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-12 09:24:00.027711 | controller | Wednesday 12 August 2026  09:24:00 +0000 (0:00:00.443)       0:00:01.533 ******
2026-08-12 09:24:01.107666 | controller | ok: [controller]
2026-08-12 09:24:01.107951 | controller |
2026-08-12 09:24:01.108216 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-08-12 09:24:01.108517 | controller | Wednesday 12 August 2026  09:24:01 +0000 (0:00:01.081)       0:00:02.614 ******
2026-08-12 09:24:01.533554 | controller | ok: [controller]
2026-08-12 09:24:01.533847 | controller |
2026-08-12 09:24:01.534240 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-12 09:24:01.534540 | controller | Wednesday 12 August 2026  09:24:01 +0000 (0:00:00.425)       0:00:03.040 ******
2026-08-12 09:24:01.578649 | controller | ok: [controller] => {
2026-08-12 09:24:01.578969 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-08-12 09:24:01.579215 | controller | }
2026-08-12 09:24:01.579449 | controller |
2026-08-12 09:24:01.579707 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-12 09:24:01.579959 | controller | Wednesday 12 August 2026  09:24:01 +0000 (0:00:00.044)       0:00:03.085 ******
2026-08-12 09:24:02.118730 | controller | ok: [controller]
2026-08-12 09:24:02.119027 | controller |
2026-08-12 09:24:02.119297 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-12 09:24:02.119553 | controller | Wednesday 12 August 2026  09:24:02 +0000 (0:00:00.539)       0:00:03.625 ******
2026-08-12 09:24:03.798633 | controller | ok: [controller]
2026-08-12 09:24:03.798729 | controller |
2026-08-12 09:24:03.798864 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-08-12 09:24:03.798955 | controller | Wednesday 12 August 2026  09:24:03 +0000 (0:00:01.680)       0:00:05.305 ******
2026-08-12 09:24:04.334570 | controller | ok: [controller]
2026-08-12 09:24:04.334622 | controller |
2026-08-12 09:24:04.334713 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-08-12 09:24:04.334805 | controller | Wednesday 12 August 2026  09:24:04 +0000 (0:00:00.534)       0:00:05.840 ******
2026-08-12 09:24:04.730314 | controller | ok: [controller]
2026-08-12 09:24:04.730365 | controller |
2026-08-12 09:24:04.730500 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-08-12 09:24:04.730590 | controller | Wednesday 12 August 2026  09:24:04 +0000 (0:00:00.397)       0:00:06.237 ******
2026-08-12 09:24:05.190286 | controller | ok: [controller]
2026-08-12 09:24:05.190348 | controller |
2026-08-12 09:24:05.190429 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-08-12 09:24:05.190512 | controller | Wednesday 12 August 2026  09:24:05 +0000 (0:00:00.460)       0:00:06.697 ******
2026-08-12 09:24:05.493432 | controller | ok: [controller]
2026-08-12 09:24:05.493504 | controller |
2026-08-12 09:24:05.493519 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-08-12 09:24:05.493631 | controller | Wednesday 12 August 2026  09:24:05 +0000 (0:00:00.300)       0:00:06.997 ******
2026-08-12 09:24:05.785975 | controller | ok: [controller]
2026-08-12 09:24:05.786173 | controller |
2026-08-12 09:24:05.786184 | controller | TASK [Install plugin] **********************************************************
2026-08-12 09:24:05.786192 | controller | Wednesday 12 August 2026  09:24:05 +0000 (0:00:00.291)       0:00:07.289 ******
2026-08-12 09:24:05.832725 | controller | included: vexxhost.containers.download_artifact for controller
2026-08-12 09:24:05.832764 | controller |
2026-08-12 09:24:05.832879 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-12 09:24:05.832970 | controller | Wednesday 12 August 2026  09:24:05 +0000 (0:00:00.050)       0:00:07.340 ******
2026-08-12 09:24:05.881366 | controller | ok: [controller] => {
2026-08-12 09:24:05.881477 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-08-12 09:24:05.881535 | controller | }
2026-08-12 09:24:05.881620 | controller |
2026-08-12 09:24:05.881714 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-12 09:24:05.881795 | controller | Wednesday 12 August 2026  09:24:05 +0000 (0:00:00.046)       0:00:07.386 ******
2026-08-12 09:24:06.303982 | controller | ok: [controller]
2026-08-12 09:24:06.304049 | controller |
2026-08-12 09:24:06.304152 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-12 09:24:06.304279 | controller | Wednesday 12 August 2026  09:24:06 +0000 (0:00:00.424)       0:00:07.811 ******
2026-08-12 09:24:07.969368 | controller | ok: [controller]
2026-08-12 09:24:07.969414 | controller |
2026-08-12 09:24:07.969424 | controller | PLAY RECAP *********************************************************************
2026-08-12 09:24:07.969432 | controller | controller                 : ok=16   changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-08-12 09:24:07.969440 | controller |
2026-08-12 09:24:07.969447 | controller |
2026-08-12 09:24:07.969454 | controller | TASKS RECAP ********************************************************************
2026-08-12 09:24:07.969462 | controller | Wednesday 12 August 2026  09:24:07 +0000 (0:00:01.660)       0:00:09.472 ******
2026-08-12 09:24:07.969469 | controller | ===============================================================================
2026-08-12 09:24:07.969476 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.68s
2026-08-12 09:24:07.969483 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.66s
2026-08-12 09:24:07.969490 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.08s
2026-08-12 09:24:07.969496 | controller | Gathering Facts --------------------------------------------------------- 1.08s
2026-08-12 09:24:07.969503 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.54s
2026-08-12 09:24:07.969510 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.53s
2026-08-12 09:24:07.969517 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.46s
2026-08-12 09:24:07.969524 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.44s
2026-08-12 09:24:07.969533 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.43s
2026-08-12 09:24:07.969540 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.42s
2026-08-12 09:24:07.969561 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.40s
2026-08-12 09:24:07.969568 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.30s
2026-08-12 09:24:07.969576 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.29s
2026-08-12 09:24:07.969583 | controller | Install plugin ---------------------------------------------------------- 0.05s
2026-08-12 09:24:07.969590 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-08-12 09:24:07.969597 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s
2026-08-12 09:24:08.156146 | controller | INFO     [helm > idempotence] Executed: Successful
2026-08-12 09:24:08.167545 | controller | INFO     [helm > side_effect] Executing
2026-08-12 09:24:08.167930 | controller | WARNING  [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-12 09:24:08.173303 | controller | INFO     [helm > verify] Executing
2026-08-12 09:24:08.993610 | controller |
2026-08-12 09:24:08.993935 | controller | PLAY [Verify] ******************************************************************
2026-08-12 09:24:08.994242 | controller |
2026-08-12 09:24:08.994541 | controller | TASK [Gathering Facts] *********************************************************
2026-08-12 09:24:08.994793 | controller | Wednesday 12 August 2026  09:24:08 +0000 (0:00:00.010)       0:00:00.010 ******
2026-08-12 09:24:10.086250 | controller | [WARNING]: Host 'controller' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.20/reference_appendices/interpreter_discovery.html for more information.
2026-08-12 09:24:10.092511 | controller | ok: [controller]
2026-08-12 09:24:10.092759 | controller |
2026-08-12 09:24:10.093013 | controller | TASK [Get the current Helm version] ********************************************
2026-08-12 09:24:10.093262 | controller | Wednesday 12 August 2026  09:24:10 +0000 (0:00:01.098)       0:00:01.108 ******
2026-08-12 09:24:10.552803 | controller | changed: [controller]
2026-08-12 09:24:10.553093 | controller |
2026-08-12 09:24:10.553362 | controller | TASK [Assert Helm version] *****************************************************
2026-08-12 09:24:10.553625 | controller | Wednesday 12 August 2026  09:24:10 +0000 (0:00:00.458)       0:00:01.567 ******
2026-08-12 09:24:10.585563 | controller | ok: [controller] => {
2026-08-12 09:24:10.585828 | controller |     "changed": false,
2026-08-12 09:24:10.586127 | controller |     "msg": "All assertions passed"
2026-08-12 09:24:10.586379 | controller | }
2026-08-12 09:24:10.586610 | controller |
2026-08-12 09:24:10.586855 | controller | PLAY RECAP *********************************************************************
2026-08-12 09:24:10.587135 | controller | controller                 : ok=3    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-08-12 09:24:10.587364 | controller |
2026-08-12 09:24:10.587681 | controller |
2026-08-12 09:24:10.587938 | controller | TASKS RECAP ********************************************************************
2026-08-12 09:24:10.588193 | controller | Wednesday 12 August 2026  09:24:10 +0000 (0:00:00.035)       0:00:01.602 ******
2026-08-12 09:24:10.588431 | controller | ===============================================================================
2026-08-12 09:24:10.588675 | controller | Gathering Facts --------------------------------------------------------- 1.10s
2026-08-12 09:24:10.588924 | controller | Get the current Helm version -------------------------------------------- 0.46s
2026-08-12 09:24:10.589170 | controller | Assert Helm version ----------------------------------------------------- 0.04s
2026-08-12 09:24:10.722250 | controller | INFO     [helm > verify] Executed: Successful
2026-08-12 09:24:10.733735 | controller | INFO     [helm > cleanup] Executing
2026-08-12 09:24:10.734129 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-12 09:24:10.739441 | controller | INFO     [helm > destroy] Executing
2026-08-12 09:24:10.739564 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-08-12 09:24:10.739666 | controller | INFO     [helm > destroy] Executed: Successful
2026-08-12 09:24:10.740018 | controller | WARNING  Molecule executed 1 scenario (1 missing files)
2026-08-12 09:24:11.172916 | controller | ok: Runtime: 0:01:20.950923
2026-08-12 09:24:11.178877 | 
2026-08-12 09:24:11.178949 | PLAY RECAP
2026-08-12 09:24:11.179009 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-12 09:24:11.179039 | 
2026-08-12 09:24:11.306311 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-12 09:24:11.308203 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-08-12 09:24:11.932351 | 
2026-08-12 09:24:11.932504 | PLAY [all]
2026-08-12 09:24:11.943645 | 
2026-08-12 09:24:11.943729 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-08-12 09:24:12.319837 | controller | changed
2026-08-12 09:24:12.324366 | 
2026-08-12 09:24:12.324449 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-08-12 09:24:12.708608 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-12 09:24:12.708723 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-12 09:24:12.716075 | controller | environment: line 1: kubectl: command not found
2026-08-12 09:24:12.717695 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-12 09:24:12.718609 | controller | environment: line 1: kubectl: command not found
2026-08-12 09:24:12.720950 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-12 09:24:12.721758 | controller | environment: line 1: kubectl: command not found
2026-08-12 09:24:12.724144 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-12 09:24:12.725935 | controller | environment: line 1: kubectl: command not found
2026-08-12 09:24:12.728264 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-12 09:24:12.729456 | controller | environment: line 1: kubectl: command not found
2026-08-12 09:24:12.730543 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-12 09:24:12.852257 | controller | ok: Runtime: 0:00:00.037368
2026-08-12 09:24:12.858980 | 
2026-08-12 09:24:12.859091 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-08-12 09:24:13.094910 | controller | changed
2026-08-12 09:24:13.100709 | 
2026-08-12 09:24:13.100789 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-08-12 09:24:13.338921 | controller | environment: line 5: kubectl: command not found
2026-08-12 09:24:13.340354 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-12 09:24:13.340382 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-12 09:24:13.340624 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-12 09:24:13.635747 | controller | ok: Runtime: 0:00:00.014159
2026-08-12 09:24:13.643016 | 
2026-08-12 09:24:13.643138 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-08-12 09:24:14.347023 | controller | changed:
2026-08-12 09:24:14.347311 | controller | created directory /var/lib/zuul/builds/82ae6278da1942508396b3244a8ca5c7/work/logs/controller
2026-08-12 09:24:14.347379 | controller | cd+++++++++ objects/
2026-08-12 09:24:14.347429 | controller | cd+++++++++ objects/cluster/
2026-08-12 09:24:14.347481 | controller | cd+++++++++ objects/namespaced/
2026-08-12 09:24:14.357078 | 
2026-08-12 09:24:14.357147 | TASK [gather-pod-logs : creating directory for pod logs]
2026-08-12 09:24:14.595702 | controller | changed
2026-08-12 09:24:14.601434 | 
2026-08-12 09:24:14.601543 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-08-12 09:24:14.827625 | controller | changed
2026-08-12 09:24:14.833655 | 
2026-08-12 09:24:14.833765 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-08-12 09:24:15.075145 | controller | environment: line 3: kubectl: command not found
2026-08-12 09:24:15.365096 | controller | ok: Runtime: 0:00:00.012617
2026-08-12 09:24:15.371410 | 
2026-08-12 09:24:15.371477 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-08-12 09:24:15.867726 | controller | changed:
2026-08-12 09:24:15.867956 | controller | cd+++++++++ pod-logs/
2026-08-12 09:24:15.867997 | controller | cd+++++++++ pod-logs/failed-pods/
2026-08-12 09:24:15.877755 | 
2026-08-12 09:24:15.877829 | LOOP [helm-release-status : creating directory for helm release status]
2026-08-12 09:24:16.109396 | controller | changed: "values"
2026-08-12 09:24:16.310055 | controller | changed: "releases"
2026-08-12 09:24:16.320340 | 
2026-08-12 09:24:16.320521 | TASK [helm-release-status : Gather get release status for helm charts]
2026-08-12 09:24:16.559509 | controller | /bin/bash: line 3: kubectl: command not found
2026-08-12 09:24:16.852260 | controller | ok: Runtime: 0:00:00.009118
2026-08-12 09:24:16.858479 | 
2026-08-12 09:24:16.858584 | TASK [helm-release-status : Downloads logs to executor]
2026-08-12 09:24:17.368396 | controller | changed:
2026-08-12 09:24:17.368667 | controller | cd+++++++++ helm/
2026-08-12 09:24:17.368727 | controller | cd+++++++++ helm/releases/
2026-08-12 09:24:17.368780 | controller | cd+++++++++ helm/values/
2026-08-12 09:24:17.375714 | 
2026-08-12 09:24:17.375781 | PLAY RECAP
2026-08-12 09:24:17.375845 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-12 09:24:17.375876 | 
2026-08-12 09:24:17.515064 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-08-12 09:24:17.517610 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-12 09:24:18.103173 | 
2026-08-12 09:24:18.103319 | PLAY [all]
2026-08-12 09:24:18.113878 | 
2026-08-12 09:24:18.113957 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-12 09:24:18.159863 | controller | skipping: Conditional result was False
2026-08-12 09:24:18.168765 | 
2026-08-12 09:24:18.168933 | TASK [fetch-output : Set log path for single node]
2026-08-12 09:24:18.211432 | controller | ok
2026-08-12 09:24:18.216630 | 
2026-08-12 09:24:18.216697 | LOOP [fetch-output : Ensure local output dirs]
2026-08-12 09:24:18.606180 | controller -> localhost | ok: "/var/lib/zuul/builds/82ae6278da1942508396b3244a8ca5c7/work/logs"
2026-08-12 09:24:18.819821 | controller -> localhost | changed: "/var/lib/zuul/builds/82ae6278da1942508396b3244a8ca5c7/work/artifacts"
2026-08-12 09:24:19.027708 | controller -> localhost | changed: "/var/lib/zuul/builds/82ae6278da1942508396b3244a8ca5c7/work/docs"
2026-08-12 09:24:19.045073 | 
2026-08-12 09:24:19.045234 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-12 09:24:19.692930 | controller | changed: .d..t...... ./
2026-08-12 09:24:19.693283 | controller | changed: All items complete
2026-08-12 09:24:19.693336 | 
2026-08-12 09:24:20.149859 | controller | changed: .d..t...... ./
2026-08-12 09:24:20.610048 | controller | changed: .d..t...... ./
2026-08-12 09:24:20.626210 | 
2026-08-12 09:24:20.626388 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-12 09:24:21.047905 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008538
2026-08-12 09:24:21.264351 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007600
2026-08-12 09:24:21.285659 | 
2026-08-12 09:24:21.285904 | PLAY [all]
2026-08-12 09:24:21.291082 | 
2026-08-12 09:24:21.291149 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-12 09:24:21.777413 | controller | changed
2026-08-12 09:24:21.782201 | 
2026-08-12 09:24:21.782266 | PLAY RECAP
2026-08-12 09:24:21.782328 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-12 09:24:21.782361 | 
2026-08-12 09:24:21.928992 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-12 09:24:21.930428 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-12 09:24:22.519953 | 
2026-08-12 09:24:22.520141 | PLAY [localhost]
2026-08-12 09:24:22.532264 | 
2026-08-12 09:24:22.532352 | TASK [Generate Zuul manifest]
2026-08-12 09:24:22.553475 | localhost | ok
2026-08-12 09:24:22.569431 | 
2026-08-12 09:24:22.569526 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-12 09:24:22.967791 | localhost | changed
2026-08-12 09:24:22.980817 | 
2026-08-12 09:24:22.981120 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-12 09:24:23.014127 | localhost | ok
2026-08-12 09:24:23.024201 | 
2026-08-12 09:24:23.024263 | TASK [Upload logs]
2026-08-12 09:24:23.047659 | localhost | ok
2026-08-12 09:24:23.111846 | 
2026-08-12 09:24:23.111979 | TASK [Set zuul-log-path fact]
2026-08-12 09:24:23.132176 | localhost | ok
2026-08-12 09:24:23.143074 | 
2026-08-12 09:24:23.143154 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-12 09:24:23.174881 | localhost | ok
2026-08-12 09:24:23.184885 | 
2026-08-12 09:24:23.184951 | TASK [upload-logs : Create log directories]
2026-08-12 09:24:23.603401 | localhost | changed
2026-08-12 09:24:23.609565 | 
2026-08-12 09:24:23.609635 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-12 09:24:24.014346 | localhost -> localhost | ok: Runtime: 0:00:00.006011
2026-08-12 09:24:24.020698 | 
2026-08-12 09:24:24.020768 | TASK [upload-logs : Upload logs to log server]
2026-08-12 09:24:24.520705 | localhost | Output suppressed because no_log was given
2026-08-12 09:24:24.524948 | 
2026-08-12 09:24:24.525028 | LOOP [upload-logs : Compress console log and json output]
2026-08-12 09:24:24.575291 | localhost | skipping: Conditional result was False
2026-08-12 09:24:24.631472 | localhost | skipping: Conditional result was False
2026-08-12 09:24:24.642060 | 
2026-08-12 09:24:24.642136 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-12 09:24:24.687795 | localhost | skipping: Conditional result was False
2026-08-12 09:24:24.688146 | 
2026-08-12 09:24:24.692186 | localhost | skipping: Conditional result was False
2026-08-12 09:24:24.705873 | 
2026-08-12 09:24:24.705966 | LOOP [upload-logs : Upload console log and json output]