2026-09-02 15:52:37.930598 | Job console starting
2026-09-02 15:52:37.947624 | Updating git repos
2026-09-02 15:52:41.021086 | Cloning repos into workspace
2026-09-02 15:52:59.974600 | Restoring repo states
2026-09-02 15:53:16.244149 | Merging changes
2026-09-02 15:53:17.837743 | Checking out repos
2026-09-02 15:53:20.439345 | Preparing playbooks
2026-09-02 15:53:30.048286 | Running Ansible setup
2026-09-02 15:53:36.543485 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-02 15:53:37.156453 | 
2026-09-02 15:53:37.156615 | PLAY [localhost]
2026-09-02 15:53:37.165928 | 
2026-09-02 15:53:37.166196 | TASK [Gathering Facts]
2026-09-02 15:53:38.067274 | localhost | ok
2026-09-02 15:53:38.076531 | 
2026-09-02 15:53:38.076632 | TASK [Setup log path fact]
2026-09-02 15:53:38.098252 | localhost | ok
2026-09-02 15:53:38.110994 | 
2026-09-02 15:53:38.111060 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-02 15:53:38.141780 | localhost | ok
2026-09-02 15:53:38.149706 | 
2026-09-02 15:53:38.149792 | TASK [emit-job-header : Print job information]
2026-09-02 15:53:38.192402 | # Job Information
2026-09-02 15:53:38.192551 | Ansible Version: 2.16.18
2026-09-02 15:53:38.192594 | Job: atmosphere-molecule-csi-rbd
2026-09-02 15:53:38.192625 | Pipeline: check
2026-09-02 15:53:38.192652 | Executor: 2d72f0692154
2026-09-02 15:53:38.192679 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4360
2026-09-02 15:53:38.192709 | Event ID: 84c10580-a6e5-11f1-98d6-99207c55d62b
2026-09-02 15:53:38.196385 | 
2026-09-02 15:53:38.196467 | LOOP [emit-job-header : Print node information]
2026-09-02 15:53:38.292820 | localhost | ok:
2026-09-02 15:53:38.293491 | localhost | # Node Information
2026-09-02 15:53:38.293524 | localhost | Inventory Hostname: instance
2026-09-02 15:53:38.293547 | localhost | Hostname: np0000191853
2026-09-02 15:53:38.293567 | localhost | Username: zuul
2026-09-02 15:53:38.293590 | localhost | Distro: Ubuntu 22.04
2026-09-02 15:53:38.293610 | localhost | Provider: yul1
2026-09-02 15:53:38.293629 | localhost | Region: ca-ymq-1
2026-09-02 15:53:38.293648 | localhost | Label: ubuntu-jammy
2026-09-02 15:53:38.293667 | localhost | Product Name: OpenStack Nova
2026-09-02 15:53:38.293686 | localhost | Interface IP: 162.253.55.193
2026-09-02 15:53:38.302431 | 
2026-09-02 15:53:38.302559 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-02 15:53:38.723431 | localhost -> localhost | changed
2026-09-02 15:53:38.732904 | 
2026-09-02 15:53:38.733766 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-02 15:53:39.565793 | localhost -> localhost | changed
2026-09-02 15:53:39.574413 | 
2026-09-02 15:53:39.574524 | PLAY [all]
2026-09-02 15:53:39.586245 | 
2026-09-02 15:53:39.586392 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-02 15:53:39.824555 | instance -> localhost | ok
2026-09-02 15:53:39.834694 | 
2026-09-02 15:53:39.834788 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-02 15:53:39.868345 | instance | ok
2026-09-02 15:53:39.884378 | instance | included: /var/lib/zuul/builds/5328397b28b24344ab1df38f181ff927/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-02 15:53:39.889916 | 
2026-09-02 15:53:39.889977 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-02 15:53:40.591838 | instance -> localhost | Generating public/private rsa key pair.
2026-09-02 15:53:40.592045 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/5328397b28b24344ab1df38f181ff927/work/5328397b28b24344ab1df38f181ff927_id_rsa
2026-09-02 15:53:40.592087 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/5328397b28b24344ab1df38f181ff927/work/5328397b28b24344ab1df38f181ff927_id_rsa.pub
2026-09-02 15:53:40.592120 | instance -> localhost | The key fingerprint is:
2026-09-02 15:53:40.592152 | instance -> localhost | SHA256:bZncz0mJT/xrfVVUAZy9F7CE0LdqIgFe3MKNYv3fLAY zuul-build-sshkey
2026-09-02 15:53:40.592200 | instance -> localhost | The key's randomart image is:
2026-09-02 15:53:40.592231 | instance -> localhost | +---[RSA 3072]----+
2026-09-02 15:53:40.592267 | instance -> localhost | |      + +.o o+=.+|
2026-09-02 15:53:40.592299 | instance -> localhost | |     + B o o.+.o.|
2026-09-02 15:53:40.592328 | instance -> localhost | |    o + o   ....o|
2026-09-02 15:53:40.592357 | instance -> localhost | |     . . E + + oo|
2026-09-02 15:53:40.592386 | instance -> localhost | |        S O * = o|
2026-09-02 15:53:40.592414 | instance -> localhost | |       . o * O o.|
2026-09-02 15:53:40.592443 | instance -> localhost | |        . + . = +|
2026-09-02 15:53:40.592472 | instance -> localhost | |               .+|
2026-09-02 15:53:40.592504 | instance -> localhost | |              ...|
2026-09-02 15:53:40.592534 | instance -> localhost | +----[SHA256]-----+
2026-09-02 15:53:40.592600 | instance -> localhost | ok: Runtime: 0:00:00.271265
2026-09-02 15:53:40.601644 | 
2026-09-02 15:53:40.601733 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-02 15:53:40.634458 | instance | ok
2026-09-02 15:53:40.645502 | instance | included: /var/lib/zuul/builds/5328397b28b24344ab1df38f181ff927/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-02 15:53:40.652816 | 
2026-09-02 15:53:40.652880 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-02 15:53:40.677807 | instance | skipping: Conditional result was False
2026-09-02 15:53:40.687716 | 
2026-09-02 15:53:40.688266 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-02 15:53:41.165490 | instance | changed
2026-09-02 15:53:41.172286 | 
2026-09-02 15:53:41.172355 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-02 15:53:41.380813 | instance | ok
2026-09-02 15:53:41.386747 | 
2026-09-02 15:53:41.386812 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-02 15:53:41.951672 | instance | changed
2026-09-02 15:53:41.959726 | 
2026-09-02 15:53:41.959812 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-02 15:53:42.481776 | instance | changed
2026-09-02 15:53:42.489899 | 
2026-09-02 15:53:42.490010 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-02 15:53:42.515113 | instance | skipping: Conditional result was False
2026-09-02 15:53:42.524281 | 
2026-09-02 15:53:42.524373 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-02 15:53:42.883419 | instance -> localhost | changed
2026-09-02 15:53:42.898364 | 
2026-09-02 15:53:42.898456 | TASK [add-build-sshkey : Add back temp key]
2026-09-02 15:53:43.228188 | instance -> localhost | Identity added: /var/lib/zuul/builds/5328397b28b24344ab1df38f181ff927/work/5328397b28b24344ab1df38f181ff927_id_rsa (zuul-build-sshkey)
2026-09-02 15:53:43.228564 | instance -> localhost | ok: Runtime: 0:00:00.013310
2026-09-02 15:53:43.236479 | 
2026-09-02 15:53:43.236541 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-02 15:53:43.589746 | instance | ok
2026-09-02 15:53:43.594374 | 
2026-09-02 15:53:43.594440 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-02 15:53:43.619577 | instance | skipping: Conditional result was False
2026-09-02 15:53:43.635447 | 
2026-09-02 15:53:43.635538 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-02 15:53:44.004689 | instance | ok
2026-09-02 15:53:44.012844 | 
2026-09-02 15:53:44.012940 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-02 15:53:47.888707 | instance | Output suppressed because no_log was given
2026-09-02 15:53:47.897399 | 
2026-09-02 15:53:47.897475 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-02 15:53:48.111955 | instance | ok: "logs"
2026-09-02 15:53:48.112201 | instance | ok: All items complete
2026-09-02 15:53:48.112230 | 
2026-09-02 15:53:48.261088 | instance | ok: "artifacts"
2026-09-02 15:53:48.446822 | instance | ok: "docs"
2026-09-02 15:53:48.639116 | 
2026-09-02 15:53:48.639242 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-02 15:53:48.855713 | instance | changed: "logs"
2026-09-02 15:53:49.035313 | instance | changed: "artifacts"
2026-09-02 15:53:49.188682 | instance | changed: "docs"
2026-09-02 15:53:49.208522 | 
2026-09-02 15:53:49.553658 | PLAY RECAP
2026-09-02 15:53:49.553929 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-02 15:53:49.554006 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-02 15:53:49.554056 | 
2026-09-02 15:53:49.756322 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-02 15:53:49.758656 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-02 15:53:50.347229 | 
2026-09-02 15:53:50.347353 | PLAY [all]
2026-09-02 15:53:50.358758 | 
2026-09-02 15:53:50.358841 | TASK [setup-uv : Extract archive]
2026-09-02 15:53:52.715948 | instance | changed
2026-09-02 15:53:52.721655 | 
2026-09-02 15:53:52.721733 | TASK [setup-uv : Print version]
2026-09-02 15:53:53.078160 | instance | uv 0.8.13
2026-09-02 15:53:53.261033 | instance | ok: Runtime: 0:00:00.016374
2026-09-02 15:53:53.271102 | 
2026-09-02 15:53:53.271179 | TASK [Install Ansible collection siblings]
2026-09-02 15:54:02.542654 | instance | changed:
2026-09-02 15:54:02.542837 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-09-02 15:54:02.542866 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-02 15:54:02.542890 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-09-02 15:54:02.542910 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-02 15:54:02.542931 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-02 15:54:02.542953 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-09-02 15:54:02.542975 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-02 15:54:02.542997 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-09-02 15:54:02.543018 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-02 15:54:02.543038 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-02 15:54:02.543058 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-02 15:54:02.543077 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-02 15:54:02.543096 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-09-02 15:54:02.543115 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-09-02 15:54:02.543134 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0
2026-09-02 15:54:02.543153 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-09-02 15:54:02.543172 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-09-02 15:54:02.543360 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-09-02 15:54:02.543406 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-09-02 15:54:02.543430 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-09-02 15:54:02.543451 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0
2026-09-02 15:54:02.543470 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-09-02 15:54:02.543492 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-09-02 15:54:02.543526 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-09-02 15:54:02.543546 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0
2026-09-02 15:54:02.543565 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-02 15:54:02.543583 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-02 15:54:02.543602 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-09-02 15:54:02.543620 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-09-02 15:54:02.543639 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-09-02 15:54:02.543657 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-09-02 15:54:02.543678 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-09-02 15:54:02.543700 | instance | Checked out ansible.netcommon to 1.2.0
2026-09-02 15:54:02.543735 | 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-02 15:54:02.543762 | instance | Starting galaxy collection install process
2026-09-02 15:54:02.543781 | instance | Process install dependency map
2026-09-02 15:54:02.543800 | instance | Starting collection install process
2026-09-02 15:54:02.543822 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-09-02 15:54:02.543840 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-09-02 15:54:02.543859 | instance | ansible.netcommon:* was installed successfully
2026-09-02 15:54:02.543877 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-09-02 15:54:02.543895 | instance | Creating virtual environment at: .venv
2026-09-02 15:54:02.543913 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-02 15:54:02.543932 | instance | Downloading openstacksdk (1.7MiB)
2026-09-02 15:54:02.543950 | instance | Downloading netaddr (2.2MiB)
2026-09-02 15:54:02.543969 | instance | Downloading kubernetes (1.9MiB)
2026-09-02 15:54:02.543988 | instance | Downloading setuptools (1.1MiB)
2026-09-02 15:54:02.544006 | instance | Downloading ansible-core (2.1MiB)
2026-09-02 15:54:02.544025 | instance | Downloading cryptography (4.2MiB)
2026-09-02 15:54:02.544044 | instance | Downloading rjsonnet (1.2MiB)
2026-09-02 15:54:02.544063 | instance | Downloading pygments (1.2MiB)
2026-09-02 15:54:02.544082 | instance |    Building pyperclip==1.9.0
2026-09-02 15:54:02.544100 | instance |  Downloading rjsonnet
2026-09-02 15:54:02.544120 | instance |  Downloading netaddr
2026-09-02 15:54:02.544139 | instance |  Downloading pygments
2026-09-02 15:54:02.544158 | instance |  Downloading cryptography
2026-09-02 15:54:02.544176 | instance |  Downloading setuptools
2026-09-02 15:54:02.544194 | instance |  Downloading kubernetes
2026-09-02 15:54:02.544212 | instance |  Downloading ansible-core
2026-09-02 15:54:02.544230 | instance |  Downloading openstacksdk
2026-09-02 15:54:02.544248 | instance |       Built pyperclip==1.9.0
2026-09-02 15:54:02.544266 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-09-02 15:54:02.544284 | instance | Installed 79 packages in 123ms
2026-09-02 15:54:02.544302 | instance | Checked out ansible.posix to 1.6.0
2026-09-02 15:54:02.544321 | 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-02 15:54:02.544339 | instance | Starting galaxy collection install process
2026-09-02 15:54:02.544358 | instance | Process install dependency map
2026-09-02 15:54:02.544376 | instance | Starting collection install process
2026-09-02 15:54:02.544396 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-02 15:54:02.544420 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-02 15:54:02.544440 | instance | ansible.posix:1.6.0 was installed successfully
2026-09-02 15:54:02.544459 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 15:54:02.544477 | instance | 2.17.12
2026-09-02 15:54:02.544496 | instance | Checked out ansible.utils to v6.1.0
2026-09-02 15:54:02.544515 | 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-02 15:54:02.544533 | instance | Starting galaxy collection install process
2026-09-02 15:54:02.544551 | instance | Process install dependency map
2026-09-02 15:54:02.544569 | instance | Starting collection install process
2026-09-02 15:54:02.544588 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-09-02 15:54:02.544606 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-09-02 15:54:02.544625 | instance | ansible.utils:6.1.0 was installed successfully
2026-09-02 15:54:02.544644 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 15:54:02.544663 | instance | 2.17.12
2026-09-02 15:54:02.544682 | instance | Checked out atmosphere.common to v0.7.0
2026-09-02 15:54:02.544701 | 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-02 15:54:02.544720 | instance | Starting galaxy collection install process
2026-09-02 15:54:02.544739 | instance | Process install dependency map
2026-09-02 15:54:02.544757 | instance | Starting collection install process
2026-09-02 15:54:02.544776 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-09-02 15:54:02.544794 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-09-02 15:54:02.544812 | instance | atmosphere.common:0.7.0 was installed successfully
2026-09-02 15:54:02.544830 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 15:54:02.544848 | instance | 2.17.12
2026-09-02 15:54:02.544867 | instance | Checked out community.crypto to 2.2.3
2026-09-02 15:54:02.544886 | 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-02 15:54:02.544904 | instance | Starting galaxy collection install process
2026-09-02 15:54:02.544922 | instance | Process install dependency map
2026-09-02 15:54:02.544941 | instance | Starting collection install process
2026-09-02 15:54:02.544976 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-02 15:54:02.544999 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-02 15:54:02.545017 | instance | community.crypto:2.2.3 was installed successfully
2026-09-02 15:54:02.545036 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 15:54:02.545054 | instance | 2.17.12
2026-09-02 15:54:02.545073 | instance | Checked out community.general to 7.3.0
2026-09-02 15:54:02.545092 | 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-02 15:54:02.545110 | instance | Starting galaxy collection install process
2026-09-02 15:54:02.545134 | instance | Process install dependency map
2026-09-02 15:54:02.545153 | instance | Starting collection install process
2026-09-02 15:54:02.545171 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-02 15:54:02.545190 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-02 15:54:02.545208 | instance | community.general:7.3.0 was installed successfully
2026-09-02 15:54:02.545227 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 15:54:02.545245 | instance | 2.17.12
2026-09-02 15:54:02.545263 | instance | Checked out community.mysql to 3.6.0
2026-09-02 15:54:02.545282 | 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-02 15:54:02.545300 | instance | Starting galaxy collection install process
2026-09-02 15:54:02.545320 | instance | Process install dependency map
2026-09-02 15:54:02.545339 | instance | Starting collection install process
2026-09-02 15:54:02.545359 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-09-02 15:54:02.545377 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-09-02 15:54:02.545396 | instance | community.mysql:3.6.0 was installed successfully
2026-09-02 15:54:02.545414 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 15:54:02.545433 | instance | 2.17.12
2026-09-02 15:54:02.545452 | instance | Checked out kubernetes.core to 2.4.0
2026-09-02 15:54:02.545471 | 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-02 15:54:02.545490 | instance | Starting galaxy collection install process
2026-09-02 15:54:02.545509 | instance | Process install dependency map
2026-09-02 15:54:02.545528 | instance | Starting collection install process
2026-09-02 15:54:02.545547 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-02 15:54:02.545565 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-02 15:54:02.545584 | instance | kubernetes.core:2.4.0 was installed successfully
2026-09-02 15:54:02.545602 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 15:54:02.545621 | instance | 2.17.12
2026-09-02 15:54:02.545639 | instance | Checked out openstack.cloud to 2.6.0
2026-09-02 15:54:02.545658 | 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-02 15:54:02.545676 | instance | Starting galaxy collection install process
2026-09-02 15:54:02.545695 | instance | Process install dependency map
2026-09-02 15:54:02.545713 | instance | Starting collection install process
2026-09-02 15:54:02.545731 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-02 15:54:02.545749 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-02 15:54:02.545767 | instance | openstack.cloud:2.6.0 was installed successfully
2026-09-02 15:54:02.545785 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 15:54:02.545804 | instance | 2.17.12
2026-09-02 15:54:02.545822 | instance | Checked out vexxhost.ceph to v4.2.0
2026-09-02 15:54:02.545841 | 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-02 15:54:02.545865 | instance | Starting galaxy collection install process
2026-09-02 15:54:02.545884 | instance | Process install dependency map
2026-09-02 15:54:02.545902 | instance | Starting collection install process
2026-09-02 15:54:02.545920 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-09-02 15:54:02.545938 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-09-02 15:54:02.545956 | instance | vexxhost.ceph:4.2.0 was installed successfully
2026-09-02 15:54:02.545974 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 15:54:02.545993 | instance | 2.17.12
2026-09-02 15:54:02.546011 | instance | Checked out vexxhost.containers to v1.6.8
2026-09-02 15:54:02.546030 | 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-02 15:54:02.546048 | instance | Starting galaxy collection install process
2026-09-02 15:54:02.546066 | instance | Process install dependency map
2026-09-02 15:54:02.546084 | instance | Starting collection install process
2026-09-02 15:54:02.546103 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-02 15:54:02.546121 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-02 15:54:02.546140 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-09-02 15:54:02.546158 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 15:54:02.546177 | instance | 2.17.12
2026-09-02 15:54:02.546195 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-09-02 15:54:02.546213 | 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-02 15:54:02.546232 | instance | Starting galaxy collection install process
2026-09-02 15:54:02.546251 | instance | Process install dependency map
2026-09-02 15:54:02.546269 | instance | Starting collection install process
2026-09-02 15:54:02.546287 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-02 15:54:02.546306 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-02 15:54:02.546325 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-09-02 15:54:02.546343 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-09-02 15:54:02.546361 | instance | 2.17.12
2026-09-02 15:54:02.552435 | 
2026-09-02 15:54:02.552485 | PLAY RECAP
2026-09-02 15:54:02.552529 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-02 15:54:02.552550 | 
2026-09-02 15:54:02.699615 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-02 15:54:02.703655 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-02 15:54:03.269287 | 
2026-09-02 15:54:03.269434 | PLAY [all]
2026-09-02 15:54:03.280690 | 
2026-09-02 15:54:03.280780 | TASK [Install "jq" for log collection]
2026-09-02 15:54:13.699809 | instance | changed
2026-09-02 15:54:13.702151 | 
2026-09-02 15:54:13.702210 | PLAY RECAP
2026-09-02 15:54:13.702260 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-02 15:54:13.702308 | 
2026-09-02 15:54:13.818605 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-09-02 15:54:13.822763 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-02 15:54:14.399714 | 
2026-09-02 15:54:14.399829 | PLAY [all]
2026-09-02 15:54:14.410806 | 
2026-09-02 15:54:14.410876 | LOOP [Check inventory directories]
2026-09-02 15:54:14.778353 | instance | ok: "src/github.com/vexxhost/atmosphere"
2026-09-02 15:54:14.942335 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory"
2026-09-02 15:54:15.116194 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory"
2026-09-02 15:54:15.135487 | 
2026-09-02 15:54:15.135675 | LOOP [Copy inventory file for Zuul]
2026-09-02 15:54:15.989289 | instance | changed:
2026-09-02 15:54:15.989586 | instance | {
2026-09-02 15:54:15.989630 | instance |   "ansible_loop_var": "item",
2026-09-02 15:54:15.989661 | instance |   "changed": false,
2026-09-02 15:54:15.989688 | instance |   "failed": false,
2026-09-02 15:54:15.989719 | instance |   "invocation": {
2026-09-02 15:54:15.989745 | instance |     "module_args": {
2026-09-02 15:54:15.989771 | instance |       "checksum_algorithm": "sha1",
2026-09-02 15:54:15.989796 | instance |       "follow": true,
2026-09-02 15:54:15.989820 | instance |       "get_attributes": true,
2026-09-02 15:54:15.989845 | instance |       "get_checksum": true,
2026-09-02 15:54:15.989870 | instance |       "get_mime": true,
2026-09-02 15:54:15.989894 | instance |       "path": "src/github.com/vexxhost/atmosphere"
2026-09-02 15:54:15.989918 | instance |     }
2026-09-02 15:54:15.989943 | instance |   },
2026-09-02 15:54:15.989966 | instance |   "item": "src/github.com/vexxhost/atmosphere",
2026-09-02 15:54:15.989990 | instance |   "stat": {
2026-09-02 15:54:15.990014 | instance |     "atime": 1788364435.1653283,
2026-09-02 15:54:15.990038 | instance |     "attr_flags": "e",
2026-09-02 15:54:15.990062 | instance |     "attributes": [
2026-09-02 15:54:15.990086 | instance |       "extents"
2026-09-02 15:54:15.990109 | instance |     ],
2026-09-02 15:54:15.990133 | instance |     "block_size": 4096,
2026-09-02 15:54:15.990156 | instance |     "blocks": 8,
2026-09-02 15:54:15.990180 | instance |     "charset": "unknown",
2026-09-02 15:54:15.990205 | instance |     "ctime": 1788364434.0852773,
2026-09-02 15:54:15.990230 | instance |     "dev": 64513,
2026-09-02 15:54:15.990254 | instance |     "device_type": 0,
2026-09-02 15:54:15.990278 | instance |     "executable": true,
2026-09-02 15:54:15.990301 | instance |     "exists": true,
2026-09-02 15:54:15.990325 | instance |     "gid": 1000,
2026-09-02 15:54:15.990348 | instance |     "gr_name": "zuul",
2026-09-02 15:54:15.990371 | instance |     "inode": 1047863,
2026-09-02 15:54:15.990395 | instance |     "isblk": false,
2026-09-02 15:54:15.990419 | instance |     "ischr": false,
2026-09-02 15:54:15.990442 | instance |     "isdir": true,
2026-09-02 15:54:15.990466 | instance |     "isfifo": false,
2026-09-02 15:54:15.990490 | instance |     "isgid": false,
2026-09-02 15:54:15.990514 | instance |     "islnk": false,
2026-09-02 15:54:15.990537 | instance |     "isreg": false,
2026-09-02 15:54:15.990560 | instance |     "issock": false,
2026-09-02 15:54:15.990583 | instance |     "isuid": false,
2026-09-02 15:54:15.990606 | instance |     "mimetype": "unknown",
2026-09-02 15:54:15.990630 | instance |     "mode": "0755",
2026-09-02 15:54:15.990652 | instance |     "mtime": 1788364434.0852773,
2026-09-02 15:54:15.990675 | instance |     "nlink": 22,
2026-09-02 15:54:15.990698 | instance |     "path": "src/github.com/vexxhost/atmosphere",
2026-09-02 15:54:15.990721 | instance |     "pw_name": "zuul",
2026-09-02 15:54:15.990748 | instance |     "readable": true,
2026-09-02 15:54:15.990772 | instance |     "rgrp": true,
2026-09-02 15:54:15.990796 | instance |     "roth": true,
2026-09-02 15:54:15.990820 | instance |     "rusr": true,
2026-09-02 15:54:15.990843 | instance |     "size": 4096,
2026-09-02 15:54:15.990866 | instance |     "uid": 1000,
2026-09-02 15:54:15.990906 | instance |     "version": "3258829693",
2026-09-02 15:54:15.990931 | instance |     "wgrp": false,
2026-09-02 15:54:15.990956 | instance |     "woth": false,
2026-09-02 15:54:15.990981 | instance |     "writeable": true,
2026-09-02 15:54:15.991004 | instance |     "wusr": true,
2026-09-02 15:54:15.991028 | instance |     "xgrp": true,
2026-09-02 15:54:15.991051 | instance |     "xoth": true,
2026-09-02 15:54:15.991074 | instance |     "xusr": true
2026-09-02 15:54:15.991097 | instance |   }
2026-09-02 15:54:15.991121 | instance | }
2026-09-02 15:54:15.991169 | instance | ok: All items complete
2026-09-02 15:54:15.991198 | 
2026-09-02 15:54:15.992931 | instance | skipping: Conditional result was False
2026-09-02 15:54:15.993802 | instance | skipping: Conditional result was False
2026-09-02 15:54:16.008813 | 
2026-09-02 15:54:16.008993 | LOOP [Switch "ansible_host" to private IP]
2026-09-02 15:54:16.387215 | instance | changed: 1 replacements made
2026-09-02 15:54:16.388915 | instance | skipping: Conditional result was False
2026-09-02 15:54:16.389730 | instance | skipping: Conditional result was False
2026-09-02 15:54:16.407455 | 
2026-09-02 15:54:16.407596 | TASK [Run Molecule scenario]
2026-09-02 15:54:17.323000 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-09-02 15:54:17.694190 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-02 15:54:17.694312 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-09-02 15:54:28.432610 | instance | INFO     [csi > dependency] Executing
2026-09-02 15:54:28.432984 | instance | WARNING  [csi > dependency] Missing roles requirements file: requirements.yml
2026-09-02 15:54:28.433464 | instance | WARNING  [csi > dependency] Missing collections requirements file: collections.yml
2026-09-02 15:54:28.434223 | instance | WARNING  [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-02 15:54:28.448878 | instance | INFO     [csi > cleanup] Executing
2026-09-02 15:54:28.449540 | instance | WARNING  [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-02 15:54:28.463510 | instance | INFO     [csi > destroy] Executing
2026-09-02 15:54:28.463603 | instance | WARNING  [csi > destroy] Skipping, '--destroy=never' requested.
2026-09-02 15:54:28.463685 | instance | INFO     [csi > destroy] Executed: Successful
2026-09-02 15:54:28.473257 | instance | INFO     [csi > syntax] Executing
2026-09-02 15:54:30.020134 | instance |
2026-09-02 15:54:30.020413 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml
2026-09-02 15:54:30.096150 | instance | INFO     [csi > syntax] Executed: Successful
2026-09-02 15:54:30.112722 | instance | INFO     [csi > create] Executing
2026-09-02 15:54:30.116501 | instance | WARNING  [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-02 15:54:30.133335 | instance | INFO     [csi > prepare] Executing
2026-09-02 15:54:30.902485 | instance |
2026-09-02 15:54:30.902678 | instance | PLAY [Prepare] *****************************************************************
2026-09-02 15:54:30.902905 | instance |
2026-09-02 15:54:30.903169 | instance | TASK [Gathering Facts] *********************************************************
2026-09-02 15:54:30.903408 | instance | Wednesday 02 September 2026  15:54:30 +0000 (0:00:00.024)       0:00:00.024 ***
2026-09-02 15:54:32.187110 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-02 15:54:32.187325 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-02 15:54:32.187838 | instance | interpreter could change the meaning of that path. See
2026-09-02 15:54:32.187869 | instance | https://docs.ansible.com/ansible-
2026-09-02 15:54:32.188052 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-02 15:54:32.198040 | instance | ok: [instance]
2026-09-02 15:54:32.198243 | instance |
2026-09-02 15:54:32.198484 | instance | TASK [Configure short hostname] ************************************************
2026-09-02 15:54:32.198712 | instance | Wednesday 02 September 2026  15:54:32 +0000 (0:00:01.296)       0:00:01.320 ***
2026-09-02 15:54:32.945494 | instance | changed: [instance]
2026-09-02 15:54:32.945692 | instance |
2026-09-02 15:54:32.945943 | instance | TASK [Ensure hostname inside hosts file] ***************************************
2026-09-02 15:54:32.946243 | instance | Wednesday 02 September 2026  15:54:32 +0000 (0:00:00.746)       0:00:02.067 ***
2026-09-02 15:54:33.220031 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2026-09-02 15:54:33.220355 | instance | with a mode of 0700, this may cause issues when running as another user. To
2026-09-02 15:54:33.220675 | instance | avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-02 15:54:33.228937 | instance | changed: [instance]
2026-09-02 15:54:33.229252 | instance |
2026-09-02 15:54:33.229491 | instance | TASK [Purge "snapd" package] ***************************************************
2026-09-02 15:54:33.229744 | instance | Wednesday 02 September 2026  15:54:33 +0000 (0:00:00.284)       0:00:02.351 ***
2026-09-02 15:54:34.131083 | instance | ok: [instance]
2026-09-02 15:54:34.131159 | instance |
2026-09-02 15:54:34.131263 | instance | PLAY [Create devices for Ceph] *************************************************
2026-09-02 15:54:34.131363 | instance |
2026-09-02 15:54:34.131470 | instance | TASK [Gathering Facts] *********************************************************
2026-09-02 15:54:34.131581 | instance | Wednesday 02 September 2026  15:54:34 +0000 (0:00:00.902)       0:00:03.253 ***
2026-09-02 15:54:34.936428 | instance | ok: [instance]
2026-09-02 15:54:34.936488 | instance |
2026-09-02 15:54:34.936664 | instance | TASK [Install depedencies] *****************************************************
2026-09-02 15:54:34.936824 | instance | Wednesday 02 September 2026  15:54:34 +0000 (0:00:00.804)       0:00:04.058 ***
2026-09-02 15:55:00.015044 | instance | changed: [instance]
2026-09-02 15:55:00.015101 | instance |
2026-09-02 15:55:00.015113 | instance | TASK [Start up service] ********************************************************
2026-09-02 15:55:00.015124 | instance | Wednesday 02 September 2026  15:55:00 +0000 (0:00:25.077)       0:00:29.135 ***
2026-09-02 15:55:00.580564 | instance | ok: [instance]
2026-09-02 15:55:00.580631 | instance |
2026-09-02 15:55:00.580892 | instance | TASK [Generate lvm.conf] *******************************************************
2026-09-02 15:55:00.580926 | instance | Wednesday 02 September 2026  15:55:00 +0000 (0:00:00.567)       0:00:29.703 ***
2026-09-02 15:55:00.911308 | instance | ok: [instance]
2026-09-02 15:55:00.912114 | instance |
2026-09-02 15:55:00.912164 | instance | TASK [Write /etc/lvm/lvm.conf] *************************************************
2026-09-02 15:55:00.912176 | instance | Wednesday 02 September 2026  15:55:00 +0000 (0:00:00.329)       0:00:30.033 ***
2026-09-02 15:55:01.640476 | instance | changed: [instance]
2026-09-02 15:55:01.641094 | instance |
2026-09-02 15:55:01.641146 | instance | TASK [Get list of all loopback devices] ****************************************
2026-09-02 15:55:01.641314 | instance | Wednesday 02 September 2026  15:55:01 +0000 (0:00:00.729)       0:00:30.762 ***
2026-09-02 15:55:01.855990 | instance | ok: [instance]
2026-09-02 15:55:01.856707 | instance |
2026-09-02 15:55:01.856757 | instance | TASK [Fail if there is any existing loopback devices] **************************
2026-09-02 15:55:01.856769 | instance | Wednesday 02 September 2026  15:55:01 +0000 (0:00:00.215)       0:00:30.977 ***
2026-09-02 15:55:01.883386 | instance | skipping: [instance]
2026-09-02 15:55:01.884518 | instance |
2026-09-02 15:55:01.884567 | instance | TASK [Create devices for Ceph] *************************************************
2026-09-02 15:55:01.884582 | instance | Wednesday 02 September 2026  15:55:01 +0000 (0:00:00.027)       0:00:31.005 ***
2026-09-02 15:55:02.482645 | instance | changed: [instance] => (item=osd0)
2026-09-02 15:55:02.482840 | instance | changed: [instance] => (item=osd1)
2026-09-02 15:55:02.483220 | instance | changed: [instance] => (item=osd2)
2026-09-02 15:55:02.483449 | instance |
2026-09-02 15:55:02.483694 | instance | TASK [Set permissions on loopback devices] *************************************
2026-09-02 15:55:02.483962 | instance | Wednesday 02 September 2026  15:55:02 +0000 (0:00:00.599)       0:00:31.604 ***
2026-09-02 15:55:03.218714 | instance | changed: [instance] => (item=osd0)
2026-09-02 15:55:03.220095 | instance | changed: [instance] => (item=osd1)
2026-09-02 15:55:03.220161 | instance | changed: [instance] => (item=osd2)
2026-09-02 15:55:03.220177 | instance |
2026-09-02 15:55:03.220187 | instance | TASK [Start loop devices] ******************************************************
2026-09-02 15:55:03.220197 | instance | Wednesday 02 September 2026  15:55:03 +0000 (0:00:00.735)       0:00:32.340 ***
2026-09-02 15:55:03.888350 | instance | changed: [instance] => (item=osd0)
2026-09-02 15:55:03.888485 | instance | changed: [instance] => (item=osd1)
2026-09-02 15:55:03.889039 | instance | changed: [instance] => (item=osd2)
2026-09-02 15:55:03.889079 | instance |
2026-09-02 15:55:03.889085 | instance | TASK [Create a volume group for each loop device] ******************************
2026-09-02 15:55:03.889090 | instance | Wednesday 02 September 2026  15:55:03 +0000 (0:00:00.669)       0:00:33.010 ***
2026-09-02 15:55:06.999299 | instance | changed: [instance] => (item=osd0)
2026-09-02 15:55:07.000477 | instance | changed: [instance] => (item=osd1)
2026-09-02 15:55:07.000538 | instance | changed: [instance] => (item=osd2)
2026-09-02 15:55:07.000549 | instance |
2026-09-02 15:55:07.000559 | instance | TASK [Create a logical volume for each loop device] ****************************
2026-09-02 15:55:07.000569 | instance | Wednesday 02 September 2026  15:55:06 +0000 (0:00:03.111)       0:00:36.121 ***
2026-09-02 15:55:08.985121 | instance | changed: [instance] => (item=ceph-instance-osd0)
2026-09-02 15:55:08.985185 | instance | changed: [instance] => (item=ceph-instance-osd1)
2026-09-02 15:55:08.985215 | instance | changed: [instance] => (item=ceph-instance-osd2)
2026-09-02 15:55:08.985225 | instance |
2026-09-02 15:55:08.985235 | instance | PLAY RECAP *********************************************************************
2026-09-02 15:55:08.985244 | instance | instance                   : ok=15   changed=9    unreachable=0    failed=0    skipped=1    rescued=0    ignored=0
2026-09-02 15:55:08.985254 | instance |
2026-09-02 15:55:08.985262 | instance | Wednesday 02 September 2026  15:55:08 +0000 (0:00:01.983)       0:00:38.104 ***
2026-09-02 15:55:08.985271 | instance | ===============================================================================
2026-09-02 15:55:08.985280 | instance | Install depedencies ---------------------------------------------------- 25.08s
2026-09-02 15:55:08.985288 | instance | Create a volume group for each loop device ------------------------------ 3.11s
2026-09-02 15:55:08.985297 | instance | Create a logical volume for each loop device ---------------------------- 1.98s
2026-09-02 15:55:08.985306 | instance | Gathering Facts --------------------------------------------------------- 1.30s
2026-09-02 15:55:08.985315 | instance | Purge "snapd" package --------------------------------------------------- 0.90s
2026-09-02 15:55:08.985323 | instance | Gathering Facts --------------------------------------------------------- 0.80s
2026-09-02 15:55:08.985340 | instance | Configure short hostname ------------------------------------------------ 0.75s
2026-09-02 15:55:08.985467 | instance | Set permissions on loopback devices ------------------------------------- 0.74s
2026-09-02 15:55:08.985828 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.73s
2026-09-02 15:55:08.986118 | instance | Start loop devices ------------------------------------------------------ 0.67s
2026-09-02 15:55:08.986408 | instance | Create devices for Ceph ------------------------------------------------- 0.60s
2026-09-02 15:55:08.986717 | instance | Start up service -------------------------------------------------------- 0.57s
2026-09-02 15:55:08.987008 | instance | Generate lvm.conf ------------------------------------------------------- 0.33s
2026-09-02 15:55:08.987298 | instance | Ensure hostname inside hosts file --------------------------------------- 0.28s
2026-09-02 15:55:08.987584 | instance | Get list of all loopback devices ---------------------------------------- 0.22s
2026-09-02 15:55:08.987897 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s
2026-09-02 15:55:09.065351 | instance | INFO     [csi > prepare] Executed: Successful
2026-09-02 15:55:09.080117 | instance | INFO     [csi > converge] Executing
2026-09-02 15:55:10.432198 | instance |
2026-09-02 15:55:10.432598 | instance | PLAY [Debug CSI driver value] **************************************************
2026-09-02 15:55:10.432974 | instance |
2026-09-02 15:55:10.433341 | instance | TASK [Gathering Facts] *********************************************************
2026-09-02 15:55:10.433765 | instance | Wednesday 02 September 2026  15:55:10 +0000 (0:00:00.007)       0:00:00.007 ***
2026-09-02 15:55:11.459049 | instance | [WARNING]: Platform linux on host instance is using the discovered Python
2026-09-02 15:55:11.459436 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python
2026-09-02 15:55:11.459806 | instance | interpreter could change the meaning of that path. See
2026-09-02 15:55:11.460122 | instance | https://docs.ansible.com/ansible-
2026-09-02 15:55:11.460431 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information.
2026-09-02 15:55:11.472494 | instance | ok: [instance]
2026-09-02 15:55:11.472843 | instance |
2026-09-02 15:55:11.473179 | instance | TASK [Display CSI driver value and environment variable] ***********************
2026-09-02 15:55:11.473479 | instance | Wednesday 02 September 2026  15:55:11 +0000 (0:00:01.040)       0:00:01.047 ***
2026-09-02 15:55:11.519722 | instance | ok: [instance] => {
2026-09-02 15:55:11.520062 | instance |     "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER="
2026-09-02 15:55:11.520351 | instance | }
2026-09-02 15:55:11.520650 | instance |
2026-09-02 15:55:11.521039 | instance | PLAY [all] *********************************************************************
2026-09-02 15:55:11.521338 | instance |
2026-09-02 15:55:11.521671 | instance | TASK [Gathering Facts] *********************************************************
2026-09-02 15:55:11.522093 | instance | Wednesday 02 September 2026  15:55:11 +0000 (0:00:00.047)       0:00:01.094 ***
2026-09-02 15:55:12.500728 | instance | ok: [instance]
2026-09-02 15:55:12.501265 | instance |
2026-09-02 15:55:12.501759 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] *****************
2026-09-02 15:55:12.502205 | instance | Wednesday 02 September 2026  15:55:12 +0000 (0:00:00.980)       0:00:02.075 ***
2026-09-02 15:55:12.717923 | instance | ok: [instance]
2026-09-02 15:55:12.718276 | instance |
2026-09-02 15:55:12.718581 | instance | PLAY [Deploy Ceph monitors & managers] *****************************************
2026-09-02 15:55:12.718883 | instance |
2026-09-02 15:55:12.719175 | instance | TASK [Gathering Facts] *********************************************************
2026-09-02 15:55:12.719468 | instance | Wednesday 02 September 2026  15:55:12 +0000 (0:00:00.217)       0:00:02.292 ***
2026-09-02 15:55:13.685881 | instance | ok: [instance]
2026-09-02 15:55:13.686365 | instance |
2026-09-02 15:55:13.686845 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-02 15:55:13.687303 | instance | Wednesday 02 September 2026  15:55:13 +0000 (0:00:00.965)       0:00:03.258 ***
2026-09-02 15:55:14.001810 | instance | ok: [instance]
2026-09-02 15:55:14.002159 | instance |
2026-09-02 15:55:14.002471 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-02 15:55:14.002800 | instance | Wednesday 02 September 2026  15:55:13 +0000 (0:00:00.317)       0:00:03.575 ***
2026-09-02 15:55:14.057683 | instance | skipping: [instance]
2026-09-02 15:55:14.057805 | instance |
2026-09-02 15:55:14.057991 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-09-02 15:55:14.058232 | instance | Wednesday 02 September 2026  15:55:14 +0000 (0:00:00.056)       0:00:03.632 ***
2026-09-02 15:55:14.437583 | instance | changed: [instance]
2026-09-02 15:55:14.437834 | instance |
2026-09-02 15:55:14.438038 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-02 15:55:14.438235 | instance | Wednesday 02 September 2026  15:55:14 +0000 (0:00:00.380)       0:00:04.012 ***
2026-09-02 15:55:14.533807 | instance | ok: [instance] => {
2026-09-02 15:55:14.534072 | instance |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-09-02 15:55:14.534393 | instance | }
2026-09-02 15:55:14.534685 | instance |
2026-09-02 15:55:14.534975 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-02 15:55:14.535257 | instance | Wednesday 02 September 2026  15:55:14 +0000 (0:00:00.094)       0:00:04.107 ***
2026-09-02 15:55:15.273233 | instance | changed: [instance]
2026-09-02 15:55:15.273640 | instance |
2026-09-02 15:55:15.273981 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-02 15:55:15.274291 | instance | Wednesday 02 September 2026  15:55:15 +0000 (0:00:00.737)       0:00:04.844 ***
2026-09-02 15:55:15.330961 | instance | skipping: [instance]
2026-09-02 15:55:15.330987 | instance |
2026-09-02 15:55:15.330995 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-02 15:55:15.331001 | instance | Wednesday 02 September 2026  15:55:15 +0000 (0:00:00.061)       0:00:04.905 ***
2026-09-02 15:55:15.384990 | instance | skipping: [instance]
2026-09-02 15:55:15.385041 | instance |
2026-09-02 15:55:15.385049 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-02 15:55:15.385055 | instance | Wednesday 02 September 2026  15:55:15 +0000 (0:00:00.053)       0:00:04.959 ***
2026-09-02 15:55:15.620593 | instance | ok: [instance]
2026-09-02 15:55:15.620639 | instance |
2026-09-02 15:55:15.620647 | instance | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-09-02 15:55:15.620653 | instance | Wednesday 02 September 2026  15:55:15 +0000 (0:00:00.235)       0:00:05.195 ***
2026-09-02 15:55:16.994830 | instance | ok: [instance]
2026-09-02 15:55:16.994887 | instance |
2026-09-02 15:55:16.994899 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-02 15:55:16.994908 | instance | Wednesday 02 September 2026  15:55:16 +0000 (0:00:01.373)       0:00:06.568 ***
2026-09-02 15:55:17.082778 | instance | ok: [instance] => {
2026-09-02 15:55:17.082815 | instance |     "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz"
2026-09-02 15:55:17.082844 | instance | }
2026-09-02 15:55:17.082853 | instance |
2026-09-02 15:55:17.082862 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-02 15:55:17.082871 | instance | Wednesday 02 September 2026  15:55:17 +0000 (0:00:00.087)       0:00:06.656 ***
2026-09-02 15:55:17.795734 | instance | changed: [instance]
2026-09-02 15:55:17.795998 | instance |
2026-09-02 15:55:17.796143 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-02 15:55:17.796284 | instance | Wednesday 02 September 2026  15:55:17 +0000 (0:00:00.713)       0:00:07.370 ***
2026-09-02 15:55:20.702137 | instance | changed: [instance]
2026-09-02 15:55:20.702191 | instance |
2026-09-02 15:55:20.702202 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] ***************
2026-09-02 15:55:20.702213 | instance | Wednesday 02 September 2026  15:55:20 +0000 (0:00:02.905)       0:00:10.275 ***
2026-09-02 15:55:20.743254 | instance | skipping: [instance]
2026-09-02 15:55:20.744072 | instance |
2026-09-02 15:55:20.744136 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] ***
2026-09-02 15:55:20.744148 | instance | Wednesday 02 September 2026  15:55:20 +0000 (0:00:00.041)       0:00:10.317 ***
2026-09-02 15:55:20.784157 | instance | skipping: [instance]
2026-09-02 15:55:20.785185 | instance |
2026-09-02 15:55:20.785386 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ********
2026-09-02 15:55:20.785399 | instance | Wednesday 02 September 2026  15:55:20 +0000 (0:00:00.041)       0:00:10.358 ***
2026-09-02 15:55:20.826138 | instance | skipping: [instance]
2026-09-02 15:55:20.826189 | instance |
2026-09-02 15:55:20.826197 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] **************
2026-09-02 15:55:20.826204 | instance | Wednesday 02 September 2026  15:55:20 +0000 (0:00:00.041)       0:00:10.400 ***
2026-09-02 15:55:26.317144 | instance | changed: [instance]
2026-09-02 15:55:26.317428 | instance |
2026-09-02 15:55:26.317460 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] ***
2026-09-02 15:55:26.317731 | instance | Wednesday 02 September 2026  15:55:26 +0000 (0:00:05.490)       0:00:15.890 ***
2026-09-02 15:55:27.009497 | instance | changed: [instance]
2026-09-02 15:55:27.010304 | instance |
2026-09-02 15:55:27.010377 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] *******
2026-09-02 15:55:27.010389 | instance | Wednesday 02 September 2026  15:55:27 +0000 (0:00:00.692)       0:00:16.584 ***
2026-09-02 15:55:28.038195 | instance | changed: [instance] => (item={'path': '/etc/containerd'})
2026-09-02 15:55:28.040058 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'})
2026-09-02 15:55:28.040129 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'})
2026-09-02 15:55:28.040141 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'})
2026-09-02 15:55:28.040152 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'})
2026-09-02 15:55:28.040162 | instance |
2026-09-02 15:55:28.040179 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] **********
2026-09-02 15:55:28.040189 | instance | Wednesday 02 September 2026  15:55:28 +0000 (0:00:01.028)       0:00:17.612 ***
2026-09-02 15:55:28.696821 | instance | changed: [instance]
2026-09-02 15:55:28.696938 | instance |
2026-09-02 15:55:28.697757 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ********
2026-09-02 15:55:28.697806 | instance | Wednesday 02 September 2026  15:55:28 +0000 (0:00:00.633)       0:00:18.246 ***
2026-09-02 15:55:28.697814 | instance |
2026-09-02 15:55:28.697820 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-02 15:55:28.697825 | instance | Wednesday 02 September 2026  15:55:28 +0000 (0:00:00.025)       0:00:18.271 ***
2026-09-02 15:55:29.693605 | instance | ok: [instance]
2026-09-02 15:55:29.694309 | instance |
2026-09-02 15:55:29.694356 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] **********
2026-09-02 15:55:29.694368 | instance | Wednesday 02 September 2026  15:55:29 +0000 (0:00:00.996)       0:00:19.268 ***
2026-09-02 15:55:30.161589 | instance | changed: [instance]
2026-09-02 15:55:30.161759 | instance |
2026-09-02 15:55:30.162130 | instance | TASK [vexxhost.containers.containerd : Enable and start service] ***************
2026-09-02 15:55:30.162203 | instance | Wednesday 02 September 2026  15:55:30 +0000 (0:00:00.468)       0:00:19.736 ***
2026-09-02 15:55:30.779360 | instance | changed: [instance]
2026-09-02 15:55:30.779417 | instance |
2026-09-02 15:55:30.779429 | instance | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-02 15:55:30.779442 | instance | Wednesday 02 September 2026  15:55:30 +0000 (0:00:00.616)       0:00:20.353 ***
2026-09-02 15:55:31.026721 | instance | ok: [instance]
2026-09-02 15:55:31.026821 | instance |
2026-09-02 15:55:31.027202 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-09-02 15:55:31.027276 | instance | Wednesday 02 September 2026  15:55:31 +0000 (0:00:00.247)       0:00:20.600 ***
2026-09-02 15:55:31.106103 | instance | ok: [instance] => {
2026-09-02 15:55:31.106142 | instance |     "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz"
2026-09-02 15:55:31.106221 | instance | }
2026-09-02 15:55:31.106821 | instance |
2026-09-02 15:55:31.106888 | instance | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-09-02 15:55:31.106897 | instance | Wednesday 02 September 2026  15:55:31 +0000 (0:00:00.079)       0:00:20.680 ***
2026-09-02 15:55:31.858861 | instance | changed: [instance]
2026-09-02 15:55:31.859608 | instance |
2026-09-02 15:55:31.859641 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-09-02 15:55:31.859654 | instance | Wednesday 02 September 2026  15:55:31 +0000 (0:00:00.753)       0:00:21.433 ***
2026-09-02 15:55:36.257175 | instance | changed: [instance]
2026-09-02 15:55:36.257265 | instance |
2026-09-02 15:55:36.257632 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ******************
2026-09-02 15:55:36.257679 | instance | Wednesday 02 September 2026  15:55:36 +0000 (0:00:04.398)       0:00:25.831 ***
2026-09-02 15:55:37.439017 | instance | ok: [instance]
2026-09-02 15:55:37.439757 | instance |
2026-09-02 15:55:37.439816 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] ***************
2026-09-02 15:55:37.439827 | instance | Wednesday 02 September 2026  15:55:37 +0000 (0:00:01.180)       0:00:27.012 ***
2026-09-02 15:55:37.834711 | instance | changed: [instance]
2026-09-02 15:55:37.835569 | instance |
2026-09-02 15:55:37.835625 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] *****
2026-09-02 15:55:37.835637 | instance | Wednesday 02 September 2026  15:55:37 +0000 (0:00:00.397)       0:00:27.409 ***
2026-09-02 15:55:38.330826 | instance | changed: [instance]
2026-09-02 15:55:38.331336 | instance |
2026-09-02 15:55:38.331376 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] ***********
2026-09-02 15:55:38.331384 | instance | Wednesday 02 September 2026  15:55:38 +0000 (0:00:00.496)       0:00:27.906 ***
2026-09-02 15:55:38.952617 | instance | changed: [instance] => (item={'path': '/etc/docker'})
2026-09-02 15:55:38.953083 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'})
2026-09-02 15:55:38.953237 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'})
2026-09-02 15:55:38.953925 | instance |
2026-09-02 15:55:38.953961 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ******
2026-09-02 15:55:38.953972 | instance | Wednesday 02 September 2026  15:55:38 +0000 (0:00:00.621)       0:00:28.527 ***
2026-09-02 15:55:39.435879 | instance | changed: [instance]
2026-09-02 15:55:39.436325 | instance |
2026-09-02 15:55:39.436347 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] ***********
2026-09-02 15:55:39.436354 | instance | Wednesday 02 September 2026  15:55:39 +0000 (0:00:00.483)       0:00:29.011 ***
2026-09-02 15:55:39.954271 | instance | changed: [instance]
2026-09-02 15:55:39.954332 | instance |
2026-09-02 15:55:39.955142 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************
2026-09-02 15:55:39.955188 | instance | Wednesday 02 September 2026  15:55:39 +0000 (0:00:00.491)       0:00:29.502 ***
2026-09-02 15:55:39.955195 | instance |
2026-09-02 15:55:39.955202 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] **************
2026-09-02 15:55:39.955208 | instance | Wednesday 02 September 2026  15:55:39 +0000 (0:00:00.026)       0:00:29.529 ***
2026-09-02 15:55:40.696945 | instance | ok: [instance]
2026-09-02 15:55:40.697703 | instance |
2026-09-02 15:55:40.697757 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ******************
2026-09-02 15:55:40.697778 | instance | Wednesday 02 September 2026  15:55:40 +0000 (0:00:00.742)       0:00:30.271 ***
2026-09-02 15:55:41.730532 | instance | changed: [instance]
2026-09-02 15:55:41.731254 | instance |
2026-09-02 15:55:41.731313 | instance | TASK [vexxhost.containers.docker : Enable and start service] *******************
2026-09-02 15:55:41.731324 | instance | Wednesday 02 September 2026  15:55:41 +0000 (0:00:01.033)       0:00:31.305 ***
2026-09-02 15:55:42.340172 | instance | changed: [instance]
2026-09-02 15:55:42.340208 | instance |
2026-09-02 15:55:42.340216 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ******
2026-09-02 15:55:42.340222 | instance | Wednesday 02 September 2026  15:55:42 +0000 (0:00:00.609)       0:00:31.914 ***
2026-09-02 15:55:42.415870 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml)
2026-09-02 15:55:42.416620 | instance |
2026-09-02 15:55:42.416675 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ********************************
2026-09-02 15:55:42.416692 | instance | Wednesday 02 September 2026  15:55:42 +0000 (0:00:00.075)       0:00:31.990 ***
2026-09-02 15:55:47.688279 | instance | changed: [instance]
2026-09-02 15:55:47.688510 | instance |
2026-09-02 15:55:47.689044 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] *********************
2026-09-02 15:55:47.689085 | instance | Wednesday 02 September 2026  15:55:47 +0000 (0:00:05.272)       0:00:37.263 ***
2026-09-02 15:55:48.388865 | instance | ok: [instance] => (item=chronyd)
2026-09-02 15:55:48.389151 | instance | ok: [instance] => (item=sshd)
2026-09-02 15:55:48.389393 | instance |
2026-09-02 15:55:48.389683 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ******************************
2026-09-02 15:55:48.389950 | instance | Wednesday 02 September 2026  15:55:48 +0000 (0:00:00.700)       0:00:37.963 ***
2026-09-02 15:55:48.768495 | instance | changed: [instance]
2026-09-02 15:55:48.768744 | instance |
2026-09-02 15:55:48.769002 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ********************
2026-09-02 15:55:48.769325 | instance | Wednesday 02 September 2026  15:55:48 +0000 (0:00:00.379)       0:00:38.343 ***
2026-09-02 15:55:49.000797 | instance | ok: [instance]
2026-09-02 15:55:49.001044 | instance |
2026-09-02 15:55:49.001364 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] ****************
2026-09-02 15:55:49.001654 | instance | Wednesday 02 September 2026  15:55:48 +0000 (0:00:00.232)       0:00:38.575 ***
2026-09-02 15:55:49.482830 | instance | changed: [instance]
2026-09-02 15:55:49.483031 | instance |
2026-09-02 15:55:49.483266 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] ***
2026-09-02 15:55:49.483521 | instance | Wednesday 02 September 2026  15:55:49 +0000 (0:00:00.481)       0:00:39.057 ***
2026-09-02 15:55:49.833040 | instance | changed: [instance]
2026-09-02 15:55:49.833102 | instance |
2026-09-02 15:55:49.833115 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] *************************
2026-09-02 15:55:49.833125 | instance | Wednesday 02 September 2026  15:55:49 +0000 (0:00:00.349)       0:00:39.407 ***
2026-09-02 15:55:49.946154 | instance | ok: [instance]
2026-09-02 15:55:49.946221 | instance |
2026-09-02 15:55:49.946238 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] *******************
2026-09-02 15:55:49.946252 | instance | Wednesday 02 September 2026  15:55:49 +0000 (0:00:00.112)       0:00:39.519 ***
2026-09-02 15:55:50.210959 | instance | ok: [instance] => (item=instance)
2026-09-02 15:55:50.211008 | instance |
2026-09-02 15:55:50.211021 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ********
2026-09-02 15:55:50.211032 | instance | Wednesday 02 September 2026  15:55:50 +0000 (0:00:00.264)       0:00:39.784 ***
2026-09-02 15:55:50.280739 | instance | ok: [instance]
2026-09-02 15:55:50.280783 | instance |
2026-09-02 15:55:50.280794 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] ***********************************
2026-09-02 15:55:50.280805 | instance | Wednesday 02 September 2026  15:55:50 +0000 (0:00:00.069)       0:00:39.854 ***
2026-09-02 15:55:50.360721 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance
2026-09-02 15:55:50.360773 | instance |
2026-09-02 15:55:50.361029 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] *************
2026-09-02 15:55:50.361066 | instance | Wednesday 02 September 2026  15:55:50 +0000 (0:00:00.081)       0:00:39.935 ***
2026-09-02 15:55:50.693579 | instance | changed: [instance]
2026-09-02 15:55:50.694309 | instance |
2026-09-02 15:55:50.694365 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ******************
2026-09-02 15:55:50.694376 | instance | Wednesday 02 September 2026  15:55:50 +0000 (0:00:00.331)       0:00:40.268 ***
2026-09-02 15:55:51.452105 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True})
2026-09-02 15:55:51.453136 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0})
2026-09-02 15:55:51.453210 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False})
2026-09-02 15:55:51.453222 | instance |
2026-09-02 15:55:51.453232 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] *******************************
2026-09-02 15:55:51.453364 | instance | Wednesday 02 September 2026  15:55:51 +0000 (0:00:00.758)       0:00:41.026 ***
2026-09-02 15:57:28.008825 | instance | ok: [instance]
2026-09-02 15:57:28.009217 | instance |
2026-09-02 15:57:28.010508 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] ***************
2026-09-02 15:57:28.010569 | instance | Wednesday 02 September 2026  15:57:28 +0000 (0:01:36.557)       0:02:17.583 ***
2026-09-02 15:57:28.270294 | instance | changed: [instance]
2026-09-02 15:57:28.270464 | instance |
2026-09-02 15:57:28.270700 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] **********************************
2026-09-02 15:57:28.270904 | instance | Wednesday 02 September 2026  15:57:28 +0000 (0:00:00.261)       0:02:17.845 ***
2026-09-02 15:57:28.351557 | instance | ok: [instance]
2026-09-02 15:57:28.351635 | instance |
2026-09-02 15:57:28.351924 | instance | TASK [Install Ceph host] *******************************************************
2026-09-02 15:57:28.351964 | instance | Wednesday 02 September 2026  15:57:28 +0000 (0:00:00.081)       0:02:17.926 ***
2026-09-02 15:57:28.436803 | instance | included: vexxhost.ceph.cephadm_host for instance
2026-09-02 15:57:28.436872 | instance |
2026-09-02 15:57:28.437109 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ******
2026-09-02 15:57:28.437147 | instance | Wednesday 02 September 2026  15:57:28 +0000 (0:00:00.085)       0:02:18.012 ***
2026-09-02 15:57:30.149448 | instance | ok: [instance]
2026-09-02 15:57:30.149688 | instance |
2026-09-02 15:57:30.149948 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] ***
2026-09-02 15:57:30.150184 | instance | Wednesday 02 September 2026  15:57:30 +0000 (0:00:01.711)       0:02:19.723 ***
2026-09-02 15:57:30.228632 | instance | ok: [instance] => (item=instance)
2026-09-02 15:57:30.228832 | instance |
2026-09-02 15:57:30.229105 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] ***********
2026-09-02 15:57:30.229356 | instance | Wednesday 02 September 2026  15:57:30 +0000 (0:00:00.079)       0:02:19.803 ***
2026-09-02 15:57:30.598452 | instance | ok: [instance]
2026-09-02 15:57:30.598654 | instance |
2026-09-02 15:57:30.598867 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] ***********************
2026-09-02 15:57:30.599074 | instance | Wednesday 02 September 2026  15:57:30 +0000 (0:00:00.369)       0:02:20.172 ***
2026-09-02 15:57:32.806241 | instance | ok: [instance]
2026-09-02 15:57:32.806519 | instance |
2026-09-02 15:57:32.806797 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ******************
2026-09-02 15:57:32.807086 | instance | Wednesday 02 September 2026  15:57:32 +0000 (0:00:02.207)       0:02:22.380 ***
2026-09-02 15:58:27.225201 | 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-02 15:58:27.225790 | 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_kg63qomc/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_kg63qomc/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_kg63qomc/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_kg63qomc/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-02T15:58:26.997+0000 7ffac513d640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgA+R5hq/mmrOiAAFGLV8OWqnhiEOG7oDaoaN9CzTP2etnGQQ18WU9zGSwg=: Malformed input [buffer:3]\n2026-09-02T15:58:26.997+0000 7ffac513d640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-02T15:58:27.001+0000 7ffac513d640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgA+R5hq/mmrOiAAFGLV8OWqnhiEOG7oDaoaN9CzTP2etnGQQ18WU9zGSwg=: Malformed input [buffer:3]\n2026-09-02T15:58:27.001+0000 7ffac513d640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-02T15:58:27.001+0000 7ffac513d640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgA+R5hq/mmrOiAAFGLV8OWqnhiEOG7oDaoaN9CzTP2etnGQQ18WU9zGSwg=: Malformed input [buffer:3]\n2026-09-02T15:58:27.001+0000 7ffac513d640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-02T15:58:27.001+0000 7ffac513d640 -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-02 15:58:27.226551 | instance |
2026-09-02 15:58:27.226845 | instance | PLAY RECAP *********************************************************************
2026-09-02 15:58:27.227167 | instance | instance                   : ok=55   changed=26   unreachable=0    failed=1    skipped=6    rescued=0    ignored=0
2026-09-02 15:58:27.227482 | instance |
2026-09-02 15:58:27.227817 | instance | Wednesday 02 September 2026  15:58:27 +0000 (0:00:54.421)       0:03:16.802 ***
2026-09-02 15:58:27.228120 | instance | ===============================================================================
2026-09-02 15:58:27.228424 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 96.56s
2026-09-02 15:58:27.228717 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 54.42s
2026-09-02 15:58:27.229155 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.49s
2026-09-02 15:58:27.229452 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.27s
2026-09-02 15:58:27.229738 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.40s
2026-09-02 15:58:27.230074 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.91s
2026-09-02 15:58:27.230371 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.21s
2026-09-02 15:58:27.230662 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.74s
2026-09-02 15:58:27.230989 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.71s
2026-09-02 15:58:27.231280 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.37s
2026-09-02 15:58:27.231571 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.18s
2026-09-02 15:58:27.231880 | instance | Gathering Facts --------------------------------------------------------- 1.04s
2026-09-02 15:58:27.232164 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 1.03s
2026-09-02 15:58:27.232448 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.03s
2026-09-02 15:58:27.232737 | instance | Gathering Facts --------------------------------------------------------- 0.98s
2026-09-02 15:58:27.233153 | instance | Gathering Facts --------------------------------------------------------- 0.97s
2026-09-02 15:58:27.233444 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.76s
2026-09-02 15:58:27.233731 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.75s
2026-09-02 15:58:27.234058 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.74s
2026-09-02 15:58:27.234336 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.71s
2026-09-02 15:58:27.319588 | 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-02 15:58:27.319779 | instance | ERROR    [csi > converge] Executed: Failed
2026-09-02 15:58:27.319948 | 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-02 15:58:27.770416 | instance | ERROR
2026-09-02 15:58:27.770665 | instance | {
2026-09-02 15:58:27.770707 | instance |   "delta": "0:04:10.620982",
2026-09-02 15:58:27.770729 | instance |   "end": "2026-09-02 15:58:27.385519",
2026-09-02 15:58:27.770748 | instance |   "msg": "non-zero return code",
2026-09-02 15:58:27.770766 | instance |   "rc": 2,
2026-09-02 15:58:27.770784 | instance |   "start": "2026-09-02 15:54:16.764537"
2026-09-02 15:58:27.770802 | instance | }
failure
2026-09-02 15:58:27.777348 | 
2026-09-02 15:58:27.777402 | PLAY RECAP
2026-09-02 15:58:27.777451 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-02 15:58:27.777474 | 
2026-09-02 15:58:27.908325 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-02 15:58:27.912636 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-02 15:58:28.533851 | 
2026-09-02 15:58:28.533998 | PLAY [all]
2026-09-02 15:58:28.548501 | 
2026-09-02 15:58:28.564607 | TASK [gather-host-logs : creating directory for system status]
2026-09-02 15:58:28.926500 | instance | changed
2026-09-02 15:58:28.932525 | 
2026-09-02 15:58:28.932717 | TASK [gather-host-logs : Get logs for each host]
2026-09-02 15:58:29.298296 | instance | + systemd-cgls --full --all --no-pager
2026-09-02 15:58:29.313629 | instance | + ip addr
2026-09-02 15:58:29.316281 | instance | + ip route
2026-09-02 15:58:29.318902 | instance | + lsblk
2026-09-02 15:58:29.324227 | instance | + mount
2026-09-02 15:58:29.326988 | instance | + docker images
2026-09-02 15:58:29.350960 | instance | + brctl show
2026-09-02 15:58:29.351438 | instance | /bin/bash: line 8: brctl: command not found
2026-09-02 15:58:29.351663 | instance | + ps aux --sort=-%mem
2026-09-02 15:58:29.372185 | instance | + dpkg -l
2026-09-02 15:58:29.383537 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-02 15:58:29.384048 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-02 15:58:29.405938 | instance | + '[' '!' -z '' ']'
2026-09-02 15:58:29.473751 | instance | ok: Runtime: 0:00:00.111651
2026-09-02 15:58:29.481661 | 
2026-09-02 15:58:29.481803 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-02 15:58:30.106568 | instance | changed:
2026-09-02 15:58:30.106753 | instance | created directory /var/lib/zuul/builds/5328397b28b24344ab1df38f181ff927/work/logs/instance
2026-09-02 15:58:30.106783 | instance | cd+++++++++ system/
2026-09-02 15:58:30.106806 | instance | >f+++++++++ system/brctl-show.txt
2026-09-02 15:58:30.106828 | instance | >f+++++++++ system/docker-images.txt
2026-09-02 15:58:30.106848 | instance | >f+++++++++ system/ip-addr.txt
2026-09-02 15:58:30.106869 | instance | >f+++++++++ system/ip-route.txt
2026-09-02 15:58:30.106890 | instance | >f+++++++++ system/lsblk.txt
2026-09-02 15:58:30.106910 | instance | >f+++++++++ system/mount.txt
2026-09-02 15:58:30.106929 | instance | >f+++++++++ system/packages.txt
2026-09-02 15:58:30.106948 | instance | >f+++++++++ system/ps.txt
2026-09-02 15:58:30.106970 | instance | >f+++++++++ system/systemd-cgls.txt
2026-09-02 15:58:30.116999 | 
2026-09-02 15:58:30.117090 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-02 15:58:30.336105 | instance | changed: "values"
2026-09-02 15:58:30.534831 | instance | changed: "releases"
2026-09-02 15:58:30.549026 | 
2026-09-02 15:58:30.549195 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-02 15:58:30.778280 | instance | /bin/bash: line 3: kubectl: command not found
2026-09-02 15:58:31.087137 | instance | ok: Runtime: 0:00:00.004755
2026-09-02 15:58:31.092770 | 
2026-09-02 15:58:31.092853 | TASK [helm-release-status : Downloads logs to executor]
2026-09-02 15:58:31.593742 | instance | changed:
2026-09-02 15:58:31.594021 | instance | cd+++++++++ helm/
2026-09-02 15:58:31.594063 | instance | cd+++++++++ helm/releases/
2026-09-02 15:58:31.594095 | instance | cd+++++++++ helm/values/
2026-09-02 15:58:31.608839 | 
2026-09-02 15:58:31.608937 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-02 15:58:31.830280 | instance | changed
2026-09-02 15:58:31.838360 | 
2026-09-02 15:58:31.838438 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-02 15:58:32.100830 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-02 15:58:32.101219 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-02 15:58:32.109247 | instance | environment: line 1: kubectl: command not found
2026-09-02 15:58:32.109742 | instance | environment: line 1: kubectl: command not found
2026-09-02 15:58:32.110558 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-02 15:58:32.111369 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-02 15:58:32.114485 | instance | environment: line 1: kubectl: command not found
2026-09-02 15:58:32.115100 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-02 15:58:32.115261 | instance | environment: line 1: kubectl: command not found
2026-09-02 15:58:32.116817 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-02 15:58:32.118620 | instance | environment: line 1: kubectl: command not found
2026-09-02 15:58:32.119869 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-02 15:58:32.383294 | instance | ok: Runtime: 0:00:00.027892
2026-09-02 15:58:32.388411 | 
2026-09-02 15:58:32.388479 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-02 15:58:32.603490 | instance | changed
2026-09-02 15:58:32.609405 | 
2026-09-02 15:58:32.609483 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-02 15:58:32.848005 | instance | environment: line 5: kubectl: command not found
2026-09-02 15:58:32.848274 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-02 15:58:32.848841 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-02 15:58:32.849075 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-02 15:58:33.148894 | instance | ok: Runtime: 0:00:00.009446
2026-09-02 15:58:33.156544 | 
2026-09-02 15:58:33.156633 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-02 15:58:33.666240 | instance | changed:
2026-09-02 15:58:33.666448 | instance | cd+++++++++ objects/
2026-09-02 15:58:33.666486 | instance | cd+++++++++ objects/cluster/
2026-09-02 15:58:33.666520 | instance | cd+++++++++ objects/namespaced/
2026-09-02 15:58:33.676613 | 
2026-09-02 15:58:33.676687 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-02 15:58:33.906628 | instance | changed
2026-09-02 15:58:33.911919 | 
2026-09-02 15:58:33.911987 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-02 15:58:34.139944 | instance | changed
2026-09-02 15:58:34.146667 | 
2026-09-02 15:58:34.146758 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-02 15:58:34.375489 | instance | environment: line 3: kubectl: command not found
2026-09-02 15:58:34.693615 | instance | ok: Runtime: 0:00:00.008363
2026-09-02 15:58:34.700195 | 
2026-09-02 15:58:34.700259 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-02 15:58:35.190900 | instance | changed:
2026-09-02 15:58:35.191169 | instance | cd+++++++++ pod-logs/
2026-09-02 15:58:35.191246 | instance | cd+++++++++ pod-logs/failed-pods/
2026-09-02 15:58:35.217361 | 
2026-09-02 15:58:35.217472 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-02 15:58:35.444422 | instance | changed
2026-09-02 15:58:35.449809 | 
2026-09-02 15:58:35.449876 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-02 15:58:35.677493 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-02 15:58:35.986427 | instance | ok: Runtime: 0:00:00.059667
2026-09-02 15:58:35.992777 | 
2026-09-02 15:58:35.992843 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-02 15:58:36.234983 | instance | /bin/bash: line 2: kubectl: command not found
2026-09-02 15:58:36.280361 | instance | ceph-mgr endpoints:
2026-09-02 15:58:36.538830 | instance | ok: Runtime: 0:00:00.052523
2026-09-02 15:58:36.544922 | 
2026-09-02 15:58:36.545016 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-02 15:58:36.772555 | instance | /bin/bash: line 4: kubectl: command not found
2026-09-02 15:58:37.081887 | instance | ok: Runtime: 0:00:00.051996
2026-09-02 15:58:37.088285 | 
2026-09-02 15:58:37.088349 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-02 15:58:37.604314 | instance | changed: cd+++++++++ prometheus/
2026-09-02 15:58:37.614267 | 
2026-09-02 15:58:37.614332 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-02 15:58:37.845929 | instance | changed
2026-09-02 15:58:37.855382 | 
2026-09-02 15:58:37.855450 | TASK [gather-selenium-data : Get selenium data]
2026-09-02 15:58:38.151606 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-02 15:58:38.152356 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-02 15:58:38.409353 | instance | ERROR
2026-09-02 15:58:38.409504 | instance | {
2026-09-02 15:58:38.409546 | instance |   "delta": "0:00:00.003471",
2026-09-02 15:58:38.409574 | instance |   "end": "2026-09-02 15:58:38.152634",
2026-09-02 15:58:38.409602 | instance |   "msg": "non-zero return code",
2026-09-02 15:58:38.409629 | instance |   "rc": 1,
2026-09-02 15:58:38.409654 | instance |   "start": "2026-09-02 15:58:38.149163"
2026-09-02 15:58:38.409679 | instance | }
2026-09-02 15:58:38.409711 | instance | ERROR: Ignoring Errors
2026-09-02 15:58:38.416034 | 
2026-09-02 15:58:38.416095 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-02 15:58:38.925460 | instance | changed: cd+++++++++ selenium/
2026-09-02 15:58:38.931709 | 
2026-09-02 15:58:38.931763 | PLAY RECAP
2026-09-02 15:58:38.931812 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-02 15:58:38.931834 | 
2026-09-02 15:58:39.053757 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-09-02 15:58:39.056632 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-02 15:58:39.674501 | 
2026-09-02 15:58:39.674638 | PLAY [all]
2026-09-02 15:58:39.687291 | 
2026-09-02 15:58:39.687407 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-02 15:58:39.732920 | instance | skipping: Conditional result was False
2026-09-02 15:58:39.740918 | 
2026-09-02 15:58:39.741085 | TASK [fetch-output : Set log path for single node]
2026-09-02 15:58:39.792750 | instance | ok
2026-09-02 15:58:39.799285 | 
2026-09-02 15:58:39.799399 | LOOP [fetch-output : Ensure local output dirs]
2026-09-02 15:58:40.164755 | instance -> localhost | ok: "/var/lib/zuul/builds/5328397b28b24344ab1df38f181ff927/work/logs"
2026-09-02 15:58:40.361344 | instance -> localhost | changed: "/var/lib/zuul/builds/5328397b28b24344ab1df38f181ff927/work/artifacts"
2026-09-02 15:58:40.554814 | instance -> localhost | changed: "/var/lib/zuul/builds/5328397b28b24344ab1df38f181ff927/work/docs"
2026-09-02 15:58:40.574451 | 
2026-09-02 15:58:40.574626 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-02 15:58:41.223208 | instance | changed: .d..t...... ./
2026-09-02 15:58:41.223451 | instance | changed: All items complete
2026-09-02 15:58:41.223479 | 
2026-09-02 15:58:41.699921 | instance | changed: .d..t...... ./
2026-09-02 15:58:42.158402 | instance | changed: .d..t...... ./
2026-09-02 15:58:42.173651 | 
2026-09-02 15:58:42.173845 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-02 15:58:42.606599 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007897
2026-09-02 15:58:42.842753 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006954
2026-09-02 15:58:42.861198 | 
2026-09-02 15:58:42.861334 | PLAY [all]
2026-09-02 15:58:42.868441 | 
2026-09-02 15:58:42.868507 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-02 15:58:43.311232 | instance | changed
2026-09-02 15:58:43.317831 | 
2026-09-02 15:58:43.317881 | PLAY RECAP
2026-09-02 15:58:43.317927 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-02 15:58:43.317952 | 
2026-09-02 15:58:43.445994 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-02 15:58:43.450697 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-02 15:58:44.102256 | 
2026-09-02 15:58:44.102411 | PLAY [localhost]
2026-09-02 15:58:44.113758 | 
2026-09-02 15:58:44.113898 | TASK [Generate Zuul manifest]
2026-09-02 15:58:44.135009 | localhost | ok
2026-09-02 15:58:44.157441 | 
2026-09-02 15:58:44.157615 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-02 15:58:44.571458 | localhost | changed
2026-09-02 15:58:44.585592 | 
2026-09-02 15:58:44.585673 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-02 15:58:44.617879 | localhost | ok
2026-09-02 15:58:44.625779 | 
2026-09-02 15:58:44.625845 | TASK [Upload logs]
2026-09-02 15:58:44.649946 | localhost | ok
2026-09-02 15:58:44.717797 | 
2026-09-02 15:58:44.718000 | TASK [Set zuul-log-path fact]
2026-09-02 15:58:44.738568 | localhost | ok
2026-09-02 15:58:44.753473 | 
2026-09-02 15:58:44.753549 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-02 15:58:44.786758 | localhost | ok
2026-09-02 15:58:44.796822 | 
2026-09-02 15:58:44.796920 | TASK [upload-logs : Create log directories]
2026-09-02 15:58:45.257074 | localhost | changed
2026-09-02 15:58:45.267521 | 
2026-09-02 15:58:45.267773 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-02 15:58:45.723797 | localhost -> localhost | ok: Runtime: 0:00:00.008477
2026-09-02 15:58:45.735493 | 
2026-09-02 15:58:45.736484 | TASK [upload-logs : Upload logs to log server]
2026-09-02 15:58:46.354950 | localhost | Output suppressed because no_log was given
2026-09-02 15:58:46.393398 | 
2026-09-02 15:58:46.393545 | LOOP [upload-logs : Compress console log and json output]
2026-09-02 15:58:46.442074 | localhost | skipping: Conditional result was False
2026-09-02 15:58:46.448937 | localhost | skipping: Conditional result was False
2026-09-02 15:58:46.457514 | 
2026-09-02 15:58:46.457664 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-02 15:58:46.499483 | localhost | skipping: Conditional result was False
2026-09-02 15:58:46.499760 | 
2026-09-02 15:58:46.505489 | localhost | skipping: Conditional result was False
2026-09-02 15:58:46.526545 | 
2026-09-02 15:58:46.526659 | LOOP [upload-logs : Upload console log and json output]