2026-08-12 01:01:19.771665 | Job console starting 2026-08-12 01:01:19.785537 | Updating git repos 2026-08-12 01:01:19.814882 | Cloning repos into workspace 2026-08-12 01:01:20.264539 | Restoring repo states 2026-08-12 01:01:20.272653 | Merging changes 2026-08-12 01:01:20.837695 | Checking out repos 2026-08-12 01:01:20.874075 | Preparing playbooks 2026-08-12 01:01:22.370907 | Running Ansible setup 2026-08-12 01:01:25.907511 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-08-12 01:01:26.500566 | 2026-08-12 01:01:26.500680 | PLAY [localhost] 2026-08-12 01:01:26.507771 | 2026-08-12 01:01:26.507837 | TASK [Gathering Facts] 2026-08-12 01:01:27.345092 | localhost | ok 2026-08-12 01:01:27.356645 | 2026-08-12 01:01:27.356781 | TASK [Setup log path fact] 2026-08-12 01:01:27.375196 | localhost | ok 2026-08-12 01:01:27.392006 | 2026-08-12 01:01:27.392151 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-12 01:01:27.422064 | localhost | ok 2026-08-12 01:01:27.432776 | 2026-08-12 01:01:27.433109 | TASK [emit-job-header : Print job information] 2026-08-12 01:01:27.466020 | # Job Information 2026-08-12 01:01:27.466173 | Ansible Version: 2.16.18 2026-08-12 01:01:27.466207 | Job: ansible-collection-containers-molecule-docker-ubuntu-jammy 2026-08-12 01:01:27.466230 | Pipeline: check 2026-08-12 01:01:27.466251 | Executor: 2d72f0692154 2026-08-12 01:01:27.466271 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/141 2026-08-12 01:01:27.466293 | Event ID: 9d5b1ec0-95e5-11f1-83a5-16991d845851 2026-08-12 01:01:27.477467 | 2026-08-12 01:01:27.477672 | LOOP [emit-job-header : Print node information] 2026-08-12 01:01:27.574197 | localhost | ok: 2026-08-12 01:01:27.574378 | localhost | # Node Information 2026-08-12 01:01:27.574419 | localhost | Inventory Hostname: ubuntu-jammy 2026-08-12 01:01:27.574447 | localhost | Hostname: np0000188842 2026-08-12 01:01:27.574471 | localhost | Username: zuul 2026-08-12 01:01:27.574499 | localhost | Distro: Ubuntu 22.04 2026-08-12 01:01:27.574524 | localhost | Provider: yul1 2026-08-12 01:01:27.574547 | localhost | Region: ca-ymq-1 2026-08-12 01:01:27.574570 | localhost | Label: ubuntu-jammy 2026-08-12 01:01:27.574593 | localhost | Product Name: OpenStack Nova 2026-08-12 01:01:27.574615 | localhost | Interface IP: 199.19.213.60 2026-08-12 01:01:27.583582 | 2026-08-12 01:01:27.583742 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-12 01:01:27.982814 | localhost -> localhost | changed 2026-08-12 01:01:27.993247 | 2026-08-12 01:01:27.993340 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-12 01:01:28.825661 | localhost -> localhost | changed 2026-08-12 01:01:28.835018 | 2026-08-12 01:01:28.835071 | PLAY [all] 2026-08-12 01:01:28.842688 | 2026-08-12 01:01:28.842754 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-12 01:01:29.088748 | ubuntu-jammy -> localhost | ok 2026-08-12 01:01:29.097675 | 2026-08-12 01:01:29.097769 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-12 01:01:29.131892 | ubuntu-jammy | ok 2026-08-12 01:01:29.146610 | ubuntu-jammy | included: /var/lib/zuul/builds/7ff8631035b540eaa0a00f3454ee7221/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-12 01:01:29.152932 | 2026-08-12 01:01:29.153013 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-12 01:01:31.355693 | ubuntu-jammy -> localhost | Generating public/private rsa key pair. 2026-08-12 01:01:31.355961 | ubuntu-jammy -> localhost | Your identification has been saved in /var/lib/zuul/builds/7ff8631035b540eaa0a00f3454ee7221/work/7ff8631035b540eaa0a00f3454ee7221_id_rsa 2026-08-12 01:01:31.356010 | ubuntu-jammy -> localhost | Your public key has been saved in /var/lib/zuul/builds/7ff8631035b540eaa0a00f3454ee7221/work/7ff8631035b540eaa0a00f3454ee7221_id_rsa.pub 2026-08-12 01:01:31.356044 | ubuntu-jammy -> localhost | The key fingerprint is: 2026-08-12 01:01:31.356075 | ubuntu-jammy -> localhost | SHA256:nCETHQBH5G2oqQyAErStsF8mGQrNV8C+m6cqOzorkl4 zuul-build-sshkey 2026-08-12 01:01:31.356131 | ubuntu-jammy -> localhost | The key's randomart image is: 2026-08-12 01:01:31.356164 | ubuntu-jammy -> localhost | +---[RSA 3072]----+ 2026-08-12 01:01:31.356202 | ubuntu-jammy -> localhost | |o. .o=Bo.. | 2026-08-12 01:01:31.356233 | ubuntu-jammy -> localhost | |.+o .+ +. | 2026-08-12 01:01:31.356262 | ubuntu-jammy -> localhost | |*.+o. = + | 2026-08-12 01:01:31.356291 | ubuntu-jammy -> localhost | |=o.+.o = o | 2026-08-12 01:01:31.356319 | ubuntu-jammy -> localhost | |+.o =. S | 2026-08-12 01:01:31.356347 | ubuntu-jammy -> localhost | | + =. | 2026-08-12 01:01:31.356375 | ubuntu-jammy -> localhost | | .+E o | 2026-08-12 01:01:31.356403 | ubuntu-jammy -> localhost | |B . o . | 2026-08-12 01:01:31.356435 | ubuntu-jammy -> localhost | |OB...o | 2026-08-12 01:01:31.356464 | ubuntu-jammy -> localhost | +----[SHA256]-----+ 2026-08-12 01:01:31.356539 | ubuntu-jammy -> localhost | ok: Runtime: 0:00:01.794922 2026-08-12 01:01:31.364685 | 2026-08-12 01:01:31.364756 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-12 01:01:31.399872 | ubuntu-jammy | ok 2026-08-12 01:01:31.409174 | ubuntu-jammy | included: /var/lib/zuul/builds/7ff8631035b540eaa0a00f3454ee7221/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-12 01:01:31.416773 | 2026-08-12 01:01:31.416838 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-12 01:01:31.442501 | ubuntu-jammy | skipping: Conditional result was False 2026-08-12 01:01:31.453806 | 2026-08-12 01:01:31.453903 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-12 01:01:31.906453 | ubuntu-jammy | changed 2026-08-12 01:01:31.914555 | 2026-08-12 01:01:31.914695 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-12 01:01:32.100021 | ubuntu-jammy | ok 2026-08-12 01:01:32.106895 | 2026-08-12 01:01:32.107039 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-12 01:01:32.585630 | ubuntu-jammy | changed 2026-08-12 01:01:32.592222 | 2026-08-12 01:01:32.592301 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-12 01:01:33.076620 | ubuntu-jammy | changed 2026-08-12 01:01:33.083248 | 2026-08-12 01:01:33.083332 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-12 01:01:33.108475 | ubuntu-jammy | skipping: Conditional result was False 2026-08-12 01:01:33.120267 | 2026-08-12 01:01:33.120388 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-12 01:01:33.481818 | ubuntu-jammy -> localhost | changed 2026-08-12 01:01:33.496123 | 2026-08-12 01:01:33.496218 | TASK [add-build-sshkey : Add back temp key] 2026-08-12 01:01:33.794587 | ubuntu-jammy -> localhost | Identity added: /var/lib/zuul/builds/7ff8631035b540eaa0a00f3454ee7221/work/7ff8631035b540eaa0a00f3454ee7221_id_rsa (zuul-build-sshkey) 2026-08-12 01:01:33.794817 | ubuntu-jammy -> localhost | ok: Runtime: 0:00:00.014869 2026-08-12 01:01:33.802046 | 2026-08-12 01:01:33.802138 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-12 01:01:34.159319 | ubuntu-jammy | ok 2026-08-12 01:01:34.166571 | 2026-08-12 01:01:34.166725 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-12 01:01:34.192732 | ubuntu-jammy | skipping: Conditional result was False 2026-08-12 01:01:34.209562 | 2026-08-12 01:01:34.209799 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-08-12 01:01:34.553195 | ubuntu-jammy | ok 2026-08-12 01:01:34.559184 | 2026-08-12 01:01:34.559265 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-08-12 01:01:35.283487 | ubuntu-jammy | Output suppressed because no_log was given 2026-08-12 01:01:35.294009 | 2026-08-12 01:01:35.294081 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-08-12 01:01:35.491613 | ubuntu-jammy | ok: "logs" 2026-08-12 01:01:35.491922 | ubuntu-jammy | ok: All items complete 2026-08-12 01:01:35.491980 | 2026-08-12 01:01:35.746380 | ubuntu-jammy | ok: "artifacts" 2026-08-12 01:01:35.832127 | ubuntu-jammy | ok: "docs" 2026-08-12 01:01:35.845800 | 2026-08-12 01:01:35.845891 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-08-12 01:01:36.046275 | ubuntu-jammy | changed: "logs" 2026-08-12 01:01:37.429709 | ubuntu-jammy | changed: "artifacts" 2026-08-12 01:01:37.584091 | ubuntu-jammy | changed: "docs" 2026-08-12 01:01:37.604159 | 2026-08-12 01:01:37.604251 | PLAY RECAP 2026-08-12 01:01:37.604325 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-12 01:01:37.604369 | ubuntu-jammy | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-08-12 01:01:37.604400 | 2026-08-12 01:01:37.797762 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-08-12 01:01:37.800278 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-08-12 01:01:38.430794 | 2026-08-12 01:01:38.430908 | PLAY [all] 2026-08-12 01:01:38.441955 | 2026-08-12 01:01:38.442041 | TASK [setup-uv : Extract archive] 2026-08-12 01:01:40.665063 | ubuntu-jammy | changed 2026-08-12 01:01:40.673383 | 2026-08-12 01:01:40.673513 | TASK [setup-uv : Print version] 2026-08-12 01:01:41.054931 | ubuntu-jammy | uv 0.8.13 2026-08-12 01:01:41.210789 | ubuntu-jammy | ok: Runtime: 0:00:00.011072 2026-08-12 01:01:41.224090 | 2026-08-12 01:01:41.224226 | TASK [Install Ansible collection siblings] 2026-08-12 01:01:41.595136 | ubuntu-jammy | ok: 2026-08-12 01:01:41.595389 | ubuntu-jammy | Skipping ansible.posix: no checked-out sibling 2026-08-12 01:01:41.595451 | ubuntu-jammy | Skipping community.general: no checked-out sibling 2026-08-12 01:01:41.599538 | 2026-08-12 01:01:41.599588 | PLAY RECAP 2026-08-12 01:01:41.599633 | ubuntu-jammy | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-12 01:01:41.599655 | 2026-08-12 01:01:41.716713 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-08-12 01:01:41.719490 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-08-12 01:01:42.356400 | 2026-08-12 01:01:42.356532 | PLAY [all] 2026-08-12 01:01:42.368126 | 2026-08-12 01:01:42.368235 | LOOP [Check inventory directories] 2026-08-12 01:01:42.645118 | ubuntu-jammy | ok: "src/github.com/vexxhost/ansible-collection-containers" 2026-08-12 01:01:42.802838 | ubuntu-jammy | ok: "src/github.com/vexxhost/ansible-collection-containers/molecule/docker/inventory" 2026-08-12 01:01:42.960588 | ubuntu-jammy | ok: "src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/docker/inventory" 2026-08-12 01:01:42.977139 | 2026-08-12 01:01:42.977331 | LOOP [Copy inventory file for Zuul] 2026-08-12 01:01:43.772360 | ubuntu-jammy | changed: 2026-08-12 01:01:43.772572 | ubuntu-jammy | { 2026-08-12 01:01:43.772615 | ubuntu-jammy | "ansible_loop_var": "item", 2026-08-12 01:01:43.772649 | ubuntu-jammy | "changed": false, 2026-08-12 01:01:43.772679 | ubuntu-jammy | "failed": false, 2026-08-12 01:01:43.772717 | ubuntu-jammy | "invocation": { 2026-08-12 01:01:43.772746 | ubuntu-jammy | "module_args": { 2026-08-12 01:01:43.772775 | ubuntu-jammy | "checksum_algorithm": "sha1", 2026-08-12 01:01:43.772802 | ubuntu-jammy | "follow": true, 2026-08-12 01:01:43.772829 | ubuntu-jammy | "get_attributes": true, 2026-08-12 01:01:43.772857 | ubuntu-jammy | "get_checksum": true, 2026-08-12 01:01:43.772884 | ubuntu-jammy | "get_mime": true, 2026-08-12 01:01:43.772911 | ubuntu-jammy | "path": "src/github.com/vexxhost/ansible-collection-containers" 2026-08-12 01:01:43.772938 | ubuntu-jammy | } 2026-08-12 01:01:43.772989 | ubuntu-jammy | }, 2026-08-12 01:01:43.773017 | ubuntu-jammy | "item": "src/github.com/vexxhost/ansible-collection-containers", 2026-08-12 01:01:43.773055 | ubuntu-jammy | "stat": { 2026-08-12 01:01:43.773082 | ubuntu-jammy | "atime": 1786496495.179569, 2026-08-12 01:01:43.773168 | ubuntu-jammy | "attr_flags": "e", 2026-08-12 01:01:43.773198 | ubuntu-jammy | "attributes": [ 2026-08-12 01:01:43.773224 | ubuntu-jammy | "extents" 2026-08-12 01:01:43.773250 | ubuntu-jammy | ], 2026-08-12 01:01:43.773276 | ubuntu-jammy | "block_size": 4096, 2026-08-12 01:01:43.773303 | ubuntu-jammy | "blocks": 8, 2026-08-12 01:01:43.773329 | ubuntu-jammy | "charset": "unknown", 2026-08-12 01:01:43.773357 | ubuntu-jammy | "ctime": 1786496495.1835692, 2026-08-12 01:01:43.773384 | ubuntu-jammy | "dev": 64513, 2026-08-12 01:01:43.773410 | ubuntu-jammy | "device_type": 0, 2026-08-12 01:01:43.773436 | ubuntu-jammy | "executable": true, 2026-08-12 01:01:43.773462 | ubuntu-jammy | "exists": true, 2026-08-12 01:01:43.773488 | ubuntu-jammy | "gid": 1000, 2026-08-12 01:01:43.773515 | ubuntu-jammy | "gr_name": "zuul", 2026-08-12 01:01:43.773540 | ubuntu-jammy | "inode": 1042461, 2026-08-12 01:01:43.773566 | ubuntu-jammy | "isblk": false, 2026-08-12 01:01:43.773592 | ubuntu-jammy | "ischr": false, 2026-08-12 01:01:43.773618 | ubuntu-jammy | "isdir": true, 2026-08-12 01:01:43.773644 | ubuntu-jammy | "isfifo": false, 2026-08-12 01:01:43.773670 | ubuntu-jammy | "isgid": false, 2026-08-12 01:01:43.773691 | ubuntu-jammy | "islnk": false, 2026-08-12 01:01:43.773711 | ubuntu-jammy | "isreg": false, 2026-08-12 01:01:43.773730 | ubuntu-jammy | "issock": false, 2026-08-12 01:01:43.773748 | ubuntu-jammy | "isuid": false, 2026-08-12 01:01:43.773765 | ubuntu-jammy | "mimetype": "unknown", 2026-08-12 01:01:43.773782 | ubuntu-jammy | "mode": "0755", 2026-08-12 01:01:43.773800 | ubuntu-jammy | "mtime": 1786496480.80691, 2026-08-12 01:01:43.773817 | ubuntu-jammy | "nlink": 11, 2026-08-12 01:01:43.773835 | ubuntu-jammy | "path": "src/github.com/vexxhost/ansible-collection-containers", 2026-08-12 01:01:43.773852 | ubuntu-jammy | "pw_name": "zuul", 2026-08-12 01:01:43.773873 | ubuntu-jammy | "readable": true, 2026-08-12 01:01:43.773891 | ubuntu-jammy | "rgrp": true, 2026-08-12 01:01:43.773908 | ubuntu-jammy | "roth": true, 2026-08-12 01:01:43.773925 | ubuntu-jammy | "rusr": true, 2026-08-12 01:01:43.773943 | ubuntu-jammy | "size": 4096, 2026-08-12 01:01:43.773960 | ubuntu-jammy | "uid": 1000, 2026-08-12 01:01:43.773977 | ubuntu-jammy | "version": "3910029072", 2026-08-12 01:01:43.773995 | ubuntu-jammy | "wgrp": false, 2026-08-12 01:01:43.774014 | ubuntu-jammy | "woth": false, 2026-08-12 01:01:43.774032 | ubuntu-jammy | "writeable": true, 2026-08-12 01:01:43.774050 | ubuntu-jammy | "wusr": true, 2026-08-12 01:01:43.774068 | ubuntu-jammy | "xgrp": true, 2026-08-12 01:01:43.774085 | ubuntu-jammy | "xoth": true, 2026-08-12 01:01:43.774102 | ubuntu-jammy | "xusr": true 2026-08-12 01:01:43.774120 | ubuntu-jammy | } 2026-08-12 01:01:43.774138 | ubuntu-jammy | } 2026-08-12 01:01:43.774171 | ubuntu-jammy | ok: All items complete 2026-08-12 01:01:43.774193 | 2026-08-12 01:01:43.775439 | ubuntu-jammy | skipping: Conditional result was False 2026-08-12 01:01:43.776018 | ubuntu-jammy | skipping: Conditional result was False 2026-08-12 01:01:43.792291 | 2026-08-12 01:01:43.792429 | LOOP [Switch "ansible_host" to private IP] 2026-08-12 01:01:44.120052 | ubuntu-jammy | changed: 1 replacements made 2026-08-12 01:01:44.121801 | ubuntu-jammy | skipping: Conditional result was False 2026-08-12 01:01:44.122746 | ubuntu-jammy | skipping: Conditional result was False 2026-08-12 01:01:44.141447 | 2026-08-12 01:01:44.141668 | TASK [Run Molecule scenario] 2026-08-12 01:01:44.685841 | ubuntu-jammy | Downloading cpython-3.13.7-linux-x86_64-gnu (download) (30.9MiB) 2026-08-12 01:01:45.489803 | ubuntu-jammy | Downloading cpython-3.13.7-linux-x86_64-gnu (download) 2026-08-12 01:01:45.666145 | ubuntu-jammy | Using CPython 3.13.7 2026-08-12 01:01:45.666226 | ubuntu-jammy | Creating virtual environment at: .venv 2026-08-12 01:01:45.726251 | ubuntu-jammy | Downloading cryptography (4.3MiB) 2026-08-12 01:01:45.727043 | ubuntu-jammy | Downloading ansible-core (2.3MiB) 2026-08-12 01:01:45.728484 | ubuntu-jammy | Downloading pygments (1.2MiB) 2026-08-12 01:01:45.885648 | ubuntu-jammy | Downloading cryptography 2026-08-12 01:01:45.933150 | ubuntu-jammy | Downloading pygments 2026-08-12 01:01:46.107763 | ubuntu-jammy | Downloading ansible-core 2026-08-12 01:01:46.142586 | ubuntu-jammy | Installed 36 packages in 34ms 2026-08-12 01:01:47.267547 | ubuntu-jammy | INFO Collection 'vexxhost.containers' detected. 2026-08-12 01:01:47.267618 | ubuntu-jammy | INFO Scenarios will be used from 'extensions/molecule' 2026-08-12 01:01:48.213627 | ubuntu-jammy | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-08-12 01:01:48.213697 | ubuntu-jammy | INFO default scenario not found, disabling shared state. 2026-08-12 01:01:48.213841 | ubuntu-jammy | INFO [docker > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-08-12 01:01:48.213951 | ubuntu-jammy | INFO [docker > prerun] Performing prerun with role_name_check=0... 2026-08-12 01:03:03.695165 | ubuntu-jammy | WARNING Retrying execution failure 1 of: ansible-galaxy collection install -vvv ansible.posix:>=1.6.0 2026-08-12 01:03:03.695286 | ubuntu-jammy | ERROR Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code: 2026-08-12 01:03:03.695295 | ubuntu-jammy | Starting galaxy collection install process 2026-08-12 01:03:03.695302 | ubuntu-jammy | Process install dependency map 2026-08-12 01:03:03.695308 | ubuntu-jammy | 2026-08-12 01:03:03.695313 | ubuntu-jammy | ansible-galaxy 2026-08-12 01:03:03.695319 | ubuntu-jammy | config file = None 2026-08-12 01:03:03.695324 | ubuntu-jammy | configured module search path = 2026-08-12 01:03:03.695330 | ubuntu-jammy | ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible 2026-08-12 01:03:03.695341 | ubuntu-jammy | 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-08-12 01:03:03.695351 | ubuntu-jammy | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/ansible-galaxy 2026-08-12 01:03:03.695357 | ubuntu-jammy | 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-08-12 01:03:03.695363 | ubuntu-jammy | jinja version = 3.1.6 2026-08-12 01:03:03.695368 | ubuntu-jammy | pyyaml version = 6.0.2 (with libyaml v0.2.5) 2026-08-12 01:03:03.695373 | ubuntu-jammy | No config file found; using defaults 2026-08-12 01:03:03.695379 | ubuntu-jammy | Created /home/zuul/.ansible/galaxy_token 2026-08-12 01:03:03.695386 | ubuntu-jammy | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an unexpected error when getting available versions of collection ansible.posix: Missing expected 'results' in ansible-galaxy cache: {'expires': '2026-08-13T01:01:57Z', 'paginated': False}. This may indicate cache corruption (for example, from concurrent ansible-galaxy runs) or a bug in how the cache was generated. Try running with --clear-response-cache or --no-cache to work around the issue: 'results' 2026-08-12 01:03:03.695397 | ubuntu-jammy | : Missing expected 'results' in ansible-galaxy cache: {'expires': '2026-08-13T01:01:57Z', 'paginated': False}. This may indicate cache corruption (for example, from concurrent ansible-galaxy runs) or a bug in how the cache was generated. Try running with --clear-response-cache or --no-cache to work around the issue: 'results' 2026-08-12 01:03:03.695403 | ubuntu-jammy | 2026-08-12 01:03:03.698702 | ubuntu-jammy | Traceback (most recent call last): 2026-08-12 01:03:03.702430 | ubuntu-jammy | File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/molecule", line 10, in 2026-08-12 01:03:03.702450 | ubuntu-jammy | sys.exit(main()) 2026-08-12 01:03:03.702456 | ubuntu-jammy | ~~~~^^ 2026-08-12 01:03:03.702634 | ubuntu-jammy | File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/click/core.py", line 1442, in __call__ 2026-08-12 01:03:03.702648 | ubuntu-jammy | return self.main(*args, **kwargs) 2026-08-12 01:03:03.702657 | ubuntu-jammy | ~~~~~~~~~^^^^^^^^^^^^^^^^^ 2026-08-12 01:03:03.702822 | ubuntu-jammy | File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/click/core.py", line 1363, in main 2026-08-12 01:03:03.702835 | ubuntu-jammy | rv = self.invoke(ctx) 2026-08-12 01:03:03.703020 | ubuntu-jammy | File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/click/core.py", line 1830, in invoke 2026-08-12 01:03:03.703034 | ubuntu-jammy | return _process_result(sub_ctx.command.invoke(sub_ctx)) 2026-08-12 01:03:03.703043 | ubuntu-jammy | ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^ 2026-08-12 01:03:03.703223 | ubuntu-jammy | File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/click/core.py", line 1226, in invoke 2026-08-12 01:03:03.703235 | ubuntu-jammy | return ctx.invoke(self.callback, **ctx.params) 2026-08-12 01:03:03.703243 | ubuntu-jammy | ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-08-12 01:03:03.703383 | ubuntu-jammy | File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/click/core.py", line 794, in invoke 2026-08-12 01:03:03.703393 | ubuntu-jammy | return callback(*args, **kwargs) 2026-08-12 01:03:03.703592 | ubuntu-jammy | 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-08-12 01:03:03.703606 | ubuntu-jammy | return func(*args, **kwargs) 2026-08-12 01:03:03.703712 | ubuntu-jammy | 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-08-12 01:03:03.703722 | ubuntu-jammy | return f(get_current_context(), *args, **kwargs) 2026-08-12 01:03:03.703895 | ubuntu-jammy | 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-08-12 01:03:03.703905 | ubuntu-jammy | return func(ctx) 2026-08-12 01:03:03.704110 | ubuntu-jammy | 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-08-12 01:03:03.704123 | ubuntu-jammy | base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude) 2026-08-12 01:03:03.704133 | ubuntu-jammy | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-08-12 01:03:03.704303 | ubuntu-jammy | 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-08-12 01:03:03.704314 | ubuntu-jammy | _run_scenarios(scenarios, command_args, default_config) 2026-08-12 01:03:03.704322 | ubuntu-jammy | ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-08-12 01:03:03.704663 | ubuntu-jammy | 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-08-12 01:03:03.704682 | ubuntu-jammy | scenario.config.runtime.prepare_environment( 2026-08-12 01:03:03.704690 | ubuntu-jammy | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-08-12 01:03:03.704697 | ubuntu-jammy | install_local=True, 2026-08-12 01:03:03.704705 | ubuntu-jammy | ^^^^^^^^^^^^^^^^^^^ 2026-08-12 01:03:03.704713 | ubuntu-jammy | role_name_check=role_name_check, 2026-08-12 01:03:03.704720 | ubuntu-jammy | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-08-12 01:03:03.704728 | ubuntu-jammy | ) 2026-08-12 01:03:03.704735 | ubuntu-jammy | ^ 2026-08-12 01:03:03.704972 | ubuntu-jammy | 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-08-12 01:03:03.704984 | ubuntu-jammy | self.install_collection( 2026-08-12 01:03:03.704992 | ubuntu-jammy | ~~~~~~~~~~~~~~~~~~~~~~~^ 2026-08-12 01:03:03.705000 | ubuntu-jammy | f"{name}{',' if is_url(name) else ':'}{required_version}", 2026-08-12 01:03:03.705016 | ubuntu-jammy | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-08-12 01:03:03.705024 | ubuntu-jammy | destination=destination, 2026-08-12 01:03:03.705032 | ubuntu-jammy | ^^^^^^^^^^^^^^^^^^^^^^^^ 2026-08-12 01:03:03.705044 | ubuntu-jammy | ) 2026-08-12 01:03:03.705052 | ubuntu-jammy | ^ 2026-08-12 01:03:03.705158 | ubuntu-jammy | 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-08-12 01:03:03.705167 | ubuntu-jammy | raise InvalidPrerequisiteError(msg) 2026-08-12 01:03:03.705855 | ubuntu-jammy | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code: 2026-08-12 01:03:03.705872 | ubuntu-jammy | Starting galaxy collection install process 2026-08-12 01:03:03.705881 | ubuntu-jammy | Process install dependency map 2026-08-12 01:03:03.705889 | ubuntu-jammy | 2026-08-12 01:03:03.705897 | ubuntu-jammy | ansible-galaxy [core 2.21.3] 2026-08-12 01:03:03.705905 | ubuntu-jammy | config file = None 2026-08-12 01:03:03.705913 | ubuntu-jammy | configured module search path = ['/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules'] 2026-08-12 01:03:03.705919 | ubuntu-jammy | ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible 2026-08-12 01:03:03.705925 | ubuntu-jammy | 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-08-12 01:03:03.705931 | ubuntu-jammy | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/ansible-galaxy 2026-08-12 01:03:03.705937 | ubuntu-jammy | 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-08-12 01:03:03.705943 | ubuntu-jammy | jinja version = 3.1.6 2026-08-12 01:03:03.705948 | ubuntu-jammy | pyyaml version = 6.0.2 (with libyaml v0.2.5) 2026-08-12 01:03:03.705954 | ubuntu-jammy | No config file found; using defaults 2026-08-12 01:03:03.705959 | ubuntu-jammy | Created /home/zuul/.ansible/galaxy_token 2026-08-12 01:03:03.705966 | ubuntu-jammy | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an unexpected error when getting available versions of collection ansible.posix: Missing expected 'results' in ansible-galaxy cache: {'expires': '2026-08-13T01:01:57Z', 'paginated': False}. This may indicate cache corruption (for example, from concurrent ansible-galaxy runs) or a bug in how the cache was generated. Try running with --clear-response-cache or --no-cache to work around the issue: 'results' 2026-08-12 01:03:03.705973 | ubuntu-jammy | [ERROR]: Missing expected 'results' in ansible-galaxy cache: {'expires': '2026-08-13T01:01:57Z', 'paginated': False}. This may indicate cache corruption (for example, from concurrent ansible-galaxy runs) or a bug in how the cache was generated. Try running with --clear-response-cache or --no-cache to work around the issue: 'results' 2026-08-12 01:03:03.705979 | ubuntu-jammy | 2026-08-12 01:03:04.302234 | ubuntu-jammy | ERROR 2026-08-12 01:03:04.302758 | ubuntu-jammy | { 2026-08-12 01:03:04.302824 | ubuntu-jammy | "delta": "0:01:19.342842", 2026-08-12 01:03:04.302858 | ubuntu-jammy | "end": "2026-08-12 01:03:03.812995", 2026-08-12 01:03:04.302887 | ubuntu-jammy | "msg": "non-zero return code", 2026-08-12 01:03:04.302915 | ubuntu-jammy | "rc": 1, 2026-08-12 01:03:04.302941 | ubuntu-jammy | "start": "2026-08-12 01:01:44.470153" 2026-08-12 01:03:04.302968 | ubuntu-jammy | } failure 2026-08-12 01:03:04.305614 | 2026-08-12 01:03:04.305674 | PLAY RECAP 2026-08-12 01:03:04.305731 | ubuntu-jammy | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-08-12 01:03:04.305763 | 2026-08-12 01:03:04.417495 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-08-12 01:03:04.418801 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-08-12 01:03:05.090192 | 2026-08-12 01:03:05.090348 | PLAY [all] 2026-08-12 01:03:05.102919 | 2026-08-12 01:03:05.103024 | TASK [fetch-output : Set log path for multiple nodes] 2026-08-12 01:03:05.149526 | ubuntu-jammy | skipping: Conditional result was False 2026-08-12 01:03:05.159179 | 2026-08-12 01:03:05.159295 | TASK [fetch-output : Set log path for single node] 2026-08-12 01:03:05.205175 | ubuntu-jammy | ok 2026-08-12 01:03:05.212550 | 2026-08-12 01:03:05.212620 | LOOP [fetch-output : Ensure local output dirs] 2026-08-12 01:03:05.584894 | ubuntu-jammy -> localhost | ok: "/var/lib/zuul/builds/7ff8631035b540eaa0a00f3454ee7221/work/logs" 2026-08-12 01:03:05.794712 | ubuntu-jammy -> localhost | changed: "/var/lib/zuul/builds/7ff8631035b540eaa0a00f3454ee7221/work/artifacts" 2026-08-12 01:03:06.010536 | ubuntu-jammy -> localhost | changed: "/var/lib/zuul/builds/7ff8631035b540eaa0a00f3454ee7221/work/docs" 2026-08-12 01:03:06.027792 | 2026-08-12 01:03:06.027963 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-12 01:03:06.667347 | ubuntu-jammy | changed: .d..t...... ./ 2026-08-12 01:03:06.667566 | ubuntu-jammy | changed: All items complete 2026-08-12 01:03:06.667606 | 2026-08-12 01:03:07.142927 | ubuntu-jammy | changed: .d..t...... ./ 2026-08-12 01:03:07.627849 | ubuntu-jammy | changed: .d..t...... ./ 2026-08-12 01:03:07.649512 | 2026-08-12 01:03:07.649597 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-12 01:03:08.050074 | ubuntu-jammy -> localhost | ok: Item: artifacts Runtime: 0:00:00.008028 2026-08-12 01:03:08.281357 | ubuntu-jammy -> localhost | ok: Item: docs Runtime: 0:00:00.007029 2026-08-12 01:03:08.295882 | 2026-08-12 01:03:08.295957 | PLAY [all] 2026-08-12 01:03:08.302573 | 2026-08-12 01:03:08.302638 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-12 01:03:08.708033 | ubuntu-jammy | changed 2026-08-12 01:03:08.716839 | 2026-08-12 01:03:08.716887 | PLAY RECAP 2026-08-12 01:03:08.716930 | ubuntu-jammy | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-08-12 01:03:08.716969 | 2026-08-12 01:03:08.818959 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-08-12 01:03:08.821232 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-12 01:03:09.431319 | 2026-08-12 01:03:09.431444 | PLAY [localhost] 2026-08-12 01:03:09.441874 | 2026-08-12 01:03:09.441964 | TASK [Generate Zuul manifest] 2026-08-12 01:03:09.460601 | localhost | ok 2026-08-12 01:03:09.478338 | 2026-08-12 01:03:09.478418 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-12 01:03:09.845899 | localhost | changed 2026-08-12 01:03:09.865452 | 2026-08-12 01:03:09.865541 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-12 01:03:09.895967 | localhost | ok 2026-08-12 01:03:10.271498 | 2026-08-12 01:03:10.271665 | TASK [Upload logs] 2026-08-12 01:03:10.301720 | localhost | ok 2026-08-12 01:03:10.366073 | 2026-08-12 01:03:10.366176 | TASK [Set zuul-log-path fact] 2026-08-12 01:03:10.387437 | localhost | ok 2026-08-12 01:03:10.403067 | 2026-08-12 01:03:10.403140 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-12 01:03:10.432407 | localhost | ok 2026-08-12 01:03:10.443547 | 2026-08-12 01:03:10.443612 | TASK [upload-logs : Create log directories] 2026-08-12 01:03:10.824730 | localhost | changed 2026-08-12 01:03:10.835445 | 2026-08-12 01:03:10.835606 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-12 01:03:11.289944 | localhost -> localhost | ok: Runtime: 0:00:00.006057 2026-08-12 01:03:11.295237 | 2026-08-12 01:03:11.295337 | TASK [upload-logs : Upload logs to log server] 2026-08-12 01:03:11.811565 | localhost | Output suppressed because no_log was given 2026-08-12 01:03:11.815375 | 2026-08-12 01:03:11.815444 | LOOP [upload-logs : Compress console log and json output] 2026-08-12 01:03:11.864714 | localhost | skipping: Conditional result was False 2026-08-12 01:03:11.872645 | localhost | skipping: Conditional result was False 2026-08-12 01:03:11.889485 | 2026-08-12 01:03:11.889679 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-12 01:03:11.936655 | localhost | skipping: Conditional result was False 2026-08-12 01:03:11.936941 | 2026-08-12 01:03:11.942016 | localhost | skipping: Conditional result was False 2026-08-12 01:03:11.955013 | 2026-08-12 01:03:11.955197 | LOOP [upload-logs : Upload console log and json output]