2026-08-22 02:41:00.742397 | Job console starting
2026-08-22 02:41:00.773144 | Updating git repos
2026-08-22 02:41:05.670888 | Cloning repos into workspace
2026-08-22 02:41:26.448106 | Restoring repo states
2026-08-22 02:41:39.982000 | Merging changes
2026-08-22 02:41:43.849804 | Checking out repos
2026-08-22 02:41:48.369386 | Preparing playbooks
2026-08-22 02:41:54.263825 | Running Ansible setup
2026-08-22 02:41:57.720080 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-22 02:41:58.292160 | 
2026-08-22 02:41:58.292341 | PLAY [localhost]
2026-08-22 02:41:58.300694 | 
2026-08-22 02:41:58.300831 | TASK [Gathering Facts]
2026-08-22 02:41:59.211503 | localhost | ok
2026-08-22 02:41:59.221089 | 
2026-08-22 02:41:59.221192 | TASK [Setup log path fact]
2026-08-22 02:41:59.239425 | localhost | ok
2026-08-22 02:41:59.251811 | 
2026-08-22 02:41:59.251885 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-22 02:41:59.282063 | localhost | ok
2026-08-22 02:41:59.290494 | 
2026-08-22 02:41:59.290591 | TASK [emit-job-header : Print job information]
2026-08-22 02:41:59.331773 | # Job Information
2026-08-22 02:41:59.331950 | Ansible Version: 2.16.18
2026-08-22 02:41:59.331992 | Job: atmosphere-molecule-csi-rbd
2026-08-22 02:41:59.332022 | Pipeline: check
2026-08-22 02:41:59.332050 | Executor: 2d72f0692154
2026-08-22 02:41:59.332077 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3501
2026-08-22 02:41:59.332107 | Event ID: 2bae1d00-9dd2-11f1-935b-dfeac00acc97
2026-08-22 02:41:59.335901 | 
2026-08-22 02:41:59.335990 | LOOP [emit-job-header : Print node information]
2026-08-22 02:41:59.434618 | localhost | ok:
2026-08-22 02:41:59.434847 | localhost | # Node Information
2026-08-22 02:41:59.434889 | localhost | Inventory Hostname: instance
2026-08-22 02:41:59.434917 | localhost | Hostname: np0000190407
2026-08-22 02:41:59.434943 | localhost | Username: zuul
2026-08-22 02:41:59.434969 | localhost | Distro: Ubuntu 22.04
2026-08-22 02:41:59.434995 | localhost | Provider: yul1
2026-08-22 02:41:59.435019 | localhost | Region: ca-ymq-1
2026-08-22 02:41:59.435042 | localhost | Label: ubuntu-jammy
2026-08-22 02:41:59.435065 | localhost | Product Name: OpenStack Nova
2026-08-22 02:41:59.435088 | localhost | Interface IP: 199.19.213.161
2026-08-22 02:41:59.455347 | 
2026-08-22 02:41:59.455506 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-22 02:41:59.839531 | localhost -> localhost | changed
2026-08-22 02:41:59.846697 | 
2026-08-22 02:41:59.846792 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-22 02:42:00.753554 | localhost -> localhost | changed
2026-08-22 02:42:00.763458 | 
2026-08-22 02:42:00.763539 | PLAY [all]
2026-08-22 02:42:00.772447 | 
2026-08-22 02:42:00.772517 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-22 02:42:00.987203 | instance -> localhost | ok
2026-08-22 02:42:00.997501 | 
2026-08-22 02:42:00.997593 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-22 02:42:01.021149 | instance | ok
2026-08-22 02:42:01.034569 | instance | included: /var/lib/zuul/builds/4f2bdffe677a4dd38bf8aa864ee66d14/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-22 02:42:01.039946 | 
2026-08-22 02:42:01.040006 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-22 02:42:02.518103 | instance -> localhost | Generating public/private rsa key pair.
2026-08-22 02:42:02.518305 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/4f2bdffe677a4dd38bf8aa864ee66d14/work/4f2bdffe677a4dd38bf8aa864ee66d14_id_rsa
2026-08-22 02:42:02.518348 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/4f2bdffe677a4dd38bf8aa864ee66d14/work/4f2bdffe677a4dd38bf8aa864ee66d14_id_rsa.pub
2026-08-22 02:42:02.518381 | instance -> localhost | The key fingerprint is:
2026-08-22 02:42:02.518412 | instance -> localhost | SHA256:BkdCYWhYiCNEaC1M/lWCRNhsay0CmJ6rlIwHbJLoqjg zuul-build-sshkey
2026-08-22 02:42:02.518460 | instance -> localhost | The key's randomart image is:
2026-08-22 02:42:02.518491 | instance -> localhost | +---[RSA 3072]----+
2026-08-22 02:42:02.518528 | instance -> localhost | |B*B*o+=o.        |
2026-08-22 02:42:02.518560 | instance -> localhost | |X=o*o.oo         |
2026-08-22 02:42:02.518591 | instance -> localhost | |B++.o.. .        |
2026-08-22 02:42:02.518622 | instance -> localhost | |=*.+.. o         |
2026-08-22 02:42:02.518653 | instance -> localhost | |*.=..   S        |
2026-08-22 02:42:02.518682 | instance -> localhost | |.*.    .         |
2026-08-22 02:42:02.518710 | instance -> localhost | |+.               |
2026-08-22 02:42:02.518739 | instance -> localhost | |E                |
2026-08-22 02:42:02.518771 | instance -> localhost | |+.               |
2026-08-22 02:42:02.518801 | instance -> localhost | +----[SHA256]-----+
2026-08-22 02:42:02.518867 | instance -> localhost | ok: Runtime: 0:00:01.079172
2026-08-22 02:42:02.526877 | 
2026-08-22 02:42:02.526955 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-22 02:42:02.560508 | instance | ok
2026-08-22 02:42:02.572582 | instance | included: /var/lib/zuul/builds/4f2bdffe677a4dd38bf8aa864ee66d14/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-22 02:42:02.579872 | 
2026-08-22 02:42:02.579934 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-22 02:42:02.603884 | instance | skipping: Conditional result was False
2026-08-22 02:42:02.613782 | 
2026-08-22 02:42:02.613889 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-22 02:42:03.187670 | instance | changed
2026-08-22 02:42:03.196079 | 
2026-08-22 02:42:03.196168 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-22 02:42:03.390822 | instance | ok
2026-08-22 02:42:03.395762 | 
2026-08-22 02:42:03.395824 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-22 02:42:03.899501 | instance | changed
2026-08-22 02:42:03.908088 | 
2026-08-22 02:42:03.908179 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-22 02:42:04.373917 | instance | changed
2026-08-22 02:42:04.381201 | 
2026-08-22 02:42:04.381270 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-22 02:42:04.405599 | instance | skipping: Conditional result was False
2026-08-22 02:42:04.413529 | 
2026-08-22 02:42:04.413620 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-22 02:42:04.782127 | instance -> localhost | changed
2026-08-22 02:42:04.813490 | 
2026-08-22 02:42:04.813718 | TASK [add-build-sshkey : Add back temp key]
2026-08-22 02:42:05.100669 | instance -> localhost | Identity added: /var/lib/zuul/builds/4f2bdffe677a4dd38bf8aa864ee66d14/work/4f2bdffe677a4dd38bf8aa864ee66d14_id_rsa (zuul-build-sshkey)
2026-08-22 02:42:05.101009 | instance -> localhost | ok: Runtime: 0:00:00.016767
2026-08-22 02:42:05.107890 | 
2026-08-22 02:42:05.107979 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-22 02:42:05.441251 | instance | ok
2026-08-22 02:42:05.447681 | 
2026-08-22 02:42:05.447772 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-22 02:42:05.474138 | instance | skipping: Conditional result was False
2026-08-22 02:42:05.502546 | 
2026-08-22 02:42:05.502630 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-22 02:42:05.830115 | instance | ok
2026-08-22 02:42:05.836200 | 
2026-08-22 02:42:05.836273 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-22 02:42:09.378409 | instance | Output suppressed because no_log was given
2026-08-22 02:42:09.388163 | 
2026-08-22 02:42:09.388226 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-22 02:42:09.578924 | instance | ok: "logs"
2026-08-22 02:42:09.579158 | instance | ok: All items complete
2026-08-22 02:42:09.579187 | 
2026-08-22 02:42:09.728435 | instance | ok: "artifacts"
2026-08-22 02:42:09.883817 | instance | ok: "docs"
2026-08-22 02:42:09.895954 | 
2026-08-22 02:42:09.896193 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-22 02:42:10.088714 | instance | changed: "logs"
2026-08-22 02:42:10.243170 | instance | changed: "artifacts"
2026-08-22 02:42:10.399069 | instance | changed: "docs"
2026-08-22 02:42:10.419011 | 
2026-08-22 02:42:10.419378 | PLAY RECAP
2026-08-22 02:42:10.419452 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-22 02:42:10.419496 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 02:42:10.419528 | 
2026-08-22 02:42:10.552080 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-22 02:42:10.556313 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-22 02:42:11.153090 | 
2026-08-22 02:42:11.153227 | PLAY [all]
2026-08-22 02:42:11.163941 | 
2026-08-22 02:42:11.164028 | TASK [setup-uv : Extract archive]
2026-08-22 02:42:13.379199 | instance | changed
2026-08-22 02:42:13.385049 | 
2026-08-22 02:42:13.385466 | TASK [setup-uv : Print version]
2026-08-22 02:42:13.753073 | instance | uv 0.8.13
2026-08-22 02:42:13.918437 | instance | ok: Runtime: 0:00:00.011628
2026-08-22 02:42:13.930676 | 
2026-08-22 02:42:13.930800 | TASK [Install Ansible collection siblings]
2026-08-22 02:42:22.945063 | instance | changed:
2026-08-22 02:42:22.945248 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-08-22 02:42:22.945280 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-08-22 02:42:22.945303 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-08-22 02:42:22.945323 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-08-22 02:42:22.945343 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-08-22 02:42:22.945367 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-08-22 02:42:22.945389 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-08-22 02:42:22.945410 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-08-22 02:42:22.945430 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-08-22 02:42:22.945449 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-08-22 02:42:22.945468 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-08-22 02:42:22.945488 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-08-22 02:42:22.945507 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-08-22 02:42:22.945525 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-08-22 02:42:22.945544 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0
2026-08-22 02:42:22.945563 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-08-22 02:42:22.945582 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-08-22 02:42:22.945601 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-08-22 02:42:22.945619 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-08-22 02:42:22.945638 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-08-22 02:42:22.945656 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0
2026-08-22 02:42:22.945674 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-08-22 02:42:22.945693 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-08-22 02:42:22.945728 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-08-22 02:42:22.945750 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0
2026-08-22 02:42:22.945768 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-08-22 02:42:22.945787 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-08-22 02:42:22.945806 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-08-22 02:42:22.945824 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-08-22 02:42:22.945842 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-08-22 02:42:22.945861 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-08-22 02:42:22.945879 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-08-22 02:42:22.945899 | instance | Checked out ansible.netcommon to 1.2.0
2026-08-22 02:42:22.945917 | 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-08-22 02:42:22.945943 | instance | Starting galaxy collection install process
2026-08-22 02:42:22.945961 | instance | Process install dependency map
2026-08-22 02:42:22.945980 | instance | Starting collection install process
2026-08-22 02:42:22.945999 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-08-22 02:42:22.946017 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-08-22 02:42:22.946036 | instance | ansible.netcommon:* was installed successfully
2026-08-22 02:42:22.946054 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-08-22 02:42:22.946072 | instance | Creating virtual environment at: .venv
2026-08-22 02:42:22.946089 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-08-22 02:42:22.946108 | instance | Downloading kubernetes (1.9MiB)
2026-08-22 02:42:22.946126 | instance | Downloading setuptools (1.1MiB)
2026-08-22 02:42:22.946145 | instance | Downloading ansible-core (2.1MiB)
2026-08-22 02:42:22.946162 | instance | Downloading pydantic-core (2.0MiB)
2026-08-22 02:42:22.946180 | instance | Downloading netaddr (2.2MiB)
2026-08-22 02:42:22.946198 | instance | Downloading cryptography (4.2MiB)
2026-08-22 02:42:22.946216 | instance | Downloading openstacksdk (1.7MiB)
2026-08-22 02:42:22.946234 | instance | Downloading rjsonnet (1.2MiB)
2026-08-22 02:42:22.946252 | instance | Downloading pygments (1.2MiB)
2026-08-22 02:42:22.946270 | instance |    Building pyperclip==1.9.0
2026-08-22 02:42:22.946289 | instance |  Downloading rjsonnet
2026-08-22 02:42:22.946309 | instance |  Downloading pydantic-core
2026-08-22 02:42:22.946327 | instance |  Downloading netaddr
2026-08-22 02:42:22.946345 | instance |  Downloading pygments
2026-08-22 02:42:22.946363 | instance |  Downloading cryptography
2026-08-22 02:42:22.946381 | instance |  Downloading setuptools
2026-08-22 02:42:22.946399 | instance |  Downloading kubernetes
2026-08-22 02:42:22.946417 | instance |  Downloading ansible-core
2026-08-22 02:42:22.946435 | instance |  Downloading openstacksdk
2026-08-22 02:42:22.946453 | instance |       Built pyperclip==1.9.0
2026-08-22 02:42:22.946472 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-08-22 02:42:22.946490 | instance | Installed 83 packages in 41ms
2026-08-22 02:42:22.946509 | instance | Checked out ansible.posix to 1.6.0
2026-08-22 02:42:22.946528 | 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-08-22 02:42:22.946546 | instance | Starting galaxy collection install process
2026-08-22 02:42:22.946564 | instance | Process install dependency map
2026-08-22 02:42:22.946587 | instance | Starting collection install process
2026-08-22 02:42:22.946606 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-08-22 02:42:22.946623 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-08-22 02:42:22.946641 | instance | ansible.posix:1.6.0 was installed successfully
2026-08-22 02:42:22.946659 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 02:42:22.946677 | instance | 2.17.12
2026-08-22 02:42:22.946695 | instance | Checked out ansible.utils to v6.1.0
2026-08-22 02:42:22.946713 | 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-08-22 02:42:22.946731 | instance | Starting galaxy collection install process
2026-08-22 02:42:22.946748 | instance | Process install dependency map
2026-08-22 02:42:22.946766 | instance | Starting collection install process
2026-08-22 02:42:22.946784 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-08-22 02:42:22.946802 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-08-22 02:42:22.946820 | instance | ansible.utils:6.1.0 was installed successfully
2026-08-22 02:42:22.946839 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 02:42:22.946857 | instance | 2.17.12
2026-08-22 02:42:22.946875 | instance | Checked out atmosphere.common to v0.7.0
2026-08-22 02:42:22.946893 | 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-08-22 02:42:22.946911 | instance | Starting galaxy collection install process
2026-08-22 02:42:22.946928 | instance | Process install dependency map
2026-08-22 02:42:22.946946 | instance | Starting collection install process
2026-08-22 02:42:22.946963 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-08-22 02:42:22.946981 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-08-22 02:42:22.946999 | instance | atmosphere.common:0.7.0 was installed successfully
2026-08-22 02:42:22.947017 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 02:42:22.947034 | instance | 2.17.12
2026-08-22 02:42:22.947052 | instance | Checked out community.crypto to 2.2.3
2026-08-22 02:42:22.947070 | 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-08-22 02:42:22.947089 | instance | Starting galaxy collection install process
2026-08-22 02:42:22.947107 | instance | Process install dependency map
2026-08-22 02:42:22.947125 | instance | Starting collection install process
2026-08-22 02:42:22.947143 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-08-22 02:42:22.947160 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-08-22 02:42:22.947179 | instance | community.crypto:2.2.3 was installed successfully
2026-08-22 02:42:22.947197 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 02:42:22.947216 | instance | 2.17.12
2026-08-22 02:42:22.947233 | instance | Checked out community.general to 7.3.0
2026-08-22 02:42:22.947251 | 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-08-22 02:42:22.947274 | instance | Starting galaxy collection install process
2026-08-22 02:42:22.947293 | instance | Process install dependency map
2026-08-22 02:42:22.947312 | instance | Starting collection install process
2026-08-22 02:42:22.947330 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-08-22 02:42:22.947347 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-08-22 02:42:22.947392 | instance | community.general:7.3.0 was installed successfully
2026-08-22 02:42:22.947412 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 02:42:22.947431 | instance | 2.17.12
2026-08-22 02:42:22.947449 | instance | Checked out community.mysql to 3.6.0
2026-08-22 02:42:22.947469 | 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-08-22 02:42:22.947488 | instance | Starting galaxy collection install process
2026-08-22 02:42:22.947506 | instance | Process install dependency map
2026-08-22 02:42:22.947524 | instance | Starting collection install process
2026-08-22 02:42:22.947542 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-08-22 02:42:22.947560 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-08-22 02:42:22.947578 | instance | community.mysql:3.6.0 was installed successfully
2026-08-22 02:42:22.947596 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 02:42:22.947614 | instance | 2.17.12
2026-08-22 02:42:22.947633 | instance | Checked out kubernetes.core to 2.4.0
2026-08-22 02:42:22.947651 | 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-08-22 02:42:22.947669 | instance | Starting galaxy collection install process
2026-08-22 02:42:22.947687 | instance | Process install dependency map
2026-08-22 02:42:22.947704 | instance | Starting collection install process
2026-08-22 02:42:22.947722 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-08-22 02:42:22.947739 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-08-22 02:42:22.947758 | instance | kubernetes.core:2.4.0 was installed successfully
2026-08-22 02:42:22.947777 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 02:42:22.947796 | instance | 2.17.12
2026-08-22 02:42:22.947813 | instance | Checked out openstack.cloud to 2.6.0
2026-08-22 02:42:22.947832 | 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-08-22 02:42:22.947850 | instance | Starting galaxy collection install process
2026-08-22 02:42:22.947868 | instance | Process install dependency map
2026-08-22 02:42:22.947887 | instance | Starting collection install process
2026-08-22 02:42:22.947904 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-08-22 02:42:22.948040 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-08-22 02:42:22.948143 | instance | openstack.cloud:2.6.0 was installed successfully
2026-08-22 02:42:22.948173 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 02:42:22.948194 | instance | 2.17.12
2026-08-22 02:42:22.948543 | instance | Checked out vexxhost.ceph to v4.2.0
2026-08-22 02:42:22.948566 | 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-08-22 02:42:22.948585 | instance | Starting galaxy collection install process
2026-08-22 02:42:22.948603 | instance | Process install dependency map
2026-08-22 02:42:22.948621 | instance | Starting collection install process
2026-08-22 02:42:22.948639 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-08-22 02:42:22.948657 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-08-22 02:42:22.948675 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-08-22 02:42:22.948693 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 02:42:22.948711 | instance | 2.17.12
2026-08-22 02:42:22.948729 | instance | Checked out vexxhost.containers to v1.6.8
2026-08-22 02:42:22.948747 | 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-08-22 02:42:22.948764 | instance | Starting galaxy collection install process
2026-08-22 02:42:22.948783 | instance | Process install dependency map
2026-08-22 02:42:22.948801 | instance | Starting collection install process
2026-08-22 02:42:22.948820 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-08-22 02:42:22.948838 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-08-22 02:42:22.948856 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-08-22 02:42:22.948874 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 02:42:22.948892 | instance | 2.17.12
2026-08-22 02:42:22.948910 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-08-22 02:42:22.948927 | 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-08-22 02:42:22.948945 | instance | Starting galaxy collection install process
2026-08-22 02:42:22.949386 | instance | Process install dependency map
2026-08-22 02:42:22.949417 | instance | Starting collection install process
2026-08-22 02:42:22.949437 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-08-22 02:42:22.949457 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-08-22 02:42:22.949475 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-08-22 02:42:22.949495 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-08-22 02:42:22.949514 | instance | 2.17.12
2026-08-22 02:42:22.956395 | 
2026-08-22 02:42:22.956445 | PLAY RECAP
2026-08-22 02:42:22.956571 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 02:42:22.956615 | 
2026-08-22 02:42:23.127842 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-22 02:42:23.131636 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-08-22 02:42:23.689586 | 
2026-08-22 02:42:23.689703 | PLAY [all]
2026-08-22 02:42:23.700347 | 
2026-08-22 02:42:23.700421 | TASK [Install "jq" for log collection]
2026-08-22 02:42:32.531615 | instance | changed
2026-08-22 02:42:32.534138 | 
2026-08-22 02:42:32.534222 | PLAY RECAP
2026-08-22 02:42:32.534292 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-22 02:42:32.534375 | 
2026-08-22 02:42:32.674751 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-08-22 02:42:32.679227 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-22 02:42:33.268316 | 
2026-08-22 02:42:33.268481 | PLAY [all]
2026-08-22 02:42:33.280280 | 
2026-08-22 02:42:33.280400 | LOOP [Check inventory directories]
2026-08-22 02:42:33.599701 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-08-22 02:42:33.768163 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-08-22 02:42:33.943855 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-08-22 02:42:33.958374 | 
2026-08-22 02:42:33.958567 | LOOP [Copy inventory file for Zuul]
2026-08-22 02:42:34.728173 | instance | changed:
2026-08-22 02:42:34.728321 | instance | {
2026-08-22 02:42:34.728349 | instance |   "ansible_loop_var": "item",
2026-08-22 02:42:34.728373 | instance |   "changed": false,
2026-08-22 02:42:34.728392 | instance |   "failed": false,
2026-08-22 02:42:34.728414 | instance |   "invocation": {
2026-08-22 02:42:34.728433 | instance |     "module_args": {
2026-08-22 02:42:34.728452 | instance |       "checksum_algorithm": "sha1",
2026-08-22 02:42:34.728471 | instance |       "follow": true,
2026-08-22 02:42:34.728489 | instance |       "get_attributes": true,
2026-08-22 02:42:34.728507 | instance |       "get_checksum": true,
2026-08-22 02:42:34.728526 | instance |       "get_mime": true,
2026-08-22 02:42:34.728544 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-08-22 02:42:34.728562 | instance |     }
2026-08-22 02:42:34.728580 | instance |   },
2026-08-22 02:42:34.728598 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-08-22 02:42:34.728615 | instance |   "stat": {
2026-08-22 02:42:34.728636 | instance |     "atime": 1787366535.7336373,
2026-08-22 02:42:34.728656 | instance |     "attr_flags": "e",
2026-08-22 02:42:34.728674 | instance |     "attributes": [
2026-08-22 02:42:34.728692 | instance |       "extents"
2026-08-22 02:42:34.728709 | instance |     ],
2026-08-22 02:42:34.728726 | instance |     "block_size": 4096,
2026-08-22 02:42:34.728744 | instance |     "blocks": 8,
2026-08-22 02:42:34.728761 | instance |     "charset": "unknown",
2026-08-22 02:42:34.728779 | instance |     "ctime": 1787366534.7015815,
2026-08-22 02:42:34.728797 | instance |     "dev": 64513,
2026-08-22 02:42:34.728815 | instance |     "device_type": 0,
2026-08-22 02:42:34.728833 | instance |     "executable": true,
2026-08-22 02:42:34.728850 | instance |     "exists": true,
2026-08-22 02:42:34.728867 | instance |     "gid": 1000,
2026-08-22 02:42:34.728884 | instance |     "gr_name": "zuul",
2026-08-22 02:42:34.728902 | instance |     "inode": 1048700,
2026-08-22 02:42:34.728919 | instance |     "isblk": false,
2026-08-22 02:42:34.728937 | instance |     "ischr": false,
2026-08-22 02:42:34.728968 | instance |     "isdir": true,
2026-08-22 02:42:34.728989 | instance |     "isfifo": false,
2026-08-22 02:42:34.729006 | instance |     "isgid": false,
2026-08-22 02:42:34.729023 | instance |     "islnk": false,
2026-08-22 02:42:34.729041 | instance |     "isreg": false,
2026-08-22 02:42:34.729057 | instance |     "issock": false,
2026-08-22 02:42:34.729074 | instance |     "isuid": false,
2026-08-22 02:42:34.729090 | instance |     "mimetype": "unknown",
2026-08-22 02:42:34.729107 | instance |     "mode": "0755",
2026-08-22 02:42:34.729124 | instance |     "mtime": 1787366534.7015815,
2026-08-22 02:42:34.729141 | instance |     "nlink": 23,
2026-08-22 02:42:34.729157 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-08-22 02:42:34.729174 | instance |     "pw_name": "zuul",
2026-08-22 02:42:34.729191 | instance |     "readable": true,
2026-08-22 02:42:34.729209 | instance |     "rgrp": true,
2026-08-22 02:42:34.729226 | instance |     "roth": true,
2026-08-22 02:42:34.729251 | instance |     "rusr": true,
2026-08-22 02:42:34.729269 | instance |     "size": 4096,
2026-08-22 02:42:34.729286 | instance |     "uid": 1000,
2026-08-22 02:42:34.729304 | instance |     "version": "2252613629",
2026-08-22 02:42:34.729321 | instance |     "wgrp": false,
2026-08-22 02:42:34.729339 | instance |     "woth": false,
2026-08-22 02:42:34.729357 | instance |     "writeable": true,
2026-08-22 02:42:34.729374 | instance |     "wusr": true,
2026-08-22 02:42:34.729392 | instance |     "xgrp": true,
2026-08-22 02:42:34.729408 | instance |     "xoth": true,
2026-08-22 02:42:34.729425 | instance |     "xusr": true
2026-08-22 02:42:34.729442 | instance |   }
2026-08-22 02:42:34.729460 | instance | }
2026-08-22 02:42:34.729491 | instance | ok: All items complete
2026-08-22 02:42:34.729512 | 
2026-08-22 02:42:34.730813 | instance | skipping: Conditional result was False
2026-08-22 02:42:34.731383 | instance | skipping: Conditional result was False
2026-08-22 02:42:34.749731 | 
2026-08-22 02:42:34.749917 | LOOP [Switch "ansible_host" to private IP]
2026-08-22 02:42:35.095050 | instance | changed: 1 replacements made
2026-08-22 02:42:35.096585 | instance | skipping: Conditional result was False
2026-08-22 02:42:35.099292 | instance | skipping: Conditional result was False
2026-08-22 02:42:35.113233 | 
2026-08-22 02:42:35.113378 | TASK [Run Molecule scenario]
2026-08-22 02:42:36.001020 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-08-22 02:42:36.367679 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-08-22 02:42:36.367799 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-08-22 02:42:47.191709 | instance | INFO     [csi > dependency] Executing
2026-08-22 02:42:47.191834 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-08-22 02:42:47.192002 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-08-22 02:42:47.192148 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-08-22 02:42:47.199543 | instance | INFO     [csi > cleanup] Executing
2026-08-22 02:42:47.199859 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-22 02:42:47.207351 | instance | INFO     [csi > destroy] Executing
2026-08-22 02:42:47.207412 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-08-22 02:42:47.207480 | instance | INFO     [csi > destroy] Executed: Successful
2026-08-22 02:42:47.214853 | instance | INFO     [csi > syntax] Executing
2026-08-22 02:42:48.990179 | instance |
2026-08-22 02:42:48.990484 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-08-22 02:42:49.056127 | instance | INFO     [csi > syntax] Executed: Successful
2026-08-22 02:42:49.068705 | instance | INFO     [csi > create] Executing
2026-08-22 02:42:49.070550 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-08-22 02:42:49.077767 | instance | INFO     [csi > prepare] Executing
2026-08-22 02:42:49.982687 | instance |
2026-08-22 02:42:49.983013 | instance | PLAY [Prepare] *****************************************************************
2026-08-22 02:42:49.983244 | instance |
2026-08-22 02:42:49.983527 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 02:42:49.983805 | instance | Saturday 22 August 2026  02:42:49 +0000 (0:00:00.030)       0:00:00.030 *******
2026-08-22 02:42:51.155101 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-08-22 02:42:51.155237 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-22 02:42:51.155408 | instance | interpreter could change the meaning of that path. See
2026-08-22 02:42:51.155576 | instance | https://docs.ansible.com/ansible-
2026-08-22 02:42:51.155744 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-08-22 02:42:51.162892 | instance | ok: [instance]
2026-08-22 02:42:51.163137 | instance |
2026-08-22 02:42:51.163406 | instance | TASK [Configure short hostname] ************************************************
2026-08-22 02:42:51.163675 | instance | Saturday 22 August 2026  02:42:51 +0000 (0:00:01.181)       0:00:01.212 *******
2026-08-22 02:42:51.849266 | instance | changed: [instance]
2026-08-22 02:42:51.849359 | instance |
2026-08-22 02:42:51.849468 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-08-22 02:42:51.849599 | instance | Saturday 22 August 2026  02:42:51 +0000 (0:00:00.686)       0:00:01.898 *******
2026-08-22 02:42:52.118488 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-08-22 02:42:52.118802 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-08-22 02:42:52.119092 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-08-22 02:42:52.127522 | instance | changed: [instance]
2026-08-22 02:42:52.127816 | instance |
2026-08-22 02:42:52.128205 | instance | TASK [Purge "snapd" package] ***************************************************
2026-08-22 02:42:52.128979 | instance | Saturday 22 August 2026  02:42:52 +0000 (0:00:00.278)       0:00:02.176 *******
2026-08-22 02:42:52.928015 | instance | ok: [instance]
2026-08-22 02:42:52.928308 | instance |
2026-08-22 02:42:52.928603 | instance | PLAY [Create devices for Ceph] *************************************************
2026-08-22 02:42:52.928837 | instance |
2026-08-22 02:42:52.929105 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 02:42:52.929376 | instance | Saturday 22 August 2026  02:42:52 +0000 (0:00:00.800)       0:00:02.976 *******
2026-08-22 02:42:53.629236 | instance | ok: [instance]
2026-08-22 02:42:53.629492 | instance |
2026-08-22 02:42:53.629759 | instance | TASK [Install depedencies] *****************************************************
2026-08-22 02:42:53.630030 | instance | Saturday 22 August 2026  02:42:53 +0000 (0:00:00.701)       0:00:03.678 *******
2026-08-22 02:43:13.847647 | instance | changed: [instance]
2026-08-22 02:43:13.847841 | instance |
2026-08-22 02:43:13.848157 | instance | TASK [Start up service] ********************************************************
2026-08-22 02:43:13.848485 | instance | Saturday 22 August 2026  02:43:13 +0000 (0:00:20.217)       0:00:23.896 *******
2026-08-22 02:43:14.398593 | instance | ok: [instance]
2026-08-22 02:43:14.398839 | instance |
2026-08-22 02:43:14.399155 | instance | TASK [Generate lvm.conf] *******************************************************
2026-08-22 02:43:14.399443 | instance | Saturday 22 August 2026  02:43:14 +0000 (0:00:00.551)       0:00:24.447 *******
2026-08-22 02:43:14.688553 | instance | ok: [instance]
2026-08-22 02:43:14.688768 | instance |
2026-08-22 02:43:14.689060 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-08-22 02:43:14.689339 | instance | Saturday 22 August 2026  02:43:14 +0000 (0:00:00.289)       0:00:24.737 *******
2026-08-22 02:43:15.398402 | instance | changed: [instance]
2026-08-22 02:43:15.398525 | instance |
2026-08-22 02:43:15.398707 | instance | TASK [Get list of all loopback devices] ****************************************
2026-08-22 02:43:15.398885 | instance | Saturday 22 August 2026  02:43:15 +0000 (0:00:00.710)       0:00:25.447 *******
2026-08-22 02:43:15.603838 | instance | ok: [instance]
2026-08-22 02:43:15.604101 | instance |
2026-08-22 02:43:15.604438 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-08-22 02:43:15.604717 | instance | Saturday 22 August 2026  02:43:15 +0000 (0:00:00.205)       0:00:25.652 *******
2026-08-22 02:43:15.629378 | instance | skipping: [instance]
2026-08-22 02:43:15.629644 | instance |
2026-08-22 02:43:15.629927 | instance | TASK [Create devices for Ceph] *************************************************
2026-08-22 02:43:15.630206 | instance | Saturday 22 August 2026  02:43:15 +0000 (0:00:00.026)       0:00:25.678 *******
2026-08-22 02:43:16.161513 | instance | changed: [instance] => (item=osd0)
2026-08-22 02:43:16.161769 | instance | changed: [instance] => (item=osd1)
2026-08-22 02:43:16.162067 | instance | changed: [instance] => (item=osd2)
2026-08-22 02:43:16.162349 | instance |
2026-08-22 02:43:16.162670 | instance | TASK [Set permissions on loopback devices] *************************************
2026-08-22 02:43:16.162976 | instance | Saturday 22 August 2026  02:43:16 +0000 (0:00:00.531)       0:00:26.210 *******
2026-08-22 02:43:16.771061 | instance | changed: [instance] => (item=osd0)
2026-08-22 02:43:16.771688 | instance | changed: [instance] => (item=osd1)
2026-08-22 02:43:16.772160 | instance | changed: [instance] => (item=osd2)
2026-08-22 02:43:16.772533 | instance |
2026-08-22 02:43:16.772889 | instance | TASK [Start loop devices] ******************************************************
2026-08-22 02:43:16.773250 | instance | Saturday 22 August 2026  02:43:16 +0000 (0:00:00.608)       0:00:26.819 *******
2026-08-22 02:43:17.511989 | instance | changed: [instance] => (item=osd0)
2026-08-22 02:43:17.512099 | instance | changed: [instance] => (item=osd1)
2026-08-22 02:43:17.512113 | instance | changed: [instance] => (item=osd2)
2026-08-22 02:43:17.512123 | instance |
2026-08-22 02:43:17.512136 | instance | TASK [Create a volume group for each loop device] ******************************
2026-08-22 02:43:17.512382 | instance | Saturday 22 August 2026  02:43:17 +0000 (0:00:00.740)       0:00:27.560 *******
2026-08-22 02:43:21.019189 | instance | changed: [instance] => (item=osd0)
2026-08-22 02:43:21.019252 | instance | changed: [instance] => (item=osd1)
2026-08-22 02:43:21.019785 | instance | changed: [instance] => (item=osd2)
2026-08-22 02:43:21.019828 | instance |
2026-08-22 02:43:21.019833 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-08-22 02:43:21.019838 | instance | Saturday 22 August 2026  02:43:21 +0000 (0:00:03.507)       0:00:31.068 *******
2026-08-22 02:43:22.944553 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-08-22 02:43:22.948235 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-08-22 02:43:22.948315 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-08-22 02:43:22.948323 | instance |
2026-08-22 02:43:22.948329 | instance | PLAY RECAP *********************************************************************
2026-08-22 02:43:22.948337 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-08-22 02:43:22.948343 | instance |
2026-08-22 02:43:22.948348 | instance | Saturday 22 August 2026  02:43:22 +0000 (0:00:01.925)       0:00:32.993 *******
2026-08-22 02:43:22.948354 | instance | ===============================================================================
2026-08-22 02:43:22.948359 | instance | Install depedencies ---------------------------------------------------- 20.22s
2026-08-22 02:43:22.948365 | instance | Create a volume group for each loop device ------------------------------ 3.51s
2026-08-22 02:43:22.948370 | instance | Create a logical volume for each loop device ---------------------------- 1.93s
2026-08-22 02:43:22.948376 | instance | Gathering Facts --------------------------------------------------------- 1.18s
2026-08-22 02:43:22.948381 | instance | Purge "snapd" package --------------------------------------------------- 0.80s
2026-08-22 02:43:22.948387 | instance | Start loop devices ------------------------------------------------------ 0.74s
2026-08-22 02:43:22.948398 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.71s
2026-08-22 02:43:22.948667 | instance | Gathering Facts --------------------------------------------------------- 0.70s
2026-08-22 02:43:22.949038 | instance | Configure short hostname ------------------------------------------------ 0.69s
2026-08-22 02:43:22.949780 | instance | Set permissions on loopback devices ------------------------------------- 0.61s
2026-08-22 02:43:22.950436 | instance | Start up service -------------------------------------------------------- 0.55s
2026-08-22 02:43:22.950805 | instance | Create devices for Ceph ------------------------------------------------- 0.53s
2026-08-22 02:43:22.951177 | instance | Generate lvm.conf ------------------------------------------------------- 0.29s
2026-08-22 02:43:22.951529 | instance | Ensure hostname inside hosts file --------------------------------------- 0.28s
2026-08-22 02:43:22.951883 | instance | Get list of all loopback devices ---------------------------------------- 0.21s
2026-08-22 02:43:22.952315 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s
2026-08-22 02:43:23.031956 | instance | INFO     [csi > prepare] Executed: Successful
2026-08-22 02:43:23.044372 | instance | INFO     [csi > converge] Executing
2026-08-22 02:43:24.580471 | instance |
2026-08-22 02:43:24.580926 | instance | PLAY [Debug CSI driver value] **************************************************
2026-08-22 02:43:24.581340 | instance |
2026-08-22 02:43:24.581746 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 02:43:24.582112 | instance | Saturday 22 August 2026  02:43:24 +0000 (0:00:00.007)       0:00:00.007 *******
2026-08-22 02:43:25.530385 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-08-22 02:43:25.530979 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-08-22 02:43:25.531469 | instance | interpreter could change the meaning of that path. See
2026-08-22 02:43:25.531963 | instance | https://docs.ansible.com/ansible-
2026-08-22 02:43:25.532504 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-08-22 02:43:25.540309 | instance | ok: [instance]
2026-08-22 02:43:25.540463 | instance |
2026-08-22 02:43:25.540617 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-08-22 02:43:25.540767 | instance | Saturday 22 August 2026  02:43:25 +0000 (0:00:00.960)       0:00:00.968 *******
2026-08-22 02:43:25.593590 | instance | ok: [instance] => {
2026-08-22 02:43:25.594186 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-08-22 02:43:25.594553 | instance | }
2026-08-22 02:43:25.594844 | instance |
2026-08-22 02:43:25.595150 | instance | PLAY [all] *********************************************************************
2026-08-22 02:43:25.595436 | instance |
2026-08-22 02:43:25.595736 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 02:43:25.596105 | instance | Saturday 22 August 2026  02:43:25 +0000 (0:00:00.052)       0:00:01.020 *******
2026-08-22 02:43:26.495719 | instance | ok: [instance]
2026-08-22 02:43:26.496146 | instance |
2026-08-22 02:43:26.496504 | instance | TASK [Fail if atmosphere_ceph_enabled is set] **********************************
2026-08-22 02:43:26.496841 | instance | Saturday 22 August 2026  02:43:26 +0000 (0:00:00.902)       0:00:01.922 *******
2026-08-22 02:43:26.539703 | instance | skipping: [instance]
2026-08-22 02:43:26.540036 | instance |
2026-08-22 02:43:26.540418 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-08-22 02:43:26.540751 | instance | Saturday 22 August 2026  02:43:26 +0000 (0:00:00.044)       0:00:01.967 *******
2026-08-22 02:43:26.723897 | instance | ok: [instance]
2026-08-22 02:43:26.724334 | instance |
2026-08-22 02:43:26.724682 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-08-22 02:43:26.724993 | instance |
2026-08-22 02:43:26.725366 | instance | TASK [Gathering Facts] *********************************************************
2026-08-22 02:43:26.725725 | instance | Saturday 22 August 2026  02:43:26 +0000 (0:00:00.183)       0:00:02.151 *******
2026-08-22 02:43:27.624622 | instance | ok: [instance]
2026-08-22 02:43:27.624704 | instance |
2026-08-22 02:43:27.624786 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-22 02:43:27.624983 | instance | Saturday 22 August 2026  02:43:27 +0000 (0:00:00.899)       0:00:03.050 *******
2026-08-22 02:43:28.003292 | instance | ok: [instance]
2026-08-22 02:43:28.003334 | instance |
2026-08-22 02:43:28.003340 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-22 02:43:28.003344 | instance | Saturday 22 August 2026  02:43:27 +0000 (0:00:00.379)       0:00:03.430 *******
2026-08-22 02:43:28.054301 | instance | skipping: [instance]
2026-08-22 02:43:28.054349 | instance |
2026-08-22 02:43:28.054355 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-08-22 02:43:28.054359 | instance | Saturday 22 August 2026  02:43:28 +0000 (0:00:00.051)       0:00:03.481 *******
2026-08-22 02:43:28.414818 | instance | changed: [instance]
2026-08-22 02:43:28.414867 | instance |
2026-08-22 02:43:28.414873 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 02:43:28.414878 | instance | Saturday 22 August 2026  02:43:28 +0000 (0:00:00.360)       0:00:03.841 *******
2026-08-22 02:43:28.506295 | instance | ok: [instance] => {
2026-08-22 02:43:28.506358 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-08-22 02:43:28.506368 | instance | }
2026-08-22 02:43:28.506377 | instance |
2026-08-22 02:43:28.506385 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 02:43:28.506392 | instance | Saturday 22 August 2026  02:43:28 +0000 (0:00:00.090)       0:00:03.932 *******
2026-08-22 02:43:29.206922 | instance | changed: [instance]
2026-08-22 02:43:29.206965 | instance |
2026-08-22 02:43:29.206972 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 02:43:29.206977 | instance | Saturday 22 August 2026  02:43:29 +0000 (0:00:00.701)       0:00:04.633 *******
2026-08-22 02:43:29.277465 | instance | skipping: [instance]
2026-08-22 02:43:29.277517 | instance |
2026-08-22 02:43:29.277523 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-22 02:43:29.277528 | instance | Saturday 22 August 2026  02:43:29 +0000 (0:00:00.070)       0:00:04.704 *******
2026-08-22 02:43:29.336188 | instance | skipping: [instance]
2026-08-22 02:43:29.336260 | instance |
2026-08-22 02:43:29.336489 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-22 02:43:29.336542 | instance | Saturday 22 August 2026  02:43:29 +0000 (0:00:00.058)       0:00:04.763 *******
2026-08-22 02:43:29.546577 | instance | ok: [instance]
2026-08-22 02:43:29.546672 | instance |
2026-08-22 02:43:29.546735 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-08-22 02:43:29.546865 | instance | Saturday 22 August 2026  02:43:29 +0000 (0:00:00.210)       0:00:04.974 *******
2026-08-22 02:43:30.956759 | instance | ok: [instance]
2026-08-22 02:43:30.956807 | instance |
2026-08-22 02:43:30.956813 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 02:43:30.956831 | instance | Saturday 22 August 2026  02:43:30 +0000 (0:00:01.409)       0:00:06.383 *******
2026-08-22 02:43:31.037888 | instance | ok: [instance] => {
2026-08-22 02:43:31.037941 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-08-22 02:43:31.037949 | instance | }
2026-08-22 02:43:31.037955 | instance |
2026-08-22 02:43:31.037961 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 02:43:31.037966 | instance | Saturday 22 August 2026  02:43:31 +0000 (0:00:00.080)       0:00:06.464 *******
2026-08-22 02:43:31.759565 | instance | changed: [instance]
2026-08-22 02:43:31.759801 | instance |
2026-08-22 02:43:31.759815 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 02:43:31.760003 | instance | Saturday 22 August 2026  02:43:31 +0000 (0:00:00.722)       0:00:07.186 *******
2026-08-22 02:43:34.720883 | instance | changed: [instance]
2026-08-22 02:43:34.720943 | instance |
2026-08-22 02:43:34.720948 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-08-22 02:43:34.720954 | instance | Saturday 22 August 2026  02:43:34 +0000 (0:00:02.960)       0:00:10.146 *******
2026-08-22 02:43:34.759282 | instance | skipping: [instance]
2026-08-22 02:43:34.760000 | instance |
2026-08-22 02:43:34.760069 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-08-22 02:43:34.760077 | instance | Saturday 22 August 2026  02:43:34 +0000 (0:00:00.039)       0:00:10.186 *******
2026-08-22 02:43:34.801316 | instance | skipping: [instance]
2026-08-22 02:43:34.801400 | instance |
2026-08-22 02:43:34.801660 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-08-22 02:43:34.801686 | instance | Saturday 22 August 2026  02:43:34 +0000 (0:00:00.042)       0:00:10.228 *******
2026-08-22 02:43:34.842667 | instance | skipping: [instance]
2026-08-22 02:43:34.842758 | instance |
2026-08-22 02:43:34.842954 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-08-22 02:43:34.843225 | instance | Saturday 22 August 2026  02:43:34 +0000 (0:00:00.041)       0:00:10.269 *******
2026-08-22 02:43:40.741239 | instance | changed: [instance]
2026-08-22 02:43:40.741313 | instance |
2026-08-22 02:43:40.741618 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-08-22 02:43:40.741673 | instance | Saturday 22 August 2026  02:43:40 +0000 (0:00:05.898)       0:00:16.168 *******
2026-08-22 02:43:41.346388 | instance | changed: [instance]
2026-08-22 02:43:41.346503 | instance |
2026-08-22 02:43:41.346518 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-08-22 02:43:41.346710 | instance | Saturday 22 August 2026  02:43:41 +0000 (0:00:00.605)       0:00:16.773 *******
2026-08-22 02:43:42.298357 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-08-22 02:43:42.299617 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-08-22 02:43:42.299801 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-08-22 02:43:42.299813 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-08-22 02:43:42.299819 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-08-22 02:43:42.299824 | instance |
2026-08-22 02:43:42.299828 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-08-22 02:43:42.299832 | instance | Saturday 22 August 2026  02:43:42 +0000 (0:00:00.951)       0:00:17.725 *******
2026-08-22 02:43:42.919670 | instance | changed: [instance]
2026-08-22 02:43:42.919749 | instance |
2026-08-22 02:43:42.920187 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-08-22 02:43:42.920284 | instance | Saturday 22 August 2026  02:43:42 +0000 (0:00:00.604)       0:00:18.329 *******
2026-08-22 02:43:42.920296 | instance |
2026-08-22 02:43:42.920313 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-22 02:43:42.920460 | instance | Saturday 22 August 2026  02:43:42 +0000 (0:00:00.017)       0:00:18.346 *******
2026-08-22 02:43:43.923540 | instance | ok: [instance]
2026-08-22 02:43:43.923657 | instance |
2026-08-22 02:43:43.923930 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-08-22 02:43:43.923975 | instance | Saturday 22 August 2026  02:43:43 +0000 (0:00:01.004)       0:00:19.351 *******
2026-08-22 02:43:44.366741 | instance | changed: [instance]
2026-08-22 02:43:44.366849 | instance |
2026-08-22 02:43:44.367073 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-08-22 02:43:44.367121 | instance | Saturday 22 August 2026  02:43:44 +0000 (0:00:00.443)       0:00:19.794 *******
2026-08-22 02:43:44.960213 | instance | changed: [instance]
2026-08-22 02:43:44.960304 | instance |
2026-08-22 02:43:44.960318 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-08-22 02:43:44.960497 | instance | Saturday 22 August 2026  02:43:44 +0000 (0:00:00.593)       0:00:20.387 *******
2026-08-22 02:43:45.173650 | instance | ok: [instance]
2026-08-22 02:43:45.173735 | instance |
2026-08-22 02:43:45.174061 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-08-22 02:43:45.174124 | instance | Saturday 22 August 2026  02:43:45 +0000 (0:00:00.213)       0:00:20.600 *******
2026-08-22 02:43:45.230960 | instance | ok: [instance] => {
2026-08-22 02:43:45.231078 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-08-22 02:43:45.231093 | instance | }
2026-08-22 02:43:45.231459 | instance |
2026-08-22 02:43:45.231495 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-08-22 02:43:45.231503 | instance | Saturday 22 August 2026  02:43:45 +0000 (0:00:00.057)       0:00:20.658 *******
2026-08-22 02:43:46.294088 | instance | changed: [instance]
2026-08-22 02:43:46.294171 | instance |
2026-08-22 02:43:46.294419 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-08-22 02:43:46.294467 | instance | Saturday 22 August 2026  02:43:46 +0000 (0:00:01.063)       0:00:21.721 *******
2026-08-22 02:43:50.669988 | instance | changed: [instance]
2026-08-22 02:43:50.670071 | instance |
2026-08-22 02:43:50.670324 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-08-22 02:43:50.670377 | instance | Saturday 22 August 2026  02:43:50 +0000 (0:00:04.376)       0:00:26.097 *******
2026-08-22 02:43:51.786683 | instance | ok: [instance]
2026-08-22 02:43:51.786762 | instance |
2026-08-22 02:43:51.787033 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-08-22 02:43:51.787082 | instance | Saturday 22 August 2026  02:43:51 +0000 (0:00:01.116)       0:00:27.214 *******
2026-08-22 02:43:52.157228 | instance | changed: [instance]
2026-08-22 02:43:52.157737 | instance |
2026-08-22 02:43:52.157785 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-08-22 02:43:52.157792 | instance | Saturday 22 August 2026  02:43:52 +0000 (0:00:00.370)       0:00:27.584 *******
2026-08-22 02:43:52.636792 | instance | changed: [instance]
2026-08-22 02:43:52.636860 | instance |
2026-08-22 02:43:52.637119 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-08-22 02:43:52.637287 | instance | Saturday 22 August 2026  02:43:52 +0000 (0:00:00.479)       0:00:28.064 *******
2026-08-22 02:43:53.227683 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-08-22 02:43:53.227728 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-08-22 02:43:53.227734 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-08-22 02:43:53.227738 | instance |
2026-08-22 02:43:53.227743 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-08-22 02:43:53.227747 | instance | Saturday 22 August 2026  02:43:53 +0000 (0:00:00.590)       0:00:28.654 *******
2026-08-22 02:43:53.671719 | instance | changed: [instance]
2026-08-22 02:43:53.671816 | instance |
2026-08-22 02:43:53.672113 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-08-22 02:43:53.672154 | instance | Saturday 22 August 2026  02:43:53 +0000 (0:00:00.444)       0:00:29.099 *******
2026-08-22 02:43:54.167236 | instance | changed: [instance]
2026-08-22 02:43:54.167329 | instance |
2026-08-22 02:43:54.168031 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-08-22 02:43:54.168114 | instance | Saturday 22 August 2026  02:43:54 +0000 (0:00:00.476)       0:00:29.576 *******
2026-08-22 02:43:54.168120 | instance |
2026-08-22 02:43:54.168125 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-08-22 02:43:54.168135 | instance | Saturday 22 August 2026  02:43:54 +0000 (0:00:00.018)       0:00:29.594 *******
2026-08-22 02:43:54.928289 | instance | ok: [instance]
2026-08-22 02:43:54.928382 | instance |
2026-08-22 02:43:54.928625 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-08-22 02:43:54.928676 | instance | Saturday 22 August 2026  02:43:54 +0000 (0:00:00.761)       0:00:30.355 *******
2026-08-22 02:43:55.714253 | instance | changed: [instance]
2026-08-22 02:43:55.714354 | instance |
2026-08-22 02:43:55.714584 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-08-22 02:43:55.714634 | instance | Saturday 22 August 2026  02:43:55 +0000 (0:00:00.786)       0:00:31.141 *******
2026-08-22 02:43:56.236431 | instance | changed: [instance]
2026-08-22 02:43:56.236559 | instance |
2026-08-22 02:43:56.236825 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-08-22 02:43:56.236860 | instance | Saturday 22 August 2026  02:43:56 +0000 (0:00:00.522)       0:00:31.664 *******
2026-08-22 02:43:56.301901 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-08-22 02:43:56.302715 | instance |
2026-08-22 02:43:56.302775 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-08-22 02:43:56.302786 | instance | Saturday 22 August 2026  02:43:56 +0000 (0:00:00.064)       0:00:31.728 *******
2026-08-22 02:44:00.705090 | instance | changed: [instance]
2026-08-22 02:44:00.705195 | instance |
2026-08-22 02:44:00.705207 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-08-22 02:44:00.705391 | instance | Saturday 22 August 2026  02:44:00 +0000 (0:00:04.403)       0:00:36.132 *******
2026-08-22 02:44:01.378641 | instance | ok: [instance] => (item=chronyd)
2026-08-22 02:44:01.378757 | instance | ok: [instance] => (item=sshd)
2026-08-22 02:44:01.378827 | instance |
2026-08-22 02:44:01.379130 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-08-22 02:44:01.379184 | instance | Saturday 22 August 2026  02:44:01 +0000 (0:00:00.673)       0:00:36.805 *******
2026-08-22 02:44:02.650140 | instance | changed: [instance]
2026-08-22 02:44:02.650243 | instance |
2026-08-22 02:44:02.650522 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-08-22 02:44:02.650580 | instance | Saturday 22 August 2026  02:44:02 +0000 (0:00:01.271)       0:00:38.077 *******
2026-08-22 02:44:02.869192 | instance | ok: [instance]
2026-08-22 02:44:02.869257 | instance |
2026-08-22 02:44:02.869551 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-08-22 02:44:02.869601 | instance | Saturday 22 August 2026  02:44:02 +0000 (0:00:00.219)       0:00:38.296 *******
2026-08-22 02:44:03.317969 | instance | changed: [instance]
2026-08-22 02:44:03.318024 | instance |
2026-08-22 02:44:03.318030 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-08-22 02:44:03.318034 | instance | Saturday 22 August 2026  02:44:03 +0000 (0:00:00.448)       0:00:38.745 *******
2026-08-22 02:44:03.655490 | instance | changed: [instance]
2026-08-22 02:44:03.655747 | instance |
2026-08-22 02:44:03.655760 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-08-22 02:44:03.655895 | instance | Saturday 22 August 2026  02:44:03 +0000 (0:00:00.337)       0:00:39.083 *******
2026-08-22 02:44:03.857314 | instance | ok: [instance]
2026-08-22 02:44:03.857390 | instance |
2026-08-22 02:44:03.857670 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-08-22 02:44:03.857719 | instance | Saturday 22 August 2026  02:44:03 +0000 (0:00:00.201)       0:00:39.284 *******
2026-08-22 02:44:04.072242 | instance | ok: [instance] => (item=instance)
2026-08-22 02:44:04.072336 | instance |
2026-08-22 02:44:04.072554 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-08-22 02:44:04.072608 | instance | Saturday 22 August 2026  02:44:04 +0000 (0:00:00.214)       0:00:39.499 *******
2026-08-22 02:44:04.128777 | instance | ok: [instance]
2026-08-22 02:44:04.128846 | instance |
2026-08-22 02:44:04.128853 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-08-22 02:44:04.128859 | instance | Saturday 22 August 2026  02:44:04 +0000 (0:00:00.055)       0:00:39.555 *******
2026-08-22 02:44:04.206586 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-08-22 02:44:04.206654 | instance |
2026-08-22 02:44:04.206961 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-08-22 02:44:04.207025 | instance | Saturday 22 August 2026  02:44:04 +0000 (0:00:00.077)       0:00:39.632 *******
2026-08-22 02:44:04.498649 | instance | changed: [instance]
2026-08-22 02:44:04.498781 | instance |
2026-08-22 02:44:04.498796 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-08-22 02:44:04.498960 | instance | Saturday 22 August 2026  02:44:04 +0000 (0:00:00.292)       0:00:39.925 *******
2026-08-22 02:44:05.138928 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-08-22 02:44:05.139017 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-08-22 02:44:05.139487 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-08-22 02:44:05.139535 | instance |
2026-08-22 02:44:05.139540 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-08-22 02:44:05.139545 | instance | Saturday 22 August 2026  02:44:05 +0000 (0:00:00.640)       0:00:40.566 *******
2026-08-22 02:45:31.444464 | instance | ok: [instance]
2026-08-22 02:45:31.444513 | instance |
2026-08-22 02:45:31.444518 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-08-22 02:45:31.444523 | instance | Saturday 22 August 2026  02:45:31 +0000 (0:01:26.304)       0:02:06.871 *******
2026-08-22 02:45:31.680609 | instance | changed: [instance]
2026-08-22 02:45:31.680665 | instance |
2026-08-22 02:45:31.680672 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-08-22 02:45:31.680679 | instance | Saturday 22 August 2026  02:45:31 +0000 (0:00:00.236)       0:02:07.107 *******
2026-08-22 02:45:31.727243 | instance | ok: [instance]
2026-08-22 02:45:31.727298 | instance |
2026-08-22 02:45:31.727308 | instance | TASK [Install Ceph host] *******************************************************
2026-08-22 02:45:31.727318 | instance | Saturday 22 August 2026  02:45:31 +0000 (0:00:00.046)       0:02:07.153 *******
2026-08-22 02:45:31.810510 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-08-22 02:45:31.810571 | instance |
2026-08-22 02:45:31.810583 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-08-22 02:45:31.810593 | instance | Saturday 22 August 2026  02:45:31 +0000 (0:00:00.083)       0:02:07.237 *******
2026-08-22 02:45:33.432162 | instance | ok: [instance]
2026-08-22 02:45:33.432242 | instance |
2026-08-22 02:45:33.432273 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-08-22 02:45:33.432465 | instance | Saturday 22 August 2026  02:45:33 +0000 (0:00:01.621)       0:02:08.858 *******
2026-08-22 02:45:33.497443 | instance | ok: [instance] => (item=instance)
2026-08-22 02:45:33.497682 | instance |
2026-08-22 02:45:33.497968 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-08-22 02:45:33.498250 | instance | Saturday 22 August 2026  02:45:33 +0000 (0:00:00.065)       0:02:08.924 *******
2026-08-22 02:45:33.889889 | instance | ok: [instance]
2026-08-22 02:45:33.890459 | instance |
2026-08-22 02:45:33.890559 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-08-22 02:45:33.890685 | instance | Saturday 22 August 2026  02:45:33 +0000 (0:00:00.389)       0:02:09.313 *******
2026-08-22 02:45:36.444341 | instance | ok: [instance]
2026-08-22 02:45:36.444438 | instance |
2026-08-22 02:45:36.444603 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-08-22 02:45:36.444768 | instance | Saturday 22 August 2026  02:45:36 +0000 (0:00:02.557)       0:02:11.870 *******
2026-08-22 02:46:24.182881 | 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-08-22 02:46:24.183053 | 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_gsp4h0ai/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_gsp4h0ai/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_gsp4h0ai/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_gsp4h0ai/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-08-22T02:46:24.014+0000 7f75663a0640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAhDYlqItUECiAAUPClH3TPlEDTx1cL8h2LxHm/zdpi7IEMHfF7DRr9tJE=: Malformed input [buffer:3]\n2026-08-22T02:46:24.014+0000 7f75663a0640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-22T02:46:24.014+0000 7f75663a0640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAhDYlqItUECiAAUPClH3TPlEDTx1cL8h2LxHm/zdpi7IEMHfF7DRr9tJE=: Malformed input [buffer:3]\n2026-08-22T02:46:24.014+0000 7f75663a0640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-22T02:46:24.014+0000 7f75663a0640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAhDYlqItUECiAAUPClH3TPlEDTx1cL8h2LxHm/zdpi7IEMHfF7DRr9tJE=: Malformed input [buffer:3]\n2026-08-22T02:46:24.014+0000 7f75663a0640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-08-22T02:46:24.014+0000 7f75663a0640 -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-08-22 02:46:24.186051 | instance |
2026-08-22 02:46:24.188129 | instance | PLAY RECAP *********************************************************************
2026-08-22 02:46:24.188192 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=7    rescued=0    ignored=0
2026-08-22 02:46:24.188200 | instance |
2026-08-22 02:46:24.188206 | instance | Saturday 22 August 2026  02:46:24 +0000 (0:00:47.742)       0:02:59.613 *******
2026-08-22 02:46:24.188212 | instance | ===============================================================================
2026-08-22 02:46:24.188217 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 86.30s
2026-08-22 02:46:24.188227 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 47.74s
2026-08-22 02:46:24.188458 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.90s
2026-08-22 02:46:24.188861 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 4.40s
2026-08-22 02:46:24.189211 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.38s
2026-08-22 02:46:24.189548 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.96s
2026-08-22 02:46:24.189931 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.56s
2026-08-22 02:46:24.190273 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.77s
2026-08-22 02:46:24.190617 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.62s
2026-08-22 02:46:24.191039 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.41s
2026-08-22 02:46:24.191426 | instance | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 1.27s
2026-08-22 02:46:24.191817 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.12s
2026-08-22 02:46:24.192278 | instance | vexxhost.containers.download_artifact : Download item ------------------- 1.06s
2026-08-22 02:46:24.192695 | instance | Gathering Facts --------------------------------------------------------- 0.96s
2026-08-22 02:46:24.193110 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.95s
2026-08-22 02:46:24.193475 | instance | Gathering Facts --------------------------------------------------------- 0.90s
2026-08-22 02:46:24.193871 | instance | Gathering Facts --------------------------------------------------------- 0.90s
2026-08-22 02:46:24.194286 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.79s
2026-08-22 02:46:24.194650 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.72s
2026-08-22 02:46:24.195119 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.70s
2026-08-22 02:46:24.340682 | 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-08-22 02:46:24.341031 | instance | ERROR    [csi > converge] Executed: Failed
2026-08-22 02:46:24.341238 | 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-08-22 02:46:24.574236 | instance | ERROR
2026-08-22 02:46:24.574446 | instance | {
2026-08-22 02:46:24.574475 | instance |   "delta": "0:03:48.942319",
2026-08-22 02:46:24.574496 | instance |   "end": "2026-08-22 02:46:24.397212",
2026-08-22 02:46:24.574515 | instance |   "msg": "non-zero return code",
2026-08-22 02:46:24.574533 | instance |   "rc": 2,
2026-08-22 02:46:24.574551 | instance |   "start": "2026-08-22 02:42:35.454893"
2026-08-22 02:46:24.574571 | instance | }
failure
2026-08-22 02:46:24.579595 | 
2026-08-22 02:46:24.579650 | PLAY RECAP
2026-08-22 02:46:24.579698 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-08-22 02:46:24.579720 | 
2026-08-22 02:46:24.710459 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-08-22 02:46:24.712676 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-08-22 02:46:25.352656 | 
2026-08-22 02:46:25.352768 | PLAY [all]
2026-08-22 02:46:25.366731 | 
2026-08-22 02:46:25.366820 | TASK [gather-host-logs : creating directory for system status]
2026-08-22 02:46:25.721531 | instance | changed
2026-08-22 02:46:25.729414 | 
2026-08-22 02:46:25.729548 | TASK [gather-host-logs : Get logs for each host]
2026-08-22 02:46:26.056611 | instance | + systemd-cgls --full --all --no-pager
2026-08-22 02:46:26.069394 | instance | + ip addr
2026-08-22 02:46:26.070727 | instance | + ip route
2026-08-22 02:46:26.072932 | instance | + lsblk
2026-08-22 02:46:26.075629 | instance | + mount
2026-08-22 02:46:26.077379 | instance | + docker images
2026-08-22 02:46:26.096371 | instance | + brctl show
2026-08-22 02:46:26.096785 | instance | /bin/bash: line 8: brctl: command not found
2026-08-22 02:46:26.096963 | instance | + ps aux --sort=-%mem
2026-08-22 02:46:26.118157 | instance | + dpkg -l
2026-08-22 02:46:26.129341 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-08-22 02:46:26.129722 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-08-22 02:46:26.146367 | instance | + '[' '!' -z '' ']'
2026-08-22 02:46:26.269155 | instance | ok: Runtime: 0:00:00.095271
2026-08-22 02:46:26.277080 | 
2026-08-22 02:46:26.277224 | TASK [gather-host-logs : Downloads logs to executor]
2026-08-22 02:46:26.929870 | instance | changed:
2026-08-22 02:46:26.930075 | instance | created directory /var/lib/zuul/builds/4f2bdffe677a4dd38bf8aa864ee66d14/work/logs/instance
2026-08-22 02:46:26.930115 | instance | cd+++++++++ system/
2026-08-22 02:46:26.930149 | instance | >f+++++++++ system/brctl-show.txt
2026-08-22 02:46:26.930177 | instance | >f+++++++++ system/docker-images.txt
2026-08-22 02:46:26.930204 | instance | >f+++++++++ system/ip-addr.txt
2026-08-22 02:46:26.930234 | instance | >f+++++++++ system/ip-route.txt
2026-08-22 02:46:26.930263 | instance | >f+++++++++ system/lsblk.txt
2026-08-22 02:46:26.930291 | instance | >f+++++++++ system/mount.txt
2026-08-22 02:46:26.930318 | instance | >f+++++++++ system/packages.txt
2026-08-22 02:46:26.930344 | instance | >f+++++++++ system/ps.txt
2026-08-22 02:46:26.930373 | instance | >f+++++++++ system/systemd-cgls.txt
2026-08-22 02:46:26.940289 | 
2026-08-22 02:46:26.940358 | LOOP [helm-release-status : creating directory for helm release status]
2026-08-22 02:46:27.140150 | instance | changed: "values"
2026-08-22 02:46:27.310917 | instance | changed: "releases"
2026-08-22 02:46:27.328486 | 
2026-08-22 02:46:27.328642 | TASK [helm-release-status : Gather get release status for helm charts]
2026-08-22 02:46:27.534910 | instance | /bin/bash: line 3: kubectl: command not found
2026-08-22 02:46:27.869286 | instance | ok: Runtime: 0:00:00.006030
2026-08-22 02:46:27.874566 | 
2026-08-22 02:46:27.874630 | TASK [helm-release-status : Downloads logs to executor]
2026-08-22 02:46:28.355245 | instance | changed:
2026-08-22 02:46:28.355427 | instance | cd+++++++++ helm/
2026-08-22 02:46:28.355466 | instance | cd+++++++++ helm/releases/
2026-08-22 02:46:28.355496 | instance | cd+++++++++ helm/values/
2026-08-22 02:46:28.365679 | 
2026-08-22 02:46:28.365751 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-08-22 02:46:28.560982 | instance | changed
2026-08-22 02:46:28.568440 | 
2026-08-22 02:46:28.568516 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-08-22 02:46:28.808283 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 02:46:28.808403 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 02:46:28.813511 | instance | environment: line 1: kubectl: command not found
2026-08-22 02:46:28.814933 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 02:46:28.815381 | instance | environment: line 1: kubectl: command not found
2026-08-22 02:46:28.816844 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 02:46:28.818762 | instance | environment: line 1: kubectl: command not found
2026-08-22 02:46:28.820219 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 02:46:28.820898 | instance | environment: line 1: kubectl: command not found
2026-08-22 02:46:28.822321 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 02:46:28.822464 | instance | environment: line 1: kubectl: command not found
2026-08-22 02:46:28.823557 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-08-22 02:46:29.108703 | instance | ok: Runtime: 0:00:00.024341
2026-08-22 02:46:29.113661 | 
2026-08-22 02:46:29.113724 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-08-22 02:46:29.310249 | instance | changed
2026-08-22 02:46:29.315962 | 
2026-08-22 02:46:29.316029 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-08-22 02:46:32.952361 | instance | environment: line 5: kubectl: command not found
2026-08-22 02:46:32.953258 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 02:46:32.953582 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 02:46:32.953851 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-08-22 02:46:33.368535 | instance | ok: Runtime: 0:00:00.010351
2026-08-22 02:46:33.376048 | 
2026-08-22 02:46:33.376150 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-08-22 02:46:33.858271 | instance | changed:
2026-08-22 02:46:33.858475 | instance | cd+++++++++ objects/
2026-08-22 02:46:33.858503 | instance | cd+++++++++ objects/cluster/
2026-08-22 02:46:33.858527 | instance | cd+++++++++ objects/namespaced/
2026-08-22 02:46:33.867501 | 
2026-08-22 02:46:33.867572 | TASK [gather-pod-logs : creating directory for pod logs]
2026-08-22 02:46:34.064057 | instance | changed
2026-08-22 02:46:34.069195 | 
2026-08-22 02:46:34.069260 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-08-22 02:46:34.255671 | instance | changed
2026-08-22 02:46:34.261062 | 
2026-08-22 02:46:34.261146 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-08-22 02:46:34.463474 | instance | environment: line 3: kubectl: command not found
2026-08-22 02:46:34.795644 | instance | ok: Runtime: 0:00:00.008503
2026-08-22 02:46:34.802490 | 
2026-08-22 02:46:34.802554 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-08-22 02:46:35.273658 | instance | changed:
2026-08-22 02:46:35.273854 | instance | cd+++++++++ pod-logs/
2026-08-22 02:46:35.273892 | instance | cd+++++++++ pod-logs/failed-pods/
2026-08-22 02:46:35.284159 | 
2026-08-22 02:46:35.284226 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-08-22 02:46:35.481711 | instance | changed
2026-08-22 02:46:35.487985 | 
2026-08-22 02:46:35.488051 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-08-22 02:46:35.687698 | instance | /bin/bash: line 2: kubectl: command not found
2026-08-22 02:46:36.020589 | instance | ok: Runtime: 0:00:00.034691
2026-08-22 02:46:36.026537 | 
2026-08-22 02:46:36.026667 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-08-22 02:46:36.219622 | instance | /bin/bash: line 2: kubectl: command not found
2026-08-22 02:46:36.248493 | instance | ceph-mgr endpoints:
2026-08-22 02:46:36.562898 | instance | ok: Runtime: 0:00:00.034473
2026-08-22 02:46:36.569831 | 
2026-08-22 02:46:36.569897 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-08-22 02:46:36.772410 | instance | /bin/bash: line 4: kubectl: command not found
2026-08-22 02:46:37.103749 | instance | ok: Runtime: 0:00:00.033975
2026-08-22 02:46:37.110831 | 
2026-08-22 02:46:37.110920 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-08-22 02:46:37.569274 | instance | changed: cd+++++++++ prometheus/
2026-08-22 02:46:37.578534 | 
2026-08-22 02:46:37.578608 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-08-22 02:46:37.774246 | instance | changed
2026-08-22 02:46:37.781347 | 
2026-08-22 02:46:37.781412 | TASK [gather-selenium-data : Get selenium data]
2026-08-22 02:46:38.036086 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-08-22 02:46:38.037695 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-08-22 02:46:38.311491 | instance | ERROR
2026-08-22 02:46:38.311679 | instance | {
2026-08-22 02:46:38.311719 | instance |   "delta": "0:00:00.007640",
2026-08-22 02:46:38.311786 | instance |   "end": "2026-08-22 02:46:38.038103",
2026-08-22 02:46:38.311815 | instance |   "msg": "non-zero return code",
2026-08-22 02:46:38.311862 | instance |   "rc": 1,
2026-08-22 02:46:38.311887 | instance |   "start": "2026-08-22 02:46:38.030463"
2026-08-22 02:46:38.311926 | instance | }
2026-08-22 02:46:38.311969 | instance | ERROR: Ignoring Errors
2026-08-22 02:46:38.319245 | 
2026-08-22 02:46:38.319396 | TASK [gather-selenium-data : Downloads logs to executor]
2026-08-22 02:46:38.819989 | instance | changed: cd+++++++++ selenium/
2026-08-22 02:46:38.828024 | 
2026-08-22 02:46:38.828260 | PLAY RECAP
2026-08-22 02:46:38.828327 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-08-22 02:46:38.828362 | 
2026-08-22 02:46:38.966185 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-08-22 02:46:38.970331 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-22 02:46:39.540877 | 
2026-08-22 02:46:39.541072 | PLAY [all]
2026-08-22 02:46:39.553079 | 
2026-08-22 02:46:39.553201 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-22 02:46:39.598044 | instance | skipping: Conditional result was False
2026-08-22 02:46:39.608859 | 
2026-08-22 02:46:39.608938 | TASK [fetch-output : Set log path for single node]
2026-08-22 02:46:39.650608 | instance | ok
2026-08-22 02:46:39.656711 | 
2026-08-22 02:46:39.656776 | LOOP [fetch-output : Ensure local output dirs]
2026-08-22 02:46:40.033304 | instance -> localhost | ok: "/var/lib/zuul/builds/4f2bdffe677a4dd38bf8aa864ee66d14/work/logs"
2026-08-22 02:46:40.244768 | instance -> localhost | changed: "/var/lib/zuul/builds/4f2bdffe677a4dd38bf8aa864ee66d14/work/artifacts"
2026-08-22 02:46:40.449321 | instance -> localhost | changed: "/var/lib/zuul/builds/4f2bdffe677a4dd38bf8aa864ee66d14/work/docs"
2026-08-22 02:46:40.463410 | 
2026-08-22 02:46:40.463541 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-22 02:46:41.084011 | instance | changed: .d..t...... ./
2026-08-22 02:46:41.084184 | instance | changed: All items complete
2026-08-22 02:46:41.084211 | 
2026-08-22 02:46:41.513945 | instance | changed: .d..t...... ./
2026-08-22 02:46:41.967855 | instance | changed: .d..t...... ./
2026-08-22 02:46:41.990615 | 
2026-08-22 02:46:41.990793 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-22 02:46:42.482327 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.009525
2026-08-22 02:46:42.723180 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007563
2026-08-22 02:46:42.734763 | 
2026-08-22 02:46:42.734836 | PLAY [all]
2026-08-22 02:46:42.741329 | 
2026-08-22 02:46:42.741391 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-22 02:46:43.204831 | instance | changed
2026-08-22 02:46:43.211294 | 
2026-08-22 02:46:43.211359 | PLAY RECAP
2026-08-22 02:46:43.211405 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-22 02:46:43.211428 | 
2026-08-22 02:46:43.331281 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-22 02:46:43.335815 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-22 02:46:43.906930 | 
2026-08-22 02:46:43.907044 | PLAY [localhost]
2026-08-22 02:46:43.916906 | 
2026-08-22 02:46:43.917007 | TASK [Generate Zuul manifest]
2026-08-22 02:46:43.934455 | localhost | ok
2026-08-22 02:46:43.960798 | 
2026-08-22 02:46:43.960891 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-22 02:46:44.327349 | localhost | changed
2026-08-22 02:46:44.383107 | 
2026-08-22 02:46:44.383247 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-22 02:46:44.412753 | localhost | ok
2026-08-22 02:46:44.420119 | 
2026-08-22 02:46:44.420190 | TASK [Upload logs]
2026-08-22 02:46:44.443447 | localhost | ok
2026-08-22 02:46:44.559220 | 
2026-08-22 02:46:44.559347 | TASK [Set zuul-log-path fact]
2026-08-22 02:46:44.581189 | localhost | ok
2026-08-22 02:46:44.596310 | 
2026-08-22 02:46:44.596443 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-22 02:46:44.628338 | localhost | ok
2026-08-22 02:46:44.637846 | 
2026-08-22 02:46:44.637980 | TASK [upload-logs : Create log directories]
2026-08-22 02:46:45.015350 | localhost | changed
2026-08-22 02:46:45.021709 | 
2026-08-22 02:46:45.021780 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-22 02:46:45.398853 | localhost -> localhost | ok: Runtime: 0:00:00.005136
2026-08-22 02:46:45.405587 | 
2026-08-22 02:46:45.405647 | TASK [upload-logs : Upload logs to log server]
2026-08-22 02:46:45.843456 | localhost | Output suppressed because no_log was given
2026-08-22 02:46:45.874094 | 
2026-08-22 02:46:45.874215 | LOOP [upload-logs : Compress console log and json output]
2026-08-22 02:46:45.918235 | localhost | skipping: Conditional result was False
2026-08-22 02:46:45.926185 | localhost | skipping: Conditional result was False
2026-08-22 02:46:45.939369 | 
2026-08-22 02:46:45.939547 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-22 02:46:45.977689 | localhost | skipping: Conditional result was False
2026-08-22 02:46:45.977934 | 
2026-08-22 02:46:45.982102 | localhost | skipping: Conditional result was False
2026-08-22 02:46:45.994047 | 
2026-08-22 02:46:45.994261 | LOOP [upload-logs : Upload console log and json output]