2026-09-17 00:56:02.956459 | Job console starting
2026-09-17 00:56:02.967655 | Updating git repos
2026-09-17 00:56:02.996410 | Cloning repos into workspace
2026-09-17 00:56:03.041795 | Restoring repo states
2026-09-17 00:56:03.050830 | Merging changes
2026-09-17 00:56:03.651878 | Checking out repos
2026-09-17 00:56:03.714009 | Preparing playbooks
2026-09-17 00:56:05.010402 | Running Ansible setup
2026-09-17 00:56:08.776125 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-17 00:56:09.387615 | 
2026-09-17 00:56:09.387781 | PLAY [localhost]
2026-09-17 00:56:09.395553 | 
2026-09-17 00:56:09.395617 | TASK [Gathering Facts]
2026-09-17 00:56:10.267117 | localhost | ok
2026-09-17 00:56:10.279616 | 
2026-09-17 00:56:10.279762 | TASK [Setup log path fact]
2026-09-17 00:56:10.310959 | localhost | ok
2026-09-17 00:56:10.331095 | 
2026-09-17 00:56:10.331237 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-17 00:56:10.373704 | localhost | ok
2026-09-17 00:56:10.385075 | 
2026-09-17 00:56:10.385214 | TASK [emit-job-header : Print job information]
2026-09-17 00:56:10.437651 | # Job Information
2026-09-17 00:56:10.437923 | Ansible Version: 2.16.18
2026-09-17 00:56:10.437988 | Job: ansible-collection-containers-molecule-docker-debian-trixie
2026-09-17 00:56:10.438033 | Pipeline: check
2026-09-17 00:56:10.438075 | Executor: 2d72f0692154
2026-09-17 00:56:10.438116 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/149
2026-09-17 00:56:10.438167 | Event ID: fdcba780-b231-11f1-9399-d3d30f48488d
2026-09-17 00:56:10.444023 | 
2026-09-17 00:56:10.444161 | LOOP [emit-job-header : Print node information]
2026-09-17 00:56:10.545051 | localhost | ok:
2026-09-17 00:56:10.545738 | localhost | # Node Information
2026-09-17 00:56:10.545801 | localhost | Inventory Hostname: debian-trixie
2026-09-17 00:56:10.545842 | localhost | Hostname: np0000192963
2026-09-17 00:56:10.545878 | localhost | Username: zuul
2026-09-17 00:56:10.545922 | localhost | Distro: Debian 13.6
2026-09-17 00:56:10.545958 | localhost | Provider: yul1
2026-09-17 00:56:10.545993 | localhost | Region: ca-ymq-1
2026-09-17 00:56:10.546028 | localhost | Label: debian-trixie
2026-09-17 00:56:10.546062 | localhost | Product Name: OpenStack Nova
2026-09-17 00:56:10.546096 | localhost | Interface IP: 162.253.55.31
2026-09-17 00:56:10.566169 | 
2026-09-17 00:56:10.566358 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-17 00:56:10.997010 | localhost -> localhost | changed
2026-09-17 00:56:11.006500 | 
2026-09-17 00:56:11.006643 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-17 00:56:11.954156 | localhost -> localhost | changed
2026-09-17 00:56:11.967263 | 
2026-09-17 00:56:11.967366 | PLAY [all]
2026-09-17 00:56:11.978337 | 
2026-09-17 00:56:11.978429 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-17 00:56:12.194930 | debian-trixie -> localhost | ok
2026-09-17 00:56:12.204524 | 
2026-09-17 00:56:12.204695 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-17 00:56:12.237622 | debian-trixie | ok
2026-09-17 00:56:12.263304 | debian-trixie | included: /var/lib/zuul/builds/c64181515a074c398c879c7e6a5df694/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-17 00:56:12.271041 | 
2026-09-17 00:56:12.271141 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-17 00:56:13.310883 | debian-trixie -> localhost | Generating public/private rsa key pair.
2026-09-17 00:56:13.311043 | debian-trixie -> localhost | Your identification has been saved in /var/lib/zuul/builds/c64181515a074c398c879c7e6a5df694/work/c64181515a074c398c879c7e6a5df694_id_rsa
2026-09-17 00:56:13.311071 | debian-trixie -> localhost | Your public key has been saved in /var/lib/zuul/builds/c64181515a074c398c879c7e6a5df694/work/c64181515a074c398c879c7e6a5df694_id_rsa.pub
2026-09-17 00:56:13.311091 | debian-trixie -> localhost | The key fingerprint is:
2026-09-17 00:56:13.311110 | debian-trixie -> localhost | SHA256:8XZVVCc1bcKbuOAN665GjaG/WuoCmbqaRIq+jNod5xQ zuul-build-sshkey
2026-09-17 00:56:13.311139 | debian-trixie -> localhost | The key's randomart image is:
2026-09-17 00:56:13.311158 | debian-trixie -> localhost | +---[RSA 3072]----+
2026-09-17 00:56:13.311180 | debian-trixie -> localhost | |             ..+O|
2026-09-17 00:56:13.311199 | debian-trixie -> localhost | |              oo=|
2026-09-17 00:56:13.311217 | debian-trixie -> localhost | |        .    ..= |
2026-09-17 00:56:13.311234 | debian-trixie -> localhost | |        .oo ..o  |
2026-09-17 00:56:13.311251 | debian-trixie -> localhost | | . o  E.S=o=..   |
2026-09-17 00:56:13.311281 | debian-trixie -> localhost | |+ +   ..o.+.o    |
2026-09-17 00:56:13.311299 | debian-trixie -> localhost | |oo .. oo..       |
2026-09-17 00:56:13.311317 | debian-trixie -> localhost | |*o ..= oo .      |
2026-09-17 00:56:13.311337 | debian-trixie -> localhost | |O=o .o=oo+.      |
2026-09-17 00:56:13.311354 | debian-trixie -> localhost | +----[SHA256]-----+
2026-09-17 00:56:13.311400 | debian-trixie -> localhost | ok: Runtime: 0:00:00.523239
2026-09-17 00:56:13.315038 | 
2026-09-17 00:56:13.315096 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-17 00:56:13.342081 | debian-trixie | ok
2026-09-17 00:56:13.348675 | debian-trixie | included: /var/lib/zuul/builds/c64181515a074c398c879c7e6a5df694/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-17 00:56:13.354230 | 
2026-09-17 00:56:13.354300 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-17 00:56:13.377500 | debian-trixie | skipping: Conditional result was False
2026-09-17 00:56:13.381631 | 
2026-09-17 00:56:13.381692 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-17 00:56:13.936128 | debian-trixie | changed
2026-09-17 00:56:13.940255 | 
2026-09-17 00:56:13.940339 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-17 00:56:14.184157 | debian-trixie | ok
2026-09-17 00:56:14.193703 | 
2026-09-17 00:56:14.193826 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-17 00:56:14.811802 | debian-trixie | changed
2026-09-17 00:56:14.819512 | 
2026-09-17 00:56:14.819642 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-17 00:56:15.410575 | debian-trixie | changed
2026-09-17 00:56:15.418055 | 
2026-09-17 00:56:15.418182 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-17 00:56:15.443631 | debian-trixie | skipping: Conditional result was False
2026-09-17 00:56:15.451875 | 
2026-09-17 00:56:15.452002 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-17 00:56:15.890382 | debian-trixie -> localhost | changed
2026-09-17 00:56:15.898046 | 
2026-09-17 00:56:15.898106 | TASK [add-build-sshkey : Add back temp key]
2026-09-17 00:56:16.193087 | debian-trixie -> localhost | Identity added: /var/lib/zuul/builds/c64181515a074c398c879c7e6a5df694/work/c64181515a074c398c879c7e6a5df694_id_rsa (zuul-build-sshkey)
2026-09-17 00:56:16.193448 | debian-trixie -> localhost | ok: Runtime: 0:00:00.017069
2026-09-17 00:56:16.206598 | 
2026-09-17 00:56:16.206720 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-17 00:56:16.610629 | debian-trixie | ok
2026-09-17 00:56:16.619048 | 
2026-09-17 00:56:16.619175 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-17 00:56:16.654820 | debian-trixie | skipping: Conditional result was False
2026-09-17 00:56:16.669496 | 
2026-09-17 00:56:16.669623 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-17 00:56:17.066782 | debian-trixie | ok
2026-09-17 00:56:17.076198 | 
2026-09-17 00:56:17.076353 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-17 00:56:17.969089 | debian-trixie | Output suppressed because no_log was given
2026-09-17 00:56:17.985093 | 
2026-09-17 00:56:17.985220 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-17 00:56:18.270600 | debian-trixie | ok: "logs"
2026-09-17 00:56:18.271557 | debian-trixie | ok: All items complete
2026-09-17 00:56:18.271623 | 
2026-09-17 00:56:18.481923 | debian-trixie | ok: "artifacts"
2026-09-17 00:56:18.660067 | debian-trixie | ok: "docs"
2026-09-17 00:56:18.677064 | 
2026-09-17 00:56:18.677298 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-17 00:56:18.896329 | debian-trixie | changed: "logs"
2026-09-17 00:56:19.123415 | debian-trixie | changed: "artifacts"
2026-09-17 00:56:19.299504 | debian-trixie | changed: "docs"
2026-09-17 00:56:19.311521 | 
2026-09-17 00:56:19.311662 | PLAY RECAP
2026-09-17 00:56:19.311715 | debian-trixie | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-17 00:56:19.311741 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-17 00:56:19.311762 | 
2026-09-17 00:56:19.459514 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-17 00:56:19.460985 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-17 00:56:20.045343 | 
2026-09-17 00:56:20.045480 | PLAY [all]
2026-09-17 00:56:20.054995 | 
2026-09-17 00:56:20.055068 | TASK [setup-uv : Extract archive]
2026-09-17 00:56:23.175895 | debian-trixie | changed
2026-09-17 00:56:23.181256 | 
2026-09-17 00:56:23.181352 | TASK [setup-uv : Print version]
2026-09-17 00:56:23.569179 | debian-trixie | uv 0.8.13
2026-09-17 00:56:23.708129 | debian-trixie | ok: Runtime: 0:00:00.011199
2026-09-17 00:56:23.722712 | 
2026-09-17 00:56:23.722928 | TASK [Install Ansible collection siblings]
2026-09-17 00:56:24.183211 | debian-trixie | ok:
2026-09-17 00:56:24.183600 | debian-trixie | Skipping ansible.posix: no checked-out sibling
2026-09-17 00:56:24.183676 | debian-trixie | Skipping community.general: no checked-out sibling
2026-09-17 00:56:24.186835 | 
2026-09-17 00:56:24.186923 | PLAY RECAP
2026-09-17 00:56:24.187011 | debian-trixie | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-17 00:56:24.187054 | 
2026-09-17 00:56:24.344281 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-17 00:56:24.345500 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-17 00:56:24.915278 | 
2026-09-17 00:56:24.915451 | PLAY [all]
2026-09-17 00:56:24.925906 | 
2026-09-17 00:56:24.925984 | LOOP [Check inventory directories]
2026-09-17 00:56:25.284176 | debian-trixie | ok: "src/github.com/vexxhost/ansible-collection-containers"
2026-09-17 00:56:25.485070 | debian-trixie | ok: "src/github.com/vexxhost/ansible-collection-containers/molecule/docker/inventory"
2026-09-17 00:56:25.689241 | debian-trixie | ok: "src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/docker/inventory"
2026-09-17 00:56:25.705584 | 
2026-09-17 00:56:25.705878 | LOOP [Copy inventory file for Zuul]
2026-09-17 00:56:26.639570 | debian-trixie | changed:
2026-09-17 00:56:26.639922 | debian-trixie | {
2026-09-17 00:56:26.639976 | debian-trixie |   "ansible_loop_var": "item",
2026-09-17 00:56:26.640019 | debian-trixie |   "changed": false,
2026-09-17 00:56:26.640056 | debian-trixie |   "failed": false,
2026-09-17 00:56:26.640097 | debian-trixie |   "invocation": {
2026-09-17 00:56:26.640132 | debian-trixie |     "module_args": {
2026-09-17 00:56:26.640165 | debian-trixie |       "checksum_algorithm": "sha1",
2026-09-17 00:56:26.640199 | debian-trixie |       "follow": true,
2026-09-17 00:56:26.640231 | debian-trixie |       "get_attributes": true,
2026-09-17 00:56:26.640287 | debian-trixie |       "get_checksum": true,
2026-09-17 00:56:26.640323 | debian-trixie |       "get_mime": true,
2026-09-17 00:56:26.640357 | debian-trixie |       "path": "src/github.com/vexxhost/ansible-collection-containers"
2026-09-17 00:56:26.640390 | debian-trixie |     }
2026-09-17 00:56:26.640421 | debian-trixie |   },
2026-09-17 00:56:26.640453 | debian-trixie |   "item": "src/github.com/vexxhost/ansible-collection-containers",
2026-09-17 00:56:26.640628 | debian-trixie |   "stat": {
2026-09-17 00:56:26.640693 | debian-trixie |     "atime": 1789606577.833294,
2026-09-17 00:56:26.640732 | debian-trixie |     "attr_flags": "e",
2026-09-17 00:56:26.640765 | debian-trixie |     "attributes": [
2026-09-17 00:56:26.640797 | debian-trixie |       "extents"
2026-09-17 00:56:26.640829 | debian-trixie |     ],
2026-09-17 00:56:26.640860 | debian-trixie |     "block_size": 4096,
2026-09-17 00:56:26.640892 | debian-trixie |     "blocks": 8,
2026-09-17 00:56:26.640924 | debian-trixie |     "charset": "unknown",
2026-09-17 00:56:26.640960 | debian-trixie |     "ctime": 1789606577.8492947,
2026-09-17 00:56:26.640992 | debian-trixie |     "dev": 65025,
2026-09-17 00:56:26.641024 | debian-trixie |     "device_type": 0,
2026-09-17 00:56:26.641055 | debian-trixie |     "executable": true,
2026-09-17 00:56:26.641085 | debian-trixie |     "exists": true,
2026-09-17 00:56:26.641115 | debian-trixie |     "gid": 1000,
2026-09-17 00:56:26.641146 | debian-trixie |     "gr_name": "zuul",
2026-09-17 00:56:26.641177 | debian-trixie |     "inode": 521495,
2026-09-17 00:56:26.641207 | debian-trixie |     "isblk": false,
2026-09-17 00:56:26.641239 | debian-trixie |     "ischr": false,
2026-09-17 00:56:26.641292 | debian-trixie |     "isdir": true,
2026-09-17 00:56:26.641327 | debian-trixie |     "isfifo": false,
2026-09-17 00:56:26.641359 | debian-trixie |     "isgid": false,
2026-09-17 00:56:26.641390 | debian-trixie |     "islnk": false,
2026-09-17 00:56:26.641420 | debian-trixie |     "isreg": false,
2026-09-17 00:56:26.641450 | debian-trixie |     "issock": false,
2026-09-17 00:56:26.641480 | debian-trixie |     "isuid": false,
2026-09-17 00:56:26.641510 | debian-trixie |     "mimetype": "unknown",
2026-09-17 00:56:26.641540 | debian-trixie |     "mode": "0755",
2026-09-17 00:56:26.641570 | debian-trixie |     "mtime": 1789606563.073523,
2026-09-17 00:56:26.641601 | debian-trixie |     "nlink": 11,
2026-09-17 00:56:26.641631 | debian-trixie |     "path": "src/github.com/vexxhost/ansible-collection-containers",
2026-09-17 00:56:26.641662 | debian-trixie |     "pw_name": "zuul",
2026-09-17 00:56:26.641700 | debian-trixie |     "readable": true,
2026-09-17 00:56:26.641732 | debian-trixie |     "rgrp": true,
2026-09-17 00:56:26.641764 | debian-trixie |     "roth": true,
2026-09-17 00:56:26.641795 | debian-trixie |     "rusr": true,
2026-09-17 00:56:26.641825 | debian-trixie |     "size": 4096,
2026-09-17 00:56:26.641855 | debian-trixie |     "uid": 1000,
2026-09-17 00:56:26.641886 | debian-trixie |     "version": "1307417110",
2026-09-17 00:56:26.641918 | debian-trixie |     "wgrp": false,
2026-09-17 00:56:26.641952 | debian-trixie |     "woth": false,
2026-09-17 00:56:26.641984 | debian-trixie |     "writeable": true,
2026-09-17 00:56:26.642015 | debian-trixie |     "wusr": true,
2026-09-17 00:56:26.642046 | debian-trixie |     "xgrp": true,
2026-09-17 00:56:26.642077 | debian-trixie |     "xoth": true,
2026-09-17 00:56:26.642108 | debian-trixie |     "xusr": true
2026-09-17 00:56:26.642139 | debian-trixie |   }
2026-09-17 00:56:26.642170 | debian-trixie | }
2026-09-17 00:56:26.642237 | debian-trixie | ok: All items complete
2026-09-17 00:56:26.642297 | 
2026-09-17 00:56:26.645248 | debian-trixie | skipping: Conditional result was False
2026-09-17 00:56:26.646510 | debian-trixie | skipping: Conditional result was False
2026-09-17 00:56:26.663731 | 
2026-09-17 00:56:26.663961 | LOOP [Switch "ansible_host" to private IP]
2026-09-17 00:56:27.081462 | debian-trixie | changed: 1 replacements made
2026-09-17 00:56:27.085311 | debian-trixie | skipping: Conditional result was False
2026-09-17 00:56:27.087085 | debian-trixie | skipping: Conditional result was False
2026-09-17 00:56:27.101509 | 
2026-09-17 00:56:27.101664 | TASK [Run Molecule scenario]
2026-09-17 00:56:27.620187 | debian-trixie | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-09-17 00:56:27.620331 | debian-trixie | Creating virtual environment at: .venv
2026-09-17 00:56:29.068235 | debian-trixie | Downloading ansible-core (2.3MiB)
2026-09-17 00:56:29.069380 | debian-trixie | Downloading pygments (1.2MiB)
2026-09-17 00:56:29.073713 | debian-trixie | Downloading cryptography (4.3MiB)
2026-09-17 00:56:29.330114 | debian-trixie |  Downloading pygments
2026-09-17 00:56:29.343243 | debian-trixie |  Downloading cryptography
2026-09-17 00:56:29.516548 | debian-trixie |  Downloading ansible-core
2026-09-17 00:56:29.583554 | debian-trixie | Installed 36 packages in 66ms
2026-09-17 00:56:30.284429 | debian-trixie | INFO     Collection 'vexxhost.containers' detected.
2026-09-17 00:56:30.284529 | debian-trixie | INFO     Scenarios will be used from 'extensions/molecule'
2026-09-17 00:56:31.176717 | debian-trixie | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-09-17 00:56:31.176791 | debian-trixie | INFO     default scenario not found, disabling shared state.
2026-09-17 00:56:31.176812 | debian-trixie | INFO     [docker > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-17 00:56:31.176911 | debian-trixie | INFO     [docker > prerun] Performing prerun with role_name_check=0...
2026-09-17 00:58:17.535685 | debian-trixie | WARNING  Retrying execution failure 1 of: ansible-galaxy collection install -vvv ansible.posix:>=1.6.0
2026-09-17 00:58:17.535752 | debian-trixie | ERROR    Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code:
2026-09-17 00:58:17.535757 | debian-trixie | Starting galaxy collection install process
2026-09-17 00:58:17.535761 | debian-trixie | Process install dependency map
2026-09-17 00:58:17.535765 | debian-trixie | Starting collection install process
2026-09-17 00:58:17.535812 | 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-1328mxmbqzmo/tmplvlgfh01/ansible-posix-2.2.2-50b6cwxn
2026-09-17 00:58:17.535816 | debian-trixie |
2026-09-17 00:58:17.535820 | debian-trixie | ansible-galaxy
2026-09-17 00:58:17.535823 | debian-trixie |   config file = None
2026-09-17 00:58:17.535827 | debian-trixie |   configured module search path =
2026-09-17 00:58:17.535830 | debian-trixie |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible
2026-09-17 00:58:17.535840 | 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-17 00:58:17.535845 | debian-trixie |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/ansible-galaxy
2026-09-17 00:58:17.535849 | 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-17 00:58:17.535852 | debian-trixie |   jinja version = 3.1.6
2026-09-17 00:58:17.535855 | debian-trixie |   pyyaml version = 6.0.2 (with libyaml v0.2.5)
2026-09-17 00:58:17.535858 | debian-trixie | No config file found; using defaults
2026-09-17 00:58:17.535864 | 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-17 00:58:17.535867 | debian-trixie | Created /home/zuul/.ansible/galaxy_token
2026-09-17 00:58:17.535870 | debian-trixie | : Failed to download collection tar from 'default' due to the following unforeseen error: The read operation timed out
2026-09-17 00:58:17.535874 | debian-trixie |
2026-09-17 00:58:17.542210 | debian-trixie | Traceback (most recent call last):
2026-09-17 00:58:17.545873 | debian-trixie |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/molecule", line 10, in <module>
2026-09-17 00:58:17.545895 | debian-trixie |     sys.exit(main())
2026-09-17 00:58:17.545903 | debian-trixie |              ~~~~^^
2026-09-17 00:58:17.546232 | 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-17 00:58:17.546244 | debian-trixie |     return self.main(*args, **kwargs)
2026-09-17 00:58:17.546252 | debian-trixie |            ~~~~~~~~~^^^^^^^^^^^^^^^^^
2026-09-17 00:58:17.546628 | 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-17 00:58:17.546645 | debian-trixie |     rv = self.invoke(ctx)
2026-09-17 00:58:17.547016 | 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-17 00:58:17.547029 | debian-trixie |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-09-17 00:58:17.547037 | debian-trixie |                            ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^
2026-09-17 00:58:17.547341 | 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-17 00:58:17.547350 | debian-trixie |     return ctx.invoke(self.callback, **ctx.params)
2026-09-17 00:58:17.547357 | debian-trixie |            ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-17 00:58:17.547642 | 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-17 00:58:17.547652 | debian-trixie |     return callback(*args, **kwargs)
2026-09-17 00:58:17.547900 | 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-17 00:58:17.547908 | debian-trixie |     return func(*args, **kwargs)
2026-09-17 00:58:17.548195 | 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-17 00:58:17.548204 | debian-trixie |     return f(get_current_context(), *args, **kwargs)
2026-09-17 00:58:17.548466 | 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-17 00:58:17.548475 | debian-trixie |     return func(ctx)
2026-09-17 00:58:17.548819 | 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-17 00:58:17.548834 | debian-trixie |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-09-17 00:58:17.548844 | debian-trixie |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-17 00:58:17.549104 | 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-17 00:58:17.549115 | debian-trixie |     _run_scenarios(scenarios, command_args, default_config)
2026-09-17 00:58:17.549123 | debian-trixie |     ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-17 00:58:17.549883 | 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-17 00:58:17.549898 | debian-trixie |     scenario.config.runtime.prepare_environment(
2026-09-17 00:58:17.549906 | debian-trixie |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-17 00:58:17.549913 | debian-trixie |         install_local=True,
2026-09-17 00:58:17.549920 | debian-trixie |         ^^^^^^^^^^^^^^^^^^^
2026-09-17 00:58:17.549927 | debian-trixie |         role_name_check=role_name_check,
2026-09-17 00:58:17.549934 | debian-trixie |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-17 00:58:17.549941 | debian-trixie |     )
2026-09-17 00:58:17.549947 | debian-trixie |     ^
2026-09-17 00:58:17.550338 | 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-17 00:58:17.550349 | debian-trixie |     self.install_collection(
2026-09-17 00:58:17.550359 | debian-trixie |     ~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-17 00:58:17.550368 | debian-trixie |         f"{name}{',' if is_url(name) else ':'}{required_version}",
2026-09-17 00:58:17.550387 | debian-trixie |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-17 00:58:17.550397 | debian-trixie |         destination=destination,
2026-09-17 00:58:17.550405 | debian-trixie |         ^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-17 00:58:17.550414 | debian-trixie |     )
2026-09-17 00:58:17.550421 | debian-trixie |     ^
2026-09-17 00:58:17.550627 | 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-17 00:58:17.550636 | debian-trixie |     raise InvalidPrerequisiteError(msg)
2026-09-17 00:58:17.551640 | debian-trixie | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code:
2026-09-17 00:58:17.551648 | debian-trixie | Starting galaxy collection install process
2026-09-17 00:58:17.551655 | debian-trixie | Process install dependency map
2026-09-17 00:58:17.551662 | debian-trixie | Starting collection install process
2026-09-17 00:58:17.551669 | 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-1328mxmbqzmo/tmplvlgfh01/ansible-posix-2.2.2-50b6cwxn
2026-09-17 00:58:17.551676 | debian-trixie |
2026-09-17 00:58:17.551684 | debian-trixie | ansible-galaxy [core 2.19.1]
2026-09-17 00:58:17.551691 | debian-trixie |   config file = None
2026-09-17 00:58:17.551698 | debian-trixie |   configured module search path = ['/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules']
2026-09-17 00:58:17.551705 | debian-trixie |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible
2026-09-17 00:58:17.551712 | 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-17 00:58:17.551720 | debian-trixie |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/ansible-galaxy
2026-09-17 00:58:17.551727 | 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-17 00:58:17.551734 | debian-trixie |   jinja version = 3.1.6
2026-09-17 00:58:17.551741 | debian-trixie |   pyyaml version = 6.0.2 (with libyaml v0.2.5)
2026-09-17 00:58:17.551748 | debian-trixie | No config file found; using defaults
2026-09-17 00:58:17.551754 | 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-17 00:58:17.551761 | debian-trixie | Created /home/zuul/.ansible/galaxy_token
2026-09-17 00:58:17.551768 | debian-trixie | [ERROR]: Failed to download collection tar from 'default' due to the following unforeseen error: The read operation timed out
2026-09-17 00:58:17.551775 | debian-trixie |
2026-09-17 00:58:17.725838 | debian-trixie | ERROR
2026-09-17 00:58:17.726073 | debian-trixie | {
2026-09-17 00:58:17.726113 | debian-trixie |   "delta": "0:01:50.161178",
2026-09-17 00:58:17.726137 | debian-trixie |   "end": "2026-09-17 00:58:17.667232",
2026-09-17 00:58:17.726159 | debian-trixie |   "msg": "non-zero return code",
2026-09-17 00:58:17.726184 | debian-trixie |   "rc": 1,
2026-09-17 00:58:17.726205 | debian-trixie |   "start": "2026-09-17 00:56:27.506054"
2026-09-17 00:58:17.726226 | debian-trixie | }
failure
2026-09-17 00:58:17.730618 | 
2026-09-17 00:58:17.730686 | PLAY RECAP
2026-09-17 00:58:17.730743 | debian-trixie | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-17 00:58:17.730770 | 
2026-09-17 00:58:17.903042 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-17 00:58:17.904417 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-17 00:58:18.496306 | 
2026-09-17 00:58:18.496461 | PLAY [all]
2026-09-17 00:58:18.506301 | 
2026-09-17 00:58:18.506374 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-17 00:58:18.570910 | debian-trixie | skipping: Conditional result was False
2026-09-17 00:58:18.575972 | 
2026-09-17 00:58:18.576070 | TASK [fetch-output : Set log path for single node]
2026-09-17 00:58:18.627226 | debian-trixie | ok
2026-09-17 00:58:18.634423 | 
2026-09-17 00:58:18.634557 | LOOP [fetch-output : Ensure local output dirs]
2026-09-17 00:58:19.099926 | debian-trixie -> localhost | ok: "/var/lib/zuul/builds/c64181515a074c398c879c7e6a5df694/work/logs"
2026-09-17 00:58:19.365785 | debian-trixie -> localhost | changed: "/var/lib/zuul/builds/c64181515a074c398c879c7e6a5df694/work/artifacts"
2026-09-17 00:58:19.588605 | debian-trixie -> localhost | changed: "/var/lib/zuul/builds/c64181515a074c398c879c7e6a5df694/work/docs"
2026-09-17 00:58:19.604285 | 
2026-09-17 00:58:19.604535 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-17 00:58:20.294060 | debian-trixie | changed: .d..t...... ./
2026-09-17 00:58:20.294450 | debian-trixie | changed: All items complete
2026-09-17 00:58:20.294538 | 
2026-09-17 00:58:20.802494 | debian-trixie | changed: .d..t...... ./
2026-09-17 00:58:21.307034 | debian-trixie | changed: .d..t...... ./
2026-09-17 00:58:21.332356 | 
2026-09-17 00:58:21.332548 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-17 00:58:21.780463 | debian-trixie -> localhost | ok: Item: artifacts Runtime: 0:00:00.008138
2026-09-17 00:58:22.024106 | debian-trixie -> localhost | ok: Item: docs Runtime: 0:00:00.008130
2026-09-17 00:58:22.045591 | 
2026-09-17 00:58:22.045715 | PLAY [all]
2026-09-17 00:58:22.055545 | 
2026-09-17 00:58:22.055711 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-17 00:58:22.577329 | debian-trixie | changed
2026-09-17 00:58:22.585482 | 
2026-09-17 00:58:22.585589 | PLAY RECAP
2026-09-17 00:58:22.585677 | debian-trixie | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-17 00:58:22.585722 | 
2026-09-17 00:58:22.706252 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-17 00:58:22.708665 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-17 00:58:23.273525 | 
2026-09-17 00:58:23.273911 | PLAY [localhost]
2026-09-17 00:58:23.283970 | 
2026-09-17 00:58:23.284054 | TASK [Generate Zuul manifest]
2026-09-17 00:58:23.301098 | localhost | ok
2026-09-17 00:58:23.314018 | 
2026-09-17 00:58:23.314139 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-17 00:58:23.688051 | localhost | changed
2026-09-17 00:58:23.706604 | 
2026-09-17 00:58:23.706749 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-17 00:58:23.749647 | localhost | ok
2026-09-17 00:58:23.761688 | 
2026-09-17 00:58:23.761807 | TASK [Upload logs]
2026-09-17 00:58:23.782230 | localhost | ok
2026-09-17 00:58:23.849809 | 
2026-09-17 00:58:23.849970 | TASK [Set zuul-log-path fact]
2026-09-17 00:58:23.867209 | localhost | ok
2026-09-17 00:58:23.876981 | 
2026-09-17 00:58:23.877041 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-17 00:58:23.906026 | localhost | ok
2026-09-17 00:58:23.914100 | 
2026-09-17 00:58:23.914176 | TASK [upload-logs : Create log directories]
2026-09-17 00:58:24.486070 | localhost | changed
2026-09-17 00:58:24.504408 | 
2026-09-17 00:58:24.504633 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-17 00:58:24.953485 | localhost -> localhost | ok: Runtime: 0:00:00.005880
2026-09-17 00:58:24.958584 | 
2026-09-17 00:58:24.958644 | TASK [upload-logs : Upload logs to log server]
2026-09-17 00:58:25.490882 | localhost | Output suppressed because no_log was given
2026-09-17 00:58:25.498124 | 
2026-09-17 00:58:25.498258 | LOOP [upload-logs : Compress console log and json output]
2026-09-17 00:58:25.574428 | localhost | skipping: Conditional result was False
2026-09-17 00:58:25.583938 | localhost | skipping: Conditional result was False
2026-09-17 00:58:25.595590 | 
2026-09-17 00:58:25.595855 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-17 00:58:25.661934 | localhost | skipping: Conditional result was False
2026-09-17 00:58:25.662381 | 
2026-09-17 00:58:25.667894 | localhost | skipping: Conditional result was False
2026-09-17 00:58:25.680476 | 
2026-09-17 00:58:25.680677 | LOOP [upload-logs : Upload console log and json output]