2026-08-22 09:44:11.164382 | Job console starting
2026-08-22 09:44:11.174393 | Updating git repos
2026-08-22 09:44:11.242877 | Cloning repos into workspace
2026-08-22 09:44:11.284064 | Restoring repo states
2026-08-22 09:44:11.302166 | Merging changes
2026-08-22 09:44:12.127582 | Checking out repos
2026-08-22 09:44:12.359381 | Preparing playbooks
2026-08-22 09:44:15.906185 | Running Ansible setup
2026-08-22 09:44:20.424799 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-22 09:44:21.077743 | 
2026-08-22 09:44:21.077881 | PLAY [localhost]
2026-08-22 09:44:21.085456 | 
2026-08-22 09:44:21.085526 | TASK [Gathering Facts]
2026-08-22 09:44:21.983917 | localhost | ok
2026-08-22 09:44:21.993922 | 
2026-08-22 09:44:21.994003 | TASK [Setup log path fact]
2026-08-22 09:44:22.015776 | localhost | ok
2026-08-22 09:44:22.030591 | 
2026-08-22 09:44:22.030671 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-22 09:44:22.062393 | localhost | ok
2026-08-22 09:44:22.072200 | 
2026-08-22 09:44:22.072291 | TASK [emit-job-header : Print job information]
2026-08-22 09:44:22.113814 | # Job Information
2026-08-22 09:44:22.114023 | Ansible Version: 2.16.18
2026-08-22 09:44:22.114093 | Job: ansible-collection-kubernetes-molecule-helm
2026-08-22 09:44:22.114142 | Pipeline: check
2026-08-22 09:44:22.114186 | Executor: 2d72f0692154
2026-08-22 09:44:22.114231 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/298
2026-08-22 09:44:22.114280 | Event ID: d2568e00-9e0c-11f1-9788-11ee75b72ba0
2026-08-22 09:44:22.117886 | 
2026-08-22 09:44:22.117952 | LOOP [emit-job-header : Print node information]
2026-08-22 09:44:22.208206 | localhost | ok:
2026-08-22 09:44:22.208414 | localhost | # Node Information
2026-08-22 09:44:22.208443 | localhost | Inventory Hostname: controller
2026-08-22 09:44:22.208464 | localhost | Hostname: np0000190507
2026-08-22 09:44:22.208484 | localhost | Username: zuul
2026-08-22 09:44:22.208508 | localhost | Distro: Debian 13.6
2026-08-22 09:44:22.208528 | localhost | Provider: yul1
2026-08-22 09:44:22.208547 | localhost | Region: ca-ymq-1
2026-08-22 09:44:22.208566 | localhost | Label: debian-trixie
2026-08-22 09:44:22.208584 | localhost | Product Name: OpenStack Nova
2026-08-22 09:44:22.208603 | localhost | Interface IP: 199.204.45.227
2026-08-22 09:44:22.225349 | 
2026-08-22 09:44:22.225510 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-22 09:44:22.656271 | localhost -> localhost | changed
2026-08-22 09:44:22.661809 | 
2026-08-22 09:44:22.661887 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-22 09:44:23.540025 | localhost -> localhost | changed
2026-08-22 09:44:23.550405 | 
2026-08-22 09:44:23.550493 | PLAY [all]
2026-08-22 09:44:23.558406 | 
2026-08-22 09:44:23.558476 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-22 09:44:23.786266 | controller -> localhost | ok
2026-08-22 09:44:23.794880 | 
2026-08-22 09:44:23.794948 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-22 09:44:23.826384 | controller | ok
2026-08-22 09:44:23.842306 | controller | included: /var/lib/zuul/builds/d208acac9acf475a85bc61fb9e948e57/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-22 09:44:23.847236 | 
2026-08-22 09:44:23.847295 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-22 09:44:24.644691 | controller -> localhost | Generating public/private rsa key pair.
2026-08-22 09:44:24.644904 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/d208acac9acf475a85bc61fb9e948e57/work/d208acac9acf475a85bc61fb9e948e57_id_rsa
2026-08-22 09:44:24.644936 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/d208acac9acf475a85bc61fb9e948e57/work/d208acac9acf475a85bc61fb9e948e57_id_rsa.pub
2026-08-22 09:44:24.644998 | controller -> localhost | The key fingerprint is:
2026-08-22 09:44:24.645022 | controller -> localhost | SHA256:J1CjHBCO8oX9lYppOPMYEd6e/3sMaIGl3SkHRWPCRl0 zuul-build-sshkey
2026-08-22 09:44:24.645069 | controller -> localhost | The key's randomart image is:
2026-08-22 09:44:24.645090 | controller -> localhost | +---[RSA 3072]----+
2026-08-22 09:44:24.645120 | controller -> localhost | |  . o=++B.E      |
2026-08-22 09:44:24.645142 | controller -> localhost | | . B .=*.+       |
2026-08-22 09:44:24.645164 | controller -> localhost | |. = =*+oo.       |
2026-08-22 09:44:24.645194 | controller -> localhost | | o =o=+++        |
2026-08-22 09:44:24.645218 | controller -> localhost | |  * * o=S .      |
2026-08-22 09:44:24.645239 | controller -> localhost | |   B .o .o       |
2026-08-22 09:44:24.645259 | controller -> localhost | |  . ...  o       |
2026-08-22 09:44:24.645283 | controller -> localhost | |       .  o      |
2026-08-22 09:44:24.645314 | controller -> localhost | |        oo       |
2026-08-22 09:44:24.645339 | controller -> localhost | +----[SHA256]-----+
2026-08-22 09:44:24.645409 | controller -> localhost | ok: Runtime: 0:00:00.387140
2026-08-22 09:44:24.650509 | 
2026-08-22 09:44:24.650586 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-22 09:44:24.683667 | controller | ok
2026-08-22 09:44:24.693323 | controller | included: /var/lib/zuul/builds/d208acac9acf475a85bc61fb9e948e57/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-22 09:44:24.700078 | 
2026-08-22 09:44:24.700145 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-22 09:44:24.725599 | controller | skipping: Conditional result was False
2026-08-22 09:44:24.735389 | 
2026-08-22 09:44:24.735461 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-22 09:44:25.294879 | controller | changed
2026-08-22 09:44:25.299396 | 
2026-08-22 09:44:25.299484 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-22 09:44:25.513399 | controller | ok
2026-08-22 09:44:25.519454 | 
2026-08-22 09:44:25.519544 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-22 09:44:26.038281 | controller | changed
2026-08-22 09:44:26.044390 | 
2026-08-22 09:44:26.044479 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-22 09:44:26.572191 | controller | changed
2026-08-22 09:44:26.579969 | 
2026-08-22 09:44:26.580033 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-22 09:44:26.605336 | controller | skipping: Conditional result was False
2026-08-22 09:44:26.613978 | 
2026-08-22 09:44:26.614209 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-22 09:44:26.985052 | controller -> localhost | changed
2026-08-22 09:44:26.996064 | 
2026-08-22 09:44:26.996145 | TASK [add-build-sshkey : Add back temp key]
2026-08-22 09:44:27.297112 | controller -> localhost | Identity added: /var/lib/zuul/builds/d208acac9acf475a85bc61fb9e948e57/work/d208acac9acf475a85bc61fb9e948e57_id_rsa (zuul-build-sshkey)
2026-08-22 09:44:27.297363 | controller -> localhost | ok: Runtime: 0:00:00.014137
2026-08-22 09:44:27.303970 | 
2026-08-22 09:44:27.304036 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-22 09:44:27.652377 | controller | ok
2026-08-22 09:44:27.656707 | 
2026-08-22 09:44:27.656818 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-22 09:44:27.682542 | controller | skipping: Conditional result was False
2026-08-22 09:44:27.695395 | 
2026-08-22 09:44:27.695577 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-22 09:44:28.061512 | controller | ok
2026-08-22 09:44:28.067679 | 
2026-08-22 09:44:28.067747 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-22 09:44:29.052217 | controller | Output suppressed because no_log was given
2026-08-22 09:44:29.061589 | 
2026-08-22 09:44:29.061679 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-22 09:44:29.290399 | controller | ok: "logs"
2026-08-22 09:44:29.291006 | controller | ok: All items complete
2026-08-22 09:44:29.291050 | 
2026-08-22 09:44:29.482168 | controller | ok: "artifacts"
2026-08-22 09:44:29.693789 | controller | ok: "docs"
2026-08-22 09:44:29.708835 | 
2026-08-22 09:44:29.709005 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-22 09:44:29.929015 | controller | changed: "logs"
2026-08-22 09:44:30.132173 | controller | changed: "artifacts"
2026-08-22 09:44:30.326921 | controller | changed: "docs"
2026-08-22 09:44:30.335574 | 
2026-08-22 09:44:30.335705 | PLAY RECAP
2026-08-22 09:44:30.335757 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-22 09:44:30.335843 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 09:44:30.335869 | 
2026-08-22 09:44:30.510895 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-22 09:44:30.514468 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-22 09:44:31.089145 | 
2026-08-22 09:44:31.089267 | PLAY [all]
2026-08-22 09:44:31.098569 | 
2026-08-22 09:44:31.098644 | TASK [setup-uv : Extract archive]
2026-08-22 09:44:33.566633 | controller | changed
2026-08-22 09:44:33.574042 | 
2026-08-22 09:44:33.574211 | TASK [setup-uv : Print version]
2026-08-22 09:44:33.977558 | controller | uv 0.8.13
2026-08-22 09:44:34.105889 | controller | ok: Runtime: 0:00:00.015853
2026-08-22 09:44:34.117249 | 
2026-08-22 09:44:34.117385 | TASK [Install Ansible collection siblings]
2026-08-22 09:44:34.525968 | controller | ok:
2026-08-22 09:44:34.526256 | controller | Skipping ansible.posix: no checked-out sibling
2026-08-22 09:44:34.526319 | controller | Skipping community.crypto: no checked-out sibling
2026-08-22 09:44:34.526368 | controller | Skipping community.general: no checked-out sibling
2026-08-22 09:44:34.526413 | controller | Skipping kubernetes.core: no checked-out sibling
2026-08-22 09:44:34.526457 | controller | Skipping vexxhost.containers: no checked-out sibling
2026-08-22 09:44:34.529518 | 
2026-08-22 09:44:34.529688 | PLAY RECAP
2026-08-22 09:44:34.529811 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 09:44:34.529840 | 
2026-08-22 09:44:34.718216 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-22 09:44:34.721111 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-08-22 09:44:35.371909 | 
2026-08-22 09:44:35.372140 | PLAY [all]
2026-08-22 09:44:35.386506 | 
2026-08-22 09:44:35.386600 | TASK [Update apt cache]
2026-08-22 09:44:40.605334 | controller | ok
2026-08-22 09:44:40.611555 | 
2026-08-22 09:44:40.611659 | TASK [Disable firewalld]
2026-08-22 09:44:40.637369 | controller | skipping: Conditional result was False
2026-08-22 09:44:40.646774 | 
2026-08-22 09:44:40.646848 | TASK [Install "jq" for log collection]
2026-08-22 09:44:43.218739 | controller | changed
2026-08-22 09:44:43.221498 | 
2026-08-22 09:44:43.221570 | PLAY RECAP
2026-08-22 09:44:43.221636 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-22 09:44:43.221667 | 
2026-08-22 09:44:43.366033 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-08-22 09:44:43.369000 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-22 09:44:44.005148 | 
2026-08-22 09:44:44.005313 | PLAY [all]
2026-08-22 09:44:44.016440 | 
2026-08-22 09:44:44.016600 | LOOP [Check inventory directories]
2026-08-22 09:44:44.432528 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-08-22 09:44:44.624287 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/helm/inventory"
2026-08-22 09:44:44.806293 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/inventory"
2026-08-22 09:44:44.818895 | 
2026-08-22 09:44:44.819175 | LOOP [Copy inventory file for Zuul]
2026-08-22 09:44:45.722237 | controller | changed:
2026-08-22 09:44:45.722459 | controller | {
2026-08-22 09:44:45.722487 | controller |   "ansible_loop_var": "item",
2026-08-22 09:44:45.722510 | controller |   "changed": false,
2026-08-22 09:44:45.722529 | controller |   "failed": false,
2026-08-22 09:44:45.722556 | controller |   "invocation": {
2026-08-22 09:44:45.722576 | controller |     "module_args": {
2026-08-22 09:44:45.722595 | controller |       "checksum_algorithm": "sha1",
2026-08-22 09:44:45.722613 | controller |       "follow": true,
2026-08-22 09:44:45.722631 | controller |       "get_attributes": true,
2026-08-22 09:44:45.722650 | controller |       "get_checksum": true,
2026-08-22 09:44:45.722668 | controller |       "get_mime": true,
2026-08-22 09:44:45.722686 | controller |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-08-22 09:44:45.722703 | controller |     }
2026-08-22 09:44:45.722721 | controller |   },
2026-08-22 09:44:45.722738 | controller |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-08-22 09:44:45.722755 | controller |   "stat": {
2026-08-22 09:44:45.722772 | controller |     "atime": 1787391868.9219642,
2026-08-22 09:44:45.722790 | controller |     "attr_flags": "e",
2026-08-22 09:44:45.722807 | controller |     "attributes": [
2026-08-22 09:44:45.722825 | controller |       "extents"
2026-08-22 09:44:45.722842 | controller |     ],
2026-08-22 09:44:45.722859 | controller |     "block_size": 4096,
2026-08-22 09:44:45.722876 | controller |     "blocks": 8,
2026-08-22 09:44:45.722894 | controller |     "charset": "unknown",
2026-08-22 09:44:45.722913 | controller |     "ctime": 1787391868.9419644,
2026-08-22 09:44:45.722931 | controller |     "dev": 65025,
2026-08-22 09:44:45.722949 | controller |     "device_type": 0,
2026-08-22 09:44:45.722967 | controller |     "executable": true,
2026-08-22 09:44:45.722984 | controller |     "exists": true,
2026-08-22 09:44:45.723001 | controller |     "gid": 1000,
2026-08-22 09:44:45.723019 | controller |     "gr_name": "zuul",
2026-08-22 09:44:45.723036 | controller |     "inode": 520215,
2026-08-22 09:44:45.723053 | controller |     "isblk": false,
2026-08-22 09:44:45.723071 | controller |     "ischr": false,
2026-08-22 09:44:45.723088 | controller |     "isdir": true,
2026-08-22 09:44:45.723105 | controller |     "isfifo": false,
2026-08-22 09:44:45.723122 | controller |     "isgid": false,
2026-08-22 09:44:45.723138 | controller |     "islnk": false,
2026-08-22 09:44:45.723155 | controller |     "isreg": false,
2026-08-22 09:44:45.723172 | controller |     "issock": false,
2026-08-22 09:44:45.723188 | controller |     "isuid": false,
2026-08-22 09:44:45.723205 | controller |     "mimetype": "unknown",
2026-08-22 09:44:45.723222 | controller |     "mode": "0755",
2026-08-22 09:44:45.723239 | controller |     "mtime": 1787391851.9895282,
2026-08-22 09:44:45.723255 | controller |     "nlink": 15,
2026-08-22 09:44:45.723272 | controller |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-08-22 09:44:45.723289 | controller |     "pw_name": "zuul",
2026-08-22 09:44:45.723309 | controller |     "readable": true,
2026-08-22 09:44:45.723327 | controller |     "rgrp": true,
2026-08-22 09:44:45.723345 | controller |     "roth": true,
2026-08-22 09:44:45.723362 | controller |     "rusr": true,
2026-08-22 09:44:45.723380 | controller |     "size": 4096,
2026-08-22 09:44:45.723398 | controller |     "uid": 1000,
2026-08-22 09:44:45.723416 | controller |     "version": "1914161741",
2026-08-22 09:44:45.723433 | controller |     "wgrp": false,
2026-08-22 09:44:45.723452 | controller |     "woth": false,
2026-08-22 09:44:45.723470 | controller |     "writeable": true,
2026-08-22 09:44:45.723487 | controller |     "wusr": true,
2026-08-22 09:44:45.723504 | controller |     "xgrp": true,
2026-08-22 09:44:45.723521 | controller |     "xoth": true,
2026-08-22 09:44:45.723538 | controller |     "xusr": true
2026-08-22 09:44:45.723555 | controller |   }
2026-08-22 09:44:45.723585 | controller | }
2026-08-22 09:44:45.723624 | controller | ok: All items complete
2026-08-22 09:44:45.723646 | 
2026-08-22 09:44:45.724862 | controller | skipping: Conditional result was False
2026-08-22 09:44:45.725457 | controller | skipping: Conditional result was False
2026-08-22 09:44:45.741130 | 
2026-08-22 09:44:45.741313 | LOOP [Switch "ansible_host" to private IP]
2026-08-22 09:44:46.127625 | controller | changed: 1 replacements made
2026-08-22 09:44:46.129405 | controller | skipping: Conditional result was False
2026-08-22 09:44:46.131063 | controller | skipping: Conditional result was False
2026-08-22 09:44:46.144430 | 
2026-08-22 09:44:46.144609 | TASK [Run Molecule scenario]
2026-08-22 09:44:46.578770 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-08-22 09:44:46.578934 | controller | Creating virtual environment at: .venv
2026-08-22 09:44:46.856239 | controller | Downloading pygments (1.2MiB)
2026-08-22 09:44:46.857660 | controller | Downloading ansible-core (2.3MiB)
2026-08-22 09:44:46.901324 | controller | Downloading cryptography (4.3MiB)
2026-08-22 09:44:47.020284 | controller |  Downloading cryptography
2026-08-22 09:44:47.077631 | controller |  Downloading pygments
2026-08-22 09:44:47.244836 | controller |  Downloading ansible-core
2026-08-22 09:44:47.294180 | controller | Installed 36 packages in 48ms
2026-08-22 09:44:48.031437 | controller | INFO     Collection 'vexxhost.kubernetes' detected.
2026-08-22 09:44:48.031511 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-08-22 09:44:48.854934 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-08-22 09:44:48.855011 | controller | INFO     default scenario not found, disabling shared state.
2026-08-22 09:44:48.855034 | controller | INFO     [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-08-22 09:44:48.855106 | controller | INFO     [helm > prerun] Performing prerun with role_name_check=0...
2026-08-22 09:45:13.682973 | controller | INFO     [helm > dependency] Executing
2026-08-22 09:45:13.683070 | controller | WARNING  [helm > dependency] Missing roles requirements file: requirements.yml
2026-08-22 09:45:13.683413 | controller | WARNING  [helm > dependency] Missing collections requirements file: collections.yml
2026-08-22 09:45:13.683562 | controller | WARNING  [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-08-22 09:45:13.691375 | controller | INFO     [helm > cleanup] Executing
2026-08-22 09:45:13.691858 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-22 09:45:13.699634 | controller | INFO     [helm > destroy] Executing
2026-08-22 09:45:13.699669 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-08-22 09:45:13.699804 | controller | INFO     [helm > destroy] Executed: Successful
2026-08-22 09:45:13.707404 | controller | INFO     [helm > syntax] Executing
2026-08-22 09:45:15.130209 | controller |
2026-08-22 09:45:15.130405 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml
2026-08-22 09:45:15.203429 | controller | INFO     [helm > syntax] Executed: Successful
2026-08-22 09:45:15.214081 | controller | INFO     [helm > create] Executing
2026-08-22 09:45:15.216204 | controller | WARNING  [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-22 09:45:15.221615 | controller | INFO     [helm > prepare] Executing
2026-08-22 09:45:16.165135 | controller |
2026-08-22 09:45:16.165235 | controller | PLAY [Prepare] *****************************************************************
2026-08-22 09:45:16.165407 | controller |
2026-08-22 09:45:16.165608 | controller | TASK [Gathering Facts] *********************************************************
2026-08-22 09:45:16.165991 | controller | Saturday 22 August 2026  09:45:16 +0000 (0:00:00.031)       0:00:00.031 *******
2026-08-22 09:45:17.438163 | 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-22 09:45:17.444663 | controller | ok: [controller]
2026-08-22 09:45:17.444901 | controller |
2026-08-22 09:45:17.445116 | controller | TASK [Install Debian packages] *************************************************
2026-08-22 09:45:17.445331 | controller | Saturday 22 August 2026  09:45:17 +0000 (0:00:01.280)       0:00:01.312 *******
2026-08-22 09:45:18.815794 | controller | ok: [controller]
2026-08-22 09:45:18.815906 | controller |
2026-08-22 09:45:18.816115 | controller | PLAY RECAP *********************************************************************
2026-08-22 09:45:18.816336 | controller | controller                 : ok=2    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-08-22 09:45:18.816517 | controller |
2026-08-22 09:45:18.816694 | controller |
2026-08-22 09:45:18.816891 | controller | TASKS RECAP ********************************************************************
2026-08-22 09:45:18.819805 | controller | Saturday 22 August 2026  09:45:18 +0000 (0:00:01.370)       0:00:02.683 *******
2026-08-22 09:45:18.819959 | controller | ===============================================================================
2026-08-22 09:45:18.820053 | controller | Install Debian packages ------------------------------------------------- 1.37s
2026-08-22 09:45:18.820146 | controller | Gathering Facts --------------------------------------------------------- 1.28s
2026-08-22 09:45:18.930264 | controller | INFO     [helm > prepare] Executed: Successful
2026-08-22 09:45:18.939037 | controller | INFO     [helm > converge] Executing
2026-08-22 09:45:19.811936 | controller |
2026-08-22 09:45:19.812023 | controller | PLAY [Converge] ****************************************************************
2026-08-22 09:45:19.812199 | controller |
2026-08-22 09:45:19.812409 | controller | TASK [Gathering Facts] *********************************************************
2026-08-22 09:45:19.812619 | controller | Saturday 22 August 2026  09:45:19 +0000 (0:00:00.013)       0:00:00.013 *******
2026-08-22 09:45:20.938161 | 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-22 09:45:20.945219 | controller | ok: [controller]
2026-08-22 09:45:20.945388 | controller |
2026-08-22 09:45:20.945586 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-22 09:45:20.945788 | controller | Saturday 22 August 2026  09:45:20 +0000 (0:00:01.134)       0:00:01.147 *******
2026-08-22 09:45:21.464558 | controller | ok: [controller]
2026-08-22 09:45:21.464591 | controller |
2026-08-22 09:45:21.464596 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-22 09:45:21.464600 | controller | Saturday 22 August 2026  09:45:21 +0000 (0:00:00.514)       0:00:01.662 *******
2026-08-22 09:45:22.483683 | controller | ok: [controller]
2026-08-22 09:45:22.483754 | controller |
2026-08-22 09:45:22.483853 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-08-22 09:45:22.483948 | controller | Saturday 22 August 2026  09:45:22 +0000 (0:00:01.023)       0:00:02.686 *******
2026-08-22 09:45:22.910077 | controller | changed: [controller]
2026-08-22 09:45:22.910205 | controller |
2026-08-22 09:45:22.910412 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 09:45:22.910611 | controller | Saturday 22 August 2026  09:45:22 +0000 (0:00:00.426)       0:00:03.112 *******
2026-08-22 09:45:22.955270 | controller | ok: [controller] => {
2026-08-22 09:45:22.955498 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-08-22 09:45:22.955744 | controller | }
2026-08-22 09:45:22.955916 | controller |
2026-08-22 09:45:22.956138 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 09:45:22.956358 | controller | Saturday 22 August 2026  09:45:22 +0000 (0:00:00.045)       0:00:03.157 *******
2026-08-22 09:45:23.934498 | 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-22 09:45:23.944012 | controller | changed: [controller]
2026-08-22 09:45:23.944242 | controller |
2026-08-22 09:45:23.944450 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 09:45:23.944656 | controller | Saturday 22 August 2026  09:45:23 +0000 (0:00:00.988)       0:00:04.146 *******
2026-08-22 09:45:25.971739 | controller | changed: [controller]
2026-08-22 09:45:25.971949 | controller |
2026-08-22 09:45:25.972155 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-08-22 09:45:25.972371 | controller | Saturday 22 August 2026  09:45:25 +0000 (0:00:02.027)       0:00:06.173 *******
2026-08-22 09:45:26.539526 | controller | ok: [controller]
2026-08-22 09:45:26.539759 | controller |
2026-08-22 09:45:26.540027 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-08-22 09:45:26.540275 | controller | Saturday 22 August 2026  09:45:26 +0000 (0:00:00.567)       0:00:06.740 *******
2026-08-22 09:45:26.993160 | controller | changed: [controller]
2026-08-22 09:45:26.993295 | controller |
2026-08-22 09:45:26.993531 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-08-22 09:45:26.993706 | controller | Saturday 22 August 2026  09:45:26 +0000 (0:00:00.454)       0:00:07.195 *******
2026-08-22 09:45:27.560552 | controller | ok: [controller]
2026-08-22 09:45:27.560745 | controller |
2026-08-22 09:45:27.561005 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-08-22 09:45:27.561230 | controller | Saturday 22 August 2026  09:45:27 +0000 (0:00:00.567)       0:00:07.762 *******
2026-08-22 09:45:27.896014 | controller | changed: [controller]
2026-08-22 09:45:27.896078 | controller |
2026-08-22 09:45:27.896223 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-08-22 09:45:27.896349 | controller | Saturday 22 August 2026  09:45:27 +0000 (0:00:00.332)       0:00:08.094 *******
2026-08-22 09:45:28.197435 | controller | ok: [controller]
2026-08-22 09:45:28.197502 | controller |
2026-08-22 09:45:28.197634 | controller | TASK [Install plugin] **********************************************************
2026-08-22 09:45:28.197756 | controller | Saturday 22 August 2026  09:45:28 +0000 (0:00:00.304)       0:00:08.399 *******
2026-08-22 09:45:28.258092 | controller | included: vexxhost.containers.download_artifact for controller
2026-08-22 09:45:28.258174 | controller |
2026-08-22 09:45:28.258269 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 09:45:28.258361 | controller | Saturday 22 August 2026  09:45:28 +0000 (0:00:00.061)       0:00:08.460 *******
2026-08-22 09:45:28.311326 | controller | ok: [controller] => {
2026-08-22 09:45:28.311473 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-08-22 09:45:28.311597 | controller | }
2026-08-22 09:45:28.311717 | controller |
2026-08-22 09:45:28.311890 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 09:45:28.311984 | controller | Saturday 22 August 2026  09:45:28 +0000 (0:00:00.052)       0:00:08.513 *******
2026-08-22 09:45:29.160352 | controller | changed: [controller]
2026-08-22 09:45:29.160388 | controller |
2026-08-22 09:45:29.160394 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 09:45:29.160400 | controller | Saturday 22 August 2026  09:45:29 +0000 (0:00:00.845)       0:00:09.358 *******
2026-08-22 09:45:31.287889 | controller | changed: [controller]
2026-08-22 09:45:31.287964 | controller |
2026-08-22 09:45:31.288253 | controller | PLAY RECAP *********************************************************************
2026-08-22 09:45:31.288378 | controller | controller                 : ok=16   changed=7    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-08-22 09:45:31.288423 | controller |
2026-08-22 09:45:31.288513 | controller |
2026-08-22 09:45:31.290147 | controller | TASKS RECAP ********************************************************************
2026-08-22 09:45:31.290180 | controller | Saturday 22 August 2026  09:45:31 +0000 (0:00:02.128)       0:00:11.487 *******
2026-08-22 09:45:31.290184 | controller | ===============================================================================
2026-08-22 09:45:31.290188 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.13s
2026-08-22 09:45:31.290191 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.03s
2026-08-22 09:45:31.290194 | controller | Gathering Facts --------------------------------------------------------- 1.13s
2026-08-22 09:45:31.290198 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.02s
2026-08-22 09:45:31.290201 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.99s
2026-08-22 09:45:31.290204 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.85s
2026-08-22 09:45:31.290207 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.57s
2026-08-22 09:45:31.290210 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.57s
2026-08-22 09:45:31.290215 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.51s
2026-08-22 09:45:31.290218 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.45s
2026-08-22 09:45:31.290233 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.43s
2026-08-22 09:45:31.290236 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.33s
2026-08-22 09:45:31.290240 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.30s
2026-08-22 09:45:31.290244 | controller | Install plugin ---------------------------------------------------------- 0.06s
2026-08-22 09:45:31.290247 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-08-22 09:45:31.290250 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-08-22 09:45:31.513359 | controller | INFO     [helm > converge] Executed: Successful
2026-08-22 09:45:31.522699 | controller | INFO     [helm > idempotence] Executing
2026-08-22 09:45:32.444375 | controller |
2026-08-22 09:45:32.444705 | controller | PLAY [Converge] ****************************************************************
2026-08-22 09:45:32.444940 | controller |
2026-08-22 09:45:32.445188 | controller | TASK [Gathering Facts] *********************************************************
2026-08-22 09:45:32.445435 | controller | Saturday 22 August 2026  09:45:32 +0000 (0:00:00.013)       0:00:00.013 *******
2026-08-22 09:45:33.565212 | 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-22 09:45:33.570856 | controller | ok: [controller]
2026-08-22 09:45:33.571130 | controller |
2026-08-22 09:45:33.571479 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-22 09:45:33.571764 | controller | Saturday 22 August 2026  09:45:33 +0000 (0:00:01.126)       0:00:01.139 *******
2026-08-22 09:45:34.013749 | controller | ok: [controller]
2026-08-22 09:45:34.014058 | controller |
2026-08-22 09:45:34.014482 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-22 09:45:34.014869 | controller | Saturday 22 August 2026  09:45:34 +0000 (0:00:00.444)       0:00:01.583 *******
2026-08-22 09:45:36.137866 | controller | ok: [controller]
2026-08-22 09:45:36.138174 | controller |
2026-08-22 09:45:36.138488 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-08-22 09:45:36.138770 | controller | Saturday 22 August 2026  09:45:36 +0000 (0:00:02.124)       0:00:03.707 *******
2026-08-22 09:45:36.611821 | controller | ok: [controller]
2026-08-22 09:45:36.611877 | controller |
2026-08-22 09:45:36.611964 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 09:45:36.612101 | controller | Saturday 22 August 2026  09:45:36 +0000 (0:00:00.470)       0:00:04.178 *******
2026-08-22 09:45:36.658677 | controller | ok: [controller] => {
2026-08-22 09:45:36.658753 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-08-22 09:45:36.658842 | controller | }
2026-08-22 09:45:36.658927 | controller |
2026-08-22 09:45:36.659023 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 09:45:36.659163 | controller | Saturday 22 August 2026  09:45:36 +0000 (0:00:00.050)       0:00:04.229 *******
2026-08-22 09:45:37.198631 | controller | ok: [controller]
2026-08-22 09:45:37.198713 | controller |
2026-08-22 09:45:37.198792 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 09:45:37.198911 | controller | Saturday 22 August 2026  09:45:37 +0000 (0:00:00.536)       0:00:04.765 *******
2026-08-22 09:45:38.988908 | controller | ok: [controller]
2026-08-22 09:45:38.988970 | controller |
2026-08-22 09:45:38.989111 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-08-22 09:45:38.989236 | controller | Saturday 22 August 2026  09:45:38 +0000 (0:00:01.792)       0:00:06.558 *******
2026-08-22 09:45:39.501532 | controller | ok: [controller]
2026-08-22 09:45:39.501578 | controller |
2026-08-22 09:45:39.501667 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-08-22 09:45:39.501753 | controller | Saturday 22 August 2026  09:45:39 +0000 (0:00:00.512)       0:00:07.071 *******
2026-08-22 09:45:39.895773 | controller | ok: [controller]
2026-08-22 09:45:39.895824 | controller |
2026-08-22 09:45:39.896103 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-08-22 09:45:39.896117 | controller | Saturday 22 August 2026  09:45:39 +0000 (0:00:00.393)       0:00:07.465 *******
2026-08-22 09:45:40.360040 | controller | ok: [controller]
2026-08-22 09:45:40.360060 | controller |
2026-08-22 09:45:40.360065 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-08-22 09:45:40.360069 | controller | Saturday 22 August 2026  09:45:40 +0000 (0:00:00.462)       0:00:07.928 *******
2026-08-22 09:45:40.660220 | controller | ok: [controller]
2026-08-22 09:45:40.660281 | controller |
2026-08-22 09:45:40.660291 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-08-22 09:45:40.660299 | controller | Saturday 22 August 2026  09:45:40 +0000 (0:00:00.300)       0:00:08.229 *******
2026-08-22 09:45:40.974461 | controller | ok: [controller]
2026-08-22 09:45:40.974513 | controller |
2026-08-22 09:45:40.974633 | controller | TASK [Install plugin] **********************************************************
2026-08-22 09:45:40.974755 | controller | Saturday 22 August 2026  09:45:40 +0000 (0:00:00.315)       0:00:08.544 *******
2026-08-22 09:45:41.027192 | controller | included: vexxhost.containers.download_artifact for controller
2026-08-22 09:45:41.027249 | controller |
2026-08-22 09:45:41.027268 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 09:45:41.027318 | controller | Saturday 22 August 2026  09:45:41 +0000 (0:00:00.053)       0:00:08.597 *******
2026-08-22 09:45:41.079771 | controller | ok: [controller] => {
2026-08-22 09:45:41.079867 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-08-22 09:45:41.079972 | controller | }
2026-08-22 09:45:41.080086 | controller |
2026-08-22 09:45:41.080216 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 09:45:41.080343 | controller | Saturday 22 August 2026  09:45:41 +0000 (0:00:00.050)       0:00:08.648 *******
2026-08-22 09:45:41.508934 | controller | ok: [controller]
2026-08-22 09:45:41.509009 | controller |
2026-08-22 09:45:41.509103 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 09:45:41.509247 | controller | Saturday 22 August 2026  09:45:41 +0000 (0:00:00.429)       0:00:09.078 *******
2026-08-22 09:45:43.179063 | controller | ok: [controller]
2026-08-22 09:45:43.179103 | controller |
2026-08-22 09:45:43.179109 | controller | PLAY RECAP *********************************************************************
2026-08-22 09:45:43.179116 | controller | controller                 : ok=16   changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-08-22 09:45:43.179121 | controller |
2026-08-22 09:45:43.179125 | controller |
2026-08-22 09:45:43.179130 | controller | TASKS RECAP ********************************************************************
2026-08-22 09:45:43.179134 | controller | Saturday 22 August 2026  09:45:43 +0000 (0:00:01.667)       0:00:10.745 *******
2026-08-22 09:45:43.179139 | controller | ===============================================================================
2026-08-22 09:45:43.179143 | controller | vexxhost.containers.package : Update state for tar ---------------------- 2.12s
2026-08-22 09:45:43.179147 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.79s
2026-08-22 09:45:43.179152 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.67s
2026-08-22 09:45:43.179156 | controller | Gathering Facts --------------------------------------------------------- 1.13s
2026-08-22 09:45:43.179161 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.54s
2026-08-22 09:45:43.179165 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.51s
2026-08-22 09:45:43.179169 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.47s
2026-08-22 09:45:43.179174 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.46s
2026-08-22 09:45:43.179179 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.44s
2026-08-22 09:45:43.179184 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.43s
2026-08-22 09:45:43.179199 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.39s
2026-08-22 09:45:43.179204 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.32s
2026-08-22 09:45:43.179208 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.30s
2026-08-22 09:45:43.179213 | controller | Install plugin ---------------------------------------------------------- 0.05s
2026-08-22 09:45:43.179217 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-08-22 09:45:43.179221 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-08-22 09:45:43.288764 | controller | INFO     [helm > idempotence] Executed: Successful
2026-08-22 09:45:43.299379 | controller | INFO     [helm > side_effect] Executing
2026-08-22 09:45:43.299870 | controller | WARNING  [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-22 09:45:43.307298 | controller | INFO     [helm > verify] Executing
2026-08-22 09:45:44.144660 | controller |
2026-08-22 09:45:44.145175 | controller | PLAY [Verify] ******************************************************************
2026-08-22 09:45:44.145439 | controller |
2026-08-22 09:45:44.145706 | controller | TASK [Gathering Facts] *********************************************************
2026-08-22 09:45:44.145975 | controller | Saturday 22 August 2026  09:45:44 +0000 (0:00:00.010)       0:00:00.010 *******
2026-08-22 09:45:45.227146 | 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-22 09:45:45.233869 | controller | ok: [controller]
2026-08-22 09:45:45.234139 | controller |
2026-08-22 09:45:45.234407 | controller | TASK [Get the current Helm version] ********************************************
2026-08-22 09:45:45.234670 | controller | Saturday 22 August 2026  09:45:45 +0000 (0:00:01.089)       0:00:01.100 *******
2026-08-22 09:45:45.690297 | controller | changed: [controller]
2026-08-22 09:45:45.690596 | controller |
2026-08-22 09:45:45.690865 | controller | TASK [Assert Helm version] *****************************************************
2026-08-22 09:45:45.691125 | controller | Saturday 22 August 2026  09:45:45 +0000 (0:00:00.455)       0:00:01.556 *******
2026-08-22 09:45:45.719891 | controller | ok: [controller] => {
2026-08-22 09:45:45.720159 | controller |     "changed": false,
2026-08-22 09:45:45.720414 | controller |     "msg": "All assertions passed"
2026-08-22 09:45:45.720688 | controller | }
2026-08-22 09:45:45.720922 | controller |
2026-08-22 09:45:45.721170 | controller | PLAY RECAP *********************************************************************
2026-08-22 09:45:45.721442 | controller | controller                 : ok=3    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-08-22 09:45:45.721672 | controller |
2026-08-22 09:45:45.721899 | controller |
2026-08-22 09:45:45.722147 | controller | TASKS RECAP ********************************************************************
2026-08-22 09:45:45.722403 | controller | Saturday 22 August 2026  09:45:45 +0000 (0:00:00.030)       0:00:01.586 *******
2026-08-22 09:45:45.722643 | controller | ===============================================================================
2026-08-22 09:45:45.722890 | controller | Gathering Facts --------------------------------------------------------- 1.09s
2026-08-22 09:45:45.723142 | controller | Get the current Helm version -------------------------------------------- 0.46s
2026-08-22 09:45:45.723444 | controller | Assert Helm version ----------------------------------------------------- 0.03s
2026-08-22 09:45:45.846081 | controller | INFO     [helm > verify] Executed: Successful
2026-08-22 09:45:45.861540 | controller | INFO     [helm > cleanup] Executing
2026-08-22 09:45:45.862017 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-22 09:45:45.869879 | controller | INFO     [helm > destroy] Executing
2026-08-22 09:45:45.870010 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-08-22 09:45:45.870130 | controller | INFO     [helm > destroy] Executed: Successful
2026-08-22 09:45:45.870538 | controller | WARNING  Molecule executed 1 scenario (1 missing files)
2026-08-22 09:45:46.228542 | controller | ok: Runtime: 0:00:59.465518
2026-08-22 09:45:46.233526 | 
2026-08-22 09:45:46.233597 | PLAY RECAP
2026-08-22 09:45:46.233654 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 09:45:46.233686 | 
2026-08-22 09:45:46.423074 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-22 09:45:46.425382 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-08-22 09:45:47.099903 | 
2026-08-22 09:45:47.100070 | PLAY [all]
2026-08-22 09:45:47.111443 | 
2026-08-22 09:45:47.111523 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-08-22 09:45:47.517787 | controller | changed
2026-08-22 09:45:47.524833 | 
2026-08-22 09:45:47.524947 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-08-22 09:45:47.896034 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 09:45:47.896146 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 09:45:47.903753 | controller | environment: line 1: kubectl: command not found
2026-08-22 09:45:47.905703 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 09:45:47.907993 | controller | environment: line 1: kubectl: command not found
2026-08-22 09:45:47.909323 | controller | environment: line 1: kubectl: command not found
2026-08-22 09:45:47.909410 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 09:45:47.910594 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 09:45:47.913062 | controller | environment: line 1: kubectl: command not found
2026-08-22 09:45:47.914396 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 09:45:47.915327 | controller | environment: line 1: kubectl: command not found
2026-08-22 09:45:47.916601 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 09:45:48.081988 | controller | ok: Runtime: 0:00:00.034644
2026-08-22 09:45:48.087587 | 
2026-08-22 09:45:48.087679 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-08-22 09:45:48.342986 | controller | changed
2026-08-22 09:45:48.348426 | 
2026-08-22 09:45:48.348531 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-08-22 09:45:48.574377 | controller | environment: line 5: kubectl: command not found
2026-08-22 09:45:48.575450 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 09:45:48.575769 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 09:45:48.575899 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 09:45:48.878340 | controller | ok: Runtime: 0:00:00.012308
2026-08-22 09:45:48.887817 | 
2026-08-22 09:45:48.887987 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-08-22 09:45:49.862429 | controller | changed:
2026-08-22 09:45:49.862644 | controller | created directory /var/lib/zuul/builds/d208acac9acf475a85bc61fb9e948e57/work/logs/controller
2026-08-22 09:45:49.862691 | controller | cd+++++++++ objects/
2026-08-22 09:45:49.862727 | controller | cd+++++++++ objects/cluster/
2026-08-22 09:45:49.862762 | controller | cd+++++++++ objects/namespaced/
2026-08-22 09:45:49.873318 | 
2026-08-22 09:45:49.873416 | TASK [gather-pod-logs : creating directory for pod logs]
2026-08-22 09:45:50.119183 | controller | changed
2026-08-22 09:45:50.124831 | 
2026-08-22 09:45:50.124924 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-08-22 09:45:50.359968 | controller | changed
2026-08-22 09:45:50.368029 | 
2026-08-22 09:45:50.368098 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-08-22 09:45:50.620344 | controller | environment: line 3: kubectl: command not found
2026-08-22 09:45:50.895543 | controller | ok: Runtime: 0:00:00.014102
2026-08-22 09:45:50.903027 | 
2026-08-22 09:45:50.903219 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-08-22 09:45:51.410278 | controller | changed:
2026-08-22 09:45:51.410448 | controller | cd+++++++++ pod-logs/
2026-08-22 09:45:51.410476 | controller | cd+++++++++ pod-logs/failed-pods/
2026-08-22 09:45:51.417624 | 
2026-08-22 09:45:51.417698 | LOOP [helm-release-status : creating directory for helm release status]
2026-08-22 09:45:51.669397 | controller | changed: "values"
2026-08-22 09:45:51.880442 | controller | changed: "releases"
2026-08-22 09:45:51.900839 | 
2026-08-22 09:45:51.901053 | TASK [helm-release-status : Gather get release status for helm charts]
2026-08-22 09:45:52.145251 | controller | /bin/bash: line 3: kubectl: command not found
2026-08-22 09:45:52.433589 | controller | ok: Runtime: 0:00:00.005754
2026-08-22 09:45:52.440304 | 
2026-08-22 09:45:52.440373 | TASK [helm-release-status : Downloads logs to executor]
2026-08-22 09:45:53.428222 | controller | changed:
2026-08-22 09:45:53.428422 | controller | cd+++++++++ helm/
2026-08-22 09:45:53.428451 | controller | cd+++++++++ helm/releases/
2026-08-22 09:45:53.428477 | controller | cd+++++++++ helm/values/
2026-08-22 09:45:53.437351 | 
2026-08-22 09:45:53.437439 | PLAY RECAP
2026-08-22 09:45:53.437503 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 09:45:53.437530 | 
2026-08-22 09:45:53.624745 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-08-22 09:45:53.626283 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-22 09:45:54.242078 | 
2026-08-22 09:45:54.242214 | PLAY [all]
2026-08-22 09:45:54.252072 | 
2026-08-22 09:45:54.252283 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-22 09:45:54.307537 | controller | skipping: Conditional result was False
2026-08-22 09:45:54.317239 | 
2026-08-22 09:45:54.318282 | TASK [fetch-output : Set log path for single node]
2026-08-22 09:45:54.370510 | controller | ok
2026-08-22 09:45:54.378051 | 
2026-08-22 09:45:54.378123 | LOOP [fetch-output : Ensure local output dirs]
2026-08-22 09:45:54.979797 | controller -> localhost | ok: "/var/lib/zuul/builds/d208acac9acf475a85bc61fb9e948e57/work/logs"
2026-08-22 09:45:55.191170 | controller -> localhost | changed: "/var/lib/zuul/builds/d208acac9acf475a85bc61fb9e948e57/work/artifacts"
2026-08-22 09:45:55.408209 | controller -> localhost | changed: "/var/lib/zuul/builds/d208acac9acf475a85bc61fb9e948e57/work/docs"
2026-08-22 09:45:55.429235 | 
2026-08-22 09:45:55.429428 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-22 09:45:56.094895 | controller | changed: .d..t...... ./
2026-08-22 09:45:56.095108 | controller | changed: All items complete
2026-08-22 09:45:56.095137 | 
2026-08-22 09:45:56.756922 | controller | changed: .d..t...... ./
2026-08-22 09:45:57.337704 | controller | changed: .d..t...... ./
2026-08-22 09:45:57.358561 | 
2026-08-22 09:45:57.358656 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-22 09:45:58.035951 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.240701
2026-08-22 09:45:58.268270 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007068
2026-08-22 09:45:58.278801 | 
2026-08-22 09:45:58.279455 | PLAY [all]
2026-08-22 09:45:58.284999 | 
2026-08-22 09:45:58.285069 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-22 09:45:58.776861 | controller | changed
2026-08-22 09:45:58.784076 | 
2026-08-22 09:45:58.784153 | PLAY RECAP
2026-08-22 09:45:58.784203 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-22 09:45:58.784225 | 
2026-08-22 09:45:58.938412 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-22 09:45:58.940539 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-22 09:45:59.530474 | 
2026-08-22 09:45:59.531037 | PLAY [localhost]
2026-08-22 09:45:59.541911 | 
2026-08-22 09:45:59.542036 | TASK [Generate Zuul manifest]
2026-08-22 09:45:59.564422 | localhost | ok
2026-08-22 09:45:59.581337 | 
2026-08-22 09:45:59.581477 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-22 09:45:59.960249 | localhost | changed
2026-08-22 09:45:59.980266 | 
2026-08-22 09:45:59.980478 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-22 09:46:00.037583 | localhost | ok
2026-08-22 09:46:00.044485 | 
2026-08-22 09:46:00.044569 | TASK [Upload logs]
2026-08-22 09:46:00.066958 | localhost | ok
2026-08-22 09:46:00.128093 | 
2026-08-22 09:46:00.128262 | TASK [Set zuul-log-path fact]
2026-08-22 09:46:00.162305 | localhost | ok
2026-08-22 09:46:00.183289 | 
2026-08-22 09:46:00.183508 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-22 09:46:00.218098 | localhost | ok
2026-08-22 09:46:00.229787 | 
2026-08-22 09:46:00.229888 | TASK [upload-logs : Create log directories]
2026-08-22 09:46:00.803750 | localhost | changed
2026-08-22 09:46:00.809093 | 
2026-08-22 09:46:00.809194 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-22 09:46:01.227861 | localhost -> localhost | ok: Runtime: 0:00:00.005519
2026-08-22 09:46:01.235085 | 
2026-08-22 09:46:01.235222 | TASK [upload-logs : Upload logs to log server]
2026-08-22 09:46:01.712885 | localhost | Output suppressed because no_log was given
2026-08-22 09:46:01.716678 | 
2026-08-22 09:46:01.716753 | LOOP [upload-logs : Compress console log and json output]
2026-08-22 09:46:01.770188 | localhost | skipping: Conditional result was False
2026-08-22 09:46:01.826016 | localhost | skipping: Conditional result was False
2026-08-22 09:46:01.845421 | 
2026-08-22 09:46:01.845586 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-22 09:46:01.890059 | localhost | skipping: Conditional result was False
2026-08-22 09:46:01.890594 | 
2026-08-22 09:46:01.898871 | localhost | skipping: Conditional result was False
2026-08-22 09:46:01.906985 | 
2026-08-22 09:46:01.907148 | LOOP [upload-logs : Upload console log and json output]