2026-08-22 06:34:01.250250 | Job console starting
2026-08-22 06:34:01.268622 | Updating git repos
2026-08-22 06:34:01.317427 | Cloning repos into workspace
2026-08-22 06:34:01.559355 | Restoring repo states
2026-08-22 06:34:01.570155 | Merging changes
2026-08-22 06:34:01.940419 | Checking out repos
2026-08-22 06:34:01.987257 | Preparing playbooks
2026-08-22 06:34:04.205353 | Running Ansible setup
2026-08-22 06:34:08.421391 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-22 06:34:09.042357 | 
2026-08-22 06:34:09.042477 | PLAY [localhost]
2026-08-22 06:34:09.050403 | 
2026-08-22 06:34:09.050477 | TASK [Gathering Facts]
2026-08-22 06:34:09.879389 | localhost | ok
2026-08-22 06:34:09.889649 | 
2026-08-22 06:34:09.889757 | TASK [Setup log path fact]
2026-08-22 06:34:09.907291 | localhost | ok
2026-08-22 06:34:09.921215 | 
2026-08-22 06:34:09.921294 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-22 06:34:09.950215 | localhost | ok
2026-08-22 06:34:09.960855 | 
2026-08-22 06:34:09.960919 | TASK [emit-job-header : Print job information]
2026-08-22 06:34:10.000443 | # Job Information
2026-08-22 06:34:10.000648 | Ansible Version: 2.16.18
2026-08-22 06:34:10.000715 | Job: ansible-collection-ceph-molecule-ha-ubuntu-jammy
2026-08-22 06:34:10.000764 | Pipeline: check
2026-08-22 06:34:10.000809 | Executor: 2d72f0692154
2026-08-22 06:34:10.000853 | Triggered by: https://github.com/vexxhost/ansible-collection-ceph/pull/116
2026-08-22 06:34:10.000903 | Event ID: 263bc0e0-9df3-11f1-9b14-5ec69431765d
2026-08-22 06:34:10.005147 | 
2026-08-22 06:34:10.005214 | LOOP [emit-job-header : Print node information]
2026-08-22 06:34:10.095908 | localhost | ok:
2026-08-22 06:34:10.096242 | localhost | # Node Information
2026-08-22 06:34:10.096309 | localhost | Inventory Hostname: mon1
2026-08-22 06:34:10.096358 | localhost | Hostname: np0000190412
2026-08-22 06:34:10.096402 | localhost | Username: zuul
2026-08-22 06:34:10.096454 | localhost | Distro: Ubuntu 22.04
2026-08-22 06:34:10.096498 | localhost | Provider: yul1
2026-08-22 06:34:10.096542 | localhost | Region: ca-ymq-1
2026-08-22 06:34:10.096583 | localhost | Label: ubuntu-jammy
2026-08-22 06:34:10.096624 | localhost | Product Name: OpenStack Nova
2026-08-22 06:34:10.096664 | localhost | Interface IP: 199.204.45.222
2026-08-22 06:34:10.135894 | localhost | ok:
2026-08-22 06:34:10.136001 | localhost | # Node Information
2026-08-22 06:34:10.136060 | localhost | Inventory Hostname: osd1
2026-08-22 06:34:10.136108 | localhost | Hostname: np0000190413
2026-08-22 06:34:10.136151 | localhost | Username: zuul
2026-08-22 06:34:10.136194 | localhost | Distro: Ubuntu 22.04
2026-08-22 06:34:10.136237 | localhost | Provider: yul1
2026-08-22 06:34:10.136279 | localhost | Region: ca-ymq-1
2026-08-22 06:34:10.136320 | localhost | Label: ubuntu-jammy
2026-08-22 06:34:10.136360 | localhost | Product Name: OpenStack Nova
2026-08-22 06:34:10.136402 | localhost | Interface IP: 199.204.45.35
2026-08-22 06:34:10.162786 | 
2026-08-22 06:34:10.218894 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-22 06:34:10.599403 | localhost -> localhost | changed
2026-08-22 06:34:10.607598 | 
2026-08-22 06:34:10.607964 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-22 06:34:11.398070 | localhost -> localhost | changed
2026-08-22 06:34:11.408234 | 
2026-08-22 06:34:11.408313 | PLAY [all]
2026-08-22 06:34:11.419596 | 
2026-08-22 06:34:11.419664 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-22 06:34:11.655954 | mon1 -> localhost | ok
2026-08-22 06:34:11.666602 | 
2026-08-22 06:34:11.666692 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-22 06:34:11.697178 | mon1 | ok
2026-08-22 06:34:11.702480 | osd1 | ok
2026-08-22 06:34:11.721981 | mon1 | included: /var/lib/zuul/builds/c7a47e51caf249758e388617fc1c0472/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-22 06:34:11.722036 | osd1 | included: /var/lib/zuul/builds/c7a47e51caf249758e388617fc1c0472/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-22 06:34:11.727658 | 
2026-08-22 06:34:11.727719 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-22 06:34:12.994588 | mon1 -> localhost | Generating public/private rsa key pair.
2026-08-22 06:34:12.994782 | mon1 -> localhost | Your identification has been saved in /var/lib/zuul/builds/c7a47e51caf249758e388617fc1c0472/work/c7a47e51caf249758e388617fc1c0472_id_rsa
2026-08-22 06:34:12.994828 | mon1 -> localhost | Your public key has been saved in /var/lib/zuul/builds/c7a47e51caf249758e388617fc1c0472/work/c7a47e51caf249758e388617fc1c0472_id_rsa.pub
2026-08-22 06:34:12.994862 | mon1 -> localhost | The key fingerprint is:
2026-08-22 06:34:12.994893 | mon1 -> localhost | SHA256:dbaDjo2CBHAP00DT3BwC+79WLlD3ZiZ6ZYe9Ulrv82k zuul-build-sshkey
2026-08-22 06:34:12.994924 | mon1 -> localhost | The key's randomart image is:
2026-08-22 06:34:12.994954 | mon1 -> localhost | +---[RSA 3072]----+
2026-08-22 06:34:12.994983 | mon1 -> localhost | |..O*.o..         |
2026-08-22 06:34:12.995013 | mon1 -> localhost | | o *+.o          |
2026-08-22 06:34:12.995041 | mon1 -> localhost | |  o .     . o    |
2026-08-22 06:34:12.995070 | mon1 -> localhost | |   o  . .. + .   |
2026-08-22 06:34:12.995099 | mon1 -> localhost | |    o. .S..oo    |
2026-08-22 06:34:12.995127 | mon1 -> localhost | |   ..o  o=O =.   |
2026-08-22 06:34:12.995155 | mon1 -> localhost | |    ..o+oBo= o   |
2026-08-22 06:34:12.995183 | mon1 -> localhost | |      +oo o . oE.|
2026-08-22 06:34:12.995210 | mon1 -> localhost | |     ..o   . .o+.|
2026-08-22 06:34:12.995236 | mon1 -> localhost | +----[SHA256]-----+
2026-08-22 06:34:12.995296 | mon1 -> localhost | ok: Runtime: 0:00:00.875340
2026-08-22 06:34:13.002163 | 
2026-08-22 06:34:13.002226 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-22 06:34:13.043199 | mon1 | ok
2026-08-22 06:34:13.051677 | osd1 | ok
2026-08-22 06:34:13.069414 | mon1 | included: /var/lib/zuul/builds/c7a47e51caf249758e388617fc1c0472/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-22 06:34:13.069488 | osd1 | included: /var/lib/zuul/builds/c7a47e51caf249758e388617fc1c0472/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-22 06:34:13.086159 | 
2026-08-22 06:34:13.086246 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-22 06:34:13.108100 | mon1 | skipping: Conditional result was False
2026-08-22 06:34:13.118934 | osd1 | skipping: Conditional result was False
2026-08-22 06:34:13.129265 | 
2026-08-22 06:34:13.129357 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-22 06:34:13.558783 | mon1 | changed
2026-08-22 06:34:13.565479 | osd1 | changed
2026-08-22 06:34:13.570385 | 
2026-08-22 06:34:13.570456 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-22 06:34:13.761660 | mon1 | ok
2026-08-22 06:34:13.768205 | osd1 | ok
2026-08-22 06:34:13.773254 | 
2026-08-22 06:34:13.773327 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-22 06:34:14.222448 | mon1 | changed
2026-08-22 06:34:14.240750 | osd1 | changed
2026-08-22 06:34:14.247613 | 
2026-08-22 06:34:14.247673 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-22 06:34:14.741544 | mon1 | changed
2026-08-22 06:34:14.745783 | osd1 | changed
2026-08-22 06:34:14.750303 | 
2026-08-22 06:34:14.750363 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-22 06:34:14.772229 | mon1 | skipping: Conditional result was False
2026-08-22 06:34:14.783140 | osd1 | skipping: Conditional result was False
2026-08-22 06:34:14.790478 | 
2026-08-22 06:34:14.790568 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-22 06:34:15.085302 | mon1 -> localhost | changed
2026-08-22 06:34:15.106830 | 
2026-08-22 06:34:15.106920 | TASK [add-build-sshkey : Add back temp key]
2026-08-22 06:34:15.370959 | mon1 -> localhost | Identity added: /var/lib/zuul/builds/c7a47e51caf249758e388617fc1c0472/work/c7a47e51caf249758e388617fc1c0472_id_rsa (zuul-build-sshkey)
2026-08-22 06:34:15.371336 | mon1 -> localhost | ok: Runtime: 0:00:00.014485
2026-08-22 06:34:15.377238 | 
2026-08-22 06:34:15.377303 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-22 06:34:15.624681 | mon1 | ok
2026-08-22 06:34:15.629970 | osd1 | ok
2026-08-22 06:34:15.634231 | 
2026-08-22 06:34:15.634295 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-22 06:34:15.661781 | mon1 | skipping: Conditional result was False
2026-08-22 06:34:15.683389 | osd1 | skipping: Conditional result was False
2026-08-22 06:34:15.706145 | 
2026-08-22 06:34:15.706212 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-22 06:34:15.983147 | mon1 | ok
2026-08-22 06:34:16.011237 | osd1 | ok
2026-08-22 06:34:16.019054 | 
2026-08-22 06:34:16.019126 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-22 06:34:16.692696 | mon1 | Output suppressed because no_log was given
2026-08-22 06:34:16.739383 | osd1 | Output suppressed because no_log was given
2026-08-22 06:34:16.755089 | 
2026-08-22 06:34:16.755250 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-22 06:34:16.937240 | mon1 | ok: "logs"
2026-08-22 06:34:16.937523 | mon1 | ok: All items complete
2026-08-22 06:34:16.937565 | 
2026-08-22 06:34:16.962031 | osd1 | ok: "logs"
2026-08-22 06:34:17.103060 | mon1 | ok: "artifacts"
2026-08-22 06:34:17.117301 | osd1 | ok: "artifacts"
2026-08-22 06:34:17.257297 | mon1 | ok: "docs"
2026-08-22 06:34:17.275373 | osd1 | ok: "docs"
2026-08-22 06:34:17.293613 | 
2026-08-22 06:34:17.293701 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-22 06:34:17.470533 | mon1 | changed: "logs"
2026-08-22 06:34:17.470786 | mon1 | ok: All items complete
2026-08-22 06:34:17.470818 | 
2026-08-22 06:34:17.481087 | osd1 | changed: "logs"
2026-08-22 06:34:17.623257 | mon1 | changed: "artifacts"
2026-08-22 06:34:17.641072 | osd1 | changed: "artifacts"
2026-08-22 06:34:17.799449 | mon1 | changed: "docs"
2026-08-22 06:34:17.806146 | osd1 | changed: "docs"
2026-08-22 06:34:17.826286 | 
2026-08-22 06:34:17.826390 | PLAY RECAP
2026-08-22 06:34:17.826438 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 06:34:17.826470 | mon1 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-22 06:34:17.826498 | osd1 | ok: 11 changed: 5 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-22 06:34:17.826518 | 
2026-08-22 06:34:17.961562 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-22 06:34:17.966424 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-22 06:34:18.555210 | 
2026-08-22 06:34:18.555321 | PLAY [all]
2026-08-22 06:34:18.566311 | 
2026-08-22 06:34:18.566387 | TASK [setup-uv : Extract archive]
2026-08-22 06:34:20.755244 | mon1 | changed
2026-08-22 06:34:20.815841 | osd1 | changed
2026-08-22 06:34:20.822613 | 
2026-08-22 06:34:20.822690 | TASK [setup-uv : Print version]
2026-08-22 06:34:21.170987 | mon1 | uv 0.8.13
2026-08-22 06:34:21.174045 | osd1 | uv 0.8.13
2026-08-22 06:34:21.380193 | mon1 | ok: Runtime: 0:00:00.011024
2026-08-22 06:34:21.387814 | osd1 | ok: Runtime: 0:00:00.010365
2026-08-22 06:34:21.400117 | 
2026-08-22 06:34:21.400181 | TASK [Install Ansible collection siblings]
2026-08-22 06:34:21.787278 | mon1 | ok:
2026-08-22 06:34:21.787476 | mon1 | Skipping community.general: no checked-out sibling
2026-08-22 06:34:21.787506 | mon1 | Skipping ansible.utils: no checked-out sibling
2026-08-22 06:34:21.787529 | mon1 | Skipping vexxhost.containers: no checked-out sibling
2026-08-22 06:34:21.789344 | 
2026-08-22 06:34:21.789435 | PLAY RECAP
2026-08-22 06:34:21.789517 | mon1 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 06:34:21.789591 | osd1 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 06:34:21.789631 | 
2026-08-22 06:34:21.904055 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-22 06:34:21.906697 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-22 06:34:22.487122 | 
2026-08-22 06:34:22.487238 | PLAY [all]
2026-08-22 06:34:22.499602 | 
2026-08-22 06:34:22.499754 | LOOP [Check inventory directories]
2026-08-22 06:34:22.830711 | mon1 | ok: "src/github.com/vexxhost/ansible-collection-ceph"
2026-08-22 06:34:22.980560 | mon1 | ok: "src/github.com/vexxhost/ansible-collection-ceph/molecule/default/inventory"
2026-08-22 06:34:23.135738 | mon1 | ok: "src/github.com/vexxhost/ansible-collection-ceph/extensions/molecule/default/inventory"
2026-08-22 06:34:23.155593 | 
2026-08-22 06:34:23.155750 | LOOP [Copy inventory file for Zuul]
2026-08-22 06:34:23.936305 | mon1 | changed:
2026-08-22 06:34:23.936549 | mon1 | {
2026-08-22 06:34:23.936590 | mon1 |   "ansible_loop_var": "item",
2026-08-22 06:34:23.936622 | mon1 |   "changed": false,
2026-08-22 06:34:23.936650 | mon1 |   "failed": false,
2026-08-22 06:34:23.936684 | mon1 |   "invocation": {
2026-08-22 06:34:23.936710 | mon1 |     "module_args": {
2026-08-22 06:34:23.936736 | mon1 |       "checksum_algorithm": "sha1",
2026-08-22 06:34:23.936761 | mon1 |       "follow": true,
2026-08-22 06:34:23.936786 | mon1 |       "get_attributes": true,
2026-08-22 06:34:23.936812 | mon1 |       "get_checksum": true,
2026-08-22 06:34:23.936837 | mon1 |       "get_mime": true,
2026-08-22 06:34:23.936861 | mon1 |       "path": "src/github.com/vexxhost/ansible-collection-ceph"
2026-08-22 06:34:23.936886 | mon1 |     }
2026-08-22 06:34:23.936910 | mon1 |   },
2026-08-22 06:34:23.936947 | mon1 |   "item": "src/github.com/vexxhost/ansible-collection-ceph",
2026-08-22 06:34:23.937055 | mon1 |   "stat": {
2026-08-22 06:34:23.937082 | mon1 |     "atime": 1787380456.5717018,
2026-08-22 06:34:23.937108 | mon1 |     "attr_flags": "e",
2026-08-22 06:34:23.937133 | mon1 |     "attributes": [
2026-08-22 06:34:23.937157 | mon1 |       "extents"
2026-08-22 06:34:23.937181 | mon1 |     ],
2026-08-22 06:34:23.937205 | mon1 |     "block_size": 4096,
2026-08-22 06:34:23.937228 | mon1 |     "blocks": 8,
2026-08-22 06:34:23.937252 | mon1 |     "charset": "unknown",
2026-08-22 06:34:23.937278 | mon1 |     "ctime": 1787380456.5797033,
2026-08-22 06:34:23.937303 | mon1 |     "dev": 64513,
2026-08-22 06:34:23.937327 | mon1 |     "device_type": 0,
2026-08-22 06:34:23.937351 | mon1 |     "executable": true,
2026-08-22 06:34:23.937374 | mon1 |     "exists": true,
2026-08-22 06:34:23.937398 | mon1 |     "gid": 1000,
2026-08-22 06:34:23.937421 | mon1 |     "gr_name": "zuul",
2026-08-22 06:34:23.937445 | mon1 |     "inode": 1042461,
2026-08-22 06:34:23.937468 | mon1 |     "isblk": false,
2026-08-22 06:34:23.937492 | mon1 |     "ischr": false,
2026-08-22 06:34:23.937515 | mon1 |     "isdir": true,
2026-08-22 06:34:23.937539 | mon1 |     "isfifo": false,
2026-08-22 06:34:23.937562 | mon1 |     "isgid": false,
2026-08-22 06:34:23.937585 | mon1 |     "islnk": false,
2026-08-22 06:34:23.937609 | mon1 |     "isreg": false,
2026-08-22 06:34:23.937633 | mon1 |     "issock": false,
2026-08-22 06:34:23.937656 | mon1 |     "isuid": false,
2026-08-22 06:34:23.937680 | mon1 |     "mimetype": "unknown",
2026-08-22 06:34:23.937704 | mon1 |     "mode": "0755",
2026-08-22 06:34:23.937728 | mon1 |     "mtime": 1787380441.5956702,
2026-08-22 06:34:23.937751 | mon1 |     "nlink": 10,
2026-08-22 06:34:23.937774 | mon1 |     "path": "src/github.com/vexxhost/ansible-collection-ceph",
2026-08-22 06:34:23.937798 | mon1 |     "pw_name": "zuul",
2026-08-22 06:34:23.937823 | mon1 |     "readable": true,
2026-08-22 06:34:23.937847 | mon1 |     "rgrp": true,
2026-08-22 06:34:23.937870 | mon1 |     "roth": true,
2026-08-22 06:34:23.937894 | mon1 |     "rusr": true,
2026-08-22 06:34:23.937918 | mon1 |     "size": 4096,
2026-08-22 06:34:23.937954 | mon1 |     "uid": 1000,
2026-08-22 06:34:23.937980 | mon1 |     "version": "3270545428",
2026-08-22 06:34:23.938005 | mon1 |     "wgrp": false,
2026-08-22 06:34:23.938031 | mon1 |     "woth": false,
2026-08-22 06:34:23.938056 | mon1 |     "writeable": true,
2026-08-22 06:34:23.938080 | mon1 |     "wusr": true,
2026-08-22 06:34:23.938104 | mon1 |     "xgrp": true,
2026-08-22 06:34:23.938128 | mon1 |     "xoth": true,
2026-08-22 06:34:23.938152 | mon1 |     "xusr": true
2026-08-22 06:34:23.938175 | mon1 |   }
2026-08-22 06:34:23.938200 | mon1 | }
2026-08-22 06:34:23.938244 | mon1 | ok: All items complete
2026-08-22 06:34:23.938273 | 
2026-08-22 06:34:23.939908 | mon1 | skipping: Conditional result was False
2026-08-22 06:34:23.940722 | mon1 | skipping: Conditional result was False
2026-08-22 06:34:23.955552 | 
2026-08-22 06:34:23.955709 | LOOP [Switch "ansible_host" to private IP]
2026-08-22 06:34:24.291853 | mon1 | changed: 2 replacements made
2026-08-22 06:34:24.293564 | mon1 | skipping: Conditional result was False
2026-08-22 06:34:24.295532 | mon1 | skipping: Conditional result was False
2026-08-22 06:34:24.317319 | 
2026-08-22 06:34:24.317450 | TASK [Run Molecule scenario]
2026-08-22 06:34:24.916788 | mon1 | Downloading cpython-3.13.7-linux-x86_64-gnu (download) (30.9MiB)
2026-08-22 06:34:25.646941 | mon1 |  Downloading cpython-3.13.7-linux-x86_64-gnu (download)
2026-08-22 06:34:25.821686 | mon1 | Using CPython 3.13.7
2026-08-22 06:34:25.821790 | mon1 | Creating virtual environment at: .venv
2026-08-22 06:34:26.056896 | mon1 | Downloading netaddr (2.2MiB)
2026-08-22 06:34:26.056988 | mon1 | Downloading pygments (1.2MiB)
2026-08-22 06:34:26.057161 | mon1 | Downloading cryptography (4.3MiB)
2026-08-22 06:34:26.096574 | mon1 | Downloading ansible-core (2.1MiB)
2026-08-22 06:34:26.234073 | mon1 |  Downloading netaddr
2026-08-22 06:34:26.244084 | mon1 |  Downloading cryptography
2026-08-22 06:34:26.289134 | mon1 |  Downloading pygments
2026-08-22 06:34:26.414435 | mon1 |  Downloading ansible-core
2026-08-22 06:34:26.444256 | mon1 | Installed 36 packages in 29ms
2026-08-22 06:34:27.572632 | mon1 | INFO     Collection 'vexxhost.ceph' detected.
2026-08-22 06:34:27.572733 | mon1 | INFO     Scenarios will be used from 'extensions/molecule'
2026-08-22 06:34:28.216719 | mon1 | INFO     [default > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-08-22 06:34:28.216831 | mon1 | INFO     [default > prerun] Performing prerun with role_name_check=0...
2026-08-22 06:34:46.328990 | mon1 | INFO     [default > dependency] Executing
2026-08-22 06:34:46.329092 | mon1 | WARNING  [default > dependency] Missing roles requirements file: requirements.yml
2026-08-22 06:34:46.329270 | mon1 | WARNING  [default > dependency] Missing collections requirements file: collections.yml
2026-08-22 06:34:46.329382 | mon1 | WARNING  [default > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-08-22 06:34:46.334526 | mon1 | INFO     [default > cleanup] Executing
2026-08-22 06:34:46.334814 | mon1 | WARNING  [default > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-22 06:34:46.339936 | mon1 | INFO     [default > destroy] Executing
2026-08-22 06:34:46.339996 | mon1 | WARNING  [default > destroy] Skipping, '--destroy=never' requested.
2026-08-22 06:34:46.340080 | mon1 | INFO     [default > destroy] Executed: Successful
2026-08-22 06:34:46.344951 | mon1 | INFO     [default > syntax] Executing
2026-08-22 06:34:47.326585 | mon1 |
2026-08-22 06:34:47.326883 | mon1 | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-ceph/extensions/molecule/default/converge.yml
2026-08-22 06:34:47.383288 | mon1 | INFO     [default > syntax] Executed: Successful
2026-08-22 06:34:47.393411 | mon1 | INFO     [default > create] Executing
2026-08-22 06:34:47.394713 | mon1 | WARNING  [default > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-22 06:34:47.399823 | mon1 | INFO     [default > prepare] Executing
2026-08-22 06:34:48.057023 | mon1 |
2026-08-22 06:34:48.057377 | mon1 | PLAY [Prepare] *****************************************************************
2026-08-22 06:34:48.057620 | mon1 |
2026-08-22 06:34:48.057937 | mon1 | TASK [Gathering Facts] *********************************************************
2026-08-22 06:34:48.058206 | mon1 | Saturday 22 August 2026  06:34:48 +0000 (0:00:00.030)       0:00:00.030 *******
2026-08-22 06:34:49.174088 | mon1 | [WARNING]: Platform linux on host mon1 is using the discovered Python
2026-08-22 06:34:49.174300 | mon1 | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-22 06:34:49.174491 | mon1 | interpreter could change the meaning of that path. See
2026-08-22 06:34:49.174722 | mon1 | https://docs.ansible.com/ansible-
2026-08-22 06:34:49.174936 | mon1 | core/2.18/reference_appendices/interpreter_discovery.html for more information.
2026-08-22 06:34:49.201374 | mon1 | [WARNING]: Platform linux on host osd1 is using the discovered Python
2026-08-22 06:34:49.201464 | mon1 | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-22 06:34:49.201596 | mon1 | interpreter could change the meaning of that path. See
2026-08-22 06:34:49.201725 | mon1 | https://docs.ansible.com/ansible-
2026-08-22 06:34:49.201857 | mon1 | core/2.18/reference_appendices/interpreter_discovery.html for more information.
2026-08-22 06:34:49.209247 | mon1 | ok: [mon1]
2026-08-22 06:34:49.209476 | mon1 | ok: [osd1]
2026-08-22 06:34:49.209663 | mon1 |
2026-08-22 06:34:49.209865 | mon1 | TASK [Configure short hostname] ************************************************
2026-08-22 06:34:49.210065 | mon1 | Saturday 22 August 2026  06:34:49 +0000 (0:00:01.152)       0:00:01.183 *******
2026-08-22 06:34:49.916337 | mon1 | changed: [mon1]
2026-08-22 06:34:49.916468 | mon1 | changed: [osd1]
2026-08-22 06:34:49.916703 | mon1 |
2026-08-22 06:34:49.916929 | mon1 | TASK [Ensure hostname inside hosts file] ***************************************
2026-08-22 06:34:49.917170 | mon1 | Saturday 22 August 2026  06:34:49 +0000 (0:00:00.706)       0:00:01.889 *******
2026-08-22 06:34:50.191069 | mon1 | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-08-22 06:34:50.191281 | mon1 | with a mode of 0700, this may cause issues when running as another user. To
2026-08-22 06:34:50.191515 | mon1 | avoid this, create the remote_tmp dir with the correct permissions manually
2026-08-22 06:34:50.213684 | mon1 | changed: [mon1]
2026-08-22 06:34:50.213892 | mon1 | changed: [osd1]
2026-08-22 06:34:50.214100 | mon1 |
2026-08-22 06:34:50.214364 | mon1 | TASK [Update apt cache] ********************************************************
2026-08-22 06:34:50.214594 | mon1 | Saturday 22 August 2026  06:34:50 +0000 (0:00:00.297)       0:00:02.187 *******
2026-08-22 06:35:00.931608 | mon1 | changed: [mon1]
2026-08-22 06:35:00.931780 | mon1 | changed: [osd1]
2026-08-22 06:35:00.931987 | mon1 |
2026-08-22 06:35:00.932246 | mon1 | PLAY [Create devices for Ceph] *************************************************
2026-08-22 06:35:00.932471 | mon1 |
2026-08-22 06:35:00.932729 | mon1 | TASK [Gathering Facts] *********************************************************
2026-08-22 06:35:00.932948 | mon1 | Saturday 22 August 2026  06:35:00 +0000 (0:00:10.718)       0:00:12.906 *******
2026-08-22 06:35:01.676682 | mon1 | ok: [osd1]
2026-08-22 06:35:01.676836 | mon1 |
2026-08-22 06:35:01.677069 | mon1 | TASK [Install depedencies] *****************************************************
2026-08-22 06:35:01.677301 | mon1 | Saturday 22 August 2026  06:35:01 +0000 (0:00:00.744)       0:00:13.650 *******
2026-08-22 06:35:21.758796 | mon1 | changed: [osd1]
2026-08-22 06:35:21.758919 | mon1 |
2026-08-22 06:35:21.759424 | mon1 | TASK [Start up service] ********************************************************
2026-08-22 06:35:21.759687 | mon1 | Saturday 22 August 2026  06:35:21 +0000 (0:00:20.082)       0:00:33.733 *******
2026-08-22 06:35:22.330293 | mon1 | ok: [osd1]
2026-08-22 06:35:22.330466 | mon1 |
2026-08-22 06:35:22.330705 | mon1 | TASK [Generate lvm.conf] *******************************************************
2026-08-22 06:35:22.330933 | mon1 | Saturday 22 August 2026  06:35:22 +0000 (0:00:00.571)       0:00:34.304 *******
2026-08-22 06:35:22.642099 | mon1 | ok: [osd1]
2026-08-22 06:35:22.642277 | mon1 |
2026-08-22 06:35:22.642522 | mon1 | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-08-22 06:35:22.642735 | mon1 | Saturday 22 August 2026  06:35:22 +0000 (0:00:00.311)       0:00:34.616 *******
2026-08-22 06:35:23.308690 | mon1 | changed: [osd1]
2026-08-22 06:35:23.308900 | mon1 |
2026-08-22 06:35:23.309137 | mon1 | TASK [Get list of all loopback devices] ****************************************
2026-08-22 06:35:23.309356 | mon1 | Saturday 22 August 2026  06:35:23 +0000 (0:00:00.666)       0:00:35.283 *******
2026-08-22 06:35:23.497800 | mon1 | ok: [osd1]
2026-08-22 06:35:23.497965 | mon1 |
2026-08-22 06:35:23.498187 | mon1 | TASK [Fail if there is any existing loopback devices] **************************
2026-08-22 06:35:23.498518 | mon1 | Saturday 22 August 2026  06:35:23 +0000 (0:00:00.188)       0:00:35.472 *******
2026-08-22 06:35:23.516169 | mon1 | skipping: [osd1]
2026-08-22 06:35:23.516398 | mon1 |
2026-08-22 06:35:23.516641 | mon1 | TASK [Create devices for Ceph] *************************************************
2026-08-22 06:35:23.516906 | mon1 | Saturday 22 August 2026  06:35:23 +0000 (0:00:00.018)       0:00:35.490 *******
2026-08-22 06:35:24.064634 | mon1 | changed: [osd1] => (item=osd0)
2026-08-22 06:35:24.064814 | mon1 | changed: [osd1] => (item=osd1)
2026-08-22 06:35:24.065032 | mon1 | changed: [osd1] => (item=osd2)
2026-08-22 06:35:24.065228 | mon1 |
2026-08-22 06:35:24.065446 | mon1 | TASK [Set permissions on loopback devices] *************************************
2026-08-22 06:35:24.065657 | mon1 | Saturday 22 August 2026  06:35:24 +0000 (0:00:00.548)       0:00:36.039 *******
2026-08-22 06:35:24.686426 | mon1 | changed: [osd1] => (item=osd0)
2026-08-22 06:35:24.686530 | mon1 | changed: [osd1] => (item=osd1)
2026-08-22 06:35:24.686660 | mon1 | changed: [osd1] => (item=osd2)
2026-08-22 06:35:24.686808 | mon1 |
2026-08-22 06:35:24.686942 | mon1 | TASK [Start loop devices] ******************************************************
2026-08-22 06:35:24.687072 | mon1 | Saturday 22 August 2026  06:35:24 +0000 (0:00:00.621)       0:00:36.660 *******
2026-08-22 06:35:25.441238 | mon1 | changed: [osd1] => (item=osd0)
2026-08-22 06:35:25.441323 | mon1 | changed: [osd1] => (item=osd1)
2026-08-22 06:35:25.441412 | mon1 | changed: [osd1] => (item=osd2)
2026-08-22 06:35:25.441543 | mon1 |
2026-08-22 06:35:25.441674 | mon1 | TASK [Create a volume group for each loop device] ******************************
2026-08-22 06:35:25.441794 | mon1 | Saturday 22 August 2026  06:35:25 +0000 (0:00:00.754)       0:00:37.415 *******
2026-08-22 06:35:28.606663 | mon1 | changed: [osd1] => (item=osd0)
2026-08-22 06:35:28.606730 | mon1 | changed: [osd1] => (item=osd1)
2026-08-22 06:35:28.606821 | mon1 | changed: [osd1] => (item=osd2)
2026-08-22 06:35:28.606911 | mon1 |
2026-08-22 06:35:28.607008 | mon1 | TASK [Create a logical volume for each loop device] ****************************
2026-08-22 06:35:28.607104 | mon1 | Saturday 22 August 2026  06:35:28 +0000 (0:00:03.165)       0:00:40.581 *******
2026-08-22 06:35:30.555486 | mon1 | changed: [osd1] => (item=ceph-osd1-osd0)
2026-08-22 06:35:30.555597 | mon1 | changed: [osd1] => (item=ceph-osd1-osd1)
2026-08-22 06:35:30.555666 | mon1 | changed: [osd1] => (item=ceph-osd1-osd2)
2026-08-22 06:35:30.556088 | mon1 |
2026-08-22 06:35:30.556102 | mon1 | PLAY RECAP *********************************************************************
2026-08-22 06:35:30.556114 | mon1 | mon1                       : ok=4    changed=3    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-08-22 06:35:30.556144 | mon1 | osd1                       : ok=15   changed=10   unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-08-22 06:35:30.556258 | mon1 |
2026-08-22 06:35:30.556367 | mon1 |
2026-08-22 06:35:30.556485 | mon1 | TASKS RECAP ********************************************************************
2026-08-22 06:35:30.556621 | mon1 | Saturday 22 August 2026  06:35:30 +0000 (0:00:01.948)       0:00:42.529 *******
2026-08-22 06:35:30.556735 | mon1 | ===============================================================================
2026-08-22 06:35:30.556849 | mon1 | Install depedencies ---------------------------------------------------- 20.08s
2026-08-22 06:35:30.556973 | mon1 | Update apt cache ------------------------------------------------------- 10.72s
2026-08-22 06:35:30.557109 | mon1 | Create a volume group for each loop device ------------------------------ 3.17s
2026-08-22 06:35:30.557223 | mon1 | Create a logical volume for each loop device ---------------------------- 1.95s
2026-08-22 06:35:30.557334 | mon1 | Gathering Facts --------------------------------------------------------- 1.15s
2026-08-22 06:35:30.557474 | mon1 | Start loop devices ------------------------------------------------------ 0.75s
2026-08-22 06:35:30.557595 | mon1 | Gathering Facts --------------------------------------------------------- 0.74s
2026-08-22 06:35:30.557717 | mon1 | Configure short hostname ------------------------------------------------ 0.71s
2026-08-22 06:35:30.557835 | mon1 | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.67s
2026-08-22 06:35:30.557974 | mon1 | Set permissions on loopback devices ------------------------------------- 0.62s
2026-08-22 06:35:30.558111 | mon1 | Start up service -------------------------------------------------------- 0.57s
2026-08-22 06:35:30.558231 | mon1 | Create devices for Ceph ------------------------------------------------- 0.55s
2026-08-22 06:35:30.558373 | mon1 | Generate lvm.conf ------------------------------------------------------- 0.31s
2026-08-22 06:35:30.558513 | mon1 | Ensure hostname inside hosts file --------------------------------------- 0.30s
2026-08-22 06:35:30.558637 | mon1 | Get list of all loopback devices ---------------------------------------- 0.19s
2026-08-22 06:35:30.558779 | mon1 | Fail if there is any existing loopback devices -------------------------- 0.02s
2026-08-22 06:35:30.653041 | mon1 | INFO     [default > prepare] Executed: Successful
2026-08-22 06:35:30.661312 | mon1 | INFO     [default > converge] Executing
2026-08-22 06:35:31.544265 | mon1 |
2026-08-22 06:35:31.544491 | mon1 | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-08-22 06:35:31.544706 | mon1 |
2026-08-22 06:35:31.544950 | mon1 | TASK [Gathering Facts] *********************************************************
2026-08-22 06:35:31.545171 | mon1 | Saturday 22 August 2026  06:35:31 +0000 (0:00:00.046)       0:00:00.046 *******
2026-08-22 06:35:32.552137 | mon1 | [WARNING]: Platform linux on host mon1 is using the discovered Python
2026-08-22 06:35:32.552362 | mon1 | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-22 06:35:32.552662 | mon1 | interpreter could change the meaning of that path. See
2026-08-22 06:35:32.552965 | mon1 | https://docs.ansible.com/ansible-
2026-08-22 06:35:32.553208 | mon1 | core/2.18/reference_appendices/interpreter_discovery.html for more information.
2026-08-22 06:35:32.562598 | mon1 | ok: [mon1]
2026-08-22 06:35:32.562711 | mon1 |
2026-08-22 06:35:32.562847 | mon1 | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-22 06:35:32.563011 | mon1 | Saturday 22 August 2026  06:35:32 +0000 (0:00:01.018)       0:00:01.065 *******
2026-08-22 06:35:32.873876 | mon1 | ok: [mon1]
2026-08-22 06:35:32.874047 | mon1 |
2026-08-22 06:35:32.874373 | mon1 | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-22 06:35:32.874507 | mon1 | Saturday 22 August 2026  06:35:32 +0000 (0:00:00.310)       0:00:01.375 *******
2026-08-22 06:35:32.914783 | mon1 | skipping: [mon1]
2026-08-22 06:35:32.914867 | mon1 |
2026-08-22 06:35:32.915155 | mon1 | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-08-22 06:35:32.915364 | mon1 | Saturday 22 August 2026  06:35:32 +0000 (0:00:00.040)       0:00:01.416 *******
2026-08-22 06:35:33.206470 | mon1 | changed: [mon1]
2026-08-22 06:35:33.206553 | mon1 |
2026-08-22 06:35:33.206795 | mon1 | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 06:35:33.207014 | mon1 | Saturday 22 August 2026  06:35:33 +0000 (0:00:00.291)       0:00:01.708 *******
2026-08-22 06:35:33.323151 | mon1 | ok: [mon1] => {
2026-08-22 06:35:33.323422 | mon1 |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-08-22 06:35:33.323624 | mon1 | }
2026-08-22 06:35:33.323814 | mon1 |
2026-08-22 06:35:33.324025 | mon1 | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 06:35:33.324231 | mon1 | Saturday 22 August 2026  06:35:33 +0000 (0:00:00.116)       0:00:01.824 *******
2026-08-22 06:35:34.391443 | mon1 | changed: [mon1]
2026-08-22 06:35:34.391686 | mon1 |
2026-08-22 06:35:34.391943 | mon1 | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 06:35:34.392246 | mon1 | Saturday 22 August 2026  06:35:34 +0000 (0:00:01.068)       0:00:02.893 *******
2026-08-22 06:35:34.433440 | mon1 | skipping: [mon1]
2026-08-22 06:35:34.433629 | mon1 |
2026-08-22 06:35:34.433850 | mon1 | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-22 06:35:34.434084 | mon1 | Saturday 22 August 2026  06:35:34 +0000 (0:00:00.042)       0:00:02.935 *******
2026-08-22 06:35:34.474510 | mon1 | skipping: [mon1]
2026-08-22 06:35:34.474685 | mon1 |
2026-08-22 06:35:34.474927 | mon1 | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-22 06:35:34.475248 | mon1 | Saturday 22 August 2026  06:35:34 +0000 (0:00:00.040)       0:00:02.976 *******
2026-08-22 06:35:34.678962 | mon1 | ok: [mon1]
2026-08-22 06:35:34.679141 | mon1 |
2026-08-22 06:35:34.679368 | mon1 | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-22 06:35:34.679623 | mon1 | Saturday 22 August 2026  06:35:34 +0000 (0:00:00.204)       0:00:03.181 *******
2026-08-22 06:35:35.938314 | mon1 | ok: [mon1]
2026-08-22 06:35:35.938416 | mon1 |
2026-08-22 06:35:35.938429 | mon1 | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 06:35:35.938580 | mon1 | Saturday 22 August 2026  06:35:35 +0000 (0:00:01.259)       0:00:04.440 *******
2026-08-22 06:35:36.019167 | mon1 | ok: [mon1] => {
2026-08-22 06:35:36.019249 | mon1 |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-08-22 06:35:36.019797 | mon1 | }
2026-08-22 06:35:36.020033 | mon1 |
2026-08-22 06:35:36.020042 | mon1 | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 06:35:36.020050 | mon1 | Saturday 22 August 2026  06:35:36 +0000 (0:00:00.080)       0:00:04.521 *******
2026-08-22 06:35:36.698061 | mon1 | changed: [mon1]
2026-08-22 06:35:36.698133 | mon1 |
2026-08-22 06:35:36.698197 | mon1 | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 06:35:36.698378 | mon1 | Saturday 22 August 2026  06:35:36 +0000 (0:00:00.678)       0:00:05.200 *******
2026-08-22 06:35:39.717040 | mon1 | changed: [mon1]
2026-08-22 06:35:39.717161 | mon1 |
2026-08-22 06:35:39.717175 | mon1 | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-08-22 06:35:39.717242 | mon1 | Saturday 22 August 2026  06:35:39 +0000 (0:00:03.019)       0:00:08.219 *******
2026-08-22 06:35:39.754191 | mon1 | skipping: [mon1]
2026-08-22 06:35:39.754286 | mon1 |
2026-08-22 06:35:39.754297 | mon1 | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-08-22 06:35:39.754305 | mon1 | Saturday 22 August 2026  06:35:39 +0000 (0:00:00.036)       0:00:08.255 *******
2026-08-22 06:35:39.784637 | mon1 | skipping: [mon1]
2026-08-22 06:35:39.784689 | mon1 |
2026-08-22 06:35:39.784801 | mon1 | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-08-22 06:35:39.784906 | mon1 | Saturday 22 August 2026  06:35:39 +0000 (0:00:00.031)       0:00:08.287 *******
2026-08-22 06:35:39.811154 | mon1 | skipping: [mon1]
2026-08-22 06:35:39.811239 | mon1 |
2026-08-22 06:35:39.811499 | mon1 | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-08-22 06:35:39.811720 | mon1 | Saturday 22 August 2026  06:35:39 +0000 (0:00:00.026)       0:00:08.313 *******
2026-08-22 06:35:45.460187 | mon1 | changed: [mon1]
2026-08-22 06:35:45.460472 | mon1 |
2026-08-22 06:35:45.460486 | mon1 | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-08-22 06:35:45.460497 | mon1 | Saturday 22 August 2026  06:35:45 +0000 (0:00:05.647)       0:00:13.961 *******
2026-08-22 06:35:46.097457 | mon1 | changed: [mon1]
2026-08-22 06:35:46.097533 | mon1 |
2026-08-22 06:35:46.097627 | mon1 | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-08-22 06:35:46.097776 | mon1 | Saturday 22 August 2026  06:35:46 +0000 (0:00:00.638)       0:00:14.599 *******
2026-08-22 06:35:47.036772 | mon1 | changed: [mon1] => (item={'path': '/etc/containerd'})
2026-08-22 06:35:47.036855 | mon1 | changed: [mon1] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-08-22 06:35:47.036897 | mon1 | changed: [mon1] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-08-22 06:35:47.037437 | mon1 | changed: [mon1] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-08-22 06:35:47.037499 | mon1 | changed: [mon1] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-08-22 06:35:47.037506 | mon1 |
2026-08-22 06:35:47.037512 | mon1 | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-08-22 06:35:47.037517 | mon1 | Saturday 22 August 2026  06:35:47 +0000 (0:00:00.939)       0:00:15.538 *******
2026-08-22 06:35:47.678281 | mon1 | changed: [mon1]
2026-08-22 06:35:47.678365 | mon1 |
2026-08-22 06:35:47.678964 | mon1 | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-08-22 06:35:47.679043 | mon1 | Saturday 22 August 2026  06:35:47 +0000 (0:00:00.633)       0:00:16.172 *******
2026-08-22 06:35:47.679051 | mon1 |
2026-08-22 06:35:47.679057 | mon1 | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-22 06:35:47.679063 | mon1 | Saturday 22 August 2026  06:35:47 +0000 (0:00:00.007)       0:00:16.180 *******
2026-08-22 06:35:48.604682 | mon1 | ok: [mon1]
2026-08-22 06:35:48.604764 | mon1 |
2026-08-22 06:35:48.605129 | mon1 | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-08-22 06:35:48.605201 | mon1 | Saturday 22 August 2026  06:35:48 +0000 (0:00:00.926)       0:00:17.106 *******
2026-08-22 06:35:49.061081 | mon1 | changed: [mon1]
2026-08-22 06:35:49.061147 | mon1 |
2026-08-22 06:35:49.061155 | mon1 | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-08-22 06:35:49.061162 | mon1 | Saturday 22 August 2026  06:35:49 +0000 (0:00:00.456)       0:00:17.562 *******
2026-08-22 06:35:49.581429 | mon1 | changed: [mon1]
2026-08-22 06:35:49.581507 | mon1 |
2026-08-22 06:35:49.581733 | mon1 | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-22 06:35:49.581804 | mon1 | Saturday 22 August 2026  06:35:49 +0000 (0:00:00.520)       0:00:18.083 *******
2026-08-22 06:35:49.798745 | mon1 | ok: [mon1]
2026-08-22 06:35:49.798855 | mon1 |
2026-08-22 06:35:49.798869 | mon1 | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 06:35:49.799034 | mon1 | Saturday 22 August 2026  06:35:49 +0000 (0:00:00.217)       0:00:18.300 *******
2026-08-22 06:35:49.872650 | mon1 | ok: [mon1] => {
2026-08-22 06:35:49.873194 | mon1 |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-08-22 06:35:49.873268 | mon1 | }
2026-08-22 06:35:49.873275 | mon1 |
2026-08-22 06:35:49.873280 | mon1 | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 06:35:49.873285 | mon1 | Saturday 22 August 2026  06:35:49 +0000 (0:00:00.073)       0:00:18.374 *******
2026-08-22 06:35:50.670587 | mon1 | changed: [mon1]
2026-08-22 06:35:50.670688 | mon1 |
2026-08-22 06:35:50.670724 | mon1 | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 06:35:50.670888 | mon1 | Saturday 22 August 2026  06:35:50 +0000 (0:00:00.798)       0:00:19.172 *******
2026-08-22 06:35:55.201502 | mon1 | changed: [mon1]
2026-08-22 06:35:55.201712 | mon1 |
2026-08-22 06:35:55.201851 | mon1 | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-08-22 06:35:55.201979 | mon1 | Saturday 22 August 2026  06:35:55 +0000 (0:00:04.519)       0:00:23.692 *******
2026-08-22 06:35:56.369782 | mon1 | ok: [mon1]
2026-08-22 06:35:56.369869 | mon1 |
2026-08-22 06:35:56.369913 | mon1 | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-08-22 06:35:56.370061 | mon1 | Saturday 22 August 2026  06:35:56 +0000 (0:00:01.179)       0:00:24.872 *******
2026-08-22 06:35:56.736244 | mon1 | changed: [mon1]
2026-08-22 06:35:56.736328 | mon1 |
2026-08-22 06:35:56.736388 | mon1 | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-08-22 06:35:56.736521 | mon1 | Saturday 22 August 2026  06:35:56 +0000 (0:00:00.366)       0:00:25.238 *******
2026-08-22 06:35:57.210229 | mon1 | changed: [mon1]
2026-08-22 06:35:57.210338 | mon1 |
2026-08-22 06:35:57.210355 | mon1 | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-08-22 06:35:57.210516 | mon1 | Saturday 22 August 2026  06:35:57 +0000 (0:00:00.473)       0:00:25.712 *******
2026-08-22 06:35:57.796863 | mon1 | changed: [mon1] => (item={'path': '/etc/docker'})
2026-08-22 06:35:57.796928 | mon1 | changed: [mon1] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-08-22 06:35:57.797299 | mon1 | changed: [mon1] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-08-22 06:35:57.797318 | mon1 |
2026-08-22 06:35:57.797324 | mon1 | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-08-22 06:35:57.797329 | mon1 | Saturday 22 August 2026  06:35:57 +0000 (0:00:00.586)       0:00:26.298 *******
2026-08-22 06:35:58.286234 | mon1 | changed: [mon1]
2026-08-22 06:35:58.286355 | mon1 |
2026-08-22 06:35:58.286623 | mon1 | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-08-22 06:35:58.286694 | mon1 | Saturday 22 August 2026  06:35:58 +0000 (0:00:00.489)       0:00:26.788 *******
2026-08-22 06:35:58.776014 | mon1 | changed: [mon1]
2026-08-22 06:35:58.776103 | mon1 |
2026-08-22 06:35:58.776165 | mon1 | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-08-22 06:35:58.776562 | mon1 | Saturday 22 August 2026  06:35:58 +0000 (0:00:00.482)       0:00:27.270 *******
2026-08-22 06:35:58.776615 | mon1 |
2026-08-22 06:35:58.776621 | mon1 | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-22 06:35:58.776638 | mon1 | Saturday 22 August 2026  06:35:58 +0000 (0:00:00.007)       0:00:27.278 *******
2026-08-22 06:35:59.460926 | mon1 | ok: [mon1]
2026-08-22 06:35:59.461073 | mon1 |
2026-08-22 06:35:59.461086 | mon1 | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-08-22 06:35:59.461210 | mon1 | Saturday 22 August 2026  06:35:59 +0000 (0:00:00.684)       0:00:27.963 *******
2026-08-22 06:36:00.190634 | mon1 | changed: [mon1]
2026-08-22 06:36:00.190747 | mon1 |
2026-08-22 06:36:00.191045 | mon1 | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-08-22 06:36:00.191117 | mon1 | Saturday 22 August 2026  06:36:00 +0000 (0:00:00.729)       0:00:28.692 *******
2026-08-22 06:36:00.714779 | mon1 | changed: [mon1]
2026-08-22 06:36:00.714874 | mon1 |
2026-08-22 06:36:00.714886 | mon1 | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-08-22 06:36:00.715030 | mon1 | Saturday 22 August 2026  06:36:00 +0000 (0:00:00.524)       0:00:29.216 *******
2026-08-22 06:36:00.764303 | mon1 | ok: [mon1] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-08-22 06:36:00.764375 | mon1 |
2026-08-22 06:36:00.764665 | mon1 | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-08-22 06:36:00.764741 | mon1 | Saturday 22 August 2026  06:36:00 +0000 (0:00:00.049)       0:00:29.266 *******
2026-08-22 06:36:26.012605 | mon1 | changed: [mon1]
2026-08-22 06:36:26.012686 | mon1 |
2026-08-22 06:36:26.012976 | mon1 | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-08-22 06:36:26.013174 | mon1 | Saturday 22 August 2026  06:36:26 +0000 (0:00:25.248)       0:00:54.514 *******
2026-08-22 06:36:26.733009 | mon1 | ok: [mon1] => (item=chronyd)
2026-08-22 06:36:26.733059 | mon1 | ok: [mon1] => (item=sshd)
2026-08-22 06:36:26.733065 | mon1 |
2026-08-22 06:36:26.733070 | mon1 | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-08-22 06:36:26.733075 | mon1 | Saturday 22 August 2026  06:36:26 +0000 (0:00:00.720)       0:00:55.234 *******
2026-08-22 06:36:27.902931 | mon1 | changed: [mon1]
2026-08-22 06:36:27.903036 | mon1 |
2026-08-22 06:36:27.903304 | mon1 | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-08-22 06:36:27.903368 | mon1 | Saturday 22 August 2026  06:36:27 +0000 (0:00:01.170)       0:00:56.405 *******
2026-08-22 06:36:28.127198 | mon1 | ok: [mon1]
2026-08-22 06:36:28.127260 | mon1 |
2026-08-22 06:36:28.127445 | mon1 | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-08-22 06:36:28.127462 | mon1 | Saturday 22 August 2026  06:36:28 +0000 (0:00:00.224)       0:00:56.629 *******
2026-08-22 06:36:28.527399 | mon1 | changed: [mon1]
2026-08-22 06:36:28.527461 | mon1 |
2026-08-22 06:36:28.527753 | mon1 | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-08-22 06:36:28.528044 | mon1 | Saturday 22 August 2026  06:36:28 +0000 (0:00:00.399)       0:00:57.029 *******
2026-08-22 06:36:28.831713 | mon1 | changed: [mon1]
2026-08-22 06:36:28.831778 | mon1 |
2026-08-22 06:36:28.831999 | mon1 | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-08-22 06:36:28.832039 | mon1 | Saturday 22 August 2026  06:36:28 +0000 (0:00:00.304)       0:00:57.334 *******
2026-08-22 06:36:28.941224 | mon1 | ok: [mon1]
2026-08-22 06:36:28.941295 | mon1 |
2026-08-22 06:36:28.941358 | mon1 | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-08-22 06:36:28.941490 | mon1 | Saturday 22 August 2026  06:36:28 +0000 (0:00:00.109)       0:00:57.443 *******
2026-08-22 06:36:29.152950 | mon1 | ok: [mon1] => (item=mon1)
2026-08-22 06:36:29.153028 | mon1 |
2026-08-22 06:36:29.153248 | mon1 | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-08-22 06:36:29.153325 | mon1 | Saturday 22 August 2026  06:36:29 +0000 (0:00:00.211)       0:00:57.655 *******
2026-08-22 06:36:29.201561 | mon1 | ok: [mon1]
2026-08-22 06:36:29.201633 | mon1 |
2026-08-22 06:36:29.201935 | mon1 | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-08-22 06:36:29.201990 | mon1 | Saturday 22 August 2026  06:36:29 +0000 (0:00:00.048)       0:00:57.703 *******
2026-08-22 06:36:29.277446 | mon1 | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for mon1
2026-08-22 06:36:29.277531 | mon1 |
2026-08-22 06:36:29.277554 | mon1 | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-08-22 06:36:29.277569 | mon1 | Saturday 22 August 2026  06:36:29 +0000 (0:00:00.076)       0:00:57.779 *******
2026-08-22 06:36:29.560413 | mon1 | changed: [mon1]
2026-08-22 06:36:29.560487 | mon1 |
2026-08-22 06:36:29.560810 | mon1 | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-08-22 06:36:29.560861 | mon1 | Saturday 22 August 2026  06:36:29 +0000 (0:00:00.283)       0:00:58.062 *******
2026-08-22 06:36:30.217918 | mon1 | changed: [mon1] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-08-22 06:36:30.218029 | mon1 | changed: [mon1] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-08-22 06:36:30.218363 | mon1 | changed: [mon1] => (item={'option': 'mon data avail warn', 'section': 'mon', 'value': 10})
2026-08-22 06:36:30.218432 | mon1 |
2026-08-22 06:36:30.218452 | mon1 | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-08-22 06:36:30.218587 | mon1 | Saturday 22 August 2026  06:36:30 +0000 (0:00:00.657)       0:00:58.720 *******
2026-08-22 06:37:53.942542 | mon1 | ok: [mon1]
2026-08-22 06:37:53.942770 | mon1 |
2026-08-22 06:37:53.942781 | mon1 | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-08-22 06:37:53.942791 | mon1 | Saturday 22 August 2026  06:37:53 +0000 (0:01:23.721)       0:02:22.442 *******
2026-08-22 06:37:54.166084 | mon1 | changed: [mon1]
2026-08-22 06:37:54.166209 | mon1 |
2026-08-22 06:37:54.166223 | mon1 | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-08-22 06:37:54.166449 | mon1 | Saturday 22 August 2026  06:37:54 +0000 (0:00:00.222)       0:02:22.664 *******
2026-08-22 06:37:54.193188 | mon1 | ok: [mon1]
2026-08-22 06:37:54.193247 | mon1 |
2026-08-22 06:37:54.193337 | mon1 | TASK [Install Ceph host] *******************************************************
2026-08-22 06:37:54.193436 | mon1 | Saturday 22 August 2026  06:37:54 +0000 (0:00:00.031)       0:02:22.695 *******
2026-08-22 06:37:54.261318 | mon1 | included: vexxhost.ceph.cephadm_host for mon1
2026-08-22 06:37:54.261414 | mon1 |
2026-08-22 06:37:54.261430 | mon1 | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-08-22 06:37:54.261600 | mon1 | Saturday 22 August 2026  06:37:54 +0000 (0:00:00.067)       0:02:22.763 *******
2026-08-22 06:37:55.950450 | mon1 | ok: [mon1]
2026-08-22 06:37:55.950534 | mon1 |
2026-08-22 06:37:55.950543 | mon1 | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-08-22 06:37:55.950551 | mon1 | Saturday 22 August 2026  06:37:55 +0000 (0:00:01.687)       0:02:24.451 *******
2026-08-22 06:37:56.004086 | mon1 | ok: [mon1] => (item=mon1)
2026-08-22 06:37:56.004155 | mon1 |
2026-08-22 06:37:56.004163 | mon1 | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-08-22 06:37:56.004170 | mon1 | Saturday 22 August 2026  06:37:56 +0000 (0:00:00.053)       0:02:24.505 *******
2026-08-22 06:37:56.353767 | mon1 | ok: [mon1]
2026-08-22 06:37:56.354388 | mon1 |
2026-08-22 06:37:56.354492 | mon1 | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-08-22 06:37:56.354518 | mon1 | Saturday 22 August 2026  06:37:56 +0000 (0:00:00.349)       0:02:24.855 *******
2026-08-22 06:37:58.711168 | mon1 | ok: [mon1]
2026-08-22 06:37:58.711282 | mon1 |
2026-08-22 06:37:58.711295 | mon1 | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-08-22 06:37:58.711361 | mon1 | Saturday 22 August 2026  06:37:58 +0000 (0:00:02.358)       0:02:27.213 *******
2026-08-22 06:38:50.294823 | 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:38:50.295162 | 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 \"/usr/lib/python3.10/runpy.py\", line 224, in run_module\n    return _run_module_code(code, init_globals, run_name, mod_spec)\n  File \"/usr/lib/python3.10/runpy.py\", line 96, in _run_module_code\n    _run_code(code, mod_globals, init_globals,\n  File \"/usr/lib/python3.10/runpy.py\", line 86, in _run_code\n    exec(code, run_globals)\n  File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_7_qnlwxy/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_7_qnlwxy/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_7_qnlwxy/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_7_qnlwxy/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:38:50.124+0000 7fb8f9934640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCYQ4lqkFZyMiAA5B+y63PJte+F2tF+x0pjZqUzGb1IZC7L2GyxU/Ygptc=: Malformed input [buffer:3]\n2026-08-22T06:38:50.124+0000 7fb8f9934640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-22T06:38:50.128+0000 7fb8f9934640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCYQ4lqkFZyMiAA5B+y63PJte+F2tF+x0pjZqUzGb1IZC7L2GyxU/Ygptc=: Malformed input [buffer:3]\n2026-08-22T06:38:50.128+0000 7fb8f9934640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-22T06:38:50.128+0000 7fb8f9934640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCYQ4lqkFZyMiAA5B+y63PJte+F2tF+x0pjZqUzGb1IZC7L2GyxU/Ygptc=: Malformed input [buffer:3]\n2026-08-22T06:38:50.128+0000 7fb8f9934640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-22T06:38:50.128+0000 7fb8f9934640 -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:38:50.296832 | mon1 |
2026-08-22 06:38:50.296932 | mon1 | PLAY RECAP *********************************************************************
2026-08-22 06:38:50.297038 | mon1 | mon1                       : ok=51   changed=26   unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2026-08-22 06:38:50.297124 | mon1 |
2026-08-22 06:38:50.297217 | mon1 |
2026-08-22 06:38:50.298129 | mon1 | TASKS RECAP ********************************************************************
2026-08-22 06:38:50.298197 | mon1 | Saturday 22 August 2026  06:38:50 +0000 (0:00:51.586)       0:03:18.799 *******
2026-08-22 06:38:50.298205 | mon1 | ===============================================================================
2026-08-22 06:38:50.298211 | mon1 | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 83.72s
2026-08-22 06:38:50.298216 | mon1 | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 51.59s
2026-08-22 06:38:50.298222 | mon1 | vexxhost.ceph.cephadm : Install packages ------------------------------- 25.25s
2026-08-22 06:38:50.298228 | mon1 | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.65s
2026-08-22 06:38:50.298239 | mon1 | vexxhost.containers.download_artifact : Extract archive ----------------- 4.52s
2026-08-22 06:38:50.299960 | mon1 | vexxhost.containers.download_artifact : Extract archive ----------------- 3.02s
2026-08-22 06:38:50.299987 | mon1 | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.36s
2026-08-22 06:38:50.300010 | mon1 | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.69s
2026-08-22 06:38:50.300018 | mon1 | vexxhost.containers.containerd : Reload systemd ------------------------- 1.61s
2026-08-22 06:38:50.300023 | mon1 | vexxhost.containers.package : Update state for tar ---------------------- 1.26s
2026-08-22 06:38:50.300029 | mon1 | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.18s
2026-08-22 06:38:50.300035 | mon1 | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 1.17s
2026-08-22 06:38:50.300041 | mon1 | vexxhost.containers.download_artifact : Download item ------------------- 1.07s
2026-08-22 06:38:50.300047 | mon1 | Gathering Facts --------------------------------------------------------- 1.02s
2026-08-22 06:38:50.300053 | mon1 | vexxhost.containers.containerd : Create folders for configuration ------- 0.94s
2026-08-22 06:38:50.300058 | mon1 | vexxhost.containers.download_artifact : Download item ------------------- 0.80s
2026-08-22 06:38:50.300064 | mon1 | vexxhost.containers.docker : Restart docker ----------------------------- 0.73s
2026-08-22 06:38:50.300070 | mon1 | vexxhost.ceph.cephadm : Ensure services are started --------------------- 0.72s
2026-08-22 06:38:50.300076 | mon1 | vexxhost.containers.download_artifact : Download item ------------------- 0.68s
2026-08-22 06:38:50.300082 | mon1 | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.66s
2026-08-22 06:38:50.444041 | 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:38:50.444294 | mon1 | ERROR    [default > converge] Executed: Failed
2026-08-22 06:38:50.444478 | 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:38:50.753292 | mon1 | ERROR
2026-08-22 06:38:50.753544 | mon1 | {
2026-08-22 06:38:50.753584 | mon1 |   "delta": "0:04:25.856819",
2026-08-22 06:38:50.753612 | mon1 |   "end": "2026-08-22 06:38:50.515174",
2026-08-22 06:38:50.753638 | mon1 |   "msg": "non-zero return code",
2026-08-22 06:38:50.753664 | mon1 |   "rc": 2,
2026-08-22 06:38:50.753692 | mon1 |   "start": "2026-08-22 06:34:24.658355"
2026-08-22 06:38:50.753717 | mon1 | }
failure
2026-08-22 06:38:50.759446 | 
2026-08-22 06:38:50.759498 | PLAY RECAP
2026-08-22 06:38:50.759545 | mon1 | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-08-22 06:38:50.759570 | 
2026-08-22 06:38:50.866529 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-22 06:38:50.871811 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-22 06:38:51.860109 | 
2026-08-22 06:38:51.860281 | PLAY [all]
2026-08-22 06:38:51.871872 | 
2026-08-22 06:38:51.871976 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-22 06:38:51.931965 | mon1 | ok
2026-08-22 06:38:51.936327 | osd1 | ok
2026-08-22 06:38:51.940759 | 
2026-08-22 06:38:51.940836 | TASK [fetch-output : Set log path for single node]
2026-08-22 06:38:51.966416 | mon1 | skipping: Conditional result was False
2026-08-22 06:38:51.977860 | osd1 | skipping: Conditional result was False
2026-08-22 06:38:51.985805 | 
2026-08-22 06:38:51.985946 | LOOP [fetch-output : Ensure local output dirs]
2026-08-22 06:38:52.837322 | osd1 -> localhost | changed: "/var/lib/zuul/builds/c7a47e51caf249758e388617fc1c0472/work/logs/osd1"
2026-08-22 06:38:52.846655 | mon1 -> localhost | changed: "/var/lib/zuul/builds/c7a47e51caf249758e388617fc1c0472/work/logs/mon1"
2026-08-22 06:38:53.031591 | mon1 -> localhost | ok: "/var/lib/zuul/builds/c7a47e51caf249758e388617fc1c0472/work/artifacts"
2026-08-22 06:38:53.032401 | osd1 -> localhost | changed: "/var/lib/zuul/builds/c7a47e51caf249758e388617fc1c0472/work/artifacts"
2026-08-22 06:38:53.231783 | osd1 -> localhost | ok: "/var/lib/zuul/builds/c7a47e51caf249758e388617fc1c0472/work/docs"
2026-08-22 06:38:53.244597 | mon1 -> localhost | changed: "/var/lib/zuul/builds/c7a47e51caf249758e388617fc1c0472/work/docs"
2026-08-22 06:38:53.244702 | mon1 -> localhost | changed: All items complete
2026-08-22 06:38:53.244741 | 
2026-08-22 06:38:53.262186 | 
2026-08-22 06:38:53.262331 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-22 06:38:53.948539 | mon1 | changed: .d..t...... ./
2026-08-22 06:38:53.954597 | osd1 | changed: .d..t...... ./
2026-08-22 06:38:54.430591 | osd1 | changed: .d..t...... ./
2026-08-22 06:38:54.436134 | mon1 | ok
2026-08-22 06:38:55.319186 | osd1 | changed: .d..t...... ./
2026-08-22 06:38:55.354840 | mon1 | ok
2026-08-22 06:38:55.354906 | mon1 | changed: All items complete
2026-08-22 06:38:55.354937 | 
2026-08-22 06:38:55.379888 | 
2026-08-22 06:38:55.380024 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-22 06:38:55.787752 | mon1 -> localhost | ok: Item: artifacts Runtime: 0:00:00.006435
2026-08-22 06:38:56.006059 | mon1 -> localhost | ok: Item: docs Runtime: 0:00:00.007168
2026-08-22 06:38:56.358412 | 
2026-08-22 06:38:56.358513 | PLAY [all]
2026-08-22 06:38:56.365072 | 
2026-08-22 06:38:56.365137 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-22 06:38:56.784328 | mon1 | changed
2026-08-22 06:38:56.964539 | osd1 | changed
2026-08-22 06:38:56.974959 | 
2026-08-22 06:38:56.975006 | PLAY RECAP
2026-08-22 06:38:56.975051 | mon1 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-22 06:38:56.975080 | osd1 | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-22 06:38:56.975100 | 
2026-08-22 06:38:57.084389 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-22 06:38:57.128038 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-22 06:38:58.372956 | 
2026-08-22 06:38:58.373132 | PLAY [localhost]
2026-08-22 06:38:58.382723 | 
2026-08-22 06:38:58.382793 | TASK [Generate Zuul manifest]
2026-08-22 06:38:58.399670 | localhost | ok
2026-08-22 06:38:58.685403 | 
2026-08-22 06:38:58.685579 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-22 06:38:59.401904 | localhost | changed
2026-08-22 06:38:59.413648 | 
2026-08-22 06:38:59.413768 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-22 06:38:59.708138 | localhost | ok
2026-08-22 06:38:59.717521 | 
2026-08-22 06:38:59.717592 | TASK [Upload logs]
2026-08-22 06:38:59.737778 | localhost | ok
2026-08-22 06:38:59.839884 | 
2026-08-22 06:38:59.840010 | TASK [Set zuul-log-path fact]
2026-08-22 06:38:59.861015 | localhost | ok
2026-08-22 06:38:59.873916 | 
2026-08-22 06:38:59.873984 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-22 06:38:59.910845 | localhost | ok
2026-08-22 06:38:59.918001 | 
2026-08-22 06:38:59.918075 | TASK [upload-logs : Create log directories]
2026-08-22 06:39:00.385888 | localhost | changed
2026-08-22 06:39:00.390742 | 
2026-08-22 06:39:00.390812 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-22 06:39:01.048193 | localhost -> localhost | ok: Runtime: 0:00:00.141188
2026-08-22 06:39:01.053349 | 
2026-08-22 06:39:01.053421 | TASK [upload-logs : Upload logs to log server]
2026-08-22 06:39:01.615089 | localhost | Output suppressed because no_log was given
2026-08-22 06:39:01.620633 | 
2026-08-22 06:39:01.620748 | LOOP [upload-logs : Compress console log and json output]
2026-08-22 06:39:01.709571 | localhost | skipping: Conditional result was False
2026-08-22 06:39:01.716577 | localhost | skipping: Conditional result was False
2026-08-22 06:39:01.733500 | 
2026-08-22 06:39:01.733776 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-22 06:39:01.814730 | localhost | skipping: Conditional result was False
2026-08-22 06:39:01.815083 | 
2026-08-22 06:39:01.818839 | localhost | skipping: Conditional result was False
2026-08-22 06:39:01.868553 | 
2026-08-22 06:39:01.868733 | LOOP [upload-logs : Upload console log and json output]