2026-09-26 00:56:04.164400 | Job console starting
2026-09-26 00:56:04.181929 | Updating git repos
2026-09-26 00:56:04.237913 | Cloning repos into workspace
2026-09-26 00:56:04.286863 | Restoring repo states
2026-09-26 00:56:04.296117 | Merging changes
2026-09-26 00:56:04.799462 | Checking out repos
2026-09-26 00:56:04.848152 | Preparing playbooks
2026-09-26 00:56:06.564734 | Running Ansible setup
2026-09-26 00:56:10.019090 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-26 00:56:10.566281 | 
2026-09-26 00:56:10.566407 | PLAY [localhost]
2026-09-26 00:56:10.572915 | 
2026-09-26 00:56:10.572980 | TASK [Gathering Facts]
2026-09-26 00:56:11.432068 | localhost | ok
2026-09-26 00:56:11.445663 | 
2026-09-26 00:56:11.445817 | TASK [Setup log path fact]
2026-09-26 00:56:11.476933 | localhost | ok
2026-09-26 00:56:11.496866 | 
2026-09-26 00:56:11.497002 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-26 00:56:11.536612 | localhost | ok
2026-09-26 00:56:11.547920 | 
2026-09-26 00:56:11.548052 | TASK [emit-job-header : Print job information]
2026-09-26 00:56:11.598811 | # Job Information
2026-09-26 00:56:11.599009 | Ansible Version: 2.16.18
2026-09-26 00:56:11.599075 | Job: ansible-collection-containers-molecule-nerdctl-ubuntu-focal
2026-09-26 00:56:11.599120 | Pipeline: check
2026-09-26 00:56:11.599160 | Executor: 2d72f0692154
2026-09-26 00:56:11.599199 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/136
2026-09-26 00:56:11.599386 | Event ID: 4fc289a0-b944-11f1-829e-01862b553d14
2026-09-26 00:56:11.604625 | 
2026-09-26 00:56:11.604762 | LOOP [emit-job-header : Print node information]
2026-09-26 00:56:11.709159 | localhost | ok:
2026-09-26 00:56:11.709340 | localhost | # Node Information
2026-09-26 00:56:11.709370 | localhost | Inventory Hostname: ubuntu-focal
2026-09-26 00:56:11.709390 | localhost | Hostname: np0000194033
2026-09-26 00:56:11.709409 | localhost | Username: zuul
2026-09-26 00:56:11.709430 | localhost | Distro: Ubuntu 20.04
2026-09-26 00:56:11.709447 | localhost | Provider: yul1
2026-09-26 00:56:11.709464 | localhost | Region: ca-ymq-1
2026-09-26 00:56:11.709481 | localhost | Label: ubuntu-focal
2026-09-26 00:56:11.709497 | localhost | Product Name: OpenStack Nova
2026-09-26 00:56:11.709514 | localhost | Interface IP: 162.253.55.80
2026-09-26 00:56:11.723986 | 
2026-09-26 00:56:11.724282 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-26 00:56:12.144172 | localhost -> localhost | changed
2026-09-26 00:56:12.158162 | 
2026-09-26 00:56:12.158298 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-26 00:56:12.965109 | localhost -> localhost | changed
2026-09-26 00:56:12.986238 | 
2026-09-26 00:56:12.986337 | PLAY [all]
2026-09-26 00:56:12.998419 | 
2026-09-26 00:56:12.998513 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-26 00:56:13.248588 | ubuntu-focal -> localhost | ok
2026-09-26 00:56:13.262062 | 
2026-09-26 00:56:13.262559 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-26 00:56:13.296767 | ubuntu-focal | ok
2026-09-26 00:56:13.321627 | ubuntu-focal | included: /var/lib/zuul/builds/39660e13f2a84ed0b579521f876ae01e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-26 00:56:13.328437 | 
2026-09-26 00:56:13.328546 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-26 00:56:14.401533 | ubuntu-focal -> localhost | Generating public/private rsa key pair.
2026-09-26 00:56:14.401814 | ubuntu-focal -> localhost | Your identification has been saved in /var/lib/zuul/builds/39660e13f2a84ed0b579521f876ae01e/work/39660e13f2a84ed0b579521f876ae01e_id_rsa
2026-09-26 00:56:14.402228 | ubuntu-focal -> localhost | Your public key has been saved in /var/lib/zuul/builds/39660e13f2a84ed0b579521f876ae01e/work/39660e13f2a84ed0b579521f876ae01e_id_rsa.pub
2026-09-26 00:56:14.402280 | ubuntu-focal -> localhost | The key fingerprint is:
2026-09-26 00:56:14.402351 | ubuntu-focal -> localhost | SHA256:I4ftcHXclZCHphDxeCuooCHXCfcpERVQwWkoSCnP43s zuul-build-sshkey
2026-09-26 00:56:14.402415 | ubuntu-focal -> localhost | The key's randomart image is:
2026-09-26 00:56:14.402457 | ubuntu-focal -> localhost | +---[RSA 3072]----+
2026-09-26 00:56:14.402506 | ubuntu-focal -> localhost | |.o. o*=+ oo  .+ o|
2026-09-26 00:56:14.402548 | ubuntu-focal -> localhost | |o.. ..+  .o. = + |
2026-09-26 00:56:14.402587 | ubuntu-focal -> localhost | |.o..o.   .oo= o  |
2026-09-26 00:56:14.402625 | ubuntu-focal -> localhost | |  ++ + +...o.    |
2026-09-26 00:56:14.402663 | ubuntu-focal -> localhost | |.o.o+ *.S. .     |
2026-09-26 00:56:14.402701 | ubuntu-focal -> localhost | |..+ ...* ..      |
2026-09-26 00:56:14.402738 | ubuntu-focal -> localhost | | . . .  .        |
2026-09-26 00:56:14.402778 | ubuntu-focal -> localhost | |  . E            |
2026-09-26 00:56:14.402822 | ubuntu-focal -> localhost | |   .             |
2026-09-26 00:56:14.402861 | ubuntu-focal -> localhost | +----[SHA256]-----+
2026-09-26 00:56:14.402946 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.603645
2026-09-26 00:56:14.410062 | 
2026-09-26 00:56:14.410143 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-26 00:56:14.442838 | ubuntu-focal | ok
2026-09-26 00:56:14.452759 | ubuntu-focal | included: /var/lib/zuul/builds/39660e13f2a84ed0b579521f876ae01e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-26 00:56:14.461821 | 
2026-09-26 00:56:14.461881 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-26 00:56:14.485307 | ubuntu-focal | skipping: Conditional result was False
2026-09-26 00:56:14.491447 | 
2026-09-26 00:56:14.491529 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-26 00:56:14.906280 | ubuntu-focal | changed
2026-09-26 00:56:14.915273 | 
2026-09-26 00:56:14.915419 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-26 00:56:15.106834 | ubuntu-focal | ok
2026-09-26 00:56:15.112695 | 
2026-09-26 00:56:15.112779 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-26 00:56:15.585906 | ubuntu-focal | changed
2026-09-26 00:56:15.594649 | 
2026-09-26 00:56:15.594773 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-26 00:56:16.042922 | ubuntu-focal | changed
2026-09-26 00:56:16.053172 | 
2026-09-26 00:56:16.053420 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-26 00:56:16.080730 | ubuntu-focal | skipping: Conditional result was False
2026-09-26 00:56:16.092789 | 
2026-09-26 00:56:16.092929 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-26 00:56:16.443684 | ubuntu-focal -> localhost | changed
2026-09-26 00:56:16.463843 | 
2026-09-26 00:56:16.463976 | TASK [add-build-sshkey : Add back temp key]
2026-09-26 00:56:16.740945 | ubuntu-focal -> localhost | Identity added: /var/lib/zuul/builds/39660e13f2a84ed0b579521f876ae01e/work/39660e13f2a84ed0b579521f876ae01e_id_rsa (zuul-build-sshkey)
2026-09-26 00:56:16.741276 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.013739
2026-09-26 00:56:16.763912 | 
2026-09-26 00:56:16.764097 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-26 00:56:17.056039 | ubuntu-focal | ok
2026-09-26 00:56:17.064732 | 
2026-09-26 00:56:17.064857 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-26 00:56:17.099685 | ubuntu-focal | skipping: Conditional result was False
2026-09-26 00:56:17.118682 | 
2026-09-26 00:56:17.118817 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-26 00:56:17.443898 | ubuntu-focal | ok
2026-09-26 00:56:17.450262 | 
2026-09-26 00:56:17.450354 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-26 00:56:18.189683 | ubuntu-focal | Output suppressed because no_log was given
2026-09-26 00:56:18.205195 | 
2026-09-26 00:56:18.205347 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-26 00:56:18.431367 | ubuntu-focal | ok: "logs"
2026-09-26 00:56:18.431539 | ubuntu-focal | ok: All items complete
2026-09-26 00:56:18.431565 | 
2026-09-26 00:56:18.560409 | ubuntu-focal | ok: "artifacts"
2026-09-26 00:56:18.707241 | ubuntu-focal | ok: "docs"
2026-09-26 00:56:18.724424 | 
2026-09-26 00:56:18.724615 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-26 00:56:18.914518 | ubuntu-focal | changed: "logs"
2026-09-26 00:56:19.063981 | ubuntu-focal | changed: "artifacts"
2026-09-26 00:56:19.223240 | ubuntu-focal | changed: "docs"
2026-09-26 00:56:19.236672 | 
2026-09-26 00:56:19.236776 | PLAY RECAP
2026-09-26 00:56:19.236857 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-26 00:56:19.236913 | ubuntu-focal | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-26 00:56:19.236954 | 
2026-09-26 00:56:19.368375 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-26 00:56:19.370622 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-26 00:56:19.972527 | 
2026-09-26 00:56:19.973424 | PLAY [all]
2026-09-26 00:56:19.985146 | 
2026-09-26 00:56:19.985218 | TASK [setup-uv : Extract archive]
2026-09-26 00:56:22.172793 | ubuntu-focal | changed
2026-09-26 00:56:22.187822 | 
2026-09-26 00:56:22.188020 | TASK [setup-uv : Print version]
2026-09-26 00:56:22.656167 | ubuntu-focal | uv 0.8.13
2026-09-26 00:56:22.731600 | ubuntu-focal | ok: Runtime: 0:00:00.009032
2026-09-26 00:56:22.757657 | 
2026-09-26 00:56:22.757782 | TASK [Install Ansible collection siblings]
2026-09-26 00:56:23.150816 | ubuntu-focal | ok:
2026-09-26 00:56:23.150958 | ubuntu-focal | Skipping ansible.posix: no checked-out sibling
2026-09-26 00:56:23.150983 | ubuntu-focal | Skipping community.general: no checked-out sibling
2026-09-26 00:56:23.152357 | 
2026-09-26 00:56:23.152399 | PLAY RECAP
2026-09-26 00:56:23.152437 | ubuntu-focal | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-26 00:56:23.152455 | 
2026-09-26 00:56:23.244341 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-26 00:56:23.245770 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-26 00:56:23.762417 | 
2026-09-26 00:56:23.762523 | PLAY [all]
2026-09-26 00:56:23.772076 | 
2026-09-26 00:56:23.772141 | LOOP [Check inventory directories]
2026-09-26 00:56:24.066190 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers"
2026-09-26 00:56:24.216031 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers/molecule/nerdctl/inventory"
2026-09-26 00:56:24.386966 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/nerdctl/inventory"
2026-09-26 00:56:24.394733 | 
2026-09-26 00:56:24.394952 | LOOP [Copy inventory file for Zuul]
2026-09-26 00:56:25.221704 | ubuntu-focal | changed:
2026-09-26 00:56:25.222051 | ubuntu-focal | {
2026-09-26 00:56:25.222111 | ubuntu-focal |   "ansible_loop_var": "item",
2026-09-26 00:56:25.222156 | ubuntu-focal |   "changed": false,
2026-09-26 00:56:25.222195 | ubuntu-focal |   "failed": false,
2026-09-26 00:56:25.222243 | ubuntu-focal |   "invocation": {
2026-09-26 00:56:25.222281 | ubuntu-focal |     "module_args": {
2026-09-26 00:56:25.222347 | ubuntu-focal |       "checksum_algorithm": "sha1",
2026-09-26 00:56:25.222493 | ubuntu-focal |       "follow": true,
2026-09-26 00:56:25.222564 | ubuntu-focal |       "get_attributes": true,
2026-09-26 00:56:25.222624 | ubuntu-focal |       "get_checksum": true,
2026-09-26 00:56:25.222664 | ubuntu-focal |       "get_mime": true,
2026-09-26 00:56:25.222700 | ubuntu-focal |       "path": "src/github.com/vexxhost/ansible-collection-containers"
2026-09-26 00:56:25.222735 | ubuntu-focal |     }
2026-09-26 00:56:25.222768 | ubuntu-focal |   },
2026-09-26 00:56:25.222802 | ubuntu-focal |   "item": "src/github.com/vexxhost/ansible-collection-containers",
2026-09-26 00:56:25.222856 | ubuntu-focal |   "stat": {
2026-09-26 00:56:25.222892 | ubuntu-focal |     "atime": 1790384178.1007464,
2026-09-26 00:56:25.222927 | ubuntu-focal |     "attr_flags": "e",
2026-09-26 00:56:25.222961 | ubuntu-focal |     "attributes": [
2026-09-26 00:56:25.222994 | ubuntu-focal |       "extents"
2026-09-26 00:56:25.223026 | ubuntu-focal |     ],
2026-09-26 00:56:25.223059 | ubuntu-focal |     "block_size": 4096,
2026-09-26 00:56:25.223093 | ubuntu-focal |     "blocks": 8,
2026-09-26 00:56:25.223127 | ubuntu-focal |     "charset": "binary",
2026-09-26 00:56:25.223162 | ubuntu-focal |     "ctime": 1790384178.1007464,
2026-09-26 00:56:25.223196 | ubuntu-focal |     "dev": 64513,
2026-09-26 00:56:25.223228 | ubuntu-focal |     "device_type": 0,
2026-09-26 00:56:25.223261 | ubuntu-focal |     "executable": true,
2026-09-26 00:56:25.223294 | ubuntu-focal |     "exists": true,
2026-09-26 00:56:25.223361 | ubuntu-focal |     "gid": 1000,
2026-09-26 00:56:25.223400 | ubuntu-focal |     "gr_name": "zuul",
2026-09-26 00:56:25.223434 | ubuntu-focal |     "inode": 1035293,
2026-09-26 00:56:25.223467 | ubuntu-focal |     "isblk": false,
2026-09-26 00:56:25.223501 | ubuntu-focal |     "ischr": false,
2026-09-26 00:56:25.223533 | ubuntu-focal |     "isdir": true,
2026-09-26 00:56:25.223579 | ubuntu-focal |     "isfifo": false,
2026-09-26 00:56:25.223625 | ubuntu-focal |     "isgid": false,
2026-09-26 00:56:25.223660 | ubuntu-focal |     "islnk": false,
2026-09-26 00:56:25.223693 | ubuntu-focal |     "isreg": false,
2026-09-26 00:56:25.223725 | ubuntu-focal |     "issock": false,
2026-09-26 00:56:25.223756 | ubuntu-focal |     "isuid": false,
2026-09-26 00:56:25.223787 | ubuntu-focal |     "mimetype": "inode/directory",
2026-09-26 00:56:25.223819 | ubuntu-focal |     "mode": "0755",
2026-09-26 00:56:25.223851 | ubuntu-focal |     "mtime": 1790384164.3198304,
2026-09-26 00:56:25.223882 | ubuntu-focal |     "nlink": 11,
2026-09-26 00:56:25.223914 | ubuntu-focal |     "path": "src/github.com/vexxhost/ansible-collection-containers",
2026-09-26 00:56:25.223947 | ubuntu-focal |     "pw_name": "zuul",
2026-09-26 00:56:25.223987 | ubuntu-focal |     "readable": true,
2026-09-26 00:56:25.224020 | ubuntu-focal |     "rgrp": true,
2026-09-26 00:56:25.224053 | ubuntu-focal |     "roth": true,
2026-09-26 00:56:25.224087 | ubuntu-focal |     "rusr": true,
2026-09-26 00:56:25.224120 | ubuntu-focal |     "size": 4096,
2026-09-26 00:56:25.224153 | ubuntu-focal |     "uid": 1000,
2026-09-26 00:56:25.224186 | ubuntu-focal |     "version": "2425787826",
2026-09-26 00:56:25.224220 | ubuntu-focal |     "wgrp": false,
2026-09-26 00:56:25.224256 | ubuntu-focal |     "woth": false,
2026-09-26 00:56:25.224289 | ubuntu-focal |     "writeable": true,
2026-09-26 00:56:25.224344 | ubuntu-focal |     "wusr": true,
2026-09-26 00:56:25.224379 | ubuntu-focal |     "xgrp": true,
2026-09-26 00:56:25.224412 | ubuntu-focal |     "xoth": true,
2026-09-26 00:56:25.224443 | ubuntu-focal |     "xusr": true
2026-09-26 00:56:25.224476 | ubuntu-focal |   }
2026-09-26 00:56:25.224756 | ubuntu-focal | }
2026-09-26 00:56:25.224841 | ubuntu-focal | ok: All items complete
2026-09-26 00:56:25.224885 | 
2026-09-26 00:56:25.229054 | ubuntu-focal | skipping: Conditional result was False
2026-09-26 00:56:25.234362 | ubuntu-focal | skipping: Conditional result was False
2026-09-26 00:56:25.256072 | 
2026-09-26 00:56:25.256315 | LOOP [Switch "ansible_host" to private IP]
2026-09-26 00:56:25.670979 | ubuntu-focal | changed: 1 replacements made
2026-09-26 00:56:25.676918 | ubuntu-focal | skipping: Conditional result was False
2026-09-26 00:56:25.678087 | ubuntu-focal | skipping: Conditional result was False
2026-09-26 00:56:25.697351 | 
2026-09-26 00:56:25.697591 | TASK [Run Molecule scenario]
2026-09-26 00:56:26.603496 | ubuntu-focal | Downloading cpython-3.13.7-linux-x86_64-gnu (download) (30.9MiB)
2026-09-26 00:56:28.224716 | ubuntu-focal |  Downloading cpython-3.13.7-linux-x86_64-gnu (download)
2026-09-26 00:56:28.414166 | ubuntu-focal | Using CPython 3.13.7
2026-09-26 00:56:28.414252 | ubuntu-focal | Creating virtual environment at: .venv
2026-09-26 00:56:29.839303 | ubuntu-focal | Downloading pygments (1.2MiB)
2026-09-26 00:56:29.839470 | ubuntu-focal | Downloading ansible-core (2.3MiB)
2026-09-26 00:56:29.844932 | ubuntu-focal | Downloading cryptography (4.3MiB)
2026-09-26 00:56:30.002742 | ubuntu-focal |  Downloading cryptography
2026-09-26 00:56:30.048126 | ubuntu-focal |  Downloading pygments
2026-09-26 00:56:30.198755 | ubuntu-focal |  Downloading ansible-core
2026-09-26 00:56:30.237671 | ubuntu-focal | Installed 36 packages in 38ms
2026-09-26 00:56:31.259713 | ubuntu-focal | INFO     Collection 'vexxhost.containers' detected.
2026-09-26 00:56:31.259797 | ubuntu-focal | INFO     Scenarios will be used from 'extensions/molecule'
2026-09-26 00:56:32.028208 | ubuntu-focal | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-09-26 00:56:32.028303 | ubuntu-focal | INFO     default scenario not found, disabling shared state.
2026-09-26 00:56:32.028327 | ubuntu-focal | INFO     [nerdctl > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-26 00:56:32.028339 | ubuntu-focal | INFO     [nerdctl > prerun] Performing prerun with role_name_check=0...
2026-09-26 01:03:30.103902 | ubuntu-focal | WARNING  Retrying execution failure 1 of: ansible-galaxy collection install -vvv community.general:>=4.5.0
2026-09-26 01:03:30.104029 | ubuntu-focal | ERROR    Command ansible-galaxy collection install -vvv community.general:>=4.5.0, returned 1 code:
2026-09-26 01:03:30.104041 | ubuntu-focal | Starting galaxy collection install process
2026-09-26 01:03:30.104050 | ubuntu-focal | Process install dependency map
2026-09-26 01:03:30.104059 | ubuntu-focal |
2026-09-26 01:03:30.104067 | ubuntu-focal | ansible-galaxy
2026-09-26 01:03:30.104074 | ubuntu-focal |   config file = None
2026-09-26 01:03:30.104082 | ubuntu-focal |   configured module search path =
2026-09-26 01:03:30.104090 | ubuntu-focal |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible
2026-09-26 01:03:30.104100 | ubuntu-focal |   ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages
2026-09-26 01:03:30.104112 | ubuntu-focal |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/ansible-galaxy
2026-09-26 01:03:30.104120 | ubuntu-focal |   python version = 3.13.7 (main, Aug 18 2025, 19:20:03)  (/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/python)
2026-09-26 01:03:30.104128 | ubuntu-focal |   jinja version = 3.1.6
2026-09-26 01:03:30.104136 | ubuntu-focal |   pyyaml version = 6.0.2 (with libyaml v0.2.5)
2026-09-26 01:03:30.104144 | ubuntu-focal | No config file found; using defaults
2026-09-26 01:03:30.104152 | ubuntu-focal | Found installed collection ansible.posix:2.2.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-26 01:03:30.104177 | ubuntu-focal | Found installed collection ansible._protomatter:2.19.1 at '/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/_internal/ansible_collections/ansible/_protomatter'
2026-09-26 01:03:30.104186 | ubuntu-focal | Opened /home/zuul/.ansible/galaxy_token
2026-09-26 01:03:30.104195 | ubuntu-focal | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an unexpected error when getting available versions of collection community.library_inventory_filtering_v1: Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/v3/collections/community/library_inventory_filtering_v1/versions/1.1.5/':  Connection reset by peer
2026-09-26 01:03:30.104204 | ubuntu-focal | : Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/v3/collections/community/library_inventory_filtering_v1/versions/1.1.5/':  Connection reset by peer
2026-09-26 01:03:30.104213 | ubuntu-focal |
2026-09-26 01:03:30.106959 | ubuntu-focal | Traceback (most recent call last):
2026-09-26 01:03:30.110053 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/molecule", line 10, in <module>
2026-09-26 01:03:30.110093 | ubuntu-focal |     sys.exit(main())
2026-09-26 01:03:30.110100 | ubuntu-focal |              ~~~~^^
2026-09-26 01:03:30.110360 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/click/core.py", line 1442, in __call__
2026-09-26 01:03:30.110373 | ubuntu-focal |     return self.main(*args, **kwargs)
2026-09-26 01:03:30.110378 | ubuntu-focal |            ~~~~~~~~~^^^^^^^^^^^^^^^^^
2026-09-26 01:03:30.110582 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/click/core.py", line 1363, in main
2026-09-26 01:03:30.110589 | ubuntu-focal |     rv = self.invoke(ctx)
2026-09-26 01:03:30.110896 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/click/core.py", line 1830, in invoke
2026-09-26 01:03:30.110913 | ubuntu-focal |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-09-26 01:03:30.110921 | ubuntu-focal |                            ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^
2026-09-26 01:03:30.111137 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/click/core.py", line 1226, in invoke
2026-09-26 01:03:30.111150 | ubuntu-focal |     return ctx.invoke(self.callback, **ctx.params)
2026-09-26 01:03:30.111173 | ubuntu-focal |            ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-26 01:03:30.111374 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/click/core.py", line 794, in invoke
2026-09-26 01:03:30.111389 | ubuntu-focal |     return callback(*args, **kwargs)
2026-09-26 01:03:30.111578 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/molecule/click_cfg.py", line 583, in wrapper
2026-09-26 01:03:30.111591 | ubuntu-focal |     return func(*args, **kwargs)
2026-09-26 01:03:30.111799 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/click/decorators.py", line 34, in new_func
2026-09-26 01:03:30.111813 | ubuntu-focal |     return f(get_current_context(), *args, **kwargs)
2026-09-26 01:03:30.112002 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/molecule/click_cfg.py", line 418, in wrapper
2026-09-26 01:03:30.112017 | ubuntu-focal |     return func(ctx)
2026-09-26 01:03:30.112306 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/molecule/command/test.py", line 81, in test
2026-09-26 01:03:30.112318 | ubuntu-focal |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-09-26 01:03:30.112326 | ubuntu-focal |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-26 01:03:30.112550 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/molecule/command/base.py", line 169, in execute_cmdline_scenarios
2026-09-26 01:03:30.112562 | ubuntu-focal |     _run_scenarios(scenarios, command_args, default_config)
2026-09-26 01:03:30.112569 | ubuntu-focal |     ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-26 01:03:30.112944 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/molecule/command/base.py", line 240, in _run_scenarios
2026-09-26 01:03:30.112956 | ubuntu-focal |     scenario.config.runtime.prepare_environment(
2026-09-26 01:03:30.112964 | ubuntu-focal |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-26 01:03:30.112971 | ubuntu-focal |         install_local=True,
2026-09-26 01:03:30.112978 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^
2026-09-26 01:03:30.112985 | ubuntu-focal |         role_name_check=role_name_check,
2026-09-26 01:03:30.112993 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-26 01:03:30.113000 | ubuntu-focal |     )
2026-09-26 01:03:30.113007 | ubuntu-focal |     ^
2026-09-26 01:03:30.113355 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible_compat/runtime.py", line 752, in prepare_environment
2026-09-26 01:03:30.113370 | ubuntu-focal |     self.install_collection(
2026-09-26 01:03:30.113378 | ubuntu-focal |     ~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-26 01:03:30.113393 | ubuntu-focal |         f"{name}{',' if is_url(name) else ':'}{required_version}",
2026-09-26 01:03:30.113400 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-26 01:03:30.113408 | ubuntu-focal |         destination=destination,
2026-09-26 01:03:30.113415 | ubuntu-focal |         ^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-26 01:03:30.113423 | ubuntu-focal |     )
2026-09-26 01:03:30.113430 | ubuntu-focal |     ^
2026-09-26 01:03:30.113572 | ubuntu-focal |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible_compat/runtime.py", line 609, in install_collection
2026-09-26 01:03:30.113584 | ubuntu-focal |     raise InvalidPrerequisiteError(msg)
2026-09-26 01:03:30.114470 | ubuntu-focal | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv community.general:>=4.5.0, returned 1 code:
2026-09-26 01:03:30.114486 | ubuntu-focal | Starting galaxy collection install process
2026-09-26 01:03:30.114494 | ubuntu-focal | Process install dependency map
2026-09-26 01:03:30.114502 | ubuntu-focal |
2026-09-26 01:03:30.114509 | ubuntu-focal | ansible-galaxy [core 2.19.1]
2026-09-26 01:03:30.114516 | ubuntu-focal |   config file = None
2026-09-26 01:03:30.114524 | ubuntu-focal |   configured module search path = ['/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules']
2026-09-26 01:03:30.114531 | ubuntu-focal |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible
2026-09-26 01:03:30.114538 | ubuntu-focal |   ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages
2026-09-26 01:03:30.114546 | ubuntu-focal |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/ansible-galaxy
2026-09-26 01:03:30.114553 | ubuntu-focal |   python version = 3.13.7 (main, Aug 18 2025, 19:20:03) [Clang 20.1.4 ] (/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/python)
2026-09-26 01:03:30.114560 | ubuntu-focal |   jinja version = 3.1.6
2026-09-26 01:03:30.114569 | ubuntu-focal |   pyyaml version = 6.0.2 (with libyaml v0.2.5)
2026-09-26 01:03:30.114577 | ubuntu-focal | No config file found; using defaults
2026-09-26 01:03:30.114585 | ubuntu-focal | Found installed collection ansible.posix:2.2.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-26 01:03:30.114593 | ubuntu-focal | Found installed collection ansible._protomatter:2.19.1 at '/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible/_internal/ansible_collections/ansible/_protomatter'
2026-09-26 01:03:30.114600 | ubuntu-focal | Opened /home/zuul/.ansible/galaxy_token
2026-09-26 01:03:30.114608 | ubuntu-focal | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an unexpected error when getting available versions of collection community.library_inventory_filtering_v1: Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/v3/collections/community/library_inventory_filtering_v1/versions/1.1.5/': [Errno 104] Connection reset by peer
2026-09-26 01:03:30.114621 | ubuntu-focal | [ERROR]: Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/v3/collections/community/library_inventory_filtering_v1/versions/1.1.5/': [Errno 104] Connection reset by peer
2026-09-26 01:03:30.114630 | ubuntu-focal |
2026-09-26 01:03:30.272870 | ubuntu-focal | ERROR
2026-09-26 01:03:30.273127 | ubuntu-focal | {
2026-09-26 01:03:30.273185 | ubuntu-focal |   "delta": "0:07:04.148756",
2026-09-26 01:03:30.273257 | ubuntu-focal |   "end": "2026-09-26 01:03:30.199796",
2026-09-26 01:03:30.273297 | ubuntu-focal |   "msg": "non-zero return code",
2026-09-26 01:03:30.273332 | ubuntu-focal |   "rc": 1,
2026-09-26 01:03:30.273366 | ubuntu-focal |   "start": "2026-09-26 00:56:26.051040"
2026-09-26 01:03:30.273399 | ubuntu-focal | }
failure
2026-09-26 01:03:30.277523 | 
2026-09-26 01:03:30.277613 | PLAY RECAP
2026-09-26 01:03:30.277690 | ubuntu-focal | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-26 01:03:30.277731 | 
2026-09-26 01:03:30.422264 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-26 01:03:30.424383 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-26 01:03:31.077233 | 
2026-09-26 01:03:31.077378 | PLAY [all]
2026-09-26 01:03:31.092434 | 
2026-09-26 01:03:31.092634 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-26 01:03:31.137816 | ubuntu-focal | skipping: Conditional result was False
2026-09-26 01:03:31.144483 | 
2026-09-26 01:03:31.144577 | TASK [fetch-output : Set log path for single node]
2026-09-26 01:03:31.174268 | ubuntu-focal | ok
2026-09-26 01:03:31.179403 | 
2026-09-26 01:03:31.179511 | LOOP [fetch-output : Ensure local output dirs]
2026-09-26 01:03:31.551805 | ubuntu-focal -> localhost | ok: "/var/lib/zuul/builds/39660e13f2a84ed0b579521f876ae01e/work/logs"
2026-09-26 01:03:31.767573 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/39660e13f2a84ed0b579521f876ae01e/work/artifacts"
2026-09-26 01:03:31.977292 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/39660e13f2a84ed0b579521f876ae01e/work/docs"
2026-09-26 01:03:31.997479 | 
2026-09-26 01:03:31.997697 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-26 01:03:32.619983 | ubuntu-focal | changed: .d..t...... ./
2026-09-26 01:03:32.620354 | ubuntu-focal | changed: All items complete
2026-09-26 01:03:32.620412 | 
2026-09-26 01:03:33.046976 | ubuntu-focal | changed: .d..t...... ./
2026-09-26 01:03:33.510384 | ubuntu-focal | changed: .d..t...... ./
2026-09-26 01:03:33.543119 | 
2026-09-26 01:03:33.543297 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-26 01:03:33.976780 | ubuntu-focal -> localhost | ok: Item: artifacts Runtime: 0:00:00.008871
2026-09-26 01:03:34.206552 | ubuntu-focal -> localhost | ok: Item: docs Runtime: 0:00:00.007130
2026-09-26 01:03:34.223393 | 
2026-09-26 01:03:34.223590 | PLAY [all]
2026-09-26 01:03:34.237347 | 
2026-09-26 01:03:34.237606 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-26 01:03:34.639130 | ubuntu-focal | changed
2026-09-26 01:03:34.649571 | 
2026-09-26 01:03:34.649670 | PLAY RECAP
2026-09-26 01:03:34.649752 | ubuntu-focal | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-26 01:03:34.649795 | 
2026-09-26 01:03:34.767160 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-26 01:03:34.769564 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-26 01:03:35.575201 | 
2026-09-26 01:03:35.575537 | PLAY [localhost]
2026-09-26 01:03:35.593327 | 
2026-09-26 01:03:35.593650 | TASK [Generate Zuul manifest]
2026-09-26 01:03:35.615347 | localhost | ok
2026-09-26 01:03:35.630494 | 
2026-09-26 01:03:35.630566 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-26 01:03:36.014768 | localhost | changed
2026-09-26 01:03:36.033822 | 
2026-09-26 01:03:36.033973 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-26 01:03:36.077650 | localhost | ok
2026-09-26 01:03:36.089980 | 
2026-09-26 01:03:36.090099 | TASK [Upload logs]
2026-09-26 01:03:36.111128 | localhost | ok
2026-09-26 01:03:36.188801 | 
2026-09-26 01:03:36.188986 | TASK [Set zuul-log-path fact]
2026-09-26 01:03:36.208201 | localhost | ok
2026-09-26 01:03:36.218440 | 
2026-09-26 01:03:36.218513 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-26 01:03:36.255847 | localhost | ok
2026-09-26 01:03:36.261668 | 
2026-09-26 01:03:36.261733 | TASK [upload-logs : Create log directories]
2026-09-26 01:03:36.672842 | localhost | changed
2026-09-26 01:03:36.678456 | 
2026-09-26 01:03:36.678523 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-26 01:03:37.133556 | localhost -> localhost | ok: Runtime: 0:00:00.004707
2026-09-26 01:03:37.143306 | 
2026-09-26 01:03:37.143443 | TASK [upload-logs : Upload logs to log server]
2026-09-26 01:03:37.664862 | localhost | Output suppressed because no_log was given
2026-09-26 01:03:37.672111 | 
2026-09-26 01:03:37.672266 | LOOP [upload-logs : Compress console log and json output]
2026-09-26 01:03:37.734534 | localhost | skipping: Conditional result was False
2026-09-26 01:03:37.743361 | localhost | skipping: Conditional result was False
2026-09-26 01:03:37.757312 | 
2026-09-26 01:03:37.757548 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-26 01:03:37.802464 | localhost | skipping: Conditional result was False
2026-09-26 01:03:37.803002 | 
2026-09-26 01:03:37.805348 | localhost | skipping: Conditional result was False
2026-09-26 01:03:37.822853 | 
2026-09-26 01:03:37.823081 | LOOP [upload-logs : Upload console log and json output]