2026-08-22 06:36:03.583548 | Job console starting
2026-08-22 06:36:03.597662 | Updating git repos
2026-08-22 06:36:03.620575 | Cloning repos into workspace
2026-08-22 06:36:03.659090 | Restoring repo states
2026-08-22 06:36:03.666091 | Merging changes
2026-08-22 06:36:03.982354 | Checking out repos
2026-08-22 06:36:04.024716 | Preparing playbooks
2026-08-22 06:36:04.992117 | Running Ansible setup
2026-08-22 06:36:09.359470 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-22 06:36:09.935946 | 
2026-08-22 06:36:09.936063 | PLAY [localhost]
2026-08-22 06:36:09.943143 | 
2026-08-22 06:36:09.943210 | TASK [Gathering Facts]
2026-08-22 06:36:10.763298 | localhost | ok
2026-08-22 06:36:10.773032 | 
2026-08-22 06:36:10.773105 | TASK [Setup log path fact]
2026-08-22 06:36:10.800380 | localhost | ok
2026-08-22 06:36:10.814278 | 
2026-08-22 06:36:10.814347 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-22 06:36:10.843974 | localhost | ok
2026-08-22 06:36:10.851538 | 
2026-08-22 06:36:10.851626 | TASK [emit-job-header : Print job information]
2026-08-22 06:36:10.891756 | # Job Information
2026-08-22 06:36:10.891898 | Ansible Version: 2.16.18
2026-08-22 06:36:10.891943 | Job: ansible-collection-ceph-molecule-ha-ubuntu-noble
2026-08-22 06:36:10.891973 | Pipeline: check
2026-08-22 06:36:10.892000 | Executor: 2d72f0692154
2026-08-22 06:36:10.892027 | Triggered by: https://github.com/vexxhost/ansible-collection-ceph/pull/116
2026-08-22 06:36:10.892057 | Event ID: 263bc0e0-9df3-11f1-9b14-5ec69431765d
2026-08-22 06:36:10.895718 | 
2026-08-22 06:36:10.895805 | LOOP [emit-job-header : Print node information]
2026-08-22 06:36:10.992793 | localhost | ok:
2026-08-22 06:36:11.039118 | localhost | # Node Information
2026-08-22 06:36:11.039219 | localhost | Inventory Hostname: mon1
2026-08-22 06:36:11.039276 | localhost | Hostname: np0000190416
2026-08-22 06:36:11.039321 | localhost | Username: zuul
2026-08-22 06:36:11.039380 | localhost | Distro: Ubuntu 24.04
2026-08-22 06:36:11.039425 | localhost | Provider: yul1
2026-08-22 06:36:11.039467 | localhost | Region: ca-ymq-1
2026-08-22 06:36:11.039507 | localhost | Label: ubuntu-noble
2026-08-22 06:36:11.039581 | localhost | Product Name: OpenStack Nova
2026-08-22 06:36:11.039623 | localhost | Interface IP: 199.19.213.194
2026-08-22 06:36:11.091797 | localhost | ok:
2026-08-22 06:36:11.091948 | localhost | # Node Information
2026-08-22 06:36:11.092004 | localhost | Inventory Hostname: osd1
2026-08-22 06:36:11.092050 | localhost | Hostname: np0000190417
2026-08-22 06:36:11.092092 | localhost | Username: zuul
2026-08-22 06:36:11.092133 | localhost | Distro: Ubuntu 24.04
2026-08-22 06:36:11.092173 | localhost | Provider: yul1
2026-08-22 06:36:11.092212 | localhost | Region: ca-ymq-1
2026-08-22 06:36:11.092251 | localhost | Label: ubuntu-noble
2026-08-22 06:36:11.092291 | localhost | Product Name: OpenStack Nova
2026-08-22 06:36:11.092332 | localhost | Interface IP: 199.204.45.113
2026-08-22 06:36:11.105370 | 
2026-08-22 06:36:11.105469 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-22 06:36:11.486753 | localhost -> localhost | changed
2026-08-22 06:36:11.495679 | 
2026-08-22 06:36:11.496029 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-22 06:36:12.285819 | localhost -> localhost | changed
2026-08-22 06:36:12.296552 | 
2026-08-22 06:36:12.296617 | PLAY [all]
2026-08-22 06:36:12.305164 | 
2026-08-22 06:36:12.305237 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-22 06:36:12.522262 | mon1 -> localhost | ok
2026-08-22 06:36:12.530164 | 
2026-08-22 06:36:12.530254 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-22 06:36:12.561898 | mon1 | ok
2026-08-22 06:36:12.578652 | osd1 | ok
2026-08-22 06:36:12.599016 | mon1 | included: /var/lib/zuul/builds/a67ec53b83ec46999f94223edf848e02/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-22 06:36:12.599073 | osd1 | included: /var/lib/zuul/builds/a67ec53b83ec46999f94223edf848e02/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-22 06:36:12.605368 | 
2026-08-22 06:36:12.605429 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-22 06:36:13.724733 | mon1 -> localhost | Generating public/private rsa key pair.
2026-08-22 06:36:13.724919 | mon1 -> localhost | Your identification has been saved in /var/lib/zuul/builds/a67ec53b83ec46999f94223edf848e02/work/a67ec53b83ec46999f94223edf848e02_id_rsa
2026-08-22 06:36:13.724950 | mon1 -> localhost | Your public key has been saved in /var/lib/zuul/builds/a67ec53b83ec46999f94223edf848e02/work/a67ec53b83ec46999f94223edf848e02_id_rsa.pub
2026-08-22 06:36:13.724993 | mon1 -> localhost | The key fingerprint is:
2026-08-22 06:36:13.725017 | mon1 -> localhost | SHA256:XNjrhLyWwzxITnWdrzNrb1cDwY5eHKx4plmmC91acaw zuul-build-sshkey
2026-08-22 06:36:13.725038 | mon1 -> localhost | The key's randomart image is:
2026-08-22 06:36:13.725058 | mon1 -> localhost | +---[RSA 3072]----+
2026-08-22 06:36:13.725078 | mon1 -> localhost | |            o    |
2026-08-22 06:36:13.725098 | mon1 -> localhost | |         o . *   |
2026-08-22 06:36:13.725118 | mon1 -> localhost | |        o = O o  |
2026-08-22 06:36:13.725138 | mon1 -> localhost | |       + = X O   |
2026-08-22 06:36:13.725158 | mon1 -> localhost | |      o S & = o  |
2026-08-22 06:36:13.725177 | mon1 -> localhost | |     + = X E . ..|
2026-08-22 06:36:13.725196 | mon1 -> localhost | |      o X = +   o|
2026-08-22 06:36:13.725214 | mon1 -> localhost | |       . =  .+. .|
2026-08-22 06:36:13.725233 | mon1 -> localhost | |           ..o.. |
2026-08-22 06:36:13.725252 | mon1 -> localhost | +----[SHA256]-----+
2026-08-22 06:36:13.725302 | mon1 -> localhost | ok: Runtime: 0:00:00.744081
2026-08-22 06:36:13.731007 | 
2026-08-22 06:36:13.731071 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-22 06:36:13.767515 | mon1 | ok
2026-08-22 06:36:13.773996 | osd1 | ok
2026-08-22 06:36:13.788942 | mon1 | included: /var/lib/zuul/builds/a67ec53b83ec46999f94223edf848e02/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-22 06:36:13.789034 | osd1 | included: /var/lib/zuul/builds/a67ec53b83ec46999f94223edf848e02/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-22 06:36:13.800377 | 
2026-08-22 06:36:13.800443 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-22 06:36:13.827632 | mon1 | skipping: Conditional result was False
2026-08-22 06:36:13.838717 | osd1 | skipping: Conditional result was False
2026-08-22 06:36:13.848704 | 
2026-08-22 06:36:13.848776 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-22 06:36:14.358779 | mon1 | changed
2026-08-22 06:36:14.387498 | osd1 | changed
2026-08-22 06:36:14.395226 | 
2026-08-22 06:36:14.395297 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-22 06:36:14.624462 | mon1 | ok
2026-08-22 06:36:14.631389 | osd1 | ok
2026-08-22 06:36:14.642568 | 
2026-08-22 06:36:14.642782 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-22 06:36:15.194220 | mon1 | changed
2026-08-22 06:36:15.243589 | osd1 | changed
2026-08-22 06:36:15.250848 | 
2026-08-22 06:36:15.250921 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-22 06:36:15.803089 | mon1 | changed
2026-08-22 06:36:15.809628 | osd1 | changed
2026-08-22 06:36:15.815090 | 
2026-08-22 06:36:15.815161 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-22 06:36:15.840691 | mon1 | skipping: Conditional result was False
2026-08-22 06:36:15.852117 | osd1 | skipping: Conditional result was False
2026-08-22 06:36:15.863814 | 
2026-08-22 06:36:15.863888 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-22 06:36:16.182959 | mon1 -> localhost | changed
2026-08-22 06:36:16.202471 | 
2026-08-22 06:36:16.202549 | TASK [add-build-sshkey : Add back temp key]
2026-08-22 06:36:16.460578 | mon1 -> localhost | Identity added: /var/lib/zuul/builds/a67ec53b83ec46999f94223edf848e02/work/a67ec53b83ec46999f94223edf848e02_id_rsa (zuul-build-sshkey)
2026-08-22 06:36:16.460815 | mon1 -> localhost | ok: Runtime: 0:00:00.013287
2026-08-22 06:36:16.468832 | 
2026-08-22 06:36:16.468923 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-22 06:36:16.757446 | osd1 | ok
2026-08-22 06:36:16.763542 | mon1 | ok
2026-08-22 06:36:16.768440 | 
2026-08-22 06:36:16.768507 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-22 06:36:16.792573 | mon1 | skipping: Conditional result was False
2026-08-22 06:36:16.803676 | osd1 | skipping: Conditional result was False
2026-08-22 06:36:16.823565 | 
2026-08-22 06:36:16.823632 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-22 06:36:17.141822 | osd1 | ok
2026-08-22 06:36:17.147259 | mon1 | ok
2026-08-22 06:36:17.153043 | 
2026-08-22 06:36:17.153106 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-22 06:36:17.935851 | mon1 | Output suppressed because no_log was given
2026-08-22 06:36:17.941364 | osd1 | Output suppressed because no_log was given
2026-08-22 06:36:17.955758 | 
2026-08-22 06:36:17.955826 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-22 06:36:18.174191 | mon1 | ok: "logs"
2026-08-22 06:36:18.174517 | mon1 | ok: All items complete
2026-08-22 06:36:18.174586 | 
2026-08-22 06:36:18.187256 | osd1 | ok: "logs"
2026-08-22 06:36:18.366393 | mon1 | ok: "artifacts"
2026-08-22 06:36:18.383056 | osd1 | ok: "artifacts"
2026-08-22 06:36:18.550521 | mon1 | ok: "docs"
2026-08-22 06:36:18.587221 | osd1 | ok: "docs"
2026-08-22 06:36:18.601670 | 
2026-08-22 06:36:18.601799 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-22 06:36:18.821093 | mon1 | changed: "logs"
2026-08-22 06:36:18.821319 | mon1 | ok: All items complete
2026-08-22 06:36:18.821348 | 
2026-08-22 06:36:18.839026 | osd1 | changed: "logs"
2026-08-22 06:36:19.009480 | mon1 | changed: "artifacts"
2026-08-22 06:36:19.039912 | osd1 | changed: "artifacts"
2026-08-22 06:36:19.200661 | mon1 | changed: "docs"
2026-08-22 06:36:19.232462 | osd1 | changed: "docs"
2026-08-22 06:36:19.265332 | 
2026-08-22 06:36:19.265412 | PLAY RECAP
2026-08-22 06:36:19.265482 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 06:36:19.265535 | mon1 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-22 06:36:19.265599 | osd1 | ok: 11 changed: 5 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-22 06:36:19.265640 | 
2026-08-22 06:36:19.455571 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-22 06:36:19.461188 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-22 06:36:20.079065 | 
2026-08-22 06:36:20.079203 | PLAY [all]
2026-08-22 06:36:20.090870 | 
2026-08-22 06:36:20.090947 | TASK [setup-uv : Extract archive]
2026-08-22 06:36:22.487238 | mon1 | changed
2026-08-22 06:36:22.546388 | osd1 | changed
2026-08-22 06:36:22.553452 | 
2026-08-22 06:36:22.553523 | TASK [setup-uv : Print version]
2026-08-22 06:36:22.967960 | mon1 | uv 0.8.13
2026-08-22 06:36:22.978321 | osd1 | uv 0.8.13
2026-08-22 06:36:23.119996 | mon1 | ok: Runtime: 0:00:00.015989
2026-08-22 06:36:23.129397 | osd1 | ok: Runtime: 0:00:00.015517
2026-08-22 06:36:23.144126 | 
2026-08-22 06:36:23.144190 | TASK [Install Ansible collection siblings]
2026-08-22 06:36:23.572387 | mon1 | ok:
2026-08-22 06:36:23.572554 | mon1 | Skipping community.general: no checked-out sibling
2026-08-22 06:36:23.572583 | mon1 | Skipping ansible.utils: no checked-out sibling
2026-08-22 06:36:23.572604 | mon1 | Skipping vexxhost.containers: no checked-out sibling
2026-08-22 06:36:23.574286 | 
2026-08-22 06:36:23.574357 | PLAY RECAP
2026-08-22 06:36:23.574411 | mon1 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 06:36:23.574452 | osd1 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 06:36:23.574472 | 
2026-08-22 06:36:23.681905 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-22 06:36:23.687214 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-22 06:36:24.265927 | 
2026-08-22 06:36:24.266053 | PLAY [all]
2026-08-22 06:36:24.277786 | 
2026-08-22 06:36:24.277867 | LOOP [Check inventory directories]
2026-08-22 06:36:24.635257 | mon1 | ok: "src/github.com/vexxhost/ansible-collection-ceph"
2026-08-22 06:36:24.809617 | mon1 | ok: "src/github.com/vexxhost/ansible-collection-ceph/molecule/default/inventory"
2026-08-22 06:36:24.983430 | mon1 | ok: "src/github.com/vexxhost/ansible-collection-ceph/extensions/molecule/default/inventory"
2026-08-22 06:36:25.005950 | 
2026-08-22 06:36:25.006168 | LOOP [Copy inventory file for Zuul]
2026-08-22 06:36:25.899940 | mon1 | changed:
2026-08-22 06:36:25.900119 | mon1 | {
2026-08-22 06:36:25.900147 | mon1 |   "ansible_loop_var": "item",
2026-08-22 06:36:25.900170 | mon1 |   "changed": false,
2026-08-22 06:36:25.900190 | mon1 |   "failed": false,
2026-08-22 06:36:25.900213 | mon1 |   "invocation": {
2026-08-22 06:36:25.900235 | mon1 |     "module_args": {
2026-08-22 06:36:25.900255 | mon1 |       "checksum_algorithm": "sha1",
2026-08-22 06:36:25.900273 | mon1 |       "follow": true,
2026-08-22 06:36:25.900291 | mon1 |       "get_attributes": true,
2026-08-22 06:36:25.900310 | mon1 |       "get_checksum": true,
2026-08-22 06:36:25.900329 | mon1 |       "get_mime": true,
2026-08-22 06:36:25.900346 | mon1 |       "path": "src/github.com/vexxhost/ansible-collection-ceph"
2026-08-22 06:36:25.900364 | mon1 |     }
2026-08-22 06:36:25.900382 | mon1 |   },
2026-08-22 06:36:25.900399 | mon1 |   "item": "src/github.com/vexxhost/ansible-collection-ceph",
2026-08-22 06:36:25.900416 | mon1 |   "stat": {
2026-08-22 06:36:25.900434 | mon1 |     "atime": 1787380577.8223293,
2026-08-22 06:36:25.900452 | mon1 |     "attr_flags": "e",
2026-08-22 06:36:25.900469 | mon1 |     "attributes": [
2026-08-22 06:36:25.900487 | mon1 |       "extents"
2026-08-22 06:36:25.900504 | mon1 |     ],
2026-08-22 06:36:25.900522 | mon1 |     "block_size": 4096,
2026-08-22 06:36:25.900539 | mon1 |     "blocks": 8,
2026-08-22 06:36:25.900556 | mon1 |     "charset": "unknown",
2026-08-22 06:36:25.900575 | mon1 |     "ctime": 1787380577.8353302,
2026-08-22 06:36:25.900593 | mon1 |     "dev": 64769,
2026-08-22 06:36:25.900611 | mon1 |     "device_type": 0,
2026-08-22 06:36:25.900629 | mon1 |     "executable": true,
2026-08-22 06:36:25.900646 | mon1 |     "exists": true,
2026-08-22 06:36:25.900663 | mon1 |     "gid": 1000,
2026-08-22 06:36:25.900680 | mon1 |     "gr_name": "zuul",
2026-08-22 06:36:25.900697 | mon1 |     "inode": 1034272,
2026-08-22 06:36:25.900714 | mon1 |     "isblk": false,
2026-08-22 06:36:25.900731 | mon1 |     "ischr": false,
2026-08-22 06:36:25.900748 | mon1 |     "isdir": true,
2026-08-22 06:36:25.900765 | mon1 |     "isfifo": false,
2026-08-22 06:36:25.900782 | mon1 |     "isgid": false,
2026-08-22 06:36:25.900799 | mon1 |     "islnk": false,
2026-08-22 06:36:25.900816 | mon1 |     "isreg": false,
2026-08-22 06:36:25.900832 | mon1 |     "issock": false,
2026-08-22 06:36:25.900849 | mon1 |     "isuid": false,
2026-08-22 06:36:25.900865 | mon1 |     "mimetype": "unknown",
2026-08-22 06:36:25.900882 | mon1 |     "mode": "0755",
2026-08-22 06:36:25.900898 | mon1 |     "mtime": 1787380563.6823852,
2026-08-22 06:36:25.900915 | mon1 |     "nlink": 10,
2026-08-22 06:36:25.900932 | mon1 |     "path": "src/github.com/vexxhost/ansible-collection-ceph",
2026-08-22 06:36:25.900949 | mon1 |     "pw_name": "zuul",
2026-08-22 06:36:25.900973 | mon1 |     "readable": true,
2026-08-22 06:36:25.900992 | mon1 |     "rgrp": true,
2026-08-22 06:36:25.901009 | mon1 |     "roth": true,
2026-08-22 06:36:25.901026 | mon1 |     "rusr": true,
2026-08-22 06:36:25.901073 | mon1 |     "size": 4096,
2026-08-22 06:36:25.901103 | mon1 |     "uid": 1000,
2026-08-22 06:36:25.901121 | mon1 |     "version": "2608364967",
2026-08-22 06:36:25.901139 | mon1 |     "wgrp": false,
2026-08-22 06:36:25.901157 | mon1 |     "woth": false,
2026-08-22 06:36:25.901175 | mon1 |     "writeable": true,
2026-08-22 06:36:25.901192 | mon1 |     "wusr": true,
2026-08-22 06:36:25.901209 | mon1 |     "xgrp": true,
2026-08-22 06:36:25.901226 | mon1 |     "xoth": true,
2026-08-22 06:36:25.901243 | mon1 |     "xusr": true
2026-08-22 06:36:25.901260 | mon1 |   }
2026-08-22 06:36:25.901277 | mon1 | }
2026-08-22 06:36:25.901309 | mon1 | ok: All items complete
2026-08-22 06:36:25.901330 | 
2026-08-22 06:36:25.902587 | mon1 | skipping: Conditional result was False
2026-08-22 06:36:25.904321 | mon1 | skipping: Conditional result was False
2026-08-22 06:36:25.915195 | 
2026-08-22 06:36:25.915262 | LOOP [Switch "ansible_host" to private IP]
2026-08-22 06:36:26.277384 | mon1 | changed: 2 replacements made
2026-08-22 06:36:26.278704 | mon1 | skipping: Conditional result was False
2026-08-22 06:36:26.279268 | mon1 | skipping: Conditional result was False
2026-08-22 06:36:26.296332 | 
2026-08-22 06:36:26.296397 | TASK [Run Molecule scenario]
2026-08-22 06:36:26.760831 | mon1 | Using CPython 3.12.3 interpreter at: /usr/bin/python3
2026-08-22 06:36:26.760989 | mon1 | Creating virtual environment at: .venv
2026-08-22 06:36:27.008367 | mon1 | Downloading netaddr (2.2MiB)
2026-08-22 06:36:27.008903 | mon1 | Downloading ansible-core (2.1MiB)
2026-08-22 06:36:27.009105 | mon1 | Downloading cryptography (4.3MiB)
2026-08-22 06:36:27.009316 | mon1 | Downloading pygments (1.2MiB)
2026-08-22 06:36:27.191886 | mon1 |  Downloading netaddr
2026-08-22 06:36:27.202712 | mon1 |  Downloading cryptography
2026-08-22 06:36:27.259609 | mon1 |  Downloading pygments
2026-08-22 06:36:27.421034 | mon1 |  Downloading ansible-core
2026-08-22 06:36:27.468240 | mon1 | Installed 37 packages in 46ms
2026-08-22 06:36:28.229211 | mon1 | INFO     Collection 'vexxhost.ceph' detected.
2026-08-22 06:36:28.229315 | mon1 | INFO     Scenarios will be used from 'extensions/molecule'
2026-08-22 06:36:28.864337 | mon1 | INFO     [default > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-08-22 06:36:28.864409 | mon1 | INFO     [default > prerun] Performing prerun with role_name_check=0...
2026-08-22 06:36:44.690265 | mon1 | INFO     [default > dependency] Executing
2026-08-22 06:36:44.690388 | mon1 | WARNING  [default > dependency] Missing roles requirements file: requirements.yml
2026-08-22 06:36:44.690590 | mon1 | WARNING  [default > dependency] Missing collections requirements file: collections.yml
2026-08-22 06:36:44.690768 | mon1 | WARNING  [default > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-08-22 06:36:44.696433 | mon1 | INFO     [default > cleanup] Executing
2026-08-22 06:36:44.696758 | mon1 | WARNING  [default > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-22 06:36:44.702296 | mon1 | INFO     [default > destroy] Executing
2026-08-22 06:36:44.702332 | mon1 | WARNING  [default > destroy] Skipping, '--destroy=never' requested.
2026-08-22 06:36:44.702439 | mon1 | INFO     [default > destroy] Executed: Successful
2026-08-22 06:36:44.708029 | mon1 | INFO     [default > syntax] Executing
2026-08-22 06:36:45.836333 | mon1 |
2026-08-22 06:36:45.836571 | mon1 | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-ceph/extensions/molecule/default/converge.yml
2026-08-22 06:36:45.889813 | mon1 | INFO     [default > syntax] Executed: Successful
2026-08-22 06:36:45.899999 | mon1 | INFO     [default > create] Executing
2026-08-22 06:36:45.901967 | mon1 | WARNING  [default > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-22 06:36:45.908182 | mon1 | INFO     [default > prepare] Executing
2026-08-22 06:36:46.669846 | mon1 |
2026-08-22 06:36:46.670053 | mon1 | PLAY [Prepare] *****************************************************************
2026-08-22 06:36:46.670279 | mon1 |
2026-08-22 06:36:46.670527 | mon1 | TASK [Gathering Facts] *********************************************************
2026-08-22 06:36:46.670809 | mon1 | Saturday 22 August 2026  06:36:46 +0000 (0:00:00.032)       0:00:00.032 *******
2026-08-22 06:36:47.918798 | mon1 | [WARNING]: Platform linux on host mon1 is using the discovered Python
2026-08-22 06:36:47.919047 | mon1 | interpreter at /usr/bin/python3.12, but future installation of another Python
2026-08-22 06:36:47.919289 | mon1 | interpreter could change the meaning of that path. See
2026-08-22 06:36:47.919528 | mon1 | https://docs.ansible.com/ansible-
2026-08-22 06:36:47.919823 | mon1 | core/2.18/reference_appendices/interpreter_discovery.html for more information.
2026-08-22 06:36:47.963570 | mon1 | [WARNING]: Platform linux on host osd1 is using the discovered Python
2026-08-22 06:36:47.963825 | mon1 | interpreter at /usr/bin/python3.12, but future installation of another Python
2026-08-22 06:36:47.964079 | mon1 | interpreter could change the meaning of that path. See
2026-08-22 06:36:47.964317 | mon1 | https://docs.ansible.com/ansible-
2026-08-22 06:36:47.964559 | mon1 | core/2.18/reference_appendices/interpreter_discovery.html for more information.
2026-08-22 06:36:47.973459 | mon1 | ok: [mon1]
2026-08-22 06:36:47.973745 | mon1 | ok: [osd1]
2026-08-22 06:36:47.974109 | mon1 |
2026-08-22 06:36:47.974293 | mon1 | TASK [Configure short hostname] ************************************************
2026-08-22 06:36:47.974549 | mon1 | Saturday 22 August 2026  06:36:47 +0000 (0:00:01.303)       0:00:01.336 *******
2026-08-22 06:36:48.718374 | mon1 | changed: [mon1]
2026-08-22 06:36:48.718643 | mon1 | changed: [osd1]
2026-08-22 06:36:48.718922 | mon1 |
2026-08-22 06:36:48.719176 | mon1 | TASK [Ensure hostname inside hosts file] ***************************************
2026-08-22 06:36:48.719455 | mon1 | Saturday 22 August 2026  06:36:48 +0000 (0:00:00.744)       0:00:02.080 *******
2026-08-22 06:36:49.032055 | mon1 | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-08-22 06:36:49.032313 | mon1 | with a mode of 0700, this may cause issues when running as another user. To
2026-08-22 06:36:49.032571 | mon1 | avoid this, create the remote_tmp dir with the correct permissions manually
2026-08-22 06:36:49.040839 | mon1 | changed: [mon1]
2026-08-22 06:36:49.041084 | mon1 | changed: [osd1]
2026-08-22 06:36:49.041318 | mon1 |
2026-08-22 06:36:49.041569 | mon1 | TASK [Update apt cache] ********************************************************
2026-08-22 06:36:49.041858 | mon1 | Saturday 22 August 2026  06:36:49 +0000 (0:00:00.322)       0:00:02.403 *******
2026-08-22 06:36:54.882768 | mon1 | changed: [mon1]
2026-08-22 06:36:54.882811 | mon1 | changed: [osd1]
2026-08-22 06:36:54.882822 | mon1 |
2026-08-22 06:36:54.883059 | mon1 | PLAY [Create devices for Ceph] *************************************************
2026-08-22 06:36:54.883274 | mon1 |
2026-08-22 06:36:54.883519 | mon1 | TASK [Gathering Facts] *********************************************************
2026-08-22 06:36:54.883806 | mon1 | Saturday 22 August 2026  06:36:54 +0000 (0:00:05.841)       0:00:08.245 *******
2026-08-22 06:36:56.726527 | mon1 | ok: [osd1]
2026-08-22 06:36:56.726856 | mon1 |
2026-08-22 06:36:56.727122 | mon1 | TASK [Install depedencies] *****************************************************
2026-08-22 06:36:56.727371 | mon1 | Saturday 22 August 2026  06:36:56 +0000 (0:00:01.843)       0:00:10.089 *******
2026-08-22 06:37:15.668940 | mon1 | changed: [osd1]
2026-08-22 06:37:15.669137 | mon1 |
2026-08-22 06:37:15.669381 | mon1 | TASK [Start up service] ********************************************************
2026-08-22 06:37:15.669626 | mon1 | Saturday 22 August 2026  06:37:15 +0000 (0:00:18.942)       0:00:29.031 *******
2026-08-22 06:37:16.349941 | mon1 | ok: [osd1]
2026-08-22 06:37:16.350009 | mon1 |
2026-08-22 06:37:16.350023 | mon1 | TASK [Generate lvm.conf] *******************************************************
2026-08-22 06:37:16.350109 | mon1 | Saturday 22 August 2026  06:37:16 +0000 (0:00:00.678)       0:00:29.710 *******
2026-08-22 06:37:16.700774 | mon1 | ok: [osd1]
2026-08-22 06:37:16.701014 | mon1 |
2026-08-22 06:37:16.701284 | mon1 | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-08-22 06:37:16.701555 | mon1 | Saturday 22 August 2026  06:37:16 +0000 (0:00:00.352)       0:00:30.063 *******
2026-08-22 06:37:17.471409 | mon1 | changed: [osd1]
2026-08-22 06:37:17.471611 | mon1 |
2026-08-22 06:37:17.471942 | mon1 | TASK [Get list of all loopback devices] ****************************************
2026-08-22 06:37:17.472201 | mon1 | Saturday 22 August 2026  06:37:17 +0000 (0:00:00.770)       0:00:30.834 *******
2026-08-22 06:37:17.723591 | mon1 | ok: [osd1]
2026-08-22 06:37:17.723821 | mon1 |
2026-08-22 06:37:17.724073 | mon1 | TASK [Fail if there is any existing loopback devices] **************************
2026-08-22 06:37:17.724323 | mon1 | Saturday 22 August 2026  06:37:17 +0000 (0:00:00.252)       0:00:31.086 *******
2026-08-22 06:37:17.750985 | mon1 | skipping: [osd1]
2026-08-22 06:37:17.751169 | mon1 |
2026-08-22 06:37:17.751413 | mon1 | TASK [Create devices for Ceph] *************************************************
2026-08-22 06:37:17.751656 | mon1 | Saturday 22 August 2026  06:37:17 +0000 (0:00:00.027)       0:00:31.114 *******
2026-08-22 06:37:18.433538 | mon1 | changed: [osd1] => (item=osd0)
2026-08-22 06:37:18.433773 | mon1 | changed: [osd1] => (item=osd1)
2026-08-22 06:37:18.434056 | mon1 | changed: [osd1] => (item=osd2)
2026-08-22 06:37:18.434276 | mon1 |
2026-08-22 06:37:18.434523 | mon1 | TASK [Set permissions on loopback devices] *************************************
2026-08-22 06:37:18.434818 | mon1 | Saturday 22 August 2026  06:37:18 +0000 (0:00:00.682)       0:00:31.796 *******
2026-08-22 06:37:19.233782 | mon1 | changed: [osd1] => (item=osd0)
2026-08-22 06:37:19.233827 | mon1 | changed: [osd1] => (item=osd1)
2026-08-22 06:37:19.233831 | mon1 | changed: [osd1] => (item=osd2)
2026-08-22 06:37:19.233835 | mon1 |
2026-08-22 06:37:19.233839 | mon1 | TASK [Start loop devices] ******************************************************
2026-08-22 06:37:19.233844 | mon1 | Saturday 22 August 2026  06:37:19 +0000 (0:00:00.798)       0:00:32.594 *******
2026-08-22 06:37:20.064765 | mon1 | changed: [osd1] => (item=osd0)
2026-08-22 06:37:20.064801 | mon1 | changed: [osd1] => (item=osd1)
2026-08-22 06:37:20.064966 | mon1 | changed: [osd1] => (item=osd2)
2026-08-22 06:37:20.065182 | mon1 |
2026-08-22 06:37:20.065426 | mon1 | TASK [Create a volume group for each loop device] ******************************
2026-08-22 06:37:20.065667 | mon1 | Saturday 22 August 2026  06:37:20 +0000 (0:00:00.831)       0:00:33.426 *******
2026-08-22 06:37:21.502497 | mon1 | changed: [osd1] => (item=osd0)
2026-08-22 06:37:21.502919 | mon1 | changed: [osd1] => (item=osd1)
2026-08-22 06:37:21.503783 | mon1 | changed: [osd1] => (item=osd2)
2026-08-22 06:37:21.503811 | mon1 |
2026-08-22 06:37:21.503859 | mon1 | TASK [Create a logical volume for each loop device] ****************************
2026-08-22 06:37:21.504102 | mon1 | Saturday 22 August 2026  06:37:21 +0000 (0:00:01.439)       0:00:34.865 *******
2026-08-22 06:37:22.868577 | mon1 | changed: [osd1] => (item=ceph-osd1-osd0)
2026-08-22 06:37:22.868826 | mon1 | changed: [osd1] => (item=ceph-osd1-osd1)
2026-08-22 06:37:22.869075 | mon1 | changed: [osd1] => (item=ceph-osd1-osd2)
2026-08-22 06:37:22.869304 | mon1 |
2026-08-22 06:37:22.869567 | mon1 | PLAY RECAP *********************************************************************
2026-08-22 06:37:22.869932 | mon1 | mon1                       : ok=4    changed=3    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-08-22 06:37:22.870131 | mon1 | osd1                       : ok=15   changed=10   unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-08-22 06:37:22.870345 | mon1 |
2026-08-22 06:37:22.870556 | mon1 |
2026-08-22 06:37:22.870828 | mon1 | TASKS RECAP ********************************************************************
2026-08-22 06:37:22.871071 | mon1 | Saturday 22 August 2026  06:37:22 +0000 (0:00:01.366)       0:00:36.231 *******
2026-08-22 06:37:22.871296 | mon1 | ===============================================================================
2026-08-22 06:37:22.871531 | mon1 | Install depedencies ---------------------------------------------------- 18.94s
2026-08-22 06:37:22.871793 | mon1 | Update apt cache -------------------------------------------------------- 5.84s
2026-08-22 06:37:22.872031 | mon1 | Gathering Facts --------------------------------------------------------- 1.84s
2026-08-22 06:37:22.872332 | mon1 | Create a volume group for each loop device ------------------------------ 1.44s
2026-08-22 06:37:22.872576 | mon1 | Create a logical volume for each loop device ---------------------------- 1.37s
2026-08-22 06:37:22.872905 | mon1 | Gathering Facts --------------------------------------------------------- 1.30s
2026-08-22 06:37:22.873083 | mon1 | Start loop devices ------------------------------------------------------ 0.83s
2026-08-22 06:37:22.873315 | mon1 | Set permissions on loopback devices ------------------------------------- 0.80s
2026-08-22 06:37:22.873465 | mon1 | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.77s
2026-08-22 06:37:22.873606 | mon1 | Configure short hostname ------------------------------------------------ 0.74s
2026-08-22 06:37:22.873786 | mon1 | Create devices for Ceph ------------------------------------------------- 0.68s
2026-08-22 06:37:22.873926 | mon1 | Start up service -------------------------------------------------------- 0.68s
2026-08-22 06:37:22.874072 | mon1 | Generate lvm.conf ------------------------------------------------------- 0.35s
2026-08-22 06:37:22.874223 | mon1 | Ensure hostname inside hosts file --------------------------------------- 0.32s
2026-08-22 06:37:22.874382 | mon1 | Get list of all loopback devices ---------------------------------------- 0.25s
2026-08-22 06:37:22.874534 | mon1 | Fail if there is any existing loopback devices -------------------------- 0.03s
2026-08-22 06:37:22.990879 | mon1 | INFO     [default > prepare] Executed: Successful
2026-08-22 06:37:23.002640 | mon1 | INFO     [default > converge] Executing
2026-08-22 06:37:23.986660 | mon1 |
2026-08-22 06:37:23.986864 | mon1 | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-08-22 06:37:23.987085 | mon1 |
2026-08-22 06:37:23.987356 | mon1 | TASK [Gathering Facts] *********************************************************
2026-08-22 06:37:23.987604 | mon1 | Saturday 22 August 2026  06:37:23 +0000 (0:00:00.051)       0:00:00.051 *******
2026-08-22 06:37:25.019490 | mon1 | [WARNING]: Platform linux on host mon1 is using the discovered Python
2026-08-22 06:37:25.019556 | mon1 | interpreter at /usr/bin/python3.12, but future installation of another Python
2026-08-22 06:37:25.019681 | mon1 | interpreter could change the meaning of that path. See
2026-08-22 06:37:25.019796 | mon1 | https://docs.ansible.com/ansible-
2026-08-22 06:37:25.019920 | mon1 | core/2.18/reference_appendices/interpreter_discovery.html for more information.
2026-08-22 06:37:25.035922 | mon1 | ok: [mon1]
2026-08-22 06:37:25.036106 | mon1 |
2026-08-22 06:37:25.036372 | mon1 | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-22 06:37:25.036637 | mon1 | Saturday 22 August 2026  06:37:25 +0000 (0:00:01.048)       0:00:01.099 *******
2026-08-22 06:37:25.381625 | mon1 | ok: [mon1]
2026-08-22 06:37:25.381718 | mon1 |
2026-08-22 06:37:25.381916 | mon1 | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-22 06:37:25.382072 | mon1 | Saturday 22 August 2026  06:37:25 +0000 (0:00:00.346)       0:00:01.446 *******
2026-08-22 06:37:25.422093 | mon1 | skipping: [mon1]
2026-08-22 06:37:25.422255 | mon1 |
2026-08-22 06:37:25.422520 | mon1 | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-08-22 06:37:25.422779 | mon1 | Saturday 22 August 2026  06:37:25 +0000 (0:00:00.040)       0:00:01.486 *******
2026-08-22 06:37:25.777170 | mon1 | changed: [mon1]
2026-08-22 06:37:25.777373 | mon1 |
2026-08-22 06:37:25.777645 | mon1 | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 06:37:25.777964 | mon1 | Saturday 22 August 2026  06:37:25 +0000 (0:00:00.354)       0:00:01.841 *******
2026-08-22 06:37:25.885324 | mon1 | ok: [mon1] => {
2026-08-22 06:37:25.885365 | mon1 |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-08-22 06:37:25.885485 | mon1 | }
2026-08-22 06:37:25.885579 | mon1 |
2026-08-22 06:37:25.885690 | mon1 | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 06:37:25.885876 | mon1 | Saturday 22 August 2026  06:37:25 +0000 (0:00:00.108)       0:00:01.950 *******
2026-08-22 06:37:26.564962 | mon1 | changed: [mon1]
2026-08-22 06:37:26.565050 | mon1 |
2026-08-22 06:37:26.565062 | mon1 | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 06:37:26.565080 | mon1 | Saturday 22 August 2026  06:37:26 +0000 (0:00:00.678)       0:00:02.628 *******
2026-08-22 06:37:26.612322 | mon1 | skipping: [mon1]
2026-08-22 06:37:26.612449 | mon1 |
2026-08-22 06:37:26.612619 | mon1 | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-22 06:37:26.613132 | mon1 | Saturday 22 August 2026  06:37:26 +0000 (0:00:00.047)       0:00:02.676 *******
2026-08-22 06:37:26.661998 | mon1 | skipping: [mon1]
2026-08-22 06:37:26.662341 | mon1 |
2026-08-22 06:37:26.662532 | mon1 | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-22 06:37:26.662801 | mon1 | Saturday 22 August 2026  06:37:26 +0000 (0:00:00.050)       0:00:02.726 *******
2026-08-22 06:37:26.912493 | mon1 | ok: [mon1]
2026-08-22 06:37:26.912695 | mon1 |
2026-08-22 06:37:26.913044 | mon1 | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-22 06:37:26.913852 | mon1 | Saturday 22 August 2026  06:37:26 +0000 (0:00:00.249)       0:00:02.976 *******
2026-08-22 06:37:27.907912 | mon1 | ok: [mon1]
2026-08-22 06:37:27.907973 | mon1 |
2026-08-22 06:37:27.907991 | mon1 | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 06:37:27.908186 | mon1 | Saturday 22 August 2026  06:37:27 +0000 (0:00:00.995)       0:00:03.971 *******
2026-08-22 06:37:28.106199 | mon1 | ok: [mon1] => {
2026-08-22 06:37:28.106391 | mon1 |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-08-22 06:37:28.106615 | mon1 | }
2026-08-22 06:37:28.106868 | mon1 |
2026-08-22 06:37:28.107110 | mon1 | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 06:37:28.107395 | mon1 | Saturday 22 August 2026  06:37:28 +0000 (0:00:00.198)       0:00:04.170 *******
2026-08-22 06:37:28.912638 | mon1 | changed: [mon1]
2026-08-22 06:37:28.912683 | mon1 |
2026-08-22 06:37:28.912694 | mon1 | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 06:37:28.912703 | mon1 | Saturday 22 August 2026  06:37:28 +0000 (0:00:00.805)       0:00:04.976 *******
2026-08-22 06:37:32.069813 | mon1 | changed: [mon1]
2026-08-22 06:37:32.069896 | mon1 |
2026-08-22 06:37:32.070227 | mon1 | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-08-22 06:37:32.070264 | mon1 | Saturday 22 August 2026  06:37:32 +0000 (0:00:03.157)       0:00:08.133 *******
2026-08-22 06:37:32.107322 | mon1 | skipping: [mon1]
2026-08-22 06:37:32.107379 | mon1 |
2026-08-22 06:37:32.107393 | mon1 | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-08-22 06:37:32.107406 | mon1 | Saturday 22 August 2026  06:37:32 +0000 (0:00:00.037)       0:00:08.170 *******
2026-08-22 06:37:32.146872 | mon1 | skipping: [mon1]
2026-08-22 06:37:32.146929 | mon1 |
2026-08-22 06:37:32.146948 | mon1 | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-08-22 06:37:32.147174 | mon1 | Saturday 22 August 2026  06:37:32 +0000 (0:00:00.039)       0:00:08.210 *******
2026-08-22 06:37:32.185821 | mon1 | skipping: [mon1]
2026-08-22 06:37:32.185884 | mon1 |
2026-08-22 06:37:32.185890 | mon1 | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-08-22 06:37:32.185994 | mon1 | Saturday 22 August 2026  06:37:32 +0000 (0:00:00.039)       0:00:08.249 *******
2026-08-22 06:37:40.768548 | mon1 | changed: [mon1]
2026-08-22 06:37:40.768625 | mon1 |
2026-08-22 06:37:40.768644 | mon1 | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-08-22 06:37:40.768860 | mon1 | Saturday 22 August 2026  06:37:40 +0000 (0:00:08.582)       0:00:16.832 *******
2026-08-22 06:37:41.433488 | mon1 | changed: [mon1]
2026-08-22 06:37:41.433587 | mon1 |
2026-08-22 06:37:41.433602 | mon1 | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-08-22 06:37:41.433616 | mon1 | Saturday 22 August 2026  06:37:41 +0000 (0:00:00.664)       0:00:17.496 *******
2026-08-22 06:37:42.525220 | mon1 | changed: [mon1] => (item={'path': '/etc/containerd'})
2026-08-22 06:37:42.525269 | mon1 | changed: [mon1] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-08-22 06:37:42.525277 | mon1 | changed: [mon1] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-08-22 06:37:42.525283 | mon1 | changed: [mon1] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-08-22 06:37:42.525290 | mon1 | changed: [mon1] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-08-22 06:37:42.525296 | mon1 |
2026-08-22 06:37:42.525302 | mon1 | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-08-22 06:37:42.525309 | mon1 | Saturday 22 August 2026  06:37:42 +0000 (0:00:01.092)       0:00:18.588 *******
2026-08-22 06:37:43.286029 | mon1 | changed: [mon1]
2026-08-22 06:37:43.286078 | mon1 |
2026-08-22 06:37:43.286092 | mon1 | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-08-22 06:37:43.286104 | mon1 | Saturday 22 August 2026  06:37:43 +0000 (0:00:00.750)       0:00:19.339 *******
2026-08-22 06:37:43.286116 | mon1 |
2026-08-22 06:37:43.286127 | mon1 | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-22 06:37:43.286139 | mon1 | Saturday 22 August 2026  06:37:43 +0000 (0:00:00.009)       0:00:19.348 *******
2026-08-22 06:37:44.411591 | mon1 | ok: [mon1]
2026-08-22 06:37:44.411630 | mon1 |
2026-08-22 06:37:44.411636 | mon1 | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-08-22 06:37:44.411642 | mon1 | Saturday 22 August 2026  06:37:44 +0000 (0:00:01.127)       0:00:20.475 *******
2026-08-22 06:37:44.984253 | mon1 | changed: [mon1]
2026-08-22 06:37:44.984370 | mon1 |
2026-08-22 06:37:44.984489 | mon1 | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-08-22 06:37:44.984962 | mon1 | Saturday 22 August 2026  06:37:44 +0000 (0:00:00.572)       0:00:21.048 *******
2026-08-22 06:37:45.677218 | mon1 | changed: [mon1]
2026-08-22 06:37:45.677275 | mon1 |
2026-08-22 06:37:45.677341 | mon1 | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-22 06:37:45.677447 | mon1 | Saturday 22 August 2026  06:37:45 +0000 (0:00:00.693)       0:00:21.741 *******
2026-08-22 06:37:45.927834 | mon1 | ok: [mon1]
2026-08-22 06:37:45.927902 | mon1 |
2026-08-22 06:37:45.927917 | mon1 | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 06:37:45.927930 | mon1 | Saturday 22 August 2026  06:37:45 +0000 (0:00:00.248)       0:00:21.990 *******
2026-08-22 06:37:46.003060 | mon1 | ok: [mon1] => {
2026-08-22 06:37:46.003230 | mon1 |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-08-22 06:37:46.003387 | mon1 | }
2026-08-22 06:37:46.003487 | mon1 |
2026-08-22 06:37:46.003601 | mon1 | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 06:37:46.003706 | mon1 | Saturday 22 August 2026  06:37:46 +0000 (0:00:00.076)       0:00:22.067 *******
2026-08-22 06:37:46.934314 | mon1 | changed: [mon1]
2026-08-22 06:37:46.934373 | mon1 |
2026-08-22 06:37:46.934387 | mon1 | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 06:37:46.934399 | mon1 | Saturday 22 August 2026  06:37:46 +0000 (0:00:00.930)       0:00:22.997 *******
2026-08-22 06:37:51.498268 | mon1 | changed: [mon1]
2026-08-22 06:37:51.498327 | mon1 |
2026-08-22 06:37:51.498340 | mon1 | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-08-22 06:37:51.498352 | mon1 | Saturday 22 August 2026  06:37:51 +0000 (0:00:04.563)       0:00:27.561 *******
2026-08-22 06:37:52.384777 | mon1 | ok: [mon1]
2026-08-22 06:37:52.384811 | mon1 |
2026-08-22 06:37:52.385220 | mon1 | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-08-22 06:37:52.385270 | mon1 | Saturday 22 August 2026  06:37:52 +0000 (0:00:00.886)       0:00:28.448 *******
2026-08-22 06:37:52.792503 | mon1 | changed: [mon1]
2026-08-22 06:37:52.792536 | mon1 |
2026-08-22 06:37:52.792542 | mon1 | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-08-22 06:37:52.792548 | mon1 | Saturday 22 August 2026  06:37:52 +0000 (0:00:00.406)       0:00:28.854 *******
2026-08-22 06:37:53.345264 | mon1 | changed: [mon1]
2026-08-22 06:37:53.345391 | mon1 |
2026-08-22 06:37:53.345880 | mon1 | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-08-22 06:37:53.345938 | mon1 | Saturday 22 August 2026  06:37:53 +0000 (0:00:00.554)       0:00:29.409 *******
2026-08-22 06:37:54.017115 | mon1 | changed: [mon1] => (item={'path': '/etc/docker'})
2026-08-22 06:37:54.017206 | mon1 | changed: [mon1] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-08-22 06:37:54.017673 | mon1 | changed: [mon1] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-08-22 06:37:54.017706 | mon1 |
2026-08-22 06:37:54.017714 | mon1 | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-08-22 06:37:54.017739 | mon1 | Saturday 22 August 2026  06:37:54 +0000 (0:00:00.670)       0:00:30.080 *******
2026-08-22 06:37:54.559116 | mon1 | changed: [mon1]
2026-08-22 06:37:54.559192 | mon1 |
2026-08-22 06:37:54.559309 | mon1 | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-08-22 06:37:54.559422 | mon1 | Saturday 22 August 2026  06:37:54 +0000 (0:00:00.543)       0:00:30.623 *******
2026-08-22 06:37:55.118928 | mon1 | changed: [mon1]
2026-08-22 06:37:55.118983 | mon1 |
2026-08-22 06:37:55.118996 | mon1 | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-08-22 06:37:55.119010 | mon1 | Saturday 22 August 2026  06:37:55 +0000 (0:00:00.548)       0:00:31.172 *******
2026-08-22 06:37:55.119022 | mon1 |
2026-08-22 06:37:55.119033 | mon1 | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-22 06:37:55.119045 | mon1 | Saturday 22 August 2026  06:37:55 +0000 (0:00:00.009)       0:00:31.181 *******
2026-08-22 06:37:55.989693 | mon1 | ok: [mon1]
2026-08-22 06:37:55.989752 | mon1 |
2026-08-22 06:37:55.989763 | mon1 | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-08-22 06:37:55.989769 | mon1 | Saturday 22 August 2026  06:37:55 +0000 (0:00:00.871)       0:00:32.053 *******
2026-08-22 06:37:57.105826 | mon1 | changed: [mon1]
2026-08-22 06:37:57.105898 | mon1 |
2026-08-22 06:37:57.106348 | mon1 | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-08-22 06:37:57.106429 | mon1 | Saturday 22 August 2026  06:37:57 +0000 (0:00:01.116)       0:00:33.169 *******
2026-08-22 06:37:57.803461 | mon1 | changed: [mon1]
2026-08-22 06:37:57.803526 | mon1 |
2026-08-22 06:37:57.803540 | mon1 | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-08-22 06:37:57.803552 | mon1 | Saturday 22 August 2026  06:37:57 +0000 (0:00:00.697)       0:00:33.866 *******
2026-08-22 06:37:57.870154 | mon1 | ok: [mon1] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/debian.yml)
2026-08-22 06:37:57.870189 | mon1 |
2026-08-22 06:37:57.870202 | mon1 | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-08-22 06:37:57.870214 | mon1 | Saturday 22 August 2026  06:37:57 +0000 (0:00:00.067)       0:00:33.933 *******
2026-08-22 06:38:15.273380 | mon1 | changed: [mon1]
2026-08-22 06:38:15.273447 | mon1 |
2026-08-22 06:38:15.273461 | mon1 | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-08-22 06:38:15.273480 | mon1 | Saturday 22 August 2026  06:38:15 +0000 (0:00:17.402)       0:00:51.336 *******
2026-08-22 06:38:16.054507 | mon1 | ok: [mon1] => (item=systemd-timesyncd)
2026-08-22 06:38:16.054559 | mon1 | ok: [mon1] => (item=ssh)
2026-08-22 06:38:16.054573 | mon1 |
2026-08-22 06:38:16.054585 | mon1 | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-08-22 06:38:16.054597 | mon1 | Saturday 22 August 2026  06:38:16 +0000 (0:00:00.780)       0:00:52.117 *******
2026-08-22 06:38:17.875384 | mon1 | changed: [mon1]
2026-08-22 06:38:17.875462 | mon1 |
2026-08-22 06:38:17.875475 | mon1 | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-08-22 06:38:17.875488 | mon1 | Saturday 22 August 2026  06:38:17 +0000 (0:00:01.821)       0:00:53.938 *******
2026-08-22 06:38:18.116461 | mon1 | ok: [mon1]
2026-08-22 06:38:18.116517 | mon1 |
2026-08-22 06:38:18.116532 | mon1 | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-08-22 06:38:18.116545 | mon1 | Saturday 22 August 2026  06:38:18 +0000 (0:00:00.241)       0:00:54.179 *******
2026-08-22 06:38:18.614283 | mon1 | changed: [mon1]
2026-08-22 06:38:18.614345 | mon1 |
2026-08-22 06:38:18.614359 | mon1 | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-08-22 06:38:18.614372 | mon1 | Saturday 22 August 2026  06:38:18 +0000 (0:00:00.497)       0:00:54.677 *******
2026-08-22 06:38:18.975381 | mon1 | changed: [mon1]
2026-08-22 06:38:18.975464 | mon1 |
2026-08-22 06:38:18.975491 | mon1 | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-08-22 06:38:18.975504 | mon1 | Saturday 22 August 2026  06:38:18 +0000 (0:00:00.361)       0:00:55.038 *******
2026-08-22 06:38:19.101909 | mon1 | ok: [mon1]
2026-08-22 06:38:19.101961 | mon1 |
2026-08-22 06:38:19.102432 | mon1 | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-08-22 06:38:19.102483 | mon1 | Saturday 22 August 2026  06:38:19 +0000 (0:00:00.127)       0:00:55.165 *******
2026-08-22 06:38:19.379227 | mon1 | ok: [mon1] => (item=mon1)
2026-08-22 06:38:19.379289 | mon1 |
2026-08-22 06:38:19.379371 | mon1 | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-08-22 06:38:19.379486 | mon1 | Saturday 22 August 2026  06:38:19 +0000 (0:00:00.277)       0:00:55.443 *******
2026-08-22 06:38:19.433111 | mon1 | ok: [mon1]
2026-08-22 06:38:19.433157 | mon1 |
2026-08-22 06:38:19.433171 | mon1 | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-08-22 06:38:19.433184 | mon1 | Saturday 22 August 2026  06:38:19 +0000 (0:00:00.053)       0:00:55.496 *******
2026-08-22 06:38:19.516798 | mon1 | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for mon1
2026-08-22 06:38:19.516856 | mon1 |
2026-08-22 06:38:19.516955 | mon1 | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-08-22 06:38:19.517281 | mon1 | Saturday 22 August 2026  06:38:19 +0000 (0:00:00.084)       0:00:55.580 *******
2026-08-22 06:38:19.845398 | mon1 | changed: [mon1]
2026-08-22 06:38:19.845768 | mon1 |
2026-08-22 06:38:19.846157 | mon1 | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-08-22 06:38:19.846482 | mon1 | Saturday 22 August 2026  06:38:19 +0000 (0:00:00.328)       0:00:55.909 *******
2026-08-22 06:38:20.621352 | mon1 | changed: [mon1] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-08-22 06:38:20.621458 | mon1 | changed: [mon1] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-08-22 06:38:20.621580 | mon1 | changed: [mon1] => (item={'option': 'mon data avail warn', 'section': 'mon', 'value': 10})
2026-08-22 06:38:20.621685 | mon1 |
2026-08-22 06:38:20.621832 | mon1 | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-08-22 06:38:20.621936 | mon1 | Saturday 22 August 2026  06:38:20 +0000 (0:00:00.775)       0:00:56.684 *******
2026-08-22 06:39:46.738973 | mon1 | ok: [mon1]
2026-08-22 06:39:46.741778 | mon1 |
2026-08-22 06:39:46.741804 | mon1 | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-08-22 06:39:46.741813 | mon1 | Saturday 22 August 2026  06:39:46 +0000 (0:01:26.119)       0:02:22.803 *******
2026-08-22 06:39:46.992465 | mon1 | changed: [mon1]
2026-08-22 06:39:46.992498 | mon1 |
2026-08-22 06:39:46.992504 | mon1 | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-08-22 06:39:46.992510 | mon1 | Saturday 22 August 2026  06:39:46 +0000 (0:00:00.251)       0:02:23.054 *******
2026-08-22 06:39:47.029348 | mon1 | ok: [mon1]
2026-08-22 06:39:47.029364 | mon1 |
2026-08-22 06:39:47.029371 | mon1 | TASK [Install Ceph host] *******************************************************
2026-08-22 06:39:47.029376 | mon1 | Saturday 22 August 2026  06:39:47 +0000 (0:00:00.039)       0:02:23.094 *******
2026-08-22 06:39:47.098206 | mon1 | included: vexxhost.ceph.cephadm_host for mon1
2026-08-22 06:39:47.098355 | mon1 |
2026-08-22 06:39:47.098512 | mon1 | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-08-22 06:39:47.099893 | mon1 | Saturday 22 August 2026  06:39:47 +0000 (0:00:00.069)       0:02:23.163 *******
2026-08-22 06:39:48.916762 | mon1 | ok: [mon1]
2026-08-22 06:39:48.916819 | mon1 |
2026-08-22 06:39:48.916833 | mon1 | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-08-22 06:39:48.916846 | mon1 | Saturday 22 August 2026  06:39:48 +0000 (0:00:01.817)       0:02:24.981 *******
2026-08-22 06:39:48.975974 | mon1 | ok: [mon1] => (item=mon1)
2026-08-22 06:39:48.976083 | mon1 |
2026-08-22 06:39:48.976194 | mon1 | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-08-22 06:39:48.976304 | mon1 | Saturday 22 August 2026  06:39:48 +0000 (0:00:00.059)       0:02:25.040 *******
2026-08-22 06:39:49.393913 | mon1 | ok: [mon1]
2026-08-22 06:39:49.393969 | mon1 |
2026-08-22 06:39:49.393984 | mon1 | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-08-22 06:39:49.393998 | mon1 | Saturday 22 August 2026  06:39:49 +0000 (0:00:00.416)       0:02:25.457 *******
2026-08-22 06:39:51.995796 | mon1 | ok: [mon1]
2026-08-22 06:39:51.995859 | mon1 |
2026-08-22 06:39:51.996035 | mon1 | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-08-22 06:39:51.996384 | mon1 | Saturday 22 August 2026  06:39:51 +0000 (0:00:02.601)       0:02:28.059 *******
2026-08-22 06:40:35.858913 | mon1 | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster)
2026-08-22 06:40:35.859370 | mon1 | fatal: [mon1]: FAILED! => {"changed": false, "module_stderr": "Traceback (most recent call last):\n  File \"<stdin>\", line 107, in <module>\n  File \"<stdin>\", line 99, in _ansiballz_main\n  File \"<stdin>\", line 47, in invoke_module\n  File \"<frozen runpy>\", line 226, in run_module\n  File \"<frozen runpy>\", line 98, in _run_module_code\n  File \"<frozen runpy>\", line 88, in _run_code\n  File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_c7te3yq6/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 202, in <module>\n  File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_c7te3yq6/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 198, in main\n  File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_c7te3yq6/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 177, in run_module\n  File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_c7te3yq6/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 120, in apply_spec\nRuntimeError: Inferring config /var/lib/ceph/1dff0e0f-3c44-48da-81cd-4f3c6e8722b2/mon.mon1/config\n2026-08-22T06:40:35.692+0000 793e8b2a4640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAHRIlqzXsoKyAAoMhGyaoX6/f0bHX3NmhR74RmCynGvnogsxZ0uJod2ik=: Malformed input [buffer:3]\n2026-08-22T06:40:35.692+0000 793e8b2a4640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-22T06:40:35.693+0000 793e8b2a4640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAHRIlqzXsoKyAAoMhGyaoX6/f0bHX3NmhR74RmCynGvnogsxZ0uJod2ik=: Malformed input [buffer:3]\n2026-08-22T06:40:35.693+0000 793e8b2a4640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-22T06:40:35.693+0000 793e8b2a4640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAHRIlqzXsoKyAAoMhGyaoX6/f0bHX3NmhR74RmCynGvnogsxZ0uJod2ik=: Malformed input [buffer:3]\n2026-08-22T06:40:35.693+0000 793e8b2a4640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-22T06:40:35.693+0000 793e8b2a4640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE: No start of json char found\nSee stdout/stderr for the exact error", "rc": 1}
2026-08-22 06:40:35.862297 | mon1 |
2026-08-22 06:40:35.862536 | mon1 | PLAY RECAP *********************************************************************
2026-08-22 06:40:35.862813 | mon1 | mon1                       : ok=51   changed=26   unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2026-08-22 06:40:35.863809 | mon1 |
2026-08-22 06:40:35.863863 | mon1 |
2026-08-22 06:40:35.863874 | mon1 | TASKS RECAP ********************************************************************
2026-08-22 06:40:35.863885 | mon1 | Saturday 22 August 2026  06:40:35 +0000 (0:00:43.867)       0:03:11.927 *******
2026-08-22 06:40:35.863894 | mon1 | ===============================================================================
2026-08-22 06:40:35.863903 | mon1 | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 86.12s
2026-08-22 06:40:35.863912 | mon1 | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 43.87s
2026-08-22 06:40:35.863928 | mon1 | vexxhost.ceph.cephadm : Install packages ------------------------------- 17.40s
2026-08-22 06:40:35.863940 | mon1 | vexxhost.containers.containerd : Install AppArmor packages -------------- 8.58s
2026-08-22 06:40:35.865667 | mon1 | vexxhost.containers.download_artifact : Extract archive ----------------- 4.56s
2026-08-22 06:40:35.865685 | mon1 | vexxhost.containers.download_artifact : Extract archive ----------------- 3.16s
2026-08-22 06:40:35.865689 | mon1 | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.60s
2026-08-22 06:40:35.865694 | mon1 | vexxhost.containers.containerd : Reload systemd ------------------------- 2.00s
2026-08-22 06:40:35.865698 | mon1 | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 1.82s
2026-08-22 06:40:35.865702 | mon1 | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.82s
2026-08-22 06:40:35.865736 | mon1 | vexxhost.containers.docker : Restart docker ----------------------------- 1.12s
2026-08-22 06:40:35.865743 | mon1 | vexxhost.containers.containerd : Create folders for configuration ------- 1.09s
2026-08-22 06:40:35.865746 | mon1 | Gathering Facts --------------------------------------------------------- 1.05s
2026-08-22 06:40:35.865750 | mon1 | vexxhost.containers.package : Update state for tar ---------------------- 1.00s
2026-08-22 06:40:35.865754 | mon1 | vexxhost.containers.download_artifact : Download item ------------------- 0.93s
2026-08-22 06:40:35.865758 | mon1 | vexxhost.containers.docker : Install AppArmor packages ------------------ 0.89s
2026-08-22 06:40:35.865761 | mon1 | vexxhost.containers.download_artifact : Download item ------------------- 0.81s
2026-08-22 06:40:35.865765 | mon1 | vexxhost.ceph.cephadm : Ensure services are started --------------------- 0.78s
2026-08-22 06:40:35.865769 | mon1 | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.78s
2026-08-22 06:40:35.865773 | mon1 | vexxhost.containers.containerd : Create containerd config file ---------- 0.75s
2026-08-22 06:40:35.947894 | mon1 | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.kre9.default/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/inventory.yaml /home/zuul/src/github.com/vexxhost/ansible-collection-ceph/extensions/molecule/default/converge.yml
2026-08-22 06:40:35.947949 | mon1 | ERROR    [default > converge] Executed: Failed
2026-08-22 06:40:35.947960 | mon1 | ERROR    Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.kre9.default/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/inventory.yaml /home/zuul/src/github.com/vexxhost/ansible-collection-ceph/extensions/molecule/default/converge.yml
2026-08-22 06:40:46.503857 | mon1 | ERROR
2026-08-22 06:40:46.504098 | mon1 | {
2026-08-22 06:40:46.504128 | mon1 |   "delta": "0:04:09.325414",
2026-08-22 06:40:46.504150 | mon1 |   "end": "2026-08-22 06:40:36.012317",
2026-08-22 06:40:46.504170 | mon1 |   "msg": "non-zero return code",
2026-08-22 06:40:46.504194 | mon1 |   "rc": 2,
2026-08-22 06:40:46.504212 | mon1 |   "start": "2026-08-22 06:36:26.686903"
2026-08-22 06:40:46.504231 | mon1 | }
failure
2026-08-22 06:40:46.509409 | 
2026-08-22 06:40:46.509465 | PLAY RECAP
2026-08-22 06:40:46.509514 | mon1 | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-08-22 06:40:46.509536 | 
2026-08-22 06:40:46.683240 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-22 06:40:46.688700 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-22 06:40:47.282705 | 
2026-08-22 06:40:47.282875 | PLAY [all]
2026-08-22 06:40:47.296518 | 
2026-08-22 06:40:47.296660 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-22 06:40:47.351193 | mon1 | ok
2026-08-22 06:40:47.357198 | osd1 | ok
2026-08-22 06:40:47.362134 | 
2026-08-22 06:40:47.362221 | TASK [fetch-output : Set log path for single node]
2026-08-22 06:40:47.388648 | mon1 | skipping: Conditional result was False
2026-08-22 06:40:47.400120 | osd1 | skipping: Conditional result was False
2026-08-22 06:40:47.413284 | 
2026-08-22 06:40:47.413438 | LOOP [fetch-output : Ensure local output dirs]
2026-08-22 06:40:47.811230 | mon1 -> localhost | changed: "/var/lib/zuul/builds/a67ec53b83ec46999f94223edf848e02/work/logs/mon1"
2026-08-22 06:40:47.824338 | osd1 -> localhost | changed: "/var/lib/zuul/builds/a67ec53b83ec46999f94223edf848e02/work/logs/osd1"
2026-08-22 06:40:48.016003 | mon1 -> localhost | changed: "/var/lib/zuul/builds/a67ec53b83ec46999f94223edf848e02/work/artifacts"
2026-08-22 06:40:48.028813 | osd1 -> localhost | ok: "/var/lib/zuul/builds/a67ec53b83ec46999f94223edf848e02/work/artifacts"
2026-08-22 06:40:48.224282 | mon1 -> localhost | changed: "/var/lib/zuul/builds/a67ec53b83ec46999f94223edf848e02/work/docs"
2026-08-22 06:40:48.254711 | osd1 -> localhost | ok: "/var/lib/zuul/builds/a67ec53b83ec46999f94223edf848e02/work/docs"
2026-08-22 06:40:48.254983 | osd1 -> localhost | changed: All items complete
2026-08-22 06:40:48.255043 | 
2026-08-22 06:40:48.270316 | 
2026-08-22 06:40:48.270475 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-22 06:40:48.932431 | osd1 | changed: .d..t...... ./
2026-08-22 06:40:48.938224 | mon1 | changed: .d..t...... ./
2026-08-22 06:40:49.382305 | osd1 | changed: .d..t...... ./
2026-08-22 06:40:49.390457 | mon1 | changed: .d..t...... ./
2026-08-22 06:40:49.845266 | osd1 | changed: .d..t...... ./
2026-08-22 06:40:49.863172 | mon1 | ok
2026-08-22 06:40:49.863248 | mon1 | changed: All items complete
2026-08-22 06:40:49.863282 | 
2026-08-22 06:40:49.892839 | 
2026-08-22 06:40:49.893044 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-22 06:40:50.313585 | mon1 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007366
2026-08-22 06:40:50.542806 | mon1 -> localhost | ok: Item: docs Runtime: 0:00:00.008518
2026-08-22 06:40:50.569593 | 
2026-08-22 06:40:50.569710 | PLAY [all]
2026-08-22 06:40:50.575985 | 
2026-08-22 06:40:50.576047 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-22 06:40:51.046781 | mon1 | changed
2026-08-22 06:40:51.241881 | osd1 | changed
2026-08-22 06:40:51.253734 | 
2026-08-22 06:40:51.253864 | PLAY RECAP
2026-08-22 06:40:51.253920 | mon1 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-22 06:40:51.253949 | osd1 | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-22 06:40:51.253967 | 
2026-08-22 06:40:51.377798 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-22 06:40:51.382862 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-22 06:40:51.951222 | 
2026-08-22 06:40:51.951392 | PLAY [localhost]
2026-08-22 06:40:51.961552 | 
2026-08-22 06:40:51.961660 | TASK [Generate Zuul manifest]
2026-08-22 06:40:51.980621 | localhost | ok
2026-08-22 06:40:51.995876 | 
2026-08-22 06:40:51.996020 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-22 06:40:52.363629 | localhost | changed
2026-08-22 06:40:52.374818 | 
2026-08-22 06:40:52.374931 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-22 06:40:52.407638 | localhost | ok
2026-08-22 06:40:52.415438 | 
2026-08-22 06:40:52.415524 | TASK [Upload logs]
2026-08-22 06:40:52.436891 | localhost | ok
2026-08-22 06:40:52.498057 | 
2026-08-22 06:40:52.498215 | TASK [Set zuul-log-path fact]
2026-08-22 06:40:52.522706 | localhost | ok
2026-08-22 06:40:52.534273 | 
2026-08-22 06:40:52.534383 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-22 06:40:52.565346 | localhost | ok
2026-08-22 06:40:52.573936 | 
2026-08-22 06:40:52.574017 | TASK [upload-logs : Create log directories]
2026-08-22 06:40:52.994568 | localhost | changed
2026-08-22 06:40:53.000547 | 
2026-08-22 06:40:53.000616 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-22 06:40:53.439844 | localhost -> localhost | ok: Runtime: 0:00:00.006453
2026-08-22 06:40:53.446392 | 
2026-08-22 06:40:53.446460 | TASK [upload-logs : Upload logs to log server]
2026-08-22 06:40:53.913077 | localhost | Output suppressed because no_log was given
2026-08-22 06:40:53.918734 | 
2026-08-22 06:40:53.918805 | LOOP [upload-logs : Compress console log and json output]
2026-08-22 06:40:54.007311 | localhost | skipping: Conditional result was False
2026-08-22 06:40:54.014417 | localhost | skipping: Conditional result was False
2026-08-22 06:40:54.025020 | 
2026-08-22 06:40:54.025159 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-22 06:40:54.114936 | localhost | skipping: Conditional result was False
2026-08-22 06:40:54.115372 | 
2026-08-22 06:40:54.118792 | localhost | skipping: Conditional result was False
2026-08-22 06:40:54.171595 | 
2026-08-22 06:40:54.171769 | LOOP [upload-logs : Upload console log and json output]