2026-09-26 00:58:11.374200 | Job console starting 2026-09-26 00:58:11.388868 | Updating git repos 2026-09-26 00:58:11.439621 | Cloning repos into workspace 2026-09-26 00:58:11.578823 | Restoring repo states 2026-09-26 00:58:11.586099 | Merging changes 2026-09-26 00:58:12.281400 | Checking out repos 2026-09-26 00:58:12.313424 | Preparing playbooks 2026-09-26 00:58:13.810052 | Running Ansible setup 2026-09-26 00:58:17.283997 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-26 00:58:17.809581 | 2026-09-26 00:58:17.809688 | PLAY [localhost] 2026-09-26 00:58:17.816428 | 2026-09-26 00:58:17.816518 | TASK [Gathering Facts] 2026-09-26 00:58:18.711690 | localhost | ok 2026-09-26 00:58:18.717227 | 2026-09-26 00:58:18.717295 | TASK [Setup log path fact] 2026-09-26 00:58:18.732600 | localhost | ok 2026-09-26 00:58:18.740869 | 2026-09-26 00:58:18.740931 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-26 00:58:18.756056 | localhost | ok 2026-09-26 00:58:18.760986 | 2026-09-26 00:58:18.761045 | TASK [emit-job-header : Print job information] 2026-09-26 00:58:18.811213 | # Job Information 2026-09-26 00:58:18.811513 | Ansible Version: 2.16.18 2026-09-26 00:58:18.811618 | Job: ansible-collection-containers-molecule-docker-upgrade-ubuntu-jammy 2026-09-26 00:58:18.811697 | Pipeline: check 2026-09-26 00:58:18.811767 | Executor: 2d72f0692154 2026-09-26 00:58:18.811838 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/136 2026-09-26 00:58:18.811917 | Event ID: 4fc289a0-b944-11f1-829e-01862b553d14 2026-09-26 00:58:18.818148 | 2026-09-26 00:58:18.818302 | LOOP [emit-job-header : Print node information] 2026-09-26 00:58:18.923706 | localhost | ok: 2026-09-26 00:58:18.923969 | localhost | # Node Information 2026-09-26 00:58:18.924069 | localhost | Inventory Hostname: ubuntu-jammy 2026-09-26 00:58:18.924134 | localhost | Hostname: np0000194041 2026-09-26 00:58:18.924204 | localhost | Username: zuul 2026-09-26 00:58:18.924254 | localhost | Distro: Ubuntu 22.04 2026-09-26 00:58:18.924295 | localhost | Provider: yul1 2026-09-26 00:58:18.924334 | localhost | Region: ca-ymq-1 2026-09-26 00:58:18.924373 | localhost | Label: ubuntu-jammy 2026-09-26 00:58:18.924414 | localhost | Product Name: OpenStack Nova 2026-09-26 00:58:18.924452 | localhost | Interface IP: 162.253.55.193 2026-09-26 00:58:18.935886 | 2026-09-26 00:58:18.936024 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-26 00:58:19.310787 | localhost -> localhost | changed 2026-09-26 00:58:19.318589 | 2026-09-26 00:58:19.318700 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-26 00:58:20.341594 | localhost -> localhost | changed 2026-09-26 00:58:20.357257 | 2026-09-26 00:58:20.357538 | PLAY [all] 2026-09-26 00:58:20.373945 | 2026-09-26 00:58:20.374082 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-26 00:58:20.745960 | ubuntu-jammy -> localhost | ok 2026-09-26 00:58:20.755709 | 2026-09-26 00:58:20.755835 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-26 00:58:20.788652 | ubuntu-jammy | ok 2026-09-26 00:58:20.812748 | ubuntu-jammy | included: /var/lib/zuul/builds/d74d0fa2836443e5bf9cf8d7259010c8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-26 00:58:20.821647 | 2026-09-26 00:58:20.821728 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-26 00:58:21.997610 | ubuntu-jammy -> localhost | Generating public/private rsa key pair. 2026-09-26 00:58:21.997862 | ubuntu-jammy -> localhost | Your identification has been saved in /var/lib/zuul/builds/d74d0fa2836443e5bf9cf8d7259010c8/work/d74d0fa2836443e5bf9cf8d7259010c8_id_rsa 2026-09-26 00:58:21.997920 | ubuntu-jammy -> localhost | Your public key has been saved in /var/lib/zuul/builds/d74d0fa2836443e5bf9cf8d7259010c8/work/d74d0fa2836443e5bf9cf8d7259010c8_id_rsa.pub 2026-09-26 00:58:21.997965 | ubuntu-jammy -> localhost | The key fingerprint is: 2026-09-26 00:58:21.998008 | ubuntu-jammy -> localhost | SHA256:6qoWCvEjqBz8SJOj19uTW9rgmHY+lTFvtwFm9D5hws8 zuul-build-sshkey 2026-09-26 00:58:21.998065 | ubuntu-jammy -> localhost | The key's randomart image is: 2026-09-26 00:58:21.998107 | ubuntu-jammy -> localhost | +---[RSA 3072]----+ 2026-09-26 00:58:21.998175 | ubuntu-jammy -> localhost | | | 2026-09-26 00:58:21.998221 | ubuntu-jammy -> localhost | | . | 2026-09-26 00:58:21.998262 | ubuntu-jammy -> localhost | | o . | 2026-09-26 00:58:21.998302 | ubuntu-jammy -> localhost | |. o * + | 2026-09-26 00:58:21.998341 | ubuntu-jammy -> localhost | |oo. SB B . | 2026-09-26 00:58:21.998379 | ubuntu-jammy -> localhost | |+B+ .o o E | 2026-09-26 00:58:21.998416 | ubuntu-jammy -> localhost | |*o*+ oo.. . + | 2026-09-26 00:58:21.998455 | ubuntu-jammy -> localhost | |+oo.o*== . | 2026-09-26 00:58:21.998500 | ubuntu-jammy -> localhost | | o.o==B+. | 2026-09-26 00:58:21.998540 | ubuntu-jammy -> localhost | +----[SHA256]-----+ 2026-09-26 00:58:21.998633 | ubuntu-jammy -> localhost | ok: Runtime: 0:00:00.724657 2026-09-26 00:58:22.008065 | 2026-09-26 00:58:22.008231 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-26 00:58:22.030433 | ubuntu-jammy | ok 2026-09-26 00:58:22.044771 | ubuntu-jammy | included: /var/lib/zuul/builds/d74d0fa2836443e5bf9cf8d7259010c8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-26 00:58:22.053869 | 2026-09-26 00:58:22.053950 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-26 00:58:22.077425 | ubuntu-jammy | skipping: Conditional result was False 2026-09-26 00:58:22.087857 | 2026-09-26 00:58:22.087977 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-26 00:58:22.521361 | ubuntu-jammy | changed 2026-09-26 00:58:22.526022 | 2026-09-26 00:58:22.526085 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-26 00:58:22.704996 | ubuntu-jammy | ok 2026-09-26 00:58:22.716558 | 2026-09-26 00:58:22.716686 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-26 00:58:23.224713 | ubuntu-jammy | changed 2026-09-26 00:58:23.237337 | 2026-09-26 00:58:23.237499 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-26 00:58:23.723881 | ubuntu-jammy | changed 2026-09-26 00:58:23.730852 | 2026-09-26 00:58:23.730933 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-26 00:58:23.754777 | ubuntu-jammy | skipping: Conditional result was False 2026-09-26 00:58:23.762184 | 2026-09-26 00:58:23.762274 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-26 00:58:24.100292 | ubuntu-jammy -> localhost | changed 2026-09-26 00:58:24.143554 | 2026-09-26 00:58:24.144100 | TASK [add-build-sshkey : Add back temp key] 2026-09-26 00:58:24.426779 | ubuntu-jammy -> localhost | Identity added: /var/lib/zuul/builds/d74d0fa2836443e5bf9cf8d7259010c8/work/d74d0fa2836443e5bf9cf8d7259010c8_id_rsa (zuul-build-sshkey) 2026-09-26 00:58:24.426949 | ubuntu-jammy -> localhost | ok: Runtime: 0:00:00.015681 2026-09-26 00:58:24.431279 | 2026-09-26 00:58:24.431338 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-26 00:58:24.746536 | ubuntu-jammy | ok 2026-09-26 00:58:24.755353 | 2026-09-26 00:58:24.755483 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-26 00:58:24.790192 | ubuntu-jammy | skipping: Conditional result was False 2026-09-26 00:58:24.808859 | 2026-09-26 00:58:24.808998 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-26 00:58:25.200964 | ubuntu-jammy | ok 2026-09-26 00:58:25.212336 | 2026-09-26 00:58:25.212474 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-26 00:58:25.929202 | ubuntu-jammy | Output suppressed because no_log was given 2026-09-26 00:58:25.936695 | 2026-09-26 00:58:25.936756 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-26 00:58:26.140102 | ubuntu-jammy | ok: "logs" 2026-09-26 00:58:26.140491 | ubuntu-jammy | ok: All items complete 2026-09-26 00:58:26.140580 | 2026-09-26 00:58:26.359572 | ubuntu-jammy | ok: "artifacts" 2026-09-26 00:58:26.454595 | ubuntu-jammy | ok: "docs" 2026-09-26 00:58:26.463021 | 2026-09-26 00:58:26.463133 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-26 00:58:26.642633 | ubuntu-jammy | changed: "logs" 2026-09-26 00:58:26.783380 | ubuntu-jammy | changed: "artifacts" 2026-09-26 00:58:26.927325 | ubuntu-jammy | changed: "docs" 2026-09-26 00:58:26.942450 | 2026-09-26 00:58:26.942597 | PLAY RECAP 2026-09-26 00:58:26.942679 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-26 00:58:26.942738 | ubuntu-jammy | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-26 00:58:26.942782 | 2026-09-26 00:58:27.066908 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-26 00:58:27.068167 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-26 00:58:27.573284 | 2026-09-26 00:58:27.573393 | PLAY [all] 2026-09-26 00:58:27.583066 | 2026-09-26 00:58:27.583138 | TASK [setup-uv : Extract archive] 2026-09-26 00:58:30.018004 | ubuntu-jammy | changed 2026-09-26 00:58:30.029233 | 2026-09-26 00:58:30.029414 | TASK [setup-uv : Print version] 2026-09-26 00:58:30.405191 | ubuntu-jammy | uv 0.8.13 2026-09-26 00:58:30.593290 | ubuntu-jammy | ok: Runtime: 0:00:00.012500 2026-09-26 00:58:30.613045 | 2026-09-26 00:58:30.613230 | TASK [Install Ansible collection siblings] 2026-09-26 00:58:30.983008 | ubuntu-jammy | ok: 2026-09-26 00:58:30.983280 | ubuntu-jammy | Skipping ansible.posix: no checked-out sibling 2026-09-26 00:58:30.983338 | ubuntu-jammy | Skipping community.general: no checked-out sibling 2026-09-26 00:58:30.986157 | 2026-09-26 00:58:30.986251 | PLAY RECAP 2026-09-26 00:58:30.986336 | ubuntu-jammy | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-26 00:58:30.986378 | 2026-09-26 00:58:31.086420 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-26 00:58:31.089369 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-26 00:58:31.708982 | 2026-09-26 00:58:31.709109 | PLAY [all] 2026-09-26 00:58:31.719501 | 2026-09-26 00:58:31.719586 | LOOP [Check inventory directories] 2026-09-26 00:58:31.990219 | ubuntu-jammy | ok: "src/github.com/vexxhost/ansible-collection-containers" 2026-09-26 00:58:32.144037 | ubuntu-jammy | ok: "src/github.com/vexxhost/ansible-collection-containers/molecule/docker-upgrade/inventory" 2026-09-26 00:58:32.289310 | ubuntu-jammy | ok: "src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/docker-upgrade/inventory" 2026-09-26 00:58:32.313727 | 2026-09-26 00:58:32.313981 | LOOP [Copy inventory file for Zuul] 2026-09-26 00:58:33.145161 | ubuntu-jammy | changed: 2026-09-26 00:58:33.145366 | ubuntu-jammy | { 2026-09-26 00:58:33.145399 | ubuntu-jammy | "ansible_loop_var": "item", 2026-09-26 00:58:33.145426 | ubuntu-jammy | "changed": false, 2026-09-26 00:58:33.145448 | ubuntu-jammy | "failed": false, 2026-09-26 00:58:33.145479 | ubuntu-jammy | "invocation": { 2026-09-26 00:58:33.145504 | ubuntu-jammy | "module_args": { 2026-09-26 00:58:33.145526 | ubuntu-jammy | "checksum_algorithm": "sha1", 2026-09-26 00:58:33.145548 | ubuntu-jammy | "follow": true, 2026-09-26 00:58:33.145568 | ubuntu-jammy | "get_attributes": true, 2026-09-26 00:58:33.145590 | ubuntu-jammy | "get_checksum": true, 2026-09-26 00:58:33.145612 | ubuntu-jammy | "get_mime": true, 2026-09-26 00:58:33.145633 | ubuntu-jammy | "path": "src/github.com/vexxhost/ansible-collection-containers" 2026-09-26 00:58:33.145654 | ubuntu-jammy | } 2026-09-26 00:58:33.145674 | ubuntu-jammy | }, 2026-09-26 00:58:33.145695 | ubuntu-jammy | "item": "src/github.com/vexxhost/ansible-collection-containers", 2026-09-26 00:58:33.145731 | ubuntu-jammy | "stat": { 2026-09-26 00:58:33.145753 | ubuntu-jammy | "atime": 1790384305.8159962, 2026-09-26 00:58:33.145775 | ubuntu-jammy | "attr_flags": "e", 2026-09-26 00:58:33.145795 | ubuntu-jammy | "attributes": [ 2026-09-26 00:58:33.145816 | ubuntu-jammy | "extents" 2026-09-26 00:58:33.145839 | ubuntu-jammy | ], 2026-09-26 00:58:33.145860 | ubuntu-jammy | "block_size": 4096, 2026-09-26 00:58:33.145881 | ubuntu-jammy | "blocks": 8, 2026-09-26 00:58:33.145902 | ubuntu-jammy | "charset": "unknown", 2026-09-26 00:58:33.145923 | ubuntu-jammy | "ctime": 1790384305.8239963, 2026-09-26 00:58:33.145944 | ubuntu-jammy | "dev": 64513, 2026-09-26 00:58:33.145966 | ubuntu-jammy | "device_type": 0, 2026-09-26 00:58:33.145986 | ubuntu-jammy | "executable": true, 2026-09-26 00:58:33.146005 | ubuntu-jammy | "exists": true, 2026-09-26 00:58:33.146025 | ubuntu-jammy | "gid": 1000, 2026-09-26 00:58:33.146046 | ubuntu-jammy | "gr_name": "zuul", 2026-09-26 00:58:33.146067 | ubuntu-jammy | "inode": 1043485, 2026-09-26 00:58:33.146088 | ubuntu-jammy | "isblk": false, 2026-09-26 00:58:33.146108 | ubuntu-jammy | "ischr": false, 2026-09-26 00:58:33.146129 | ubuntu-jammy | "isdir": true, 2026-09-26 00:58:33.146164 | ubuntu-jammy | "isfifo": false, 2026-09-26 00:58:33.146186 | ubuntu-jammy | "isgid": false, 2026-09-26 00:58:33.146205 | ubuntu-jammy | "islnk": false, 2026-09-26 00:58:33.146227 | ubuntu-jammy | "isreg": false, 2026-09-26 00:58:33.146247 | ubuntu-jammy | "issock": false, 2026-09-26 00:58:33.146266 | ubuntu-jammy | "isuid": false, 2026-09-26 00:58:33.146285 | ubuntu-jammy | "mimetype": "unknown", 2026-09-26 00:58:33.146305 | ubuntu-jammy | "mode": "0755", 2026-09-26 00:58:33.146326 | ubuntu-jammy | "mtime": 1790384291.6105766, 2026-09-26 00:58:33.146346 | ubuntu-jammy | "nlink": 11, 2026-09-26 00:58:33.146365 | ubuntu-jammy | "path": "src/github.com/vexxhost/ansible-collection-containers", 2026-09-26 00:58:33.146385 | ubuntu-jammy | "pw_name": "zuul", 2026-09-26 00:58:33.146436 | ubuntu-jammy | "readable": true, 2026-09-26 00:58:33.146464 | ubuntu-jammy | "rgrp": true, 2026-09-26 00:58:33.146484 | ubuntu-jammy | "roth": true, 2026-09-26 00:58:33.146503 | ubuntu-jammy | "rusr": true, 2026-09-26 00:58:33.146523 | ubuntu-jammy | "size": 4096, 2026-09-26 00:58:33.146544 | ubuntu-jammy | "uid": 1000, 2026-09-26 00:58:33.146566 | ubuntu-jammy | "version": "49657304", 2026-09-26 00:58:33.146586 | ubuntu-jammy | "wgrp": false, 2026-09-26 00:58:33.146609 | ubuntu-jammy | "woth": false, 2026-09-26 00:58:33.146632 | ubuntu-jammy | "writeable": true, 2026-09-26 00:58:33.146653 | ubuntu-jammy | "wusr": true, 2026-09-26 00:58:33.146673 | ubuntu-jammy | "xgrp": true, 2026-09-26 00:58:33.146695 | ubuntu-jammy | "xoth": true, 2026-09-26 00:58:33.146714 | ubuntu-jammy | "xusr": true 2026-09-26 00:58:33.146734 | ubuntu-jammy | } 2026-09-26 00:58:33.146756 | ubuntu-jammy | } 2026-09-26 00:58:33.146795 | ubuntu-jammy | ok: All items complete 2026-09-26 00:58:33.146818 | 2026-09-26 00:58:33.148247 | ubuntu-jammy | skipping: Conditional result was False 2026-09-26 00:58:33.148946 | ubuntu-jammy | skipping: Conditional result was False 2026-09-26 00:58:33.164779 | 2026-09-26 00:58:33.164959 | LOOP [Switch "ansible_host" to private IP] 2026-09-26 00:58:33.517203 | ubuntu-jammy | changed: 1 replacements made 2026-09-26 00:58:33.520559 | ubuntu-jammy | skipping: Conditional result was False 2026-09-26 00:58:33.531978 | ubuntu-jammy | skipping: Conditional result was False 2026-09-26 00:58:33.550432 | 2026-09-26 00:58:33.550598 | TASK [Run Molecule scenario] 2026-09-26 00:58:34.442040 | ubuntu-jammy | Downloading cpython-3.13.7-linux-x86_64-gnu (download) (30.9MiB) 2026-09-26 00:58:35.325580 | ubuntu-jammy | Downloading cpython-3.13.7-linux-x86_64-gnu (download) 2026-09-26 00:58:35.519544 | ubuntu-jammy | Using CPython 3.13.7 2026-09-26 00:58:35.519815 | ubuntu-jammy | Creating virtual environment at: .venv 2026-09-26 00:58:36.156037 | ubuntu-jammy | Downloading pygments (1.2MiB) 2026-09-26 00:58:36.157286 | ubuntu-jammy | Downloading cryptography (4.3MiB) 2026-09-26 00:58:36.157719 | ubuntu-jammy | Downloading ansible-core (2.3MiB) 2026-09-26 00:58:36.306024 | ubuntu-jammy | Downloading cryptography 2026-09-26 00:58:36.360249 | ubuntu-jammy | Downloading pygments 2026-09-26 00:58:36.517747 | ubuntu-jammy | Downloading ansible-core 2026-09-26 00:58:36.561026 | ubuntu-jammy | Installed 36 packages in 42ms 2026-09-26 00:58:37.617719 | ubuntu-jammy | INFO Collection 'vexxhost.containers' detected. 2026-09-26 00:58:37.617812 | ubuntu-jammy | INFO Scenarios will be used from 'extensions/molecule' 2026-09-26 00:58:38.410579 | ubuntu-jammy | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-09-26 00:58:38.410666 | ubuntu-jammy | INFO default scenario not found, disabling shared state. 2026-09-26 00:58:38.410682 | ubuntu-jammy | INFO [docker-upgrade > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-26 00:58:38.410771 | ubuntu-jammy | INFO [docker-upgrade > prerun] Performing prerun with role_name_check=0... 2026-09-26 00:58:41.812544 | ubuntu-jammy | WARNING Retrying execution failure 1 of: ansible-galaxy collection install -vvv ansible.posix:>=1.6.0 2026-09-26 00:58:41.812698 | ubuntu-jammy | ERROR Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code: 2026-09-26 00:58:41.812707 | ubuntu-jammy | Starting galaxy collection install process 2026-09-26 00:58:41.812712 | ubuntu-jammy | Process install dependency map 2026-09-26 00:58:41.812717 | ubuntu-jammy | 2026-09-26 00:58:41.812722 | ubuntu-jammy | ansible-galaxy 2026-09-26 00:58:41.812727 | ubuntu-jammy | config file = None 2026-09-26 00:58:41.812731 | ubuntu-jammy | configured module search path = 2026-09-26 00:58:41.812736 | ubuntu-jammy | ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible 2026-09-26 00:58:41.812743 | ubuntu-jammy | ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages 2026-09-26 00:58:41.812751 | ubuntu-jammy | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/ansible-galaxy 2026-09-26 00:58:41.812756 | ubuntu-jammy | python version = 3.13.7 (main, Aug 18 2025, 19:20:03) (/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/python) 2026-09-26 00:58:41.812761 | ubuntu-jammy | jinja version = 3.1.6 2026-09-26 00:58:41.812766 | ubuntu-jammy | pyyaml version = 6.0.2 (with libyaml v0.2.5) 2026-09-26 00:58:41.812770 | ubuntu-jammy | No config file found; using defaults 2026-09-26 00:58:41.812776 | ubuntu-jammy | 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 00:58:41.812780 | ubuntu-jammy | Created /home/zuul/.ansible/galaxy_token 2026-09-26 00:58:41.812787 | ubuntu-jammy | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an unexpected error when getting available versions of collection ansible.posix: Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/v3/collections/ansible/posix/versions/2.2.2/': 2026-09-26 00:58:41.812793 | ubuntu-jammy | : Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/v3/collections/ansible/posix/versions/2.2.2/': 2026-09-26 00:58:41.812797 | ubuntu-jammy | 2026-09-26 00:58:41.812802 | ubuntu-jammy | Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/v3/collections/ansible/posix/versions/2.2.2/'. 2026-09-26 00:58:41.812806 | ubuntu-jammy | 2026-09-26 00:58:41.812811 | ubuntu-jammy | <<< caused by >>> 2026-09-26 00:58:41.812816 | ubuntu-jammy | 2026-09-26 00:58:41.812820 | ubuntu-jammy | 2026-09-26 00:58:41.812825 | ubuntu-jammy | 2026-09-26 00:58:41.812847 | ubuntu-jammy | 2026-09-26 00:58:41.816021 | ubuntu-jammy | Traceback (most recent call last): 2026-09-26 00:58:41.819736 | ubuntu-jammy | File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/molecule", line 10, in 2026-09-26 00:58:41.819749 | ubuntu-jammy | sys.exit(main()) 2026-09-26 00:58:41.819753 | ubuntu-jammy | ~~~~^^ 2026-09-26 00:58:41.820054 | ubuntu-jammy | File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/click/core.py", line 1442, in __call__ 2026-09-26 00:58:41.820068 | ubuntu-jammy | return self.main(*args, **kwargs) 2026-09-26 00:58:41.820075 | ubuntu-jammy | ~~~~~~~~~^^^^^^^^^^^^^^^^^ 2026-09-26 00:58:41.820384 | ubuntu-jammy | File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/click/core.py", line 1363, in main 2026-09-26 00:58:41.820393 | ubuntu-jammy | rv = self.invoke(ctx) 2026-09-26 00:58:41.820704 | ubuntu-jammy | File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/click/core.py", line 1830, in invoke 2026-09-26 00:58:41.820714 | ubuntu-jammy | return _process_result(sub_ctx.command.invoke(sub_ctx)) 2026-09-26 00:58:41.820721 | ubuntu-jammy | ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^ 2026-09-26 00:58:41.820994 | ubuntu-jammy | File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/click/core.py", line 1226, in invoke 2026-09-26 00:58:41.821006 | ubuntu-jammy | return ctx.invoke(self.callback, **ctx.params) 2026-09-26 00:58:41.821013 | ubuntu-jammy | ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-26 00:58:41.821472 | ubuntu-jammy | File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/click/core.py", line 794, in invoke 2026-09-26 00:58:41.821483 | ubuntu-jammy | return callback(*args, **kwargs) 2026-09-26 00:58:41.821724 | ubuntu-jammy | File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/molecule/click_cfg.py", line 583, in wrapper 2026-09-26 00:58:41.821733 | ubuntu-jammy | return func(*args, **kwargs) 2026-09-26 00:58:41.821935 | ubuntu-jammy | File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/click/decorators.py", line 34, in new_func 2026-09-26 00:58:41.821943 | ubuntu-jammy | return f(get_current_context(), *args, **kwargs) 2026-09-26 00:58:41.822203 | ubuntu-jammy | File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/molecule/click_cfg.py", line 418, in wrapper 2026-09-26 00:58:41.822216 | ubuntu-jammy | return func(ctx) 2026-09-26 00:58:41.822532 | ubuntu-jammy | File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/molecule/command/test.py", line 81, in test 2026-09-26 00:58:41.822542 | ubuntu-jammy | base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude) 2026-09-26 00:58:41.822551 | ubuntu-jammy | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-26 00:58:41.822791 | ubuntu-jammy | File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/molecule/command/base.py", line 169, in execute_cmdline_scenarios 2026-09-26 00:58:41.822799 | ubuntu-jammy | _run_scenarios(scenarios, command_args, default_config) 2026-09-26 00:58:41.822805 | ubuntu-jammy | ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-26 00:58:41.823267 | ubuntu-jammy | File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/molecule/command/base.py", line 240, in _run_scenarios 2026-09-26 00:58:41.823279 | ubuntu-jammy | scenario.config.runtime.prepare_environment( 2026-09-26 00:58:41.823285 | ubuntu-jammy | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-26 00:58:41.823292 | ubuntu-jammy | install_local=True, 2026-09-26 00:58:41.823298 | ubuntu-jammy | ^^^^^^^^^^^^^^^^^^^ 2026-09-26 00:58:41.823305 | ubuntu-jammy | role_name_check=role_name_check, 2026-09-26 00:58:41.823310 | ubuntu-jammy | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-26 00:58:41.823317 | ubuntu-jammy | ) 2026-09-26 00:58:41.823323 | ubuntu-jammy | ^ 2026-09-26 00:58:41.823709 | ubuntu-jammy | File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible_compat/runtime.py", line 752, in prepare_environment 2026-09-26 00:58:41.823725 | ubuntu-jammy | self.install_collection( 2026-09-26 00:58:41.823732 | ubuntu-jammy | ~~~~~~~~~~~~~~~~~~~~~~~^ 2026-09-26 00:58:41.823738 | ubuntu-jammy | f"{name}{',' if is_url(name) else ':'}{required_version}", 2026-09-26 00:58:41.823745 | ubuntu-jammy | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-26 00:58:41.823751 | ubuntu-jammy | destination=destination, 2026-09-26 00:58:41.823757 | ubuntu-jammy | ^^^^^^^^^^^^^^^^^^^^^^^^ 2026-09-26 00:58:41.823763 | ubuntu-jammy | ) 2026-09-26 00:58:41.823769 | ubuntu-jammy | ^ 2026-09-26 00:58:41.823934 | ubuntu-jammy | File "/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible_compat/runtime.py", line 609, in install_collection 2026-09-26 00:58:41.823942 | ubuntu-jammy | raise InvalidPrerequisiteError(msg) 2026-09-26 00:58:41.825119 | ubuntu-jammy | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv ansible.posix:>=1.6.0, returned 1 code: 2026-09-26 00:58:41.825131 | ubuntu-jammy | Starting galaxy collection install process 2026-09-26 00:58:41.825138 | ubuntu-jammy | Process install dependency map 2026-09-26 00:58:41.825145 | ubuntu-jammy | 2026-09-26 00:58:41.825152 | ubuntu-jammy | ansible-galaxy [core 2.19.1] 2026-09-26 00:58:41.825158 | ubuntu-jammy | config file = None 2026-09-26 00:58:41.825164 | ubuntu-jammy | configured module search path = ['/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules'] 2026-09-26 00:58:41.825170 | ubuntu-jammy | ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages/ansible 2026-09-26 00:58:41.825177 | ubuntu-jammy | ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/lib/python3.13/site-packages 2026-09-26 00:58:41.825184 | ubuntu-jammy | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/ansible-galaxy 2026-09-26 00:58:41.825190 | ubuntu-jammy | python version = 3.13.7 (main, Aug 18 2025, 19:20:03) [Clang 20.1.4 ] (/home/zuul/src/github.com/vexxhost/ansible-collection-containers/.venv/bin/python) 2026-09-26 00:58:41.825196 | ubuntu-jammy | jinja version = 3.1.6 2026-09-26 00:58:41.825202 | ubuntu-jammy | pyyaml version = 6.0.2 (with libyaml v0.2.5) 2026-09-26 00:58:41.825208 | ubuntu-jammy | No config file found; using defaults 2026-09-26 00:58:41.825214 | ubuntu-jammy | 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 00:58:41.825221 | ubuntu-jammy | Created /home/zuul/.ansible/galaxy_token 2026-09-26 00:58:41.825231 | ubuntu-jammy | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an unexpected error when getting available versions of collection ansible.posix: Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/v3/collections/ansible/posix/versions/2.2.2/': 2026-09-26 00:58:41.825238 | ubuntu-jammy | [ERROR]: Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/v3/collections/ansible/posix/versions/2.2.2/': 2026-09-26 00:58:41.825245 | ubuntu-jammy | 2026-09-26 00:58:41.825251 | ubuntu-jammy | Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/v3/collections/ansible/posix/versions/2.2.2/'. 2026-09-26 00:58:41.825257 | ubuntu-jammy | 2026-09-26 00:58:41.825263 | ubuntu-jammy | <<< caused by >>> 2026-09-26 00:58:41.825269 | ubuntu-jammy | 2026-09-26 00:58:41.825275 | ubuntu-jammy | 2026-09-26 00:58:41.825281 | ubuntu-jammy | 2026-09-26 00:58:41.825287 | ubuntu-jammy | 2026-09-26 00:58:42.113181 | ubuntu-jammy | ERROR 2026-09-26 00:58:42.113548 | ubuntu-jammy | { 2026-09-26 00:58:42.113617 | ubuntu-jammy | "delta": "0:00:07.986070", 2026-09-26 00:58:42.113662 | ubuntu-jammy | "end": "2026-09-26 00:58:41.916838", 2026-09-26 00:58:42.113700 | ubuntu-jammy | "msg": "non-zero return code", 2026-09-26 00:58:42.113739 | ubuntu-jammy | "rc": 1, 2026-09-26 00:58:42.113774 | ubuntu-jammy | "start": "2026-09-26 00:58:33.930768" 2026-09-26 00:58:42.113810 | ubuntu-jammy | } failure 2026-09-26 00:58:42.118287 | 2026-09-26 00:58:42.118399 | PLAY RECAP 2026-09-26 00:58:42.118512 | ubuntu-jammy | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-26 00:58:42.118560 | 2026-09-26 00:58:42.298877 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-26 00:58:42.301339 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-26 00:58:42.824710 | 2026-09-26 00:58:42.824817 | PLAY [all] 2026-09-26 00:58:42.834248 | 2026-09-26 00:58:42.834318 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-26 00:58:42.888043 | ubuntu-jammy | skipping: Conditional result was False 2026-09-26 00:58:42.893990 | 2026-09-26 00:58:42.894087 | TASK [fetch-output : Set log path for single node] 2026-09-26 00:58:42.945545 | ubuntu-jammy | ok 2026-09-26 00:58:42.954035 | 2026-09-26 00:58:42.954189 | LOOP [fetch-output : Ensure local output dirs] 2026-09-26 00:58:43.405432 | ubuntu-jammy -> localhost | ok: "/var/lib/zuul/builds/d74d0fa2836443e5bf9cf8d7259010c8/work/logs" 2026-09-26 00:58:43.633090 | ubuntu-jammy -> localhost | changed: "/var/lib/zuul/builds/d74d0fa2836443e5bf9cf8d7259010c8/work/artifacts" 2026-09-26 00:58:43.850891 | ubuntu-jammy -> localhost | changed: "/var/lib/zuul/builds/d74d0fa2836443e5bf9cf8d7259010c8/work/docs" 2026-09-26 00:58:43.866497 | 2026-09-26 00:58:43.866728 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-26 00:58:44.559480 | ubuntu-jammy | ok 2026-09-26 00:58:44.559752 | ubuntu-jammy | changed: All items complete 2026-09-26 00:58:44.559805 | 2026-09-26 00:58:45.039885 | ubuntu-jammy | changed: .d..t...... ./ 2026-09-26 00:58:45.525495 | ubuntu-jammy | changed: .d..t...... ./ 2026-09-26 00:58:45.535719 | 2026-09-26 00:58:45.536317 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-26 00:58:45.954471 | ubuntu-jammy -> localhost | ok: Item: artifacts Runtime: 0:00:00.006337 2026-09-26 00:58:46.184857 | ubuntu-jammy -> localhost | ok: Item: docs Runtime: 0:00:00.007387 2026-09-26 00:58:46.200423 | 2026-09-26 00:58:46.200518 | PLAY [all] 2026-09-26 00:58:46.207871 | 2026-09-26 00:58:46.207947 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-26 00:58:46.656468 | ubuntu-jammy | changed 2026-09-26 00:58:46.667621 | 2026-09-26 00:58:46.667717 | PLAY RECAP 2026-09-26 00:58:46.667818 | ubuntu-jammy | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-26 00:58:46.667862 | 2026-09-26 00:58:46.797565 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-26 00:58:46.798642 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-26 00:58:47.410813 | 2026-09-26 00:58:47.410942 | PLAY [localhost] 2026-09-26 00:58:47.423473 | 2026-09-26 00:58:47.423596 | TASK [Generate Zuul manifest] 2026-09-26 00:58:47.439541 | localhost | ok 2026-09-26 00:58:47.452042 | 2026-09-26 00:58:47.452113 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-26 00:58:47.827076 | localhost | changed 2026-09-26 00:58:47.845157 | 2026-09-26 00:58:47.845311 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-26 00:58:47.876877 | localhost | ok 2026-09-26 00:58:47.888764 | 2026-09-26 00:58:47.888883 | TASK [Upload logs] 2026-09-26 00:58:47.908373 | localhost | ok 2026-09-26 00:58:47.972793 | 2026-09-26 00:58:47.972867 | TASK [Set zuul-log-path fact] 2026-09-26 00:58:47.988925 | localhost | ok 2026-09-26 00:58:48.001275 | 2026-09-26 00:58:48.001357 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-26 00:58:48.040113 | localhost | ok 2026-09-26 00:58:48.050113 | 2026-09-26 00:58:48.050207 | TASK [upload-logs : Create log directories] 2026-09-26 00:58:48.455270 | localhost | changed 2026-09-26 00:58:48.465324 | 2026-09-26 00:58:48.465463 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-26 00:58:48.841413 | localhost -> localhost | ok: Runtime: 0:00:00.004702 2026-09-26 00:58:48.850929 | 2026-09-26 00:58:48.851066 | TASK [upload-logs : Upload logs to log server] 2026-09-26 00:58:49.318366 | localhost | Output suppressed because no_log was given 2026-09-26 00:58:49.324998 | 2026-09-26 00:58:49.325155 | LOOP [upload-logs : Compress console log and json output] 2026-09-26 00:58:49.386925 | localhost | skipping: Conditional result was False 2026-09-26 00:58:49.397512 | localhost | skipping: Conditional result was False 2026-09-26 00:58:49.407616 | 2026-09-26 00:58:49.407776 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-26 00:58:49.472047 | localhost | skipping: Conditional result was False 2026-09-26 00:58:49.472650 | 2026-09-26 00:58:49.478783 | localhost | skipping: Conditional result was False 2026-09-26 00:58:49.491331 | 2026-09-26 00:58:49.491492 | LOOP [upload-logs : Upload console log and json output]