2026-08-31 06:14:23.861732 | Job console starting
2026-08-31 06:14:23.871350 | Updating git repos
2026-08-31 06:14:23.929277 | Cloning repos into workspace
2026-08-31 06:14:24.028310 | Restoring repo states
2026-08-31 06:14:24.042740 | Merging changes
2026-08-31 06:14:24.892524 | Checking out repos
2026-08-31 06:14:25.117950 | Preparing playbooks
2026-08-31 06:14:27.442781 | Running Ansible setup
2026-08-31 06:14:31.085344 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-31 06:14:31.690275 | 
2026-08-31 06:14:31.690419 | PLAY [localhost]
2026-08-31 06:14:31.697986 | 
2026-08-31 06:14:31.698056 | TASK [Gathering Facts]
2026-08-31 06:14:32.557773 | localhost | ok
2026-08-31 06:14:32.566789 | 
2026-08-31 06:14:32.566862 | TASK [Setup log path fact]
2026-08-31 06:14:32.586651 | localhost | ok
2026-08-31 06:14:32.599086 | 
2026-08-31 06:14:32.599152 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-31 06:14:32.629555 | localhost | ok
2026-08-31 06:14:32.637813 | 
2026-08-31 06:14:32.637912 | TASK [emit-job-header : Print job information]
2026-08-31 06:14:32.679090 | # Job Information
2026-08-31 06:14:32.679309 | Ansible Version: 2.16.18
2026-08-31 06:14:32.679380 | Job: ansible-collection-kubernetes-molecule-aio-debian-trixie
2026-08-31 06:14:32.679432 | Pipeline: check
2026-08-31 06:14:32.679479 | Executor: 2d72f0692154
2026-08-31 06:14:32.679524 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/268
2026-08-31 06:14:32.679575 | Event ID: 09be109c-a502-11f1-80a7-823ea524666b
2026-08-31 06:14:32.683589 | 
2026-08-31 06:14:32.683651 | LOOP [emit-job-header : Print node information]
2026-08-31 06:14:32.768051 | localhost | ok:
2026-08-31 06:14:32.768792 | localhost | # Node Information
2026-08-31 06:14:32.768837 | localhost | Inventory Hostname: controller
2026-08-31 06:14:32.768885 | localhost | Hostname: np0000191491
2026-08-31 06:14:32.768924 | localhost | Username: zuul
2026-08-31 06:14:32.768987 | localhost | Distro: Debian 13.6
2026-08-31 06:14:32.769029 | localhost | Provider: yul1
2026-08-31 06:14:32.769067 | localhost | Region: ca-ymq-1
2026-08-31 06:14:32.769102 | localhost | Label: debian-trixie
2026-08-31 06:14:32.769138 | localhost | Product Name: OpenStack Nova
2026-08-31 06:14:32.769172 | localhost | Interface IP: 162.253.55.82
2026-08-31 06:14:32.779423 | 
2026-08-31 06:14:32.779541 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-31 06:14:33.158333 | localhost -> localhost | changed
2026-08-31 06:14:33.166322 | 
2026-08-31 06:14:33.166419 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-31 06:14:33.990796 | localhost -> localhost | changed
2026-08-31 06:14:34.001356 | 
2026-08-31 06:14:34.001415 | PLAY [all]
2026-08-31 06:14:34.007848 | 
2026-08-31 06:14:34.007909 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-31 06:14:34.225263 | controller -> localhost | ok
2026-08-31 06:14:34.235274 | 
2026-08-31 06:14:34.235340 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-31 06:14:34.267498 | controller | ok
2026-08-31 06:14:34.283714 | controller | included: /var/lib/zuul/builds/0864da617a114374adbb20a04cd0fb1b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-31 06:14:34.288302 | 
2026-08-31 06:14:34.288360 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-31 06:14:35.854339 | controller -> localhost | Generating public/private rsa key pair.
2026-08-31 06:14:35.854617 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/0864da617a114374adbb20a04cd0fb1b/work/0864da617a114374adbb20a04cd0fb1b_id_rsa
2026-08-31 06:14:35.854680 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/0864da617a114374adbb20a04cd0fb1b/work/0864da617a114374adbb20a04cd0fb1b_id_rsa.pub
2026-08-31 06:14:35.854729 | controller -> localhost | The key fingerprint is:
2026-08-31 06:14:35.854777 | controller -> localhost | SHA256:pSwab10AZp2Aksct6/Pnmb37mq2vwy41xMp66sTw0lU zuul-build-sshkey
2026-08-31 06:14:35.854841 | controller -> localhost | The key's randomart image is:
2026-08-31 06:14:35.854888 | controller -> localhost | +---[RSA 3072]----+
2026-08-31 06:14:35.854940 | controller -> localhost | |   o o=o .       |
2026-08-31 06:14:35.855004 | controller -> localhost | |  o =o..o        |
2026-08-31 06:14:35.855053 | controller -> localhost | |   o o  ..E      |
2026-08-31 06:14:35.855099 | controller -> localhost | |    .  . =o      |
2026-08-31 06:14:35.855143 | controller -> localhost | |   .o ..So.      |
2026-08-31 06:14:35.855187 | controller -> localhost | |    oO +o.o      |
2026-08-31 06:14:35.855232 | controller -> localhost | |    ooB..o .     |
2026-08-31 06:14:35.855276 | controller -> localhost | |     +o ++oo     |
2026-08-31 06:14:35.855323 | controller -> localhost | |     .o=+oXX+    |
2026-08-31 06:14:35.855368 | controller -> localhost | +----[SHA256]-----+
2026-08-31 06:14:35.855470 | controller -> localhost | ok: Runtime: 0:00:01.160264
2026-08-31 06:14:35.862228 | 
2026-08-31 06:14:35.862293 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-31 06:14:35.893450 | controller | ok
2026-08-31 06:14:35.902391 | controller | included: /var/lib/zuul/builds/0864da617a114374adbb20a04cd0fb1b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-31 06:14:35.908735 | 
2026-08-31 06:14:35.908797 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-31 06:14:35.933511 | controller | skipping: Conditional result was False
2026-08-31 06:14:35.941348 | 
2026-08-31 06:14:35.941433 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-31 06:14:36.512284 | controller | changed
2026-08-31 06:14:36.520251 | 
2026-08-31 06:14:36.520338 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-31 06:14:36.730782 | controller | ok
2026-08-31 06:14:36.736091 | 
2026-08-31 06:14:36.736159 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-31 06:14:37.300015 | controller | changed
2026-08-31 06:14:37.305532 | 
2026-08-31 06:14:37.305658 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-31 06:14:37.881661 | controller | changed
2026-08-31 06:14:37.887119 | 
2026-08-31 06:14:37.887207 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-31 06:14:37.913168 | controller | skipping: Conditional result was False
2026-08-31 06:14:37.922546 | 
2026-08-31 06:14:37.922642 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-31 06:14:38.303569 | controller -> localhost | changed
2026-08-31 06:14:38.319364 | 
2026-08-31 06:14:38.319432 | TASK [add-build-sshkey : Add back temp key]
2026-08-31 06:14:38.593478 | controller -> localhost | Identity added: /var/lib/zuul/builds/0864da617a114374adbb20a04cd0fb1b/work/0864da617a114374adbb20a04cd0fb1b_id_rsa (zuul-build-sshkey)
2026-08-31 06:14:38.593722 | controller -> localhost | ok: Runtime: 0:00:00.011765
2026-08-31 06:14:38.599129 | 
2026-08-31 06:14:38.599194 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-31 06:14:38.942752 | controller | ok
2026-08-31 06:14:38.946684 | 
2026-08-31 06:14:38.946753 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-31 06:14:38.971929 | controller | skipping: Conditional result was False
2026-08-31 06:14:38.988871 | 
2026-08-31 06:14:38.989040 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-31 06:14:39.380209 | controller | ok
2026-08-31 06:14:39.384978 | 
2026-08-31 06:14:39.385044 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-31 06:14:40.508816 | controller | Output suppressed because no_log was given
2026-08-31 06:14:40.515303 | 
2026-08-31 06:14:40.515368 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-31 06:14:40.749998 | controller | ok: "logs"
2026-08-31 06:14:40.750313 | controller | ok: All items complete
2026-08-31 06:14:40.750357 | 
2026-08-31 06:14:40.947524 | controller | ok: "artifacts"
2026-08-31 06:14:41.120288 | controller | ok: "docs"
2026-08-31 06:14:41.136200 | 
2026-08-31 06:14:41.136476 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-31 06:14:41.337624 | controller | changed: "logs"
2026-08-31 06:14:41.505014 | controller | changed: "artifacts"
2026-08-31 06:14:41.715008 | controller | changed: "docs"
2026-08-31 06:14:41.727689 | 
2026-08-31 06:14:41.727824 | PLAY RECAP
2026-08-31 06:14:41.727888 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-31 06:14:41.727928 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-31 06:14:41.727959 | 
2026-08-31 06:14:41.883401 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-31 06:14:41.885048 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-31 06:14:42.474611 | 
2026-08-31 06:14:42.474759 | PLAY [all]
2026-08-31 06:14:42.484326 | 
2026-08-31 06:14:42.484400 | TASK [setup-uv : Extract archive]
2026-08-31 06:14:44.897854 | controller | changed
2026-08-31 06:14:44.904464 | 
2026-08-31 06:14:44.904551 | TASK [setup-uv : Print version]
2026-08-31 06:14:45.292994 | controller | uv 0.8.13
2026-08-31 06:14:45.436634 | controller | ok: Runtime: 0:00:00.017265
2026-08-31 06:14:45.448019 | 
2026-08-31 06:14:45.448094 | TASK [Install Ansible collection siblings]
2026-08-31 06:14:45.886080 | controller | ok:
2026-08-31 06:14:45.886296 | controller | Skipping ansible.posix: no checked-out sibling
2026-08-31 06:14:45.886337 | controller | Skipping community.crypto: no checked-out sibling
2026-08-31 06:14:45.886368 | controller | Skipping community.general: no checked-out sibling
2026-08-31 06:14:45.886396 | controller | Skipping kubernetes.core: no checked-out sibling
2026-08-31 06:14:45.886423 | controller | Skipping vexxhost.containers: no checked-out sibling
2026-08-31 06:14:45.888652 | 
2026-08-31 06:14:45.888716 | PLAY RECAP
2026-08-31 06:14:45.888777 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-31 06:14:45.888808 | 
2026-08-31 06:14:46.041353 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-31 06:14:46.044193 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-08-31 06:14:46.637187 | 
2026-08-31 06:14:46.637321 | PLAY [all]
2026-08-31 06:14:46.647174 | 
2026-08-31 06:14:46.647250 | TASK [Update apt cache]
2026-08-31 06:14:52.477985 | controller | ok
2026-08-31 06:14:52.483811 | 
2026-08-31 06:14:52.483889 | TASK [Disable firewalld]
2026-08-31 06:14:52.500343 | controller | skipping: Conditional result was False
2026-08-31 06:14:52.509573 | 
2026-08-31 06:14:52.509974 | TASK [Install "jq" for log collection]
2026-08-31 06:14:55.229263 | controller | changed
2026-08-31 06:14:55.232639 | 
2026-08-31 06:14:55.232740 | PLAY RECAP
2026-08-31 06:14:55.232788 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-31 06:14:55.232810 | 
2026-08-31 06:14:55.349219 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-08-31 06:14:55.351997 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-31 06:14:55.935784 | 
2026-08-31 06:14:55.935922 | PLAY [all]
2026-08-31 06:14:55.945917 | 
2026-08-31 06:14:55.945991 | LOOP [Check inventory directories]
2026-08-31 06:14:56.328031 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-08-31 06:14:56.519793 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/aio/inventory"
2026-08-31 06:14:56.716752 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/aio/inventory"
2026-08-31 06:14:56.734959 | 
2026-08-31 06:14:56.735201 | LOOP [Copy inventory file for Zuul]
2026-08-31 06:14:57.667424 | controller | changed:
2026-08-31 06:14:57.667707 | controller | {
2026-08-31 06:14:57.667753 | controller |   "ansible_loop_var": "item",
2026-08-31 06:14:57.667784 | controller |   "changed": false,
2026-08-31 06:14:57.667810 | controller |   "failed": false,
2026-08-31 06:14:57.667840 | controller |   "invocation": {
2026-08-31 06:14:57.667866 | controller |     "module_args": {
2026-08-31 06:14:57.667891 | controller |       "checksum_algorithm": "sha1",
2026-08-31 06:14:57.667916 | controller |       "follow": true,
2026-08-31 06:14:57.667941 | controller |       "get_attributes": true,
2026-08-31 06:14:57.667971 | controller |       "get_checksum": true,
2026-08-31 06:14:57.668000 | controller |       "get_mime": true,
2026-08-31 06:14:57.668029 | controller |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-08-31 06:14:57.668058 | controller |     }
2026-08-31 06:14:57.668089 | controller |   },
2026-08-31 06:14:57.668118 | controller |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-08-31 06:14:57.668148 | controller |   "stat": {
2026-08-31 06:14:57.668177 | controller |     "atime": 1788156880.352623,
2026-08-31 06:14:57.668208 | controller |     "attr_flags": "e",
2026-08-31 06:14:57.668240 | controller |     "attributes": [
2026-08-31 06:14:57.668272 | controller |       "extents"
2026-08-31 06:14:57.668301 | controller |     ],
2026-08-31 06:14:57.668325 | controller |     "block_size": 4096,
2026-08-31 06:14:57.668348 | controller |     "blocks": 8,
2026-08-31 06:14:57.668372 | controller |     "charset": "unknown",
2026-08-31 06:14:57.668397 | controller |     "ctime": 1788156880.3886235,
2026-08-31 06:14:57.668421 | controller |     "dev": 65025,
2026-08-31 06:14:57.668444 | controller |     "device_type": 0,
2026-08-31 06:14:57.668467 | controller |     "executable": true,
2026-08-31 06:14:57.668490 | controller |     "exists": true,
2026-08-31 06:14:57.668513 | controller |     "gid": 1000,
2026-08-31 06:14:57.668535 | controller |     "gr_name": "zuul",
2026-08-31 06:14:57.668558 | controller |     "inode": 521495,
2026-08-31 06:14:57.668581 | controller |     "isblk": false,
2026-08-31 06:14:57.668605 | controller |     "ischr": false,
2026-08-31 06:14:57.668628 | controller |     "isdir": true,
2026-08-31 06:14:57.668652 | controller |     "isfifo": false,
2026-08-31 06:14:57.668675 | controller |     "isgid": false,
2026-08-31 06:14:57.668697 | controller |     "islnk": false,
2026-08-31 06:14:57.668720 | controller |     "isreg": false,
2026-08-31 06:14:57.668743 | controller |     "issock": false,
2026-08-31 06:14:57.668767 | controller |     "isuid": false,
2026-08-31 06:14:57.668790 | controller |     "mimetype": "unknown",
2026-08-31 06:14:57.668813 | controller |     "mode": "0755",
2026-08-31 06:14:57.668835 | controller |     "mtime": 1788156864.7606285,
2026-08-31 06:14:57.668857 | controller |     "nlink": 14,
2026-08-31 06:14:57.668881 | controller |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-08-31 06:14:57.668905 | controller |     "pw_name": "zuul",
2026-08-31 06:14:57.668934 | controller |     "readable": true,
2026-08-31 06:14:57.669001 | controller |     "rgrp": true,
2026-08-31 06:14:57.669034 | controller |     "roth": true,
2026-08-31 06:14:57.669060 | controller |     "rusr": true,
2026-08-31 06:14:57.669084 | controller |     "size": 4096,
2026-08-31 06:14:57.669108 | controller |     "uid": 1000,
2026-08-31 06:14:57.669133 | controller |     "version": "255804496",
2026-08-31 06:14:57.669157 | controller |     "wgrp": false,
2026-08-31 06:14:57.669183 | controller |     "woth": false,
2026-08-31 06:14:57.669208 | controller |     "writeable": true,
2026-08-31 06:14:57.669232 | controller |     "wusr": true,
2026-08-31 06:14:57.669256 | controller |     "xgrp": true,
2026-08-31 06:14:57.669280 | controller |     "xoth": true,
2026-08-31 06:14:57.669309 | controller |     "xusr": true
2026-08-31 06:14:57.669360 | controller |   }
2026-08-31 06:14:57.669389 | controller | }
2026-08-31 06:14:57.669448 | controller | ok: All items complete
2026-08-31 06:14:57.669491 | 
2026-08-31 06:14:57.671243 | controller | skipping: Conditional result was False
2026-08-31 06:14:57.672168 | controller | skipping: Conditional result was False
2026-08-31 06:14:57.686498 | 
2026-08-31 06:14:57.686687 | LOOP [Switch "ansible_host" to private IP]
2026-08-31 06:14:58.078796 | controller | changed: 1 replacements made
2026-08-31 06:14:58.080097 | controller | skipping: Conditional result was False
2026-08-31 06:14:58.080699 | controller | skipping: Conditional result was False
2026-08-31 06:14:58.092648 | 
2026-08-31 06:14:58.092835 | TASK [Run Molecule scenario]
2026-08-31 06:14:58.576556 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-08-31 06:14:58.576651 | controller | Creating virtual environment at: .venv
2026-08-31 06:14:59.801705 | controller | Downloading pygments (1.2MiB)
2026-08-31 06:14:59.803731 | controller | Downloading cryptography (4.3MiB)
2026-08-31 06:14:59.821233 | controller | Downloading ansible-core (2.3MiB)
2026-08-31 06:14:59.977737 | controller |  Downloading cryptography
2026-08-31 06:15:00.027352 | controller |  Downloading pygments
2026-08-31 06:15:00.165912 | controller |  Downloading ansible-core
2026-08-31 06:15:00.233595 | controller | Installed 36 packages in 67ms
2026-08-31 06:15:00.901104 | controller | INFO     Collection 'vexxhost.kubernetes' detected.
2026-08-31 06:15:00.901139 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-08-31 06:15:01.821086 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-08-31 06:15:01.821115 | controller | INFO     default scenario not found, disabling shared state.
2026-08-31 06:15:01.821233 | controller | INFO     [aio > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-08-31 06:15:01.821306 | controller | INFO     [aio > prerun] Performing prerun with role_name_check=0...
2026-08-31 06:25:58.862009 | controller | WARNING  Retrying execution failure 250 of: ansible-galaxy collection install -vvv community.general:>=4.5.0
2026-08-31 06:25:58.862074 | controller | ERROR    Command ansible-galaxy collection install -vvv community.general:>=4.5.0, returned 250 code:
2026-08-31 06:25:58.862127 | controller | Starting galaxy collection install process
2026-08-31 06:25:58.862135 | controller | Process install dependency map
2026-08-31 06:25:58.862144 | controller |
2026-08-31 06:25:58.862169 | controller | ansible-galaxy
2026-08-31 06:25:58.862177 | controller |   config file = None
2026-08-31 06:25:58.862184 | controller |   configured module search path =
2026-08-31 06:25:58.862191 | controller |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible
2026-08-31 06:25:58.862202 | 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-08-31 06:25:58.862211 | controller |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy
2026-08-31 06:25:58.862222 | 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-08-31 06:25:58.862229 | controller |   jinja version = 3.1.6
2026-08-31 06:25:58.862236 | controller |   pyyaml version = 6.0.3 (with libyaml v0.2.5)
2026-08-31 06:25:58.862244 | controller | No config file found; using defaults
2026-08-31 06:25:58.862251 | controller | Found installed collection ansible.posix:2.2.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-08-31 06:25:58.862258 | controller | Found installed collection community.crypto:3.3.0 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-08-31 06:25:58.862265 | controller | Opened /home/zuul/.ansible/galaxy_token
2026-08-31 06:25:58.862272 | controller | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an unexpected error when getting available versions of collection community.general: 'results'
2026-08-31 06:25:58.862279 | controller | : Unexpected Exception, this is probably a bug: 'results'
2026-08-31 06:25:58.862287 | controller |
2026-08-31 06:25:58.862294 | controller | Traceback (most recent call last):
2026-08-31 06:25:58.862301 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/__init__.py", line 661, in cli_executor
2026-08-31 06:25:58.862308 | controller |     exit_code = cli.run()
2026-08-31 06:25:58.862315 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 723, in run
2026-08-31 06:25:58.862341 | controller |     return context.CLIARGS()
2026-08-31 06:25:58.862348 | controller |            ~~~~~~~~~~~~~~~~~~~~~~~^^
2026-08-31 06:25:58.862362 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 98, in method_wrapper
2026-08-31 06:25:58.862369 | controller |     return wrapped_method(*args, **kwargs)
2026-08-31 06:25:58.862375 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 1388, in execute_install
2026-08-31 06:25:58.862383 | controller |     self._execute_install_collection(
2026-08-31 06:25:58.862389 | controller |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-08-31 06:25:58.862396 | controller |         collection_requirements, collection_path,
2026-08-31 06:25:58.862403 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 06:25:58.862410 | controller |         artifacts_manager=artifacts_manager,
2026-08-31 06:25:58.862417 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 06:25:58.862424 | controller |     )
2026-08-31 06:25:58.862431 | controller |     ^
2026-08-31 06:25:58.862438 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 1437, in _execute_install_collection
2026-08-31 06:25:58.862445 | controller |     install_collections(
2026-08-31 06:25:58.862452 | controller |     ~~~~~~~~~~~~~~~~~~~^
2026-08-31 06:25:58.862459 | controller |         requirements, output_path, self.api_servers, ignore_errors,
2026-08-31 06:25:58.862466 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 06:25:58.862474 | controller |     ...<5 lines>...
2026-08-31 06:25:58.862481 | controller |         read_requirement_paths=read_req_paths,
2026-08-31 06:25:58.862488 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 06:25:58.862494 | controller |     )
2026-08-31 06:25:58.862501 | controller |     ^
2026-08-31 06:25:58.862508 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/__init__.py", line 722, in install_collections
2026-08-31 06:25:58.862515 | controller |     dependency_map = _resolve_depenency_map(
2026-08-31 06:25:58.862522 | controller |         collections,
2026-08-31 06:25:58.862529 | controller |     ...<7 lines>...
2026-08-31 06:25:58.862535 | controller |         offline=offline,
2026-08-31 06:25:58.862542 | controller |     )
2026-08-31 06:25:58.862549 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/__init__.py", line 1844, in _resolve_depenency_map
2026-08-31 06:25:58.862556 | controller |     collection_dep_resolver.resolve(
2026-08-31 06:25:58.862563 | controller |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-08-31 06:25:58.862570 | controller |         requested_requirements,
2026-08-31 06:25:58.862577 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 06:25:58.862584 | controller |         max_rounds=2000000,  # NOTE: same constant pip uses
2026-08-31 06:25:58.862591 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 06:25:58.862597 | controller |     ).mapping,
2026-08-31 06:25:58.862604 | controller |     ^
2026-08-31 06:25:58.862620 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 601, in resolve
2026-08-31 06:25:58.862627 | controller |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-08-31 06:25:58.862634 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 513, in resolve
2026-08-31 06:25:58.862641 | controller |     failure_criterion = self._attempt_to_pin_criterion(name)
2026-08-31 06:25:58.862648 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 220, in _attempt_to_pin_criterion
2026-08-31 06:25:58.862655 | controller |     criteria = self._get_updated_criteria(candidate)
2026-08-31 06:25:58.862662 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 210, in _get_updated_criteria
2026-08-31 06:25:58.862674 | controller |     for requirement in self._p.get_dependencies(candidate=candidate):
2026-08-31 06:25:58.862681 | controller |                        ~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^
2026-08-31 06:25:58.862688 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 410, in get_dependencies
2026-08-31 06:25:58.862695 | controller |     req_map = self._api_proxy.get_collection_dependencies(candidate)
2026-08-31 06:25:58.862702 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 171, in get_collection_dependencies
2026-08-31 06:25:58.862709 | controller |     get_collection_version_metadata(collection_candidate).
2026-08-31 06:25:58.862716 | controller |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 06:25:58.862723 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 157, in get_collection_version_metadata
2026-08-31 06:25:58.862730 | controller |     raise last_err
2026-08-31 06:25:58.862743 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 124, in get_collection_version_metadata
2026-08-31 06:25:58.862750 | controller |     version_metadata = api.get_collection_version_metadata(
2026-08-31 06:25:58.862756 | controller |         collection_candidate.namespace,
2026-08-31 06:25:58.862763 | controller |         collection_candidate.name,
2026-08-31 06:25:58.862770 | controller |         collection_candidate.ver,
2026-08-31 06:25:58.862777 | controller |     )
2026-08-31 06:25:58.862784 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 128, in wrapped
2026-08-31 06:25:58.862791 | controller |     return method(self, *args, **kwargs)
2026-08-31 06:25:58.862798 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 770, in get_collection_version_metadata
2026-08-31 06:25:58.862805 | controller |     data = self._call_galaxy(n_collection_url, error_context_msg=error_context_msg, cache=True)
2026-08-31 06:25:58.862812 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/module_utils/api.py", line 168, in run_function
2026-08-31 06:25:58.862819 | controller |     return call_retryable_function()
2026-08-31 06:25:58.862826 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 366, in _call_galaxy
2026-08-31 06:25:58.862833 | controller |     res = path_cache
2026-08-31 06:25:58.862840 | controller |           ~~~~~~~~~~^^^^^^^^^^^
2026-08-31 06:25:58.862847 | controller | KeyError: 'results'
2026-08-31 06:25:58.862854 | controller |
2026-08-31 06:25:58.862861 | controller | The above exception was the direct cause of the following exception:
2026-08-31 06:25:58.862868 | controller |
2026-08-31 06:25:58.862875 | controller | Traceback (most recent call last):
2026-08-31 06:25:58.862882 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/__init__.py", line 670, in cli_executor
2026-08-31 06:25:58.862889 | controller |     raise AnsibleError("Unexpected Exception, this is probably a bug.") from ex
2026-08-31 06:25:58.862896 | controller | ansible.errors.AnsibleError: Unexpected Exception, this is probably a bug: 'results'
2026-08-31 06:25:58.862903 | controller |
2026-08-31 06:25:58.862909 | controller |
2026-08-31 06:25:58.865420 | controller | Traceback (most recent call last):
2026-08-31 06:25:58.868622 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/molecule", line 10, in <module>
2026-08-31 06:25:58.868646 | controller |     sys.exit(main())
2026-08-31 06:25:58.868655 | controller |              ~~~~^^
2026-08-31 06:25:58.868913 | 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-08-31 06:25:58.868945 | controller |     return self.main(*args, **kwargs)
2026-08-31 06:25:58.868954 | controller |            ~~~~~~~~~^^^^^^^^^^^^^^^^^
2026-08-31 06:25:58.869189 | 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-08-31 06:25:58.869216 | controller |     rv = self.invoke(ctx)
2026-08-31 06:25:58.869508 | 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-08-31 06:25:58.869535 | controller |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-08-31 06:25:58.869545 | controller |                            ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^
2026-08-31 06:25:58.869790 | 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-08-31 06:25:58.869814 | controller |     return ctx.invoke(self.callback, **ctx.params)
2026-08-31 06:25:58.869822 | controller |            ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 06:25:58.870020 | 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-08-31 06:25:58.870043 | controller |     return callback(*args, **kwargs)
2026-08-31 06:25:58.870224 | 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-08-31 06:25:58.870234 | controller |     return func(*args, **kwargs)
2026-08-31 06:25:58.870493 | 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-08-31 06:25:58.870516 | controller |     return f(get_current_context(), *args, **kwargs)
2026-08-31 06:25:58.870682 | 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-08-31 06:25:58.870692 | controller |     return func(ctx)
2026-08-31 06:25:58.870944 | 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-08-31 06:25:58.870953 | controller |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-08-31 06:25:58.870960 | controller |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 06:25:58.871228 | 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-08-31 06:25:58.871238 | controller |     _run_scenarios(scenarios, command_args, default_config)
2026-08-31 06:25:58.871245 | controller |     ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 06:25:58.871640 | 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-08-31 06:25:58.871648 | controller |     scenario.config.runtime.prepare_environment(
2026-08-31 06:25:58.871655 | controller |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-08-31 06:25:58.871662 | controller |         install_local=True,
2026-08-31 06:25:58.871669 | controller |         ^^^^^^^^^^^^^^^^^^^
2026-08-31 06:25:58.871676 | controller |         role_name_check=role_name_check,
2026-08-31 06:25:58.871683 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 06:25:58.871690 | controller |     )
2026-08-31 06:25:58.871697 | controller |     ^
2026-08-31 06:25:58.872048 | 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-08-31 06:25:58.872057 | controller |     self.install_collection(
2026-08-31 06:25:58.872064 | controller |     ~~~~~~~~~~~~~~~~~~~~~~~^
2026-08-31 06:25:58.872071 | controller |         f"{name}{',' if is_url(name) else ':'}{required_version}",
2026-08-31 06:25:58.872078 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 06:25:58.872093 | controller |         destination=destination,
2026-08-31 06:25:58.872101 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 06:25:58.872107 | controller |     )
2026-08-31 06:25:58.872114 | controller |     ^
2026-08-31 06:25:58.872341 | 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-08-31 06:25:58.872353 | controller |     raise InvalidPrerequisiteError(msg)
2026-08-31 06:25:58.875830 | controller | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv community.general:>=4.5.0, returned 250 code:
2026-08-31 06:25:58.875838 | controller | Starting galaxy collection install process
2026-08-31 06:25:58.875845 | controller | Process install dependency map
2026-08-31 06:25:58.875853 | controller |
2026-08-31 06:25:58.875861 | controller | ansible-galaxy [core 2.20.1]
2026-08-31 06:25:58.875868 | controller |   config file = None
2026-08-31 06:25:58.875875 | 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-08-31 06:25:58.875882 | controller |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible
2026-08-31 06:25:58.875890 | 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-08-31 06:25:58.875898 | controller |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy
2026-08-31 06:25:58.875905 | 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-08-31 06:25:58.875912 | controller |   jinja version = 3.1.6
2026-08-31 06:25:58.875919 | controller |   pyyaml version = 6.0.3 (with libyaml v0.2.5)
2026-08-31 06:25:58.875926 | controller | No config file found; using defaults
2026-08-31 06:25:58.875933 | controller | Found installed collection ansible.posix:2.2.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-08-31 06:25:58.875940 | controller | Found installed collection community.crypto:3.3.0 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-08-31 06:25:58.875947 | controller | Opened /home/zuul/.ansible/galaxy_token
2026-08-31 06:25:58.875954 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an unexpected error when getting available versions of collection community.general: 'results'
2026-08-31 06:25:58.875961 | controller | [ERROR]: Unexpected Exception, this is probably a bug: 'results'
2026-08-31 06:25:58.875968 | controller |
2026-08-31 06:25:58.875975 | controller | Traceback (most recent call last):
2026-08-31 06:25:58.875982 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/__init__.py", line 661, in cli_executor
2026-08-31 06:25:58.875989 | controller |     exit_code = cli.run()
2026-08-31 06:25:58.875996 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 723, in run
2026-08-31 06:25:58.876003 | controller |     return context.CLIARGS['func']()
2026-08-31 06:25:58.876010 | controller |            ~~~~~~~~~~~~~~~~~~~~~~~^^
2026-08-31 06:25:58.876016 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 98, in method_wrapper
2026-08-31 06:25:58.876023 | controller |     return wrapped_method(*args, **kwargs)
2026-08-31 06:25:58.876030 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 1388, in execute_install
2026-08-31 06:25:58.876044 | controller |     self._execute_install_collection(
2026-08-31 06:25:58.876051 | controller |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-08-31 06:25:58.876065 | controller |         collection_requirements, collection_path,
2026-08-31 06:25:58.876072 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 06:25:58.876079 | controller |         artifacts_manager=artifacts_manager,
2026-08-31 06:25:58.876086 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 06:25:58.876093 | controller |     )
2026-08-31 06:25:58.876099 | controller |     ^
2026-08-31 06:25:58.876106 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 1437, in _execute_install_collection
2026-08-31 06:25:58.876113 | controller |     install_collections(
2026-08-31 06:25:58.876120 | controller |     ~~~~~~~~~~~~~~~~~~~^
2026-08-31 06:25:58.876127 | controller |         requirements, output_path, self.api_servers, ignore_errors,
2026-08-31 06:25:58.876134 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 06:25:58.876141 | controller |     ...<5 lines>...
2026-08-31 06:25:58.876186 | controller |         read_requirement_paths=read_req_paths,
2026-08-31 06:25:58.876195 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 06:25:58.876202 | controller |     )
2026-08-31 06:25:58.876208 | controller |     ^
2026-08-31 06:25:58.876215 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/__init__.py", line 722, in install_collections
2026-08-31 06:25:58.876222 | controller |     dependency_map = _resolve_depenency_map(
2026-08-31 06:25:58.876229 | controller |         collections,
2026-08-31 06:25:58.876236 | controller |     ...<7 lines>...
2026-08-31 06:25:58.876243 | controller |         offline=offline,
2026-08-31 06:25:58.876249 | controller |     )
2026-08-31 06:25:58.876256 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/__init__.py", line 1844, in _resolve_depenency_map
2026-08-31 06:25:58.876263 | controller |     collection_dep_resolver.resolve(
2026-08-31 06:25:58.876279 | controller |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-08-31 06:25:58.876286 | controller |         requested_requirements,
2026-08-31 06:25:58.876293 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 06:25:58.876300 | controller |         max_rounds=2000000,  # NOTE: same constant pip uses
2026-08-31 06:25:58.876307 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 06:25:58.876314 | controller |     ).mapping,
2026-08-31 06:25:58.876321 | controller |     ^
2026-08-31 06:25:58.876328 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 601, in resolve
2026-08-31 06:25:58.876335 | controller |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-08-31 06:25:58.876342 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 513, in resolve
2026-08-31 06:25:58.876348 | controller |     failure_criterion = self._attempt_to_pin_criterion(name)
2026-08-31 06:25:58.876356 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 220, in _attempt_to_pin_criterion
2026-08-31 06:25:58.876363 | controller |     criteria = self._get_updated_criteria(candidate)
2026-08-31 06:25:58.876369 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 210, in _get_updated_criteria
2026-08-31 06:25:58.876376 | controller |     for requirement in self._p.get_dependencies(candidate=candidate):
2026-08-31 06:25:58.876383 | controller |                        ~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^
2026-08-31 06:25:58.876390 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 410, in get_dependencies
2026-08-31 06:25:58.876396 | controller |     req_map = self._api_proxy.get_collection_dependencies(candidate)
2026-08-31 06:25:58.876409 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 171, in get_collection_dependencies
2026-08-31 06:25:58.876416 | controller |     get_collection_version_metadata(collection_candidate).
2026-08-31 06:25:58.876423 | controller |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^
2026-08-31 06:25:58.876430 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 157, in get_collection_version_metadata
2026-08-31 06:25:58.876437 | controller |     raise last_err
2026-08-31 06:25:58.876443 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 124, in get_collection_version_metadata
2026-08-31 06:25:58.876452 | controller |     version_metadata = api.get_collection_version_metadata(
2026-08-31 06:25:58.876459 | controller |         collection_candidate.namespace,
2026-08-31 06:25:58.876466 | controller |         collection_candidate.name,
2026-08-31 06:25:58.876473 | controller |         collection_candidate.ver,
2026-08-31 06:25:58.876480 | controller |     )
2026-08-31 06:25:58.876487 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 128, in wrapped
2026-08-31 06:25:58.876494 | controller |     return method(self, *args, **kwargs)
2026-08-31 06:25:58.876500 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 770, in get_collection_version_metadata
2026-08-31 06:25:58.876507 | controller |     data = self._call_galaxy(n_collection_url, error_context_msg=error_context_msg, cache=True)
2026-08-31 06:25:58.876520 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/module_utils/api.py", line 168, in run_function
2026-08-31 06:25:58.876528 | controller |     return call_retryable_function()
2026-08-31 06:25:58.876534 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 366, in _call_galaxy
2026-08-31 06:25:58.876541 | controller |     res = path_cache['results']
2026-08-31 06:25:58.876548 | controller |           ~~~~~~~~~~^^^^^^^^^^^
2026-08-31 06:25:58.876555 | controller | KeyError: 'results'
2026-08-31 06:25:58.876562 | controller |
2026-08-31 06:25:58.876569 | controller | The above exception was the direct cause of the following exception:
2026-08-31 06:25:58.876576 | controller |
2026-08-31 06:25:58.876583 | controller | Traceback (most recent call last):
2026-08-31 06:25:58.876590 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/cli/__init__.py", line 670, in cli_executor
2026-08-31 06:25:58.876596 | controller |     raise AnsibleError("Unexpected Exception, this is probably a bug.") from ex
2026-08-31 06:25:58.876603 | controller | ansible.errors.AnsibleError: Unexpected Exception, this is probably a bug: 'results'
2026-08-31 06:25:58.876610 | controller |
2026-08-31 06:25:58.876617 | controller |
2026-08-31 06:25:59.405841 | controller | ERROR
2026-08-31 06:25:59.406097 | controller | {
2026-08-31 06:25:59.406137 | controller |   "delta": "0:11:00.481700",
2026-08-31 06:25:59.406166 | controller |   "end": "2026-08-31 06:25:58.954061",
2026-08-31 06:25:59.406192 | controller |   "msg": "non-zero return code",
2026-08-31 06:25:59.406217 | controller |   "rc": 1,
2026-08-31 06:25:59.406242 | controller |   "start": "2026-08-31 06:14:58.472361"
2026-08-31 06:25:59.406266 | controller | }
failure
2026-08-31 06:25:59.411127 | 
2026-08-31 06:25:59.411177 | PLAY RECAP
2026-08-31 06:25:59.411226 | controller | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-08-31 06:25:59.411271 | 
2026-08-31 06:25:59.537704 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-31 06:25:59.539361 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-08-31 06:26:00.150075 | 
2026-08-31 06:26:00.150878 | PLAY [all]
2026-08-31 06:26:00.161950 | 
2026-08-31 06:26:00.162023 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-08-31 06:26:00.578614 | controller | changed
2026-08-31 06:26:00.584466 | 
2026-08-31 06:26:00.584576 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-08-31 06:26:00.999706 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-31 06:26:00.999828 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-31 06:26:01.006948 | controller | environment: line 1: kubectl: command not found
2026-08-31 06:26:01.009691 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-31 06:26:01.011912 | controller | environment: line 1: kubectl: command not found
2026-08-31 06:26:01.013801 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-31 06:26:01.016046 | controller | environment: line 1: kubectl: command not found
2026-08-31 06:26:01.018208 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-31 06:26:01.020665 | controller | environment: line 1: kubectl: command not found
2026-08-31 06:26:01.022709 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-31 06:26:01.023835 | controller | environment: line 1: kubectl: command not found
2026-08-31 06:26:01.025587 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-31 06:26:01.117106 | controller | ok: Runtime: 0:00:00.040693
2026-08-31 06:26:01.124098 | 
2026-08-31 06:26:01.124168 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-08-31 06:26:01.367215 | controller | changed
2026-08-31 06:26:01.372349 | 
2026-08-31 06:26:01.372426 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-08-31 06:26:01.629965 | controller | environment: line 5: kubectl: command not found
2026-08-31 06:26:01.630963 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-31 06:26:01.631550 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-31 06:26:01.631845 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-31 06:26:01.904054 | controller | ok: Runtime: 0:00:00.014479
2026-08-31 06:26:01.911957 | 
2026-08-31 06:26:01.912048 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-08-31 06:26:02.542637 | controller | changed:
2026-08-31 06:26:02.542855 | controller | created directory /var/lib/zuul/builds/0864da617a114374adbb20a04cd0fb1b/work/logs/controller
2026-08-31 06:26:02.542890 | controller | cd+++++++++ objects/
2026-08-31 06:26:02.542913 | controller | cd+++++++++ objects/cluster/
2026-08-31 06:26:02.542936 | controller | cd+++++++++ objects/namespaced/
2026-08-31 06:26:02.550061 | 
2026-08-31 06:26:02.550130 | TASK [gather-pod-logs : creating directory for pod logs]
2026-08-31 06:26:02.806237 | controller | changed
2026-08-31 06:26:02.811867 | 
2026-08-31 06:26:02.811961 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-08-31 06:26:03.079786 | controller | changed
2026-08-31 06:26:03.084268 | 
2026-08-31 06:26:03.084356 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-08-31 06:26:03.347405 | controller | environment: line 3: kubectl: command not found
2026-08-31 06:26:03.620675 | controller | ok: Runtime: 0:00:00.013268
2026-08-31 06:26:03.626383 | 
2026-08-31 06:26:03.626472 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-08-31 06:26:04.115388 | controller | changed:
2026-08-31 06:26:04.115698 | controller | cd+++++++++ pod-logs/
2026-08-31 06:26:04.115760 | controller | cd+++++++++ pod-logs/failed-pods/
2026-08-31 06:26:04.124264 | 
2026-08-31 06:26:04.124338 | LOOP [helm-release-status : creating directory for helm release status]
2026-08-31 06:26:04.384509 | controller | changed: "values"
2026-08-31 06:26:04.604197 | controller | changed: "releases"
2026-08-31 06:26:04.615432 | 
2026-08-31 06:26:04.615595 | TASK [helm-release-status : Gather get release status for helm charts]
2026-08-31 06:26:04.875454 | controller | /bin/bash: line 3: kubectl: command not found
2026-08-31 06:26:05.147931 | controller | ok: Runtime: 0:00:00.009027
2026-08-31 06:26:05.154423 | 
2026-08-31 06:26:05.154511 | TASK [helm-release-status : Downloads logs to executor]
2026-08-31 06:26:05.642525 | controller | changed:
2026-08-31 06:26:05.642846 | controller | cd+++++++++ helm/
2026-08-31 06:26:05.642907 | controller | cd+++++++++ helm/releases/
2026-08-31 06:26:05.642969 | controller | cd+++++++++ helm/values/
2026-08-31 06:26:05.647871 | 
2026-08-31 06:26:05.647923 | PLAY RECAP
2026-08-31 06:26:05.647972 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-31 06:26:05.647993 | 
2026-08-31 06:26:05.780255 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-08-31 06:26:05.783166 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-31 06:26:06.358396 | 
2026-08-31 06:26:06.358529 | PLAY [all]
2026-08-31 06:26:06.368523 | 
2026-08-31 06:26:06.368598 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-31 06:26:06.414362 | controller | skipping: Conditional result was False
2026-08-31 06:26:06.422733 | 
2026-08-31 06:26:06.422895 | TASK [fetch-output : Set log path for single node]
2026-08-31 06:26:06.466181 | controller | ok
2026-08-31 06:26:06.471167 | 
2026-08-31 06:26:06.471234 | LOOP [fetch-output : Ensure local output dirs]
2026-08-31 06:26:06.846177 | controller -> localhost | ok: "/var/lib/zuul/builds/0864da617a114374adbb20a04cd0fb1b/work/logs"
2026-08-31 06:26:07.045512 | controller -> localhost | changed: "/var/lib/zuul/builds/0864da617a114374adbb20a04cd0fb1b/work/artifacts"
2026-08-31 06:26:07.254831 | controller -> localhost | changed: "/var/lib/zuul/builds/0864da617a114374adbb20a04cd0fb1b/work/docs"
2026-08-31 06:26:07.271158 | 
2026-08-31 06:26:07.271328 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-31 06:26:07.921471 | controller | changed: .d..t...... ./
2026-08-31 06:26:07.921777 | controller | changed: All items complete
2026-08-31 06:26:07.921815 | 
2026-08-31 06:26:08.390657 | controller | changed: .d..t...... ./
2026-08-31 06:26:08.860211 | controller | changed: .d..t...... ./
2026-08-31 06:26:08.885063 | 
2026-08-31 06:26:08.885217 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-31 06:26:09.306555 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008032
2026-08-31 06:26:09.542299 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007840
2026-08-31 06:26:09.556146 | 
2026-08-31 06:26:09.556279 | PLAY [all]
2026-08-31 06:26:09.562785 | 
2026-08-31 06:26:09.562868 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-31 06:26:10.064393 | controller | changed
2026-08-31 06:26:10.069714 | 
2026-08-31 06:26:10.069765 | PLAY RECAP
2026-08-31 06:26:10.069811 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-31 06:26:10.069834 | 
2026-08-31 06:26:10.205187 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-31 06:26:10.207919 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-31 06:26:10.765706 | 
2026-08-31 06:26:10.765833 | PLAY [localhost]
2026-08-31 06:26:10.775660 | 
2026-08-31 06:26:10.775732 | TASK [Generate Zuul manifest]
2026-08-31 06:26:10.795193 | localhost | ok
2026-08-31 06:26:10.813063 | 
2026-08-31 06:26:10.813139 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-31 06:26:11.189807 | localhost | changed
2026-08-31 06:26:11.200756 | 
2026-08-31 06:26:11.200834 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-31 06:26:11.232509 | localhost | ok
2026-08-31 06:26:11.241717 | 
2026-08-31 06:26:11.241803 | TASK [Upload logs]
2026-08-31 06:26:11.262944 | localhost | ok
2026-08-31 06:26:11.330732 | 
2026-08-31 06:26:11.330861 | TASK [Set zuul-log-path fact]
2026-08-31 06:26:11.352815 | localhost | ok
2026-08-31 06:26:11.368201 | 
2026-08-31 06:26:11.368272 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-31 06:26:11.400996 | localhost | ok
2026-08-31 06:26:11.410518 | 
2026-08-31 06:26:11.410584 | TASK [upload-logs : Create log directories]
2026-08-31 06:26:11.838431 | localhost | changed
2026-08-31 06:26:11.845557 | 
2026-08-31 06:26:11.845656 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-31 06:26:12.286662 | localhost -> localhost | ok: Runtime: 0:00:00.004213
2026-08-31 06:26:12.293248 | 
2026-08-31 06:26:12.293341 | TASK [upload-logs : Upload logs to log server]
2026-08-31 06:26:12.772253 | localhost | Output suppressed because no_log was given
2026-08-31 06:26:12.779857 | 
2026-08-31 06:26:12.779982 | LOOP [upload-logs : Compress console log and json output]
2026-08-31 06:26:12.836061 | localhost | skipping: Conditional result was False
2026-08-31 06:26:12.890933 | localhost | skipping: Conditional result was False
2026-08-31 06:26:12.906925 | 
2026-08-31 06:26:12.907112 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-31 06:26:12.954496 | localhost | skipping: Conditional result was False
2026-08-31 06:26:12.954954 | 
2026-08-31 06:26:12.958539 | localhost | skipping: Conditional result was False
2026-08-31 06:26:12.974564 | 
2026-08-31 06:26:12.974736 | LOOP [upload-logs : Upload console log and json output]