2026-09-07 01:50:01.535403 | Job console starting
2026-09-07 01:50:01.551816 | Updating git repos
2026-09-07 01:50:01.596395 | Cloning repos into workspace
2026-09-07 01:50:01.665766 | Restoring repo states
2026-09-07 01:50:01.679395 | Merging changes
2026-09-07 01:50:02.311954 | Checking out repos
2026-09-07 01:50:02.420447 | Preparing playbooks
2026-09-07 01:50:04.523785 | Running Ansible setup
2026-09-07 01:50:09.241808 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-07 01:50:09.813913 | 
2026-09-07 01:50:09.814352 | PLAY [localhost]
2026-09-07 01:50:09.820955 | 
2026-09-07 01:50:09.821016 | TASK [Gathering Facts]
2026-09-07 01:50:10.799666 | localhost | ok
2026-09-07 01:50:10.811886 | 
2026-09-07 01:50:10.812021 | TASK [Setup log path fact]
2026-09-07 01:50:10.832588 | localhost | ok
2026-09-07 01:50:10.852587 | 
2026-09-07 01:50:10.852723 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-07 01:50:10.884687 | localhost | ok
2026-09-07 01:50:10.895078 | 
2026-09-07 01:50:10.895197 | TASK [emit-job-header : Print job information]
2026-09-07 01:50:10.946046 | # Job Information
2026-09-07 01:50:10.946240 | Ansible Version: 2.16.18
2026-09-07 01:50:10.946301 | Job: ansible-collection-kubernetes-molecule-helm
2026-09-07 01:50:10.946343 | Pipeline: check
2026-09-07 01:50:10.946381 | Executor: 2d72f0692154
2026-09-07 01:50:10.946417 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/300
2026-09-07 01:50:10.946459 | Event ID: 1a3e6320-aa5e-11f1-83e1-f73eae392cf5
2026-09-07 01:50:10.951406 | 
2026-09-07 01:50:10.951519 | LOOP [emit-job-header : Print node information]
2026-09-07 01:50:11.037396 | localhost | ok:
2026-09-07 01:50:11.037607 | localhost | # Node Information
2026-09-07 01:50:11.037641 | localhost | Inventory Hostname: controller
2026-09-07 01:50:11.037711 | localhost | Hostname: np0000191986
2026-09-07 01:50:11.037736 | localhost | Username: zuul
2026-09-07 01:50:11.037757 | localhost | Distro: Debian 13.6
2026-09-07 01:50:11.037774 | localhost | Provider: yul1
2026-09-07 01:50:11.037790 | localhost | Region: ca-ymq-1
2026-09-07 01:50:11.037806 | localhost | Label: debian-trixie
2026-09-07 01:50:11.037822 | localhost | Product Name: OpenStack Nova
2026-09-07 01:50:11.037861 | localhost | Interface IP: 162.253.55.27
2026-09-07 01:50:11.057816 | 
2026-09-07 01:50:11.057987 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-07 01:50:11.498177 | localhost -> localhost | changed
2026-09-07 01:50:11.506619 | 
2026-09-07 01:50:11.506744 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-07 01:50:12.390711 | localhost -> localhost | changed
2026-09-07 01:50:12.396583 | 
2026-09-07 01:50:12.396634 | PLAY [all]
2026-09-07 01:50:12.402420 | 
2026-09-07 01:50:12.402478 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-07 01:50:12.659113 | controller -> localhost | ok
2026-09-07 01:50:12.667674 | 
2026-09-07 01:50:12.667945 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-07 01:50:12.699796 | controller | ok
2026-09-07 01:50:12.724108 | controller | included: /var/lib/zuul/builds/9599c196a0e94213bd39d68da2fdd658/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-07 01:50:12.732116 | 
2026-09-07 01:50:12.732190 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-07 01:50:13.691141 | controller -> localhost | Generating public/private rsa key pair.
2026-09-07 01:50:13.691378 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9599c196a0e94213bd39d68da2fdd658/work/9599c196a0e94213bd39d68da2fdd658_id_rsa
2026-09-07 01:50:13.691437 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9599c196a0e94213bd39d68da2fdd658/work/9599c196a0e94213bd39d68da2fdd658_id_rsa.pub
2026-09-07 01:50:13.691480 | controller -> localhost | The key fingerprint is:
2026-09-07 01:50:13.691521 | controller -> localhost | SHA256:v8wQxBr5kewHYl7HdOIzVzCp3QSA3misHnjuZ7czDgM zuul-build-sshkey
2026-09-07 01:50:13.691577 | controller -> localhost | The key's randomart image is:
2026-09-07 01:50:13.691618 | controller -> localhost | +---[RSA 3072]----+
2026-09-07 01:50:13.691667 | controller -> localhost | |         .+.=+.  |
2026-09-07 01:50:13.691709 | controller -> localhost | |       +.= o.o.  |
2026-09-07 01:50:13.691748 | controller -> localhost | |      =oOo*o.o   |
2026-09-07 01:50:13.691787 | controller -> localhost | |     o O=+o+. .  |
2026-09-07 01:50:13.691843 | controller -> localhost | |     .EoS .      |
2026-09-07 01:50:13.691884 | controller -> localhost | |    . +. +       |
2026-09-07 01:50:13.691921 | controller -> localhost | |     + .+ .      |
2026-09-07 01:50:13.691959 | controller -> localhost | |      o o*+.     |
2026-09-07 01:50:13.692002 | controller -> localhost | |     ..o o*+     |
2026-09-07 01:50:13.692041 | controller -> localhost | +----[SHA256]-----+
2026-09-07 01:50:13.692126 | controller -> localhost | ok: Runtime: 0:00:00.533855
2026-09-07 01:50:13.699882 | 
2026-09-07 01:50:13.700001 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-07 01:50:13.730896 | controller | ok
2026-09-07 01:50:13.744408 | controller | included: /var/lib/zuul/builds/9599c196a0e94213bd39d68da2fdd658/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-07 01:50:13.753840 | 
2026-09-07 01:50:13.753933 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-07 01:50:13.778518 | controller | skipping: Conditional result was False
2026-09-07 01:50:13.787718 | 
2026-09-07 01:50:13.787916 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-07 01:50:14.338873 | controller | changed
2026-09-07 01:50:14.346026 | 
2026-09-07 01:50:14.346148 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-07 01:50:14.580930 | controller | ok
2026-09-07 01:50:14.596540 | 
2026-09-07 01:50:14.596688 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-07 01:50:15.168980 | controller | changed
2026-09-07 01:50:15.176160 | 
2026-09-07 01:50:15.176283 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-07 01:50:15.740158 | controller | changed
2026-09-07 01:50:15.748581 | 
2026-09-07 01:50:15.748744 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-07 01:50:15.773767 | controller | skipping: Conditional result was False
2026-09-07 01:50:15.785545 | 
2026-09-07 01:50:15.785702 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-07 01:50:16.139121 | controller -> localhost | changed
2026-09-07 01:50:16.162462 | 
2026-09-07 01:50:16.162631 | TASK [add-build-sshkey : Add back temp key]
2026-09-07 01:50:16.498302 | controller -> localhost | Identity added: /var/lib/zuul/builds/9599c196a0e94213bd39d68da2fdd658/work/9599c196a0e94213bd39d68da2fdd658_id_rsa (zuul-build-sshkey)
2026-09-07 01:50:16.498471 | controller -> localhost | ok: Runtime: 0:00:00.013553
2026-09-07 01:50:16.501878 | 
2026-09-07 01:50:16.501934 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-07 01:50:16.886897 | controller | ok
2026-09-07 01:50:16.892921 | 
2026-09-07 01:50:16.893002 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-07 01:50:16.927903 | controller | skipping: Conditional result was False
2026-09-07 01:50:16.936866 | 
2026-09-07 01:50:16.936935 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-07 01:50:17.319648 | controller | ok
2026-09-07 01:50:17.330855 | 
2026-09-07 01:50:17.331001 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-07 01:50:18.512453 | controller | Output suppressed because no_log was given
2026-09-07 01:50:18.531032 | 
2026-09-07 01:50:18.531119 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-07 01:50:18.769734 | controller | ok: "logs"
2026-09-07 01:50:18.770085 | controller | ok: All items complete
2026-09-07 01:50:18.770149 | 
2026-09-07 01:50:18.967078 | controller | ok: "artifacts"
2026-09-07 01:50:19.168004 | controller | ok: "docs"
2026-09-07 01:50:19.180763 | 
2026-09-07 01:50:19.180848 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-07 01:50:19.411930 | controller | changed: "logs"
2026-09-07 01:50:19.596356 | controller | changed: "artifacts"
2026-09-07 01:50:19.771403 | controller | changed: "docs"
2026-09-07 01:50:19.791336 | 
2026-09-07 01:50:19.791480 | PLAY RECAP
2026-09-07 01:50:19.791540 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-07 01:50:19.791595 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 01:50:19.791630 | 
2026-09-07 01:50:19.943850 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-07 01:50:19.946980 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-07 01:50:20.483910 | 
2026-09-07 01:50:20.484127 | PLAY [all]
2026-09-07 01:50:20.492920 | 
2026-09-07 01:50:20.492987 | TASK [setup-uv : Extract archive]
2026-09-07 01:50:23.087889 | controller | changed
2026-09-07 01:50:23.099136 | 
2026-09-07 01:50:23.099435 | TASK [setup-uv : Print version]
2026-09-07 01:50:23.533366 | controller | uv 0.8.13
2026-09-07 01:50:23.645155 | controller | ok: Runtime: 0:00:00.015413
2026-09-07 01:50:23.658526 | 
2026-09-07 01:50:23.658660 | TASK [Install Ansible collection siblings]
2026-09-07 01:50:24.079107 | controller | ok:
2026-09-07 01:50:24.079377 | controller | Skipping ansible.posix: no checked-out sibling
2026-09-07 01:50:24.079434 | controller | Skipping community.crypto: no checked-out sibling
2026-09-07 01:50:24.079476 | controller | Skipping community.general: no checked-out sibling
2026-09-07 01:50:24.079515 | controller | Skipping kubernetes.core: no checked-out sibling
2026-09-07 01:50:24.079597 | controller | Skipping vexxhost.containers: no checked-out sibling
2026-09-07 01:50:24.082402 | 
2026-09-07 01:50:24.082481 | PLAY RECAP
2026-09-07 01:50:24.082565 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 01:50:24.082610 | 
2026-09-07 01:50:24.218520 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-07 01:50:24.221105 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-09-07 01:50:24.813289 | 
2026-09-07 01:50:24.813399 | PLAY [all]
2026-09-07 01:50:24.822031 | 
2026-09-07 01:50:24.822098 | TASK [Update apt cache]
2026-09-07 01:50:30.537735 | controller | ok
2026-09-07 01:50:30.546514 | 
2026-09-07 01:50:30.546738 | TASK [Disable firewalld]
2026-09-07 01:50:30.584045 | controller | skipping: Conditional result was False
2026-09-07 01:50:30.595840 | 
2026-09-07 01:50:30.595999 | TASK [Install "jq" for log collection]
2026-09-07 01:50:33.232567 | controller | changed
2026-09-07 01:50:33.235758 | 
2026-09-07 01:50:33.235876 | PLAY RECAP
2026-09-07 01:50:33.235964 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-07 01:50:33.236006 | 
2026-09-07 01:50:33.381215 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-09-07 01:50:33.382718 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-07 01:50:34.004444 | 
2026-09-07 01:50:34.004602 | PLAY [all]
2026-09-07 01:50:34.014495 | 
2026-09-07 01:50:34.014605 | LOOP [Check inventory directories]
2026-09-07 01:50:34.428225 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-09-07 01:50:34.634846 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/helm/inventory"
2026-09-07 01:50:34.842803 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/helm/inventory"
2026-09-07 01:50:34.858973 | 
2026-09-07 01:50:34.859229 | LOOP [Copy inventory file for Zuul]
2026-09-07 01:50:35.756961 | controller | changed:
2026-09-07 01:50:35.757179 | controller | {
2026-09-07 01:50:35.757207 | controller |   "ansible_loop_var": "item",
2026-09-07 01:50:35.757227 | controller |   "changed": false,
2026-09-07 01:50:35.757244 | controller |   "failed": false,
2026-09-07 01:50:35.757263 | controller |   "invocation": {
2026-09-07 01:50:35.757279 | controller |     "module_args": {
2026-09-07 01:50:35.757295 | controller |       "checksum_algorithm": "sha1",
2026-09-07 01:50:35.757311 | controller |       "follow": true,
2026-09-07 01:50:35.757326 | controller |       "get_attributes": true,
2026-09-07 01:50:35.757342 | controller |       "get_checksum": true,
2026-09-07 01:50:35.757358 | controller |       "get_mime": true,
2026-09-07 01:50:35.757392 | controller |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-09-07 01:50:35.757416 | controller |     }
2026-09-07 01:50:35.757431 | controller |   },
2026-09-07 01:50:35.757447 | controller |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-09-07 01:50:35.757461 | controller |   "stat": {
2026-09-07 01:50:35.757476 | controller |     "atime": 1788745818.3572018,
2026-09-07 01:50:35.757491 | controller |     "attr_flags": "e",
2026-09-07 01:50:35.757506 | controller |     "attributes": [
2026-09-07 01:50:35.757520 | controller |       "extents"
2026-09-07 01:50:35.757535 | controller |     ],
2026-09-07 01:50:35.757549 | controller |     "block_size": 4096,
2026-09-07 01:50:35.757563 | controller |     "blocks": 8,
2026-09-07 01:50:35.757578 | controller |     "charset": "unknown",
2026-09-07 01:50:35.757594 | controller |     "ctime": 1788745818.3932047,
2026-09-07 01:50:35.757609 | controller |     "dev": 65025,
2026-09-07 01:50:35.757624 | controller |     "device_type": 0,
2026-09-07 01:50:35.757639 | controller |     "executable": true,
2026-09-07 01:50:35.757770 | controller |     "exists": true,
2026-09-07 01:50:35.757808 | controller |     "gid": 1000,
2026-09-07 01:50:35.757825 | controller |     "gr_name": "zuul",
2026-09-07 01:50:35.757841 | controller |     "inode": 521495,
2026-09-07 01:50:35.757856 | controller |     "isblk": false,
2026-09-07 01:50:35.757871 | controller |     "ischr": false,
2026-09-07 01:50:35.757886 | controller |     "isdir": true,
2026-09-07 01:50:35.757901 | controller |     "isfifo": false,
2026-09-07 01:50:35.757915 | controller |     "isgid": false,
2026-09-07 01:50:35.757930 | controller |     "islnk": false,
2026-09-07 01:50:35.757944 | controller |     "isreg": false,
2026-09-07 01:50:35.757957 | controller |     "issock": false,
2026-09-07 01:50:35.757972 | controller |     "isuid": false,
2026-09-07 01:50:35.757986 | controller |     "mimetype": "unknown",
2026-09-07 01:50:35.758000 | controller |     "mode": "0755",
2026-09-07 01:50:35.758014 | controller |     "mtime": 1788745801.802184,
2026-09-07 01:50:35.758056 | controller |     "nlink": 14,
2026-09-07 01:50:35.758074 | controller |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-09-07 01:50:35.758090 | controller |     "pw_name": "zuul",
2026-09-07 01:50:35.758109 | controller |     "readable": true,
2026-09-07 01:50:35.758124 | controller |     "rgrp": true,
2026-09-07 01:50:35.758139 | controller |     "roth": true,
2026-09-07 01:50:35.758153 | controller |     "rusr": true,
2026-09-07 01:50:35.758168 | controller |     "size": 4096,
2026-09-07 01:50:35.758182 | controller |     "uid": 1000,
2026-09-07 01:50:35.758197 | controller |     "version": "1806762969",
2026-09-07 01:50:35.758212 | controller |     "wgrp": false,
2026-09-07 01:50:35.758229 | controller |     "woth": false,
2026-09-07 01:50:35.758244 | controller |     "writeable": true,
2026-09-07 01:50:35.758259 | controller |     "wusr": true,
2026-09-07 01:50:35.758274 | controller |     "xgrp": true,
2026-09-07 01:50:35.758288 | controller |     "xoth": true,
2026-09-07 01:50:35.758302 | controller |     "xusr": true
2026-09-07 01:50:35.758317 | controller |   }
2026-09-07 01:50:35.758340 | controller | }
2026-09-07 01:50:35.758371 | controller | ok: All items complete
2026-09-07 01:50:35.758390 | 
2026-09-07 01:50:35.762422 | controller | skipping: Conditional result was False
2026-09-07 01:50:35.767803 | controller | skipping: Conditional result was False
2026-09-07 01:50:35.789126 | 
2026-09-07 01:50:35.789906 | LOOP [Switch "ansible_host" to private IP]
2026-09-07 01:50:36.183751 | controller | changed: 1 replacements made
2026-09-07 01:50:36.185686 | controller | skipping: Conditional result was False
2026-09-07 01:50:36.193182 | controller | skipping: Conditional result was False
2026-09-07 01:50:36.225292 | 
2026-09-07 01:50:36.225526 | TASK [Run Molecule scenario]
2026-09-07 01:50:36.721766 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-09-07 01:50:36.721899 | controller | Creating virtual environment at: .venv
2026-09-07 01:50:37.010717 | controller | Downloading ansible-core (2.3MiB)
2026-09-07 01:50:37.011640 | controller | Downloading pygments (1.2MiB)
2026-09-07 01:50:37.019988 | controller | Downloading cryptography (4.3MiB)
2026-09-07 01:50:43.685426 | controller |  Downloading cryptography
2026-09-07 01:50:43.730470 | controller |  Downloading pygments
2026-09-07 01:50:43.889444 | controller |  Downloading ansible-core
2026-09-07 01:50:43.934120 | controller | Installed 36 packages in 43ms
2026-09-07 01:50:44.573329 | controller | INFO     Collection 'vexxhost.kubernetes' detected.
2026-09-07 01:50:44.573434 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-09-07 01:50:45.489891 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-09-07 01:50:45.489946 | controller | INFO     default scenario not found, disabling shared state.
2026-09-07 01:50:45.490000 | controller | INFO     [helm > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-07 01:50:45.490087 | controller | INFO     [helm > prerun] Performing prerun with role_name_check=0...
2026-09-07 01:52:03.444910 | controller | WARNING  Retrying execution failure 1 of: ansible-galaxy collection install -vvv ansible.posix:>=1.6.0
2026-09-07 01:52:03.445002 | controller | ERROR    Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code:
2026-09-07 01:52:03.445013 | controller | Starting galaxy collection install process
2026-09-07 01:52:03.445021 | controller | Process install dependency map
2026-09-07 01:52:03.445030 | controller | Starting collection install process
2026-09-07 01:52:03.445075 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-posix-2.2.2.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1499fkv5ib1e/tmplulisofa/ansible-posix-2.2.2-yes110ey
2026-09-07 01:52:03.445097 | controller |
2026-09-07 01:52:03.445106 | controller | ansible-galaxy
2026-09-07 01:52:03.445113 | controller |   config file = None
2026-09-07 01:52:03.445120 | controller |   configured module search path =
2026-09-07 01:52:03.445128 | controller |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible
2026-09-07 01:52:03.445148 | controller |   ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/local/lib/python3.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages
2026-09-07 01:52:03.445159 | controller |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy
2026-09-07 01:52:03.445166 | controller |   python version = 3.13.5 (main, Jul 15 2026, 20:25:40)  (/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/python)
2026-09-07 01:52:03.445174 | controller |   jinja version = 3.1.6
2026-09-07 01:52:03.445182 | controller |   pyyaml version = 6.0.3 (with libyaml v0.2.5)
2026-09-07 01:52:03.445189 | controller | No config file found; using defaults
2026-09-07 01:52:03.445197 | controller | Created /home/zuul/.ansible/galaxy_token
2026-09-07 01:52:03.445204 | controller | : Failed to download collection tar from 'default' due to the following unforeseen error: The read operation timed out
2026-09-07 01:52:03.445212 | controller |
2026-09-07 01:52:03.448600 | controller | Traceback (most recent call last):
2026-09-07 01:52:03.452053 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/molecule", line 10, in <module>
2026-09-07 01:52:03.452074 | controller |     sys.exit(main())
2026-09-07 01:52:03.452093 | controller |              ~~~~^^
2026-09-07 01:52:03.452335 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 1485, in __call__
2026-09-07 01:52:03.452346 | controller |     return self.main(*args, **kwargs)
2026-09-07 01:52:03.452370 | controller |            ~~~~~~~~~^^^^^^^^^^^^^^^^^
2026-09-07 01:52:03.452577 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 1406, in main
2026-09-07 01:52:03.452586 | controller |     rv = self.invoke(ctx)
2026-09-07 01:52:03.452849 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 1873, in invoke
2026-09-07 01:52:03.452857 | controller |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-09-07 01:52:03.452864 | controller |                            ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^
2026-09-07 01:52:03.453144 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 1269, in invoke
2026-09-07 01:52:03.453153 | controller |     return ctx.invoke(self.callback, **ctx.params)
2026-09-07 01:52:03.453160 | controller |            ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 01:52:03.453403 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 824, in invoke
2026-09-07 01:52:03.453413 | controller |     return callback(*args, **kwargs)
2026-09-07 01:52:03.453657 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/click_cfg.py", line 583, in wrapper
2026-09-07 01:52:03.453666 | controller |     return func(*args, **kwargs)
2026-09-07 01:52:03.453888 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/decorators.py", line 34, in new_func
2026-09-07 01:52:03.453897 | controller |     return f(get_current_context(), *args, **kwargs)
2026-09-07 01:52:03.454342 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/click_cfg.py", line 418, in wrapper
2026-09-07 01:52:03.454353 | controller |     return func(ctx)
2026-09-07 01:52:03.454602 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/command/test.py", line 81, in test
2026-09-07 01:52:03.454610 | controller |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-09-07 01:52:03.454617 | controller |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 01:52:03.454873 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/command/base.py", line 169, in execute_cmdline_scenarios
2026-09-07 01:52:03.454882 | controller |     _run_scenarios(scenarios, command_args, default_config)
2026-09-07 01:52:03.454889 | controller |     ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 01:52:03.455394 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/command/base.py", line 240, in _run_scenarios
2026-09-07 01:52:03.455404 | controller |     scenario.config.runtime.prepare_environment(
2026-09-07 01:52:03.455411 | controller |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-07 01:52:03.455418 | controller |         install_local=True,
2026-09-07 01:52:03.455425 | controller |         ^^^^^^^^^^^^^^^^^^^
2026-09-07 01:52:03.455432 | controller |         role_name_check=role_name_check,
2026-09-07 01:52:03.455439 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 01:52:03.455447 | controller |     )
2026-09-07 01:52:03.455454 | controller |     ^
2026-09-07 01:52:03.455799 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible_compat/runtime.py", line 752, in prepare_environment
2026-09-07 01:52:03.455807 | controller |     self.install_collection(
2026-09-07 01:52:03.455814 | controller |     ~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-07 01:52:03.455821 | controller |         f"{name}{',' if is_url(name) else ':'}{required_version}",
2026-09-07 01:52:03.455829 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 01:52:03.455836 | controller |         destination=destination,
2026-09-07 01:52:03.455843 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 01:52:03.455850 | controller |     )
2026-09-07 01:52:03.455865 | controller |     ^
2026-09-07 01:52:03.456062 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible_compat/runtime.py", line 609, in install_collection
2026-09-07 01:52:03.456072 | controller |     raise InvalidPrerequisiteError(msg)
2026-09-07 01:52:03.457038 | controller | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code:
2026-09-07 01:52:03.457046 | controller | Starting galaxy collection install process
2026-09-07 01:52:03.457054 | controller | Process install dependency map
2026-09-07 01:52:03.457061 | controller | Starting collection install process
2026-09-07 01:52:03.457068 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-posix-2.2.2.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1499fkv5ib1e/tmplulisofa/ansible-posix-2.2.2-yes110ey
2026-09-07 01:52:03.457076 | controller |
2026-09-07 01:52:03.457083 | controller | ansible-galaxy [core 2.20.1]
2026-09-07 01:52:03.457099 | controller |   config file = None
2026-09-07 01:52:03.457106 | controller |   configured module search path = ['/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/plugins/modules', '/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules']
2026-09-07 01:52:03.457141 | controller |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible
2026-09-07 01:52:03.457150 | controller |   ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/local/lib/python3.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages
2026-09-07 01:52:03.457158 | controller |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy
2026-09-07 01:52:03.457165 | controller |   python version = 3.13.5 (main, Jul 15 2026, 20:25:40) [GCC 14.2.0] (/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/python)
2026-09-07 01:52:03.457172 | controller |   jinja version = 3.1.6
2026-09-07 01:52:03.457180 | controller |   pyyaml version = 6.0.3 (with libyaml v0.2.5)
2026-09-07 01:52:03.457187 | controller | No config file found; using defaults
2026-09-07 01:52:03.457193 | controller | Created /home/zuul/.ansible/galaxy_token
2026-09-07 01:52:03.457200 | controller | [ERROR]: Failed to download collection tar from 'default' due to the following unforeseen error: The read operation timed out
2026-09-07 01:52:03.457208 | controller |
2026-09-07 01:52:03.837199 | controller | ERROR
2026-09-07 01:52:03.837475 | controller | {
2026-09-07 01:52:03.837532 | controller |   "delta": "0:01:26.960897",
2026-09-07 01:52:03.837571 | controller |   "end": "2026-09-07 01:52:03.577491",
2026-09-07 01:52:03.837608 | controller |   "msg": "non-zero return code",
2026-09-07 01:52:03.837665 | controller |   "rc": 1,
2026-09-07 01:52:03.837702 | controller |   "start": "2026-09-07 01:50:36.616594"
2026-09-07 01:52:03.837739 | controller | }
failure
2026-09-07 01:52:03.842246 | 
2026-09-07 01:52:03.842338 | PLAY RECAP
2026-09-07 01:52:03.842420 | controller | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-07 01:52:03.842462 | 
2026-09-07 01:52:03.958343 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-07 01:52:03.959702 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-09-07 01:52:04.616516 | 
2026-09-07 01:52:04.616674 | PLAY [all]
2026-09-07 01:52:04.627310 | 
2026-09-07 01:52:04.627392 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-07 01:52:05.020661 | controller | changed
2026-09-07 01:52:05.026795 | 
2026-09-07 01:52:05.026899 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-07 01:52:05.408728 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:52:05.408832 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:52:05.415446 | controller | environment: line 1: kubectl: command not found
2026-09-07 01:52:05.417768 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:52:05.419130 | controller | environment: line 1: kubectl: command not found
2026-09-07 01:52:05.420601 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:52:05.423100 | controller | environment: line 1: kubectl: command not found
2026-09-07 01:52:05.424457 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:52:05.425599 | controller | environment: line 1: kubectl: command not found
2026-09-07 01:52:05.427558 | controller | environment: line 1: kubectl: command not found
2026-09-07 01:52:05.427579 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:52:05.429813 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 01:52:05.558340 | controller | ok: Runtime: 0:00:00.033280
2026-09-07 01:52:05.566511 | 
2026-09-07 01:52:05.566668 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-07 01:52:05.807616 | controller | changed
2026-09-07 01:52:05.811198 | 
2026-09-07 01:52:05.811272 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-07 01:52:06.098071 | controller | environment: line 5: kubectl: command not found
2026-09-07 01:52:06.099199 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:52:06.099498 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:52:06.100097 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 01:52:06.342988 | controller | ok: Runtime: 0:00:00.014010
2026-09-07 01:52:06.352619 | 
2026-09-07 01:52:06.352772 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-07 01:52:07.057265 | controller | changed:
2026-09-07 01:52:07.057521 | controller | created directory /var/lib/zuul/builds/9599c196a0e94213bd39d68da2fdd658/work/logs/controller
2026-09-07 01:52:07.057583 | controller | cd+++++++++ objects/
2026-09-07 01:52:07.057628 | controller | cd+++++++++ objects/cluster/
2026-09-07 01:52:07.057694 | controller | cd+++++++++ objects/namespaced/
2026-09-07 01:52:07.071029 | 
2026-09-07 01:52:07.071158 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-07 01:52:07.333850 | controller | changed
2026-09-07 01:52:07.341070 | 
2026-09-07 01:52:07.341196 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-07 01:52:07.589226 | controller | changed
2026-09-07 01:52:07.596993 | 
2026-09-07 01:52:07.597131 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-07 01:52:07.881965 | controller | environment: line 3: kubectl: command not found
2026-09-07 01:52:08.137818 | controller | ok: Runtime: 0:00:00.012976
2026-09-07 01:52:08.145488 | 
2026-09-07 01:52:08.145616 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-07 01:52:08.687362 | controller | changed:
2026-09-07 01:52:08.687837 | controller | cd+++++++++ pod-logs/
2026-09-07 01:52:08.687896 | controller | cd+++++++++ pod-logs/failed-pods/
2026-09-07 01:52:08.703691 | 
2026-09-07 01:52:08.703879 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-07 01:52:08.919598 | controller | changed: "values"
2026-09-07 01:52:09.117042 | controller | changed: "releases"
2026-09-07 01:52:09.130467 | 
2026-09-07 01:52:09.130730 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-07 01:52:09.409949 | controller | /bin/bash: line 3: kubectl: command not found
2026-09-07 01:52:09.672268 | controller | ok: Runtime: 0:00:00.008526
2026-09-07 01:52:09.681587 | 
2026-09-07 01:52:09.681743 | TASK [helm-release-status : Downloads logs to executor]
2026-09-07 01:52:10.250367 | controller | changed:
2026-09-07 01:52:10.251935 | controller | cd+++++++++ helm/
2026-09-07 01:52:10.252006 | controller | cd+++++++++ helm/releases/
2026-09-07 01:52:10.252054 | controller | cd+++++++++ helm/values/
2026-09-07 01:52:10.260347 | 
2026-09-07 01:52:10.260434 | PLAY RECAP
2026-09-07 01:52:10.260979 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 01:52:10.262960 | 
2026-09-07 01:52:10.440561 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-09-07 01:52:10.443780 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-07 01:52:11.000057 | 
2026-09-07 01:52:11.000178 | PLAY [all]
2026-09-07 01:52:11.009292 | 
2026-09-07 01:52:11.009365 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-07 01:52:11.063854 | controller | skipping: Conditional result was False
2026-09-07 01:52:11.070667 | 
2026-09-07 01:52:11.070769 | TASK [fetch-output : Set log path for single node]
2026-09-07 01:52:11.112679 | controller | ok
2026-09-07 01:52:11.119773 | 
2026-09-07 01:52:11.119907 | LOOP [fetch-output : Ensure local output dirs]
2026-09-07 01:52:11.533020 | controller -> localhost | ok: "/var/lib/zuul/builds/9599c196a0e94213bd39d68da2fdd658/work/logs"
2026-09-07 01:52:11.752363 | controller -> localhost | changed: "/var/lib/zuul/builds/9599c196a0e94213bd39d68da2fdd658/work/artifacts"
2026-09-07 01:52:11.971972 | controller -> localhost | changed: "/var/lib/zuul/builds/9599c196a0e94213bd39d68da2fdd658/work/docs"
2026-09-07 01:52:11.988660 | 
2026-09-07 01:52:11.988803 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-07 01:52:12.725361 | controller | changed: .d..t...... ./
2026-09-07 01:52:12.725765 | controller | changed: All items complete
2026-09-07 01:52:12.725926 | 
2026-09-07 01:52:13.226424 | controller | changed: .d..t...... ./
2026-09-07 01:52:13.742723 | controller | changed: .d..t...... ./
2026-09-07 01:52:13.762700 | 
2026-09-07 01:52:13.762868 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-07 01:52:14.203685 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007578
2026-09-07 01:52:14.420745 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.005475
2026-09-07 01:52:14.440268 | 
2026-09-07 01:52:14.440413 | PLAY [all]
2026-09-07 01:52:14.447356 | 
2026-09-07 01:52:14.447439 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-07 01:52:14.930350 | controller | changed
2026-09-07 01:52:14.940777 | 
2026-09-07 01:52:14.940877 | PLAY RECAP
2026-09-07 01:52:14.940969 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-07 01:52:14.941011 | 
2026-09-07 01:52:15.112751 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-07 01:52:15.114100 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-07 01:52:15.706822 | 
2026-09-07 01:52:15.706965 | PLAY [localhost]
2026-09-07 01:52:15.716246 | 
2026-09-07 01:52:15.716315 | TASK [Generate Zuul manifest]
2026-09-07 01:52:15.732673 | localhost | ok
2026-09-07 01:52:15.747064 | 
2026-09-07 01:52:15.747141 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-07 01:52:16.131034 | localhost | changed
2026-09-07 01:52:16.153428 | 
2026-09-07 01:52:16.153582 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-07 01:52:16.185177 | localhost | ok
2026-09-07 01:52:16.198062 | 
2026-09-07 01:52:16.198184 | TASK [Upload logs]
2026-09-07 01:52:16.218442 | localhost | ok
2026-09-07 01:52:16.303215 | 
2026-09-07 01:52:16.303338 | TASK [Set zuul-log-path fact]
2026-09-07 01:52:16.320354 | localhost | ok
2026-09-07 01:52:16.331361 | 
2026-09-07 01:52:16.331436 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-07 01:52:16.359519 | localhost | ok
2026-09-07 01:52:16.368370 | 
2026-09-07 01:52:16.368454 | TASK [upload-logs : Create log directories]
2026-09-07 01:52:16.891728 | localhost | changed
2026-09-07 01:52:16.897671 | 
2026-09-07 01:52:16.897755 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-07 01:52:17.302403 | localhost -> localhost | ok: Runtime: 0:00:00.004499
2026-09-07 01:52:17.320979 | 
2026-09-07 01:52:17.321305 | TASK [upload-logs : Upload logs to log server]
2026-09-07 01:52:17.851993 | localhost | Output suppressed because no_log was given
2026-09-07 01:52:17.859203 | 
2026-09-07 01:52:17.859351 | LOOP [upload-logs : Compress console log and json output]
2026-09-07 01:52:17.924739 | localhost | skipping: Conditional result was False
2026-09-07 01:52:17.966722 | localhost | skipping: Conditional result was False
2026-09-07 01:52:17.976892 | 
2026-09-07 01:52:17.977087 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-07 01:52:18.042391 | localhost | skipping: Conditional result was False
2026-09-07 01:52:18.042790 | 
2026-09-07 01:52:18.048401 | localhost | skipping: Conditional result was False
2026-09-07 01:52:18.061812 | 
2026-09-07 01:52:18.061999 | LOOP [upload-logs : Upload console log and json output]