2026-09-10 00:45:02.904481 | Job console starting
2026-09-10 00:45:02.917881 | Updating git repos
2026-09-10 00:45:02.990412 | Cloning repos into workspace
2026-09-10 00:45:03.142335 | Restoring repo states
2026-09-10 00:45:03.160389 | Merging changes
2026-09-10 00:45:04.042821 | Checking out repos
2026-09-10 00:45:04.274152 | Preparing playbooks
2026-09-10 00:45:07.156955 | Running Ansible setup
2026-09-10 00:45:10.843334 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-10 00:45:11.443963 | 
2026-09-10 00:45:11.444147 | PLAY [localhost]
2026-09-10 00:45:11.451967 | 
2026-09-10 00:45:11.452048 | TASK [Gathering Facts]
2026-09-10 00:45:12.339407 | localhost | ok
2026-09-10 00:45:12.352155 | 
2026-09-10 00:45:12.352325 | TASK [Setup log path fact]
2026-09-10 00:45:12.374957 | localhost | ok
2026-09-10 00:45:12.396425 | 
2026-09-10 00:45:12.396543 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-10 00:45:12.436570 | localhost | ok
2026-09-10 00:45:12.447461 | 
2026-09-10 00:45:12.447605 | TASK [emit-job-header : Print job information]
2026-09-10 00:45:12.499748 | # Job Information
2026-09-10 00:45:12.500010 | Ansible Version: 2.16.18
2026-09-10 00:45:12.500077 | Job: ansible-collection-kubernetes-molecule-helm
2026-09-10 00:45:12.500123 | Pipeline: check
2026-09-10 00:45:12.500165 | Executor: 2d72f0692154
2026-09-10 00:45:12.500205 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/293
2026-09-10 00:45:12.500355 | Event ID: 74b07920-acb0-11f1-857a-6fc2eb2d7428
2026-09-10 00:45:12.505781 | 
2026-09-10 00:45:12.505911 | LOOP [emit-job-header : Print node information]
2026-09-10 00:45:12.599813 | localhost | ok:
2026-09-10 00:45:12.600105 | localhost | # Node Information
2026-09-10 00:45:12.600162 | localhost | Inventory Hostname: controller
2026-09-10 00:45:12.600204 | localhost | Hostname: np0000192420
2026-09-10 00:45:12.600269 | localhost | Username: zuul
2026-09-10 00:45:12.600318 | localhost | Distro: Debian 13.6
2026-09-10 00:45:12.600359 | localhost | Provider: yul1
2026-09-10 00:45:12.600396 | localhost | Region: ca-ymq-1
2026-09-10 00:45:12.600432 | localhost | Label: debian-trixie
2026-09-10 00:45:12.600470 | localhost | Product Name: OpenStack Nova
2026-09-10 00:45:12.600536 | localhost | Interface IP: 162.253.55.113
2026-09-10 00:45:12.619517 | 
2026-09-10 00:45:12.619787 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-10 00:45:13.051350 | localhost -> localhost | changed
2026-09-10 00:45:13.060635 | 
2026-09-10 00:45:13.060801 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-10 00:45:13.986590 | localhost -> localhost | changed
2026-09-10 00:45:13.998801 | 
2026-09-10 00:45:13.998942 | PLAY [all]
2026-09-10 00:45:14.012540 | 
2026-09-10 00:45:14.012689 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-10 00:45:14.245091 | controller -> localhost | ok
2026-09-10 00:45:14.254385 | 
2026-09-10 00:45:14.254521 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-10 00:45:14.286361 | controller | ok
2026-09-10 00:45:14.315736 | controller | included: /var/lib/zuul/builds/1633de5e9c8b4350a02aad8c1d07ac87/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-10 00:45:14.322714 | 
2026-09-10 00:45:14.322807 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-10 00:45:16.640845 | controller -> localhost | Generating public/private rsa key pair.
2026-09-10 00:45:16.641112 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/1633de5e9c8b4350a02aad8c1d07ac87/work/1633de5e9c8b4350a02aad8c1d07ac87_id_rsa
2026-09-10 00:45:16.641172 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/1633de5e9c8b4350a02aad8c1d07ac87/work/1633de5e9c8b4350a02aad8c1d07ac87_id_rsa.pub
2026-09-10 00:45:16.641217 | controller -> localhost | The key fingerprint is:
2026-09-10 00:45:16.641283 | controller -> localhost | SHA256:W/J/RtO/wnRx1bNY2n/3A9JizJr+sRgAfICMo7FDc94 zuul-build-sshkey
2026-09-10 00:45:16.641347 | controller -> localhost | The key's randomart image is:
2026-09-10 00:45:16.641389 | controller -> localhost | +---[RSA 3072]----+
2026-09-10 00:45:16.641439 | controller -> localhost | |  o ..          .|
2026-09-10 00:45:16.641483 | controller -> localhost | |.= +. .        oo|
2026-09-10 00:45:16.641523 | controller -> localhost | |oo= .o .      = +|
2026-09-10 00:45:16.641564 | controller -> localhost | |+  . Eo      o +.|
2026-09-10 00:45:16.641603 | controller -> localhost | | .     .S + .  .+|
2026-09-10 00:45:16.641642 | controller -> localhost | |        .= * +o.=|
2026-09-10 00:45:16.641681 | controller -> localhost | |        ..=.=.o.=|
2026-09-10 00:45:16.641720 | controller -> localhost | |         oo.ooo.o|
2026-09-10 00:45:16.641764 | controller -> localhost | |        .o.o.o..o|
2026-09-10 00:45:16.641804 | controller -> localhost | +----[SHA256]-----+
2026-09-10 00:45:16.641899 | controller -> localhost | ok: Runtime: 0:00:01.869332
2026-09-10 00:45:16.650434 | 
2026-09-10 00:45:16.650600 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-10 00:45:16.683064 | controller | ok
2026-09-10 00:45:16.698213 | controller | included: /var/lib/zuul/builds/1633de5e9c8b4350a02aad8c1d07ac87/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-10 00:45:16.709164 | 
2026-09-10 00:45:16.709298 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-10 00:45:16.733960 | controller | skipping: Conditional result was False
2026-09-10 00:45:16.745419 | 
2026-09-10 00:45:16.745604 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-10 00:45:17.340775 | controller | changed
2026-09-10 00:45:17.345503 | 
2026-09-10 00:45:17.345583 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-10 00:45:17.589433 | controller | ok
2026-09-10 00:45:17.597231 | 
2026-09-10 00:45:17.597385 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-10 00:45:18.206264 | controller | changed
2026-09-10 00:45:18.210312 | 
2026-09-10 00:45:18.210374 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-10 00:45:18.790481 | controller | changed
2026-09-10 00:45:18.798644 | 
2026-09-10 00:45:18.798779 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-10 00:45:18.825409 | controller | skipping: Conditional result was False
2026-09-10 00:45:18.834971 | 
2026-09-10 00:45:18.835108 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-10 00:45:19.481876 | controller -> localhost | changed
2026-09-10 00:45:19.489864 | 
2026-09-10 00:45:19.489923 | TASK [add-build-sshkey : Add back temp key]
2026-09-10 00:45:19.805682 | controller -> localhost | Identity added: /var/lib/zuul/builds/1633de5e9c8b4350a02aad8c1d07ac87/work/1633de5e9c8b4350a02aad8c1d07ac87_id_rsa (zuul-build-sshkey)
2026-09-10 00:45:19.805975 | controller -> localhost | ok: Runtime: 0:00:00.014465
2026-09-10 00:45:19.814158 | 
2026-09-10 00:45:19.814302 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-10 00:45:20.192461 | controller | ok
2026-09-10 00:45:20.201070 | 
2026-09-10 00:45:20.201235 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-10 00:45:20.236868 | controller | skipping: Conditional result was False
2026-09-10 00:45:20.253221 | 
2026-09-10 00:45:20.253452 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-10 00:45:20.678643 | controller | ok
2026-09-10 00:45:20.689849 | 
2026-09-10 00:45:20.689993 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-10 00:45:21.802439 | controller | Output suppressed because no_log was given
2026-09-10 00:45:21.817834 | 
2026-09-10 00:45:21.818038 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-10 00:45:22.054704 | controller | ok: "logs"
2026-09-10 00:45:22.055227 | controller | ok: All items complete
2026-09-10 00:45:22.055355 | 
2026-09-10 00:45:22.262283 | controller | ok: "artifacts"
2026-09-10 00:45:22.448818 | controller | ok: "docs"
2026-09-10 00:45:22.462798 | 
2026-09-10 00:45:22.463064 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-10 00:45:22.719190 | controller | changed: "logs"
2026-09-10 00:45:22.913108 | controller | changed: "artifacts"
2026-09-10 00:45:23.122327 | controller | changed: "docs"
2026-09-10 00:45:23.152041 | 
2026-09-10 00:45:23.153101 | PLAY RECAP
2026-09-10 00:45:23.153211 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-10 00:45:23.153303 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-10 00:45:23.153351 | 
2026-09-10 00:45:23.333088 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-10 00:45:23.336094 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-10 00:45:23.883538 | 
2026-09-10 00:45:23.883658 | PLAY [all]
2026-09-10 00:45:23.892455 | 
2026-09-10 00:45:23.892549 | TASK [setup-uv : Extract archive]
2026-09-10 00:45:26.423389 | controller | changed
2026-09-10 00:45:26.434353 | 
2026-09-10 00:45:26.434516 | TASK [setup-uv : Print version]
2026-09-10 00:45:26.837986 | controller | uv 0.8.13
2026-09-10 00:45:26.970984 | controller | ok: Runtime: 0:00:00.017032
2026-09-10 00:45:26.989688 | 
2026-09-10 00:45:26.989878 | TASK [Install Ansible collection siblings]
2026-09-10 00:45:27.419833 | controller | ok:
2026-09-10 00:45:27.420067 | controller | Skipping ansible.posix: no checked-out sibling
2026-09-10 00:45:27.420202 | controller | Skipping community.crypto: no checked-out sibling
2026-09-10 00:45:27.420250 | controller | Skipping community.general: no checked-out sibling
2026-09-10 00:45:27.420318 | controller | Skipping kubernetes.core: no checked-out sibling
2026-09-10 00:45:27.420357 | controller | Skipping vexxhost.containers: no checked-out sibling
2026-09-10 00:45:27.423056 | 
2026-09-10 00:45:27.423145 | PLAY RECAP
2026-09-10 00:45:27.423229 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-10 00:45:27.423313 | 
2026-09-10 00:45:27.552380 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-10 00:45:27.554037 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-09-10 00:45:28.148111 | 
2026-09-10 00:45:28.148275 | PLAY [all]
2026-09-10 00:45:28.158617 | 
2026-09-10 00:45:28.158691 | TASK [Update apt cache]
2026-09-10 00:45:34.054624 | controller | ok
2026-09-10 00:45:34.059462 | 
2026-09-10 00:45:34.059546 | TASK [Disable firewalld]
2026-09-10 00:45:34.084952 | controller | skipping: Conditional result was False
2026-09-10 00:45:34.089095 | 
2026-09-10 00:45:34.089163 | TASK [Install "jq" for log collection]
2026-09-10 00:45:36.889993 | controller | changed
2026-09-10 00:45:36.892973 | 
2026-09-10 00:45:36.893061 | PLAY RECAP
2026-09-10 00:45:36.893139 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-10 00:45:36.893180 | 
2026-09-10 00:45:37.006076 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-09-10 00:45:37.008543 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-10 00:45:37.573674 | 
2026-09-10 00:45:37.573809 | PLAY [all]
2026-09-10 00:45:37.583097 | 
2026-09-10 00:45:37.583218 | LOOP [Check inventory directories]
2026-09-10 00:45:37.969252 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-09-10 00:45:38.169280 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/helm/inventory"
2026-09-10 00:45:38.373813 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/inventory"
2026-09-10 00:45:38.394548 | 
2026-09-10 00:45:38.394808 | LOOP [Copy inventory file for Zuul]
2026-09-10 00:45:39.345144 | controller | changed:
2026-09-10 00:45:39.345444 | controller | {
2026-09-10 00:45:39.345500 | controller |   "ansible_loop_var": "item",
2026-09-10 00:45:39.345545 | controller |   "changed": false,
2026-09-10 00:45:39.345583 | controller |   "failed": false,
2026-09-10 00:45:39.345627 | controller |   "invocation": {
2026-09-10 00:45:39.345662 | controller |     "module_args": {
2026-09-10 00:45:39.345698 | controller |       "checksum_algorithm": "sha1",
2026-09-10 00:45:39.345733 | controller |       "follow": true,
2026-09-10 00:45:39.345768 | controller |       "get_attributes": true,
2026-09-10 00:45:39.345805 | controller |       "get_checksum": true,
2026-09-10 00:45:39.345839 | controller |       "get_mime": true,
2026-09-10 00:45:39.345874 | controller |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-09-10 00:45:39.345909 | controller |     }
2026-09-10 00:45:39.345943 | controller |   },
2026-09-10 00:45:39.345977 | controller |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-09-10 00:45:39.346011 | controller |   "stat": {
2026-09-10 00:45:39.346045 | controller |     "atime": 1789001121.6489444,
2026-09-10 00:45:39.346080 | controller |     "attr_flags": "e",
2026-09-10 00:45:39.346114 | controller |     "attributes": [
2026-09-10 00:45:39.346148 | controller |       "extents"
2026-09-10 00:45:39.346182 | controller |     ],
2026-09-10 00:45:39.346216 | controller |     "block_size": 4096,
2026-09-10 00:45:39.346249 | controller |     "blocks": 8,
2026-09-10 00:45:39.346309 | controller |     "charset": "unknown",
2026-09-10 00:45:39.346347 | controller |     "ctime": 1789001121.6889446,
2026-09-10 00:45:39.346382 | controller |     "dev": 65025,
2026-09-10 00:45:39.346417 | controller |     "device_type": 0,
2026-09-10 00:45:39.346452 | controller |     "executable": true,
2026-09-10 00:45:39.346486 | controller |     "exists": true,
2026-09-10 00:45:39.346519 | controller |     "gid": 1000,
2026-09-10 00:45:39.346552 | controller |     "gr_name": "zuul",
2026-09-10 00:45:39.346586 | controller |     "inode": 521495,
2026-09-10 00:45:39.346620 | controller |     "isblk": false,
2026-09-10 00:45:39.346654 | controller |     "ischr": false,
2026-09-10 00:45:39.346687 | controller |     "isdir": true,
2026-09-10 00:45:39.346720 | controller |     "isfifo": false,
2026-09-10 00:45:39.346752 | controller |     "isgid": false,
2026-09-10 00:45:39.346784 | controller |     "islnk": false,
2026-09-10 00:45:39.346816 | controller |     "isreg": false,
2026-09-10 00:45:39.346848 | controller |     "issock": false,
2026-09-10 00:45:39.346879 | controller |     "isuid": false,
2026-09-10 00:45:39.346912 | controller |     "mimetype": "unknown",
2026-09-10 00:45:39.346945 | controller |     "mode": "0755",
2026-09-10 00:45:39.346978 | controller |     "mtime": 1789001103.3202095,
2026-09-10 00:45:39.347011 | controller |     "nlink": 14,
2026-09-10 00:45:39.347044 | controller |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-09-10 00:45:39.347078 | controller |     "pw_name": "zuul",
2026-09-10 00:45:39.347117 | controller |     "readable": true,
2026-09-10 00:45:39.347151 | controller |     "rgrp": true,
2026-09-10 00:45:39.347185 | controller |     "roth": true,
2026-09-10 00:45:39.347220 | controller |     "rusr": true,
2026-09-10 00:45:39.347254 | controller |     "size": 4096,
2026-09-10 00:45:39.347306 | controller |     "uid": 1000,
2026-09-10 00:45:39.347342 | controller |     "version": "1408679552",
2026-09-10 00:45:39.347393 | controller |     "wgrp": false,
2026-09-10 00:45:39.347452 | controller |     "woth": false,
2026-09-10 00:45:39.347506 | controller |     "writeable": true,
2026-09-10 00:45:39.347549 | controller |     "wusr": true,
2026-09-10 00:45:39.347584 | controller |     "xgrp": true,
2026-09-10 00:45:39.347618 | controller |     "xoth": true,
2026-09-10 00:45:39.347655 | controller |     "xusr": true
2026-09-10 00:45:39.347688 | controller |   }
2026-09-10 00:45:39.347736 | controller | }
2026-09-10 00:45:39.347803 | controller | ok: All items complete
2026-09-10 00:45:39.347845 | 
2026-09-10 00:45:39.350134 | controller | skipping: Conditional result was False
2026-09-10 00:45:39.351255 | controller | skipping: Conditional result was False
2026-09-10 00:45:39.364212 | 
2026-09-10 00:45:39.364413 | LOOP [Switch "ansible_host" to private IP]
2026-09-10 00:45:39.756361 | controller | changed: 1 replacements made
2026-09-10 00:45:39.758646 | controller | skipping: Conditional result was False
2026-09-10 00:45:39.764907 | controller | skipping: Conditional result was False
2026-09-10 00:45:39.781976 | 
2026-09-10 00:45:39.782201 | TASK [Run Molecule scenario]
2026-09-10 00:45:40.265826 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-09-10 00:45:40.265919 | controller | Creating virtual environment at: .venv
2026-09-10 00:45:42.053459 | controller | Downloading ansible-core (2.3MiB)
2026-09-10 00:45:42.054506 | controller | Downloading cryptography (4.3MiB)
2026-09-10 00:45:42.055373 | controller | Downloading pygments (1.2MiB)
2026-09-10 00:45:42.280989 | controller |  Downloading cryptography
2026-09-10 00:45:42.313926 | controller |  Downloading pygments
2026-09-10 00:45:42.478514 | controller |  Downloading ansible-core
2026-09-10 00:45:42.534760 | controller | Installed 36 packages in 51ms
2026-09-10 00:45:43.180504 | controller | INFO     Collection 'vexxhost.kubernetes' detected.
2026-09-10 00:45:43.180600 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-09-10 00:45:44.083907 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-09-10 00:45:44.084006 | controller | INFO     default scenario not found, disabling shared state.
2026-09-10 00:45:44.084265 | controller | INFO     [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-10 00:45:44.084365 | controller | INFO     [helm > prerun] Performing prerun with role_name_check=0...
2026-09-10 00:50:18.222521 | controller | INFO     [helm > dependency] Executing
2026-09-10 00:50:18.222619 | controller | WARNING  [helm > dependency] Missing roles requirements file: requirements.yml
2026-09-10 00:50:18.222629 | controller | WARNING  [helm > dependency] Missing collections requirements file: collections.yml
2026-09-10 00:50:18.222808 | controller | WARNING  [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-10 00:50:18.233526 | controller | INFO     [helm > cleanup] Executing
2026-09-10 00:50:18.234172 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-10 00:50:18.246304 | controller | INFO     [helm > destroy] Executing
2026-09-10 00:50:18.246520 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-09-10 00:50:18.246743 | controller | INFO     [helm > destroy] Executed: Successful
2026-09-10 00:50:18.255154 | controller | INFO     [helm > syntax] Executing
2026-09-10 00:50:19.114802 | controller |
2026-09-10 00:50:19.115048 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml
2026-09-10 00:50:19.180788 | controller | INFO     [helm > syntax] Executed: Successful
2026-09-10 00:50:19.193697 | controller | INFO     [helm > create] Executing
2026-09-10 00:50:19.196945 | controller | WARNING  [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-10 00:50:19.207481 | controller | INFO     [helm > prepare] Executing
2026-09-10 00:50:19.999268 | controller |
2026-09-10 00:50:19.999413 | controller | PLAY [Prepare] *****************************************************************
2026-09-10 00:50:19.999585 | controller |
2026-09-10 00:50:19.999781 | controller | TASK [Gathering Facts] *********************************************************
2026-09-10 00:50:19.999969 | controller | Thursday 10 September 2026  00:50:19 +0000 (0:00:00.028)       0:00:00.028 ****
2026-09-10 00:50:21.298791 | 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-09-10 00:50:21.304293 | controller | ok: [controller]
2026-09-10 00:50:21.304513 | controller |
2026-09-10 00:50:21.304709 | controller | TASK [Install Debian packages] *************************************************
2026-09-10 00:50:21.304892 | controller | Thursday 10 September 2026  00:50:21 +0000 (0:00:01.305)       0:00:01.333 ****
2026-09-10 00:50:23.095197 | controller | ok: [controller]
2026-09-10 00:50:23.095255 | controller |
2026-09-10 00:50:23.095388 | controller | PLAY RECAP *********************************************************************
2026-09-10 00:50:23.095521 | controller | controller                 : ok=2    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-09-10 00:50:23.095680 | controller |
2026-09-10 00:50:23.095696 | controller |
2026-09-10 00:50:23.095835 | controller | TASKS RECAP ********************************************************************
2026-09-10 00:50:23.095956 | controller | Thursday 10 September 2026  00:50:23 +0000 (0:00:01.791)       0:00:03.124 ****
2026-09-10 00:50:23.096060 | controller | ===============================================================================
2026-09-10 00:50:23.096170 | controller | Install Debian packages ------------------------------------------------- 1.79s
2026-09-10 00:50:23.096278 | controller | Gathering Facts --------------------------------------------------------- 1.31s
2026-09-10 00:50:23.202163 | controller | INFO     [helm > prepare] Executed: Successful
2026-09-10 00:50:23.214225 | controller | INFO     [helm > converge] Executing
2026-09-10 00:50:24.072788 | controller |
2026-09-10 00:50:24.072941 | controller | PLAY [Converge] ****************************************************************
2026-09-10 00:50:24.073157 | controller |
2026-09-10 00:50:24.073305 | controller | TASK [Gathering Facts] *********************************************************
2026-09-10 00:50:24.073511 | controller | Thursday 10 September 2026  00:50:24 +0000 (0:00:00.013)       0:00:00.013 ****
2026-09-10 00:50:26.193727 | 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-09-10 00:50:26.201743 | controller | ok: [controller]
2026-09-10 00:50:26.201861 | controller |
2026-09-10 00:50:26.201994 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-10 00:50:26.202123 | controller | Thursday 10 September 2026  00:50:26 +0000 (0:00:02.128)       0:00:02.142 ****
2026-09-10 00:50:26.746519 | controller | ok: [controller]
2026-09-10 00:50:26.746648 | controller |
2026-09-10 00:50:26.746896 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-10 00:50:26.747068 | controller | Thursday 10 September 2026  00:50:26 +0000 (0:00:00.544)       0:00:02.687 ****
2026-09-10 00:50:27.843187 | controller | ok: [controller]
2026-09-10 00:50:27.843354 | controller |
2026-09-10 00:50:27.843588 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-10 00:50:27.843832 | controller | Thursday 10 September 2026  00:50:27 +0000 (0:00:01.095)       0:00:03.783 ****
2026-09-10 00:50:28.302237 | controller | changed: [controller]
2026-09-10 00:50:28.306133 | controller |
2026-09-10 00:50:28.306155 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-10 00:50:28.306164 | controller | Thursday 10 September 2026  00:50:28 +0000 (0:00:00.458)       0:00:04.241 ****
2026-09-10 00:50:28.366881 | controller | ok: [controller] => {
2026-09-10 00:50:28.367018 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-09-10 00:50:28.367179 | controller | }
2026-09-10 00:50:28.367334 | controller |
2026-09-10 00:50:28.367512 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-10 00:50:28.367690 | controller | Thursday 10 September 2026  00:50:28 +0000 (0:00:00.065)       0:00:04.306 ****
2026-09-10 00:50:31.583694 | 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-09-10 00:50:31.595949 | controller | changed: [controller]
2026-09-10 00:50:31.596161 | controller |
2026-09-10 00:50:31.596430 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-10 00:50:31.596723 | controller | Thursday 10 September 2026  00:50:31 +0000 (0:00:03.229)       0:00:07.535 ****
2026-09-10 00:50:33.797715 | controller | changed: [controller]
2026-09-10 00:50:33.797836 | controller |
2026-09-10 00:50:33.798058 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-09-10 00:50:33.798242 | controller | Thursday 10 September 2026  00:50:33 +0000 (0:00:02.201)       0:00:09.737 ****
2026-09-10 00:50:34.336990 | controller | ok: [controller]
2026-09-10 00:50:34.337110 | controller |
2026-09-10 00:50:34.337348 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-09-10 00:50:34.337566 | controller | Thursday 10 September 2026  00:50:34 +0000 (0:00:00.539)       0:00:10.277 ****
2026-09-10 00:50:34.800456 | controller | changed: [controller]
2026-09-10 00:50:34.800636 | controller |
2026-09-10 00:50:34.800836 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-09-10 00:50:34.801012 | controller | Thursday 10 September 2026  00:50:34 +0000 (0:00:00.463)       0:00:10.740 ****
2026-09-10 00:50:35.324073 | controller | ok: [controller]
2026-09-10 00:50:35.324130 | controller |
2026-09-10 00:50:35.324179 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-09-10 00:50:35.324282 | controller | Thursday 10 September 2026  00:50:35 +0000 (0:00:00.523)       0:00:11.264 ****
2026-09-10 00:50:35.641163 | controller | changed: [controller]
2026-09-10 00:50:35.641347 | controller |
2026-09-10 00:50:35.641665 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-09-10 00:50:35.641930 | controller | Thursday 10 September 2026  00:50:35 +0000 (0:00:00.317)       0:00:11.581 ****
2026-09-10 00:50:35.932278 | controller | ok: [controller]
2026-09-10 00:50:35.932420 | controller |
2026-09-10 00:50:35.932648 | controller | TASK [Install plugin] **********************************************************
2026-09-10 00:50:35.932854 | controller | Thursday 10 September 2026  00:50:35 +0000 (0:00:00.290)       0:00:11.871 ****
2026-09-10 00:50:36.004876 | controller | included: vexxhost.containers.download_artifact for controller
2026-09-10 00:50:36.004951 | controller |
2026-09-10 00:50:36.005148 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-10 00:50:36.005314 | controller | Thursday 10 September 2026  00:50:36 +0000 (0:00:00.073)       0:00:11.945 ****
2026-09-10 00:50:36.064232 | controller | ok: [controller] => {
2026-09-10 00:50:36.064460 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-09-10 00:50:36.064733 | controller | }
2026-09-10 00:50:36.064947 | controller |
2026-09-10 00:50:36.065172 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-10 00:50:36.065350 | controller | Thursday 10 September 2026  00:50:36 +0000 (0:00:00.058)       0:00:12.003 ****
2026-09-10 00:50:37.152891 | controller | changed: [controller]
2026-09-10 00:50:37.152942 | controller |
2026-09-10 00:50:37.152951 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-10 00:50:37.152960 | controller | Thursday 10 September 2026  00:50:37 +0000 (0:00:01.088)       0:00:13.092 ****
2026-09-10 00:50:39.361154 | controller | changed: [controller]
2026-09-10 00:50:39.361470 | controller |
2026-09-10 00:50:39.361709 | controller | PLAY RECAP *********************************************************************
2026-09-10 00:50:39.361961 | controller | controller                 : ok=16   changed=7    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-09-10 00:50:39.362170 | controller |
2026-09-10 00:50:39.362375 | controller |
2026-09-10 00:50:39.362622 | controller | TASKS RECAP ********************************************************************
2026-09-10 00:50:39.362891 | controller | Thursday 10 September 2026  00:50:39 +0000 (0:00:02.210)       0:00:15.302 ****
2026-09-10 00:50:39.363105 | controller | ===============================================================================
2026-09-10 00:50:39.363328 | controller | vexxhost.containers.download_artifact : Download item ------------------- 3.23s
2026-09-10 00:50:39.363579 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.21s
2026-09-10 00:50:39.363809 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.20s
2026-09-10 00:50:39.364028 | controller | Gathering Facts --------------------------------------------------------- 2.13s
2026-09-10 00:50:39.364251 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.10s
2026-09-10 00:50:39.364497 | controller | vexxhost.containers.download_artifact : Download item ------------------- 1.09s
2026-09-10 00:50:39.364723 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.54s
2026-09-10 00:50:39.364947 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.54s
2026-09-10 00:50:39.365170 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.52s
2026-09-10 00:50:39.365414 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.46s
2026-09-10 00:50:39.365665 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.46s
2026-09-10 00:50:39.365899 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.32s
2026-09-10 00:50:39.366135 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.29s
2026-09-10 00:50:39.366356 | controller | Install plugin ---------------------------------------------------------- 0.07s
2026-09-10 00:50:39.366621 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.06s
2026-09-10 00:50:39.366909 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.06s
2026-09-10 00:50:39.479101 | controller | INFO     [helm > converge] Executed: Successful
2026-09-10 00:50:39.493954 | controller | INFO     [helm > idempotence] Executing
2026-09-10 00:50:40.331491 | controller |
2026-09-10 00:50:40.331802 | controller | PLAY [Converge] ****************************************************************
2026-09-10 00:50:40.332007 | controller |
2026-09-10 00:50:40.332224 | controller | TASK [Gathering Facts] *********************************************************
2026-09-10 00:50:40.332467 | controller | Thursday 10 September 2026  00:50:40 +0000 (0:00:00.011)       0:00:00.011 ****
2026-09-10 00:50:41.472176 | 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-09-10 00:50:41.479705 | controller | ok: [controller]
2026-09-10 00:50:41.479805 | controller |
2026-09-10 00:50:41.479909 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-10 00:50:41.480012 | controller | Thursday 10 September 2026  00:50:41 +0000 (0:00:01.148)       0:00:01.159 ****
2026-09-10 00:50:41.951003 | controller | ok: [controller]
2026-09-10 00:50:41.951275 | controller |
2026-09-10 00:50:41.951545 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-10 00:50:41.951778 | controller | Thursday 10 September 2026  00:50:41 +0000 (0:00:00.471)       0:00:01.631 ****
2026-09-10 00:50:43.048175 | controller | ok: [controller]
2026-09-10 00:50:43.048475 | controller |
2026-09-10 00:50:43.048720 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-10 00:50:43.048952 | controller | Thursday 10 September 2026  00:50:43 +0000 (0:00:01.096)       0:00:02.727 ****
2026-09-10 00:50:43.506315 | controller | ok: [controller]
2026-09-10 00:50:43.506579 | controller |
2026-09-10 00:50:43.506989 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-10 00:50:43.507230 | controller | Thursday 10 September 2026  00:50:43 +0000 (0:00:00.458)       0:00:03.185 ****
2026-09-10 00:50:43.569863 | controller | ok: [controller] => {
2026-09-10 00:50:43.570125 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-09-10 00:50:43.570379 | controller | }
2026-09-10 00:50:43.570591 | controller |
2026-09-10 00:50:43.570856 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-10 00:50:43.571084 | controller | Thursday 10 September 2026  00:50:43 +0000 (0:00:00.063)       0:00:03.249 ****
2026-09-10 00:50:44.146430 | controller | ok: [controller]
2026-09-10 00:50:44.146688 | controller |
2026-09-10 00:50:44.146994 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-10 00:50:44.147223 | controller | Thursday 10 September 2026  00:50:44 +0000 (0:00:00.575)       0:00:03.825 ****
2026-09-10 00:50:45.975548 | controller | ok: [controller]
2026-09-10 00:50:45.975748 | controller |
2026-09-10 00:50:45.975936 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-09-10 00:50:45.976131 | controller | Thursday 10 September 2026  00:50:45 +0000 (0:00:01.827)       0:00:05.653 ****
2026-09-10 00:50:46.527339 | controller | ok: [controller]
2026-09-10 00:50:46.527419 | controller |
2026-09-10 00:50:46.527617 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-09-10 00:50:46.527795 | controller | Thursday 10 September 2026  00:50:46 +0000 (0:00:00.550)       0:00:06.204 ****
2026-09-10 00:50:46.983657 | controller | ok: [controller]
2026-09-10 00:50:46.983735 | controller |
2026-09-10 00:50:46.983935 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-09-10 00:50:46.984116 | controller | Thursday 10 September 2026  00:50:46 +0000 (0:00:00.459)       0:00:06.663 ****
2026-09-10 00:50:47.501785 | controller | ok: [controller]
2026-09-10 00:50:47.501868 | controller |
2026-09-10 00:50:47.502068 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-09-10 00:50:47.502268 | controller | Thursday 10 September 2026  00:50:47 +0000 (0:00:00.518)       0:00:07.181 ****
2026-09-10 00:50:47.845873 | controller | ok: [controller]
2026-09-10 00:50:47.845959 | controller |
2026-09-10 00:50:47.845969 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-09-10 00:50:47.846036 | controller | Thursday 10 September 2026  00:50:47 +0000 (0:00:00.341)       0:00:07.522 ****
2026-09-10 00:50:48.163853 | controller | ok: [controller]
2026-09-10 00:50:48.163931 | controller |
2026-09-10 00:50:48.166739 | controller | TASK [Install plugin] **********************************************************
2026-09-10 00:50:48.166757 | controller | Thursday 10 September 2026  00:50:48 +0000 (0:00:00.321)       0:00:07.843 ****
2026-09-10 00:50:48.243676 | controller | included: vexxhost.containers.download_artifact for controller
2026-09-10 00:50:48.243712 | controller |
2026-09-10 00:50:48.243718 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-10 00:50:48.243724 | controller | Thursday 10 September 2026  00:50:48 +0000 (0:00:00.075)       0:00:07.919 ****
2026-09-10 00:50:48.302199 | controller | ok: [controller] => {
2026-09-10 00:50:48.302512 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-09-10 00:50:48.302796 | controller | }
2026-09-10 00:50:48.303069 | controller |
2026-09-10 00:50:48.303342 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-10 00:50:48.303572 | controller | Thursday 10 September 2026  00:50:48 +0000 (0:00:00.062)       0:00:07.981 ****
2026-09-10 00:50:48.767177 | controller | ok: [controller]
2026-09-10 00:50:48.767242 | controller |
2026-09-10 00:50:48.767327 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-10 00:50:48.767472 | controller | Thursday 10 September 2026  00:50:48 +0000 (0:00:00.464)       0:00:08.446 ****
2026-09-10 00:50:50.510885 | controller | ok: [controller]
2026-09-10 00:50:50.510932 | controller |
2026-09-10 00:50:50.510942 | controller | PLAY RECAP *********************************************************************
2026-09-10 00:50:50.510951 | controller | controller                 : ok=16   changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-09-10 00:50:50.510959 | controller |
2026-09-10 00:50:50.510966 | controller |
2026-09-10 00:50:50.510973 | controller | TASKS RECAP ********************************************************************
2026-09-10 00:50:50.510980 | controller | Thursday 10 September 2026  00:50:50 +0000 (0:00:01.743)       0:00:10.189 ****
2026-09-10 00:50:50.510987 | controller | ===============================================================================
2026-09-10 00:50:50.510993 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.83s
2026-09-10 00:50:50.511000 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.74s
2026-09-10 00:50:50.511015 | controller | Gathering Facts --------------------------------------------------------- 1.15s
2026-09-10 00:50:50.513116 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.10s
2026-09-10 00:50:50.513158 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.58s
2026-09-10 00:50:50.513167 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.55s
2026-09-10 00:50:50.513175 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.52s
2026-09-10 00:50:50.513182 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.47s
2026-09-10 00:50:50.513190 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.46s
2026-09-10 00:50:50.513197 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.46s
2026-09-10 00:50:50.513216 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.46s
2026-09-10 00:50:50.513223 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.34s
2026-09-10 00:50:50.513230 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.32s
2026-09-10 00:50:50.513237 | controller | Install plugin ---------------------------------------------------------- 0.08s
2026-09-10 00:50:50.513244 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.06s
2026-09-10 00:50:50.513251 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.06s
2026-09-10 00:50:50.657208 | controller | INFO     [helm > idempotence] Executed: Successful
2026-09-10 00:50:50.668326 | controller | INFO     [helm > side_effect] Executing
2026-09-10 00:50:50.668939 | controller | WARNING  [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-10 00:50:50.679392 | controller | INFO     [helm > verify] Executing
2026-09-10 00:50:51.498215 | controller |
2026-09-10 00:50:51.498467 | controller | PLAY [Verify] ******************************************************************
2026-09-10 00:50:51.498656 | controller |
2026-09-10 00:50:51.498913 | controller | TASK [Gathering Facts] *********************************************************
2026-09-10 00:50:51.499142 | controller | Thursday 10 September 2026  00:50:51 +0000 (0:00:00.009)       0:00:00.009 ****
2026-09-10 00:50:52.659294 | 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-09-10 00:50:52.666555 | controller | ok: [controller]
2026-09-10 00:50:52.666577 | controller |
2026-09-10 00:50:52.666586 | controller | TASK [Get the current Helm version] ********************************************
2026-09-10 00:50:52.666595 | controller | Thursday 10 September 2026  00:50:52 +0000 (0:00:01.167)       0:00:01.176 ****
2026-09-10 00:50:53.157194 | controller | changed: [controller]
2026-09-10 00:50:53.157505 | controller |
2026-09-10 00:50:53.157794 | controller | TASK [Assert Helm version] *****************************************************
2026-09-10 00:50:53.158119 | controller | Thursday 10 September 2026  00:50:53 +0000 (0:00:00.490)       0:00:01.667 ****
2026-09-10 00:50:53.198663 | controller | ok: [controller] => {
2026-09-10 00:50:53.198949 | controller |     "changed": false,
2026-09-10 00:50:53.199203 | controller |     "msg": "All assertions passed"
2026-09-10 00:50:53.199415 | controller | }
2026-09-10 00:50:53.199618 | controller |
2026-09-10 00:50:53.199879 | controller | PLAY RECAP *********************************************************************
2026-09-10 00:50:53.200143 | controller | controller                 : ok=3    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-09-10 00:50:53.200345 | controller |
2026-09-10 00:50:53.200543 | controller |
2026-09-10 00:50:53.200782 | controller | TASKS RECAP ********************************************************************
2026-09-10 00:50:53.201082 | controller | Thursday 10 September 2026  00:50:53 +0000 (0:00:00.042)       0:00:01.710 ****
2026-09-10 00:50:53.201352 | controller | ===============================================================================
2026-09-10 00:50:53.201575 | controller | Gathering Facts --------------------------------------------------------- 1.17s
2026-09-10 00:50:53.201790 | controller | Get the current Helm version -------------------------------------------- 0.49s
2026-09-10 00:50:53.202091 | controller | Assert Helm version ----------------------------------------------------- 0.04s
2026-09-10 00:50:53.306822 | controller | INFO     [helm > verify] Executed: Successful
2026-09-10 00:50:53.322038 | controller | INFO     [helm > cleanup] Executing
2026-09-10 00:50:53.322909 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-10 00:50:53.335497 | controller | INFO     [helm > destroy] Executing
2026-09-10 00:50:53.335728 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-09-10 00:50:53.335982 | controller | INFO     [helm > destroy] Executed: Successful
2026-09-10 00:50:53.336619 | controller | WARNING  Molecule executed 1 scenario (1 missing files)
2026-09-10 00:50:53.518856 | controller | ok: Runtime: 0:05:13.267042
2026-09-10 00:50:53.524951 | 
2026-09-10 00:50:53.525042 | PLAY RECAP
2026-09-10 00:50:53.525119 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-10 00:50:53.525160 | 
2026-09-10 00:50:53.641487 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-10 00:50:53.644287 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-09-10 00:50:54.227681 | 
2026-09-10 00:50:54.227803 | PLAY [all]
2026-09-10 00:50:54.237682 | 
2026-09-10 00:50:54.237757 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-10 00:50:54.652616 | controller | changed
2026-09-10 00:50:54.660917 | 
2026-09-10 00:50:54.661086 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-10 00:50:55.098172 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-10 00:50:55.098397 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-10 00:50:55.106363 | controller | environment: line 1: kubectl: command not found
2026-09-10 00:50:55.108432 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-10 00:50:55.110670 | controller | environment: line 1: kubectl: command not found
2026-09-10 00:50:55.112119 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-10 00:50:55.113893 | controller | environment: line 1: kubectl: command not found
2026-09-10 00:50:55.116343 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-10 00:50:55.117049 | controller | environment: line 1: kubectl: command not found
2026-09-10 00:50:55.119752 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-10 00:50:55.122371 | controller | environment: line 1: kubectl: command not found
2026-09-10 00:50:55.124827 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-10 00:50:55.204914 | controller | ok: Runtime: 0:00:00.043331
2026-09-10 00:50:55.213056 | 
2026-09-10 00:50:55.213191 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-10 00:50:55.490824 | controller | changed
2026-09-10 00:50:55.498678 | 
2026-09-10 00:50:55.498833 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-10 00:50:55.779911 | controller | environment: line 5: kubectl: command not found
2026-09-10 00:50:55.781092 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-10 00:50:55.781321 | controller | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args valuewarning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-10 00:50:55.781335 | controller |
2026-09-10 00:50:56.038386 | controller | ok: Runtime: 0:00:00.013042
2026-09-10 00:50:56.047447 | 
2026-09-10 00:50:56.047606 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-10 00:50:56.740085 | controller | changed:
2026-09-10 00:50:56.740358 | controller | created directory /var/lib/zuul/builds/1633de5e9c8b4350a02aad8c1d07ac87/work/logs/controller
2026-09-10 00:50:56.740651 | controller | cd+++++++++ objects/
2026-09-10 00:50:56.740752 | controller | cd+++++++++ objects/cluster/
2026-09-10 00:50:56.740806 | controller | cd+++++++++ objects/namespaced/
2026-09-10 00:50:56.755435 | 
2026-09-10 00:50:56.755605 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-10 00:50:57.043146 | controller | changed
2026-09-10 00:50:57.050854 | 
2026-09-10 00:50:57.050987 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-10 00:50:57.317030 | controller | changed
2026-09-10 00:50:57.324440 | 
2026-09-10 00:50:57.324646 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-10 00:50:57.595263 | controller | environment: line 3: kubectl: command not found
2026-09-10 00:50:57.867197 | controller | ok: Runtime: 0:00:00.012488
2026-09-10 00:50:57.875293 | 
2026-09-10 00:50:57.875425 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-10 00:50:58.394929 | controller | changed:
2026-09-10 00:50:58.395282 | controller | cd+++++++++ pod-logs/
2026-09-10 00:50:58.395361 | controller | cd+++++++++ pod-logs/failed-pods/
2026-09-10 00:50:58.415315 | 
2026-09-10 00:50:58.415496 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-10 00:50:58.672887 | controller | changed: "values"
2026-09-10 00:50:58.881492 | controller | changed: "releases"
2026-09-10 00:50:58.906693 | 
2026-09-10 00:50:58.906932 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-10 00:50:59.181379 | controller | /bin/bash: line 3: kubectl: command not found
2026-09-10 00:50:59.447961 | controller | ok: Runtime: 0:00:00.009187
2026-09-10 00:50:59.455410 | 
2026-09-10 00:50:59.455534 | TASK [helm-release-status : Downloads logs to executor]
2026-09-10 00:51:00.008052 | controller | changed:
2026-09-10 00:51:00.008332 | controller | cd+++++++++ helm/
2026-09-10 00:51:00.008390 | controller | cd+++++++++ helm/releases/
2026-09-10 00:51:00.008437 | controller | cd+++++++++ helm/values/
2026-09-10 00:51:00.017631 | 
2026-09-10 00:51:00.017759 | PLAY RECAP
2026-09-10 00:51:00.017859 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-10 00:51:00.017902 | 
2026-09-10 00:51:00.190823 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-09-10 00:51:00.192533 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-10 00:51:00.758281 | 
2026-09-10 00:51:00.758848 | PLAY [all]
2026-09-10 00:51:00.767758 | 
2026-09-10 00:51:00.767825 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-10 00:51:00.832270 | controller | skipping: Conditional result was False
2026-09-10 00:51:00.840319 | 
2026-09-10 00:51:00.840478 | TASK [fetch-output : Set log path for single node]
2026-09-10 00:51:00.882152 | controller | ok
2026-09-10 00:51:00.888957 | 
2026-09-10 00:51:00.889087 | LOOP [fetch-output : Ensure local output dirs]
2026-09-10 00:51:01.320679 | controller -> localhost | ok: "/var/lib/zuul/builds/1633de5e9c8b4350a02aad8c1d07ac87/work/logs"
2026-09-10 00:51:01.527128 | controller -> localhost | changed: "/var/lib/zuul/builds/1633de5e9c8b4350a02aad8c1d07ac87/work/artifacts"
2026-09-10 00:51:01.746138 | controller -> localhost | changed: "/var/lib/zuul/builds/1633de5e9c8b4350a02aad8c1d07ac87/work/docs"
2026-09-10 00:51:01.767058 | 
2026-09-10 00:51:01.767274 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-10 00:51:02.421917 | controller | changed: .d..t...... ./
2026-09-10 00:51:02.422269 | controller | changed: All items complete
2026-09-10 00:51:02.422334 | 
2026-09-10 00:51:02.890918 | controller | changed: .d..t...... ./
2026-09-10 00:51:03.426682 | controller | changed: .d..t...... ./
2026-09-10 00:51:03.453970 | 
2026-09-10 00:51:03.454183 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-10 00:51:03.884398 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008757
2026-09-10 00:51:04.135185 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007811
2026-09-10 00:51:04.145892 | 
2026-09-10 00:51:04.146024 | PLAY [all]
2026-09-10 00:51:04.150635 | 
2026-09-10 00:51:04.150699 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-10 00:51:04.661757 | controller | changed
2026-09-10 00:51:04.670615 | 
2026-09-10 00:51:04.670721 | PLAY RECAP
2026-09-10 00:51:04.670812 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-10 00:51:04.670856 | 
2026-09-10 00:51:04.791370 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-10 00:51:04.794584 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-10 00:51:05.332153 | 
2026-09-10 00:51:05.332721 | PLAY [localhost]
2026-09-10 00:51:05.342939 | 
2026-09-10 00:51:05.343030 | TASK [Generate Zuul manifest]
2026-09-10 00:51:05.360116 | localhost | ok
2026-09-10 00:51:05.375043 | 
2026-09-10 00:51:05.375119 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-10 00:51:05.777589 | localhost | changed
2026-09-10 00:51:05.792608 | 
2026-09-10 00:51:05.792718 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-10 00:51:05.822209 | localhost | ok
2026-09-10 00:51:05.830839 | 
2026-09-10 00:51:05.830917 | TASK [Upload logs]
2026-09-10 00:51:05.850120 | localhost | ok
2026-09-10 00:51:05.909355 | 
2026-09-10 00:51:05.909505 | TASK [Set zuul-log-path fact]
2026-09-10 00:51:05.927895 | localhost | ok
2026-09-10 00:51:05.938104 | 
2026-09-10 00:51:05.938169 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-10 00:51:05.975558 | localhost | ok
2026-09-10 00:51:05.982407 | 
2026-09-10 00:51:05.982470 | TASK [upload-logs : Create log directories]
2026-09-10 00:51:06.427000 | localhost | changed
2026-09-10 00:51:06.433290 | 
2026-09-10 00:51:06.433374 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-10 00:51:06.879920 | localhost -> localhost | ok: Runtime: 0:00:00.005955
2026-09-10 00:51:06.886730 | 
2026-09-10 00:51:06.886815 | TASK [upload-logs : Upload logs to log server]
2026-09-10 00:51:07.350703 | localhost | Output suppressed because no_log was given
2026-09-10 00:51:07.355900 | 
2026-09-10 00:51:07.355989 | LOOP [upload-logs : Compress console log and json output]
2026-09-10 00:51:07.423567 | localhost | skipping: Conditional result was False
2026-09-10 00:51:07.475119 | localhost | skipping: Conditional result was False
2026-09-10 00:51:07.487385 | 
2026-09-10 00:51:07.487636 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-10 00:51:07.530426 | localhost | skipping: Conditional result was False
2026-09-10 00:51:07.530778 | 
2026-09-10 00:51:07.531850 | localhost | skipping: Conditional result was False
2026-09-10 00:51:07.537428 | 
2026-09-10 00:51:07.537597 | LOOP [upload-logs : Upload console log and json output]