2026-09-15 16:47:09.041489 | Job console starting
2026-09-15 16:47:09.053800 | Updating git repos
2026-09-15 16:47:09.104327 | Cloning repos into workspace
2026-09-15 16:47:09.190838 | Restoring repo states
2026-09-15 16:47:09.209260 | Merging changes
2026-09-15 16:47:10.150516 | Checking out repos
2026-09-15 16:47:10.446658 | Preparing playbooks
2026-09-15 16:47:14.789544 | Running Ansible setup
2026-09-15 16:47:19.779423 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-15 16:47:20.405059 | 
2026-09-15 16:47:20.405196 | PLAY [localhost]
2026-09-15 16:47:20.412367 | 
2026-09-15 16:47:20.412441 | TASK [Gathering Facts]
2026-09-15 16:47:21.290889 | localhost | ok
2026-09-15 16:47:21.304213 | 
2026-09-15 16:47:21.304458 | TASK [Setup log path fact]
2026-09-15 16:47:21.324254 | localhost | ok
2026-09-15 16:47:21.344864 | 
2026-09-15 16:47:21.345048 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-15 16:47:21.375629 | localhost | ok
2026-09-15 16:47:21.386885 | 
2026-09-15 16:47:21.387011 | TASK [emit-job-header : Print job information]
2026-09-15 16:47:21.437879 | # Job Information
2026-09-15 16:47:21.438117 | Ansible Version: 2.16.18
2026-09-15 16:47:21.438179 | Job: ansible-collection-kubernetes-molecule-cluster-api
2026-09-15 16:47:21.438221 | Pipeline: check
2026-09-15 16:47:21.438259 | Executor: 2d72f0692154
2026-09-15 16:47:21.438296 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/291
2026-09-15 16:47:21.438339 | Event ID: 7e3b23a0-b124-11f1-9be4-5c390cfddffc
2026-09-15 16:47:21.443497 | 
2026-09-15 16:47:21.443619 | LOOP [emit-job-header : Print node information]
2026-09-15 16:47:21.548117 | localhost | ok:
2026-09-15 16:47:21.563324 | localhost | # Node Information
2026-09-15 16:47:21.563399 | localhost | Inventory Hostname: controller
2026-09-15 16:47:21.563431 | localhost | Hostname: np0000192833
2026-09-15 16:47:21.563455 | localhost | Username: zuul
2026-09-15 16:47:21.563482 | localhost | Distro: Debian 13.6
2026-09-15 16:47:21.563505 | localhost | Provider: yul1
2026-09-15 16:47:21.563528 | localhost | Region: ca-ymq-1
2026-09-15 16:47:21.563550 | localhost | Label: debian-trixie
2026-09-15 16:47:21.563572 | localhost | Product Name: OpenStack Nova
2026-09-15 16:47:21.563593 | localhost | Interface IP: 162.253.55.77
2026-09-15 16:47:21.581803 | 
2026-09-15 16:47:21.581993 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-15 16:47:22.053086 | localhost -> localhost | changed
2026-09-15 16:47:22.063662 | 
2026-09-15 16:47:22.063821 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-15 16:47:23.097905 | localhost -> localhost | changed
2026-09-15 16:47:23.113304 | 
2026-09-15 16:47:23.113487 | PLAY [all]
2026-09-15 16:47:23.125044 | 
2026-09-15 16:47:23.125192 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-15 16:47:23.361235 | controller -> localhost | ok
2026-09-15 16:47:23.365858 | 
2026-09-15 16:47:23.365922 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-15 16:47:23.393385 | controller | ok
2026-09-15 16:47:23.416118 | controller | included: /var/lib/zuul/builds/8e888531fcb74ebe814ac3fdeb151b72/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-15 16:47:23.420671 | 
2026-09-15 16:47:23.420735 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-15 16:47:24.564866 | controller -> localhost | Generating public/private rsa key pair.
2026-09-15 16:47:24.565493 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/8e888531fcb74ebe814ac3fdeb151b72/work/8e888531fcb74ebe814ac3fdeb151b72_id_rsa
2026-09-15 16:47:24.565555 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/8e888531fcb74ebe814ac3fdeb151b72/work/8e888531fcb74ebe814ac3fdeb151b72_id_rsa.pub
2026-09-15 16:47:24.565598 | controller -> localhost | The key fingerprint is:
2026-09-15 16:47:24.565638 | controller -> localhost | SHA256:czZibLrs9a99+Wgs6ZYE8Fuhzy49XGl7wZdk+nye2m4 zuul-build-sshkey
2026-09-15 16:47:24.565695 | controller -> localhost | The key's randomart image is:
2026-09-15 16:47:24.565735 | controller -> localhost | +---[RSA 3072]----+
2026-09-15 16:47:24.565784 | controller -> localhost | |                 |
2026-09-15 16:47:24.565825 | controller -> localhost | |        .   .    |
2026-09-15 16:47:24.565864 | controller -> localhost | |         o . .   |
2026-09-15 16:47:24.565902 | controller -> localhost | |       .  + .  o |
2026-09-15 16:47:24.565939 | controller -> localhost | |        S +*  =..|
2026-09-15 16:47:24.565975 | controller -> localhost | |       + =..+.++.|
2026-09-15 16:47:24.566011 | controller -> localhost | |      . .  = *ooo|
2026-09-15 16:47:24.566049 | controller -> localhost | |     . o ...X BE+|
2026-09-15 16:47:24.566093 | controller -> localhost | |     .+   o*+*=B+|
2026-09-15 16:47:24.566131 | controller -> localhost | +----[SHA256]-----+
2026-09-15 16:47:24.566209 | controller -> localhost | ok: Runtime: 0:00:00.667187
2026-09-15 16:47:24.573908 | 
2026-09-15 16:47:24.574025 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-15 16:47:24.597321 | controller | ok
2026-09-15 16:47:24.616450 | controller | included: /var/lib/zuul/builds/8e888531fcb74ebe814ac3fdeb151b72/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-15 16:47:24.625785 | 
2026-09-15 16:47:24.625862 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-15 16:47:24.649232 | controller | skipping: Conditional result was False
2026-09-15 16:47:24.658070 | 
2026-09-15 16:47:24.658224 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-15 16:47:25.258735 | controller | changed
2026-09-15 16:47:25.265914 | 
2026-09-15 16:47:25.266039 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-15 16:47:25.502758 | controller | ok
2026-09-15 16:47:25.512644 | 
2026-09-15 16:47:25.512850 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-15 16:47:26.129673 | controller | changed
2026-09-15 16:47:26.138545 | 
2026-09-15 16:47:26.138710 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-15 16:47:26.740030 | controller | changed
2026-09-15 16:47:26.747584 | 
2026-09-15 16:47:26.747710 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-15 16:47:26.773315 | controller | skipping: Conditional result was False
2026-09-15 16:47:26.781294 | 
2026-09-15 16:47:26.781452 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-15 16:47:27.138980 | controller -> localhost | changed
2026-09-15 16:47:27.149769 | 
2026-09-15 16:47:27.149855 | TASK [add-build-sshkey : Add back temp key]
2026-09-15 16:47:27.488682 | controller -> localhost | Identity added: /var/lib/zuul/builds/8e888531fcb74ebe814ac3fdeb151b72/work/8e888531fcb74ebe814ac3fdeb151b72_id_rsa (zuul-build-sshkey)
2026-09-15 16:47:27.488955 | controller -> localhost | ok: Runtime: 0:00:00.013077
2026-09-15 16:47:27.498713 | 
2026-09-15 16:47:27.498965 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-15 16:47:27.876657 | controller | ok
2026-09-15 16:47:27.883801 | 
2026-09-15 16:47:27.883937 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-15 16:47:27.909580 | controller | skipping: Conditional result was False
2026-09-15 16:47:27.925895 | 
2026-09-15 16:47:27.926204 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-15 16:47:28.373601 | controller | ok
2026-09-15 16:47:28.382980 | 
2026-09-15 16:47:28.383277 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-15 16:47:29.440681 | controller | Output suppressed because no_log was given
2026-09-15 16:47:29.453392 | 
2026-09-15 16:47:29.453520 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-15 16:47:29.710819 | controller | ok: "logs"
2026-09-15 16:47:29.711202 | controller | ok: All items complete
2026-09-15 16:47:29.711278 | 
2026-09-15 16:47:29.922653 | controller | ok: "artifacts"
2026-09-15 16:47:30.104201 | controller | ok: "docs"
2026-09-15 16:47:30.121658 | 
2026-09-15 16:47:30.121861 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-15 16:47:30.366922 | controller | changed: "logs"
2026-09-15 16:47:30.566091 | controller | changed: "artifacts"
2026-09-15 16:47:30.753699 | controller | changed: "docs"
2026-09-15 16:47:30.775686 | 
2026-09-15 16:47:30.775899 | PLAY RECAP
2026-09-15 16:47:30.776023 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-15 16:47:30.776114 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 16:47:30.776184 | 
2026-09-15 16:47:30.914887 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-15 16:47:30.917801 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-15 16:47:31.488104 | 
2026-09-15 16:47:31.488229 | PLAY [all]
2026-09-15 16:47:31.497128 | 
2026-09-15 16:47:31.497221 | TASK [setup-uv : Extract archive]
2026-09-15 16:47:34.069276 | controller | changed
2026-09-15 16:47:34.077520 | 
2026-09-15 16:47:34.077689 | TASK [setup-uv : Print version]
2026-09-15 16:47:34.496937 | controller | uv 0.8.13
2026-09-15 16:47:34.615060 | controller | ok: Runtime: 0:00:00.015730
2026-09-15 16:47:34.628742 | 
2026-09-15 16:47:34.628866 | TASK [Install Ansible collection siblings]
2026-09-15 16:47:35.045058 | controller | ok:
2026-09-15 16:47:35.045249 | controller | Skipping ansible.posix: no checked-out sibling
2026-09-15 16:47:35.045285 | controller | Skipping community.crypto: no checked-out sibling
2026-09-15 16:47:35.045313 | controller | Skipping community.general: no checked-out sibling
2026-09-15 16:47:35.045354 | controller | Skipping kubernetes.core: no checked-out sibling
2026-09-15 16:47:35.045383 | controller | Skipping vexxhost.containers: no checked-out sibling
2026-09-15 16:47:35.048123 | 
2026-09-15 16:47:35.048212 | PLAY RECAP
2026-09-15 16:47:35.048300 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 16:47:35.048370 | 
2026-09-15 16:47:35.196183 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-15 16:47:35.198989 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-09-15 16:47:35.759074 | 
2026-09-15 16:47:35.759188 | PLAY [all]
2026-09-15 16:47:35.768111 | 
2026-09-15 16:47:35.768181 | TASK [Update apt cache]
2026-09-15 16:47:42.197886 | controller | ok
2026-09-15 16:47:42.206077 | 
2026-09-15 16:47:42.206229 | TASK [Disable firewalld]
2026-09-15 16:47:42.231846 | controller | skipping: Conditional result was False
2026-09-15 16:47:42.240544 | 
2026-09-15 16:47:42.240707 | TASK [Install "jq" for log collection]
2026-09-15 16:47:44.889961 | controller | changed
2026-09-15 16:47:44.893248 | 
2026-09-15 16:47:44.893375 | PLAY RECAP
2026-09-15 16:47:44.893460 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-15 16:47:44.893501 | 
2026-09-15 16:47:45.066496 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-09-15 16:47:45.069289 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-15 16:47:45.633324 | 
2026-09-15 16:47:45.633473 | PLAY [all]
2026-09-15 16:47:45.643045 | 
2026-09-15 16:47:45.643114 | LOOP [Check inventory directories]
2026-09-15 16:47:45.983403 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-09-15 16:47:46.189369 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/cluster-api/inventory"
2026-09-15 16:47:46.378110 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/cluster-api/inventory"
2026-09-15 16:47:46.389315 | 
2026-09-15 16:47:46.389604 | LOOP [Copy inventory file for Zuul]
2026-09-15 16:47:47.359973 | controller | changed:
2026-09-15 16:47:47.360361 | controller | {
2026-09-15 16:47:47.360427 | controller |   "ansible_loop_var": "item",
2026-09-15 16:47:47.360473 | controller |   "changed": false,
2026-09-15 16:47:47.360546 | controller |   "failed": false,
2026-09-15 16:47:47.360593 | controller |   "invocation": {
2026-09-15 16:47:47.360637 | controller |     "module_args": {
2026-09-15 16:47:47.360686 | controller |       "checksum_algorithm": "sha1",
2026-09-15 16:47:47.360733 | controller |       "follow": true,
2026-09-15 16:47:47.360772 | controller |       "get_attributes": true,
2026-09-15 16:47:47.360842 | controller |       "get_checksum": true,
2026-09-15 16:47:47.360898 | controller |       "get_mime": true,
2026-09-15 16:47:47.360945 | controller |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-09-15 16:47:47.360983 | controller |     }
2026-09-15 16:47:47.361049 | controller |   },
2026-09-15 16:47:47.361086 | controller |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-09-15 16:47:47.361131 | controller |   "stat": {
2026-09-15 16:47:47.361169 | controller |     "atime": 1789490849.27835,
2026-09-15 16:47:47.361223 | controller |     "attr_flags": "e",
2026-09-15 16:47:47.361262 | controller |     "attributes": [
2026-09-15 16:47:47.361310 | controller |       "extents"
2026-09-15 16:47:47.361369 | controller |     ],
2026-09-15 16:47:47.361408 | controller |     "block_size": 4096,
2026-09-15 16:47:47.361461 | controller |     "blocks": 8,
2026-09-15 16:47:47.361499 | controller |     "charset": "unknown",
2026-09-15 16:47:47.361548 | controller |     "ctime": 1789490849.3183503,
2026-09-15 16:47:47.361595 | controller |     "dev": 65025,
2026-09-15 16:47:47.361633 | controller |     "device_type": 0,
2026-09-15 16:47:47.361670 | controller |     "executable": true,
2026-09-15 16:47:47.361706 | controller |     "exists": true,
2026-09-15 16:47:47.361750 | controller |     "gid": 1000,
2026-09-15 16:47:47.361796 | controller |     "gr_name": "zuul",
2026-09-15 16:47:47.361834 | controller |     "inode": 521495,
2026-09-15 16:47:47.361881 | controller |     "isblk": false,
2026-09-15 16:47:47.361956 | controller |     "ischr": false,
2026-09-15 16:47:47.362000 | controller |     "isdir": true,
2026-09-15 16:47:47.362045 | controller |     "isfifo": false,
2026-09-15 16:47:47.362081 | controller |     "isgid": false,
2026-09-15 16:47:47.362116 | controller |     "islnk": false,
2026-09-15 16:47:47.362151 | controller |     "isreg": false,
2026-09-15 16:47:47.362195 | controller |     "issock": false,
2026-09-15 16:47:47.362240 | controller |     "isuid": false,
2026-09-15 16:47:47.362274 | controller |     "mimetype": "unknown",
2026-09-15 16:47:47.362311 | controller |     "mode": "0755",
2026-09-15 16:47:47.362364 | controller |     "mtime": 1789490829.9891493,
2026-09-15 16:47:47.362410 | controller |     "nlink": 14,
2026-09-15 16:47:47.362455 | controller |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-09-15 16:47:47.362492 | controller |     "pw_name": "zuul",
2026-09-15 16:47:47.362532 | controller |     "readable": true,
2026-09-15 16:47:47.362580 | controller |     "rgrp": true,
2026-09-15 16:47:47.362627 | controller |     "roth": true,
2026-09-15 16:47:47.362664 | controller |     "rusr": true,
2026-09-15 16:47:47.362710 | controller |     "size": 4096,
2026-09-15 16:47:47.362747 | controller |     "uid": 1000,
2026-09-15 16:47:47.362792 | controller |     "version": "890633920",
2026-09-15 16:47:47.362838 | controller |     "wgrp": false,
2026-09-15 16:47:47.362887 | controller |     "woth": false,
2026-09-15 16:47:47.362949 | controller |     "writeable": true,
2026-09-15 16:47:47.363002 | controller |     "wusr": true,
2026-09-15 16:47:47.363040 | controller |     "xgrp": true,
2026-09-15 16:47:47.363094 | controller |     "xoth": true,
2026-09-15 16:47:47.363130 | controller |     "xusr": true
2026-09-15 16:47:47.363200 | controller |   }
2026-09-15 16:47:47.363248 | controller | }
2026-09-15 16:47:47.363316 | controller | ok: All items complete
2026-09-15 16:47:47.363388 | 
2026-09-15 16:47:47.366129 | controller | skipping: Conditional result was False
2026-09-15 16:47:47.367804 | controller | skipping: Conditional result was False
2026-09-15 16:47:47.389571 | 
2026-09-15 16:47:47.389785 | LOOP [Switch "ansible_host" to private IP]
2026-09-15 16:47:47.812636 | controller | changed: 1 replacements made
2026-09-15 16:47:47.814984 | controller | skipping: Conditional result was False
2026-09-15 16:47:47.821069 | controller | skipping: Conditional result was False
2026-09-15 16:47:47.831566 | 
2026-09-15 16:47:47.831705 | TASK [Run Molecule scenario]
2026-09-15 16:47:48.329220 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-09-15 16:47:48.329567 | controller | Creating virtual environment at: .venv
2026-09-15 16:47:48.698154 | controller | Downloading pygments (1.2MiB)
2026-09-15 16:47:48.698852 | controller | Downloading ansible-core (2.3MiB)
2026-09-15 16:47:48.699270 | controller | Downloading cryptography (4.3MiB)
2026-09-15 16:47:48.875374 | controller |  Downloading cryptography
2026-09-15 16:47:48.925761 | controller |  Downloading pygments
2026-09-15 16:47:49.099854 | controller |  Downloading ansible-core
2026-09-15 16:47:49.153180 | controller | Installed 36 packages in 52ms
2026-09-15 16:47:49.842168 | controller | INFO     Collection 'vexxhost.kubernetes' detected.
2026-09-15 16:47:49.842251 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-09-15 16:47:50.920172 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-09-15 16:47:50.920253 | controller | INFO     default scenario not found, disabling shared state.
2026-09-15 16:47:50.920345 | controller | INFO     [cluster-api > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-15 16:47:50.920418 | controller | INFO     [cluster-api > prerun] Performing prerun with role_name_check=0...
2026-09-15 16:50:13.984282 | controller | WARNING  Retrying execution failure 1 of: ansible-galaxy collection install -vvv ansible.posix:>=1.6.0
2026-09-15 16:50:13.984360 | controller | ERROR    Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code:
2026-09-15 16:50:13.984369 | controller | Starting galaxy collection install process
2026-09-15 16:50:13.984377 | controller | Process install dependency map
2026-09-15 16:50:13.984384 | controller | Starting collection install process
2026-09-15 16:50:13.984432 | 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-1481m_7w0cda/tmpb38fy3_9/ansible-posix-2.2.2-mya3olyg
2026-09-15 16:50:13.984451 | controller |
2026-09-15 16:50:13.984461 | controller | ansible-galaxy
2026-09-15 16:50:13.984468 | controller |   config file = None
2026-09-15 16:50:13.984475 | controller |   configured module search path =
2026-09-15 16:50:13.984496 | controller |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible
2026-09-15 16:50:13.984512 | 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-15 16:50:13.984522 | controller |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy
2026-09-15 16:50:13.984530 | 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-15 16:50:13.984537 | controller |   jinja version = 3.1.6
2026-09-15 16:50:13.984544 | controller |   pyyaml version = 6.0.3 (with libyaml v0.2.5)
2026-09-15 16:50:13.984551 | controller | No config file found; using defaults
2026-09-15 16:50:13.984558 | controller | Created /home/zuul/.ansible/galaxy_token
2026-09-15 16:50:13.984565 | controller | : Failed to download collection tar from 'default' due to the following unforeseen error: The read operation timed out
2026-09-15 16:50:13.984572 | controller |
2026-09-15 16:50:13.986246 | controller | Traceback (most recent call last):
2026-09-15 16:50:13.989472 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/molecule", line 10, in <module>
2026-09-15 16:50:13.989492 | controller |     sys.exit(main())
2026-09-15 16:50:13.989500 | controller |              ~~~~^^
2026-09-15 16:50:13.989809 | 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-15 16:50:13.989820 | controller |     return self.main(*args, **kwargs)
2026-09-15 16:50:13.989842 | controller |            ~~~~~~~~~^^^^^^^^^^^^^^^^^
2026-09-15 16:50:13.990134 | 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-15 16:50:13.990144 | controller |     rv = self.invoke(ctx)
2026-09-15 16:50:13.990442 | 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-15 16:50:13.990450 | controller |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-09-15 16:50:13.990457 | controller |                            ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^
2026-09-15 16:50:13.990734 | 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-15 16:50:13.990759 | controller |     return ctx.invoke(self.callback, **ctx.params)
2026-09-15 16:50:13.990766 | controller |            ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-15 16:50:13.991007 | 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-15 16:50:13.991015 | controller |     return callback(*args, **kwargs)
2026-09-15 16:50:13.991239 | 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-15 16:50:13.991248 | controller |     return func(*args, **kwargs)
2026-09-15 16:50:13.991478 | 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-15 16:50:13.991486 | controller |     return f(get_current_context(), *args, **kwargs)
2026-09-15 16:50:13.991707 | 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-15 16:50:13.991717 | controller |     return func(ctx)
2026-09-15 16:50:13.992017 | 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-15 16:50:13.992025 | controller |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-09-15 16:50:13.992033 | controller |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-15 16:50:13.992292 | 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-15 16:50:13.992301 | controller |     _run_scenarios(scenarios, command_args, default_config)
2026-09-15 16:50:13.992308 | controller |     ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-15 16:50:13.992730 | 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-15 16:50:13.992753 | controller |     scenario.config.runtime.prepare_environment(
2026-09-15 16:50:13.992760 | controller |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-15 16:50:13.992767 | controller |         install_local=True,
2026-09-15 16:50:13.992774 | controller |         ^^^^^^^^^^^^^^^^^^^
2026-09-15 16:50:13.992780 | controller |         role_name_check=role_name_check,
2026-09-15 16:50:13.992787 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-15 16:50:13.992794 | controller |     )
2026-09-15 16:50:13.992801 | controller |     ^
2026-09-15 16:50:13.993191 | 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-15 16:50:13.993200 | controller |     self.install_collection(
2026-09-15 16:50:13.993206 | controller |     ~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-15 16:50:13.993213 | controller |         f"{name}{',' if is_url(name) else ':'}{required_version}",
2026-09-15 16:50:13.993220 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-15 16:50:13.993227 | controller |         destination=destination,
2026-09-15 16:50:13.993234 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-15 16:50:13.993241 | controller |     )
2026-09-15 16:50:13.993255 | controller |     ^
2026-09-15 16:50:13.993437 | 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-15 16:50:13.993445 | controller |     raise InvalidPrerequisiteError(msg)
2026-09-15 16:50:13.994401 | controller | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code:
2026-09-15 16:50:13.994412 | controller | Starting galaxy collection install process
2026-09-15 16:50:13.994419 | controller | Process install dependency map
2026-09-15 16:50:13.994433 | controller | Starting collection install process
2026-09-15 16:50:13.994440 | 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-1481m_7w0cda/tmpb38fy3_9/ansible-posix-2.2.2-mya3olyg
2026-09-15 16:50:13.994448 | controller |
2026-09-15 16:50:13.994455 | controller | ansible-galaxy [core 2.21.4]
2026-09-15 16:50:13.994462 | controller |   config file = None
2026-09-15 16:50:13.994469 | 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-15 16:50:13.994484 | controller |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible
2026-09-15 16:50:13.994491 | 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-15 16:50:13.994499 | controller |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy
2026-09-15 16:50:13.994506 | 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-15 16:50:13.994513 | controller |   jinja version = 3.1.6
2026-09-15 16:50:13.994520 | controller |   pyyaml version = 6.0.3 (with libyaml v0.2.5)
2026-09-15 16:50:13.994526 | controller | No config file found; using defaults
2026-09-15 16:50:13.994533 | controller | Created /home/zuul/.ansible/galaxy_token
2026-09-15 16:50:13.994540 | controller | [ERROR]: Failed to download collection tar from 'default' due to the following unforeseen error: The read operation timed out
2026-09-15 16:50:13.994547 | controller |
2026-09-15 16:50:14.457077 | controller | ERROR
2026-09-15 16:50:14.457305 | controller | {
2026-09-15 16:50:14.457385 | controller |   "delta": "0:02:25.897018",
2026-09-15 16:50:14.457416 | controller |   "end": "2026-09-15 16:50:14.116829",
2026-09-15 16:50:14.457438 | controller |   "msg": "non-zero return code",
2026-09-15 16:50:14.457459 | controller |   "rc": 1,
2026-09-15 16:50:14.457479 | controller |   "start": "2026-09-15 16:47:48.219811"
2026-09-15 16:50:14.457501 | controller | }
failure
2026-09-15 16:50:14.460530 | 
2026-09-15 16:50:14.460584 | PLAY RECAP
2026-09-15 16:50:14.460632 | controller | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-15 16:50:14.460658 | 
2026-09-15 16:50:14.609287 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-15 16:50:14.612662 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-09-15 16:50:15.185843 | 
2026-09-15 16:50:15.185968 | PLAY [all]
2026-09-15 16:50:15.195510 | 
2026-09-15 16:50:15.195591 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-15 16:50:15.620181 | controller | changed
2026-09-15 16:50:15.628728 | 
2026-09-15 16:50:15.628896 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-15 16:50:16.057522 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 16:50:16.057677 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 16:50:16.066635 | controller | environment: line 1: kubectl: command not found
2026-09-15 16:50:16.068800 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 16:50:16.069195 | controller | environment: line 1: kubectl: command not found
2026-09-15 16:50:16.071514 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 16:50:16.077007 | controller | environment: line 1: kubectl: command not found
2026-09-15 16:50:16.077049 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 16:50:16.077508 | controller | environment: line 1: kubectl: command not found
2026-09-15 16:50:16.079566 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 16:50:16.081809 | controller | environment: line 1: kubectl: command not found
2026-09-15 16:50:16.083934 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 16:50:16.171418 | controller | ok: Runtime: 0:00:00.045275
2026-09-15 16:50:16.179209 | 
2026-09-15 16:50:16.179354 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-15 16:50:16.443723 | controller | changed
2026-09-15 16:50:16.451060 | 
2026-09-15 16:50:16.451206 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-15 16:50:16.762121 | controller | environment: line 5: kubectl: command not found
2026-09-15 16:50:16.764043 | controller | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args valuewarning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 16:50:16.764076 | controller |
2026-09-15 16:50:16.764364 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 16:50:16.989869 | controller | ok: Runtime: 0:00:00.013894
2026-09-15 16:50:16.998616 | 
2026-09-15 16:50:16.998746 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-15 16:50:17.654508 | controller | changed:
2026-09-15 16:50:17.654752 | controller | created directory /var/lib/zuul/builds/8e888531fcb74ebe814ac3fdeb151b72/work/logs/controller
2026-09-15 16:50:17.654812 | controller | cd+++++++++ objects/
2026-09-15 16:50:17.654857 | controller | cd+++++++++ objects/cluster/
2026-09-15 16:50:17.654901 | controller | cd+++++++++ objects/namespaced/
2026-09-15 16:50:17.666552 | 
2026-09-15 16:50:17.666673 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-15 16:50:17.927103 | controller | changed
2026-09-15 16:50:17.935228 | 
2026-09-15 16:50:17.935337 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-15 16:50:18.219259 | controller | changed
2026-09-15 16:50:18.226647 | 
2026-09-15 16:50:18.226782 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-15 16:50:18.505089 | controller | environment: line 3: kubectl: command not found
2026-09-15 16:50:18.770861 | controller | ok: Runtime: 0:00:00.012606
2026-09-15 16:50:18.778652 | 
2026-09-15 16:50:18.778783 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-15 16:50:19.381773 | controller | changed:
2026-09-15 16:50:19.382046 | controller | cd+++++++++ pod-logs/
2026-09-15 16:50:19.382115 | controller | cd+++++++++ pod-logs/failed-pods/
2026-09-15 16:50:19.397601 | 
2026-09-15 16:50:19.397771 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-15 16:50:19.656387 | controller | changed: "values"
2026-09-15 16:50:19.904692 | controller | changed: "releases"
2026-09-15 16:50:19.922496 | 
2026-09-15 16:50:19.922824 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-15 16:50:20.198294 | controller | /bin/bash: line 3: kubectl: command not found
2026-09-15 16:50:20.465894 | controller | ok: Runtime: 0:00:00.008885
2026-09-15 16:50:20.473178 | 
2026-09-15 16:50:20.473322 | TASK [helm-release-status : Downloads logs to executor]
2026-09-15 16:50:21.024956 | controller | changed:
2026-09-15 16:50:21.025238 | controller | cd+++++++++ helm/
2026-09-15 16:50:21.025382 | controller | cd+++++++++ helm/releases/
2026-09-15 16:50:21.025520 | controller | cd+++++++++ helm/values/
2026-09-15 16:50:21.035337 | 
2026-09-15 16:50:21.035450 | PLAY RECAP
2026-09-15 16:50:21.035543 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 16:50:21.035593 | 
2026-09-15 16:50:21.180948 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-09-15 16:50:21.183892 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-15 16:50:21.786958 | 
2026-09-15 16:50:21.787091 | PLAY [all]
2026-09-15 16:50:21.796449 | 
2026-09-15 16:50:21.796547 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-15 16:50:21.850232 | controller | skipping: Conditional result was False
2026-09-15 16:50:21.855879 | 
2026-09-15 16:50:21.855985 | TASK [fetch-output : Set log path for single node]
2026-09-15 16:50:21.906638 | controller | ok
2026-09-15 16:50:21.914009 | 
2026-09-15 16:50:21.914160 | LOOP [fetch-output : Ensure local output dirs]
2026-09-15 16:50:22.330173 | controller -> localhost | ok: "/var/lib/zuul/builds/8e888531fcb74ebe814ac3fdeb151b72/work/logs"
2026-09-15 16:50:22.598158 | controller -> localhost | changed: "/var/lib/zuul/builds/8e888531fcb74ebe814ac3fdeb151b72/work/artifacts"
2026-09-15 16:50:22.839043 | controller -> localhost | changed: "/var/lib/zuul/builds/8e888531fcb74ebe814ac3fdeb151b72/work/docs"
2026-09-15 16:50:22.863095 | 
2026-09-15 16:50:22.863350 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-15 16:50:23.793766 | controller | changed: .d..t...... ./
2026-09-15 16:50:23.794129 | controller | changed: All items complete
2026-09-15 16:50:23.794193 | 
2026-09-15 16:50:24.283196 | controller | changed: .d..t...... ./
2026-09-15 16:50:24.788801 | controller | changed: .d..t...... ./
2026-09-15 16:50:24.825919 | 
2026-09-15 16:50:24.826103 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-15 16:50:25.325086 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.009364
2026-09-15 16:50:25.555850 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.005774
2026-09-15 16:50:25.579785 | 
2026-09-15 16:50:25.580615 | PLAY [all]
2026-09-15 16:50:25.594795 | 
2026-09-15 16:50:25.595011 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-15 16:50:26.115222 | controller | changed
2026-09-15 16:50:26.123590 | 
2026-09-15 16:50:26.123694 | PLAY RECAP
2026-09-15 16:50:26.123780 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-15 16:50:26.123824 | 
2026-09-15 16:50:26.255008 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-15 16:50:26.256443 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-15 16:50:26.789735 | 
2026-09-15 16:50:26.789842 | PLAY [localhost]
2026-09-15 16:50:26.798656 | 
2026-09-15 16:50:26.798732 | TASK [Generate Zuul manifest]
2026-09-15 16:50:26.814961 | localhost | ok
2026-09-15 16:50:26.828631 | 
2026-09-15 16:50:26.828727 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-15 16:50:27.240827 | localhost | changed
2026-09-15 16:50:27.260266 | 
2026-09-15 16:50:27.260430 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-15 16:50:27.291057 | localhost | ok
2026-09-15 16:50:27.299723 | 
2026-09-15 16:50:27.299822 | TASK [Upload logs]
2026-09-15 16:50:27.316969 | localhost | ok
2026-09-15 16:50:27.395339 | 
2026-09-15 16:50:27.395461 | TASK [Set zuul-log-path fact]
2026-09-15 16:50:27.411857 | localhost | ok
2026-09-15 16:50:27.421656 | 
2026-09-15 16:50:27.421723 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-15 16:50:27.447418 | localhost | ok
2026-09-15 16:50:27.453849 | 
2026-09-15 16:50:27.453910 | TASK [upload-logs : Create log directories]
2026-09-15 16:50:27.911502 | localhost | changed
2026-09-15 16:50:27.921052 | 
2026-09-15 16:50:27.921203 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-15 16:50:28.358631 | localhost -> localhost | ok: Runtime: 0:00:00.005526
2026-09-15 16:50:28.368045 | 
2026-09-15 16:50:28.368195 | TASK [upload-logs : Upload logs to log server]
2026-09-15 16:50:28.790382 | localhost | Output suppressed because no_log was given
2026-09-15 16:50:28.793709 | 
2026-09-15 16:50:28.793772 | LOOP [upload-logs : Compress console log and json output]
2026-09-15 16:50:28.855873 | localhost | skipping: Conditional result was False
2026-09-15 16:50:28.903995 | localhost | skipping: Conditional result was False
2026-09-15 16:50:28.914018 | 
2026-09-15 16:50:28.914192 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-15 16:50:28.955121 | localhost | skipping: Conditional result was False
2026-09-15 16:50:28.955446 | 
2026-09-15 16:50:28.958449 | localhost | skipping: Conditional result was False
2026-09-15 16:50:28.965357 | 
2026-09-15 16:50:28.965533 | LOOP [upload-logs : Upload console log and json output]