2026-09-12 00:56:50.061936 | Job console starting
2026-09-12 00:56:50.076742 | Updating git repos
2026-09-12 00:56:50.104766 | Cloning repos into workspace
2026-09-12 00:56:50.162720 | Restoring repo states
2026-09-12 00:56:50.171441 | Merging changes
2026-09-12 00:56:51.131247 | Checking out repos
2026-09-12 00:56:51.168972 | Preparing playbooks
2026-09-12 00:56:53.472185 | Running Ansible setup
2026-09-12 00:56:57.187291 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-12 00:56:57.853836 | 
2026-09-12 00:56:57.854004 | PLAY [localhost]
2026-09-12 00:56:57.864762 | 
2026-09-12 00:56:57.864862 | TASK [Gathering Facts]
2026-09-12 00:56:58.673946 | localhost | ok
2026-09-12 00:56:58.689068 | 
2026-09-12 00:56:58.689448 | TASK [Setup log path fact]
2026-09-12 00:56:58.722846 | localhost | ok
2026-09-12 00:56:58.745254 | 
2026-09-12 00:56:58.745478 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-12 00:56:58.786621 | localhost | ok
2026-09-12 00:56:58.798591 | 
2026-09-12 00:56:58.798729 | TASK [emit-job-header : Print job information]
2026-09-12 00:56:58.850352 | # Job Information
2026-09-12 00:56:58.850561 | Ansible Version: 2.16.18
2026-09-12 00:56:58.850626 | Job: ansible-collection-containers-molecule-nerdctl-ubuntu-focal
2026-09-12 00:56:58.850669 | Pipeline: check
2026-09-12 00:56:58.850709 | Executor: 2d72f0692154
2026-09-12 00:56:58.850748 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/150
2026-09-12 00:56:58.850793 | Event ID: c02f8e60-ae43-11f1-9f8b-a3c8ebe95043
2026-09-12 00:56:58.856107 | 
2026-09-12 00:56:58.856258 | LOOP [emit-job-header : Print node information]
2026-09-12 00:56:58.974628 | localhost | ok:
2026-09-12 00:56:58.975390 | localhost | # Node Information
2026-09-12 00:56:58.975509 | localhost | Inventory Hostname: ubuntu-focal
2026-09-12 00:56:58.975591 | localhost | Hostname: np0000192657
2026-09-12 00:56:58.975652 | localhost | Username: zuul
2026-09-12 00:56:58.975719 | localhost | Distro: Ubuntu 20.04
2026-09-12 00:56:58.975778 | localhost | Provider: yul1
2026-09-12 00:56:58.975834 | localhost | Region: ca-ymq-1
2026-09-12 00:56:58.975898 | localhost | Label: ubuntu-focal
2026-09-12 00:56:58.975965 | localhost | Product Name: OpenStack Nova
2026-09-12 00:56:58.976035 | localhost | Interface IP: 162.253.55.239
2026-09-12 00:56:58.996541 | 
2026-09-12 00:56:58.996768 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-12 00:56:59.376997 | localhost -> localhost | changed
2026-09-12 00:56:59.387428 | 
2026-09-12 00:56:59.387564 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-12 00:57:00.355103 | localhost -> localhost | changed
2026-09-12 00:57:00.366327 | 
2026-09-12 00:57:00.366443 | PLAY [all]
2026-09-12 00:57:00.381977 | 
2026-09-12 00:57:00.382104 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-12 00:57:00.609845 | ubuntu-focal -> localhost | ok
2026-09-12 00:57:00.624658 | 
2026-09-12 00:57:00.624825 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-12 00:57:00.659083 | ubuntu-focal | ok
2026-09-12 00:57:00.677782 | ubuntu-focal | included: /var/lib/zuul/builds/551e36dc1703409ea0358ce15c896a5f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-12 00:57:00.682752 | 
2026-09-12 00:57:00.682808 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-12 00:57:01.913127 | ubuntu-focal -> localhost | Generating public/private rsa key pair.
2026-09-12 00:57:01.913418 | ubuntu-focal -> localhost | Your identification has been saved in /var/lib/zuul/builds/551e36dc1703409ea0358ce15c896a5f/work/551e36dc1703409ea0358ce15c896a5f_id_rsa
2026-09-12 00:57:01.913479 | ubuntu-focal -> localhost | Your public key has been saved in /var/lib/zuul/builds/551e36dc1703409ea0358ce15c896a5f/work/551e36dc1703409ea0358ce15c896a5f_id_rsa.pub
2026-09-12 00:57:01.913523 | ubuntu-focal -> localhost | The key fingerprint is:
2026-09-12 00:57:01.913565 | ubuntu-focal -> localhost | SHA256:s7yZe6jOGmY2jwbGCal1aaLcknBjGwpg2qDC4cfiWaY zuul-build-sshkey
2026-09-12 00:57:01.913630 | ubuntu-focal -> localhost | The key's randomart image is:
2026-09-12 00:57:01.913671 | ubuntu-focal -> localhost | +---[RSA 3072]----+
2026-09-12 00:57:01.913719 | ubuntu-focal -> localhost | |                 |
2026-09-12 00:57:01.913762 | ubuntu-focal -> localhost | |oo               |
2026-09-12 00:57:01.913801 | ubuntu-focal -> localhost | |B+o  .           |
2026-09-12 00:57:01.913838 | ubuntu-focal -> localhost | |O+O=+            |
2026-09-12 00:57:01.913877 | ubuntu-focal -> localhost | |BOXO    S        |
2026-09-12 00:57:01.913915 | ubuntu-focal -> localhost | |+E*.   . o       |
2026-09-12 00:57:01.913952 | ubuntu-focal -> localhost | | ...*   o.       |
2026-09-12 00:57:01.913989 | ubuntu-focal -> localhost | |   +.*  .+.      |
2026-09-12 00:57:01.914034 | ubuntu-focal -> localhost | |   .oo=.=o       |
2026-09-12 00:57:01.914141 | ubuntu-focal -> localhost | +----[SHA256]-----+
2026-09-12 00:57:01.914287 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.794565
2026-09-12 00:57:01.928722 | 
2026-09-12 00:57:01.929029 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-12 00:57:01.976286 | ubuntu-focal | ok
2026-09-12 00:57:01.989504 | ubuntu-focal | included: /var/lib/zuul/builds/551e36dc1703409ea0358ce15c896a5f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-12 00:57:01.997902 | 
2026-09-12 00:57:01.997960 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-12 00:57:02.021689 | ubuntu-focal | skipping: Conditional result was False
2026-09-12 00:57:02.027669 | 
2026-09-12 00:57:02.027750 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-12 00:57:02.475211 | ubuntu-focal | changed
2026-09-12 00:57:02.481543 | 
2026-09-12 00:57:02.481624 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-12 00:57:02.678519 | ubuntu-focal | ok
2026-09-12 00:57:02.688749 | 
2026-09-12 00:57:02.688884 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-12 00:57:03.199822 | ubuntu-focal | changed
2026-09-12 00:57:03.221454 | 
2026-09-12 00:57:03.221678 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-12 00:57:03.672435 | ubuntu-focal | changed
2026-09-12 00:57:03.678642 | 
2026-09-12 00:57:03.678720 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-12 00:57:03.702860 | ubuntu-focal | skipping: Conditional result was False
2026-09-12 00:57:03.709819 | 
2026-09-12 00:57:03.709937 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-12 00:57:04.098408 | ubuntu-focal -> localhost | changed
2026-09-12 00:57:04.119449 | 
2026-09-12 00:57:04.119584 | TASK [add-build-sshkey : Add back temp key]
2026-09-12 00:57:04.447427 | ubuntu-focal -> localhost | Identity added: /var/lib/zuul/builds/551e36dc1703409ea0358ce15c896a5f/work/551e36dc1703409ea0358ce15c896a5f_id_rsa (zuul-build-sshkey)
2026-09-12 00:57:04.447720 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.014851
2026-09-12 00:57:04.457685 | 
2026-09-12 00:57:04.457808 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-12 00:57:04.775117 | ubuntu-focal | ok
2026-09-12 00:57:04.784292 | 
2026-09-12 00:57:04.784425 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-12 00:57:04.820161 | ubuntu-focal | skipping: Conditional result was False
2026-09-12 00:57:04.842009 | 
2026-09-12 00:57:04.842162 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-12 00:57:05.166093 | ubuntu-focal | ok
2026-09-12 00:57:05.171575 | 
2026-09-12 00:57:05.172420 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-12 00:57:05.908254 | ubuntu-focal | Output suppressed because no_log was given
2026-09-12 00:57:05.929134 | 
2026-09-12 00:57:05.929276 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-12 00:57:06.185644 | ubuntu-focal | ok: "logs"
2026-09-12 00:57:06.185920 | ubuntu-focal | ok: All items complete
2026-09-12 00:57:06.185963 | 
2026-09-12 00:57:06.276908 | ubuntu-focal | ok: "artifacts"
2026-09-12 00:57:06.454819 | ubuntu-focal | ok: "docs"
2026-09-12 00:57:06.468970 | 
2026-09-12 00:57:06.469145 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-12 00:57:06.660804 | ubuntu-focal | changed: "logs"
2026-09-12 00:57:06.833463 | ubuntu-focal | changed: "artifacts"
2026-09-12 00:57:07.003058 | ubuntu-focal | changed: "docs"
2026-09-12 00:57:07.061703 | 
2026-09-12 00:57:07.061874 | PLAY RECAP
2026-09-12 00:57:07.061961 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-12 00:57:07.062021 | ubuntu-focal | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-12 00:57:07.062064 | 
2026-09-12 00:57:07.232160 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-12 00:57:07.233603 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-12 00:57:07.848307 | 
2026-09-12 00:57:07.848454 | PLAY [all]
2026-09-12 00:57:07.862925 | 
2026-09-12 00:57:07.863019 | TASK [setup-uv : Extract archive]
2026-09-12 00:57:10.267287 | ubuntu-focal | changed
2026-09-12 00:57:10.285009 | 
2026-09-12 00:57:10.285266 | TASK [setup-uv : Print version]
2026-09-12 00:57:10.664465 | ubuntu-focal | uv 0.8.13
2026-09-12 00:57:10.830572 | ubuntu-focal | ok: Runtime: 0:00:00.011070
2026-09-12 00:57:10.848087 | 
2026-09-12 00:57:10.848231 | TASK [Install Ansible collection siblings]
2026-09-12 00:57:11.271613 | ubuntu-focal | ok:
2026-09-12 00:57:11.271893 | ubuntu-focal | Skipping ansible.posix: no checked-out sibling
2026-09-12 00:57:11.271951 | ubuntu-focal | Skipping community.general: no checked-out sibling
2026-09-12 00:57:11.274773 | 
2026-09-12 00:57:11.274866 | PLAY RECAP
2026-09-12 00:57:11.274955 | ubuntu-focal | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-12 00:57:11.274996 | 
2026-09-12 00:57:11.413714 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-12 00:57:11.416071 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-12 00:57:12.049007 | 
2026-09-12 00:57:12.049203 | PLAY [all]
2026-09-12 00:57:12.061165 | 
2026-09-12 00:57:12.061323 | LOOP [Check inventory directories]
2026-09-12 00:57:12.377217 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers"
2026-09-12 00:57:12.549299 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers/molecule/nerdctl/inventory"
2026-09-12 00:57:12.717618 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/nerdctl/inventory"
2026-09-12 00:57:12.737843 | 
2026-09-12 00:57:12.738176 | LOOP [Copy inventory file for Zuul]
2026-09-12 00:57:13.540341 | ubuntu-focal | changed:
2026-09-12 00:57:13.540695 | ubuntu-focal | {
2026-09-12 00:57:13.540754 | ubuntu-focal |   "ansible_loop_var": "item",
2026-09-12 00:57:13.540801 | ubuntu-focal |   "changed": false,
2026-09-12 00:57:13.540839 | ubuntu-focal |   "failed": false,
2026-09-12 00:57:13.540883 | ubuntu-focal |   "invocation": {
2026-09-12 00:57:13.540920 | ubuntu-focal |     "module_args": {
2026-09-12 00:57:13.540956 | ubuntu-focal |       "checksum_algorithm": "sha1",
2026-09-12 00:57:13.540990 | ubuntu-focal |       "follow": true,
2026-09-12 00:57:13.541025 | ubuntu-focal |       "get_attributes": true,
2026-09-12 00:57:13.541061 | ubuntu-focal |       "get_checksum": true,
2026-09-12 00:57:13.541118 | ubuntu-focal |       "get_mime": true,
2026-09-12 00:57:13.541154 | ubuntu-focal |       "path": "src/github.com/vexxhost/ansible-collection-containers"
2026-09-12 00:57:13.541188 | ubuntu-focal |     }
2026-09-12 00:57:13.541222 | ubuntu-focal |   },
2026-09-12 00:57:13.541255 | ubuntu-focal |   "item": "src/github.com/vexxhost/ansible-collection-containers",
2026-09-12 00:57:13.541310 | ubuntu-focal |   "stat": {
2026-09-12 00:57:13.541346 | ubuntu-focal |     "atime": 1789174625.8126662,
2026-09-12 00:57:13.541380 | ubuntu-focal |     "attr_flags": "e",
2026-09-12 00:57:13.541414 | ubuntu-focal |     "attributes": [
2026-09-12 00:57:13.541448 | ubuntu-focal |       "extents"
2026-09-12 00:57:13.541482 | ubuntu-focal |     ],
2026-09-12 00:57:13.541515 | ubuntu-focal |     "block_size": 4096,
2026-09-12 00:57:13.541547 | ubuntu-focal |     "blocks": 8,
2026-09-12 00:57:13.541579 | ubuntu-focal |     "charset": "binary",
2026-09-12 00:57:13.541614 | ubuntu-focal |     "ctime": 1789174625.8126662,
2026-09-12 00:57:13.541648 | ubuntu-focal |     "dev": 64513,
2026-09-12 00:57:13.541681 | ubuntu-focal |     "device_type": 0,
2026-09-12 00:57:13.541713 | ubuntu-focal |     "executable": true,
2026-09-12 00:57:13.541746 | ubuntu-focal |     "exists": true,
2026-09-12 00:57:13.541778 | ubuntu-focal |     "gid": 1000,
2026-09-12 00:57:13.541810 | ubuntu-focal |     "gr_name": "zuul",
2026-09-12 00:57:13.541842 | ubuntu-focal |     "inode": 1035293,
2026-09-12 00:57:13.541873 | ubuntu-focal |     "isblk": false,
2026-09-12 00:57:13.541906 | ubuntu-focal |     "ischr": false,
2026-09-12 00:57:13.541939 | ubuntu-focal |     "isdir": true,
2026-09-12 00:57:13.541972 | ubuntu-focal |     "isfifo": false,
2026-09-12 00:57:13.542004 | ubuntu-focal |     "isgid": false,
2026-09-12 00:57:13.542036 | ubuntu-focal |     "islnk": false,
2026-09-12 00:57:13.542068 | ubuntu-focal |     "isreg": false,
2026-09-12 00:57:13.542120 | ubuntu-focal |     "issock": false,
2026-09-12 00:57:13.542155 | ubuntu-focal |     "isuid": false,
2026-09-12 00:57:13.542187 | ubuntu-focal |     "mimetype": "inode/directory",
2026-09-12 00:57:13.542220 | ubuntu-focal |     "mode": "0755",
2026-09-12 00:57:13.542252 | ubuntu-focal |     "mtime": 1789174610.19458,
2026-09-12 00:57:13.542285 | ubuntu-focal |     "nlink": 11,
2026-09-12 00:57:13.542318 | ubuntu-focal |     "path": "src/github.com/vexxhost/ansible-collection-containers",
2026-09-12 00:57:13.542351 | ubuntu-focal |     "pw_name": "zuul",
2026-09-12 00:57:13.542389 | ubuntu-focal |     "readable": true,
2026-09-12 00:57:13.542423 | ubuntu-focal |     "rgrp": true,
2026-09-12 00:57:13.542456 | ubuntu-focal |     "roth": true,
2026-09-12 00:57:13.542489 | ubuntu-focal |     "rusr": true,
2026-09-12 00:57:13.542522 | ubuntu-focal |     "size": 4096,
2026-09-12 00:57:13.542554 | ubuntu-focal |     "uid": 1000,
2026-09-12 00:57:13.542587 | ubuntu-focal |     "version": "1141045857",
2026-09-12 00:57:13.542621 | ubuntu-focal |     "wgrp": false,
2026-09-12 00:57:13.542656 | ubuntu-focal |     "woth": false,
2026-09-12 00:57:13.542690 | ubuntu-focal |     "writeable": true,
2026-09-12 00:57:13.542722 | ubuntu-focal |     "wusr": true,
2026-09-12 00:57:13.542755 | ubuntu-focal |     "xgrp": true,
2026-09-12 00:57:13.542787 | ubuntu-focal |     "xoth": true,
2026-09-12 00:57:13.542818 | ubuntu-focal |     "xusr": true
2026-09-12 00:57:13.542851 | ubuntu-focal |   }
2026-09-12 00:57:13.542884 | ubuntu-focal | }
2026-09-12 00:57:13.542943 | ubuntu-focal | ok: All items complete
2026-09-12 00:57:13.542983 | 
2026-09-12 00:57:13.548886 | ubuntu-focal | skipping: Conditional result was False
2026-09-12 00:57:13.550738 | ubuntu-focal | skipping: Conditional result was False
2026-09-12 00:57:13.567575 | 
2026-09-12 00:57:13.567801 | LOOP [Switch "ansible_host" to private IP]
2026-09-12 00:57:13.955069 | ubuntu-focal | changed: 1 replacements made
2026-09-12 00:57:13.956992 | ubuntu-focal | skipping: Conditional result was False
2026-09-12 00:57:13.963347 | ubuntu-focal | skipping: Conditional result was False
2026-09-12 00:57:13.980921 | 
2026-09-12 00:57:13.981074 | TASK [Run Molecule scenario]
2026-09-12 00:57:14.648908 | ubuntu-focal | Downloading cpython-3.13.7-linux-x86_64-gnu (download) (30.9MiB)
2026-09-12 00:57:15.354351 | ubuntu-focal |  Downloading cpython-3.13.7-linux-x86_64-gnu (download)
2026-09-12 00:57:15.560250 | ubuntu-focal | Using CPython 3.13.7
2026-09-12 00:57:15.560353 | ubuntu-focal | Creating virtual environment at: .venv
2026-09-12 00:57:16.681015 | ubuntu-focal | Downloading ansible-core (2.3MiB)
2026-09-12 00:57:16.682980 | ubuntu-focal | Downloading cryptography (4.3MiB)
2026-09-12 00:57:16.683578 | ubuntu-focal | Downloading pygments (1.2MiB)
2026-09-12 00:57:16.821493 | ubuntu-focal |  Downloading cryptography
2026-09-12 00:57:16.864066 | ubuntu-focal |  Downloading pygments
2026-09-12 00:57:17.022180 | ubuntu-focal |  Downloading ansible-core
2026-09-12 00:57:17.058555 | ubuntu-focal | Installed 36 packages in 35ms
2026-09-12 00:57:18.109354 | ubuntu-focal | INFO     Collection 'vexxhost.containers' detected.
2026-09-12 00:57:18.109452 | ubuntu-focal | INFO     Scenarios will be used from 'extensions/molecule'
2026-09-12 00:57:18.893775 | ubuntu-focal | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-09-12 00:57:18.893837 | ubuntu-focal | INFO     default scenario not found, disabling shared state.
2026-09-12 00:57:18.893933 | ubuntu-focal | INFO     [nerdctl > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-12 00:57:18.893996 | ubuntu-focal | INFO     [nerdctl > prerun] Performing prerun with role_name_check=0...
2026-09-12 00:58:29.410111 | ubuntu-focal | WARNING  Retrying execution failure 250 of: ansible-galaxy collection install -vvv ansible.posix:>=1.6.0
2026-09-12 00:58:29.410193 | ubuntu-focal | ERROR    Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 250 code:
2026-09-12 00:58:29.410200 | ubuntu-focal | Starting galaxy collection install process
2026-09-12 00:58:29.410205 | ubuntu-focal | Process install dependency map
2026-09-12 00:58:29.410210 | ubuntu-focal |
2026-09-12 00:58:29.410214 | ubuntu-focal | ansible-galaxy
2026-09-12 00:58:29.410218 | ubuntu-focal |   config file = None
2026-09-12 00:58:29.410223 | ubuntu-focal |   configured module search path =
2026-09-12 00:58:29.410247 | ubuntu-focal |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible
2026-09-12 00:58:29.410253 | 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-12 00:58:29.410260 | ubuntu-focal |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/ansible-galaxy
2026-09-12 00:58:29.410264 | 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-12 00:58:29.410268 | ubuntu-focal |   jinja version = 3.1.6
2026-09-12 00:58:29.410272 | ubuntu-focal |   pyyaml version = 6.0.2 (with libyaml v0.2.5)
2026-09-12 00:58:29.410276 | ubuntu-focal | No config file found; using defaults
2026-09-12 00:58:29.410281 | 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-12 00:58:29.410285 | ubuntu-focal | Created /home/zuul/.ansible/galaxy_token
2026-09-12 00:58:29.410290 | ubuntu-focal | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an unexpected error when getting available versions of collection ansible.posix: 'results'
2026-09-12 00:58:29.410294 | ubuntu-focal | : Unexpected Exception, this is probably a bug: 'results'
2026-09-12 00:58:29.410298 | ubuntu-focal |
2026-09-12 00:58:29.410302 | ubuntu-focal | Traceback (most recent call last):
2026-09-12 00:58:29.410306 | 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-12 00:58:29.410310 | ubuntu-focal |     exit_code = cli.run()
2026-09-12 00:58:29.410315 | 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-12 00:58:29.410319 | ubuntu-focal |     return context.CLIARGS()
2026-09-12 00:58:29.410323 | ubuntu-focal |            ~~~~~~~~~~~~~~~~~~~~~~~^^
2026-09-12 00:58:29.410327 | 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-12 00:58:29.410346 | ubuntu-focal |     return wrapped_method(*args, **kwargs)
2026-09-12 00:58:29.410350 | 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-12 00:58:29.410354 | ubuntu-focal |     self._execute_install_collection(
2026-09-12 00:58:29.410357 | ubuntu-focal |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-12 00:58:29.410361 | ubuntu-focal |         collection_requirements, collection_path,
2026-09-12 00:58:29.410365 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-12 00:58:29.410369 | ubuntu-focal |         artifacts_manager=artifacts_manager,
2026-09-12 00:58:29.410372 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-12 00:58:29.410376 | ubuntu-focal |     )
2026-09-12 00:58:29.410380 | ubuntu-focal |     ^
2026-09-12 00:58:29.410384 | 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-12 00:58:29.410388 | ubuntu-focal |     install_collections(
2026-09-12 00:58:29.410392 | ubuntu-focal |     ~~~~~~~~~~~~~~~~~~~^
2026-09-12 00:58:29.410395 | ubuntu-focal |         requirements, output_path, self.api_servers, ignore_errors,
2026-09-12 00:58:29.410399 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-12 00:58:29.410403 | ubuntu-focal |     ...<5 lines>...
2026-09-12 00:58:29.410407 | ubuntu-focal |         read_requirement_paths=read_req_paths,
2026-09-12 00:58:29.410410 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-12 00:58:29.410414 | ubuntu-focal |     )
2026-09-12 00:58:29.410418 | ubuntu-focal |     ^
2026-09-12 00:58:29.410424 | 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-12 00:58:29.410428 | ubuntu-focal |     dependency_map = _resolve_depenency_map(
2026-09-12 00:58:29.410431 | ubuntu-focal |         collections,
2026-09-12 00:58:29.410435 | ubuntu-focal |     ...<7 lines>...
2026-09-12 00:58:29.410439 | ubuntu-focal |         offline=offline,
2026-09-12 00:58:29.410443 | ubuntu-focal |     )
2026-09-12 00:58:29.410446 | 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-12 00:58:29.410450 | ubuntu-focal |     return collection_dep_resolver.resolve(
2026-09-12 00:58:29.410454 | ubuntu-focal |            ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-12 00:58:29.410459 | ubuntu-focal |         requested_requirements,
2026-09-12 00:58:29.410463 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^
2026-09-12 00:58:29.410468 | ubuntu-focal |         max_rounds=2000000,  # NOTE: same constant pip uses
2026-09-12 00:58:29.410472 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-12 00:58:29.410476 | ubuntu-focal |     ).mapping
2026-09-12 00:58:29.410481 | ubuntu-focal |     ^
2026-09-12 00:58:29.410485 | 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-12 00:58:29.410489 | ubuntu-focal |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-09-12 00:58:29.410496 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 508, in resolve
2026-09-12 00:58:29.410500 | ubuntu-focal |     failure_criterion = self._attempt_to_pin_criterion(name)
2026-09-12 00:58:29.410504 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 220, in _attempt_to_pin_criterion
2026-09-12 00:58:29.410508 | ubuntu-focal |     criteria = self._get_updated_criteria(candidate)
2026-09-12 00:58:29.410512 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 210, in _get_updated_criteria
2026-09-12 00:58:29.410515 | ubuntu-focal |     for requirement in self._p.get_dependencies(candidate=candidate):
2026-09-12 00:58:29.410522 | ubuntu-focal |                        ~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^
2026-09-12 00:58:29.410526 | 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 441, in get_dependencies
2026-09-12 00:58:29.410530 | ubuntu-focal |     req_map = self._api_proxy.get_collection_dependencies(candidate)
2026-09-12 00:58:29.410534 | 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 171, in get_collection_dependencies
2026-09-12 00:58:29.410538 | ubuntu-focal |     get_collection_version_metadata(collection_candidate).
2026-09-12 00:58:29.410542 | ubuntu-focal |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^
2026-09-12 00:58:29.410545 | 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 157, in get_collection_version_metadata
2026-09-12 00:58:29.410549 | ubuntu-focal |     raise last_err
2026-09-12 00:58:29.410553 | 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 124, in get_collection_version_metadata
2026-09-12 00:58:29.410557 | ubuntu-focal |     version_metadata = api.get_collection_version_metadata(
2026-09-12 00:58:29.410560 | ubuntu-focal |         collection_candidate.namespace,
2026-09-12 00:58:29.410564 | ubuntu-focal |         collection_candidate.name,
2026-09-12 00:58:29.410568 | ubuntu-focal |         collection_candidate.ver,
2026-09-12 00:58:29.410572 | ubuntu-focal |     )
2026-09-12 00:58:29.410575 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 144, in wrapped
2026-09-12 00:58:29.410579 | ubuntu-focal |     return method(self, *args, **kwargs)
2026-09-12 00:58:29.410583 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 809, in get_collection_version_metadata
2026-09-12 00:58:29.410587 | ubuntu-focal |     data = self._call_galaxy(n_collection_url, error_context_msg=error_context_msg, cache=True)
2026-09-12 00:58:29.410591 | 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-12 00:58:29.410594 | ubuntu-focal |     return call_retryable_function()
2026-09-12 00:58:29.410598 | 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-12 00:58:29.410602 | ubuntu-focal |     res = path_cache
2026-09-12 00:58:29.410606 | ubuntu-focal |           ~~~~~~~~~~^^^^^^^^^^^
2026-09-12 00:58:29.410610 | ubuntu-focal | KeyError: 'results'
2026-09-12 00:58:29.410613 | ubuntu-focal |
2026-09-12 00:58:29.410617 | ubuntu-focal | The above exception was the direct cause of the following exception:
2026-09-12 00:58:29.410621 | ubuntu-focal |
2026-09-12 00:58:29.410625 | ubuntu-focal | Traceback (most recent call last):
2026-09-12 00:58:29.410630 | 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-12 00:58:29.410634 | ubuntu-focal |     raise AnsibleError("Unexpected Exception, this is probably a bug.") from ex
2026-09-12 00:58:29.410637 | ubuntu-focal | ansible.errors.AnsibleError: Unexpected Exception, this is probably a bug: 'results'
2026-09-12 00:58:29.410641 | ubuntu-focal |
2026-09-12 00:58:29.410645 | ubuntu-focal |
2026-09-12 00:58:29.414121 | ubuntu-focal | Traceback (most recent call last):
2026-09-12 00:58:29.417447 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/molecule", line 10, in <module>
2026-09-12 00:58:29.417462 | ubuntu-focal |     sys.exit(main())
2026-09-12 00:58:29.417468 | ubuntu-focal |              ~~~~^^
2026-09-12 00:58:29.417725 | 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-12 00:58:29.417732 | ubuntu-focal |     return self.main(*args, **kwargs)
2026-09-12 00:58:29.417737 | ubuntu-focal |            ~~~~~~~~~^^^^^^^^^^^^^^^^^
2026-09-12 00:58:29.417941 | 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-12 00:58:29.417948 | ubuntu-focal |     rv = self.invoke(ctx)
2026-09-12 00:58:29.418197 | 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-12 00:58:29.418203 | ubuntu-focal |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-09-12 00:58:29.418208 | ubuntu-focal |                            ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^
2026-09-12 00:58:29.418461 | 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-12 00:58:29.418468 | ubuntu-focal |     return ctx.invoke(self.callback, **ctx.params)
2026-09-12 00:58:29.418473 | ubuntu-focal |            ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-12 00:58:29.418669 | 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-12 00:58:29.418675 | ubuntu-focal |     return callback(*args, **kwargs)
2026-09-12 00:58:29.418869 | 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-12 00:58:29.418875 | ubuntu-focal |     return func(*args, **kwargs)
2026-09-12 00:58:29.419071 | 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-12 00:58:29.419078 | ubuntu-focal |     return f(get_current_context(), *args, **kwargs)
2026-09-12 00:58:29.419294 | 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-12 00:58:29.419301 | ubuntu-focal |     return func(ctx)
2026-09-12 00:58:29.419561 | 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-12 00:58:29.419569 | ubuntu-focal |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-09-12 00:58:29.419575 | ubuntu-focal |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-12 00:58:29.419814 | 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-12 00:58:29.419821 | ubuntu-focal |     _run_scenarios(scenarios, command_args, default_config)
2026-09-12 00:58:29.419826 | ubuntu-focal |     ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-12 00:58:29.420198 | 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-12 00:58:29.420204 | ubuntu-focal |     scenario.config.runtime.prepare_environment(
2026-09-12 00:58:29.420210 | ubuntu-focal |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-12 00:58:29.420215 | ubuntu-focal |         install_local=True,
2026-09-12 00:58:29.420220 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^
2026-09-12 00:58:29.420225 | ubuntu-focal |         role_name_check=role_name_check,
2026-09-12 00:58:29.420242 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-12 00:58:29.420248 | ubuntu-focal |     )
2026-09-12 00:58:29.420253 | ubuntu-focal |     ^
2026-09-12 00:58:29.420796 | 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-12 00:58:29.420806 | ubuntu-focal |     self.install_collection(
2026-09-12 00:58:29.420811 | ubuntu-focal |     ~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-12 00:58:29.420816 | ubuntu-focal |         f"{name}{',' if is_url(name) else ':'}{required_version}",
2026-09-12 00:58:29.420822 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-12 00:58:29.420827 | ubuntu-focal |         destination=destination,
2026-09-12 00:58:29.420832 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-12 00:58:29.420837 | ubuntu-focal |     )
2026-09-12 00:58:29.420842 | ubuntu-focal |     ^
2026-09-12 00:58:29.420923 | 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-12 00:58:29.420930 | ubuntu-focal |     raise InvalidPrerequisiteError(msg)
2026-09-12 00:58:29.424464 | ubuntu-focal | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 250 code:
2026-09-12 00:58:29.424482 | ubuntu-focal | Starting galaxy collection install process
2026-09-12 00:58:29.424488 | ubuntu-focal | Process install dependency map
2026-09-12 00:58:29.424494 | ubuntu-focal |
2026-09-12 00:58:29.424499 | ubuntu-focal | ansible-galaxy [core 2.19.1]
2026-09-12 00:58:29.424504 | ubuntu-focal |   config file = None
2026-09-12 00:58:29.424509 | ubuntu-focal |   configured module search path = ['/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules']
2026-09-12 00:58:29.424515 | ubuntu-focal |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible
2026-09-12 00:58:29.424520 | 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-12 00:58:29.424525 | ubuntu-focal |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/ansible-galaxy
2026-09-12 00:58:29.424530 | 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-12 00:58:29.424535 | ubuntu-focal |   jinja version = 3.1.6
2026-09-12 00:58:29.424540 | ubuntu-focal |   pyyaml version = 6.0.2 (with libyaml v0.2.5)
2026-09-12 00:58:29.424545 | ubuntu-focal | No config file found; using defaults
2026-09-12 00:58:29.424551 | 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-12 00:58:29.424556 | ubuntu-focal | Created /home/zuul/.ansible/galaxy_token
2026-09-12 00:58:29.424561 | ubuntu-focal | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an unexpected error when getting available versions of collection ansible.posix: 'results'
2026-09-12 00:58:29.424566 | ubuntu-focal | [ERROR]: Unexpected Exception, this is probably a bug: 'results'
2026-09-12 00:58:29.424572 | ubuntu-focal |
2026-09-12 00:58:29.424577 | ubuntu-focal | Traceback (most recent call last):
2026-09-12 00:58:29.424582 | 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-12 00:58:29.424587 | ubuntu-focal |     exit_code = cli.run()
2026-09-12 00:58:29.424592 | 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-12 00:58:29.424597 | ubuntu-focal |     return context.CLIARGS['func']()
2026-09-12 00:58:29.424602 | ubuntu-focal |            ~~~~~~~~~~~~~~~~~~~~~~~^^
2026-09-12 00:58:29.424607 | 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-12 00:58:29.424612 | ubuntu-focal |     return wrapped_method(*args, **kwargs)
2026-09-12 00:58:29.424617 | 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-12 00:58:29.424623 | ubuntu-focal |     self._execute_install_collection(
2026-09-12 00:58:29.424628 | ubuntu-focal |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-12 00:58:29.424634 | ubuntu-focal |         collection_requirements, collection_path,
2026-09-12 00:58:29.424639 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-12 00:58:29.424644 | ubuntu-focal |         artifacts_manager=artifacts_manager,
2026-09-12 00:58:29.424649 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-12 00:58:29.424654 | ubuntu-focal |     )
2026-09-12 00:58:29.424659 | ubuntu-focal |     ^
2026-09-12 00:58:29.424664 | 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-12 00:58:29.424678 | ubuntu-focal |     install_collections(
2026-09-12 00:58:29.424684 | ubuntu-focal |     ~~~~~~~~~~~~~~~~~~~^
2026-09-12 00:58:29.424689 | ubuntu-focal |         requirements, output_path, self.api_servers, ignore_errors,
2026-09-12 00:58:29.424695 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-12 00:58:29.424700 | ubuntu-focal |     ...<5 lines>...
2026-09-12 00:58:29.424705 | ubuntu-focal |         read_requirement_paths=read_req_paths,
2026-09-12 00:58:29.424710 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-12 00:58:29.424716 | ubuntu-focal |     )
2026-09-12 00:58:29.424722 | ubuntu-focal |     ^
2026-09-12 00:58:29.424730 | 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-12 00:58:29.424736 | ubuntu-focal |     dependency_map = _resolve_depenency_map(
2026-09-12 00:58:29.424741 | ubuntu-focal |         collections,
2026-09-12 00:58:29.424746 | ubuntu-focal |     ...<7 lines>...
2026-09-12 00:58:29.424751 | ubuntu-focal |         offline=offline,
2026-09-12 00:58:29.424756 | ubuntu-focal |     )
2026-09-12 00:58:29.424761 | 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-12 00:58:29.424766 | ubuntu-focal |     return collection_dep_resolver.resolve(
2026-09-12 00:58:29.424771 | ubuntu-focal |            ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-12 00:58:29.424776 | ubuntu-focal |         requested_requirements,
2026-09-12 00:58:29.424781 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^
2026-09-12 00:58:29.424786 | ubuntu-focal |         max_rounds=2000000,  # NOTE: same constant pip uses
2026-09-12 00:58:29.424791 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-12 00:58:29.424797 | ubuntu-focal |     ).mapping
2026-09-12 00:58:29.424802 | ubuntu-focal |     ^
2026-09-12 00:58:29.424807 | 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-12 00:58:29.424819 | ubuntu-focal |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-09-12 00:58:29.424825 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 508, in resolve
2026-09-12 00:58:29.424830 | ubuntu-focal |     failure_criterion = self._attempt_to_pin_criterion(name)
2026-09-12 00:58:29.424835 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 220, in _attempt_to_pin_criterion
2026-09-12 00:58:29.424840 | ubuntu-focal |     criteria = self._get_updated_criteria(candidate)
2026-09-12 00:58:29.424845 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/resolvelib/resolvers/resolution.py", line 210, in _get_updated_criteria
2026-09-12 00:58:29.424850 | ubuntu-focal |     for requirement in self._p.get_dependencies(candidate=candidate):
2026-09-12 00:58:29.424855 | ubuntu-focal |                        ~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^
2026-09-12 00:58:29.424860 | 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 441, in get_dependencies
2026-09-12 00:58:29.424865 | ubuntu-focal |     req_map = self._api_proxy.get_collection_dependencies(candidate)
2026-09-12 00:58:29.424871 | 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 171, in get_collection_dependencies
2026-09-12 00:58:29.424876 | ubuntu-focal |     get_collection_version_metadata(collection_candidate).
2026-09-12 00:58:29.424881 | ubuntu-focal |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^
2026-09-12 00:58:29.424886 | 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 157, in get_collection_version_metadata
2026-09-12 00:58:29.424894 | ubuntu-focal |     raise last_err
2026-09-12 00:58:29.424899 | 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 124, in get_collection_version_metadata
2026-09-12 00:58:29.424905 | ubuntu-focal |     version_metadata = api.get_collection_version_metadata(
2026-09-12 00:58:29.424910 | ubuntu-focal |         collection_candidate.namespace,
2026-09-12 00:58:29.424915 | ubuntu-focal |         collection_candidate.name,
2026-09-12 00:58:29.424920 | ubuntu-focal |         collection_candidate.ver,
2026-09-12 00:58:29.424925 | ubuntu-focal |     )
2026-09-12 00:58:29.424930 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 144, in wrapped
2026-09-12 00:58:29.424935 | ubuntu-focal |     return method(self, *args, **kwargs)
2026-09-12 00:58:29.424940 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 809, in get_collection_version_metadata
2026-09-12 00:58:29.424945 | ubuntu-focal |     data = self._call_galaxy(n_collection_url, error_context_msg=error_context_msg, cache=True)
2026-09-12 00:58:29.424950 | 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-12 00:58:29.424955 | ubuntu-focal |     return call_retryable_function()
2026-09-12 00:58:29.424960 | 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-12 00:58:29.424965 | ubuntu-focal |     res = path_cache['results']
2026-09-12 00:58:29.424971 | ubuntu-focal |           ~~~~~~~~~~^^^^^^^^^^^
2026-09-12 00:58:29.424976 | ubuntu-focal | KeyError: 'results'
2026-09-12 00:58:29.424981 | ubuntu-focal |
2026-09-12 00:58:29.424986 | ubuntu-focal | The above exception was the direct cause of the following exception:
2026-09-12 00:58:29.424991 | ubuntu-focal |
2026-09-12 00:58:29.424996 | ubuntu-focal | Traceback (most recent call last):
2026-09-12 00:58:29.425001 | 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-12 00:58:29.425006 | ubuntu-focal |     raise AnsibleError("Unexpected Exception, this is probably a bug.") from ex
2026-09-12 00:58:29.425011 | ubuntu-focal | ansible.errors.AnsibleError: Unexpected Exception, this is probably a bug: 'results'
2026-09-12 00:58:29.425016 | ubuntu-focal |
2026-09-12 00:58:29.425021 | ubuntu-focal |
2026-09-12 00:58:29.641725 | ubuntu-focal | ERROR
2026-09-12 00:58:29.642020 | ubuntu-focal | {
2026-09-12 00:58:29.642079 | ubuntu-focal |   "delta": "0:01:15.127404",
2026-09-12 00:58:29.642118 | ubuntu-focal |   "end": "2026-09-12 00:58:29.536925",
2026-09-12 00:58:29.642154 | ubuntu-focal |   "msg": "non-zero return code",
2026-09-12 00:58:29.642188 | ubuntu-focal |   "rc": 1,
2026-09-12 00:58:29.642222 | ubuntu-focal |   "start": "2026-09-12 00:57:14.409521"
2026-09-12 00:58:29.642255 | ubuntu-focal | }
failure
2026-09-12 00:58:29.649777 | 
2026-09-12 00:58:29.649877 | PLAY RECAP
2026-09-12 00:58:29.649983 | ubuntu-focal | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-12 00:58:29.650026 | 
2026-09-12 00:58:29.802777 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-12 00:58:29.806026 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-12 00:58:30.353438 | 
2026-09-12 00:58:30.353568 | PLAY [all]
2026-09-12 00:58:30.363534 | 
2026-09-12 00:58:30.364103 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-12 00:58:30.418821 | ubuntu-focal | skipping: Conditional result was False
2026-09-12 00:58:30.425543 | 
2026-09-12 00:58:30.425648 | TASK [fetch-output : Set log path for single node]
2026-09-12 00:58:30.479520 | ubuntu-focal | ok
2026-09-12 00:58:30.488545 | 
2026-09-12 00:58:30.488680 | LOOP [fetch-output : Ensure local output dirs]
2026-09-12 00:58:30.918494 | ubuntu-focal -> localhost | ok: "/var/lib/zuul/builds/551e36dc1703409ea0358ce15c896a5f/work/logs"
2026-09-12 00:58:31.135943 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/551e36dc1703409ea0358ce15c896a5f/work/artifacts"
2026-09-12 00:58:31.364831 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/551e36dc1703409ea0358ce15c896a5f/work/docs"
2026-09-12 00:58:31.382809 | 
2026-09-12 00:58:31.382907 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-12 00:58:31.982522 | ubuntu-focal | changed: .d..t...... ./
2026-09-12 00:58:31.982832 | ubuntu-focal | changed: All items complete
2026-09-12 00:58:31.982902 | 
2026-09-12 00:58:32.434070 | ubuntu-focal | changed: .d..t...... ./
2026-09-12 00:58:32.881033 | ubuntu-focal | changed: .d..t...... ./
2026-09-12 00:58:32.900162 | 
2026-09-12 00:58:32.900304 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-12 00:58:33.350454 | ubuntu-focal -> localhost | ok: Item: artifacts Runtime: 0:00:00.008303
2026-09-12 00:58:33.566054 | ubuntu-focal -> localhost | ok: Item: docs Runtime: 0:00:00.007169
2026-09-12 00:58:33.586192 | 
2026-09-12 00:58:33.586327 | PLAY [all]
2026-09-12 00:58:33.593736 | 
2026-09-12 00:58:33.593817 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-12 00:58:34.063735 | ubuntu-focal | changed
2026-09-12 00:58:34.075144 | 
2026-09-12 00:58:34.075238 | PLAY RECAP
2026-09-12 00:58:34.075321 | ubuntu-focal | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-12 00:58:34.075363 | 
2026-09-12 00:58:34.197676 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-12 00:58:34.200353 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-12 00:58:34.775192 | 
2026-09-12 00:58:34.775392 | PLAY [localhost]
2026-09-12 00:58:34.789431 | 
2026-09-12 00:58:34.789530 | TASK [Generate Zuul manifest]
2026-09-12 00:58:34.809592 | localhost | ok
2026-09-12 00:58:34.830873 | 
2026-09-12 00:58:34.830998 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-12 00:58:35.247681 | localhost | changed
2026-09-12 00:58:35.266488 | 
2026-09-12 00:58:35.266627 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-12 00:58:35.309078 | localhost | ok
2026-09-12 00:58:35.321686 | 
2026-09-12 00:58:35.321806 | TASK [Upload logs]
2026-09-12 00:58:35.342792 | localhost | ok
2026-09-12 00:58:35.410045 | 
2026-09-12 00:58:35.410180 | TASK [Set zuul-log-path fact]
2026-09-12 00:58:35.426677 | localhost | ok
2026-09-12 00:58:35.436426 | 
2026-09-12 00:58:35.436489 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-12 00:58:35.474009 | localhost | ok
2026-09-12 00:58:35.482385 | 
2026-09-12 00:58:35.482467 | TASK [upload-logs : Create log directories]
2026-09-12 00:58:35.923300 | localhost | changed
2026-09-12 00:58:35.930885 | 
2026-09-12 00:58:35.930957 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-12 00:58:36.342724 | localhost -> localhost | ok: Runtime: 0:00:00.004887
2026-09-12 00:58:36.347614 | 
2026-09-12 00:58:36.347683 | TASK [upload-logs : Upload logs to log server]
2026-09-12 00:58:36.844252 | localhost | Output suppressed because no_log was given
2026-09-12 00:58:36.851159 | 
2026-09-12 00:58:36.851284 | LOOP [upload-logs : Compress console log and json output]
2026-09-12 00:58:36.911666 | localhost | skipping: Conditional result was False
2026-09-12 00:58:36.920852 | localhost | skipping: Conditional result was False
2026-09-12 00:58:36.935226 | 
2026-09-12 00:58:36.935432 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-12 00:58:36.976245 | localhost | skipping: Conditional result was False
2026-09-12 00:58:36.976617 | 
2026-09-12 00:58:36.979887 | localhost | skipping: Conditional result was False
2026-09-12 00:58:36.998284 | 
2026-09-12 00:58:36.998492 | LOOP [upload-logs : Upload console log and json output]