2026-09-05 00:55:43.707055 | Job console starting
2026-09-05 00:55:43.743191 | Updating git repos
2026-09-05 00:55:43.795887 | Cloning repos into workspace
2026-09-05 00:55:43.849432 | Restoring repo states
2026-09-05 00:55:43.863341 | Merging changes
2026-09-05 00:55:44.483544 | Checking out repos
2026-09-05 00:55:44.515753 | Preparing playbooks
2026-09-05 00:55:45.810338 | Running Ansible setup
2026-09-05 00:55:50.519013 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-05 00:55:51.133185 | 
2026-09-05 00:55:51.133323 | PLAY [localhost]
2026-09-05 00:55:51.140209 | 
2026-09-05 00:55:51.140274 | TASK [Gathering Facts]
2026-09-05 00:55:52.006164 | localhost | ok
2026-09-05 00:55:52.012337 | 
2026-09-05 00:55:52.012412 | TASK [Setup log path fact]
2026-09-05 00:55:52.028572 | localhost | ok
2026-09-05 00:55:52.038066 | 
2026-09-05 00:55:52.038133 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-05 00:55:52.066583 | localhost | ok
2026-09-05 00:55:52.073883 | 
2026-09-05 00:55:52.073970 | TASK [emit-job-header : Print job information]
2026-09-05 00:55:52.123566 | # Job Information
2026-09-05 00:55:52.123810 | Ansible Version: 2.16.18
2026-09-05 00:55:52.123893 | Job: ansible-collection-containers-molecule-download-binaries
2026-09-05 00:55:52.123953 | Pipeline: check
2026-09-05 00:55:52.124012 | Executor: 2d72f0692154
2026-09-05 00:55:52.124065 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/149
2026-09-05 00:55:52.124120 | Event ID: b7f4aa30-a8c3-11f1-9178-a13e202f19f2
2026-09-05 00:55:52.130948 | 
2026-09-05 00:55:52.131074 | LOOP [emit-job-header : Print node information]
2026-09-05 00:55:52.226050 | localhost | ok:
2026-09-05 00:55:52.226297 | localhost | # Node Information
2026-09-05 00:55:52.226323 | localhost | Inventory Hostname: debian-trixie
2026-09-05 00:55:52.226342 | localhost | Hostname: np0000191947
2026-09-05 00:55:52.226359 | localhost | Username: zuul
2026-09-05 00:55:52.226378 | localhost | Distro: Debian 13.6
2026-09-05 00:55:52.226396 | localhost | Provider: yul1
2026-09-05 00:55:52.226412 | localhost | Region: ca-ymq-1
2026-09-05 00:55:52.226427 | localhost | Label: debian-trixie
2026-09-05 00:55:52.226443 | localhost | Product Name: OpenStack Nova
2026-09-05 00:55:52.226459 | localhost | Interface IP: 162.253.55.111
2026-09-05 00:55:52.239881 | 
2026-09-05 00:55:52.240174 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-05 00:55:52.718589 | localhost -> localhost | changed
2026-09-05 00:55:52.723433 | 
2026-09-05 00:55:52.723508 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-05 00:55:53.574588 | localhost -> localhost | changed
2026-09-05 00:55:53.586247 | 
2026-09-05 00:55:53.586383 | PLAY [all]
2026-09-05 00:55:53.600060 | 
2026-09-05 00:55:53.600197 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-05 00:55:53.827695 | debian-trixie -> localhost | ok
2026-09-05 00:55:53.836915 | 
2026-09-05 00:55:53.837040 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-05 00:55:53.869137 | debian-trixie | ok
2026-09-05 00:55:53.893532 | debian-trixie | included: /var/lib/zuul/builds/199165b34f774f8f861e84178dfcbc03/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-05 00:55:53.899999 | 
2026-09-05 00:55:53.900084 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-05 00:55:54.706058 | debian-trixie -> localhost | Generating public/private rsa key pair.
2026-09-05 00:55:54.706263 | debian-trixie -> localhost | Your identification has been saved in /var/lib/zuul/builds/199165b34f774f8f861e84178dfcbc03/work/199165b34f774f8f861e84178dfcbc03_id_rsa
2026-09-05 00:55:54.706318 | debian-trixie -> localhost | Your public key has been saved in /var/lib/zuul/builds/199165b34f774f8f861e84178dfcbc03/work/199165b34f774f8f861e84178dfcbc03_id_rsa.pub
2026-09-05 00:55:54.706360 | debian-trixie -> localhost | The key fingerprint is:
2026-09-05 00:55:54.706533 | debian-trixie -> localhost | SHA256:cRKJxn0P4VLiIqipr6vDqs5ElfO4LMgLpvDBQiae3WY zuul-build-sshkey
2026-09-05 00:55:54.706614 | debian-trixie -> localhost | The key's randomart image is:
2026-09-05 00:55:54.706671 | debian-trixie -> localhost | +---[RSA 3072]----+
2026-09-05 00:55:54.706726 | debian-trixie -> localhost | |     . oo.o.     |
2026-09-05 00:55:54.706785 | debian-trixie -> localhost | |   .. +.o=o      |
2026-09-05 00:55:54.706836 | debian-trixie -> localhost | |  .+... =.oo     |
2026-09-05 00:55:54.706878 | debian-trixie -> localhost | | o. +. . =  .    |
2026-09-05 00:55:54.706928 | debian-trixie -> localhost | |++ . .  S        |
2026-09-05 00:55:54.707003 | debian-trixie -> localhost | |X.+ o            |
2026-09-05 00:55:54.707079 | debian-trixie -> localhost | |BBo+ E           |
2026-09-05 00:55:54.707194 | debian-trixie -> localhost | |O=o.o            |
2026-09-05 00:55:54.707262 | debian-trixie -> localhost | |%Bo              |
2026-09-05 00:55:54.707358 | debian-trixie -> localhost | +----[SHA256]-----+
2026-09-05 00:55:54.707485 | debian-trixie -> localhost | ok: Runtime: 0:00:00.357091
2026-09-05 00:55:54.725237 | 
2026-09-05 00:55:54.725375 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-05 00:55:54.755379 | debian-trixie | ok
2026-09-05 00:55:54.765364 | debian-trixie | included: /var/lib/zuul/builds/199165b34f774f8f861e84178dfcbc03/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-05 00:55:54.773928 | 
2026-09-05 00:55:54.774024 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-05 00:55:54.798772 | debian-trixie | skipping: Conditional result was False
2026-09-05 00:55:54.805427 | 
2026-09-05 00:55:54.805754 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-05 00:55:55.372583 | debian-trixie | changed
2026-09-05 00:55:55.380097 | 
2026-09-05 00:55:55.380221 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-05 00:55:55.602565 | debian-trixie | ok
2026-09-05 00:55:55.612555 | 
2026-09-05 00:55:55.612683 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-05 00:55:56.152735 | debian-trixie | changed
2026-09-05 00:55:56.156379 | 
2026-09-05 00:55:56.156433 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-05 00:55:56.720423 | debian-trixie | changed
2026-09-05 00:55:56.733513 | 
2026-09-05 00:55:56.733642 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-05 00:55:56.758407 | debian-trixie | skipping: Conditional result was False
2026-09-05 00:55:56.766430 | 
2026-09-05 00:55:56.766582 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-05 00:55:57.150276 | debian-trixie -> localhost | changed
2026-09-05 00:55:57.159333 | 
2026-09-05 00:55:57.159426 | TASK [add-build-sshkey : Add back temp key]
2026-09-05 00:55:57.414838 | debian-trixie -> localhost | Identity added: /var/lib/zuul/builds/199165b34f774f8f861e84178dfcbc03/work/199165b34f774f8f861e84178dfcbc03_id_rsa (zuul-build-sshkey)
2026-09-05 00:55:57.415109 | debian-trixie -> localhost | ok: Runtime: 0:00:00.014983
2026-09-05 00:55:57.424930 | 
2026-09-05 00:55:57.425056 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-05 00:55:57.758718 | debian-trixie | ok
2026-09-05 00:55:57.775294 | 
2026-09-05 00:55:57.775496 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-05 00:55:57.810658 | debian-trixie | skipping: Conditional result was False
2026-09-05 00:55:57.826618 | 
2026-09-05 00:55:57.826797 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-05 00:55:58.211736 | debian-trixie | ok
2026-09-05 00:55:58.220081 | 
2026-09-05 00:55:58.220211 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-05 00:55:59.122863 | debian-trixie | Output suppressed because no_log was given
2026-09-05 00:55:59.137907 | 
2026-09-05 00:55:59.138057 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-05 00:55:59.351338 | debian-trixie | ok: "logs"
2026-09-05 00:55:59.351628 | debian-trixie | ok: All items complete
2026-09-05 00:55:59.351676 | 
2026-09-05 00:55:59.563825 | debian-trixie | ok: "artifacts"
2026-09-05 00:55:59.767606 | debian-trixie | ok: "docs"
2026-09-05 00:55:59.784148 | 
2026-09-05 00:55:59.784274 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-05 00:56:00.033431 | debian-trixie | changed: "logs"
2026-09-05 00:56:00.214669 | debian-trixie | changed: "artifacts"
2026-09-05 00:56:00.420875 | debian-trixie | changed: "docs"
2026-09-05 00:56:00.440581 | 
2026-09-05 00:56:00.440836 | PLAY RECAP
2026-09-05 00:56:00.440983 | debian-trixie | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-05 00:56:00.441052 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-05 00:56:00.441115 | 
2026-09-05 00:56:00.576566 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-05 00:56:00.578604 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-05 00:56:01.124830 | 
2026-09-05 00:56:01.124948 | PLAY [all]
2026-09-05 00:56:01.134209 | 
2026-09-05 00:56:01.134301 | TASK [setup-uv : Extract archive]
2026-09-05 00:56:03.494349 | debian-trixie | changed
2026-09-05 00:56:03.503081 | 
2026-09-05 00:56:03.503225 | TASK [setup-uv : Print version]
2026-09-05 00:56:03.915386 | debian-trixie | uv 0.8.13
2026-09-05 00:56:04.045525 | debian-trixie | ok: Runtime: 0:00:00.017084
2026-09-05 00:56:04.058654 | 
2026-09-05 00:56:04.058811 | TASK [Install Ansible collection siblings]
2026-09-05 00:56:04.467278 | debian-trixie | ok:
2026-09-05 00:56:04.467516 | debian-trixie | Skipping ansible.posix: no checked-out sibling
2026-09-05 00:56:04.467573 | debian-trixie | Skipping community.general: no checked-out sibling
2026-09-05 00:56:04.471273 | 
2026-09-05 00:56:04.471366 | PLAY RECAP
2026-09-05 00:56:04.471445 | debian-trixie | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-05 00:56:04.471509 | 
2026-09-05 00:56:04.587250 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-05 00:56:04.589234 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-05 00:56:05.174626 | 
2026-09-05 00:56:05.177913 | PLAY [all]
2026-09-05 00:56:05.187589 | 
2026-09-05 00:56:05.187671 | LOOP [Check inventory directories]
2026-09-05 00:56:05.496860 | debian-trixie | ok: "src/github.com/vexxhost/ansible-collection-containers"
2026-09-05 00:56:05.690635 | debian-trixie | ok: "src/github.com/vexxhost/ansible-collection-containers/molecule/download-binaries/inventory"
2026-09-05 00:56:05.863494 | debian-trixie | ok: "src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/download-binaries/inventory"
2026-09-05 00:56:05.874413 | 
2026-09-05 00:56:05.874672 | LOOP [Copy inventory file for Zuul]
2026-09-05 00:56:06.831308 | debian-trixie | changed:
2026-09-05 00:56:06.831593 | debian-trixie | {
2026-09-05 00:56:06.831651 | debian-trixie |   "ansible_loop_var": "item",
2026-09-05 00:56:06.831698 | debian-trixie |   "changed": false,
2026-09-05 00:56:06.831738 | debian-trixie |   "failed": false,
2026-09-05 00:56:06.831782 | debian-trixie |   "invocation": {
2026-09-05 00:56:06.831820 | debian-trixie |     "module_args": {
2026-09-05 00:56:06.831857 | debian-trixie |       "checksum_algorithm": "sha1",
2026-09-05 00:56:06.831894 | debian-trixie |       "follow": true,
2026-09-05 00:56:06.831930 | debian-trixie |       "get_attributes": true,
2026-09-05 00:56:06.831968 | debian-trixie |       "get_checksum": true,
2026-09-05 00:56:06.832003 | debian-trixie |       "get_mime": true,
2026-09-05 00:56:06.832038 | debian-trixie |       "path": "src/github.com/vexxhost/ansible-collection-containers"
2026-09-05 00:56:06.832074 | debian-trixie |     }
2026-09-05 00:56:06.832110 | debian-trixie |   },
2026-09-05 00:56:06.832145 | debian-trixie |   "item": "src/github.com/vexxhost/ansible-collection-containers",
2026-09-05 00:56:06.832194 | debian-trixie |   "stat": {
2026-09-05 00:56:06.832231 | debian-trixie |     "atime": 1788569758.9996731,
2026-09-05 00:56:06.832334 | debian-trixie |     "attr_flags": "e",
2026-09-05 00:56:06.832371 | debian-trixie |     "attributes": [
2026-09-05 00:56:06.832406 | debian-trixie |       "extents"
2026-09-05 00:56:06.832441 | debian-trixie |     ],
2026-09-05 00:56:06.832529 | debian-trixie |     "block_size": 4096,
2026-09-05 00:56:06.832571 | debian-trixie |     "blocks": 8,
2026-09-05 00:56:06.832607 | debian-trixie |     "charset": "unknown",
2026-09-05 00:56:06.832644 | debian-trixie |     "ctime": 1788569759.0156732,
2026-09-05 00:56:06.832678 | debian-trixie |     "dev": 65025,
2026-09-05 00:56:06.832712 | debian-trixie |     "device_type": 0,
2026-09-05 00:56:06.832746 | debian-trixie |     "executable": true,
2026-09-05 00:56:06.832779 | debian-trixie |     "exists": true,
2026-09-05 00:56:06.832813 | debian-trixie |     "gid": 1000,
2026-09-05 00:56:06.832847 | debian-trixie |     "gr_name": "zuul",
2026-09-05 00:56:06.832881 | debian-trixie |     "inode": 521495,
2026-09-05 00:56:06.832914 | debian-trixie |     "isblk": false,
2026-09-05 00:56:06.832949 | debian-trixie |     "ischr": false,
2026-09-05 00:56:06.832983 | debian-trixie |     "isdir": true,
2026-09-05 00:56:06.833017 | debian-trixie |     "isfifo": false,
2026-09-05 00:56:06.833050 | debian-trixie |     "isgid": false,
2026-09-05 00:56:06.833083 | debian-trixie |     "islnk": false,
2026-09-05 00:56:06.833116 | debian-trixie |     "isreg": false,
2026-09-05 00:56:06.833149 | debian-trixie |     "issock": false,
2026-09-05 00:56:06.833181 | debian-trixie |     "isuid": false,
2026-09-05 00:56:06.833213 | debian-trixie |     "mimetype": "unknown",
2026-09-05 00:56:06.833245 | debian-trixie |     "mode": "0755",
2026-09-05 00:56:06.833277 | debian-trixie |     "mtime": 1788569743.8881679,
2026-09-05 00:56:06.833310 | debian-trixie |     "nlink": 11,
2026-09-05 00:56:06.833343 | debian-trixie |     "path": "src/github.com/vexxhost/ansible-collection-containers",
2026-09-05 00:56:06.833375 | debian-trixie |     "pw_name": "zuul",
2026-09-05 00:56:06.833413 | debian-trixie |     "readable": true,
2026-09-05 00:56:06.833447 | debian-trixie |     "rgrp": true,
2026-09-05 00:56:06.833507 | debian-trixie |     "roth": true,
2026-09-05 00:56:06.833542 | debian-trixie |     "rusr": true,
2026-09-05 00:56:06.833577 | debian-trixie |     "size": 4096,
2026-09-05 00:56:06.833611 | debian-trixie |     "uid": 1000,
2026-09-05 00:56:06.833644 | debian-trixie |     "version": "32096433",
2026-09-05 00:56:06.833678 | debian-trixie |     "wgrp": false,
2026-09-05 00:56:06.833714 | debian-trixie |     "woth": false,
2026-09-05 00:56:06.833748 | debian-trixie |     "writeable": true,
2026-09-05 00:56:06.833781 | debian-trixie |     "wusr": true,
2026-09-05 00:56:06.833815 | debian-trixie |     "xgrp": true,
2026-09-05 00:56:06.833848 | debian-trixie |     "xoth": true,
2026-09-05 00:56:06.833881 | debian-trixie |     "xusr": true
2026-09-05 00:56:06.833915 | debian-trixie |   }
2026-09-05 00:56:06.833949 | debian-trixie | }
2026-09-05 00:56:06.834015 | debian-trixie | ok: All items complete
2026-09-05 00:56:06.834058 | 
2026-09-05 00:56:06.836379 | debian-trixie | skipping: Conditional result was False
2026-09-05 00:56:06.838525 | debian-trixie | skipping: Conditional result was False
2026-09-05 00:56:06.851517 | 
2026-09-05 00:56:06.851657 | LOOP [Switch "ansible_host" to private IP]
2026-09-05 00:56:07.240149 | debian-trixie | changed: 1 replacements made
2026-09-05 00:56:07.242710 | debian-trixie | skipping: Conditional result was False
2026-09-05 00:56:07.247909 | debian-trixie | skipping: Conditional result was False
2026-09-05 00:56:07.264541 | 
2026-09-05 00:56:07.264682 | TASK [Run Molecule scenario]
2026-09-05 00:56:07.772959 | debian-trixie | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-09-05 00:56:07.773094 | debian-trixie | Creating virtual environment at: .venv
2026-09-05 00:56:08.029688 | debian-trixie | Downloading pygments (1.2MiB)
2026-09-05 00:56:08.034457 | debian-trixie | Downloading cryptography (4.3MiB)
2026-09-05 00:56:08.036096 | debian-trixie | Downloading ansible-core (2.3MiB)
2026-09-05 00:56:08.201324 | debian-trixie |  Downloading cryptography
2026-09-05 00:56:08.250786 | debian-trixie |  Downloading pygments
2026-09-05 00:56:08.421551 | debian-trixie |  Downloading ansible-core
2026-09-05 00:56:08.492855 | debian-trixie | Installed 36 packages in 70ms
2026-09-05 00:56:09.144136 | debian-trixie | INFO     Collection 'vexxhost.containers' detected.
2026-09-05 00:56:09.144177 | debian-trixie | INFO     Scenarios will be used from 'extensions/molecule'
2026-09-05 00:56:10.084473 | debian-trixie | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-09-05 00:56:10.084530 | debian-trixie | INFO     default scenario not found, disabling shared state.
2026-09-05 00:56:10.084588 | debian-trixie | INFO     [download-binaries > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-05 00:56:10.084662 | debian-trixie | INFO     [download-binaries > prerun] Performing prerun with role_name_check=0...
2026-09-05 00:57:19.612777 | debian-trixie | WARNING  Retrying execution failure 1 of: ansible-galaxy collection install -vvv ansible.posix:>=1.6.0
2026-09-05 00:57:19.612885 | debian-trixie | ERROR    Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code:
2026-09-05 00:57:19.612901 | debian-trixie | Starting galaxy collection install process
2026-09-05 00:57:19.612913 | debian-trixie | Process install dependency map
2026-09-05 00:57:19.612924 | debian-trixie | Starting collection install process
2026-09-05 00:57:19.612934 | debian-trixie | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-posix-2.2.2.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1328god4vrjs/tmp6mqhd4bo/ansible-posix-2.2.2-ecczgrrb
2026-09-05 00:57:19.612945 | debian-trixie |
2026-09-05 00:57:19.612956 | debian-trixie | ansible-galaxy
2026-09-05 00:57:19.612966 | debian-trixie |   config file = None
2026-09-05 00:57:19.612975 | debian-trixie |   configured module search path =
2026-09-05 00:57:19.612985 | debian-trixie |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible
2026-09-05 00:57:19.612999 | debian-trixie |   ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/local/lib/python3.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages
2026-09-05 00:57:19.613012 | debian-trixie |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/ansible-galaxy
2026-09-05 00:57:19.613023 | debian-trixie |   python version = 3.13.5 (main, Jul 15 2026, 20:25:40)  (/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/python)
2026-09-05 00:57:19.613033 | debian-trixie |   jinja version = 3.1.6
2026-09-05 00:57:19.613043 | debian-trixie |   pyyaml version = 6.0.2 (with libyaml v0.2.5)
2026-09-05 00:57:19.613053 | debian-trixie | No config file found; using defaults
2026-09-05 00:57:19.613071 | debian-trixie | 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:57:19.613082 | debian-trixie | Created /home/zuul/.ansible/galaxy_token
2026-09-05 00:57:19.613092 | debian-trixie | : Failed to download collection tar from 'default': <urlopen error _ssl.c:1012: The handshake operation timed out>
2026-09-05 00:57:19.613103 | debian-trixie |
2026-09-05 00:57:19.613113 | debian-trixie | Failed to download collection tar from 'default': <urlopen error _ssl.c:1012: The handshake operation timed out>
2026-09-05 00:57:19.613123 | debian-trixie |
2026-09-05 00:57:19.613133 | debian-trixie | <<< caused by >>>
2026-09-05 00:57:19.613144 | debian-trixie |
2026-09-05 00:57:19.613154 | debian-trixie | <urlopen error _ssl.c:1012: The handshake operation timed out>
2026-09-05 00:57:19.613186 | debian-trixie |
2026-09-05 00:57:19.613217 | debian-trixie |
2026-09-05 00:57:19.614467 | debian-trixie | Traceback (most recent call last):
2026-09-05 00:57:19.617716 | debian-trixie |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/molecule", line 10, in <module>
2026-09-05 00:57:19.617743 | debian-trixie |     sys.exit(main())
2026-09-05 00:57:19.617756 | debian-trixie |              ~~~~^^
2026-09-05 00:57:19.618149 | debian-trixie |   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:57:19.618173 | debian-trixie |     return self.main(*args, **kwargs)
2026-09-05 00:57:19.618185 | debian-trixie |            ~~~~~~~~~^^^^^^^^^^^^^^^^^
2026-09-05 00:57:19.618518 | debian-trixie |   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:57:19.618543 | debian-trixie |     rv = self.invoke(ctx)
2026-09-05 00:57:19.618885 | debian-trixie |   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:57:19.618909 | debian-trixie |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-09-05 00:57:19.618922 | debian-trixie |                            ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^
2026-09-05 00:57:19.619243 | debian-trixie |   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:57:19.619267 | debian-trixie |     return ctx.invoke(self.callback, **ctx.params)
2026-09-05 00:57:19.619280 | debian-trixie |            ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-05 00:57:19.619587 | debian-trixie |   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:57:19.619609 | debian-trixie |     return callback(*args, **kwargs)
2026-09-05 00:57:19.619893 | debian-trixie |   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:57:19.619915 | debian-trixie |     return func(*args, **kwargs)
2026-09-05 00:57:19.620220 | debian-trixie |   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:57:19.620243 | debian-trixie |     return f(get_current_context(), *args, **kwargs)
2026-09-05 00:57:19.620528 | debian-trixie |   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:57:19.620550 | debian-trixie |     return func(ctx)
2026-09-05 00:57:19.620896 | debian-trixie |   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:57:19.620919 | debian-trixie |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-09-05 00:57:19.620931 | debian-trixie |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-05 00:57:19.621259 | debian-trixie |   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:57:19.621283 | debian-trixie |     _run_scenarios(scenarios, command_args, default_config)
2026-09-05 00:57:19.621295 | debian-trixie |     ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-05 00:57:19.621782 | debian-trixie |   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:57:19.621805 | debian-trixie |     scenario.config.runtime.prepare_environment(
2026-09-05 00:57:19.621816 | debian-trixie |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-05 00:57:19.621827 | debian-trixie |         install_local=True,
2026-09-05 00:57:19.621838 | debian-trixie |         ^^^^^^^^^^^^^^^^^^^
2026-09-05 00:57:19.621849 | debian-trixie |         role_name_check=role_name_check,
2026-09-05 00:57:19.621860 | debian-trixie |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-05 00:57:19.621870 | debian-trixie |     )
2026-09-05 00:57:19.621881 | debian-trixie |     ^
2026-09-05 00:57:19.622499 | debian-trixie |   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:57:19.622525 | debian-trixie |     self.install_collection(
2026-09-05 00:57:19.622538 | debian-trixie |     ~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-05 00:57:19.622549 | debian-trixie |         f"{name}{',' if is_url(name) else ':'}{required_version}",
2026-09-05 00:57:19.622560 | debian-trixie |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-05 00:57:19.622572 | debian-trixie |         destination=destination,
2026-09-05 00:57:19.622582 | debian-trixie |         ^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-05 00:57:19.622593 | debian-trixie |     )
2026-09-05 00:57:19.622603 | debian-trixie |     ^
2026-09-05 00:57:19.622875 | debian-trixie |   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:57:19.622897 | debian-trixie |     raise InvalidPrerequisiteError(msg)
2026-09-05 00:57:19.624265 | debian-trixie | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code:
2026-09-05 00:57:19.624289 | debian-trixie | Starting galaxy collection install process
2026-09-05 00:57:19.624300 | debian-trixie | Process install dependency map
2026-09-05 00:57:19.624311 | debian-trixie | Starting collection install process
2026-09-05 00:57:19.624322 | debian-trixie | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-posix-2.2.2.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1328god4vrjs/tmp6mqhd4bo/ansible-posix-2.2.2-ecczgrrb
2026-09-05 00:57:19.624333 | debian-trixie |
2026-09-05 00:57:19.624344 | debian-trixie | ansible-galaxy [core 2.19.1]
2026-09-05 00:57:19.624354 | debian-trixie |   config file = None
2026-09-05 00:57:19.624365 | debian-trixie |   configured module search path = ['/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules']
2026-09-05 00:57:19.624375 | debian-trixie |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible
2026-09-05 00:57:19.624388 | debian-trixie |   ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/local/lib/python3.13/dist-packages:/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages
2026-09-05 00:57:19.624398 | debian-trixie |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/ansible-galaxy
2026-09-05 00:57:19.624409 | debian-trixie |   python version = 3.13.5 (main, Jul 15 2026, 20:25:40) [GCC 14.2.0] (/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/python)
2026-09-05 00:57:19.624419 | debian-trixie |   jinja version = 3.1.6
2026-09-05 00:57:19.624429 | debian-trixie |   pyyaml version = 6.0.2 (with libyaml v0.2.5)
2026-09-05 00:57:19.624438 | debian-trixie | No config file found; using defaults
2026-09-05 00:57:19.624448 | debian-trixie | 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:57:19.624458 | debian-trixie | Created /home/zuul/.ansible/galaxy_token
2026-09-05 00:57:19.624469 | debian-trixie | [ERROR]: Failed to download collection tar from 'default': <urlopen error _ssl.c:1012: The handshake operation timed out>
2026-09-05 00:57:19.624480 | debian-trixie |
2026-09-05 00:57:19.624492 | debian-trixie | Failed to download collection tar from 'default': <urlopen error _ssl.c:1012: The handshake operation timed out>
2026-09-05 00:57:19.624503 | debian-trixie |
2026-09-05 00:57:19.624514 | debian-trixie | <<< caused by >>>
2026-09-05 00:57:19.624525 | debian-trixie |
2026-09-05 00:57:19.624536 | debian-trixie | <urlopen error _ssl.c:1012: The handshake operation timed out>
2026-09-05 00:57:19.624548 | debian-trixie |
2026-09-05 00:57:19.624559 | debian-trixie |
2026-09-05 00:57:19.855052 | debian-trixie | ERROR
2026-09-05 00:57:19.855247 | debian-trixie | {
2026-09-05 00:57:19.855303 | debian-trixie |   "delta": "0:01:12.065007",
2026-09-05 00:57:19.855342 | debian-trixie |   "end": "2026-09-05 00:57:19.728505",
2026-09-05 00:57:19.855378 | debian-trixie |   "msg": "non-zero return code",
2026-09-05 00:57:19.855412 | debian-trixie |   "rc": 1,
2026-09-05 00:57:19.855470 | debian-trixie |   "start": "2026-09-05 00:56:07.663498"
2026-09-05 00:57:19.855505 | debian-trixie | }
failure
2026-09-05 00:57:19.859551 | 
2026-09-05 00:57:19.859642 | PLAY RECAP
2026-09-05 00:57:19.859795 | debian-trixie | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-05 00:57:19.859842 | 
2026-09-05 00:57:19.996113 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-05 00:57:19.998218 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-05 00:57:20.568478 | 
2026-09-05 00:57:20.568611 | PLAY [all]
2026-09-05 00:57:20.577727 | 
2026-09-05 00:57:20.577795 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-05 00:57:20.631963 | debian-trixie | skipping: Conditional result was False
2026-09-05 00:57:20.637141 | 
2026-09-05 00:57:20.637239 | TASK [fetch-output : Set log path for single node]
2026-09-05 00:57:20.677572 | debian-trixie | ok
2026-09-05 00:57:20.684335 | 
2026-09-05 00:57:20.684488 | LOOP [fetch-output : Ensure local output dirs]
2026-09-05 00:57:21.118844 | debian-trixie -> localhost | ok: "/var/lib/zuul/builds/199165b34f774f8f861e84178dfcbc03/work/logs"
2026-09-05 00:57:21.337030 | debian-trixie -> localhost | changed: "/var/lib/zuul/builds/199165b34f774f8f861e84178dfcbc03/work/artifacts"
2026-09-05 00:57:21.605668 | debian-trixie -> localhost | changed: "/var/lib/zuul/builds/199165b34f774f8f861e84178dfcbc03/work/docs"
2026-09-05 00:57:21.623643 | 
2026-09-05 00:57:21.623819 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-05 00:57:22.289033 | debian-trixie | changed: .d..t...... ./
2026-09-05 00:57:22.289340 | debian-trixie | changed: All items complete
2026-09-05 00:57:22.289403 | 
2026-09-05 00:57:22.798809 | debian-trixie | changed: .d..t...... ./
2026-09-05 00:57:23.293775 | debian-trixie | changed: .d..t...... ./
2026-09-05 00:57:23.312182 | 
2026-09-05 00:57:23.312366 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-05 00:57:23.744581 | debian-trixie -> localhost | ok: Item: artifacts Runtime: 0:00:00.008190
2026-09-05 00:57:24.032870 | debian-trixie -> localhost | ok: Item: docs Runtime: 0:00:00.008192
2026-09-05 00:57:24.052886 | 
2026-09-05 00:57:24.053079 | PLAY [all]
2026-09-05 00:57:24.063036 | 
2026-09-05 00:57:24.063170 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-05 00:57:24.570394 | debian-trixie | changed
2026-09-05 00:57:24.578584 | 
2026-09-05 00:57:24.578680 | PLAY RECAP
2026-09-05 00:57:24.578764 | debian-trixie | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-05 00:57:24.578806 | 
2026-09-05 00:57:24.683061 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-05 00:57:24.685219 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-05 00:57:25.209332 | 
2026-09-05 00:57:25.210068 | PLAY [localhost]
2026-09-05 00:57:25.219361 | 
2026-09-05 00:57:25.219442 | TASK [Generate Zuul manifest]
2026-09-05 00:57:25.245340 | localhost | ok
2026-09-05 00:57:25.257816 | 
2026-09-05 00:57:25.257888 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-05 00:57:25.702369 | localhost | changed
2026-09-05 00:57:25.721290 | 
2026-09-05 00:57:25.721450 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-05 00:57:25.766627 | localhost | ok
2026-09-05 00:57:25.779167 | 
2026-09-05 00:57:25.779293 | TASK [Upload logs]
2026-09-05 00:57:25.800996 | localhost | ok
2026-09-05 00:57:25.866965 | 
2026-09-05 00:57:25.867064 | TASK [Set zuul-log-path fact]
2026-09-05 00:57:25.883722 | localhost | ok
2026-09-05 00:57:25.893273 | 
2026-09-05 00:57:25.893336 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-05 00:57:25.922145 | localhost | ok
2026-09-05 00:57:25.930025 | 
2026-09-05 00:57:25.930106 | TASK [upload-logs : Create log directories]
2026-09-05 00:57:26.395294 | localhost | changed
2026-09-05 00:57:26.407053 | 
2026-09-05 00:57:26.407194 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-05 00:57:26.835304 | localhost -> localhost | ok: Runtime: 0:00:00.004572
2026-09-05 00:57:26.844584 | 
2026-09-05 00:57:26.844721 | TASK [upload-logs : Upload logs to log server]
2026-09-05 00:57:27.334815 | localhost | Output suppressed because no_log was given
2026-09-05 00:57:27.342044 | 
2026-09-05 00:57:27.342175 | LOOP [upload-logs : Compress console log and json output]
2026-09-05 00:57:27.405570 | localhost | skipping: Conditional result was False
2026-09-05 00:57:27.414724 | localhost | skipping: Conditional result was False
2026-09-05 00:57:27.427160 | 
2026-09-05 00:57:27.427357 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-05 00:57:27.471509 | localhost | skipping: Conditional result was False
2026-09-05 00:57:27.471882 | 
2026-09-05 00:57:27.475250 | localhost | skipping: Conditional result was False
2026-09-05 00:57:27.491157 | 
2026-09-05 00:57:27.491320 | LOOP [upload-logs : Upload console log and json output]