2026-09-15 04:35:10.977174 | Job console starting
2026-09-15 04:35:10.987936 | Updating git repos
2026-09-15 04:35:14.981313 | Cloning repos into workspace
2026-09-15 04:35:32.910769 | Restoring repo states
2026-09-15 04:35:48.182766 | Merging changes
2026-09-15 04:35:49.374141 | Checking out repos
2026-09-15 04:35:50.963216 | Preparing playbooks
2026-09-15 04:35:52.945376 | Running Ansible setup
2026-09-15 04:35:56.454592 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-15 04:35:57.065286 | 
2026-09-15 04:35:57.065430 | PLAY [localhost]
2026-09-15 04:35:57.072949 | 
2026-09-15 04:35:57.073021 | TASK [Gathering Facts]
2026-09-15 04:35:57.942125 | localhost | ok
2026-09-15 04:35:57.954918 | 
2026-09-15 04:35:57.955072 | TASK [Setup log path fact]
2026-09-15 04:35:57.976291 | localhost | ok
2026-09-15 04:35:57.996181 | 
2026-09-15 04:35:57.996317 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-15 04:35:58.037120 | localhost | ok
2026-09-15 04:35:58.048138 | 
2026-09-15 04:35:58.048275 | TASK [emit-job-header : Print job information]
2026-09-15 04:35:58.098829 | # Job Information
2026-09-15 04:35:58.099047 | Ansible Version: 2.16.18
2026-09-15 04:35:58.099097 | Job: atmosphere-molecule-csi-rbd
2026-09-15 04:35:58.099131 | Pipeline: check
2026-09-15 04:35:58.099159 | Executor: 2d72f0692154
2026-09-15 04:35:58.099189 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4089
2026-09-15 04:35:58.099220 | Event ID: 5869a770-b0be-11f1-9560-697898c116ad
2026-09-15 04:35:58.103118 | 
2026-09-15 04:35:58.103203 | LOOP [emit-job-header : Print node information]
2026-09-15 04:35:58.217075 | localhost | ok:
2026-09-15 04:35:58.217382 | localhost | # Node Information
2026-09-15 04:35:58.217438 | localhost | Inventory Hostname: instance
2026-09-15 04:35:58.217480 | localhost | Hostname: np0000192771
2026-09-15 04:35:58.217518 | localhost | Username: zuul
2026-09-15 04:35:58.217565 | localhost | Distro: Ubuntu 22.04
2026-09-15 04:35:58.217603 | localhost | Provider: yul1
2026-09-15 04:35:58.217640 | localhost | Region: ca-ymq-1
2026-09-15 04:35:58.217676 | localhost | Label: ubuntu-jammy
2026-09-15 04:35:58.217711 | localhost | Product Name: OpenStack Nova
2026-09-15 04:35:58.217747 | localhost | Interface IP: 162.253.55.206
2026-09-15 04:35:58.225658 | 
2026-09-15 04:35:58.225839 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-15 04:35:58.616450 | localhost -> localhost | changed
2026-09-15 04:35:58.621969 | 
2026-09-15 04:35:58.622038 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-15 04:35:59.574218 | localhost -> localhost | changed
2026-09-15 04:35:59.606155 | 
2026-09-15 04:35:59.606275 | PLAY [all]
2026-09-15 04:35:59.622630 | 
2026-09-15 04:35:59.622755 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-15 04:35:59.870216 | instance -> localhost | ok
2026-09-15 04:35:59.882081 | 
2026-09-15 04:35:59.882215 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-15 04:35:59.917474 | instance | ok
2026-09-15 04:35:59.945948 | instance | included: /var/lib/zuul/builds/99772b6725f04c108abd260000ff738f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-15 04:35:59.952854 | 
2026-09-15 04:35:59.952933 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-15 04:36:01.288848 | instance -> localhost | Generating public/private rsa key pair.
2026-09-15 04:36:01.289460 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/99772b6725f04c108abd260000ff738f/work/99772b6725f04c108abd260000ff738f_id_rsa
2026-09-15 04:36:01.289536 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/99772b6725f04c108abd260000ff738f/work/99772b6725f04c108abd260000ff738f_id_rsa.pub
2026-09-15 04:36:01.289582 | instance -> localhost | The key fingerprint is:
2026-09-15 04:36:01.289726 | instance -> localhost | SHA256:xc5e3tLV1UQ+Mfr+UAI2biAX7gc5p7VzAuiL7wIdu0M zuul-build-sshkey
2026-09-15 04:36:01.289817 | instance -> localhost | The key's randomart image is:
2026-09-15 04:36:01.289862 | instance -> localhost | +---[RSA 3072]----+
2026-09-15 04:36:01.289914 | instance -> localhost | |          .    +o|
2026-09-15 04:36:01.289958 | instance -> localhost | |         + o  .o+|
2026-09-15 04:36:01.289997 | instance -> localhost | |        o @ *. .+|
2026-09-15 04:36:01.290036 | instance -> localhost | |      .. B @ +. +|
2026-09-15 04:36:01.290074 | instance -> localhost | |     . oS = O o.+|
2026-09-15 04:36:01.290113 | instance -> localhost | |    . E. o = *.+ |
2026-09-15 04:36:01.290153 | instance -> localhost | |     o... . o +. |
2026-09-15 04:36:01.290190 | instance -> localhost | |      +.     . ..|
2026-09-15 04:36:01.290233 | instance -> localhost | |       +o       .|
2026-09-15 04:36:01.290272 | instance -> localhost | +----[SHA256]-----+
2026-09-15 04:36:01.290358 | instance -> localhost | ok: Runtime: 0:00:00.890974
2026-09-15 04:36:01.301950 | 
2026-09-15 04:36:01.302072 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-15 04:36:01.335125 | instance | ok
2026-09-15 04:36:01.351547 | instance | included: /var/lib/zuul/builds/99772b6725f04c108abd260000ff738f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-15 04:36:01.361022 | 
2026-09-15 04:36:01.361098 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-15 04:36:01.385211 | instance | skipping: Conditional result was False
2026-09-15 04:36:01.395271 | 
2026-09-15 04:36:01.395408 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-15 04:36:01.874876 | instance | changed
2026-09-15 04:36:01.886260 | 
2026-09-15 04:36:01.886391 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-15 04:36:02.091809 | instance | ok
2026-09-15 04:36:02.101072 | 
2026-09-15 04:36:02.101214 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-15 04:36:02.631109 | instance | changed
2026-09-15 04:36:02.640753 | 
2026-09-15 04:36:02.640902 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-15 04:36:03.151141 | instance | changed
2026-09-15 04:36:03.162341 | 
2026-09-15 04:36:03.162467 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-15 04:36:03.187783 | instance | skipping: Conditional result was False
2026-09-15 04:36:03.197590 | 
2026-09-15 04:36:03.197715 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-15 04:36:03.592290 | instance -> localhost | changed
2026-09-15 04:36:03.612263 | 
2026-09-15 04:36:03.612389 | TASK [add-build-sshkey : Add back temp key]
2026-09-15 04:36:03.917581 | instance -> localhost | Identity added: /var/lib/zuul/builds/99772b6725f04c108abd260000ff738f/work/99772b6725f04c108abd260000ff738f_id_rsa (zuul-build-sshkey)
2026-09-15 04:36:03.917943 | instance -> localhost | ok: Runtime: 0:00:00.014952
2026-09-15 04:36:03.929730 | 
2026-09-15 04:36:03.930162 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-15 04:36:04.287125 | instance | ok
2026-09-15 04:36:04.291205 | 
2026-09-15 04:36:04.291268 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-15 04:36:04.325211 | instance | skipping: Conditional result was False
2026-09-15 04:36:04.334299 | 
2026-09-15 04:36:04.334366 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-15 04:36:04.701487 | instance | ok
2026-09-15 04:36:04.712067 | 
2026-09-15 04:36:04.712194 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-15 04:36:08.418196 | instance | Output suppressed because no_log was given
2026-09-15 04:36:08.434250 | 
2026-09-15 04:36:08.434395 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-15 04:36:08.658231 | instance | ok: "logs"
2026-09-15 04:36:08.658674 | instance | ok: All items complete
2026-09-15 04:36:08.658737 | 
2026-09-15 04:36:08.826595 | instance | ok: "artifacts"
2026-09-15 04:36:08.986724 | instance | ok: "docs"
2026-09-15 04:36:09.003133 | 
2026-09-15 04:36:09.003363 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-15 04:36:09.203375 | instance | changed: "logs"
2026-09-15 04:36:09.340157 | instance | changed: "artifacts"
2026-09-15 04:36:09.488221 | instance | changed: "docs"
2026-09-15 04:36:09.503439 | 
2026-09-15 04:36:09.503546 | PLAY RECAP
2026-09-15 04:36:09.503645 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-15 04:36:09.503711 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 04:36:09.503764 | 
2026-09-15 04:36:09.668660 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-15 04:36:09.672331 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-15 04:36:10.241633 | 
2026-09-15 04:36:10.241800 | PLAY [all]
2026-09-15 04:36:10.253176 | 
2026-09-15 04:36:10.253263 | TASK [setup-uv : Extract archive]
2026-09-15 04:36:12.509448 | instance | changed
2026-09-15 04:36:12.522219 | 
2026-09-15 04:36:12.522500 | TASK [setup-uv : Print version]
2026-09-15 04:36:12.911903 | instance | uv 0.8.13
2026-09-15 04:36:13.075683 | instance | ok: Runtime: 0:00:00.012664
2026-09-15 04:36:13.093853 | 
2026-09-15 04:36:13.094109 | TASK [Install Ansible collection siblings]
2026-09-15 04:36:22.491753 | instance | changed:
2026-09-15 04:36:22.492052 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-15 04:36:22.492112 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-15 04:36:22.492154 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-15 04:36:22.492193 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-15 04:36:22.492231 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-15 04:36:22.492273 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-15 04:36:22.492312 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-15 04:36:22.492353 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-15 04:36:22.492390 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-15 04:36:22.492427 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-15 04:36:22.492463 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-15 04:36:22.492527 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-15 04:36:22.492570 | instance | Resolved ansible.netcommon to v8.6.2 for >=1.2.0
2026-09-15 04:36:22.492606 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-15 04:36:22.492642 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0
2026-09-15 04:36:22.492678 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-15 04:36:22.492713 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-15 04:36:22.492749 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-15 04:36:22.492804 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-15 04:36:22.492843 | instance | Resolved openstack.cloud to 1.7.0 for 1.7.0
2026-09-15 04:36:22.492879 | instance | Resolved atmosphere.common to v0.6.0 for >=0.6.0,<0.7.0
2026-09-15 04:36:22.492915 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.1.2
2026-09-15 04:36:22.492953 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-15 04:36:22.493006 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-09-15 04:36:22.493043 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-15 04:36:22.493079 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-15 04:36:22.493113 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-15 04:36:22.493148 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-09-15 04:36:22.493182 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-15 04:36:22.493216 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-15 04:36:22.493250 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-15 04:36:22.493285 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-15 04:36:22.493320 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-15 04:36:22.493354 | instance | Checked out ansible.netcommon to v8.6.2
2026-09-15 04:36:22.493388 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-15 04:36:22.493434 | instance | Starting galaxy collection install process
2026-09-15 04:36:22.493469 | instance | Process install dependency map
2026-09-15 04:36:22.493503 | instance | Starting collection install process
2026-09-15 04:36:22.493537 | instance | Installing 'ansible.netcommon:8.6.2' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-15 04:36:22.493571 | instance | Created collection for ansible.netcommon:8.6.2 at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-15 04:36:22.493606 | instance | ansible.netcommon:8.6.2 was installed successfully
2026-09-15 04:36:22.493639 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-15 04:36:22.493673 | instance | Creating virtual environment at: .venv
2026-09-15 04:36:22.493707 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-15 04:36:22.493741 | instance | Downloading pygments (1.2MiB)
2026-09-15 04:36:22.493781 | instance | Downloading netaddr (2.2MiB)
2026-09-15 04:36:22.493979 | instance | Downloading setuptools (1.1MiB)
2026-09-15 04:36:22.494016 | instance | Downloading kubernetes (1.9MiB)
2026-09-15 04:36:22.494052 | instance | Downloading ansible-core (2.1MiB)
2026-09-15 04:36:22.494087 | instance | Downloading openstacksdk (1.7MiB)
2026-09-15 04:36:22.494122 | instance | Downloading cryptography (4.2MiB)
2026-09-15 04:36:22.494156 | instance | Downloading rjsonnet (1.2MiB)
2026-09-15 04:36:22.494190 | instance |    Building pyperclip==1.9.0
2026-09-15 04:36:22.494228 | instance |  Downloading rjsonnet
2026-09-15 04:36:22.494263 | instance |  Downloading pygments
2026-09-15 04:36:22.494298 | instance |  Downloading netaddr
2026-09-15 04:36:22.494333 | instance |  Downloading cryptography
2026-09-15 04:36:22.494367 | instance |  Downloading setuptools
2026-09-15 04:36:22.494401 | instance |  Downloading kubernetes
2026-09-15 04:36:22.494434 | instance |  Downloading ansible-core
2026-09-15 04:36:22.494468 | instance |  Downloading openstacksdk
2026-09-15 04:36:22.494503 | instance |       Built pyperclip==1.9.0
2026-09-15 04:36:22.494534 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-15 04:36:22.494559 | instance | Installed 79 packages in 54ms
2026-09-15 04:36:22.494583 | instance | Checked out ansible.posix to 1.6.0
2026-09-15 04:36:22.494607 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-15 04:36:22.494628 | instance | Starting galaxy collection install process
2026-09-15 04:36:22.494649 | instance | Process install dependency map
2026-09-15 04:36:22.494678 | instance | Starting collection install process
2026-09-15 04:36:22.494858 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-15 04:36:22.494901 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-15 04:36:22.494926 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-15 04:36:22.494949 | instance | Checked out ansible.utils to v6.1.0
2026-09-15 04:36:22.494972 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-15 04:36:22.494995 | instance | Starting galaxy collection install process
2026-09-15 04:36:22.495016 | instance | Process install dependency map
2026-09-15 04:36:22.495038 | instance | Starting collection install process
2026-09-15 04:36:22.495059 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-15 04:36:22.495080 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-15 04:36:22.495101 | instance | ansible.utils:6.1.0 was installed successfully
2026-09-15 04:36:22.495123 | instance | Checked out atmosphere.common to v0.6.0
2026-09-15 04:36:22.495145 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-15 04:36:22.495167 | instance | Starting galaxy collection install process
2026-09-15 04:36:22.495188 | instance | Process install dependency map
2026-09-15 04:36:22.495210 | instance | Starting collection install process
2026-09-15 04:36:22.495231 | instance | Installing 'atmosphere.common:0.6.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-15 04:36:22.495252 | instance | Created collection for atmosphere.common:0.6.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-15 04:36:22.495273 | instance | atmosphere.common:0.6.0 was installed successfully
2026-09-15 04:36:22.495295 | instance | Checked out community.crypto to 2.2.3
2026-09-15 04:36:22.495316 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-15 04:36:22.495338 | instance | Starting galaxy collection install process
2026-09-15 04:36:22.495359 | instance | Process install dependency map
2026-09-15 04:36:22.495380 | instance | Starting collection install process
2026-09-15 04:36:22.495401 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-15 04:36:22.495423 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-15 04:36:22.495443 | instance | community.crypto:2.2.3 was installed successfully
2026-09-15 04:36:22.495464 | instance | Checked out community.general to 7.3.0
2026-09-15 04:36:22.495485 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general
2026-09-15 04:36:22.495507 | instance | Starting galaxy collection install process
2026-09-15 04:36:22.495528 | instance | Process install dependency map
2026-09-15 04:36:22.495550 | instance | Starting collection install process
2026-09-15 04:36:22.495571 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-15 04:36:22.495592 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-15 04:36:22.495613 | instance | community.general:7.3.0 was installed successfully
2026-09-15 04:36:22.495643 | instance | Checked out community.mysql to 3.6.0
2026-09-15 04:36:22.495666 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-15 04:36:22.495687 | instance | Starting galaxy collection install process
2026-09-15 04:36:22.495709 | instance | Process install dependency map
2026-09-15 04:36:22.495729 | instance | Starting collection install process
2026-09-15 04:36:22.495751 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-15 04:36:22.495772 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-15 04:36:22.495809 | instance | community.mysql:3.6.0 was installed successfully
2026-09-15 04:36:22.495832 | instance | Checked out kubernetes.core to 2.4.0
2026-09-15 04:36:22.495854 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-15 04:36:22.495876 | instance | Starting galaxy collection install process
2026-09-15 04:36:22.495898 | instance | Process install dependency map
2026-09-15 04:36:22.495922 | instance | Starting collection install process
2026-09-15 04:36:22.495945 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-15 04:36:22.495967 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-15 04:36:22.495989 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-15 04:36:22.496011 | instance | Checked out openstack.cloud to 1.7.0
2026-09-15 04:36:22.496033 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-15 04:36:22.496055 | instance | Starting galaxy collection install process
2026-09-15 04:36:22.496076 | instance | Process install dependency map
2026-09-15 04:36:22.496098 | instance | Starting collection install process
2026-09-15 04:36:22.496120 | instance | Installing 'openstack.cloud:1.7.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-15 04:36:22.496142 | instance | Created collection for openstack.cloud:1.7.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-15 04:36:22.496163 | instance | openstack.cloud:1.7.0 was installed successfully
2026-09-15 04:36:22.496267 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-15 04:36:22.496295 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-15 04:36:22.496318 | instance | Starting galaxy collection install process
2026-09-15 04:36:22.496340 | instance | Process install dependency map
2026-09-15 04:36:22.496362 | instance | Starting collection install process
2026-09-15 04:36:22.496383 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-15 04:36:22.496404 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-15 04:36:22.496426 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-15 04:36:22.496447 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-15 04:36:22.496468 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-15 04:36:22.496490 | instance | Starting galaxy collection install process
2026-09-15 04:36:22.496544 | instance | Process install dependency map
2026-09-15 04:36:22.496570 | instance | Starting collection install process
2026-09-15 04:36:22.496591 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-15 04:36:22.496613 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-15 04:36:22.496635 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-15 04:36:22.496656 | instance | Checked out vexxhost.kubernetes to v3.4.0
2026-09-15 04:36:22.496677 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-15 04:36:22.496698 | instance | Starting galaxy collection install process
2026-09-15 04:36:22.496720 | instance | Process install dependency map
2026-09-15 04:36:22.496741 | instance | Starting collection install process
2026-09-15 04:36:22.496762 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-15 04:36:22.496783 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-15 04:36:22.496824 | instance | vexxhost.kubernetes:3.4.0 was installed successfully
2026-09-15 04:36:22.503403 | 
2026-09-15 04:36:22.503458 | PLAY RECAP
2026-09-15 04:36:22.503515 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 04:36:22.503539 | 
2026-09-15 04:36:22.678428 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-15 04:36:22.680326 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-15 04:36:23.240425 | 
2026-09-15 04:36:23.240568 | PLAY [all]
2026-09-15 04:36:23.250904 | 
2026-09-15 04:36:23.250973 | TASK [Install "jq" for log collection]
2026-09-15 04:36:33.455355 | instance | changed
2026-09-15 04:36:33.458823 | 
2026-09-15 04:36:33.463861 | PLAY RECAP
2026-09-15 04:36:33.464012 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-15 04:36:33.464124 | 
2026-09-15 04:36:33.611207 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-15 04:36:33.615998 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-15 04:36:34.195186 | 
2026-09-15 04:36:34.203252 | PLAY [all]
2026-09-15 04:36:34.213711 | 
2026-09-15 04:36:34.213781 | LOOP [Check inventory directories]
2026-09-15 04:36:34.578634 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-15 04:36:34.751258 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-15 04:36:34.920174 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-15 04:36:34.943123 | 
2026-09-15 04:36:34.943414 | LOOP [Copy inventory file for Zuul]
2026-09-15 04:36:35.794585 | instance | changed:
2026-09-15 04:36:35.794905 | instance | {
2026-09-15 04:36:35.794963 | instance |   "ansible_loop_var": "item",
2026-09-15 04:36:35.795009 | instance |   "changed": false,
2026-09-15 04:36:35.795049 | instance |   "failed": false,
2026-09-15 04:36:35.795091 | instance |   "invocation": {
2026-09-15 04:36:35.795128 | instance |     "module_args": {
2026-09-15 04:36:35.795164 | instance |       "checksum_algorithm": "sha1",
2026-09-15 04:36:35.795200 | instance |       "follow": true,
2026-09-15 04:36:35.795235 | instance |       "get_attributes": true,
2026-09-15 04:36:35.795271 | instance |       "get_checksum": true,
2026-09-15 04:36:35.795307 | instance |       "get_mime": true,
2026-09-15 04:36:35.795340 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-15 04:36:35.795395 | instance |     }
2026-09-15 04:36:35.795431 | instance |   },
2026-09-15 04:36:35.795467 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-15 04:36:35.795501 | instance |   "stat": {
2026-09-15 04:36:35.795534 | instance |     "atime": 1789446975.4036267,
2026-09-15 04:36:35.795570 | instance |     "attr_flags": "e",
2026-09-15 04:36:35.795604 | instance |     "attributes": [
2026-09-15 04:36:35.795637 | instance |       "extents"
2026-09-15 04:36:35.795671 | instance |     ],
2026-09-15 04:36:35.795705 | instance |     "block_size": 4096,
2026-09-15 04:36:35.795739 | instance |     "blocks": 8,
2026-09-15 04:36:35.795773 | instance |     "charset": "unknown",
2026-09-15 04:36:35.795829 | instance |     "ctime": 1789446973.9276128,
2026-09-15 04:36:35.795866 | instance |     "dev": 64513,
2026-09-15 04:36:35.795900 | instance |     "device_type": 0,
2026-09-15 04:36:35.795934 | instance |     "executable": true,
2026-09-15 04:36:35.795968 | instance |     "exists": true,
2026-09-15 04:36:35.796002 | instance |     "gid": 1000,
2026-09-15 04:36:35.796035 | instance |     "gr_name": "zuul",
2026-09-15 04:36:35.796069 | instance |     "inode": 1049151,
2026-09-15 04:36:35.796103 | instance |     "isblk": false,
2026-09-15 04:36:35.796137 | instance |     "ischr": false,
2026-09-15 04:36:35.796171 | instance |     "isdir": true,
2026-09-15 04:36:35.796203 | instance |     "isfifo": false,
2026-09-15 04:36:35.796237 | instance |     "isgid": false,
2026-09-15 04:36:35.796269 | instance |     "islnk": false,
2026-09-15 04:36:35.796303 | instance |     "isreg": false,
2026-09-15 04:36:35.796335 | instance |     "issock": false,
2026-09-15 04:36:35.796368 | instance |     "isuid": false,
2026-09-15 04:36:35.796401 | instance |     "mimetype": "unknown",
2026-09-15 04:36:35.796434 | instance |     "mode": "0755",
2026-09-15 04:36:35.796466 | instance |     "mtime": 1789446973.9276128,
2026-09-15 04:36:35.796529 | instance |     "nlink": 22,
2026-09-15 04:36:35.796566 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-15 04:36:35.796599 | instance |     "pw_name": "zuul",
2026-09-15 04:36:35.796634 | instance |     "readable": true,
2026-09-15 04:36:35.796668 | instance |     "rgrp": true,
2026-09-15 04:36:35.796702 | instance |     "roth": true,
2026-09-15 04:36:35.796736 | instance |     "rusr": true,
2026-09-15 04:36:35.796770 | instance |     "size": 4096,
2026-09-15 04:36:35.796823 | instance |     "uid": 1000,
2026-09-15 04:36:35.796859 | instance |     "version": "3961013847",
2026-09-15 04:36:35.796894 | instance |     "wgrp": false,
2026-09-15 04:36:35.796943 | instance |     "woth": false,
2026-09-15 04:36:35.796999 | instance |     "writeable": true,
2026-09-15 04:36:35.797055 | instance |     "wusr": true,
2026-09-15 04:36:35.797109 | instance |     "xgrp": true,
2026-09-15 04:36:35.797164 | instance |     "xoth": true,
2026-09-15 04:36:35.797218 | instance |     "xusr": true
2026-09-15 04:36:35.797270 | instance |   }
2026-09-15 04:36:35.797323 | instance | }
2026-09-15 04:36:35.797416 | instance | ok: All items complete
2026-09-15 04:36:35.797475 | 
2026-09-15 04:36:35.801027 | instance | skipping: Conditional result was False
2026-09-15 04:36:35.802796 | instance | skipping: Conditional result was False
2026-09-15 04:36:35.820008 | 
2026-09-15 04:36:35.820279 | LOOP [Switch "ansible_host" to private IP]
2026-09-15 04:36:36.167545 | instance | changed: 1 replacements made
2026-09-15 04:36:36.169900 | instance | skipping: Conditional result was False
2026-09-15 04:36:36.171060 | instance | skipping: Conditional result was False
2026-09-15 04:36:36.195374 | 
2026-09-15 04:36:36.195661 | TASK [Run Molecule scenario]
2026-09-15 04:36:37.155469 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-15 04:36:37.588179 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-15 04:36:37.588329 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-15 04:36:48.528575 | instance | INFO     [csi > dependency] Executing
2026-09-15 04:36:48.529070 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-15 04:36:48.529654 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-15 04:36:48.529879 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-15 04:36:48.546335 | instance | INFO     [csi > cleanup] Executing
2026-09-15 04:36:48.547237 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-15 04:36:48.560310 | instance | INFO     [csi > destroy] Executing
2026-09-15 04:36:48.560416 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-15 04:36:48.560590 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-15 04:36:48.570410 | instance | INFO     [csi > syntax] Executing
2026-09-15 04:36:50.109203 | instance |
2026-09-15 04:36:50.109434 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-15 04:36:50.189504 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-15 04:36:50.207252 | instance | INFO     [csi > create] Executing
2026-09-15 04:36:50.211389 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-15 04:36:50.228144 | instance | INFO     [csi > prepare] Executing
2026-09-15 04:36:51.032772 | instance |
2026-09-15 04:36:51.032902 | instance | PLAY [Prepare] *****************************************************************
2026-09-15 04:36:51.033309 | instance |
2026-09-15 04:36:51.033418 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 04:36:51.033656 | instance | Tuesday 15 September 2026  04:36:51 +0000 (0:00:00.025)       0:00:00.025 *****
2026-09-15 04:36:52.283273 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-15 04:36:52.283512 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-15 04:36:52.283790 | instance | interpreter could change the meaning of that path. See
2026-09-15 04:36:52.284036 | instance | https://docs.ansible.com/ansible-
2026-09-15 04:36:52.284307 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-15 04:36:52.293785 | instance | ok: [instance]
2026-09-15 04:36:52.293997 | instance |
2026-09-15 04:36:52.294258 | instance | TASK [Configure short hostname] ************************************************
2026-09-15 04:36:52.294495 | instance | Tuesday 15 September 2026  04:36:52 +0000 (0:00:01.261)       0:00:01.287 *****
2026-09-15 04:36:53.831266 | instance | changed: [instance]
2026-09-15 04:36:53.831470 | instance |
2026-09-15 04:36:53.831716 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-15 04:36:53.831965 | instance | Tuesday 15 September 2026  04:36:53 +0000 (0:00:01.536)       0:00:02.823 *****
2026-09-15 04:36:54.120944 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-15 04:36:54.121268 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-15 04:36:54.121512 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-15 04:36:54.125462 | instance | changed: [instance]
2026-09-15 04:36:54.125857 | instance |
2026-09-15 04:36:54.126126 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-15 04:36:54.126367 | instance | Tuesday 15 September 2026  04:36:54 +0000 (0:00:00.295)       0:00:03.119 *****
2026-09-15 04:36:54.903932 | instance | ok: [instance]
2026-09-15 04:36:54.904134 | instance |
2026-09-15 04:36:54.904371 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-15 04:36:54.904628 | instance |
2026-09-15 04:36:54.904857 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 04:36:54.905117 | instance | Tuesday 15 September 2026  04:36:54 +0000 (0:00:00.777)       0:00:03.896 *****
2026-09-15 04:36:55.682048 | instance | ok: [instance]
2026-09-15 04:36:55.682330 | instance |
2026-09-15 04:36:55.682586 | instance | TASK [Install depedencies] *****************************************************
2026-09-15 04:36:55.682931 | instance | Tuesday 15 September 2026  04:36:55 +0000 (0:00:00.778)       0:00:04.675 *****
2026-09-15 04:37:19.966688 | instance | changed: [instance]
2026-09-15 04:37:19.966958 | instance |
2026-09-15 04:37:19.967382 | instance | TASK [Start up service] ********************************************************
2026-09-15 04:37:19.967447 | instance | Tuesday 15 September 2026  04:37:19 +0000 (0:00:24.284)       0:00:28.959 *****
2026-09-15 04:37:20.565464 | instance | ok: [instance]
2026-09-15 04:37:20.566233 | instance |
2026-09-15 04:37:20.566299 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-15 04:37:20.566312 | instance | Tuesday 15 September 2026  04:37:20 +0000 (0:00:00.598)       0:00:29.558 *****
2026-09-15 04:37:20.897688 | instance | ok: [instance]
2026-09-15 04:37:20.898372 | instance |
2026-09-15 04:37:20.898422 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-15 04:37:20.898434 | instance | Tuesday 15 September 2026  04:37:20 +0000 (0:00:00.332)       0:00:29.890 *****
2026-09-15 04:37:21.635975 | instance | changed: [instance]
2026-09-15 04:37:21.636030 | instance |
2026-09-15 04:37:21.636038 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-15 04:37:21.636045 | instance | Tuesday 15 September 2026  04:37:21 +0000 (0:00:00.738)       0:00:30.628 *****
2026-09-15 04:37:21.837726 | instance | ok: [instance]
2026-09-15 04:37:21.837781 | instance |
2026-09-15 04:37:21.837793 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-15 04:37:21.837803 | instance | Tuesday 15 September 2026  04:37:21 +0000 (0:00:00.200)       0:00:30.829 *****
2026-09-15 04:37:21.866687 | instance | skipping: [instance]
2026-09-15 04:37:21.867473 | instance |
2026-09-15 04:37:21.867550 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-15 04:37:21.867585 | instance | Tuesday 15 September 2026  04:37:21 +0000 (0:00:00.029)       0:00:30.859 *****
2026-09-15 04:37:22.508326 | instance | changed: [instance] => (item=osd0)
2026-09-15 04:37:22.508662 | instance | changed: [instance] => (item=osd1)
2026-09-15 04:37:22.509051 | instance | changed: [instance] => (item=osd2)
2026-09-15 04:37:22.509400 | instance |
2026-09-15 04:37:22.509744 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-15 04:37:22.510052 | instance | Tuesday 15 September 2026  04:37:22 +0000 (0:00:00.641)       0:00:31.501 *****
2026-09-15 04:37:23.179096 | instance | changed: [instance] => (item=osd0)
2026-09-15 04:37:23.179272 | instance | changed: [instance] => (item=osd1)
2026-09-15 04:37:23.179530 | instance | changed: [instance] => (item=osd2)
2026-09-15 04:37:23.180081 | instance |
2026-09-15 04:37:23.180387 | instance | TASK [Start loop devices] ******************************************************
2026-09-15 04:37:23.180801 | instance | Tuesday 15 September 2026  04:37:23 +0000 (0:00:00.670)       0:00:32.171 *****
2026-09-15 04:37:23.938009 | instance | changed: [instance] => (item=osd0)
2026-09-15 04:37:23.938227 | instance | changed: [instance] => (item=osd1)
2026-09-15 04:37:23.938595 | instance | changed: [instance] => (item=osd2)
2026-09-15 04:37:23.938880 | instance |
2026-09-15 04:37:23.939177 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-15 04:37:23.939473 | instance | Tuesday 15 September 2026  04:37:23 +0000 (0:00:00.758)       0:00:32.930 *****
2026-09-15 04:37:27.353977 | instance | changed: [instance] => (item=osd0)
2026-09-15 04:37:27.354040 | instance | changed: [instance] => (item=osd1)
2026-09-15 04:37:27.354051 | instance | changed: [instance] => (item=osd2)
2026-09-15 04:37:27.354062 | instance |
2026-09-15 04:37:27.354072 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-15 04:37:27.354083 | instance | Tuesday 15 September 2026  04:37:27 +0000 (0:00:03.414)       0:00:36.345 *****
2026-09-15 04:37:29.300892 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-15 04:37:29.301547 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-15 04:37:29.301586 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-15 04:37:29.301600 | instance |
2026-09-15 04:37:29.302679 | instance | PLAY RECAP *********************************************************************
2026-09-15 04:37:29.302707 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-15 04:37:29.302718 | instance |
2026-09-15 04:37:29.302728 | instance | Tuesday 15 September 2026  04:37:29 +0000 (0:00:01.949)       0:00:38.294 *****
2026-09-15 04:37:29.304524 | instance | ===============================================================================
2026-09-15 04:37:29.304544 | instance | Install depedencies ---------------------------------------------------- 24.28s
2026-09-15 04:37:29.304550 | instance | Create a volume group for each loop device ------------------------------ 3.41s
2026-09-15 04:37:29.304555 | instance | Create a logical volume for each loop device ---------------------------- 1.95s
2026-09-15 04:37:29.304561 | instance | Configure short hostname ------------------------------------------------ 1.54s
2026-09-15 04:37:29.304566 | instance | Gathering Facts --------------------------------------------------------- 1.26s
2026-09-15 04:37:29.304572 | instance | Gathering Facts --------------------------------------------------------- 0.78s
2026-09-15 04:37:29.304577 | instance | Purge "snapd" package --------------------------------------------------- 0.78s
2026-09-15 04:37:29.304693 | instance | Start loop devices ------------------------------------------------------ 0.76s
2026-09-15 04:37:29.304968 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.74s
2026-09-15 04:37:29.305195 | instance | Set permissions on loopback devices ------------------------------------- 0.67s
2026-09-15 04:37:29.305431 | instance | Create devices for Ceph ------------------------------------------------- 0.64s
2026-09-15 04:37:29.305687 | instance | Start up service -------------------------------------------------------- 0.60s
2026-09-15 04:37:29.305914 | instance | Generate lvm.conf ------------------------------------------------------- 0.33s
2026-09-15 04:37:29.306146 | instance | Ensure hostname inside hosts file --------------------------------------- 0.30s
2026-09-15 04:37:29.306370 | instance | Get list of all loopback devices ---------------------------------------- 0.20s
2026-09-15 04:37:29.306617 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s
2026-09-15 04:37:29.394330 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-15 04:37:29.409207 | instance | INFO     [csi > converge] Executing
2026-09-15 04:37:30.888932 | instance |
2026-09-15 04:37:30.889029 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-15 04:37:30.889100 | instance |
2026-09-15 04:37:30.889208 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 04:37:30.889322 | instance | Tuesday 15 September 2026  04:37:30 +0000 (0:00:00.008)       0:00:00.008 *****
2026-09-15 04:37:31.955291 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-15 04:37:31.955367 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-15 04:37:31.955496 | instance | interpreter could change the meaning of that path. See
2026-09-15 04:37:31.955594 | instance | https://docs.ansible.com/ansible-
2026-09-15 04:37:31.955696 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-15 04:37:31.969901 | instance | ok: [instance]
2026-09-15 04:37:31.969954 | instance |
2026-09-15 04:37:31.970052 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-15 04:37:31.970157 | instance | Tuesday 15 September 2026  04:37:31 +0000 (0:00:01.080)       0:00:01.089 *****
2026-09-15 04:37:32.025439 | instance | ok: [instance] => {
2026-09-15 04:37:32.025497 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-15 04:37:32.025599 | instance | }
2026-09-15 04:37:32.025695 | instance |
2026-09-15 04:37:32.025803 | instance | PLAY [all] *********************************************************************
2026-09-15 04:37:32.025901 | instance |
2026-09-15 04:37:32.026016 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 04:37:32.026109 | instance | Tuesday 15 September 2026  04:37:32 +0000 (0:00:00.055)       0:00:01.145 *****
2026-09-15 04:37:33.035854 | instance | ok: [instance]
2026-09-15 04:37:33.036196 | instance |
2026-09-15 04:37:33.036543 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-15 04:37:33.036842 | instance | Tuesday 15 September 2026  04:37:33 +0000 (0:00:01.010)       0:00:02.155 *****
2026-09-15 04:37:33.221368 | instance | ok: [instance]
2026-09-15 04:37:33.221754 | instance |
2026-09-15 04:37:33.222050 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-15 04:37:33.222317 | instance |
2026-09-15 04:37:33.222618 | instance | TASK [Gathering Facts] *********************************************************
2026-09-15 04:37:33.222929 | instance | Tuesday 15 September 2026  04:37:33 +0000 (0:00:00.184)       0:00:02.339 *****
2026-09-15 04:37:34.189424 | instance | ok: [instance]
2026-09-15 04:37:34.189683 | instance |
2026-09-15 04:37:34.191511 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-15 04:37:34.191550 | instance | Tuesday 15 September 2026  04:37:34 +0000 (0:00:00.967)       0:00:03.307 *****
2026-09-15 04:37:34.529373 | instance | ok: [instance]
2026-09-15 04:37:34.529533 | instance |
2026-09-15 04:37:34.529822 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-15 04:37:34.530172 | instance | Tuesday 15 September 2026  04:37:34 +0000 (0:00:00.340)       0:00:03.648 *****
2026-09-15 04:37:34.573307 | instance | skipping: [instance]
2026-09-15 04:37:34.573480 | instance |
2026-09-15 04:37:34.573718 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-15 04:37:34.573906 | instance | Tuesday 15 September 2026  04:37:34 +0000 (0:00:00.044)       0:00:03.692 *****
2026-09-15 04:37:34.896265 | instance | changed: [instance]
2026-09-15 04:37:34.896326 | instance |
2026-09-15 04:37:34.896336 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-15 04:37:34.896343 | instance | Tuesday 15 September 2026  04:37:34 +0000 (0:00:00.323)       0:00:04.015 *****
2026-09-15 04:37:35.000345 | instance | ok: [instance] => {
2026-09-15 04:37:35.000417 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-15 04:37:35.000430 | instance | }
2026-09-15 04:37:35.000440 | instance |
2026-09-15 04:37:35.000449 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-15 04:37:35.000459 | instance | Tuesday 15 September 2026  04:37:34 +0000 (0:00:00.102)       0:00:04.118 *****
2026-09-15 04:37:35.807865 | instance | changed: [instance]
2026-09-15 04:37:35.807917 | instance |
2026-09-15 04:37:35.807925 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-15 04:37:35.807933 | instance | Tuesday 15 September 2026  04:37:35 +0000 (0:00:00.808)       0:00:04.926 *****
2026-09-15 04:37:35.856574 | instance | skipping: [instance]
2026-09-15 04:37:35.856628 | instance |
2026-09-15 04:37:35.856911 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-15 04:37:35.856923 | instance | Tuesday 15 September 2026  04:37:35 +0000 (0:00:00.049)       0:00:04.976 *****
2026-09-15 04:37:35.929270 | instance | skipping: [instance]
2026-09-15 04:37:35.929334 | instance |
2026-09-15 04:37:35.929342 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-15 04:37:35.929349 | instance | Tuesday 15 September 2026  04:37:35 +0000 (0:00:00.072)       0:00:05.048 *****
2026-09-15 04:37:36.170558 | instance | ok: [instance]
2026-09-15 04:37:36.170643 | instance |
2026-09-15 04:37:36.170960 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-15 04:37:36.171024 | instance | Tuesday 15 September 2026  04:37:36 +0000 (0:00:00.241)       0:00:05.290 *****
2026-09-15 04:37:37.586807 | instance | ok: [instance]
2026-09-15 04:37:37.586856 | instance |
2026-09-15 04:37:37.586862 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-15 04:37:37.586867 | instance | Tuesday 15 September 2026  04:37:37 +0000 (0:00:01.416)       0:00:06.706 *****
2026-09-15 04:37:37.688703 | instance | ok: [instance] => {
2026-09-15 04:37:37.689911 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-15 04:37:37.690001 | instance | }
2026-09-15 04:37:37.690014 | instance |
2026-09-15 04:37:37.690025 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-15 04:37:37.690035 | instance | Tuesday 15 September 2026  04:37:37 +0000 (0:00:00.100)       0:00:06.807 *****
2026-09-15 04:37:38.623292 | instance | changed: [instance]
2026-09-15 04:37:38.624050 | instance |
2026-09-15 04:37:38.624118 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-15 04:37:38.624132 | instance | Tuesday 15 September 2026  04:37:38 +0000 (0:00:00.935)       0:00:07.742 *****
2026-09-15 04:37:41.630601 | instance | changed: [instance]
2026-09-15 04:37:41.630719 | instance |
2026-09-15 04:37:41.630984 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-15 04:37:41.631054 | instance | Tuesday 15 September 2026  04:37:41 +0000 (0:00:03.006)       0:00:10.749 *****
2026-09-15 04:37:41.679705 | instance | skipping: [instance]
2026-09-15 04:37:41.679823 | instance |
2026-09-15 04:37:41.680203 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-15 04:37:41.680417 | instance | Tuesday 15 September 2026  04:37:41 +0000 (0:00:00.049)       0:00:10.799 *****
2026-09-15 04:37:41.722567 | instance | skipping: [instance]
2026-09-15 04:37:41.722674 | instance |
2026-09-15 04:37:41.723332 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-15 04:37:41.723387 | instance | Tuesday 15 September 2026  04:37:41 +0000 (0:00:00.042)       0:00:10.842 *****
2026-09-15 04:37:41.769936 | instance | skipping: [instance]
2026-09-15 04:37:41.770830 | instance |
2026-09-15 04:37:41.770881 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-15 04:37:41.770895 | instance | Tuesday 15 September 2026  04:37:41 +0000 (0:00:00.047)       0:00:10.889 *****
2026-09-15 04:37:48.057251 | instance | changed: [instance]
2026-09-15 04:37:48.057330 | instance |
2026-09-15 04:37:48.057692 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-15 04:37:48.057749 | instance | Tuesday 15 September 2026  04:37:48 +0000 (0:00:06.287)       0:00:17.176 *****
2026-09-15 04:37:48.783440 | instance | changed: [instance]
2026-09-15 04:37:48.783737 | instance |
2026-09-15 04:37:48.784224 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-15 04:37:48.784289 | instance | Tuesday 15 September 2026  04:37:48 +0000 (0:00:00.726)       0:00:17.902 *****
2026-09-15 04:37:49.907064 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-15 04:37:49.907148 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-15 04:37:49.907161 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-15 04:37:49.907171 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-15 04:37:49.907181 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-15 04:37:49.907191 | instance |
2026-09-15 04:37:49.907208 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-15 04:37:49.907218 | instance | Tuesday 15 September 2026  04:37:49 +0000 (0:00:01.120)       0:00:19.023 *****
2026-09-15 04:37:50.558781 | instance | changed: [instance]
2026-09-15 04:37:50.559671 | instance |
2026-09-15 04:37:50.559689 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-15 04:37:50.559708 | instance | Tuesday 15 September 2026  04:37:50 +0000 (0:00:00.637)       0:00:19.661 *****
2026-09-15 04:37:50.559713 | instance |
2026-09-15 04:37:50.559719 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-15 04:37:50.559724 | instance | Tuesday 15 September 2026  04:37:50 +0000 (0:00:00.017)       0:00:19.678 *****
2026-09-15 04:37:51.526274 | instance | ok: [instance]
2026-09-15 04:37:51.526482 | instance |
2026-09-15 04:37:51.526881 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-15 04:37:51.527200 | instance | Tuesday 15 September 2026  04:37:51 +0000 (0:00:00.966)       0:00:20.645 *****
2026-09-15 04:37:52.021491 | instance | changed: [instance]
2026-09-15 04:37:52.021722 | instance |
2026-09-15 04:37:52.022152 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-15 04:37:52.022502 | instance | Tuesday 15 September 2026  04:37:52 +0000 (0:00:00.495)       0:00:21.140 *****
2026-09-15 04:37:52.649827 | instance | changed: [instance]
2026-09-15 04:37:52.649956 | instance |
2026-09-15 04:37:52.650398 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-15 04:37:52.650459 | instance | Tuesday 15 September 2026  04:37:52 +0000 (0:00:00.628)       0:00:21.769 *****
2026-09-15 04:37:52.901791 | instance | ok: [instance]
2026-09-15 04:37:52.901868 | instance |
2026-09-15 04:37:52.901913 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-15 04:37:52.901924 | instance | Tuesday 15 September 2026  04:37:52 +0000 (0:00:00.250)       0:00:22.019 *****
2026-09-15 04:37:52.974808 | instance | ok: [instance] => {
2026-09-15 04:37:52.974916 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-15 04:37:52.975329 | instance | }
2026-09-15 04:37:52.975346 | instance |
2026-09-15 04:37:52.975351 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-15 04:37:52.975356 | instance | Tuesday 15 September 2026  04:37:52 +0000 (0:00:00.074)       0:00:22.094 *****
2026-09-15 04:37:54.945337 | instance | changed: [instance]
2026-09-15 04:37:54.946556 | instance |
2026-09-15 04:37:54.946852 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-15 04:37:54.946882 | instance | Tuesday 15 September 2026  04:37:54 +0000 (0:00:01.969)       0:00:24.064 *****
2026-09-15 04:37:59.391534 | instance | changed: [instance]
2026-09-15 04:37:59.391654 | instance |
2026-09-15 04:37:59.392019 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-15 04:37:59.392272 | instance | Tuesday 15 September 2026  04:37:59 +0000 (0:00:04.447)       0:00:28.511 *****
2026-09-15 04:38:00.459727 | instance | ok: [instance]
2026-09-15 04:38:00.460460 | instance |
2026-09-15 04:38:00.460518 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-15 04:38:00.460530 | instance | Tuesday 15 September 2026  04:38:00 +0000 (0:00:01.067)       0:00:29.579 *****
2026-09-15 04:38:00.881718 | instance | changed: [instance]
2026-09-15 04:38:00.882567 | instance |
2026-09-15 04:38:00.882651 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-15 04:38:00.882664 | instance | Tuesday 15 September 2026  04:38:00 +0000 (0:00:00.421)       0:00:30.000 *****
2026-09-15 04:38:01.431813 | instance | changed: [instance]
2026-09-15 04:38:01.432062 | instance |
2026-09-15 04:38:01.432593 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-15 04:38:01.432976 | instance | Tuesday 15 September 2026  04:38:01 +0000 (0:00:00.550)       0:00:30.550 *****
2026-09-15 04:38:02.089722 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-15 04:38:02.089809 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-15 04:38:02.089822 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-15 04:38:02.089840 | instance |
2026-09-15 04:38:02.089941 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-15 04:38:02.090219 | instance | Tuesday 15 September 2026  04:38:02 +0000 (0:00:00.656)       0:00:31.207 *****
2026-09-15 04:38:02.631892 | instance | changed: [instance]
2026-09-15 04:38:02.632106 | instance |
2026-09-15 04:38:02.632362 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-15 04:38:02.632639 | instance | Tuesday 15 September 2026  04:38:02 +0000 (0:00:00.543)       0:00:31.751 *****
2026-09-15 04:38:03.194832 | instance | changed: [instance]
2026-09-15 04:38:03.194906 | instance |
2026-09-15 04:38:03.194914 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-15 04:38:03.194921 | instance | Tuesday 15 September 2026  04:38:03 +0000 (0:00:00.539)       0:00:32.290 *****
2026-09-15 04:38:03.194927 | instance |
2026-09-15 04:38:03.194933 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-15 04:38:03.194938 | instance | Tuesday 15 September 2026  04:38:03 +0000 (0:00:00.022)       0:00:32.313 *****
2026-09-15 04:38:03.985774 | instance | ok: [instance]
2026-09-15 04:38:03.985855 | instance |
2026-09-15 04:38:03.986127 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-15 04:38:03.986192 | instance | Tuesday 15 September 2026  04:38:03 +0000 (0:00:00.792)       0:00:33.105 *****
2026-09-15 04:38:04.910288 | instance | changed: [instance]
2026-09-15 04:38:04.910385 | instance |
2026-09-15 04:38:04.910398 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-15 04:38:04.910409 | instance | Tuesday 15 September 2026  04:38:04 +0000 (0:00:00.922)       0:00:34.028 *****
2026-09-15 04:38:05.512577 | instance | changed: [instance]
2026-09-15 04:38:05.512706 | instance |
2026-09-15 04:38:05.512719 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-15 04:38:05.512931 | instance | Tuesday 15 September 2026  04:38:05 +0000 (0:00:00.603)       0:00:34.632 *****
2026-09-15 04:38:05.574970 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-15 04:38:05.575102 | instance |
2026-09-15 04:38:05.575116 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-15 04:38:05.575248 | instance | Tuesday 15 September 2026  04:38:05 +0000 (0:00:00.062)       0:00:34.694 *****
2026-09-15 04:38:11.102389 | instance | changed: [instance]
2026-09-15 04:38:11.102600 | instance |
2026-09-15 04:38:11.103173 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-15 04:38:11.103304 | instance | Tuesday 15 September 2026  04:38:11 +0000 (0:00:05.526)       0:00:40.221 *****
2026-09-15 04:38:11.780580 | instance | ok: [instance] => (item=chronyd)
2026-09-15 04:38:11.781764 | instance | ok: [instance] => (item=sshd)
2026-09-15 04:38:11.781835 | instance |
2026-09-15 04:38:11.781846 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-15 04:38:11.781857 | instance | Tuesday 15 September 2026  04:38:11 +0000 (0:00:00.678)       0:00:40.899 *****
2026-09-15 04:38:25.704270 | instance | changed: [instance]
2026-09-15 04:38:25.704597 | instance |
2026-09-15 04:38:25.705099 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-09-15 04:38:25.705183 | instance | Tuesday 15 September 2026  04:38:25 +0000 (0:00:13.923)       0:00:54.823 *****
2026-09-15 04:38:25.941869 | instance | ok: [instance]
2026-09-15 04:38:25.941936 | instance |
2026-09-15 04:38:25.942197 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-09-15 04:38:25.942216 | instance | Tuesday 15 September 2026  04:38:25 +0000 (0:00:00.238)       0:00:55.061 *****
2026-09-15 04:38:26.442073 | instance | changed: [instance]
2026-09-15 04:38:26.442187 | instance |
2026-09-15 04:38:26.442741 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-09-15 04:38:26.442816 | instance | Tuesday 15 September 2026  04:38:26 +0000 (0:00:00.499)       0:00:55.561 *****
2026-09-15 04:38:26.809046 | instance | changed: [instance]
2026-09-15 04:38:26.809116 | instance |
2026-09-15 04:38:26.809124 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-09-15 04:38:26.809129 | instance | Tuesday 15 September 2026  04:38:26 +0000 (0:00:00.367)       0:00:55.928 *****
2026-09-15 04:38:26.952588 | instance | ok: [instance]
2026-09-15 04:38:26.953425 | instance |
2026-09-15 04:38:26.953461 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-09-15 04:38:26.953473 | instance | Tuesday 15 September 2026  04:38:26 +0000 (0:00:00.143)       0:00:56.071 *****
2026-09-15 04:38:27.233517 | instance | ok: [instance] => (item=instance)
2026-09-15 04:38:27.234284 | instance |
2026-09-15 04:38:27.234364 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-09-15 04:38:27.234377 | instance | Tuesday 15 September 2026  04:38:27 +0000 (0:00:00.280)       0:00:56.352 *****
2026-09-15 04:38:27.315425 | instance | ok: [instance]
2026-09-15 04:38:27.316269 | instance |
2026-09-15 04:38:27.316358 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-09-15 04:38:27.316375 | instance | Tuesday 15 September 2026  04:38:27 +0000 (0:00:00.081)       0:00:56.434 *****
2026-09-15 04:38:27.418523 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-09-15 04:38:27.418629 | instance |
2026-09-15 04:38:27.418640 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-09-15 04:38:27.418805 | instance | Tuesday 15 September 2026  04:38:27 +0000 (0:00:00.103)       0:00:56.538 *****
2026-09-15 04:38:27.811434 | instance | changed: [instance]
2026-09-15 04:38:27.811554 | instance |
2026-09-15 04:38:27.811623 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-09-15 04:38:27.811831 | instance | Tuesday 15 September 2026  04:38:27 +0000 (0:00:00.392)       0:00:56.930 *****
2026-09-15 04:38:28.591782 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-09-15 04:38:28.592563 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-09-15 04:38:28.592602 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-09-15 04:38:28.593581 | instance |
2026-09-15 04:38:28.593611 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-09-15 04:38:28.593623 | instance | Tuesday 15 September 2026  04:38:28 +0000 (0:00:00.780)       0:00:57.710 *****
2026-09-15 04:40:01.654736 | instance | ok: [instance]
2026-09-15 04:40:01.655143 | instance |
2026-09-15 04:40:01.655197 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-09-15 04:40:01.655211 | instance | Tuesday 15 September 2026  04:40:01 +0000 (0:01:33.061)       0:02:30.772 *****
2026-09-15 04:40:01.914465 | instance | changed: [instance]
2026-09-15 04:40:01.914525 | instance |
2026-09-15 04:40:01.914539 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-09-15 04:40:01.914549 | instance | Tuesday 15 September 2026  04:40:01 +0000 (0:00:00.260)       0:02:31.032 *****
2026-09-15 04:40:01.956441 | instance | ok: [instance]
2026-09-15 04:40:01.956474 | instance |
2026-09-15 04:40:01.956519 | instance | TASK [Install Ceph host] *******************************************************
2026-09-15 04:40:01.956590 | instance | Tuesday 15 September 2026  04:40:01 +0000 (0:00:00.042)       0:02:31.075 *****
2026-09-15 04:40:02.049021 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-09-15 04:40:02.049061 | instance |
2026-09-15 04:40:02.049069 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-09-15 04:40:02.049075 | instance | Tuesday 15 September 2026  04:40:02 +0000 (0:00:00.092)       0:02:31.168 *****
2026-09-15 04:40:03.738504 | instance | ok: [instance]
2026-09-15 04:40:03.738581 | instance |
2026-09-15 04:40:03.738599 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-09-15 04:40:03.738615 | instance | Tuesday 15 September 2026  04:40:03 +0000 (0:00:01.687)       0:02:32.856 *****
2026-09-15 04:40:03.798955 | instance | ok: [instance] => (item=instance)
2026-09-15 04:40:03.799178 | instance |
2026-09-15 04:40:03.799467 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-09-15 04:40:03.799706 | instance | Tuesday 15 September 2026  04:40:03 +0000 (0:00:00.061)       0:02:32.918 *****
2026-09-15 04:40:04.240122 | instance | ok: [instance]
2026-09-15 04:40:04.240205 | instance |
2026-09-15 04:40:04.240438 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-09-15 04:40:04.240518 | instance | Tuesday 15 September 2026  04:40:04 +0000 (0:00:00.441)       0:02:33.360 *****
2026-09-15 04:40:06.671716 | instance | ok: [instance]
2026-09-15 04:40:06.671852 | instance |
2026-09-15 04:40:06.672384 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-09-15 04:40:06.672438 | instance | Tuesday 15 September 2026  04:40:06 +0000 (0:00:02.430)       0:02:35.791 *****
2026-09-15 04:40:58.297458 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster)
2026-09-15 04:40:58.298255 | 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_egt82lba/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_egt82lba/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_egt82lba/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_egt82lba/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-15T04:40:58.073+0000 7f9a6b806640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgD6y6hqCRTaByAA2a2dMuzgaSxFCJhFV31hiL1OTgkQd3dbbG/MBhuYImo=: Malformed input [buffer:3]\n2026-09-15T04:40:58.073+0000 7f9a6b806640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-15T04:40:58.077+0000 7f9a6b806640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgD6y6hqCRTaByAA2a2dMuzgaSxFCJhFV31hiL1OTgkQd3dbbG/MBhuYImo=: Malformed input [buffer:3]\n2026-09-15T04:40:58.077+0000 7f9a6b806640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-15T04:40:58.077+0000 7f9a6b806640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgD6y6hqCRTaByAA2a2dMuzgaSxFCJhFV31hiL1OTgkQd3dbbG/MBhuYImo=: Malformed input [buffer:3]\n2026-09-15T04:40:58.077+0000 7f9a6b806640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-15T04:40:58.077+0000 7f9a6b806640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1}
2026-09-15 04:40:58.301029 | instance |
2026-09-15 04:40:58.301304 | instance | PLAY RECAP *********************************************************************
2026-09-15 04:40:58.302280 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2026-09-15 04:40:58.302303 | instance |
2026-09-15 04:40:58.302312 | instance | Tuesday 15 September 2026  04:40:58 +0000 (0:00:51.630)       0:03:27.421 *****
2026-09-15 04:40:58.302321 | instance | ===============================================================================
2026-09-15 04:40:58.303319 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 93.06s
2026-09-15 04:40:58.303392 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 51.63s
2026-09-15 04:40:58.303403 | instance | vexxhost.ceph.cephadm : Download "cephadm" ----------------------------- 13.92s
2026-09-15 04:40:58.303413 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 6.29s
2026-09-15 04:40:58.303428 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.53s
2026-09-15 04:40:58.304594 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.45s
2026-09-15 04:40:58.304665 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 3.01s
2026-09-15 04:40:58.304677 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.43s
2026-09-15 04:40:58.304707 | instance | vexxhost.containers.download_artifact : Download item ------------------- 1.97s
2026-09-15 04:40:58.304717 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.76s
2026-09-15 04:40:58.304734 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.69s
2026-09-15 04:40:58.306800 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.42s
2026-09-15 04:40:58.306854 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.12s
2026-09-15 04:40:58.306865 | instance | Gathering Facts --------------------------------------------------------- 1.08s
2026-09-15 04:40:58.306875 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.07s
2026-09-15 04:40:58.306883 | instance | Gathering Facts --------------------------------------------------------- 1.01s
2026-09-15 04:40:58.306892 | instance | Gathering Facts --------------------------------------------------------- 0.97s
2026-09-15 04:40:58.306900 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.94s
2026-09-15 04:40:58.306909 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.92s
2026-09-15 04:40:58.306917 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.81s
2026-09-15 04:40:58.465242 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-15 04:40:58.465325 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-15 04:40:58.465371 | instance | ERROR    Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-15 04:40:58.624588 | instance | ERROR
2026-09-15 04:40:58.624894 | instance | {
2026-09-15 04:40:58.624971 | instance |   "delta": "0:04:21.989113",
2026-09-15 04:40:58.625028 | instance |   "end": "2026-09-15 04:40:58.562046",
2026-09-15 04:40:58.625081 | instance |   "msg": "non-zero return code",
2026-09-15 04:40:58.625155 | instance |   "rc": 2,
2026-09-15 04:40:58.625209 | instance |   "start": "2026-09-15 04:36:36.572933"
2026-09-15 04:40:58.625254 | instance | }
failure
2026-09-15 04:40:58.635551 | 
2026-09-15 04:40:58.635617 | PLAY RECAP
2026-09-15 04:40:58.635678 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-15 04:40:58.635708 | 
2026-09-15 04:40:58.850871 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-15 04:40:58.853402 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-15 04:40:59.675822 | 
2026-09-15 04:40:59.675935 | PLAY [all]
2026-09-15 04:40:59.688350 | 
2026-09-15 04:40:59.688423 | TASK [gather-host-logs : creating directory for system status]
2026-09-15 04:41:00.064332 | instance | changed
2026-09-15 04:41:00.075546 | 
2026-09-15 04:41:00.075701 | TASK [gather-host-logs : Get logs for each host]
2026-09-15 04:41:00.488966 | instance | + systemd-cgls --full --all --no-pager
2026-09-15 04:41:00.504938 | instance | + ip addr
2026-09-15 04:41:00.508284 | instance | + ip route
2026-09-15 04:41:00.511079 | instance | + lsblk
2026-09-15 04:41:00.514049 | instance | + mount
2026-09-15 04:41:00.516598 | instance | + docker images
2026-09-15 04:41:00.543612 | instance | + brctl show
2026-09-15 04:41:00.544142 | instance | /bin/bash: line 8: brctl: command not found
2026-09-15 04:41:00.544525 | instance | + ip neigh
2026-09-15 04:41:00.546838 | instance | + bridge fdb show
2026-09-15 04:41:00.550884 | instance | + ps aux --sort=-%mem
2026-09-15 04:41:00.574038 | instance | + dpkg -l
2026-09-15 04:41:00.587484 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-15 04:41:00.588264 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-15 04:41:00.612700 | instance | + '[' '!' -z '' ']'
2026-09-15 04:41:00.670736 | instance | ok: Runtime: 0:00:00.129815
2026-09-15 04:41:00.682382 | 
2026-09-15 04:41:00.682533 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-15 04:41:01.375931 | instance | changed:
2026-09-15 04:41:01.376202 | instance | created directory /var/lib/zuul/builds/99772b6725f04c108abd260000ff738f/work/logs/instance
2026-09-15 04:41:01.376260 | instance | cd+++++++++ system/
2026-09-15 04:41:01.376301 | instance | >f+++++++++ system/brctl-show.txt
2026-09-15 04:41:01.376341 | instance | >f+++++++++ system/bridge-fdb.txt
2026-09-15 04:41:01.376379 | instance | >f+++++++++ system/docker-images.txt
2026-09-15 04:41:01.376421 | instance | >f+++++++++ system/ip-addr.txt
2026-09-15 04:41:01.376461 | instance | >f+++++++++ system/ip-neigh.txt
2026-09-15 04:41:01.376529 | instance | >f+++++++++ system/ip-route.txt
2026-09-15 04:41:01.376578 | instance | >f+++++++++ system/lsblk.txt
2026-09-15 04:41:01.376617 | instance | >f+++++++++ system/mount.txt
2026-09-15 04:41:01.376654 | instance | >f+++++++++ system/packages.txt
2026-09-15 04:41:01.376690 | instance | >f+++++++++ system/ps.txt
2026-09-15 04:41:01.376726 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-15 04:41:01.394408 | 
2026-09-15 04:41:01.394539 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-15 04:41:01.625237 | instance | changed: "values"
2026-09-15 04:41:01.813350 | instance | changed: "releases"
2026-09-15 04:41:01.829776 | 
2026-09-15 04:41:01.830012 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-15 04:41:02.071691 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-15 04:41:02.383177 | instance | ok: Runtime: 0:00:00.006955
2026-09-15 04:41:02.392145 | 
2026-09-15 04:41:02.392276 | TASK [helm-release-status : Downloads logs to executor]
2026-09-15 04:41:02.922029 | instance | changed:
2026-09-15 04:41:02.922303 | instance | cd+++++++++ helm/
2026-09-15 04:41:02.922357 | instance | cd+++++++++ helm/releases/
2026-09-15 04:41:02.922397 | instance | cd+++++++++ helm/values/
2026-09-15 04:41:02.939640 | 
2026-09-15 04:41:02.939838 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-15 04:41:03.169001 | instance | changed
2026-09-15 04:41:03.179908 | 
2026-09-15 04:41:03.180108 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-15 04:41:03.493942 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 04:41:03.494227 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 04:41:03.498341 | instance | environment: line 1: kubectl: command not found
2026-09-15 04:41:03.500273 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 04:41:03.501324 | instance | environment: line 1: kubectl: command not found
2026-09-15 04:41:03.501963 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 04:41:03.505767 | instance | environment: line 1: kubectl: command not found
2026-09-15 04:41:03.507147 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 04:41:03.508102 | instance | environment: line 1: kubectl: command not found
2026-09-15 04:41:03.510635 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 04:41:03.510907 | instance | environment: line 1: kubectl: command not found
2026-09-15 04:41:03.512529 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-15 04:41:03.730321 | instance | ok: Runtime: 0:00:00.029464
2026-09-15 04:41:03.739812 | 
2026-09-15 04:41:03.739973 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-15 04:41:03.977174 | instance | changed
2026-09-15 04:41:03.986621 | 
2026-09-15 04:41:03.986784 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-15 04:41:04.212395 | instance | environment: line 5: kubectl: command not found
2026-09-15 04:41:04.212523 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 04:41:04.213587 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 04:41:04.214051 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-15 04:41:04.534836 | instance | ok: Runtime: 0:00:00.008222
2026-09-15 04:41:04.543575 | 
2026-09-15 04:41:04.543675 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-15 04:41:05.065514 | instance | changed:
2026-09-15 04:41:05.065823 | instance | cd+++++++++ objects/
2026-09-15 04:41:05.065888 | instance | cd+++++++++ objects/cluster/
2026-09-15 04:41:05.065940 | instance | cd+++++++++ objects/namespaced/
2026-09-15 04:41:05.082784 | 
2026-09-15 04:41:05.082937 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-15 04:41:05.310286 | instance | changed
2026-09-15 04:41:05.319936 | 
2026-09-15 04:41:05.320072 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-15 04:41:05.554411 | instance | changed
2026-09-15 04:41:05.567778 | 
2026-09-15 04:41:05.567941 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-15 04:41:05.830623 | instance | environment: line 3: kubectl: command not found
2026-09-15 04:41:06.113796 | instance | ok: Runtime: 0:00:00.008176
2026-09-15 04:41:06.123008 | 
2026-09-15 04:41:06.123164 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-15 04:41:06.701118 | instance | changed:
2026-09-15 04:41:06.701374 | instance | cd+++++++++ pod-logs/
2026-09-15 04:41:06.701414 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-15 04:41:06.713539 | 
2026-09-15 04:41:06.713621 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-15 04:41:06.959213 | instance | changed
2026-09-15 04:41:06.968043 | 
2026-09-15 04:41:06.968189 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-15 04:41:07.225917 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-15 04:41:07.518414 | instance | ok: Runtime: 0:00:00.057750
2026-09-15 04:41:07.527225 | 
2026-09-15 04:41:07.527353 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-15 04:41:07.787479 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-15 04:41:07.838839 | instance | ceph-mgr endpoints:
2026-09-15 04:41:08.076336 | instance | ok: Runtime: 0:00:00.058484
2026-09-15 04:41:08.087613 | 
2026-09-15 04:41:08.087760 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-15 04:41:08.349064 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-15 04:41:08.635967 | instance | ok: Runtime: 0:00:00.050238
2026-09-15 04:41:08.645471 | 
2026-09-15 04:41:08.645597 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-15 04:41:09.175793 | instance | changed: cd+++++++++ prometheus/
2026-09-15 04:41:09.192517 | 
2026-09-15 04:41:09.192666 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-15 04:41:09.422397 | instance | changed
2026-09-15 04:41:09.431250 | 
2026-09-15 04:41:09.431384 | TASK [gather-selenium-data : Get selenium data]
2026-09-15 04:41:09.755803 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-15 04:41:09.757659 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-15 04:41:09.981658 | instance | ERROR
2026-09-15 04:41:09.981968 | instance | {
2026-09-15 04:41:09.982028 | instance |   "delta": "0:00:00.007947",
2026-09-15 04:41:09.982068 | instance |   "end": "2026-09-15 04:41:09.758146",
2026-09-15 04:41:09.982105 | instance |   "msg": "non-zero return code",
2026-09-15 04:41:09.982166 | instance |   "rc": 1,
2026-09-15 04:41:09.982205 | instance |   "start": "2026-09-15 04:41:09.750199"
2026-09-15 04:41:09.982243 | instance | }
2026-09-15 04:41:09.982304 | instance | ERROR: Ignoring Errors
2026-09-15 04:41:09.991387 | 
2026-09-15 04:41:09.991510 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-15 04:41:10.515100 | instance | changed: cd+++++++++ selenium/
2026-09-15 04:41:10.525434 | 
2026-09-15 04:41:10.525508 | PLAY RECAP
2026-09-15 04:41:10.525567 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-15 04:41:10.525594 | 
2026-09-15 04:41:10.671778 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-15 04:41:10.673778 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-15 04:41:11.240258 | 
2026-09-15 04:41:11.240390 | PLAY [all]
2026-09-15 04:41:11.250764 | 
2026-09-15 04:41:11.250834 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-15 04:41:11.305469 | instance | skipping: Conditional result was False
2026-09-15 04:41:11.312625 | 
2026-09-15 04:41:11.312755 | TASK [fetch-output : Set log path for single node]
2026-09-15 04:41:11.365779 | instance | ok
2026-09-15 04:41:11.375188 | 
2026-09-15 04:41:11.375326 | LOOP [fetch-output : Ensure local output dirs]
2026-09-15 04:41:11.802844 | instance -> localhost | ok: "/var/lib/zuul/builds/99772b6725f04c108abd260000ff738f/work/logs"
2026-09-15 04:41:12.033241 | instance -> localhost | changed: "/var/lib/zuul/builds/99772b6725f04c108abd260000ff738f/work/artifacts"
2026-09-15 04:41:12.259514 | instance -> localhost | changed: "/var/lib/zuul/builds/99772b6725f04c108abd260000ff738f/work/docs"
2026-09-15 04:41:12.293045 | 
2026-09-15 04:41:12.293261 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-15 04:41:12.977165 | instance | changed: .d..t...... ./
2026-09-15 04:41:12.977584 | instance | changed: All items complete
2026-09-15 04:41:12.978575 | 
2026-09-15 04:41:13.519189 | instance | changed: .d..t...... ./
2026-09-15 04:41:13.994050 | instance | changed: .d..t...... ./
2026-09-15 04:41:14.017441 | 
2026-09-15 04:41:14.017631 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-15 04:41:14.464535 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008421
2026-09-15 04:41:14.695909 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006893
2026-09-15 04:41:14.713288 | 
2026-09-15 04:41:14.713423 | PLAY [all]
2026-09-15 04:41:14.722073 | 
2026-09-15 04:41:14.722308 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-15 04:41:15.162660 | instance | changed
2026-09-15 04:41:15.172725 | 
2026-09-15 04:41:15.172828 | PLAY RECAP
2026-09-15 04:41:15.172918 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-15 04:41:15.172984 | 
2026-09-15 04:41:15.317318 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-15 04:41:15.321108 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-15 04:41:15.866317 | 
2026-09-15 04:41:15.866441 | PLAY [localhost]
2026-09-15 04:41:15.875485 | 
2026-09-15 04:41:15.875597 | TASK [Generate Zuul manifest]
2026-09-15 04:41:15.892737 | localhost | ok
2026-09-15 04:41:15.909175 | 
2026-09-15 04:41:15.909282 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-15 04:41:16.314888 | localhost | changed
2026-09-15 04:41:16.340356 | 
2026-09-15 04:41:16.340555 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-15 04:41:16.383397 | localhost | ok
2026-09-15 04:41:16.396114 | 
2026-09-15 04:41:16.396260 | TASK [Upload logs]
2026-09-15 04:41:16.420255 | localhost | ok
2026-09-15 04:41:16.504307 | 
2026-09-15 04:41:16.504457 | TASK [Set zuul-log-path fact]
2026-09-15 04:41:16.523316 | localhost | ok
2026-09-15 04:41:16.536330 | 
2026-09-15 04:41:16.536392 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-15 04:41:16.574752 | localhost | ok
2026-09-15 04:41:16.582837 | 
2026-09-15 04:41:16.582926 | TASK [upload-logs : Create log directories]
2026-09-15 04:41:16.969306 | localhost | changed
2026-09-15 04:41:16.978170 | 
2026-09-15 04:41:16.978306 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-15 04:41:17.444257 | localhost -> localhost | ok: Runtime: 0:00:00.006171
2026-09-15 04:41:17.450827 | 
2026-09-15 04:41:17.450918 | TASK [upload-logs : Upload logs to log server]
2026-09-15 04:41:17.941241 | localhost | Output suppressed because no_log was given
2026-09-15 04:41:17.997134 | 
2026-09-15 04:41:17.997354 | LOOP [upload-logs : Compress console log and json output]
2026-09-15 04:41:18.061117 | localhost | skipping: Conditional result was False
2026-09-15 04:41:18.070393 | localhost | skipping: Conditional result was False
2026-09-15 04:41:18.083370 | 
2026-09-15 04:41:18.083612 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-15 04:41:18.128718 | localhost | skipping: Conditional result was False
2026-09-15 04:41:18.129094 | 
2026-09-15 04:41:18.132437 | localhost | skipping: Conditional result was False
2026-09-15 04:41:18.149004 | 
2026-09-15 04:41:18.149265 | LOOP [upload-logs : Upload console log and json output]