2026-09-17 19:17:49.602023 | Job console starting
2026-09-17 19:17:49.612352 | Updating git repos
2026-09-17 19:17:51.802437 | Cloning repos into workspace
2026-09-17 19:18:08.798332 | Restoring repo states
2026-09-17 19:18:23.695884 | Merging changes
2026-09-17 19:18:24.517748 | Checking out repos
2026-09-17 19:18:25.865347 | Preparing playbooks
2026-09-17 19:18:27.487526 | Running Ansible setup
2026-09-17 19:18:31.001132 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-17 19:18:31.676474 | 
2026-09-17 19:18:31.676603 | PLAY [localhost]
2026-09-17 19:18:31.683760 | 
2026-09-17 19:18:31.683835 | TASK [Gathering Facts]
2026-09-17 19:18:32.467866 | localhost | ok
2026-09-17 19:18:32.474138 | 
2026-09-17 19:18:32.474210 | TASK [Setup log path fact]
2026-09-17 19:18:32.491163 | localhost | ok
2026-09-17 19:18:32.500758 | 
2026-09-17 19:18:32.500861 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-17 19:18:32.541392 | localhost | ok
2026-09-17 19:18:32.554413 | 
2026-09-17 19:18:32.554500 | TASK [emit-job-header : Print job information]
2026-09-17 19:18:32.597436 | # Job Information
2026-09-17 19:18:32.597702 | Ansible Version: 2.16.18
2026-09-17 19:18:32.597800 | Job: atmosphere-molecule-csi-rbd
2026-09-17 19:18:32.597862 | Pipeline: check
2026-09-17 19:18:32.597915 | Executor: 2d72f0692154
2026-09-17 19:18:32.597965 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4370
2026-09-17 19:18:32.598028 | Event ID: 356bd2c0-b2cb-11f1-9a72-768b15c3b759
2026-09-17 19:18:32.604444 | 
2026-09-17 19:18:32.604623 | LOOP [emit-job-header : Print node information]
2026-09-17 19:18:32.685148 | localhost | ok:
2026-09-17 19:18:32.685378 | localhost | # Node Information
2026-09-17 19:18:32.685433 | localhost | Inventory Hostname: instance
2026-09-17 19:18:32.685475 | localhost | Hostname: np0000193096
2026-09-17 19:18:32.685513 | localhost | Username: zuul
2026-09-17 19:18:32.685556 | localhost | Distro: Ubuntu 22.04
2026-09-17 19:18:32.685594 | localhost | Provider: yul1
2026-09-17 19:18:32.685631 | localhost | Region: ca-ymq-1
2026-09-17 19:18:32.685667 | localhost | Label: ubuntu-jammy
2026-09-17 19:18:32.685701 | localhost | Product Name: OpenStack Nova
2026-09-17 19:18:32.685736 | localhost | Interface IP: 162.253.55.86
2026-09-17 19:18:32.706072 | 
2026-09-17 19:18:32.706310 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-17 19:18:33.037479 | localhost -> localhost | changed
2026-09-17 19:18:33.046413 | 
2026-09-17 19:18:33.046633 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-17 19:18:33.833301 | localhost -> localhost | changed
2026-09-17 19:18:33.845690 | 
2026-09-17 19:18:33.845827 | PLAY [all]
2026-09-17 19:18:33.864403 | 
2026-09-17 19:18:33.864574 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-17 19:18:34.103061 | instance -> localhost | ok
2026-09-17 19:18:34.114464 | 
2026-09-17 19:18:34.114610 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-17 19:18:34.149880 | instance | ok
2026-09-17 19:18:34.173991 | instance | included: /var/lib/zuul/builds/118a874471d44483bcce24ddc43e324e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-17 19:18:34.182205 | 
2026-09-17 19:18:34.182305 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-17 19:18:35.716628 | instance -> localhost | Generating public/private rsa key pair.
2026-09-17 19:18:35.716986 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/118a874471d44483bcce24ddc43e324e/work/118a874471d44483bcce24ddc43e324e_id_rsa
2026-09-17 19:18:35.717066 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/118a874471d44483bcce24ddc43e324e/work/118a874471d44483bcce24ddc43e324e_id_rsa.pub
2026-09-17 19:18:35.717115 | instance -> localhost | The key fingerprint is:
2026-09-17 19:18:35.717161 | instance -> localhost | SHA256:d24Xv09HldtOR6ItVwRjB8UJK6vi6AAASNdLQIEQcOs zuul-build-sshkey
2026-09-17 19:18:35.717229 | instance -> localhost | The key's randomart image is:
2026-09-17 19:18:35.717274 | instance -> localhost | +---[RSA 3072]----+
2026-09-17 19:18:35.717327 | instance -> localhost | |Xo+=+        .*==|
2026-09-17 19:18:35.717372 | instance -> localhost | |oo.. o       ..=o|
2026-09-17 19:18:35.717414 | instance -> localhost | |. . . .    . ...+|
2026-09-17 19:18:35.717475 | instance -> localhost | |..   .      oo ++|
2026-09-17 19:18:35.717518 | instance -> localhost | | .E     S ..+ +.=|
2026-09-17 19:18:35.717558 | instance -> localhost | |  .      ..o o *.|
2026-09-17 19:18:35.717598 | instance -> localhost | |   .   . .  o . =|
2026-09-17 19:18:35.717639 | instance -> localhost | |    . o .  . . .o|
2026-09-17 19:18:35.717684 | instance -> localhost | |    .o .       .o|
2026-09-17 19:18:35.717725 | instance -> localhost | +----[SHA256]-----+
2026-09-17 19:18:35.717849 | instance -> localhost | ok: Runtime: 0:00:01.077997
2026-09-17 19:18:35.731183 | 
2026-09-17 19:18:35.731320 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-17 19:18:35.763373 | instance | ok
2026-09-17 19:18:35.773763 | instance | included: /var/lib/zuul/builds/118a874471d44483bcce24ddc43e324e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-17 19:18:35.782782 | 
2026-09-17 19:18:35.782865 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-17 19:18:35.806556 | instance | skipping: Conditional result was False
2026-09-17 19:18:35.817402 | 
2026-09-17 19:18:35.817537 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-17 19:18:36.246150 | instance | changed
2026-09-17 19:18:36.259593 | 
2026-09-17 19:18:36.259829 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-17 19:18:36.468251 | instance | ok
2026-09-17 19:18:36.480469 | 
2026-09-17 19:18:36.480656 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-17 19:18:36.979678 | instance | changed
2026-09-17 19:18:36.987427 | 
2026-09-17 19:18:36.987533 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-17 19:18:37.461347 | instance | changed
2026-09-17 19:18:37.473210 | 
2026-09-17 19:18:37.473350 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-17 19:18:37.497706 | instance | skipping: Conditional result was False
2026-09-17 19:18:37.507700 | 
2026-09-17 19:18:37.507860 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-17 19:18:37.846471 | instance -> localhost | changed
2026-09-17 19:18:37.865695 | 
2026-09-17 19:18:37.865855 | TASK [add-build-sshkey : Add back temp key]
2026-09-17 19:18:38.143304 | instance -> localhost | Identity added: /var/lib/zuul/builds/118a874471d44483bcce24ddc43e324e/work/118a874471d44483bcce24ddc43e324e_id_rsa (zuul-build-sshkey)
2026-09-17 19:18:38.143643 | instance -> localhost | ok: Runtime: 0:00:00.007464
2026-09-17 19:18:38.153632 | 
2026-09-17 19:18:38.153823 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-17 19:18:38.485185 | instance | ok
2026-09-17 19:18:38.494110 | 
2026-09-17 19:18:38.494243 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-17 19:18:38.531487 | instance | skipping: Conditional result was False
2026-09-17 19:18:38.550268 | 
2026-09-17 19:18:38.550436 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-17 19:18:38.900433 | instance | ok
2026-09-17 19:18:38.911060 | 
2026-09-17 19:18:38.911207 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-17 19:18:42.464777 | instance | Output suppressed because no_log was given
2026-09-17 19:18:42.476452 | 
2026-09-17 19:18:42.476596 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-17 19:18:42.720896 | instance | ok: "logs"
2026-09-17 19:18:42.722939 | instance | ok: All items complete
2026-09-17 19:18:42.723068 | 
2026-09-17 19:18:42.887120 | instance | ok: "artifacts"
2026-09-17 19:18:43.032075 | instance | ok: "docs"
2026-09-17 19:18:43.054979 | 
2026-09-17 19:18:43.055130 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-17 19:18:43.266467 | instance | changed: "logs"
2026-09-17 19:18:43.409334 | instance | changed: "artifacts"
2026-09-17 19:18:43.564750 | instance | changed: "docs"
2026-09-17 19:18:43.583547 | 
2026-09-17 19:18:43.583689 | PLAY RECAP
2026-09-17 19:18:43.583809 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-17 19:18:43.583868 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-17 19:18:43.583911 | 
2026-09-17 19:18:43.689862 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-17 19:18:43.694572 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-17 19:18:44.309734 | 
2026-09-17 19:18:44.309896 | PLAY [all]
2026-09-17 19:18:44.320651 | 
2026-09-17 19:18:44.320777 | TASK [setup-uv : Extract archive]
2026-09-17 19:18:46.689917 | instance | changed
2026-09-17 19:18:46.701022 | 
2026-09-17 19:18:46.701190 | TASK [setup-uv : Print version]
2026-09-17 19:18:47.064625 | instance | uv 0.8.13
2026-09-17 19:18:47.245843 | instance | ok: Runtime: 0:00:00.010983
2026-09-17 19:18:47.263978 | 
2026-09-17 19:18:47.264137 | TASK [Install Ansible collection siblings]
2026-09-17 19:18:56.942995 | instance | changed:
2026-09-17 19:18:56.943242 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-17 19:18:56.943302 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-17 19:18:56.943346 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-17 19:18:56.943384 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-17 19:18:56.943421 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-17 19:18:56.943467 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-17 19:18:56.943507 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-17 19:18:56.943550 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-17 19:18:56.943588 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-17 19:18:56.943625 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-17 19:18:56.943661 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-17 19:18:56.943697 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-17 19:18:56.943732 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-09-17 19:18:56.943768 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-17 19:18:56.943825 | instance | Resolved ansible.utils to v6.1.1 for >=2.9.0
2026-09-17 19:18:56.943865 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-17 19:18:56.943901 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-17 19:18:56.943937 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-17 19:18:56.943972 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-17 19:18:56.944007 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-09-17 19:18:56.944042 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0
2026-09-17 19:18:56.944077 | instance | Resolved atmosphere.common to v0.7.1 for >=0.7.0
2026-09-17 19:18:56.944115 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-17 19:18:56.944166 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-17 19:18:56.944203 | instance | Resolved ansible.utils to v6.1.1 for >=2.9.0,>=6.0.0
2026-09-17 19:18:56.944237 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-17 19:18:56.944274 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-17 19:18:56.944333 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-17 19:18:56.944432 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-17 19:18:56.944481 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-17 19:18:56.944573 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-17 19:18:56.944618 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-17 19:18:56.944655 | instance | Checked out ansible.netcommon to 1.2.0
2026-09-17 19:18:56.944691 | 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-17 19:18:56.944738 | instance | Starting galaxy collection install process
2026-09-17 19:18:56.944773 | instance | Process install dependency map
2026-09-17 19:18:56.944831 | instance | Starting collection install process
2026-09-17 19:18:56.944867 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-17 19:18:56.944901 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-17 19:18:56.944934 | instance | ansible.netcommon:* was installed successfully
2026-09-17 19:18:56.944968 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-17 19:18:56.945001 | instance | Creating virtual environment at: .venv
2026-09-17 19:18:56.945034 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-17 19:18:56.945069 | instance | Downloading setuptools (1.1MiB)
2026-09-17 19:18:56.945103 | instance | Downloading pygments (1.2MiB)
2026-09-17 19:18:56.945142 | instance | Downloading cryptography (4.2MiB)
2026-09-17 19:18:56.945178 | instance | Downloading pydantic-core (2.0MiB)
2026-09-17 19:18:56.945212 | instance | Downloading ansible-core (2.1MiB)
2026-09-17 19:18:56.945246 | instance | Downloading rjsonnet (1.2MiB)
2026-09-17 19:18:56.945280 | instance | Downloading openstacksdk (1.7MiB)
2026-09-17 19:18:56.945315 | instance | Downloading kubernetes (1.9MiB)
2026-09-17 19:18:56.945349 | instance | Downloading netaddr (2.2MiB)
2026-09-17 19:18:56.945383 | instance |    Building pyperclip==1.9.0
2026-09-17 19:18:56.945422 | instance |  Downloading rjsonnet
2026-09-17 19:18:56.945457 | instance |  Downloading pydantic-core
2026-09-17 19:18:56.945492 | instance |  Downloading netaddr
2026-09-17 19:18:56.945526 | instance |  Downloading pygments
2026-09-17 19:18:56.945560 | instance |  Downloading cryptography
2026-09-17 19:18:56.945594 | instance |  Downloading setuptools
2026-09-17 19:18:56.945628 | instance |  Downloading kubernetes
2026-09-17 19:18:56.945662 | instance |  Downloading ansible-core
2026-09-17 19:18:56.945696 | instance |  Downloading openstacksdk
2026-09-17 19:18:56.945730 | instance |       Built pyperclip==1.9.0
2026-09-17 19:18:56.945766 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-17 19:18:56.945822 | instance | Installed 83 packages in 59ms
2026-09-17 19:18:56.945859 | instance | Checked out ansible.posix to 1.6.0
2026-09-17 19:18:56.945893 | 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-17 19:18:56.945928 | instance | Starting galaxy collection install process
2026-09-17 19:18:56.945961 | instance | Process install dependency map
2026-09-17 19:18:56.946008 | instance | Starting collection install process
2026-09-17 19:18:56.946043 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-17 19:18:56.946077 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-17 19:18:56.946111 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-17 19:18:56.946200 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:18:56.946248 | instance | 2.17.12
2026-09-17 19:18:56.946277 | instance | Checked out ansible.utils to v6.1.1
2026-09-17 19:18:56.946300 | 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-17 19:18:56.946351 | instance | Starting galaxy collection install process
2026-09-17 19:18:56.946375 | instance | Process install dependency map
2026-09-17 19:18:56.946422 | instance | Starting collection install process
2026-09-17 19:18:56.946449 | instance | Installing 'ansible.utils:6.1.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-17 19:18:56.946493 | instance | Created collection for ansible.utils:6.1.1 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-17 19:18:56.946524 | instance | ansible.utils:6.1.1 was installed successfully
2026-09-17 19:18:56.946546 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:18:56.946595 | instance | 2.17.12
2026-09-17 19:18:56.946621 | instance | Checked out atmosphere.common to v0.7.1
2026-09-17 19:18:56.946667 | 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-17 19:18:56.946695 | instance | Starting galaxy collection install process
2026-09-17 19:18:56.946718 | instance | Process install dependency map
2026-09-17 19:18:56.946739 | instance | Starting collection install process
2026-09-17 19:18:56.946761 | instance | Installing 'atmosphere.common:0.7.1' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-17 19:18:56.946783 | instance | Created collection for atmosphere.common:0.7.1 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-17 19:18:56.946866 | instance | atmosphere.common:0.7.1 was installed successfully
2026-09-17 19:18:56.946888 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:18:56.946910 | instance | 2.17.12
2026-09-17 19:18:56.946932 | instance | Checked out community.crypto to 2.2.3
2026-09-17 19:18:56.946963 | 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-17 19:18:56.947022 | instance | Starting galaxy collection install process
2026-09-17 19:18:56.947065 | instance | Process install dependency map
2026-09-17 19:18:56.947089 | instance | Starting collection install process
2026-09-17 19:18:56.947112 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-17 19:18:56.947135 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-17 19:18:56.947156 | instance | community.crypto:2.2.3 was installed successfully
2026-09-17 19:18:56.947178 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:18:56.947199 | instance | 2.17.12
2026-09-17 19:18:56.947220 | instance | Checked out community.general to 7.3.0
2026-09-17 19:18:56.947242 | 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-17 19:18:56.947271 | instance | Starting galaxy collection install process
2026-09-17 19:18:56.947294 | instance | Process install dependency map
2026-09-17 19:18:56.947315 | instance | Starting collection install process
2026-09-17 19:18:56.947336 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-17 19:18:56.947357 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-17 19:18:56.947378 | instance | community.general:7.3.0 was installed successfully
2026-09-17 19:18:56.947400 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:18:56.947421 | instance | 2.17.12
2026-09-17 19:18:56.947442 | instance | Checked out community.mysql to 3.6.0
2026-09-17 19:18:56.947467 | 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-17 19:18:56.947490 | instance | Starting galaxy collection install process
2026-09-17 19:18:56.947512 | instance | Process install dependency map
2026-09-17 19:18:56.947533 | instance | Starting collection install process
2026-09-17 19:18:56.947555 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-17 19:18:56.947577 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-17 19:18:56.947598 | instance | community.mysql:3.6.0 was installed successfully
2026-09-17 19:18:56.947620 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:18:56.947641 | instance | 2.17.12
2026-09-17 19:18:56.947662 | instance | Checked out kubernetes.core to 2.4.0
2026-09-17 19:18:56.947684 | 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-17 19:18:56.947706 | instance | Starting galaxy collection install process
2026-09-17 19:18:56.947727 | instance | Process install dependency map
2026-09-17 19:18:56.947748 | instance | Starting collection install process
2026-09-17 19:18:56.947770 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-17 19:18:56.947807 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-17 19:18:56.947831 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-17 19:18:56.947852 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:18:56.947873 | instance | 2.17.12
2026-09-17 19:18:56.947895 | instance | Checked out openstack.cloud to 2.6.0
2026-09-17 19:18:56.947916 | 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-17 19:18:56.947937 | instance | Starting galaxy collection install process
2026-09-17 19:18:56.947958 | instance | Process install dependency map
2026-09-17 19:18:56.947979 | instance | Starting collection install process
2026-09-17 19:18:56.948000 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-17 19:18:56.948020 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-17 19:18:56.948041 | instance | openstack.cloud:2.6.0 was installed successfully
2026-09-17 19:18:56.948062 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:18:56.948083 | instance | 2.17.12
2026-09-17 19:18:56.948108 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-17 19:18:56.948130 | 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-17 19:18:56.948151 | instance | Starting galaxy collection install process
2026-09-17 19:18:56.948172 | instance | Process install dependency map
2026-09-17 19:18:56.948192 | instance | Starting collection install process
2026-09-17 19:18:56.948213 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-17 19:18:56.948234 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-17 19:18:56.948254 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-17 19:18:56.948275 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:18:56.948296 | instance | 2.17.12
2026-09-17 19:18:56.948317 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-17 19:18:56.948338 | 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-17 19:18:56.948359 | instance | Starting galaxy collection install process
2026-09-17 19:18:56.948380 | instance | Process install dependency map
2026-09-17 19:18:56.948401 | instance | Starting collection install process
2026-09-17 19:18:56.948422 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-17 19:18:56.948442 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-17 19:18:56.948464 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-17 19:18:56.948485 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:18:56.948546 | instance | 2.17.12
2026-09-17 19:18:56.948573 | instance | Checked out vexxhost.kubernetes to v3.4.0
2026-09-17 19:18:56.948597 | 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-17 19:18:56.948619 | instance | Starting galaxy collection install process
2026-09-17 19:18:56.948640 | instance | Process install dependency map
2026-09-17 19:18:56.948661 | instance | Starting collection install process
2026-09-17 19:18:56.948682 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-17 19:18:56.948703 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-17 19:18:56.948725 | instance | vexxhost.kubernetes:3.4.0 was installed successfully
2026-09-17 19:18:56.948746 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:18:56.948767 | instance | 2.17.12
2026-09-17 19:18:56.955082 | 
2026-09-17 19:18:56.955143 | PLAY RECAP
2026-09-17 19:18:56.955197 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-17 19:18:56.955223 | 
2026-09-17 19:18:57.087776 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-17 19:18:57.092574 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-17 19:18:57.700124 | 
2026-09-17 19:18:57.700226 | PLAY [all]
2026-09-17 19:18:57.710264 | 
2026-09-17 19:18:57.710331 | TASK [Install "jq" for log collection]
2026-09-17 19:19:07.090545 | instance | changed
2026-09-17 19:19:07.093890 | 
2026-09-17 19:19:07.094012 | PLAY RECAP
2026-09-17 19:19:07.094110 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-17 19:19:07.094224 | 
2026-09-17 19:19:07.202384 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-17 19:19:07.206559 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-17 19:19:07.748120 | 
2026-09-17 19:19:07.748237 | PLAY [all]
2026-09-17 19:19:07.758013 | 
2026-09-17 19:19:07.758082 | LOOP [Check inventory directories]
2026-09-17 19:19:08.119673 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-17 19:19:08.296479 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-17 19:19:08.450169 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-17 19:19:08.469027 | 
2026-09-17 19:19:08.469255 | LOOP [Copy inventory file for Zuul]
2026-09-17 19:19:09.345184 | instance | changed:
2026-09-17 19:19:09.345484 | instance | {
2026-09-17 19:19:09.345528 | instance |   "ansible_loop_var": "item",
2026-09-17 19:19:09.345560 | instance |   "changed": false,
2026-09-17 19:19:09.345590 | instance |   "failed": false,
2026-09-17 19:19:09.345620 | instance |   "invocation": {
2026-09-17 19:19:09.345644 | instance |     "module_args": {
2026-09-17 19:19:09.345668 | instance |       "checksum_algorithm": "sha1",
2026-09-17 19:19:09.345691 | instance |       "follow": true,
2026-09-17 19:19:09.345713 | instance |       "get_attributes": true,
2026-09-17 19:19:09.345736 | instance |       "get_checksum": true,
2026-09-17 19:19:09.345757 | instance |       "get_mime": true,
2026-09-17 19:19:09.345778 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-17 19:19:09.345799 | instance |     }
2026-09-17 19:19:09.345838 | instance |   },
2026-09-17 19:19:09.345861 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-17 19:19:09.345885 | instance |   "stat": {
2026-09-17 19:19:09.345911 | instance |     "atime": 1789672729.3114765,
2026-09-17 19:19:09.345936 | instance |     "attr_flags": "e",
2026-09-17 19:19:09.345961 | instance |     "attributes": [
2026-09-17 19:19:09.345985 | instance |       "extents"
2026-09-17 19:19:09.346007 | instance |     ],
2026-09-17 19:19:09.346029 | instance |     "block_size": 4096,
2026-09-17 19:19:09.346051 | instance |     "blocks": 8,
2026-09-17 19:19:09.346073 | instance |     "charset": "unknown",
2026-09-17 19:19:09.346096 | instance |     "ctime": 1789672728.1634283,
2026-09-17 19:19:09.346118 | instance |     "dev": 64513,
2026-09-17 19:19:09.346139 | instance |     "device_type": 0,
2026-09-17 19:19:09.346159 | instance |     "executable": true,
2026-09-17 19:19:09.346180 | instance |     "exists": true,
2026-09-17 19:19:09.346200 | instance |     "gid": 1000,
2026-09-17 19:19:09.346221 | instance |     "gr_name": "zuul",
2026-09-17 19:19:09.346241 | instance |     "inode": 1048532,
2026-09-17 19:19:09.346264 | instance |     "isblk": false,
2026-09-17 19:19:09.346291 | instance |     "ischr": false,
2026-09-17 19:19:09.346316 | instance |     "isdir": true,
2026-09-17 19:19:09.346341 | instance |     "isfifo": false,
2026-09-17 19:19:09.346363 | instance |     "isgid": false,
2026-09-17 19:19:09.346385 | instance |     "islnk": false,
2026-09-17 19:19:09.346406 | instance |     "isreg": false,
2026-09-17 19:19:09.346427 | instance |     "issock": false,
2026-09-17 19:19:09.346448 | instance |     "isuid": false,
2026-09-17 19:19:09.346469 | instance |     "mimetype": "unknown",
2026-09-17 19:19:09.346490 | instance |     "mode": "0755",
2026-09-17 19:19:09.346510 | instance |     "mtime": 1789672728.1634283,
2026-09-17 19:19:09.346530 | instance |     "nlink": 22,
2026-09-17 19:19:09.346550 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-17 19:19:09.346572 | instance |     "pw_name": "zuul",
2026-09-17 19:19:09.346598 | instance |     "readable": true,
2026-09-17 19:19:09.346623 | instance |     "rgrp": true,
2026-09-17 19:19:09.346647 | instance |     "roth": true,
2026-09-17 19:19:09.346687 | instance |     "rusr": true,
2026-09-17 19:19:09.346715 | instance |     "size": 4096,
2026-09-17 19:19:09.346738 | instance |     "uid": 1000,
2026-09-17 19:19:09.346760 | instance |     "version": "215025791",
2026-09-17 19:19:09.346781 | instance |     "wgrp": false,
2026-09-17 19:19:09.346805 | instance |     "woth": false,
2026-09-17 19:19:09.346844 | instance |     "writeable": true,
2026-09-17 19:19:09.346867 | instance |     "wusr": true,
2026-09-17 19:19:09.346889 | instance |     "xgrp": true,
2026-09-17 19:19:09.346910 | instance |     "xoth": true,
2026-09-17 19:19:09.346931 | instance |     "xusr": true
2026-09-17 19:19:09.346951 | instance |   }
2026-09-17 19:19:09.346972 | instance | }
2026-09-17 19:19:09.347014 | instance | ok: All items complete
2026-09-17 19:19:09.347044 | 
2026-09-17 19:19:09.348600 | instance | skipping: Conditional result was False
2026-09-17 19:19:09.354352 | instance | skipping: Conditional result was False
2026-09-17 19:19:09.375833 | 
2026-09-17 19:19:09.375959 | LOOP [Switch "ansible_host" to private IP]
2026-09-17 19:19:09.781567 | instance | changed: 1 replacements made
2026-09-17 19:19:09.783953 | instance | skipping: Conditional result was False
2026-09-17 19:19:09.785446 | instance | skipping: Conditional result was False
2026-09-17 19:19:09.802794 | 
2026-09-17 19:19:09.803039 | TASK [Run Molecule scenario]
2026-09-17 19:19:10.740638 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-17 19:19:11.147668 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-17 19:19:11.147754 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-17 19:19:21.954080 | instance | INFO     [csi > dependency] Executing
2026-09-17 19:19:21.954508 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-17 19:19:21.955004 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-17 19:19:21.955246 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-17 19:19:21.970310 | instance | INFO     [csi > cleanup] Executing
2026-09-17 19:19:21.971031 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-17 19:19:21.982718 | instance | INFO     [csi > destroy] Executing
2026-09-17 19:19:21.982822 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-17 19:19:21.982917 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-17 19:19:21.991787 | instance | INFO     [csi > syntax] Executing
2026-09-17 19:19:23.519312 | instance |
2026-09-17 19:19:23.519744 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-17 19:19:23.580689 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-17 19:19:23.597273 | instance | INFO     [csi > create] Executing
2026-09-17 19:19:23.601066 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-17 19:19:23.617400 | instance | INFO     [csi > prepare] Executing
2026-09-17 19:19:24.392820 | instance |
2026-09-17 19:19:24.393080 | instance | PLAY [Prepare] *****************************************************************
2026-09-17 19:19:24.393345 | instance |
2026-09-17 19:19:24.393837 | instance | TASK [Gathering Facts] *********************************************************
2026-09-17 19:19:24.394256 | instance | Thursday 17 September 2026  19:19:24 +0000 (0:00:00.023)       0:00:00.023 ****
2026-09-17 19:19:25.639749 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-17 19:19:25.639999 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-17 19:19:25.640163 | instance | interpreter could change the meaning of that path. See
2026-09-17 19:19:25.640420 | instance | https://docs.ansible.com/ansible-
2026-09-17 19:19:25.640659 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-17 19:19:25.654588 | instance | ok: [instance]
2026-09-17 19:19:25.654807 | instance |
2026-09-17 19:19:25.655053 | instance | TASK [Configure short hostname] ************************************************
2026-09-17 19:19:25.655271 | instance | Thursday 17 September 2026  19:19:25 +0000 (0:00:01.262)       0:00:01.286 ****
2026-09-17 19:19:26.382186 | instance | changed: [instance]
2026-09-17 19:19:26.382492 | instance |
2026-09-17 19:19:26.382824 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-17 19:19:26.383140 | instance | Thursday 17 September 2026  19:19:26 +0000 (0:00:00.726)       0:00:02.012 ****
2026-09-17 19:19:26.659246 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-17 19:19:26.659594 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-17 19:19:26.659883 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-17 19:19:26.668561 | instance | changed: [instance]
2026-09-17 19:19:26.668650 | instance |
2026-09-17 19:19:26.668768 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-17 19:19:26.668883 | instance | Thursday 17 September 2026  19:19:26 +0000 (0:00:00.287)       0:00:02.300 ****
2026-09-17 19:19:27.481196 | instance | ok: [instance]
2026-09-17 19:19:27.481337 | instance |
2026-09-17 19:19:27.481552 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-17 19:19:27.481709 | instance |
2026-09-17 19:19:27.481859 | instance | TASK [Gathering Facts] *********************************************************
2026-09-17 19:19:27.482010 | instance | Thursday 17 September 2026  19:19:27 +0000 (0:00:00.812)       0:00:03.112 ****
2026-09-17 19:19:28.308539 | instance | ok: [instance]
2026-09-17 19:19:28.308743 | instance |
2026-09-17 19:19:28.308999 | instance | TASK [Install depedencies] *****************************************************
2026-09-17 19:19:28.309252 | instance | Thursday 17 September 2026  19:19:28 +0000 (0:00:00.826)       0:00:03.939 ****
2026-09-17 19:19:51.232926 | instance | changed: [instance]
2026-09-17 19:19:51.232985 | instance |
2026-09-17 19:19:51.232993 | instance | TASK [Start up service] ********************************************************
2026-09-17 19:19:51.233000 | instance | Thursday 17 September 2026  19:19:51 +0000 (0:00:22.923)       0:00:26.863 ****
2026-09-17 19:19:51.781758 | instance | ok: [instance]
2026-09-17 19:19:51.781821 | instance |
2026-09-17 19:19:51.782094 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-17 19:19:51.782128 | instance | Thursday 17 September 2026  19:19:51 +0000 (0:00:00.550)       0:00:27.413 ****
2026-09-17 19:19:52.135427 | instance | ok: [instance]
2026-09-17 19:19:52.135546 | instance |
2026-09-17 19:19:52.136126 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-17 19:19:52.136179 | instance | Thursday 17 September 2026  19:19:52 +0000 (0:00:00.353)       0:00:27.766 ****
2026-09-17 19:19:52.845082 | instance | changed: [instance]
2026-09-17 19:19:52.845657 | instance |
2026-09-17 19:19:52.845850 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-17 19:19:52.845868 | instance | Thursday 17 September 2026  19:19:52 +0000 (0:00:00.709)       0:00:28.475 ****
2026-09-17 19:19:53.063712 | instance | ok: [instance]
2026-09-17 19:19:53.063901 | instance |
2026-09-17 19:19:53.064313 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-17 19:19:53.064643 | instance | Thursday 17 September 2026  19:19:53 +0000 (0:00:00.219)       0:00:28.694 ****
2026-09-17 19:19:53.097025 | instance | skipping: [instance]
2026-09-17 19:19:53.097276 | instance |
2026-09-17 19:19:53.097683 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-17 19:19:53.098064 | instance | Thursday 17 September 2026  19:19:53 +0000 (0:00:00.033)       0:00:28.728 ****
2026-09-17 19:19:53.699916 | instance | changed: [instance] => (item=osd0)
2026-09-17 19:19:53.701381 | instance | changed: [instance] => (item=osd1)
2026-09-17 19:19:53.701465 | instance | changed: [instance] => (item=osd2)
2026-09-17 19:19:53.701477 | instance |
2026-09-17 19:19:53.701488 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-17 19:19:53.701499 | instance | Thursday 17 September 2026  19:19:53 +0000 (0:00:00.602)       0:00:29.330 ****
2026-09-17 19:19:54.377658 | instance | changed: [instance] => (item=osd0)
2026-09-17 19:19:54.378859 | instance | changed: [instance] => (item=osd1)
2026-09-17 19:19:54.379130 | instance | changed: [instance] => (item=osd2)
2026-09-17 19:19:54.379144 | instance |
2026-09-17 19:19:54.379155 | instance | TASK [Start loop devices] ******************************************************
2026-09-17 19:19:54.379167 | instance | Thursday 17 September 2026  19:19:54 +0000 (0:00:00.677)       0:00:30.008 ****
2026-09-17 19:19:55.147647 | instance | changed: [instance] => (item=osd0)
2026-09-17 19:19:55.147897 | instance | changed: [instance] => (item=osd1)
2026-09-17 19:19:55.148334 | instance | changed: [instance] => (item=osd2)
2026-09-17 19:19:55.148679 | instance |
2026-09-17 19:19:55.149029 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-17 19:19:55.149363 | instance | Thursday 17 September 2026  19:19:55 +0000 (0:00:00.769)       0:00:30.778 ****
2026-09-17 19:19:58.349630 | instance | changed: [instance] => (item=osd0)
2026-09-17 19:19:58.351099 | instance | changed: [instance] => (item=osd1)
2026-09-17 19:19:58.351210 | instance | changed: [instance] => (item=osd2)
2026-09-17 19:19:58.351226 | instance |
2026-09-17 19:19:58.351262 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-17 19:19:58.351434 | instance | Thursday 17 September 2026  19:19:58 +0000 (0:00:03.202)       0:00:33.980 ****
2026-09-17 19:20:00.323423 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-17 19:20:00.323732 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-17 19:20:00.323765 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-17 19:20:00.324491 | instance |
2026-09-17 19:20:00.324514 | instance | PLAY RECAP *********************************************************************
2026-09-17 19:20:00.324525 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-17 19:20:00.324534 | instance |
2026-09-17 19:20:00.324543 | instance | Thursday 17 September 2026  19:20:00 +0000 (0:00:01.974)       0:00:35.955 ****
2026-09-17 19:20:00.325371 | instance | ===============================================================================
2026-09-17 19:20:00.325429 | instance | Install depedencies ---------------------------------------------------- 22.92s
2026-09-17 19:20:00.325434 | instance | Create a volume group for each loop device ------------------------------ 3.20s
2026-09-17 19:20:00.325438 | instance | Create a logical volume for each loop device ---------------------------- 1.97s
2026-09-17 19:20:00.325443 | instance | Gathering Facts --------------------------------------------------------- 1.26s
2026-09-17 19:20:00.325452 | instance | Gathering Facts --------------------------------------------------------- 0.83s
2026-09-17 19:20:00.325457 | instance | Purge "snapd" package --------------------------------------------------- 0.81s
2026-09-17 19:20:00.326741 | instance | Start loop devices ------------------------------------------------------ 0.77s
2026-09-17 19:20:00.326760 | instance | Configure short hostname ------------------------------------------------ 0.73s
2026-09-17 19:20:00.326765 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.71s
2026-09-17 19:20:00.326769 | instance | Set permissions on loopback devices ------------------------------------- 0.68s
2026-09-17 19:20:00.326774 | instance | Create devices for Ceph ------------------------------------------------- 0.60s
2026-09-17 19:20:00.326778 | instance | Start up service -------------------------------------------------------- 0.55s
2026-09-17 19:20:00.326782 | instance | Generate lvm.conf ------------------------------------------------------- 0.35s
2026-09-17 19:20:00.326787 | instance | Ensure hostname inside hosts file --------------------------------------- 0.29s
2026-09-17 19:20:00.326792 | instance | Get list of all loopback devices ---------------------------------------- 0.22s
2026-09-17 19:20:00.326797 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s
2026-09-17 19:20:00.406548 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-17 19:20:00.421747 | instance | INFO     [csi > converge] Executing
2026-09-17 19:20:01.795019 | instance |
2026-09-17 19:20:01.795412 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-17 19:20:01.795719 | instance |
2026-09-17 19:20:01.796050 | instance | TASK [Gathering Facts] *********************************************************
2026-09-17 19:20:01.796363 | instance | Thursday 17 September 2026  19:20:01 +0000 (0:00:00.006)       0:00:00.006 ****
2026-09-17 19:20:02.888135 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-17 19:20:02.888180 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-17 19:20:02.888187 | instance | interpreter could change the meaning of that path. See
2026-09-17 19:20:02.888194 | instance | https://docs.ansible.com/ansible-
2026-09-17 19:20:02.888200 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-17 19:20:02.902905 | instance | ok: [instance]
2026-09-17 19:20:02.902950 | instance |
2026-09-17 19:20:02.902967 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-17 19:20:02.902981 | instance | Thursday 17 September 2026  19:20:02 +0000 (0:00:01.106)       0:00:01.113 ****
2026-09-17 19:20:02.975895 | instance | ok: [instance] => {
2026-09-17 19:20:02.975954 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-17 19:20:02.975965 | instance | }
2026-09-17 19:20:02.975975 | instance |
2026-09-17 19:20:02.975984 | instance | PLAY [all] *********************************************************************
2026-09-17 19:20:02.975993 | instance |
2026-09-17 19:20:02.976002 | instance | TASK [Gathering Facts] *********************************************************
2026-09-17 19:20:02.976029 | instance | Thursday 17 September 2026  19:20:02 +0000 (0:00:00.071)       0:00:01.185 ****
2026-09-17 19:20:03.981351 | instance | ok: [instance]
2026-09-17 19:20:03.981792 | instance |
2026-09-17 19:20:03.982234 | instance | TASK [Fail if atmosphere_ceph_enabled is set] **********************************
2026-09-17 19:20:03.982614 | instance | Thursday 17 September 2026  19:20:03 +0000 (0:00:01.007)       0:00:02.192 ****
2026-09-17 19:20:04.034091 | instance | skipping: [instance]
2026-09-17 19:20:04.034338 | instance |
2026-09-17 19:20:04.034726 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-17 19:20:04.035086 | instance | Thursday 17 September 2026  19:20:04 +0000 (0:00:00.052)       0:00:02.245 ****
2026-09-17 19:20:04.250431 | instance | ok: [instance]
2026-09-17 19:20:04.250844 | instance |
2026-09-17 19:20:04.251181 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-17 19:20:04.251481 | instance |
2026-09-17 19:20:04.251793 | instance | TASK [Gathering Facts] *********************************************************
2026-09-17 19:20:04.252152 | instance | Thursday 17 September 2026  19:20:04 +0000 (0:00:00.215)       0:00:02.461 ****
2026-09-17 19:20:05.274195 | instance | ok: [instance]
2026-09-17 19:20:05.274443 | instance |
2026-09-17 19:20:05.274649 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-17 19:20:05.274888 | instance | Thursday 17 September 2026  19:20:05 +0000 (0:00:01.021)       0:00:03.482 ****
2026-09-17 19:20:05.586151 | instance | ok: [instance]
2026-09-17 19:20:05.586386 | instance |
2026-09-17 19:20:05.586599 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-17 19:20:05.586821 | instance | Thursday 17 September 2026  19:20:05 +0000 (0:00:00.312)       0:00:03.795 ****
2026-09-17 19:20:05.632815 | instance | skipping: [instance]
2026-09-17 19:20:05.633060 | instance |
2026-09-17 19:20:05.633285 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-17 19:20:05.633506 | instance | Thursday 17 September 2026  19:20:05 +0000 (0:00:00.047)       0:00:03.842 ****
2026-09-17 19:20:06.025706 | instance | changed: [instance]
2026-09-17 19:20:06.025862 | instance |
2026-09-17 19:20:06.025954 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-17 19:20:06.026250 | instance | Thursday 17 September 2026  19:20:06 +0000 (0:00:00.394)       0:00:04.236 ****
2026-09-17 19:20:06.133909 | instance | ok: [instance] => {
2026-09-17 19:20:06.134113 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-17 19:20:06.134409 | instance | }
2026-09-17 19:20:06.134620 | instance |
2026-09-17 19:20:06.134885 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-17 19:20:06.135120 | instance | Thursday 17 September 2026  19:20:06 +0000 (0:00:00.107)       0:00:04.344 ****
2026-09-17 19:20:06.907275 | instance | changed: [instance]
2026-09-17 19:20:06.907537 | instance |
2026-09-17 19:20:06.907695 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-17 19:20:06.907951 | instance | Thursday 17 September 2026  19:20:06 +0000 (0:00:00.773)       0:00:05.118 ****
2026-09-17 19:20:06.974007 | instance | skipping: [instance]
2026-09-17 19:20:06.974049 | instance |
2026-09-17 19:20:06.974184 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-17 19:20:06.974317 | instance | Thursday 17 September 2026  19:20:06 +0000 (0:00:00.067)       0:00:05.185 ****
2026-09-17 19:20:07.037619 | instance | skipping: [instance]
2026-09-17 19:20:07.037985 | instance |
2026-09-17 19:20:07.038364 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-17 19:20:07.038708 | instance | Thursday 17 September 2026  19:20:07 +0000 (0:00:00.063)       0:00:05.249 ****
2026-09-17 19:20:07.292652 | instance | ok: [instance]
2026-09-17 19:20:07.292836 | instance |
2026-09-17 19:20:07.293096 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-17 19:20:07.293335 | instance | Thursday 17 September 2026  19:20:07 +0000 (0:00:00.254)       0:00:05.503 ****
2026-09-17 19:20:08.704945 | instance | ok: [instance]
2026-09-17 19:20:08.705206 | instance |
2026-09-17 19:20:08.705466 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-17 19:20:08.705785 | instance | Thursday 17 September 2026  19:20:08 +0000 (0:00:01.412)       0:00:06.916 ****
2026-09-17 19:20:08.776072 | instance | ok: [instance] => {
2026-09-17 19:20:08.776315 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-17 19:20:08.776552 | instance | }
2026-09-17 19:20:08.776802 | instance |
2026-09-17 19:20:08.777078 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-17 19:20:08.777316 | instance | Thursday 17 September 2026  19:20:08 +0000 (0:00:00.071)       0:00:06.987 ****
2026-09-17 19:20:09.632607 | instance | changed: [instance]
2026-09-17 19:20:09.632707 | instance |
2026-09-17 19:20:09.632820 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-17 19:20:09.632929 | instance | Thursday 17 September 2026  19:20:09 +0000 (0:00:00.856)       0:00:07.844 ****
2026-09-17 19:20:12.565617 | instance | changed: [instance]
2026-09-17 19:20:12.566334 | instance |
2026-09-17 19:20:12.566375 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-17 19:20:12.566411 | instance | Thursday 17 September 2026  19:20:12 +0000 (0:00:02.932)       0:00:10.777 ****
2026-09-17 19:20:12.590758 | instance | skipping: [instance]
2026-09-17 19:20:12.590831 | instance |
2026-09-17 19:20:12.591248 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-17 19:20:12.591286 | instance | Thursday 17 September 2026  19:20:12 +0000 (0:00:00.025)       0:00:10.802 ****
2026-09-17 19:20:12.623926 | instance | skipping: [instance]
2026-09-17 19:20:12.624372 | instance |
2026-09-17 19:20:12.624408 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-17 19:20:12.624416 | instance | Thursday 17 September 2026  19:20:12 +0000 (0:00:00.032)       0:00:10.835 ****
2026-09-17 19:20:12.665866 | instance | skipping: [instance]
2026-09-17 19:20:12.665974 | instance |
2026-09-17 19:20:12.666332 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-17 19:20:12.666378 | instance | Thursday 17 September 2026  19:20:12 +0000 (0:00:00.041)       0:00:10.877 ****
2026-09-17 19:20:18.120163 | instance | changed: [instance]
2026-09-17 19:20:18.120244 | instance |
2026-09-17 19:20:18.120463 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-17 19:20:18.120718 | instance | Thursday 17 September 2026  19:20:18 +0000 (0:00:05.454)       0:00:16.332 ****
2026-09-17 19:20:18.748460 | instance | changed: [instance]
2026-09-17 19:20:18.748724 | instance |
2026-09-17 19:20:18.749098 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-17 19:20:18.749417 | instance | Thursday 17 September 2026  19:20:18 +0000 (0:00:00.627)       0:00:16.959 ****
2026-09-17 19:20:19.787559 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-17 19:20:19.787874 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-17 19:20:19.788251 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-17 19:20:19.788640 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-17 19:20:19.788982 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-17 19:20:19.789316 | instance |
2026-09-17 19:20:19.789733 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-17 19:20:19.790078 | instance | Thursday 17 September 2026  19:20:19 +0000 (0:00:01.038)       0:00:17.998 ****
2026-09-17 19:20:20.387749 | instance | changed: [instance]
2026-09-17 19:20:20.387838 | instance |
2026-09-17 19:20:20.387915 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-17 19:20:20.388030 | instance | Thursday 17 September 2026  19:20:20 +0000 (0:00:00.583)       0:00:18.582 ****
2026-09-17 19:20:20.388211 | instance |
2026-09-17 19:20:20.388352 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-17 19:20:20.388487 | instance | Thursday 17 September 2026  19:20:20 +0000 (0:00:00.017)       0:00:18.599 ****
2026-09-17 19:20:21.357640 | instance | ok: [instance]
2026-09-17 19:20:21.357908 | instance |
2026-09-17 19:20:21.357926 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-17 19:20:21.357946 | instance | Thursday 17 September 2026  19:20:21 +0000 (0:00:00.968)       0:00:19.568 ****
2026-09-17 19:20:21.851588 | instance | changed: [instance]
2026-09-17 19:20:21.852430 | instance |
2026-09-17 19:20:21.852490 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-17 19:20:21.852534 | instance | Thursday 17 September 2026  19:20:21 +0000 (0:00:00.494)       0:00:20.063 ****
2026-09-17 19:20:22.439008 | instance | changed: [instance]
2026-09-17 19:20:22.439665 | instance |
2026-09-17 19:20:22.439719 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-17 19:20:22.439730 | instance | Thursday 17 September 2026  19:20:22 +0000 (0:00:00.587)       0:00:20.650 ****
2026-09-17 19:20:22.686131 | instance | ok: [instance]
2026-09-17 19:20:22.686883 | instance |
2026-09-17 19:20:22.686936 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-17 19:20:22.686948 | instance | Thursday 17 September 2026  19:20:22 +0000 (0:00:00.247)       0:00:20.897 ****
2026-09-17 19:20:22.755023 | instance | ok: [instance] => {
2026-09-17 19:20:22.755884 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-17 19:20:22.755929 | instance | }
2026-09-17 19:20:22.755938 | instance |
2026-09-17 19:20:22.755944 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-17 19:20:22.755952 | instance | Thursday 17 September 2026  19:20:22 +0000 (0:00:00.069)       0:00:20.966 ****
2026-09-17 19:20:23.657694 | instance | changed: [instance]
2026-09-17 19:20:23.657757 | instance |
2026-09-17 19:20:23.657774 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-17 19:20:23.658001 | instance | Thursday 17 September 2026  19:20:23 +0000 (0:00:00.901)       0:00:21.868 ****
2026-09-17 19:20:28.094524 | instance | changed: [instance]
2026-09-17 19:20:28.094596 | instance |
2026-09-17 19:20:28.094901 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-17 19:20:28.094942 | instance | Thursday 17 September 2026  19:20:28 +0000 (0:00:04.437)       0:00:26.306 ****
2026-09-17 19:20:29.232260 | instance | ok: [instance]
2026-09-17 19:20:29.232301 | instance |
2026-09-17 19:20:29.232309 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-17 19:20:29.232315 | instance | Thursday 17 September 2026  19:20:29 +0000 (0:00:01.137)       0:00:27.443 ****
2026-09-17 19:20:29.724435 | instance | changed: [instance]
2026-09-17 19:20:29.725267 | instance |
2026-09-17 19:20:29.725503 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-17 19:20:29.725517 | instance | Thursday 17 September 2026  19:20:29 +0000 (0:00:00.492)       0:00:27.935 ****
2026-09-17 19:20:30.235578 | instance | changed: [instance]
2026-09-17 19:20:30.235629 | instance |
2026-09-17 19:20:30.235641 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-17 19:20:30.235650 | instance | Thursday 17 September 2026  19:20:30 +0000 (0:00:00.510)       0:00:28.446 ****
2026-09-17 19:20:30.894135 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-17 19:20:30.895521 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-17 19:20:30.895577 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-17 19:20:30.895591 | instance |
2026-09-17 19:20:30.895600 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-17 19:20:30.895609 | instance | Thursday 17 September 2026  19:20:30 +0000 (0:00:00.659)       0:00:29.105 ****
2026-09-17 19:20:31.382729 | instance | changed: [instance]
2026-09-17 19:20:31.383570 | instance |
2026-09-17 19:20:31.383653 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-17 19:20:31.383665 | instance | Thursday 17 September 2026  19:20:31 +0000 (0:00:00.488)       0:00:29.593 ****
2026-09-17 19:20:31.913339 | instance | changed: [instance]
2026-09-17 19:20:31.913492 | instance |
2026-09-17 19:20:31.913511 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-17 19:20:31.914267 | instance | Thursday 17 September 2026  19:20:31 +0000 (0:00:00.503)       0:00:30.097 ****
2026-09-17 19:20:31.914355 | instance |
2026-09-17 19:20:31.914364 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-17 19:20:31.914378 | instance | Thursday 17 September 2026  19:20:31 +0000 (0:00:00.028)       0:00:30.125 ****
2026-09-17 19:20:32.711132 | instance | ok: [instance]
2026-09-17 19:20:32.712117 | instance |
2026-09-17 19:20:32.712404 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-17 19:20:32.712423 | instance | Thursday 17 September 2026  19:20:32 +0000 (0:00:00.797)       0:00:30.922 ****
2026-09-17 19:20:33.776926 | instance | changed: [instance]
2026-09-17 19:20:33.777497 | instance |
2026-09-17 19:20:33.777569 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-17 19:20:33.777581 | instance | Thursday 17 September 2026  19:20:33 +0000 (0:00:01.066)       0:00:31.988 ****
2026-09-17 19:20:34.385221 | instance | changed: [instance]
2026-09-17 19:20:34.385430 | instance |
2026-09-17 19:20:34.385469 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-17 19:20:34.385754 | instance | Thursday 17 September 2026  19:20:34 +0000 (0:00:00.608)       0:00:32.597 ****
2026-09-17 19:20:34.463068 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-17 19:20:34.463161 | instance |
2026-09-17 19:20:34.463544 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-17 19:20:34.463618 | instance | Thursday 17 September 2026  19:20:34 +0000 (0:00:00.077)       0:00:32.674 ****
2026-09-17 19:20:40.512928 | instance | changed: [instance]
2026-09-17 19:20:40.513018 | instance |
2026-09-17 19:20:40.513492 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-17 19:20:40.513574 | instance | Thursday 17 September 2026  19:20:40 +0000 (0:00:06.050)       0:00:38.724 ****
2026-09-17 19:20:41.212354 | instance | ok: [instance] => (item=chronyd)
2026-09-17 19:20:41.212465 | instance | ok: [instance] => (item=sshd)
2026-09-17 19:20:41.212970 | instance |
2026-09-17 19:20:41.213239 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-17 19:20:41.213249 | instance | Thursday 17 September 2026  19:20:41 +0000 (0:00:00.699)       0:00:39.423 ****
2026-09-17 19:20:41.837620 | instance | changed: [instance]
2026-09-17 19:20:41.837761 | instance |
2026-09-17 19:20:41.837913 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-09-17 19:20:41.838068 | instance | Thursday 17 September 2026  19:20:41 +0000 (0:00:00.625)       0:00:40.049 ****
2026-09-17 19:20:42.094210 | instance | ok: [instance]
2026-09-17 19:20:42.094462 | instance |
2026-09-17 19:20:42.094755 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-09-17 19:20:42.095023 | instance | Thursday 17 September 2026  19:20:42 +0000 (0:00:00.255)       0:00:40.305 ****
2026-09-17 19:20:42.594180 | instance | changed: [instance]
2026-09-17 19:20:42.594615 | instance |
2026-09-17 19:20:42.594905 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-09-17 19:20:42.595181 | instance | Thursday 17 September 2026  19:20:42 +0000 (0:00:00.500)       0:00:40.805 ****
2026-09-17 19:20:43.008639 | instance | changed: [instance]
2026-09-17 19:20:43.008847 | instance |
2026-09-17 19:20:43.009138 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-09-17 19:20:43.009428 | instance | Thursday 17 September 2026  19:20:43 +0000 (0:00:00.414)       0:00:41.220 ****
2026-09-17 19:20:43.145201 | instance | ok: [instance]
2026-09-17 19:20:43.145389 | instance |
2026-09-17 19:20:43.145728 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-09-17 19:20:43.146148 | instance | Thursday 17 September 2026  19:20:43 +0000 (0:00:00.136)       0:00:41.356 ****
2026-09-17 19:20:43.408287 | instance | ok: [instance] => (item=instance)
2026-09-17 19:20:43.408554 | instance |
2026-09-17 19:20:43.408859 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-09-17 19:20:43.409191 | instance | Thursday 17 September 2026  19:20:43 +0000 (0:00:00.263)       0:00:41.619 ****
2026-09-17 19:20:43.472718 | instance | ok: [instance]
2026-09-17 19:20:43.472926 | instance |
2026-09-17 19:20:43.473239 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-09-17 19:20:43.473549 | instance | Thursday 17 September 2026  19:20:43 +0000 (0:00:00.064)       0:00:41.684 ****
2026-09-17 19:20:43.561591 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-09-17 19:20:43.561859 | instance |
2026-09-17 19:20:43.562142 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-09-17 19:20:43.562411 | instance | Thursday 17 September 2026  19:20:43 +0000 (0:00:00.088)       0:00:41.773 ****
2026-09-17 19:20:43.946407 | instance | changed: [instance]
2026-09-17 19:20:43.946603 | instance |
2026-09-17 19:20:43.946845 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-09-17 19:20:43.947099 | instance | Thursday 17 September 2026  19:20:43 +0000 (0:00:00.384)       0:00:42.157 ****
2026-09-17 19:20:44.720670 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-09-17 19:20:44.720713 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-09-17 19:20:44.720718 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-09-17 19:20:44.720727 | instance |
2026-09-17 19:20:44.721470 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-09-17 19:20:44.721658 | instance | Thursday 17 September 2026  19:20:44 +0000 (0:00:00.773)       0:00:42.931 ****
2026-09-17 19:22:15.481252 | instance | ok: [instance]
2026-09-17 19:22:15.481320 | instance |
2026-09-17 19:22:15.481332 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-09-17 19:22:15.481342 | instance | Thursday 17 September 2026  19:22:15 +0000 (0:01:30.759)       0:02:13.690 ****
2026-09-17 19:22:15.719179 | instance | changed: [instance]
2026-09-17 19:22:15.719250 | instance |
2026-09-17 19:22:15.719263 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-09-17 19:22:15.719273 | instance | Thursday 17 September 2026  19:22:15 +0000 (0:00:00.239)       0:02:13.930 ****
2026-09-17 19:22:15.760030 | instance | ok: [instance]
2026-09-17 19:22:15.760504 | instance |
2026-09-17 19:22:15.760568 | instance | TASK [Install Ceph host] *******************************************************
2026-09-17 19:22:15.760577 | instance | Thursday 17 September 2026  19:22:15 +0000 (0:00:00.041)       0:02:13.971 ****
2026-09-17 19:22:15.893569 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-09-17 19:22:15.893661 | instance |
2026-09-17 19:22:15.893886 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-09-17 19:22:15.893941 | instance | Thursday 17 September 2026  19:22:15 +0000 (0:00:00.133)       0:02:14.105 ****
2026-09-17 19:22:17.543276 | instance | ok: [instance]
2026-09-17 19:22:17.543505 | instance |
2026-09-17 19:22:17.543781 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-09-17 19:22:17.544019 | instance | Thursday 17 September 2026  19:22:17 +0000 (0:00:01.649)       0:02:15.754 ****
2026-09-17 19:22:17.631046 | instance | ok: [instance] => (item=instance)
2026-09-17 19:22:17.631379 | instance |
2026-09-17 19:22:17.631627 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-09-17 19:22:17.631872 | instance | Thursday 17 September 2026  19:22:17 +0000 (0:00:00.086)       0:02:15.841 ****
2026-09-17 19:22:17.985477 | instance | ok: [instance]
2026-09-17 19:22:17.985512 | instance |
2026-09-17 19:22:17.985518 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-09-17 19:22:17.985627 | instance | Thursday 17 September 2026  19:22:17 +0000 (0:00:00.355)       0:02:16.197 ****
2026-09-17 19:22:20.389474 | instance | ok: [instance]
2026-09-17 19:22:20.389588 | instance |
2026-09-17 19:22:20.389959 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-09-17 19:22:20.390005 | instance | Thursday 17 September 2026  19:22:20 +0000 (0:00:02.403)       0:02:18.601 ****
2026-09-17 19:23:10.029195 | 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-17 19:23:10.030131 | 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_fqy__5d5/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_fqy__5d5/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_fqy__5d5/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_fqy__5d5/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-17T19:23:09.797+0000 7fa5f20c5640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgC+Paxq+tbiMyAA05ubmBeVbXUgHsKuDzvZ3wIZzt8Ag+GF12SQrYfNcFI=: Malformed input [buffer:3]\n2026-09-17T19:23:09.797+0000 7fa5f20c5640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-17T19:23:09.801+0000 7fa5f20c5640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgC+Paxq+tbiMyAA05ubmBeVbXUgHsKuDzvZ3wIZzt8Ag+GF12SQrYfNcFI=: Malformed input [buffer:3]\n2026-09-17T19:23:09.801+0000 7fa5f20c5640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-17T19:23:09.801+0000 7fa5f20c5640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgC+Paxq+tbiMyAA05ubmBeVbXUgHsKuDzvZ3wIZzt8Ag+GF12SQrYfNcFI=: Malformed input [buffer:3]\n2026-09-17T19:23:09.801+0000 7fa5f20c5640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-17T19:23:09.801+0000 7fa5f20c5640 -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-17 19:23:10.033056 | instance |
2026-09-17 19:23:10.033327 | instance | PLAY RECAP *********************************************************************
2026-09-17 19:23:10.037659 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=7    rescued=0    ignored=0
2026-09-17 19:23:10.037991 | instance |
2026-09-17 19:23:10.038005 | instance | Thursday 17 September 2026  19:23:10 +0000 (0:00:49.644)       0:03:08.245 ****
2026-09-17 19:23:10.038016 | instance | ===============================================================================
2026-09-17 19:23:10.038024 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 90.76s
2026-09-17 19:23:10.038033 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 49.64s
2026-09-17 19:23:10.038042 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 6.05s
2026-09-17 19:23:10.038050 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.45s
2026-09-17 19:23:10.038059 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.44s
2026-09-17 19:23:10.038067 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.93s
2026-09-17 19:23:10.038095 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.40s
2026-09-17 19:23:10.038104 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.77s
2026-09-17 19:23:10.038112 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.65s
2026-09-17 19:23:10.038120 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.41s
2026-09-17 19:23:10.038129 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.14s
2026-09-17 19:23:10.038137 | instance | Gathering Facts --------------------------------------------------------- 1.11s
2026-09-17 19:23:10.038145 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 1.07s
2026-09-17 19:23:10.038153 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.04s
2026-09-17 19:23:10.038170 | instance | Gathering Facts --------------------------------------------------------- 1.02s
2026-09-17 19:23:10.038178 | instance | Gathering Facts --------------------------------------------------------- 1.01s
2026-09-17 19:23:10.038187 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.90s
2026-09-17 19:23:10.038419 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.86s
2026-09-17 19:23:10.038755 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.77s
2026-09-17 19:23:10.039039 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.77s
2026-09-17 19:23:10.149518 | 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-17 19:23:10.149989 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-17 19:23:10.150171 | 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-17 19:23:10.671230 | instance | ERROR
2026-09-17 19:23:10.671538 | instance | {
2026-09-17 19:23:10.671576 | instance |   "delta": "0:04:00.041719",
2026-09-17 19:23:10.671601 | instance |   "end": "2026-09-17 19:23:10.220436",
2026-09-17 19:23:10.671623 | instance |   "msg": "non-zero return code",
2026-09-17 19:23:10.671643 | instance |   "rc": 2,
2026-09-17 19:23:10.671665 | instance |   "start": "2026-09-17 19:19:10.178717"
2026-09-17 19:23:10.671690 | instance | }
failure
2026-09-17 19:23:10.680159 | 
2026-09-17 19:23:10.680346 | PLAY RECAP
2026-09-17 19:23:10.680685 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-17 19:23:10.680725 | 
2026-09-17 19:23:10.935338 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-17 19:23:10.939311 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-17 19:23:11.679459 | 
2026-09-17 19:23:11.679590 | PLAY [all]
2026-09-17 19:23:11.693676 | 
2026-09-17 19:23:11.693792 | TASK [gather-host-logs : creating directory for system status]
2026-09-17 19:23:12.050120 | instance | changed
2026-09-17 19:23:12.058895 | 
2026-09-17 19:23:12.059005 | TASK [gather-host-logs : Get logs for each host]
2026-09-17 19:23:12.473265 | instance | + systemd-cgls --full --all --no-pager
2026-09-17 19:23:12.489073 | instance | + ip addr
2026-09-17 19:23:12.491779 | instance | + ip route
2026-09-17 19:23:12.494218 | instance | + lsblk
2026-09-17 19:23:12.499875 | instance | + mount
2026-09-17 19:23:12.503988 | instance | + docker images
2026-09-17 19:23:12.529839 | instance | + brctl show
2026-09-17 19:23:12.530562 | instance | /bin/bash: line 8: brctl: command not found
2026-09-17 19:23:12.530954 | instance | + ip neigh
2026-09-17 19:23:12.534122 | instance | + bridge fdb show
2026-09-17 19:23:12.537751 | instance | + ps aux --sort=-%mem
2026-09-17 19:23:12.560927 | instance | + dpkg -l
2026-09-17 19:23:12.572732 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-17 19:23:12.573341 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-17 19:23:12.593706 | instance | + '[' '!' -z '' ']'
2026-09-17 19:23:13.100053 | instance | ok: Runtime: 0:00:00.125199
2026-09-17 19:23:13.113938 | 
2026-09-17 19:23:13.114364 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-17 19:23:13.789092 | instance | changed:
2026-09-17 19:23:13.789353 | instance | created directory /var/lib/zuul/builds/118a874471d44483bcce24ddc43e324e/work/logs/instance
2026-09-17 19:23:13.789413 | instance | cd+++++++++ system/
2026-09-17 19:23:13.789458 | instance | >f+++++++++ system/brctl-show.txt
2026-09-17 19:23:13.789498 | instance | >f+++++++++ system/bridge-fdb.txt
2026-09-17 19:23:13.789537 | instance | >f+++++++++ system/docker-images.txt
2026-09-17 19:23:13.789580 | instance | >f+++++++++ system/ip-addr.txt
2026-09-17 19:23:13.789621 | instance | >f+++++++++ system/ip-neigh.txt
2026-09-17 19:23:13.789660 | instance | >f+++++++++ system/ip-route.txt
2026-09-17 19:23:13.789703 | instance | >f+++++++++ system/lsblk.txt
2026-09-17 19:23:13.789809 | instance | >f+++++++++ system/mount.txt
2026-09-17 19:23:13.789898 | instance | >f+++++++++ system/packages.txt
2026-09-17 19:23:13.789950 | instance | >f+++++++++ system/ps.txt
2026-09-17 19:23:13.790004 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-17 19:23:13.810404 | 
2026-09-17 19:23:13.810617 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-17 19:23:14.031021 | instance | changed: "values"
2026-09-17 19:23:14.226571 | instance | changed: "releases"
2026-09-17 19:23:14.244252 | 
2026-09-17 19:23:14.244410 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-17 19:23:14.503766 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-17 19:23:14.792853 | instance | ok: Runtime: 0:00:00.004836
2026-09-17 19:23:14.801952 | 
2026-09-17 19:23:14.802078 | TASK [helm-release-status : Downloads logs to executor]
2026-09-17 19:23:15.335275 | instance | changed:
2026-09-17 19:23:15.335520 | instance | cd+++++++++ helm/
2026-09-17 19:23:15.335562 | instance | cd+++++++++ helm/releases/
2026-09-17 19:23:15.335592 | instance | cd+++++++++ helm/values/
2026-09-17 19:23:15.353125 | 
2026-09-17 19:23:15.353335 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-17 19:23:15.580616 | instance | changed
2026-09-17 19:23:15.592308 | 
2026-09-17 19:23:15.592539 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-17 19:23:15.912715 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-17 19:23:15.913836 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-17 19:23:15.917198 | instance | environment: line 1: kubectl: command not found
2026-09-17 19:23:15.917881 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-17 19:23:15.919240 | instance | environment: line 1: kubectl: command not found
2026-09-17 19:23:15.920489 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-17 19:23:15.922374 | instance | environment: line 1: kubectl: command not found
2026-09-17 19:23:15.924576 | instance | environment: line 1: kubectl: command not found
2026-09-17 19:23:15.925445 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-17 19:23:15.926601 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-17 19:23:15.928239 | instance | environment: line 1: kubectl: command not found
2026-09-17 19:23:15.929394 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-17 19:23:16.141419 | instance | ok: Runtime: 0:00:00.025740
2026-09-17 19:23:16.148760 | 
2026-09-17 19:23:16.148917 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-17 19:23:16.398921 | instance | changed
2026-09-17 19:23:16.410632 | 
2026-09-17 19:23:16.410959 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-17 19:23:16.666347 | instance | environment: line 5: kubectl: command not found
2026-09-17 19:23:16.667134 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-17 19:23:16.668068 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-17 19:23:16.668087 | instance | warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-17 19:23:16.957312 | instance | ok: Runtime: 0:00:00.012295
2026-09-17 19:23:16.967511 | 
2026-09-17 19:23:16.967637 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-17 19:23:17.487017 | instance | changed:
2026-09-17 19:23:17.487437 | instance | cd+++++++++ objects/
2026-09-17 19:23:17.487499 | instance | cd+++++++++ objects/cluster/
2026-09-17 19:23:17.487540 | instance | cd+++++++++ objects/namespaced/
2026-09-17 19:23:17.504679 | 
2026-09-17 19:23:17.504815 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-17 19:23:17.731275 | instance | changed
2026-09-17 19:23:17.737310 | 
2026-09-17 19:23:17.737389 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-17 19:23:17.981871 | instance | changed
2026-09-17 19:23:17.988376 | 
2026-09-17 19:23:17.988458 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-17 19:23:18.237513 | instance | environment: line 3: kubectl: command not found
2026-09-17 19:23:18.528365 | instance | ok: Runtime: 0:00:00.009929
2026-09-17 19:23:18.537564 | 
2026-09-17 19:23:18.537705 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-17 19:23:19.049656 | instance | changed:
2026-09-17 19:23:19.049850 | instance | cd+++++++++ pod-logs/
2026-09-17 19:23:19.049887 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-17 19:23:19.061379 | 
2026-09-17 19:23:19.061461 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-17 19:23:19.286293 | instance | changed
2026-09-17 19:23:19.297599 | 
2026-09-17 19:23:19.297819 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-17 19:23:19.541361 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-17 19:23:19.850736 | instance | ok: Runtime: 0:00:00.056165
2026-09-17 19:23:19.860093 | 
2026-09-17 19:23:19.860272 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-17 19:23:20.089364 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-17 19:23:20.129976 | instance | ceph-mgr endpoints:
2026-09-17 19:23:20.407749 | instance | ok: Runtime: 0:00:00.048840
2026-09-17 19:23:20.416999 | 
2026-09-17 19:23:20.417117 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-17 19:23:20.653317 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-17 19:23:20.959070 | instance | ok: Runtime: 0:00:00.053046
2026-09-17 19:23:20.963352 | 
2026-09-17 19:23:20.963409 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-17 19:23:21.460706 | instance | changed: cd+++++++++ prometheus/
2026-09-17 19:23:21.477417 | 
2026-09-17 19:23:21.477581 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-17 19:23:21.695870 | instance | changed
2026-09-17 19:23:21.706174 | 
2026-09-17 19:23:21.706364 | TASK [gather-selenium-data : Get selenium data]
2026-09-17 19:23:22.015156 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-17 19:23:22.016857 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-17 19:23:22.251803 | instance | ERROR
2026-09-17 19:23:22.252018 | instance | {
2026-09-17 19:23:22.252053 | instance |   "delta": "0:00:00.006602",
2026-09-17 19:23:22.252078 | instance |   "end": "2026-09-17 19:23:22.017247",
2026-09-17 19:23:22.252098 | instance |   "msg": "non-zero return code",
2026-09-17 19:23:22.252118 | instance |   "rc": 1,
2026-09-17 19:23:22.252137 | instance |   "start": "2026-09-17 19:23:22.010645"
2026-09-17 19:23:22.252156 | instance | }
2026-09-17 19:23:22.252181 | instance | ERROR: Ignoring Errors
2026-09-17 19:23:22.257695 | 
2026-09-17 19:23:22.257778 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-17 19:23:22.777493 | instance | changed: cd+++++++++ selenium/
2026-09-17 19:23:22.789227 | 
2026-09-17 19:23:22.789337 | PLAY RECAP
2026-09-17 19:23:22.789417 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-17 19:23:22.789458 | 
2026-09-17 19:23:22.912410 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-17 19:23:22.916595 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-17 19:23:23.488556 | 
2026-09-17 19:23:23.488719 | PLAY [all]
2026-09-17 19:23:23.500136 | 
2026-09-17 19:23:23.731396 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-17 19:23:23.788917 | instance | skipping: Conditional result was False
2026-09-17 19:23:23.799798 | 
2026-09-17 19:23:23.799966 | TASK [fetch-output : Set log path for single node]
2026-09-17 19:23:23.854861 | instance | ok
2026-09-17 19:23:23.864695 | 
2026-09-17 19:23:23.864840 | LOOP [fetch-output : Ensure local output dirs]
2026-09-17 19:23:24.221701 | instance -> localhost | ok: "/var/lib/zuul/builds/118a874471d44483bcce24ddc43e324e/work/logs"
2026-09-17 19:23:24.455960 | instance -> localhost | changed: "/var/lib/zuul/builds/118a874471d44483bcce24ddc43e324e/work/artifacts"
2026-09-17 19:23:24.677524 | instance -> localhost | changed: "/var/lib/zuul/builds/118a874471d44483bcce24ddc43e324e/work/docs"
2026-09-17 19:23:24.692413 | 
2026-09-17 19:23:24.692563 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-17 19:23:25.327906 | instance | changed: .d..t...... ./
2026-09-17 19:23:25.328294 | instance | changed: All items complete
2026-09-17 19:23:25.328349 | 
2026-09-17 19:23:25.836165 | instance | changed: .d..t...... ./
2026-09-17 19:23:26.289758 | instance | changed: .d..t...... ./
2026-09-17 19:23:26.312946 | 
2026-09-17 19:23:26.313141 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-17 19:23:26.662303 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.006353
2026-09-17 19:23:26.880680 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007208
2026-09-17 19:23:26.904290 | 
2026-09-17 19:23:26.904463 | PLAY [all]
2026-09-17 19:23:26.916952 | 
2026-09-17 19:23:26.917116 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-17 19:23:27.335169 | instance | changed
2026-09-17 19:23:27.347496 | 
2026-09-17 19:23:27.347588 | PLAY RECAP
2026-09-17 19:23:27.347670 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-17 19:23:27.347713 | 
2026-09-17 19:23:27.461154 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-17 19:23:27.463598 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-17 19:23:28.017011 | 
2026-09-17 19:23:28.017289 | PLAY [localhost]
2026-09-17 19:23:28.032588 | 
2026-09-17 19:23:28.032713 | TASK [Generate Zuul manifest]
2026-09-17 19:23:28.052894 | localhost | ok
2026-09-17 19:23:28.082789 | 
2026-09-17 19:23:28.083022 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-17 19:23:28.468684 | localhost | changed
2026-09-17 19:23:28.487861 | 
2026-09-17 19:23:28.488067 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-17 19:23:28.519771 | localhost | ok
2026-09-17 19:23:28.533048 | 
2026-09-17 19:23:28.533212 | TASK [Upload logs]
2026-09-17 19:23:28.556426 | localhost | ok
2026-09-17 19:23:28.631675 | 
2026-09-17 19:23:28.631805 | TASK [Set zuul-log-path fact]
2026-09-17 19:23:28.648277 | localhost | ok
2026-09-17 19:23:28.658779 | 
2026-09-17 19:23:28.658845 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-17 19:23:28.685548 | localhost | ok
2026-09-17 19:23:28.694104 | 
2026-09-17 19:23:28.694194 | TASK [upload-logs : Create log directories]
2026-09-17 19:23:29.116444 | localhost | changed
2026-09-17 19:23:29.120645 | 
2026-09-17 19:23:29.120738 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-17 19:23:29.511581 | localhost -> localhost | ok: Runtime: 0:00:00.004893
2026-09-17 19:23:29.515826 | 
2026-09-17 19:23:29.515885 | TASK [upload-logs : Upload logs to log server]
2026-09-17 19:23:29.983033 | localhost | Output suppressed because no_log was given
2026-09-17 19:23:30.025969 | 
2026-09-17 19:23:30.026113 | LOOP [upload-logs : Compress console log and json output]
2026-09-17 19:23:30.089299 | localhost | skipping: Conditional result was False
2026-09-17 19:23:30.096200 | localhost | skipping: Conditional result was False
2026-09-17 19:23:30.109940 | 
2026-09-17 19:23:30.110150 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-17 19:23:30.153185 | localhost | skipping: Conditional result was False
2026-09-17 19:23:30.153674 | 
2026-09-17 19:23:30.156476 | localhost | skipping: Conditional result was False
2026-09-17 19:23:30.173425 | 
2026-09-17 19:23:30.173620 | LOOP [upload-logs : Upload console log and json output]