2026-09-05 00:56:34.969419 | Job console starting 2026-09-05 00:56:35.003045 | Updating git repos 2026-09-05 00:56:35.026586 | Cloning repos into workspace 2026-09-05 00:56:35.098759 | Restoring repo states 2026-09-05 00:56:35.109838 | Merging changes 2026-09-05 00:56:35.803619 | Checking out repos 2026-09-05 00:56:35.865622 | Preparing playbooks 2026-09-05 00:56:38.521744 | Running Ansible setup 2026-09-05 00:56:41.997044 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-05 00:56:42.558084 | 2026-09-05 00:56:42.558204 | PLAY [localhost] 2026-09-05 00:56:42.565222 | 2026-09-05 00:56:42.565287 | TASK [Gathering Facts] 2026-09-05 00:56:43.513076 | localhost | ok 2026-09-05 00:56:43.522124 | 2026-09-05 00:56:43.522253 | TASK [Setup log path fact] 2026-09-05 00:56:43.543155 | localhost | ok 2026-09-05 00:56:43.555776 | 2026-09-05 00:56:43.555883 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-05 00:56:43.582489 | localhost | ok 2026-09-05 00:56:43.590805 | 2026-09-05 00:56:43.590957 | TASK [emit-job-header : Print job information] 2026-09-05 00:56:43.638062 | # Job Information 2026-09-05 00:56:43.638212 | Ansible Version: 2.16.18 2026-09-05 00:56:43.638242 | Job: ansible-collection-containers-molecule-nerdctl-ubuntu-focal 2026-09-05 00:56:43.638262 | Pipeline: check 2026-09-05 00:56:43.638280 | Executor: 2d72f0692154 2026-09-05 00:56:43.638298 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/149 2026-09-05 00:56:43.638318 | Event ID: b7f4aa30-a8c3-11f1-9178-a13e202f19f2 2026-09-05 00:56:43.640890 | 2026-09-05 00:56:43.640969 | LOOP [emit-job-header : Print node information] 2026-09-05 00:56:43.736803 | localhost | ok: 2026-09-05 00:56:43.737483 | localhost | # Node Information 2026-09-05 00:56:43.737549 | localhost | Inventory Hostname: ubuntu-focal 2026-09-05 00:56:43.737592 | localhost | Hostname: np0000191951 2026-09-05 00:56:43.737631 | localhost | Username: zuul 2026-09-05 00:56:43.737678 | localhost | Distro: Ubuntu 20.04 2026-09-05 00:56:43.737716 | localhost | Provider: yul1 2026-09-05 00:56:43.737753 | localhost | Region: ca-ymq-1 2026-09-05 00:56:43.737789 | localhost | Label: ubuntu-focal 2026-09-05 00:56:43.737825 | localhost | Product Name: OpenStack Nova 2026-09-05 00:56:43.737860 | localhost | Interface IP: 162.253.55.112 2026-09-05 00:56:43.752994 | 2026-09-05 00:56:43.753117 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-05 00:56:44.211863 | localhost -> localhost | changed 2026-09-05 00:56:44.217851 | 2026-09-05 00:56:44.217934 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-05 00:56:45.181456 | localhost -> localhost | changed 2026-09-05 00:56:45.188922 | 2026-09-05 00:56:45.189323 | PLAY [all] 2026-09-05 00:56:45.201037 | 2026-09-05 00:56:45.201244 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-05 00:56:45.443915 | ubuntu-focal -> localhost | ok 2026-09-05 00:56:45.452872 | 2026-09-05 00:56:45.452964 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-05 00:56:45.485895 | ubuntu-focal | ok 2026-09-05 00:56:45.513298 | ubuntu-focal | included: /var/lib/zuul/builds/208300f1b2494bee9b5c0c209787de29/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-05 00:56:45.520247 | 2026-09-05 00:56:45.520331 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-05 00:56:46.844942 | ubuntu-focal -> localhost | Generating public/private rsa key pair. 2026-09-05 00:56:46.845468 | ubuntu-focal -> localhost | Your identification has been saved in /var/lib/zuul/builds/208300f1b2494bee9b5c0c209787de29/work/208300f1b2494bee9b5c0c209787de29_id_rsa 2026-09-05 00:56:46.846608 | ubuntu-focal -> localhost | Your public key has been saved in /var/lib/zuul/builds/208300f1b2494bee9b5c0c209787de29/work/208300f1b2494bee9b5c0c209787de29_id_rsa.pub 2026-09-05 00:56:46.846686 | ubuntu-focal -> localhost | The key fingerprint is: 2026-09-05 00:56:46.846731 | ubuntu-focal -> localhost | SHA256:loffRFwgpJeK2Bb8XG6Y+6r8mDtL+aqMcdkt1nyzWdw zuul-build-sshkey 2026-09-05 00:56:46.846797 | ubuntu-focal -> localhost | The key's randomart image is: 2026-09-05 00:56:46.846837 | ubuntu-focal -> localhost | +---[RSA 3072]----+ 2026-09-05 00:56:46.846892 | ubuntu-focal -> localhost | | .o ... | 2026-09-05 00:56:46.846933 | ubuntu-focal -> localhost | | . . + . | 2026-09-05 00:56:46.846970 | ubuntu-focal -> localhost | | o . + o | 2026-09-05 00:56:46.847006 | ubuntu-focal -> localhost | | o = X . | 2026-09-05 00:56:46.847042 | ubuntu-focal -> localhost | | . + S + . | 2026-09-05 00:56:46.847078 | ubuntu-focal -> localhost | | + * = + . | 2026-09-05 00:56:46.847113 | ubuntu-focal -> localhost | | . o * = + + E | 2026-09-05 00:56:46.847149 | ubuntu-focal -> localhost | | = +.= o = | 2026-09-05 00:56:46.847191 | ubuntu-focal -> localhost | | . o.OB+.+ | 2026-09-05 00:56:46.847228 | ubuntu-focal -> localhost | +----[SHA256]-----+ 2026-09-05 00:56:46.847327 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.826367 2026-09-05 00:56:46.860787 | 2026-09-05 00:56:46.860892 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-05 00:56:46.898353 | ubuntu-focal | ok 2026-09-05 00:56:46.911516 | ubuntu-focal | included: /var/lib/zuul/builds/208300f1b2494bee9b5c0c209787de29/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-05 00:56:46.924574 | 2026-09-05 00:56:46.924640 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-05 00:56:46.939087 | ubuntu-focal | skipping: Conditional result was False 2026-09-05 00:56:46.944864 | 2026-09-05 00:56:46.944927 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-05 00:56:47.415764 | ubuntu-focal | changed 2026-09-05 00:56:47.420264 | 2026-09-05 00:56:47.420321 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-05 00:56:47.588461 | ubuntu-focal | ok 2026-09-05 00:56:47.593943 | 2026-09-05 00:56:47.594201 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-05 00:56:48.039647 | ubuntu-focal | changed 2026-09-05 00:56:48.044036 | 2026-09-05 00:56:48.044097 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-05 00:56:48.471399 | ubuntu-focal | changed 2026-09-05 00:56:48.476057 | 2026-09-05 00:56:48.476118 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-05 00:56:48.499948 | ubuntu-focal | skipping: Conditional result was False 2026-09-05 00:56:48.505195 | 2026-09-05 00:56:48.505261 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-05 00:56:48.854999 | ubuntu-focal -> localhost | changed 2026-09-05 00:56:48.866255 | 2026-09-05 00:56:48.866323 | TASK [add-build-sshkey : Add back temp key] 2026-09-05 00:56:49.162789 | ubuntu-focal -> localhost | Identity added: /var/lib/zuul/builds/208300f1b2494bee9b5c0c209787de29/work/208300f1b2494bee9b5c0c209787de29_id_rsa (zuul-build-sshkey) 2026-09-05 00:56:49.163061 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.014720 2026-09-05 00:56:49.175745 | 2026-09-05 00:56:49.175963 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-05 00:56:49.533699 | ubuntu-focal | ok 2026-09-05 00:56:49.539240 | 2026-09-05 00:56:49.539309 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-05 00:56:49.565014 | ubuntu-focal | skipping: Conditional result was False 2026-09-05 00:56:49.580353 | 2026-09-05 00:56:49.580551 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-05 00:56:49.875236 | ubuntu-focal | ok 2026-09-05 00:56:49.881489 | 2026-09-05 00:56:49.881562 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-05 00:56:50.616889 | ubuntu-focal | Output suppressed because no_log was given 2026-09-05 00:56:50.627259 | 2026-09-05 00:56:50.627340 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-05 00:56:50.860300 | ubuntu-focal | ok: "logs" 2026-09-05 00:56:50.860797 | ubuntu-focal | ok: All items complete 2026-09-05 00:56:50.860827 | 2026-09-05 00:56:51.002606 | ubuntu-focal | ok: "artifacts" 2026-09-05 00:56:51.168008 | ubuntu-focal | ok: "docs" 2026-09-05 00:56:51.179640 | 2026-09-05 00:56:51.179802 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-05 00:56:51.387241 | ubuntu-focal | changed: "logs" 2026-09-05 00:56:51.531086 | ubuntu-focal | changed: "artifacts" 2026-09-05 00:56:51.689943 | ubuntu-focal | changed: "docs" 2026-09-05 00:56:51.709717 | 2026-09-05 00:56:51.709871 | PLAY RECAP 2026-09-05 00:56:51.709954 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-05 00:56:51.710012 | ubuntu-focal | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-05 00:56:51.710054 | 2026-09-05 00:56:51.864695 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-05 00:56:51.865707 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-05 00:56:52.490628 | 2026-09-05 00:56:52.491081 | PLAY [all] 2026-09-05 00:56:52.505452 | 2026-09-05 00:56:52.505547 | TASK [setup-uv : Extract archive] 2026-09-05 00:56:54.851665 | ubuntu-focal | changed 2026-09-05 00:56:54.862819 | 2026-09-05 00:56:54.862988 | TASK [setup-uv : Print version] 2026-09-05 00:56:55.252110 | ubuntu-focal | uv 0.8.13 2026-09-05 00:56:55.419866 | ubuntu-focal | ok: Runtime: 0:00:00.010217 2026-09-05 00:56:55.435792 | 2026-09-05 00:56:55.435934 | TASK [Install Ansible collection siblings] 2026-09-05 00:56:55.870219 | ubuntu-focal | ok: 2026-09-05 00:56:55.870367 | ubuntu-focal | Skipping ansible.posix: no checked-out sibling 2026-09-05 00:56:55.870393 | ubuntu-focal | Skipping community.general: no checked-out sibling 2026-09-05 00:56:55.871746 | 2026-09-05 00:56:55.871785 | PLAY RECAP 2026-09-05 00:56:55.871834 | ubuntu-focal | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-05 00:56:55.871855 | 2026-09-05 00:56:55.992265 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-05 00:56:55.993547 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-05 00:56:56.615862 | 2026-09-05 00:56:56.616007 | PLAY [all] 2026-09-05 00:56:56.627194 | 2026-09-05 00:56:56.627272 | LOOP [Check inventory directories] 2026-09-05 00:56:56.915734 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers" 2026-09-05 00:56:57.075586 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers/molecule/nerdctl/inventory" 2026-09-05 00:56:57.252921 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/nerdctl/inventory" 2026-09-05 00:56:57.262419 | 2026-09-05 00:56:57.262683 | LOOP [Copy inventory file for Zuul] 2026-09-05 00:56:58.121219 | ubuntu-focal | changed: 2026-09-05 00:56:58.121515 | ubuntu-focal | { 2026-09-05 00:56:58.121573 | ubuntu-focal | "ansible_loop_var": "item", 2026-09-05 00:56:58.121619 | ubuntu-focal | "changed": false, 2026-09-05 00:56:58.121657 | ubuntu-focal | "failed": false, 2026-09-05 00:56:58.121707 | ubuntu-focal | "invocation": { 2026-09-05 00:56:58.121745 | ubuntu-focal | "module_args": { 2026-09-05 00:56:58.121781 | ubuntu-focal | "checksum_algorithm": "sha1", 2026-09-05 00:56:58.121816 | ubuntu-focal | "follow": true, 2026-09-05 00:56:58.121851 | ubuntu-focal | "get_attributes": true, 2026-09-05 00:56:58.121886 | ubuntu-focal | "get_checksum": true, 2026-09-05 00:56:58.121920 | ubuntu-focal | "get_mime": true, 2026-09-05 00:56:58.121954 | ubuntu-focal | "path": "src/github.com/vexxhost/ansible-collection-containers" 2026-09-05 00:56:58.121988 | ubuntu-focal | } 2026-09-05 00:56:58.122021 | ubuntu-focal | }, 2026-09-05 00:56:58.122054 | ubuntu-focal | "item": "src/github.com/vexxhost/ansible-collection-containers", 2026-09-05 00:56:58.122100 | ubuntu-focal | "stat": { 2026-09-05 00:56:58.122135 | ubuntu-focal | "atime": 1788569810.5342507, 2026-09-05 00:56:58.122169 | ubuntu-focal | "attr_flags": "e", 2026-09-05 00:56:58.122202 | ubuntu-focal | "attributes": [ 2026-09-05 00:56:58.122235 | ubuntu-focal | "extents" 2026-09-05 00:56:58.122268 | ubuntu-focal | ], 2026-09-05 00:56:58.122301 | ubuntu-focal | "block_size": 4096, 2026-09-05 00:56:58.122333 | ubuntu-focal | "blocks": 8, 2026-09-05 00:56:58.122367 | ubuntu-focal | "charset": "binary", 2026-09-05 00:56:58.122402 | ubuntu-focal | "ctime": 1788569810.5342507, 2026-09-05 00:56:58.122455 | ubuntu-focal | "dev": 64513, 2026-09-05 00:56:58.122491 | ubuntu-focal | "device_type": 0, 2026-09-05 00:56:58.122524 | ubuntu-focal | "executable": true, 2026-09-05 00:56:58.122557 | ubuntu-focal | "exists": true, 2026-09-05 00:56:58.122590 | ubuntu-focal | "gid": 1000, 2026-09-05 00:56:58.122622 | ubuntu-focal | "gr_name": "zuul", 2026-09-05 00:56:58.122654 | ubuntu-focal | "inode": 1035293, 2026-09-05 00:56:58.122687 | ubuntu-focal | "isblk": false, 2026-09-05 00:56:58.122721 | ubuntu-focal | "ischr": false, 2026-09-05 00:56:58.122753 | ubuntu-focal | "isdir": true, 2026-09-05 00:56:58.122785 | ubuntu-focal | "isfifo": false, 2026-09-05 00:56:58.122817 | ubuntu-focal | "isgid": false, 2026-09-05 00:56:58.122849 | ubuntu-focal | "islnk": false, 2026-09-05 00:56:58.122881 | ubuntu-focal | "isreg": false, 2026-09-05 00:56:58.122913 | ubuntu-focal | "issock": false, 2026-09-05 00:56:58.122945 | ubuntu-focal | "isuid": false, 2026-09-05 00:56:58.122977 | ubuntu-focal | "mimetype": "inode/directory", 2026-09-05 00:56:58.123009 | ubuntu-focal | "mode": "0755", 2026-09-05 00:56:58.123040 | ubuntu-focal | "mtime": 1788569795.1292677, 2026-09-05 00:56:58.123072 | ubuntu-focal | "nlink": 11, 2026-09-05 00:56:58.123103 | ubuntu-focal | "path": "src/github.com/vexxhost/ansible-collection-containers", 2026-09-05 00:56:58.123135 | ubuntu-focal | "pw_name": "zuul", 2026-09-05 00:56:58.123168 | ubuntu-focal | "readable": true, 2026-09-05 00:56:58.123200 | ubuntu-focal | "rgrp": true, 2026-09-05 00:56:58.123233 | ubuntu-focal | "roth": true, 2026-09-05 00:56:58.123267 | ubuntu-focal | "rusr": true, 2026-09-05 00:56:58.123300 | ubuntu-focal | "size": 4096, 2026-09-05 00:56:58.123333 | ubuntu-focal | "uid": 1000, 2026-09-05 00:56:58.123366 | ubuntu-focal | "version": "4028602036", 2026-09-05 00:56:58.123399 | ubuntu-focal | "wgrp": false, 2026-09-05 00:56:58.123451 | ubuntu-focal | "woth": false, 2026-09-05 00:56:58.123486 | ubuntu-focal | "writeable": true, 2026-09-05 00:56:58.123519 | ubuntu-focal | "wusr": true, 2026-09-05 00:56:58.123552 | ubuntu-focal | "xgrp": true, 2026-09-05 00:56:58.123584 | ubuntu-focal | "xoth": true, 2026-09-05 00:56:58.123616 | ubuntu-focal | "xusr": true 2026-09-05 00:56:58.123649 | ubuntu-focal | } 2026-09-05 00:56:58.123682 | ubuntu-focal | } 2026-09-05 00:56:58.123750 | ubuntu-focal | ok: All items complete 2026-09-05 00:56:58.123792 | 2026-09-05 00:56:58.126157 | ubuntu-focal | skipping: Conditional result was False 2026-09-05 00:56:58.128223 | ubuntu-focal | skipping: Conditional result was False 2026-09-05 00:56:58.147607 | 2026-09-05 00:56:58.147759 | LOOP [Switch "ansible_host" to private IP] 2026-09-05 00:56:58.493268 | ubuntu-focal | changed: 1 replacements made 2026-09-05 00:56:58.496919 | ubuntu-focal | skipping: Conditional result was False 2026-09-05 00:56:58.498578 | ubuntu-focal | skipping: Conditional result was False 2026-09-05 00:56:58.513870 | 2026-09-05 00:56:58.513993 | TASK [Run Molecule scenario] 2026-09-05 00:56:59.312354 | ubuntu-focal | Downloading cpython-3.13.7-linux-x86_64-gnu (download) (30.9MiB) 2026-09-05 00:57:00.073904 | ubuntu-focal | Downloading cpython-3.13.7-linux-x86_64-gnu (download) 2026-09-05 00:57:00.288983 | ubuntu-focal | Using CPython 3.13.7 2026-09-05 00:57:00.289050 | ubuntu-focal | Creating virtual environment at: .venv 2026-09-05 00:57:00.541220 | ubuntu-focal | Downloading pygments (1.2MiB) 2026-09-05 00:57:00.542443 | ubuntu-focal | Downloading ansible-core (2.3MiB) 2026-09-05 00:57:00.543449 | ubuntu-focal | Downloading cryptography (4.3MiB) 2026-09-05 00:57:00.706289 | ubuntu-focal | Downloading cryptography 2026-09-05 00:57:00.757399 | ubuntu-focal | Downloading pygments 2026-09-05 00:57:00.898069 | ubuntu-focal | Downloading ansible-core 2026-09-05 00:57:00.935750 | ubuntu-focal | Installed 36 packages in 36ms 2026-09-05 00:57:02.032831 | ubuntu-focal | INFO Collection 'vexxhost.containers' detected. 2026-09-05 00:57:02.032956 | ubuntu-focal | INFO Scenarios will be used from 'extensions/molecule' 2026-09-05 00:57:02.813070 | ubuntu-focal | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-09-05 00:57:02.813220 | ubuntu-focal | INFO default scenario not found, disabling shared state. 2026-09-05 00:57:02.813427 | ubuntu-focal | INFO [nerdctl > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-05 00:57:02.813529 | ubuntu-focal | INFO [nerdctl > prerun] Performing prerun with role_name_check=0... 2026-09-05 00:59:12.626045 | ubuntu-focal | WARNING Retrying execution failure 250 of: ansible-galaxy collection install -vvv community.general:>=4.5.0 2026-09-05 00:59:12.627025 | ubuntu-focal | ERROR Command ansible-galaxy collection install -vvv community.general:>=4.5.0, returned 250 code: 2026-09-05 00:59:12.627051 | ubuntu-focal | Starting galaxy collection install process 2026-09-05 00:59:12.627064 | ubuntu-focal | Process install dependency map 2026-09-05 00:59:12.627075 | ubuntu-focal | 2026-09-05 00:59:12.627085 | ubuntu-focal | ansible-galaxy 2026-09-05 00:59:12.627095 | ubuntu-focal | config file = None 2026-09-05 00:59:12.627105 | ubuntu-focal | configured module search path = 2026-09-05 00:59:12.627116 | ubuntu-focal | ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible 2026-09-05 00:59:12.627127 | 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-05 00:59:12.627195 | ubuntu-focal | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/ansible-galaxy 2026-09-05 00:59:12.627207 | 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-05 00:59:12.627217 | ubuntu-focal | jinja version = 3.1.6 2026-09-05 00:59:12.627227 | ubuntu-focal | pyyaml version = 6.0.2 (with libyaml v0.2.5) 2026-09-05 00:59:12.627237 | ubuntu-focal | No config file found; using defaults 2026-09-05 00:59:12.627248 | ubuntu-focal | Found installed collection ansible.posix:2.2.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-09-05 00:59:12.627258 | 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-05 00:59:12.627269 | ubuntu-focal | Opened /home/zuul/.ansible/galaxy_token 2026-09-05 00:59:12.627279 | ubuntu-focal | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an unexpected error when getting available versions of collection community.general: 'results' 2026-09-05 00:59:12.627289 | ubuntu-focal | : Unexpected Exception, this is probably a bug: 'results' 2026-09-05 00:59:12.627299 | ubuntu-focal | 2026-09-05 00:59:12.627309 | ubuntu-focal | Traceback (most recent call last): 2026-09-05 00:59:12.627319 | 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-05 00:59:12.627329 | ubuntu-focal | exit_code = cli.run() 2026-09-05 00:59:12.627339 | 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-05 00:59:12.627372 | ubuntu-focal | return context.CLIARGS() 2026-09-05 00:59:12.627381 | ubuntu-focal | ~~~~~~~~~~~~~~~~~~~~~~~^^ 2026-09-05 00:59:12.627390 | 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-05 00:59:12.627399 | ubuntu-focal | return wrapped_method(*args, **kwargs) 2026-09-05 00:59:12.627408 | 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-05 00:59:12.627417 | ubuntu-focal | self._execute_install_collection( 2026-09-05 00:59:12.627427 | ubuntu-focal | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-05 00:59:12.627436 | ubuntu-focal | collection_requirements, collection_path, 2026-09-05 00:59:12.627445 | ubuntu-focal | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-05 00:59:12.627454 | ubuntu-focal | artifacts_manager=artifacts_manager, 2026-09-05 00:59:12.627463 | ubuntu-focal | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-05 00:59:12.627472 | ubuntu-focal | ) 2026-09-05 00:59:12.627481 | ubuntu-focal | ^ 2026-09-05 00:59:12.627490 | 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-05 00:59:12.627502 | ubuntu-focal | install_collections( 2026-09-05 00:59:12.627511 | ubuntu-focal | ~~~~~~~~~~~~~~~~~~~^ 2026-09-05 00:59:12.627520 | ubuntu-focal | requirements, output_path, self.api_servers, ignore_errors, 2026-09-05 00:59:12.627529 | ubuntu-focal | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-05 00:59:12.627537 | ubuntu-focal | ...<5 lines>... 2026-09-05 00:59:12.627546 | ubuntu-focal | read_requirement_paths=read_req_paths, 2026-09-05 00:59:12.627555 | ubuntu-focal | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-05 00:59:12.627567 | ubuntu-focal | ) 2026-09-05 00:59:12.627580 | ubuntu-focal | ^ 2026-09-05 00:59:12.627602 | 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-05 00:59:12.627616 | ubuntu-focal | dependency_map = _resolve_depenency_map( 2026-09-05 00:59:12.627685 | ubuntu-focal | collections, 2026-09-05 00:59:12.627696 | ubuntu-focal | ...<7 lines>... 2026-09-05 00:59:12.627706 | ubuntu-focal | offline=offline, 2026-09-05 00:59:12.627715 | ubuntu-focal | ) 2026-09-05 00:59:12.627724 | 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-05 00:59:12.627734 | ubuntu-focal | return collection_dep_resolver.resolve( 2026-09-05 00:59:12.627743 | ubuntu-focal | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-05 00:59:12.627752 | ubuntu-focal | requested_requirements, 2026-09-05 00:59:12.627761 | ubuntu-focal | ^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-05 00:59:12.627770 | ubuntu-focal | max_rounds=2000000, # NOTE: same constant pip uses 2026-09-05 00:59:12.627780 | ubuntu-focal | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-05 00:59:12.627789 | ubuntu-focal | ).mapping 2026-09-05 00:59:12.627798 | ubuntu-focal | ^ 2026-09-05 00:59:12.627807 | 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-05 00:59:12.627826 | ubuntu-focal | state = resolution.resolve(requirements, max_rounds=max_rounds) 2026-09-05 00:59:12.627836 | 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-05 00:59:12.627845 | ubuntu-focal | self._add_to_criteria(self.state.criteria, r, parent=None) 2026-09-05 00:59:12.627854 | ubuntu-focal | ~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-05 00:59:12.627863 | 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-05 00:59:12.627872 | ubuntu-focal | matches = self._p.find_matches( 2026-09-05 00:59:12.627880 | ubuntu-focal | identifier=identifier, 2026-09-05 00:59:12.627889 | ubuntu-focal | ...<9 lines>... 2026-09-05 00:59:12.627906 | ubuntu-focal | ), 2026-09-05 00:59:12.627915 | ubuntu-focal | ) 2026-09-05 00:59:12.627924 | 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-05 00:59:12.627932 | ubuntu-focal | match for match in self._find_matches(list(requirements)) 2026-09-05 00:59:12.627941 | ubuntu-focal | ~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-05 00:59:12.627950 | 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-05 00:59:12.627959 | ubuntu-focal | coll_versions = if preinstalled_candidates else self._api_proxy.get_collection_versions(first_req) # type: t.Iterable] 2026-09-05 00:59:12.627968 | ubuntu-focal | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^ 2026-09-05 00:59:12.627977 | 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-05 00:59:12.627986 | ubuntu-focal | return set( 2026-09-05 00:59:12.627995 | ubuntu-focal | (version, api) 2026-09-05 00:59:12.628003 | ubuntu-focal | ...<2 lines>... 2026-09-05 00:59:12.628012 | ubuntu-focal | ) 2026-09-05 00:59:12.628021 | ubuntu-focal | ) 2026-09-05 00:59:12.628030 | 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 2026-09-05 00:59:12.628039 | ubuntu-focal | for api, version in self._get_collection_versions( 2026-09-05 00:59:12.628048 | ubuntu-focal | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-05 00:59:12.628057 | ubuntu-focal | requirement, 2026-09-05 00:59:12.628066 | ubuntu-focal | ^^^^^^^^^^^^ 2026-09-05 00:59:12.628075 | ubuntu-focal | ) 2026-09-05 00:59:12.628084 | ubuntu-focal | ^ 2026-09-05 00:59:12.628093 | 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-05 00:59:12.628102 | ubuntu-focal | raise last_error 2026-09-05 00:59:12.628111 | 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-05 00:59:12.628120 | ubuntu-focal | versions = api.get_collection_versions(requirement.namespace, requirement.name) 2026-09-05 00:59:12.628129 | 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-05 00:59:12.628157 | ubuntu-focal | return method(self, *args, **kwargs) 2026-09-05 00:59:12.628167 | ubuntu-focal | File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 876, in get_collection_versions 2026-09-05 00:59:12.628176 | ubuntu-focal | data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key) 2026-09-05 00:59:12.628190 | 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-05 00:59:12.628200 | ubuntu-focal | return call_retryable_function() 2026-09-05 00:59:12.628209 | 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-05 00:59:12.628218 | ubuntu-focal | res = path_cache 2026-09-05 00:59:12.628227 | ubuntu-focal | ~~~~~~~~~~^^^^^^^^^^^ 2026-09-05 00:59:12.628236 | ubuntu-focal | KeyError: 'results' 2026-09-05 00:59:12.628244 | ubuntu-focal | 2026-09-05 00:59:12.628253 | ubuntu-focal | The above exception was the direct cause of the following exception: 2026-09-05 00:59:12.628262 | ubuntu-focal | 2026-09-05 00:59:12.628273 | ubuntu-focal | Traceback (most recent call last): 2026-09-05 00:59:12.628282 | 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-05 00:59:12.628297 | ubuntu-focal | raise AnsibleError("Unexpected Exception, this is probably a bug.") from ex 2026-09-05 00:59:12.628306 | ubuntu-focal | ansible.errors.AnsibleError: Unexpected Exception, this is probably a bug: 'results' 2026-09-05 00:59:12.628315 | ubuntu-focal | 2026-09-05 00:59:12.628324 | ubuntu-focal | 2026-09-05 00:59:12.633519 | ubuntu-focal | Traceback (most recent call last): 2026-09-05 00:59:12.638220 | ubuntu-focal | File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/molecule", line 10, in 2026-09-05 00:59:12.638243 | ubuntu-focal | sys.exit(main()) 2026-09-05 00:59:12.638251 | ubuntu-focal | ~~~~^^ 2026-09-05 00:59:12.638576 | 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-05 00:59:12.638587 | ubuntu-focal | return self.main(*args, **kwargs) 2026-09-05 00:59:12.638595 | ubuntu-focal | ~~~~~~~~~^^^^^^^^^^^^^^^^^ 2026-09-05 00:59:12.638840 | 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-05 00:59:12.638849 | ubuntu-focal | rv = self.invoke(ctx) 2026-09-05 00:59:12.639219 | 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-05 00:59:12.639230 | ubuntu-focal | return _process_result(sub_ctx.command.invoke(sub_ctx)) 2026-09-05 00:59:12.639238 | ubuntu-focal | ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^ 2026-09-05 00:59:12.639535 | 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-05 00:59:12.639545 | ubuntu-focal | return ctx.invoke(self.callback, **ctx.params) 2026-09-05 00:59:12.639553 | ubuntu-focal | ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-05 00:59:12.639856 | 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-05 00:59:12.639869 | ubuntu-focal | return callback(*args, **kwargs) 2026-09-05 00:59:12.640174 | 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-05 00:59:12.640185 | ubuntu-focal | return func(*args, **kwargs) 2026-09-05 00:59:12.640531 | 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-05 00:59:12.640541 | ubuntu-focal | return f(get_current_context(), *args, **kwargs) 2026-09-05 00:59:12.640827 | 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-05 00:59:12.640842 | ubuntu-focal | return func(ctx) 2026-09-05 00:59:12.641267 | 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-05 00:59:12.641279 | ubuntu-focal | base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude) 2026-09-05 00:59:12.641288 | ubuntu-focal | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-05 00:59:12.641524 | 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-05 00:59:12.641534 | ubuntu-focal | _run_scenarios(scenarios, command_args, default_config) 2026-09-05 00:59:12.641542 | ubuntu-focal | ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-05 00:59:12.641927 | 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-05 00:59:12.641941 | ubuntu-focal | scenario.config.runtime.prepare_environment( 2026-09-05 00:59:12.641949 | ubuntu-focal | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-05 00:59:12.641957 | ubuntu-focal | install_local=True, 2026-09-05 00:59:12.642632 | ubuntu-focal | ^^^^^^^^^^^^^^^^^^^ 2026-09-05 00:59:12.642643 | ubuntu-focal | role_name_check=role_name_check, 2026-09-05 00:59:12.642652 | ubuntu-focal | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-05 00:59:12.642660 | ubuntu-focal | ) 2026-09-05 00:59:12.642669 | ubuntu-focal | ^ 2026-09-05 00:59:12.642704 | 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-05 00:59:12.642714 | ubuntu-focal | self.install_collection( 2026-09-05 00:59:12.642722 | ubuntu-focal | ~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-05 00:59:12.642731 | ubuntu-focal | f"{name}{',' if is_url(name) else ':'}{required_version}", 2026-09-05 00:59:12.642739 | ubuntu-focal | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-05 00:59:12.642748 | ubuntu-focal | destination=destination, 2026-09-05 00:59:12.642757 | ubuntu-focal | ^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-05 00:59:12.642765 | ubuntu-focal | ) 2026-09-05 00:59:12.642773 | ubuntu-focal | ^ 2026-09-05 00:59:12.642785 | 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-05 00:59:12.642793 | ubuntu-focal | raise InvalidPrerequisiteError(msg) 2026-09-05 00:59:12.646753 | ubuntu-focal | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv community.general:>=4.5.0, returned 250 code: 2026-09-05 00:59:12.646782 | ubuntu-focal | Starting galaxy collection install process 2026-09-05 00:59:12.646796 | ubuntu-focal | Process install dependency map 2026-09-05 00:59:12.646808 | ubuntu-focal | 2026-09-05 00:59:12.646817 | ubuntu-focal | ansible-galaxy [core 2.19.1] 2026-09-05 00:59:12.646826 | ubuntu-focal | config file = None 2026-09-05 00:59:12.646834 | ubuntu-focal | configured module search path = ['/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules'] 2026-09-05 00:59:12.646842 | ubuntu-focal | ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible 2026-09-05 00:59:12.646851 | 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-05 00:59:12.646859 | ubuntu-focal | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/ansible-galaxy 2026-09-05 00:59:12.646867 | 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-05 00:59:12.646878 | ubuntu-focal | jinja version = 3.1.6 2026-09-05 00:59:12.646891 | ubuntu-focal | pyyaml version = 6.0.2 (with libyaml v0.2.5) 2026-09-05 00:59:12.646900 | ubuntu-focal | No config file found; using defaults 2026-09-05 00:59:12.646909 | ubuntu-focal | Found installed collection ansible.posix:2.2.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-09-05 00:59:12.646917 | 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-05 00:59:12.646926 | ubuntu-focal | Opened /home/zuul/.ansible/galaxy_token 2026-09-05 00:59:12.646937 | ubuntu-focal | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an unexpected error when getting available versions of collection community.general: 'results' 2026-09-05 00:59:12.646945 | ubuntu-focal | [ERROR]: Unexpected Exception, this is probably a bug: 'results' 2026-09-05 00:59:12.646953 | ubuntu-focal | 2026-09-05 00:59:12.646965 | ubuntu-focal | Traceback (most recent call last): 2026-09-05 00:59:12.646978 | 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-05 00:59:12.646988 | ubuntu-focal | exit_code = cli.run() 2026-09-05 00:59:12.646997 | 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-05 00:59:12.647005 | ubuntu-focal | return context.CLIARGS['func']() 2026-09-05 00:59:12.647028 | ubuntu-focal | ~~~~~~~~~~~~~~~~~~~~~~~^^ 2026-09-05 00:59:12.647037 | 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-05 00:59:12.647048 | ubuntu-focal | return wrapped_method(*args, **kwargs) 2026-09-05 00:59:12.647060 | 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-05 00:59:12.647070 | ubuntu-focal | self._execute_install_collection( 2026-09-05 00:59:12.647078 | ubuntu-focal | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-05 00:59:12.647086 | ubuntu-focal | collection_requirements, collection_path, 2026-09-05 00:59:12.647094 | ubuntu-focal | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-05 00:59:12.647102 | ubuntu-focal | artifacts_manager=artifacts_manager, 2026-09-05 00:59:12.647115 | ubuntu-focal | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-05 00:59:12.647124 | ubuntu-focal | ) 2026-09-05 00:59:12.647136 | ubuntu-focal | ^ 2026-09-05 00:59:12.647170 | 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-05 00:59:12.647178 | ubuntu-focal | install_collections( 2026-09-05 00:59:12.647187 | ubuntu-focal | ~~~~~~~~~~~~~~~~~~~^ 2026-09-05 00:59:12.647195 | ubuntu-focal | requirements, output_path, self.api_servers, ignore_errors, 2026-09-05 00:59:12.647204 | ubuntu-focal | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-05 00:59:12.647212 | ubuntu-focal | ...<5 lines>... 2026-09-05 00:59:12.647220 | ubuntu-focal | read_requirement_paths=read_req_paths, 2026-09-05 00:59:12.647231 | ubuntu-focal | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-05 00:59:12.647244 | ubuntu-focal | ) 2026-09-05 00:59:12.647257 | ubuntu-focal | ^ 2026-09-05 00:59:12.647266 | 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-05 00:59:12.647276 | ubuntu-focal | dependency_map = _resolve_depenency_map( 2026-09-05 00:59:12.647285 | ubuntu-focal | collections, 2026-09-05 00:59:12.647294 | ubuntu-focal | ...<7 lines>... 2026-09-05 00:59:12.647303 | ubuntu-focal | offline=offline, 2026-09-05 00:59:12.647312 | ubuntu-focal | ) 2026-09-05 00:59:12.647326 | 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-05 00:59:12.647339 | ubuntu-focal | return collection_dep_resolver.resolve( 2026-09-05 00:59:12.647349 | ubuntu-focal | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-05 00:59:12.647358 | ubuntu-focal | requested_requirements, 2026-09-05 00:59:12.647367 | ubuntu-focal | ^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-05 00:59:12.647376 | ubuntu-focal | max_rounds=2000000, # NOTE: same constant pip uses 2026-09-05 00:59:12.647385 | ubuntu-focal | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-05 00:59:12.647395 | ubuntu-focal | ).mapping 2026-09-05 00:59:12.647406 | ubuntu-focal | ^ 2026-09-05 00:59:12.647430 | 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-05 00:59:12.647440 | ubuntu-focal | state = resolution.resolve(requirements, max_rounds=max_rounds) 2026-09-05 00:59:12.647450 | 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-05 00:59:12.647459 | ubuntu-focal | self._add_to_criteria(self.state.criteria, r, parent=None) 2026-09-05 00:59:12.647468 | ubuntu-focal | ~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-05 00:59:12.647478 | 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-05 00:59:12.647486 | ubuntu-focal | matches = self._p.find_matches( 2026-09-05 00:59:12.647498 | ubuntu-focal | identifier=identifier, 2026-09-05 00:59:12.647509 | ubuntu-focal | ...<9 lines>... 2026-09-05 00:59:12.647519 | ubuntu-focal | ), 2026-09-05 00:59:12.647534 | ubuntu-focal | ) 2026-09-05 00:59:12.647542 | 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-05 00:59:12.647551 | ubuntu-focal | match for match in self._find_matches(list(requirements[identifier])) 2026-09-05 00:59:12.647559 | ubuntu-focal | ~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-05 00:59:12.647567 | 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-05 00:59:12.647577 | 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-05 00:59:12.647589 | ubuntu-focal | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^ 2026-09-05 00:59:12.647600 | 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-05 00:59:12.647609 | ubuntu-focal | return set( 2026-09-05 00:59:12.647617 | ubuntu-focal | (version, api) 2026-09-05 00:59:12.647646 | ubuntu-focal | ...<2 lines>... 2026-09-05 00:59:12.647659 | ubuntu-focal | ) 2026-09-05 00:59:12.647670 | ubuntu-focal | ) 2026-09-05 00:59:12.647682 | 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 2026-09-05 00:59:12.647692 | ubuntu-focal | for api, version in self._get_collection_versions( 2026-09-05 00:59:12.647700 | ubuntu-focal | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-05 00:59:12.647708 | ubuntu-focal | requirement, 2026-09-05 00:59:12.647716 | ubuntu-focal | ^^^^^^^^^^^^ 2026-09-05 00:59:12.647724 | ubuntu-focal | ) 2026-09-05 00:59:12.647732 | ubuntu-focal | ^ 2026-09-05 00:59:12.647740 | 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-05 00:59:12.647750 | ubuntu-focal | raise last_error 2026-09-05 00:59:12.647762 | 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-05 00:59:12.647774 | ubuntu-focal | versions = api.get_collection_versions(requirement.namespace, requirement.name) 2026-09-05 00:59:12.647782 | 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-05 00:59:12.647795 | ubuntu-focal | return method(self, *args, **kwargs) 2026-09-05 00:59:12.647804 | ubuntu-focal | File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 876, in get_collection_versions 2026-09-05 00:59:12.647812 | ubuntu-focal | data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key) 2026-09-05 00:59:12.647821 | 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-05 00:59:12.647829 | ubuntu-focal | return call_retryable_function() 2026-09-05 00:59:12.647840 | 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-05 00:59:12.647852 | ubuntu-focal | res = path_cache['results'] 2026-09-05 00:59:12.647862 | ubuntu-focal | ~~~~~~~~~~^^^^^^^^^^^ 2026-09-05 00:59:12.647870 | ubuntu-focal | KeyError: 'results' 2026-09-05 00:59:12.647878 | ubuntu-focal | 2026-09-05 00:59:12.647886 | ubuntu-focal | The above exception was the direct cause of the following exception: 2026-09-05 00:59:12.647894 | ubuntu-focal | 2026-09-05 00:59:12.647903 | ubuntu-focal | Traceback (most recent call last): 2026-09-05 00:59:12.647911 | 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-05 00:59:12.647929 | ubuntu-focal | raise AnsibleError("Unexpected Exception, this is probably a bug.") from ex 2026-09-05 00:59:12.647941 | ubuntu-focal | ansible.errors.AnsibleError: Unexpected Exception, this is probably a bug: 'results' 2026-09-05 00:59:12.647951 | ubuntu-focal | 2026-09-05 00:59:12.647959 | ubuntu-focal | 2026-09-05 00:59:13.265184 | ubuntu-focal | ERROR 2026-09-05 00:59:13.265462 | ubuntu-focal | { 2026-09-05 00:59:13.265520 | ubuntu-focal | "delta": "0:02:13.893652", 2026-09-05 00:59:13.265560 | ubuntu-focal | "end": "2026-09-05 00:59:12.770462", 2026-09-05 00:59:13.265596 | ubuntu-focal | "msg": "non-zero return code", 2026-09-05 00:59:13.265631 | ubuntu-focal | "rc": 1, 2026-09-05 00:59:13.265665 | ubuntu-focal | "start": "2026-09-05 00:56:58.876810" 2026-09-05 00:59:13.265700 | ubuntu-focal | } failure 2026-09-05 00:59:13.274212 | 2026-09-05 00:59:13.274310 | PLAY RECAP 2026-09-05 00:59:13.274409 | ubuntu-focal | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-05 00:59:13.274453 | 2026-09-05 00:59:13.391351 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-05 00:59:13.394889 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-05 00:59:14.024598 | 2026-09-05 00:59:14.024780 | PLAY [all] 2026-09-05 00:59:14.036742 | 2026-09-05 00:59:14.036820 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-05 00:59:14.093715 | ubuntu-focal | skipping: Conditional result was False 2026-09-05 00:59:14.101439 | 2026-09-05 00:59:14.101552 | TASK [fetch-output : Set log path for single node] 2026-09-05 00:59:14.148366 | ubuntu-focal | ok 2026-09-05 00:59:14.157865 | 2026-09-05 00:59:14.157956 | LOOP [fetch-output : Ensure local output dirs] 2026-09-05 00:59:14.562138 | ubuntu-focal -> localhost | ok: "/var/lib/zuul/builds/208300f1b2494bee9b5c0c209787de29/work/logs" 2026-09-05 00:59:14.798478 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/208300f1b2494bee9b5c0c209787de29/work/artifacts" 2026-09-05 00:59:15.049302 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/208300f1b2494bee9b5c0c209787de29/work/docs" 2026-09-05 00:59:15.070653 | 2026-09-05 00:59:15.070883 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-05 00:59:15.747313 | ubuntu-focal | ok 2026-09-05 00:59:15.747547 | ubuntu-focal | changed: All items complete 2026-09-05 00:59:15.747580 | 2026-09-05 00:59:16.177412 | ubuntu-focal | changed: .d..t...... ./ 2026-09-05 00:59:16.622096 | ubuntu-focal | changed: .d..t...... ./ 2026-09-05 00:59:16.652967 | 2026-09-05 00:59:16.653398 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-05 00:59:17.091105 | ubuntu-focal -> localhost | ok: Item: artifacts Runtime: 0:00:00.008189 2026-09-05 00:59:17.330744 | ubuntu-focal -> localhost | ok: Item: docs Runtime: 0:00:00.007798 2026-09-05 00:59:17.349285 | 2026-09-05 00:59:17.349517 | PLAY [all] 2026-09-05 00:59:17.357986 | 2026-09-05 00:59:17.358071 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-05 00:59:17.816967 | ubuntu-focal | changed 2026-09-05 00:59:17.828264 | 2026-09-05 00:59:17.828362 | PLAY RECAP 2026-09-05 00:59:17.828479 | ubuntu-focal | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-05 00:59:17.828651 | 2026-09-05 00:59:18.011016 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-05 00:59:18.012151 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-05 00:59:18.574628 | 2026-09-05 00:59:18.574788 | PLAY [localhost] 2026-09-05 00:59:18.585283 | 2026-09-05 00:59:18.585353 | TASK [Generate Zuul manifest] 2026-09-05 00:59:18.603495 | localhost | ok 2026-09-05 00:59:18.617312 | 2026-09-05 00:59:18.617416 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-05 00:59:19.043889 | localhost | changed 2026-09-05 00:59:19.067346 | 2026-09-05 00:59:19.067527 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-05 00:59:19.112603 | localhost | ok 2026-09-05 00:59:19.125285 | 2026-09-05 00:59:19.125434 | TASK [Upload logs] 2026-09-05 00:59:19.147553 | localhost | ok 2026-09-05 00:59:19.219136 | 2026-09-05 00:59:19.219316 | TASK [Set zuul-log-path fact] 2026-09-05 00:59:19.237389 | localhost | ok 2026-09-05 00:59:19.248317 | 2026-09-05 00:59:19.248509 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-05 00:59:19.277982 | localhost | ok 2026-09-05 00:59:19.286490 | 2026-09-05 00:59:19.286585 | TASK [upload-logs : Create log directories] 2026-09-05 00:59:19.714364 | localhost | changed 2026-09-05 00:59:19.725216 | 2026-09-05 00:59:19.725364 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-05 00:59:20.175566 | localhost -> localhost | ok: Runtime: 0:00:00.004442 2026-09-05 00:59:20.185077 | 2026-09-05 00:59:20.185198 | TASK [upload-logs : Upload logs to log server] 2026-09-05 00:59:20.616207 | localhost | Output suppressed because no_log was given 2026-09-05 00:59:20.624271 | 2026-09-05 00:59:20.624424 | LOOP [upload-logs : Compress console log and json output] 2026-09-05 00:59:20.687057 | localhost | skipping: Conditional result was False 2026-09-05 00:59:20.695967 | localhost | skipping: Conditional result was False 2026-09-05 00:59:20.707774 | 2026-09-05 00:59:20.707936 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-05 00:59:20.765098 | localhost | skipping: Conditional result was False 2026-09-05 00:59:20.765446 | 2026-09-05 00:59:20.770779 | localhost | skipping: Conditional result was False 2026-09-05 00:59:20.780093 | 2026-09-05 00:59:20.780245 | LOOP [upload-logs : Upload console log and json output]