2026-08-18 09:17:41.867822 | Job console starting 2026-08-18 09:17:41.882721 | Updating git repos 2026-08-18 09:17:41.912756 | Cloning repos into workspace 2026-08-18 09:17:41.946213 | Restoring repo states 2026-08-18 09:17:41.951116 | Merging changes 2026-08-18 09:17:42.515491 | Checking out repos 2026-08-18 09:17:42.825892 | Preparing playbooks 2026-08-18 09:17:44.349511 | Running Ansible setup 2026-08-18 09:17:48.295855 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-08-18 09:17:48.933949 | 2026-08-18 09:17:48.934128 | PLAY [localhost] 2026-08-18 09:17:48.942429 | 2026-08-18 09:17:48.942519 | TASK [Gathering Facts] 2026-08-18 09:17:49.782142 | localhost | ok 2026-08-18 09:17:49.795198 | 2026-08-18 09:17:49.795318 | TASK [Setup log path fact] 2026-08-18 09:17:49.817373 | localhost | ok 2026-08-18 09:17:49.830004 | 2026-08-18 09:17:49.830111 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-18 09:17:49.861257 | localhost | ok 2026-08-18 09:17:49.871348 | 2026-08-18 09:17:49.871547 | TASK [emit-job-header : Print job information] 2026-08-18 09:17:49.914520 | # Job Information 2026-08-18 09:17:49.914784 | Ansible Version: 2.16.18 2026-08-18 09:17:49.914842 | Job: ansible-collection-ceph-molecule-ha-ubuntu-jammy 2026-08-18 09:17:49.914881 | Pipeline: check 2026-08-18 09:17:49.914918 | Executor: 2d72f0692154 2026-08-18 09:17:49.914958 | Triggered by: https://github.com/vexxhost/ansible-collection-ceph/pull/123 2026-08-18 09:17:49.915000 | Event ID: 1c4de010-9ae5-11f1-8883-6bded93dd05e 2026-08-18 09:17:49.920258 | 2026-08-18 09:17:49.920370 | LOOP [emit-job-header : Print node information] 2026-08-18 09:17:50.018095 | localhost | ok: 2026-08-18 09:17:50.018356 | localhost | # Node Information 2026-08-18 09:17:50.018397 | localhost | Inventory Hostname: mon1 2026-08-18 09:17:50.018427 | localhost | Hostname: np0000189963 2026-08-18 09:17:50.018457 | localhost | Username: zuul 2026-08-18 09:17:50.018489 | localhost | Distro: Ubuntu 22.04 2026-08-18 09:17:50.018517 | localhost | Provider: yul1 2026-08-18 09:17:50.018545 | localhost | Region: ca-ymq-1 2026-08-18 09:17:50.018570 | localhost | Label: ubuntu-jammy 2026-08-18 09:17:50.018596 | localhost | Product Name: OpenStack Nova 2026-08-18 09:17:50.018622 | localhost | Interface IP: 199.204.45.61 2026-08-18 09:17:50.060638 | localhost | ok: 2026-08-18 09:17:50.060746 | localhost | # Node Information 2026-08-18 09:17:50.060782 | localhost | Inventory Hostname: osd1 2026-08-18 09:17:50.060811 | localhost | Hostname: np0000189964 2026-08-18 09:17:50.060838 | localhost | Username: zuul 2026-08-18 09:17:50.060864 | localhost | Distro: Ubuntu 22.04 2026-08-18 09:17:50.060892 | localhost | Provider: yul1 2026-08-18 09:17:50.060917 | localhost | Region: ca-ymq-1 2026-08-18 09:17:50.060944 | localhost | Label: ubuntu-jammy 2026-08-18 09:17:50.060979 | localhost | Product Name: OpenStack Nova 2026-08-18 09:17:50.061026 | localhost | Interface IP: 199.204.45.144 2026-08-18 09:17:50.073179 | 2026-08-18 09:17:50.073366 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-18 09:17:50.494799 | localhost -> localhost | changed 2026-08-18 09:17:50.503441 | 2026-08-18 09:17:50.503785 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-18 09:17:51.321860 | localhost -> localhost | changed 2026-08-18 09:17:51.331529 | 2026-08-18 09:17:51.331607 | PLAY [all] 2026-08-18 09:17:51.340474 | 2026-08-18 09:17:51.340541 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-18 09:17:51.558808 | mon1 -> localhost | ok 2026-08-18 09:17:51.570845 | 2026-08-18 09:17:51.570914 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-18 09:17:51.605152 | mon1 | ok 2026-08-18 09:17:51.610494 | osd1 | ok 2026-08-18 09:17:51.635165 | mon1 | included: /var/lib/zuul/builds/cdeed2c0f31144a29889c27dafbad7c4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-18 09:17:51.635274 | osd1 | included: /var/lib/zuul/builds/cdeed2c0f31144a29889c27dafbad7c4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-18 09:17:51.641066 | 2026-08-18 09:17:51.641160 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-18 09:17:52.419367 | mon1 -> localhost | Generating public/private rsa key pair. 2026-08-18 09:17:52.419527 | mon1 -> localhost | Your identification has been saved in /var/lib/zuul/builds/cdeed2c0f31144a29889c27dafbad7c4/work/cdeed2c0f31144a29889c27dafbad7c4_id_rsa 2026-08-18 09:17:52.419557 | mon1 -> localhost | Your public key has been saved in /var/lib/zuul/builds/cdeed2c0f31144a29889c27dafbad7c4/work/cdeed2c0f31144a29889c27dafbad7c4_id_rsa.pub 2026-08-18 09:17:52.419580 | mon1 -> localhost | The key fingerprint is: 2026-08-18 09:17:52.419602 | mon1 -> localhost | SHA256:MBx5TD4hkEyd1wcSFKeFSSvj+PUDdwxl+75g7eRG4Ys zuul-build-sshkey 2026-08-18 09:17:52.419622 | mon1 -> localhost | The key's randomart image is: 2026-08-18 09:17:52.419642 | mon1 -> localhost | +---[RSA 3072]----+ 2026-08-18 09:17:52.419662 | mon1 -> localhost | | oo+oBO*+.o | 2026-08-18 09:17:52.419683 | mon1 -> localhost | | o.++*B.o.. | 2026-08-18 09:17:52.419703 | mon1 -> localhost | | *o= ... | 2026-08-18 09:17:52.419723 | mon1 -> localhost | | o = . o .. | 2026-08-18 09:17:52.419742 | mon1 -> localhost | | . . S . o... | 2026-08-18 09:17:52.419761 | mon1 -> localhost | | . . + . oo | 2026-08-18 09:17:52.419779 | mon1 -> localhost | | . o oo+. | 2026-08-18 09:17:52.419798 | mon1 -> localhost | | oE=o. | 2026-08-18 09:17:52.419816 | mon1 -> localhost | | .+ | 2026-08-18 09:17:52.419835 | mon1 -> localhost | +----[SHA256]-----+ 2026-08-18 09:17:52.419882 | mon1 -> localhost | ok: Runtime: 0:00:00.374856 2026-08-18 09:17:52.424728 | 2026-08-18 09:17:52.424787 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-18 09:17:52.456149 | mon1 | ok 2026-08-18 09:17:52.462544 | osd1 | ok 2026-08-18 09:17:52.474037 | mon1 | included: /var/lib/zuul/builds/cdeed2c0f31144a29889c27dafbad7c4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-18 09:17:52.474082 | osd1 | included: /var/lib/zuul/builds/cdeed2c0f31144a29889c27dafbad7c4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-18 09:17:52.483732 | 2026-08-18 09:17:52.483791 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-18 09:17:52.505179 | mon1 | skipping: Conditional result was False 2026-08-18 09:17:52.515970 | osd1 | skipping: Conditional result was False 2026-08-18 09:17:52.521096 | 2026-08-18 09:17:52.521161 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-18 09:17:52.967880 | osd1 | changed 2026-08-18 09:17:52.995751 | mon1 | changed 2026-08-18 09:17:53.002006 | 2026-08-18 09:17:53.002068 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-18 09:17:53.191761 | mon1 | ok 2026-08-18 09:17:53.199572 | osd1 | ok 2026-08-18 09:17:53.206547 | 2026-08-18 09:17:53.206634 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-18 09:17:53.672002 | osd1 | changed 2026-08-18 09:17:53.691404 | mon1 | changed 2026-08-18 09:17:53.698250 | 2026-08-18 09:17:53.698347 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-18 09:17:54.210430 | mon1 | changed 2026-08-18 09:17:54.214696 | osd1 | changed 2026-08-18 09:17:54.219423 | 2026-08-18 09:17:54.219507 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-18 09:17:54.242533 | mon1 | skipping: Conditional result was False 2026-08-18 09:17:54.253564 | osd1 | skipping: Conditional result was False 2026-08-18 09:17:54.263046 | 2026-08-18 09:17:54.263110 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-18 09:17:54.581709 | mon1 -> localhost | changed 2026-08-18 09:17:54.601374 | 2026-08-18 09:17:54.601450 | TASK [add-build-sshkey : Add back temp key] 2026-08-18 09:17:55.192803 | mon1 -> localhost | Identity added: /var/lib/zuul/builds/cdeed2c0f31144a29889c27dafbad7c4/work/cdeed2c0f31144a29889c27dafbad7c4_id_rsa (zuul-build-sshkey) 2026-08-18 09:17:55.193092 | mon1 -> localhost | ok: Runtime: 0:00:00.013136 2026-08-18 09:17:55.200234 | 2026-08-18 09:17:55.200320 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-18 09:17:55.465480 | osd1 | ok 2026-08-18 09:17:55.473389 | mon1 | ok 2026-08-18 09:17:55.479009 | 2026-08-18 09:17:55.479073 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-18 09:17:55.505429 | mon1 | skipping: Conditional result was False 2026-08-18 09:17:55.516480 | osd1 | skipping: Conditional result was False 2026-08-18 09:17:55.534260 | 2026-08-18 09:17:55.534326 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-08-18 09:17:55.810275 | mon1 | ok 2026-08-18 09:17:55.814706 | osd1 | ok 2026-08-18 09:17:55.820024 | 2026-08-18 09:17:55.820085 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-08-18 09:17:56.479126 | mon1 | Output suppressed because no_log was given 2026-08-18 09:17:56.538254 | osd1 | Output suppressed because no_log was given 2026-08-18 09:17:56.559523 | 2026-08-18 09:17:56.559636 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-08-18 09:17:56.751447 | mon1 | ok: "logs" 2026-08-18 09:17:56.751690 | mon1 | ok: All items complete 2026-08-18 09:17:56.751718 | 2026-08-18 09:17:56.757600 | osd1 | ok: "logs" 2026-08-18 09:17:56.903406 | mon1 | ok: "artifacts" 2026-08-18 09:17:56.922757 | osd1 | ok: "artifacts" 2026-08-18 09:17:57.061533 | mon1 | ok: "docs" 2026-08-18 09:17:57.069530 | osd1 | ok: "docs" 2026-08-18 09:17:57.090740 | 2026-08-18 09:17:57.091004 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-08-18 09:17:57.269618 | mon1 | changed: "logs" 2026-08-18 09:17:57.269864 | mon1 | ok: All items complete 2026-08-18 09:17:57.269897 | 2026-08-18 09:17:57.282394 | osd1 | changed: "logs" 2026-08-18 09:17:57.426452 | mon1 | changed: "artifacts" 2026-08-18 09:17:57.446198 | osd1 | changed: "artifacts" 2026-08-18 09:17:57.585590 | mon1 | changed: "docs" 2026-08-18 09:17:57.615578 | osd1 | changed: "docs" 2026-08-18 09:17:57.638728 | 2026-08-18 09:17:57.638786 | PLAY RECAP 2026-08-18 09:17:57.638832 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-18 09:17:57.638861 | mon1 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-08-18 09:17:57.638887 | osd1 | ok: 11 changed: 5 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-08-18 09:17:57.638906 | 2026-08-18 09:17:57.753284 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-08-18 09:17:57.755623 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-08-18 09:17:58.354795 | 2026-08-18 09:17:58.354936 | PLAY [all] 2026-08-18 09:17:58.366282 | 2026-08-18 09:17:58.366367 | TASK [setup-uv : Extract archive] 2026-08-18 09:18:00.680855 | mon1 | changed 2026-08-18 09:18:01.502713 | osd1 | changed 2026-08-18 09:18:01.512514 | 2026-08-18 09:18:01.512760 | TASK [setup-uv : Print version] 2026-08-18 09:18:01.859700 | mon1 | uv 0.8.13 2026-08-18 09:18:01.874173 | osd1 | uv 0.8.13 2026-08-18 09:18:02.075770 | mon1 | ok: Runtime: 0:00:00.011290 2026-08-18 09:18:02.083838 | osd1 | ok: Runtime: 0:00:00.014076 2026-08-18 09:18:02.097368 | 2026-08-18 09:18:02.097435 | TASK [Install Ansible collection siblings] 2026-08-18 09:18:02.434717 | mon1 | ok: 2026-08-18 09:18:02.434899 | mon1 | Skipping community.crypto: no checked-out sibling 2026-08-18 09:18:02.434939 | mon1 | Skipping community.docker: no checked-out sibling 2026-08-18 09:18:02.434968 | mon1 | Skipping community.general: no checked-out sibling 2026-08-18 09:18:02.435001 | mon1 | Skipping ansible.utils: no checked-out sibling 2026-08-18 09:18:02.435032 | mon1 | Skipping vexxhost.containers: no checked-out sibling 2026-08-18 09:18:02.437287 | 2026-08-18 09:18:02.437362 | PLAY RECAP 2026-08-18 09:18:02.437438 | mon1 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-18 09:18:02.437478 | osd1 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-18 09:18:02.437504 | 2026-08-18 09:18:02.550530 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-08-18 09:18:02.555339 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-08-18 09:18:03.119813 | 2026-08-18 09:18:03.119935 | PLAY [all] 2026-08-18 09:18:03.130958 | 2026-08-18 09:18:03.131056 | LOOP [Check inventory directories] 2026-08-18 09:18:03.448438 | mon1 | ok: "src/github.com/vexxhost/ansible-collection-ceph" 2026-08-18 09:18:03.597621 | mon1 | ok: "src/github.com/vexxhost/ansible-collection-ceph/molecule/default/inventory" 2026-08-18 09:18:03.742175 | mon1 | ok: "src/github.com/vexxhost/ansible-collection-ceph/extensions/molecule/default/inventory" 2026-08-18 09:18:03.762170 | 2026-08-18 09:18:03.762403 | LOOP [Copy inventory file for Zuul] 2026-08-18 09:18:04.484790 | mon1 | changed: 2026-08-18 09:18:04.485062 | mon1 | { 2026-08-18 09:18:04.485104 | mon1 | "ansible_loop_var": "item", 2026-08-18 09:18:04.485138 | mon1 | "changed": false, 2026-08-18 09:18:04.485168 | mon1 | "failed": false, 2026-08-18 09:18:04.485200 | mon1 | "invocation": { 2026-08-18 09:18:04.485228 | mon1 | "module_args": { 2026-08-18 09:18:04.485255 | mon1 | "checksum_algorithm": "sha1", 2026-08-18 09:18:04.485281 | mon1 | "follow": true, 2026-08-18 09:18:04.485308 | mon1 | "get_attributes": true, 2026-08-18 09:18:04.485335 | mon1 | "get_checksum": true, 2026-08-18 09:18:04.485362 | mon1 | "get_mime": true, 2026-08-18 09:18:04.485420 | mon1 | "path": "src/github.com/vexxhost/ansible-collection-ceph" 2026-08-18 09:18:04.485448 | mon1 | } 2026-08-18 09:18:04.485474 | mon1 | }, 2026-08-18 09:18:04.485500 | mon1 | "item": "src/github.com/vexxhost/ansible-collection-ceph", 2026-08-18 09:18:04.485526 | mon1 | "stat": { 2026-08-18 09:18:04.485551 | mon1 | "atime": 1787044676.376566, 2026-08-18 09:18:04.485578 | mon1 | "attr_flags": "e", 2026-08-18 09:18:04.485604 | mon1 | "attributes": [ 2026-08-18 09:18:04.485630 | mon1 | "extents" 2026-08-18 09:18:04.485656 | mon1 | ], 2026-08-18 09:18:04.485681 | mon1 | "block_size": 4096, 2026-08-18 09:18:04.485707 | mon1 | "blocks": 8, 2026-08-18 09:18:04.485733 | mon1 | "charset": "unknown", 2026-08-18 09:18:04.485760 | mon1 | "ctime": 1787044676.384568, 2026-08-18 09:18:04.485785 | mon1 | "dev": 64513, 2026-08-18 09:18:04.485811 | mon1 | "device_type": 0, 2026-08-18 09:18:04.485837 | mon1 | "executable": true, 2026-08-18 09:18:04.485862 | mon1 | "exists": true, 2026-08-18 09:18:04.485888 | mon1 | "gid": 1000, 2026-08-18 09:18:04.485913 | mon1 | "gr_name": "zuul", 2026-08-18 09:18:04.485939 | mon1 | "inode": 1042461, 2026-08-18 09:18:04.485965 | mon1 | "isblk": false, 2026-08-18 09:18:04.485991 | mon1 | "ischr": false, 2026-08-18 09:18:04.486017 | mon1 | "isdir": true, 2026-08-18 09:18:04.486043 | mon1 | "isfifo": false, 2026-08-18 09:18:04.486068 | mon1 | "isgid": false, 2026-08-18 09:18:04.486093 | mon1 | "islnk": false, 2026-08-18 09:18:04.486118 | mon1 | "isreg": false, 2026-08-18 09:18:04.486142 | mon1 | "issock": false, 2026-08-18 09:18:04.486166 | mon1 | "isuid": false, 2026-08-18 09:18:04.486191 | mon1 | "mimetype": "unknown", 2026-08-18 09:18:04.486216 | mon1 | "mode": "0755", 2026-08-18 09:18:04.486240 | mon1 | "mtime": 1787044662.4688947, 2026-08-18 09:18:04.486266 | mon1 | "nlink": 10, 2026-08-18 09:18:04.486290 | mon1 | "path": "src/github.com/vexxhost/ansible-collection-ceph", 2026-08-18 09:18:04.486315 | mon1 | "pw_name": "zuul", 2026-08-18 09:18:04.486342 | mon1 | "readable": true, 2026-08-18 09:18:04.486367 | mon1 | "rgrp": true, 2026-08-18 09:18:04.486407 | mon1 | "roth": true, 2026-08-18 09:18:04.486434 | mon1 | "rusr": true, 2026-08-18 09:18:04.486459 | mon1 | "size": 4096, 2026-08-18 09:18:04.486484 | mon1 | "uid": 1000, 2026-08-18 09:18:04.486510 | mon1 | "version": "1523981583", 2026-08-18 09:18:04.486536 | mon1 | "wgrp": false, 2026-08-18 09:18:04.486564 | mon1 | "woth": false, 2026-08-18 09:18:04.486590 | mon1 | "writeable": true, 2026-08-18 09:18:04.486617 | mon1 | "wusr": true, 2026-08-18 09:18:04.486643 | mon1 | "xgrp": true, 2026-08-18 09:18:04.486668 | mon1 | "xoth": true, 2026-08-18 09:18:04.486692 | mon1 | "xusr": true 2026-08-18 09:18:04.486718 | mon1 | } 2026-08-18 09:18:04.486744 | mon1 | } 2026-08-18 09:18:04.486788 | mon1 | ok: All items complete 2026-08-18 09:18:04.486818 | 2026-08-18 09:18:04.488404 | mon1 | skipping: Conditional result was False 2026-08-18 09:18:04.488978 | mon1 | skipping: Conditional result was False 2026-08-18 09:18:04.504230 | 2026-08-18 09:18:04.504409 | LOOP [Switch "ansible_host" to private IP] 2026-08-18 09:18:04.840284 | mon1 | changed: 2 replacements made 2026-08-18 09:18:04.841627 | mon1 | skipping: Conditional result was False 2026-08-18 09:18:04.842264 | mon1 | skipping: Conditional result was False 2026-08-18 09:18:04.862906 | 2026-08-18 09:18:04.863077 | TASK [Run Molecule scenario] 2026-08-18 09:18:05.414394 | mon1 | Downloading cpython-3.13.7-linux-x86_64-gnu (download) (30.9MiB) 2026-08-18 09:18:06.141268 | mon1 | Downloading cpython-3.13.7-linux-x86_64-gnu (download) 2026-08-18 09:18:06.334878 | mon1 | Using CPython 3.13.7 2026-08-18 09:18:06.334963 | mon1 | Creating virtual environment at: .venv 2026-08-18 09:18:06.377529 | mon1 | Downloading pygments (1.2MiB) 2026-08-18 09:18:06.380787 | mon1 | Downloading netaddr (2.2MiB) 2026-08-18 09:18:06.381007 | mon1 | Downloading ansible-core (2.1MiB) 2026-08-18 09:18:06.381199 | mon1 | Downloading cryptography (4.3MiB) 2026-08-18 09:18:06.576875 | mon1 | Downloading netaddr 2026-08-18 09:18:06.585294 | mon1 | Downloading cryptography 2026-08-18 09:18:06.624564 | mon1 | Downloading pygments 2026-08-18 09:18:06.749539 | mon1 | Downloading ansible-core 2026-08-18 09:18:06.786886 | mon1 | Installed 42 packages in 36ms 2026-08-18 09:18:07.866782 | mon1 | INFO Collection 'vexxhost.ceph' detected. 2026-08-18 09:18:07.866854 | mon1 | INFO Scenarios will be used from 'extensions/molecule' 2026-08-18 09:18:08.492954 | mon1 | INFO [default > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-08-18 09:18:08.493035 | mon1 | INFO [default > prerun] Performing prerun with role_name_check=0... 2026-08-18 09:19:21.630868 | mon1 | WARNING Retrying execution failure 250 of: ansible-galaxy collection install -vvv community.docker:>=4.3.1 2026-08-18 09:19:21.631006 | mon1 | ERROR Command ansible-galaxy collection install -vvv community.docker:>=4.3.1, returned 250 code: 2026-08-18 09:19:21.631046 | mon1 | Starting galaxy collection install process 2026-08-18 09:19:21.631058 | mon1 | Process install dependency map 2026-08-18 09:19:21.631068 | mon1 | the full traceback was: 2026-08-18 09:19:21.631079 | mon1 | 2026-08-18 09:19:21.631089 | mon1 | Traceback (most recent call last): 2026-08-18 09:19:21.631100 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/cli/__init__.py", line 646, in cli_executor 2026-08-18 09:19:21.631111 | mon1 | exit_code = cli.run() 2026-08-18 09:19:21.631122 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 727, in run 2026-08-18 09:19:21.631132 | mon1 | return context.CLIARGS() 2026-08-18 09:19:21.631143 | mon1 | ~~~~~~~~~~~~~~~~~~~~~~~^^ 2026-08-18 09:19:21.631153 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 98, in method_wrapper 2026-08-18 09:19:21.631163 | mon1 | return wrapped_method(*args, **kwargs) 2026-08-18 09:19:21.631173 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 1392, in execute_install 2026-08-18 09:19:21.631184 | mon1 | self._execute_install_collection( 2026-08-18 09:19:21.631193 | mon1 | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-08-18 09:19:21.631202 | mon1 | collection_requirements, collection_path, 2026-08-18 09:19:21.631211 | mon1 | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-08-18 09:19:21.631224 | mon1 | artifacts_manager=artifacts_manager, 2026-08-18 09:19:21.631236 | mon1 | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-08-18 09:19:21.631249 | mon1 | ) 2026-08-18 09:19:21.631262 | mon1 | ^ 2026-08-18 09:19:21.631275 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 1441, in _execute_install_collection 2026-08-18 09:19:21.631289 | mon1 | install_collections( 2026-08-18 09:19:21.631298 | mon1 | ~~~~~~~~~~~~~~~~~~~^ 2026-08-18 09:19:21.631307 | mon1 | requirements, output_path, self.api_servers, ignore_errors, 2026-08-18 09:19:21.631317 | mon1 | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-08-18 09:19:21.631326 | mon1 | ...<5 lines>... 2026-08-18 09:19:21.631335 | mon1 | read_requirement_paths=read_req_paths, 2026-08-18 09:19:21.631344 | mon1 | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-08-18 09:19:21.631354 | mon1 | ) 2026-08-18 09:19:21.631362 | mon1 | ^ 2026-08-18 09:19:21.631371 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/__init__.py", line 736, in install_collections 2026-08-18 09:19:21.631400 | mon1 | dependency_map = _resolve_depenency_map( 2026-08-18 09:19:21.631409 | mon1 | collections, 2026-08-18 09:19:21.631418 | mon1 | ...<7 lines>... 2026-08-18 09:19:21.631426 | mon1 | offline=offline, 2026-08-18 09:19:21.631435 | mon1 | ) 2026-08-18 09:19:21.631445 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/__init__.py", line 1863, in _resolve_depenency_map 2026-08-18 09:19:21.631454 | mon1 | return collection_dep_resolver.resolve( 2026-08-18 09:19:21.631463 | mon1 | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-08-18 09:19:21.631472 | mon1 | requested_requirements, 2026-08-18 09:19:21.631480 | mon1 | ^^^^^^^^^^^^^^^^^^^^^^^ 2026-08-18 09:19:21.631489 | mon1 | max_rounds=2000000, # NOTE: same constant pip uses 2026-08-18 09:19:21.631498 | mon1 | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-08-18 09:19:21.631507 | mon1 | ).mapping 2026-08-18 09:19:21.631516 | mon1 | ^ 2026-08-18 09:19:21.631525 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/resolvelib/resolvers.py", line 546, in resolve 2026-08-18 09:19:21.631534 | mon1 | state = resolution.resolve(requirements, max_rounds=max_rounds) 2026-08-18 09:19:21.631543 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/resolvelib/resolvers.py", line 427, in resolve 2026-08-18 09:19:21.631552 | mon1 | failure_causes = self._attempt_to_pin_criterion(name) 2026-08-18 09:19:21.631561 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/resolvelib/resolvers.py", line 239, in _attempt_to_pin_criterion 2026-08-18 09:19:21.631570 | mon1 | criteria = self._get_updated_criteria(candidate) 2026-08-18 09:19:21.631579 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/resolvelib/resolvers.py", line 229, in _get_updated_criteria 2026-08-18 09:19:21.631588 | mon1 | for requirement in self._p.get_dependencies(candidate=candidate): 2026-08-18 09:19:21.631597 | mon1 | ~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^ 2026-08-18 09:19:21.631606 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 441, in get_dependencies 2026-08-18 09:19:21.631615 | mon1 | req_map = self._api_proxy.get_collection_dependencies(candidate) 2026-08-18 09:19:21.631624 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 171, in get_collection_dependencies 2026-08-18 09:19:21.631633 | mon1 | get_collection_version_metadata(collection_candidate). 2026-08-18 09:19:21.631642 | mon1 | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^ 2026-08-18 09:19:21.631659 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 157, in get_collection_version_metadata 2026-08-18 09:19:21.631676 | mon1 | raise last_err 2026-08-18 09:19:21.631689 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 124, in get_collection_version_metadata 2026-08-18 09:19:21.631698 | mon1 | version_metadata = api.get_collection_version_metadata( 2026-08-18 09:19:21.631706 | mon1 | collection_candidate.namespace, 2026-08-18 09:19:21.631715 | mon1 | collection_candidate.name, 2026-08-18 09:19:21.631724 | mon1 | collection_candidate.ver, 2026-08-18 09:19:21.631733 | mon1 | ) 2026-08-18 09:19:21.631742 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 144, in wrapped 2026-08-18 09:19:21.631751 | mon1 | return method(self, *args, **kwargs) 2026-08-18 09:19:21.631766 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 815, in get_collection_version_metadata 2026-08-18 09:19:21.631775 | mon1 | data = self._call_galaxy(n_collection_url, error_context_msg=error_context_msg, cache=True) 2026-08-18 09:19:21.631785 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/module_utils/api.py", line 169, in run_function 2026-08-18 09:19:21.631794 | mon1 | return call_retryable_function() 2026-08-18 09:19:21.631803 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 389, in _call_galaxy 2026-08-18 09:19:21.631812 | mon1 | res = path_cache 2026-08-18 09:19:21.631820 | mon1 | ~~~~~~~~~~^^^^^^^^^^^ 2026-08-18 09:19:21.631829 | mon1 | KeyError: 'results' 2026-08-18 09:19:21.631838 | mon1 | 2026-08-18 09:19:21.631847 | mon1 | ansible-galaxy 2026-08-18 09:19:21.631856 | mon1 | config file = None 2026-08-18 09:19:21.631864 | mon1 | configured module search path = 2026-08-18 09:19:21.631873 | mon1 | ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible 2026-08-18 09:19:21.631882 | mon1 | ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages 2026-08-18 09:19:21.631892 | mon1 | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/bin/ansible-galaxy 2026-08-18 09:19:21.631900 | mon1 | python version = 3.13.7 (main, Aug 18 2025, 19:20:03) (/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/bin/python) 2026-08-18 09:19:21.631909 | mon1 | jinja version = 3.1.6 2026-08-18 09:19:21.631918 | mon1 | libyaml = True 2026-08-18 09:19:21.631927 | mon1 | No config file found; using defaults 2026-08-18 09:19:21.631936 | mon1 | Found installed collection community.crypto:3.3.0 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-08-18 09:19:21.631944 | mon1 | Opened /home/zuul/.ansible/galaxy_token 2026-08-18 09:19:21.631953 | mon1 | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2026-08-18 09:19:21.631962 | mon1 | unexpected error when getting available versions of collection 2026-08-18 09:19:21.631971 | mon1 | community.docker: 'results' 2026-08-18 09:19:21.631980 | mon1 | ERROR! Unexpected Exception, this is probably a bug: 'results' 2026-08-18 09:19:21.631988 | mon1 | 2026-08-18 09:19:21.632488 | mon1 | Traceback (most recent call last): 2026-08-18 09:19:21.634194 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/bin/molecule", line 10, in 2026-08-18 09:19:21.634225 | mon1 | sys.exit(main()) 2026-08-18 09:19:21.634234 | mon1 | ~~~~^^ 2026-08-18 09:19:21.634335 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/click/core.py", line 1442, in __call__ 2026-08-18 09:19:21.634352 | mon1 | return self.main(*args, **kwargs) 2026-08-18 09:19:21.634365 | mon1 | ~~~~~~~~~^^^^^^^^^^^^^^^^^ 2026-08-18 09:19:21.634468 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/click/core.py", line 1363, in main 2026-08-18 09:19:21.634481 | mon1 | rv = self.invoke(ctx) 2026-08-18 09:19:21.634654 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/click/core.py", line 1830, in invoke 2026-08-18 09:19:21.634688 | mon1 | return _process_result(sub_ctx.command.invoke(sub_ctx)) 2026-08-18 09:19:21.634699 | mon1 | ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^ 2026-08-18 09:19:21.634735 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/click/core.py", line 1226, in invoke 2026-08-18 09:19:21.634746 | mon1 | return ctx.invoke(self.callback, **ctx.params) 2026-08-18 09:19:21.634755 | mon1 | ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-08-18 09:19:21.634875 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/click/core.py", line 794, in invoke 2026-08-18 09:19:21.634900 | mon1 | return callback(*args, **kwargs) 2026-08-18 09:19:21.634987 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/molecule/click_cfg.py", line 613, in wrapper 2026-08-18 09:19:21.634999 | mon1 | return func(*args, **kwargs) 2026-08-18 09:19:21.635099 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/click/decorators.py", line 34, in new_func 2026-08-18 09:19:21.635109 | mon1 | return f(get_current_context(), *args, **kwargs) 2026-08-18 09:19:21.635247 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/molecule/click_cfg.py", line 468, in wrapper 2026-08-18 09:19:21.635260 | mon1 | return func(ctx) 2026-08-18 09:19:21.635364 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/molecule/command/test.py", line 95, in test 2026-08-18 09:19:21.635374 | mon1 | base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude) 2026-08-18 09:19:21.635383 | mon1 | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-08-18 09:19:21.635507 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/molecule/command/base.py", line 235, in execute_cmdline_scenarios 2026-08-18 09:19:21.635516 | mon1 | _run_scenarios(scenarios, command_args, default_config) 2026-08-18 09:19:21.635525 | mon1 | ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-08-18 09:19:21.635747 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/molecule/command/base.py", line 314, in _run_scenarios 2026-08-18 09:19:21.635760 | mon1 | scenario.config.runtime.prepare_environment( 2026-08-18 09:19:21.635769 | mon1 | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-08-18 09:19:21.635778 | mon1 | install_local=True, 2026-08-18 09:19:21.635787 | mon1 | ^^^^^^^^^^^^^^^^^^^ 2026-08-18 09:19:21.635799 | mon1 | role_name_check=role_name_check, 2026-08-18 09:19:21.635811 | mon1 | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-08-18 09:19:21.635823 | mon1 | ) 2026-08-18 09:19:21.635835 | mon1 | ^ 2026-08-18 09:19:21.635950 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible_compat/runtime.py", line 694, in prepare_environment 2026-08-18 09:19:21.635962 | mon1 | self.install_collection( 2026-08-18 09:19:21.635971 | mon1 | ~~~~~~~~~~~~~~~~~~~~~~~^ 2026-08-18 09:19:21.635980 | mon1 | f"{name}{',' if is_url(name) else ':'}{required_version}", 2026-08-18 09:19:21.635989 | mon1 | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-08-18 09:19:21.635998 | mon1 | destination=destination, 2026-08-18 09:19:21.636007 | mon1 | ^^^^^^^^^^^^^^^^^^^^^^^^ 2026-08-18 09:19:21.636016 | mon1 | ) 2026-08-18 09:19:21.636028 | mon1 | ^ 2026-08-18 09:19:21.636074 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible_compat/runtime.py", line 552, in install_collection 2026-08-18 09:19:21.636084 | mon1 | raise InvalidPrerequisiteError(msg) 2026-08-18 09:19:21.637822 | mon1 | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv community.docker:>=4.3.1, returned 250 code: 2026-08-18 09:19:21.637842 | mon1 | Starting galaxy collection install process 2026-08-18 09:19:21.637851 | mon1 | Process install dependency map 2026-08-18 09:19:21.637860 | mon1 | the full traceback was: 2026-08-18 09:19:21.637869 | mon1 | 2026-08-18 09:19:21.637878 | mon1 | Traceback (most recent call last): 2026-08-18 09:19:21.637887 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/cli/__init__.py", line 646, in cli_executor 2026-08-18 09:19:21.637896 | mon1 | exit_code = cli.run() 2026-08-18 09:19:21.637905 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 727, in run 2026-08-18 09:19:21.637924 | mon1 | return context.CLIARGS['func']() 2026-08-18 09:19:21.637933 | mon1 | ~~~~~~~~~~~~~~~~~~~~~~~^^ 2026-08-18 09:19:21.637942 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 98, in method_wrapper 2026-08-18 09:19:21.637952 | mon1 | return wrapped_method(*args, **kwargs) 2026-08-18 09:19:21.637964 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 1392, in execute_install 2026-08-18 09:19:21.637976 | mon1 | self._execute_install_collection( 2026-08-18 09:19:21.637988 | mon1 | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-08-18 09:19:21.637997 | mon1 | collection_requirements, collection_path, 2026-08-18 09:19:21.638006 | mon1 | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-08-18 09:19:21.638015 | mon1 | artifacts_manager=artifacts_manager, 2026-08-18 09:19:21.638023 | mon1 | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-08-18 09:19:21.638032 | mon1 | ) 2026-08-18 09:19:21.638040 | mon1 | ^ 2026-08-18 09:19:21.638049 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/cli/galaxy.py", line 1441, in _execute_install_collection 2026-08-18 09:19:21.638058 | mon1 | install_collections( 2026-08-18 09:19:21.638067 | mon1 | ~~~~~~~~~~~~~~~~~~~^ 2026-08-18 09:19:21.638075 | mon1 | requirements, output_path, self.api_servers, ignore_errors, 2026-08-18 09:19:21.638084 | mon1 | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-08-18 09:19:21.638093 | mon1 | ...<5 lines>... 2026-08-18 09:19:21.638102 | mon1 | read_requirement_paths=read_req_paths, 2026-08-18 09:19:21.638111 | mon1 | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-08-18 09:19:21.638123 | mon1 | ) 2026-08-18 09:19:21.638135 | mon1 | ^ 2026-08-18 09:19:21.638151 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/__init__.py", line 736, in install_collections 2026-08-18 09:19:21.638160 | mon1 | dependency_map = _resolve_depenency_map( 2026-08-18 09:19:21.638210 | mon1 | collections, 2026-08-18 09:19:21.638219 | mon1 | ...<7 lines>... 2026-08-18 09:19:21.638228 | mon1 | offline=offline, 2026-08-18 09:19:21.638237 | mon1 | ) 2026-08-18 09:19:21.638245 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/__init__.py", line 1863, in _resolve_depenency_map 2026-08-18 09:19:21.638254 | mon1 | return collection_dep_resolver.resolve( 2026-08-18 09:19:21.638263 | mon1 | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 2026-08-18 09:19:21.638272 | mon1 | requested_requirements, 2026-08-18 09:19:21.638280 | mon1 | ^^^^^^^^^^^^^^^^^^^^^^^ 2026-08-18 09:19:21.638289 | mon1 | max_rounds=2000000, # NOTE: same constant pip uses 2026-08-18 09:19:21.638298 | mon1 | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-08-18 09:19:21.638306 | mon1 | ).mapping 2026-08-18 09:19:21.638315 | mon1 | ^ 2026-08-18 09:19:21.638324 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/resolvelib/resolvers.py", line 546, in resolve 2026-08-18 09:19:21.638333 | mon1 | state = resolution.resolve(requirements, max_rounds=max_rounds) 2026-08-18 09:19:21.638342 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/resolvelib/resolvers.py", line 427, in resolve 2026-08-18 09:19:21.638351 | mon1 | failure_causes = self._attempt_to_pin_criterion(name) 2026-08-18 09:19:21.638360 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/resolvelib/resolvers.py", line 239, in _attempt_to_pin_criterion 2026-08-18 09:19:21.638368 | mon1 | criteria = self._get_updated_criteria(candidate) 2026-08-18 09:19:21.638377 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/resolvelib/resolvers.py", line 229, in _get_updated_criteria 2026-08-18 09:19:21.638393 | mon1 | for requirement in self._p.get_dependencies(candidate=candidate): 2026-08-18 09:19:21.638402 | mon1 | ~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^ 2026-08-18 09:19:21.638411 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 441, in get_dependencies 2026-08-18 09:19:21.638420 | mon1 | req_map = self._api_proxy.get_collection_dependencies(candidate) 2026-08-18 09:19:21.638429 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 171, in get_collection_dependencies 2026-08-18 09:19:21.638438 | mon1 | get_collection_version_metadata(collection_candidate). 2026-08-18 09:19:21.638447 | mon1 | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^ 2026-08-18 09:19:21.638463 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 157, in get_collection_version_metadata 2026-08-18 09:19:21.638473 | mon1 | raise last_err 2026-08-18 09:19:21.638482 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 124, in get_collection_version_metadata 2026-08-18 09:19:21.638490 | mon1 | version_metadata = api.get_collection_version_metadata( 2026-08-18 09:19:21.638499 | mon1 | collection_candidate.namespace, 2026-08-18 09:19:21.638509 | mon1 | collection_candidate.name, 2026-08-18 09:19:21.638518 | mon1 | collection_candidate.ver, 2026-08-18 09:19:21.638529 | mon1 | ) 2026-08-18 09:19:21.638539 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 144, in wrapped 2026-08-18 09:19:21.638548 | mon1 | return method(self, *args, **kwargs) 2026-08-18 09:19:21.638557 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 815, in get_collection_version_metadata 2026-08-18 09:19:21.638566 | mon1 | data = self._call_galaxy(n_collection_url, error_context_msg=error_context_msg, cache=True) 2026-08-18 09:19:21.638574 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/module_utils/api.py", line 169, in run_function 2026-08-18 09:19:21.638583 | mon1 | return call_retryable_function() 2026-08-18 09:19:21.638592 | mon1 | File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 389, in _call_galaxy 2026-08-18 09:19:21.638601 | mon1 | res = path_cache['results'] 2026-08-18 09:19:21.638610 | mon1 | ~~~~~~~~~~^^^^^^^^^^^ 2026-08-18 09:19:21.638622 | mon1 | KeyError: 'results' 2026-08-18 09:19:21.638631 | mon1 | 2026-08-18 09:19:21.638640 | mon1 | ansible-galaxy [core 2.18.6] 2026-08-18 09:19:21.638649 | mon1 | config file = None 2026-08-18 09:19:21.638657 | mon1 | configured module search path = ['/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/plugins/modules', '/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules'] 2026-08-18 09:19:21.638666 | mon1 | ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible 2026-08-18 09:19:21.638675 | mon1 | ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages 2026-08-18 09:19:21.638684 | mon1 | executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/bin/ansible-galaxy 2026-08-18 09:19:21.638693 | mon1 | python version = 3.13.7 (main, Aug 18 2025, 19:20:03) [Clang 20.1.4 ] (/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/bin/python) 2026-08-18 09:19:21.638701 | mon1 | jinja version = 3.1.6 2026-08-18 09:19:21.638717 | mon1 | libyaml = True 2026-08-18 09:19:21.638726 | mon1 | No config file found; using defaults 2026-08-18 09:19:21.638735 | mon1 | Found installed collection community.crypto:3.3.0 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-08-18 09:19:21.638744 | mon1 | Opened /home/zuul/.ansible/galaxy_token 2026-08-18 09:19:21.638753 | mon1 | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2026-08-18 09:19:21.638761 | mon1 | unexpected error when getting available versions of collection 2026-08-18 09:19:21.638770 | mon1 | community.docker: 'results' 2026-08-18 09:19:21.638779 | mon1 | ERROR! Unexpected Exception, this is probably a bug: 'results' 2026-08-18 09:19:21.638788 | mon1 | 2026-08-18 09:19:32.083187 | mon1 | ERROR 2026-08-18 09:19:32.083444 | mon1 | { 2026-08-18 09:19:32.083487 | mon1 | "delta": "0:01:16.503532", 2026-08-18 09:19:32.083518 | mon1 | "end": "2026-08-18 09:19:21.706084", 2026-08-18 09:19:32.083558 | mon1 | "msg": "non-zero return code", 2026-08-18 09:19:32.083587 | mon1 | "rc": 1, 2026-08-18 09:19:32.083614 | mon1 | "start": "2026-08-18 09:18:05.202552" 2026-08-18 09:19:32.083640 | mon1 | } failure 2026-08-18 09:19:32.087118 | 2026-08-18 09:19:32.087165 | PLAY RECAP 2026-08-18 09:19:32.087206 | mon1 | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-08-18 09:19:32.087227 | 2026-08-18 09:19:32.209309 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-08-18 09:19:32.217122 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-08-18 09:19:32.842687 | 2026-08-18 09:19:32.842850 | PLAY [all] 2026-08-18 09:19:32.854689 | 2026-08-18 09:19:32.854762 | TASK [fetch-output : Set log path for multiple nodes] 2026-08-18 09:19:32.908531 | mon1 | ok 2026-08-18 09:19:32.914614 | osd1 | ok 2026-08-18 09:19:32.919836 | 2026-08-18 09:19:32.919912 | TASK [fetch-output : Set log path for single node] 2026-08-18 09:19:32.955039 | mon1 | skipping: Conditional result was False 2026-08-18 09:19:32.955416 | osd1 | skipping: Conditional result was False 2026-08-18 09:19:32.960347 | 2026-08-18 09:19:32.960412 | LOOP [fetch-output : Ensure local output dirs] 2026-08-18 09:19:33.347668 | osd1 -> localhost | changed: "/var/lib/zuul/builds/cdeed2c0f31144a29889c27dafbad7c4/work/logs/osd1" 2026-08-18 09:19:33.370441 | mon1 -> localhost | changed: "/var/lib/zuul/builds/cdeed2c0f31144a29889c27dafbad7c4/work/logs/mon1" 2026-08-18 09:19:33.549816 | osd1 -> localhost | changed: "/var/lib/zuul/builds/cdeed2c0f31144a29889c27dafbad7c4/work/artifacts" 2026-08-18 09:19:33.567958 | mon1 -> localhost | ok: "/var/lib/zuul/builds/cdeed2c0f31144a29889c27dafbad7c4/work/artifacts" 2026-08-18 09:19:33.748536 | osd1 -> localhost | changed: "/var/lib/zuul/builds/cdeed2c0f31144a29889c27dafbad7c4/work/docs" 2026-08-18 09:19:33.765956 | mon1 -> localhost | ok: "/var/lib/zuul/builds/cdeed2c0f31144a29889c27dafbad7c4/work/docs" 2026-08-18 09:19:33.766078 | mon1 -> localhost | changed: All items complete 2026-08-18 09:19:33.766138 | 2026-08-18 09:19:33.779925 | 2026-08-18 09:19:33.779997 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-18 09:19:34.421429 | mon1 | changed: .d..t...... ./ 2026-08-18 09:19:34.427193 | osd1 | changed: .d..t...... ./ 2026-08-18 09:19:34.881198 | mon1 | changed: .d..t...... ./ 2026-08-18 09:19:34.898737 | osd1 | ok 2026-08-18 09:19:35.331182 | mon1 | changed: .d..t...... ./ 2026-08-18 09:19:35.368211 | osd1 | ok 2026-08-18 09:19:35.368287 | osd1 | changed: All items complete 2026-08-18 09:19:35.368318 | 2026-08-18 09:19:35.391044 | 2026-08-18 09:19:35.391438 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-18 09:19:35.919843 | mon1 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008049 2026-08-18 09:19:36.157902 | mon1 -> localhost | ok: Item: docs Runtime: 0:00:00.008400 2026-08-18 09:19:36.179420 | 2026-08-18 09:19:36.179581 | PLAY [all] 2026-08-18 09:19:36.186715 | 2026-08-18 09:19:36.186784 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-18 09:19:36.619395 | mon1 | changed 2026-08-18 09:19:36.831704 | osd1 | changed 2026-08-18 09:19:36.843721 | 2026-08-18 09:19:36.843769 | PLAY RECAP 2026-08-18 09:19:36.843830 | mon1 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-08-18 09:19:36.843859 | osd1 | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-08-18 09:19:36.843878 | 2026-08-18 09:19:36.960316 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-08-18 09:19:36.965468 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-18 09:19:37.514021 | 2026-08-18 09:19:37.514139 | PLAY [localhost] 2026-08-18 09:19:37.523362 | 2026-08-18 09:19:37.523429 | TASK [Generate Zuul manifest] 2026-08-18 09:19:37.541826 | localhost | ok 2026-08-18 09:19:37.557720 | 2026-08-18 09:19:37.557795 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-18 09:19:37.943199 | localhost | changed 2026-08-18 09:19:37.956817 | 2026-08-18 09:19:37.956897 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-18 09:19:37.998426 | localhost | ok 2026-08-18 09:19:38.006743 | 2026-08-18 09:19:38.006805 | TASK [Upload logs] 2026-08-18 09:19:38.028397 | localhost | ok 2026-08-18 09:19:38.086516 | 2026-08-18 09:19:38.086606 | TASK [Set zuul-log-path fact] 2026-08-18 09:19:38.106820 | localhost | ok 2026-08-18 09:19:38.120431 | 2026-08-18 09:19:38.120496 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-18 09:19:38.151018 | localhost | ok 2026-08-18 09:19:38.159142 | 2026-08-18 09:19:38.159206 | TASK [upload-logs : Create log directories] 2026-08-18 09:19:38.537335 | localhost | changed 2026-08-18 09:19:38.543931 | 2026-08-18 09:19:38.544029 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-18 09:19:38.952590 | localhost -> localhost | ok: Runtime: 0:00:00.005107 2026-08-18 09:19:38.960816 | 2026-08-18 09:19:38.960883 | TASK [upload-logs : Upload logs to log server] 2026-08-18 09:19:39.411014 | localhost | Output suppressed because no_log was given 2026-08-18 09:19:39.416068 | 2026-08-18 09:19:39.416154 | LOOP [upload-logs : Compress console log and json output] 2026-08-18 09:19:39.488415 | localhost | skipping: Conditional result was False 2026-08-18 09:19:39.495437 | localhost | skipping: Conditional result was False 2026-08-18 09:19:39.511309 | 2026-08-18 09:19:39.511403 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-18 09:19:39.582008 | localhost | skipping: Conditional result was False 2026-08-18 09:19:39.582352 | 2026-08-18 09:19:39.586135 | localhost | skipping: Conditional result was False 2026-08-18 09:19:39.627033 | 2026-08-18 09:19:39.627182 | LOOP [upload-logs : Upload console log and json output]