2026-09-17 19:18:40.123311 | Job console starting
2026-09-17 19:18:40.133255 | Updating git repos
2026-09-17 19:18:42.426569 | Cloning repos into workspace
2026-09-17 19:19:00.157756 | Restoring repo states
2026-09-17 19:19:16.088086 | Merging changes
2026-09-17 19:19:17.331344 | Checking out repos
2026-09-17 19:19:18.843562 | Preparing playbooks
2026-09-17 19:19:21.014518 | Running Ansible setup
2026-09-17 19:19:24.944484 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-17 19:19:25.525471 | 
2026-09-17 19:19:25.525573 | PLAY [localhost]
2026-09-17 19:19:25.532712 | 
2026-09-17 19:19:25.532779 | TASK [Gathering Facts]
2026-09-17 19:19:26.682471 | localhost | ok
2026-09-17 19:19:26.695174 | 
2026-09-17 19:19:26.695331 | TASK [Setup log path fact]
2026-09-17 19:19:26.715452 | localhost | ok
2026-09-17 19:19:26.735305 | 
2026-09-17 19:19:26.735388 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-17 19:19:26.764597 | localhost | ok
2026-09-17 19:19:26.780011 | 
2026-09-17 19:19:26.780111 | TASK [emit-job-header : Print job information]
2026-09-17 19:19:26.830265 | # Job Information
2026-09-17 19:19:26.830457 | Ansible Version: 2.16.18
2026-09-17 19:19:26.830520 | Job: atmosphere-molecule-csi-rbd
2026-09-17 19:19:26.830562 | Pipeline: check
2026-09-17 19:19:26.830599 | Executor: 2d72f0692154
2026-09-17 19:19:26.830635 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4381
2026-09-17 19:19:26.830678 | Event ID: b6798b00-b2cb-11f1-8b20-b6b6a791427f
2026-09-17 19:19:26.836324 | 
2026-09-17 19:19:26.836482 | LOOP [emit-job-header : Print node information]
2026-09-17 19:19:26.942072 | localhost | ok:
2026-09-17 19:19:26.942319 | localhost | # Node Information
2026-09-17 19:19:26.942374 | localhost | Inventory Hostname: instance
2026-09-17 19:19:26.942416 | localhost | Hostname: np0000193089
2026-09-17 19:19:26.942454 | localhost | Username: zuul
2026-09-17 19:19:26.942496 | localhost | Distro: Ubuntu 22.04
2026-09-17 19:19:26.942535 | localhost | Provider: yul1
2026-09-17 19:19:26.942572 | localhost | Region: ca-ymq-1
2026-09-17 19:19:26.942610 | localhost | Label: ubuntu-jammy
2026-09-17 19:19:26.942646 | localhost | Product Name: OpenStack Nova
2026-09-17 19:19:26.942681 | localhost | Interface IP: 162.253.55.186
2026-09-17 19:19:26.953272 | 
2026-09-17 19:19:27.036140 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-17 19:19:27.652783 | localhost -> localhost | changed
2026-09-17 19:19:27.661269 | 
2026-09-17 19:19:27.661401 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-17 19:19:28.768085 | localhost -> localhost | changed
2026-09-17 19:19:28.780428 | 
2026-09-17 19:19:28.780647 | PLAY [all]
2026-09-17 19:19:28.801661 | 
2026-09-17 19:19:28.801901 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-17 19:19:29.041121 | instance -> localhost | ok
2026-09-17 19:19:29.052326 | 
2026-09-17 19:19:29.052545 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-17 19:19:29.077609 | instance | ok
2026-09-17 19:19:29.101771 | instance | included: /var/lib/zuul/builds/1531ccc2d48e432ba42f46aa06d51029/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-17 19:19:29.109906 | 
2026-09-17 19:19:29.110093 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-17 19:19:30.824716 | instance -> localhost | Generating public/private rsa key pair.
2026-09-17 19:19:30.824988 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/1531ccc2d48e432ba42f46aa06d51029/work/1531ccc2d48e432ba42f46aa06d51029_id_rsa
2026-09-17 19:19:30.825052 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/1531ccc2d48e432ba42f46aa06d51029/work/1531ccc2d48e432ba42f46aa06d51029_id_rsa.pub
2026-09-17 19:19:30.825099 | instance -> localhost | The key fingerprint is:
2026-09-17 19:19:30.825143 | instance -> localhost | SHA256:LGP2HbjGQR/cTJ9BZzPOYYfWn+x//jcM/rDPOwlgmms zuul-build-sshkey
2026-09-17 19:19:30.825197 | instance -> localhost | The key's randomart image is:
2026-09-17 19:19:30.825240 | instance -> localhost | +---[RSA 3072]----+
2026-09-17 19:19:30.825291 | instance -> localhost | |            ..oB+|
2026-09-17 19:19:30.825335 | instance -> localhost | |         . + .*=*|
2026-09-17 19:19:30.825376 | instance -> localhost | |        . o o.+oo|
2026-09-17 19:19:30.825417 | instance -> localhost | |       o o +   o.|
2026-09-17 19:19:30.825458 | instance -> localhost | |      = S * . .  |
2026-09-17 19:19:30.825498 | instance -> localhost | |     o = * . o . |
2026-09-17 19:19:30.825537 | instance -> localhost | |        = o ..+ o|
2026-09-17 19:19:30.825577 | instance -> localhost | |       . E   .+=+|
2026-09-17 19:19:30.825623 | instance -> localhost | |        .    .o*X|
2026-09-17 19:19:30.825665 | instance -> localhost | +----[SHA256]-----+
2026-09-17 19:19:30.825756 | instance -> localhost | ok: Runtime: 0:00:01.249654
2026-09-17 19:19:30.836991 | 
2026-09-17 19:19:30.837120 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-17 19:19:30.869762 | instance | ok
2026-09-17 19:19:30.884745 | instance | included: /var/lib/zuul/builds/1531ccc2d48e432ba42f46aa06d51029/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-17 19:19:30.897308 | 
2026-09-17 19:19:30.897396 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-17 19:19:30.921650 | instance | skipping: Conditional result was False
2026-09-17 19:19:30.931324 | 
2026-09-17 19:19:30.931440 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-17 19:19:31.368828 | instance | changed
2026-09-17 19:19:31.381596 | 
2026-09-17 19:19:31.381775 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-17 19:19:31.581489 | instance | ok
2026-09-17 19:19:31.593300 | 
2026-09-17 19:19:31.593420 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-17 19:19:32.117040 | instance | changed
2026-09-17 19:19:32.126798 | 
2026-09-17 19:19:32.126943 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-17 19:19:32.594131 | instance | changed
2026-09-17 19:19:32.605095 | 
2026-09-17 19:19:32.605241 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-17 19:19:32.619225 | instance | skipping: Conditional result was False
2026-09-17 19:19:32.629009 | 
2026-09-17 19:19:32.629141 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-17 19:19:33.017583 | instance -> localhost | changed
2026-09-17 19:19:33.027493 | 
2026-09-17 19:19:33.027576 | TASK [add-build-sshkey : Add back temp key]
2026-09-17 19:19:33.291985 | instance -> localhost | Identity added: /var/lib/zuul/builds/1531ccc2d48e432ba42f46aa06d51029/work/1531ccc2d48e432ba42f46aa06d51029_id_rsa (zuul-build-sshkey)
2026-09-17 19:19:33.292277 | instance -> localhost | ok: Runtime: 0:00:00.015411
2026-09-17 19:19:33.301450 | 
2026-09-17 19:19:33.301572 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-17 19:19:33.599316 | instance | ok
2026-09-17 19:19:33.607460 | 
2026-09-17 19:19:33.607579 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-17 19:19:33.641973 | instance | skipping: Conditional result was False
2026-09-17 19:19:33.658708 | 
2026-09-17 19:19:33.658823 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-17 19:19:33.995523 | instance | ok
2026-09-17 19:19:34.023477 | 
2026-09-17 19:19:34.023607 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-17 19:19:38.761701 | instance | Output suppressed because no_log was given
2026-09-17 19:19:38.769307 | 
2026-09-17 19:19:38.769386 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-17 19:19:38.976619 | instance | ok: "logs"
2026-09-17 19:19:38.976809 | instance | ok: All items complete
2026-09-17 19:19:38.976834 | 
2026-09-17 19:19:39.127796 | instance | ok: "artifacts"
2026-09-17 19:19:39.304610 | instance | ok: "docs"
2026-09-17 19:19:39.503190 | 
2026-09-17 19:19:39.503361 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-17 19:19:39.706227 | instance | changed: "logs"
2026-09-17 19:19:39.871104 | instance | changed: "artifacts"
2026-09-17 19:19:40.067044 | instance | changed: "docs"
2026-09-17 19:19:40.085591 | 
2026-09-17 19:19:40.085774 | PLAY RECAP
2026-09-17 19:19:40.085905 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-17 19:19:40.085968 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-17 19:19:40.086014 | 
2026-09-17 19:19:40.204418 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-17 19:19:40.207777 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-17 19:19:41.085117 | 
2026-09-17 19:19:41.085234 | PLAY [all]
2026-09-17 19:19:41.104641 | 
2026-09-17 19:19:41.104754 | TASK [setup-uv : Extract archive]
2026-09-17 19:19:43.149783 | instance | changed
2026-09-17 19:19:43.159994 | 
2026-09-17 19:19:43.160140 | TASK [setup-uv : Print version]
2026-09-17 19:19:43.535945 | instance | uv 0.8.13
2026-09-17 19:19:43.706158 | instance | ok: Runtime: 0:00:00.014070
2026-09-17 19:19:43.723756 | 
2026-09-17 19:19:43.723947 | TASK [Install Ansible collection siblings]
2026-09-17 19:19:52.926228 | instance | changed:
2026-09-17 19:19:52.926462 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-17 19:19:52.926521 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-17 19:19:52.926563 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-17 19:19:52.926602 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-17 19:19:52.926640 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-17 19:19:52.926685 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-17 19:19:52.926725 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-17 19:19:52.926768 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-17 19:19:52.926807 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-17 19:19:52.926845 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-17 19:19:52.926882 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-17 19:19:52.926940 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-17 19:19:52.926978 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-09-17 19:19:52.927014 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-17 19:19:52.927048 | instance | Resolved ansible.utils to v6.1.1 for >=2.9.0
2026-09-17 19:19:52.927085 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-17 19:19:52.927120 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-17 19:19:52.927156 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-17 19:19:52.927191 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-17 19:19:52.927226 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-09-17 19:19:52.927261 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0
2026-09-17 19:19:52.927295 | instance | Resolved atmosphere.common to v0.7.1 for >=0.7.0
2026-09-17 19:19:52.927334 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-17 19:19:52.927383 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-17 19:19:52.927421 | instance | Resolved ansible.utils to v6.1.1 for >=2.9.0,>=6.0.0
2026-09-17 19:19:52.927457 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-17 19:19:52.927492 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-17 19:19:52.927528 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-17 19:19:52.927563 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-17 19:19:52.927599 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-17 19:19:52.927634 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-17 19:19:52.927671 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-17 19:19:52.927706 | instance | Checked out ansible.netcommon to 1.2.0
2026-09-17 19:19:52.927742 | 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:19:52.927787 | instance | Starting galaxy collection install process
2026-09-17 19:19:52.927823 | instance | Process install dependency map
2026-09-17 19:19:52.927858 | instance | Starting collection install process
2026-09-17 19:19:52.927911 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-17 19:19:52.927949 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-17 19:19:52.927983 | instance | ansible.netcommon:* was installed successfully
2026-09-17 19:19:52.928018 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-17 19:19:52.928052 | instance | Creating virtual environment at: .venv
2026-09-17 19:19:52.928087 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-17 19:19:52.928121 | instance | Downloading openstacksdk (1.7MiB)
2026-09-17 19:19:52.928156 | instance | Downloading rjsonnet (1.2MiB)
2026-09-17 19:19:52.928192 | instance | Downloading cryptography (4.2MiB)
2026-09-17 19:19:52.928228 | instance | Downloading pydantic-core (2.0MiB)
2026-09-17 19:19:52.928263 | instance | Downloading ansible-core (2.1MiB)
2026-09-17 19:19:52.928298 | instance | Downloading kubernetes (1.9MiB)
2026-09-17 19:19:52.928334 | instance | Downloading netaddr (2.2MiB)
2026-09-17 19:19:52.928370 | instance | Downloading pygments (1.2MiB)
2026-09-17 19:19:52.928405 | instance | Downloading setuptools (1.1MiB)
2026-09-17 19:19:52.928440 | instance |    Building pyperclip==1.9.0
2026-09-17 19:19:52.928477 | instance |  Downloading rjsonnet
2026-09-17 19:19:52.928630 | instance |  Downloading pydantic-core
2026-09-17 19:19:52.928681 | instance |  Downloading netaddr
2026-09-17 19:19:52.928718 | instance |  Downloading pygments
2026-09-17 19:19:52.928753 | instance |  Downloading cryptography
2026-09-17 19:19:52.928788 | instance |  Downloading setuptools
2026-09-17 19:19:52.928822 | instance |  Downloading kubernetes
2026-09-17 19:19:52.928857 | instance |  Downloading ansible-core
2026-09-17 19:19:52.928915 | instance |  Downloading openstacksdk
2026-09-17 19:19:52.928954 | instance |       Built pyperclip==1.9.0
2026-09-17 19:19:52.928990 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-17 19:19:52.929024 | instance | Installed 83 packages in 57ms
2026-09-17 19:19:52.929058 | instance | Checked out ansible.posix to 1.6.0
2026-09-17 19:19:52.929092 | 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:19:52.929127 | instance | Starting galaxy collection install process
2026-09-17 19:19:52.929160 | instance | Process install dependency map
2026-09-17 19:19:52.929204 | instance | Starting collection install process
2026-09-17 19:19:52.929240 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-17 19:19:52.929274 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-17 19:19:52.929308 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-17 19:19:52.929341 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:19:52.929375 | instance | 2.17.12
2026-09-17 19:19:52.929408 | instance | Checked out ansible.utils to v6.1.1
2026-09-17 19:19:52.929443 | 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:19:52.929477 | instance | Starting galaxy collection install process
2026-09-17 19:19:52.929511 | instance | Process install dependency map
2026-09-17 19:19:52.929544 | instance | Starting collection install process
2026-09-17 19:19:52.929579 | instance | Installing 'ansible.utils:6.1.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-17 19:19:52.929614 | instance | Created collection for ansible.utils:6.1.1 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-17 19:19:52.929649 | instance | ansible.utils:6.1.1 was installed successfully
2026-09-17 19:19:52.929684 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:19:52.929719 | instance | 2.17.12
2026-09-17 19:19:52.929752 | instance | Checked out atmosphere.common to v0.7.1
2026-09-17 19:19:52.929787 | 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:19:52.929823 | instance | Starting galaxy collection install process
2026-09-17 19:19:52.929858 | instance | Process install dependency map
2026-09-17 19:19:52.929909 | instance | Starting collection install process
2026-09-17 19:19:52.929946 | instance | Installing 'atmosphere.common:0.7.1' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-17 19:19:52.929981 | instance | Created collection for atmosphere.common:0.7.1 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-17 19:19:52.930016 | instance | atmosphere.common:0.7.1 was installed successfully
2026-09-17 19:19:52.930050 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:19:52.930085 | instance | 2.17.12
2026-09-17 19:19:52.930122 | instance | Checked out community.crypto to 2.2.3
2026-09-17 19:19:52.930157 | 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:19:52.930191 | instance | Starting galaxy collection install process
2026-09-17 19:19:52.930226 | instance | Process install dependency map
2026-09-17 19:19:52.930260 | instance | Starting collection install process
2026-09-17 19:19:52.930294 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-17 19:19:52.930329 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-17 19:19:52.930363 | instance | community.crypto:2.2.3 was installed successfully
2026-09-17 19:19:52.930398 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:19:52.930432 | instance | 2.17.12
2026-09-17 19:19:52.930466 | instance | Checked out community.general to 7.3.0
2026-09-17 19:19:52.930500 | 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:19:52.930544 | instance | Starting galaxy collection install process
2026-09-17 19:19:52.930578 | instance | Process install dependency map
2026-09-17 19:19:52.930612 | instance | Starting collection install process
2026-09-17 19:19:52.930646 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-17 19:19:52.930680 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-17 19:19:52.930714 | instance | community.general:7.3.0 was installed successfully
2026-09-17 19:19:52.930749 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:19:52.930783 | instance | 2.17.12
2026-09-17 19:19:52.930818 | instance | Checked out community.mysql to 3.6.0
2026-09-17 19:19:52.930857 | 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:19:52.930913 | instance | Starting galaxy collection install process
2026-09-17 19:19:52.930950 | instance | Process install dependency map
2026-09-17 19:19:52.930986 | instance | Starting collection install process
2026-09-17 19:19:52.931021 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-17 19:19:52.931056 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-17 19:19:52.931091 | instance | community.mysql:3.6.0 was installed successfully
2026-09-17 19:19:52.931125 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:19:52.931159 | instance | 2.17.12
2026-09-17 19:19:52.931193 | instance | Checked out kubernetes.core to 2.4.0
2026-09-17 19:19:52.931229 | 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:19:52.931265 | instance | Starting galaxy collection install process
2026-09-17 19:19:52.931299 | instance | Process install dependency map
2026-09-17 19:19:52.931335 | instance | Starting collection install process
2026-09-17 19:19:52.931370 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-17 19:19:52.931405 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-17 19:19:52.931439 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-17 19:19:52.931473 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:19:52.931506 | instance | 2.17.12
2026-09-17 19:19:52.931540 | instance | Checked out openstack.cloud to 2.6.0
2026-09-17 19:19:52.931575 | 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:19:52.931610 | instance | Starting galaxy collection install process
2026-09-17 19:19:52.931645 | instance | Process install dependency map
2026-09-17 19:19:52.931680 | instance | Starting collection install process
2026-09-17 19:19:52.931714 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-17 19:19:52.931748 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-17 19:19:52.931782 | instance | openstack.cloud:2.6.0 was installed successfully
2026-09-17 19:19:52.931817 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:19:52.931851 | instance | 2.17.12
2026-09-17 19:19:52.931913 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-17 19:19:52.931951 | 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:19:52.931986 | instance | Starting galaxy collection install process
2026-09-17 19:19:52.932020 | instance | Process install dependency map
2026-09-17 19:19:52.932053 | instance | Starting collection install process
2026-09-17 19:19:52.932087 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-17 19:19:52.932121 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-17 19:19:52.932155 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-17 19:19:52.932189 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:19:52.932222 | instance | 2.17.12
2026-09-17 19:19:52.932255 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-17 19:19:52.932289 | 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:19:52.932323 | instance | Starting galaxy collection install process
2026-09-17 19:19:52.932357 | instance | Process install dependency map
2026-09-17 19:19:52.932391 | instance | Starting collection install process
2026-09-17 19:19:52.932424 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-17 19:19:52.932459 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-17 19:19:52.932522 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-17 19:19:52.932582 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:19:52.932621 | instance | 2.17.12
2026-09-17 19:19:52.932656 | instance | Checked out vexxhost.kubernetes to v3.4.0
2026-09-17 19:19:52.932691 | 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:19:52.932726 | instance | Starting galaxy collection install process
2026-09-17 19:19:52.932761 | instance | Process install dependency map
2026-09-17 19:19:52.932795 | instance | Starting collection install process
2026-09-17 19:19:52.932829 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-17 19:19:52.932863 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-17 19:19:52.932921 | instance | vexxhost.kubernetes:3.4.0 was installed successfully
2026-09-17 19:19:52.932958 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-17 19:19:52.932993 | instance | 2.17.12
2026-09-17 19:19:52.944586 | 
2026-09-17 19:19:52.944680 | PLAY RECAP
2026-09-17 19:19:52.944761 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-17 19:19:52.944802 | 
2026-09-17 19:19:53.052253 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-17 19:19:53.056260 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-17 19:19:53.621422 | 
2026-09-17 19:19:53.621531 | PLAY [all]
2026-09-17 19:19:53.631596 | 
2026-09-17 19:19:53.631670 | TASK [Install "jq" for log collection]
2026-09-17 19:20:03.551775 | instance | changed
2026-09-17 19:20:03.554999 | 
2026-09-17 19:20:03.555115 | PLAY RECAP
2026-09-17 19:20:03.555213 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-17 19:20:03.555327 | 
2026-09-17 19:20:03.669297 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-17 19:20:03.673005 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-17 19:20:04.232944 | 
2026-09-17 19:20:04.233067 | PLAY [all]
2026-09-17 19:20:04.248538 | 
2026-09-17 19:20:04.248617 | LOOP [Check inventory directories]
2026-09-17 19:20:04.622664 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-17 19:20:04.813235 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-17 19:20:05.002350 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-17 19:20:05.021680 | 
2026-09-17 19:20:05.021991 | LOOP [Copy inventory file for Zuul]
2026-09-17 19:20:05.822675 | instance | changed:
2026-09-17 19:20:05.822814 | instance | {
2026-09-17 19:20:05.822935 | instance |   "ansible_loop_var": "item",
2026-09-17 19:20:05.822957 | instance |   "changed": false,
2026-09-17 19:20:05.822975 | instance |   "failed": false,
2026-09-17 19:20:05.822994 | instance |   "invocation": {
2026-09-17 19:20:05.823011 | instance |     "module_args": {
2026-09-17 19:20:05.823027 | instance |       "checksum_algorithm": "sha1",
2026-09-17 19:20:05.823044 | instance |       "follow": true,
2026-09-17 19:20:05.823060 | instance |       "get_attributes": true,
2026-09-17 19:20:05.823077 | instance |       "get_checksum": true,
2026-09-17 19:20:05.823093 | instance |       "get_mime": true,
2026-09-17 19:20:05.823108 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-17 19:20:05.823124 | instance |     }
2026-09-17 19:20:05.823140 | instance |   },
2026-09-17 19:20:05.823155 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-17 19:20:05.823171 | instance |   "stat": {
2026-09-17 19:20:05.823186 | instance |     "atime": 1789672785.5515087,
2026-09-17 19:20:05.823202 | instance |     "attr_flags": "e",
2026-09-17 19:20:05.823218 | instance |     "attributes": [
2026-09-17 19:20:05.823233 | instance |       "extents"
2026-09-17 19:20:05.823248 | instance |     ],
2026-09-17 19:20:05.823263 | instance |     "block_size": 4096,
2026-09-17 19:20:05.823278 | instance |     "blocks": 8,
2026-09-17 19:20:05.823293 | instance |     "charset": "unknown",
2026-09-17 19:20:05.823310 | instance |     "ctime": 1789672784.5154848,
2026-09-17 19:20:05.823325 | instance |     "dev": 64513,
2026-09-17 19:20:05.823340 | instance |     "device_type": 0,
2026-09-17 19:20:05.823355 | instance |     "executable": true,
2026-09-17 19:20:05.823370 | instance |     "exists": true,
2026-09-17 19:20:05.823384 | instance |     "gid": 1000,
2026-09-17 19:20:05.823399 | instance |     "gr_name": "zuul",
2026-09-17 19:20:05.823414 | instance |     "inode": 1048503,
2026-09-17 19:20:05.823429 | instance |     "isblk": false,
2026-09-17 19:20:05.823444 | instance |     "ischr": false,
2026-09-17 19:20:05.823458 | instance |     "isdir": true,
2026-09-17 19:20:05.823473 | instance |     "isfifo": false,
2026-09-17 19:20:05.823488 | instance |     "isgid": false,
2026-09-17 19:20:05.823503 | instance |     "islnk": false,
2026-09-17 19:20:05.823518 | instance |     "isreg": false,
2026-09-17 19:20:05.823533 | instance |     "issock": false,
2026-09-17 19:20:05.823548 | instance |     "isuid": false,
2026-09-17 19:20:05.823562 | instance |     "mimetype": "unknown",
2026-09-17 19:20:05.823577 | instance |     "mode": "0755",
2026-09-17 19:20:05.823592 | instance |     "mtime": 1789672784.5154848,
2026-09-17 19:20:05.823606 | instance |     "nlink": 23,
2026-09-17 19:20:05.823621 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-17 19:20:05.823636 | instance |     "pw_name": "zuul",
2026-09-17 19:20:05.823657 | instance |     "readable": true,
2026-09-17 19:20:05.823673 | instance |     "rgrp": true,
2026-09-17 19:20:05.823688 | instance |     "roth": true,
2026-09-17 19:20:05.823715 | instance |     "rusr": true,
2026-09-17 19:20:05.823731 | instance |     "size": 4096,
2026-09-17 19:20:05.823746 | instance |     "uid": 1000,
2026-09-17 19:20:05.823761 | instance |     "version": "2625337118",
2026-09-17 19:20:05.823776 | instance |     "wgrp": false,
2026-09-17 19:20:05.823793 | instance |     "woth": false,
2026-09-17 19:20:05.823808 | instance |     "writeable": true,
2026-09-17 19:20:05.823823 | instance |     "wusr": true,
2026-09-17 19:20:05.823838 | instance |     "xgrp": true,
2026-09-17 19:20:05.823853 | instance |     "xoth": true,
2026-09-17 19:20:05.823868 | instance |     "xusr": true
2026-09-17 19:20:05.823882 | instance |   }
2026-09-17 19:20:05.823897 | instance | }
2026-09-17 19:20:05.823938 | instance | ok: All items complete
2026-09-17 19:20:05.823958 | 
2026-09-17 19:20:05.826566 | instance | skipping: Conditional result was False
2026-09-17 19:20:05.833994 | instance | skipping: Conditional result was False
2026-09-17 19:20:05.847775 | 
2026-09-17 19:20:05.847903 | LOOP [Switch "ansible_host" to private IP]
2026-09-17 19:20:06.183226 | instance | changed: 1 replacements made
2026-09-17 19:20:06.185363 | instance | skipping: Conditional result was False
2026-09-17 19:20:06.191972 | instance | skipping: Conditional result was False
2026-09-17 19:20:06.208842 | 
2026-09-17 19:20:06.208975 | TASK [Run Molecule scenario]
2026-09-17 19:20:07.182898 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-17 19:20:07.609630 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-17 19:20:07.609705 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-17 19:20:19.292071 | instance | INFO     [csi > dependency] Executing
2026-09-17 19:20:19.292456 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-17 19:20:19.292887 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-17 19:20:19.293099 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-17 19:20:19.307975 | instance | INFO     [csi > cleanup] Executing
2026-09-17 19:20:19.308639 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-17 19:20:19.319784 | instance | INFO     [csi > destroy] Executing
2026-09-17 19:20:19.319845 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-17 19:20:19.319944 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-17 19:20:19.329196 | instance | INFO     [csi > syntax] Executing
2026-09-17 19:20:20.885573 | instance |
2026-09-17 19:20:20.885752 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-17 19:20:20.962293 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-17 19:20:20.983271 | instance | INFO     [csi > create] Executing
2026-09-17 19:20:20.986896 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-17 19:20:21.001574 | instance | INFO     [csi > prepare] Executing
2026-09-17 19:20:21.937638 | instance |
2026-09-17 19:20:21.937751 | instance | PLAY [Prepare] *****************************************************************
2026-09-17 19:20:21.938181 | instance |
2026-09-17 19:20:21.938444 | instance | TASK [Gathering Facts] *********************************************************
2026-09-17 19:20:21.938729 | instance | Thursday 17 September 2026  19:20:21 +0000 (0:00:00.034)       0:00:00.034 ****
2026-09-17 19:20:23.268018 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-17 19:20:23.268191 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-17 19:20:23.268485 | instance | interpreter could change the meaning of that path. See
2026-09-17 19:20:23.268802 | instance | https://docs.ansible.com/ansible-
2026-09-17 19:20:23.269062 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-17 19:20:23.278672 | instance | ok: [instance]
2026-09-17 19:20:23.278956 | instance |
2026-09-17 19:20:23.279202 | instance | TASK [Configure short hostname] ************************************************
2026-09-17 19:20:23.279441 | instance | Thursday 17 September 2026  19:20:23 +0000 (0:00:01.342)       0:00:01.376 ****
2026-09-17 19:20:24.082441 | instance | changed: [instance]
2026-09-17 19:20:24.082545 | instance |
2026-09-17 19:20:24.082762 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-17 19:20:24.083015 | instance | Thursday 17 September 2026  19:20:24 +0000 (0:00:00.803)       0:00:02.179 ****
2026-09-17 19:20:24.373864 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-17 19:20:24.374106 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-17 19:20:24.374384 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-17 19:20:24.384304 | instance | changed: [instance]
2026-09-17 19:20:24.384589 | instance |
2026-09-17 19:20:24.384844 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-17 19:20:24.385145 | instance | Thursday 17 September 2026  19:20:24 +0000 (0:00:00.302)       0:00:02.482 ****
2026-09-17 19:20:25.257449 | instance | ok: [instance]
2026-09-17 19:20:25.257727 | instance |
2026-09-17 19:20:25.258034 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-17 19:20:25.258268 | instance |
2026-09-17 19:20:25.258522 | instance | TASK [Gathering Facts] *********************************************************
2026-09-17 19:20:25.258838 | instance | Thursday 17 September 2026  19:20:25 +0000 (0:00:00.873)       0:00:03.355 ****
2026-09-17 19:20:26.025796 | instance | ok: [instance]
2026-09-17 19:20:26.025983 | instance |
2026-09-17 19:20:26.026282 | instance | TASK [Install depedencies] *****************************************************
2026-09-17 19:20:26.026532 | instance | Thursday 17 September 2026  19:20:26 +0000 (0:00:00.767)       0:00:04.122 ****
2026-09-17 19:20:50.676949 | instance | changed: [instance]
2026-09-17 19:20:50.677222 | instance |
2026-09-17 19:20:50.677444 | instance | TASK [Start up service] ********************************************************
2026-09-17 19:20:50.677588 | instance | Thursday 17 September 2026  19:20:50 +0000 (0:00:24.651)       0:00:28.774 ****
2026-09-17 19:20:51.267526 | instance | ok: [instance]
2026-09-17 19:20:51.268245 | instance |
2026-09-17 19:20:51.268303 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-17 19:20:51.268315 | instance | Thursday 17 September 2026  19:20:51 +0000 (0:00:00.590)       0:00:29.365 ****
2026-09-17 19:20:51.610357 | instance | ok: [instance]
2026-09-17 19:20:51.610498 | instance |
2026-09-17 19:20:51.610990 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-17 19:20:51.611042 | instance | Thursday 17 September 2026  19:20:51 +0000 (0:00:00.342)       0:00:29.708 ****
2026-09-17 19:20:52.346532 | instance | changed: [instance]
2026-09-17 19:20:52.347257 | instance |
2026-09-17 19:20:52.347319 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-17 19:20:52.347331 | instance | Thursday 17 September 2026  19:20:52 +0000 (0:00:00.736)       0:00:30.444 ****
2026-09-17 19:20:52.564691 | instance | ok: [instance]
2026-09-17 19:20:52.565160 | instance |
2026-09-17 19:20:52.565563 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-17 19:20:52.565875 | instance | Thursday 17 September 2026  19:20:52 +0000 (0:00:00.217)       0:00:30.662 ****
2026-09-17 19:20:52.594319 | instance | skipping: [instance]
2026-09-17 19:20:52.594575 | instance |
2026-09-17 19:20:52.594987 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-17 19:20:52.595354 | instance | Thursday 17 September 2026  19:20:52 +0000 (0:00:00.029)       0:00:30.691 ****
2026-09-17 19:20:53.189668 | instance | changed: [instance] => (item=osd0)
2026-09-17 19:20:53.190855 | instance | changed: [instance] => (item=osd1)
2026-09-17 19:20:53.190935 | instance | changed: [instance] => (item=osd2)
2026-09-17 19:20:53.190947 | instance |
2026-09-17 19:20:53.190957 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-17 19:20:53.190967 | instance | Thursday 17 September 2026  19:20:53 +0000 (0:00:00.595)       0:00:31.287 ****
2026-09-17 19:20:53.837168 | instance | changed: [instance] => (item=osd0)
2026-09-17 19:20:53.838374 | instance | changed: [instance] => (item=osd1)
2026-09-17 19:20:53.838437 | instance | changed: [instance] => (item=osd2)
2026-09-17 19:20:53.838449 | instance |
2026-09-17 19:20:53.838459 | instance | TASK [Start loop devices] ******************************************************
2026-09-17 19:20:53.838469 | instance | Thursday 17 September 2026  19:20:53 +0000 (0:00:00.647)       0:00:31.934 ****
2026-09-17 19:20:54.637404 | instance | changed: [instance] => (item=osd0)
2026-09-17 19:20:54.637509 | instance | changed: [instance] => (item=osd1)
2026-09-17 19:20:54.638004 | instance | changed: [instance] => (item=osd2)
2026-09-17 19:20:54.638060 | instance |
2026-09-17 19:20:54.638066 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-17 19:20:54.638072 | instance | Thursday 17 September 2026  19:20:54 +0000 (0:00:00.800)       0:00:32.735 ****
2026-09-17 19:20:58.085578 | instance | changed: [instance] => (item=osd0)
2026-09-17 19:20:58.086481 | instance | changed: [instance] => (item=osd1)
2026-09-17 19:20:58.086541 | instance | changed: [instance] => (item=osd2)
2026-09-17 19:20:58.086549 | instance |
2026-09-17 19:20:58.086556 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-17 19:20:58.086563 | instance | Thursday 17 September 2026  19:20:58 +0000 (0:00:03.447)       0:00:36.183 ****
2026-09-17 19:21:00.034751 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-17 19:21:00.036475 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-17 19:21:00.036588 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-17 19:21:00.036600 | instance |
2026-09-17 19:21:00.036610 | instance | PLAY RECAP *********************************************************************
2026-09-17 19:21:00.036622 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-17 19:21:00.036632 | instance |
2026-09-17 19:21:00.036651 | instance | Thursday 17 September 2026  19:21:00 +0000 (0:00:01.949)       0:00:38.133 ****
2026-09-17 19:21:00.040435 | instance | ===============================================================================
2026-09-17 19:21:00.040535 | instance | Install depedencies ---------------------------------------------------- 24.65s
2026-09-17 19:21:00.040548 | instance | Create a volume group for each loop device ------------------------------ 3.45s
2026-09-17 19:21:00.040557 | instance | Create a logical volume for each loop device ---------------------------- 1.95s
2026-09-17 19:21:00.040567 | instance | Gathering Facts --------------------------------------------------------- 1.34s
2026-09-17 19:21:00.040576 | instance | Purge "snapd" package --------------------------------------------------- 0.87s
2026-09-17 19:21:00.040585 | instance | Configure short hostname ------------------------------------------------ 0.80s
2026-09-17 19:21:00.040594 | instance | Start loop devices ------------------------------------------------------ 0.80s
2026-09-17 19:21:00.040603 | instance | Gathering Facts --------------------------------------------------------- 0.77s
2026-09-17 19:21:00.040611 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.74s
2026-09-17 19:21:00.040620 | instance | Set permissions on loopback devices ------------------------------------- 0.65s
2026-09-17 19:21:00.040629 | instance | Create devices for Ceph ------------------------------------------------- 0.60s
2026-09-17 19:21:00.040637 | instance | Start up service -------------------------------------------------------- 0.59s
2026-09-17 19:21:00.040646 | instance | Generate lvm.conf ------------------------------------------------------- 0.34s
2026-09-17 19:21:00.040655 | instance | Ensure hostname inside hosts file --------------------------------------- 0.30s
2026-09-17 19:21:00.040849 | instance | Get list of all loopback devices ---------------------------------------- 0.22s
2026-09-17 19:21:00.041187 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s
2026-09-17 19:21:00.143918 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-17 19:21:00.159968 | instance | INFO     [csi > converge] Executing
2026-09-17 19:21:01.668479 | instance |
2026-09-17 19:21:01.668953 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-17 19:21:01.669305 | instance |
2026-09-17 19:21:01.669706 | instance | TASK [Gathering Facts] *********************************************************
2026-09-17 19:21:01.670222 | instance | Thursday 17 September 2026  19:21:01 +0000 (0:00:00.007)       0:00:00.007 ****
2026-09-17 19:21:02.754686 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-17 19:21:02.755156 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-17 19:21:02.755560 | instance | interpreter could change the meaning of that path. See
2026-09-17 19:21:02.755947 | instance | https://docs.ansible.com/ansible-
2026-09-17 19:21:02.756381 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-17 19:21:02.770650 | instance | ok: [instance]
2026-09-17 19:21:02.771028 | instance |
2026-09-17 19:21:02.771404 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-17 19:21:02.771727 | instance | Thursday 17 September 2026  19:21:02 +0000 (0:00:01.101)       0:00:01.109 ****
2026-09-17 19:21:02.847502 | instance | ok: [instance] => {
2026-09-17 19:21:02.847907 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-17 19:21:02.848255 | instance | }
2026-09-17 19:21:02.848588 | instance |
2026-09-17 19:21:02.848897 | instance | PLAY [all] *********************************************************************
2026-09-17 19:21:02.849267 | instance |
2026-09-17 19:21:02.849583 | instance | TASK [Gathering Facts] *********************************************************
2026-09-17 19:21:02.849917 | instance | Thursday 17 September 2026  19:21:02 +0000 (0:00:00.077)       0:00:01.186 ****
2026-09-17 19:21:03.776736 | instance | ok: [instance]
2026-09-17 19:21:03.776996 | instance |
2026-09-17 19:21:03.777206 | instance | TASK [Fail if atmosphere_ceph_enabled is set] **********************************
2026-09-17 19:21:03.777355 | instance | Thursday 17 September 2026  19:21:03 +0000 (0:00:00.928)       0:00:02.115 ****
2026-09-17 19:21:03.822291 | instance | skipping: [instance]
2026-09-17 19:21:03.822503 | instance |
2026-09-17 19:21:03.822653 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-17 19:21:03.822794 | instance | Thursday 17 September 2026  19:21:03 +0000 (0:00:00.046)       0:00:02.162 ****
2026-09-17 19:21:04.014140 | instance | ok: [instance]
2026-09-17 19:21:04.014532 | instance |
2026-09-17 19:21:04.014865 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-17 19:21:04.015196 | instance |
2026-09-17 19:21:04.015522 | instance | TASK [Gathering Facts] *********************************************************
2026-09-17 19:21:04.015867 | instance | Thursday 17 September 2026  19:21:04 +0000 (0:00:00.191)       0:00:02.353 ****
2026-09-17 19:21:04.993251 | instance | ok: [instance]
2026-09-17 19:21:04.993721 | instance |
2026-09-17 19:21:04.994057 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-17 19:21:04.994395 | instance | Thursday 17 September 2026  19:21:04 +0000 (0:00:00.978)       0:00:03.332 ****
2026-09-17 19:21:05.321207 | instance | ok: [instance]
2026-09-17 19:21:05.321423 | instance |
2026-09-17 19:21:05.321692 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-17 19:21:05.321888 | instance | Thursday 17 September 2026  19:21:05 +0000 (0:00:00.327)       0:00:03.660 ****
2026-09-17 19:21:05.381154 | instance | skipping: [instance]
2026-09-17 19:21:05.381246 | instance |
2026-09-17 19:21:05.381458 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-17 19:21:05.381718 | instance | Thursday 17 September 2026  19:21:05 +0000 (0:00:00.059)       0:00:03.719 ****
2026-09-17 19:21:05.716977 | instance | changed: [instance]
2026-09-17 19:21:05.717069 | instance |
2026-09-17 19:21:05.717168 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-17 19:21:05.717283 | instance | Thursday 17 September 2026  19:21:05 +0000 (0:00:00.337)       0:00:04.056 ****
2026-09-17 19:21:05.821545 | instance | ok: [instance] => {
2026-09-17 19:21:05.821790 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-17 19:21:05.822192 | instance | }
2026-09-17 19:21:05.822491 | instance |
2026-09-17 19:21:05.822804 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-17 19:21:05.823162 | instance | Thursday 17 September 2026  19:21:05 +0000 (0:00:00.102)       0:00:04.159 ****
2026-09-17 19:21:06.589145 | instance | changed: [instance]
2026-09-17 19:21:06.589528 | instance |
2026-09-17 19:21:06.589855 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-17 19:21:06.590190 | instance | Thursday 17 September 2026  19:21:06 +0000 (0:00:00.766)       0:00:04.925 ****
2026-09-17 19:21:06.647214 | instance | skipping: [instance]
2026-09-17 19:21:06.647264 | instance |
2026-09-17 19:21:06.647272 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-17 19:21:06.647279 | instance | Thursday 17 September 2026  19:21:06 +0000 (0:00:00.061)       0:00:04.986 ****
2026-09-17 19:21:06.705866 | instance | skipping: [instance]
2026-09-17 19:21:06.705917 | instance |
2026-09-17 19:21:06.705924 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-17 19:21:06.705931 | instance | Thursday 17 September 2026  19:21:06 +0000 (0:00:00.058)       0:00:05.045 ****
2026-09-17 19:21:06.937221 | instance | ok: [instance]
2026-09-17 19:21:06.937264 | instance |
2026-09-17 19:21:06.937270 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-17 19:21:06.937422 | instance | Thursday 17 September 2026  19:21:06 +0000 (0:00:00.231)       0:00:05.276 ****
2026-09-17 19:21:08.243283 | instance | ok: [instance]
2026-09-17 19:21:08.243486 | instance |
2026-09-17 19:21:08.243756 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-17 19:21:08.244041 | instance | Thursday 17 September 2026  19:21:08 +0000 (0:00:01.305)       0:00:06.582 ****
2026-09-17 19:21:08.337880 | instance | ok: [instance] => {
2026-09-17 19:21:08.338129 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-17 19:21:08.338455 | instance | }
2026-09-17 19:21:08.338734 | instance |
2026-09-17 19:21:08.338995 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-17 19:21:08.339331 | instance | Thursday 17 September 2026  19:21:08 +0000 (0:00:00.094)       0:00:06.676 ****
2026-09-17 19:21:09.175377 | instance | changed: [instance]
2026-09-17 19:21:09.175465 | instance |
2026-09-17 19:21:09.175533 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-17 19:21:09.175649 | instance | Thursday 17 September 2026  19:21:09 +0000 (0:00:00.838)       0:00:07.515 ****
2026-09-17 19:21:12.094181 | instance | changed: [instance]
2026-09-17 19:21:12.094289 | instance |
2026-09-17 19:21:12.094303 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-17 19:21:12.094314 | instance | Thursday 17 September 2026  19:21:12 +0000 (0:00:02.917)       0:00:10.432 ****
2026-09-17 19:21:12.135033 | instance | skipping: [instance]
2026-09-17 19:21:12.135983 | instance |
2026-09-17 19:21:12.136057 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-17 19:21:12.136069 | instance | Thursday 17 September 2026  19:21:12 +0000 (0:00:00.041)       0:00:10.474 ****
2026-09-17 19:21:12.163022 | instance | skipping: [instance]
2026-09-17 19:21:12.163108 | instance |
2026-09-17 19:21:12.163196 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-17 19:21:12.163416 | instance | Thursday 17 September 2026  19:21:12 +0000 (0:00:00.028)       0:00:10.502 ****
2026-09-17 19:21:12.211582 | instance | skipping: [instance]
2026-09-17 19:21:12.211680 | instance |
2026-09-17 19:21:12.211835 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-17 19:21:12.212131 | instance | Thursday 17 September 2026  19:21:12 +0000 (0:00:00.048)       0:00:10.551 ****
2026-09-17 19:21:18.763239 | instance | changed: [instance]
2026-09-17 19:21:18.763522 | instance |
2026-09-17 19:21:18.764027 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-17 19:21:18.764059 | instance | Thursday 17 September 2026  19:21:18 +0000 (0:00:06.551)       0:00:17.102 ****
2026-09-17 19:21:19.427913 | instance | changed: [instance]
2026-09-17 19:21:19.428453 | instance |
2026-09-17 19:21:19.428522 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-17 19:21:19.428723 | instance | Thursday 17 September 2026  19:21:19 +0000 (0:00:00.664)       0:00:17.767 ****
2026-09-17 19:21:20.503517 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-17 19:21:20.504520 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-17 19:21:20.504614 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-17 19:21:20.504637 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-17 19:21:20.504659 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-17 19:21:20.505322 | instance |
2026-09-17 19:21:20.505413 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-17 19:21:20.505428 | instance | Thursday 17 September 2026  19:21:20 +0000 (0:00:01.075)       0:00:18.842 ****
2026-09-17 19:21:21.193542 | instance | changed: [instance]
2026-09-17 19:21:21.193628 | instance |
2026-09-17 19:21:21.194622 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-17 19:21:21.194676 | instance | Thursday 17 September 2026  19:21:21 +0000 (0:00:00.665)       0:00:19.508 ****
2026-09-17 19:21:21.194684 | instance |
2026-09-17 19:21:21.194690 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-17 19:21:21.194696 | instance | Thursday 17 September 2026  19:21:21 +0000 (0:00:00.025)       0:00:19.533 ****
2026-09-17 19:21:22.198056 | instance | ok: [instance]
2026-09-17 19:21:22.198308 | instance |
2026-09-17 19:21:22.198532 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-17 19:21:22.198720 | instance | Thursday 17 September 2026  19:21:22 +0000 (0:00:01.004)       0:00:20.537 ****
2026-09-17 19:21:22.718309 | instance | changed: [instance]
2026-09-17 19:21:22.718495 | instance |
2026-09-17 19:21:22.718595 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-17 19:21:22.718882 | instance | Thursday 17 September 2026  19:21:22 +0000 (0:00:00.519)       0:00:21.057 ****
2026-09-17 19:21:23.370744 | instance | changed: [instance]
2026-09-17 19:21:23.371333 | instance |
2026-09-17 19:21:23.371591 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-17 19:21:23.371603 | instance | Thursday 17 September 2026  19:21:23 +0000 (0:00:00.653)       0:00:21.710 ****
2026-09-17 19:21:23.627906 | instance | ok: [instance]
2026-09-17 19:21:23.628515 | instance |
2026-09-17 19:21:23.628599 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-17 19:21:23.628765 | instance | Thursday 17 September 2026  19:21:23 +0000 (0:00:00.256)       0:00:21.967 ****
2026-09-17 19:21:23.709467 | instance | ok: [instance] => {
2026-09-17 19:21:23.709580 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-17 19:21:23.710645 | instance | }
2026-09-17 19:21:23.710714 | instance |
2026-09-17 19:21:23.710726 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-17 19:21:23.710737 | instance | Thursday 17 September 2026  19:21:23 +0000 (0:00:00.081)       0:00:22.048 ****
2026-09-17 19:21:34.693643 | instance | changed: [instance]
2026-09-17 19:21:34.694693 | instance |
2026-09-17 19:21:34.694766 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-17 19:21:34.694781 | instance | Thursday 17 September 2026  19:21:34 +0000 (0:00:10.984)       0:00:33.032 ****
2026-09-17 19:21:39.164122 | instance | changed: [instance]
2026-09-17 19:21:39.164240 | instance |
2026-09-17 19:21:39.164443 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-17 19:21:39.164534 | instance | Thursday 17 September 2026  19:21:39 +0000 (0:00:04.470)       0:00:37.503 ****
2026-09-17 19:21:40.308478 | instance | ok: [instance]
2026-09-17 19:21:40.308568 | instance |
2026-09-17 19:21:40.308787 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-17 19:21:40.308833 | instance | Thursday 17 September 2026  19:21:40 +0000 (0:00:01.144)       0:00:38.648 ****
2026-09-17 19:21:40.702133 | instance | changed: [instance]
2026-09-17 19:21:40.702311 | instance |
2026-09-17 19:21:40.702958 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-17 19:21:40.703031 | instance | Thursday 17 September 2026  19:21:40 +0000 (0:00:00.392)       0:00:39.041 ****
2026-09-17 19:21:41.227380 | instance | changed: [instance]
2026-09-17 19:21:41.227477 | instance |
2026-09-17 19:21:41.227698 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-17 19:21:41.227743 | instance | Thursday 17 September 2026  19:21:41 +0000 (0:00:00.525)       0:00:39.567 ****
2026-09-17 19:21:41.909097 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-17 19:21:41.910479 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-17 19:21:41.910538 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-17 19:21:41.910550 | instance |
2026-09-17 19:21:41.910559 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-17 19:21:41.910569 | instance | Thursday 17 September 2026  19:21:41 +0000 (0:00:00.681)       0:00:40.248 ****
2026-09-17 19:21:42.402681 | instance | changed: [instance]
2026-09-17 19:21:42.403473 | instance |
2026-09-17 19:21:42.403537 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-17 19:21:42.403549 | instance | Thursday 17 September 2026  19:21:42 +0000 (0:00:00.493)       0:00:40.741 ****
2026-09-17 19:21:42.939026 | instance | changed: [instance]
2026-09-17 19:21:42.939079 | instance |
2026-09-17 19:21:42.939084 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-17 19:21:42.939100 | instance | Thursday 17 September 2026  19:21:42 +0000 (0:00:00.517)       0:00:41.259 ****
2026-09-17 19:21:42.939104 | instance |
2026-09-17 19:21:42.939108 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-17 19:21:42.939118 | instance | Thursday 17 September 2026  19:21:42 +0000 (0:00:00.018)       0:00:41.277 ****
2026-09-17 19:21:43.696117 | instance | ok: [instance]
2026-09-17 19:21:43.696493 | instance |
2026-09-17 19:21:43.696573 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-17 19:21:43.696660 | instance | Thursday 17 September 2026  19:21:43 +0000 (0:00:00.758)       0:00:42.035 ****
2026-09-17 19:21:44.544720 | instance | changed: [instance]
2026-09-17 19:21:44.544921 | instance |
2026-09-17 19:21:44.545184 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-17 19:21:44.545498 | instance | Thursday 17 September 2026  19:21:44 +0000 (0:00:00.847)       0:00:42.883 ****
2026-09-17 19:21:45.268779 | instance | changed: [instance]
2026-09-17 19:21:45.269474 | instance |
2026-09-17 19:21:45.269490 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-17 19:21:45.269617 | instance | Thursday 17 September 2026  19:21:45 +0000 (0:00:00.722)       0:00:43.606 ****
2026-09-17 19:21:45.336238 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-17 19:21:45.336455 | instance |
2026-09-17 19:21:45.336862 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-17 19:21:45.337109 | instance | Thursday 17 September 2026  19:21:45 +0000 (0:00:00.070)       0:00:43.676 ****
2026-09-17 19:21:51.438713 | instance | changed: [instance]
2026-09-17 19:21:51.438895 | instance |
2026-09-17 19:21:51.439246 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-17 19:21:51.439559 | instance | Thursday 17 September 2026  19:21:51 +0000 (0:00:06.101)       0:00:49.777 ****
2026-09-17 19:21:52.144167 | instance | ok: [instance] => (item=chronyd)
2026-09-17 19:21:52.144443 | instance | ok: [instance] => (item=sshd)
2026-09-17 19:21:52.144679 | instance |
2026-09-17 19:21:52.144924 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-17 19:21:52.145230 | instance | Thursday 17 September 2026  19:21:52 +0000 (0:00:00.706)       0:00:50.484 ****
2026-09-17 19:21:57.998719 | instance | changed: [instance]
2026-09-17 19:21:57.998921 | instance |
2026-09-17 19:21:57.999206 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-09-17 19:21:57.999467 | instance | Thursday 17 September 2026  19:21:57 +0000 (0:00:05.853)       0:00:56.337 ****
2026-09-17 19:21:58.242749 | instance | ok: [instance]
2026-09-17 19:21:58.242941 | instance |
2026-09-17 19:21:58.243226 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-09-17 19:21:58.243492 | instance | Thursday 17 September 2026  19:21:58 +0000 (0:00:00.243)       0:00:56.581 ****
2026-09-17 19:21:58.716487 | instance | changed: [instance]
2026-09-17 19:21:58.716703 | instance |
2026-09-17 19:21:58.716990 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-09-17 19:21:58.717248 | instance | Thursday 17 September 2026  19:21:58 +0000 (0:00:00.474)       0:00:57.056 ****
2026-09-17 19:21:59.080422 | instance | changed: [instance]
2026-09-17 19:21:59.080486 | instance |
2026-09-17 19:21:59.080498 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-09-17 19:21:59.080515 | instance | Thursday 17 September 2026  19:21:59 +0000 (0:00:00.363)       0:00:57.419 ****
2026-09-17 19:21:59.212706 | instance | ok: [instance]
2026-09-17 19:21:59.212867 | instance |
2026-09-17 19:21:59.213140 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-09-17 19:21:59.213375 | instance | Thursday 17 September 2026  19:21:59 +0000 (0:00:00.132)       0:00:57.551 ****
2026-09-17 19:21:59.467008 | instance | ok: [instance] => (item=instance)
2026-09-17 19:21:59.467196 | instance |
2026-09-17 19:21:59.467459 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-09-17 19:21:59.467698 | instance | Thursday 17 September 2026  19:21:59 +0000 (0:00:00.254)       0:00:57.806 ****
2026-09-17 19:21:59.513502 | instance | ok: [instance]
2026-09-17 19:21:59.513715 | instance |
2026-09-17 19:21:59.513943 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-09-17 19:21:59.514203 | instance | Thursday 17 September 2026  19:21:59 +0000 (0:00:00.046)       0:00:57.853 ****
2026-09-17 19:21:59.567154 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-09-17 19:21:59.567340 | instance |
2026-09-17 19:21:59.567564 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-09-17 19:21:59.567793 | instance | Thursday 17 September 2026  19:21:59 +0000 (0:00:00.053)       0:00:57.906 ****
2026-09-17 19:21:59.909208 | instance | changed: [instance]
2026-09-17 19:21:59.909425 | instance |
2026-09-17 19:21:59.909685 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-09-17 19:21:59.909930 | instance | Thursday 17 September 2026  19:21:59 +0000 (0:00:00.341)       0:00:58.248 ****
2026-09-17 19:22:00.670993 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-09-17 19:22:00.671054 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-09-17 19:22:00.671179 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-09-17 19:22:00.671294 | instance |
2026-09-17 19:22:00.671407 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-09-17 19:22:00.671517 | instance | Thursday 17 September 2026  19:22:00 +0000 (0:00:00.761)       0:00:59.010 ****
2026-09-17 19:23:32.868762 | instance | ok: [instance]
2026-09-17 19:23:32.868837 | instance |
2026-09-17 19:23:32.868932 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-09-17 19:23:32.869049 | instance | Thursday 17 September 2026  19:23:32 +0000 (0:01:32.198)       0:02:31.208 ****
2026-09-17 19:23:33.118685 | instance | changed: [instance]
2026-09-17 19:23:33.118956 | instance |
2026-09-17 19:23:33.119264 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-09-17 19:23:33.119508 | instance | Thursday 17 September 2026  19:23:33 +0000 (0:00:00.249)       0:02:31.458 ****
2026-09-17 19:23:33.159597 | instance | ok: [instance]
2026-09-17 19:23:33.159821 | instance |
2026-09-17 19:23:33.160099 | instance | TASK [Install Ceph host] *******************************************************
2026-09-17 19:23:33.160407 | instance | Thursday 17 September 2026  19:23:33 +0000 (0:00:00.040)       0:02:31.499 ****
2026-09-17 19:23:33.271581 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-09-17 19:23:33.271952 | instance |
2026-09-17 19:23:33.272406 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-09-17 19:23:33.272815 | instance | Thursday 17 September 2026  19:23:33 +0000 (0:00:00.112)       0:02:31.611 ****
2026-09-17 19:23:34.838503 | instance | ok: [instance]
2026-09-17 19:23:34.838563 | instance |
2026-09-17 19:23:34.838713 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-09-17 19:23:34.838813 | instance | Thursday 17 September 2026  19:23:34 +0000 (0:00:01.567)       0:02:33.178 ****
2026-09-17 19:23:34.910835 | instance | ok: [instance] => (item=instance)
2026-09-17 19:23:34.911003 | instance |
2026-09-17 19:23:34.911254 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-09-17 19:23:34.911450 | instance | Thursday 17 September 2026  19:23:34 +0000 (0:00:00.072)       0:02:33.250 ****
2026-09-17 19:23:35.323475 | instance | ok: [instance]
2026-09-17 19:23:35.323571 | instance |
2026-09-17 19:23:35.323770 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-09-17 19:23:35.323950 | instance | Thursday 17 September 2026  19:23:35 +0000 (0:00:00.412)       0:02:33.663 ****
2026-09-17 19:23:37.830332 | instance | ok: [instance]
2026-09-17 19:23:37.830415 | instance |
2026-09-17 19:23:37.830430 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-09-17 19:23:37.830441 | instance | Thursday 17 September 2026  19:23:37 +0000 (0:00:02.505)       0:02:36.168 ****
2026-09-17 19:24:19.793753 | 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:24:19.794631 | 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_j24o_0of/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_j24o_0of/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_j24o_0of/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_j24o_0of/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:24:19.433+0000 7f605361d640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAKPqxqUPTFJiAAkJrSChiCgfcgKCJnnFf7iMf3ncprJhaf4Tqp6WE8Or8=: Malformed input [buffer:3]\n2026-09-17T19:24:19.433+0000 7f605361d640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-17T19:24:19.433+0000 7f605361d640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAKPqxqUPTFJiAAkJrSChiCgfcgKCJnnFf7iMf3ncprJhaf4Tqp6WE8Or8=: Malformed input [buffer:3]\n2026-09-17T19:24:19.433+0000 7f605361d640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-17T19:24:19.433+0000 7f605361d640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAKPqxqUPTFJiAAkJrSChiCgfcgKCJnnFf7iMf3ncprJhaf4Tqp6WE8Or8=: Malformed input [buffer:3]\n2026-09-17T19:24:19.433+0000 7f605361d640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-17T19:24:19.433+0000 7f605361d640 -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:24:19.797544 | instance |
2026-09-17 19:24:19.800519 | instance | PLAY RECAP *********************************************************************
2026-09-17 19:24:19.800594 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=7    rescued=0    ignored=0
2026-09-17 19:24:19.800611 | instance |
2026-09-17 19:24:19.800624 | instance | Thursday 17 September 2026  19:24:19 +0000 (0:00:41.969)       0:03:18.137 ****
2026-09-17 19:24:19.800636 | instance | ===============================================================================
2026-09-17 19:24:19.800648 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 92.20s
2026-09-17 19:24:19.800659 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 41.97s
2026-09-17 19:24:19.800672 | instance | vexxhost.containers.download_artifact : Download item ------------------ 10.98s
2026-09-17 19:24:19.800685 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 6.55s
2026-09-17 19:24:19.800697 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 6.10s
2026-09-17 19:24:19.800708 | instance | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 5.85s
2026-09-17 19:24:19.800749 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.47s
2026-09-17 19:24:19.804059 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.92s
2026-09-17 19:24:19.804096 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.51s
2026-09-17 19:24:19.804111 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.76s
2026-09-17 19:24:19.804123 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.57s
2026-09-17 19:24:19.804135 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.31s
2026-09-17 19:24:19.804174 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.14s
2026-09-17 19:24:19.804186 | instance | Gathering Facts --------------------------------------------------------- 1.10s
2026-09-17 19:24:19.804197 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.08s
2026-09-17 19:24:19.804205 | instance | Gathering Facts --------------------------------------------------------- 0.98s
2026-09-17 19:24:19.804216 | instance | Gathering Facts --------------------------------------------------------- 0.93s
2026-09-17 19:24:19.804225 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.85s
2026-09-17 19:24:19.804235 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.84s
2026-09-17 19:24:19.804245 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.77s
2026-09-17 19:24:19.913617 | 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:24:19.913941 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-17 19:24:19.914168 | 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:24:20.108655 | instance | ERROR
2026-09-17 19:24:20.108911 | instance | {
2026-09-17 19:24:20.108968 | instance |   "delta": "0:04:13.392425",
2026-09-17 19:24:20.109008 | instance |   "end": "2026-09-17 19:24:19.983530",
2026-09-17 19:24:20.109044 | instance |   "msg": "non-zero return code",
2026-09-17 19:24:20.109078 | instance |   "rc": 2,
2026-09-17 19:24:20.109112 | instance |   "start": "2026-09-17 19:20:06.591105"
2026-09-17 19:24:20.109148 | instance | }
failure
2026-09-17 19:24:20.120554 | 
2026-09-17 19:24:20.120673 | PLAY RECAP
2026-09-17 19:24:20.120890 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-17 19:24:20.120956 | 
2026-09-17 19:24:20.285693 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-17 19:24:20.290068 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-17 19:24:20.851034 | 
2026-09-17 19:24:20.851138 | PLAY [all]
2026-09-17 19:24:20.864065 | 
2026-09-17 19:24:20.864150 | TASK [gather-host-logs : creating directory for system status]
2026-09-17 19:24:21.220801 | instance | changed
2026-09-17 19:24:21.230872 | 
2026-09-17 19:24:21.231023 | TASK [gather-host-logs : Get logs for each host]
2026-09-17 19:24:21.607992 | instance | + systemd-cgls --full --all --no-pager
2026-09-17 19:24:21.625261 | instance | + ip addr
2026-09-17 19:24:21.627889 | instance | + ip route
2026-09-17 19:24:21.630836 | instance | + lsblk
2026-09-17 19:24:21.636792 | instance | + mount
2026-09-17 19:24:21.640732 | instance | + docker images
2026-09-17 19:24:21.665936 | instance | + brctl show
2026-09-17 19:24:21.666581 | instance | /bin/bash: line 8: brctl: command not found
2026-09-17 19:24:21.666859 | instance | + ip neigh
2026-09-17 19:24:21.669455 | instance | + bridge fdb show
2026-09-17 19:24:21.672679 | instance | + ps aux --sort=-%mem
2026-09-17 19:24:21.693629 | instance | + dpkg -l
2026-09-17 19:24:21.705720 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-17 19:24:21.706246 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-17 19:24:21.730062 | instance | + '[' '!' -z '' ']'
2026-09-17 19:24:21.788046 | instance | ok: Runtime: 0:00:00.127255
2026-09-17 19:24:21.798496 | 
2026-09-17 19:24:21.798616 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-17 19:24:22.450099 | instance | changed:
2026-09-17 19:24:22.450373 | instance | created directory /var/lib/zuul/builds/1531ccc2d48e432ba42f46aa06d51029/work/logs/instance
2026-09-17 19:24:22.450439 | instance | cd+++++++++ system/
2026-09-17 19:24:22.450529 | instance | >f+++++++++ system/brctl-show.txt
2026-09-17 19:24:22.450608 | instance | >f+++++++++ system/bridge-fdb.txt
2026-09-17 19:24:22.450652 | instance | >f+++++++++ system/docker-images.txt
2026-09-17 19:24:22.450695 | instance | >f+++++++++ system/ip-addr.txt
2026-09-17 19:24:22.450734 | instance | >f+++++++++ system/ip-neigh.txt
2026-09-17 19:24:22.450772 | instance | >f+++++++++ system/ip-route.txt
2026-09-17 19:24:22.450812 | instance | >f+++++++++ system/lsblk.txt
2026-09-17 19:24:22.450848 | instance | >f+++++++++ system/mount.txt
2026-09-17 19:24:22.450883 | instance | >f+++++++++ system/packages.txt
2026-09-17 19:24:22.450918 | instance | >f+++++++++ system/ps.txt
2026-09-17 19:24:22.450952 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-17 19:24:22.469492 | 
2026-09-17 19:24:22.469621 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-17 19:24:22.697106 | instance | changed: "values"
2026-09-17 19:24:22.892041 | instance | changed: "releases"
2026-09-17 19:24:22.912850 | 
2026-09-17 19:24:22.912980 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-17 19:24:23.174358 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-17 19:24:23.447253 | instance | ok: Runtime: 0:00:00.006734
2026-09-17 19:24:23.451265 | 
2026-09-17 19:24:23.451338 | TASK [helm-release-status : Downloads logs to executor]
2026-09-17 19:24:24.006666 | instance | changed:
2026-09-17 19:24:24.006810 | instance | cd+++++++++ helm/
2026-09-17 19:24:24.006836 | instance | cd+++++++++ helm/releases/
2026-09-17 19:24:24.006856 | instance | cd+++++++++ helm/values/
2026-09-17 19:24:24.014660 | 
2026-09-17 19:24:24.014762 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-17 19:24:24.244379 | instance | changed
2026-09-17 19:24:24.249373 | 
2026-09-17 19:24:24.249444 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-17 19:24:24.535781 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args valuewarning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-17 19:24:24.535900 | instance |
2026-09-17 19:24:24.541291 | instance | environment: line 1: kubectl: command not found
2026-09-17 19:24:24.542632 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-17 19:24:24.544024 | instance | environment: line 1: kubectl: command not found
2026-09-17 19:24:24.545393 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-17 19:24:24.545987 | instance | environment: line 1: kubectl: command not found
2026-09-17 19:24:24.548313 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-17 19:24:24.549934 | instance | environment: line 1: kubectl: command not found
2026-09-17 19:24:24.551187 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-17 19:24:24.552428 | instance | environment: line 1: kubectl: command not found
2026-09-17 19:24:24.554037 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-17 19:24:24.800405 | instance | ok: Runtime: 0:00:00.027847
2026-09-17 19:24:24.811259 | 
2026-09-17 19:24:24.811600 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-17 19:24:25.022007 | instance | changed
2026-09-17 19:24:25.028205 | 
2026-09-17 19:24:25.028287 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-17 19:24:25.250232 | instance | environment: line 5: kubectl: command not found
2026-09-17 19:24:25.250830 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-17 19:24:25.250862 | instance | warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-17 19:24:25.251187 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-17 19:24:25.570807 | instance | ok: Runtime: 0:00:00.011313
2026-09-17 19:24:25.582738 | 
2026-09-17 19:24:25.582872 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-17 19:24:26.129464 | instance | changed:
2026-09-17 19:24:26.129607 | instance | cd+++++++++ objects/
2026-09-17 19:24:26.129635 | instance | cd+++++++++ objects/cluster/
2026-09-17 19:24:26.129654 | instance | cd+++++++++ objects/namespaced/
2026-09-17 19:24:26.137944 | 
2026-09-17 19:24:26.138004 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-17 19:24:26.373114 | instance | changed
2026-09-17 19:24:26.378810 | 
2026-09-17 19:24:26.387430 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-17 19:24:26.615316 | instance | changed
2026-09-17 19:24:26.625798 | 
2026-09-17 19:24:26.625951 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-17 19:24:26.886796 | instance | environment: line 3: kubectl: command not found
2026-09-17 19:24:27.199468 | instance | ok: Runtime: 0:00:00.011457
2026-09-17 19:24:27.205163 | 
2026-09-17 19:24:27.205247 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-17 19:24:27.778655 | instance | changed:
2026-09-17 19:24:27.779412 | instance | cd+++++++++ pod-logs/
2026-09-17 19:24:27.779479 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-17 19:24:27.807999 | 
2026-09-17 19:24:27.808116 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-17 19:24:28.019507 | instance | changed
2026-09-17 19:24:28.025980 | 
2026-09-17 19:24:28.026060 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-17 19:24:28.274513 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-17 19:24:28.566087 | instance | ok: Runtime: 0:00:00.054402
2026-09-17 19:24:28.575263 | 
2026-09-17 19:24:28.575360 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-17 19:24:28.819279 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-17 19:24:28.863770 | instance | ceph-mgr endpoints:
2026-09-17 19:24:29.110482 | instance | ok: Runtime: 0:00:00.051995
2026-09-17 19:24:29.120780 | 
2026-09-17 19:24:29.120920 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-17 19:24:29.389746 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-17 19:24:29.687908 | instance | ok: Runtime: 0:00:00.039736
2026-09-17 19:24:29.699482 | 
2026-09-17 19:24:29.699657 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-17 19:24:30.313490 | instance | changed: cd+++++++++ prometheus/
2026-09-17 19:24:30.331562 | 
2026-09-17 19:24:30.331692 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-17 19:24:30.562563 | instance | changed
2026-09-17 19:24:30.573660 | 
2026-09-17 19:24:30.581334 | TASK [gather-selenium-data : Get selenium data]
2026-09-17 19:24:30.883126 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-17 19:24:30.884605 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-17 19:24:31.140152 | instance | ERROR
2026-09-17 19:24:31.145098 | instance | {
2026-09-17 19:24:31.147709 | instance |   "delta": "0:00:00.007384",
2026-09-17 19:24:31.147788 | instance |   "end": "2026-09-17 19:24:30.885045",
2026-09-17 19:24:31.147832 | instance |   "msg": "non-zero return code",
2026-09-17 19:24:31.147869 | instance |   "rc": 1,
2026-09-17 19:24:31.147905 | instance |   "start": "2026-09-17 19:24:30.877661"
2026-09-17 19:24:31.147941 | instance | }
2026-09-17 19:24:31.148004 | instance | ERROR: Ignoring Errors
2026-09-17 19:24:31.162837 | 
2026-09-17 19:24:31.165638 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-17 19:24:31.709778 | instance | changed: cd+++++++++ selenium/
2026-09-17 19:24:31.715587 | 
2026-09-17 19:24:31.715647 | PLAY RECAP
2026-09-17 19:24:31.715699 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-17 19:24:31.715721 | 
2026-09-17 19:24:31.888031 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-17 19:24:31.891850 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-17 19:24:32.497914 | 
2026-09-17 19:24:32.498160 | PLAY [all]
2026-09-17 19:24:32.515920 | 
2026-09-17 19:24:32.521242 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-17 19:24:32.566692 | instance | skipping: Conditional result was False
2026-09-17 19:24:32.576815 | 
2026-09-17 19:24:32.577286 | TASK [fetch-output : Set log path for single node]
2026-09-17 19:24:32.621790 | instance | ok
2026-09-17 19:24:32.631481 | 
2026-09-17 19:24:32.631623 | LOOP [fetch-output : Ensure local output dirs]
2026-09-17 19:24:33.137850 | instance -> localhost | ok: "/var/lib/zuul/builds/1531ccc2d48e432ba42f46aa06d51029/work/logs"
2026-09-17 19:24:33.423862 | instance -> localhost | changed: "/var/lib/zuul/builds/1531ccc2d48e432ba42f46aa06d51029/work/artifacts"
2026-09-17 19:24:33.697102 | instance -> localhost | changed: "/var/lib/zuul/builds/1531ccc2d48e432ba42f46aa06d51029/work/docs"
2026-09-17 19:24:33.716717 | 
2026-09-17 19:24:33.741399 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-17 19:24:34.461202 | instance | changed: .d..t...... ./
2026-09-17 19:24:34.461759 | instance | changed: All items complete
2026-09-17 19:24:34.461788 | 
2026-09-17 19:24:34.956519 | instance | changed: .d..t...... ./
2026-09-17 19:24:35.478528 | instance | changed: .d..t...... ./
2026-09-17 19:24:35.502099 | 
2026-09-17 19:24:35.533838 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-17 19:24:36.018615 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008278
2026-09-17 19:24:36.269059 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007668
2026-09-17 19:24:36.289563 | 
2026-09-17 19:24:36.289720 | PLAY [all]
2026-09-17 19:24:36.300329 | 
2026-09-17 19:24:36.300455 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-17 19:24:36.743466 | instance | changed
2026-09-17 19:24:36.753124 | 
2026-09-17 19:24:36.753219 | PLAY RECAP
2026-09-17 19:24:36.753300 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-17 19:24:36.753376 | 
2026-09-17 19:24:36.861517 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-17 19:24:36.864966 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-17 19:24:37.431508 | 
2026-09-17 19:24:37.431780 | PLAY [localhost]
2026-09-17 19:24:37.441917 | 
2026-09-17 19:24:37.441987 | TASK [Generate Zuul manifest]
2026-09-17 19:24:37.459337 | localhost | ok
2026-09-17 19:24:37.475613 | 
2026-09-17 19:24:37.475714 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-17 19:24:37.802337 | localhost | changed
2026-09-17 19:24:37.822463 | 
2026-09-17 19:24:37.822622 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-17 19:24:37.855028 | localhost | ok
2026-09-17 19:24:37.868023 | 
2026-09-17 19:24:37.868154 | TASK [Upload logs]
2026-09-17 19:24:37.893783 | localhost | ok
2026-09-17 19:24:37.961805 | 
2026-09-17 19:24:37.961936 | TASK [Set zuul-log-path fact]
2026-09-17 19:24:37.982260 | localhost | ok
2026-09-17 19:24:37.994558 | 
2026-09-17 19:24:37.994760 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-17 19:24:38.034783 | localhost | ok
2026-09-17 19:24:38.044777 | 
2026-09-17 19:24:38.047399 | TASK [upload-logs : Create log directories]
2026-09-17 19:24:38.505943 | localhost | changed
2026-09-17 19:24:38.521786 | 
2026-09-17 19:24:38.521934 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-17 19:24:38.948466 | localhost -> localhost | ok: Runtime: 0:00:00.004420
2026-09-17 19:24:38.957390 | 
2026-09-17 19:24:38.957514 | TASK [upload-logs : Upload logs to log server]
2026-09-17 19:24:39.436519 | localhost | Output suppressed because no_log was given
2026-09-17 19:24:39.480958 | 
2026-09-17 19:24:39.481090 | LOOP [upload-logs : Compress console log and json output]
2026-09-17 19:24:39.549966 | localhost | skipping: Conditional result was False
2026-09-17 19:24:39.558482 | localhost | skipping: Conditional result was False
2026-09-17 19:24:39.567097 | 
2026-09-17 19:24:39.567245 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-17 19:24:39.626915 | localhost | skipping: Conditional result was False
2026-09-17 19:24:39.627255 | 
2026-09-17 19:24:39.633379 | localhost | skipping: Conditional result was False
2026-09-17 19:24:39.645289 | 
2026-09-17 19:24:39.645564 | LOOP [upload-logs : Upload console log and json output]