2026-06-18 05:10:05.647921 | Job console starting
2026-06-18 05:10:05.662272 | Updating git repos
2026-06-18 05:10:05.710014 | Cloning repos into workspace
2026-06-18 05:10:33.521307 | Restoring repo states
2026-06-18 05:10:33.544607 | Merging changes
2026-06-18 05:10:41.523362 | Checking out repos
2026-06-18 05:10:42.883253 | Preparing playbooks
2026-06-18 05:13:14.145248 | Running Ansible setup
2026-06-18 05:13:20.607933 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-06-18 05:13:21.324049 | 
2026-06-18 05:13:21.324338 | PLAY [localhost]
2026-06-18 05:13:21.333485 | 
2026-06-18 05:13:21.333639 | TASK [Gathering Facts]
2026-06-18 05:13:22.812597 | localhost | ok
2026-06-18 05:13:22.823040 | 
2026-06-18 05:13:22.823238 | TASK [Setup log path fact]
2026-06-18 05:13:22.845970 | localhost | ok
2026-06-18 05:13:22.862048 | 
2026-06-18 05:13:22.862181 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-18 05:13:22.897406 | localhost | ok
2026-06-18 05:13:22.906517 | 
2026-06-18 05:13:22.906634 | TASK [emit-job-header : Print job information]
2026-06-18 05:13:22.954461 | # Job Information
2026-06-18 05:13:22.954678 | Ansible Version: 2.16.18
2026-06-18 05:13:22.954727 | Job: ansible-collection-kubernetes-molecule-helm
2026-06-18 05:13:22.954760 | Pipeline: check
2026-06-18 05:13:22.954790 | Executor: 2d72f0692154
2026-06-18 05:13:22.954818 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/286
2026-06-18 05:13:22.954852 | Event ID: cb974c10-6ad3-11f1-8a2a-4ac938a36eb0
2026-06-18 05:13:22.959358 | 
2026-06-18 05:13:22.959463 | LOOP [emit-job-header : Print node information]
2026-06-18 05:13:23.069256 | localhost | ok:
2026-06-18 05:13:23.069468 | localhost | # Node Information
2026-06-18 05:13:23.069508 | localhost | Inventory Hostname: controller
2026-06-18 05:13:23.069537 | localhost | Hostname: np0000181887
2026-06-18 05:13:23.069566 | localhost | Username: zuul
2026-06-18 05:13:23.069631 | localhost | Distro: Debian 13.5
2026-06-18 05:13:23.069684 | localhost | Provider: yul1
2026-06-18 05:13:23.069716 | localhost | Region: ca-ymq-1
2026-06-18 05:13:23.069743 | localhost | Label: debian-trixie
2026-06-18 05:13:23.069770 | localhost | Product Name: OpenStack Nova
2026-06-18 05:13:23.069797 | localhost | Interface IP: 199.19.213.60
2026-06-18 05:13:23.086207 | 
2026-06-18 05:13:23.086401 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-06-18 05:13:23.805532 | localhost -> localhost | changed
2026-06-18 05:13:23.815035 | 
2026-06-18 05:13:23.815257 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-06-18 05:13:27.076903 | localhost -> localhost | changed
2026-06-18 05:13:27.090967 | 
2026-06-18 05:13:27.091181 | PLAY [all]
2026-06-18 05:13:27.103849 | 
2026-06-18 05:13:27.103985 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-06-18 05:13:27.383790 | controller -> localhost | ok
2026-06-18 05:13:27.390266 | 
2026-06-18 05:13:27.390399 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-06-18 05:13:27.422163 | controller | ok
2026-06-18 05:13:27.439044 | controller | included: /var/lib/zuul/builds/6bbf35ac14cf4c0681e61cdb33f77b90/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-06-18 05:13:27.443834 | 
2026-06-18 05:13:27.443896 | TASK [add-build-sshkey : Create Temp SSH key]
2026-06-18 05:13:28.334775 | controller -> localhost | Generating public/private rsa key pair.
2026-06-18 05:13:28.334968 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/6bbf35ac14cf4c0681e61cdb33f77b90/work/6bbf35ac14cf4c0681e61cdb33f77b90_id_rsa
2026-06-18 05:13:28.334999 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/6bbf35ac14cf4c0681e61cdb33f77b90/work/6bbf35ac14cf4c0681e61cdb33f77b90_id_rsa.pub
2026-06-18 05:13:28.335023 | controller -> localhost | The key fingerprint is:
2026-06-18 05:13:28.335045 | controller -> localhost | SHA256:jMY4X421ivUDnp6qq+kNOeNaYC1MiSMNqP2bA8M3D9Q zuul-build-sshkey
2026-06-18 05:13:28.335086 | controller -> localhost | The key's randomart image is:
2026-06-18 05:13:28.335108 | controller -> localhost | +---[RSA 3072]----+
2026-06-18 05:13:28.335137 | controller -> localhost | |o                |
2026-06-18 05:13:28.335160 | controller -> localhost | |oo.              |
2026-06-18 05:13:28.335181 | controller -> localhost | |=+.  .    .      |
2026-06-18 05:13:28.335201 | controller -> localhost | |=.o .oEo + .     |
2026-06-18 05:13:28.335221 | controller -> localhost | |.= +o + S o      |
2026-06-18 05:13:28.335241 | controller -> localhost | |..=.=+ = =       |
2026-06-18 05:13:28.335261 | controller -> localhost | |  *+ *o + o      |
2026-06-18 05:13:28.335281 | controller -> localhost | | o *+ .. . .     |
2026-06-18 05:13:28.335303 | controller -> localhost | |.o=.++..o        |
2026-06-18 05:13:28.335324 | controller -> localhost | +----[SHA256]-----+
2026-06-18 05:13:28.335375 | controller -> localhost | ok: Runtime: 0:00:00.418253
2026-06-18 05:13:28.339607 | 
2026-06-18 05:13:28.339679 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-06-18 05:13:28.371639 | controller | ok
2026-06-18 05:13:28.382633 | controller | included: /var/lib/zuul/builds/6bbf35ac14cf4c0681e61cdb33f77b90/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-06-18 05:13:28.388970 | 
2026-06-18 05:13:28.389052 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-06-18 05:13:28.414018 | controller | skipping: Conditional result was False
2026-06-18 05:13:28.422996 | 
2026-06-18 05:13:28.423131 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-06-18 05:13:28.953908 | controller | changed
2026-06-18 05:13:28.957803 | 
2026-06-18 05:13:28.957883 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-06-18 05:13:29.156318 | controller | ok
2026-06-18 05:13:29.162882 | 
2026-06-18 05:13:29.162975 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-06-18 05:13:29.711758 | controller | changed
2026-06-18 05:13:29.716636 | 
2026-06-18 05:13:29.716744 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-06-18 05:13:30.240616 | controller | changed
2026-06-18 05:13:30.246245 | 
2026-06-18 05:13:30.246339 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-06-18 05:13:30.271275 | controller | skipping: Conditional result was False
2026-06-18 05:13:30.276243 | 
2026-06-18 05:13:30.276321 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-06-18 05:13:30.748554 | controller -> localhost | changed
2026-06-18 05:13:30.765686 | 
2026-06-18 05:13:30.766158 | TASK [add-build-sshkey : Add back temp key]
2026-06-18 05:13:31.084553 | controller -> localhost | Identity added: /var/lib/zuul/builds/6bbf35ac14cf4c0681e61cdb33f77b90/work/6bbf35ac14cf4c0681e61cdb33f77b90_id_rsa (zuul-build-sshkey)
2026-06-18 05:13:31.084809 | controller -> localhost | ok: Runtime: 0:00:00.014845
2026-06-18 05:13:31.090343 | 
2026-06-18 05:13:31.090429 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-06-18 05:13:31.460549 | controller | ok
2026-06-18 05:13:31.464896 | 
2026-06-18 05:13:31.465002 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-06-18 05:13:31.490731 | controller | skipping: Conditional result was False
2026-06-18 05:13:31.503658 | 
2026-06-18 05:13:31.503733 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-06-18 05:13:31.880590 | controller | ok
2026-06-18 05:13:31.887431 | 
2026-06-18 05:13:31.887527 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-06-18 05:13:33.027113 | controller | Output suppressed because no_log was given
2026-06-18 05:13:33.037746 | 
2026-06-18 05:13:33.037890 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-06-18 05:13:33.240148 | controller | ok: "logs"
2026-06-18 05:13:33.240434 | controller | ok: All items complete
2026-06-18 05:13:33.240472 | 
2026-06-18 05:13:33.425784 | controller | ok: "artifacts"
2026-06-18 05:13:33.620924 | controller | ok: "docs"
2026-06-18 05:13:34.426192 | 
2026-06-18 05:13:34.426347 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-06-18 05:13:34.672029 | controller | changed: "logs"
2026-06-18 05:13:34.853844 | controller | changed: "artifacts"
2026-06-18 05:13:35.036049 | controller | changed: "docs"
2026-06-18 05:13:35.051022 | 
2026-06-18 05:13:35.051297 | PLAY RECAP
2026-06-18 05:13:35.051374 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-06-18 05:13:35.051418 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-18 05:13:35.051453 | 
2026-06-18 05:13:35.234865 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-06-18 05:13:35.236424 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-06-18 05:13:35.952719 | 
2026-06-18 05:13:35.952911 | PLAY [all]
2026-06-18 05:13:35.963157 | 
2026-06-18 05:13:35.963268 | TASK [setup-uv : Extract archive]
2026-06-18 05:13:38.353380 | controller | changed
2026-06-18 05:13:38.360674 | 
2026-06-18 05:13:38.360793 | TASK [setup-uv : Print version]
2026-06-18 05:13:38.796278 | controller | uv 0.8.13
2026-06-18 05:13:39.208002 | controller | ok: Runtime: 0:00:00.011496
2026-06-18 05:13:39.217548 | 
2026-06-18 05:13:39.217714 | TASK [Install Ansible collection siblings]
2026-06-18 05:13:39.629712 | controller | ok:
2026-06-18 05:13:39.629968 | controller | Skipping ansible.posix: no checked-out sibling
2026-06-18 05:13:39.630006 | controller | Skipping community.crypto: no checked-out sibling
2026-06-18 05:13:39.630036 | controller | Skipping community.general: no checked-out sibling
2026-06-18 05:13:39.630064 | controller | Skipping kubernetes.core: no checked-out sibling
2026-06-18 05:13:39.630090 | controller | Skipping vexxhost.containers: no checked-out sibling
2026-06-18 05:13:39.632826 | 
2026-06-18 05:13:39.633064 | PLAY RECAP
2026-06-18 05:13:39.633139 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-18 05:13:39.633169 | 
2026-06-18 05:13:39.823819 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-06-18 05:13:39.825355 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-06-18 05:13:40.573894 | 
2026-06-18 05:13:40.574090 | PLAY [all]
2026-06-18 05:13:40.588420 | 
2026-06-18 05:13:40.588526 | TASK [Update apt cache]
2026-06-18 05:13:45.300388 | controller | ok
2026-06-18 05:13:45.306888 | 
2026-06-18 05:13:45.307098 | TASK [Disable firewalld]
2026-06-18 05:13:45.333881 | controller | skipping: Conditional result was False
2026-06-18 05:13:45.341322 | 
2026-06-18 05:13:45.341520 | TASK [Install "jq" for log collection]
2026-06-18 05:13:47.348319 | controller | changed
2026-06-18 05:13:47.351336 | 
2026-06-18 05:13:47.351592 | PLAY RECAP
2026-06-18 05:13:47.351676 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-06-18 05:13:47.351707 | 
2026-06-18 05:13:47.536922 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-06-18 05:13:47.540565 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-06-18 05:13:48.181324 | 
2026-06-18 05:13:48.181480 | PLAY [all]
2026-06-18 05:13:48.192212 | 
2026-06-18 05:13:48.192336 | LOOP [Check inventory directories]
2026-06-18 05:13:48.574188 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-06-18 05:13:48.754320 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/helm/inventory"
2026-06-18 05:13:48.925405 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/inventory"
2026-06-18 05:13:48.941699 | 
2026-06-18 05:13:48.942068 | LOOP [Copy inventory file for Zuul]
2026-06-18 05:13:49.834092 | controller | changed:
2026-06-18 05:13:49.834315 | controller | {
2026-06-18 05:13:49.834343 | controller |   "ansible_loop_var": "item",
2026-06-18 05:13:49.834366 | controller |   "changed": false,
2026-06-18 05:13:49.834386 | controller |   "failed": false,
2026-06-18 05:13:49.834414 | controller |   "invocation": {
2026-06-18 05:13:49.834434 | controller |     "module_args": {
2026-06-18 05:13:49.834453 | controller |       "checksum_algorithm": "sha1",
2026-06-18 05:13:49.834471 | controller |       "follow": true,
2026-06-18 05:13:49.834490 | controller |       "get_attributes": true,
2026-06-18 05:13:49.834509 | controller |       "get_checksum": true,
2026-06-18 05:13:49.834527 | controller |       "get_mime": true,
2026-06-18 05:13:49.834545 | controller |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-06-18 05:13:49.834563 | controller |     }
2026-06-18 05:13:49.834581 | controller |   },
2026-06-18 05:13:49.834598 | controller |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-06-18 05:13:49.834616 | controller |   "stat": {
2026-06-18 05:13:49.834633 | controller |     "atime": 1781759612.886252,
2026-06-18 05:13:49.834651 | controller |     "attr_flags": "e",
2026-06-18 05:13:49.834668 | controller |     "attributes": [
2026-06-18 05:13:49.834686 | controller |       "extents"
2026-06-18 05:13:49.834704 | controller |     ],
2026-06-18 05:13:49.834721 | controller |     "block_size": 4096,
2026-06-18 05:13:49.834738 | controller |     "blocks": 8,
2026-06-18 05:13:49.834756 | controller |     "charset": "unknown",
2026-06-18 05:13:49.834775 | controller |     "ctime": 1781759612.9142535,
2026-06-18 05:13:49.834792 | controller |     "dev": 65025,
2026-06-18 05:13:49.834810 | controller |     "device_type": 0,
2026-06-18 05:13:49.834827 | controller |     "executable": true,
2026-06-18 05:13:49.834844 | controller |     "exists": true,
2026-06-18 05:13:49.834861 | controller |     "gid": 1000,
2026-06-18 05:13:49.834878 | controller |     "gr_name": "zuul",
2026-06-18 05:13:49.834895 | controller |     "inode": 520215,
2026-06-18 05:13:49.834912 | controller |     "isblk": false,
2026-06-18 05:13:49.834930 | controller |     "ischr": false,
2026-06-18 05:13:49.834947 | controller |     "isdir": true,
2026-06-18 05:13:49.834965 | controller |     "isfifo": false,
2026-06-18 05:13:49.834983 | controller |     "isgid": false,
2026-06-18 05:13:49.834999 | controller |     "islnk": false,
2026-06-18 05:13:49.835016 | controller |     "isreg": false,
2026-06-18 05:13:49.835033 | controller |     "issock": false,
2026-06-18 05:13:49.835050 | controller |     "isuid": false,
2026-06-18 05:13:49.835067 | controller |     "mimetype": "unknown",
2026-06-18 05:13:49.835084 | controller |     "mode": "0755",
2026-06-18 05:13:49.835101 | controller |     "mtime": 1781759434.7576723,
2026-06-18 05:13:49.835118 | controller |     "nlink": 14,
2026-06-18 05:13:49.835135 | controller |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-06-18 05:13:49.835152 | controller |     "pw_name": "zuul",
2026-06-18 05:13:49.835173 | controller |     "readable": true,
2026-06-18 05:13:49.835191 | controller |     "rgrp": true,
2026-06-18 05:13:49.835208 | controller |     "roth": true,
2026-06-18 05:13:49.835225 | controller |     "rusr": true,
2026-06-18 05:13:49.835242 | controller |     "size": 4096,
2026-06-18 05:13:49.835260 | controller |     "uid": 1000,
2026-06-18 05:13:49.835278 | controller |     "version": "417901104",
2026-06-18 05:13:49.835296 | controller |     "wgrp": false,
2026-06-18 05:13:49.835314 | controller |     "woth": false,
2026-06-18 05:13:49.835332 | controller |     "writeable": true,
2026-06-18 05:13:49.835350 | controller |     "wusr": true,
2026-06-18 05:13:49.835367 | controller |     "xgrp": true,
2026-06-18 05:13:49.835385 | controller |     "xoth": true,
2026-06-18 05:13:49.835401 | controller |     "xusr": true
2026-06-18 05:13:49.835418 | controller |   }
2026-06-18 05:13:49.835452 | controller | }
2026-06-18 05:13:49.835488 | controller | ok: All items complete
2026-06-18 05:13:49.835510 | 
2026-06-18 05:13:49.836730 | controller | skipping: Conditional result was False
2026-06-18 05:13:49.837336 | controller | skipping: Conditional result was False
2026-06-18 05:13:49.855407 | 
2026-06-18 05:13:49.855605 | LOOP [Switch "ansible_host" to private IP]
2026-06-18 05:13:50.279365 | controller | changed: 1 replacements made
2026-06-18 05:13:50.281657 | controller | skipping: Conditional result was False
2026-06-18 05:13:50.282647 | controller | skipping: Conditional result was False
2026-06-18 05:13:50.302551 | 
2026-06-18 05:13:50.302664 | TASK [Run Molecule scenario]
2026-06-18 05:13:50.780075 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-06-18 05:13:50.780160 | controller | Creating virtual environment at: .venv
2026-06-18 05:13:51.365504 | controller | Downloading pygments (1.2MiB)
2026-06-18 05:13:51.368634 | controller | Downloading ansible-core (2.3MiB)
2026-06-18 05:13:51.369887 | controller | Downloading cryptography (4.3MiB)
2026-06-18 05:13:51.574124 | controller |  Downloading cryptography
2026-06-18 05:13:51.584302 | controller |  Downloading pygments
2026-06-18 05:13:51.715561 | controller |  Downloading ansible-core
2026-06-18 05:13:51.762195 | controller | Installed 36 packages in 46ms
2026-06-18 05:13:52.423778 | controller | INFO     Collection 'vexxhost.kubernetes' detected.
2026-06-18 05:13:52.423836 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-06-18 05:13:53.269612 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-06-18 05:13:53.269656 | controller | INFO     default scenario not found, disabling shared state.
2026-06-18 05:13:53.269802 | controller | INFO     [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-06-18 05:13:53.269881 | controller | INFO     [helm > prerun] Performing prerun with role_name_check=0...
2026-06-18 05:14:30.778609 | controller | INFO     [helm > dependency] Executing
2026-06-18 05:14:30.778743 | controller | WARNING  [helm > dependency] Missing roles requirements file: requirements.yml
2026-06-18 05:14:30.778949 | controller | WARNING  [helm > dependency] Missing collections requirements file: collections.yml
2026-06-18 05:14:30.779057 | controller | WARNING  [helm > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-06-18 05:14:30.784871 | controller | INFO     [helm > cleanup] Executing
2026-06-18 05:14:30.785203 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-06-18 05:14:30.790862 | controller | INFO     [helm > destroy] Executing
2026-06-18 05:14:30.790889 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-06-18 05:14:30.790950 | controller | INFO     [helm > destroy] Executed: Successful
2026-06-18 05:14:30.796282 | controller | INFO     [helm > syntax] Executing
2026-06-18 05:14:31.742520 | controller |
2026-06-18 05:14:31.742566 | controller | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/converge.yml
2026-06-18 05:14:31.810470 | controller | INFO     [helm > syntax] Executed: Successful
2026-06-18 05:14:31.817489 | controller | INFO     [helm > create] Executing
2026-06-18 05:14:31.818992 | controller | WARNING  [helm > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-06-18 05:14:31.824234 | controller | INFO     [helm > prepare] Executing
2026-06-18 05:14:32.710562 | controller |
2026-06-18 05:14:32.710631 | controller | PLAY [Prepare] *****************************************************************
2026-06-18 05:14:32.710637 | controller |
2026-06-18 05:14:32.710641 | controller | TASK [Gathering Facts] *********************************************************
2026-06-18 05:14:32.710647 | controller | Thursday 18 June 2026  05:14:32 +0000 (0:00:00.031)       0:00:00.031 *********
2026-06-18 05:14:33.969904 | 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-06-18 05:14:33.975962 | controller | ok: [controller]
2026-06-18 05:14:33.976133 | controller |
2026-06-18 05:14:33.976358 | controller | TASK [Install Debian packages] *************************************************
2026-06-18 05:14:33.976582 | controller | Thursday 18 June 2026  05:14:33 +0000 (0:00:01.267)       0:00:01.299 *********
2026-06-18 05:14:35.389472 | controller | ok: [controller]
2026-06-18 05:14:35.389638 | controller |
2026-06-18 05:14:35.389853 | controller | PLAY RECAP *********************************************************************
2026-06-18 05:14:35.390089 | controller | controller                 : ok=2    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-06-18 05:14:35.390278 | controller |
2026-06-18 05:14:35.390518 | controller |
2026-06-18 05:14:35.390723 | controller | TASKS RECAP ********************************************************************
2026-06-18 05:14:35.390930 | controller | Thursday 18 June 2026  05:14:35 +0000 (0:00:01.413)       0:00:02.712 *********
2026-06-18 05:14:35.391116 | controller | ===============================================================================
2026-06-18 05:14:35.391379 | controller | Install Debian packages ------------------------------------------------- 1.41s
2026-06-18 05:14:35.391627 | controller | Gathering Facts --------------------------------------------------------- 1.27s
2026-06-18 05:14:35.469255 | controller | INFO     [helm > prepare] Executed: Successful
2026-06-18 05:14:35.478258 | controller | INFO     [helm > converge] Executing
2026-06-18 05:14:36.328706 | controller |
2026-06-18 05:14:36.328756 | controller | PLAY [Converge] ****************************************************************
2026-06-18 05:14:36.328842 | controller |
2026-06-18 05:14:36.328943 | controller | TASK [Gathering Facts] *********************************************************
2026-06-18 05:14:36.329036 | controller | Thursday 18 June 2026  05:14:36 +0000 (0:00:00.013)       0:00:00.013 *********
2026-06-18 05:14:37.406421 | 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-06-18 05:14:37.409302 | controller | ok: [controller]
2026-06-18 05:14:37.409492 | controller |
2026-06-18 05:14:37.409701 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-06-18 05:14:37.409901 | controller | Thursday 18 June 2026  05:14:37 +0000 (0:00:01.080)       0:00:01.093 *********
2026-06-18 05:14:37.910964 | controller | ok: [controller]
2026-06-18 05:14:37.911120 | controller |
2026-06-18 05:14:37.911364 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-06-18 05:14:37.911581 | controller | Thursday 18 June 2026  05:14:37 +0000 (0:00:00.501)       0:00:01.595 *********
2026-06-18 05:14:38.929585 | controller | ok: [controller]
2026-06-18 05:14:38.929641 | controller |
2026-06-18 05:14:38.929800 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-06-18 05:14:38.929946 | controller | Thursday 18 June 2026  05:14:38 +0000 (0:00:01.018)       0:00:02.613 *********
2026-06-18 05:14:39.348325 | controller | changed: [controller]
2026-06-18 05:14:39.348493 | controller |
2026-06-18 05:14:39.348708 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-06-18 05:14:39.348914 | controller | Thursday 18 June 2026  05:14:39 +0000 (0:00:00.419)       0:00:03.032 *********
2026-06-18 05:14:39.390768 | controller | ok: [controller] => {
2026-06-18 05:14:39.391010 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-06-18 05:14:39.391208 | controller | }
2026-06-18 05:14:39.391393 | controller |
2026-06-18 05:14:39.391610 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-06-18 05:14:39.391801 | controller | Thursday 18 June 2026  05:14:39 +0000 (0:00:00.042)       0:00:03.074 *********
2026-06-18 05:14:40.163541 | 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-06-18 05:14:40.170918 | controller | changed: [controller]
2026-06-18 05:14:40.171113 | controller |
2026-06-18 05:14:40.171350 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-06-18 05:14:40.171557 | controller | Thursday 18 June 2026  05:14:40 +0000 (0:00:00.780)       0:00:03.855 *********
2026-06-18 05:14:42.187217 | controller | changed: [controller]
2026-06-18 05:14:42.187275 | controller |
2026-06-18 05:14:42.187327 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-06-18 05:14:42.187429 | controller | Thursday 18 June 2026  05:14:42 +0000 (0:00:02.010)       0:00:05.865 *********
2026-06-18 05:14:42.663244 | controller | ok: [controller]
2026-06-18 05:14:42.663457 | controller |
2026-06-18 05:14:42.663777 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-06-18 05:14:42.664013 | controller | Thursday 18 June 2026  05:14:42 +0000 (0:00:00.481)       0:00:06.347 *********
2026-06-18 05:14:43.070271 | controller | changed: [controller]
2026-06-18 05:14:43.070507 | controller |
2026-06-18 05:14:43.070699 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-06-18 05:14:43.070880 | controller | Thursday 18 June 2026  05:14:43 +0000 (0:00:00.407)       0:00:06.754 *********
2026-06-18 05:14:43.514266 | controller | ok: [controller]
2026-06-18 05:14:43.514497 | controller |
2026-06-18 05:14:43.514771 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-06-18 05:14:43.514976 | controller | Thursday 18 June 2026  05:14:43 +0000 (0:00:00.444)       0:00:07.198 *********
2026-06-18 05:14:43.804479 | controller | changed: [controller]
2026-06-18 05:14:43.804530 | controller |
2026-06-18 05:14:43.804646 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-06-18 05:14:43.806510 | controller | Thursday 18 June 2026  05:14:43 +0000 (0:00:00.290)       0:00:07.488 *********
2026-06-18 05:14:44.099347 | controller | ok: [controller]
2026-06-18 05:14:44.099533 | controller |
2026-06-18 05:14:44.099757 | controller | TASK [Install plugin] **********************************************************
2026-06-18 05:14:44.099972 | controller | Thursday 18 June 2026  05:14:44 +0000 (0:00:00.294)       0:00:07.782 *********
2026-06-18 05:14:44.147241 | controller | included: vexxhost.containers.download_artifact for controller
2026-06-18 05:14:44.147446 | controller |
2026-06-18 05:14:44.147657 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-06-18 05:14:44.147881 | controller | Thursday 18 June 2026  05:14:44 +0000 (0:00:00.048)       0:00:07.831 *********
2026-06-18 05:14:44.187099 | controller | ok: [controller] => {
2026-06-18 05:14:44.187366 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-06-18 05:14:44.187550 | controller | }
2026-06-18 05:14:44.187710 | controller |
2026-06-18 05:14:44.187901 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-06-18 05:14:44.188097 | controller | Thursday 18 June 2026  05:14:44 +0000 (0:00:00.039)       0:00:07.871 *********
2026-06-18 05:14:45.021565 | controller | changed: [controller]
2026-06-18 05:14:45.021656 | controller |
2026-06-18 05:14:45.021917 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-06-18 05:14:45.022134 | controller | Thursday 18 June 2026  05:14:45 +0000 (0:00:00.833)       0:00:08.705 *********
2026-06-18 05:14:47.098966 | controller | changed: [controller]
2026-06-18 05:14:47.099010 | controller |
2026-06-18 05:14:47.099147 | controller | PLAY RECAP *********************************************************************
2026-06-18 05:14:47.099280 | controller | controller                 : ok=16   changed=7    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-06-18 05:14:47.099400 | controller |
2026-06-18 05:14:47.099494 | controller |
2026-06-18 05:14:47.099600 | controller | TASKS RECAP ********************************************************************
2026-06-18 05:14:47.099686 | controller | Thursday 18 June 2026  05:14:47 +0000 (0:00:02.074)       0:00:10.780 *********
2026-06-18 05:14:47.099768 | controller | ===============================================================================
2026-06-18 05:14:47.099856 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.07s
2026-06-18 05:14:47.102445 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.01s
2026-06-18 05:14:47.102468 | controller | Gathering Facts --------------------------------------------------------- 1.08s
2026-06-18 05:14:47.102472 | controller | vexxhost.containers.package : Update state for tar ---------------------- 1.02s
2026-06-18 05:14:47.102475 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.83s
2026-06-18 05:14:47.102478 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.78s
2026-06-18 05:14:47.102481 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.50s
2026-06-18 05:14:47.102484 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.48s
2026-06-18 05:14:47.102489 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.44s
2026-06-18 05:14:47.102492 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.42s
2026-06-18 05:14:47.102502 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.41s
2026-06-18 05:14:47.102505 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.29s
2026-06-18 05:14:47.102509 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.29s
2026-06-18 05:14:47.102512 | controller | Install plugin ---------------------------------------------------------- 0.05s
2026-06-18 05:14:47.102515 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s
2026-06-18 05:14:47.102519 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s
2026-06-18 05:14:47.206519 | controller | INFO     [helm > converge] Executed: Successful
2026-06-18 05:14:47.214003 | controller | INFO     [helm > idempotence] Executing
2026-06-18 05:14:48.048015 | controller |
2026-06-18 05:14:48.048129 | controller | PLAY [Converge] ****************************************************************
2026-06-18 05:14:48.048307 | controller |
2026-06-18 05:14:48.048498 | controller | TASK [Gathering Facts] *********************************************************
2026-06-18 05:14:48.048687 | controller | Thursday 18 June 2026  05:14:48 +0000 (0:00:00.013)       0:00:00.013 *********
2026-06-18 05:14:49.079282 | 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-06-18 05:14:49.084314 | controller | ok: [controller]
2026-06-18 05:14:49.084430 | controller |
2026-06-18 05:14:49.084557 | controller | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-06-18 05:14:49.084663 | controller | Thursday 18 June 2026  05:14:49 +0000 (0:00:01.036)       0:00:01.049 *********
2026-06-18 05:14:49.550730 | controller | ok: [controller]
2026-06-18 05:14:49.550782 | controller |
2026-06-18 05:14:49.550795 | controller | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-06-18 05:14:49.554443 | controller | Thursday 18 June 2026  05:14:49 +0000 (0:00:00.466)       0:00:01.516 *********
2026-06-18 05:14:50.525151 | controller | ok: [controller]
2026-06-18 05:14:50.525174 | controller |
2026-06-18 05:14:50.525179 | controller | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-06-18 05:14:50.525182 | controller | Thursday 18 June 2026  05:14:50 +0000 (0:00:00.971)       0:00:02.487 *********
2026-06-18 05:14:50.922069 | controller | ok: [controller]
2026-06-18 05:14:50.922202 | controller |
2026-06-18 05:14:50.922328 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-06-18 05:14:50.922487 | controller | Thursday 18 June 2026  05:14:50 +0000 (0:00:00.398)       0:00:02.886 *********
2026-06-18 05:14:50.966206 | controller | ok: [controller] => {
2026-06-18 05:14:50.966568 | controller |     "msg": "https://get.helm.sh/helm-v3.11.2-linux-amd64.tar.gz"
2026-06-18 05:14:50.966818 | controller | }
2026-06-18 05:14:50.967045 | controller |
2026-06-18 05:14:50.967295 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-06-18 05:14:50.967538 | controller | Thursday 18 June 2026  05:14:50 +0000 (0:00:00.045)       0:00:02.931 *********
2026-06-18 05:14:51.485261 | controller | ok: [controller]
2026-06-18 05:14:51.485550 | controller |
2026-06-18 05:14:51.485839 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-06-18 05:14:51.486108 | controller | Thursday 18 June 2026  05:14:51 +0000 (0:00:00.519)       0:00:03.450 *********
2026-06-18 05:14:54.119189 | controller | ok: [controller]
2026-06-18 05:14:54.119523 | controller |
2026-06-18 05:14:54.119814 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm repository] ***********************
2026-06-18 05:14:54.120081 | controller | Thursday 18 June 2026  05:14:54 +0000 (0:00:02.633)       0:00:06.084 *********
2026-06-18 05:14:54.618102 | controller | ok: [controller]
2026-06-18 05:14:54.618158 | controller |
2026-06-18 05:14:54.618299 | controller | TASK [vexxhost.kubernetes.helm : Add bash autocomplete for helm] ***************
2026-06-18 05:14:54.618485 | controller | Thursday 18 June 2026  05:14:54 +0000 (0:00:00.497)       0:00:06.581 *********
2026-06-18 05:14:55.016622 | controller | ok: [controller]
2026-06-18 05:14:55.016673 | controller |
2026-06-18 05:14:55.016956 | controller | TASK [vexxhost.kubernetes.helm : Get Helm plugins dir] *************************
2026-06-18 05:14:55.016981 | controller | Thursday 18 June 2026  05:14:55 +0000 (0:00:00.400)       0:00:06.982 *********
2026-06-18 05:14:55.450587 | controller | ok: [controller]
2026-06-18 05:14:55.450649 | controller |
2026-06-18 05:14:55.450815 | controller | TASK [vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist] ***
2026-06-18 05:14:55.450931 | controller | Thursday 18 June 2026  05:14:55 +0000 (0:00:00.433)       0:00:07.415 *********
2026-06-18 05:14:55.750717 | controller | ok: [controller]
2026-06-18 05:14:55.750800 | controller |
2026-06-18 05:14:55.750916 | controller | TASK [vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin] ***
2026-06-18 05:14:55.751010 | controller | Thursday 18 June 2026  05:14:55 +0000 (0:00:00.299)       0:00:07.715 *********
2026-06-18 05:14:56.034300 | controller | ok: [controller]
2026-06-18 05:14:56.034401 | controller |
2026-06-18 05:14:56.034737 | controller | TASK [Install plugin] **********************************************************
2026-06-18 05:14:56.034764 | controller | Thursday 18 June 2026  05:14:56 +0000 (0:00:00.284)       0:00:07.999 *********
2026-06-18 05:14:56.082824 | controller | included: vexxhost.containers.download_artifact for controller
2026-06-18 05:14:56.082882 | controller |
2026-06-18 05:14:56.082950 | controller | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-06-18 05:14:56.083048 | controller | Thursday 18 June 2026  05:14:56 +0000 (0:00:00.047)       0:00:08.047 *********
2026-06-18 05:14:56.123773 | controller | ok: [controller] => {
2026-06-18 05:14:56.123868 | controller |     "msg": "https://github.com/databus23/helm-diff/releases/download/v3.8.1/helm-diff-linux-amd64.tgz"
2026-06-18 05:14:56.123945 | controller | }
2026-06-18 05:14:56.124053 | controller |
2026-06-18 05:14:56.124176 | controller | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-06-18 05:14:56.124320 | controller | Thursday 18 June 2026  05:14:56 +0000 (0:00:00.042)       0:00:08.089 *********
2026-06-18 05:14:56.522976 | controller | ok: [controller]
2026-06-18 05:14:56.523028 | controller |
2026-06-18 05:14:56.523037 | controller | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-06-18 05:14:56.523054 | controller | Thursday 18 June 2026  05:14:56 +0000 (0:00:00.396)       0:00:08.485 *********
2026-06-18 05:14:58.110497 | controller | ok: [controller]
2026-06-18 05:14:58.110542 | controller |
2026-06-18 05:14:58.110552 | controller | PLAY RECAP *********************************************************************
2026-06-18 05:14:58.110562 | controller | controller                 : ok=16   changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-06-18 05:14:58.110572 | controller |
2026-06-18 05:14:58.110581 | controller |
2026-06-18 05:14:58.110589 | controller | TASKS RECAP ********************************************************************
2026-06-18 05:14:58.110599 | controller | Thursday 18 June 2026  05:14:58 +0000 (0:00:01.586)       0:00:10.072 *********
2026-06-18 05:14:58.110609 | controller | ===============================================================================
2026-06-18 05:14:58.110618 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 2.63s
2026-06-18 05:14:58.110627 | controller | vexxhost.containers.download_artifact : Extract archive ----------------- 1.59s
2026-06-18 05:14:58.110636 | controller | Gathering Facts --------------------------------------------------------- 1.04s
2026-06-18 05:14:58.110645 | controller | vexxhost.containers.package : Update state for tar ---------------------- 0.97s
2026-06-18 05:14:58.110654 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.52s
2026-06-18 05:14:58.110662 | controller | vexxhost.kubernetes.helm : Remove Helm repository ----------------------- 0.50s
2026-06-18 05:14:58.110672 | controller | vexxhost.containers.forget_package : Forget package --------------------- 0.47s
2026-06-18 05:14:58.110680 | controller | vexxhost.kubernetes.helm : Get Helm plugins dir ------------------------- 0.43s
2026-06-18 05:14:58.110688 | controller | vexxhost.kubernetes.helm : Add bash autocomplete for helm --------------- 0.40s
2026-06-18 05:14:58.110695 | controller | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.40s
2026-06-18 05:14:58.110716 | controller | vexxhost.containers.download_artifact : Download item ------------------- 0.40s
2026-06-18 05:14:58.110723 | controller | vexxhost.kubernetes.helm : Create Helm plugins directory if it does not exist --- 0.30s
2026-06-18 05:14:58.110730 | controller | vexxhost.kubernetes.helm : Remove Helm diff plugin installed by kubernetes.core.helm_plugin --- 0.28s
2026-06-18 05:14:58.110864 | controller | Install plugin ---------------------------------------------------------- 0.05s
2026-06-18 05:14:58.111129 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.05s
2026-06-18 05:14:58.111447 | controller | vexxhost.containers.download_artifact : Starting download of file ------- 0.04s
2026-06-18 05:14:58.215157 | controller | INFO     [helm > idempotence] Executed: Successful
2026-06-18 05:14:58.225627 | controller | INFO     [helm > side_effect] Executing
2026-06-18 05:14:58.225954 | controller | WARNING  [helm > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-06-18 05:14:58.231300 | controller | INFO     [helm > verify] Executing
2026-06-18 05:14:59.075478 | controller |
2026-06-18 05:14:59.075822 | controller | PLAY [Verify] ******************************************************************
2026-06-18 05:14:59.076079 | controller |
2026-06-18 05:14:59.076396 | controller | TASK [Gathering Facts] *********************************************************
2026-06-18 05:14:59.076759 | controller | Thursday 18 June 2026  05:14:59 +0000 (0:00:00.010)       0:00:00.010 *********
2026-06-18 05:15:00.122332 | 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-06-18 05:15:00.127800 | controller | ok: [controller]
2026-06-18 05:15:00.127976 | controller |
2026-06-18 05:15:00.128145 | controller | TASK [Get the current Helm version] ********************************************
2026-06-18 05:15:00.128297 | controller | Thursday 18 June 2026  05:15:00 +0000 (0:00:01.052)       0:00:01.062 *********
2026-06-18 05:15:00.561664 | controller | changed: [controller]
2026-06-18 05:15:00.562750 | controller |
2026-06-18 05:15:00.562847 | controller | TASK [Assert Helm version] *****************************************************
2026-06-18 05:15:00.562940 | controller | Thursday 18 June 2026  05:15:00 +0000 (0:00:00.433)       0:00:01.496 *********
2026-06-18 05:15:00.589940 | controller | ok: [controller] => {
2026-06-18 05:15:00.590209 | controller |     "changed": false,
2026-06-18 05:15:00.590485 | controller |     "msg": "All assertions passed"
2026-06-18 05:15:00.590682 | controller | }
2026-06-18 05:15:00.590865 | controller |
2026-06-18 05:15:00.591065 | controller | PLAY RECAP *********************************************************************
2026-06-18 05:15:00.591338 | controller | controller                 : ok=3    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-06-18 05:15:00.591531 | controller |
2026-06-18 05:15:00.591711 | controller |
2026-06-18 05:15:00.591911 | controller | TASKS RECAP ********************************************************************
2026-06-18 05:15:00.592210 | controller | Thursday 18 June 2026  05:15:00 +0000 (0:00:00.028)       0:00:01.524 *********
2026-06-18 05:15:00.592436 | controller | ===============================================================================
2026-06-18 05:15:00.592685 | controller | Gathering Facts --------------------------------------------------------- 1.05s
2026-06-18 05:15:00.592894 | controller | Get the current Helm version -------------------------------------------- 0.43s
2026-06-18 05:15:00.593093 | controller | Assert Helm version ----------------------------------------------------- 0.03s
2026-06-18 05:15:00.681161 | controller | INFO     [helm > verify] Executed: Successful
2026-06-18 05:15:00.690915 | controller | INFO     [helm > cleanup] Executing
2026-06-18 05:15:00.691389 | controller | WARNING  [helm > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-06-18 05:15:00.697350 | controller | INFO     [helm > destroy] Executing
2026-06-18 05:15:00.697447 | controller | WARNING  [helm > destroy] Skipping, '--destroy=never' requested.
2026-06-18 05:15:00.697521 | controller | INFO     [helm > destroy] Executed: Successful
2026-06-18 05:15:00.697763 | controller | WARNING  Molecule executed 1 scenario (1 missing files)
2026-06-18 05:15:00.873359 | controller | ok: Runtime: 0:01:10.053093
2026-06-18 05:15:00.878628 | 
2026-06-18 05:15:00.878712 | PLAY RECAP
2026-06-18 05:15:00.878776 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-18 05:15:00.878813 | 
2026-06-18 05:15:01.094726 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-06-18 05:15:01.096573 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-06-18 05:15:01.794472 | 
2026-06-18 05:15:01.794702 | PLAY [all]
2026-06-18 05:15:01.807532 | 
2026-06-18 05:15:01.807687 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-06-18 05:15:02.212184 | controller | changed
2026-06-18 05:15:02.216914 | 
2026-06-18 05:15:02.217116 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-06-18 05:15:02.620834 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-06-18 05:15:02.621749 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-06-18 05:15:02.629077 | controller | environment: line 1: kubectl: command not found
2026-06-18 05:15:02.631588 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-06-18 05:15:02.633028 | controller | environment: line 1: kubectl: command not found
2026-06-18 05:15:02.634488 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-06-18 05:15:02.637097 | controller | environment: line 1: kubectl: command not found
2026-06-18 05:15:02.637827 | controller | environment: line 1: kubectl: command not found
2026-06-18 05:15:02.639415 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-06-18 05:15:02.639752 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-06-18 05:15:02.642762 | controller | environment: line 1: kubectl: command not found
2026-06-18 05:15:02.644186 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-06-18 05:15:02.751480 | controller | ok: Runtime: 0:00:00.032902
2026-06-18 05:15:02.757731 | 
2026-06-18 05:15:02.757849 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-06-18 05:15:02.986204 | controller | changed
2026-06-18 05:15:02.991913 | 
2026-06-18 05:15:02.992047 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-06-18 05:15:03.254455 | controller | environment: line 5: kubectl: command not found
2026-06-18 05:15:03.255113 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-06-18 05:15:03.255517 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-06-18 05:15:03.256120 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-06-18 05:15:03.527397 | controller | ok: Runtime: 0:00:00.012995
2026-06-18 05:15:03.536211 | 
2026-06-18 05:15:03.536424 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-06-18 05:15:04.232066 | controller | changed:
2026-06-18 05:15:04.232303 | controller | created directory /var/lib/zuul/builds/6bbf35ac14cf4c0681e61cdb33f77b90/work/logs/controller
2026-06-18 05:15:04.232353 | controller | cd+++++++++ objects/
2026-06-18 05:15:04.232391 | controller | cd+++++++++ objects/cluster/
2026-06-18 05:15:04.232424 | controller | cd+++++++++ objects/namespaced/
2026-06-18 05:15:04.241578 | 
2026-06-18 05:15:04.241656 | TASK [gather-pod-logs : creating directory for pod logs]
2026-06-18 05:15:04.530849 | controller | changed
2026-06-18 05:15:04.536413 | 
2026-06-18 05:15:04.536484 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-06-18 05:15:04.802626 | controller | changed
2026-06-18 05:15:04.808988 | 
2026-06-18 05:15:04.809169 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-06-18 05:15:05.057168 | controller | environment: line 3: kubectl: command not found
2026-06-18 05:15:05.340238 | controller | ok: Runtime: 0:00:00.012278
2026-06-18 05:15:05.346492 | 
2026-06-18 05:15:05.346649 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-06-18 05:15:05.857832 | controller | changed:
2026-06-18 05:15:05.858114 | controller | cd+++++++++ pod-logs/
2026-06-18 05:15:05.858181 | controller | cd+++++++++ pod-logs/failed-pods/
2026-06-18 05:15:05.867705 | 
2026-06-18 05:15:05.867794 | LOOP [helm-release-status : creating directory for helm release status]
2026-06-18 05:15:06.108113 | controller | changed: "values"
2026-06-18 05:15:06.341129 | controller | changed: "releases"
2026-06-18 05:15:06.350701 | 
2026-06-18 05:15:06.350907 | TASK [helm-release-status : Gather get release status for helm charts]
2026-06-18 05:15:06.625775 | controller | /bin/bash: line 3: kubectl: command not found
2026-06-18 05:15:06.892456 | controller | ok: Runtime: 0:00:00.009372
2026-06-18 05:15:06.898579 | 
2026-06-18 05:15:06.898677 | TASK [helm-release-status : Downloads logs to executor]
2026-06-18 05:15:07.429837 | controller | changed:
2026-06-18 05:15:07.430052 | controller | cd+++++++++ helm/
2026-06-18 05:15:07.430093 | controller | cd+++++++++ helm/releases/
2026-06-18 05:15:07.430129 | controller | cd+++++++++ helm/values/
2026-06-18 05:15:07.436719 | 
2026-06-18 05:15:07.436773 | PLAY RECAP
2026-06-18 05:15:07.436822 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-18 05:15:07.436844 | 
2026-06-18 05:15:07.577567 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-06-18 05:15:07.580572 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-06-18 05:15:08.248155 | 
2026-06-18 05:15:08.248331 | PLAY [all]
2026-06-18 05:15:08.259171 | 
2026-06-18 05:15:08.259274 | TASK [fetch-output : Set log path for multiple nodes]
2026-06-18 05:15:08.315201 | controller | skipping: Conditional result was False
2026-06-18 05:15:08.323604 | 
2026-06-18 05:15:08.323769 | TASK [fetch-output : Set log path for single node]
2026-06-18 05:15:08.367835 | controller | ok
2026-06-18 05:15:08.373942 | 
2026-06-18 05:15:08.374042 | LOOP [fetch-output : Ensure local output dirs]
2026-06-18 05:15:08.804075 | controller -> localhost | ok: "/var/lib/zuul/builds/6bbf35ac14cf4c0681e61cdb33f77b90/work/logs"
2026-06-18 05:15:09.020740 | controller -> localhost | changed: "/var/lib/zuul/builds/6bbf35ac14cf4c0681e61cdb33f77b90/work/artifacts"
2026-06-18 05:15:09.241458 | controller -> localhost | changed: "/var/lib/zuul/builds/6bbf35ac14cf4c0681e61cdb33f77b90/work/docs"
2026-06-18 05:15:09.257136 | 
2026-06-18 05:15:09.257309 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-06-18 05:15:09.909497 | controller | changed: .d..t...... ./
2026-06-18 05:15:09.909771 | controller | changed: All items complete
2026-06-18 05:15:09.909811 | 
2026-06-18 05:15:10.363612 | controller | changed: .d..t...... ./
2026-06-18 05:15:10.842620 | controller | changed: .d..t...... ./
2026-06-18 05:15:10.868991 | 
2026-06-18 05:15:10.869154 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-06-18 05:15:11.601583 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008479
2026-06-18 05:15:11.868067 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008108
2026-06-18 05:15:11.882976 | 
2026-06-18 05:15:11.883103 | PLAY [all]
2026-06-18 05:15:11.888443 | 
2026-06-18 05:15:11.888510 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-06-18 05:15:12.418670 | controller | changed
2026-06-18 05:15:12.426236 | 
2026-06-18 05:15:12.426404 | PLAY RECAP
2026-06-18 05:15:12.426499 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-06-18 05:15:12.426552 | 
2026-06-18 05:15:12.630019 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-06-18 05:15:12.632181 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-06-18 05:15:13.340264 | 
2026-06-18 05:15:13.340518 | PLAY [localhost]
2026-06-18 05:15:13.356894 | 
2026-06-18 05:15:13.357178 | TASK [Generate Zuul manifest]
2026-06-18 05:15:13.379951 | localhost | ok
2026-06-18 05:15:13.401540 | 
2026-06-18 05:15:13.401753 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-06-18 05:15:13.825682 | localhost | changed
2026-06-18 05:15:13.839808 | 
2026-06-18 05:15:13.839979 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-06-18 05:15:13.870744 | localhost | ok
2026-06-18 05:15:13.879943 | 
2026-06-18 05:15:13.880042 | TASK [Upload logs]
2026-06-18 05:15:13.905171 | localhost | ok
2026-06-18 05:15:13.968627 | 
2026-06-18 05:15:13.968777 | TASK [Set zuul-log-path fact]
2026-06-18 05:15:13.991420 | localhost | ok
2026-06-18 05:15:14.004163 | 
2026-06-18 05:15:14.004284 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-18 05:15:14.034459 | localhost | ok
2026-06-18 05:15:14.043319 | 
2026-06-18 05:15:14.043421 | TASK [upload-logs : Create log directories]
2026-06-18 05:15:14.543820 | localhost | changed
2026-06-18 05:15:14.550974 | 
2026-06-18 05:15:14.551101 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-06-18 05:15:14.997923 | localhost -> localhost | ok: Runtime: 0:00:00.005700
2026-06-18 05:15:15.005857 | 
2026-06-18 05:15:15.006034 | TASK [upload-logs : Upload logs to log server]
2026-06-18 05:15:15.495331 | localhost | Output suppressed because no_log was given
2026-06-18 05:15:15.501792 | 
2026-06-18 05:15:15.501988 | LOOP [upload-logs : Compress console log and json output]
2026-06-18 05:15:15.551697 | localhost | skipping: Conditional result was False
2026-06-18 05:15:15.610210 | localhost | skipping: Conditional result was False
2026-06-18 05:15:15.622404 | 
2026-06-18 05:15:15.622623 | LOOP [upload-logs : Upload compressed console log and json output]
2026-06-18 05:15:15.673467 | localhost | skipping: Conditional result was False
2026-06-18 05:15:15.673987 | 
2026-06-18 05:15:15.679560 | localhost | skipping: Conditional result was False
2026-06-18 05:15:15.691506 | 
2026-06-18 05:15:15.691758 | LOOP [upload-logs : Upload console log and json output]