2026-09-16 15:46:31.722521 | Job console starting
2026-09-16 15:46:31.734193 | Updating git repos
2026-09-16 15:46:35.696411 | Cloning repos into workspace
2026-09-16 15:46:53.059412 | Restoring repo states
2026-09-16 15:47:08.184233 | Merging changes
2026-09-16 15:47:09.713144 | Checking out repos
2026-09-16 15:47:12.849398 | Preparing playbooks
2026-09-16 15:47:17.004944 | Running Ansible setup
2026-09-16 15:47:20.655776 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-16 15:47:21.237793 | 
2026-09-16 15:47:21.237935 | PLAY [localhost]
2026-09-16 15:47:21.245234 | 
2026-09-16 15:47:21.245302 | TASK [Gathering Facts]
2026-09-16 15:47:22.122313 | localhost | ok
2026-09-16 15:47:22.131794 | 
2026-09-16 15:47:22.131892 | TASK [Setup log path fact]
2026-09-16 15:47:22.150417 | localhost | ok
2026-09-16 15:47:22.163648 | 
2026-09-16 15:47:22.163783 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-16 15:47:22.203033 | localhost | ok
2026-09-16 15:47:22.213907 | 
2026-09-16 15:47:22.214039 | TASK [emit-job-header : Print job information]
2026-09-16 15:47:22.265214 | # Job Information
2026-09-16 15:47:22.265422 | Ansible Version: 2.16.18
2026-09-16 15:47:22.265491 | Job: atmosphere-molecule-csi-rbd
2026-09-16 15:47:22.265535 | Pipeline: check
2026-09-16 15:47:22.265601 | Executor: 2d72f0692154
2026-09-16 15:47:22.265639 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4373
2026-09-16 15:47:22.265684 | Event ID: 32266440-b1e5-11f1-98e4-98ea9388f62f
2026-09-16 15:47:22.270838 | 
2026-09-16 15:47:22.270964 | LOOP [emit-job-header : Print node information]
2026-09-16 15:47:22.375028 | localhost | ok:
2026-09-16 15:47:22.375291 | localhost | # Node Information
2026-09-16 15:47:22.375349 | localhost | Inventory Hostname: instance
2026-09-16 15:47:22.375392 | localhost | Hostname: np0000192911
2026-09-16 15:47:22.375432 | localhost | Username: zuul
2026-09-16 15:47:22.375476 | localhost | Distro: Ubuntu 22.04
2026-09-16 15:47:22.375514 | localhost | Provider: yul1
2026-09-16 15:47:22.375572 | localhost | Region: ca-ymq-1
2026-09-16 15:47:22.375610 | localhost | Label: ubuntu-jammy
2026-09-16 15:47:22.375646 | localhost | Product Name: OpenStack Nova
2026-09-16 15:47:22.375682 | localhost | Interface IP: 162.253.55.241
2026-09-16 15:47:22.394694 | 
2026-09-16 15:47:22.394936 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-16 15:47:22.778523 | localhost -> localhost | changed
2026-09-16 15:47:22.788282 | 
2026-09-16 15:47:22.788417 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-16 15:47:23.659939 | localhost -> localhost | changed
2026-09-16 15:47:23.670684 | 
2026-09-16 15:47:23.670806 | PLAY [all]
2026-09-16 15:47:23.687449 | 
2026-09-16 15:47:23.687623 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-16 15:47:23.935280 | instance -> localhost | ok
2026-09-16 15:47:23.940532 | 
2026-09-16 15:47:23.940612 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-16 15:47:23.967591 | instance | ok
2026-09-16 15:47:23.979386 | instance | included: /var/lib/zuul/builds/b45ce1904bfc42e5a414a0f9c0b64d61/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-16 15:47:23.984570 | 
2026-09-16 15:47:23.984641 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-16 15:47:25.114735 | instance -> localhost | Generating public/private rsa key pair.
2026-09-16 15:47:25.114999 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/b45ce1904bfc42e5a414a0f9c0b64d61/work/b45ce1904bfc42e5a414a0f9c0b64d61_id_rsa
2026-09-16 15:47:25.115079 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/b45ce1904bfc42e5a414a0f9c0b64d61/work/b45ce1904bfc42e5a414a0f9c0b64d61_id_rsa.pub
2026-09-16 15:47:25.115131 | instance -> localhost | The key fingerprint is:
2026-09-16 15:47:25.115175 | instance -> localhost | SHA256:8b19eDuX9ieWlMQZ7MXtnO448h6qBs7KbEK9lcJQrtU zuul-build-sshkey
2026-09-16 15:47:25.115239 | instance -> localhost | The key's randomart image is:
2026-09-16 15:47:25.115282 | instance -> localhost | +---[RSA 3072]----+
2026-09-16 15:47:25.115335 | instance -> localhost | |             . ..|
2026-09-16 15:47:25.115381 | instance -> localhost | |     .        o +|
2026-09-16 15:47:25.115423 | instance -> localhost | |    o . .    o *.|
2026-09-16 15:47:25.115464 | instance -> localhost | |   . o E o .  =.o|
2026-09-16 15:47:25.115503 | instance -> localhost | |    *   S . .... |
2026-09-16 15:47:25.115565 | instance -> localhost | |   o + +     ooo |
2026-09-16 15:47:25.115607 | instance -> localhost | |  .   * .   .o=.+|
2026-09-16 15:47:25.115646 | instance -> localhost | |   .oo o . ..o=B+|
2026-09-16 15:47:25.115691 | instance -> localhost | |    o+. ....++oo*|
2026-09-16 15:47:25.115732 | instance -> localhost | +----[SHA256]-----+
2026-09-16 15:47:25.115825 | instance -> localhost | ok: Runtime: 0:00:00.709472
2026-09-16 15:47:25.130625 | 
2026-09-16 15:47:25.130856 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-16 15:47:25.152444 | instance | ok
2026-09-16 15:47:25.163363 | instance | included: /var/lib/zuul/builds/b45ce1904bfc42e5a414a0f9c0b64d61/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-16 15:47:25.172115 | 
2026-09-16 15:47:25.172205 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-16 15:47:25.196014 | instance | skipping: Conditional result was False
2026-09-16 15:47:25.205857 | 
2026-09-16 15:47:25.206010 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-16 15:47:25.706874 | instance | changed
2026-09-16 15:47:25.721396 | 
2026-09-16 15:47:25.721626 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-16 15:47:25.926257 | instance | ok
2026-09-16 15:47:25.935787 | 
2026-09-16 15:47:25.935913 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-16 15:47:26.468798 | instance | changed
2026-09-16 15:47:26.479251 | 
2026-09-16 15:47:26.479394 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-16 15:47:27.012474 | instance | changed
2026-09-16 15:47:27.027754 | 
2026-09-16 15:47:27.027893 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-16 15:47:27.044911 | instance | skipping: Conditional result was False
2026-09-16 15:47:27.051033 | 
2026-09-16 15:47:27.051106 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-16 15:47:27.481555 | instance -> localhost | changed
2026-09-16 15:47:27.495462 | 
2026-09-16 15:47:27.495596 | TASK [add-build-sshkey : Add back temp key]
2026-09-16 15:47:27.815103 | instance -> localhost | Identity added: /var/lib/zuul/builds/b45ce1904bfc42e5a414a0f9c0b64d61/work/b45ce1904bfc42e5a414a0f9c0b64d61_id_rsa (zuul-build-sshkey)
2026-09-16 15:47:27.815426 | instance -> localhost | ok: Runtime: 0:00:00.015773
2026-09-16 15:47:27.825867 | 
2026-09-16 15:47:27.826001 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-16 15:47:28.204130 | instance | ok
2026-09-16 15:47:28.216834 | 
2026-09-16 15:47:28.217060 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-16 15:47:28.252446 | instance | skipping: Conditional result was False
2026-09-16 15:47:28.272690 | 
2026-09-16 15:47:28.272830 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-16 15:47:28.641774 | instance | ok
2026-09-16 15:47:28.648914 | 
2026-09-16 15:47:28.649025 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-16 15:47:32.427835 | instance | Output suppressed because no_log was given
2026-09-16 15:47:32.444133 | 
2026-09-16 15:47:32.444258 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-16 15:47:32.646272 | instance | ok: "logs"
2026-09-16 15:47:32.646551 | instance | ok: All items complete
2026-09-16 15:47:32.646588 | 
2026-09-16 15:47:32.803261 | instance | ok: "artifacts"
2026-09-16 15:47:33.007450 | instance | ok: "docs"
2026-09-16 15:47:33.027157 | 
2026-09-16 15:47:33.027390 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-16 15:47:33.259810 | instance | changed: "logs"
2026-09-16 15:47:33.436415 | instance | changed: "artifacts"
2026-09-16 15:47:33.591378 | instance | changed: "docs"
2026-09-16 15:47:33.612235 | 
2026-09-16 15:47:33.612379 | PLAY RECAP
2026-09-16 15:47:33.612460 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-16 15:47:33.612581 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-16 15:47:33.612645 | 
2026-09-16 15:47:33.778613 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-16 15:47:33.782065 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-16 15:47:34.308633 | 
2026-09-16 15:47:34.308763 | PLAY [all]
2026-09-16 15:47:34.318688 | 
2026-09-16 15:47:34.318771 | TASK [setup-uv : Extract archive]
2026-09-16 15:47:36.610993 | instance | changed
2026-09-16 15:47:36.618460 | 
2026-09-16 15:47:36.618578 | TASK [setup-uv : Print version]
2026-09-16 15:47:37.020185 | instance | uv 0.8.13
2026-09-16 15:47:37.154988 | instance | ok: Runtime: 0:00:00.014170
2026-09-16 15:47:37.169640 | 
2026-09-16 15:47:37.169797 | TASK [Install Ansible collection siblings]
2026-09-16 15:47:48.902469 | instance | changed:
2026-09-16 15:47:48.902768 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-16 15:47:48.902828 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-16 15:47:48.902872 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-16 15:47:48.902911 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-16 15:47:48.902950 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-16 15:47:48.902992 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-16 15:47:48.903031 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-16 15:47:48.903081 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-16 15:47:48.903141 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-16 15:47:48.903201 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-16 15:47:48.903246 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-16 15:47:48.903283 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-16 15:47:48.903320 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-09-16 15:47:48.903357 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-16 15:47:48.903394 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0
2026-09-16 15:47:48.903432 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-16 15:47:48.903469 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-16 15:47:48.903535 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-16 15:47:48.903573 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-16 15:47:48.903610 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-09-16 15:47:48.903647 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0
2026-09-16 15:47:48.903683 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-09-16 15:47:48.903722 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-16 15:47:48.903779 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-16 15:47:48.903818 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0
2026-09-16 15:47:48.903854 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-16 15:47:48.903889 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-16 15:47:48.903925 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0
2026-09-16 15:47:48.904038 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-16 15:47:48.904079 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-16 15:47:48.904115 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-16 15:47:48.904152 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-16 15:47:48.904189 | instance | Checked out ansible.netcommon to 1.2.0
2026-09-16 15:47:48.904280 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-16 15:47:48.904335 | instance | Starting galaxy collection install process
2026-09-16 15:47:48.904371 | instance | Process install dependency map
2026-09-16 15:47:48.904408 | instance | Starting collection install process
2026-09-16 15:47:48.904584 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-16 15:47:48.904641 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-16 15:47:48.904680 | instance | ansible.netcommon:* was installed successfully
2026-09-16 15:47:48.904774 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-16 15:47:48.904834 | instance | Creating virtual environment at: .venv
2026-09-16 15:47:48.904888 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-16 15:47:48.905015 | instance | Downloading ansible-core (2.1MiB)
2026-09-16 15:47:48.905055 | instance | Downloading openstacksdk (1.7MiB)
2026-09-16 15:47:48.905099 | instance | Downloading kubernetes (1.9MiB)
2026-09-16 15:47:48.905136 | instance | Downloading pydantic-core (2.0MiB)
2026-09-16 15:47:48.905232 | instance | Downloading cryptography (4.2MiB)
2026-09-16 15:47:48.905271 | instance | Downloading pygments (1.2MiB)
2026-09-16 15:47:48.905307 | instance | Downloading netaddr (2.2MiB)
2026-09-16 15:47:48.905343 | instance | Downloading setuptools (1.1MiB)
2026-09-16 15:47:48.905430 | instance | Downloading rjsonnet (1.2MiB)
2026-09-16 15:47:48.905467 | instance | Downloading rjsonnet (1.2MiB)
2026-09-16 15:47:48.905526 | instance | Downloading cryptography (4.2MiB)
2026-09-16 15:47:48.905564 | instance | Downloading kubernetes (1.9MiB)
2026-09-16 15:47:48.905652 | instance | Downloading pygments (1.2MiB)
2026-09-16 15:47:48.905693 | instance | Downloading setuptools (1.1MiB)
2026-09-16 15:47:48.905728 | instance | Downloading openstacksdk (1.7MiB)
2026-09-16 15:47:48.905763 | instance | Downloading pydantic-core (2.0MiB)
2026-09-16 15:47:48.905850 | instance | Downloading ansible-core (2.1MiB)
2026-09-16 15:47:48.905888 | instance | Downloading netaddr (2.2MiB)
2026-09-16 15:47:48.905923 | instance |  Downloading rjsonnet
2026-09-16 15:47:48.906005 | instance |  Downloading pydantic-core
2026-09-16 15:47:48.906051 | instance |  Downloading netaddr
2026-09-16 15:47:48.906131 | instance |  Downloading cryptography
2026-09-16 15:47:48.906174 | instance |  Downloading pygments
2026-09-16 15:47:48.906209 | instance |  Downloading setuptools
2026-09-16 15:47:48.906244 | instance |  Downloading kubernetes
2026-09-16 15:47:48.906278 | instance |  Downloading ansible-core
2026-09-16 15:47:48.906311 | instance |  Downloading openstacksdk
2026-09-16 15:47:48.906345 | instance |    Building pyperclip==1.9.0
2026-09-16 15:47:48.906428 | instance |       Built pyperclip==1.9.0
2026-09-16 15:47:48.906511 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-16 15:47:48.906578 | instance | Installed 83 packages in 48ms
2026-09-16 15:47:48.906701 | instance | Checked out ansible.posix to 1.6.0
2026-09-16 15:47:48.906742 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-16 15:47:48.906779 | instance | Starting galaxy collection install process
2026-09-16 15:47:48.906864 | instance | Process install dependency map
2026-09-16 15:47:48.906906 | instance | Starting collection install process
2026-09-16 15:47:48.906942 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-16 15:47:48.906979 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-16 15:47:48.907067 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-16 15:47:48.907107 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-16 15:47:48.907144 | instance | 2.17.12
2026-09-16 15:47:48.907193 | instance | Checked out ansible.utils to v6.1.0
2026-09-16 15:47:48.907326 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-16 15:47:48.907384 | instance | Starting galaxy collection install process
2026-09-16 15:47:48.907520 | instance | Process install dependency map
2026-09-16 15:47:48.907579 | instance | Starting collection install process
2026-09-16 15:47:48.907617 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-16 15:47:48.907654 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-16 15:47:48.907689 | instance | ansible.utils:6.1.0 was installed successfully
2026-09-16 15:47:48.907783 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-16 15:47:48.907823 | instance | 2.17.12
2026-09-16 15:47:48.907890 | instance | Checked out atmosphere.common to v0.7.0
2026-09-16 15:47:48.908074 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-16 15:47:48.908153 | instance | Starting galaxy collection install process
2026-09-16 15:47:48.908195 | instance | Process install dependency map
2026-09-16 15:47:48.908233 | instance | Starting collection install process
2026-09-16 15:47:48.908269 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-16 15:47:48.908383 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-16 15:47:48.908429 | instance | atmosphere.common:0.7.0 was installed successfully
2026-09-16 15:47:48.908467 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-16 15:47:48.908555 | instance | 2.17.12
2026-09-16 15:47:48.908658 | instance | Checked out community.crypto to 2.2.3
2026-09-16 15:47:48.908700 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-16 15:47:48.908736 | instance | Starting galaxy collection install process
2026-09-16 15:47:48.908772 | instance | Process install dependency map
2026-09-16 15:47:48.908816 | instance | Starting collection install process
2026-09-16 15:47:48.908871 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-16 15:47:48.909090 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-16 15:47:48.909169 | instance | community.crypto:2.2.3 was installed successfully
2026-09-16 15:47:48.909230 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-16 15:47:48.909288 | instance | 2.17.12
2026-09-16 15:47:48.909349 | instance | Checked out community.general to 7.3.0
2026-09-16 15:47:48.909414 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general
2026-09-16 15:47:48.909498 | instance | Starting galaxy collection install process
2026-09-16 15:47:48.909565 | instance | Process install dependency map
2026-09-16 15:47:48.909623 | instance | Starting collection install process
2026-09-16 15:47:48.909680 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-16 15:47:48.909738 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-16 15:47:48.909794 | instance | community.general:7.3.0 was installed successfully
2026-09-16 15:47:48.909853 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-16 15:47:48.909908 | instance | 2.17.12
2026-09-16 15:47:48.909965 | instance | Checked out community.mysql to 3.6.0
2026-09-16 15:47:48.910023 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-16 15:47:48.910083 | instance | Starting galaxy collection install process
2026-09-16 15:47:48.910143 | instance | Process install dependency map
2026-09-16 15:47:48.910200 | instance | Starting collection install process
2026-09-16 15:47:48.910260 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-16 15:47:48.910319 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-16 15:47:48.910373 | instance | community.mysql:3.6.0 was installed successfully
2026-09-16 15:47:48.910432 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-16 15:47:48.910526 | instance | 2.17.12
2026-09-16 15:47:48.910596 | instance | Checked out kubernetes.core to 2.4.0
2026-09-16 15:47:48.910654 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-16 15:47:48.910719 | instance | Starting galaxy collection install process
2026-09-16 15:47:48.910781 | instance | Process install dependency map
2026-09-16 15:47:48.910843 | instance | Starting collection install process
2026-09-16 15:47:48.910905 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-16 15:47:48.910963 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-16 15:47:48.911026 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-16 15:47:48.911089 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-16 15:47:48.911167 | instance | 2.17.12
2026-09-16 15:47:48.911228 | instance | Checked out openstack.cloud to 2.6.0
2026-09-16 15:47:48.911293 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-16 15:47:48.911364 | instance | Starting galaxy collection install process
2026-09-16 15:47:48.911433 | instance | Process install dependency map
2026-09-16 15:47:48.911544 | instance | Starting collection install process
2026-09-16 15:47:48.911621 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-16 15:47:48.911687 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-16 15:47:48.911755 | instance | openstack.cloud:2.6.0 was installed successfully
2026-09-16 15:47:48.911822 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-16 15:47:48.911891 | instance | 2.17.12
2026-09-16 15:47:48.911958 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-16 15:47:48.912018 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-16 15:47:48.912078 | instance | Starting galaxy collection install process
2026-09-16 15:47:48.912139 | instance | Process install dependency map
2026-09-16 15:47:48.912204 | instance | Starting collection install process
2026-09-16 15:47:48.912268 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-16 15:47:48.912331 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-16 15:47:48.912390 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-16 15:47:48.912446 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-16 15:47:48.912571 | instance | 2.17.12
2026-09-16 15:47:48.912634 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-16 15:47:48.912674 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-16 15:47:48.912714 | instance | Starting galaxy collection install process
2026-09-16 15:47:48.912751 | instance | Process install dependency map
2026-09-16 15:47:48.912786 | instance | Starting collection install process
2026-09-16 15:47:48.912820 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-16 15:47:48.912856 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-16 15:47:48.912892 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-16 15:47:48.912926 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-16 15:47:48.912961 | instance | 2.17.12
2026-09-16 15:47:48.912996 | instance | Checked out vexxhost.kubernetes to v3.4.0
2026-09-16 15:47:48.913031 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-16 15:47:48.913066 | instance | Starting galaxy collection install process
2026-09-16 15:47:48.913101 | instance | Process install dependency map
2026-09-16 15:47:48.913135 | instance | Starting collection install process
2026-09-16 15:47:48.913169 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-16 15:47:48.913204 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-16 15:47:48.913239 | instance | vexxhost.kubernetes:3.4.0 was installed successfully
2026-09-16 15:47:48.913274 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-16 15:47:48.913309 | instance | 2.17.12
2026-09-16 15:47:48.921276 | 
2026-09-16 15:47:48.921342 | PLAY RECAP
2026-09-16 15:47:48.921400 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-16 15:47:48.921438 | 
2026-09-16 15:47:49.056427 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-16 15:47:49.062365 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-16 15:47:49.668789 | 
2026-09-16 15:47:49.668935 | PLAY [all]
2026-09-16 15:47:49.679989 | 
2026-09-16 15:47:49.680091 | TASK [Install "jq" for log collection]
2026-09-16 15:47:59.424723 | instance | changed
2026-09-16 15:47:59.427729 | 
2026-09-16 15:47:59.427842 | PLAY RECAP
2026-09-16 15:47:59.427936 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-16 15:47:59.428033 | 
2026-09-16 15:47:59.557019 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-16 15:47:59.561202 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-16 15:48:00.233181 | 
2026-09-16 15:48:00.233324 | PLAY [all]
2026-09-16 15:48:00.244584 | 
2026-09-16 15:48:00.244690 | LOOP [Check inventory directories]
2026-09-16 15:48:00.595154 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-16 15:48:00.735122 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-16 15:48:00.914063 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-16 15:48:00.930124 | 
2026-09-16 15:48:00.930335 | LOOP [Copy inventory file for Zuul]
2026-09-16 15:48:01.749648 | instance | changed:
2026-09-16 15:48:01.749941 | instance | {
2026-09-16 15:48:01.749998 | instance |   "ansible_loop_var": "item",
2026-09-16 15:48:01.750044 | instance |   "changed": false,
2026-09-16 15:48:01.750082 | instance |   "failed": false,
2026-09-16 15:48:01.750127 | instance |   "invocation": {
2026-09-16 15:48:01.750165 | instance |     "module_args": {
2026-09-16 15:48:01.750200 | instance |       "checksum_algorithm": "sha1",
2026-09-16 15:48:01.750234 | instance |       "follow": true,
2026-09-16 15:48:01.750268 | instance |       "get_attributes": true,
2026-09-16 15:48:01.750305 | instance |       "get_checksum": true,
2026-09-16 15:48:01.750342 | instance |       "get_mime": true,
2026-09-16 15:48:01.750380 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-16 15:48:01.750415 | instance |     }
2026-09-16 15:48:01.750477 | instance |   },
2026-09-16 15:48:01.750517 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-16 15:48:01.750554 | instance |   "stat": {
2026-09-16 15:48:01.750591 | instance |     "atime": 1789573661.4456015,
2026-09-16 15:48:01.750629 | instance |     "attr_flags": "e",
2026-09-16 15:48:01.750663 | instance |     "attributes": [
2026-09-16 15:48:01.750697 | instance |       "extents"
2026-09-16 15:48:01.750730 | instance |     ],
2026-09-16 15:48:01.750764 | instance |     "block_size": 4096,
2026-09-16 15:48:01.750800 | instance |     "blocks": 8,
2026-09-16 15:48:01.750837 | instance |     "charset": "unknown",
2026-09-16 15:48:01.750878 | instance |     "ctime": 1789573658.0255692,
2026-09-16 15:48:01.750931 | instance |     "dev": 64513,
2026-09-16 15:48:01.750979 | instance |     "device_type": 0,
2026-09-16 15:48:01.751015 | instance |     "executable": true,
2026-09-16 15:48:01.751050 | instance |     "exists": true,
2026-09-16 15:48:01.751083 | instance |     "gid": 1000,
2026-09-16 15:48:01.751118 | instance |     "gr_name": "zuul",
2026-09-16 15:48:01.751154 | instance |     "inode": 1049829,
2026-09-16 15:48:01.751187 | instance |     "isblk": false,
2026-09-16 15:48:01.751222 | instance |     "ischr": false,
2026-09-16 15:48:01.751256 | instance |     "isdir": true,
2026-09-16 15:48:01.751291 | instance |     "isfifo": false,
2026-09-16 15:48:01.751325 | instance |     "isgid": false,
2026-09-16 15:48:01.751357 | instance |     "islnk": false,
2026-09-16 15:48:01.751389 | instance |     "isreg": false,
2026-09-16 15:48:01.751424 | instance |     "issock": false,
2026-09-16 15:48:01.751499 | instance |     "isuid": false,
2026-09-16 15:48:01.751540 | instance |     "mimetype": "unknown",
2026-09-16 15:48:01.751576 | instance |     "mode": "0755",
2026-09-16 15:48:01.751609 | instance |     "mtime": 1789573658.0255692,
2026-09-16 15:48:01.751645 | instance |     "nlink": 23,
2026-09-16 15:48:01.751678 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-16 15:48:01.751712 | instance |     "pw_name": "zuul",
2026-09-16 15:48:01.751754 | instance |     "readable": true,
2026-09-16 15:48:01.751791 | instance |     "rgrp": true,
2026-09-16 15:48:01.751827 | instance |     "roth": true,
2026-09-16 15:48:01.751863 | instance |     "rusr": true,
2026-09-16 15:48:01.751897 | instance |     "size": 4096,
2026-09-16 15:48:01.751931 | instance |     "uid": 1000,
2026-09-16 15:48:01.751965 | instance |     "version": "3045114675",
2026-09-16 15:48:01.751999 | instance |     "wgrp": false,
2026-09-16 15:48:01.752036 | instance |     "woth": false,
2026-09-16 15:48:01.752072 | instance |     "writeable": true,
2026-09-16 15:48:01.752105 | instance |     "wusr": true,
2026-09-16 15:48:01.752138 | instance |     "xgrp": true,
2026-09-16 15:48:01.752170 | instance |     "xoth": true,
2026-09-16 15:48:01.752202 | instance |     "xusr": true
2026-09-16 15:48:01.752235 | instance |   }
2026-09-16 15:48:01.752268 | instance | }
2026-09-16 15:48:01.752335 | instance | ok: All items complete
2026-09-16 15:48:01.752381 | 
2026-09-16 15:48:01.755123 | instance | skipping: Conditional result was False
2026-09-16 15:48:01.757649 | instance | skipping: Conditional result was False
2026-09-16 15:48:01.775858 | 
2026-09-16 15:48:01.776021 | LOOP [Switch "ansible_host" to private IP]
2026-09-16 15:48:02.168139 | instance | changed: 1 replacements made
2026-09-16 15:48:02.172209 | instance | skipping: Conditional result was False
2026-09-16 15:48:02.179479 | instance | skipping: Conditional result was False
2026-09-16 15:48:02.192582 | 
2026-09-16 15:48:02.192740 | TASK [Run Molecule scenario]
2026-09-16 15:48:03.152323 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-16 15:48:03.583811 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-16 15:48:03.583925 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-16 15:48:15.165750 | instance | INFO     [csi > dependency] Executing
2026-09-16 15:48:15.166185 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-16 15:48:15.166616 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-16 15:48:15.166844 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-16 15:48:15.182665 | instance | INFO     [csi > cleanup] Executing
2026-09-16 15:48:15.183416 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-16 15:48:15.197155 | instance | INFO     [csi > destroy] Executing
2026-09-16 15:48:15.197274 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-16 15:48:15.197375 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-16 15:48:15.206579 | instance | INFO     [csi > syntax] Executing
2026-09-16 15:48:16.770544 | instance |
2026-09-16 15:48:16.770848 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-16 15:48:16.846764 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-16 15:48:16.862030 | instance | INFO     [csi > create] Executing
2026-09-16 15:48:16.865889 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-16 15:48:16.880951 | instance | INFO     [csi > prepare] Executing
2026-09-16 15:48:17.702421 | instance |
2026-09-16 15:48:17.702661 | instance | PLAY [Prepare] *****************************************************************
2026-09-16 15:48:17.702870 | instance |
2026-09-16 15:48:17.703098 | instance | TASK [Gathering Facts] *********************************************************
2026-09-16 15:48:17.703333 | instance | Wednesday 16 September 2026  15:48:17 +0000 (0:00:00.027)       0:00:00.027 ***
2026-09-16 15:48:18.967808 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-16 15:48:18.968001 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-16 15:48:18.968240 | instance | interpreter could change the meaning of that path. See
2026-09-16 15:48:18.968488 | instance | https://docs.ansible.com/ansible-
2026-09-16 15:48:18.968745 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-16 15:48:18.980552 | instance | ok: [instance]
2026-09-16 15:48:18.980764 | instance |
2026-09-16 15:48:18.981087 | instance | TASK [Configure short hostname] ************************************************
2026-09-16 15:48:18.981320 | instance | Wednesday 16 September 2026  15:48:18 +0000 (0:00:01.278)       0:00:01.305 ***
2026-09-16 15:48:19.832308 | instance | changed: [instance]
2026-09-16 15:48:19.832437 | instance |
2026-09-16 15:48:19.832791 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-16 15:48:19.833070 | instance | Wednesday 16 September 2026  15:48:19 +0000 (0:00:00.851)       0:00:02.157 ***
2026-09-16 15:48:20.133025 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-16 15:48:20.133351 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-16 15:48:20.133748 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-16 15:48:20.137596 | instance | changed: [instance]
2026-09-16 15:48:20.137869 | instance |
2026-09-16 15:48:20.138150 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-16 15:48:20.138432 | instance | Wednesday 16 September 2026  15:48:20 +0000 (0:00:00.305)       0:00:02.463 ***
2026-09-16 15:48:20.976060 | instance | ok: [instance]
2026-09-16 15:48:20.976169 | instance |
2026-09-16 15:48:20.976307 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-16 15:48:20.976454 | instance |
2026-09-16 15:48:20.976669 | instance | TASK [Gathering Facts] *********************************************************
2026-09-16 15:48:20.976899 | instance | Wednesday 16 September 2026  15:48:20 +0000 (0:00:00.838)       0:00:03.301 ***
2026-09-16 15:48:21.749640 | instance | ok: [instance]
2026-09-16 15:48:21.749856 | instance |
2026-09-16 15:48:21.750114 | instance | TASK [Install depedencies] *****************************************************
2026-09-16 15:48:21.750549 | instance | Wednesday 16 September 2026  15:48:21 +0000 (0:00:00.772)       0:00:04.074 ***
2026-09-16 15:48:45.242460 | instance | changed: [instance]
2026-09-16 15:48:45.242563 | instance |
2026-09-16 15:48:45.242747 | instance | TASK [Start up service] ********************************************************
2026-09-16 15:48:45.242931 | instance | Wednesday 16 September 2026  15:48:45 +0000 (0:00:23.493)       0:00:27.568 ***
2026-09-16 15:48:45.867731 | instance | ok: [instance]
2026-09-16 15:48:45.867894 | instance |
2026-09-16 15:48:45.868148 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-16 15:48:45.868414 | instance | Wednesday 16 September 2026  15:48:45 +0000 (0:00:00.624)       0:00:28.193 ***
2026-09-16 15:48:46.161883 | instance | ok: [instance]
2026-09-16 15:48:46.162143 | instance |
2026-09-16 15:48:46.162580 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-16 15:48:46.162888 | instance | Wednesday 16 September 2026  15:48:46 +0000 (0:00:00.294)       0:00:28.487 ***
2026-09-16 15:48:46.934929 | instance | changed: [instance]
2026-09-16 15:48:46.935728 | instance |
2026-09-16 15:48:46.935763 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-16 15:48:46.935775 | instance | Wednesday 16 September 2026  15:48:46 +0000 (0:00:00.772)       0:00:29.260 ***
2026-09-16 15:48:47.110140 | instance | ok: [instance]
2026-09-16 15:48:47.110301 | instance |
2026-09-16 15:48:47.110605 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-16 15:48:47.110624 | instance | Wednesday 16 September 2026  15:48:47 +0000 (0:00:00.175)       0:00:29.435 ***
2026-09-16 15:48:47.138265 | instance | skipping: [instance]
2026-09-16 15:48:47.139083 | instance |
2026-09-16 15:48:47.139111 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-16 15:48:47.139123 | instance | Wednesday 16 September 2026  15:48:47 +0000 (0:00:00.027)       0:00:29.463 ***
2026-09-16 15:48:47.732519 | instance | changed: [instance] => (item=osd0)
2026-09-16 15:48:47.732735 | instance | changed: [instance] => (item=osd1)
2026-09-16 15:48:47.733195 | instance | changed: [instance] => (item=osd2)
2026-09-16 15:48:47.733539 | instance |
2026-09-16 15:48:47.733849 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-16 15:48:47.734151 | instance | Wednesday 16 September 2026  15:48:47 +0000 (0:00:00.594)       0:00:30.057 ***
2026-09-16 15:48:48.379600 | instance | changed: [instance] => (item=osd0)
2026-09-16 15:48:48.379826 | instance | changed: [instance] => (item=osd1)
2026-09-16 15:48:48.380138 | instance | changed: [instance] => (item=osd2)
2026-09-16 15:48:48.380447 | instance |
2026-09-16 15:48:48.380736 | instance | TASK [Start loop devices] ******************************************************
2026-09-16 15:48:48.381054 | instance | Wednesday 16 September 2026  15:48:48 +0000 (0:00:00.647)       0:00:30.705 ***
2026-09-16 15:48:49.181777 | instance | changed: [instance] => (item=osd0)
2026-09-16 15:48:49.181887 | instance | changed: [instance] => (item=osd1)
2026-09-16 15:48:49.182543 | instance | changed: [instance] => (item=osd2)
2026-09-16 15:48:49.182689 | instance |
2026-09-16 15:48:49.182697 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-16 15:48:49.182704 | instance | Wednesday 16 September 2026  15:48:49 +0000 (0:00:00.802)       0:00:31.507 ***
2026-09-16 15:48:52.843723 | instance | changed: [instance] => (item=osd0)
2026-09-16 15:48:52.845005 | instance | changed: [instance] => (item=osd1)
2026-09-16 15:48:52.845110 | instance | changed: [instance] => (item=osd2)
2026-09-16 15:48:52.845119 | instance |
2026-09-16 15:48:52.845126 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-16 15:48:52.845133 | instance | Wednesday 16 September 2026  15:48:52 +0000 (0:00:03.661)       0:00:35.168 ***
2026-09-16 15:48:54.784421 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-16 15:48:54.784918 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-16 15:48:54.784981 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-16 15:48:54.788989 | instance |
2026-09-16 15:48:54.789043 | instance | PLAY RECAP *********************************************************************
2026-09-16 15:48:54.789094 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-16 15:48:54.789104 | instance |
2026-09-16 15:48:54.789113 | instance | Wednesday 16 September 2026  15:48:54 +0000 (0:00:01.941)       0:00:37.110 ***
2026-09-16 15:48:54.789122 | instance | ===============================================================================
2026-09-16 15:48:54.789130 | instance | Install depedencies ---------------------------------------------------- 23.49s
2026-09-16 15:48:54.789139 | instance | Create a volume group for each loop device ------------------------------ 3.66s
2026-09-16 15:48:54.789164 | instance | Create a logical volume for each loop device ---------------------------- 1.94s
2026-09-16 15:48:54.789173 | instance | Gathering Facts --------------------------------------------------------- 1.28s
2026-09-16 15:48:54.789182 | instance | Configure short hostname ------------------------------------------------ 0.85s
2026-09-16 15:48:54.789191 | instance | Purge "snapd" package --------------------------------------------------- 0.84s
2026-09-16 15:48:54.789199 | instance | Start loop devices ------------------------------------------------------ 0.80s
2026-09-16 15:48:54.789207 | instance | Gathering Facts --------------------------------------------------------- 0.77s
2026-09-16 15:48:54.789216 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.77s
2026-09-16 15:48:54.789224 | instance | Set permissions on loopback devices ------------------------------------- 0.65s
2026-09-16 15:48:54.789238 | instance | Start up service -------------------------------------------------------- 0.63s
2026-09-16 15:48:54.789522 | instance | Create devices for Ceph ------------------------------------------------- 0.59s
2026-09-16 15:48:54.789947 | instance | Ensure hostname inside hosts file --------------------------------------- 0.31s
2026-09-16 15:48:54.790427 | instance | Generate lvm.conf ------------------------------------------------------- 0.29s
2026-09-16 15:48:54.790821 | instance | Get list of all loopback devices ---------------------------------------- 0.18s
2026-09-16 15:48:54.791208 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s
2026-09-16 15:48:54.869788 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-16 15:48:54.885309 | instance | INFO     [csi > converge] Executing
2026-09-16 15:48:56.274478 | instance |
2026-09-16 15:48:56.274887 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-16 15:48:56.275254 | instance |
2026-09-16 15:48:56.275565 | instance | TASK [Gathering Facts] *********************************************************
2026-09-16 15:48:56.275894 | instance | Wednesday 16 September 2026  15:48:56 +0000 (0:00:00.006)       0:00:00.006 ***
2026-09-16 15:48:57.351785 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-16 15:48:57.352265 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-16 15:48:57.352625 | instance | interpreter could change the meaning of that path. See
2026-09-16 15:48:57.353021 | instance | https://docs.ansible.com/ansible-
2026-09-16 15:48:57.353400 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-16 15:48:57.369052 | instance | ok: [instance]
2026-09-16 15:48:57.369408 | instance |
2026-09-16 15:48:57.369723 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-16 15:48:57.370078 | instance | Wednesday 16 September 2026  15:48:57 +0000 (0:00:01.093)       0:00:01.100 ***
2026-09-16 15:48:57.447694 | instance | ok: [instance] => {
2026-09-16 15:48:57.448169 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-16 15:48:57.448493 | instance | }
2026-09-16 15:48:57.448774 | instance |
2026-09-16 15:48:57.449103 | instance | PLAY [all] *********************************************************************
2026-09-16 15:48:57.449432 | instance |
2026-09-16 15:48:57.449736 | instance | TASK [Gathering Facts] *********************************************************
2026-09-16 15:48:57.450170 | instance | Wednesday 16 September 2026  15:48:57 +0000 (0:00:00.079)       0:00:01.179 ***
2026-09-16 15:48:58.416489 | instance | ok: [instance]
2026-09-16 15:48:58.416938 | instance |
2026-09-16 15:48:58.417304 | instance | TASK [Fail if atmosphere_ceph_enabled is set] **********************************
2026-09-16 15:48:58.417630 | instance | Wednesday 16 September 2026  15:48:58 +0000 (0:00:00.968)       0:00:02.148 ***
2026-09-16 15:48:58.472526 | instance | skipping: [instance]
2026-09-16 15:48:58.472991 | instance |
2026-09-16 15:48:58.473436 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-16 15:48:58.473858 | instance | Wednesday 16 September 2026  15:48:58 +0000 (0:00:00.053)       0:00:02.201 ***
2026-09-16 15:48:58.664679 | instance | ok: [instance]
2026-09-16 15:48:58.664717 | instance |
2026-09-16 15:48:58.664723 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-16 15:48:58.664728 | instance |
2026-09-16 15:48:58.664732 | instance | TASK [Gathering Facts] *********************************************************
2026-09-16 15:48:58.664740 | instance | Wednesday 16 September 2026  15:48:58 +0000 (0:00:00.195)       0:00:02.396 ***
2026-09-16 15:48:59.597942 | instance | ok: [instance]
2026-09-16 15:48:59.598009 | instance |
2026-09-16 15:48:59.598017 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-16 15:48:59.598024 | instance | Wednesday 16 September 2026  15:48:59 +0000 (0:00:00.932)       0:00:03.329 ***
2026-09-16 15:48:59.895707 | instance | ok: [instance]
2026-09-16 15:48:59.895756 | instance |
2026-09-16 15:48:59.895765 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-16 15:48:59.895800 | instance | Wednesday 16 September 2026  15:48:59 +0000 (0:00:00.296)       0:00:03.626 ***
2026-09-16 15:48:59.929084 | instance | skipping: [instance]
2026-09-16 15:48:59.929420 | instance |
2026-09-16 15:48:59.929779 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-16 15:48:59.930115 | instance | Wednesday 16 September 2026  15:48:59 +0000 (0:00:00.034)       0:00:03.661 ***
2026-09-16 15:49:00.324840 | instance | changed: [instance]
2026-09-16 15:49:00.325229 | instance |
2026-09-16 15:49:00.325563 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-16 15:49:00.325944 | instance | Wednesday 16 September 2026  15:49:00 +0000 (0:00:00.395)       0:00:04.056 ***
2026-09-16 15:49:00.402897 | instance | ok: [instance] => {
2026-09-16 15:49:00.402998 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-16 15:49:00.403128 | instance | }
2026-09-16 15:49:00.403255 | instance |
2026-09-16 15:49:00.403390 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-16 15:49:00.403518 | instance | Wednesday 16 September 2026  15:49:00 +0000 (0:00:00.078)       0:00:04.135 ***
2026-09-16 15:49:01.084325 | instance | changed: [instance]
2026-09-16 15:49:01.084520 | instance |
2026-09-16 15:49:01.084898 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-16 15:49:01.085291 | instance | Wednesday 16 September 2026  15:49:01 +0000 (0:00:00.681)       0:00:04.816 ***
2026-09-16 15:49:01.133533 | instance | skipping: [instance]
2026-09-16 15:49:01.133621 | instance |
2026-09-16 15:49:01.133836 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-16 15:49:01.134032 | instance | Wednesday 16 September 2026  15:49:01 +0000 (0:00:00.049)       0:00:04.865 ***
2026-09-16 15:49:01.182967 | instance | skipping: [instance]
2026-09-16 15:49:01.183354 | instance |
2026-09-16 15:49:01.183765 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-16 15:49:01.184180 | instance | Wednesday 16 September 2026  15:49:01 +0000 (0:00:00.049)       0:00:04.915 ***
2026-09-16 15:49:01.398344 | instance | ok: [instance]
2026-09-16 15:49:01.398451 | instance |
2026-09-16 15:49:01.398596 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-16 15:49:01.398746 | instance | Wednesday 16 September 2026  15:49:01 +0000 (0:00:00.215)       0:00:05.130 ***
2026-09-16 15:49:02.877214 | instance | ok: [instance]
2026-09-16 15:49:02.877441 | instance |
2026-09-16 15:49:02.877700 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-16 15:49:02.878156 | instance | Wednesday 16 September 2026  15:49:02 +0000 (0:00:01.478)       0:00:06.608 ***
2026-09-16 15:49:02.958832 | instance | ok: [instance] => {
2026-09-16 15:49:02.959113 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-16 15:49:02.959374 | instance | }
2026-09-16 15:49:02.959605 | instance |
2026-09-16 15:49:02.959851 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-16 15:49:02.960170 | instance | Wednesday 16 September 2026  15:49:02 +0000 (0:00:00.081)       0:00:06.690 ***
2026-09-16 15:49:03.674840 | instance | changed: [instance]
2026-09-16 15:49:03.675391 | instance |
2026-09-16 15:49:03.675672 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-16 15:49:03.676310 | instance | Wednesday 16 September 2026  15:49:03 +0000 (0:00:00.711)       0:00:07.402 ***
2026-09-16 15:49:06.726339 | instance | changed: [instance]
2026-09-16 15:49:06.726705 | instance |
2026-09-16 15:49:06.727062 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-16 15:49:06.727399 | instance | Wednesday 16 September 2026  15:49:06 +0000 (0:00:03.056)       0:00:10.458 ***
2026-09-16 15:49:06.765619 | instance | skipping: [instance]
2026-09-16 15:49:06.765694 | instance |
2026-09-16 15:49:06.766180 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-16 15:49:06.766425 | instance | Wednesday 16 September 2026  15:49:06 +0000 (0:00:00.038)       0:00:10.496 ***
2026-09-16 15:49:06.801407 | instance | skipping: [instance]
2026-09-16 15:49:06.801647 | instance |
2026-09-16 15:49:06.801854 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-16 15:49:06.802074 | instance | Wednesday 16 September 2026  15:49:06 +0000 (0:00:00.036)       0:00:10.533 ***
2026-09-16 15:49:06.848996 | instance | skipping: [instance]
2026-09-16 15:49:06.849577 | instance |
2026-09-16 15:49:06.849780 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-16 15:49:06.849985 | instance | Wednesday 16 September 2026  15:49:06 +0000 (0:00:00.043)       0:00:10.577 ***
2026-09-16 15:49:13.574605 | instance | changed: [instance]
2026-09-16 15:49:13.574687 | instance |
2026-09-16 15:49:13.574696 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-16 15:49:13.574707 | instance | Wednesday 16 September 2026  15:49:13 +0000 (0:00:06.728)       0:00:17.306 ***
2026-09-16 15:49:14.302379 | instance | changed: [instance]
2026-09-16 15:49:14.302435 | instance |
2026-09-16 15:49:14.302446 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-16 15:49:14.302456 | instance | Wednesday 16 September 2026  15:49:14 +0000 (0:00:00.727)       0:00:18.033 ***
2026-09-16 15:49:15.238669 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-16 15:49:15.238717 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-16 15:49:15.238725 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-16 15:49:15.238739 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-16 15:49:15.238746 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-16 15:49:15.238751 | instance |
2026-09-16 15:49:15.238757 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-16 15:49:15.238762 | instance | Wednesday 16 September 2026  15:49:15 +0000 (0:00:00.936)       0:00:18.969 ***
2026-09-16 15:49:15.844745 | instance | changed: [instance]
2026-09-16 15:49:15.844910 | instance |
2026-09-16 15:49:15.845850 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-16 15:49:15.846045 | instance | Wednesday 16 September 2026  15:49:15 +0000 (0:00:00.581)       0:00:19.551 ***
2026-09-16 15:49:15.846056 | instance |
2026-09-16 15:49:15.846065 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-16 15:49:15.846072 | instance | Wednesday 16 September 2026  15:49:15 +0000 (0:00:00.025)       0:00:19.577 ***
2026-09-16 15:49:16.768626 | instance | ok: [instance]
2026-09-16 15:49:16.768908 | instance |
2026-09-16 15:49:16.769243 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-16 15:49:16.769533 | instance | Wednesday 16 September 2026  15:49:16 +0000 (0:00:00.923)       0:00:20.500 ***
2026-09-16 15:49:17.261504 | instance | changed: [instance]
2026-09-16 15:49:17.262096 | instance |
2026-09-16 15:49:17.262200 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-16 15:49:17.262537 | instance | Wednesday 16 September 2026  15:49:17 +0000 (0:00:00.492)       0:00:20.993 ***
2026-09-16 15:49:17.861564 | instance | changed: [instance]
2026-09-16 15:49:17.861813 | instance |
2026-09-16 15:49:17.862097 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-16 15:49:17.862427 | instance | Wednesday 16 September 2026  15:49:17 +0000 (0:00:00.600)       0:00:21.593 ***
2026-09-16 15:49:18.089664 | instance | ok: [instance]
2026-09-16 15:49:18.089926 | instance |
2026-09-16 15:49:18.090191 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-16 15:49:18.090413 | instance | Wednesday 16 September 2026  15:49:18 +0000 (0:00:00.228)       0:00:21.821 ***
2026-09-16 15:49:18.157222 | instance | ok: [instance] => {
2026-09-16 15:49:18.157502 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-16 15:49:18.157750 | instance | }
2026-09-16 15:49:18.158016 | instance |
2026-09-16 15:49:18.158297 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-16 15:49:18.158551 | instance | Wednesday 16 September 2026  15:49:18 +0000 (0:00:00.067)       0:00:21.889 ***
2026-09-16 15:49:19.154821 | instance | changed: [instance]
2026-09-16 15:49:19.154884 | instance |
2026-09-16 15:49:19.154895 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-16 15:49:19.154905 | instance | Wednesday 16 September 2026  15:49:19 +0000 (0:00:00.996)       0:00:22.885 ***
2026-09-16 15:49:23.537258 | instance | changed: [instance]
2026-09-16 15:49:23.537345 | instance |
2026-09-16 15:49:23.537673 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-16 15:49:23.537746 | instance | Wednesday 16 September 2026  15:49:23 +0000 (0:00:04.383)       0:00:27.269 ***
2026-09-16 15:49:24.689078 | instance | ok: [instance]
2026-09-16 15:49:24.689163 | instance |
2026-09-16 15:49:24.689178 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-16 15:49:24.689191 | instance | Wednesday 16 September 2026  15:49:24 +0000 (0:00:01.149)       0:00:28.418 ***
2026-09-16 15:49:25.080413 | instance | changed: [instance]
2026-09-16 15:49:25.080948 | instance |
2026-09-16 15:49:25.081096 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-16 15:49:25.081368 | instance | Wednesday 16 September 2026  15:49:25 +0000 (0:00:00.392)       0:00:28.811 ***
2026-09-16 15:49:25.608408 | instance | changed: [instance]
2026-09-16 15:49:25.608605 | instance |
2026-09-16 15:49:25.608914 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-16 15:49:25.609163 | instance | Wednesday 16 September 2026  15:49:25 +0000 (0:00:00.528)       0:00:29.340 ***
2026-09-16 15:49:26.281127 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-16 15:49:26.281340 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-16 15:49:26.281610 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-16 15:49:26.281835 | instance |
2026-09-16 15:49:26.282067 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-16 15:49:26.282298 | instance | Wednesday 16 September 2026  15:49:26 +0000 (0:00:00.672)       0:00:30.012 ***
2026-09-16 15:49:26.829930 | instance | changed: [instance]
2026-09-16 15:49:26.830039 | instance |
2026-09-16 15:49:26.830206 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-16 15:49:26.830367 | instance | Wednesday 16 September 2026  15:49:26 +0000 (0:00:00.549)       0:00:30.561 ***
2026-09-16 15:49:27.397049 | instance | changed: [instance]
2026-09-16 15:49:27.397120 | instance |
2026-09-16 15:49:27.397128 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-16 15:49:27.397166 | instance | Wednesday 16 September 2026  15:49:27 +0000 (0:00:00.544)       0:00:31.106 ***
2026-09-16 15:49:27.397960 | instance |
2026-09-16 15:49:27.398054 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-16 15:49:27.398095 | instance | Wednesday 16 September 2026  15:49:27 +0000 (0:00:00.021)       0:00:31.128 ***
2026-09-16 15:49:28.252949 | instance | ok: [instance]
2026-09-16 15:49:28.253009 | instance |
2026-09-16 15:49:28.253401 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-16 15:49:28.253455 | instance | Wednesday 16 September 2026  15:49:28 +0000 (0:00:00.856)       0:00:31.984 ***
2026-09-16 15:49:29.164622 | instance | changed: [instance]
2026-09-16 15:49:29.164703 | instance |
2026-09-16 15:49:29.164915 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-16 15:49:29.165228 | instance | Wednesday 16 September 2026  15:49:29 +0000 (0:00:00.912)       0:00:32.897 ***
2026-09-16 15:49:29.736004 | instance | changed: [instance]
2026-09-16 15:49:29.736119 | instance |
2026-09-16 15:49:29.736492 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-16 15:49:29.736532 | instance | Wednesday 16 September 2026  15:49:29 +0000 (0:00:00.571)       0:00:33.468 ***
2026-09-16 15:49:29.781363 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-16 15:49:29.781431 | instance |
2026-09-16 15:49:29.781678 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-16 15:49:29.781715 | instance | Wednesday 16 September 2026  15:49:29 +0000 (0:00:00.045)       0:00:33.514 ***
2026-09-16 15:49:35.635813 | instance | changed: [instance]
2026-09-16 15:49:35.636990 | instance |
2026-09-16 15:49:35.637106 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-16 15:49:35.637129 | instance | Wednesday 16 September 2026  15:49:35 +0000 (0:00:05.853)       0:00:39.367 ***
2026-09-16 15:49:36.339406 | instance | ok: [instance] => (item=chronyd)
2026-09-16 15:49:36.340495 | instance | ok: [instance] => (item=sshd)
2026-09-16 15:49:36.340574 | instance |
2026-09-16 15:49:36.340586 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-16 15:49:36.340597 | instance | Wednesday 16 September 2026  15:49:36 +0000 (0:00:00.703)       0:00:40.070 ***
2026-09-16 15:49:53.886372 | instance | changed: [instance]
2026-09-16 15:49:53.886444 | instance |
2026-09-16 15:49:53.886460 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-09-16 15:49:53.886475 | instance | Wednesday 16 September 2026  15:49:53 +0000 (0:00:17.545)       0:00:57.616 ***
2026-09-16 15:49:54.132855 | instance | ok: [instance]
2026-09-16 15:49:54.133915 | instance |
2026-09-16 15:49:54.133974 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-09-16 15:49:54.133985 | instance | Wednesday 16 September 2026  15:49:54 +0000 (0:00:00.247)       0:00:57.864 ***
2026-09-16 15:49:54.661218 | instance | changed: [instance]
2026-09-16 15:49:54.661328 | instance |
2026-09-16 15:49:54.661676 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-09-16 15:49:54.661720 | instance | Wednesday 16 September 2026  15:49:54 +0000 (0:00:00.529)       0:00:58.393 ***
2026-09-16 15:49:55.036040 | instance | changed: [instance]
2026-09-16 15:49:55.036142 | instance |
2026-09-16 15:49:55.036174 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-09-16 15:49:55.036317 | instance | Wednesday 16 September 2026  15:49:55 +0000 (0:00:00.375)       0:00:58.768 ***
2026-09-16 15:49:55.171355 | instance | ok: [instance]
2026-09-16 15:49:55.171434 | instance |
2026-09-16 15:49:55.171810 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-09-16 15:49:55.171865 | instance | Wednesday 16 September 2026  15:49:55 +0000 (0:00:00.133)       0:00:58.902 ***
2026-09-16 15:49:55.435918 | instance | ok: [instance] => (item=instance)
2026-09-16 15:49:55.436046 | instance |
2026-09-16 15:49:55.436638 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-09-16 15:49:55.436698 | instance | Wednesday 16 September 2026  15:49:55 +0000 (0:00:00.265)       0:00:59.167 ***
2026-09-16 15:49:55.507715 | instance | ok: [instance]
2026-09-16 15:49:55.507838 | instance |
2026-09-16 15:49:55.508232 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-09-16 15:49:55.508350 | instance | Wednesday 16 September 2026  15:49:55 +0000 (0:00:00.071)       0:00:59.239 ***
2026-09-16 15:49:55.590731 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-09-16 15:49:55.590850 | instance |
2026-09-16 15:49:55.590863 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-09-16 15:49:55.590876 | instance | Wednesday 16 September 2026  15:49:55 +0000 (0:00:00.083)       0:00:59.323 ***
2026-09-16 15:49:55.952750 | instance | changed: [instance]
2026-09-16 15:49:55.952997 | instance |
2026-09-16 15:49:55.953462 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-09-16 15:49:55.953516 | instance | Wednesday 16 September 2026  15:49:55 +0000 (0:00:00.361)       0:00:59.684 ***
2026-09-16 15:49:56.656310 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-09-16 15:49:56.656373 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-09-16 15:49:56.656880 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-09-16 15:49:56.656916 | instance |
2026-09-16 15:49:56.656922 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-09-16 15:49:56.656927 | instance | Wednesday 16 September 2026  15:49:56 +0000 (0:00:00.704)       0:01:00.388 ***
2026-09-16 15:51:21.905924 | instance | ok: [instance]
2026-09-16 15:51:21.906089 | instance |
2026-09-16 15:51:21.906610 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-09-16 15:51:21.906635 | instance | Wednesday 16 September 2026  15:51:21 +0000 (0:01:25.248)       0:02:25.637 ***
2026-09-16 15:51:22.143710 | instance | changed: [instance]
2026-09-16 15:51:22.143756 | instance |
2026-09-16 15:51:22.143761 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-09-16 15:51:22.143766 | instance | Wednesday 16 September 2026  15:51:22 +0000 (0:00:00.238)       0:02:25.875 ***
2026-09-16 15:51:22.180068 | instance | ok: [instance]
2026-09-16 15:51:22.180109 | instance |
2026-09-16 15:51:22.180116 | instance | TASK [Install Ceph host] *******************************************************
2026-09-16 15:51:22.180121 | instance | Wednesday 16 September 2026  15:51:22 +0000 (0:00:00.035)       0:02:25.911 ***
2026-09-16 15:51:22.315006 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-09-16 15:51:22.315048 | instance |
2026-09-16 15:51:22.315055 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-09-16 15:51:22.315061 | instance | Wednesday 16 September 2026  15:51:22 +0000 (0:00:00.135)       0:02:26.047 ***
2026-09-16 15:51:23.921968 | instance | ok: [instance]
2026-09-16 15:51:23.922030 | instance |
2026-09-16 15:51:23.922054 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-09-16 15:51:23.922109 | instance | Wednesday 16 September 2026  15:51:23 +0000 (0:00:01.606)       0:02:27.653 ***
2026-09-16 15:51:24.004375 | instance | ok: [instance] => (item=instance)
2026-09-16 15:51:24.004418 | instance |
2026-09-16 15:51:24.004424 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-09-16 15:51:24.004429 | instance | Wednesday 16 September 2026  15:51:23 +0000 (0:00:00.082)       0:02:27.736 ***
2026-09-16 15:51:24.382928 | instance | ok: [instance]
2026-09-16 15:51:24.382982 | instance |
2026-09-16 15:51:24.382993 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-09-16 15:51:24.383003 | instance | Wednesday 16 September 2026  15:51:24 +0000 (0:00:00.378)       0:02:28.114 ***
2026-09-16 15:51:26.699697 | instance | ok: [instance]
2026-09-16 15:51:26.699821 | instance |
2026-09-16 15:51:26.699847 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-09-16 15:51:26.699859 | instance | Wednesday 16 September 2026  15:51:26 +0000 (0:00:02.317)       0:02:30.431 ***
2026-09-16 15:52:12.543055 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster)
2026-09-16 15:52:12.543156 | 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_beza05lh/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_beza05lh/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_beza05lh/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_beza05lh/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-16T15:52:12.379+0000 7fa20c398640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDPuqpq6IobOyAA/2fpSETUH1q163GfFpm/42axVR2D7b/1ebrptgNA0r8=: Malformed input [buffer:3]\n2026-09-16T15:52:12.379+0000 7fa20c398640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-16T15:52:12.379+0000 7fa20c398640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDPuqpq6IobOyAA/2fpSETUH1q163GfFpm/42axVR2D7b/1ebrptgNA0r8=: Malformed input [buffer:3]\n2026-09-16T15:52:12.379+0000 7fa20c398640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-16T15:52:12.379+0000 7fa20c398640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDPuqpq6IobOyAA/2fpSETUH1q163GfFpm/42axVR2D7b/1ebrptgNA0r8=: Malformed input [buffer:3]\n2026-09-16T15:52:12.379+0000 7fa20c398640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-16T15:52:12.379+0000 7fa20c398640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1}
2026-09-16 15:52:12.545851 | instance |
2026-09-16 15:52:12.548986 | instance | PLAY RECAP *********************************************************************
2026-09-16 15:52:12.549088 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=7    rescued=0    ignored=0
2026-09-16 15:52:12.549109 | instance |
2026-09-16 15:52:12.549124 | instance | Wednesday 16 September 2026  15:52:12 +0000 (0:00:45.846)       0:03:16.278 ***
2026-09-16 15:52:12.549139 | instance | ===============================================================================
2026-09-16 15:52:12.549152 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 85.25s
2026-09-16 15:52:12.549165 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 45.85s
2026-09-16 15:52:12.549179 | instance | vexxhost.ceph.cephadm : Download "cephadm" ----------------------------- 17.55s
2026-09-16 15:52:12.549192 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 6.73s
2026-09-16 15:52:12.549206 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.85s
2026-09-16 15:52:12.549220 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.38s
2026-09-16 15:52:12.549256 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 3.06s
2026-09-16 15:52:12.549279 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.32s
2026-09-16 15:52:12.549454 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.78s
2026-09-16 15:52:12.549794 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.61s
2026-09-16 15:52:12.550078 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.48s
2026-09-16 15:52:12.550359 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.15s
2026-09-16 15:52:12.550660 | instance | Gathering Facts --------------------------------------------------------- 1.09s
2026-09-16 15:52:12.550941 | instance | vexxhost.containers.download_artifact : Download item ------------------- 1.00s
2026-09-16 15:52:12.551218 | instance | Gathering Facts --------------------------------------------------------- 0.97s
2026-09-16 15:52:12.551498 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.94s
2026-09-16 15:52:12.551813 | instance | Gathering Facts --------------------------------------------------------- 0.93s
2026-09-16 15:52:12.552094 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 0.91s
2026-09-16 15:52:12.552381 | instance | vexxhost.containers.containerd : Create systemd service file for containerd --- 0.73s
2026-09-16 15:52:12.552686 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.71s
2026-09-16 15:52:12.684196 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-16 15:52:12.684352 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-16 15:52:12.684442 | instance | ERROR    Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-16 15:52:13.066462 | instance | ERROR
2026-09-16 15:52:13.066696 | instance | {
2026-09-16 15:52:13.066741 | instance |   "delta": "0:04:10.189384",
2026-09-16 15:52:13.066775 | instance |   "end": "2026-09-16 15:52:12.735734",
2026-09-16 15:52:13.066806 | instance |   "msg": "non-zero return code",
2026-09-16 15:52:13.066836 | instance |   "rc": 2,
2026-09-16 15:52:13.066865 | instance |   "start": "2026-09-16 15:48:02.546350"
2026-09-16 15:52:13.066895 | instance | }
failure
2026-09-16 15:52:13.074179 | 
2026-09-16 15:52:13.074254 | PLAY RECAP
2026-09-16 15:52:13.074321 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-16 15:52:13.074358 | 
2026-09-16 15:52:13.218545 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-16 15:52:13.222330 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-16 15:52:13.850627 | 
2026-09-16 15:52:13.850968 | PLAY [all]
2026-09-16 15:52:13.867310 | 
2026-09-16 15:52:13.867508 | TASK [gather-host-logs : creating directory for system status]
2026-09-16 15:52:14.234717 | instance | changed
2026-09-16 15:52:14.242518 | 
2026-09-16 15:52:14.242715 | TASK [gather-host-logs : Get logs for each host]
2026-09-16 15:52:14.624262 | instance | + systemd-cgls --full --all --no-pager
2026-09-16 15:52:14.635437 | instance | + ip addr
2026-09-16 15:52:14.638266 | instance | + ip route
2026-09-16 15:52:14.639949 | instance | + lsblk
2026-09-16 15:52:14.643464 | instance | + mount
2026-09-16 15:52:14.646267 | instance | + docker images
2026-09-16 15:52:14.669122 | instance | + brctl show
2026-09-16 15:52:14.669558 | instance | /bin/bash: line 8: brctl: command not found
2026-09-16 15:52:14.669729 | instance | + ip neigh
2026-09-16 15:52:14.671764 | instance | + bridge fdb show
2026-09-16 15:52:14.675443 | instance | + ps aux --sort=-%mem
2026-09-16 15:52:14.689581 | instance | + dpkg -l
2026-09-16 15:52:14.702109 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-16 15:52:14.702515 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-16 15:52:14.717981 | instance | + '[' '!' -z '' ']'
2026-09-16 15:52:14.777188 | instance | ok: Runtime: 0:00:00.098794
2026-09-16 15:52:14.785667 | 
2026-09-16 15:52:14.785817 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-16 15:52:15.510874 | instance | changed:
2026-09-16 15:52:15.511159 | instance | created directory /var/lib/zuul/builds/b45ce1904bfc42e5a414a0f9c0b64d61/work/logs/instance
2026-09-16 15:52:15.511219 | instance | cd+++++++++ system/
2026-09-16 15:52:15.511264 | instance | >f+++++++++ system/brctl-show.txt
2026-09-16 15:52:15.511307 | instance | >f+++++++++ system/bridge-fdb.txt
2026-09-16 15:52:15.511346 | instance | >f+++++++++ system/docker-images.txt
2026-09-16 15:52:15.511390 | instance | >f+++++++++ system/ip-addr.txt
2026-09-16 15:52:15.511430 | instance | >f+++++++++ system/ip-neigh.txt
2026-09-16 15:52:15.511496 | instance | >f+++++++++ system/ip-route.txt
2026-09-16 15:52:15.511541 | instance | >f+++++++++ system/lsblk.txt
2026-09-16 15:52:15.511580 | instance | >f+++++++++ system/mount.txt
2026-09-16 15:52:15.511617 | instance | >f+++++++++ system/packages.txt
2026-09-16 15:52:15.511654 | instance | >f+++++++++ system/ps.txt
2026-09-16 15:52:15.511691 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-16 15:52:15.535351 | 
2026-09-16 15:52:15.535589 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-16 15:52:15.747425 | instance | changed: "values"
2026-09-16 15:52:15.934697 | instance | changed: "releases"
2026-09-16 15:52:16.007438 | 
2026-09-16 15:52:16.007731 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-16 15:52:16.293326 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-16 15:52:16.570146 | instance | ok: Runtime: 0:00:00.005779
2026-09-16 15:52:16.582112 | 
2026-09-16 15:52:16.582341 | TASK [helm-release-status : Downloads logs to executor]
2026-09-16 15:52:17.114503 | instance | changed:
2026-09-16 15:52:17.114749 | instance | cd+++++++++ helm/
2026-09-16 15:52:17.114803 | instance | cd+++++++++ helm/releases/
2026-09-16 15:52:17.114845 | instance | cd+++++++++ helm/values/
2026-09-16 15:52:17.131505 | 
2026-09-16 15:52:17.131683 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-16 15:52:17.357810 | instance | changed
2026-09-16 15:52:17.368876 | 
2026-09-16 15:52:17.369079 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-16 15:52:17.675658 | 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-16 15:52:17.675806 | instance |
2026-09-16 15:52:17.684415 | instance | environment: line 1: kubectl: command not found
2026-09-16 15:52:17.685831 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-16 15:52:17.687618 | instance | environment: line 1: kubectl: command not found
2026-09-16 15:52:17.690885 | instance | environment: line 1: kubectl: command not found
2026-09-16 15:52:17.691011 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-16 15:52:17.692271 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-16 15:52:17.694941 | instance | environment: line 1: kubectl: command not found
2026-09-16 15:52:17.696286 | instance | environment: line 1: kubectl: command not found
2026-09-16 15:52:17.696739 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-16 15:52:17.697636 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-16 15:52:17.919045 | instance | ok: Runtime: 0:00:00.032427
2026-09-16 15:52:17.929216 | 
2026-09-16 15:52:17.929447 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-16 15:52:18.134061 | instance | changed
2026-09-16 15:52:18.142474 | 
2026-09-16 15:52:18.142601 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-16 15:52:18.384069 | instance | environment: line 5: kubectl: command not found
2026-09-16 15:52:18.385286 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-16 15:52:18.385330 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-16 15:52:18.385720 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-16 15:52:18.678686 | instance | ok: Runtime: 0:00:00.011359
2026-09-16 15:52:18.686087 | 
2026-09-16 15:52:18.686232 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-16 15:52:19.238616 | instance | changed:
2026-09-16 15:52:19.238831 | instance | cd+++++++++ objects/
2026-09-16 15:52:19.238874 | instance | cd+++++++++ objects/cluster/
2026-09-16 15:52:19.238905 | instance | cd+++++++++ objects/namespaced/
2026-09-16 15:52:19.250062 | 
2026-09-16 15:52:19.250962 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-16 15:52:19.494534 | instance | changed
2026-09-16 15:52:19.498947 | 
2026-09-16 15:52:19.499050 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-16 15:52:19.726678 | instance | changed
2026-09-16 15:52:19.733110 | 
2026-09-16 15:52:19.733226 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-16 15:52:20.027042 | instance | environment: line 3: kubectl: command not found
2026-09-16 15:52:20.273888 | instance | ok: Runtime: 0:00:00.011796
2026-09-16 15:52:20.283250 | 
2026-09-16 15:52:20.312813 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-16 15:52:20.855771 | instance | changed:
2026-09-16 15:52:20.856047 | instance | cd+++++++++ pod-logs/
2026-09-16 15:52:20.856105 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-16 15:52:20.871811 | 
2026-09-16 15:52:20.871952 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-16 15:52:21.097786 | instance | changed
2026-09-16 15:52:21.102117 | 
2026-09-16 15:52:21.102188 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-16 15:52:21.368593 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-16 15:52:21.630368 | instance | ok: Runtime: 0:00:00.052406
2026-09-16 15:52:21.636452 | 
2026-09-16 15:52:21.636570 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-16 15:52:21.890076 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-16 15:52:21.931014 | instance | ceph-mgr endpoints:
2026-09-16 15:52:22.177617 | instance | ok: Runtime: 0:00:00.048123
2026-09-16 15:52:22.184711 | 
2026-09-16 15:52:22.184897 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-16 15:52:22.443843 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-16 15:52:22.728385 | instance | ok: Runtime: 0:00:00.053158
2026-09-16 15:52:22.739531 | 
2026-09-16 15:52:22.739763 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-16 15:52:23.295334 | instance | changed: cd+++++++++ prometheus/
2026-09-16 15:52:23.313363 | 
2026-09-16 15:52:23.313602 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-16 15:52:23.546047 | instance | changed
2026-09-16 15:52:23.550303 | 
2026-09-16 15:52:23.550374 | TASK [gather-selenium-data : Get selenium data]
2026-09-16 15:52:23.859730 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-16 15:52:23.861638 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-16 15:52:24.078671 | instance | ERROR
2026-09-16 15:52:24.078879 | instance | {
2026-09-16 15:52:24.078907 | instance |   "delta": "0:00:00.008839",
2026-09-16 15:52:24.078925 | instance |   "end": "2026-09-16 15:52:23.862148",
2026-09-16 15:52:24.078942 | instance |   "msg": "non-zero return code",
2026-09-16 15:52:24.078959 | instance |   "rc": 1,
2026-09-16 15:52:24.078986 | instance |   "start": "2026-09-16 15:52:23.853309"
2026-09-16 15:52:24.079003 | instance | }
2026-09-16 15:52:24.079024 | instance | ERROR: Ignoring Errors
2026-09-16 15:52:24.083330 | 
2026-09-16 15:52:24.083405 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-16 15:52:24.633665 | instance | changed: cd+++++++++ selenium/
2026-09-16 15:52:24.641495 | 
2026-09-16 15:52:24.641634 | PLAY RECAP
2026-09-16 15:52:24.641702 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-16 15:52:24.641755 | 
2026-09-16 15:52:24.857787 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-16 15:52:24.861030 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-16 15:52:25.462046 | 
2026-09-16 15:52:25.462585 | PLAY [all]
2026-09-16 15:52:25.475200 | 
2026-09-16 15:52:25.475344 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-16 15:52:25.530004 | instance | skipping: Conditional result was False
2026-09-16 15:52:25.537218 | 
2026-09-16 15:52:25.537402 | TASK [fetch-output : Set log path for single node]
2026-09-16 15:52:25.581846 | instance | ok
2026-09-16 15:52:25.591079 | 
2026-09-16 15:52:25.591248 | LOOP [fetch-output : Ensure local output dirs]
2026-09-16 15:52:26.029628 | instance -> localhost | ok: "/var/lib/zuul/builds/b45ce1904bfc42e5a414a0f9c0b64d61/work/logs"
2026-09-16 15:52:26.248909 | instance -> localhost | changed: "/var/lib/zuul/builds/b45ce1904bfc42e5a414a0f9c0b64d61/work/artifacts"
2026-09-16 15:52:26.450682 | instance -> localhost | changed: "/var/lib/zuul/builds/b45ce1904bfc42e5a414a0f9c0b64d61/work/docs"
2026-09-16 15:52:26.474339 | 
2026-09-16 15:52:26.474507 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-16 15:52:27.155877 | instance | changed: .d..t...... ./
2026-09-16 15:52:27.156420 | instance | changed: All items complete
2026-09-16 15:52:27.156488 | 
2026-09-16 15:52:27.597981 | instance | changed: .d..t...... ./
2026-09-16 15:52:28.101905 | instance | changed: .d..t...... ./
2026-09-16 15:52:28.131836 | 
2026-09-16 15:52:28.132049 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-16 15:52:28.618158 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.009481
2026-09-16 15:52:28.883654 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007843
2026-09-16 15:52:28.903777 | 
2026-09-16 15:52:28.903913 | PLAY [all]
2026-09-16 15:52:28.911852 | 
2026-09-16 15:52:28.912021 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-16 15:52:29.351902 | instance | changed
2026-09-16 15:52:29.361945 | 
2026-09-16 15:52:29.362103 | PLAY RECAP
2026-09-16 15:52:29.362189 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-16 15:52:29.362231 | 
2026-09-16 15:52:29.530654 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-16 15:52:29.533331 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-16 15:52:30.141446 | 
2026-09-16 15:52:30.141609 | PLAY [localhost]
2026-09-16 15:52:30.153830 | 
2026-09-16 15:52:30.154042 | TASK [Generate Zuul manifest]
2026-09-16 15:52:30.171350 | localhost | ok
2026-09-16 15:52:30.186212 | 
2026-09-16 15:52:30.186360 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-16 15:52:30.652357 | localhost | changed
2026-09-16 15:52:30.669949 | 
2026-09-16 15:52:30.670089 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-16 15:52:30.701911 | localhost | ok
2026-09-16 15:52:30.712081 | 
2026-09-16 15:52:30.712267 | TASK [Upload logs]
2026-09-16 15:52:30.742846 | localhost | ok
2026-09-16 15:52:30.822264 | 
2026-09-16 15:52:30.822409 | TASK [Set zuul-log-path fact]
2026-09-16 15:52:30.849387 | localhost | ok
2026-09-16 15:52:30.860036 | 
2026-09-16 15:52:30.860173 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-16 15:52:30.897226 | localhost | ok
2026-09-16 15:52:30.904446 | 
2026-09-16 15:52:30.904593 | TASK [upload-logs : Create log directories]
2026-09-16 15:52:31.358323 | localhost | changed
2026-09-16 15:52:31.364890 | 
2026-09-16 15:52:31.365026 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-16 15:52:31.876089 | localhost -> localhost | ok: Runtime: 0:00:00.003628
2026-09-16 15:52:31.886122 | 
2026-09-16 15:52:31.886293 | TASK [upload-logs : Upload logs to log server]
2026-09-16 15:52:32.399070 | localhost | Output suppressed because no_log was given
2026-09-16 15:52:32.449021 | 
2026-09-16 15:52:32.449174 | LOOP [upload-logs : Compress console log and json output]
2026-09-16 15:52:32.498714 | localhost | skipping: Conditional result was False
2026-09-16 15:52:32.506409 | localhost | skipping: Conditional result was False
2026-09-16 15:52:32.520400 | 
2026-09-16 15:52:32.520531 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-16 15:52:32.566664 | localhost | skipping: Conditional result was False
2026-09-16 15:52:32.567047 | 
2026-09-16 15:52:32.570756 | localhost | skipping: Conditional result was False
2026-09-16 15:52:32.580347 | 
2026-09-16 15:52:32.580448 | LOOP [upload-logs : Upload console log and json output]