2026-08-26 20:52:37.303082 | Job console starting
2026-08-26 20:52:37.318573 | Updating git repos
2026-08-26 20:52:37.350599 | Cloning repos into workspace
2026-08-26 20:52:37.399404 | Restoring repo states
2026-08-26 20:52:37.407404 | Merging changes
2026-08-26 20:52:38.736898 | Checking out repos
2026-08-26 20:52:38.769531 | Preparing playbooks
2026-08-26 20:52:40.635294 | Running Ansible setup
2026-08-26 20:52:44.960599 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-26 20:52:45.578761 | 
2026-08-26 20:52:45.669153 | PLAY [localhost]
2026-08-26 20:52:45.683713 | 
2026-08-26 20:52:45.683817 | TASK [Gathering Facts]
2026-08-26 20:52:46.574168 | localhost | ok
2026-08-26 20:52:46.586160 | 
2026-08-26 20:52:46.586263 | TASK [Setup log path fact]
2026-08-26 20:52:46.607965 | localhost | ok
2026-08-26 20:52:46.622577 | 
2026-08-26 20:52:46.622666 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-26 20:52:46.653337 | localhost | ok
2026-08-26 20:52:46.661967 | 
2026-08-26 20:52:46.662053 | TASK [emit-job-header : Print job information]
2026-08-26 20:52:46.703185 | # Job Information
2026-08-26 20:52:46.703334 | Ansible Version: 2.16.18
2026-08-26 20:52:46.703378 | Job: ansible-collection-ceph-molecule-ha-ubuntu-jammy
2026-08-26 20:52:46.703409 | Pipeline: check
2026-08-26 20:52:46.703437 | Executor: 2d72f0692154
2026-08-26 20:52:46.703466 | Triggered by: https://github.com/vexxhost/ansible-collection-ceph/pull/117
2026-08-26 20:52:46.703497 | Event ID: 67c110d0-a18e-11f1-96e8-dbebb464ef4a
2026-08-26 20:52:46.707158 | 
2026-08-26 20:52:46.707242 | LOOP [emit-job-header : Print node information]
2026-08-26 20:52:46.806744 | localhost | ok:
2026-08-26 20:52:46.807060 | localhost | # Node Information
2026-08-26 20:52:46.807105 | localhost | Inventory Hostname: mon1
2026-08-26 20:52:46.807137 | localhost | Hostname: np0000191307
2026-08-26 20:52:46.807165 | localhost | Username: zuul
2026-08-26 20:52:46.807209 | localhost | Distro: Ubuntu 22.04
2026-08-26 20:52:46.807238 | localhost | Provider: yul1
2026-08-26 20:52:46.807265 | localhost | Region: ca-ymq-1
2026-08-26 20:52:46.807292 | localhost | Label: ubuntu-jammy
2026-08-26 20:52:46.807317 | localhost | Product Name: OpenStack Nova
2026-08-26 20:52:46.807343 | localhost | Interface IP: 162.253.55.119
2026-08-26 20:52:46.849461 | localhost | ok:
2026-08-26 20:52:46.849569 | localhost | # Node Information
2026-08-26 20:52:46.849627 | localhost | Inventory Hostname: osd1
2026-08-26 20:52:46.849672 | localhost | Hostname: np0000191308
2026-08-26 20:52:46.849715 | localhost | Username: zuul
2026-08-26 20:52:46.849758 | localhost | Distro: Ubuntu 22.04
2026-08-26 20:52:46.849799 | localhost | Provider: yul1
2026-08-26 20:52:46.849841 | localhost | Region: ca-ymq-1
2026-08-26 20:52:46.849882 | localhost | Label: ubuntu-jammy
2026-08-26 20:52:46.849923 | localhost | Product Name: OpenStack Nova
2026-08-26 20:52:46.849964 | localhost | Interface IP: 162.253.55.81
2026-08-26 20:52:46.868133 | 
2026-08-26 20:52:46.868267 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-26 20:52:47.269344 | localhost -> localhost | changed
2026-08-26 20:52:47.274774 | 
2026-08-26 20:52:47.274847 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-26 20:52:48.133100 | localhost -> localhost | changed
2026-08-26 20:52:48.144669 | 
2026-08-26 20:52:48.144747 | PLAY [all]
2026-08-26 20:52:48.155043 | 
2026-08-26 20:52:48.155118 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-26 20:52:48.372407 | mon1 -> localhost | ok
2026-08-26 20:52:48.384330 | 
2026-08-26 20:52:48.384422 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-26 20:52:48.419420 | mon1 | ok
2026-08-26 20:52:48.425487 | osd1 | ok
2026-08-26 20:52:48.444469 | mon1 | included: /var/lib/zuul/builds/8ff921f2324c4eea8a4063a4c5208d26/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-26 20:52:48.444531 | osd1 | included: /var/lib/zuul/builds/8ff921f2324c4eea8a4063a4c5208d26/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-26 20:52:48.451192 | 
2026-08-26 20:52:48.451256 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-26 20:52:49.423422 | mon1 -> localhost | Generating public/private rsa key pair.
2026-08-26 20:52:49.423752 | mon1 -> localhost | Your identification has been saved in /var/lib/zuul/builds/8ff921f2324c4eea8a4063a4c5208d26/work/8ff921f2324c4eea8a4063a4c5208d26_id_rsa
2026-08-26 20:52:49.423823 | mon1 -> localhost | Your public key has been saved in /var/lib/zuul/builds/8ff921f2324c4eea8a4063a4c5208d26/work/8ff921f2324c4eea8a4063a4c5208d26_id_rsa.pub
2026-08-26 20:52:49.423876 | mon1 -> localhost | The key fingerprint is:
2026-08-26 20:52:49.423927 | mon1 -> localhost | SHA256:T28ho6In94RA/DRWmD2W1YSRoJk0/rUq8STUl4zgfWI zuul-build-sshkey
2026-08-26 20:52:49.423973 | mon1 -> localhost | The key's randomart image is:
2026-08-26 20:52:49.424020 | mon1 -> localhost | +---[RSA 3072]----+
2026-08-26 20:52:49.424065 | mon1 -> localhost | |      ++o+o*.    |
2026-08-26 20:52:49.424338 | mon1 -> localhost | |   . +oX=oo..    |
2026-08-26 20:52:49.424395 | mon1 -> localhost | |    o @.E.*      |
2026-08-26 20:52:49.424442 | mon1 -> localhost | |   . = + = .     |
2026-08-26 20:52:49.424486 | mon1 -> localhost | |    . + S = .    |
2026-08-26 20:52:49.424531 | mon1 -> localhost | |     . * = + .   |
2026-08-26 20:52:49.424574 | mon1 -> localhost | |      + = . o    |
2026-08-26 20:52:49.424618 | mon1 -> localhost | |    ..o+   .     |
2026-08-26 20:52:49.424662 | mon1 -> localhost | |    .+ ..        |
2026-08-26 20:52:49.424705 | mon1 -> localhost | +----[SHA256]-----+
2026-08-26 20:52:49.424920 | mon1 -> localhost | ok: Runtime: 0:00:00.564924
2026-08-26 20:52:49.432908 | 
2026-08-26 20:52:49.432996 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-26 20:52:49.478219 | mon1 | ok
2026-08-26 20:52:49.488059 | osd1 | ok
2026-08-26 20:52:49.506372 | mon1 | included: /var/lib/zuul/builds/8ff921f2324c4eea8a4063a4c5208d26/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-26 20:52:49.506559 | osd1 | included: /var/lib/zuul/builds/8ff921f2324c4eea8a4063a4c5208d26/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-26 20:52:49.523987 | 
2026-08-26 20:52:49.524096 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-26 20:52:49.549464 | mon1 | skipping: Conditional result was False
2026-08-26 20:52:49.560480 | osd1 | skipping: Conditional result was False
2026-08-26 20:52:49.568578 | 
2026-08-26 20:52:49.568687 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-26 20:52:50.048242 | mon1 | changed
2026-08-26 20:52:50.052857 | osd1 | changed
2026-08-26 20:52:50.057665 | 
2026-08-26 20:52:50.057728 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-26 20:52:50.261522 | mon1 | ok
2026-08-26 20:52:50.269075 | osd1 | ok
2026-08-26 20:52:50.274397 | 
2026-08-26 20:52:50.274461 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-26 20:52:50.775549 | osd1 | changed
2026-08-26 20:52:50.780224 | mon1 | changed
2026-08-26 20:52:50.790576 | 
2026-08-26 20:52:50.790770 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-26 20:52:51.329751 | osd1 | changed
2026-08-26 20:52:51.336876 | mon1 | changed
2026-08-26 20:52:51.350333 | 
2026-08-26 20:52:51.350562 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-26 20:52:51.383988 | mon1 | skipping: Conditional result was False
2026-08-26 20:52:51.395600 | osd1 | skipping: Conditional result was False
2026-08-26 20:52:51.403481 | 
2026-08-26 20:52:51.403576 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-26 20:52:51.723547 | mon1 -> localhost | changed
2026-08-26 20:52:51.746534 | 
2026-08-26 20:52:51.746608 | TASK [add-build-sshkey : Add back temp key]
2026-08-26 20:52:52.037053 | mon1 -> localhost | Identity added: /var/lib/zuul/builds/8ff921f2324c4eea8a4063a4c5208d26/work/8ff921f2324c4eea8a4063a4c5208d26_id_rsa (zuul-build-sshkey)
2026-08-26 20:52:52.037320 | mon1 -> localhost | ok: Runtime: 0:00:00.015852
2026-08-26 20:52:52.044487 | 
2026-08-26 20:52:52.044571 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-26 20:52:52.331704 | mon1 | ok
2026-08-26 20:52:52.341075 | osd1 | ok
2026-08-26 20:52:52.346682 | 
2026-08-26 20:52:52.346823 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-26 20:52:52.375818 | mon1 | skipping: Conditional result was False
2026-08-26 20:52:52.387612 | osd1 | skipping: Conditional result was False
2026-08-26 20:52:52.408833 | 
2026-08-26 20:52:52.408982 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-26 20:52:52.709197 | osd1 | ok
2026-08-26 20:52:52.713647 | mon1 | ok
2026-08-26 20:52:52.719554 | 
2026-08-26 20:52:52.719619 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-26 20:52:53.476158 | mon1 | Output suppressed because no_log was given
2026-08-26 20:52:53.480263 | osd1 | Output suppressed because no_log was given
2026-08-26 20:52:53.492609 | 
2026-08-26 20:52:53.492674 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-26 20:52:53.675939 | mon1 | ok: "logs"
2026-08-26 20:52:53.676305 | mon1 | ok: All items complete
2026-08-26 20:52:53.676346 | 
2026-08-26 20:52:53.707817 | osd1 | ok: "logs"
2026-08-26 20:52:53.855862 | mon1 | ok: "artifacts"
2026-08-26 20:52:53.889575 | osd1 | ok: "artifacts"
2026-08-26 20:52:54.020455 | mon1 | ok: "docs"
2026-08-26 20:52:54.043117 | osd1 | ok: "docs"
2026-08-26 20:52:54.056614 | 
2026-08-26 20:52:54.057211 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-26 20:52:54.247159 | mon1 | changed: "logs"
2026-08-26 20:52:54.247555 | mon1 | ok: All items complete
2026-08-26 20:52:54.247603 | 
2026-08-26 20:52:54.285000 | osd1 | changed: "logs"
2026-08-26 20:52:54.395029 | mon1 | changed: "artifacts"
2026-08-26 20:52:54.448302 | osd1 | changed: "artifacts"
2026-08-26 20:52:54.583438 | mon1 | changed: "docs"
2026-08-26 20:52:54.631321 | osd1 | changed: "docs"
2026-08-26 20:52:54.657579 | 
2026-08-26 20:52:54.657699 | PLAY RECAP
2026-08-26 20:52:54.657748 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-26 20:52:54.657778 | mon1 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-26 20:52:54.657803 | osd1 | ok: 11 changed: 5 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-26 20:52:54.657821 | 
2026-08-26 20:52:54.863682 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-26 20:52:54.884236 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-26 20:52:55.479460 | 
2026-08-26 20:52:55.479625 | PLAY [all]
2026-08-26 20:52:55.491161 | 
2026-08-26 20:52:55.491264 | TASK [setup-uv : Extract archive]
2026-08-26 20:53:00.760787 | osd1 | changed
2026-08-26 20:53:22.882993 | mon1 | changed
2026-08-26 20:53:22.888718 | 
2026-08-26 20:53:22.888798 | TASK [setup-uv : Print version]
2026-08-26 20:53:23.277677 | mon1 | uv 0.8.13
2026-08-26 20:53:23.267567 | osd1 | uv 0.8.13
2026-08-26 20:53:23.450098 | osd1 | ok: Runtime: 0:00:00.009117
2026-08-26 20:53:23.459498 | mon1 | ok: Runtime: 0:00:00.012152
2026-08-26 20:53:23.472362 | 
2026-08-26 20:53:23.472428 | TASK [Install Ansible collection siblings]
2026-08-26 20:53:23.852265 | mon1 | ok:
2026-08-26 20:53:23.852583 | mon1 | Skipping community.general: no checked-out sibling
2026-08-26 20:53:23.852652 | mon1 | Skipping ansible.utils: no checked-out sibling
2026-08-26 20:53:23.852704 | mon1 | Skipping vexxhost.containers: no checked-out sibling
2026-08-26 20:53:23.854972 | 
2026-08-26 20:53:23.855021 | PLAY RECAP
2026-08-26 20:53:23.855069 | mon1 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-26 20:53:23.855109 | osd1 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-26 20:53:23.855128 | 
2026-08-26 20:53:23.992486 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-26 20:53:23.997801 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-26 20:53:24.571189 | 
2026-08-26 20:53:24.571341 | PLAY [all]
2026-08-26 20:53:24.582590 | 
2026-08-26 20:53:24.582663 | LOOP [Check inventory directories]
2026-08-26 20:53:24.912765 | mon1 | ok: "src/github.com/vexxhost/ansible-collection-ceph"
2026-08-26 20:53:25.086253 | mon1 | ok: "src/github.com/vexxhost/ansible-collection-ceph/molecule/default/inventory"
2026-08-26 20:53:25.236079 | mon1 | ok: "src/github.com/vexxhost/ansible-collection-ceph/extensions/molecule/default/inventory"
2026-08-26 20:53:25.252239 | 
2026-08-26 20:53:25.252488 | LOOP [Copy inventory file for Zuul]
2026-08-26 20:53:26.081176 | mon1 | changed:
2026-08-26 20:53:26.081681 | mon1 | {
2026-08-26 20:53:26.081759 | mon1 |   "ansible_loop_var": "item",
2026-08-26 20:53:26.081813 | mon1 |   "changed": false,
2026-08-26 20:53:26.081858 | mon1 |   "failed": false,
2026-08-26 20:53:26.081915 | mon1 |   "invocation": {
2026-08-26 20:53:26.081958 | mon1 |     "module_args": {
2026-08-26 20:53:26.081998 | mon1 |       "checksum_algorithm": "sha1",
2026-08-26 20:53:26.082037 | mon1 |       "follow": true,
2026-08-26 20:53:26.082078 | mon1 |       "get_attributes": true,
2026-08-26 20:53:26.082119 | mon1 |       "get_checksum": true,
2026-08-26 20:53:26.082159 | mon1 |       "get_mime": true,
2026-08-26 20:53:26.082197 | mon1 |       "path": "src/github.com/vexxhost/ansible-collection-ceph"
2026-08-26 20:53:26.082237 | mon1 |     }
2026-08-26 20:53:26.082275 | mon1 |   },
2026-08-26 20:53:26.082314 | mon1 |   "item": "src/github.com/vexxhost/ansible-collection-ceph",
2026-08-26 20:53:26.082340 | mon1 |   "stat": {
2026-08-26 20:53:26.082357 | mon1 |     "atime": 1787777573.3276997,
2026-08-26 20:53:26.082375 | mon1 |     "attr_flags": "e",
2026-08-26 20:53:26.082392 | mon1 |     "attributes": [
2026-08-26 20:53:26.082409 | mon1 |       "extents"
2026-08-26 20:53:26.082426 | mon1 |     ],
2026-08-26 20:53:26.082443 | mon1 |     "block_size": 4096,
2026-08-26 20:53:26.082460 | mon1 |     "blocks": 8,
2026-08-26 20:53:26.082477 | mon1 |     "charset": "unknown",
2026-08-26 20:53:26.082495 | mon1 |     "ctime": 1787777573.339699,
2026-08-26 20:53:26.082513 | mon1 |     "dev": 64513,
2026-08-26 20:53:26.082530 | mon1 |     "device_type": 0,
2026-08-26 20:53:26.082547 | mon1 |     "executable": true,
2026-08-26 20:53:26.082564 | mon1 |     "exists": true,
2026-08-26 20:53:26.082581 | mon1 |     "gid": 1000,
2026-08-26 20:53:26.082598 | mon1 |     "gr_name": "zuul",
2026-08-26 20:53:26.082615 | mon1 |     "inode": 1042461,
2026-08-26 20:53:26.082631 | mon1 |     "isblk": false,
2026-08-26 20:53:26.082648 | mon1 |     "ischr": false,
2026-08-26 20:53:26.082665 | mon1 |     "isdir": true,
2026-08-26 20:53:26.082683 | mon1 |     "isfifo": false,
2026-08-26 20:53:26.082699 | mon1 |     "isgid": false,
2026-08-26 20:53:26.082717 | mon1 |     "islnk": false,
2026-08-26 20:53:26.082734 | mon1 |     "isreg": false,
2026-08-26 20:53:26.082751 | mon1 |     "issock": false,
2026-08-26 20:53:26.082768 | mon1 |     "isuid": false,
2026-08-26 20:53:26.082784 | mon1 |     "mimetype": "unknown",
2026-08-26 20:53:26.082801 | mon1 |     "mode": "0755",
2026-08-26 20:53:26.082817 | mon1 |     "mtime": 1787777558.7081313,
2026-08-26 20:53:26.082834 | mon1 |     "nlink": 10,
2026-08-26 20:53:26.082851 | mon1 |     "path": "src/github.com/vexxhost/ansible-collection-ceph",
2026-08-26 20:53:26.082867 | mon1 |     "pw_name": "zuul",
2026-08-26 20:53:26.082889 | mon1 |     "readable": true,
2026-08-26 20:53:26.082906 | mon1 |     "rgrp": true,
2026-08-26 20:53:26.082923 | mon1 |     "roth": true,
2026-08-26 20:53:26.082940 | mon1 |     "rusr": true,
2026-08-26 20:53:26.082957 | mon1 |     "size": 4096,
2026-08-26 20:53:26.082988 | mon1 |     "uid": 1000,
2026-08-26 20:53:26.083006 | mon1 |     "version": "1365866983",
2026-08-26 20:53:26.083023 | mon1 |     "wgrp": false,
2026-08-26 20:53:26.083041 | mon1 |     "woth": false,
2026-08-26 20:53:26.083059 | mon1 |     "writeable": true,
2026-08-26 20:53:26.083076 | mon1 |     "wusr": true,
2026-08-26 20:53:26.083093 | mon1 |     "xgrp": true,
2026-08-26 20:53:26.083109 | mon1 |     "xoth": true,
2026-08-26 20:53:26.083126 | mon1 |     "xusr": true
2026-08-26 20:53:26.083143 | mon1 |   }
2026-08-26 20:53:26.083159 | mon1 | }
2026-08-26 20:53:26.083196 | mon1 | ok: All items complete
2026-08-26 20:53:26.083217 | 
2026-08-26 20:53:26.084518 | mon1 | skipping: Conditional result was False
2026-08-26 20:53:26.085138 | mon1 | skipping: Conditional result was False
2026-08-26 20:53:26.104990 | 
2026-08-26 20:53:26.105221 | LOOP [Switch "ansible_host" to private IP]
2026-08-26 20:53:26.466643 | mon1 | changed: 2 replacements made
2026-08-26 20:53:26.467909 | mon1 | skipping: Conditional result was False
2026-08-26 20:53:26.468484 | mon1 | skipping: Conditional result was False
2026-08-26 20:53:26.489765 | 
2026-08-26 20:53:26.489960 | TASK [Run Molecule scenario]
2026-08-26 20:53:47.125784 | mon1 | Downloading cpython-3.13.7-linux-x86_64-gnu (download) (30.9MiB)
2026-08-26 20:53:47.823793 | mon1 |  Downloading cpython-3.13.7-linux-x86_64-gnu (download)
2026-08-26 20:53:47.991883 | mon1 | Using CPython 3.13.7
2026-08-26 20:53:47.991925 | mon1 | Creating virtual environment at: .venv
2026-08-26 20:53:58.045550 | mon1 | Downloading netaddr (2.2MiB)
2026-08-26 20:53:58.045864 | mon1 | Downloading pygments (1.2MiB)
2026-08-26 20:53:58.047268 | mon1 | Downloading ansible-core (2.3MiB)
2026-08-26 20:53:58.048207 | mon1 | Downloading cryptography (4.3MiB)
2026-08-26 20:53:58.348698 | mon1 |  Downloading netaddr
2026-08-26 20:53:58.361771 | mon1 |  Downloading cryptography
2026-08-26 20:53:58.403707 | mon1 |  Downloading pygments
2026-08-26 20:53:58.541076 | mon1 |  Downloading ansible-core
2026-08-26 20:53:58.571521 | mon1 | Installed 36 packages in 29ms
2026-08-26 20:53:59.583572 | mon1 | INFO     Collection 'vexxhost.ceph' detected.
2026-08-26 20:53:59.583713 | mon1 | INFO     Scenarios will be used from 'extensions/molecule'
2026-08-26 20:54:00.504603 | mon1 | INFO     [default > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-08-26 20:54:00.504772 | mon1 | INFO     [default > prerun] Performing prerun with role_name_check=0...
2026-08-26 20:54:34.629425 | mon1 | WARNING  Retrying execution failure 1 of: ansible-galaxy collection install -vvv community.general:>=4.5.0
2026-08-26 20:54:34.629802 | mon1 | ERROR    Command ansible-galaxy collection install -vvv community.general:>=4.5.0, returned 1 code:
2026-08-26 20:54:34.629849 | mon1 | Starting galaxy collection install process
2026-08-26 20:54:34.629865 | mon1 | Process install dependency map
2026-08-26 20:54:34.629878 | mon1 | Starting collection install process
2026-08-26 20:54:34.629897 | mon1 | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/community-general-13.3.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1045e_v2_y19/tmp91d0wn1j/community-general-13.3.0-emi288zl
2026-08-26 20:54:34.629911 | mon1 | Installing 'community.general:13.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-08-26 20:54:34.629923 | mon1 | community.general:13.3.0 was installed successfully
2026-08-26 20:54:34.629934 | mon1 | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/community-library_inventory_filtering_v1-1.1.5.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1045e_v2_y19/tmp91d0wn1j/community-library_inventory_filtering_v1-1.1.5-dv576tps
2026-08-26 20:54:34.629948 | mon1 |
2026-08-26 20:54:34.629957 | mon1 | ansible-galaxy
2026-08-26 20:54:34.629965 | mon1 |   config file = None
2026-08-26 20:54:34.629974 | mon1 |   configured module search path =
2026-08-26 20:54:34.629983 | mon1 |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible
2026-08-26 20:54:34.629994 | mon1 |   ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages
2026-08-26 20:54:34.630003 | mon1 |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/bin/ansible-galaxy
2026-08-26 20:54:34.630012 | mon1 |   python version = 3.13.7 (main, Aug 18 2025, 19:20:03)  (/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/bin/python)
2026-08-26 20:54:34.630020 | mon1 |   jinja version = 3.1.6
2026-08-26 20:54:34.630029 | mon1 |   pyyaml version = 6.0.2 (with libyaml v0.2.5)
2026-08-26 20:54:34.630038 | mon1 | No config file found; using defaults
2026-08-26 20:54:34.630046 | mon1 | Created /home/zuul/.ansible/galaxy_token
2026-08-26 20:54:34.630055 | mon1 | Collection 'community.general:13.3.0' obtained from server default https://galaxy.ansible.com/api/
2026-08-26 20:54:34.630063 | mon1 | : Failed to download collection tar from 'default': <urlopen error  Temporary failure in name resolution>
2026-08-26 20:54:34.630072 | mon1 |
2026-08-26 20:54:34.630081 | mon1 | Failed to download collection tar from 'default': <urlopen error  Temporary failure in name resolution>
2026-08-26 20:54:34.630089 | mon1 |
2026-08-26 20:54:34.630115 | mon1 | <<< caused by >>>
2026-08-26 20:54:34.630124 | mon1 |
2026-08-26 20:54:34.630133 | mon1 | <urlopen error  Temporary failure in name resolution>
2026-08-26 20:54:34.630141 | mon1 |
2026-08-26 20:54:34.630150 | mon1 |
2026-08-26 20:54:34.633687 | mon1 | Traceback (most recent call last):
2026-08-26 20:54:34.637228 | mon1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/bin/molecule", line 10, in <module>
2026-08-26 20:54:34.637260 | mon1 |     sys.exit(main())
2026-08-26 20:54:34.637271 | mon1 |              ~~~~^^
2026-08-26 20:54:34.637666 | mon1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/click/core.py", line 1442, in __call__
2026-08-26 20:54:34.637689 | mon1 |     return self.main(*args, **kwargs)
2026-08-26 20:54:34.637699 | mon1 |            ~~~~~~~~~^^^^^^^^^^^^^^^^^
2026-08-26 20:54:34.638016 | mon1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/click/core.py", line 1363, in main
2026-08-26 20:54:34.638037 | mon1 |     rv = self.invoke(ctx)
2026-08-26 20:54:34.638423 | mon1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/click/core.py", line 1830, in invoke
2026-08-26 20:54:34.638455 | mon1 |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-08-26 20:54:34.638469 | mon1 |                            ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^
2026-08-26 20:54:34.638877 | mon1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/click/core.py", line 1226, in invoke
2026-08-26 20:54:34.638903 | mon1 |     return ctx.invoke(self.callback, **ctx.params)
2026-08-26 20:54:34.638913 | mon1 |            ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-26 20:54:34.639216 | mon1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/click/core.py", line 794, in invoke
2026-08-26 20:54:34.639238 | mon1 |     return callback(*args, **kwargs)
2026-08-26 20:54:34.639558 | mon1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/molecule/click_cfg.py", line 613, in wrapper
2026-08-26 20:54:34.639580 | mon1 |     return func(*args, **kwargs)
2026-08-26 20:54:34.639873 | mon1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/click/decorators.py", line 34, in new_func
2026-08-26 20:54:34.639894 | mon1 |     return f(get_current_context(), *args, **kwargs)
2026-08-26 20:54:34.640244 | mon1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/molecule/click_cfg.py", line 468, in wrapper
2026-08-26 20:54:34.640283 | mon1 |     return func(ctx)
2026-08-26 20:54:34.640740 | mon1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/molecule/command/test.py", line 95, in test
2026-08-26 20:54:34.640768 | mon1 |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-08-26 20:54:34.640778 | mon1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-26 20:54:34.641106 | mon1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/molecule/command/base.py", line 235, in execute_cmdline_scenarios
2026-08-26 20:54:34.641128 | mon1 |     _run_scenarios(scenarios, command_args, default_config)
2026-08-26 20:54:34.641138 | mon1 |     ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-26 20:54:34.641629 | mon1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/molecule/command/base.py", line 314, in _run_scenarios
2026-08-26 20:54:34.641651 | mon1 |     scenario.config.runtime.prepare_environment(
2026-08-26 20:54:34.641660 | mon1 |     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^
2026-08-26 20:54:34.641669 | mon1 |         install_local=True,
2026-08-26 20:54:34.641678 | mon1 |         ^^^^^^^^^^^^^^^^^^^
2026-08-26 20:54:34.641686 | mon1 |         role_name_check=role_name_check,
2026-08-26 20:54:34.641695 | mon1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-26 20:54:34.641703 | mon1 |     )
2026-08-26 20:54:34.641722 | mon1 |     ^
2026-08-26 20:54:34.642286 | mon1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible_compat/runtime.py", line 694, in prepare_environment
2026-08-26 20:54:34.642313 | mon1 |     self.install_collection(
2026-08-26 20:54:34.642323 | mon1 |     ~~~~~~~~~~~~~~~~~~~~~~~^
2026-08-26 20:54:34.642332 | mon1 |         f"{name}{',' if is_url(name) else ':'}{required_version}",
2026-08-26 20:54:34.642342 | mon1 |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-26 20:54:34.642350 | mon1 |         destination=destination,
2026-08-26 20:54:34.642379 | mon1 |         ^^^^^^^^^^^^^^^^^^^^^^^^
2026-08-26 20:54:34.642387 | mon1 |     )
2026-08-26 20:54:34.642396 | mon1 |     ^
2026-08-26 20:54:34.642716 | mon1 |   File "/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible_compat/runtime.py", line 552, in install_collection
2026-08-26 20:54:34.642737 | mon1 |     raise InvalidPrerequisiteError(msg)
2026-08-26 20:54:34.644119 | mon1 | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv community.general:>=4.5.0, returned 1 code:
2026-08-26 20:54:34.644146 | mon1 | Starting galaxy collection install process
2026-08-26 20:54:34.644156 | mon1 | Process install dependency map
2026-08-26 20:54:34.644165 | mon1 | Starting collection install process
2026-08-26 20:54:34.644175 | mon1 | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/community-general-13.3.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1045e_v2_y19/tmp91d0wn1j/community-general-13.3.0-emi288zl
2026-08-26 20:54:34.644184 | mon1 | Installing 'community.general:13.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-08-26 20:54:34.644193 | mon1 | community.general:13.3.0 was installed successfully
2026-08-26 20:54:34.644202 | mon1 | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/community-library_inventory_filtering_v1-1.1.5.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1045e_v2_y19/tmp91d0wn1j/community-library_inventory_filtering_v1-1.1.5-dv576tps
2026-08-26 20:54:34.644211 | mon1 |
2026-08-26 20:54:34.644220 | mon1 | ansible-galaxy [core 2.21.3]
2026-08-26 20:54:34.644228 | mon1 |   config file = None
2026-08-26 20:54:34.644237 | mon1 |   configured module search path = ['/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/plugins/modules', '/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules']
2026-08-26 20:54:34.644245 | mon1 |   ansible python module location = /home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages/ansible
2026-08-26 20:54:34.644254 | mon1 |   ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/lib/python3.13/site-packages
2026-08-26 20:54:34.644263 | mon1 |   executable location = /home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/bin/ansible-galaxy
2026-08-26 20:54:34.644271 | mon1 |   python version = 3.13.7 (main, Aug 18 2025, 19:20:03) [Clang 20.1.4 ] (/home/zuul/src/github.com/vexxhost/ansible-collection-ceph/.venv/bin/python)
2026-08-26 20:54:34.644280 | mon1 |   jinja version = 3.1.6
2026-08-26 20:54:34.644288 | mon1 |   pyyaml version = 6.0.2 (with libyaml v0.2.5)
2026-08-26 20:54:34.644297 | mon1 | No config file found; using defaults
2026-08-26 20:54:34.644305 | mon1 | Created /home/zuul/.ansible/galaxy_token
2026-08-26 20:54:34.644314 | mon1 | Collection 'community.general:13.3.0' obtained from server default https://galaxy.ansible.com/api/
2026-08-26 20:54:34.644402 | mon1 | [ERROR]: Failed to download collection tar from 'default': <urlopen error [Errno -3] Temporary failure in name resolution>
2026-08-26 20:54:34.644414 | mon1 |
2026-08-26 20:54:34.644424 | mon1 | Failed to download collection tar from 'default': <urlopen error [Errno -3] Temporary failure in name resolution>
2026-08-26 20:54:34.644433 | mon1 |
2026-08-26 20:54:34.644441 | mon1 | <<< caused by >>>
2026-08-26 20:54:34.644450 | mon1 |
2026-08-26 20:54:34.644458 | mon1 | <urlopen error [Errno -3] Temporary failure in name resolution>
2026-08-26 20:54:34.644477 | mon1 |
2026-08-26 20:54:34.644486 | mon1 |
2026-08-26 20:54:45.170102 | mon1 | ERROR
2026-08-26 20:54:45.170305 | mon1 | {
2026-08-26 20:54:45.170337 | mon1 |   "delta": "0:01:07.898845",
2026-08-26 20:54:45.170359 | mon1 |   "end": "2026-08-26 20:54:34.752650",
2026-08-26 20:54:45.170379 | mon1 |   "msg": "non-zero return code",
2026-08-26 20:54:45.170397 | mon1 |   "rc": 1,
2026-08-26 20:54:45.170415 | mon1 |   "start": "2026-08-26 20:53:26.853805"
2026-08-26 20:54:45.170434 | mon1 | }
failure
2026-08-26 20:54:45.172943 | 
2026-08-26 20:54:45.173011 | PLAY RECAP
2026-08-26 20:54:45.173062 | mon1 | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-08-26 20:54:45.173084 | 
2026-08-26 20:54:45.305206 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-26 20:54:45.311185 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-26 20:54:45.972894 | 
2026-08-26 20:54:45.973051 | PLAY [all]
2026-08-26 20:54:45.984428 | 
2026-08-26 20:54:45.984506 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-26 20:54:46.040950 | mon1 | ok
2026-08-26 20:54:46.047644 | osd1 | ok
2026-08-26 20:54:46.053819 | 
2026-08-26 20:54:46.054294 | TASK [fetch-output : Set log path for single node]
2026-08-26 20:54:46.089004 | mon1 | skipping: Conditional result was False
2026-08-26 20:54:46.090932 | osd1 | skipping: Conditional result was False
2026-08-26 20:54:46.100946 | 
2026-08-26 20:54:46.101038 | LOOP [fetch-output : Ensure local output dirs]
2026-08-26 20:54:46.514976 | osd1 -> localhost | changed: "/var/lib/zuul/builds/8ff921f2324c4eea8a4063a4c5208d26/work/logs/osd1"
2026-08-26 20:54:46.517863 | mon1 -> localhost | changed: "/var/lib/zuul/builds/8ff921f2324c4eea8a4063a4c5208d26/work/logs/mon1"
2026-08-26 20:54:46.731469 | osd1 -> localhost | changed: "/var/lib/zuul/builds/8ff921f2324c4eea8a4063a4c5208d26/work/artifacts"
2026-08-26 20:54:46.755882 | mon1 -> localhost | ok: "/var/lib/zuul/builds/8ff921f2324c4eea8a4063a4c5208d26/work/artifacts"
2026-08-26 20:54:46.942275 | osd1 -> localhost | changed: "/var/lib/zuul/builds/8ff921f2324c4eea8a4063a4c5208d26/work/docs"
2026-08-26 20:54:46.962368 | mon1 -> localhost | ok: "/var/lib/zuul/builds/8ff921f2324c4eea8a4063a4c5208d26/work/docs"
2026-08-26 20:54:46.963012 | mon1 -> localhost | changed: All items complete
2026-08-26 20:54:46.963090 | 
2026-08-26 20:54:46.983216 | 
2026-08-26 20:54:46.983379 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-26 20:54:47.675492 | osd1 | changed: .d..t...... ./
2026-08-26 20:54:47.680164 | mon1 | changed: .d..t...... ./
2026-08-26 20:54:48.165219 | mon1 | changed: .d..t...... ./
2026-08-26 20:54:48.169750 | osd1 | ok
2026-08-26 20:54:48.627662 | mon1 | changed: .d..t...... ./
2026-08-26 20:54:48.637195 | osd1 | ok
2026-08-26 20:54:48.661367 | 
2026-08-26 20:54:48.661539 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-26 20:54:49.134497 | mon1 -> localhost | ok: Item: artifacts Runtime: 0:00:00.006128
2026-08-26 20:54:49.134749 | mon1 -> localhost | changed: All items complete
2026-08-26 20:54:49.134787 | 
2026-08-26 20:54:49.389871 | mon1 -> localhost | ok: Item: docs Runtime: 0:00:00.007981
2026-08-26 20:54:49.421076 | 
2026-08-26 20:54:49.421192 | PLAY [all]
2026-08-26 20:54:49.428868 | 
2026-08-26 20:54:49.428938 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-26 20:54:49.847608 | mon1 | changed
2026-08-26 20:54:50.039453 | osd1 | changed
2026-08-26 20:54:50.052580 | 
2026-08-26 20:54:50.052630 | PLAY RECAP
2026-08-26 20:54:50.052679 | mon1 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-26 20:54:50.052708 | osd1 | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-26 20:54:50.052727 | 
2026-08-26 20:54:50.190586 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-26 20:54:50.194371 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-26 20:54:50.810560 | 
2026-08-26 20:54:50.810709 | PLAY [localhost]
2026-08-26 20:54:50.820726 | 
2026-08-26 20:54:50.820798 | TASK [Generate Zuul manifest]
2026-08-26 20:54:50.846017 | localhost | ok
2026-08-26 20:54:50.866892 | 
2026-08-26 20:54:50.866996 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-26 20:54:51.252435 | localhost | changed
2026-08-26 20:54:51.268528 | 
2026-08-26 20:54:51.268797 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-26 20:54:51.305058 | localhost | ok
2026-08-26 20:54:51.316104 | 
2026-08-26 20:54:51.316201 | TASK [Upload logs]
2026-08-26 20:54:51.342461 | localhost | ok
2026-08-26 20:54:51.416907 | 
2026-08-26 20:54:51.417081 | TASK [Set zuul-log-path fact]
2026-08-26 20:54:51.440259 | localhost | ok
2026-08-26 20:54:51.455168 | 
2026-08-26 20:54:51.455243 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-26 20:54:51.487199 | localhost | ok
2026-08-26 20:54:51.496599 | 
2026-08-26 20:54:51.496672 | TASK [upload-logs : Create log directories]
2026-08-26 20:54:51.956063 | localhost | changed
2026-08-26 20:54:51.962105 | 
2026-08-26 20:54:51.962177 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-26 20:54:52.644829 | localhost -> localhost | ok: Runtime: 0:00:00.006494
2026-08-26 20:54:52.651785 | 
2026-08-26 20:54:52.651878 | TASK [upload-logs : Upload logs to log server]
2026-08-26 20:54:53.228277 | localhost | Output suppressed because no_log was given
2026-08-26 20:54:53.232337 | 
2026-08-26 20:54:53.232406 | LOOP [upload-logs : Compress console log and json output]
2026-08-26 20:54:53.318386 | localhost | skipping: Conditional result was False
2026-08-26 20:54:53.325941 | localhost | skipping: Conditional result was False
2026-08-26 20:54:53.342044 | 
2026-08-26 20:54:53.342237 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-26 20:54:53.442655 | localhost | skipping: Conditional result was False
2026-08-26 20:54:53.442937 | 
2026-08-26 20:54:53.449265 | localhost | skipping: Conditional result was False
2026-08-26 20:54:53.507552 | 
2026-08-26 20:54:53.520452 | LOOP [upload-logs : Upload console log and json output]