2026-09-15 11:17:01.353709 | Job console starting
2026-09-15 11:17:01.375020 | Updating git repos
2026-09-15 11:17:05.392800 | Cloning repos into workspace
2026-09-15 11:17:22.552170 | Restoring repo states
2026-09-15 11:17:32.724775 | Merging changes
2026-09-15 11:17:34.080001 | Checking out repos
2026-09-15 11:17:36.270802 | Preparing playbooks
2026-09-15 11:17:40.927094 | Running Ansible setup
2026-09-15 11:17:44.704040 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-15 11:17:45.350925 | 
2026-09-15 11:17:45.351108 | PLAY [localhost]
2026-09-15 11:17:45.360995 | 
2026-09-15 11:17:45.361087 | TASK [Gathering Facts]
2026-09-15 11:17:46.347257 | localhost | ok
2026-09-15 11:17:46.353670 | 
2026-09-15 11:17:46.353747 | TASK [Setup log path fact]
2026-09-15 11:17:46.375360 | localhost | ok
2026-09-15 11:17:46.391697 | 
2026-09-15 11:17:46.391797 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-15 11:17:46.434441 | localhost | ok
2026-09-15 11:17:46.447187 | 
2026-09-15 11:17:46.447390 | TASK [emit-job-header : Print job information]
2026-09-15 11:17:46.500785 | # Job Information
2026-09-15 11:17:46.501153 | Ansible Version: 2.16.18
2026-09-15 11:17:46.501278 | Job: atmosphere-molecule-csi-rbd
2026-09-15 11:17:46.501353 | Pipeline: check
2026-09-15 11:17:46.501413 | Executor: 2d72f0692154
2026-09-15 11:17:46.501469 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4090
2026-09-15 11:17:46.501536 | Event ID: 492e98f0-b0f6-11f1-9dd1-2131358dde72
2026-09-15 11:17:46.510310 | 
2026-09-15 11:17:46.510510 | LOOP [emit-job-header : Print node information]
2026-09-15 11:17:46.606867 | localhost | ok:
2026-09-15 11:17:46.607079 | localhost | # Node Information
2026-09-15 11:17:46.607118 | localhost | Inventory Hostname: instance
2026-09-15 11:17:46.607148 | localhost | Hostname: np0000192797
2026-09-15 11:17:46.607176 | localhost | Username: zuul
2026-09-15 11:17:46.607206 | localhost | Distro: Ubuntu 22.04
2026-09-15 11:17:46.607249 | localhost | Provider: yul1
2026-09-15 11:17:46.607280 | localhost | Region: ca-ymq-1
2026-09-15 11:17:46.607305 | localhost | Label: ubuntu-jammy
2026-09-15 11:17:46.607331 | localhost | Product Name: OpenStack Nova
2026-09-15 11:17:46.607355 | localhost | Interface IP: 162.253.55.135
2026-09-15 11:17:46.622551 | 
2026-09-15 11:17:46.622781 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-15 11:17:47.104748 | localhost -> localhost | changed
2026-09-15 11:17:47.114763 | 
2026-09-15 11:17:47.114932 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-15 11:17:47.990128 | localhost -> localhost | changed
2026-09-15 11:17:48.001126 | 
2026-09-15 11:17:48.001273 | PLAY [all]
2026-09-15 11:17:48.016569 | 
2026-09-15 11:17:48.016736 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-15 11:17:48.223643 | instance -> localhost | ok
2026-09-15 11:17:48.234389 | 
2026-09-15 11:17:48.234535 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-15 11:17:48.268792 | instance | ok
2026-09-15 11:17:48.293609 | instance | included: /var/lib/zuul/builds/7f544e7d58f84c99813d7cd2dacef52b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-15 11:17:48.301024 | 
2026-09-15 11:17:48.301112 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-15 11:17:49.973161 | instance -> localhost | Generating public/private rsa key pair.
2026-09-15 11:17:49.973374 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/7f544e7d58f84c99813d7cd2dacef52b/work/7f544e7d58f84c99813d7cd2dacef52b_id_rsa
2026-09-15 11:17:49.973412 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/7f544e7d58f84c99813d7cd2dacef52b/work/7f544e7d58f84c99813d7cd2dacef52b_id_rsa.pub
2026-09-15 11:17:49.973440 | instance -> localhost | The key fingerprint is:
2026-09-15 11:17:49.973466 | instance -> localhost | SHA256:uc3IdmHySDnlXpcH2PZe6E1Yf7RqeYxZ31lzbIK8QA0 zuul-build-sshkey
2026-09-15 11:17:49.973502 | instance -> localhost | The key's randomart image is:
2026-09-15 11:17:49.973527 | instance -> localhost | +---[RSA 3072]----+
2026-09-15 11:17:49.973559 | instance -> localhost | |          E      |
2026-09-15 11:17:49.973585 | instance -> localhost | |           o o   |
2026-09-15 11:17:49.973609 | instance -> localhost | |          o o + o|
2026-09-15 11:17:49.973633 | instance -> localhost | |         * . o Xo|
2026-09-15 11:17:49.973657 | instance -> localhost | |        S = + *+%|
2026-09-15 11:17:49.973679 | instance -> localhost | |       o % + +BBX|
2026-09-15 11:17:49.973702 | instance -> localhost | |        * * .*.+=|
2026-09-15 11:17:49.973725 | instance -> localhost | |       . .  . .  |
2026-09-15 11:17:49.973751 | instance -> localhost | |                 |
2026-09-15 11:17:49.973775 | instance -> localhost | +----[SHA256]-----+
2026-09-15 11:17:49.973833 | instance -> localhost | ok: Runtime: 0:00:01.247644
2026-09-15 11:17:49.982925 | 
2026-09-15 11:17:49.983017 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-15 11:17:50.018049 | instance | ok
2026-09-15 11:17:50.035211 | instance | included: /var/lib/zuul/builds/7f544e7d58f84c99813d7cd2dacef52b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-15 11:17:50.045514 | 
2026-09-15 11:17:50.045601 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-15 11:17:50.070623 | instance | skipping: Conditional result was False
2026-09-15 11:17:50.081947 | 
2026-09-15 11:17:50.082052 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-15 11:17:50.601322 | instance | changed
2026-09-15 11:17:50.616818 | 
2026-09-15 11:17:50.617001 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-15 11:17:50.833928 | instance | ok
2026-09-15 11:17:50.845667 | 
2026-09-15 11:17:50.845834 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-15 11:17:51.330486 | instance | changed
2026-09-15 11:17:51.345205 | 
2026-09-15 11:17:51.345549 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-15 11:17:51.892267 | instance | changed
2026-09-15 11:17:51.910959 | 
2026-09-15 11:17:51.911112 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-15 11:17:51.935320 | instance | skipping: Conditional result was False
2026-09-15 11:17:51.946445 | 
2026-09-15 11:17:51.946576 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-15 11:17:52.328613 | instance -> localhost | changed
2026-09-15 11:17:52.339474 | 
2026-09-15 11:17:52.339583 | TASK [add-build-sshkey : Add back temp key]
2026-09-15 11:17:52.691092 | instance -> localhost | Identity added: /var/lib/zuul/builds/7f544e7d58f84c99813d7cd2dacef52b/work/7f544e7d58f84c99813d7cd2dacef52b_id_rsa (zuul-build-sshkey)
2026-09-15 11:17:52.691428 | instance -> localhost | ok: Runtime: 0:00:00.013434
2026-09-15 11:17:52.701886 | 
2026-09-15 11:17:52.702021 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-15 11:17:53.062667 | instance | ok
2026-09-15 11:17:53.071879 | 
2026-09-15 11:17:53.072010 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-15 11:17:53.107635 | instance | skipping: Conditional result was False
2026-09-15 11:17:53.128075 | 
2026-09-15 11:17:53.128266 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-15 11:17:53.498057 | instance | ok
2026-09-15 11:17:53.510363 | 
2026-09-15 11:17:53.510490 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-15 11:17:57.286560 | instance | Output suppressed because no_log was given
2026-09-15 11:17:57.301623 | 
2026-09-15 11:17:57.301707 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-15 11:17:57.542712 | instance | ok: "logs"
2026-09-15 11:17:57.543392 | instance | ok: All items complete
2026-09-15 11:17:57.543465 | 
2026-09-15 11:17:57.716160 | instance | ok: "artifacts"
2026-09-15 11:17:57.891726 | instance | ok: "docs"
2026-09-15 11:17:57.908919 | 
2026-09-15 11:17:57.909130 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-15 11:17:58.131597 | instance | changed: "logs"
2026-09-15 11:17:58.304568 | instance | changed: "artifacts"
2026-09-15 11:17:58.482672 | instance | changed: "docs"
2026-09-15 11:17:58.503190 | 
2026-09-15 11:17:58.503381 | PLAY RECAP
2026-09-15 11:17:58.503469 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-15 11:17:58.503525 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 11:17:58.503570 | 
2026-09-15 11:17:58.658657 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-15 11:17:58.663612 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-15 11:17:59.242389 | 
2026-09-15 11:17:59.242503 | PLAY [all]
2026-09-15 11:17:59.252206 | 
2026-09-15 11:17:59.252288 | TASK [setup-uv : Extract archive]
2026-09-15 11:18:01.531946 | instance | changed
2026-09-15 11:18:01.543292 | 
2026-09-15 11:18:01.543448 | TASK [setup-uv : Print version]
2026-09-15 11:18:01.907031 | instance | uv 0.8.13
2026-09-15 11:18:02.106139 | instance | ok: Runtime: 0:00:00.012029
2026-09-15 11:18:02.124741 | 
2026-09-15 11:18:02.125042 | TASK [Install Ansible collection siblings]
2026-09-15 11:18:12.094467 | instance | changed:
2026-09-15 11:18:12.094753 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-15 11:18:12.094812 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-15 11:18:12.094856 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-15 11:18:12.094895 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-15 11:18:12.094933 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-15 11:18:12.094980 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-15 11:18:12.095020 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-15 11:18:12.095063 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-15 11:18:12.095114 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-15 11:18:12.095169 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-15 11:18:12.095226 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-15 11:18:12.095301 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-15 11:18:12.095342 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-09-15 11:18:12.095380 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-15 11:18:12.095417 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0
2026-09-15 11:18:12.095454 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-15 11:18:12.095491 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-15 11:18:12.095528 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-15 11:18:12.095563 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-15 11:18:12.095598 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-09-15 11:18:12.095633 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.1.2
2026-09-15 11:18:12.095669 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-09-15 11:18:12.095709 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-15 11:18:12.095765 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-15 11:18:12.095803 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0
2026-09-15 11:18:12.095839 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-15 11:18:12.095875 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-15 11:18:12.095911 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-15 11:18:12.095947 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-15 11:18:12.095982 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-15 11:18:12.096018 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-15 11:18:12.096056 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-15 11:18:12.096093 | instance | Checked out ansible.netcommon to 1.2.0
2026-09-15 11:18:12.096129 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-15 11:18:12.096177 | instance | Starting galaxy collection install process
2026-09-15 11:18:12.096214 | instance | Process install dependency map
2026-09-15 11:18:12.096266 | instance | Starting collection install process
2026-09-15 11:18:12.096304 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-15 11:18:12.096341 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-15 11:18:12.096377 | instance | ansible.netcommon:* was installed successfully
2026-09-15 11:18:12.096412 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-15 11:18:12.096448 | instance | Creating virtual environment at: .venv
2026-09-15 11:18:12.096483 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-15 11:18:12.096553 | instance | Downloading setuptools (1.1MiB)
2026-09-15 11:18:12.096591 | instance | Downloading pygments (1.2MiB)
2026-09-15 11:18:12.096627 | instance | Downloading cryptography (4.2MiB)
2026-09-15 11:18:12.096663 | instance | Downloading netaddr (2.2MiB)
2026-09-15 11:18:12.096699 | instance | Downloading kubernetes (1.9MiB)
2026-09-15 11:18:12.096734 | instance | Downloading ansible-core (2.1MiB)
2026-09-15 11:18:12.096770 | instance | Downloading openstacksdk (1.7MiB)
2026-09-15 11:18:12.096805 | instance | Downloading rjsonnet (1.2MiB)
2026-09-15 11:18:12.096840 | instance |    Building pyperclip==1.9.0
2026-09-15 11:18:12.096876 | instance |  Downloading rjsonnet
2026-09-15 11:18:12.096916 | instance |  Downloading netaddr
2026-09-15 11:18:12.096954 | instance |  Downloading cryptography
2026-09-15 11:18:12.096991 | instance |  Downloading pygments
2026-09-15 11:18:12.097027 | instance |  Downloading setuptools
2026-09-15 11:18:12.097062 | instance |  Downloading kubernetes
2026-09-15 11:18:12.097097 | instance |  Downloading ansible-core
2026-09-15 11:18:12.097132 | instance |  Downloading openstacksdk
2026-09-15 11:18:12.097167 | instance |       Built pyperclip==1.9.0
2026-09-15 11:18:12.097203 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-15 11:18:12.097239 | instance | Installed 79 packages in 160ms
2026-09-15 11:18:12.097297 | instance | Checked out ansible.posix to 1.6.0
2026-09-15 11:18:12.097335 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-15 11:18:12.097371 | instance | Starting galaxy collection install process
2026-09-15 11:18:12.097406 | instance | Process install dependency map
2026-09-15 11:18:12.097441 | instance | Starting collection install process
2026-09-15 11:18:12.097476 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-15 11:18:12.097521 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-15 11:18:12.097558 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-15 11:18:12.097594 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 11:18:12.097630 | instance | 2.17.12
2026-09-15 11:18:12.097668 | instance | Checked out ansible.utils to v6.1.0
2026-09-15 11:18:12.097704 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-15 11:18:12.097740 | instance | Starting galaxy collection install process
2026-09-15 11:18:12.097777 | instance | Process install dependency map
2026-09-15 11:18:12.097811 | instance | Starting collection install process
2026-09-15 11:18:12.097847 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-15 11:18:12.097882 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-15 11:18:12.097918 | instance | ansible.utils:6.1.0 was installed successfully
2026-09-15 11:18:12.097954 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 11:18:12.097991 | instance | 2.17.12
2026-09-15 11:18:12.098028 | instance | Checked out atmosphere.common to v0.7.0
2026-09-15 11:18:12.098063 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-15 11:18:12.098099 | instance | Starting galaxy collection install process
2026-09-15 11:18:12.098133 | instance | Process install dependency map
2026-09-15 11:18:12.098167 | instance | Starting collection install process
2026-09-15 11:18:12.098202 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-15 11:18:12.098237 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-15 11:18:12.098294 | instance | atmosphere.common:0.7.0 was installed successfully
2026-09-15 11:18:12.098330 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 11:18:12.098365 | instance | 2.17.12
2026-09-15 11:18:12.098401 | instance | Checked out community.crypto to 2.2.3
2026-09-15 11:18:12.098437 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-15 11:18:12.098473 | instance | Starting galaxy collection install process
2026-09-15 11:18:12.098509 | instance | Process install dependency map
2026-09-15 11:18:12.098544 | instance | Starting collection install process
2026-09-15 11:18:12.098579 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-15 11:18:12.098614 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-15 11:18:12.098649 | instance | community.crypto:2.2.3 was installed successfully
2026-09-15 11:18:12.098684 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 11:18:12.098718 | instance | 2.17.12
2026-09-15 11:18:12.098753 | instance | Checked out community.general to 7.3.0
2026-09-15 11:18:12.098788 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general
2026-09-15 11:18:12.098823 | instance | Starting galaxy collection install process
2026-09-15 11:18:12.098868 | instance | Process install dependency map
2026-09-15 11:18:12.098904 | instance | Starting collection install process
2026-09-15 11:18:12.098940 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-15 11:18:12.098975 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-15 11:18:12.099009 | instance | community.general:7.3.0 was installed successfully
2026-09-15 11:18:12.099045 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 11:18:12.099080 | instance | 2.17.12
2026-09-15 11:18:12.099116 | instance | Checked out community.mysql to 3.6.0
2026-09-15 11:18:12.099152 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-15 11:18:12.099189 | instance | Starting galaxy collection install process
2026-09-15 11:18:12.099228 | instance | Process install dependency map
2026-09-15 11:18:12.099288 | instance | Starting collection install process
2026-09-15 11:18:12.099326 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-15 11:18:12.099361 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-15 11:18:12.099396 | instance | community.mysql:3.6.0 was installed successfully
2026-09-15 11:18:12.099431 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 11:18:12.099466 | instance | 2.17.12
2026-09-15 11:18:12.099501 | instance | Checked out kubernetes.core to 2.4.0
2026-09-15 11:18:12.099537 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-15 11:18:12.099573 | instance | Starting galaxy collection install process
2026-09-15 11:18:12.099608 | instance | Process install dependency map
2026-09-15 11:18:12.099643 | instance | Starting collection install process
2026-09-15 11:18:12.099677 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-15 11:18:12.099713 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-15 11:18:12.099759 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-15 11:18:12.099859 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 11:18:12.099923 | instance | 2.17.12
2026-09-15 11:18:12.099985 | instance | Checked out openstack.cloud to 2.6.0
2026-09-15 11:18:12.100036 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-15 11:18:12.100087 | instance | Starting galaxy collection install process
2026-09-15 11:18:12.100349 | instance | Process install dependency map
2026-09-15 11:18:12.100422 | instance | Starting collection install process
2026-09-15 11:18:12.100467 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-15 11:18:12.100538 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-15 11:18:12.100592 | instance | openstack.cloud:2.6.0 was installed successfully
2026-09-15 11:18:12.100632 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 11:18:12.100670 | instance | 2.17.12
2026-09-15 11:18:12.100710 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-15 11:18:12.100747 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-15 11:18:12.100797 | instance | Starting galaxy collection install process
2026-09-15 11:18:12.100841 | instance | Process install dependency map
2026-09-15 11:18:12.100897 | instance | Starting collection install process
2026-09-15 11:18:12.100973 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-15 11:18:12.101022 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-15 11:18:12.101075 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-15 11:18:12.101127 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 11:18:12.101169 | instance | 2.17.12
2026-09-15 11:18:12.101209 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-15 11:18:12.101271 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-15 11:18:12.101325 | instance | Starting galaxy collection install process
2026-09-15 11:18:12.101382 | instance | Process install dependency map
2026-09-15 11:18:12.101427 | instance | Starting collection install process
2026-09-15 11:18:12.101465 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-15 11:18:12.101504 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-15 11:18:12.101542 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-15 11:18:12.101579 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 11:18:12.101616 | instance | 2.17.12
2026-09-15 11:18:12.101666 | instance | Checked out vexxhost.kubernetes to v3.4.0
2026-09-15 11:18:12.101717 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-15 11:18:12.101758 | instance | Starting galaxy collection install process
2026-09-15 11:18:12.101796 | instance | Process install dependency map
2026-09-15 11:18:12.101835 | instance | Starting collection install process
2026-09-15 11:18:12.101873 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-15 11:18:12.101910 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-15 11:18:12.101983 | instance | vexxhost.kubernetes:3.4.0 was installed successfully
2026-09-15 11:18:12.102024 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-15 11:18:12.102070 | instance | 2.17.12
2026-09-15 11:18:12.112647 | 
2026-09-15 11:18:12.112716 | PLAY RECAP
2026-09-15 11:18:12.112785 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 11:18:12.112818 | 
2026-09-15 11:18:12.269821 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-15 11:18:12.272179 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-15 11:18:12.889826 | 
2026-09-15 11:18:12.952058 | PLAY [all]
2026-09-15 11:18:12.969767 | 
2026-09-15 11:18:12.969957 | TASK [Install "jq" for log collection]
2026-09-15 11:18:23.015632 | instance | changed
2026-09-15 11:18:23.017575 | 
2026-09-15 11:18:23.017635 | PLAY RECAP
2026-09-15 11:18:23.017685 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 11:18:23.017739 | 
2026-09-15 11:18:23.164830 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-15 11:18:23.168202 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-15 11:18:23.815328 | 
2026-09-15 11:18:23.815487 | PLAY [all]
2026-09-15 11:18:23.826810 | 
2026-09-15 11:18:23.826886 | LOOP [Check inventory directories]
2026-09-15 11:18:24.238070 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-15 11:18:24.413901 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-15 11:18:24.556818 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-15 11:18:24.574643 | 
2026-09-15 11:18:24.574963 | LOOP [Copy inventory file for Zuul]
2026-09-15 11:18:25.489958 | instance | changed:
2026-09-15 11:18:25.490181 | instance | {
2026-09-15 11:18:25.490208 | instance |   "ansible_loop_var": "item",
2026-09-15 11:18:25.490229 | instance |   "changed": false,
2026-09-15 11:18:25.490247 | instance |   "failed": false,
2026-09-15 11:18:25.490280 | instance |   "invocation": {
2026-09-15 11:18:25.490298 | instance |     "module_args": {
2026-09-15 11:18:25.490323 | instance |       "checksum_algorithm": "sha1",
2026-09-15 11:18:25.490345 | instance |       "follow": true,
2026-09-15 11:18:25.490366 | instance |       "get_attributes": true,
2026-09-15 11:18:25.490389 | instance |       "get_checksum": true,
2026-09-15 11:18:25.490411 | instance |       "get_mime": true,
2026-09-15 11:18:25.490431 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-15 11:18:25.490450 | instance |     }
2026-09-15 11:18:25.490465 | instance |   },
2026-09-15 11:18:25.490480 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-15 11:18:25.490495 | instance |   "stat": {
2026-09-15 11:18:25.490509 | instance |     "atime": 1789471084.829919,
2026-09-15 11:18:25.490524 | instance |     "attr_flags": "e",
2026-09-15 11:18:25.490539 | instance |     "attributes": [
2026-09-15 11:18:25.490554 | instance |       "extents"
2026-09-15 11:18:25.490569 | instance |     ],
2026-09-15 11:18:25.490584 | instance |     "block_size": 4096,
2026-09-15 11:18:25.490599 | instance |     "blocks": 8,
2026-09-15 11:18:25.490614 | instance |     "charset": "unknown",
2026-09-15 11:18:25.490718 | instance |     "ctime": 1789471083.0218542,
2026-09-15 11:18:25.492303 | instance |     "dev": 64513,
2026-09-15 11:18:25.492327 | instance |     "device_type": 0,
2026-09-15 11:18:25.492344 | instance |     "executable": true,
2026-09-15 11:18:25.492360 | instance |     "exists": true,
2026-09-15 11:18:25.492375 | instance |     "gid": 1000,
2026-09-15 11:18:25.492390 | instance |     "gr_name": "zuul",
2026-09-15 11:18:25.492405 | instance |     "inode": 1048862,
2026-09-15 11:18:25.492419 | instance |     "isblk": false,
2026-09-15 11:18:25.492435 | instance |     "ischr": false,
2026-09-15 11:18:25.492450 | instance |     "isdir": true,
2026-09-15 11:18:25.492464 | instance |     "isfifo": false,
2026-09-15 11:18:25.492479 | instance |     "isgid": false,
2026-09-15 11:18:25.492508 | instance |     "islnk": false,
2026-09-15 11:18:25.492524 | instance |     "isreg": false,
2026-09-15 11:18:25.492539 | instance |     "issock": false,
2026-09-15 11:18:25.492553 | instance |     "isuid": false,
2026-09-15 11:18:25.492566 | instance |     "mimetype": "unknown",
2026-09-15 11:18:25.492591 | instance |     "mode": "0755",
2026-09-15 11:18:25.492610 | instance |     "mtime": 1789471083.0218542,
2026-09-15 11:18:25.492641 | instance |     "nlink": 21,
2026-09-15 11:18:25.492667 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-15 11:18:25.492687 | instance |     "pw_name": "zuul",
2026-09-15 11:18:25.492712 | instance |     "readable": true,
2026-09-15 11:18:25.492728 | instance |     "rgrp": true,
2026-09-15 11:18:25.492750 | instance |     "roth": true,
2026-09-15 11:18:25.492766 | instance |     "rusr": true,
2026-09-15 11:18:25.492780 | instance |     "size": 4096,
2026-09-15 11:18:25.492795 | instance |     "uid": 1000,
2026-09-15 11:18:25.492824 | instance |     "version": "2871192463",
2026-09-15 11:18:25.492839 | instance |     "wgrp": false,
2026-09-15 11:18:25.492856 | instance |     "woth": false,
2026-09-15 11:18:25.492871 | instance |     "writeable": true,
2026-09-15 11:18:25.492888 | instance |     "wusr": true,
2026-09-15 11:18:25.492908 | instance |     "xgrp": true,
2026-09-15 11:18:25.492926 | instance |     "xoth": true,
2026-09-15 11:18:25.492940 | instance |     "xusr": true
2026-09-15 11:18:25.492954 | instance |   }
2026-09-15 11:18:25.492969 | instance | }
2026-09-15 11:18:25.493002 | instance | ok: All items complete
2026-09-15 11:18:25.493021 | 
2026-09-15 11:18:25.494268 | instance | skipping: Conditional result was False
2026-09-15 11:18:25.503276 | instance | skipping: Conditional result was False
2026-09-15 11:18:25.518674 | 
2026-09-15 11:18:25.532721 | LOOP [Switch "ansible_host" to private IP]
2026-09-15 11:18:25.894926 | instance | changed: 1 replacements made
2026-09-15 11:18:25.905728 | instance | skipping: Conditional result was False
2026-09-15 11:18:25.911692 | instance | skipping: Conditional result was False
2026-09-15 11:18:25.927217 | 
2026-09-15 11:18:25.927487 | TASK [Run Molecule scenario]
2026-09-15 11:18:26.918034 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-15 11:18:27.330045 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-15 11:18:27.330141 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-15 11:18:38.564037 | instance | INFO     [csi > dependency] Executing
2026-09-15 11:18:38.564440 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-15 11:18:38.564912 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-15 11:18:38.565146 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-15 11:18:38.580181 | instance | INFO     [csi > cleanup] Executing
2026-09-15 11:18:38.580840 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-15 11:18:38.593527 | instance | INFO     [csi > destroy] Executing
2026-09-15 11:18:38.593626 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-15 11:18:38.593740 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-15 11:18:38.602997 | instance | INFO     [csi > syntax] Executing
2026-09-15 11:18:40.174155 | instance |
2026-09-15 11:18:40.174413 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-15 11:18:40.260754 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-15 11:18:40.277685 | instance | INFO     [csi > create] Executing
2026-09-15 11:18:40.281504 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-15 11:18:40.296243 | instance | INFO     [csi > prepare] Executing
2026-09-15 11:18:41.125803 | instance |
2026-09-15 11:18:41.125894 | instance | PLAY [Prepare] *****************************************************************
2026-09-15 11:18:41.126075 | instance |
2026-09-15 11:18:41.126294 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 11:18:41.126474 | instance | Tuesday 15 September 2026  11:18:41 +0000 (0:00:00.026)       0:00:00.026 *****
2026-09-15 11:18:42.401435 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-15 11:18:42.401653 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-15 11:18:42.401924 | instance | interpreter could change the meaning of that path. See
2026-09-15 11:18:42.402169 | instance | https://docs.ansible.com/ansible-
2026-09-15 11:18:42.402408 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-15 11:18:42.409768 | instance | ok: [instance]
2026-09-15 11:18:42.410051 | instance |
2026-09-15 11:18:42.410212 | instance | TASK [Configure short hostname] ************************************************
2026-09-15 11:18:42.410444 | instance | Tuesday 15 September 2026  11:18:42 +0000 (0:00:01.288)       0:00:01.314 *****
2026-09-15 11:18:43.239135 | instance | changed: [instance]
2026-09-15 11:18:43.239391 | instance |
2026-09-15 11:18:43.239404 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-15 11:18:43.239442 | instance | Tuesday 15 September 2026  11:18:43 +0000 (0:00:00.829)       0:00:02.143 *****
2026-09-15 11:18:43.553693 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-15 11:18:43.553962 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-15 11:18:43.554211 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-15 11:18:43.558328 | instance | changed: [instance]
2026-09-15 11:18:43.558565 | instance |
2026-09-15 11:18:43.558850 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-15 11:18:43.559101 | instance | Tuesday 15 September 2026  11:18:43 +0000 (0:00:00.319)       0:00:02.463 *****
2026-09-15 11:18:44.501312 | instance | ok: [instance]
2026-09-15 11:18:44.501994 | instance |
2026-09-15 11:18:44.502016 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-15 11:18:44.502090 | instance |
2026-09-15 11:18:44.502338 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 11:18:44.502651 | instance | Tuesday 15 September 2026  11:18:44 +0000 (0:00:00.941)       0:00:03.405 *****
2026-09-15 11:18:45.232965 | instance | ok: [instance]
2026-09-15 11:18:45.233203 | instance |
2026-09-15 11:18:45.233544 | instance | TASK [Install depedencies] *****************************************************
2026-09-15 11:18:45.233870 | instance | Tuesday 15 September 2026  11:18:45 +0000 (0:00:00.731)       0:00:04.136 *****
2026-09-15 11:19:08.690979 | instance | changed: [instance]
2026-09-15 11:19:08.691039 | instance |
2026-09-15 11:19:08.691051 | instance | TASK [Start up service] ********************************************************
2026-09-15 11:19:08.691069 | instance | Tuesday 15 September 2026  11:19:08 +0000 (0:00:23.457)       0:00:27.594 *****
2026-09-15 11:19:09.266114 | instance | ok: [instance]
2026-09-15 11:19:09.266167 | instance |
2026-09-15 11:19:09.266179 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-15 11:19:09.266190 | instance | Tuesday 15 September 2026  11:19:09 +0000 (0:00:00.575)       0:00:28.169 *****
2026-09-15 11:19:09.581696 | instance | ok: [instance]
2026-09-15 11:19:09.582037 | instance |
2026-09-15 11:19:09.582371 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-15 11:19:09.582676 | instance | Tuesday 15 September 2026  11:19:09 +0000 (0:00:00.316)       0:00:28.485 *****
2026-09-15 11:19:10.353454 | instance | changed: [instance]
2026-09-15 11:19:10.353861 | instance |
2026-09-15 11:19:10.354213 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-15 11:19:10.354582 | instance | Tuesday 15 September 2026  11:19:10 +0000 (0:00:00.771)       0:00:29.257 *****
2026-09-15 11:19:10.551762 | instance | ok: [instance]
2026-09-15 11:19:10.551865 | instance |
2026-09-15 11:19:10.551931 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-15 11:19:10.552050 | instance | Tuesday 15 September 2026  11:19:10 +0000 (0:00:00.199)       0:00:29.456 *****
2026-09-15 11:19:10.580521 | instance | skipping: [instance]
2026-09-15 11:19:10.580832 | instance |
2026-09-15 11:19:10.581215 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-15 11:19:10.581595 | instance | Tuesday 15 September 2026  11:19:10 +0000 (0:00:00.027)       0:00:29.484 *****
2026-09-15 11:19:11.176430 | instance | changed: [instance] => (item=osd0)
2026-09-15 11:19:11.176496 | instance | changed: [instance] => (item=osd1)
2026-09-15 11:19:11.176507 | instance | changed: [instance] => (item=osd2)
2026-09-15 11:19:11.176516 | instance |
2026-09-15 11:19:11.176526 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-15 11:19:11.176537 | instance | Tuesday 15 September 2026  11:19:11 +0000 (0:00:00.594)       0:00:30.079 *****
2026-09-15 11:19:11.836779 | instance | changed: [instance] => (item=osd0)
2026-09-15 11:19:11.837450 | instance | changed: [instance] => (item=osd1)
2026-09-15 11:19:11.837554 | instance | changed: [instance] => (item=osd2)
2026-09-15 11:19:11.837577 | instance |
2026-09-15 11:19:11.838124 | instance | TASK [Start loop devices] ******************************************************
2026-09-15 11:19:11.838188 | instance | Tuesday 15 September 2026  11:19:11 +0000 (0:00:00.661)       0:00:30.740 *****
2026-09-15 11:19:12.655857 | instance | changed: [instance] => (item=osd0)
2026-09-15 11:19:12.657202 | instance | changed: [instance] => (item=osd1)
2026-09-15 11:19:12.657285 | instance | changed: [instance] => (item=osd2)
2026-09-15 11:19:12.657301 | instance |
2026-09-15 11:19:12.657312 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-15 11:19:12.657325 | instance | Tuesday 15 September 2026  11:19:12 +0000 (0:00:00.818)       0:00:31.559 *****
2026-09-15 11:19:16.029045 | instance | changed: [instance] => (item=osd0)
2026-09-15 11:19:16.029359 | instance | changed: [instance] => (item=osd1)
2026-09-15 11:19:16.029439 | instance | changed: [instance] => (item=osd2)
2026-09-15 11:19:16.029635 | instance |
2026-09-15 11:19:16.029801 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-15 11:19:16.029960 | instance | Tuesday 15 September 2026  11:19:16 +0000 (0:00:03.374)       0:00:34.933 *****
2026-09-15 11:19:18.077773 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-15 11:19:18.078106 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-15 11:19:18.078501 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-15 11:19:18.078805 | instance |
2026-09-15 11:19:18.079148 | instance | PLAY RECAP *********************************************************************
2026-09-15 11:19:18.079495 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-15 11:19:18.079784 | instance |
2026-09-15 11:19:18.080103 | instance | Tuesday 15 September 2026  11:19:18 +0000 (0:00:02.048)       0:00:36.982 *****
2026-09-15 11:19:18.080456 | instance | ===============================================================================
2026-09-15 11:19:18.080735 | instance | Install depedencies ---------------------------------------------------- 23.46s
2026-09-15 11:19:18.081034 | instance | Create a volume group for each loop device ------------------------------ 3.37s
2026-09-15 11:19:18.081403 | instance | Create a logical volume for each loop device ---------------------------- 2.05s
2026-09-15 11:19:18.081749 | instance | Gathering Facts --------------------------------------------------------- 1.29s
2026-09-15 11:19:18.082060 | instance | Purge "snapd" package --------------------------------------------------- 0.94s
2026-09-15 11:19:18.082305 | instance | Configure short hostname ------------------------------------------------ 0.83s
2026-09-15 11:19:18.082535 | instance | Start loop devices ------------------------------------------------------ 0.82s
2026-09-15 11:19:18.082760 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.77s
2026-09-15 11:19:18.083082 | instance | Gathering Facts --------------------------------------------------------- 0.73s
2026-09-15 11:19:18.083367 | instance | Set permissions on loopback devices ------------------------------------- 0.66s
2026-09-15 11:19:18.083611 | instance | Create devices for Ceph ------------------------------------------------- 0.59s
2026-09-15 11:19:18.083842 | instance | Start up service -------------------------------------------------------- 0.58s
2026-09-15 11:19:18.084080 | instance | Ensure hostname inside hosts file --------------------------------------- 0.32s
2026-09-15 11:19:18.084341 | instance | Generate lvm.conf ------------------------------------------------------- 0.32s
2026-09-15 11:19:18.084592 | instance | Get list of all loopback devices ---------------------------------------- 0.20s
2026-09-15 11:19:18.084860 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s
2026-09-15 11:19:18.184022 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-15 11:19:18.200663 | instance | INFO     [csi > converge] Executing
2026-09-15 11:19:19.615467 | instance |
2026-09-15 11:19:19.615692 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-15 11:19:19.615919 | instance |
2026-09-15 11:19:19.616179 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 11:19:19.616414 | instance | Tuesday 15 September 2026  11:19:19 +0000 (0:00:00.009)       0:00:00.009 *****
2026-09-15 11:19:20.679321 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-15 11:19:20.679549 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-15 11:19:20.679814 | instance | interpreter could change the meaning of that path. See
2026-09-15 11:19:20.680056 | instance | https://docs.ansible.com/ansible-
2026-09-15 11:19:20.680327 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-15 11:19:20.693521 | instance | ok: [instance]
2026-09-15 11:19:20.693717 | instance |
2026-09-15 11:19:20.693963 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-15 11:19:20.694293 | instance | Tuesday 15 September 2026  11:19:20 +0000 (0:00:01.078)       0:00:01.087 *****
2026-09-15 11:19:20.755049 | instance | ok: [instance] => {
2026-09-15 11:19:20.755293 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-15 11:19:20.755630 | instance | }
2026-09-15 11:19:20.755901 | instance |
2026-09-15 11:19:20.756313 | instance | PLAY [all] *********************************************************************
2026-09-15 11:19:20.756610 | instance |
2026-09-15 11:19:20.756928 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 11:19:20.757193 | instance | Tuesday 15 September 2026  11:19:20 +0000 (0:00:00.061)       0:00:01.149 *****
2026-09-15 11:19:21.738205 | instance | ok: [instance]
2026-09-15 11:19:21.738470 | instance |
2026-09-15 11:19:21.738750 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-15 11:19:21.739008 | instance | Tuesday 15 September 2026  11:19:21 +0000 (0:00:00.982)       0:00:02.131 *****
2026-09-15 11:19:21.949989 | instance | ok: [instance]
2026-09-15 11:19:21.950271 | instance |
2026-09-15 11:19:21.950531 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-15 11:19:21.950758 | instance |
2026-09-15 11:19:21.951013 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 11:19:21.951334 | instance | Tuesday 15 September 2026  11:19:21 +0000 (0:00:00.212)       0:00:02.344 *****
2026-09-15 11:19:22.926876 | instance | ok: [instance]
2026-09-15 11:19:22.927330 | instance |
2026-09-15 11:19:22.927706 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-15 11:19:22.928040 | instance | Tuesday 15 September 2026  11:19:22 +0000 (0:00:00.975)       0:00:03.320 *****
2026-09-15 11:19:23.258005 | instance | ok: [instance]
2026-09-15 11:19:23.258127 | instance |
2026-09-15 11:19:23.258290 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-15 11:19:23.258539 | instance | Tuesday 15 September 2026  11:19:23 +0000 (0:00:00.329)       0:00:03.650 *****
2026-09-15 11:19:23.322130 | instance | skipping: [instance]
2026-09-15 11:19:23.322222 | instance |
2026-09-15 11:19:23.322384 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-15 11:19:23.322631 | instance | Tuesday 15 September 2026  11:19:23 +0000 (0:00:00.064)       0:00:03.714 *****
2026-09-15 11:19:23.622203 | instance | changed: [instance]
2026-09-15 11:19:23.622363 | instance |
2026-09-15 11:19:23.622555 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-15 11:19:23.622803 | instance | Tuesday 15 September 2026  11:19:23 +0000 (0:00:00.300)       0:00:04.015 *****
2026-09-15 11:19:23.722320 | instance | ok: [instance] => {
2026-09-15 11:19:23.722624 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-15 11:19:23.723004 | instance | }
2026-09-15 11:19:23.723339 | instance |
2026-09-15 11:19:23.723700 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-15 11:19:23.724090 | instance | Tuesday 15 September 2026  11:19:23 +0000 (0:00:00.099)       0:00:04.115 *****
2026-09-15 11:19:24.422123 | instance | changed: [instance]
2026-09-15 11:19:24.422360 | instance |
2026-09-15 11:19:24.422719 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-15 11:19:24.423049 | instance | Tuesday 15 September 2026  11:19:24 +0000 (0:00:00.697)       0:00:04.813 *****
2026-09-15 11:19:24.492597 | instance | skipping: [instance]
2026-09-15 11:19:24.492854 | instance |
2026-09-15 11:19:24.493139 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-15 11:19:24.493435 | instance | Tuesday 15 September 2026  11:19:24 +0000 (0:00:00.070)       0:00:04.884 *****
2026-09-15 11:19:24.555365 | instance | skipping: [instance]
2026-09-15 11:19:24.555648 | instance |
2026-09-15 11:19:24.555896 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-15 11:19:24.556159 | instance | Tuesday 15 September 2026  11:19:24 +0000 (0:00:00.065)       0:00:04.949 *****
2026-09-15 11:19:24.798275 | instance | ok: [instance]
2026-09-15 11:19:24.798545 | instance |
2026-09-15 11:19:24.798775 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-15 11:19:24.798981 | instance | Tuesday 15 September 2026  11:19:24 +0000 (0:00:00.242)       0:00:05.192 *****
2026-09-15 11:19:26.107711 | instance | ok: [instance]
2026-09-15 11:19:26.107895 | instance |
2026-09-15 11:19:26.108064 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-15 11:19:26.108204 | instance | Tuesday 15 September 2026  11:19:26 +0000 (0:00:01.309)       0:00:06.501 *****
2026-09-15 11:19:26.198442 | instance | ok: [instance] => {
2026-09-15 11:19:26.198919 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-15 11:19:26.199331 | instance | }
2026-09-15 11:19:26.199620 | instance |
2026-09-15 11:19:26.199922 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-15 11:19:26.200247 | instance | Tuesday 15 September 2026  11:19:26 +0000 (0:00:00.090)       0:00:06.592 *****
2026-09-15 11:19:26.922360 | instance | changed: [instance]
2026-09-15 11:19:26.922541 | instance |
2026-09-15 11:19:26.922687 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-15 11:19:26.922829 | instance | Tuesday 15 September 2026  11:19:26 +0000 (0:00:00.723)       0:00:07.316 *****
2026-09-15 11:19:29.910798 | instance | changed: [instance]
2026-09-15 11:19:29.910863 | instance |
2026-09-15 11:19:29.910876 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-15 11:19:29.910888 | instance | Tuesday 15 September 2026  11:19:29 +0000 (0:00:02.987)       0:00:10.303 *****
2026-09-15 11:19:29.954158 | instance | skipping: [instance]
2026-09-15 11:19:29.954866 | instance |
2026-09-15 11:19:29.955101 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-15 11:19:29.955116 | instance | Tuesday 15 September 2026  11:19:29 +0000 (0:00:00.044)       0:00:10.347 *****
2026-09-15 11:19:30.000302 | instance | skipping: [instance]
2026-09-15 11:19:30.001175 | instance |
2026-09-15 11:19:30.001265 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-15 11:19:30.001277 | instance | Tuesday 15 September 2026  11:19:29 +0000 (0:00:00.046)       0:00:10.393 *****
2026-09-15 11:19:30.040021 | instance | skipping: [instance]
2026-09-15 11:19:30.040534 | instance |
2026-09-15 11:19:30.040648 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-15 11:19:30.040658 | instance | Tuesday 15 September 2026  11:19:30 +0000 (0:00:00.040)       0:00:10.434 *****
2026-09-15 11:19:35.973903 | instance | changed: [instance]
2026-09-15 11:19:35.974008 | instance |
2026-09-15 11:19:35.974296 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-15 11:19:35.974349 | instance | Tuesday 15 September 2026  11:19:35 +0000 (0:00:05.933)       0:00:16.367 *****
2026-09-15 11:19:36.691147 | instance | changed: [instance]
2026-09-15 11:19:36.691913 | instance |
2026-09-15 11:19:36.692164 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-15 11:19:36.692178 | instance | Tuesday 15 September 2026  11:19:36 +0000 (0:00:00.716)       0:00:17.084 *****
2026-09-15 11:19:37.751456 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-15 11:19:37.753427 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-15 11:19:37.753535 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-15 11:19:37.753547 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-15 11:19:37.753559 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-15 11:19:37.753569 | instance |
2026-09-15 11:19:37.753600 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-15 11:19:37.753620 | instance | Tuesday 15 September 2026  11:19:37 +0000 (0:00:01.060)       0:00:18.145 *****
2026-09-15 11:19:38.405020 | instance | changed: [instance]
2026-09-15 11:19:38.405124 | instance |
2026-09-15 11:19:38.405445 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-15 11:19:38.405557 | instance | Tuesday 15 September 2026  11:19:38 +0000 (0:00:00.627)       0:00:18.772 *****
2026-09-15 11:19:38.405569 | instance |
2026-09-15 11:19:38.405909 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-15 11:19:38.405971 | instance | Tuesday 15 September 2026  11:19:38 +0000 (0:00:00.026)       0:00:18.798 *****
2026-09-15 11:19:39.491848 | instance | ok: [instance]
2026-09-15 11:19:39.492615 | instance |
2026-09-15 11:19:39.492688 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-15 11:19:39.492702 | instance | Tuesday 15 September 2026  11:19:39 +0000 (0:00:01.086)       0:00:19.885 *****
2026-09-15 11:19:40.002719 | instance | changed: [instance]
2026-09-15 11:19:40.002930 | instance |
2026-09-15 11:19:40.003509 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-15 11:19:40.003570 | instance | Tuesday 15 September 2026  11:19:39 +0000 (0:00:00.510)       0:00:20.396 *****
2026-09-15 11:19:40.639043 | instance | changed: [instance]
2026-09-15 11:19:40.639131 | instance |
2026-09-15 11:19:40.639451 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-15 11:19:40.639504 | instance | Tuesday 15 September 2026  11:19:40 +0000 (0:00:00.636)       0:00:21.033 *****
2026-09-15 11:19:40.894516 | instance | ok: [instance]
2026-09-15 11:19:40.894862 | instance |
2026-09-15 11:19:40.895123 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-15 11:19:40.895369 | instance | Tuesday 15 September 2026  11:19:40 +0000 (0:00:00.253)       0:00:21.287 *****
2026-09-15 11:19:40.955257 | instance | ok: [instance] => {
2026-09-15 11:19:40.955596 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-15 11:19:40.956079 | instance | }
2026-09-15 11:19:40.956304 | instance |
2026-09-15 11:19:40.956543 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-15 11:19:40.956923 | instance | Tuesday 15 September 2026  11:19:40 +0000 (0:00:00.062)       0:00:21.349 *****
2026-09-15 11:19:44.020730 | instance | changed: [instance]
2026-09-15 11:19:44.020850 | instance |
2026-09-15 11:19:44.020982 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-15 11:19:44.021134 | instance | Tuesday 15 September 2026  11:19:44 +0000 (0:00:03.064)       0:00:24.413 *****
2026-09-15 11:19:48.388536 | instance | changed: [instance]
2026-09-15 11:19:48.388629 | instance |
2026-09-15 11:19:48.388643 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-15 11:19:48.388657 | instance | Tuesday 15 September 2026  11:19:48 +0000 (0:00:04.367)       0:00:28.781 *****
2026-09-15 11:19:49.552084 | instance | ok: [instance]
2026-09-15 11:19:49.552251 | instance |
2026-09-15 11:19:49.552942 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-15 11:19:49.553018 | instance | Tuesday 15 September 2026  11:19:49 +0000 (0:00:01.164)       0:00:29.945 *****
2026-09-15 11:19:49.967488 | instance | changed: [instance]
2026-09-15 11:19:49.967602 | instance |
2026-09-15 11:19:49.967615 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-15 11:19:49.967736 | instance | Tuesday 15 September 2026  11:19:49 +0000 (0:00:00.416)       0:00:30.361 *****
2026-09-15 11:19:50.519529 | instance | changed: [instance]
2026-09-15 11:19:50.520251 | instance |
2026-09-15 11:19:50.520324 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-15 11:19:50.520337 | instance | Tuesday 15 September 2026  11:19:50 +0000 (0:00:00.551)       0:00:30.913 *****
2026-09-15 11:19:51.149785 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-15 11:19:51.149913 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-15 11:19:51.149977 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-15 11:19:51.150327 | instance |
2026-09-15 11:19:51.150392 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-15 11:19:51.150398 | instance | Tuesday 15 September 2026  11:19:51 +0000 (0:00:00.630)       0:00:31.543 *****
2026-09-15 11:19:51.660688 | instance | changed: [instance]
2026-09-15 11:19:51.661414 | instance |
2026-09-15 11:19:51.661505 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-15 11:19:51.661532 | instance | Tuesday 15 September 2026  11:19:51 +0000 (0:00:00.510)       0:00:32.054 *****
2026-09-15 11:19:52.150341 | instance | changed: [instance]
2026-09-15 11:19:52.150409 | instance |
2026-09-15 11:19:52.150967 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-15 11:19:52.151016 | instance | Tuesday 15 September 2026  11:19:52 +0000 (0:00:00.474)       0:00:32.528 *****
2026-09-15 11:19:52.151022 | instance |
2026-09-15 11:19:52.151027 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-15 11:19:52.151031 | instance | Tuesday 15 September 2026  11:19:52 +0000 (0:00:00.016)       0:00:32.544 *****
2026-09-15 11:19:52.858099 | instance | ok: [instance]
2026-09-15 11:19:52.858390 | instance |
2026-09-15 11:19:52.858402 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-15 11:19:52.858418 | instance | Tuesday 15 September 2026  11:19:52 +0000 (0:00:00.706)       0:00:33.251 *****
2026-09-15 11:19:53.875828 | instance | changed: [instance]
2026-09-15 11:19:53.875901 | instance |
2026-09-15 11:19:53.876283 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-15 11:19:53.876340 | instance | Tuesday 15 September 2026  11:19:53 +0000 (0:00:01.018)       0:00:34.270 *****
2026-09-15 11:19:54.513978 | instance | changed: [instance]
2026-09-15 11:19:54.514068 | instance |
2026-09-15 11:19:54.514397 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-15 11:19:54.514437 | instance | Tuesday 15 September 2026  11:19:54 +0000 (0:00:00.638)       0:00:34.908 *****
2026-09-15 11:19:54.588417 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-15 11:19:54.589190 | instance |
2026-09-15 11:19:54.589278 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-15 11:19:54.589293 | instance | Tuesday 15 September 2026  11:19:54 +0000 (0:00:00.073)       0:00:34.982 *****
2026-09-15 11:20:00.171475 | instance | changed: [instance]
2026-09-15 11:20:00.171580 | instance |
2026-09-15 11:20:00.172007 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-15 11:20:00.172077 | instance | Tuesday 15 September 2026  11:20:00 +0000 (0:00:05.583)       0:00:40.565 *****
2026-09-15 11:20:00.844625 | instance | ok: [instance] => (item=chronyd)
2026-09-15 11:20:00.844714 | instance | ok: [instance] => (item=sshd)
2026-09-15 11:20:00.844773 | instance |
2026-09-15 11:20:00.845012 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-15 11:20:00.845062 | instance | Tuesday 15 September 2026  11:20:00 +0000 (0:00:00.673)       0:00:41.238 *****
2026-09-15 11:20:16.451160 | instance | changed: [instance]
2026-09-15 11:20:16.452156 | instance |
2026-09-15 11:20:16.452231 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-09-15 11:20:16.452243 | instance | Tuesday 15 September 2026  11:20:16 +0000 (0:00:15.605)       0:00:56.844 *****
2026-09-15 11:20:16.699802 | instance | ok: [instance]
2026-09-15 11:20:16.699901 | instance |
2026-09-15 11:20:16.699918 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-09-15 11:20:16.700100 | instance | Tuesday 15 September 2026  11:20:16 +0000 (0:00:00.249)       0:00:57.093 *****
2026-09-15 11:20:17.218003 | instance | changed: [instance]
2026-09-15 11:20:17.218112 | instance |
2026-09-15 11:20:17.218231 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-09-15 11:20:17.218423 | instance | Tuesday 15 September 2026  11:20:17 +0000 (0:00:00.518)       0:00:57.612 *****
2026-09-15 11:20:17.629201 | instance | changed: [instance]
2026-09-15 11:20:17.629326 | instance |
2026-09-15 11:20:17.629631 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-09-15 11:20:17.629811 | instance | Tuesday 15 September 2026  11:20:17 +0000 (0:00:00.411)       0:00:58.023 *****
2026-09-15 11:20:17.763869 | instance | ok: [instance]
2026-09-15 11:20:17.764018 | instance |
2026-09-15 11:20:17.764650 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-09-15 11:20:17.764726 | instance | Tuesday 15 September 2026  11:20:17 +0000 (0:00:00.133)       0:00:58.157 *****
2026-09-15 11:20:18.026829 | instance | ok: [instance] => (item=instance)
2026-09-15 11:20:18.026926 | instance |
2026-09-15 11:20:18.026942 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-09-15 11:20:18.027090 | instance | Tuesday 15 September 2026  11:20:18 +0000 (0:00:00.263)       0:00:58.421 *****
2026-09-15 11:20:18.094272 | instance | ok: [instance]
2026-09-15 11:20:18.094758 | instance |
2026-09-15 11:20:18.094815 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-09-15 11:20:18.094827 | instance | Tuesday 15 September 2026  11:20:18 +0000 (0:00:00.067)       0:00:58.488 *****
2026-09-15 11:20:18.186381 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-09-15 11:20:18.186467 | instance |
2026-09-15 11:20:18.186777 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-09-15 11:20:18.186828 | instance | Tuesday 15 September 2026  11:20:18 +0000 (0:00:00.092)       0:00:58.580 *****
2026-09-15 11:20:18.516094 | instance | changed: [instance]
2026-09-15 11:20:18.517000 | instance |
2026-09-15 11:20:18.517035 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-09-15 11:20:18.517046 | instance | Tuesday 15 September 2026  11:20:18 +0000 (0:00:00.328)       0:00:58.909 *****
2026-09-15 11:20:19.311310 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-09-15 11:20:19.312154 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-09-15 11:20:19.312218 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-09-15 11:20:19.312239 | instance |
2026-09-15 11:20:19.312252 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-09-15 11:20:19.312260 | instance | Tuesday 15 September 2026  11:20:19 +0000 (0:00:00.795)       0:00:59.704 *****
2026-09-15 11:21:55.124532 | instance | ok: [instance]
2026-09-15 11:21:55.124942 | instance |
2026-09-15 11:21:55.125699 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-09-15 11:21:55.128924 | instance | Tuesday 15 September 2026  11:21:55 +0000 (0:01:35.813)       0:02:35.517 *****
2026-09-15 11:21:55.365332 | instance | changed: [instance]
2026-09-15 11:21:55.365648 | instance |
2026-09-15 11:21:55.365951 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-09-15 11:21:55.366185 | instance | Tuesday 15 September 2026  11:21:55 +0000 (0:00:00.241)       0:02:35.759 *****
2026-09-15 11:21:55.405824 | instance | ok: [instance]
2026-09-15 11:21:55.406118 | instance |
2026-09-15 11:21:55.406371 | instance | TASK [Install Ceph host] *******************************************************
2026-09-15 11:21:55.406669 | instance | Tuesday 15 September 2026  11:21:55 +0000 (0:00:00.040)       0:02:35.799 *****
2026-09-15 11:21:55.490928 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-09-15 11:21:55.490982 | instance |
2026-09-15 11:21:55.491082 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-09-15 11:21:55.491187 | instance | Tuesday 15 September 2026  11:21:55 +0000 (0:00:00.085)       0:02:35.885 *****
2026-09-15 11:21:57.163166 | instance | ok: [instance]
2026-09-15 11:21:57.163494 | instance |
2026-09-15 11:21:57.163508 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-09-15 11:21:57.163601 | instance | Tuesday 15 September 2026  11:21:57 +0000 (0:00:01.671)       0:02:37.556 *****
2026-09-15 11:21:57.236607 | instance | ok: [instance] => (item=instance)
2026-09-15 11:21:57.236765 | instance |
2026-09-15 11:21:57.237026 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-09-15 11:21:57.237293 | instance | Tuesday 15 September 2026  11:21:57 +0000 (0:00:00.073)       0:02:37.630 *****
2026-09-15 11:21:57.659001 | instance | ok: [instance]
2026-09-15 11:21:57.659184 | instance |
2026-09-15 11:21:57.659434 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-09-15 11:21:57.659701 | instance | Tuesday 15 September 2026  11:21:57 +0000 (0:00:00.422)       0:02:38.052 *****
2026-09-15 11:22:00.189029 | instance | ok: [instance]
2026-09-15 11:22:00.189125 | instance |
2026-09-15 11:22:00.189139 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-09-15 11:22:00.189151 | instance | Tuesday 15 September 2026  11:22:00 +0000 (0:00:02.528)       0:02:40.581 *****
2026-09-15 11:22:48.050832 | instance | 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-09-15 11:22:48.051470 | instance | fatal: [instance]: 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_ey_efbxy/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_ey_efbxy/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_ey_efbxy/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_ey_efbxy/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/4837cbf8-4f90-4300-b3f6-726c9b9f89b4/mon.instance/config\n2026-09-15T11:22:47.828+0000 7f94fad8d640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAmKqlqiCR/KiAA8iAtWscw9mF5xtiZqg8mi+TUyfBP7qDiYFq1f3EQ+r4=: Malformed input [buffer:3]\n2026-09-15T11:22:47.828+0000 7f94fad8d640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-15T11:22:47.828+0000 7f94fad8d640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAmKqlqiCR/KiAA8iAtWscw9mF5xtiZqg8mi+TUyfBP7qDiYFq1f3EQ+r4=: Malformed input [buffer:3]\n2026-09-15T11:22:47.828+0000 7f94fad8d640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-15T11:22:47.832+0000 7f94fad8d640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAmKqlqiCR/KiAA8iAtWscw9mF5xtiZqg8mi+TUyfBP7qDiYFq1f3EQ+r4=: Malformed input [buffer:3]\n2026-09-15T11:22:47.832+0000 7f94fad8d640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-15T11:22:47.832+0000 7f94fad8d640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1}
2026-09-15 11:22:48.055173 | instance |
2026-09-15 11:22:48.057374 | instance | PLAY RECAP *********************************************************************
2026-09-15 11:22:48.057444 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2026-09-15 11:22:48.057457 | instance |
2026-09-15 11:22:48.057467 | instance | Tuesday 15 September 2026  11:22:48 +0000 (0:00:47.868)       0:03:28.449 *****
2026-09-15 11:22:48.057477 | instance | ===============================================================================
2026-09-15 11:22:48.057487 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 95.81s
2026-09-15 11:22:48.057496 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 47.87s
2026-09-15 11:22:48.057506 | instance | vexxhost.ceph.cephadm : Download "cephadm" ----------------------------- 15.61s
2026-09-15 11:22:48.057522 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.93s
2026-09-15 11:22:48.061330 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.58s
2026-09-15 11:22:48.061395 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.37s
2026-09-15 11:22:48.061406 | instance | vexxhost.containers.download_artifact : Download item ------------------- 3.06s
2026-09-15 11:22:48.061415 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.99s
2026-09-15 11:22:48.061449 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.53s
2026-09-15 11:22:48.061462 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.79s
2026-09-15 11:22:48.061475 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.67s
2026-09-15 11:22:48.061487 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.31s
2026-09-15 11:22:48.061498 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.16s
2026-09-15 11:22:48.061506 | instance | Gathering Facts --------------------------------------------------------- 1.08s
2026-09-15 11:22:48.061515 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.06s
2026-09-15 11:22:48.061524 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 1.02s
2026-09-15 11:22:48.061532 | instance | Gathering Facts --------------------------------------------------------- 0.98s
2026-09-15 11:22:48.061541 | instance | Gathering Facts --------------------------------------------------------- 0.98s
2026-09-15 11:22:48.061549 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.80s
2026-09-15 11:22:48.061558 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.72s
2026-09-15 11:22:48.186758 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-15 11:22:48.187002 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-15 11:22:48.187146 | instance | ERROR    Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-15 11:22:48.322130 | instance | ERROR
2026-09-15 11:22:48.322366 | instance | {
2026-09-15 11:22:48.322441 | instance |   "delta": "0:04:21.940942",
2026-09-15 11:22:48.322472 | instance |   "end": "2026-09-15 11:22:48.269567",
2026-09-15 11:22:48.322499 | instance |   "msg": "non-zero return code",
2026-09-15 11:22:48.322525 | instance |   "rc": 2,
2026-09-15 11:22:48.322551 | instance |   "start": "2026-09-15 11:18:26.328625"
2026-09-15 11:22:48.322576 | instance | }
failure
2026-09-15 11:22:48.328787 | 
2026-09-15 11:22:48.328860 | PLAY RECAP
2026-09-15 11:22:48.328916 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-15 11:22:48.328948 | 
2026-09-15 11:22:48.483453 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-15 11:22:48.490244 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-15 11:22:49.271723 | 
2026-09-15 11:22:49.271874 | PLAY [all]
2026-09-15 11:22:49.288851 | 
2026-09-15 11:22:49.288987 | TASK [gather-host-logs : creating directory for system status]
2026-09-15 11:22:49.688554 | instance | changed
2026-09-15 11:22:49.701050 | 
2026-09-15 11:22:49.701249 | TASK [gather-host-logs : Get logs for each host]
2026-09-15 11:22:50.086009 | instance | + systemd-cgls --full --all --no-pager
2026-09-15 11:22:50.101498 | instance | + ip addr
2026-09-15 11:22:50.104182 | instance | + ip route
2026-09-15 11:22:50.107782 | instance | + lsblk
2026-09-15 11:22:50.114211 | instance | + mount
2026-09-15 11:22:50.117541 | instance | + docker images
2026-09-15 11:22:50.141438 | instance | + brctl show
2026-09-15 11:22:50.142028 | instance | /bin/bash: line 8: brctl: command not found
2026-09-15 11:22:50.142316 | instance | + ip neigh
2026-09-15 11:22:50.145547 | instance | + bridge fdb show
2026-09-15 11:22:50.149171 | instance | + ps aux --sort=-%mem
2026-09-15 11:22:50.169542 | instance | + dpkg -l
2026-09-15 11:22:50.178086 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-15 11:22:50.178608 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-15 11:22:50.199291 | instance | + '[' '!' -z '' ']'
2026-09-15 11:22:50.254887 | instance | ok: Runtime: 0:00:00.118484
2026-09-15 11:22:50.267947 | 
2026-09-15 11:22:50.268184 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-15 11:22:50.960619 | instance | changed:
2026-09-15 11:22:50.960793 | instance | created directory /var/lib/zuul/builds/7f544e7d58f84c99813d7cd2dacef52b/work/logs/instance
2026-09-15 11:22:50.960819 | instance | cd+++++++++ system/
2026-09-15 11:22:50.960838 | instance | >f+++++++++ system/brctl-show.txt
2026-09-15 11:22:50.960856 | instance | >f+++++++++ system/bridge-fdb.txt
2026-09-15 11:22:50.960873 | instance | >f+++++++++ system/docker-images.txt
2026-09-15 11:22:50.960893 | instance | >f+++++++++ system/ip-addr.txt
2026-09-15 11:22:50.960911 | instance | >f+++++++++ system/ip-neigh.txt
2026-09-15 11:22:50.960928 | instance | >f+++++++++ system/ip-route.txt
2026-09-15 11:22:50.960946 | instance | >f+++++++++ system/lsblk.txt
2026-09-15 11:22:50.960963 | instance | >f+++++++++ system/mount.txt
2026-09-15 11:22:50.960980 | instance | >f+++++++++ system/packages.txt
2026-09-15 11:22:50.960996 | instance | >f+++++++++ system/ps.txt
2026-09-15 11:22:50.961012 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-15 11:22:50.969032 | 
2026-09-15 11:22:50.969099 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-15 11:22:51.177676 | instance | changed: "values"
2026-09-15 11:22:51.387830 | instance | changed: "releases"
2026-09-15 11:22:51.405793 | 
2026-09-15 11:22:51.406011 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-15 11:22:51.665383 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-15 11:22:51.959830 | instance | ok: Runtime: 0:00:00.005428
2026-09-15 11:22:51.969828 | 
2026-09-15 11:22:51.969960 | TASK [helm-release-status : Downloads logs to executor]
2026-09-15 11:22:52.514468 | instance | changed:
2026-09-15 11:22:52.514709 | instance | cd+++++++++ helm/
2026-09-15 11:22:52.514769 | instance | cd+++++++++ helm/releases/
2026-09-15 11:22:52.514809 | instance | cd+++++++++ helm/values/
2026-09-15 11:22:52.532035 | 
2026-09-15 11:22:52.532159 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-15 11:22:52.757981 | instance | changed
2026-09-15 11:22:52.768598 | 
2026-09-15 11:22:52.768764 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-15 11:22:53.076714 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 11:22:53.077893 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 11:22:53.082609 | instance | environment: line 1: kubectl: command not found
2026-09-15 11:22:53.083162 | instance | environment: line 1: kubectl: command not found
2026-09-15 11:22:53.084052 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 11:22:53.084677 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 11:22:53.088211 | instance | environment: line 1: kubectl: command not found
2026-09-15 11:22:53.088746 | instance | environment: line 1: kubectl: command not found
2026-09-15 11:22:53.089677 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 11:22:53.090113 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 11:22:53.092928 | instance | environment: line 1: kubectl: command not found
2026-09-15 11:22:53.094240 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 11:22:53.311094 | instance | ok: Runtime: 0:00:00.024000
2026-09-15 11:22:53.321113 | 
2026-09-15 11:22:53.321302 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-15 11:22:53.569303 | instance | changed
2026-09-15 11:22:53.580165 | 
2026-09-15 11:22:53.580306 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-15 11:22:53.836768 | instance | environment: line 5: kubectl: command not found
2026-09-15 11:22:53.837812 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 11:22:53.837923 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 11:22:53.838105 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 11:22:54.128914 | instance | ok: Runtime: 0:00:00.008315
2026-09-15 11:22:54.140589 | 
2026-09-15 11:22:54.140890 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-15 11:22:54.704006 | instance | changed:
2026-09-15 11:22:54.704272 | instance | cd+++++++++ objects/
2026-09-15 11:22:54.704325 | instance | cd+++++++++ objects/cluster/
2026-09-15 11:22:54.704366 | instance | cd+++++++++ objects/namespaced/
2026-09-15 11:22:54.722837 | 
2026-09-15 11:22:54.722978 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-15 11:22:54.941678 | instance | changed
2026-09-15 11:22:54.951799 | 
2026-09-15 11:22:54.951932 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-15 11:22:55.180966 | instance | changed
2026-09-15 11:22:55.191019 | 
2026-09-15 11:22:55.191163 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-15 11:22:55.464513 | instance | environment: line 3: kubectl: command not found
2026-09-15 11:22:55.741603 | instance | ok: Runtime: 0:00:00.009546
2026-09-15 11:22:55.752992 | 
2026-09-15 11:22:55.753328 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-15 11:22:56.308615 | instance | changed:
2026-09-15 11:22:56.308910 | instance | cd+++++++++ pod-logs/
2026-09-15 11:22:56.308970 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-15 11:22:56.328652 | 
2026-09-15 11:22:56.328840 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-15 11:22:56.566887 | instance | changed
2026-09-15 11:22:56.577041 | 
2026-09-15 11:22:56.577178 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-15 11:22:56.835806 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-15 11:22:57.127425 | instance | ok: Runtime: 0:00:00.050998
2026-09-15 11:22:57.136830 | 
2026-09-15 11:22:57.136986 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-15 11:22:57.406091 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-15 11:22:57.436987 | instance | ceph-mgr endpoints:
2026-09-15 11:22:57.691775 | instance | ok: Runtime: 0:00:00.037118
2026-09-15 11:22:57.700002 | 
2026-09-15 11:22:57.700133 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-15 11:22:57.953963 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-15 11:22:58.246604 | instance | ok: Runtime: 0:00:00.056251
2026-09-15 11:22:58.257259 | 
2026-09-15 11:22:58.257427 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-15 11:22:58.845954 | instance | changed: cd+++++++++ prometheus/
2026-09-15 11:22:58.865697 | 
2026-09-15 11:22:58.865962 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-15 11:22:59.100785 | instance | changed
2026-09-15 11:22:59.108261 | 
2026-09-15 11:22:59.108358 | TASK [gather-selenium-data : Get selenium data]
2026-09-15 11:22:59.394672 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-15 11:22:59.396149 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-15 11:22:59.649097 | instance | ERROR
2026-09-15 11:22:59.649404 | instance | {
2026-09-15 11:22:59.649465 | instance |   "delta": "0:00:00.005085",
2026-09-15 11:22:59.649506 | instance |   "end": "2026-09-15 11:22:59.396447",
2026-09-15 11:22:59.649543 | instance |   "msg": "non-zero return code",
2026-09-15 11:22:59.649579 | instance |   "rc": 1,
2026-09-15 11:22:59.649614 | instance |   "start": "2026-09-15 11:22:59.391362"
2026-09-15 11:22:59.649649 | instance | }
2026-09-15 11:22:59.649695 | instance | ERROR: Ignoring Errors
2026-09-15 11:22:59.659060 | 
2026-09-15 11:22:59.659201 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-15 11:23:00.235712 | instance | changed: cd+++++++++ selenium/
2026-09-15 11:23:00.245696 | 
2026-09-15 11:23:00.245788 | PLAY RECAP
2026-09-15 11:23:00.245862 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-15 11:23:00.245896 | 
2026-09-15 11:23:00.402074 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-15 11:23:00.405970 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-15 11:23:01.070523 | 
2026-09-15 11:23:01.070672 | PLAY [all]
2026-09-15 11:23:01.083170 | 
2026-09-15 11:23:01.083255 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-15 11:23:01.160768 | instance | skipping: Conditional result was False
2026-09-15 11:23:01.167515 | 
2026-09-15 11:23:01.167618 | TASK [fetch-output : Set log path for single node]
2026-09-15 11:23:01.202901 | instance | ok
2026-09-15 11:23:01.215632 | 
2026-09-15 11:23:01.215820 | LOOP [fetch-output : Ensure local output dirs]
2026-09-15 11:23:01.653618 | instance -> localhost | ok: "/var/lib/zuul/builds/7f544e7d58f84c99813d7cd2dacef52b/work/logs"
2026-09-15 11:23:01.973831 | instance -> localhost | changed: "/var/lib/zuul/builds/7f544e7d58f84c99813d7cd2dacef52b/work/artifacts"
2026-09-15 11:23:02.236784 | instance -> localhost | changed: "/var/lib/zuul/builds/7f544e7d58f84c99813d7cd2dacef52b/work/docs"
2026-09-15 11:23:02.260593 | 
2026-09-15 11:23:02.260763 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-15 11:23:03.196619 | instance | changed: .d..t...... ./
2026-09-15 11:23:03.196897 | instance | changed: All items complete
2026-09-15 11:23:03.196935 | 
2026-09-15 11:23:03.713865 | instance | changed: .d..t...... ./
2026-09-15 11:23:04.263426 | instance | changed: .d..t...... ./
2026-09-15 11:23:04.302225 | 
2026-09-15 11:23:04.302459 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-15 11:23:04.881684 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.027437
2026-09-15 11:23:05.129025 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.010277
2026-09-15 11:23:05.147750 | 
2026-09-15 11:23:05.148279 | PLAY [all]
2026-09-15 11:23:05.157766 | 
2026-09-15 11:23:05.157869 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-15 11:23:05.614318 | instance | changed
2026-09-15 11:23:05.626100 | 
2026-09-15 11:23:05.626274 | PLAY RECAP
2026-09-15 11:23:05.626454 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-15 11:23:05.626511 | 
2026-09-15 11:23:05.853455 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-15 11:23:05.878505 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-15 11:23:06.589242 | 
2026-09-15 11:23:06.589420 | PLAY [localhost]
2026-09-15 11:23:06.599804 | 
2026-09-15 11:23:06.599995 | TASK [Generate Zuul manifest]
2026-09-15 11:23:06.619767 | localhost | ok
2026-09-15 11:23:06.635881 | 
2026-09-15 11:23:06.636016 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-15 11:23:07.049479 | localhost | changed
2026-09-15 11:23:07.082461 | 
2026-09-15 11:23:07.082572 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-15 11:23:07.110517 | localhost | ok
2026-09-15 11:23:07.120004 | 
2026-09-15 11:23:07.120092 | TASK [Upload logs]
2026-09-15 11:23:07.139823 | localhost | ok
2026-09-15 11:23:07.233310 | 
2026-09-15 11:23:07.233463 | TASK [Set zuul-log-path fact]
2026-09-15 11:23:07.263696 | localhost | ok
2026-09-15 11:23:07.287090 | 
2026-09-15 11:23:07.287239 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-15 11:23:07.325156 | localhost | ok
2026-09-15 11:23:07.332027 | 
2026-09-15 11:23:07.332101 | TASK [upload-logs : Create log directories]
2026-09-15 11:23:07.774359 | localhost | changed
2026-09-15 11:23:07.778968 | 
2026-09-15 11:23:07.779033 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-15 11:23:08.208265 | localhost -> localhost | ok: Runtime: 0:00:00.006684
2026-09-15 11:23:08.220237 | 
2026-09-15 11:23:08.220372 | TASK [upload-logs : Upload logs to log server]
2026-09-15 11:23:08.722350 | localhost | Output suppressed because no_log was given
2026-09-15 11:23:08.785468 | 
2026-09-15 11:23:08.785625 | LOOP [upload-logs : Compress console log and json output]
2026-09-15 11:23:08.838488 | localhost | skipping: Conditional result was False
2026-09-15 11:23:08.847757 | localhost | skipping: Conditional result was False
2026-09-15 11:23:08.859904 | 
2026-09-15 11:23:08.860104 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-15 11:23:08.923344 | localhost | skipping: Conditional result was False
2026-09-15 11:23:08.923947 | 
2026-09-15 11:23:08.929698 | localhost | skipping: Conditional result was False
2026-09-15 11:23:08.945377 | 
2026-09-15 11:23:08.945672 | LOOP [upload-logs : Upload console log and json output]