2026-09-16 21:35:47.243801 | Job console starting
2026-09-16 21:35:47.262034 | Updating git repos
2026-09-16 21:35:50.926048 | Cloning repos into workspace
2026-09-16 21:36:06.704326 | Restoring repo states
2026-09-16 21:36:12.957744 | Merging changes
2026-09-16 21:36:14.524025 | Checking out repos
2026-09-16 21:36:16.477490 | Preparing playbooks
2026-09-16 21:36:18.686433 | Running Ansible setup
2026-09-16 21:36:23.520683 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-16 21:36:24.084339 | 
2026-09-16 21:36:24.084475 | PLAY [localhost]
2026-09-16 21:36:24.091626 | 
2026-09-16 21:36:24.091698 | TASK [Gathering Facts]
2026-09-16 21:36:25.054329 | localhost | ok
2026-09-16 21:36:25.090015 | 
2026-09-16 21:36:25.091029 | TASK [Setup log path fact]
2026-09-16 21:36:25.114386 | localhost | ok
2026-09-16 21:36:25.135688 | 
2026-09-16 21:36:25.135835 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-16 21:36:25.178766 | localhost | ok
2026-09-16 21:36:25.192698 | 
2026-09-16 21:36:25.192860 | TASK [emit-job-header : Print job information]
2026-09-16 21:36:25.248202 | # Job Information
2026-09-16 21:36:25.248452 | Ansible Version: 2.16.18
2026-09-16 21:36:25.248553 | Job: atmosphere-molecule-csi-rbd
2026-09-16 21:36:25.248602 | Pipeline: check
2026-09-16 21:36:25.248641 | Executor: 2d72f0692154
2026-09-16 21:36:25.248680 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4091
2026-09-16 21:36:25.248725 | Event ID: f397b860-b215-11f1-84f7-6f3b021f98cd
2026-09-16 21:36:25.254959 | 
2026-09-16 21:36:25.255122 | LOOP [emit-job-header : Print node information]
2026-09-16 21:36:25.364832 | localhost | ok:
2026-09-16 21:36:25.365164 | localhost | # Node Information
2026-09-16 21:36:25.365236 | localhost | Inventory Hostname: instance
2026-09-16 21:36:25.365286 | localhost | Hostname: np0000192937
2026-09-16 21:36:25.365334 | localhost | Username: zuul
2026-09-16 21:36:25.365386 | localhost | Distro: Ubuntu 22.04
2026-09-16 21:36:25.365435 | localhost | Provider: yul1
2026-09-16 21:36:25.365481 | localhost | Region: ca-ymq-1
2026-09-16 21:36:25.365526 | localhost | Label: ubuntu-jammy
2026-09-16 21:36:25.365570 | localhost | Product Name: OpenStack Nova
2026-09-16 21:36:25.365613 | localhost | Interface IP: 162.253.55.129
2026-09-16 21:36:25.382412 | 
2026-09-16 21:36:25.435631 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-16 21:36:25.881930 | localhost -> localhost | changed
2026-09-16 21:36:25.891898 | 
2026-09-16 21:36:25.892049 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-16 21:36:26.909430 | localhost -> localhost | changed
2026-09-16 21:36:26.921728 | 
2026-09-16 21:36:26.921858 | PLAY [all]
2026-09-16 21:36:26.940458 | 
2026-09-16 21:36:26.940666 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-16 21:36:27.250191 | instance -> localhost | ok
2026-09-16 21:36:27.263738 | 
2026-09-16 21:36:27.263884 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-16 21:36:27.300362 | instance | ok
2026-09-16 21:36:27.324627 | instance | included: /var/lib/zuul/builds/98519b06c06748d58b55baf049836bb1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-16 21:36:27.331731 | 
2026-09-16 21:36:27.331814 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-16 21:36:28.000286 | instance -> localhost | Generating public/private rsa key pair.
2026-09-16 21:36:28.000542 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/98519b06c06748d58b55baf049836bb1/work/98519b06c06748d58b55baf049836bb1_id_rsa
2026-09-16 21:36:28.000581 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/98519b06c06748d58b55baf049836bb1/work/98519b06c06748d58b55baf049836bb1_id_rsa.pub
2026-09-16 21:36:28.000609 | instance -> localhost | The key fingerprint is:
2026-09-16 21:36:28.000635 | instance -> localhost | SHA256:O6DysnPml+550Ca39Ssd7trWoUQj+S37icU0Abc6nlU zuul-build-sshkey
2026-09-16 21:36:28.000677 | instance -> localhost | The key's randomart image is:
2026-09-16 21:36:28.000704 | instance -> localhost | +---[RSA 3072]----+
2026-09-16 21:36:28.000735 | instance -> localhost | |            . .  |
2026-09-16 21:36:28.000762 | instance -> localhost | |             o . |
2026-09-16 21:36:28.000786 | instance -> localhost | |           .  o E|
2026-09-16 21:36:28.000811 | instance -> localhost | |          o o. o |
2026-09-16 21:36:28.000836 | instance -> localhost | |      ..S  +oo+  |
2026-09-16 21:36:28.000862 | instance -> localhost | |     .o.+...=*o. |
2026-09-16 21:36:28.000886 | instance -> localhost | |  . .  *oo =o*o. |
2026-09-16 21:36:28.000911 | instance -> localhost | |  ooo o.o...Bo.. |
2026-09-16 21:36:28.000941 | instance -> localhost | |  .Bo++.  .==oo  |
2026-09-16 21:36:28.000968 | instance -> localhost | +----[SHA256]-----+
2026-09-16 21:36:28.001048 | instance -> localhost | ok: Runtime: 0:00:00.219700
2026-09-16 21:36:28.009413 | 
2026-09-16 21:36:28.009614 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-16 21:36:28.041417 | instance | ok
2026-09-16 21:36:28.053220 | instance | included: /var/lib/zuul/builds/98519b06c06748d58b55baf049836bb1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-16 21:36:28.063408 | 
2026-09-16 21:36:28.063593 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-16 21:36:28.088189 | instance | skipping: Conditional result was False
2026-09-16 21:36:28.096060 | 
2026-09-16 21:36:28.096246 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-16 21:36:28.580021 | instance | changed
2026-09-16 21:36:28.589717 | 
2026-09-16 21:36:28.589856 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-16 21:36:28.788164 | instance | ok
2026-09-16 21:36:28.799831 | 
2026-09-16 21:36:28.799960 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-16 21:36:29.293847 | instance | changed
2026-09-16 21:36:29.301276 | 
2026-09-16 21:36:29.301365 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-16 21:36:29.806437 | instance | changed
2026-09-16 21:36:29.817891 | 
2026-09-16 21:36:29.818030 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-16 21:36:29.846760 | instance | skipping: Conditional result was False
2026-09-16 21:36:29.858898 | 
2026-09-16 21:36:29.859144 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-16 21:36:30.263749 | instance -> localhost | changed
2026-09-16 21:36:30.289788 | 
2026-09-16 21:36:30.289974 | TASK [add-build-sshkey : Add back temp key]
2026-09-16 21:36:30.635314 | instance -> localhost | Identity added: /var/lib/zuul/builds/98519b06c06748d58b55baf049836bb1/work/98519b06c06748d58b55baf049836bb1_id_rsa (zuul-build-sshkey)
2026-09-16 21:36:30.635566 | instance -> localhost | ok: Runtime: 0:00:00.015571
2026-09-16 21:36:30.643368 | 
2026-09-16 21:36:30.643451 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-16 21:36:31.018682 | instance | ok
2026-09-16 21:36:31.025626 | 
2026-09-16 21:36:31.025730 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-16 21:36:31.073125 | instance | skipping: Conditional result was False
2026-09-16 21:36:31.090878 | 
2026-09-16 21:36:31.090970 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-16 21:36:31.425934 | instance | ok
2026-09-16 21:36:31.433707 | 
2026-09-16 21:36:31.433784 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-16 21:36:35.263813 | instance | Output suppressed because no_log was given
2026-09-16 21:36:35.272462 | 
2026-09-16 21:36:35.272546 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-16 21:36:35.462578 | instance | ok: "logs"
2026-09-16 21:36:35.463164 | instance | ok: All items complete
2026-09-16 21:36:35.463236 | 
2026-09-16 21:36:35.587273 | instance | ok: "artifacts"
2026-09-16 21:36:35.732571 | instance | ok: "docs"
2026-09-16 21:36:35.745939 | 
2026-09-16 21:36:35.746277 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-16 21:36:35.948384 | instance | changed: "logs"
2026-09-16 21:36:36.102022 | instance | changed: "artifacts"
2026-09-16 21:36:36.284714 | instance | changed: "docs"
2026-09-16 21:36:36.306947 | 
2026-09-16 21:36:36.307160 | PLAY RECAP
2026-09-16 21:36:36.307345 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-16 21:36:36.307408 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-16 21:36:36.307452 | 
2026-09-16 21:36:36.464272 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-16 21:36:36.468036 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-16 21:36:37.098897 | 
2026-09-16 21:36:37.099325 | PLAY [all]
2026-09-16 21:36:37.110284 | 
2026-09-16 21:36:37.110386 | TASK [setup-uv : Extract archive]
2026-09-16 21:36:39.430912 | instance | changed
2026-09-16 21:36:39.444830 | 
2026-09-16 21:36:39.445011 | TASK [setup-uv : Print version]
2026-09-16 21:36:39.866891 | instance | uv 0.8.13
2026-09-16 21:36:39.986017 | instance | ok: Runtime: 0:00:00.021777
2026-09-16 21:36:40.011718 | 
2026-09-16 21:36:40.012188 | TASK [Install Ansible collection siblings]
2026-09-16 21:36:49.708446 | instance | changed:
2026-09-16 21:36:49.708659 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-16 21:36:49.708687 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-16 21:36:49.708708 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-16 21:36:49.708725 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-16 21:36:49.708742 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-16 21:36:49.708762 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-16 21:36:49.708780 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-16 21:36:49.708800 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-16 21:36:49.708817 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-16 21:36:49.708834 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-16 21:36:49.708850 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-16 21:36:49.708867 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-16 21:36:49.708883 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-09-16 21:36:49.708899 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-16 21:36:49.708915 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0
2026-09-16 21:36:49.708932 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-16 21:36:49.708948 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-16 21:36:49.708965 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-16 21:36:49.708982 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-16 21:36:49.708998 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-09-16 21:36:49.709015 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.1.2
2026-09-16 21:36:49.709031 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-09-16 21:36:49.709049 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-16 21:36:49.709088 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-16 21:36:49.709108 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0
2026-09-16 21:36:49.709124 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-16 21:36:49.709140 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-16 21:36:49.709156 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-16 21:36:49.709171 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-16 21:36:49.709187 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-16 21:36:49.709202 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-16 21:36:49.709218 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-16 21:36:49.709234 | instance | Checked out ansible.netcommon to 1.2.0
2026-09-16 21:36:49.709250 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-16 21:36:49.709272 | instance | Starting galaxy collection install process
2026-09-16 21:36:49.709287 | instance | Process install dependency map
2026-09-16 21:36:49.709303 | instance | Starting collection install process
2026-09-16 21:36:49.709319 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-16 21:36:49.709334 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-16 21:36:49.709350 | instance | ansible.netcommon:* was installed successfully
2026-09-16 21:36:49.709366 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-16 21:36:49.709382 | instance | Creating virtual environment at: .venv
2026-09-16 21:36:49.709398 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-16 21:36:49.709413 | instance | Downloading cryptography (4.2MiB)
2026-09-16 21:36:49.709430 | instance | Downloading openstacksdk (1.7MiB)
2026-09-16 21:36:49.709449 | instance | Downloading ansible-core (2.1MiB)
2026-09-16 21:36:49.709466 | instance | Downloading rjsonnet (1.2MiB)
2026-09-16 21:36:49.709483 | instance | Downloading kubernetes (1.9MiB)
2026-09-16 21:36:49.709499 | instance | Downloading pygments (1.2MiB)
2026-09-16 21:36:49.709516 | instance | Downloading setuptools (1.1MiB)
2026-09-16 21:36:49.709538 | instance | Downloading netaddr (2.2MiB)
2026-09-16 21:36:49.709555 | instance |  Downloading rjsonnet
2026-09-16 21:36:49.709572 | instance |    Building pyperclip==1.9.0
2026-09-16 21:36:49.709590 | instance |  Downloading netaddr
2026-09-16 21:36:49.709613 | instance |  Downloading pygments
2026-09-16 21:36:49.709635 | instance |  Downloading cryptography
2026-09-16 21:36:49.709657 | instance |  Downloading setuptools
2026-09-16 21:36:49.709674 | instance |  Downloading kubernetes
2026-09-16 21:36:49.709691 | instance |  Downloading ansible-core
2026-09-16 21:36:49.709707 | instance |  Downloading openstacksdk
2026-09-16 21:36:49.709728 | instance |       Built pyperclip==1.9.0
2026-09-16 21:36:49.709754 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-16 21:36:49.709773 | instance | Installed 79 packages in 150ms
2026-09-16 21:36:49.709790 | instance | Checked out ansible.posix to 1.6.0
2026-09-16 21:36:49.709807 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-16 21:36:49.709824 | instance | Starting galaxy collection install process
2026-09-16 21:36:49.709841 | instance | Process install dependency map
2026-09-16 21:36:49.709857 | instance | Starting collection install process
2026-09-16 21:36:49.709874 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-16 21:36:49.709897 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-16 21:36:49.709916 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-16 21:36:49.709933 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-16 21:36:49.709950 | instance | 2.17.12
2026-09-16 21:36:49.709967 | instance | Checked out ansible.utils to v6.1.0
2026-09-16 21:36:49.709983 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-16 21:36:49.710000 | instance | Starting galaxy collection install process
2026-09-16 21:36:49.710016 | instance | Process install dependency map
2026-09-16 21:36:49.710033 | instance | Starting collection install process
2026-09-16 21:36:49.710049 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-16 21:36:49.710065 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-16 21:36:49.710095 | instance | ansible.utils:6.1.0 was installed successfully
2026-09-16 21:36:49.710112 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-16 21:36:49.710129 | instance | 2.17.12
2026-09-16 21:36:49.710145 | instance | Checked out atmosphere.common to v0.7.0
2026-09-16 21:36:49.710161 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-16 21:36:49.710178 | instance | Starting galaxy collection install process
2026-09-16 21:36:49.710194 | instance | Process install dependency map
2026-09-16 21:36:49.710210 | instance | Starting collection install process
2026-09-16 21:36:49.710226 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-16 21:36:49.710243 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-16 21:36:49.710259 | instance | atmosphere.common:0.7.0 was installed successfully
2026-09-16 21:36:49.710275 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-16 21:36:49.710291 | instance | 2.17.12
2026-09-16 21:36:49.710308 | instance | Checked out community.crypto to 2.2.3
2026-09-16 21:36:49.710324 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-16 21:36:49.710341 | instance | Starting galaxy collection install process
2026-09-16 21:36:49.710358 | instance | Process install dependency map
2026-09-16 21:36:49.710375 | instance | Starting collection install process
2026-09-16 21:36:49.710391 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-16 21:36:49.710407 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-16 21:36:49.710423 | instance | community.crypto:2.2.3 was installed successfully
2026-09-16 21:36:49.710440 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-16 21:36:49.710456 | instance | 2.17.12
2026-09-16 21:36:49.710472 | instance | Checked out community.general to 7.3.0
2026-09-16 21:36:49.710489 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general
2026-09-16 21:36:49.710505 | instance | Starting galaxy collection install process
2026-09-16 21:36:49.710526 | instance | Process install dependency map
2026-09-16 21:36:49.710543 | instance | Starting collection install process
2026-09-16 21:36:49.710560 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-16 21:36:49.710577 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-16 21:36:49.710594 | instance | community.general:7.3.0 was installed successfully
2026-09-16 21:36:49.710610 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-16 21:36:49.710626 | instance | 2.17.12
2026-09-16 21:36:49.710642 | instance | Checked out community.mysql to 3.6.0
2026-09-16 21:36:49.710659 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-16 21:36:49.710676 | instance | Starting galaxy collection install process
2026-09-16 21:36:49.710694 | instance | Process install dependency map
2026-09-16 21:36:49.710712 | instance | Starting collection install process
2026-09-16 21:36:49.710729 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-16 21:36:49.710746 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-16 21:36:49.710762 | instance | community.mysql:3.6.0 was installed successfully
2026-09-16 21:36:49.710780 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-16 21:36:49.710797 | instance | 2.17.12
2026-09-16 21:36:49.710814 | instance | Checked out kubernetes.core to 2.4.0
2026-09-16 21:36:49.710832 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-16 21:36:49.710848 | instance | Starting galaxy collection install process
2026-09-16 21:36:49.710870 | instance | Process install dependency map
2026-09-16 21:36:49.710887 | instance | Starting collection install process
2026-09-16 21:36:49.710903 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-16 21:36:49.710920 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-16 21:36:49.710937 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-16 21:36:49.710959 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-16 21:36:49.710976 | instance | 2.17.12
2026-09-16 21:36:49.710992 | instance | Checked out openstack.cloud to 2.6.0
2026-09-16 21:36:49.711009 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-16 21:36:49.711025 | instance | Starting galaxy collection install process
2026-09-16 21:36:49.711041 | instance | Process install dependency map
2026-09-16 21:36:49.711057 | instance | Starting collection install process
2026-09-16 21:36:49.711084 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-16 21:36:49.711111 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-16 21:36:49.711128 | instance | openstack.cloud:2.6.0 was installed successfully
2026-09-16 21:36:49.711144 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-16 21:36:49.711160 | instance | 2.17.12
2026-09-16 21:36:49.711176 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-16 21:36:49.711192 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-16 21:36:49.711215 | instance | Starting galaxy collection install process
2026-09-16 21:36:49.711232 | instance | Process install dependency map
2026-09-16 21:36:49.711249 | instance | Starting collection install process
2026-09-16 21:36:49.711266 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-16 21:36:49.711283 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-16 21:36:49.711300 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-16 21:36:49.711316 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-16 21:36:49.711332 | instance | 2.17.12
2026-09-16 21:36:49.711348 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-16 21:36:49.711364 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-16 21:36:49.711380 | instance | Starting galaxy collection install process
2026-09-16 21:36:49.711395 | instance | Process install dependency map
2026-09-16 21:36:49.711411 | instance | Starting collection install process
2026-09-16 21:36:49.711427 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-16 21:36:49.711443 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-16 21:36:49.711459 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-16 21:36:49.711475 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-16 21:36:49.711491 | instance | 2.17.12
2026-09-16 21:36:49.711507 | instance | Checked out vexxhost.kubernetes to v3.4.0
2026-09-16 21:36:49.711523 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-16 21:36:49.711539 | instance | Starting galaxy collection install process
2026-09-16 21:36:49.711554 | instance | Process install dependency map
2026-09-16 21:36:49.711570 | instance | Starting collection install process
2026-09-16 21:36:49.711586 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-16 21:36:49.711601 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-16 21:36:49.711617 | instance | vexxhost.kubernetes:3.4.0 was installed successfully
2026-09-16 21:36:49.711634 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-16 21:36:49.711649 | instance | 2.17.12
2026-09-16 21:36:49.717565 | 
2026-09-16 21:36:49.717730 | PLAY RECAP
2026-09-16 21:36:49.717828 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-16 21:36:49.717874 | 
2026-09-16 21:36:49.852513 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-16 21:36:49.854333 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-16 21:36:50.458665 | 
2026-09-16 21:36:50.458798 | PLAY [all]
2026-09-16 21:36:50.469437 | 
2026-09-16 21:36:50.469517 | TASK [Install "jq" for log collection]
2026-09-16 21:37:00.673873 | instance | changed
2026-09-16 21:37:00.676990 | 
2026-09-16 21:37:00.677142 | PLAY RECAP
2026-09-16 21:37:00.677245 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-16 21:37:00.677344 | 
2026-09-16 21:37:00.845537 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-16 21:37:00.849205 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-16 21:37:01.431986 | 
2026-09-16 21:37:01.432132 | PLAY [all]
2026-09-16 21:37:01.442568 | 
2026-09-16 21:37:01.442646 | LOOP [Check inventory directories]
2026-09-16 21:37:01.800927 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-16 21:37:01.984585 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-16 21:37:02.169548 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-16 21:37:02.190843 | 
2026-09-16 21:37:02.191226 | LOOP [Copy inventory file for Zuul]
2026-09-16 21:37:03.009069 | instance | changed:
2026-09-16 21:37:03.009338 | instance | {
2026-09-16 21:37:03.009367 | instance |   "ansible_loop_var": "item",
2026-09-16 21:37:03.009388 | instance |   "changed": false,
2026-09-16 21:37:03.009406 | instance |   "failed": false,
2026-09-16 21:37:03.009426 | instance |   "invocation": {
2026-09-16 21:37:03.009442 | instance |     "module_args": {
2026-09-16 21:37:03.009457 | instance |       "checksum_algorithm": "sha1",
2026-09-16 21:37:03.009473 | instance |       "follow": true,
2026-09-16 21:37:03.009488 | instance |       "get_attributes": true,
2026-09-16 21:37:03.009504 | instance |       "get_checksum": true,
2026-09-16 21:37:03.009520 | instance |       "get_mime": true,
2026-09-16 21:37:03.009534 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-16 21:37:03.009550 | instance |     }
2026-09-16 21:37:03.009565 | instance |   },
2026-09-16 21:37:03.009579 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-16 21:37:03.009594 | instance |   "stat": {
2026-09-16 21:37:03.009609 | instance |     "atime": 1789594602.4434361,
2026-09-16 21:37:03.009624 | instance |     "attr_flags": "e",
2026-09-16 21:37:03.009640 | instance |     "attributes": [
2026-09-16 21:37:03.009654 | instance |       "extents"
2026-09-16 21:37:03.009669 | instance |     ],
2026-09-16 21:37:03.009684 | instance |     "block_size": 4096,
2026-09-16 21:37:03.009699 | instance |     "blocks": 8,
2026-09-16 21:37:03.009713 | instance |     "charset": "unknown",
2026-09-16 21:37:03.009729 | instance |     "ctime": 1789594600.9274364,
2026-09-16 21:37:03.009744 | instance |     "dev": 64513,
2026-09-16 21:37:03.009759 | instance |     "device_type": 0,
2026-09-16 21:37:03.009773 | instance |     "executable": true,
2026-09-16 21:37:03.009788 | instance |     "exists": true,
2026-09-16 21:37:03.009803 | instance |     "gid": 1000,
2026-09-16 21:37:03.009817 | instance |     "gr_name": "zuul",
2026-09-16 21:37:03.009832 | instance |     "inode": 1048685,
2026-09-16 21:37:03.009846 | instance |     "isblk": false,
2026-09-16 21:37:03.009861 | instance |     "ischr": false,
2026-09-16 21:37:03.009876 | instance |     "isdir": true,
2026-09-16 21:37:03.009891 | instance |     "isfifo": false,
2026-09-16 21:37:03.009905 | instance |     "isgid": false,
2026-09-16 21:37:03.009919 | instance |     "islnk": false,
2026-09-16 21:37:03.009934 | instance |     "isreg": false,
2026-09-16 21:37:03.009948 | instance |     "issock": false,
2026-09-16 21:37:03.009962 | instance |     "isuid": false,
2026-09-16 21:37:03.009976 | instance |     "mimetype": "unknown",
2026-09-16 21:37:03.009991 | instance |     "mode": "0755",
2026-09-16 21:37:03.010005 | instance |     "mtime": 1789594600.9274364,
2026-09-16 21:37:03.010019 | instance |     "nlink": 21,
2026-09-16 21:37:03.010034 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-16 21:37:03.010048 | instance |     "pw_name": "zuul",
2026-09-16 21:37:03.010065 | instance |     "readable": true,
2026-09-16 21:37:03.010093 | instance |     "rgrp": true,
2026-09-16 21:37:03.010109 | instance |     "roth": true,
2026-09-16 21:37:03.010124 | instance |     "rusr": true,
2026-09-16 21:37:03.010139 | instance |     "size": 4096,
2026-09-16 21:37:03.010154 | instance |     "uid": 1000,
2026-09-16 21:37:03.010185 | instance |     "version": "1408566822",
2026-09-16 21:37:03.010201 | instance |     "wgrp": false,
2026-09-16 21:37:03.010217 | instance |     "woth": false,
2026-09-16 21:37:03.010232 | instance |     "writeable": true,
2026-09-16 21:37:03.010247 | instance |     "wusr": true,
2026-09-16 21:37:03.010262 | instance |     "xgrp": true,
2026-09-16 21:37:03.010276 | instance |     "xoth": true,
2026-09-16 21:37:03.010290 | instance |     "xusr": true
2026-09-16 21:37:03.010304 | instance |   }
2026-09-16 21:37:03.010319 | instance | }
2026-09-16 21:37:03.010351 | instance | ok: All items complete
2026-09-16 21:37:03.010369 | 
2026-09-16 21:37:03.012804 | instance | skipping: Conditional result was False
2026-09-16 21:37:03.020235 | instance | skipping: Conditional result was False
2026-09-16 21:37:03.045962 | 
2026-09-16 21:37:03.046092 | LOOP [Switch "ansible_host" to private IP]
2026-09-16 21:37:03.441387 | instance | changed: 1 replacements made
2026-09-16 21:37:03.444206 | instance | skipping: Conditional result was False
2026-09-16 21:37:03.452380 | instance | skipping: Conditional result was False
2026-09-16 21:37:03.478966 | 
2026-09-16 21:37:03.479368 | TASK [Run Molecule scenario]
2026-09-16 21:37:04.440225 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-16 21:37:04.870580 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-16 21:37:04.870674 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-16 21:37:15.639512 | instance | INFO     [csi > dependency] Executing
2026-09-16 21:37:15.639747 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-16 21:37:15.640083 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-16 21:37:15.640208 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-16 21:37:15.650871 | instance | INFO     [csi > cleanup] Executing
2026-09-16 21:37:15.651344 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-16 21:37:15.658382 | instance | INFO     [csi > destroy] Executing
2026-09-16 21:37:15.658432 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-16 21:37:15.658515 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-16 21:37:15.665205 | instance | INFO     [csi > syntax] Executing
2026-09-16 21:37:17.109729 | instance |
2026-09-16 21:37:17.110378 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-16 21:37:17.169797 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-16 21:37:17.186301 | instance | INFO     [csi > create] Executing
2026-09-16 21:37:17.189924 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-16 21:37:17.205573 | instance | INFO     [csi > prepare] Executing
2026-09-16 21:37:17.964588 | instance |
2026-09-16 21:37:17.964802 | instance | PLAY [Prepare] *****************************************************************
2026-09-16 21:37:17.965057 | instance |
2026-09-16 21:37:17.965430 | instance | TASK [Gathering Facts] *********************************************************
2026-09-16 21:37:17.965706 | instance | Wednesday 16 September 2026  21:37:17 +0000 (0:00:00.027)       0:00:00.027 ***
2026-09-16 21:37:19.210759 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-16 21:37:19.210883 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-16 21:37:19.210991 | instance | interpreter could change the meaning of that path. See
2026-09-16 21:37:19.211113 | instance | https://docs.ansible.com/ansible-
2026-09-16 21:37:19.211243 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-16 21:37:19.220364 | instance | ok: [instance]
2026-09-16 21:37:19.220454 | instance |
2026-09-16 21:37:19.220572 | instance | TASK [Configure short hostname] ************************************************
2026-09-16 21:37:19.220684 | instance | Wednesday 16 September 2026  21:37:19 +0000 (0:00:01.257)       0:00:01.284 ***
2026-09-16 21:37:19.965574 | instance | changed: [instance]
2026-09-16 21:37:19.965655 | instance |
2026-09-16 21:37:19.965791 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-16 21:37:19.965958 | instance | Wednesday 16 September 2026  21:37:19 +0000 (0:00:00.742)       0:00:02.026 ***
2026-09-16 21:37:20.273935 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-16 21:37:20.274174 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-16 21:37:20.274419 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-16 21:37:20.281271 | instance | changed: [instance]
2026-09-16 21:37:20.281519 | instance |
2026-09-16 21:37:20.281812 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-16 21:37:20.282063 | instance | Wednesday 16 September 2026  21:37:20 +0000 (0:00:00.318)       0:00:02.345 ***
2026-09-16 21:37:21.103059 | instance | ok: [instance]
2026-09-16 21:37:21.103169 | instance |
2026-09-16 21:37:21.103324 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-16 21:37:21.103437 | instance |
2026-09-16 21:37:21.103571 | instance | TASK [Gathering Facts] *********************************************************
2026-09-16 21:37:21.103737 | instance | Wednesday 16 September 2026  21:37:21 +0000 (0:00:00.821)       0:00:03.166 ***
2026-09-16 21:37:21.869982 | instance | ok: [instance]
2026-09-16 21:37:21.870179 | instance |
2026-09-16 21:37:21.870427 | instance | TASK [Install depedencies] *****************************************************
2026-09-16 21:37:21.870671 | instance | Wednesday 16 September 2026  21:37:21 +0000 (0:00:00.766)       0:00:03.933 ***
2026-09-16 21:37:44.607170 | instance | changed: [instance]
2026-09-16 21:37:44.607397 | instance |
2026-09-16 21:37:44.607645 | instance | TASK [Start up service] ********************************************************
2026-09-16 21:37:44.607914 | instance | Wednesday 16 September 2026  21:37:44 +0000 (0:00:22.737)       0:00:26.671 ***
2026-09-16 21:37:45.132668 | instance | ok: [instance]
2026-09-16 21:37:45.132862 | instance |
2026-09-16 21:37:45.133112 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-16 21:37:45.133407 | instance | Wednesday 16 September 2026  21:37:45 +0000 (0:00:00.525)       0:00:27.196 ***
2026-09-16 21:37:45.426443 | instance | ok: [instance]
2026-09-16 21:37:45.426534 | instance |
2026-09-16 21:37:45.426658 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-16 21:37:45.426825 | instance | Wednesday 16 September 2026  21:37:45 +0000 (0:00:00.293)       0:00:27.490 ***
2026-09-16 21:37:46.083717 | instance | changed: [instance]
2026-09-16 21:37:46.083874 | instance |
2026-09-16 21:37:46.083883 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-16 21:37:46.083889 | instance | Wednesday 16 September 2026  21:37:46 +0000 (0:00:00.657)       0:00:28.147 ***
2026-09-16 21:37:46.263699 | instance | ok: [instance]
2026-09-16 21:37:46.264434 | instance |
2026-09-16 21:37:46.264484 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-16 21:37:46.264493 | instance | Wednesday 16 September 2026  21:37:46 +0000 (0:00:00.180)       0:00:28.327 ***
2026-09-16 21:37:46.285931 | instance | skipping: [instance]
2026-09-16 21:37:46.286014 | instance |
2026-09-16 21:37:46.286405 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-16 21:37:46.286436 | instance | Wednesday 16 September 2026  21:37:46 +0000 (0:00:00.022)       0:00:28.350 ***
2026-09-16 21:37:46.881984 | instance | changed: [instance] => (item=osd0)
2026-09-16 21:37:46.883305 | instance | changed: [instance] => (item=osd1)
2026-09-16 21:37:46.883359 | instance | changed: [instance] => (item=osd2)
2026-09-16 21:37:46.883371 | instance |
2026-09-16 21:37:46.883382 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-16 21:37:46.883392 | instance | Wednesday 16 September 2026  21:37:46 +0000 (0:00:00.595)       0:00:28.945 ***
2026-09-16 21:37:47.505005 | instance | changed: [instance] => (item=osd0)
2026-09-16 21:37:47.505175 | instance | changed: [instance] => (item=osd1)
2026-09-16 21:37:47.506331 | instance | changed: [instance] => (item=osd2)
2026-09-16 21:37:47.506404 | instance |
2026-09-16 21:37:47.506421 | instance | TASK [Start loop devices] ******************************************************
2026-09-16 21:37:47.506436 | instance | Wednesday 16 September 2026  21:37:47 +0000 (0:00:00.622)       0:00:29.568 ***
2026-09-16 21:37:48.206355 | instance | changed: [instance] => (item=osd0)
2026-09-16 21:37:48.206391 | instance | changed: [instance] => (item=osd1)
2026-09-16 21:37:48.206395 | instance | changed: [instance] => (item=osd2)
2026-09-16 21:37:48.206400 | instance |
2026-09-16 21:37:48.206404 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-16 21:37:48.206409 | instance | Wednesday 16 September 2026  21:37:48 +0000 (0:00:00.701)       0:00:30.270 ***
2026-09-16 21:37:51.355241 | instance | changed: [instance] => (item=osd0)
2026-09-16 21:37:51.355941 | instance | changed: [instance] => (item=osd1)
2026-09-16 21:37:51.355980 | instance | changed: [instance] => (item=osd2)
2026-09-16 21:37:51.356626 | instance |
2026-09-16 21:37:51.356661 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-16 21:37:51.356673 | instance | Wednesday 16 September 2026  21:37:51 +0000 (0:00:03.148)       0:00:33.418 ***
2026-09-16 21:37:53.347929 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-16 21:37:53.347986 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-16 21:37:53.348009 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-16 21:37:53.348015 | instance |
2026-09-16 21:37:53.348022 | instance | PLAY RECAP *********************************************************************
2026-09-16 21:37:53.348028 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-16 21:37:53.348034 | instance |
2026-09-16 21:37:53.348039 | instance | Wednesday 16 September 2026  21:37:53 +0000 (0:00:01.989)       0:00:35.408 ***
2026-09-16 21:37:53.348044 | instance | ===============================================================================
2026-09-16 21:37:53.348049 | instance | Install depedencies ---------------------------------------------------- 22.74s
2026-09-16 21:37:53.348054 | instance | Create a volume group for each loop device ------------------------------ 3.15s
2026-09-16 21:37:53.348059 | instance | Create a logical volume for each loop device ---------------------------- 1.99s
2026-09-16 21:37:53.348065 | instance | Gathering Facts --------------------------------------------------------- 1.26s
2026-09-16 21:37:53.348070 | instance | Purge "snapd" package --------------------------------------------------- 0.82s
2026-09-16 21:37:53.348076 | instance | Gathering Facts --------------------------------------------------------- 0.77s
2026-09-16 21:37:53.348081 | instance | Configure short hostname ------------------------------------------------ 0.74s
2026-09-16 21:37:53.348085 | instance | Start loop devices ------------------------------------------------------ 0.70s
2026-09-16 21:37:53.348091 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.66s
2026-09-16 21:37:53.348096 | instance | Set permissions on loopback devices ------------------------------------- 0.62s
2026-09-16 21:37:53.348106 | instance | Create devices for Ceph ------------------------------------------------- 0.60s
2026-09-16 21:37:53.348533 | instance | Start up service -------------------------------------------------------- 0.53s
2026-09-16 21:37:53.348840 | instance | Ensure hostname inside hosts file --------------------------------------- 0.32s
2026-09-16 21:37:53.349039 | instance | Generate lvm.conf ------------------------------------------------------- 0.29s
2026-09-16 21:37:53.349235 | instance | Get list of all loopback devices ---------------------------------------- 0.18s
2026-09-16 21:37:53.349538 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s
2026-09-16 21:37:53.418535 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-16 21:37:53.434241 | instance | INFO     [csi > converge] Executing
2026-09-16 21:37:54.803258 | instance |
2026-09-16 21:37:54.803662 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-16 21:37:54.803981 | instance |
2026-09-16 21:37:54.804277 | instance | TASK [Gathering Facts] *********************************************************
2026-09-16 21:37:54.804599 | instance | Wednesday 16 September 2026  21:37:54 +0000 (0:00:00.007)       0:00:00.007 ***
2026-09-16 21:37:55.864509 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-16 21:37:55.864883 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-16 21:37:55.865197 | instance | interpreter could change the meaning of that path. See
2026-09-16 21:37:55.865525 | instance | https://docs.ansible.com/ansible-
2026-09-16 21:37:55.865831 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-16 21:37:55.873143 | instance | ok: [instance]
2026-09-16 21:37:55.873495 | instance |
2026-09-16 21:37:55.873815 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-16 21:37:55.874121 | instance | Wednesday 16 September 2026  21:37:55 +0000 (0:00:01.070)       0:00:01.077 ***
2026-09-16 21:37:55.931254 | instance | ok: [instance] => {
2026-09-16 21:37:55.931609 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-16 21:37:55.931929 | instance | }
2026-09-16 21:37:55.932217 | instance |
2026-09-16 21:37:55.932536 | instance | PLAY [all] *********************************************************************
2026-09-16 21:37:55.932809 | instance |
2026-09-16 21:37:55.933103 | instance | TASK [Gathering Facts] *********************************************************
2026-09-16 21:37:55.933446 | instance | Wednesday 16 September 2026  21:37:55 +0000 (0:00:00.058)       0:00:01.135 ***
2026-09-16 21:37:56.870762 | instance | ok: [instance]
2026-09-16 21:37:56.871091 | instance |
2026-09-16 21:37:56.871419 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-16 21:37:56.871713 | instance | Wednesday 16 September 2026  21:37:56 +0000 (0:00:00.939)       0:00:02.074 ***
2026-09-16 21:37:57.084018 | instance | ok: [instance]
2026-09-16 21:37:57.084194 | instance |
2026-09-16 21:37:57.084349 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-16 21:37:57.084478 | instance |
2026-09-16 21:37:57.084610 | instance | TASK [Gathering Facts] *********************************************************
2026-09-16 21:37:57.084805 | instance | Wednesday 16 September 2026  21:37:57 +0000 (0:00:00.211)       0:00:02.286 ***
2026-09-16 21:37:58.066795 | instance | ok: [instance]
2026-09-16 21:37:58.066951 | instance |
2026-09-16 21:37:58.066964 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-16 21:37:58.066984 | instance | Wednesday 16 September 2026  21:37:58 +0000 (0:00:00.982)       0:00:03.268 ***
2026-09-16 21:37:58.371376 | instance | ok: [instance]
2026-09-16 21:37:58.371442 | instance |
2026-09-16 21:37:58.371448 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-16 21:37:58.371454 | instance | Wednesday 16 September 2026  21:37:58 +0000 (0:00:00.305)       0:00:03.574 ***
2026-09-16 21:37:58.408103 | instance | skipping: [instance]
2026-09-16 21:37:58.408213 | instance |
2026-09-16 21:37:58.408221 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-16 21:37:58.408236 | instance | Wednesday 16 September 2026  21:37:58 +0000 (0:00:00.036)       0:00:03.611 ***
2026-09-16 21:37:58.729963 | instance | changed: [instance]
2026-09-16 21:37:58.730065 | instance |
2026-09-16 21:37:58.730146 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-16 21:37:58.730276 | instance | Wednesday 16 September 2026  21:37:58 +0000 (0:00:00.322)       0:00:03.933 ***
2026-09-16 21:37:58.817522 | instance | ok: [instance] => {
2026-09-16 21:37:58.817599 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-16 21:37:58.817688 | instance | }
2026-09-16 21:37:58.817814 | instance |
2026-09-16 21:37:58.817894 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-16 21:37:58.817998 | instance | Wednesday 16 September 2026  21:37:58 +0000 (0:00:00.087)       0:00:04.021 ***
2026-09-16 21:37:59.478161 | instance | changed: [instance]
2026-09-16 21:37:59.478809 | instance |
2026-09-16 21:37:59.479159 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-16 21:37:59.479509 | instance | Wednesday 16 September 2026  21:37:59 +0000 (0:00:00.660)       0:00:04.681 ***
2026-09-16 21:37:59.527394 | instance | skipping: [instance]
2026-09-16 21:37:59.527516 | instance |
2026-09-16 21:37:59.527529 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-16 21:37:59.527604 | instance | Wednesday 16 September 2026  21:37:59 +0000 (0:00:00.049)       0:00:04.731 ***
2026-09-16 21:37:59.600656 | instance | skipping: [instance]
2026-09-16 21:37:59.600924 | instance |
2026-09-16 21:37:59.601142 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-16 21:37:59.601379 | instance | Wednesday 16 September 2026  21:37:59 +0000 (0:00:00.071)       0:00:04.802 ***
2026-09-16 21:37:59.863989 | instance | ok: [instance]
2026-09-16 21:37:59.864436 | instance |
2026-09-16 21:37:59.864657 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-16 21:37:59.864859 | instance | Wednesday 16 September 2026  21:37:59 +0000 (0:00:00.264)       0:00:05.067 ***
2026-09-16 21:38:01.266705 | instance | ok: [instance]
2026-09-16 21:38:01.266791 | instance |
2026-09-16 21:38:01.266874 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-16 21:38:01.267047 | instance | Wednesday 16 September 2026  21:38:01 +0000 (0:00:01.403)       0:00:06.471 ***
2026-09-16 21:38:01.340622 | instance | ok: [instance] => {
2026-09-16 21:38:01.340784 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-16 21:38:01.341027 | instance | }
2026-09-16 21:38:01.341223 | instance |
2026-09-16 21:38:01.341473 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-16 21:38:01.341754 | instance | Wednesday 16 September 2026  21:38:01 +0000 (0:00:00.073)       0:00:06.544 ***
2026-09-16 21:38:01.940728 | instance | changed: [instance]
2026-09-16 21:38:01.940931 | instance |
2026-09-16 21:38:01.941172 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-16 21:38:01.941422 | instance | Wednesday 16 September 2026  21:38:01 +0000 (0:00:00.600)       0:00:07.144 ***
2026-09-16 21:38:04.706845 | instance | changed: [instance]
2026-09-16 21:38:04.706945 | instance |
2026-09-16 21:38:04.707046 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-16 21:38:04.707141 | instance | Wednesday 16 September 2026  21:38:04 +0000 (0:00:02.766)       0:00:09.911 ***
2026-09-16 21:38:04.748460 | instance | skipping: [instance]
2026-09-16 21:38:04.748541 | instance |
2026-09-16 21:38:04.748611 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-16 21:38:04.748719 | instance | Wednesday 16 September 2026  21:38:04 +0000 (0:00:00.041)       0:00:09.952 ***
2026-09-16 21:38:04.781855 | instance | skipping: [instance]
2026-09-16 21:38:04.782053 | instance |
2026-09-16 21:38:04.782596 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-16 21:38:04.782882 | instance | Wednesday 16 September 2026  21:38:04 +0000 (0:00:00.033)       0:00:09.985 ***
2026-09-16 21:38:04.806496 | instance | skipping: [instance]
2026-09-16 21:38:04.806713 | instance |
2026-09-16 21:38:04.807023 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-16 21:38:04.807203 | instance | Wednesday 16 September 2026  21:38:04 +0000 (0:00:00.024)       0:00:10.010 ***
2026-09-16 21:38:10.269376 | instance | changed: [instance]
2026-09-16 21:38:10.269582 | instance |
2026-09-16 21:38:10.269595 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-16 21:38:10.269605 | instance | Wednesday 16 September 2026  21:38:10 +0000 (0:00:05.461)       0:00:15.472 ***
2026-09-16 21:38:10.871363 | instance | changed: [instance]
2026-09-16 21:38:10.871829 | instance |
2026-09-16 21:38:10.871888 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-16 21:38:10.871896 | instance | Wednesday 16 September 2026  21:38:10 +0000 (0:00:00.603)       0:00:16.075 ***
2026-09-16 21:38:11.831083 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-16 21:38:11.831132 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-16 21:38:11.831144 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-16 21:38:11.831154 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-16 21:38:11.831163 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-16 21:38:11.831173 | instance |
2026-09-16 21:38:11.831188 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-16 21:38:11.831197 | instance | Wednesday 16 September 2026  21:38:11 +0000 (0:00:00.957)       0:00:17.033 ***
2026-09-16 21:38:12.423828 | instance | changed: [instance]
2026-09-16 21:38:12.423949 | instance |
2026-09-16 21:38:12.424543 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-16 21:38:12.424560 | instance | Wednesday 16 September 2026  21:38:12 +0000 (0:00:00.574)       0:00:17.607 ***
2026-09-16 21:38:12.424565 | instance |
2026-09-16 21:38:12.424569 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-16 21:38:12.424573 | instance | Wednesday 16 September 2026  21:38:12 +0000 (0:00:00.020)       0:00:17.628 ***
2026-09-16 21:38:13.374027 | instance | ok: [instance]
2026-09-16 21:38:13.375070 | instance |
2026-09-16 21:38:13.375108 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-16 21:38:13.375124 | instance | Wednesday 16 September 2026  21:38:13 +0000 (0:00:00.949)       0:00:18.577 ***
2026-09-16 21:38:13.851337 | instance | changed: [instance]
2026-09-16 21:38:13.851406 | instance |
2026-09-16 21:38:13.851414 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-16 21:38:13.851420 | instance | Wednesday 16 September 2026  21:38:13 +0000 (0:00:00.476)       0:00:19.054 ***
2026-09-16 21:38:14.385299 | instance | changed: [instance]
2026-09-16 21:38:14.385415 | instance |
2026-09-16 21:38:14.385681 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-16 21:38:14.385728 | instance | Wednesday 16 September 2026  21:38:14 +0000 (0:00:00.534)       0:00:19.589 ***
2026-09-16 21:38:14.611877 | instance | ok: [instance]
2026-09-16 21:38:14.611996 | instance |
2026-09-16 21:38:14.612265 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-16 21:38:14.612321 | instance | Wednesday 16 September 2026  21:38:14 +0000 (0:00:00.226)       0:00:19.816 ***
2026-09-16 21:38:14.690639 | instance | ok: [instance] => {
2026-09-16 21:38:14.691781 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-16 21:38:14.691835 | instance | }
2026-09-16 21:38:14.691869 | instance |
2026-09-16 21:38:14.691881 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-16 21:38:14.691890 | instance | Wednesday 16 September 2026  21:38:14 +0000 (0:00:00.078)       0:00:19.894 ***
2026-09-16 21:38:18.620543 | instance | changed: [instance]
2026-09-16 21:38:18.620651 | instance |
2026-09-16 21:38:18.621032 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-16 21:38:18.621094 | instance | Wednesday 16 September 2026  21:38:18 +0000 (0:00:03.930)       0:00:23.824 ***
2026-09-16 21:38:23.109739 | instance | changed: [instance]
2026-09-16 21:38:23.109870 | instance |
2026-09-16 21:38:23.109941 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-16 21:38:23.110156 | instance | Wednesday 16 September 2026  21:38:23 +0000 (0:00:04.488)       0:00:28.313 ***
2026-09-16 21:38:24.232760 | instance | ok: [instance]
2026-09-16 21:38:24.232885 | instance |
2026-09-16 21:38:24.233174 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-16 21:38:24.233232 | instance | Wednesday 16 September 2026  21:38:24 +0000 (0:00:01.123)       0:00:29.436 ***
2026-09-16 21:38:24.650279 | instance | changed: [instance]
2026-09-16 21:38:24.650357 | instance |
2026-09-16 21:38:24.650772 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-16 21:38:24.650852 | instance | Wednesday 16 September 2026  21:38:24 +0000 (0:00:00.416)       0:00:29.853 ***
2026-09-16 21:38:25.145651 | instance | changed: [instance]
2026-09-16 21:38:25.145759 | instance |
2026-09-16 21:38:25.145771 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-16 21:38:25.145926 | instance | Wednesday 16 September 2026  21:38:25 +0000 (0:00:00.496)       0:00:30.350 ***
2026-09-16 21:38:25.775275 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-16 21:38:25.775342 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-16 21:38:25.775358 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-16 21:38:25.775370 | instance |
2026-09-16 21:38:25.775383 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-16 21:38:25.775396 | instance | Wednesday 16 September 2026  21:38:25 +0000 (0:00:00.627)       0:00:30.977 ***
2026-09-16 21:38:26.286806 | instance | changed: [instance]
2026-09-16 21:38:26.287638 | instance |
2026-09-16 21:38:26.287670 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-16 21:38:26.287682 | instance | Wednesday 16 September 2026  21:38:26 +0000 (0:00:00.513)       0:00:31.490 ***
2026-09-16 21:38:26.802939 | instance | changed: [instance]
2026-09-16 21:38:26.803268 | instance |
2026-09-16 21:38:26.804139 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-16 21:38:26.804226 | instance | Wednesday 16 September 2026  21:38:26 +0000 (0:00:00.484)       0:00:31.975 ***
2026-09-16 21:38:26.804239 | instance |
2026-09-16 21:38:26.804250 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-16 21:38:26.804267 | instance | Wednesday 16 September 2026  21:38:26 +0000 (0:00:00.031)       0:00:32.007 ***
2026-09-16 21:38:27.584643 | instance | ok: [instance]
2026-09-16 21:38:27.585145 | instance |
2026-09-16 21:38:27.585192 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-16 21:38:27.585209 | instance | Wednesday 16 September 2026  21:38:27 +0000 (0:00:00.781)       0:00:32.788 ***
2026-09-16 21:38:28.541369 | instance | changed: [instance]
2026-09-16 21:38:28.542118 | instance |
2026-09-16 21:38:28.542211 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-16 21:38:28.542224 | instance | Wednesday 16 September 2026  21:38:28 +0000 (0:00:00.955)       0:00:33.744 ***
2026-09-16 21:38:29.170035 | instance | changed: [instance]
2026-09-16 21:38:29.170173 | instance |
2026-09-16 21:38:29.170449 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-16 21:38:29.170506 | instance | Wednesday 16 September 2026  21:38:29 +0000 (0:00:00.629)       0:00:34.373 ***
2026-09-16 21:38:29.238184 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-16 21:38:29.238979 | instance |
2026-09-16 21:38:29.239057 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-16 21:38:29.239070 | instance | Wednesday 16 September 2026  21:38:29 +0000 (0:00:00.067)       0:00:34.441 ***
2026-09-16 21:38:35.344498 | instance | changed: [instance]
2026-09-16 21:38:35.344804 | instance |
2026-09-16 21:38:35.344811 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-16 21:38:35.344818 | instance | Wednesday 16 September 2026  21:38:35 +0000 (0:00:06.106)       0:00:40.547 ***
2026-09-16 21:38:36.047391 | instance | ok: [instance] => (item=chronyd)
2026-09-16 21:38:36.047502 | instance | ok: [instance] => (item=sshd)
2026-09-16 21:38:36.048071 | instance |
2026-09-16 21:38:36.048170 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-16 21:38:36.048188 | instance | Wednesday 16 September 2026  21:38:36 +0000 (0:00:00.703)       0:00:41.251 ***
2026-09-16 21:38:38.006671 | instance | changed: [instance]
2026-09-16 21:38:38.006850 | instance |
2026-09-16 21:38:38.007121 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-09-16 21:38:38.007360 | instance | Wednesday 16 September 2026  21:38:38 +0000 (0:00:01.959)       0:00:43.210 ***
2026-09-16 21:38:38.248323 | instance | ok: [instance]
2026-09-16 21:38:38.248576 | instance |
2026-09-16 21:38:38.248854 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-09-16 21:38:38.249060 | instance | Wednesday 16 September 2026  21:38:38 +0000 (0:00:00.241)       0:00:43.451 ***
2026-09-16 21:38:38.739438 | instance | changed: [instance]
2026-09-16 21:38:38.739603 | instance |
2026-09-16 21:38:38.739819 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-09-16 21:38:38.740035 | instance | Wednesday 16 September 2026  21:38:38 +0000 (0:00:00.491)       0:00:43.942 ***
2026-09-16 21:38:39.105888 | instance | changed: [instance]
2026-09-16 21:38:39.106103 | instance |
2026-09-16 21:38:39.106117 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-09-16 21:38:39.106127 | instance | Wednesday 16 September 2026  21:38:39 +0000 (0:00:00.365)       0:00:44.308 ***
2026-09-16 21:38:39.211689 | instance | ok: [instance]
2026-09-16 21:38:39.211768 | instance |
2026-09-16 21:38:39.212151 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-09-16 21:38:39.212223 | instance | Wednesday 16 September 2026  21:38:39 +0000 (0:00:00.106)       0:00:44.415 ***
2026-09-16 21:38:39.472547 | instance | ok: [instance] => (item=instance)
2026-09-16 21:38:39.472676 | instance |
2026-09-16 21:38:39.473173 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-09-16 21:38:39.473233 | instance | Wednesday 16 September 2026  21:38:39 +0000 (0:00:00.260)       0:00:44.676 ***
2026-09-16 21:38:39.556097 | instance | ok: [instance]
2026-09-16 21:38:39.556344 | instance |
2026-09-16 21:38:39.556649 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-09-16 21:38:39.556920 | instance | Wednesday 16 September 2026  21:38:39 +0000 (0:00:00.082)       0:00:44.758 ***
2026-09-16 21:38:39.652873 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-09-16 21:38:39.653081 | instance |
2026-09-16 21:38:39.653524 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-09-16 21:38:39.653757 | instance | Wednesday 16 September 2026  21:38:39 +0000 (0:00:00.097)       0:00:44.856 ***
2026-09-16 21:38:40.060671 | instance | changed: [instance]
2026-09-16 21:38:40.060744 | instance |
2026-09-16 21:38:40.060915 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-09-16 21:38:40.061091 | instance | Wednesday 16 September 2026  21:38:40 +0000 (0:00:00.408)       0:00:45.264 ***
2026-09-16 21:38:40.885904 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-09-16 21:38:40.886133 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-09-16 21:38:40.886380 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-09-16 21:38:40.886579 | instance |
2026-09-16 21:38:40.886831 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-09-16 21:38:40.887176 | instance | Wednesday 16 September 2026  21:38:40 +0000 (0:00:00.825)       0:00:46.089 ***
2026-09-16 21:40:15.230299 | instance | ok: [instance]
2026-09-16 21:40:15.230370 | instance |
2026-09-16 21:40:15.230382 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-09-16 21:40:15.230540 | instance | Wednesday 16 September 2026  21:40:15 +0000 (0:01:34.343)       0:02:20.433 ***
2026-09-16 21:40:15.485081 | instance | changed: [instance]
2026-09-16 21:40:15.485291 | instance |
2026-09-16 21:40:15.485527 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-09-16 21:40:15.485792 | instance | Wednesday 16 September 2026  21:40:15 +0000 (0:00:00.255)       0:02:20.688 ***
2026-09-16 21:40:15.536930 | instance | ok: [instance]
2026-09-16 21:40:15.537676 | instance |
2026-09-16 21:40:15.537709 | instance | TASK [Install Ceph host] *******************************************************
2026-09-16 21:40:15.537720 | instance | Wednesday 16 September 2026  21:40:15 +0000 (0:00:00.051)       0:02:20.740 ***
2026-09-16 21:40:15.628642 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-09-16 21:40:15.628696 | instance |
2026-09-16 21:40:15.628896 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-09-16 21:40:15.628912 | instance | Wednesday 16 September 2026  21:40:15 +0000 (0:00:00.092)       0:02:20.833 ***
2026-09-16 21:40:17.335337 | instance | ok: [instance]
2026-09-16 21:40:17.335565 | instance |
2026-09-16 21:40:17.335738 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-09-16 21:40:17.335929 | instance | Wednesday 16 September 2026  21:40:17 +0000 (0:00:01.706)       0:02:22.539 ***
2026-09-16 21:40:17.412080 | instance | ok: [instance] => (item=instance)
2026-09-16 21:40:17.412311 | instance |
2026-09-16 21:40:17.412738 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-09-16 21:40:17.413027 | instance | Wednesday 16 September 2026  21:40:17 +0000 (0:00:00.076)       0:02:22.615 ***
2026-09-16 21:40:17.846631 | instance | ok: [instance]
2026-09-16 21:40:17.846752 | instance |
2026-09-16 21:40:17.846902 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-09-16 21:40:17.847058 | instance | Wednesday 16 September 2026  21:40:17 +0000 (0:00:00.434)       0:02:23.050 ***
2026-09-16 21:40:20.311528 | instance | ok: [instance]
2026-09-16 21:40:20.311740 | instance |
2026-09-16 21:40:20.312015 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-09-16 21:40:20.312273 | instance | Wednesday 16 September 2026  21:40:20 +0000 (0:00:02.464)       0:02:25.515 ***
2026-09-16 21:41:12.383258 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster)
2026-09-16 21:41:12.383352 | 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_7fc0_1bh/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_7fc0_1bh/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_7fc0_1bh/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_7fc0_1bh/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 120, in apply_spec\nRuntimeError: Inferring config /var/lib/ceph/4837cbf8-4f90-4300-b3f6-726c9b9f89b4/mon.instance/config\n2026-09-16T21:41:12.156+0000 7fecada57640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCUDKtqS3LREiAAqdeCEDEvF/CDyfW/SiyFs0uEVpYvDj1xIzfzESMQdgA=: Malformed input [buffer:3]\n2026-09-16T21:41:12.156+0000 7fecada57640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-16T21:41:12.160+0000 7fecada57640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCUDKtqS3LREiAAqdeCEDEvF/CDyfW/SiyFs0uEVpYvDj1xIzfzESMQdgA=: Malformed input [buffer:3]\n2026-09-16T21:41:12.160+0000 7fecada57640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-16T21:41:12.160+0000 7fecada57640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCUDKtqS3LREiAAqdeCEDEvF/CDyfW/SiyFs0uEVpYvDj1xIzfzESMQdgA=: Malformed input [buffer:3]\n2026-09-16T21:41:12.160+0000 7fecada57640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-16T21:41:12.160+0000 7fecada57640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1}
2026-09-16 21:41:12.384157 | instance |
2026-09-16 21:41:12.384402 | instance | PLAY RECAP *********************************************************************
2026-09-16 21:41:12.384484 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2026-09-16 21:41:12.384540 | instance |
2026-09-16 21:41:12.386980 | instance | Wednesday 16 September 2026  21:41:12 +0000 (0:00:52.073)       0:03:17.588 ***
2026-09-16 21:41:12.387042 | instance | ===============================================================================
2026-09-16 21:41:12.387047 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 94.34s
2026-09-16 21:41:12.387051 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 52.07s
2026-09-16 21:41:12.387055 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 6.11s
2026-09-16 21:41:12.387059 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.46s
2026-09-16 21:41:12.387063 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.49s
2026-09-16 21:41:12.387067 | instance | vexxhost.containers.download_artifact : Download item ------------------- 3.93s
2026-09-16 21:41:12.387071 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.77s
2026-09-16 21:41:12.387075 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.46s
2026-09-16 21:41:12.387091 | instance | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 1.96s
2026-09-16 21:41:12.387095 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.73s
2026-09-16 21:41:12.387099 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.71s
2026-09-16 21:41:12.387103 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.40s
2026-09-16 21:41:12.387107 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.12s
2026-09-16 21:41:12.387111 | instance | Gathering Facts --------------------------------------------------------- 1.07s
2026-09-16 21:41:12.387114 | instance | Gathering Facts --------------------------------------------------------- 0.98s
2026-09-16 21:41:12.387118 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.96s
2026-09-16 21:41:12.387122 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.96s
2026-09-16 21:41:12.387126 | instance | Gathering Facts --------------------------------------------------------- 0.94s
2026-09-16 21:41:12.387129 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.83s
2026-09-16 21:41:12.387133 | instance | vexxhost.ceph.cephadm : Ensure services are started --------------------- 0.70s
2026-09-16 21:41:12.491243 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-16 21:41:12.491432 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-16 21:41:12.491564 | instance | ERROR    Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-16 21:41:12.848041 | instance | ERROR
2026-09-16 21:41:12.848268 | instance | {
2026-09-16 21:41:12.848306 | instance |   "delta": "0:04:08.674105",
2026-09-16 21:41:12.848326 | instance |   "end": "2026-09-16 21:41:12.545559",
2026-09-16 21:41:12.848343 | instance |   "msg": "non-zero return code",
2026-09-16 21:41:12.848361 | instance |   "rc": 2,
2026-09-16 21:41:12.848380 | instance |   "start": "2026-09-16 21:37:03.871454"
2026-09-16 21:41:12.848396 | instance | }
failure
2026-09-16 21:41:12.852959 | 
2026-09-16 21:41:12.853009 | PLAY RECAP
2026-09-16 21:41:12.853051 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-16 21:41:12.853071 | 
2026-09-16 21:41:13.011076 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-16 21:41:13.013409 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-16 21:41:13.606848 | 
2026-09-16 21:41:13.607033 | PLAY [all]
2026-09-16 21:41:13.626332 | 
2026-09-16 21:41:13.626541 | TASK [gather-host-logs : creating directory for system status]
2026-09-16 21:41:14.045582 | instance | changed
2026-09-16 21:41:14.060261 | 
2026-09-16 21:41:14.060576 | TASK [gather-host-logs : Get logs for each host]
2026-09-16 21:41:14.465375 | instance | + systemd-cgls --full --all --no-pager
2026-09-16 21:41:14.484004 | instance | + ip addr
2026-09-16 21:41:14.486376 | instance | + ip route
2026-09-16 21:41:14.489282 | instance | + lsblk
2026-09-16 21:41:14.494229 | instance | + mount
2026-09-16 21:41:14.497364 | instance | + docker images
2026-09-16 21:41:14.521069 | instance | + brctl show
2026-09-16 21:41:14.521683 | instance | /bin/bash: line 8: brctl: command not found
2026-09-16 21:41:14.522015 | instance | + ip neigh
2026-09-16 21:41:14.524792 | instance | + bridge fdb show
2026-09-16 21:41:14.528547 | instance | + ps aux --sort=-%mem
2026-09-16 21:41:14.549192 | instance | + dpkg -l
2026-09-16 21:41:14.562027 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-16 21:41:14.562473 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-16 21:41:14.584628 | instance | + '[' '!' -z '' ']'
2026-09-16 21:41:14.615305 | instance | ok: Runtime: 0:00:00.122370
2026-09-16 21:41:14.620767 | 
2026-09-16 21:41:14.620860 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-16 21:41:15.290076 | instance | changed:
2026-09-16 21:41:15.290349 | instance | created directory /var/lib/zuul/builds/98519b06c06748d58b55baf049836bb1/work/logs/instance
2026-09-16 21:41:15.290398 | instance | cd+++++++++ system/
2026-09-16 21:41:15.290438 | instance | >f+++++++++ system/brctl-show.txt
2026-09-16 21:41:15.290472 | instance | >f+++++++++ system/bridge-fdb.txt
2026-09-16 21:41:15.290505 | instance | >f+++++++++ system/docker-images.txt
2026-09-16 21:41:15.290580 | instance | >f+++++++++ system/ip-addr.txt
2026-09-16 21:41:15.290617 | instance | >f+++++++++ system/ip-neigh.txt
2026-09-16 21:41:15.290651 | instance | >f+++++++++ system/ip-route.txt
2026-09-16 21:41:15.290686 | instance | >f+++++++++ system/lsblk.txt
2026-09-16 21:41:15.290719 | instance | >f+++++++++ system/mount.txt
2026-09-16 21:41:15.290751 | instance | >f+++++++++ system/packages.txt
2026-09-16 21:41:15.290896 | instance | >f+++++++++ system/ps.txt
2026-09-16 21:41:15.290939 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-16 21:41:15.305836 | 
2026-09-16 21:41:15.305967 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-16 21:41:15.539007 | instance | changed: "values"
2026-09-16 21:41:15.721954 | instance | changed: "releases"
2026-09-16 21:41:15.732074 | 
2026-09-16 21:41:15.732162 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-16 21:41:16.017251 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-16 21:41:16.277866 | instance | ok: Runtime: 0:00:00.003620
2026-09-16 21:41:16.299630 | 
2026-09-16 21:41:16.299870 | TASK [helm-release-status : Downloads logs to executor]
2026-09-16 21:41:16.870507 | instance | changed:
2026-09-16 21:41:16.870783 | instance | cd+++++++++ helm/
2026-09-16 21:41:16.870836 | instance | cd+++++++++ helm/releases/
2026-09-16 21:41:16.870876 | instance | cd+++++++++ helm/values/
2026-09-16 21:41:16.893798 | 
2026-09-16 21:41:16.894022 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-16 21:41:17.084452 | instance | changed
2026-09-16 21:41:17.095749 | 
2026-09-16 21:41:17.095938 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-16 21:41:17.408349 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-16 21:41:17.408823 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-16 21:41:17.413287 | instance | environment: line 1: kubectl: command not found
2026-09-16 21:41:17.414967 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-16 21:41:17.415966 | instance | environment: line 1: kubectl: command not found
2026-09-16 21:41:17.419108 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-16 21:41:17.419147 | instance | environment: line 1: kubectl: command not found
2026-09-16 21:41:17.419163 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-16 21:41:17.421193 | instance | environment: line 1: kubectl: command not found
2026-09-16 21:41:17.422568 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-16 21:41:17.424574 | instance | environment: line 1: kubectl: command not found
2026-09-16 21:41:17.425916 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-16 21:41:17.634632 | instance | ok: Runtime: 0:00:00.026325
2026-09-16 21:41:17.644107 | 
2026-09-16 21:41:17.644235 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-16 21:41:17.858588 | instance | changed
2026-09-16 21:41:17.865175 | 
2026-09-16 21:41:17.865321 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-16 21:41:18.098085 | instance | environment: line 5: kubectl: command not found
2026-09-16 21:41:18.098725 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-16 21:41:18.098947 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-16 21:41:18.099069 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-16 21:41:18.406279 | instance | ok: Runtime: 0:00:00.008859
2026-09-16 21:41:18.416798 | 
2026-09-16 21:41:18.416933 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-16 21:41:18.943256 | instance | changed:
2026-09-16 21:41:18.943431 | instance | cd+++++++++ objects/
2026-09-16 21:41:18.943457 | instance | cd+++++++++ objects/cluster/
2026-09-16 21:41:18.943477 | instance | cd+++++++++ objects/namespaced/
2026-09-16 21:41:18.951272 | 
2026-09-16 21:41:18.951336 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-16 21:41:19.172897 | instance | changed
2026-09-16 21:41:19.179115 | 
2026-09-16 21:41:19.179201 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-16 21:41:19.403714 | instance | changed
2026-09-16 21:41:19.409787 | 
2026-09-16 21:41:19.409859 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-16 21:41:19.656218 | instance | environment: line 3: kubectl: command not found
2026-09-16 21:41:19.952662 | instance | ok: Runtime: 0:00:00.007153
2026-09-16 21:41:19.963124 | 
2026-09-16 21:41:19.963373 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-16 21:41:20.520334 | instance | changed:
2026-09-16 21:41:20.520538 | instance | cd+++++++++ pod-logs/
2026-09-16 21:41:20.520568 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-16 21:41:20.529113 | 
2026-09-16 21:41:20.529178 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-16 21:41:20.755027 | instance | changed
2026-09-16 21:41:20.764892 | 
2026-09-16 21:41:20.765114 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-16 21:41:21.021672 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-16 21:41:21.315644 | instance | ok: Runtime: 0:00:00.048628
2026-09-16 21:41:21.325386 | 
2026-09-16 21:41:21.325526 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-16 21:41:21.569494 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-16 21:41:21.618007 | instance | ceph-mgr endpoints:
2026-09-16 21:41:21.875601 | instance | ok: Runtime: 0:00:00.053570
2026-09-16 21:41:21.888973 | 
2026-09-16 21:41:21.889164 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-16 21:41:22.131184 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-16 21:41:22.427772 | instance | ok: Runtime: 0:00:00.048758
2026-09-16 21:41:22.433957 | 
2026-09-16 21:41:22.434049 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-16 21:41:22.959072 | instance | changed: cd+++++++++ prometheus/
2026-09-16 21:41:22.967516 | 
2026-09-16 21:41:22.967623 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-16 21:41:23.175682 | instance | changed
2026-09-16 21:41:23.179991 | 
2026-09-16 21:41:23.180056 | TASK [gather-selenium-data : Get selenium data]
2026-09-16 21:41:23.448908 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-16 21:41:23.449854 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-16 21:41:23.712675 | instance | ERROR
2026-09-16 21:41:23.713062 | instance | {
2026-09-16 21:41:23.713174 | instance |   "delta": "0:00:00.005759",
2026-09-16 21:41:23.713228 | instance |   "end": "2026-09-16 21:41:23.450198",
2026-09-16 21:41:23.713275 | instance |   "msg": "non-zero return code",
2026-09-16 21:41:23.713322 | instance |   "rc": 1,
2026-09-16 21:41:23.713368 | instance |   "start": "2026-09-16 21:41:23.444439"
2026-09-16 21:41:23.713414 | instance | }
2026-09-16 21:41:23.713474 | instance | ERROR: Ignoring Errors
2026-09-16 21:41:23.725022 | 
2026-09-16 21:41:23.725217 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-16 21:41:24.300157 | instance | changed: cd+++++++++ selenium/
2026-09-16 21:41:24.313465 | 
2026-09-16 21:41:24.313625 | PLAY RECAP
2026-09-16 21:41:24.313723 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-16 21:41:24.313769 | 
2026-09-16 21:41:24.535720 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-16 21:41:24.540216 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-16 21:41:25.180607 | 
2026-09-16 21:41:25.180757 | PLAY [all]
2026-09-16 21:41:25.191811 | 
2026-09-16 21:41:25.191896 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-16 21:41:25.236183 | instance | skipping: Conditional result was False
2026-09-16 21:41:25.243015 | 
2026-09-16 21:41:25.243142 | TASK [fetch-output : Set log path for single node]
2026-09-16 21:41:25.286901 | instance | ok
2026-09-16 21:41:25.294707 | 
2026-09-16 21:41:25.294794 | LOOP [fetch-output : Ensure local output dirs]
2026-09-16 21:41:25.692064 | instance -> localhost | ok: "/var/lib/zuul/builds/98519b06c06748d58b55baf049836bb1/work/logs"
2026-09-16 21:41:25.969738 | instance -> localhost | changed: "/var/lib/zuul/builds/98519b06c06748d58b55baf049836bb1/work/artifacts"
2026-09-16 21:41:26.174708 | instance -> localhost | changed: "/var/lib/zuul/builds/98519b06c06748d58b55baf049836bb1/work/docs"
2026-09-16 21:41:26.202352 | 
2026-09-16 21:41:26.202604 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-16 21:41:27.212687 | instance | changed: .d..t...... ./
2026-09-16 21:41:27.213016 | instance | changed: All items complete
2026-09-16 21:41:27.213070 | 
2026-09-16 21:41:27.723580 | instance | changed: .d..t...... ./
2026-09-16 21:41:28.222921 | instance | changed: .d..t...... ./
2026-09-16 21:41:28.252750 | 
2026-09-16 21:41:28.252961 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-16 21:41:28.720827 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008451
2026-09-16 21:41:28.987265 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008318
2026-09-16 21:41:29.013760 | 
2026-09-16 21:41:29.013962 | PLAY [all]
2026-09-16 21:41:29.030126 | 
2026-09-16 21:41:29.030365 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-16 21:41:29.480230 | instance | changed
2026-09-16 21:41:29.491264 | 
2026-09-16 21:41:29.491365 | PLAY RECAP
2026-09-16 21:41:29.491450 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-16 21:41:29.491495 | 
2026-09-16 21:41:29.686641 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-16 21:41:29.694261 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-16 21:41:30.245679 | 
2026-09-16 21:41:30.245992 | PLAY [localhost]
2026-09-16 21:41:30.255774 | 
2026-09-16 21:41:30.255844 | TASK [Generate Zuul manifest]
2026-09-16 21:41:30.274900 | localhost | ok
2026-09-16 21:41:30.301820 | 
2026-09-16 21:41:30.301942 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-16 21:41:30.799208 | localhost | changed
2026-09-16 21:41:30.823023 | 
2026-09-16 21:41:30.913197 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-16 21:41:30.996617 | localhost | ok
2026-09-16 21:41:31.009425 | 
2026-09-16 21:41:31.009541 | TASK [Upload logs]
2026-09-16 21:41:31.034482 | localhost | ok
2026-09-16 21:41:31.137034 | 
2026-09-16 21:41:31.137230 | TASK [Set zuul-log-path fact]
2026-09-16 21:41:31.156938 | localhost | ok
2026-09-16 21:41:31.190854 | 
2026-09-16 21:41:31.191043 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-16 21:41:31.232861 | localhost | ok
2026-09-16 21:41:31.241208 | 
2026-09-16 21:41:31.241306 | TASK [upload-logs : Create log directories]
2026-09-16 21:41:31.689982 | localhost | changed
2026-09-16 21:41:31.699538 | 
2026-09-16 21:41:31.699688 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-16 21:41:32.277951 | localhost -> localhost | ok: Runtime: 0:00:00.127394
2026-09-16 21:41:32.285262 | 
2026-09-16 21:41:32.285375 | TASK [upload-logs : Upload logs to log server]
2026-09-16 21:41:32.848049 | localhost | Output suppressed because no_log was given
2026-09-16 21:41:32.904394 | 
2026-09-16 21:41:32.904574 | LOOP [upload-logs : Compress console log and json output]
2026-09-16 21:41:32.946604 | localhost | skipping: Conditional result was False
2026-09-16 21:41:32.952761 | localhost | skipping: Conditional result was False
2026-09-16 21:41:32.967414 | 
2026-09-16 21:41:32.967627 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-16 21:41:33.033301 | localhost | skipping: Conditional result was False
2026-09-16 21:41:33.033814 | 
2026-09-16 21:41:33.039598 | localhost | skipping: Conditional result was False
2026-09-16 21:41:33.054126 | 
2026-09-16 21:41:33.054348 | LOOP [upload-logs : Upload console log and json output]