2026-09-07 01:50:01.023482 | Job console starting
2026-09-07 01:50:01.034186 | Updating git repos
2026-09-07 01:50:01.097951 | Cloning repos into workspace
2026-09-07 01:50:01.158910 | Restoring repo states
2026-09-07 01:50:01.175719 | Merging changes
2026-09-07 01:50:02.141182 | Checking out repos
2026-09-07 01:50:02.183671 | Preparing playbooks
2026-09-07 01:50:04.495073 | Running Ansible setup
2026-09-07 01:50:08.210965 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-07 01:50:08.769925 | 
2026-09-07 01:50:08.770150 | PLAY [localhost]
2026-09-07 01:50:08.777360 | 
2026-09-07 01:50:08.777427 | TASK [Gathering Facts]
2026-09-07 01:50:09.665033 | localhost | ok
2026-09-07 01:50:09.677354 | 
2026-09-07 01:50:09.677501 | TASK [Setup log path fact]
2026-09-07 01:50:09.699540 | localhost | ok
2026-09-07 01:50:09.722592 | 
2026-09-07 01:50:09.722710 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-07 01:50:09.753663 | localhost | ok
2026-09-07 01:50:09.763892 | 
2026-09-07 01:50:09.763992 | TASK [emit-job-header : Print job information]
2026-09-07 01:50:09.811402 | # Job Information
2026-09-07 01:50:09.811561 | Ansible Version: 2.16.18
2026-09-07 01:50:09.811592 | Job: ansible-collection-kubernetes-molecule-cluster-api
2026-09-07 01:50:09.811615 | Pipeline: check
2026-09-07 01:50:09.811642 | Executor: 2d72f0692154
2026-09-07 01:50:09.811664 | Triggered by: https://github.com/vexxhost/ansible-collection-kubernetes/pull/300
2026-09-07 01:50:09.811685 | Event ID: 1a3e6320-aa5e-11f1-83e1-f73eae392cf5
2026-09-07 01:50:09.814174 | 
2026-09-07 01:50:09.814235 | LOOP [emit-job-header : Print node information]
2026-09-07 01:50:09.906378 | localhost | ok:
2026-09-07 01:50:09.906630 | localhost | # Node Information
2026-09-07 01:50:09.906691 | localhost | Inventory Hostname: controller
2026-09-07 01:50:09.906732 | localhost | Hostname: np0000191985
2026-09-07 01:50:09.906769 | localhost | Username: zuul
2026-09-07 01:50:09.906811 | localhost | Distro: Debian 13.6
2026-09-07 01:50:09.906874 | localhost | Provider: yul1
2026-09-07 01:50:09.906933 | localhost | Region: ca-ymq-1
2026-09-07 01:50:09.906988 | localhost | Label: debian-trixie
2026-09-07 01:50:09.907027 | localhost | Product Name: OpenStack Nova
2026-09-07 01:50:09.907063 | localhost | Interface IP: 162.253.55.119
2026-09-07 01:50:09.913964 | 
2026-09-07 01:50:09.914075 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-07 01:50:10.312460 | localhost -> localhost | changed
2026-09-07 01:50:10.319020 | 
2026-09-07 01:50:10.319100 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-07 01:50:11.265109 | localhost -> localhost | changed
2026-09-07 01:50:11.282909 | 
2026-09-07 01:50:11.283025 | PLAY [all]
2026-09-07 01:50:11.295776 | 
2026-09-07 01:50:11.295921 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-07 01:50:11.544416 | controller -> localhost | ok
2026-09-07 01:50:11.553290 | 
2026-09-07 01:50:11.553422 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-07 01:50:11.585407 | controller | ok
2026-09-07 01:50:11.611720 | controller | included: /var/lib/zuul/builds/1171b07af41b449bbd19b6fa493806d1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-07 01:50:11.618144 | 
2026-09-07 01:50:11.618217 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-07 01:50:12.559478 | controller -> localhost | Generating public/private rsa key pair.
2026-09-07 01:50:12.559747 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/1171b07af41b449bbd19b6fa493806d1/work/1171b07af41b449bbd19b6fa493806d1_id_rsa
2026-09-07 01:50:12.559814 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/1171b07af41b449bbd19b6fa493806d1/work/1171b07af41b449bbd19b6fa493806d1_id_rsa.pub
2026-09-07 01:50:12.559897 | controller -> localhost | The key fingerprint is:
2026-09-07 01:50:12.559944 | controller -> localhost | SHA256:cJvmUoBnLbrcVL43JfZ7cpzuprCP3OgPsbN8lv8T1PY zuul-build-sshkey
2026-09-07 01:50:12.560008 | controller -> localhost | The key's randomart image is:
2026-09-07 01:50:12.560054 | controller -> localhost | +---[RSA 3072]----+
2026-09-07 01:50:12.560110 | controller -> localhost | |                 |
2026-09-07 01:50:12.560157 | controller -> localhost | |     . .         |
2026-09-07 01:50:12.560203 | controller -> localhost | |    . * +       .|
2026-09-07 01:50:12.560245 | controller -> localhost | |     + B o     .o|
2026-09-07 01:50:12.560290 | controller -> localhost | |    . . S + . ...|
2026-09-07 01:50:12.560331 | controller -> localhost | |   . + + o *   .E|
2026-09-07 01:50:12.560373 | controller -> localhost | |    o o o B .o ..|
2026-09-07 01:50:12.560414 | controller -> localhost | |       . + @=.*. |
2026-09-07 01:50:12.560464 | controller -> localhost | |         .O=*X+.o|
2026-09-07 01:50:12.560596 | controller -> localhost | +----[SHA256]-----+
2026-09-07 01:50:12.560709 | controller -> localhost | ok: Runtime: 0:00:00.489959
2026-09-07 01:50:12.568700 | 
2026-09-07 01:50:12.568822 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-07 01:50:12.600280 | controller | ok
2026-09-07 01:50:12.614653 | controller | included: /var/lib/zuul/builds/1171b07af41b449bbd19b6fa493806d1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-07 01:50:12.627234 | 
2026-09-07 01:50:12.627359 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-07 01:50:12.651673 | controller | skipping: Conditional result was False
2026-09-07 01:50:12.660275 | 
2026-09-07 01:50:12.660419 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-07 01:50:13.210120 | controller | changed
2026-09-07 01:50:13.217139 | 
2026-09-07 01:50:13.217254 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-07 01:50:13.437057 | controller | ok
2026-09-07 01:50:13.440581 | 
2026-09-07 01:50:13.440641 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-07 01:50:14.028138 | controller | changed
2026-09-07 01:50:14.036601 | 
2026-09-07 01:50:14.036736 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-07 01:50:14.622502 | controller | changed
2026-09-07 01:50:14.627278 | 
2026-09-07 01:50:14.627356 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-07 01:50:14.651981 | controller | skipping: Conditional result was False
2026-09-07 01:50:14.659973 | 
2026-09-07 01:50:14.660103 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-07 01:50:15.079691 | controller -> localhost | changed
2026-09-07 01:50:15.097239 | 
2026-09-07 01:50:15.097469 | TASK [add-build-sshkey : Add back temp key]
2026-09-07 01:50:15.420368 | controller -> localhost | Identity added: /var/lib/zuul/builds/1171b07af41b449bbd19b6fa493806d1/work/1171b07af41b449bbd19b6fa493806d1_id_rsa (zuul-build-sshkey)
2026-09-07 01:50:15.420685 | controller -> localhost | ok: Runtime: 0:00:00.013866
2026-09-07 01:50:15.428562 | 
2026-09-07 01:50:15.428687 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-07 01:50:15.794961 | controller | ok
2026-09-07 01:50:15.801890 | 
2026-09-07 01:50:15.802036 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-07 01:50:15.837356 | controller | skipping: Conditional result was False
2026-09-07 01:50:15.853462 | 
2026-09-07 01:50:15.853671 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-07 01:50:16.345481 | controller | ok
2026-09-07 01:50:16.363061 | 
2026-09-07 01:50:16.363202 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-07 01:50:17.772895 | controller | Output suppressed because no_log was given
2026-09-07 01:50:17.803005 | 
2026-09-07 01:50:17.803160 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-07 01:50:18.035057 | controller | ok: "logs"
2026-09-07 01:50:18.035485 | controller | ok: All items complete
2026-09-07 01:50:18.035587 | 
2026-09-07 01:50:18.247329 | controller | ok: "artifacts"
2026-09-07 01:50:18.451972 | controller | ok: "docs"
2026-09-07 01:50:18.467058 | 
2026-09-07 01:50:18.467217 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-07 01:50:18.691749 | controller | changed: "logs"
2026-09-07 01:50:18.873937 | controller | changed: "artifacts"
2026-09-07 01:50:19.072249 | controller | changed: "docs"
2026-09-07 01:50:19.086007 | 
2026-09-07 01:50:19.086180 | PLAY RECAP
2026-09-07 01:50:19.086282 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-07 01:50:19.086328 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 01:50:19.086358 | 
2026-09-07 01:50:19.284609 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-07 01:50:19.285935 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-07 01:50:19.828767 | 
2026-09-07 01:50:19.828908 | PLAY [all]
2026-09-07 01:50:19.837918 | 
2026-09-07 01:50:19.837991 | TASK [setup-uv : Extract archive]
2026-09-07 01:50:22.380222 | controller | changed
2026-09-07 01:50:22.390218 | 
2026-09-07 01:50:22.390370 | TASK [setup-uv : Print version]
2026-09-07 01:50:22.789373 | controller | uv 0.8.13
2026-09-07 01:50:22.932119 | controller | ok: Runtime: 0:00:00.016805
2026-09-07 01:50:22.942128 | 
2026-09-07 01:50:22.942343 | TASK [Install Ansible collection siblings]
2026-09-07 01:50:23.389143 | controller | ok:
2026-09-07 01:50:23.389411 | controller | Skipping ansible.posix: no checked-out sibling
2026-09-07 01:50:23.389466 | controller | Skipping community.crypto: no checked-out sibling
2026-09-07 01:50:23.389509 | controller | Skipping community.general: no checked-out sibling
2026-09-07 01:50:23.389549 | controller | Skipping kubernetes.core: no checked-out sibling
2026-09-07 01:50:23.389589 | controller | Skipping vexxhost.containers: no checked-out sibling
2026-09-07 01:50:23.392477 | 
2026-09-07 01:50:23.392575 | PLAY RECAP
2026-09-07 01:50:23.392660 | controller | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 01:50:23.392704 | 
2026-09-07 01:50:23.616383 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-07 01:50:23.619109 | PRE-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-09-07 01:50:24.175091 | 
2026-09-07 01:50:24.175204 | PLAY [all]
2026-09-07 01:50:24.183978 | 
2026-09-07 01:50:24.184042 | TASK [Update apt cache]
2026-09-07 01:50:29.455555 | controller | ok
2026-09-07 01:50:29.462442 | 
2026-09-07 01:50:29.462547 | TASK [Disable firewalld]
2026-09-07 01:50:29.477152 | controller | skipping: Conditional result was False
2026-09-07 01:50:29.483000 | 
2026-09-07 01:50:29.483085 | TASK [Install "jq" for log collection]
2026-09-07 01:50:31.922652 | controller | changed
2026-09-07 01:50:31.924949 | 
2026-09-07 01:50:31.925011 | PLAY RECAP
2026-09-07 01:50:31.925067 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-07 01:50:31.925095 | 
2026-09-07 01:50:32.110841 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/pre.yml@main]
2026-09-07 01:50:32.112983 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-07 01:50:32.670183 | 
2026-09-07 01:50:32.670608 | PLAY [all]
2026-09-07 01:50:32.680804 | 
2026-09-07 01:50:32.680871 | LOOP [Check inventory directories]
2026-09-07 01:50:33.086187 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-09-07 01:50:33.260346 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/molecule/cluster-api/inventory"
2026-09-07 01:50:33.458822 | controller | ok: "src/github.com/vexxhost/ansible-collection-kubernetes/extensions/molecule/cluster-api/inventory"
2026-09-07 01:50:33.482014 | 
2026-09-07 01:50:33.482309 | LOOP [Copy inventory file for Zuul]
2026-09-07 01:50:34.393720 | controller | changed:
2026-09-07 01:50:34.394009 | controller | {
2026-09-07 01:50:34.394065 | controller |   "ansible_loop_var": "item",
2026-09-07 01:50:34.394111 | controller |   "changed": false,
2026-09-07 01:50:34.394149 | controller |   "failed": false,
2026-09-07 01:50:34.394193 | controller |   "invocation": {
2026-09-07 01:50:34.394229 | controller |     "module_args": {
2026-09-07 01:50:34.394266 | controller |       "checksum_algorithm": "sha1",
2026-09-07 01:50:34.394300 | controller |       "follow": true,
2026-09-07 01:50:34.394334 | controller |       "get_attributes": true,
2026-09-07 01:50:34.394409 | controller |       "get_checksum": true,
2026-09-07 01:50:34.394475 | controller |       "get_mime": true,
2026-09-07 01:50:34.394514 | controller |       "path": "src/github.com/vexxhost/ansible-collection-kubernetes"
2026-09-07 01:50:34.394550 | controller |     }
2026-09-07 01:50:34.394584 | controller |   },
2026-09-07 01:50:34.394616 | controller |   "item": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-09-07 01:50:34.394650 | controller |   "stat": {
2026-09-07 01:50:34.394682 | controller |     "atime": 1788745817.599453,
2026-09-07 01:50:34.394715 | controller |     "attr_flags": "e",
2026-09-07 01:50:34.394748 | controller |     "attributes": [
2026-09-07 01:50:34.394781 | controller |       "extents"
2026-09-07 01:50:34.394837 | controller |     ],
2026-09-07 01:50:34.394870 | controller |     "block_size": 4096,
2026-09-07 01:50:34.394903 | controller |     "blocks": 8,
2026-09-07 01:50:34.394935 | controller |     "charset": "unknown",
2026-09-07 01:50:34.394971 | controller |     "ctime": 1788745817.635453,
2026-09-07 01:50:34.395005 | controller |     "dev": 65025,
2026-09-07 01:50:34.395037 | controller |     "device_type": 0,
2026-09-07 01:50:34.395069 | controller |     "executable": true,
2026-09-07 01:50:34.395100 | controller |     "exists": true,
2026-09-07 01:50:34.395132 | controller |     "gid": 1000,
2026-09-07 01:50:34.395164 | controller |     "gr_name": "zuul",
2026-09-07 01:50:34.395195 | controller |     "inode": 521495,
2026-09-07 01:50:34.395227 | controller |     "isblk": false,
2026-09-07 01:50:34.395259 | controller |     "ischr": false,
2026-09-07 01:50:34.395291 | controller |     "isdir": true,
2026-09-07 01:50:34.395321 | controller |     "isfifo": false,
2026-09-07 01:50:34.395352 | controller |     "isgid": false,
2026-09-07 01:50:34.395382 | controller |     "islnk": false,
2026-09-07 01:50:34.395413 | controller |     "isreg": false,
2026-09-07 01:50:34.395443 | controller |     "issock": false,
2026-09-07 01:50:34.395474 | controller |     "isuid": false,
2026-09-07 01:50:34.395503 | controller |     "mimetype": "unknown",
2026-09-07 01:50:34.395534 | controller |     "mode": "0755",
2026-09-07 01:50:34.395563 | controller |     "mtime": 1788745801.306173,
2026-09-07 01:50:34.395594 | controller |     "nlink": 14,
2026-09-07 01:50:34.395625 | controller |     "path": "src/github.com/vexxhost/ansible-collection-kubernetes",
2026-09-07 01:50:34.395656 | controller |     "pw_name": "zuul",
2026-09-07 01:50:34.395694 | controller |     "readable": true,
2026-09-07 01:50:34.395726 | controller |     "rgrp": true,
2026-09-07 01:50:34.395757 | controller |     "roth": true,
2026-09-07 01:50:34.395804 | controller |     "rusr": true,
2026-09-07 01:50:34.395839 | controller |     "size": 4096,
2026-09-07 01:50:34.395871 | controller |     "uid": 1000,
2026-09-07 01:50:34.395903 | controller |     "version": "1808310154",
2026-09-07 01:50:34.395936 | controller |     "wgrp": false,
2026-09-07 01:50:34.395972 | controller |     "woth": false,
2026-09-07 01:50:34.396005 | controller |     "writeable": true,
2026-09-07 01:50:34.396036 | controller |     "wusr": true,
2026-09-07 01:50:34.396068 | controller |     "xgrp": true,
2026-09-07 01:50:34.396100 | controller |     "xoth": true,
2026-09-07 01:50:34.396130 | controller |     "xusr": true
2026-09-07 01:50:34.396178 | controller |   }
2026-09-07 01:50:34.396212 | controller | }
2026-09-07 01:50:34.396269 | controller | ok: All items complete
2026-09-07 01:50:34.396307 | 
2026-09-07 01:50:34.398699 | controller | skipping: Conditional result was False
2026-09-07 01:50:34.399931 | controller | skipping: Conditional result was False
2026-09-07 01:50:34.415410 | 
2026-09-07 01:50:34.415480 | LOOP [Switch "ansible_host" to private IP]
2026-09-07 01:50:34.793683 | controller | changed: 1 replacements made
2026-09-07 01:50:34.797942 | controller | skipping: Conditional result was False
2026-09-07 01:50:34.806108 | controller | skipping: Conditional result was False
2026-09-07 01:50:34.826434 | 
2026-09-07 01:50:34.826586 | TASK [Run Molecule scenario]
2026-09-07 01:50:35.356822 | controller | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-09-07 01:50:35.356928 | controller | Creating virtual environment at: .venv
2026-09-07 01:50:35.715860 | controller | Downloading pygments (1.2MiB)
2026-09-07 01:50:35.718773 | controller | Downloading cryptography (4.3MiB)
2026-09-07 01:50:35.748682 | controller | Downloading ansible-core (2.3MiB)
2026-09-07 01:50:35.867402 | controller |  Downloading cryptography
2026-09-07 01:50:35.914486 | controller |  Downloading pygments
2026-09-07 01:50:36.079994 | controller |  Downloading ansible-core
2026-09-07 01:50:36.143091 | controller | Installed 36 packages in 62ms
2026-09-07 01:50:36.774442 | controller | INFO     Collection 'vexxhost.kubernetes' detected.
2026-09-07 01:50:36.774519 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-09-07 01:50:37.695922 | controller | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-09-07 01:50:37.695969 | controller | INFO     default scenario not found, disabling shared state.
2026-09-07 01:50:37.696137 | controller | INFO     [cluster-api > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-07 01:50:37.696177 | controller | INFO     [cluster-api > prerun] Performing prerun with role_name_check=0...
2026-09-07 02:03:42.999544 | controller | WARNING  Retrying execution failure 250 of: ansible-galaxy collection install -vvv vexxhost.containers:>=1.6.0
2026-09-07 02:03:42.999605 | controller | ERROR    Command ansible-galaxy collection install -vvv vexxhost.containers:>=1.6.0, returned 250 code:
2026-09-07 02:03:42.999706 | controller | Starting galaxy collection install process
2026-09-07 02:03:42.999713 | controller | Process install dependency map
2026-09-07 02:03:42.999717 | controller |
2026-09-07 02:03:42.999721 | controller | ansible-galaxy
2026-09-07 02:03:42.999724 | controller |   config file = None
2026-09-07 02:03:42.999728 | controller |   configured module search path =
2026-09-07 02:03:42.999732 | controller |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible
2026-09-07 02:03:42.999742 | controller |   ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/local/lib/python3.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages
2026-09-07 02:03:42.999746 | controller |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy
2026-09-07 02:03:42.999752 | controller |   python version = 3.13.5 (main, Jul 15 2026, 20:25:40)  (/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/python)
2026-09-07 02:03:42.999756 | controller |   jinja version = 3.1.6
2026-09-07 02:03:42.999759 | controller |   pyyaml version = 6.0.3 (with libyaml v0.2.5)
2026-09-07 02:03:42.999763 | controller | No config file found; using defaults
2026-09-07 02:03:42.999766 | controller | Found installed collection ansible.posix:2.2.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-07 02:03:42.999769 | controller | Found installed collection community.crypto:3.4.0 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-07 02:03:42.999773 | controller | Found installed collection kubernetes.core:6.5.0 at '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-07 02:03:42.999776 | controller | Found installed collection community.library_inventory_filtering_v1:1.1.5 at '/home/zuul/.ansible/collections/ansible_collections/community/library_inventory_filtering_v1'
2026-09-07 02:03:42.999780 | controller | Found installed collection community.general:13.3.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-07 02:03:42.999786 | controller | Opened /home/zuul/.ansible/galaxy_token
2026-09-07 02:03:42.999790 | controller | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an unexpected error when getting available versions of collection vexxhost.containers: 'results'
2026-09-07 02:03:42.999794 | controller | : Unexpected Exception, this is probably a bug: 'results'
2026-09-07 02:03:42.999807 | controller |
2026-09-07 02:03:42.999812 | controller | Traceback (most recent call last):
2026-09-07 02:03:42.999816 | 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-09-07 02:03:42.999820 | controller |     exit_code = cli.run()
2026-09-07 02:03:42.999824 | 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-09-07 02:03:42.999827 | controller |     return context.CLIARGS()
2026-09-07 02:03:42.999830 | controller |            ~~~~~~~~~~~~~~~~~~~~~~~^^
2026-09-07 02:03:42.999833 | 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-09-07 02:03:42.999836 | controller |     return wrapped_method(*args, **kwargs)
2026-09-07 02:03:42.999839 | 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-09-07 02:03:42.999842 | controller |     self._execute_install_collection(
2026-09-07 02:03:42.999845 | controller |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-07 02:03:42.999848 | controller |         collection_requirements, collection_path,
2026-09-07 02:03:42.999869 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 02:03:42.999873 | controller |         artifacts_manager=artifacts_manager,
2026-09-07 02:03:42.999876 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 02:03:42.999879 | controller |     )
2026-09-07 02:03:42.999883 | controller |     ^
2026-09-07 02:03:42.999886 | 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-09-07 02:03:42.999889 | controller |     install_collections(
2026-09-07 02:03:42.999893 | controller |     ~~~~~~~~~~~~~~~~~~~^
2026-09-07 02:03:42.999896 | controller |         requirements, output_path, self.api_servers, ignore_errors,
2026-09-07 02:03:42.999899 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 02:03:42.999902 | controller |     ...<5 lines>...
2026-09-07 02:03:42.999905 | controller |         read_requirement_paths=read_req_paths,
2026-09-07 02:03:42.999909 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 02:03:42.999913 | controller |     )
2026-09-07 02:03:42.999917 | controller |     ^
2026-09-07 02:03:42.999921 | 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-09-07 02:03:42.999925 | controller |     dependency_map = _resolve_depenency_map(
2026-09-07 02:03:42.999929 | controller |         collections,
2026-09-07 02:03:42.999932 | controller |     ...<7 lines>...
2026-09-07 02:03:42.999935 | controller |         offline=offline,
2026-09-07 02:03:42.999938 | controller |     )
2026-09-07 02:03:42.999946 | 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-09-07 02:03:42.999949 | controller |     collection_dep_resolver.resolve(
2026-09-07 02:03:42.999960 | controller |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-07 02:03:42.999963 | controller |         requested_requirements,
2026-09-07 02:03:42.999966 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 02:03:42.999970 | controller |         max_rounds=2000000,  # NOTE: same constant pip uses
2026-09-07 02:03:42.999973 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 02:03:42.999976 | controller |     ).mapping,
2026-09-07 02:03:42.999979 | controller |     ^
2026-09-07 02:03:42.999982 | 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-09-07 02:03:42.999986 | controller |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-09-07 02:03:42.999989 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 434, in resolve
2026-09-07 02:03:42.999995 | controller |     self._add_to_criteria(self.state.criteria, r, parent=None)
2026-09-07 02:03:42.999999 | controller |     ~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 02:03:43.000003 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 125, in _add_to_criteria
2026-09-07 02:03:43.000007 | controller |     matches = self._p.find_matches(
2026-09-07 02:03:43.000011 | controller |         identifier=identifier,
2026-09-07 02:03:43.000015 | controller |     ...<9 lines>...
2026-09-07 02:03:43.000019 | controller |         ),
2026-09-07 02:03:43.000022 | controller |     )
2026-09-07 02:03:43.000025 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 192, in find_matches
2026-09-07 02:03:43.000029 | controller |     match for match in self._find_matches(list(requirements))
2026-09-07 02:03:43.000033 | controller |                        ~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 02:03:43.000037 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 219, in _find_matches
2026-09-07 02:03:43.000052 | controller |     else self._api_proxy.get_collection_versions(first_req)
2026-09-07 02:03:43.000056 | controller |          ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^
2026-09-07 02:03:43.000059 | 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 103, in get_collection_versions
2026-09-07 02:03:43.000062 | controller |     return set(
2026-09-07 02:03:43.000065 | controller |         (version, api)
2026-09-07 02:03:43.000068 | controller |     ...<2 lines>...
2026-09-07 02:03:43.000071 | controller |         )
2026-09-07 02:03:43.000074 | controller |     )
2026-09-07 02:03:43.000082 | 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 105, in <genexpr>
2026-09-07 02:03:43.000086 | controller |     for api, version in self._get_collection_versions(
2026-09-07 02:03:43.000089 | controller |                         ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-07 02:03:43.000092 | controller |         requirement,
2026-09-07 02:03:43.000095 | controller |         ^^^^^^^^^^^^
2026-09-07 02:03:43.000098 | controller |     )
2026-09-07 02:03:43.000101 | controller |     ^
2026-09-07 02:03:43.000104 | 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 85, in _get_collection_versions
2026-09-07 02:03:43.000107 | controller |     raise last_error
2026-09-07 02:03:43.000110 | 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 64, in _get_collection_versions
2026-09-07 02:03:43.000113 | controller |     versions = api.get_collection_versions(requirement.namespace, requirement.name)
2026-09-07 02:03:43.000117 | 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-09-07 02:03:43.000120 | controller |     return method(self, *args, **kwargs)
2026-09-07 02:03:43.000123 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 830, in get_collection_versions
2026-09-07 02:03:43.000127 | controller |     data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key)
2026-09-07 02:03:43.000130 | 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-09-07 02:03:43.000133 | controller |     return call_retryable_function()
2026-09-07 02:03:43.000136 | 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-09-07 02:03:43.000142 | controller |     res = path_cache
2026-09-07 02:03:43.000146 | controller |           ~~~~~~~~~~^^^^^^^^^^^
2026-09-07 02:03:43.000149 | controller | KeyError: 'results'
2026-09-07 02:03:43.000152 | controller |
2026-09-07 02:03:43.000155 | controller | The above exception was the direct cause of the following exception:
2026-09-07 02:03:43.000158 | controller |
2026-09-07 02:03:43.000161 | controller | Traceback (most recent call last):
2026-09-07 02:03:43.000182 | 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-09-07 02:03:43.000186 | controller |     raise AnsibleError("Unexpected Exception, this is probably a bug.") from ex
2026-09-07 02:03:43.000191 | controller | ansible.errors.AnsibleError: Unexpected Exception, this is probably a bug: 'results'
2026-09-07 02:03:43.000195 | controller |
2026-09-07 02:03:43.000198 | controller |
2026-09-07 02:03:43.002416 | controller | Traceback (most recent call last):
2026-09-07 02:03:43.006705 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/molecule", line 10, in <module>
2026-09-07 02:03:43.006715 | controller |     sys.exit(main())
2026-09-07 02:03:43.006718 | controller |              ~~~~^^
2026-09-07 02:03:43.007121 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 1485, in __call__
2026-09-07 02:03:43.007128 | controller |     return self.main(*args, **kwargs)
2026-09-07 02:03:43.007131 | controller |            ~~~~~~~~~^^^^^^^^^^^^^^^^^
2026-09-07 02:03:43.007504 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 1406, in main
2026-09-07 02:03:43.007510 | controller |     rv = self.invoke(ctx)
2026-09-07 02:03:43.007919 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 1873, in invoke
2026-09-07 02:03:43.007924 | controller |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-09-07 02:03:43.007927 | controller |                            ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^
2026-09-07 02:03:43.008339 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 1269, in invoke
2026-09-07 02:03:43.008343 | controller |     return ctx.invoke(self.callback, **ctx.params)
2026-09-07 02:03:43.008346 | controller |            ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 02:03:43.008674 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/core.py", line 824, in invoke
2026-09-07 02:03:43.008678 | controller |     return callback(*args, **kwargs)
2026-09-07 02:03:43.009000 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/click_cfg.py", line 583, in wrapper
2026-09-07 02:03:43.009004 | controller |     return func(*args, **kwargs)
2026-09-07 02:03:43.009343 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/click/decorators.py", line 34, in new_func
2026-09-07 02:03:43.009347 | controller |     return f(get_current_context(), *args, **kwargs)
2026-09-07 02:03:43.009690 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/click_cfg.py", line 418, in wrapper
2026-09-07 02:03:43.009695 | controller |     return func(ctx)
2026-09-07 02:03:43.010039 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/command/test.py", line 81, in test
2026-09-07 02:03:43.010046 | controller |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-09-07 02:03:43.010050 | controller |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 02:03:43.010473 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/command/base.py", line 169, in execute_cmdline_scenarios
2026-09-07 02:03:43.010481 | controller |     _run_scenarios(scenarios, command_args, default_config)
2026-09-07 02:03:43.010488 | controller |     ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 02:03:43.010902 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/molecule/command/base.py", line 240, in _run_scenarios
2026-09-07 02:03:43.010907 | controller |     scenario.config.runtime.prepare_environment(
2026-09-07 02:03:43.010910 | controller |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-07 02:03:43.010914 | controller |         install_local=True,
2026-09-07 02:03:43.010917 | controller |         ^^^^^^^^^^^^^^^^^^^
2026-09-07 02:03:43.010920 | controller |         role_name_check=role_name_check,
2026-09-07 02:03:43.010923 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 02:03:43.010926 | controller |     )
2026-09-07 02:03:43.010930 | controller |     ^
2026-09-07 02:03:43.011342 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible_compat/runtime.py", line 752, in prepare_environment
2026-09-07 02:03:43.011346 | controller |     self.install_collection(
2026-09-07 02:03:43.011349 | controller |     ~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-07 02:03:43.011352 | controller |         f"{name}{',' if is_url(name) else ':'}{required_version}",
2026-09-07 02:03:43.011356 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 02:03:43.011359 | controller |         destination=destination,
2026-09-07 02:03:43.011362 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 02:03:43.011365 | controller |     )
2026-09-07 02:03:43.011368 | controller |     ^
2026-09-07 02:03:43.011589 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible_compat/runtime.py", line 609, in install_collection
2026-09-07 02:03:43.011594 | controller |     raise InvalidPrerequisiteError(msg)
2026-09-07 02:03:43.017109 | controller | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv vexxhost.containers:>=1.6.0, returned 250 code:
2026-09-07 02:03:43.017115 | controller | Starting galaxy collection install process
2026-09-07 02:03:43.017118 | controller | Process install dependency map
2026-09-07 02:03:43.017121 | controller |
2026-09-07 02:03:43.017124 | controller | ansible-galaxy [core 2.20.1]
2026-09-07 02:03:43.017127 | controller |   config file = None
2026-09-07 02:03:43.017131 | controller |   configured module search path = ['/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/plugins/modules', '/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules']
2026-09-07 02:03:43.017139 | controller |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible
2026-09-07 02:03:43.017146 | controller |   ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/local/lib/python3.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages
2026-09-07 02:03:43.017150 | controller |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/ansible-galaxy
2026-09-07 02:03:43.017153 | controller |   python version = 3.13.5 (main, Jul 15 2026, 20:25:40) [GCC 14.2.0] (/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/bin/python)
2026-09-07 02:03:43.017156 | controller |   jinja version = 3.1.6
2026-09-07 02:03:43.017159 | controller |   pyyaml version = 6.0.3 (with libyaml v0.2.5)
2026-09-07 02:03:43.017179 | controller | No config file found; using defaults
2026-09-07 02:03:43.017182 | controller | Found installed collection ansible.posix:2.2.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-07 02:03:43.017185 | controller | Found installed collection community.crypto:3.4.0 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-07 02:03:43.017188 | controller | Found installed collection kubernetes.core:6.5.0 at '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-07 02:03:43.017192 | controller | Found installed collection community.library_inventory_filtering_v1:1.1.5 at '/home/zuul/.ansible/collections/ansible_collections/community/library_inventory_filtering_v1'
2026-09-07 02:03:43.017197 | controller | Found installed collection community.general:13.3.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-07 02:03:43.017201 | controller | Opened /home/zuul/.ansible/galaxy_token
2026-09-07 02:03:43.017204 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an unexpected error when getting available versions of collection vexxhost.containers: 'results'
2026-09-07 02:03:43.017207 | controller | [ERROR]: Unexpected Exception, this is probably a bug: 'results'
2026-09-07 02:03:43.017210 | controller |
2026-09-07 02:03:43.017213 | controller | Traceback (most recent call last):
2026-09-07 02:03:43.017216 | 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-09-07 02:03:43.017219 | controller |     exit_code = cli.run()
2026-09-07 02:03:43.017222 | 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-09-07 02:03:43.017225 | controller |     return context.CLIARGS['func']()
2026-09-07 02:03:43.017228 | controller |            ~~~~~~~~~~~~~~~~~~~~~~~^^
2026-09-07 02:03:43.017231 | 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-09-07 02:03:43.017234 | controller |     return wrapped_method(*args, **kwargs)
2026-09-07 02:03:43.017237 | 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-09-07 02:03:43.017240 | controller |     self._execute_install_collection(
2026-09-07 02:03:43.017243 | controller |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-07 02:03:43.017246 | controller |         collection_requirements, collection_path,
2026-09-07 02:03:43.017249 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 02:03:43.017253 | controller |         artifacts_manager=artifacts_manager,
2026-09-07 02:03:43.017255 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 02:03:43.017259 | controller |     )
2026-09-07 02:03:43.017262 | controller |     ^
2026-09-07 02:03:43.017265 | 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-09-07 02:03:43.017268 | controller |     install_collections(
2026-09-07 02:03:43.017271 | controller |     ~~~~~~~~~~~~~~~~~~~^
2026-09-07 02:03:43.017274 | controller |         requirements, output_path, self.api_servers, ignore_errors,
2026-09-07 02:03:43.017277 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 02:03:43.017280 | controller |     ...<5 lines>...
2026-09-07 02:03:43.017283 | controller |         read_requirement_paths=read_req_paths,
2026-09-07 02:03:43.017286 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 02:03:43.017289 | controller |     )
2026-09-07 02:03:43.017292 | controller |     ^
2026-09-07 02:03:43.017299 | 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-09-07 02:03:43.017303 | controller |     dependency_map = _resolve_depenency_map(
2026-09-07 02:03:43.017307 | controller |         collections,
2026-09-07 02:03:43.017311 | controller |     ...<7 lines>...
2026-09-07 02:03:43.017314 | controller |         offline=offline,
2026-09-07 02:03:43.017317 | controller |     )
2026-09-07 02:03:43.017321 | 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-09-07 02:03:43.017324 | controller |     collection_dep_resolver.resolve(
2026-09-07 02:03:43.017328 | controller |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-07 02:03:43.017332 | controller |         requested_requirements,
2026-09-07 02:03:43.017335 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 02:03:43.017338 | controller |         max_rounds=2000000,  # NOTE: same constant pip uses
2026-09-07 02:03:43.017343 | controller |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 02:03:43.017346 | controller |     ).mapping,
2026-09-07 02:03:43.017350 | controller |     ^
2026-09-07 02:03:43.017356 | 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-09-07 02:03:43.017359 | controller |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-09-07 02:03:43.017362 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 434, in resolve
2026-09-07 02:03:43.017365 | controller |     self._add_to_criteria(self.state.criteria, r, parent=None)
2026-09-07 02:03:43.017368 | controller |     ~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 02:03:43.017372 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 125, in _add_to_criteria
2026-09-07 02:03:43.017375 | controller |     matches = self._p.find_matches(
2026-09-07 02:03:43.017378 | controller |         identifier=identifier,
2026-09-07 02:03:43.017381 | controller |     ...<9 lines>...
2026-09-07 02:03:43.017384 | controller |         ),
2026-09-07 02:03:43.017387 | controller |     )
2026-09-07 02:03:43.017390 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 192, in find_matches
2026-09-07 02:03:43.017393 | controller |     match for match in self._find_matches(list(requirements[identifier]))
2026-09-07 02:03:43.017396 | controller |                        ~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 02:03:43.017399 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 219, in _find_matches
2026-09-07 02:03:43.017402 | controller |     else self._api_proxy.get_collection_versions(first_req)
2026-09-07 02:03:43.017405 | controller |          ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^
2026-09-07 02:03:43.017408 | 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 103, in get_collection_versions
2026-09-07 02:03:43.017411 | controller |     return set(
2026-09-07 02:03:43.017414 | controller |         (version, api)
2026-09-07 02:03:43.017417 | controller |     ...<2 lines>...
2026-09-07 02:03:43.017421 | controller |         )
2026-09-07 02:03:43.017424 | controller |     )
2026-09-07 02:03:43.017427 | 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 105, in <genexpr>
2026-09-07 02:03:43.017430 | controller |     for api, version in self._get_collection_versions(
2026-09-07 02:03:43.017433 | controller |                         ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-07 02:03:43.017436 | controller |         requirement,
2026-09-07 02:03:43.017439 | controller |         ^^^^^^^^^^^^
2026-09-07 02:03:43.017442 | controller |     )
2026-09-07 02:03:43.017445 | controller |     ^
2026-09-07 02:03:43.017448 | 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 85, in _get_collection_versions
2026-09-07 02:03:43.017451 | controller |     raise last_error
2026-09-07 02:03:43.017454 | 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 64, in _get_collection_versions
2026-09-07 02:03:43.017457 | controller |     versions = api.get_collection_versions(requirement.namespace, requirement.name)
2026-09-07 02:03:43.017460 | 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-09-07 02:03:43.017463 | controller |     return method(self, *args, **kwargs)
2026-09-07 02:03:43.017467 | controller |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 830, in get_collection_versions
2026-09-07 02:03:43.017472 | controller |     data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key)
2026-09-07 02:03:43.017475 | 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-09-07 02:03:43.017478 | controller |     return call_retryable_function()
2026-09-07 02:03:43.017481 | 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-09-07 02:03:43.017484 | controller |     res = path_cache['results']
2026-09-07 02:03:43.017490 | controller |           ~~~~~~~~~~^^^^^^^^^^^
2026-09-07 02:03:43.017493 | controller | KeyError: 'results'
2026-09-07 02:03:43.017496 | controller |
2026-09-07 02:03:43.017500 | controller | The above exception was the direct cause of the following exception:
2026-09-07 02:03:43.017503 | controller |
2026-09-07 02:03:43.017506 | controller | Traceback (most recent call last):
2026-09-07 02:03:43.017509 | 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-09-07 02:03:43.017512 | controller |     raise AnsibleError("Unexpected Exception, this is probably a bug.") from ex
2026-09-07 02:03:43.017515 | controller | ansible.errors.AnsibleError: Unexpected Exception, this is probably a bug: 'results'
2026-09-07 02:03:43.017518 | controller |
2026-09-07 02:03:43.017521 | controller |
2026-09-07 02:03:43.329167 | controller | ERROR
2026-09-07 02:03:43.329415 | controller | {
2026-09-07 02:03:43.329468 | controller |   "delta": "0:13:07.879095",
2026-09-07 02:03:43.329506 | controller |   "end": "2026-09-07 02:03:43.128016",
2026-09-07 02:03:43.329542 | controller |   "msg": "non-zero return code",
2026-09-07 02:03:43.329575 | controller |   "rc": 1,
2026-09-07 02:03:43.329608 | controller |   "start": "2026-09-07 01:50:35.248921"
2026-09-07 02:03:43.329640 | controller | }
failure
2026-09-07 02:03:43.337194 | 
2026-09-07 02:03:43.337299 | PLAY RECAP
2026-09-07 02:03:43.337381 | controller | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-07 02:03:43.337421 | 
2026-09-07 02:03:43.551699 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-07 02:03:43.554855 | POST-RUN START: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-09-07 02:03:44.159111 | 
2026-09-07 02:03:44.159233 | PLAY [all]
2026-09-07 02:03:44.169282 | 
2026-09-07 02:03:44.169355 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-07 02:03:44.625376 | controller | changed
2026-09-07 02:03:44.633709 | 
2026-09-07 02:03:44.633870 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-07 02:03:45.057994 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 02:03:45.058130 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 02:03:45.066593 | controller | environment: line 1: kubectl: command not found
2026-09-07 02:03:45.068913 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 02:03:45.070852 | controller | environment: line 1: kubectl: command not found
2026-09-07 02:03:45.072703 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 02:03:45.074702 | controller | environment: line 1: kubectl: command not found
2026-09-07 02:03:45.076662 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 02:03:45.080131 | controller | environment: line 1: kubectl: command not found
2026-09-07 02:03:45.083461 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 02:03:45.083482 | controller | environment: line 1: kubectl: command not found
2026-09-07 02:03:45.084418 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-07 02:03:45.173466 | controller | ok: Runtime: 0:00:00.040980
2026-09-07 02:03:45.181216 | 
2026-09-07 02:03:45.181340 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-07 02:03:45.436404 | controller | changed
2026-09-07 02:03:45.443627 | 
2026-09-07 02:03:45.443768 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-07 02:03:45.741286 | controller | environment: line 5: kubectl: command not found
2026-09-07 02:03:45.742764 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 02:03:45.743391 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 02:03:45.743408 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-07 02:03:45.982220 | controller | ok: Runtime: 0:00:00.013923
2026-09-07 02:03:45.991765 | 
2026-09-07 02:03:45.991898 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-07 02:03:46.664392 | controller | changed:
2026-09-07 02:03:46.664662 | controller | created directory /var/lib/zuul/builds/1171b07af41b449bbd19b6fa493806d1/work/logs/controller
2026-09-07 02:03:46.664726 | controller | cd+++++++++ objects/
2026-09-07 02:03:46.664772 | controller | cd+++++++++ objects/cluster/
2026-09-07 02:03:46.664818 | controller | cd+++++++++ objects/namespaced/
2026-09-07 02:03:46.677705 | 
2026-09-07 02:03:46.677859 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-07 02:03:46.953761 | controller | changed
2026-09-07 02:03:46.961034 | 
2026-09-07 02:03:46.961157 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-07 02:03:47.205001 | controller | changed
2026-09-07 02:03:47.212228 | 
2026-09-07 02:03:47.212355 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-07 02:03:47.506709 | controller | environment: line 3: kubectl: command not found
2026-09-07 02:03:47.749665 | controller | ok: Runtime: 0:00:00.012580
2026-09-07 02:03:47.757678 | 
2026-09-07 02:03:47.757803 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-07 02:03:48.300727 | controller | changed:
2026-09-07 02:03:48.300991 | controller | cd+++++++++ pod-logs/
2026-09-07 02:03:48.301051 | controller | cd+++++++++ pod-logs/failed-pods/
2026-09-07 02:03:48.314049 | 
2026-09-07 02:03:48.314167 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-07 02:03:48.587375 | controller | changed: "values"
2026-09-07 02:03:48.823245 | controller | changed: "releases"
2026-09-07 02:03:48.832900 | 
2026-09-07 02:03:48.833085 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-07 02:03:49.126779 | controller | /bin/bash: line 3: kubectl: command not found
2026-09-07 02:03:49.371404 | controller | ok: Runtime: 0:00:00.009598
2026-09-07 02:03:49.379009 | 
2026-09-07 02:03:49.379200 | TASK [helm-release-status : Downloads logs to executor]
2026-09-07 02:03:49.917143 | controller | changed:
2026-09-07 02:03:49.917401 | controller | cd+++++++++ helm/
2026-09-07 02:03:49.917457 | controller | cd+++++++++ helm/releases/
2026-09-07 02:03:49.917508 | controller | cd+++++++++ helm/values/
2026-09-07 02:03:49.926269 | 
2026-09-07 02:03:49.926368 | PLAY RECAP
2026-09-07 02:03:49.926454 | controller | ok: 12 changed: 12 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 02:03:49.926499 | 
2026-09-07 02:03:50.043466 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/ansible-collection-kubernetes/test-playbooks/molecule/post.yml@main]
2026-09-07 02:03:50.045058 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-07 02:03:50.605045 | 
2026-09-07 02:03:50.605260 | PLAY [all]
2026-09-07 02:03:50.614527 | 
2026-09-07 02:03:50.614610 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-07 02:03:50.668357 | controller | skipping: Conditional result was False
2026-09-07 02:03:50.672206 | 
2026-09-07 02:03:50.672276 | TASK [fetch-output : Set log path for single node]
2026-09-07 02:03:50.723328 | controller | ok
2026-09-07 02:03:50.730825 | 
2026-09-07 02:03:50.730991 | LOOP [fetch-output : Ensure local output dirs]
2026-09-07 02:03:51.125024 | controller -> localhost | ok: "/var/lib/zuul/builds/1171b07af41b449bbd19b6fa493806d1/work/logs"
2026-09-07 02:03:51.345768 | controller -> localhost | changed: "/var/lib/zuul/builds/1171b07af41b449bbd19b6fa493806d1/work/artifacts"
2026-09-07 02:03:51.602509 | controller -> localhost | changed: "/var/lib/zuul/builds/1171b07af41b449bbd19b6fa493806d1/work/docs"
2026-09-07 02:03:51.623909 | 
2026-09-07 02:03:51.624178 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-07 02:03:52.339160 | controller | changed: .d..t...... ./
2026-09-07 02:03:52.339480 | controller | changed: All items complete
2026-09-07 02:03:52.339534 | 
2026-09-07 02:03:52.865367 | controller | changed: .d..t...... ./
2026-09-07 02:03:53.392812 | controller | changed: .d..t...... ./
2026-09-07 02:03:53.413411 | 
2026-09-07 02:03:53.413606 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-07 02:03:53.879435 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006412
2026-09-07 02:03:54.117326 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008154
2026-09-07 02:03:54.138285 | 
2026-09-07 02:03:54.138408 | PLAY [all]
2026-09-07 02:03:54.147524 | 
2026-09-07 02:03:54.147647 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-07 02:03:54.625706 | controller | changed
2026-09-07 02:03:54.634129 | 
2026-09-07 02:03:54.634229 | PLAY RECAP
2026-09-07 02:03:54.634309 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-07 02:03:54.634351 | 
2026-09-07 02:03:54.748770 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-07 02:03:54.751794 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-07 02:03:55.303991 | 
2026-09-07 02:03:55.304162 | PLAY [localhost]
2026-09-07 02:03:55.314459 | 
2026-09-07 02:03:55.314595 | TASK [Generate Zuul manifest]
2026-09-07 02:03:55.331334 | localhost | ok
2026-09-07 02:03:55.345689 | 
2026-09-07 02:03:55.345773 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-07 02:03:55.800176 | localhost | changed
2026-09-07 02:03:55.820367 | 
2026-09-07 02:03:55.820564 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-07 02:03:55.862556 | localhost | ok
2026-09-07 02:03:55.877581 | 
2026-09-07 02:03:55.877712 | TASK [Upload logs]
2026-09-07 02:03:55.899802 | localhost | ok
2026-09-07 02:03:55.975562 | 
2026-09-07 02:03:55.975711 | TASK [Set zuul-log-path fact]
2026-09-07 02:03:55.993728 | localhost | ok
2026-09-07 02:03:56.004126 | 
2026-09-07 02:03:56.004203 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-07 02:03:56.042653 | localhost | ok
2026-09-07 02:03:56.051924 | 
2026-09-07 02:03:56.052045 | TASK [upload-logs : Create log directories]
2026-09-07 02:03:56.457991 | localhost | changed
2026-09-07 02:03:56.466773 | 
2026-09-07 02:03:56.466901 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-07 02:03:56.873939 | localhost -> localhost | ok: Runtime: 0:00:00.004072
2026-09-07 02:03:56.883606 | 
2026-09-07 02:03:56.883739 | TASK [upload-logs : Upload logs to log server]
2026-09-07 02:03:57.384591 | localhost | Output suppressed because no_log was given
2026-09-07 02:03:57.391272 | 
2026-09-07 02:03:57.391389 | LOOP [upload-logs : Compress console log and json output]
2026-09-07 02:03:57.445391 | localhost | skipping: Conditional result was False
2026-09-07 02:03:57.499094 | localhost | skipping: Conditional result was False
2026-09-07 02:03:57.515334 | 
2026-09-07 02:03:57.515475 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-07 02:03:57.577473 | localhost | skipping: Conditional result was False
2026-09-07 02:03:57.577834 | 
2026-09-07 02:03:57.582698 | localhost | skipping: Conditional result was False
2026-09-07 02:03:57.599920 | 
2026-09-07 02:03:57.600094 | LOOP [upload-logs : Upload console log and json output]