2026-09-17 00:57:45.389178 | Job console starting
2026-09-17 00:57:45.404367 | Updating git repos
2026-09-17 00:57:45.438938 | Cloning repos into workspace
2026-09-17 00:57:45.491194 | Restoring repo states
2026-09-17 00:57:45.501031 | Merging changes
2026-09-17 00:57:46.087879 | Checking out repos
2026-09-17 00:57:46.119220 | Preparing playbooks
2026-09-17 00:57:47.255925 | Running Ansible setup
2026-09-17 00:57:50.782818 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-17 00:57:51.413372 | 
2026-09-17 00:57:51.413539 | PLAY [localhost]
2026-09-17 00:57:51.421329 | 
2026-09-17 00:57:51.421413 | TASK [Gathering Facts]
2026-09-17 00:57:52.332131 | localhost | ok
2026-09-17 00:57:52.338407 | 
2026-09-17 00:57:52.338483 | TASK [Setup log path fact]
2026-09-17 00:57:52.366099 | localhost | ok
2026-09-17 00:57:52.375498 | 
2026-09-17 00:57:52.375570 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-17 00:57:52.412475 | localhost | ok
2026-09-17 00:57:52.418123 | 
2026-09-17 00:57:52.418189 | TASK [emit-job-header : Print job information]
2026-09-17 00:57:52.471317 | # Job Information
2026-09-17 00:57:52.471608 | Ansible Version: 2.16.18
2026-09-17 00:57:52.471672 | Job: ansible-collection-containers-molecule-docker-ubuntu-focal
2026-09-17 00:57:52.471715 | Pipeline: check
2026-09-17 00:57:52.471754 | Executor: 2d72f0692154
2026-09-17 00:57:52.471792 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/149
2026-09-17 00:57:52.471835 | Event ID: fdcba780-b231-11f1-9399-d3d30f48488d
2026-09-17 00:57:52.477728 | 
2026-09-17 00:57:52.477863 | LOOP [emit-job-header : Print node information]
2026-09-17 00:57:52.585521 | localhost | ok:
2026-09-17 00:57:52.586462 | localhost | # Node Information
2026-09-17 00:57:52.586530 | localhost | Inventory Hostname: ubuntu-focal
2026-09-17 00:57:52.586576 | localhost | Hostname: np0000192978
2026-09-17 00:57:52.586617 | localhost | Username: zuul
2026-09-17 00:57:52.586663 | localhost | Distro: Ubuntu 20.04
2026-09-17 00:57:52.586704 | localhost | Provider: yul1
2026-09-17 00:57:52.586743 | localhost | Region: ca-ymq-1
2026-09-17 00:57:52.586781 | localhost | Label: ubuntu-focal
2026-09-17 00:57:52.586818 | localhost | Product Name: OpenStack Nova
2026-09-17 00:57:52.586855 | localhost | Interface IP: 162.253.55.193
2026-09-17 00:57:52.606759 | 
2026-09-17 00:57:52.606918 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-17 00:57:53.077609 | localhost -> localhost | changed
2026-09-17 00:57:53.087589 | 
2026-09-17 00:57:53.087736 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-17 00:57:53.918805 | localhost -> localhost | changed
2026-09-17 00:57:53.933798 | 
2026-09-17 00:57:53.933914 | PLAY [all]
2026-09-17 00:57:53.949875 | 
2026-09-17 00:57:53.950011 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-17 00:57:54.217905 | ubuntu-focal -> localhost | ok
2026-09-17 00:57:54.228233 | 
2026-09-17 00:57:54.228409 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-17 00:57:54.261547 | ubuntu-focal | ok
2026-09-17 00:57:54.290985 | ubuntu-focal | included: /var/lib/zuul/builds/e670ab512cb54cc8b8c798d30a89a129/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-17 00:57:54.299910 | 
2026-09-17 00:57:54.300007 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-17 00:57:55.385960 | ubuntu-focal -> localhost | Generating public/private rsa key pair.
2026-09-17 00:57:55.386215 | ubuntu-focal -> localhost | Your identification has been saved in /var/lib/zuul/builds/e670ab512cb54cc8b8c798d30a89a129/work/e670ab512cb54cc8b8c798d30a89a129_id_rsa
2026-09-17 00:57:55.386295 | ubuntu-focal -> localhost | Your public key has been saved in /var/lib/zuul/builds/e670ab512cb54cc8b8c798d30a89a129/work/e670ab512cb54cc8b8c798d30a89a129_id_rsa.pub
2026-09-17 00:57:55.386341 | ubuntu-focal -> localhost | The key fingerprint is:
2026-09-17 00:57:55.386383 | ubuntu-focal -> localhost | SHA256:MEXoEr77iWVdfmLefrHKyc6dcOAcbaG5YQsZ9BDCGU8 zuul-build-sshkey
2026-09-17 00:57:55.386443 | ubuntu-focal -> localhost | The key's randomart image is:
2026-09-17 00:57:55.386483 | ubuntu-focal -> localhost | +---[RSA 3072]----+
2026-09-17 00:57:55.386532 | ubuntu-focal -> localhost | |       +=oE.     |
2026-09-17 00:57:55.386573 | ubuntu-focal -> localhost | |    . ..o= o     |
2026-09-17 00:57:55.386817 | ubuntu-focal -> localhost | |   . oo   o . .  |
2026-09-17 00:57:55.386870 | ubuntu-focal -> localhost | |    o .o   o + . |
2026-09-17 00:57:55.386910 | ubuntu-focal -> localhost | |     o  S + B o  |
2026-09-17 00:57:55.386948 | ubuntu-focal -> localhost | |    .  . o = B.  |
2026-09-17 00:57:55.386986 | ubuntu-focal -> localhost | |     .o . + B .o |
2026-09-17 00:57:55.387023 | ubuntu-focal -> localhost | |    .+ . o B =o. |
2026-09-17 00:57:55.387066 | ubuntu-focal -> localhost | |    ..o   .oXoo  |
2026-09-17 00:57:55.387104 | ubuntu-focal -> localhost | +----[SHA256]-----+
2026-09-17 00:57:55.387193 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.643954
2026-09-17 00:57:55.396724 | 
2026-09-17 00:57:55.396848 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-17 00:57:55.430524 | ubuntu-focal | ok
2026-09-17 00:57:55.446157 | ubuntu-focal | included: /var/lib/zuul/builds/e670ab512cb54cc8b8c798d30a89a129/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-17 00:57:55.459785 | 
2026-09-17 00:57:55.459888 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-17 00:57:55.484341 | ubuntu-focal | skipping: Conditional result was False
2026-09-17 00:57:55.496408 | 
2026-09-17 00:57:55.496668 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-17 00:57:55.972605 | ubuntu-focal | changed
2026-09-17 00:57:55.981791 | 
2026-09-17 00:57:55.981916 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-17 00:57:56.191123 | ubuntu-focal | ok
2026-09-17 00:57:56.205658 | 
2026-09-17 00:57:56.205794 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-17 00:57:56.698793 | ubuntu-focal | changed
2026-09-17 00:57:56.707955 | 
2026-09-17 00:57:56.708083 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-17 00:57:57.197326 | ubuntu-focal | changed
2026-09-17 00:57:57.207085 | 
2026-09-17 00:57:57.207247 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-17 00:57:57.233058 | ubuntu-focal | skipping: Conditional result was False
2026-09-17 00:57:57.243258 | 
2026-09-17 00:57:57.243414 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-17 00:57:57.674453 | ubuntu-focal -> localhost | changed
2026-09-17 00:57:57.695800 | 
2026-09-17 00:57:57.695938 | TASK [add-build-sshkey : Add back temp key]
2026-09-17 00:57:58.034313 | ubuntu-focal -> localhost | Identity added: /var/lib/zuul/builds/e670ab512cb54cc8b8c798d30a89a129/work/e670ab512cb54cc8b8c798d30a89a129_id_rsa (zuul-build-sshkey)
2026-09-17 00:57:58.034595 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.016887
2026-09-17 00:57:58.043881 | 
2026-09-17 00:57:58.043998 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-17 00:57:58.365743 | ubuntu-focal | ok
2026-09-17 00:57:58.374365 | 
2026-09-17 00:57:58.374495 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-17 00:57:58.410132 | ubuntu-focal | skipping: Conditional result was False
2026-09-17 00:57:58.430713 | 
2026-09-17 00:57:58.430867 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-17 00:57:58.780830 | ubuntu-focal | ok
2026-09-17 00:57:58.792116 | 
2026-09-17 00:57:58.792204 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-17 00:57:59.718865 | ubuntu-focal | Output suppressed because no_log was given
2026-09-17 00:57:59.738953 | 
2026-09-17 00:57:59.739083 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-17 00:58:00.007983 | ubuntu-focal | ok: "logs"
2026-09-17 00:58:00.008230 | ubuntu-focal | ok: All items complete
2026-09-17 00:58:00.008257 | 
2026-09-17 00:58:00.108163 | ubuntu-focal | ok: "artifacts"
2026-09-17 00:58:00.282570 | ubuntu-focal | ok: "docs"
2026-09-17 00:58:00.303582 | 
2026-09-17 00:58:00.303795 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-17 00:58:00.544335 | ubuntu-focal | changed: "logs"
2026-09-17 00:58:00.721886 | ubuntu-focal | changed: "artifacts"
2026-09-17 00:58:00.868685 | ubuntu-focal | changed: "docs"
2026-09-17 00:58:00.881444 | 
2026-09-17 00:58:00.881546 | PLAY RECAP
2026-09-17 00:58:00.881589 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-17 00:58:00.881618 | ubuntu-focal | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-17 00:58:00.881638 | 
2026-09-17 00:58:00.983389 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-17 00:58:00.986911 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-17 00:58:01.649213 | 
2026-09-17 00:58:01.649401 | PLAY [all]
2026-09-17 00:58:01.662929 | 
2026-09-17 00:58:01.663040 | TASK [setup-uv : Extract archive]
2026-09-17 00:58:03.953417 | ubuntu-focal | changed
2026-09-17 00:58:03.964308 | 
2026-09-17 00:58:03.964486 | TASK [setup-uv : Print version]
2026-09-17 00:58:04.422271 | ubuntu-focal | uv 0.8.13
2026-09-17 00:58:04.517518 | ubuntu-focal | ok: Runtime: 0:00:00.009198
2026-09-17 00:58:04.534366 | 
2026-09-17 00:58:04.534499 | TASK [Install Ansible collection siblings]
2026-09-17 00:58:04.938975 | ubuntu-focal | ok:
2026-09-17 00:58:04.939146 | ubuntu-focal | Skipping ansible.posix: no checked-out sibling
2026-09-17 00:58:04.939171 | ubuntu-focal | Skipping community.general: no checked-out sibling
2026-09-17 00:58:04.940677 | 
2026-09-17 00:58:04.940720 | PLAY RECAP
2026-09-17 00:58:04.940761 | ubuntu-focal | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-17 00:58:04.940780 | 
2026-09-17 00:58:05.048812 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-17 00:58:05.050046 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-17 00:58:05.607924 | 
2026-09-17 00:58:05.608081 | PLAY [all]
2026-09-17 00:58:05.618924 | 
2026-09-17 00:58:05.618996 | LOOP [Check inventory directories]
2026-09-17 00:58:05.965253 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers"
2026-09-17 00:58:06.109490 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers/molecule/docker/inventory"
2026-09-17 00:58:06.276810 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/docker/inventory"
2026-09-17 00:58:06.286913 | 
2026-09-17 00:58:06.287144 | LOOP [Copy inventory file for Zuul]
2026-09-17 00:58:07.118227 | ubuntu-focal | changed:
2026-09-17 00:58:07.118517 | ubuntu-focal | {
2026-09-17 00:58:07.118571 | ubuntu-focal |   "ansible_loop_var": "item",
2026-09-17 00:58:07.118617 | ubuntu-focal |   "changed": false,
2026-09-17 00:58:07.118758 | ubuntu-focal |   "failed": false,
2026-09-17 00:58:07.118804 | ubuntu-focal |   "invocation": {
2026-09-17 00:58:07.118841 | ubuntu-focal |     "module_args": {
2026-09-17 00:58:07.118877 | ubuntu-focal |       "checksum_algorithm": "sha1",
2026-09-17 00:58:07.118910 | ubuntu-focal |       "follow": true,
2026-09-17 00:58:07.118944 | ubuntu-focal |       "get_attributes": true,
2026-09-17 00:58:07.118980 | ubuntu-focal |       "get_checksum": true,
2026-09-17 00:58:07.119015 | ubuntu-focal |       "get_mime": true,
2026-09-17 00:58:07.119048 | ubuntu-focal |       "path": "src/github.com/vexxhost/ansible-collection-containers"
2026-09-17 00:58:07.119082 | ubuntu-focal |     }
2026-09-17 00:58:07.119115 | ubuntu-focal |   },
2026-09-17 00:58:07.119147 | ubuntu-focal |   "item": "src/github.com/vexxhost/ansible-collection-containers",
2026-09-17 00:58:07.119193 | ubuntu-focal |   "stat": {
2026-09-17 00:58:07.119227 | ubuntu-focal |     "atime": 1789606679.627723,
2026-09-17 00:58:07.119260 | ubuntu-focal |     "attr_flags": "e",
2026-09-17 00:58:07.119314 | ubuntu-focal |     "attributes": [
2026-09-17 00:58:07.119347 | ubuntu-focal |       "extents"
2026-09-17 00:58:07.119380 | ubuntu-focal |     ],
2026-09-17 00:58:07.119412 | ubuntu-focal |     "block_size": 4096,
2026-09-17 00:58:07.119444 | ubuntu-focal |     "blocks": 8,
2026-09-17 00:58:07.119477 | ubuntu-focal |     "charset": "binary",
2026-09-17 00:58:07.119512 | ubuntu-focal |     "ctime": 1789606679.627723,
2026-09-17 00:58:07.119545 | ubuntu-focal |     "dev": 64513,
2026-09-17 00:58:07.119579 | ubuntu-focal |     "device_type": 0,
2026-09-17 00:58:07.119611 | ubuntu-focal |     "executable": true,
2026-09-17 00:58:07.119643 | ubuntu-focal |     "exists": true,
2026-09-17 00:58:07.119675 | ubuntu-focal |     "gid": 1000,
2026-09-17 00:58:07.119707 | ubuntu-focal |     "gr_name": "zuul",
2026-09-17 00:58:07.119739 | ubuntu-focal |     "inode": 1035293,
2026-09-17 00:58:07.119771 | ubuntu-focal |     "isblk": false,
2026-09-17 00:58:07.119804 | ubuntu-focal |     "ischr": false,
2026-09-17 00:58:07.119836 | ubuntu-focal |     "isdir": true,
2026-09-17 00:58:07.119868 | ubuntu-focal |     "isfifo": false,
2026-09-17 00:58:07.119899 | ubuntu-focal |     "isgid": false,
2026-09-17 00:58:07.119931 | ubuntu-focal |     "islnk": false,
2026-09-17 00:58:07.119963 | ubuntu-focal |     "isreg": false,
2026-09-17 00:58:07.119993 | ubuntu-focal |     "issock": false,
2026-09-17 00:58:07.120025 | ubuntu-focal |     "isuid": false,
2026-09-17 00:58:07.120056 | ubuntu-focal |     "mimetype": "inode/directory",
2026-09-17 00:58:07.120087 | ubuntu-focal |     "mode": "0755",
2026-09-17 00:58:07.120118 | ubuntu-focal |     "mtime": 1789606665.5198069,
2026-09-17 00:58:07.120149 | ubuntu-focal |     "nlink": 11,
2026-09-17 00:58:07.120181 | ubuntu-focal |     "path": "src/github.com/vexxhost/ansible-collection-containers",
2026-09-17 00:58:07.120213 | ubuntu-focal |     "pw_name": "zuul",
2026-09-17 00:58:07.120251 | ubuntu-focal |     "readable": true,
2026-09-17 00:58:07.120308 | ubuntu-focal |     "rgrp": true,
2026-09-17 00:58:07.120342 | ubuntu-focal |     "roth": true,
2026-09-17 00:58:07.120375 | ubuntu-focal |     "rusr": true,
2026-09-17 00:58:07.120407 | ubuntu-focal |     "size": 4096,
2026-09-17 00:58:07.120440 | ubuntu-focal |     "uid": 1000,
2026-09-17 00:58:07.120471 | ubuntu-focal |     "version": "149997371",
2026-09-17 00:58:07.120541 | ubuntu-focal |     "wgrp": false,
2026-09-17 00:58:07.120581 | ubuntu-focal |     "woth": false,
2026-09-17 00:58:07.120610 | ubuntu-focal |     "writeable": true,
2026-09-17 00:58:07.120633 | ubuntu-focal |     "wusr": true,
2026-09-17 00:58:07.120653 | ubuntu-focal |     "xgrp": true,
2026-09-17 00:58:07.120672 | ubuntu-focal |     "xoth": true,
2026-09-17 00:58:07.120692 | ubuntu-focal |     "xusr": true
2026-09-17 00:58:07.120712 | ubuntu-focal |   }
2026-09-17 00:58:07.120732 | ubuntu-focal | }
2026-09-17 00:58:07.120772 | ubuntu-focal | ok: All items complete
2026-09-17 00:58:07.120797 | 
2026-09-17 00:58:07.122404 | ubuntu-focal | skipping: Conditional result was False
2026-09-17 00:58:07.123103 | ubuntu-focal | skipping: Conditional result was False
2026-09-17 00:58:07.139727 | 
2026-09-17 00:58:07.139951 | LOOP [Switch "ansible_host" to private IP]
2026-09-17 00:58:07.510651 | ubuntu-focal | changed: 1 replacements made
2026-09-17 00:58:07.513025 | ubuntu-focal | skipping: Conditional result was False
2026-09-17 00:58:07.516695 | ubuntu-focal | skipping: Conditional result was False
2026-09-17 00:58:07.532052 | 
2026-09-17 00:58:07.532253 | TASK [Run Molecule scenario]
2026-09-17 00:58:08.256507 | ubuntu-focal | Downloading cpython-3.13.7-linux-x86_64-gnu (download) (30.9MiB)
2026-09-17 00:58:09.149670 | ubuntu-focal |  Downloading cpython-3.13.7-linux-x86_64-gnu (download)
2026-09-17 00:58:09.345730 | ubuntu-focal | Using CPython 3.13.7
2026-09-17 00:58:09.345812 | ubuntu-focal | Creating virtual environment at: .venv
2026-09-17 00:58:09.961200 | ubuntu-focal | Downloading pygments (1.2MiB)
2026-09-17 00:58:09.962920 | ubuntu-focal | Downloading cryptography (4.3MiB)
2026-09-17 00:58:09.966799 | ubuntu-focal | Downloading ansible-core (2.3MiB)
2026-09-17 00:58:10.133648 | ubuntu-focal |  Downloading cryptography
2026-09-17 00:58:10.177628 | ubuntu-focal |  Downloading pygments
2026-09-17 00:58:10.341032 | ubuntu-focal |  Downloading ansible-core
2026-09-17 00:58:10.366930 | ubuntu-focal | Installed 36 packages in 25ms
2026-09-17 00:58:11.392915 | ubuntu-focal | INFO     Collection 'vexxhost.containers' detected.
2026-09-17 00:58:11.392991 | ubuntu-focal | INFO     Scenarios will be used from 'extensions/molecule'
2026-09-17 00:58:12.163926 | ubuntu-focal | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-09-17 00:58:12.164003 | ubuntu-focal | INFO     default scenario not found, disabling shared state.
2026-09-17 00:58:12.164026 | ubuntu-focal | INFO     [docker > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-17 00:58:12.164116 | ubuntu-focal | INFO     [docker > prerun] Performing prerun with role_name_check=0...
2026-09-17 00:59:13.912858 | ubuntu-focal | WARNING  Retrying execution failure 250 of: ansible-galaxy collection install -vvv ansible.posix:>=1.6.0
2026-09-17 00:59:13.912970 | ubuntu-focal | ERROR    Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 250 code:
2026-09-17 00:59:13.912978 | ubuntu-focal | Starting galaxy collection install process
2026-09-17 00:59:13.912984 | ubuntu-focal | Process install dependency map
2026-09-17 00:59:13.912990 | ubuntu-focal |
2026-09-17 00:59:13.912996 | ubuntu-focal | ansible-galaxy
2026-09-17 00:59:13.913041 | ubuntu-focal |   config file = None
2026-09-17 00:59:13.913050 | ubuntu-focal |   configured module search path =
2026-09-17 00:59:13.913056 | ubuntu-focal |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible
2026-09-17 00:59:13.913063 | ubuntu-focal |   ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages
2026-09-17 00:59:13.913074 | ubuntu-focal |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/ansible-galaxy
2026-09-17 00:59:13.913081 | ubuntu-focal |   python version = 3.13.7 (main, Aug 18 2025, 19:20:03)  (/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/python)
2026-09-17 00:59:13.913088 | ubuntu-focal |   jinja version = 3.1.6
2026-09-17 00:59:13.913095 | ubuntu-focal |   pyyaml version = 6.0.2 (with libyaml v0.2.5)
2026-09-17 00:59:13.913101 | ubuntu-focal | No config file found; using defaults
2026-09-17 00:59:13.913108 | ubuntu-focal | Found installed collection ansible._protomatter:2.19.1 at '/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/_internal/ansible_collections/ansible/_protomatter'
2026-09-17 00:59:13.913116 | ubuntu-focal | Created /home/zuul/.ansible/galaxy_token
2026-09-17 00:59:13.913122 | ubuntu-focal | : Skipping Galaxy server https://galaxy.ansible.com. Got an unexpected error when getting available versions of collection ansible.posix: 'results'
2026-09-17 00:59:13.913130 | ubuntu-focal | : Unexpected Exception, this is probably a bug: 'results'
2026-09-17 00:59:13.913136 | ubuntu-focal |
2026-09-17 00:59:13.913142 | ubuntu-focal | Traceback (most recent call last):
2026-09-17 00:59:13.913149 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/cli/__init__.py", line 660, in cli_executor
2026-09-17 00:59:13.913156 | ubuntu-focal |     exit_code = cli.run()
2026-09-17 00:59:13.913163 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 732, in run
2026-09-17 00:59:13.913169 | ubuntu-focal |     return context.CLIARGS()
2026-09-17 00:59:13.913175 | ubuntu-focal |            ~~~~~~~~~~~~~~~~~~~~~~~^^
2026-09-17 00:59:13.913181 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 100, in method_wrapper
2026-09-17 00:59:13.913207 | ubuntu-focal |     return wrapped_method(*args, **kwargs)
2026-09-17 00:59:13.913213 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 1397, in execute_install
2026-09-17 00:59:13.913218 | ubuntu-focal |     self._execute_install_collection(
2026-09-17 00:59:13.913224 | ubuntu-focal |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-17 00:59:13.913229 | ubuntu-focal |         collection_requirements, collection_path,
2026-09-17 00:59:13.913235 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-17 00:59:13.913240 | ubuntu-focal |         artifacts_manager=artifacts_manager,
2026-09-17 00:59:13.913245 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-17 00:59:13.913251 | ubuntu-focal |     )
2026-09-17 00:59:13.913256 | ubuntu-focal |     ^
2026-09-17 00:59:13.913262 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 1446, in _execute_install_collection
2026-09-17 00:59:13.913268 | ubuntu-focal |     install_collections(
2026-09-17 00:59:13.913273 | ubuntu-focal |     ~~~~~~~~~~~~~~~~~~~^
2026-09-17 00:59:13.913278 | ubuntu-focal |         requirements, output_path, self.api_servers, ignore_errors,
2026-09-17 00:59:13.913283 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-17 00:59:13.913288 | ubuntu-focal |     ...<5 lines>...
2026-09-17 00:59:13.913293 | ubuntu-focal |         read_requirement_paths=read_req_paths,
2026-09-17 00:59:13.913299 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-17 00:59:13.913304 | ubuntu-focal |     )
2026-09-17 00:59:13.913309 | ubuntu-focal |     ^
2026-09-17 00:59:13.913321 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/__init__.py", line 735, in install_collections
2026-09-17 00:59:13.913327 | ubuntu-focal |     dependency_map = _resolve_depenency_map(
2026-09-17 00:59:13.913333 | ubuntu-focal |         collections,
2026-09-17 00:59:13.913338 | ubuntu-focal |     ...<7 lines>...
2026-09-17 00:59:13.913343 | ubuntu-focal |         offline=offline,
2026-09-17 00:59:13.913349 | ubuntu-focal |     )
2026-09-17 00:59:13.913354 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/__init__.py", line 1855, in _resolve_depenency_map
2026-09-17 00:59:13.913360 | ubuntu-focal |     return collection_dep_resolver.resolve(
2026-09-17 00:59:13.913365 | ubuntu-focal |            ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-17 00:59:13.913371 | ubuntu-focal |         requested_requirements,
2026-09-17 00:59:13.913376 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^
2026-09-17 00:59:13.913382 | ubuntu-focal |         max_rounds=2000000,  # NOTE: same constant pip uses
2026-09-17 00:59:13.913387 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-17 00:59:13.913394 | ubuntu-focal |     ).mapping
2026-09-17 00:59:13.913400 | ubuntu-focal |     ^
2026-09-17 00:59:13.913406 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 596, in resolve
2026-09-17 00:59:13.913413 | ubuntu-focal |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-09-17 00:59:13.913432 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 429, in resolve
2026-09-17 00:59:13.913438 | ubuntu-focal |     self._add_to_criteria(self.state.criteria, r, parent=None)
2026-09-17 00:59:13.913444 | ubuntu-focal |     ~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-17 00:59:13.913449 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 125, in _add_to_criteria
2026-09-17 00:59:13.913455 | ubuntu-focal |     matches = self._p.find_matches(
2026-09-17 00:59:13.913460 | ubuntu-focal |         identifier=identifier,
2026-09-17 00:59:13.913466 | ubuntu-focal |     ...<9 lines>...
2026-09-17 00:59:13.913471 | ubuntu-focal |         ),
2026-09-17 00:59:13.913476 | ubuntu-focal |     )
2026-09-17 00:59:13.913482 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 477, in find_matches
2026-09-17 00:59:13.913492 | ubuntu-focal |     match for match in self._find_matches(list(requirements))
2026-09-17 00:59:13.913498 | ubuntu-focal |                        ~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-17 00:59:13.913503 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 253, in _find_matches
2026-09-17 00:59:13.913509 | ubuntu-focal |     coll_versions =  if preinstalled_candidates else self._api_proxy.get_collection_versions(first_req)  # type: t.Iterable]
2026-09-17 00:59:13.913515 | ubuntu-focal |                                                        ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^
2026-09-17 00:59:13.913520 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 103, in get_collection_versions
2026-09-17 00:59:13.913525 | ubuntu-focal |     return set(
2026-09-17 00:59:13.913531 | ubuntu-focal |         (version, api)
2026-09-17 00:59:13.913536 | ubuntu-focal |     ...<2 lines>...
2026-09-17 00:59:13.913541 | ubuntu-focal |         )
2026-09-17 00:59:13.913547 | ubuntu-focal |     )
2026-09-17 00:59:13.913552 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 105, in <genexpr>
2026-09-17 00:59:13.913557 | ubuntu-focal |     for api, version in self._get_collection_versions(
2026-09-17 00:59:13.913563 | ubuntu-focal |                         ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-17 00:59:13.913568 | ubuntu-focal |         requirement,
2026-09-17 00:59:13.913573 | ubuntu-focal |         ^^^^^^^^^^^^
2026-09-17 00:59:13.913578 | ubuntu-focal |     )
2026-09-17 00:59:13.913583 | ubuntu-focal |     ^
2026-09-17 00:59:13.913589 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 85, in _get_collection_versions
2026-09-17 00:59:13.913594 | ubuntu-focal |     raise last_error
2026-09-17 00:59:13.913599 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 64, in _get_collection_versions
2026-09-17 00:59:13.913605 | ubuntu-focal |     versions = api.get_collection_versions(requirement.namespace, requirement.name)
2026-09-17 00:59:13.913610 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 93, in wrapped
2026-09-17 00:59:13.913615 | ubuntu-focal |     data = self._call_galaxy(n_url, method='GET', error_context_msg=error_context_msg, cache=True)
2026-09-17 00:59:13.913620 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/module_utils/api.py", line 168, in run_function
2026-09-17 00:59:13.913626 | ubuntu-focal |     return call_retryable_function()
2026-09-17 00:59:13.913634 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 386, in _call_galaxy
2026-09-17 00:59:13.913640 | ubuntu-focal |     res = path_cache
2026-09-17 00:59:13.913663 | ubuntu-focal |           ~~~~~~~~~~^^^^^^^^^^^
2026-09-17 00:59:13.913669 | ubuntu-focal | KeyError: 'results'
2026-09-17 00:59:13.913674 | ubuntu-focal |
2026-09-17 00:59:13.913679 | ubuntu-focal | The above exception was the direct cause of the following exception:
2026-09-17 00:59:13.913685 | ubuntu-focal |
2026-09-17 00:59:13.913690 | ubuntu-focal | Traceback (most recent call last):
2026-09-17 00:59:13.913695 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/cli/__init__.py", line 669, in cli_executor
2026-09-17 00:59:13.913700 | ubuntu-focal |     raise AnsibleError("Unexpected Exception, this is probably a bug.") from ex
2026-09-17 00:59:13.913706 | ubuntu-focal | ansible.errors.AnsibleError: Unexpected Exception, this is probably a bug: 'results'
2026-09-17 00:59:13.913712 | ubuntu-focal |
2026-09-17 00:59:13.913717 | ubuntu-focal |
2026-09-17 00:59:13.914641 | ubuntu-focal | Traceback (most recent call last):
2026-09-17 00:59:13.916267 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/molecule", line 10, in <module>
2026-09-17 00:59:13.916282 | ubuntu-focal |     sys.exit(main())
2026-09-17 00:59:13.916288 | ubuntu-focal |              ~~~~^^
2026-09-17 00:59:13.916385 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/click/core.py", line 1442, in __call__
2026-09-17 00:59:13.916395 | ubuntu-focal |     return self.main(*args, **kwargs)
2026-09-17 00:59:13.916401 | ubuntu-focal |            ~~~~~~~~~^^^^^^^^^^^^^^^^^
2026-09-17 00:59:13.916489 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/click/core.py", line 1363, in main
2026-09-17 00:59:13.916500 | ubuntu-focal |     rv = self.invoke(ctx)
2026-09-17 00:59:13.916608 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/click/core.py", line 1830, in invoke
2026-09-17 00:59:13.916620 | ubuntu-focal |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-09-17 00:59:13.916626 | ubuntu-focal |                            ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^
2026-09-17 00:59:13.916735 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/click/core.py", line 1226, in invoke
2026-09-17 00:59:13.916746 | ubuntu-focal |     return ctx.invoke(self.callback, **ctx.params)
2026-09-17 00:59:13.916752 | ubuntu-focal |            ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-17 00:59:13.916819 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/click/core.py", line 794, in invoke
2026-09-17 00:59:13.916828 | ubuntu-focal |     return callback(*args, **kwargs)
2026-09-17 00:59:13.916921 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/molecule/click_cfg.py", line 583, in wrapper
2026-09-17 00:59:13.916930 | ubuntu-focal |     return func(*args, **kwargs)
2026-09-17 00:59:13.917005 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/click/decorators.py", line 34, in new_func
2026-09-17 00:59:13.917013 | ubuntu-focal |     return f(get_current_context(), *args, **kwargs)
2026-09-17 00:59:13.917096 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/molecule/click_cfg.py", line 418, in wrapper
2026-09-17 00:59:13.917105 | ubuntu-focal |     return func(ctx)
2026-09-17 00:59:13.917206 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/molecule/command/test.py", line 81, in test
2026-09-17 00:59:13.917214 | ubuntu-focal |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-09-17 00:59:13.917220 | ubuntu-focal |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-17 00:59:13.917315 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/molecule/command/base.py", line 169, in execute_cmdline_scenarios
2026-09-17 00:59:13.917322 | ubuntu-focal |     _run_scenarios(scenarios, command_args, default_config)
2026-09-17 00:59:13.917328 | ubuntu-focal |     ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-17 00:59:13.917514 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/molecule/command/base.py", line 240, in _run_scenarios
2026-09-17 00:59:13.917523 | ubuntu-focal |     scenario.config.runtime.prepare_environment(
2026-09-17 00:59:13.917528 | ubuntu-focal |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-17 00:59:13.917534 | ubuntu-focal |         install_local=True,
2026-09-17 00:59:13.917539 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^
2026-09-17 00:59:13.917545 | ubuntu-focal |         role_name_check=role_name_check,
2026-09-17 00:59:13.917550 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-17 00:59:13.917555 | ubuntu-focal |     )
2026-09-17 00:59:13.917561 | ubuntu-focal |     ^
2026-09-17 00:59:13.917688 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible_compat/runtime.py", line 752, in prepare_environment
2026-09-17 00:59:13.917704 | ubuntu-focal |     self.install_collection(
2026-09-17 00:59:13.917710 | ubuntu-focal |     ~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-17 00:59:13.917718 | ubuntu-focal |         f"{name}{',' if is_url(name) else ':'}{required_version}",
2026-09-17 00:59:13.917724 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-17 00:59:13.917729 | ubuntu-focal |         destination=destination,
2026-09-17 00:59:13.917734 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-17 00:59:13.917740 | ubuntu-focal |     )
2026-09-17 00:59:13.917745 | ubuntu-focal |     ^
2026-09-17 00:59:13.917784 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible_compat/runtime.py", line 609, in install_collection
2026-09-17 00:59:13.917792 | ubuntu-focal |     raise InvalidPrerequisiteError(msg)
2026-09-17 00:59:13.919568 | ubuntu-focal | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 250 code:
2026-09-17 00:59:13.919585 | ubuntu-focal | Starting galaxy collection install process
2026-09-17 00:59:13.919591 | ubuntu-focal | Process install dependency map
2026-09-17 00:59:13.919596 | ubuntu-focal |
2026-09-17 00:59:13.919602 | ubuntu-focal | ansible-galaxy [core 2.19.1]
2026-09-17 00:59:13.919607 | ubuntu-focal |   config file = None
2026-09-17 00:59:13.919613 | ubuntu-focal |   configured module search path = ['/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules']
2026-09-17 00:59:13.919618 | ubuntu-focal |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible
2026-09-17 00:59:13.919624 | ubuntu-focal |   ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages
2026-09-17 00:59:13.919630 | ubuntu-focal |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/ansible-galaxy
2026-09-17 00:59:13.919636 | ubuntu-focal |   python version = 3.13.7 (main, Aug 18 2025, 19:20:03) [Clang 20.1.4 ] (/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/python)
2026-09-17 00:59:13.919653 | ubuntu-focal |   jinja version = 3.1.6
2026-09-17 00:59:13.919661 | ubuntu-focal |   pyyaml version = 6.0.2 (with libyaml v0.2.5)
2026-09-17 00:59:13.919667 | ubuntu-focal | No config file found; using defaults
2026-09-17 00:59:13.919673 | ubuntu-focal | Found installed collection ansible._protomatter:2.19.1 at '/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/_internal/ansible_collections/ansible/_protomatter'
2026-09-17 00:59:13.919678 | ubuntu-focal | Created /home/zuul/.ansible/galaxy_token
2026-09-17 00:59:13.919684 | ubuntu-focal | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com. Got an unexpected error when getting available versions of collection ansible.posix: 'results'
2026-09-17 00:59:13.919689 | ubuntu-focal | [ERROR]: Unexpected Exception, this is probably a bug: 'results'
2026-09-17 00:59:13.919695 | ubuntu-focal |
2026-09-17 00:59:13.919702 | ubuntu-focal | Traceback (most recent call last):
2026-09-17 00:59:13.919708 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/cli/__init__.py", line 660, in cli_executor
2026-09-17 00:59:13.919714 | ubuntu-focal |     exit_code = cli.run()
2026-09-17 00:59:13.919720 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 732, in run
2026-09-17 00:59:13.919726 | ubuntu-focal |     return context.CLIARGS['func']()
2026-09-17 00:59:13.919732 | ubuntu-focal |            ~~~~~~~~~~~~~~~~~~~~~~~^^
2026-09-17 00:59:13.919739 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 100, in method_wrapper
2026-09-17 00:59:13.919744 | ubuntu-focal |     return wrapped_method(*args, **kwargs)
2026-09-17 00:59:13.919750 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 1397, in execute_install
2026-09-17 00:59:13.919756 | ubuntu-focal |     self._execute_install_collection(
2026-09-17 00:59:13.919761 | ubuntu-focal |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-17 00:59:13.919773 | ubuntu-focal |         collection_requirements, collection_path,
2026-09-17 00:59:13.919779 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-17 00:59:13.919784 | ubuntu-focal |         artifacts_manager=artifacts_manager,
2026-09-17 00:59:13.919790 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-17 00:59:13.919795 | ubuntu-focal |     )
2026-09-17 00:59:13.919801 | ubuntu-focal |     ^
2026-09-17 00:59:13.919806 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 1446, in _execute_install_collection
2026-09-17 00:59:13.919812 | ubuntu-focal |     install_collections(
2026-09-17 00:59:13.919817 | ubuntu-focal |     ~~~~~~~~~~~~~~~~~~~^
2026-09-17 00:59:13.919823 | ubuntu-focal |         requirements, output_path, self.api_servers, ignore_errors,
2026-09-17 00:59:13.919828 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-17 00:59:13.919834 | ubuntu-focal |     ...<5 lines>...
2026-09-17 00:59:13.919839 | ubuntu-focal |         read_requirement_paths=read_req_paths,
2026-09-17 00:59:13.919844 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-17 00:59:13.919850 | ubuntu-focal |     )
2026-09-17 00:59:13.919855 | ubuntu-focal |     ^
2026-09-17 00:59:13.919861 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/__init__.py", line 735, in install_collections
2026-09-17 00:59:13.919866 | ubuntu-focal |     dependency_map = _resolve_depenency_map(
2026-09-17 00:59:13.919871 | ubuntu-focal |         collections,
2026-09-17 00:59:13.919876 | ubuntu-focal |     ...<7 lines>...
2026-09-17 00:59:13.919882 | ubuntu-focal |         offline=offline,
2026-09-17 00:59:13.919887 | ubuntu-focal |     )
2026-09-17 00:59:13.919891 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/__init__.py", line 1855, in _resolve_depenency_map
2026-09-17 00:59:13.919896 | ubuntu-focal |     return collection_dep_resolver.resolve(
2026-09-17 00:59:13.919902 | ubuntu-focal |            ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-17 00:59:13.919907 | ubuntu-focal |         requested_requirements,
2026-09-17 00:59:13.919913 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^
2026-09-17 00:59:13.919918 | ubuntu-focal |         max_rounds=2000000,  # NOTE: same constant pip uses
2026-09-17 00:59:13.919923 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-17 00:59:13.919929 | ubuntu-focal |     ).mapping
2026-09-17 00:59:13.919934 | ubuntu-focal |     ^
2026-09-17 00:59:13.919940 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 596, in resolve
2026-09-17 00:59:13.919951 | ubuntu-focal |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-09-17 00:59:13.919957 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 429, in resolve
2026-09-17 00:59:13.919963 | ubuntu-focal |     self._add_to_criteria(self.state.criteria, r, parent=None)
2026-09-17 00:59:13.919968 | ubuntu-focal |     ~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-17 00:59:13.919973 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 125, in _add_to_criteria
2026-09-17 00:59:13.919979 | ubuntu-focal |     matches = self._p.find_matches(
2026-09-17 00:59:13.919984 | ubuntu-focal |         identifier=identifier,
2026-09-17 00:59:13.919989 | ubuntu-focal |     ...<9 lines>...
2026-09-17 00:59:13.919995 | ubuntu-focal |         ),
2026-09-17 00:59:13.920000 | ubuntu-focal |     )
2026-09-17 00:59:13.920009 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 477, in find_matches
2026-09-17 00:59:13.920015 | ubuntu-focal |     match for match in self._find_matches(list(requirements[identifier]))
2026-09-17 00:59:13.920021 | ubuntu-focal |                        ~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-17 00:59:13.920026 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 253, in _find_matches
2026-09-17 00:59:13.920036 | ubuntu-focal |     coll_versions = [] if preinstalled_candidates else self._api_proxy.get_collection_versions(first_req)  # type: t.Iterable[t.Tuple[str, GalaxyAPI]]
2026-09-17 00:59:13.920042 | ubuntu-focal |                                                        ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^
2026-09-17 00:59:13.920047 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 103, in get_collection_versions
2026-09-17 00:59:13.920052 | ubuntu-focal |     return set(
2026-09-17 00:59:13.920058 | ubuntu-focal |         (version, api)
2026-09-17 00:59:13.920063 | ubuntu-focal |     ...<2 lines>...
2026-09-17 00:59:13.920069 | ubuntu-focal |         )
2026-09-17 00:59:13.920074 | ubuntu-focal |     )
2026-09-17 00:59:13.920079 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 105, in <genexpr>
2026-09-17 00:59:13.920084 | ubuntu-focal |     for api, version in self._get_collection_versions(
2026-09-17 00:59:13.920090 | ubuntu-focal |                         ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-17 00:59:13.920095 | ubuntu-focal |         requirement,
2026-09-17 00:59:13.920100 | ubuntu-focal |         ^^^^^^^^^^^^
2026-09-17 00:59:13.920106 | ubuntu-focal |     )
2026-09-17 00:59:13.920111 | ubuntu-focal |     ^
2026-09-17 00:59:13.920117 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 85, in _get_collection_versions
2026-09-17 00:59:13.920122 | ubuntu-focal |     raise last_error
2026-09-17 00:59:13.920127 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 64, in _get_collection_versions
2026-09-17 00:59:13.920133 | ubuntu-focal |     versions = api.get_collection_versions(requirement.namespace, requirement.name)
2026-09-17 00:59:13.920138 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 93, in wrapped
2026-09-17 00:59:13.920144 | ubuntu-focal |     data = self._call_galaxy(n_url, method='GET', error_context_msg=error_context_msg, cache=True)
2026-09-17 00:59:13.920149 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/module_utils/api.py", line 168, in run_function
2026-09-17 00:59:13.920157 | ubuntu-focal |     return call_retryable_function()
2026-09-17 00:59:13.920163 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 386, in _call_galaxy
2026-09-17 00:59:13.920169 | ubuntu-focal |     res = path_cache['results']
2026-09-17 00:59:13.920174 | ubuntu-focal |           ~~~~~~~~~~^^^^^^^^^^^
2026-09-17 00:59:13.920179 | ubuntu-focal | KeyError: 'results'
2026-09-17 00:59:13.920184 | ubuntu-focal |
2026-09-17 00:59:13.920190 | ubuntu-focal | The above exception was the direct cause of the following exception:
2026-09-17 00:59:13.920195 | ubuntu-focal |
2026-09-17 00:59:13.920201 | ubuntu-focal | Traceback (most recent call last):
2026-09-17 00:59:13.920206 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/cli/__init__.py", line 669, in cli_executor
2026-09-17 00:59:13.920212 | ubuntu-focal |     raise AnsibleError("Unexpected Exception, this is probably a bug.") from ex
2026-09-17 00:59:13.920217 | ubuntu-focal | ansible.errors.AnsibleError: Unexpected Exception, this is probably a bug: 'results'
2026-09-17 00:59:13.920222 | ubuntu-focal |
2026-09-17 00:59:13.920228 | ubuntu-focal |
2026-09-17 00:59:14.189547 | ubuntu-focal | ERROR
2026-09-17 00:59:14.189850 | ubuntu-focal | {
2026-09-17 00:59:14.189905 | ubuntu-focal |   "delta": "0:01:06.106444",
2026-09-17 00:59:14.189944 | ubuntu-focal |   "end": "2026-09-17 00:59:14.022587",
2026-09-17 00:59:14.189980 | ubuntu-focal |   "msg": "non-zero return code",
2026-09-17 00:59:14.190014 | ubuntu-focal |   "rc": 1,
2026-09-17 00:59:14.190047 | ubuntu-focal |   "start": "2026-09-17 00:58:07.916143"
2026-09-17 00:59:14.190080 | ubuntu-focal | }
failure
2026-09-17 00:59:14.198873 | 
2026-09-17 00:59:14.198971 | PLAY RECAP
2026-09-17 00:59:14.199113 | ubuntu-focal | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-17 00:59:14.199184 | 
2026-09-17 00:59:14.417351 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-17 00:59:14.420097 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-17 00:59:14.926966 | 
2026-09-17 00:59:14.927078 | PLAY [all]
2026-09-17 00:59:14.937126 | 
2026-09-17 00:59:14.937200 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-17 00:59:14.993503 | ubuntu-focal | skipping: Conditional result was False
2026-09-17 00:59:14.998300 | 
2026-09-17 00:59:14.998374 | TASK [fetch-output : Set log path for single node]
2026-09-17 00:59:15.026240 | ubuntu-focal | ok
2026-09-17 00:59:15.030250 | 
2026-09-17 00:59:15.030328 | LOOP [fetch-output : Ensure local output dirs]
2026-09-17 00:59:15.414204 | ubuntu-focal -> localhost | ok: "/var/lib/zuul/builds/e670ab512cb54cc8b8c798d30a89a129/work/logs"
2026-09-17 00:59:15.654728 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/e670ab512cb54cc8b8c798d30a89a129/work/artifacts"
2026-09-17 00:59:15.872049 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/e670ab512cb54cc8b8c798d30a89a129/work/docs"
2026-09-17 00:59:15.891167 | 
2026-09-17 00:59:15.891397 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-17 00:59:16.543245 | ubuntu-focal | ok
2026-09-17 00:59:16.543622 | ubuntu-focal | changed: All items complete
2026-09-17 00:59:16.543675 | 
2026-09-17 00:59:16.991052 | ubuntu-focal | changed: .d..t...... ./
2026-09-17 00:59:17.461786 | ubuntu-focal | changed: .d..t...... ./
2026-09-17 00:59:17.489670 | 
2026-09-17 00:59:17.489895 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-17 00:59:17.964105 | ubuntu-focal -> localhost | ok: Item: artifacts Runtime: 0:00:00.008008
2026-09-17 00:59:18.210593 | ubuntu-focal -> localhost | ok: Item: docs Runtime: 0:00:00.005470
2026-09-17 00:59:18.230916 | 
2026-09-17 00:59:18.231080 | PLAY [all]
2026-09-17 00:59:18.241676 | 
2026-09-17 00:59:18.241797 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-17 00:59:18.661116 | ubuntu-focal | changed
2026-09-17 00:59:18.671992 | 
2026-09-17 00:59:18.672091 | PLAY RECAP
2026-09-17 00:59:18.672176 | ubuntu-focal | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-17 00:59:18.672219 | 
2026-09-17 00:59:18.848815 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-17 00:59:18.850956 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-17 00:59:19.423613 | 
2026-09-17 00:59:19.423763 | PLAY [localhost]
2026-09-17 00:59:19.433864 | 
2026-09-17 00:59:19.433931 | TASK [Generate Zuul manifest]
2026-09-17 00:59:19.451012 | localhost | ok
2026-09-17 00:59:19.463998 | 
2026-09-17 00:59:19.464068 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-17 00:59:19.858613 | localhost | changed
2026-09-17 00:59:19.877783 | 
2026-09-17 00:59:19.877941 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-17 00:59:19.921145 | localhost | ok
2026-09-17 00:59:19.933309 | 
2026-09-17 00:59:19.933436 | TASK [Upload logs]
2026-09-17 00:59:19.954301 | localhost | ok
2026-09-17 00:59:20.021371 | 
2026-09-17 00:59:20.021482 | TASK [Set zuul-log-path fact]
2026-09-17 00:59:20.038631 | localhost | ok
2026-09-17 00:59:20.048220 | 
2026-09-17 00:59:20.048297 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-17 00:59:20.077386 | localhost | ok
2026-09-17 00:59:20.085297 | 
2026-09-17 00:59:20.085376 | TASK [upload-logs : Create log directories]
2026-09-17 00:59:20.510807 | localhost | changed
2026-09-17 00:59:20.521308 | 
2026-09-17 00:59:20.521447 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-17 00:59:20.959126 | localhost -> localhost | ok: Runtime: 0:00:00.004719
2026-09-17 00:59:20.968102 | 
2026-09-17 00:59:20.968243 | TASK [upload-logs : Upload logs to log server]
2026-09-17 00:59:21.443259 | localhost | Output suppressed because no_log was given
2026-09-17 00:59:21.448232 | 
2026-09-17 00:59:21.448331 | LOOP [upload-logs : Compress console log and json output]
2026-09-17 00:59:21.514373 | localhost | skipping: Conditional result was False
2026-09-17 00:59:21.523584 | localhost | skipping: Conditional result was False
2026-09-17 00:59:21.530992 | 
2026-09-17 00:59:21.531155 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-17 00:59:21.590343 | localhost | skipping: Conditional result was False
2026-09-17 00:59:21.590669 | 
2026-09-17 00:59:21.596767 | localhost | skipping: Conditional result was False
2026-09-17 00:59:21.613191 | 
2026-09-17 00:59:21.613424 | LOOP [upload-logs : Upload console log and json output]