2026-09-16 21:45:06.392572 | Job console starting
2026-09-16 21:45:06.405283 | Updating git repos
2026-09-16 21:45:09.077744 | Cloning repos into workspace
2026-09-16 21:45:26.125638 | Restoring repo states
2026-09-16 21:45:40.367942 | Merging changes
2026-09-16 21:45:41.511473 | Checking out repos
2026-09-16 21:45:43.628424 | Preparing playbooks
2026-09-16 21:45:45.630239 | Running Ansible setup
2026-09-16 21:45:49.248267 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-16 21:45:49.858638 | 
2026-09-16 21:45:49.858777 | PLAY [localhost]
2026-09-16 21:45:49.866490 | 
2026-09-16 21:45:49.866564 | TASK [Gathering Facts]
2026-09-16 21:45:50.764855 | localhost | ok
2026-09-16 21:45:50.774482 | 
2026-09-16 21:45:50.774637 | TASK [Setup log path fact]
2026-09-16 21:45:50.794017 | localhost | ok
2026-09-16 21:45:50.808412 | 
2026-09-16 21:45:50.808648 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-16 21:45:50.848352 | localhost | ok
2026-09-16 21:45:50.856275 | 
2026-09-16 21:45:50.856454 | TASK [emit-job-header : Print job information]
2026-09-16 21:45:50.908166 | # Job Information
2026-09-16 21:45:50.908598 | Ansible Version: 2.16.18
2026-09-16 21:45:50.908696 | Job: atmosphere-molecule-csi-rbd
2026-09-16 21:45:50.908745 | Pipeline: check
2026-09-16 21:45:50.908784 | Executor: 2d72f0692154
2026-09-16 21:45:50.908821 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4095
2026-09-16 21:45:50.908865 | Event ID: 964e1400-b216-11f1-9a58-cb8ceac34e79
2026-09-16 21:45:50.915315 | 
2026-09-16 21:45:50.915451 | LOOP [emit-job-header : Print node information]
2026-09-16 21:45:51.022872 | localhost | ok:
2026-09-16 21:45:51.023180 | localhost | # Node Information
2026-09-16 21:45:51.023231 | localhost | Inventory Hostname: instance
2026-09-16 21:45:51.023267 | localhost | Hostname: np0000192955
2026-09-16 21:45:51.023305 | localhost | Username: zuul
2026-09-16 21:45:51.023341 | localhost | Distro: Ubuntu 22.04
2026-09-16 21:45:51.023374 | localhost | Provider: yul1
2026-09-16 21:45:51.023407 | localhost | Region: ca-ymq-1
2026-09-16 21:45:51.023439 | localhost | Label: ubuntu-jammy
2026-09-16 21:45:51.023475 | localhost | Product Name: OpenStack Nova
2026-09-16 21:45:51.023505 | localhost | Interface IP: 162.253.55.76
2026-09-16 21:45:51.035658 | 
2026-09-16 21:45:51.035887 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-16 21:45:51.449816 | localhost -> localhost | changed
2026-09-16 21:45:51.460624 | 
2026-09-16 21:45:51.460782 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-16 21:45:52.380829 | localhost -> localhost | changed
2026-09-16 21:45:52.389915 | 
2026-09-16 21:45:52.390059 | PLAY [all]
2026-09-16 21:45:52.402156 | 
2026-09-16 21:45:52.402321 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-16 21:45:52.641885 | instance -> localhost | ok
2026-09-16 21:45:52.651555 | 
2026-09-16 21:45:52.651709 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-16 21:45:52.680586 | instance | ok
2026-09-16 21:45:52.708837 | instance | included: /var/lib/zuul/builds/5297627213494c45854380c5d423319c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-16 21:45:52.718545 | 
2026-09-16 21:45:52.718764 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-16 21:45:53.503381 | instance -> localhost | Generating public/private rsa key pair.
2026-09-16 21:45:53.657572 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/5297627213494c45854380c5d423319c/work/5297627213494c45854380c5d423319c_id_rsa
2026-09-16 21:45:53.657748 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/5297627213494c45854380c5d423319c/work/5297627213494c45854380c5d423319c_id_rsa.pub
2026-09-16 21:45:53.657801 | instance -> localhost | The key fingerprint is:
2026-09-16 21:45:53.657844 | instance -> localhost | SHA256:tbDOgiHe5fRxwH0xNtMB0PBy8PMIkuPe3inQhUbSGiw zuul-build-sshkey
2026-09-16 21:45:53.657906 | instance -> localhost | The key's randomart image is:
2026-09-16 21:45:53.657949 | instance -> localhost | +---[RSA 3072]----+
2026-09-16 21:45:53.658000 | instance -> localhost | |       . .++Bo.. |
2026-09-16 21:45:53.658042 | instance -> localhost | |      E.+oo=.=.  |
2026-09-16 21:45:53.658105 | instance -> localhost | |       .B==.*    |
2026-09-16 21:45:53.658147 | instance -> localhost | |       ..Bo*.+   |
2026-09-16 21:45:53.658185 | instance -> localhost | |  . . o Soo.. .  |
2026-09-16 21:45:53.658222 | instance -> localhost | | . o * =.+.      |
2026-09-16 21:45:53.658259 | instance -> localhost | |  . o o =..      |
2026-09-16 21:45:53.658297 | instance -> localhost | |       . ... .   |
2026-09-16 21:45:53.658341 | instance -> localhost | |          ..o    |
2026-09-16 21:45:53.658381 | instance -> localhost | +----[SHA256]-----+
2026-09-16 21:45:53.658521 | instance -> localhost | ok: Runtime: 0:00:00.284877
2026-09-16 21:45:53.672305 | 
2026-09-16 21:45:53.672442 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-16 21:45:53.707712 | instance | ok
2026-09-16 21:45:53.724372 | instance | included: /var/lib/zuul/builds/5297627213494c45854380c5d423319c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-16 21:45:53.737468 | 
2026-09-16 21:45:53.737556 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-16 21:45:53.761985 | instance | skipping: Conditional result was False
2026-09-16 21:45:53.774894 | 
2026-09-16 21:45:53.775040 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-16 21:45:54.276471 | instance | changed
2026-09-16 21:45:54.291043 | 
2026-09-16 21:45:54.291216 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-16 21:45:54.497927 | instance | ok
2026-09-16 21:45:54.508019 | 
2026-09-16 21:45:54.508196 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-16 21:45:55.010521 | instance | changed
2026-09-16 21:45:55.020804 | 
2026-09-16 21:45:55.020940 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-16 21:45:55.531239 | instance | changed
2026-09-16 21:45:55.538657 | 
2026-09-16 21:45:55.538805 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-16 21:45:55.562952 | instance | skipping: Conditional result was False
2026-09-16 21:45:55.570473 | 
2026-09-16 21:45:55.570554 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-16 21:45:55.985277 | instance -> localhost | changed
2026-09-16 21:45:56.009903 | 
2026-09-16 21:45:56.010158 | TASK [add-build-sshkey : Add back temp key]
2026-09-16 21:45:56.351040 | instance -> localhost | Identity added: /var/lib/zuul/builds/5297627213494c45854380c5d423319c/work/5297627213494c45854380c5d423319c_id_rsa (zuul-build-sshkey)
2026-09-16 21:45:56.351662 | instance -> localhost | ok: Runtime: 0:00:00.015473
2026-09-16 21:45:56.362654 | 
2026-09-16 21:45:56.362793 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-16 21:45:56.766811 | instance | ok
2026-09-16 21:45:56.776632 | 
2026-09-16 21:45:56.776787 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-16 21:45:56.801752 | instance | skipping: Conditional result was False
2026-09-16 21:45:56.820632 | 
2026-09-16 21:45:56.820822 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-16 21:45:57.179568 | instance | ok
2026-09-16 21:45:57.191054 | 
2026-09-16 21:45:57.191213 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-16 21:46:00.972019 | instance | Output suppressed because no_log was given
2026-09-16 21:46:00.988691 | 
2026-09-16 21:46:00.988817 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-16 21:46:01.213023 | instance | ok: "logs"
2026-09-16 21:46:01.213598 | instance | ok: All items complete
2026-09-16 21:46:01.213671 | 
2026-09-16 21:46:01.380754 | instance | ok: "artifacts"
2026-09-16 21:46:01.556280 | instance | ok: "docs"
2026-09-16 21:46:01.572862 | 
2026-09-16 21:46:01.573125 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-16 21:46:01.780057 | instance | changed: "logs"
2026-09-16 21:46:01.931178 | instance | changed: "artifacts"
2026-09-16 21:46:02.079716 | instance | changed: "docs"
2026-09-16 21:46:02.091402 | 
2026-09-16 21:46:02.091486 | PLAY RECAP
2026-09-16 21:46:02.091539 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-16 21:46:02.091577 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-16 21:46:02.091602 | 
2026-09-16 21:46:02.278188 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-16 21:46:02.280806 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-16 21:46:02.854849 | 
2026-09-16 21:46:02.854981 | PLAY [all]
2026-09-16 21:46:02.864899 | 
2026-09-16 21:46:02.864983 | TASK [setup-uv : Extract archive]
2026-09-16 21:46:05.188923 | instance | changed
2026-09-16 21:46:05.199685 | 
2026-09-16 21:46:05.199836 | TASK [setup-uv : Print version]
2026-09-16 21:46:05.594908 | instance | uv 0.8.13
2026-09-16 21:46:05.748253 | instance | ok: Runtime: 0:00:00.007663
2026-09-16 21:46:05.765821 | 
2026-09-16 21:46:05.765989 | TASK [Install Ansible collection siblings]
2026-09-16 21:46:17.267732 | instance | changed:
2026-09-16 21:46:17.267981 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-16 21:46:17.268040 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-16 21:46:17.268101 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-16 21:46:17.268142 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-16 21:46:17.268180 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-16 21:46:17.268225 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-16 21:46:17.268264 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-16 21:46:17.268306 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-16 21:46:17.268343 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-16 21:46:17.268380 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-16 21:46:17.268417 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-16 21:46:17.268453 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-16 21:46:17.268488 | instance | Resolved ansible.netcommon to v8.6.2 for >=1.2.0
2026-09-16 21:46:17.268657 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-16 21:46:17.268700 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0
2026-09-16 21:46:17.268739 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-16 21:46:17.268776 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-16 21:46:17.268813 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-16 21:46:17.268848 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-16 21:46:17.268883 | instance | Resolved openstack.cloud to 1.7.0 for 1.7.0
2026-09-16 21:46:17.268918 | instance | Resolved atmosphere.common to v0.6.0 for >=0.6.0,<0.7.0
2026-09-16 21:46:17.268952 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.1.2
2026-09-16 21:46:17.268990 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-16 21:46:17.269046 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-09-16 21:46:17.269109 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-16 21:46:17.269148 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-16 21:46:17.269184 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-16 21:46:17.269219 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=3.0.0,>=6.0.0
2026-09-16 21:46:17.269253 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-16 21:46:17.269287 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-16 21:46:17.269322 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-16 21:46:17.269362 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-16 21:46:17.269436 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-16 21:46:17.269532 | instance | Checked out ansible.netcommon to v8.6.2
2026-09-16 21:46:17.269604 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-16 21:46:17.269680 | instance | Starting galaxy collection install process
2026-09-16 21:46:17.269785 | instance | Process install dependency map
2026-09-16 21:46:17.269876 | instance | Starting collection install process
2026-09-16 21:46:17.269970 | instance | Installing 'ansible.netcommon:8.6.2' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-16 21:46:17.270026 | instance | Created collection for ansible.netcommon:8.6.2 at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-16 21:46:17.270065 | instance | ansible.netcommon:8.6.2 was installed successfully
2026-09-16 21:46:17.270122 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-16 21:46:17.270158 | instance | Creating virtual environment at: .venv
2026-09-16 21:46:17.270192 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-16 21:46:17.270228 | instance | Downloading openstacksdk (1.7MiB)
2026-09-16 21:46:17.270269 | instance | Downloading pygments (1.2MiB)
2026-09-16 21:46:17.270305 | instance | Downloading cryptography (4.2MiB)
2026-09-16 21:46:17.270340 | instance | Downloading netaddr (2.2MiB)
2026-09-16 21:46:17.270374 | instance | Downloading rjsonnet (1.2MiB)
2026-09-16 21:46:17.270409 | instance | Downloading kubernetes (1.9MiB)
2026-09-16 21:46:17.270443 | instance | Downloading setuptools (1.1MiB)
2026-09-16 21:46:17.270476 | instance | Downloading ansible-core (2.1MiB)
2026-09-16 21:46:17.270509 | instance |    Building pyperclip==1.9.0
2026-09-16 21:46:17.270548 | instance |  Downloading rjsonnet
2026-09-16 21:46:17.270583 | instance |  Downloading netaddr
2026-09-16 21:46:17.270618 | instance |  Downloading pygments
2026-09-16 21:46:17.270652 | instance |  Downloading cryptography
2026-09-16 21:46:17.270686 | instance |  Downloading setuptools
2026-09-16 21:46:17.270719 | instance |  Downloading kubernetes
2026-09-16 21:46:17.270752 | instance |  Downloading ansible-core
2026-09-16 21:46:17.270785 | instance |  Downloading openstacksdk
2026-09-16 21:46:17.270820 | instance |       Built pyperclip==1.9.0
2026-09-16 21:46:17.270855 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-16 21:46:17.270959 | instance | Installed 79 packages in 43ms
2026-09-16 21:46:17.270995 | instance | Checked out ansible.posix to 1.6.0
2026-09-16 21:46:17.271029 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-16 21:46:17.271064 | instance | Starting galaxy collection install process
2026-09-16 21:46:17.271121 | instance | Process install dependency map
2026-09-16 21:46:17.271167 | instance | Starting collection install process
2026-09-16 21:46:17.271203 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-16 21:46:17.271239 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-16 21:46:17.271309 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-16 21:46:17.271363 | instance | Checked out ansible.utils to v6.1.0
2026-09-16 21:46:17.271415 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-16 21:46:17.271468 | instance | Starting galaxy collection install process
2026-09-16 21:46:17.271519 | instance | Process install dependency map
2026-09-16 21:46:17.271577 | instance | Starting collection install process
2026-09-16 21:46:17.271633 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-16 21:46:17.271690 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-16 21:46:17.271744 | instance | ansible.utils:6.1.0 was installed successfully
2026-09-16 21:46:17.271800 | instance | Checked out atmosphere.common to v0.6.0
2026-09-16 21:46:17.271851 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-16 21:46:17.271935 | instance | Starting galaxy collection install process
2026-09-16 21:46:17.271996 | instance | Process install dependency map
2026-09-16 21:46:17.272046 | instance | Starting collection install process
2026-09-16 21:46:17.272117 | instance | Installing 'atmosphere.common:0.6.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-16 21:46:17.272168 | instance | Created collection for atmosphere.common:0.6.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-16 21:46:17.272216 | instance | atmosphere.common:0.6.0 was installed successfully
2026-09-16 21:46:17.272264 | instance | Checked out community.crypto to 2.2.3
2026-09-16 21:46:17.272312 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-16 21:46:17.272361 | instance | Starting galaxy collection install process
2026-09-16 21:46:17.272408 | instance | Process install dependency map
2026-09-16 21:46:17.272455 | instance | Starting collection install process
2026-09-16 21:46:17.272600 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-16 21:46:17.272671 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-16 21:46:17.272722 | instance | community.crypto:2.2.3 was installed successfully
2026-09-16 21:46:17.272770 | instance | Checked out community.general to 7.3.0
2026-09-16 21:46:17.272816 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general
2026-09-16 21:46:17.272863 | instance | Starting galaxy collection install process
2026-09-16 21:46:17.272909 | instance | Process install dependency map
2026-09-16 21:46:17.273127 | instance | Starting collection install process
2026-09-16 21:46:17.273201 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-16 21:46:17.273250 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-16 21:46:17.273298 | instance | community.general:7.3.0 was installed successfully
2026-09-16 21:46:17.273364 | instance | Checked out community.mysql to 3.6.0
2026-09-16 21:46:17.273411 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-16 21:46:17.273457 | instance | Starting galaxy collection install process
2026-09-16 21:46:17.273503 | instance | Process install dependency map
2026-09-16 21:46:17.273548 | instance | Starting collection install process
2026-09-16 21:46:17.273594 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-16 21:46:17.273640 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-16 21:46:17.273686 | instance | community.mysql:3.6.0 was installed successfully
2026-09-16 21:46:17.273733 | instance | Checked out kubernetes.core to 2.4.0
2026-09-16 21:46:17.273778 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-16 21:46:17.273825 | instance | Starting galaxy collection install process
2026-09-16 21:46:17.273871 | instance | Process install dependency map
2026-09-16 21:46:17.273923 | instance | Starting collection install process
2026-09-16 21:46:17.273972 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-16 21:46:17.274020 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-16 21:46:17.274065 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-16 21:46:17.274142 | instance | Checked out openstack.cloud to 1.7.0
2026-09-16 21:46:17.274240 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-16 21:46:17.274304 | instance | Starting galaxy collection install process
2026-09-16 21:46:17.274344 | instance | Process install dependency map
2026-09-16 21:46:17.274380 | instance | Starting collection install process
2026-09-16 21:46:17.274416 | instance | Installing 'openstack.cloud:1.7.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-16 21:46:17.274451 | instance | Created collection for openstack.cloud:1.7.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-16 21:46:17.274488 | instance | openstack.cloud:1.7.0 was installed successfully
2026-09-16 21:46:17.274523 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-16 21:46:17.274558 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-16 21:46:17.274593 | instance | Starting galaxy collection install process
2026-09-16 21:46:17.274627 | instance | Process install dependency map
2026-09-16 21:46:17.274662 | instance | Starting collection install process
2026-09-16 21:46:17.274697 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-16 21:46:17.274731 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-16 21:46:17.274765 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-16 21:46:17.274811 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-16 21:46:17.274898 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-16 21:46:17.274955 | instance | Starting galaxy collection install process
2026-09-16 21:46:17.275005 | instance | Process install dependency map
2026-09-16 21:46:17.275042 | instance | Starting collection install process
2026-09-16 21:46:17.275099 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-16 21:46:17.275138 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-16 21:46:17.275173 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-16 21:46:17.275207 | instance | Checked out vexxhost.kubernetes to v3.4.0
2026-09-16 21:46:17.275242 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-16 21:46:17.275276 | instance | Starting galaxy collection install process
2026-09-16 21:46:17.275312 | instance | Process install dependency map
2026-09-16 21:46:17.275347 | instance | Starting collection install process
2026-09-16 21:46:17.275381 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-16 21:46:17.275415 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-16 21:46:17.275448 | instance | vexxhost.kubernetes:3.4.0 was installed successfully
2026-09-16 21:46:17.285251 | 
2026-09-16 21:46:17.285339 | PLAY RECAP
2026-09-16 21:46:17.285419 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-16 21:46:17.285459 | 
2026-09-16 21:46:17.427705 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-16 21:46:17.432267 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-16 21:46:18.025856 | 
2026-09-16 21:46:18.026018 | PLAY [all]
2026-09-16 21:46:18.036552 | 
2026-09-16 21:46:18.036641 | TASK [Install "jq" for log collection]
2026-09-16 21:46:27.120625 | instance | changed
2026-09-16 21:46:27.123967 | 
2026-09-16 21:46:27.124109 | PLAY RECAP
2026-09-16 21:46:27.124212 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-16 21:46:27.124312 | 
2026-09-16 21:46:27.303336 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-16 21:46:27.306029 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-16 21:46:27.910230 | 
2026-09-16 21:46:27.910385 | PLAY [all]
2026-09-16 21:46:27.921356 | 
2026-09-16 21:46:27.921456 | LOOP [Check inventory directories]
2026-09-16 21:46:28.280505 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-16 21:46:28.451156 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-16 21:46:28.632905 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-16 21:46:28.649876 | 
2026-09-16 21:46:28.650156 | LOOP [Copy inventory file for Zuul]
2026-09-16 21:46:29.538759 | instance | changed:
2026-09-16 21:46:29.539029 | instance | {
2026-09-16 21:46:29.539063 | instance |   "ansible_loop_var": "item",
2026-09-16 21:46:29.539105 | instance |   "changed": false,
2026-09-16 21:46:29.539125 | instance |   "failed": false,
2026-09-16 21:46:29.539151 | instance |   "invocation": {
2026-09-16 21:46:29.539169 | instance |     "module_args": {
2026-09-16 21:46:29.539187 | instance |       "checksum_algorithm": "sha1",
2026-09-16 21:46:29.539203 | instance |       "follow": true,
2026-09-16 21:46:29.539219 | instance |       "get_attributes": true,
2026-09-16 21:46:29.539236 | instance |       "get_checksum": true,
2026-09-16 21:46:29.539253 | instance |       "get_mime": true,
2026-09-16 21:46:29.539269 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-16 21:46:29.539286 | instance |     }
2026-09-16 21:46:29.539309 | instance |   },
2026-09-16 21:46:29.539325 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-16 21:46:29.539341 | instance |   "stat": {
2026-09-16 21:46:29.539356 | instance |     "atime": 1789595170.007981,
2026-09-16 21:46:29.539372 | instance |     "attr_flags": "e",
2026-09-16 21:46:29.539388 | instance |     "attributes": [
2026-09-16 21:46:29.539405 | instance |       "extents"
2026-09-16 21:46:29.539420 | instance |     ],
2026-09-16 21:46:29.539436 | instance |     "block_size": 4096,
2026-09-16 21:46:29.539452 | instance |     "blocks": 8,
2026-09-16 21:46:29.539468 | instance |     "charset": "unknown",
2026-09-16 21:46:29.539485 | instance |     "ctime": 1789595166.555784,
2026-09-16 21:46:29.539501 | instance |     "dev": 64513,
2026-09-16 21:46:29.539516 | instance |     "device_type": 0,
2026-09-16 21:46:29.539532 | instance |     "executable": true,
2026-09-16 21:46:29.539548 | instance |     "exists": true,
2026-09-16 21:46:29.539563 | instance |     "gid": 1000,
2026-09-16 21:46:29.539579 | instance |     "gr_name": "zuul",
2026-09-16 21:46:29.539594 | instance |     "inode": 1049557,
2026-09-16 21:46:29.539610 | instance |     "isblk": false,
2026-09-16 21:46:29.539626 | instance |     "ischr": false,
2026-09-16 21:46:29.539641 | instance |     "isdir": true,
2026-09-16 21:46:29.539656 | instance |     "isfifo": false,
2026-09-16 21:46:29.539672 | instance |     "isgid": false,
2026-09-16 21:46:29.539687 | instance |     "islnk": false,
2026-09-16 21:46:29.539702 | instance |     "isreg": false,
2026-09-16 21:46:29.539717 | instance |     "issock": false,
2026-09-16 21:46:29.539732 | instance |     "isuid": false,
2026-09-16 21:46:29.539748 | instance |     "mimetype": "unknown",
2026-09-16 21:46:29.539763 | instance |     "mode": "0755",
2026-09-16 21:46:29.539778 | instance |     "mtime": 1789595166.555784,
2026-09-16 21:46:29.539793 | instance |     "nlink": 21,
2026-09-16 21:46:29.539808 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-16 21:46:29.539823 | instance |     "pw_name": "zuul",
2026-09-16 21:46:29.539840 | instance |     "readable": true,
2026-09-16 21:46:29.539855 | instance |     "rgrp": true,
2026-09-16 21:46:29.539871 | instance |     "roth": true,
2026-09-16 21:46:29.539886 | instance |     "rusr": true,
2026-09-16 21:46:29.539902 | instance |     "size": 4096,
2026-09-16 21:46:29.539917 | instance |     "uid": 1000,
2026-09-16 21:46:29.539933 | instance |     "version": "584532814",
2026-09-16 21:46:29.539948 | instance |     "wgrp": false,
2026-09-16 21:46:29.539966 | instance |     "woth": false,
2026-09-16 21:46:29.539982 | instance |     "writeable": true,
2026-09-16 21:46:29.539997 | instance |     "wusr": true,
2026-09-16 21:46:29.540013 | instance |     "xgrp": true,
2026-09-16 21:46:29.540028 | instance |     "xoth": true,
2026-09-16 21:46:29.540043 | instance |     "xusr": true
2026-09-16 21:46:29.540059 | instance |   }
2026-09-16 21:46:29.540094 | instance | }
2026-09-16 21:46:29.540134 | instance | ok: All items complete
2026-09-16 21:46:29.540154 | 
2026-09-16 21:46:29.542592 | instance | skipping: Conditional result was False
2026-09-16 21:46:29.550112 | instance | skipping: Conditional result was False
2026-09-16 21:46:29.574923 | 
2026-09-16 21:46:29.575178 | LOOP [Switch "ansible_host" to private IP]
2026-09-16 21:46:29.963228 | instance | changed: 1 replacements made
2026-09-16 21:46:29.966268 | instance | skipping: Conditional result was False
2026-09-16 21:46:29.972419 | instance | skipping: Conditional result was False
2026-09-16 21:46:29.993167 | 
2026-09-16 21:46:29.993296 | TASK [Run Molecule scenario]
2026-09-16 21:46:30.984938 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-16 21:46:31.451276 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-16 21:46:31.451366 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-16 21:46:42.170185 | instance | INFO     [csi > dependency] Executing
2026-09-16 21:46:42.170352 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-16 21:46:42.170368 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-16 21:46:42.170484 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-16 21:46:42.185088 | instance | INFO     [csi > cleanup] Executing
2026-09-16 21:46:42.185783 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-16 21:46:42.200114 | instance | INFO     [csi > destroy] Executing
2026-09-16 21:46:42.200186 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-16 21:46:42.200330 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-16 21:46:42.209203 | instance | INFO     [csi > syntax] Executing
2026-09-16 21:46:43.661185 | instance |
2026-09-16 21:46:43.661455 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-16 21:46:43.722997 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-16 21:46:43.739618 | instance | INFO     [csi > create] Executing
2026-09-16 21:46:43.745851 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-16 21:46:43.761569 | instance | INFO     [csi > prepare] Executing
2026-09-16 21:46:44.523495 | instance |
2026-09-16 21:46:44.523742 | instance | PLAY [Prepare] *****************************************************************
2026-09-16 21:46:44.523911 | instance |
2026-09-16 21:46:44.524085 | instance | TASK [Gathering Facts] *********************************************************
2026-09-16 21:46:44.524276 | instance | Wednesday 16 September 2026  21:46:44 +0000 (0:00:00.031)       0:00:00.031 ***
2026-09-16 21:46:45.846785 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-16 21:46:45.846848 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-16 21:46:45.846862 | instance | interpreter could change the meaning of that path. See
2026-09-16 21:46:45.846870 | instance | https://docs.ansible.com/ansible-
2026-09-16 21:46:45.846878 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-16 21:46:45.855095 | instance | ok: [instance]
2026-09-16 21:46:45.855142 | instance |
2026-09-16 21:46:45.855152 | instance | TASK [Configure short hostname] ************************************************
2026-09-16 21:46:45.855161 | instance | Wednesday 16 September 2026  21:46:45 +0000 (0:00:01.331)       0:00:01.363 ***
2026-09-16 21:46:46.665205 | instance | changed: [instance]
2026-09-16 21:46:46.665346 | instance |
2026-09-16 21:46:46.665364 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-16 21:46:46.665380 | instance | Wednesday 16 September 2026  21:46:46 +0000 (0:00:00.808)       0:00:02.172 ***
2026-09-16 21:46:46.977247 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-16 21:46:46.977721 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-16 21:46:46.978078 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-16 21:46:46.986210 | instance | changed: [instance]
2026-09-16 21:46:46.986569 | instance |
2026-09-16 21:46:46.986883 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-16 21:46:46.987189 | instance | Wednesday 16 September 2026  21:46:46 +0000 (0:00:00.322)       0:00:02.495 ***
2026-09-16 21:46:47.751829 | instance | ok: [instance]
2026-09-16 21:46:47.752225 | instance |
2026-09-16 21:46:47.752581 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-16 21:46:47.752880 | instance |
2026-09-16 21:46:47.753220 | instance | TASK [Gathering Facts] *********************************************************
2026-09-16 21:46:47.753608 | instance | Wednesday 16 September 2026  21:46:47 +0000 (0:00:00.765)       0:00:03.260 ***
2026-09-16 21:46:48.504001 | instance | ok: [instance]
2026-09-16 21:46:48.504405 | instance |
2026-09-16 21:46:48.504742 | instance | TASK [Install depedencies] *****************************************************
2026-09-16 21:46:48.505045 | instance | Wednesday 16 September 2026  21:46:48 +0000 (0:00:00.752)       0:00:04.012 ***
2026-09-16 21:47:11.927567 | instance | changed: [instance]
2026-09-16 21:47:11.927629 | instance |
2026-09-16 21:47:11.927641 | instance | TASK [Start up service] ********************************************************
2026-09-16 21:47:11.927652 | instance | Wednesday 16 September 2026  21:47:11 +0000 (0:00:23.422)       0:00:27.435 ***
2026-09-16 21:47:12.467212 | instance | ok: [instance]
2026-09-16 21:47:12.467240 | instance |
2026-09-16 21:47:12.467247 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-16 21:47:12.467252 | instance | Wednesday 16 September 2026  21:47:12 +0000 (0:00:00.541)       0:00:27.976 ***
2026-09-16 21:47:12.751983 | instance | ok: [instance]
2026-09-16 21:47:12.752041 | instance |
2026-09-16 21:47:12.752057 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-16 21:47:12.752071 | instance | Wednesday 16 September 2026  21:47:12 +0000 (0:00:00.283)       0:00:28.259 ***
2026-09-16 21:47:13.445992 | instance | changed: [instance]
2026-09-16 21:47:13.446045 | instance |
2026-09-16 21:47:13.446057 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-16 21:47:13.446068 | instance | Wednesday 16 September 2026  21:47:13 +0000 (0:00:00.693)       0:00:28.953 ***
2026-09-16 21:47:13.655517 | instance | ok: [instance]
2026-09-16 21:47:13.655785 | instance |
2026-09-16 21:47:13.656179 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-16 21:47:13.656517 | instance | Wednesday 16 September 2026  21:47:13 +0000 (0:00:00.210)       0:00:29.164 ***
2026-09-16 21:47:13.679283 | instance | skipping: [instance]
2026-09-16 21:47:13.679415 | instance |
2026-09-16 21:47:13.679648 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-16 21:47:13.679851 | instance | Wednesday 16 September 2026  21:47:13 +0000 (0:00:00.023)       0:00:29.188 ***
2026-09-16 21:47:14.250963 | instance | changed: [instance] => (item=osd0)
2026-09-16 21:47:14.251210 | instance | changed: [instance] => (item=osd1)
2026-09-16 21:47:14.251534 | instance | changed: [instance] => (item=osd2)
2026-09-16 21:47:14.251826 | instance |
2026-09-16 21:47:14.252151 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-16 21:47:14.252456 | instance | Wednesday 16 September 2026  21:47:14 +0000 (0:00:00.571)       0:00:29.759 ***
2026-09-16 21:47:14.925221 | instance | changed: [instance] => (item=osd0)
2026-09-16 21:47:14.925636 | instance | changed: [instance] => (item=osd1)
2026-09-16 21:47:14.925938 | instance | changed: [instance] => (item=osd2)
2026-09-16 21:47:14.926235 | instance |
2026-09-16 21:47:14.926527 | instance | TASK [Start loop devices] ******************************************************
2026-09-16 21:47:14.926816 | instance | Wednesday 16 September 2026  21:47:14 +0000 (0:00:00.670)       0:00:30.430 ***
2026-09-16 21:47:15.701228 | instance | changed: [instance] => (item=osd0)
2026-09-16 21:47:15.701280 | instance | changed: [instance] => (item=osd1)
2026-09-16 21:47:15.701296 | instance | changed: [instance] => (item=osd2)
2026-09-16 21:47:15.701509 | instance |
2026-09-16 21:47:15.701828 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-16 21:47:15.702234 | instance | Wednesday 16 September 2026  21:47:15 +0000 (0:00:00.778)       0:00:31.209 ***
2026-09-16 21:47:19.030670 | instance | changed: [instance] => (item=osd0)
2026-09-16 21:47:19.030913 | instance | changed: [instance] => (item=osd1)
2026-09-16 21:47:19.031185 | instance | changed: [instance] => (item=osd2)
2026-09-16 21:47:19.031492 | instance |
2026-09-16 21:47:19.031808 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-16 21:47:19.032097 | instance | Wednesday 16 September 2026  21:47:19 +0000 (0:00:03.329)       0:00:34.539 ***
2026-09-16 21:47:21.025023 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-16 21:47:21.025098 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-16 21:47:21.025124 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-16 21:47:21.025133 | instance |
2026-09-16 21:47:21.025169 | instance | PLAY RECAP *********************************************************************
2026-09-16 21:47:21.025185 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-16 21:47:21.029219 | instance |
2026-09-16 21:47:21.029255 | instance | Wednesday 16 September 2026  21:47:21 +0000 (0:00:01.993)       0:00:36.533 ***
2026-09-16 21:47:21.029266 | instance | ===============================================================================
2026-09-16 21:47:21.029274 | instance | Install depedencies ---------------------------------------------------- 23.42s
2026-09-16 21:47:21.029283 | instance | Create a volume group for each loop device ------------------------------ 3.33s
2026-09-16 21:47:21.029291 | instance | Create a logical volume for each loop device ---------------------------- 1.99s
2026-09-16 21:47:21.029301 | instance | Gathering Facts --------------------------------------------------------- 1.33s
2026-09-16 21:47:21.029309 | instance | Configure short hostname ------------------------------------------------ 0.81s
2026-09-16 21:47:21.029318 | instance | Start loop devices ------------------------------------------------------ 0.78s
2026-09-16 21:47:21.029326 | instance | Purge "snapd" package --------------------------------------------------- 0.77s
2026-09-16 21:47:21.029335 | instance | Gathering Facts --------------------------------------------------------- 0.75s
2026-09-16 21:47:21.029343 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.69s
2026-09-16 21:47:21.029352 | instance | Set permissions on loopback devices ------------------------------------- 0.67s
2026-09-16 21:47:21.029360 | instance | Create devices for Ceph ------------------------------------------------- 0.57s
2026-09-16 21:47:21.029369 | instance | Start up service -------------------------------------------------------- 0.54s
2026-09-16 21:47:21.029377 | instance | Ensure hostname inside hosts file --------------------------------------- 0.32s
2026-09-16 21:47:21.029386 | instance | Generate lvm.conf ------------------------------------------------------- 0.28s
2026-09-16 21:47:21.029580 | instance | Get list of all loopback devices ---------------------------------------- 0.21s
2026-09-16 21:47:21.030084 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s
2026-09-16 21:47:21.154123 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-16 21:47:21.170567 | instance | INFO     [csi > converge] Executing
2026-09-16 21:47:22.648842 | instance |
2026-09-16 21:47:22.649350 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-16 21:47:22.649720 | instance |
2026-09-16 21:47:22.650172 | instance | TASK [Gathering Facts] *********************************************************
2026-09-16 21:47:22.650565 | instance | Wednesday 16 September 2026  21:47:22 +0000 (0:00:00.007)       0:00:00.007 ***
2026-09-16 21:47:23.668468 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-16 21:47:23.668523 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-16 21:47:23.668535 | instance | interpreter could change the meaning of that path. See
2026-09-16 21:47:23.668544 | instance | https://docs.ansible.com/ansible-
2026-09-16 21:47:23.668553 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-16 21:47:23.683301 | instance | ok: [instance]
2026-09-16 21:47:23.683334 | instance |
2026-09-16 21:47:23.683346 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-16 21:47:23.683356 | instance | Wednesday 16 September 2026  21:47:23 +0000 (0:00:01.033)       0:00:01.040 ***
2026-09-16 21:47:23.752867 | instance | ok: [instance] => {
2026-09-16 21:47:23.752906 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-16 21:47:23.752922 | instance | }
2026-09-16 21:47:23.752934 | instance |
2026-09-16 21:47:23.752947 | instance | PLAY [all] *********************************************************************
2026-09-16 21:47:23.752959 | instance |
2026-09-16 21:47:23.752970 | instance | TASK [Gathering Facts] *********************************************************
2026-09-16 21:47:23.753002 | instance | Wednesday 16 September 2026  21:47:23 +0000 (0:00:00.068)       0:00:01.109 ***
2026-09-16 21:47:24.727583 | instance | ok: [instance]
2026-09-16 21:47:24.727801 | instance |
2026-09-16 21:47:24.727986 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-16 21:47:24.728194 | instance | Wednesday 16 September 2026  21:47:24 +0000 (0:00:00.976)       0:00:02.085 ***
2026-09-16 21:47:24.894062 | instance | ok: [instance]
2026-09-16 21:47:24.894124 | instance |
2026-09-16 21:47:24.894253 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-16 21:47:24.894413 | instance |
2026-09-16 21:47:24.894548 | instance | TASK [Gathering Facts] *********************************************************
2026-09-16 21:47:24.894680 | instance | Wednesday 16 September 2026  21:47:24 +0000 (0:00:00.167)       0:00:02.253 ***
2026-09-16 21:47:25.863539 | instance | ok: [instance]
2026-09-16 21:47:25.863602 | instance |
2026-09-16 21:47:25.863613 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-16 21:47:25.863632 | instance | Wednesday 16 September 2026  21:47:25 +0000 (0:00:00.967)       0:00:03.221 ***
2026-09-16 21:47:26.194360 | instance | ok: [instance]
2026-09-16 21:47:26.194472 | instance |
2026-09-16 21:47:26.194782 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-16 21:47:26.194842 | instance | Wednesday 16 September 2026  21:47:26 +0000 (0:00:00.331)       0:00:03.552 ***
2026-09-16 21:47:26.252813 | instance | skipping: [instance]
2026-09-16 21:47:26.252914 | instance |
2026-09-16 21:47:26.253410 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-16 21:47:26.253521 | instance | Wednesday 16 September 2026  21:47:26 +0000 (0:00:00.058)       0:00:03.611 ***
2026-09-16 21:47:26.571874 | instance | changed: [instance]
2026-09-16 21:47:26.572165 | instance |
2026-09-16 21:47:26.572182 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-16 21:47:26.572486 | instance | Wednesday 16 September 2026  21:47:26 +0000 (0:00:00.319)       0:00:03.930 ***
2026-09-16 21:47:26.660142 | instance | ok: [instance] => {
2026-09-16 21:47:26.660304 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-16 21:47:26.661302 | instance | }
2026-09-16 21:47:26.661403 | instance |
2026-09-16 21:47:26.661410 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-16 21:47:26.661417 | instance | Wednesday 16 September 2026  21:47:26 +0000 (0:00:00.087)       0:00:04.017 ***
2026-09-16 21:47:27.396580 | instance | changed: [instance]
2026-09-16 21:47:27.396695 | instance |
2026-09-16 21:47:27.396854 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-16 21:47:27.397125 | instance | Wednesday 16 September 2026  21:47:27 +0000 (0:00:00.737)       0:00:04.755 ***
2026-09-16 21:47:27.467131 | instance | skipping: [instance]
2026-09-16 21:47:27.467261 | instance |
2026-09-16 21:47:27.467488 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-16 21:47:27.467758 | instance | Wednesday 16 September 2026  21:47:27 +0000 (0:00:00.070)       0:00:04.825 ***
2026-09-16 21:47:27.533778 | instance | skipping: [instance]
2026-09-16 21:47:27.533895 | instance |
2026-09-16 21:47:27.534003 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-16 21:47:27.534231 | instance | Wednesday 16 September 2026  21:47:27 +0000 (0:00:00.066)       0:00:04.892 ***
2026-09-16 21:47:27.785944 | instance | ok: [instance]
2026-09-16 21:47:27.786118 | instance |
2026-09-16 21:47:27.786394 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-16 21:47:27.786622 | instance | Wednesday 16 September 2026  21:47:27 +0000 (0:00:00.249)       0:00:05.141 ***
2026-09-16 21:47:29.210206 | instance | ok: [instance]
2026-09-16 21:47:29.210472 | instance |
2026-09-16 21:47:29.210669 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-16 21:47:29.210859 | instance | Wednesday 16 September 2026  21:47:29 +0000 (0:00:01.423)       0:00:06.565 ***
2026-09-16 21:47:29.278148 | instance | ok: [instance] => {
2026-09-16 21:47:29.278391 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-16 21:47:29.278825 | instance | }
2026-09-16 21:47:29.279162 | instance |
2026-09-16 21:47:29.279482 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-16 21:47:29.279832 | instance | Wednesday 16 September 2026  21:47:29 +0000 (0:00:00.070)       0:00:06.635 ***
2026-09-16 21:47:29.981684 | instance | changed: [instance]
2026-09-16 21:47:29.982105 | instance |
2026-09-16 21:47:29.982407 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-16 21:47:29.982695 | instance | Wednesday 16 September 2026  21:47:29 +0000 (0:00:00.700)       0:00:07.336 ***
2026-09-16 21:47:32.967735 | instance | changed: [instance]
2026-09-16 21:47:32.968001 | instance |
2026-09-16 21:47:32.968022 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-16 21:47:32.968033 | instance | Wednesday 16 September 2026  21:47:32 +0000 (0:00:02.988)       0:00:10.325 ***
2026-09-16 21:47:33.008362 | instance | skipping: [instance]
2026-09-16 21:47:33.008447 | instance |
2026-09-16 21:47:33.008458 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-16 21:47:33.008468 | instance | Wednesday 16 September 2026  21:47:32 +0000 (0:00:00.039)       0:00:10.365 ***
2026-09-16 21:47:33.042583 | instance | skipping: [instance]
2026-09-16 21:47:33.042660 | instance |
2026-09-16 21:47:33.042669 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-16 21:47:33.042676 | instance | Wednesday 16 September 2026  21:47:33 +0000 (0:00:00.035)       0:00:10.400 ***
2026-09-16 21:47:33.080210 | instance | skipping: [instance]
2026-09-16 21:47:33.080433 | instance |
2026-09-16 21:47:33.080585 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-16 21:47:33.080729 | instance | Wednesday 16 September 2026  21:47:33 +0000 (0:00:00.038)       0:00:10.438 ***
2026-09-16 21:47:38.861248 | instance | changed: [instance]
2026-09-16 21:47:38.861306 | instance |
2026-09-16 21:47:38.861317 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-16 21:47:38.861332 | instance | Wednesday 16 September 2026  21:47:38 +0000 (0:00:05.780)       0:00:16.218 ***
2026-09-16 21:47:39.503950 | instance | changed: [instance]
2026-09-16 21:47:39.504680 | instance |
2026-09-16 21:47:39.504712 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-16 21:47:39.504723 | instance | Wednesday 16 September 2026  21:47:39 +0000 (0:00:00.643)       0:00:16.862 ***
2026-09-16 21:47:40.515487 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-16 21:47:40.517221 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-16 21:47:40.517265 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-16 21:47:40.517272 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-16 21:47:40.517278 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-16 21:47:40.517284 | instance |
2026-09-16 21:47:40.517298 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-16 21:47:40.517384 | instance | Wednesday 16 September 2026  21:47:40 +0000 (0:00:01.011)       0:00:17.873 ***
2026-09-16 21:47:41.143323 | instance | changed: [instance]
2026-09-16 21:47:41.143419 | instance |
2026-09-16 21:47:41.144188 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-16 21:47:41.144239 | instance | Wednesday 16 September 2026  21:47:41 +0000 (0:00:00.600)       0:00:18.474 ***
2026-09-16 21:47:41.144247 | instance |
2026-09-16 21:47:41.144254 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-16 21:47:41.144260 | instance | Wednesday 16 September 2026  21:47:41 +0000 (0:00:00.027)       0:00:18.502 ***
2026-09-16 21:47:42.050675 | instance | ok: [instance]
2026-09-16 21:47:42.050750 | instance |
2026-09-16 21:47:42.050999 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-16 21:47:42.051031 | instance | Wednesday 16 September 2026  21:47:42 +0000 (0:00:00.907)       0:00:19.409 ***
2026-09-16 21:47:42.512846 | instance | changed: [instance]
2026-09-16 21:47:42.512952 | instance |
2026-09-16 21:47:42.513014 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-16 21:47:42.513155 | instance | Wednesday 16 September 2026  21:47:42 +0000 (0:00:00.462)       0:00:19.872 ***
2026-09-16 21:47:43.112585 | instance | changed: [instance]
2026-09-16 21:47:43.113116 | instance |
2026-09-16 21:47:43.113185 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-16 21:47:43.113198 | instance | Wednesday 16 September 2026  21:47:43 +0000 (0:00:00.599)       0:00:20.471 ***
2026-09-16 21:47:43.317282 | instance | ok: [instance]
2026-09-16 21:47:43.317341 | instance |
2026-09-16 21:47:43.317587 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-16 21:47:43.317630 | instance | Wednesday 16 September 2026  21:47:43 +0000 (0:00:00.204)       0:00:20.676 ***
2026-09-16 21:47:43.356534 | instance | ok: [instance] => {
2026-09-16 21:47:43.356631 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-16 21:47:43.357061 | instance | }
2026-09-16 21:47:43.357075 | instance |
2026-09-16 21:47:43.357081 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-16 21:47:43.357085 | instance | Wednesday 16 September 2026  21:47:43 +0000 (0:00:00.039)       0:00:20.715 ***
2026-09-16 21:47:54.762782 | instance | FAILED - RETRYING: [instance]: Download item (4 retries left).
2026-09-16 21:47:54.762898 | instance | changed: [instance]
2026-09-16 21:47:54.763361 | instance |
2026-09-16 21:47:54.763431 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-16 21:47:54.763440 | instance | Wednesday 16 September 2026  21:47:54 +0000 (0:00:11.405)       0:00:32.121 ***
2026-09-16 21:47:59.223731 | instance | changed: [instance]
2026-09-16 21:47:59.223879 | instance |
2026-09-16 21:47:59.223962 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-16 21:47:59.224159 | instance | Wednesday 16 September 2026  21:47:59 +0000 (0:00:04.460)       0:00:36.582 ***
2026-09-16 21:48:00.674932 | instance | ok: [instance]
2026-09-16 21:48:00.675309 | instance |
2026-09-16 21:48:00.675463 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-16 21:48:00.675471 | instance | Wednesday 16 September 2026  21:48:00 +0000 (0:00:01.451)       0:00:38.033 ***
2026-09-16 21:48:01.026092 | instance | changed: [instance]
2026-09-16 21:48:01.026181 | instance |
2026-09-16 21:48:01.026200 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-16 21:48:01.026344 | instance | Wednesday 16 September 2026  21:48:01 +0000 (0:00:00.351)       0:00:38.385 ***
2026-09-16 21:48:01.514324 | instance | changed: [instance]
2026-09-16 21:48:01.515117 | instance |
2026-09-16 21:48:01.515171 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-16 21:48:01.515184 | instance | Wednesday 16 September 2026  21:48:01 +0000 (0:00:00.487)       0:00:38.872 ***
2026-09-16 21:48:02.132143 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-16 21:48:02.132209 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-16 21:48:02.132646 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-16 21:48:02.132683 | instance |
2026-09-16 21:48:02.132689 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-16 21:48:02.132693 | instance | Wednesday 16 September 2026  21:48:02 +0000 (0:00:00.618)       0:00:39.490 ***
2026-09-16 21:48:02.598736 | instance | changed: [instance]
2026-09-16 21:48:02.598853 | instance |
2026-09-16 21:48:02.599257 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-16 21:48:02.599327 | instance | Wednesday 16 September 2026  21:48:02 +0000 (0:00:00.466)       0:00:39.957 ***
2026-09-16 21:48:03.113506 | instance | changed: [instance]
2026-09-16 21:48:03.113634 | instance |
2026-09-16 21:48:03.114382 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-16 21:48:03.114442 | instance | Wednesday 16 September 2026  21:48:03 +0000 (0:00:00.484)       0:00:40.441 ***
2026-09-16 21:48:03.114450 | instance |
2026-09-16 21:48:03.114456 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-16 21:48:03.114474 | instance | Wednesday 16 September 2026  21:48:03 +0000 (0:00:00.031)       0:00:40.472 ***
2026-09-16 21:48:03.889545 | instance | ok: [instance]
2026-09-16 21:48:03.889614 | instance |
2026-09-16 21:48:03.889910 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-16 21:48:03.889974 | instance | Wednesday 16 September 2026  21:48:03 +0000 (0:00:00.775)       0:00:41.248 ***
2026-09-16 21:48:04.676001 | instance | changed: [instance]
2026-09-16 21:48:04.676100 | instance |
2026-09-16 21:48:04.676396 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-16 21:48:04.676447 | instance | Wednesday 16 September 2026  21:48:04 +0000 (0:00:00.786)       0:00:42.034 ***
2026-09-16 21:48:05.281819 | instance | changed: [instance]
2026-09-16 21:48:05.282258 | instance |
2026-09-16 21:48:05.282278 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-16 21:48:05.282286 | instance | Wednesday 16 September 2026  21:48:05 +0000 (0:00:00.605)       0:00:42.640 ***
2026-09-16 21:48:05.358691 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-16 21:48:05.359435 | instance |
2026-09-16 21:48:05.359466 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-16 21:48:05.359478 | instance | Wednesday 16 September 2026  21:48:05 +0000 (0:00:00.076)       0:00:42.717 ***
2026-09-16 21:48:11.057109 | instance | changed: [instance]
2026-09-16 21:48:11.057477 | instance |
2026-09-16 21:48:11.057951 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-16 21:48:11.058008 | instance | Wednesday 16 September 2026  21:48:11 +0000 (0:00:05.698)       0:00:48.415 ***
2026-09-16 21:48:11.754896 | instance | ok: [instance] => (item=chronyd)
2026-09-16 21:48:11.755005 | instance | ok: [instance] => (item=sshd)
2026-09-16 21:48:11.755018 | instance |
2026-09-16 21:48:11.755276 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-16 21:48:11.755327 | instance | Wednesday 16 September 2026  21:48:11 +0000 (0:00:00.698)       0:00:49.113 ***
2026-09-16 21:48:16.039443 | instance | changed: [instance]
2026-09-16 21:48:16.040161 | instance |
2026-09-16 21:48:16.040216 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-09-16 21:48:16.040229 | instance | Wednesday 16 September 2026  21:48:16 +0000 (0:00:04.283)       0:00:53.397 ***
2026-09-16 21:48:16.282174 | instance | ok: [instance]
2026-09-16 21:48:16.282987 | instance |
2026-09-16 21:48:16.283280 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-09-16 21:48:16.283297 | instance | Wednesday 16 September 2026  21:48:16 +0000 (0:00:00.242)       0:00:53.639 ***
2026-09-16 21:48:16.810917 | instance | changed: [instance]
2026-09-16 21:48:16.811022 | instance |
2026-09-16 21:48:16.811325 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-09-16 21:48:16.811394 | instance | Wednesday 16 September 2026  21:48:16 +0000 (0:00:00.529)       0:00:54.169 ***
2026-09-16 21:48:17.236628 | instance | changed: [instance]
2026-09-16 21:48:17.236705 | instance |
2026-09-16 21:48:17.237084 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-09-16 21:48:17.237176 | instance | Wednesday 16 September 2026  21:48:17 +0000 (0:00:00.425)       0:00:54.594 ***
2026-09-16 21:48:17.374263 | instance | ok: [instance]
2026-09-16 21:48:17.375015 | instance |
2026-09-16 21:48:17.375084 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-09-16 21:48:17.375097 | instance | Wednesday 16 September 2026  21:48:17 +0000 (0:00:00.137)       0:00:54.732 ***
2026-09-16 21:48:17.637302 | instance | ok: [instance] => (item=instance)
2026-09-16 21:48:17.637417 | instance |
2026-09-16 21:48:17.637687 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-09-16 21:48:17.637761 | instance | Wednesday 16 September 2026  21:48:17 +0000 (0:00:00.263)       0:00:54.995 ***
2026-09-16 21:48:17.715464 | instance | ok: [instance]
2026-09-16 21:48:17.715633 | instance |
2026-09-16 21:48:17.716219 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-09-16 21:48:17.716302 | instance | Wednesday 16 September 2026  21:48:17 +0000 (0:00:00.077)       0:00:55.073 ***
2026-09-16 21:48:17.799273 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-09-16 21:48:17.799355 | instance |
2026-09-16 21:48:17.799590 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-09-16 21:48:17.799628 | instance | Wednesday 16 September 2026  21:48:17 +0000 (0:00:00.084)       0:00:55.158 ***
2026-09-16 21:48:18.146906 | instance | changed: [instance]
2026-09-16 21:48:18.147733 | instance |
2026-09-16 21:48:18.147808 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-09-16 21:48:18.147820 | instance | Wednesday 16 September 2026  21:48:18 +0000 (0:00:00.346)       0:00:55.504 ***
2026-09-16 21:48:18.955201 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-09-16 21:48:18.955328 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-09-16 21:48:18.955341 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-09-16 21:48:18.955724 | instance |
2026-09-16 21:48:18.955790 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-09-16 21:48:18.955797 | instance | Wednesday 16 September 2026  21:48:18 +0000 (0:00:00.809)       0:00:56.313 ***
2026-09-16 21:49:53.400894 | instance | ok: [instance]
2026-09-16 21:49:53.401251 | instance |
2026-09-16 21:49:53.402067 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-09-16 21:49:53.402195 | instance | Wednesday 16 September 2026  21:49:53 +0000 (0:01:34.444)       0:02:30.758 ***
2026-09-16 21:49:53.640568 | instance | changed: [instance]
2026-09-16 21:49:53.640808 | instance |
2026-09-16 21:49:53.641068 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-09-16 21:49:53.641373 | instance | Wednesday 16 September 2026  21:49:53 +0000 (0:00:00.240)       0:02:30.998 ***
2026-09-16 21:49:53.686647 | instance | ok: [instance]
2026-09-16 21:49:53.686878 | instance |
2026-09-16 21:49:53.687119 | instance | TASK [Install Ceph host] *******************************************************
2026-09-16 21:49:53.687361 | instance | Wednesday 16 September 2026  21:49:53 +0000 (0:00:00.046)       0:02:31.045 ***
2026-09-16 21:49:53.763330 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-09-16 21:49:53.763549 | instance |
2026-09-16 21:49:53.763796 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-09-16 21:49:53.764058 | instance | Wednesday 16 September 2026  21:49:53 +0000 (0:00:00.076)       0:02:31.121 ***
2026-09-16 21:49:55.568886 | instance | ok: [instance]
2026-09-16 21:49:55.569169 | instance |
2026-09-16 21:49:55.569508 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-09-16 21:49:55.569894 | instance | Wednesday 16 September 2026  21:49:55 +0000 (0:00:01.805)       0:02:32.927 ***
2026-09-16 21:49:55.641390 | instance | ok: [instance] => (item=instance)
2026-09-16 21:49:55.641658 | instance |
2026-09-16 21:49:55.642038 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-09-16 21:49:55.642343 | instance | Wednesday 16 September 2026  21:49:55 +0000 (0:00:00.072)       0:02:33.000 ***
2026-09-16 21:49:56.029611 | instance | ok: [instance]
2026-09-16 21:49:56.029890 | instance |
2026-09-16 21:49:56.030196 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-09-16 21:49:56.030456 | instance | Wednesday 16 September 2026  21:49:56 +0000 (0:00:00.387)       0:02:33.387 ***
2026-09-16 21:49:58.557038 | instance | ok: [instance]
2026-09-16 21:49:58.557104 | instance |
2026-09-16 21:49:58.557116 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-09-16 21:49:58.557125 | instance | Wednesday 16 September 2026  21:49:58 +0000 (0:00:02.526)       0:02:35.914 ***
2026-09-16 21:50:49.337531 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster)
2026-09-16 21:50:49.338578 | 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_6yhxy_p0/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_6yhxy_p0/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_6yhxy_p0/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_6yhxy_p0/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 120, in apply_spec\nRuntimeError: Inferring config /var/lib/ceph/4837cbf8-4f90-4300-b3f6-726c9b9f89b4/mon.instance/config\n2026-09-16T21:50:49.172+0000 7f6c159c9640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDVDqtq8QXYACAAfQ1Pd+hXxRYUxvOMyl4uCejMSuwZtGbje8QsdDlHHfc=: Malformed input [buffer:3]\n2026-09-16T21:50:49.172+0000 7f6c159c9640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-16T21:50:49.172+0000 7f6c159c9640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDVDqtq8QXYACAAfQ1Pd+hXxRYUxvOMyl4uCejMSuwZtGbje8QsdDlHHfc=: Malformed input [buffer:3]\n2026-09-16T21:50:49.172+0000 7f6c159c9640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-16T21:50:49.172+0000 7f6c159c9640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDVDqtq8QXYACAAfQ1Pd+hXxRYUxvOMyl4uCejMSuwZtGbje8QsdDlHHfc=: Malformed input [buffer:3]\n2026-09-16T21:50:49.172+0000 7f6c159c9640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-16T21:50:49.172+0000 7f6c159c9640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1}
2026-09-16 21:50:49.341395 | instance |
2026-09-16 21:50:49.345224 | instance | PLAY RECAP *********************************************************************
2026-09-16 21:50:49.345281 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2026-09-16 21:50:49.345292 | instance |
2026-09-16 21:50:49.345302 | instance | Wednesday 16 September 2026  21:50:49 +0000 (0:00:50.786)       0:03:26.700 ***
2026-09-16 21:50:49.345311 | instance | ===============================================================================
2026-09-16 21:50:49.345320 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 94.44s
2026-09-16 21:50:49.345328 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 50.79s
2026-09-16 21:50:49.345337 | instance | vexxhost.containers.download_artifact : Download item ------------------ 11.41s
2026-09-16 21:50:49.345345 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.78s
2026-09-16 21:50:49.345354 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.70s
2026-09-16 21:50:49.345362 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.46s
2026-09-16 21:50:49.345371 | instance | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 4.28s
2026-09-16 21:50:49.345396 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.99s
2026-09-16 21:50:49.345405 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.53s
2026-09-16 21:50:49.345413 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.81s
2026-09-16 21:50:49.345422 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.68s
2026-09-16 21:50:49.345436 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.45s
2026-09-16 21:50:49.345445 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.42s
2026-09-16 21:50:49.345678 | instance | Gathering Facts --------------------------------------------------------- 1.03s
2026-09-16 21:50:49.345978 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.01s
2026-09-16 21:50:49.346254 | instance | Gathering Facts --------------------------------------------------------- 0.98s
2026-09-16 21:50:49.346550 | instance | Gathering Facts --------------------------------------------------------- 0.97s
2026-09-16 21:50:49.346830 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.81s
2026-09-16 21:50:49.347114 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.79s
2026-09-16 21:50:49.347393 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.74s
2026-09-16 21:50:49.450347 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-16 21:50:49.450409 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-16 21:50:49.450506 | instance | ERROR    Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-16 21:50:49.884280 | instance | ERROR
2026-09-16 21:50:49.884663 | instance | {
2026-09-16 21:50:49.884733 | instance |   "delta": "0:04:19.138440",
2026-09-16 21:50:49.884794 | instance |   "end": "2026-09-16 21:50:49.505229",
2026-09-16 21:50:49.884854 | instance |   "msg": "non-zero return code",
2026-09-16 21:50:49.884910 | instance |   "rc": 2,
2026-09-16 21:50:49.884961 | instance |   "start": "2026-09-16 21:46:30.366789"
2026-09-16 21:50:49.885007 | instance | }
failure
2026-09-16 21:50:49.897262 | 
2026-09-16 21:50:49.897437 | PLAY RECAP
2026-09-16 21:50:49.897529 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-16 21:50:49.897572 | 
2026-09-16 21:50:50.080112 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-16 21:50:50.082474 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-16 21:50:50.701154 | 
2026-09-16 21:50:50.701289 | PLAY [all]
2026-09-16 21:50:50.714246 | 
2026-09-16 21:50:50.714337 | TASK [gather-host-logs : creating directory for system status]
2026-09-16 21:50:51.090955 | instance | changed
2026-09-16 21:50:51.100782 | 
2026-09-16 21:50:51.100937 | TASK [gather-host-logs : Get logs for each host]
2026-09-16 21:50:51.509208 | instance | + systemd-cgls --full --all --no-pager
2026-09-16 21:50:51.526415 | instance | + ip addr
2026-09-16 21:50:51.529210 | instance | + ip route
2026-09-16 21:50:51.531984 | instance | + lsblk
2026-09-16 21:50:51.536928 | instance | + mount
2026-09-16 21:50:51.540078 | instance | + docker images
2026-09-16 21:50:51.566264 | instance | + brctl show
2026-09-16 21:50:51.566967 | instance | /bin/bash: line 8: brctl: command not found
2026-09-16 21:50:51.567246 | instance | + ip neigh
2026-09-16 21:50:51.570354 | instance | + bridge fdb show
2026-09-16 21:50:51.578428 | instance | + ps aux --sort=-%mem
2026-09-16 21:50:51.598941 | instance | + dpkg -l
2026-09-16 21:50:51.611861 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-16 21:50:51.612519 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-16 21:50:51.634629 | instance | + '[' '!' -z '' ']'
2026-09-16 21:50:51.692221 | instance | ok: Runtime: 0:00:00.131346
2026-09-16 21:50:51.702931 | 
2026-09-16 21:50:51.703078 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-16 21:50:52.407454 | instance | changed:
2026-09-16 21:50:52.407753 | instance | created directory /var/lib/zuul/builds/5297627213494c45854380c5d423319c/work/logs/instance
2026-09-16 21:50:52.407836 | instance | cd+++++++++ system/
2026-09-16 21:50:52.407897 | instance | >f+++++++++ system/brctl-show.txt
2026-09-16 21:50:52.407942 | instance | >f+++++++++ system/bridge-fdb.txt
2026-09-16 21:50:52.407979 | instance | >f+++++++++ system/docker-images.txt
2026-09-16 21:50:52.408033 | instance | >f+++++++++ system/ip-addr.txt
2026-09-16 21:50:52.408098 | instance | >f+++++++++ system/ip-neigh.txt
2026-09-16 21:50:52.408137 | instance | >f+++++++++ system/ip-route.txt
2026-09-16 21:50:52.408179 | instance | >f+++++++++ system/lsblk.txt
2026-09-16 21:50:52.408217 | instance | >f+++++++++ system/mount.txt
2026-09-16 21:50:52.408254 | instance | >f+++++++++ system/packages.txt
2026-09-16 21:50:52.408289 | instance | >f+++++++++ system/ps.txt
2026-09-16 21:50:52.408325 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-16 21:50:52.427598 | 
2026-09-16 21:50:52.427780 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-16 21:50:52.662236 | instance | changed: "values"
2026-09-16 21:50:52.817174 | instance | changed: "releases"
2026-09-16 21:50:52.838114 | 
2026-09-16 21:50:52.838370 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-16 21:50:53.092821 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-16 21:50:53.393088 | instance | ok: Runtime: 0:00:00.005327
2026-09-16 21:50:53.402360 | 
2026-09-16 21:50:53.402488 | TASK [helm-release-status : Downloads logs to executor]
2026-09-16 21:50:53.934896 | instance | changed:
2026-09-16 21:50:53.935180 | instance | cd+++++++++ helm/
2026-09-16 21:50:53.935234 | instance | cd+++++++++ helm/releases/
2026-09-16 21:50:53.935275 | instance | cd+++++++++ helm/values/
2026-09-16 21:50:53.951896 | 
2026-09-16 21:50:53.952068 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-16 21:50:54.168775 | instance | changed
2026-09-16 21:50:54.179884 | 
2026-09-16 21:50:54.180033 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-16 21:50:54.469216 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-16 21:50:54.469399 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-16 21:50:54.475498 | instance | environment: line 1: kubectl: command not found
2026-09-16 21:50:54.476920 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-16 21:50:54.477568 | instance | environment: line 1: kubectl: command not found
2026-09-16 21:50:54.478626 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-16 21:50:54.480281 | instance | environment: line 1: kubectl: command not found
2026-09-16 21:50:54.481685 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-16 21:50:54.482639 | instance | environment: line 1: kubectl: command not found
2026-09-16 21:50:54.484234 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-16 21:50:54.484428 | instance | environment: line 1: kubectl: command not found
2026-09-16 21:50:54.485431 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-16 21:50:54.726744 | instance | ok: Runtime: 0:00:00.024768
2026-09-16 21:50:54.736206 | 
2026-09-16 21:50:54.736372 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-16 21:50:54.949723 | instance | changed
2026-09-16 21:50:54.955942 | 
2026-09-16 21:50:54.956085 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-16 21:50:55.204271 | instance | environment: line 5: kubectl: command not found
2026-09-16 21:50:55.205548 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-16 21:50:55.205795 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-16 21:50:55.206401 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-16 21:50:55.499823 | instance | ok: Runtime: 0:00:00.010251
2026-09-16 21:50:55.510232 | 
2026-09-16 21:50:55.510419 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-16 21:50:55.999919 | instance | changed:
2026-09-16 21:50:56.000204 | instance | cd+++++++++ objects/
2026-09-16 21:50:56.000288 | instance | cd+++++++++ objects/cluster/
2026-09-16 21:50:56.000339 | instance | cd+++++++++ objects/namespaced/
2026-09-16 21:50:56.019079 | 
2026-09-16 21:50:56.019252 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-16 21:50:56.243005 | instance | changed
2026-09-16 21:50:56.249640 | 
2026-09-16 21:50:56.249744 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-16 21:50:56.468464 | instance | changed
2026-09-16 21:50:56.477851 | 
2026-09-16 21:50:56.477980 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-16 21:50:56.722533 | instance | environment: line 3: kubectl: command not found
2026-09-16 21:50:57.023389 | instance | ok: Runtime: 0:00:00.008208
2026-09-16 21:50:57.029764 | 
2026-09-16 21:50:57.029854 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-16 21:50:57.579161 | instance | changed:
2026-09-16 21:50:57.579431 | instance | cd+++++++++ pod-logs/
2026-09-16 21:50:57.579484 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-16 21:50:57.598106 | 
2026-09-16 21:50:57.598237 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-16 21:50:57.811215 | instance | changed
2026-09-16 21:50:57.817569 | 
2026-09-16 21:50:57.817654 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-16 21:50:58.058276 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-16 21:50:58.356419 | instance | ok: Runtime: 0:00:00.030386
2026-09-16 21:50:58.362971 | 
2026-09-16 21:50:58.363130 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-16 21:50:58.617650 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-16 21:50:58.667240 | instance | ceph-mgr endpoints:
2026-09-16 21:50:58.901970 | instance | ok: Runtime: 0:00:00.055347
2026-09-16 21:50:58.909435 | 
2026-09-16 21:50:58.909540 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-16 21:50:59.161970 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-16 21:50:59.448992 | instance | ok: Runtime: 0:00:00.026657
2026-09-16 21:50:59.455689 | 
2026-09-16 21:50:59.455771 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-16 21:50:59.981379 | instance | changed: cd+++++++++ prometheus/
2026-09-16 21:50:59.999460 | 
2026-09-16 21:50:59.999586 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-16 21:51:00.241136 | instance | changed
2026-09-16 21:51:00.251482 | 
2026-09-16 21:51:00.251652 | TASK [gather-selenium-data : Get selenium data]
2026-09-16 21:51:00.545193 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-16 21:51:00.546637 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-16 21:51:00.795735 | instance | ERROR
2026-09-16 21:51:00.795959 | instance | {
2026-09-16 21:51:00.796016 | instance |   "delta": "0:00:00.005301",
2026-09-16 21:51:00.796072 | instance |   "end": "2026-09-16 21:51:00.546965",
2026-09-16 21:51:00.796109 | instance |   "msg": "non-zero return code",
2026-09-16 21:51:00.796144 | instance |   "rc": 1,
2026-09-16 21:51:00.796178 | instance |   "start": "2026-09-16 21:51:00.541664"
2026-09-16 21:51:00.796212 | instance | }
2026-09-16 21:51:00.796255 | instance | ERROR: Ignoring Errors
2026-09-16 21:51:00.805459 | 
2026-09-16 21:51:00.805574 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-16 21:51:01.341293 | instance | changed: cd+++++++++ selenium/
2026-09-16 21:51:01.352705 | 
2026-09-16 21:51:01.352829 | PLAY RECAP
2026-09-16 21:51:01.352966 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-16 21:51:01.353025 | 
2026-09-16 21:51:01.500609 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-16 21:51:01.502762 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-16 21:51:02.129392 | 
2026-09-16 21:51:02.129552 | PLAY [all]
2026-09-16 21:51:02.142739 | 
2026-09-16 21:51:02.142905 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-16 21:51:02.188090 | instance | skipping: Conditional result was False
2026-09-16 21:51:02.193515 | 
2026-09-16 21:51:02.193613 | TASK [fetch-output : Set log path for single node]
2026-09-16 21:51:02.234397 | instance | ok
2026-09-16 21:51:02.240671 | 
2026-09-16 21:51:02.240789 | LOOP [fetch-output : Ensure local output dirs]
2026-09-16 21:51:02.622520 | instance -> localhost | ok: "/var/lib/zuul/builds/5297627213494c45854380c5d423319c/work/logs"
2026-09-16 21:51:02.840988 | instance -> localhost | changed: "/var/lib/zuul/builds/5297627213494c45854380c5d423319c/work/artifacts"
2026-09-16 21:51:03.059030 | instance -> localhost | changed: "/var/lib/zuul/builds/5297627213494c45854380c5d423319c/work/docs"
2026-09-16 21:51:03.076686 | 
2026-09-16 21:51:03.077693 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-16 21:51:03.781792 | instance | changed: .d..t...... ./
2026-09-16 21:51:03.782092 | instance | changed: All items complete
2026-09-16 21:51:03.782133 | 
2026-09-16 21:51:04.261560 | instance | changed: .d..t...... ./
2026-09-16 21:51:04.742526 | instance | changed: .d..t...... ./
2026-09-16 21:51:04.770301 | 
2026-09-16 21:51:04.770508 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-16 21:51:05.239966 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007925
2026-09-16 21:51:05.472146 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006730
2026-09-16 21:51:05.488914 | 
2026-09-16 21:51:05.489123 | PLAY [all]
2026-09-16 21:51:05.501601 | 
2026-09-16 21:51:05.501738 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-16 21:51:05.943029 | instance | changed
2026-09-16 21:51:05.953456 | 
2026-09-16 21:51:05.953548 | PLAY RECAP
2026-09-16 21:51:05.953630 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-16 21:51:05.953673 | 
2026-09-16 21:51:06.125158 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-16 21:51:06.129241 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-16 21:51:06.840171 | 
2026-09-16 21:51:06.840316 | PLAY [localhost]
2026-09-16 21:51:06.850381 | 
2026-09-16 21:51:06.850449 | TASK [Generate Zuul manifest]
2026-09-16 21:51:06.866997 | localhost | ok
2026-09-16 21:51:06.880521 | 
2026-09-16 21:51:06.880594 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-16 21:51:07.264627 | localhost | changed
2026-09-16 21:51:07.288342 | 
2026-09-16 21:51:07.288458 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-16 21:51:07.317702 | localhost | ok
2026-09-16 21:51:07.329501 | 
2026-09-16 21:51:07.329633 | TASK [Upload logs]
2026-09-16 21:51:07.351100 | localhost | ok
2026-09-16 21:51:07.420398 | 
2026-09-16 21:51:07.420550 | TASK [Set zuul-log-path fact]
2026-09-16 21:51:07.437259 | localhost | ok
2026-09-16 21:51:07.450550 | 
2026-09-16 21:51:07.450659 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-16 21:51:07.489657 | localhost | ok
2026-09-16 21:51:07.495920 | 
2026-09-16 21:51:07.495997 | TASK [upload-logs : Create log directories]
2026-09-16 21:51:07.963553 | localhost | changed
2026-09-16 21:51:07.972763 | 
2026-09-16 21:51:07.972901 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-16 21:51:08.407935 | localhost -> localhost | ok: Runtime: 0:00:00.005596
2026-09-16 21:51:08.413183 | 
2026-09-16 21:51:08.413266 | TASK [upload-logs : Upload logs to log server]
2026-09-16 21:51:08.955134 | localhost | Output suppressed because no_log was given
2026-09-16 21:51:09.014842 | 
2026-09-16 21:51:09.015000 | LOOP [upload-logs : Compress console log and json output]
2026-09-16 21:51:09.086452 | localhost | skipping: Conditional result was False
2026-09-16 21:51:09.093417 | localhost | skipping: Conditional result was False
2026-09-16 21:51:09.108906 | 
2026-09-16 21:51:09.109154 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-16 21:51:09.169220 | localhost | skipping: Conditional result was False
2026-09-16 21:51:09.169723 | 
2026-09-16 21:51:09.174339 | localhost | skipping: Conditional result was False
2026-09-16 21:51:09.186345 | 
2026-09-16 21:51:09.186589 | LOOP [upload-logs : Upload console log and json output]