2026-09-07 00:49:41.082148 | Job console starting
2026-09-07 00:49:41.141578 | Updating git repos
2026-09-07 00:49:41.190750 | Cloning repos into workspace
2026-09-07 00:49:41.249798 | Restoring repo states
2026-09-07 00:49:41.258301 | Merging changes
2026-09-07 00:49:41.608624 | Checking out repos
2026-09-07 00:49:41.749274 | Preparing playbooks
2026-09-07 00:49:43.017167 | Running Ansible setup
2026-09-07 00:49:47.388746 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-07 00:49:47.992976 | 
2026-09-07 00:49:47.993112 | PLAY [localhost]
2026-09-07 00:49:48.000374 | 
2026-09-07 00:49:48.000457 | TASK [Gathering Facts]
2026-09-07 00:49:49.970707 | localhost | ok
2026-09-07 00:49:49.978836 | 
2026-09-07 00:49:49.978939 | TASK [Setup log path fact]
2026-09-07 00:49:50.007821 | localhost | ok
2026-09-07 00:49:50.021822 | 
2026-09-07 00:49:50.021964 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-07 00:49:50.062674 | localhost | ok
2026-09-07 00:49:50.075388 | 
2026-09-07 00:49:50.075513 | TASK [emit-job-header : Print job information]
2026-09-07 00:49:50.126758 | # Job Information
2026-09-07 00:49:50.127044 | Ansible Version: 2.16.18
2026-09-07 00:49:50.127110 | Job: ansible-collection-ceph-molecule-ha-ubuntu-jammy
2026-09-07 00:49:50.127156 | Pipeline: check
2026-09-07 00:49:50.127196 | Executor: 2d72f0692154
2026-09-07 00:49:50.127234 | Triggered by: https://github.com/vexxhost/ansible-collection-ceph/pull/116
2026-09-07 00:49:50.127278 | Event ID: 8daa5b10-aa55-11f1-8fa3-d098d80f981f
2026-09-07 00:49:50.133004 | 
2026-09-07 00:49:50.133135 | LOOP [emit-job-header : Print node information]
2026-09-07 00:49:50.236022 | localhost | ok:
2026-09-07 00:49:50.236421 | localhost | # Node Information
2026-09-07 00:49:50.236568 | localhost | Inventory Hostname: mon1
2026-09-07 00:49:50.236627 | localhost | Hostname: np0000191979
2026-09-07 00:49:50.236672 | localhost | Username: zuul
2026-09-07 00:49:50.236726 | localhost | Distro: Ubuntu 22.04
2026-09-07 00:49:50.236770 | localhost | Provider: yul1
2026-09-07 00:49:50.236811 | localhost | Region: ca-ymq-1
2026-09-07 00:49:50.236853 | localhost | Label: ubuntu-jammy
2026-09-07 00:49:50.236925 | localhost | Product Name: OpenStack Nova
2026-09-07 00:49:50.236968 | localhost | Interface IP: 162.253.55.136
2026-09-07 00:49:50.274309 | localhost | ok:
2026-09-07 00:49:50.274518 | localhost | # Node Information
2026-09-07 00:49:50.274585 | localhost | Inventory Hostname: osd1
2026-09-07 00:49:50.274631 | localhost | Hostname: np0000191980
2026-09-07 00:49:50.274680 | localhost | Username: zuul
2026-09-07 00:49:50.274727 | localhost | Distro: Ubuntu 22.04
2026-09-07 00:49:50.274781 | localhost | Provider: yul1
2026-09-07 00:49:50.274825 | localhost | Region: ca-ymq-1
2026-09-07 00:49:50.274887 | localhost | Label: ubuntu-jammy
2026-09-07 00:49:50.274938 | localhost | Product Name: OpenStack Nova
2026-09-07 00:49:50.274984 | localhost | Interface IP: 162.253.55.19
2026-09-07 00:49:50.289424 | 
2026-09-07 00:49:50.289681 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-07 00:49:50.732447 | localhost -> localhost | changed
2026-09-07 00:49:50.737519 | 
2026-09-07 00:49:50.737788 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-07 00:49:51.790445 | localhost -> localhost | changed
2026-09-07 00:49:51.801673 | 
2026-09-07 00:49:51.801798 | PLAY [all]
2026-09-07 00:49:51.817765 | 
2026-09-07 00:49:51.817925 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-07 00:49:52.121972 | mon1 -> localhost | ok
2026-09-07 00:49:52.135707 | 
2026-09-07 00:49:52.135900 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-07 00:49:52.175842 | mon1 | ok
2026-09-07 00:49:52.184848 | osd1 | ok
2026-09-07 00:49:52.236972 | mon1 | included: /var/lib/zuul/builds/29af656eb8294292b5a315a2d444a56b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-07 00:49:52.237171 | osd1 | included: /var/lib/zuul/builds/29af656eb8294292b5a315a2d444a56b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-07 00:49:52.254825 | 
2026-09-07 00:49:52.255012 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-07 00:49:53.582047 | mon1 -> localhost | Generating public/private rsa key pair.
2026-09-07 00:49:53.582227 | mon1 -> localhost | Your identification has been saved in /var/lib/zuul/builds/29af656eb8294292b5a315a2d444a56b/work/29af656eb8294292b5a315a2d444a56b_id_rsa
2026-09-07 00:49:53.582256 | mon1 -> localhost | Your public key has been saved in /var/lib/zuul/builds/29af656eb8294292b5a315a2d444a56b/work/29af656eb8294292b5a315a2d444a56b_id_rsa.pub
2026-09-07 00:49:53.582277 | mon1 -> localhost | The key fingerprint is:
2026-09-07 00:49:53.582297 | mon1 -> localhost | SHA256:JGsA0qcoJgEOh+Ewh0RheaiLdK7KgFE4u2XuDfHMQRk zuul-build-sshkey
2026-09-07 00:49:53.582316 | mon1 -> localhost | The key's randomart image is:
2026-09-07 00:49:53.582335 | mon1 -> localhost | +---[RSA 3072]----+
2026-09-07 00:49:53.582352 | mon1 -> localhost | |@XB E            |
2026-09-07 00:49:53.582371 | mon1 -> localhost | |XO.o.o           |
2026-09-07 00:49:53.582389 | mon1 -> localhost | |+=oo+ . .        |
2026-09-07 00:49:53.582407 | mon1 -> localhost | |=*.o . +         |
2026-09-07 00:49:53.582424 | mon1 -> localhost | |O.* . o S        |
2026-09-07 00:49:53.582442 | mon1 -> localhost | |+* * o           |
2026-09-07 00:49:53.582459 | mon1 -> localhost | |+ + +            |
2026-09-07 00:49:53.582475 | mon1 -> localhost | |oo o             |
2026-09-07 00:49:53.582492 | mon1 -> localhost | |o.. .            |
2026-09-07 00:49:53.582508 | mon1 -> localhost | +----[SHA256]-----+
2026-09-07 00:49:53.582551 | mon1 -> localhost | ok: Runtime: 0:00:00.835641
2026-09-07 00:49:53.587558 | 
2026-09-07 00:49:53.587623 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-07 00:49:53.617148 | mon1 | ok
2026-09-07 00:49:53.632295 | osd1 | ok
2026-09-07 00:49:53.642751 | mon1 | included: /var/lib/zuul/builds/29af656eb8294292b5a315a2d444a56b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-07 00:49:53.642798 | osd1 | included: /var/lib/zuul/builds/29af656eb8294292b5a315a2d444a56b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-07 00:49:53.651176 | 
2026-09-07 00:49:53.651236 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-07 00:49:53.673905 | mon1 | skipping: Conditional result was False
2026-09-07 00:49:53.684720 | osd1 | skipping: Conditional result was False
2026-09-07 00:49:53.689907 | 
2026-09-07 00:49:53.689985 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-07 00:49:54.201856 | mon1 | changed
2026-09-07 00:49:54.221735 | osd1 | changed
2026-09-07 00:49:54.233551 | 
2026-09-07 00:49:54.233693 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-07 00:49:54.425742 | mon1 | ok
2026-09-07 00:49:54.467798 | osd1 | ok
2026-09-07 00:49:54.478245 | 
2026-09-07 00:49:54.478383 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-07 00:49:54.989463 | mon1 | changed
2026-09-07 00:49:55.005078 | osd1 | changed
2026-09-07 00:49:55.009762 | 
2026-09-07 00:49:55.009914 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-07 00:49:55.586610 | mon1 | changed
2026-09-07 00:49:55.590939 | osd1 | changed
2026-09-07 00:49:55.595388 | 
2026-09-07 00:49:55.595454 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-07 00:49:55.619925 | mon1 | skipping: Conditional result was False
2026-09-07 00:49:55.641005 | osd1 | skipping: Conditional result was False
2026-09-07 00:49:55.648981 | 
2026-09-07 00:49:55.649437 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-07 00:49:56.040809 | mon1 -> localhost | changed
2026-09-07 00:49:56.068110 | 
2026-09-07 00:49:56.068286 | TASK [add-build-sshkey : Add back temp key]
2026-09-07 00:49:56.437767 | mon1 -> localhost | Identity added: /var/lib/zuul/builds/29af656eb8294292b5a315a2d444a56b/work/29af656eb8294292b5a315a2d444a56b_id_rsa (zuul-build-sshkey)
2026-09-07 00:49:56.438122 | mon1 -> localhost | ok: Runtime: 0:00:00.016678
2026-09-07 00:49:56.447468 | 
2026-09-07 00:49:56.447589 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-07 00:49:56.755639 | mon1 | ok
2026-09-07 00:49:56.767255 | osd1 | ok
2026-09-07 00:49:56.775847 | 
2026-09-07 00:49:56.776004 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-07 00:49:56.804001 | mon1 | skipping: Conditional result was False
2026-09-07 00:49:56.815467 | osd1 | skipping: Conditional result was False
2026-09-07 00:49:56.839980 | 
2026-09-07 00:49:56.840123 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-07 00:49:57.108624 | mon1 | ok
2026-09-07 00:49:57.140122 | osd1 | ok
2026-09-07 00:49:57.157837 | 
2026-09-07 00:49:57.158119 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-07 00:49:57.915755 | mon1 | Output suppressed because no_log was given
2026-09-07 00:49:57.996002 | osd1 | Output suppressed because no_log was given
2026-09-07 00:49:58.013958 | 
2026-09-07 00:49:58.014051 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-07 00:49:58.237350 | osd1 | ok: "logs"
2026-09-07 00:49:58.237725 | osd1 | ok: All items complete
2026-09-07 00:49:58.237784 | 
2026-09-07 00:49:58.246720 | mon1 | ok: "logs"
2026-09-07 00:49:58.410369 | osd1 | ok: "artifacts"
2026-09-07 00:49:58.428194 | mon1 | ok: "artifacts"
2026-09-07 00:49:58.562313 | osd1 | ok: "docs"
2026-09-07 00:49:58.590448 | mon1 | ok: "docs"
2026-09-07 00:49:58.606024 | 
2026-09-07 00:49:58.606221 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-07 00:49:58.810461 | mon1 | changed: "logs"
2026-09-07 00:49:58.810824 | mon1 | ok: All items complete
2026-09-07 00:49:58.810897 | 
2026-09-07 00:49:58.821096 | osd1 | changed: "logs"
2026-09-07 00:49:58.954751 | mon1 | changed: "artifacts"
2026-09-07 00:49:58.985031 | osd1 | changed: "artifacts"
2026-09-07 00:49:59.122691 | mon1 | changed: "docs"
2026-09-07 00:49:59.156725 | osd1 | changed: "docs"
2026-09-07 00:49:59.207360 | 
2026-09-07 00:49:59.207514 | PLAY RECAP
2026-09-07 00:49:59.207578 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 00:49:59.207620 | mon1 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-07 00:49:59.207653 | osd1 | ok: 11 changed: 5 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-07 00:49:59.207676 | 
2026-09-07 00:49:59.382605 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-07 00:49:59.387080 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-07 00:49:59.970859 | 
2026-09-07 00:49:59.971025 | PLAY [all]
2026-09-07 00:49:59.981648 | 
2026-09-07 00:49:59.981792 | TASK [setup-uv : Extract archive]
2026-09-07 00:50:02.405096 | osd1 | changed
2026-09-07 00:50:02.485947 | mon1 | changed
2026-09-07 00:50:02.497696 | 
2026-09-07 00:50:02.497894 | TASK [setup-uv : Print version]
2026-09-07 00:50:02.921870 | mon1 | uv 0.8.13
2026-09-07 00:50:02.930330 | osd1 | uv 0.8.13
2026-09-07 00:50:03.077587 | mon1 | ok: Runtime: 0:00:00.008587
2026-09-07 00:50:03.094956 | osd1 | ok: Runtime: 0:00:00.010609
2026-09-07 00:50:03.121110 | 
2026-09-07 00:50:03.121290 | TASK [Install Ansible collection siblings]
2026-09-07 00:50:03.525446 | mon1 | ok:
2026-09-07 00:50:03.525702 | mon1 | Skipping community.general: no checked-out sibling
2026-09-07 00:50:03.525760 | mon1 | Skipping ansible.utils: no checked-out sibling
2026-09-07 00:50:03.525802 | mon1 | Skipping vexxhost.containers: no checked-out sibling
2026-09-07 00:50:03.528799 | 
2026-09-07 00:50:03.528946 | PLAY RECAP
2026-09-07 00:50:03.529064 | mon1 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 00:50:03.529185 | osd1 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-07 00:50:03.529693 | 
2026-09-07 00:50:03.673633 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-07 00:50:03.678167 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-07 00:50:04.278577 | 
2026-09-07 00:50:04.278956 | PLAY [all]
2026-09-07 00:50:04.291950 | 
2026-09-07 00:50:04.292142 | LOOP [Check inventory directories]
2026-09-07 00:50:04.680634 | mon1 | ok: "src/github.com/vexxhost/ansible-collection-ceph"
2026-09-07 00:50:04.863685 | mon1 | ok: "src/github.com/vexxhost/ansible-collection-ceph/molecule/default/inventory"
2026-09-07 00:50:05.029926 | mon1 | ok: "src/github.com/vexxhost/ansible-collection-ceph/extensions/molecule/default/inventory"
2026-09-07 00:50:05.088766 | 
2026-09-07 00:50:05.090023 | LOOP [Copy inventory file for Zuul]
2026-09-07 00:50:05.956022 | mon1 | changed:
2026-09-07 00:50:05.956322 | mon1 | {
2026-09-07 00:50:05.956382 | mon1 |   "ansible_loop_var": "item",
2026-09-07 00:50:05.956434 | mon1 |   "changed": false,
2026-09-07 00:50:05.956476 | mon1 |   "failed": false,
2026-09-07 00:50:05.956617 | mon1 |   "invocation": {
2026-09-07 00:50:05.956664 | mon1 |     "module_args": {
2026-09-07 00:50:05.956701 | mon1 |       "checksum_algorithm": "sha1",
2026-09-07 00:50:05.956736 | mon1 |       "follow": true,
2026-09-07 00:50:05.956772 | mon1 |       "get_attributes": true,
2026-09-07 00:50:05.956810 | mon1 |       "get_checksum": true,
2026-09-07 00:50:05.956845 | mon1 |       "get_mime": true,
2026-09-07 00:50:05.956905 | mon1 |       "path": "src/github.com/vexxhost/ansible-collection-ceph"
2026-09-07 00:50:05.956943 | mon1 |     }
2026-09-07 00:50:05.956978 | mon1 |   },
2026-09-07 00:50:05.957063 | mon1 |   "item": "src/github.com/vexxhost/ansible-collection-ceph",
2026-09-07 00:50:05.957100 | mon1 |   "stat": {
2026-09-07 00:50:05.957134 | mon1 |     "atime": 1788742197.7952487,
2026-09-07 00:50:05.957170 | mon1 |     "attr_flags": "e",
2026-09-07 00:50:05.957204 | mon1 |     "attributes": [
2026-09-07 00:50:05.957238 | mon1 |       "extents"
2026-09-07 00:50:05.957272 | mon1 |     ],
2026-09-07 00:50:05.957306 | mon1 |     "block_size": 4096,
2026-09-07 00:50:05.957351 | mon1 |     "blocks": 8,
2026-09-07 00:50:05.957404 | mon1 |     "charset": "unknown",
2026-09-07 00:50:05.957461 | mon1 |     "ctime": 1788742197.8032486,
2026-09-07 00:50:05.957500 | mon1 |     "dev": 64513,
2026-09-07 00:50:05.957534 | mon1 |     "device_type": 0,
2026-09-07 00:50:05.957568 | mon1 |     "executable": true,
2026-09-07 00:50:05.957601 | mon1 |     "exists": true,
2026-09-07 00:50:05.957647 | mon1 |     "gid": 1000,
2026-09-07 00:50:05.957683 | mon1 |     "gr_name": "zuul",
2026-09-07 00:50:05.957716 | mon1 |     "inode": 1042973,
2026-09-07 00:50:05.957749 | mon1 |     "isblk": false,
2026-09-07 00:50:05.957783 | mon1 |     "ischr": false,
2026-09-07 00:50:05.957817 | mon1 |     "isdir": true,
2026-09-07 00:50:05.957850 | mon1 |     "isfifo": false,
2026-09-07 00:50:05.957900 | mon1 |     "isgid": false,
2026-09-07 00:50:05.957934 | mon1 |     "islnk": false,
2026-09-07 00:50:05.957966 | mon1 |     "isreg": false,
2026-09-07 00:50:05.957998 | mon1 |     "issock": false,
2026-09-07 00:50:05.958029 | mon1 |     "isuid": false,
2026-09-07 00:50:05.958062 | mon1 |     "mimetype": "unknown",
2026-09-07 00:50:05.958094 | mon1 |     "mode": "0755",
2026-09-07 00:50:05.958126 | mon1 |     "mtime": 1788742181.277625,
2026-09-07 00:50:05.958159 | mon1 |     "nlink": 10,
2026-09-07 00:50:05.958191 | mon1 |     "path": "src/github.com/vexxhost/ansible-collection-ceph",
2026-09-07 00:50:05.958224 | mon1 |     "pw_name": "zuul",
2026-09-07 00:50:05.958258 | mon1 |     "readable": true,
2026-09-07 00:50:05.958292 | mon1 |     "rgrp": true,
2026-09-07 00:50:05.958325 | mon1 |     "roth": true,
2026-09-07 00:50:05.958358 | mon1 |     "rusr": true,
2026-09-07 00:50:05.958391 | mon1 |     "size": 4096,
2026-09-07 00:50:05.958442 | mon1 |     "uid": 1000,
2026-09-07 00:50:05.958476 | mon1 |     "version": "3641159442",
2026-09-07 00:50:05.958510 | mon1 |     "wgrp": false,
2026-09-07 00:50:05.958546 | mon1 |     "woth": false,
2026-09-07 00:50:05.958579 | mon1 |     "writeable": true,
2026-09-07 00:50:05.958611 | mon1 |     "wusr": true,
2026-09-07 00:50:05.958643 | mon1 |     "xgrp": true,
2026-09-07 00:50:05.958675 | mon1 |     "xoth": true,
2026-09-07 00:50:05.958715 | mon1 |     "xusr": true
2026-09-07 00:50:05.958807 | mon1 |   }
2026-09-07 00:50:05.958854 | mon1 | }
2026-09-07 00:50:05.958950 | mon1 | ok: All items complete
2026-09-07 00:50:05.958993 | 
2026-09-07 00:50:05.961652 | mon1 | skipping: Conditional result was False
2026-09-07 00:50:05.963460 | mon1 | skipping: Conditional result was False
2026-09-07 00:50:05.986926 | 
2026-09-07 00:50:05.987123 | LOOP [Switch "ansible_host" to private IP]
2026-09-07 00:50:06.372634 | mon1 | changed: 2 replacements made
2026-09-07 00:50:06.377579 | mon1 | skipping: Conditional result was False
2026-09-07 00:50:06.379245 | mon1 | skipping: Conditional result was False
2026-09-07 00:50:06.398323 | 
2026-09-07 00:50:06.398481 | TASK [Run Molecule scenario]
2026-09-07 00:50:07.079468 | mon1 | Downloading cpython-3.13.7-linux-x86_64-gnu (download) (30.9MiB)
2026-09-07 00:50:07.922218 | mon1 |  Downloading cpython-3.13.7-linux-x86_64-gnu (download)
2026-09-07 00:50:08.111195 | mon1 | Using CPython 3.13.7
2026-09-07 00:50:08.111297 | mon1 | Creating virtual environment at: .venv
2026-09-07 00:50:08.410419 | mon1 | Downloading netaddr (2.2MiB)
2026-09-07 00:50:08.414474 | mon1 | Downloading cryptography (4.3MiB)
2026-09-07 00:50:08.414652 | mon1 | Downloading ansible-core (2.1MiB)
2026-09-07 00:50:08.418009 | mon1 | Downloading pygments (1.2MiB)
2026-09-07 00:50:08.583576 | mon1 |  Downloading netaddr
2026-09-07 00:50:08.593411 | mon1 |  Downloading cryptography
2026-09-07 00:50:08.654763 | mon1 |  Downloading pygments
2026-09-07 00:50:08.803445 | mon1 |  Downloading ansible-core
2026-09-07 00:50:08.843196 | mon1 | Installed 36 packages in 39ms
2026-09-07 00:50:10.003100 | mon1 | INFO     Collection 'vexxhost.ceph' detected.
2026-09-07 00:50:10.003187 | mon1 | INFO     Scenarios will be used from 'extensions/molecule'
2026-09-07 00:50:10.642095 | mon1 | INFO     [default > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-07 00:50:10.642207 | mon1 | INFO     [default > prerun] Performing prerun with role_name_check=0...
2026-09-07 00:51:37.675040 | mon1 | WARNING  Retrying execution failure 250 of: ansible-galaxy collection install -vvv vexxhost.containers:>=1.1.1
2026-09-07 00:51:37.675213 | mon1 | ERROR    Command ansible-galaxy collection install -vvv vexxhost.containers:>=1.1.1, returned 250 code:
2026-09-07 00:51:37.675245 | mon1 | Starting galaxy collection install process
2026-09-07 00:51:37.675257 | mon1 | Process install dependency map
2026-09-07 00:51:37.675267 | mon1 | the full traceback was:
2026-09-07 00:51:37.675277 | mon1 |
2026-09-07 00:51:37.675286 | mon1 | Traceback (most recent call last):
2026-09-07 00:51:37.675295 | 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-09-07 00:51:37.675305 | mon1 |     exit_code = cli.run()
2026-09-07 00:51:37.675314 | 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-09-07 00:51:37.675324 | mon1 |     return context.CLIARGS()
2026-09-07 00:51:37.675333 | mon1 |            ~~~~~~~~~~~~~~~~~~~~~~~^^
2026-09-07 00:51:37.675342 | 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-09-07 00:51:37.675351 | mon1 |     return wrapped_method(*args, **kwargs)
2026-09-07 00:51:37.675360 | 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-09-07 00:51:37.675370 | mon1 |     self._execute_install_collection(
2026-09-07 00:51:37.675378 | mon1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-07 00:51:37.675387 | mon1 |         collection_requirements, collection_path,
2026-09-07 00:51:37.675399 | mon1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 00:51:37.675411 | mon1 |         artifacts_manager=artifacts_manager,
2026-09-07 00:51:37.675424 | mon1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 00:51:37.675438 | mon1 |     )
2026-09-07 00:51:37.675452 | mon1 |     ^
2026-09-07 00:51:37.675467 | 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-09-07 00:51:37.675481 | mon1 |     install_collections(
2026-09-07 00:51:37.675493 | mon1 |     ~~~~~~~~~~~~~~~~~~~^
2026-09-07 00:51:37.675506 | mon1 |         requirements, output_path, self.api_servers, ignore_errors,
2026-09-07 00:51:37.675519 | mon1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 00:51:37.675531 | mon1 |     ...<5 lines>...
2026-09-07 00:51:37.675543 | mon1 |         read_requirement_paths=read_req_paths,
2026-09-07 00:51:37.675556 | mon1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 00:51:37.675568 | mon1 |     )
2026-09-07 00:51:37.675581 | mon1 |     ^
2026-09-07 00:51:37.675620 | 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-09-07 00:51:37.675655 | mon1 |     dependency_map = _resolve_depenency_map(
2026-09-07 00:51:37.675669 | mon1 |         collections,
2026-09-07 00:51:37.675680 | mon1 |     ...<7 lines>...
2026-09-07 00:51:37.675692 | mon1 |         offline=offline,
2026-09-07 00:51:37.675704 | mon1 |     )
2026-09-07 00:51:37.675717 | 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-09-07 00:51:37.675730 | mon1 |     return collection_dep_resolver.resolve(
2026-09-07 00:51:37.675742 | mon1 |            ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-07 00:51:37.675754 | mon1 |         requested_requirements,
2026-09-07 00:51:37.675763 | mon1 |         ^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 00:51:37.675772 | mon1 |         max_rounds=2000000,  # NOTE: same constant pip uses
2026-09-07 00:51:37.675781 | mon1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 00:51:37.675790 | mon1 |     ).mapping
2026-09-07 00:51:37.675799 | mon1 |     ^
2026-09-07 00:51:37.675808 | 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-09-07 00:51:37.675817 | mon1 |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-09-07 00:51:37.675826 | mon1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/resolvelib/resolvers.py", line 397, in resolve
2026-09-07 00:51:37.675835 | mon1 |     self._add_to_criteria(self.state.criteria, r, parent=None)
2026-09-07 00:51:37.675844 | mon1 |     ~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 00:51:37.675853 | mon1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/resolvelib/resolvers.py", line 148, in _add_to_criteria
2026-09-07 00:51:37.675862 | mon1 |     matches = self._p.find_matches(
2026-09-07 00:51:37.675870 | mon1 |         identifier=identifier,
2026-09-07 00:51:37.675879 | mon1 |     ...<9 lines>...
2026-09-07 00:51:37.675888 | mon1 |         ),
2026-09-07 00:51:37.675897 | mon1 |     )
2026-09-07 00:51:37.675906 | mon1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 477, in find_matches
2026-09-07 00:51:37.675914 | mon1 |     match for match in self._find_matches(list(requirements))
2026-09-07 00:51:37.675923 | mon1 |                        ~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 00:51:37.675938 | mon1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 253, in _find_matches
2026-09-07 00:51:37.675947 | mon1 |     coll_versions =  if preinstalled_candidates else self._api_proxy.get_collection_versions(first_req)  # type: t.Iterable]
2026-09-07 00:51:37.675956 | mon1 |                                                        ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^
2026-09-07 00:51:37.675975 | 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 103, in get_collection_versions
2026-09-07 00:51:37.675985 | mon1 |     return set(
2026-09-07 00:51:37.675993 | mon1 |         (version, api)
2026-09-07 00:51:37.676002 | mon1 |     ...<2 lines>...
2026-09-07 00:51:37.676011 | mon1 |         )
2026-09-07 00:51:37.676020 | mon1 |     )
2026-09-07 00:51:37.676029 | 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 105, in <genexpr>
2026-09-07 00:51:37.676038 | mon1 |     for api, version in self._get_collection_versions(
2026-09-07 00:51:37.676047 | mon1 |                         ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-07 00:51:37.676056 | mon1 |         requirement,
2026-09-07 00:51:37.676064 | mon1 |         ^^^^^^^^^^^^
2026-09-07 00:51:37.676073 | mon1 |     )
2026-09-07 00:51:37.676082 | mon1 |     ^
2026-09-07 00:51:37.676098 | 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 85, in _get_collection_versions
2026-09-07 00:51:37.676107 | mon1 |     raise last_error
2026-09-07 00:51:37.676116 | 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 64, in _get_collection_versions
2026-09-07 00:51:37.676125 | mon1 |     versions = api.get_collection_versions(requirement.namespace, requirement.name)
2026-09-07 00:51:37.676135 | 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-09-07 00:51:37.676143 | mon1 |     return method(self, *args, **kwargs)
2026-09-07 00:51:37.676152 | mon1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 873, in get_collection_versions
2026-09-07 00:51:37.676161 | mon1 |     data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key)
2026-09-07 00:51:37.676170 | 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-09-07 00:51:37.676179 | mon1 |     return call_retryable_function()
2026-09-07 00:51:37.676188 | 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-09-07 00:51:37.676197 | mon1 |     res = path_cache
2026-09-07 00:51:37.676206 | mon1 |           ~~~~~~~~~~^^^^^^^^^^^
2026-09-07 00:51:37.676215 | mon1 | KeyError: 'results'
2026-09-07 00:51:37.676223 | mon1 |
2026-09-07 00:51:37.676232 | mon1 | ansible-galaxy
2026-09-07 00:51:37.676241 | mon1 |   config file = None
2026-09-07 00:51:37.676250 | mon1 |   configured module search path =
2026-09-07 00:51:37.676259 | mon1 |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible
2026-09-07 00:51:37.676268 | 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-09-07 00:51:37.676277 | mon1 |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/bin/ansible-galaxy
2026-09-07 00:51:37.676285 | 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-09-07 00:51:37.676294 | mon1 |   jinja version = 3.1.6
2026-09-07 00:51:37.676303 | mon1 |   libyaml = True
2026-09-07 00:51:37.676312 | mon1 | No config file found; using defaults
2026-09-07 00:51:37.676321 | mon1 | Found installed collection community.library_inventory_filtering_v1:1.1.5 at '/home/zuul/.ansible/collections/ansible_collections/community/library_inventory_filtering_v1'
2026-09-07 00:51:37.676330 | mon1 | Found installed collection community.general:13.3.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-07 00:51:37.676339 | mon1 | Found installed collection ansible.utils:6.1.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-07 00:51:37.676348 | mon1 | Opened /home/zuul/.ansible/galaxy_token
2026-09-07 00:51:37.676357 | mon1 | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an
2026-09-07 00:51:37.676366 | mon1 | unexpected error when getting available versions of collection
2026-09-07 00:51:37.676375 | mon1 | vexxhost.containers: 'results'
2026-09-07 00:51:37.676384 | mon1 | ERROR! Unexpected Exception, this is probably a bug: 'results'
2026-09-07 00:51:37.676393 | mon1 |
2026-09-07 00:51:37.677465 | mon1 | Traceback (most recent call last):
2026-09-07 00:51:37.679823 | mon1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/bin/molecule", line 10, in <module>
2026-09-07 00:51:37.679845 | mon1 |     sys.exit(main())
2026-09-07 00:51:37.679855 | mon1 |              ~~~~^^
2026-09-07 00:51:37.680058 | 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-09-07 00:51:37.680080 | mon1 |     return self.main(*args, **kwargs)
2026-09-07 00:51:37.680089 | mon1 |            ~~~~~~~~~^^^^^^^^^^^^^^^^^
2026-09-07 00:51:37.680234 | 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-09-07 00:51:37.680245 | mon1 |     rv = self.invoke(ctx)
2026-09-07 00:51:37.680427 | 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-09-07 00:51:37.680439 | mon1 |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-09-07 00:51:37.680449 | mon1 |                            ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^
2026-09-07 00:51:37.680614 | 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-09-07 00:51:37.680625 | mon1 |     return ctx.invoke(self.callback, **ctx.params)
2026-09-07 00:51:37.680634 | mon1 |            ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 00:51:37.680812 | 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-09-07 00:51:37.680823 | mon1 |     return callback(*args, **kwargs)
2026-09-07 00:51:37.680957 | 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-09-07 00:51:37.680967 | mon1 |     return func(*args, **kwargs)
2026-09-07 00:51:37.681111 | 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-09-07 00:51:37.681120 | mon1 |     return f(get_current_context(), *args, **kwargs)
2026-09-07 00:51:37.681257 | 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-09-07 00:51:37.681267 | mon1 |     return func(ctx)
2026-09-07 00:51:37.681432 | 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-09-07 00:51:37.681442 | mon1 |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-09-07 00:51:37.681451 | mon1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 00:51:37.681619 | 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-09-07 00:51:37.681630 | mon1 |     _run_scenarios(scenarios, command_args, default_config)
2026-09-07 00:51:37.681639 | mon1 |     ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 00:51:37.681914 | 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-09-07 00:51:37.681932 | mon1 |     scenario.config.runtime.prepare_environment(
2026-09-07 00:51:37.681944 | mon1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-07 00:51:37.681955 | mon1 |         install_local=True,
2026-09-07 00:51:37.681967 | mon1 |         ^^^^^^^^^^^^^^^^^^^
2026-09-07 00:51:37.681979 | mon1 |         role_name_check=role_name_check,
2026-09-07 00:51:37.681991 | mon1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 00:51:37.682004 | mon1 |     )
2026-09-07 00:51:37.682015 | mon1 |     ^
2026-09-07 00:51:37.682184 | 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-09-07 00:51:37.682201 | mon1 |     self.install_collection(
2026-09-07 00:51:37.682210 | mon1 |     ~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-07 00:51:37.682219 | mon1 |         f"{name}{',' if is_url(name) else ':'}{required_version}",
2026-09-07 00:51:37.682228 | mon1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 00:51:37.682247 | mon1 |         destination=destination,
2026-09-07 00:51:37.682256 | mon1 |         ^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 00:51:37.682265 | mon1 |     )
2026-09-07 00:51:37.682274 | mon1 |     ^
2026-09-07 00:51:37.682321 | 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-09-07 00:51:37.682331 | mon1 |     raise InvalidPrerequisiteError(msg)
2026-09-07 00:51:37.684855 | mon1 | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv vexxhost.containers:>=1.1.1, returned 250 code:
2026-09-07 00:51:37.684872 | mon1 | Starting galaxy collection install process
2026-09-07 00:51:37.684881 | mon1 | Process install dependency map
2026-09-07 00:51:37.684890 | mon1 | the full traceback was:
2026-09-07 00:51:37.684899 | mon1 |
2026-09-07 00:51:37.684908 | mon1 | Traceback (most recent call last):
2026-09-07 00:51:37.684917 | 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-09-07 00:51:37.684926 | mon1 |     exit_code = cli.run()
2026-09-07 00:51:37.684935 | 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-09-07 00:51:37.684944 | mon1 |     return context.CLIARGS['func']()
2026-09-07 00:51:37.684953 | mon1 |            ~~~~~~~~~~~~~~~~~~~~~~~^^
2026-09-07 00:51:37.684962 | 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-09-07 00:51:37.684970 | mon1 |     return wrapped_method(*args, **kwargs)
2026-09-07 00:51:37.684979 | 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-09-07 00:51:37.684988 | mon1 |     self._execute_install_collection(
2026-09-07 00:51:37.684997 | mon1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-07 00:51:37.685006 | mon1 |         collection_requirements, collection_path,
2026-09-07 00:51:37.685015 | mon1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 00:51:37.685031 | mon1 |         artifacts_manager=artifacts_manager,
2026-09-07 00:51:37.685040 | mon1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 00:51:37.685049 | mon1 |     )
2026-09-07 00:51:37.685058 | mon1 |     ^
2026-09-07 00:51:37.685067 | 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-09-07 00:51:37.685077 | mon1 |     install_collections(
2026-09-07 00:51:37.685086 | mon1 |     ~~~~~~~~~~~~~~~~~~~^
2026-09-07 00:51:37.685096 | mon1 |         requirements, output_path, self.api_servers, ignore_errors,
2026-09-07 00:51:37.685105 | mon1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 00:51:37.685114 | mon1 |     ...<5 lines>...
2026-09-07 00:51:37.685123 | mon1 |         read_requirement_paths=read_req_paths,
2026-09-07 00:51:37.685132 | mon1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 00:51:37.685141 | mon1 |     )
2026-09-07 00:51:37.685150 | mon1 |     ^
2026-09-07 00:51:37.685159 | 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-09-07 00:51:37.685167 | mon1 |     dependency_map = _resolve_depenency_map(
2026-09-07 00:51:37.685176 | mon1 |         collections,
2026-09-07 00:51:37.685185 | mon1 |     ...<7 lines>...
2026-09-07 00:51:37.685193 | mon1 |         offline=offline,
2026-09-07 00:51:37.685202 | mon1 |     )
2026-09-07 00:51:37.685211 | 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-09-07 00:51:37.685219 | mon1 |     return collection_dep_resolver.resolve(
2026-09-07 00:51:37.685228 | mon1 |            ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-07 00:51:37.685237 | mon1 |         requested_requirements,
2026-09-07 00:51:37.685245 | mon1 |         ^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 00:51:37.685261 | mon1 |         max_rounds=2000000,  # NOTE: same constant pip uses
2026-09-07 00:51:37.685270 | mon1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 00:51:37.685279 | mon1 |     ).mapping
2026-09-07 00:51:37.685288 | mon1 |     ^
2026-09-07 00:51:37.685297 | 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-09-07 00:51:37.685305 | mon1 |     state = resolution.resolve(requirements, max_rounds=max_rounds)
2026-09-07 00:51:37.685315 | mon1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/resolvelib/resolvers.py", line 397, in resolve
2026-09-07 00:51:37.685323 | mon1 |     self._add_to_criteria(self.state.criteria, r, parent=None)
2026-09-07 00:51:37.685332 | mon1 |     ~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 00:51:37.685341 | mon1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/resolvelib/resolvers.py", line 148, in _add_to_criteria
2026-09-07 00:51:37.685350 | mon1 |     matches = self._p.find_matches(
2026-09-07 00:51:37.685358 | mon1 |         identifier=identifier,
2026-09-07 00:51:37.685367 | mon1 |     ...<9 lines>...
2026-09-07 00:51:37.685377 | mon1 |         ),
2026-09-07 00:51:37.685388 | mon1 |     )
2026-09-07 00:51:37.685400 | mon1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 477, in find_matches
2026-09-07 00:51:37.685413 | mon1 |     match for match in self._find_matches(list(requirements[identifier]))
2026-09-07 00:51:37.685426 | mon1 |                        ~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-07 00:51:37.685439 | mon1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 253, in _find_matches
2026-09-07 00:51:37.685452 | mon1 |     coll_versions = [] if preinstalled_candidates else self._api_proxy.get_collection_versions(first_req)  # type: t.Iterable[t.Tuple[str, GalaxyAPI]]
2026-09-07 00:51:37.685465 | mon1 |                                                        ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^
2026-09-07 00:51:37.685486 | 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 103, in get_collection_versions
2026-09-07 00:51:37.685499 | mon1 |     return set(
2026-09-07 00:51:37.685511 | mon1 |         (version, api)
2026-09-07 00:51:37.685520 | mon1 |     ...<2 lines>...
2026-09-07 00:51:37.685528 | mon1 |         )
2026-09-07 00:51:37.685537 | mon1 |     )
2026-09-07 00:51:37.685546 | 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 105, in <genexpr>
2026-09-07 00:51:37.685555 | mon1 |     for api, version in self._get_collection_versions(
2026-09-07 00:51:37.685564 | mon1 |                         ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-09-07 00:51:37.685572 | mon1 |         requirement,
2026-09-07 00:51:37.685581 | mon1 |         ^^^^^^^^^^^^
2026-09-07 00:51:37.685622 | mon1 |     )
2026-09-07 00:51:37.685633 | mon1 |     ^
2026-09-07 00:51:37.685651 | 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 85, in _get_collection_versions
2026-09-07 00:51:37.685663 | mon1 |     raise last_error
2026-09-07 00:51:37.685674 | 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 64, in _get_collection_versions
2026-09-07 00:51:37.685685 | mon1 |     versions = api.get_collection_versions(requirement.namespace, requirement.name)
2026-09-07 00:51:37.685696 | 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-09-07 00:51:37.685708 | mon1 |     return method(self, *args, **kwargs)
2026-09-07 00:51:37.685729 | mon1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible/galaxy/api.py", line 873, in get_collection_versions
2026-09-07 00:51:37.685741 | mon1 |     data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key)
2026-09-07 00:51:37.685751 | 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-09-07 00:51:37.685759 | mon1 |     return call_retryable_function()
2026-09-07 00:51:37.685768 | 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-09-07 00:51:37.685777 | mon1 |     res = path_cache['results']
2026-09-07 00:51:37.685786 | mon1 |           ~~~~~~~~~~^^^^^^^^^^^
2026-09-07 00:51:37.685795 | mon1 | KeyError: 'results'
2026-09-07 00:51:37.685803 | mon1 |
2026-09-07 00:51:37.685812 | mon1 | ansible-galaxy [core 2.18.6]
2026-09-07 00:51:37.685820 | mon1 |   config file = None
2026-09-07 00:51:37.685829 | 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-09-07 00:51:37.685838 | mon1 |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible
2026-09-07 00:51:37.685847 | 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-09-07 00:51:37.685856 | mon1 |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/bin/ansible-galaxy
2026-09-07 00:51:37.685865 | 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-09-07 00:51:37.685874 | mon1 |   jinja version = 3.1.6
2026-09-07 00:51:37.685882 | mon1 |   libyaml = True
2026-09-07 00:51:37.685894 | mon1 | No config file found; using defaults
2026-09-07 00:51:37.685906 | mon1 | Found installed collection community.library_inventory_filtering_v1:1.1.5 at '/home/zuul/.ansible/collections/ansible_collections/community/library_inventory_filtering_v1'
2026-09-07 00:51:37.685919 | mon1 | Found installed collection community.general:13.3.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-07 00:51:37.685931 | mon1 | Found installed collection ansible.utils:6.1.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-07 00:51:37.685944 | mon1 | Opened /home/zuul/.ansible/galaxy_token
2026-09-07 00:51:37.685956 | mon1 | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an
2026-09-07 00:51:37.685968 | mon1 | unexpected error when getting available versions of collection
2026-09-07 00:51:37.685980 | mon1 | vexxhost.containers: 'results'
2026-09-07 00:51:37.685991 | mon1 | ERROR! Unexpected Exception, this is probably a bug: 'results'
2026-09-07 00:51:37.686003 | mon1 |
2026-09-07 00:51:48.123881 | mon1 | ERROR
2026-09-07 00:51:48.124097 | mon1 | {
2026-09-07 00:51:48.124131 | mon1 |   "delta": "0:01:31.018213",
2026-09-07 00:51:48.124155 | mon1 |   "end": "2026-09-07 00:51:37.805692",
2026-09-07 00:51:48.124177 | mon1 |   "msg": "non-zero return code",
2026-09-07 00:51:48.124198 | mon1 |   "rc": 1,
2026-09-07 00:51:48.124218 | mon1 |   "start": "2026-09-07 00:50:06.787479"
2026-09-07 00:51:48.124239 | mon1 | }
failure
2026-09-07 00:51:48.128724 | 
2026-09-07 00:51:48.128787 | PLAY RECAP
2026-09-07 00:51:48.128848 | mon1 | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-07 00:51:48.128908 | 
2026-09-07 00:51:48.239666 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-07 00:51:48.245502 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-07 00:51:48.877901 | 
2026-09-07 00:51:48.878033 | PLAY [all]
2026-09-07 00:51:48.888671 | 
2026-09-07 00:51:48.888761 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-07 00:51:48.959785 | mon1 | ok
2026-09-07 00:51:48.967823 | osd1 | ok
2026-09-07 00:51:48.973942 | 
2026-09-07 00:51:48.974040 | TASK [fetch-output : Set log path for single node]
2026-09-07 00:51:49.009764 | mon1 | skipping: Conditional result was False
2026-09-07 00:51:49.010466 | osd1 | skipping: Conditional result was False
2026-09-07 00:51:49.019722 | 
2026-09-07 00:51:49.019875 | LOOP [fetch-output : Ensure local output dirs]
2026-09-07 00:51:49.441570 | osd1 -> localhost | changed: "/var/lib/zuul/builds/29af656eb8294292b5a315a2d444a56b/work/logs/osd1"
2026-09-07 00:51:49.514105 | mon1 -> localhost | changed: "/var/lib/zuul/builds/29af656eb8294292b5a315a2d444a56b/work/logs/mon1"
2026-09-07 00:51:49.660635 | osd1 -> localhost | changed: "/var/lib/zuul/builds/29af656eb8294292b5a315a2d444a56b/work/artifacts"
2026-09-07 00:51:49.739554 | mon1 -> localhost | ok: "/var/lib/zuul/builds/29af656eb8294292b5a315a2d444a56b/work/artifacts"
2026-09-07 00:51:49.901688 | osd1 -> localhost | changed: "/var/lib/zuul/builds/29af656eb8294292b5a315a2d444a56b/work/docs"
2026-09-07 00:51:49.936067 | mon1 -> localhost | ok: "/var/lib/zuul/builds/29af656eb8294292b5a315a2d444a56b/work/docs"
2026-09-07 00:51:49.936190 | mon1 -> localhost | changed: All items complete
2026-09-07 00:51:49.936224 | 
2026-09-07 00:51:49.960032 | 
2026-09-07 00:51:49.960204 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-07 00:51:50.709107 | osd1 | changed: .d..t...... ./
2026-09-07 00:51:50.721763 | mon1 | changed: .d..t...... ./
2026-09-07 00:51:51.205513 | osd1 | changed: .d..t...... ./
2026-09-07 00:51:51.227812 | mon1 | ok
2026-09-07 00:51:51.665348 | osd1 | changed: .d..t...... ./
2026-09-07 00:51:51.760785 | mon1 | ok
2026-09-07 00:51:51.761001 | mon1 | changed: All items complete
2026-09-07 00:51:51.761071 | 
2026-09-07 00:51:51.800953 | 
2026-09-07 00:51:51.801102 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-07 00:51:52.273578 | mon1 -> localhost | ok: Item: artifacts Runtime: 0:00:00.025538
2026-09-07 00:51:52.559423 | mon1 -> localhost | ok: Item: docs Runtime: 0:00:00.006357
2026-09-07 00:51:52.587337 | 
2026-09-07 00:51:52.587459 | PLAY [all]
2026-09-07 00:51:52.598671 | 
2026-09-07 00:51:52.599082 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-07 00:51:53.014496 | mon1 | changed
2026-09-07 00:51:53.221958 | osd1 | changed
2026-09-07 00:51:53.231133 | 
2026-09-07 00:51:53.231178 | PLAY RECAP
2026-09-07 00:51:53.231217 | mon1 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-07 00:51:53.231242 | osd1 | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-07 00:51:53.231259 | 
2026-09-07 00:51:53.370583 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-07 00:51:53.373296 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-07 00:51:53.926949 | 
2026-09-07 00:51:53.927082 | PLAY [localhost]
2026-09-07 00:51:53.936625 | 
2026-09-07 00:51:53.936707 | TASK [Generate Zuul manifest]
2026-09-07 00:51:53.955342 | localhost | ok
2026-09-07 00:51:53.972667 | 
2026-09-07 00:51:53.972766 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-07 00:51:54.389962 | localhost | changed
2026-09-07 00:51:54.619391 | 
2026-09-07 00:51:54.619612 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-07 00:51:54.664361 | localhost | ok
2026-09-07 00:51:54.681224 | 
2026-09-07 00:51:54.681374 | TASK [Upload logs]
2026-09-07 00:51:54.704142 | localhost | ok
2026-09-07 00:51:54.817941 | 
2026-09-07 00:51:54.818100 | TASK [Set zuul-log-path fact]
2026-09-07 00:51:54.835788 | localhost | ok
2026-09-07 00:51:54.850780 | 
2026-09-07 00:51:54.850926 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-07 00:51:54.891958 | localhost | ok
2026-09-07 00:51:54.901613 | 
2026-09-07 00:51:54.901701 | TASK [upload-logs : Create log directories]
2026-09-07 00:51:55.344997 | localhost | changed
2026-09-07 00:51:55.349692 | 
2026-09-07 00:51:55.349777 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-07 00:51:55.747377 | localhost -> localhost | ok: Runtime: 0:00:00.003865
2026-09-07 00:51:55.757139 | 
2026-09-07 00:51:55.757272 | TASK [upload-logs : Upload logs to log server]
2026-09-07 00:51:56.287091 | localhost | Output suppressed because no_log was given
2026-09-07 00:51:56.294252 | 
2026-09-07 00:51:56.294375 | LOOP [upload-logs : Compress console log and json output]
2026-09-07 00:51:56.401875 | localhost | skipping: Conditional result was False
2026-09-07 00:51:56.418404 | localhost | skipping: Conditional result was False
2026-09-07 00:51:56.431973 | 
2026-09-07 00:51:56.432135 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-07 00:51:56.527400 | localhost | skipping: Conditional result was False
2026-09-07 00:51:56.527787 | 
2026-09-07 00:51:56.531137 | localhost | skipping: Conditional result was False
2026-09-07 00:51:56.590144 | 
2026-09-07 00:51:56.590444 | LOOP [upload-logs : Upload console log and json output]